[
https://issues.apache.org/jira/browse/CLOUDSTACK-8964?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14973427#comment-14973427
]
ASF subversion and git services commented on CLOUDSTACK-8964:
-------------------------------------------------------------
Commit 7d73e9bfafd8fc34229c8115fc2cd96eeb00d737 in cloudstack's branch
refs/heads/master from [~remibergsma]
[ https://git-wip-us.apache.org/repos/asf?p=cloudstack.git;h=7d73e9b ]
Merge pull request #976 from ustcweizhou/CLOUDSTACK-8964-removed-volume
CLOUDSTACK-8964: Can't create volume from snapshot of a removed volumeThis
issue happens on KVM as well.
This is because the volume info is missing in the CopyCommand once the volume
has been removed.
When the KVM agent tries to process the command, it will throws a NPE.
* pr/976:
CLOUDSTACK-8964: Can't create volume from snapshot of a removed volume
Signed-off-by: Remi Bergsma <git...@remi.nl>
> Can't create template or volume from snapshot - "Are you sure you got the
> right type of server?"
> ------------------------------------------------------------------------------------------------
>
> Key: CLOUDSTACK-8964
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-8964
> Project: CloudStack
> Issue Type: Bug
> Security Level: Public(Anyone can view this level - this is the
> default.)
> Components: Secondary Storage
> Affects Versions: 4.6.0
> Environment: CentOS 6 HVs & mgmt
> Reporter: Nux
> Priority: Blocker
>
> I have a couple of snapshots left-over from by now deleted instances. Trying
> to turn them into volumes fails with (UI/cloudmonkey shows this):
> "Failed to create templateUnsupported command issued:
> org.apache.cloudstack.storage.command.CopyCommand. Are you sure you got the
> right type of server?"
> mgmt server logs for when trying to create template:
> "2015-10-18 09:15:58,437 DEBUG [c.c.a.ApiServlet]
> (catalina-exec-5:ctx-84b2a9be) ===START=== 192.168.192.198 -- GET
> command=createTemplate&response=json&snapshotid=da79387b-ecae-4d5c-b414-3942d29ad821&name=testsnap1&displayText=testsnap1&osTypeId=ba03db1c-7359-11e5-b4d0-f2a3ece198a5&isPublic=false&passwordEnabled=false&isdynamicallyscalable=false&_=1445156157698
> 2015-10-18 09:15:58,459 DEBUG [c.c.t.TemplateManagerImpl]
> (catalina-exec-5:ctx-84b2a9be ctx-921b9b20) This template is getting created
> from other template, setting source template Id to: 201
> 2015-10-18 09:15:58,500 INFO [o.a.c.f.j.i.AsyncJobMonitor]
> (API-Job-Executor-33:ctx-f566f6af job-135) Add job-135 into job monitoring
> 2015-10-18 09:15:58,506 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (catalina-exec-5:ctx-84b2a9be ctx-921b9b20) submit async job-135, details:
> AsyncJobVO {id:135, userId: 2, accountId: 2, instanceType: Template,
> instanceId: 207, cmd:
> org.apache.cloudstack.api.command.admin.template.CreateTemplateCmdByAdmin,
> cmdInfo:
> {"cmdEventType":"TEMPLATE.CREATE","ctxUserId":"2","httpmethod":"GET","osTypeId":"ba03db1c-7359-11e5-b4d0-f2a3ece198a5","isPublic":"false","isdynamicallyscalable":"false","response":"json","id":"207","ctxDetails":"{\"interface
>
> com.cloud.template.VirtualMachineTemplate\":\"9c045e56-2463-47f8-a257-840656e1c0bd\",\"interface
>
> com.cloud.storage.Snapshot\":\"da79387b-ecae-4d5c-b414-3942d29ad821\",\"interface
>
> com.cloud.storage.GuestOS\":\"ba03db1c-7359-11e5-b4d0-f2a3ece198a5\"}","displayText":"testsnap1","snapshotid":"da79387b-ecae-4d5c-b414-3942d29ad821","passwordEnabled":"false","name":"testsnap1","_":"1445156157698","uuid":"9c045e56-2463-47f8-a257-840656e1c0bd","ctxAccountId":"2","ctxStartEventId":"253"},
> cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result:
> null, initMsid: 266785867798693, completeMsid: null, lastUpdated: null,
> lastPolled: null, created: null}
> 2015-10-18 09:15:58,506 DEBUG [c.c.a.ApiServlet]
> (catalina-exec-5:ctx-84b2a9be ctx-921b9b20) ===END=== 192.168.192.198 -- GET
>
> command=createTemplate&response=json&snapshotid=da79387b-ecae-4d5c-b414-3942d29ad821&name=testsnap1&displayText=testsnap1&osTypeId=ba03db1c-7359-11e5-b4d0-f2a3ece198a5&isPublic=false&passwordEnabled=false&isdynamicallyscalable=false&_=1445156157698
> 2015-10-18 09:15:58,507 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (API-Job-Executor-33:ctx-f566f6af job-135) Executing AsyncJobVO {id:135,
> userId: 2, accountId: 2, instanceType: Template, instanceId: 207, cmd:
> org.apache.cloudstack.api.command.admin.template.CreateTemplateCmdByAdmin,
> cmdInfo:
> {"cmdEventType":"TEMPLATE.CREATE","ctxUserId":"2","httpmethod":"GET","osTypeId":"ba03db1c-7359-11e5-b4d0-f2a3ece198a5","isPublic":"false","isdynamicallyscalable":"false","response":"json","id":"207","ctxDetails":"{\"interface
>
> com.cloud.template.VirtualMachineTemplate\":\"9c045e56-2463-47f8-a257-840656e1c0bd\",\"interface
>
> com.cloud.storage.Snapshot\":\"da79387b-ecae-4d5c-b414-3942d29ad821\",\"interface
>
> com.cloud.storage.GuestOS\":\"ba03db1c-7359-11e5-b4d0-f2a3ece198a5\"}","displayText":"testsnap1","snapshotid":"da79387b-ecae-4d5c-b414-3942d29ad821","passwordEnabled":"false","name":"testsnap1","_":"1445156157698","uuid":"9c045e56-2463-47f8-a257-840656e1c0bd","ctxAccountId":"2","ctxStartEventId":"253"},
> cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result:
> null, initMsid: 266785867798693, completeMsid: null, lastUpdated: null,
> lastPolled: null, created: null}
> 2015-10-18 09:15:58,531 DEBUG [o.a.c.s.i.TemplateDataFactoryImpl]
> (API-Job-Executor-33:ctx-f566f6af job-135 ctx-bd1cf294) template 207 is
> already in store:1, type:Image
> 2015-10-18 09:15:58,539 DEBUG [o.a.c.s.m.AncientDataMotionStrategy]
> (API-Job-Executor-33:ctx-f566f6af job-135 ctx-bd1cf294) copyAsync inspecting
> src type SNAPSHOT copyAsync inspecting dest type TEMPLATE
> 2015-10-18 09:15:58,545 DEBUG [c.c.h.o.r.Ovm3HypervisorGuru]
> (API-Job-Executor-33:ctx-f566f6af job-135 ctx-bd1cf294)
> getCommandHostDelegation: class
> org.apache.cloudstack.storage.command.CopyCommand
> 2015-10-18 09:15:58,545 DEBUG [c.c.h.o.r.Ovm3HypervisorGuru]
> (API-Job-Executor-33:ctx-f566f6af job-135 ctx-bd1cf294) Snapshot to Template:
> org.apache.cloudstack.storage.command.CopyCommand
> 2015-10-18 09:15:58,548 DEBUG [c.c.a.t.Request]
> (API-Job-Executor-33:ctx-f566f6af job-135 ctx-bd1cf294) Seq
> 1-2581969961366860773: Sending { Cmd , MgmtId: 266785867798693, via:
> 1(server1707.cloud.priv), Ver: v1, Flags: 100111,
> [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.SnapshotObjectTO":{"path":"snapshots/2/9/189d64ac-2621-46c6-9e00-f7f1e424bf44","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://192.168.200.67/secondary","_role":"Image"}},"name":"tests1","hypervisorType":"KVM","id":1,"quiescevm":false,"physicalSize":0}},"destTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/207","uuid":"9c045e56-2463-47f8-a257-840656e1c0bd","id":207,"format":"RAW","accountId":2,"hvm":true,"displayText":"testsnap1","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://192.168.200.67/secondary","_role":"Image"}},"name":"207-2-7e2f5898-46bb-3110-9b17-ee4fa27a3f4a","hypervisorType":"KVM"}},"executeInSequence":true,"options":{},"options2":{},"wait":10800}}]
> }
> 2015-10-18 09:15:58,591 DEBUG [c.c.a.t.Request]
> (AgentManager-Handler-11:null) Seq 1-2581969961366860773: Processing: { Ans:
> , MgmtId: 266785867798693, via: 1, Ver: v1, Flags: 110,
> [{"com.cloud.agent.api.UnsupportedAnswer":{"result":false,"details":"Unsupported
> command issued: org.apache.cloudstack.storage.command.CopyCommand. Are you
> sure you got the right type of server?","wait":0}}] }
> 2015-10-18 09:15:58,591 DEBUG [c.c.a.m.AgentAttache]
> (AgentManager-Handler-11:null) Seq 1-2581969961366860773: No more commands
> found
> 2015-10-18 09:15:58,591 DEBUG [c.c.a.t.Request]
> (API-Job-Executor-33:ctx-f566f6af job-135 ctx-bd1cf294) Seq
> 1-2581969961366860773: Received: { Ans: , MgmtId: 266785867798693, via: 1,
> Ver: v1, Flags: 110, { UnsupportedAnswer } }
> 2015-10-18 09:15:58,591 WARN [c.c.a.m.AgentManagerImpl]
> (API-Job-Executor-33:ctx-f566f6af job-135 ctx-bd1cf294) Unsupported Command:
> Unsupported command issued:
> org.apache.cloudstack.storage.command.CopyCommand. Are you sure you got the
> right type of server?
> 2015-10-18 09:15:58,600 DEBUG [c.c.t.TemplateManagerImpl]
> (API-Job-Executor-33:ctx-f566f6af job-135 ctx-bd1cf294) Failed to create
> templateUnsupported command issued:
> org.apache.cloudstack.storage.command.CopyCommand. Are you sure you got the
> right type of server?
> 2015-10-18 09:15:58,614 ERROR [c.c.a.ApiAsyncJobDispatcher]
> (API-Job-Executor-33:ctx-f566f6af job-135) Unexpected exception while
> executing
> org.apache.cloudstack.api.command.admin.template.CreateTemplateCmdByAdmin
> com.cloud.utils.exception.CloudRuntimeException: Failed to create
> templateUnsupported command issued:
> org.apache.cloudstack.storage.command.CopyCommand. Are you sure you got the
> right type of server?
> at
> com.cloud.template.TemplateManagerImpl.createPrivateTemplate(TemplateManagerImpl.java:1498)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:606)
> at
> org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
> at
> org.apache.cloudstack.network.contrail.management.EventUtils$EventInterceptor.invoke(EventUtils.java:106)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
> at
> com.cloud.event.ActionEventInterceptor.invoke(ActionEventInterceptor.java:51)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
> at
> org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
> at
> org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
> at com.sun.proxy.$Proxy185.createPrivateTemplate(Unknown Source)
> at
> org.apache.cloudstack.api.command.admin.template.CreateTemplateCmdByAdmin.execute(CreateTemplateCmdByAdmin.java:43)
> at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:150)
> at
> com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:108)
> at
> org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:537)
> 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
> org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:494)
> at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> at java.util.concurrent.FutureTask.run(FutureTask.java:262)
> at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
> at java.lang.Thread.run(Thread.java:745)
> 2015-10-18 09:15:58,615 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (API-Job-Executor-33:ctx-f566f6af job-135) Complete async job-135, jobStatus:
> FAILED, resultCode: 530, result:
> org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"errorcode":530,"errortext":"Failed
> to create templateUnsupported command issued:
> org.apache.cloudstack.storage.command.CopyCommand. Are you sure you got the
> right type of server?"}
> 2015-10-18 09:15:58,616 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (API-Job-Executor-33:ctx-f566f6af job-135) Publish async job-135 complete on
> message bus
> 2015-10-18 09:15:58,616 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (API-Job-Executor-33:ctx-f566f6af job-135) Wake up jobs related to job-135
> 2015-10-18 09:15:58,616 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (API-Job-Executor-33:ctx-f566f6af job-135) Update db status for job-135
> 2015-10-18 09:15:58,617 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (API-Job-Executor-33:ctx-f566f6af job-135) Wake up jobs joined with job-135
> and disjoin all subjobs created from job- 135
> 2015-10-18 09:15:58,622 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (API-Job-Executor-33:ctx-f566f6af job-135) Done executing
> org.apache.cloudstack.api.command.admin.template.CreateTemplateCmdByAdmin for
> job-135
> 2015-10-18 09:15:58,622 INFO [o.a.c.f.j.i.AsyncJobMonitor]
> (API-Job-Executor-33:ctx-f566f6af job-135) Remove job-135 from job monitoring
> 2015-10-18 09:16:00,186 DEBUG [o.a.c.s.SecondaryStorageManagerImpl]
> (secstorage-1:ctx-b1ebd7ae) Zone 1 is ready to launch secondary storage VM
> 2015-10-18 09:16:00,284 DEBUG [c.c.c.ConsoleProxyManagerImpl]
> (consoleproxy-1:ctx-504e558f) Zone 1 is ready to launch console proxy
> 2015-10-18 09:16:00,913 DEBUG [c.c.a.m.AgentManagerImpl]
> (AgentManager-Handler-10:null) SeqA 3-29514: Processing Seq 3-29514: { Cmd ,
> MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> \"connections\": []\n}","wait":0}}] }
> 2015-10-18 09:16:00,918 DEBUG [c.c.a.m.AgentManagerImpl]
> (AgentManager-Handler-10:null) SeqA 3-29514: Sending Seq 3-29514: { Ans: ,
> MgmtId: 266785867798693, via: 3, Ver: v1, Flags: 100010,
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }"
> mgmt server log for when trying to create volume from snapshot:
> "2015-10-18 09:22:54,554 DEBUG [c.c.a.ApiServlet]
> (catalina-exec-25:ctx-a0b2e544 ctx-ed58876f) ===END=== 192.168.192.198 --
> GET command=listZones&available=true&response=json&_=1445156573821
> 2015-10-18 09:22:59,041 DEBUG [c.c.a.ApiServlet]
> (catalina-exec-10:ctx-b4b4be7d) ===START=== 192.168.192.198 -- GET
> command=createVolume&response=json&snapshotid=da79387b-ecae-4d5c-b414-3942d29ad821&name=etetete&_=1445156578325
> 2015-10-18 09:22:59,080 INFO [o.a.c.f.j.i.AsyncJobMonitor]
> (API-Job-Executor-35:ctx-58dc6474 job-137) Add job-137 into job monitoring
> 2015-10-18 09:22:59,086 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (catalina-exec-10:ctx-b4b4be7d ctx-323d5529) submit async job-137, details:
> AsyncJobVO {id:137, userId: 2, accountId: 2, instanceType: Volume,
> instanceId: 13, cmd:
> org.apache.cloudstack.api.command.admin.volume.CreateVolumeCmdByAdmin,
> cmdInfo: {"id":"13","response":"json","ctxDetails":"{\"interface
> com.cloud.storage.Volume\":\"57ef80a3-d160-41ba-ba27-d2858d0499ad\",\"interface
>
> com.cloud.storage.Snapshot\":\"da79387b-ecae-4d5c-b414-3942d29ad821\"}","cmdEventType":"VOLUME.CREATE","ctxUserId":"2","snapshotid":"da79387b-ecae-4d5c-b414-3942d29ad821","name":"etetete","httpmethod":"GET","_":"1445156578325","uuid":"57ef80a3-d160-41ba-ba27-d2858d0499ad","ctxAccountId":"2","ctxStartEventId":"261"},
> cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result:
> null, initMsid: 266785867798693, completeMsid: null, lastUpdated: null,
> lastPolled: null, created: null}
> 2015-10-18 09:22:59,086 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137) Executing AsyncJobVO {id:137,
> userId: 2, accountId: 2, instanceType: Volume, instanceId: 13, cmd:
> org.apache.cloudstack.api.command.admin.volume.CreateVolumeCmdByAdmin,
> cmdInfo: {"id":"13","response":"json","ctxDetails":"{\"interface
> com.cloud.storage.Volume\":\"57ef80a3-d160-41ba-ba27-d2858d0499ad\",\"interface
>
> com.cloud.storage.Snapshot\":\"da79387b-ecae-4d5c-b414-3942d29ad821\"}","cmdEventType":"VOLUME.CREATE","ctxUserId":"2","snapshotid":"da79387b-ecae-4d5c-b414-3942d29ad821","name":"etetete","httpmethod":"GET","_":"1445156578325","uuid":"57ef80a3-d160-41ba-ba27-d2858d0499ad","ctxAccountId":"2","ctxStartEventId":"261"},
> cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result:
> null, initMsid: 266785867798693, completeMsid: null, lastUpdated: null,
> lastPolled: null, created: null}
> 2015-10-18 09:22:59,086 DEBUG [c.c.a.ApiServlet]
> (catalina-exec-10:ctx-b4b4be7d ctx-323d5529) ===END=== 192.168.192.198 --
> GET
> command=createVolume&response=json&snapshotid=da79387b-ecae-4d5c-b414-3942d29ad821&name=etetete&_=1445156578325
> 2015-10-18 09:22:59,102 DEBUG [o.a.c.s.a.LocalStoragePoolAllocator]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829)
> LocalStoragePoolAllocator trying to find storage pool to fit the vm
> 2015-10-18 09:22:59,102 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829)
> ClusterScopeStoragePoolAllocator looking for storage pool
> 2015-10-18 09:22:59,102 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Looking for pools in
> dc: 1 pod:1 cluster:null. Disabled pools will be ignored.
> 2015-10-18 09:22:59,103 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Found pools matching
> tags: []
> 2015-10-18 09:22:59,103 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) No storage pools
> available for shared volume allocation, returning
> 2015-10-18 09:22:59,103 DEBUG [o.a.c.s.a.ZoneWideStoragePoolAllocator]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829)
> ZoneWideStoragePoolAllocator to find storage pool
> 2015-10-18 09:22:59,106 DEBUG [o.a.c.s.a.AbstractStoragePoolAllocator]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Checking if storage
> pool is suitable, name: null ,poolId: 2
> 2015-10-18 09:22:59,107 INFO [c.c.s.StorageManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Storage pool null (2)
> does not supply IOPS capacity, assuming enough capacity
> 2015-10-18 09:22:59,108 DEBUG [c.c.s.StorageManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Checking pool 2 for
> storage, totalSize: 249934381056, usedBytes: 132560977920, usedPct:
> 0.5303831244021547, disable threshold: 0.85
> 2015-10-18 09:22:59,112 DEBUG [c.c.s.StorageManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Found storage pool
> pri1nfs of type NetworkFilesystem with overprovisioning factor 2
> 2015-10-18 09:22:59,112 DEBUG [c.c.s.StorageManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Total over
> provisioned capacity calculated is 2 * 249934381056
> 2015-10-18 09:22:59,112 DEBUG [c.c.s.StorageManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Total capacity of the
> pool pri1nfs id: 2 is 499868762112
> 2015-10-18 09:22:59,113 DEBUG [c.c.s.StorageManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Checking pool: 2 for
> volume allocation [Vol[13|vm=null|DATADISK]], maxSize : 499868762112,
> totalAllocatedSize : 317901312, askingSize : 52428800, allocated disable
> threshold: 0.85
> 2015-10-18 09:22:59,113 DEBUG [o.a.c.e.o.VolumeOrchestrator]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Found a suitable pool
> for create volume: 2
> 2015-10-18 09:22:59,133 DEBUG [o.a.c.s.m.AncientDataMotionStrategy]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) copyAsync inspecting
> src type SNAPSHOT copyAsync inspecting dest type VOLUME
> 2015-10-18 09:22:59,137 DEBUG [c.c.h.o.r.Ovm3HypervisorGuru]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829)
> getCommandHostDelegation: class
> org.apache.cloudstack.storage.command.CopyCommand
> 2015-10-18 09:22:59,138 DEBUG [c.c.a.t.Request]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Seq
> 1-2581969961366860796: Sending { Cmd , MgmtId: 266785867798693, via:
> 1(server1707.cloud.priv), Ver: v1, Flags: 100111,
> [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.SnapshotObjectTO":{"path":"snapshots/2/9/189d64ac-2621-46c6-9e00-f7f1e424bf44","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://192.168.200.67/secondary","_role":"Image"}},"name":"tests1","hypervisorType":"KVM","id":1,"quiescevm":false,"physicalSize":0}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"57ef80a3-d160-41ba-ba27-d2858d0499ad","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"29d6d3ac-1805-3501-89e4-9287e05f2398","id":2,"poolType":"NetworkFilesystem","host":"192.168.203.67","path":"/primary","port":2049,"url":"NetworkFilesystem://192.168.203.67/primary/?ROLE=Primary&STOREUUID=29d6d3ac-1805-3501-89e4-9287e05f2398"}},"name":"etetete","size":52428800,"volumeId":13,"accountId":2,"format":"QCOW2","provisioningType":"THIN","id":13,"hypervisorType":"KVM"}},"executeInSequence":true,"options":{},"options2":{},"wait":10800}}]
> }
> 2015-10-18 09:22:59,203 DEBUG [c.c.a.t.Request]
> (AgentManager-Handler-14:null) Seq 1-2581969961366860796: Processing: { Ans:
> , MgmtId: 266785867798693, via: 1, Ver: v1, Flags: 110,
> [{"com.cloud.agent.api.UnsupportedAnswer":{"result":false,"details":"Unsupported
> command issued: org.apache.cloudstack.storage.command.CopyCommand. Are you
> sure you got the right type of server?","wait":0}}] }
> 2015-10-18 09:22:59,203 DEBUG [c.c.a.m.AgentAttache]
> (AgentManager-Handler-14:null) Seq 1-2581969961366860796: No more commands
> found
> 2015-10-18 09:22:59,203 DEBUG [c.c.a.t.Request]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Seq
> 1-2581969961366860796: Received: { Ans: , MgmtId: 266785867798693, via: 1,
> Ver: v1, Flags: 110, { UnsupportedAnswer } }
> 2015-10-18 09:22:59,203 WARN [c.c.a.m.AgentManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Unsupported Command:
> Unsupported command issued:
> org.apache.cloudstack.storage.command.CopyCommand. Are you sure you got the
> right type of server?
> 2015-10-18 09:22:59,209 WARN [o.a.c.s.d.ObjectInDataStoreManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Unsupported data
> object (VOLUME,
> org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@10b8a20a), no
> need to delete from object in store ref table
> 2015-10-18 09:22:59,215 WARN [o.a.c.s.d.ObjectInDataStoreManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Snapshot 1 is not
> found on image store 1, so no need to delete
> 2015-10-18 09:22:59,215 DEBUG [o.a.c.e.o.VolumeOrchestrator]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Failed to create
> volume from snapshot:Unsupported command issued:
> org.apache.cloudstack.storage.command.CopyCommand. Are you sure you got the
> right type of server?
> 2015-10-18 09:22:59,231 DEBUG [c.c.u.AccountManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Access granted to
> Acct[cf13e45c-7359-11e5-b4d0-f2a3ece198a5-admin] to Domain:1/ by
> AffinityGroupAccessChecker
> 2015-10-18 09:22:59,243 ERROR [c.c.a.ApiAsyncJobDispatcher]
> (API-Job-Executor-35:ctx-58dc6474 job-137) Unexpected exception while
> executing
> org.apache.cloudstack.api.command.admin.volume.CreateVolumeCmdByAdmin
> com.cloud.utils.exception.CloudRuntimeException: Failed to create volume: 13
> at
> com.cloud.storage.VolumeApiServiceImpl.createVolume(VolumeApiServiceImpl.java:776)
> at
> com.cloud.storage.VolumeApiServiceImpl.createVolume(VolumeApiServiceImpl.java:172)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:606)
> at
> org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
> at
> org.apache.cloudstack.network.contrail.management.EventUtils$EventInterceptor.invoke(EventUtils.java:106)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
> at
> com.cloud.event.ActionEventInterceptor.invoke(ActionEventInterceptor.java:51)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
> at
> org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
> at
> org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
> at com.sun.proxy.$Proxy195.createVolume(Unknown Source)
> at
> org.apache.cloudstack.api.command.admin.volume.CreateVolumeCmdByAdmin.execute(CreateVolumeCmdByAdmin.java:42)
> at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:150)
> at
> com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:108)
> at
> org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:537)
> 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
> org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:494)
> at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> at java.util.concurrent.FutureTask.run(FutureTask.java:262)
> at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
> at java.lang.Thread.run(Thread.java:745)
> Caused by: com.cloud.utils.exception.CloudRuntimeException: Failed to create
> volume from snapshot:Unsupported command issued:
> org.apache.cloudstack.storage.command.CopyCommand. Are you sure you got the
> right type of server?
> at
> org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.createVolumeFromSnapshot(VolumeOrchestrator.java:402)
> at
> com.cloud.storage.VolumeApiServiceImpl.createVolumeFromSnapshot(VolumeApiServiceImpl.java:798)
> at
> com.cloud.storage.VolumeApiServiceImpl.createVolume(VolumeApiServiceImpl.java:748)
> ... 31 more
> 2015-10-18 09:22:59,244 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137) Complete async job-137, jobStatus:
> FAILED, resultCode: 530, result:
> org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"errorcode":530,"errortext":"Failed
> to create volume: 13"}
> 2015-10-18 09:22:59,245 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137) Publish async job-137 complete on
> message bus
> 2015-10-18 09:22:59,245 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137) Wake up jobs related to job-137
> 2015-10-18 09:22:59,245 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137) Update db status for job-137
> 2015-10-18 09:22:59,245 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137) Wake up jobs joined with job-137
> and disjoin all subjobs created from job- 137
> 2015-10-18 09:22:59,250 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
> (API-Job-Executor-35:ctx-58dc6474 job-137) Done executing
> org.apache.cloudstack.api.command.admin.volume.CreateVolumeCmdByAdmin for
> job-137
> 2015-10-18 09:22:59,250 INFO [o.a.c.f.j.i.AsyncJobMonitor]
> (API-Job-Executor-35:ctx-58dc6474 job-137) Remove job-137 from job monitoring
> 2015-10-18 09:23:00,186 DEBUG [o.a.c.s.SecondaryStorageManagerImpl]
> (secstorage-1:ctx-f43244c2) Zone 1 is ready to launch secondary storage VM
> 2015-10-18 09:23:00,284 DEBUG [c.c.c.ConsoleProxyManagerImpl]
> (consoleproxy-1:ctx-49b9abf0) Zone 1 is ready to launch console proxy
> 2015-10-18 09:23:00,941 DEBUG [c.c.a.m.AgentManagerImpl]
> (AgentManager-Handler-2:null) SeqA 3-29565: Processing Seq 3-29565: { Cmd ,
> MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> \"connections\": []\n}","wait":0}}] }
> 2015-10-18 09:23:00,946 DEBUG [c.c.a.m.AgentManagerImpl]
> (AgentManager-Handler-2:null) SeqA 3-29565: Sending Seq 3-29565: { Ans: ,
> MgmtId: 266785867798693, via: 3, Ver: v1, Flags: 100010,
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }"
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
