cloudstack-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "ASF subversion and git services (JIRA)" <j...@apache.org>
Subject [jira] [Commented] (CLOUDSTACK-5555) [Hyper-V] Deployment of VM with a Data Disk is failing
Date Fri, 20 Dec 2013 11:42:09 GMT

    [ 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)

Mime
View raw message