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 629CA109C1 for ; Tue, 17 Dec 2013 13:46:20 +0000 (UTC) Received: (qmail 60300 invoked by uid 500); 17 Dec 2013 13:46:17 -0000 Delivered-To: apmail-cloudstack-issues-archive@cloudstack.apache.org Received: (qmail 60144 invoked by uid 500); 17 Dec 2013 13:46:15 -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 60123 invoked by uid 500); 17 Dec 2013 13:46:14 -0000 Delivered-To: apmail-incubator-cloudstack-issues@incubator.apache.org Received: (qmail 60068 invoked by uid 99); 17 Dec 2013 13:46:13 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 17 Dec 2013 13:46:13 +0000 Date: Tue, 17 Dec 2013 13:46:13 +0000 (UTC) From: "Likitha Shetty (JIRA)" To: cloudstack-issues@incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Resolved] (CLOUDSTACK-4875) VMWARE: vCenter 5.5 - SYSTEM VM: Unable to create deployment for VM 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-4875?page=3Dcom.atl= assian.jira.plugin.system.issuetabpanels:all-tabpanel ] Likitha Shetty resolved CLOUDSTACK-4875. ---------------------------------------- Resolution: Fixed > VMWARE: vCenter 5.5 - SYSTEM VM: Unable to create deployment for VM > ------------------------------------------------------------------- > > Key: CLOUDSTACK-4875 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-487= 5 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the defa= ult.)=20 > Components: SystemVM, VMware > Affects Versions: 4.2.1 > Environment: Master with vCenter 5.5 / ESXi 5.5 > Reporter: Parth Jagirdar > Assignee: Likitha Shetty > Priority: Blocker > Fix For: 4.3.0 > > Attachments: VC5.5.jpg, catalina.out, management-server.log > > > Unable to launch system VM's > See attached logs > 2013-10-15 13:31:40,061 DEBUG [cloud.storage.VolumeManagerImpl] (consolep= roxy-1:null) Unable to create Vol[2|vm=3D2|ROOT]:java.lang.RuntimeException= : File [803eb6157bb83df98d96c4da27252fa8] ROOT-2/ROOT-2.vmdk was not found > 2013-10-15 13:31:40,061 INFO [cloud.vm.VirtualMachineManagerImpl] (conso= leproxy-1:null) Unable to contact resource. > com.cloud.exception.StorageUnavailableException: Resource [StoragePool:1]= is unreachable: Unable to create Vol[2|vm=3D2|ROOT]:java.lang.RuntimeExcep= tion: File [803eb6157bb83df98d96c4da27252fa8] ROOT-2/ROOT-2.vmdk was not fo= und > at com.cloud.storage.VolumeManagerImpl.recreateVolume(VolumeManag= erImpl.java:2566) > at com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.= java:2617) > at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMac= hineManagerImpl.java:889) > at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineMan= agerImpl.java:578) > at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineMan= agerImpl.java:571) > at com.cloud.consoleproxy.ConsoleProxyManagerImpl.startProxy(Cons= oleProxyManagerImpl.java:556) > at com.cloud.consoleproxy.ConsoleProxyManagerImpl.allocCapacity(C= onsoleProxyManagerImpl.java:928) > at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(Cons= oleProxyManagerImpl.java:1672) > at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(Cons= oleProxyManagerImpl.java:157) > at com.cloud.vm.SystemVmLoadScanner.loadScan(SystemVmLoadScanner.= java:111) > at com.cloud.vm.SystemVmLoadScanner.access$100(SystemVmLoadScanne= r.java:33) > at com.cloud.vm.SystemVmLoadScanner$1.reallyRun(SystemVmLoadScann= er.java:81) > at com.cloud.vm.SystemVmLoadScanner$1.run(SystemVmLoadScanner.jav= a:72) > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.= java:471) > at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTa= sk.java:351) > at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:17= 8) > at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutu= reTask.access$201(ScheduledThreadPoolExecutor.java:165) > at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutu= reTask.run(ScheduledThreadPoolExecutor.java:267) > 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-10-15 13:31:40,068 DEBUG [cloud.vm.VirtualMachineManagerImpl] (conso= leproxy-1:null) Cleaning up resources for the vm VM[ConsoleProxy|v-2-VM] in= Starting state > type TEMPLATE copyAsync inspecting dest type VOLUME > 2013-10-15 13:33:00,630 DEBUG [agent.transport.Request] (secstorage-1:nul= l) Seq 1-401276957: Waiting for Seq 401276956 Scheduling: { Cmd , MgmtId: = 15929225863658, via: 1, Ver: v1, Flags: 100111, [{"org.apache.cloudstack.st= orage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.Temp= lateObjectTO":{"path":"b8766e32c0c838539a8f75c2cc62a30b","origUrl":"http://= download.cloud.com/templates/4.2/systemvmtemplate-4.2-vh7.ova","uuid":"e1eb= 1bf2-35c3-11e3-a10c-0e7ccfd961ea","id":8,"format":"OVA","accountId":1,"chec= ksum":"8fde62b1089e5844a9cd3b9b953f9596","hvm":false,"displayText":"SystemV= M Template (vSphere)","imageDataStore":{"org.apache.cloudstack.storage.to.P= rimaryDataStoreTO":{"uuid":"803eb615-7bb8-3df9-8d96-c4da27252fa8","id":1,"p= oolType":"NetworkFilesystem","host":"10.223.110.232","path":"/export/home/p= arth/49.211/primary","port":2049}},"name":"routing-8","hypervisorType":"VMw= are"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":= "6d6ab2fd-6866-4dcb-bc2f-b52b65ccc104","volumeType":"ROOT","dataStore":{"or= g.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"803eb615-7bb8-3= df9-8d96-c4da27252fa8","id":1,"poolType":"NetworkFilesystem","host":"10.223= .110.232","path":"/export/home/parth/49.211/primary","port":2049}},"name":"= ROOT-4","size":0,"volumeId":4,"vmName":"s-4-VM","accountId":1,"format":"OVA= ","id":4,"hypervisorType":"VMware"}},"executeInSequence":true,"wait":0}}] } > 2013-10-15 13:33:02,064 ERROR [storage.resource.VmwareStorageProcessor] (= DirectAgent-30:10.223.248.194) clone volume from base image failed due to E= xception: java.lang.RuntimeException > Message: The name 'ROOT-2' already exists. > java.lang.RuntimeException: The name 'ROOT-2' already exists. > at com.cloud.hypervisor.vmware.util.VmwareClient.waitForTask(Vmwa= reClient.java:412) > at com.cloud.hypervisor.vmware.mo.VirtualMachineMO.createFullClon= e(VirtualMachineMO.java:602) > at com.cloud.storage.resource.VmwareStorageProcessor.createVMFull= Clone(VmwareStorageProcessor.java:296) > at com.cloud.storage.resource.VmwareStorageProcessor.cloneVolumeF= romBaseTemplate(VmwareStorageProcessor.java:387) > at com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.= execute(StorageSubsystemCommandHandlerBase.java:73) > at com.cloud.storage.resource.VmwareStorageSubsystemCommandHandle= r.execute(VmwareStorageSubsystemCommandHandler.java:155) > at com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.= handleStorageCommands(StorageSubsystemCommandHandlerBase.java:49) > at com.cloud.hypervisor.vmware.resource.VmwareResource.executeReq= uest(VmwareResource.java:559) > 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-10-15 13:33:02,065 DEBUG [agent.manager.DirectAgentAttache] (DirectA= gent-30:null) Seq 1-401276956: Response Received: > 2013-10-15 13:33:02,065 DEBUG [agent.transport.Request] (DirectAgent-30:n= ull) Seq 1-401276956: Processing: { Ans: , MgmtId: 15929225863658, via: 1,= Ver: v1, Flags: 110, [{"org.apache.cloudstack.storage.command.CopyCmdAnswe= r":{"result":false,"details":"java.lang.RuntimeException: The name 'ROOT-2'= already exists.","wait":0}}] } > 2013-10-15 13:33:02,065 DEBUG [agent.transport.Request] (consoleproxy-1:n= ull) Seq 1-401276956: Received: { Ans: , MgmtId: 15929225863658, via: 1, V= er: v1, Flags: 110, { CopyCmdAnswer } } > 2013-10-15 13:33:02,068 DEBUG [agent.manager.AgentAttache] (DirectAgent-3= 0:null) Seq 1-401276957: Sending now. is current sequence. > 2013-10-15 13:33:02,074 WARN [storage.datastore.ObjectInDataStoreManager= Impl] (consoleproxy-1:null) Unsupported data object (VOLUME, org.apache.clo= udstack.storage.datastore.PrimaryDataStoreImpl@4198553e), no need to delete= from object in store ref table > 2013-10-15 13:33:02,074 DEBUG [cloud.storage.VolumeManagerImpl] (consolep= roxy-1:null) Unable to create Vol[2|vm=3D2|ROOT]:java.lang.RuntimeException= : The name 'ROOT-2' already exists. > 2013-10-15 13:33:02,074 INFO [cloud.vm.VirtualMachineManagerImpl] (conso= leproxy-1:null) Unable to contact resource. > com.cloud.exception.StorageUnavailableException: Resource [StoragePool:1]= is unreachable: Unable to create Vol[2|vm=3D2|ROOT]:java.lang.RuntimeExcep= tion: The name 'ROOT-2' already exists. > at com.cloud.storage.VolumeManagerImpl.recreateVolume(VolumeManag= erImpl.java:2566) > at com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.= java:2617) > at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMac= hineManagerImpl.java:889) > at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineMan= agerImpl.java:578) > at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineMan= agerImpl.java:571) > at com.cloud.consoleproxy.ConsoleProxyManagerImpl.startProxy(Cons= oleProxyManagerImpl.java:556) > at com.cloud.consoleproxy.ConsoleProxyManagerImpl.allocCapacity(C= onsoleProxyManagerImpl.java:928) > at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(Cons= oleProxyManagerImpl.java:1672) > at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(Cons= oleProxyManagerImpl.java:157) > at com.cloud.vm.SystemVmLoadScanner.loadScan(SystemVmLoadScanner.= java:111) > at com.cloud.vm.SystemVmLoadScanner.access$100(SystemVmLoadScanne= r.java:33) > at com.cloud.vm.SystemVmLoadScanner$1.reallyRun(SystemVmLoadScann= er.java:81) > at com.cloud.vm.SystemVmLoadScanner$1.run(SystemVmLoadScanner.jav= a:72) > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.= java:471) > at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTa= sk.java:351) > at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:17= 8) > at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutu= reTask.access$201(ScheduledThreadPoolExecutor.java:165) > at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutu= reTask.run(ScheduledThreadPoolExecutor.java:267) > 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-10-15 13:33:02,075 DEBUG [agent.transport.Request] (DirectAgent-30:n= ull) Seq 1-401276957: Executing: { Cmd , MgmtId: 15929225863658, via: 1, V= er: v1, Flags: 100111, [{"org.apache.cloudstack.storage.command.CopyCommand= ":{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"b8= 766e32c0c838539a8f75c2cc62a30b","origUrl":"http://download.cloud.com/templa= tes/4.2/systemvmtemplate-4.2-vh7.ova","uuid":"e1eb1bf2-35c3-11e3-a10c-0e7cc= fd961ea","id":8,"format":"OVA","accountId":1,"checksum":"8fde62b1089e5844a9= cd3b9b953f9596","hvm":false,"displayText":"SystemVM Template (vSphere)","im= ageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid= ":"803eb615-7bb8-3df9-8d96-c4da27252fa8","id":1,"poolType":"NetworkFilesyst= em","host":"10.223.110.232","path":"/export/home/parth/49.211/primary","por= t":2049}},"name":"routing-8","hypervisorType":"VMware"}},"destTO":{"org.apa= che.cloudstack.storage.to.VolumeObjectTO":{"uuid":"6d6ab2fd-6866-4dcb-bc2f-= b52b65ccc104","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.stora= ge.to.PrimaryDataStoreTO":{"uuid":"803eb615-7bb8-3df9-8d96-c4da27252fa8","i= d":1,"poolType":"NetworkFilesystem","host":"10.223.110.232","path":"/export= /home/parth/49.211/primary","port":2049}},"name":"ROOT-4","size":0,"volumeI= d":4,"vmName":"s-4-VM","accountId":1,"format":"OVA","id":4,"hypervisorType"= :"VMware"}},"executeInSequence":true,"wait":0}}] } > 2013-10-15 13:33:02,077 DEBUG [agent.manager.DirectAgentAttache] (DirectA= gent-6:null) Seq 1-401276957: Executing request > 2013-10-15 13:33:02,081 DEBUG [cloud.vm.VirtualMachineManagerImpl] (conso= leproxy-1:null) Cleaning up resources for the vm VM[ConsoleProxy|v-2-VM] in= Starting state > 2013-10-15 13:33:02,084 DEBUG [agent.transport.Request] (consoleproxy-1:n= ull) Seq 1-401276958: Waiting for Seq 401276957 Scheduling: { Cmd , MgmtId= : 15929225863658, via: 1, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.St= opCommand":{"isProxy":false,"executeInSequence":true,"vmName":"v-2-VM","wai= t":0}}] } > 2013-10-15 13:33:02,308 INFO [vmware.mo.DatastoreMO] (DirectAgent-6:10.2= 23.248.194) Fo -- This message was sent by Atlassian JIRA (v6.1.4#6159)