cloudstack-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "ASF GitHub Bot (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 Fri, 30 Oct 2015 16:05:27 GMT

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

ASF GitHub Bot commented on CLOUDSTACK-8964:
--------------------------------------------

Github user remibergsma commented on the pull request:

    https://github.com/apache/cloudstack/pull/975#issuecomment-152566972
  
    @dahn are you OK to merge this as-is?


> 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
>            Assignee: Wei Zhou
>            Priority: Blocker
>             Fix For: 4.6.0
>
>
> 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