[ https://issues.apache.org/jira/browse/CLOUDSTACK-5668?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13886427#comment-13886427 ]
Nathan Rich commented on CLOUDSTACK-5668: ----------------------------------------- More info : the uuid ab95476b-af90-4577-59d2-0d8ef02a1745 is an SR uuid as per this line 2014-01-29 14:56:03,437 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-319:null) Host x.x.x.x OpaqueRef:d149a47c-6917-7f9c-446a-c1739e56a93c: Created a SR; UUID is ab95476b-af90-4577-59d2-0d8ef02a1745 device config is {serverpath=/export/secondary/template/tmpl/2/255, server=san01.cloud.xxx.com} It seems that cloudstack creates a temporary SR to copy template, but then doesn't realize that the path after running that ^ will be /export/secondary/template/tmpl/2/255/ab95476b-af90-4577-59d2-0d8ef02a1745 , not /export/secondary/template/tmpl/2/255 > Can't make templates from XenServer VHDs > ---------------------------------------- > > Key: CLOUDSTACK-5668 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-5668 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the > default.) > Components: Template > Affects Versions: 4.2.0, 4.2.1 > Environment: XenServer 6.2 hypers with > XS62E001,XS62E002,XS62E004,XS62E005,XS62E007,XS62E008,XS62E009,XS62E010,XS62E011,XS62E012,XS62E013,XS62ESP1 > (service pack 1) > Reporter: Nathan Rich > Priority: Critical > Labels: template, xenserver > > When creating a template from VHD it says it can't find the vhd at > ""template/tmpl/2/223/ac4c722d-2896-487e-9e44-9bde3a047daf.vhd"" > When I look for the VHD I find it instead at > "template/tmpl/2/223/f52254da-33a4-8ceb-8788-35c7e57cb62e/ac4c722d-2896-487e-9e44-9bde3a047daf.vhd" > There is an additional UUID in the path that cloudstack is not looking for. > traceback: > 2013-12-28 13:04:13,255 DEBUG [cloud.storage.VolumeManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Checking > if we need to prepare 1 volumes for > VM[User|46144118-2e69-471f-9bd6-3068449e8f0a] > 2013-12-28 13:04:13,267 DEBUG [storage.image.TemplateDataFactoryImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) template > 223 is already in store:2, type:Image > 2013-12-28 13:04:13,273 DEBUG [storage.datastore.PrimaryDataStoreImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Not found > (templateId:223poolId:2) in template_spool_ref, persisting it > 2013-12-28 13:04:13,281 DEBUG [storage.image.TemplateDataFactoryImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) template > 223 is already in store:2, type:Primary > 2013-12-28 13:04:13,283 DEBUG [storage.volume.VolumeServiceImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Found > template ac4c722d-2896-487e-9e44-9bde3a047daf in storage pool 2 with > VMTemplateStorag ePool id: 16 > 2013-12-28 13:04:13,291 DEBUG [storage.volume.VolumeServiceImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Acquire > lock on VMTemplateStoragePool 16 with timeout 3600 seconds > 2013-12-28 13:04:13,293 INFO [storage.volume.VolumeServiceImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) lock is > acquired for VMTemplateStoragePool 16 > 2013-12-28 13:04:13,301 DEBUG [storage.motion.AncientDataMotionStrategy] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) copyAsync > inspecting src type TEMPLATE copyAsync inspecting dest type TEMPLATE > 2013-12-28 13:04:13,316 DEBUG [agent.transport.Request] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Seq > 9-1395326995: Sending { Cmd , MgmtId: 253519258224950, via: 9, Ver: v1, > Flags: 100111, [{"org.a > pache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/223/ac4c722d-2896-487e-9e44-9bde3a047daf.vhd","uuid":"d6d85c3e-c3af-40cd-afbd-37d335b8f56b" > > ,"id":223,"format":"VHD","accountId":2,"hvm":true,"displayText":"test2","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://san01.cloud.xxx.com/export/secondary","_role":"Image"}},"name":"ac4c722d-2896-487e-9e > > 44-9bde3a047daf","hypervisorType":"XenServer"}},"destTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"uuid":"d6d85c3e-c3af-40cd-afbd-37d335b8f56b","id":223,"format":"VHD","accountId":2,"hvm":true,"displayText":"tes > > t2","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"Primary","id":2,"poolType":"PreSetup","host":"localhost","path":"/Primary","port":0}},"name":"ac4c722d-2896-487e-9e44-9bde3a047daf","hyperv > > isorType":"XenServer"}},"executeInSequence":true,"wait":10800}}] } > 2013-12-28 13:04:13,317 DEBUG [agent.transport.Request] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Seq > 9-1395326995: Executing: { Cmd , MgmtId: 253519258224950, via: 9, Ver: v1, > Flags: 100111, [{"or > g.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/223/ac4c722d-2896-487e-9e44-9bde3a047daf.vhd","uuid":"d6d85c3e-c3af-40cd-afbd-37d335b8f5 > > 6b","id":223,"format":"VHD","accountId":2,"hvm":true,"displayText":"test2","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://san01.cloud.xxx.com/export/secondary","_role":"Image"}},"name":"ac4c722d-2896-487e > > -9e44-9bde3a047daf","hypervisorType":"XenServer"}},"destTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"uuid":"d6d85c3e-c3af-40cd-afbd-37d335b8f56b","id":223,"format":"VHD","accountId":2,"hvm":true,"displayText":" > > test2","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"Primary","id":2,"poolType":"PreSetup","host":"localhost","path":"/Primary","port":0}},"name":"ac4c722d-2896-487e-9e44-9bde3a047daf","hyp > > ervisorType":"XenServer"}},"executeInSequence":true,"wait":10800}}] } > 2013-12-28 13:04:13,317 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-96:null) Seq 9-1395326995: Executing request > 2013-12-28 13:04:14,034 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-17:null) Ping from 2 > 2013-12-28 13:04:14,609 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-108:null) Ping from 9 > 2013-12-28 13:04:14,940 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null) > ===START=== 69.89.79.19 -- GET > command=queryAsyncJobResult&jobId=2e8f9cf4-3e9b-45d9-8481-093ec790cdd4&response=json&sessionkey=2aBNKkj6Hzwel63VVT > zVmzTaph8%3D&_=1388264654382 > 2013-12-28 13:04:14,967 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null) > ===END=== 69.89.79.19 -- GET > command=queryAsyncJobResult&jobId=2e8f9cf4-3e9b-45d9-8481-093ec790cdd4&response=json&sessionkey=2aBNKkj6Hzwel63VVTzV > mzTaph8%3D&_=1388264654382 > 2013-12-28 13:04:14,978 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-63:null) Ping from 12 > 2013-12-28 13:04:15,396 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-11:null) Seq 2-449708037: Executing request > 2013-12-28 13:04:15,420 WARN [xen.resource.XenServerStorageProcessor] > (DirectAgent-96:null) destoryVDIbyNameLabel failed due to there are 0 VDIs > with name cloud-c2c2d197-3da0-4128-a9d2-1c661b4aa2e2 > 2013-12-28 13:04:15,421 WARN [xen.resource.XenServerStorageProcessor] > (DirectAgent-96:null) can not create vdi in sr > 8ae79926-f5b3-f7f4-3e2f-3ba33d9aea21 > 2013-12-28 13:04:15,421 WARN [xen.resource.XenServerStorageProcessor] > (DirectAgent-96:null) Catch Exception > com.cloud.utils.exception.CloudRuntimeException for template + due to > com.cloud.utils.exception.CloudRuntimeExcept ion: can not create > vdi in sr 8ae79926-f5b3-f7f4-3e2f-3ba33d9aea21 > com.cloud.utils.exception.CloudRuntimeException: can not create vdi in sr > 8ae79926-f5b3-f7f4-3e2f-3ba33d9aea21 > at > com.cloud.hypervisor.xen.resource.XenServerStorageProcessor.copy_vhd_from_secondarystorage(XenServerStorageProcessor.java:792) > at > com.cloud.hypervisor.xen.resource.XenServerStorageProcessor.copyTemplateToPrimaryStorage(XenServerStorageProcessor.java:863) > at > com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.execute(StorageSubsystemCommandHandlerBase.java:70) > at > com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.handleStorageCommands(StorageSubsystemCommandHandlerBase.java:49) > at > com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixResourceBase.java:621) > at > com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenServer56Resource.java:73) > at > com.cloud.hypervisor.xen.resource.XenServer610Resource.executeRequest(XenServer610Resource.java:104) > 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:701) > 2013-12-28 13:04:15,422 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-96:null) Seq 9-1395326995: Response Received: > 2013-12-28 13:04:15,422 DEBUG [agent.transport.Request] (DirectAgent-96:null) > Seq 9-1395326995: Processing: { Ans: , MgmtId: 253519258224950, via: 9, Ver: > v1, Flags: 110, [{"org.apache.cloudstack.storage.command.CopyCmdAnsw > er":{"result":false,"details":"Catch Exception > com.cloud.utils.exception.CloudRuntimeException for template + due to > com.cloud.utils.exception.CloudRuntimeException: can not create vdi in sr > 8ae79926-f5b3-f7f4-3e2f-3ba33d9a ea21","wait":0}}] } > 2013-12-28 13:04:15,423 DEBUG [agent.transport.Request] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Seq > 9-1395326995: Received: { Ans: , MgmtId: 253519258224950, via: 9, Ver: v1, > Flags: 110, { CopyCm dAnswer } } > 2013-12-28 13:04:15,423 DEBUG [agent.manager.AgentAttache] > (DirectAgent-96:null) Seq 9-1395326995: No more commands found > 2013-12-28 13:04:15,434 INFO [storage.volume.VolumeServiceImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) releasing > lock for VMTemplateStoragePool 16 > 2013-12-28 13:04:15,434 WARN [utils.db.Merovingian2] (Job-Executor-2:job-123 > = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Was unable to find lock for the > key template_spool_ref16 and thread id 691039818 > 2013-12-28 13:04:15,435 DEBUG [cloud.storage.VolumeManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Unable to > create Vol[48|vm=41|ROOT]:Catch Exception > com.cloud.utils.exception.CloudRuntimeEx ception for template + > due to com.cloud.utils.exception.CloudRuntimeException: can not create vdi in > sr 8ae79926-f5b3-f7f4-3e2f-3ba33d9aea21 > 2013-12-28 13:04:15,435 INFO [cloud.vm.VirtualMachineManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Unable to > contact resource. > com.cloud.exception.StorageUnavailableException: Resource [StoragePool:2] is > unreachable: Unable to create Vol[48|vm=41|ROOT]:Catch Exception > com.cloud.utils.exception.CloudRuntimeException for template + due to > com.cloud.u tils.exception.CloudRuntimeException: can not create > vdi in sr 8ae79926-f5b3-f7f4-3e2f-3ba33d9aea21 > at > com.cloud.storage.VolumeManagerImpl.recreateVolume(VolumeManagerImpl.java:2544) > at > com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.java:2592) > at > com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:889) > at > com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:578) > at > org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.deployVirtualMachine(VMEntityManagerImpl.java:227) > at > org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.deploy(VirtualMachineEntityImpl.java:209) > at > com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3406) > at > com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2966) > at > com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2952) > 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:1146) > at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) > at java.lang.Thread.run(Thread.java:701) > 2013-12-28 13:04:15,441 DEBUG [cloud.vm.VirtualMachineManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Cleaning > up resources for the vm VM[User|46144118-2e69-471f-9bd6-3068449e8f0a] in > Startin g state > 2013-12-28 13:04:15,443 DEBUG [agent.transport.Request] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Seq > 9-1395326996: Sending { Cmd , MgmtId: 253519258224950, via: 9, Ver: v1, > Flags: 100111, [{"com.c > loud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"i-2-41-VM","wait":0}}] > } > 2013-12-28 13:04:15,443 DEBUG [agent.transport.Request] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Seq > 9-1395326996: Executing: { Cmd , MgmtId: 253519258224950, via: 9, Ver: v1, > Flags: 100111, [{"co > m.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"i-2-41-VM","wait":0}}] > } > 2013-12-28 13:04:15,444 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-76:null) Seq 9-1395326996: Executing request > 2013-12-28 13:04:15,598 INFO [xen.resource.CitrixResourceBase] > (DirectAgent-76:null) VM does not exist on > XenServerc1a556e6-028c-4ecc-bfd9-426cde76f530 > 2013-12-28 13:04:15,598 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-76:null) Seq 9-1395326996: Response Received: > 2013-12-28 13:04:15,598 DEBUG [agent.transport.Request] (DirectAgent-76:null) > Seq 9-1395326996: Processing: { Ans: , MgmtId: 253519258224950, via: 9, Ver: > v1, Flags: 110, [{"com.cloud.agent.api.StopAnswer":{"vncPort":0,"res > ult":true,"details":"VM does not exist","wait":0}}] } > 2013-12-28 13:04:15,598 DEBUG [agent.manager.AgentAttache] > (DirectAgent-76:null) Seq 9-1395326996: No more commands found > 2013-12-28 13:04:15,598 DEBUG [agent.transport.Request] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Seq > 9-1395326996: Received: { Ans: , MgmtId: 253519258224950, via: 9, Ver: v1, > Flags: 110, { StopAn swer } } > 2013-12-28 13:04:15,598 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-11:null) Seq 2-449708037: Response Received: > 2013-12-28 13:04:15,599 DEBUG [agent.transport.Request] (DirectAgent-11:null) > Seq 2-449708037: Processing: { Ans: , MgmtId: 253519258224950, via: 2, Ver: > v1, Flags: 10, [{"com.cloud.agent.api.ClusterSyncAnswer":{"_clusterId > ":1,"_newStates":{},"_isExecuted":false,"result":true,"wait":0}}] } > 2013-12-28 13:04:15,610 DEBUG [cloud.network.NetworkModelImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Service > SecurityGroup is not supported in the network id=207 > 2013-12-28 13:04:15,614 DEBUG [cloud.network.NetworkManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Changing > active number of nics for network id=207 on -1 > 2013-12-28 13:04:15,624 DEBUG [cloud.network.NetworkManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Asking > VirtualRouter to release > Nic[92-41-2829633d-58ce-4fa3-b417-d231a73e761d-10.1.1.199] > 2013-12-28 13:04:15,624 DEBUG [cloud.vm.VirtualMachineManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) > Successfully released network resources for the vm > VM[User|46144118-2e69-471f-9bd6-306844 9e8f0a] > 2013-12-28 13:04:15,624 DEBUG [cloud.vm.VirtualMachineManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) > Successfully cleanued up resources for the vm > VM[User|46144118-2e69-471f-9bd6-3068449e8f0 a] in Starting state > 2013-12-28 13:04:15,638 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Deploy > avoids pods: [], clusters: [], hosts: [9] > 2013-12-28 13:04:15,640 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) > DeploymentPlanner allocation algorithm: > com.cloud.deploy.FirstFitPlanner_Enhancer > ByCloudStack_dd371ad3@261337c3 > 2013-12-28 13:04:15,640 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Trying to > allocate a host and storage pools from dc:1, pod:1,cluster:1, requested > cpu: 500, requested ram: 536870912 > 2013-12-28 13:04:15,640 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Is ROOT > volume READY (pool already allocated)?: No > 2013-12-28 13:04:15,640 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) > DeploymentPlan has host_id specified, choosing this host and making no checks > on this host: 9 > 2013-12-28 13:04:15,642 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) The > specified host is in avoid set > 2013-12-28 13:04:15,642 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Cannnot > deploy to specified host, returning. > 2013-12-28 13:04:15,655 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) VM state > transitted from :Starting to Stopped with event: OperationFailedvm's original > ho st id: null new host id: null host id before state > transition: 9 > 2013-12-28 13:04:15,663 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Hosts's > actual total CPU: 48000 and CPU after applying overprovisioning: 48000 > 2013-12-28 13:04:15,663 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Hosts's > actual total RAM: 63270027072 and RAM after applying overprovisioning: > 6327002726 4 > 2013-12-28 13:04:15,664 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) release > cpu from host: 9, old used: 500,reserved: 0, actual total: 48000, total with > over provisioning: 48000; new used: 0,reserved:0; > movedfromreserved: false,moveToReserveredfalse > 2013-12-28 13:04:15,664 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) release > mem from host: 9, old used: 536870912,reserved: 0, total: 63270027264; new > used: 0,reserved:0; movedfromreserved: > false,moveToReserveredfalse > 2013-12-28 13:04:15,687 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) VM state > transitted from :Stopped to Starting with event: StartRequestedvm's original > hos t id: null new host id: null host id before state > transition: null > 2013-12-28 13:04:15,687 DEBUG [cloud.vm.VirtualMachineManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) > Successfully transitioned to start state for > VM[User|46144118-2e69-471f-9bd6-3068449e8f0a ] reservation id = > f594ecf8-304c-4d85-84ba-c766935ceacf > 2013-12-28 13:04:15,692 DEBUG [cloud.vm.VirtualMachineManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Trying to > deploy VM, vm has dcId: 1 and podId: 1 > 2013-12-28 13:04:15,692 DEBUG [cloud.vm.VirtualMachineManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Deploy > avoids pods: [], clusters: [], hosts: [9] > 2013-12-28 13:04:15,706 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Deploy > avoids pods: [], clusters: [], hosts: [9] > 2013-12-28 13:04:15,708 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) > DeploymentPlanner allocation algorithm: > com.cloud.deploy.FirstFitPlanner_Enhancer > ByCloudStack_dd371ad3@261337c3 > 2013-12-28 13:04:15,708 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Trying to > allocate a host and storage pools from dc:1, pod:1,cluster:null, reques > ted cpu: 500, requested ram: 536870912 > 2013-12-28 13:04:15,708 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Is ROOT > volume READY (pool already allocated)?: No > 2013-12-28 13:04:15,708 DEBUG [cloud.deploy.FirstFitPlanner] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Searching > resources only under specified Pod: 1 > 2013-12-28 13:04:15,708 DEBUG [cloud.deploy.FirstFitPlanner] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Listing > clusters in order of aggregate capacity, that have (atleast one host with) > enough CPU a nd RAM capacity under this Pod: 1 > 2013-12-28 13:04:15,713 DEBUG [cloud.deploy.FirstFitPlanner] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Removing > from the clusterId list these clusters from avoid set: [] > 2013-12-28 13:04:15,720 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Checking > resources in Cluster: 1 under Pod: 1 > 2013-12-28 13:04:15,720 DEBUG [allocator.impl.FirstFitAllocator] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Looking for hosts in dc: 1 pod:1 cluster:1 > 2013-12-28 13:04:15,725 DEBUG [allocator.impl.FirstFitAllocator] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) FirstFitAllocator has 3 hosts to check for > allocation: [Host[-12-R outing], Host[-9-Routing], > Host[-2-Routing]] > 2013-12-28 13:04:15,732 DEBUG [allocator.impl.FirstFitAllocator] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Found 3 hosts for allocation after prioritization: > [Host[-12-Routi ng], Host[-9-Routing], Host[-2-Routing]] > 2013-12-28 13:04:15,732 DEBUG [allocator.impl.FirstFitAllocator] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Looking for speed=500Mhz, Ram=512 > 2013-12-28 13:04:15,740 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Checking if host: 12 has enough capacity for > requested CPU: 500 and requested RAM: 536870912 , > cpuOverprovisioningFactor: 1.0 > 2013-12-28 13:04:15,744 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Hosts's actual total CPU: 48000 and CPU after > applying overprovi sioning: 48000 > 2013-12-28 13:04:15,744 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Free CPU: 46500 , Requested CPU: 500 > 2013-12-28 13:04:15,744 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Free RAM: 61656576000 , Requested RAM: 536870912 > 2013-12-28 13:04:15,744 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Host has enough CPU and RAM available > 2013-12-28 13:04:15,744 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) STATS: Can alloc CPU from host: 12, used: 1500, > reserved: 0, act ual total: 48000, total with overprovisioning: > 48000; requested cpu:500,alloc_from_last_host?:false > ,considerReservedCapacity?: true > 2013-12-28 13:04:15,744 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) STATS: Can alloc MEM from host: 12, used: > 1610612736, reserved: 0, total: 63267188736; requested mem: > 536870912,alloc_from_last_host?:false ,considerReservedCapacity?: true > 2013-12-28 13:04:15,744 DEBUG [allocator.impl.FirstFitAllocator] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Found a suitable host, adding to list: 12 > 2013-12-28 13:04:15,744 DEBUG [allocator.impl.FirstFitAllocator] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Host name: xs02.cloud.xxx.com, hostId: 9 is in > avoid set, skipp ing this and trying other available hosts > 2013-12-28 13:04:15,752 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Checking if host: 2 has enough capacity for > requested CPU: 500 a nd requested RAM: 536870912 , > cpuOverprovisioningFactor: 1.0 > 2013-12-28 13:04:15,755 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Hosts's actual total CPU: 48000 and CPU after > applying overprovi sioning: 48000 > 2013-12-28 13:04:15,755 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Free CPU: 46500 , Requested CPU: 500 > 2013-12-28 13:04:15,755 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Free RAM: 61790793728 , Requested RAM: 536870912 > 2013-12-28 13:04:15,755 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Host has enough CPU and RAM available > 2013-12-28 13:04:15,755 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) STATS: Can alloc CPU from host: 2, used: 1500, > reserved: 0, actu al total: 48000, total with overprovisioning: > 48000; requested cpu:500,alloc_from_last_host?:false > ,considerReservedCapacity?: true > 2013-12-28 13:04:15,755 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) STATS: Can alloc MEM from host: 2, used: > 1476395008, reserved: 0 , total: 63267188736; requested mem: > 536870912,alloc_from_last_host?:false ,considerReservedCapacity?: true > 2013-12-28 13:04:15,755 DEBUG [allocator.impl.FirstFitAllocator] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Found a suitable host, adding to list: 2 > 2013-12-28 13:04:15,756 DEBUG [allocator.impl.FirstFitAllocator] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ] > FirstFitRoutingAllocator) Host Allocator returning 2 suitable hosts > 2013-12-28 13:04:15,758 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Checking > suitable pools for volume (Id, Type): (48,ROOT) > 2013-12-28 13:04:15,758 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) We need > to allocate new storagepool for this volume > 2013-12-28 13:04:15,758 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Calling > StoragePoolAllocators to find suitable pools > 2013-12-28 13:04:15,758 DEBUG [storage.allocator.LocalStoragePoolAllocator] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) > LocalStoragePoolAllocator trying to find storage pool to fit the vm > 2013-12-28 13:04:15,758 DEBUG > [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-2:job-123 > = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) ClusterScopeStoragePoolAllocator > looking for storage pool > 2013-12-28 13:04:15,758 DEBUG > [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-2:job-123 > = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Looking for pools in dc: 1 pod:1 > cluster:1 > 2013-12-28 13:04:15,764 DEBUG > [storage.allocator.AbstractStoragePoolAllocator] (Job-Executor-2:job-123 = [ > 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Checking if storage pool is suitable, > name: null ,poolId: 2 > 2013-12-28 13:04:15,764 DEBUG > [storage.allocator.AbstractStoragePoolAllocator] (Job-Executor-2:job-123 = [ > 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) StoragePool is in avoid set, skipping > this pool > 2013-12-28 13:04:15,764 DEBUG > [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-2:job-123 > = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) FirstFitStoragePoolAllocator > returning 0 suitable storage pools > 2013-12-28 13:04:15,764 DEBUG > [storage.allocator.ZoneWideStoragePoolAllocator] (Job-Executor-2:job-123 = [ > 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) ZoneWideStoragePoolAllocator to find > storage pool > 2013-12-28 13:04:15,771 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) No > suitable pools found for volume: Vol[48|vm=41|ROOT] under cluster: 1 > 2013-12-28 13:04:15,771 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) No > suitable pools found > 2013-12-28 13:04:15,771 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) No > suitable storagePools found under this Cluster: 1 > 2013-12-28 13:04:15,776 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Could not > find suitable Deployment Destination for this VM under any clusters, re > turning. > 2013-12-28 13:04:15,776 DEBUG [cloud.deploy.FirstFitPlanner] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Searching > resources only under specified Pod: 1 > 2013-12-28 13:04:15,776 DEBUG [cloud.deploy.FirstFitPlanner] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Listing > clusters in order of aggregate capacity, that have (atleast one host with) > enough CPU a nd RAM capacity under this Pod: 1 > 2013-12-28 13:04:15,781 DEBUG [cloud.deploy.FirstFitPlanner] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Removing > from the clusterId list these clusters from avoid set: [1] > 2013-12-28 13:04:15,781 DEBUG [cloud.deploy.FirstFitPlanner] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) No > clusters found after removing disabled clusters and clusters in avoid list, > returning. > 2013-12-28 13:04:15,792 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) VM state > transitted from :Starting to Stopped with event: OperationFailedvm's original > ho st id: null new host id: null host id before state > transition: null > 2013-12-28 13:04:15,808 DEBUG [cloud.vm.UserVmManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) > Destroying vm VM[User|46144118-2e69-471f-9bd6-3068449e8f0a] as it failed to > create on Host with I d:null > 2013-12-28 13:04:15,822 DEBUG [cloud.capacity.CapacityManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) VM state > transitted from :Stopped to Error with event: OperationFailedToErrorvm's > origina l host id: null new host id: null host id before state > transition: null > 2013-12-28 13:04:15,835 WARN [apache.cloudstack.alerts] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) > alertType:: 8 // dataCenterId:: 1 // podId:: 1 // clusterId:: null // > message:: Failed to deploy V m with Id: 41, on Host with Id: null > 2013-12-28 13:04:15,874 INFO [user.vm.DeployVMCmd] (Job-Executor-2:job-123 = > [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) > com.cloud.exception.InsufficientServerCapacityException: Unable to create a > deployment for VM[User|46144 > 118-2e69-471f-9bd6-3068449e8f0a]Scope=interface com.cloud.dc.DataCenter; id=1 > 2013-12-28 13:04:15,874 INFO [user.vm.DeployVMCmd] (Job-Executor-2:job-123 = > [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Unable to create a deployment for > VM[User|46144118-2e69-471f-9bd6-3068449e8f0a] > com.cloud.exception.InsufficientServerCapacityException: Unable to create a > deployment for VM[User|46144118-2e69-471f-9bd6-3068449e8f0a]Scope=interface > com.cloud.dc.DataCenter; id=1 > at > com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:842) > at > com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:578) > at > org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.deployVirtualMachine(VMEntityManagerImpl.java:237) > at > org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.deploy(VirtualMachineEntityImpl.java:209) > at > com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3406) > at > com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2966) > at > com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2952) > 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:1146) > at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) > at java.lang.Thread.run(Thread.java:701) > 2013-12-28 13:04:15,876 DEBUG [cloud.async.AsyncJobManagerImpl] > (Job-Executor-2:job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ]) Complete > async job-123 = [ 2e8f9cf4-3e9b-45d9-8481-093ec790cdd4 ], jobStatus: 2, > resultCode: 530, result: Error Code: 533 Error text: Unable to > create a deployment for VM[User|46144118-2e69-471f-9bd6-3068449e8f0a] > 2013-12-28 13:04:16,082 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-111:null) Seq 9-1395326981: Executing request > 2013-12-28 13:04:16,157 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-111:null) Seq 9-1395326981: Response Received: > 2013-12-28 13:04:16,157 DEBUG [agent.transport.Request] > (DirectAgent-111:null) Seq 9-1395326981: Processing: { Ans: , MgmtId: > 253519258224950, via: 9, Ver: v1, Flags: 10, > [{"com.cloud.agent.api.Answer":{"result":true,"wait" :0}}] } > 2013-12-28 13:04:16,631 DEBUG [agent.manager.AgentManagerImpl] > (AgentManager-Handler-11:null) SeqA 10-93070: Processing Seq 10-93070: { Cmd > , MgmtId: -1, via: 10, Ver: v1, Flags: 11, > [{"com.cloud.agent.api.ConsoleProxyLoadR > eportCommand":{"_proxyVmId":20,"_loadInfo":"{\n \"connections\": > []\n}","wait":0}}] } > 2013-12-28 13:04:16,636 DEBUG [agent.manager.AgentManagerImpl] > (AgentManager-Handler-11:null) SeqA 10-93070: Sending Seq 10-93070: { Ans: , > MgmtId: 253519258224950, via: 10, Ver: v1, Flags: 100010, > [{"com.cloud.agent.api.Ag > entControlAnswer":{"result":true,"wait":0}}] } -- This message was sent by Atlassian JIRA (v6.1.5#6160)