[CLOUDSTACK-6145] Unable to create instance with a template

• Type: Bug
• Status: Open
• Priority: Major
• Resolution: Unresolved
• Affects Version/s: 4.2.0
• Component/s: VMware
• Security Level: Public (Anyone can view this level - this is the default.)
• Environment: Cloudstack 4.2.0 ; Vmware ESXi 4.1
• Assignee: Unassigned
• Reporter: Irving de Vries
• Created: 20/Feb/14 08:00
• Updated: 24/Jun/14 17:53

Description

Message from cloudstack
Failed to deploy Vm with Id: 1320, on Host with Id: null
2014-02-20 08:18:08,100 INFO [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-3:job-37 = [ 64ff5536-ecdb-40b9-8b7f-14710d09aa71 ]) Unable to contact resource.
com.cloud.exception.StorageUnavailableException: Resource [StoragePool:9] is unreachable: Unable to create Vol[1321|vm=1321|ROOT]:Unable to copy template to primary storage due to exception:Exception: java.lang.IndexOutOfBoundsException
Message: Index: 0, Size: 0

at com.cloud.storage.VolumeManagerImpl.recreateVolume(VolumeManagerImpl.java:2544)
at com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.java:2592)
at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:889)
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:578)
at com.cloud.network.router.VirtualNetworkApplianceManagerImpl.start(VirtualNetworkApplianceManagerImpl.java:2740)
at com.cloud.network.router.VirtualNetworkApplianceManagerImpl.startVirtualRouter(VirtualNetworkApplianceManagerImpl.java:1872)
at com.cloud.network.router.VirtualNetworkApplianceManagerImpl.startRouters(VirtualNetworkApplianceManagerImpl.java:1972)
at com.cloud.network.router.VirtualNetworkApplianceManagerImpl.deployVirtualRouterInGuestNetwork(VirtualNetworkApplianceManagerImpl.java:1950)
at com.cloud.network.element.VirtualRouterElement.implement(VirtualRouterElement.java:188)
at com.cloud.network.NetworkManagerImpl.implementNetworkElementsAndResources(NetworkManagerImpl.java:2034)
at com.cloud.network.NetworkManagerImpl.implementNetwork(NetworkManagerImpl.java:1939)
at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at com.cloud.network.NetworkManagerImpl.prepare(NetworkManagerImpl.java:2120)
at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:887)
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:578)
at org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.deployVirtualMachine(VMEntityManagerImpl.java:227)
at org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.deploy(VirtualMachineEntityImpl.java:209)
at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3406)
at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2966)
at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2952)
at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at org.apache.cloudstack.api.command.user.vm.DeployVMCmd.execute(DeployVMCmd.java:420)
at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:158)
at com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:531)
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(ThreadPoolExecutor.java:1146)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:701)
2014-02-20 08:18:08,117 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-3:job-37 = [ 64ff5536-ecdb-40b9-8b7f-14710d09aa71 ]) Cleaning up resources for the vm VM[DomainRouter|r-1321-VM] in Starting state
2014-02-20 08:18:08,119 DEBUG [agent.transport.Request] (Job-Executor-3:job-37 = [ 64ff5536-ecdb-40b9-8b7f-14710d09aa71 ]) Seq 2-336532654: Sending { Cmd , MgmtId: 345051971925, via: 2, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"r-1321-VM","wait":0}}] }
2014-02-20 08:18:08,120 DEBUG [agent.transport.Request] (Job-Executor-3:job-37 = [ 64ff5536-ecdb-40b9-8b7f-14710d09aa71 ]) Seq 2-336532654: Executing: { Cmd , MgmtId: 345051971925, via: 2, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"r-1321-VM","wait":0}}] }
2014-02-20 08:18:08,120 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-421:null) Seq 2-336532654: Executing request
2014-02-20 08:18:08,120 INFO [vmware.resource.VmwareResource] (DirectAgent-421:ciman0201s.ciman.nl) Executing resource StopCommand:

{"isProxy":false,"executeInSequence":true,"vmName":"r-1321-VM","wait":0}

2014-02-20 08:18:08,121 DEBUG [vmware.mo.HostMO] (DirectAgent-421:ciman0201s.ciman.nl) find VM r-1321-VM on host
2014-02-20 08:18:08,121 INFO [vmware.mo.HostMO] (DirectAgent-421:ciman0201s.ciman.nl) VM r-1321-VM not found in host cache
2014-02-20 08:18:08,121 DEBUG [vmware.mo.HostMO] (DirectAgent-421:ciman0201s.ciman.nl) load VM cache on host
2014-02-20 08:18:08,140 INFO [vmware.resource.VmwareResource] (DirectAgent-421:ciman0201s.ciman.nl) VM r-1321-VM is no longer in vSphere
2014-02-20 08:18:08,141 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-421:null) Seq 2-336532654: Response Received:
2014-02-20 08:18:08,141 DEBUG [agent.transport.Request] (DirectAgent-421:null) Seq 2-336532654: Processing: { Ans: , MgmtId: 345051971925, via: 2, Ver: v1, Flags: 110, [{"com.cloud.agent.api.StopAnswer":{"vncPort":0,"result":true,"details":"VM r-1321-VM is no longer in vSphere","wait":0}}] }
2014-02-20 08:18:08,141 DEBUG [agent.manager.AgentAttache] (DirectAgent-421:null) Seq 2-336532654: No more commands found