GitHub user sptest232 created a discussion: Failed to create VPC on KVM ACS 4.22
I have a zone with two clusters: XCP-NG and KVM.
Everything works fine with cluster xcp-ng, but an error appears when trying to
create a VPC in the KVM cluster. However, the Isolated network is created
without problems, even though it uses the same service offering as the other
one.
Judging by the logs, the ACS is trying to create a VPC VR for KVM from a
template for XEN.
Allocating the VR with id=25 in datacenter Zone {"id": "1", "name": "lab-zone1"
, "uuid": "c585cadf-a5e4-48e5-9e9a-6ef29dd103b1"} with the hypervisor type
XenServer
service_offering:
id: 18
cpu: 1
speed: 500
ram_size: 512
nw_rate: NULL
mc_rate: NULL
ha_enabled: 0
limit_cpu_use: 0
host_tag: kvm
default_use: 0
vm_type: domainrouter
sort_key: 0
is_volatile: 0
deployment_planner: NULL
dynamic_scaling_enabled: 1
uuid: 662d8d26-5299-431e-88f4-1a9cd89c0256
name: KVM-Service Offering-VR
display_text: KVM-Service Offering-VR
unique_name: NULL
customized: 0
created: 2025-12-21 17:05:48
removed: NULL
state: Active
disk_offering_id: 24
system_use: 1
disk_offering_strictness: 0
vgpu_profile_id: NULL
gpu_count: NULL
gpu_display: 0
Network_offering:
id: 31
name: KVM-Network Offering
uuid: 72bca966-ca0a-43f4-af6f-fce0fa83a115
unique_name: KVM-Network Offering
display_text: KVM-Network Offering
nw_rate: NULL
mc_rate: 10
traffic_type: Guest
tags: NULL
system_only: 0
specify_vlan: 0
service_offering_id: 18
conserve_mode: 1
created: 2025-12-21 17:08:19
removed: NULL
default: 0
availability: Optional
dedicated_lb_service: 1
shared_source_nat_service: 0
sort_key: 0
redundant_router_service: 0
state: Enabled
guest_type: Isolated
elastic_ip_service: 0
eip_associate_public_ip: 0
elastic_lb_service: 0
specify_ip_ranges: 0
inline: 0
is_persistent: 0
internal_lb: 0
public_lb: 1
egress_default_policy: 0
concurrent_connections: 4096
keep_alive_enabled: 0
supports_streched_l2: 0
supports_public_access: 0
for_vpc: 0
service_package_id: NULL
supports_vm_autoscaling: 1
network_mode: NULL
routing_mode: NULL
specify_as_number: 0
vpc_offernig:
id: 12
uuid: 6d3e8914-b01f-409c-bd33-32ac7cc4593c
unique_name: kvm-VPC Offering
name: kvm-VPC Offering
display_text: kvm-VPC Offering
state: Enabled
default: 0
removed: NULL
created: 2025-12-21 17:16:50
service_offering_id: 18
supports_distributed_router: 0
supports_region_level_vpc: 0
redundant_router_service: 0
sort_key: 0
network_mode: NULL
routing_mode: NULL
specify_as_number: 0
===== ACS Log
2025-12-21 20:17:42,728 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl$5]
(API-Job-Executor-47:[ctx-ef119e43, job-253]) (logid:7fa07f5c) Executing
AsyncJob {"accountId":2,"cmd":"org.apache.cloudstack.api.command.admin.vpc.
CreateVPCCmdByAdmin","cmdInfo":"{\"sessionkey\":\"dX77w4XCeErZuFoIMVv-AgNxHnA\",\"start\":\"true\",\"httpmethod\":\"POST\",\"ctxAccountId\":\"2\",\"uuid\":\"c934cc4c-73e9-4061-b0f4-cf120fbc3d72\",\"domainid\":\"c0a
09325-c571-11f0-8379-0050569f62ba\",\"cmdEventType\":\"VPC.CREATE\",\"vpcofferingid\":\"6d3e8914-b01f-409c-bd33-32ac7cc4593c\",\"displaytext\":\"kvm-vpc-network\",\"response\":\"json\",\"ctxUserId\":\"2\",\"name\":
\"kvm-vpc-network\",\"zoneid\":\"c585cadf-a5e4-48e5-9e9a-6ef29dd103b1\",\"cidr\":\"192.168.0.0\/16\",\"ctxStartEventId\":\"6959\",\"id\":\"6\",\"ctxDetails\":\"{\\\"interface
com.cloud.dc.DataCenter\\\":\\\"c585cad
f-a5e4-48e5-9e9a-6ef29dd103b1\\\",\\\"interface
com.cloud.domain.Domain\\\":\\\"c0a09325-c571-11f0-8379-0050569f62ba\\\",\\\"interface
com.cloud.network.vpc.VpcOffering\\\":\\\"6d3e8914-b01f-409c-bd33-32ac7cc4593c\
\\",\\\"interface
com.cloud.network.vpc.Vpc\\\":\\\"c934cc4c-73e9-4061-b0f4-cf120fbc3d72\\\"}\",\"account\":\"admin\"}","cmdVersion":0,"completeMsid":null,"created":null,"id":253,"initMsid":345050669754,"instanceId
":6,"instanceType":"None","lastPolled":null,"lastUpdated":null,"processStatus":0,"removed":null,"result":null,"resultCode":0,"status":"IN_PROGRESS","userId":2,"uuid":"7fa07f5c-48d2-4086-9ad1-a7b28637abab"}
2025-12-21 20:17:42,768 DEBUG [c.c.n.IpAddressManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
lock account Account [{"accountName":"admin","id":2,"uuid":"0338ecf0-c572-11f0
-8379-0050569f62ba"}] is acquired
2025-12-21 20:17:42,778 DEBUG [c.c.n.IpAddressManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Public IP address [IPAddress {"address":"10.10.10.133","dataCenterId":1,"id":4
,"uuid":"45cd4c34-4797-4e42-972a-54f78093fdb3"}] is not in quarantine;
therefore, it is allowed to be allocated.
2025-12-21 20:17:42,778 DEBUG [c.c.n.IpAddressManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
trying ip address 10.10.10.133
2025-12-21 20:17:42,782 DEBUG [c.c.n.IpAddressManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
locked row for ip address 10.10.10.133 (id: 45cd4c34-4797-4e42-972a-54f78093fd
b3)
2025-12-21 20:17:42,783 INFO [c.c.n.IpAddressManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
successfully allocated ip address 10.10.10.133
2025-12-21 20:17:42,791 DEBUG [c.c.n.IpAddressManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c) IP
address [IPAddress {"address":"10.10.10.133","dataCenterId":1,"id":4,"uuid"
:"45cd4c34-4797-4e42-972a-54f78093fdb3"}] is not direct, dedicated or reserved;
therefore, the resource count should be updated.
2025-12-21 20:17:42,794 DEBUG [c.c.r.ResourceLimitManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Updating resource Type = public_ip count for Account with id = 2 Operation
= increasing Amount = 1
2025-12-21 20:17:42,813 DEBUG [c.c.n.IpAddressManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Releasing lock account Account [{"accountName":"admin","id":2,"uuid":"0338ecf0
-c572-11f0-8379-0050569f62ba"}]
2025-12-21 20:17:42,828 DEBUG [c.c.c.CapacityManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Host {"id":4,"name":"s23.lab.local","type":"Routing","uuid":"d235f71e-e391-45da
-b0d2-6dcef42e3ff7"} has cpu capability (cpu: 24, speed: 2600 ) to support
requested CPU: 1 and requested speed: 500
2025-12-21 20:17:42,828 DEBUG [c.c.c.CapacityManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Checking if host: Host {"id":4,"name":"s23.lab.local","type":"Routing","uuid":"
d235f71e-e391-45da-b0d2-6dcef42e3ff7"} has enough capacity for requested CPU:
500 and requested RAM: (512.00 MB) 536870912 , cpuOverprovisioningFactor: 1.0
2025-12-21 20:17:42,829 DEBUG [c.c.c.CapacityManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Hosts's actual total CPU: 62400 and CPU after applying overprovisioning: 62400
2025-12-21 20:17:42,829 DEBUG [c.c.c.CapacityManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
considerReservedCapacity isfalse , not considering reserved capacity for calcul
ating free capacity
2025-12-21 20:17:42,829 DEBUG [c.c.c.CapacityManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Free CPU: 62400 , Requested CPU: 500
2025-12-21 20:17:42,830 DEBUG [c.c.c.CapacityManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Free RAM: (116.68 GB) 125283090432 , Requested RAM: (512.00 MB) 536870912
2025-12-21 20:17:42,830 DEBUG [c.c.c.CapacityManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Host has enough CPU and RAM available
2025-12-21 20:17:42,830 DEBUG [c.c.c.CapacityManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
STATS: Can alloc CPU from host: Host {"id":4,"name":"s23.lab.local","type":"Rou
ting","uuid":"d235f71e-e391-45da-b0d2-6dcef42e3ff7"}, used: 0, reserved: 0,
actual total: 62400, total with overprovisioning: 62400; requested cpu: 500,
alloc_from_last_host?: false, considerReservedCapacity?: fals
e
2025-12-21 20:17:42,830 DEBUG [c.c.c.CapacityManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
STATS: Can alloc MEM from host: Host {"id":4,"name":"s23.lab.local","type":"Rou
ting","uuid":"d235f71e-e391-45da-b0d2-6dcef42e3ff7"}, used: (0 bytes) 0,
reserved: (0 bytes) 0, total: (116.68 GB) 125283090432; requested mem: (512.00
MB) 536870912, alloc_from_last_host?: false, considerReservedC
apacity?: false
2025-12-21 20:17:42,830 DEBUG [c.c.n.r.NetworkHelperImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Host Host {"id":4,"name":"s23.lab.local","type":"Routing","uuid":"d235f71e-e391
-45da-b0d2-6dcef42e3ff7"} has enough capacity for the router
2025-12-21 20:17:42,835 DEBUG [c.c.n.r.NetworkHelperImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Allocating the VR with id=25 in datacenter Zone {"id": "1", "name": "lab-zone1"
, "uuid": "c585cadf-a5e4-48e5-9e9a-6ef29dd103b1"} with the hypervisor type
XenServer
2025-12-21 20:17:42,851 DEBUG [c.c.n.r.NetworkHelperImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Adding nic for Virtual Router in Control network
2025-12-21 20:17:42,857 DEBUG [o.a.c.e.o.NetworkOrchestrator]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Found existing network configuration for offering NetworkOffering {"id":3,"
name":"System-Control-Network","trafficType":"Control","uuid":"acd79457-ab58-48cc-af31-6ff9a9c6e585"}:
Network {"id": 202, "name": "null", "uuid":
"f644dc61-e475-4428-9a09-9d24b258e097", "networkofferingid": 3}
2025-12-21 20:17:42,857 DEBUG [o.a.c.e.o.NetworkOrchestrator]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Releasing lock for Account [{"accountName":"system","id":1,"uuid":"0338614a
-c572-11f0-8379-0050569f62ba"}]
2025-12-21 20:17:42,872 DEBUG [c.c.n.r.NetworkHelperImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Adding nic for Virtual Router in Public network
2025-12-21 20:17:42,879 DEBUG [o.a.c.e.o.NetworkOrchestrator]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Found existing network configuration for offering NetworkOffering {"id":1,"
name":"System-Public-Network","trafficType":"Public","uuid":"b0b0f3fa-7366-4b47-bd94-aefecea4e15e"}:
Network {"id": 200, "name": "null", "uuid":
"8e40a790-5bee-4d0c-b56c-066b8944b1b9", "networkofferingid": 1}
2025-12-21 20:17:42,880 DEBUG [o.a.c.e.o.NetworkOrchestrator]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Releasing lock for Account [{"accountName":"system","id":1,"uuid":"0338614a
-c572-11f0-8379-0050569f62ba"}]
2025-12-21 20:17:42,893 INFO [c.c.v.ClusteredVirtualMachineManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
allocating virtual machine from template: Template {"format":"VH
D","id":204,"name":"systemvm-xenserver-4.22.0-x86_64","uniqueName":"204-2-c18d3983-2a26-38e1-951e-cfeae0e19db2","uuid":"8e3fc9f3-9ac1-44fd-9fc7-811c470943f5"}
with hostname: r-25-VM and 2 networks
2025-12-21 20:17:42,896 DEBUG [c.c.v.ClusteredVirtualMachineManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Allocating entries for VM: VM instance
{"id":25,"instanceName":"r-25-VM","state":"Stopped","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}
2025-12-21 20:17:42,903 DEBUG [c.c.v.ClusteredVirtualMachineManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Allocating nics for VM instance
{"id":25,"instanceName":"r-25-VM","state":"Stopped","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}
2025-12-21 20:17:42,906 DEBUG [o.a.c.e.o.NetworkOrchestrator]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Allocating nic for vm VM instance
{"id":25,"instanceName":"r-25-VM","state":"Stopped","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}
in network Network {"id": 202, "name": "null", "uuid":
"f644dc61-e475-4428-9a09-9d24b258e097", "networkofferingid": 3} with requested
profile null
2025-12-21 20:17:42,922 DEBUG [o.a.c.e.o.NetworkOrchestrator]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Allocating nic for vm VM instance
{"id":25,"instanceName":"r-25-VM","state":"Stopped","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}
in network Network {"id": 200, "name": "null", "uuid":
"8e40a790-5bee-4d0c-b56c-066b8944b1b9", "networkofferingid": 1} with requested
profile NicProfile
{"broadcastUri":"vlan:\/\/905","deviceId":null,"iPv4Address":"10.10.10.133","id":0,"reservationId":null,"uuid":null,"vmId":0}
2025-12-21 20:17:42,951 DEBUG [c.c.v.ClusteredVirtualMachineManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Allocating disks for VM instance
{"id":25,"instanceName":"r-25-VM","state":"Stopped","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}
2025-12-21 20:17:42,952 INFO [o.a.c.e.o.VolumeOrchestrator]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7, ctx-e520989e])
(logid:7fa07f5c) Adding disk object [ROOT-25] to VM [VM instance
{"id":25,"instanceName":"r-25-VM","state":"Stopped","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}]
2025-12-21 20:17:42,975 DEBUG [c.c.v.ClusteredVirtualMachineManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Allocation completed for VM: VM instance
{"id":25,"instanceName":"r-25-VM","state":"Stopped","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}
2025-12-21 20:17:42,981 DEBUG [o.a.c.n.r.d.VpcRouterDeploymentDefinition]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Lock is released for vpc [id: 6] (VPC
{"id":6,"name":"kvm-vpc-network","uuid":"c934cc4c-73e9-4061-b0f4-cf120fbc3d72"})
as a part of router startup in
Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
: Dest[Zone(1)-Pod(null)-Cluster(null)-Host(null)-Storage()]
2025-12-21 20:17:42,981 DEBUG [c.c.n.r.NetworkHelperImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Starting router VM instance
{"id":25,"instanceName":"r-25-VM","state":"Stopped","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}
2025-12-21 20:17:42,981 DEBUG [c.c.v.ClusteredVirtualMachineManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
start parameter value of enterHardwareSetup == null during processing of queued
job
2025-12-21 20:17:42,990 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Sync job-254 execution on object VmWorkJobQueue.25
2025-12-21 20:17:43,154 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl$5]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254]) (logid:7fa07f5c)
Executing AsyncJob
{"accountId":2,"cmd":"com.cloud.vm.VmWorkStart","cmdInfo":"rO0ABXNyABhjb20uY2xvdWQudm0uVm1Xb3JrU3RhcnR9cMGsvxz73gIAC0oABGRjSWRMAAZhdm9pZHN0ADBMY29tL2Nsb3VkL2RlcGxveS9EZXBsb3ltZW50UGxhbm5lciRFeGNsdWRlTGlzdDtMAAljbHVzdGVySWR0ABBMamF2YS9sYW5nL0xvbmc7TAAGaG9zdElkcQB-AAJMAAtqb3VybmFsTmFtZXQAEkxqYXZhL2xhbmcvU3RyaW5nO0wAEXBoeXNpY2FsTmV0d29ya0lkcQB-AAJMAAdwbGFubmVycQB-AANMAAVwb2RJZHEAfgACTAAGcG9vbElkcQB-AAJMAAlyYXdQYXJhbXN0AA9MamF2YS91dGlsL01hcDtMAA1yZXNlcnZhdGlvbklkcQB-AAN4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3VudElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1lcQB-AAN4cAAAAAAAAAACAAAAAAAAAAIAAAAAAAAAGXQAGVZpcnR1YWxNYWNoaW5lTWFuYWdlckltcGwAAAAAAAAAAHBwcHBwcHBwc3IAEWphdmEudXRpbC5IYXNoTWFwBQfawcMWYNEDAAJGAApsb2FkRmFjdG9ySQAJdGhyZXNob2xkeHA_QAAAAAAADHcIAAAAEAAAAAF0AA5SZXN0YXJ0TmV0d29ya3QAP3JPMEFCWE55QUJGcVlYWmhMbXhoYm1jdVFtOXZiR1
ZoYnMwZ2NvRFZuUHJ1QWdBQldnQUZkbUZzZFdWNGNBRXhw","cmdVersion":0,"completeMsid":null,"created":"Sun
Dec 21 20:17:42 MSK
2025","id":254,"initMsid":345050669754,"instanceId":null,"instanceType":null,"lastPolled":null,"lastUpdated":null,"processStatus":0,"removed":null,"result":null,"resultCode":0,"status":"IN_PROGRESS","userId":2,"uuid":"4b02ea57-e473-4f94-8dcb-ad9f9f25d1bd"}
2025-12-21 20:17:43,154 DEBUG [c.c.v.VmWorkJobDispatcher]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254]) (logid:7fa07f5c) Run VM
work job: com.cloud.vm.VmWorkStart for VM 25, job origin: 253
2025-12-21 20:17:43,157 DEBUG [c.c.v.VmWorkJobHandlerProxy]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Execute VM work job:
com.cloud.vm.VmWorkStart{"accountId":2,"dcId":0,"vmId":25,"handlerName":"VirtualMachineManagerImpl","userId":2,"rawParams":{"RestartNetwork":"rO0ABXNyABFqYXZhLmxhbmcuQm9vbGVhbs0gcoDVnPruAgABWgAFdmFsdWV4cAE"}}
2025-12-21 20:17:43,158 DEBUG [c.c.v.ClusteredVirtualMachineManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) orchestrating VM start for 'r-25-VM'
com.cloud.vm.VirtualMachineProfile$Param@b66cdd7d set to null
2025-12-21 20:17:43,158 DEBUG [c.c.v.ClusteredVirtualMachineManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Trying to start VM ["52e61cd2-5ed5-4a7f-afc5-717aea77178e"]
using plan [null] and planner [null].
2025-12-21 20:17:43,167 DEBUG [c.c.c.CapacityManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) VM instance
{"id":25,"instanceName":"r-25-VM","state":"Starting","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}
state transited from [Stopped] to [Starting] with event [StartRequested]. VM's
original host: null, new host: null, host before state transition: null
2025-12-21 20:17:43,167 DEBUG [c.c.v.ClusteredVirtualMachineManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Successfully transitioned to start state for VM instance
{"id":25,"instanceName":"r-25-VM","state":"Starting","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}
reservation id = 7d84b906-05fa-41e0-9733-c484ccfda615
2025-12-21 20:17:43,170 DEBUG [c.c.v.ClusteredVirtualMachineManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Deploy avoids pods: null, clusters: null, hosts: null
2025-12-21 20:17:43,174 DEBUG [c.c.v.ClusteredVirtualMachineManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) VM start attempt #1
2025-12-21 20:17:43,179 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Trying to deploy VM [error decoding VM instance
{"id":25,"instanceName":"r-25-VM","state":"Starting","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}]
and details: Plan [error decoding
{"_clusterId":null,"_dcId":1,"_physicalNetworkId":null,"_podId":null,"_poolId":null,"migrationPlan":false}];
avoid list [{}] and planner: [null].
2025-12-21 20:17:43,180 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Checking non dedicated resources to deploy VM [VM instance
{"id":25,"instanceName":"r-25-VM","state":"Starting","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}].
2025-12-21 20:17:43,190 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Adding pods [[]], clusters [[]] and hosts [[]] to the avoid
list in the deploy process of VR VM [error decoding VM instance
{"id":25,"instanceName":"r-25-VM","state":"Starting","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}],
because this VM is not ded
icated to this components.
2025-12-21 20:17:43,190 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Trying to allocate a host and storage pools from datacenter
[Zone {"id": "1", "name": "lab-zone1", "uuid":
"c585cadf-a5e4-48e5-9e9a-6ef29dd103b1"}], pod [null], cluster [null], to deploy
VM [VM instance
{"id":25,"instanceName":"r-25-VM","state":"Starting","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}]
with requested CPU [500] and requested RAM [(512.00 MB) 536870912].
2025-12-21 20:17:43,191 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) ROOT volume [Volume
{"id":28,"instanceId":25,"name":"ROOT-25","uuid":"3b5d35b9-bb25-496c-b963-e9e73cb671bf","volumeType":"ROOT"}]
is not ready to deploy VM [VM instance
{"id":25,"instanceName":"r-25-VM","state":"Starting","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}].
2025-12-21 20:17:43,193 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Adding pods [] to the avoid set because these pods are in the
Disabled state.
2025-12-21 20:17:43,194 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Adding clusters [] of pod [1] to the void set because these
clusters are in the Disabled state.
2025-12-21 20:17:43,195 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Adding clusters [] of pod [2] to the void set because these
clusters are in the Disabled state.
2025-12-21 20:17:43,196 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Adding hosts [] of datacenter
[c585cadf-a5e4-48e5-9e9a-6ef29dd103b1] to the avoid set, because these hosts
are in the Disabled state.
2025-12-21 20:17:43,197 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) DeploymentPlan [DataCenterDeployment] has not specified host.
Trying to find another destination to deploy VM [VM instance
{"id":25,"instanceName":"r-25-VM","state":"Starting","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}],
avoiding pods [], clusters [] and hosts [].
2025-12-21 20:17:43,197 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Deploy avoids pods: [], clusters: [], hosts: [].
2025-12-21 20:17:43,197 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Deploy hosts with priorities {}, hosts have NORMAL priority by
default
2025-12-21 20:17:43,198 DEBUG [c.c.d.FirstFitPlanner]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Searching all possible resources under this Zone: Zone {"id":
"1", "name": "lab-zone1", "uuid": "c585cadf-a5e4-48e5-9e9a-6ef29dd103b1"}
2025-12-21 20:17:43,199 DEBUG [c.c.d.FirstFitPlanner]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Listing clusters in order of aggregate capacity, that have (at
least one host with) enough CPU and RAM capacity under this Zone: 1
2025-12-21 20:17:43,201 DEBUG [c.c.d.FirstFitPlanner]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) CapacityType: CPU is used for Cluster ordering
2025-12-21 20:17:43,202 DEBUG [c.c.d.FirstFitPlanner]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Removing from the clusterId list these clusters from avoid
set: []
2025-12-21 20:17:43,207 DEBUG [c.c.d.FirstFitPlanner]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) The clusterId list for the given offering tag: [2]
2025-12-21 20:17:43,211 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Adding cluster [Cluster {id: "2", name: "lab-kvm-cluster1",
uuid: "2fe50657-ebce-45d6-a8e2-9b56146b885e"}] to the avoid set because the
cluster's hypervisor [KVM] does not match the VM [VM instance
{"id":25,"instanceName":"r-25-VM","state":"Starting","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}]
hypervisor: [XenServer]. Skipping this cluster.
2025-12-21 20:17:43,211 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Could not find suitable Deployment Destination for this VM
under any clusters, returning.
2025-12-21 20:17:43,212 DEBUG [c.c.d.FirstFitPlanner]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Searching all possible resources under this Zone: Zone {"id":
"1", "name": "lab-zone1", "uuid": "c585cadf-a5e4-48e5-9e9a-6ef29dd103b1"}
2025-12-21 20:17:43,213 DEBUG [c.c.d.FirstFitPlanner]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Listing clusters in order of aggregate capacity, that have (at
least one host with) enough CPU and RAM capacity under this Zone: 1
2025-12-21 20:17:43,214 DEBUG [c.c.d.FirstFitPlanner]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) CapacityType: CPU is used for Cluster ordering
2025-12-21 20:17:43,215 DEBUG [c.c.d.FirstFitPlanner]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Removing from the clusterId list these clusters from avoid
set: [2]
2025-12-21 20:17:43,218 DEBUG [c.c.d.FirstFitPlanner]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) The clusterId list for the given offering tag: []
2025-12-21 20:17:43,218 DEBUG [c.c.d.FirstFitPlanner]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) No clusters found after removing disabled clusters and
clusters in avoid list, returning.
2025-12-21 20:17:43,232 DEBUG [c.c.c.CapacityManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) VM instance
{"id":25,"instanceName":"r-25-VM","state":"Stopped","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}
state transited from [Starting] to [Stopped] with event [OperationFailed].
VM's original host: null, new host: null, host before state transition: null
2025-12-21 20:17:43,232 ERROR [c.c.v.VmWorkJobHandlerProxy]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Invocation exception, caused by:
com.cloud.exception.InsufficientServerCapacityException: Unable to create a
deployment for VM instance
{"id":25,"instanceName":"r-25-VM","state":"Starting","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}Scope=interface
com.cloud.dc.DataCenter; id=1
2025-12-21 20:17:43,232 INFO [c.c.v.VmWorkJobHandlerProxy]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254, ctx-804b7e90])
(logid:7fa07f5c) Rethrow exception
com.cloud.exception.InsufficientServerCapacityException: Unable to create a
deployment for VM instance
{"id":25,"instanceName":"r-25-VM","state":"Starting","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}Scope=interface
com.cloud.dc.DataCenter; id=1
2025-12-21 20:17:43,232 DEBUG [c.c.v.VmWorkJobDispatcher]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254]) (logid:7fa07f5c) Done
with run of VM work job: com.cloud.vm.VmWorkStart for VM 25, job origin: 253
2025-12-21 20:17:43,232 ERROR [c.c.v.VmWorkJobDispatcher]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254]) (logid:7fa07f5c) Unable
to complete AsyncJob {"accountId":2,"cmd":"com.cloud.vm.VmWorkStart","cmdInfo
":"rO0ABXNyABhjb20uY2xvdWQudm0uVm1Xb3JrU3RhcnR9cMGsvxz73gIAC0oABGRjSWRMAAZhdm9pZHN0ADBMY29tL2Nsb3VkL2RlcGxveS9EZXBsb3ltZW50UGxhbm5lciRFeGNsdWRlTGlzdDtMAAljbHVzdGVySWR0ABBMamF2YS9sYW5nL0xvbmc7TAAGaG9zdElkcQB-AAJMAAtqb3VybmFsTmFtZXQAEkxqYXZhL2xhbmcvU3RyaW5nO0wAEXBoeXNpY2FsTmV0d29ya0lkcQB-AAJMAAdwbGFubmVycQB-AANMAAVwb2RJZHEAfgACTAAGcG9vbElkcQB-AAJMAAlyYXdQYXJhbXN0AA9MamF2YS91dGlsL01hcDtMAA1yZXNlcnZhdGlvbklkcQB-AAN4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3VudElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1lcQB-AAN4cAAAAAAAAAACAAAAAAAAAAIAAAAAAAAAGXQAGVZpcnR1YWxNYWNoaW5lTWFuYWdlckltcGwAAAAAAAAAAHBwcHBwcHBwc3IAEWphdmEudXRpbC5IYXNoTWFwBQfawcMWYNEDAAJGAApsb2FkRmFjdG9ySQAJdGhyZXNob2xkeHA_QAAAAAAADHcIAAAAEAAAAAF0AA5SZXN0YXJ0TmV0d29ya3QAP3JPMEFCWE55QUJGcVlYWmhMbXhoYm1jdVFtOXZiR1ZoYnMwZ2NvRFZuUHJ1QWdBQldnQUZkbUZzZFdWNGNBRXhw","cmdVersion":0,"completeMsid":null,"created":"Sun
Dec 21 20:17:42 MSK
2025","id":254,"initMsid":345050669754,"instanceId":null,"instanceType":null,"lastPolled":null,
"lastUpdated":null,"processStatus":0,"removed":null,"result":null,"resultCode":0,"status":"IN_PROGRESS","userId":2,"uuid":"4b02ea57-e473-4f94-8dcb-ad9f9f25d1bd"},
job origin: 253 com.cloud.exception.InsufficientServerCapacityException:
Unable to create a deployment for VM instance
{"id":25,"instanceName":"r-25-VM","state":"Starting","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}Scope=interface
com.cloud.dc.DataCenter; id=1
2025-12-21 20:17:43,234 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254]) (logid:7fa07f5c)
Complete async job-254, jobStatus: FAILED, resultCode: 0, result:
rO0ABXNyADdjb20uY2xvdWQuZXhjZXB0aW9uLkluc3VmZmljaWVudFNlcnZlckNhcGFjaXR5RXhjZXB0aW9uVk1PcAAAABACAAFaABVhZmZpbml0eUdyb3Vwc0FwcGxpZWR4cgAxY29tLmNsb3VkLmV4Y2VwdGlvbi5JbnN1ZmZpY2llbnRDYXBhY2l0eUV4Y2VwdGlvblZNT3AAAAAVAgACTAACaWR0ABBMamF2YS9sYW5nL0xvbmc7TAAFc2NvcGV0ABFMamF2YS9sYW5nL0NsYXNzO3hyACJjb20uY2xvdWQuZXhjZXB0aW9uLkNsb3VkRXhjZXB0aW9ueeiOG_HkRMcCAAJMAAtjc0Vycm9yQ29kZXQAE0xqYXZhL2xhbmcvSW50ZWdlcjtMAAZpZExpc3R0ABVMamF2YS91dGlsL0FycmF5TGlzdDt4cgATamF2YS5sYW5nLkV4Y2VwdGlvbtD9Hz4aOxzEAgAAeHIAE2phdmEubGFuZy5UaHJvd2FibGXVxjUnOXe4ywMABEwABWNhdXNldAAVTGphdmEvbGFuZy9UaHJvd2FibGU7TAANZGV0YWlsTWVzc2FnZXQAEkxqYXZhL2xhbmcvU3RyaW5nO1sACnN0YWNrVHJhY2V0AB5bTGphdmEvbGFuZy9TdGFja1RyYWNlRWxlbWVudDtMABRzdXBwcmVzc2VkRXhjZXB0aW9uc3QAEExqYXZhL3V0aWwvTGlzdDt4cHEAfgANdACnVW5hYmxlIHRvIGNyZWF0ZSBhIGRlcGxveW1lbnQg
Zm9yIFZNIGluc3RhbmNlIHsiaWQiOjI1LCJpbnN0YW5jZU5hbWUiOiJyLTI1LVZNIiwic3RhdGUiOiJTdGFydGluZyIsInR5cGUiOiJEb21haW5Sb3V0ZXIiLCJ1dWlkIjoiNTJlNjFjZDItNWVkNS00YTdmLWFmYzUtNzE3YWVhNzcxNzhlIn11cgAeW0xqYXZhLmxhbmcuU3RhY2tUcmFjZUVsZW1lbnQ7AkYqPDz9IjkCAAB4cAAAABRzcgAbamF2YS5sYW5nLlN0YWNrVHJhY2VFbGVtZW50YQnFmiY23YUCAAhCAAZmb3JtYXRJAApsaW5lTnVtYmVyTAAPY2xhc3NMb2FkZXJOYW1lcQB-AApMAA5kZWNsYXJpbmdDbGFzc3EAfgAKTAAIZmlsZU5hbWVxAH4ACkwACm1ldGhvZE5hbWVxAH4ACkwACm1vZHVsZU5hbWVxAH4ACkwADW1vZHVsZVZlcnNpb25xAH4ACnhwAQAABa50AANhcHB0ACZjb20uY2xvdWQudm0uVmlydHVhbE1hY2hpbmVNYW5hZ2VySW1wbHQAHlZpcnR1YWxNYWNoaW5lTWFuYWdlckltcGwuamF2YXQAEG9yY2hlc3RyYXRlU3RhcnRwcHNxAH4AEQEAABc_cQB-ABNxAH4AFHEAfgAVcQB-ABZwcHNxAH4AEQD_____cHQAL2pkay5pbnRlcm5hbC5yZWZsZWN0LkdlbmVyYXRlZE1ldGhvZEFjY2Vzc29yNTY1cHQABmludm9rZXBwc3EAfgARAgAAACtwdAAxamRrLmludGVybmFsLnJlZmxlY3QuRGVsZWdhdGluZ01ldGhvZEFjY2Vzc29ySW1wbHQAIURlbGVnYXRpbmdNZXRob2RBY2Nlc3NvckltcGwuamF2YXEAfgAadAAJamF2YS5iYXNldAAHMTcuMC4xN3NxAH4AEQIAAAI5cHQAGGphdmEubGFuZy5yZWZsZWN0Lk1ld
GhvZHQAC01ldGhvZC5qYXZhcQB-ABpxAH4AHnEAfgAfc3EAfgARAQAAAGZxAH4AE3QAImNvbS5jbG91ZC52bS5WbVdvcmtKb2JIYW5kbGVyUHJveHl0ABpWbVdvcmtKb2JIYW5kbGVyUHJveHkuamF2YXQAD2hhbmRsZVZtV29ya0pvYnBwc3EAfgARAQAAF7txAH4AE3EAfgAUcQB-ABVxAH4AJnBwc3EAfgARAQAAAGNxAH4AE3QAIGNvbS5jbG91ZC52bS5WbVdvcmtKb2JEaXNwYXRjaGVydAAYVm1Xb3JrSm9iRGlzcGF0Y2hlci5qYXZhdAAGcnVuSm9icHBzcQB-ABEBAAACunEAfgATdAA_b3JnLmFwYWNoZS5jbG91ZHN0YWNrLmZyYW1ld29yay5qb2JzLmltcGwuQXN5bmNKb2JNYW5hZ2VySW1wbCQ1dAAYQXN5bmNKb2JNYW5hZ2VySW1wbC5qYXZhdAAMcnVuSW5Db250ZXh0cHBzcQB-ABEBAAAAMXEAfgATdAA-b3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5NYW5hZ2VkQ29udGV4dFJ1bm5hYmxlJDF0ABtNYW5hZ2VkQ29udGV4dFJ1bm5hYmxlLmphdmF0AANydW5wcHNxAH4AEQEAAAA4cQB-ABN0AEJvcmcuYXBhY2hlLmNsb3Vkc3RhY2subWFuYWdlZC5jb250ZXh0LmltcGwuRGVmYXVsdE1hbmFnZWRDb250ZXh0JDF0ABpEZWZhdWx0TWFuYWdlZENvbnRleHQuamF2YXQABGNhbGxwcHNxAH4AEQEAAABncQB-ABN0AEBvcmcuYXBhY2hlLmNsb3Vkc3RhY2subWFuYWdlZC5jb250ZXh0LmltcGwuRGVmYXVsdE1hbmFnZWRDb250ZXh0cQB-ADZ0AA9jYWxsV2l0aENvbnRleHRwcHNxAH4AEQEAAAA1cQB-AB
NxAH4AOXEAfgA2dAAOcnVuV2l0aENvbnRleHRwcHNxAH4AEQEAAAAucQB-ABN0ADxvcmcuYXBhY2hlLmNsb3Vkc3RhY2subWFuYWdlZC5jb250ZXh0Lk1hbmFnZWRDb250ZXh0UnVubmFibGVxAH4AMnEAfgAzcHBzcQB-ABEBAAAChnEAfgATcQB-AC1xAH4ALnEAfgAzcHBzcQB-ABECAAACG3B0AC5qYXZhLnV0aWwuY29uY3VycmVudC5FeGVjdXRvcnMkUnVubmFibGVBZGFwdGVydAAORXhlY3V0b3JzLmphdmFxAH4AN3EAfgAecQB-AB9zcQB-ABECAAABCHB0AB9qYXZhLnV0aWwuY29uY3VycmVudC5GdXR1cmVUYXNrdAAPRnV0dXJlVGFzay5qYXZhcQB-ADNxAH4AHnEAfgAfc3EAfgARAgAABHBwdAAnamF2YS51dGlsLmNvbmN1cnJlbnQuVGhyZWFkUG9vbEV4ZWN1dG9ydAAXVGhyZWFkUG9vbEV4ZWN1dG9yLmphdmF0AAlydW5Xb3JrZXJxAH4AHnEAfgAfc3EAfgARAgAAAntwdAAuamF2YS51dGlsLmNvbmN1cnJlbnQuVGhyZWFkUG9vbEV4ZWN1dG9yJFdvcmtlcnEAfgBIcQB-ADNxAH4AHnEAfgAfc3EAfgARAgAAA0hwdAAQamF2YS5sYW5nLlRocmVhZHQAC1RocmVhZC5qYXZhcQB-ADNxAH4AHnEAfgAfc3IAH2phdmEudXRpbC5Db2xsZWN0aW9ucyRFbXB0eUxpc3R6uBe0PKee3gIAAHhweHNyABFqYXZhLmxhbmcuSW50ZWdlchLioKT3gYc4AgABSQAFdmFsdWV4cgAQamF2YS5sYW5nLk51bWJlcoaslR0LlOCLAgAAeHAAABDvc3IAE2phdmEudXRpbC5BcnJheUxpc3R4gdIdmcdhnQMAAUkABHNpemV4cAAAAAB3BAAAAAB
4c3IADmphdmEubGFuZy5Mb25nO4vkkMyPI98CAAFKAAV2YWx1ZXhxAH4AUgAAAAAAAAABdnIAF2NvbS5jbG91ZC5kYy5EYXRhQ2VudGVyEFBUbQNFHgACAAB4cAA
2025-12-21 20:17:43,235 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254]) (logid:7fa07f5c) Publish
async job-254 complete on message bus
2025-12-21 20:17:43,235 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254]) (logid:7fa07f5c) Wake up
jobs related to job-254
2025-12-21 20:17:43,235 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254]) (logid:7fa07f5c) Update
db status for job-254
2025-12-21 20:17:43,236 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254]) (logid:7fa07f5c) Wake up
jobs joined with job-254 and disjoin all subjobs created from job- 254
2025-12-21 20:17:43,248 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl$5]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254]) (logid:7fa07f5c) Done
executing com.cloud.vm.VmWorkStart for job-254
2025-12-21 20:17:43,250 INFO [o.a.c.f.j.i.AsyncJobMonitor]
(Work-Job-Executor-72:[ctx-334eec0e, job-253/job-254]) (logid:7fa07f5c) Remove
job-254 from job monitoring
2025-12-21 20:17:43,256 WARN [c.c.n.v.VpcManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Failed to start vpc VPC
{"id":6,"name":"kvm-vpc-network","uuid":"c934cc4c-73e9-4061-b0f4-cf120fbc3d72"}
due to com.cloud.exception.InsufficientServerCapacityException: Unable to
create a deployment for VM instance
{"id":25,"instanceName":"r-25-VM","state":"Starting","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}Scope=interface
com.cloud.dc.DataCenter; id=1
2025-12-21 20:17:43,257 DEBUG [c.c.n.v.VpcManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Destroying vpc VPC
{"id":6,"name":"kvm-vpc-network","uuid":"c934cc4c-73e9-4061-b0f4-cf120fbc3d72"}
that failed to start
2025-12-21 20:17:43,257 DEBUG [c.c.n.v.VpcManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Destroying vpc VPC
{"id":6,"name":"kvm-vpc-network","uuid":"c934cc4c-73e9-4061-b0f4-cf120fbc3d72"}
2025-12-21 20:17:43,258 DEBUG [c.c.n.v.VpcManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Updating VPC VPC
{"id":6,"name":"kvm-vpc-network","uuid":"c934cc4c-73e9-4061-b0f4-cf120fbc3d72"}
with state Inactive as a part of vpc delete
2025-12-21 20:17:43,261 DEBUG [c.c.r.ResourceLimitManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Updating resource Type = vpc count for Account with id = 2 Operation =
decreasing Amount = 1
2025-12-21 20:17:43,268 DEBUG [c.c.n.v.VpcManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Shutting down vpc VPC
{"id":6,"name":"kvm-vpc-network","uuid":"c934cc4c-73e9-4061-b0f4-cf120fbc3d72"}
2025-12-21 20:17:43,270 DEBUG [c.c.n.r.NetworkHelperImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Attempting to destroy router VM instance
{"id":25,"instanceName":"r-25-VM","state":"Stopped","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}
with id 25
2025-12-21 20:17:43,277 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Sync job-255 execution on object VmWorkJobQueue.25
2025-12-21 20:17:45,146 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl$5]
(Work-Job-Executor-73:[ctx-ab629834, job-253/job-255]) (logid:7fa07f5c)
Executing AsyncJob
{"accountId":2,"cmd":"com.cloud.vm.VmWorkStop","cmdInfo":"rO0ABXNyABdjb20uY2xvdWQudm0uVm1Xb3JrU3RvcALQ4GymiWjjAgABWgAHY2xlYW51cHhyABNjb20uY2xvdWQudm0uVm1Xb3Jrn5m2VvAlZ2sCAARKAAlhY2NvdW50SWRKAAZ1c2VySWRKAAR2bUlkTAALaGFuZGxlck5hbWV0ABJMamF2YS9sYW5nL1N0cmluZzt4cAAAAAAAAAACAAAAAAAAAAIAAAAAAAAAGXQAGVZpcnR1YWxNYWNoaW5lTWFuYWdlckltcGwA","cmdVersion":0,"completeMsid":null,"created":"Sun
Dec 21 20:17:43 MSK
2025","id":255,"initMsid":345050669754,"instanceId":null,"instanceType":null,"lastP
olled":null,"lastUpdated":null,"processStatus":0,"removed":null,"result":null,"resultCode":0,"status":"IN_PROGRESS","userId":2,"uuid":"6a659a07-46d0-4a35-b1d9-ff4cc49c1127"}
2025-12-21 20:17:45,146 DEBUG [c.c.v.VmWorkJobDispatcher]
(Work-Job-Executor-73:[ctx-ab629834, job-253/job-255]) (logid:7fa07f5c) Run VM
work job: com.cloud.vm.VmWorkStop for VM 25, job origin: 253
2025-12-21 20:17:45,147 DEBUG [c.c.v.VmWorkJobHandlerProxy]
(Work-Job-Executor-73:[ctx-ab629834, job-253/job-255, ctx-0fe6fda7])
(logid:7fa07f5c) Execute VM work job:
com.cloud.vm.VmWorkStop{"cleanup":false,"userId":2,"accountId":2,"vmId":25,"handlerName":"VirtualMachineManagerImpl"}
2025-12-21 20:17:45,148 DEBUG [c.c.v.ClusteredVirtualMachineManagerImpl]
(Work-Job-Executor-73:[ctx-ab629834, job-253/job-255, ctx-0fe6fda7])
(logid:7fa07f5c) VM is already stopped: VM instance
{"id":25,"instanceName":"r-25-VM","state":"Stopped","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}
2025-12-21 20:17:45,149 DEBUG [c.c.v.VmWorkJobHandlerProxy]
(Work-Job-Executor-73:[ctx-ab629834, job-253/job-255, ctx-0fe6fda7])
(logid:7fa07f5c) Done executing VM work job:
com.cloud.vm.VmWorkStop{"cleanup":false,"userId":2,"accountId":2,"vmId":25,"handlerName":"VirtualMachineManagerImpl"}
2025-12-21 20:17:45,149 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(Work-Job-Executor-73:[ctx-ab629834, job-253/job-255, ctx-0fe6fda7])
(logid:7fa07f5c) Complete async job-255, jobStatus: SUCCEEDED, resultCode: 0,
result: null
2025-12-21 20:17:45,149 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(Work-Job-Executor-73:[ctx-ab629834, job-253/job-255, ctx-0fe6fda7])
(logid:7fa07f5c) Publish async job-255 complete on message bus
2025-12-21 20:17:45,149 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(Work-Job-Executor-73:[ctx-ab629834, job-253/job-255, ctx-0fe6fda7])
(logid:7fa07f5c) Wake up jobs related to job-255
2025-12-21 20:17:45,149 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(Work-Job-Executor-73:[ctx-ab629834, job-253/job-255, ctx-0fe6fda7])
(logid:7fa07f5c) Update db status for job-255
2025-12-21 20:17:45,150 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(Work-Job-Executor-73:[ctx-ab629834, job-253/job-255, ctx-0fe6fda7])
(logid:7fa07f5c) Wake up jobs joined with job-255 and disjoin all subjobs
created from job- 255
2025-12-21 20:17:45,158 DEBUG [c.c.v.VmWorkJobDispatcher]
(Work-Job-Executor-73:[ctx-ab629834, job-253/job-255]) (logid:7fa07f5c) Done
with run of VM work job: com.cloud.vm.VmWorkStop for VM 25, job origin: 253
2025-12-21 20:17:45,158 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl$5]
(Work-Job-Executor-73:[ctx-ab629834, job-253/job-255]) (logid:7fa07f5c) Done
executing com.cloud.vm.VmWorkStop for job-255
2025-12-21 20:17:45,160 INFO [o.a.c.f.j.i.AsyncJobMonitor]
(Work-Job-Executor-73:[ctx-ab629834, job-253/job-255]) (logid:7fa07f5c) Remove
job-255 from job monitoring
2025-12-21 20:17:45,169 DEBUG [c.c.c.CapacityManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c) VM
instance
{"id":25,"instanceName":"r-25-VM","state":"Expunging","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}
state transited from [Stopped] to [Expunging] with event [ExpungeOperation].
VM's original host: null, new host: null, host before state transition: null
2025-12-21 20:17:45,172 DEBUG [c.c.v.ClusteredVirtualMachineManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Expunging vm VM instance
{"id":25,"instanceName":"r-25-VM","state":"Expunging","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}
2025-12-21 20:17:45,172 DEBUG [c.c.v.ClusteredVirtualMachineManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Cleaning up NICS [] of VM instance
{"id":25,"instanceName":"r-25-VM","state":"Expunging","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}.
2025-12-21 20:17:45,172 DEBUG [o.a.c.e.o.NetworkOrchestrator]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Cleaning network for vm: VM instance
{"id":25,"instanceName":"r-25-VM","state":"Expunging","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}
2025-12-21 20:17:45,181 DEBUG [o.a.c.e.o.NetworkOrchestrator]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Removed nic Nic
{"broadcastUri":null,"deviceId":0,"iPv4Address":null,"id":50,"instanceId":25,"reservationId":null,"uuid":"338f3ad2-779c-43cd-baa1-53e7038ba303"}
2025-12-21 20:17:45,182 DEBUG [o.a.c.e.o.NetworkOrchestrator]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Revoving nic secondary ip entry ...
2025-12-21 20:17:45,188 DEBUG [c.c.n.g.PublicNetworkGuru]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
public network deallocate network: networkId: 200, ip: 10.10.10.133
2025-12-21 20:17:45,189 DEBUG [c.c.n.g.PublicNetworkGuru]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Deallocated nic: NicProfile
{"broadcastUri":null,"deviceId":null,"iPv4Address":null,"id":51,"reservationId":null,"uuid":"6d178610-efc6-43a7-94f1-a86c326d0e76","vmId":25}
2025-12-21 20:17:45,194 DEBUG [o.a.c.e.o.NetworkOrchestrator]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Removed nic Nic
{"broadcastUri":"vlan:\/\/905","deviceId":1,"iPv4Address":"10.10.10.133","id":51,"instanceId":25,"reservationId":null,"uuid":"6d178610-efc6-43a7-94f1-a86c326d0e76"}
2025-12-21 20:17:45,195 DEBUG [o.a.c.e.o.NetworkOrchestrator]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Revoving nic secondary ip entry ...
2025-12-21 20:17:45,195 DEBUG [c.c.v.ClusteredVirtualMachineManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Cleaning up hypervisor data structures (ex. SRs in XenServer) for managed
storage. Data from VM instance
{"id":25,"instanceName":"r-25-VM","state":"Expunging","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}.
2025-12-21 20:17:45,198 DEBUG [o.a.c.e.o.VolumeOrchestrator]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Cleaning storage for VM [null].
2025-12-21 20:17:45,225 INFO [o.a.c.s.v.VolumeServiceImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Expunge volume with no data store specified
2025-12-21 20:17:45,226 INFO [o.a.c.s.v.VolumeServiceImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Volume VolumeObject {"dataStore":null,"volumeVO":"Volume
{\"id\":28,\"instanceId\":25,\"name\":\"ROOT-25\",\"uuid\":\"3b5d35b9-bb25-496c-b963-e9e73cb671bf\",\"volumeType\":\"ROOT\"}"}
is not referred anywhere, remove it from volumes table
2025-12-21 20:17:45,226 DEBUG [c.c.s.d.VolumeDaoImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Removing volume 28 from DB
2025-12-21 20:17:45,238 DEBUG [c.c.v.ClusteredVirtualMachineManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Expunged VM instance
{"id":25,"instanceName":"r-25-VM","state":"Expunging","type":"DomainRouter","uuid":"52e61cd2-5ed5-4a7f-afc5-717aea77178e"}
2025-12-21 20:17:45,247 DEBUG [c.c.n.v.VpcManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Vpc VPC
{"id":6,"name":"kvm-vpc-network","uuid":"c934cc4c-73e9-4061-b0f4-cf120fbc3d72"}
has been shutdown successfully
2025-12-21 20:17:45,248 DEBUG [c.c.n.v.VpcManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Cleaning up resources for vpc VPC
{"id":6,"name":"kvm-vpc-network","uuid":"c934cc4c-73e9-4061-b0f4-cf120fbc3d72"}
2025-12-21 20:17:45,248 DEBUG [c.c.n.v.VpcManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Cleaning up existed site to site VPN connections
2025-12-21 20:17:45,249 DEBUG [c.c.n.v.VpcManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Cleaning up existed site to site VPN gateways
2025-12-21 20:17:45,251 DEBUG [c.c.n.v.VpcManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Releasing ips for vpc VPC
{"id":6,"name":"kvm-vpc-network","uuid":"c934cc4c-73e9-4061-b0f4-cf120fbc3d72"}
as a part of vpc cleanup
2025-12-21 20:17:45,254 DEBUG [c.c.n.IpAddressManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Revoking all Firewall rules as a part of public IP IPAddress
{"address":"10.10.10.133","dataCenterId":1,"id":4,"uuid":"45cd4c34-4797-4e42-972a-54f78093fdb3"}
release...
2025-12-21 20:17:45,263 DEBUG [c.c.n.f.FirewallManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Releasing 0 firewall rules for ip IPAddress
{"address":"10.10.10.133","dataCenterId":1,"id":4,"uuid":"45cd4c34-4797-4e42-972a-54f78093fdb3"}
2025-12-21 20:17:45,264 DEBUG [c.c.n.f.FirewallManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
There are no firewall rules to apply
2025-12-21 20:17:45,265 DEBUG [c.c.n.f.FirewallManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Successfully released firewall rules for ip IPAddress
{"address":"10.10.10.133","dataCenterId":1,"id":4,"uuid":"45cd4c34-4797-4e42-972a-54f78093fdb3"}
and # of rules now = 0
2025-12-21 20:17:45,274 DEBUG [c.c.n.IpAddressManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Revoking all PortForwarding/StaticNat rules as a part of public IP IPAddress
{"address":"10.10.10.133","dataCenterId":1,"id":4,"uuid":"45cd4c34-4797-4e42-972a-54f78093fdb3"}
release...
2025-12-21 20:17:45,275 DEBUG [c.c.n.r.RulesManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Releasing 0 port forwarding rules for ip id=4
2025-12-21 20:17:45,275 DEBUG [c.c.n.r.RulesManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Releasing 0 static nat rules for ip id=4
2025-12-21 20:17:45,277 DEBUG [c.c.n.r.RulesManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
There are no port forwarding rules to apply for ip id=4
2025-12-21 20:17:45,277 DEBUG [c.c.n.r.RulesManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
There are no static nat rules to apply for ip id=4
2025-12-21 20:17:45,278 DEBUG [c.c.n.r.RulesManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Successfully released rules for ip IPAddress
{"address":"10.10.10.133","dataCenterId":1,"id":4,"uuid":"45cd4c34-4797-4e42-972a-54f78093fdb3"}
and # of rules now = 0
2025-12-21 20:17:45,278 DEBUG [c.c.n.IpAddressManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Revoking all LoadBalancing rules as a part of public IP IPAddress
{"address":"10.10.10.133","dataCenterId":1,"id":4,"uuid":"45cd4c34-4797-4e42-972a-54f78093fdb3"}
release...
2025-12-21 20:17:45,278 DEBUG [c.c.n.l.LoadBalancingRulesManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Found 0 lb rules to cleanup
2025-12-21 20:17:45,278 DEBUG [c.c.n.IpAddressManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Cleaning up remote access vpns as a part of public IP IPAddress
{"address":"10.10.10.133","dataCenterId":1,"id":4,"uuid":"45cd4c34-4797-4e42-972a-54f78093fdb3"}
release...
2025-12-21 20:17:45,286 DEBUG [c.c.n.v.RemoteAccessVpnManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
there are no Remote access vpns for public ip address id=4
2025-12-21 20:17:45,294 DEBUG [c.c.n.IpAddressManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c) IP
address [IPAddress
{"address":"10.10.10.133","dataCenterId":1,"id":4,"uuid":"45cd4c34-4797-4e42-972a-54f78093fdb3"}]
is not direct, dedicated or reserved; therefore, the resource count should be
updated.
2025-12-21 20:17:45,294 DEBUG [c.c.r.ResourceLimitManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Updating resource Type = public_ip count for Account with id = 2 Operation =
decreasing Amount = 1
2025-12-21 20:17:45,307 DEBUG [c.c.n.IpAddressManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Releasing ip IPAddress
{"address":"10.10.10.133","dataCenterId":1,"id":4,"uuid":"45cd4c34-4797-4e42-972a-54f78093fdb3"};
sourceNat = true
2025-12-21 20:17:45,309 DEBUG [c.c.n.IpAddressManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Not adding IP [10.10.10.133] to quarantine because configuration
[public.ip.address.quarantine.duration] has value equal or less to 0.
2025-12-21 20:17:45,316 DEBUG [c.c.n.IpAddressManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Released a public ip IPAddress
{"address":"10.10.10.133","dataCenterId":1,"id":4,"uuid":"45cd4c34-4797-4e42-972a-54f78093fdb3"}
2025-12-21 20:17:45,325 DEBUG [c.c.n.v.VpcManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Released ip addresses for vpc VPC
{"id":6,"name":"kvm-vpc-network","uuid":"c934cc4c-73e9-4061-b0f4-cf120fbc3d72"}
as a part of cleanup vpc process
2025-12-21 20:17:45,327 DEBUG [c.c.n.v.VpcManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Found 0 static routes for VPC 6
2025-12-21 20:17:45,327 DEBUG [c.c.n.v.VpcManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Found 0 to revoke for the vpc VPC
{"id":6,"name":"kvm-vpc-network","uuid":"c934cc4c-73e9-4061-b0f4-cf120fbc3d72"}
2025-12-21 20:17:45,335 DEBUG [c.c.n.v.VpcManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Vpc VPC
{"id":6,"name":"kvm-vpc-network","uuid":"c934cc4c-73e9-4061-b0f4-cf120fbc3d72"}
is destroyed successfully
2025-12-21 20:17:45,340 WARN [c.c.n.v.VpcManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253, ctx-036408b7]) (logid:7fa07f5c)
Successfully destroyed vpc VPC
{"id":6,"name":"kvm-vpc-network","uuid":"c934cc4c-73e9-4061-b0f4-cf120fbc3d72"}
that failed to start
2025-12-21 20:17:45,348 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253]) (logid:7fa07f5c) Complete async
job-253, jobStatus: FAILED, resultCode: 530, result:
org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"errorcode":"530","errortext":"Failed
to create VPC"}
2025-12-21 20:17:45,349 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253]) (logid:7fa07f5c) Publish async
job-253 complete on message bus
2025-12-21 20:17:45,349 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253]) (logid:7fa07f5c) Wake up jobs
related to job-253
2025-12-21 20:17:45,349 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253]) (logid:7fa07f5c) Update db status
for job-253
2025-12-21 20:17:45,349 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-47:[ctx-ef119e43, job-253]) (logid:7fa07f5c) Wake up jobs
joined with job-253 and disjoin all subjobs created from job- 253
2025-12-21 20:17:45,357 DEBUG [c.c.a.ApiServer]
(API-Job-Executor-47:[ctx-ef119e43, job-253]) (logid:7fa07f5c) Retrieved
cmdEventType from job info: VPC.CREATE
2025-12-21 20:17:45,359 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl$5]
(API-Job-Executor-47:[ctx-ef119e43, job-253]) (logid:7fa07f5c) Done executing
org.apache.cloudstack.api.command.admin.vpc.CreateVPCCmdByAdmin for job-253
2025-12-21 20:17:45,359 INFO [o.a.c.f.j.i.AsyncJobMonitor]
(API-Job-Executor-47:[ctx-ef119e43, job-253]) (logid:7fa07f5c) Remove job-253
from job monitoring
GitHub link: https://github.com/apache/cloudstack/discussions/12315
----
This is an automatically sent email for [email protected].
To unsubscribe, please send an email to: [email protected]
