You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users-cn@cloudstack.apache.org by 余文兵 <bi...@163.com> on 2013/02/22 09:26:50 UTC
vms创建失败
采用cloudstack4.0.1和KVM的环境，主存储采用NFS共享挂在host主机上，二级存储也采用NFS共享挂在管理服务器上。
在host主机上拥有两个主存储目录，都新建了tag（primary1和primary2），在计算资源上创建了Large Instance采用的存储标签设置为主存储的一样的primary2（也就是在这种计算资源下创建的vms采用的是主存储primary2），但是vms一直创建失败，查看日志信息，没有找到有相关vms创建失败的信息。
2013-02-22 15:53:25,750 DEBUG [cloud.vm.VirtualMachineManagerImpl] (catalina-exec-5:null) Allocaing disks for
2013-02-22 15:53:25,773 DEBUG [cloud.vm.VirtualMachineManagerImpl] (catalina-exec-5:null) Allocation complete
2013-02-22 15:53:25,774 DEBUG [cloud.vm.UserVmManagerImpl] (catalina-exec-5:null) Successfully allocated DB e
2013-02-22 15:53:26,218 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-5:null) submit async job-48, d 2, accountId: 2, sessionKey: null, instanceType: VirtualMachine, instanceId: 19, cmd: com.cloud.api.commands cmdInfo: {"sessionkey":"sZgX9alAaebzE3rd4/tx0rfiKgk\u003d","ctxUserId":"2","serviceOfferingId":"2439cb41-8a2:"006cd875-177d-4758-b22e-5544c3afb070","templateId":"3a356cd2-85c2-4e6b-bd8d-a87fc8c73d3a","response":"json""name":"win3","diskOfferingId":"15f6782c-694e-4dab-8968-6d65313aba18","_":"1361519611305","ctxAccountId":"2",198","displayname":"win3"}, cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0, processStatus: itMsid: 159090354587057, completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
2013-02-22 15:53:26,222 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-1:job-48) Executing com.cloud.a
2013-02-22 15:53:26,555 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-1:job-48) VM state transittevent: StartRequestedvm's original host id: null new host id: null host id before state transition: null
2013-02-22 15:53:26,556 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-1:job-48) Successfully trans|win3] reservation id = 49e28e23-c7de-4304-9071-3815ad246978
2013-02-22 15:53:26,616 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-1:job-48) Trying to deploy V
2013-02-22 15:53:26,616 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-1:job-48) Deploy avoids podsl
2013-02-22 15:53:26,628 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-48) DeploymentPlanner alloca
2013-02-22 15:53:26,628 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-48) Trying to allocate a hos:null,cluster:null, requested cpu: 8800, requested ram: 4294967296
2013-02-22 15:53:26,628 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-48) Is ROOT volume READY (po
2013-02-22 15:53:26,628 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-48) Searching all possible r
2013-02-22 15:53:26,632 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-48) Listing clusters in orde (atleast one host with) enough CPU and RAM capacity under this Zone: 1
2013-02-22 15:53:26,633 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-48) CPUOverprovisioningFacto
2013-02-22 15:53:26,637 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-1:job-48) No clusters found havingrning.
2013-02-22 15:53:26,750 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-1:job-48) VM state transittevent: OperationFailedvm's original host id: null new host id: null host id before state transition: null
2013-02-22 15:53:26,757 DEBUG [cloud.vm.UserVmManagerImpl] (Job-Executor-1:job-48) Destroying vm VM[User|win3
2013-02-22 15:53:26,861 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-1:job-48) VM state transittet: OperationFailedToErrorvm's original host id: null new host id: null host id before state transition: null
2013-02-22 15:53:27,288 INFO [api.commands.DeployVMCmd] (Job-Executor-1:job-48) com.cloud.exception.Insuffice to create a deployment for VM[User|win3]Scope=interface com.cloud.dc.DataCenter; id=1
2013-02-22 15:53:27,288 WARN [cloud.api.ApiDispatcher] (Job-Executor-1:job-48) class com.cloud.api.ServerApiloyment for VM[User|win3]
2013-02-22 15:53:27,289 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-1:job-48) Complete async job-48esult: Error Code: 533 Error text: Unable to create a deployment for VM[User|win3]
2013-02-22 15:53:31,353 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-25:null) Async job-48 complete
2013-02-22 15:53:31,406 DEBUG [cloud.vm.UserVmManagerImpl] (catalina-exec-23:null) THE WHERE CLAUSE IS:user_v
2013-02-22 15:53:37,524 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is rM
2013-02-22 15:53:37,611 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is re
2013-02-22 15:53:38,038 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null
2013-02-22 15:54:07,523 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is rM
2013-02-22 15:54:07,611 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is re
2013-02-22 15:54:08,038 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null
2013-02-22 15:54:09,434 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-12:null) Ping from 1
2013-02-22 15:54:09,526 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-13:null) Ping from 2
2013-02-22 15:54:11,250 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-14:null) Ping from 3
2013-02-22 15:54:23,156 DEBUG [cloud.server.StatsCollector] (StatsCollector-3:null) VmStatsCollector is runni
2013-02-22 15:54:23,384 DEBUG [agent.transport.Request] (StatsCollector-3:null) Seq 3-1959002135: Received: via: 3, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2013-02-22 15:54:23,564 DEBUG [cloud.server.StatsCollector] (StatsCollector-3:null) StorageCollector is runni
2013-02-22 15:54:23,628 DEBUG [agent.transport.Request] (StatsCollector-3:null) Seq 1-199032843: Received: {ia: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2013-02-22 15:54:23,738 DEBUG [agent.transport.Request] (StatsCollector-3:null) Seq 3-1959002136: Received: via: 3, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2013-02-22 15:54:23,876 DEBUG [agent.transport.Request] (StatsCollector-3:null) Seq 3-1959002137: Received: via: 3, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2013-02-22 15:54:24,880 DEBUG [cloud.server.StatsCollector] (StatsCollector-3:null) HostStatsCollector is run
2013-02-22 15:54:25,503 DEBUG [agent.transport.Request] (StatsCollector-3:null) Seq 3-1959002138: Received: via: 3, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2013-02-22 15:54:37,523 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is rM
2013-02-22 15:54:37,610 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is re
2013-02-22 15:54:38,038 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null
2013-02-22 15:55:07,523 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is rM
2013-02-22 15:55:07,610 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is re
2013-02-22 15:55:07,969 DEBUG [storage.snapshot.SnapshotSchedulerImpl] (SnapshotPollTask:null) Snapshot sched02-22 07:55:07 GMT
2013-02-22 15:55:07,972 DEBUG [storage.snapshot.SnapshotSchedulerImpl] (SnapshotPollTask:null) Got 0 snapshot55:07 GMT
2013-02-22 15:55:07,987 DEBUG [cloud.network.ExternalLoadBalancerUsageManagerImpl] (ExternalNetworkMonitor-1:es stats collector is running...
2013-02-22 15:55:08,037 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterMonitor-1:null) Foun
2013-02-22 15:55:08,038 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null
2013-02-22 15:55:09,436 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-7:null) Ping from 1
2013-02-22 15:55:09,528 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-11:null) Ping from 2
2013-02-22 15:55:11,309 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-8:null) Ping from 3
2013-02-22 15:55:23,384 DEBUG [cloud.server.StatsCollector] (StatsCollector-3:null) VmStatsCollector is runni
2013-02-22 15:55:23,618 DEBUG [agent.transport.Request] (StatsCollector-3:null) Seq 3-1959002139: Received: via: 3, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2013-02-22 15:55:23,876 DEBUG [cloud.server.StatsCollector] (StatsCollector-3:null) StorageCollector is runni
2013-02-22 15:55:23,937 DEBUG [agent.transport.Request] (StatsCollector-3:null) Seq 1-199032844: Received: {ia: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2013-02-22 15:55:24,047 DEBUG [agent.transport.Request] (StatsCollector-3:null) Seq 3-1959002140: Received: via: 3, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2013-02-22 15:55:24,185 DEBUG [agent.transport.Request] (StatsCollector-3:null) Seq 3-1959002141: Received: via: 3, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2013-02-22 15:55:25,504 DEBUG [cloud.server.StatsCollector] (StatsCollector-1:null) HostStatsCollector is run
2013-02-22 15:55:26,131 DEBUG [agent.transport.Request] (StatsCollector-1:null) Seq 3-1959002142: Received: via: 3, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2013-02-22 15:55:37,524 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is rM
2013-02-22 15:55:37,611 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is re
2013-02-22 15:55:37,953 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) Running Capacity Checker
2013-02-22 15:55:37,953 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) recalculating system capa
2013-02-22 15:55:37,953 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) Executing cpu/ram capacit
2013-02-22 15:55:37,969 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 5 VMs on host
2013-02-22 15:55:37,971 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 0 VM, not run
2013-02-22 15:55:37,975 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrat500 reservedCpu: 0
2013-02-22 15:55:37,975 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrat 6845104128 reservedMem: 0
2013-02-22 15:55:37,975 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) Done executing cpu/ram ca
2013-02-22 15:55:37,975 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) Executing storage capacit
2013-02-22 15:55:38,038 DEBUG [cloud.storage.StorageManagerImpl] (CapacityChecker:null) Successfully set Capaype - 3 , DataCenterId - 1, HostOrPoolId - 200, PodId 1
2013-02-22 15:55:38,038 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null
2013-02-22 15:55:38,044 DEBUG [cloud.storage.StorageManagerImpl] (CapacityChecker:null) Successfully set Capatype - 3 , DataCenterId - 1, HostOrPoolId - 204, PodId 1
2013-02-22 15:55:38,044 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) Done executing storage ca
2013-02-22 15:55:38,044 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) Executing capacity update
2013-02-22 15:55:38,056 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) Done capacity updates for
2013-02-22 15:55:38,056 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) Executing capacity update
2013-02-22 15:55:38,067 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) Done executing capacity u
2013-02-22 15:55:38,067 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) Done recalculating system
2013-02-22 15:55:38,086 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) System Alert: Low Availabd geipod of availablity zone geizone
2013-02-22 15:55:38,086 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) System memory is low, tot)
2013-02-22 15:55:38,089 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) Have already sent: 1 emaisend email
2013-02-22 15:55:38,096 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) Done running Capacity Che
2013-02-22 15:56:07,524 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is rM
2013-02-22 15:56:07,611 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is re
2013-02-22 15:56:08,039 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null
2013-02-22 15:56:09,437 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-13:null) Ping from 1
2013-02-22 15:56:09,528 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-14:null) Ping from 2
2013-02-22 15:56:11,299 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-15:null) Ping from 3
2013-02-22 15:56:23,619 DEBUG [cloud.server.StatsCollector] (StatsCollector-1:null) VmStatsCollector is runni
2013-02-22 15:56:23,850 DEBUG [agent.transport.Request] (StatsCollector-1:null) Seq 3-1959002143: Received: via: 3, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2013-02-22 15:56:24,185 DEBUG [cloud.server.StatsCollector] (StatsCollector-1:null) StorageCollector is runni
2013-02-22 15:56:24,246 DEBUG [agent.transport.Request] (StatsCollector-1:null) Seq 1-199032845: Received: {ia: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2013-02-22 15:56:24,358 DEBUG [agent.transport.Request] (StatsCollector-1:null) Seq 3-1959002144: Received: via: 3, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2013-02-22 15:56:24,494 DEBUG [agent.transport.Request] (StatsCollector-1:null) Seq 3-1959002145: Received: via: 3, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2013-02-22 15:56:26,131 DEBUG [cloud.server.StatsCollector] (StatsCollector-1:null) HostStatsCollector is run
2013-02-22 15:56:26,766 DEBUG [agent.transport.Request] (StatsCollector-1:null) Seq 3-1959002146: Received: via: 3, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2013-02-22 15:56:37,523 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is rM
2013-02-22 15:56:37,610 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is re
2013-02-22 15:56:38,038 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null
Re: vms创建失败
Posted by 马营 <br...@gmail.com>.
2013-02-22 15:53:26,637 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-1:job-48) No clusters found havingrning.
2013-02-22 15:53:26,750 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-1:job-48) VM state transittevent: OperationFailedvm's
original host id: null new host id: null host id before state transition:
null
2013-02-22 15:53:26,757 DEBUG [cloud.vm.UserVmManagerImpl]
(Job-Executor-1:job-48) Destroying vm VM[User|win3
2013-02-22 15:53:26,861 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-1:job-48) VM state transittet: OperationFailedToErrorvm's
original host id: null new host id: null host id before state transition:
null
2013-02-22 15:53:27,288 INFO [api.commands.DeployVMCmd]
(Job-Executor-1:job-48) com.cloud.exception.Insuffice to create a
deployment for VM[User|win3]Scope=interface com.cloud.dc.DataCenter; id=1
2013-02-22 15:53:27,288 WARN [cloud.api.ApiDispatcher]
(Job-Executor-1:job-48) class com.cloud.api.ServerApiloyment for
VM[User|win3]
2013-02-22 15:53:27,289 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-1:job-48) Complete async job-48esult: Error Code: 533 Error
text: Unable to create a deployment for VM[User|win3]
从这边开始可以看到cluster的状态没有running，所以最后unable deploy实例
2013/2/22 余文兵 <bi...@163.com>
> 采用cloudstack4.0.1和KVM的环境，主存储采用NFS共享挂在host主机上，二级存储也采用NFS共享挂在管理服务器上。
> 在host主机上拥有两个主存储目录，都新建了tag（primary1和primary2），在计算资源上创建了Large
> Instance采用的存储标签设置为主存储的一样的primary2（也就是在这种计算资源下创建的vms采用的是主存储primary2），但是vms一直创建失败，查看日志信息，没有找到有相关vms创建失败的信息。
>
>
>
>
>
> 2013-02-22 15:53:25,750 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (catalina-exec-5:null) Allocaing disks for
> 2013-02-22 15:53:25,773 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (catalina-exec-5:null) Allocation complete
> 2013-02-22 15:53:25,774 DEBUG [cloud.vm.UserVmManagerImpl]
> (catalina-exec-5:null) Successfully allocated DB e
> 2013-02-22 15:53:26,218 DEBUG [cloud.async.AsyncJobManagerImpl]
> (catalina-exec-5:null) submit async job-48, d 2, accountId: 2, sessionKey:
> null, instanceType: VirtualMachine, instanceId: 19, cmd:
> com.cloud.api.commands cmdInfo:
> {"sessionkey":"sZgX9alAaebzE3rd4/tx0rfiKgk\u003d","ctxUserId":"2","serviceOfferingId":"2439cb41-8a2:"006cd875-177d-4758-b22e-5544c3afb070","templateId":"3a356cd2-85c2-4e6b-bd8d-a87fc8c73d3a","response":"json""name":"win3","diskOfferingId":"15f6782c-694e-4dab-8968-6d65313aba18","_":"1361519611305","ctxAccountId":"2",198","displayname":"win3"},
> cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0,
> processStatus: itMsid: 159090354587057, completeMsid: null, lastUpdated:
> null, lastPolled: null, created: null}
> 2013-02-22 15:53:26,222 DEBUG [cloud.async.AsyncJobManagerImpl]
> (Job-Executor-1:job-48) Executing com.cloud.a
> 2013-02-22 15:53:26,555 DEBUG [cloud.capacity.CapacityManagerImpl]
> (Job-Executor-1:job-48) VM state transittevent: StartRequestedvm's original
> host id: null new host id: null host id before state transition: null
> 2013-02-22 15:53:26,556 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (Job-Executor-1:job-48) Successfully trans|win3] reservation id =
> 49e28e23-c7de-4304-9071-3815ad246978
> 2013-02-22 15:53:26,616 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (Job-Executor-1:job-48) Trying to deploy V
> 2013-02-22 15:53:26,616 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (Job-Executor-1:job-48) Deploy avoids podsl
> 2013-02-22 15:53:26,628 DEBUG [cloud.deploy.FirstFitPlanner]
> (Job-Executor-1:job-48) DeploymentPlanner alloca
> 2013-02-22 15:53:26,628 DEBUG [cloud.deploy.FirstFitPlanner]
> (Job-Executor-1:job-48) Trying to allocate a hos:null,cluster:null,
> requested cpu: 8800, requested ram: 4294967296
> 2013-02-22 15:53:26,628 DEBUG [cloud.deploy.FirstFitPlanner]
> (Job-Executor-1:job-48) Is ROOT volume READY (po
> 2013-02-22 15:53:26,628 DEBUG [cloud.deploy.FirstFitPlanner]
> (Job-Executor-1:job-48) Searching all possible r
> 2013-02-22 15:53:26,632 DEBUG [cloud.deploy.FirstFitPlanner]
> (Job-Executor-1:job-48) Listing clusters in orde (atleast one host with)
> enough CPU and RAM capacity under this Zone: 1
> 2013-02-22 15:53:26,633 DEBUG [cloud.deploy.FirstFitPlanner]
> (Job-Executor-1:job-48) CPUOverprovisioningFacto
> 2013-02-22 15:53:26,637 DEBUG [cloud.deploy.FirstFitPlanner]
> (Job-Executor-1:job-48) No clusters found havingrning.
> 2013-02-22 15:53:26,750 DEBUG [cloud.capacity.CapacityManagerImpl]
> (Job-Executor-1:job-48) VM state transittevent: OperationFailedvm's
> original host id: null new host id: null host id before state transition:
> null
> 2013-02-22 15:53:26,757 DEBUG [cloud.vm.UserVmManagerImpl]
> (Job-Executor-1:job-48) Destroying vm VM[User|win3
> 2013-02-22 15:53:26,861 DEBUG [cloud.capacity.CapacityManagerImpl]
> (Job-Executor-1:job-48) VM state transittet: OperationFailedToErrorvm's
> original host id: null new host id: null host id before state transition:
> null
> 2013-02-22 15:53:27,288 INFO [api.commands.DeployVMCmd]
> (Job-Executor-1:job-48) com.cloud.exception.Insuffice to create a
> deployment for VM[User|win3]Scope=interface com.cloud.dc.DataCenter; id=1
> 2013-02-22 15:53:27,288 WARN [cloud.api.ApiDispatcher]
> (Job-Executor-1:job-48) class com.cloud.api.ServerApiloyment for
> VM[User|win3]
> 2013-02-22 15:53:27,289 DEBUG [cloud.async.AsyncJobManagerImpl]
> (Job-Executor-1:job-48) Complete async job-48esult: Error Code: 533 Error
> text: Unable to create a deployment for VM[User|win3]
> 2013-02-22 15:53:31,353 DEBUG [cloud.async.AsyncJobManagerImpl]
> (catalina-exec-25:null) Async job-48 complete
> 2013-02-22 15:53:31,406 DEBUG [cloud.vm.UserVmManagerImpl]
> (catalina-exec-23:null) THE WHERE CLAUSE IS:user_v
> 2013-02-22 15:53:37,524 DEBUG
> [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1
> is rM
> 2013-02-22 15:53:37,611 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl]
> (consoleproxy-1:null) Zone 1 is re
> 2013-02-22 15:53:38,038 DEBUG
> [network.router.VirtualNetworkApplianceManagerImpl]
> (RouterStatusMonitor-1:null
> 2013-02-22 15:54:07,523 DEBUG
> [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1
> is rM
> 2013-02-22 15:54:07,611 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl]
> (consoleproxy-1:null) Zone 1 is re
> 2013-02-22 15:54:08,038 DEBUG
> [network.router.VirtualNetworkApplianceManagerImpl]
> (RouterStatusMonitor-1:null
> 2013-02-22 15:54:09,434 DEBUG [agent.manager.AgentManagerImpl]
> (AgentManager-Handler-12:null) Ping from 1
> 2013-02-22 15:54:09,526 DEBUG [agent.manager.AgentManagerImpl]
> (AgentManager-Handler-13:null) Ping from 2
> 2013-02-22 15:54:11,250 DEBUG [agent.manager.AgentManagerImpl]
> (AgentManager-Handler-14:null) Ping from 3
> 2013-02-22 15:54:23,156 DEBUG [cloud.server.StatsCollector]
> (StatsCollector-3:null) VmStatsCollector is runni
> 2013-02-22 15:54:23,384 DEBUG [agent.transport.Request]
> (StatsCollector-3:null) Seq 3-1959002135: Received: via: 3, Ver: v1,
> Flags: 10, { GetVmStatsAnswer } }
> 2013-02-22 15:54:23,564 DEBUG [cloud.server.StatsCollector]
> (StatsCollector-3:null) StorageCollector is runni
> 2013-02-22 15:54:23,628 DEBUG [agent.transport.Request]
> (StatsCollector-3:null) Seq 1-199032843: Received: {ia: 1, Ver: v1, Flags:
> 10, { GetStorageStatsAnswer } }
> 2013-02-22 15:54:23,738 DEBUG [agent.transport.Request]
> (StatsCollector-3:null) Seq 3-1959002136: Received: via: 3, Ver: v1,
> Flags: 10, { GetStorageStatsAnswer } }
> 2013-02-22 15:54:23,876 DEBUG [agent.transport.Request]
> (StatsCollector-3:null) Seq 3-1959002137: Received: via: 3, Ver: v1,
> Flags: 10, { GetStorageStatsAnswer } }
> 2013-02-22 15:54:24,880 DEBUG [cloud.server.StatsCollector]
> (StatsCollector-3:null) HostStatsCollector is run
> 2013-02-22 15:54:25,503 DEBUG [agent.transport.Request]
> (StatsCollector-3:null) Seq 3-1959002138: Received: via: 3, Ver: v1,
> Flags: 10, { GetHostStatsAnswer } }
> 2013-02-22 15:54:37,523 DEBUG
> [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1
> is rM
> 2013-02-22 15:54:37,610 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl]
> (consoleproxy-1:null) Zone 1 is re
> 2013-02-22 15:54:38,038 DEBUG
> [network.router.VirtualNetworkApplianceManagerImpl]
> (RouterStatusMonitor-1:null
> 2013-02-22 15:55:07,523 DEBUG
> [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1
> is rM
> 2013-02-22 15:55:07,610 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl]
> (consoleproxy-1:null) Zone 1 is re
> 2013-02-22 15:55:07,969 DEBUG [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Snapshot sched02-22 07:55:07 GMT
> 2013-02-22 15:55:07,972 DEBUG [storage.snapshot.SnapshotSchedulerImpl]
> (SnapshotPollTask:null) Got 0 snapshot55:07 GMT
> 2013-02-22 15:55:07,987 DEBUG
> [cloud.network.ExternalLoadBalancerUsageManagerImpl]
> (ExternalNetworkMonitor-1:es stats collector is running...
> 2013-02-22 15:55:08,037 DEBUG
> [network.router.VirtualNetworkApplianceManagerImpl] (RouterMonitor-1:null)
> Foun
> 2013-02-22 15:55:08,038 DEBUG
> [network.router.VirtualNetworkApplianceManagerImpl]
> (RouterStatusMonitor-1:null
> 2013-02-22 15:55:09,436 DEBUG [agent.manager.AgentManagerImpl]
> (AgentManager-Handler-7:null) Ping from 1
> 2013-02-22 15:55:09,528 DEBUG [agent.manager.AgentManagerImpl]
> (AgentManager-Handler-11:null) Ping from 2
> 2013-02-22 15:55:11,309 DEBUG [agent.manager.AgentManagerImpl]
> (AgentManager-Handler-8:null) Ping from 3
> 2013-02-22 15:55:23,384 DEBUG [cloud.server.StatsCollector]
> (StatsCollector-3:null) VmStatsCollector is runni
> 2013-02-22 15:55:23,618 DEBUG [agent.transport.Request]
> (StatsCollector-3:null) Seq 3-1959002139: Received: via: 3, Ver: v1,
> Flags: 10, { GetVmStatsAnswer } }
> 2013-02-22 15:55:23,876 DEBUG [cloud.server.StatsCollector]
> (StatsCollector-3:null) StorageCollector is runni
> 2013-02-22 15:55:23,937 DEBUG [agent.transport.Request]
> (StatsCollector-3:null) Seq 1-199032844: Received: {ia: 1, Ver: v1, Flags:
> 10, { GetStorageStatsAnswer } }
> 2013-02-22 15:55:24,047 DEBUG [agent.transport.Request]
> (StatsCollector-3:null) Seq 3-1959002140: Received: via: 3, Ver: v1,
> Flags: 10, { GetStorageStatsAnswer } }
> 2013-02-22 15:55:24,185 DEBUG [agent.transport.Request]
> (StatsCollector-3:null) Seq 3-1959002141: Received: via: 3, Ver: v1,
> Flags: 10, { GetStorageStatsAnswer } }
> 2013-02-22 15:55:25,504 DEBUG [cloud.server.StatsCollector]
> (StatsCollector-1:null) HostStatsCollector is run
> 2013-02-22 15:55:26,131 DEBUG [agent.transport.Request]
> (StatsCollector-1:null) Seq 3-1959002142: Received: via: 3, Ver: v1,
> Flags: 10, { GetHostStatsAnswer } }
> 2013-02-22 15:55:37,524 DEBUG
> [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1
> is rM
> 2013-02-22 15:55:37,611 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl]
> (consoleproxy-1:null) Zone 1 is re
> 2013-02-22 15:55:37,953 DEBUG [cloud.alert.AlertManagerImpl]
> (CapacityChecker:null) Running Capacity Checker
> 2013-02-22 15:55:37,953 DEBUG [cloud.alert.AlertManagerImpl]
> (CapacityChecker:null) recalculating system capa
> 2013-02-22 15:55:37,953 DEBUG [cloud.alert.AlertManagerImpl]
> (CapacityChecker:null) Executing cpu/ram capacit
> 2013-02-22 15:55:37,969 DEBUG [cloud.capacity.CapacityManagerImpl]
> (CapacityChecker:null) Found 5 VMs on host
> 2013-02-22 15:55:37,971 DEBUG [cloud.capacity.CapacityManagerImpl]
> (CapacityChecker:null) Found 0 VM, not run
> 2013-02-22 15:55:37,975 DEBUG [cloud.capacity.CapacityManagerImpl]
> (CapacityChecker:null) No need to calibrat500 reservedCpu: 0
> 2013-02-22 15:55:37,975 DEBUG [cloud.capacity.CapacityManagerImpl]
> (CapacityChecker:null) No need to calibrat 6845104128 reservedMem: 0
> 2013-02-22 15:55:37,975 DEBUG [cloud.alert.AlertManagerImpl]
> (CapacityChecker:null) Done executing cpu/ram ca
> 2013-02-22 15:55:37,975 DEBUG [cloud.alert.AlertManagerImpl]
> (CapacityChecker:null) Executing storage capacit
> 2013-02-22 15:55:38,038 DEBUG [cloud.storage.StorageManagerImpl]
> (CapacityChecker:null) Successfully set Capaype - 3 , DataCenterId - 1,
> HostOrPoolId - 200, PodId 1
> 2013-02-22 15:55:38,038 DEBUG
> [network.router.VirtualNetworkApplianceManagerImpl]
> (RouterStatusMonitor-1:null
> 2013-02-22 15:55:38,044 DEBUG [cloud.storage.StorageManagerImpl]
> (CapacityChecker:null) Successfully set Capatype - 3 , DataCenterId - 1,
> HostOrPoolId - 204, PodId 1
> 2013-02-22 15:55:38,044 DEBUG [cloud.alert.AlertManagerImpl]
> (CapacityChecker:null) Done executing storage ca
> 2013-02-22 15:55:38,044 DEBUG [cloud.alert.AlertManagerImpl]
> (CapacityChecker:null) Executing capacity update
> 2013-02-22 15:55:38,056 DEBUG [cloud.alert.AlertManagerImpl]
> (CapacityChecker:null) Done capacity updates for
> 2013-02-22 15:55:38,056 DEBUG [cloud.alert.AlertManagerImpl]
> (CapacityChecker:null) Executing capacity update
> 2013-02-22 15:55:38,067 DEBUG [cloud.alert.AlertManagerImpl]
> (CapacityChecker:null) Done executing capacity u
> 2013-02-22 15:55:38,067 DEBUG [cloud.alert.AlertManagerImpl]
> (CapacityChecker:null) Done recalculating system
> 2013-02-22 15:55:38,086 DEBUG [cloud.alert.AlertManagerImpl]
> (CapacityChecker:null) System Alert: Low Availabd geipod of availablity
> zone geizone
> 2013-02-22 15:55:38,086 DEBUG [cloud.alert.AlertManagerImpl]
> (CapacityChecker:null) System memory is low, tot)
> 2013-02-22 15:55:38,089 DEBUG [cloud.alert.AlertManagerImpl]
> (CapacityChecker:null) Have already sent: 1 emaisend email
> 2013-02-22 15:55:38,096 DEBUG [cloud.alert.AlertManagerImpl]
> (CapacityChecker:null) Done running Capacity Che
> 2013-02-22 15:56:07,524 DEBUG
> [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1
> is rM
> 2013-02-22 15:56:07,611 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl]
> (consoleproxy-1:null) Zone 1 is re
> 2013-02-22 15:56:08,039 DEBUG
> [network.router.VirtualNetworkApplianceManagerImpl]
> (RouterStatusMonitor-1:null
> 2013-02-22 15:56:09,437 DEBUG [agent.manager.AgentManagerImpl]
> (AgentManager-Handler-13:null) Ping from 1
> 2013-02-22 15:56:09,528 DEBUG [agent.manager.AgentManagerImpl]
> (AgentManager-Handler-14:null) Ping from 2
> 2013-02-22 15:56:11,299 DEBUG [agent.manager.AgentManagerImpl]
> (AgentManager-Handler-15:null) Ping from 3
> 2013-02-22 15:56:23,619 DEBUG [cloud.server.StatsCollector]
> (StatsCollector-1:null) VmStatsCollector is runni
> 2013-02-22 15:56:23,850 DEBUG [agent.transport.Request]
> (StatsCollector-1:null) Seq 3-1959002143: Received: via: 3, Ver: v1,
> Flags: 10, { GetVmStatsAnswer } }
> 2013-02-22 15:56:24,185 DEBUG [cloud.server.StatsCollector]
> (StatsCollector-1:null) StorageCollector is runni
> 2013-02-22 15:56:24,246 DEBUG [agent.transport.Request]
> (StatsCollector-1:null) Seq 1-199032845: Received: {ia: 1, Ver: v1, Flags:
> 10, { GetStorageStatsAnswer } }
> 2013-02-22 15:56:24,358 DEBUG [agent.transport.Request]
> (StatsCollector-1:null) Seq 3-1959002144: Received: via: 3, Ver: v1,
> Flags: 10, { GetStorageStatsAnswer } }
> 2013-02-22 15:56:24,494 DEBUG [agent.transport.Request]
> (StatsCollector-1:null) Seq 3-1959002145: Received: via: 3, Ver: v1,
> Flags: 10, { GetStorageStatsAnswer } }
> 2013-02-22 15:56:26,131 DEBUG [cloud.server.StatsCollector]
> (StatsCollector-1:null) HostStatsCollector is run
> 2013-02-22 15:56:26,766 DEBUG [agent.transport.Request]
> (StatsCollector-1:null) Seq 3-1959002146: Received: via: 3, Ver: v1,
> Flags: 10, { GetHostStatsAnswer } }
> 2013-02-22 15:56:37,523 DEBUG
> [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1
> is rM
> 2013-02-22 15:56:37,610 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl]
> (consoleproxy-1:null) Zone 1 is re
> 2013-02-22 15:56:38,038 DEBUG
> [network.router.VirtualNetworkApplianceManagerImpl]
> (RouterStatusMonitor-1:null