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 Sat, 30 May 2015 21:54:37 GMT
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-->Pool(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-->Pool(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","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataS
toreTO":{"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=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","hypervisorType":"KVM"}},"diskSeq":0,"path":"c65a5e7d-b3b9-495a-9170-f423765779cf","type":"ROOT","_details":{"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":"D
ATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"ccfacebe-19c3-38f4-a161-2bd9e4ac70a9","id":8,"poolType":"RBD","host":"ceph-mon-rr.storage","path":"cloudstack0
1","port":6789,"url":"RBD://ceph-mon-rr.storage/cloudstack01/?ROLE=Primary&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","hypervisorType":"KVM"}},"diskSeq":1,"path":"0f76d53b-2dfd-45ff-a274-0
94d05ffd291","type":"DATADISK","_details":{"managed":"false","storagePort":"6789","storageHost":"ceph-mon-rr.storage","volumeSize":"53687091200"}},{"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-e10c-4baf-ba90-6bf51a18feb9","volumeId":1095,"vmName":"i-78-595-VM","accountId":78,"format":"RAW","id":1095,"deviceId":4,"cacheMo
de":"NONE","hypervisorType":"KVM"}},"diskSeq":4,"path":"63ebce9c-e10c-4baf-ba90-6bf51a18feb9","type":"DATADISK","_details":{"managed":"false","storagePort":"6789","storageHost":"ceph-mon-rr.stora
ge","volumeSize":"536870912000"}},{"data":{"org.apache.cloudstack.storage.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-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.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-8f3ff5e63aea&response=
json&sessionkey=BzpqHhBkXr6XuS3sbumiqUWjTJY%3D&projectid=4865e6c6-b1ff-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-8f3ff5e63ae
a&response=json&sessionkey=BzpqHhBkXr6XuS3sbumiqUWjTJY%3D&projectid=4865e6c6-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-8f3ff5e63aea&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-8f3ff5e63aea
&response=json&sessionkey=BzpqHhBkXr6XuS3sbumiqUWjTJY%3D&projectid=4865e6c6-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-8f3ff5e63aea&response=
json&sessionkey=BzpqHhBkXr6XuS3sbumiqUWjTJY%3D&projectid=4865e6c6-b1ff-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-8f3ff5e63ae
a&response=json&sessionkey=BzpqHhBkXr6XuS3sbumiqUWjTJY%3D&projectid=4865e6c6-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-8f3ff5e63aea&response=
json&sessionkey=BzpqHhBkXr6XuS3sbumiqUWjTJY%3D&projectid=4865e6c6-b1ff-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-8f3ff5e63ae
a&response=json&sessionkey=BzpqHhBkXr6XuS3sbumiqUWjTJY%3D&projectid=4865e6c6-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":"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","vncA
ddr":"172.27.0.3","params":{"memoryOvercommitRatio":"1","cpuOvercommitRatio":"1.2","Message.ReservedCapacityFreed.Flag":"false"},"uuid":"8f3faedc-2401-4eed-9f81-4f160d3590c0","disks":[{"data":{"o
rg.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f5d96e65-a7f8-4c5a-a2f4-86bbfc53e5ee","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"ccfa
cebe-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=ccfa
cebe-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":10
90,"deviceId":0,"cacheMode":"NONE","hypervisorType":"KVM"}},"diskSeq":0,"path":"c65a5e7d-b3b9-495a-9170-f423765779cf","type":"ROOT","_details":{"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=ccfacebe-19c3-38f4-a161-2bd9e4ac70a9"}},"name":"SHARE-001","size":53687091200,"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","storageHost":"ceph-mon-rr.storage","volumeSize":"53687091200"}},{"data":{"org.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":"06ced5eb-37d8-452b-9544-6bf6a85e4cff","volumeType":"DATADI
SK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"ccfacebe-19c3-38f4-a161-2bd9e4ac70a9","id":8,"poolType":"RBD","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-data-500g","size":536870912000,"path":"63ebce9c-e10c-4baf-ba
90-6bf51a18feb9","volumeId":1095,"vmName":"i-78-595-VM","accountId":78,"format":"RAW","id":1095,"deviceId":4,"cacheMode":"NONE","hypervisorType":"KVM"}},"diskSeq":4,"path":"63ebce9c-e10c-4baf-ba9
0-6bf51a18feb9","type":"DATADISK","_details":{"managed":"false","storagePort":"6789","storageHost":"ceph-mon-rr.storage","volumeSize":"536870912000"}}],"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,"name":"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,"executeInSequence":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

Mime
View raw message