You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@cloudstack.apache.org by "Srikanteswararao Talluri (JIRA)" <ji...@apache.org> on 2013/06/12 11:34:20 UTC

[jira] [Commented] (CLOUDSTACK-2510) 2013-05-15 10:56:24,530 ERROR [cloud.api.ApiServer] (catalina-exec-14:null) unhandled exception executing api command: listStoragePools java.lang.NullPointerException

    [ https://issues.apache.org/jira/browse/CLOUDSTACK-2510?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13681068#comment-13681068 ] 

Srikanteswararao Talluri commented on CLOUDSTACK-2510:
------------------------------------------------------

I have hit this issue on vmware setup while adding second zone wide primary storage. Another observation is , this issue is not seen after some time. I could list the storage pools successfully

===START===  10.252.192.7 -- GET  command=createStoragePool&scope=zone&zoneid=15a42cd5-317c-4c48-b331-d1cc3f9f2eea&hypervisor=VMware&name=zwps2&url=nfs%3A%2F%2F10.147.28.7%2Fexport%2Fhome%2Ftalluri%2Fvmwarezwps2&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&_=1371029094785
2013-06-12 10:49:05,614 DEBUG [cloud.storage.StorageManagerImpl] (catalina-exec-19:null) Adding pool null to  host 1
2013-06-12 10:49:05,622 DEBUG [agent.transport.Request] (catalina-exec-19:null) Seq 1-2112815925: Sending  { Cmd , MgmtId: 7635042566263, via: 1, Ver: v1, Flags: 100011, [{"ModifyStoragePoolCommand":{"add":true,"pool":{"id":4,"uuid":"7a535f47-191f-3048-a0a0-f5d97b010c4e","host":"10.147.28.7","path":"/export/home/talluri/vmwarezwps2","port":2049,"type":"NetworkFilesystem"},"localPath":"/mnt//7a535f47-191f-3048-a0a0-f5d97b010c4e","wait":0}}] }
2013-06-12 10:49:05,623 DEBUG [agent.transport.Request] (catalina-exec-19:null) Seq 1-2112815925: Executing:  { Cmd , MgmtId: 7635042566263, via: 1, Ver: v1, Flags: 100011, [{"ModifyStoragePoolCommand":{"add":true,"pool":{"id":4,"uuid":"7a535f47-191f-3048-a0a0-f5d97b010c4e","host":"10.147.28.7","path":"/export/home/talluri/vmwarezwps2","port":2049,"type":"NetworkFilesystem"},"localPath":"/mnt//7a535f47-191f-3048-a0a0-f5d97b010c4e","wait":0}}] }
2013-06-12 10:49:05,623 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-206:null) Seq 1-2112815925: Executing request
2013-06-12 10:49:05,624 INFO  [vmware.resource.VmwareResource] (DirectAgent-206:10.147.40.7) Executing resource ModifyStoragePoolCommand: {"add":true,"pool":{"id":4,"uuid":"7a535f47-191f-3048-a0a0-f5d97b010c4e","host":"10.147.28.7","path":"/export/home/talluri/vmwarezwps2","port":2049,"type":"NetworkFilesystem"},"localPath":"/mnt//7a535f47-191f-3048-a0a0-f5d97b010c4e","wait":0}
2013-06-12 10:49:06,041 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===START===  10.252.192.7 -- GET  command=queryAsyncJobResult&jobId=ec2f72db-0095-4ca0-aa9d-bc2e45103964&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&_=1371029095223
2013-06-12 10:49:06,074 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===END===  10.252.192.7 -- GET  command=queryAsyncJobResult&jobId=ec2f72db-0095-4ca0-aa9d-bc2e45103964&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&_=1371029095223
2013-06-12 10:49:07,249 DEBUG [cloud.api.ApiServlet] (catalina-exec-23:null) ===START===  10.252.192.7 -- GET  command=listZones&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&_=1371029096459
2013-06-12 10:49:07,251 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null) ===START===  10.252.192.33 -- GET  command=queryAsyncJobResult&jobId=undefined&response=json&sessionkey=%2BuRMVVnN2JBVe6oDv%2B0ZAqV8gso%3D&_=1371029096334
2013-06-12 10:49:07,268 DEBUG [cloud.api.ApiDispatcher] (catalina-exec-1:null) Object entity uuid = undefined does not exist in the database.
2013-06-12 10:49:07,269 INFO  [cloud.api.ApiServer] (catalina-exec-1:null) Unable to execute API command queryasyncjobresult due to invalid value. Invalid parameter jobid value=undefined due to incorrect long value format, or entity does not exist or due to incorrect parameter annotation for the field in api cmd class.
2013-06-12 10:49:07,272 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null) ===END===  10.252.192.33 -- GET  command=queryAsyncJobResult&jobId=undefined&response=json&sessionkey=%2BuRMVVnN2JBVe6oDv%2B0ZAqV8gso%3D&_=1371029096334
2013-06-12 10:49:07,277 DEBUG [cloud.api.ApiServlet] (catalina-exec-23:null) ===END===  10.252.192.7 -- GET  command=listZones&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&_=1371029096459
2013-06-12 10:49:07,310 DEBUG [cloud.api.ApiServlet] (catalina-exec-13:null) ===START===  10.252.192.7 -- GET  command=listPods&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&page=1&pagesize=1&_=1371029096520
2013-06-12 10:49:07,325 DEBUG [cloud.api.ApiServlet] (catalina-exec-13:null) ===END===  10.252.192.7 -- GET  command=listPods&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&page=1&pagesize=1&_=1371029096520
2013-06-12 10:49:07,361 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) ===START===  10.252.192.7 -- GET  command=listClusters&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&page=1&pagesize=1&_=1371029096568
2013-06-12 10:49:07,384 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) ===END===  10.252.192.7 -- GET  command=listClusters&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&page=1&pagesize=1&_=1371029096568
2013-06-12 10:49:07,417 DEBUG [cloud.api.ApiServlet] (catalina-exec-25:null) ===START===  10.252.192.7 -- GET  command=listHosts&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&type=routing&page=1&pagesize=1&_=1371029096627
2013-06-12 10:49:07,439 DEBUG [api.query.QueryManagerImpl] (catalina-exec-25:null) >>>Searching for hosts>>>
2013-06-12 10:49:07,457 DEBUG [api.query.QueryManagerImpl] (catalina-exec-25:null) >>>Generating Response>>>
2013-06-12 10:49:07,463 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is ready to launch secondary storage VM
2013-06-12 10:49:07,480 DEBUG [cloud.api.ApiServlet] (catalina-exec-25:null) ===END===  10.252.192.7 -- GET  command=listHosts&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&type=routing&page=1&pagesize=1&_=1371029096627
2013-06-12 10:49:07,516 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===START===  10.252.192.7 -- GET  command=listStoragePools&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&page=1&pagesize=1&_=1371029096725
2013-06-12 10:49:07,534 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===END===  10.252.192.7 -- GET  command=listStoragePools&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&page=1&pagesize=1&_=1371029096725
2013-06-12 10:49:07,569 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===START===  10.252.192.7 -- GET  command=listHosts&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&type=SecondaryStorage&page=1&pagesize=1&_=1371029096778
2013-06-12 10:49:07,577 DEBUG [api.query.QueryManagerImpl] (catalina-exec-7:null) >>>Searching for hosts>>>
2013-06-12 10:49:07,588 DEBUG [api.query.QueryManagerImpl] (catalina-exec-7:null) >>>Generating Response>>>
2013-06-12 10:49:07,591 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===END===  10.252.192.7 -- GET  command=listHosts&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&type=SecondaryStorage&page=1&pagesize=1&_=1371029096778
2013-06-12 10:49:07,624 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===START===  10.252.192.7 -- GET  command=listSystemVms&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&page=1&pagesize=1&_=1371029096833
2013-06-12 10:49:07,734 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===END===  10.252.192.7 -- GET  command=listSystemVms&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&page=1&pagesize=1&_=1371029096833
2013-06-12 10:49:07,768 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) ===START===  10.252.192.7 -- GET  command=listRouters&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&projectid=-1&page=1&pagesize=1&_=1371029096977
2013-06-12 10:49:07,791 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) ===END===  10.252.192.7 -- GET  command=listRouters&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&projectid=-1&page=1&pagesize=1&_=1371029096977
2013-06-12 10:49:07,813 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is ready to launch console proxy
2013-06-12 10:49:07,825 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===START===  10.252.192.7 -- GET  command=listRouters&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&listAll=true&page=1&pagesize=1&_=1371029097035
2013-06-12 10:49:07,849 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===END===  10.252.192.7 -- GET  command=listRouters&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&listAll=true&page=1&pagesize=1&_=1371029097035
2013-06-12 10:49:07,884 DEBUG [cloud.api.ApiServlet] (catalina-exec-20:null) ===START===  10.252.192.7 -- GET  command=listCapacity&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&_=1371029097093
2013-06-12 10:49:07,907 DEBUG [cloud.api.ApiServlet] (catalina-exec-20:null) ===END===  10.252.192.7 -- GET  command=listCapacity&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&_=1371029097093
2013-06-12 10:49:08,956 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===START===  10.252.192.7 -- GET  command=listStoragePools&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&page=1&pageSize=20&listAll=true&_=1371029098162
2013-06-12 10:49:08,977 ERROR [cloud.api.ApiServer] (catalina-exec-5:null) unhandled exception executing api command: listStoragePools
java.lang.NullPointerException
	at com.cloud.api.query.dao.StoragePoolJoinDaoImpl.newStoragePoolResponse(StoragePoolJoinDaoImpl.java:86)
	at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
	at com.cloud.api.ApiDBUtils.newStoragePoolResponse(ApiDBUtils.java:1578)
	at com.cloud.api.query.ViewResponseHelper.createStoragePoolResponse(ViewResponseHelper.java:275)
	at com.cloud.api.query.QueryManagerImpl.searchForStoragePools(QueryManagerImpl.java:1882)
	at org.apache.cloudstack.api.command.admin.storage.ListStoragePoolsCmd.execute(ListStoragePoolsCmd.java:121)
	at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:155)
	at com.cloud.api.ApiServer.queueCommand(ApiServer.java:528)
	at com.cloud.api.ApiServer.handleRequest(ApiServer.java:371)
	at com.cloud.api.ApiServlet.processRequest(ApiServlet.java:304)
	at com.cloud.api.ApiServlet.doGet(ApiServlet.java:66)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:617)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
	at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:555)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:298)
	at org.apache.coyote.http11.Http11NioProcessor.process(Http11NioProcessor.java:889)
	at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:721)
	at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:2268)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
	at java.lang.Thread.run(Thread.java:679)
2013-06-12 10:49:08,982 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===END===  10.252.192.7 -- GET  command=listStoragePools&response=json&sessionkey=78yZ9E1cc1DcOk9TqRoqnU2ycyU%3D&page=1&pageSize=20&listAll=true&_=1371029098162

                
> 2013-05-15 10:56:24,530 ERROR [cloud.api.ApiServer] (catalina-exec-14:null) unhandled exception executing api command: listStoragePools java.lang.NullPointerException
> ----------------------------------------------------------------------------------------------------------------------------------------------------------------------
>
>                 Key: CLOUDSTACK-2510
>                 URL: https://issues.apache.org/jira/browse/CLOUDSTACK-2510
>             Project: CloudStack
>          Issue Type: Bug
>      Security Level: Public(Anyone can view this level - this is the default.) 
>          Components: Management Server
>    Affects Versions: 4.2.0
>            Reporter: prashant kumar mishra
>             Fix For: 4.2.0
>
>         Attachments: access_log.2013-05-15.txt, apilog.log, catalina.out, management-server.log, screenshot-1.jpg
>
>
> Tried to add a primary storage to empty xen cluster ;after this event not able to list primary storage getting NPE .
> Steps to reproduce
> ---------------------------
> 1-Add a empty xen cluster(without any host)
> 2-Add a cluster wide primary storage to cluster created in step1
> 3-Try to list primary storage
> Actual
> ---------------
> Primary storage is not getting listed
> API response
> -------------------
> "NetworkError: 530  - http://10.*.*.*:8080/client/api?command=listStoragePools&response=json&sessionkey=uuXcmrcMh1PSlwGk%2Fd0fBWnGp7s%3D&page=1&pageSize=20&listAll=true&_=
> Snippet of log
> --------------------
>  2013-05-15 11:01:19,245 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===START===  10.146.0.130 -- GET  command=listStoragePools&response=json&sessionkey=uuXcmrcMh1PSlwGk%2Fd0fBWnGp7s%3D&page=1&pageSize=20&listAll=true&_=1368610481963
> 2013-05-15 11:01:19,268 ERROR [cloud.api.ApiServer] (catalina-exec-10:null) unhandled exception executing api command: listStoragePools
> java.lang.NullPointerException
>         at com.cloud.api.query.dao.StoragePoolJoinDaoImpl.newStoragePoolResponse(StoragePoolJoinDaoImpl.java:87)
>         at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
>         at com.cloud.api.ApiDBUtils.newStoragePoolResponse(ApiDBUtils.java:1568)
>         at com.cloud.api.query.ViewResponseHelper.createStoragePoolResponse(ViewResponseHelper.java:277)
>         at com.cloud.api.query.QueryManagerImpl.searchForStoragePools(QueryManagerImpl.java:1865)
>         at org.apache.cloudstack.api.command.admin.storage.ListStoragePoolsCmd.execute(ListStoragePoolsCmd.java:121)
>         at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:155)
>         at com.cloud.api.ApiServer.queueCommand(ApiServer.java:519)
>         at com.cloud.api.ApiServer.handleRequest(ApiServer.java:369)
>         at com.cloud.api.ApiServlet.processRequest(ApiServlet.java:304)
>         at com.cloud.api.ApiServlet.doGet(ApiServlet.java:66)
>         at javax.servlet.http.HttpServlet.service(HttpServlet.java:617)
>         at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
>         at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
>         at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
>         at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
>         at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
>         at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
>         at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
>         at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:555)
>         at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
>         at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:298)
>         at org.apache.coyote.http11.Http11NioProcessor.process(Http11NioProcessor.java:889)
>         at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:721)
>         at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:2268)
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
>         at java.lang.Thread.run(Thread.java:679)

--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators
For more information on JIRA, see: http://www.atlassian.com/software/jira