2026-02-12 02:41:22,750 - INFO - Domain Default found not creating 2026-02-12 02:41:22,917 - INFO - Project ctest-TestPolicyAclIpv4v6-13795166 not found, creating it 2026-02-12 02:41:23,471 - INFO - Created Project:ctest-TestPolicyAclIpv4v6-13795166, ID : 5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:41:24,980 - INFO - ================================================================================ 2026-02-12 02:41:24,980 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2026-02-12 02:41:26,246 - DEBUG - Nothing to compare xmpp stats {'10.0.0.141': {'10.20.0.254': '0', '10.20.0.14': '0'}, '10.0.0.131': {'10.20.0.254': '0', '10.20.0.14': '0'}} with 2026-02-12 02:41:26,246 - INFO - Initial checks done. Running the testcase now 2026-02-12 02:41:26,247 - INFO - 2026-02-12 02:41:26,247 - INFO - ================================================================================ 2026-02-12 02:41:26,247 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2026-02-12 02:41:26,247 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance 2026-02-12 02:41:27,523 - DEBUG - Nothing to compare xmpp stats {'10.0.0.141': {'10.20.0.254': '0', '10.20.0.14': '0'}, '10.0.0.131': {'10.20.0.254': '0', '10.20.0.14': '0'}} with 2026-02-12 02:41:27,524 - INFO - Initial checks done. Running the testcase now 2026-02-12 02:41:27,524 - INFO - 2026-02-12 02:41:28,813 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:41:29,115 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:41:29,145 - DEBUG - Requesting: http://10.0.0.130:8082/domain/dbbdb6d5-ac40-4bc8-b2c9-9c29259fb031 2026-02-12 02:41:29,159 - DEBUG - Requesting: http://10.0.0.130:8082/project/5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:41:29,261 - DEBUG - Requesting: http://10.0.0.130:8082/network-ipam/5676aa27-6f84-43ef-a226-bb36f5efaa7c 2026-02-12 02:41:29,269 - INFO - Verifications in API Server for IPAM: ipam1 passed 2026-02-12 02:41:29,273 - DEBUG - Control-node 10.0.0.130 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6230354220865045487', 'uuid-lslong': '11684232127988083324'}, 'enable': 'true', 'created': '2026-02-12T02:41:27', 'last-modified': '2026-02-12T02:41:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.144717'} 2026-02-12 02:41:29,279 - DEBUG - Control-node 10.0.0.22 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6230354220865045487', 'uuid-lslong': '11684232127988083324'}, 'enable': 'true', 'created': '2026-02-12T02:41:27', 'last-modified': '2026-02-12T02:41:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.159697'} 2026-02-12 02:41:29,284 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6230354220865045487', 'uuid-lslong': '11684232127988083324'}, 'enable': 'true', 'created': '2026-02-12T02:41:27', 'last-modified': '2026-02-12T02:41:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.156229'} 2026-02-12 02:41:29,284 - INFO - Verifications in Control node for IPAM: ipam1 passed 2026-02-12 02:41:29,284 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:41:29,290 - DEBUG - Requesting: http://10.0.0.130:8082/domain/dbbdb6d5-ac40-4bc8-b2c9-9c29259fb031 2026-02-12 02:41:29,300 - DEBUG - Requesting: http://10.0.0.130:8082/project/5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:41:29,390 - DEBUG - Requesting: http://10.0.0.130:8082/network-ipam/9857fa91-40b7-4037-b306-10dccc67a40b 2026-02-12 02:41:29,402 - INFO - Verifications in API Server for IPAM: ipam2 passed 2026-02-12 02:41:29,411 - DEBUG - Control-node 10.0.0.130 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10977518118501826615', 'uuid-lslong': '12900016723157558283'}, 'enable': 'true', 'created': '2026-02-12T02:41:28', 'last-modified': '2026-02-12T02:41:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.947831'} 2026-02-12 02:41:29,416 - DEBUG - Control-node 10.0.0.22 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10977518118501826615', 'uuid-lslong': '12900016723157558283'}, 'enable': 'true', 'created': '2026-02-12T02:41:28', 'last-modified': '2026-02-12T02:41:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.961544'} 2026-02-12 02:41:29,422 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10977518118501826615', 'uuid-lslong': '12900016723157558283'}, 'enable': 'true', 'created': '2026-02-12T02:41:28', 'last-modified': '2026-02-12T02:41:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.963673'} 2026-02-12 02:41:29,422 - INFO - Verifications in Control node for IPAM: ipam2 passed 2026-02-12 02:41:29,422 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:41:29,428 - DEBUG - Requesting: http://10.0.0.130:8082/domain/dbbdb6d5-ac40-4bc8-b2c9-9c29259fb031 2026-02-12 02:41:29,438 - DEBUG - Requesting: http://10.0.0.130:8082/project/5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:41:29,532 - DEBUG - Requesting: http://10.0.0.130:8082/network-ipam/0ebf4cb6-fb55-4d31-bcfe-473ebd56333c 2026-02-12 02:41:29,539 - INFO - Verifications in API Server for IPAM: ipam3 passed 2026-02-12 02:41:29,545 - DEBUG - Control-node 10.0.0.130 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1062652385867156785', 'uuid-lslong': '13618400658005046076'}, 'enable': 'true', 'created': '2026-02-12T02:41:28', 'last-modified': '2026-02-12T02:41:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.748342'} 2026-02-12 02:41:29,550 - DEBUG - Control-node 10.0.0.22 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1062652385867156785', 'uuid-lslong': '13618400658005046076'}, 'enable': 'true', 'created': '2026-02-12T02:41:28', 'last-modified': '2026-02-12T02:41:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.762529'} 2026-02-12 02:41:29,555 - DEBUG - Control-node 10.0.0.23 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1062652385867156785', 'uuid-lslong': '13618400658005046076'}, 'enable': 'true', 'created': '2026-02-12T02:41:28', 'last-modified': '2026-02-12T02:41:28', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.772336'} 2026-02-12 02:41:29,555 - INFO - Verifications in Control node for IPAM: ipam3 passed 2026-02-12 02:41:29,857 - DEBUG - Response for create_network : {'network': {'id': '9782bb80-9754-4c59-b4eb-934162e00736', 'name': 'ctest-VN1-44636705', 'tenant_id': '5afbd078064e4979a63df0895efdf617', 'project_id': '5afbd078064e4979a63df0895efdf617', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ctest-VN1-44636705'], 'port_security_enabled': True, 'description': ''}} 2026-02-12 02:41:30,146 - DEBUG - Response for create_subnet : {'subnet': {'id': '3a562f64-fcff-4eab-ad9b-45a3d89b93d4', 'name': '', 'tenant_id': '5afbd078064e4979a63df0895efdf617', 'network_id': '9782bb80-9754-4c59-b4eb-934162e00736', 'ip_version': 4, 'cidr': '43.79.168.128/26', 'allocation_pools': [{'start': '43.79.168.130', 'end': '43.79.168.190'}], 'gateway_ip': '43.79.168.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '43.79.168.130', 'tags': [], 'project_id': '5afbd078064e4979a63df0895efdf617'}} 2026-02-12 02:41:30,359 - DEBUG - Response for create_subnet : {'subnet': {'id': '815897e9-8efc-4806-8324-dcb9f35b0f8b', 'name': '', 'tenant_id': '5afbd078064e4979a63df0895efdf617', 'network_id': '9782bb80-9754-4c59-b4eb-934162e00736', 'ip_version': 6, 'cidr': '212c:3297:778d:70bb:cfec:4eb8::/96', 'allocation_pools': [{'start': '212c:3297:778d:70bb:cfec:4eb8:0:2', 'end': '212c:3297:778d:70bb:cfec:4eb8:ffff:fffe'}], 'gateway_ip': '212c:3297:778d:70bb:cfec:4eb8:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '212c:3297:778d:70bb:cfec:4eb8:0:2', 'tags': [], 'project_id': '5afbd078064e4979a63df0895efdf617'}} 2026-02-12 02:41:30,383 - INFO - Created VN ctest-VN1-44636705 2026-02-12 02:41:30,393 - DEBUG - VN ctest-VN1-44636705 UUID is 9782bb80-9754-4c59-b4eb-934162e00736 2026-02-12 02:41:30,581 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705(9782bb80-9754-4c59-b4eb-934162e00736) with subnets ['43.79.168.128/26', '212c:3297:778d:70bb:cfec:4eb8::/96'] 2026-02-12 02:41:30,929 - DEBUG - Response for create_network : {'network': {'id': '03e11bac-94bf-485d-b037-8fb2c81fd480', 'name': 'ctest-VN2-84231563', 'tenant_id': '5afbd078064e4979a63df0895efdf617', 'project_id': '5afbd078064e4979a63df0895efdf617', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ctest-VN2-84231563'], 'port_security_enabled': True, 'description': ''}} 2026-02-12 02:41:31,205 - DEBUG - Response for create_subnet : {'subnet': {'id': '3faeffaf-c15d-45e3-9fff-99b874227c2f', 'name': '', 'tenant_id': '5afbd078064e4979a63df0895efdf617', 'network_id': '03e11bac-94bf-485d-b037-8fb2c81fd480', 'ip_version': 4, 'cidr': '71.113.246.0/26', 'allocation_pools': [{'start': '71.113.246.2', 'end': '71.113.246.62'}], 'gateway_ip': '71.113.246.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '71.113.246.2', 'tags': [], 'project_id': '5afbd078064e4979a63df0895efdf617'}} 2026-02-12 02:41:31,439 - DEBUG - Response for create_subnet : {'subnet': {'id': '21aad978-807b-4ba5-80bc-3cfceb062490', 'name': '', 'tenant_id': '5afbd078064e4979a63df0895efdf617', 'network_id': '03e11bac-94bf-485d-b037-8fb2c81fd480', 'ip_version': 6, 'cidr': '3298:584a:bbd3:4f92:ffc5:b563::/96', 'allocation_pools': [{'start': '3298:584a:bbd3:4f92:ffc5:b563:0:2', 'end': '3298:584a:bbd3:4f92:ffc5:b563:ffff:fffe'}], 'gateway_ip': '3298:584a:bbd3:4f92:ffc5:b563:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3298:584a:bbd3:4f92:ffc5:b563:0:2', 'tags': [], 'project_id': '5afbd078064e4979a63df0895efdf617'}} 2026-02-12 02:41:31,481 - INFO - Created VN ctest-VN2-84231563 2026-02-12 02:41:31,496 - DEBUG - VN ctest-VN2-84231563 UUID is 03e11bac-94bf-485d-b037-8fb2c81fd480 2026-02-12 02:41:31,920 - DEBUG - Response for create_network : {'network': {'id': '80a17cb1-3d03-4a5e-998a-3358b9821dfd', 'name': 'ctest-VN3-46913689', 'tenant_id': '5afbd078064e4979a63df0895efdf617', 'project_id': '5afbd078064e4979a63df0895efdf617', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ctest-VN3-46913689'], 'port_security_enabled': True, 'description': ''}} 2026-02-12 02:41:32,173 - DEBUG - Response for create_subnet : {'subnet': {'id': '322b6359-bc53-4ca8-8a95-0ce00ca85a32', 'name': '', 'tenant_id': '5afbd078064e4979a63df0895efdf617', 'network_id': '80a17cb1-3d03-4a5e-998a-3358b9821dfd', 'ip_version': 4, 'cidr': '142.45.251.192/26', 'allocation_pools': [{'start': '142.45.251.194', 'end': '142.45.251.254'}], 'gateway_ip': '142.45.251.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '142.45.251.194', 'tags': [], 'project_id': '5afbd078064e4979a63df0895efdf617'}} 2026-02-12 02:41:32,413 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e2db3908-e2c3-4e2f-9dc4-3c08a1199d7a', 'name': '', 'tenant_id': '5afbd078064e4979a63df0895efdf617', 'network_id': '80a17cb1-3d03-4a5e-998a-3358b9821dfd', 'ip_version': 6, 'cidr': '34c8:b992:dd0c:65d3:1ceb:86e4::/96', 'allocation_pools': [{'start': '34c8:b992:dd0c:65d3:1ceb:86e4:0:2', 'end': '34c8:b992:dd0c:65d3:1ceb:86e4:ffff:fffe'}], 'gateway_ip': '34c8:b992:dd0c:65d3:1ceb:86e4:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '34c8:b992:dd0c:65d3:1ceb:86e4:0:2', 'tags': [], 'project_id': '5afbd078064e4979a63df0895efdf617'}} 2026-02-12 02:41:32,439 - INFO - Created VN ctest-VN3-46913689 2026-02-12 02:41:32,450 - DEBUG - VN ctest-VN3-46913689 UUID is 80a17cb1-3d03-4a5e-998a-3358b9821dfd 2026-02-12 02:41:32,571 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:41:32,579 - DEBUG - Requesting: http://10.0.0.130:8082/domain/dbbdb6d5-ac40-4bc8-b2c9-9c29259fb031 2026-02-12 02:41:32,589 - DEBUG - Requesting: http://10.0.0.130:8082/project/5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:41:32,673 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/9782bb80-9754-4c59-b4eb-934162e00736 2026-02-12 02:41:32,689 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/9782bb80-9754-4c59-b4eb-934162e00736 2026-02-12 02:41:32,698 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/4197ebc6-a6a5-4634-b08a-bb3caffd4d5b 2026-02-12 02:41:32,707 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/4197ebc6-a6a5-4634-b08a-bb3caffd4d5b 2026-02-12 02:41:32,713 - DEBUG - Requesting: http://10.0.0.130:8082/route-target/c19bfb39-f22f-403c-a357-c9889d99532f 2026-02-12 02:41:32,718 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-02-12 02:41:32,719 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/9782bb80-9754-4c59-b4eb-934162e00736 2026-02-12 02:41:32,727 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/4197ebc6-a6a5-4634-b08a-bb3caffd4d5b 2026-02-12 02:41:32,741 - INFO - Verified VN network id 15 for VN 9782bb80-9754-4c59-b4eb-934162e00736 2026-02-12 02:41:32,741 - INFO - Verifications in API Server for VN ctest-VN1-44636705 passed 2026-02-12 02:41:32,741 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/9782bb80-9754-4c59-b4eb-934162e00736 2026-02-12 02:41:32,751 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/4197ebc6-a6a5-4634-b08a-bb3caffd4d5b 2026-02-12 02:41:32,758 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/4197ebc6-a6a5-4634-b08a-bb3caffd4d5b 2026-02-12 02:41:32,766 - DEBUG - Requesting: http://10.0.0.130:8082/route-target/c19bfb39-f22f-403c-a357-c9889d99532f 2026-02-12 02:41:32,779 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10917494607668595801', 'uuid-lslong': '13036675455395039030'}, 'enable': 'true', 'created': '2026-02-12T02:41:29', 'last-modified': '2026-02-12T02:41:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.474686'} 2026-02-12 02:41:32,785 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-02-12 02:41:32,789 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10917494607668595801', 'uuid-lslong': '13036675455395039030'}, 'enable': 'true', 'created': '2026-02-12T02:41:29', 'last-modified': '2026-02-12T02:41:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.494367'} 2026-02-12 02:41:32,795 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-02-12 02:41:32,801 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10917494607668595801', 'uuid-lslong': '13036675455395039030'}, 'enable': 'true', 'created': '2026-02-12T02:41:29', 'last-modified': '2026-02-12T02:41:30', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.506049'} 2026-02-12 02:41:32,810 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-02-12 02:41:32,811 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-44636705 passed 2026-02-12 02:41:32,811 - DEBUG - ====Verifying policy data for ctest-VN1-44636705 in API_Server ====== 2026-02-12 02:41:32,811 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:41:32,817 - DEBUG - Requesting: http://10.0.0.130:8082/domain/dbbdb6d5-ac40-4bc8-b2c9-9c29259fb031 2026-02-12 02:41:32,836 - DEBUG - Requesting: http://10.0.0.130:8082/project/5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:41:32,942 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/9782bb80-9754-4c59-b4eb-934162e00736 2026-02-12 02:41:32,951 - DEBUG - =>VN ctest-VN1-44636705 has no policy to be verified 2026-02-12 02:41:32,952 - DEBUG - Verifying the vn in opserver 2026-02-12 02:41:32,952 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 virtual network link through opserver 10.0.0.130 2026-02-12 02:41:32,952 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks 2026-02-12 02:41:33,273 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks 2026-02-12 02:41:33,363 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705?flat'} 2026-02-12 02:41:33,363 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 virtual network link through opserver 10.0.0.22 2026-02-12 02:41:33,363 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-02-12 02:41:33,677 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-02-12 02:41:33,735 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705?flat'} 2026-02-12 02:41:33,735 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 virtual network link through opserver 10.0.0.23 2026-02-12 02:41:33,735 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2026-02-12 02:41:34,029 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2026-02-12 02:41:34,087 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705?flat'} 2026-02-12 02:41:34,087 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is found in opserver 2026-02-12 02:41:34,106 - DEBUG - Do not have enough data to verify VN in agent 2026-02-12 02:41:34,115 - DEBUG - VRF ids for VN ctest-VN1-44636705: {} 2026-02-12 02:41:34,115 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:41:34,121 - DEBUG - Requesting: http://10.0.0.130:8082/domain/dbbdb6d5-ac40-4bc8-b2c9-9c29259fb031 2026-02-12 02:41:34,132 - DEBUG - Requesting: http://10.0.0.130:8082/project/5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:41:34,228 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/03e11bac-94bf-485d-b037-8fb2c81fd480 2026-02-12 02:41:34,238 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/03e11bac-94bf-485d-b037-8fb2c81fd480 2026-02-12 02:41:34,247 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/2c2e3b00-e526-4509-a27e-d98d4c470232 2026-02-12 02:41:34,254 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/2c2e3b00-e526-4509-a27e-d98d4c470232 2026-02-12 02:41:34,260 - DEBUG - Requesting: http://10.0.0.130:8082/route-target/33f4feb8-c7e9-451c-aa5c-d34ba23ac6fa 2026-02-12 02:41:34,266 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-02-12 02:41:34,266 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/03e11bac-94bf-485d-b037-8fb2c81fd480 2026-02-12 02:41:34,275 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/2c2e3b00-e526-4509-a27e-d98d4c470232 2026-02-12 02:41:34,290 - INFO - Verified VN network id 16 for VN 03e11bac-94bf-485d-b037-8fb2c81fd480 2026-02-12 02:41:34,290 - INFO - Verifications in API Server for VN ctest-VN2-84231563 passed 2026-02-12 02:41:34,290 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/03e11bac-94bf-485d-b037-8fb2c81fd480 2026-02-12 02:41:34,299 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/2c2e3b00-e526-4509-a27e-d98d4c470232 2026-02-12 02:41:34,306 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/2c2e3b00-e526-4509-a27e-d98d4c470232 2026-02-12 02:41:34,312 - DEBUG - Requesting: http://10.0.0.130:8082/route-target/33f4feb8-c7e9-451c-aa5c-d34ba23ac6fa 2026-02-12 02:41:34,324 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '279535079917570141', 'uuid-lslong': '12697775672418882688'}, 'enable': 'true', 'created': '2026-02-12T02:41:30', 'last-modified': '2026-02-12T02:41:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.939421'} 2026-02-12 02:41:34,329 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-02-12 02:41:34,334 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '279535079917570141', 'uuid-lslong': '12697775672418882688'}, 'enable': 'true', 'created': '2026-02-12T02:41:30', 'last-modified': '2026-02-12T02:41:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.953226'} 2026-02-12 02:41:34,340 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-02-12 02:41:34,346 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '279535079917570141', 'uuid-lslong': '12697775672418882688'}, 'enable': 'true', 'created': '2026-02-12T02:41:30', 'last-modified': '2026-02-12T02:41:31', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.961049'} 2026-02-12 02:41:34,353 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-02-12 02:41:34,353 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-84231563 passed 2026-02-12 02:41:34,354 - DEBUG - ====Verifying policy data for ctest-VN2-84231563 in API_Server ====== 2026-02-12 02:41:34,355 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:41:34,361 - DEBUG - Requesting: http://10.0.0.130:8082/domain/dbbdb6d5-ac40-4bc8-b2c9-9c29259fb031 2026-02-12 02:41:34,371 - DEBUG - Requesting: http://10.0.0.130:8082/project/5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:41:34,462 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/03e11bac-94bf-485d-b037-8fb2c81fd480 2026-02-12 02:41:34,471 - DEBUG - =>VN ctest-VN2-84231563 has no policy to be verified 2026-02-12 02:41:34,471 - DEBUG - Verifying the vn in opserver 2026-02-12 02:41:34,472 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563 virtual network link through opserver 10.0.0.130 2026-02-12 02:41:34,472 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks 2026-02-12 02:41:34,530 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563?flat'} 2026-02-12 02:41:34,530 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563 virtual network link through opserver 10.0.0.22 2026-02-12 02:41:34,530 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-02-12 02:41:34,598 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563?flat'} 2026-02-12 02:41:34,598 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563 virtual network link through opserver 10.0.0.23 2026-02-12 02:41:34,598 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2026-02-12 02:41:34,658 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563?flat'} 2026-02-12 02:41:34,659 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563 is found in opserver 2026-02-12 02:41:34,681 - DEBUG - Do not have enough data to verify VN in agent 2026-02-12 02:41:34,692 - DEBUG - VRF ids for VN ctest-VN2-84231563: {} 2026-02-12 02:41:34,692 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:41:34,699 - DEBUG - Requesting: http://10.0.0.130:8082/domain/dbbdb6d5-ac40-4bc8-b2c9-9c29259fb031 2026-02-12 02:41:34,710 - DEBUG - Requesting: http://10.0.0.130:8082/project/5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:41:34,799 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/80a17cb1-3d03-4a5e-998a-3358b9821dfd 2026-02-12 02:41:34,811 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/80a17cb1-3d03-4a5e-998a-3358b9821dfd 2026-02-12 02:41:34,822 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/554afa63-2834-45ce-ba98-e73514b656fe 2026-02-12 02:41:34,832 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/554afa63-2834-45ce-ba98-e73514b656fe 2026-02-12 02:41:34,839 - DEBUG - Requesting: http://10.0.0.130:8082/route-target/54db4ac2-be66-44d0-976f-946e6b3fc830 2026-02-12 02:41:34,847 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-02-12 02:41:34,847 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/80a17cb1-3d03-4a5e-998a-3358b9821dfd 2026-02-12 02:41:34,856 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/554afa63-2834-45ce-ba98-e73514b656fe 2026-02-12 02:41:34,873 - INFO - Verified VN network id 17 for VN 80a17cb1-3d03-4a5e-998a-3358b9821dfd 2026-02-12 02:41:34,873 - INFO - Verifications in API Server for VN ctest-VN3-46913689 passed 2026-02-12 02:41:34,873 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/80a17cb1-3d03-4a5e-998a-3358b9821dfd 2026-02-12 02:41:34,884 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/554afa63-2834-45ce-ba98-e73514b656fe 2026-02-12 02:41:34,891 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/554afa63-2834-45ce-ba98-e73514b656fe 2026-02-12 02:41:34,899 - DEBUG - Requesting: http://10.0.0.130:8082/route-target/54db4ac2-be66-44d0-976f-946e6b3fc830 2026-02-12 02:41:34,910 - DEBUG - Control-node 10.0.0.130 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9268826608779872862', 'uuid-lslong': '11063711890751495677'}, 'enable': 'true', 'created': '2026-02-12T02:41:31', 'last-modified': '2026-02-12T02:41:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.549634'} 2026-02-12 02:41:34,916 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-02-12 02:41:34,921 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9268826608779872862', 'uuid-lslong': '11063711890751495677'}, 'enable': 'true', 'created': '2026-02-12T02:41:31', 'last-modified': '2026-02-12T02:41:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.551729'} 2026-02-12 02:41:34,926 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-02-12 02:41:34,931 - DEBUG - Control-node 10.0.0.23 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-13795166', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9268826608779872862', 'uuid-lslong': '11063711890751495677'}, 'enable': 'true', 'created': '2026-02-12T02:41:31', 'last-modified': '2026-02-12T02:41:32', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.568889'} 2026-02-12 02:41:34,936 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-02-12 02:41:34,937 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-46913689 passed 2026-02-12 02:41:34,937 - DEBUG - ====Verifying policy data for ctest-VN3-46913689 in API_Server ====== 2026-02-12 02:41:34,937 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:41:34,942 - DEBUG - Requesting: http://10.0.0.130:8082/domain/dbbdb6d5-ac40-4bc8-b2c9-9c29259fb031 2026-02-12 02:41:34,954 - DEBUG - Requesting: http://10.0.0.130:8082/project/5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:41:35,051 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/80a17cb1-3d03-4a5e-998a-3358b9821dfd 2026-02-12 02:41:35,063 - DEBUG - =>VN ctest-VN3-46913689 has no policy to be verified 2026-02-12 02:41:35,063 - DEBUG - Verifying the vn in opserver 2026-02-12 02:41:35,063 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689 virtual network link through opserver 10.0.0.130 2026-02-12 02:41:35,063 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-networks 2026-02-12 02:41:35,126 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689?flat'} 2026-02-12 02:41:35,127 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689 virtual network link through opserver 10.0.0.22 2026-02-12 02:41:35,127 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2026-02-12 02:41:35,193 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689?flat'} 2026-02-12 02:41:35,193 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689 virtual network link through opserver 10.0.0.23 2026-02-12 02:41:35,193 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-networks 2026-02-12 02:41:35,254 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689?flat'} 2026-02-12 02:41:35,254 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689 is found in opserver 2026-02-12 02:41:35,274 - DEBUG - Do not have enough data to verify VN in agent 2026-02-12 02:41:35,284 - DEBUG - VRF ids for VN ctest-VN3-46913689: {} 2026-02-12 02:41:35,290 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705, 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-13795166:ctest-VN3-46913689, 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-02-12 02:41:35,549 - DEBUG - No source network defined 2026-02-12 02:41:35,549 - DEBUG - No source network defined 2026-02-12 02:41:35,549 - 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-13795166:ctest-policy13-16031057, 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-13795166:ctest-policy13-16031057, 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-02-12 02:41:35,809 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563, 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-13795166:ctest-VN1-44636705, 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-13795166:ctest-VN2-84231563, 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-13795166:ctest-VN1-44636705, 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-02-12 02:41:36,014 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:41:36,021 - DEBUG - Requesting: http://10.0.0.130:8082/domain/dbbdb6d5-ac40-4bc8-b2c9-9c29259fb031 2026-02-12 02:41:36,031 - DEBUG - Requesting: http://10.0.0.130:8082/project/5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:41:36,125 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/9782bb80-9754-4c59-b4eb-934162e00736 2026-02-12 02:41:36,136 - DEBUG - Setup step: Associating the policy to VN' 2026-02-12 02:41:36,240 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '9782bb80-9754-4c59-b4eb-934162e00736', 'name': 'ctest-VN1-44636705', 'tenant_id': '5afbd078064e4979a63df0895efdf617', 'project_id': '5afbd078064e4979a63df0895efdf617', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['3a562f64-fcff-4eab-ad9b-45a3d89b93d4', '815897e9-8efc-4806-8324-dcb9f35b0f8b'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ctest-VN1-44636705'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ctest-policy12-67402433'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ctest-policy13-16031057']], 'subnet_ipam': [{'subnet_cidr': '43.79.168.128/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ipam1']}, {'subnet_cidr': '212c:3297:778d:70bb:cfec:4eb8::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ipam1']}], 'port_security_enabled': True, 'description': ''}} 2026-02-12 02:41:36,403 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ctest-policy12-67402433'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ctest-policy13-16031057']] to ctest-VN1-44636705 2026-02-12 02:41:36,403 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:41:36,410 - DEBUG - Requesting: http://10.0.0.130:8082/domain/dbbdb6d5-ac40-4bc8-b2c9-9c29259fb031 2026-02-12 02:41:36,421 - DEBUG - Requesting: http://10.0.0.130:8082/project/5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:41:36,520 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/03e11bac-94bf-485d-b037-8fb2c81fd480 2026-02-12 02:41:36,531 - DEBUG - Setup step: Associating the policy to VN' 2026-02-12 02:41:36,650 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '03e11bac-94bf-485d-b037-8fb2c81fd480', 'name': 'ctest-VN2-84231563', 'tenant_id': '5afbd078064e4979a63df0895efdf617', 'project_id': '5afbd078064e4979a63df0895efdf617', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['3faeffaf-c15d-45e3-9fff-99b874227c2f', '21aad978-807b-4ba5-80bc-3cfceb062490'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ctest-VN2-84231563'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ctest-policy21-02878832']], 'subnet_ipam': [{'subnet_cidr': '71.113.246.0/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ipam2']}, {'subnet_cidr': '3298:584a:bbd3:4f92:ffc5:b563::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ipam2']}], 'port_security_enabled': True, 'description': ''}} 2026-02-12 02:41:36,776 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ctest-policy21-02878832']] to ctest-VN2-84231563 2026-02-12 02:41:36,879 - DEBUG - Services list from nova: [, , , ] 2026-02-12 02:41:38,129 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5305-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5305-2) 2026-02-12 02:41:39,480 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5305-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5305-1) 2026-02-12 02:41:40,934 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5305-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5305-2) 2026-02-12 02:41:41,022 - DEBUG - VM is in ACTIVE state now 2026-02-12 02:41:41,022 - INFO - VM name : ctest-VM11-15542569 2026-02-12 02:41:41,115 - DEBUG - VM ctest-VM11-15542569 ID is a409ec57-c79a-4fd8-a33e-86b48ccccb32 2026-02-12 02:41:41,196 - DEBUG - VM is in ACTIVE state now 2026-02-12 02:41:41,228 - DEBUG - VM ctest-VM11-15542569 launched on Node an-jenkins-deploy-platform-ansible-os-5305-2 2026-02-12 02:41:41,419 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/a409ec57-c79a-4fd8-a33e-86b48ccccb32 2026-02-12 02:41:41,812 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/a409ec57-c79a-4fd8-a33e-86b48ccccb32 2026-02-12 02:41:41,847 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/a409ec57-c79a-4fd8-a33e-86b48ccccb32 2026-02-12 02:41:41,855 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/e9ea76f2-68a0-402d-81ff-7e0c0a0ef3dd 2026-02-12 02:41:41,865 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/a409ec57-c79a-4fd8-a33e-86b48ccccb32 2026-02-12 02:41:41,879 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/e9ea76f2-68a0-402d-81ff-7e0c0a0ef3dd 2026-02-12 02:41:41,891 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/0955edd7-46c1-4a81-ac36-927b419dbe0f 2026-02-12 02:41:41,916 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/9417bc08-9c38-41bc-ae5d-e660292ed684 2026-02-12 02:41:41,926 - DEBUG - Verifying in api server 10.0.0.23 2026-02-12 02:41:41,926 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/a409ec57-c79a-4fd8-a33e-86b48ccccb32 2026-02-12 02:41:41,933 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/e9ea76f2-68a0-402d-81ff-7e0c0a0ef3dd 2026-02-12 02:41:41,941 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/0955edd7-46c1-4a81-ac36-927b419dbe0f 2026-02-12 02:41:41,948 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/9417bc08-9c38-41bc-ae5d-e660292ed684 2026-02-12 02:41:41,956 - INFO - VM ctest-VM11-15542569 verfication in all API Servers passed 2026-02-12 02:41:41,973 - DEBUG - VM ctest-VM11-15542569 Tap interface: {'index': '3', 'name': 'tape9ea76f2-68', 'uuid': 'e9ea76f2-68a0-402d-81ff-7e0c0a0ef3dd', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705:ctest-VN1-44636705', '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-13795166:ctest-VN1-44636705', 'vm_uuid': 'a409ec57-c79a-4fd8-a33e-86b48ccccb32', 'vm_name': 'ctest-VM11-15542569', 'ip_addr': '43.79.168.131', 'mac_addr': '02:e9:ea:76:f2:68', '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-13795166:e9ea76f2-68a0-402d-81ff-7e0c0a0ef3dd', 'sg_uuid_list': ['4ef07067-8001-4b9a-9dbd-768d0fbd0242'], 'static_route_list': None, 'vm_project_uuid': '5afbd078-064e-4979-a63d-f0895efdf617', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '212c:3297:778d:70bb:cfec:4eb8: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-02-12 02:41:41,973 - DEBUG - Agent 10.0.0.131 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705:ctest-VN1-44636705 2026-02-12 02:41:41,983 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705:ctest-VN1-44636705', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705', 'table_label': '-1', 'vxlan_id': '15', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.252:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-02-12 02:41:42,079 - DEBUG - VM ctest-VM11-15542569 : Tap interface tape9ea76f2-68 is set to Active 2026-02-12 02:41:42,079 - DEBUG - Tap interface tape9ea76f2-68 detail : {'index': '3', 'name': 'tape9ea76f2-68', 'uuid': 'e9ea76f2-68a0-402d-81ff-7e0c0a0ef3dd', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705:ctest-VN1-44636705', '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-13795166:ctest-VN1-44636705', 'vm_uuid': 'a409ec57-c79a-4fd8-a33e-86b48ccccb32', 'vm_name': 'ctest-VM11-15542569', 'ip_addr': '43.79.168.131', 'mac_addr': '02:e9:ea:76:f2:68', '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-13795166:e9ea76f2-68a0-402d-81ff-7e0c0a0ef3dd', 'sg_uuid_list': ['4ef07067-8001-4b9a-9dbd-768d0fbd0242'], 'static_route_list': None, 'vm_project_uuid': '5afbd078-064e-4979-a63d-f0895efdf617', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '212c:3297:778d:70bb:cfec:4eb8: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-02-12 02:41:42,185 - DEBUG - Starting Layer 2 verification in Agent 2026-02-12 02:41:42,195 - DEBUG - Layer 2 path is seen for VM MAC 02:e9:ea:76:f2:68 in agent 10.0.0.131 2026-02-12 02:41:42,195 - DEBUG - Active layer 2 route in agent is present for VMI tape9ea76f2-68 2026-02-12 02:41:42,195 - DEBUG - L2 label(29) matches bw route table and interface table 2026-02-12 02:41:42,207 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is found in Agent of node 10.0.0.131 2026-02-12 02:41:42,395 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is consistent in agent 10.0.0.131 2026-02-12 02:41:42,395 - DEBUG - Route for VM IP ['43.79.168.131', '212c:3297:778d:70bb:cfec:4eb8:0:3'] is consistent in agent 10.0.0.131 2026-02-12 02:41:42,395 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 verification for VM ctest-VM11-15542569 in Agent 10.0.0.131 passed 2026-02-12 02:41:42,465 - DEBUG - Starting all layer 2 verification in agent 10.0.0.131 2026-02-12 02:41:42,527 - DEBUG - Route for VM MAC 02:e9:ea:76:f2:68 is consistent in agent 10.0.0.131 2026-02-12 02:41:45,662 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms') 2026-02-12 02:41:45,663 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-15542569 failed! 2026-02-12 02:41:45,680 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 43.79.168.129 and allocation pool is NOT set 2026-02-12 02:41:45,680 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 212c:3297:778d:70bb:cfec:4eb8:0:1 and allocation pool is NOT set 2026-02-12 02:41:49,760 - 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 1022ms') 2026-02-12 02:41:49,760 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-15542569 failed! 2026-02-12 02:41:49,773 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 43.79.168.129 and allocation pool is NOT set 2026-02-12 02:41:49,773 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 212c:3297:778d:70bb:cfec:4eb8:0:1 and allocation pool is NOT set 2026-02-12 02:41:53,857 - 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-02-12 02:41:53,857 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-15542569 failed! 2026-02-12 02:41:53,870 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 43.79.168.129 and allocation pool is NOT set 2026-02-12 02:41:53,870 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 212c:3297:778d:70bb:cfec:4eb8:0:1 and allocation pool is NOT set 2026-02-12 02:41:57,958 - 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-02-12 02:41:57,958 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-15542569 failed! 2026-02-12 02:41:57,971 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 43.79.168.129 and allocation pool is NOT set 2026-02-12 02:41:57,971 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 212c:3297:778d:70bb:cfec:4eb8:0:1 and allocation pool is NOT set 2026-02-12 02:42:02,054 - 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-02-12 02:42:02,054 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-15542569 failed! 2026-02-12 02:42:02,110 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 43.79.168.129 and allocation pool is NOT set 2026-02-12 02:42:02,110 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 212c:3297:778d:70bb:cfec:4eb8:0:1 and allocation pool is NOT set 2026-02-12 02:42:06,183 - 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 1011ms') 2026-02-12 02:42:06,183 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-15542569 failed! 2026-02-12 02:42:06,197 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 43.79.168.129 and allocation pool is NOT set 2026-02-12 02:42:06,197 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 212c:3297:778d:70bb:cfec:4eb8:0:1 and allocation pool is NOT set 2026-02-12 02:42:10,271 - 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 1015ms') 2026-02-12 02:42:10,271 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-15542569 failed! 2026-02-12 02:42:10,291 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 43.79.168.129 and allocation pool is NOT set 2026-02-12 02:42:10,291 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 212c:3297:778d:70bb:cfec:4eb8:0:1 and allocation pool is NOT set 2026-02-12 02:42:14,376 - 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-02-12 02:42:14,376 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-15542569 failed! 2026-02-12 02:42:14,389 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 43.79.168.129 and allocation pool is NOT set 2026-02-12 02:42:14,389 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 212c:3297:778d:70bb:cfec:4eb8:0:1 and allocation pool is NOT set 2026-02-12 02:42:18,464 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms') 2026-02-12 02:42:18,464 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-15542569 failed! 2026-02-12 02:42:18,477 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 43.79.168.129 and allocation pool is NOT set 2026-02-12 02:42:18,477 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 212c:3297:778d:70bb:cfec:4eb8:0:1 and allocation pool is NOT set 2026-02-12 02:42:22,561 - 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 1031ms') 2026-02-12 02:42:22,562 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-15542569 failed! 2026-02-12 02:42:22,575 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 43.79.168.129 and allocation pool is NOT set 2026-02-12 02:42:22,575 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 is 212c:3297:778d:70bb:cfec:4eb8:0:1 and allocation pool is NOT set 2026-02-12 02:42:24,650 - 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.84 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=7.25 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 7.249/7.546/7.843/0.297 ms') 2026-02-12 02:42:24,650 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-15542569 passed 2026-02-12 02:42:24,650 - INFO - VM ctest-VM11-15542569 verifications in Compute nodes passed 2026-02-12 02:42:24,895 - DEBUG - Validated VM route 43.79.168.131 in vrouter of 10.0.0.131 2026-02-12 02:42:24,902 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-02-12 02:42:24,902 - INFO - Validated routes of VM ctest-VM11-15542569 in all vrouters 2026-02-12 02:42:25,316 - DEBUG - Starting all layer2 verification in 10.0.0.23 Control Node 2026-02-12 02:42:25,436 - DEBUG - Layer2 route found for VM MAC 02:e9:ea:76:f2:68 in Control-node 10.0.0.23 2026-02-12 02:42:25,436 - DEBUG - L2 Label for VM ctest-VM11-15542569 same between Control-node 10.0.0.23 and Agent, Expected: 29, Seen: 29 2026-02-12 02:42:25,550 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.23, not supported 2026-02-12 02:42:25,550 - DEBUG - Starting all layer2 verification in 10.0.0.22 Control Node 2026-02-12 02:42:25,672 - DEBUG - Layer2 route found for VM MAC 02:e9:ea:76:f2:68 in Control-node 10.0.0.22 2026-02-12 02:42:25,672 - DEBUG - L2 Label for VM ctest-VM11-15542569 same between Control-node 10.0.0.22 and Agent, Expected: 29, Seen: 29 2026-02-12 02:42:25,785 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.22, not supported 2026-02-12 02:42:25,785 - INFO - Verification in Control-nodes for VM ctest-VM11-15542569 passed 2026-02-12 02:42:25,785 - DEBUG - Verifying the vm in opserver 2026-02-12 02:42:25,785 - DEBUG - Verifying in collector 10.0.0.23 ... 2026-02-12 02:42:25,785 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/a409ec57-c79a-4fd8-a33e-86b48ccccb32?flat 2026-02-12 02:42:25,801 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-13795166:e9ea76f2-68a0-402d-81ff-7e0c0a0ef3dd?flat 2026-02-12 02:42:25,818 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-13795166:e9ea76f2-68a0-402d-81ff-7e0c0a0ef3dd?flat 2026-02-12 02:42:25,836 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-13795166:e9ea76f2-68a0-402d-81ff-7e0c0a0ef3dd?flat 2026-02-12 02:42:25,854 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/a409ec57-c79a-4fd8-a33e-86b48ccccb32?flat 2026-02-12 02:42:25,870 - DEBUG - Verifying vm in vn uve 2026-02-12 02:42:25,870 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-13795166:e9ea76f2-68a0-402d-81ff-7e0c0a0ef3dd?flat 2026-02-12 02:42:25,887 - DEBUG - VM uve shows interface as {'rx_vlan': 65535, 'fixed_ip6_list': ['212c:3297:778d:70bb:cfec:4eb8:0:3'], '__T': 1770864105440362, 'uuid': 'e9ea76f2-68a0-402d-81ff-7e0c0a0ef3dd', 'active': True, 'label': 25, 'port_mirror_enabled': False, 'ip4_active': True, 'vm_uuid': 'a409ec57-c79a-4fd8-a33e-86b48ccccb32', 'ip6_address': '212c:3297:778d:70bb:cfec:4eb8:0:3', 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705', 'vm_name': 'ctest-VM11-15542569', 'vn_uuid': '9782bb80-9754-4c59-b4eb-934162e00736', 'admin_state': True, 'ip_address': '43.79.168.131', 'gateway': '43.79.168.129', 'ip6_active': True, 'l2_active': True, 'tx_vlan': 65535, 'vhostuser_mode': 0, 'mac_address': '02:e9:ea:76:f2:68', 'is_health_check_active': True, 'fixed_ip4_list': ['43.79.168.131']} 2026-02-12 02:42:25,887 - DEBUG - VM uve shows ip address as ['43.79.168.131', '212c:3297:778d:70bb:cfec:4eb8:0:3'] 2026-02-12 02:42:25,887 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 2026-02-12 02:42:25,887 - INFO - Verifying through opserver in 10.0.0.130 2026-02-12 02:42:25,887 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705?flat 2026-02-12 02:42:25,910 - DEBUG - expected vm list a409ec57-c79a-4fd8-a33e-86b48ccccb32 2026-02-12 02:42:25,910 - DEBUG - Extracted vm list ['a409ec57-c79a-4fd8-a33e-86b48ccccb32'] 2026-02-12 02:42:25,910 - INFO - VM a409ec57-c79a-4fd8-a33e-86b48ccccb32 is present in default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 2026-02-12 02:42:25,911 - DEBUG - Verifying vm in vrouter uve 2026-02-12 02:42:25,911 - DEBUG - Getting info from collector 10.0.0.23.. 2026-02-12 02:42:25,911 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/a409ec57-c79a-4fd8-a33e-86b48ccccb32?flat 2026-02-12 02:42:25,934 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5305-2'] 2026-02-12 02:42:25,934 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-2:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:42:25,970 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-2?flat 2026-02-12 02:42:26,000 - DEBUG - VM a409ec57-c79a-4fd8-a33e-86b48ccccb32 is present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:42:26,000 - DEBUG - Validated that VM ctest-VM11-15542569 is in Vrouter an-jenkins-deploy-platform-ansible-os-5305-2 UVE 2026-02-12 02:42:26,000 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-02-12 02:42:26,007 - DEBUG - Expected tap interface of VM uuid a409ec57-c79a-4fd8-a33e-86b48ccccb32 is default-domain:ctest-TestPolicyAclIpv4v6-13795166:e9ea76f2-68a0-402d-81ff-7e0c0a0ef3dd 2026-02-12 02:42:26,008 - DEBUG - Expected VN of VM uuid a409ec57-c79a-4fd8-a33e-86b48ccccb32 is default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 2026-02-12 02:42:26,008 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-2:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:42:26,050 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-2?flat 2026-02-12 02:42:26,089 - DEBUG - VM a409ec57-c79a-4fd8-a33e-86b48ccccb32 is present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:42:26,089 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-13795166:e9ea76f2-68a0-402d-81ff-7e0c0a0ef3dd of vm a409ec57-c79a-4fd8-a33e-86b48ccccb32 is present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:42:26,089 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 of vm a409ec57-c79a-4fd8-a33e-86b48ccccb32 is present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:42:26,089 - DEBUG - Verifying the a409ec57-c79a-4fd8-a33e-86b48ccccb32 virtual network link through opserver 10.0.0.130 2026-02-12 02:42:26,089 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines 2026-02-12 02:42:26,105 - DEBUG - VM link and name as {'name': 'a409ec57-c79a-4fd8-a33e-86b48ccccb32', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-machine/a409ec57-c79a-4fd8-a33e-86b48ccccb32?flat'} 2026-02-12 02:42:26,105 - DEBUG - Verifying the a409ec57-c79a-4fd8-a33e-86b48ccccb32 virtual network link through opserver 10.0.0.22 2026-02-12 02:42:26,105 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines 2026-02-12 02:42:26,117 - DEBUG - VM link and name as {'name': 'a409ec57-c79a-4fd8-a33e-86b48ccccb32', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-machine/a409ec57-c79a-4fd8-a33e-86b48ccccb32?flat'} 2026-02-12 02:42:26,118 - DEBUG - Verifying the a409ec57-c79a-4fd8-a33e-86b48ccccb32 virtual network link through opserver 10.0.0.23 2026-02-12 02:42:26,118 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2026-02-12 02:42:26,131 - DEBUG - VM link and name as {'name': 'a409ec57-c79a-4fd8-a33e-86b48ccccb32', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/a409ec57-c79a-4fd8-a33e-86b48ccccb32?flat'} 2026-02-12 02:42:26,131 - INFO - VM ctest-VM11-15542569 validations in Opserver passed 2026-02-12 02:42:26,220 - DEBUG - VM is in ACTIVE state now 2026-02-12 02:42:26,220 - INFO - VM name : ctest-VM21-42024060 2026-02-12 02:42:26,303 - DEBUG - VM ctest-VM21-42024060 ID is 065e86e5-f597-4005-a20a-487693d21e99 2026-02-12 02:42:26,405 - DEBUG - VM is in ACTIVE state now 2026-02-12 02:42:26,406 - DEBUG - VM ctest-VM21-42024060 launched on Node an-jenkins-deploy-platform-ansible-os-5305-1 2026-02-12 02:42:26,492 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/065e86e5-f597-4005-a20a-487693d21e99 2026-02-12 02:42:26,504 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/065e86e5-f597-4005-a20a-487693d21e99 2026-02-12 02:42:26,513 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/856cc28f-1b67-416e-b8e1-d99ae84b3eea 2026-02-12 02:42:26,524 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/065e86e5-f597-4005-a20a-487693d21e99 2026-02-12 02:42:26,531 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/856cc28f-1b67-416e-b8e1-d99ae84b3eea 2026-02-12 02:42:26,541 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/90e88f1e-0362-4370-bba2-2a27bbf620db 2026-02-12 02:42:26,550 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/c65a97c5-1acc-43a1-84de-a4704a76026a 2026-02-12 02:42:26,559 - DEBUG - Verifying in api server 10.0.0.23 2026-02-12 02:42:26,559 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/065e86e5-f597-4005-a20a-487693d21e99 2026-02-12 02:42:26,566 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/856cc28f-1b67-416e-b8e1-d99ae84b3eea 2026-02-12 02:42:26,576 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/90e88f1e-0362-4370-bba2-2a27bbf620db 2026-02-12 02:42:26,583 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/c65a97c5-1acc-43a1-84de-a4704a76026a 2026-02-12 02:42:26,592 - INFO - VM ctest-VM21-42024060 verfication in all API Servers passed 2026-02-12 02:42:26,613 - DEBUG - VM ctest-VM21-42024060 Tap interface: {'index': '3', 'name': 'tap856cc28f-1b', 'uuid': '856cc28f-1b67-416e-b8e1-d99ae84b3eea', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563:ctest-VN2-84231563', '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-13795166:ctest-VN2-84231563', 'vm_uuid': '065e86e5-f597-4005-a20a-487693d21e99', 'vm_name': 'ctest-VM21-42024060', 'ip_addr': '71.113.246.3', 'mac_addr': '02:85:6c:c2:8f:1b', '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-13795166:856cc28f-1b67-416e-b8e1-d99ae84b3eea', 'sg_uuid_list': ['4ef07067-8001-4b9a-9dbd-768d0fbd0242'], 'static_route_list': None, 'vm_project_uuid': '5afbd078-064e-4979-a63d-f0895efdf617', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '3298:584a:bbd3:4f92:ffc5:b563: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-02-12 02:42:26,613 - DEBUG - Agent 10.0.0.141 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563:ctest-VN2-84231563 2026-02-12 02:42:26,620 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563:ctest-VN2-84231563', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563', 'table_label': '-1', 'vxlan_id': '16', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.18:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-02-12 02:42:26,762 - DEBUG - VM ctest-VM21-42024060 : Tap interface tap856cc28f-1b is set to Active 2026-02-12 02:42:26,762 - DEBUG - Tap interface tap856cc28f-1b detail : {'index': '3', 'name': 'tap856cc28f-1b', 'uuid': '856cc28f-1b67-416e-b8e1-d99ae84b3eea', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563:ctest-VN2-84231563', '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-13795166:ctest-VN2-84231563', 'vm_uuid': '065e86e5-f597-4005-a20a-487693d21e99', 'vm_name': 'ctest-VM21-42024060', 'ip_addr': '71.113.246.3', 'mac_addr': '02:85:6c:c2:8f:1b', '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-13795166:856cc28f-1b67-416e-b8e1-d99ae84b3eea', 'sg_uuid_list': ['4ef07067-8001-4b9a-9dbd-768d0fbd0242'], 'static_route_list': None, 'vm_project_uuid': '5afbd078-064e-4979-a63d-f0895efdf617', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '3298:584a:bbd3:4f92:ffc5:b563: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-02-12 02:42:26,874 - DEBUG - Starting Layer 2 verification in Agent 2026-02-12 02:42:26,886 - DEBUG - Layer 2 path is seen for VM MAC 02:85:6c:c2:8f:1b in agent 10.0.0.141 2026-02-12 02:42:26,886 - DEBUG - Active layer 2 route in agent is present for VMI tap856cc28f-1b 2026-02-12 02:42:26,886 - DEBUG - L2 label(29) matches bw route table and interface table 2026-02-12 02:42:26,893 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563 is found in Agent of node 10.0.0.141 2026-02-12 02:42:27,059 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563 is consistent in agent 10.0.0.141 2026-02-12 02:42:27,059 - DEBUG - Route for VM IP ['3298:584a:bbd3:4f92:ffc5:b563:0:3', '71.113.246.3'] is consistent in agent 10.0.0.141 2026-02-12 02:42:27,059 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563 verification for VM ctest-VM21-42024060 in Agent 10.0.0.141 passed 2026-02-12 02:42:27,170 - DEBUG - Starting all layer 2 verification in agent 10.0.0.141 2026-02-12 02:42:27,191 - DEBUG - Route for VM MAC 02:85:6c:c2:8f:1b is consistent in agent 10.0.0.141 2026-02-12 02:42:28,375 - 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=65.0 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.52 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 2.522/33.760/64.999/31.238 ms') 2026-02-12 02:42:28,375 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-42024060 passed 2026-02-12 02:42:28,375 - INFO - VM ctest-VM21-42024060 verifications in Compute nodes passed 2026-02-12 02:42:28,606 - DEBUG - Validated VM route 71.113.246.3 in vrouter of 10.0.0.141 2026-02-12 02:42:28,612 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-02-12 02:42:28,612 - INFO - Validated routes of VM ctest-VM21-42024060 in all vrouters 2026-02-12 02:42:29,018 - DEBUG - Starting all layer2 verification in 10.0.0.23 Control Node 2026-02-12 02:42:29,126 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.23, not supported 2026-02-12 02:42:29,243 - DEBUG - Layer2 route found for VM MAC 02:85:6c:c2:8f:1b in Control-node 10.0.0.23 2026-02-12 02:42:29,243 - DEBUG - L2 Label for VM ctest-VM21-42024060 same between Control-node 10.0.0.23 and Agent, Expected: 29, Seen: 29 2026-02-12 02:42:29,243 - DEBUG - Starting all layer2 verification in 10.0.0.22 Control Node 2026-02-12 02:42:29,349 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.22, not supported 2026-02-12 02:42:29,475 - DEBUG - Layer2 route found for VM MAC 02:85:6c:c2:8f:1b in Control-node 10.0.0.22 2026-02-12 02:42:29,475 - DEBUG - L2 Label for VM ctest-VM21-42024060 same between Control-node 10.0.0.22 and Agent, Expected: 29, Seen: 29 2026-02-12 02:42:29,475 - INFO - Verification in Control-nodes for VM ctest-VM21-42024060 passed 2026-02-12 02:42:29,475 - DEBUG - Verifying the vm in opserver 2026-02-12 02:42:29,475 - DEBUG - Verifying in collector 10.0.0.23 ... 2026-02-12 02:42:29,475 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/065e86e5-f597-4005-a20a-487693d21e99?flat 2026-02-12 02:42:29,491 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-13795166:856cc28f-1b67-416e-b8e1-d99ae84b3eea?flat 2026-02-12 02:42:29,512 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-13795166:856cc28f-1b67-416e-b8e1-d99ae84b3eea?flat 2026-02-12 02:42:29,529 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-13795166:856cc28f-1b67-416e-b8e1-d99ae84b3eea?flat 2026-02-12 02:42:29,547 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/065e86e5-f597-4005-a20a-487693d21e99?flat 2026-02-12 02:42:29,560 - DEBUG - Verifying vm in vn uve 2026-02-12 02:42:29,560 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-13795166:856cc28f-1b67-416e-b8e1-d99ae84b3eea?flat 2026-02-12 02:42:29,579 - DEBUG - VM uve shows interface as {'rx_vlan': 65535, 'fixed_ip6_list': ['3298:584a:bbd3:4f92:ffc5:b563:0:3'], '__T': 1770864105873195, 'uuid': '856cc28f-1b67-416e-b8e1-d99ae84b3eea', 'active': True, 'label': 25, 'port_mirror_enabled': False, 'ip4_active': True, 'vm_uuid': '065e86e5-f597-4005-a20a-487693d21e99', 'ip6_address': '3298:584a:bbd3:4f92:ffc5:b563:0:3', 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563', 'vm_name': 'ctest-VM21-42024060', 'vn_uuid': '03e11bac-94bf-485d-b037-8fb2c81fd480', 'admin_state': True, 'ip_address': '71.113.246.3', 'gateway': '71.113.246.1', 'ip6_active': True, 'l2_active': True, 'tx_vlan': 65535, 'vhostuser_mode': 0, 'mac_address': '02:85:6c:c2:8f:1b', 'is_health_check_active': True, 'fixed_ip4_list': ['71.113.246.3']} 2026-02-12 02:42:29,580 - DEBUG - VM uve shows ip address as ['71.113.246.3', '3298:584a:bbd3:4f92:ffc5:b563:0:3'] 2026-02-12 02:42:29,580 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563 2026-02-12 02:42:29,580 - INFO - Verifying through opserver in 10.0.0.130 2026-02-12 02:42:29,580 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563?flat 2026-02-12 02:42:29,600 - DEBUG - expected vm list 065e86e5-f597-4005-a20a-487693d21e99 2026-02-12 02:42:29,600 - DEBUG - Extracted vm list ['065e86e5-f597-4005-a20a-487693d21e99'] 2026-02-12 02:42:29,600 - INFO - VM 065e86e5-f597-4005-a20a-487693d21e99 is present in default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563 2026-02-12 02:42:29,600 - DEBUG - Verifying vm in vrouter uve 2026-02-12 02:42:29,600 - DEBUG - Getting info from collector 10.0.0.23.. 2026-02-12 02:42:29,600 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/065e86e5-f597-4005-a20a-487693d21e99?flat 2026-02-12 02:42:29,615 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5305-1'] 2026-02-12 02:42:29,615 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-1:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:42:29,646 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-1?flat 2026-02-12 02:42:29,674 - DEBUG - VM 065e86e5-f597-4005-a20a-487693d21e99 is present in vrouter an-jenkins-deploy-platform-ansible-os-5305-1 uve 2026-02-12 02:42:29,674 - DEBUG - Validated that VM ctest-VM21-42024060 is in Vrouter an-jenkins-deploy-platform-ansible-os-5305-1 UVE 2026-02-12 02:42:29,674 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-02-12 02:42:29,681 - DEBUG - Expected tap interface of VM uuid 065e86e5-f597-4005-a20a-487693d21e99 is default-domain:ctest-TestPolicyAclIpv4v6-13795166:856cc28f-1b67-416e-b8e1-d99ae84b3eea 2026-02-12 02:42:29,681 - DEBUG - Expected VN of VM uuid 065e86e5-f597-4005-a20a-487693d21e99 is default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563 2026-02-12 02:42:29,681 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-1:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:42:29,710 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-1?flat 2026-02-12 02:42:29,736 - DEBUG - VM 065e86e5-f597-4005-a20a-487693d21e99 is present in vrouter an-jenkins-deploy-platform-ansible-os-5305-1 uve 2026-02-12 02:42:29,736 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-13795166:856cc28f-1b67-416e-b8e1-d99ae84b3eea of vm 065e86e5-f597-4005-a20a-487693d21e99 is present in vrouter an-jenkins-deploy-platform-ansible-os-5305-1 uve 2026-02-12 02:42:29,736 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563 of vm 065e86e5-f597-4005-a20a-487693d21e99 is present in vrouter an-jenkins-deploy-platform-ansible-os-5305-1 uve 2026-02-12 02:42:29,736 - DEBUG - Verifying the 065e86e5-f597-4005-a20a-487693d21e99 virtual network link through opserver 10.0.0.130 2026-02-12 02:42:29,736 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines 2026-02-12 02:42:29,746 - DEBUG - VM link and name as {'name': '065e86e5-f597-4005-a20a-487693d21e99', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-machine/065e86e5-f597-4005-a20a-487693d21e99?flat'} 2026-02-12 02:42:29,746 - DEBUG - Verifying the 065e86e5-f597-4005-a20a-487693d21e99 virtual network link through opserver 10.0.0.22 2026-02-12 02:42:29,746 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines 2026-02-12 02:42:29,759 - DEBUG - VM link and name as {'name': '065e86e5-f597-4005-a20a-487693d21e99', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-machine/065e86e5-f597-4005-a20a-487693d21e99?flat'} 2026-02-12 02:42:29,759 - DEBUG - Verifying the 065e86e5-f597-4005-a20a-487693d21e99 virtual network link through opserver 10.0.0.23 2026-02-12 02:42:29,759 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2026-02-12 02:42:29,770 - DEBUG - VM link and name as {'name': '065e86e5-f597-4005-a20a-487693d21e99', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/065e86e5-f597-4005-a20a-487693d21e99?flat'} 2026-02-12 02:42:29,770 - INFO - VM ctest-VM21-42024060 validations in Opserver passed 2026-02-12 02:42:29,851 - DEBUG - VM is in ACTIVE state now 2026-02-12 02:42:29,851 - INFO - VM name : ctest-VM31-64864539 2026-02-12 02:42:29,940 - DEBUG - VM ctest-VM31-64864539 ID is 405296e8-656c-46a8-91c0-d5aaa11bffa3 2026-02-12 02:42:30,036 - DEBUG - VM is in ACTIVE state now 2026-02-12 02:42:30,036 - DEBUG - VM ctest-VM31-64864539 launched on Node an-jenkins-deploy-platform-ansible-os-5305-2 2026-02-12 02:42:30,129 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/405296e8-656c-46a8-91c0-d5aaa11bffa3 2026-02-12 02:42:30,146 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/405296e8-656c-46a8-91c0-d5aaa11bffa3 2026-02-12 02:42:30,153 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/4fc6c1de-3a97-4e87-a2f5-79ff28e52ed8 2026-02-12 02:42:30,163 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/405296e8-656c-46a8-91c0-d5aaa11bffa3 2026-02-12 02:42:30,170 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/4fc6c1de-3a97-4e87-a2f5-79ff28e52ed8 2026-02-12 02:42:30,179 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/34ba75f3-2b3e-4787-9004-7c97deeb49cb 2026-02-12 02:42:30,191 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/a078655e-6da0-4641-bed5-055f45610686 2026-02-12 02:42:30,202 - DEBUG - Verifying in api server 10.0.0.23 2026-02-12 02:42:30,202 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/405296e8-656c-46a8-91c0-d5aaa11bffa3 2026-02-12 02:42:30,212 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/4fc6c1de-3a97-4e87-a2f5-79ff28e52ed8 2026-02-12 02:42:30,222 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/34ba75f3-2b3e-4787-9004-7c97deeb49cb 2026-02-12 02:42:30,229 - DEBUG - Requesting: http://10.0.0.23:8082/instance-ip/a078655e-6da0-4641-bed5-055f45610686 2026-02-12 02:42:30,235 - INFO - VM ctest-VM31-64864539 verfication in all API Servers passed 2026-02-12 02:42:30,254 - DEBUG - VM ctest-VM31-64864539 Tap interface: {'index': '4', 'name': 'tap4fc6c1de-3a', 'uuid': '4fc6c1de-3a97-4e87-a2f5-79ff28e52ed8', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689:ctest-VN3-46913689', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '17', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689', 'vm_uuid': '405296e8-656c-46a8-91c0-d5aaa11bffa3', 'vm_name': 'ctest-VM31-64864539', 'ip_addr': '142.45.251.195', 'mac_addr': '02:4f:c6:c1:de:3a', '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-13795166:4fc6c1de-3a97-4e87-a2f5-79ff28e52ed8', 'sg_uuid_list': ['4ef07067-8001-4b9a-9dbd-768d0fbd0242'], 'static_route_list': None, 'vm_project_uuid': '5afbd078-064e-4979-a63d-f0895efdf617', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '34c8:b992:dd0c:65d3:1ceb:86e4: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-02-12 02:42:30,254 - DEBUG - Agent 10.0.0.131 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689:ctest-VN3-46913689 2026-02-12 02:42:30,260 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689:ctest-VN3-46913689', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689', 'table_label': '-1', 'vxlan_id': '17', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.252:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-02-12 02:42:30,352 - DEBUG - VM ctest-VM31-64864539 : Tap interface tap4fc6c1de-3a is set to Active 2026-02-12 02:42:30,352 - DEBUG - Tap interface tap4fc6c1de-3a detail : {'index': '4', 'name': 'tap4fc6c1de-3a', 'uuid': '4fc6c1de-3a97-4e87-a2f5-79ff28e52ed8', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689:ctest-VN3-46913689', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Active', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Enable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '17', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689', 'vm_uuid': '405296e8-656c-46a8-91c0-d5aaa11bffa3', 'vm_name': 'ctest-VM31-64864539', 'ip_addr': '142.45.251.195', 'mac_addr': '02:4f:c6:c1:de:3a', '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-13795166:4fc6c1de-3a97-4e87-a2f5-79ff28e52ed8', 'sg_uuid_list': ['4ef07067-8001-4b9a-9dbd-768d0fbd0242'], 'static_route_list': None, 'vm_project_uuid': '5afbd078-064e-4979-a63d-f0895efdf617', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '34c8:b992:dd0c:65d3:1ceb:86e4: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-02-12 02:42:30,462 - DEBUG - Starting Layer 2 verification in Agent 2026-02-12 02:42:30,476 - DEBUG - Layer 2 path is seen for VM MAC 02:4f:c6:c1:de:3a in agent 10.0.0.131 2026-02-12 02:42:30,476 - DEBUG - Active layer 2 route in agent is present for VMI tap4fc6c1de-3a 2026-02-12 02:42:30,476 - DEBUG - L2 label(36) matches bw route table and interface table 2026-02-12 02:42:30,487 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689 is found in Agent of node 10.0.0.131 2026-02-12 02:42:30,631 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689 is consistent in agent 10.0.0.131 2026-02-12 02:42:30,631 - DEBUG - Route for VM IP ['34c8:b992:dd0c:65d3:1ceb:86e4:0:3', '142.45.251.195'] is consistent in agent 10.0.0.131 2026-02-12 02:42:30,631 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689 verification for VM ctest-VM31-64864539 in Agent 10.0.0.131 passed 2026-02-12 02:42:30,747 - DEBUG - Starting all layer 2 verification in agent 10.0.0.131 2026-02-12 02:42:30,756 - DEBUG - Route for VM MAC 02:4f:c6:c1:de:3a is consistent in agent 10.0.0.131 2026-02-12 02:42:31,941 - 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=6.28 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.341 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.341/3.309/6.277/2.968 ms') 2026-02-12 02:42:31,941 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-64864539 passed 2026-02-12 02:42:31,941 - INFO - VM ctest-VM31-64864539 verifications in Compute nodes passed 2026-02-12 02:42:32,154 - DEBUG - Validated VM route 142.45.251.195 in vrouter of 10.0.0.131 2026-02-12 02:42:32,160 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-02-12 02:42:32,160 - INFO - Validated routes of VM ctest-VM31-64864539 in all vrouters 2026-02-12 02:42:32,671 - DEBUG - Starting all layer2 verification in 10.0.0.23 Control Node 2026-02-12 02:42:32,793 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.23, not supported 2026-02-12 02:42:32,925 - DEBUG - Layer2 route found for VM MAC 02:4f:c6:c1:de:3a in Control-node 10.0.0.23 2026-02-12 02:42:32,925 - DEBUG - L2 Label for VM ctest-VM31-64864539 same between Control-node 10.0.0.23 and Agent, Expected: 36, Seen: 36 2026-02-12 02:42:32,925 - DEBUG - Starting all layer2 verification in 10.0.0.22 Control Node 2026-02-12 02:42:33,041 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.22, not supported 2026-02-12 02:42:33,171 - DEBUG - Layer2 route found for VM MAC 02:4f:c6:c1:de:3a in Control-node 10.0.0.22 2026-02-12 02:42:33,171 - DEBUG - L2 Label for VM ctest-VM31-64864539 same between Control-node 10.0.0.22 and Agent, Expected: 36, Seen: 36 2026-02-12 02:42:33,171 - INFO - Verification in Control-nodes for VM ctest-VM31-64864539 passed 2026-02-12 02:42:33,171 - DEBUG - Verifying the vm in opserver 2026-02-12 02:42:33,171 - DEBUG - Verifying in collector 10.0.0.23 ... 2026-02-12 02:42:33,171 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/405296e8-656c-46a8-91c0-d5aaa11bffa3?flat 2026-02-12 02:42:33,187 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-13795166:4fc6c1de-3a97-4e87-a2f5-79ff28e52ed8?flat 2026-02-12 02:42:33,205 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-13795166:4fc6c1de-3a97-4e87-a2f5-79ff28e52ed8?flat 2026-02-12 02:42:33,230 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-13795166:4fc6c1de-3a97-4e87-a2f5-79ff28e52ed8?flat 2026-02-12 02:42:33,251 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/405296e8-656c-46a8-91c0-d5aaa11bffa3?flat 2026-02-12 02:42:33,266 - DEBUG - Verifying vm in vn uve 2026-02-12 02:42:33,267 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-13795166:4fc6c1de-3a97-4e87-a2f5-79ff28e52ed8?flat 2026-02-12 02:42:33,287 - DEBUG - VM uve shows interface as {'rx_vlan': 65535, 'fixed_ip6_list': ['34c8:b992:dd0c:65d3:1ceb:86e4:0:3'], '__T': 1770864105440339, 'uuid': '4fc6c1de-3a97-4e87-a2f5-79ff28e52ed8', 'active': True, 'label': 32, 'port_mirror_enabled': False, 'ip4_active': True, 'vm_uuid': '405296e8-656c-46a8-91c0-d5aaa11bffa3', 'ip6_address': '34c8:b992:dd0c:65d3:1ceb:86e4:0:3', 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689', 'vm_name': 'ctest-VM31-64864539', 'vn_uuid': '80a17cb1-3d03-4a5e-998a-3358b9821dfd', 'admin_state': True, 'ip_address': '142.45.251.195', 'gateway': '142.45.251.193', 'ip6_active': True, 'l2_active': True, 'tx_vlan': 65535, 'vhostuser_mode': 0, 'mac_address': '02:4f:c6:c1:de:3a', 'is_health_check_active': True, 'fixed_ip4_list': ['142.45.251.195']} 2026-02-12 02:42:33,287 - DEBUG - VM uve shows ip address as ['142.45.251.195', '34c8:b992:dd0c:65d3:1ceb:86e4:0:3'] 2026-02-12 02:42:33,287 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689 2026-02-12 02:42:33,287 - INFO - Verifying through opserver in 10.0.0.130 2026-02-12 02:42:33,287 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689?flat 2026-02-12 02:42:33,307 - DEBUG - expected vm list 405296e8-656c-46a8-91c0-d5aaa11bffa3 2026-02-12 02:42:33,307 - DEBUG - Extracted vm list ['405296e8-656c-46a8-91c0-d5aaa11bffa3'] 2026-02-12 02:42:33,307 - INFO - VM 405296e8-656c-46a8-91c0-d5aaa11bffa3 is present in default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689 2026-02-12 02:42:33,307 - DEBUG - Verifying vm in vrouter uve 2026-02-12 02:42:33,307 - DEBUG - Getting info from collector 10.0.0.23.. 2026-02-12 02:42:33,307 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machine/405296e8-656c-46a8-91c0-d5aaa11bffa3?flat 2026-02-12 02:42:33,322 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5305-2'] 2026-02-12 02:42:33,322 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-2:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:42:33,358 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-2?flat 2026-02-12 02:42:33,388 - DEBUG - VM 405296e8-656c-46a8-91c0-d5aaa11bffa3 is present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:42:33,388 - DEBUG - Validated that VM ctest-VM31-64864539 is in Vrouter an-jenkins-deploy-platform-ansible-os-5305-2 UVE 2026-02-12 02:42:33,388 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-02-12 02:42:33,395 - DEBUG - Expected tap interface of VM uuid 405296e8-656c-46a8-91c0-d5aaa11bffa3 is default-domain:ctest-TestPolicyAclIpv4v6-13795166:4fc6c1de-3a97-4e87-a2f5-79ff28e52ed8 2026-02-12 02:42:33,395 - DEBUG - Expected VN of VM uuid 405296e8-656c-46a8-91c0-d5aaa11bffa3 is default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689 2026-02-12 02:42:33,395 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-2:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:42:33,428 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-2?flat 2026-02-12 02:42:33,459 - DEBUG - VM 405296e8-656c-46a8-91c0-d5aaa11bffa3 is present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:42:33,459 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-13795166:4fc6c1de-3a97-4e87-a2f5-79ff28e52ed8 of vm 405296e8-656c-46a8-91c0-d5aaa11bffa3 is present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:42:33,459 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689 of vm 405296e8-656c-46a8-91c0-d5aaa11bffa3 is present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:42:33,459 - DEBUG - Verifying the 405296e8-656c-46a8-91c0-d5aaa11bffa3 virtual network link through opserver 10.0.0.130 2026-02-12 02:42:33,459 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines 2026-02-12 02:42:33,472 - DEBUG - VM link and name as {'name': '405296e8-656c-46a8-91c0-d5aaa11bffa3', 'href': 'http://10.0.0.130:8081/analytics/uves/virtual-machine/405296e8-656c-46a8-91c0-d5aaa11bffa3?flat'} 2026-02-12 02:42:33,472 - DEBUG - Verifying the 405296e8-656c-46a8-91c0-d5aaa11bffa3 virtual network link through opserver 10.0.0.22 2026-02-12 02:42:33,472 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines 2026-02-12 02:42:33,485 - DEBUG - VM link and name as {'name': '405296e8-656c-46a8-91c0-d5aaa11bffa3', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-machine/405296e8-656c-46a8-91c0-d5aaa11bffa3?flat'} 2026-02-12 02:42:33,485 - DEBUG - Verifying the 405296e8-656c-46a8-91c0-d5aaa11bffa3 virtual network link through opserver 10.0.0.23 2026-02-12 02:42:33,485 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2026-02-12 02:42:33,497 - DEBUG - VM link and name as {'name': '405296e8-656c-46a8-91c0-d5aaa11bffa3', 'href': 'http://10.0.0.23:8081/analytics/uves/virtual-machine/405296e8-656c-46a8-91c0-d5aaa11bffa3?flat'} 2026-02-12 02:42:33,497 - INFO - VM ctest-VM31-64864539 validations in Opserver passed 2026-02-12 02:42:33,497 - INFO - Waiting for VM ctest-VM11-15542569 to be up.. 2026-02-12 02:42:33,497 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-12 02:42:33,498 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/a409ec57-c79a-4fd8-a33e-86b48ccccb32 2026-02-12 02:42:33,505 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/e9ea76f2-68a0-402d-81ff-7e0c0a0ef3dd 2026-02-12 02:42:34,672 - 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.84 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.81 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.810/1.825/1.840/0.015 ms') 2026-02-12 02:42:34,673 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-15542569 passed 2026-02-12 02:42:34,742 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-12 02:42:34,742 - DEBUG - Waiting to SSH to VM ctest-VM11-15542569, IP 43.79.168.131, Port 22 2026-02-12 02:42:34,807 - 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-02-12 02:42:34,883 - DEBUG - VM ctest-VM11-15542569 is NOT ready for SSH connections, VM status: ACTIVE 2026-02-12 02:42:39,884 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-12 02:42:39,884 - DEBUG - Waiting to SSH to VM ctest-VM11-15542569, IP 43.79.168.131, Port 22 2026-02-12 02:42:39,940 - 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-02-12 02:42:40,031 - DEBUG - VM ctest-VM11-15542569 is NOT ready for SSH connections, VM status: ACTIVE 2026-02-12 02:42:45,032 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-12 02:42:45,032 - DEBUG - Waiting to SSH to VM ctest-VM11-15542569, IP 43.79.168.131, Port 22 2026-02-12 02:42:45,100 - 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-02-12 02:42:45,180 - DEBUG - VM ctest-VM11-15542569 is NOT ready for SSH connections, VM status: ACTIVE 2026-02-12 02:42:50,181 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-12 02:42:50,181 - DEBUG - Waiting to SSH to VM ctest-VM11-15542569, IP 43.79.168.131, Port 22 2026-02-12 02:42:50,346 - DEBUG - VM ctest-VM11-15542569 is ready for SSH connections 2026-02-12 02:42:50,346 - INFO - Waiting for VM ctest-VM21-42024060 to be up.. 2026-02-12 02:42:50,346 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-12 02:42:50,346 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/065e86e5-f597-4005-a20a-487693d21e99 2026-02-12 02:42:50,355 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/856cc28f-1b67-416e-b8e1-d99ae84b3eea 2026-02-12 02:42:51,555 - 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=3.13 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.431 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.431/1.781/3.131/1.350 ms') 2026-02-12 02:42:51,556 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-42024060 passed 2026-02-12 02:42:51,626 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-12 02:42:51,627 - DEBUG - Waiting to SSH to VM ctest-VM21-42024060, IP 71.113.246.3, Port 22 2026-02-12 02:42:51,793 - DEBUG - VM ctest-VM21-42024060 is ready for SSH connections 2026-02-12 02:42:51,793 - INFO - Waiting for VM ctest-VM31-64864539 to be up.. 2026-02-12 02:42:51,793 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-12 02:42:51,794 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/405296e8-656c-46a8-91c0-d5aaa11bffa3 2026-02-12 02:42:51,803 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/4fc6c1de-3a97-4e87-a2f5-79ff28e52ed8 2026-02-12 02:42:52,957 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.68 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.513 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.513/1.595/2.678/1.082 ms') 2026-02-12 02:42:52,957 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-64864539 passed 2026-02-12 02:42:53,026 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-12 02:42:53,026 - DEBUG - Waiting to SSH to VM ctest-VM31-64864539, IP 142.45.251.195, Port 22 2026-02-12 02:42:53,195 - DEBUG - VM ctest-VM31-64864539 is ready for SSH connections 2026-02-12 02:42:53,195 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 71.113.246.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.131, gateway password: c0ntrail123 2026-02-12 02:42:53,196 - DEBUG - ping -s 56 -c 3 -W 1 71.113.246.3 2026-02-12 02:43:11,091 - DEBUG - PING 71.113.246.3 (71.113.246.3) 56(84) bytes of data. 64 bytes from 71.113.246.3: icmp_req=1 ttl=63 time=7.33 ms 64 bytes from 71.113.246.3: icmp_req=2 ttl=63 time=1.37 ms 64 bytes from 71.113.246.3: icmp_req=3 ttl=63 time=0.902 ms --- 71.113.246.3 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2003ms rtt min/avg/max/mdev = 0.902/3.204/7.332/2.925 ms 2026-02-12 02:43:11,091 - INFO - Ping to IP 71.113.246.3 from VM ctest-VM11-15542569 passed 2026-02-12 02:43:11,091 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 3298:584a:bbd3:4f92:ffc5:b563:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.131, gateway password: c0ntrail123 2026-02-12 02:43:11,091 - DEBUG - ping6 -s 56 -c 3 -W 1 3298:584a:bbd3:4f92:ffc5:b563:0:3 2026-02-12 02:43:13,603 - DEBUG - PING 3298:584a:bbd3:4f92:ffc5:b563:0:3(3298:584a:bbd3:4f92:ffc5:b563:0:3) 56 data bytes 64 bytes from 3298:584a:bbd3:4f92:ffc5:b563:0:3: icmp_seq=1 ttl=64 time=5.73 ms 64 bytes from 3298:584a:bbd3:4f92:ffc5:b563:0:3: icmp_seq=2 ttl=64 time=1.30 ms 64 bytes from 3298:584a:bbd3:4f92:ffc5:b563:0:3: icmp_seq=3 ttl=64 time=1.19 ms --- 3298:584a:bbd3:4f92:ffc5:b563:0:3 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.196/2.745/5.738/2.116 ms 2026-02-12 02:43:13,603 - INFO - Ping to IP 3298:584a:bbd3:4f92:ffc5:b563:0:3 from VM ctest-VM11-15542569 passed 2026-02-12 02:43:13,604 - INFO - Test with src as policy and dst as any PASSED 2026-02-12 02:43:13,604 - INFO - Deleting VM ctest-VM31-64864539 2026-02-12 02:43:13,684 - DEBUG - Verifying in api server 10.0.0.23 2026-02-12 02:43:13,684 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/405296e8-656c-46a8-91c0-d5aaa11bffa3 2026-02-12 02:43:13,698 - DEBUG - VM ID 405296e8-656c-46a8-91c0-d5aaa11bffa3 of VM ctest-VM31-64864539 is still found in API Server 2026-02-12 02:43:15,699 - DEBUG - Verifying in api server 10.0.0.23 2026-02-12 02:43:15,699 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/405296e8-656c-46a8-91c0-d5aaa11bffa3 2026-02-12 02:43:15,706 - DEBUG - Response Code: 404 2026-02-12 02:43:15,706 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/405296e8-656c-46a8-91c0-d5aaa11bffa3 2026-02-12 02:43:15,712 - DEBUG - Response Code: 404 2026-02-12 02:43:15,712 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/405296e8-656c-46a8-91c0-d5aaa11bffa3 2026-02-12 02:43:15,718 - DEBUG - Response Code: 404 2026-02-12 02:43:15,718 - INFO - VM ctest-VM31-64864539 is fully removed in API-Server 2026-02-12 02:43:15,873 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-02-12 02:43:15,916 - DEBUG - Validated that vrouter 10.0.0.141 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689 2026-02-12 02:43:15,925 - DEBUG - Validated that vrouter 10.0.0.131 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689 2026-02-12 02:43:15,925 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689 2026-02-12 02:43:15,925 - INFO - VM ctest-VM31-64864539 is removed in Compute, and routes are removed in all compute nodes 2026-02-12 02:43:16,154 - INFO - Routes for VM ctest-VM31-64864539 is removed in all control-nodes 2026-02-12 02:43:16,154 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-2:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:43:16,186 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-2?flat 2026-02-12 02:43:16,212 - DEBUG - VM 405296e8-656c-46a8-91c0-d5aaa11bffa3 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:43:16,212 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:43:16,213 - DEBUG - interface for vm 405296e8-656c-46a8-91c0-d5aaa11bffa3 deleted from vrouter uve ... 2026-02-12 02:43:16,213 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:43:16,213 - DEBUG - Validated that VM 405296e8-656c-46a8-91c0-d5aaa11bffa3 is removed in Vrouter UVE 2026-02-12 02:43:16,213 - DEBUG - Verifying through opserver in 10.0.0.130 2026-02-12 02:43:16,213 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689?flat 2026-02-12 02:43:16,229 - DEBUG - 405296e8-656c-46a8-91c0-d5aaa11bffa3 BM not in default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN3-46913689 uve 2026-02-12 02:43:16,229 - DEBUG - Validated that VM 405296e8-656c-46a8-91c0-d5aaa11bffa3 is not present in the VN UVE 2026-02-12 02:43:16,229 - DEBUG - Verifying the 405296e8-656c-46a8-91c0-d5aaa11bffa3 virtual network link through opserver 10.0.0.130 2026-02-12 02:43:16,229 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines 2026-02-12 02:43:16,241 - INFO - 405296e8-656c-46a8-91c0-d5aaa11bffa3 vm uve deleted from opserver 2026-02-12 02:43:16,241 - DEBUG - Verifying the 405296e8-656c-46a8-91c0-d5aaa11bffa3 virtual network link through opserver 10.0.0.22 2026-02-12 02:43:16,241 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines 2026-02-12 02:43:16,252 - INFO - 405296e8-656c-46a8-91c0-d5aaa11bffa3 vm uve deleted from opserver 2026-02-12 02:43:16,252 - DEBUG - Verifying the 405296e8-656c-46a8-91c0-d5aaa11bffa3 virtual network link through opserver 10.0.0.23 2026-02-12 02:43:16,252 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2026-02-12 02:43:16,265 - INFO - 405296e8-656c-46a8-91c0-d5aaa11bffa3 vm uve deleted from opserver 2026-02-12 02:43:16,265 - INFO - Validated that VM 405296e8-656c-46a8-91c0-d5aaa11bffa3 is removed from Opserver 2026-02-12 02:43:16,265 - INFO - Deleting VM ctest-VM21-42024060 2026-02-12 02:43:16,337 - DEBUG - Verifying in api server 10.0.0.23 2026-02-12 02:43:16,337 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/065e86e5-f597-4005-a20a-487693d21e99 2026-02-12 02:43:16,346 - DEBUG - VM ID 065e86e5-f597-4005-a20a-487693d21e99 of VM ctest-VM21-42024060 is still found in API Server 2026-02-12 02:43:18,347 - DEBUG - Verifying in api server 10.0.0.23 2026-02-12 02:43:18,347 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/065e86e5-f597-4005-a20a-487693d21e99 2026-02-12 02:43:18,354 - DEBUG - Response Code: 404 2026-02-12 02:43:18,354 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/065e86e5-f597-4005-a20a-487693d21e99 2026-02-12 02:43:18,361 - DEBUG - Response Code: 404 2026-02-12 02:43:18,361 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/065e86e5-f597-4005-a20a-487693d21e99 2026-02-12 02:43:18,367 - DEBUG - Response Code: 404 2026-02-12 02:43:18,367 - INFO - VM ctest-VM21-42024060 is fully removed in API-Server 2026-02-12 02:43:18,535 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-02-12 02:43:18,574 - DEBUG - Validated that vrouter 10.0.0.141 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563 2026-02-12 02:43:18,585 - DEBUG - Validated that vrouter 10.0.0.131 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563 2026-02-12 02:43:18,585 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563 2026-02-12 02:43:18,585 - INFO - VM ctest-VM21-42024060 is removed in Compute, and routes are removed in all compute nodes 2026-02-12 02:43:18,809 - INFO - Routes for VM ctest-VM21-42024060 is removed in all control-nodes 2026-02-12 02:43:18,809 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-1:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:43:18,841 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-1?flat 2026-02-12 02:43:18,870 - DEBUG - VM 065e86e5-f597-4005-a20a-487693d21e99 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-1 uve 2026-02-12 02:43:18,870 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:43:18,870 - DEBUG - interface for vm 065e86e5-f597-4005-a20a-487693d21e99 deleted from vrouter uve ... 2026-02-12 02:43:18,870 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:43:21,871 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-1:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:43:21,906 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-1?flat 2026-02-12 02:43:21,936 - DEBUG - VM 065e86e5-f597-4005-a20a-487693d21e99 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-1 uve 2026-02-12 02:43:21,936 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:43:21,936 - DEBUG - interface for vm 065e86e5-f597-4005-a20a-487693d21e99 deleted from vrouter uve ... 2026-02-12 02:43:21,936 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:43:24,937 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-1:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:43:24,973 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-1?flat 2026-02-12 02:43:25,003 - DEBUG - VM 065e86e5-f597-4005-a20a-487693d21e99 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-1 uve 2026-02-12 02:43:25,003 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:43:25,003 - DEBUG - interface for vm 065e86e5-f597-4005-a20a-487693d21e99 deleted from vrouter uve ... 2026-02-12 02:43:25,003 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:43:28,004 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-1:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:43:28,035 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-1?flat 2026-02-12 02:43:28,061 - DEBUG - VM 065e86e5-f597-4005-a20a-487693d21e99 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-1 uve 2026-02-12 02:43:28,061 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:43:28,061 - DEBUG - interface for vm 065e86e5-f597-4005-a20a-487693d21e99 deleted from vrouter uve ... 2026-02-12 02:43:28,061 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:43:31,062 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-1:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:43:31,093 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-1?flat 2026-02-12 02:43:31,123 - DEBUG - VM 065e86e5-f597-4005-a20a-487693d21e99 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-1 uve 2026-02-12 02:43:31,123 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:43:31,123 - DEBUG - interface for vm 065e86e5-f597-4005-a20a-487693d21e99 deleted from vrouter uve ... 2026-02-12 02:43:31,123 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:43:34,124 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-1:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:43:34,153 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-1?flat 2026-02-12 02:43:34,180 - DEBUG - VM 065e86e5-f597-4005-a20a-487693d21e99 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-1 uve 2026-02-12 02:43:34,180 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:43:34,180 - DEBUG - interface for vm 065e86e5-f597-4005-a20a-487693d21e99 deleted from vrouter uve ... 2026-02-12 02:43:34,180 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:43:37,181 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-1:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:43:37,213 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-1?flat 2026-02-12 02:43:37,241 - DEBUG - VM 065e86e5-f597-4005-a20a-487693d21e99 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-1 uve 2026-02-12 02:43:37,241 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:43:37,241 - DEBUG - interface for vm 065e86e5-f597-4005-a20a-487693d21e99 deleted from vrouter uve ... 2026-02-12 02:43:37,241 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:43:40,242 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-1:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:43:40,272 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-1?flat 2026-02-12 02:43:40,303 - DEBUG - VM 065e86e5-f597-4005-a20a-487693d21e99 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-1 uve 2026-02-12 02:43:40,303 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:43:40,303 - DEBUG - interface for vm 065e86e5-f597-4005-a20a-487693d21e99 deleted from vrouter uve ... 2026-02-12 02:43:40,303 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:43:43,303 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-1:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:43:43,334 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-1?flat 2026-02-12 02:43:43,360 - DEBUG - VM 065e86e5-f597-4005-a20a-487693d21e99 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-1 uve 2026-02-12 02:43:43,360 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:43:43,360 - DEBUG - interface for vm 065e86e5-f597-4005-a20a-487693d21e99 deleted from vrouter uve ... 2026-02-12 02:43:43,360 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:43:46,361 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-1:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:43:46,394 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-1?flat 2026-02-12 02:43:46,420 - DEBUG - VM 065e86e5-f597-4005-a20a-487693d21e99 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5305-1 uve 2026-02-12 02:43:46,420 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:43:46,420 - DEBUG - interface for vm 065e86e5-f597-4005-a20a-487693d21e99 deleted from vrouter uve ... 2026-02-12 02:43:46,420 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:43:46,420 - DEBUG - Validated that VM 065e86e5-f597-4005-a20a-487693d21e99 is removed in Vrouter UVE 2026-02-12 02:43:46,420 - DEBUG - Verifying through opserver in 10.0.0.130 2026-02-12 02:43:46,420 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563?flat 2026-02-12 02:43:46,438 - DEBUG - 065e86e5-f597-4005-a20a-487693d21e99 BM not in default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN2-84231563 uve 2026-02-12 02:43:46,438 - DEBUG - Validated that VM 065e86e5-f597-4005-a20a-487693d21e99 is not present in the VN UVE 2026-02-12 02:43:46,438 - DEBUG - Verifying the 065e86e5-f597-4005-a20a-487693d21e99 virtual network link through opserver 10.0.0.130 2026-02-12 02:43:46,438 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines 2026-02-12 02:43:46,449 - INFO - 065e86e5-f597-4005-a20a-487693d21e99 vm uve deleted from opserver 2026-02-12 02:43:46,450 - DEBUG - Verifying the 065e86e5-f597-4005-a20a-487693d21e99 virtual network link through opserver 10.0.0.22 2026-02-12 02:43:46,450 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines 2026-02-12 02:43:46,463 - INFO - 065e86e5-f597-4005-a20a-487693d21e99 vm uve deleted from opserver 2026-02-12 02:43:46,463 - DEBUG - Verifying the 065e86e5-f597-4005-a20a-487693d21e99 virtual network link through opserver 10.0.0.23 2026-02-12 02:43:46,463 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2026-02-12 02:43:46,476 - INFO - 065e86e5-f597-4005-a20a-487693d21e99 vm uve deleted from opserver 2026-02-12 02:43:46,476 - INFO - Validated that VM 065e86e5-f597-4005-a20a-487693d21e99 is removed from Opserver 2026-02-12 02:43:46,476 - INFO - Deleting VM ctest-VM11-15542569 2026-02-12 02:43:46,543 - DEBUG - Verifying in api server 10.0.0.23 2026-02-12 02:43:46,543 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/a409ec57-c79a-4fd8-a33e-86b48ccccb32 2026-02-12 02:43:46,554 - DEBUG - VM ID a409ec57-c79a-4fd8-a33e-86b48ccccb32 of VM ctest-VM11-15542569 is still found in API Server 2026-02-12 02:43:48,555 - DEBUG - Verifying in api server 10.0.0.23 2026-02-12 02:43:48,556 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/a409ec57-c79a-4fd8-a33e-86b48ccccb32 2026-02-12 02:43:48,564 - DEBUG - Response Code: 404 2026-02-12 02:43:48,565 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/a409ec57-c79a-4fd8-a33e-86b48ccccb32 2026-02-12 02:43:48,571 - DEBUG - Response Code: 404 2026-02-12 02:43:48,571 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/a409ec57-c79a-4fd8-a33e-86b48ccccb32 2026-02-12 02:43:48,577 - DEBUG - Response Code: 404 2026-02-12 02:43:48,577 - INFO - VM ctest-VM11-15542569 is fully removed in API-Server 2026-02-12 02:43:48,732 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-02-12 02:43:48,771 - DEBUG - Validated that vrouter 10.0.0.141 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 2026-02-12 02:43:48,779 - DEBUG - Validated that vrouter 10.0.0.131 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 2026-02-12 02:43:48,779 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 2026-02-12 02:43:48,779 - INFO - VM ctest-VM11-15542569 is removed in Compute, and routes are removed in all compute nodes 2026-02-12 02:43:48,988 - INFO - Routes for VM ctest-VM11-15542569 is removed in all control-nodes 2026-02-12 02:43:48,988 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-2:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:43:49,026 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-2?flat 2026-02-12 02:43:49,056 - DEBUG - VM a409ec57-c79a-4fd8-a33e-86b48ccccb32 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:43:49,056 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:43:49,056 - DEBUG - interface for vm a409ec57-c79a-4fd8-a33e-86b48ccccb32 deleted from vrouter uve ... 2026-02-12 02:43:49,057 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:43:52,057 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-2:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:43:52,087 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-2?flat 2026-02-12 02:43:52,118 - DEBUG - VM a409ec57-c79a-4fd8-a33e-86b48ccccb32 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:43:52,118 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:43:52,118 - DEBUG - interface for vm a409ec57-c79a-4fd8-a33e-86b48ccccb32 deleted from vrouter uve ... 2026-02-12 02:43:52,118 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:43:55,119 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-2:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:43:55,148 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-2?flat 2026-02-12 02:43:55,173 - DEBUG - VM a409ec57-c79a-4fd8-a33e-86b48ccccb32 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:43:55,173 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:43:55,174 - DEBUG - interface for vm a409ec57-c79a-4fd8-a33e-86b48ccccb32 deleted from vrouter uve ... 2026-02-12 02:43:55,174 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:43:58,174 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-2:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:43:58,206 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-2?flat 2026-02-12 02:43:58,232 - DEBUG - VM a409ec57-c79a-4fd8-a33e-86b48ccccb32 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:43:58,232 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:43:58,232 - DEBUG - interface for vm a409ec57-c79a-4fd8-a33e-86b48ccccb32 deleted from vrouter uve ... 2026-02-12 02:43:58,232 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:44:01,233 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-2:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:44:01,265 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-2?flat 2026-02-12 02:44:01,298 - DEBUG - VM a409ec57-c79a-4fd8-a33e-86b48ccccb32 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:44:01,298 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:44:01,298 - DEBUG - interface for vm a409ec57-c79a-4fd8-a33e-86b48ccccb32 deleted from vrouter uve ... 2026-02-12 02:44:01,298 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:44:04,299 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-2:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:44:04,341 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-2?flat 2026-02-12 02:44:04,368 - DEBUG - VM a409ec57-c79a-4fd8-a33e-86b48ccccb32 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:44:04,368 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:44:04,368 - DEBUG - interface for vm a409ec57-c79a-4fd8-a33e-86b48ccccb32 deleted from vrouter uve ... 2026-02-12 02:44:04,368 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:44:07,369 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-2:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:44:07,400 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-2?flat 2026-02-12 02:44:07,428 - DEBUG - VM a409ec57-c79a-4fd8-a33e-86b48ccccb32 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:44:07,428 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:44:07,428 - DEBUG - interface for vm a409ec57-c79a-4fd8-a33e-86b48ccccb32 deleted from vrouter uve ... 2026-02-12 02:44:07,428 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:44:10,429 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-2:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:44:10,461 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-2?flat 2026-02-12 02:44:10,488 - DEBUG - VM a409ec57-c79a-4fd8-a33e-86b48ccccb32 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:44:10,488 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:44:10,488 - DEBUG - interface for vm a409ec57-c79a-4fd8-a33e-86b48ccccb32 deleted from vrouter uve ... 2026-02-12 02:44:10,489 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:44:13,489 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-2:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:44:13,522 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-2?flat 2026-02-12 02:44:13,549 - DEBUG - VM a409ec57-c79a-4fd8-a33e-86b48ccccb32 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:44:13,549 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:44:13,549 - DEBUG - interface for vm a409ec57-c79a-4fd8-a33e-86b48ccccb32 deleted from vrouter uve ... 2026-02-12 02:44:13,549 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:44:16,549 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5305-2:Compute:contrail-vrouter-agent:0?flat 2026-02-12 02:44:16,599 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5305-2?flat 2026-02-12 02:44:16,630 - DEBUG - VM a409ec57-c79a-4fd8-a33e-86b48ccccb32 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5305-2 uve 2026-02-12 02:44:16,630 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-02-12 02:44:16,630 - DEBUG - interface for vm a409ec57-c79a-4fd8-a33e-86b48ccccb32 deleted from vrouter uve ... 2026-02-12 02:44:16,630 - DEBUG - Deleted interface not in error interface list ... 2026-02-12 02:44:16,630 - DEBUG - Validated that VM a409ec57-c79a-4fd8-a33e-86b48ccccb32 is removed in Vrouter UVE 2026-02-12 02:44:16,630 - DEBUG - Verifying through opserver in 10.0.0.130 2026-02-12 02:44:16,630 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705?flat 2026-02-12 02:44:16,655 - DEBUG - a409ec57-c79a-4fd8-a33e-86b48ccccb32 BM not in default-domain:ctest-TestPolicyAclIpv4v6-13795166:ctest-VN1-44636705 uve 2026-02-12 02:44:16,655 - DEBUG - Validated that VM a409ec57-c79a-4fd8-a33e-86b48ccccb32 is not present in the VN UVE 2026-02-12 02:44:16,655 - DEBUG - Verifying the a409ec57-c79a-4fd8-a33e-86b48ccccb32 virtual network link through opserver 10.0.0.130 2026-02-12 02:44:16,655 - DEBUG - Requesting: http://10.0.0.130:8081/analytics/uves/virtual-machines 2026-02-12 02:44:16,668 - DEBUG - No links retuned for a409ec57-c79a-4fd8-a33e-86b48ccccb32 2026-02-12 02:44:16,668 - INFO - a409ec57-c79a-4fd8-a33e-86b48ccccb32 vm uve deleted from opserver 2026-02-12 02:44:16,668 - DEBUG - Verifying the a409ec57-c79a-4fd8-a33e-86b48ccccb32 virtual network link through opserver 10.0.0.22 2026-02-12 02:44:16,668 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines 2026-02-12 02:44:16,682 - DEBUG - No links retuned for a409ec57-c79a-4fd8-a33e-86b48ccccb32 2026-02-12 02:44:16,682 - INFO - a409ec57-c79a-4fd8-a33e-86b48ccccb32 vm uve deleted from opserver 2026-02-12 02:44:16,682 - DEBUG - Verifying the a409ec57-c79a-4fd8-a33e-86b48ccccb32 virtual network link through opserver 10.0.0.23 2026-02-12 02:44:16,682 - DEBUG - Requesting: http://10.0.0.23:8081/analytics/uves/virtual-machines 2026-02-12 02:44:16,695 - DEBUG - No links retuned for a409ec57-c79a-4fd8-a33e-86b48ccccb32 2026-02-12 02:44:16,696 - INFO - a409ec57-c79a-4fd8-a33e-86b48ccccb32 vm uve deleted from opserver 2026-02-12 02:44:16,696 - INFO - Validated that VM a409ec57-c79a-4fd8-a33e-86b48ccccb32 is removed from Opserver 2026-02-12 02:44:16,696 - DEBUG - Detaching the Policy for VN :ctest-VN2-84231563 2026-02-12 02:44:16,696 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:44:16,704 - DEBUG - Requesting: http://10.0.0.130:8082/domain/dbbdb6d5-ac40-4bc8-b2c9-9c29259fb031 2026-02-12 02:44:16,745 - DEBUG - Requesting: http://10.0.0.130:8082/project/5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:44:16,841 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/03e11bac-94bf-485d-b037-8fb2c81fd480 2026-02-12 02:44:16,857 - DEBUG - Requesting: http://10.0.0.130:8082/network-policy/0fb777e0-015d-437d-a1d0-3329fc3c183b 2026-02-12 02:44:17,023 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ctest-policy21-02878832']] from ctest-VN2-84231563 2026-02-12 02:44:17,023 - DEBUG - Detaching the Policy for VN :ctest-VN1-44636705 2026-02-12 02:44:17,023 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:44:17,031 - DEBUG - Requesting: http://10.0.0.130:8082/domain/dbbdb6d5-ac40-4bc8-b2c9-9c29259fb031 2026-02-12 02:44:17,049 - DEBUG - Requesting: http://10.0.0.130:8082/project/5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:44:17,138 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-network/9782bb80-9754-4c59-b4eb-934162e00736 2026-02-12 02:44:17,154 - DEBUG - Requesting: http://10.0.0.130:8082/network-policy/c0ad01f6-4c88-4060-a4bf-bf648f485b5c 2026-02-12 02:44:17,164 - DEBUG - Requesting: http://10.0.0.130:8082/network-policy/51b8ddaf-e2df-497c-9424-c3efe4d62f5d 2026-02-12 02:44:17,317 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ctest-policy12-67402433'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-13795166', 'ctest-policy13-16031057']] from ctest-VN1-44636705 2026-02-12 02:44:17,362 - INFO - Deleted policy ctest-policy21-02878832 2026-02-12 02:44:17,422 - INFO - Deleted policy ctest-policy12-67402433 2026-02-12 02:44:17,464 - INFO - Deleted policy ctest-policy13-16031057 2026-02-12 02:44:17,464 - INFO - Deleting VN ctest-VN3-46913689 2026-02-12 02:44:17,682 - DEBUG - Response for deleting network () 2026-02-12 02:44:17,682 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/554afa63-2834-45ce-ba98-e73514b656fe 2026-02-12 02:44:17,689 - DEBUG - Response Code: 404 2026-02-12 02:44:17,689 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:44:17,699 - DEBUG - Requesting: http://10.0.0.130:8082/domain/dbbdb6d5-ac40-4bc8-b2c9-9c29259fb031 2026-02-12 02:44:17,710 - DEBUG - Requesting: http://10.0.0.130:8082/project/5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:44:17,825 - INFO - Validated that VN ctest-VN3-46913689 is not found in API Server 2026-02-12 02:44:17,834 - DEBUG - VN ctest-VN3-46913689 is not present in Agent 10.0.0.141 2026-02-12 02:44:17,844 - DEBUG - VN ctest-VN3-46913689 is not present in Agent 10.0.0.131 2026-02-12 02:44:17,844 - INFO - Validated that VN ctest-VN3-46913689 is not in any agent 2026-02-12 02:44:17,871 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-46913689 info 2026-02-12 02:44:17,871 - INFO - Deleting VN ctest-VN2-84231563 2026-02-12 02:44:18,041 - DEBUG - Response for deleting network () 2026-02-12 02:44:18,041 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/2c2e3b00-e526-4509-a27e-d98d4c470232 2026-02-12 02:44:18,052 - DEBUG - Response Code: 404 2026-02-12 02:44:18,053 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:44:18,059 - DEBUG - Requesting: http://10.0.0.130:8082/domain/dbbdb6d5-ac40-4bc8-b2c9-9c29259fb031 2026-02-12 02:44:18,072 - DEBUG - Requesting: http://10.0.0.130:8082/project/5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:44:18,175 - INFO - Validated that VN ctest-VN2-84231563 is not found in API Server 2026-02-12 02:44:18,186 - DEBUG - VN ctest-VN2-84231563 is not present in Agent 10.0.0.141 2026-02-12 02:44:18,196 - DEBUG - VN ctest-VN2-84231563 is not present in Agent 10.0.0.131 2026-02-12 02:44:18,196 - INFO - Validated that VN ctest-VN2-84231563 is not in any agent 2026-02-12 02:44:18,242 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-84231563 info 2026-02-12 02:44:18,242 - INFO - Deleting VN ctest-VN1-44636705 2026-02-12 02:44:18,438 - DEBUG - Response for deleting network () 2026-02-12 02:44:18,438 - DEBUG - Requesting: http://10.0.0.130:8082/routing-instance/4197ebc6-a6a5-4634-b08a-bb3caffd4d5b 2026-02-12 02:44:18,448 - DEBUG - Response Code: 404 2026-02-12 02:44:18,448 - DEBUG - Requesting: http://10.0.0.130:8082/domains 2026-02-12 02:44:18,457 - DEBUG - Requesting: http://10.0.0.130:8082/domain/dbbdb6d5-ac40-4bc8-b2c9-9c29259fb031 2026-02-12 02:44:18,514 - DEBUG - Requesting: http://10.0.0.130:8082/project/5afbd078-064e-4979-a63d-f0895efdf617 2026-02-12 02:44:18,614 - INFO - Validated that VN ctest-VN1-44636705 is not found in API Server 2026-02-12 02:44:18,623 - DEBUG - VN ctest-VN1-44636705 is not present in Agent 10.0.0.141 2026-02-12 02:44:18,633 - DEBUG - VN ctest-VN1-44636705 is not present in Agent 10.0.0.131 2026-02-12 02:44:18,633 - INFO - Validated that VN ctest-VN1-44636705 is not in any agent 2026-02-12 02:44:18,664 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-44636705 info 2026-02-12 02:44:18,757 - INFO - IPAM: ipam3 is not found in API Server 2026-02-12 02:44:18,777 - INFO - IPAM:ipam3 is not found in control node 2026-02-12 02:44:18,913 - INFO - IPAM: ipam2 is not found in API Server 2026-02-12 02:44:18,936 - INFO - IPAM:ipam2 is not found in control node 2026-02-12 02:44:19,073 - INFO - IPAM: ipam1 is not found in API Server 2026-02-12 02:44:19,094 - INFO - IPAM:ipam1 is not found in control node 2026-02-12 02:44:20,353 - DEBUG - No XMPP flaps were noticed during the test

2026-02-12 02:44:20,353 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:54]

2026-02-12 02:44:20,353 - INFO - -------------------------------------------------------------------------------- 2026-02-12 02:44:21,619 - DEBUG - No XMPP flaps were noticed during the test

2026-02-12 02:44:21,619 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:02:57]

2026-02-12 02:44:21,619 - INFO - -------------------------------------------------------------------------------- 2026-02-12 02:44:22,351 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-13795166, ID : 5afbd078-064e-4979-a63d-f0895efdf617