Rayees Namathponnan created CLOUDSTACK-4462:
-----------------------------------------------
Summary: [Automation][vmware] Failed to find vmdk filed during
deployment; and deployment failed
Key: CLOUDSTACK-4462
URL: https://issues.apache.org/jira/browse/CLOUDSTACK-4462
Project: CloudStack
Issue Type: Bug
Security Level: Public (Anyone can view this level - this is the default.)
Components: Automation, VMware
Affects Versions: 4.2.0
Environment: Automation
vmware
found with 4.2.0-forward branch
Reporter: Rayees Namathponnan
Priority: Critical
Fix For: 4.2.1
During automation run, MS unable to find vmdk file and deployment failed.
Observed below error in MS
2013-08-22 11:55:51,779 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null)
===END=== 10.223.240.195 -- GET
signature=RPK2DwAB3%2FQ8uWNzeyn%2Fp72Ffcw%3D&apiKey=9QzLLT5rIGDi6assUgBQ6s-cdRtyblucm14vV5seHyG6NijTFPH
y-4vU2HOoE157MdQOo8GjwghoBcQdhwZyBA&command=queryAsyncJobResult&response=json&jobid=8c43beaf-fcf6-4440-90a8-9da05b0312d3
2013-08-22 11:55:51,798 DEBUG [agent.manager.AgentManagerImpl]
(AgentManager-Handler-15:null) SeqA 7-8020: Sending Seq 7-8020: { Ans: ,
MgmtId: 90928106758026, via: 7, Ver: v1, Flags: 100010, [{"com.cloud.agent.a
pi.AgentControlAnswer":{"result":true,"wait":0}}] }
2013-08-22 11:55:53,014 INFO [vmware.resource.VmwareResource]
(DirectAgent-169:10.223.250.131) Trying to connect to 10.223.250.185
2013-08-22 11:55:53,015 INFO [vmware.resource.VmwareResource]
(DirectAgent-169:10.223.250.131) Could not connect to 10.223.250.185 due to
java.net.ConnectException: Connection refused
2013-08-22 11:55:53,454 WARN [vmware.resource.VmwareResource]
(DirectAgent-306:10.223.250.130) StartCommand failed due to Exception:
java.lang.RuntimeException
Message: File [4faf04c26dd83025b43f65d32cc49d02] ROOT-421.vmdk was not found
java.lang.RuntimeException: File [4faf04c26dd83025b43f65d32cc49d02]
ROOT-421.vmdk was not found
at
com.cloud.hypervisor.vmware.util.VmwareClient.waitForTask(VmwareClient.java:378)
at
com.cloud.hypervisor.vmware.mo.DatastoreMO.moveDatastoreFile(DatastoreMO.java:235)
at
com.cloud.storage.resource.VmwareStorageLayoutHelper.syncVolumeToVmDefaultFolder(VmwareStorageLayoutHelper.java:133)
at
com.cloud.hypervisor.vmware.resource.VmwareResource.execute(VmwareResource.java:2880)
at
com.cloud.hypervisor.vmware.resource.VmwareResource.executeRequest(VmwareResource.java:514)
at
com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgentAttache.java:186)
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.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:1110)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:722)
2013-08-22 11:55:53,465 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-306:null) Seq 2-542512498: Cancelling because one of the answers
is false and it is stop on error.
2013-08-22 11:55:53,465 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-306:null) Seq 2-542512498: Response Received:
2013-08-22 11:55:53,466 DEBUG [agent.transport.Request] (DirectAgent-306:null)
Seq 2-542512498: Processing: { Ans: , MgmtId: 90928106758026, via: 2, Ver: v1,
Flags: 110,
[{"com.cloud.agent.api.StartAnswer":{"vm":{"id":421,"name":"r-421-TestVM","bootloader":"HVM","type":"DomainRouter","cpus":1,"minSpeed":500,"maxSpeed":500,"minRam":134217728,"maxRam":134217728,"hostName":"r-421-TestVM","arch":"i686","os":"Debian
GNU/Linux 5.0 (32-bit)","bootArgs":" vpccidr=10.1.1.1/16
domain=test.domain.org dns1=8.8.8.8 template=domP name=r-421-TestVM
eth0ip=10.223.250.178 eth0mask=255.255.255.192 mgmtcidr=10.223.49.192/26
localgw=10.223.250.129 type=vpcrouter disable_rp_filter=true extra_pubnics=2
nic_macs=02:00:1b:48:00:db","rebootOnCrash":false,"enableHA":true,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"f5c0692c16ebfcce","params":{"nicAdapter":"E1000"},"uuid":"c6b5e89f-ab1a-466f-b2b9-d760da583195","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"fa27b974-d9b9-4d05-b809-e4d98f764db2","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"4faf04c2-6dd8-3025-b43f-65d32cc49d02","id":1,"poolType":"NetworkFilesystem","host":"10.223.110.232","path":"/export/home/automation/SC-CLOUD-QA03/primary1","port":2049}},"name":"ROOT-421","size":2097152000,"path":"ROOT-421","volumeId":458,"vmName":"r-421-TestVM","accountId":289,"format":"OVA","id":458,"hypervisorType":"VMware"}},"diskSeq":0,"type":"ROOT"}],"nics":[{"deviceId":0,"networkRateMbps":-1,"defaultNic":false,"uuid":"83c22754-214c-4d2c-8af0-ded2d9c43bfc","ip":"10.223.250.178","netmask":"255.255.255.192","gateway":"10.223.250.129","mac":"02:00:1b:48:00:db","broadcastType":"Native","type":"Control","isSecurityGroupEnabled":false}]},"result":false,"details":"StartCommand
failed due to Exception: java.lang.RuntimeException\nMessage: File
[4faf04c26dd83025b43f65d32cc49d02] ROOT-421.vmdk was not found\n","wait":0}}] }
2013-08-22 11:55:53,466 DEBUG [agent.transport.Request]
(Job-Executor-33:job-1697 = [ 8c43beaf-fcf6-4440-90a8-9da05b0312d3 ]) Seq
2-542512498: Received: { Ans: , MgmtId: 90928106758026, via: 2, Ver: v1,
Flags: 110, { StartAnswer } }
2013-08-22 11:55:53,467 DEBUG [agent.manager.AgentAttache]
(DirectAgent-306:null) Seq 2-542512498: No more commands found
2013-08-22 11:55:53,470 INFO [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-33:job-1697 = [ 8c43beaf-fcf6-4440-90a8-9da05b0312d3 ]) Unable to
start VM on Host[-2-Routing] due to StartCommand failed due to Exception:
java.lang.RuntimeException
Message: File [4faf04c26dd83025b43f65d32cc49d02] ROOT-421.vmdk was not found
2013-08-22 11:55:53,472 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-33:job-1697 = [ 8c43beaf-fcf6-4440-90a8-9da05b0312d3 ]) Cleaning
up resources for the vm VM[DomainRouter|r-421-TestVM] in Starting state
--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators
For more information on JIRA, see: http://www.atlassian.com/software/jira
