[CLOUDSTACK-5669] [Automation] Destroy VM failed with error "Unable to delete vm snapshots for VM"

• Type: Bug
• Status: Closed
• Priority: Blocker
• Resolution: Fixed
• Affects Version/s: 4.3.0
• Fix Version/s: 4.3.0
• Component/s: Management Server
• Security Level: Public (Anyone can view this level - this is the default.)
• Environment: KVM (rhel 6.3); Branch 4.3
• Assignee: Kelven Yang
• Reporter: Rayees Namathponnan
• Created: 29/Dec/13 06:31
• Updated: 04/Jan/14 16:20
• Resolved: 31/Dec/13 23:51

Description

Steps to reproduce this issue
Step 1 : Create advanced zone in KVM
Step 2 : Deploy a vm
Step 3 : Destroy the VM

Actual Result

Destroy vm failed with below error

2013-12-28 22:27:25,773 DEBUG [c.c.v.s.VMSnapshotManagerImpl] (Job-Executor-72:ctx-7f27a041 ctx-0e39c47b) Execute Delete-All-VM-Snapshot within VM work job context. vmId: 685
2013-12-28 22:27:25,773 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Executor-72:ctx-7f27a041 ctx-0e39c47b) Complete async job-3495, jobStatus: SUCCEEDED, resultCode: 0, result: rO0ABXNyABFqYXZhLmxhbmcuQm9vbGVhbs0gcoDVnPruAgABWgAFdmFsdWV4cAE
2013-12-28 22:27:25,782 INFO [o.a.c.f.j.i.AsyncJobMonitor] (Job-Executor-132:ctx-cc2e347e) Add job-3494 into job monitoring
2013-12-28 22:27:25,782 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Executor-132:ctx-cc2e347e) Executing AsyncJobVO {id:3494, userId: 2, accountId: 2, instanceType: VirtualMachine, instanceId: 685, cmd: org.apache.cloudstack.api.command.user.vm.DestroyVMCmd, cmdInfo:

{"id":"751ac91a-9a63-4d75-9700-70e269b539a4","response":"json","sessionkey":"rpAfwAtIsmOkK0hrRyHNw+XC6Dc\u003d","cmdEventType":"VM.DESTROY","ctxUserId":"2","httpmethod":"GET","_":"1388298410496","ctxAccountId":"2","ctxStartEventId":"13788"}

, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 29066118877352, completeMsid: null, lastUpdated: null, lastPolled: Sat Dec 28 22:27:23 PST 2013, created: Sat Dec 28 22:26:50 PST 2013}
2013-12-28 22:27:25,785 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Executor-72:ctx-7f27a041) Done executing com.cloud.vm.snapshot.VmWorkDeleteAllVMSnapshots for job-3495
2013-12-28 22:27:25,788 DEBUG [c.c.v.VirtualMachineManagerImpl] (Job-Executor-151:ctx-7f1c4523 ctx-0e39c47b) Unable to delete all snapshots for VM[User|ryzvm2]
2013-12-28 22:27:25,792 ERROR [c.c.a.ApiAsyncJobDispatcher] (Job-Executor-151:ctx-7f1c4523) Unexpected exception while executing org.apache.cloudstack.api.command.user.vm.DestroyVMCmd
com.cloud.utils.exception.CloudRuntimeException: Unable to delete vm snapshots for VM[User|ryzvm2]
at com.cloud.vm.VirtualMachineManagerImpl.destroy(VirtualMachineManagerImpl.java:1527)
at org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.destroyVirtualMachine(VMEntityManagerImpl.java:256)
at org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.destroy(VirtualMachineEntityImpl.java:223)
at com.cloud.vm.UserVmManagerImpl.destroyVm(UserVmManagerImpl.java:3616)
at com.cloud.vm.UserVmManagerImpl.destroyVm(UserVmManagerImpl.java:2085)
at sun.reflect.GeneratedMethodAccessor747.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:616)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at com.cloud.event.ActionEventInterceptor.invoke(ActionEventInterceptor.java:50)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
at $Proxy169.destroyVm(Unknown Source)
at org.apache.cloudstack.api.command.user.vm.DestroyVMCmd.execute(DestroyVMCmd.java:112)
at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:161)
at com.cloud.api.ApiAsyncJobDispatcher.runJobInContext(ApiAsyncJobDispatcher.java:109)
at com.cloud.api.ApiAsyncJobDispatcher$1.run(ApiAsyncJobDispatcher.java:66)
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
at com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:63)
at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:522)
at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
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-12-28 22:27:25,796 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Executor-151:ctx-7f1c4523) Complete async job-3494, jobStatus: FAILED, resultCode: 530, result: org.apache.cloudstack.api.response.ExceptionResponse/null/

{"uuidList":[],"errorcode":530,"errortext":"Unable to delete vm snapshots for VM[User|ryzvm2]"}

2013-12-28 22:27:25,806 WARN [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Executor-132:ctx-cc2e347e) job-3494 is scheduled for wakeup run, but there is no joining info anymore
2013-12-28 22:27:25,806 ERROR [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Executor-132:ctx-cc2e347e) Unable to find a wakeup dispatcher from the joined job: AsyncJobVO {id:3494, userId: 2, accountId: 2, instanceType: VirtualMachine, instanceId: 685, cmd: org.apache.cloudstack.api.command.user.vm.DestroyVMCmd, cmdInfo:

{"id":"751ac91a-9a63-4d75-9700-70e269b539a4","response":"json","sessionkey":"rpAfwAtIsmOkK0hrRyHNw+XC6Dc\u003d","cmdEventType":"VM.DESTROY","ctxUserId":"2","httpmethod":"GET","_":"1388298410496","ctxAccountId":"2","ctxStartEventId":"13788"}

, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 29066118877352, completeMsid: null, lastUpdated: null, lastPolled: Sat Dec 28 22:27:23 PST 2013, created: Sat Dec 28 22:26:50 PST 2013}

Issue Links

• blocks: CLOUDSTACK-5633 [Automation]cleanup failed due to network deletion failed