[ https://issues.apache.org/jira/browse/CLOUDSTACK-2528?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Sanjeev N reopened CLOUDSTACK-2528: ----------------------------------- Observed NPE while executing extract volume cmd: 2013-05-24 10:43:54,790 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) ===START=== 10.146.0.15 -- GET command=extractVolume&id=497a4f9e-3af8-4535-825e-1153d61bd10a&zoneid=ab0cac49-0ba0-47b6-acc7-a79803852bc7&mode=HTTP_DOWNLOAD&response=json&sessionkey=ixXFsVuKowmsD%2FQeRoCj8INv7Y0%3D&_=1369386924092 2013-05-24 10:43:54,932 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-12:null) submit async job-18, details: AsyncJobVO {id:18, userId: 2, accountId: 2, sessionKey: null, instanceType: Volume, instanceId: 4, cmd: org.apache.cloudstack.api.command.user.volume.ExtractVolumeCmd, cmdOriginator: null, cmdInfo: {"response":"json","id":"497a4f9e-3af8-4535-825e-1153d61bd10a","sessionkey":"ixXFsVuKowmsD/QeRoCj8INv7Y0\u003d","ctxUserId":"2","zoneid":"ab0cac49-0ba0-47b6-acc7-a79803852bc7","httpmethod":"GET","_":"1369386924092","ctxAccountId":"2","ctxStartEventId":"64","mode":"HTTP_DOWNLOAD"}, cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0, processStatus: 0, resultCode: 0, result: null, initMsid: 7332683579487, completeMsid: null, lastUpdated: null, lastPolled: null, created: null} 2013-05-24 10:43:54,935 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) ===END=== 10.146.0.15 -- GET command=extractVolume&id=497a4f9e-3af8-4535-825e-1153d61bd10a&zoneid=ab0cac49-0ba0-47b6-acc7-a79803852bc7&mode=HTTP_DOWNLOAD&response=json&sessionkey=ixXFsVuKowmsD%2FQeRoCj8INv7Y0%3D&_=1369386924092 2013-05-24 10:43:54,938 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-18:job-18) Executing org.apache.cloudstack.api.command.user.volume.ExtractVolumeCmd for job-18 2013-05-24 10:43:54,997 DEBUG [cloud.server.ManagementServerImpl] (Job-Executor-18:job-18) Extract Mode - HTTP_DOWNLOAD 2013-05-24 10:43:55,012 DEBUG [agent.transport.Request] (Job-Executor-18:job-18) Seq 1-698155503: Sending { Cmd , MgmtId: 7332683579487, via: 1, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.storage.CopyVolumeCommand":{"volumeId":4,"volumePath":"0ebe4e49-0147-406a-8af5-17e1c3bb97af","pool":{"id":1,"uuid":"c65a038a-750c-3b4f-bf26-7ce3b74e1c85","host":"10.147.28.7","path":"/export/home/sanjeev/pri_xen_os","port":2049,"type":"NetworkFilesystem"},"toSecondaryStorage":true,"wait":10800}}] } 2013-05-24 10:43:55,012 DEBUG [agent.transport.Request] (Job-Executor-18:job-18) Seq 1-698155503: Executing: { Cmd , MgmtId: 7332683579487, via: 1, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.storage.CopyVolumeCommand":{"volumeId":4,"volumePath":"0ebe4e49-0147-406a-8af5-17e1c3bb97af","pool":{"id":1,"uuid":"c65a038a-750c-3b4f-bf26-7ce3b74e1c85","host":"10.147.28.7","path":"/export/home/sanjeev/pri_xen_os","port":2049,"type":"NetworkFilesystem"},"toSecondaryStorage":true,"wait":10800}}] } 2013-05-24 10:43:55,013 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-434:null) Seq 1-698155503: Executing request 2013-05-24 10:43:55,103 WARN [xen.resource.CitrixResourceBase] (DirectAgent-434:null) Catch Exception java.lang.NullPointerException due to java.lang.NullPointerException java.lang.NullPointerException at java.net.URI$Parser.parse(URI.java:3019) at java.net.URI.<init>(URI.java:595) at com.cloud.hypervisor.xen.resource.CitrixResourceBase.execute(CitrixResourceBase.java:6447) at com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixResourceBase.java:557) at com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenServer56Resource.java:73) at com.cloud.hypervisor.xen.resource.XenServer610Resource.executeRequest(XenServer610Resource.java:104) at com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgentAttache.java:186) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:165) at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:679) 2013-05-24 10:43:55,105 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-434:null) Seq 1-698155503: Response Received: 2013-05-24 10:43:55,106 DEBUG [agent.transport.Request] (DirectAgent-434:null) Seq 1-698155503: Processing: { Ans: , MgmtId: 7332683579487, via: 1, Ver: v1, Flags: 110, [{"com.cloud.agent.api.storage.CopyVolumeAnswer":{"result":false,"details":"Catch Exception java.lang.NullPointerException due to java.lang.NullPointerException","wait":0}}] } 2013-05-24 10:43:55,106 DEBUG [agent.transport.Request] (Job-Executor-18:job-18) Seq 1-698155503: Received: { Ans: , MgmtId: 7332683579487, via: 1, Ver: v1, Flags: 110, { CopyVolumeAnswer } } 2013-05-24 10:43:55,112 DEBUG [agent.manager.AgentAttache] (DirectAgent-434:null) Seq 1-698155503: No more commands found 2013-05-24 10:43:55,128 ERROR [cloud.async.AsyncJobManagerImpl] (Job-Executor-18:job-18) Unexpected exception while executing org.apache.cloudstack.api.command.user.volume.ExtractVolumeCmd com.cloud.utils.exception.CloudRuntimeException: Failed to copy the volume from the source primary storage pool to secondary storage. at com.cloud.server.ManagementServerImpl.extractVolume(ManagementServerImpl.java:3526) at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125) at org.apache.cloudstack.api.command.user.volume.ExtractVolumeCmd.execute(ExtractVolumeCmd.java:131) at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:155) at com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:437) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:679) 2013-05-24 10:43:55,129 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-18:job-18) Complete async job-18, jobStatus: 2, resultCode: 530, result: Error Code: 530 Error text: Failed to copy the volume from the source primary storage pool to secondary storage. This is observed in latest object_store branch. Build: CloudStack-non-OSS-object_store-47-rhel6.3.tar.gz > Object_Store_Refactor - Not able to download volumes. > ----------------------------------------------------- > > Key: CLOUDSTACK-2528 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-2528 > 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 > Environment: Build from object_store > Reporter: Sangeetha Hariharan > Assignee: edison su > Fix For: 4.2.0 > > > 1.Using UI/createVolume API create new volume. > 2.Bring volume to ready state. > 3.Download volume using UI/extractVolume API > Volume downlaod fails. > Following exception seen in management server logs: > 8ce-dbb58722c34c&zoneid=387432e3-2470-475b-ae39-f4ef14e91779&mode=HTTP_DOWNLOAD&response=json&sessionkey=s5BZB6QnqMRZJHgCPOGxdTxPjMY%3D&_=1368668522760 > 2013-05-15 17:45:02,547 DEBUG [cloud.async.AsyncJobManagerImpl] > (catalina-exec-14:null) submit async job-47, details: AsyncJobVO {id:47, > userId: 2, accountId: 2, sessionKey: null, instanceType: Volume, instanceId: > 16, cmd: org.apache.cloudstack.api.command.user.volume.ExtractVolumeCmd, > cmdOriginator: null, cmdInfo: > {"id":"0e03eb25-8b1b-4f93-b8ce-dbb58722c34c","response":"json","sessionkey":"s5BZB6QnqMRZJHgCPOGxdTxPjMY\u003d","ctxUserId":"2","zoneid":"387432e3-2470-475b-ae39-f4ef14e91779","httpmethod":"GET","_":"1368668522760","ctxAccountId":"2","ctxStartEventId":"270","mode":"HTTP_DOWNLOAD"}, > cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0, > processStatus: 0, resultCode: 0, result: null, initMsid: 206915885079359, > completeMsid: null, lastUpdated: null, lastPolled: null, created: null} > 2013-05-15 17:45:02,548 DEBUG [cloud.async.AsyncJobManagerImpl] > (Job-Executor-11:job-47) Executing > org.apache.cloudstack.api.command.user.volume.ExtractVolumeCmd for job-47 > 2013-05-15 17:45:02,550 DEBUG [cloud.api.ApiServlet] (catalina-exec-14:null) > ===END=== 10.217.252.128 -- GET > command=extractVolume&id=0e03eb25-8b1b-4f93-b8ce-dbb58722c34c&zoneid=387432e3-2470-475b-ae39-f4ef14e91779&mode=HTTP_DOWNLOAD&response=json&sessionkey=s5BZB6QnqMRZJHgCPOGxdTxPjMY%3D&_=1368668522760 > 2013-05-15 17:45:02,598 DEBUG [cloud.server.ManagementServerImpl] > (Job-Executor-11:job-47) Extract Mode - HTTP_DOWNLOAD > 2013-05-15 17:45:02,604 DEBUG [agent.transport.Request] > (Job-Executor-11:job-47) Seq 1-1843200414: Sending { Cmd , MgmtId: > 206915885079359, via: 1, Ver: v1, Flags: 100111, > [{"com.cloud.agent.api.storage.CopyVolumeCommand":{"volumeId":16,"volumePath":"64bf190f-6e90-4fc1-86eb-5340c894e328","pool":{"id":1,"uuid":"e97b5727-d157-3dc1-b32c-065b99806623","host":"10.223.110.232","path":"/export/home/sangeetha/campo-systemp-1/primary","port":2049,"type":"NetworkFilesystem"},"secondaryStorageURL":"nfs://10.223.110.232/export/home/sangeetha/campo-systemp-1/secondary","toSecondaryStorage":true,"wait":10800}}] > } > 2013-05-15 17:45:02,604 DEBUG [agent.transport.Request] > (Job-Executor-11:job-47) Seq 1-1843200414: Executing: { Cmd , MgmtId: > 206915885079359, via: 1, Ver: v1, Flags: 100111, > [{"com.cloud.agent.api.storage.CopyVolumeCommand":{"volumeId":16,"volumePath":"64bf190f-6e90-4fc1-86eb-5340c894e328","pool":{"id":1,"uuid":"e97b5727-d157-3dc1-b32c-065b99806623","host":"10.223.110.232","path":"/export/home/sangeetha/campo-systemp-1/primary","port":2049,"type":"NetworkFilesystem"},"secondaryStorageURL":"nfs://10.223.110.232/export/home/sangeetha/campo-systemp-1/secondary","toSecondaryStorage":true,"wait":10800}}] > } > 2013-05-15 17:45:21,872 DEBUG [agent.transport.Request] > (Job-Executor-11:job-47) Seq 1-1843200414: Received: { Ans: , MgmtId: > 206915885079359, via: 1, Ver: v1, Flags: 110, { CopyVolumeAnswer } } > 2013-05-15 17:45:21,872 DEBUG [agent.manager.AgentAttache] > (DirectAgent-421:null) Seq 1-1843200414: No more commands found > 2013-05-15 17:45:21,964 ERROR [cloud.async.AsyncJobManagerImpl] > (Job-Executor-11:job-47) Unexpected exception while executing > org.apache.cloudstack.api.command.user.volume.ExtractVolumeCmd > java.lang.ClassCastException: > org.apache.cloudstack.storage.image.store.ImageStoreImpl cannot be cast to > org.apache.cloudstack.storage.datastore.db.ImageStoreVO > at > com.cloud.storage.upload.UploadMonitorImpl.createVolumeDownloadURL(UploadMonitorImpl.java:289) > at > com.cloud.server.ManagementServerImpl.extractVolume(ManagementServerImpl.java:3439) > at > com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125) > at > org.apache.cloudstack.api.command.user.volume.ExtractVolumeCmd.execute(ExtractVolumeCmd.java:131) > at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:155) > at > com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:437) > at > java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) > at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) > at java.util.concurrent.FutureTask.run(FutureTask.java:166) > 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-05-15 17:45:21,964 DEBUG [cloud.async.AsyncJobManagerImpl] > (Job-Executor-11:job-47) Complete async job-47, jobStatus: 2, resultCode: > 530, result: Error Code: 530 Error text: > org.apache.cloudstack.storage.image.store.ImageStoreImpl cannot be cast to > org.apache.cloudstack.storage.datastore.db.ImageStoreVO > 2013-05-15 17:45:23,703 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null) > ===START=== 10.217.252.128 -- GET > command=queryAsyncJobResult&jobId=1ff79668-e6fd-48c0-b1ad-3f4c898bb2f2&response=json&sessionkey=s5BZB6QnqMRZJHgCPOGxdTxPjMY%3D&_=1368668544052 > 2013-05-15 17:45:23,715 DEBUG [cloud.async.AsyncJobManagerImpl] > (catalina-exec-15:null) Async job-47 completed -- 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