cloudstack-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "ASF subversion and git services (JIRA)" <j...@apache.org>
Subject [jira] [Commented] (CLOUDSTACK-8964) Can't create template or volume from snapshot - "Are you sure you got the right type of server?"
Date Sun, 25 Oct 2015 20:27:28 GMT

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

ASF subversion and git services commented on CLOUDSTACK-8964:
-------------------------------------------------------------

Commit bef92052ee9a7303b427782838a8a97623de231c in cloudstack's branch refs/heads/master from
Wei Zhou
[ https://git-wip-us.apache.org/repos/asf?p=cloudstack.git;h=bef9205 ]

CLOUDSTACK-8964: Can't create volume from snapshot of a removed volume

This 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.


> 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)

Mime
View raw message