2026-01-22 02:29:15,447 - INFO - Domain Default found not creating 2026-01-22 02:29:15,619 - INFO - Project ctest-TestPolicyAclIpv4v6-93935683 not found, creating it 2026-01-22 02:29:16,140 - INFO - Created Project:ctest-TestPolicyAclIpv4v6-93935683, ID : 72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:29:17,866 - INFO - ================================================================================ 2026-01-22 02:29:17,866 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2026-01-22 02:29:19,145 - DEBUG - Nothing to compare xmpp stats {'10.0.0.23': {'10.20.0.19': '0', '10.20.0.5': '0'}, '10.0.0.141': {'10.20.0.19': '0', '10.20.0.5': '0'}} with 2026-01-22 02:29:19,145 - INFO - Initial checks done. Running the testcase now 2026-01-22 02:29:19,145 - INFO - 2026-01-22 02:29:19,145 - INFO - ================================================================================ 2026-01-22 02:29:19,145 - INFO - STARTING TEST : test_policy_inheritance_src_pol_dst_any 2026-01-22 02:29:19,145 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance 2026-01-22 02:29:20,429 - DEBUG - Nothing to compare xmpp stats {'10.0.0.23': {'10.20.0.19': '0', '10.20.0.5': '0'}, '10.0.0.141': {'10.20.0.19': '0', '10.20.0.5': '0'}} with 2026-01-22 02:29:20,429 - INFO - Initial checks done. Running the testcase now 2026-01-22 02:29:20,429 - INFO - 2026-01-22 02:29:21,735 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:29:22,042 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:29:22,078 - DEBUG - Requesting: http://10.0.0.38:8082/domain/43eea14e-faa6-4579-85d2-09c8cc682740 2026-01-22 02:29:22,093 - DEBUG - Requesting: http://10.0.0.38:8082/project/72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:29:22,203 - DEBUG - Requesting: http://10.0.0.38:8082/network-ipam/8fa5bcd5-f9e1-402f-a65a-182a2a4ee1d0 2026-01-22 02:29:22,211 - INFO - Verifications in API Server for IPAM: ipam1 passed 2026-01-22 02:29:22,216 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10350886945787297839', 'uuid-lslong': '11986919927577502160'}, 'enable': 'true', 'created': '2026-01-22T02:29:20', 'last-modified': '2026-01-22T02:29:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.186356'} 2026-01-22 02:29:22,222 - DEBUG - Control-node 10.0.0.50 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10350886945787297839', 'uuid-lslong': '11986919927577502160'}, 'enable': 'true', 'created': '2026-01-22T02:29:20', 'last-modified': '2026-01-22T02:29:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.185085'} 2026-01-22 02:29:22,227 - DEBUG - Control-node 10.0.0.131 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10350886945787297839', 'uuid-lslong': '11986919927577502160'}, 'enable': 'true', 'created': '2026-01-22T02:29:20', 'last-modified': '2026-01-22T02:29:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.196237'} 2026-01-22 02:29:22,227 - INFO - Verifications in Control node for IPAM: ipam1 passed 2026-01-22 02:29:22,227 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:29:22,234 - DEBUG - Requesting: http://10.0.0.38:8082/domain/43eea14e-faa6-4579-85d2-09c8cc682740 2026-01-22 02:29:22,247 - DEBUG - Requesting: http://10.0.0.38:8082/project/72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:29:22,351 - DEBUG - Requesting: http://10.0.0.38:8082/network-ipam/eece11ea-51ba-45ba-bc53-391aa201590d 2026-01-22 02:29:22,359 - INFO - Verifications in API Server for IPAM: ipam2 passed 2026-01-22 02:29:22,364 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17207710924320425402', 'uuid-lslong': '13570252888747366669'}, 'enable': 'true', 'created': '2026-01-22T02:29:21', 'last-modified': '2026-01-22T02:29:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.998821'} 2026-01-22 02:29:22,370 - DEBUG - Control-node 10.0.0.50 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17207710924320425402', 'uuid-lslong': '13570252888747366669'}, 'enable': 'true', 'created': '2026-01-22T02:29:21', 'last-modified': '2026-01-22T02:29:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.997319'} 2026-01-22 02:29:22,375 - DEBUG - Control-node 10.0.0.131 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17207710924320425402', 'uuid-lslong': '13570252888747366669'}, 'enable': 'true', 'created': '2026-01-22T02:29:21', 'last-modified': '2026-01-22T02:29:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.010686'} 2026-01-22 02:29:22,375 - INFO - Verifications in Control node for IPAM: ipam2 passed 2026-01-22 02:29:22,375 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:29:22,381 - DEBUG - Requesting: http://10.0.0.38:8082/domain/43eea14e-faa6-4579-85d2-09c8cc682740 2026-01-22 02:29:22,394 - DEBUG - Requesting: http://10.0.0.38:8082/project/72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:29:22,481 - DEBUG - Requesting: http://10.0.0.38:8082/network-ipam/0e025a88-7ca8-4f52-bd10-f8fb5fc04179 2026-01-22 02:29:22,490 - INFO - Verifications in API Server for IPAM: ipam3 passed 2026-01-22 02:29:22,496 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1009468808737869650', 'uuid-lslong': '13623662631322665337'}, 'enable': 'true', 'created': '2026-01-22T02:29:21', 'last-modified': '2026-01-22T02:29:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.790635'} 2026-01-22 02:29:22,503 - DEBUG - Control-node 10.0.0.50 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1009468808737869650', 'uuid-lslong': '13623662631322665337'}, 'enable': 'true', 'created': '2026-01-22T02:29:21', 'last-modified': '2026-01-22T02:29:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.798629'} 2026-01-22 02:29:22,509 - DEBUG - Control-node 10.0.0.131 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1009468808737869650', 'uuid-lslong': '13623662631322665337'}, 'enable': 'true', 'created': '2026-01-22T02:29:21', 'last-modified': '2026-01-22T02:29:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.803139'} 2026-01-22 02:29:22,509 - INFO - Verifications in Control node for IPAM: ipam3 passed 2026-01-22 02:29:22,846 - DEBUG - Response for create_network : {'network': {'id': 'effbbcd1-6185-458d-9887-f1b3126be13b', 'name': 'ctest-VN1-37730507', 'tenant_id': '72dd62db1b204f2086571f257294a7dc', 'project_id': '72dd62db1b204f2086571f257294a7dc', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ctest-VN1-37730507'], 'port_security_enabled': True, 'description': ''}} 2026-01-22 02:29:23,102 - DEBUG - Response for create_subnet : {'subnet': {'id': '162e0c72-a217-428e-aa18-8d3a80100fa6', 'name': '', 'tenant_id': '72dd62db1b204f2086571f257294a7dc', 'network_id': 'effbbcd1-6185-458d-9887-f1b3126be13b', 'ip_version': 4, 'cidr': '133.243.38.192/26', 'allocation_pools': [{'start': '133.243.38.194', 'end': '133.243.38.254'}], 'gateway_ip': '133.243.38.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '133.243.38.194', 'tags': [], 'project_id': '72dd62db1b204f2086571f257294a7dc'}} 2026-01-22 02:29:23,343 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e1c1f53e-da2d-403c-8670-3147f535ae10', 'name': '', 'tenant_id': '72dd62db1b204f2086571f257294a7dc', 'network_id': 'effbbcd1-6185-458d-9887-f1b3126be13b', 'ip_version': 6, 'cidr': '26dc:b43b:e8c5:c405:886:d987::/96', 'allocation_pools': [{'start': '26dc:b43b:e8c5:c405:886:d987:0:2', 'end': '26dc:b43b:e8c5:c405:886:d987:ffff:fffe'}], 'gateway_ip': '26dc:b43b:e8c5:c405:886:d987:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '26dc:b43b:e8c5:c405:886:d987:0:2', 'tags': [], 'project_id': '72dd62db1b204f2086571f257294a7dc'}} 2026-01-22 02:29:23,369 - INFO - Created VN ctest-VN1-37730507 2026-01-22 02:29:23,379 - DEBUG - VN ctest-VN1-37730507 UUID is effbbcd1-6185-458d-9887-f1b3126be13b 2026-01-22 02:29:23,551 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507(effbbcd1-6185-458d-9887-f1b3126be13b) with subnets ['133.243.38.192/26', '26dc:b43b:e8c5:c405:886:d987::/96'] 2026-01-22 02:29:23,860 - DEBUG - Response for create_network : {'network': {'id': '08dfa1db-d1a7-4962-8510-7fe335322957', 'name': 'ctest-VN2-77208543', 'tenant_id': '72dd62db1b204f2086571f257294a7dc', 'project_id': '72dd62db1b204f2086571f257294a7dc', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ctest-VN2-77208543'], 'port_security_enabled': True, 'description': ''}} 2026-01-22 02:29:24,091 - DEBUG - Response for create_subnet : {'subnet': {'id': '945c1887-78f4-406d-b054-13affccbf5b8', 'name': '', 'tenant_id': '72dd62db1b204f2086571f257294a7dc', 'network_id': '08dfa1db-d1a7-4962-8510-7fe335322957', 'ip_version': 4, 'cidr': '141.56.182.64/26', 'allocation_pools': [{'start': '141.56.182.66', 'end': '141.56.182.126'}], 'gateway_ip': '141.56.182.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '141.56.182.66', 'tags': [], 'project_id': '72dd62db1b204f2086571f257294a7dc'}} 2026-01-22 02:29:24,264 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e6b55314-b98d-49ee-9aeb-6070020037ee', 'name': '', 'tenant_id': '72dd62db1b204f2086571f257294a7dc', 'network_id': '08dfa1db-d1a7-4962-8510-7fe335322957', 'ip_version': 6, 'cidr': '3e79:3564:8839:b1bf:8e8c:c28::/96', 'allocation_pools': [{'start': '3e79:3564:8839:b1bf:8e8c:c28:0:2', 'end': '3e79:3564:8839:b1bf:8e8c:c28:ffff:fffe'}], 'gateway_ip': '3e79:3564:8839:b1bf:8e8c:c28:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3e79:3564:8839:b1bf:8e8c:c28:0:2', 'tags': [], 'project_id': '72dd62db1b204f2086571f257294a7dc'}} 2026-01-22 02:29:24,288 - INFO - Created VN ctest-VN2-77208543 2026-01-22 02:29:24,299 - DEBUG - VN ctest-VN2-77208543 UUID is 08dfa1db-d1a7-4962-8510-7fe335322957 2026-01-22 02:29:24,684 - DEBUG - Response for create_network : {'network': {'id': '2e55d92c-7a4c-45a5-9141-9b9ed9525fcf', 'name': 'ctest-VN3-45142121', 'tenant_id': '72dd62db1b204f2086571f257294a7dc', 'project_id': '72dd62db1b204f2086571f257294a7dc', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ctest-VN3-45142121'], 'port_security_enabled': True, 'description': ''}} 2026-01-22 02:29:24,937 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ca9a9bb4-73f4-419b-a9bb-545c0611a3b4', 'name': '', 'tenant_id': '72dd62db1b204f2086571f257294a7dc', 'network_id': '2e55d92c-7a4c-45a5-9141-9b9ed9525fcf', 'ip_version': 4, 'cidr': '60.237.74.64/26', 'allocation_pools': [{'start': '60.237.74.66', 'end': '60.237.74.126'}], 'gateway_ip': '60.237.74.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '60.237.74.66', 'tags': [], 'project_id': '72dd62db1b204f2086571f257294a7dc'}} 2026-01-22 02:29:25,175 - DEBUG - Response for create_subnet : {'subnet': {'id': '01c50e77-bf43-415a-9609-e0c5fda6eedf', 'name': '', 'tenant_id': '72dd62db1b204f2086571f257294a7dc', 'network_id': '2e55d92c-7a4c-45a5-9141-9b9ed9525fcf', 'ip_version': 6, 'cidr': '2c27:d355:82b5:390f:9bc7:9ece::/96', 'allocation_pools': [{'start': '2c27:d355:82b5:390f:9bc7:9ece:0:2', 'end': '2c27:d355:82b5:390f:9bc7:9ece:ffff:fffe'}], 'gateway_ip': '2c27:d355:82b5:390f:9bc7:9ece:0:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2c27:d355:82b5:390f:9bc7:9ece:0:2', 'tags': [], 'project_id': '72dd62db1b204f2086571f257294a7dc'}} 2026-01-22 02:29:25,197 - INFO - Created VN ctest-VN3-45142121 2026-01-22 02:29:25,208 - DEBUG - VN ctest-VN3-45142121 UUID is 2e55d92c-7a4c-45a5-9141-9b9ed9525fcf 2026-01-22 02:29:25,301 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:29:25,307 - DEBUG - Requesting: http://10.0.0.38:8082/domain/43eea14e-faa6-4579-85d2-09c8cc682740 2026-01-22 02:29:25,317 - DEBUG - Requesting: http://10.0.0.38:8082/project/72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:29:25,403 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/effbbcd1-6185-458d-9887-f1b3126be13b 2026-01-22 02:29:25,415 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/effbbcd1-6185-458d-9887-f1b3126be13b 2026-01-22 02:29:25,424 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/71507b6d-6205-4655-8c4b-4a8e148a90e9 2026-01-22 02:29:25,431 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/71507b6d-6205-4655-8c4b-4a8e148a90e9 2026-01-22 02:29:25,437 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/f8303a19-4763-4076-a599-fca0df231e12 2026-01-22 02:29:25,443 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-01-22 02:29:25,443 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/effbbcd1-6185-458d-9887-f1b3126be13b 2026-01-22 02:29:25,451 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/71507b6d-6205-4655-8c4b-4a8e148a90e9 2026-01-22 02:29:25,466 - INFO - Verified VN network id 12 for VN effbbcd1-6185-458d-9887-f1b3126be13b 2026-01-22 02:29:25,466 - INFO - Verifications in API Server for VN ctest-VN1-37730507 passed 2026-01-22 02:29:25,466 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/effbbcd1-6185-458d-9887-f1b3126be13b 2026-01-22 02:29:25,475 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/71507b6d-6205-4655-8c4b-4a8e148a90e9 2026-01-22 02:29:25,482 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/71507b6d-6205-4655-8c4b-4a8e148a90e9 2026-01-22 02:29:25,489 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/f8303a19-4763-4076-a599-fca0df231e12 2026-01-22 02:29:25,501 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17292622801689462157', 'uuid-lslong': '10991019167031484731'}, 'enable': 'true', 'created': '2026-01-22T02:29:22', 'last-modified': '2026-01-22T02:29:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.225430'} 2026-01-22 02:29:25,506 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-01-22 02:29:25,513 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17292622801689462157', 'uuid-lslong': '10991019167031484731'}, 'enable': 'true', 'created': '2026-01-22T02:29:22', 'last-modified': '2026-01-22T02:29:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.234941'} 2026-01-22 02:29:25,520 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-01-22 02:29:25,526 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17292622801689462157', 'uuid-lslong': '10991019167031484731'}, 'enable': 'true', 'created': '2026-01-22T02:29:22', 'last-modified': '2026-01-22T02:29:23', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.250624'} 2026-01-22 02:29:25,533 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-01-22 02:29:25,533 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-37730507 passed 2026-01-22 02:29:25,533 - DEBUG - ====Verifying policy data for ctest-VN1-37730507 in API_Server ====== 2026-01-22 02:29:25,533 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:29:25,539 - DEBUG - Requesting: http://10.0.0.38:8082/domain/43eea14e-faa6-4579-85d2-09c8cc682740 2026-01-22 02:29:25,550 - DEBUG - Requesting: http://10.0.0.38:8082/project/72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:29:25,654 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/effbbcd1-6185-458d-9887-f1b3126be13b 2026-01-22 02:29:25,668 - DEBUG - =>VN ctest-VN1-37730507 has no policy to be verified 2026-01-22 02:29:25,668 - DEBUG - Verifying the vn in opserver 2026-01-22 02:29:25,668 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 virtual network link through opserver 10.0.0.38 2026-01-22 02:29:25,668 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-01-22 02:29:25,970 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-01-22 02:29:26,036 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507?flat'} 2026-01-22 02:29:26,036 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 virtual network link through opserver 10.0.0.50 2026-01-22 02:29:26,037 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2026-01-22 02:29:26,346 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2026-01-22 02:29:26,418 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507?flat'} 2026-01-22 02:29:26,418 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 virtual network link through opserver 10.0.0.131 2026-01-22 02:29:26,419 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks 2026-01-22 02:29:26,732 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks 2026-01-22 02:29:26,794 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507?flat'} 2026-01-22 02:29:26,794 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is found in opserver 2026-01-22 02:29:26,817 - DEBUG - Do not have enough data to verify VN in agent 2026-01-22 02:29:26,827 - DEBUG - VRF ids for VN ctest-VN1-37730507: {} 2026-01-22 02:29:26,827 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:29:26,833 - DEBUG - Requesting: http://10.0.0.38:8082/domain/43eea14e-faa6-4579-85d2-09c8cc682740 2026-01-22 02:29:26,845 - DEBUG - Requesting: http://10.0.0.38:8082/project/72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:29:26,943 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/08dfa1db-d1a7-4962-8510-7fe335322957 2026-01-22 02:29:26,956 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/08dfa1db-d1a7-4962-8510-7fe335322957 2026-01-22 02:29:26,966 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/3d174095-5a17-41d5-bfbc-5dec19417f4d 2026-01-22 02:29:26,975 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/3d174095-5a17-41d5-bfbc-5dec19417f4d 2026-01-22 02:29:26,983 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/5b19fe7e-1ce5-4fc3-9b8e-afdadf699e5e 2026-01-22 02:29:26,990 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-01-22 02:29:26,990 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/08dfa1db-d1a7-4962-8510-7fe335322957 2026-01-22 02:29:26,998 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/3d174095-5a17-41d5-bfbc-5dec19417f4d 2026-01-22 02:29:27,012 - INFO - Verified VN network id 13 for VN 08dfa1db-d1a7-4962-8510-7fe335322957 2026-01-22 02:29:27,012 - INFO - Verifications in API Server for VN ctest-VN2-77208543 passed 2026-01-22 02:29:27,012 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/08dfa1db-d1a7-4962-8510-7fe335322957 2026-01-22 02:29:27,022 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/3d174095-5a17-41d5-bfbc-5dec19417f4d 2026-01-22 02:29:27,029 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/3d174095-5a17-41d5-bfbc-5dec19417f4d 2026-01-22 02:29:27,035 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/5b19fe7e-1ce5-4fc3-9b8e-afdadf699e5e 2026-01-22 02:29:27,045 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '639407637597210978', 'uuid-lslong': '9588304220498569559'}, 'enable': 'true', 'created': '2026-01-22T02:29:23', 'last-modified': '2026-01-22T02:29:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.821811'} 2026-01-22 02:29:27,053 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-01-22 02:29:27,058 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '639407637597210978', 'uuid-lslong': '9588304220498569559'}, 'enable': 'true', 'created': '2026-01-22T02:29:23', 'last-modified': '2026-01-22T02:29:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.830936'} 2026-01-22 02:29:27,071 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-01-22 02:29:27,076 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '639407637597210978', 'uuid-lslong': '9588304220498569559'}, 'enable': 'true', 'created': '2026-01-22T02:29:23', 'last-modified': '2026-01-22T02:29:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.849658'} 2026-01-22 02:29:27,083 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-01-22 02:29:27,083 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-77208543 passed 2026-01-22 02:29:27,084 - DEBUG - ====Verifying policy data for ctest-VN2-77208543 in API_Server ====== 2026-01-22 02:29:27,084 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:29:27,089 - DEBUG - Requesting: http://10.0.0.38:8082/domain/43eea14e-faa6-4579-85d2-09c8cc682740 2026-01-22 02:29:27,100 - DEBUG - Requesting: http://10.0.0.38:8082/project/72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:29:27,192 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/08dfa1db-d1a7-4962-8510-7fe335322957 2026-01-22 02:29:27,203 - DEBUG - =>VN ctest-VN2-77208543 has no policy to be verified 2026-01-22 02:29:27,203 - DEBUG - Verifying the vn in opserver 2026-01-22 02:29:27,203 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543 virtual network link through opserver 10.0.0.38 2026-01-22 02:29:27,203 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-01-22 02:29:27,265 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543?flat'} 2026-01-22 02:29:27,265 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543 virtual network link through opserver 10.0.0.50 2026-01-22 02:29:27,266 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2026-01-22 02:29:27,329 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543?flat'} 2026-01-22 02:29:27,329 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543 virtual network link through opserver 10.0.0.131 2026-01-22 02:29:27,329 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks 2026-01-22 02:29:27,393 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543?flat'} 2026-01-22 02:29:27,393 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543 is found in opserver 2026-01-22 02:29:27,417 - DEBUG - Do not have enough data to verify VN in agent 2026-01-22 02:29:27,428 - DEBUG - VRF ids for VN ctest-VN2-77208543: {} 2026-01-22 02:29:27,428 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:29:27,433 - DEBUG - Requesting: http://10.0.0.38:8082/domain/43eea14e-faa6-4579-85d2-09c8cc682740 2026-01-22 02:29:27,445 - DEBUG - Requesting: http://10.0.0.38:8082/project/72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:29:27,535 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/2e55d92c-7a4c-45a5-9141-9b9ed9525fcf 2026-01-22 02:29:27,548 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/2e55d92c-7a4c-45a5-9141-9b9ed9525fcf 2026-01-22 02:29:27,559 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/0fcdcf27-4bf0-46d0-9ae9-c808ae8e2b07 2026-01-22 02:29:27,568 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/0fcdcf27-4bf0-46d0-9ae9-c808ae8e2b07 2026-01-22 02:29:27,574 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/ce08f1c4-59a5-4eff-ab66-9826dd73824a 2026-01-22 02:29:27,583 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-01-22 02:29:27,583 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/2e55d92c-7a4c-45a5-9141-9b9ed9525fcf 2026-01-22 02:29:27,593 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/0fcdcf27-4bf0-46d0-9ae9-c808ae8e2b07 2026-01-22 02:29:27,610 - INFO - Verified VN network id 14 for VN 2e55d92c-7a4c-45a5-9141-9b9ed9525fcf 2026-01-22 02:29:27,610 - INFO - Verifications in API Server for VN ctest-VN3-45142121 passed 2026-01-22 02:29:27,610 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/2e55d92c-7a4c-45a5-9141-9b9ed9525fcf 2026-01-22 02:29:27,623 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/0fcdcf27-4bf0-46d0-9ae9-c808ae8e2b07 2026-01-22 02:29:27,631 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/0fcdcf27-4bf0-46d0-9ae9-c808ae8e2b07 2026-01-22 02:29:27,639 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/ce08f1c4-59a5-4eff-ab66-9826dd73824a 2026-01-22 02:29:27,651 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3338813483818698149', 'uuid-lslong': '10466818115538935759'}, 'enable': 'true', 'created': '2026-01-22T02:29:24', 'last-modified': '2026-01-22T02:29:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.530633'} 2026-01-22 02:29:27,657 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-01-22 02:29:27,663 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3338813483818698149', 'uuid-lslong': '10466818115538935759'}, 'enable': 'true', 'created': '2026-01-22T02:29:24', 'last-modified': '2026-01-22T02:29:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.537894'} 2026-01-22 02:29:27,670 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-01-22 02:29:27,675 - DEBUG - Control-node 10.0.0.131 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAclIpv4v6-93935683', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3338813483818698149', 'uuid-lslong': '10466818115538935759'}, 'enable': 'true', 'created': '2026-01-22T02:29:24', 'last-modified': '2026-01-22T02:29:25', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.555360'} 2026-01-22 02:29:27,681 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-01-22 02:29:27,681 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-45142121 passed 2026-01-22 02:29:27,681 - DEBUG - ====Verifying policy data for ctest-VN3-45142121 in API_Server ====== 2026-01-22 02:29:27,681 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:29:27,687 - DEBUG - Requesting: http://10.0.0.38:8082/domain/43eea14e-faa6-4579-85d2-09c8cc682740 2026-01-22 02:29:27,697 - DEBUG - Requesting: http://10.0.0.38:8082/project/72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:29:27,793 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/2e55d92c-7a4c-45a5-9141-9b9ed9525fcf 2026-01-22 02:29:27,802 - DEBUG - =>VN ctest-VN3-45142121 has no policy to be verified 2026-01-22 02:29:27,802 - DEBUG - Verifying the vn in opserver 2026-01-22 02:29:27,802 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121 virtual network link through opserver 10.0.0.38 2026-01-22 02:29:27,802 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-01-22 02:29:27,871 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121?flat'} 2026-01-22 02:29:27,871 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121 virtual network link through opserver 10.0.0.50 2026-01-22 02:29:27,871 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks 2026-01-22 02:29:27,934 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121?flat'} 2026-01-22 02:29:27,934 - DEBUG - Verifying the default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121 virtual network link through opserver 10.0.0.131 2026-01-22 02:29:27,934 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-networks 2026-01-22 02:29:28,010 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121?flat'} 2026-01-22 02:29:28,010 - INFO - Validated that VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121 is found in opserver 2026-01-22 02:29:28,031 - DEBUG - Do not have enough data to verify VN in agent 2026-01-22 02:29:28,043 - DEBUG - VRF ids for VN ctest-VN3-45142121: {} 2026-01-22 02:29:28,051 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507, 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-93935683:ctest-VN3-45142121, 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-01-22 02:29:28,311 - DEBUG - No source network defined 2026-01-22 02:29:28,311 - DEBUG - No source network defined 2026-01-22 02:29:28,311 - 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-93935683:ctest-policy13-44091123, 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-93935683:ctest-policy13-44091123, 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-01-22 02:29:28,579 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543, 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-93935683:ctest-VN1-37730507, 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-93935683:ctest-VN2-77208543, 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-93935683:ctest-VN1-37730507, 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-01-22 02:29:28,799 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:29:28,805 - DEBUG - Requesting: http://10.0.0.38:8082/domain/43eea14e-faa6-4579-85d2-09c8cc682740 2026-01-22 02:29:28,816 - DEBUG - Requesting: http://10.0.0.38:8082/project/72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:29:28,919 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/effbbcd1-6185-458d-9887-f1b3126be13b 2026-01-22 02:29:28,928 - DEBUG - Setup step: Associating the policy to VN' 2026-01-22 02:29:29,037 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'effbbcd1-6185-458d-9887-f1b3126be13b', 'name': 'ctest-VN1-37730507', 'tenant_id': '72dd62db1b204f2086571f257294a7dc', 'project_id': '72dd62db1b204f2086571f257294a7dc', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['162e0c72-a217-428e-aa18-8d3a80100fa6', 'e1c1f53e-da2d-403c-8670-3147f535ae10'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ctest-VN1-37730507'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ctest-policy12-30354641'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ctest-policy13-44091123']], 'subnet_ipam': [{'subnet_cidr': '133.243.38.192/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ipam1']}, {'subnet_cidr': '26dc:b43b:e8c5:c405:886:d987::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ipam1']}], 'port_security_enabled': True, 'description': ''}} 2026-01-22 02:29:29,209 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ctest-policy12-30354641'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ctest-policy13-44091123']] to ctest-VN1-37730507 2026-01-22 02:29:29,210 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:29:29,215 - DEBUG - Requesting: http://10.0.0.38:8082/domain/43eea14e-faa6-4579-85d2-09c8cc682740 2026-01-22 02:29:29,226 - DEBUG - Requesting: http://10.0.0.38:8082/project/72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:29:29,318 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/08dfa1db-d1a7-4962-8510-7fe335322957 2026-01-22 02:29:29,335 - DEBUG - Setup step: Associating the policy to VN' 2026-01-22 02:29:29,446 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '08dfa1db-d1a7-4962-8510-7fe335322957', 'name': 'ctest-VN2-77208543', 'tenant_id': '72dd62db1b204f2086571f257294a7dc', 'project_id': '72dd62db1b204f2086571f257294a7dc', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['945c1887-78f4-406d-b054-13affccbf5b8', 'e6b55314-b98d-49ee-9aeb-6070020037ee'], 'fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ctest-VN2-77208543'], 'policys': [['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ctest-policy21-42116024']], 'subnet_ipam': [{'subnet_cidr': '141.56.182.64/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ipam2']}, {'subnet_cidr': '3e79:3564:8839:b1bf:8e8c:c28::/96', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ipam2']}], 'port_security_enabled': True, 'description': ''}} 2026-01-22 02:29:29,537 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ctest-policy21-42116024']] to ctest-VN2-77208543 2026-01-22 02:29:29,648 - DEBUG - Services list from nova: [, , , ] 2026-01-22 02:29:30,842 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5055-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5055-1) 2026-01-22 02:29:32,193 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5055-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5055-2) 2026-01-22 02:29:33,669 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5055-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5055-1) 2026-01-22 02:29:33,770 - DEBUG - VM is in ACTIVE state now 2026-01-22 02:29:33,770 - INFO - VM name : ctest-VM11-40119303 2026-01-22 02:29:33,880 - DEBUG - VM ctest-VM11-40119303 ID is 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad 2026-01-22 02:29:33,975 - DEBUG - VM is in ACTIVE state now 2026-01-22 02:29:34,004 - DEBUG - VM ctest-VM11-40119303 launched on Node an-jenkins-deploy-platform-ansible-os-5055-1 2026-01-22 02:29:34,120 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/4919c2ec-3a13-4db1-a3a5-8d287f2a41ad 2026-01-22 02:29:34,563 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/4919c2ec-3a13-4db1-a3a5-8d287f2a41ad 2026-01-22 02:29:34,620 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/4919c2ec-3a13-4db1-a3a5-8d287f2a41ad 2026-01-22 02:29:34,628 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/a7b21cd4-3fc9-4098-9ae9-9dedda71daa9 2026-01-22 02:29:34,638 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/4919c2ec-3a13-4db1-a3a5-8d287f2a41ad 2026-01-22 02:29:34,646 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/a7b21cd4-3fc9-4098-9ae9-9dedda71daa9 2026-01-22 02:29:34,657 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/81a6caaf-668f-4481-aaff-e37909ceb091 2026-01-22 02:29:34,669 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/b3215880-a0d7-49b6-bc4c-418937c01762 2026-01-22 02:29:34,679 - DEBUG - Verifying in api server 10.0.0.131 2026-01-22 02:29:34,679 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/4919c2ec-3a13-4db1-a3a5-8d287f2a41ad 2026-01-22 02:29:34,688 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/a7b21cd4-3fc9-4098-9ae9-9dedda71daa9 2026-01-22 02:29:34,701 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/81a6caaf-668f-4481-aaff-e37909ceb091 2026-01-22 02:29:34,710 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/b3215880-a0d7-49b6-bc4c-418937c01762 2026-01-22 02:29:34,719 - INFO - VM ctest-VM11-40119303 verfication in all API Servers passed 2026-01-22 02:29:34,757 - DEBUG - VM ctest-VM11-40119303 Tap interface: {'index': '3', 'name': 'tapa7b21cd4-3f', 'uuid': 'a7b21cd4-3fc9-4098-9ae9-9dedda71daa9', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507:ctest-VN1-37730507', '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': '12', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507', 'vm_uuid': '4919c2ec-3a13-4db1-a3a5-8d287f2a41ad', 'vm_name': 'ctest-VM11-40119303', 'ip_addr': '133.243.38.195', 'mac_addr': '02:a7:b2:1c:d4:3f', '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-93935683:a7b21cd4-3fc9-4098-9ae9-9dedda71daa9', 'sg_uuid_list': ['cbc5f751-c423-4c9a-a571-6dc223d6a08b'], 'static_route_list': None, 'vm_project_uuid': '72dd62db-1b20-4f20-8657-1f257294a7dc', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '26dc:b43b:e8c5:c405:886:d987: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-01-22 02:29:34,757 - DEBUG - Agent 10.0.0.23 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507:ctest-VN1-37730507 2026-01-22 02:29:34,774 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507:ctest-VN1-37730507', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507', 'table_label': '-1', 'vxlan_id': '12', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.131:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-01-22 02:29:34,903 - DEBUG - VM ctest-VM11-40119303 : Tap interface tapa7b21cd4-3f is set to Active 2026-01-22 02:29:34,903 - DEBUG - Tap interface tapa7b21cd4-3f detail : {'index': '3', 'name': 'tapa7b21cd4-3f', 'uuid': 'a7b21cd4-3fc9-4098-9ae9-9dedda71daa9', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507:ctest-VN1-37730507', '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': '12', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507', 'vm_uuid': '4919c2ec-3a13-4db1-a3a5-8d287f2a41ad', 'vm_name': 'ctest-VM11-40119303', 'ip_addr': '133.243.38.195', 'mac_addr': '02:a7:b2:1c:d4:3f', '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-93935683:a7b21cd4-3fc9-4098-9ae9-9dedda71daa9', 'sg_uuid_list': ['cbc5f751-c423-4c9a-a571-6dc223d6a08b'], 'static_route_list': None, 'vm_project_uuid': '72dd62db-1b20-4f20-8657-1f257294a7dc', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '26dc:b43b:e8c5:c405:886:d987: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-01-22 02:29:34,971 - DEBUG - Starting Layer 2 verification in Agent 2026-01-22 02:29:35,017 - DEBUG - Layer 2 path is seen for VM MAC 02:a7:b2:1c:d4:3f in agent 10.0.0.23 2026-01-22 02:29:35,017 - DEBUG - Active layer 2 route in agent is present for VMI tapa7b21cd4-3f 2026-01-22 02:29:35,017 - DEBUG - L2 label(29) matches bw route table and interface table 2026-01-22 02:29:35,032 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is found in Agent of node 10.0.0.23 2026-01-22 02:29:35,174 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is consistent in agent 10.0.0.23 2026-01-22 02:29:35,174 - DEBUG - Route for VM IP ['26dc:b43b:e8c5:c405:886:d987:0:3', '133.243.38.195'] is consistent in agent 10.0.0.23 2026-01-22 02:29:35,174 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 verification for VM ctest-VM11-40119303 in Agent 10.0.0.23 passed 2026-01-22 02:29:35,254 - DEBUG - Starting all layer 2 verification in agent 10.0.0.23 2026-01-22 02:29:35,276 - DEBUG - Route for VM MAC 02:a7:b2:1c:d4:3f is consistent in agent 10.0.0.23 2026-01-22 02:29:38,482 - 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 1027ms') 2026-01-22 02:29:38,482 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-40119303 failed! 2026-01-22 02:29:38,496 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 133.243.38.193 and allocation pool is NOT set 2026-01-22 02:29:38,496 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 26dc:b43b:e8c5:c405:886:d987:0:1 and allocation pool is NOT set 2026-01-22 02:29:42,585 - 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-01-22 02:29:42,585 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-40119303 failed! 2026-01-22 02:29:42,604 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 133.243.38.193 and allocation pool is NOT set 2026-01-22 02:29:42,604 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 26dc:b43b:e8c5:c405:886:d987:0:1 and allocation pool is NOT set 2026-01-22 02:29:46,710 - 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 1032ms') 2026-01-22 02:29:46,710 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-40119303 failed! 2026-01-22 02:29:46,725 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 133.243.38.193 and allocation pool is NOT set 2026-01-22 02:29:46,725 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 26dc:b43b:e8c5:c405:886:d987:0:1 and allocation pool is NOT set 2026-01-22 02:29:50,798 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2026-01-22 02:29:50,799 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-40119303 failed! 2026-01-22 02:29:50,814 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 133.243.38.193 and allocation pool is NOT set 2026-01-22 02:29:50,814 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 26dc:b43b:e8c5:c405:886:d987:0:1 and allocation pool is NOT set 2026-01-22 02:29:54,893 - 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-01-22 02:29:54,893 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-40119303 failed! 2026-01-22 02:29:54,907 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 133.243.38.193 and allocation pool is NOT set 2026-01-22 02:29:54,907 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 26dc:b43b:e8c5:c405:886:d987:0:1 and allocation pool is NOT set 2026-01-22 02:29:58,990 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2026-01-22 02:29:58,990 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-40119303 failed! 2026-01-22 02:29:59,048 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 133.243.38.193 and allocation pool is NOT set 2026-01-22 02:29:59,049 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 26dc:b43b:e8c5:c405:886:d987:0:1 and allocation pool is NOT set 2026-01-22 02:30:03,128 - 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-01-22 02:30:03,128 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-40119303 failed! 2026-01-22 02:30:03,146 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 133.243.38.193 and allocation pool is NOT set 2026-01-22 02:30:03,146 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 26dc:b43b:e8c5:c405:886:d987:0:1 and allocation pool is NOT set 2026-01-22 02:30:07,220 - 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-01-22 02:30:07,220 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-40119303 failed! 2026-01-22 02:30:07,233 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 133.243.38.193 and allocation pool is NOT set 2026-01-22 02:30:07,233 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 26dc:b43b:e8c5:c405:886:d987:0:1 and allocation pool is NOT set 2026-01-22 02:30:11,316 - 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-01-22 02:30:11,316 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-40119303 failed! 2026-01-22 02:30:11,333 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 133.243.38.193 and allocation pool is NOT set 2026-01-22 02:30:11,333 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 26dc:b43b:e8c5:c405:886:d987:0:1 and allocation pool is NOT set 2026-01-22 02:30:15,405 - 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-01-22 02:30:15,405 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-40119303 failed! 2026-01-22 02:30:15,421 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 133.243.38.193 and allocation pool is NOT set 2026-01-22 02:30:15,421 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 26dc:b43b:e8c5:c405:886:d987:0:1 and allocation pool is NOT set 2026-01-22 02:30:19,501 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1020ms') 2026-01-22 02:30:19,501 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-40119303 failed! 2026-01-22 02:30:19,514 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 133.243.38.193 and allocation pool is NOT set 2026-01-22 02:30:19,514 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 26dc:b43b:e8c5:c405:886:d987:0:1 and allocation pool is NOT set 2026-01-22 02:30:23,607 - 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 1025ms') 2026-01-22 02:30:23,607 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-40119303 failed! 2026-01-22 02:30:23,664 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 133.243.38.193 and allocation pool is NOT set 2026-01-22 02:30:23,665 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 is 26dc:b43b:e8c5:c405:886:d987:0:1 and allocation pool is NOT set 2026-01-22 02:30:25,737 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=12.9 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.57 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 3.567/8.246/12.926/4.679 ms') 2026-01-22 02:30:25,737 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-40119303 passed 2026-01-22 02:30:25,737 - INFO - VM ctest-VM11-40119303 verifications in Compute nodes passed 2026-01-22 02:30:26,016 - DEBUG - Validated VM route 133.243.38.195 in vrouter of 10.0.0.23 2026-01-22 02:30:26,023 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-01-22 02:30:26,023 - INFO - Validated routes of VM ctest-VM11-40119303 in all vrouters 2026-01-22 02:30:26,455 - DEBUG - Starting all layer2 verification in 10.0.0.38 Control Node 2026-01-22 02:30:26,568 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.38, not supported 2026-01-22 02:30:26,696 - DEBUG - Layer2 route found for VM MAC 02:a7:b2:1c:d4:3f in Control-node 10.0.0.38 2026-01-22 02:30:26,697 - DEBUG - L2 Label for VM ctest-VM11-40119303 same between Control-node 10.0.0.38 and Agent, Expected: 29, Seen: 29 2026-01-22 02:30:26,697 - DEBUG - Starting all layer2 verification in 10.0.0.50 Control Node 2026-01-22 02:30:26,811 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.50, not supported 2026-01-22 02:30:26,936 - DEBUG - Layer2 route found for VM MAC 02:a7:b2:1c:d4:3f in Control-node 10.0.0.50 2026-01-22 02:30:26,937 - DEBUG - L2 Label for VM ctest-VM11-40119303 same between Control-node 10.0.0.50 and Agent, Expected: 29, Seen: 29 2026-01-22 02:30:26,937 - INFO - Verification in Control-nodes for VM ctest-VM11-40119303 passed 2026-01-22 02:30:26,937 - DEBUG - Verifying the vm in opserver 2026-01-22 02:30:26,937 - DEBUG - Verifying in collector 10.0.0.131 ... 2026-01-22 02:30:26,937 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/4919c2ec-3a13-4db1-a3a5-8d287f2a41ad?flat 2026-01-22 02:30:26,955 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-93935683:a7b21cd4-3fc9-4098-9ae9-9dedda71daa9?flat 2026-01-22 02:30:26,975 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-93935683:a7b21cd4-3fc9-4098-9ae9-9dedda71daa9?flat 2026-01-22 02:30:26,994 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-93935683:a7b21cd4-3fc9-4098-9ae9-9dedda71daa9?flat 2026-01-22 02:30:27,014 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/4919c2ec-3a13-4db1-a3a5-8d287f2a41ad?flat 2026-01-22 02:30:27,032 - DEBUG - Verifying vm in vn uve 2026-01-22 02:30:27,032 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-93935683:a7b21cd4-3fc9-4098-9ae9-9dedda71daa9?flat 2026-01-22 02:30:27,051 - DEBUG - VM uve shows interface as {'mac_address': '02:a7:b2:1c:d4:3f', 'fixed_ip4_list': ['133.243.38.195'], 'ip6_address': '26dc:b43b:e8c5:c405:886:d987:0:3', 'admin_state': True, '__T': 1769048979222314, 'ip_address': '133.243.38.195', 'vm_uuid': '4919c2ec-3a13-4db1-a3a5-8d287f2a41ad', 'ip4_active': True, 'gateway': '133.243.38.193', 'label': 25, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507', 'tx_vlan': 65535, 'ip6_active': True, 'active': True, 'vhostuser_mode': 0, 'rx_vlan': 65535, 'vm_name': 'ctest-VM11-40119303', 'is_health_check_active': True, 'vn_uuid': 'effbbcd1-6185-458d-9887-f1b3126be13b', 'uuid': 'a7b21cd4-3fc9-4098-9ae9-9dedda71daa9', 'l2_active': True, 'fixed_ip6_list': ['26dc:b43b:e8c5:c405:886:d987:0:3'], 'port_mirror_enabled': False} 2026-01-22 02:30:27,051 - DEBUG - VM uve shows ip address as ['133.243.38.195', '26dc:b43b:e8c5:c405:886:d987:0:3'] 2026-01-22 02:30:27,051 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 2026-01-22 02:30:27,051 - INFO - Verifying through opserver in 10.0.0.38 2026-01-22 02:30:27,051 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507?flat 2026-01-22 02:30:27,076 - DEBUG - expected vm list 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad 2026-01-22 02:30:27,076 - DEBUG - Extracted vm list ['4919c2ec-3a13-4db1-a3a5-8d287f2a41ad'] 2026-01-22 02:30:27,076 - INFO - VM 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is present in default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 2026-01-22 02:30:27,076 - DEBUG - Verifying vm in vrouter uve 2026-01-22 02:30:27,076 - DEBUG - Getting info from collector 10.0.0.131.. 2026-01-22 02:30:27,076 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/4919c2ec-3a13-4db1-a3a5-8d287f2a41ad?flat 2026-01-22 02:30:27,092 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5055-1'] 2026-01-22 02:30:27,092 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:30:27,128 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:30:27,164 - DEBUG - VM 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:30:27,165 - DEBUG - Validated that VM ctest-VM11-40119303 is in Vrouter an-jenkins-deploy-platform-ansible-os-5055-1 UVE 2026-01-22 02:30:27,165 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-01-22 02:30:27,178 - DEBUG - Expected tap interface of VM uuid 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is default-domain:ctest-TestPolicyAclIpv4v6-93935683:a7b21cd4-3fc9-4098-9ae9-9dedda71daa9 2026-01-22 02:30:27,178 - DEBUG - Expected VN of VM uuid 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 2026-01-22 02:30:27,178 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:30:27,215 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:30:27,244 - DEBUG - VM 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:30:27,244 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-93935683:a7b21cd4-3fc9-4098-9ae9-9dedda71daa9 of vm 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:30:27,244 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 of vm 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:30:27,244 - DEBUG - Verifying the 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad virtual network link through opserver 10.0.0.38 2026-01-22 02:30:27,244 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-01-22 02:30:27,258 - DEBUG - VM link and name as {'name': '4919c2ec-3a13-4db1-a3a5-8d287f2a41ad', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/4919c2ec-3a13-4db1-a3a5-8d287f2a41ad?flat'} 2026-01-22 02:30:27,258 - DEBUG - Verifying the 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad virtual network link through opserver 10.0.0.50 2026-01-22 02:30:27,258 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines 2026-01-22 02:30:27,271 - DEBUG - VM link and name as {'name': '4919c2ec-3a13-4db1-a3a5-8d287f2a41ad', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-machine/4919c2ec-3a13-4db1-a3a5-8d287f2a41ad?flat'} 2026-01-22 02:30:27,271 - DEBUG - Verifying the 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad virtual network link through opserver 10.0.0.131 2026-01-22 02:30:27,271 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2026-01-22 02:30:27,283 - DEBUG - VM link and name as {'name': '4919c2ec-3a13-4db1-a3a5-8d287f2a41ad', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-machine/4919c2ec-3a13-4db1-a3a5-8d287f2a41ad?flat'} 2026-01-22 02:30:27,283 - INFO - VM ctest-VM11-40119303 validations in Opserver passed 2026-01-22 02:30:27,375 - DEBUG - VM is in ACTIVE state now 2026-01-22 02:30:27,376 - INFO - VM name : ctest-VM21-57252136 2026-01-22 02:30:27,459 - DEBUG - VM ctest-VM21-57252136 ID is f1f43417-ebcf-4ee5-a175-2ad8cde0742a 2026-01-22 02:30:27,554 - DEBUG - VM is in ACTIVE state now 2026-01-22 02:30:27,554 - DEBUG - VM ctest-VM21-57252136 launched on Node an-jenkins-deploy-platform-ansible-os-5055-2 2026-01-22 02:30:27,644 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/f1f43417-ebcf-4ee5-a175-2ad8cde0742a 2026-01-22 02:30:27,658 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/f1f43417-ebcf-4ee5-a175-2ad8cde0742a 2026-01-22 02:30:27,665 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/19a374a5-4dea-4e60-9f22-d269bf08e00a 2026-01-22 02:30:27,675 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/f1f43417-ebcf-4ee5-a175-2ad8cde0742a 2026-01-22 02:30:27,682 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/19a374a5-4dea-4e60-9f22-d269bf08e00a 2026-01-22 02:30:27,691 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/803fd9c2-e1bf-49b2-912e-bfa57673eef5 2026-01-22 02:30:27,708 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/d4edd23b-a28a-4f5d-8394-d6ccf573d6c3 2026-01-22 02:30:27,717 - DEBUG - Verifying in api server 10.0.0.131 2026-01-22 02:30:27,718 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/f1f43417-ebcf-4ee5-a175-2ad8cde0742a 2026-01-22 02:30:27,724 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/19a374a5-4dea-4e60-9f22-d269bf08e00a 2026-01-22 02:30:27,734 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/803fd9c2-e1bf-49b2-912e-bfa57673eef5 2026-01-22 02:30:27,743 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/d4edd23b-a28a-4f5d-8394-d6ccf573d6c3 2026-01-22 02:30:27,755 - INFO - VM ctest-VM21-57252136 verfication in all API Servers passed 2026-01-22 02:30:27,785 - DEBUG - VM ctest-VM21-57252136 Tap interface: {'index': '3', 'name': 'tap19a374a5-4d', 'uuid': '19a374a5-4dea-4e60-9f22-d269bf08e00a', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543:ctest-VN2-77208543', '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': '13', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543', 'vm_uuid': 'f1f43417-ebcf-4ee5-a175-2ad8cde0742a', 'vm_name': 'ctest-VM21-57252136', 'ip_addr': '141.56.182.67', 'mac_addr': '02:19:a3:74:a5:4d', '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-93935683:19a374a5-4dea-4e60-9f22-d269bf08e00a', 'sg_uuid_list': ['cbc5f751-c423-4c9a-a571-6dc223d6a08b'], 'static_route_list': None, 'vm_project_uuid': '72dd62db-1b20-4f20-8657-1f257294a7dc', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '3e79:3564:8839:b1bf:8e8c:c28: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-01-22 02:30:27,785 - DEBUG - Agent 10.0.0.141 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543:ctest-VN2-77208543 2026-01-22 02:30:27,796 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543:ctest-VN2-77208543', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543', 'table_label': '-1', 'vxlan_id': '13', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.25:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-01-22 02:30:27,907 - DEBUG - VM ctest-VM21-57252136 : Tap interface tap19a374a5-4d is set to Active 2026-01-22 02:30:27,907 - DEBUG - Tap interface tap19a374a5-4d detail : {'index': '3', 'name': 'tap19a374a5-4d', 'uuid': '19a374a5-4dea-4e60-9f22-d269bf08e00a', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543:ctest-VN2-77208543', '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': '13', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543', 'vm_uuid': 'f1f43417-ebcf-4ee5-a175-2ad8cde0742a', 'vm_name': 'ctest-VM21-57252136', 'ip_addr': '141.56.182.67', 'mac_addr': '02:19:a3:74:a5:4d', '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-93935683:19a374a5-4dea-4e60-9f22-d269bf08e00a', 'sg_uuid_list': ['cbc5f751-c423-4c9a-a571-6dc223d6a08b'], 'static_route_list': None, 'vm_project_uuid': '72dd62db-1b20-4f20-8657-1f257294a7dc', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '3e79:3564:8839:b1bf:8e8c:c28: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-01-22 02:30:27,975 - DEBUG - Starting Layer 2 verification in Agent 2026-01-22 02:30:27,986 - DEBUG - Layer 2 path is seen for VM MAC 02:19:a3:74:a5:4d in agent 10.0.0.141 2026-01-22 02:30:27,987 - DEBUG - Active layer 2 route in agent is present for VMI tap19a374a5-4d 2026-01-22 02:30:27,987 - DEBUG - L2 label(29) matches bw route table and interface table 2026-01-22 02:30:28,010 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543 is found in Agent of node 10.0.0.141 2026-01-22 02:30:28,142 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543 is consistent in agent 10.0.0.141 2026-01-22 02:30:28,142 - DEBUG - Route for VM IP ['3e79:3564:8839:b1bf:8e8c:c28:0:3', '141.56.182.67'] is consistent in agent 10.0.0.141 2026-01-22 02:30:28,142 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543 verification for VM ctest-VM21-57252136 in Agent 10.0.0.141 passed 2026-01-22 02:30:28,224 - DEBUG - Starting all layer 2 verification in agent 10.0.0.141 2026-01-22 02:30:28,247 - DEBUG - Route for VM MAC 02:19:a3:74:a5:4d is consistent in agent 10.0.0.141 2026-01-22 02:30:29,426 - 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.11 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.47 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.472/4.788/7.105/2.316 ms') 2026-01-22 02:30:29,426 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-57252136 passed 2026-01-22 02:30:29,427 - INFO - VM ctest-VM21-57252136 verifications in Compute nodes passed 2026-01-22 02:30:29,666 - DEBUG - Validated VM route 141.56.182.67 in vrouter of 10.0.0.141 2026-01-22 02:30:29,673 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-01-22 02:30:29,673 - INFO - Validated routes of VM ctest-VM21-57252136 in all vrouters 2026-01-22 02:30:30,105 - DEBUG - Starting all layer2 verification in 10.0.0.38 Control Node 2026-01-22 02:30:30,214 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.38, not supported 2026-01-22 02:30:30,338 - DEBUG - Layer2 route found for VM MAC 02:19:a3:74:a5:4d in Control-node 10.0.0.38 2026-01-22 02:30:30,338 - DEBUG - L2 Label for VM ctest-VM21-57252136 same between Control-node 10.0.0.38 and Agent, Expected: 29, Seen: 29 2026-01-22 02:30:30,338 - DEBUG - Starting all layer2 verification in 10.0.0.50 Control Node 2026-01-22 02:30:30,451 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.50, not supported 2026-01-22 02:30:30,574 - DEBUG - Layer2 route found for VM MAC 02:19:a3:74:a5:4d in Control-node 10.0.0.50 2026-01-22 02:30:30,574 - DEBUG - L2 Label for VM ctest-VM21-57252136 same between Control-node 10.0.0.50 and Agent, Expected: 29, Seen: 29 2026-01-22 02:30:30,574 - INFO - Verification in Control-nodes for VM ctest-VM21-57252136 passed 2026-01-22 02:30:30,574 - DEBUG - Verifying the vm in opserver 2026-01-22 02:30:30,574 - DEBUG - Verifying in collector 10.0.0.131 ... 2026-01-22 02:30:30,574 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/f1f43417-ebcf-4ee5-a175-2ad8cde0742a?flat 2026-01-22 02:30:30,592 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-93935683:19a374a5-4dea-4e60-9f22-d269bf08e00a?flat 2026-01-22 02:30:30,617 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-93935683:19a374a5-4dea-4e60-9f22-d269bf08e00a?flat 2026-01-22 02:30:30,639 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-93935683:19a374a5-4dea-4e60-9f22-d269bf08e00a?flat 2026-01-22 02:30:30,659 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/f1f43417-ebcf-4ee5-a175-2ad8cde0742a?flat 2026-01-22 02:30:30,675 - DEBUG - Verifying vm in vn uve 2026-01-22 02:30:30,675 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-93935683:19a374a5-4dea-4e60-9f22-d269bf08e00a?flat 2026-01-22 02:30:30,694 - DEBUG - VM uve shows interface as {'mac_address': '02:19:a3:74:a5:4d', 'fixed_ip4_list': ['141.56.182.67'], 'ip6_address': '3e79:3564:8839:b1bf:8e8c:c28:0:3', 'admin_state': True, '__T': 1769048979450899, 'ip_address': '141.56.182.67', 'vm_uuid': 'f1f43417-ebcf-4ee5-a175-2ad8cde0742a', 'ip4_active': True, 'gateway': '141.56.182.65', 'label': 25, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543', 'tx_vlan': 65535, 'ip6_active': True, 'active': True, 'vhostuser_mode': 0, 'rx_vlan': 65535, 'vm_name': 'ctest-VM21-57252136', 'is_health_check_active': True, 'vn_uuid': '08dfa1db-d1a7-4962-8510-7fe335322957', 'uuid': '19a374a5-4dea-4e60-9f22-d269bf08e00a', 'l2_active': True, 'fixed_ip6_list': ['3e79:3564:8839:b1bf:8e8c:c28:0:3'], 'port_mirror_enabled': False} 2026-01-22 02:30:30,694 - DEBUG - VM uve shows ip address as ['141.56.182.67', '3e79:3564:8839:b1bf:8e8c:c28:0:3'] 2026-01-22 02:30:30,694 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543 2026-01-22 02:30:30,694 - INFO - Verifying through opserver in 10.0.0.38 2026-01-22 02:30:30,694 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543?flat 2026-01-22 02:30:30,716 - DEBUG - expected vm list f1f43417-ebcf-4ee5-a175-2ad8cde0742a 2026-01-22 02:30:30,716 - DEBUG - Extracted vm list ['f1f43417-ebcf-4ee5-a175-2ad8cde0742a'] 2026-01-22 02:30:30,716 - INFO - VM f1f43417-ebcf-4ee5-a175-2ad8cde0742a is present in default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543 2026-01-22 02:30:30,716 - DEBUG - Verifying vm in vrouter uve 2026-01-22 02:30:30,716 - DEBUG - Getting info from collector 10.0.0.131.. 2026-01-22 02:30:30,716 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/f1f43417-ebcf-4ee5-a175-2ad8cde0742a?flat 2026-01-22 02:30:30,731 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5055-2'] 2026-01-22 02:30:30,731 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-2:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:30:30,768 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-2?flat 2026-01-22 02:30:30,804 - DEBUG - VM f1f43417-ebcf-4ee5-a175-2ad8cde0742a is present in vrouter an-jenkins-deploy-platform-ansible-os-5055-2 uve 2026-01-22 02:30:30,804 - DEBUG - Validated that VM ctest-VM21-57252136 is in Vrouter an-jenkins-deploy-platform-ansible-os-5055-2 UVE 2026-01-22 02:30:30,804 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-01-22 02:30:30,811 - DEBUG - Expected tap interface of VM uuid f1f43417-ebcf-4ee5-a175-2ad8cde0742a is default-domain:ctest-TestPolicyAclIpv4v6-93935683:19a374a5-4dea-4e60-9f22-d269bf08e00a 2026-01-22 02:30:30,811 - DEBUG - Expected VN of VM uuid f1f43417-ebcf-4ee5-a175-2ad8cde0742a is default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543 2026-01-22 02:30:30,811 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-2:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:30:30,850 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-2?flat 2026-01-22 02:30:30,885 - DEBUG - VM f1f43417-ebcf-4ee5-a175-2ad8cde0742a is present in vrouter an-jenkins-deploy-platform-ansible-os-5055-2 uve 2026-01-22 02:30:30,885 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-93935683:19a374a5-4dea-4e60-9f22-d269bf08e00a of vm f1f43417-ebcf-4ee5-a175-2ad8cde0742a is present in vrouter an-jenkins-deploy-platform-ansible-os-5055-2 uve 2026-01-22 02:30:30,885 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543 of vm f1f43417-ebcf-4ee5-a175-2ad8cde0742a is present in vrouter an-jenkins-deploy-platform-ansible-os-5055-2 uve 2026-01-22 02:30:30,885 - DEBUG - Verifying the f1f43417-ebcf-4ee5-a175-2ad8cde0742a virtual network link through opserver 10.0.0.38 2026-01-22 02:30:30,885 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-01-22 02:30:30,898 - DEBUG - VM link and name as {'name': 'f1f43417-ebcf-4ee5-a175-2ad8cde0742a', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/f1f43417-ebcf-4ee5-a175-2ad8cde0742a?flat'} 2026-01-22 02:30:30,898 - DEBUG - Verifying the f1f43417-ebcf-4ee5-a175-2ad8cde0742a virtual network link through opserver 10.0.0.50 2026-01-22 02:30:30,898 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines 2026-01-22 02:30:30,914 - DEBUG - VM link and name as {'name': 'f1f43417-ebcf-4ee5-a175-2ad8cde0742a', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-machine/f1f43417-ebcf-4ee5-a175-2ad8cde0742a?flat'} 2026-01-22 02:30:30,914 - DEBUG - Verifying the f1f43417-ebcf-4ee5-a175-2ad8cde0742a virtual network link through opserver 10.0.0.131 2026-01-22 02:30:30,914 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2026-01-22 02:30:30,926 - DEBUG - VM link and name as {'name': 'f1f43417-ebcf-4ee5-a175-2ad8cde0742a', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-machine/f1f43417-ebcf-4ee5-a175-2ad8cde0742a?flat'} 2026-01-22 02:30:30,926 - INFO - VM ctest-VM21-57252136 validations in Opserver passed 2026-01-22 02:30:31,011 - DEBUG - VM is in ACTIVE state now 2026-01-22 02:30:31,011 - INFO - VM name : ctest-VM31-21589980 2026-01-22 02:30:31,102 - DEBUG - VM ctest-VM31-21589980 ID is 0fa24390-f6d1-4cde-9917-df3581bd2407 2026-01-22 02:30:31,184 - DEBUG - VM is in ACTIVE state now 2026-01-22 02:30:31,184 - DEBUG - VM ctest-VM31-21589980 launched on Node an-jenkins-deploy-platform-ansible-os-5055-1 2026-01-22 02:30:31,273 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/0fa24390-f6d1-4cde-9917-df3581bd2407 2026-01-22 02:30:31,282 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/0fa24390-f6d1-4cde-9917-df3581bd2407 2026-01-22 02:30:31,289 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/c261a8f3-f0cb-4849-8edf-bb881a5eee7d 2026-01-22 02:30:31,303 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/0fa24390-f6d1-4cde-9917-df3581bd2407 2026-01-22 02:30:31,309 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/c261a8f3-f0cb-4849-8edf-bb881a5eee7d 2026-01-22 02:30:31,320 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/35ed8712-0e79-4a6f-af86-c823f5b7de4b 2026-01-22 02:30:31,329 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/6aaebb3d-7068-46b2-a520-a998ad85fe87 2026-01-22 02:30:31,339 - DEBUG - Verifying in api server 10.0.0.131 2026-01-22 02:30:31,339 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/0fa24390-f6d1-4cde-9917-df3581bd2407 2026-01-22 02:30:31,347 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/c261a8f3-f0cb-4849-8edf-bb881a5eee7d 2026-01-22 02:30:31,357 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/35ed8712-0e79-4a6f-af86-c823f5b7de4b 2026-01-22 02:30:31,365 - DEBUG - Requesting: http://10.0.0.131:8082/instance-ip/6aaebb3d-7068-46b2-a520-a998ad85fe87 2026-01-22 02:30:31,373 - INFO - VM ctest-VM31-21589980 verfication in all API Servers passed 2026-01-22 02:30:31,391 - DEBUG - VM ctest-VM31-21589980 Tap interface: {'index': '4', 'name': 'tapc261a8f3-f0', 'uuid': 'c261a8f3-f0cb-4849-8edf-bb881a5eee7d', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121:ctest-VN3-45142121', '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': '14', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121', 'vm_uuid': '0fa24390-f6d1-4cde-9917-df3581bd2407', 'vm_name': 'ctest-VM31-21589980', 'ip_addr': '60.237.74.67', 'mac_addr': '02:c2:61:a8:f3:f0', '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-93935683:c261a8f3-f0cb-4849-8edf-bb881a5eee7d', 'sg_uuid_list': ['cbc5f751-c423-4c9a-a571-6dc223d6a08b'], 'static_route_list': None, 'vm_project_uuid': '72dd62db-1b20-4f20-8657-1f257294a7dc', 'admin_state': 'Enabled', 'flow_key_idx': '40', 'allowed_address_pair_list': None, 'ip6_addr': '2c27:d355:82b5:390f:9bc7:9ece: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-01-22 02:30:31,391 - DEBUG - Agent 10.0.0.23 vrf name: default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121:ctest-VN3-45142121 2026-01-22 02:30:31,397 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121:ctest-VN3-45142121', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.131:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-01-22 02:30:31,495 - DEBUG - VM ctest-VM31-21589980 : Tap interface tapc261a8f3-f0 is set to Active 2026-01-22 02:30:31,495 - DEBUG - Tap interface tapc261a8f3-f0 detail : {'index': '4', 'name': 'tapc261a8f3-f0', 'uuid': 'c261a8f3-f0cb-4849-8edf-bb881a5eee7d', 'vrf_name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121:ctest-VN3-45142121', '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': '14', 'vn_name': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121', 'vm_uuid': '0fa24390-f6d1-4cde-9917-df3581bd2407', 'vm_name': 'ctest-VM31-21589980', 'ip_addr': '60.237.74.67', 'mac_addr': '02:c2:61:a8:f3:f0', '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-93935683:c261a8f3-f0cb-4849-8edf-bb881a5eee7d', 'sg_uuid_list': ['cbc5f751-c423-4c9a-a571-6dc223d6a08b'], 'static_route_list': None, 'vm_project_uuid': '72dd62db-1b20-4f20-8657-1f257294a7dc', 'admin_state': 'Enabled', 'flow_key_idx': '40', 'allowed_address_pair_list': None, 'ip6_addr': '2c27:d355:82b5:390f:9bc7:9ece: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-01-22 02:30:31,612 - DEBUG - Starting Layer 2 verification in Agent 2026-01-22 02:30:31,627 - DEBUG - Layer 2 path is seen for VM MAC 02:c2:61:a8:f3:f0 in agent 10.0.0.23 2026-01-22 02:30:31,627 - DEBUG - Active layer 2 route in agent is present for VMI tapc261a8f3-f0 2026-01-22 02:30:31,627 - DEBUG - L2 label(36) matches bw route table and interface table 2026-01-22 02:30:31,636 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121 is found in Agent of node 10.0.0.23 2026-01-22 02:30:31,788 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121 is consistent in agent 10.0.0.23 2026-01-22 02:30:31,788 - DEBUG - Route for VM IP ['60.237.74.67', '2c27:d355:82b5:390f:9bc7:9ece:0:3'] is consistent in agent 10.0.0.23 2026-01-22 02:30:31,788 - DEBUG - VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121 verification for VM ctest-VM31-21589980 in Agent 10.0.0.23 passed 2026-01-22 02:30:31,904 - DEBUG - Starting all layer 2 verification in agent 10.0.0.23 2026-01-22 02:30:31,914 - DEBUG - Route for VM MAC 02:c2:61:a8:f3:f0 is consistent in agent 10.0.0.23 2026-01-22 02:30:33,096 - 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.81 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=4.99 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 = 4.986/5.896/6.806/0.910 ms') 2026-01-22 02:30:33,097 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-21589980 passed 2026-01-22 02:30:33,097 - INFO - VM ctest-VM31-21589980 verifications in Compute nodes passed 2026-01-22 02:30:33,302 - DEBUG - Validated VM route 60.237.74.67 in vrouter of 10.0.0.23 2026-01-22 02:30:33,307 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-01-22 02:30:33,307 - INFO - Validated routes of VM ctest-VM31-21589980 in all vrouters 2026-01-22 02:30:33,813 - DEBUG - Starting all layer2 verification in 10.0.0.38 Control Node 2026-01-22 02:30:33,940 - DEBUG - Layer2 route found for VM MAC 02:c2:61:a8:f3:f0 in Control-node 10.0.0.38 2026-01-22 02:30:33,940 - DEBUG - L2 Label for VM ctest-VM31-21589980 same between Control-node 10.0.0.38 and Agent, Expected: 36, Seen: 36 2026-01-22 02:30:34,051 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.38, not supported 2026-01-22 02:30:34,051 - DEBUG - Starting all layer2 verification in 10.0.0.50 Control Node 2026-01-22 02:30:34,173 - DEBUG - Layer2 route found for VM MAC 02:c2:61:a8:f3:f0 in Control-node 10.0.0.50 2026-01-22 02:30:34,173 - DEBUG - L2 Label for VM ctest-VM31-21589980 same between Control-node 10.0.0.50 and Agent, Expected: 36, Seen: 36 2026-01-22 02:30:34,295 - DEBUG - Skipping L2 verification of v6 route on cn 10.0.0.50, not supported 2026-01-22 02:30:34,295 - INFO - Verification in Control-nodes for VM ctest-VM31-21589980 passed 2026-01-22 02:30:34,295 - DEBUG - Verifying the vm in opserver 2026-01-22 02:30:34,295 - DEBUG - Verifying in collector 10.0.0.131 ... 2026-01-22 02:30:34,295 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/0fa24390-f6d1-4cde-9917-df3581bd2407?flat 2026-01-22 02:30:34,313 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-93935683:c261a8f3-f0cb-4849-8edf-bb881a5eee7d?flat 2026-01-22 02:30:34,333 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-93935683:c261a8f3-f0cb-4849-8edf-bb881a5eee7d?flat 2026-01-22 02:30:34,356 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-93935683:c261a8f3-f0cb-4849-8edf-bb881a5eee7d?flat 2026-01-22 02:30:34,376 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/0fa24390-f6d1-4cde-9917-df3581bd2407?flat 2026-01-22 02:30:34,392 - DEBUG - Verifying vm in vn uve 2026-01-22 02:30:34,392 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAclIpv4v6-93935683:c261a8f3-f0cb-4849-8edf-bb881a5eee7d?flat 2026-01-22 02:30:34,416 - DEBUG - VM uve shows interface as {'mac_address': '02:c2:61:a8:f3:f0', 'fixed_ip4_list': ['60.237.74.67'], 'ip6_address': '2c27:d355:82b5:390f:9bc7:9ece:0:3', 'admin_state': True, '__T': 1769048979222367, 'ip_address': '60.237.74.67', 'vm_uuid': '0fa24390-f6d1-4cde-9917-df3581bd2407', 'ip4_active': True, 'gateway': '60.237.74.65', 'label': 32, 'virtual_network': 'default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121', 'tx_vlan': 65535, 'ip6_active': True, 'active': True, 'vhostuser_mode': 0, 'rx_vlan': 65535, 'vm_name': 'ctest-VM31-21589980', 'is_health_check_active': True, 'vn_uuid': '2e55d92c-7a4c-45a5-9141-9b9ed9525fcf', 'uuid': 'c261a8f3-f0cb-4849-8edf-bb881a5eee7d', 'l2_active': True, 'fixed_ip6_list': ['2c27:d355:82b5:390f:9bc7:9ece:0:3'], 'port_mirror_enabled': False} 2026-01-22 02:30:34,416 - DEBUG - VM uve shows ip address as ['60.237.74.67', '2c27:d355:82b5:390f:9bc7:9ece:0:3'] 2026-01-22 02:30:34,416 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121 2026-01-22 02:30:34,416 - INFO - Verifying through opserver in 10.0.0.38 2026-01-22 02:30:34,416 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121?flat 2026-01-22 02:30:34,438 - DEBUG - expected vm list 0fa24390-f6d1-4cde-9917-df3581bd2407 2026-01-22 02:30:34,438 - DEBUG - Extracted vm list ['0fa24390-f6d1-4cde-9917-df3581bd2407'] 2026-01-22 02:30:34,438 - INFO - VM 0fa24390-f6d1-4cde-9917-df3581bd2407 is present in default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121 2026-01-22 02:30:34,438 - DEBUG - Verifying vm in vrouter uve 2026-01-22 02:30:34,438 - DEBUG - Getting info from collector 10.0.0.131.. 2026-01-22 02:30:34,438 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machine/0fa24390-f6d1-4cde-9917-df3581bd2407?flat 2026-01-22 02:30:34,453 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5055-1'] 2026-01-22 02:30:34,453 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:30:34,485 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:30:34,518 - DEBUG - VM 0fa24390-f6d1-4cde-9917-df3581bd2407 is present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:30:34,518 - DEBUG - Validated that VM ctest-VM31-21589980 is in Vrouter an-jenkins-deploy-platform-ansible-os-5055-1 UVE 2026-01-22 02:30:34,518 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-01-22 02:30:34,525 - DEBUG - Expected tap interface of VM uuid 0fa24390-f6d1-4cde-9917-df3581bd2407 is default-domain:ctest-TestPolicyAclIpv4v6-93935683:c261a8f3-f0cb-4849-8edf-bb881a5eee7d 2026-01-22 02:30:34,525 - DEBUG - Expected VN of VM uuid 0fa24390-f6d1-4cde-9917-df3581bd2407 is default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121 2026-01-22 02:30:34,525 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:30:34,557 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:30:34,588 - DEBUG - VM 0fa24390-f6d1-4cde-9917-df3581bd2407 is present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:30:34,588 - INFO - tap interface default-domain:ctest-TestPolicyAclIpv4v6-93935683:c261a8f3-f0cb-4849-8edf-bb881a5eee7d of vm 0fa24390-f6d1-4cde-9917-df3581bd2407 is present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:30:34,588 - DEBUG - Connected network default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121 of vm 0fa24390-f6d1-4cde-9917-df3581bd2407 is present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:30:34,588 - DEBUG - Verifying the 0fa24390-f6d1-4cde-9917-df3581bd2407 virtual network link through opserver 10.0.0.38 2026-01-22 02:30:34,588 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-01-22 02:30:34,601 - DEBUG - VM link and name as {'name': '0fa24390-f6d1-4cde-9917-df3581bd2407', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/0fa24390-f6d1-4cde-9917-df3581bd2407?flat'} 2026-01-22 02:30:34,601 - DEBUG - Verifying the 0fa24390-f6d1-4cde-9917-df3581bd2407 virtual network link through opserver 10.0.0.50 2026-01-22 02:30:34,601 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines 2026-01-22 02:30:34,613 - DEBUG - VM link and name as {'name': '0fa24390-f6d1-4cde-9917-df3581bd2407', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-machine/0fa24390-f6d1-4cde-9917-df3581bd2407?flat'} 2026-01-22 02:30:34,613 - DEBUG - Verifying the 0fa24390-f6d1-4cde-9917-df3581bd2407 virtual network link through opserver 10.0.0.131 2026-01-22 02:30:34,613 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2026-01-22 02:30:34,625 - DEBUG - VM link and name as {'name': '0fa24390-f6d1-4cde-9917-df3581bd2407', 'href': 'http://10.0.0.131:8081/analytics/uves/virtual-machine/0fa24390-f6d1-4cde-9917-df3581bd2407?flat'} 2026-01-22 02:30:34,625 - INFO - VM ctest-VM31-21589980 validations in Opserver passed 2026-01-22 02:30:34,626 - INFO - Waiting for VM ctest-VM11-40119303 to be up.. 2026-01-22 02:30:34,626 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-22 02:30:34,626 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/4919c2ec-3a13-4db1-a3a5-8d287f2a41ad 2026-01-22 02:30:34,634 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/a7b21cd4-3fc9-4098-9ae9-9dedda71daa9 2026-01-22 02:30:35,800 - 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=2.20 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.508 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.508/1.354/2.200/0.846 ms') 2026-01-22 02:30:35,801 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-40119303 passed 2026-01-22 02:30:35,872 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-22 02:30:35,873 - DEBUG - Waiting to SSH to VM ctest-VM11-40119303, IP 133.243.38.195, Port 22 2026-01-22 02:30:35,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-01-22 02:30:36,036 - DEBUG - VM ctest-VM11-40119303 is NOT ready for SSH connections, VM status: ACTIVE 2026-01-22 02:30:41,036 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-22 02:30:41,036 - DEBUG - Waiting to SSH to VM ctest-VM11-40119303, IP 133.243.38.195, Port 22 2026-01-22 02:30:41,105 - 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-01-22 02:30:41,210 - DEBUG - VM ctest-VM11-40119303 is NOT ready for SSH connections, VM status: ACTIVE 2026-01-22 02:30:46,210 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-22 02:30:46,211 - DEBUG - Waiting to SSH to VM ctest-VM11-40119303, IP 133.243.38.195, Port 22 2026-01-22 02:30:46,277 - 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-01-22 02:30:46,369 - DEBUG - VM ctest-VM11-40119303 is NOT ready for SSH connections, VM status: ACTIVE 2026-01-22 02:30:51,370 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-22 02:30:51,370 - DEBUG - Waiting to SSH to VM ctest-VM11-40119303, IP 133.243.38.195, Port 22 2026-01-22 02:30:51,441 - 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-01-22 02:30:51,532 - DEBUG - VM ctest-VM11-40119303 is NOT ready for SSH connections, VM status: ACTIVE 2026-01-22 02:30:56,533 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-22 02:30:56,533 - DEBUG - Waiting to SSH to VM ctest-VM11-40119303, IP 133.243.38.195, Port 22 2026-01-22 02:30:56,698 - DEBUG - VM ctest-VM11-40119303 is ready for SSH connections 2026-01-22 02:30:56,698 - INFO - Waiting for VM ctest-VM21-57252136 to be up.. 2026-01-22 02:30:56,698 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-22 02:30:56,698 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/f1f43417-ebcf-4ee5-a175-2ad8cde0742a 2026-01-22 02:30:56,707 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/19a374a5-4dea-4e60-9f22-d269bf08e00a 2026-01-22 02:30:57,873 - 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.33 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.665 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 = 0.665/1.996/3.328/1.331 ms') 2026-01-22 02:30:57,873 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-57252136 passed 2026-01-22 02:30:57,943 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-22 02:30:57,943 - DEBUG - Waiting to SSH to VM ctest-VM21-57252136, IP 141.56.182.67, Port 22 2026-01-22 02:30:58,114 - DEBUG - VM ctest-VM21-57252136 is ready for SSH connections 2026-01-22 02:30:58,114 - INFO - Waiting for VM ctest-VM31-21589980 to be up.. 2026-01-22 02:30:58,114 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-22 02:30:58,114 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/0fa24390-f6d1-4cde-9917-df3581bd2407 2026-01-22 02:30:58,124 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/c261a8f3-f0cb-4849-8edf-bb881a5eee7d 2026-01-22 02:30:59,294 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=5.28 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.55 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 = 2.549/3.913/5.277/1.364 ms') 2026-01-22 02:30:59,294 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-21589980 passed 2026-01-22 02:30:59,360 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-22 02:30:59,360 - DEBUG - Waiting to SSH to VM ctest-VM31-21589980, IP 60.237.74.67, Port 22 2026-01-22 02:30:59,532 - DEBUG - VM ctest-VM31-21589980 is ready for SSH connections 2026-01-22 02:30:59,533 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 141.56.182.67, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123 2026-01-22 02:30:59,533 - DEBUG - ping -s 56 -c 3 -W 1 141.56.182.67 2026-01-22 02:31:23,469 - DEBUG - PING 141.56.182.67 (141.56.182.67) 56(84) bytes of data. 64 bytes from 141.56.182.67: icmp_req=1 ttl=63 time=5.83 ms 64 bytes from 141.56.182.67: icmp_req=2 ttl=63 time=1.30 ms 64 bytes from 141.56.182.67: icmp_req=3 ttl=63 time=1.45 ms --- 141.56.182.67 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.306/2.864/5.834/2.100 ms 2026-01-22 02:31:23,469 - INFO - Ping to IP 141.56.182.67 from VM ctest-VM11-40119303 passed 2026-01-22 02:31:23,469 - DEBUG - Running remote_cmd, Cmd : ping6 -s 56 -c 3 -W 1 3e79:3564:8839:b1bf:8e8c:c28:0:3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123 2026-01-22 02:31:23,469 - DEBUG - ping6 -s 56 -c 3 -W 1 3e79:3564:8839:b1bf:8e8c:c28:0:3 2026-01-22 02:31:26,059 - DEBUG - PING 3e79:3564:8839:b1bf:8e8c:c28:0:3(3e79:3564:8839:b1bf:8e8c:c28:0:3) 56 data bytes 64 bytes from 3e79:3564:8839:b1bf:8e8c:c28:0:3: icmp_seq=1 ttl=64 time=9.29 ms 64 bytes from 3e79:3564:8839:b1bf:8e8c:c28:0:3: icmp_seq=2 ttl=64 time=1.31 ms 64 bytes from 3e79:3564:8839:b1bf:8e8c:c28:0:3: icmp_seq=3 ttl=64 time=1.43 ms --- 3e79:3564:8839:b1bf:8e8c:c28:0:3 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.313/4.013/9.293/3.734 ms 2026-01-22 02:31:26,059 - INFO - Ping to IP 3e79:3564:8839:b1bf:8e8c:c28:0:3 from VM ctest-VM11-40119303 passed 2026-01-22 02:31:26,059 - INFO - Test with src as policy and dst as any PASSED 2026-01-22 02:31:26,059 - INFO - Deleting VM ctest-VM31-21589980 2026-01-22 02:31:26,138 - DEBUG - Verifying in api server 10.0.0.131 2026-01-22 02:31:26,138 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/0fa24390-f6d1-4cde-9917-df3581bd2407 2026-01-22 02:31:26,146 - DEBUG - VM ID 0fa24390-f6d1-4cde-9917-df3581bd2407 of VM ctest-VM31-21589980 is still found in API Server 2026-01-22 02:31:28,147 - DEBUG - Verifying in api server 10.0.0.131 2026-01-22 02:31:28,147 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/0fa24390-f6d1-4cde-9917-df3581bd2407 2026-01-22 02:31:28,154 - DEBUG - Response Code: 404 2026-01-22 02:31:28,154 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/0fa24390-f6d1-4cde-9917-df3581bd2407 2026-01-22 02:31:28,160 - DEBUG - Response Code: 404 2026-01-22 02:31:28,160 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/0fa24390-f6d1-4cde-9917-df3581bd2407 2026-01-22 02:31:28,165 - DEBUG - Response Code: 404 2026-01-22 02:31:28,165 - INFO - VM ctest-VM31-21589980 is fully removed in API-Server 2026-01-22 02:31:28,322 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-01-22 02:31:28,363 - DEBUG - Validated that vrouter 10.0.0.23 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121 2026-01-22 02:31:28,373 - DEBUG - Validated that vrouter 10.0.0.141 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121 2026-01-22 02:31:28,374 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121 2026-01-22 02:31:28,374 - INFO - VM ctest-VM31-21589980 is removed in Compute, and routes are removed in all compute nodes 2026-01-22 02:31:28,617 - INFO - Routes for VM ctest-VM31-21589980 is removed in all control-nodes 2026-01-22 02:31:28,617 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:31:28,657 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:31:28,691 - DEBUG - VM 0fa24390-f6d1-4cde-9917-df3581bd2407 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:31:28,691 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:31:28,692 - DEBUG - interface for vm 0fa24390-f6d1-4cde-9917-df3581bd2407 deleted from vrouter uve ... 2026-01-22 02:31:28,692 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:31:31,692 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:31:31,728 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:31:31,758 - DEBUG - VM 0fa24390-f6d1-4cde-9917-df3581bd2407 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:31:31,758 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:31:31,758 - DEBUG - interface for vm 0fa24390-f6d1-4cde-9917-df3581bd2407 deleted from vrouter uve ... 2026-01-22 02:31:31,758 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:31:34,758 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:31:34,792 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:31:34,823 - DEBUG - VM 0fa24390-f6d1-4cde-9917-df3581bd2407 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:31:34,823 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:31:34,823 - DEBUG - interface for vm 0fa24390-f6d1-4cde-9917-df3581bd2407 deleted from vrouter uve ... 2026-01-22 02:31:34,823 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:31:37,823 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:31:37,862 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:31:37,899 - DEBUG - VM 0fa24390-f6d1-4cde-9917-df3581bd2407 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:31:37,899 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:31:37,899 - DEBUG - interface for vm 0fa24390-f6d1-4cde-9917-df3581bd2407 deleted from vrouter uve ... 2026-01-22 02:31:37,899 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:31:40,900 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:31:40,935 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:31:40,976 - DEBUG - VM 0fa24390-f6d1-4cde-9917-df3581bd2407 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:31:40,976 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:31:40,976 - DEBUG - interface for vm 0fa24390-f6d1-4cde-9917-df3581bd2407 deleted from vrouter uve ... 2026-01-22 02:31:40,976 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:31:40,976 - DEBUG - Validated that VM 0fa24390-f6d1-4cde-9917-df3581bd2407 is removed in Vrouter UVE 2026-01-22 02:31:40,976 - DEBUG - Verifying through opserver in 10.0.0.38 2026-01-22 02:31:40,976 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121?flat 2026-01-22 02:31:40,994 - DEBUG - 0fa24390-f6d1-4cde-9917-df3581bd2407 BM not in default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN3-45142121 uve 2026-01-22 02:31:40,994 - DEBUG - Validated that VM 0fa24390-f6d1-4cde-9917-df3581bd2407 is not present in the VN UVE 2026-01-22 02:31:40,994 - DEBUG - Verifying the 0fa24390-f6d1-4cde-9917-df3581bd2407 virtual network link through opserver 10.0.0.38 2026-01-22 02:31:40,994 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-01-22 02:31:41,007 - INFO - 0fa24390-f6d1-4cde-9917-df3581bd2407 vm uve deleted from opserver 2026-01-22 02:31:41,007 - DEBUG - Verifying the 0fa24390-f6d1-4cde-9917-df3581bd2407 virtual network link through opserver 10.0.0.50 2026-01-22 02:31:41,007 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines 2026-01-22 02:31:41,020 - INFO - 0fa24390-f6d1-4cde-9917-df3581bd2407 vm uve deleted from opserver 2026-01-22 02:31:41,020 - DEBUG - Verifying the 0fa24390-f6d1-4cde-9917-df3581bd2407 virtual network link through opserver 10.0.0.131 2026-01-22 02:31:41,021 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2026-01-22 02:31:41,033 - INFO - 0fa24390-f6d1-4cde-9917-df3581bd2407 vm uve deleted from opserver 2026-01-22 02:31:41,034 - INFO - Validated that VM 0fa24390-f6d1-4cde-9917-df3581bd2407 is removed from Opserver 2026-01-22 02:31:41,034 - INFO - Deleting VM ctest-VM21-57252136 2026-01-22 02:31:41,109 - DEBUG - Verifying in api server 10.0.0.131 2026-01-22 02:31:41,110 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/f1f43417-ebcf-4ee5-a175-2ad8cde0742a 2026-01-22 02:31:41,118 - DEBUG - VM ID f1f43417-ebcf-4ee5-a175-2ad8cde0742a of VM ctest-VM21-57252136 is still found in API Server 2026-01-22 02:31:43,119 - DEBUG - Verifying in api server 10.0.0.131 2026-01-22 02:31:43,119 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/f1f43417-ebcf-4ee5-a175-2ad8cde0742a 2026-01-22 02:31:43,126 - DEBUG - Response Code: 404 2026-01-22 02:31:43,126 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/f1f43417-ebcf-4ee5-a175-2ad8cde0742a 2026-01-22 02:31:43,132 - DEBUG - Response Code: 404 2026-01-22 02:31:43,132 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/f1f43417-ebcf-4ee5-a175-2ad8cde0742a 2026-01-22 02:31:43,138 - DEBUG - Response Code: 404 2026-01-22 02:31:43,138 - INFO - VM ctest-VM21-57252136 is fully removed in API-Server 2026-01-22 02:31:43,298 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-01-22 02:31:43,344 - DEBUG - Validated that vrouter 10.0.0.23 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543 2026-01-22 02:31:43,353 - DEBUG - Validated that vrouter 10.0.0.141 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543 2026-01-22 02:31:43,353 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543 2026-01-22 02:31:43,353 - INFO - VM ctest-VM21-57252136 is removed in Compute, and routes are removed in all compute nodes 2026-01-22 02:31:43,576 - INFO - Routes for VM ctest-VM21-57252136 is removed in all control-nodes 2026-01-22 02:31:43,576 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-2:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:31:43,611 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-2?flat 2026-01-22 02:31:43,648 - DEBUG - VM f1f43417-ebcf-4ee5-a175-2ad8cde0742a is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-2 uve 2026-01-22 02:31:43,648 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:31:43,648 - DEBUG - interface for vm f1f43417-ebcf-4ee5-a175-2ad8cde0742a deleted from vrouter uve ... 2026-01-22 02:31:43,648 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:31:46,649 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-2:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:31:46,685 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-2?flat 2026-01-22 02:31:46,717 - DEBUG - VM f1f43417-ebcf-4ee5-a175-2ad8cde0742a is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-2 uve 2026-01-22 02:31:46,717 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:31:46,717 - DEBUG - interface for vm f1f43417-ebcf-4ee5-a175-2ad8cde0742a deleted from vrouter uve ... 2026-01-22 02:31:46,717 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:31:49,718 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-2:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:31:49,753 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-2?flat 2026-01-22 02:31:49,789 - DEBUG - VM f1f43417-ebcf-4ee5-a175-2ad8cde0742a is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-2 uve 2026-01-22 02:31:49,789 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:31:49,789 - DEBUG - interface for vm f1f43417-ebcf-4ee5-a175-2ad8cde0742a deleted from vrouter uve ... 2026-01-22 02:31:49,789 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:31:52,790 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-2:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:31:52,829 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-2?flat 2026-01-22 02:31:52,859 - DEBUG - VM f1f43417-ebcf-4ee5-a175-2ad8cde0742a is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-2 uve 2026-01-22 02:31:52,859 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:31:52,859 - DEBUG - interface for vm f1f43417-ebcf-4ee5-a175-2ad8cde0742a deleted from vrouter uve ... 2026-01-22 02:31:52,859 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:31:55,860 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-2:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:31:55,895 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-2?flat 2026-01-22 02:31:55,926 - DEBUG - VM f1f43417-ebcf-4ee5-a175-2ad8cde0742a is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-2 uve 2026-01-22 02:31:55,926 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:31:55,926 - DEBUG - interface for vm f1f43417-ebcf-4ee5-a175-2ad8cde0742a deleted from vrouter uve ... 2026-01-22 02:31:55,926 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:31:58,927 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-2:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:31:58,964 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-2?flat 2026-01-22 02:31:58,996 - DEBUG - VM f1f43417-ebcf-4ee5-a175-2ad8cde0742a is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-2 uve 2026-01-22 02:31:58,996 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:31:58,996 - DEBUG - interface for vm f1f43417-ebcf-4ee5-a175-2ad8cde0742a deleted from vrouter uve ... 2026-01-22 02:31:58,996 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:32:01,997 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-2:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:32:02,034 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-2?flat 2026-01-22 02:32:02,066 - DEBUG - VM f1f43417-ebcf-4ee5-a175-2ad8cde0742a is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-2 uve 2026-01-22 02:32:02,066 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:32:02,066 - DEBUG - interface for vm f1f43417-ebcf-4ee5-a175-2ad8cde0742a deleted from vrouter uve ... 2026-01-22 02:32:02,066 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:32:05,067 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-2:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:32:05,103 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-2?flat 2026-01-22 02:32:05,136 - DEBUG - VM f1f43417-ebcf-4ee5-a175-2ad8cde0742a is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-2 uve 2026-01-22 02:32:05,136 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:32:05,136 - DEBUG - interface for vm f1f43417-ebcf-4ee5-a175-2ad8cde0742a deleted from vrouter uve ... 2026-01-22 02:32:05,136 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:32:08,137 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-2:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:32:08,173 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-2?flat 2026-01-22 02:32:08,204 - DEBUG - VM f1f43417-ebcf-4ee5-a175-2ad8cde0742a is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-2 uve 2026-01-22 02:32:08,204 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:32:08,204 - DEBUG - interface for vm f1f43417-ebcf-4ee5-a175-2ad8cde0742a deleted from vrouter uve ... 2026-01-22 02:32:08,204 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:32:11,205 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-2:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:32:11,240 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-2?flat 2026-01-22 02:32:11,276 - DEBUG - VM f1f43417-ebcf-4ee5-a175-2ad8cde0742a is not present in vrouter an-jenkins-deploy-platform-ansible-os-5055-2 uve 2026-01-22 02:32:11,276 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:32:11,276 - DEBUG - interface for vm f1f43417-ebcf-4ee5-a175-2ad8cde0742a deleted from vrouter uve ... 2026-01-22 02:32:11,276 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:32:11,276 - DEBUG - Validated that VM f1f43417-ebcf-4ee5-a175-2ad8cde0742a is removed in Vrouter UVE 2026-01-22 02:32:11,276 - DEBUG - Verifying through opserver in 10.0.0.38 2026-01-22 02:32:11,276 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543?flat 2026-01-22 02:32:11,295 - DEBUG - f1f43417-ebcf-4ee5-a175-2ad8cde0742a BM not in default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN2-77208543 uve 2026-01-22 02:32:11,295 - DEBUG - Validated that VM f1f43417-ebcf-4ee5-a175-2ad8cde0742a is not present in the VN UVE 2026-01-22 02:32:11,295 - DEBUG - Verifying the f1f43417-ebcf-4ee5-a175-2ad8cde0742a virtual network link through opserver 10.0.0.38 2026-01-22 02:32:11,295 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-01-22 02:32:11,309 - INFO - f1f43417-ebcf-4ee5-a175-2ad8cde0742a vm uve deleted from opserver 2026-01-22 02:32:11,309 - DEBUG - Verifying the f1f43417-ebcf-4ee5-a175-2ad8cde0742a virtual network link through opserver 10.0.0.50 2026-01-22 02:32:11,309 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines 2026-01-22 02:32:11,323 - INFO - f1f43417-ebcf-4ee5-a175-2ad8cde0742a vm uve deleted from opserver 2026-01-22 02:32:11,323 - DEBUG - Verifying the f1f43417-ebcf-4ee5-a175-2ad8cde0742a virtual network link through opserver 10.0.0.131 2026-01-22 02:32:11,323 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2026-01-22 02:32:11,335 - INFO - f1f43417-ebcf-4ee5-a175-2ad8cde0742a vm uve deleted from opserver 2026-01-22 02:32:11,335 - INFO - Validated that VM f1f43417-ebcf-4ee5-a175-2ad8cde0742a is removed from Opserver 2026-01-22 02:32:11,335 - INFO - Deleting VM ctest-VM11-40119303 2026-01-22 02:32:11,405 - DEBUG - Verifying in api server 10.0.0.131 2026-01-22 02:32:11,406 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/4919c2ec-3a13-4db1-a3a5-8d287f2a41ad 2026-01-22 02:32:11,414 - DEBUG - VM ID 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad of VM ctest-VM11-40119303 is still found in API Server 2026-01-22 02:32:13,414 - DEBUG - Verifying in api server 10.0.0.131 2026-01-22 02:32:13,415 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/4919c2ec-3a13-4db1-a3a5-8d287f2a41ad 2026-01-22 02:32:13,421 - DEBUG - Response Code: 404 2026-01-22 02:32:13,421 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/4919c2ec-3a13-4db1-a3a5-8d287f2a41ad 2026-01-22 02:32:13,426 - DEBUG - Response Code: 404 2026-01-22 02:32:13,427 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/4919c2ec-3a13-4db1-a3a5-8d287f2a41ad 2026-01-22 02:32:13,431 - DEBUG - Response Code: 404 2026-01-22 02:32:13,432 - INFO - VM ctest-VM11-40119303 is fully removed in API-Server 2026-01-22 02:32:13,593 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-01-22 02:32:13,643 - DEBUG - Validated that vrouter 10.0.0.23 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 2026-01-22 02:32:13,655 - DEBUG - Validated that vrouter 10.0.0.141 does not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 2026-01-22 02:32:13,655 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 2026-01-22 02:32:13,655 - INFO - VM ctest-VM11-40119303 is removed in Compute, and routes are removed in all compute nodes 2026-01-22 02:32:13,844 - INFO - Routes for VM ctest-VM11-40119303 is removed in all control-nodes 2026-01-22 02:32:13,844 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:32:13,878 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:32:13,913 - DEBUG - VM 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:32:13,913 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:32:13,913 - DEBUG - interface for vm 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad deleted from vrouter uve ... 2026-01-22 02:32:13,913 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:32:16,914 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:32:16,947 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:32:16,981 - DEBUG - VM 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:32:16,981 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:32:16,981 - DEBUG - interface for vm 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad deleted from vrouter uve ... 2026-01-22 02:32:16,981 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:32:19,981 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:32:20,017 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:32:20,049 - DEBUG - VM 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:32:20,049 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:32:20,049 - DEBUG - interface for vm 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad deleted from vrouter uve ... 2026-01-22 02:32:20,049 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:32:23,050 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:32:23,087 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:32:23,126 - DEBUG - VM 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:32:23,126 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:32:23,127 - DEBUG - interface for vm 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad deleted from vrouter uve ... 2026-01-22 02:32:23,127 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:32:26,127 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:32:26,161 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:32:26,200 - DEBUG - VM 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:32:26,200 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:32:26,200 - DEBUG - interface for vm 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad deleted from vrouter uve ... 2026-01-22 02:32:26,200 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:32:29,200 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:32:29,235 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:32:29,264 - DEBUG - VM 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:32:29,264 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:32:29,264 - DEBUG - interface for vm 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad deleted from vrouter uve ... 2026-01-22 02:32:29,264 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:32:32,265 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:32:32,307 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:32:32,343 - DEBUG - VM 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:32:32,343 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:32:32,343 - DEBUG - interface for vm 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad deleted from vrouter uve ... 2026-01-22 02:32:32,343 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:32:35,344 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:32:35,380 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:32:35,414 - DEBUG - VM 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:32:35,415 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:32:35,415 - DEBUG - interface for vm 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad deleted from vrouter uve ... 2026-01-22 02:32:35,415 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:32:38,415 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:32:38,454 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:32:38,492 - DEBUG - VM 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is still present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:32:38,492 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:32:38,492 - DEBUG - interface for vm 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad deleted from vrouter uve ... 2026-01-22 02:32:38,492 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:32:41,493 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5055-1:Compute:contrail-vrouter-agent:0?flat 2026-01-22 02:32:41,530 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5055-1?flat 2026-01-22 02:32:41,561 - DEBUG - VM 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is not present in vrouter an-jenkins-deploy-platform-ansible-os-5055-1 uve 2026-01-22 02:32:41,561 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-01-22 02:32:41,561 - DEBUG - interface for vm 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad deleted from vrouter uve ... 2026-01-22 02:32:41,561 - DEBUG - Deleted interface not in error interface list ... 2026-01-22 02:32:41,561 - DEBUG - Validated that VM 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is removed in Vrouter UVE 2026-01-22 02:32:41,561 - DEBUG - Verifying through opserver in 10.0.0.38 2026-01-22 02:32:41,561 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507?flat 2026-01-22 02:32:41,581 - DEBUG - 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad BM not in default-domain:ctest-TestPolicyAclIpv4v6-93935683:ctest-VN1-37730507 uve 2026-01-22 02:32:41,581 - DEBUG - Validated that VM 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is not present in the VN UVE 2026-01-22 02:32:41,581 - DEBUG - Verifying the 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad virtual network link through opserver 10.0.0.38 2026-01-22 02:32:41,581 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-01-22 02:32:41,594 - DEBUG - No links retuned for 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad 2026-01-22 02:32:41,594 - INFO - 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad vm uve deleted from opserver 2026-01-22 02:32:41,594 - DEBUG - Verifying the 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad virtual network link through opserver 10.0.0.50 2026-01-22 02:32:41,594 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-machines 2026-01-22 02:32:41,606 - DEBUG - No links retuned for 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad 2026-01-22 02:32:41,606 - INFO - 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad vm uve deleted from opserver 2026-01-22 02:32:41,606 - DEBUG - Verifying the 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad virtual network link through opserver 10.0.0.131 2026-01-22 02:32:41,606 - DEBUG - Requesting: http://10.0.0.131:8081/analytics/uves/virtual-machines 2026-01-22 02:32:41,618 - DEBUG - No links retuned for 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad 2026-01-22 02:32:41,618 - INFO - 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad vm uve deleted from opserver 2026-01-22 02:32:41,618 - INFO - Validated that VM 4919c2ec-3a13-4db1-a3a5-8d287f2a41ad is removed from Opserver 2026-01-22 02:32:41,618 - DEBUG - Detaching the Policy for VN :ctest-VN2-77208543 2026-01-22 02:32:41,618 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:32:41,625 - DEBUG - Requesting: http://10.0.0.38:8082/domain/43eea14e-faa6-4579-85d2-09c8cc682740 2026-01-22 02:32:41,637 - DEBUG - Requesting: http://10.0.0.38:8082/project/72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:32:41,734 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/08dfa1db-d1a7-4962-8510-7fe335322957 2026-01-22 02:32:41,755 - DEBUG - Requesting: http://10.0.0.38:8082/network-policy/f4bd2e1e-3dbf-48a4-8143-d9476d7bf0e1 2026-01-22 02:32:41,962 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ctest-policy21-42116024']] from ctest-VN2-77208543 2026-01-22 02:32:41,962 - DEBUG - Detaching the Policy for VN :ctest-VN1-37730507 2026-01-22 02:32:41,962 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:32:41,975 - DEBUG - Requesting: http://10.0.0.38:8082/domain/43eea14e-faa6-4579-85d2-09c8cc682740 2026-01-22 02:32:41,997 - DEBUG - Requesting: http://10.0.0.38:8082/project/72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:32:42,091 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/effbbcd1-6185-458d-9887-f1b3126be13b 2026-01-22 02:32:42,106 - DEBUG - Requesting: http://10.0.0.38:8082/network-policy/0a47b74e-87d8-4060-81d6-68090b815cb3 2026-01-22 02:32:42,115 - DEBUG - Requesting: http://10.0.0.38:8082/network-policy/b5dda908-f957-44b5-bbd2-394a1d2e87e4 2026-01-22 02:32:42,264 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ctest-policy12-30354641'], ['default-domain', 'ctest-TestPolicyAclIpv4v6-93935683', 'ctest-policy13-44091123']] from ctest-VN1-37730507 2026-01-22 02:32:42,303 - INFO - Deleted policy ctest-policy21-42116024 2026-01-22 02:32:42,339 - INFO - Deleted policy ctest-policy12-30354641 2026-01-22 02:32:42,374 - INFO - Deleted policy ctest-policy13-44091123 2026-01-22 02:32:42,374 - INFO - Deleting VN ctest-VN3-45142121 2026-01-22 02:32:42,630 - DEBUG - Response for deleting network () 2026-01-22 02:32:42,630 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/0fcdcf27-4bf0-46d0-9ae9-c808ae8e2b07 2026-01-22 02:32:42,640 - DEBUG - Response Code: 404 2026-01-22 02:32:42,640 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:32:42,646 - DEBUG - Requesting: http://10.0.0.38:8082/domain/43eea14e-faa6-4579-85d2-09c8cc682740 2026-01-22 02:32:42,657 - DEBUG - Requesting: http://10.0.0.38:8082/project/72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:32:42,755 - INFO - Validated that VN ctest-VN3-45142121 is not found in API Server 2026-01-22 02:32:42,769 - DEBUG - VN ctest-VN3-45142121 is not present in Agent 10.0.0.23 2026-01-22 02:32:42,780 - DEBUG - VN ctest-VN3-45142121 is not present in Agent 10.0.0.141 2026-01-22 02:32:42,780 - INFO - Validated that VN ctest-VN3-45142121 is not in any agent 2026-01-22 02:32:42,812 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-45142121 info 2026-01-22 02:32:42,812 - INFO - Deleting VN ctest-VN2-77208543 2026-01-22 02:32:43,011 - DEBUG - Response for deleting network () 2026-01-22 02:32:43,011 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/3d174095-5a17-41d5-bfbc-5dec19417f4d 2026-01-22 02:32:43,023 - DEBUG - Response Code: 404 2026-01-22 02:32:43,023 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:32:43,029 - DEBUG - Requesting: http://10.0.0.38:8082/domain/43eea14e-faa6-4579-85d2-09c8cc682740 2026-01-22 02:32:43,080 - DEBUG - Requesting: http://10.0.0.38:8082/project/72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:32:43,173 - INFO - Validated that VN ctest-VN2-77208543 is not found in API Server 2026-01-22 02:32:43,184 - DEBUG - VN ctest-VN2-77208543 is not present in Agent 10.0.0.23 2026-01-22 02:32:43,194 - DEBUG - VN ctest-VN2-77208543 is not present in Agent 10.0.0.141 2026-01-22 02:32:43,194 - INFO - Validated that VN ctest-VN2-77208543 is not in any agent 2026-01-22 02:32:43,225 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-77208543 info 2026-01-22 02:32:43,225 - INFO - Deleting VN ctest-VN1-37730507 2026-01-22 02:32:43,386 - DEBUG - Response for deleting network () 2026-01-22 02:32:43,386 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/71507b6d-6205-4655-8c4b-4a8e148a90e9 2026-01-22 02:32:43,391 - DEBUG - Response Code: 404 2026-01-22 02:32:43,391 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2026-01-22 02:32:43,396 - DEBUG - Requesting: http://10.0.0.38:8082/domain/43eea14e-faa6-4579-85d2-09c8cc682740 2026-01-22 02:32:43,412 - DEBUG - Requesting: http://10.0.0.38:8082/project/72dd62db-1b20-4f20-8657-1f257294a7dc 2026-01-22 02:32:43,504 - INFO - Validated that VN ctest-VN1-37730507 is not found in API Server 2026-01-22 02:32:43,516 - DEBUG - VN ctest-VN1-37730507 is not present in Agent 10.0.0.23 2026-01-22 02:32:43,526 - DEBUG - VN ctest-VN1-37730507 is not present in Agent 10.0.0.141 2026-01-22 02:32:43,526 - INFO - Validated that VN ctest-VN1-37730507 is not in any agent 2026-01-22 02:32:43,554 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-37730507 info 2026-01-22 02:32:43,642 - INFO - IPAM: ipam3 is not found in API Server 2026-01-22 02:32:43,666 - INFO - IPAM:ipam3 is not found in control node 2026-01-22 02:32:43,807 - INFO - IPAM: ipam2 is not found in API Server 2026-01-22 02:32:43,834 - INFO - IPAM:ipam2 is not found in control node 2026-01-22 02:32:43,982 - INFO - IPAM: ipam1 is not found in API Server 2026-01-22 02:32:44,006 - INFO - IPAM:ipam1 is not found in control node 2026-01-22 02:32:45,280 - DEBUG - No XMPP flaps were noticed during the test

2026-01-22 02:32:45,280 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:03:26]

2026-01-22 02:32:45,280 - INFO - -------------------------------------------------------------------------------- 2026-01-22 02:32:46,576 - DEBUG - No XMPP flaps were noticed during the test

2026-01-22 02:32:46,576 - INFO - END TEST : test_policy_inheritance_src_pol_dst_any : PASSED[0:03:29]

2026-01-22 02:32:46,576 - INFO - -------------------------------------------------------------------------------- 2026-01-22 02:32:47,301 - INFO - Deleted project: ctest-TestPolicyAclIpv4v6-93935683, ID : 72dd62db-1b20-4f20-8657-1f257294a7dc