nimbustech-lab commented on issue #11052:
URL: https://github.com/apache/cloudstack/issues/11052#issuecomment-2989652580
Hi @DaanHoogland . This is what I got when I tried to create a new VM with
the ID, i-4-619-VM.
root@rnd-ubuntu-mgmt-svr:/home/nimbus# grep -i "i-4-619-VM"
/var/log/cloudstack/management/management-server.log | less
2025-06-20 02:48:12,232 INFO [c.c.v.VirtualMachineManagerImpl]
(qtp341748265-5716:ctx-4c68571b ctx-98e5ca78) (logid:a4cedac3) allocating
virtual machine from template:a1905274-90d1-4c9e-bbf1-6067fc93ffda with
hostname:i-4-619-VM and 1 networks
2025-06-20 02:48:12,235 DEBUG [c.c.v.VirtualMachineManagerImpl]
(qtp341748265-5716:ctx-4c68571b ctx-98e5ca78) (logid:a4cedac3) Allocating
entries for VM: VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
2025-06-20 02:48:12,241 DEBUG [c.c.v.VirtualMachineManagerImpl]
(qtp341748265-5716:ctx-4c68571b ctx-98e5ca78) (logid:a4cedac3) Allocating nics
for VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
2025-06-20 02:48:12,244 DEBUG [o.a.c.e.o.NetworkOrchestrator]
(qtp341748265-5716:ctx-4c68571b ctx-98e5ca78) (logid:a4cedac3) Allocating nic
for vm VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
in network Network {"id": 214, "name": "VM-NIC1", "uuid":
"759ea379-6e4d-4316-a943-2904e70b5706", "networkofferingid": 16} with requested
profile NicProfile
{"broadcastUri":null,"deviceId":null,"iPv4Address":null,"id":0,"reservationId":null,"vmId":0}
2025-06-20 02:48:12,272 DEBUG [c.c.v.VirtualMachineManagerImpl]
(qtp341748265-5716:ctx-4c68571b ctx-98e5ca78) (logid:a4cedac3) Allocating disks
for VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
2025-06-20 02:48:12,274 INFO [o.a.c.e.o.VolumeOrchestrator]
(qtp341748265-5716:ctx-4c68571b ctx-98e5ca78 ctx-31d617df) (logid:a4cedac3)
Adding disk object [ROOT-619] to VM [VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}]
2025-06-20 02:48:12,429 DEBUG [c.c.v.VirtualMachineManagerImpl]
(qtp341748265-5716:ctx-4c68571b ctx-98e5ca78) (logid:a4cedac3) Allocation
completed for VM: VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
2025-06-20 02:48:12,429 DEBUG [c.c.v.UserVmManagerImpl]
(qtp341748265-5716:ctx-4c68571b ctx-98e5ca78) (logid:a4cedac3) Successfully
allocated DB entry for VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
2025-06-20 02:48:14,146 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) orchestrating VM start for 'i-4-619-VM'
com.cloud.vm.VirtualMachineProfile$Param@b66cdd7d set to null
2025-06-20 02:48:14,154 DEBUG [c.c.c.CapacityManagerImpl]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
state transited from [Stopped] to [Starting] with event [StartRequested]. VM's
original host: null, new host: null, host before state transition: null
2025-06-20 02:48:14,155 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) Successfully transitioned to start state for VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
reservation id = 469163ee-aecd-49cf-ab8d-543e7cb0236b
2025-06-20 02:48:14,258 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) Deployment found - Attempt #1 - P0=VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"},
P0=Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
:
Dest[Zone(3)-Pod(3)-Cluster(2)-Host(2)-Storage(Volume(614|ROOT-->Pool(4)Volume(615|DATADISK-->Pool(4))]
2025-06-20 02:48:14,270 DEBUG [c.c.c.CapacityManagerImpl]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
state transited from [Starting] to [Starting] with event [OperationRetry].
VM's original host: null, new host: Host
{"id":2,"name":"192.168.80.81","type":"Routing","uuid":"2c7b726b-c9b7-4b12-9c27-28a5f58aede0"},
host before state transition: null
2025-06-20 02:48:14,424 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) Seq 2-327073922937792462: Sending { Cmd , MgmtId:
345049157785, via: 2(192.168.80.81), Ver: v1, Flags: 100111,
[{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"5ac6b8c4a7af3e3fbf2fb321fffd9dd7","uuid":"a1905274-90d1-4c9e-bbf1-6067fc93ffda","id":"221","format":"OVA","accountId":"4","hvm":"true","displayText":"This
is the template for Windows Server 2022 with custom (unconstrained)
offering.","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"c651d575-bfbc-401e-bb0c-b23963a38df9","name":"ds-cloudstack","id":"4","poolType":"VMFS","host":"VMFS
datastore: datastore-16","path":"datastore-16","port":"0","url":"VMFS://VMFS
datastore:
datastore-16/datastore-16/?ROLE=Primary&STOREUUID=c651d575-bfbc-401e-bb0c-b23963a38df9","isManaged":"false"}},"name":"221-
4-cdb53111-8212-38a5-91ec-2bece31fe4b9","size":"(35.00 GB)
37580963840","hypervisorType":"VMware","bootable":"false","uniqueName":"221-4-cdb53111-8212-38a5-91ec-2bece31fe4b9","directDownload":"false","deployAsIs":"false","followRedirects":"false"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"3fe7354e-5cad-4616-a1a2-996ff4486e2e","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"c651d575-bfbc-401e-bb0c-b23963a38df9","name":"ds-cloudstack","id":"4","poolType":"VMFS","host":"VMFS
datastore: datastore-16","path":"datastore-16","port":"0","url":"VMFS://VMFS
datastore:
datastore-16/datastore-16/?ROLE=Primary&STOREUUID=c651d575-bfbc-401e-bb0c-b23963a38df9","fullCloneFlag":"true","diskProvisioningStrictnessFlag":"false","isManaged":"false"}},"name":"ROOT-619","size":"(35.00
GB)
37580963840","volumeId":"614","vmName":"i-4-619-VM","accountId":"4","format":"OVA","provisioningType":"FAT","poolId":"4","id":"614","deviceId":"0
","cacheMode":"WRITETHROUGH","hypervisorType":"VMware","directDownload":"false","deployAsIs":"false","followRedirects":"false"}},"executeInSequence":"true","options":{},"options2":{},"wait":"0","bypassHostMaintenance":"false"}}]
}
2025-06-20 02:48:14,426 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) Seq 2-327073922937792462: Executing: { Cmd , MgmtId:
345049157785, via: 2(192.168.80.81), Ver: v1, Flags: 100111,
[{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"5ac6b8c4a7af3e3fbf2fb321fffd9dd7","uuid":"a1905274-90d1-4c9e-bbf1-6067fc93ffda","id":"221","format":"OVA","accountId":"4","hvm":"true","displayText":"This
is the template for Windows Server 2022 with custom (unconstrained)
offering.","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"c651d575-bfbc-401e-bb0c-b23963a38df9","name":"ds-cloudstack","id":"4","poolType":"VMFS","host":"VMFS
datastore: datastore-16","path":"datastore-16","port":"0","url":"VMFS://VMFS
datastore:
datastore-16/datastore-16/?ROLE=Primary&STOREUUID=c651d575-bfbc-401e-bb0c-b23963a38df9","isManaged":"false"}},"name":"2
21-4-cdb53111-8212-38a5-91ec-2bece31fe4b9","size":"(35.00 GB)
37580963840","hypervisorType":"VMware","bootable":"false","uniqueName":"221-4-cdb53111-8212-38a5-91ec-2bece31fe4b9","directDownload":"false","deployAsIs":"false","followRedirects":"false"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"3fe7354e-5cad-4616-a1a2-996ff4486e2e","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"c651d575-bfbc-401e-bb0c-b23963a38df9","name":"ds-cloudstack","id":"4","poolType":"VMFS","host":"VMFS
datastore: datastore-16","path":"datastore-16","port":"0","url":"VMFS://VMFS
datastore:
datastore-16/datastore-16/?ROLE=Primary&STOREUUID=c651d575-bfbc-401e-bb0c-b23963a38df9","fullCloneFlag":"true","diskProvisioningStrictnessFlag":"false","isManaged":"false"}},"name":"ROOT-619","size":"(35.00
GB)
37580963840","volumeId":"614","vmName":"i-4-619-VM","accountId":"4","format":"OVA","provisioningType":"FAT","poolId":"4","id":"614","deviceId"
:"0","cacheMode":"WRITETHROUGH","hypervisorType":"VMware","directDownload":"false","deployAsIs":"false","followRedirects":"false"}},"executeInSequence":"true","options":{},"options2":{},"wait":"0","bypassHostMaintenance":"false"}}]
}
2025-06-20 02:48:14,426 DEBUG [c.c.h.v.r.VmwareResource]
(DirectAgent-345:ctx-11d49002) (logid:9556019e) Executing resource command
CopyCommand:
[{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"5ac6b8c4a7af3e3fbf2fb321fffd9dd7","uuid":"a1905274-90d1-4c9e-bbf1-6067fc93ffda","id":221,"format":"OVA","accountId":4,"hvm":true,"displayText":"This
is the template for Windows Server 2022 with custom (unconstrained)
offering.","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"c651d575-bfbc-401e-bb0c-b23963a38df9","name":"ds-cloudstack","id":4,"poolType":"VMFS","host":"VMFS
datastore: datastore-16","path":"datastore-16","port":0,"url":"VMFS://VMFS
datastore:
datastore-16/datastore-16/?ROLE=Primary&STOREUUID=c651d575-bfbc-401e-bb0c-b23963a38df9","isManaged":false}},"name":"221-4-cdb53111-8212-38a5-91ec-2bece31fe4b9","size":37580963840,"hypervisorType":"VMware","bootable":false,"uniqueName":"221-4-cdb53111-8212-38a5-91ec-2bece31fe4b9","dir
ectDownload":false,"deployAsIs":false,"followRedirects":false}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"3fe7354e-5cad-4616-a1a2-996ff4486e2e","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"c651d575-bfbc-401e-bb0c-b23963a38df9","name":"ds-cloudstack","id":4,"poolType":"VMFS","host":"VMFS
datastore: datastore-16","path":"datastore-16","port":0,"url":"VMFS://VMFS
datastore:
datastore-16/datastore-16/?ROLE=Primary&STOREUUID=c651d575-bfbc-401e-bb0c-b23963a38df9","fullCloneFlag":true,"diskProvisioningStrictnessFlag":false,"isManaged":false}},"name":"ROOT-619","size":37580963840,"volumeId":614,"vmName":"i-4-619-VM","accountId":4,"format":"OVA","provisioningType":"FAT","poolId":4,"id":614,"deviceId":0,"cacheMode":"WRITETHROUGH","hypervisorType":"VMware","directDownload":false,"deployAsIs":false,"followRedirects":false}},"executeInSequence":true,"options":{},"options2":{},"wait":0,"bypassHostMaintenance":false}].
2025-06-20 02:48:14,427 DEBUG [c.c.s.r.StorageSubsystemCommandHandlerBase]
(DirectAgent-345:ctx-11d49002 192.168.80.81, job-2149/job-2150, cmd:
CopyCommand) (logid:9556019e) Executing command CopyCommand:
[{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"5ac6b8c4a7af3e3fbf2fb321fffd9dd7","uuid":"a1905274-90d1-4c9e-bbf1-6067fc93ffda","id":221,"format":"OVA","accountId":4,"hvm":true,"displayText":"This
is the template for Windows Server 2022 with custom (unconstrained)
offering.","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"c651d575-bfbc-401e-bb0c-b23963a38df9","name":"ds-cloudstack","id":4,"poolType":"VMFS","host":"VMFS
datastore: datastore-16","path":"datastore-16","port":0,"url":"VMFS://VMFS
datastore:
datastore-16/datastore-16/?ROLE=Primary&STOREUUID=c651d575-bfbc-401e-bb0c-b23963a38df9","isManaged":false}},"name":"221-4-cdb53111-8212-38a5-91ec-2bece31fe4b9","size":37580963840,"hypervisorType":"VMware","bootable":false,"u
niqueName":"221-4-cdb53111-8212-38a5-91ec-2bece31fe4b9","directDownload":false,"deployAsIs":false,"followRedirects":false}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"3fe7354e-5cad-4616-a1a2-996ff4486e2e","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"c651d575-bfbc-401e-bb0c-b23963a38df9","name":"ds-cloudstack","id":4,"poolType":"VMFS","host":"VMFS
datastore: datastore-16","path":"datastore-16","port":0,"url":"VMFS://VMFS
datastore:
datastore-16/datastore-16/?ROLE=Primary&STOREUUID=c651d575-bfbc-401e-bb0c-b23963a38df9","fullCloneFlag":true,"diskProvisioningStrictnessFlag":false,"isManaged":false}},"name":"ROOT-619","size":37580963840,"volumeId":614,"vmName":"i-4-619-VM","accountId":4,"format":"OVA","provisioningType":"FAT","poolId":4,"id":614,"deviceId":0,"cacheMode":"WRITETHROUGH","hypervisorType":"VMware","directDownload":false,"deployAsIs":false,"followRedirects":false}},"executeInSequence":true,"options":
{},"options2":{},"wait":0,"bypassHostMaintenance":false}].
2025-06-20 02:48:14,556 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) Cleaning up resources for the vm VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
in Starting state
2025-06-20 02:48:14,569 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
is a [User], returning null for control Nic IP.
2025-06-20 02:48:14,576 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) Seq 2-327073922937792463: Sending { Cmd , MgmtId:
345049157785, via: 2(192.168.80.81), Ver: v1, Flags: 100111,
[{"com.cloud.agent.api.StopCommand":{"isProxy":"false","checkBeforeCleanup":"false","forceStop":"false","vlanToPersistenceMap":{"700":"true"},"volumesToDisconnect":[],"vmName":"i-4-619-VM","executeInSequence":"true","wait":"0","bypassHostMaintenance":"false"}}]
}
2025-06-20 02:48:14,576 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) Seq 2-327073922937792463: Executing: { Cmd , MgmtId:
345049157785, via: 2(192.168.80.81), Ver: v1, Flags: 100111,
[{"com.cloud.agent.api.StopCommand":{"isProxy":"false","checkBeforeCleanup":"false","forceStop":"false","vlanToPersistenceMap":{"700":"true"},"volumesToDisconnect":[],"vmName":"i-4-619-VM","executeInSequence":"true","wait":"0","bypassHostMaintenance":"false"}}]
}
2025-06-20 02:48:14,577 DEBUG [c.c.h.v.r.VmwareResource]
(DirectAgent-87:ctx-80d2d086) (logid:9556019e) Executing resource command
StopCommand:
[{"isProxy":false,"checkBeforeCleanup":false,"forceStop":false,"vlanToPersistenceMap":{"700":true},"volumesToDisconnect":[],"vmName":"i-4-619-VM","executeInSequence":true,"wait":0,"bypassHostMaintenance":false}].
2025-06-20 02:48:14,590 DEBUG [c.c.h.v.m.HostMO]
(DirectAgent-87:ctx-80d2d086 192.168.80.81, job-2149/job-2150, cmd:
StopCommand) (logid:9556019e) find VM i-4-619-VM on host
2025-06-20 02:48:14,590 INFO [c.c.h.v.m.HostMO]
(DirectAgent-87:ctx-80d2d086 192.168.80.81, job-2149/job-2150, cmd:
StopCommand) (logid:9556019e) VM i-4-619-VM not found in host cache
2025-06-20 02:48:14,608 INFO [c.c.h.v.r.VmwareResource]
(DirectAgent-87:ctx-80d2d086 192.168.80.81, job-2149/job-2150, cmd:
StopCommand) (logid:9556019e) VM i-4-619-VM is no longer on the expected host
in vSphere
2025-06-20 02:48:14,608 DEBUG [c.c.a.t.Request]
(DirectAgent-87:ctx-80d2d086) (logid:9556019e) Seq 2-327073922937792463:
Processing: { Ans: , MgmtId: 345049157785, via: 2(192.168.80.81), Ver: v1,
Flags: 110, [{"com.cloud.agent.api.StopAnswer":{"result":"true","details":"VM
i-4-619-VM is no longer on the expected host in
vSphere","wait":"0","bypassHostMaintenance":"false"}}] }
2025-06-20 02:48:14,627 DEBUG [o.a.c.e.o.NetworkOrchestrator]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) The nic Nic
{"broadcastUri":"vlan:\/\/700","deviceId":0,"iPv4Address":null,"id":1863,"instanceId":619,"reservationId":"469163ee-aecd-49cf-ab8d-543e7cb0236b"}
on NicProfile
{"broadcastUri":null,"deviceId":0,"iPv4Address":null,"id":1863,"reservationId":"469163ee-aecd-49cf-ab8d-543e7cb0236b","vmId":619}
was released according to VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
by guru com.cloud.network.guru.ExternalGuestNetworkGuru@74884fdd, now updating
record.
2025-06-20 02:48:14,884 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) Successfully released network resources for the VM VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
in Starting state
2025-06-20 02:48:14,893 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) Successfully released storage resources for the VM VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
in Starting state
2025-06-20 02:48:14,893 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) Successfully cleaned up resources for the VM VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
in Starting state
2025-06-20 02:48:14,926 DEBUG [c.c.c.CapacityManagerImpl]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
state transited from [Starting] to [Stopped] with event [OperationFailed].
VM's original host: null, new host: null, host before state transition: Host
{"id":2,"name":"192.168.80.81","type":"Routing","uuid":"2c7b726b-c9b7-4b12-9c27-28a5f58aede0"}
2025-06-20 02:48:14,947 ERROR [c.c.v.VmWorkJobHandlerProxy]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) Invocation exception, caused by:
com.cloud.exception.InsufficientServerCapacityException: Unable to create a
deployment for VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}Scope=interface
com.cloud.dc.DataCenter; id=3
2025-06-20 02:48:14,947 INFO [c.c.v.VmWorkJobHandlerProxy]
(Work-Job-Executor-29:ctx-94f5ba93 job-2149/job-2150 ctx-01b8dfa1)
(logid:9556019e) Rethrow exception
com.cloud.exception.InsufficientServerCapacityException: Unable to create a
deployment for VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}Scope=interface
com.cloud.dc.DataCenter; id=3
com.cloud.exception.InsufficientServerCapacityException: Unable to create a
deployment for VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}Scope=interface
com.cloud.dc.DataCenter; id=3
2025-06-20 02:48:16,139 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-30:ctx-6fdefcee job-2149/job-2151 ctx-ff58a858)
(logid:9556019e) orchestrating VM start for 'i-4-619-VM'
com.cloud.vm.VirtualMachineProfile$Param@b66cdd7d set to null
2025-06-20 02:48:16,147 DEBUG [c.c.c.CapacityManagerImpl]
(Work-Job-Executor-30:ctx-6fdefcee job-2149/job-2151 ctx-ff58a858)
(logid:9556019e) VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
state transited from [Stopped] to [Starting] with event [StartRequested]. VM's
original host: null, new host: null, host before state transition: null
2025-06-20 02:48:16,147 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-30:ctx-6fdefcee job-2149/job-2151 ctx-ff58a858)
(logid:9556019e) Successfully transitioned to start state for VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
reservation id = 3937a520-5226-4f99-aa81-881b7482c30f
2025-06-20 02:48:16,299 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-30:ctx-6fdefcee job-2149/job-2151 ctx-ff58a858)
(logid:9556019e) Deployment found - Attempt #1 - P0=VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"},
P0=Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
:
Dest[Zone(3)-Pod(3)-Cluster(2)-Host(2)-Storage(Volume(614|ROOT-->Pool(4)Volume(615|DATADISK-->Pool(4))]
2025-06-20 02:48:16,312 DEBUG [c.c.c.CapacityManagerImpl]
(Work-Job-Executor-30:ctx-6fdefcee job-2149/job-2151 ctx-ff58a858)
(logid:9556019e) VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
state transited from [Starting] to [Starting] with event [OperationRetry].
VM's original host: null, new host: Host
{"id":2,"name":"192.168.80.81","type":"Routing","uuid":"2c7b726b-c9b7-4b12-9c27-28a5f58aede0"},
host before state transition: null
2025-06-20 02:48:16,472 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-30:ctx-6fdefcee job-2149/job-2151 ctx-ff58a858)
(logid:9556019e) Seq 2-327073922937792465: Sending { Cmd , MgmtId:
345049157785, via: 2(192.168.80.81), Ver: v1, Flags: 100111,
[{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"5ac6b8c4a7af3e3fbf2fb321fffd9dd7","uuid":"a1905274-90d1-4c9e-bbf1-6067fc93ffda","id":"221","format":"OVA","accountId":"4","hvm":"true","displayText":"This
is the template for Windows Server 2022 with custom (unconstrained)
offering.","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"c651d575-bfbc-401e-bb0c-b23963a38df9","name":"ds-cloudstack","id":"4","poolType":"VMFS","host":"VMFS
datastore: datastore-16","path":"datastore-16","port":"0","url":"VMFS://VMFS
datastore:
datastore-16/datastore-16/?ROLE=Primary&STOREUUID=c651d575-bfbc-401e-bb0c-b23963a38df9","isManaged":"false"}},"name":"221-
4-cdb53111-8212-38a5-91ec-2bece31fe4b9","size":"(35.00 GB)
37580963840","hypervisorType":"VMware","bootable":"false","uniqueName":"221-4-cdb53111-8212-38a5-91ec-2bece31fe4b9","directDownload":"false","deployAsIs":"false","followRedirects":"false"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"3fe7354e-5cad-4616-a1a2-996ff4486e2e","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"c651d575-bfbc-401e-bb0c-b23963a38df9","name":"ds-cloudstack","id":"4","poolType":"VMFS","host":"VMFS
datastore: datastore-16","path":"datastore-16","port":"0","url":"VMFS://VMFS
datastore:
datastore-16/datastore-16/?ROLE=Primary&STOREUUID=c651d575-bfbc-401e-bb0c-b23963a38df9","fullCloneFlag":"true","diskProvisioningStrictnessFlag":"false","isManaged":"false"}},"name":"ROOT-619","size":"(35.00
GB)
37580963840","volumeId":"614","vmName":"i-4-619-VM","accountId":"4","format":"OVA","provisioningType":"FAT","poolId":"4","id":"614","deviceId":"0
","cacheMode":"WRITETHROUGH","hypervisorType":"VMware","directDownload":"false","deployAsIs":"false","followRedirects":"false"}},"executeInSequence":"true","options":{},"options2":{},"wait":"0","bypassHostMaintenance":"false"}}]
}
2025-06-20 02:48:16,474 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-30:ctx-6fdefcee job-2149/job-2151 ctx-ff58a858)
(logid:9556019e) Seq 2-327073922937792465: Executing: { Cmd , MgmtId:
345049157785, via: 2(192.168.80.81), Ver: v1, Flags: 100111,
[{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"5ac6b8c4a7af3e3fbf2fb321fffd9dd7","uuid":"a1905274-90d1-4c9e-bbf1-6067fc93ffda","id":"221","format":"OVA","accountId":"4","hvm":"true","displayText":"This
is the template for Windows Server 2022 with custom (unconstrained)
offering.","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"c651d575-bfbc-401e-bb0c-b23963a38df9","name":"ds-cloudstack","id":"4","poolType":"VMFS","host":"VMFS
datastore: datastore-16","path":"datastore-16","port":"0","url":"VMFS://VMFS
datastore:
datastore-16/datastore-16/?ROLE=Primary&STOREUUID=c651d575-bfbc-401e-bb0c-b23963a38df9","isManaged":"false"}},"name":"2
21-4-cdb53111-8212-38a5-91ec-2bece31fe4b9","size":"(35.00 GB)
37580963840","hypervisorType":"VMware","bootable":"false","uniqueName":"221-4-cdb53111-8212-38a5-91ec-2bece31fe4b9","directDownload":"false","deployAsIs":"false","followRedirects":"false"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"3fe7354e-5cad-4616-a1a2-996ff4486e2e","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"c651d575-bfbc-401e-bb0c-b23963a38df9","name":"ds-cloudstack","id":"4","poolType":"VMFS","host":"VMFS
datastore: datastore-16","path":"datastore-16","port":"0","url":"VMFS://VMFS
datastore:
datastore-16/datastore-16/?ROLE=Primary&STOREUUID=c651d575-bfbc-401e-bb0c-b23963a38df9","fullCloneFlag":"true","diskProvisioningStrictnessFlag":"false","isManaged":"false"}},"name":"ROOT-619","size":"(35.00
GB)
37580963840","volumeId":"614","vmName":"i-4-619-VM","accountId":"4","format":"OVA","provisioningType":"FAT","poolId":"4","id":"614","deviceId"
:"0","cacheMode":"WRITETHROUGH","hypervisorType":"VMware","directDownload":"false","deployAsIs":"false","followRedirects":"false"}},"executeInSequence":"true","options":{},"options2":{},"wait":"0","bypassHostMaintenance":"false"}}]
}
2025-06-20 02:48:16,474 DEBUG [c.c.h.v.r.VmwareResource]
(DirectAgent-22:ctx-ea084317) (logid:9556019e) Executing resource command
CopyCommand:
[{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"5ac6b8c4a7af3e3fbf2fb321fffd9dd7","uuid":"a1905274-90d1-4c9e-bbf1-6067fc93ffda","id":221,"format":"OVA","accountId":4,"hvm":true,"displayText":"This
is the template for Windows Server 2022 with custom (unconstrained)
offering.","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"c651d575-bfbc-401e-bb0c-b23963a38df9","name":"ds-cloudstack","id":4,"poolType":"VMFS","host":"VMFS
datastore: datastore-16","path":"datastore-16","port":0,"url":"VMFS://VMFS
datastore:
datastore-16/datastore-16/?ROLE=Primary&STOREUUID=c651d575-bfbc-401e-bb0c-b23963a38df9","isManaged":false}},"name":"221-4-cdb53111-8212-38a5-91ec-2bece31fe4b9","size":37580963840,"hypervisorType":"VMware","bootable":false,"uniqueName":"221-4-cdb53111-8212-38a5-91ec-2bece31fe4b9","dire
ctDownload":false,"deployAsIs":false,"followRedirects":false}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"3fe7354e-5cad-4616-a1a2-996ff4486e2e","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"c651d575-bfbc-401e-bb0c-b23963a38df9","name":"ds-cloudstack","id":4,"poolType":"VMFS","host":"VMFS
datastore: datastore-16","path":"datastore-16","port":0,"url":"VMFS://VMFS
datastore:
datastore-16/datastore-16/?ROLE=Primary&STOREUUID=c651d575-bfbc-401e-bb0c-b23963a38df9","fullCloneFlag":true,"diskProvisioningStrictnessFlag":false,"isManaged":false}},"name":"ROOT-619","size":37580963840,"volumeId":614,"vmName":"i-4-619-VM","accountId":4,"format":"OVA","provisioningType":"FAT","poolId":4,"id":614,"deviceId":0,"cacheMode":"WRITETHROUGH","hypervisorType":"VMware","directDownload":false,"deployAsIs":false,"followRedirects":false}},"executeInSequence":true,"options":{},"options2":{},"wait":0,"bypassHostMaintenance":false}].
2025-06-20 02:48:16,475 DEBUG [c.c.s.r.StorageSubsystemCommandHandlerBase]
(DirectAgent-22:ctx-ea084317 192.168.80.81, job-2149/job-2151, cmd:
CopyCommand) (logid:9556019e) Executing command CopyCommand:
[{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"5ac6b8c4a7af3e3fbf2fb321fffd9dd7","uuid":"a1905274-90d1-4c9e-bbf1-6067fc93ffda","id":221,"format":"OVA","accountId":4,"hvm":true,"displayText":"This
is the template for Windows Server 2022 with custom (unconstrained)
offering.","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"c651d575-bfbc-401e-bb0c-b23963a38df9","name":"ds-cloudstack","id":4,"poolType":"VMFS","host":"VMFS
datastore: datastore-16","path":"datastore-16","port":0,"url":"VMFS://VMFS
datastore:
datastore-16/datastore-16/?ROLE=Primary&STOREUUID=c651d575-bfbc-401e-bb0c-b23963a38df9","isManaged":false}},"name":"221-4-cdb53111-8212-38a5-91ec-2bece31fe4b9","size":37580963840,"hypervisorType":"VMware","bootable":false,"un
iqueName":"221-4-cdb53111-8212-38a5-91ec-2bece31fe4b9","directDownload":false,"deployAsIs":false,"followRedirects":false}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"3fe7354e-5cad-4616-a1a2-996ff4486e2e","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"c651d575-bfbc-401e-bb0c-b23963a38df9","name":"ds-cloudstack","id":4,"poolType":"VMFS","host":"VMFS
datastore: datastore-16","path":"datastore-16","port":0,"url":"VMFS://VMFS
datastore:
datastore-16/datastore-16/?ROLE=Primary&STOREUUID=c651d575-bfbc-401e-bb0c-b23963a38df9","fullCloneFlag":true,"diskProvisioningStrictnessFlag":false,"isManaged":false}},"name":"ROOT-619","size":37580963840,"volumeId":614,"vmName":"i-4-619-VM","accountId":4,"format":"OVA","provisioningType":"FAT","poolId":4,"id":614,"deviceId":0,"cacheMode":"WRITETHROUGH","hypervisorType":"VMware","directDownload":false,"deployAsIs":false,"followRedirects":false}},"executeInSequence":true,"options":{
},"options2":{},"wait":0,"bypassHostMaintenance":false}].
2025-06-20 02:48:16,608 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-30:ctx-6fdefcee job-2149/job-2151 ctx-ff58a858)
(logid:9556019e) Cleaning up resources for the vm VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
in Starting state
2025-06-20 02:48:16,624 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-30:ctx-6fdefcee job-2149/job-2151 ctx-ff58a858)
(logid:9556019e) VM instance
{"id":619,"instanceName":"i-4-619-VM","type":"User","uuid":"cd1d3dd3-8474-4b1c-80b3-a2d9d34e3367"}
is a [User], returning null for control Nic IP.
2025-06-20 02:48:16,629 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-30:ctx-6fdefcee job-2149/job-2151 ctx-ff58a858)
(logid:9556019e) Seq 2-327073922937792466: Sending { Cmd , MgmtId:
345049157785, via: 2(192.168.80.81), Ver: v1, Flags: 100111,
[{"com.cloud.agent.api.StopCommand":{"isProxy":"false","checkBeforeCleanup":"false","forceStop":"false","vlanToPersistenceMap":{"700":"true"},"volumesToDisconnect":[],"vmName":"i-4-619-VM","executeInSequence":"true","wait":"0","bypassHostMaintenance":"false"}}]
}
2025-06-20 02:48:16,630 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-30:ctx-6fdefcee job-2149/job-2151 ctx-ff58a858)
(logid:9556019e) Seq 2-327073922937792466: Executing: { Cmd , MgmtId:
345049157785, via: 2(192.168.80.81), Ver: v1, Flags: 100111,
[{"com.cloud.agent.api.StopCommand":{"isProxy":"false","checkBeforeCleanup":"false","forceStop":"false","vlanToPersistenceMap":{"700":"true"},"volumesToDisconnect":[],"vmName":"i-4-619-VM","executeInSequence":"true","wait":"0","bypassHostMaintenance":"false"}}]
}
2025-06-20 02:48:16,630 DEBUG [c.c.h.v.r.VmwareResource]
(DirectAgent-347:ctx-e73f7263) (logid:9556019e) Executing resource command
StopCommand:
[{"isProxy":false,"checkBeforeCleanup":false,"forceStop":false,"vlanToPersistenceMap":{"700":true},"volumesToDisconnect":[],"vmName":"i-4-619-VM","executeInSequence":true,"wait":0,"bypassHostMaintenance":false}].
2025-06-20 02:48:16,641 DEBUG [c.c.h.v.m.HostMO]
(DirectAgent-347:ctx-e73f7263 192.168.80.81, job-2149/job-2151, cmd:
StopCommand) (logid:9556019e) find VM i-4-619-VM on host
:
--
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]
