Details
Critical Description
If we try to reset a VM that has VM snapshots we get -
14-07-14 12:29:57,402 DEBUG [c.c.a.t.Request] (StatsCollector-3:ctx-e5232a43) Seq 1-1802268263: Received: { Ans: , MgmtId: 20620141786272, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-07-14 12:29:57,643 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-1:null) SeqA 12-189267: Processing Seq 12-189267: { Cmd , MgmtId: -1, via: 12, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":76,"_loadInfo":"{\n \"connections\": []\n}","wait":0}}] }
2014-07-14 12:29:57,651 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-1:null) SeqA 12-189267: Sending Seq 12-189267: { Ans: , MgmtId: 20620141786272, via: 12, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
2014-07-14 12:29:57,703 DEBUG [c.c.a.ApiServlet] (catalina-exec-9:ctx-cdf8ae2a) ===START=== 10.103.29.20 -- GET command=queryAsyncJobResult&jobId=cce9e2ca-9800-400c-9365-efd7465a2a2f&response=json&sessionkey=SUS3X0KmlHMIe98nfAd1pi2r4sw%3D&_=1405321596769
2014-07-14 12:29:57,725 DEBUG [c.c.a.ApiServlet] (catalina-exec-9:ctx-cdf8ae2a ctx-5ef4ce7b) ===END=== 10.103.29.20 -- GET command=queryAsyncJobResult&jobId=cce9e2ca-9800-400c-9365-efd7465a2a2f&response=json&sessionkey=SUS3X0KmlHMIe98nfAd1pi2r4sw%3D&_=1405321596769
2014-07-14 12:29:58,595 DEBUG [c.c.c.ConsoleProxyManagerImpl] (consoleproxy-1:ctx-a63c77bf) Zone 1 is ready to launch console proxy
2014-07-14 12:29:58,628 DEBUG [c.c.s.s.SecondaryStorageManagerImpl] (secstorage-1:ctx-46f24bb3) Zone 1 is ready to launch secondary storage VM
2014-07-14 12:29:58,890 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-3:null) Ping from 12
2014-07-14 12:29:59,698 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-8:null) Ping from 11
2014-07-14 12:30:00,224 DEBUG [c.c.s.StatsCollector] (StatsCollector-1:ctx-ca62e80b) HostStatsCollector is running...
2014-07-14 12:30:00,229 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-53:ctx-dfca1177) Seq 1-1802268264: Executing request
2014-07-14 12:30:00,258 WARN [c.c.h.v.r.VmwareResource] (DirectAgent-77:ctx-a645727a 172.16.12.20) StartCommand failed due to Exception: java.lang.RuntimeException
Message: File [datastore1] VM-a6cbbb37-66e8-4bee-80bf-fcdd217c6b01/ROOT-87-000001.vmdk was not found
java.lang.RuntimeException: File [datastore1] VM-a6cbbb37-66e8-4bee-80bf-fcdd217c6b01/ROOT-87-000001.vmdk was not found
at com.cloud.hypervisor.vmware.util.VmwareClient.waitForTask(VmwareClient.java:336)
at com.cloud.hypervisor.vmware.mo.VirtualMachineMO.powerOn(VirtualMachineMO.java:259)
at com.cloud.hypervisor.vmware.resource.VmwareResource.execute(VmwareResource.java:3162)
at com.cloud.hypervisor.vmware.resource.VmwareResource.executeRequest(VmwareResource.java:578)
at com.cloud.agent.manager.DirectAgentAttache$Task.runInContext(DirectAgentAttache.java:215)
at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:50)
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:47)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:178)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:292)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
2014-07-14 12:30:00,261 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-77:ctx-a645727a) Seq 4-164890826: Response Received:
2014-07-14 12:30:00,265 DEBUG [c.c.a.t.Request] (DirectAgent-77:ctx-a645727a) Seq 4-164890826: Processing: { Ans: , MgmtId: 20620141786272, via: 4, Ver: v1, Flags: 10, [{"com.cloud.agent.api.StartAnswer":{"vm":{"id":87,"name":"i-2-87-VM","bootloader":"HVM","type":"User","cpus":1,"minSpeed":500,"maxSpeed":500,"minRam":536870912,"maxRam":536870912,"hostName":"VM-a6cbbb37-66e8-4bee-80bf-fcdd217c6b01","arch":"x86_64","os":"CentOS 5.3 (64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"c84c9a32be661e62","params":{"Message.ReservedCapacityFreed.Flag":"false","dataDiskController":"osdefault","nicAdapter":"E1000","vmware.reserve.cpu":"false","nestedVirtualizationFlag":"false","rootDiskController":"ide","vmware.reserve.mem":"false"},"uuid":"a6cbbb37-66e8-4bee-80bf-fcdd217c6b01","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"53492d58-ebb4-4987-a4f4-8e7420fbc0ce","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"435a29b7-646f-395d-bd9f-110ff57883bd","id":5,"poolType":"NetworkFilesystem","host":"10.104.93.6","path":"/Aggr0/Vol0/Kiran/kpvf","port":2049,"url":"NetworkFilesystem://10.104.93.6//Aggr0/Vol0/Kiran/kpvf/?ROLE=Primary&STOREUUID=435a29b7-646f-395d-bd9f-110ff57883bd"}},"name":"ROOT-87","size":2147483648,"path":"ROOT-87-000001","volumeId":108,"vmName":"i-2-87-VM","accountId":2,"chainInfo":"{\"diskDeviceBusName\":\"ide0:1\",\"diskChain\":[\"[datastore1] VM-a6cbbb37-66e8-4bee-80bf-fcdd217c6b01/ROOT-87-000001.vmdk\",\"[datastore1] VM-a6cbbb37-66e8-4bee-80bf-fcdd217c6b01/ROOT-87.vmdk\",\"[datastore1] 884ab036188e3901bcc3185f506db80f/884ab036188e3901bcc3185f506db80f.vmdk\"]}","format":"OVA","id":108,"deviceId":0,"hypervisorType":"VMware"}},"diskSeq":0,"path":"ROOT-87","type":"ROOT","_details":{"managed":"false","storagePort":"2049","storageHost":"10.104.93.6","volumeSize":"2147483648"}},{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"id":0,"format":"ISO","accountId":0,"hvm":false}},"diskSeq":3,"type":"ISO"}],"nics":[{"deviceId":0,"networkRateMbps":200,"defaultNic":true,"uuid":"7b282991-0b8e-46a7-a5de-d4efb817adbf","ip":"10.1.1.232","netmask":"255.255.255.0","gateway":"10.1.1.1","mac":"02:00:3e:4e:00:07","dns1":"10.151.22.5","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://329","isolationUri":"vlan://329","isSecurityGroupEnabled":false}]},"result":false,"details":"StartCommand failed due to Exception: java.lang.RuntimeException\nMessage: File [datastore1] VM-a6cbbb37-66e8-4bee-80bf-fcdd217c6b01/ROOT-87-000001.vmdk was not found\n","wait":0}}] }
2014-07-14 12:30:00,265 DEBUG [c.c.a.t.Request] (Work-Job-Executor-114:ctx-83604a18 job-700/job-702 ctx-4d248787) Seq 4-164890826: Received: { Ans: , MgmtId: 20620141786272, via: 4, Ver: v1, Flags: 10, { StartAnswer } }
2014-07-14 12:30:00,263 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-53:ctx-dfca1177) Seq 1-1802268264: Response Received:
2014-07-14 12:30:00,267 DEBUG [c.c.a.t.Request] (StatsCollector-1:ctx-ca62e80b) Seq 1-1802268264: Received: { Ans: , MgmtId: 20620141786272, via: 1, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-07-14 12:30:00,273 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-138:ctx-7391b7a1) Seq 4-164890830: Executing request
2014-07-14 12:30:00,305 INFO [c.c.v.VirtualMachineManagerImpl] (Work-Job-Executor-114:ctx-83604a18 job-700/job-702 ctx-4d248787) Unable to start VM on Host[-4-Routing] due to StartCommand failed due to Exception: java.lang.RuntimeException
Message: File [datastore1] VM-a6cbbb37-66e8-4bee-80bf-fcdd217c6b01/ROOT-87-000001.vmdk was not found
We should allow VM reset when VM has VM snapshots.