AndreaGHG commented on issue #3164: Cannot open console: ConsoleProxyServlet 
NullPointerException
URL: https://github.com/apache/cloudstack/issues/3164#issuecomment-469742868
 
 
   This is the managemente-server.log when I try to open an instance console:
   
   <pre>
   2019-03-05 17:09:38,224 DEBUG [c.c.a.t.Request] (qtp510113906-14:null) 
(logid:) Seq 1-5531546242317840041: Sending  { Cmd , MgmtId: 345052229470, via: 
1(cs-esxy3-san.hypergrid.it), Ver: v1, Flags: 100011, 
[{"com.cloud.agent.api.GetVncPortCommand":{"id":46,"name":"i-10-46-VM","wait":0}}]
 }
   2019-03-05 17:09:38,224 DEBUG [c.c.a.t.Request] (qtp510113906-14:null) 
(logid:) Seq 1-5531546242317840041: Executing:  { Cmd , MgmtId: 345052229470, 
via: 1(cs-esxy3-san.hypergrid.it), Ver: v1, Flags: 100011, 
[{"com.cloud.agent.api.GetVncPortCommand":{"id":46,"name":"i-10-46-VM","wait":0}}]
 }
   2019-03-05 17:09:38,224 DEBUG [c.c.a.m.DirectAgentAttache] 
(DirectAgent-302:ctx-39f3edba) (logid:e9ae50da) Seq 1-5531546242317840041: 
Executing request
   2019-03-05 17:09:38,242 DEBUG [c.c.h.v.m.HostMO] 
(DirectAgent-302:ctx-39f3edba cs-esxy3-san.hypergrid.it, cmd: 
GetVncPortCommand) (logid:e9ae50da) find VM i-10-46-VM on host
   2019-03-05 17:09:38,242 INFO  [c.c.h.v.m.HostMO] 
(DirectAgent-302:ctx-39f3edba cs-esxy3-san.hypergrid.it, cmd: 
GetVncPortCommand) (logid:e9ae50da) VM i-10-46-VM not found in host cache
   2019-03-05 17:09:38,242 DEBUG [c.c.h.v.m.HostMO] 
(DirectAgent-302:ctx-39f3edba cs-esxy3-san.hypergrid.it, cmd: 
GetVncPortCommand) (logid:e9ae50da) load VM cache on host
   2019-03-05 17:09:38,394 DEBUG [c.c.a.m.DirectAgentAttache] 
(DirectAgent-302:ctx-39f3edba) (logid:e9ae50da) Seq 1-5531546242317840041: 
Response Received: 
   2019-03-05 17:09:38,394 DEBUG [c.c.a.t.Request] 
(DirectAgent-302:ctx-39f3edba) (logid:e9ae50da) Seq 1-5531546242317840041: 
Processing:  { Ans: , MgmtId: 345052229470, via: 1(cs-esxy3-san.hypergrid.it), 
Ver: v1, Flags: 10, 
[{"com.cloud.agent.api.GetVncPortAnswer":{"address":"10.168.1.173","port":5950,"result":true,"wait":0}}]
 }
   2019-03-05 17:09:38,394 DEBUG [c.c.a.t.Request] (qtp510113906-14:null) 
(logid:) Seq 1-5531546242317840041: Received:  { Ans: , MgmtId: 345052229470, 
via: 1(cs-esxy3-san.hypergrid.it), Ver: v1, Flags: 10, { GetVncPortAnswer } }
   2019-03-05 17:09:38,394 DEBUG [c.c.s.ConsoleProxyServlet] 
(qtp510113906-14:null) (logid:) Port info 10.168.1.173
   2019-03-05 17:09:38,394 INFO  [c.c.s.ConsoleProxyServlet] 
(qtp510113906-14:null) (logid:) Parse host info returned from executing 
GetVNCPortCommand. host info: 10.168.1.173
   2019-03-05 17:09:38,400 DEBUG [c.c.s.ConsoleProxyServlet] 
(qtp510113906-14:null) (logid:) Compose console url: 
http://cs-proxy1-pub.hypergrid.it/ajax?token=Nb4P4M53ZEwJ7ommgbcBCAfcNCpx2uOjUJxhExVQh8Xk_nbJlbVMdE9hNOeX8lyUe-icsH4QB8Xz8Qpa68EjwJS5BFW6gT_56kO7uD0nJoUxG7G51SXtKU7O3UTb2MpoexKgXP9of98foLvF0VFJZXUKcbuaSuhx-G4awJEa3kELov6zSmtT2Lskttf0nACO95KTldFwP6Il4r5b6UIuKOQgAMleopW7SCgJjkeZu6rdq-0NguhkJBfs3NY7YG1Pb-FKiKQ5MjbZI_K3v697_arQ1U0gZn6xSwmTdaY4EFI
   2019-03-05 17:09:38,401 DEBUG [c.c.s.ConsoleProxyServlet] 
(qtp510113906-14:null) (logid:) the console url is :: 
<html><title>galera4</title><frameset><frame 
src="http://cs-proxy1-pub.hypergrid.it/ajax?token=Nb4P4M53ZEwJ7ommgbcBCAfcNCpx2uOjUJxhExVQh8Xk_nbJlbVMdE9hNOeX8lyUe-icsH4QB8Xz8Qpa68EjwJS5BFW6gT_56kO7uD0nJoUxG7G51SXtKU7O3UTb2MpoexKgXP9of98foLvF0VFJZXUKcbuaSuhx-G4awJEa3kELov6zSmtT2Lskttf0nACO95KTldFwP6Il4r5b6UIuKOQgAMleopW7SCgJjkeZu6rdq-0NguhkJBfs3NY7YG1Pb-FKiKQ5MjbZI_K3v697_arQ1U0gZn6xSwmTdaY4EFI";></frame></frameset></html>
   2019-03-05 17:09:42,411 DEBUG [o.a.c.s.SecondaryStorageManagerImpl] 
(secstorage-1:ctx-c08f9763) (logid:33004849) Zone 2 is ready to launch 
secondary storage VM
   2019-03-05 17:09:42,565 DEBUG [c.c.c.ConsoleProxyManagerImpl] 
(consoleproxy-1:ctx-0acfabfb) (logid:fc01f57f) Zone 2 is ready to launch 
console proxy
   2019-03-05 17:09:42,820 DEBUG [c.c.s.StatsCollector] 
(StatsCollector-4:ctx-8e9a73b3) (logid:0d6f34c2) AutoScaling Monitor is 
running...
   </pre>
   
   This is the section of 'Sending Connect to listener: ConsoleProxyListener' 
in the management-server.log:
   
   <pre>
   2019-03-05 15:36:51,608 DEBUG [c.c.a.m.AgentManagerImpl] 
(AgentConnectTaskPool-7:ctx-75349498) (logid:bba0aad4) Sending Connect to 
listener: ConsoleProxyListener
   2019-03-05 15:36:51,612 ERROR [c.c.c.AgentHookBase] 
(AgentConnectTaskPool-7:ctx-75349498) (logid:bba0aad4) Unexpected exception 
when sending http handling startup command(time out) to the console proxy 
resource for proxy:69
   com.cloud.utils.exception.CloudRuntimeException: Unable to find keystore 
CPVMCertificate
           at 
org.apache.cloudstack.framework.security.keystore.KeystoreManagerImpl.getKeystoreBits(KeystoreManagerImpl.java:96)
           at 
com.cloud.consoleproxy.AgentHookBase.startAgentHttpHandlerInVM(AgentHookBase.java:205)
           at 
com.cloud.consoleproxy.ConsoleProxyListener.processConnect(ConsoleProxyListener.java:75)
           at 
com.cloud.agent.manager.AgentManagerImpl.notifyMonitorsOfConnection(AgentManagerImpl.java:570)
           at 
com.cloud.agent.manager.AgentManagerImpl.handleConnectedAgent(AgentManagerImpl.java:1111)
           at 
com.cloud.agent.manager.AgentManagerImpl.access$000(AgentManagerImpl.java:126)
           at 
com.cloud.agent.manager.AgentManagerImpl$HandleAgentConnectTask.runInContext(AgentManagerImpl.java:1195)
           at 
org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
           at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
           at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
           at 
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
           at 
org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
           at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
           at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
           at java.lang.Thread.run(Thread.java:748)
   2019-03-05 15:36:51,613 DEBUG [c.c.a.m.AgentManagerImpl] 
(AgentConnectTaskPool-7:ctx-75349498) (logid:bba0aad4) Sending Connect to 
listener: DownloadListener
   2019-03-05 15:36:51,614 DEBUG [c.c.a.m.AgentManagerImpl] 
(AgentConnectTaskPool-7:ctx-75349498) (logid:bba0aad4) Sending Connect to 
listener: UploadListener
   2019-03-05 15:36:51,620 DEBUG [c.c.a.t.Request] 
(AgentConnectTaskPool-7:ctx-75349498) (logid:bba0aad4) Seq 
11-5734771175502905345: Sending  { Cmd , MgmtId: 345052229470, via: 
11(v-69-VM), Ver: v1, Flags: 100111, 
[{"com.cloud.agent.api.ReadyCommand":{"dcId":2,"hostId":11,"wait":0}}] }
   2019-03-05 15:36:51,687 DEBUG [c.c.a.m.AgentManagerImpl] 
(AgentManager-Handler-15:null) (logid:) Ping from 11(v-69-VM)
   2019-03-05 15:36:51,745 DEBUG [c.c.a.t.Request] 
(AgentManager-Handler-1:null) (logid:) Seq 11-5734771175502905345: Processing:  
{ Ans: , MgmtId: 345052229470, via: 11, Ver: v1, Flags: 110, 
[{"com.cloud.agent.api.ReadyAnswer":{"result":true,"wait":0}}] }
   2019-03-05 15:36:51,745 DEBUG [c.c.a.t.Request] 
(AgentConnectTaskPool-7:ctx-75349498) (logid:bba0aad4) Seq 
11-5734771175502905345: Received:  { Ans: , MgmtId: 345052229470, via: 
11(v-69-VM), Ver: v1, Flags: 110, { ReadyAnswer } }
   2019-03-05 15:36:51,750 DEBUG [c.c.h.Status] 
(AgentConnectTaskPool-7:ctx-75349498) (logid:bba0aad4) Transition:[Resource 
state = Enabled, Agent event = Ready, Host id = 11, name = v-69-VM]
   2019-03-05 15:36:51,751 DEBUG [c.c.a.m.AgentAttache] 
(AgentManager-Handler-1:null) (logid:) Seq 11-5734771175502905345: No more 
commands found
   2019-03-05 15:36:51,775 DEBUG [c.c.a.t.Request] 
(AgentConnectTaskPool-7:ctx-75349498) (logid:bba0aad4) Seq 
11-5734771175502905346: Sending  { Cmd , MgmtId: 345052229470, via: 
11(v-69-VM), Ver: v1, Flags: 100111, 
[{"com.cloud.agent.api.ReadyCommand":{"dcId":2,"hostId":11,"msHostList":["10.168.1.248"],"lbAlgorithm":"static","lbCheckInterval":0,"wait":0}}]
 }
   </pre>

----------------------------------------------------------------
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.
 
For queries about this service, please contact Infrastructure at:
[email protected]


With regards,
Apache Git Services

Reply via email to