cloudstack-users-cn mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From leifan8440 <leifan8...@gmail.com>
Subject 回复: cloudstack + xen 无法创建WINDOWS系列实例
Date Fri, 25 Jul 2014 01:24:13 GMT
  errorInfo: [VM_HVM_REQUIRED, OpaqueRef:6fb805d2-615d-28bc-0244-4697e32e6c62]
 
         otherConfig: {}



leifan8440
 
发件人: 7data@163.com
发送时间: 2014-07-24 21:30
收件人: users-cn
主题: cloudstack + xen 无法创建WINDOWS系列实例
 
 
 
 
 
 
日志如下:麻烦超哥再看下,windowsxpsp3也不行 ?WIN7 ?WIN8 ?2003 ? 2008 ?都试了
?
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.checkForSuccess(CitrixResourceBase.java:3764)
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.startVM(CitrixResourceBase.java:3876)
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.execute(CitrixResourceBase.java:1721)
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixResourceBase.java:545)
 
at com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenServer56Resource.java:59)
 
at com.cloud.hypervisor.xen.resource.XenServer610Resource.executeRequest(XenServer610Resource.java:106)
 
at com.cloud.agent.manager.DirectAgentAttache$Task.runInContext(DirectAgentAttache.java:216)
 
at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
 
at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
 
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)
 
2014-07-24 21:27:30,402 WARN  [c.c.h.x.r.CitrixResourceBase] (DirectAgent-272:ctx-2589d52d)
Catch Exception: class com.cloud.utils.exception.CloudRuntimeException due to com.cloud.utils.exception.CloudRuntimeException:
Unable to start VM(i-2-35-VM) on host(96472e33-a067-485a-aac3-6863972943e9) due to Task failed!
Task record:                 uuid: a1f12ec0-771c-ff9d-f33f-ebd740aaca18
 
           nameLabel: Async.VM.start_on
 
     nameDescription: 
 
   allowedOperations: []
 
   currentOperations: {}
 
             created: Thu Jul 24 21:25:55 CST 2014
 
            finished: Thu Jul 24 21:25:55 CST 2014
 
              status: failure
 
          residentOn: com.xensource.xenapi.Host@ada5de2f
 
            progress: 1.0
 
                type: <none/>
 
              result: 
 
           errorInfo: [VM_HVM_REQUIRED, OpaqueRef:6fb805d2-615d-28bc-0244-4697e32e6c62]
 
         otherConfig: {}
 
           subtaskOf: com.xensource.xenapi.Task@aaf13f6f
 
            subtasks: []
 
 
 
com.cloud.utils.exception.CloudRuntimeException: Unable to start VM(i-2-35-VM) on host(96472e33-a067-485a-aac3-6863972943e9)
due to Task failed! Task record:                 uuid: a1f12ec0-771c-ff9d-f33f-ebd740aaca18
 
           nameLabel: Async.VM.start_on
 
     nameDescription: 
 
   allowedOperations: []
 
   currentOperations: {}
 
             created: Thu Jul 24 21:25:55 CST 2014
 
            finished: Thu Jul 24 21:25:55 CST 2014
 
              status: failure
 
          residentOn: com.xensource.xenapi.Host@ada5de2f
 
            progress: 1.0
 
                type: <none/>
 
              result: 
 
           errorInfo: [VM_HVM_REQUIRED, OpaqueRef:6fb805d2-615d-28bc-0244-4697e32e6c62]
 
         otherConfig: {}
 
           subtaskOf: com.xensource.xenapi.Task@aaf13f6f
 
            subtasks: []
 
 
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.startVM(CitrixResourceBase.java:3887)
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.execute(CitrixResourceBase.java:1721)
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixResourceBase.java:545)
 
at com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenServer56Resource.java:59)
 
at com.cloud.hypervisor.xen.resource.XenServer610Resource.executeRequest(XenServer610Resource.java:106)
 
at com.cloud.agent.manager.DirectAgentAttache$Task.runInContext(DirectAgentAttache.java:216)
 
at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
 
at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
 
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)
 
2014-07-24 21:27:30,403 WARN  [c.c.h.x.r.CitrixResourceBase] (DirectAgent-272:ctx-2589d52d)
Unable to start i-2-35-VM due to 
 
com.cloud.utils.exception.CloudRuntimeException: Unable to start VM(i-2-35-VM) on host(96472e33-a067-485a-aac3-6863972943e9)
due to Task failed! Task record:                 uuid: a1f12ec0-771c-ff9d-f33f-ebd740aaca18
 
           nameLabel: Async.VM.start_on
 
     nameDescription: 
 
   allowedOperations: []
 
   currentOperations: {}
 
             created: Thu Jul 24 21:25:55 CST 2014
 
            finished: Thu Jul 24 21:25:55 CST 2014
 
              status: failure
 
          residentOn: com.xensource.xenapi.Host@ada5de2f
 
            progress: 1.0
 
                type: <none/>
 
              result: 
 
           errorInfo: [VM_HVM_REQUIRED, OpaqueRef:6fb805d2-615d-28bc-0244-4697e32e6c62]
 
         otherConfig: {}
 
           subtaskOf: com.xensource.xenapi.Task@aaf13f6f
 
            subtasks: []
 
 
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.startVM(CitrixResourceBase.java:3887)
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.execute(CitrixResourceBase.java:1721)
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixResourceBase.java:545)
 
at com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenServer56Resource.java:59)
 
at com.cloud.hypervisor.xen.resource.XenServer610Resource.executeRequest(XenServer610Resource.java:106)
 
at com.cloud.agent.manager.DirectAgentAttache$Task.runInContext(DirectAgentAttache.java:216)
 
at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
 
at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
 
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)
 
2014-07-24 21:27:30,435 WARN  [c.c.h.x.r.CitrixResourceBase] (DirectAgent-272:ctx-2589d52d)
Unable to clean up VBD due to 
 
You gave an invalid object reference.  The object may have recently been deleted.  The class
parameter gives the type of reference given, and the handle parameter echoes the bad value
given.
 
at com.xensource.xenapi.Types.checkResponse(Types.java:209)
 
at com.xensource.xenapi.Connection.dispatch(Connection.java:368)
 
at com.cloud.hypervisor.xen.resource.XenServerConnectionPool$XenServerConnection.dispatch(XenServerConnectionPool.java:909)
 
at com.xensource.xenapi.VBD.unplug(VBD.java:1059)
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.handleVmStartFailure(CitrixResourceBase.java:1462)
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.execute(CitrixResourceBase.java:1800)
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixResourceBase.java:545)
 
at com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenServer56Resource.java:59)
 
at com.cloud.hypervisor.xen.resource.XenServer610Resource.executeRequest(XenServer610Resource.java:106)
 
at com.cloud.agent.manager.DirectAgentAttache$Task.runInContext(DirectAgentAttache.java:216)
 
at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
 
at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
 
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)
 
2014-07-24 21:27:30,442 WARN  [c.c.h.x.r.CitrixResourceBase] (DirectAgent-272:ctx-2589d52d)
Unable to clean up VBD due to 
 
You gave an invalid object reference.  The object may have recently been deleted.  The class
parameter gives the type of reference given, and the handle parameter echoes the bad value
given.
 
at com.xensource.xenapi.Types.checkResponse(Types.java:209)
 
at com.xensource.xenapi.Connection.dispatch(Connection.java:368)
 
at com.cloud.hypervisor.xen.resource.XenServerConnectionPool$XenServerConnection.dispatch(XenServerConnectionPool.java:909)
 
at com.xensource.xenapi.VBD.unplug(VBD.java:1059)
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.handleVmStartFailure(CitrixResourceBase.java:1462)
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.execute(CitrixResourceBase.java:1800)
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixResourceBase.java:545)
 
at com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenServer56Resource.java:59)
 
at com.cloud.hypervisor.xen.resource.XenServer610Resource.executeRequest(XenServer610Resource.java:106)
 
at com.cloud.agent.manager.DirectAgentAttache$Task.runInContext(DirectAgentAttache.java:216)
 
at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
 
at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
 
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)
 
2014-07-24 21:27:30,449 WARN  [c.c.h.x.r.CitrixResourceBase] (DirectAgent-272:ctx-2589d52d)
Unable to cleanup VIF
 
You gave an invalid object reference.  The object may have recently been deleted.  The class
parameter gives the type of reference given, and the handle parameter echoes the bad value
given.
 
at com.xensource.xenapi.Types.checkResponse(Types.java:209)
 
at com.xensource.xenapi.Connection.dispatch(Connection.java:368)
 
at com.cloud.hypervisor.xen.resource.XenServerConnectionPool$XenServerConnection.dispatch(XenServerConnectionPool.java:909)
 
at com.xensource.xenapi.VIF.unplug(VIF.java:878)
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.handleVmStartFailure(CitrixResourceBase.java:1470)
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.execute(CitrixResourceBase.java:1800)
 
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixResourceBase.java:545)
 
at com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenServer56Resource.java:59)
 
at com.cloud.hypervisor.xen.resource.XenServer610Resource.executeRequest(XenServer610Resource.java:106)
 
at com.cloud.agent.manager.DirectAgentAttache$Task.runInContext(DirectAgentAttache.java:216)
 
at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
 
at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
 
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)
 
2014-07-24 21:27:30,456 DEBUG [c.c.h.x.r.CitrixResourceBase] (DirectAgent-272:ctx-2589d52d)
The VM is in stopped state, detected problem during startup : i-2-35-VM
 
2014-07-24 21:27:30,456 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-272:ctx-2589d52d)
Seq 1-1629426678: Response Received: 
 
2014-07-24 21:27:30,457 DEBUG [c.c.a.t.Request] (DirectAgent-272:ctx-2589d52d) Seq 1-1629426678:
Processing:  { Ans: , MgmtId: 345049289015, via: 1, Ver: v1, Flags: 10, [{"com.cloud.agent.api.StartAnswer":{"vm":{"id":35,"name":"i-2-35-VM","bootloader":"CD","type":"User","cpus":1,"minSpeed":1000,"maxSpeed":1000,"minRam":1073741824,"maxRam":1073741824,"arch":"x86_64","os":"Windows
XP SP3 (32-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"642c4e486a3eb3ee","params":{},"uuid":"150eeeb5-cd67-4c73-8099-1770a8200f35","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"18e8a7ec-4ee6-4d0a-b487-0bcfc2ea08b2","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"2933a431-4034-3a2a-b03e-a0eca4db194f","id":3,"poolType":"NetworkFilesystem","host":"192.168.20.193","path":"/sdb/primary3","port":2049,"url":"NetworkFilesystem://192.168.20.193//sdb/primary3/?ROLE=Primary&STOREUUID=2933a431-4034-3a2a-b03e-a0eca4db194f"}},"name":"ROOT-35","size":107374182400,"path":"cb5c5e0c-a235-4e42-956b-4849fcb718bb","volumeId":36,"vmName":"i-2-35-VM","accountId":2,"format":"VHD","id":36,"deviceId":0,"hypervisorType":"XenServer"}},"diskSeq":0,"path":"cb5c5e0c-a235-4e42-956b-4849fcb718bb","type":"ROOT","_details":{"managed":"false","storagePort":"2049","storageHost":"192.168.20.193","volumeSize":"107374182400"}},{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/206/206-2-924c9cb8-20ec-370f-8a1a-6ea3b60453b8.iso","origUrl":"http://192.168.20.168:8080/en_windows_xp_professional_with_service_pack_3_x86_cd_vl_x14-73974.iso","uuid":"5c9d9c0d-df3c-4560-bef1-27b6dc6010b1","id":206,"format":"ISO","accountId":2,"checksum":"5bf476e2fc445b8d06b3c2a6091fe3aa","hvm":false,"displayText":"x86","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://192.168.20.195/export/secondary","_role":"Image"}},"name":"206-2-924c9cb8-20ec-370f-8a1a-6ea3b60453b8","guestOsType":"Windows
XP SP3 (32-bit)","hypervisorType":"None"}},"diskSeq":3,"type":"ISO"}],"nics":[{"deviceId":0,"networkRateMbps":200,"defaultNic":true,"uuid":"16176c10-6ff2-442e-8b5d-efc17c8dbc57","ip":"192.168.20.33","netmask":"255.255.255.0","gateway":"192.168.20.1","mac":"06:51:50:00:00:2c","dns1":"8.8.8.8","dns2":"8.8.4.4","broadcastType":"Native","type":"Guest","broadcastUri":"vlan://untagged","isSecurityGroupEnabled":true}],"vcpuMaxLimit":16},"_iqnToPath":{},"result":false,"details":"Unable
to start i-2-35-VM due to ","wait":0}}] }
 
2014-07-24 21:27:30,457 DEBUG [c.c.a.t.Request] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
Seq 1-1629426678: Received:  { Ans: , MgmtId: 345049289015, via: 1, Ver: v1, Flags: 10, {
StartAnswer } }
 
2014-07-24 21:27:30,461 INFO  [c.c.v.VirtualMachineManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Unable to start VM on Host[-1-Routing] due to Unable to start i-2-35-VM due
to 
 
2014-07-24 21:27:30,463 DEBUG [c.c.v.VirtualMachineManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Cleaning up resources for the vm VM[User|VM-150eeeb5-cd67-4c73-8099-1770a8200f35]
in Starting state
 
2014-07-24 21:27:30,465 DEBUG [c.c.a.t.Request] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
Seq 1-1629426679: Sending  { Cmd , MgmtId: 345049289015, via: 1(xenserver-zqksoayl), Ver:
v1, Flags: 100011, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"vmName":"i-2-35-VM","wait":0}}]
}
 
2014-07-24 21:27:30,466 DEBUG [c.c.a.t.Request] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
Seq 1-1629426679: Executing:  { Cmd , MgmtId: 345049289015, via: 1(xenserver-zqksoayl), Ver:
v1, Flags: 100011, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"vmName":"i-2-35-VM","wait":0}}]
}
 
2014-07-24 21:27:30,466 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-29:ctx-21a3fbf9) Seq
1-1629426679: Executing request
 
2014-07-24 21:27:30,573 INFO  [c.c.h.x.r.CitrixResourceBase] (DirectAgent-29:ctx-21a3fbf9)
VM does not exist on XenServer96472e33-a067-485a-aac3-6863972943e9
 
2014-07-24 21:27:30,574 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-29:ctx-21a3fbf9) Seq
1-1629426679: Response Received: 
 
2014-07-24 21:27:30,574 DEBUG [c.c.a.t.Request] (DirectAgent-29:ctx-21a3fbf9) Seq 1-1629426679:
Processing:  { Ans: , MgmtId: 345049289015, via: 1, Ver: v1, Flags: 10, [{"com.cloud.agent.api.StopAnswer":{"result":true,"details":"VM
does not exist","wait":0}}] }
 
2014-07-24 21:27:30,574 DEBUG [c.c.a.t.Request] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
Seq 1-1629426679: Received:  { Ans: , MgmtId: 345049289015, via: 1, Ver: v1, Flags: 10, {
StopAnswer } }
 
2014-07-24 21:27:30,584 DEBUG [o.a.c.e.o.NetworkOrchestrator] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Changing active number of nics for network id=204 on -1
 
2014-07-24 21:27:30,590 DEBUG [o.a.c.e.o.NetworkOrchestrator] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Asking VirtualRouter to release NicProfile[48-35-bbd783f5-12ba-44c9-86a9-1c199f23ead1-192.168.20.33-vlan://untagged
 
2014-07-24 21:27:30,592 DEBUG [o.a.c.e.o.NetworkOrchestrator] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Asking SecurityGroupProvider to release NicProfile[48-35-bbd783f5-12ba-44c9-86a9-1c199f23ead1-192.168.20.33-vlan://untagged
 
2014-07-24 21:27:30,592 DEBUG [c.c.v.VirtualMachineManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Successfully released network resources for the vm VM[User|VM-150eeeb5-cd67-4c73-8099-1770a8200f35]
 
2014-07-24 21:27:30,592 DEBUG [c.c.v.VirtualMachineManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Successfully cleanued up resources for the vm VM[User|VM-150eeeb5-cd67-4c73-8099-1770a8200f35]
in Starting state
 
2014-07-24 21:27:30,594 DEBUG [c.c.v.VirtualMachineManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Root volume is ready, need to place VM in volume's cluster
 
2014-07-24 21:27:30,602 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Deploy avoids pods: [], clusters: [], hosts: [1]
 
2014-07-24 21:27:30,603 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner@1ce0830b
 
2014-07-24 21:27:30,603 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Trying to allocate a host and storage pools from dc:1, pod:1,cluster:1, requested
cpu: 1000, requested ram: 1073741824
 
2014-07-24 21:27:30,603 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Is ROOT volume READY (pool already allocated)?: Yes
 
2014-07-24 21:27:30,603 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) DeploymentPlan has host_id specified, choosing this host and making no checks
on this host: 1
 
2014-07-24 21:27:30,604 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) The specified host is in avoid set
 
2014-07-24 21:27:30,604 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Cannnot deploy to specified host, returning.
 
2014-07-24 21:27:30,622 DEBUG [c.c.c.CapacityManagerImpl] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
VM state transitted from :Starting to Stopped with event: OperationFailedvm's original host
id: null new host id: null host id before state transition: 1
 
2014-07-24 21:27:30,627 DEBUG [c.c.c.CapacityManagerImpl] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
Hosts's actual total CPU: 16784 and CPU after applying overprovisioning: 16784
 
2014-07-24 21:27:30,627 DEBUG [c.c.c.CapacityManagerImpl] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
Hosts's actual total RAM: 7555125312 and RAM after applying overprovisioning: 7555125248
 
2014-07-24 21:27:30,627 DEBUG [c.c.c.CapacityManagerImpl] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
release cpu from host: 1, old used: 4500,reserved: 0, actual total: 16784, total with overprovisioning:
16784; new used: 3500,reserved:0; movedfromreserved: false,moveToReserveredfalse
 
2014-07-24 21:27:30,627 DEBUG [c.c.c.CapacityManagerImpl] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
release mem from host: 1, old used: 4966055936,reserved: 0, total: 7555125248; new used: 3892314112,reserved:0;
movedfromreserved: false,moveToReserveredfalse
 
2014-07-24 21:27:30,645 DEBUG [c.c.c.CapacityManagerImpl] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
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
 
2014-07-24 21:27:30,645 DEBUG [c.c.v.VirtualMachineManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Successfully transitioned to start state for VM[User|VM-150eeeb5-cd67-4c73-8099-1770a8200f35]
reservation id = ce52d1cd-f378-4080-90c1-eaa165942082
 
2014-07-24 21:27:30,648 DEBUG [c.c.v.VirtualMachineManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Trying to deploy VM, vm has dcId: 1 and podId: 1
 
2014-07-24 21:27:30,648 DEBUG [c.c.v.VirtualMachineManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Deploy avoids pods: [], clusters: [], hosts: [1]
 
2014-07-24 21:27:30,651 DEBUG [c.c.v.VirtualMachineManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Root volume is ready, need to place VM in volume's cluster
 
2014-07-24 21:27:30,651 DEBUG [c.c.v.VirtualMachineManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Vol[36|vm=35|ROOT] is READY, changing deployment plan to use this pool's dcId:
1 , podId: 1 , and clusterId: 1
 
2014-07-24 21:27:30,659 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Deploy avoids pods: [], clusters: [], hosts: [1]
 
2014-07-24 21:27:30,660 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner@1ce0830b
 
2014-07-24 21:27:30,660 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Trying to allocate a host and storage pools from dc:1, pod:1,cluster:1, requested
cpu: 1000, requested ram: 1073741824
 
2014-07-24 21:27:30,660 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Is ROOT volume READY (pool already allocated)?: Yes
 
2014-07-24 21:27:30,660 DEBUG [c.c.d.FirstFitPlanner] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
Searching resources only under specified Cluster: 1
 
2014-07-24 21:27:30,665 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Checking resources in Cluster: 1 under Pod: 1
 
2014-07-24 21:27:30,665 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b FirstFitRoutingAllocator) Looking for hosts in dc: 1  pod:1  cluster:1
 
2014-07-24 21:27:30,668 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to check for allocation:
[Host[-1-Routing]]
 
2014-07-24 21:27:30,670 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b FirstFitRoutingAllocator) Found 1 hosts for allocation after prioritization:
[Host[-1-Routing]]
 
2014-07-24 21:27:30,670 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b FirstFitRoutingAllocator) Looking for speed=1000Mhz, Ram=1024
 
2014-07-24 21:27:30,670 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b FirstFitRoutingAllocator) Host name: xenserver-zqksoayl, hostId: 1 is in avoid
set, skipping this and trying other available hosts
 
2014-07-24 21:27:30,670 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b FirstFitRoutingAllocator) Host Allocator returning 0 suitable hosts
 
2014-07-24 21:27:30,670 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) No suitable hosts found
 
2014-07-24 21:27:30,670 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) No suitable hosts found under this Cluster: 1
 
2014-07-24 21:27:30,672 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Could not find suitable Deployment Destination for this VM under any clusters,
returning. 
 
2014-07-24 21:27:30,672 DEBUG [c.c.d.FirstFitPlanner] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
Searching resources only under specified Cluster: 1
 
2014-07-24 21:27:30,673 DEBUG [c.c.d.FirstFitPlanner] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
The specified cluster is in avoid set, returning.
 
2014-07-24 21:27:30,680 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Deploy avoids pods: [], clusters: [1], hosts: [1]
 
2014-07-24 21:27:30,681 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner@1ce0830b
 
2014-07-24 21:27:30,681 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Trying to allocate a host and storage pools from dc:1, pod:1,cluster:null, requested
cpu: 1000, requested ram: 1073741824
 
2014-07-24 21:27:30,681 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Job-Executor-74:ctx-f80863bb
ctx-d832c59b) Is ROOT volume READY (pool already allocated)?: No
 
2014-07-24 21:27:30,681 DEBUG [c.c.d.FirstFitPlanner] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
Searching resources only under specified Pod: 1
 
2014-07-24 21:27:30,681 DEBUG [c.c.d.FirstFitPlanner] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
Listing clusters in order of aggregate capacity, that have (atleast one host with) enough
CPU and RAM capacity under this Pod: 1
 
2014-07-24 21:27:30,684 DEBUG [c.c.d.FirstFitPlanner] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
Removing from the clusterId list these clusters from avoid set: [1]
 
2014-07-24 21:27:30,684 DEBUG [c.c.d.FirstFitPlanner] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
No clusters found after removing disabled clusters and clusters in avoid list, returning.
 
2014-07-24 21:27:30,693 DEBUG [c.c.c.CapacityManagerImpl] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
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
 
2014-07-24 21:27:30,702 DEBUG [c.c.v.UserVmManagerImpl] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
Destroying vm VM[User|VM-150eeeb5-cd67-4c73-8099-1770a8200f35] as it failed to create on Host
with Id:null
 
2014-07-24 21:27:30,707 DEBUG [c.c.c.CapacityManagerImpl] (Job-Executor-74:ctx-f80863bb ctx-d832c59b)
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
 
2014-07-24 21:27:30,739 WARN  [o.a.c.alerts] (Job-Executor-74:ctx-f80863bb ctx-d832c59b) 
alertType:: 8 // dataCenterId:: 1 // podId:: 1 // clusterId:: null // message:: Failed to
deploy Vm with Id: 35, on Host with Id: null
 
2014-07-24 21:27:30,787 ERROR [c.c.a.ApiAsyncJobDispatcher] (Job-Executor-74:ctx-f80863bb)
Unexpected exception while executing org.apache.cloudstack.api.command.user.vm.DeployVMCmd
 
com.cloud.utils.exception.CloudRuntimeException: Unable to start a VM due to insufficient
capacity
 
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:605)
 
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:207)
 
at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3581)
 
at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3161)
 
at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3147)
 
at sun.reflect.GeneratedMethodAccessor390.invoke(Unknown Source)
 
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
 
at java.lang.reflect.Method.invoke(Method.java:622)
 
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
 
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
 
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
 
at com.cloud.event.ActionEventInterceptor.invoke(ActionEventInterceptor.java:50)
 
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
 
at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
 
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
 
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
 
at com.sun.proxy.$Proxy169.startVirtualMachine(Unknown Source)
 
at org.apache.cloudstack.api.command.user.vm.DeployVMCmd.execute(DeployVMCmd.java:443)
 
at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:161)
 
at com.cloud.api.ApiAsyncJobDispatcher.runJobInContext(ApiAsyncJobDispatcher.java:109)
 
at com.cloud.api.ApiAsyncJobDispatcher$1.run(ApiAsyncJobDispatcher.java:66)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
 
at com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:63)
 
at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:509)
 
at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
 
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
 
at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
 
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)
 
Caused by: com.cloud.exception.InsufficientServerCapacityException: Unable to create a deployment
for VM[User|VM-150eeeb5-cd67-4c73-8099-1770a8200f35]Scope=interface com.cloud.dc.DataCenter;
id=1
 
at com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachineManagerImpl.java:921)
 
at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:761)
 
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:601)
 
... 37 more
 
2014-07-24 21:27:30,789 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Executor-74:ctx-f80863bb)
Complete async job-86, jobStatus: FAILED, resultCode: 530, result: org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"errorcode":530,"errortext":"Unable
to start a VM due to insufficient capacity"}
 
2014-07-24 21:27:30,794 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Executor-74:ctx-f80863bb)
Done executing org.apache.cloudstack.api.command.user.vm.DeployVMCmd for job-86
 
2014-07-24 21:27:30,798 INFO  [o.a.c.f.j.i.AsyncJobMonitor] (Job-Executor-74:ctx-f80863bb)
Remove job-86 from job monitoring
 
2014-07-24 21:27:31,274 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-6:null) SeqA
2-4337: Processing Seq 2-4337:  { Cmd , MgmtId: -1, via: 2, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
 \"connections\": []\n}","wait":0}}] }
 
2014-07-24 21:27:31,279 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-6:null) SeqA
2-4337: Sending Seq 2-4337:  { Ans: , MgmtId: 345049289015, via: 2, Ver: v1, Flags: 100010,
[{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
 
2014-07-24 21:27:31,420 DEBUG [c.c.a.ApiServlet] (catalina-exec-25:ctx-681cd831) ===START===
 192.168.20.242 -- GET  command=queryAsyncJobResult&jobId=7cafc6aa-537b-4969-af9f-841c6c3c7217&response=json&sessionkey=qqGIr2PICUR7Nfq7X9m3931DRxk%3D&_=1406208441593
 
2014-07-24 21:27:31,435 DEBUG [c.c.a.ApiServlet] (catalina-exec-25:ctx-681cd831 ctx-ec7a4113)
===END===  192.168.20.242 -- GET  command=queryAsyncJobResult&jobId=7cafc6aa-537b-4969-af9f-841c6c3c7217&response=json&sessionkey=qqGIr2PICUR7Nfq7X9m3931DRxk%3D&_=1406208441593
 
2014-07-24 21:27:31,542 DEBUG [c.c.a.ApiServlet] (catalina-exec-14:ctx-7b4a081b) ===START===
 192.168.20.242 -- GET  command=listVirtualMachines&id=150eeeb5-cd67-4c73-8099-1770a8200f35&response=json&sessionkey=qqGIr2PICUR7Nfq7X9m3931DRxk%3D&_=1406208441709
 
2014-07-24 21:27:31,560 DEBUG [c.c.a.ApiServlet] (catalina-exec-14:ctx-7b4a081b ctx-e219e021)
===END===  192.168.20.242 -- GET  command=listVirtualMachines&id=150eeeb5-cd67-4c73-8099-1770a8200f35&response=json&sessionkey=qqGIr2PICUR7Nfq7X9m3931DRxk%3D&_=1406208441709
 
^A2014-07-24 21:27:36,275 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-7:null) SeqA
2-4338: Processing Seq 2-4338:  { Cmd , MgmtId: -1, via: 2, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
 \"connections\": []\n}","wait":0}}] }
 
2014-07-24 21:27:36,279 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-7:null) SeqA
2-4338: Sending Seq 2-4338:  { Ans: , MgmtId: 345049289015, via: 2, Ver: v1, Flags: 100010,
[{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
 
2014-07-24 21:27:36,538 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-9:null) Ping
from 4
 
2014-07-24 21:27:41,277 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-12:null) SeqA
2-4339: Processing Seq 2-4339:  { Cmd , MgmtId: -1, via: 2, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
 \"connections\": []\n}","wait":0}}] }
 
2014-07-24 21:27:41,281 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-12:null) SeqA
2-4339: Sending Seq 2-4339:  { Ans: , MgmtId: 345049289015, via: 2, Ver: v1, Flags: 100010,
[{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
 
2014-07-24 21:27:42,478 DEBUG [c.c.s.StatsCollector] (StatsCollector-1:ctx-cd358c0e) HostStatsCollector
is running...
 
2014-07-24 21:27:42,486 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-87:ctx-17af49b8) Seq
1-1629426680: Executing request
 
2014-07-24 21:27:42,799 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-87:ctx-17af49b8) Seq
1-1629426680: Response Received: 
 
2014-07-24 21:27:42,800 DEBUG [c.c.a.t.Request] (StatsCollector-1:ctx-cd358c0e) Seq 1-1629426680:
Received:  { Ans: , MgmtId: 345049289015, via: 1, Ver: v1, Flags: 10, { GetHostStatsAnswer
} }
 
2014-07-24 21:27:44,827 DEBUG [c.c.s.s.SecondaryStorageManagerImpl] (secstorage-1:ctx-76ae982b)
Zone 1 is ready to launch secondary storage VM
 
2014-07-24 21:27:45,141 DEBUG [c.c.c.ConsoleProxyManagerImpl] (consoleproxy-1:ctx-149323bd)
Zone 1 is ready to launch console proxy
 
2014-07-24 21:27:47,850 DEBUG [c.c.s.StatsCollector] (StatsCollector-1:ctx-84a0bf72) VmStatsCollector
is running...
 
2014-07-24 21:27:47,864 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-316:ctx-9437735d)
Seq 1-1629426681: Executing request
 
2014-07-24 21:27:48,169 DEBUG [c.c.h.x.r.CitrixResourceBase] (DirectAgent-316:ctx-9437735d)
Vm cpu utilization 0.03
 
2014-07-24 21:27:48,169 DEBUG [c.c.h.x.r.CitrixResourceBase] (DirectAgent-316:ctx-9437735d)
Vm cpu utilization 0.03
 
2014-07-24 21:27:48,212 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-316:ctx-9437735d)
Seq 1-1629426681: Response Received: 
 
2014-07-24 21:27:48,212 DEBUG [c.c.a.t.Request] (StatsCollector-1:ctx-84a0bf72) Seq 1-1629426681:
Received:  { Ans: , MgmtId: 345049289015, via: 1, Ver: v1, Flags: 10, { GetVmStatsAnswer }
}
 
2014-07-24 21:27:51,279 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-11:null) SeqA
2-4340: Processing Seq 2-4340:  { Cmd , MgmtId: -1, via: 2, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
 \"connections\": []\n}","wait":0}}] }
 
2014-07-24 21:27:51,283 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-11:null) SeqA
2-4340: Sending Seq 2-4340:  { Ans: , MgmtId: 345049289015, via: 2, Ver: v1, Flags: 100010,
[{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
 
2014-07-24 21:27:51,796 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-87:ctx-aa16066c) Ping
from 1(xenserver-zqksoayl)
 
2014-07-24 21:27:54,281 DEBUG [c.c.h.d.HostDaoImpl] (ClusteredAgentManager Timer:ctx-6bb0f9d2)
Resetting hosts suitable for reconnect
 
2014-07-24 21:27:54,282 DEBUG [c.c.h.d.HostDaoImpl] (ClusteredAgentManager Timer:ctx-6bb0f9d2)
Completed resetting hosts suitable for reconnect
 
2014-07-24 21:27:54,282 DEBUG [c.c.h.d.HostDaoImpl] (ClusteredAgentManager Timer:ctx-6bb0f9d2)
Acquiring hosts for clusters already owned by this management server
 
2014-07-24 21:27:54,284 DEBUG [c.c.h.d.HostDaoImpl] (ClusteredAgentManager Timer:ctx-6bb0f9d2)
Completed acquiring hosts for clusters already owned by this management server
 
2014-07-24 21:27:54,284 DEBUG [c.c.h.d.HostDaoImpl] (ClusteredAgentManager Timer:ctx-6bb0f9d2)
Acquiring hosts for clusters not owned by any management server
 
2014-07-24 21:27:54,284 DEBUG [c.c.h.d.HostDaoImpl] (ClusteredAgentManager Timer:ctx-6bb0f9d2)
Completed acquiring hosts for clusters not owned by any management server
 
2014-07-24 21:27:55,640 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:ctx-53b5262f)
Found 0 routers to update status. 
 
2014-07-24 21:27:55,641 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:ctx-53b5262f)
Found 0 networks to update RvR status. 
 
2014-07-24 21:27:56,280 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-10:null) SeqA
2-4341: Processing Seq 2-4341:  { Cmd , MgmtId: -1, via: 2, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
 \"connections\": []\n}","wait":0}}] }
 
2014-07-24 21:27:56,284 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-10:null) SeqA
2-4341: Sending Seq 2-4341:  { Ans: , MgmtId: 345049289015, via: 2, Ver: v1, Flags: 100010,
[{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
 
 
7data@163.com
 
Mime
  • Unnamed multipart/alternative (inline, None, 0 bytes)
View raw message