cloudstack-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Prachi Damle (JIRA)" <j...@apache.org>
Subject [jira] [Commented] (CLOUDSTACK-5090) Anti-Affinity: VM fails to start on a cluster belonging to a different pod.
Date Fri, 08 Nov 2013 23:48:19 GMT

    [ https://issues.apache.org/jira/browse/CLOUDSTACK-5090?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13817861#comment-13817861
] 

Prachi Damle commented on CLOUDSTACK-5090:
------------------------------------------

I see that currently a VM is running in some pod, we are always trying to start it in that
pod. The podId and zoneId is persisted in the DB when the VM is first started and is never
cleared even if VM is stopped.

So on next starts, VM always gets deployed within the pod - it can move within clusters of
the same pod.

In case of the above test, both clusters uder the VM's pod cannot be used to place the VM
due to anti-affinity. Even if there is a third cluster, it is present in a different pod.
So Vm does not get started in the cluster#3.

Now, in theory for advance zone, VM should be able to move out of pod. Need to check more
why we limit the VM to the pod.


> Anti-Affinity: VM fails to start on a cluster belonging to a different pod.
> ---------------------------------------------------------------------------
>
>                 Key: CLOUDSTACK-5090
>                 URL: https://issues.apache.org/jira/browse/CLOUDSTACK-5090
>             Project: CloudStack
>          Issue Type: Bug
>      Security Level: Public(Anyone can view this level - this is the default.) 
>          Components: Management Server
>    Affects Versions: 4.2.1
>            Reporter: Chandan Purushothama
>            Assignee: Prachi Damle
>             Fix For: 4.2.1
>
>         Attachments: mysql_cloudstack_db_dumps.zip
>
>
> Test scenario:
> Set up have 3 clusters with 1 host each. One of the cluster's belong to a different pod:
> 1.As regular user - U1 , Create multiple anti-affinity group.
> 2. Deploy 2 Vms , say Vm11(host1) and VM12(host2) using affinity group A1.
> 3. Deploy 1 Vm , say Vm21(host1) using affinity group A2.
> 4. Stop Vm11.
> 5. Update the list of affinityGroups for this VM to "A1" and "A2"
> 6. Start the VM. 
> ===========
> Start VM Job:
> ===========
> 2013-11-07 14:43:15,755 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-7:null)
submit async job-40 = [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ], details: AsyncJobVO {id:40,
userId: 3, accountId: 3, sessionKey: null, instanceType: VirtualMachine, instanceId: 9, cmd:
org.apache.cloudstack.api.command.user.vm.StartVMCmd, cmdOriginator: null, cmdInfo: {"response":"json","id":"02e909e8-f28f-40b7-9830-ea68e44aa0ed","sessionkey":"Xj52fOTZWqAUf0nnCSUZlybSVfI\u003d","cmdEventType":"VM.START","ctxUserId":"3","httpmethod":"GET","_":"1383864701141","ctxAccountId":"3","ctxStartEventId":"138"},
cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0, processStatus: 0, resultCode:
0, result: null, initMsid: 7471666038533, completeMsid: null, lastUpdated: null, lastPolled:
null, created: null}
> 2013-11-07 14:43:15,756 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===END===
 10.214.4.75 -- GET  command=startVirtualMachine&id=02e909e8-f28f-40b7-9830-ea68e44aa0ed&response=json&sessionkey=Xj52fOTZWqAUf0nnCSUZlybSVfI%3D&_=1383864701141
> 2013-11-07 14:43:15,758 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Executing org.apache.cloudstack.api.command.user.vm.StartVMCmd
for job-40 = [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]
> 2013-11-07 14:43:15,774 DEBUG [cloud.user.AccountManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Access to VM[User|TestVM-1] granted to Acct[609f0727-6b59-45aa-9bc2-14877b39b4e1-test]
by DomainChecker_EnhancerByCloudStack_a4e5904f
> 2013-11-07 14:43:15,784 DEBUG [cloud.network.NetworkModelImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Service SecurityGroup is not supported in the
network id=205
> 2013-11-07 14:43:15,788 DEBUG [cloud.network.NetworkModelImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Service SecurityGroup is not supported in the
network id=205
> 2013-11-07 14:43:15,811 DEBUG [cloudstack.affinity.HostAntiAffinityProcessor] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Processing affinity group A1 for VM Id: 9
> 2013-11-07 14:43:15,813 DEBUG [cloudstack.affinity.HostAntiAffinityProcessor] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Added host 1 to avoid set, since VM 11 is present
on the host
> 2013-11-07 14:43:15,814 DEBUG [cloudstack.affinity.HostAntiAffinityProcessor] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Processing affinity group A2 for VM Id: 9
> 2013-11-07 14:43:15,816 DEBUG [cloudstack.affinity.HostAntiAffinityProcessor] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Added host 6 to avoid set, since VM 12 is present
on the host
> 2013-11-07 14:43:15,835 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Deploy avoids pods: [], clusters: [], hosts: [1,
6]
> 2013-11-07 14:43:15,836 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner_EnhancerByCloudStack_9c110ba0@49591f8a
> 2013-11-07 14:43:15,837 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Trying to allocate a host and storage pools from
dc:1, pod:1,cluster:3, requested cpu: 500, requested ram: 536870912
> 2013-11-07 14:43:15,837 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Is ROOT volume READY (pool already allocated)?:
Yes
> 2013-11-07 14:43:15,837 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) This VM has last host_id specified, trying to
choose the same host: 6
> 2013-11-07 14:43:15,838 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) The last host of this VM is in avoid set
> 2013-11-07 14:43:15,838 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Cannot choose the last host to deploy this VM
> 2013-11-07 14:43:15,853 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Searching resources only under specified Cluster:
3
> 2013-11-07 14:43:15,918 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Checking resources in Cluster: 3 under Pod: 1
> 2013-11-07 14:43:15,921 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) Looking for hosts in
dc: 1  pod:1  cluster:3
> 2013-11-07 14:43:15,925 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) FirstFitAllocator has
1 hosts to check for allocation: [Host[-6-Routing]]
> 2013-11-07 14:43:15,928 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) Found 1 hosts for allocation
after prioritization: [Host[-6-Routing]]
> 2013-11-07 14:43:15,928 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) Looking for speed=500Mhz,
Ram=512
> 2013-11-07 14:43:15,928 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) Host name: 10.223.59.66,
hostId: 6 is in avoid set, skipping this and trying other available hosts
> 2013-11-07 14:43:15,928 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) Host Allocator returning
0 suitable hosts
> 2013-11-07 14:43:15,928 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) No suitable hosts found
> 2013-11-07 14:43:15,929 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) No suitable hosts found under this Cluster: 3
> 2013-11-07 14:43:15,931 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Could not find suitable Deployment Destination
for this VM under any clusters, returning.
> 2013-11-07 14:43:15,931 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Searching resources only under specified Cluster:
3
> 2013-11-07 14:43:15,932 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) The specified cluster is in avoid set, returning.
> 2013-11-07 14:43:15,942 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) VM state transitted from :Stopped to Starting
with event: StartRequestedvm's original host id: 6 new host id: null host id before state
transition: null
> 2013-11-07 14:43:15,942 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Successfully transitioned to start state for VM[User|TestVM-1]
reservation id = ad7d6cee-e20b-4945-8b02-3e2fb3aeeec8
> 2013-11-07 14:43:15,949 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Trying to deploy VM, vm has dcId: 1 and podId:
1
> 2013-11-07 14:43:15,949 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Deploy avoids pods: null, clusters: null, hosts:
null
> 2013-11-07 14:43:15,951 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Root volume is ready, need to place VM in volume's
cluster
> 2013-11-07 14:43:15,952 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Vol[11|vm=9|ROOT] is READY, changing deployment
plan to use this pool's dcId: 1 , podId: 1 , and clusterId: 3
> 2013-11-07 14:43:15,954 DEBUG [cloudstack.affinity.HostAntiAffinityProcessor] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Processing affinity group A1 for VM Id: 9
> 2013-11-07 14:43:15,957 DEBUG [cloudstack.affinity.HostAntiAffinityProcessor] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Added host 1 to avoid set, since VM 11 is present
on the host
> 2013-11-07 14:43:15,958 DEBUG [cloudstack.affinity.HostAntiAffinityProcessor] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Processing affinity group A2 for VM Id: 9
> 2013-11-07 14:43:15,960 DEBUG [cloudstack.affinity.HostAntiAffinityProcessor] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Added host 6 to avoid set, since VM 12 is present
on the host
> 2013-11-07 14:43:15,965 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Deploy avoids pods: [], clusters: [], hosts: [1,
6]
> 2013-11-07 14:43:15,966 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner_EnhancerByCloudStack_9c110ba0@49591f8a
> 2013-11-07 14:43:15,966 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Trying to allocate a host and storage pools from
dc:1, pod:1,cluster:3, requested cpu: 500, requested ram: 536870912
> 2013-11-07 14:43:15,966 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Is ROOT volume READY (pool already allocated)?:
Yes
> 2013-11-07 14:43:15,966 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) This VM has last host_id specified, trying to
choose the same host: 6
> 2013-11-07 14:43:15,967 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) The last host of this VM is in avoid set
> 2013-11-07 14:43:15,967 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Cannot choose the last host to deploy this VM
> 2013-11-07 14:43:15,967 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Searching resources only under specified Cluster:
3
> 2013-11-07 14:43:15,990 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Checking resources in Cluster: 3 under Pod: 1
> 2013-11-07 14:43:15,990 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) Looking for hosts in
dc: 1  pod:1  cluster:3
> 2013-11-07 14:43:15,995 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) FirstFitAllocator has
1 hosts to check for allocation: [Host[-6-Routing]]
> 2013-11-07 14:43:15,997 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) Found 1 hosts for allocation
after prioritization: [Host[-6-Routing]]
> 2013-11-07 14:43:15,997 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) Looking for speed=500Mhz,
Ram=512
> 2013-11-07 14:43:15,997 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) Host name: 10.223.59.66,
hostId: 6 is in avoid set, skipping this and trying other available hosts
> 2013-11-07 14:43:15,997 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) Host Allocator returning
0 suitable hosts
> 2013-11-07 14:43:15,997 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) No suitable hosts found
> 2013-11-07 14:43:15,997 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) No suitable hosts found under this Cluster: 3
> 2013-11-07 14:43:15,998 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Could not find suitable Deployment Destination
for this VM under any clusters, returning.
> 2013-11-07 14:43:15,998 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Searching resources only under specified Cluster:
3
> 2013-11-07 14:43:16,000 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) The specified cluster is in avoid set, returning.
> 2013-11-07 14:43:16,003 DEBUG [cloudstack.affinity.HostAntiAffinityProcessor] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Processing affinity group A1 for VM Id: 9
> 2013-11-07 14:43:16,005 DEBUG [cloudstack.affinity.HostAntiAffinityProcessor] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Added host 1 to avoid set, since VM 11 is present
on the host
> 2013-11-07 14:43:16,005 DEBUG [cloudstack.affinity.HostAntiAffinityProcessor] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Processing affinity group A2 for VM Id: 9
> 2013-11-07 14:43:16,007 DEBUG [cloudstack.affinity.HostAntiAffinityProcessor] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Added host 6 to avoid set, since VM 12 is present
on the host
> 2013-11-07 14:43:16,013 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Deploy avoids pods: [], clusters: [3], hosts:
[1, 6]
> 2013-11-07 14:43:16,013 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner_EnhancerByCloudStack_9c110ba0@49591f8a
> 2013-11-07 14:43:16,013 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Trying to allocate a host and storage pools from
dc:1, pod:1,cluster:null, requested cpu: 500, requested ram: 536870912
> 2013-11-07 14:43:16,014 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Is ROOT volume READY (pool already allocated)?:
No
> 2013-11-07 14:43:16,014 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) This VM has last host_id specified, trying to
choose the same host: 6
> 2013-11-07 14:43:16,015 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) The last host of this VM is in avoid set
> 2013-11-07 14:43:16,015 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Cannot choose the last host to deploy this VM
> 2013-11-07 14:43:16,015 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Searching resources only under specified Pod:
1
> 2013-11-07 14:43:16,018 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Listing clusters in order of aggregate capacity,
that have (atleast one host with) enough CPU and RAM capacity under this Pod: 1
> 2013-11-07 14:43:16,037 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Removing from the clusterId list these clusters
from avoid set: [3]
> 2013-11-07 14:43:16,078 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Checking resources in Cluster: 1 under Pod: 1
> 2013-11-07 14:43:16,078 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) Looking for hosts in
dc: 1  pod:1  cluster:1
> 2013-11-07 14:43:16,082 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) FirstFitAllocator has
1 hosts to check for allocation: [Host[-1-Routing]]
> 2013-11-07 14:43:16,084 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) Found 1 hosts for allocation
after prioritization: [Host[-1-Routing]]
> 2013-11-07 14:43:16,084 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) Looking for speed=500Mhz,
Ram=512
> 2013-11-07 14:43:16,084 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) Host name: 10.223.59.67,
hostId: 1 is in avoid set, skipping this and trying other available hosts
> 2013-11-07 14:43:16,084 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] FirstFitRoutingAllocator) Host Allocator returning
0 suitable hosts
> 2013-11-07 14:43:16,084 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) No suitable hosts found
> 2013-11-07 14:43:16,084 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) No suitable hosts found under this Cluster: 1
> 2013-11-07 14:43:16,085 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Could not find suitable Deployment Destination
for this VM under any clusters, returning.
> 2013-11-07 14:43:16,086 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Searching resources only under specified Pod:
1
> 2013-11-07 14:43:16,086 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Listing clusters in order of aggregate capacity,
that have (atleast one host with) enough CPU and RAM capacity under this Pod: 1
> 2013-11-07 14:43:16,088 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Removing from the clusterId list these clusters
from avoid set: [1, 3]
> 2013-11-07 14:43:16,089 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) No clusters found after removing disabled clusters
and clusters in avoid list, returning.
> 2013-11-07 14:43:16,218 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) VM state transitted from :Starting to Stopped
with event: OperationFailedvm's original host id: 6 new host id: null host id before state
transition: null
> 2013-11-07 14:43:16,260 INFO  [user.vm.StartVMCmd] (Job-Executor-31:job-40 = [ c6b67100-24d5-4eaf-8104-a93c3c75ce16
]) com.cloud.exception.InsufficientServerCapacityException: Unable to create a deployment
for VM[User|TestVM-1]Scope=interface com.cloud.dc.DataCenter; id=1
> 2013-11-07 14:43:16,260 INFO  [user.vm.StartVMCmd] (Job-Executor-31:job-40 = [ c6b67100-24d5-4eaf-8104-a93c3c75ce16
]) Unable to create a deployment for VM[User|TestVM-1], Please check the affinity groups provided,
there may not be sufficient capacity to follow them
> com.cloud.exception.InsufficientServerCapacityException: Unable to create a deployment
for VM[User|TestVM-1]Scope=interface com.cloud.dc.DataCenter; id=1
>         at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:841)
>         at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:577)
>         at org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.deployVirtualMachine(VMEntityManagerImpl.java:242)
>         at org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.deploy(VirtualMachineEntityImpl.java:209)
>         at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3440)
>         at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:1959)
>         at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
>         at org.apache.cloudstack.api.command.user.vm.StartVMCmd.execute(StartVMCmd.java:120)
>         at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:158)
>         at com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:531)
>         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)
> 2013-11-07 14:43:16,263 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-31:job-40
= [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ]) Complete async job-40 = [ c6b67100-24d5-4eaf-8104-a93c3c75ce16
], jobStatus: 2, resultCode: 530, result: Error Code: 533 Error text: Unable to create a deployment
for VM[User|TestVM-1], Please check the affinity groups provided, there may not be sufficient
capacity to follow them
> 2013-11-07 14:43:17,129 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null)
SeqA 8-1258: Processing Seq 8-1258:  { Cmd , MgmtId: -1, via: 8, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":6,"_loadInfo":"{\n
 \"connections\": []\n}","wait":0}}] }
> 2013-11-07 14:43:17,137 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null)
SeqA 8-1258: Sending Seq 8-1258:  { Ans: , MgmtId: 7471666038533, via: 8, Ver: v1, Flags:
100010, [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> 2013-11-07 14:43:18,900 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===START===
 10.214.4.75 -- GET  command=queryAsyncJobResult&jobId=c6b67100-24d5-4eaf-8104-a93c3c75ce16&response=json&sessionkey=Xj52fOTZWqAUf0nnCSUZlybSVfI%3D&_=1383864704475
> 2013-11-07 14:43:18,913 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-2:null)
Async job-40 = [ c6b67100-24d5-4eaf-8104-a93c3c75ce16 ] completed
> 2013-11-07 14:43:18,918 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===END===
 10.214.4.75 -- GET  command=queryAsyncJobResult&jobId=c6b67100-24d5-4eaf-8104-a93c3c75ce16&response=json&sessionkey=Xj52fOTZWqAUf0nnCSUZlybSVfI%3D&_=1383864704475



--
This message was sent by Atlassian JIRA
(v6.1#6144)

Mime
View raw message