麻烦提供下hyper-v宿主机 日志查看器中的信息。 运行输入: eventvwr 在左侧 windows日志,应用程序日志中,查看级别为错误的日志,吧报错贴上来。
2014-11-10 9:13 GMT+08:00 w...@landhightech.com <w...@landhightech.com>: > > 大家好: > 我使用的环境是CloudStack4.4.0 + hyper-v, AgentShell.exe > 是自己编译的,在配置CloudStack过程中,二级存储虚拟机一直无法启动,在hyper-v管理 > 服务器上看到系统虚拟机创建了又被删除了,一直这样循环;日志的错误提示如下: > 请大家帮忙看看,谢谢啦。 > > > > > 2014-11-10 08:33:20,538 DEBUG [c.c.a.t.Request] > (Work-Job-Executor-59:ctx-a3f5f22b job-1363/job-2320 ctx-cd7c5bea) Seq > 10-909445649752131411: Received: { Ans: , MgmtId: 52231492654, via: 10, > Ver: v1, Flags: 10, { StartAnswer } } > 2014-11-10 08:33:20,542 INFO [c.c.v.VirtualMachineManagerImpl] > (Work-Job-Executor-59:ctx-a3f5f22b job-1363/job-2320 ctx-cd7c5bea) Unable > to start VM on Host[-10-Routing] due to com.cloud.agent.api.StartCommand > fail on exception未将对象引用设置到对象的实例。 > 2014-11-10 08:33:20,545 DEBUG [c.c.v.VirtualMachineManagerImpl] > (Work-Job-Executor-59:ctx-a3f5f22b job-1363/job-2320 ctx-cd7c5bea) Cleaning > up resources for the vm VM[SecondaryStorageVm|s-432-VM] in Starting state > 2014-11-10 08:33:20,550 DEBUG [c.c.a.t.Request] > (Work-Job-Executor-59:ctx-a3f5f22b job-1363/job-2320 ctx-cd7c5bea) Seq > 10-909445649752131413: Sending { Cmd , MgmtId: 52231492654, via: > 10(192.168.1.80), Ver: v1, Flags: 100011, > [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"checkBeforeCleanup":false,"vmName":"s-432-VM","wait":0}}] > } > 2014-11-10 08:33:20,550 DEBUG [c.c.a.t.Request] > (Work-Job-Executor-59:ctx-a3f5f22b job-1363/job-2320 ctx-cd7c5bea) Seq > 10-909445649752131413: Executing: { Cmd , MgmtId: 52231492654, via: > 10(192.168.1.80), Ver: v1, Flags: 100011, > [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"checkBeforeCleanup":false,"vmName":"s-432-VM","wait":0}}] > } > 2014-11-10 08:33:20,551 DEBUG [c.c.a.m.DirectAgentAttache] > (DirectAgent-193:ctx-83dc4a29) Seq 10-909445649752131413: Executing request > 2014-11-10 08:33:20,551 DEBUG [c.c.h.h.r.HypervDirectConnectResource] > (DirectAgent-193:ctx-83dc4a29) POST request to > https://192.168.1.80:8250/api/HypervResource/com.cloud.agent.api.StopCommand > with contents > {"isProxy":false,"executeInSequence":false,"checkBeforeCleanup":false,"vmName":"s-432-VM","contextMap":{},"wait":0} > 2014-11-10 08:33:20,557 DEBUG [c.c.h.h.r.HypervDirectConnectResource] > (DirectAgent-193:ctx-83dc4a29) Sending cmd to > https://192.168.1.80:8250/api/HypervResource/com.cloud.agent.api.StopCommand > cmd > data:{"isProxy":false,"executeInSequence":false,"checkBeforeCleanup":false,"vmName":"s-432-VM","contextMap":{},"wait":0} > 2014-11-10 08:33:21,673 DEBUG [c.c.h.h.r.HypervDirectConnectResource] > (DirectAgent-137:ctx-8b01638f) POST response is > [{"com.cloud.agent.api.StopAnswer":{"result":true,"details":null,"vm":null,"contextMap":{}}}] > 2014-11-10 08:33:21,674 DEBUG [c.c.h.h.r.HypervDirectConnectResource] > (DirectAgent-137:ctx-8b01638f) executeRequest received response > [Lcom.cloud.agent.api.Answer;@2301dfff > 2014-11-10 08:33:21,674 DEBUG [c.c.a.m.DirectAgentAttache] > (DirectAgent-137:ctx-8b01638f) Seq 10-909445649752131412: Response Received: > 2014-11-10 08:33:21,675 DEBUG [c.c.a.t.Request] > (DirectAgent-137:ctx-8b01638f) Seq 10-909445649752131412: Processing: { > Ans: , MgmtId: 52231492654, via: 10, Ver: v1, Flags: 10, > [{"com.cloud.agent.api.StopAnswer":{"result":true,"wait":0}}] } > 2014-11-10 08:33:21,675 DEBUG [c.c.a.t.Request] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Seq > 10-909445649752131412: Received: { Ans: , MgmtId: 52231492654, via: 10, > Ver: v1, Flags: 10, { StopAnswer } } > 2014-11-10 08:33:21,692 DEBUG [o.a.c.e.o.NetworkOrchestrator] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Asking > VirtualRouter to release > NicProfile[1653-421-c45920d8-198a-46f1-a359-74bcfe82f8c6-192.168.1.227-vlan://untagged > 2014-11-10 08:33:21,695 DEBUG [o.a.c.e.o.NetworkOrchestrator] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Asking > SecurityGroupProvider to release > NicProfile[1653-421-c45920d8-198a-46f1-a359-74bcfe82f8c6-192.168.1.227-vlan://untagged > 2014-11-10 08:33:21,699 DEBUG [c.c.n.g.ControlNetworkGuru] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Released > nic: NicProfile[1654-421-null-null-null > 2014-11-10 08:33:21,707 DEBUG [c.c.d.d.DataCenterIpAddressDaoImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) > Releasing ip address for > reservationId=c45920d8-198a-46f1-a359-74bcfe82f8c6, instance=1655 > 2014-11-10 08:33:21,708 DEBUG [c.c.n.g.PodBasedNetworkGuru] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Released > nic: NicProfile[1655-421-null-null-null > 2014-11-10 08:33:21,712 DEBUG [c.c.v.VirtualMachineManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) > Successfully released network resources for the vm VM[ConsoleProxy|v-421-VM] > 2014-11-10 08:33:21,712 DEBUG [c.c.v.VirtualMachineManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) > Successfully cleanued up resources for the vm VM[ConsoleProxy|v-421-VM] in > Starting state > 2014-11-10 08:33:21,715 DEBUG [c.c.v.VirtualMachineManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Root > volume is ready, need to place VM in volume's cluster > 2014-11-10 08:33:21,715 DEBUG [c.c.v.VirtualMachineManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) > Vol[421|vm=421|ROOT] is READY, changing deployment plan to use this pool's > dcId: 8 , podId: 8 , and clusterId: 17 > 2014-11-10 08:33:21,718 DEBUG [c.c.d.DeploymentPlanningManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Deploy > avoids pods: null, clusters: null, hosts: [10] > 2014-11-10 08:33:21,719 DEBUG [c.c.d.DeploymentPlanningManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) > DeploymentPlanner allocation algorithm: > com.cloud.deploy.FirstFitPlanner@6ae903b2 > 2014-11-10 08:33:21,720 DEBUG [c.c.d.DeploymentPlanningManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Trying > to allocate a host and storage pools from dc:8, pod:8,cluster:17, requested > cpu: 500, requested ram: 1073741824 > 2014-11-10 08:33:21,720 DEBUG [c.c.d.DeploymentPlanningManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Is ROOT > volume READY (pool already allocated)?: Yes > 2014-11-10 08:33:21,720 DEBUG [c.c.d.FirstFitPlanner] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) > Searching resources only under specified Cluster: 17 > 2014-11-10 08:33:21,728 DEBUG [c.c.d.DeploymentPlanningManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Checking > resources in Cluster: 17 under Pod: 8 > 2014-11-10 08:33:21,728 DEBUG [c.c.a.m.a.i.FirstFitAllocator] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89 > FirstFitRoutingAllocator) Looking for hosts in dc: 8 pod:8 cluster:17 > 2014-11-10 08:33:21,732 DEBUG [c.c.a.m.a.i.FirstFitAllocator] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89 > FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to check for > allocation: [Host[-10-Routing]] > 2014-11-10 08:33:21,738 DEBUG [c.c.a.m.a.i.FirstFitAllocator] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89 > FirstFitRoutingAllocator) Found 1 hosts for allocation after > prioritization: [Host[-10-Routing]] > 2014-11-10 08:33:21,738 DEBUG [c.c.a.m.a.i.FirstFitAllocator] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89 > FirstFitRoutingAllocator) Looking for speed=500Mhz, Ram=1024 > 2014-11-10 08:33:21,738 DEBUG [c.c.a.m.a.i.FirstFitAllocator] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89 > FirstFitRoutingAllocator) Host name: 192.168.1.80, hostId: 10 is in avoid > set, skipping this and trying other available hosts > 2014-11-10 08:33:21,738 DEBUG [c.c.a.m.a.i.FirstFitAllocator] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89 > FirstFitRoutingAllocator) Host Allocator returning 0 suitable hosts > 2014-11-10 08:33:21,738 DEBUG [c.c.d.DeploymentPlanningManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) No > suitable hosts found > 2014-11-10 08:33:21,738 DEBUG [c.c.d.DeploymentPlanningManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) No > suitable hosts found under this Cluster: 17 > 2014-11-10 08:33:21,740 DEBUG [c.c.d.DeploymentPlanningManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Could > not find suitable Deployment Destination for this VM under any clusters, > returning. > 2014-11-10 08:33:21,740 DEBUG [c.c.d.FirstFitPlanner] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) > Searching resources only under specified Cluster: 17 > 2014-11-10 08:33:21,742 DEBUG [c.c.d.FirstFitPlanner] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) The > specified cluster is in avoid set, returning. > 2014-11-10 08:33:21,745 DEBUG [c.c.d.DeploymentPlanningManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Deploy > avoids pods: null, clusters: [17], hosts: [10] > 2014-11-10 08:33:21,746 DEBUG [c.c.d.DeploymentPlanningManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) > DeploymentPlanner allocation algorithm: > com.cloud.deploy.FirstFitPlanner@6ae903b2 > 2014-11-10 08:33:21,746 DEBUG [c.c.d.DeploymentPlanningManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Trying > to allocate a host and storage pools from dc:8, pod:8,cluster:null, > requested cpu: 500, requested ram: 1073741824 > 2014-11-10 08:33:21,746 DEBUG [c.c.d.DeploymentPlanningManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Is ROOT > volume READY (pool already allocated)?: No > 2014-11-10 08:33:21,747 DEBUG [c.c.d.FirstFitPlanner] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) > Searching resources only under specified Pod: 8 > 2014-11-10 08:33:21,747 DEBUG [c.c.d.FirstFitPlanner] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Listing > clusters in order of aggregate capacity, that have (atleast one host with) > enough CPU and RAM capacity under this Pod: 8 > 2014-11-10 08:33:21,751 DEBUG [c.c.d.FirstFitPlanner] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Removing > from the clusterId list these clusters from avoid set: [17] > 2014-11-10 08:33:21,753 DEBUG [c.c.d.FirstFitPlanner] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) No > clusters found after removing disabled clusters and clusters in avoid list, > returning. > 2014-11-10 08:33:21,763 DEBUG [c.c.c.CapacityManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) VM state > transitted from :Starting to Stopped with event: OperationFailedvm's > original host id: null new host id: null host id before state transition: 10 > 2014-11-10 08:33:21,771 DEBUG [c.c.c.CapacityManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Hosts's > actual total CPU: 32000 and CPU after applying overprovisioning: 32000 > 2014-11-10 08:33:21,771 DEBUG [c.c.c.CapacityManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Hosts's > actual total RAM: 137357664256 and RAM after applying overprovisioning: > 137357664256 > 2014-11-10 08:33:21,772 DEBUG [c.c.c.CapacityManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) release > cpu from host: 10, old used: 1000,reserved: 0, actual total: 32000, total > with overprovisioning: 32000; new used: 500,reserved:0; movedfromreserved: > false,moveToReserveredfalse > 2014-11-10 08:33:21,772 DEBUG [c.c.c.CapacityManagerImpl] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) release > mem from host: 10, old used: 1610612736,reserved: 0, total: 137357664256; > new used: 536870912,reserved:0; movedfromreserved: > false,moveToReserveredfalse > 2014-11-10 08:33:21,774 ERROR [c.c.v.VmWorkJobHandlerProxy] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) > Invocation exception, caused by: > com.cloud.exception.InsufficientServerCapacityException: Unable to create a > deployment for VM[ConsoleProxy|v-421-VM]Scope=interface > com.cloud.dc.DataCenter; id=8 > 2014-11-10 08:33:21,775 INFO [c.c.v.VmWorkJobHandlerProxy] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319 ctx-aa767b89) Rethrow > exception com.cloud.exception.InsufficientServerCapacityException: Unable > to create a deployment for VM[ConsoleProxy|v-421-VM]Scope=interface > com.cloud.dc.DataCenter; id=8 > 2014-11-10 08:33:21,775 DEBUG [c.c.v.VmWorkJobDispatcher] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319) Done with run of VM > work job: com.cloud.vm.VmWorkStart for VM 421, job origin: 1362 > 2014-11-10 08:33:21,775 ERROR [c.c.v.VmWorkJobDispatcher] > (Work-Job-Executor-58:ctx-3a4c1a3f job-1362/job-2319) Unable to complete > AsyncJobVO {id:2319, userId: 1, accountId: 1, instanceType: null, > instanceId: null, cmd: com.cloud.vm.VmWorkStart, cmdInfo: > rO0ABXNyABhjb20uY2xvdWQudm0uVm1Xb3JrU3RhcnR9cMGsvxz73gIAC0oABGRjSWRMAAZhdm9pZHN0ADBMY29tL2Nsb3VkL2RlcGxveS9EZXBsb3ltZW50UGxhbm5lciRFeGNsdWRlTGlzdDtMAAljbHVzdGVySWR0ABBMamF2YS9sYW5nL0xvbmc7TAAGaG9zdElkcQB-AAJMAAtqb3VybmFsTmFtZXQAEkxqYXZhL2xhbmcvU3RyaW5nO0wAEXBoeXNpY2FsTmV0d29ya0lkcQB-AAJMAAdwbGFubmVycQB-AANMAAVwb2RJZHEAfgACTAAGcG9vbElkcQB-AAJMAAlyYXdQYXJhbXN0AA9MamF2YS91dGlsL01hcDtMAA1yZXNlcnZhdGlvbklkcQB-AAN4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3VudElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1lcQB-AAN4cAAAAAAAAAABAAAAAAAAAAEAAAAAAAABpXQAGVZpcnR1YWxNYWNoaW5lTWFuYWdlckltcGwAAAAAAAAAAHBwcHBwcHBwcHA, > cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, > result: null, initMsid: 52231492654, completeMsid: null, lastUpdated: null, > lastPolled: null, created: Mon Nov 10 08:33:16 CST 2014}, job origin:1362 > com.cloud.exception.InsufficientServerCapacityException: Unable to create > a deployment for VM[ConsoleProxy|v-421-VM]Scope=interface > com.cloud.dc.DataCenter; id=8 > at > com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachineManagerImpl.java:946) > at > com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachineManagerImpl.java:5190) > at sun.reflect.GeneratedMethodAccessor553.invoke(Unknown Source) > at > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > > w...@landhightech.com >