GitHub user SviridoffA added a comment to the discussion: Need help with VM
console
Hi @prashanthr2 ! Thanks for your reply. I have checked this logs. In
/var/log/cloud.log I have found in connection moment this:
```
2025-10-20 15:12:33,950 INFO [cloud.consoleproxy.ConsoleProxyResourceHandler]
(Thread-450:null) Get resource request for /resource/noVNC/vnc.html
2025-10-20 15:12:33,961 INFO [cloud.consoleproxy.ConsoleProxyResourceHandler]
(Thread-450:null) Sent file /resource/noVNC/vnc.html with content type text/html
2025-10-20 15:12:34,372 INFO [cloud.consoleproxy.ConsoleProxyNoVNCHandler]
(qtp989805526-42:null) Get websocket connection request from remote IP :
213.219.199.84
2025-10-20 15:12:34,438 INFO [cloud.consoleproxy.ConsoleProxyNoVncClient]
(Thread-451:null) Connect to VNC server directly. host: 10.66.50.51, port: 5900
2025-10-20 15:12:34,443 INFO [consoleproxy.vnc.NoVncClient] (Thread-451:null)
Connecting to VNC server 10.66.50.51:5900 ...
2025-10-20 15:12:34,513 INFO [vnc.security.VncTLSSecurity] (Thread-451:null)
Processing VNC TLS security
2025-10-20 15:12:34,514 INFO [utils.nio.Link] (Thread-451:null) Conf file
found: /usr/local/cloud/systemvm/conf/agent.properties
2025-10-20 15:17:34,509 ERROR [cloud.consoleproxy.ConsoleProxyNoVNCHandler]
(Connector-Scheduler-753671eb-1:null) Error on websocket
org.eclipse.jetty.websocket.api.CloseException:
java.util.concurrent.TimeoutException: Idle timeout expired: 300001/300000 ms
at
org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection.onReadTimeout(AbstractWebSocketConnection.java:564)
at
org.eclipse.jetty.io.AbstractConnection.onFillInterestedFailed(AbstractConnection.java:172)
at
org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection.onFillInterestedFailed(AbstractWebSocketConnection.java:539)
at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.failed(AbstractConnection.java:317)
at org.eclipse.jetty.io.FillInterest.onFail(FillInterest.java:140)
at
org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillableFail(SslConnection.java:591)
at
org.eclipse.jetty.io.ssl.SslConnection.onFillInterestedFailed(SslConnection.java:419)
at
org.eclipse.jetty.io.ssl.SslConnection$2.failed(SslConnection.java:170)
at org.eclipse.jetty.io.FillInterest.onFail(FillInterest.java:140)
at
org.eclipse.jetty.io.AbstractEndPoint.onIdleExpired(AbstractEndPoint.java:407)
at
org.eclipse.jetty.io.IdleTimeout.checkIdleTimeout(IdleTimeout.java:171)
at org.eclipse.jetty.io.IdleTimeout.idleCheck(IdleTimeout.java:113)
at
java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at
java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:829)
Caused by: java.util.concurrent.TimeoutException: Idle timeout expired:
300001/300000 ms
... 8 more
```
It seems its on INFO log level right now.
And in /var/log/cloudstack/management/management-server.log and I have cheked
it earlier:
```
2025-10-20 18:12:33,580 DEBUG [c.c.a.ApiServlet]
(qtp510109769-48734:ctx-f99b49e1) (logid:4d604cf2) ===START=== 10.66.50.7 --
GET
virtualmachineid=21baafa2-bfac-4de7-a70d-4b42b46a9098&command=createConsoleEndpoint&response=json&sessionkey=UGRrC2wWDkw5CRvUWGyKWVL97gQ
2025-10-20 18:12:33,580 DEBUG [c.c.a.ApiServlet]
(qtp510109769-48734:ctx-f99b49e1) (logid:4d604cf2) Two factor authentication is
already verified for the user 2, so skipping
2025-10-20 18:12:33,593 DEBUG [c.c.a.ApiServer]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) CIDRs from
which account 'Account
[{"accountName":"admin","id":2,"uuid":"2613b845-8888-11ef-82b0-0050568d3660"}]'
is allowed to perform API calls: 0.0.0.0/0,::/0
2025-10-20 18:12:33,601 DEBUG [o.a.c.a.StaticRoleBasedAPIAccessChecker]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) RoleService is
enabled. We will use it instead of StaticRoleBasedAPIAccessChecker.
2025-10-20 18:12:33,602 DEBUG [o.a.c.r.ApiRateLimitServiceImpl]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) API rate
limiting is disabled. We will not use ApiRateLimitService.
2025-10-20 18:12:33,604 WARN [c.c.a.d.ParamGenericValidationWorker]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) Received
unknown parameters for command createConsoleEndpoint. Unknown parameters :
client-inet-address
2025-10-20 18:12:33,616 DEBUG [c.c.c.ConsoleProxyManagerImpl]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) Assign previous
allocated console proxy for user vm : 14
2025-10-20 18:12:33,621 DEBUG [c.c.a.t.Request]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) Seq
1-7362540965820630397: Sending { Cmd , MgmtId: 345049528973, via: 1(node1),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.GetVncPortCommand":{"id":"14","name":"i-2-14-VM","wait":"0","bypassHostMaintenance":"false"}}]
}
2025-10-20 18:12:33,665 DEBUG [c.c.a.t.Request] (AgentManager-Handler-10:null)
(logid:) Seq 1-7362540965820630397: Processing: { Ans: , MgmtId: 345049528973,
via: 1, Ver: v1, Flags: 10,
[{"com.cloud.agent.api.GetVncPortAnswer":{"address":"10.66.50.51","port":"5900","result":"true","wait":"0","bypassHostMaintenance":"false"}}]
}
2025-10-20 18:12:33,666 DEBUG [c.c.a.t.Request]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) Seq
1-7362540965820630397: Received: { Ans: , MgmtId: 345049528973, via: 1(node1),
Ver: v1, Flags: 10, { GetVncPortAnswer } }
2025-10-20 18:12:33,666 DEBUG [o.a.c.c.ConsoleAccessManagerImpl]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) Port info
10.66.50.51
2025-10-20 18:12:33,666 INFO [o.a.c.c.ConsoleAccessManagerImpl]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) Parse host info
returned from executing GetVNCPortCommand. host info: 10.66.50.51
2025-10-20 18:12:33,671 DEBUG [o.a.c.c.ConsoleAccessManagerImpl]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) Compose console
url:
https://<hidenadress>/resource/noVNC/vnc.html?autoconnect=true&port=8443&token=WeZo6pUd0j8qTQJjP+nl90TYgC0N0tnW5nDU4OJtjR0uZoKfYqK08IL/2p9wPWRtPESYxvzaQAYhKpjGDdlPe8EAMyHQBOMBg8NI5INsjFnb9oS7XT+nw9TxQwQq0/8IV9cdqS3YwEV8LL3odfOWp06vuvUFyTrqZ0kEeSUsyE3jQZDuI1B6MIYmKvZ3uQwV/maQpA4G0lYlO1RRc1PnoHnUmvCd6G6tpRUtQ//+c6n8jN7h9xcQcoV8LD2JoZERIi3F2OWN+lksvBOyZ/EGaaQo4FWVrem2rp0Mgue6MW8jMHK/1QVl4n/SS/KPErDHKrYTge6/qxaNZpwO1CnecPDWaBIeYs6EyMkZEBHilcZyQBxejCBvH8ElFhtsjy0YbaYmm3JasRLiTP4QYprT7q9JGb/nYjEsqOpd0PT7ZdVczgEuOwA3SF1fuJ0I07ZIlTkZ3NpIXxfyyvpp4FE1mmsF1KvmlPnQ2qNhFE1yhnYosmCwiCSEkP/mGB+G2E8ZCLrZsJEWphnFuxDfV8NNGhQ=
2025-10-20 18:12:33,671 DEBUG [o.a.c.c.ConsoleAccessManagerImpl]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) Adding allowed
session: 0d22b1a5-3621-4382-b7b3-bc2606edbbca
2025-10-20 18:12:33,689 DEBUG [c.c.c.ConsoleProxyManagerImpl]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) Assign previous
allocated console proxy for user vm : 14
2025-10-20 18:12:33,700 DEBUG [c.c.a.m.ClusteredAgentAttache]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) Seq
9-5062608931117858870: Forwarding Seq 9-5062608931117858870: { Cmd , MgmtId:
345049528973, via: 9(v-64-VM), Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.proxy.AllowConsoleAccessCommand":{"sessionUuid":"0d22b1a5-3621-4382-b7b3-bc2606edbbca","wait":"0","bypassHostMaintenance":"false"}}]
} to 345049509737
2025-10-20 18:12:33,745 DEBUG [c.c.a.t.Request] (AgentManager-Handler-7:null)
(logid:) Seq 9-5062608931117858870: Processing: { Ans: , MgmtId: 345049528973,
via: 9, Ver: v1, Flags: 10,
[{"com.cloud.agent.api.Answer":{"result":"true","wait":"0","bypassHostMaintenance":"false"}}]
}
2025-10-20 18:12:33,745 DEBUG [c.c.a.t.Request]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) Seq
9-5062608931117858870: Received: { Ans: , MgmtId: 345049528973, via:
9(v-64-VM), Ver: v1, Flags: 10, { Answer } }
2025-10-20 18:12:33,754 DEBUG [c.c.c.ConsoleProxyManagerImpl]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) Assign previous
allocated console proxy for user vm : 14
2025-10-20 18:12:33,756 DEBUG [o.a.c.c.ConsoleAccessManagerImpl]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) The console URL
is:
https://<hidenadress>/resource/noVNC/vnc.html?autoconnect=true&port=8443&token=WeZo6pUd0j8qTQJjP+nl90TYgC0N0tnW5nDU4OJtjR0uZoKfYqK08IL/2p9wPWRtPESYxvzaQAYhKpjGDdlPe8EAMyHQBOMBg8NI5INsjFnb9oS7XT+nw9TxQwQq0/8IV9cdqS3YwEV8LL3odfOWp06vuvUFyTrqZ0kEeSUsyE3jQZDuI1B6MIYmKvZ3uQwV/maQpA4G0lYlO1RRc1PnoHnUmvCd6G6tpRUtQ//+c6n8jN7h9xcQcoV8LD2JoZERIi3F2OWN+lksvBOyZ/EGaaQo4FWVrem2rp0Mgue6MW8jMHK/1QVl4n/SS/KPErDHKrYTge6/qxaNZpwO1CnecPDWaBIeYs6EyMkZEBHilcZyQBxejCBvH8ElFhtsjy0YbaYmm3JasRLiTP4QYprT7q9JGb/nYjEsqOpd0PT7ZdVczgEuOwA3SF1fuJ0I07ZIlTkZ3NpIXxfyyvpp4FE1mmsF1KvmlPnQ2qNhFE1yhnYosmCwiCSEkP/mGB+G2E8ZCLrZsJEWphnFuxDfV8NNGhQ=
2025-10-20 18:12:33,756 DEBUG [c.c.a.ApiServlet]
(qtp510109769-48734:ctx-f99b49e1 ctx-e0fd92c3) (logid:4d604cf2) ===END===
10.66.50.7 -- GET
virtualmachineid=21baafa2-bfac-4de7-a70d-4b42b46a9098&command=createConsoleEndpoint&response=json&sessionkey=UGRrC2wWDkw5CRvUWGyKWVL97gQ
2025-10-20 18:12:33,903 DEBUG [o.a.c.h.HAManagerImpl]
(BackgroundTaskPollManager-2:ctx-3c3b9d44) (logid:3b2f028f) HA health check
task is running...
2025-10-20 18:12:34,967 DEBUG [c.c.a.ApiServlet]
(qtp510109769-48735:ctx-db2e63a3) (logid:c023ae56) ===START=== 10.66.50.7 --
GET
command=readyForShutdown&response=json&sessionkey=UGRrC2wWDkw5CRvUWGyKWVL97gQ
2025-10-20 18:12:34,967 DEBUG [c.c.a.ApiServlet]
(qtp510109769-48735:ctx-db2e63a3) (logid:c023ae56) Two factor authentication is
already verified for the user 2, so skipping
2025-10-20 18:12:34,984 DEBUG [c.c.a.ApiServer]
(qtp510109769-48735:ctx-db2e63a3 ctx-828d5846) (logid:c023ae56) CIDRs from
which account 'Account
[{"accountName":"admin","id":2,"uuid":"2613b845-8888-11ef-82b0-0050568d3660"}]'
is allowed to perform API calls: 0.0.0.0/0,::/0
2025-10-20 18:12:34,990 DEBUG [o.a.c.a.StaticRoleBasedAPIAccessChecker]
(qtp510109769-48735:ctx-db2e63a3 ctx-828d5846) (logid:c023ae56) RoleService is
enabled. We will use it instead of StaticRoleBasedAPIAccessChecker.
2025-10-20 18:12:34,991 DEBUG [o.a.c.r.ApiRateLimitServiceImpl]
(qtp510109769-48735:ctx-db2e63a3 ctx-828d5846) (logid:c023ae56) API rate
limiting is disabled. We will not use ApiRateLimitService.
2025-10-20 18:12:34,994 DEBUG [c.c.a.ApiServlet]
(qtp510109769-48735:ctx-db2e63a3 ctx-828d5846) (logid:c023ae56) ===END===
10.66.50.7 -- GET
command=readyForShutdown&response=json&sessionkey=UGRrC2wWDkw5CRvUWGyKWVL97gQ
2025-10-20 18:12:37,670 DEBUG [c.c.a.m.AgentManagerImpl]
(AgentManager-Handler-13:null) (logid:) Ping from Routing host 10(node5)
2025-10-20 18:12:37,671 DEBUG [c.c.v.VirtualMachinePowerStateSyncImpl]
(AgentManager-Handler-13:null) (logid:) Process host VM state report from ping
process. host: 10
2025-10-20 18:12:37,704 DEBUG [c.c.v.VirtualMachinePowerStateSyncImpl]
(AgentManager-Handler-13:null) (logid:) Process VM state report. host: 10,
number of records in report: 16
2025-10-20 18:12:37,704 DEBUG [c.c.v.VirtualMachinePowerStateSyncImpl]
(AgentManager-Handler-13:null) (logid:) VM state report. host: 10, vm id: 577,
power state: PowerOn
2025-10-20 18:12:37,706 DEBUG [c.c.v.VirtualMachinePowerStateSyncImpl]
(AgentManager-Handler-13:null) (logid:) VM state report. host: 10, vm id: 1089,
power state: PowerOn
2025-10-20 18:12:37,709 DEBUG [c.c.v.VirtualMachinePowerStateSyncImpl]
(AgentManager-Handler-13:null) (logid:) VM state report. host: 10, vm id: 578,
power state: PowerOn
2025-10-20 18:12:37,713 DEBUG [c.c.v.VirtualMachinePowerStateSyncImpl]
(AgentManager-Handler-13:null) (logid:) VM state report. host: 10, vm id: 1122,
power state: PowerOn
```
and nothing else about this connection till timeout on console proxy vm
GitHub link:
https://github.com/apache/cloudstack/discussions/11866#discussioncomment-14731409
----
This is an automatically sent email for [email protected].
To unsubscribe, please send an email to: [email protected]
