目测是资源不足导致
2013-06-09
张晓飞
发件人：张秉南 <zbnyouj...@gmail.com>
发送时间：2013-06-09 17:16
主题：自己生成的模板不能创建虚拟机的问题
收件人："users-cn"<users-cn@cloudstack.apache.org>
抄送：
2013-06-09 16:33:45,900 DEBUG [cloud.server.StatsCollector]
(StatsCollector-2:null) HostStatsCollector is running...
2013-06-09 16:33:46,569 DEBUG [agent.transport.Request]
(StatsCollector-2:null) Seq 1-862126178: Received: { Ans: , MgmtId:
42645977839493, via: 1, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2013-06-09 16:33:47,077 DEBUG
[storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1
is ready to launch secondary storage VM
2013-06-09 16:33:47,153 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl]
(consoleproxy-1:null) Zone 1 is ready to launch console proxy
2013-06-09 16:33:47,195 DEBUG [agent.transport.Request]
(StatsCollector-2:null) Seq 6-373948592: Received: { Ans: , MgmtId:
42645977839493, via: 6, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2013-06-09 16:33:47,384 DEBUG
[network.router.VirtualNetworkApplianceManagerImpl]
(RouterStatusMonitor-1:null) Found 0 routers.
2013-06-09 16:33:47,859 DEBUG [agent.transport.Request]
(StatsCollector-2:null) Seq 7-1222180958: Received: { Ans: , MgmtId:
42645977839493, via: 7, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2013-06-09 16:33:51,384 DEBUG [cloud.server.StatsCollector]
(StatsCollector-2:null) StorageCollector is running...
2013-06-09 16:33:51,445 DEBUG [agent.transport.Request]
(StatsCollector-2:null) Seq 5-2010775630: Received: { Ans: , MgmtId:
42645977839493, via: 5, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2013-06-09 16:33:51,729 DEBUG [agent.transport.Request]
(StatsCollector-2:null) Seq 1-862126179: Received: { Ans: , MgmtId:
42645977839493, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2013-06-09 16:33:55,729 DEBUG [cloud.vm.UserVmManagerImpl]
(catalina-exec-4:null) Allocating in the DB for vm
2013-06-09 16:33:55,729 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(catalina-exec-4:null) Allocating entries for VM: VM[User|web05]
2013-06-09 16:33:55,734 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(catalina-exec-4:null) Allocating nics for VM[User|web05]
2013-06-09 16:33:55,735 DEBUG [cloud.network.NetworkManagerImpl]
(catalina-exec-4:null) Allocating nic for vm VM[User|web05] in network
Ntwk[204|Guest|6] with requested profile NicProfile[0-0-null-null-null
2013-06-09 16:33:55,745 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(catalina-exec-4:null) Allocaing disks for VM[User|web05]
2013-06-09 16:33:55,760 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(catalina-exec-4:null) Allocation completed for VM: VM[User|web05]
2013-06-09 16:33:55,761 DEBUG [cloud.vm.UserVmManagerImpl]
(catalina-exec-4:null) Successfully allocated DB entry for VM[User|web05]
2013-06-09 16:33:55,813 DEBUG [cloud.async.AsyncJobManagerImpl]
(catalina-exec-4:null) submit async job-34, details: AsyncJobVO {id:34,
userId: 2, accountId: 2, sessionKey: null, instanceType: VirtualMachine,
instanceId: 89, cmd: com.cloud.api.commands.DeployVMCmd, cmdOriginator:
null, cmdInfo:
{"sessionkey":"YUkbfJ3wL9FSaGKqQrXsydfN1Oo\u003d","ctxUserId":"2","serviceOfferingId":"c55c591a-8845-4326-86cb-6873fa3e4df9","zoneId":"72c53a56-728f-424a-a219-97fcbc0efce1","templateId":"40fceb2c-3d6a-42e3-828b-a280278be47c","response":"json","id":"89","hypervisor":"KVM","name":"web05","diskOfferingId":"e5208413-2d37-4f16-a927-adaae90d3805","_":"1370766835511","ctxAccountId":"2","ctxStartEventId":"127","displayname":"web05"},
cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0,
processStatus: 0, resultCode: 0, result: null, initMsid: 42645977839493,
completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
2013-06-09 16:33:55,814 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-3:job-34) Executing com.cloud.api.commands.DeployVMCmd for
job-34
2013-06-09 16:33:55,874 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34) VM state transitted from :Stopped to Starting with
event: StartRequestedvm's original host id: null new host id: null host id
before state transition: null
2013-06-09 16:33:55,874 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-3:job-34) Successfully transitioned to start state for
VM[User|web05] reservation id = 7f619259-e242-4020-9271-2dba65d0cdf6
2013-06-09 16:33:55,877 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-3:job-34) Trying to deploy VM, vm has dcId: 1 and podId: null
2013-06-09 16:33:55,877 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-3:job-34) Deploy avoids pods: null, clusters: null, hosts:
null
2013-06-09 16:33:55,880 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-3:job-34) DeploymentPlanner allocation algorithm: random
2013-06-09 16:33:55,880 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-3:job-34) Trying to allocate a host and storage pools from
dc:1, pod:null,cluster:null, requested cpu: 8000, requested ram: 12884901888
2013-06-09 16:33:55,880 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-3:job-34) Is ROOT volume READY (pool already allocated)?: No
2013-06-09 16:33:55,880 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-3:job-34) Searching all possible resources under this Zone: 1
2013-06-09 16:33:55,882 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-3:job-34) Listing clusters in order of aggregate capacity,
that have (atleast one host with) enough CPU and RAM capacity under this
Zone: 1
2013-06-09 16:33:55,883 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-3:job-34) CPUOverprovisioningFactor considered: 4.0
2013-06-09 16:33:55,894 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-3:job-34) Checking resources in Cluster: 1 under Pod: 1
2013-06-09 16:33:55,894 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-3:job-34) Calling HostAllocators to find suitable hosts
2013-06-09 16:33:55,894 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Looking for hosts in dc: 1
pod:1 cluster:1
2013-06-09 16:33:55,894 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Looking for hosts having
tag specified on SvcOffering:web
2013-06-09 16:33:55,897 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Hosts with tag 'web'
are:[Host[-1-Routing], Host[-6-Routing], Host[-7-Routing]]
2013-06-09 16:33:55,897 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) FirstFitAllocator has 3
hosts to check for allocation: [Host[-6-Routing], Host[-1-Routing],
Host[-7-Routing]]
2013-06-09 16:33:55,902 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Found 3 hosts for
allocation after prioritization: [Host[-6-Routing], Host[-1-Routing],
Host[-7-Routing]]
2013-06-09 16:33:55,902 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Looking for speed=8000Mhz,
Ram=12288
2013-06-09 16:33:55,904 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Checking if host: 6 has
enough capacity for requested CPU: 8000 and requested RAM: 12884901888 ,
cpuOverprovisioningFactor: 4.0
2013-06-09 16:33:55,907 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Hosts's actual total CPU:
40416 and CPU after applying overprovisioning: 161664
2013-06-09 16:33:55,907 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Free CPU: 153664 ,
Requested CPU: 8000
2013-06-09 16:33:55,908 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Free RAM: 12300541952 ,
Requested RAM: 12884901888
2013-06-09 16:33:55,908 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) STATS: Failed to alloc
resource from host: 6 reservedCpu: 0, used cpu: 8000, requested cpu: 8000,
actual total cpu: 40416, total cpu with overprovisioning: 161664,
reservedMem: 0, used Mem: 12884901888, requested mem: 12884901888, total
Mem:25185443840 ,considerReservedCapacity?: true
2013-06-09 16:33:55,908 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Host does not have enough
RAM available, cannot allocate to this host.
2013-06-09 16:33:55,908 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Not using host 6;
numCpusGood: true; cpuFreqGood: true, host has capacity?false
2013-06-09 16:33:55,910 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Checking if host: 1 has
enough capacity for requested CPU: 8000 and requested RAM: 12884901888 ,
cpuOverprovisioningFactor: 4.0
2013-06-09 16:33:55,913 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Hosts's actual total CPU:
64000 and CPU after applying overprovisioning: 256000
2013-06-09 16:33:55,913 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Free CPU: 254500 ,
Requested CPU: 8000
2013-06-09 16:33:55,913 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Free RAM: 49085001728 ,
Requested RAM: 12884901888
2013-06-09 16:33:55,913 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Host has enough CPU and
RAM available
2013-06-09 16:33:55,913 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) STATS: Can alloc CPU from
host: 1, used: 1500, reserved: 0, actual total: 64000, total with
overprovisioning: 256000; requested cpu:8000,alloc_from_last_host?:false
,considerReservedCapacity?: true
2013-06-09 16:33:55,913 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) STATS: Can alloc MEM from
host: 1, used: 1476395008, reserved: 0, total: 50561396736; requested mem:
12884901888,alloc_from_last_host?:false ,considerReservedCapacity?: true
2013-06-09 16:33:55,914 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Not using host 1;
numCpusGood: true; cpuFreqGood: false, host has capacity?true
2013-06-09 16:33:55,916 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Checking if host: 7 has
enough capacity for requested CPU: 8000 and requested RAM: 12884901888 ,
cpuOverprovisioningFactor: 4.0
2013-06-09 16:33:55,918 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Hosts's actual total CPU:
64000 and CPU after applying overprovisioning: 256000
2013-06-09 16:33:55,919 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Free CPU: 256000 ,
Requested CPU: 8000
2013-06-09 16:33:55,919 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Free RAM: 50565599232 ,
Requested RAM: 12884901888
2013-06-09 16:33:55,919 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Host has enough CPU and
RAM available
2013-06-09 16:33:55,919 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) STATS: Can alloc CPU from
host: 7, used: 0, reserved: 0, actual total: 64000, total with
overprovisioning: 256000; requested cpu:8000,alloc_from_last_host?:false
,considerReservedCapacity?: true
2013-06-09 16:33:55,919 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) STATS: Can alloc MEM from
host: 7, used: 0, reserved: 0, total: 50565599232; requested mem:
12884901888,alloc_from_last_host?:false ,considerReservedCapacity?: true
2013-06-09 16:33:55,919 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Not using host 7;
numCpusGood: true; cpuFreqGood: false, host has capacity?true
2013-06-09 16:33:55,919 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-3:job-34 FirstFitRoutingAllocator) Host Allocator returning 0
suitable hosts
2013-06-09 16:33:55,919 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-3:job-34) No suitable hosts found
2013-06-09 16:33:55,919 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-3:job-34) No suitable hosts found under this Cluster: 1
2013-06-09 16:33:55,919 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-3:job-34) Could not find suitable Deployment Destination for
this VM under any clusters, returning.
2013-06-09 16:33:55,924 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34) VM state transitted from :Starting to Stopped with
event: OperationFailedvm's original host id: null new host id: null host id
before state transition: null
2013-06-09 16:33:55,928 DEBUG [cloud.vm.UserVmManagerImpl]
(Job-Executor-3:job-34) Destroying vm VM[User|web05] as it failed to create
2013-06-09 16:33:55,934 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-3:job-34) VM state transitted from :Stopped to Error with
event: OperationFailedToErrorvm's original host id: null new host id: null
host id before state transition: null
2013-06-09 16:33:55,981 INFO [api.commands.DeployVMCmd]
(Job-Executor-3:job-34)
com.cloud.exception.InsufficientServerCapacityException: Unable to create a
deployment for VM[User|web05]Scope=interface com.cloud.dc.DataCenter; id=1
2013-06-09 16:33:55,982 WARN [cloud.api.ApiDispatcher]
(Job-Executor-3:job-34) class com.cloud.api.ServerApiException : Unable to
create a deployment for VM[User|web05]
2013-06-09 16:33:55,982 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-3:job-34) Complete async job-34, jobStatus: 2, resultCode:
530, result: Error Code: 533 Error text: Unable to create a deployment for
VM[User|web05]
2013-06-09 16:34:00,892 DEBUG [cloud.async.AsyncJobManagerImpl]
(catalina-exec-6:null) Async job-34 completed
2013-06-09 16:34:00,941 DEBUG [cloud.vm.UserVmManagerImpl]
(catalina-exec-2:null) THE WHERE CLAUSE IS:user_vm.id = ?
