2026-05-11 02:44:11,045 - INFO - Domain Default found not creating
2026-05-11 02:44:11,258 - INFO - Project ctest-TestPolicyAcl-61138828 not found, creating it
2026-05-11 02:44:11,806 - INFO - Created Project:ctest-TestPolicyAcl-61138828, ID : fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:44:13,340 - INFO - ================================================================================
2026-05-11 02:44:13,340 - INFO - STARTING TEST : test_policy_inheritance_src_vn_dst_pol
2026-05-11 02:44:13,340 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance
2026-05-11 02:44:14,629 - DEBUG - Nothing to compare xmpp stats {'10.0.0.23': {'10.20.0.129': '0', '10.20.0.193': '0'}, '10.0.0.240': {'10.20.0.129': '0', '10.20.0.18': '0'}} with
2026-05-11 02:44:14,629 - INFO - Initial checks done. Running the testcase now
2026-05-11 02:44:14,629 - INFO -
2026-05-11 02:44:15,961 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:44:16,268 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:44:16,307 - DEBUG - Requesting: http://10.0.0.141:8082/domain/a797b29a-b743-4bb0-b070-a90ae89983d0
2026-05-11 02:44:16,329 - DEBUG - Requesting: http://10.0.0.141:8082/project/fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:44:16,448 - DEBUG - Requesting: http://10.0.0.141:8082/network-ipam/fdf40784-622f-437a-89d9-62c8b058c5eb
2026-05-11 02:44:16,465 - INFO - Verifications in API Server for IPAM: ipam1 passed
2026-05-11 02:44:16,471 - DEBUG - Control-node 10.0.0.141 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-61138828:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18299259451077510010', 'uuid-lslong': '9933079067233928683'}, 'enable': 'true', 'created': '2026-05-11T02:44:15', 'last-modified': '2026-05-11T02:44:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.215490'}
2026-05-11 02:44:16,477 - DEBUG - Control-node 10.0.0.249 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-61138828:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18299259451077510010', 'uuid-lslong': '9933079067233928683'}, 'enable': 'true', 'created': '2026-05-11T02:44:15', 'last-modified': '2026-05-11T02:44:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.216404'}
2026-05-11 02:44:16,483 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-61138828:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18299259451077510010', 'uuid-lslong': '9933079067233928683'}, 'enable': 'true', 'created': '2026-05-11T02:44:15', 'last-modified': '2026-05-11T02:44:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.224801'}
2026-05-11 02:44:16,483 - INFO - Verifications in Control node for IPAM: ipam1 passed
2026-05-11 02:44:16,483 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:44:16,491 - DEBUG - Requesting: http://10.0.0.141:8082/domain/a797b29a-b743-4bb0-b070-a90ae89983d0
2026-05-11 02:44:16,506 - DEBUG - Requesting: http://10.0.0.141:8082/project/fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:44:16,612 - DEBUG - Requesting: http://10.0.0.141:8082/network-ipam/018ef2aa-ec5e-4e49-90da-c3963755b9f0
2026-05-11 02:44:16,620 - INFO - Verifications in API Server for IPAM: ipam2 passed
2026-05-11 02:44:16,626 - DEBUG - Control-node 10.0.0.141 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-61138828:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '112293856654806601', 'uuid-lslong': '10437870136325421552'}, 'enable': 'true', 'created': '2026-05-11T02:44:15', 'last-modified': '2026-05-11T02:44:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.033877'}
2026-05-11 02:44:16,633 - DEBUG - Control-node 10.0.0.249 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-61138828:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '112293856654806601', 'uuid-lslong': '10437870136325421552'}, 'enable': 'true', 'created': '2026-05-11T02:44:15', 'last-modified': '2026-05-11T02:44:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.022073'}
2026-05-11 02:44:16,639 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-61138828:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '112293856654806601', 'uuid-lslong': '10437870136325421552'}, 'enable': 'true', 'created': '2026-05-11T02:44:15', 'last-modified': '2026-05-11T02:44:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.048176'}
2026-05-11 02:44:16,639 - INFO - Verifications in Control node for IPAM: ipam2 passed
2026-05-11 02:44:16,639 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:44:16,645 - DEBUG - Requesting: http://10.0.0.141:8082/domain/a797b29a-b743-4bb0-b070-a90ae89983d0
2026-05-11 02:44:16,657 - DEBUG - Requesting: http://10.0.0.141:8082/project/fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:44:16,752 - DEBUG - Requesting: http://10.0.0.141:8082/network-ipam/7600efe9-aea5-4e45-a471-5d990dc57a59
2026-05-11 02:44:16,769 - INFO - Verifications in API Server for IPAM: ipam3 passed
2026-05-11 02:44:16,777 - DEBUG - Control-node 10.0.0.141 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-61138828:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8503059883411983941', 'uuid-lslong': '11849355006530910809'}, 'enable': 'true', 'created': '2026-05-11T02:44:15', 'last-modified': '2026-05-11T02:44:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.842066'}
2026-05-11 02:44:16,788 - DEBUG - Control-node 10.0.0.249 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-61138828:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8503059883411983941', 'uuid-lslong': '11849355006530910809'}, 'enable': 'true', 'created': '2026-05-11T02:44:15', 'last-modified': '2026-05-11T02:44:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.853010'}
2026-05-11 02:44:16,794 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-61138828:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8503059883411983941', 'uuid-lslong': '11849355006530910809'}, 'enable': 'true', 'created': '2026-05-11T02:44:15', 'last-modified': '2026-05-11T02:44:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.864322'}
2026-05-11 02:44:16,794 - INFO - Verifications in Control node for IPAM: ipam3 passed
2026-05-11 02:44:17,140 - DEBUG - Response for create_network : {'network': {'id': 'b6175711-edb9-4f0e-b326-2fbce5233e8b', 'name': 'ctest-VN1-04725702', 'tenant_id': 'fed3a47956fd4270b2284aadf5713ba4', 'project_id': 'fed3a47956fd4270b2284aadf5713ba4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-61138828', 'ctest-VN1-04725702'], 'port_security_enabled': True, 'description': ''}}
2026-05-11 02:44:17,404 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c92ac2ae-3885-44e4-a9e8-c4b622b3d786', 'name': '', 'tenant_id': 'fed3a47956fd4270b2284aadf5713ba4', 'network_id': 'b6175711-edb9-4f0e-b326-2fbce5233e8b', 'ip_version': 4, 'cidr': '17.28.167.192/26', 'allocation_pools': [{'start': '17.28.167.194', 'end': '17.28.167.254'}], 'gateway_ip': '17.28.167.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '17.28.167.194', 'tags': [], 'project_id': 'fed3a47956fd4270b2284aadf5713ba4'}}
2026-05-11 02:44:17,430 - INFO - Created VN ctest-VN1-04725702
2026-05-11 02:44:17,446 - DEBUG - VN ctest-VN1-04725702 UUID is b6175711-edb9-4f0e-b326-2fbce5233e8b
2026-05-11 02:44:17,629 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702(b6175711-edb9-4f0e-b326-2fbce5233e8b) with subnets ['17.28.167.192/26']
2026-05-11 02:44:18,016 - DEBUG - Response for create_network : {'network': {'id': '769681dd-6727-4eb3-893a-d5ac6244f1b8', 'name': 'ctest-VN2-96759136', 'tenant_id': 'fed3a47956fd4270b2284aadf5713ba4', 'project_id': 'fed3a47956fd4270b2284aadf5713ba4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-61138828', 'ctest-VN2-96759136'], 'port_security_enabled': True, 'description': ''}}
2026-05-11 02:44:18,262 - DEBUG - Response for create_subnet : {'subnet': {'id': '0c3cca0d-3905-4c03-be95-e96455bb2cf9', 'name': '', 'tenant_id': 'fed3a47956fd4270b2284aadf5713ba4', 'network_id': '769681dd-6727-4eb3-893a-d5ac6244f1b8', 'ip_version': 4, 'cidr': '84.224.22.192/26', 'allocation_pools': [{'start': '84.224.22.194', 'end': '84.224.22.254'}], 'gateway_ip': '84.224.22.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '84.224.22.194', 'tags': [], 'project_id': 'fed3a47956fd4270b2284aadf5713ba4'}}
2026-05-11 02:44:18,284 - INFO - Created VN ctest-VN2-96759136
2026-05-11 02:44:18,295 - DEBUG - VN ctest-VN2-96759136 UUID is 769681dd-6727-4eb3-893a-d5ac6244f1b8
2026-05-11 02:44:18,716 - DEBUG - Response for create_network : {'network': {'id': '6ea778c4-62d6-48eb-b067-848c3f23ba4a', 'name': 'ctest-VN3-81211999', 'tenant_id': 'fed3a47956fd4270b2284aadf5713ba4', 'project_id': 'fed3a47956fd4270b2284aadf5713ba4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-61138828', 'ctest-VN3-81211999'], 'port_security_enabled': True, 'description': ''}}
2026-05-11 02:44:18,992 - DEBUG - Response for create_subnet : {'subnet': {'id': '42094731-ccce-4d90-9849-52abebc20afa', 'name': '', 'tenant_id': 'fed3a47956fd4270b2284aadf5713ba4', 'network_id': '6ea778c4-62d6-48eb-b067-848c3f23ba4a', 'ip_version': 4, 'cidr': '30.90.115.64/26', 'allocation_pools': [{'start': '30.90.115.66', 'end': '30.90.115.126'}], 'gateway_ip': '30.90.115.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '30.90.115.66', 'tags': [], 'project_id': 'fed3a47956fd4270b2284aadf5713ba4'}}
2026-05-11 02:44:19,018 - INFO - Created VN ctest-VN3-81211999
2026-05-11 02:44:19,033 - DEBUG - VN ctest-VN3-81211999 UUID is 6ea778c4-62d6-48eb-b067-848c3f23ba4a
2026-05-11 02:44:19,126 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:44:19,135 - DEBUG - Requesting: http://10.0.0.141:8082/domain/a797b29a-b743-4bb0-b070-a90ae89983d0
2026-05-11 02:44:19,146 - DEBUG - Requesting: http://10.0.0.141:8082/project/fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:44:19,261 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/b6175711-edb9-4f0e-b326-2fbce5233e8b
2026-05-11 02:44:19,276 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/b6175711-edb9-4f0e-b326-2fbce5233e8b
2026-05-11 02:44:19,286 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/8c159a3b-a8e1-47a8-bf2c-13277a2f0d6a
2026-05-11 02:44:19,299 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/8c159a3b-a8e1-47a8-bf2c-13277a2f0d6a
2026-05-11 02:44:19,305 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/63a794ea-d5f6-4415-8b94-b2072adc3915
2026-05-11 02:44:19,312 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-11 02:44:19,312 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/b6175711-edb9-4f0e-b326-2fbce5233e8b
2026-05-11 02:44:19,327 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/8c159a3b-a8e1-47a8-bf2c-13277a2f0d6a
2026-05-11 02:44:19,346 - INFO - Verified VN network id 13 for VN b6175711-edb9-4f0e-b326-2fbce5233e8b
2026-05-11 02:44:19,347 - INFO - Verifications in API Server for VN ctest-VN1-04725702 passed
2026-05-11 02:44:19,348 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/b6175711-edb9-4f0e-b326-2fbce5233e8b
2026-05-11 02:44:19,359 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/8c159a3b-a8e1-47a8-bf2c-13277a2f0d6a
2026-05-11 02:44:19,368 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/8c159a3b-a8e1-47a8-bf2c-13277a2f0d6a
2026-05-11 02:44:19,376 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/63a794ea-d5f6-4415-8b94-b2072adc3915
2026-05-11 02:44:19,388 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13121051773881634574', 'uuid-lslong': '12909057870248754827'}, 'enable': 'true', 'created': '2026-05-11T02:44:17', 'last-modified': '2026-05-11T02:44:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.047527'}
2026-05-11 02:44:19,395 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-11 02:44:19,403 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13121051773881634574', 'uuid-lslong': '12909057870248754827'}, 'enable': 'true', 'created': '2026-05-11T02:44:17', 'last-modified': '2026-05-11T02:44:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.062131'}
2026-05-11 02:44:19,411 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-11 02:44:19,417 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13121051773881634574', 'uuid-lslong': '12909057870248754827'}, 'enable': 'true', 'created': '2026-05-11T02:44:17', 'last-modified': '2026-05-11T02:44:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.074055'}
2026-05-11 02:44:19,423 - DEBUG - Route Targets: ['target:64512:8000004']
2026-05-11 02:44:19,423 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-04725702 passed
2026-05-11 02:44:19,424 - DEBUG - ====Verifying policy data for ctest-VN1-04725702 in API_Server ======
2026-05-11 02:44:19,424 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:44:19,434 - DEBUG - Requesting: http://10.0.0.141:8082/domain/a797b29a-b743-4bb0-b070-a90ae89983d0
2026-05-11 02:44:19,448 - DEBUG - Requesting: http://10.0.0.141:8082/project/fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:44:19,577 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/b6175711-edb9-4f0e-b326-2fbce5233e8b
2026-05-11 02:44:19,589 - DEBUG - =>VN ctest-VN1-04725702 has no policy to be verified
2026-05-11 02:44:19,589 - DEBUG - Verifying the vn in opserver
2026-05-11 02:44:19,589 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 virtual network link through opserver 10.0.0.141
2026-05-11 02:44:19,589 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2026-05-11 02:44:19,891 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2026-05-11 02:44:19,977 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702?flat'}
2026-05-11 02:44:19,977 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 virtual network link through opserver 10.0.0.249
2026-05-11 02:44:19,977 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-05-11 02:44:20,289 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-05-11 02:44:20,354 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702?flat'}
2026-05-11 02:44:20,355 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 virtual network link through opserver 10.0.0.27
2026-05-11 02:44:20,355 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-05-11 02:44:20,649 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-05-11 02:44:20,720 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702?flat'}
2026-05-11 02:44:20,721 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 is found in opserver
2026-05-11 02:44:20,746 - DEBUG - Do not have enough data to verify VN in agent
2026-05-11 02:44:20,757 - DEBUG - VRF ids for VN ctest-VN1-04725702: {}
2026-05-11 02:44:20,757 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:44:20,764 - DEBUG - Requesting: http://10.0.0.141:8082/domain/a797b29a-b743-4bb0-b070-a90ae89983d0
2026-05-11 02:44:20,774 - DEBUG - Requesting: http://10.0.0.141:8082/project/fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:44:20,868 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/769681dd-6727-4eb3-893a-d5ac6244f1b8
2026-05-11 02:44:20,884 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/769681dd-6727-4eb3-893a-d5ac6244f1b8
2026-05-11 02:44:20,894 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/9c81a491-90ae-44b0-8b51-711f2d6ff8bf
2026-05-11 02:44:20,903 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/9c81a491-90ae-44b0-8b51-711f2d6ff8bf
2026-05-11 02:44:20,912 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/0f550705-640c-4532-ade0-e8901ee1e42e
2026-05-11 02:44:20,920 - DEBUG - Route Targets: ['target:64512:8000005']
2026-05-11 02:44:20,921 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/769681dd-6727-4eb3-893a-d5ac6244f1b8
2026-05-11 02:44:20,931 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/9c81a491-90ae-44b0-8b51-711f2d6ff8bf
2026-05-11 02:44:20,957 - INFO - Verified VN network id 14 for VN 769681dd-6727-4eb3-893a-d5ac6244f1b8
2026-05-11 02:44:20,957 - INFO - Verifications in API Server for VN ctest-VN2-96759136 passed
2026-05-11 02:44:20,957 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/769681dd-6727-4eb3-893a-d5ac6244f1b8
2026-05-11 02:44:20,968 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/9c81a491-90ae-44b0-8b51-711f2d6ff8bf
2026-05-11 02:44:20,976 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/9c81a491-90ae-44b0-8b51-711f2d6ff8bf
2026-05-11 02:44:20,984 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/0f550705-640c-4532-ade0-e8901ee1e42e
2026-05-11 02:44:20,996 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8545160130900479667', 'uuid-lslong': '9888450868205121976'}, 'enable': 'true', 'created': '2026-05-11T02:44:17', 'last-modified': '2026-05-11T02:44:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.788944'}
2026-05-11 02:44:21,002 - DEBUG - Route Targets: ['target:64512:8000005']
2026-05-11 02:44:21,009 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8545160130900479667', 'uuid-lslong': '9888450868205121976'}, 'enable': 'true', 'created': '2026-05-11T02:44:17', 'last-modified': '2026-05-11T02:44:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.812582'}
2026-05-11 02:44:21,016 - DEBUG - Route Targets: ['target:64512:8000005']
2026-05-11 02:44:21,021 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8545160130900479667', 'uuid-lslong': '9888450868205121976'}, 'enable': 'true', 'created': '2026-05-11T02:44:17', 'last-modified': '2026-05-11T02:44:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.814082'}
2026-05-11 02:44:21,027 - DEBUG - Route Targets: ['target:64512:8000005']
2026-05-11 02:44:21,027 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-96759136 passed
2026-05-11 02:44:21,027 - DEBUG - ====Verifying policy data for ctest-VN2-96759136 in API_Server ======
2026-05-11 02:44:21,027 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:44:21,034 - DEBUG - Requesting: http://10.0.0.141:8082/domain/a797b29a-b743-4bb0-b070-a90ae89983d0
2026-05-11 02:44:21,046 - DEBUG - Requesting: http://10.0.0.141:8082/project/fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:44:21,153 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/769681dd-6727-4eb3-893a-d5ac6244f1b8
2026-05-11 02:44:21,165 - DEBUG - =>VN ctest-VN2-96759136 has no policy to be verified
2026-05-11 02:44:21,165 - DEBUG - Verifying the vn in opserver
2026-05-11 02:44:21,165 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136 virtual network link through opserver 10.0.0.141
2026-05-11 02:44:21,165 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2026-05-11 02:44:21,230 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136?flat'}
2026-05-11 02:44:21,230 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136 virtual network link through opserver 10.0.0.249
2026-05-11 02:44:21,230 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-05-11 02:44:21,300 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136?flat'}
2026-05-11 02:44:21,300 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136 virtual network link through opserver 10.0.0.27
2026-05-11 02:44:21,300 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-05-11 02:44:21,371 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136?flat'}
2026-05-11 02:44:21,371 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136 is found in opserver
2026-05-11 02:44:21,392 - DEBUG - Do not have enough data to verify VN in agent
2026-05-11 02:44:21,403 - DEBUG - VRF ids for VN ctest-VN2-96759136: {}
2026-05-11 02:44:21,403 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:44:21,409 - DEBUG - Requesting: http://10.0.0.141:8082/domain/a797b29a-b743-4bb0-b070-a90ae89983d0
2026-05-11 02:44:21,422 - DEBUG - Requesting: http://10.0.0.141:8082/project/fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:44:21,539 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/6ea778c4-62d6-48eb-b067-848c3f23ba4a
2026-05-11 02:44:21,557 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/6ea778c4-62d6-48eb-b067-848c3f23ba4a
2026-05-11 02:44:21,569 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/7b422dc8-dc21-4c6f-a3c7-5d5f58b92ad6
2026-05-11 02:44:21,578 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/7b422dc8-dc21-4c6f-a3c7-5d5f58b92ad6
2026-05-11 02:44:21,587 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/6ca06bcb-4826-444d-9d78-3ef3b691d36a
2026-05-11 02:44:21,594 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-11 02:44:21,594 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/6ea778c4-62d6-48eb-b067-848c3f23ba4a
2026-05-11 02:44:21,604 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/7b422dc8-dc21-4c6f-a3c7-5d5f58b92ad6
2026-05-11 02:44:21,622 - INFO - Verified VN network id 15 for VN 6ea778c4-62d6-48eb-b067-848c3f23ba4a
2026-05-11 02:44:21,622 - INFO - Verifications in API Server for VN ctest-VN3-81211999 passed
2026-05-11 02:44:21,622 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/6ea778c4-62d6-48eb-b067-848c3f23ba4a
2026-05-11 02:44:21,633 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/7b422dc8-dc21-4c6f-a3c7-5d5f58b92ad6
2026-05-11 02:44:21,640 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/7b422dc8-dc21-4c6f-a3c7-5d5f58b92ad6
2026-05-11 02:44:21,647 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/6ca06bcb-4826-444d-9d78-3ef3b691d36a
2026-05-11 02:44:21,662 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7973474450149886187', 'uuid-lslong': '12711274211166108234'}, 'enable': 'true', 'created': '2026-05-11T02:44:18', 'last-modified': '2026-05-11T02:44:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.728041'}
2026-05-11 02:44:21,667 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-11 02:44:21,672 - DEBUG - Control-node 10.0.0.249 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7973474450149886187', 'uuid-lslong': '12711274211166108234'}, 'enable': 'true', 'created': '2026-05-11T02:44:18', 'last-modified': '2026-05-11T02:44:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.755891'}
2026-05-11 02:44:21,678 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-11 02:44:21,683 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-61138828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7973474450149886187', 'uuid-lslong': '12711274211166108234'}, 'enable': 'true', 'created': '2026-05-11T02:44:18', 'last-modified': '2026-05-11T02:44:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.761518'}
2026-05-11 02:44:21,689 - DEBUG - Route Targets: ['target:64512:8000006']
2026-05-11 02:44:21,689 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-81211999 passed
2026-05-11 02:44:21,689 - DEBUG - ====Verifying policy data for ctest-VN3-81211999 in API_Server ======
2026-05-11 02:44:21,689 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:44:21,696 - DEBUG - Requesting: http://10.0.0.141:8082/domain/a797b29a-b743-4bb0-b070-a90ae89983d0
2026-05-11 02:44:21,709 - DEBUG - Requesting: http://10.0.0.141:8082/project/fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:44:21,809 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/6ea778c4-62d6-48eb-b067-848c3f23ba4a
2026-05-11 02:44:21,821 - DEBUG - =>VN ctest-VN3-81211999 has no policy to be verified
2026-05-11 02:44:21,821 - DEBUG - Verifying the vn in opserver
2026-05-11 02:44:21,821 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999 virtual network link through opserver 10.0.0.141
2026-05-11 02:44:21,821 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks
2026-05-11 02:44:21,889 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999?flat'}
2026-05-11 02:44:21,889 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999 virtual network link through opserver 10.0.0.249
2026-05-11 02:44:21,889 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-networks
2026-05-11 02:44:21,965 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999?flat'}
2026-05-11 02:44:21,965 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999 virtual network link through opserver 10.0.0.27
2026-05-11 02:44:21,965 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-05-11 02:44:22,033 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999?flat'}
2026-05-11 02:44:22,034 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999 is found in opserver
2026-05-11 02:44:22,058 - DEBUG - Do not have enough data to verify VN in agent
2026-05-11 02:44:22,069 - DEBUG - VRF ids for VN ctest-VN3-81211999: {}
2026-05-11 02:44:22,075 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702, 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-61138828:ctest-VN2-96759136, 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-05-11 02:44:22,349 - DEBUG - No destination network defined
2026-05-11 02:44:22,349 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136, 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-61138828:ctest-policy13-40554556, 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-05-11 02:44:22,625 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702, 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-61138828:ctest-VN3-81211999, 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-05-11 02:44:22,854 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:44:22,861 - DEBUG - Requesting: http://10.0.0.141:8082/domain/a797b29a-b743-4bb0-b070-a90ae89983d0
2026-05-11 02:44:22,874 - DEBUG - Requesting: http://10.0.0.141:8082/project/fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:44:22,979 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/b6175711-edb9-4f0e-b326-2fbce5233e8b
2026-05-11 02:44:22,991 - DEBUG - Setup step: Associating the policy to VN'
2026-05-11 02:44:23,193 - INFO - Associated Policy to ctest-VN1-04725702
2026-05-11 02:44:23,194 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:44:23,207 - DEBUG - Requesting: http://10.0.0.141:8082/domain/a797b29a-b743-4bb0-b070-a90ae89983d0
2026-05-11 02:44:23,224 - DEBUG - Requesting: http://10.0.0.141:8082/project/fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:44:23,346 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/769681dd-6727-4eb3-893a-d5ac6244f1b8
2026-05-11 02:44:23,357 - DEBUG - Setup step: Associating the policy to VN'
2026-05-11 02:44:23,499 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '769681dd-6727-4eb3-893a-d5ac6244f1b8', 'name': 'ctest-VN2-96759136', 'tenant_id': 'fed3a47956fd4270b2284aadf5713ba4', 'project_id': 'fed3a47956fd4270b2284aadf5713ba4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['0c3cca0d-3905-4c03-be95-e96455bb2cf9'], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-61138828', 'ctest-VN2-96759136'], 'policys': [['default-domain', 'ctest-TestPolicyAcl-61138828', 'ctest-policy21-45348557']], 'subnet_ipam': [{'subnet_cidr': '84.224.22.192/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAcl-61138828', 'ipam2']}], 'port_security_enabled': True, 'description': ''}}
2026-05-11 02:44:23,593 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAcl-61138828', 'ctest-policy21-45348557']] to ctest-VN2-96759136
2026-05-11 02:44:23,714 - DEBUG - Services list from nova: [, , , ]
2026-05-11 02:44:24,766 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5767-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5767-2)
2026-05-11 02:44:26,073 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5767-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5767-1)
2026-05-11 02:44:27,595 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5767-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5767-2)
2026-05-11 02:44:27,698 - DEBUG - VM is in ACTIVE state now
2026-05-11 02:44:27,698 - INFO - VM name : ctest-VM11-80306733
2026-05-11 02:44:27,797 - DEBUG - VM ctest-VM11-80306733 ID is 2c040c44-6c03-45ea-8637-ea6d44e3364f
2026-05-11 02:44:27,886 - DEBUG - VM is in ACTIVE state now
2026-05-11 02:44:27,913 - DEBUG - VM ctest-VM11-80306733 launched on Node an-jenkins-deploy-platform-ansible-os-5767-2
2026-05-11 02:44:28,008 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2c040c44-6c03-45ea-8637-ea6d44e3364f
2026-05-11 02:44:28,426 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2c040c44-6c03-45ea-8637-ea6d44e3364f
2026-05-11 02:44:28,467 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2c040c44-6c03-45ea-8637-ea6d44e3364f
2026-05-11 02:44:28,489 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/37ce34de-f98a-41eb-9e61-962fb7ba964d
2026-05-11 02:44:28,501 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2c040c44-6c03-45ea-8637-ea6d44e3364f
2026-05-11 02:44:28,509 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/37ce34de-f98a-41eb-9e61-962fb7ba964d
2026-05-11 02:44:28,521 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/b08be621-c45f-4f64-bd42-ff42173da86b
2026-05-11 02:44:28,544 - DEBUG - Verifying in api server 10.0.0.27
2026-05-11 02:44:28,544 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2c040c44-6c03-45ea-8637-ea6d44e3364f
2026-05-11 02:44:28,563 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/37ce34de-f98a-41eb-9e61-962fb7ba964d
2026-05-11 02:44:28,577 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/b08be621-c45f-4f64-bd42-ff42173da86b
2026-05-11 02:44:28,589 - INFO - VM ctest-VM11-80306733 verfication in all API Servers passed
2026-05-11 02:44:28,611 - DEBUG - VM ctest-VM11-80306733 Tap interface: {'index': '3', 'name': 'tap37ce34de-f9', 'uuid': '37ce34de-f98a-41eb-9e61-962fb7ba964d', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702:ctest-VN1-04725702', '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': '13', 'vn_name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702', 'vm_uuid': '2c040c44-6c03-45ea-8637-ea6d44e3364f', 'vm_name': 'ctest-VM11-80306733', 'ip_addr': '17.28.167.195', 'mac_addr': '02:37:ce:34:de:f9', '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-61138828:37ce34de-f98a-41eb-9e61-962fb7ba964d', 'sg_uuid_list': ['45843ff1-2399-46f1-abe7-28b22b0f6ba8'], 'static_route_list': None, 'vm_project_uuid': 'fed3a479-56fd-4270-b228-4aadf5713ba4', '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-05-11 02:44:28,611 - DEBUG - Agent 10.0.0.240 vrf name: default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702:ctest-VN1-04725702
2026-05-11 02:44:28,618 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702:ctest-VN1-04725702', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702', 'table_label': '-1', 'vxlan_id': '13', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.201:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-05-11 02:44:28,706 - DEBUG - VM ctest-VM11-80306733 : Tap interface tap37ce34de-f9 is set to Active
2026-05-11 02:44:28,706 - DEBUG - Tap interface tap37ce34de-f9 detail : {'index': '3', 'name': 'tap37ce34de-f9', 'uuid': '37ce34de-f98a-41eb-9e61-962fb7ba964d', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702:ctest-VN1-04725702', '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': '13', 'vn_name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702', 'vm_uuid': '2c040c44-6c03-45ea-8637-ea6d44e3364f', 'vm_name': 'ctest-VM11-80306733', 'ip_addr': '17.28.167.195', 'mac_addr': '02:37:ce:34:de:f9', '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-61138828:37ce34de-f98a-41eb-9e61-962fb7ba964d', 'sg_uuid_list': ['45843ff1-2399-46f1-abe7-28b22b0f6ba8'], 'static_route_list': None, 'vm_project_uuid': 'fed3a479-56fd-4270-b228-4aadf5713ba4', '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-05-11 02:44:28,820 - DEBUG - Starting Layer 2 verification in Agent
2026-05-11 02:44:28,834 - DEBUG - Layer 2 path is seen for VM MAC 02:37:ce:34:de:f9 in agent 10.0.0.240
2026-05-11 02:44:28,834 - DEBUG - Active layer 2 route in agent is present for VMI tap37ce34de-f9
2026-05-11 02:44:28,834 - DEBUG - L2 label(29) matches bw route table and interface table
2026-05-11 02:44:28,863 - DEBUG - VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 is found in Agent of node 10.0.0.240
2026-05-11 02:44:28,959 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 is consistent in agent 10.0.0.240
2026-05-11 02:44:28,959 - DEBUG - Route for VM IP ['17.28.167.195'] is consistent in agent 10.0.0.240
2026-05-11 02:44:28,959 - DEBUG - VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 verification for VM ctest-VM11-80306733 in Agent 10.0.0.240 passed
2026-05-11 02:44:29,074 - DEBUG - Starting all layer 2 verification in agent 10.0.0.240
2026-05-11 02:44:29,085 - DEBUG - Route for VM MAC 02:37:ce:34:de:f9 is consistent in agent 10.0.0.240
2026-05-11 02:44:32,282 - 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-05-11 02:44:32,283 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-80306733 failed!
2026-05-11 02:44:32,300 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 is 17.28.167.193 and allocation pool is NOT set
2026-05-11 02:44:36,379 - 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 1010ms')
2026-05-11 02:44:36,379 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-80306733 failed!
2026-05-11 02:44:36,399 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 is 17.28.167.193 and allocation pool is NOT set
2026-05-11 02:44:40,471 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms')
2026-05-11 02:44:40,471 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-80306733 failed!
2026-05-11 02:44:40,485 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 is 17.28.167.193 and allocation pool is NOT set
2026-05-11 02:44:44,567 - 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-05-11 02:44:44,567 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-80306733 failed!
2026-05-11 02:44:44,583 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 is 17.28.167.193 and allocation pool is NOT set
2026-05-11 02:44:48,662 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms')
2026-05-11 02:44:48,662 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-80306733 failed!
2026-05-11 02:44:48,676 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 is 17.28.167.193 and allocation pool is NOT set
2026-05-11 02:44:52,759 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms')
2026-05-11 02:44:52,759 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-80306733 failed!
2026-05-11 02:44:52,774 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 is 17.28.167.193 and allocation pool is NOT set
2026-05-11 02:44:56,861 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1022ms')
2026-05-11 02:44:56,862 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-80306733 failed!
2026-05-11 02:44:56,876 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 is 17.28.167.193 and allocation pool is NOT set
2026-05-11 02:45:00,955 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2026-05-11 02:45:00,955 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-80306733 failed!
2026-05-11 02:45:00,974 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 is 17.28.167.193 and allocation pool is NOT set
2026-05-11 02:45:05,043 - 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 1009ms')
2026-05-11 02:45:05,043 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-80306733 failed!
2026-05-11 02:45:05,062 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 is 17.28.167.193 and allocation pool is NOT set
2026-05-11 02:45:09,147 - 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-05-11 02:45:09,147 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-80306733 failed!
2026-05-11 02:45:09,165 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 is 17.28.167.193 and allocation pool is NOT set
2026-05-11 02:45:13,245 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms')
2026-05-11 02:45:13,245 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-80306733 failed!
2026-05-11 02:45:13,261 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 is 17.28.167.193 and allocation pool is NOT set
2026-05-11 02:45:17,336 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms')
2026-05-11 02:45:17,336 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-80306733 failed!
2026-05-11 02:45:17,353 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 is 17.28.167.193 and allocation pool is NOT set
2026-05-11 02:45:19,421 - 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=16.1 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.39 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.392/9.755/16.118/6.363 ms')
2026-05-11 02:45:19,421 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-80306733 passed
2026-05-11 02:45:19,421 - INFO - VM ctest-VM11-80306733 verifications in Compute nodes passed
2026-05-11 02:45:19,695 - DEBUG - Validated VM route 17.28.167.195 in vrouter of 10.0.0.240
2026-05-11 02:45:19,702 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-05-11 02:45:19,702 - INFO - Validated routes of VM ctest-VM11-80306733 in all vrouters
2026-05-11 02:45:20,149 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node
2026-05-11 02:45:20,282 - DEBUG - Layer2 route found for VM MAC 02:37:ce:34:de:f9 in Control-node 10.0.0.27
2026-05-11 02:45:20,282 - DEBUG - L2 Label for VM ctest-VM11-80306733 same between Control-node 10.0.0.27 and Agent, Expected: 29, Seen: 29
2026-05-11 02:45:20,282 - DEBUG - Starting all layer2 verification in 10.0.0.249 Control Node
2026-05-11 02:45:20,418 - DEBUG - Layer2 route found for VM MAC 02:37:ce:34:de:f9 in Control-node 10.0.0.249
2026-05-11 02:45:20,418 - DEBUG - L2 Label for VM ctest-VM11-80306733 same between Control-node 10.0.0.249 and Agent, Expected: 29, Seen: 29
2026-05-11 02:45:20,418 - DEBUG - Starting all layer2 verification in 10.0.0.141 Control Node
2026-05-11 02:45:20,546 - DEBUG - Layer2 route found for VM MAC 02:37:ce:34:de:f9 in Control-node 10.0.0.141
2026-05-11 02:45:20,546 - DEBUG - L2 Label for VM ctest-VM11-80306733 same between Control-node 10.0.0.141 and Agent, Expected: 29, Seen: 29
2026-05-11 02:45:20,546 - INFO - Verification in Control-nodes for VM ctest-VM11-80306733 passed
2026-05-11 02:45:20,546 - DEBUG - Verifying the vm in opserver
2026-05-11 02:45:20,546 - DEBUG - Verifying in collector 10.0.0.27 ...
2026-05-11 02:45:20,546 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/2c040c44-6c03-45ea-8637-ea6d44e3364f?flat
2026-05-11 02:45:20,564 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-61138828:37ce34de-f98a-41eb-9e61-962fb7ba964d?flat
2026-05-11 02:45:20,587 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-61138828:37ce34de-f98a-41eb-9e61-962fb7ba964d?flat
2026-05-11 02:45:20,609 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/2c040c44-6c03-45ea-8637-ea6d44e3364f?flat
2026-05-11 02:45:20,624 - DEBUG - Verifying vm in vn uve
2026-05-11 02:45:20,624 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-61138828:37ce34de-f98a-41eb-9e61-962fb7ba964d?flat
2026-05-11 02:45:20,643 - DEBUG - VM uve shows interface as {'vn_uuid': 'b6175711-edb9-4f0e-b326-2fbce5233e8b', 'mac_address': '02:37:ce:34:de:f9', 'label': 25, 'port_mirror_enabled': False, 'l2_active': True, 'is_health_check_active': True, 'uuid': '37ce34de-f98a-41eb-9e61-962fb7ba964d', 'virtual_network': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702', 'vhostuser_mode': 0, 'fixed_ip4_list': ['17.28.167.195'], 'ip6_active': False, 'ip_address': '17.28.167.195', 'rx_vlan': 65535, 'tx_vlan': 65535, '__T': 1778467490058888, 'ip6_address': '::', 'ip4_active': True, 'vm_name': 'ctest-VM11-80306733', 'vm_uuid': '2c040c44-6c03-45ea-8637-ea6d44e3364f', 'admin_state': True, 'gateway': '17.28.167.193', 'active': True}
2026-05-11 02:45:20,643 - DEBUG - VM uve shows ip address as ['17.28.167.195', '::']
2026-05-11 02:45:20,643 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702
2026-05-11 02:45:20,643 - INFO - Verifying through opserver in 10.0.0.141
2026-05-11 02:45:20,643 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702?flat
2026-05-11 02:45:20,669 - DEBUG - expected vm list 2c040c44-6c03-45ea-8637-ea6d44e3364f
2026-05-11 02:45:20,669 - DEBUG - Extracted vm list ['2c040c44-6c03-45ea-8637-ea6d44e3364f']
2026-05-11 02:45:20,669 - INFO - VM 2c040c44-6c03-45ea-8637-ea6d44e3364f is present in default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702
2026-05-11 02:45:20,669 - DEBUG - Verifying vm in vrouter uve
2026-05-11 02:45:20,669 - DEBUG - Getting info from collector 10.0.0.27..
2026-05-11 02:45:20,669 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/2c040c44-6c03-45ea-8637-ea6d44e3364f?flat
2026-05-11 02:45:20,687 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5767-2']
2026-05-11 02:45:20,688 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-2:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:45:20,727 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-2?flat
2026-05-11 02:45:20,760 - DEBUG - VM 2c040c44-6c03-45ea-8637-ea6d44e3364f is present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:45:20,760 - DEBUG - Validated that VM ctest-VM11-80306733 is in Vrouter an-jenkins-deploy-platform-ansible-os-5767-2 UVE
2026-05-11 02:45:20,760 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-05-11 02:45:20,767 - DEBUG - Expected tap interface of VM uuid 2c040c44-6c03-45ea-8637-ea6d44e3364f is default-domain:ctest-TestPolicyAcl-61138828:37ce34de-f98a-41eb-9e61-962fb7ba964d
2026-05-11 02:45:20,767 - DEBUG - Expected VN of VM uuid 2c040c44-6c03-45ea-8637-ea6d44e3364f is default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702
2026-05-11 02:45:20,767 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-2:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:45:20,805 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-2?flat
2026-05-11 02:45:20,836 - DEBUG - VM 2c040c44-6c03-45ea-8637-ea6d44e3364f is present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:45:20,836 - INFO - tap interface default-domain:ctest-TestPolicyAcl-61138828:37ce34de-f98a-41eb-9e61-962fb7ba964d of vm 2c040c44-6c03-45ea-8637-ea6d44e3364f is present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:45:20,836 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 of vm 2c040c44-6c03-45ea-8637-ea6d44e3364f is present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:45:20,836 - DEBUG - Verifying the 2c040c44-6c03-45ea-8637-ea6d44e3364f virtual network link through opserver 10.0.0.141
2026-05-11 02:45:20,836 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machines
2026-05-11 02:45:20,850 - DEBUG - VM link and name as {'name': '2c040c44-6c03-45ea-8637-ea6d44e3364f', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-machine/2c040c44-6c03-45ea-8637-ea6d44e3364f?flat'}
2026-05-11 02:45:20,850 - DEBUG - Verifying the 2c040c44-6c03-45ea-8637-ea6d44e3364f virtual network link through opserver 10.0.0.249
2026-05-11 02:45:20,850 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-05-11 02:45:20,867 - DEBUG - VM link and name as {'name': '2c040c44-6c03-45ea-8637-ea6d44e3364f', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-machine/2c040c44-6c03-45ea-8637-ea6d44e3364f?flat'}
2026-05-11 02:45:20,867 - DEBUG - Verifying the 2c040c44-6c03-45ea-8637-ea6d44e3364f virtual network link through opserver 10.0.0.27
2026-05-11 02:45:20,867 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-05-11 02:45:20,884 - DEBUG - VM link and name as {'name': '2c040c44-6c03-45ea-8637-ea6d44e3364f', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/2c040c44-6c03-45ea-8637-ea6d44e3364f?flat'}
2026-05-11 02:45:20,884 - INFO - VM ctest-VM11-80306733 validations in Opserver passed
2026-05-11 02:45:21,030 - DEBUG - VM is in ACTIVE state now
2026-05-11 02:45:21,030 - INFO - VM name : ctest-VM21-88281192
2026-05-11 02:45:21,130 - DEBUG - VM ctest-VM21-88281192 ID is 2ed297b1-ee39-4cfa-81f5-48cdd952503e
2026-05-11 02:45:21,219 - DEBUG - VM is in ACTIVE state now
2026-05-11 02:45:21,220 - DEBUG - VM ctest-VM21-88281192 launched on Node an-jenkins-deploy-platform-ansible-os-5767-1
2026-05-11 02:45:21,313 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2ed297b1-ee39-4cfa-81f5-48cdd952503e
2026-05-11 02:45:21,326 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2ed297b1-ee39-4cfa-81f5-48cdd952503e
2026-05-11 02:45:21,335 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/66ca11d4-33cc-4b5c-94d4-0c5acc0a127e
2026-05-11 02:45:21,355 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2ed297b1-ee39-4cfa-81f5-48cdd952503e
2026-05-11 02:45:21,365 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/66ca11d4-33cc-4b5c-94d4-0c5acc0a127e
2026-05-11 02:45:21,375 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/475e4264-1072-41ee-a6b2-d982458bd4ba
2026-05-11 02:45:21,386 - DEBUG - Verifying in api server 10.0.0.27
2026-05-11 02:45:21,386 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2ed297b1-ee39-4cfa-81f5-48cdd952503e
2026-05-11 02:45:21,394 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/66ca11d4-33cc-4b5c-94d4-0c5acc0a127e
2026-05-11 02:45:21,403 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/475e4264-1072-41ee-a6b2-d982458bd4ba
2026-05-11 02:45:21,412 - INFO - VM ctest-VM21-88281192 verfication in all API Servers passed
2026-05-11 02:45:21,437 - DEBUG - VM ctest-VM21-88281192 Tap interface: {'index': '3', 'name': 'tap66ca11d4-33', 'uuid': '66ca11d4-33cc-4b5c-94d4-0c5acc0a127e', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136:ctest-VN2-96759136', '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': '14', 'vn_name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136', 'vm_uuid': '2ed297b1-ee39-4cfa-81f5-48cdd952503e', 'vm_name': 'ctest-VM21-88281192', 'ip_addr': '84.224.22.195', 'mac_addr': '02:66:ca:11:d4:33', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '41', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-61138828:66ca11d4-33cc-4b5c-94d4-0c5acc0a127e', 'sg_uuid_list': ['45843ff1-2399-46f1-abe7-28b22b0f6ba8'], 'static_route_list': None, 'vm_project_uuid': 'fed3a479-56fd-4270-b228-4aadf5713ba4', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-05-11 02:45:21,437 - DEBUG - Agent 10.0.0.23 vrf name: default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136:ctest-VN2-96759136
2026-05-11 02:45:21,446 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136:ctest-VN2-96759136', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136', 'table_label': '-1', 'vxlan_id': '14', '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-05-11 02:45:21,537 - DEBUG - VM ctest-VM21-88281192 : Tap interface tap66ca11d4-33 is set to Active
2026-05-11 02:45:21,537 - DEBUG - Tap interface tap66ca11d4-33 detail : {'index': '3', 'name': 'tap66ca11d4-33', 'uuid': '66ca11d4-33cc-4b5c-94d4-0c5acc0a127e', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136:ctest-VN2-96759136', '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': '14', 'vn_name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136', 'vm_uuid': '2ed297b1-ee39-4cfa-81f5-48cdd952503e', 'vm_name': 'ctest-VM21-88281192', 'ip_addr': '84.224.22.195', 'mac_addr': '02:66:ca:11:d4:33', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '41', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-61138828:66ca11d4-33cc-4b5c-94d4-0c5acc0a127e', 'sg_uuid_list': ['45843ff1-2399-46f1-abe7-28b22b0f6ba8'], 'static_route_list': None, 'vm_project_uuid': 'fed3a479-56fd-4270-b228-4aadf5713ba4', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-05-11 02:45:21,651 - DEBUG - Starting Layer 2 verification in Agent
2026-05-11 02:45:21,673 - DEBUG - Layer 2 path is seen for VM MAC 02:66:ca:11:d4:33 in agent 10.0.0.23
2026-05-11 02:45:21,673 - DEBUG - Active layer 2 route in agent is present for VMI tap66ca11d4-33
2026-05-11 02:45:21,673 - DEBUG - L2 label(29) matches bw route table and interface table
2026-05-11 02:45:21,680 - DEBUG - VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136 is found in Agent of node 10.0.0.23
2026-05-11 02:45:21,821 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136 is consistent in agent 10.0.0.23
2026-05-11 02:45:21,821 - DEBUG - Route for VM IP ['84.224.22.195'] is consistent in agent 10.0.0.23
2026-05-11 02:45:21,821 - DEBUG - VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136 verification for VM ctest-VM21-88281192 in Agent 10.0.0.23 passed
2026-05-11 02:45:21,934 - DEBUG - Starting all layer 2 verification in agent 10.0.0.23
2026-05-11 02:45:21,957 - DEBUG - Route for VM MAC 02:66:ca:11:d4:33 is consistent in agent 10.0.0.23
2026-05-11 02:45:23,141 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=1.85 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.09 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.851/1.971/2.092/0.120 ms')
2026-05-11 02:45:23,141 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-88281192 passed
2026-05-11 02:45:23,141 - INFO - VM ctest-VM21-88281192 verifications in Compute nodes passed
2026-05-11 02:45:23,387 - DEBUG - Validated VM route 84.224.22.195 in vrouter of 10.0.0.23
2026-05-11 02:45:23,395 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-05-11 02:45:23,395 - INFO - Validated routes of VM ctest-VM21-88281192 in all vrouters
2026-05-11 02:45:23,816 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node
2026-05-11 02:45:24,010 - DEBUG - Layer2 route found for VM MAC 02:66:ca:11:d4:33 in Control-node 10.0.0.27
2026-05-11 02:45:24,010 - DEBUG - L2 Label for VM ctest-VM21-88281192 same between Control-node 10.0.0.27 and Agent, Expected: 29, Seen: 29
2026-05-11 02:45:24,010 - DEBUG - Starting all layer2 verification in 10.0.0.249 Control Node
2026-05-11 02:45:24,139 - DEBUG - Layer2 route found for VM MAC 02:66:ca:11:d4:33 in Control-node 10.0.0.249
2026-05-11 02:45:24,139 - DEBUG - L2 Label for VM ctest-VM21-88281192 same between Control-node 10.0.0.249 and Agent, Expected: 29, Seen: 29
2026-05-11 02:45:24,139 - DEBUG - Starting all layer2 verification in 10.0.0.141 Control Node
2026-05-11 02:45:24,262 - DEBUG - Layer2 route found for VM MAC 02:66:ca:11:d4:33 in Control-node 10.0.0.141
2026-05-11 02:45:24,263 - DEBUG - L2 Label for VM ctest-VM21-88281192 same between Control-node 10.0.0.141 and Agent, Expected: 29, Seen: 29
2026-05-11 02:45:24,263 - INFO - Verification in Control-nodes for VM ctest-VM21-88281192 passed
2026-05-11 02:45:24,263 - DEBUG - Verifying the vm in opserver
2026-05-11 02:45:24,263 - DEBUG - Verifying in collector 10.0.0.27 ...
2026-05-11 02:45:24,263 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/2ed297b1-ee39-4cfa-81f5-48cdd952503e?flat
2026-05-11 02:45:24,278 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-61138828:66ca11d4-33cc-4b5c-94d4-0c5acc0a127e?flat
2026-05-11 02:45:24,302 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-61138828:66ca11d4-33cc-4b5c-94d4-0c5acc0a127e?flat
2026-05-11 02:45:24,322 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/2ed297b1-ee39-4cfa-81f5-48cdd952503e?flat
2026-05-11 02:45:24,338 - DEBUG - Verifying vm in vn uve
2026-05-11 02:45:24,338 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-61138828:66ca11d4-33cc-4b5c-94d4-0c5acc0a127e?flat
2026-05-11 02:45:24,358 - DEBUG - VM uve shows interface as {'vn_uuid': '769681dd-6727-4eb3-893a-d5ac6244f1b8', 'mac_address': '02:66:ca:11:d4:33', 'label': 25, 'port_mirror_enabled': False, 'l2_active': True, 'is_health_check_active': True, 'uuid': '66ca11d4-33cc-4b5c-94d4-0c5acc0a127e', 'virtual_network': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136', 'vhostuser_mode': 0, 'fixed_ip4_list': ['84.224.22.195'], 'ip6_active': False, 'ip_address': '84.224.22.195', 'rx_vlan': 65535, 'tx_vlan': 65535, '__T': 1778467491610891, 'ip6_address': '::', 'ip4_active': True, 'vm_name': 'ctest-VM21-88281192', 'vm_uuid': '2ed297b1-ee39-4cfa-81f5-48cdd952503e', 'admin_state': True, 'gateway': '84.224.22.193', 'active': True}
2026-05-11 02:45:24,358 - DEBUG - VM uve shows ip address as ['84.224.22.195', '::']
2026-05-11 02:45:24,358 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136
2026-05-11 02:45:24,358 - INFO - Verifying through opserver in 10.0.0.141
2026-05-11 02:45:24,358 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136?flat
2026-05-11 02:45:24,382 - DEBUG - expected vm list 2ed297b1-ee39-4cfa-81f5-48cdd952503e
2026-05-11 02:45:24,382 - DEBUG - Extracted vm list ['2ed297b1-ee39-4cfa-81f5-48cdd952503e']
2026-05-11 02:45:24,383 - INFO - VM 2ed297b1-ee39-4cfa-81f5-48cdd952503e is present in default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136
2026-05-11 02:45:24,383 - DEBUG - Verifying vm in vrouter uve
2026-05-11 02:45:24,383 - DEBUG - Getting info from collector 10.0.0.27..
2026-05-11 02:45:24,383 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/2ed297b1-ee39-4cfa-81f5-48cdd952503e?flat
2026-05-11 02:45:24,406 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5767-1']
2026-05-11 02:45:24,406 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-1:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:45:24,444 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-1?flat
2026-05-11 02:45:24,480 - DEBUG - VM 2ed297b1-ee39-4cfa-81f5-48cdd952503e is present in vrouter an-jenkins-deploy-platform-ansible-os-5767-1 uve
2026-05-11 02:45:24,480 - DEBUG - Validated that VM ctest-VM21-88281192 is in Vrouter an-jenkins-deploy-platform-ansible-os-5767-1 UVE
2026-05-11 02:45:24,480 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-05-11 02:45:24,490 - DEBUG - Expected tap interface of VM uuid 2ed297b1-ee39-4cfa-81f5-48cdd952503e is default-domain:ctest-TestPolicyAcl-61138828:66ca11d4-33cc-4b5c-94d4-0c5acc0a127e
2026-05-11 02:45:24,490 - DEBUG - Expected VN of VM uuid 2ed297b1-ee39-4cfa-81f5-48cdd952503e is default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136
2026-05-11 02:45:24,490 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-1:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:45:24,525 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-1?flat
2026-05-11 02:45:24,560 - DEBUG - VM 2ed297b1-ee39-4cfa-81f5-48cdd952503e is present in vrouter an-jenkins-deploy-platform-ansible-os-5767-1 uve
2026-05-11 02:45:24,560 - INFO - tap interface default-domain:ctest-TestPolicyAcl-61138828:66ca11d4-33cc-4b5c-94d4-0c5acc0a127e of vm 2ed297b1-ee39-4cfa-81f5-48cdd952503e is present in vrouter an-jenkins-deploy-platform-ansible-os-5767-1 uve
2026-05-11 02:45:24,560 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136 of vm 2ed297b1-ee39-4cfa-81f5-48cdd952503e is present in vrouter an-jenkins-deploy-platform-ansible-os-5767-1 uve
2026-05-11 02:45:24,561 - DEBUG - Verifying the 2ed297b1-ee39-4cfa-81f5-48cdd952503e virtual network link through opserver 10.0.0.141
2026-05-11 02:45:24,561 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machines
2026-05-11 02:45:24,574 - DEBUG - VM link and name as {'name': '2ed297b1-ee39-4cfa-81f5-48cdd952503e', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-machine/2ed297b1-ee39-4cfa-81f5-48cdd952503e?flat'}
2026-05-11 02:45:24,574 - DEBUG - Verifying the 2ed297b1-ee39-4cfa-81f5-48cdd952503e virtual network link through opserver 10.0.0.249
2026-05-11 02:45:24,575 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-05-11 02:45:24,589 - DEBUG - VM link and name as {'name': '2ed297b1-ee39-4cfa-81f5-48cdd952503e', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-machine/2ed297b1-ee39-4cfa-81f5-48cdd952503e?flat'}
2026-05-11 02:45:24,589 - DEBUG - Verifying the 2ed297b1-ee39-4cfa-81f5-48cdd952503e virtual network link through opserver 10.0.0.27
2026-05-11 02:45:24,589 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-05-11 02:45:24,601 - DEBUG - VM link and name as {'name': '2ed297b1-ee39-4cfa-81f5-48cdd952503e', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/2ed297b1-ee39-4cfa-81f5-48cdd952503e?flat'}
2026-05-11 02:45:24,601 - INFO - VM ctest-VM21-88281192 validations in Opserver passed
2026-05-11 02:45:24,682 - DEBUG - VM is in ACTIVE state now
2026-05-11 02:45:24,683 - INFO - VM name : ctest-VM31-39084602
2026-05-11 02:45:24,764 - DEBUG - VM ctest-VM31-39084602 ID is 66faa303-1fde-4b32-b2c8-710fd5d516ac
2026-05-11 02:45:24,848 - DEBUG - VM is in ACTIVE state now
2026-05-11 02:45:24,848 - DEBUG - VM ctest-VM31-39084602 launched on Node an-jenkins-deploy-platform-ansible-os-5767-2
2026-05-11 02:45:24,928 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/66faa303-1fde-4b32-b2c8-710fd5d516ac
2026-05-11 02:45:24,938 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/66faa303-1fde-4b32-b2c8-710fd5d516ac
2026-05-11 02:45:24,944 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/1bc2d48a-9b10-4d21-9a5f-ffd8eb9fd28b
2026-05-11 02:45:24,955 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/66faa303-1fde-4b32-b2c8-710fd5d516ac
2026-05-11 02:45:24,962 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/1bc2d48a-9b10-4d21-9a5f-ffd8eb9fd28b
2026-05-11 02:45:24,973 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/225d09b3-ff9c-4fee-8661-1de866c8812f
2026-05-11 02:45:24,984 - DEBUG - Verifying in api server 10.0.0.27
2026-05-11 02:45:24,984 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/66faa303-1fde-4b32-b2c8-710fd5d516ac
2026-05-11 02:45:24,992 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/1bc2d48a-9b10-4d21-9a5f-ffd8eb9fd28b
2026-05-11 02:45:25,003 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/225d09b3-ff9c-4fee-8661-1de866c8812f
2026-05-11 02:45:25,012 - INFO - VM ctest-VM31-39084602 verfication in all API Servers passed
2026-05-11 02:45:25,032 - DEBUG - VM ctest-VM31-39084602 Tap interface: {'index': '4', 'name': 'tap1bc2d48a-9b', 'uuid': '1bc2d48a-9b10-4d21-9a5f-ffd8eb9fd28b', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999:ctest-VN3-81211999', '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': '15', 'vn_name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999', 'vm_uuid': '66faa303-1fde-4b32-b2c8-710fd5d516ac', 'vm_name': 'ctest-VM31-39084602', 'ip_addr': '30.90.115.67', 'mac_addr': '02:1b:c2:d4:8a:9b', '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-61138828:1bc2d48a-9b10-4d21-9a5f-ffd8eb9fd28b', 'sg_uuid_list': ['45843ff1-2399-46f1-abe7-28b22b0f6ba8'], 'static_route_list': None, 'vm_project_uuid': 'fed3a479-56fd-4270-b228-4aadf5713ba4', 'admin_state': 'Enabled', 'flow_key_idx': '39', '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-05-11 02:45:25,033 - DEBUG - Agent 10.0.0.240 vrf name: default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999:ctest-VN3-81211999
2026-05-11 02:45:25,039 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999:ctest-VN3-81211999', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999', 'table_label': '-1', 'vxlan_id': '15', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.201:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-05-11 02:45:25,120 - DEBUG - VM ctest-VM31-39084602 : Tap interface tap1bc2d48a-9b is set to Active
2026-05-11 02:45:25,120 - DEBUG - Tap interface tap1bc2d48a-9b detail : {'index': '4', 'name': 'tap1bc2d48a-9b', 'uuid': '1bc2d48a-9b10-4d21-9a5f-ffd8eb9fd28b', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999:ctest-VN3-81211999', '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': '15', 'vn_name': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999', 'vm_uuid': '66faa303-1fde-4b32-b2c8-710fd5d516ac', 'vm_name': 'ctest-VM31-39084602', 'ip_addr': '30.90.115.67', 'mac_addr': '02:1b:c2:d4:8a:9b', '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-61138828:1bc2d48a-9b10-4d21-9a5f-ffd8eb9fd28b', 'sg_uuid_list': ['45843ff1-2399-46f1-abe7-28b22b0f6ba8'], 'static_route_list': None, 'vm_project_uuid': 'fed3a479-56fd-4270-b228-4aadf5713ba4', 'admin_state': 'Enabled', 'flow_key_idx': '39', '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-05-11 02:45:25,233 - DEBUG - Starting Layer 2 verification in Agent
2026-05-11 02:45:25,250 - DEBUG - Layer 2 path is seen for VM MAC 02:1b:c2:d4:8a:9b in agent 10.0.0.240
2026-05-11 02:45:25,250 - DEBUG - Active layer 2 route in agent is present for VMI tap1bc2d48a-9b
2026-05-11 02:45:25,250 - DEBUG - L2 label(36) matches bw route table and interface table
2026-05-11 02:45:25,263 - DEBUG - VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999 is found in Agent of node 10.0.0.240
2026-05-11 02:45:25,398 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999 is consistent in agent 10.0.0.240
2026-05-11 02:45:25,398 - DEBUG - Route for VM IP ['30.90.115.67'] is consistent in agent 10.0.0.240
2026-05-11 02:45:25,398 - DEBUG - VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999 verification for VM ctest-VM31-39084602 in Agent 10.0.0.240 passed
2026-05-11 02:45:25,512 - DEBUG - Starting all layer 2 verification in agent 10.0.0.240
2026-05-11 02:45:25,523 - DEBUG - Route for VM MAC 02:1b:c2:d4:8a:9b is consistent in agent 10.0.0.240
2026-05-11 02:45:26,713 - 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.70 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=3.60 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 3.597/3.649/3.702/0.052 ms')
2026-05-11 02:45:26,713 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-39084602 passed
2026-05-11 02:45:26,713 - INFO - VM ctest-VM31-39084602 verifications in Compute nodes passed
2026-05-11 02:45:26,998 - DEBUG - Validated VM route 30.90.115.67 in vrouter of 10.0.0.240
2026-05-11 02:45:27,004 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-05-11 02:45:27,004 - INFO - Validated routes of VM ctest-VM31-39084602 in all vrouters
2026-05-11 02:45:27,550 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node
2026-05-11 02:45:27,673 - DEBUG - Layer2 route found for VM MAC 02:1b:c2:d4:8a:9b in Control-node 10.0.0.27
2026-05-11 02:45:27,673 - DEBUG - L2 Label for VM ctest-VM31-39084602 same between Control-node 10.0.0.27 and Agent, Expected: 36, Seen: 36
2026-05-11 02:45:27,673 - DEBUG - Starting all layer2 verification in 10.0.0.249 Control Node
2026-05-11 02:45:27,800 - DEBUG - Layer2 route found for VM MAC 02:1b:c2:d4:8a:9b in Control-node 10.0.0.249
2026-05-11 02:45:27,800 - DEBUG - L2 Label for VM ctest-VM31-39084602 same between Control-node 10.0.0.249 and Agent, Expected: 36, Seen: 36
2026-05-11 02:45:27,800 - INFO - Verification in Control-nodes for VM ctest-VM31-39084602 passed
2026-05-11 02:45:27,801 - DEBUG - Verifying the vm in opserver
2026-05-11 02:45:27,801 - DEBUG - Verifying in collector 10.0.0.27 ...
2026-05-11 02:45:27,801 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/66faa303-1fde-4b32-b2c8-710fd5d516ac?flat
2026-05-11 02:45:27,817 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-61138828:1bc2d48a-9b10-4d21-9a5f-ffd8eb9fd28b?flat
2026-05-11 02:45:27,838 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-61138828:1bc2d48a-9b10-4d21-9a5f-ffd8eb9fd28b?flat
2026-05-11 02:45:27,855 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/66faa303-1fde-4b32-b2c8-710fd5d516ac?flat
2026-05-11 02:45:27,870 - DEBUG - Verifying vm in vn uve
2026-05-11 02:45:27,870 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-61138828:1bc2d48a-9b10-4d21-9a5f-ffd8eb9fd28b?flat
2026-05-11 02:45:27,893 - DEBUG - VM uve shows interface as {'vn_uuid': '6ea778c4-62d6-48eb-b067-848c3f23ba4a', 'mac_address': '02:1b:c2:d4:8a:9b', 'label': 32, 'port_mirror_enabled': False, 'l2_active': True, 'is_health_check_active': True, 'uuid': '1bc2d48a-9b10-4d21-9a5f-ffd8eb9fd28b', 'virtual_network': 'default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999', 'vhostuser_mode': 0, 'fixed_ip4_list': ['30.90.115.67'], 'ip6_active': False, 'ip_address': '30.90.115.67', 'rx_vlan': 65535, 'tx_vlan': 65535, '__T': 1778467490058860, 'ip6_address': '::', 'ip4_active': True, 'vm_name': 'ctest-VM31-39084602', 'vm_uuid': '66faa303-1fde-4b32-b2c8-710fd5d516ac', 'admin_state': True, 'gateway': '30.90.115.65', 'active': True}
2026-05-11 02:45:27,893 - DEBUG - VM uve shows ip address as ['30.90.115.67', '::']
2026-05-11 02:45:27,893 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999
2026-05-11 02:45:27,893 - INFO - Verifying through opserver in 10.0.0.141
2026-05-11 02:45:27,893 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999?flat
2026-05-11 02:45:27,916 - DEBUG - expected vm list 66faa303-1fde-4b32-b2c8-710fd5d516ac
2026-05-11 02:45:27,916 - DEBUG - Extracted vm list ['66faa303-1fde-4b32-b2c8-710fd5d516ac']
2026-05-11 02:45:27,916 - INFO - VM 66faa303-1fde-4b32-b2c8-710fd5d516ac is present in default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999
2026-05-11 02:45:27,916 - DEBUG - Verifying vm in vrouter uve
2026-05-11 02:45:27,916 - DEBUG - Getting info from collector 10.0.0.27..
2026-05-11 02:45:27,916 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/66faa303-1fde-4b32-b2c8-710fd5d516ac?flat
2026-05-11 02:45:27,930 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5767-2']
2026-05-11 02:45:27,930 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-2:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:45:27,964 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-2?flat
2026-05-11 02:45:27,993 - DEBUG - VM 66faa303-1fde-4b32-b2c8-710fd5d516ac is present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:45:27,993 - DEBUG - Validated that VM ctest-VM31-39084602 is in Vrouter an-jenkins-deploy-platform-ansible-os-5767-2 UVE
2026-05-11 02:45:27,993 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-05-11 02:45:28,000 - DEBUG - Expected tap interface of VM uuid 66faa303-1fde-4b32-b2c8-710fd5d516ac is default-domain:ctest-TestPolicyAcl-61138828:1bc2d48a-9b10-4d21-9a5f-ffd8eb9fd28b
2026-05-11 02:45:28,000 - DEBUG - Expected VN of VM uuid 66faa303-1fde-4b32-b2c8-710fd5d516ac is default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999
2026-05-11 02:45:28,000 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-2:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:45:28,040 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-2?flat
2026-05-11 02:45:28,074 - DEBUG - VM 66faa303-1fde-4b32-b2c8-710fd5d516ac is present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:45:28,074 - INFO - tap interface default-domain:ctest-TestPolicyAcl-61138828:1bc2d48a-9b10-4d21-9a5f-ffd8eb9fd28b of vm 66faa303-1fde-4b32-b2c8-710fd5d516ac is present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:45:28,074 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999 of vm 66faa303-1fde-4b32-b2c8-710fd5d516ac is present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:45:28,074 - DEBUG - Verifying the 66faa303-1fde-4b32-b2c8-710fd5d516ac virtual network link through opserver 10.0.0.141
2026-05-11 02:45:28,074 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machines
2026-05-11 02:45:28,087 - DEBUG - VM link and name as {'name': '66faa303-1fde-4b32-b2c8-710fd5d516ac', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-machine/66faa303-1fde-4b32-b2c8-710fd5d516ac?flat'}
2026-05-11 02:45:28,087 - DEBUG - Verifying the 66faa303-1fde-4b32-b2c8-710fd5d516ac virtual network link through opserver 10.0.0.249
2026-05-11 02:45:28,087 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-05-11 02:45:28,103 - DEBUG - VM link and name as {'name': '66faa303-1fde-4b32-b2c8-710fd5d516ac', 'href': 'http://10.0.0.249:8081/analytics/uves/virtual-machine/66faa303-1fde-4b32-b2c8-710fd5d516ac?flat'}
2026-05-11 02:45:28,103 - DEBUG - Verifying the 66faa303-1fde-4b32-b2c8-710fd5d516ac virtual network link through opserver 10.0.0.27
2026-05-11 02:45:28,103 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-05-11 02:45:28,116 - DEBUG - VM link and name as {'name': '66faa303-1fde-4b32-b2c8-710fd5d516ac', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/66faa303-1fde-4b32-b2c8-710fd5d516ac?flat'}
2026-05-11 02:45:28,117 - INFO - VM ctest-VM31-39084602 validations in Opserver passed
2026-05-11 02:45:28,117 - INFO - Waiting for VM ctest-VM11-80306733 to be up..
2026-05-11 02:45:28,117 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 02:45:28,117 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2c040c44-6c03-45ea-8637-ea6d44e3364f
2026-05-11 02:45:28,124 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/37ce34de-f98a-41eb-9e61-962fb7ba964d
2026-05-11 02:45:29,303 - 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.77 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.73 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.727/2.248/2.770/0.521 ms')
2026-05-11 02:45:29,304 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-80306733 passed
2026-05-11 02:45:29,373 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 02:45:29,373 - DEBUG - Waiting to SSH to VM ctest-VM11-80306733, IP 17.28.167.195, Port 22
2026-05-11 02:45:29,440 - 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-05-11 02:45:29,523 - DEBUG - VM ctest-VM11-80306733 is NOT ready for SSH connections, VM status: ACTIVE
2026-05-11 02:45:34,524 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 02:45:34,524 - DEBUG - Waiting to SSH to VM ctest-VM11-80306733, IP 17.28.167.195, Port 22
2026-05-11 02:45:34,593 - 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-05-11 02:45:34,687 - DEBUG - VM ctest-VM11-80306733 is NOT ready for SSH connections, VM status: ACTIVE
2026-05-11 02:45:39,688 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 02:45:39,688 - DEBUG - Waiting to SSH to VM ctest-VM11-80306733, IP 17.28.167.195, Port 22
2026-05-11 02:45:39,755 - 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-05-11 02:45:39,854 - DEBUG - VM ctest-VM11-80306733 is NOT ready for SSH connections, VM status: ACTIVE
2026-05-11 02:45:44,855 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 02:45:44,855 - DEBUG - Waiting to SSH to VM ctest-VM11-80306733, IP 17.28.167.195, Port 22
2026-05-11 02:45:45,017 - DEBUG - VM ctest-VM11-80306733 is ready for SSH connections
2026-05-11 02:45:45,017 - INFO - Waiting for VM ctest-VM21-88281192 to be up..
2026-05-11 02:45:45,018 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 02:45:45,018 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2ed297b1-ee39-4cfa-81f5-48cdd952503e
2026-05-11 02:45:45,027 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/66ca11d4-33cc-4b5c-94d4-0c5acc0a127e
2026-05-11 02:45:46,191 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=3.85 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.532 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.532/2.189/3.847/1.657 ms')
2026-05-11 02:45:46,191 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-88281192 passed
2026-05-11 02:45:46,263 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 02:45:46,264 - DEBUG - Waiting to SSH to VM ctest-VM21-88281192, IP 84.224.22.195, Port 22
2026-05-11 02:45:46,438 - DEBUG - VM ctest-VM21-88281192 is ready for SSH connections
2026-05-11 02:45:46,438 - INFO - Waiting for VM ctest-VM31-39084602 to be up..
2026-05-11 02:45:46,438 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 02:45:46,438 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/66faa303-1fde-4b32-b2c8-710fd5d516ac
2026-05-11 02:45:46,446 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/1bc2d48a-9b10-4d21-9a5f-ffd8eb9fd28b
2026-05-11 02:45:47,617 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=5.99 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.65 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.648/3.818/5.989/2.170 ms')
2026-05-11 02:45:47,617 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-39084602 passed
2026-05-11 02:45:47,686 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 02:45:47,686 - DEBUG - Waiting to SSH to VM ctest-VM31-39084602, IP 30.90.115.67, Port 22
2026-05-11 02:45:47,852 - DEBUG - VM ctest-VM31-39084602 is ready for SSH connections
2026-05-11 02:45:47,852 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 84.224.22.195, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-05-11 02:45:47,852 - DEBUG - ping -s 56 -c 3 -W 1 84.224.22.195
2026-05-11 02:46:13,480 - DEBUG - PING 84.224.22.195 (84.224.22.195) 56(84) bytes of data.
64 bytes from 84.224.22.195: icmp_req=1 ttl=63 time=8.70 ms
64 bytes from 84.224.22.195: icmp_req=2 ttl=63 time=1.29 ms
64 bytes from 84.224.22.195: icmp_req=3 ttl=63 time=1.39 ms
--- 84.224.22.195 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 1.293/3.795/8.704/3.471 ms
2026-05-11 02:46:13,480 - INFO - Ping to IP 84.224.22.195 from VM ctest-VM11-80306733 passed
2026-05-11 02:46:13,480 - INFO - Test with src as VN and dst as policy PASSED
2026-05-11 02:46:13,480 - INFO - Deleting VM ctest-VM31-39084602
2026-05-11 02:46:13,565 - DEBUG - Verifying in api server 10.0.0.27
2026-05-11 02:46:13,565 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/66faa303-1fde-4b32-b2c8-710fd5d516ac
2026-05-11 02:46:13,575 - DEBUG - VM ID 66faa303-1fde-4b32-b2c8-710fd5d516ac of VM ctest-VM31-39084602 is still found in API Server
2026-05-11 02:46:15,576 - DEBUG - Verifying in api server 10.0.0.27
2026-05-11 02:46:15,577 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/66faa303-1fde-4b32-b2c8-710fd5d516ac
2026-05-11 02:46:15,586 - DEBUG - Response Code: 404
2026-05-11 02:46:15,586 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/66faa303-1fde-4b32-b2c8-710fd5d516ac
2026-05-11 02:46:15,595 - DEBUG - Response Code: 404
2026-05-11 02:46:15,595 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/66faa303-1fde-4b32-b2c8-710fd5d516ac
2026-05-11 02:46:15,602 - DEBUG - Response Code: 404
2026-05-11 02:46:15,603 - INFO - VM ctest-VM31-39084602 is fully removed in API-Server
2026-05-11 02:46:15,775 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-05-11 02:46:15,823 - DEBUG - Validated that vrouter 10.0.0.23 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999
2026-05-11 02:46:15,835 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999
2026-05-11 02:46:15,835 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999
2026-05-11 02:46:15,835 - INFO - VM ctest-VM31-39084602 is removed in Compute, and routes are removed in all compute nodes
2026-05-11 02:46:16,093 - INFO - Routes for VM ctest-VM31-39084602 is removed in all control-nodes
2026-05-11 02:46:16,093 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-2:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:46:16,137 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-2?flat
2026-05-11 02:46:16,167 - DEBUG - VM 66faa303-1fde-4b32-b2c8-710fd5d516ac is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:46:16,167 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:46:16,167 - DEBUG - interface for vm 66faa303-1fde-4b32-b2c8-710fd5d516ac deleted from vrouter uve ...
2026-05-11 02:46:16,167 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:46:19,168 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-2:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:46:19,221 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-2?flat
2026-05-11 02:46:19,257 - DEBUG - VM 66faa303-1fde-4b32-b2c8-710fd5d516ac is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:46:19,257 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:46:19,258 - DEBUG - interface for vm 66faa303-1fde-4b32-b2c8-710fd5d516ac deleted from vrouter uve ...
2026-05-11 02:46:19,258 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:46:22,258 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-2:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:46:22,298 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-2?flat
2026-05-11 02:46:22,337 - DEBUG - VM 66faa303-1fde-4b32-b2c8-710fd5d516ac is not present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:46:22,337 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:46:22,337 - DEBUG - interface for vm 66faa303-1fde-4b32-b2c8-710fd5d516ac deleted from vrouter uve ...
2026-05-11 02:46:22,337 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:46:22,337 - DEBUG - Validated that VM 66faa303-1fde-4b32-b2c8-710fd5d516ac is removed in Vrouter UVE
2026-05-11 02:46:22,337 - DEBUG - Verifying through opserver in 10.0.0.141
2026-05-11 02:46:22,337 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999?flat
2026-05-11 02:46:22,358 - DEBUG - 66faa303-1fde-4b32-b2c8-710fd5d516ac BM not in default-domain:ctest-TestPolicyAcl-61138828:ctest-VN3-81211999 uve
2026-05-11 02:46:22,358 - DEBUG - Validated that VM 66faa303-1fde-4b32-b2c8-710fd5d516ac is not present in the VN UVE
2026-05-11 02:46:22,359 - DEBUG - Verifying the 66faa303-1fde-4b32-b2c8-710fd5d516ac virtual network link through opserver 10.0.0.141
2026-05-11 02:46:22,359 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machines
2026-05-11 02:46:22,374 - INFO - 66faa303-1fde-4b32-b2c8-710fd5d516ac vm uve deleted from opserver
2026-05-11 02:46:22,374 - DEBUG - Verifying the 66faa303-1fde-4b32-b2c8-710fd5d516ac virtual network link through opserver 10.0.0.249
2026-05-11 02:46:22,374 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-05-11 02:46:22,393 - INFO - 66faa303-1fde-4b32-b2c8-710fd5d516ac vm uve deleted from opserver
2026-05-11 02:46:22,393 - DEBUG - Verifying the 66faa303-1fde-4b32-b2c8-710fd5d516ac virtual network link through opserver 10.0.0.27
2026-05-11 02:46:22,394 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-05-11 02:46:22,409 - INFO - 66faa303-1fde-4b32-b2c8-710fd5d516ac vm uve deleted from opserver
2026-05-11 02:46:22,409 - INFO - Validated that VM 66faa303-1fde-4b32-b2c8-710fd5d516ac is removed from Opserver
2026-05-11 02:46:22,409 - INFO - Deleting VM ctest-VM21-88281192
2026-05-11 02:46:22,508 - DEBUG - Verifying in api server 10.0.0.27
2026-05-11 02:46:22,508 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2ed297b1-ee39-4cfa-81f5-48cdd952503e
2026-05-11 02:46:22,518 - DEBUG - VM ID 2ed297b1-ee39-4cfa-81f5-48cdd952503e of VM ctest-VM21-88281192 is still found in API Server
2026-05-11 02:46:24,519 - DEBUG - Verifying in api server 10.0.0.27
2026-05-11 02:46:24,519 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2ed297b1-ee39-4cfa-81f5-48cdd952503e
2026-05-11 02:46:24,527 - DEBUG - Response Code: 404
2026-05-11 02:46:24,527 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2ed297b1-ee39-4cfa-81f5-48cdd952503e
2026-05-11 02:46:24,535 - DEBUG - Response Code: 404
2026-05-11 02:46:24,535 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2ed297b1-ee39-4cfa-81f5-48cdd952503e
2026-05-11 02:46:24,543 - DEBUG - Response Code: 404
2026-05-11 02:46:24,543 - INFO - VM ctest-VM21-88281192 is fully removed in API-Server
2026-05-11 02:46:24,697 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-05-11 02:46:24,737 - DEBUG - Validated that vrouter 10.0.0.23 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136
2026-05-11 02:46:24,747 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136
2026-05-11 02:46:24,747 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136
2026-05-11 02:46:24,748 - INFO - VM ctest-VM21-88281192 is removed in Compute, and routes are removed in all compute nodes
2026-05-11 02:46:24,981 - INFO - Routes for VM ctest-VM21-88281192 is removed in all control-nodes
2026-05-11 02:46:24,981 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-1:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:46:25,020 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-1?flat
2026-05-11 02:46:25,060 - DEBUG - VM 2ed297b1-ee39-4cfa-81f5-48cdd952503e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-1 uve
2026-05-11 02:46:25,060 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:46:25,060 - DEBUG - interface for vm 2ed297b1-ee39-4cfa-81f5-48cdd952503e deleted from vrouter uve ...
2026-05-11 02:46:25,060 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:46:28,061 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-1:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:46:28,100 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-1?flat
2026-05-11 02:46:28,132 - DEBUG - VM 2ed297b1-ee39-4cfa-81f5-48cdd952503e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-1 uve
2026-05-11 02:46:28,132 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:46:28,133 - DEBUG - interface for vm 2ed297b1-ee39-4cfa-81f5-48cdd952503e deleted from vrouter uve ...
2026-05-11 02:46:28,133 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:46:31,134 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-1:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:46:31,176 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-1?flat
2026-05-11 02:46:31,206 - DEBUG - VM 2ed297b1-ee39-4cfa-81f5-48cdd952503e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-1 uve
2026-05-11 02:46:31,206 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:46:31,206 - DEBUG - interface for vm 2ed297b1-ee39-4cfa-81f5-48cdd952503e deleted from vrouter uve ...
2026-05-11 02:46:31,206 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:46:34,207 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-1:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:46:34,249 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-1?flat
2026-05-11 02:46:34,285 - DEBUG - VM 2ed297b1-ee39-4cfa-81f5-48cdd952503e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-1 uve
2026-05-11 02:46:34,285 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:46:34,285 - DEBUG - interface for vm 2ed297b1-ee39-4cfa-81f5-48cdd952503e deleted from vrouter uve ...
2026-05-11 02:46:34,285 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:46:37,286 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-1:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:46:37,323 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-1?flat
2026-05-11 02:46:37,355 - DEBUG - VM 2ed297b1-ee39-4cfa-81f5-48cdd952503e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-1 uve
2026-05-11 02:46:37,355 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:46:37,355 - DEBUG - interface for vm 2ed297b1-ee39-4cfa-81f5-48cdd952503e deleted from vrouter uve ...
2026-05-11 02:46:37,355 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:46:40,356 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-1:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:46:40,391 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-1?flat
2026-05-11 02:46:40,419 - DEBUG - VM 2ed297b1-ee39-4cfa-81f5-48cdd952503e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-1 uve
2026-05-11 02:46:40,419 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:46:40,419 - DEBUG - interface for vm 2ed297b1-ee39-4cfa-81f5-48cdd952503e deleted from vrouter uve ...
2026-05-11 02:46:40,419 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:46:43,420 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-1:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:46:43,467 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-1?flat
2026-05-11 02:46:43,502 - DEBUG - VM 2ed297b1-ee39-4cfa-81f5-48cdd952503e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-1 uve
2026-05-11 02:46:43,502 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:46:43,502 - DEBUG - interface for vm 2ed297b1-ee39-4cfa-81f5-48cdd952503e deleted from vrouter uve ...
2026-05-11 02:46:43,502 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:46:46,503 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-1:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:46:46,544 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-1?flat
2026-05-11 02:46:46,573 - DEBUG - VM 2ed297b1-ee39-4cfa-81f5-48cdd952503e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-1 uve
2026-05-11 02:46:46,573 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:46:46,573 - DEBUG - interface for vm 2ed297b1-ee39-4cfa-81f5-48cdd952503e deleted from vrouter uve ...
2026-05-11 02:46:46,573 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:46:49,575 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-1:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:46:49,612 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-1?flat
2026-05-11 02:46:49,646 - DEBUG - VM 2ed297b1-ee39-4cfa-81f5-48cdd952503e is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-1 uve
2026-05-11 02:46:49,646 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:46:49,646 - DEBUG - interface for vm 2ed297b1-ee39-4cfa-81f5-48cdd952503e deleted from vrouter uve ...
2026-05-11 02:46:49,646 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:46:52,647 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-1:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:46:52,695 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-1?flat
2026-05-11 02:46:52,727 - DEBUG - VM 2ed297b1-ee39-4cfa-81f5-48cdd952503e is not present in vrouter an-jenkins-deploy-platform-ansible-os-5767-1 uve
2026-05-11 02:46:52,727 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:46:52,727 - DEBUG - interface for vm 2ed297b1-ee39-4cfa-81f5-48cdd952503e deleted from vrouter uve ...
2026-05-11 02:46:52,727 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:46:52,727 - DEBUG - Validated that VM 2ed297b1-ee39-4cfa-81f5-48cdd952503e is removed in Vrouter UVE
2026-05-11 02:46:52,727 - DEBUG - Verifying through opserver in 10.0.0.141
2026-05-11 02:46:52,727 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136?flat
2026-05-11 02:46:52,754 - DEBUG - 2ed297b1-ee39-4cfa-81f5-48cdd952503e BM not in default-domain:ctest-TestPolicyAcl-61138828:ctest-VN2-96759136 uve
2026-05-11 02:46:52,754 - DEBUG - Validated that VM 2ed297b1-ee39-4cfa-81f5-48cdd952503e is not present in the VN UVE
2026-05-11 02:46:52,754 - DEBUG - Verifying the 2ed297b1-ee39-4cfa-81f5-48cdd952503e virtual network link through opserver 10.0.0.141
2026-05-11 02:46:52,754 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machines
2026-05-11 02:46:52,769 - INFO - 2ed297b1-ee39-4cfa-81f5-48cdd952503e vm uve deleted from opserver
2026-05-11 02:46:52,769 - DEBUG - Verifying the 2ed297b1-ee39-4cfa-81f5-48cdd952503e virtual network link through opserver 10.0.0.249
2026-05-11 02:46:52,769 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-05-11 02:46:52,784 - INFO - 2ed297b1-ee39-4cfa-81f5-48cdd952503e vm uve deleted from opserver
2026-05-11 02:46:52,784 - DEBUG - Verifying the 2ed297b1-ee39-4cfa-81f5-48cdd952503e virtual network link through opserver 10.0.0.27
2026-05-11 02:46:52,784 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-05-11 02:46:52,841 - INFO - 2ed297b1-ee39-4cfa-81f5-48cdd952503e vm uve deleted from opserver
2026-05-11 02:46:52,841 - INFO - Validated that VM 2ed297b1-ee39-4cfa-81f5-48cdd952503e is removed from Opserver
2026-05-11 02:46:52,841 - INFO - Deleting VM ctest-VM11-80306733
2026-05-11 02:46:52,914 - DEBUG - Verifying in api server 10.0.0.27
2026-05-11 02:46:52,914 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2c040c44-6c03-45ea-8637-ea6d44e3364f
2026-05-11 02:46:52,924 - DEBUG - VM ID 2c040c44-6c03-45ea-8637-ea6d44e3364f of VM ctest-VM11-80306733 is still found in API Server
2026-05-11 02:46:54,924 - DEBUG - Verifying in api server 10.0.0.27
2026-05-11 02:46:54,924 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2c040c44-6c03-45ea-8637-ea6d44e3364f
2026-05-11 02:46:54,933 - DEBUG - Response Code: 404
2026-05-11 02:46:54,933 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2c040c44-6c03-45ea-8637-ea6d44e3364f
2026-05-11 02:46:54,940 - DEBUG - Response Code: 404
2026-05-11 02:46:54,940 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2c040c44-6c03-45ea-8637-ea6d44e3364f
2026-05-11 02:46:54,948 - DEBUG - Response Code: 404
2026-05-11 02:46:54,948 - INFO - VM ctest-VM11-80306733 is fully removed in API-Server
2026-05-11 02:46:55,104 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-05-11 02:46:55,147 - DEBUG - Validated that vrouter 10.0.0.23 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702
2026-05-11 02:46:55,159 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702
2026-05-11 02:46:55,159 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702
2026-05-11 02:46:55,159 - INFO - VM ctest-VM11-80306733 is removed in Compute, and routes are removed in all compute nodes
2026-05-11 02:46:55,359 - INFO - Routes for VM ctest-VM11-80306733 is removed in all control-nodes
2026-05-11 02:46:55,359 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-2:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:46:55,405 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-2?flat
2026-05-11 02:46:55,437 - DEBUG - VM 2c040c44-6c03-45ea-8637-ea6d44e3364f is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:46:55,437 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:46:55,437 - DEBUG - interface for vm 2c040c44-6c03-45ea-8637-ea6d44e3364f deleted from vrouter uve ...
2026-05-11 02:46:55,437 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:46:58,438 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-2:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:46:58,481 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-2?flat
2026-05-11 02:46:58,516 - DEBUG - VM 2c040c44-6c03-45ea-8637-ea6d44e3364f is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:46:58,516 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:46:58,516 - DEBUG - interface for vm 2c040c44-6c03-45ea-8637-ea6d44e3364f deleted from vrouter uve ...
2026-05-11 02:46:58,516 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:47:01,517 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-2:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:47:01,558 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-2?flat
2026-05-11 02:47:01,596 - DEBUG - VM 2c040c44-6c03-45ea-8637-ea6d44e3364f is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:47:01,596 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:47:01,596 - DEBUG - interface for vm 2c040c44-6c03-45ea-8637-ea6d44e3364f deleted from vrouter uve ...
2026-05-11 02:47:01,596 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:47:04,596 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-2:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:47:04,646 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-2?flat
2026-05-11 02:47:04,679 - DEBUG - VM 2c040c44-6c03-45ea-8637-ea6d44e3364f is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:47:04,679 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:47:04,679 - DEBUG - interface for vm 2c040c44-6c03-45ea-8637-ea6d44e3364f deleted from vrouter uve ...
2026-05-11 02:47:04,679 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:47:07,680 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-2:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:47:07,719 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-2?flat
2026-05-11 02:47:07,751 - DEBUG - VM 2c040c44-6c03-45ea-8637-ea6d44e3364f is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:47:07,751 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:47:07,751 - DEBUG - interface for vm 2c040c44-6c03-45ea-8637-ea6d44e3364f deleted from vrouter uve ...
2026-05-11 02:47:07,751 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:47:10,752 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-2:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:47:10,797 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-2?flat
2026-05-11 02:47:10,845 - DEBUG - VM 2c040c44-6c03-45ea-8637-ea6d44e3364f is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:47:10,845 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:47:10,845 - DEBUG - interface for vm 2c040c44-6c03-45ea-8637-ea6d44e3364f deleted from vrouter uve ...
2026-05-11 02:47:10,845 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:47:13,846 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-2:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:47:13,893 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-2?flat
2026-05-11 02:47:13,934 - DEBUG - VM 2c040c44-6c03-45ea-8637-ea6d44e3364f is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:47:13,934 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:47:13,934 - DEBUG - interface for vm 2c040c44-6c03-45ea-8637-ea6d44e3364f deleted from vrouter uve ...
2026-05-11 02:47:13,934 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:47:16,935 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-2:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:47:16,977 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-2?flat
2026-05-11 02:47:17,009 - DEBUG - VM 2c040c44-6c03-45ea-8637-ea6d44e3364f is still present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:47:17,009 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:47:17,009 - DEBUG - interface for vm 2c040c44-6c03-45ea-8637-ea6d44e3364f deleted from vrouter uve ...
2026-05-11 02:47:17,009 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:47:20,010 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5767-2:Compute:contrail-vrouter-agent:0?flat
2026-05-11 02:47:20,050 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5767-2?flat
2026-05-11 02:47:20,088 - DEBUG - VM 2c040c44-6c03-45ea-8637-ea6d44e3364f is not present in vrouter an-jenkins-deploy-platform-ansible-os-5767-2 uve
2026-05-11 02:47:20,088 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-05-11 02:47:20,088 - DEBUG - interface for vm 2c040c44-6c03-45ea-8637-ea6d44e3364f deleted from vrouter uve ...
2026-05-11 02:47:20,088 - DEBUG - Deleted interface not in error interface list ...
2026-05-11 02:47:20,088 - DEBUG - Validated that VM 2c040c44-6c03-45ea-8637-ea6d44e3364f is removed in Vrouter UVE
2026-05-11 02:47:20,088 - DEBUG - Verifying through opserver in 10.0.0.141
2026-05-11 02:47:20,088 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702?flat
2026-05-11 02:47:20,118 - DEBUG - 2c040c44-6c03-45ea-8637-ea6d44e3364f BM not in default-domain:ctest-TestPolicyAcl-61138828:ctest-VN1-04725702 uve
2026-05-11 02:47:20,118 - DEBUG - Validated that VM 2c040c44-6c03-45ea-8637-ea6d44e3364f is not present in the VN UVE
2026-05-11 02:47:20,118 - DEBUG - Verifying the 2c040c44-6c03-45ea-8637-ea6d44e3364f virtual network link through opserver 10.0.0.141
2026-05-11 02:47:20,118 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-machines
2026-05-11 02:47:20,134 - DEBUG - No links retuned for 2c040c44-6c03-45ea-8637-ea6d44e3364f
2026-05-11 02:47:20,134 - INFO - 2c040c44-6c03-45ea-8637-ea6d44e3364f vm uve deleted from opserver
2026-05-11 02:47:20,134 - DEBUG - Verifying the 2c040c44-6c03-45ea-8637-ea6d44e3364f virtual network link through opserver 10.0.0.249
2026-05-11 02:47:20,134 - DEBUG - Requesting: http://10.0.0.249:8081/analytics/uves/virtual-machines
2026-05-11 02:47:20,152 - DEBUG - No links retuned for 2c040c44-6c03-45ea-8637-ea6d44e3364f
2026-05-11 02:47:20,152 - INFO - 2c040c44-6c03-45ea-8637-ea6d44e3364f vm uve deleted from opserver
2026-05-11 02:47:20,152 - DEBUG - Verifying the 2c040c44-6c03-45ea-8637-ea6d44e3364f virtual network link through opserver 10.0.0.27
2026-05-11 02:47:20,152 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-05-11 02:47:20,168 - DEBUG - No links retuned for 2c040c44-6c03-45ea-8637-ea6d44e3364f
2026-05-11 02:47:20,168 - INFO - 2c040c44-6c03-45ea-8637-ea6d44e3364f vm uve deleted from opserver
2026-05-11 02:47:20,168 - INFO - Validated that VM 2c040c44-6c03-45ea-8637-ea6d44e3364f is removed from Opserver
2026-05-11 02:47:20,168 - DEBUG - Detaching the Policy for VN :ctest-VN2-96759136
2026-05-11 02:47:20,168 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:47:20,176 - DEBUG - Requesting: http://10.0.0.141:8082/domain/a797b29a-b743-4bb0-b070-a90ae89983d0
2026-05-11 02:47:20,190 - DEBUG - Requesting: http://10.0.0.141:8082/project/fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:47:20,284 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/769681dd-6727-4eb3-893a-d5ac6244f1b8
2026-05-11 02:47:20,306 - DEBUG - Requesting: http://10.0.0.141:8082/network-policy/0cd8fd22-00b5-418a-b638-508d6c7aae92
2026-05-11 02:47:20,512 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAcl-61138828', 'ctest-policy21-45348557']] from ctest-VN2-96759136
2026-05-11 02:47:20,512 - DEBUG - Detaching the Policy for VN :ctest-VN1-04725702
2026-05-11 02:47:20,512 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:47:20,519 - DEBUG - Requesting: http://10.0.0.141:8082/domain/a797b29a-b743-4bb0-b070-a90ae89983d0
2026-05-11 02:47:20,535 - DEBUG - Requesting: http://10.0.0.141:8082/project/fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:47:20,681 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/b6175711-edb9-4f0e-b326-2fbce5233e8b
2026-05-11 02:47:20,869 - INFO - Detached Policy from ctest-VN1-04725702
2026-05-11 02:47:20,913 - INFO - Deleted policy ctest-policy13-40554556
2026-05-11 02:47:20,957 - INFO - Deleted policy ctest-policy21-45348557
2026-05-11 02:47:20,996 - INFO - Deleted policy ctest-policy12-34303536
2026-05-11 02:47:20,997 - INFO - Deleting VN ctest-VN3-81211999
2026-05-11 02:47:21,209 - DEBUG - Response for deleting network ()
2026-05-11 02:47:21,209 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/7b422dc8-dc21-4c6f-a3c7-5d5f58b92ad6
2026-05-11 02:47:21,229 - DEBUG - Response Code: 404
2026-05-11 02:47:21,229 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:47:21,244 - DEBUG - Requesting: http://10.0.0.141:8082/domain/a797b29a-b743-4bb0-b070-a90ae89983d0
2026-05-11 02:47:21,271 - DEBUG - Requesting: http://10.0.0.141:8082/project/fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:47:21,366 - INFO - Validated that VN ctest-VN3-81211999 is not found in API Server
2026-05-11 02:47:21,378 - DEBUG - VN ctest-VN3-81211999 is not present in Agent 10.0.0.23
2026-05-11 02:47:21,391 - DEBUG - VN ctest-VN3-81211999 is not present in Agent 10.0.0.240
2026-05-11 02:47:21,391 - INFO - Validated that VN ctest-VN3-81211999 is not in any agent
2026-05-11 02:47:21,424 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-81211999 info
2026-05-11 02:47:21,424 - INFO - Deleting VN ctest-VN2-96759136
2026-05-11 02:47:21,662 - DEBUG - Response for deleting network ()
2026-05-11 02:47:21,662 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/9c81a491-90ae-44b0-8b51-711f2d6ff8bf
2026-05-11 02:47:21,668 - DEBUG - Response Code: 404
2026-05-11 02:47:21,668 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:47:21,674 - DEBUG - Requesting: http://10.0.0.141:8082/domain/a797b29a-b743-4bb0-b070-a90ae89983d0
2026-05-11 02:47:21,687 - DEBUG - Requesting: http://10.0.0.141:8082/project/fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:47:21,784 - INFO - Validated that VN ctest-VN2-96759136 is not found in API Server
2026-05-11 02:47:21,796 - DEBUG - VN ctest-VN2-96759136 is not present in Agent 10.0.0.23
2026-05-11 02:47:21,809 - DEBUG - VN ctest-VN2-96759136 is not present in Agent 10.0.0.240
2026-05-11 02:47:21,810 - INFO - Validated that VN ctest-VN2-96759136 is not in any agent
2026-05-11 02:47:21,844 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-96759136 info
2026-05-11 02:47:21,844 - INFO - Deleting VN ctest-VN1-04725702
2026-05-11 02:47:22,066 - DEBUG - Response for deleting network ()
2026-05-11 02:47:22,066 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/8c159a3b-a8e1-47a8-bf2c-13277a2f0d6a
2026-05-11 02:47:22,074 - DEBUG - Response Code: 404
2026-05-11 02:47:22,074 - DEBUG - Requesting: http://10.0.0.141:8082/domains
2026-05-11 02:47:22,080 - DEBUG - Requesting: http://10.0.0.141:8082/domain/a797b29a-b743-4bb0-b070-a90ae89983d0
2026-05-11 02:47:22,093 - DEBUG - Requesting: http://10.0.0.141:8082/project/fed3a479-56fd-4270-b228-4aadf5713ba4
2026-05-11 02:47:22,215 - INFO - Validated that VN ctest-VN1-04725702 is not found in API Server
2026-05-11 02:47:22,231 - DEBUG - VN ctest-VN1-04725702 is not present in Agent 10.0.0.23
2026-05-11 02:47:22,246 - DEBUG - VN ctest-VN1-04725702 is not present in Agent 10.0.0.240
2026-05-11 02:47:22,246 - INFO - Validated that VN ctest-VN1-04725702 is not in any agent
2026-05-11 02:47:22,285 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-04725702 info
2026-05-11 02:47:22,377 - INFO - IPAM: ipam3 is not found in API Server
2026-05-11 02:47:22,399 - INFO - IPAM:ipam3 is not found in control node
2026-05-11 02:47:22,549 - INFO - IPAM: ipam2 is not found in API Server
2026-05-11 02:47:22,569 - INFO - IPAM:ipam2 is not found in control node
2026-05-11 02:47:22,701 - INFO - IPAM: ipam1 is not found in API Server
2026-05-11 02:47:22,723 - INFO - IPAM:ipam1 is not found in control node
2026-05-11 02:47:24,018 - DEBUG - No XMPP flaps were noticed during the test
2026-05-11 02:47:24,018 - INFO - END TEST : test_policy_inheritance_src_vn_dst_pol : PASSED[0:03:11]
2026-05-11 02:47:24,018 - INFO - --------------------------------------------------------------------------------
2026-05-11 02:47:24,793 - INFO - Deleted project: ctest-TestPolicyAcl-61138828, ID : fed3a479-56fd-4270-b228-4aadf5713ba4