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.pa...@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.joc...@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
