[
https://issues.apache.org/jira/browse/CLOUDSTACK-5555?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13853885#comment-13853885
]
ASF subversion and git services commented on CLOUDSTACK-5555:
-------------------------------------------------------------
Commit c5e95be7ef2f07a76bddf3315a323e0c5c653344 in branch refs/heads/4.3 from
[~devdeep]
[ https://git-wip-us.apache.org/repos/asf?p=cloudstack.git;h=c5e95be ]
CLOUDSTACK-5555: Fixing booting a vm on hyperv with data disk. Made
changes to attach a data disk on scsi controller when a vm is being
created.
> [Hyper-V] Deployment of VM with a Data Disk is failing
> -------------------------------------------------------
>
> Key: CLOUDSTACK-5555
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-5555
> Project: CloudStack
> Issue Type: Bug
> Security Level: Public(Anyone can view this level - this is the
> default.)
> Components: Hypervisor Controller, Management Server
> Affects Versions: 4.3.0
> Environment: 4.3, Hyper-V
> Reporter: Abhinav Roy
> Assignee: Devdeep Singh
> Priority: Blocker
> Labels: hyper-V,, hyper-v, hyperv
> Fix For: 4.3.0
>
>
> Steps :-
> 1. Create an advanced zone setup with hyper-v as the host hypervisor type.
> 2. While deploying a VM select a data disk offering and create the VM.
> Expected behaviour :
> =========================
> 1. VM creation should be successful
> Observed behaviour :
> =========================
> VM creation with a data disk offering is failing with
> 2013-12-19 11:21:45,930 INFO [c.c.v.VirtualMachineManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Unable to start VM on
> Host[-1-Routing] due to com.cloud.agent.api.StartCommand fail on
> exceptionUnknown disk type DATADISK for disk DATA-13, vm named i-2-13-VM
> 2013-12-19 11:21:45,937 DEBUG [c.c.v.VirtualMachineManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Cleaning up resources for the vm
> VM[User|v7] in Starting state
> 2013-12-19 11:21:45,939 DEBUG [c.c.a.t.Request] (Job-Executor-14:ctx-6285db7d
> ctx-89e5ca8f) Seq 1-562367613: Sending { Cmd , MgmtId: 280320865129348, via:
> 1(10.102.192.14), Ver: v1, Flags: 100011,
> [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"vmName":"i-2-13-VM","wait":0}}]
> }
> 2013-12-19 11:21:45,939 DEBUG [c.c.a.t.Request] (Job-Executor-14:ctx-6285db7d
> ctx-89e5ca8f) Seq 1-562367613: Executing: { Cmd , MgmtId: 280320865129348,
> via: 1(10.102.192.14), Ver: v1, Flags: 100011,
> [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"vmName":"i-2-13-VM","wait":0}}]
> }
> 2013-12-19 11:21:45,940 DEBUG [c.c.a.m.DirectAgentAttache]
> (DirectAgent-303:ctx-fb4d1b3c) Seq 1-562367613: Executing request
> 2013-12-19 11:21:45,940 DEBUG [c.c.h.h.r.HypervDirectConnectResource]
> (DirectAgent-303:ctx-fb4d1b3c) POST request
> tohttp://10.102.192.14:8250/api/HypervResource/com.cloud.agent.api.StopCommand
> with
> contents{"isProxy":false,"executeInSequence":false,"vmName":"i-2-13-VM","contextMap":{},"wait":0}
> 2013-12-19 11:21:45,940 DEBUG [c.c.h.h.r.HypervDirectConnectResource]
> (DirectAgent-303:ctx-fb4d1b3c) Sending cmd to
> http://10.102.192.14:8250/api/HypervResource/com.cloud.agent.api.StopCommand
> cmd
> data:{"isProxy":false,"executeInSequence":false,"vmName":"i-2-13-VM","contextMap":{},"wait":0}
> 2013-12-19 11:21:46,177 DEBUG [c.c.a.m.AgentManagerImpl]
> (AgentManager-Handler-7:null) SeqA 3-14064: Processing Seq 3-14064: { Cmd ,
> MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":3,"_loadInfo":"{\n
> \"connections\": []\n}","wait":0}}] }
> 2013-12-19 11:21:46,180 DEBUG [c.c.a.m.AgentManagerImpl]
> (AgentManager-Handler-7:null) SeqA 3-14064: Sending Seq 3-14064: { Ans: ,
> MgmtId: 280320865129348, via: 3, Ver: v1, Flags: 100010,
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> 2013-12-19 11:21:46,491 DEBUG [c.c.a.ApiServlet]
> (catalina-exec-8:ctx-3b06c603) ===START=== 10.144.7.10 -- GET
> command=queryAsyncJobResult&jobId=a0ed9b36-ac87-4583-9213-c90285655c52&response=json&sessionkey=6bIWbcVBGNYGRf4pXkSyH9yjpx0%3D&_=1387432317177
> 2013-12-19 11:21:46,503 DEBUG [c.c.a.ApiServlet]
> (catalina-exec-8:ctx-3b06c603 ctx-119ff428) ===END=== 10.144.7.10 -- GET
> command=queryAsyncJobResult&jobId=a0ed9b36-ac87-4583-9213-c90285655c52&response=json&sessionkey=6bIWbcVBGNYGRf4pXkSyH9yjpx0%3D&_=1387432317177
> 2013-12-19 11:21:47,087 DEBUG [c.c.h.h.r.HypervDirectConnectResource]
> (DirectAgent-303:ctx-fb4d1b3c) POST response
> is[{"com.cloud.agent.api.StopAnswer":{"result":true,"details":null,"vm":null,"contextMap":{}}}]
> 2013-12-19 11:21:47,087 DEBUG [c.c.h.h.r.HypervDirectConnectResource]
> (DirectAgent-303:ctx-fb4d1b3c) executeRequest received response
> [{"com.cloud.agent.api.StopAnswer":{"result":true,"contextMap":{},"wait":0}}]
> 2013-12-19 11:21:47,087 DEBUG [c.c.a.m.DirectAgentAttache]
> (DirectAgent-303:ctx-fb4d1b3c) Seq 1-562367613: Response Received:
> 2013-12-19 11:21:47,087 DEBUG [c.c.a.t.Request]
> (DirectAgent-303:ctx-fb4d1b3c) Seq 1-562367613: Processing: { Ans: , MgmtId:
> 280320865129348, via: 1, Ver: v1, Flags: 10,
> [{"com.cloud.agent.api.StopAnswer":{"result":true,"wait":0}}] }
> 2013-12-19 11:21:47,087 DEBUG [c.c.a.t.Request] (Job-Executor-14:ctx-6285db7d
> ctx-89e5ca8f) Seq 1-562367613: Received: { Ans: , MgmtId: 280320865129348,
> via: 1, Ver: v1, Flags: 10, { StopAnswer } }
> 2013-12-19 11:21:47,095 DEBUG [c.c.n.NetworkModelImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Service SecurityGroup is not
> supported in the network id=205
> 2013-12-19 11:21:47,098 DEBUG [o.a.c.e.o.NetworkOrchestrator]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Changing active number of nics
> for network id=205 on -1
> 2013-12-19 11:21:47,102 DEBUG [o.a.c.e.o.NetworkOrchestrator]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Asking VirtualRouter to release
> NicProfile[27-13-35606b3f-7d53-4499-aead-0e408ae9ddfc-10.1.1.51-null
> 2013-12-19 11:21:47,103 DEBUG [c.c.v.VirtualMachineManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Successfully released network
> resources for the vm VM[User|v7]
> 2013-12-19 11:21:47,103 DEBUG [c.c.v.VirtualMachineManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Successfully cleanued up
> resources for the vm VM[User|v7] in Starting state
> 2013-12-19 11:21:47,105 DEBUG [c.c.v.VirtualMachineManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Root volume is ready, need to
> place VM in volume's cluster
> 2013-12-19 11:21:47,112 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Deploy avoids pods: [], clusters:
> [], hosts: [1]
> 2013-12-19 11:21:47,113 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) DeploymentPlanner allocation
> algorithm: com.cloud.deploy.FirstFitPlanner@390a1b36
> 2013-12-19 11:21:47,113 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Trying to allocate a host and
> storage pools from dc:1, pod:1,cluster:1, requested cpu: 500, requested ram:
> 536870912
> 2013-12-19 11:21:47,113 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Is ROOT volume READY (pool
> already allocated)?: Yes
> 2013-12-19 11:21:47,113 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) DeploymentPlan has host_id
> specified, choosing this host and making no checks on this host: 1
> 2013-12-19 11:21:47,114 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) The specified host is in avoid set
> 2013-12-19 11:21:47,114 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Cannnot deploy to specified host,
> returning.
> 2013-12-19 11:21:47,125 DEBUG [c.c.c.CapacityManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) 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
> 2013-12-19 11:21:47,130 DEBUG [c.c.c.CapacityManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Hosts's actual total CPU: 9576
> and CPU after applying overprovisioning: 9576
> 2013-12-19 11:21:47,130 DEBUG [c.c.c.CapacityManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Hosts's actual total RAM:
> 17166315520 and RAM after applying overprovisioning: 17166315520
> 2013-12-19 11:21:47,130 DEBUG [c.c.c.CapacityManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) release cpu from host: 1, old
> used: 5500,reserved: 0, actual total: 9576, total with overprovisioning:
> 9576; new used: 5000,reserved:0; movedfromreserved:
> false,moveToReserveredfalse
> 2013-12-19 11:21:47,130 DEBUG [c.c.c.CapacityManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) release mem from host: 1, old
> used: 5368709120,reserved: 0, total: 17166315520; new used:
> 4831838208,reserved:0; movedfromreserved: false,moveToReserveredfalse
> 2013-12-19 11:21:47,153 DEBUG [c.c.c.CapacityManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) VM state transitted from :Stopped
> to Starting with event: StartRequestedvm's original host id: null new host
> id: null host id before state transition: null
> 2013-12-19 11:21:47,153 DEBUG [c.c.v.VirtualMachineManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Successfully transitioned to
> start state for VM[User|v7] reservation id =
> 78a628b6-42b7-42d4-a308-d18d7ba36243
> 2013-12-19 11:21:47,159 DEBUG [c.c.v.VirtualMachineManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Trying to deploy VM, vm has dcId:
> 1 and podId: 1
> 2013-12-19 11:21:47,159 DEBUG [c.c.v.VirtualMachineManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Deploy avoids pods: [], clusters:
> [], hosts: [1]
> 2013-12-19 11:21:47,162 DEBUG [c.c.v.VirtualMachineManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Root volume is ready, need to
> place VM in volume's cluster
> 2013-12-19 11:21:47,162 DEBUG [c.c.v.VirtualMachineManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Vol[15|vm=13|ROOT] is READY,
> changing deployment plan to use this pool's dcId: 1 , podId: 1 , and
> clusterId: 1
> 2013-12-19 11:21:47,169 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Deploy avoids pods: [], clusters:
> [], hosts: [1]
> 2013-12-19 11:21:47,170 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) DeploymentPlanner allocation
> algorithm: com.cloud.deploy.FirstFitPlanner@390a1b36
> 2013-12-19 11:21:47,170 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Trying to allocate a host and
> storage pools from dc:1, pod:1,cluster:1, requested cpu: 500, requested ram:
> 536870912
> 2013-12-19 11:21:47,170 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Is ROOT volume READY (pool
> already allocated)?: Yes
> 2013-12-19 11:21:47,170 DEBUG [c.c.d.FirstFitPlanner]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Searching resources only under
> specified Cluster: 1
> 2013-12-19 11:21:47,175 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Checking resources in Cluster: 1
> under Pod: 1
> 2013-12-19 11:21:47,175 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f FirstFitRoutingAllocator) Looking
> for hosts in dc: 1 pod:1 cluster:1
> 2013-12-19 11:21:47,178 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f FirstFitRoutingAllocator)
> FirstFitAllocator has 2 hosts to check for allocation: [Host[-1-Routing],
> Host[-4-Routing]]
> 2013-12-19 11:21:47,181 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f FirstFitRoutingAllocator) Found 2
> hosts for allocation after prioritization: [Host[-1-Routing],
> Host[-4-Routing]]
> 2013-12-19 11:21:47,181 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f FirstFitRoutingAllocator) Looking
> for speed=500Mhz, Ram=512
> 2013-12-19 11:21:47,181 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f FirstFitRoutingAllocator) Host
> name: 10.102.192.14, hostId: 1 is in avoid set, skipping this and trying
> other available hosts
> 2013-12-19 11:21:47,186 DEBUG [c.c.c.CapacityManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f FirstFitRoutingAllocator) Host: 4
> has cpu capability (cpu:4, speed:2394) to support requested CPU: 1 and
> requested speed: 500
> 2013-12-19 11:21:47,186 DEBUG [c.c.c.CapacityManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f FirstFitRoutingAllocator) Checking
> if host: 4 has enough capacity for requested CPU: 500 and requested RAM:
> 536870912 , cpuOverprovisioningFactor: 1.0
> 2013-12-19 11:21:47,188 DEBUG [c.c.c.CapacityManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f FirstFitRoutingAllocator) Hosts's
> actual total CPU: 9576 and CPU after applying overprovisioning: 9576
> 2013-12-19 11:21:47,188 DEBUG [c.c.c.CapacityManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f FirstFitRoutingAllocator) Free
> CPU: 9576 , Requested CPU: 500
> 2013-12-19 11:21:47,188 DEBUG [c.c.c.CapacityManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f FirstFitRoutingAllocator) Free
> RAM: 17166323712 , Requested RAM: 536870912
> 2013-12-19 11:21:47,188 DEBUG [c.c.c.CapacityManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f FirstFitRoutingAllocator) Host has
> enough CPU and RAM available
> 2013-12-19 11:21:47,188 DEBUG [c.c.c.CapacityManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f FirstFitRoutingAllocator) STATS:
> Can alloc CPU from host: 4, used: 0, reserved: 0, actual total: 9576, total
> with overprovisioning: 9576; requested cpu:500,alloc_from_last_host?:false
> ,considerReservedCapacity?: true
> 2013-12-19 11:21:47,188 DEBUG [c.c.c.CapacityManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f FirstFitRoutingAllocator) STATS:
> Can alloc MEM from host: 4, used: 0, reserved: 0, total: 17166323712;
> requested mem: 536870912,alloc_from_last_host?:false
> ,considerReservedCapacity?: true
> 2013-12-19 11:21:47,188 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f FirstFitRoutingAllocator) Found a
> suitable host, adding to list: 4
> 2013-12-19 11:21:47,188 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f FirstFitRoutingAllocator) Host
> Allocator returning 1 suitable hosts
> 2013-12-19 11:21:47,190 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Checking suitable pools for
> volume (Id, Type): (15,ROOT)
> 2013-12-19 11:21:47,190 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Volume has pool already
> allocated, checking if pool can be reused, poolId: 1
> 2013-12-19 11:21:47,191 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Planner need not allocate a pool
> for this volume since its READY
> 2013-12-19 11:21:47,191 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Checking suitable pools for
> volume (Id, Type): (16,DATADISK)
> 2013-12-19 11:21:47,191 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Volume has pool already
> allocated, checking if pool can be reused, poolId: 1
> 2013-12-19 11:21:47,192 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Planner need not allocate a pool
> for this volume since its READY
> 2013-12-19 11:21:47,192 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Trying to find a potenial host
> and associated storage pools from the suitable host/pool lists for this VM
> 2013-12-19 11:21:47,192 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Checking if host: 4 can access
> any suitable storage pool for volume: DATADISK
> 2013-12-19 11:21:47,193 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Host: 4 cannot access pool: 1
> 2013-12-19 11:21:47,193 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Could not find a potential host
> that has associated storage pools from the suitable host/pool lists for this
> VM
> 2013-12-19 11:21:47,194 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Could not find suitable
> Deployment Destination for this VM under any clusters, returning.
> 2013-12-19 11:21:47,194 DEBUG [c.c.d.FirstFitPlanner]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Searching resources only under
> specified Cluster: 1
> 2013-12-19 11:21:47,195 DEBUG [c.c.d.FirstFitPlanner]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) The specified cluster is in avoid
> set, returning.
> 2013-12-19 11:21:47,202 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Deploy avoids pods: [], clusters:
> [1], hosts: [1, 4]
> 2013-12-19 11:21:47,203 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) DeploymentPlanner allocation
> algorithm: com.cloud.deploy.FirstFitPlanner@390a1b36
> 2013-12-19 11:21:47,203 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Trying to allocate a host and
> storage pools from dc:1, pod:1,cluster:null, requested cpu: 500, requested
> ram: 536870912
> 2013-12-19 11:21:47,203 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Is ROOT volume READY (pool
> already allocated)?: No
> 2013-12-19 11:21:47,203 DEBUG [c.c.d.FirstFitPlanner]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Searching resources only under
> specified Pod: 1
> 2013-12-19 11:21:47,203 DEBUG [c.c.d.FirstFitPlanner]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Listing clusters in order of
> aggregate capacity, that have (atleast one host with) enough CPU and RAM
> capacity under this Pod: 1
> 2013-12-19 11:21:47,206 DEBUG [c.c.d.FirstFitPlanner]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Removing from the clusterId list
> these clusters from avoid set: [1]
> 2013-12-19 11:21:47,206 DEBUG [c.c.d.FirstFitPlanner]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) No clusters found after removing
> disabled clusters and clusters in avoid list, returning.
> 2013-12-19 11:21:47,213 DEBUG [c.c.c.CapacityManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) VM state transitted from
> :Starting to Stopped with event: OperationFailedvm's original host id: null
> new host id: null host id before state transition: null
> 2013-12-19 11:21:47,221 DEBUG [c.c.v.UserVmManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) Destroying vm VM[User|v7] as it
> failed to create on Host with Id:null
> 2013-12-19 11:21:47,224 DEBUG [c.c.c.CapacityManagerImpl]
> (Job-Executor-14:ctx-6285db7d ctx-89e5ca8f) VM state transitted from :Stopped
> to Error with event: OperationFailedToErrorvm's original host id: null new
> host id: null host id before state transition: null
> 2013-12-19 11:21:47,263 WARN [o.a.c.alerts] (Job-Executor-14:ctx-6285db7d
> ctx-89e5ca8f) alertType:: 8 // dataCenterId:: 1 // podId:: 1 // clusterId::
> null // message:: Failed to deploy Vm with Id: 13, on Host with Id: null
> 2013-12-19 11:21:47,309 ERROR [c.c.a.ApiAsyncJobDispatcher]
> (Job-Executor-14:ctx-6285db7d) 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:563)
> 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:209)
> at
> com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3559)
> at
> com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3140)
> at
> com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3126)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:616)
> 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 $Proxy170.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:521)
> 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:1110)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
> at java.lang.Thread.run(Thread.java:679)
> Caused by: com.cloud.exception.InsufficientServerCapacityException: Unable to
> create a deployment for VM[User|v7]Scope=interface com.cloud.dc.DataCenter;
> id=1
> at
> com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachineManagerImpl.java:869)
> at
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:713)
> at
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:559)
> ... 38 more
--
This message was sent by Atlassian JIRA
(v6.1.4#6159)
