Return-Path: X-Original-To: apmail-cloudstack-issues-archive@www.apache.org Delivered-To: apmail-cloudstack-issues-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id E57C317FAD for ; Mon, 2 Nov 2015 12:39:34 +0000 (UTC) Received: (qmail 30507 invoked by uid 500); 2 Nov 2015 12:39:28 -0000 Delivered-To: apmail-cloudstack-issues-archive@cloudstack.apache.org Received: (qmail 30403 invoked by uid 500); 2 Nov 2015 12:39:28 -0000 Mailing-List: contact issues-help@cloudstack.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@cloudstack.apache.org Delivered-To: mailing list issues@cloudstack.apache.org Received: (qmail 30248 invoked by uid 500); 2 Nov 2015 12:39:28 -0000 Delivered-To: apmail-incubator-cloudstack-issues@incubator.apache.org Received: (qmail 30220 invoked by uid 99); 2 Nov 2015 12:39:28 -0000 Received: from arcas.apache.org (HELO arcas) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 02 Nov 2015 12:39:28 +0000 Received: from arcas.apache.org (localhost [127.0.0.1]) by arcas (Postfix) with ESMTP id 2CAF32C1F6B for ; Mon, 2 Nov 2015 12:39:28 +0000 (UTC) Date: Mon, 2 Nov 2015 12:39:28 +0000 (UTC) From: "ASF GitHub Bot (JIRA)" To: cloudstack-issues@incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Commented] (CLOUDSTACK-8964) Can't create template or volume from snapshot - "Are you sure you got the right type of server?" MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 [ https://issues.apache.org/jira/browse/CLOUDSTACK-8964?page=3Dcom.atla= ssian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId= =3D14985154#comment-14985154 ]=20 ASF GitHub Bot commented on CLOUDSTACK-8964: -------------------------------------------- Github user asfgit closed the pull request at: https://github.com/apache/cloudstack/pull/975 > 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-896= 4 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the defa= ult.)=20 > 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. Tr= ying to turn them into volumes fails with (UI/cloudmonkey shows this): > "Failed to create templateUnsupported command issued: org.apache.cloudsta= ck.storage.command.CopyCommand. Are you sure you got the right type of serv= er?" > 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-84= b2a9be) =3D=3D=3DSTART=3D=3D=3D 192.168.192.198 -- GET command=3DcreateTe= mplate&response=3Djson&snapshotid=3Dda79387b-ecae-4d5c-b414-3942d29ad821&na= me=3Dtestsnap1&displayText=3Dtestsnap1&osTypeId=3Dba03db1c-7359-11e5-b4d0-f= 2a3ece198a5&isPublic=3Dfalse&passwordEnabled=3Dfalse&isdynamicallyscalable= =3Dfalse&_=3D1445156157698 > 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 te= mplate, setting source template Id to: 201 > 2015-10-18 09:15:58,500 INFO [o.a.c.f.j.i.AsyncJobMonitor] (API-Job-Exec= utor-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: AsyncJobV= O {id:135, userId: 2, accountId: 2, instanceType: Template, instanceId: 207= , cmd: org.apache.cloudstack.api.command.admin.template.CreateTemplateCmdBy= Admin, cmdInfo: {"cmdEventType":"TEMPLATE.CREATE","ctxUserId":"2","httpmeth= od":"GET","osTypeId":"ba03db1c-7359-11e5-b4d0-f2a3ece198a5","isPublic":"fal= se","isdynamicallyscalable":"false","response":"json","id":"207","ctxDetail= s":"{\"interface com.cloud.template.VirtualMachineTemplate\":\"9c045e56-246= 3-47f8-a257-840656e1c0bd\",\"interface com.cloud.storage.Snapshot\":\"da793= 87b-ecae-4d5c-b414-3942d29ad821\",\"interface com.cloud.storage.GuestOS\":\= "ba03db1c-7359-11e5-b4d0-f2a3ece198a5\"}","displayText":"testsnap1","snapsh= otid":"da79387b-ecae-4d5c-b414-3942d29ad821","passwordEnabled":"false","nam= e":"testsnap1","_":"1445156157698","uuid":"9c045e56-2463-47f8-a257-840656e1= c0bd","ctxAccountId":"2","ctxStartEventId":"253"}, cmdVersion: 0, status: I= N_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 266785= 867798693, completeMsid: null, lastUpdated: null, lastPolled: null, created= : null} > 2015-10-18 09:15:58,506 DEBUG [c.c.a.ApiServlet] (catalina-exec-5:ctx-84b= 2a9be ctx-921b9b20) =3D=3D=3DEND=3D=3D=3D 192.168.192.198 -- GET command= =3DcreateTemplate&response=3Djson&snapshotid=3Dda79387b-ecae-4d5c-b414-3942= d29ad821&name=3Dtestsnap1&displayText=3Dtestsnap1&osTypeId=3Dba03db1c-7359-= 11e5-b4d0-f2a3ece198a5&isPublic=3Dfalse&passwordEnabled=3Dfalse&isdynamical= lyscalable=3Dfalse&_=3D1445156157698 > 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.clou= dstack.api.command.admin.template.CreateTemplateCmdByAdmin, cmdInfo: {"cmdE= ventType":"TEMPLATE.CREATE","ctxUserId":"2","httpmethod":"GET","osTypeId":"= ba03db1c-7359-11e5-b4d0-f2a3ece198a5","isPublic":"false","isdynamicallyscal= able":"false","response":"json","id":"207","ctxDetails":"{\"interface com.c= loud.template.VirtualMachineTemplate\":\"9c045e56-2463-47f8-a257-840656e1c0= bd\",\"interface com.cloud.storage.Snapshot\":\"da79387b-ecae-4d5c-b414-394= 2d29ad821\",\"interface com.cloud.storage.GuestOS\":\"ba03db1c-7359-11e5-b4= d0-f2a3ece198a5\"}","displayText":"testsnap1","snapshotid":"da79387b-ecae-4= d5c-b414-3942d29ad821","passwordEnabled":"false","name":"testsnap1","_":"14= 45156157698","uuid":"9c045e56-2463-47f8-a257-840656e1c0bd","ctxAccountId":"= 2","ctxStartEventId":"253"}, cmdVersion: 0, status: IN_PROGRESS, processSta= tus: 0, resultCode: 0, result: null, initMsid: 266785867798693, completeMsi= d: null, lastUpdated: null, lastPolled: null, created: null} > 2015-10-18 09:15:58,531 DEBUG [o.a.c.s.i.TemplateDataFactoryImpl] (API-Jo= b-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-Exe= cutor-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-Exe= cutor-33:ctx-f566f6af job-135 ctx-bd1cf294) Snapshot to Template: org.apach= e.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: 100= 111, [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.a= pache.cloudstack.storage.to.SnapshotObjectTO":{"path":"snapshots/2/9/189d64= ac-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/tm= pl/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","_ro= le":"Image"}},"name":"207-2-7e2f5898-46bb-3110-9b17-ee4fa27a3f4a","hypervis= orType":"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: 266785867798= 693, via: 1, Ver: v1, Flags: 110, [{"com.cloud.agent.api.UnsupportedAnswer"= :{"result":false,"details":"Unsupported command issued: org.apache.cloudsta= ck.storage.command.CopyCommand. Are you sure you got the right type of ser= ver?","wait":0}}] } > 2015-10-18 09:15:58,591 DEBUG [c.c.a.m.AgentAttache] (AgentManager-Handle= r-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, { UnsupportedAnswe= r } } > 2015-10-18 09:15:58,591 WARN [c.c.a.m.AgentManagerImpl] (API-Job-Executo= r-33:ctx-f566f6af job-135 ctx-bd1cf294) Unsupported Command: Unsupported co= mmand issued: org.apache.cloudstack.storage.command.CopyCommand. Are you s= ure you got the right type of server? > 2015-10-18 09:15:58,600 DEBUG [c.c.t.TemplateManagerImpl] (API-Job-Execut= or-33:ctx-f566f6af job-135 ctx-bd1cf294) Failed to create templateUnsupport= ed 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-Exec= utor-33:ctx-f566f6af job-135) Unexpected exception while executing org.apac= he.cloudstack.api.command.admin.template.CreateTemplateCmdByAdmin > com.cloud.utils.exception.CloudRuntimeException: Failed to create templat= eUnsupported command issued: org.apache.cloudstack.storage.command.CopyComm= and. Are you sure you got the right type of server? > =09at com.cloud.template.TemplateManagerImpl.createPrivateTemplate(Templa= teManagerImpl.java:1498) > =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImp= l.java:57) > =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcc= essorImpl.java:43) > =09at java.lang.reflect.Method.invoke(Method.java:606) > =09at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflec= tion(AopUtils.java:317) > =09at org.springframework.aop.framework.ReflectiveMethodInvocation.invoke= Joinpoint(ReflectiveMethodInvocation.java:183) > =09at org.springframework.aop.framework.ReflectiveMethodInvocation.procee= d(ReflectiveMethodInvocation.java:150) > =09at org.apache.cloudstack.network.contrail.management.EventUtils$EventI= nterceptor.invoke(EventUtils.java:106) > =09at org.springframework.aop.framework.ReflectiveMethodInvocation.procee= d(ReflectiveMethodInvocation.java:161) > =09at com.cloud.event.ActionEventInterceptor.invoke(ActionEventIntercepto= r.java:51) > =09at org.springframework.aop.framework.ReflectiveMethodInvocation.procee= d(ReflectiveMethodInvocation.java:161) > =09at org.springframework.aop.interceptor.ExposeInvocationInterceptor.inv= oke(ExposeInvocationInterceptor.java:91) > =09at org.springframework.aop.framework.ReflectiveMethodInvocation.procee= d(ReflectiveMethodInvocation.java:172) > =09at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDyna= micAopProxy.java:204) > =09at com.sun.proxy.$Proxy185.createPrivateTemplate(Unknown Source) > =09at org.apache.cloudstack.api.command.admin.template.CreateTemplateCmdB= yAdmin.execute(CreateTemplateCmdByAdmin.java:43) > =09at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:150) > =09at com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.ja= va:108) > =09at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run= InContext(AsyncJobManagerImpl.java:537) > =09at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(= ManagedContextRunnable.java:49) > =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.= call(DefaultManagedContext.java:56) > =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.ca= llWithContext(DefaultManagedContext.java:103) > =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.ru= nWithContext(DefaultManagedContext.java:53) > =09at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(Ma= nagedContextRunnable.java:46) > =09at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run= (AsyncJobManagerImpl.java:494) > =09at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:= 471) > =09at java.util.concurrent.FutureTask.run(FutureTask.java:262) > =09at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecuto= r.java:1145) > =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecut= or.java:615) > =09at 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.ExceptionResp= onse/null/{"uuidList":[],"errorcode":530,"errortext":"Failed to create temp= lateUnsupported command issued: org.apache.cloudstack.storage.command.CopyC= ommand. 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 disj= oin 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-Exec= utor-33:ctx-f566f6af job-135) Remove job-135 from job monitoring > 2015-10-18 09:16:00,186 DEBUG [o.a.c.s.SecondaryStorageManagerImpl] (secs= torage-1:ctx-b1ebd7ae) Zone 1 is ready to launch secondary storage VM > 2015-10-18 09:16:00,284 DEBUG [c.c.c.ConsoleProxyManagerImpl] (consolepro= xy-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-Ha= ndler-10:null) SeqA 3-29514: Processing Seq 3-29514: { Cmd , MgmtId: -1, v= ia: 3, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCom= mand":{"_proxyVmId":2,"_loadInfo":"{\n \"connections\": []\n}","wait":0}}]= } > 2015-10-18 09:16:00,918 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Ha= ndler-10:null) SeqA 3-29514: Sending Seq 3-29514: { Ans: , MgmtId: 2667858= 67798693, via: 3, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentContr= olAnswer":{"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-a= 0b2e544 ctx-ed58876f) =3D=3D=3DEND=3D=3D=3D 192.168.192.198 -- GET comman= d=3DlistZones&available=3Dtrue&response=3Djson&_=3D1445156573821 > 2015-10-18 09:22:59,041 DEBUG [c.c.a.ApiServlet] (catalina-exec-10:ctx-b4= b4be7d) =3D=3D=3DSTART=3D=3D=3D 192.168.192.198 -- GET command=3DcreateVo= lume&response=3Djson&snapshotid=3Dda79387b-ecae-4d5c-b414-3942d29ad821&name= =3Detetete&_=3D1445156578325 > 2015-10-18 09:22:59,080 INFO [o.a.c.f.j.i.AsyncJobMonitor] (API-Job-Exec= utor-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: AsyncJob= VO {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\"}","cmdEve= ntType":"VOLUME.CREATE","ctxUserId":"2","snapshotid":"da79387b-ecae-4d5c-b4= 14-3942d29ad821","name":"etetete","httpmethod":"GET","_":"1445156578325","u= uid":"57ef80a3-d160-41ba-ba27-d2858d0499ad","ctxAccountId":"2","ctxStartEve= ntId":"261"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultC= ode: 0, result: null, initMsid: 266785867798693, completeMsid: null, lastUp= dated: 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.cloudst= ack.api.command.admin.volume.CreateVolumeCmdByAdmin, cmdInfo: {"id":"13","r= esponse":"json","ctxDetails":"{\"interface com.cloud.storage.Volume\":\"57e= f80a3-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-41b= a-ba27-d2858d0499ad","ctxAccountId":"2","ctxStartEventId":"261"}, cmdVersio= n: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, i= nitMsid: 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-b4= b4be7d ctx-323d5529) =3D=3D=3DEND=3D=3D=3D 192.168.192.198 -- GET command= =3DcreateVolume&response=3Djson&snapshotid=3Dda79387b-ecae-4d5c-b414-3942d2= 9ad821&name=3Detetete&_=3D1445156578325 > 2015-10-18 09:22:59,102 DEBUG [o.a.c.s.a.LocalStoragePoolAllocator] (API-= Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) LocalStoragePoolAllocato= r 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) ClusterScopeStora= gePoolAllocator 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 match= ing 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] (A= PI-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) ZoneWideStoragePoolAl= locator to find storage pool > 2015-10-18 09:22:59,106 DEBUG [o.a.c.s.a.AbstractStoragePoolAllocator] (A= PI-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Checking if storage p= ool is suitable, name: null ,poolId: 2 > 2015-10-18 09:22:59,107 INFO [c.c.s.StorageManagerImpl] (API-Job-Executo= r-35:ctx-58dc6474 job-137 ctx-5a575829) Storage pool null (2) does not supp= ly IOPS capacity, assuming enough capacity > 2015-10-18 09:22:59,108 DEBUG [c.c.s.StorageManagerImpl] (API-Job-Executo= r-35:ctx-58dc6474 job-137 ctx-5a575829) Checking pool 2 for storage, totalS= ize: 249934381056, usedBytes: 132560977920, usedPct: 0.5303831244021547, di= sable threshold: 0.85 > 2015-10-18 09:22:59,112 DEBUG [c.c.s.StorageManagerImpl] (API-Job-Executo= r-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-Executo= r-35:ctx-58dc6474 job-137 ctx-5a575829) Total over provisioned capacity cal= culated is 2 * 249934381056 > 2015-10-18 09:22:59,112 DEBUG [c.c.s.StorageManagerImpl] (API-Job-Executo= r-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-Executo= r-35:ctx-58dc6474 job-137 ctx-5a575829) Checking pool: 2 for volume allocat= ion [Vol[13|vm=3Dnull|DATADISK]], maxSize : 499868762112, totalAllocatedSiz= e : 317901312, askingSize : 52428800, allocated disable threshold: 0.85 > 2015-10-18 09:22:59,113 DEBUG [o.a.c.e.o.VolumeOrchestrator] (API-Job-Exe= cutor-35:ctx-58dc6474 job-137 ctx-5a575829) Found a suitable pool for creat= e 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-Exe= cutor-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: 100= 111, [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.a= pache.cloudstack.storage.to.SnapshotObjectTO":{"path":"snapshots/2/9/189d64= ac-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.c= loudstack.storage.to.PrimaryDataStoreTO":{"uuid":"29d6d3ac-1805-3501-89e4-9= 287e05f2398","id":2,"poolType":"NetworkFilesystem","host":"192.168.203.67",= "path":"/primary","port":2049,"url":"NetworkFilesystem://192.168.203.67/pri= mary/?ROLE=3DPrimary&STOREUUID=3D29d6d3ac-1805-3501-89e4-9287e05f2398"}},"n= ame":"etetete","size":52428800,"volumeId":13,"accountId":2,"format":"QCOW2"= ,"provisioningType":"THIN","id":13,"hypervisorType":"KVM"}},"executeInSeque= nce":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: 266785867798= 693, via: 1, Ver: v1, Flags: 110, [{"com.cloud.agent.api.UnsupportedAnswer"= :{"result":false,"details":"Unsupported command issued: org.apache.cloudsta= ck.storage.command.CopyCommand. Are you sure you got the right type of ser= ver?","wait":0}}] } > 2015-10-18 09:22:59,203 DEBUG [c.c.a.m.AgentAttache] (AgentManager-Handle= r-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, { UnsupportedAnswe= r } } > 2015-10-18 09:22:59,203 WARN [c.c.a.m.AgentManagerImpl] (API-Job-Executo= r-35:ctx-58dc6474 job-137 ctx-5a575829) Unsupported Command: Unsupported co= mmand issued: org.apache.cloudstack.storage.command.CopyCommand. Are you s= ure you got the right type of server? > 2015-10-18 09:22:59,209 WARN [o.a.c.s.d.ObjectInDataStoreManagerImpl] (A= PI-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Unsupported data obje= ct (VOLUME, org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@10= b8a20a), no need to delete from object in store ref table > 2015-10-18 09:22:59,215 WARN [o.a.c.s.d.ObjectInDataStoreManagerImpl] (A= PI-Job-Executor-35:ctx-58dc6474 job-137 ctx-5a575829) Snapshot 1 is not fou= nd 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-Exe= cutor-35:ctx-58dc6474 job-137 ctx-5a575829) Failed to create volume from sn= apshot:Unsupported command issued: org.apache.cloudstack.storage.command.Co= pyCommand. Are you sure you got the right type of server? > 2015-10-18 09:22:59,231 DEBUG [c.c.u.AccountManagerImpl] (API-Job-Executo= r-35:ctx-58dc6474 job-137 ctx-5a575829) Access granted to Acct[cf13e45c-735= 9-11e5-b4d0-f2a3ece198a5-admin] to Domain:1/ by AffinityGroupAccessChecker > 2015-10-18 09:22:59,243 ERROR [c.c.a.ApiAsyncJobDispatcher] (API-Job-Exec= utor-35:ctx-58dc6474 job-137) Unexpected exception while executing org.apac= he.cloudstack.api.command.admin.volume.CreateVolumeCmdByAdmin > com.cloud.utils.exception.CloudRuntimeException: Failed to create volume:= 13 > =09at com.cloud.storage.VolumeApiServiceImpl.createVolume(VolumeApiServic= eImpl.java:776) > =09at com.cloud.storage.VolumeApiServiceImpl.createVolume(VolumeApiServic= eImpl.java:172) > =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImp= l.java:57) > =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcc= essorImpl.java:43) > =09at java.lang.reflect.Method.invoke(Method.java:606) > =09at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflec= tion(AopUtils.java:317) > =09at org.springframework.aop.framework.ReflectiveMethodInvocation.invoke= Joinpoint(ReflectiveMethodInvocation.java:183) > =09at org.springframework.aop.framework.ReflectiveMethodInvocation.procee= d(ReflectiveMethodInvocation.java:150) > =09at org.apache.cloudstack.network.contrail.management.EventUtils$EventI= nterceptor.invoke(EventUtils.java:106) > =09at org.springframework.aop.framework.ReflectiveMethodInvocation.procee= d(ReflectiveMethodInvocation.java:161) > =09at com.cloud.event.ActionEventInterceptor.invoke(ActionEventIntercepto= r.java:51) > =09at org.springframework.aop.framework.ReflectiveMethodInvocation.procee= d(ReflectiveMethodInvocation.java:161) > =09at org.springframework.aop.interceptor.ExposeInvocationInterceptor.inv= oke(ExposeInvocationInterceptor.java:91) > =09at org.springframework.aop.framework.ReflectiveMethodInvocation.procee= d(ReflectiveMethodInvocation.java:172) > =09at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDyna= micAopProxy.java:204) > =09at com.sun.proxy.$Proxy195.createVolume(Unknown Source) > =09at org.apache.cloudstack.api.command.admin.volume.CreateVolumeCmdByAdm= in.execute(CreateVolumeCmdByAdmin.java:42) > =09at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:150) > =09at com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.ja= va:108) > =09at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run= InContext(AsyncJobManagerImpl.java:537) > =09at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(= ManagedContextRunnable.java:49) > =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.= call(DefaultManagedContext.java:56) > =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.ca= llWithContext(DefaultManagedContext.java:103) > =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.ru= nWithContext(DefaultManagedContext.java:53) > =09at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(Ma= nagedContextRunnable.java:46) > =09at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run= (AsyncJobManagerImpl.java:494) > =09at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:= 471) > =09at java.util.concurrent.FutureTask.run(FutureTask.java:262) > =09at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecuto= r.java:1145) > =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecut= or.java:615) > =09at java.lang.Thread.run(Thread.java:745) > Caused by: com.cloud.utils.exception.CloudRuntimeException: Failed to cre= ate volume from snapshot:Unsupported command issued: org.apache.cloudstack.= storage.command.CopyCommand. Are you sure you got the right type of server= ? > =09at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.creat= eVolumeFromSnapshot(VolumeOrchestrator.java:402) > =09at com.cloud.storage.VolumeApiServiceImpl.createVolumeFromSnapshot(Vol= umeApiServiceImpl.java:798) > =09at com.cloud.storage.VolumeApiServiceImpl.createVolume(VolumeApiServic= eImpl.java:748) > =09... 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.ExceptionResp= onse/null/{"uuidList":[],"errorcode":530,"errortext":"Failed to create volu= me: 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 disj= oin 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-Exec= utor-35:ctx-58dc6474 job-137) Remove job-137 from job monitoring > 2015-10-18 09:23:00,186 DEBUG [o.a.c.s.SecondaryStorageManagerImpl] (secs= torage-1:ctx-f43244c2) Zone 1 is ready to launch secondary storage VM > 2015-10-18 09:23:00,284 DEBUG [c.c.c.ConsoleProxyManagerImpl] (consolepro= xy-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-Ha= ndler-2:null) SeqA 3-29565: Processing Seq 3-29565: { Cmd , MgmtId: -1, vi= a: 3, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportComm= and":{"_proxyVmId":2,"_loadInfo":"{\n \"connections\": []\n}","wait":0}}] = } > 2015-10-18 09:23:00,946 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Ha= ndler-2:null) SeqA 3-29565: Sending Seq 3-29565: { Ans: , MgmtId: 26678586= 7798693, via: 3, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentContro= lAnswer":{"result":true,"wait":0}}] }" -- This message was sent by Atlassian JIRA (v6.3.4#6332)