cloudstack-users mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Jochim, Ingo" <Ingo.Joc...@bautzen-it.de>
Subject AW: hostId: 25 is in avoid set, skipping this and trying other available hosts
Date Mon, 01 Jun 2015 07:47:40 GMT
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:andrija.panic@gmail.com] 
Gesendet: Sonntag, 31. Mai 2015 12:18
An: users@cloudstack.apache.org
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 <andrija.panic@gmail.com> 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 <Ingo.Jochim@bautzen-it.de> 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 [andrija.panic@gmail.com]
>> Gesendet: Sonntag, 31. Mai 2015 00:32
>> An: users@cloudstack.apache.org
>> 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 <Ingo.Jochim@bautzen-it.de> 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 [andrija.panic@gmail.com]
>> > Gesendet: Sonntag, 31. Mai 2015 00:20
>> > An: users@cloudstack.apache.org
>> > 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 <Ingo.Jochim@bautzen-it.de>
>> 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 [andrija.panic@gmail.com]
>> > > Gesendet: Samstag, 30. Mai 2015 23:45
>> > > An: users@cloudstack.apache.org
>> > > 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 <Ingo.Jochim@bautzen-it.de>
>> 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
Mime
View raw message