GitHub user alsko-icom added a comment to the discussion: Starting instances in
a domain other than root fails,
Apologies I may have correlated the VM uuid instead of the "No suitable host"!
After capturing the correct lines this time it appears as the VM has a UEFI tag
which the hosts do not. If I get this right, how do I overcome? Its a simple
selection of a UEFI VM, not something exotic with manually creating tags for
it. Pasting the logs below, 50~ish lines. I am pasting both the tail of the
error and the cat of the logid.
[root@cloudstack1 ~]# tail -f
/var/log/cloudstack/management/management-server.log |grep 'No suitable host
found'
2025-03-06 15:17:06,708 ERROR [c.c.a.m.a.i.FirstFitRoutingAllocator]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b,
FirstFitRoutingAllocator]) (logid:c51b4fab) No suitable host found for vm [VM
instance
{"id":39,"instanceName":"i-15-39-VM","type":"User","uuid":"68cbdc53-28f2-4a9a-8e97-54796be4ed19"}]
with tags [null].
2025-03-06 15:17:06,714 ERROR [c.c.a.ApiAsyncJobDispatcher]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825]) (logid:c51b4fab) Unexpected
exception while executing org.apache.cloudstack.api.command.user.vm.StartVMCmd
com.cloud.utils.exception.CloudRuntimeException: No suitable host found for vm
[VM instance
{"id":39,"instanceName":"i-15-39-VM","type":"User","uuid":"68cbdc53-28f2-4a9a-8e97-54796be4ed19"}].
2025-03-06 15:17:06,715 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825]) (logid:c51b4fab) Complete async
job-825, jobStatus: FAILED, resultCode: 530, result:
org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"errorcode":"530","errortext":"No
suitable host found for vm [VM instance
{"id":39,"instanceName":"i-15-39-VM","type":"User","uuid":"68cbdc53-28f2-4a9a-8e97-54796be4ed19"}]."}
2025-03-06 15:17:09,727 INFO [c.c.a.ApiServlet]
(qtp1390913202-174318:[ctx-f4a2683f, ctx-e228e9fb]) (logid:3a225138) (userId=12
accountId=15 sessionId=node0oauoy6jvtzhhede9ib5ylg5u17) 10.200.54.67 -- GET
jobId=c51b4fab-5333-4523-bd32-f22074d5d1ec&command=queryAsyncJobResult&response=json&sessionkey=J5sGOdRq-HKF5P0AAqfeSqwQq0I
200
{"queryasyncjobresultresponse":{"accountid":"b0f41028-9b78-4d71-bac2-ef0bcedb8d26","account":"alsko-VF9","domainid":"3796f13c-747c-46c8-b536-0cf018806957","domainpath":"ROOT/VodafoneVCS/VF0000999sub","userid":"7a8769e9-8124-4468-a16c-8ab0a4d6b956","cmd":"org.apache.cloudstack.api.command.user.vm.StartVMCmd","jobstatus":2,"jobprocstatus":0,"jobresultcode":530,"jobresulttype":"object","jobresult":{"errorcode":530,"errortext":"No
suitable host found for vm [VM instance
{\"id\":39,\"instanceName\":\"i-15-39-VM\",\"type\":\"User\",\"uuid\":\"68cbdc53-28f2-4a9a-8e97-54796be4ed19\"}]."},"jobinstancetype":"VirtualMachine","jobinstanceid":"68cbdc53-28f2-4a9a-8e97-54
796be4ed19","created":"2025-03-06T15:17:06+0200","completed":"2025-03-06T15:17:06+0200","jobid":"c51b4fab-5333-4523-bd32-f22074d5d1ec"}}
^C
[root@cloudstack1 ~]# cat /var/log/cloudstack/management/management-server.log
|grep 'logid:c51b4fab'
[root@cloudstack1 ~]# cat /var/log/cloudstack/management/management-server.log
|grep 'logid:c51b4fab'
2025-03-06 15:17:06,665 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl$5]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825]) (logid:c51b4fab) Executing
AsyncJobVO: {id:825, userId: 12, accountId: 15, instanceType: VirtualMachine,
instanceId: 39, cmd: org.apache.cloudstack.api.command.user.vm.StartVMCmd,
cmdInfo:
{"considerlasthost":"true","response":"json","ctxUserId":"12","sessionkey":"J5sGOdRq-HKF5P0AAqfeSqwQq0I","httpmethod":"GET","ctxStartEventId":"10383","id":"68cbdc53-28f2-4a9a-8e97-54796be4ed19","ctxDetails":"{\"interface
com.cloud.vm.VirtualMachine\":\"68cbdc53-28f2-4a9a-8e97-54796be4ed19\"}","ctxAccountId":"15","uuid":"68cbdc53-28f2-4a9a-8e97-54796be4ed19","cmdEventType":"VM.START"},
cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result:
null, initMsid: 246321136130000, completeMsid: null, lastUpdated: null,
lastPolled: null, created: null, removed: null}
2025-03-06 15:17:06,666 DEBUG [o.a.c.a.c.u.v.StartVMCmd]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Ignoring parameter considerlasthost as the caller is not authorized to pass it
in
2025-03-06 15:17:06,666 DEBUG [o.a.c.a.c.u.v.StartVMCmd]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Ignoring parameter deploymentplanner as the caller is not authorized to pass it
in
2025-03-06 15:17:06,669 DEBUG [c.c.u.AccountManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Access to Account
[{"accountName":"alsko-VF9","id":15,"uuid":"b0f41028-9b78-4d71-bac2-ef0bcedb8d26"}]
granted to Account
[{"accountName":"alsko-VF9","id":15,"uuid":"b0f41028-9b78-4d71-bac2-ef0bcedb8d26"}]
by DomainChecker
2025-03-06 15:17:06,670 DEBUG [c.c.u.AccountManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Access to VM[User|alsko-debi-vf9] granted to Account
[{"accountName":"alsko-VF9","id":15,"uuid":"b0f41028-9b78-4d71-bac2-ef0bcedb8d26"}]
by DomainChecker
2025-03-06 15:17:06,671 DEBUG [c.c.u.AccountManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Account [b0f41028-9b78-4d71-bac2-ef0bcedb8d26] has access to resource.
2025-03-06 15:17:06,671 DEBUG [o.a.c.a.c.u.v.StartVMCmd]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Ignoring parameter considerlasthost as the caller is not authorized to pass it
in
2025-03-06 15:17:06,671 DEBUG [o.a.c.a.c.u.v.StartVMCmd]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Ignoring parameter deploymentplanner as the caller is not authorized to pass it
in
2025-03-06 15:17:06,671 WARN [c.c.a.d.ParamGenericValidationWorker]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Received unknown parameters for command startVirtualMachine. Unknown parameters
: considerlasthost
2025-03-06 15:17:06,680 DEBUG [c.c.u.AccountManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Access to VM instance
{"id":39,"instanceName":"i-15-39-VM","type":"User","uuid":"68cbdc53-28f2-4a9a-8e97-54796be4ed19"}
granted to Account
[{"accountName":"alsko-VF9","id":15,"uuid":"b0f41028-9b78-4d71-bac2-ef0bcedb8d26"}]
by DomainChecker
2025-03-06 15:17:06,687 DEBUG [c.c.n.NetworkModelImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Service SecurityGroup is not supported in the network id=218
2025-03-06 15:17:06,689 DEBUG [c.c.n.NetworkModelImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Service SecurityGroup is not supported in the network id=218
2025-03-06 15:17:06,692 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Trying to deploy VM [error decoding VM instance
{"id":39,"instanceName":"i-15-39-VM","type":"User","uuid":"68cbdc53-28f2-4a9a-8e97-54796be4ed19"}]
and details: Plan
[{"_dcId":1,"_recreateDisks":false,"preferredHostIds":[],"migrationPlan":false,"hostPriorities":{}}];
avoid list [{}] and planner: [].
2025-03-06 15:17:06,693 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Checking non dedicated resources to deploy VM
[{"instanceName":"i-15-39-VM","type":"User","uuid":"68cbdc53-28f2-4a9a-8e97-54796be4ed19"}].
2025-03-06 15:17:06,695 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Adding pods [[]], clusters [[]] and hosts [[]] to the avoid list in the deploy
process of user VM ["68cbdc53-28f2-4a9a-8e97-54796be4ed19"], because this VM is
not explicitly dedicated to these components.
2025-03-06 15:17:06,695 DEBUG [o.a.c.u.r.ReflectionToStringBuilderUtils]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Object is null, not reflecting it.
2025-03-06 15:17:06,696 DEBUG [o.a.c.u.r.ReflectionToStringBuilderUtils]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Object is null, not reflecting it.
2025-03-06 15:17:06,696 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Trying to allocate a host and storage pools from datacenter
[{"name":"A5-Zone1","uuid":"d5043dad-92b7-4307-a3b7-36b7db2a7e36"}], pod
[null], cluster [null], to deploy VM
[{"instanceName":"i-15-39-VM","type":"User","uuid":"68cbdc53-28f2-4a9a-8e97-54796be4ed19"}]
with requested CPU [1000] and requested RAM [(1.00 GB) 1073741824].
2025-03-06 15:17:06,696 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
ROOT volume [a8dc2f47-0acf-4f8d-812b-3333aa7740cb] is not ready to deploy VM
[68cbdc53-28f2-4a9a-8e97-54796be4ed19].
2025-03-06 15:17:06,697 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Adding pods [] to the avoid set because these pods are in the Disabled state.
2025-03-06 15:17:06,698 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Adding clusters [] of pod [1] to the void set because these clusters are in the
Disabled state.
2025-03-06 15:17:06,698 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Adding hosts [] of datacenter [d5043dad-92b7-4307-a3b7-36b7db2a7e36] to the
avoid set, because these hosts are in the Disabled state.
2025-03-06 15:17:06,699 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
DeploymentPlan [DataCenterDeployment] has not specified host. Trying to find
another destination to deploy VM [68cbdc53-28f2-4a9a-8e97-54796be4ed19],
avoiding pods [], clusters [] and hosts [].
2025-03-06 15:17:06,699 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Deploy avoids pods: [], clusters: [], hosts: [].
2025-03-06 15:17:06,699 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Deploy hosts with priorities {}, hosts have NORMAL priority by default
2025-03-06 15:17:06,700 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Searching all possible resources under this Zone: 1
2025-03-06 15:17:06,700 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Listing clusters in order of aggregate capacity, that have (at least one host
with) enough CPU and RAM capacity under this Zone: 1
2025-03-06 15:17:06,702 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Removing from the clusterId list these clusters from avoid set: []
2025-03-06 15:17:06,705 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Adding cluster [ed71471b-9017-449e-8b0b-006ceb909777] to the avoid set because
the cluster's hypervisor [VMware] does not match the VM
[68cbdc53-28f2-4a9a-8e97-54796be4ed19] hypervisor: [KVM]. Skipping this cluster.
2025-03-06 15:17:06,705 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Checking resources in Cluster: 1 under Pod: 1
2025-03-06 15:17:06,706 INFO [c.c.a.m.a.i.FirstFitRoutingAllocator]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b,
FirstFitRoutingAllocator]) (logid:c51b4fab) Guest VM is requested with
Custom[UEFI] Boot Type true
2025-03-06 15:17:06,706 DEBUG [c.c.a.m.a.i.FirstFitRoutingAllocator]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b,
FirstFitRoutingAllocator]) (logid:c51b4fab) Looking for hosts in zone [1], pod
[1], cluster [1]
2025-03-06 15:17:06,707 DEBUG [c.c.a.m.a.i.FirstFitRoutingAllocator]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b,
FirstFitRoutingAllocator]) (logid:c51b4fab) Hosts with tag 'UEFI' are:[]
2025-03-06 15:17:06,708 ERROR [c.c.a.m.a.i.FirstFitRoutingAllocator]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b,
FirstFitRoutingAllocator]) (logid:c51b4fab) No suitable host found for vm [VM
instance
{"id":39,"instanceName":"i-15-39-VM","type":"User","uuid":"68cbdc53-28f2-4a9a-8e97-54796be4ed19"}]
with tags [null].
2025-03-06 15:17:06,708 WARN [o.a.c.m.w.WebhookServiceImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Skipping delivering event [ID: null, description: {"details":"Vm Id:
68cbdc53-28f2-4a9a-8e97-54796be4ed19","event":"VM.START","status":"Completed"}]
to any webhook as account ID is missing
2025-03-06 15:17:06,709 WARN [o.a.c.f.e.EventDistributorImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825, ctx-99e4d63b]) (logid:c51b4fab)
Failed to publish event [category: ActionEvent, type: VM.START] on bus
webhookEventBus
2025-03-06 15:17:06,714 ERROR [c.c.a.ApiAsyncJobDispatcher]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825]) (logid:c51b4fab) Unexpected
exception while executing org.apache.cloudstack.api.command.user.vm.StartVMCmd
com.cloud.utils.exception.CloudRuntimeException: No suitable host found for vm
[VM instance
{"id":39,"instanceName":"i-15-39-VM","type":"User","uuid":"68cbdc53-28f2-4a9a-8e97-54796be4ed19"}].
2025-03-06 15:17:06,715 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825]) (logid:c51b4fab) Complete async
job-825, jobStatus: FAILED, resultCode: 530, result:
org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"errorcode":"530","errortext":"No
suitable host found for vm [VM instance
{"id":39,"instanceName":"i-15-39-VM","type":"User","uuid":"68cbdc53-28f2-4a9a-8e97-54796be4ed19"}]."}
2025-03-06 15:17:06,715 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825]) (logid:c51b4fab) Publish async
job-825 complete on message bus
2025-03-06 15:17:06,715 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825]) (logid:c51b4fab) Wake up jobs
related to job-825
2025-03-06 15:17:06,715 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825]) (logid:c51b4fab) Update db status
for job-825
2025-03-06 15:17:06,716 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825]) (logid:c51b4fab) Wake up jobs
joined with job-825 and disjoin all subjobs created from job- 825
2025-03-06 15:17:06,719 DEBUG [c.c.a.ApiServer]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825]) (logid:c51b4fab) Retrieved
cmdEventType from job info: VM.START
2025-03-06 15:17:06,721 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl$5]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825]) (logid:c51b4fab) Done executing
org.apache.cloudstack.api.command.user.vm.StartVMCmd for job-825
2025-03-06 15:17:06,721 INFO [o.a.c.f.j.i.AsyncJobMonitor]
(API-Job-Executor-44:[ctx-7c27e5ec, job-825]) (logid:c51b4fab) Remove job-825
from job monitoring
[root@cloudstack1 ~]#
GitHub link:
https://github.com/apache/cloudstack/discussions/10430#discussioncomment-12414186
----
This is an automatically sent email for users@cloudstack.apache.org.
To unsubscribe, please send an email to: users-unsubscr...@cloudstack.apache.org
