You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@cloudstack.apache.org by "Devdeep Singh (JIRA)" <ji...@apache.org> on 2013/12/20 12:44:10 UTC
[jira] [Resolved] (CLOUDSTACK-5555) [Hyper-V] Deployment of VM with
a Data Disk is failing
[ https://issues.apache.org/jira/browse/CLOUDSTACK-5555?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Devdeep Singh resolved CLOUDSTACK-5555.
---------------------------------------
Resolution: Fixed
> [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)