2026-03-02 02:41:54,118 - INFO - Domain Default found not creating
2026-03-02 02:41:54,289 - INFO - Project ctest-TestPolicyAclIpv4v6-17723440 not found, creating it
2026-03-02 02:41:54,860 - INFO - Created Project:ctest-TestPolicyAclIpv4v6-17723440, ID : 48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:41:56,490 - INFO - ================================================================================
2026-03-02 02:41:56,490 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any
2026-03-02 02:41:57,759 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.254': '3', '10.20.0.193': '3'}, '10.0.0.240': {'10.20.0.254': '3', '10.20.0.193': '0'}} with
2026-03-02 02:41:57,759 - INFO - Initial checks done. Running the testcase now
2026-03-02 02:41:57,759 - INFO -
2026-03-02 02:41:57,759 - INFO - ================================================================================
2026-03-02 02:41:57,759 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any
2026-03-02 02:41:57,759 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance
2026-03-02 02:41:59,012 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.254': '3', '10.20.0.193': '3'}, '10.0.0.240': {'10.20.0.254': '3', '10.20.0.193': '0'}} with
2026-03-02 02:41:59,012 - INFO - Initial checks done. Running the testcase now
2026-03-02 02:41:59,012 - INFO -
2026-03-02 02:42:00,342 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:42:00,677 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:42:00,713 - DEBUG - Requesting: http://10.0.0.242:8082/domain/cc2d0805-fa5b-4698-bfcc-d7811c08231a
2026-03-02 02:42:00,730 - DEBUG - Requesting: http://10.0.0.242:8082/project/48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:42:00,850 - DEBUG - Requesting: http://10.0.0.242:8082/network-ipam/7ba9987e-4234-4f17-bc40-23457830c4bd
2026-03-02 02:42:00,859 - INFO - Verifications in API Server for IPAM: ipam1 passed
2026-03-02 02:42:00,864 - DEBUG - Control-node 10.0.0.242 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8910821005773262615', 'uuid-lslong': '13564880858916111549'}, 'enable': 'true', 'created': '2026-03-02T02:41:59', 'last-modified': '2026-03-02T02:41:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.213722'}
2026-03-02 02:42:00,871 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8910821005773262615', 'uuid-lslong': '13564880858916111549'}, 'enable': 'true', 'created': '2026-03-02T02:41:59', 'last-modified': '2026-03-02T02:41:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.220664'}
2026-03-02 02:42:00,877 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8910821005773262615', 'uuid-lslong': '13564880858916111549'}, 'enable': 'true', 'created': '2026-03-02T02:41:59', 'last-modified': '2026-03-02T02:41:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.229527'}
2026-03-02 02:42:00,877 - INFO - Verifications in Control node for IPAM: ipam1 passed
2026-03-02 02:42:00,877 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:42:00,883 - DEBUG - Requesting: http://10.0.0.242:8082/domain/cc2d0805-fa5b-4698-bfcc-d7811c08231a
2026-03-02 02:42:00,900 - DEBUG - Requesting: http://10.0.0.242:8082/project/48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:42:01,001 - DEBUG - Requesting: http://10.0.0.242:8082/network-ipam/77e6ccd2-3527-45d7-9ede-e2dd701924ae
2026-03-02 02:42:01,013 - INFO - Verifications in API Server for IPAM: ipam2 passed
2026-03-02 02:42:01,019 - DEBUG - Control-node 10.0.0.242 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8639818138363839959', 'uuid-lslong': '11447836743518725294'}, 'enable': 'true', 'created': '2026-03-02T02:41:59', 'last-modified': '2026-03-02T02:41:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.041501'}
2026-03-02 02:42:01,026 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8639818138363839959', 'uuid-lslong': '11447836743518725294'}, 'enable': 'true', 'created': '2026-03-02T02:41:59', 'last-modified': '2026-03-02T02:41:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.047248'}
2026-03-02 02:42:01,032 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8639818138363839959', 'uuid-lslong': '11447836743518725294'}, 'enable': 'true', 'created': '2026-03-02T02:41:59', 'last-modified': '2026-03-02T02:41:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.056494'}
2026-03-02 02:42:01,032 - INFO - Verifications in Control node for IPAM: ipam2 passed
2026-03-02 02:42:01,032 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:42:01,039 - DEBUG - Requesting: http://10.0.0.242:8082/domain/cc2d0805-fa5b-4698-bfcc-d7811c08231a
2026-03-02 02:42:01,054 - DEBUG - Requesting: http://10.0.0.242:8082/project/48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:42:01,146 - DEBUG - Requesting: http://10.0.0.242:8082/network-ipam/bf435985-423f-4e19-a263-a3929d6fc2d3
2026-03-02 02:42:01,156 - INFO - Verifications in API Server for IPAM: ipam3 passed
2026-03-02 02:42:01,162 - DEBUG - Control-node 10.0.0.242 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13781957713560817177', 'uuid-lslong': '11701376106940580563'}, 'enable': 'true', 'created': '2026-03-02T02:42:00', 'last-modified': '2026-03-02T02:42:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.842305'}
2026-03-02 02:42:01,170 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13781957713560817177', 'uuid-lslong': '11701376106940580563'}, 'enable': 'true', 'created': '2026-03-02T02:42:00', 'last-modified': '2026-03-02T02:42:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.850226'}
2026-03-02 02:42:01,177 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13781957713560817177', 'uuid-lslong': '11701376106940580563'}, 'enable': 'true', 'created': '2026-03-02T02:42:00', 'last-modified': '2026-03-02T02:42:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.860109'}
2026-03-02 02:42:01,177 - INFO - Verifications in Control node for IPAM: ipam3 passed
2026-03-02 02:42:01,542 - DEBUG - Response for create_network : {'network': {'id': 'afc1311a-65f1-41ce-9831-e2a8985738b2', 'name': 'ctest-VN1-03474604', 'tenant_id': '48910b0c92494308a3add4c3b57cb7c1', 'project_id': '48910b0c92494308a3add4c3b57cb7c1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ctest-VN1-03474604'], 'port_security_enabled': True, 'description': ''}}
2026-03-02 02:42:01,937 - DEBUG - Response for create_subnet : {'subnet': {'id': '4bb1327b-7fb1-4bc6-bb02-df7f32767efd', 'name': '', 'tenant_id': '48910b0c92494308a3add4c3b57cb7c1', 'network_id': 'afc1311a-65f1-41ce-9831-e2a8985738b2', 'ip_version': 4, 'cidr': '72.161.99.192/26', 'allocation_pools': [{'start': '72.161.99.194', 'end': '72.161.99.254'}], 'gateway_ip': '72.161.99.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '72.161.99.194', 'tags': [], 'project_id': '48910b0c92494308a3add4c3b57cb7c1'}}
2026-03-02 02:42:02,166 - DEBUG - Response for create_subnet : {'subnet': {'id': '178319c0-6b74-4228-94b6-5c1c53d0fb79', 'name': '', 'tenant_id': '48910b0c92494308a3add4c3b57cb7c1', 'network_id': 'afc1311a-65f1-41ce-9831-e2a8985738b2', 'ip_version': 6, 'cidr': '3635:256d:d79c:356:e61e:e729::/96', 'allocation_pools': [{'start': '3635:256d:d79c:356:e61e:e729:0:2', 'end': '3635:256d:d79c:356:e61e:e729:ffff:fffe'}], 'gateway_ip': '3635:256d:d79c:356:e61e:e729:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3635:256d:d79c:356:e61e:e729:0:2', 'tags': [], 'project_id': '48910b0c92494308a3add4c3b57cb7c1'}}
2026-03-02 02:42:02,217 - INFO - Created VN ctest-VN1-03474604
2026-03-02 02:42:02,229 - DEBUG - VN ctest-VN1-03474604 UUID is afc1311a-65f1-41ce-9831-e2a8985738b2
2026-03-02 02:42:02,426 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604(afc1311a-65f1-41ce-9831-e2a8985738b2) with subnets ['72.161.99.192/26', '3635:256d:d79c:356:e61e:e729::/96']
2026-03-02 02:42:02,775 - DEBUG - Response for create_network : {'network': {'id': 'b3151025-5b7c-45f1-97af-91a62eba7b7a', 'name': 'ctest-VN2-32434507', 'tenant_id': '48910b0c92494308a3add4c3b57cb7c1', 'project_id': '48910b0c92494308a3add4c3b57cb7c1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ctest-VN2-32434507'], 'port_security_enabled': True, 'description': ''}}
2026-03-02 02:42:03,035 - DEBUG - Response for create_subnet : {'subnet': {'id': '1e79545a-ac92-4bf0-97bd-3ec1abe1a738', 'name': '', 'tenant_id': '48910b0c92494308a3add4c3b57cb7c1', 'network_id': 'b3151025-5b7c-45f1-97af-91a62eba7b7a', 'ip_version': 4, 'cidr': '191.142.253.128/26', 'allocation_pools': [{'start': '191.142.253.130', 'end': '191.142.253.190'}], 'gateway_ip': '191.142.253.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '191.142.253.130', 'tags': [], 'project_id': '48910b0c92494308a3add4c3b57cb7c1'}}
2026-03-02 02:42:03,269 - DEBUG - Response for create_subnet : {'subnet': {'id': '63b9817b-d018-4c5f-9d7e-e1fd962e7afc', 'name': '', 'tenant_id': '48910b0c92494308a3add4c3b57cb7c1', 'network_id': 'b3151025-5b7c-45f1-97af-91a62eba7b7a', 'ip_version': 6, 'cidr': '2be7:5cc5:dca2:259c:e3b7:dc2a::/96', 'allocation_pools': [{'start': '2be7:5cc5:dca2:259c:e3b7:dc2a:0:2', 'end': '2be7:5cc5:dca2:259c:e3b7:dc2a:ffff:fffe'}], 'gateway_ip': '2be7:5cc5:dca2:259c:e3b7:dc2a:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2be7:5cc5:dca2:259c:e3b7:dc2a:0:2', 'tags': [], 'project_id': '48910b0c92494308a3add4c3b57cb7c1'}}
2026-03-02 02:42:03,302 - INFO - Created VN ctest-VN2-32434507
2026-03-02 02:42:03,313 - DEBUG - VN ctest-VN2-32434507 UUID is b3151025-5b7c-45f1-97af-91a62eba7b7a
2026-03-02 02:42:03,749 - DEBUG - Response for create_network : {'network': {'id': '0ba68893-8afc-4728-a316-91542df589f2', 'name': 'ctest-VN3-29328908', 'tenant_id': '48910b0c92494308a3add4c3b57cb7c1', 'project_id': '48910b0c92494308a3add4c3b57cb7c1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ctest-VN3-29328908'], 'port_security_enabled': True, 'description': ''}}
2026-03-02 02:42:03,996 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e9801811-7ad0-42f9-b684-3db67f96b136', 'name': '', 'tenant_id': '48910b0c92494308a3add4c3b57cb7c1', 'network_id': '0ba68893-8afc-4728-a316-91542df589f2', 'ip_version': 4, 'cidr': '214.86.152.128/26', 'allocation_pools': [{'start': '214.86.152.130', 'end': '214.86.152.190'}], 'gateway_ip': '214.86.152.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '214.86.152.130', 'tags': [], 'project_id': '48910b0c92494308a3add4c3b57cb7c1'}}
2026-03-02 02:42:04,220 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c90e8b95-e431-490d-a17d-00b72a3b584f', 'name': '', 'tenant_id': '48910b0c92494308a3add4c3b57cb7c1', 'network_id': '0ba68893-8afc-4728-a316-91542df589f2', 'ip_version': 6, 'cidr': '3e8b:64f5:878b:6cb9:883b:d274::/96', 'allocation_pools': [{'start': '3e8b:64f5:878b:6cb9:883b:d274:0:2', 'end': '3e8b:64f5:878b:6cb9:883b:d274:ffff:fffe'}], 'gateway_ip': '3e8b:64f5:878b:6cb9:883b:d274:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3e8b:64f5:878b:6cb9:883b:d274:0:2', 'tags': [], 'project_id': '48910b0c92494308a3add4c3b57cb7c1'}}
2026-03-02 02:42:04,251 - INFO - Created VN ctest-VN3-29328908
2026-03-02 02:42:04,263 - DEBUG - VN ctest-VN3-29328908 UUID is 0ba68893-8afc-4728-a316-91542df589f2
2026-03-02 02:42:04,372 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:42:04,378 - DEBUG - Requesting: http://10.0.0.242:8082/domain/cc2d0805-fa5b-4698-bfcc-d7811c08231a
2026-03-02 02:42:04,389 - DEBUG - Requesting: http://10.0.0.242:8082/project/48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:42:04,482 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/afc1311a-65f1-41ce-9831-e2a8985738b2
2026-03-02 02:42:04,519 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/afc1311a-65f1-41ce-9831-e2a8985738b2
2026-03-02 02:42:04,529 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c0930fb8-25aa-4e5c-ba04-1a7cc3c70c67
2026-03-02 02:42:04,538 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c0930fb8-25aa-4e5c-ba04-1a7cc3c70c67
2026-03-02 02:42:04,551 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/702e24ce-d4d9-4d6e-ba60-2b4e61faf2cb
2026-03-02 02:42:04,569 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-02 02:42:04,570 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/afc1311a-65f1-41ce-9831-e2a8985738b2
2026-03-02 02:42:04,586 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c0930fb8-25aa-4e5c-ba04-1a7cc3c70c67
2026-03-02 02:42:04,612 - INFO - Verified VN network id 15 for VN afc1311a-65f1-41ce-9831-e2a8985738b2
2026-03-02 02:42:04,612 - INFO - Verifications in API Server for VN ctest-VN1-03474604 passed
2026-03-02 02:42:04,612 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/afc1311a-65f1-41ce-9831-e2a8985738b2
2026-03-02 02:42:04,629 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c0930fb8-25aa-4e5c-ba04-1a7cc3c70c67
2026-03-02 02:42:04,639 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c0930fb8-25aa-4e5c-ba04-1a7cc3c70c67
2026-03-02 02:42:04,648 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/702e24ce-d4d9-4d6e-ba60-2b4e61faf2cb
2026-03-02 02:42:04,663 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12664457616591765966', 'uuid-lslong': '10966795781362104498'}, 'enable': 'true', 'created': '2026-03-02T02:42:01', 'last-modified': '2026-03-02T02:42:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.546127'}
2026-03-02 02:42:04,669 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-02 02:42:04,675 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12664457616591765966', 'uuid-lslong': '10966795781362104498'}, 'enable': 'true', 'created': '2026-03-02T02:42:01', 'last-modified': '2026-03-02T02:42:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.569430'}
2026-03-02 02:42:04,683 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-02 02:42:04,688 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12664457616591765966', 'uuid-lslong': '10966795781362104498'}, 'enable': 'true', 'created': '2026-03-02T02:42:01', 'last-modified': '2026-03-02T02:42:02', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.583697'}
2026-03-02 02:42:04,695 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-02 02:42:04,696 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-03474604 passed
2026-03-02 02:42:04,696 - DEBUG - ====Verifying policy data for ctest-VN1-03474604 in API_Server ======
2026-03-02 02:42:04,696 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:42:04,703 - DEBUG - Requesting: http://10.0.0.242:8082/domain/cc2d0805-fa5b-4698-bfcc-d7811c08231a
2026-03-02 02:42:04,717 - DEBUG - Requesting: http://10.0.0.242:8082/project/48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:42:04,816 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/afc1311a-65f1-41ce-9831-e2a8985738b2
2026-03-02 02:42:04,828 - DEBUG - =>VN ctest-VN1-03474604 has no policy to be verified
2026-03-02 02:42:04,828 - DEBUG - Verifying the vn in opserver
2026-03-02 02:42:04,828 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 virtual network link through opserver 10.0.0.242
2026-03-02 02:42:04,828 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-03-02 02:42:05,377 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-03-02 02:42:05,478 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604?flat'}
2026-03-02 02:42:05,478 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 virtual network link through opserver 10.0.0.27
2026-03-02 02:42:05,478 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-03-02 02:42:05,834 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-03-02 02:42:05,903 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604?flat'}
2026-03-02 02:42:05,903 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 virtual network link through opserver 10.0.0.23
2026-03-02 02:42:05,903 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-03-02 02:42:06,244 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-03-02 02:42:06,308 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604?flat'}
2026-03-02 02:42:06,308 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is found in opserver
2026-03-02 02:42:06,332 - DEBUG - Do not have enough data to verify VN in agent
2026-03-02 02:42:06,345 - DEBUG - VRF ids for VN ctest-VN1-03474604: {}
2026-03-02 02:42:06,345 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:42:06,353 - DEBUG - Requesting: http://10.0.0.242:8082/domain/cc2d0805-fa5b-4698-bfcc-d7811c08231a
2026-03-02 02:42:06,369 - DEBUG - Requesting: http://10.0.0.242:8082/project/48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:42:06,472 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b3151025-5b7c-45f1-97af-91a62eba7b7a
2026-03-02 02:42:06,484 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b3151025-5b7c-45f1-97af-91a62eba7b7a
2026-03-02 02:42:06,494 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/9d5bee9b-6231-4146-a8e5-21dc6b9771eb
2026-03-02 02:42:06,502 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/9d5bee9b-6231-4146-a8e5-21dc6b9771eb
2026-03-02 02:42:06,510 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/390fbce3-c5ab-41aa-bbef-58259efa1229
2026-03-02 02:42:06,519 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-02 02:42:06,519 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b3151025-5b7c-45f1-97af-91a62eba7b7a
2026-03-02 02:42:06,531 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/9d5bee9b-6231-4146-a8e5-21dc6b9771eb
2026-03-02 02:42:06,555 - INFO - Verified VN network id 16 for VN b3151025-5b7c-45f1-97af-91a62eba7b7a
2026-03-02 02:42:06,555 - INFO - Verifications in API Server for VN ctest-VN2-32434507 passed
2026-03-02 02:42:06,555 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b3151025-5b7c-45f1-97af-91a62eba7b7a
2026-03-02 02:42:06,569 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/9d5bee9b-6231-4146-a8e5-21dc6b9771eb
2026-03-02 02:42:06,577 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/9d5bee9b-6231-4146-a8e5-21dc6b9771eb
2026-03-02 02:42:06,586 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/390fbce3-c5ab-41aa-bbef-58259efa1229
2026-03-02 02:42:06,598 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12904238059934729713', 'uuid-lslong': '10930114963586055034'}, 'enable': 'true', 'created': '2026-03-02T02:42:02', 'last-modified': '2026-03-02T02:42:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.385184'}
2026-03-02 02:42:06,605 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-02 02:42:06,612 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12904238059934729713', 'uuid-lslong': '10930114963586055034'}, 'enable': 'true', 'created': '2026-03-02T02:42:02', 'last-modified': '2026-03-02T02:42:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.400186'}
2026-03-02 02:42:06,619 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-02 02:42:06,624 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12904238059934729713', 'uuid-lslong': '10930114963586055034'}, 'enable': 'true', 'created': '2026-03-02T02:42:02', 'last-modified': '2026-03-02T02:42:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.411303'}
2026-03-02 02:42:06,630 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-02 02:42:06,630 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-32434507 passed
2026-03-02 02:42:06,630 - DEBUG - ====Verifying policy data for ctest-VN2-32434507 in API_Server ======
2026-03-02 02:42:06,630 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:42:06,637 - DEBUG - Requesting: http://10.0.0.242:8082/domain/cc2d0805-fa5b-4698-bfcc-d7811c08231a
2026-03-02 02:42:06,648 - DEBUG - Requesting: http://10.0.0.242:8082/project/48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:42:06,764 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b3151025-5b7c-45f1-97af-91a62eba7b7a
2026-03-02 02:42:06,777 - DEBUG - =>VN ctest-VN2-32434507 has no policy to be verified
2026-03-02 02:42:06,777 - DEBUG - Verifying the vn in opserver
2026-03-02 02:42:06,777 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507 virtual network link through opserver 10.0.0.242
2026-03-02 02:42:06,777 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-03-02 02:42:06,840 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507?flat'}
2026-03-02 02:42:06,840 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507 virtual network link through opserver 10.0.0.27
2026-03-02 02:42:06,840 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-03-02 02:42:06,900 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507?flat'}
2026-03-02 02:42:06,900 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507 virtual network link through opserver 10.0.0.23
2026-03-02 02:42:06,900 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-03-02 02:42:06,960 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507?flat'}
2026-03-02 02:42:06,960 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507 is found in opserver
2026-03-02 02:42:06,983 - DEBUG - Do not have enough data to verify VN in agent
2026-03-02 02:42:06,992 - DEBUG - VRF ids for VN ctest-VN2-32434507: {}
2026-03-02 02:42:06,992 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:42:06,997 - DEBUG - Requesting: http://10.0.0.242:8082/domain/cc2d0805-fa5b-4698-bfcc-d7811c08231a
2026-03-02 02:42:07,008 - DEBUG - Requesting: http://10.0.0.242:8082/project/48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:42:07,101 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/0ba68893-8afc-4728-a316-91542df589f2
2026-03-02 02:42:07,120 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/0ba68893-8afc-4728-a316-91542df589f2
2026-03-02 02:42:07,130 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5a79aa99-a24b-4680-8a2c-ad7c6d97a881
2026-03-02 02:42:07,140 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5a79aa99-a24b-4680-8a2c-ad7c6d97a881
2026-03-02 02:42:07,148 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/f7c969c7-3c58-4b4d-96b7-3ccd62de86c8
2026-03-02 02:42:07,156 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-02 02:42:07,156 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/0ba68893-8afc-4728-a316-91542df589f2
2026-03-02 02:42:07,167 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5a79aa99-a24b-4680-8a2c-ad7c6d97a881
2026-03-02 02:42:07,185 - INFO - Verified VN network id 17 for VN 0ba68893-8afc-4728-a316-91542df589f2
2026-03-02 02:42:07,185 - INFO - Verifications in API Server for VN ctest-VN3-29328908 passed
2026-03-02 02:42:07,185 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/0ba68893-8afc-4728-a316-91542df589f2
2026-03-02 02:42:07,195 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5a79aa99-a24b-4680-8a2c-ad7c6d97a881
2026-03-02 02:42:07,202 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5a79aa99-a24b-4680-8a2c-ad7c6d97a881
2026-03-02 02:42:07,209 - DEBUG - Requesting: http://10.0.0.242:8082/route-target/f7c969c7-3c58-4b4d-96b7-3ccd62de86c8
2026-03-02 02:42:07,220 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '839508547824535336', 'uuid-lslong': '11751740068404234738'}, 'enable': 'true', 'created': '2026-03-02T02:42:03', 'last-modified': '2026-03-02T02:42:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.059875'}
2026-03-02 02:42:07,225 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-02 02:42:07,231 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '839508547824535336', 'uuid-lslong': '11751740068404234738'}, 'enable': 'true', 'created': '2026-03-02T02:42:03', 'last-modified': '2026-03-02T02:42:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.071864'}
2026-03-02 02:42:07,236 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-02 02:42:07,241 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-17723440', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '839508547824535336', 'uuid-lslong': '11751740068404234738'}, 'enable': 'true', 'created': '2026-03-02T02:42:03', 'last-modified': '2026-03-02T02:42:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.082029'}
2026-03-02 02:42:07,246 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-02 02:42:07,246 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-29328908 passed
2026-03-02 02:42:07,246 - DEBUG - ====Verifying policy data for ctest-VN3-29328908 in API_Server ======
2026-03-02 02:42:07,246 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:42:07,251 - DEBUG - Requesting: http://10.0.0.242:8082/domain/cc2d0805-fa5b-4698-bfcc-d7811c08231a
2026-03-02 02:42:07,262 - DEBUG - Requesting: http://10.0.0.242:8082/project/48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:42:07,361 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/0ba68893-8afc-4728-a316-91542df589f2
2026-03-02 02:42:07,379 - DEBUG - =>VN ctest-VN3-29328908 has no policy to be verified
2026-03-02 02:42:07,379 - DEBUG - Verifying the vn in opserver
2026-03-02 02:42:07,379 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908 virtual network link through opserver 10.0.0.242
2026-03-02 02:42:07,379 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-03-02 02:42:07,441 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908?flat'}
2026-03-02 02:42:07,441 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908 virtual network link through opserver 10.0.0.27
2026-03-02 02:42:07,441 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-03-02 02:42:07,503 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908?flat'}
2026-03-02 02:42:07,503 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908 virtual network link through opserver 10.0.0.23
2026-03-02 02:42:07,503 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks
2026-03-02 02:42:07,573 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908?flat'}
2026-03-02 02:42:07,574 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908 is found in opserver
2026-03-02 02:42:07,595 - DEBUG - Do not have enough data to verify VN in agent
2026-03-02 02:42:07,605 - DEBUG - VRF ids for VN ctest-VN3-29328908: {}
2026-03-02 02:42:07,611 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604, 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-17723440:ctest-VN3-29328908, 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-03-02 02:42:07,882 - DEBUG - No source network defined
2026-03-02 02:42:07,882 - DEBUG - No source network defined
2026-03-02 02:42:07,882 - 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-17723440:ctest-policy13-59530610, 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-17723440:ctest-policy13-59530610, 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-03-02 02:42:08,162 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507, 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-17723440:ctest-VN1-03474604, 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-17723440:ctest-VN2-32434507, 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-17723440:ctest-VN1-03474604, 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-03-02 02:42:08,396 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:42:08,402 - DEBUG - Requesting: http://10.0.0.242:8082/domain/cc2d0805-fa5b-4698-bfcc-d7811c08231a
2026-03-02 02:42:08,415 - DEBUG - Requesting: http://10.0.0.242:8082/project/48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:42:08,520 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/afc1311a-65f1-41ce-9831-e2a8985738b2
2026-03-02 02:42:08,531 - DEBUG - Setup step: Associating the policy to VN'
2026-03-02 02:42:08,652 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'afc1311a-65f1-41ce-9831-e2a8985738b2', 'name': 'ctest-VN1-03474604', 'tenant_id': '48910b0c92494308a3add4c3b57cb7c1', 'project_id': '48910b0c92494308a3add4c3b57cb7c1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['4bb1327b-7fb1-4bc6-bb02-df7f32767efd', '178319c0-6b74-4228-94b6-5c1c53d0fb79'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ctest-VN1-03474604'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ctest-policy12-39811060'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ctest-policy13-59530610']], 'subnet_ipam': [{'subnet_cidr': '72.161.99.192/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ipam1']}, {'subnet_cidr': '3635:256d:d79c:356:e61e:e729::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ipam1']}], 'port_security_enabled': True, 'description': ''}}
2026-03-02 02:42:08,821 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ctest-policy12-39811060'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ctest-policy13-59530610']] to ctest-VN1-03474604
2026-03-02 02:42:08,821 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:42:08,827 - DEBUG - Requesting: http://10.0.0.242:8082/domain/cc2d0805-fa5b-4698-bfcc-d7811c08231a
2026-03-02 02:42:08,838 - DEBUG - Requesting: http://10.0.0.242:8082/project/48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:42:08,941 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b3151025-5b7c-45f1-97af-91a62eba7b7a
2026-03-02 02:42:08,954 - DEBUG - Setup step: Associating the policy to VN'
2026-03-02 02:42:09,103 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'b3151025-5b7c-45f1-97af-91a62eba7b7a', 'name': 'ctest-VN2-32434507', 'tenant_id': '48910b0c92494308a3add4c3b57cb7c1', 'project_id': '48910b0c92494308a3add4c3b57cb7c1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['1e79545a-ac92-4bf0-97bd-3ec1abe1a738', '63b9817b-d018-4c5f-9d7e-e1fd962e7afc'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ctest-VN2-32434507'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ctest-policy21-05210832']], 'subnet_ipam': [{'subnet_cidr': '191.142.253.128/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ipam2']}, {'subnet_cidr': '2be7:5cc5:dca2:259c:e3b7:dc2a::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ipam2']}], 'port_security_enabled': True, 'description': ''}}
2026-03-02 02:42:09,237 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ctest-policy21-05210832']] to ctest-VN2-32434507
2026-03-02 02:42:09,341 - DEBUG - Services list from nova: [, , , ]
2026-03-02 02:42:10,580 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5413-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5413-1)
2026-03-02 02:42:12,048 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5413-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5413-2)
2026-03-02 02:42:13,618 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5413-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5413-1)
2026-03-02 02:42:13,713 - DEBUG - VM is in ACTIVE state now
2026-03-02 02:42:13,713 - INFO - VM name : ctest-VM11-24757346
2026-03-02 02:42:13,804 - DEBUG - VM ctest-VM11-24757346 ID is 189bf50d-6ef4-456c-8bac-d0d654e1efa8
2026-03-02 02:42:13,905 - DEBUG - VM is in ACTIVE state now
2026-03-02 02:42:13,931 - DEBUG - VM ctest-VM11-24757346 launched on Node an-jenkins-deploy-platform-ansible-os-5413-1
2026-03-02 02:42:14,077 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/189bf50d-6ef4-456c-8bac-d0d654e1efa8
2026-03-02 02:42:14,545 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/189bf50d-6ef4-456c-8bac-d0d654e1efa8
2026-03-02 02:42:14,587 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/189bf50d-6ef4-456c-8bac-d0d654e1efa8
2026-03-02 02:42:14,595 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/f7a4b1cc-9dc7-45ea-bd47-3d4c281944d3
2026-03-02 02:42:14,604 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/189bf50d-6ef4-456c-8bac-d0d654e1efa8
2026-03-02 02:42:14,611 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/f7a4b1cc-9dc7-45ea-bd47-3d4c281944d3
2026-03-02 02:42:14,620 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/0d9b9b17-a26d-48ae-9022-c2928723c7c5
2026-03-02 02:42:14,631 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/ca47cf6a-678c-4cb8-aedc-7b8e299579e2
2026-03-02 02:42:14,640 - DEBUG - Verifying in api server 10.0.0.23
2026-03-02 02:42:14,640 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/189bf50d-6ef4-456c-8bac-d0d654e1efa8
2026-03-02 02:42:14,647 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/f7a4b1cc-9dc7-45ea-bd47-3d4c281944d3
2026-03-02 02:42:14,656 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/0d9b9b17-a26d-48ae-9022-c2928723c7c5
2026-03-02 02:42:14,663 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/ca47cf6a-678c-4cb8-aedc-7b8e299579e2
2026-03-02 02:42:14,669 - INFO - VM ctest-VM11-24757346 verfication in all API Servers passed
2026-03-02 02:42:14,687 - DEBUG - VM ctest-VM11-24757346 Tap interface: {'index': '3', 'name': 'tapf7a4b1cc-9d', 'uuid': 'f7a4b1cc-9dc7-45ea-bd47-3d4c281944d3', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604:ctest-VN1-03474604', '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-17723440:ctest-VN1-03474604', 'vm_uuid': '189bf50d-6ef4-456c-8bac-d0d654e1efa8', 'vm_name': 'ctest-VM11-24757346', 'ip_addr': '72.161.99.195', 'mac_addr': '02:f7:a4:b1:cc:9d', '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-17723440:f7a4b1cc-9dc7-45ea-bd47-3d4c281944d3', 'sg_uuid_list': ['604f5b22-17b1-48ea-8e4b-cb3ba6e7e7ad'], 'static_route_list': None, 'vm_project_uuid': '48910b0c-9249-4308-a3ad-d4c3b57cb7c1', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '3635:256d:d79c:356:e61e:e729: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-03-02 02:42:14,687 - DEBUG - Agent 10.0.0.41 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604:ctest-VN1-03474604
2026-03-02 02:42:14,692 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604:ctest-VN1-03474604', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604', 'table_label': '-1', 'vxlan_id': '15', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.129:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-03-02 02:42:14,790 - DEBUG - VM ctest-VM11-24757346 : Tap interface tapf7a4b1cc-9d is set to Active
2026-03-02 02:42:14,790 - DEBUG - Tap interface tapf7a4b1cc-9d detail : {'index': '3', 'name': 'tapf7a4b1cc-9d', 'uuid': 'f7a4b1cc-9dc7-45ea-bd47-3d4c281944d3', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604:ctest-VN1-03474604', '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-17723440:ctest-VN1-03474604', 'vm_uuid': '189bf50d-6ef4-456c-8bac-d0d654e1efa8', 'vm_name': 'ctest-VM11-24757346', 'ip_addr': '72.161.99.195', 'mac_addr': '02:f7:a4:b1:cc:9d', '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-17723440:f7a4b1cc-9dc7-45ea-bd47-3d4c281944d3', 'sg_uuid_list': ['604f5b22-17b1-48ea-8e4b-cb3ba6e7e7ad'], 'static_route_list': None, 'vm_project_uuid': '48910b0c-9249-4308-a3ad-d4c3b57cb7c1', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '3635:256d:d79c:356:e61e:e729: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-03-02 02:42:14,902 - DEBUG - Starting Layer 2 verification in Agent
2026-03-02 02:42:14,920 - DEBUG - Layer 2 path is seen for VM MAC 02:f7:a4:b1:cc:9d in agent 10.0.0.41
2026-03-02 02:42:14,920 - DEBUG - Active layer 2 route in agent is present for VMI tapf7a4b1cc-9d
2026-03-02 02:42:14,920 - DEBUG - L2 label(29) matches bw route table and interface table
2026-03-02 02:42:14,932 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is found in Agent of node 10.0.0.41
2026-03-02 02:42:15,141 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is consistent in agent 10.0.0.41
2026-03-02 02:42:15,141 - DEBUG - Route for VM IP ['72.161.99.195', '3635:256d:d79c:356:e61e:e729:0:3'] is consistent in agent 10.0.0.41
2026-03-02 02:42:15,141 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 verification for VM ctest-VM11-24757346 in Agent 10.0.0.41 passed
2026-03-02 02:42:15,206 - DEBUG - Starting all layer 2 verification in agent 10.0.0.41
2026-03-02 02:42:15,239 - DEBUG - Route for VM MAC 02:f7:a4:b1:cc:9d is consistent in agent 10.0.0.41
2026-03-02 02:42:18,393 - 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-03-02 02:42:18,394 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-24757346 failed!
2026-03-02 02:42:18,407 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 72.161.99.193 and allocation pool is NOT set
2026-03-02 02:42:18,407 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 3635:256d:d79c:356:e61e:e729:0:1 and allocation pool is NOT set
2026-03-02 02:42:22,491 - 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-03-02 02:42:22,491 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-24757346 failed!
2026-03-02 02:42:22,515 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 72.161.99.193 and allocation pool is NOT set
2026-03-02 02:42:22,515 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 3635:256d:d79c:356:e61e:e729:0:1 and allocation pool is NOT set
2026-03-02 02:42:26,592 - 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 1013ms')
2026-03-02 02:42:26,592 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-24757346 failed!
2026-03-02 02:42:26,605 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 72.161.99.193 and allocation pool is NOT set
2026-03-02 02:42:26,605 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 3635:256d:d79c:356:e61e:e729:0:1 and allocation pool is NOT set
2026-03-02 02:42:30,686 - 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-03-02 02:42:30,686 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-24757346 failed!
2026-03-02 02:42:30,703 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 72.161.99.193 and allocation pool is NOT set
2026-03-02 02:42:30,703 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 3635:256d:d79c:356:e61e:e729:0:1 and allocation pool is NOT set
2026-03-02 02:42:34,782 - 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-03-02 02:42:34,782 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-24757346 failed!
2026-03-02 02:42:34,797 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 72.161.99.193 and allocation pool is NOT set
2026-03-02 02:42:34,798 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 3635:256d:d79c:356:e61e:e729:0:1 and allocation pool is NOT set
2026-03-02 02:42:38,881 - 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-03-02 02:42:38,881 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-24757346 failed!
2026-03-02 02:42:38,895 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 72.161.99.193 and allocation pool is NOT set
2026-03-02 02:42:38,895 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 3635:256d:d79c:356:e61e:e729:0:1 and allocation pool is NOT set
2026-03-02 02:42:42,977 - 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-03-02 02:42:42,977 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-24757346 failed!
2026-03-02 02:42:42,991 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 72.161.99.193 and allocation pool is NOT set
2026-03-02 02:42:42,991 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 3635:256d:d79c:356:e61e:e729:0:1 and allocation pool is NOT set
2026-03-02 02:42:47,067 - 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-03-02 02:42:47,067 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-24757346 failed!
2026-03-02 02:42:47,084 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 72.161.99.193 and allocation pool is NOT set
2026-03-02 02:42:47,084 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 3635:256d:d79c:356:e61e:e729:0:1 and allocation pool is NOT set
2026-03-02 02:42:51,163 - 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 1024ms')
2026-03-02 02:42:51,163 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-24757346 failed!
2026-03-02 02:42:51,178 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 72.161.99.193 and allocation pool is NOT set
2026-03-02 02:42:51,178 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 3635:256d:d79c:356:e61e:e729:0:1 and allocation pool is NOT set
2026-03-02 02:42:55,261 - 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 1023ms')
2026-03-02 02:42:55,261 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-24757346 failed!
2026-03-02 02:42:55,274 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 72.161.99.193 and allocation pool is NOT set
2026-03-02 02:42:55,274 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 is 3635:256d:d79c:356:e61e:e729:0:1 and allocation pool is NOT set
2026-03-02 02:42:57,341 - 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.33 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=4.58 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 = 4.579/5.452/6.326/0.873 ms')
2026-03-02 02:42:57,341 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-24757346 passed
2026-03-02 02:42:57,341 - INFO - VM ctest-VM11-24757346 verifications in Compute nodes passed
2026-03-02 02:42:57,636 - DEBUG - Validated VM route 72.161.99.195 in vrouter of 10.0.0.41
2026-03-02 02:42:57,642 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-03-02 02:42:57,642 - INFO - Validated routes of VM ctest-VM11-24757346 in all vrouters
2026-03-02 02:42:58,048 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node
2026-03-02 02:42:58,166 - DEBUG - Layer2 route found for VM MAC 02:f7:a4:b1:cc:9d in Control-node 10.0.0.27
2026-03-02 02:42:58,166 - DEBUG - L2 Label for VM ctest-VM11-24757346 same between Control-node 10.0.0.27 and Agent, Expected: 29, Seen: 29
2026-03-02 02:42:58,274 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.27, not supported
2026-03-02 02:42:58,274 - DEBUG - Starting all layer2 verification in 10.0.0.242 Control Node
2026-03-02 02:42:58,398 - DEBUG - Layer2 route found for VM MAC 02:f7:a4:b1:cc:9d in Control-node 10.0.0.242
2026-03-02 02:42:58,399 - DEBUG - L2 Label for VM ctest-VM11-24757346 same between Control-node 10.0.0.242 and Agent, Expected: 29, Seen: 29
2026-03-02 02:42:58,511 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.242, not supported
2026-03-02 02:42:58,512 - INFO - Verification in Control-nodes for VM ctest-VM11-24757346 passed
2026-03-02 02:42:58,512 - DEBUG - Verifying the vm in opserver
2026-03-02 02:42:58,512 - DEBUG - Verifying in collector 10.0.0.23 ...
2026-03-02 02:42:58,512 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/189bf50d-6ef4-456c-8bac-d0d654e1efa8?flat
2026-03-02 02:42:58,529 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-17723440:f7a4b1cc-9dc7-45ea-bd47-3d4c281944d3?flat
2026-03-02 02:42:58,551 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-17723440:f7a4b1cc-9dc7-45ea-bd47-3d4c281944d3?flat
2026-03-02 02:42:58,573 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-17723440:f7a4b1cc-9dc7-45ea-bd47-3d4c281944d3?flat
2026-03-02 02:42:58,599 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/189bf50d-6ef4-456c-8bac-d0d654e1efa8?flat
2026-03-02 02:42:58,614 - DEBUG - Verifying vm in vn uve
2026-03-02 02:42:58,614 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-17723440:f7a4b1cc-9dc7-45ea-bd47-3d4c281944d3?flat
2026-03-02 02:42:58,633 - DEBUG - VM uve shows interface as {'ip6_address': '3635:256d:d79c:356:e61e:e729:0:3', 'vm_name': 'ctest-VM11-24757346', 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604', 'ip_address': '72.161.99.195', 'vn_uuid': 'afc1311a-65f1-41ce-9831-e2a8985738b2', 'fixed_ip4_list': ['72.161.99.195'], 'ip4_active': True, 'ip6_active': True, 'port_mirror_enabled': False, 'gateway': '72.161.99.193', 'vm_uuid': '189bf50d-6ef4-456c-8bac-d0d654e1efa8', 'uuid': 'f7a4b1cc-9dc7-45ea-bd47-3d4c281944d3', 'tx_vlan': 65535, 'l2_active': True, 'is_health_check_active': True, 'admin_state': True, 'fixed_ip6_list': ['3635:256d:d79c:356:e61e:e729:0:3'], 'vhostuser_mode': 0, '__T': 1772419339042249, 'active': True, 'rx_vlan': 65535, 'label': 25, 'mac_address': '02:f7:a4:b1:cc:9d'}
2026-03-02 02:42:58,633 - DEBUG - VM uve shows ip address as ['72.161.99.195', '3635:256d:d79c:356:e61e:e729:0:3']
2026-03-02 02:42:58,633 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604
2026-03-02 02:42:58,633 - INFO - Verifying through opserver in 10.0.0.242
2026-03-02 02:42:58,633 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604?flat
2026-03-02 02:42:58,661 - DEBUG - expected vm list 189bf50d-6ef4-456c-8bac-d0d654e1efa8
2026-03-02 02:42:58,661 - DEBUG - Extracted vm list ['189bf50d-6ef4-456c-8bac-d0d654e1efa8']
2026-03-02 02:42:58,661 - INFO - VM 189bf50d-6ef4-456c-8bac-d0d654e1efa8 is present in default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604
2026-03-02 02:42:58,661 - DEBUG - Verifying vm in vrouter uve
2026-03-02 02:42:58,661 - DEBUG - Getting info from collector 10.0.0.23..
2026-03-02 02:42:58,662 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/189bf50d-6ef4-456c-8bac-d0d654e1efa8?flat
2026-03-02 02:42:58,677 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5413-1']
2026-03-02 02:42:58,677 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-1:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:42:58,714 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-1?flat
2026-03-02 02:42:58,745 - DEBUG - VM 189bf50d-6ef4-456c-8bac-d0d654e1efa8 is present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:42:58,745 - DEBUG - Validated that VM ctest-VM11-24757346 is in Vrouter an-jenkins-deploy-platform-ansible-os-5413-1 UVE
2026-03-02 02:42:58,745 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-03-02 02:42:58,753 - DEBUG - Expected tap interface of VM uuid 189bf50d-6ef4-456c-8bac-d0d654e1efa8 is default-domain:ctest-TestPolicyAclIpv4v6-17723440:f7a4b1cc-9dc7-45ea-bd47-3d4c281944d3
2026-03-02 02:42:58,753 - DEBUG - Expected VN of VM uuid 189bf50d-6ef4-456c-8bac-d0d654e1efa8 is default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604
2026-03-02 02:42:58,753 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-1:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:42:58,789 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-1?flat
2026-03-02 02:42:58,820 - DEBUG - VM 189bf50d-6ef4-456c-8bac-d0d654e1efa8 is present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:42:58,820 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-17723440:f7a4b1cc-9dc7-45ea-bd47-3d4c281944d3 of vm 189bf50d-6ef4-456c-8bac-d0d654e1efa8 is present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:42:58,820 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 of vm 189bf50d-6ef4-456c-8bac-d0d654e1efa8 is present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:42:58,820 - DEBUG - Verifying the 189bf50d-6ef4-456c-8bac-d0d654e1efa8 virtual network link through opserver 10.0.0.242
2026-03-02 02:42:58,820 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machines
2026-03-02 02:42:58,831 - DEBUG - VM link and name as {'name': '189bf50d-6ef4-456c-8bac-d0d654e1efa8', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-machine/189bf50d-6ef4-456c-8bac-d0d654e1efa8?flat'}
2026-03-02 02:42:58,831 - DEBUG - Verifying the 189bf50d-6ef4-456c-8bac-d0d654e1efa8 virtual network link through opserver 10.0.0.27
2026-03-02 02:42:58,831 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-03-02 02:42:58,845 - DEBUG - VM link and name as {'name': '189bf50d-6ef4-456c-8bac-d0d654e1efa8', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/189bf50d-6ef4-456c-8bac-d0d654e1efa8?flat'}
2026-03-02 02:42:58,846 - DEBUG - Verifying the 189bf50d-6ef4-456c-8bac-d0d654e1efa8 virtual network link through opserver 10.0.0.23
2026-03-02 02:42:58,846 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-03-02 02:42:58,858 - DEBUG - VM link and name as {'name': '189bf50d-6ef4-456c-8bac-d0d654e1efa8', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/189bf50d-6ef4-456c-8bac-d0d654e1efa8?flat'}
2026-03-02 02:42:58,858 - INFO - VM ctest-VM11-24757346 validations in Opserver passed
2026-03-02 02:42:58,952 - DEBUG - VM is in ACTIVE state now
2026-03-02 02:42:58,952 - INFO - VM name : ctest-VM21-13259595
2026-03-02 02:42:59,037 - DEBUG - VM ctest-VM21-13259595 ID is 819996ec-95ae-411c-bb23-d0ebdf51a805
2026-03-02 02:42:59,132 - DEBUG - VM is in ACTIVE state now
2026-03-02 02:42:59,132 - DEBUG - VM ctest-VM21-13259595 launched on Node an-jenkins-deploy-platform-ansible-os-5413-2
2026-03-02 02:42:59,221 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/819996ec-95ae-411c-bb23-d0ebdf51a805
2026-03-02 02:42:59,232 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/819996ec-95ae-411c-bb23-d0ebdf51a805
2026-03-02 02:42:59,240 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/73dc293f-eed6-4453-9643-4eaf7e4d52b3
2026-03-02 02:42:59,251 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/819996ec-95ae-411c-bb23-d0ebdf51a805
2026-03-02 02:42:59,262 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/73dc293f-eed6-4453-9643-4eaf7e4d52b3
2026-03-02 02:42:59,273 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/08f466af-bbcf-4a34-a2fa-320fdc60f986
2026-03-02 02:42:59,283 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/805e3d5e-451d-444f-bed5-adf75efe6d7f
2026-03-02 02:42:59,293 - DEBUG - Verifying in api server 10.0.0.23
2026-03-02 02:42:59,293 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/819996ec-95ae-411c-bb23-d0ebdf51a805
2026-03-02 02:42:59,299 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/73dc293f-eed6-4453-9643-4eaf7e4d52b3
2026-03-02 02:42:59,309 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/08f466af-bbcf-4a34-a2fa-320fdc60f986
2026-03-02 02:42:59,316 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/805e3d5e-451d-444f-bed5-adf75efe6d7f
2026-03-02 02:42:59,323 - INFO - VM ctest-VM21-13259595 verfication in all API Servers passed
2026-03-02 02:42:59,341 - DEBUG - VM ctest-VM21-13259595 Tap interface: {'index': '3', 'name': 'tap73dc293f-ee', 'uuid': '73dc293f-eed6-4453-9643-4eaf7e4d52b3', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507:ctest-VN2-32434507', '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': '16', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507', 'vm_uuid': '819996ec-95ae-411c-bb23-d0ebdf51a805', 'vm_name': 'ctest-VM21-13259595', 'ip_addr': '191.142.253.131', 'mac_addr': '02:73:dc:29:3f:ee', '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-17723440:73dc293f-eed6-4453-9643-4eaf7e4d52b3', 'sg_uuid_list': ['604f5b22-17b1-48ea-8e4b-cb3ba6e7e7ad'], 'static_route_list': None, 'vm_project_uuid': '48910b0c-9249-4308-a3ad-d4c3b57cb7c1', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '2be7:5cc5:dca2:259c:e3b7:dc2a: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-03-02 02:42:59,341 - DEBUG - Agent 10.0.0.240 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507:ctest-VN2-32434507
2026-03-02 02:42:59,347 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507:ctest-VN2-32434507', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507', 'table_label': '-1', 'vxlan_id': '16', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.201:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-03-02 02:42:59,438 - DEBUG - VM ctest-VM21-13259595 : Tap interface tap73dc293f-ee is set to Active
2026-03-02 02:42:59,438 - DEBUG - Tap interface tap73dc293f-ee detail : {'index': '3', 'name': 'tap73dc293f-ee', 'uuid': '73dc293f-eed6-4453-9643-4eaf7e4d52b3', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507:ctest-VN2-32434507', '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': '16', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507', 'vm_uuid': '819996ec-95ae-411c-bb23-d0ebdf51a805', 'vm_name': 'ctest-VM21-13259595', 'ip_addr': '191.142.253.131', 'mac_addr': '02:73:dc:29:3f:ee', '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-17723440:73dc293f-eed6-4453-9643-4eaf7e4d52b3', 'sg_uuid_list': ['604f5b22-17b1-48ea-8e4b-cb3ba6e7e7ad'], 'static_route_list': None, 'vm_project_uuid': '48910b0c-9249-4308-a3ad-d4c3b57cb7c1', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '2be7:5cc5:dca2:259c:e3b7:dc2a: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-03-02 02:42:59,556 - DEBUG - Starting Layer 2 verification in Agent
2026-03-02 02:42:59,566 - DEBUG - Layer 2 path is seen for VM MAC 02:73:dc:29:3f:ee in agent 10.0.0.240
2026-03-02 02:42:59,566 - DEBUG - Active layer 2 route in agent is present for VMI tap73dc293f-ee
2026-03-02 02:42:59,567 - DEBUG - L2 label(29) matches bw route table and interface table
2026-03-02 02:42:59,577 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507 is found in Agent of node 10.0.0.240
2026-03-02 02:42:59,722 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507 is consistent in agent 10.0.0.240
2026-03-02 02:42:59,722 - DEBUG - Route for VM IP ['191.142.253.131', '2be7:5cc5:dca2:259c:e3b7:dc2a:0:3'] is consistent in agent 10.0.0.240
2026-03-02 02:42:59,722 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507 verification for VM ctest-VM21-13259595 in Agent 10.0.0.240 passed
2026-03-02 02:42:59,836 - DEBUG - Starting all layer 2 verification in agent 10.0.0.240
2026-03-02 02:42:59,851 - DEBUG - Route for VM MAC 02:73:dc:29:3f:ee is consistent in agent 10.0.0.240
2026-03-02 02:43:03,059 - 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 1021ms')
2026-03-02 02:43:03,059 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-13259595 failed!
2026-03-02 02:43:03,075 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507 is 191.142.253.129 and allocation pool is NOT set
2026-03-02 02:43:03,075 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507 is 2be7:5cc5:dca2:259c:e3b7:dc2a:0:1 and allocation pool is NOT set
2026-03-02 02:43:05,151 - 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=12.0 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.71 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 = 3.713/7.874/12.035/4.161 ms')
2026-03-02 02:43:05,151 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-13259595 passed
2026-03-02 02:43:05,151 - INFO - VM ctest-VM21-13259595 verifications in Compute nodes passed
2026-03-02 02:43:05,435 - DEBUG - Validated VM route 191.142.253.131 in vrouter of 10.0.0.240
2026-03-02 02:43:05,443 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-03-02 02:43:05,443 - INFO - Validated routes of VM ctest-VM21-13259595 in all vrouters
2026-03-02 02:43:05,892 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node
2026-03-02 02:43:06,031 - DEBUG - Layer2 route found for VM MAC 02:73:dc:29:3f:ee in Control-node 10.0.0.27
2026-03-02 02:43:06,031 - DEBUG - L2 Label for VM ctest-VM21-13259595 same between Control-node 10.0.0.27 and Agent, Expected: 29, Seen: 29
2026-03-02 02:43:06,139 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.27, not supported
2026-03-02 02:43:06,139 - DEBUG - Starting all layer2 verification in 10.0.0.242 Control Node
2026-03-02 02:43:06,262 - DEBUG - Layer2 route found for VM MAC 02:73:dc:29:3f:ee in Control-node 10.0.0.242
2026-03-02 02:43:06,262 - DEBUG - L2 Label for VM ctest-VM21-13259595 same between Control-node 10.0.0.242 and Agent, Expected: 29, Seen: 29
2026-03-02 02:43:06,371 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.242, not supported
2026-03-02 02:43:06,371 - INFO - Verification in Control-nodes for VM ctest-VM21-13259595 passed
2026-03-02 02:43:06,371 - DEBUG - Verifying the vm in opserver
2026-03-02 02:43:06,371 - DEBUG - Verifying in collector 10.0.0.23 ...
2026-03-02 02:43:06,371 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/819996ec-95ae-411c-bb23-d0ebdf51a805?flat
2026-03-02 02:43:06,390 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-17723440:73dc293f-eed6-4453-9643-4eaf7e4d52b3?flat
2026-03-02 02:43:06,414 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-17723440:73dc293f-eed6-4453-9643-4eaf7e4d52b3?flat
2026-03-02 02:43:06,439 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-17723440:73dc293f-eed6-4453-9643-4eaf7e4d52b3?flat
2026-03-02 02:43:06,460 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/819996ec-95ae-411c-bb23-d0ebdf51a805?flat
2026-03-02 02:43:06,478 - DEBUG - Verifying vm in vn uve
2026-03-02 02:43:06,478 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-17723440:73dc293f-eed6-4453-9643-4eaf7e4d52b3?flat
2026-03-02 02:43:06,503 - DEBUG - VM uve shows interface as {'ip4_active': True, 'ip6_active': True, 'gateway': '191.142.253.129', 'vn_uuid': 'b3151025-5b7c-45f1-97af-91a62eba7b7a', 'l2_active': True, 'vm_name': 'ctest-VM21-13259595', 'is_health_check_active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507', 'admin_state': True, 'ip_address': '191.142.253.131', 'vm_uuid': '819996ec-95ae-411c-bb23-d0ebdf51a805', 'tx_vlan': 65535, 'label': 25, 'ip6_address': '2be7:5cc5:dca2:259c:e3b7:dc2a:0:3', 'rx_vlan': 65535, 'uuid': '73dc293f-eed6-4453-9643-4eaf7e4d52b3', 'port_mirror_enabled': False, 'fixed_ip4_list': ['191.142.253.131'], 'mac_address': '02:73:dc:29:3f:ee', '__T': 1772419361191202, 'vhostuser_mode': 0, 'active': True, 'fixed_ip6_list': ['2be7:5cc5:dca2:259c:e3b7:dc2a:0:3']}
2026-03-02 02:43:06,503 - DEBUG - VM uve shows ip address as ['191.142.253.131', '2be7:5cc5:dca2:259c:e3b7:dc2a:0:3']
2026-03-02 02:43:06,503 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507
2026-03-02 02:43:06,503 - INFO - Verifying through opserver in 10.0.0.242
2026-03-02 02:43:06,503 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507?flat
2026-03-02 02:43:06,525 - DEBUG - expected vm list 819996ec-95ae-411c-bb23-d0ebdf51a805
2026-03-02 02:43:06,525 - DEBUG - Extracted vm list ['819996ec-95ae-411c-bb23-d0ebdf51a805']
2026-03-02 02:43:06,525 - INFO - VM 819996ec-95ae-411c-bb23-d0ebdf51a805 is present in default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507
2026-03-02 02:43:06,525 - DEBUG - Verifying vm in vrouter uve
2026-03-02 02:43:06,525 - DEBUG - Getting info from collector 10.0.0.23..
2026-03-02 02:43:06,525 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/819996ec-95ae-411c-bb23-d0ebdf51a805?flat
2026-03-02 02:43:06,541 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5413-2']
2026-03-02 02:43:06,541 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-2:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:43:06,579 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-2?flat
2026-03-02 02:43:06,612 - DEBUG - VM 819996ec-95ae-411c-bb23-d0ebdf51a805 is present in vrouter an-jenkins-deploy-platform-ansible-os-5413-2 uve
2026-03-02 02:43:06,612 - DEBUG - Validated that VM ctest-VM21-13259595 is in Vrouter an-jenkins-deploy-platform-ansible-os-5413-2 UVE
2026-03-02 02:43:06,612 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-03-02 02:43:06,619 - DEBUG - Expected tap interface of VM uuid 819996ec-95ae-411c-bb23-d0ebdf51a805 is default-domain:ctest-TestPolicyAclIpv4v6-17723440:73dc293f-eed6-4453-9643-4eaf7e4d52b3
2026-03-02 02:43:06,619 - DEBUG - Expected VN of VM uuid 819996ec-95ae-411c-bb23-d0ebdf51a805 is default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507
2026-03-02 02:43:06,619 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-2:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:43:06,653 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-2?flat
2026-03-02 02:43:06,684 - DEBUG - VM 819996ec-95ae-411c-bb23-d0ebdf51a805 is present in vrouter an-jenkins-deploy-platform-ansible-os-5413-2 uve
2026-03-02 02:43:06,684 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-17723440:73dc293f-eed6-4453-9643-4eaf7e4d52b3 of vm 819996ec-95ae-411c-bb23-d0ebdf51a805 is present in vrouter an-jenkins-deploy-platform-ansible-os-5413-2 uve
2026-03-02 02:43:06,684 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507 of vm 819996ec-95ae-411c-bb23-d0ebdf51a805 is present in vrouter an-jenkins-deploy-platform-ansible-os-5413-2 uve
2026-03-02 02:43:06,684 - DEBUG - Verifying the 819996ec-95ae-411c-bb23-d0ebdf51a805 virtual network link through opserver 10.0.0.242
2026-03-02 02:43:06,684 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machines
2026-03-02 02:43:06,698 - DEBUG - VM link and name as {'name': '819996ec-95ae-411c-bb23-d0ebdf51a805', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-machine/819996ec-95ae-411c-bb23-d0ebdf51a805?flat'}
2026-03-02 02:43:06,698 - DEBUG - Verifying the 819996ec-95ae-411c-bb23-d0ebdf51a805 virtual network link through opserver 10.0.0.27
2026-03-02 02:43:06,698 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-03-02 02:43:06,712 - DEBUG - VM link and name as {'name': '819996ec-95ae-411c-bb23-d0ebdf51a805', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/819996ec-95ae-411c-bb23-d0ebdf51a805?flat'}
2026-03-02 02:43:06,712 - DEBUG - Verifying the 819996ec-95ae-411c-bb23-d0ebdf51a805 virtual network link through opserver 10.0.0.23
2026-03-02 02:43:06,712 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-03-02 02:43:06,724 - DEBUG - VM link and name as {'name': '819996ec-95ae-411c-bb23-d0ebdf51a805', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/819996ec-95ae-411c-bb23-d0ebdf51a805?flat'}
2026-03-02 02:43:06,724 - INFO - VM ctest-VM21-13259595 validations in Opserver passed
2026-03-02 02:43:06,805 - DEBUG - VM is in ACTIVE state now
2026-03-02 02:43:06,805 - INFO - VM name : ctest-VM31-96380439
2026-03-02 02:43:06,886 - DEBUG - VM ctest-VM31-96380439 ID is 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5
2026-03-02 02:43:06,967 - DEBUG - VM is in ACTIVE state now
2026-03-02 02:43:06,968 - DEBUG - VM ctest-VM31-96380439 launched on Node an-jenkins-deploy-platform-ansible-os-5413-1
2026-03-02 02:43:07,049 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5
2026-03-02 02:43:07,058 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5
2026-03-02 02:43:07,065 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/2fa71d81-ce87-45f0-a2c6-6498e82146c5
2026-03-02 02:43:07,074 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5
2026-03-02 02:43:07,080 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/2fa71d81-ce87-45f0-a2c6-6498e82146c5
2026-03-02 02:43:07,090 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/c304303f-2e71-48f6-8319-499a89abcb6c
2026-03-02 02:43:07,099 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/f2c6c88e-89f5-4d94-847b-af0fde45464b
2026-03-02 02:43:07,109 - DEBUG - Verifying in api server 10.0.0.23
2026-03-02 02:43:07,109 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5
2026-03-02 02:43:07,115 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/2fa71d81-ce87-45f0-a2c6-6498e82146c5
2026-03-02 02:43:07,125 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/c304303f-2e71-48f6-8319-499a89abcb6c
2026-03-02 02:43:07,132 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/f2c6c88e-89f5-4d94-847b-af0fde45464b
2026-03-02 02:43:07,138 - INFO - VM ctest-VM31-96380439 verfication in all API Servers passed
2026-03-02 02:43:07,183 - DEBUG - VM ctest-VM31-96380439 Tap interface: {'index': '4', 'name': 'tap2fa71d81-ce', 'uuid': '2fa71d81-ce87-45f0-a2c6-6498e82146c5', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908:ctest-VN3-29328908', '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': '30', 'l2_label': '34', 'vxlan_id': '17', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908', 'vm_uuid': '5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5', 'vm_name': 'ctest-VM31-96380439', 'ip_addr': '214.86.152.131', 'mac_addr': '02:2f:a7:1d:81:ce', '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-17723440:2fa71d81-ce87-45f0-a2c6-6498e82146c5', 'sg_uuid_list': ['604f5b22-17b1-48ea-8e4b-cb3ba6e7e7ad'], 'static_route_list': None, 'vm_project_uuid': '48910b0c-9249-4308-a3ad-d4c3b57cb7c1', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '3e8b:64f5:878b:6cb9:883b:d274: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-03-02 02:43:07,183 - DEBUG - Agent 10.0.0.41 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908:ctest-VN3-29328908
2026-03-02 02:43:07,206 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908:ctest-VN3-29328908', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908', 'table_label': '-1', 'vxlan_id': '17', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.129:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-03-02 02:43:07,330 - DEBUG - VM ctest-VM31-96380439 : Tap interface tap2fa71d81-ce is set to Active
2026-03-02 02:43:07,331 - DEBUG - Tap interface tap2fa71d81-ce detail : {'index': '4', 'name': 'tap2fa71d81-ce', 'uuid': '2fa71d81-ce87-45f0-a2c6-6498e82146c5', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908:ctest-VN3-29328908', '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': '30', 'l2_label': '34', 'vxlan_id': '17', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908', 'vm_uuid': '5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5', 'vm_name': 'ctest-VM31-96380439', 'ip_addr': '214.86.152.131', 'mac_addr': '02:2f:a7:1d:81:ce', '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-17723440:2fa71d81-ce87-45f0-a2c6-6498e82146c5', 'sg_uuid_list': ['604f5b22-17b1-48ea-8e4b-cb3ba6e7e7ad'], 'static_route_list': None, 'vm_project_uuid': '48910b0c-9249-4308-a3ad-d4c3b57cb7c1', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '3e8b:64f5:878b:6cb9:883b:d274: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-03-02 02:43:07,402 - DEBUG - Starting Layer 2 verification in Agent
2026-03-02 02:43:07,421 - DEBUG - Layer 2 path is seen for VM MAC 02:2f:a7:1d:81:ce in agent 10.0.0.41
2026-03-02 02:43:07,421 - DEBUG - Active layer 2 route in agent is present for VMI tap2fa71d81-ce
2026-03-02 02:43:07,421 - DEBUG - L2 label(34) matches bw route table and interface table
2026-03-02 02:43:07,427 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908 is found in Agent of node 10.0.0.41
2026-03-02 02:43:07,550 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908 is consistent in agent 10.0.0.41
2026-03-02 02:43:07,551 - DEBUG - Route for VM IP ['3e8b:64f5:878b:6cb9:883b:d274:0:3', '214.86.152.131'] is consistent in agent 10.0.0.41
2026-03-02 02:43:07,551 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908 verification for VM ctest-VM31-96380439 in Agent 10.0.0.41 passed
2026-03-02 02:43:07,659 - DEBUG - Starting all layer 2 verification in agent 10.0.0.41
2026-03-02 02:43:07,669 - DEBUG - Route for VM MAC 02:2f:a7:1d:81:ce is consistent in agent 10.0.0.41
2026-03-02 02:43:08,851 - 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.21 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=5.34 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 = 5.208/5.273/5.339/0.065 ms')
2026-03-02 02:43:08,852 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-96380439 passed
2026-03-02 02:43:08,852 - INFO - VM ctest-VM31-96380439 verifications in Compute nodes passed
2026-03-02 02:43:09,053 - DEBUG - Validated VM route 214.86.152.131 in vrouter of 10.0.0.41
2026-03-02 02:43:09,056 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-03-02 02:43:09,056 - INFO - Validated routes of VM ctest-VM31-96380439 in all vrouters
2026-03-02 02:43:09,539 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node
2026-03-02 02:43:09,652 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.27, not supported
2026-03-02 02:43:09,770 - DEBUG - Layer2 route found for VM MAC 02:2f:a7:1d:81:ce in Control-node 10.0.0.27
2026-03-02 02:43:09,770 - DEBUG - L2 Label for VM ctest-VM31-96380439 same between Control-node 10.0.0.27 and Agent, Expected: 34, Seen: 34
2026-03-02 02:43:09,770 - DEBUG - Starting all layer2 verification in 10.0.0.242 Control Node
2026-03-02 02:43:09,884 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.242, not supported
2026-03-02 02:43:10,010 - DEBUG - Layer2 route found for VM MAC 02:2f:a7:1d:81:ce in Control-node 10.0.0.242
2026-03-02 02:43:10,010 - DEBUG - L2 Label for VM ctest-VM31-96380439 same between Control-node 10.0.0.242 and Agent, Expected: 34, Seen: 34
2026-03-02 02:43:10,010 - INFO - Verification in Control-nodes for VM ctest-VM31-96380439 passed
2026-03-02 02:43:10,010 - DEBUG - Verifying the vm in opserver
2026-03-02 02:43:10,010 - DEBUG - Verifying in collector 10.0.0.23 ...
2026-03-02 02:43:10,010 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5?flat
2026-03-02 02:43:10,034 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-17723440:2fa71d81-ce87-45f0-a2c6-6498e82146c5?flat
2026-03-02 02:43:10,053 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-17723440:2fa71d81-ce87-45f0-a2c6-6498e82146c5?flat
2026-03-02 02:43:10,076 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-17723440:2fa71d81-ce87-45f0-a2c6-6498e82146c5?flat
2026-03-02 02:43:10,101 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5?flat
2026-03-02 02:43:10,119 - DEBUG - Verifying vm in vn uve
2026-03-02 02:43:10,119 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-17723440:2fa71d81-ce87-45f0-a2c6-6498e82146c5?flat
2026-03-02 02:43:10,151 - DEBUG - VM uve shows interface as {'ip6_address': '3e8b:64f5:878b:6cb9:883b:d274:0:3', 'vm_name': 'ctest-VM31-96380439', 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908', 'ip_address': '214.86.152.131', 'vn_uuid': '0ba68893-8afc-4728-a316-91542df589f2', 'fixed_ip4_list': ['214.86.152.131'], 'ip4_active': True, 'ip6_active': True, 'port_mirror_enabled': False, 'gateway': '214.86.152.129', 'vm_uuid': '5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5', 'uuid': '2fa71d81-ce87-45f0-a2c6-6498e82146c5', 'tx_vlan': 65535, 'l2_active': True, 'is_health_check_active': True, 'admin_state': True, 'fixed_ip6_list': ['3e8b:64f5:878b:6cb9:883b:d274:0:3'], 'vhostuser_mode': 0, '__T': 1772419339042226, 'active': True, 'rx_vlan': 65535, 'label': 30, 'mac_address': '02:2f:a7:1d:81:ce'}
2026-03-02 02:43:10,151 - DEBUG - VM uve shows ip address as ['214.86.152.131', '3e8b:64f5:878b:6cb9:883b:d274:0:3']
2026-03-02 02:43:10,151 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908
2026-03-02 02:43:10,151 - INFO - Verifying through opserver in 10.0.0.242
2026-03-02 02:43:10,151 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908?flat
2026-03-02 02:43:10,180 - DEBUG - expected vm list 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5
2026-03-02 02:43:10,180 - DEBUG - Extracted vm list ['5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5']
2026-03-02 02:43:10,180 - INFO - VM 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is present in default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908
2026-03-02 02:43:10,180 - DEBUG - Verifying vm in vrouter uve
2026-03-02 02:43:10,180 - DEBUG - Getting info from collector 10.0.0.23..
2026-03-02 02:43:10,181 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5?flat
2026-03-02 02:43:10,198 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5413-1']
2026-03-02 02:43:10,199 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-1:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:43:10,234 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-1?flat
2026-03-02 02:43:10,269 - DEBUG - VM 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:43:10,269 - DEBUG - Validated that VM ctest-VM31-96380439 is in Vrouter an-jenkins-deploy-platform-ansible-os-5413-1 UVE
2026-03-02 02:43:10,269 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-03-02 02:43:10,275 - DEBUG - Expected tap interface of VM uuid 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is default-domain:ctest-TestPolicyAclIpv4v6-17723440:2fa71d81-ce87-45f0-a2c6-6498e82146c5
2026-03-02 02:43:10,276 - DEBUG - Expected VN of VM uuid 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908
2026-03-02 02:43:10,276 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-1:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:43:10,313 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-1?flat
2026-03-02 02:43:10,344 - DEBUG - VM 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:43:10,344 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-17723440:2fa71d81-ce87-45f0-a2c6-6498e82146c5 of vm 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:43:10,344 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908 of vm 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:43:10,344 - DEBUG - Verifying the 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 virtual network link through opserver 10.0.0.242
2026-03-02 02:43:10,344 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machines
2026-03-02 02:43:10,358 - DEBUG - VM link and name as {'name': '5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-machine/5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5?flat'}
2026-03-02 02:43:10,358 - DEBUG - Verifying the 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 virtual network link through opserver 10.0.0.27
2026-03-02 02:43:10,358 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-03-02 02:43:10,372 - DEBUG - VM link and name as {'name': '5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5?flat'}
2026-03-02 02:43:10,372 - DEBUG - Verifying the 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 virtual network link through opserver 10.0.0.23
2026-03-02 02:43:10,372 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-03-02 02:43:10,384 - DEBUG - VM link and name as {'name': '5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5?flat'}
2026-03-02 02:43:10,385 - INFO - VM ctest-VM31-96380439 validations in Opserver passed
2026-03-02 02:43:10,385 - INFO - Waiting for VM ctest-VM11-24757346 to be up..
2026-03-02 02:43:10,385 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-02 02:43:10,385 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/189bf50d-6ef4-456c-8bac-d0d654e1efa8
2026-03-02 02:43:10,392 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/f7a4b1cc-9dc7-45ea-bd47-3d4c281944d3
2026-03-02 02:43:11,557 - 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.34 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.48 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.476/4.909/7.342/2.433 ms')
2026-03-02 02:43:11,557 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-24757346 passed
2026-03-02 02:43:11,626 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-02 02:43:11,626 - DEBUG - Waiting to SSH to VM ctest-VM11-24757346, IP 72.161.99.195, Port 22
2026-03-02 02:43:11,698 - 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-03-02 02:43:11,779 - DEBUG - VM ctest-VM11-24757346 is NOT ready for SSH connections, VM status: ACTIVE
2026-03-02 02:43:16,780 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-02 02:43:16,781 - DEBUG - Waiting to SSH to VM ctest-VM11-24757346, IP 72.161.99.195, Port 22
2026-03-02 02:43:16,951 - DEBUG - VM ctest-VM11-24757346 is ready for SSH connections
2026-03-02 02:43:16,951 - INFO - Waiting for VM ctest-VM21-13259595 to be up..
2026-03-02 02:43:16,951 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-02 02:43:16,952 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/819996ec-95ae-411c-bb23-d0ebdf51a805
2026-03-02 02:43:16,962 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/73dc293f-eed6-4453-9643-4eaf7e4d52b3
2026-03-02 02:43:18,136 - 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.69 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.477 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 = 0.477/1.083/1.690/0.606 ms')
2026-03-02 02:43:18,136 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-13259595 passed
2026-03-02 02:43:18,207 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-02 02:43:18,207 - DEBUG - Waiting to SSH to VM ctest-VM21-13259595, IP 191.142.253.131, Port 22
2026-03-02 02:43:18,274 - 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-03-02 02:43:18,360 - DEBUG - VM ctest-VM21-13259595 is NOT ready for SSH connections, VM status: ACTIVE
2026-03-02 02:43:23,361 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-02 02:43:23,361 - DEBUG - Waiting to SSH to VM ctest-VM21-13259595, IP 191.142.253.131, Port 22
2026-03-02 02:43:23,429 - 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-03-02 02:43:23,542 - DEBUG - VM ctest-VM21-13259595 is NOT ready for SSH connections, VM status: ACTIVE
2026-03-02 02:43:28,542 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-02 02:43:28,543 - DEBUG - Waiting to SSH to VM ctest-VM21-13259595, IP 191.142.253.131, Port 22
2026-03-02 02:43:28,610 - 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-03-02 02:43:28,704 - DEBUG - VM ctest-VM21-13259595 is NOT ready for SSH connections, VM status: ACTIVE
2026-03-02 02:43:33,705 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-02 02:43:33,705 - DEBUG - Waiting to SSH to VM ctest-VM21-13259595, IP 191.142.253.131, Port 22
2026-03-02 02:43:33,788 - 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-03-02 02:43:33,874 - DEBUG - VM ctest-VM21-13259595 is NOT ready for SSH connections, VM status: ACTIVE
2026-03-02 02:43:38,874 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-02 02:43:38,875 - DEBUG - Waiting to SSH to VM ctest-VM21-13259595, IP 191.142.253.131, Port 22
2026-03-02 02:43:39,057 - DEBUG - VM ctest-VM21-13259595 is ready for SSH connections
2026-03-02 02:43:39,057 - INFO - Waiting for VM ctest-VM31-96380439 to be up..
2026-03-02 02:43:39,057 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-02 02:43:39,058 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5
2026-03-02 02:43:39,065 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/2fa71d81-ce87-45f0-a2c6-6498e82146c5
2026-03-02 02:43:40,211 - 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=1.05 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.385 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.385/0.719/1.053/0.334 ms')
2026-03-02 02:43:40,211 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-96380439 passed
2026-03-02 02:43:40,288 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-02 02:43:40,288 - DEBUG - Waiting to SSH to VM ctest-VM31-96380439, IP 214.86.152.131, Port 22
2026-03-02 02:43:40,453 - DEBUG - VM ctest-VM31-96380439 is ready for SSH connections
2026-03-02 02:43:40,454 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 191.142.253.131, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2026-03-02 02:43:40,454 - DEBUG - ping -s 56 -c 3 -W 1 191.142.253.131
2026-03-02 02:44:21,893 - DEBUG - PING 191.142.253.131 (191.142.253.131) 56(84) bytes of data.
64 bytes from 191.142.253.131: icmp_req=1 ttl=63 time=12.4 ms
64 bytes from 191.142.253.131: icmp_req=2 ttl=63 time=1.29 ms
64 bytes from 191.142.253.131: icmp_req=3 ttl=63 time=1.15 ms
--- 191.142.253.131 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.156/4.949/12.401/5.269 ms
2026-03-02 02:44:21,893 - INFO - Ping to IP 191.142.253.131 from VM ctest-VM11-24757346 passed
2026-03-02 02:44:21,893 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 2be7:5cc5:dca2:259c:e3b7:dc2a:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2026-03-02 02:44:21,893 - DEBUG - ping6 -s 56 -c 3 -W 1 2be7:5cc5:dca2:259c:e3b7:dc2a:0:3
2026-03-02 02:44:24,418 - DEBUG - PING 2be7:5cc5:dca2:259c:e3b7:dc2a:0:3(2be7:5cc5:dca2:259c:e3b7:dc2a:0:3) 56 data bytes
64 bytes from 2be7:5cc5:dca2:259c:e3b7:dc2a:0:3: icmp_seq=1 ttl=64 time=8.68 ms
64 bytes from 2be7:5cc5:dca2:259c:e3b7:dc2a:0:3: icmp_seq=2 ttl=64 time=1.43 ms
64 bytes from 2be7:5cc5:dca2:259c:e3b7:dc2a:0:3: icmp_seq=3 ttl=64 time=1.30 ms
--- 2be7:5cc5:dca2:259c:e3b7:dc2a:0:3 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.305/3.807/8.685/3.449 ms
2026-03-02 02:44:24,418 - INFO - Ping to IP 2be7:5cc5:dca2:259c:e3b7:dc2a:0:3 from VM ctest-VM11-24757346 passed
2026-03-02 02:44:24,418 - INFO - Test with src as policy and dst as any PASSED
2026-03-02 02:44:24,418 - INFO - Deleting VM ctest-VM31-96380439
2026-03-02 02:44:24,512 - DEBUG - Verifying in api server 10.0.0.23
2026-03-02 02:44:24,512 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5
2026-03-02 02:44:24,521 - DEBUG - VM ID 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 of VM ctest-VM31-96380439 is still found in API Server
2026-03-02 02:44:26,522 - DEBUG - Verifying in api server 10.0.0.23
2026-03-02 02:44:26,522 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5
2026-03-02 02:44:26,528 - DEBUG - Response Code: 404
2026-03-02 02:44:26,528 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5
2026-03-02 02:44:26,534 - DEBUG - Response Code: 404
2026-03-02 02:44:26,534 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5
2026-03-02 02:44:26,541 - DEBUG - Response Code: 404
2026-03-02 02:44:26,541 - INFO - VM ctest-VM31-96380439 is fully removed in API-Server
2026-03-02 02:44:26,698 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-03-02 02:44:26,737 - DEBUG - Validated that vrouter 10.0.0.41 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908
2026-03-02 02:44:26,748 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908
2026-03-02 02:44:26,748 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908
2026-03-02 02:44:26,748 - INFO - VM ctest-VM31-96380439 is removed in Compute, and routes are removed in all compute nodes
2026-03-02 02:44:26,980 - INFO - Routes for VM ctest-VM31-96380439 is removed in all control-nodes
2026-03-02 02:44:26,980 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-1:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:44:27,021 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-1?flat
2026-03-02 02:44:27,057 - DEBUG - VM 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:44:27,057 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:44:27,057 - DEBUG - interface for vm 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 deleted from vrouter uve ...
2026-03-02 02:44:27,057 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:44:30,058 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-1:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:44:30,094 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-1?flat
2026-03-02 02:44:30,125 - DEBUG - VM 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:44:30,125 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:44:30,125 - DEBUG - interface for vm 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 deleted from vrouter uve ...
2026-03-02 02:44:30,125 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:44:33,126 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-1:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:44:33,164 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-1?flat
2026-03-02 02:44:33,199 - DEBUG - VM 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:44:33,199 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:44:33,199 - DEBUG - interface for vm 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 deleted from vrouter uve ...
2026-03-02 02:44:33,199 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:44:36,199 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-1:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:44:36,235 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-1?flat
2026-03-02 02:44:36,266 - DEBUG - VM 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:44:36,266 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:44:36,266 - DEBUG - interface for vm 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 deleted from vrouter uve ...
2026-03-02 02:44:36,266 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:44:39,267 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-1:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:44:39,305 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-1?flat
2026-03-02 02:44:39,340 - DEBUG - VM 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:44:39,340 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:44:39,340 - DEBUG - interface for vm 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 deleted from vrouter uve ...
2026-03-02 02:44:39,340 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:44:42,341 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-1:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:44:42,377 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-1?flat
2026-03-02 02:44:42,407 - DEBUG - VM 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:44:42,407 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:44:42,407 - DEBUG - interface for vm 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 deleted from vrouter uve ...
2026-03-02 02:44:42,407 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:44:45,407 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-1:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:44:45,444 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-1?flat
2026-03-02 02:44:45,475 - DEBUG - VM 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:44:45,475 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:44:45,475 - DEBUG - interface for vm 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 deleted from vrouter uve ...
2026-03-02 02:44:45,475 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:44:48,475 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-1:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:44:48,517 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-1?flat
2026-03-02 02:44:48,550 - DEBUG - VM 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:44:48,550 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:44:48,550 - DEBUG - interface for vm 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 deleted from vrouter uve ...
2026-03-02 02:44:48,550 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:44:51,550 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-1:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:44:51,602 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-1?flat
2026-03-02 02:44:51,636 - DEBUG - VM 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:44:51,636 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:44:51,636 - DEBUG - interface for vm 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 deleted from vrouter uve ...
2026-03-02 02:44:51,636 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:44:51,636 - DEBUG - Validated that VM 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is removed in Vrouter UVE
2026-03-02 02:44:51,637 - DEBUG - Verifying through opserver in 10.0.0.242
2026-03-02 02:44:51,637 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908?flat
2026-03-02 02:44:51,657 - DEBUG - 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 BM not in default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN3-29328908 uve
2026-03-02 02:44:51,657 - DEBUG - Validated that VM 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is not present in the VN UVE
2026-03-02 02:44:51,657 - DEBUG - Verifying the 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 virtual network link through opserver 10.0.0.242
2026-03-02 02:44:51,657 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machines
2026-03-02 02:44:51,670 - INFO - 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 vm uve deleted from opserver
2026-03-02 02:44:51,671 - DEBUG - Verifying the 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 virtual network link through opserver 10.0.0.27
2026-03-02 02:44:51,671 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-03-02 02:44:51,686 - INFO - 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 vm uve deleted from opserver
2026-03-02 02:44:51,686 - DEBUG - Verifying the 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 virtual network link through opserver 10.0.0.23
2026-03-02 02:44:51,686 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-03-02 02:44:51,698 - INFO - 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 vm uve deleted from opserver
2026-03-02 02:44:51,699 - INFO - Validated that VM 5fc3fcb3-68ae-40cf-93d5-20955f0b4fc5 is removed from Opserver
2026-03-02 02:44:51,699 - INFO - Deleting VM ctest-VM21-13259595
2026-03-02 02:44:51,797 - DEBUG - Verifying in api server 10.0.0.23
2026-03-02 02:44:51,798 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/819996ec-95ae-411c-bb23-d0ebdf51a805
2026-03-02 02:44:51,806 - DEBUG - VM ID 819996ec-95ae-411c-bb23-d0ebdf51a805 of VM ctest-VM21-13259595 is still found in API Server
2026-03-02 02:44:53,806 - DEBUG - Verifying in api server 10.0.0.23
2026-03-02 02:44:53,807 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/819996ec-95ae-411c-bb23-d0ebdf51a805
2026-03-02 02:44:53,815 - DEBUG - Response Code: 404
2026-03-02 02:44:53,815 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/819996ec-95ae-411c-bb23-d0ebdf51a805
2026-03-02 02:44:53,821 - DEBUG - Response Code: 404
2026-03-02 02:44:53,821 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/819996ec-95ae-411c-bb23-d0ebdf51a805
2026-03-02 02:44:53,826 - DEBUG - Response Code: 404
2026-03-02 02:44:53,827 - INFO - VM ctest-VM21-13259595 is fully removed in API-Server
2026-03-02 02:44:53,979 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-03-02 02:44:54,020 - DEBUG - Validated that vrouter 10.0.0.41 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507
2026-03-02 02:44:54,029 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507
2026-03-02 02:44:54,029 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507
2026-03-02 02:44:54,029 - INFO - VM ctest-VM21-13259595 is removed in Compute, and routes are removed in all compute nodes
2026-03-02 02:44:54,257 - INFO - Routes for VM ctest-VM21-13259595 is removed in all control-nodes
2026-03-02 02:44:54,257 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-2:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:44:54,294 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-2?flat
2026-03-02 02:44:54,325 - DEBUG - VM 819996ec-95ae-411c-bb23-d0ebdf51a805 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5413-2 uve
2026-03-02 02:44:54,325 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:44:54,326 - DEBUG - interface for vm 819996ec-95ae-411c-bb23-d0ebdf51a805 deleted from vrouter uve ...
2026-03-02 02:44:54,326 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:44:57,327 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-2:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:44:57,369 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-2?flat
2026-03-02 02:44:57,405 - DEBUG - VM 819996ec-95ae-411c-bb23-d0ebdf51a805 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5413-2 uve
2026-03-02 02:44:57,405 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:44:57,405 - DEBUG - interface for vm 819996ec-95ae-411c-bb23-d0ebdf51a805 deleted from vrouter uve ...
2026-03-02 02:44:57,405 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:45:00,406 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-2:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:45:00,455 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-2?flat
2026-03-02 02:45:00,489 - DEBUG - VM 819996ec-95ae-411c-bb23-d0ebdf51a805 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5413-2 uve
2026-03-02 02:45:00,489 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:45:00,489 - DEBUG - interface for vm 819996ec-95ae-411c-bb23-d0ebdf51a805 deleted from vrouter uve ...
2026-03-02 02:45:00,489 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:45:03,490 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-2:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:45:03,530 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-2?flat
2026-03-02 02:45:03,563 - DEBUG - VM 819996ec-95ae-411c-bb23-d0ebdf51a805 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5413-2 uve
2026-03-02 02:45:03,563 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:45:03,563 - DEBUG - interface for vm 819996ec-95ae-411c-bb23-d0ebdf51a805 deleted from vrouter uve ...
2026-03-02 02:45:03,563 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:45:06,563 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-2:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:45:06,603 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-2?flat
2026-03-02 02:45:06,643 - DEBUG - VM 819996ec-95ae-411c-bb23-d0ebdf51a805 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5413-2 uve
2026-03-02 02:45:06,643 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:45:06,643 - DEBUG - interface for vm 819996ec-95ae-411c-bb23-d0ebdf51a805 deleted from vrouter uve ...
2026-03-02 02:45:06,643 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:45:09,643 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-2:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:45:09,681 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-2?flat
2026-03-02 02:45:09,715 - DEBUG - VM 819996ec-95ae-411c-bb23-d0ebdf51a805 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5413-2 uve
2026-03-02 02:45:09,715 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:45:09,715 - DEBUG - interface for vm 819996ec-95ae-411c-bb23-d0ebdf51a805 deleted from vrouter uve ...
2026-03-02 02:45:09,715 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:45:12,716 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-2:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:45:12,753 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-2?flat
2026-03-02 02:45:12,784 - DEBUG - VM 819996ec-95ae-411c-bb23-d0ebdf51a805 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5413-2 uve
2026-03-02 02:45:12,784 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:45:12,784 - DEBUG - interface for vm 819996ec-95ae-411c-bb23-d0ebdf51a805 deleted from vrouter uve ...
2026-03-02 02:45:12,785 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:45:12,785 - DEBUG - Validated that VM 819996ec-95ae-411c-bb23-d0ebdf51a805 is removed in Vrouter UVE
2026-03-02 02:45:12,785 - DEBUG - Verifying through opserver in 10.0.0.242
2026-03-02 02:45:12,785 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507?flat
2026-03-02 02:45:12,802 - DEBUG - 819996ec-95ae-411c-bb23-d0ebdf51a805 BM not in default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN2-32434507 uve
2026-03-02 02:45:12,802 - DEBUG - Validated that VM 819996ec-95ae-411c-bb23-d0ebdf51a805 is not present in the VN UVE
2026-03-02 02:45:12,802 - DEBUG - Verifying the 819996ec-95ae-411c-bb23-d0ebdf51a805 virtual network link through opserver 10.0.0.242
2026-03-02 02:45:12,802 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machines
2026-03-02 02:45:12,814 - INFO - 819996ec-95ae-411c-bb23-d0ebdf51a805 vm uve deleted from opserver
2026-03-02 02:45:12,814 - DEBUG - Verifying the 819996ec-95ae-411c-bb23-d0ebdf51a805 virtual network link through opserver 10.0.0.27
2026-03-02 02:45:12,814 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-03-02 02:45:12,827 - INFO - 819996ec-95ae-411c-bb23-d0ebdf51a805 vm uve deleted from opserver
2026-03-02 02:45:12,827 - DEBUG - Verifying the 819996ec-95ae-411c-bb23-d0ebdf51a805 virtual network link through opserver 10.0.0.23
2026-03-02 02:45:12,827 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-03-02 02:45:12,839 - INFO - 819996ec-95ae-411c-bb23-d0ebdf51a805 vm uve deleted from opserver
2026-03-02 02:45:12,839 - INFO - Validated that VM 819996ec-95ae-411c-bb23-d0ebdf51a805 is removed from Opserver
2026-03-02 02:45:12,839 - INFO - Deleting VM ctest-VM11-24757346
2026-03-02 02:45:12,920 - DEBUG - Verifying in api server 10.0.0.23
2026-03-02 02:45:12,920 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/189bf50d-6ef4-456c-8bac-d0d654e1efa8
2026-03-02 02:45:12,929 - DEBUG - VM ID 189bf50d-6ef4-456c-8bac-d0d654e1efa8 of VM ctest-VM11-24757346 is still found in API Server
2026-03-02 02:45:14,929 - DEBUG - Verifying in api server 10.0.0.23
2026-03-02 02:45:14,929 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/189bf50d-6ef4-456c-8bac-d0d654e1efa8
2026-03-02 02:45:14,935 - DEBUG - Response Code: 404
2026-03-02 02:45:14,935 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/189bf50d-6ef4-456c-8bac-d0d654e1efa8
2026-03-02 02:45:14,941 - DEBUG - Response Code: 404
2026-03-02 02:45:14,941 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/189bf50d-6ef4-456c-8bac-d0d654e1efa8
2026-03-02 02:45:14,947 - DEBUG - Response Code: 404
2026-03-02 02:45:14,947 - INFO - VM ctest-VM11-24757346 is fully removed in API-Server
2026-03-02 02:45:15,102 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-03-02 02:45:15,141 - DEBUG - Validated that vrouter 10.0.0.41 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604
2026-03-02 02:45:15,151 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604
2026-03-02 02:45:15,151 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604
2026-03-02 02:45:15,151 - INFO - VM ctest-VM11-24757346 is removed in Compute, and routes are removed in all compute nodes
2026-03-02 02:45:15,338 - INFO - Routes for VM ctest-VM11-24757346 is removed in all control-nodes
2026-03-02 02:45:15,338 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-1:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:45:15,374 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-1?flat
2026-03-02 02:45:15,409 - DEBUG - VM 189bf50d-6ef4-456c-8bac-d0d654e1efa8 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:45:15,409 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:45:15,409 - DEBUG - interface for vm 189bf50d-6ef4-456c-8bac-d0d654e1efa8 deleted from vrouter uve ...
2026-03-02 02:45:15,409 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:45:18,410 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-1:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:45:18,460 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-1?flat
2026-03-02 02:45:18,491 - DEBUG - VM 189bf50d-6ef4-456c-8bac-d0d654e1efa8 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:45:18,491 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:45:18,491 - DEBUG - interface for vm 189bf50d-6ef4-456c-8bac-d0d654e1efa8 deleted from vrouter uve ...
2026-03-02 02:45:18,491 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:45:21,491 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5413-1:Compute:contrail-vrouter-agent:0?flat
2026-03-02 02:45:21,542 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5413-1?flat
2026-03-02 02:45:21,593 - DEBUG - VM 189bf50d-6ef4-456c-8bac-d0d654e1efa8 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5413-1 uve
2026-03-02 02:45:21,593 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-02 02:45:21,593 - DEBUG - interface for vm 189bf50d-6ef4-456c-8bac-d0d654e1efa8 deleted from vrouter uve ...
2026-03-02 02:45:21,593 - DEBUG - Deleted interface not in error interface list ...
2026-03-02 02:45:21,593 - DEBUG - Validated that VM 189bf50d-6ef4-456c-8bac-d0d654e1efa8 is removed in Vrouter UVE
2026-03-02 02:45:21,593 - DEBUG - Verifying through opserver in 10.0.0.242
2026-03-02 02:45:21,593 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604?flat
2026-03-02 02:45:21,626 - DEBUG - 189bf50d-6ef4-456c-8bac-d0d654e1efa8 BM not in default-domain:ctest-TestPolicyAclIpv4v6-17723440:ctest-VN1-03474604 uve
2026-03-02 02:45:21,626 - DEBUG - Validated that VM 189bf50d-6ef4-456c-8bac-d0d654e1efa8 is not present in the VN UVE
2026-03-02 02:45:21,626 - DEBUG - Verifying the 189bf50d-6ef4-456c-8bac-d0d654e1efa8 virtual network link through opserver 10.0.0.242
2026-03-02 02:45:21,626 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machines
2026-03-02 02:45:21,641 - DEBUG - No links retuned for 189bf50d-6ef4-456c-8bac-d0d654e1efa8
2026-03-02 02:45:21,642 - INFO - 189bf50d-6ef4-456c-8bac-d0d654e1efa8 vm uve deleted from opserver
2026-03-02 02:45:21,642 - DEBUG - Verifying the 189bf50d-6ef4-456c-8bac-d0d654e1efa8 virtual network link through opserver 10.0.0.27
2026-03-02 02:45:21,642 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-03-02 02:45:21,660 - DEBUG - No links retuned for 189bf50d-6ef4-456c-8bac-d0d654e1efa8
2026-03-02 02:45:21,660 - INFO - 189bf50d-6ef4-456c-8bac-d0d654e1efa8 vm uve deleted from opserver
2026-03-02 02:45:21,660 - DEBUG - Verifying the 189bf50d-6ef4-456c-8bac-d0d654e1efa8 virtual network link through opserver 10.0.0.23
2026-03-02 02:45:21,660 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines
2026-03-02 02:45:21,674 - DEBUG - No links retuned for 189bf50d-6ef4-456c-8bac-d0d654e1efa8
2026-03-02 02:45:21,674 - INFO - 189bf50d-6ef4-456c-8bac-d0d654e1efa8 vm uve deleted from opserver
2026-03-02 02:45:21,674 - INFO - Validated that VM 189bf50d-6ef4-456c-8bac-d0d654e1efa8 is removed from Opserver
2026-03-02 02:45:21,675 - DEBUG - Detaching the Policy for VN :ctest-VN2-32434507
2026-03-02 02:45:21,675 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:45:21,684 - DEBUG - Requesting: http://10.0.0.242:8082/domain/cc2d0805-fa5b-4698-bfcc-d7811c08231a
2026-03-02 02:45:21,735 - DEBUG - Requesting: http://10.0.0.242:8082/project/48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:45:21,854 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/b3151025-5b7c-45f1-97af-91a62eba7b7a
2026-03-02 02:45:21,875 - DEBUG - Requesting: http://10.0.0.242:8082/network-policy/686c8625-75f0-4203-aafa-ddd9e098cf93
2026-03-02 02:45:22,161 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ctest-policy21-05210832']] from ctest-VN2-32434507
2026-03-02 02:45:22,161 - DEBUG - Detaching the Policy for VN :ctest-VN1-03474604
2026-03-02 02:45:22,161 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:45:22,167 - DEBUG - Requesting: http://10.0.0.242:8082/domain/cc2d0805-fa5b-4698-bfcc-d7811c08231a
2026-03-02 02:45:22,177 - DEBUG - Requesting: http://10.0.0.242:8082/project/48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:45:22,302 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-network/afc1311a-65f1-41ce-9831-e2a8985738b2
2026-03-02 02:45:22,333 - DEBUG - Requesting: http://10.0.0.242:8082/network-policy/9f0d20e1-6950-4288-8688-672999c20f1e
2026-03-02 02:45:22,348 - DEBUG - Requesting: http://10.0.0.242:8082/network-policy/6eaea92e-e77a-4c2e-9b84-a441ca3298ef
2026-03-02 02:45:22,650 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ctest-policy12-39811060'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-17723440', 'ctest-policy13-59530610']] from ctest-VN1-03474604
2026-03-02 02:45:22,751 - INFO - Deleted policy ctest-policy21-05210832
2026-03-02 02:45:22,833 - INFO - Deleted policy ctest-policy12-39811060
2026-03-02 02:45:22,890 - INFO - Deleted policy ctest-policy13-59530610
2026-03-02 02:45:22,890 - INFO - Deleting VN ctest-VN3-29328908
2026-03-02 02:45:23,196 - DEBUG - Response for deleting network ()
2026-03-02 02:45:23,196 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/5a79aa99-a24b-4680-8a2c-ad7c6d97a881
2026-03-02 02:45:23,202 - DEBUG - Response Code: 404
2026-03-02 02:45:23,202 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:45:23,223 - DEBUG - Requesting: http://10.0.0.242:8082/domain/cc2d0805-fa5b-4698-bfcc-d7811c08231a
2026-03-02 02:45:23,244 - DEBUG - Requesting: http://10.0.0.242:8082/project/48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:45:23,382 - INFO - Validated that VN ctest-VN3-29328908 is not found in API Server
2026-03-02 02:45:23,395 - DEBUG - VN ctest-VN3-29328908 is not present in Agent 10.0.0.41
2026-03-02 02:45:23,405 - DEBUG - VN ctest-VN3-29328908 is not present in Agent 10.0.0.240
2026-03-02 02:45:23,405 - INFO - Validated that VN ctest-VN3-29328908 is not in any agent
2026-03-02 02:45:23,439 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-29328908 info
2026-03-02 02:45:23,439 - INFO - Deleting VN ctest-VN2-32434507
2026-03-02 02:45:23,851 - DEBUG - Response for deleting network ()
2026-03-02 02:45:23,851 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/9d5bee9b-6231-4146-a8e5-21dc6b9771eb
2026-03-02 02:45:23,866 - DEBUG - Response Code: 404
2026-03-02 02:45:23,866 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:45:23,876 - DEBUG - Requesting: http://10.0.0.242:8082/domain/cc2d0805-fa5b-4698-bfcc-d7811c08231a
2026-03-02 02:45:23,891 - DEBUG - Requesting: http://10.0.0.242:8082/project/48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:45:23,986 - INFO - Validated that VN ctest-VN2-32434507 is not found in API Server
2026-03-02 02:45:23,997 - DEBUG - VN ctest-VN2-32434507 is not present in Agent 10.0.0.41
2026-03-02 02:45:24,012 - DEBUG - VN ctest-VN2-32434507 is not present in Agent 10.0.0.240
2026-03-02 02:45:24,012 - INFO - Validated that VN ctest-VN2-32434507 is not in any agent
2026-03-02 02:45:24,057 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-32434507 info
2026-03-02 02:45:24,058 - INFO - Deleting VN ctest-VN1-03474604
2026-03-02 02:45:24,390 - DEBUG - Response for deleting network ()
2026-03-02 02:45:24,391 - DEBUG - Requesting: http://10.0.0.242:8082/routing-instance/c0930fb8-25aa-4e5c-ba04-1a7cc3c70c67
2026-03-02 02:45:24,397 - DEBUG - Response Code: 404
2026-03-02 02:45:24,397 - DEBUG - Requesting: http://10.0.0.242:8082/domains
2026-03-02 02:45:24,405 - DEBUG - Requesting: http://10.0.0.242:8082/domain/cc2d0805-fa5b-4698-bfcc-d7811c08231a
2026-03-02 02:45:24,421 - DEBUG - Requesting: http://10.0.0.242:8082/project/48910b0c-9249-4308-a3ad-d4c3b57cb7c1
2026-03-02 02:45:24,545 - INFO - Validated that VN ctest-VN1-03474604 is not found in API Server
2026-03-02 02:45:24,558 - DEBUG - VN ctest-VN1-03474604 is not present in Agent 10.0.0.41
2026-03-02 02:45:24,572 - DEBUG - VN ctest-VN1-03474604 is not present in Agent 10.0.0.240
2026-03-02 02:45:24,572 - INFO - Validated that VN ctest-VN1-03474604 is not in any agent
2026-03-02 02:45:24,622 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-03474604 info
2026-03-02 02:45:24,730 - INFO - IPAM: ipam3 is not found in API Server
2026-03-02 02:45:24,759 - INFO - IPAM:ipam3 is not found in control node
2026-03-02 02:45:24,898 - INFO - IPAM: ipam2 is not found in API Server
2026-03-02 02:45:24,921 - INFO - IPAM:ipam2 is not found in control node
2026-03-02 02:45:25,062 - INFO - IPAM: ipam1 is not found in API Server
2026-03-02 02:45:25,088 - INFO - IPAM:ipam1 is not found in control node
2026-03-02 02:45:26,385 - DEBUG - No XMPP flaps were noticed during the test
2026-03-02 02:45:26,385 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:03:29]
2026-03-02 02:45:26,385 - INFO - --------------------------------------------------------------------------------
2026-03-02 02:45:27,659 - DEBUG - No XMPP flaps were noticed during the test
2026-03-02 02:45:27,659 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:03:31]
2026-03-02 02:45:27,659 - INFO - --------------------------------------------------------------------------------
2026-03-02 02:45:28,456 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-17723440, ID : 48910b0c-9249-4308-a3ad-d4c3b57cb7c1