You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@cloudstack.apache.org by "Sailaja Mada (JIRA)" <ji...@apache.org> on 2013/10/31 16:09:23 UTC
[jira] [Created] (CLOUDSTACK-5016) Failed to reboot the VM which
has VM Snapshots and Migrated Volumes
Sailaja Mada created CLOUDSTACK-5016:
----------------------------------------
Summary: Failed to reboot the VM which has VM Snapshots and Migrated Volumes
Key: CLOUDSTACK-5016
URL: https://issues.apache.org/jira/browse/CLOUDSTACK-5016
Project: CloudStack
Issue Type: Bug
Security Level: Public (Anyone can view this level - this is the default.)
Components: Storage Controller, VMware
Affects Versions: 4.2.1
Reporter: Sailaja Mada
Priority: Critical
Steps:
1. Configure Adv Zone with 2 zone wide primary storages using VMWARE 5.0 update2 Hypervisor
2. Deploy VM using user account
3. Create 2 VMsnapshots wo memory and 1 VM snapshot with Memory
4. Revert to VM Snap2 then to VM Snap1
5. Stop the VM and Migrate the Volume to second Primary Storage
7. Start the VM - It got started.
8. Tried to reboot the VM.
Observation:
It failed to start the VM .
2013-10-31 20:17:28,495 WARN [storage.resource.VmwareStorageLayoutHelper] (DirectAgent-289:10.102.192.18) Unable to locate VMDK file: a933eb3fa28a473ab5e28b99f5f2607e-delta.vmdk
2013-10-31 20:17:28,496 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-289:null) Seq 9-894174187: Response Received:
2013-10-31 20:17:28,497 DEBUG [agent.transport.Request] (DirectAgent-289:null) Seq 9-894174187: Processing: { Ans: , MgmtId: 94838926819810, via: 9, Ver: v1, Flags: 10, [{"com.cloud.agent.api.Answer":{"result":true,"details":"Success","wait":0}}] }
2013-10-31 20:17:28,497 DEBUG [agent.transport.Request] (Job-Executor-5:job-143 = [ 57e484cb-c0aa-47c8-b991-1aa7aed9b038 ]) Seq 9-894174187: Received: { Ans: , MgmtId: 94838926819810, via: 9, Ver: v1, Flags: 10, { Answer } }
2013-10-31 20:17:28,507 INFO [storage.volume.VolumeServiceImpl] (Job-Executor-5:job-143 = [ 57e484cb-c0aa-47c8-b991-1aa7aed9b038 ]) Volume 39 is not referred anywhere, remove it from volumes table
2013-10-31 20:17:28,513 ERROR [cloud.storage.VolumeManagerImpl] (Job-Executor-5:job-143 = [ 57e484cb-c0aa-47c8-b991-1aa7aed9b038 ]) migrate volume failed:copy volume from primary to secondary failed due to exception: Exception: java.lang.RuntimeException
Message: File [7f18caf5397a340a934ed37c558aee2b] i-5-24-VM/i-5-24-VM.vmx was not found
2013-10-31 20:17:28,519 INFO [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-5:job-143 = [ 57e484cb-c0aa-47c8-b991-1aa7aed9b038 ]) Unable to contact resource.
com.cloud.exception.StorageUnavailableException: Resource [StoragePool:5] is unreachable: migrate volume failed: copy volume from primary to secondary failed due to exception: Exception: java.lang.RuntimeException
Message: File [7f18caf5397a340a934ed37c558aee2b] i-5-24-VM/i-5-24-VM.vmx was not found
2013-10-31 20:17:28,519 INFO [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-5:job-143 = [ 57e484cb-c0aa-47c8-b991-1aa7aed9b038 ]) Unable to contact resource.
com.cloud.exception.StorageUnavailableException: Resource [StoragePool:5] is unreachable: migrate volume failed: copy volume from primary to secondary failed due to exception: Exception: java.lang.RuntimeException
Message: File [7f18caf5397a340a934ed37c558aee2b] i-5-24-VM/i-5-24-VM.vmx was not found
at com.cloud.storage.VolumeManagerImpl.migrateVolume(VolumeManagerImpl.java:2278)
at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.java:2629)
at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:888)
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:577)
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:570)
at com.cloud.vm.UserVmManagerImpl.restoreVMInternal(UserVmManagerImpl.java:4930)
at com.cloud.vm.UserVmManagerImpl.rebootVirtualMachine(UserVmManagerImpl.java:1971)
at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at org.apache.cloudstack.api.command.user.vm.RebootVMCmd.execute(RebootVMCmd.java:99)
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:1110)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:679)
2013-10-31 20:17:28,547 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-5:job-143 = [ 57e484cb-c0aa-47c8-b991-1aa7aed9b038 ]) Cleaning up resources for the vm VM[User|sailajaVM1] in Starting state
2013-10-31 20:17:28,549 DEBUG [agent.transport.Request] (Job-Executor-5:job-143 = [ 57e484cb-c0aa-47c8-b991-1aa7aed9b038 ]) Seq 9-894174188: Sending { Cmd , MgmtId: 94838926819810, via: 9, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"i-5-24-VM","wait":0}}] }
2013-10-31 20:17:28,549 DEBUG [agent.transport.Request] (Job-Executor-5:job-143 = [ 57e484cb-c0aa-47c8-b991-1aa7aed9b038 ]) Seq 9-894174188: Executing: { Cmd , MgmtId: 94838926819810, via: 9, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"i-5-24-VM","wait":0}}] }
2013-10-31 20:17:28,853 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-5:job-143 = [ 57e484cb-c0aa-47c8-b991-1aa7aed9b038 ]) release mem from host: 9, old used: 2013265920,reserved: 0, total: 17166258176; new used: 1476395008,reserved:0; movedfromreserved: false,moveToReserveredfalse
2013-10-31 20:17:28,881 DEBUG [cloud.vm.UserVmManagerImpl] (Job-Executor-5:job-143 = [ 57e484cb-c0aa-47c8-b991-1aa7aed9b038 ]) Unable to start VM 1ec83c40-e0dc-48ad-90d8-f4000c0dfe91
com.cloud.exception.InsufficientServerCapacityException: Unable to create a deployment for VM[User|sailajaVM1]Scope=interface com.cloud.dc.DataCenter; id=4
at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:841)
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:577)
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:570)
at com.cloud.vm.UserVmManagerImpl.restoreVMInternal(UserVmManagerImpl.java:4930)
at com.cloud.vm.UserVmManagerImpl.rebootVirtualMachine(UserVmManagerImpl.java:1971)
at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at org.apache.cloudstack.api.command.user.vm.RebootVMCmd.execute(RebootVMCmd.java:99)
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:1110)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:679)
2013-10-31 20:17:28,889 ERROR [cloud.async.AsyncJobManagerImpl] (Job-Executor-5:job-143 = [ 57e484cb-c0aa-47c8-b991-1aa7aed9b038 ]) Unexpected exception while executing org.apache.cloudstack.api.command.user.vm.RebootVMCmd
com.cloud.utils.exception.CloudRuntimeException: Unable to start VM with specified idUnable to create a deployment for VM[User|sailajaVM1]
at com.cloud.vm.UserVmManagerImpl.restoreVMInternal(UserVmManagerImpl.java:4933)
at com.cloud.vm.UserVmManagerImpl.rebootVirtualMachine(UserVmManagerImpl.java:1971)
at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at org.apache.cloudstack.api.command.user.vm.RebootVMCmd.execute(RebootVMCmd.java:99)
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:1110)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:679)
2013-10-31 20:17:28,891 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-5:job-143 = [ 57e484cb-c0aa-47c8-b991-1aa7aed9b038 ]) Complete async job-143 = [ 57e484cb-c0aa-47c8-b991-1aa7aed9b038 ], jobStatus: 2, resultCode: 530, result: Error Code: 530 Error text: Unable to start VM with specified idUnable to create a deployment for VM[User|sailajaVM1]
2013-10-31 20:17:30,404 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===START=== 10.104.255.45 -- GET command=queryAsyncJobResult&jobId=57e484cb-c0aa-47c8-b991-1aa7aed9b038&response=json&sessionkey=CHP8%2Bg6QICbqOnSaFI0Tkhq5ngs%3D&_=1383231183604
2013-10-31 20:17:30,417 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-2:null) Async job-143 = [ 57e484cb-c0aa-47c8-b991-1aa7aed9b038 ] completed
2013-10-31 20:17:28,549 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-228:null) Seq 9-894174188: Executing request
2013-10-31 20:17:28,549 INFO [vmware.resource.VmwareResource] (DirectAgent-228:10.102.192.18) Executing resource StopCommand: {"isProxy":false,"executeInSequence":true,"vmName":"i-5-24-VM","wait":0}
--
This message was sent by Atlassian JIRA
(v6.1#6144)