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 E978810BA1 for ; Thu, 29 May 2014 07:46:02 +0000 (UTC) Received: (qmail 50375 invoked by uid 500); 29 May 2014 07:46:02 -0000 Delivered-To: apmail-cloudstack-issues-archive@cloudstack.apache.org Received: (qmail 50352 invoked by uid 500); 29 May 2014 07:46:02 -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 50345 invoked by uid 500); 29 May 2014 07:46:02 -0000 Delivered-To: apmail-incubator-cloudstack-issues@incubator.apache.org Received: (qmail 50342 invoked by uid 99); 29 May 2014 07:46:02 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 29 May 2014 07:46:02 +0000 Date: Thu, 29 May 2014 07:46:02 +0000 (UTC) From: "Devdeep Singh (JIRA)" To: cloudstack-issues@incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Assigned] (CLOUDSTACK-6802) [HyperV] Attaching a data disk created on local storage to a VM is failing 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-6802?page=3Dcom.atl= assian.jira.plugin.system.issuetabpanels:all-tabpanel ] Devdeep Singh reassigned CLOUDSTACK-6802: ----------------------------------------- Assignee: Devdeep Singh > [HyperV] Attaching a data disk created on local storage to a VM is failin= g > -------------------------------------------------------------------------= - > > Key: CLOUDSTACK-6802 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-680= 2 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the defa= ult.)=20 > Components: Management Server, Storage Controller > Affects Versions: 4.4.0 > Reporter: Abhinav Roy > Assignee: Devdeep Singh > Priority: Critical > Labels: hyper-V,, hyper-v, hyperv > Fix For: 4.4.0 > > > Steps : > =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D > 1. Deploy a CS advanced zone setup with both local storage and shared sto= rage enabled. > 2. Deploy VMs. ( both on shared and local storage) > 3. create a disk offering with local storage > 4. Create a data disk with local storage disk offering. > 5. attach the datadisk created on step 4 to VM created on step 2 > Expected result : > =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D > Volume should be attached successfully. > Observed result : > =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D > volume attach fails with following error. > 2014-05-29 13:02:45,410 DEBUG [c.c.a.ApiServlet] (catalina-exec-16:ctx-87= ef4f22) =3D=3D=3DSTART=3D=3D=3D 10.101.254.225 -- GET command=3DcreateVol= ume&response=3Djson&sessionkey=3DYYidBdJcBJV64kdZ8c%2BKOeSqY14%3D&name=3Dda= ta-local2&zoneId=3D242c701a-43e8-4790-84f3-9112ca0b5db7&diskOfferingId=3D8c= f4d614-08b8-455c-a99f-814f4b109d24&_=3D1401348494217 > 2014-05-29 13:02:45,466 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (catalina= -exec-16:ctx-87ef4f22 ctx-bf1bb5b6) submit async job-64, details: AsyncJobV= O {id:64, userId: 2, accountId: 2, instanceType: Volume, instanceId: 18, cm= d: org.apache.cloudstack.api.command.admin.volume.CreateVolumeCmdByAdmin, c= mdInfo: {"id":"18","response":"json","sessionkey":"YYidBdJcBJV64kdZ8c+KOeSq= Y14\u003d","ctxDetails":"{\"com.cloud.storage.Volume\":18,\"Volume\":\"dd5d= 8d32-03b4-4afd-80ca-58586c7c9e8d\",\"com.cloud.dc.DataCenter\":1,\"com.clou= d.offering.DiskOffering\":14}","cmdEventType":"VOLUME.CREATE","ctxUserId":"= 2","name":"data-local2","diskOfferingId":"8cf4d614-08b8-455c-a99f-814f4b109= d24","httpmethod":"GET","_":"1401348494217","uuid":"dd5d8d32-03b4-4afd-80ca= -58586c7c9e8d","ctxAccountId":"2","ctxStartEventId":"125","zoneId":"242c701= a-43e8-4790-84f3-9112ca0b5db7"}, cmdVersion: 0, status: IN_PROGRESS, proces= sStatus: 0, resultCode: 0, result: null, initMsid: 213737702773493, complet= eMsid: null, lastUpdated: null, lastPolled: null, created: null} > 2014-05-29 13:02:45,467 DEBUG [c.c.a.ApiServlet] (catalina-exec-16:ctx-87= ef4f22 ctx-bf1bb5b6) =3D=3D=3DEND=3D=3D=3D 10.101.254.225 -- GET command= =3DcreateVolume&response=3Djson&sessionkey=3DYYidBdJcBJV64kdZ8c%2BKOeSqY14%= 3D&name=3Ddata-local2&zoneId=3D242c701a-43e8-4790-84f3-9112ca0b5db7&diskOff= eringId=3D8cf4d614-08b8-455c-a99f-814f4b109d24&_=3D1401348494217 > 2014-05-29 13:02:45,470 INFO [o.a.c.f.j.i.AsyncJobMonitor] (API-Job-Exec= utor-14:ctx-23711cb3 job-64) Add job-64 into job monitoring > 2014-05-29 13:02:45,470 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-= Executor-14:ctx-23711cb3 job-64) Executing AsyncJobVO {id:64, userId: 2, ac= countId: 2, instanceType: Volume, instanceId: 18, cmd: org.apache.cloudstac= k.api.command.admin.volume.CreateVolumeCmdByAdmin, cmdInfo: {"id":"18","res= ponse":"json","sessionkey":"YYidBdJcBJV64kdZ8c+KOeSqY14\u003d","ctxDetails"= :"{\"com.cloud.storage.Volume\":18,\"Volume\":\"dd5d8d32-03b4-4afd-80ca-585= 86c7c9e8d\",\"com.cloud.dc.DataCenter\":1,\"com.cloud.offering.DiskOffering= \":14}","cmdEventType":"VOLUME.CREATE","ctxUserId":"2","name":"data-local2"= ,"diskOfferingId":"8cf4d614-08b8-455c-a99f-814f4b109d24","httpmethod":"GET"= ,"_":"1401348494217","uuid":"dd5d8d32-03b4-4afd-80ca-58586c7c9e8d","ctxAcco= untId":"2","ctxStartEventId":"125","zoneId":"242c701a-43e8-4790-84f3-9112ca= 0b5db7"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode:= 0, result: null, initMsid: 213737702773493, completeMsid: null, lastUpdate= d: null, lastPolled: null, created: null} > 2014-05-29 13:02:45,475 WARN [c.c.a.d.ParamGenericValidationWorker] (API= -Job-Executor-14:ctx-23711cb3 job-64 ctx-5e5a2bf1) Received unknown paramet= ers for command createVolume. Unknown parameters : ctxdetails > 2014-05-29 13:02:45,501 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-= Executor-14:ctx-23711cb3 job-64 ctx-5e5a2bf1) Complete async job-64, jobSta= tus: SUCCEEDED, resultCode: 0, result: org.apache.cloudstack.api.response.V= olumeResponse/volume/{"id":"dd5d8d32-03b4-4afd-80ca-58586c7c9e8d","name":"d= ata-local2","zoneid":"242c701a-43e8-4790-84f3-9112ca0b5db7","zonename":"hyp= erv","type":"DATADISK","size":3221225472,"created":"2014-05-29T13:02:45+053= 0","state":"Allocated","account":"admin","domainid":"c190bda2-e497-11e3-855= 7-c264afd952f5","domain":"ROOT","storagetype":"local","hypervisor":"None","= diskofferingid":"8cf4d614-08b8-455c-a99f-814f4b109d24","diskofferingname":"= local","diskofferingdisplaytext":"local","destroyed":false,"isextractable":= true,"tags":[],"displayvolume":true,"quiescevm":false,"jobid":"109c52a3-416= 3-44db-b438-258e5e4964af","jobstatus":0} > 2014-05-29 13:02:45,507 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-= Executor-14:ctx-23711cb3 job-64) Done executing org.apache.cloudstack.api.c= ommand.admin.volume.CreateVolumeCmdByAdmin for job-64 > 2014-05-29 13:02:45,510 INFO [o.a.c.f.j.i.AsyncJobMonitor] (API-Job-Exec= utor-14:ctx-23711cb3 job-64) Remove job-64 from job monitoring > 2014-05-29 13:02:48,085 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Ha= ndler-1:null) SeqA 3-57302: Processing Seq 3-57302: { Cmd , MgmtId: -1, vi= a: 3, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportComm= and":{"_proxyVmId":2,"_loadInfo":"{\n \"connections\": []\n}","wait":0}}] = } > 2014-05-29 13:02:48,088 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Ha= ndler-1:null) SeqA 3-57302: Sending Seq 3-57302: { Ans: , MgmtId: 21373770= 2773493, via: 3, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentContro= lAnswer":{"result":true,"wait":0}}] } > 2014-05-29 13:02:48,589 DEBUG [c.c.a.ApiServlet] (catalina-exec-13:ctx-d2= 71c568) =3D=3D=3DSTART=3D=3D=3D 10.101.254.225 -- GET command=3DqueryAsyn= cJobResult&jobId=3D109c52a3-4163-44db-b438-258e5e4964af&response=3Djson&ses= sionkey=3DYYidBdJcBJV64kdZ8c%2BKOeSqY14%3D&_=3D1401348497515 > 2014-05-29 13:02:48,607 DEBUG [c.c.a.ApiServlet] (catalina-exec-13:ctx-d2= 71c568 ctx-fb9bf614) =3D=3D=3DEND=3D=3D=3D 10.101.254.225 -- GET command= =3DqueryAsyncJobResult&jobId=3D109c52a3-4163-44db-b438-258e5e4964af&respons= e=3Djson&sessionkey=3DYYidBdJcBJV64kdZ8c%2BKOeSqY14%3D&_=3D1401348497515 > 2014-05-29 13:02:49,898 DEBUG [c.c.a.ApiServlet] (catalina-exec-6:ctx-a1b= 32fd9) =3D=3D=3DSTART=3D=3D=3D 10.101.254.225 -- GET command=3DlistVolume= s&id=3Ddd5d8d32-03b4-4afd-80ca-58586c7c9e8d&response=3Djson&sessionkey=3DYY= idBdJcBJV64kdZ8c%2BKOeSqY14%3D&_=3D1401348498800 > 2014-05-29 13:02:49,914 DEBUG [c.c.a.ApiServlet] (catalina-exec-6:ctx-a1b= 32fd9 ctx-da942c63) =3D=3D=3DEND=3D=3D=3D 10.101.254.225 -- GET command= =3DlistVolumes&id=3Ddd5d8d32-03b4-4afd-80ca-58586c7c9e8d&response=3Djson&se= ssionkey=3DYYidBdJcBJV64kdZ8c%2BKOeSqY14%3D&_=3D1401348498800 > 2014-05-29 13:02:51,929 DEBUG [c.c.a.ApiServlet] (catalina-exec-21:ctx-d1= 70c74c) =3D=3D=3DSTART=3D=3D=3D 10.101.254.225 -- GET command=3DlistVirtu= alMachines&response=3Djson&sessionkey=3DYYidBdJcBJV64kdZ8c%2BKOeSqY14%3D&zo= neid=3D242c701a-43e8-4790-84f3-9112ca0b5db7&domainid=3Dc190bda2-e497-11e3-8= 557-c264afd952f5&account=3Dadmin&state=3DRunning&_=3D1401348500887 > 2014-05-29 13:02:51,939 DEBUG [c.c.u.AccountManagerImpl] (catalina-exec-2= 1:ctx-d170c74c ctx-1d221a22) Access granted to Acct[f3f99282-e497-11e3-8557= -c264afd952f5-admin] to Domain:1/ by AffinityGroupAccessChecker > 2014-05-29 13:02:51,993 DEBUG [c.c.a.ApiServlet] (catalina-exec-21:ctx-d1= 70c74c ctx-1d221a22) =3D=3D=3DEND=3D=3D=3D 10.101.254.225 -- GET command= =3DlistVirtualMachines&response=3Djson&sessionkey=3DYYidBdJcBJV64kdZ8c%2BKO= eSqY14%3D&zoneid=3D242c701a-43e8-4790-84f3-9112ca0b5db7&domainid=3Dc190bda2= -e497-11e3-8557-c264afd952f5&account=3Dadmin&state=3DRunning&_=3D1401348500= 887 > 2014-05-29 13:02:52,079 DEBUG [c.c.a.ApiServlet] (catalina-exec-11:ctx-55= eeb5f4) =3D=3D=3DSTART=3D=3D=3D 10.101.254.225 -- GET command=3DlistVirtu= alMachines&response=3Djson&sessionkey=3DYYidBdJcBJV64kdZ8c%2BKOeSqY14%3D&zo= neid=3D242c701a-43e8-4790-84f3-9112ca0b5db7&domainid=3Dc190bda2-e497-11e3-8= 557-c264afd952f5&account=3Dadmin&state=3DStopped&_=3D1401348501034 > 2014-05-29 13:02:52,088 DEBUG [c.c.u.AccountManagerImpl] (catalina-exec-1= 1:ctx-55eeb5f4 ctx-db0754d3) Access granted to Acct[f3f99282-e497-11e3-8557= -c264afd952f5-admin] to Domain:1/ by AffinityGroupAccessChecker > 2014-05-29 13:02:52,100 DEBUG [c.c.a.ApiServlet] (catalina-exec-11:ctx-55= eeb5f4 ctx-db0754d3) =3D=3D=3DEND=3D=3D=3D 10.101.254.225 -- GET command= =3DlistVirtualMachines&response=3Djson&sessionkey=3DYYidBdJcBJV64kdZ8c%2BKO= eSqY14%3D&zoneid=3D242c701a-43e8-4790-84f3-9112ca0b5db7&domainid=3Dc190bda2= -e497-11e3-8557-c264afd952f5&account=3Dadmin&state=3DStopped&_=3D1401348501= 034 > 2014-05-29 13:02:53,089 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Ha= ndler-2:null) SeqA 3-57303: Processing Seq 3-57303: { Cmd , MgmtId: -1, vi= a: 3, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportComm= and":{"_proxyVmId":2,"_loadInfo":"{\n \"connections\": []\n}","wait":0}}] = } > 2014-05-29 13:02:53,093 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Ha= ndler-2:null) SeqA 3-57303: Sending Seq 3-57303: { Ans: , MgmtId: 21373770= 2773493, via: 3, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentContro= lAnswer":{"result":true,"wait":0}}] } > 2014-05-29 13:02:53,659 DEBUG [c.c.a.ApiServlet] (catalina-exec-14:ctx-f2= 6e23d2) =3D=3D=3DSTART=3D=3D=3D 10.101.254.225 -- GET command=3DattachVol= ume&id=3Ddd5d8d32-03b4-4afd-80ca-58586c7c9e8d&virtualMachineId=3D83b71999-c= f87-49c8-8634-57829534fa46&response=3Djson&sessionkey=3DYYidBdJcBJV64kdZ8c%= 2BKOeSqY14%3D&_=3D1401348502533 > 2014-05-29 13:02:53,700 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (catalina= -exec-14:ctx-f26e23d2 ctx-4696969d) submit async job-65, details: AsyncJobV= O {id:65, userId: 2, accountId: 2, instanceType: Volume, instanceId: 18, cm= d: org.apache.cloudstack.api.command.admin.volume.AttachVolumeCmdByAdmin, c= mdInfo: {"response":"json","id":"dd5d8d32-03b4-4afd-80ca-58586c7c9e8d","ses= sionkey":"YYidBdJcBJV64kdZ8c+KOeSqY14\u003d","ctxDetails":"{\"com.cloud.sto= rage.Volume\":18,\"Volume\":\"dd5d8d32-03b4-4afd-80ca-58586c7c9e8d\",\"com.= cloud.vm.VirtualMachine\":10}","cmdEventType":"VOLUME.ATTACH","ctxUserId":"= 2","virtualMachineId":"83b71999-cf87-49c8-8634-57829534fa46","httpmethod":"= GET","_":"1401348502533","uuid":"dd5d8d32-03b4-4afd-80ca-58586c7c9e8d","ctx= AccountId":"2","ctxStartEventId":"129"}, cmdVersion: 0, status: IN_PROGRESS= , processStatus: 0, resultCode: 0, result: null, initMsid: 213737702773493,= completeMsid: null, lastUpdated: null, lastPolled: null, created: null} > 2014-05-29 13:02:53,701 DEBUG [c.c.a.ApiServlet] (catalina-exec-14:ctx-f2= 6e23d2 ctx-4696969d) =3D=3D=3DEND=3D=3D=3D 10.101.254.225 -- GET command= =3DattachVolume&id=3Ddd5d8d32-03b4-4afd-80ca-58586c7c9e8d&virtualMachineId= =3D83b71999-cf87-49c8-8634-57829534fa46&response=3Djson&sessionkey=3DYYidBd= JcBJV64kdZ8c%2BKOeSqY14%3D&_=3D1401348502533 > 2014-05-29 13:02:53,702 INFO [o.a.c.f.j.i.AsyncJobMonitor] (API-Job-Exec= utor-15:ctx-cbdb1b18 job-65) Add job-65 into job monitoring > 2014-05-29 13:02:53,702 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-= Executor-15:ctx-cbdb1b18 job-65) Executing AsyncJobVO {id:65, userId: 2, ac= countId: 2, instanceType: Volume, instanceId: 18, cmd: org.apache.cloudstac= k.api.command.admin.volume.AttachVolumeCmdByAdmin, cmdInfo: {"response":"js= on","id":"dd5d8d32-03b4-4afd-80ca-58586c7c9e8d","sessionkey":"YYidBdJcBJV64= kdZ8c+KOeSqY14\u003d","ctxDetails":"{\"com.cloud.storage.Volume\":18,\"Volu= me\":\"dd5d8d32-03b4-4afd-80ca-58586c7c9e8d\",\"com.cloud.vm.VirtualMachine= \":10}","cmdEventType":"VOLUME.ATTACH","ctxUserId":"2","virtualMachineId":"= 83b71999-cf87-49c8-8634-57829534fa46","httpmethod":"GET","_":"1401348502533= ","uuid":"dd5d8d32-03b4-4afd-80ca-58586c7c9e8d","ctxAccountId":"2","ctxStar= tEventId":"129"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, res= ultCode: 0, result: null, initMsid: 213737702773493, completeMsid: null, la= stUpdated: null, lastPolled: null, created: null} > 2014-05-29 13:02:53,712 WARN [c.c.a.d.ParamGenericValidationWorker] (API= -Job-Executor-15:ctx-cbdb1b18 job-65 ctx-0cee308d) Received unknown paramet= ers for command attachVolume. Unknown parameters : ctxdetails > 2014-05-29 13:02:53,724 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-= Executor-15:ctx-cbdb1b18 job-65 ctx-0cee308d) Sync job-66 execution on obje= ct VmWorkJobQueue.10 > 2014-05-29 13:02:53,727 DEBUG [c.c.s.VolumeApiServiceImpl] (API-Job-Execu= tor-15:ctx-cbdb1b18 job-65 ctx-0cee308d) New job 66, result field: null > 2014-05-29 13:02:53,727 WARN [c.c.u.d.Merovingian2] (API-Job-Executor-15= :ctx-cbdb1b18 job-65 ctx-0cee308d) Was unable to find lock for the key vm_i= nstance10 and thread id 1410233040 > 2014-05-29 13:02:54,454 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (AsyncJob= Mgr-Heartbeat-1:ctx-8739672c) Execute sync-queue item: SyncQueueItemVO {id:= 23, queueId: 17, contentType: AsyncJob, contentId: 66, lastProcessMsid: nul= l, lastprocessNumber: null, lastProcessTime: null, created: Thu May 29 13:0= 2:53 IST 2014} > 2014-05-29 13:02:54,456 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (AsyncJob= Mgr-Heartbeat-1:ctx-8739672c) Schedule queued job-66 > 2014-05-29 13:02:54,477 INFO [o.a.c.f.j.i.AsyncJobMonitor] (Work-Job-Exe= cutor-15:ctx-3c499195 job-65/job-66) Add job-66 into job monitoring > 2014-05-29 13:02:54,477 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job= -Executor-15:ctx-3c499195 job-65/job-66) Executing AsyncJobVO {id:66, userI= d: 2, accountId: 2, instanceType: null, instanceId: null, cmd: com.cloud.st= orage.VmWorkAttachVolume, cmdInfo: rO0ABXNyACRjb20uY2xvdWQuc3RvcmFnZS5WbVdv= cmtBdHRhY2hWb2x1bWUHra_5YYfiHAIAAkwACGRldmljZUlkdAAQTGphdmEvbGFuZy9Mb25nO0w= ACHZvbHVtZUlkcQB-AAF4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3= VudElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1ldAASTGphdmEvbGFuZy9TdHJpbmc7e= HAAAAAAAAAAAgAAAAAAAAACAAAAAAAAAAp0ABRWb2x1bWVBcGlTZXJ2aWNlSW1wbHBzcgAOamF2= YS5sYW5nLkxvbmc7i-SQzI8j3wIAAUoABXZhbHVleHIAEGphdmEubGFuZy5OdW1iZXKGrJUdC5T= giwIAAHhwAAAAAAAAABI, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0,= resultCode: 0, result: null, initMsid: 213737702773493, completeMsid: null= , lastUpdated: null, lastPolled: null, created: Thu May 29 13:02:53 IST 201= 4} > 2014-05-29 13:02:54,477 DEBUG [c.c.v.VmWorkJobDispatcher] (Work-Job-Execu= tor-15:ctx-3c499195 job-65/job-66) Run VM work job: com.cloud.storage.VmWor= kAttachVolume for VM 10, job origin: 65 > 2014-05-29 13:02:54,479 DEBUG [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Exe= cutor-15:ctx-3c499195 job-65/job-66 ctx-8e2aeba5) Execute VM work job: com.= cloud.storage.VmWorkAttachVolume{"volumeId":18,"userId":2,"accountId":2,"vm= Id":10,"handlerName":"VolumeApiServiceImpl"} > 2014-05-29 13:02:54,501 DEBUG [o.a.c.s.a.LocalStoragePoolAllocator] (Work= -Job-Executor-15:ctx-3c499195 job-65/job-66 ctx-8e2aeba5) LocalStoragePoolA= llocator trying to find storage pool to fit the vm > 2014-05-29 13:02:54,503 DEBUG [o.a.c.s.a.LocalStoragePoolAllocator] (Work= -Job-Executor-15:ctx-3c499195 job-65/job-66 ctx-8e2aeba5) LocalStoragePoolA= llocator returning 0 suitable storage pools > 2014-05-29 13:02:54,504 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator= ] (Work-Job-Executor-15:ctx-3c499195 job-65/job-66 ctx-8e2aeba5) ClusterSco= peStoragePoolAllocator looking for storage pool > 2014-05-29 13:02:54,504 DEBUG [o.a.c.s.a.ZoneWideStoragePoolAllocator] (W= ork-Job-Executor-15:ctx-3c499195 job-65/job-66 ctx-8e2aeba5) ZoneWideStorag= ePoolAllocator to find storage pool > 2014-05-29 13:02:54,504 WARN [o.a.c.e.o.VolumeOrchestrator] (Work-Job-Ex= ecutor-15:ctx-3c499195 job-65/job-66 ctx-8e2aeba5) Unable to find suitable = primary storage when creating volume data-local2 > 2014-05-29 13:02:54,504 ERROR [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Exe= cutor-15:ctx-3c499195 job-65/job-66 ctx-8e2aeba5) Invocation exception, cau= sed by: com.cloud.utils.exception.CloudRuntimeException: Unable to find sui= table primary storage when creating volume data-local2 > 2014-05-29 13:02:54,504 INFO [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Exe= cutor-15:ctx-3c499195 job-65/job-66 ctx-8e2aeba5) Rethrow exception com.clo= ud.utils.exception.CloudRuntimeException: Unable to find suitable primary s= torage when creating volume data-local2 > 2014-05-29 13:02:54,504 DEBUG [c.c.v.VmWorkJobDispatcher] (Work-Job-Execu= tor-15:ctx-3c499195 job-65/job-66) Done with run of VM work job: com.cloud.= storage.VmWorkAttachVolume for VM 10, job origin: 65 > 2014-05-29 13:02:54,505 ERROR [c.c.v.VmWorkJobDispatcher] (Work-Job-Execu= tor-15:ctx-3c499195 job-65/job-66) Unable to complete AsyncJobVO {id:66, us= erId: 2, accountId: 2, instanceType: null, instanceId: null, cmd: com.cloud= .storage.VmWorkAttachVolume, cmdInfo: rO0ABXNyACRjb20uY2xvdWQuc3RvcmFnZS5Wb= VdvcmtBdHRhY2hWb2x1bWUHra_5YYfiHAIAAkwACGRldmljZUlkdAAQTGphdmEvbGFuZy9Mb25n= O0wACHZvbHVtZUlkcQB-AAF4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWN= jb3VudElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1ldAASTGphdmEvbGFuZy9TdHJpbm= c7eHAAAAAAAAAAAgAAAAAAAAACAAAAAAAAAAp0ABRWb2x1bWVBcGlTZXJ2aWNlSW1wbHBzcgAOa= mF2YS5sYW5nLkxvbmc7i-SQzI8j3wIAAUoABXZhbHVleHIAEGphdmEubGFuZy5OdW1iZXKGrJUd= C5TgiwIAAHhwAAAAAAAAABI, cmdVersion: 0, status: IN_PROGRESS, processStatus:= 0, resultCode: 0, result: null, initMsid: 213737702773493, completeMsid: n= ull, lastUpdated: null, lastPolled: null, created: Thu May 29 13:02:53 IST = 2014}, job origin:65 > com.cloud.utils.exception.CloudRuntimeException: Unable to find suitable = primary storage when creating volume data-local2 > at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.= createVolume(VolumeOrchestrator.java:447) > at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.= createVolumeOnPrimaryStorage(VolumeOrchestrator.java:734) > at com.cloud.storage.VolumeApiServiceImpl.attachVolumeToVM(Volume= ApiServiceImpl.java:1186) > at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolume= ToVM(VolumeApiServiceImpl.java:1054) > at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolume= ToVM(VolumeApiServiceImpl.java:2475) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccess= orImpl.java:57) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMeth= odAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:601) > at com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHa= ndlerProxy.java:107) > at com.cloud.storage.VolumeApiServiceImpl.handleVmWorkJob(VolumeA= piServiceImpl.java:2510) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccess= orImpl.java:57) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMeth= odAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:601) > at org.springframework.aop.support.AopUtils.invokeJoinpointUsingR= eflection(AopUtils.java:317) > at org.springframework.aop.framework.ReflectiveMethodInvocation.i= nvokeJoinpoint(ReflectiveMethodInvocation.java:183) > at org.springframework.aop.framework.ReflectiveMethodInvocation.p= roceed(ReflectiveMethodInvocation.java:150) > at org.springframework.aop.interceptor.ExposeInvocationIntercepto= r.invoke(ExposeInvocationInterceptor.java:91) > at org.springframework.aop.framework.ReflectiveMethodInvocation.p= roceed(ReflectiveMethodInvocation.java:172) > at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(Jd= kDynamicAopProxy.java:204) > at $Proxy181.handleVmWorkJob(Unknown Source) > at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.ja= va:102) > at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$= 5.runInContext(AsyncJobManagerImpl.java:503) > at org.apache.cloudstack.managed.context.ManagedContextRunnable$1= .run(ManagedContextRunnable.java:49) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt$1.call(DefaultManagedContext.java:56) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt.callWithContext(DefaultManagedContext.java:103) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt.runWithContext(DefaultManagedContext.java:53) > at org.apache.cloudstack.managed.context.ManagedContextRunnable.r= un(ManagedContextRunnable.java:46) > at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$= 5.run(AsyncJobManagerImpl.java:460) > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.= java:471) > at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:= 334) > at java.util.concurrent.FutureTask.run(FutureTask.java:166) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolEx= ecutor.java:1110) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolE= xecutor.java:603) > at java.lang.Thread.run(Thread.java:722) > 2014-05-29 13:02:54,525 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job= -Executor-15:ctx-3c499195 job-65/job-66) Complete async job-66, jobStatus: = FAILED, resultCode: 0, result: rO0ABXNyABpqYXZhLmxhbmcuUnVudGltZUV4Y2VwdGlv= bp5fBkcKNIPlAgAAeHIAE2phdmEubGFuZy5FeGNlcHRpb27Q_R8-GjscxAIAAHhyABNqYXZhLmx= hbmcuVGhyb3dhYmxl1cY1Jzl3uMsDAARMAAVjYXVzZXQAFUxqYXZhL2xhbmcvVGhyb3dhYmxlO0= wADWRldGFpbE1lc3NhZ2V0ABJMamF2YS9sYW5nL1N0cmluZztbAApzdGFja1RyYWNldAAeW0xqY= XZhL2xhbmcvU3RhY2tUcmFjZUVsZW1lbnQ7TAAUc3VwcHJlc3NlZEV4Y2VwdGlvbnN0ABBMamF2= YS91dGlsL0xpc3Q7eHBxAH4AB3QAZEpvYiBmYWlsZWQgZHVlIHRvIGV4Y2VwdGlvbiBVbmFibGU= gdG8gZmluZCBzdWl0YWJsZSBwcmltYXJ5IHN0b3JhZ2Ugd2hlbiBjcmVhdGluZyB2b2x1bWUgZG= F0YS1sb2NhbDJ1cgAeW0xqYXZhLmxhbmcuU3RhY2tUcmFjZUVsZW1lbnQ7AkYqPDz9IjkCAAB4c= AAAAA5zcgAbamF2YS5sYW5nLlN0YWNrVHJhY2VFbGVtZW50YQnFmiY23YUCAARJAApsaW5lTnVt= YmVyTAAOZGVjbGFyaW5nQ2xhc3NxAH4ABEwACGZpbGVOYW1lcQB-AARMAAptZXRob2ROYW1lcQB= -AAR4cAAAAHJ0ACBjb20uY2xvdWQudm0uVm1Xb3JrSm9iRGlzcGF0Y2hlcnQAGFZtV29ya0pvYk= Rpc3BhdGNoZXIuamF2YXQABnJ1bkpvYnNxAH4ACwAAAfd0AD9vcmcuYXBhY2hlLmNsb3Vkc3RhY= 2suZnJhbWV3b3JrLmpvYnMuaW1wbC5Bc3luY0pvYk1hbmFnZXJJbXBsJDV0ABhBc3luY0pvYk1h= bmFnZXJJbXBsLmphdmF0AAxydW5JbkNvbnRleHRzcQB-AAsAAAAxdAA-b3JnLmFwYWNoZS5jbG9= 1ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5NYW5hZ2VkQ29udGV4dFJ1bm5hYmxlJDF0ABtNYW5hZ2= VkQ29udGV4dFJ1bm5hYmxlLmphdmF0AANydW5zcQB-AAsAAAA4dABCb3JnLmFwYWNoZS5jbG91Z= HN0YWNrLm1hbmFnZWQuY29udGV4dC5pbXBsLkRlZmF1bHRNYW5hZ2VkQ29udGV4dCQxdAAaRGVm= YXVsdE1hbmFnZWRDb250ZXh0LmphdmF0AARjYWxsc3EAfgALAAAAZ3QAQG9yZy5hcGFjaGUuY2x= vdWRzdGFjay5tYW5hZ2VkLmNvbnRleHQuaW1wbC5EZWZhdWx0TWFuYWdlZENvbnRleHRxAH4AGn= QAD2NhbGxXaXRoQ29udGV4dHNxAH4ACwAAADVxAH4AHXEAfgAadAAOcnVuV2l0aENvbnRleHRzc= QB-AAsAAAAudAA8b3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5NYW5hZ2Vk= Q29udGV4dFJ1bm5hYmxlcQB-ABZxAH4AF3NxAH4ACwAAAcxxAH4AEXEAfgAScQB-ABdzcQB-AAs= AAAHXdAAuamF2YS51dGlsLmNvbmN1cnJlbnQuRXhlY3V0b3JzJFJ1bm5hYmxlQWRhcHRlcnQADk= V4ZWN1dG9ycy5qYXZhcQB-ABtzcQB-AAsAAAFOdAAkamF2YS51dGlsLmNvbmN1cnJlbnQuRnV0d= XJlVGFzayRTeW5jdAAPRnV0dXJlVGFzay5qYXZhdAAIaW5uZXJSdW5zcQB-AAsAAACmdAAfamF2= YS51dGlsLmNvbmN1cnJlbnQuRnV0dXJlVGFza3EAfgApcQB-ABdzcQB-AAsAAARWdAAnamF2YS5= 1dGlsLmNvbmN1cnJlbnQuVGhyZWFkUG9vbEV4ZWN1dG9ydAAXVGhyZWFkUG9vbEV4ZWN1dG9yLm= phdmF0AAlydW5Xb3JrZXJzcQB-AAsAAAJbdAAuamF2YS51dGlsLmNvbmN1cnJlbnQuVGhyZWFkU= G9vbEV4ZWN1dG9yJFdvcmtlcnEAfgAvcQB-ABdzcQB-AAsAAALSdAAQamF2YS5sYW5nLlRocmVh= ZHQAC1RocmVhZC5qYXZhcQB-ABdzcgAmamF2YS51dGlsLkNvbGxlY3Rpb25zJFVubW9kaWZpYWJ= sZUxpc3T8DyUxteyOEAIAAUwABGxpc3RxAH4ABnhyACxqYXZhLnV0aWwuQ29sbGVjdGlvbnMkVW= 5tb2RpZmlhYmxlQ29sbGVjdGlvbhlCAIDLXvceAgABTAABY3QAFkxqYXZhL3V0aWwvQ29sbGVjd= Glvbjt4cHNyABNqYXZhLnV0aWwuQXJyYXlMaXN0eIHSHZnHYZ0DAAFJAARzaXpleHAAAAAAdwQA= AAAAeHEAfgA7eA > 2014-05-29 13:02:54,534 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job= -Executor-15:ctx-3c499195 job-65/job-66) Done executing com.cloud.storage.V= mWorkAttachVolume for job-66 > 2014-05-29 13:02:54,552 ERROR [c.c.a.ApiAsyncJobDispatcher] (API-Job-Exec= utor-15:ctx-cbdb1b18 job-65) Unexpected exception while executing org.apach= e.cloudstack.api.command.admin.volume.AttachVolumeCmdByAdmin > java.lang.RuntimeException: Unexpected exception > at com.cloud.storage.VolumeApiServiceImpl.attachVolumeToVM(Volume= ApiServiceImpl.java:1044) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccess= orImpl.java:57) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMeth= odAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:601) > at org.springframework.aop.support.AopUtils.invokeJoinpointUsingR= eflection(AopUtils.java:317) > at org.springframework.aop.framework.ReflectiveMethodInvocation.i= nvokeJoinpoint(ReflectiveMethodInvocation.java:183) > at org.springframework.aop.framework.ReflectiveMethodInvocation.p= roceed(ReflectiveMethodInvocation.java:150) > at org.springframework.aop.interceptor.ExposeInvocationIntercepto= r.invoke(ExposeInvocationInterceptor.java:91) > at org.springframework.aop.framework.ReflectiveMethodInvocation.p= roceed(ReflectiveMethodInvocation.java:172) > at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(Jd= kDynamicAopProxy.java:204) > at $Proxy181.attachVolumeToVM(Unknown Source) > at org.apache.cloudstack.api.command.admin.volume.AttachVolumeCmd= ByAdmin.execute(AttachVolumeCmdByAdmin.java:38) > at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:141) > at com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatch= er.java:108) > at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$= 5.runInContext(AsyncJobManagerImpl.java:503) > at org.apache.cloudstack.managed.context.ManagedContextRunnable$1= .run(ManagedContextRunnable.java:49) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt$1.call(DefaultManagedContext.java:56) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt.callWithContext(DefaultManagedContext.java:103) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt.runWithContext(DefaultManagedContext.java:53) > at org.apache.cloudstack.managed.context.ManagedContextRunnable.r= un(ManagedContextRunnable.java:46) > at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$= 5.run(AsyncJobManagerImpl.java:460) > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.= java:471) > at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:= 334) > at java.util.concurrent.FutureTask.run(FutureTask.java:166) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolEx= ecutor.java:1110) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolE= xecutor.java:603) > at java.lang.Thread.run(Thread.java:722) > Caused by: java.lang.RuntimeException: Job failed due to exception Unable= to find suitable primary storage when creating volume data-local2 > ... 27 more > 2014-05-29 13:02:54,556 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-= Executor-15:ctx-cbdb1b18 job-65) Complete async job-65, jobStatus: FAILED, = resultCode: 530, result: org.apache.cloudstack.api.response.ExceptionRespon= se/null/{"uuidList":[],"errorcode":530,"errortext":"Unexpected exception"} -- This message was sent by Atlassian JIRA (v6.2#6252)