[
https://issues.apache.org/jira/browse/CLOUDSTACK-4875?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13850453#comment-13850453
]
ASF subversion and git services commented on CLOUDSTACK-4875:
-------------------------------------------------------------
Commit 11ef692acd8c6bc291a06e5c141e9b3bc1bb976a in branch refs/heads/4.3 from
[~likithas]
[ https://git-wip-us.apache.org/repos/asf?p=cloudstack.git;h=11ef692 ]
CLOUDSTACK-4875. Vmware vCenter 5.5 - System VM deployment fails.
During VM deployment when base template is being cloned to create VM ROOT disk,
get the disk path
i.e. base file name of the VM's ROOT disk from vCenter
> VMWARE: vCenter 5.5 - SYSTEM VM: Unable to create deployment for VM
> -------------------------------------------------------------------
>
> Key: CLOUDSTACK-4875
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-4875
> Project: CloudStack
> Issue Type: Bug
> Security Level: Public(Anyone can view this level - this is the
> default.)
> 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]
> (consoleproxy-1:null) Unable to create
> Vol[2|vm=2|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]
> (consoleproxy-1:null) Unable to contact resource.
> com.cloud.exception.StorageUnavailableException: Resource [StoragePool:1] is
> unreachable: Unable to create Vol[2|vm=2|ROOT]:java.lang.RuntimeException:
> File [803eb6157bb83df98d96c4da27252fa8] ROOT-2/ROOT-2.vmdk was not found
> at
> com.cloud.storage.VolumeManagerImpl.recreateVolume(VolumeManagerImpl.java:2566)
> at
> com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.java:2617)
> at
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:889)
> at
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:578)
> at
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:571)
> at
> com.cloud.consoleproxy.ConsoleProxyManagerImpl.startProxy(ConsoleProxyManagerImpl.java:556)
> at
> com.cloud.consoleproxy.ConsoleProxyManagerImpl.allocCapacity(ConsoleProxyManagerImpl.java:928)
> at
> com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:1672)
> at
> com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:157)
> at
> com.cloud.vm.SystemVmLoadScanner.loadScan(SystemVmLoadScanner.java:111)
> at
> com.cloud.vm.SystemVmLoadScanner.access$100(SystemVmLoadScanner.java:33)
> at
> com.cloud.vm.SystemVmLoadScanner$1.reallyRun(SystemVmLoadScanner.java:81)
> at com.cloud.vm.SystemVmLoadScanner$1.run(SystemVmLoadScanner.java:72)
> at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> at
> java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:351)
> at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:178)
> at
> java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:165)
> at
> java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:267)
> at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
> at java.lang.Thread.run(Thread.java:679)
> 2013-10-15 13:31:40,068 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (consoleproxy-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:null)
> Seq 1-401276957: Waiting for Seq 401276956 Scheduling: { Cmd , MgmtId:
> 15929225863658, via: 1, Ver: v1, Flags: 100111,
> [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"b8766e32c0c838539a8f75c2cc62a30b","origUrl":"http://download.cloud.com/templates/4.2/systemvmtemplate-4.2-vh7.ova","uuid":"e1eb1bf2-35c3-11e3-a10c-0e7ccfd961ea","id":8,"format":"OVA","accountId":1,"checksum":"8fde62b1089e5844a9cd3b9b953f9596","hvm":false,"displayText":"SystemVM
> Template
> (vSphere)","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"803eb615-7bb8-3df9-8d96-c4da27252fa8","id":1,"poolType":"NetworkFilesystem","host":"10.223.110.232","path":"/export/home/parth/49.211/primary","port":2049}},"name":"routing-8","hypervisorType":"VMware"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"6d6ab2fd-6866-4dcb-bc2f-b52b65ccc104","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"803eb615-7bb8-3df9-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
> Exception: 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(VmwareClient.java:412)
> at
> com.cloud.hypervisor.vmware.mo.VirtualMachineMO.createFullClone(VirtualMachineMO.java:602)
> at
> com.cloud.storage.resource.VmwareStorageProcessor.createVMFullClone(VmwareStorageProcessor.java:296)
> at
> com.cloud.storage.resource.VmwareStorageProcessor.cloneVolumeFromBaseTemplate(VmwareStorageProcessor.java:387)
> at
> com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.execute(StorageSubsystemCommandHandlerBase.java:73)
> at
> com.cloud.storage.resource.VmwareStorageSubsystemCommandHandler.execute(VmwareStorageSubsystemCommandHandler.java:155)
> at
> com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.handleStorageCommands(StorageSubsystemCommandHandlerBase.java:49)
> at
> com.cloud.hypervisor.vmware.resource.VmwareResource.executeRequest(VmwareResource.java:559)
> at
> com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgentAttache.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$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:165)
> at
> java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:266)
> at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
> at java.lang.Thread.run(Thread.java:679)
> 2013-10-15 13:33:02,065 DEBUG [agent.manager.DirectAgentAttache]
> (DirectAgent-30:null) Seq 1-401276956: Response Received:
> 2013-10-15 13:33:02,065 DEBUG [agent.transport.Request] (DirectAgent-30:null)
> Seq 1-401276956: Processing: { Ans: , MgmtId: 15929225863658, via: 1, Ver:
> v1, Flags: 110,
> [{"org.apache.cloudstack.storage.command.CopyCmdAnswer":{"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:null)
> Seq 1-401276956: Received: { Ans: , MgmtId: 15929225863658, via: 1, Ver: v1,
> Flags: 110, { CopyCmdAnswer } }
> 2013-10-15 13:33:02,068 DEBUG [agent.manager.AgentAttache]
> (DirectAgent-30:null) Seq 1-401276957: Sending now. is current sequence.
> 2013-10-15 13:33:02,074 WARN
> [storage.datastore.ObjectInDataStoreManagerImpl] (consoleproxy-1:null)
> Unsupported data object (VOLUME,
> org.apache.cloudstack.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]
> (consoleproxy-1:null) Unable to create
> Vol[2|vm=2|ROOT]:java.lang.RuntimeException: The name 'ROOT-2' already exists.
> 2013-10-15 13:33:02,074 INFO [cloud.vm.VirtualMachineManagerImpl]
> (consoleproxy-1:null) Unable to contact resource.
> com.cloud.exception.StorageUnavailableException: Resource [StoragePool:1] is
> unreachable: Unable to create Vol[2|vm=2|ROOT]:java.lang.RuntimeException:
> The name 'ROOT-2' already exists.
> at
> com.cloud.storage.VolumeManagerImpl.recreateVolume(VolumeManagerImpl.java:2566)
> at
> com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.java:2617)
> at
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:889)
> at
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:578)
> at
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:571)
> at
> com.cloud.consoleproxy.ConsoleProxyManagerImpl.startProxy(ConsoleProxyManagerImpl.java:556)
> at
> com.cloud.consoleproxy.ConsoleProxyManagerImpl.allocCapacity(ConsoleProxyManagerImpl.java:928)
> at
> com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:1672)
> at
> com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:157)
> at
> com.cloud.vm.SystemVmLoadScanner.loadScan(SystemVmLoadScanner.java:111)
> at
> com.cloud.vm.SystemVmLoadScanner.access$100(SystemVmLoadScanner.java:33)
> at
> com.cloud.vm.SystemVmLoadScanner$1.reallyRun(SystemVmLoadScanner.java:81)
> at com.cloud.vm.SystemVmLoadScanner$1.run(SystemVmLoadScanner.java:72)
> at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> at
> java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:351)
> at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:178)
> at
> java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:165)
> at
> java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:267)
> at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
> at java.lang.Thread.run(Thread.java:679)
> 2013-10-15 13:33:02,075 DEBUG [agent.transport.Request] (DirectAgent-30:null)
> Seq 1-401276957: Executing: { Cmd , MgmtId: 15929225863658, via: 1, Ver: v1,
> Flags: 100111,
> [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"b8766e32c0c838539a8f75c2cc62a30b","origUrl":"http://download.cloud.com/templates/4.2/systemvmtemplate-4.2-vh7.ova","uuid":"e1eb1bf2-35c3-11e3-a10c-0e7ccfd961ea","id":8,"format":"OVA","accountId":1,"checksum":"8fde62b1089e5844a9cd3b9b953f9596","hvm":false,"displayText":"SystemVM
> Template
> (vSphere)","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"803eb615-7bb8-3df9-8d96-c4da27252fa8","id":1,"poolType":"NetworkFilesystem","host":"10.223.110.232","path":"/export/home/parth/49.211/primary","port":2049}},"name":"routing-8","hypervisorType":"VMware"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"6d6ab2fd-6866-4dcb-bc2f-b52b65ccc104","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"803eb615-7bb8-3df9-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,077 DEBUG [agent.manager.DirectAgentAttache]
> (DirectAgent-6:null) Seq 1-401276957: Executing request
> 2013-10-15 13:33:02,081 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (consoleproxy-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:null)
> Seq 1-401276958: Waiting for Seq 401276957 Scheduling: { Cmd , MgmtId:
> 15929225863658, via: 1, Ver: v1, Flags: 100111,
> [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"v-2-VM","wait":0}}]
> }
> 2013-10-15 13:33:02,308 INFO [vmware.mo.DatastoreMO]
> (DirectAgent-6:10.223.248.194) Fo
--
This message was sent by Atlassian JIRA
(v6.1.4#6159)