edikevich commented on issue #5803:
URL: https://github.com/apache/cloudstack/issues/5803#issuecomment-1010875638
2022-01-12 12:42:14,354 INFO [o.a.c.f.j.i.AsyncJobMonitor]
(API-Job-Executor-17:ctx-10e86d4b job-3810) (logid:0d4e47c6) Add job-3810 into
job monitoring
2022-01-12 12:42:14,358 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(qtp239372207-1931187:ctx-e0fcca0e ctx-b49fa34b) (logid:ccaa1505) submit async
job-3810, details: AsyncJobVO {id:3810, userId: 37, accountId: 34,
instanceType: VirtualMachine, instanceId: 334, cmd:
org.apache.cloudstack.api.command.user.vm.DeployVMCmd, cmdInfo:
{"iptonetworklist[0].networkid":"35d851d4-ccf2-4aaa-a372-5946aa1fd432","boottype":"BIOS","httpmethod":"POST","templateid":"445f7bc5-4d1e-4dff-a171-962adf124733","ctxAccountId":"34","uuid":"88d9976d-0b1a-4c78-a201-b6d153cf0e58","cmdEventType":"VM.CREATE","startvm":"true","bootmode":"LEGACY","serviceofferingid":"29710aa1-50be-472d-b76b-3dbcad42fc1b","response":"json","ctxUserId":"37","displayname":"test-vm1","name":"test-vm1","zoneid":"a5a0c8c3-f4ed-48e9-b3bf-3d645c96d252","ctxStartEventId":"13128","id":"334","ctxDetails":"{\"interface
com.cloud.offering.ServiceOffering\":\"29710aa1-50be-472d-b76b-3dbcad42fc1b\",\"interface
com.cloud.dc.DataCenter\":\"a5a0c8c3-f4
ed-48e9-b3bf-3d645c96d252\",\"interface
com.cloud.template.VirtualMachineTemplate\":\"445f7bc5-4d1e-4dff-a171-962adf124733\",\"interface
com.cloud.vm.VirtualMachine\":\"88d9976d-0b1a-4c78-a201-b6d153cf0e58\"}","affinitygroupids":""},
cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result:
null, initMsid: 172320828, completeMsid: null, lastUpdated: null, lastPolled:
null, created: null, removed: null}
2022-01-12 12:42:14,359 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810) (logid:7ebaa05f) Executing
AsyncJobVO {id:3810, userId: 37, accountId: 34, instanceType: VirtualMachine,
instanceId: 334, cmd: org.apache.cloudstack.api.command.user.vm.DeployVMCmd,
cmdInfo:
{"iptonetworklist[0].networkid":"35d851d4-ccf2-4aaa-a372-5946aa1fd432","boottype":"BIOS","httpmethod":"POST","templateid":"445f7bc5-4d1e-4dff-a171-962adf124733","ctxAccountId":"34","uuid":"88d9976d-0b1a-4c78-a201-b6d153cf0e58","cmdEventType":"VM.CREATE","startvm":"true","bootmode":"LEGACY","serviceofferingid":"29710aa1-50be-472d-b76b-3dbcad42fc1b","response":"json","ctxUserId":"37","displayname":"test-vm1","name":"test-vm1","zoneid":"a5a0c8c3-f4ed-48e9-b3bf-3d645c96d252","ctxStartEventId":"13128","id":"334","ctxDetails":"{\"interface
com.cloud.offering.ServiceOffering\":\"29710aa1-50be-472d-b76b-3dbcad42fc1b\",\"interface
com.cloud.dc.DataCenter\":\"a5a0c8c3-f4ed-48e9-b3bf-3d645c96d252\"
,\"interface
com.cloud.template.VirtualMachineTemplate\":\"445f7bc5-4d1e-4dff-a171-962adf124733\",\"interface
com.cloud.vm.VirtualMachine\":\"88d9976d-0b1a-4c78-a201-b6d153cf0e58\"}","affinitygroupids":""},
cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result:
null, initMsid: 172320828, completeMsid: null, lastUpdated: null, lastPolled:
null, created: null, removed: null}
2022-01-12 12:42:14,361 DEBUG [o.a.c.a.BaseCmd]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Ignoring paremeter displayvm as the caller is not authorized to pass it in
2022-01-12 12:42:14,361 DEBUG [o.a.c.a.BaseCmd]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Ignoring paremeter deploymentplanner as the caller is not authorized to pass it
in
2022-01-12 12:42:14,369 DEBUG [c.c.u.AccountManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Access to Acct[160ae17c-428d-4486-ac32-18c172a02735-esm] -- Account {"id": 34,
"name": "esm", "uuid": "160ae17c-428d-4486-ac32-18c172a02735"} granted to
Acct[160ae17c-428d-4486-ac32-18c172a02735-esm] -- Account {"id": 34, "name":
"esm", "uuid": "160ae17c-428d-4486-ac32-18c172a02735"} by DomainChecker
2022-01-12 12:42:14,374 DEBUG [c.c.u.AccountManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Access to Acct[160ae17c-428d-4486-ac32-18c172a02735-esm] -- Account {"id": 34,
"name": "esm", "uuid": "160ae17c-428d-4486-ac32-18c172a02735"} granted to
Acct[160ae17c-428d-4486-ac32-18c172a02735-esm] -- Account {"id": 34, "name":
"esm", "uuid": "160ae17c-428d-4486-ac32-18c172a02735"} by DomainChecker
2022-01-12 12:42:14,380 DEBUG [c.c.u.AccountManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Access to Tmpl[228-VHD-0219c594-238a-4cba-af52-65609364d5f7 granted to
Acct[160ae17c-428d-4486-ac32-18c172a02735-esm] -- Account {"id": 34, "name":
"esm", "uuid": "160ae17c-428d-4486-ac32-18c172a02735"} by DomainChecker
2022-01-12 12:42:14,380 DEBUG [o.a.c.a.BaseCmd]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Ignoring paremeter displayvm as the caller is not authorized to pass it in
2022-01-12 12:42:14,380 DEBUG [o.a.c.a.BaseCmd]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Ignoring paremeter deploymentplanner as the caller is not authorized to pass it
in
2022-01-12 12:42:14,394 DEBUG [c.c.u.AccountManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Access to VM instance {id: "334", name: "i-34-334-VM", uuid:
"88d9976d-0b1a-4c78-a201-b6d153cf0e58", type="User"} granted to
Acct[160ae17c-428d-4486-ac32-18c172a02735-esm] -- Account {"id": 34, "name":
"esm", "uuid": "160ae17c-428d-4486-ac32-18c172a02735"} by DomainChecker
2022-01-12 12:42:14,412 DEBUG [c.c.n.NetworkModelImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Service SecurityGroup is not supported in the network id=260
2022-01-12 12:42:14,418 DEBUG [c.c.n.NetworkModelImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Service SecurityGroup is not supported in the network id=260
2022-01-12 12:42:14,433 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
DeploymentPlanner allocation algorithm: null
2022-01-12 12:42:14,433 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Trying to allocate a host and storage pools from dc:1, pod:null,cluster:null,
requested cpu: 2000, requested ram: (2.00 GB) 2147483648
2022-01-12 12:42:14,433 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f) Is
ROOT volume READY (pool already allocated)?: No
2022-01-12 12:42:14,441 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Deploy avoids pods: [2], clusters: [], hosts: []
2022-01-12 12:42:14,442 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Searching all possible resources under this Zone: 1
2022-01-12 12:42:14,444 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Listing clusters in order of aggregate capacity, that have (at least one host
with) enough CPU and RAM capacity under this Zone: 1
2022-01-12 12:42:14,448 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Removing from the clusterId list these clusters from avoid set: []
2022-01-12 12:42:14,454 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f) The
clusterId list for the given offering tag: []
2022-01-12 12:42:14,454 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f) No
clusters found after removing disabled clusters and clusters in avoid list,
returning.
2022-01-12 12:42:14,457 DEBUG [c.c.v.UserVmManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Destroying vm VM instance {id: "334", name: "i-34-334-VM", uuid:
"88d9976d-0b1a-4c78-a201-b6d153cf0e58", type="User"} as it failed to create on
Host with Id:null
2022-01-12 12:42:14,468 DEBUG [c.c.c.CapacityManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f) VM
instance {id: "334", name: "i-34-334-VM", uuid:
"88d9976d-0b1a-4c78-a201-b6d153cf0e58", type="User"} state transited from
[Stopped] to [Error] with event [OperationFailedToError]. VM's original host:
null, new host: null, host before state transition: null
2022-01-12 12:42:14,492 DEBUG [c.c.r.ResourceLimitManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Updating resource Type = volume count for Account = 34 Operation = decreasing
Amount = 1
2022-01-12 12:42:14,502 DEBUG [c.c.r.ResourceLimitManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Updating resource Type = primary_storage count for Account = 34 Operation =
decreasing Amount = (10.00 GB) 10737418240
2022-01-12 12:42:14,517 WARN [c.c.a.AlertManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
alertType=[8] dataCenterId=[1] podId=[null] clusterId=[null] message=[Failed to
deploy Vm with Id: 334, on Host with Id: null].
2022-01-12 12:42:14,523 WARN [c.c.a.AlertManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f) No
recipients set in 'alert.email.addresses', skipping sending alert with subject:
Failed to deploy Vm with Id: 334, on Host with Id: null and content: Failed to
deploy Vm with Id: 334, on Host with Id: null
2022-01-12 12:42:14,525 DEBUG [c.c.r.ResourceLimitManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Updating resource Type = user_vm count for Account = 34 Operation = decreasing
Amount = 1
2022-01-12 12:42:14,535 DEBUG [c.c.r.ResourceLimitManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Updating resource Type = cpu count for Account = 34 Operation = decreasing
Amount = 1
2022-01-12 12:42:14,544 DEBUG [c.c.r.ResourceLimitManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Updating resource Type = memory count for Account = 34 Operation = decreasing
Amount = 2048
2022-01-12 12:42:14,559 INFO [o.a.c.a.c.u.v.DeployVMCmd]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
com.cloud.exception.InsufficientServerCapacityException: Unable to create a
deployment for VM instance {id: "334", name: "i-34-334-VM", uuid:
"88d9976d-0b1a-4c78-a201-b6d153cf0e58", type="User"}Scope=interface
com.cloud.dc.DataCenter; id=1
2022-01-12 12:42:14,559 INFO [o.a.c.a.c.u.v.DeployVMCmd]
(API-Job-Executor-17:ctx-10e86d4b job-3810 ctx-ed36cebb) (logid:7ebaa05f)
Unable to create a deployment for VM instance {id: "334", name: "i-34-334-VM",
uuid: "88d9976d-0b1a-4c78-a201-b6d153cf0e58", type="User"}
2022-01-12 12:42:14,561 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810) (logid:7ebaa05f) Complete async
job-3810, jobStatus: FAILED, resultCode: 530, result:
org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"errorcode":"533","errortext":"Unable
to create a deployment for VM instance {id: "334", name: "i-34-334-VM", uuid:
"88d9976d-0b1a-4c78-a201-b6d153cf0e58", type="User"}"}
2022-01-12 12:42:14,562 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810) (logid:7ebaa05f) Publish async
job-3810 complete on message bus
2022-01-12 12:42:14,562 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810) (logid:7ebaa05f) Wake up jobs
related to job-3810
2022-01-12 12:42:14,562 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810) (logid:7ebaa05f) Update db status
for job-3810
2022-01-12 12:42:14,563 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810) (logid:7ebaa05f) Wake up jobs
joined with job-3810 and disjoin all subjobs created from job- 3810
2022-01-12 12:42:14,570 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-17:ctx-10e86d4b job-3810) (logid:7ebaa05f) Done executing
org.apache.cloudstack.api.command.user.vm.DeployVMCmd for job-3810
2022-01-12 12:42:14,570 INFO [o.a.c.f.j.i.AsyncJobMonitor]
(API-Job-Executor-17:ctx-10e86d4b job-3810) (logid:7ebaa05f) Remove job-3810
from job monitoring
or you want all log before job start?
--
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.
To unsubscribe, e-mail: [email protected]
For queries about this service, please contact Infrastructure at:
[email protected]
