Hi all, I got the machines to work. Here is my workaround: - removed all disks from VM (exept root disk) - power on VM (which worked now) - hot attached the disks again - rebooted machine
My disks show up as sdX now which was vdX before. Something happened to the config after migrating the storage. This was the important error message: "Only 1 ide controller is supported" Many thanks to Andrija. Regards, Ingo -----Ursprüngliche Nachricht----- Von: Andrija Panic [mailto:[email protected]] Gesendet: Sonntag, 31. Mai 2015 12:18 An: [email protected] Betreff: Re: hostId: 25 is in avoid set, skipping this and trying other available hosts 1. 2015-05-30 22:43:51,109 DEBUG [c.c.a.t.Request] (AgentManager-Handler-16:null) Seq 25-2722988924698902936: Processing: { Ans: , MgmtId: 90520736249963, via: 25, Ver: v1, Flags: 10, [{"org.apache.cloudstack.storage.command.CopyCmdAnswer":{"result":false,"details":"com.cloud.utils.exception.CloudRuntimeException: org.libvirt.LibvirtException: internal error: Child process (/usr/bin/qemu-img create -f qcow2 -o compat=0.10 /mnt/882c3906-e385-3859-b26e-313bd710b364/da376a72-c0ab-477f-a696-a85f4aafa753.qcow2 419430400K) unexpected exit status 1: qemu-img: /mnt/882c3906-e385-3859-b26e-313bd710b364/da376a72-c0ab-477f-a696-a85f4aafa753.qcow2: Could not write qcow2 header: No space left on device\n","wait":0}}] } Not sure if it's related... On 31 May 2015 at 12:13, Andrija Panic <[email protected]> wrote: > Do you have VM name that you are having problems with ? (i-78-387-VM > ?) > > On 31 May 2015 at 02:01, Jochim, Ingo <[email protected]> wrote: > >> Hi Andrija, >> >> I'm able to run clients using ceph on all my hypervisors and can live >> migrate them between all of them. >> So I think librados should work in general. >> I can also power on other clients. Capacity on the hypervisors should >> not be the problem. >> >> Here are parts of my large logfile: >> >> https://paste.ee/p/PdHgN >> https://paste.ee/p/jXZBL >> https://paste.ee/p/TS8ed >> https://paste.ee/p/lLqGS >> >> >> Thanks and regards, >> Ingo >> >> >> >> ________________________________________ >> Von: Andrija Panic [[email protected]] >> Gesendet: Sonntag, 31. Mai 2015 00:32 >> An: [email protected] >> Betreff: Re: hostId: 25 is in avoid set, skipping this and trying >> other available hosts >> >> well, send whole log (on pastebin.com) so we can check... >> >> migrating from storage to storage, can result in various errors :) >> (related to disk offerings, if you experimented, tagged them, etc) >> was the VM runing on CEPH at all ? >> do you have librbd installed on KVM nodes ? etc.. >> >> On 31 May 2015 at 00:28, Jochim, Ingo <[email protected]> wrote: >> >> > Hi Andrija, >> > >> > there should be plenty of free CPU and RAM. >> > This VM was on a day ago. The only thing I did was migrating the >> > disk >> from >> > NFS to Ceph storage. >> > The hosts are all up. >> > Is there anything I can check? >> > >> > Regards, >> > Ingo >> > >> > >> > ________________________________________ >> > Von: Andrija Panic [[email protected]] >> > Gesendet: Sonntag, 31. Mai 2015 00:20 >> > An: [email protected] >> > Betreff: Re: hostId: 25 is in avoid set, skipping this and trying >> > other available hosts >> > >> > 2015-05-30 23:05:56,115 INFO [c.c.v.VirtualMachineManagerImpl] >> > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 ctx-50d9961e) >> Unable >> > to start VM on Host[-25-Routing] due to internal error: Only 1 ide >> > controller is supported >> > >> > cant be 100% sure, but that seem sto be problem... >> > >> > Also search in above the sent lines, there is usually clear >> > explanation >> WHY >> > the host is added to avoid-set - lack of capacity, or host cant >> > access storage pool, or similar... >> > >> > On 30 May 2015 at 23:54, Jochim, Ingo <[email protected]> >> wrote: >> > >> > > Hello Andrija, >> > > >> > > here are the previous lines. >> > > >> > > Thanks, >> > > Ingo >> > > >> > > >> > > >> > > 2015-05-30 23:05:45,006 DEBUG [c.c.c.CapacityManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e >> > > FirstFitRoutingAllocator) Host has enough CPU and RAM available >> > > 2015-05-30 23:05:45,006 DEBUG [c.c.c.CapacityManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e >> > > FirstFitRoutingAllocator) STATS: Can alloc CPU from host: 25, >> > > used: 27 892, reserved: 9600, actual total: 57624, total with >> > > overprovisioning: >> > > 69148; requested cpu:4000,alloc_from_last_host?:false >> > > ,considerReservedCapacity?: true >> > > 2015-05-30 23:05:45,006 DEBUG [c.c.c.CapacityManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e >> > > FirstFitRoutingAllocator) STATS: Can alloc MEM from host: 25, >> > > used: 55 968792576, reserved: 34359738368, total: 202843127808; >> > > requested mem: >> > > 536870912,alloc_from_last_host?:false ,considerReservedCapacity?: >> > > true 2015-05-30 23:05:45,006 DEBUG >> > > [c.c.a.m.a.i.FirstFitAllocator] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e >> > > FirstFitRoutingAllocator) Found a suitable host, adding to list: >> > > 2 >> > > 5 >> > > 2015-05-30 23:05:45,006 DEBUG [c.c.a.m.a.i.FirstFitAllocator] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e >> > > FirstFitRoutingAllocator) Host Allocator returning 1 suitable >> > > host s 2015-05-30 23:05:45,010 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Checking suitable pools for volume (Id, Type): >> > > (1090,ROOT) 2015-05-30 23:05:45,010 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Volume >> > > has pool already allocated, checking if pool can be reused, >> > > poolId: 8 2015-05-30 23:05:45,014 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Planner >> > > need not allocate a pool for this volume since its READY >> > > 2015-05-30 23:05:45,014 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Checking suitable pools for volume (Id, Type): >> > > (1091,DATADI >> > > SK) >> > > 2015-05-30 23:05:45,014 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Volume >> > > has pool already allocated, checking if pool can be reused, >> > > poolId: 8 2015-05-30 23:05:45,018 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Planner >> > > need not allocate a pool for this volume since its READY >> > > 2015-05-30 23:05:45,018 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Checking suitable pools for volume (Id, Type): >> > > (1095,DATADI >> > > SK) >> > > 2015-05-30 23:05:45,018 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Volume >> > > has pool already allocated, checking if pool can be reused, >> > > poolId: 8 2015-05-30 23:05:45,022 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Planner >> > > need not allocate a pool for this volume since its READY >> > > 2015-05-30 23:05:45,022 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Trying >> > > to find a potenial host and associated storage pools from the >> > > suitable host/pool lists for this VM 2015-05-30 23:05:45,022 >> > > DEBUG [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Checking if host: 25 can access any suitable >> > > storage pool f or volume: DATADISK 2015-05-30 23:05:45,025 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> Host: >> > > 25 can access pool: 8 >> > > 2015-05-30 23:05:45,025 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Checking if host: 25 can access any suitable >> > > storage pool f or volume: DATADISK 2015-05-30 23:05:45,029 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> Host: >> > > 25 can access pool: 8 >> > > 2015-05-30 23:05:45,029 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Checking if host: 25 can access any suitable >> > > storage pool f or volume: ROOT 2015-05-30 23:05:45,033 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> Host: >> > > 25 can access pool: 8 >> > > 2015-05-30 23:05:45,036 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Found a >> > > potential host id: 25 name: hv01 and associated sto rage pools >> > > for this VM 2015-05-30 23:05:45,041 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Returning Deployment Destination: >> > > Dest[Zone(Id)-Pod(Id)-Clu >> > > ster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))] : >> > > Dest[Zone(2)-Pod(2)-Cluster(2)-Host(25)-Storage()] >> > > 2015-05-30 23:05:45,041 DEBUG [c.c.v.VirtualMachineManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Deployment found - P0=VM[User|i-78-595-VM], >> > > P0=Dest[Zone(Id)-P >> > > od(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))] : >> > > Dest[Zone(2)-Pod(2)-Cluster(2)-Host(25)-Storage()] >> > > >> > > 2015-05-30 23:05:45,136 DEBUG [c.c.c.CapacityManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) VM state transitted from :Starting to Starting with >> > > event: OperationR etryvm's original host id: 24 new host id: 25 >> > > host id before state >> > > transition: 24 >> > > 2015-05-30 23:05:45,155 DEBUG [c.c.c.CapacityManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Hosts's >> > > actual total CPU: 57624 and CPU after applying overprovisioni >> > > ng: 69148 >> > > 2015-05-30 23:05:45,155 DEBUG [c.c.c.CapacityManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Hosts's >> > > actual total RAM: 202843119616 and RAM after applying overpro >> > > visioning: 202843127808 >> > > 2015-05-30 23:05:45,155 DEBUG [c.c.c.CapacityManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > release >> > > cpu from host: 24, old used: 39296,reserved: 800, actual tota >> > > l: 57624, total with overprovisioning: 69148; new used: >> > > 35296,reserved:800; movedfromreserved: >> > > false,moveToReserveredfalse 2015-05-30 23:05:45,155 DEBUG >> > > [c.c.c.CapacityManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > release >> > > mem from host: 24, old used: 67108864000,reserved: 1664299827 2, >> > > total: 202843127808; new used: 66571993088,reserved:16642998272; >> > > movedfromreserved: false,moveToReserveredfalse 2015-05-30 >> > > 23:05:45,188 DEBUG [c.c.c.CapacityManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Hosts's >> > > actual total CPU: 57624 and CPU after applying overprovisioni >> > > ng: 69148 >> > > 2015-05-30 23:05:45,188 DEBUG [c.c.c.CapacityManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) We >> > are >> > > allocating VM, increasing the used capacity of this host:25 >> > > 2015-05-30 23:05:45,188 DEBUG [c.c.c.CapacityManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Current >> > > Used CPU: 27892 , Free CPU:31656 ,Requested CPU: 4000 2015-05-30 >> > > 23:05:45,188 DEBUG [c.c.c.CapacityManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Current >> > > Used RAM: 55968792576 , Free RAM:112514596864 ,Requested RAM: >> > > 536870912 >> > > 2015-05-30 23:05:45,188 DEBUG [c.c.c.CapacityManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> CPU >> > > STATS after allocation: for host: 25, old used: 27892, old reserv >> > > ed: 9600, actual total: 57624, total with overprovisioning: >> > > 69148; new used:31892, reserved:9600; requested >> > > cpu:4000,alloc_from_last:false 2015-05-30 23:05:45,188 DEBUG >> > > [c.c.c.CapacityManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> RAM >> > > STATS after allocation: for host: 25, old used: 55968792576, old >> > > reserved: 34359738368, total: 202843127808; new used: >> > > 56505663488, >> > > reserved: 34359738368; requested mem: >> > > 536870912,alloc_from_last:false 2015-05-30 23:05:45,197 DEBUG >> > > [c.c.v.VirtualMachineManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> VM is >> > > being created in podId: 2 >> > > 2015-05-30 23:05:45,205 DEBUG [o.a.c.e.o.NetworkOrchestrator] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Network >> > > id=275 is already implemented >> > > 2015-05-30 23:05:45,241 DEBUG [c.c.n.NetworkModelImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Service >> > > SecurityGroup is not supported in the network id=275 2015-05-30 >> > > 23:05:45,254 DEBUG [o.a.c.e.o.NetworkOrchestrator] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Changing active number of nics for network id=275 >> > > on 1 2015-05-30 23:05:45,273 DEBUG >> > > [o.a.c.e.o.NetworkOrchestrator] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Asking >> > > VirtualRouter to prepare for Nic[1485-595-75b8798a-0438-4b >> > > 05-b159-0b5ca47df77f-172.26.0.110] >> > > 2015-05-30 23:05:45,309 DEBUG >> > [c.c.n.r.VirtualNetworkApplianceManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> Lock >> > is >> > > acquired for network id 275 as a part of rou ter startup in >> > > >> > >> Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->P >> ool(Id))] >> > > : Dest[Zone(2)-Pod(2)-Cluster(2)-Host(25)-Storage()] >> > > 2015-05-30 23:05:45,319 DEBUG >> > [c.c.n.r.VirtualNetworkApplianceManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> Lock >> > is >> > > released for network id 275 as a part of rou ter startup in >> > > >> > >> Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->P >> ool(Id))] >> > > : Dest[Zone(2)-Pod(2)-Cluster(2)-Host(25)-Storage()] >> > > 2015-05-30 23:05:45,343 DEBUG [c.c.n.NetworkModelImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Service >> > > SecurityGroup is not supported in the network id=275 2015-05-30 >> > > 23:05:45,380 DEBUG [c.c.n.NetworkModelImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Service >> > > SecurityGroup is not supported in the network id=275 2015-05-30 >> > > 23:05:45,393 DEBUG >> > [c.c.n.r.VirtualNetworkApplianceManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Applying dhcp entry in network >> > > Ntwk[60e1fb05-45a9-4c >> > > ba-a28f-5eea85580362|Guest|8] >> > > 2015-05-30 23:05:45,450 DEBUG [c.c.a.t.Request] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> Seq >> > > 25-2722988924698903049: Sending { Cmd , MgmtId: 90520736249963, via: >> > 25(hv >> > > 01), Ver: v1, Flags: 100111, >> > > >> > >> [{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"02:00:1b:cf:00:2f","vmIpAddress":"172.26.0.110","vmName":"bts004","defaultRouter":"172.26.0.1","defaultDns" >> > > >> > > >> > >> :"172.26.0.1","duid":"00:03:00:01:02:00:1b:cf:00:2f","isDefault":true,"executeInSequence":true,"accessDetails":{"zone.network.type":"Advanced","router.guest.ip":"172.26.0.1","router.ip":"169.254. >> > > 2.67","router.name":"r-385-VM"},"wait":0}}] } 2015-05-30 >> > > 23:05:45,631 DEBUG [c.c.a.t.Request] >> > > (AgentManager-Handler-13:null) Seq 25-2722988924698903049: >> Processing: { >> > > Ans: , MgmtId: 90520736249963, via: 25, Ver: v1, Flags: 110, >> [{"com.cloud >> > > .agent.api.Answer":{"result":true,"details":"","wait":0}}] } >> > > 2015-05-30 23:05:45,631 DEBUG [c.c.a.m.AgentAttache] >> > > (AgentManager-Handler-13:null) Seq 25-2722988924698903049: No >> > > more >> > commands >> > > found >> > > 2015-05-30 23:05:45,631 DEBUG [c.c.a.t.Request] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> Seq >> > > 25-2722988924698903049: Received: { Ans: , MgmtId: >> > > 90520736249963, >> via: >> > 25 >> > > , Ver: v1, Flags: 110, { Answer } } 2015-05-30 23:05:45,652 DEBUG >> > > [c.c.n.NetworkModelImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Service >> > > SecurityGroup is not supported in the network id=275 2015-05-30 >> > > 23:05:45,661 DEBUG >> > [c.c.n.r.VirtualNetworkApplianceManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Applying userdata and password entry in network >> > > Ntwk [60e1fb05-45a9-4cba-a28f-5eea85580362|Guest|8] >> > > >> > > 2015-05-30 23:05:45,691 DEBUG [c.c.a.t.Request] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> Seq >> > > 25-2722988924698903050: Sending { Cmd , MgmtId: 90520736249963, via: >> > 25(hv >> > > 01), Ver: v1, Flags: 100111, >> > > >> > >> [{"com.cloud.agent.api.routing.VmDataCommand":{"vmIpAddress":"172.26.0.110","vmName":"bts004","executeInSequence":true,"accessDetails":{"zone.network.type":"Advanced" >> > > ,"router.name >> > >> ":"r-385-VM","router.ip":"169.254.2.67","router.guest.ip":"172.26.0.1 >> "},"wait":0}}] >> > > } >> > > 2015-05-30 23:05:45,806 DEBUG [c.c.a.t.Request] >> > > (AgentManager-Handler-2:null) Seq 25-2722988924698903050: >> Processing: { >> > > Ans: , MgmtId: 90520736249963, via: 25, Ver: v1, Flags: 110, >> > [{"com.cloud. >> > > agent.api.Answer":{"result":true,"details":"","wait":0}}] } >> > > 2015-05-30 23:05:45,806 DEBUG [c.c.a.m.AgentAttache] >> > > (AgentManager-Handler-2:null) Seq 25-2722988924698903050: No more >> > commands >> > > found >> > > 2015-05-30 23:05:45,806 DEBUG [c.c.a.t.Request] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> Seq >> > > 25-2722988924698903050: Received: { Ans: , MgmtId: >> > > 90520736249963, >> via: >> > 25 >> > > , Ver: v1, Flags: 110, { Answer } } 2015-05-30 23:05:45,810 DEBUG >> > > [c.c.n.NetworkModelImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Service >> > > SecurityGroup is not supported in the network id=275 2015-05-30 >> > > 23:05:45,814 DEBUG [o.a.c.e.o.VolumeOrchestrator] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Checking if we need to prepare 3 volumes for >> > > VM[User|i-78-595-VM] 2015-05-30 23:05:46,013 DEBUG >> > > [c.c.a.t.Request] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> Seq >> > > 25-2722988924698903051: Sending { Cmd , MgmtId: 90520736249963, via: >> > 25(hv >> > > 01), Ver: v1, Flags: 100011, >> > > >> > >> [{"com.cloud.agent.api.StartCommand":{"vm":{"id":595,"name":"i-78-595 >> -VM","type":"User","cpus":2,"minSpeed":1666,"maxSpeed":2000,"minRam": >> 536870912,"maxRam":536870912 >> > > ,"arch":"x86_64","os":"SUSE Linux Enterprise Server 11 SP3 >> > > >> > >> (64-bit)","platformEmulator":"Other","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm" >> > > >> > > >> > >> :true,"vncPassword":"f56cc9072e6697a0","params":{"memoryOvercommitRatio":"1","cpuOvercommitRatio":"1.2","Message.ReservedCapacityFreed.Flag":"false"},"uuid":"8f3faedc-2401-4eed-9f81-4f160d3590c0" >> > > >> > > >> > >> ,"disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO": >> {"uuid":"f5d96e65-a7f8-4c5a-a2f4-86bbfc53e5ee","volumeType":"ROOT","d >> ataStore":{"org.apache.cloudstack.storage.to.PrimaryDataS >> > > >> > > >> > >> toreTO":{"uuid":"ccfacebe-19c3-38f4-a161-2bd9e4ac70a9","id":8,"poolTy >> pe":"RBD","host":"ceph-mon-rr.storage","path":"cloudstack01","port":6 >> 789,"url":"RBD://ceph-mon-rr.storage/cloudstack01/?ROLE=P >> > > >> > > >> > >> rimary&STOREUUID=ccfacebe-19c3-38f4-a161-2bd9e4ac70a9"}},"name":"ROOT-595","size":10737418240,"path":"c65a5e7d-b3b9-495a-9170-f423765779cf","volumeId":1090,"vmName":"i-78-595-VM","accountId":78," >> > > >> > > >> > >> format":"RAW","id":1090,"deviceId":0,"cacheMode":"NONE","hypervisorTy >> pe":"KVM"}},"diskSeq":0,"path":"c65a5e7d-b3b9-495a-9170-f423765779cf" >> ,"type":"ROOT","_details":{"managed":"false","storagePort >> > > >> > > >> > >> ":"6789","storageHost":"ceph-mon-rr.storage","volumeSize":"1073741824 >> 0"}},{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uui >> d":"5d7a7ce4-890f-4b75-9b0b-d63f911cc6ff","volumeType":"D >> > > >> > > >> > >> ATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataSt >> oreTO":{"uuid":"ccfacebe-19c3-38f4-a161-2bd9e4ac70a9","id":8,"poolTyp >> e":"RBD","host":"ceph-mon-rr.storage","path":"cloudstack0 >> > > >> > > >> > >> 1","port":6789,"url":"RBD://ceph-mon-rr.storage/cloudstack01/?ROLE=Pr >> imary&STOREUUID=ccfacebe-19c3-38f4-a161-2bd9e4ac70a9"}},"name":"SHARE >> -001","size":53687091200,"path":"0f76d53b-2dfd-45ff-a274- >> > > >> > > >> > >> 094d05ffd291","volumeId":1091,"vmName":"i-78-595-VM","accountId":78," >> format":"RAW","id":1091,"deviceId":1,"cacheMode":"NONE","hypervisorTy >> pe":"KVM"}},"diskSeq":1,"path":"0f76d53b-2dfd-45ff-a274-0 >> > > >> > > >> > >> 94d05ffd291","type":"DATADISK","_details":{"managed":"false","storage >> Port":"6789","storageHost":"ceph-mon-rr.storage","volumeSize":"536870 >> 91200"}},{"data":{"org.apache.cloudstack.storage.to.Volum >> > > >> > > >> > >> eObjectTO":{"uuid":"06ced5eb-37d8-452b-9544-6bf6a85e4cff","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"ccfacebe-19c3-38f4-a161-2bd9e4ac70a9" >> > > >> > > >> > >> ,"id":8,"poolType":"RBD","host":"ceph-mon-rr.storage","path":"cloudstack01","port":6789,"url":"RBD://ceph-mon-rr.storage/cloudstack01/?ROLE=Primary&STOREUUID=ccfacebe-19c3-38f4-a161-2bd9e4ac70a9" >> > > >> > > >> > >> }},"name":"bts004-data-500g","size":536870912000,"path":"63ebce9c-e10 >> c-4baf-ba90-6bf51a18feb9","volumeId":1095,"vmName":"i-78-595-VM","acc >> ountId":78,"format":"RAW","id":1095,"deviceId":4,"cacheMo >> > > >> > > >> > >> de":"NONE","hypervisorType":"KVM"}},"diskSeq":4,"path":"63ebce9c-e10c >> -4baf-ba90-6bf51a18feb9","type":"DATADISK","_details":{"managed":"fal >> se","storagePort":"6789","storageHost":"ceph-mon-rr.stora >> > > >> > > >> > >> ge","volumeSize":"536870912000"}},{"data":{"org.apache.cloudstack.sto >> rage.to.TemplateObjectTO":{"id":0,"format":"ISO","accountId":0,"hvm": >> false}},"diskSeq":3,"type":"ISO"}],"nics":[{"deviceId":0, >> > > >> > > >> > >> "networkRateMbps":200,"defaultNic":true,"uuid":"60e1fb05-45a9-4cba-a2 >> 8f-5eea85580362","ip":"172.26.0.110","netmask":"255.255.0.0","gateway >> ":"172.26.0.1","mac":"02:00:1b:cf:00:2f","dns1":"10.100.6 >> > > >> > > >> > >> 5.170","dns2":"213.73.91.35","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://1024","isolationUri":"vlan://1024","isSecurityGroupEnabled":false,"name":"guest"}]},"hostIp":"172.27.0.3" >> > > ,"executeInSequence":false,"wait":0}}] } 2015-05-30 23:05:46,586 >> > > DEBUG [c.c.a.ApiServlet] >> > > (http-6443-exec-13:ctx-6e51d2fb) ===START=== 172.27.0.19 -- GET >> > > >> > >> command=queryAsyncJobResult&jobId=bf73d5c2-1b45-4d8a-8ee4-8f3ff5e63ae >> a&response= >> > > >> > > >> > >> json&sessionkey=BzpqHhBkXr6XuS3sbumiqUWjTJY%3D&projectid=4865e6c6-b1f >> f-48ef-b908-1f5fde7d7b6e&_=1433019955809 >> > > 2015-05-30 23:05:46,604 WARN >> > > [c.c.a.d.ParamGenericValidationWorker] >> > > (http-6443-exec-13:ctx-6e51d2fb ctx-8d752f10) Received unknown >> parameters >> > > for command queryAsyncJobResult. Unknown parameters >> > > : projectid >> > > 2015-05-30 23:05:46,641 DEBUG [c.c.a.ApiServlet] >> > > (http-6443-exec-13:ctx-6e51d2fb ctx-8d752f10) ===END=== >> > > 172.27.0.19 >> -- >> > > GET >> > command=queryAsyncJobResult&jobId=bf73d5c2-1b45-4d8a-8ee4-8f3ff5e63 >> > ae >> > > >> > > >> > >> a&response=json&sessionkey=BzpqHhBkXr6XuS3sbumiqUWjTJY%3D&projectid=4 >> 865e6c6-b1ff-48ef-b908-1f5fde7d7b6e&_=1433019955809 >> > > 2015-05-30 23:05:46,950 DEBUG [c.c.a.m.AgentManagerImpl] >> > > (AgentManager-Handler-4:null) SeqA 26-38453: Processing Seq >> 26-38453: { >> > > Cmd , MgmtId: -1, via: 26, Ver: v1, Flags: 11, [{"com.cloud.agent >> > > .api.ConsoleProxyLoadReportCommand":{"_proxyVmId":674,"_loadInfo" >> > > :"{\n >> > > \"connections\": []\n}","wait":0}}] } 2015-05-30 23:05:46,962 >> > > DEBUG [c.c.a.m.AgentManagerImpl] >> > > (AgentManager-Handler-4:null) SeqA 26-38453: Sending Seq >> > > 26-38453: { >> > Ans: >> > > , MgmtId: 90520736249963, via: 26, Ver: v1, Flags: 100010, [{"c >> > > om.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] >> > > } 2015-05-30 23:05:49,595 DEBUG [c.c.a.ApiServlet] >> > > (http-6443-exec-4:ctx-e431b7ae) ===START=== 172.27.0.19 -- GET >> > > >> > >> command=queryAsyncJobResult&jobId=bf73d5c2-1b45-4d8a-8ee4-8f3ff5e63ae >> a&response=j >> > > >> > > >> > >> son&sessionkey=BzpqHhBkXr6XuS3sbumiqUWjTJY%3D&projectid=4865e6c6-b1ff >> -48ef-b908-1f5fde7d7b6e&_=1433019958810 >> > > 2015-05-30 23:05:49,614 WARN >> > > [c.c.a.d.ParamGenericValidationWorker] >> > > (http-6443-exec-4:ctx-e431b7ae ctx-c4d43b59) Received unknown >> parameters >> > > for command queryAsyncJobResult. Unknown parameters : >> > > projectid >> > > >> > > 2015-05-30 23:05:49,653 DEBUG [c.c.a.ApiServlet] >> > > (http-6443-exec-4:ctx-e431b7ae ctx-c4d43b59) ===END=== >> > > 172.27.0.19 -- >> > GET >> > > command=queryAsyncJobResult&jobId=bf73d5c2-1b45-4d8a-8ee4-8f3ff5e >> > > 63aea >> > > >> > > >> > >> &response=json&sessionkey=BzpqHhBkXr6XuS3sbumiqUWjTJY%3D&projectid=48 >> 65e6c6-b1ff-48ef-b908-1f5fde7d7b6e&_=1433019958810 >> > > 2015-05-30 23:05:52,129 DEBUG [c.c.h.d.HostDaoImpl] >> > (ClusteredAgentManager >> > > Timer:ctx-8c516db7) Resetting hosts suitable for reconnect >> > > 2015-05-30 23:05:52,133 DEBUG [c.c.h.d.HostDaoImpl] >> > (ClusteredAgentManager >> > > Timer:ctx-8c516db7) Completed resetting hosts suitable for >> > > reconnect 2015-05-30 23:05:52,133 DEBUG [c.c.h.d.HostDaoImpl] >> > (ClusteredAgentManager >> > > Timer:ctx-8c516db7) Acquiring hosts for clusters already owned by >> > > this management server 2015-05-30 23:05:52,134 DEBUG >> > > [c.c.h.d.HostDaoImpl] >> > (ClusteredAgentManager >> > > Timer:ctx-8c516db7) Completed acquiring hosts for clusters >> > > already >> owned >> > by >> > > this management server >> > > 2015-05-30 23:05:52,134 DEBUG [c.c.h.d.HostDaoImpl] >> > (ClusteredAgentManager >> > > Timer:ctx-8c516db7) Acquiring hosts for clusters not owned by any >> > > management server 2015-05-30 23:05:52,135 DEBUG >> > > [c.c.h.d.HostDaoImpl] >> > (ClusteredAgentManager >> > > Timer:ctx-8c516db7) Completed acquiring hosts for clusters not >> > > owned >> by >> > any >> > > management server >> > > 2015-05-30 23:05:52,596 DEBUG [c.c.a.ApiServlet] >> > > (http-6443-exec-12:ctx-158c7d0c) ===START=== 172.27.0.19 -- GET >> > > >> > >> command=queryAsyncJobResult&jobId=bf73d5c2-1b45-4d8a-8ee4-8f3ff5e63ae >> a&response= >> > > >> > > >> > >> json&sessionkey=BzpqHhBkXr6XuS3sbumiqUWjTJY%3D&projectid=4865e6c6-b1f >> f-48ef-b908-1f5fde7d7b6e&_=1433019961815 >> > > 2015-05-30 23:05:52,615 WARN >> > > [c.c.a.d.ParamGenericValidationWorker] >> > > (http-6443-exec-12:ctx-158c7d0c ctx-e643cad2) Received unknown >> parameters >> > > for command queryAsyncJobResult. Unknown parameters >> > > : projectid >> > > 2015-05-30 23:05:52,656 DEBUG [c.c.a.ApiServlet] >> > > (http-6443-exec-12:ctx-158c7d0c ctx-e643cad2) ===END=== >> > > 172.27.0.19 >> -- >> > > GET >> > command=queryAsyncJobResult&jobId=bf73d5c2-1b45-4d8a-8ee4-8f3ff5e63 >> > ae >> > > >> > > >> > >> a&response=json&sessionkey=BzpqHhBkXr6XuS3sbumiqUWjTJY%3D&projectid=4 >> 865e6c6-b1ff-48ef-b908-1f5fde7d7b6e&_=1433019961815 >> > > 2015-05-30 23:05:53,621 DEBUG [c.c.s.StatsCollector] >> > > (StatsCollector-4:ctx-2c9e8103) AutoScaling Monitor is running... >> > > 2015-05-30 23:05:55,591 DEBUG [c.c.a.ApiServlet] >> > > (http-6443-exec-11:ctx-01bb5a95) ===START=== 172.27.0.19 -- GET >> > > >> > >> command=queryAsyncJobResult&jobId=bf73d5c2-1b45-4d8a-8ee4-8f3ff5e63ae >> a&response= >> > > >> > > >> > >> json&sessionkey=BzpqHhBkXr6XuS3sbumiqUWjTJY%3D&projectid=4865e6c6-b1f >> f-48ef-b908-1f5fde7d7b6e&_=1433019964815 >> > > 2015-05-30 23:05:55,611 WARN >> > > [c.c.a.d.ParamGenericValidationWorker] >> > > (http-6443-exec-11:ctx-01bb5a95 ctx-1591751b) Received unknown >> parameters >> > > for command queryAsyncJobResult. Unknown parameters >> > > : projectid >> > > 2015-05-30 23:05:55,650 DEBUG [c.c.a.ApiServlet] >> > > (http-6443-exec-11:ctx-01bb5a95 ctx-1591751b) ===END=== >> > > 172.27.0.19 >> -- >> > > GET >> > command=queryAsyncJobResult&jobId=bf73d5c2-1b45-4d8a-8ee4-8f3ff5e63 >> > ae >> > > >> > > >> > >> a&response=json&sessionkey=BzpqHhBkXr6XuS3sbumiqUWjTJY%3D&projectid=4 >> 865e6c6-b1ff-48ef-b908-1f5fde7d7b6e&_=1433019964815 >> > > 2015-05-30 23:05:56,102 DEBUG [c.c.a.t.Request] >> > > (AgentManager-Handler-3:null) Seq 25-2722988924698903051: >> Processing: { >> > > Ans: , MgmtId: 90520736249963, via: 25, Ver: v1, Flags: 10, >> > [{"com.cloud.a >> > > >> > >> gent.api.StartAnswer":{"vm":{"id":595,"name":"i-78-595-VM","type":"Us >> er","cpus":2,"minSpeed":1666,"maxSpeed":2000,"minRam":536870912,"maxR >> am":536870912,"arch":"x86_64","os":"SUSE >> > > Linux Enterprise >> > > Server 11 SP3 >> > > >> > >> (64-bit)","platformEmulator":"Other","bootArgs":"","rebootOnCrash":fa >> lse,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":t >> rue,"vncPassword":"f56cc9072e6697a0","vncA >> > > >> > > >> > >> ddr":"172.27.0.3","params":{"memoryOvercommitRatio":"1","cpuOvercommi >> tRatio":"1.2","Message.ReservedCapacityFreed.Flag":"false"},"uuid":"8 >> f3faedc-2401-4eed-9f81-4f160d3590c0","disks":[{"data":{"o >> > > >> > > >> > >> rg.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f5d96e65-a7f >> 8-4c5a-a2f4-86bbfc53e5ee","volumeType":"ROOT","dataStore":{"org.apach >> e.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"ccfa >> > > >> > > >> > >> cebe-19c3-38f4-a161-2bd9e4ac70a9","id":8,"poolType":"RBD","host":"cep >> h-mon-rr.storage","path":"cloudstack01","port":6789,"url":"RBD://ceph >> -mon-rr.storage/cloudstack01/?ROLE=Primary&STOREUUID=ccfa >> > > >> > > >> > >> cebe-19c3-38f4-a161-2bd9e4ac70a9"}},"name":"ROOT-595","size":10737418 >> 240,"path":"c65a5e7d-b3b9-495a-9170-f423765779cf","volumeId":1090,"vm >> Name":"i-78-595-VM","accountId":78,"format":"RAW","id":10 >> > > >> > > >> > >> 90,"deviceId":0,"cacheMode":"NONE","hypervisorType":"KVM"}},"diskSeq" >> :0,"path":"c65a5e7d-b3b9-495a-9170-f423765779cf","type":"ROOT","_deta >> ils":{"managed":"false","storagePort":"6789","storageHost >> > > >> > > >> > >> ":"ceph-mon-rr.storage","volumeSize":"10737418240"}},{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"5d7a7ce4-890f-4b75-9b0b-d63f911cc6ff","volumeType":"DATADISK","dataStore": >> > > >> > > >> > >> {"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"ccfacebe-19c3-38f4-a161-2bd9e4ac70a9","id":8,"poolType":"RBD","host":"ceph-mon-rr.storage","path":"cloudstack01","port":6789,"url": >> > > >> > > >> > >> "RBD://ceph-mon-rr.storage/cloudstack01/?ROLE=Primary&STOREUUID=ccfac >> ebe-19c3-38f4-a161-2bd9e4ac70a9"}},"name":"SHARE-001","size":53687091 >> 200,"path":"0f76d53b-2dfd-45ff-a274-094d05ffd291","volume >> > > >> > > >> > >> Id":1091,"vmName":"i-78-595-VM","accountId":78,"format":"RAW","id":1091,"deviceId":1,"cacheMode":"NONE","hypervisorType":"KVM"}},"diskSeq":1,"path":"0f76d53b-2dfd-45ff-a274-094d05ffd291","type":" >> > > >> > > >> > >> DATADISK","_details":{"managed":"false","storagePort":"6789","storage >> Host":"ceph-mon-rr.storage","volumeSize":"53687091200"}},{"data":{"or >> g.apache.cloudstack.storage.to.TemplateObjectTO":{"id":0, >> > > >> > > >> > >> "format":"ISO","accountId":0,"hvm":false}},"diskSeq":3,"type":"ISO"}, >> {"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"0 >> 6ced5eb-37d8-452b-9544-6bf6a85e4cff","volumeType":"DATADI >> > > >> > > >> > >> SK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO >> ":{"uuid":"ccfacebe-19c3-38f4-a161-2bd9e4ac70a9","id":8,"poolType":"R >> BD","host":"ceph-mon-rr.storage","path":"cloudstack01","p >> > > >> > > >> > >> ort":6789,"url":"RBD://ceph-mon-rr.storage/cloudstack01/?ROLE=Primary >> &STOREUUID=ccfacebe-19c3-38f4-a161-2bd9e4ac70a9"}},"name":"bts004-dat >> a-500g","size":536870912000,"path":"63ebce9c-e10c-4baf-ba >> > > >> > > >> > >> 90-6bf51a18feb9","volumeId":1095,"vmName":"i-78-595-VM","accountId":7 >> 8,"format":"RAW","id":1095,"deviceId":4,"cacheMode":"NONE","hyperviso >> rType":"KVM"}},"diskSeq":4,"path":"63ebce9c-e10c-4baf-ba9 >> > > >> > > >> > >> 0-6bf51a18feb9","type":"DATADISK","_details":{"managed":"false","stor >> agePort":"6789","storageHost":"ceph-mon-rr.storage","volumeSize":"536 >> 870912000"}}],"nics":[{"deviceId":0,"networkRateMbps":200 >> > > >> > > >> > >> ,"defaultNic":true,"uuid":"60e1fb05-45a9-4cba-a28f-5eea85580362","ip" >> :"172.26.0.110","netmask":"255.255.0.0","gateway":"172.26.0.1","mac": >> "02:00:1b:cf:00:2f","dns1":"10.100.65.170","dns2":"213.73 >> > > >> > >> .91.35","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan:// >> 1024","isolationUri":"vlan://1024","isSecurityGroupEnabled":false,"na >> me":"guest"}]},"result":false,"details":"internal >> > > error: >> > > Only 1 ide controller is supported","wait":0}}] } >> > > >> > > 2015-05-30 23:05:56,102 DEBUG [c.c.a.t.Request] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> Seq >> > > 25-2722988924698903051: Received: { Ans: , MgmtId: >> > > 90520736249963, >> via: >> > > 25, Ver: v1, Flags: 10, { StartAnswer } } 2015-05-30 23:05:56,115 >> > > INFO [c.c.v.VirtualMachineManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Unable >> > > to start VM on Host[-25-Routing] due to internal error: Only 1 >> > > ide controller is supported 2015-05-30 23:05:56,128 DEBUG >> > > [c.c.v.VirtualMachineManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Cleaning up resources for the vm >> > > VM[User|i-78-595-VM] in Starting >> state >> > > 2015-05-30 23:05:56,139 DEBUG [c.c.a.t.Request] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> Seq >> > > 25-2722988924698903052: Sending { Cmd , MgmtId: 90520736249963, via: >> > > 25(hv01), Ver: v1, Flags: 100011, >> > > >> > >> [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequen >> ce":false,"checkBeforeCleanup":false,"vmName":"i-78-595-VM","wait":0} >> }] >> > > } >> > > 2015-05-30 23:05:56,320 DEBUG [c.c.a.t.Request] >> > > (AgentManager-Handler-18:null) Seq 25-2722988924698903052: >> Processing: { >> > > Ans: , MgmtId: 90520736249963, via: 25, Ver: v1, Flags: 10, >> > > [{"com.cloud.agent.api.StopAnswer":{"result":true,"wait":0}}] } >> > > 2015-05-30 23:05:56,320 DEBUG [c.c.a.t.Request] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> Seq >> > > 25-2722988924698903052: Received: { Ans: , MgmtId: >> > > 90520736249963, >> via: >> > > 25, Ver: v1, Flags: 10, { StopAnswer } } 2015-05-30 23:05:56,347 >> > > DEBUG [c.c.n.NetworkModelImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Service >> > > SecurityGroup is not supported in the network id=275 2015-05-30 >> > > 23:05:56,356 DEBUG [o.a.c.e.o.NetworkOrchestrator] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Changing active number of nics for network id=275 >> > > on -1 2015-05-30 23:05:56,378 DEBUG >> > > [o.a.c.e.o.NetworkOrchestrator] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Asking >> > > VirtualRouter to release >> > > >> > >> NicProfile[1485-595-75b8798a-0438-4b05-b159-0b5ca47df77f-172.26.0.110 >> -null >> > > 2015-05-30 23:05:56,378 DEBUG [c.c.v.VirtualMachineManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Successfully released network resources for the vm >> VM[User|i-78-595-VM] >> > > 2015-05-30 23:05:56,378 DEBUG [c.c.v.VirtualMachineManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Successfully cleanued up resources for the vm >> > > VM[User|i-78-595-VM] in Starting state 2015-05-30 23:05:56,381 >> > > DEBUG [c.c.c.ConsoleProxyManagerImpl] >> > > (consoleproxy-1:ctx-9fc2ccb9) Zone 2 is ready to launch console >> > > proxy 2015-05-30 23:05:56,387 DEBUG >> > > [c.c.v.VirtualMachineManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> Root >> > > volume is ready, need to place VM in volume's cluster 2015-05-30 >> > > 23:05:56,387 DEBUG [c.c.v.VirtualMachineManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Vol[1090|vm=595|ROOT] is READY, changing deployment >> > > plan to use this >> > pool's >> > > dcId: 2 , podId: 2 , and clusterId: 2 2015-05-30 23:05:56,421 >> > > DEBUG [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Deploy >> > > avoids pods: [], clusters: [], hosts: [23, 25, 24] 2015-05-30 >> > > 23:05:56,425 DEBUG [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) DeploymentPlanner allocation algorithm: >> > > com.cloud.deploy.FirstFitPlanner@17dc6042 >> > > 2015-05-30 23:05:56,425 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Trying >> > > to allocate a host and storage pools from dc:2, pod:2,cluster:2, >> > requested >> > > cpu: 4000, requested ram: 536870912 2015-05-30 23:05:56,425 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Is >> > ROOT >> > > volume READY (pool already allocated)?: Yes 2015-05-30 >> > > 23:05:56,425 DEBUG [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> This >> > VM >> > > has last host_id specified, trying to choose the same host: 24 >> > > 2015-05-30 23:05:56,429 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> The >> > > last host of this VM is in avoid set 2015-05-30 23:05:56,429 >> > > DEBUG [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) >> > Cannot >> > > choose the last host to deploy this VM 2015-05-30 23:05:56,429 >> > > DEBUG [c.c.d.FirstFitPlanner] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Searching resources only under specified Cluster: 2 >> > > 2015-05-30 23:05:56,447 DEBUG >> > > [c.c.d.DeploymentPlanningManagerImpl] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e) Checking resources in Cluster: 2 under Pod: 2 >> > > 2015-05-30 23:05:56,448 DEBUG [c.c.a.m.a.i.FirstFitAllocator] >> > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > ctx-50d9961e >> > > FirstFitRoutingAllocator) Looking for hosts in dc: 2 pod:2 >> > > cluster:2 >> > > >> > > >> > > ________________________________________ >> > > Von: Andrija Panic [[email protected]] >> > > Gesendet: Samstag, 30. Mai 2015 23:45 >> > > An: [email protected] >> > > Betreff: Re: hostId: 25 is in avoid set, skipping this and trying >> other >> > > available hosts >> > > >> > > Please send log lines before the current lines... >> > > >> > > On 30 May 2015 at 23:34, Jochim, Ingo <[email protected]> >> wrote: >> > > >> > > > Hello all, >> > > > >> > > > do you have any idea why all my hosts are to avoid and how can >> > > > I >> remove >> > > > this. >> > > > >> > > > Your help is very appreciated. >> > > > >> > > > Regards, >> > > > Ingo >> > > > >> > > > 2015-05-30 23:05:56,448 DEBUG [c.c.a.m.a.i.FirstFitAllocator] >> > > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > > ctx-50d9961e >> > > > FirstFitRoutingAllocator) Looking for hosts in dc: 2 pod:2 >> > > > cluster:2 2015-05-30 23:05:56,458 DEBUG >> > > > [c.c.a.m.a.i.FirstFitAllocator] >> > > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > > ctx-50d9961e >> > > > FirstFitRoutingAllocator) FirstFitAllocator has 3 hosts to >> > > > check for >> > > > allocation: [Host[-23-Routing], Host[-24-Routing], >> Host[-25-Routing]] >> > > > 2015-05-30 23:05:56,488 DEBUG [c.c.a.m.a.i.FirstFitAllocator] >> > > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > > ctx-50d9961e >> > > > FirstFitRoutingAllocator) Found 3 hosts for allocation after >> > > > prioritization: [Host[-23-Routing], Host[-24-Routing], >> > Host[-25-Routing]] >> > > > 2015-05-30 23:05:56,488 DEBUG [c.c.a.m.a.i.FirstFitAllocator] >> > > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > > ctx-50d9961e >> > > > FirstFitRoutingAllocator) Looking for speed=4000Mhz, Ram=512 >> > > > 2015-05-30 23:05:56,488 DEBUG [c.c.a.m.a.i.FirstFitAllocator] >> > > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > > ctx-50d9961e >> > > > FirstFitRoutingAllocator) Host name: hv03, hostId: 23 is in >> > > > avoid >> set, >> > > > skipping this and trying other available hosts 2015-05-30 >> > > > 23:05:56,488 DEBUG [c.c.a.m.a.i.FirstFitAllocator] >> > > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > > ctx-50d9961e >> > > > FirstFitRoutingAllocator) Host name: hv02, hostId: 24 is in >> > > > avoid >> set, >> > > > skipping this and trying other available hosts 2015-05-30 >> > > > 23:05:56,488 DEBUG [c.c.a.m.a.i.FirstFitAllocator] >> > > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > > ctx-50d9961e >> > > > FirstFitRoutingAllocator) Host name: hv01, hostId: 25 is in >> > > > avoid >> set, >> > > > skipping this and trying other available hosts 2015-05-30 >> > > > 23:05:56,488 DEBUG [c.c.a.m.a.i.FirstFitAllocator] >> > > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > > ctx-50d9961e >> > > > FirstFitRoutingAllocator) Host Allocator returning 0 suitable >> > > > hosts 2015-05-30 23:05:56,488 DEBUG >> > > > [c.c.d.DeploymentPlanningManagerImpl] >> > > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > > ctx-50d9961e) >> No >> > > > suitable hosts found >> > > > 2015-05-30 23:05:56,488 DEBUG >> > > > [c.c.d.DeploymentPlanningManagerImpl] >> > > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > > ctx-50d9961e) >> No >> > > > suitable hosts found under this Cluster: 2 2015-05-30 >> > > > 23:05:56,493 DEBUG [c.c.d.DeploymentPlanningManagerImpl] >> > > > (Work-Job-Executor-113:ctx-8f509c78 job-2236/job-2238 >> > > > ctx-50d9961e) >> > Could >> > > > not find suitable Deployment Destination for this VM under any >> > clusters, >> > > > returning. >> > > > >> > > > -- >> > > > This email was Virus checked by UTM 9. http://www.sophos.com >> > > > >> > > >> > > >> > > >> > > -- >> > > >> > > Andrija Pani? >> > > >> > > -- >> > > This email was Virus checked by UTM 9. http://www.sophos.com >> > > >> > >> > >> > >> > -- >> > >> > Andrija Pani? >> > >> > -- >> > This email was Virus checked by UTM 9. http://www.sophos.com >> > >> > >> >> >> -- >> >> Andrija Pani? >> >> -- >> This email was Virus checked by UTM 9. http://www.sophos.com >> >> > > > -- > > Andrija Panić > -- Andrija Panić -- This email was Virus checked by UTM 9. http://www.sophos.com
