Nux created CLOUDSTACK-8964:
-------------------------------
Summary: Can't create template from snapshot or volume - "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
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)
