[
https://issues.apache.org/jira/browse/CLOUDSTACK-2482?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13659106#comment-13659106
]
Sangeetha Hariharan commented on CLOUDSTACK-2482:
-------------------------------------------------
2013-05-15 17:12:08,164 DEBUG [cloud.async.AsyncJobManagerImpl]
(catalina-exec-18:null) submit async job-38, details: AsyncJobVO {id:38,
userId: 2, accountId: 2, sessionKey: null, instanceType: Volume, instanceId:
15, cmd: org.apache.cloudstack.api.command.user.volume.CreateVolumeCmd,
cmdOriginator: null, cmdInfo:
{"id":"15","response":"json","sessionkey":"s5BZB6QnqMRZJHgCPOGxdTxPjMY\u003d","ctxUserId":"2","snapshotid":"4d6a8843-707a-4b25-9f1e-1224d44cf5ae","name":"volume-from-root-snap","httpmethod":"GET","_":"1368666548291","ctxAccountId":"2","ctxStartEventId":"231"},
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:12:08,166 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-2:job-38) Executing
org.apache.cloudstack.api.command.user.volume.CreateVolumeCmd for job-38
2013-05-15 17:12:08,167 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null)
===END=== 10.217.252.128 -- GET
command=createVolume&response=json&sessionkey=s5BZB6QnqMRZJHgCPOGxdTxPjMY%3D&snapshotid=4d6a8843-707a-4b25-9f1e-1224d44cf5ae&name=volume-from-root-snap&_=1368666548291
2013-05-15 17:12:08,200 DEBUG [cloud.storage.StorageManagerImpl]
(Job-Executor-2:job-38) Checking pool 1 for storage, totalSize: 11810778316800,
usedBytes: 8676258349056, usedPct: 0.7346051306978333, disable threshold: 0.85
2013-05-15 17:12:08,269 DEBUG [cloud.storage.StorageManagerImpl]
(Job-Executor-2:job-38) Checking pool: 1 for volume allocation
[Vol[15|vm=null|DATADISK]], maxSize : 23621556633600, totalAllocatedSize :
158712463360, askingSize : 21474836480, allocated disable threshold: 0.85
2013-05-15 17:12:08,271 DEBUG [allocator.impl.UserConcentratedAllocator]
(Job-Executor-2:job-38) There are no pods with enough memory/CPU capacity in
zone zone1
2013-05-15 17:12:08,332 DEBUG [agent.transport.Request] (Job-Executor-2:job-38)
Seq 1-1843200246: Sending { Cmd , MgmtId: 206915885079359, via: 1, Ver: v1,
Flags: 100111,
[{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.SnapshotObjectTO":{"path":"snapshots/3/3/fdb257a4-9bb4-4ebc-9878-ae936c6771b5","volume":{"uuid":"47b5ecf0-634f-4f8c-bcdb-9e1248ed188c","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"e97b5727-d157-3dc1-b32c-065b99806623","id":1,"poolType":"NetworkFilesystem","host":"10.223.110.232","path":"/export/home/sangeetha/campo-systemp-1/primary","port":2049}},"name":"ROOT-3","size":21474836480,"path":"ad51b53a-acf9-46fe-add2-4e8bc3fcb646","volumeId":3,"vmName":"i-3-3-VM","accountId":3,"id":3},"dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.223.110.232/export/home/sangeetha/campo-systemp-1/secondary","_role":"Image"}},"vmName":"i-3-3-VM","name":"sangee-1_ROOT-3_20130515192006","hypervisorType":"XenServer","id":1}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"dbd95a1e-7d5d-4a3b-99ac-b76763b77179","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"e97b5727-d157-3dc1-b32c-065b99806623","id":1,"poolType":"NetworkFilesystem","host":"10.223.110.232","path":"/export/home/sangeetha/campo-systemp-1/primary","port":2049}},"name":"volume-from-root-snap","size":21474836480,"volumeId":15,"accountId":2,"id":15}},"wait":10800}}]
}
2013-05-15 17:12:08,333 DEBUG [agent.transport.Request] (Job-Executor-2:job-38)
Seq 1-1843200246: Executing: { Cmd , MgmtId: 206915885079359, via: 1, Ver: v1,
Flags: 100111,
[{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.SnapshotObjectTO":{"path":"snapshots/3/3/fdb257a4-9bb4-4ebc-9878-ae936c6771b5","volume":{"uuid":"47b5ecf0-634f-4f8c-bcdb-9e1248ed188c","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"e97b5727-d157-3dc1-b32c-065b99806623","id":1,"poolType":"NetworkFilesystem","host":"10.223.110.232","path":"/export/home/sangeetha/campo-systemp-1/primary","port":2049}},"name":"ROOT-3","size":21474836480,"path":"ad51b53a-acf9-46fe-add2-4e8bc3fcb646","volumeId":3,"vmName":"i-3-3-VM","accountId":3,"id":3},"dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.223.110.232/export/home/sangeetha/campo-systemp-1/secondary","_role":"Image"}},"vmName":"i-3-3-VM","name":"sangee-1_ROOT-3_20130515192006","hypervisorType":"XenServer","id":1}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"dbd95a1e-7d5d-4a3b-99ac-b76763b77179","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"e97b5727-d157-3dc1-b32c-065b99806623","id":1,"poolType":"NetworkFilesystem","host":"10.223.110.232","path":"/export/home/sangeetha/campo-systemp-1/primary","port":2049}},"name":"volume-from-root-snap","size":21474836480,"volumeId":15,"accountId":2,"id":15}},"wait":10800}}]
}
013-05-15 17:13:08,042 DEBUG [agent.transport.Request] (Job-Executor-2:job-38)
Seq 1-1843200246: Recei
ved: { Ans: , MgmtId: 206915885079359, via: 1, Ver: v1, Flags: 110, {
CopyCmdAnswer } }
2013-05-15 17:13:08,042 DEBUG [cloud.storage.VolumeManagerImpl]
(Job-Executor-2:job-38) Failed to creat
e volume from snapshot:java.lang.NullPointerException
2013-05-15 17:13:08,045 DEBUG [cloud.storage.VolumeManagerImpl]
(Job-Executor-2:job-38) Failed to creat
e volume: 15
com.cloud.utils.exception.CloudRuntimeException: Failed to create volume from
snapshot:java.lang.NullPo
interException
at
com.cloud.storage.VolumeManagerImpl.createVolumeFromSnapshot(VolumeManagerImpl.java:548)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercep
t(ComponentInstantiationPostProcessor.java:125)
at
com.cloud.storage.VolumeManagerImpl.createVolumeFromSnapshot(VolumeManagerImpl.java:593)
at
com.cloud.storage.VolumeManagerImpl.createVolume(VolumeManagerImpl.java:1020)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercep
t(ComponentInstantiationPostProcessor.java:125)
at
com.cloud.storage.VolumeManagerImpl.createVolume(VolumeManagerImpl.java:183)
at
org.apache.cloudstack.api.command.user.volume.CreateVolumeCmd.execute(CreateVolumeCmd.java:1
74)
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:13:08,112 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-2:job-38) Complete async
job-38, jobStatus: 2, resultCode: 530, result: Error Code:
530 Error text: Failed to create a volume
2013-05-15 17:13:08,381 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null)
===START=== 10.217.252.128
-- GET
command=queryAsyncJobResult&jobId=e43b9dbd-fd9c-4862-99e3-ac1d566f8f46&response=json&sessionke
y=s5BZB6QnqMRZJHgCPOGxdTxPjMY%3D&_=1368666608732
2013-05-15 17:13:08,397 DEBUG [cloud.async.AsyncJobManagerImpl]
(catalina-exec-9:null) Async job-38 com
pleted
> Object_Store_Refactor - Snapshots - Not able to create volume from snapshot.
> -----------------------------------------------------------------------------
>
> Key: CLOUDSTACK-2482
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-2482
> 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
> Priority: Critical
> Fix For: 4.2.0
>
>
> Steps to reproduce the problem:
> Deploy Vm.
> Create a snapshot of the root volume.
> Create a volume from snapshot.
> This fails.
> Following exception seen in management server logs:
> 2013-05-14 09:55:53,384 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null)
> ===START=== 10.217.252.128 -- GET command=createVolume&response=json&s
>
> essionkey=X%2FK09rgZ%2BYIUuGYkv7UGa0FoBEc%3D&snapshotid=c7bc851c-a7aa-4125-8d8e-f3d70b807e2c&name=Volume123&_=1368553972160
> 2013-05-14 09:55:53,474 DEBUG [cloud.async.AsyncJobManagerImpl]
> (catalina-exec-9:null) submit async job-19, details: AsyncJobVO {id:19,
> userId: 2, a ccountId: 2, sessionKey: null, instanceType:
> Volume, instanceId: 7, cmd:
> org.apache.cloudstack.api.command.user.volume.CreateVolumeCmd, cmdOriginato
> r: null, cmdInfo:
> {"id":"7","response":"json","sessionkey":"X/K09rgZ+YIUuGYkv7UGa0FoBEc\u003d","ctxUserId":"2","snapshotid":"c7bc851c-a7aa-4125-8d8e
>
> -f3d70b807e2c","name":"Volume123","httpmethod":"GET","_":"1368553972160","ctxAccountId":"2","ctxStartEventId":"65"},
> 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-14 09:55:53,476 DEBUG [cloud.async.AsyncJobManagerImpl]
> (Job-Executor-21:job-19) Executing
> org.apache.cloudstack.api.command.user.volume.Cre
> ateVolumeCmd for job-19
> 2013-05-14 09:55:53,477 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null)
> ===END=== 10.217.252.128 -- GET command=createVolume&response=json&ses
>
> sionkey=X%2FK09rgZ%2BYIUuGYkv7UGa0FoBEc%3D&snapshotid=c7bc851c-a7aa-4125-8d8e-f3d70b807e2c&name=Volume123&_=1368553972160
> 2013-05-14 09:55:53,505 DEBUG [cloud.storage.StorageManagerImpl]
> (Job-Executor-21:job-19) Checking pool 1 for storage, totalSize:
> 11810778316800, us edBytes: 8603883175936, usedPct:
> 0.7284772387690642, disable threshold: 0.85
> 2013-05-14 09:55:53,535 DEBUG [cloud.storage.StorageManagerImpl]
> (Job-Executor-21:job-19) Checking pool: 1 for volume allocation
> [Vol[7|vm=null|DATA DISK]], maxSize : 23621556633600,
> totalAllocatedSize : 23471325184, askingSize : 8589934592, allocated disable
> threshold: 0.85
> 2013-05-14 09:55:53,538 DEBUG [allocator.impl.UserConcentratedAllocator]
> (Job-Executor-21:job-19) There are no pods with enough memory/CPU capacity
> in zone zone1
> 2013-05-14 09:55:53,577 DEBUG [agent.transport.Request]
> (Job-Executor-21:job-19) Seq 1-738331999: Sending { Cmd , MgmtId:
> 206915885079359, via: 1, Ver: v1, Flags: 100111,
> [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.SnapshotObjectTO":{"path":
>
> "snapshots/2/2/76979672-c725-4b20-a517-eb8410205ef6","volume":{"uuid":"5d0bd6d7-4ebe-4fb6-98ad-5df52850e739","volumeType":"ROOT","dataStore":{"org.a
>
> pache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"e97b5727-d157-3dc1-b32c-065b99806623","id":1,"poolType":"NetworkFilesystem","host":"10.223.
>
> 110.232","path":"/export/home/sangeetha/campo-systemp-1/primary","port":2049}},"name":"ROOT-5","size":8589934592,"path":"86b8d3a8-44c9-4bb0-b4fb-1ba
>
> 98d3e4f75","volumeId":5,"vmName":"i-2-5-VM","accountId":2,"id":5},"dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.223.110.232/export/h
>
> ome/sangeetha/campo-systemp-1/secondary","_role":"Image"}},"vmName":"i-2-5-VM","name":"test-2_ROOT-5_20130514162437","hypervisorType":"XenServer","i
>
> d":2}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"d9bade32-54ff-41d9-bb09-2b6388884f94","volumeType":"DATADISK","dataStore
>
> ":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"e97b5727-d157-3dc1-b32c-065b99806623","id":1,"poolType":"NetworkFilesystem","host"
>
> :"10.223.110.232","path":"/export/home/sangeetha/campo-systemp-1/primary","port":2049}},"name":"Volume123","size":8589934592,"volumeId":7,"accountId
> ":2,"id":7}},"wait":10800}}] }
> 2013-05-14 09:55:53,578 DEBUG [agent.transport.Request]
> (Job-Executor-21:job-19) Seq 1-738331999: Executing: { Cmd , MgmtId:
> 206915885079359, via: 1, Ver: v1, Flags: 100111,
> [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.SnapshotObjectTO":{"pat
>
> h":"snapshots/2/2/76979672-c725-4b20-a517-eb8410205ef6","volume":{"uuid":"5d0bd6d7-4ebe-4fb6-98ad-5df52850e739","volumeType":"ROOT","dataStore":{"or
>
> g.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"e97b5727-d157-3dc1-b32c-065b99806623","id":1,"poolType":"NetworkFilesystem","host":"10.2
>
> 23.110.232","path":"/export/home/sangeetha/campo-systemp-1/primary","port":2049}},"name":"ROOT-5","size":8589934592,"path":"86b8d3a8-44c9-4bb0-b4fb-
>
> 1ba98d3e4f75","volumeId":5,"vmName":"i-2-5-VM","accountId":2,"id":5},"dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.223.110.232/expor
>
> t/home/sangeetha/campo-systemp-1/secondary","_role":"Image"}},"vmName":"i-2-5-VM","name":"test-2_ROOT-5_20130514162437","hypervisorType":"XenServer"
>
> ,"id":2}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"d9bade32-54ff-41d9-bb09-2b6388884f94","volumeType":"DATADISK","dataSt
>
> ore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"e97b5727-d157-3dc1-b32c-065b99806623","id":1,"poolType":"NetworkFilesystem","ho
>
> st":"10.223.110.232","path":"/export/home/sangeetha/campo-systemp-1/primary","port":2049}},"name":"Volume123","size":8589934592,"volumeId":7,"accoun
> tId":2,"id":7}},"wait":10800}}] }
> 2013-05-14 09:55:53,578 DEBUG [agent.manager.DirectAgentAttache]
> (DirectAgent-62:null) Seq 1-738331999: Executing request
> 2013-05-14 09:55:53,578 DEBUG [agent.manager.DirectAgentAttache]
> (DirectAgent-62:null) Seq 1-738331999: Response Received:
> 2013-05-14 09:55:53,578 DEBUG [agent.transport.Request] (DirectAgent-62:null)
> Seq 1-738331999: Processing: { Ans: , MgmtId: 206915885079359, via: 1
> , Ver: v1, Flags: 110,
> [{"com.cloud.agent.api.Answer":{"result":false,"details":"not implemented
> yet","wait":0}}] }
> 2013-05-14 09:55:53,579 DEBUG [agent.manager.AgentAttache]
> (DirectAgent-62:null) Seq 1-738331999: No more commands found
> 2013-05-14 09:55:53,579 DEBUG [agent.transport.Request]
> (Job-Executor-21:job-19) Seq 1-738331999: Received: { Ans: , MgmtId:
> 206915885079359, via: 1, Ver: v1, Flags: 110, { Answer } }
> 2013-05-14 09:55:53,590 DEBUG [cloud.storage.VolumeManagerImpl]
> (Job-Executor-21:job-19) Failed to create volume from snapshot:not
> implemented yet
> 2013-05-14 09:55:53,590 DEBUG [cloud.storage.VolumeManagerImpl]
> (Job-Executor-21:job-19) Failed to create volume: 7
> com.cloud.utils.exception.CloudRuntimeException: Failed to create volume from
> snapshot:not implemented yet
> at
> com.cloud.storage.VolumeManagerImpl.createVolumeFromSnapshot(VolumeManagerImpl.java:544)
> at
> com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:12
> 5)
> at
> com.cloud.storage.VolumeManagerImpl.createVolumeFromSnapshot(VolumeManagerImpl.java:589)
> at
> com.cloud.storage.VolumeManagerImpl.createVolume(VolumeManagerImpl.java:1006)
> at
> com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:12
> 5)
> at
> com.cloud.storage.VolumeManagerImpl.createVolume(VolumeManagerImpl.java:181)
> at
> org.apache.cloudstack.api.command.user.volume.CreateVolumeCmd.execute(CreateVolumeCmd.java:168)
> 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-14 09:55:53,616 DEBUG [cloud.async.AsyncJobManagerImpl]
> (Job-Executor-21:job-19) Complete async job-19, jobStatus: 2, resultCode:
> 530, resul t: Error Code: 530 Error text: Failed to create
> a volume
--
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
