Sangeetha Hariharan created CLOUDSTACK-3569:
-----------------------------------------------
Summary: Parallel deployment - Vmware - When deploying 30 parallel
Vms , we see few Vms failing in SavePasswordCommand,DhcpEntryCommand
,VmDataCommand.
Key: CLOUDSTACK-3569
URL: https://issues.apache.org/jira/browse/CLOUDSTACK-3569
Project: CloudStack
Issue Type: Bug
Security Level: Public (Anyone can view this level - this is the default.)
Components: Management Server
Affects Versions: 4.2.0
Environment: Build from 4.2.0
Reporter: Sangeetha Hariharan
Priority: Blocker
Fix For: 4.2.0
Parallel deployment - Vmware - When deploying 30 parallel Vms , we see ~15 Vms
fail in org.apache.cloudstack.storage.command.CopyCommand.
Steps to reproduce the problem:
Set up - Advanced zone with Vmware ESXI, 5.0.0 host.
Deploy 30 Vms in parallel in a network that is already implemented (router is
already running).
Only 9 vms were successfully deployed.
~15 Vms failed due to issue reported in cloudstack-3568.
Rest of the Vm deplpoyment failed in SavePasswordCommand,DhcpEntryCommand
,VmDataCommand where it encounters an IO Exception when trying to communicate
with the router.
mysql> select count(*),state,type from vm_instance where name like "hello%"
group by state,type ;
+----------+---------+------+
| count(*) | state | type |
+----------+---------+------+
| 42 | Error | User |
| 9 | Running | User |
+----------+---------+------+
2 rows in set (0.00 sec)
Following exception seen in management server logs:
2013-07-16 15:46:18,814 ERROR [vmware.resource.VmwareResource]
(DirectAgent-44:10.223.57.66) VmDataCommand failed due to Exception:
java.io.IOException
Message: There was a problem while connecting to 10.223.57.96:3922
java.io.IOException: There was a problem while connecting to 10.223.57.96:3922
at com.trilead.ssh2.Connection.connect(Connection.java:792)
at com.cloud.utils.ssh.SshHelper.sshExecute(SshHelper.java:132)
at com.cloud.utils.ssh.SshHelper.sshExecute(SshHelper.java:37)
at
com.cloud.hypervisor.vmware.resource.VmwareResource.execute(VmwareResource.java:2345)
at
com.cloud.hypervisor.vmware.resource.VmwareResource.executeRequest(VmwareResource.java:441)
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$101(ScheduledThreadPoolExecutor.java:165)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:266)
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)
Caused by: java.io.IOException: Premature connection close
at
com.trilead.ssh2.transport.ClientServerHello.readLineRN(ClientServerHello.java:33)
at
com.trilead.ssh2.transport.ClientServerHello.<init>(ClientServerHello.java:68)
at
com.trilead.ssh2.transport.TransportManager.initialize(TransportManager.java:461)
at com.trilead.ssh2.Connection.connect(Connection.java:732)
... 13 more
2013-07-16 15:46:18,815 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-44:null) Seq 1-227737678: Response Received:
2013-07-16 15:46:18,815 DEBUG [agent.transport.Request] (DirectAgent-44:null)
Seq 1-227737678: Processing: { Ans: , MgmtId: 7647994577963, via: 1, Ver: v1,
Flags: 10,
[{"com.cloud.agent.api.Answer":{"result":true,"wait":0}},{"com.cloud.agent.api.Answer":{"result":false,"details":"VmDataCommand
failed due to Exception: java.io.IOException\nMessage: There was a problem
while connecting to 10.223.57.96:3922\n","wait":0}}] }
2013-07-16 15:46:18,816 DEBUG [agent.transport.Request]
(Job-Executor-16:job-109 = [ f31f7d1f-7f1e-453c-ba0a-5600ebe43487 ]) Seq
1-227737678: Received: { Ans: , MgmtId: 7647994577963, via: 1, Ver: v1, Flags:
10, { Answer, Answer } }
2013-07-16 15:46:18,816 INFO [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-16:job-109 = [ f31f7d1f-7f1e-453c-ba0a-5600ebe43487 ]) Unable to
contact resource.
com.cloud.exception.ResourceUnavailableException: Resource [DataCenter:1] is
unreachable: Unable to apply userdata and password entry on router
at
com.cloud.network.router.VirtualNetworkApplianceManagerImpl.applyRules(VirtualNetworkApplianceManagerImpl.java:3804)
at
com.cloud.network.router.VirtualNetworkApplianceManagerImpl.applyUserData(VirtualNetworkApplianceManagerImpl.java:2991)
at
com.cloud.network.element.VirtualRouterElement.addPasswordAndUserdata(VirtualRouterElement.java:944)
at
com.cloud.network.NetworkManagerImpl.prepareElement(NetworkManagerImpl.java:2015)
at
com.cloud.network.NetworkManagerImpl.prepareNic(NetworkManagerImpl.java:2121)
at
com.cloud.network.NetworkManagerImpl.prepare(NetworkManagerImpl.java:2062)
at
com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:858)
at
com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:566)
at
org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.deployVirtualMachine(VMEntityManagerImpl.java:239)
at
org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.deploy(VirtualMachineEntityImpl.java:209)
at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3318)
at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2878)
at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2864)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at
org.apache.cloudstack.api.command.user.vm.DeployVMCmd.execute(DeployVMCmd.java:420)
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)
--
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
