You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@cloudstack.apache.org by "shweta agarwal (JIRA)" <ji...@apache.org> on 2013/07/31 14:43:50 UTC
[jira] [Reopened] (CLOUDSTACK-3569) Parallel deployment - Vmware -
When deploying 30 parallel Vms , we see few Vms failing in
SavePasswordCommand,DhcpEntryCommand ,VmDataCommand.
[ https://issues.apache.org/jira/browse/CLOUDSTACK-3569?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
shweta agarwal reopened CLOUDSTACK-3569:
----------------------------------------
Can still see several failures in vmware setup
MS log snippet for one of it
2013-07-31 17:18:57,310 INFO [storage.resource.VmwareStorageProcessor] (DirectAgent-61:10.147.40.27) detach disks from volume-wrapper VM ROOT-47-48
2013-07-31 17:18:57,329 ERROR [storage.resource.VmwareStorageProcessor] (DirectAgent-61:10.147.40.27) CreateCommand failed due to Exception: java.lang.NullPointerException
Message: null
java.lang.NullPointerException
at com.cloud.storage.resource.VmwareStorageProcessor.cloneVolumeFromBaseTemplate(VmwareStorageProcessor.java:395)
at com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.execute(StorageSubsystemCommandHandlerBase.java:73)
at com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.handleStorageCommands(StorageSubsystemCommandHandlerBase.java:49)
at com.cloud.hypervisor.vmware.resource.VmwareResource.executeRequest(VmwareResource.java:562)
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:1146)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:679)
2013-07-31 17:18:57,336 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-61:null) Seq 1-557318255: Response Received:
2013-07-31 17:18:57,336 DEBUG [agent.transport.Request] (DirectAgent-61:null) Seq 1-557318255: Processing: { Ans: , MgmtId: 7036733489194, via: 1, Ver: v1, Flags: 10, [{"org.apache.cloudstack.storage.command.CopyCmdAnswer":{"result":false,"details":"java.lang.NullPointerException","wait":0}}] }
2013-07-31 17:18:57,336 DEBUG [agent.tra
Attaching full Ms log
> 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
> Assignee: Sheng Yang
> Priority: Blocker
> Fix For: 4.2.0
>
> Attachments: management-server.log
>
>
> 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