2026-06-08 02:45:31,695 - INFO - Domain Default found not creating 2026-06-08 02:45:31,861 - INFO - Project ctest-TestPolicyAcl-56590479 not found, creating it 2026-06-08 02:45:32,420 - INFO - Created Project:ctest-TestPolicyAcl-56590479, ID : 8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:45:33,965 - INFO - ================================================================================ 2026-06-08 02:45:33,965 - INFO - STARTING TEST : test_policy_inheritance_src_vn_dst_pol 2026-06-08 02:45:33,965 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance 2026-06-08 02:45:35,246 - DEBUG - Nothing to compare xmpp stats {'10.0.0.23': {'10.20.0.193': '3', '10.20.0.25': '1'}, '10.0.0.240': {'10.20.0.129': '3', '10.20.0.25': '2'}} with 2026-06-08 02:45:35,246 - INFO - Initial checks done. Running the testcase now 2026-06-08 02:45:35,246 - INFO - 2026-06-08 02:45:36,599 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:45:36,895 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:45:36,926 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2441b78a-01cc-4005-9aa7-6b434eb6fb41 2026-06-08 02:45:36,943 - DEBUG - Requesting: http://10.0.0.33:8082/project/8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:45:37,066 - DEBUG - Requesting: http://10.0.0.33:8082/network-ipam/7641fd73-1124-45c1-abcf-a52f7823898f 2026-06-08 02:45:37,076 - INFO - Verifications in API Server for IPAM: ipam1 passed 2026-06-08 02:45:37,083 - DEBUG - Control-node 10.0.0.33 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56590479:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8521370640612345281', 'uuid-lslong': '12380295523962423695'}, 'enable': 'true', 'created': '2026-06-08T02:45:35', 'last-modified': '2026-06-08T02:45:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.214427'} 2026-06-08 02:45:37,089 - DEBUG - Control-node 10.0.0.249 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56590479:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8521370640612345281', 'uuid-lslong': '12380295523962423695'}, 'enable': 'true', 'created': '2026-06-08T02:45:35', 'last-modified': '2026-06-08T02:45:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.219040'} 2026-06-08 02:45:37,096 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56590479:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8521370640612345281', 'uuid-lslong': '12380295523962423695'}, 'enable': 'true', 'created': '2026-06-08T02:45:35', 'last-modified': '2026-06-08T02:45:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.224546'} 2026-06-08 02:45:37,097 - INFO - Verifications in Control node for IPAM: ipam1 passed 2026-06-08 02:45:37,097 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:45:37,103 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2441b78a-01cc-4005-9aa7-6b434eb6fb41 2026-06-08 02:45:37,114 - DEBUG - Requesting: http://10.0.0.33:8082/project/8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:45:37,220 - DEBUG - Requesting: http://10.0.0.33:8082/network-ipam/c6c85cc5-2678-4257-9ca7-ec5eeb70f272 2026-06-08 02:45:37,229 - INFO - Verifications in API Server for IPAM: ipam2 passed 2026-06-08 02:45:37,235 - DEBUG - Control-node 10.0.0.33 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56590479:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14323800616675590743', 'uuid-lslong': '11288250883448566386'}, 'enable': 'true', 'created': '2026-06-08T02:45:35', 'last-modified': '2026-06-08T02:45:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.997980'} 2026-06-08 02:45:37,240 - DEBUG - Control-node 10.0.0.249 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56590479:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14323800616675590743', 'uuid-lslong': '11288250883448566386'}, 'enable': 'true', 'created': '2026-06-08T02:45:35', 'last-modified': '2026-06-08T02:45:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.016712'} 2026-06-08 02:45:37,245 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56590479:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14323800616675590743', 'uuid-lslong': '11288250883448566386'}, 'enable': 'true', 'created': '2026-06-08T02:45:35', 'last-modified': '2026-06-08T02:45:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.010035'} 2026-06-08 02:45:37,245 - INFO - Verifications in Control node for IPAM: ipam2 passed 2026-06-08 02:45:37,245 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:45:37,251 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2441b78a-01cc-4005-9aa7-6b434eb6fb41 2026-06-08 02:45:37,263 - DEBUG - Requesting: http://10.0.0.33:8082/project/8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:45:37,358 - DEBUG - Requesting: http://10.0.0.33:8082/network-ipam/36445e62-de84-40ae-a612-a5634fb16cd0 2026-06-08 02:45:37,368 - INFO - Verifications in API Server for IPAM: ipam3 passed 2026-06-08 02:45:37,374 - DEBUG - Control-node 10.0.0.33 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56590479:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3910354155197448366', 'uuid-lslong': '11966809005834202320'}, 'enable': 'true', 'created': '2026-06-08T02:45:36', 'last-modified': '2026-06-08T02:45:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.795684'} 2026-06-08 02:45:37,381 - DEBUG - Control-node 10.0.0.249 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56590479:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3910354155197448366', 'uuid-lslong': '11966809005834202320'}, 'enable': 'true', 'created': '2026-06-08T02:45:36', 'last-modified': '2026-06-08T02:45:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.816865'} 2026-06-08 02:45:37,386 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56590479:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3910354155197448366', 'uuid-lslong': '11966809005834202320'}, 'enable': 'true', 'created': '2026-06-08T02:45:36', 'last-modified': '2026-06-08T02:45:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.820273'} 2026-06-08 02:45:37,386 - INFO - Verifications in Control node for IPAM: ipam3 passed 2026-06-08 02:45:37,697 - DEBUG - Response for create_network : {'network': {'id': '9dfb0857-4e4a-4e1b-b329-5af1cbc51a93', 'name': 'ctest-VN1-93485738', 'tenant_id': '8bd0efefb1af454b814575c3f392db3b', 'project_id': '8bd0efefb1af454b814575c3f392db3b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-56590479', 'ctest-VN1-93485738'], 'port_security_enabled': True, 'description': ''}} 2026-06-08 02:45:38,003 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fdbb8863-d626-45eb-8761-27008abaeb3f', 'name': '', 'tenant_id': '8bd0efefb1af454b814575c3f392db3b', 'network_id': '9dfb0857-4e4a-4e1b-b329-5af1cbc51a93', 'ip_version': 4, 'cidr': '96.17.181.128/26', 'allocation_pools': [{'start': '96.17.181.130', 'end': '96.17.181.190'}], 'gateway_ip': '96.17.181.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '96.17.181.130', 'tags': [], 'project_id': '8bd0efefb1af454b814575c3f392db3b'}} 2026-06-08 02:45:38,028 - INFO - Created VN ctest-VN1-93485738 2026-06-08 02:45:38,042 - DEBUG - VN ctest-VN1-93485738 UUID is 9dfb0857-4e4a-4e1b-b329-5af1cbc51a93 2026-06-08 02:45:38,199 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738(9dfb0857-4e4a-4e1b-b329-5af1cbc51a93) with subnets ['96.17.181.128/26'] 2026-06-08 02:45:38,583 - DEBUG - Response for create_network : {'network': {'id': '1e0fa0f9-5e0a-43cd-ab56-32c0005835aa', 'name': 'ctest-VN2-53207202', 'tenant_id': '8bd0efefb1af454b814575c3f392db3b', 'project_id': '8bd0efefb1af454b814575c3f392db3b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-56590479', 'ctest-VN2-53207202'], 'port_security_enabled': True, 'description': ''}} 2026-06-08 02:45:38,906 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b61f1dca-0b54-4d86-a4f3-41559abd3e06', 'name': '', 'tenant_id': '8bd0efefb1af454b814575c3f392db3b', 'network_id': '1e0fa0f9-5e0a-43cd-ab56-32c0005835aa', 'ip_version': 4, 'cidr': '221.33.84.0/26', 'allocation_pools': [{'start': '221.33.84.2', 'end': '221.33.84.62'}], 'gateway_ip': '221.33.84.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '221.33.84.2', 'tags': [], 'project_id': '8bd0efefb1af454b814575c3f392db3b'}} 2026-06-08 02:45:38,928 - INFO - Created VN ctest-VN2-53207202 2026-06-08 02:45:38,947 - DEBUG - VN ctest-VN2-53207202 UUID is 1e0fa0f9-5e0a-43cd-ab56-32c0005835aa 2026-06-08 02:45:39,378 - DEBUG - Response for create_network : {'network': {'id': 'f6aab222-0ed0-4ff5-8020-f0f577393a17', 'name': 'ctest-VN3-64063234', 'tenant_id': '8bd0efefb1af454b814575c3f392db3b', 'project_id': '8bd0efefb1af454b814575c3f392db3b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-56590479', 'ctest-VN3-64063234'], 'port_security_enabled': True, 'description': ''}} 2026-06-08 02:45:39,667 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b1bdfcd5-914c-4fc1-93e3-b5378df80665', 'name': '', 'tenant_id': '8bd0efefb1af454b814575c3f392db3b', 'network_id': 'f6aab222-0ed0-4ff5-8020-f0f577393a17', 'ip_version': 4, 'cidr': '114.77.37.64/26', 'allocation_pools': [{'start': '114.77.37.66', 'end': '114.77.37.126'}], 'gateway_ip': '114.77.37.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '114.77.37.66', 'tags': [], 'project_id': '8bd0efefb1af454b814575c3f392db3b'}} 2026-06-08 02:45:39,687 - INFO - Created VN ctest-VN3-64063234 2026-06-08 02:45:39,703 - DEBUG - VN ctest-VN3-64063234 UUID is f6aab222-0ed0-4ff5-8020-f0f577393a17 2026-06-08 02:45:39,774 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:45:39,780 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2441b78a-01cc-4005-9aa7-6b434eb6fb41 2026-06-08 02:45:39,792 - DEBUG - Requesting: http://10.0.0.33:8082/project/8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:45:39,893 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/9dfb0857-4e4a-4e1b-b329-5af1cbc51a93 2026-06-08 02:45:39,906 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/9dfb0857-4e4a-4e1b-b329-5af1cbc51a93 2026-06-08 02:45:39,915 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/8a0df807-2dad-47a5-97cb-c85343f9dc72 2026-06-08 02:45:39,923 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/8a0df807-2dad-47a5-97cb-c85343f9dc72 2026-06-08 02:45:39,931 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/dff25dcd-8a0d-470b-98e2-44eaccbbd4e8 2026-06-08 02:45:39,938 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-06-08 02:45:39,938 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/9dfb0857-4e4a-4e1b-b329-5af1cbc51a93 2026-06-08 02:45:39,949 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/8a0df807-2dad-47a5-97cb-c85343f9dc72 2026-06-08 02:45:39,967 - INFO - Verified VN network id 15 for VN 9dfb0857-4e4a-4e1b-b329-5af1cbc51a93 2026-06-08 02:45:39,968 - INFO - Verifications in API Server for VN ctest-VN1-93485738 passed 2026-06-08 02:45:39,968 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/9dfb0857-4e4a-4e1b-b329-5af1cbc51a93 2026-06-08 02:45:39,978 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/8a0df807-2dad-47a5-97cb-c85343f9dc72 2026-06-08 02:45:39,989 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/8a0df807-2dad-47a5-97cb-c85343f9dc72 2026-06-08 02:45:39,997 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/dff25dcd-8a0d-470b-98e2-44eaccbbd4e8 2026-06-08 02:45:40,008 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11383701654177730075', 'uuid-lslong': '12909949801386547859'}, 'enable': 'true', 'created': '2026-06-08T02:45:37', 'last-modified': '2026-06-08T02:45:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.066420'} 2026-06-08 02:45:40,014 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-06-08 02:45:40,019 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11383701654177730075', 'uuid-lslong': '12909949801386547859'}, 'enable': 'true', 'created': '2026-06-08T02:45:37', 'last-modified': '2026-06-08T02:45:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.072874'} 2026-06-08 02:45:40,025 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-06-08 02:45:40,031 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11383701654177730075', 'uuid-lslong': '12909949801386547859'}, 'enable': 'true', 'created': '2026-06-08T02:45:37', 'last-modified': '2026-06-08T02:45:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.089596'} 2026-06-08 02:45:40,037 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-06-08 02:45:40,037 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-93485738 passed 2026-06-08 02:45:40,037 - DEBUG - ====Verifying policy data for ctest-VN1-93485738 in API_Server ====== 2026-06-08 02:45:40,037 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:45:40,044 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2441b78a-01cc-4005-9aa7-6b434eb6fb41 2026-06-08 02:45:40,055 - DEBUG - Requesting: http://10.0.0.33:8082/project/8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:45:40,145 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/9dfb0857-4e4a-4e1b-b329-5af1cbc51a93 2026-06-08 02:45:40,158 - DEBUG - =>VN ctest-VN1-93485738 has no policy to be verified 2026-06-08 02:45:40,158 - DEBUG - Verifying the vn in opserver 2026-06-08 02:45:40,158 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 virtual network link through opserver 10.0.0.33 2026-06-08 02:45:40,158 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2026-06-08 02:45:40,459 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2026-06-08 02:45:40,523 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738?flat'} 2026-06-08 02:45:40,523 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 virtual network link through opserver 10.0.0.249 2026-06-08 02:45:40,523 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks 2026-06-08 02:45:40,819 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks 2026-06-08 02:45:40,884 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738?flat'} 2026-06-08 02:45:40,884 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 virtual network link through opserver 10.0.0.27 2026-06-08 02:45:40,884 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-06-08 02:45:41,187 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-06-08 02:45:41,264 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738?flat'} 2026-06-08 02:45:41,264 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 is found in opserver 2026-06-08 02:45:41,286 - DEBUG - Do not have enough data to verify VN in agent 2026-06-08 02:45:41,297 - DEBUG - VRF ids for VN ctest-VN1-93485738: {} 2026-06-08 02:45:41,297 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:45:41,302 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2441b78a-01cc-4005-9aa7-6b434eb6fb41 2026-06-08 02:45:41,313 - DEBUG - Requesting: http://10.0.0.33:8082/project/8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:45:41,399 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1e0fa0f9-5e0a-43cd-ab56-32c0005835aa 2026-06-08 02:45:41,413 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1e0fa0f9-5e0a-43cd-ab56-32c0005835aa 2026-06-08 02:45:41,421 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b827d75e-7bc6-4af0-b0e1-6e5d79be5aa8 2026-06-08 02:45:41,431 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b827d75e-7bc6-4af0-b0e1-6e5d79be5aa8 2026-06-08 02:45:41,438 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/2cbe0643-4348-4125-9c45-c1b0ce1743f6 2026-06-08 02:45:41,447 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-06-08 02:45:41,447 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1e0fa0f9-5e0a-43cd-ab56-32c0005835aa 2026-06-08 02:45:41,457 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b827d75e-7bc6-4af0-b0e1-6e5d79be5aa8 2026-06-08 02:45:41,475 - INFO - Verified VN network id 16 for VN 1e0fa0f9-5e0a-43cd-ab56-32c0005835aa 2026-06-08 02:45:41,475 - INFO - Verifications in API Server for VN ctest-VN2-53207202 passed 2026-06-08 02:45:41,475 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1e0fa0f9-5e0a-43cd-ab56-32c0005835aa 2026-06-08 02:45:41,487 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b827d75e-7bc6-4af0-b0e1-6e5d79be5aa8 2026-06-08 02:45:41,496 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b827d75e-7bc6-4af0-b0e1-6e5d79be5aa8 2026-06-08 02:45:41,503 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/2cbe0643-4348-4125-9c45-c1b0ce1743f6 2026-06-08 02:45:41,515 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2166126938673529805', 'uuid-lslong': '12346111228703684010'}, 'enable': 'true', 'created': '2026-06-08T02:45:38', 'last-modified': '2026-06-08T02:45:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.677340'} 2026-06-08 02:45:41,521 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-06-08 02:45:41,526 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2166126938673529805', 'uuid-lslong': '12346111228703684010'}, 'enable': 'true', 'created': '2026-06-08T02:45:38', 'last-modified': '2026-06-08T02:45:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.691461'} 2026-06-08 02:45:41,531 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-06-08 02:45:41,541 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2166126938673529805', 'uuid-lslong': '12346111228703684010'}, 'enable': 'true', 'created': '2026-06-08T02:45:38', 'last-modified': '2026-06-08T02:45:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.707413'} 2026-06-08 02:45:41,549 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-06-08 02:45:41,550 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-53207202 passed 2026-06-08 02:45:41,550 - DEBUG - ====Verifying policy data for ctest-VN2-53207202 in API_Server ====== 2026-06-08 02:45:41,550 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:45:41,558 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2441b78a-01cc-4005-9aa7-6b434eb6fb41 2026-06-08 02:45:41,572 - DEBUG - Requesting: http://10.0.0.33:8082/project/8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:45:41,663 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1e0fa0f9-5e0a-43cd-ab56-32c0005835aa 2026-06-08 02:45:41,676 - DEBUG - =>VN ctest-VN2-53207202 has no policy to be verified 2026-06-08 02:45:41,676 - DEBUG - Verifying the vn in opserver 2026-06-08 02:45:41,677 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202 virtual network link through opserver 10.0.0.33 2026-06-08 02:45:41,677 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2026-06-08 02:45:41,751 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202?flat'} 2026-06-08 02:45:41,751 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202 virtual network link through opserver 10.0.0.249 2026-06-08 02:45:41,751 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks 2026-06-08 02:45:41,814 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202?flat'} 2026-06-08 02:45:41,814 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202 virtual network link through opserver 10.0.0.27 2026-06-08 02:45:41,814 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-06-08 02:45:41,876 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202?flat'} 2026-06-08 02:45:41,876 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202 is found in opserver 2026-06-08 02:45:41,900 - DEBUG - Do not have enough data to verify VN in agent 2026-06-08 02:45:41,912 - DEBUG - VRF ids for VN ctest-VN2-53207202: {} 2026-06-08 02:45:41,912 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:45:41,918 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2441b78a-01cc-4005-9aa7-6b434eb6fb41 2026-06-08 02:45:41,929 - DEBUG - Requesting: http://10.0.0.33:8082/project/8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:45:42,023 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/f6aab222-0ed0-4ff5-8020-f0f577393a17 2026-06-08 02:45:42,035 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/f6aab222-0ed0-4ff5-8020-f0f577393a17 2026-06-08 02:45:42,043 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/233d14b8-bb00-4150-8799-8c1b127e064d 2026-06-08 02:45:42,050 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/233d14b8-bb00-4150-8799-8c1b127e064d 2026-06-08 02:45:42,058 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/97c68da5-d075-4679-a74d-2d71361d8df6 2026-06-08 02:45:42,065 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-06-08 02:45:42,065 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/f6aab222-0ed0-4ff5-8020-f0f577393a17 2026-06-08 02:45:42,074 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/233d14b8-bb00-4150-8799-8c1b127e064d 2026-06-08 02:45:42,092 - INFO - Verified VN network id 17 for VN f6aab222-0ed0-4ff5-8020-f0f577393a17 2026-06-08 02:45:42,092 - INFO - Verifications in API Server for VN ctest-VN3-64063234 passed 2026-06-08 02:45:42,092 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/f6aab222-0ed0-4ff5-8020-f0f577393a17 2026-06-08 02:45:42,101 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/233d14b8-bb00-4150-8799-8c1b127e064d 2026-06-08 02:45:42,109 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/233d14b8-bb00-4150-8799-8c1b127e064d 2026-06-08 02:45:42,116 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/97c68da5-d075-4679-a74d-2d71361d8df6 2026-06-08 02:45:42,128 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17774214738718248949', 'uuid-lslong': '9232644173167409687'}, 'enable': 'true', 'created': '2026-06-08T02:45:39', 'last-modified': '2026-06-08T02:45:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.531269'} 2026-06-08 02:45:42,134 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-06-08 02:45:42,139 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17774214738718248949', 'uuid-lslong': '9232644173167409687'}, 'enable': 'true', 'created': '2026-06-08T02:45:39', 'last-modified': '2026-06-08T02:45:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.542272'} 2026-06-08 02:45:42,145 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-06-08 02:45:42,153 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56590479', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17774214738718248949', 'uuid-lslong': '9232644173167409687'}, 'enable': 'true', 'created': '2026-06-08T02:45:39', 'last-modified': '2026-06-08T02:45:39', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.551035'} 2026-06-08 02:45:42,159 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-06-08 02:45:42,159 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-64063234 passed 2026-06-08 02:45:42,160 - DEBUG - ====Verifying policy data for ctest-VN3-64063234 in API_Server ====== 2026-06-08 02:45:42,160 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:45:42,166 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2441b78a-01cc-4005-9aa7-6b434eb6fb41 2026-06-08 02:45:42,176 - DEBUG - Requesting: http://10.0.0.33:8082/project/8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:45:42,274 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/f6aab222-0ed0-4ff5-8020-f0f577393a17 2026-06-08 02:45:42,285 - DEBUG - =>VN ctest-VN3-64063234 has no policy to be verified 2026-06-08 02:45:42,285 - DEBUG - Verifying the vn in opserver 2026-06-08 02:45:42,285 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234 virtual network link through opserver 10.0.0.33 2026-06-08 02:45:42,285 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2026-06-08 02:45:42,342 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234?flat'} 2026-06-08 02:45:42,342 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234 virtual network link through opserver 10.0.0.249 2026-06-08 02:45:42,342 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks 2026-06-08 02:45:42,403 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234?flat'} 2026-06-08 02:45:42,403 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234 virtual network link through opserver 10.0.0.27 2026-06-08 02:45:42,403 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks 2026-06-08 02:45:42,466 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234?flat'} 2026-06-08 02:45:42,466 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234 is found in opserver 2026-06-08 02:45:42,491 - DEBUG - Do not have enough data to verify VN in agent 2026-06-08 02:45:42,501 - DEBUG - VRF ids for VN ctest-VN3-64063234: {} 2026-06-08 02:45:42,508 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738, 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-56590479:ctest-VN2-53207202, 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-06-08 02:45:42,831 - DEBUG - No destination network defined 2026-06-08 02:45:42,831 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202, 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-56590479:ctest-policy13-44688988, 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-06-08 02:45:43,131 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738, 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-56590479:ctest-VN3-64063234, 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-06-08 02:45:43,383 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:45:43,389 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2441b78a-01cc-4005-9aa7-6b434eb6fb41 2026-06-08 02:45:43,401 - DEBUG - Requesting: http://10.0.0.33:8082/project/8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:45:43,510 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/9dfb0857-4e4a-4e1b-b329-5af1cbc51a93 2026-06-08 02:45:43,520 - DEBUG - Setup step: Associating the policy to VN' 2026-06-08 02:45:43,746 - INFO - Associated Policy to ctest-VN1-93485738 2026-06-08 02:45:43,747 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:45:43,753 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2441b78a-01cc-4005-9aa7-6b434eb6fb41 2026-06-08 02:45:43,768 - DEBUG - Requesting: http://10.0.0.33:8082/project/8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:45:43,867 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1e0fa0f9-5e0a-43cd-ab56-32c0005835aa 2026-06-08 02:45:43,882 - DEBUG - Setup step: Associating the policy to VN' 2026-06-08 02:45:44,000 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '1e0fa0f9-5e0a-43cd-ab56-32c0005835aa', 'name': 'ctest-VN2-53207202', 'tenant_id': '8bd0efefb1af454b814575c3f392db3b', 'project_id': '8bd0efefb1af454b814575c3f392db3b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['b61f1dca-0b54-4d86-a4f3-41559abd3e06'], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-56590479', 'ctest-VN2-53207202'], 'policys': [['default-domain', 'ctest-TestPolicyAcl-56590479', 'ctest-policy21-49423054']], 'subnet_ipam': [{'subnet_cidr': '221.33.84.0/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAcl-56590479', 'ipam2']}], 'port_security_enabled': True, 'description': ''}} 2026-06-08 02:45:44,146 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAcl-56590479', 'ctest-policy21-49423054']] to ctest-VN2-53207202 2026-06-08 02:45:44,258 - DEBUG - Services list from nova: [, , , ] 2026-06-08 02:45:46,022 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5901-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5901-2) 2026-06-08 02:45:47,543 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5901-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5901-1) 2026-06-08 02:45:49,188 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5901-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5901-2) 2026-06-08 02:45:49,284 - DEBUG - VM is in ACTIVE state now 2026-06-08 02:45:49,284 - INFO - VM name : ctest-VM11-44863839 2026-06-08 02:45:49,378 - DEBUG - VM ctest-VM11-44863839 ID is 6f85cbc4-76f2-4774-8683-4b6b7881fc63 2026-06-08 02:45:49,460 - DEBUG - VM is in ACTIVE state now 2026-06-08 02:45:49,487 - DEBUG - VM ctest-VM11-44863839 launched on Node an-jenkins-deploy-platform-ansible-os-5901-2 2026-06-08 02:45:49,588 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/6f85cbc4-76f2-4774-8683-4b6b7881fc63 2026-06-08 02:45:50,029 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/6f85cbc4-76f2-4774-8683-4b6b7881fc63 2026-06-08 02:45:50,075 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/6f85cbc4-76f2-4774-8683-4b6b7881fc63 2026-06-08 02:45:50,084 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/f0a81eb5-e658-4667-a886-372ffc843693 2026-06-08 02:45:50,095 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/6f85cbc4-76f2-4774-8683-4b6b7881fc63 2026-06-08 02:45:50,129 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/f0a81eb5-e658-4667-a886-372ffc843693 2026-06-08 02:45:50,141 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/83806f62-cd71-4bd9-acac-0dc954713592 2026-06-08 02:45:50,179 - DEBUG - Verifying in api server 10.0.0.27 2026-06-08 02:45:50,179 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/6f85cbc4-76f2-4774-8683-4b6b7881fc63 2026-06-08 02:45:50,191 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/f0a81eb5-e658-4667-a886-372ffc843693 2026-06-08 02:45:50,347 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/83806f62-cd71-4bd9-acac-0dc954713592 2026-06-08 02:45:50,357 - INFO - VM ctest-VM11-44863839 verfication in all API Servers passed 2026-06-08 02:45:50,374 - DEBUG - VM ctest-VM11-44863839 Tap interface: {'index': '3', 'name': 'tapf0a81eb5-e6', 'uuid': 'f0a81eb5-e658-4667-a886-372ffc843693', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738:ctest-VN1-93485738', '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': '15', 'vn_name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738', 'vm_uuid': '6f85cbc4-76f2-4774-8683-4b6b7881fc63', 'vm_name': 'ctest-VM11-44863839', 'ip_addr': '96.17.181.131', 'mac_addr': '02:f0:a8:1e:b5:e6', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '46', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-56590479:f0a81eb5-e658-4667-a886-372ffc843693', 'sg_uuid_list': ['250b71a2-f93b-41b4-992e-2e758482b768'], 'static_route_list': None, 'vm_project_uuid': '8bd0efef-b1af-454b-8145-75c3f392db3b', 'admin_state': 'Enabled', 'flow_key_idx': '31', '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-06-08 02:45:50,375 - DEBUG - Agent 10.0.0.240 vrf name: default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738:ctest-VN1-93485738 2026-06-08 02:45:50,380 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738:ctest-VN1-93485738', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738', 'table_label': '-1', 'vxlan_id': '15', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.192:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-06-08 02:45:50,464 - DEBUG - VM ctest-VM11-44863839 : Tap interface tapf0a81eb5-e6 is set to Active 2026-06-08 02:45:50,465 - DEBUG - Tap interface tapf0a81eb5-e6 detail : {'index': '3', 'name': 'tapf0a81eb5-e6', 'uuid': 'f0a81eb5-e658-4667-a886-372ffc843693', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738:ctest-VN1-93485738', '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': '15', 'vn_name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738', 'vm_uuid': '6f85cbc4-76f2-4774-8683-4b6b7881fc63', 'vm_name': 'ctest-VM11-44863839', 'ip_addr': '96.17.181.131', 'mac_addr': '02:f0:a8:1e:b5:e6', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '46', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-56590479:f0a81eb5-e658-4667-a886-372ffc843693', 'sg_uuid_list': ['250b71a2-f93b-41b4-992e-2e758482b768'], 'static_route_list': None, 'vm_project_uuid': '8bd0efef-b1af-454b-8145-75c3f392db3b', 'admin_state': 'Enabled', 'flow_key_idx': '31', '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-06-08 02:45:50,581 - DEBUG - Starting Layer 2 verification in Agent 2026-06-08 02:45:50,596 - DEBUG - Layer 2 path is seen for VM MAC 02:f0:a8:1e:b5:e6 in agent 10.0.0.240 2026-06-08 02:45:50,596 - DEBUG - Active layer 2 route in agent is present for VMI tapf0a81eb5-e6 2026-06-08 02:45:50,596 - DEBUG - L2 label(29) matches bw route table and interface table 2026-06-08 02:45:50,610 - DEBUG - VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 is found in Agent of node 10.0.0.240 2026-06-08 02:45:50,745 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 is consistent in agent 10.0.0.240 2026-06-08 02:45:50,745 - DEBUG - Route for VM IP ['96.17.181.131'] is consistent in agent 10.0.0.240 2026-06-08 02:45:50,745 - DEBUG - VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 verification for VM ctest-VM11-44863839 in Agent 10.0.0.240 passed 2026-06-08 02:45:50,818 - DEBUG - Starting all layer 2 verification in agent 10.0.0.240 2026-06-08 02:45:50,878 - DEBUG - Route for VM MAC 02:f0:a8:1e:b5:e6 is consistent in agent 10.0.0.240 2026-06-08 02:45:54,033 - 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-06-08 02:45:54,033 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-44863839 failed! 2026-06-08 02:45:54,049 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 is 96.17.181.129 and allocation pool is NOT set 2026-06-08 02:45:58,129 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1021ms') 2026-06-08 02:45:58,129 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-44863839 failed! 2026-06-08 02:45:58,144 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 is 96.17.181.129 and allocation pool is NOT set 2026-06-08 02:46:02,225 - 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 1004ms') 2026-06-08 02:46:02,225 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-44863839 failed! 2026-06-08 02:46:02,242 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 is 96.17.181.129 and allocation pool is NOT set 2026-06-08 02:46:06,315 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2026-06-08 02:46:06,315 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-44863839 failed! 2026-06-08 02:46:06,330 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 is 96.17.181.129 and allocation pool is NOT set 2026-06-08 02:46:10,420 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1027ms') 2026-06-08 02:46:10,420 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-44863839 failed! 2026-06-08 02:46:10,436 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 is 96.17.181.129 and allocation pool is NOT set 2026-06-08 02:46:14,513 - 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-06-08 02:46:14,513 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-44863839 failed! 2026-06-08 02:46:14,529 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 is 96.17.181.129 and allocation pool is NOT set 2026-06-08 02:46:18,609 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2026-06-08 02:46:18,609 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-44863839 failed! 2026-06-08 02:46:18,624 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 is 96.17.181.129 and allocation pool is NOT set 2026-06-08 02:46:22,703 - 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-06-08 02:46:22,703 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-44863839 failed! 2026-06-08 02:46:22,716 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 is 96.17.181.129 and allocation pool is NOT set 2026-06-08 02:46:26,801 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms') 2026-06-08 02:46:26,801 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-44863839 failed! 2026-06-08 02:46:26,817 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 is 96.17.181.129 and allocation pool is NOT set 2026-06-08 02:46:30,895 - 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-06-08 02:46:30,896 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-44863839 failed! 2026-06-08 02:46:30,914 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 is 96.17.181.129 and allocation pool is NOT set 2026-06-08 02:46:34,995 - 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=8.39 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1019ms\r\nrtt min/avg/max/mdev = 8.385/8.385/8.385/0.000 ms') 2026-06-08 02:46:34,995 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-44863839 passed 2026-06-08 02:46:34,995 - INFO - VM ctest-VM11-44863839 verifications in Compute nodes passed 2026-06-08 02:46:35,246 - DEBUG - Validated VM route 96.17.181.131 in vrouter of 10.0.0.240 2026-06-08 02:46:35,253 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-06-08 02:46:35,253 - INFO - Validated routes of VM ctest-VM11-44863839 in all vrouters 2026-06-08 02:46:35,696 - DEBUG - Starting all layer2 verification in 10.0.0.33 Control Node 2026-06-08 02:46:35,826 - DEBUG - Layer2 route found for VM MAC 02:f0:a8:1e:b5:e6 in Control-node 10.0.0.33 2026-06-08 02:46:35,826 - DEBUG - L2 Label for VM ctest-VM11-44863839 same between Control-node 10.0.0.33 and Agent, Expected: 29, Seen: 29 2026-06-08 02:46:35,826 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node 2026-06-08 02:46:35,957 - DEBUG - Layer2 route found for VM MAC 02:f0:a8:1e:b5:e6 in Control-node 10.0.0.27 2026-06-08 02:46:35,957 - DEBUG - L2 Label for VM ctest-VM11-44863839 same between Control-node 10.0.0.27 and Agent, Expected: 29, Seen: 29 2026-06-08 02:46:35,957 - DEBUG - Starting all layer2 verification in 10.0.0.249 Control Node 2026-06-08 02:46:36,084 - DEBUG - Layer2 route found for VM MAC 02:f0:a8:1e:b5:e6 in Control-node 10.0.0.249 2026-06-08 02:46:36,084 - DEBUG - L2 Label for VM ctest-VM11-44863839 same between Control-node 10.0.0.249 and Agent, Expected: 29, Seen: 29 2026-06-08 02:46:36,084 - INFO - Verification in Control-nodes for VM ctest-VM11-44863839 passed 2026-06-08 02:46:36,084 - DEBUG - Verifying the vm in opserver 2026-06-08 02:46:36,084 - DEBUG - Verifying in collector 10.0.0.27 ... 2026-06-08 02:46:36,084 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/6f85cbc4-76f2-4774-8683-4b6b7881fc63?flat 2026-06-08 02:46:36,104 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56590479:f0a81eb5-e658-4667-a886-372ffc843693?flat 2026-06-08 02:46:36,125 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56590479:f0a81eb5-e658-4667-a886-372ffc843693?flat 2026-06-08 02:46:36,145 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/6f85cbc4-76f2-4774-8683-4b6b7881fc63?flat 2026-06-08 02:46:36,164 - DEBUG - Verifying vm in vn uve 2026-06-08 02:46:36,164 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56590479:f0a81eb5-e658-4667-a886-372ffc843693?flat 2026-06-08 02:46:36,182 - DEBUG - VM uve shows interface as {'tx_vlan': 65535, 'virtual_network': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738', 'vm_name': 'ctest-VM11-44863839', '__T': 1780886770530647, 'ip4_active': True, 'is_health_check_active': True, 'rx_vlan': 65535, 'uuid': 'f0a81eb5-e658-4667-a886-372ffc843693', 'fixed_ip4_list': ['96.17.181.131'], 'admin_state': True, 'ip_address': '96.17.181.131', 'gateway': '96.17.181.129', 'ip6_active': False, 'l2_active': True, 'label': 25, 'vm_uuid': '6f85cbc4-76f2-4774-8683-4b6b7881fc63', 'vhostuser_mode': 0, 'vn_uuid': '9dfb0857-4e4a-4e1b-b329-5af1cbc51a93', 'active': True, 'port_mirror_enabled': False, 'ip6_address': '::', 'mac_address': '02:f0:a8:1e:b5:e6'} 2026-06-08 02:46:36,182 - DEBUG - VM uve shows ip address as ['96.17.181.131', '::'] 2026-06-08 02:46:36,182 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 2026-06-08 02:46:36,182 - INFO - Verifying through opserver in 10.0.0.33 2026-06-08 02:46:36,182 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738?flat 2026-06-08 02:46:36,204 - DEBUG - expected vm list 6f85cbc4-76f2-4774-8683-4b6b7881fc63 2026-06-08 02:46:36,204 - DEBUG - Extracted vm list ['6f85cbc4-76f2-4774-8683-4b6b7881fc63'] 2026-06-08 02:46:36,204 - INFO - VM 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is present in default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 2026-06-08 02:46:36,204 - DEBUG - Verifying vm in vrouter uve 2026-06-08 02:46:36,204 - DEBUG - Getting info from collector 10.0.0.27.. 2026-06-08 02:46:36,204 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/6f85cbc4-76f2-4774-8683-4b6b7881fc63?flat 2026-06-08 02:46:36,227 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5901-2'] 2026-06-08 02:46:36,227 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:46:36,261 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:46:36,293 - DEBUG - VM 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:46:36,293 - DEBUG - Validated that VM ctest-VM11-44863839 is in Vrouter an-jenkins-deploy-platform-ansible-os-5901-2 UVE 2026-06-08 02:46:36,293 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-06-08 02:46:36,304 - DEBUG - Expected tap interface of VM uuid 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is default-domain:ctest-TestPolicyAcl-56590479:f0a81eb5-e658-4667-a886-372ffc843693 2026-06-08 02:46:36,304 - DEBUG - Expected VN of VM uuid 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 2026-06-08 02:46:36,304 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:46:36,336 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:46:36,370 - DEBUG - VM 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:46:36,370 - INFO - tap interface default-domain:ctest-TestPolicyAcl-56590479:f0a81eb5-e658-4667-a886-372ffc843693 of vm 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:46:36,370 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 of vm 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:46:36,370 - DEBUG - Verifying the 6f85cbc4-76f2-4774-8683-4b6b7881fc63 virtual network link through opserver 10.0.0.33 2026-06-08 02:46:36,370 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2026-06-08 02:46:36,383 - DEBUG - VM link and name as {'name': '6f85cbc4-76f2-4774-8683-4b6b7881fc63', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-machine/6f85cbc4-76f2-4774-8683-4b6b7881fc63?flat'} 2026-06-08 02:46:36,383 - DEBUG - Verifying the 6f85cbc4-76f2-4774-8683-4b6b7881fc63 virtual network link through opserver 10.0.0.249 2026-06-08 02:46:36,383 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines 2026-06-08 02:46:36,402 - DEBUG - VM link and name as {'name': '6f85cbc4-76f2-4774-8683-4b6b7881fc63', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-machine/6f85cbc4-76f2-4774-8683-4b6b7881fc63?flat'} 2026-06-08 02:46:36,402 - DEBUG - Verifying the 6f85cbc4-76f2-4774-8683-4b6b7881fc63 virtual network link through opserver 10.0.0.27 2026-06-08 02:46:36,402 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2026-06-08 02:46:36,419 - DEBUG - VM link and name as {'name': '6f85cbc4-76f2-4774-8683-4b6b7881fc63', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/6f85cbc4-76f2-4774-8683-4b6b7881fc63?flat'} 2026-06-08 02:46:36,419 - INFO - VM ctest-VM11-44863839 validations in Opserver passed 2026-06-08 02:46:36,542 - DEBUG - VM is in ACTIVE state now 2026-06-08 02:46:36,542 - INFO - VM name : ctest-VM21-98306848 2026-06-08 02:46:36,628 - DEBUG - VM ctest-VM21-98306848 ID is be975cbc-1b9c-45b8-8639-aaa03be3a888 2026-06-08 02:46:36,712 - DEBUG - VM is in ACTIVE state now 2026-06-08 02:46:36,712 - DEBUG - VM ctest-VM21-98306848 launched on Node an-jenkins-deploy-platform-ansible-os-5901-1 2026-06-08 02:46:36,798 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/be975cbc-1b9c-45b8-8639-aaa03be3a888 2026-06-08 02:46:36,813 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/be975cbc-1b9c-45b8-8639-aaa03be3a888 2026-06-08 02:46:36,821 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/05e3fa36-c686-4e11-a22c-72b28267d756 2026-06-08 02:46:36,831 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/be975cbc-1b9c-45b8-8639-aaa03be3a888 2026-06-08 02:46:36,840 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/05e3fa36-c686-4e11-a22c-72b28267d756 2026-06-08 02:46:36,851 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/ee6518fd-99eb-44c3-9ef7-a8b55566c2f3 2026-06-08 02:46:36,861 - DEBUG - Verifying in api server 10.0.0.27 2026-06-08 02:46:36,861 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/be975cbc-1b9c-45b8-8639-aaa03be3a888 2026-06-08 02:46:36,868 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/05e3fa36-c686-4e11-a22c-72b28267d756 2026-06-08 02:46:36,878 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/ee6518fd-99eb-44c3-9ef7-a8b55566c2f3 2026-06-08 02:46:36,887 - INFO - VM ctest-VM21-98306848 verfication in all API Servers passed 2026-06-08 02:46:36,908 - DEBUG - VM ctest-VM21-98306848 Tap interface: {'index': '3', 'name': 'tap05e3fa36-c6', 'uuid': '05e3fa36-c686-4e11-a22c-72b28267d756', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202:ctest-VN2-53207202', '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-56590479:ctest-VN2-53207202', 'vm_uuid': 'be975cbc-1b9c-45b8-8639-aaa03be3a888', 'vm_name': 'ctest-VM21-98306848', 'ip_addr': '221.33.84.3', 'mac_addr': '02:05:e3:fa:36:c6', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '42', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-56590479:05e3fa36-c686-4e11-a22c-72b28267d756', 'sg_uuid_list': ['250b71a2-f93b-41b4-992e-2e758482b768'], 'static_route_list': None, 'vm_project_uuid': '8bd0efef-b1af-454b-8145-75c3f392db3b', 'admin_state': 'Enabled', 'flow_key_idx': '31', '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-06-08 02:46:36,908 - DEBUG - Agent 10.0.0.23 vrf name: default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202:ctest-VN2-53207202 2026-06-08 02:46:36,917 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202:ctest-VN2-53207202', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202', 'table_label': '-1', 'vxlan_id': '16', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.18:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-06-08 02:46:37,005 - DEBUG - VM ctest-VM21-98306848 : Tap interface tap05e3fa36-c6 is set to Active 2026-06-08 02:46:37,005 - DEBUG - Tap interface tap05e3fa36-c6 detail : {'index': '3', 'name': 'tap05e3fa36-c6', 'uuid': '05e3fa36-c686-4e11-a22c-72b28267d756', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202:ctest-VN2-53207202', '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-56590479:ctest-VN2-53207202', 'vm_uuid': 'be975cbc-1b9c-45b8-8639-aaa03be3a888', 'vm_name': 'ctest-VM21-98306848', 'ip_addr': '221.33.84.3', 'mac_addr': '02:05:e3:fa:36:c6', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '42', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-56590479:05e3fa36-c686-4e11-a22c-72b28267d756', 'sg_uuid_list': ['250b71a2-f93b-41b4-992e-2e758482b768'], 'static_route_list': None, 'vm_project_uuid': '8bd0efef-b1af-454b-8145-75c3f392db3b', 'admin_state': 'Enabled', 'flow_key_idx': '31', '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-06-08 02:46:37,131 - DEBUG - Starting Layer 2 verification in Agent 2026-06-08 02:46:37,142 - DEBUG - Layer 2 path is seen for VM MAC 02:05:e3:fa:36:c6 in agent 10.0.0.23 2026-06-08 02:46:37,142 - DEBUG - Active layer 2 route in agent is present for VMI tap05e3fa36-c6 2026-06-08 02:46:37,142 - DEBUG - L2 label(29) matches bw route table and interface table 2026-06-08 02:46:37,150 - DEBUG - VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202 is found in Agent of node 10.0.0.23 2026-06-08 02:46:37,290 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202 is consistent in agent 10.0.0.23 2026-06-08 02:46:37,290 - DEBUG - Route for VM IP ['221.33.84.3'] is consistent in agent 10.0.0.23 2026-06-08 02:46:37,291 - DEBUG - VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202 verification for VM ctest-VM21-98306848 in Agent 10.0.0.23 passed 2026-06-08 02:46:37,409 - DEBUG - Starting all layer 2 verification in agent 10.0.0.23 2026-06-08 02:46:37,421 - DEBUG - Route for VM MAC 02:05:e3:fa:36:c6 is consistent in agent 10.0.0.23 2026-06-08 02:46:38,621 - 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=11.0 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.00 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 3.001/6.985/10.970/3.984 ms') 2026-06-08 02:46:38,621 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-98306848 passed 2026-06-08 02:46:38,621 - INFO - VM ctest-VM21-98306848 verifications in Compute nodes passed 2026-06-08 02:46:38,857 - DEBUG - Validated VM route 221.33.84.3 in vrouter of 10.0.0.23 2026-06-08 02:46:38,864 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-06-08 02:46:38,864 - INFO - Validated routes of VM ctest-VM21-98306848 in all vrouters 2026-06-08 02:46:39,312 - DEBUG - Starting all layer2 verification in 10.0.0.33 Control Node 2026-06-08 02:46:39,438 - DEBUG - Layer2 route found for VM MAC 02:05:e3:fa:36:c6 in Control-node 10.0.0.33 2026-06-08 02:46:39,438 - DEBUG - L2 Label for VM ctest-VM21-98306848 same between Control-node 10.0.0.33 and Agent, Expected: 29, Seen: 29 2026-06-08 02:46:39,438 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node 2026-06-08 02:46:39,570 - DEBUG - Layer2 route found for VM MAC 02:05:e3:fa:36:c6 in Control-node 10.0.0.27 2026-06-08 02:46:39,570 - DEBUG - L2 Label for VM ctest-VM21-98306848 same between Control-node 10.0.0.27 and Agent, Expected: 29, Seen: 29 2026-06-08 02:46:39,570 - DEBUG - Starting all layer2 verification in 10.0.0.249 Control Node 2026-06-08 02:46:39,694 - DEBUG - Layer2 route found for VM MAC 02:05:e3:fa:36:c6 in Control-node 10.0.0.249 2026-06-08 02:46:39,695 - DEBUG - L2 Label for VM ctest-VM21-98306848 same between Control-node 10.0.0.249 and Agent, Expected: 29, Seen: 29 2026-06-08 02:46:39,695 - INFO - Verification in Control-nodes for VM ctest-VM21-98306848 passed 2026-06-08 02:46:39,695 - DEBUG - Verifying the vm in opserver 2026-06-08 02:46:39,695 - DEBUG - Verifying in collector 10.0.0.27 ... 2026-06-08 02:46:39,695 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/be975cbc-1b9c-45b8-8639-aaa03be3a888?flat 2026-06-08 02:46:39,710 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56590479:05e3fa36-c686-4e11-a22c-72b28267d756?flat 2026-06-08 02:46:39,732 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56590479:05e3fa36-c686-4e11-a22c-72b28267d756?flat 2026-06-08 02:46:39,766 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/be975cbc-1b9c-45b8-8639-aaa03be3a888?flat 2026-06-08 02:46:39,781 - DEBUG - Verifying vm in vn uve 2026-06-08 02:46:39,781 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56590479:05e3fa36-c686-4e11-a22c-72b28267d756?flat 2026-06-08 02:46:39,801 - DEBUG - VM uve shows interface as {'label': 25, 'virtual_network': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202', 'vhostuser_mode': 0, 'l2_active': True, 'is_health_check_active': True, 'vn_uuid': '1e0fa0f9-5e0a-43cd-ab56-32c0005835aa', 'port_mirror_enabled': False, 'vm_uuid': 'be975cbc-1b9c-45b8-8639-aaa03be3a888', 'vm_name': 'ctest-VM21-98306848', 'admin_state': True, 'rx_vlan': 65535, 'active': True, 'ip_address': '221.33.84.3', 'ip6_address': '::', 'tx_vlan': 65535, 'fixed_ip4_list': ['221.33.84.3'], 'uuid': '05e3fa36-c686-4e11-a22c-72b28267d756', 'ip6_active': False, 'gateway': '221.33.84.1', 'mac_address': '02:05:e3:fa:36:c6', 'ip4_active': True, '__T': 1780886769833032} 2026-06-08 02:46:39,801 - DEBUG - VM uve shows ip address as ['221.33.84.3', '::'] 2026-06-08 02:46:39,801 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202 2026-06-08 02:46:39,801 - INFO - Verifying through opserver in 10.0.0.33 2026-06-08 02:46:39,801 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202?flat 2026-06-08 02:46:39,826 - DEBUG - expected vm list be975cbc-1b9c-45b8-8639-aaa03be3a888 2026-06-08 02:46:39,826 - DEBUG - Extracted vm list ['be975cbc-1b9c-45b8-8639-aaa03be3a888'] 2026-06-08 02:46:39,826 - INFO - VM be975cbc-1b9c-45b8-8639-aaa03be3a888 is present in default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202 2026-06-08 02:46:39,826 - DEBUG - Verifying vm in vrouter uve 2026-06-08 02:46:39,826 - DEBUG - Getting info from collector 10.0.0.27.. 2026-06-08 02:46:39,826 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/be975cbc-1b9c-45b8-8639-aaa03be3a888?flat 2026-06-08 02:46:39,846 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5901-1'] 2026-06-08 02:46:39,846 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-1:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:46:39,887 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-1?flat 2026-06-08 02:46:39,918 - DEBUG - VM be975cbc-1b9c-45b8-8639-aaa03be3a888 is present in vrouter an-jenkins-deploy-platform-ansible-os-5901-1 uve 2026-06-08 02:46:39,918 - DEBUG - Validated that VM ctest-VM21-98306848 is in Vrouter an-jenkins-deploy-platform-ansible-os-5901-1 UVE 2026-06-08 02:46:39,918 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-06-08 02:46:39,925 - DEBUG - Expected tap interface of VM uuid be975cbc-1b9c-45b8-8639-aaa03be3a888 is default-domain:ctest-TestPolicyAcl-56590479:05e3fa36-c686-4e11-a22c-72b28267d756 2026-06-08 02:46:39,925 - DEBUG - Expected VN of VM uuid be975cbc-1b9c-45b8-8639-aaa03be3a888 is default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202 2026-06-08 02:46:39,926 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-1:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:46:39,957 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-1?flat 2026-06-08 02:46:39,985 - DEBUG - VM be975cbc-1b9c-45b8-8639-aaa03be3a888 is present in vrouter an-jenkins-deploy-platform-ansible-os-5901-1 uve 2026-06-08 02:46:39,985 - INFO - tap interface default-domain:ctest-TestPolicyAcl-56590479:05e3fa36-c686-4e11-a22c-72b28267d756 of vm be975cbc-1b9c-45b8-8639-aaa03be3a888 is present in vrouter an-jenkins-deploy-platform-ansible-os-5901-1 uve 2026-06-08 02:46:39,985 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202 of vm be975cbc-1b9c-45b8-8639-aaa03be3a888 is present in vrouter an-jenkins-deploy-platform-ansible-os-5901-1 uve 2026-06-08 02:46:39,986 - DEBUG - Verifying the be975cbc-1b9c-45b8-8639-aaa03be3a888 virtual network link through opserver 10.0.0.33 2026-06-08 02:46:39,986 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2026-06-08 02:46:39,997 - DEBUG - VM link and name as {'name': 'be975cbc-1b9c-45b8-8639-aaa03be3a888', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-machine/be975cbc-1b9c-45b8-8639-aaa03be3a888?flat'} 2026-06-08 02:46:39,997 - DEBUG - Verifying the be975cbc-1b9c-45b8-8639-aaa03be3a888 virtual network link through opserver 10.0.0.249 2026-06-08 02:46:39,997 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines 2026-06-08 02:46:40,009 - DEBUG - VM link and name as {'name': 'be975cbc-1b9c-45b8-8639-aaa03be3a888', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-machine/be975cbc-1b9c-45b8-8639-aaa03be3a888?flat'} 2026-06-08 02:46:40,009 - DEBUG - Verifying the be975cbc-1b9c-45b8-8639-aaa03be3a888 virtual network link through opserver 10.0.0.27 2026-06-08 02:46:40,009 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2026-06-08 02:46:40,021 - DEBUG - VM link and name as {'name': 'be975cbc-1b9c-45b8-8639-aaa03be3a888', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/be975cbc-1b9c-45b8-8639-aaa03be3a888?flat'} 2026-06-08 02:46:40,021 - INFO - VM ctest-VM21-98306848 validations in Opserver passed 2026-06-08 02:46:40,105 - DEBUG - VM is in ACTIVE state now 2026-06-08 02:46:40,106 - INFO - VM name : ctest-VM31-35306908 2026-06-08 02:46:40,210 - DEBUG - VM ctest-VM31-35306908 ID is 0b1700a8-f0d0-4c7c-a407-be756ba2d12e 2026-06-08 02:46:40,303 - DEBUG - VM is in ACTIVE state now 2026-06-08 02:46:40,303 - DEBUG - VM ctest-VM31-35306908 launched on Node an-jenkins-deploy-platform-ansible-os-5901-2 2026-06-08 02:46:40,385 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b1700a8-f0d0-4c7c-a407-be756ba2d12e 2026-06-08 02:46:40,398 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b1700a8-f0d0-4c7c-a407-be756ba2d12e 2026-06-08 02:46:40,408 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/76daf8a5-118d-443b-a645-f3c82a23960a 2026-06-08 02:46:40,419 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b1700a8-f0d0-4c7c-a407-be756ba2d12e 2026-06-08 02:46:40,430 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/76daf8a5-118d-443b-a645-f3c82a23960a 2026-06-08 02:46:40,446 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/34faf4a9-13a1-4ee3-99dd-3b6168607d43 2026-06-08 02:46:40,459 - DEBUG - Verifying in api server 10.0.0.27 2026-06-08 02:46:40,459 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b1700a8-f0d0-4c7c-a407-be756ba2d12e 2026-06-08 02:46:40,471 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/76daf8a5-118d-443b-a645-f3c82a23960a 2026-06-08 02:46:40,484 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/34faf4a9-13a1-4ee3-99dd-3b6168607d43 2026-06-08 02:46:40,494 - INFO - VM ctest-VM31-35306908 verfication in all API Servers passed 2026-06-08 02:46:40,513 - DEBUG - VM ctest-VM31-35306908 Tap interface: {'index': '4', 'name': 'tap76daf8a5-11', 'uuid': '76daf8a5-118d-443b-a645-f3c82a23960a', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234:ctest-VN3-64063234', '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-56590479:ctest-VN3-64063234', 'vm_uuid': '0b1700a8-f0d0-4c7c-a407-be756ba2d12e', 'vm_name': 'ctest-VM31-35306908', 'ip_addr': '114.77.37.67', 'mac_addr': '02:76:da:f8:a5:11', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '47', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-56590479:76daf8a5-118d-443b-a645-f3c82a23960a', 'sg_uuid_list': ['250b71a2-f93b-41b4-992e-2e758482b768'], 'static_route_list': None, 'vm_project_uuid': '8bd0efef-b1af-454b-8145-75c3f392db3b', 'admin_state': 'Enabled', 'flow_key_idx': '41', '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-06-08 02:46:40,513 - DEBUG - Agent 10.0.0.240 vrf name: default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234:ctest-VN3-64063234 2026-06-08 02:46:40,519 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234:ctest-VN3-64063234', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234', 'table_label': '-1', 'vxlan_id': '17', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.192:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-06-08 02:46:40,622 - DEBUG - VM ctest-VM31-35306908 : Tap interface tap76daf8a5-11 is set to Active 2026-06-08 02:46:40,623 - DEBUG - Tap interface tap76daf8a5-11 detail : {'index': '4', 'name': 'tap76daf8a5-11', 'uuid': '76daf8a5-118d-443b-a645-f3c82a23960a', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234:ctest-VN3-64063234', '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-56590479:ctest-VN3-64063234', 'vm_uuid': '0b1700a8-f0d0-4c7c-a407-be756ba2d12e', 'vm_name': 'ctest-VM31-35306908', 'ip_addr': '114.77.37.67', 'mac_addr': '02:76:da:f8:a5:11', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.4', 'service_vlan_list': None, 'os_ifindex': '47', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-56590479:76daf8a5-118d-443b-a645-f3c82a23960a', 'sg_uuid_list': ['250b71a2-f93b-41b4-992e-2e758482b768'], 'static_route_list': None, 'vm_project_uuid': '8bd0efef-b1af-454b-8145-75c3f392db3b', 'admin_state': 'Enabled', 'flow_key_idx': '41', '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-06-08 02:46:40,741 - DEBUG - Starting Layer 2 verification in Agent 2026-06-08 02:46:40,751 - DEBUG - Layer 2 path is seen for VM MAC 02:76:da:f8:a5:11 in agent 10.0.0.240 2026-06-08 02:46:40,751 - DEBUG - Active layer 2 route in agent is present for VMI tap76daf8a5-11 2026-06-08 02:46:40,751 - DEBUG - L2 label(36) matches bw route table and interface table 2026-06-08 02:46:40,762 - DEBUG - VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234 is found in Agent of node 10.0.0.240 2026-06-08 02:46:40,899 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234 is consistent in agent 10.0.0.240 2026-06-08 02:46:40,899 - DEBUG - Route for VM IP ['114.77.37.67'] is consistent in agent 10.0.0.240 2026-06-08 02:46:40,899 - DEBUG - VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234 verification for VM ctest-VM31-35306908 in Agent 10.0.0.240 passed 2026-06-08 02:46:41,019 - DEBUG - Starting all layer 2 verification in agent 10.0.0.240 2026-06-08 02:46:41,029 - DEBUG - Route for VM MAC 02:76:da:f8:a5:11 is consistent in agent 10.0.0.240 2026-06-08 02:46:42,228 - 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=8.22 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=3.52 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 = 3.520/5.870/8.220/2.350 ms') 2026-06-08 02:46:42,228 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-35306908 passed 2026-06-08 02:46:42,228 - INFO - VM ctest-VM31-35306908 verifications in Compute nodes passed 2026-06-08 02:46:42,437 - DEBUG - Validated VM route 114.77.37.67 in vrouter of 10.0.0.240 2026-06-08 02:46:42,441 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2026-06-08 02:46:42,441 - INFO - Validated routes of VM ctest-VM31-35306908 in all vrouters 2026-06-08 02:46:42,999 - DEBUG - Starting all layer2 verification in 10.0.0.33 Control Node 2026-06-08 02:46:43,128 - DEBUG - Layer2 route found for VM MAC 02:76:da:f8:a5:11 in Control-node 10.0.0.33 2026-06-08 02:46:43,128 - DEBUG - L2 Label for VM ctest-VM31-35306908 same between Control-node 10.0.0.33 and Agent, Expected: 36, Seen: 36 2026-06-08 02:46:43,128 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node 2026-06-08 02:46:43,256 - DEBUG - Layer2 route found for VM MAC 02:76:da:f8:a5:11 in Control-node 10.0.0.27 2026-06-08 02:46:43,256 - DEBUG - L2 Label for VM ctest-VM31-35306908 same between Control-node 10.0.0.27 and Agent, Expected: 36, Seen: 36 2026-06-08 02:46:43,256 - INFO - Verification in Control-nodes for VM ctest-VM31-35306908 passed 2026-06-08 02:46:43,256 - DEBUG - Verifying the vm in opserver 2026-06-08 02:46:43,256 - DEBUG - Verifying in collector 10.0.0.27 ... 2026-06-08 02:46:43,256 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/0b1700a8-f0d0-4c7c-a407-be756ba2d12e?flat 2026-06-08 02:46:43,274 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56590479:76daf8a5-118d-443b-a645-f3c82a23960a?flat 2026-06-08 02:46:43,292 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56590479:76daf8a5-118d-443b-a645-f3c82a23960a?flat 2026-06-08 02:46:43,317 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/0b1700a8-f0d0-4c7c-a407-be756ba2d12e?flat 2026-06-08 02:46:43,336 - DEBUG - Verifying vm in vn uve 2026-06-08 02:46:43,336 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56590479:76daf8a5-118d-443b-a645-f3c82a23960a?flat 2026-06-08 02:46:43,357 - DEBUG - VM uve shows interface as {'tx_vlan': 65535, 'virtual_network': 'default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234', 'vm_name': 'ctest-VM31-35306908', '__T': 1780886770530630, 'ip4_active': True, 'is_health_check_active': True, 'rx_vlan': 65535, 'uuid': '76daf8a5-118d-443b-a645-f3c82a23960a', 'fixed_ip4_list': ['114.77.37.67'], 'admin_state': True, 'ip_address': '114.77.37.67', 'gateway': '114.77.37.65', 'ip6_active': False, 'l2_active': True, 'label': 32, 'vm_uuid': '0b1700a8-f0d0-4c7c-a407-be756ba2d12e', 'vhostuser_mode': 0, 'vn_uuid': 'f6aab222-0ed0-4ff5-8020-f0f577393a17', 'active': True, 'port_mirror_enabled': False, 'ip6_address': '::', 'mac_address': '02:76:da:f8:a5:11'} 2026-06-08 02:46:43,357 - DEBUG - VM uve shows ip address as ['114.77.37.67', '::'] 2026-06-08 02:46:43,357 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234 2026-06-08 02:46:43,357 - INFO - Verifying through opserver in 10.0.0.33 2026-06-08 02:46:43,357 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234?flat 2026-06-08 02:46:43,380 - DEBUG - expected vm list 0b1700a8-f0d0-4c7c-a407-be756ba2d12e 2026-06-08 02:46:43,380 - DEBUG - Extracted vm list ['0b1700a8-f0d0-4c7c-a407-be756ba2d12e'] 2026-06-08 02:46:43,380 - INFO - VM 0b1700a8-f0d0-4c7c-a407-be756ba2d12e is present in default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234 2026-06-08 02:46:43,380 - DEBUG - Verifying vm in vrouter uve 2026-06-08 02:46:43,381 - DEBUG - Getting info from collector 10.0.0.27.. 2026-06-08 02:46:43,381 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/0b1700a8-f0d0-4c7c-a407-be756ba2d12e?flat 2026-06-08 02:46:43,403 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5901-2'] 2026-06-08 02:46:43,403 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:46:43,458 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:46:43,493 - DEBUG - VM 0b1700a8-f0d0-4c7c-a407-be756ba2d12e is present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:46:43,493 - DEBUG - Validated that VM ctest-VM31-35306908 is in Vrouter an-jenkins-deploy-platform-ansible-os-5901-2 UVE 2026-06-08 02:46:43,493 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2026-06-08 02:46:43,500 - DEBUG - Expected tap interface of VM uuid 0b1700a8-f0d0-4c7c-a407-be756ba2d12e is default-domain:ctest-TestPolicyAcl-56590479:76daf8a5-118d-443b-a645-f3c82a23960a 2026-06-08 02:46:43,500 - DEBUG - Expected VN of VM uuid 0b1700a8-f0d0-4c7c-a407-be756ba2d12e is default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234 2026-06-08 02:46:43,500 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:46:43,533 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:46:43,565 - DEBUG - VM 0b1700a8-f0d0-4c7c-a407-be756ba2d12e is present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:46:43,565 - INFO - tap interface default-domain:ctest-TestPolicyAcl-56590479:76daf8a5-118d-443b-a645-f3c82a23960a of vm 0b1700a8-f0d0-4c7c-a407-be756ba2d12e is present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:46:43,566 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234 of vm 0b1700a8-f0d0-4c7c-a407-be756ba2d12e is present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:46:43,566 - DEBUG - Verifying the 0b1700a8-f0d0-4c7c-a407-be756ba2d12e virtual network link through opserver 10.0.0.33 2026-06-08 02:46:43,566 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2026-06-08 02:46:43,586 - DEBUG - VM link and name as {'name': '0b1700a8-f0d0-4c7c-a407-be756ba2d12e', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-machine/0b1700a8-f0d0-4c7c-a407-be756ba2d12e?flat'} 2026-06-08 02:46:43,586 - DEBUG - Verifying the 0b1700a8-f0d0-4c7c-a407-be756ba2d12e virtual network link through opserver 10.0.0.249 2026-06-08 02:46:43,586 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines 2026-06-08 02:46:43,602 - DEBUG - VM link and name as {'name': '0b1700a8-f0d0-4c7c-a407-be756ba2d12e', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-machine/0b1700a8-f0d0-4c7c-a407-be756ba2d12e?flat'} 2026-06-08 02:46:43,602 - DEBUG - Verifying the 0b1700a8-f0d0-4c7c-a407-be756ba2d12e virtual network link through opserver 10.0.0.27 2026-06-08 02:46:43,602 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2026-06-08 02:46:43,616 - DEBUG - VM link and name as {'name': '0b1700a8-f0d0-4c7c-a407-be756ba2d12e', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/0b1700a8-f0d0-4c7c-a407-be756ba2d12e?flat'} 2026-06-08 02:46:43,616 - INFO - VM ctest-VM31-35306908 validations in Opserver passed 2026-06-08 02:46:43,616 - INFO - Waiting for VM ctest-VM11-44863839 to be up.. 2026-06-08 02:46:43,616 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-08 02:46:43,616 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/6f85cbc4-76f2-4774-8683-4b6b7881fc63 2026-06-08 02:46:43,626 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/f0a81eb5-e658-4667-a886-372ffc843693 2026-06-08 02:46:44,820 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=2.18 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.10 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 = 2.101/2.138/2.176/0.037 ms') 2026-06-08 02:46:44,820 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-44863839 passed 2026-06-08 02:46:44,892 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-08 02:46:44,892 - DEBUG - Waiting to SSH to VM ctest-VM11-44863839, IP 96.17.181.131, Port 22 2026-06-08 02:46:45,050 - 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-06-08 02:46:45,135 - DEBUG - VM ctest-VM11-44863839 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-08 02:46:50,136 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-08 02:46:50,136 - DEBUG - Waiting to SSH to VM ctest-VM11-44863839, IP 96.17.181.131, Port 22 2026-06-08 02:46:50,203 - 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-06-08 02:46:50,294 - DEBUG - VM ctest-VM11-44863839 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-08 02:46:55,295 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-08 02:46:55,295 - DEBUG - Waiting to SSH to VM ctest-VM11-44863839, IP 96.17.181.131, Port 22 2026-06-08 02:46:55,466 - DEBUG - VM ctest-VM11-44863839 is ready for SSH connections 2026-06-08 02:46:55,466 - INFO - Waiting for VM ctest-VM21-98306848 to be up.. 2026-06-08 02:46:55,466 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-08 02:46:55,466 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/be975cbc-1b9c-45b8-8639-aaa03be3a888 2026-06-08 02:46:55,475 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/05e3fa36-c686-4e11-a22c-72b28267d756 2026-06-08 02:46:56,641 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=6.19 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.18 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.177/3.685/6.193/2.508 ms') 2026-06-08 02:46:56,642 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-98306848 passed 2026-06-08 02:46:56,711 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-08 02:46:56,711 - DEBUG - Waiting to SSH to VM ctest-VM21-98306848, IP 221.33.84.3, Port 22 2026-06-08 02:46:56,884 - DEBUG - VM ctest-VM21-98306848 is ready for SSH connections 2026-06-08 02:46:56,885 - INFO - Waiting for VM ctest-VM31-35306908 to be up.. 2026-06-08 02:46:56,885 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-08 02:46:56,885 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b1700a8-f0d0-4c7c-a407-be756ba2d12e 2026-06-08 02:46:56,895 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/76daf8a5-118d-443b-a645-f3c82a23960a 2026-06-08 02:46:58,063 - 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.74 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.815 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.815/2.277/3.740/1.462 ms') 2026-06-08 02:46:58,063 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-35306908 passed 2026-06-08 02:46:58,135 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-08 02:46:58,135 - DEBUG - Waiting to SSH to VM ctest-VM31-35306908, IP 114.77.37.67, Port 22 2026-06-08 02:46:58,314 - DEBUG - VM ctest-VM31-35306908 is ready for SSH connections 2026-06-08 02:46:58,314 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 221.33.84.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-08 02:46:58,315 - DEBUG - ping -s 56 -c 3 -W 1 221.33.84.3 2026-06-08 02:47:26,565 - DEBUG - PING 221.33.84.3 (221.33.84.3) 56(84) bytes of data. 64 bytes from 221.33.84.3: icmp_req=1 ttl=63 time=9.84 ms 64 bytes from 221.33.84.3: icmp_req=2 ttl=63 time=1.45 ms 64 bytes from 221.33.84.3: icmp_req=3 ttl=63 time=1.31 ms --- 221.33.84.3 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2003ms rtt min/avg/max/mdev = 1.314/4.204/9.848/3.991 ms 2026-06-08 02:47:26,565 - INFO - Ping to IP 221.33.84.3 from VM ctest-VM11-44863839 passed 2026-06-08 02:47:26,565 - INFO - Test with src as VN and dst as policy PASSED 2026-06-08 02:47:26,565 - INFO - Deleting VM ctest-VM31-35306908 2026-06-08 02:47:26,632 - DEBUG - Verifying in api server 10.0.0.27 2026-06-08 02:47:26,632 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b1700a8-f0d0-4c7c-a407-be756ba2d12e 2026-06-08 02:47:26,642 - DEBUG - VM ID 0b1700a8-f0d0-4c7c-a407-be756ba2d12e of VM ctest-VM31-35306908 is still found in API Server 2026-06-08 02:47:28,642 - DEBUG - Verifying in api server 10.0.0.27 2026-06-08 02:47:28,642 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b1700a8-f0d0-4c7c-a407-be756ba2d12e 2026-06-08 02:47:28,652 - DEBUG - Response Code: 404 2026-06-08 02:47:28,653 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b1700a8-f0d0-4c7c-a407-be756ba2d12e 2026-06-08 02:47:28,660 - DEBUG - Response Code: 404 2026-06-08 02:47:28,660 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b1700a8-f0d0-4c7c-a407-be756ba2d12e 2026-06-08 02:47:28,672 - DEBUG - Response Code: 404 2026-06-08 02:47:28,672 - INFO - VM ctest-VM31-35306908 is fully removed in API-Server 2026-06-08 02:47:28,822 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-06-08 02:47:28,864 - DEBUG - Validated that vrouter 10.0.0.23 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234 2026-06-08 02:47:28,875 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234 2026-06-08 02:47:28,875 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234 2026-06-08 02:47:28,875 - INFO - VM ctest-VM31-35306908 is removed in Compute, and routes are removed in all compute nodes 2026-06-08 02:47:29,125 - INFO - Routes for VM ctest-VM31-35306908 is removed in all control-nodes 2026-06-08 02:47:29,125 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:47:29,160 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:47:29,191 - DEBUG - VM 0b1700a8-f0d0-4c7c-a407-be756ba2d12e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:47:29,191 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:47:29,191 - DEBUG - interface for vm 0b1700a8-f0d0-4c7c-a407-be756ba2d12e deleted from vrouter uve ... 2026-06-08 02:47:29,191 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:47:32,192 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:47:32,282 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:47:32,316 - DEBUG - VM 0b1700a8-f0d0-4c7c-a407-be756ba2d12e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:47:32,317 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:47:32,317 - DEBUG - interface for vm 0b1700a8-f0d0-4c7c-a407-be756ba2d12e deleted from vrouter uve ... 2026-06-08 02:47:32,317 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:47:35,318 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:47:35,353 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:47:35,388 - DEBUG - VM 0b1700a8-f0d0-4c7c-a407-be756ba2d12e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:47:35,388 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:47:35,389 - DEBUG - interface for vm 0b1700a8-f0d0-4c7c-a407-be756ba2d12e deleted from vrouter uve ... 2026-06-08 02:47:35,389 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:47:38,389 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:47:38,429 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:47:38,469 - DEBUG - VM 0b1700a8-f0d0-4c7c-a407-be756ba2d12e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:47:38,469 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:47:38,469 - DEBUG - interface for vm 0b1700a8-f0d0-4c7c-a407-be756ba2d12e deleted from vrouter uve ... 2026-06-08 02:47:38,469 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:47:41,470 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:47:41,506 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:47:41,539 - DEBUG - VM 0b1700a8-f0d0-4c7c-a407-be756ba2d12e is not present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:47:41,539 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:47:41,539 - DEBUG - interface for vm 0b1700a8-f0d0-4c7c-a407-be756ba2d12e deleted from vrouter uve ... 2026-06-08 02:47:41,539 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:47:41,539 - DEBUG - Validated that VM 0b1700a8-f0d0-4c7c-a407-be756ba2d12e is removed in Vrouter UVE 2026-06-08 02:47:41,539 - DEBUG - Verifying through opserver in 10.0.0.33 2026-06-08 02:47:41,539 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234?flat 2026-06-08 02:47:41,557 - DEBUG - 0b1700a8-f0d0-4c7c-a407-be756ba2d12e BM not in default-domain:ctest-TestPolicyAcl-56590479:ctest-VN3-64063234 uve 2026-06-08 02:47:41,557 - DEBUG - Validated that VM 0b1700a8-f0d0-4c7c-a407-be756ba2d12e is not present in the VN UVE 2026-06-08 02:47:41,557 - DEBUG - Verifying the 0b1700a8-f0d0-4c7c-a407-be756ba2d12e virtual network link through opserver 10.0.0.33 2026-06-08 02:47:41,558 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2026-06-08 02:47:41,571 - INFO - 0b1700a8-f0d0-4c7c-a407-be756ba2d12e vm uve deleted from opserver 2026-06-08 02:47:41,571 - DEBUG - Verifying the 0b1700a8-f0d0-4c7c-a407-be756ba2d12e virtual network link through opserver 10.0.0.249 2026-06-08 02:47:41,571 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines 2026-06-08 02:47:41,583 - INFO - 0b1700a8-f0d0-4c7c-a407-be756ba2d12e vm uve deleted from opserver 2026-06-08 02:47:41,583 - DEBUG - Verifying the 0b1700a8-f0d0-4c7c-a407-be756ba2d12e virtual network link through opserver 10.0.0.27 2026-06-08 02:47:41,583 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2026-06-08 02:47:41,595 - INFO - 0b1700a8-f0d0-4c7c-a407-be756ba2d12e vm uve deleted from opserver 2026-06-08 02:47:41,595 - INFO - Validated that VM 0b1700a8-f0d0-4c7c-a407-be756ba2d12e is removed from Opserver 2026-06-08 02:47:41,596 - INFO - Deleting VM ctest-VM21-98306848 2026-06-08 02:47:41,669 - DEBUG - Verifying in api server 10.0.0.27 2026-06-08 02:47:41,669 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/be975cbc-1b9c-45b8-8639-aaa03be3a888 2026-06-08 02:47:41,679 - DEBUG - VM ID be975cbc-1b9c-45b8-8639-aaa03be3a888 of VM ctest-VM21-98306848 is still found in API Server 2026-06-08 02:47:43,680 - DEBUG - Verifying in api server 10.0.0.27 2026-06-08 02:47:43,680 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/be975cbc-1b9c-45b8-8639-aaa03be3a888 2026-06-08 02:47:43,689 - DEBUG - Response Code: 404 2026-06-08 02:47:43,690 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/be975cbc-1b9c-45b8-8639-aaa03be3a888 2026-06-08 02:47:43,697 - DEBUG - Response Code: 404 2026-06-08 02:47:43,697 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/be975cbc-1b9c-45b8-8639-aaa03be3a888 2026-06-08 02:47:43,704 - DEBUG - Response Code: 404 2026-06-08 02:47:43,704 - INFO - VM ctest-VM21-98306848 is fully removed in API-Server 2026-06-08 02:47:43,859 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-06-08 02:47:43,905 - DEBUG - Validated that vrouter 10.0.0.23 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202 2026-06-08 02:47:43,916 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202 2026-06-08 02:47:43,916 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202 2026-06-08 02:47:43,916 - INFO - VM ctest-VM21-98306848 is removed in Compute, and routes are removed in all compute nodes 2026-06-08 02:47:44,141 - INFO - Routes for VM ctest-VM21-98306848 is removed in all control-nodes 2026-06-08 02:47:44,141 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-1:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:47:44,178 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-1?flat 2026-06-08 02:47:44,206 - DEBUG - VM be975cbc-1b9c-45b8-8639-aaa03be3a888 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-1 uve 2026-06-08 02:47:44,206 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:47:44,207 - DEBUG - interface for vm be975cbc-1b9c-45b8-8639-aaa03be3a888 deleted from vrouter uve ... 2026-06-08 02:47:44,207 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:47:47,207 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-1:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:47:47,261 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-1?flat 2026-06-08 02:47:47,296 - DEBUG - VM be975cbc-1b9c-45b8-8639-aaa03be3a888 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-1 uve 2026-06-08 02:47:47,296 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:47:47,296 - DEBUG - interface for vm be975cbc-1b9c-45b8-8639-aaa03be3a888 deleted from vrouter uve ... 2026-06-08 02:47:47,296 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:47:50,297 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-1:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:47:50,330 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-1?flat 2026-06-08 02:47:50,364 - DEBUG - VM be975cbc-1b9c-45b8-8639-aaa03be3a888 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-1 uve 2026-06-08 02:47:50,364 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:47:50,364 - DEBUG - interface for vm be975cbc-1b9c-45b8-8639-aaa03be3a888 deleted from vrouter uve ... 2026-06-08 02:47:50,364 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:47:53,365 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-1:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:47:53,413 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-1?flat 2026-06-08 02:47:53,445 - DEBUG - VM be975cbc-1b9c-45b8-8639-aaa03be3a888 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-1 uve 2026-06-08 02:47:53,445 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:47:53,445 - DEBUG - interface for vm be975cbc-1b9c-45b8-8639-aaa03be3a888 deleted from vrouter uve ... 2026-06-08 02:47:53,445 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:47:56,446 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-1:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:47:56,478 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-1?flat 2026-06-08 02:47:56,510 - DEBUG - VM be975cbc-1b9c-45b8-8639-aaa03be3a888 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-1 uve 2026-06-08 02:47:56,511 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:47:56,511 - DEBUG - interface for vm be975cbc-1b9c-45b8-8639-aaa03be3a888 deleted from vrouter uve ... 2026-06-08 02:47:56,511 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:47:59,512 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-1:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:47:59,552 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-1?flat 2026-06-08 02:47:59,588 - DEBUG - VM be975cbc-1b9c-45b8-8639-aaa03be3a888 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-1 uve 2026-06-08 02:47:59,588 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:47:59,588 - DEBUG - interface for vm be975cbc-1b9c-45b8-8639-aaa03be3a888 deleted from vrouter uve ... 2026-06-08 02:47:59,588 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:48:02,589 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-1:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:48:02,621 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-1?flat 2026-06-08 02:48:02,649 - DEBUG - VM be975cbc-1b9c-45b8-8639-aaa03be3a888 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-1 uve 2026-06-08 02:48:02,649 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:48:02,649 - DEBUG - interface for vm be975cbc-1b9c-45b8-8639-aaa03be3a888 deleted from vrouter uve ... 2026-06-08 02:48:02,649 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:48:05,650 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-1:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:48:05,685 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-1?flat 2026-06-08 02:48:05,720 - DEBUG - VM be975cbc-1b9c-45b8-8639-aaa03be3a888 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-1 uve 2026-06-08 02:48:05,720 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:48:05,720 - DEBUG - interface for vm be975cbc-1b9c-45b8-8639-aaa03be3a888 deleted from vrouter uve ... 2026-06-08 02:48:05,720 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:48:08,720 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-1:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:48:08,762 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-1?flat 2026-06-08 02:48:08,802 - DEBUG - VM be975cbc-1b9c-45b8-8639-aaa03be3a888 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-1 uve 2026-06-08 02:48:08,802 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:48:08,802 - DEBUG - interface for vm be975cbc-1b9c-45b8-8639-aaa03be3a888 deleted from vrouter uve ... 2026-06-08 02:48:08,802 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:48:11,803 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-1:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:48:11,841 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-1?flat 2026-06-08 02:48:11,873 - DEBUG - VM be975cbc-1b9c-45b8-8639-aaa03be3a888 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5901-1 uve 2026-06-08 02:48:11,873 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:48:11,873 - DEBUG - interface for vm be975cbc-1b9c-45b8-8639-aaa03be3a888 deleted from vrouter uve ... 2026-06-08 02:48:11,873 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:48:11,873 - DEBUG - Validated that VM be975cbc-1b9c-45b8-8639-aaa03be3a888 is removed in Vrouter UVE 2026-06-08 02:48:11,873 - DEBUG - Verifying through opserver in 10.0.0.33 2026-06-08 02:48:11,873 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202?flat 2026-06-08 02:48:11,903 - DEBUG - be975cbc-1b9c-45b8-8639-aaa03be3a888 BM not in default-domain:ctest-TestPolicyAcl-56590479:ctest-VN2-53207202 uve 2026-06-08 02:48:11,903 - DEBUG - Validated that VM be975cbc-1b9c-45b8-8639-aaa03be3a888 is not present in the VN UVE 2026-06-08 02:48:11,904 - DEBUG - Verifying the be975cbc-1b9c-45b8-8639-aaa03be3a888 virtual network link through opserver 10.0.0.33 2026-06-08 02:48:11,904 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2026-06-08 02:48:11,914 - INFO - be975cbc-1b9c-45b8-8639-aaa03be3a888 vm uve deleted from opserver 2026-06-08 02:48:11,914 - DEBUG - Verifying the be975cbc-1b9c-45b8-8639-aaa03be3a888 virtual network link through opserver 10.0.0.249 2026-06-08 02:48:11,914 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines 2026-06-08 02:48:11,925 - INFO - be975cbc-1b9c-45b8-8639-aaa03be3a888 vm uve deleted from opserver 2026-06-08 02:48:11,925 - DEBUG - Verifying the be975cbc-1b9c-45b8-8639-aaa03be3a888 virtual network link through opserver 10.0.0.27 2026-06-08 02:48:11,925 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2026-06-08 02:48:11,939 - INFO - be975cbc-1b9c-45b8-8639-aaa03be3a888 vm uve deleted from opserver 2026-06-08 02:48:11,939 - INFO - Validated that VM be975cbc-1b9c-45b8-8639-aaa03be3a888 is removed from Opserver 2026-06-08 02:48:11,939 - INFO - Deleting VM ctest-VM11-44863839 2026-06-08 02:48:12,007 - DEBUG - Verifying in api server 10.0.0.27 2026-06-08 02:48:12,007 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/6f85cbc4-76f2-4774-8683-4b6b7881fc63 2026-06-08 02:48:12,017 - DEBUG - VM ID 6f85cbc4-76f2-4774-8683-4b6b7881fc63 of VM ctest-VM11-44863839 is still found in API Server 2026-06-08 02:48:14,018 - DEBUG - Verifying in api server 10.0.0.27 2026-06-08 02:48:14,019 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/6f85cbc4-76f2-4774-8683-4b6b7881fc63 2026-06-08 02:48:14,026 - DEBUG - Response Code: 404 2026-06-08 02:48:14,026 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/6f85cbc4-76f2-4774-8683-4b6b7881fc63 2026-06-08 02:48:14,033 - DEBUG - Response Code: 404 2026-06-08 02:48:14,034 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/6f85cbc4-76f2-4774-8683-4b6b7881fc63 2026-06-08 02:48:14,040 - DEBUG - Response Code: 404 2026-06-08 02:48:14,040 - INFO - VM ctest-VM11-44863839 is fully removed in API-Server 2026-06-08 02:48:14,194 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2026-06-08 02:48:14,235 - DEBUG - Validated that vrouter 10.0.0.23 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 2026-06-08 02:48:14,247 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 2026-06-08 02:48:14,247 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 2026-06-08 02:48:14,247 - INFO - VM ctest-VM11-44863839 is removed in Compute, and routes are removed in all compute nodes 2026-06-08 02:48:14,451 - INFO - Routes for VM ctest-VM11-44863839 is removed in all control-nodes 2026-06-08 02:48:14,451 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:48:14,489 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:48:14,526 - DEBUG - VM 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:48:14,526 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:48:14,527 - DEBUG - interface for vm 6f85cbc4-76f2-4774-8683-4b6b7881fc63 deleted from vrouter uve ... 2026-06-08 02:48:14,527 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:48:17,527 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:48:17,562 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:48:17,592 - DEBUG - VM 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:48:17,592 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:48:17,592 - DEBUG - interface for vm 6f85cbc4-76f2-4774-8683-4b6b7881fc63 deleted from vrouter uve ... 2026-06-08 02:48:17,592 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:48:20,593 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:48:20,626 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:48:20,657 - DEBUG - VM 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:48:20,657 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:48:20,657 - DEBUG - interface for vm 6f85cbc4-76f2-4774-8683-4b6b7881fc63 deleted from vrouter uve ... 2026-06-08 02:48:20,657 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:48:23,661 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:48:23,700 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:48:23,734 - DEBUG - VM 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:48:23,734 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:48:23,734 - DEBUG - interface for vm 6f85cbc4-76f2-4774-8683-4b6b7881fc63 deleted from vrouter uve ... 2026-06-08 02:48:23,734 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:48:26,735 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:48:26,780 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:48:26,816 - DEBUG - VM 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:48:26,817 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:48:26,817 - DEBUG - interface for vm 6f85cbc4-76f2-4774-8683-4b6b7881fc63 deleted from vrouter uve ... 2026-06-08 02:48:26,817 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:48:29,818 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:48:29,852 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:48:29,881 - DEBUG - VM 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:48:29,881 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:48:29,881 - DEBUG - interface for vm 6f85cbc4-76f2-4774-8683-4b6b7881fc63 deleted from vrouter uve ... 2026-06-08 02:48:29,881 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:48:32,882 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:48:32,924 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:48:32,958 - DEBUG - VM 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:48:32,958 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:48:32,958 - DEBUG - interface for vm 6f85cbc4-76f2-4774-8683-4b6b7881fc63 deleted from vrouter uve ... 2026-06-08 02:48:32,958 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:48:35,958 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:48:36,003 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:48:36,034 - DEBUG - VM 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:48:36,034 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:48:36,034 - DEBUG - interface for vm 6f85cbc4-76f2-4774-8683-4b6b7881fc63 deleted from vrouter uve ... 2026-06-08 02:48:36,034 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:48:39,035 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:48:39,067 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:48:39,101 - DEBUG - VM 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:48:39,101 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:48:39,101 - DEBUG - interface for vm 6f85cbc4-76f2-4774-8683-4b6b7881fc63 deleted from vrouter uve ... 2026-06-08 02:48:39,101 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:48:42,102 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5901-2:Compute:contrail-vrouter-agent:0?flat 2026-06-08 02:48:42,137 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5901-2?flat 2026-06-08 02:48:42,171 - DEBUG - VM 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5901-2 uve 2026-06-08 02:48:42,171 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2026-06-08 02:48:42,171 - DEBUG - interface for vm 6f85cbc4-76f2-4774-8683-4b6b7881fc63 deleted from vrouter uve ... 2026-06-08 02:48:42,171 - DEBUG - Deleted interface not in error interface list ... 2026-06-08 02:48:42,171 - DEBUG - Validated that VM 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is removed in Vrouter UVE 2026-06-08 02:48:42,171 - DEBUG - Verifying through opserver in 10.0.0.33 2026-06-08 02:48:42,171 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738?flat 2026-06-08 02:48:42,191 - DEBUG - 6f85cbc4-76f2-4774-8683-4b6b7881fc63 BM not in default-domain:ctest-TestPolicyAcl-56590479:ctest-VN1-93485738 uve 2026-06-08 02:48:42,191 - DEBUG - Validated that VM 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is not present in the VN UVE 2026-06-08 02:48:42,191 - DEBUG - Verifying the 6f85cbc4-76f2-4774-8683-4b6b7881fc63 virtual network link through opserver 10.0.0.33 2026-06-08 02:48:42,191 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-machines 2026-06-08 02:48:42,202 - DEBUG - No links retuned for 6f85cbc4-76f2-4774-8683-4b6b7881fc63 2026-06-08 02:48:42,202 - INFO - 6f85cbc4-76f2-4774-8683-4b6b7881fc63 vm uve deleted from opserver 2026-06-08 02:48:42,202 - DEBUG - Verifying the 6f85cbc4-76f2-4774-8683-4b6b7881fc63 virtual network link through opserver 10.0.0.249 2026-06-08 02:48:42,203 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines 2026-06-08 02:48:42,216 - DEBUG - No links retuned for 6f85cbc4-76f2-4774-8683-4b6b7881fc63 2026-06-08 02:48:42,216 - INFO - 6f85cbc4-76f2-4774-8683-4b6b7881fc63 vm uve deleted from opserver 2026-06-08 02:48:42,216 - DEBUG - Verifying the 6f85cbc4-76f2-4774-8683-4b6b7881fc63 virtual network link through opserver 10.0.0.27 2026-06-08 02:48:42,216 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines 2026-06-08 02:48:42,231 - DEBUG - No links retuned for 6f85cbc4-76f2-4774-8683-4b6b7881fc63 2026-06-08 02:48:42,231 - INFO - 6f85cbc4-76f2-4774-8683-4b6b7881fc63 vm uve deleted from opserver 2026-06-08 02:48:42,231 - INFO - Validated that VM 6f85cbc4-76f2-4774-8683-4b6b7881fc63 is removed from Opserver 2026-06-08 02:48:42,231 - DEBUG - Detaching the Policy for VN :ctest-VN2-53207202 2026-06-08 02:48:42,231 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:48:42,237 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2441b78a-01cc-4005-9aa7-6b434eb6fb41 2026-06-08 02:48:42,289 - DEBUG - Requesting: http://10.0.0.33:8082/project/8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:48:42,394 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1e0fa0f9-5e0a-43cd-ab56-32c0005835aa 2026-06-08 02:48:42,409 - DEBUG - Requesting: http://10.0.0.33:8082/network-policy/2dd4e3fd-3d07-4e0a-a47b-d7b0c4eaf3ba 2026-06-08 02:48:42,569 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAcl-56590479', 'ctest-policy21-49423054']] from ctest-VN2-53207202 2026-06-08 02:48:42,569 - DEBUG - Detaching the Policy for VN :ctest-VN1-93485738 2026-06-08 02:48:42,569 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:48:42,575 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2441b78a-01cc-4005-9aa7-6b434eb6fb41 2026-06-08 02:48:42,587 - DEBUG - Requesting: http://10.0.0.33:8082/project/8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:48:42,693 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/9dfb0857-4e4a-4e1b-b329-5af1cbc51a93 2026-06-08 02:48:42,887 - INFO - Detached Policy from ctest-VN1-93485738 2026-06-08 02:48:42,923 - INFO - Deleted policy ctest-policy13-44688988 2026-06-08 02:48:42,965 - INFO - Deleted policy ctest-policy21-49423054 2026-06-08 02:48:43,017 - INFO - Deleted policy ctest-policy12-03923082 2026-06-08 02:48:43,017 - INFO - Deleting VN ctest-VN3-64063234 2026-06-08 02:48:43,308 - DEBUG - Response for deleting network () 2026-06-08 02:48:43,308 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/233d14b8-bb00-4150-8799-8c1b127e064d 2026-06-08 02:48:43,316 - DEBUG - Response Code: 404 2026-06-08 02:48:43,316 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:48:43,326 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2441b78a-01cc-4005-9aa7-6b434eb6fb41 2026-06-08 02:48:43,339 - DEBUG - Requesting: http://10.0.0.33:8082/project/8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:48:43,444 - INFO - Validated that VN ctest-VN3-64063234 is not found in API Server 2026-06-08 02:48:43,454 - DEBUG - VN ctest-VN3-64063234 is not present in Agent 10.0.0.23 2026-06-08 02:48:43,466 - DEBUG - VN ctest-VN3-64063234 is not present in Agent 10.0.0.240 2026-06-08 02:48:43,466 - INFO - Validated that VN ctest-VN3-64063234 is not in any agent 2026-06-08 02:48:43,506 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-64063234 info 2026-06-08 02:48:43,506 - INFO - Deleting VN ctest-VN2-53207202 2026-06-08 02:48:43,759 - DEBUG - Response for deleting network () 2026-06-08 02:48:43,759 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b827d75e-7bc6-4af0-b0e1-6e5d79be5aa8 2026-06-08 02:48:43,764 - DEBUG - Response Code: 404 2026-06-08 02:48:43,765 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:48:43,770 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2441b78a-01cc-4005-9aa7-6b434eb6fb41 2026-06-08 02:48:43,782 - DEBUG - Requesting: http://10.0.0.33:8082/project/8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:48:43,881 - INFO - Validated that VN ctest-VN2-53207202 is not found in API Server 2026-06-08 02:48:43,893 - DEBUG - VN ctest-VN2-53207202 is not present in Agent 10.0.0.23 2026-06-08 02:48:43,905 - DEBUG - VN ctest-VN2-53207202 is not present in Agent 10.0.0.240 2026-06-08 02:48:43,906 - INFO - Validated that VN ctest-VN2-53207202 is not in any agent 2026-06-08 02:48:43,941 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-53207202 info 2026-06-08 02:48:43,941 - INFO - Deleting VN ctest-VN1-93485738 2026-06-08 02:48:44,120 - DEBUG - Response for deleting network () 2026-06-08 02:48:44,120 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/8a0df807-2dad-47a5-97cb-c85343f9dc72 2026-06-08 02:48:44,130 - DEBUG - Response Code: 404 2026-06-08 02:48:44,131 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2026-06-08 02:48:44,138 - DEBUG - Requesting: http://10.0.0.33:8082/domain/2441b78a-01cc-4005-9aa7-6b434eb6fb41 2026-06-08 02:48:44,201 - DEBUG - Requesting: http://10.0.0.33:8082/project/8bd0efef-b1af-454b-8145-75c3f392db3b 2026-06-08 02:48:44,309 - INFO - Validated that VN ctest-VN1-93485738 is not found in API Server 2026-06-08 02:48:44,321 - DEBUG - VN ctest-VN1-93485738 is not present in Agent 10.0.0.23 2026-06-08 02:48:44,332 - DEBUG - VN ctest-VN1-93485738 is not present in Agent 10.0.0.240 2026-06-08 02:48:44,332 - INFO - Validated that VN ctest-VN1-93485738 is not in any agent 2026-06-08 02:48:44,367 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-93485738 info 2026-06-08 02:48:44,459 - INFO - IPAM: ipam3 is not found in API Server 2026-06-08 02:48:44,482 - INFO - IPAM:ipam3 is not found in control node 2026-06-08 02:48:44,643 - INFO - IPAM: ipam2 is not found in API Server 2026-06-08 02:48:44,670 - INFO - IPAM:ipam2 is not found in control node 2026-06-08 02:48:44,823 - INFO - IPAM: ipam1 is not found in API Server 2026-06-08 02:48:44,854 - INFO - IPAM:ipam1 is not found in control node 2026-06-08 02:48:46,151 - DEBUG - No XMPP flaps were noticed during the test
2026-06-08 02:48:46,152 - INFO - END TEST : test_policy_inheritance_src_vn_dst_pol : PASSED[0:03:13]
2026-06-08 02:48:46,152 - INFO - -------------------------------------------------------------------------------- 2026-06-08 02:48:46,943 - INFO - Deleted project: ctest-TestPolicyAcl-56590479, ID : 8bd0efef-b1af-454b-8145-75c3f392db3b