cloudstack-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Sailaja Mada (JIRA)" <j...@apache.org>
Subject [jira] [Updated] (CLOUDSTACK-5016) Failed to reboot the VM which has VM Snapshots and Migrated Volumes
Date Thu, 31 Oct 2013 15:13:20 GMT

     [ https://issues.apache.org/jira/browse/CLOUDSTACK-5016?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]

Sailaja Mada updated CLOUDSTACK-5016:
-------------------------------------

    Attachment: logsall.rar

> 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
>             Fix For: 4.2.1
>
>         Attachments: logsall.rar, startvm.png
>
>
> 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)

Mime
View raw message