2026-08-17 02:56:52,816 - INFO - Domain Default found not creating 2026-08-17 02:56:52,997 - INFO - Project ctest-TestPolicyAcl-42565280 not found, creating it 2026-08-17 02:56:53,586 - INFO - Created Project:ctest-TestPolicyAcl-42565280, ID : 0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 02:56:55,321 - INFO - ================================================================================ 2026-08-17 02:56:55,321 - INFO - STARTING TEST : test_policy_inheritance_src_vn_dst_pol 2026-08-17 02:56:55,322 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance 2026-08-17 02:56:56,604 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.14': '0', '10.20.0.254': '0'}, '10.0.0.186': {'10.20.0.14': '0', '10.20.0.254': '0'}} with 2026-08-17 02:56:56,604 - INFO - Initial checks done. Running the testcase now 2026-08-17 02:56:56,604 - INFO - 2026-08-17 02:56:58,024 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 02:56:58,416 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 02:56:58,455 - DEBUG - Requesting: http://10.0.0.177:8082/domain/2b2edc1c-1b78-4df2-9472-fa661dfea482 2026-08-17 02:56:58,473 - DEBUG - Requesting: http://10.0.0.177:8082/project/0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 02:56:58,597 - DEBUG - Requesting: http://10.0.0.177:8082/network-ipam/131a4325-30bd-4acb-92c9-3b1b9a9a6c2b 2026-08-17 02:56:58,607 - INFO - Verifications in API Server for IPAM: ipam1 passed 2026-08-17 02:56:58,613 - DEBUG - Control-node 10.0.0.177 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-42565280:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1376486463125670603', 'uuid-lslong': '10577050189600287787'}, 'enable': 'true', 'created': '2026-08-17T02:56:57', 'last-modified': '2026-08-17T02:56:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.292717'} 2026-08-17 02:56:58,619 - DEBUG - Control-node 10.0.0.190 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-42565280:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1376486463125670603', 'uuid-lslong': '10577050189600287787'}, 'enable': 'true', 'created': '2026-08-17T02:56:57', 'last-modified': '2026-08-17T02:56:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.299418'} 2026-08-17 02:56:58,625 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-42565280:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1376486463125670603', 'uuid-lslong': '10577050189600287787'}, 'enable': 'true', 'created': '2026-08-17T02:56:57', 'last-modified': '2026-08-17T02:56:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.309985'} 2026-08-17 02:56:58,626 - INFO - Verifications in Control node for IPAM: ipam1 passed 2026-08-17 02:56:58,626 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 02:56:58,636 - DEBUG - Requesting: http://10.0.0.177:8082/domain/2b2edc1c-1b78-4df2-9472-fa661dfea482 2026-08-17 02:56:58,652 - DEBUG - Requesting: http://10.0.0.177:8082/project/0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 02:56:58,764 - DEBUG - Requesting: http://10.0.0.177:8082/network-ipam/206e96fb-a891-4d6f-a741-7f2e1c2115e7 2026-08-17 02:56:58,775 - INFO - Verifications in API Server for IPAM: ipam2 passed 2026-08-17 02:56:58,783 - DEBUG - Control-node 10.0.0.177 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-42565280:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2336971264260918639', 'uuid-lslong': '12052053913837311463'}, 'enable': 'true', 'created': '2026-08-17T02:56:57', 'last-modified': '2026-08-17T02:56:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.116020'} 2026-08-17 02:56:58,791 - DEBUG - Control-node 10.0.0.190 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-42565280:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2336971264260918639', 'uuid-lslong': '12052053913837311463'}, 'enable': 'true', 'created': '2026-08-17T02:56:57', 'last-modified': '2026-08-17T02:56:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.132421'} 2026-08-17 02:56:58,798 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-42565280:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2336971264260918639', 'uuid-lslong': '12052053913837311463'}, 'enable': 'true', 'created': '2026-08-17T02:56:57', 'last-modified': '2026-08-17T02:56:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.132571'} 2026-08-17 02:56:58,798 - INFO - Verifications in Control node for IPAM: ipam2 passed 2026-08-17 02:56:58,798 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 02:56:58,807 - DEBUG - Requesting: http://10.0.0.177:8082/domain/2b2edc1c-1b78-4df2-9472-fa661dfea482 2026-08-17 02:56:58,823 - DEBUG - Requesting: http://10.0.0.177:8082/project/0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 02:56:58,932 - DEBUG - Requesting: http://10.0.0.177:8082/network-ipam/76131508-a7f7-44d4-b764-ac5cecb06204 2026-08-17 02:56:58,946 - INFO - Verifications in API Server for IPAM: ipam3 passed 2026-08-17 02:56:58,953 - DEBUG - Control-node 10.0.0.177 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-42565280:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8508167247954920660', 'uuid-lslong': '13214876721719828996'}, 'enable': 'true', 'created': '2026-08-17T02:56:57', 'last-modified': '2026-08-17T02:56:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.959755'} 2026-08-17 02:56:58,959 - DEBUG - Control-node 10.0.0.190 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-42565280:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8508167247954920660', 'uuid-lslong': '13214876721719828996'}, 'enable': 'true', 'created': '2026-08-17T02:56:57', 'last-modified': '2026-08-17T02:56:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.965929'} 2026-08-17 02:56:58,966 - DEBUG - Control-node 10.0.0.38 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-42565280:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8508167247954920660', 'uuid-lslong': '13214876721719828996'}, 'enable': 'true', 'created': '2026-08-17T02:56:57', 'last-modified': '2026-08-17T02:56:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.964407'} 2026-08-17 02:56:58,966 - INFO - Verifications in Control node for IPAM: ipam3 passed 2026-08-17 02:56:59,326 - DEBUG - Response for create_network : {'network': {'id': '57f40c0a-fdbf-459b-988f-df5d2fbcb901', 'name': 'ctest-VN1-32761542', 'tenant_id': '0c16a75bca524b84837287b3a46aa0ab', 'project_id': '0c16a75bca524b84837287b3a46aa0ab', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-42565280', 'ctest-VN1-32761542'], 'port_security_enabled': True, 'description': ''}} 2026-08-17 02:56:59,683 - DEBUG - Response for create_subnet : {'subnet': {'id': '52eff178-dd71-4635-8901-db11c822effc', 'name': '', 'tenant_id': '0c16a75bca524b84837287b3a46aa0ab', 'network_id': '57f40c0a-fdbf-459b-988f-df5d2fbcb901', 'ip_version': 4, 'cidr': '35.51.71.128/26', 'allocation_pools': [{'start': '35.51.71.130', 'end': '35.51.71.190'}], 'gateway_ip': '35.51.71.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '35.51.71.130', 'tags': [], 'project_id': '0c16a75bca524b84837287b3a46aa0ab'}} 2026-08-17 02:56:59,717 - INFO - Created VN ctest-VN1-32761542 2026-08-17 02:56:59,732 - DEBUG - VN ctest-VN1-32761542 UUID is 57f40c0a-fdbf-459b-988f-df5d2fbcb901 2026-08-17 02:56:59,915 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542(57f40c0a-fdbf-459b-988f-df5d2fbcb901) with subnets ['35.51.71.128/26'] 2026-08-17 02:57:00,270 - DEBUG - Response for create_network : {'network': {'id': 'e93fa069-c622-4321-9ec3-a167b453b336', 'name': 'ctest-VN2-82822838', 'tenant_id': '0c16a75bca524b84837287b3a46aa0ab', 'project_id': '0c16a75bca524b84837287b3a46aa0ab', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-42565280', 'ctest-VN2-82822838'], 'port_security_enabled': True, 'description': ''}} 2026-08-17 02:57:00,615 - DEBUG - Response for create_subnet : {'subnet': {'id': '23f53ea9-167b-46d5-a2d7-41da9cd95f54', 'name': '', 'tenant_id': '0c16a75bca524b84837287b3a46aa0ab', 'network_id': 'e93fa069-c622-4321-9ec3-a167b453b336', 'ip_version': 4, 'cidr': '191.9.167.64/26', 'allocation_pools': [{'start': '191.9.167.66', 'end': '191.9.167.126'}], 'gateway_ip': '191.9.167.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '191.9.167.66', 'tags': [], 'project_id': '0c16a75bca524b84837287b3a46aa0ab'}} 2026-08-17 02:57:00,644 - INFO - Created VN ctest-VN2-82822838 2026-08-17 02:57:00,668 - DEBUG - VN ctest-VN2-82822838 UUID is e93fa069-c622-4321-9ec3-a167b453b336 2026-08-17 02:57:01,252 - DEBUG - Response for create_network : {'network': {'id': '3d78f871-c6b8-45c0-b95d-c423c6f89507', 'name': 'ctest-VN3-47714290', 'tenant_id': '0c16a75bca524b84837287b3a46aa0ab', 'project_id': '0c16a75bca524b84837287b3a46aa0ab', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-42565280', 'ctest-VN3-47714290'], 'port_security_enabled': True, 'description': ''}} 2026-08-17 02:57:01,591 - DEBUG - Response for create_subnet : {'subnet': {'id': '7c2ab601-af63-410e-ab5e-ed4b951ff460', 'name': '', 'tenant_id': '0c16a75bca524b84837287b3a46aa0ab', 'network_id': '3d78f871-c6b8-45c0-b95d-c423c6f89507', 'ip_version': 4, 'cidr': '154.232.220.64/26', 'allocation_pools': [{'start': '154.232.220.66', 'end': '154.232.220.126'}], 'gateway_ip': '154.232.220.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '154.232.220.66', 'tags': [], 'project_id': '0c16a75bca524b84837287b3a46aa0ab'}} 2026-08-17 02:57:01,621 - INFO - Created VN ctest-VN3-47714290 2026-08-17 02:57:01,636 - DEBUG - VN ctest-VN3-47714290 UUID is 3d78f871-c6b8-45c0-b95d-c423c6f89507 2026-08-17 02:57:01,731 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 02:57:01,740 - DEBUG - Requesting: http://10.0.0.177:8082/domain/2b2edc1c-1b78-4df2-9472-fa661dfea482 2026-08-17 02:57:01,760 - DEBUG - Requesting: http://10.0.0.177:8082/project/0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 02:57:01,878 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/57f40c0a-fdbf-459b-988f-df5d2fbcb901 2026-08-17 02:57:01,897 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/57f40c0a-fdbf-459b-988f-df5d2fbcb901 2026-08-17 02:57:01,912 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/e7cdeaf5-e121-42e0-bcb4-513676b846bb 2026-08-17 02:57:01,926 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/e7cdeaf5-e121-42e0-bcb4-513676b846bb 2026-08-17 02:57:01,937 - DEBUG - Requesting: http://10.0.0.177:8082/route-target/b306f4c1-a6ab-46d9-a90b-772fa661a95e 2026-08-17 02:57:01,948 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-08-17 02:57:01,948 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/57f40c0a-fdbf-459b-988f-df5d2fbcb901 2026-08-17 02:57:01,960 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/e7cdeaf5-e121-42e0-bcb4-513676b846bb 2026-08-17 02:57:01,979 - INFO - Verified VN network id 15 for VN 57f40c0a-fdbf-459b-988f-df5d2fbcb901 2026-08-17 02:57:01,980 - INFO - Verifications in API Server for VN ctest-VN1-32761542 passed 2026-08-17 02:57:01,980 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/57f40c0a-fdbf-459b-988f-df5d2fbcb901 2026-08-17 02:57:01,993 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/e7cdeaf5-e121-42e0-bcb4-513676b846bb 2026-08-17 02:57:02,004 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/e7cdeaf5-e121-42e0-bcb4-513676b846bb 2026-08-17 02:57:02,015 - DEBUG - Requesting: http://10.0.0.177:8082/route-target/b306f4c1-a6ab-46d9-a90b-772fa661a95e 2026-08-17 02:57:02,031 - DEBUG - Control-node 10.0.0.177 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6337703816963507611', 'uuid-lslong': '10993250806760519937'}, 'enable': 'true', 'created': '2026-08-17T02:56:59', 'last-modified': '2026-08-17T02:56:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.427468'} 2026-08-17 02:57:02,037 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-08-17 02:57:02,043 - DEBUG - Control-node 10.0.0.190 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6337703816963507611', 'uuid-lslong': '10993250806760519937'}, 'enable': 'true', 'created': '2026-08-17T02:56:59', 'last-modified': '2026-08-17T02:56:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.435941'} 2026-08-17 02:57:02,050 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-08-17 02:57:02,056 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6337703816963507611', 'uuid-lslong': '10993250806760519937'}, 'enable': 'true', 'created': '2026-08-17T02:56:59', 'last-modified': '2026-08-17T02:56:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.452273'} 2026-08-17 02:57:02,062 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-08-17 02:57:02,062 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-32761542 passed 2026-08-17 02:57:02,062 - DEBUG - ====Verifying policy data for ctest-VN1-32761542 in API_Server ====== 2026-08-17 02:57:02,063 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 02:57:02,070 - DEBUG - Requesting: http://10.0.0.177:8082/domain/2b2edc1c-1b78-4df2-9472-fa661dfea482 2026-08-17 02:57:02,088 - DEBUG - Requesting: http://10.0.0.177:8082/project/0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 02:57:02,199 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/57f40c0a-fdbf-459b-988f-df5d2fbcb901 2026-08-17 02:57:02,212 - DEBUG - =>VN ctest-VN1-32761542 has no policy to be verified 2026-08-17 02:57:02,212 - DEBUG - Verifying the vn in opserver 2026-08-17 02:57:02,212 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 virtual network link through opserver 10.0.0.177 2026-08-17 02:57:02,212 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-networks 2026-08-17 02:57:02,611 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-networks 2026-08-17 02:57:02,752 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542', 'href': 'http://10.0.0.177:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542?flat'} 2026-08-17 02:57:02,752 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 virtual network link through opserver 10.0.0.190 2026-08-17 02:57:02,752 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-networks 2026-08-17 02:57:03,143 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-networks 2026-08-17 02:57:03,209 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542', 'href': 'http://10.0.0.190:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542?flat'} 2026-08-17 02:57:03,209 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 virtual network link through opserver 10.0.0.38 2026-08-17 02:57:03,209 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-08-17 02:57:03,597 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-08-17 02:57:03,656 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542?flat'} 2026-08-17 02:57:03,656 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 is found in opserver 2026-08-17 02:57:03,679 - DEBUG - Do not have enough data to verify VN in agent 2026-08-17 02:57:03,691 - DEBUG - VRF ids for VN ctest-VN1-32761542: {} 2026-08-17 02:57:03,691 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 02:57:03,698 - DEBUG - Requesting: http://10.0.0.177:8082/domain/2b2edc1c-1b78-4df2-9472-fa661dfea482 2026-08-17 02:57:03,715 - DEBUG - Requesting: http://10.0.0.177:8082/project/0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 02:57:03,824 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/e93fa069-c622-4321-9ec3-a167b453b336 2026-08-17 02:57:03,840 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/e93fa069-c622-4321-9ec3-a167b453b336 2026-08-17 02:57:03,851 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/0996b0a1-20a3-4fb5-a4f9-78472efbfc23 2026-08-17 02:57:03,860 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/0996b0a1-20a3-4fb5-a4f9-78472efbfc23 2026-08-17 02:57:03,868 - DEBUG - Requesting: http://10.0.0.177:8082/route-target/5d962ec1-d6d4-49f8-b7f1-6a59cc365c70 2026-08-17 02:57:03,877 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-08-17 02:57:03,877 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/e93fa069-c622-4321-9ec3-a167b453b336 2026-08-17 02:57:03,886 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/0996b0a1-20a3-4fb5-a4f9-78472efbfc23 2026-08-17 02:57:03,905 - INFO - Verified VN network id 16 for VN e93fa069-c622-4321-9ec3-a167b453b336 2026-08-17 02:57:03,906 - INFO - Verifications in API Server for VN ctest-VN2-82822838 passed 2026-08-17 02:57:03,906 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/e93fa069-c622-4321-9ec3-a167b453b336 2026-08-17 02:57:03,916 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/0996b0a1-20a3-4fb5-a4f9-78472efbfc23 2026-08-17 02:57:03,926 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/0996b0a1-20a3-4fb5-a4f9-78472efbfc23 2026-08-17 02:57:03,934 - DEBUG - Requesting: http://10.0.0.177:8082/route-target/5d962ec1-d6d4-49f8-b7f1-6a59cc365c70 2026-08-17 02:57:03,949 - DEBUG - Control-node 10.0.0.177 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16807328710526124833', 'uuid-lslong': '11440164945230279478'}, 'enable': 'true', 'created': '2026-08-17T02:57:00', 'last-modified': '2026-08-17T02:57:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.401525'} 2026-08-17 02:57:03,955 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-08-17 02:57:03,961 - DEBUG - Control-node 10.0.0.190 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16807328710526124833', 'uuid-lslong': '11440164945230279478'}, 'enable': 'true', 'created': '2026-08-17T02:57:00', 'last-modified': '2026-08-17T02:57:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.423306'} 2026-08-17 02:57:03,969 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-08-17 02:57:03,975 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16807328710526124833', 'uuid-lslong': '11440164945230279478'}, 'enable': 'true', 'created': '2026-08-17T02:57:00', 'last-modified': '2026-08-17T02:57:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.436304'} 2026-08-17 02:57:03,982 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-08-17 02:57:03,982 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-82822838 passed 2026-08-17 02:57:03,982 - DEBUG - ====Verifying policy data for ctest-VN2-82822838 in API_Server ====== 2026-08-17 02:57:03,982 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 02:57:03,989 - DEBUG - Requesting: http://10.0.0.177:8082/domain/2b2edc1c-1b78-4df2-9472-fa661dfea482 2026-08-17 02:57:04,004 - DEBUG - Requesting: http://10.0.0.177:8082/project/0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 02:57:04,109 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/e93fa069-c622-4321-9ec3-a167b453b336 2026-08-17 02:57:04,123 - DEBUG - =>VN ctest-VN2-82822838 has no policy to be verified 2026-08-17 02:57:04,124 - DEBUG - Verifying the vn in opserver 2026-08-17 02:57:04,124 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838 virtual network link through opserver 10.0.0.177 2026-08-17 02:57:04,124 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-networks 2026-08-17 02:57:04,182 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838', 'href': 'http://10.0.0.177:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838?flat'} 2026-08-17 02:57:04,182 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838 virtual network link through opserver 10.0.0.190 2026-08-17 02:57:04,182 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-networks 2026-08-17 02:57:04,234 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838', 'href': 'http://10.0.0.190:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838?flat'} 2026-08-17 02:57:04,234 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838 virtual network link through opserver 10.0.0.38 2026-08-17 02:57:04,234 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-08-17 02:57:04,287 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838?flat'} 2026-08-17 02:57:04,288 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838 is found in opserver 2026-08-17 02:57:04,309 - DEBUG - Do not have enough data to verify VN in agent 2026-08-17 02:57:04,321 - DEBUG - VRF ids for VN ctest-VN2-82822838: {} 2026-08-17 02:57:04,321 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 02:57:04,327 - DEBUG - Requesting: http://10.0.0.177:8082/domain/2b2edc1c-1b78-4df2-9472-fa661dfea482 2026-08-17 02:57:04,345 - DEBUG - Requesting: http://10.0.0.177:8082/project/0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 02:57:04,450 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/3d78f871-c6b8-45c0-b95d-c423c6f89507 2026-08-17 02:57:04,463 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/3d78f871-c6b8-45c0-b95d-c423c6f89507 2026-08-17 02:57:04,481 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/f0be616e-e508-4454-87a5-6d992bce869c 2026-08-17 02:57:04,492 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/f0be616e-e508-4454-87a5-6d992bce869c 2026-08-17 02:57:04,501 - DEBUG - Requesting: http://10.0.0.177:8082/route-target/38916ee5-5384-4c74-ade3-56bf48e385fe 2026-08-17 02:57:04,512 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-08-17 02:57:04,512 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/3d78f871-c6b8-45c0-b95d-c423c6f89507 2026-08-17 02:57:04,529 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/f0be616e-e508-4454-87a5-6d992bce869c 2026-08-17 02:57:04,549 - INFO - Verified VN network id 17 for VN 3d78f871-c6b8-45c0-b95d-c423c6f89507 2026-08-17 02:57:04,549 - INFO - Verifications in API Server for VN ctest-VN3-47714290 passed 2026-08-17 02:57:04,549 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/3d78f871-c6b8-45c0-b95d-c423c6f89507 2026-08-17 02:57:04,561 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/f0be616e-e508-4454-87a5-6d992bce869c 2026-08-17 02:57:04,571 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/f0be616e-e508-4454-87a5-6d992bce869c 2026-08-17 02:57:04,581 - DEBUG - Requesting: http://10.0.0.177:8082/route-target/38916ee5-5384-4c74-ade3-56bf48e385fe 2026-08-17 02:57:04,598 - DEBUG - Control-node 10.0.0.177 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4429563401067840960', 'uuid-lslong': '13357047727791838471'}, 'enable': 'true', 'created': '2026-08-17T02:57:01', 'last-modified': '2026-08-17T02:57:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.089879'} 2026-08-17 02:57:04,611 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-08-17 02:57:04,617 - DEBUG - Control-node 10.0.0.190 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4429563401067840960', 'uuid-lslong': '13357047727791838471'}, 'enable': 'true', 'created': '2026-08-17T02:57:01', 'last-modified': '2026-08-17T02:57:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.110842'} 2026-08-17 02:57:04,624 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-08-17 02:57:04,631 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-42565280', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '4429563401067840960', 'uuid-lslong': '13357047727791838471'}, 'enable': 'true', 'created': '2026-08-17T02:57:01', 'last-modified': '2026-08-17T02:57:01', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.112664'} 2026-08-17 02:57:04,639 - DEBUG - Route Targets: ['target:64512:8000007'] 2026-08-17 02:57:04,640 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-47714290 passed 2026-08-17 02:57:04,640 - DEBUG - ====Verifying policy data for ctest-VN3-47714290 in API_Server ====== 2026-08-17 02:57:04,640 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 02:57:04,648 - DEBUG - Requesting: http://10.0.0.177:8082/domain/2b2edc1c-1b78-4df2-9472-fa661dfea482 2026-08-17 02:57:04,662 - DEBUG - Requesting: http://10.0.0.177:8082/project/0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 02:57:04,777 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/3d78f871-c6b8-45c0-b95d-c423c6f89507 2026-08-17 02:57:04,789 - DEBUG - =>VN ctest-VN3-47714290 has no policy to be verified 2026-08-17 02:57:04,789 - DEBUG - Verifying the vn in opserver 2026-08-17 02:57:04,789 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290 virtual network link through opserver 10.0.0.177 2026-08-17 02:57:04,789 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-networks 2026-08-17 02:57:04,856 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290', 'href': 'http://10.0.0.177:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290?flat'} 2026-08-17 02:57:04,856 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290 virtual network link through opserver 10.0.0.190 2026-08-17 02:57:04,856 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-networks 2026-08-17 02:57:04,912 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290', 'href': 'http://10.0.0.190:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290?flat'} 2026-08-17 02:57:04,912 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290 virtual network link through opserver 10.0.0.38 2026-08-17 02:57:04,912 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2026-08-17 02:57:04,967 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290?flat'} 2026-08-17 02:57:04,967 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290 is found in opserver 2026-08-17 02:57:04,990 - DEBUG - Do not have enough data to verify VN in agent 2026-08-17 02:57:05,001 - DEBUG - VRF ids for VN ctest-VN3-47714290: {} 2026-08-17 02:57:05,009 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542, 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-TestPolicyAcl-42565280:ctest-VN2-82822838, 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-08-17 02:57:05,292 - DEBUG - No destination network defined 2026-08-17 02:57:05,293 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = -1, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = None, security_group = None, network_policy = default-domain:ctest-TestPolicyAcl-42565280:ctest-policy13-88634337, 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-08-17 02:57:05,576 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542, 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-TestPolicyAcl-42565280:ctest-VN3-47714290, 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-08-17 02:57:05,812 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 02:57:05,820 - DEBUG - Requesting: http://10.0.0.177:8082/domain/2b2edc1c-1b78-4df2-9472-fa661dfea482 2026-08-17 02:57:05,833 - DEBUG - Requesting: http://10.0.0.177:8082/project/0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 02:57:05,952 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/57f40c0a-fdbf-459b-988f-df5d2fbcb901 2026-08-17 02:57:05,963 - DEBUG - Setup step: Associating the policy to VN' 2026-08-17 02:57:06,201 - INFO - Associated Policy to ctest-VN1-32761542 2026-08-17 02:57:06,201 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 02:57:06,211 - DEBUG - Requesting: http://10.0.0.177:8082/domain/2b2edc1c-1b78-4df2-9472-fa661dfea482 2026-08-17 02:57:06,274 - DEBUG - Requesting: http://10.0.0.177:8082/project/0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 02:57:06,392 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/e93fa069-c622-4321-9ec3-a167b453b336 2026-08-17 02:57:06,404 - DEBUG - Setup step: Associating the policy to VN' 2026-08-17 02:57:06,590 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'e93fa069-c622-4321-9ec3-a167b453b336', 'name': 'ctest-VN2-82822838', 'tenant_id': '0c16a75bca524b84837287b3a46aa0ab', 'project_id': '0c16a75bca524b84837287b3a46aa0ab', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['23f53ea9-167b-46d5-a2d7-41da9cd95f54'], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-42565280', 'ctest-VN2-82822838'], 'policys': [['default-domain', 'ctest-TestPolicyAcl-42565280', 'ctest-policy21-68920213']], 'subnet_ipam': [{'subnet_cidr': '191.9.167.64/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAcl-42565280', 'ipam2']}], 'port_security_enabled': True, 'description': ''}} 2026-08-17 02:57:06,747 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAcl-42565280', 'ctest-policy21-68920213']] to ctest-VN2-82822838 2026-08-17 02:57:06,870 - DEBUG - Services list from nova: [, , , ] 2026-08-17 02:57:07,434 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6167-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6167-1) 2026-08-17 02:57:08,017 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6167-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6167-2) 2026-08-17 02:57:08,664 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6167-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6167-1) 2026-08-17 02:57:08,733 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 02:57:13,797 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 02:57:18,891 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 02:57:23,981 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 02:57:29,083 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 02:57:34,167 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 02:57:39,268 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 02:57:44,410 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 02:57:49,519 - DEBUG - VM is in ACTIVE state now 2026-08-17 02:57:49,520 - INFO - VM name : ctest-VM11-67228162 2026-08-17 02:57:49,617 - DEBUG - VM ctest-VM11-67228162 ID is da67b236-d1c1-44d2-ad37-3198cccdca22 2026-08-17 02:57:49,718 - DEBUG - VM is in ACTIVE state now 2026-08-17 02:57:49,748 - DEBUG - VM ctest-VM11-67228162 launched on Node an-jenkins-deploy-platform-ansible-os-6167-1 2026-08-17 02:57:49,842 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22 2026-08-17 02:57:50,214 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22 2026-08-17 02:57:50,248 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22 2026-08-17 02:57:50,257 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/a965d206-965c-44e7-a7a8-ba4296676439 2026-08-17 02:57:50,267 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22 2026-08-17 02:57:50,275 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/a965d206-965c-44e7-a7a8-ba4296676439 2026-08-17 02:57:50,286 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/56df87a8-1aec-4a63-9926-0d38404b226a 2026-08-17 02:57:50,299 - DEBUG - Verifying in api server 10.0.0.38 2026-08-17 02:57:50,299 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22 2026-08-17 02:57:50,309 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/a965d206-965c-44e7-a7a8-ba4296676439 2026-08-17 02:57:50,324 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/56df87a8-1aec-4a63-9926-0d38404b226a 2026-08-17 02:57:50,335 - INFO - VM ctest-VM11-67228162 verfication in all API Servers passed 2026-08-17 02:57:50,363 - DEBUG - VM ctest-VM11-67228162 Tap interface: {'index': '3', 'name': 'tapa965d206-96', 'uuid': 'a965d206-965c-44e7-a7a8-ba4296676439', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542:ctest-VN1-32761542', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '27', 'l2_label': '31', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542', 'vm_uuid': 'da67b236-d1c1-44d2-ad37-3198cccdca22', 'vm_name': 'ctest-VM11-67228162', 'ip_addr': '35.51.71.131', 'mac_addr': '02:a9:65:d2:06:96', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '32', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-42565280:a965d206-965c-44e7-a7a8-ba4296676439', 'sg_uuid_list': ['17c6e137-0e46-4b46-a034-b4885fb08a9a'], 'static_route_list': None, 'vm_project_uuid': '0c16a75b-ca52-4b84-8372-87b3a46aa0ab', 'admin_state': 'Enabled', 'flow_key_idx': '34', 'allowed_address_pair_list': None, 'ip6_addr': '::', '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-08-17 02:57:50,363 - DEBUG - Agent 10.0.0.50 vrf name: default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542:ctest-VN1-32761542 2026-08-17 02:57:50,373 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542:ctest-VN1-32761542', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542', 'table_label': '-1', 'vxlan_id': '15', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.29:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-08-17 02:57:50,457 - DEBUG - VM ctest-VM11-67228162 : Tap interface tapa965d206-96 is set to Active 2026-08-17 02:57:50,457 - DEBUG - Tap interface tapa965d206-96 detail : {'index': '3', 'name': 'tapa965d206-96', 'uuid': 'a965d206-965c-44e7-a7a8-ba4296676439', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542:ctest-VN1-32761542', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '27', 'l2_label': '31', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542', 'vm_uuid': 'da67b236-d1c1-44d2-ad37-3198cccdca22', 'vm_name': 'ctest-VM11-67228162', 'ip_addr': '35.51.71.131', 'mac_addr': '02:a9:65:d2:06:96', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '32', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-42565280:a965d206-965c-44e7-a7a8-ba4296676439', 'sg_uuid_list': ['17c6e137-0e46-4b46-a034-b4885fb08a9a'], 'static_route_list': None, 'vm_project_uuid': '0c16a75b-ca52-4b84-8372-87b3a46aa0ab', 'admin_state': 'Enabled', 'flow_key_idx': '34', 'allowed_address_pair_list': None, 'ip6_addr': '::', '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-08-17 02:57:50,574 - DEBUG - Starting Layer 2 verification in Agent 2026-08-17 02:57:50,593 - DEBUG - Layer 2 path is seen for VM MAC 02:a9:65:d2:06:96 in agent 10.0.0.50 2026-08-17 02:57:50,593 - DEBUG - Active layer 2 route in agent is present for VMI tapa965d206-96 2026-08-17 02:57:50,593 - DEBUG - L2 label(31) matches bw route table and interface table 2026-08-17 02:57:50,606 - DEBUG - VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 is found in Agent of node 10.0.0.50 2026-08-17 02:57:50,742 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 is consistent in agent 10.0.0.50 2026-08-17 02:57:50,742 - DEBUG - Route for VM IP ['35.51.71.131'] is consistent in agent 10.0.0.50 2026-08-17 02:57:50,743 - DEBUG - VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 verification for VM ctest-VM11-67228162 in Agent 10.0.0.50 passed 2026-08-17 02:57:50,858 - DEBUG - Starting all layer 2 verification in agent 10.0.0.50 2026-08-17 02:57:50,869 - DEBUG - Route for VM MAC 02:a9:65:d2:06:96 is consistent in agent 10.0.0.50 2026-08-17 02:57:54,073 - 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-08-17 02:57:54,074 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-67228162 failed! 2026-08-17 02:57:54,090 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 is 35.51.71.129 and allocation pool is NOT set 2026-08-17 02:57:58,171 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1012ms') 2026-08-17 02:57:58,171 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-67228162 failed! 2026-08-17 02:57:58,187 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 is 35.51.71.129 and allocation pool is NOT set 2026-08-17 02:58:02,267 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms') 2026-08-17 02:58:02,267 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-67228162 failed! 2026-08-17 02:58:02,286 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 is 35.51.71.129 and allocation pool is NOT set 2026-08-17 02:58:06,357 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2026-08-17 02:58:06,357 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-67228162 failed! 2026-08-17 02:58:06,374 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 is 35.51.71.129 and allocation pool is NOT set 2026-08-17 02:58:10,456 - 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-08-17 02:58:10,456 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-67228162 failed! 2026-08-17 02:58:10,474 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 is 35.51.71.129 and allocation pool is NOT set 2026-08-17 02:58:14,555 - 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-08-17 02:58:14,555 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-67228162 failed! 2026-08-17 02:58:14,574 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 is 35.51.71.129 and allocation pool is NOT set 2026-08-17 02:58:18,650 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms') 2026-08-17 02:58:18,650 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-67228162 failed! 2026-08-17 02:58:18,666 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 is 35.51.71.129 and allocation pool is NOT set 2026-08-17 02:58:22,744 - 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=2 ttl=63 time=7.08 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1017ms\r\nrtt min/avg/max/mdev = 7.083/7.083/7.083/0.000 ms') 2026-08-17 02:58:22,745 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-67228162 passed 2026-08-17 02:58:22,745 - INFO - VM ctest-VM11-67228162 verifications in Compute nodes passed 2026-08-17 02:58:23,082 - DEBUG - Validated VM route 35.51.71.131 in vrouter of 10.0.0.50 2026-08-17 02:58:23,090 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-08-17 02:58:23,090 - INFO - Validated routes of VM ctest-VM11-67228162 in all vrouters 2026-08-17 02:58:23,596 - DEBUG - Starting all layer2 verification in 10.0.0.177 Control Node 2026-08-17 02:58:23,680 - DEBUG - Layer2 route found for VM MAC 02:a9:65:d2:06:96 in Control-node 10.0.0.177 2026-08-17 02:58:23,680 - DEBUG - L2 Label for VM ctest-VM11-67228162 same between Control-node 10.0.0.177 and Agent, Expected: 31, Seen: 31 2026-08-17 02:58:23,680 - DEBUG - Starting all layer2 verification in 10.0.0.190 Control Node 2026-08-17 02:58:23,811 - DEBUG - Layer2 route found for VM MAC 02:a9:65:d2:06:96 in Control-node 10.0.0.190 2026-08-17 02:58:23,811 - DEBUG - L2 Label for VM ctest-VM11-67228162 same between Control-node 10.0.0.190 and Agent, Expected: 31, Seen: 31 2026-08-17 02:58:23,811 - INFO - Verification in Control-nodes for VM ctest-VM11-67228162 passed 2026-08-17 02:58:23,811 - DEBUG - Verifying the vm in opserver 2026-08-17 02:58:23,811 - DEBUG - Verifying in collector 10.0.0.38 ... 2026-08-17 02:58:23,811 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22?flat 2026-08-17 02:58:23,830 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-42565280:a965d206-965c-44e7-a7a8-ba4296676439?flat 2026-08-17 02:58:23,852 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-42565280:a965d206-965c-44e7-a7a8-ba4296676439?flat 2026-08-17 02:58:23,874 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22?flat 2026-08-17 02:58:23,894 - DEBUG - Verifying vm in vn uve 2026-08-17 02:58:23,894 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-42565280:a965d206-965c-44e7-a7a8-ba4296676439?flat 2026-08-17 02:58:23,917 - DEBUG - VM uve shows interface as {'is_health_check_active': True, 'ip6_address': '::', 'vn_uuid': '57f40c0a-fdbf-459b-988f-df5d2fbcb901', 'uuid': 'a965d206-965c-44e7-a7a8-ba4296676439', '__T': 1786935479388673, 'admin_state': True, 'ip_address': '35.51.71.131', 'active': True, 'mac_address': '02:a9:65:d2:06:96', 'fixed_ip4_list': ['35.51.71.131'], 'l2_active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542', 'port_mirror_enabled': False, 'ip4_active': True, 'vhostuser_mode': 0, 'label': 27, 'ip6_active': False, 'rx_vlan': 65535, 'gateway': '35.51.71.129', 'vm_name': 'ctest-VM11-67228162', 'tx_vlan': 65535, 'vm_uuid': 'da67b236-d1c1-44d2-ad37-3198cccdca22'} 2026-08-17 02:58:23,917 - DEBUG - VM uve shows ip address as ['35.51.71.131', '::'] 2026-08-17 02:58:23,917 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 2026-08-17 02:58:23,917 - INFO - Verifying through opserver in 10.0.0.177 2026-08-17 02:58:23,918 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542?flat 2026-08-17 02:58:23,948 - DEBUG - expected vm list da67b236-d1c1-44d2-ad37-3198cccdca22 2026-08-17 02:58:23,948 - DEBUG - Extracted vm list ['da67b236-d1c1-44d2-ad37-3198cccdca22'] 2026-08-17 02:58:23,948 - INFO - VM da67b236-d1c1-44d2-ad37-3198cccdca22 is present in default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 2026-08-17 02:58:23,948 - DEBUG - Verifying vm in vrouter uve 2026-08-17 02:58:23,948 - DEBUG - Getting info from collector 10.0.0.38.. 2026-08-17 02:58:23,949 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22?flat 2026-08-17 02:58:23,967 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-6167-1'] 2026-08-17 02:58:23,967 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-1:Compute:contrail-vrouter-agent:0?flat 2026-08-17 02:58:24,005 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-1?flat 2026-08-17 02:58:24,038 - DEBUG - VM da67b236-d1c1-44d2-ad37-3198cccdca22 is present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 02:58:24,039 - DEBUG - Validated that VM ctest-VM11-67228162 is in Vrouter an-jenkins-deploy-platform-ansible-os-6167-1 UVE 2026-08-17 02:58:24,039 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-08-17 02:58:24,047 - DEBUG - Expected tap interface of VM uuid da67b236-d1c1-44d2-ad37-3198cccdca22 is default-domain:ctest-TestPolicyAcl-42565280:a965d206-965c-44e7-a7a8-ba4296676439 2026-08-17 02:58:24,048 - DEBUG - Expected VN of VM uuid da67b236-d1c1-44d2-ad37-3198cccdca22 is default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 2026-08-17 02:58:24,048 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-1:Compute:contrail-vrouter-agent:0?flat 2026-08-17 02:58:24,089 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-1?flat 2026-08-17 02:58:24,124 - DEBUG - VM da67b236-d1c1-44d2-ad37-3198cccdca22 is present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 02:58:24,124 - INFO - tap interface default-domain:ctest-TestPolicyAcl-42565280:a965d206-965c-44e7-a7a8-ba4296676439 of vm da67b236-d1c1-44d2-ad37-3198cccdca22 is present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 02:58:24,124 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 of vm da67b236-d1c1-44d2-ad37-3198cccdca22 is present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 02:58:24,124 - DEBUG - Verifying the da67b236-d1c1-44d2-ad37-3198cccdca22 virtual network link through opserver 10.0.0.177 2026-08-17 02:58:24,124 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-machines 2026-08-17 02:58:24,138 - DEBUG - VM link and name as {'name': 'da67b236-d1c1-44d2-ad37-3198cccdca22', 'href': 'http://10.0.0.177:8081/analytics/uves/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22?flat'} 2026-08-17 02:58:24,138 - DEBUG - Verifying the da67b236-d1c1-44d2-ad37-3198cccdca22 virtual network link through opserver 10.0.0.190 2026-08-17 02:58:24,138 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machines 2026-08-17 02:58:24,152 - DEBUG - VM link and name as {'name': 'da67b236-d1c1-44d2-ad37-3198cccdca22', 'href': 'http://10.0.0.190:8081/analytics/uves/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22?flat'} 2026-08-17 02:58:24,152 - DEBUG - Verifying the da67b236-d1c1-44d2-ad37-3198cccdca22 virtual network link through opserver 10.0.0.38 2026-08-17 02:58:24,152 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-08-17 02:58:24,166 - DEBUG - VM link and name as {'name': 'da67b236-d1c1-44d2-ad37-3198cccdca22', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22?flat'} 2026-08-17 02:58:24,166 - INFO - VM ctest-VM11-67228162 validations in Opserver passed 2026-08-17 02:58:24,265 - DEBUG - VM is in ACTIVE state now 2026-08-17 02:58:24,265 - INFO - VM name : ctest-VM21-60654801 2026-08-17 02:58:24,346 - DEBUG - VM ctest-VM21-60654801 ID is b5e52458-f413-40c9-ad29-f13c330cb24d 2026-08-17 02:58:24,429 - DEBUG - VM is in ACTIVE state now 2026-08-17 02:58:24,429 - DEBUG - VM ctest-VM21-60654801 launched on Node an-jenkins-deploy-platform-ansible-os-6167-2 2026-08-17 02:58:24,514 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/b5e52458-f413-40c9-ad29-f13c330cb24d 2026-08-17 02:58:24,525 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/b5e52458-f413-40c9-ad29-f13c330cb24d 2026-08-17 02:58:24,534 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/46dff152-034e-4da0-ae27-360eeb8a18e2 2026-08-17 02:58:24,546 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/b5e52458-f413-40c9-ad29-f13c330cb24d 2026-08-17 02:58:24,555 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/46dff152-034e-4da0-ae27-360eeb8a18e2 2026-08-17 02:58:24,584 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/ebcd86f9-e81c-4c07-9cb2-f19dbc7b08b0 2026-08-17 02:58:24,597 - DEBUG - Verifying in api server 10.0.0.38 2026-08-17 02:58:24,597 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/b5e52458-f413-40c9-ad29-f13c330cb24d 2026-08-17 02:58:24,605 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/46dff152-034e-4da0-ae27-360eeb8a18e2 2026-08-17 02:58:24,618 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/ebcd86f9-e81c-4c07-9cb2-f19dbc7b08b0 2026-08-17 02:58:24,631 - INFO - VM ctest-VM21-60654801 verfication in all API Servers passed 2026-08-17 02:58:24,656 - DEBUG - VM ctest-VM21-60654801 Tap interface: {'index': '3', 'name': 'tap46dff152-03', 'uuid': '46dff152-034e-4da0-ae27-360eeb8a18e2', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838:ctest-VN2-82822838', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '16', 'vn_name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838', 'vm_uuid': 'b5e52458-f413-40c9-ad29-f13c330cb24d', 'vm_name': 'ctest-VM21-60654801', 'ip_addr': '191.9.167.67', 'mac_addr': '02:46:df:f1:52:03', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '28', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-42565280:46dff152-034e-4da0-ae27-360eeb8a18e2', 'sg_uuid_list': ['17c6e137-0e46-4b46-a034-b4885fb08a9a'], 'static_route_list': None, 'vm_project_uuid': '0c16a75b-ca52-4b84-8372-87b3a46aa0ab', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '::', '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-08-17 02:58:24,656 - DEBUG - Agent 10.0.0.186 vrf name: default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838:ctest-VN2-82822838 2026-08-17 02:58:24,665 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838:ctest-VN2-82822838', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838', 'table_label': '-1', 'vxlan_id': '16', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.193:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-08-17 02:58:24,751 - DEBUG - VM ctest-VM21-60654801 : Tap interface tap46dff152-03 is set to Active 2026-08-17 02:58:24,752 - DEBUG - Tap interface tap46dff152-03 detail : {'index': '3', 'name': 'tap46dff152-03', 'uuid': '46dff152-034e-4da0-ae27-360eeb8a18e2', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838:ctest-VN2-82822838', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '16', 'vn_name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838', 'vm_uuid': 'b5e52458-f413-40c9-ad29-f13c330cb24d', 'vm_name': 'ctest-VM21-60654801', 'ip_addr': '191.9.167.67', 'mac_addr': '02:46:df:f1:52:03', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '28', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-42565280:46dff152-034e-4da0-ae27-360eeb8a18e2', 'sg_uuid_list': ['17c6e137-0e46-4b46-a034-b4885fb08a9a'], 'static_route_list': None, 'vm_project_uuid': '0c16a75b-ca52-4b84-8372-87b3a46aa0ab', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '::', '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-08-17 02:58:24,867 - DEBUG - Starting Layer 2 verification in Agent 2026-08-17 02:58:24,891 - DEBUG - Layer 2 path is seen for VM MAC 02:46:df:f1:52:03 in agent 10.0.0.186 2026-08-17 02:58:24,892 - DEBUG - Active layer 2 route in agent is present for VMI tap46dff152-03 2026-08-17 02:58:24,892 - DEBUG - L2 label(29) matches bw route table and interface table 2026-08-17 02:58:24,922 - DEBUG - VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838 is found in Agent of node 10.0.0.186 2026-08-17 02:58:25,043 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838 is consistent in agent 10.0.0.186 2026-08-17 02:58:25,044 - DEBUG - Route for VM IP ['191.9.167.67'] is consistent in agent 10.0.0.186 2026-08-17 02:58:25,044 - DEBUG - VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838 verification for VM ctest-VM21-60654801 in Agent 10.0.0.186 passed 2026-08-17 02:58:25,159 - DEBUG - Starting all layer 2 verification in agent 10.0.0.186 2026-08-17 02:58:25,172 - DEBUG - Route for VM MAC 02:46:df:f1:52:03 is consistent in agent 10.0.0.186 2026-08-17 02:58:26,361 - 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.78 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.14 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 = 3.142/3.460/3.779/0.318 ms') 2026-08-17 02:58:26,361 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-60654801 passed 2026-08-17 02:58:26,361 - INFO - VM ctest-VM21-60654801 verifications in Compute nodes passed 2026-08-17 02:58:26,711 - DEBUG - Validated VM route 191.9.167.67 in vrouter of 10.0.0.186 2026-08-17 02:58:26,720 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-08-17 02:58:26,720 - INFO - Validated routes of VM ctest-VM21-60654801 in all vrouters 2026-08-17 02:58:27,182 - DEBUG - Starting all layer2 verification in 10.0.0.177 Control Node 2026-08-17 02:58:27,307 - DEBUG - Layer2 route found for VM MAC 02:46:df:f1:52:03 in Control-node 10.0.0.177 2026-08-17 02:58:27,307 - DEBUG - L2 Label for VM ctest-VM21-60654801 same between Control-node 10.0.0.177 and Agent, Expected: 29, Seen: 29 2026-08-17 02:58:27,307 - DEBUG - Starting all layer2 verification in 10.0.0.190 Control Node 2026-08-17 02:58:27,435 - DEBUG - Layer2 route found for VM MAC 02:46:df:f1:52:03 in Control-node 10.0.0.190 2026-08-17 02:58:27,439 - DEBUG - L2 Label for VM ctest-VM21-60654801 same between Control-node 10.0.0.190 and Agent, Expected: 29, Seen: 29 2026-08-17 02:58:27,439 - INFO - Verification in Control-nodes for VM ctest-VM21-60654801 passed 2026-08-17 02:58:27,439 - DEBUG - Verifying the vm in opserver 2026-08-17 02:58:27,439 - DEBUG - Verifying in collector 10.0.0.38 ... 2026-08-17 02:58:27,440 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/b5e52458-f413-40c9-ad29-f13c330cb24d?flat 2026-08-17 02:58:27,459 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-42565280:46dff152-034e-4da0-ae27-360eeb8a18e2?flat 2026-08-17 02:58:27,483 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-42565280:46dff152-034e-4da0-ae27-360eeb8a18e2?flat 2026-08-17 02:58:27,507 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/b5e52458-f413-40c9-ad29-f13c330cb24d?flat 2026-08-17 02:58:27,525 - DEBUG - Verifying vm in vn uve 2026-08-17 02:58:27,525 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-42565280:46dff152-034e-4da0-ae27-360eeb8a18e2?flat 2026-08-17 02:58:27,555 - DEBUG - VM uve shows interface as {'is_health_check_active': True, 'ip6_address': '::', 'vn_uuid': 'e93fa069-c622-4321-9ec3-a167b453b336', 'uuid': '46dff152-034e-4da0-ae27-360eeb8a18e2', '__T': 1786935479399537, 'admin_state': True, 'ip_address': '191.9.167.67', 'active': True, 'mac_address': '02:46:df:f1:52:03', 'fixed_ip4_list': ['191.9.167.67'], 'l2_active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838', 'port_mirror_enabled': False, 'ip4_active': True, 'vhostuser_mode': 0, 'label': 25, 'ip6_active': False, 'rx_vlan': 65535, 'gateway': '191.9.167.65', 'vm_name': 'ctest-VM21-60654801', 'tx_vlan': 65535, 'vm_uuid': 'b5e52458-f413-40c9-ad29-f13c330cb24d'} 2026-08-17 02:58:27,555 - DEBUG - VM uve shows ip address as ['191.9.167.67', '::'] 2026-08-17 02:58:27,555 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838 2026-08-17 02:58:27,555 - INFO - Verifying through opserver in 10.0.0.177 2026-08-17 02:58:27,555 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838?flat 2026-08-17 02:58:27,592 - DEBUG - expected vm list b5e52458-f413-40c9-ad29-f13c330cb24d 2026-08-17 02:58:27,592 - DEBUG - Extracted vm list ['b5e52458-f413-40c9-ad29-f13c330cb24d'] 2026-08-17 02:58:27,592 - INFO - VM b5e52458-f413-40c9-ad29-f13c330cb24d is present in default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838 2026-08-17 02:58:27,592 - DEBUG - Verifying vm in vrouter uve 2026-08-17 02:58:27,592 - DEBUG - Getting info from collector 10.0.0.38.. 2026-08-17 02:58:27,592 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/b5e52458-f413-40c9-ad29-f13c330cb24d?flat 2026-08-17 02:58:27,611 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-6167-2'] 2026-08-17 02:58:27,611 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-2:Compute:contrail-vrouter-agent:0?flat 2026-08-17 02:58:27,669 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-2?flat 2026-08-17 02:58:27,701 - DEBUG - VM b5e52458-f413-40c9-ad29-f13c330cb24d is present in vrouter an-jenkins-deploy-platform-ansible-os-6167-2 uve 2026-08-17 02:58:27,702 - DEBUG - Validated that VM ctest-VM21-60654801 is in Vrouter an-jenkins-deploy-platform-ansible-os-6167-2 UVE 2026-08-17 02:58:27,702 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-08-17 02:58:27,713 - DEBUG - Expected tap interface of VM uuid b5e52458-f413-40c9-ad29-f13c330cb24d is default-domain:ctest-TestPolicyAcl-42565280:46dff152-034e-4da0-ae27-360eeb8a18e2 2026-08-17 02:58:27,713 - DEBUG - Expected VN of VM uuid b5e52458-f413-40c9-ad29-f13c330cb24d is default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838 2026-08-17 02:58:27,713 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-2:Compute:contrail-vrouter-agent:0?flat 2026-08-17 02:58:27,756 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-2?flat 2026-08-17 02:58:27,787 - DEBUG - VM b5e52458-f413-40c9-ad29-f13c330cb24d is present in vrouter an-jenkins-deploy-platform-ansible-os-6167-2 uve 2026-08-17 02:58:27,787 - INFO - tap interface default-domain:ctest-TestPolicyAcl-42565280:46dff152-034e-4da0-ae27-360eeb8a18e2 of vm b5e52458-f413-40c9-ad29-f13c330cb24d is present in vrouter an-jenkins-deploy-platform-ansible-os-6167-2 uve 2026-08-17 02:58:27,787 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838 of vm b5e52458-f413-40c9-ad29-f13c330cb24d is present in vrouter an-jenkins-deploy-platform-ansible-os-6167-2 uve 2026-08-17 02:58:27,787 - DEBUG - Verifying the b5e52458-f413-40c9-ad29-f13c330cb24d virtual network link through opserver 10.0.0.177 2026-08-17 02:58:27,787 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-machines 2026-08-17 02:58:27,801 - DEBUG - VM link and name as {'name': 'b5e52458-f413-40c9-ad29-f13c330cb24d', 'href': 'http://10.0.0.177:8081/analytics/uves/virtual-machine/b5e52458-f413-40c9-ad29-f13c330cb24d?flat'} 2026-08-17 02:58:27,801 - DEBUG - Verifying the b5e52458-f413-40c9-ad29-f13c330cb24d virtual network link through opserver 10.0.0.190 2026-08-17 02:58:27,801 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machines 2026-08-17 02:58:27,814 - DEBUG - VM link and name as {'name': 'b5e52458-f413-40c9-ad29-f13c330cb24d', 'href': 'http://10.0.0.190:8081/analytics/uves/virtual-machine/b5e52458-f413-40c9-ad29-f13c330cb24d?flat'} 2026-08-17 02:58:27,814 - DEBUG - Verifying the b5e52458-f413-40c9-ad29-f13c330cb24d virtual network link through opserver 10.0.0.38 2026-08-17 02:58:27,815 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-08-17 02:58:27,829 - DEBUG - VM link and name as {'name': 'b5e52458-f413-40c9-ad29-f13c330cb24d', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/b5e52458-f413-40c9-ad29-f13c330cb24d?flat'} 2026-08-17 02:58:27,829 - INFO - VM ctest-VM21-60654801 validations in Opserver passed 2026-08-17 02:58:27,911 - DEBUG - VM is in ACTIVE state now 2026-08-17 02:58:27,911 - INFO - VM name : ctest-VM31-52695105 2026-08-17 02:58:28,002 - DEBUG - VM ctest-VM31-52695105 ID is 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 2026-08-17 02:58:28,099 - DEBUG - VM is in ACTIVE state now 2026-08-17 02:58:28,100 - DEBUG - VM ctest-VM31-52695105 launched on Node an-jenkins-deploy-platform-ansible-os-6167-1 2026-08-17 02:58:28,184 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 2026-08-17 02:58:28,195 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 2026-08-17 02:58:28,203 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/7affaecf-1c59-4371-a49d-189a350682b7 2026-08-17 02:58:28,212 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 2026-08-17 02:58:28,220 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/7affaecf-1c59-4371-a49d-189a350682b7 2026-08-17 02:58:28,230 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/1e1e652d-ca43-48a9-acf0-bfedaeeeb181 2026-08-17 02:58:28,239 - DEBUG - Verifying in api server 10.0.0.38 2026-08-17 02:58:28,239 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 2026-08-17 02:58:28,246 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/7affaecf-1c59-4371-a49d-189a350682b7 2026-08-17 02:58:28,257 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/1e1e652d-ca43-48a9-acf0-bfedaeeeb181 2026-08-17 02:58:28,265 - INFO - VM ctest-VM31-52695105 verfication in all API Servers passed 2026-08-17 02:58:28,285 - DEBUG - VM ctest-VM31-52695105 Tap interface: {'index': '4', 'name': 'tap7affaecf-1c', 'uuid': '7affaecf-1c59-4371-a49d-189a350682b7', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290:ctest-VN3-47714290', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '17', 'vn_name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290', 'vm_uuid': '6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02', 'vm_name': 'ctest-VM31-52695105', 'ip_addr': '154.232.220.67', 'mac_addr': '02:7a:ff:ae:cf:1c', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '33', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-42565280:7affaecf-1c59-4371-a49d-189a350682b7', 'sg_uuid_list': ['17c6e137-0e46-4b46-a034-b4885fb08a9a'], 'static_route_list': None, 'vm_project_uuid': '0c16a75b-ca52-4b84-8372-87b3a46aa0ab', 'admin_state': 'Enabled', 'flow_key_idx': '29', 'allowed_address_pair_list': None, 'ip6_addr': '::', '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-08-17 02:58:28,285 - DEBUG - Agent 10.0.0.50 vrf name: default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290:ctest-VN3-47714290 2026-08-17 02:58:28,290 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290:ctest-VN3-47714290', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290', 'table_label': '-1', 'vxlan_id': '17', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.29:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-08-17 02:58:28,367 - DEBUG - VM ctest-VM31-52695105 : Tap interface tap7affaecf-1c is set to Active 2026-08-17 02:58:28,368 - DEBUG - Tap interface tap7affaecf-1c detail : {'index': '4', 'name': 'tap7affaecf-1c', 'uuid': '7affaecf-1c59-4371-a49d-189a350682b7', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290:ctest-VN3-47714290', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '32', 'l2_label': '36', 'vxlan_id': '17', 'vn_name': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290', 'vm_uuid': '6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02', 'vm_name': 'ctest-VM31-52695105', 'ip_addr': '154.232.220.67', 'mac_addr': '02:7a:ff:ae:cf:1c', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '33', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-42565280:7affaecf-1c59-4371-a49d-189a350682b7', 'sg_uuid_list': ['17c6e137-0e46-4b46-a034-b4885fb08a9a'], 'static_route_list': None, 'vm_project_uuid': '0c16a75b-ca52-4b84-8372-87b3a46aa0ab', 'admin_state': 'Enabled', 'flow_key_idx': '29', 'allowed_address_pair_list': None, 'ip6_addr': '::', '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-08-17 02:58:28,486 - DEBUG - Starting Layer 2 verification in Agent 2026-08-17 02:58:28,499 - DEBUG - Layer 2 path is seen for VM MAC 02:7a:ff:ae:cf:1c in agent 10.0.0.50 2026-08-17 02:58:28,499 - DEBUG - Active layer 2 route in agent is present for VMI tap7affaecf-1c 2026-08-17 02:58:28,499 - DEBUG - L2 label(36) matches bw route table and interface table 2026-08-17 02:58:28,519 - DEBUG - VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290 is found in Agent of node 10.0.0.50 2026-08-17 02:58:28,633 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290 is consistent in agent 10.0.0.50 2026-08-17 02:58:28,633 - DEBUG - Route for VM IP ['154.232.220.67'] is consistent in agent 10.0.0.50 2026-08-17 02:58:28,633 - DEBUG - VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290 verification for VM ctest-VM31-52695105 in Agent 10.0.0.50 passed 2026-08-17 02:58:28,746 - DEBUG - Starting all layer 2 verification in agent 10.0.0.50 2026-08-17 02:58:28,759 - DEBUG - Route for VM MAC 02:7a:ff:ae:cf:1c is consistent in agent 10.0.0.50 2026-08-17 02:58:29,953 - 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.62 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.584 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.584/3.600/6.616/3.016 ms') 2026-08-17 02:58:29,954 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-52695105 passed 2026-08-17 02:58:29,954 - INFO - VM ctest-VM31-52695105 verifications in Compute nodes passed 2026-08-17 02:58:30,267 - DEBUG - Validated VM route 154.232.220.67 in vrouter of 10.0.0.50 2026-08-17 02:58:30,273 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-08-17 02:58:30,273 - INFO - Validated routes of VM ctest-VM31-52695105 in all vrouters 2026-08-17 02:58:30,794 - DEBUG - Starting all layer2 verification in 10.0.0.177 Control Node 2026-08-17 02:58:30,880 - DEBUG - Layer2 route found for VM MAC 02:7a:ff:ae:cf:1c in Control-node 10.0.0.177 2026-08-17 02:58:30,880 - DEBUG - L2 Label for VM ctest-VM31-52695105 same between Control-node 10.0.0.177 and Agent, Expected: 36, Seen: 36 2026-08-17 02:58:30,880 - DEBUG - Starting all layer2 verification in 10.0.0.190 Control Node 2026-08-17 02:58:31,012 - DEBUG - Layer2 route found for VM MAC 02:7a:ff:ae:cf:1c in Control-node 10.0.0.190 2026-08-17 02:58:31,012 - DEBUG - L2 Label for VM ctest-VM31-52695105 same between Control-node 10.0.0.190 and Agent, Expected: 36, Seen: 36 2026-08-17 02:58:31,012 - INFO - Verification in Control-nodes for VM ctest-VM31-52695105 passed 2026-08-17 02:58:31,012 - DEBUG - Verifying the vm in opserver 2026-08-17 02:58:31,013 - DEBUG - Verifying in collector 10.0.0.38 ... 2026-08-17 02:58:31,013 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02?flat 2026-08-17 02:58:31,030 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-42565280:7affaecf-1c59-4371-a49d-189a350682b7?flat 2026-08-17 02:58:31,050 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-42565280:7affaecf-1c59-4371-a49d-189a350682b7?flat 2026-08-17 02:58:31,070 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02?flat 2026-08-17 02:58:31,087 - DEBUG - Verifying vm in vn uve 2026-08-17 02:58:31,087 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-42565280:7affaecf-1c59-4371-a49d-189a350682b7?flat 2026-08-17 02:58:31,107 - DEBUG - VM uve shows interface as {'is_health_check_active': True, 'ip6_address': '::', 'vn_uuid': '3d78f871-c6b8-45c0-b95d-c423c6f89507', 'uuid': '7affaecf-1c59-4371-a49d-189a350682b7', '__T': 1786935479388660, 'admin_state': True, 'ip_address': '154.232.220.67', 'active': True, 'mac_address': '02:7a:ff:ae:cf:1c', 'fixed_ip4_list': ['154.232.220.67'], 'l2_active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290', 'port_mirror_enabled': False, 'ip4_active': True, 'vhostuser_mode': 0, 'label': 32, 'ip6_active': False, 'rx_vlan': 65535, 'gateway': '154.232.220.65', 'vm_name': 'ctest-VM31-52695105', 'tx_vlan': 65535, 'vm_uuid': '6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02'} 2026-08-17 02:58:31,107 - DEBUG - VM uve shows ip address as ['154.232.220.67', '::'] 2026-08-17 02:58:31,107 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290 2026-08-17 02:58:31,107 - INFO - Verifying through opserver in 10.0.0.177 2026-08-17 02:58:31,107 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290?flat 2026-08-17 02:58:31,135 - DEBUG - expected vm list 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 2026-08-17 02:58:31,135 - DEBUG - Extracted vm list ['6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02'] 2026-08-17 02:58:31,135 - INFO - VM 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 is present in default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290 2026-08-17 02:58:31,135 - DEBUG - Verifying vm in vrouter uve 2026-08-17 02:58:31,136 - DEBUG - Getting info from collector 10.0.0.38.. 2026-08-17 02:58:31,136 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machine/6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02?flat 2026-08-17 02:58:31,153 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-6167-1'] 2026-08-17 02:58:31,153 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-1:Compute:contrail-vrouter-agent:0?flat 2026-08-17 02:58:31,191 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-1?flat 2026-08-17 02:58:31,227 - DEBUG - VM 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 is present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 02:58:31,228 - DEBUG - Validated that VM ctest-VM31-52695105 is in Vrouter an-jenkins-deploy-platform-ansible-os-6167-1 UVE 2026-08-17 02:58:31,228 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-08-17 02:58:31,236 - DEBUG - Expected tap interface of VM uuid 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 is default-domain:ctest-TestPolicyAcl-42565280:7affaecf-1c59-4371-a49d-189a350682b7 2026-08-17 02:58:31,236 - DEBUG - Expected VN of VM uuid 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 is default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290 2026-08-17 02:58:31,236 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-1:Compute:contrail-vrouter-agent:0?flat 2026-08-17 02:58:31,272 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-1?flat 2026-08-17 02:58:31,303 - DEBUG - VM 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 is present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 02:58:31,303 - INFO - tap interface default-domain:ctest-TestPolicyAcl-42565280:7affaecf-1c59-4371-a49d-189a350682b7 of vm 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 is present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 02:58:31,303 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290 of vm 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 is present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 02:58:31,303 - DEBUG - Verifying the 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 virtual network link through opserver 10.0.0.177 2026-08-17 02:58:31,303 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-machines 2026-08-17 02:58:31,317 - DEBUG - VM link and name as {'name': '6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02', 'href': 'http://10.0.0.177:8081/analytics/uves/virtual-machine/6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02?flat'} 2026-08-17 02:58:31,317 - DEBUG - Verifying the 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 virtual network link through opserver 10.0.0.190 2026-08-17 02:58:31,317 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machines 2026-08-17 02:58:31,329 - DEBUG - VM link and name as {'name': '6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02', 'href': 'http://10.0.0.190:8081/analytics/uves/virtual-machine/6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02?flat'} 2026-08-17 02:58:31,329 - DEBUG - Verifying the 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 virtual network link through opserver 10.0.0.38 2026-08-17 02:58:31,329 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-08-17 02:58:31,342 - DEBUG - VM link and name as {'name': '6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-machine/6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02?flat'} 2026-08-17 02:58:31,342 - INFO - VM ctest-VM31-52695105 validations in Opserver passed 2026-08-17 02:58:31,342 - INFO - Waiting for VM ctest-VM11-67228162 to be up.. 2026-08-17 02:58:31,342 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 02:58:31,343 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22 2026-08-17 02:58:31,351 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/a965d206-965c-44e7-a7a8-ba4296676439 2026-08-17 02:58:32,518 - 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.11 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.573 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.573/1.840/3.108/1.267 ms') 2026-08-17 02:58:32,518 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-67228162 passed 2026-08-17 02:58:32,594 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 02:58:32,594 - DEBUG - Waiting to SSH to VM ctest-VM11-67228162, IP 35.51.71.131, Port 22 2026-08-17 02:58:32,652 - 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-08-17 02:58:32,767 - DEBUG - VM ctest-VM11-67228162 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-17 02:58:37,767 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 02:58:37,768 - DEBUG - Waiting to SSH to VM ctest-VM11-67228162, IP 35.51.71.131, Port 22 2026-08-17 02:58:37,938 - DEBUG - VM ctest-VM11-67228162 is ready for SSH connections 2026-08-17 02:58:37,938 - INFO - Waiting for VM ctest-VM21-60654801 to be up.. 2026-08-17 02:58:37,938 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 02:58:37,939 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/b5e52458-f413-40c9-ad29-f13c330cb24d 2026-08-17 02:58:37,949 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/46dff152-034e-4da0-ae27-360eeb8a18e2 2026-08-17 02:58:39,114 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=1.24 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.538 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.538/0.889/1.241/0.351 ms') 2026-08-17 02:58:39,114 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-60654801 passed 2026-08-17 02:58:39,191 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 02:58:39,192 - DEBUG - Waiting to SSH to VM ctest-VM21-60654801, IP 191.9.167.67, Port 22 2026-08-17 02:58:39,366 - DEBUG - VM ctest-VM21-60654801 is ready for SSH connections 2026-08-17 02:58:39,366 - INFO - Waiting for VM ctest-VM31-52695105 to be up.. 2026-08-17 02:58:39,367 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 02:58:39,367 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 2026-08-17 02:58:39,376 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/7affaecf-1c59-4371-a49d-189a350682b7 2026-08-17 02:58:40,532 - 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=3.31 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.66 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.659/2.486/3.313/0.827 ms') 2026-08-17 02:58:40,532 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-52695105 passed 2026-08-17 02:58:40,602 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 02:58:40,602 - DEBUG - Waiting to SSH to VM ctest-VM31-52695105, IP 154.232.220.67, Port 22 2026-08-17 02:58:40,782 - DEBUG - VM ctest-VM31-52695105 is ready for SSH connections 2026-08-17 02:58:40,782 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 191.9.167.67, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-08-17 02:58:40,782 - DEBUG - ping -s 56 -c 3 -W 1 191.9.167.67 2026-08-17 02:59:24,518 - DEBUG - PING 191.9.167.67 (191.9.167.67) 56(84) bytes of data. 64 bytes from 191.9.167.67: icmp_req=1 ttl=63 time=9.32 ms 64 bytes from 191.9.167.67: icmp_req=2 ttl=63 time=1.50 ms 64 bytes from 191.9.167.67: icmp_req=3 ttl=63 time=1.15 ms --- 191.9.167.67 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2003ms rtt min/avg/max/mdev = 1.156/3.997/9.328/3.772 ms 2026-08-17 02:59:24,518 - INFO - Ping to IP 191.9.167.67 from VM ctest-VM11-67228162 passed 2026-08-17 02:59:24,518 - INFO - Test with src as VN and dst as policy PASSED 2026-08-17 02:59:24,518 - INFO - Deleting VM ctest-VM31-52695105 2026-08-17 02:59:24,614 - DEBUG - Verifying in api server 10.0.0.38 2026-08-17 02:59:24,614 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 2026-08-17 02:59:24,625 - DEBUG - VM ID 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 of VM ctest-VM31-52695105 is still found in API Server 2026-08-17 02:59:26,626 - DEBUG - Verifying in api server 10.0.0.38 2026-08-17 02:59:26,626 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 2026-08-17 02:59:26,636 - DEBUG - VM ID 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 of VM ctest-VM31-52695105 is still found in API Server 2026-08-17 02:59:28,636 - DEBUG - Verifying in api server 10.0.0.38 2026-08-17 02:59:28,637 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 2026-08-17 02:59:28,644 - DEBUG - Response Code: 404 2026-08-17 02:59:28,645 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 2026-08-17 02:59:28,652 - DEBUG - Response Code: 404 2026-08-17 02:59:28,652 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 2026-08-17 02:59:28,658 - DEBUG - Response Code: 404 2026-08-17 02:59:28,658 - INFO - VM ctest-VM31-52695105 is fully removed in API-Server 2026-08-17 02:59:28,827 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-08-17 02:59:28,883 - DEBUG - Validated that vrouter 10.0.0.50 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290 2026-08-17 02:59:28,892 - DEBUG - Validated that vrouter 10.0.0.186 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290 2026-08-17 02:59:28,892 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290 2026-08-17 02:59:28,892 - INFO - VM ctest-VM31-52695105 is removed in Compute, and routes are removed in all compute nodes 2026-08-17 02:59:29,136 - INFO - Routes for VM ctest-VM31-52695105 is removed in all control-nodes 2026-08-17 02:59:29,136 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-1:Compute:contrail-vrouter-agent:0?flat 2026-08-17 02:59:29,173 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-1?flat 2026-08-17 02:59:29,206 - DEBUG - VM 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 02:59:29,206 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 02:59:29,206 - DEBUG - interface for vm 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 deleted from vrouter uve ... 2026-08-17 02:59:29,206 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 02:59:32,207 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-1:Compute:contrail-vrouter-agent:0?flat 2026-08-17 02:59:32,243 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-1?flat 2026-08-17 02:59:32,280 - DEBUG - VM 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 is not present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 02:59:32,280 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 02:59:32,280 - DEBUG - interface for vm 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 deleted from vrouter uve ... 2026-08-17 02:59:32,280 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 02:59:32,280 - DEBUG - Validated that VM 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 is removed in Vrouter UVE 2026-08-17 02:59:32,280 - DEBUG - Verifying through opserver in 10.0.0.177 2026-08-17 02:59:32,280 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290?flat 2026-08-17 02:59:32,301 - DEBUG - 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 BM not in default-domain:ctest-TestPolicyAcl-42565280:ctest-VN3-47714290 uve 2026-08-17 02:59:32,301 - DEBUG - Validated that VM 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 is not present in the VN UVE 2026-08-17 02:59:32,301 - DEBUG - Verifying the 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 virtual network link through opserver 10.0.0.177 2026-08-17 02:59:32,301 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-machines 2026-08-17 02:59:32,314 - INFO - 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 vm uve deleted from opserver 2026-08-17 02:59:32,314 - DEBUG - Verifying the 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 virtual network link through opserver 10.0.0.190 2026-08-17 02:59:32,314 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machines 2026-08-17 02:59:32,328 - INFO - 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 vm uve deleted from opserver 2026-08-17 02:59:32,328 - DEBUG - Verifying the 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 virtual network link through opserver 10.0.0.38 2026-08-17 02:59:32,328 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-08-17 02:59:32,341 - INFO - 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 vm uve deleted from opserver 2026-08-17 02:59:32,341 - INFO - Validated that VM 6a7c22a0-dd58-4fdd-a6ef-1ffd96557c02 is removed from Opserver 2026-08-17 02:59:32,341 - INFO - Deleting VM ctest-VM21-60654801 2026-08-17 02:59:32,416 - DEBUG - Verifying in api server 10.0.0.38 2026-08-17 02:59:32,416 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/b5e52458-f413-40c9-ad29-f13c330cb24d 2026-08-17 02:59:32,425 - DEBUG - VM ID b5e52458-f413-40c9-ad29-f13c330cb24d of VM ctest-VM21-60654801 is still found in API Server 2026-08-17 02:59:34,426 - DEBUG - Verifying in api server 10.0.0.38 2026-08-17 02:59:34,426 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/b5e52458-f413-40c9-ad29-f13c330cb24d 2026-08-17 02:59:34,435 - DEBUG - VM ID b5e52458-f413-40c9-ad29-f13c330cb24d of VM ctest-VM21-60654801 is still found in API Server 2026-08-17 02:59:36,436 - DEBUG - Verifying in api server 10.0.0.38 2026-08-17 02:59:36,436 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/b5e52458-f413-40c9-ad29-f13c330cb24d 2026-08-17 02:59:36,443 - DEBUG - Response Code: 404 2026-08-17 02:59:36,444 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/b5e52458-f413-40c9-ad29-f13c330cb24d 2026-08-17 02:59:36,450 - DEBUG - Response Code: 404 2026-08-17 02:59:36,450 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/b5e52458-f413-40c9-ad29-f13c330cb24d 2026-08-17 02:59:36,457 - DEBUG - Response Code: 404 2026-08-17 02:59:36,457 - INFO - VM ctest-VM21-60654801 is fully removed in API-Server 2026-08-17 02:59:36,623 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-08-17 02:59:36,668 - DEBUG - Validated that vrouter 10.0.0.50 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838 2026-08-17 02:59:36,679 - DEBUG - Validated that vrouter 10.0.0.186 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838 2026-08-17 02:59:36,679 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838 2026-08-17 02:59:36,679 - INFO - VM ctest-VM21-60654801 is removed in Compute, and routes are removed in all compute nodes 2026-08-17 02:59:36,903 - INFO - Routes for VM ctest-VM21-60654801 is removed in all control-nodes 2026-08-17 02:59:36,903 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-2:Compute:contrail-vrouter-agent:0?flat 2026-08-17 02:59:36,958 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-2?flat 2026-08-17 02:59:36,997 - DEBUG - VM b5e52458-f413-40c9-ad29-f13c330cb24d is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-2 uve 2026-08-17 02:59:36,997 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 02:59:36,997 - DEBUG - interface for vm b5e52458-f413-40c9-ad29-f13c330cb24d deleted from vrouter uve ... 2026-08-17 02:59:36,997 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 02:59:39,998 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-2:Compute:contrail-vrouter-agent:0?flat 2026-08-17 02:59:40,048 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-2?flat 2026-08-17 02:59:40,090 - DEBUG - VM b5e52458-f413-40c9-ad29-f13c330cb24d is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-2 uve 2026-08-17 02:59:40,090 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 02:59:40,090 - DEBUG - interface for vm b5e52458-f413-40c9-ad29-f13c330cb24d deleted from vrouter uve ... 2026-08-17 02:59:40,090 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 02:59:43,090 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-2:Compute:contrail-vrouter-agent:0?flat 2026-08-17 02:59:43,130 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-2?flat 2026-08-17 02:59:43,163 - DEBUG - VM b5e52458-f413-40c9-ad29-f13c330cb24d is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-2 uve 2026-08-17 02:59:43,163 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 02:59:43,163 - DEBUG - interface for vm b5e52458-f413-40c9-ad29-f13c330cb24d deleted from vrouter uve ... 2026-08-17 02:59:43,163 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 02:59:46,163 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-2:Compute:contrail-vrouter-agent:0?flat 2026-08-17 02:59:46,207 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-2?flat 2026-08-17 02:59:46,246 - DEBUG - VM b5e52458-f413-40c9-ad29-f13c330cb24d is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-2 uve 2026-08-17 02:59:46,246 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 02:59:46,246 - DEBUG - interface for vm b5e52458-f413-40c9-ad29-f13c330cb24d deleted from vrouter uve ... 2026-08-17 02:59:46,246 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 02:59:49,246 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-2:Compute:contrail-vrouter-agent:0?flat 2026-08-17 02:59:49,286 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-2?flat 2026-08-17 02:59:49,318 - DEBUG - VM b5e52458-f413-40c9-ad29-f13c330cb24d is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-2 uve 2026-08-17 02:59:49,318 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 02:59:49,318 - DEBUG - interface for vm b5e52458-f413-40c9-ad29-f13c330cb24d deleted from vrouter uve ... 2026-08-17 02:59:49,318 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 02:59:52,319 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-2:Compute:contrail-vrouter-agent:0?flat 2026-08-17 02:59:52,364 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-2?flat 2026-08-17 02:59:52,398 - DEBUG - VM b5e52458-f413-40c9-ad29-f13c330cb24d is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-2 uve 2026-08-17 02:59:52,398 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 02:59:52,398 - DEBUG - interface for vm b5e52458-f413-40c9-ad29-f13c330cb24d deleted from vrouter uve ... 2026-08-17 02:59:52,398 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 02:59:55,399 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-2:Compute:contrail-vrouter-agent:0?flat 2026-08-17 02:59:55,435 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-2?flat 2026-08-17 02:59:55,466 - DEBUG - VM b5e52458-f413-40c9-ad29-f13c330cb24d is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-2 uve 2026-08-17 02:59:55,467 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 02:59:55,467 - DEBUG - interface for vm b5e52458-f413-40c9-ad29-f13c330cb24d deleted from vrouter uve ... 2026-08-17 02:59:55,467 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 02:59:58,467 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-2:Compute:contrail-vrouter-agent:0?flat 2026-08-17 02:59:58,506 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-2?flat 2026-08-17 02:59:58,541 - DEBUG - VM b5e52458-f413-40c9-ad29-f13c330cb24d is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-2 uve 2026-08-17 02:59:58,541 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 02:59:58,541 - DEBUG - interface for vm b5e52458-f413-40c9-ad29-f13c330cb24d deleted from vrouter uve ... 2026-08-17 02:59:58,541 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 03:00:01,542 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-2:Compute:contrail-vrouter-agent:0?flat 2026-08-17 03:00:01,586 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-2?flat 2026-08-17 03:00:01,626 - DEBUG - VM b5e52458-f413-40c9-ad29-f13c330cb24d is not present in vrouter an-jenkins-deploy-platform-ansible-os-6167-2 uve 2026-08-17 03:00:01,626 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 03:00:01,626 - DEBUG - interface for vm b5e52458-f413-40c9-ad29-f13c330cb24d deleted from vrouter uve ... 2026-08-17 03:00:01,626 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 03:00:01,626 - DEBUG - Validated that VM b5e52458-f413-40c9-ad29-f13c330cb24d is removed in Vrouter UVE 2026-08-17 03:00:01,627 - DEBUG - Verifying through opserver in 10.0.0.177 2026-08-17 03:00:01,627 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838?flat 2026-08-17 03:00:01,650 - DEBUG - b5e52458-f413-40c9-ad29-f13c330cb24d BM not in default-domain:ctest-TestPolicyAcl-42565280:ctest-VN2-82822838 uve 2026-08-17 03:00:01,650 - DEBUG - Validated that VM b5e52458-f413-40c9-ad29-f13c330cb24d is not present in the VN UVE 2026-08-17 03:00:01,650 - DEBUG - Verifying the b5e52458-f413-40c9-ad29-f13c330cb24d virtual network link through opserver 10.0.0.177 2026-08-17 03:00:01,650 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-machines 2026-08-17 03:00:01,676 - INFO - b5e52458-f413-40c9-ad29-f13c330cb24d vm uve deleted from opserver 2026-08-17 03:00:01,677 - DEBUG - Verifying the b5e52458-f413-40c9-ad29-f13c330cb24d virtual network link through opserver 10.0.0.190 2026-08-17 03:00:01,677 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machines 2026-08-17 03:00:01,694 - INFO - b5e52458-f413-40c9-ad29-f13c330cb24d vm uve deleted from opserver 2026-08-17 03:00:01,694 - DEBUG - Verifying the b5e52458-f413-40c9-ad29-f13c330cb24d virtual network link through opserver 10.0.0.38 2026-08-17 03:00:01,694 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-08-17 03:00:01,710 - INFO - b5e52458-f413-40c9-ad29-f13c330cb24d vm uve deleted from opserver 2026-08-17 03:00:01,711 - INFO - Validated that VM b5e52458-f413-40c9-ad29-f13c330cb24d is removed from Opserver 2026-08-17 03:00:01,711 - INFO - Deleting VM ctest-VM11-67228162 2026-08-17 03:00:01,812 - DEBUG - Verifying in api server 10.0.0.38 2026-08-17 03:00:01,812 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22 2026-08-17 03:00:01,822 - DEBUG - VM ID da67b236-d1c1-44d2-ad37-3198cccdca22 of VM ctest-VM11-67228162 is still found in API Server 2026-08-17 03:00:03,823 - DEBUG - Verifying in api server 10.0.0.38 2026-08-17 03:00:03,823 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22 2026-08-17 03:00:03,833 - DEBUG - VM ID da67b236-d1c1-44d2-ad37-3198cccdca22 of VM ctest-VM11-67228162 is still found in API Server 2026-08-17 03:00:05,834 - DEBUG - Verifying in api server 10.0.0.38 2026-08-17 03:00:05,835 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22 2026-08-17 03:00:05,844 - DEBUG - Response Code: 404 2026-08-17 03:00:05,845 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22 2026-08-17 03:00:05,852 - DEBUG - Response Code: 404 2026-08-17 03:00:05,852 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/da67b236-d1c1-44d2-ad37-3198cccdca22 2026-08-17 03:00:05,859 - DEBUG - Response Code: 404 2026-08-17 03:00:05,859 - INFO - VM ctest-VM11-67228162 is fully removed in API-Server 2026-08-17 03:00:06,027 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-08-17 03:00:06,076 - DEBUG - Validated that vrouter 10.0.0.50 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 2026-08-17 03:00:06,089 - DEBUG - Validated that vrouter 10.0.0.186 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 2026-08-17 03:00:06,089 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 2026-08-17 03:00:06,089 - INFO - VM ctest-VM11-67228162 is removed in Compute, and routes are removed in all compute nodes 2026-08-17 03:00:06,305 - INFO - Routes for VM ctest-VM11-67228162 is removed in all control-nodes 2026-08-17 03:00:06,305 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-1:Compute:contrail-vrouter-agent:0?flat 2026-08-17 03:00:06,348 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-1?flat 2026-08-17 03:00:06,384 - DEBUG - VM da67b236-d1c1-44d2-ad37-3198cccdca22 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 03:00:06,384 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 03:00:06,385 - DEBUG - interface for vm da67b236-d1c1-44d2-ad37-3198cccdca22 deleted from vrouter uve ... 2026-08-17 03:00:06,385 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 03:00:09,385 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-1:Compute:contrail-vrouter-agent:0?flat 2026-08-17 03:00:09,424 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-1?flat 2026-08-17 03:00:09,457 - DEBUG - VM da67b236-d1c1-44d2-ad37-3198cccdca22 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 03:00:09,457 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 03:00:09,457 - DEBUG - interface for vm da67b236-d1c1-44d2-ad37-3198cccdca22 deleted from vrouter uve ... 2026-08-17 03:00:09,457 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 03:00:12,458 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-1:Compute:contrail-vrouter-agent:0?flat 2026-08-17 03:00:12,496 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-1?flat 2026-08-17 03:00:12,530 - DEBUG - VM da67b236-d1c1-44d2-ad37-3198cccdca22 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 03:00:12,530 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 03:00:12,530 - DEBUG - interface for vm da67b236-d1c1-44d2-ad37-3198cccdca22 deleted from vrouter uve ... 2026-08-17 03:00:12,530 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 03:00:15,531 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-1:Compute:contrail-vrouter-agent:0?flat 2026-08-17 03:00:15,573 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-1?flat 2026-08-17 03:00:15,607 - DEBUG - VM da67b236-d1c1-44d2-ad37-3198cccdca22 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 03:00:15,607 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 03:00:15,607 - DEBUG - interface for vm da67b236-d1c1-44d2-ad37-3198cccdca22 deleted from vrouter uve ... 2026-08-17 03:00:15,607 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 03:00:18,608 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-1:Compute:contrail-vrouter-agent:0?flat 2026-08-17 03:00:18,673 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-1?flat 2026-08-17 03:00:18,707 - DEBUG - VM da67b236-d1c1-44d2-ad37-3198cccdca22 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 03:00:18,707 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 03:00:18,707 - DEBUG - interface for vm da67b236-d1c1-44d2-ad37-3198cccdca22 deleted from vrouter uve ... 2026-08-17 03:00:18,707 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 03:00:21,708 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-1:Compute:contrail-vrouter-agent:0?flat 2026-08-17 03:00:21,751 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-1?flat 2026-08-17 03:00:21,780 - DEBUG - VM da67b236-d1c1-44d2-ad37-3198cccdca22 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 03:00:21,780 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 03:00:21,780 - DEBUG - interface for vm da67b236-d1c1-44d2-ad37-3198cccdca22 deleted from vrouter uve ... 2026-08-17 03:00:21,780 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 03:00:24,781 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-1:Compute:contrail-vrouter-agent:0?flat 2026-08-17 03:00:24,832 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-1?flat 2026-08-17 03:00:24,873 - DEBUG - VM da67b236-d1c1-44d2-ad37-3198cccdca22 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 03:00:24,873 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 03:00:24,873 - DEBUG - interface for vm da67b236-d1c1-44d2-ad37-3198cccdca22 deleted from vrouter uve ... 2026-08-17 03:00:24,873 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 03:00:27,874 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-1:Compute:contrail-vrouter-agent:0?flat 2026-08-17 03:00:27,919 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-1?flat 2026-08-17 03:00:27,951 - DEBUG - VM da67b236-d1c1-44d2-ad37-3198cccdca22 is still present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 03:00:27,951 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 03:00:27,951 - DEBUG - interface for vm da67b236-d1c1-44d2-ad37-3198cccdca22 deleted from vrouter uve ... 2026-08-17 03:00:27,951 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 03:00:30,952 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-6167-1:Compute:contrail-vrouter-agent:0?flat 2026-08-17 03:00:30,998 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-6167-1?flat 2026-08-17 03:00:31,048 - DEBUG - VM da67b236-d1c1-44d2-ad37-3198cccdca22 is not present in vrouter an-jenkins-deploy-platform-ansible-os-6167-1 uve 2026-08-17 03:00:31,048 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-08-17 03:00:31,048 - DEBUG - interface for vm da67b236-d1c1-44d2-ad37-3198cccdca22 deleted from vrouter uve ... 2026-08-17 03:00:31,048 - DEBUG - Deleted interface not in error interface list ... 2026-08-17 03:00:31,049 - DEBUG - Validated that VM da67b236-d1c1-44d2-ad37-3198cccdca22 is removed in Vrouter UVE 2026-08-17 03:00:31,049 - DEBUG - Verifying through opserver in 10.0.0.177 2026-08-17 03:00:31,049 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542?flat 2026-08-17 03:00:31,073 - DEBUG - da67b236-d1c1-44d2-ad37-3198cccdca22 BM not in default-domain:ctest-TestPolicyAcl-42565280:ctest-VN1-32761542 uve 2026-08-17 03:00:31,073 - DEBUG - Validated that VM da67b236-d1c1-44d2-ad37-3198cccdca22 is not present in the VN UVE 2026-08-17 03:00:31,073 - DEBUG - Verifying the da67b236-d1c1-44d2-ad37-3198cccdca22 virtual network link through opserver 10.0.0.177 2026-08-17 03:00:31,073 - DEBUG - Requesting: http://10.0.0.177:8081/analytics/uves/virtual-machines 2026-08-17 03:00:31,087 - INFO - da67b236-d1c1-44d2-ad37-3198cccdca22 vm uve deleted from opserver 2026-08-17 03:00:31,087 - DEBUG - Verifying the da67b236-d1c1-44d2-ad37-3198cccdca22 virtual network link through opserver 10.0.0.190 2026-08-17 03:00:31,087 - DEBUG - Requesting: http://10.0.0.190:8081/analytics/uves/virtual-machines 2026-08-17 03:00:31,109 - INFO - da67b236-d1c1-44d2-ad37-3198cccdca22 vm uve deleted from opserver 2026-08-17 03:00:31,110 - DEBUG - Verifying the da67b236-d1c1-44d2-ad37-3198cccdca22 virtual network link through opserver 10.0.0.38 2026-08-17 03:00:31,110 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-machines 2026-08-17 03:00:31,124 - INFO - da67b236-d1c1-44d2-ad37-3198cccdca22 vm uve deleted from opserver 2026-08-17 03:00:31,124 - INFO - Validated that VM da67b236-d1c1-44d2-ad37-3198cccdca22 is removed from Opserver 2026-08-17 03:00:31,124 - DEBUG - Detaching the Policy for VN :ctest-VN2-82822838 2026-08-17 03:00:31,124 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 03:00:31,132 - DEBUG - Requesting: http://10.0.0.177:8082/domain/2b2edc1c-1b78-4df2-9472-fa661dfea482 2026-08-17 03:00:31,144 - DEBUG - Requesting: http://10.0.0.177:8082/project/0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 03:00:31,281 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/e93fa069-c622-4321-9ec3-a167b453b336 2026-08-17 03:00:31,308 - DEBUG - Requesting: http://10.0.0.177:8082/network-policy/533b99b7-a7fb-4721-baf0-4bbe6ab947ac 2026-08-17 03:00:31,487 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAcl-42565280', 'ctest-policy21-68920213']] from ctest-VN2-82822838 2026-08-17 03:00:31,487 - DEBUG - Detaching the Policy for VN :ctest-VN1-32761542 2026-08-17 03:00:31,487 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 03:00:31,494 - DEBUG - Requesting: http://10.0.0.177:8082/domain/2b2edc1c-1b78-4df2-9472-fa661dfea482 2026-08-17 03:00:31,509 - DEBUG - Requesting: http://10.0.0.177:8082/project/0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 03:00:31,648 - DEBUG - Requesting: http://10.0.0.177:8082/virtual-network/57f40c0a-fdbf-459b-988f-df5d2fbcb901 2026-08-17 03:00:31,892 - INFO - Detached Policy from ctest-VN1-32761542 2026-08-17 03:00:31,944 - INFO - Deleted policy ctest-policy13-88634337 2026-08-17 03:00:31,996 - INFO - Deleted policy ctest-policy21-68920213 2026-08-17 03:00:32,039 - INFO - Deleted policy ctest-policy12-63488760 2026-08-17 03:00:32,039 - INFO - Deleting VN ctest-VN3-47714290 2026-08-17 03:00:32,311 - DEBUG - Response for deleting network () 2026-08-17 03:00:32,311 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/f0be616e-e508-4454-87a5-6d992bce869c 2026-08-17 03:00:32,318 - DEBUG - Response Code: 404 2026-08-17 03:00:32,318 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 03:00:32,325 - DEBUG - Requesting: http://10.0.0.177:8082/domain/2b2edc1c-1b78-4df2-9472-fa661dfea482 2026-08-17 03:00:32,344 - DEBUG - Requesting: http://10.0.0.177:8082/project/0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 03:00:32,462 - INFO - Validated that VN ctest-VN3-47714290 is not found in API Server 2026-08-17 03:00:32,476 - DEBUG - VN ctest-VN3-47714290 is not present in Agent 10.0.0.50 2026-08-17 03:00:32,488 - DEBUG - VN ctest-VN3-47714290 is not present in Agent 10.0.0.186 2026-08-17 03:00:32,488 - INFO - Validated that VN ctest-VN3-47714290 is not in any agent 2026-08-17 03:00:32,525 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-47714290 info 2026-08-17 03:00:32,525 - INFO - Deleting VN ctest-VN2-82822838 2026-08-17 03:00:32,763 - DEBUG - Response for deleting network () 2026-08-17 03:00:32,763 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/0996b0a1-20a3-4fb5-a4f9-78472efbfc23 2026-08-17 03:00:32,771 - DEBUG - Response Code: 404 2026-08-17 03:00:32,771 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 03:00:32,779 - DEBUG - Requesting: http://10.0.0.177:8082/domain/2b2edc1c-1b78-4df2-9472-fa661dfea482 2026-08-17 03:00:32,798 - DEBUG - Requesting: http://10.0.0.177:8082/project/0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 03:00:32,908 - INFO - Validated that VN ctest-VN2-82822838 is not found in API Server 2026-08-17 03:00:32,919 - DEBUG - VN ctest-VN2-82822838 is not present in Agent 10.0.0.50 2026-08-17 03:00:32,931 - DEBUG - VN ctest-VN2-82822838 is not present in Agent 10.0.0.186 2026-08-17 03:00:32,931 - INFO - Validated that VN ctest-VN2-82822838 is not in any agent 2026-08-17 03:00:32,967 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-82822838 info 2026-08-17 03:00:32,967 - INFO - Deleting VN ctest-VN1-32761542 2026-08-17 03:00:33,158 - DEBUG - Response for deleting network () 2026-08-17 03:00:33,158 - DEBUG - Requesting: http://10.0.0.177:8082/routing-instance/e7cdeaf5-e121-42e0-bcb4-513676b846bb 2026-08-17 03:00:33,166 - DEBUG - Response Code: 404 2026-08-17 03:00:33,166 - DEBUG - Requesting: http://10.0.0.177:8082/domains 2026-08-17 03:00:33,174 - DEBUG - Requesting: http://10.0.0.177:8082/domain/2b2edc1c-1b78-4df2-9472-fa661dfea482 2026-08-17 03:00:33,237 - DEBUG - Requesting: http://10.0.0.177:8082/project/0c16a75b-ca52-4b84-8372-87b3a46aa0ab 2026-08-17 03:00:33,346 - INFO - Validated that VN ctest-VN1-32761542 is not found in API Server 2026-08-17 03:00:33,361 - DEBUG - VN ctest-VN1-32761542 is not present in Agent 10.0.0.50 2026-08-17 03:00:33,375 - DEBUG - VN ctest-VN1-32761542 is not present in Agent 10.0.0.186 2026-08-17 03:00:33,375 - INFO - Validated that VN ctest-VN1-32761542 is not in any agent 2026-08-17 03:00:33,408 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-32761542 info 2026-08-17 03:00:33,508 - INFO - IPAM: ipam3 is not found in API Server 2026-08-17 03:00:33,538 - INFO - IPAM:ipam3 is not found in control node 2026-08-17 03:00:33,688 - INFO - IPAM: ipam2 is not found in API Server 2026-08-17 03:00:33,727 - INFO - IPAM:ipam2 is not found in control node 2026-08-17 03:00:33,834 - INFO - IPAM: ipam1 is not found in API Server 2026-08-17 03:00:33,870 - INFO - IPAM:ipam1 is not found in control node 2026-08-17 03:00:35,170 - DEBUG - No XMPP flaps were noticed during the test

2026-08-17 03:00:35,170 - INFO - END TEST : test_policy_inheritance_src_vn_dst_pol : PASSED[0:03:40]

2026-08-17 03:00:35,170 - INFO - -------------------------------------------------------------------------------- 2026-08-17 03:00:36,060 - INFO - Deleted project: ctest-TestPolicyAcl-42565280, ID : 0c16a75b-ca52-4b84-8372-87b3a46aa0ab