FirstFitRoutingAllocator) Not considering hosts: [Host[-1-Routing]] to deploy template: Tmpl[201-ISO-201-2-281cbc51-8e8c-350e-9c78-83cb5cab5ae4 as they are not HVM enabled
??????????????????????????host?????????????????????????????? wanghaicheng2...@gmail.com ???????? ???? ?????????? 2013-11-16 17:03 ???????? users-cn ?????? ???????????? ??????cloudstack4.2+CentOS6.2 host??xenserver6.2 8G????????????????????????????????????????????????????????????????????????????????????????host?????????????????????????????????????? ?????????? 2013-11-16 00:54:31,186 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-4:null) Seq 1-1325989893: Response Received: 2013-11-16 00:54:31,187 DEBUG [agent.transport.Request] (DirectAgent-4:null) Seq 1-1325989893: Processing: { Ans: , MgmtId: 52235182734, via: 1, Ver: v1, Flags: 10, [{"com.cloud.agent.api.ClusterSyncAnswer":{"_clusterId":1,"_newStates":{},"_isExecuted":false,"result":true,"wait":0}}] } 2013-11-16 00:54:35,083 DEBUG [cloud.api.ApiServlet] (catalina-exec-13:null) ===START=== 192.168.16.111 -- GET command=deployVirtualMachine&zoneId=42530a8e-6dd4-4be3-9530-7448a165cd29&templateId=d3d6597a-9d6a-4f07-8ae2-e5930b248471&hypervisor=XenServer&serviceOfferingId=27c6028d-a43e-4fc2-b55b-ad9fa92074f5&diskOfferingId=f30cc1ae-0009-4c95-8d27-8fdf8b500aa7&displayname=liang&name=liang&response=json&sessionkey=hyZ3jyP7HScd6egutBy8MrCZpoI%3D&_=1384592075183 2013-11-16 00:54:35,102 DEBUG [cloud.api.ApiDispatcher] (catalina-exec-13:null) InfrastructureEntity name is:com.cloud.offering.ServiceOffering 2013-11-16 00:54:35,104 DEBUG [cloud.api.ApiDispatcher] (catalina-exec-13:null) ControlledEntity name is:com.cloud.template.VirtualMachineTemplate 2013-11-16 00:54:35,601 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-4:null) SeqA 3-137: Processing Seq 3-137: { Cmd , MgmtId: -1, via: 3, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n \"connections\": []\n}","wait":0}}] } 2013-11-16 00:54:35,607 DEBUG [cloud.api.ApiDispatcher] (catalina-exec-13:null) InfrastructureEntity name is:com.cloud.offering.DiskOffering 2013-11-16 00:54:35,612 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-4:null) SeqA 3-137: Sending Seq 3-137: { Ans: , MgmtId: 52235182734, via: 3, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] } 2013-11-16 00:54:35,661 DEBUG [cloud.vm.UserVmManagerImpl] (catalina-exec-13:null) Allocating in the DB for vm 2013-11-16 00:54:35,695 DEBUG [cloud.vm.VirtualMachineManagerImpl] (catalina-exec-13:null) Allocating entries for VM: VM[User|liang] 2013-11-16 00:54:35,700 DEBUG [cloud.vm.VirtualMachineManagerImpl] (catalina-exec-13:null) Allocating nics for VM[User|liang] 2013-11-16 00:54:35,701 DEBUG [cloud.network.NetworkManagerImpl] (catalina-exec-13:null) Allocating nic for vm VM[User|liang] in network Ntwk[204|Guest|6] with requested profile NicProfile[0-0-null-null-null 2013-11-16 00:54:35,713 DEBUG [cloud.vm.VirtualMachineManagerImpl] (catalina-exec-13:null) Allocating disks for VM[User|liang] 2013-11-16 00:54:35,732 DEBUG [cloud.vm.VirtualMachineManagerImpl] (catalina-exec-13:null) Allocation completed for VM: VM[User|liang] 2013-11-16 00:54:35,732 DEBUG [cloud.vm.UserVmManagerImpl] (catalina-exec-13:null) Successfully allocated DB entry for VM[User|liang] 2013-11-16 00:54:35,816 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-13:null) submit async job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ], details: AsyncJobVO {id:18, userId: 2, accountId: 2, sessionKey: null, instanceType: VirtualMachine, instanceId: 5, cmd: org.apache.cloudstack.api.command.user.vm.DeployVMCmd, cmdOriginator: null, cmdInfo: {"sessionkey":"hyZ3jyP7HScd6egutBy8MrCZpoI\u003d","cmdEventType":"VM.CREATE","ctxUserId":"2","serviceOfferingId":"27c6028d-a43e-4fc2-b55b-ad9fa92074f5","httpmethod":"GET","zoneId":"42530a8e-6dd4-4be3-9530-7448a165cd29","templateId":"d3d6597a-9d6a-4f07-8ae2-e5930b248471","response":"json","id":"5","hypervisor":"XenServer","name":"liang","diskOfferingId":"f30cc1ae-0009-4c95-8d27-8fdf8b500aa7","_":"1384592075183","ctxAccountId":"2","ctxStartEventId":"67","displayname":"liang"}, cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0, processStatus: 0, resultCode: 0, result: null, initMsid: 52235182734, completeMsid: null, lastUpdated: null, lastPolled: null, created: null} 2013-11-16 00:54:35,821 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Executing org.apache.cloudstack.api.command.user.vm.DeployVMCmd for job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] 2013-11-16 00:54:35,822 DEBUG [cloud.api.ApiServlet] (catalina-exec-13:null) ===END=== 192.168.16.111 -- GET command=deployVirtualMachine&zoneId=42530a8e-6dd4-4be3-9530-7448a165cd29&templateId=d3d6597a-9d6a-4f07-8ae2-e5930b248471&hypervisor=XenServer&serviceOfferingId=27c6028d-a43e-4fc2-b55b-ad9fa92074f5&diskOfferingId=f30cc1ae-0009-4c95-8d27-8fdf8b500aa7&displayname=liang&name=liang&response=json&sessionkey=hyZ3jyP7HScd6egutBy8MrCZpoI%3D&_=1384592075183 2013-11-16 00:54:35,828 DEBUG [cloud.api.ApiDispatcher] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) InfrastructureEntity name is:com.cloud.offering.ServiceOffering 2013-11-16 00:54:35,830 DEBUG [cloud.api.ApiDispatcher] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) ControlledEntity name is:com.cloud.template.VirtualMachineTemplate 2013-11-16 00:54:35,832 DEBUG [cloud.api.ApiDispatcher] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) InfrastructureEntity name is:com.cloud.offering.DiskOffering 2013-11-16 00:54:35,924 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Deploy avoids pods: [], clusters: [], hosts: [] 2013-11-16 00:54:35,929 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner_EnhancerByCloudStack_8232bcd9@1553b17c 2013-11-16 00:54:35,929 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Trying to allocate a host and storage pools from dc:1, pod:null,cluster:null, requested cpu: 500, requested ram: 536870912 2013-11-16 00:54:35,929 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Is ROOT volume READY (pool already allocated)?: No 2013-11-16 00:54:35,930 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Searching all possible resources under this Zone: 1 2013-11-16 00:54:35,931 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Listing clusters in order of aggregate capacity, that have (atleast one host with) enough CPU and RAM capacity under this Zone: 1 2013-11-16 00:54:35,943 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Removing from the clusterId list these clusters from avoid set: [] 2013-11-16 00:54:35,962 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Checking resources in Cluster: 1 under Pod: 1 2013-11-16 00:54:35,979 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Looking for hosts in dc: 1 pod:1 cluster:1 2013-11-16 00:54:35,993 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to check for allocation: [Host[-1-Routing]] 2013-11-16 00:54:35,998 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Not considering hosts: [Host[-1-Routing]] to deploy template: Tmpl[201-ISO-201-2-281cbc51-8e8c-350e-9c78-83cb5cab5ae4 as they are not HVM enabled 2013-11-16 00:54:35,998 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Found 0 hosts for allocation after prioritization: [] 2013-11-16 00:54:35,999 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Looking for speed=500Mhz, Ram=512 2013-11-16 00:54:35,999 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Host Allocator returning 0 suitable hosts 2013-11-16 00:54:35,999 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) No suitable hosts found 2013-11-16 00:54:35,999 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) No suitable hosts found under this Cluster: 1 2013-11-16 00:54:36,009 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Could not find suitable Deployment Destination for this VM under any clusters, returning. 2013-11-16 00:54:36,011 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Searching all possible resources under this Zone: 1 2013-11-16 00:54:36,014 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Listing clusters in order of aggregate capacity, that have (atleast one host with) enough CPU and RAM capacity under this Zone: 1 2013-11-16 00:54:36,024 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Removing from the clusterId list these clusters from avoid set: [] 2013-11-16 00:54:36,040 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Checking resources in Cluster: 1 under Pod: 1 2013-11-16 00:54:36,040 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Looking for hosts in dc: 1 pod:1 cluster:1 2013-11-16 00:54:36,047 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to check for allocation: [Host[-1-Routing]] 2013-11-16 00:54:36,050 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Not considering hosts: [Host[-1-Routing]] to deploy template: Tmpl[201-ISO-201-2-281cbc51-8e8c-350e-9c78-83cb5cab5ae4 as they are not HVM enabled 2013-11-16 00:54:36,050 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Found 0 hosts for allocation after prioritization: [] 2013-11-16 00:54:36,050 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Looking for speed=500Mhz, Ram=512 2013-11-16 00:54:36,050 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Host Allocator returning 0 suitable hosts 2013-11-16 00:54:36,050 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) No suitable hosts found 2013-11-16 00:54:36,050 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) No suitable hosts found under this Cluster: 1 2013-11-16 00:54:36,061 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Could not find suitable Deployment Destination for this VM under any clusters, returning. 2013-11-16 00:54:36,061 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Searching all possible resources under this Zone: 1 2013-11-16 00:54:36,062 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Listing clusters in order of aggregate capacity, that have (atleast one host with) enough CPU and RAM capacity under this Zone: 1 2013-11-16 00:54:36,072 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Removing from the clusterId list these clusters from avoid set: [] 2013-11-16 00:54:36,107 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Checking resources in Cluster: 1 under Pod: 1 2013-11-16 00:54:36,108 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Looking for hosts in dc: 1 pod:1 cluster:1 2013-11-16 00:54:36,114 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to check for allocation: [Host[-1-Routing]] 2013-11-16 00:54:36,117 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Not considering hosts: [Host[-1-Routing]] to deploy template: Tmpl[201-ISO-201-2-281cbc51-8e8c-350e-9c78-83cb5cab5ae4 as they are not HVM enabled 2013-11-16 00:54:36,117 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Found 0 hosts for allocation after prioritization: [] 2013-11-16 00:54:36,117 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Looking for speed=500Mhz, Ram=512 2013-11-16 00:54:36,117 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Host Allocator returning 0 suitable hosts 2013-11-16 00:54:36,117 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) No suitable hosts found 2013-11-16 00:54:36,117 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) No suitable hosts found under this Cluster: 1 2013-11-16 00:54:36,128 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Could not find suitable Deployment Destination for this VM under any clusters, returning. 2013-11-16 00:54:36,129 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Searching all possible resources under this Zone: 1 2013-11-16 00:54:36,131 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Listing clusters in order of aggregate capacity, that have (atleast one host with) enough CPU and RAM capacity under this Zone: 1 2013-11-16 00:54:36,139 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Removing from the clusterId list these clusters from avoid set: [] 2013-11-16 00:54:36,150 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Checking resources in Cluster: 1 under Pod: 1 2013-11-16 00:54:36,150 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Looking for hosts in dc: 1 pod:1 cluster:1 2013-11-16 00:54:36,162 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to check for allocation: [Host[-1-Routing]] 2013-11-16 00:54:36,165 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Not considering hosts: [Host[-1-Routing]] to deploy template: Tmpl[201-ISO-201-2-281cbc51-8e8c-350e-9c78-83cb5cab5ae4 as they are not HVM enabled 2013-11-16 00:54:36,165 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Found 0 hosts for allocation after prioritization: [] 2013-11-16 00:54:36,165 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Looking for speed=500Mhz, Ram=512 2013-11-16 00:54:36,165 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ] FirstFitRoutingAllocator) Host Allocator returning 0 suitable hosts 2013-11-16 00:54:36,165 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) No suitable hosts found 2013-11-16 00:54:36,165 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) No suitable hosts found under this Cluster: 1 2013-11-16 00:54:36,174 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-1:job-18 = [ 6f006332-08c9-4a30-997b-eceaf1f27c15 ]) Could not find suitable Deployment Destination for this VM under any clusters, returning.