2026-04-23 02:36:49,848 - INFO - Domain Default found not creating
2026-04-23 02:36:50,023 - INFO - Project ctest-TestPolicyAclIpv4v6-48672592 not found, creating it
2026-04-23 02:36:50,614 - INFO - Created Project:ctest-TestPolicyAclIpv4v6-48672592, ID : 16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:36:52,154 - INFO - ================================================================================
2026-04-23 02:36:52,154 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any
2026-04-23 02:36:53,430 - DEBUG - Nothing to compare xmpp stats {'10.0.0.73': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.131': {'10.20.0.25': '0', '10.20.0.193': '0'}} with
2026-04-23 02:36:53,431 - INFO - Initial checks done. Running the testcase now
2026-04-23 02:36:53,431 - INFO -
2026-04-23 02:36:53,431 - INFO - ================================================================================
2026-04-23 02:36:53,431 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any
2026-04-23 02:36:53,431 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance
2026-04-23 02:36:54,739 - DEBUG - Nothing to compare xmpp stats {'10.0.0.73': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.131': {'10.20.0.25': '0', '10.20.0.193': '0'}} with
2026-04-23 02:36:54,739 - INFO - Initial checks done. Running the testcase now
2026-04-23 02:36:54,739 - INFO -
2026-04-23 02:36:56,013 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:36:56,404 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:36:56,447 - DEBUG - Requesting: http://10.0.0.23:8082/domain/36692cb6-e180-463e-9a23-ae16be53ee8b
2026-04-23 02:36:56,464 - DEBUG - Requesting: http://10.0.0.23:8082/project/16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:36:56,570 - DEBUG - Requesting: http://10.0.0.23:8082/network-ipam/c47d056c-5ce8-471f-be00-b829633aa90a
2026-04-23 02:36:56,578 - INFO - Verifications in API Server for IPAM: ipam1 passed
2026-04-23 02:36:56,584 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14158478766496040735', 'uuid-lslong': '13691145355104266506'}, 'enable': 'true', 'created': '2026-04-23T02:36:55', 'last-modified': '2026-04-23T02:36:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.269623'}
2026-04-23 02:36:56,590 - DEBUG - Control-node 10.0.0.130 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14158478766496040735', 'uuid-lslong': '13691145355104266506'}, 'enable': 'true', 'created': '2026-04-23T02:36:55', 'last-modified': '2026-04-23T02:36:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.275449'}
2026-04-23 02:36:56,597 - DEBUG - Control-node 10.0.0.249 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14158478766496040735', 'uuid-lslong': '13691145355104266506'}, 'enable': 'true', 'created': '2026-04-23T02:36:55', 'last-modified': '2026-04-23T02:36:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.281942'}
2026-04-23 02:36:56,597 - INFO - Verifications in Control node for IPAM: ipam1 passed
2026-04-23 02:36:56,597 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:36:56,611 - DEBUG - Requesting: http://10.0.0.23:8082/domain/36692cb6-e180-463e-9a23-ae16be53ee8b
2026-04-23 02:36:56,622 - DEBUG - Requesting: http://10.0.0.23:8082/project/16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:36:56,714 - DEBUG - Requesting: http://10.0.0.23:8082/network-ipam/5a46ba4b-6917-46fb-a8e6-d4c7173d0667
2026-04-23 02:36:56,723 - INFO - Verifications in API Server for IPAM: ipam2 passed
2026-04-23 02:36:56,734 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6505091544831706875', 'uuid-lslong': '12170648994568799847'}, 'enable': 'true', 'created': '2026-04-23T02:36:55', 'last-modified': '2026-04-23T02:36:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.083081'}
2026-04-23 02:36:56,741 - DEBUG - Control-node 10.0.0.130 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6505091544831706875', 'uuid-lslong': '12170648994568799847'}, 'enable': 'true', 'created': '2026-04-23T02:36:55', 'last-modified': '2026-04-23T02:36:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.092593'}
2026-04-23 02:36:56,750 - DEBUG - Control-node 10.0.0.249 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6505091544831706875', 'uuid-lslong': '12170648994568799847'}, 'enable': 'true', 'created': '2026-04-23T02:36:55', 'last-modified': '2026-04-23T02:36:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.103236'}
2026-04-23 02:36:56,750 - INFO - Verifications in Control node for IPAM: ipam2 passed
2026-04-23 02:36:56,751 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:36:56,759 - DEBUG - Requesting: http://10.0.0.23:8082/domain/36692cb6-e180-463e-9a23-ae16be53ee8b
2026-04-23 02:36:56,769 - DEBUG - Requesting: http://10.0.0.23:8082/project/16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:36:56,881 - DEBUG - Requesting: http://10.0.0.23:8082/network-ipam/c5c1c419-b372-45b9-945f-68932a0b2ea3
2026-04-23 02:36:56,892 - INFO - Verifications in API Server for IPAM: ipam3 passed
2026-04-23 02:36:56,902 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14249886310640797113', 'uuid-lslong': '10691379021675703971'}, 'enable': 'true', 'created': '2026-04-23T02:36:55', 'last-modified': '2026-04-23T02:36:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.913310'}
2026-04-23 02:36:56,913 - DEBUG - Control-node 10.0.0.130 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14249886310640797113', 'uuid-lslong': '10691379021675703971'}, 'enable': 'true', 'created': '2026-04-23T02:36:55', 'last-modified': '2026-04-23T02:36:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.920631'}
2026-04-23 02:36:56,918 - DEBUG - Control-node 10.0.0.249 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14249886310640797113', 'uuid-lslong': '10691379021675703971'}, 'enable': 'true', 'created': '2026-04-23T02:36:55', 'last-modified': '2026-04-23T02:36:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.933658'}
2026-04-23 02:36:56,918 - INFO - Verifications in Control node for IPAM: ipam3 passed
2026-04-23 02:36:57,335 - DEBUG - Response for create_network : {'network': {'id': 'b8d67109-1a59-4f61-aaa7-0465eeb4eef9', 'name': 'ctest-VN1-10545643', 'tenant_id': '16088fd565c14716a13d2ade51022390', 'project_id': '16088fd565c14716a13d2ade51022390', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ctest-VN1-10545643'], 'port_security_enabled': True, 'description': ''}}
2026-04-23 02:36:57,746 - DEBUG - Response for create_subnet : {'subnet': {'id': '62857027-3810-4cac-a899-e07e9a03745a', 'name': '', 'tenant_id': '16088fd565c14716a13d2ade51022390', 'network_id': 'b8d67109-1a59-4f61-aaa7-0465eeb4eef9', 'ip_version': 4, 'cidr': '216.7.3.128/26', 'allocation_pools': [{'start': '216.7.3.130', 'end': '216.7.3.190'}], 'gateway_ip': '216.7.3.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '216.7.3.130', 'tags': [], 'project_id': '16088fd565c14716a13d2ade51022390'}}
2026-04-23 02:36:57,997 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cb3dcc96-7588-4aa4-9f6d-3e72d7f04bb1', 'name': '', 'tenant_id': '16088fd565c14716a13d2ade51022390', 'network_id': 'b8d67109-1a59-4f61-aaa7-0465eeb4eef9', 'ip_version': 6, 'cidr': '3db9:df63:7da1:b110:60ea:c0c3::/96', 'allocation_pools': [{'start': '3db9:df63:7da1:b110:60ea:c0c3:0:2', 'end': '3db9:df63:7da1:b110:60ea:c0c3:ffff:fffe'}], 'gateway_ip': '3db9:df63:7da1:b110:60ea:c0c3:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3db9:df63:7da1:b110:60ea:c0c3:0:2', 'tags': [], 'project_id': '16088fd565c14716a13d2ade51022390'}}
2026-04-23 02:36:58,023 - INFO - Created VN ctest-VN1-10545643
2026-04-23 02:36:58,037 - DEBUG - VN ctest-VN1-10545643 UUID is b8d67109-1a59-4f61-aaa7-0465eeb4eef9
2026-04-23 02:36:58,213 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643(b8d67109-1a59-4f61-aaa7-0465eeb4eef9) with subnets ['216.7.3.128/26', '3db9:df63:7da1:b110:60ea:c0c3::/96']
2026-04-23 02:36:58,520 - DEBUG - Response for create_network : {'network': {'id': '16abf06a-a71a-43af-8399-014e3050bf3b', 'name': 'ctest-VN2-57009616', 'tenant_id': '16088fd565c14716a13d2ade51022390', 'project_id': '16088fd565c14716a13d2ade51022390', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ctest-VN2-57009616'], 'port_security_enabled': True, 'description': ''}}
2026-04-23 02:36:58,792 - DEBUG - Response for create_subnet : {'subnet': {'id': '00793120-7aab-46b7-9959-d26955d3f137', 'name': '', 'tenant_id': '16088fd565c14716a13d2ade51022390', 'network_id': '16abf06a-a71a-43af-8399-014e3050bf3b', 'ip_version': 4, 'cidr': '75.13.77.128/26', 'allocation_pools': [{'start': '75.13.77.130', 'end': '75.13.77.190'}], 'gateway_ip': '75.13.77.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '75.13.77.130', 'tags': [], 'project_id': '16088fd565c14716a13d2ade51022390'}}
2026-04-23 02:36:58,979 - DEBUG - Response for create_subnet : {'subnet': {'id': '2765ae29-cb16-4a56-834f-db4cfbe2e9e9', 'name': '', 'tenant_id': '16088fd565c14716a13d2ade51022390', 'network_id': '16abf06a-a71a-43af-8399-014e3050bf3b', 'ip_version': 6, 'cidr': '3b5b:541d:29a4:f336:cd43:71a3::/96', 'allocation_pools': [{'start': '3b5b:541d:29a4:f336:cd43:71a3:0:2', 'end': '3b5b:541d:29a4:f336:cd43:71a3:ffff:fffe'}], 'gateway_ip': '3b5b:541d:29a4:f336:cd43:71a3:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3b5b:541d:29a4:f336:cd43:71a3:0:2', 'tags': [], 'project_id': '16088fd565c14716a13d2ade51022390'}}
2026-04-23 02:36:59,005 - INFO - Created VN ctest-VN2-57009616
2026-04-23 02:36:59,017 - DEBUG - VN ctest-VN2-57009616 UUID is 16abf06a-a71a-43af-8399-014e3050bf3b
2026-04-23 02:36:59,390 - DEBUG - Response for create_network : {'network': {'id': '77e044ce-e21b-4c6a-a80a-157d9873228d', 'name': 'ctest-VN3-54060924', 'tenant_id': '16088fd565c14716a13d2ade51022390', 'project_id': '16088fd565c14716a13d2ade51022390', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ctest-VN3-54060924'], 'port_security_enabled': True, 'description': ''}}
2026-04-23 02:36:59,577 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd1c9b667-053a-4263-8c33-f4a95bcba899', 'name': '', 'tenant_id': '16088fd565c14716a13d2ade51022390', 'network_id': '77e044ce-e21b-4c6a-a80a-157d9873228d', 'ip_version': 4, 'cidr': '89.51.149.128/26', 'allocation_pools': [{'start': '89.51.149.130', 'end': '89.51.149.190'}], 'gateway_ip': '89.51.149.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '89.51.149.130', 'tags': [], 'project_id': '16088fd565c14716a13d2ade51022390'}}
2026-04-23 02:36:59,793 - DEBUG - Response for create_subnet : {'subnet': {'id': '905572cf-57b1-44cf-b633-dd9f9bc25a1c', 'name': '', 'tenant_id': '16088fd565c14716a13d2ade51022390', 'network_id': '77e044ce-e21b-4c6a-a80a-157d9873228d', 'ip_version': 6, 'cidr': '20fd:4a43:19ca:d929:7e59:c358::/96', 'allocation_pools': [{'start': '20fd:4a43:19ca:d929:7e59:c358:0:2', 'end': '20fd:4a43:19ca:d929:7e59:c358:ffff:fffe'}], 'gateway_ip': '20fd:4a43:19ca:d929:7e59:c358:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20fd:4a43:19ca:d929:7e59:c358:0:2', 'tags': [], 'project_id': '16088fd565c14716a13d2ade51022390'}}
2026-04-23 02:36:59,820 - INFO - Created VN ctest-VN3-54060924
2026-04-23 02:36:59,830 - DEBUG - VN ctest-VN3-54060924 UUID is 77e044ce-e21b-4c6a-a80a-157d9873228d
2026-04-23 02:36:59,923 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:36:59,929 - DEBUG - Requesting: http://10.0.0.23:8082/domain/36692cb6-e180-463e-9a23-ae16be53ee8b
2026-04-23 02:36:59,940 - DEBUG - Requesting: http://10.0.0.23:8082/project/16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:37:00,028 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b8d67109-1a59-4f61-aaa7-0465eeb4eef9
2026-04-23 02:37:00,046 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b8d67109-1a59-4f61-aaa7-0465eeb4eef9
2026-04-23 02:37:00,060 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/54ba1c05-248a-4a51-9d74-d6466181356e
2026-04-23 02:37:00,068 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/54ba1c05-248a-4a51-9d74-d6466181356e
2026-04-23 02:37:00,075 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/6ebdc14c-047f-4db0-a613-8f561e264ea5
2026-04-23 02:37:00,083 - DEBUG - Route Targets: ['target:64512:8000004']
2026-04-23 02:37:00,084 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b8d67109-1a59-4f61-aaa7-0465eeb4eef9
2026-04-23 02:37:00,095 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/54ba1c05-248a-4a51-9d74-d6466181356e
2026-04-23 02:37:00,115 - INFO - Verified VN network id 14 for VN b8d67109-1a59-4f61-aaa7-0465eeb4eef9
2026-04-23 02:37:00,115 - INFO - Verifications in API Server for VN ctest-VN1-10545643 passed
2026-04-23 02:37:00,116 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b8d67109-1a59-4f61-aaa7-0465eeb4eef9
2026-04-23 02:37:00,127 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/54ba1c05-248a-4a51-9d74-d6466181356e
2026-04-23 02:37:00,136 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/54ba1c05-248a-4a51-9d74-d6466181356e
2026-04-23 02:37:00,143 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/6ebdc14c-047f-4db0-a613-8f561e264ea5
2026-04-23 02:37:00,153 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13318957231905525601', 'uuid-lslong': '12296802143401471737'}, 'enable': 'true', 'created': '2026-04-23T02:36:57', 'last-modified': '2026-04-23T02:36:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.213843'}
2026-04-23 02:37:00,160 - DEBUG - Route Targets: ['target:64512:8000004']
2026-04-23 02:37:00,166 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13318957231905525601', 'uuid-lslong': '12296802143401471737'}, 'enable': 'true', 'created': '2026-04-23T02:36:57', 'last-modified': '2026-04-23T02:36:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.227340'}
2026-04-23 02:37:00,174 - DEBUG - Route Targets: ['target:64512:8000004']
2026-04-23 02:37:00,180 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13318957231905525601', 'uuid-lslong': '12296802143401471737'}, 'enable': 'true', 'created': '2026-04-23T02:36:57', 'last-modified': '2026-04-23T02:36:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.239066'}
2026-04-23 02:37:00,186 - DEBUG - Route Targets: ['target:64512:8000004']
2026-04-23 02:37:00,187 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-10545643 passed
2026-04-23 02:37:00,187 - DEBUG - ====Verifying policy data for ctest-VN1-10545643 in API_Server ======
2026-04-23 02:37:00,187 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:37:00,193 - DEBUG - Requesting: http://10.0.0.23:8082/domain/36692cb6-e180-463e-9a23-ae16be53ee8b
2026-04-23 02:37:00,203 - DEBUG - Requesting: http://10.0.0.23:8082/project/16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:37:00,292 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b8d67109-1a59-4f61-aaa7-0465eeb4eef9
2026-04-23 02:37:00,302 - DEBUG - =>VN ctest-VN1-10545643 has no policy to be verified
2026-04-23 02:37:00,302 - DEBUG - Verifying the vn in opserver
2026-04-23 02:37:00,302 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 virtual network link through opserver 10.0.0.23
2026-04-23 02:37:00,302 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-23 02:37:00,607 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-23 02:37:00,699 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643?flat'}
2026-04-23 02:37:00,699 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 virtual network link through opserver 10.0.0.130
2026-04-23 02:37:00,699 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2026-04-23 02:37:01,006 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2026-04-23 02:37:01,076 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643?flat'}
2026-04-23 02:37:01,076 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 virtual network link through opserver 10.0.0.249
2026-04-23 02:37:01,076 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-23 02:37:01,382 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-23 02:37:01,450 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643?flat'}
2026-04-23 02:37:01,450 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is found in opserver
2026-04-23 02:37:01,473 - DEBUG - Do not have enough data to verify VN in agent
2026-04-23 02:37:01,486 - DEBUG - VRF ids for VN ctest-VN1-10545643: {}
2026-04-23 02:37:01,486 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:37:01,492 - DEBUG - Requesting: http://10.0.0.23:8082/domain/36692cb6-e180-463e-9a23-ae16be53ee8b
2026-04-23 02:37:01,503 - DEBUG - Requesting: http://10.0.0.23:8082/project/16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:37:01,601 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/16abf06a-a71a-43af-8399-014e3050bf3b
2026-04-23 02:37:01,614 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/16abf06a-a71a-43af-8399-014e3050bf3b
2026-04-23 02:37:01,623 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ba5c4e4b-af3a-4a74-92d8-83d8a7454c15
2026-04-23 02:37:01,630 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ba5c4e4b-af3a-4a74-92d8-83d8a7454c15
2026-04-23 02:37:01,637 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/bf4592bf-e26b-4be9-aa70-6ad530dadabd
2026-04-23 02:37:01,644 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-23 02:37:01,644 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/16abf06a-a71a-43af-8399-014e3050bf3b
2026-04-23 02:37:01,651 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ba5c4e4b-af3a-4a74-92d8-83d8a7454c15
2026-04-23 02:37:01,667 - INFO - Verified VN network id 15 for VN 16abf06a-a71a-43af-8399-014e3050bf3b
2026-04-23 02:37:01,667 - INFO - Verifications in API Server for VN ctest-VN2-57009616 passed
2026-04-23 02:37:01,668 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/16abf06a-a71a-43af-8399-014e3050bf3b
2026-04-23 02:37:01,677 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ba5c4e4b-af3a-4a74-92d8-83d8a7454c15
2026-04-23 02:37:01,684 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ba5c4e4b-af3a-4a74-92d8-83d8a7454c15
2026-04-23 02:37:01,691 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/bf4592bf-e26b-4be9-aa70-6ad530dadabd
2026-04-23 02:37:01,702 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1633663630712652719', 'uuid-lslong': '9482611925734965051'}, 'enable': 'true', 'created': '2026-04-23T02:36:58', 'last-modified': '2026-04-23T02:36:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.772801'}
2026-04-23 02:37:01,708 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-23 02:37:01,714 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1633663630712652719', 'uuid-lslong': '9482611925734965051'}, 'enable': 'true', 'created': '2026-04-23T02:36:58', 'last-modified': '2026-04-23T02:36:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.794419'}
2026-04-23 02:37:01,722 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-23 02:37:01,727 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1633663630712652719', 'uuid-lslong': '9482611925734965051'}, 'enable': 'true', 'created': '2026-04-23T02:36:58', 'last-modified': '2026-04-23T02:36:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.809530'}
2026-04-23 02:37:01,732 - DEBUG - Route Targets: ['target:64512:8000006']
2026-04-23 02:37:01,732 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-57009616 passed
2026-04-23 02:37:01,732 - DEBUG - ====Verifying policy data for ctest-VN2-57009616 in API_Server ======
2026-04-23 02:37:01,733 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:37:01,738 - DEBUG - Requesting: http://10.0.0.23:8082/domain/36692cb6-e180-463e-9a23-ae16be53ee8b
2026-04-23 02:37:01,749 - DEBUG - Requesting: http://10.0.0.23:8082/project/16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:37:01,852 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/16abf06a-a71a-43af-8399-014e3050bf3b
2026-04-23 02:37:01,869 - DEBUG - =>VN ctest-VN2-57009616 has no policy to be verified
2026-04-23 02:37:01,869 - DEBUG - Verifying the vn in opserver
2026-04-23 02:37:01,869 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616 virtual network link through opserver 10.0.0.23
2026-04-23 02:37:01,869 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-23 02:37:01,936 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616?flat'}
2026-04-23 02:37:01,936 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616 virtual network link through opserver 10.0.0.130
2026-04-23 02:37:01,936 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2026-04-23 02:37:01,997 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616?flat'}
2026-04-23 02:37:01,997 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616 virtual network link through opserver 10.0.0.249
2026-04-23 02:37:01,997 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-23 02:37:02,063 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616?flat'}
2026-04-23 02:37:02,063 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616 is found in opserver
2026-04-23 02:37:02,087 - DEBUG - Do not have enough data to verify VN in agent
2026-04-23 02:37:02,100 - DEBUG - VRF ids for VN ctest-VN2-57009616: {}
2026-04-23 02:37:02,100 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:37:02,107 - DEBUG - Requesting: http://10.0.0.23:8082/domain/36692cb6-e180-463e-9a23-ae16be53ee8b
2026-04-23 02:37:02,120 - DEBUG - Requesting: http://10.0.0.23:8082/project/16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:37:02,219 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/77e044ce-e21b-4c6a-a80a-157d9873228d
2026-04-23 02:37:02,234 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/77e044ce-e21b-4c6a-a80a-157d9873228d
2026-04-23 02:37:02,243 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/1898fd36-1514-4375-a9fc-964d2bba132b
2026-04-23 02:37:02,252 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/1898fd36-1514-4375-a9fc-964d2bba132b
2026-04-23 02:37:02,260 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/aaed584c-af0a-4ff0-8b90-b736b93930ed
2026-04-23 02:37:02,268 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-23 02:37:02,268 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/77e044ce-e21b-4c6a-a80a-157d9873228d
2026-04-23 02:37:02,280 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/1898fd36-1514-4375-a9fc-964d2bba132b
2026-04-23 02:37:02,299 - INFO - Verified VN network id 16 for VN 77e044ce-e21b-4c6a-a80a-157d9873228d
2026-04-23 02:37:02,299 - INFO - Verifications in API Server for VN ctest-VN3-54060924 passed
2026-04-23 02:37:02,300 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/77e044ce-e21b-4c6a-a80a-157d9873228d
2026-04-23 02:37:02,312 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/1898fd36-1514-4375-a9fc-964d2bba132b
2026-04-23 02:37:02,320 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/1898fd36-1514-4375-a9fc-964d2bba132b
2026-04-23 02:37:02,326 - DEBUG - Requesting: http://10.0.0.23:8082/route-target/aaed584c-af0a-4ff0-8b90-b736b93930ed
2026-04-23 02:37:02,337 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8637979740644002922', 'uuid-lslong': '12108514177311777421'}, 'enable': 'true', 'created': '2026-04-23T02:36:59', 'last-modified': '2026-04-23T02:36:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.595060'}
2026-04-23 02:37:02,344 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-23 02:37:02,351 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8637979740644002922', 'uuid-lslong': '12108514177311777421'}, 'enable': 'true', 'created': '2026-04-23T02:36:59', 'last-modified': '2026-04-23T02:36:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.604294'}
2026-04-23 02:37:02,357 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-23 02:37:02,363 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-48672592', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8637979740644002922', 'uuid-lslong': '12108514177311777421'}, 'enable': 'true', 'created': '2026-04-23T02:36:59', 'last-modified': '2026-04-23T02:36:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.622183'}
2026-04-23 02:37:02,369 - DEBUG - Route Targets: ['target:64512:8000007']
2026-04-23 02:37:02,369 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-54060924 passed
2026-04-23 02:37:02,370 - DEBUG - ====Verifying policy data for ctest-VN3-54060924 in API_Server ======
2026-04-23 02:37:02,370 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:37:02,377 - DEBUG - Requesting: http://10.0.0.23:8082/domain/36692cb6-e180-463e-9a23-ae16be53ee8b
2026-04-23 02:37:02,388 - DEBUG - Requesting: http://10.0.0.23:8082/project/16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:37:02,477 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/77e044ce-e21b-4c6a-a80a-157d9873228d
2026-04-23 02:37:02,487 - DEBUG - =>VN ctest-VN3-54060924 has no policy to be verified
2026-04-23 02:37:02,487 - DEBUG - Verifying the vn in opserver
2026-04-23 02:37:02,487 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924 virtual network link through opserver 10.0.0.23
2026-04-23 02:37:02,487 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-04-23 02:37:02,546 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924?flat'}
2026-04-23 02:37:02,546 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924 virtual network link through opserver 10.0.0.130
2026-04-23 02:37:02,546 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks
2026-04-23 02:37:02,610 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924?flat'}
2026-04-23 02:37:02,610 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924 virtual network link through opserver 10.0.0.249
2026-04-23 02:37:02,610 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-04-23 02:37:02,675 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924?flat'}
2026-04-23 02:37:02,675 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924 is found in opserver
2026-04-23 02:37:02,699 - DEBUG - Do not have enough data to verify VN in agent
2026-04-23 02:37:02,711 - DEBUG - VRF ids for VN ctest-VN3-54060924: {}
2026-04-23 02:37:02,717 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = deny, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2026-04-23 02:37:03,001 - DEBUG - No source network defined
2026-04-23 02:37:03,001 - DEBUG - No source network defined
2026-04-23 02:37:03,001 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = None, security_group = None, network_policy = default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-policy13-16050762, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = any, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = 58, src_addresses = [subnet = None, virtual_network = None, security_group = None, network_policy = default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-policy13-16050762, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = any, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2026-04-23 02:37:03,273 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None, rule_sequence = None, rule_uuid = None, direction = <>, protocol = 58, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = -1, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None]
2026-04-23 02:37:03,498 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:37:03,504 - DEBUG - Requesting: http://10.0.0.23:8082/domain/36692cb6-e180-463e-9a23-ae16be53ee8b
2026-04-23 02:37:03,516 - DEBUG - Requesting: http://10.0.0.23:8082/project/16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:37:03,630 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b8d67109-1a59-4f61-aaa7-0465eeb4eef9
2026-04-23 02:37:03,645 - DEBUG - Setup step: Associating the policy to VN'
2026-04-23 02:37:03,773 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'b8d67109-1a59-4f61-aaa7-0465eeb4eef9', 'name': 'ctest-VN1-10545643', 'tenant_id': '16088fd565c14716a13d2ade51022390', 'project_id': '16088fd565c14716a13d2ade51022390', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['62857027-3810-4cac-a899-e07e9a03745a', 'cb3dcc96-7588-4aa4-9f6d-3e72d7f04bb1'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ctest-VN1-10545643'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ctest-policy12-77873303'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ctest-policy13-16050762']], 'subnet_ipam': [{'subnet_cidr': '216.7.3.128/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ipam1']}, {'subnet_cidr': '3db9:df63:7da1:b110:60ea:c0c3::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ipam1']}], 'port_security_enabled': True, 'description': ''}}
2026-04-23 02:37:03,953 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ctest-policy12-77873303'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ctest-policy13-16050762']] to ctest-VN1-10545643
2026-04-23 02:37:03,953 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:37:03,960 - DEBUG - Requesting: http://10.0.0.23:8082/domain/36692cb6-e180-463e-9a23-ae16be53ee8b
2026-04-23 02:37:03,972 - DEBUG - Requesting: http://10.0.0.23:8082/project/16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:37:04,064 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/16abf06a-a71a-43af-8399-014e3050bf3b
2026-04-23 02:37:04,073 - DEBUG - Setup step: Associating the policy to VN'
2026-04-23 02:37:04,181 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '16abf06a-a71a-43af-8399-014e3050bf3b', 'name': 'ctest-VN2-57009616', 'tenant_id': '16088fd565c14716a13d2ade51022390', 'project_id': '16088fd565c14716a13d2ade51022390', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['00793120-7aab-46b7-9959-d26955d3f137', '2765ae29-cb16-4a56-834f-db4cfbe2e9e9'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ctest-VN2-57009616'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ctest-policy21-66635010']], 'subnet_ipam': [{'subnet_cidr': '75.13.77.128/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ipam2']}, {'subnet_cidr': '3b5b:541d:29a4:f336:cd43:71a3::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ipam2']}], 'port_security_enabled': True, 'description': ''}}
2026-04-23 02:37:04,280 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ctest-policy21-66635010']] to ctest-VN2-57009616
2026-04-23 02:37:04,378 - DEBUG - Services list from nova: [, , , ]
2026-04-23 02:37:05,595 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5672-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5672-2)
2026-04-23 02:37:07,007 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5672-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5672-1)
2026-04-23 02:37:08,432 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5672-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5672-2)
2026-04-23 02:37:08,521 - DEBUG - VM is in ACTIVE state now
2026-04-23 02:37:08,522 - INFO - VM name : ctest-VM11-84765133
2026-04-23 02:37:08,603 - DEBUG - VM ctest-VM11-84765133 ID is 7542d49a-be25-47a0-9872-4e930843c4a6
2026-04-23 02:37:08,704 - DEBUG - VM is in ACTIVE state now
2026-04-23 02:37:08,736 - DEBUG - VM ctest-VM11-84765133 launched on Node an-jenkins-deploy-platform-ansible-os-5672-2
2026-04-23 02:37:08,835 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/7542d49a-be25-47a0-9872-4e930843c4a6
2026-04-23 02:37:09,258 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/7542d49a-be25-47a0-9872-4e930843c4a6
2026-04-23 02:37:09,298 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/7542d49a-be25-47a0-9872-4e930843c4a6
2026-04-23 02:37:09,306 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/9ca6a08c-f203-4b11-97a5-c8234d35167e
2026-04-23 02:37:09,317 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/7542d49a-be25-47a0-9872-4e930843c4a6
2026-04-23 02:37:09,327 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/9ca6a08c-f203-4b11-97a5-c8234d35167e
2026-04-23 02:37:09,348 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/28421f4a-f632-4244-918a-b0bcd0d144d5
2026-04-23 02:37:09,372 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/768c9794-ce95-42cc-8e7a-fefbcce4e033
2026-04-23 02:37:09,397 - DEBUG - Verifying in api server 10.0.0.249
2026-04-23 02:37:09,397 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/7542d49a-be25-47a0-9872-4e930843c4a6
2026-04-23 02:37:09,406 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/9ca6a08c-f203-4b11-97a5-c8234d35167e
2026-04-23 02:37:09,418 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/28421f4a-f632-4244-918a-b0bcd0d144d5
2026-04-23 02:37:09,432 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/768c9794-ce95-42cc-8e7a-fefbcce4e033
2026-04-23 02:37:09,442 - INFO - VM ctest-VM11-84765133 verfication in all API Servers passed
2026-04-23 02:37:09,464 - DEBUG - VM ctest-VM11-84765133 Tap interface: {'index': '3', 'name': 'tap9ca6a08c-f2', 'uuid': '9ca6a08c-f203-4b11-97a5-c8234d35167e', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643:ctest-VN1-10545643', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '14', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643', 'vm_uuid': '7542d49a-be25-47a0-9872-4e930843c4a6', 'vm_name': 'ctest-VM11-84765133', 'ip_addr': '216.7.3.131', 'mac_addr': '02:9c:a6:a0:8c:f2', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '50', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:9ca6a08c-f203-4b11-97a5-c8234d35167e', 'sg_uuid_list': ['6b255360-dd84-4e03-b7fd-a02143d569e2'], 'static_route_list': None, 'vm_project_uuid': '16088fd5-65c1-4716-a13d-2ade51022390', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '3db9:df63:7da1:b110:60ea:c0c3:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-04-23 02:37:09,464 - DEBUG - Agent 10.0.0.131 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643:ctest-VN1-10545643
2026-04-23 02:37:09,469 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643:ctest-VN1-10545643', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.192:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-04-23 02:37:09,572 - DEBUG - VM ctest-VM11-84765133 : Tap interface tap9ca6a08c-f2 is set to Active
2026-04-23 02:37:09,572 - DEBUG - Tap interface tap9ca6a08c-f2 detail : {'index': '3', 'name': 'tap9ca6a08c-f2', 'uuid': '9ca6a08c-f203-4b11-97a5-c8234d35167e', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643:ctest-VN1-10545643', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '14', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643', 'vm_uuid': '7542d49a-be25-47a0-9872-4e930843c4a6', 'vm_name': 'ctest-VM11-84765133', 'ip_addr': '216.7.3.131', 'mac_addr': '02:9c:a6:a0:8c:f2', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '50', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:9ca6a08c-f203-4b11-97a5-c8234d35167e', 'sg_uuid_list': ['6b255360-dd84-4e03-b7fd-a02143d569e2'], 'static_route_list': None, 'vm_project_uuid': '16088fd5-65c1-4716-a13d-2ade51022390', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '3db9:df63:7da1:b110:60ea:c0c3:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-04-23 02:37:09,682 - DEBUG - Starting Layer 2 verification in Agent
2026-04-23 02:37:09,695 - DEBUG - Layer 2 path is seen for VM MAC 02:9c:a6:a0:8c:f2 in agent 10.0.0.131
2026-04-23 02:37:09,695 - DEBUG - Active layer 2 route in agent is present for VMI tap9ca6a08c-f2
2026-04-23 02:37:09,695 - DEBUG - L2 label(29) matches bw route table and interface table
2026-04-23 02:37:09,710 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is found in Agent of node 10.0.0.131
2026-04-23 02:37:09,864 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is consistent in agent 10.0.0.131
2026-04-23 02:37:09,864 - DEBUG - Route for VM IP ['216.7.3.131', '3db9:df63:7da1:b110:60ea:c0c3:0:3'] is consistent in agent 10.0.0.131
2026-04-23 02:37:09,864 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 verification for VM ctest-VM11-84765133 in Agent 10.0.0.131 passed
2026-04-23 02:37:09,981 - DEBUG - Starting all layer 2 verification in agent 10.0.0.131
2026-04-23 02:37:10,007 - DEBUG - Route for VM MAC 02:9c:a6:a0:8c:f2 is consistent in agent 10.0.0.131
2026-04-23 02:37:13,223 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1033ms')
2026-04-23 02:37:13,223 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-84765133 failed!
2026-04-23 02:37:13,238 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 216.7.3.129 and allocation pool is NOT set
2026-04-23 02:37:13,238 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 3db9:df63:7da1:b110:60ea:c0c3:0:1 and allocation pool is NOT set
2026-04-23 02:37:17,317 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms')
2026-04-23 02:37:17,317 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-84765133 failed!
2026-04-23 02:37:17,332 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 216.7.3.129 and allocation pool is NOT set
2026-04-23 02:37:17,332 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 3db9:df63:7da1:b110:60ea:c0c3:0:1 and allocation pool is NOT set
2026-04-23 02:37:21,415 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2026-04-23 02:37:21,415 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-84765133 failed!
2026-04-23 02:37:21,429 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 216.7.3.129 and allocation pool is NOT set
2026-04-23 02:37:21,429 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 3db9:df63:7da1:b110:60ea:c0c3:0:1 and allocation pool is NOT set
2026-04-23 02:37:25,514 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms')
2026-04-23 02:37:25,514 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-84765133 failed!
2026-04-23 02:37:25,569 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 216.7.3.129 and allocation pool is NOT set
2026-04-23 02:37:25,569 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 3db9:df63:7da1:b110:60ea:c0c3:0:1 and allocation pool is NOT set
2026-04-23 02:37:29,638 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1007ms')
2026-04-23 02:37:29,638 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-84765133 failed!
2026-04-23 02:37:29,657 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 216.7.3.129 and allocation pool is NOT set
2026-04-23 02:37:29,657 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 3db9:df63:7da1:b110:60ea:c0c3:0:1 and allocation pool is NOT set
2026-04-23 02:37:33,737 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2026-04-23 02:37:33,737 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-84765133 failed!
2026-04-23 02:37:33,750 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 216.7.3.129 and allocation pool is NOT set
2026-04-23 02:37:33,750 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 3db9:df63:7da1:b110:60ea:c0c3:0:1 and allocation pool is NOT set
2026-04-23 02:37:37,832 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms')
2026-04-23 02:37:37,832 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-84765133 failed!
2026-04-23 02:37:37,845 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 216.7.3.129 and allocation pool is NOT set
2026-04-23 02:37:37,845 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 3db9:df63:7da1:b110:60ea:c0c3:0:1 and allocation pool is NOT set
2026-04-23 02:37:41,926 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms')
2026-04-23 02:37:41,926 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-84765133 failed!
2026-04-23 02:37:41,941 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 216.7.3.129 and allocation pool is NOT set
2026-04-23 02:37:41,941 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 3db9:df63:7da1:b110:60ea:c0c3:0:1 and allocation pool is NOT set
2026-04-23 02:37:46,022 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1012ms')
2026-04-23 02:37:46,022 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-84765133 failed!
2026-04-23 02:37:46,035 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 216.7.3.129 and allocation pool is NOT set
2026-04-23 02:37:46,035 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 3db9:df63:7da1:b110:60ea:c0c3:0:1 and allocation pool is NOT set
2026-04-23 02:37:50,116 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms')
2026-04-23 02:37:50,116 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-84765133 failed!
2026-04-23 02:37:50,130 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 216.7.3.129 and allocation pool is NOT set
2026-04-23 02:37:50,130 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 3db9:df63:7da1:b110:60ea:c0c3:0:1 and allocation pool is NOT set
2026-04-23 02:37:54,216 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1020ms')
2026-04-23 02:37:54,216 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-84765133 failed!
2026-04-23 02:37:54,231 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 216.7.3.129 and allocation pool is NOT set
2026-04-23 02:37:54,231 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 3db9:df63:7da1:b110:60ea:c0c3:0:1 and allocation pool is NOT set
2026-04-23 02:37:58,310 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1012ms')
2026-04-23 02:37:58,310 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-84765133 failed!
2026-04-23 02:37:58,325 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 216.7.3.129 and allocation pool is NOT set
2026-04-23 02:37:58,325 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 is 3db9:df63:7da1:b110:60ea:c0c3:0:1 and allocation pool is NOT set
2026-04-23 02:38:00,390 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=7.86 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=4.07 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 4.066/5.960/7.855/1.894 ms')
2026-04-23 02:38:00,390 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-84765133 passed
2026-04-23 02:38:00,390 - INFO - VM ctest-VM11-84765133 verifications in Compute nodes passed
2026-04-23 02:38:00,654 - DEBUG - Validated VM route 216.7.3.131 in vrouter of 10.0.0.131
2026-04-23 02:38:00,660 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-04-23 02:38:00,661 - INFO - Validated routes of VM ctest-VM11-84765133 in all vrouters
2026-04-23 02:38:01,104 - DEBUG - Starting all layer2 verification in 10.0.0.23 Control Node
2026-04-23 02:38:01,234 - DEBUG - Layer2 route found for VM MAC 02:9c:a6:a0:8c:f2 in Control-node 10.0.0.23
2026-04-23 02:38:01,234 - DEBUG - L2 Label for VM ctest-VM11-84765133 same between Control-node 10.0.0.23 and Agent, Expected: 29, Seen: 29
2026-04-23 02:38:01,346 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.23, not supported
2026-04-23 02:38:01,346 - DEBUG - Starting all layer2 verification in 10.0.0.130 Control Node
2026-04-23 02:38:01,471 - DEBUG - Layer2 route found for VM MAC 02:9c:a6:a0:8c:f2 in Control-node 10.0.0.130
2026-04-23 02:38:01,471 - DEBUG - L2 Label for VM ctest-VM11-84765133 same between Control-node 10.0.0.130 and Agent, Expected: 29, Seen: 29
2026-04-23 02:38:01,584 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.130, not supported
2026-04-23 02:38:01,584 - INFO - Verification in Control-nodes for VM ctest-VM11-84765133 passed
2026-04-23 02:38:01,584 - DEBUG - Verifying the vm in opserver
2026-04-23 02:38:01,584 - DEBUG - Verifying in collector 10.0.0.249 ...
2026-04-23 02:38:01,584 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/7542d49a-be25-47a0-9872-4e930843c4a6?flat
2026-04-23 02:38:01,608 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-48672592:9ca6a08c-f203-4b11-97a5-c8234d35167e?flat
2026-04-23 02:38:01,632 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-48672592:9ca6a08c-f203-4b11-97a5-c8234d35167e?flat
2026-04-23 02:38:01,657 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-48672592:9ca6a08c-f203-4b11-97a5-c8234d35167e?flat
2026-04-23 02:38:01,679 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/7542d49a-be25-47a0-9872-4e930843c4a6?flat
2026-04-23 02:38:01,696 - DEBUG - Verifying vm in vn uve
2026-04-23 02:38:01,697 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-48672592:9ca6a08c-f203-4b11-97a5-c8234d35167e?flat
2026-04-23 02:38:01,720 - DEBUG - VM uve shows interface as {'port_mirror_enabled': False, 'active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643', 'fixed_ip6_list': ['3db9:df63:7da1:b110:60ea:c0c3:0:3'], 'fixed_ip4_list': ['216.7.3.131'], 'l2_active': True, 'ip4_active': True, 'label': 25, 'ip_address': '216.7.3.131', 'ip6_active': True, 'is_health_check_active': True, 'vn_uuid': 'b8d67109-1a59-4f61-aaa7-0465eeb4eef9', 'admin_state': True, 'ip6_address': '3db9:df63:7da1:b110:60ea:c0c3:0:3', 'uuid': '9ca6a08c-f203-4b11-97a5-c8234d35167e', 'tx_vlan': 65535, '__T': 1776911833722366, 'rx_vlan': 65535, 'mac_address': '02:9c:a6:a0:8c:f2', 'vhostuser_mode': 0, 'gateway': '216.7.3.129', 'vm_uuid': '7542d49a-be25-47a0-9872-4e930843c4a6', 'vm_name': 'ctest-VM11-84765133'}
2026-04-23 02:38:01,720 - DEBUG - VM uve shows ip address as ['216.7.3.131', '3db9:df63:7da1:b110:60ea:c0c3:0:3']
2026-04-23 02:38:01,720 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643
2026-04-23 02:38:01,721 - INFO - Verifying through opserver in 10.0.0.23
2026-04-23 02:38:01,721 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643?flat
2026-04-23 02:38:01,748 - DEBUG - expected vm list 7542d49a-be25-47a0-9872-4e930843c4a6
2026-04-23 02:38:01,748 - DEBUG - Extracted vm list ['7542d49a-be25-47a0-9872-4e930843c4a6']
2026-04-23 02:38:01,748 - INFO - VM 7542d49a-be25-47a0-9872-4e930843c4a6 is present in default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643
2026-04-23 02:38:01,748 - DEBUG - Verifying vm in vrouter uve
2026-04-23 02:38:01,748 - DEBUG - Getting info from collector 10.0.0.249..
2026-04-23 02:38:01,749 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/7542d49a-be25-47a0-9872-4e930843c4a6?flat
2026-04-23 02:38:01,768 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5672-2']
2026-04-23 02:38:01,769 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:38:01,815 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:38:01,859 - DEBUG - VM 7542d49a-be25-47a0-9872-4e930843c4a6 is present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:38:01,860 - DEBUG - Validated that VM ctest-VM11-84765133 is in Vrouter an-jenkins-deploy-platform-ansible-os-5672-2 UVE
2026-04-23 02:38:01,860 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-04-23 02:38:01,870 - DEBUG - Expected tap interface of VM uuid 7542d49a-be25-47a0-9872-4e930843c4a6 is default-domain:ctest-TestPolicyAclIpv4v6-48672592:9ca6a08c-f203-4b11-97a5-c8234d35167e
2026-04-23 02:38:01,871 - DEBUG - Expected VN of VM uuid 7542d49a-be25-47a0-9872-4e930843c4a6 is default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643
2026-04-23 02:38:01,871 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:38:01,925 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:38:01,967 - DEBUG - VM 7542d49a-be25-47a0-9872-4e930843c4a6 is present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:38:01,967 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-48672592:9ca6a08c-f203-4b11-97a5-c8234d35167e of vm 7542d49a-be25-47a0-9872-4e930843c4a6 is present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:38:01,968 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 of vm 7542d49a-be25-47a0-9872-4e930843c4a6 is present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:38:01,968 - DEBUG - Verifying the 7542d49a-be25-47a0-9872-4e930843c4a6 virtual network link through opserver 10.0.0.23
2026-04-23 02:38:01,968 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-04-23 02:38:01,989 - DEBUG - VM link and name as {'name': '7542d49a-be25-47a0-9872-4e930843c4a6', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/7542d49a-be25-47a0-9872-4e930843c4a6?flat'}
2026-04-23 02:38:01,990 - DEBUG - Verifying the 7542d49a-be25-47a0-9872-4e930843c4a6 virtual network link through opserver 10.0.0.130
2026-04-23 02:38:01,990 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines
2026-04-23 02:38:02,004 - DEBUG - VM link and name as {'name': '7542d49a-be25-47a0-9872-4e930843c4a6', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-machine/7542d49a-be25-47a0-9872-4e930843c4a6?flat'}
2026-04-23 02:38:02,004 - DEBUG - Verifying the 7542d49a-be25-47a0-9872-4e930843c4a6 virtual network link through opserver 10.0.0.249
2026-04-23 02:38:02,005 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-04-23 02:38:02,021 - DEBUG - VM link and name as {'name': '7542d49a-be25-47a0-9872-4e930843c4a6', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-machine/7542d49a-be25-47a0-9872-4e930843c4a6?flat'}
2026-04-23 02:38:02,021 - INFO - VM ctest-VM11-84765133 validations in Opserver passed
2026-04-23 02:38:02,125 - DEBUG - VM is in ACTIVE state now
2026-04-23 02:38:02,125 - INFO - VM name : ctest-VM21-60947672
2026-04-23 02:38:02,209 - DEBUG - VM ctest-VM21-60947672 ID is b3730ad5-9626-44e5-bbfe-b6103b6b6c68
2026-04-23 02:38:02,292 - DEBUG - VM is in ACTIVE state now
2026-04-23 02:38:02,292 - DEBUG - VM ctest-VM21-60947672 launched on Node an-jenkins-deploy-platform-ansible-os-5672-1
2026-04-23 02:38:02,369 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/b3730ad5-9626-44e5-bbfe-b6103b6b6c68
2026-04-23 02:38:02,382 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/b3730ad5-9626-44e5-bbfe-b6103b6b6c68
2026-04-23 02:38:02,391 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/f154f68f-dfa9-439d-bebd-0900ab48b57c
2026-04-23 02:38:02,401 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/b3730ad5-9626-44e5-bbfe-b6103b6b6c68
2026-04-23 02:38:02,408 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/f154f68f-dfa9-439d-bebd-0900ab48b57c
2026-04-23 02:38:02,419 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/19d2ed85-be8f-4e0c-8be6-3130c056e5b0
2026-04-23 02:38:02,429 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/c5f70b4c-70cd-43c2-b67f-0e7cb8ac4f18
2026-04-23 02:38:02,439 - DEBUG - Verifying in api server 10.0.0.249
2026-04-23 02:38:02,439 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/b3730ad5-9626-44e5-bbfe-b6103b6b6c68
2026-04-23 02:38:02,447 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/f154f68f-dfa9-439d-bebd-0900ab48b57c
2026-04-23 02:38:02,458 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/19d2ed85-be8f-4e0c-8be6-3130c056e5b0
2026-04-23 02:38:02,467 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/c5f70b4c-70cd-43c2-b67f-0e7cb8ac4f18
2026-04-23 02:38:02,475 - INFO - VM ctest-VM21-60947672 verfication in all API Servers passed
2026-04-23 02:38:02,496 - DEBUG - VM ctest-VM21-60947672 Tap interface: {'index': '3', 'name': 'tapf154f68f-df', 'uuid': 'f154f68f-dfa9-439d-bebd-0900ab48b57c', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616:ctest-VN2-57009616', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616', 'vm_uuid': 'b3730ad5-9626-44e5-bbfe-b6103b6b6c68', 'vm_name': 'ctest-VM21-60947672', 'ip_addr': '75.13.77.131', 'mac_addr': '02:f1:54:f6:8f:df', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '44', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:f154f68f-dfa9-439d-bebd-0900ab48b57c', 'sg_uuid_list': ['6b255360-dd84-4e03-b7fd-a02143d569e2'], 'static_route_list': None, 'vm_project_uuid': '16088fd5-65c1-4716-a13d-2ade51022390', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '3b5b:541d:29a4:f336:cd43:71a3:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-04-23 02:38:02,496 - DEBUG - Agent 10.0.0.73 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616:ctest-VN2-57009616
2026-04-23 02:38:02,506 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616:ctest-VN2-57009616', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616', 'table_label': '-1', 'vxlan_id': '15', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.18:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-04-23 02:38:02,599 - DEBUG - VM ctest-VM21-60947672 : Tap interface tapf154f68f-df is set to Active
2026-04-23 02:38:02,599 - DEBUG - Tap interface tapf154f68f-df detail : {'index': '3', 'name': 'tapf154f68f-df', 'uuid': 'f154f68f-dfa9-439d-bebd-0900ab48b57c', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616:ctest-VN2-57009616', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616', 'vm_uuid': 'b3730ad5-9626-44e5-bbfe-b6103b6b6c68', 'vm_name': 'ctest-VM21-60947672', 'ip_addr': '75.13.77.131', 'mac_addr': '02:f1:54:f6:8f:df', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '44', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:f154f68f-dfa9-439d-bebd-0900ab48b57c', 'sg_uuid_list': ['6b255360-dd84-4e03-b7fd-a02143d569e2'], 'static_route_list': None, 'vm_project_uuid': '16088fd5-65c1-4716-a13d-2ade51022390', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '3b5b:541d:29a4:f336:cd43:71a3:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-04-23 02:38:02,714 - DEBUG - Starting Layer 2 verification in Agent
2026-04-23 02:38:02,727 - DEBUG - Layer 2 path is seen for VM MAC 02:f1:54:f6:8f:df in agent 10.0.0.73
2026-04-23 02:38:02,727 - DEBUG - Active layer 2 route in agent is present for VMI tapf154f68f-df
2026-04-23 02:38:02,727 - DEBUG - L2 label(29) matches bw route table and interface table
2026-04-23 02:38:02,735 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616 is found in Agent of node 10.0.0.73
2026-04-23 02:38:02,891 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616 is consistent in agent 10.0.0.73
2026-04-23 02:38:02,891 - DEBUG - Route for VM IP ['3b5b:541d:29a4:f336:cd43:71a3:0:3', '75.13.77.131'] is consistent in agent 10.0.0.73
2026-04-23 02:38:02,891 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616 verification for VM ctest-VM21-60947672 in Agent 10.0.0.73 passed
2026-04-23 02:38:03,010 - DEBUG - Starting all layer 2 verification in agent 10.0.0.73
2026-04-23 02:38:03,022 - DEBUG - Route for VM MAC 02:f1:54:f6:8f:df is consistent in agent 10.0.0.73
2026-04-23 02:38:06,211 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms')
2026-04-23 02:38:06,211 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-60947672 failed!
2026-04-23 02:38:06,227 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616 is 75.13.77.129 and allocation pool is NOT set
2026-04-23 02:38:06,227 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616 is 3b5b:541d:29a4:f336:cd43:71a3:0:1 and allocation pool is NOT set
2026-04-23 02:38:08,297 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=6.83 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.33 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.332/4.582/6.833/2.250 ms')
2026-04-23 02:38:08,297 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-60947672 passed
2026-04-23 02:38:08,297 - INFO - VM ctest-VM21-60947672 verifications in Compute nodes passed
2026-04-23 02:38:08,525 - DEBUG - Validated VM route 75.13.77.131 in vrouter of 10.0.0.73
2026-04-23 02:38:08,532 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-04-23 02:38:08,532 - INFO - Validated routes of VM ctest-VM21-60947672 in all vrouters
2026-04-23 02:38:08,956 - DEBUG - Starting all layer2 verification in 10.0.0.23 Control Node
2026-04-23 02:38:09,074 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.23, not supported
2026-04-23 02:38:09,201 - DEBUG - Layer2 route found for VM MAC 02:f1:54:f6:8f:df in Control-node 10.0.0.23
2026-04-23 02:38:09,202 - DEBUG - L2 Label for VM ctest-VM21-60947672 same between Control-node 10.0.0.23 and Agent, Expected: 29, Seen: 29
2026-04-23 02:38:09,202 - DEBUG - Starting all layer2 verification in 10.0.0.130 Control Node
2026-04-23 02:38:09,314 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.130, not supported
2026-04-23 02:38:09,439 - DEBUG - Layer2 route found for VM MAC 02:f1:54:f6:8f:df in Control-node 10.0.0.130
2026-04-23 02:38:09,439 - DEBUG - L2 Label for VM ctest-VM21-60947672 same between Control-node 10.0.0.130 and Agent, Expected: 29, Seen: 29
2026-04-23 02:38:09,439 - INFO - Verification in Control-nodes for VM ctest-VM21-60947672 passed
2026-04-23 02:38:09,439 - DEBUG - Verifying the vm in opserver
2026-04-23 02:38:09,439 - DEBUG - Verifying in collector 10.0.0.249 ...
2026-04-23 02:38:09,439 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/b3730ad5-9626-44e5-bbfe-b6103b6b6c68?flat
2026-04-23 02:38:09,457 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-48672592:f154f68f-dfa9-439d-bebd-0900ab48b57c?flat
2026-04-23 02:38:09,477 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-48672592:f154f68f-dfa9-439d-bebd-0900ab48b57c?flat
2026-04-23 02:38:09,499 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-48672592:f154f68f-dfa9-439d-bebd-0900ab48b57c?flat
2026-04-23 02:38:09,521 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/b3730ad5-9626-44e5-bbfe-b6103b6b6c68?flat
2026-04-23 02:38:09,536 - DEBUG - Verifying vm in vn uve
2026-04-23 02:38:09,536 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-48672592:f154f68f-dfa9-439d-bebd-0900ab48b57c?flat
2026-04-23 02:38:09,557 - DEBUG - VM uve shows interface as {'vn_uuid': '16abf06a-a71a-43af-8399-014e3050bf3b', 'ip6_address': '3b5b:541d:29a4:f336:cd43:71a3:0:3', 'admin_state': True, 'ip_address': '75.13.77.131', 'vhostuser_mode': 0, 'mac_address': '02:f1:54:f6:8f:df', 'gateway': '75.13.77.129', 'ip4_active': True, 'uuid': 'f154f68f-dfa9-439d-bebd-0900ab48b57c', 'vm_name': 'ctest-VM21-60947672', 'rx_vlan': 65535, 'fixed_ip6_list': ['3b5b:541d:29a4:f336:cd43:71a3:0:3'], 'port_mirror_enabled': False, 'active': True, 'tx_vlan': 65535, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616', 'label': 25, 'l2_active': True, '__T': 1776911864461088, 'fixed_ip4_list': ['75.13.77.131'], 'ip6_active': True, 'is_health_check_active': True, 'vm_uuid': 'b3730ad5-9626-44e5-bbfe-b6103b6b6c68'}
2026-04-23 02:38:09,557 - DEBUG - VM uve shows ip address as ['75.13.77.131', '3b5b:541d:29a4:f336:cd43:71a3:0:3']
2026-04-23 02:38:09,557 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616
2026-04-23 02:38:09,558 - INFO - Verifying through opserver in 10.0.0.23
2026-04-23 02:38:09,558 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616?flat
2026-04-23 02:38:09,591 - DEBUG - expected vm list b3730ad5-9626-44e5-bbfe-b6103b6b6c68
2026-04-23 02:38:09,591 - DEBUG - Extracted vm list ['b3730ad5-9626-44e5-bbfe-b6103b6b6c68']
2026-04-23 02:38:09,591 - INFO - VM b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is present in default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616
2026-04-23 02:38:09,592 - DEBUG - Verifying vm in vrouter uve
2026-04-23 02:38:09,592 - DEBUG - Getting info from collector 10.0.0.249..
2026-04-23 02:38:09,592 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/b3730ad5-9626-44e5-bbfe-b6103b6b6c68?flat
2026-04-23 02:38:09,609 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5672-1']
2026-04-23 02:38:09,609 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-1:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:38:09,656 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-1?flat
2026-04-23 02:38:09,698 - DEBUG - VM b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is present in vrouter an-jenkins-deploy-platform-ansible-os-5672-1 uve
2026-04-23 02:38:09,698 - DEBUG - Validated that VM ctest-VM21-60947672 is in Vrouter an-jenkins-deploy-platform-ansible-os-5672-1 UVE
2026-04-23 02:38:09,698 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-04-23 02:38:09,709 - DEBUG - Expected tap interface of VM uuid b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is default-domain:ctest-TestPolicyAclIpv4v6-48672592:f154f68f-dfa9-439d-bebd-0900ab48b57c
2026-04-23 02:38:09,709 - DEBUG - Expected VN of VM uuid b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616
2026-04-23 02:38:09,709 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-1:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:38:09,748 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-1?flat
2026-04-23 02:38:09,786 - DEBUG - VM b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is present in vrouter an-jenkins-deploy-platform-ansible-os-5672-1 uve
2026-04-23 02:38:09,786 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-48672592:f154f68f-dfa9-439d-bebd-0900ab48b57c of vm b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is present in vrouter an-jenkins-deploy-platform-ansible-os-5672-1 uve
2026-04-23 02:38:09,786 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616 of vm b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is present in vrouter an-jenkins-deploy-platform-ansible-os-5672-1 uve
2026-04-23 02:38:09,786 - DEBUG - Verifying the b3730ad5-9626-44e5-bbfe-b6103b6b6c68 virtual network link through opserver 10.0.0.23
2026-04-23 02:38:09,786 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-04-23 02:38:09,798 - DEBUG - VM link and name as {'name': 'b3730ad5-9626-44e5-bbfe-b6103b6b6c68', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/b3730ad5-9626-44e5-bbfe-b6103b6b6c68?flat'}
2026-04-23 02:38:09,798 - DEBUG - Verifying the b3730ad5-9626-44e5-bbfe-b6103b6b6c68 virtual network link through opserver 10.0.0.130
2026-04-23 02:38:09,798 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines
2026-04-23 02:38:09,814 - DEBUG - VM link and name as {'name': 'b3730ad5-9626-44e5-bbfe-b6103b6b6c68', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-machine/b3730ad5-9626-44e5-bbfe-b6103b6b6c68?flat'}
2026-04-23 02:38:09,814 - DEBUG - Verifying the b3730ad5-9626-44e5-bbfe-b6103b6b6c68 virtual network link through opserver 10.0.0.249
2026-04-23 02:38:09,814 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-04-23 02:38:09,828 - DEBUG - VM link and name as {'name': 'b3730ad5-9626-44e5-bbfe-b6103b6b6c68', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-machine/b3730ad5-9626-44e5-bbfe-b6103b6b6c68?flat'}
2026-04-23 02:38:09,828 - INFO - VM ctest-VM21-60947672 validations in Opserver passed
2026-04-23 02:38:09,915 - DEBUG - VM is in ACTIVE state now
2026-04-23 02:38:09,915 - INFO - VM name : ctest-VM31-44157703
2026-04-23 02:38:10,008 - DEBUG - VM ctest-VM31-44157703 ID is bcb4c44a-fd28-4f17-a145-cb4de0d7762e
2026-04-23 02:38:10,099 - DEBUG - VM is in ACTIVE state now
2026-04-23 02:38:10,099 - DEBUG - VM ctest-VM31-44157703 launched on Node an-jenkins-deploy-platform-ansible-os-5672-2
2026-04-23 02:38:10,183 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/bcb4c44a-fd28-4f17-a145-cb4de0d7762e
2026-04-23 02:38:10,193 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/bcb4c44a-fd28-4f17-a145-cb4de0d7762e
2026-04-23 02:38:10,202 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/ca3025a9-9872-47ce-814a-e6525c210165
2026-04-23 02:38:10,214 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/bcb4c44a-fd28-4f17-a145-cb4de0d7762e
2026-04-23 02:38:10,224 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/ca3025a9-9872-47ce-814a-e6525c210165
2026-04-23 02:38:10,235 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/9739fa43-a970-44f7-9f5e-503bfb958352
2026-04-23 02:38:10,246 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/af5b5d73-6b86-4735-9fdd-4e209ea794a6
2026-04-23 02:38:10,257 - DEBUG - Verifying in api server 10.0.0.249
2026-04-23 02:38:10,258 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/bcb4c44a-fd28-4f17-a145-cb4de0d7762e
2026-04-23 02:38:10,268 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/ca3025a9-9872-47ce-814a-e6525c210165
2026-04-23 02:38:10,281 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/9739fa43-a970-44f7-9f5e-503bfb958352
2026-04-23 02:38:10,290 - DEBUG - Requesting: http://10.0.0.249:8082/instance-ip/af5b5d73-6b86-4735-9fdd-4e209ea794a6
2026-04-23 02:38:10,299 - INFO - VM ctest-VM31-44157703 verfication in all API Servers passed
2026-04-23 02:38:10,327 - DEBUG - VM ctest-VM31-44157703 Tap interface: {'index': '4', 'name': 'tapca3025a9-98', 'uuid': 'ca3025a9-9872-47ce-814a-e6525c210165', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924:ctest-VN3-54060924', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '16', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924', 'vm_uuid': 'bcb4c44a-fd28-4f17-a145-cb4de0d7762e', 'vm_name': 'ctest-VM31-44157703', 'ip_addr': '89.51.149.131', 'mac_addr': '02:ca:30:25:a9:98', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '51', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ca3025a9-9872-47ce-814a-e6525c210165', 'sg_uuid_list': ['6b255360-dd84-4e03-b7fd-a02143d569e2'], 'static_route_list': None, 'vm_project_uuid': '16088fd5-65c1-4716-a13d-2ade51022390', 'admin_state': 'Enabled', 'flow_key_idx': '40', 'allowed_address_pair_list': None, 'ip6_addr': '20fd:4a43:19ca:d929:7e59:c358:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-04-23 02:38:10,327 - DEBUG - Agent 10.0.0.131 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924:ctest-VN3-54060924
2026-04-23 02:38:10,333 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924:ctest-VN3-54060924', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924', 'table_label': '-1', 'vxlan_id': '16', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.192:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-04-23 02:38:10,442 - DEBUG - VM ctest-VM31-44157703 : Tap interface tapca3025a9-98 is set to Active
2026-04-23 02:38:10,442 - DEBUG - Tap interface tapca3025a9-98 detail : {'index': '4', 'name': 'tapca3025a9-98', 'uuid': 'ca3025a9-9872-47ce-814a-e6525c210165', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924:ctest-VN3-54060924', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '16', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924', 'vm_uuid': 'bcb4c44a-fd28-4f17-a145-cb4de0d7762e', 'vm_name': 'ctest-VM31-44157703', 'ip_addr': '89.51.149.131', 'mac_addr': '02:ca:30:25:a9:98', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '51', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ca3025a9-9872-47ce-814a-e6525c210165', 'sg_uuid_list': ['6b255360-dd84-4e03-b7fd-a02143d569e2'], 'static_route_list': None, 'vm_project_uuid': '16088fd5-65c1-4716-a13d-2ade51022390', 'admin_state': 'Enabled', 'flow_key_idx': '40', 'allowed_address_pair_list': None, 'ip6_addr': '20fd:4a43:19ca:d929:7e59:c358:0:3', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-04-23 02:38:10,556 - DEBUG - Starting Layer 2 verification in Agent
2026-04-23 02:38:10,579 - DEBUG - Layer 2 path is seen for VM MAC 02:ca:30:25:a9:98 in agent 10.0.0.131
2026-04-23 02:38:10,580 - DEBUG - Active layer 2 route in agent is present for VMI tapca3025a9-98
2026-04-23 02:38:10,580 - DEBUG - L2 label(36) matches bw route table and interface table
2026-04-23 02:38:10,593 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924 is found in Agent of node 10.0.0.131
2026-04-23 02:38:10,726 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924 is consistent in agent 10.0.0.131
2026-04-23 02:38:10,726 - DEBUG - Route for VM IP ['89.51.149.131', '20fd:4a43:19ca:d929:7e59:c358:0:3'] is consistent in agent 10.0.0.131
2026-04-23 02:38:10,726 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924 verification for VM ctest-VM31-44157703 in Agent 10.0.0.131 passed
2026-04-23 02:38:10,799 - DEBUG - Starting all layer 2 verification in agent 10.0.0.131
2026-04-23 02:38:10,838 - DEBUG - Route for VM MAC 02:ca:30:25:a9:98 is consistent in agent 10.0.0.131
2026-04-23 02:38:12,009 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=5.03 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.20 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.197/3.114/5.031/1.917 ms')
2026-04-23 02:38:12,009 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-44157703 passed
2026-04-23 02:38:12,009 - INFO - VM ctest-VM31-44157703 verifications in Compute nodes passed
2026-04-23 02:38:12,234 - DEBUG - Validated VM route 89.51.149.131 in vrouter of 10.0.0.131
2026-04-23 02:38:12,238 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-04-23 02:38:12,238 - INFO - Validated routes of VM ctest-VM31-44157703 in all vrouters
2026-04-23 02:38:12,742 - DEBUG - Starting all layer2 verification in 10.0.0.23 Control Node
2026-04-23 02:38:12,869 - DEBUG - Layer2 route found for VM MAC 02:ca:30:25:a9:98 in Control-node 10.0.0.23
2026-04-23 02:38:12,869 - DEBUG - L2 Label for VM ctest-VM31-44157703 same between Control-node 10.0.0.23 and Agent, Expected: 36, Seen: 36
2026-04-23 02:38:12,986 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.23, not supported
2026-04-23 02:38:12,987 - DEBUG - Starting all layer2 verification in 10.0.0.130 Control Node
2026-04-23 02:38:13,112 - DEBUG - Layer2 route found for VM MAC 02:ca:30:25:a9:98 in Control-node 10.0.0.130
2026-04-23 02:38:13,112 - DEBUG - L2 Label for VM ctest-VM31-44157703 same between Control-node 10.0.0.130 and Agent, Expected: 36, Seen: 36
2026-04-23 02:38:13,228 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.130, not supported
2026-04-23 02:38:13,228 - INFO - Verification in Control-nodes for VM ctest-VM31-44157703 passed
2026-04-23 02:38:13,228 - DEBUG - Verifying the vm in opserver
2026-04-23 02:38:13,228 - DEBUG - Verifying in collector 10.0.0.249 ...
2026-04-23 02:38:13,228 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/bcb4c44a-fd28-4f17-a145-cb4de0d7762e?flat
2026-04-23 02:38:13,245 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ca3025a9-9872-47ce-814a-e6525c210165?flat
2026-04-23 02:38:13,267 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ca3025a9-9872-47ce-814a-e6525c210165?flat
2026-04-23 02:38:13,287 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ca3025a9-9872-47ce-814a-e6525c210165?flat
2026-04-23 02:38:13,308 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/bcb4c44a-fd28-4f17-a145-cb4de0d7762e?flat
2026-04-23 02:38:13,324 - DEBUG - Verifying vm in vn uve
2026-04-23 02:38:13,324 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ca3025a9-9872-47ce-814a-e6525c210165?flat
2026-04-23 02:38:13,345 - DEBUG - VM uve shows interface as {'port_mirror_enabled': False, 'active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924', 'fixed_ip6_list': ['20fd:4a43:19ca:d929:7e59:c358:0:3'], 'fixed_ip4_list': ['89.51.149.131'], 'l2_active': True, 'ip4_active': True, 'label': 32, 'ip_address': '89.51.149.131', 'ip6_active': True, 'is_health_check_active': True, 'vn_uuid': '77e044ce-e21b-4c6a-a80a-157d9873228d', 'admin_state': True, 'ip6_address': '20fd:4a43:19ca:d929:7e59:c358:0:3', 'uuid': 'ca3025a9-9872-47ce-814a-e6525c210165', 'tx_vlan': 65535, '__T': 1776911833722407, 'rx_vlan': 65535, 'mac_address': '02:ca:30:25:a9:98', 'vhostuser_mode': 0, 'gateway': '89.51.149.129', 'vm_uuid': 'bcb4c44a-fd28-4f17-a145-cb4de0d7762e', 'vm_name': 'ctest-VM31-44157703'}
2026-04-23 02:38:13,345 - DEBUG - VM uve shows ip address as ['89.51.149.131', '20fd:4a43:19ca:d929:7e59:c358:0:3']
2026-04-23 02:38:13,345 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924
2026-04-23 02:38:13,345 - INFO - Verifying through opserver in 10.0.0.23
2026-04-23 02:38:13,345 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924?flat
2026-04-23 02:38:13,367 - DEBUG - expected vm list bcb4c44a-fd28-4f17-a145-cb4de0d7762e
2026-04-23 02:38:13,367 - DEBUG - Extracted vm list ['bcb4c44a-fd28-4f17-a145-cb4de0d7762e']
2026-04-23 02:38:13,367 - INFO - VM bcb4c44a-fd28-4f17-a145-cb4de0d7762e is present in default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924
2026-04-23 02:38:13,367 - DEBUG - Verifying vm in vrouter uve
2026-04-23 02:38:13,368 - DEBUG - Getting info from collector 10.0.0.249..
2026-04-23 02:38:13,368 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machine/bcb4c44a-fd28-4f17-a145-cb4de0d7762e?flat
2026-04-23 02:38:13,384 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5672-2']
2026-04-23 02:38:13,385 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:38:13,419 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:38:13,451 - DEBUG - VM bcb4c44a-fd28-4f17-a145-cb4de0d7762e is present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:38:13,451 - DEBUG - Validated that VM ctest-VM31-44157703 is in Vrouter an-jenkins-deploy-platform-ansible-os-5672-2 UVE
2026-04-23 02:38:13,451 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-04-23 02:38:13,460 - DEBUG - Expected tap interface of VM uuid bcb4c44a-fd28-4f17-a145-cb4de0d7762e is default-domain:ctest-TestPolicyAclIpv4v6-48672592:ca3025a9-9872-47ce-814a-e6525c210165
2026-04-23 02:38:13,460 - DEBUG - Expected VN of VM uuid bcb4c44a-fd28-4f17-a145-cb4de0d7762e is default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924
2026-04-23 02:38:13,460 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:38:13,494 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:38:13,531 - DEBUG - VM bcb4c44a-fd28-4f17-a145-cb4de0d7762e is present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:38:13,531 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-48672592:ca3025a9-9872-47ce-814a-e6525c210165 of vm bcb4c44a-fd28-4f17-a145-cb4de0d7762e is present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:38:13,531 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924 of vm bcb4c44a-fd28-4f17-a145-cb4de0d7762e is present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:38:13,531 - DEBUG - Verifying the bcb4c44a-fd28-4f17-a145-cb4de0d7762e virtual network link through opserver 10.0.0.23
2026-04-23 02:38:13,531 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-04-23 02:38:13,545 - DEBUG - VM link and name as {'name': 'bcb4c44a-fd28-4f17-a145-cb4de0d7762e', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/bcb4c44a-fd28-4f17-a145-cb4de0d7762e?flat'}
2026-04-23 02:38:13,545 - DEBUG - Verifying the bcb4c44a-fd28-4f17-a145-cb4de0d7762e virtual network link through opserver 10.0.0.130
2026-04-23 02:38:13,546 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines
2026-04-23 02:38:13,560 - DEBUG - VM link and name as {'name': 'bcb4c44a-fd28-4f17-a145-cb4de0d7762e', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-machine/bcb4c44a-fd28-4f17-a145-cb4de0d7762e?flat'}
2026-04-23 02:38:13,560 - DEBUG - Verifying the bcb4c44a-fd28-4f17-a145-cb4de0d7762e virtual network link through opserver 10.0.0.249
2026-04-23 02:38:13,560 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-04-23 02:38:13,574 - DEBUG - VM link and name as {'name': 'bcb4c44a-fd28-4f17-a145-cb4de0d7762e', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-machine/bcb4c44a-fd28-4f17-a145-cb4de0d7762e?flat'}
2026-04-23 02:38:13,574 - INFO - VM ctest-VM31-44157703 validations in Opserver passed
2026-04-23 02:38:13,574 - INFO - Waiting for VM ctest-VM11-84765133 to be up..
2026-04-23 02:38:13,574 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-23 02:38:13,574 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/7542d49a-be25-47a0-9872-4e930843c4a6
2026-04-23 02:38:13,582 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/9ca6a08c-f203-4b11-97a5-c8234d35167e
2026-04-23 02:38:14,762 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=7.21 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.40 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.398/4.804/7.211/2.406 ms')
2026-04-23 02:38:14,762 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-84765133 passed
2026-04-23 02:38:14,873 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-23 02:38:14,873 - DEBUG - Waiting to SSH to VM ctest-VM11-84765133, IP 216.7.3.131, Port 22
2026-04-23 02:38:14,941 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-04-23 02:38:15,033 - DEBUG - VM ctest-VM11-84765133 is NOT ready for SSH connections, VM status: ACTIVE
2026-04-23 02:38:20,034 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-23 02:38:20,034 - DEBUG - Waiting to SSH to VM ctest-VM11-84765133, IP 216.7.3.131, Port 22
2026-04-23 02:38:20,101 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-04-23 02:38:20,182 - DEBUG - VM ctest-VM11-84765133 is NOT ready for SSH connections, VM status: ACTIVE
2026-04-23 02:38:25,183 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-23 02:38:25,183 - DEBUG - Waiting to SSH to VM ctest-VM11-84765133, IP 216.7.3.131, Port 22
2026-04-23 02:38:25,248 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-04-23 02:38:25,335 - DEBUG - VM ctest-VM11-84765133 is NOT ready for SSH connections, VM status: ACTIVE
2026-04-23 02:38:30,336 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-23 02:38:30,336 - DEBUG - Waiting to SSH to VM ctest-VM11-84765133, IP 216.7.3.131, Port 22
2026-04-23 02:38:30,516 - DEBUG - VM ctest-VM11-84765133 is ready for SSH connections
2026-04-23 02:38:30,516 - INFO - Waiting for VM ctest-VM21-60947672 to be up..
2026-04-23 02:38:30,517 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-23 02:38:30,517 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/b3730ad5-9626-44e5-bbfe-b6103b6b6c68
2026-04-23 02:38:30,532 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/f154f68f-dfa9-439d-bebd-0900ab48b57c
2026-04-23 02:38:31,725 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=1.64 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.72 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.642/2.183/2.724/0.541 ms')
2026-04-23 02:38:31,725 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-60947672 passed
2026-04-23 02:38:31,793 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-23 02:38:31,793 - DEBUG - Waiting to SSH to VM ctest-VM21-60947672, IP 75.13.77.131, Port 22
2026-04-23 02:38:31,966 - DEBUG - VM ctest-VM21-60947672 is ready for SSH connections
2026-04-23 02:38:31,966 - INFO - Waiting for VM ctest-VM31-44157703 to be up..
2026-04-23 02:38:31,966 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-23 02:38:31,966 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/bcb4c44a-fd28-4f17-a145-cb4de0d7762e
2026-04-23 02:38:31,975 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/ca3025a9-9872-47ce-814a-e6525c210165
2026-04-23 02:38:33,155 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.51 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.789 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.789/1.648/2.508/0.859 ms')
2026-04-23 02:38:33,155 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-44157703 passed
2026-04-23 02:38:33,226 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-23 02:38:33,227 - DEBUG - Waiting to SSH to VM ctest-VM31-44157703, IP 89.51.149.131, Port 22
2026-04-23 02:38:33,396 - DEBUG - VM ctest-VM31-44157703 is ready for SSH connections
2026-04-23 02:38:33,396 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 75.13.77.131, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.131, gateway password: c0ntrail123
2026-04-23 02:38:33,396 - DEBUG - ping -s 56 -c 3 -W 1 75.13.77.131
2026-04-23 02:38:58,230 - DEBUG - PING 75.13.77.131 (75.13.77.131) 56(84) bytes of data.
64 bytes from 75.13.77.131: icmp_req=1 ttl=63 time=10.5 ms
64 bytes from 75.13.77.131: icmp_req=2 ttl=63 time=1.62 ms
64 bytes from 75.13.77.131: icmp_req=3 ttl=63 time=1.33 ms
--- 75.13.77.131 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.337/4.519/10.597/4.299 ms
2026-04-23 02:38:58,230 - INFO - Ping to IP 75.13.77.131 from VM ctest-VM11-84765133 passed
2026-04-23 02:38:58,230 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 3b5b:541d:29a4:f336:cd43:71a3:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.131, gateway password: c0ntrail123
2026-04-23 02:38:58,231 - DEBUG - ping6 -s 56 -c 3 -W 1 3b5b:541d:29a4:f336:cd43:71a3:0:3
2026-04-23 02:39:00,799 - DEBUG - PING 3b5b:541d:29a4:f336:cd43:71a3:0:3(3b5b:541d:29a4:f336:cd43:71a3:0:3) 56 data bytes
64 bytes from 3b5b:541d:29a4:f336:cd43:71a3:0:3: icmp_seq=1 ttl=64 time=12.6 ms
64 bytes from 3b5b:541d:29a4:f336:cd43:71a3:0:3: icmp_seq=2 ttl=64 time=4.46 ms
64 bytes from 3b5b:541d:29a4:f336:cd43:71a3:0:3: icmp_seq=3 ttl=64 time=3.65 ms
--- 3b5b:541d:29a4:f336:cd43:71a3:0:3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 3.653/6.909/12.609/4.044 ms
2026-04-23 02:39:00,799 - INFO - Ping to IP 3b5b:541d:29a4:f336:cd43:71a3:0:3 from VM ctest-VM11-84765133 passed
2026-04-23 02:39:00,799 - INFO - Test with src as policy and dst as any PASSED
2026-04-23 02:39:00,799 - INFO - Deleting VM ctest-VM31-44157703
2026-04-23 02:39:00,878 - DEBUG - Verifying in api server 10.0.0.249
2026-04-23 02:39:00,878 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/bcb4c44a-fd28-4f17-a145-cb4de0d7762e
2026-04-23 02:39:00,888 - DEBUG - VM ID bcb4c44a-fd28-4f17-a145-cb4de0d7762e of VM ctest-VM31-44157703 is still found in API Server
2026-04-23 02:39:02,889 - DEBUG - Verifying in api server 10.0.0.249
2026-04-23 02:39:02,890 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/bcb4c44a-fd28-4f17-a145-cb4de0d7762e
2026-04-23 02:39:02,897 - DEBUG - Response Code: 404
2026-04-23 02:39:02,898 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/bcb4c44a-fd28-4f17-a145-cb4de0d7762e
2026-04-23 02:39:02,904 - DEBUG - Response Code: 404
2026-04-23 02:39:02,904 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/bcb4c44a-fd28-4f17-a145-cb4de0d7762e
2026-04-23 02:39:02,912 - DEBUG - Response Code: 404
2026-04-23 02:39:02,912 - INFO - VM ctest-VM31-44157703 is fully removed in API-Server
2026-04-23 02:39:03,064 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-04-23 02:39:03,112 - DEBUG - Validated that vrouter 10.0.0.73 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924
2026-04-23 02:39:03,127 - DEBUG - Validated that vrouter 10.0.0.131 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924
2026-04-23 02:39:03,127 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924
2026-04-23 02:39:03,127 - INFO - VM ctest-VM31-44157703 is removed in Compute, and routes are removed in all compute nodes
2026-04-23 02:39:03,361 - INFO - Routes for VM ctest-VM31-44157703 is removed in all control-nodes
2026-04-23 02:39:03,361 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:03,407 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:39:03,447 - DEBUG - VM bcb4c44a-fd28-4f17-a145-cb4de0d7762e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:39:03,447 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:03,447 - DEBUG - interface for vm bcb4c44a-fd28-4f17-a145-cb4de0d7762e deleted from vrouter uve ...
2026-04-23 02:39:03,447 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:06,448 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:06,488 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:39:06,526 - DEBUG - VM bcb4c44a-fd28-4f17-a145-cb4de0d7762e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:39:06,526 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:06,526 - DEBUG - interface for vm bcb4c44a-fd28-4f17-a145-cb4de0d7762e deleted from vrouter uve ...
2026-04-23 02:39:06,526 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:09,527 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:09,560 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:39:09,590 - DEBUG - VM bcb4c44a-fd28-4f17-a145-cb4de0d7762e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:39:09,590 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:09,590 - DEBUG - interface for vm bcb4c44a-fd28-4f17-a145-cb4de0d7762e deleted from vrouter uve ...
2026-04-23 02:39:09,590 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:12,591 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:12,626 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:39:12,656 - DEBUG - VM bcb4c44a-fd28-4f17-a145-cb4de0d7762e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:39:12,656 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:12,657 - DEBUG - interface for vm bcb4c44a-fd28-4f17-a145-cb4de0d7762e deleted from vrouter uve ...
2026-04-23 02:39:12,657 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:15,657 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:15,691 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:39:15,721 - DEBUG - VM bcb4c44a-fd28-4f17-a145-cb4de0d7762e is not present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:39:15,721 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:15,721 - DEBUG - interface for vm bcb4c44a-fd28-4f17-a145-cb4de0d7762e deleted from vrouter uve ...
2026-04-23 02:39:15,721 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:15,721 - DEBUG - Validated that VM bcb4c44a-fd28-4f17-a145-cb4de0d7762e is removed in Vrouter UVE
2026-04-23 02:39:15,721 - DEBUG - Verifying through opserver in 10.0.0.23
2026-04-23 02:39:15,722 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924?flat
2026-04-23 02:39:15,739 - DEBUG - bcb4c44a-fd28-4f17-a145-cb4de0d7762e BM not in default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN3-54060924 uve
2026-04-23 02:39:15,739 - DEBUG - Validated that VM bcb4c44a-fd28-4f17-a145-cb4de0d7762e is not present in the VN UVE
2026-04-23 02:39:15,739 - DEBUG - Verifying the bcb4c44a-fd28-4f17-a145-cb4de0d7762e virtual network link through opserver 10.0.0.23
2026-04-23 02:39:15,739 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-04-23 02:39:15,751 - INFO - bcb4c44a-fd28-4f17-a145-cb4de0d7762e vm uve deleted from opserver
2026-04-23 02:39:15,751 - DEBUG - Verifying the bcb4c44a-fd28-4f17-a145-cb4de0d7762e virtual network link through opserver 10.0.0.130
2026-04-23 02:39:15,751 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines
2026-04-23 02:39:15,764 - INFO - bcb4c44a-fd28-4f17-a145-cb4de0d7762e vm uve deleted from opserver
2026-04-23 02:39:15,764 - DEBUG - Verifying the bcb4c44a-fd28-4f17-a145-cb4de0d7762e virtual network link through opserver 10.0.0.249
2026-04-23 02:39:15,764 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-04-23 02:39:15,778 - INFO - bcb4c44a-fd28-4f17-a145-cb4de0d7762e vm uve deleted from opserver
2026-04-23 02:39:15,778 - INFO - Validated that VM bcb4c44a-fd28-4f17-a145-cb4de0d7762e is removed from Opserver
2026-04-23 02:39:15,779 - INFO - Deleting VM ctest-VM21-60947672
2026-04-23 02:39:15,852 - DEBUG - Verifying in api server 10.0.0.249
2026-04-23 02:39:15,852 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/b3730ad5-9626-44e5-bbfe-b6103b6b6c68
2026-04-23 02:39:15,865 - DEBUG - VM ID b3730ad5-9626-44e5-bbfe-b6103b6b6c68 of VM ctest-VM21-60947672 is still found in API Server
2026-04-23 02:39:17,866 - DEBUG - Verifying in api server 10.0.0.249
2026-04-23 02:39:17,866 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/b3730ad5-9626-44e5-bbfe-b6103b6b6c68
2026-04-23 02:39:17,873 - DEBUG - Response Code: 404
2026-04-23 02:39:17,873 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/b3730ad5-9626-44e5-bbfe-b6103b6b6c68
2026-04-23 02:39:17,879 - DEBUG - Response Code: 404
2026-04-23 02:39:17,879 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/b3730ad5-9626-44e5-bbfe-b6103b6b6c68
2026-04-23 02:39:17,885 - DEBUG - Response Code: 404
2026-04-23 02:39:17,885 - INFO - VM ctest-VM21-60947672 is fully removed in API-Server
2026-04-23 02:39:18,037 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-04-23 02:39:18,080 - DEBUG - Validated that vrouter 10.0.0.73 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616
2026-04-23 02:39:18,090 - DEBUG - Validated that vrouter 10.0.0.131 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616
2026-04-23 02:39:18,091 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616
2026-04-23 02:39:18,091 - INFO - VM ctest-VM21-60947672 is removed in Compute, and routes are removed in all compute nodes
2026-04-23 02:39:18,312 - INFO - Routes for VM ctest-VM21-60947672 is removed in all control-nodes
2026-04-23 02:39:18,312 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-1:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:18,350 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-1?flat
2026-04-23 02:39:18,381 - DEBUG - VM b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-1 uve
2026-04-23 02:39:18,381 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:18,381 - DEBUG - interface for vm b3730ad5-9626-44e5-bbfe-b6103b6b6c68 deleted from vrouter uve ...
2026-04-23 02:39:18,381 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:21,381 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-1:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:21,415 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-1?flat
2026-04-23 02:39:21,445 - DEBUG - VM b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-1 uve
2026-04-23 02:39:21,445 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:21,445 - DEBUG - interface for vm b3730ad5-9626-44e5-bbfe-b6103b6b6c68 deleted from vrouter uve ...
2026-04-23 02:39:21,445 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:24,446 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-1:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:24,479 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-1?flat
2026-04-23 02:39:24,509 - DEBUG - VM b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-1 uve
2026-04-23 02:39:24,509 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:24,509 - DEBUG - interface for vm b3730ad5-9626-44e5-bbfe-b6103b6b6c68 deleted from vrouter uve ...
2026-04-23 02:39:24,509 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:27,510 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-1:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:27,543 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-1?flat
2026-04-23 02:39:27,571 - DEBUG - VM b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-1 uve
2026-04-23 02:39:27,571 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:27,571 - DEBUG - interface for vm b3730ad5-9626-44e5-bbfe-b6103b6b6c68 deleted from vrouter uve ...
2026-04-23 02:39:27,571 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:30,576 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-1:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:30,614 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-1?flat
2026-04-23 02:39:30,643 - DEBUG - VM b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-1 uve
2026-04-23 02:39:30,643 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:30,643 - DEBUG - interface for vm b3730ad5-9626-44e5-bbfe-b6103b6b6c68 deleted from vrouter uve ...
2026-04-23 02:39:30,643 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:33,644 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-1:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:33,677 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-1?flat
2026-04-23 02:39:33,709 - DEBUG - VM b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-1 uve
2026-04-23 02:39:33,709 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:33,709 - DEBUG - interface for vm b3730ad5-9626-44e5-bbfe-b6103b6b6c68 deleted from vrouter uve ...
2026-04-23 02:39:33,709 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:36,710 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-1:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:36,743 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-1?flat
2026-04-23 02:39:36,772 - DEBUG - VM b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-1 uve
2026-04-23 02:39:36,772 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:36,772 - DEBUG - interface for vm b3730ad5-9626-44e5-bbfe-b6103b6b6c68 deleted from vrouter uve ...
2026-04-23 02:39:36,772 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:39,772 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-1:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:39,804 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-1?flat
2026-04-23 02:39:39,835 - DEBUG - VM b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-1 uve
2026-04-23 02:39:39,835 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:39,835 - DEBUG - interface for vm b3730ad5-9626-44e5-bbfe-b6103b6b6c68 deleted from vrouter uve ...
2026-04-23 02:39:39,835 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:42,836 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-1:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:42,882 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-1?flat
2026-04-23 02:39:42,913 - DEBUG - VM b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-1 uve
2026-04-23 02:39:42,913 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:42,913 - DEBUG - interface for vm b3730ad5-9626-44e5-bbfe-b6103b6b6c68 deleted from vrouter uve ...
2026-04-23 02:39:42,913 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:45,915 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-1:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:45,948 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-1?flat
2026-04-23 02:39:45,977 - DEBUG - VM b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5672-1 uve
2026-04-23 02:39:45,977 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:45,977 - DEBUG - interface for vm b3730ad5-9626-44e5-bbfe-b6103b6b6c68 deleted from vrouter uve ...
2026-04-23 02:39:45,978 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:45,978 - DEBUG - Validated that VM b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is removed in Vrouter UVE
2026-04-23 02:39:45,978 - DEBUG - Verifying through opserver in 10.0.0.23
2026-04-23 02:39:45,978 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616?flat
2026-04-23 02:39:45,996 - DEBUG - b3730ad5-9626-44e5-bbfe-b6103b6b6c68 BM not in default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN2-57009616 uve
2026-04-23 02:39:45,996 - DEBUG - Validated that VM b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is not present in the VN UVE
2026-04-23 02:39:45,996 - DEBUG - Verifying the b3730ad5-9626-44e5-bbfe-b6103b6b6c68 virtual network link through opserver 10.0.0.23
2026-04-23 02:39:45,996 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-04-23 02:39:46,008 - INFO - b3730ad5-9626-44e5-bbfe-b6103b6b6c68 vm uve deleted from opserver
2026-04-23 02:39:46,008 - DEBUG - Verifying the b3730ad5-9626-44e5-bbfe-b6103b6b6c68 virtual network link through opserver 10.0.0.130
2026-04-23 02:39:46,008 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines
2026-04-23 02:39:46,020 - INFO - b3730ad5-9626-44e5-bbfe-b6103b6b6c68 vm uve deleted from opserver
2026-04-23 02:39:46,020 - DEBUG - Verifying the b3730ad5-9626-44e5-bbfe-b6103b6b6c68 virtual network link through opserver 10.0.0.249
2026-04-23 02:39:46,020 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-04-23 02:39:46,033 - INFO - b3730ad5-9626-44e5-bbfe-b6103b6b6c68 vm uve deleted from opserver
2026-04-23 02:39:46,033 - INFO - Validated that VM b3730ad5-9626-44e5-bbfe-b6103b6b6c68 is removed from Opserver
2026-04-23 02:39:46,033 - INFO - Deleting VM ctest-VM11-84765133
2026-04-23 02:39:46,102 - DEBUG - Verifying in api server 10.0.0.249
2026-04-23 02:39:46,102 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/7542d49a-be25-47a0-9872-4e930843c4a6
2026-04-23 02:39:46,112 - DEBUG - VM ID 7542d49a-be25-47a0-9872-4e930843c4a6 of VM ctest-VM11-84765133 is still found in API Server
2026-04-23 02:39:48,112 - DEBUG - Verifying in api server 10.0.0.249
2026-04-23 02:39:48,112 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/7542d49a-be25-47a0-9872-4e930843c4a6
2026-04-23 02:39:48,120 - DEBUG - Response Code: 404
2026-04-23 02:39:48,120 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/7542d49a-be25-47a0-9872-4e930843c4a6
2026-04-23 02:39:48,128 - DEBUG - Response Code: 404
2026-04-23 02:39:48,128 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/7542d49a-be25-47a0-9872-4e930843c4a6
2026-04-23 02:39:48,136 - DEBUG - Response Code: 404
2026-04-23 02:39:48,136 - INFO - VM ctest-VM11-84765133 is fully removed in API-Server
2026-04-23 02:39:48,288 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-04-23 02:39:48,334 - DEBUG - Validated that vrouter 10.0.0.73 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643
2026-04-23 02:39:48,345 - DEBUG - Validated that vrouter 10.0.0.131 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643
2026-04-23 02:39:48,345 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643
2026-04-23 02:39:48,345 - INFO - VM ctest-VM11-84765133 is removed in Compute, and routes are removed in all compute nodes
2026-04-23 02:39:48,537 - INFO - Routes for VM ctest-VM11-84765133 is removed in all control-nodes
2026-04-23 02:39:48,537 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:48,571 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:39:48,600 - DEBUG - VM 7542d49a-be25-47a0-9872-4e930843c4a6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:39:48,601 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:48,601 - DEBUG - interface for vm 7542d49a-be25-47a0-9872-4e930843c4a6 deleted from vrouter uve ...
2026-04-23 02:39:48,601 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:51,602 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:51,636 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:39:51,665 - DEBUG - VM 7542d49a-be25-47a0-9872-4e930843c4a6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:39:51,666 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:51,666 - DEBUG - interface for vm 7542d49a-be25-47a0-9872-4e930843c4a6 deleted from vrouter uve ...
2026-04-23 02:39:51,666 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:54,666 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:54,702 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:39:54,735 - DEBUG - VM 7542d49a-be25-47a0-9872-4e930843c4a6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:39:54,735 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:54,735 - DEBUG - interface for vm 7542d49a-be25-47a0-9872-4e930843c4a6 deleted from vrouter uve ...
2026-04-23 02:39:54,735 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:39:57,736 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:39:57,782 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:39:57,813 - DEBUG - VM 7542d49a-be25-47a0-9872-4e930843c4a6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:39:57,813 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:39:57,813 - DEBUG - interface for vm 7542d49a-be25-47a0-9872-4e930843c4a6 deleted from vrouter uve ...
2026-04-23 02:39:57,813 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:40:00,814 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:40:00,848 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:40:00,879 - DEBUG - VM 7542d49a-be25-47a0-9872-4e930843c4a6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:40:00,879 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:40:00,879 - DEBUG - interface for vm 7542d49a-be25-47a0-9872-4e930843c4a6 deleted from vrouter uve ...
2026-04-23 02:40:00,879 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:40:03,880 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:40:03,915 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:40:03,946 - DEBUG - VM 7542d49a-be25-47a0-9872-4e930843c4a6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:40:03,946 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:40:03,946 - DEBUG - interface for vm 7542d49a-be25-47a0-9872-4e930843c4a6 deleted from vrouter uve ...
2026-04-23 02:40:03,946 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:40:06,947 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:40:07,002 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:40:07,055 - DEBUG - VM 7542d49a-be25-47a0-9872-4e930843c4a6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:40:07,056 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:40:07,056 - DEBUG - interface for vm 7542d49a-be25-47a0-9872-4e930843c4a6 deleted from vrouter uve ...
2026-04-23 02:40:07,056 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:40:10,056 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:40:10,091 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:40:10,120 - DEBUG - VM 7542d49a-be25-47a0-9872-4e930843c4a6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:40:10,120 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:40:10,120 - DEBUG - interface for vm 7542d49a-be25-47a0-9872-4e930843c4a6 deleted from vrouter uve ...
2026-04-23 02:40:10,120 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:40:13,121 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:40:13,157 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:40:13,187 - DEBUG - VM 7542d49a-be25-47a0-9872-4e930843c4a6 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:40:13,187 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:40:13,187 - DEBUG - interface for vm 7542d49a-be25-47a0-9872-4e930843c4a6 deleted from vrouter uve ...
2026-04-23 02:40:13,187 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:40:16,188 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5672-2:Compute:contrail-vrouter-agent:0?flat
2026-04-23 02:40:16,224 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5672-2?flat
2026-04-23 02:40:16,254 - DEBUG - VM 7542d49a-be25-47a0-9872-4e930843c4a6 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5672-2 uve
2026-04-23 02:40:16,254 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-04-23 02:40:16,254 - DEBUG - interface for vm 7542d49a-be25-47a0-9872-4e930843c4a6 deleted from vrouter uve ...
2026-04-23 02:40:16,254 - DEBUG - Deleted interface not in error interface list ...
2026-04-23 02:40:16,254 - DEBUG - Validated that VM 7542d49a-be25-47a0-9872-4e930843c4a6 is removed in Vrouter UVE
2026-04-23 02:40:16,254 - DEBUG - Verifying through opserver in 10.0.0.23
2026-04-23 02:40:16,254 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643?flat
2026-04-23 02:40:16,272 - DEBUG - 7542d49a-be25-47a0-9872-4e930843c4a6 BM not in default-domain:ctest-TestPolicyAclIpv4v6-48672592:ctest-VN1-10545643 uve
2026-04-23 02:40:16,272 - DEBUG - Validated that VM 7542d49a-be25-47a0-9872-4e930843c4a6 is not present in the VN UVE
2026-04-23 02:40:16,272 - DEBUG - Verifying the 7542d49a-be25-47a0-9872-4e930843c4a6 virtual network link through opserver 10.0.0.23
2026-04-23 02:40:16,272 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-04-23 02:40:16,282 - DEBUG - No links retuned for 7542d49a-be25-47a0-9872-4e930843c4a6
2026-04-23 02:40:16,282 - INFO - 7542d49a-be25-47a0-9872-4e930843c4a6 vm uve deleted from opserver
2026-04-23 02:40:16,282 - DEBUG - Verifying the 7542d49a-be25-47a0-9872-4e930843c4a6 virtual network link through opserver 10.0.0.130
2026-04-23 02:40:16,282 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines
2026-04-23 02:40:16,297 - DEBUG - No links retuned for 7542d49a-be25-47a0-9872-4e930843c4a6
2026-04-23 02:40:16,297 - INFO - 7542d49a-be25-47a0-9872-4e930843c4a6 vm uve deleted from opserver
2026-04-23 02:40:16,297 - DEBUG - Verifying the 7542d49a-be25-47a0-9872-4e930843c4a6 virtual network link through opserver 10.0.0.249
2026-04-23 02:40:16,297 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-04-23 02:40:16,309 - DEBUG - No links retuned for 7542d49a-be25-47a0-9872-4e930843c4a6
2026-04-23 02:40:16,309 - INFO - 7542d49a-be25-47a0-9872-4e930843c4a6 vm uve deleted from opserver
2026-04-23 02:40:16,309 - INFO - Validated that VM 7542d49a-be25-47a0-9872-4e930843c4a6 is removed from Opserver
2026-04-23 02:40:16,309 - DEBUG - Detaching the Policy for VN :ctest-VN2-57009616
2026-04-23 02:40:16,309 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:40:16,315 - DEBUG - Requesting: http://10.0.0.23:8082/domain/36692cb6-e180-463e-9a23-ae16be53ee8b
2026-04-23 02:40:16,327 - DEBUG - Requesting: http://10.0.0.23:8082/project/16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:40:16,419 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/16abf06a-a71a-43af-8399-014e3050bf3b
2026-04-23 02:40:16,435 - DEBUG - Requesting: http://10.0.0.23:8082/network-policy/be2e9413-bc31-45e6-8019-77acec3f50a5
2026-04-23 02:40:16,610 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ctest-policy21-66635010']] from ctest-VN2-57009616
2026-04-23 02:40:16,610 - DEBUG - Detaching the Policy for VN :ctest-VN1-10545643
2026-04-23 02:40:16,610 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:40:16,616 - DEBUG - Requesting: http://10.0.0.23:8082/domain/36692cb6-e180-463e-9a23-ae16be53ee8b
2026-04-23 02:40:16,630 - DEBUG - Requesting: http://10.0.0.23:8082/project/16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:40:16,720 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-network/b8d67109-1a59-4f61-aaa7-0465eeb4eef9
2026-04-23 02:40:16,735 - DEBUG - Requesting: http://10.0.0.23:8082/network-policy/29df70d3-2b7b-4d52-8b31-c272e4279314
2026-04-23 02:40:16,744 - DEBUG - Requesting: http://10.0.0.23:8082/network-policy/054eae6f-1437-45fa-ad8b-36d08d88eb73
2026-04-23 02:40:16,907 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ctest-policy12-77873303'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-48672592', 'ctest-policy13-16050762']] from ctest-VN1-10545643
2026-04-23 02:40:16,954 - INFO - Deleted policy ctest-policy21-66635010
2026-04-23 02:40:16,993 - INFO - Deleted policy ctest-policy12-77873303
2026-04-23 02:40:17,028 - INFO - Deleted policy ctest-policy13-16050762
2026-04-23 02:40:17,028 - INFO - Deleting VN ctest-VN3-54060924
2026-04-23 02:40:17,273 - DEBUG - Response for deleting network ()
2026-04-23 02:40:17,273 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/1898fd36-1514-4375-a9fc-964d2bba132b
2026-04-23 02:40:17,279 - DEBUG - Response Code: 404
2026-04-23 02:40:17,279 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:40:17,286 - DEBUG - Requesting: http://10.0.0.23:8082/domain/36692cb6-e180-463e-9a23-ae16be53ee8b
2026-04-23 02:40:17,296 - DEBUG - Requesting: http://10.0.0.23:8082/project/16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:40:17,381 - INFO - Validated that VN ctest-VN3-54060924 is not found in API Server
2026-04-23 02:40:17,391 - DEBUG - VN ctest-VN3-54060924 is not present in Agent 10.0.0.73
2026-04-23 02:40:17,400 - DEBUG - VN ctest-VN3-54060924 is not present in Agent 10.0.0.131
2026-04-23 02:40:17,400 - INFO - Validated that VN ctest-VN3-54060924 is not in any agent
2026-04-23 02:40:17,430 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-54060924 info
2026-04-23 02:40:17,430 - INFO - Deleting VN ctest-VN2-57009616
2026-04-23 02:40:17,662 - DEBUG - Response for deleting network ()
2026-04-23 02:40:17,662 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/ba5c4e4b-af3a-4a74-92d8-83d8a7454c15
2026-04-23 02:40:17,668 - DEBUG - Response Code: 404
2026-04-23 02:40:17,668 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:40:17,674 - DEBUG - Requesting: http://10.0.0.23:8082/domain/36692cb6-e180-463e-9a23-ae16be53ee8b
2026-04-23 02:40:17,692 - DEBUG - Requesting: http://10.0.0.23:8082/project/16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:40:17,786 - INFO - Validated that VN ctest-VN2-57009616 is not found in API Server
2026-04-23 02:40:17,805 - DEBUG - VN ctest-VN2-57009616 is not present in Agent 10.0.0.73
2026-04-23 02:40:17,822 - DEBUG - VN ctest-VN2-57009616 is not present in Agent 10.0.0.131
2026-04-23 02:40:17,822 - INFO - Validated that VN ctest-VN2-57009616 is not in any agent
2026-04-23 02:40:17,856 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-57009616 info
2026-04-23 02:40:17,856 - INFO - Deleting VN ctest-VN1-10545643
2026-04-23 02:40:18,029 - DEBUG - Response for deleting network ()
2026-04-23 02:40:18,029 - DEBUG - Requesting: http://10.0.0.23:8082/routing-instance/54ba1c05-248a-4a51-9d74-d6466181356e
2026-04-23 02:40:18,044 - DEBUG - Response Code: 404
2026-04-23 02:40:18,044 - DEBUG - Requesting: http://10.0.0.23:8082/domains
2026-04-23 02:40:18,051 - DEBUG - Requesting: http://10.0.0.23:8082/domain/36692cb6-e180-463e-9a23-ae16be53ee8b
2026-04-23 02:40:18,061 - DEBUG - Requesting: http://10.0.0.23:8082/project/16088fd5-65c1-4716-a13d-2ade51022390
2026-04-23 02:40:18,144 - INFO - Validated that VN ctest-VN1-10545643 is not found in API Server
2026-04-23 02:40:18,155 - DEBUG - VN ctest-VN1-10545643 is not present in Agent 10.0.0.73
2026-04-23 02:40:18,168 - DEBUG - VN ctest-VN1-10545643 is not present in Agent 10.0.0.131
2026-04-23 02:40:18,168 - INFO - Validated that VN ctest-VN1-10545643 is not in any agent
2026-04-23 02:40:18,201 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-10545643 info
2026-04-23 02:40:18,297 - INFO - IPAM: ipam3 is not found in API Server
2026-04-23 02:40:18,322 - INFO - IPAM:ipam3 is not found in control node
2026-04-23 02:40:18,457 - INFO - IPAM: ipam2 is not found in API Server
2026-04-23 02:40:18,483 - INFO - IPAM:ipam2 is not found in control node
2026-04-23 02:40:18,621 - INFO - IPAM: ipam1 is not found in API Server
2026-04-23 02:40:18,644 - INFO - IPAM:ipam1 is not found in control node
2026-04-23 02:40:19,930 - DEBUG - No XMPP flaps were noticed during the test
2026-04-23 02:40:19,931 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:03:26]
2026-04-23 02:40:19,931 - INFO - --------------------------------------------------------------------------------
2026-04-23 02:40:21,208 - DEBUG - No XMPP flaps were noticed during the test
2026-04-23 02:40:21,208 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:03:29]
2026-04-23 02:40:21,208 - INFO - --------------------------------------------------------------------------------
2026-04-23 02:40:21,918 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-48672592, ID : 16088fd5-65c1-4716-a13d-2ade51022390