Abhinav Roy created CLOUDSTACK-5555:
---------------------------------------
Summary: [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
Priority: Critical
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)
