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 72A7DF5DD for ; Fri, 5 Apr 2013 17:45:16 +0000 (UTC) Received: (qmail 69710 invoked by uid 500); 5 Apr 2013 17:45:16 -0000 Delivered-To: apmail-cloudstack-issues-archive@cloudstack.apache.org Received: (qmail 69691 invoked by uid 500); 5 Apr 2013 17:45:16 -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 69684 invoked by uid 500); 5 Apr 2013 17:45:16 -0000 Delivered-To: apmail-incubator-cloudstack-issues@incubator.apache.org Received: (qmail 69680 invoked by uid 99); 5 Apr 2013 17:45:16 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 05 Apr 2013 17:45:16 +0000 Date: Fri, 5 Apr 2013 17:45:16 +0000 (UTC) From: "Rohit Yadav (JIRA)" To: cloudstack-issues@incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Resolved] (CLOUDSTACK-1774) Used Master Branch System VM Template: Volume of System VM Failed to Create on the ESXi host due to Runtime Error 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-1774?page=3Dcom.atl= assian.jira.plugin.system.issuetabpanels:all-tabpanel ] Rohit Yadav resolved CLOUDSTACK-1774. ------------------------------------- Resolution: Duplicate We have a duplicate here: https://issues.apache.org/jira/browse/CLOUDSTACK-= 1867 Closing this one as it's older, will fix the jenkins build to make use of t= he nonoss ovftool to export ova that is readable by vmware exsi as soon as = I get time. Cheers. =20 > Used Master Branch System VM Template: Volume of System VM Failed to Crea= te on the ESXi host due to Runtime Error > -------------------------------------------------------------------------= ---------------------------------------- > > Key: CLOUDSTACK-1774 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-177= 4 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the defa= ult.)=20 > Components: Management Server > Affects Versions: 4.2.0 > Reporter: Chandan Purushothama > Assignee: Rohit Yadav > Priority: Blocker > Fix For: 4.2.0 > > Attachments: management-server.zip > > > Observed the bug on an Advanced Zone setup where I used the new master br= anch System VM Template for VMWare. I am not sure whether the bug is in the= CloudStack management server code or the System VM Template itself. I will= re-check the build using the old System VM Template and add more informati= on to the bug report. > =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D > Observations: > =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D > 2013-03-21 19:44:24,629 DEBUG [agent.transport.Request] (consoleproxy-1:n= ull) Seq 1-62062601: Sending { Cmd , MgmtId: 206915885093604, via: 1, Ver:= v1, Flags: 100111, [{"storage.PrimaryStorageDownloadCommand":{"localPath":= "/mnt/d7574a80-9bb1-3c9c-be00-f12d6fd010f6","poolUuid":"d7574a80-9bb1-3c9c-= be00-f12d6fd010f6","poolId":200,"primaryPool":{"id":200,"uuid":"d7574a80-9b= b1-3c9c-be00-f12d6fd010f6","host":"10.223.110.232","path":"/export/home/cha= ndan/asfmas-59-67/primary","port":2049,"type":"NetworkFilesystem"},"seconda= ryStorageUrl":"nfs://10.223.110.232/export/home/chandan/asfmas-59-67/second= ary","primaryStorageUrl":"nfs://10.223.110.232/export/home/chandan/asfmas-5= 9-67/primary","url":"nfs://10.223.110.232/export/home/chandan/asfmas-59-67/= secondary/template/tmpl/1/8/","format":"OVA","accountId":1,"name":"routing-= 8","wait":10800}}] } > 2013-03-21 19:44:24,630 DEBUG [agent.transport.Request] (consoleproxy-1:n= ull) Seq 1-62062601: Executing: { Cmd , MgmtId: 206915885093604, via: 1, V= er: v1, Flags: 100111, [{"storage.PrimaryStorageDownloadCommand":{"localPat= h":"/mnt/d7574a80-9bb1-3c9c-be00-f12d6fd010f6","poolUuid":"d7574a80-9bb1-3c= 9c-be00-f12d6fd010f6","poolId":200,"primaryPool":{"id":200,"uuid":"d7574a80= -9bb1-3c9c-be00-f12d6fd010f6","host":"10.223.110.232","path":"/export/home/= chandan/asfmas-59-67/primary","port":2049,"type":"NetworkFilesystem"},"seco= ndaryStorageUrl":"nfs://10.223.110.232/export/home/chandan/asfmas-59-67/sec= ondary","primaryStorageUrl":"nfs://10.223.110.232/export/home/chandan/asfma= s-59-67/primary","url":"nfs://10.223.110.232/export/home/chandan/asfmas-59-= 67/secondary/template/tmpl/1/8/","format":"OVA","accountId":1,"name":"routi= ng-8","wait":10800}}] } > 2013-03-21 19:44:24,631 DEBUG [agent.manager.DirectAgentAttache] (DirectA= gent-1:null) Seq 1-62062601: Executing request > 2013-03-21 19:44:24,632 INFO [vmware.resource.VmwareResource] (DirectAge= nt-1:10.223.59.66) Executing resource PrimaryStorageDownloadCommand: {"loca= lPath":"/mnt/d7574a80-9bb1-3c9c-be00-f12d6fd010f6","poolUuid":"d7574a80-9bb= 1-3c9c-be00-f12d6fd010f6","poolId":200,"primaryPool":{"id":200,"uuid":"d757= 4a80-9bb1-3c9c-be00-f12d6fd010f6","host":"10.223.110.232","path":"/export/h= ome/chandan/asfmas-59-67/primary","port":2049,"type":"NetworkFilesystem"},"= secondaryStorageUrl":"nfs://10.223.110.232/export/home/chandan/asfmas-59-67= /secondary","primaryStorageUrl":"nfs://10.223.110.232/export/home/chandan/a= sfmas-59-67/primary","url":"nfs://10.223.110.232/export/home/chandan/asfmas= -59-67/secondary/template/tmpl/1/8/","format":"OVA","accountId":1,"name":"r= outing-8","wait":10800} > 2013-03-21 19:44:24,642 DEBUG [agent.manager.DirectAgentAttache] (DirectA= gent-5:null) Seq 1-62062600: Response Received: > 2013-03-21 19:44:24,643 DEBUG [agent.transport.Request] (DirectAgent-5:nu= ll) Seq 1-62062600: Processing: { Ans: , MgmtId: 206915885093604, via: 1, = Ver: v1, Flags: 10, [{"CheckHealthAnswer":{"result":true,"details":"resourc= e is alive","wait":0}}] } > 2013-03-21 19:44:24,643 DEBUG [agent.transport.Request] (AgentTaskPool-1:= null) Seq 1-62062600: Received: { Ans: , MgmtId: 206915885093604, via: 1, = Ver: v1, Flags: 10, { CheckHealthAnswer } } > 2013-03-21 19:44:24,643 DEBUG [agent.manager.AgentManagerImpl] (AgentTask= Pool-1:null) Details from executing class com.cloud.agent.api.CheckHealthCo= mmand: resource is alive > 2013-03-21 19:44:24,643 DEBUG [agent.manager.AgentManagerImpl] (AgentTask= Pool-1:null) agent (1) responded to checkHeathCommand, reporting that agent= is Up > 2013-03-21 19:44:24,643 INFO [agent.manager.AgentManagerImpl] (AgentTask= Pool-1:null) The state determined is Up > 2013-03-21 19:44:24,643 INFO [agent.manager.AgentManagerImpl] (AgentTask= Pool-1:null) Agent is determined to be up and running > 2013-03-21 19:44:24,643 DEBUG [cloud.host.Status] (AgentTaskPool-1:null) = Transition:[Resource state =3D Enabled, Agent event =3D Ping, Host id =3D 1= , name =3D 10.223.59.66] > 2013-03-21 19:44:24,656 INFO [vmware.manager.VmwareStorageManagerImpl] (= DirectAgent-1:10.223.59.66) Template routing-8 is not setup yet, setup temp= late from secondary storage with uuid name: b3ec0e8a2229370f80cc00b0ad6c4c7= 2 > 2013-03-21 19:44:24,659 DEBUG [cloud.host.Status] (AgentTaskPool-1:null) = Agent status update: [id =3D 1; name =3D 10.223.59.66; old status =3D Up; e= vent =3D Ping; new status =3D Up; old update count =3D 2; new update count = =3D 3] > 2013-03-21 19:44:24,668 INFO [vmware.manager.VmwareStorageManagerImpl] (= DirectAgent-1:10.223.59.66) Executing copyTemplateFromSecondaryToPrimary. s= econdaryStorage: nfs://10.223.110.232/export/home/chandan/asfmas-59-67/seco= ndary, templatePathAtSecondaryStorage: template/tmpl/1/8/, templateName: ro= uting-8 > 2013-03-21 19:44:24,668 INFO [vmware.manager.VmwareStorageManagerImpl] (= DirectAgent-1:10.223.59.66) Secondary storage mount point: /var/cloudstack/= mnt/NEWSYSTEMPLVMW/206915885093604.e5d2f74 > 2013-03-21 19:44:24,674 ERROR [vmware.manager.VmwareStorageManagerImpl] (= DirectAgent-1:10.223.59.66) Unable to execute PrimaryStorageDownloadCommand= due to exception > java.io.FileNotFoundException: /var/cloudstack/mnt/NEWSYSTEMPLVMW/2069158= 85093604.e5d2f74/template/tmpl/1/8/systemvmtemplate-2013-03-20-master-vmwar= e.ovf (Permission denied) > at java.io.FileInputStream.open(Native Method) > at java.io.FileInputStream.(FileInputStream.java:137) > at java.io.FileInputStream.(FileInputStream.java:96) > at com.cloud.hypervisor.vmware.mo.HttpNfcLeaseMO.readOvfContent(H= ttpNfcLeaseMO.java:125) > at com.cloud.hypervisor.vmware.mo.HypervisorHostHelper.importVmFr= omOVF(HypervisorHostHelper.java:981) > at com.cloud.hypervisor.vmware.mo.HostMO.importVmFromOVF(HostMO.j= ava:681) > at com.cloud.hypervisor.vmware.manager.VmwareStorageManagerImpl.c= opyTemplateFromSecondaryToPrimary(VmwareStorageManagerImpl.java:478) > at com.cloud.hypervisor.vmware.manager.VmwareStorageManagerImpl.e= xecute(VmwareStorageManagerImpl.java:153) > at com.cloud.hypervisor.vmware.resource.VmwareResource.execute(Vm= wareResource.java:3660) > at com.cloud.hypervisor.vmware.resource.VmwareResource.executeReq= uest(VmwareResource.java:414) > at com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgen= tAttache.java:186) > 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.ScheduledThreadPoolExecutor$ScheduledFutu= reTask.access$101(ScheduledThreadPoolExecutor.java:165) > at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutu= reTask.run(ScheduledThreadPoolExecutor.java:266) > 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:679) > 2013-03-21 19:44:24,676 DEBUG [agent.manager.DirectAgentAttache] (DirectA= gent-1:null) Seq 1-62062601: Response Received: > 2013-03-21 19:44:24,676 DEBUG [agent.transport.Request] (DirectAgent-1:nu= ll) Seq 1-62062601: Processing: { Ans: , MgmtId: 206915885093604, via: 1, = Ver: v1, Flags: 110, [{"storage.PrimaryStorageDownloadAnswer":{"templateSiz= e":0,"result":false,"details":"Unable to execute PrimaryStorageDownloadComm= and due to exception","wait":0}}] } > 2013-03-21 19:44:24,676 DEBUG [agent.transport.Request] (consoleproxy-1:n= ull) Seq 1-62062601: Received: { Ans: , MgmtId: 206915885093604, via: 1, V= er: v1, Flags: 110, { PrimaryStorageDownloadAnswer } } > 2013-03-21 19:44:24,676 DEBUG [agent.manager.AgentAttache] (DirectAgent-1= :null) Seq 1-62062601: No more commands found > 2013-03-21 19:44:24,677 DEBUG [agent.manager.AgentManagerImpl] (consolepr= oxy-1:null) Details from executing class com.cloud.agent.api.storage.Primar= yStorageDownloadCommand: Unable to execute PrimaryStorageDownloadCommand du= e to exception > 2013-03-21 19:44:24,677 DEBUG [cloud.template.TemplateManagerImpl] (conso= leproxy-1:null) Template 8 download to pool 200 failed due to Unable to exe= cute PrimaryStorageDownloadCommand due to exception > 2013-03-21 19:44:24,678 DEBUG [cloud.template.TemplateManagerImpl] (conso= leproxy-1:null) Template 8 is not found on and can not be downloaded to poo= l 200 > 2013-03-21 19:44:24,744 DEBUG [agent.transport.Request] (consoleproxy-1:n= ull) Seq 1-62062602: Sending { Cmd , MgmtId: 206915885093604, via: 1, Ver:= v1, Flags: 100111, [{"storage.CreateCommand":{"volId":2,"pool":{"id":200,"= uuid":"d7574a80-9bb1-3c9c-be00-f12d6fd010f6","host":"10.223.110.232","path"= :"/export/home/chandan/asfmas-59-67/primary","port":2049,"type":"NetworkFil= esystem"},"diskCharacteristics":{"size":0,"tags":[],"type":"ROOT","name":"R= OOT-2","useLocalStorage":false,"recreatable":true,"diskOfferingId":10,"volu= meId":2},"wait":0}}] } > 2013-03-21 19:44:24,745 DEBUG [agent.transport.Request] (consoleproxy-1:n= ull) Seq 1-62062602: Executing: { Cmd , MgmtId: 206915885093604, via: 1, V= er: v1, Flags: 100111, [{"storage.CreateCommand":{"volId":2,"pool":{"id":20= 0,"uuid":"d7574a80-9bb1-3c9c-be00-f12d6fd010f6","host":"10.223.110.232","pa= th":"/export/home/chandan/asfmas-59-67/primary","port":2049,"type":"Network= Filesystem"},"diskCharacteristics":{"size":0,"tags":[],"type":"ROOT","name"= :"ROOT-2","useLocalStorage":false,"recreatable":true,"diskOfferingId":10,"v= olumeId":2},"wait":0}}] } > 2013-03-21 19:44:24,745 DEBUG [agent.manager.DirectAgentAttache] (DirectA= gent-6:null) Seq 1-62062602: Executing request > 2013-03-21 19:44:24,747 INFO [vmware.resource.VmwareResource] (DirectAge= nt-6:10.223.59.66) Executing resource CreateCommand: {"volId":2,"pool":{"id= ":200,"uuid":"d7574a80-9bb1-3c9c-be00-f12d6fd010f6","host":"10.223.110.232"= ,"path":"/export/home/chandan/asfmas-59-67/primary","port":2049,"type":"Net= workFilesystem"},"diskCharacteristics":{"size":0,"tags":[],"type":"ROOT","n= ame":"ROOT-2","useLocalStorage":false,"recreatable":true,"diskOfferingId":1= 0,"volumeId":2},"wait":0} > 2013-03-21 19:44:27,227 DEBUG [vmware.mo.HostMO] (DirectAgent-6:10.223.59= .66) find VM 56b1ebcb2b854ec5b786d6be4afcaaa4 on host > 2013-03-21 19:44:27,227 DEBUG [vmware.mo.HostMO] (DirectAgent-6:10.223.59= .66) load VM cache on host > 2013-03-21 19:44:27,238 INFO [vmware.resource.VmwareResource] (DirectAge= nt-6:10.223.59.66) Delete file if exists in datastore to clear the way for = creating the volume. file: [d7574a809bb13c9cbe00f12d6fd010f6] ROOT-2-2.vmdk > 2013-03-21 19:44:27,243 INFO [vmware.mo.DatastoreMO] (DirectAgent-6:10.2= 23.59.66) Search file ROOT-2-2.vmdk on [d7574a809bb13c9cbe00f12d6fd010f6] > 2013-03-21 19:44:27,258 INFO [vmware.mo.DatastoreMO] (DirectAgent-6:10.2= 23.59.66) File [d7574a809bb13c9cbe00f12d6fd010f6] ROOT-2-2.vmdk does not ex= ist on datastore > 2013-03-21 19:44:27,261 INFO [vmware.mo.DatastoreMO] (DirectAgent-6:10.2= 23.59.66) Search file ROOT-2-2-flat.vmdk on [d7574a809bb13c9cbe00f12d6fd010= f6] > 2013-03-21 19:44:27,277 INFO [vmware.mo.DatastoreMO] (DirectAgent-6:10.2= 23.59.66) File [d7574a809bb13c9cbe00f12d6fd010f6] ROOT-2-2-flat.vmdk does n= ot exist on datastore > 2013-03-21 19:44:27,280 INFO [vmware.mo.DatastoreMO] (DirectAgent-6:10.2= 23.59.66) Search file ROOT-2-2-delta.vmdk on [d7574a809bb13c9cbe00f12d6fd01= 0f6] > 2013-03-21 19:44:27,298 INFO [vmware.mo.DatastoreMO] (DirectAgent-6:10.2= 23.59.66) File [d7574a809bb13c9cbe00f12d6fd010f6] ROOT-2-2-delta.vmdk does = not exist on datastore > 2013-03-21 19:44:27,843 INFO [vmware.resource.VmwareResource] (DirectAge= nt-6:10.223.59.66) Destroy dummy VM after volume creation > 2013-03-21 19:44:28,898 ERROR [vmware.resource.VmwareResource] (DirectAge= nt-6:10.223.59.66) CreateCommand failed due to Exception: java.lang.Runtime= Exception > Message: Invalid configuration for device '0'. > java.lang.RuntimeException: Invalid configuration for device '0'. > at com.cloud.hypervisor.vmware.util.VmwareClient.waitForTask(Vmwa= reClient.java:291) > at com.cloud.hypervisor.vmware.mo.VirtualMachineMO.createDisk(Vir= tualMachineMO.java:944) > at com.cloud.hypervisor.vmware.mo.VirtualMachineMO.createDisk(Vir= tualMachineMO.java:867) > at com.cloud.hypervisor.vmware.resource.VmwareResource.execute(Vm= wareResource.java:3955) > at com.cloud.hypervisor.vmware.resource.VmwareResource.executeReq= uest(VmwareResource.java:348) > at com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgen= tAttache.java:186) > 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.ScheduledThreadPoolExecutor$ScheduledFutu= reTask.access$101(ScheduledThreadPoolExecutor.java:165) > at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutu= reTask.run(ScheduledThreadPoolExecutor.java:266) > 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:679) > 2013-03-21 19:44:28,900 DEBUG [agent.manager.DirectAgentAttache] (DirectA= gent-6:null) Seq 1-62062602: Response Received: > 2013-03-21 19:44:28,901 DEBUG [agent.transport.Request] (DirectAgent-6:nu= ll) Seq 1-62062602: Processing: { Ans: , MgmtId: 206915885093604, via: 1, = Ver: v1, Flags: 110, [{"storage.CreateAnswer":{"requestTemplateReload":fals= e,"result":false,"details":"Exception: java.lang.Exception\nMessage: java.l= ang.RuntimeException: Invalid configuration for device '0'.\nStack: java.la= ng.Exception: java.lang.RuntimeException: Invalid configuration for device = '0'.\n\tat com.cloud.hypervisor.vmware.resource.VmwareResource.execute(Vmwa= reResource.java:4034)\n\tat com.cloud.hypervisor.vmware.resource.VmwareReso= urce.executeRequest(VmwareResource.java:348)\n\tat com.cloud.agent.manager.= DirectAgentAttache$Task.run(DirectAgentAttache.java:186)\n\tat java.util.co= ncurrent.Executors$RunnableAdapter.call(Executors.java:471)\n\tat java.util= .concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)\n\tat java.util.c= oncurrent.FutureTask.run(FutureTask.java:166)\n\tat java.util.concurrent.Sc= heduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoo= lExecutor.java:165)\n\tat java.util.concurrent.ScheduledThreadPoolExecutor$= ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:266)\n\tat java.ut= il.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)\n\= tat java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.j= ava:603)\n\tat java.lang.Thread.run(Thread.java:679)\nCaused by: java.lang.= RuntimeException: Invalid configuration for device '0'.\n\tat com.cloud.hyp= ervisor.vmware.util.VmwareClient.waitForTask(VmwareClient.java:291)\n\tat c= om.cloud.hypervisor.vmware.mo.VirtualMachineMO.createDisk(VirtualMachineMO.= java:944)\n\tat com.cloud.hypervisor.vmware.mo.VirtualMachineMO.createDisk(= VirtualMachineMO.java:867)\n\tat com.cloud.hypervisor.vmware.resource.Vmwar= eResource.execute(VmwareResource.java:3955)\n\t... 10 more\n","wait":0}}] } -- This message is automatically generated by JIRA. If you think it was sent incorrectly, please contact your JIRA administrato= rs For more information on JIRA, see: http://www.atlassian.com/software/jira