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 青山依旧 <di...@foxmail.com> on 2014/07/25 16:46:15 UTC
回复： Re: cloudstack + xen 无法创建WINDOWS系列实例
hypervisor如果不支持HVM时，是可以创建Linux 系统，
创建Windows vm则需要hypervisor 必须支持HVM！！！
------------------ 原始邮件 ------------------
发件人: "wanghaicheng2004@gmail.com";<wa...@gmail.com>;
发送时间: 2014年7月25日(星期五) 晚上10:42
收件人: "users-cn"<us...@cloudstack.apache.org>;
主题: Re: Re: cloudstack + xen 无法创建WINDOWS系列实例
哥们，你不是说过能够创建linux么？怎么hvm功能又没开
资源肯定没用光 LINUX还能继续创建，WINDOWS不行 我是ESXI5下的二级虚拟环境
7data@163.com
发件人： YUAN, GUI -HBIS发送时间： 2014-07-24 18:50收件人： users-cn@cloudstack.apache.org主题： RE: cloudstack无法创建windows系列实例问题建的VM超限，资源用光了，
可以看控制台，CPU、内存、IP地址、存储。看一下哪个满了，或者剩下的不够你新创建一个VM了。
wanghaicheng2004@gmail.com
发件人： 7data@163.com
发送时间： 2014-07-25 21:55
收件人： users-cn
主题： Re: Re: cloudstack + xen 无法创建WINDOWS系列实例
问题解决 VHM 硬件虚拟化没开
7data@163.com
发件人： HellCat发送时间： 2014-07-25 17:40收件人： users-cn主题： Re: 回复: cloudstack + xen 无法创建WINDOWS系列实例好像你Xenserver服务器的硬件虚拟化功能没打开
------------------ Original ------------------
From: "tanthalas";<ta...@hotmail.com>;
Date: Fri, Jul 25, 2014 05:31 PM
To: "users-cn"<us...@cloudstack.apache.org>; "cloudstackfqa2013"<cl...@163.com>;
Subject: 回复: 回复: cloudstack + xen 无法创建WINDOWS系列实例
原本XenServer对这些操作系统都应该是支持的，但从log来看是XenServer报出来的错误，并不是cloudstack的判断。暂时看不出来原因，不知道你之前给的mysql命令是不是有关系，选择的操作系统类型是不是有关系，这些需要你对XenServer有比较多的了解。
刘宇超
?发件人：?cloudstackfqa2013发送时间：?2014-07-25?09:43收件人：?users-cn主题：?回复: 回复: cloudstack + xen 无法创建WINDOWS系列实例您好：
?
???? 别老是抓着 cloudstack 的日志不放，cloudstack 只是一个调用者，出错很多问题不会告诉你的。
这个进入那个VM所在的宿主机，把xenserver的日志也抓出来，看一下，就明白了。
?
?
?
?
cloudstackfqa2013
?
发件人： leifan8440
发送时间： 2014-07-25 09:24
收件人： users-cn@cloudstack.apache.org
主题： 回复: cloudstack + xen 无法创建WINDOWS系列实例
? 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
回复: 回复: cloudstack + xen 无法创建WINDOWS系列实例
Posted by "7data@163.com" <7d...@163.com>.
青山依旧 说的很对， 只要我把linux ISO强制在数据库修改成hvm=0 就可以了 windows则必须HVM支持
7data@163.com
发件人： 青山依旧发送时间： 2014-07-25 22:46收件人： users-cn主题： 回复： Re: cloudstack + xen 无法创建WINDOWS系列实例hypervisor如果不支持HVM时，是可以创建Linux 系统，
创建Windows vm则需要hypervisor 必须支持HVM！！！
------------------ 原始邮件 ------------------
发件人: "wanghaicheng2004@gmail.com";<wa...@gmail.com>;
发送时间: 2014年7月25日(星期五) 晚上10:42
收件人: "users-cn"<us...@cloudstack.apache.org>;
主题: Re: Re: cloudstack + xen 无法创建WINDOWS系列实例
哥们，你不是说过能够创建linux么？怎么hvm功能又没开
资源肯定没用光 LINUX还能继续创建，WINDOWS不行 我是ESXI5下的二级虚拟环境
7data@163.com
发件人： YUAN, GUI -HBIS发送时间： 2014-07-24 18:50收件人： users-cn@cloudstack.apache.org主题： RE: cloudstack无法创建windows系列实例问题建的VM超限，资源用光了，
可以看控制台，CPU、内存、IP地址、存储。看一下哪个满了，或者剩下的不够你新创建一个VM了。
wanghaicheng2004@gmail.com
发件人： 7data@163.com
发送时间： 2014-07-25 21:55
收件人： users-cn
主题： Re: Re: cloudstack + xen 无法创建WINDOWS系列实例
问题解决 VHM 硬件虚拟化没开
7data@163.com
发件人： HellCat发送时间： 2014-07-25 17:40收件人： users-cn主题： Re: 回复: cloudstack + xen 无法创建WINDOWS系列实例好像你Xenserver服务器的硬件虚拟化功能没打开
------------------ Original ------------------
From: "tanthalas";<ta...@hotmail.com>;
Date: Fri, Jul 25, 2014 05:31 PM
To: "users-cn"<us...@cloudstack.apache.org>; "cloudstackfqa2013"<cl...@163.com>;
Subject: 回复: 回复: cloudstack + xen 无法创建WINDOWS系列实例
原本XenServer对这些操作系统都应该是支持的，但从log来看是XenServer报出来的错误，并不是cloudstack的判断。暂时看不出来原因，不知道你之前给的mysql命令是不是有关系，选择的操作系统类型是不是有关系，这些需要你对XenServer有比较多的了解。
刘宇超
?发件人：?cloudstackfqa2013发送时间：?2014-07-25?09:43收件人：?users-cn主题：?回复: 回复: cloudstack + xen 无法创建WINDOWS系列实例您好：
?
???? 别老是抓着 cloudstack 的日志不放，cloudstack 只是一个调用者，出错很多问题不会告诉你的。
这个进入那个VM所在的宿主机，把xenserver的日志也抓出来，看一下，就明白了。
?
?
?
?
cloudstackfqa2013
?
发件人： leifan8440
发送时间： 2014-07-25 09:24
收件人： users-cn@cloudstack.apache.org
主题： 回复: cloudstack + xen 无法创建WINDOWS系列实例
? 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