2026-03-19 02:50:00,760 - INFO - Domain Default found not creating
2026-03-19 02:50:01,004 - INFO - Project ctest-TestPolicyAcl-40076022 not found, creating it
2026-03-19 02:50:01,609 - INFO - Created Project:ctest-TestPolicyAcl-40076022, ID : 33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:50:03,298 - INFO - ================================================================================
2026-03-19 02:50:03,298 - INFO - STARTING TEST : test_policy_inheritance_src_vn_dst_pol
2026-03-19 02:50:03,298 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance
2026-03-19 02:50:04,564 - DEBUG - Nothing to compare xmpp stats {'10.0.0.23': {'10.20.0.129': '0', '10.20.0.18': '0'}, '10.0.0.240': {'10.20.0.18': '0', '10.20.0.193': '0'}} with
2026-03-19 02:50:04,564 - INFO - Initial checks done. Running the testcase now
2026-03-19 02:50:04,564 - INFO -
2026-03-19 02:50:05,902 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:50:06,218 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:50:06,259 - DEBUG - Requesting: http://10.0.0.41:8082/domain/cb4acbf6-9e94-415a-a3f1-1be95317ba76
2026-03-19 02:50:06,305 - DEBUG - Requesting: http://10.0.0.41:8082/project/33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:50:06,418 - DEBUG - Requesting: http://10.0.0.41:8082/network-ipam/60d65511-e369-4334-a529-abff0b87ea25
2026-03-19 02:50:06,427 - INFO - Verifications in API Server for IPAM: ipam1 passed
2026-03-19 02:50:06,433 - DEBUG - Control-node 10.0.0.41 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-40076022:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6977858207975293748', 'uuid-lslong': '11901232602201713189'}, 'enable': 'true', 'created': '2026-03-19T02:50:04', 'last-modified': '2026-03-19T02:50:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.235263'}
2026-03-19 02:50:06,439 - DEBUG - Control-node 10.0.0.242 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-40076022:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6977858207975293748', 'uuid-lslong': '11901232602201713189'}, 'enable': 'true', 'created': '2026-03-19T02:50:04', 'last-modified': '2026-03-19T02:50:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.241539'}
2026-03-19 02:50:06,444 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-40076022:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6977858207975293748', 'uuid-lslong': '11901232602201713189'}, 'enable': 'true', 'created': '2026-03-19T02:50:04', 'last-modified': '2026-03-19T02:50:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.247077'}
2026-03-19 02:50:06,444 - INFO - Verifications in Control node for IPAM: ipam1 passed
2026-03-19 02:50:06,444 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:50:06,451 - DEBUG - Requesting: http://10.0.0.41:8082/domain/cb4acbf6-9e94-415a-a3f1-1be95317ba76
2026-03-19 02:50:06,462 - DEBUG - Requesting: http://10.0.0.41:8082/project/33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:50:06,559 - DEBUG - Requesting: http://10.0.0.41:8082/network-ipam/2ca84596-7db9-4f7a-9c19-819eda6a3c8f
2026-03-19 02:50:06,567 - INFO - Verifications in API Server for IPAM: ipam2 passed
2026-03-19 02:50:06,575 - DEBUG - Control-node 10.0.0.41 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-40076022:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3217898446412926842', 'uuid-lslong': '11248164063603735695'}, 'enable': 'true', 'created': '2026-03-19T02:50:05', 'last-modified': '2026-03-19T02:50:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.034360'}
2026-03-19 02:50:06,581 - DEBUG - Control-node 10.0.0.242 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-40076022:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3217898446412926842', 'uuid-lslong': '11248164063603735695'}, 'enable': 'true', 'created': '2026-03-19T02:50:05', 'last-modified': '2026-03-19T02:50:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.043976'}
2026-03-19 02:50:06,586 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-40076022:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '3217898446412926842', 'uuid-lslong': '11248164063603735695'}, 'enable': 'true', 'created': '2026-03-19T02:50:05', 'last-modified': '2026-03-19T02:50:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.049096'}
2026-03-19 02:50:06,586 - INFO - Verifications in Control node for IPAM: ipam2 passed
2026-03-19 02:50:06,586 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:50:06,594 - DEBUG - Requesting: http://10.0.0.41:8082/domain/cb4acbf6-9e94-415a-a3f1-1be95317ba76
2026-03-19 02:50:06,609 - DEBUG - Requesting: http://10.0.0.41:8082/project/33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:50:06,721 - DEBUG - Requesting: http://10.0.0.41:8082/network-ipam/9cee40fa-c1f4-4e9c-8c94-c8f01cefac28
2026-03-19 02:50:06,732 - INFO - Verifications in API Server for IPAM: ipam3 passed
2026-03-19 02:50:06,743 - DEBUG - Control-node 10.0.0.41 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-40076022:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11308047160113909404', 'uuid-lslong': '10129942395466263592'}, 'enable': 'true', 'created': '2026-03-19T02:50:05', 'last-modified': '2026-03-19T02:50:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.865689'}
2026-03-19 02:50:06,749 - DEBUG - Control-node 10.0.0.242 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-40076022:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11308047160113909404', 'uuid-lslong': '10129942395466263592'}, 'enable': 'true', 'created': '2026-03-19T02:50:05', 'last-modified': '2026-03-19T02:50:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.874990'}
2026-03-19 02:50:06,754 - DEBUG - Control-node 10.0.0.27 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-40076022:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11308047160113909404', 'uuid-lslong': '10129942395466263592'}, 'enable': 'true', 'created': '2026-03-19T02:50:05', 'last-modified': '2026-03-19T02:50:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.881552'}
2026-03-19 02:50:06,755 - INFO - Verifications in Control node for IPAM: ipam3 passed
2026-03-19 02:50:07,094 - DEBUG - Response for create_network : {'network': {'id': '973228db-25e2-491e-adac-2dc9ec9a391e', 'name': 'ctest-VN1-08136919', 'tenant_id': '33d32817c0004f6c84a49be12ee8ab43', 'project_id': '33d32817c0004f6c84a49be12ee8ab43', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-40076022', 'ctest-VN1-08136919'], 'port_security_enabled': True, 'description': ''}}
2026-03-19 02:50:07,446 - DEBUG - Response for create_subnet : {'subnet': {'id': '0b4bbd16-45f6-4a70-b43f-7439af2ecab7', 'name': '', 'tenant_id': '33d32817c0004f6c84a49be12ee8ab43', 'network_id': '973228db-25e2-491e-adac-2dc9ec9a391e', 'ip_version': 4, 'cidr': '131.156.149.64/26', 'allocation_pools': [{'start': '131.156.149.66', 'end': '131.156.149.126'}], 'gateway_ip': '131.156.149.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '131.156.149.66', 'tags': [], 'project_id': '33d32817c0004f6c84a49be12ee8ab43'}}
2026-03-19 02:50:07,473 - INFO - Created VN ctest-VN1-08136919
2026-03-19 02:50:07,486 - DEBUG - VN ctest-VN1-08136919 UUID is 973228db-25e2-491e-adac-2dc9ec9a391e
2026-03-19 02:50:07,662 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919(973228db-25e2-491e-adac-2dc9ec9a391e) with subnets ['131.156.149.64/26']
2026-03-19 02:50:07,971 - DEBUG - Response for create_network : {'network': {'id': 'f599b983-9d72-42d9-af6f-eb7a10eddafa', 'name': 'ctest-VN2-37764134', 'tenant_id': '33d32817c0004f6c84a49be12ee8ab43', 'project_id': '33d32817c0004f6c84a49be12ee8ab43', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-40076022', 'ctest-VN2-37764134'], 'port_security_enabled': True, 'description': ''}}
2026-03-19 02:50:08,249 - DEBUG - Response for create_subnet : {'subnet': {'id': '8238ac01-9525-4636-8c6c-52807237494f', 'name': '', 'tenant_id': '33d32817c0004f6c84a49be12ee8ab43', 'network_id': 'f599b983-9d72-42d9-af6f-eb7a10eddafa', 'ip_version': 4, 'cidr': '216.208.137.192/26', 'allocation_pools': [{'start': '216.208.137.194', 'end': '216.208.137.254'}], 'gateway_ip': '216.208.137.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '216.208.137.194', 'tags': [], 'project_id': '33d32817c0004f6c84a49be12ee8ab43'}}
2026-03-19 02:50:08,291 - INFO - Created VN ctest-VN2-37764134
2026-03-19 02:50:08,306 - DEBUG - VN ctest-VN2-37764134 UUID is f599b983-9d72-42d9-af6f-eb7a10eddafa
2026-03-19 02:50:08,633 - DEBUG - Response for create_network : {'network': {'id': 'a8a9ce7d-453e-43f7-827d-be9fb84d1263', 'name': 'ctest-VN3-02987025', 'tenant_id': '33d32817c0004f6c84a49be12ee8ab43', 'project_id': '33d32817c0004f6c84a49be12ee8ab43', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-40076022', 'ctest-VN3-02987025'], 'port_security_enabled': True, 'description': ''}}
2026-03-19 02:50:08,902 - DEBUG - Response for create_subnet : {'subnet': {'id': '3004ef29-3569-4de6-98d3-98e6dbeeba49', 'name': '', 'tenant_id': '33d32817c0004f6c84a49be12ee8ab43', 'network_id': 'a8a9ce7d-453e-43f7-827d-be9fb84d1263', 'ip_version': 4, 'cidr': '6.112.58.128/26', 'allocation_pools': [{'start': '6.112.58.130', 'end': '6.112.58.190'}], 'gateway_ip': '6.112.58.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '6.112.58.130', 'tags': [], 'project_id': '33d32817c0004f6c84a49be12ee8ab43'}}
2026-03-19 02:50:08,923 - INFO - Created VN ctest-VN3-02987025
2026-03-19 02:50:08,934 - DEBUG - VN ctest-VN3-02987025 UUID is a8a9ce7d-453e-43f7-827d-be9fb84d1263
2026-03-19 02:50:09,009 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:50:09,016 - DEBUG - Requesting: http://10.0.0.41:8082/domain/cb4acbf6-9e94-415a-a3f1-1be95317ba76
2026-03-19 02:50:09,030 - DEBUG - Requesting: http://10.0.0.41:8082/project/33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:50:09,123 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/973228db-25e2-491e-adac-2dc9ec9a391e
2026-03-19 02:50:09,135 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/973228db-25e2-491e-adac-2dc9ec9a391e
2026-03-19 02:50:09,144 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/b8bf32f6-d25f-4832-8eb2-0950aaca88a3
2026-03-19 02:50:09,154 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/b8bf32f6-d25f-4832-8eb2-0950aaca88a3
2026-03-19 02:50:09,162 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/ab490797-53af-4928-8de8-eb8ec0ab3682
2026-03-19 02:50:09,170 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-19 02:50:09,170 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/973228db-25e2-491e-adac-2dc9ec9a391e
2026-03-19 02:50:09,180 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/b8bf32f6-d25f-4832-8eb2-0950aaca88a3
2026-03-19 02:50:09,196 - INFO - Verified VN network id 14 for VN 973228db-25e2-491e-adac-2dc9ec9a391e
2026-03-19 02:50:09,196 - INFO - Verifications in API Server for VN ctest-VN1-08136919 passed
2026-03-19 02:50:09,197 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/973228db-25e2-491e-adac-2dc9ec9a391e
2026-03-19 02:50:09,212 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/b8bf32f6-d25f-4832-8eb2-0950aaca88a3
2026-03-19 02:50:09,224 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/b8bf32f6-d25f-4832-8eb2-0950aaca88a3
2026-03-19 02:50:09,232 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/ab490797-53af-4928-8de8-eb8ec0ab3682
2026-03-19 02:50:09,246 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10894815370261186846', 'uuid-lslong': '12514427809836972318'}, 'enable': 'true', 'created': '2026-03-19T02:50:07', 'last-modified': '2026-03-19T02:50:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.882800'}
2026-03-19 02:50:09,252 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-19 02:50:09,258 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10894815370261186846', 'uuid-lslong': '12514427809836972318'}, 'enable': 'true', 'created': '2026-03-19T02:50:07', 'last-modified': '2026-03-19T02:50:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.900848'}
2026-03-19 02:50:09,264 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-19 02:50:09,269 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '10894815370261186846', 'uuid-lslong': '12514427809836972318'}, 'enable': 'true', 'created': '2026-03-19T02:50:07', 'last-modified': '2026-03-19T02:50:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.913943'}
2026-03-19 02:50:09,274 - DEBUG - Route Targets: ['target:64512:8000004']
2026-03-19 02:50:09,275 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-08136919 passed
2026-03-19 02:50:09,275 - DEBUG - ====Verifying policy data for ctest-VN1-08136919 in API_Server ======
2026-03-19 02:50:09,275 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:50:09,282 - DEBUG - Requesting: http://10.0.0.41:8082/domain/cb4acbf6-9e94-415a-a3f1-1be95317ba76
2026-03-19 02:50:09,294 - DEBUG - Requesting: http://10.0.0.41:8082/project/33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:50:09,389 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/973228db-25e2-491e-adac-2dc9ec9a391e
2026-03-19 02:50:09,399 - DEBUG - =>VN ctest-VN1-08136919 has no policy to be verified
2026-03-19 02:50:09,399 - DEBUG - Verifying the vn in opserver
2026-03-19 02:50:09,399 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 virtual network link through opserver 10.0.0.41
2026-03-19 02:50:09,399 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-03-19 02:50:09,703 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-03-19 02:50:09,774 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919?flat'}
2026-03-19 02:50:09,774 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 virtual network link through opserver 10.0.0.242
2026-03-19 02:50:09,774 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-03-19 02:50:10,094 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-03-19 02:50:10,472 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919?flat'}
2026-03-19 02:50:10,472 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 virtual network link through opserver 10.0.0.27
2026-03-19 02:50:10,472 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-03-19 02:50:10,786 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-03-19 02:50:11,178 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919?flat'}
2026-03-19 02:50:11,178 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 is found in opserver
2026-03-19 02:50:11,202 - DEBUG - Do not have enough data to verify VN in agent
2026-03-19 02:50:11,213 - DEBUG - VRF ids for VN ctest-VN1-08136919: {}
2026-03-19 02:50:11,213 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:50:11,220 - DEBUG - Requesting: http://10.0.0.41:8082/domain/cb4acbf6-9e94-415a-a3f1-1be95317ba76
2026-03-19 02:50:11,231 - DEBUG - Requesting: http://10.0.0.41:8082/project/33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:50:11,322 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f599b983-9d72-42d9-af6f-eb7a10eddafa
2026-03-19 02:50:11,335 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f599b983-9d72-42d9-af6f-eb7a10eddafa
2026-03-19 02:50:11,344 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/f2783530-971a-4e7f-b179-2b57de9e4fa8
2026-03-19 02:50:11,352 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/f2783530-971a-4e7f-b179-2b57de9e4fa8
2026-03-19 02:50:11,360 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/adddead0-a9da-4179-bb15-63f568c7b174
2026-03-19 02:50:11,369 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-19 02:50:11,369 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f599b983-9d72-42d9-af6f-eb7a10eddafa
2026-03-19 02:50:11,378 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/f2783530-971a-4e7f-b179-2b57de9e4fa8
2026-03-19 02:50:11,394 - INFO - Verified VN network id 15 for VN f599b983-9d72-42d9-af6f-eb7a10eddafa
2026-03-19 02:50:11,394 - INFO - Verifications in API Server for VN ctest-VN2-37764134 passed
2026-03-19 02:50:11,394 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f599b983-9d72-42d9-af6f-eb7a10eddafa
2026-03-19 02:50:11,407 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/f2783530-971a-4e7f-b179-2b57de9e4fa8
2026-03-19 02:50:11,414 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/f2783530-971a-4e7f-b179-2b57de9e4fa8
2026-03-19 02:50:11,423 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/adddead0-a9da-4179-bb15-63f568c7b174
2026-03-19 02:50:11,434 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17697380185662440153', 'uuid-lslong': '12641581588554832634'}, 'enable': 'true', 'created': '2026-03-19T02:50:07', 'last-modified': '2026-03-19T02:50:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.243326'}
2026-03-19 02:50:11,441 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-19 02:50:11,447 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17697380185662440153', 'uuid-lslong': '12641581588554832634'}, 'enable': 'true', 'created': '2026-03-19T02:50:07', 'last-modified': '2026-03-19T02:50:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.256906'}
2026-03-19 02:50:11,453 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-19 02:50:11,459 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17697380185662440153', 'uuid-lslong': '12641581588554832634'}, 'enable': 'true', 'created': '2026-03-19T02:50:07', 'last-modified': '2026-03-19T02:50:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.271860'}
2026-03-19 02:50:11,465 - DEBUG - Route Targets: ['target:64512:8000005']
2026-03-19 02:50:11,465 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-37764134 passed
2026-03-19 02:50:11,465 - DEBUG - ====Verifying policy data for ctest-VN2-37764134 in API_Server ======
2026-03-19 02:50:11,465 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:50:11,472 - DEBUG - Requesting: http://10.0.0.41:8082/domain/cb4acbf6-9e94-415a-a3f1-1be95317ba76
2026-03-19 02:50:11,485 - DEBUG - Requesting: http://10.0.0.41:8082/project/33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:50:11,573 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f599b983-9d72-42d9-af6f-eb7a10eddafa
2026-03-19 02:50:11,584 - DEBUG - =>VN ctest-VN2-37764134 has no policy to be verified
2026-03-19 02:50:11,584 - DEBUG - Verifying the vn in opserver
2026-03-19 02:50:11,584 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134 virtual network link through opserver 10.0.0.41
2026-03-19 02:50:11,584 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-03-19 02:50:11,648 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134?flat'}
2026-03-19 02:50:11,648 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134 virtual network link through opserver 10.0.0.242
2026-03-19 02:50:11,648 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-03-19 02:50:11,711 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134?flat'}
2026-03-19 02:50:11,711 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134 virtual network link through opserver 10.0.0.27
2026-03-19 02:50:11,711 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-03-19 02:50:11,773 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134?flat'}
2026-03-19 02:50:11,773 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134 is found in opserver
2026-03-19 02:50:11,799 - DEBUG - Do not have enough data to verify VN in agent
2026-03-19 02:50:11,810 - DEBUG - VRF ids for VN ctest-VN2-37764134: {}
2026-03-19 02:50:11,810 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:50:11,817 - DEBUG - Requesting: http://10.0.0.41:8082/domain/cb4acbf6-9e94-415a-a3f1-1be95317ba76
2026-03-19 02:50:11,830 - DEBUG - Requesting: http://10.0.0.41:8082/project/33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:50:11,919 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/a8a9ce7d-453e-43f7-827d-be9fb84d1263
2026-03-19 02:50:11,935 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/a8a9ce7d-453e-43f7-827d-be9fb84d1263
2026-03-19 02:50:11,945 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/fb2b8122-128b-452f-80eb-6ce9a1a4966f
2026-03-19 02:50:11,953 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/fb2b8122-128b-452f-80eb-6ce9a1a4966f
2026-03-19 02:50:11,963 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/61614e31-929b-4e50-8e82-62d70323c20d
2026-03-19 02:50:11,970 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-19 02:50:11,970 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/a8a9ce7d-453e-43f7-827d-be9fb84d1263
2026-03-19 02:50:11,980 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/fb2b8122-128b-452f-80eb-6ce9a1a4966f
2026-03-19 02:50:11,997 - INFO - Verified VN network id 16 for VN a8a9ce7d-453e-43f7-827d-be9fb84d1263
2026-03-19 02:50:11,997 - INFO - Verifications in API Server for VN ctest-VN3-02987025 passed
2026-03-19 02:50:11,997 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/a8a9ce7d-453e-43f7-827d-be9fb84d1263
2026-03-19 02:50:12,010 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/fb2b8122-128b-452f-80eb-6ce9a1a4966f
2026-03-19 02:50:12,021 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/fb2b8122-128b-452f-80eb-6ce9a1a4966f
2026-03-19 02:50:12,030 - DEBUG - Requesting: http://10.0.0.41:8082/route-target/61614e31-929b-4e50-8e82-62d70323c20d
2026-03-19 02:50:12,045 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12153472106863936503', 'uuid-lslong': '9402881190220599907'}, 'enable': 'true', 'created': '2026-03-19T02:50:08', 'last-modified': '2026-03-19T02:50:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.184195'}
2026-03-19 02:50:12,051 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-19 02:50:12,057 - DEBUG - Control-node 10.0.0.242 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12153472106863936503', 'uuid-lslong': '9402881190220599907'}, 'enable': 'true', 'created': '2026-03-19T02:50:08', 'last-modified': '2026-03-19T02:50:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.201482'}
2026-03-19 02:50:12,064 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-19 02:50:12,070 - DEBUG - Control-node 10.0.0.27 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-40076022', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12153472106863936503', 'uuid-lslong': '9402881190220599907'}, 'enable': 'true', 'created': '2026-03-19T02:50:08', 'last-modified': '2026-03-19T02:50:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:03.210932'}
2026-03-19 02:50:12,078 - DEBUG - Route Targets: ['target:64512:8000006']
2026-03-19 02:50:12,078 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-02987025 passed
2026-03-19 02:50:12,079 - DEBUG - ====Verifying policy data for ctest-VN3-02987025 in API_Server ======
2026-03-19 02:50:12,079 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:50:12,085 - DEBUG - Requesting: http://10.0.0.41:8082/domain/cb4acbf6-9e94-415a-a3f1-1be95317ba76
2026-03-19 02:50:12,098 - DEBUG - Requesting: http://10.0.0.41:8082/project/33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:50:12,200 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/a8a9ce7d-453e-43f7-827d-be9fb84d1263
2026-03-19 02:50:12,217 - DEBUG - =>VN ctest-VN3-02987025 has no policy to be verified
2026-03-19 02:50:12,217 - DEBUG - Verifying the vn in opserver
2026-03-19 02:50:12,217 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025 virtual network link through opserver 10.0.0.41
2026-03-19 02:50:12,217 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2026-03-19 02:50:12,230 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025?flat'}
2026-03-19 02:50:12,230 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025 virtual network link through opserver 10.0.0.242
2026-03-19 02:50:12,230 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-networks
2026-03-19 02:50:12,295 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025?flat'}
2026-03-19 02:50:12,295 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025 virtual network link through opserver 10.0.0.27
2026-03-19 02:50:12,295 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-networks
2026-03-19 02:50:12,367 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025?flat'}
2026-03-19 02:50:12,367 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025 is found in opserver
2026-03-19 02:50:12,393 - DEBUG - Do not have enough data to verify VN in agent
2026-03-19 02:50:12,404 - DEBUG - VRF ids for VN ctest-VN3-02987025: {}
2026-03-19 02:50:12,410 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919, 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-40076022:ctest-VN2-37764134, 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-03-19 02:50:12,686 - DEBUG - No destination network defined
2026-03-19 02:50:12,686 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134, 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-40076022:ctest-policy13-68666429, 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-03-19 02:50:12,966 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919, 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-40076022:ctest-VN3-02987025, 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-03-19 02:50:13,187 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:50:13,194 - DEBUG - Requesting: http://10.0.0.41:8082/domain/cb4acbf6-9e94-415a-a3f1-1be95317ba76
2026-03-19 02:50:13,208 - DEBUG - Requesting: http://10.0.0.41:8082/project/33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:50:13,320 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/973228db-25e2-491e-adac-2dc9ec9a391e
2026-03-19 02:50:13,331 - DEBUG - Setup step: Associating the policy to VN'
2026-03-19 02:50:13,542 - INFO - Associated Policy to ctest-VN1-08136919
2026-03-19 02:50:13,543 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:50:13,551 - DEBUG - Requesting: http://10.0.0.41:8082/domain/cb4acbf6-9e94-415a-a3f1-1be95317ba76
2026-03-19 02:50:13,566 - DEBUG - Requesting: http://10.0.0.41:8082/project/33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:50:13,658 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f599b983-9d72-42d9-af6f-eb7a10eddafa
2026-03-19 02:50:13,670 - DEBUG - Setup step: Associating the policy to VN'
2026-03-19 02:50:13,773 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': 'f599b983-9d72-42d9-af6f-eb7a10eddafa', 'name': 'ctest-VN2-37764134', 'tenant_id': '33d32817c0004f6c84a49be12ee8ab43', 'project_id': '33d32817c0004f6c84a49be12ee8ab43', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['8238ac01-9525-4636-8c6c-52807237494f'], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-40076022', 'ctest-VN2-37764134'], 'policys': [['default-domain', 'ctest-TestPolicyAcl-40076022', 'ctest-policy21-53403451']], 'subnet_ipam': [{'subnet_cidr': '216.208.137.192/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAcl-40076022', 'ipam2']}], 'port_security_enabled': True, 'description': ''}}
2026-03-19 02:50:13,890 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAcl-40076022', 'ctest-policy21-53403451']] to ctest-VN2-37764134
2026-03-19 02:50:14,001 - DEBUG - Services list from nova: [, , , ]
2026-03-19 02:50:15,165 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5493-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5493-2)
2026-03-19 02:50:16,513 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5493-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5493-1)
2026-03-19 02:50:18,034 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5493-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5493-2)
2026-03-19 02:50:18,136 - DEBUG - VM is in ACTIVE state now
2026-03-19 02:50:18,136 - INFO - VM name : ctest-VM11-36076924
2026-03-19 02:50:18,244 - DEBUG - VM ctest-VM11-36076924 ID is a43f22dd-0760-4226-984e-0dda1501c420
2026-03-19 02:50:18,350 - DEBUG - VM is in ACTIVE state now
2026-03-19 02:50:18,382 - DEBUG - VM ctest-VM11-36076924 launched on Node an-jenkins-deploy-platform-ansible-os-5493-2
2026-03-19 02:50:18,531 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a43f22dd-0760-4226-984e-0dda1501c420
2026-03-19 02:50:18,991 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a43f22dd-0760-4226-984e-0dda1501c420
2026-03-19 02:50:19,038 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a43f22dd-0760-4226-984e-0dda1501c420
2026-03-19 02:50:19,045 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/b5575e58-72ba-4e4b-a59d-0871c3ba552e
2026-03-19 02:50:19,055 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a43f22dd-0760-4226-984e-0dda1501c420
2026-03-19 02:50:19,062 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/b5575e58-72ba-4e4b-a59d-0871c3ba552e
2026-03-19 02:50:19,075 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/58577345-c27b-473d-90fe-65cc7f57a890
2026-03-19 02:50:19,085 - DEBUG - Verifying in api server 10.0.0.27
2026-03-19 02:50:19,086 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a43f22dd-0760-4226-984e-0dda1501c420
2026-03-19 02:50:19,099 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/b5575e58-72ba-4e4b-a59d-0871c3ba552e
2026-03-19 02:50:19,108 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/58577345-c27b-473d-90fe-65cc7f57a890
2026-03-19 02:50:19,116 - INFO - VM ctest-VM11-36076924 verfication in all API Servers passed
2026-03-19 02:50:19,136 - DEBUG - VM ctest-VM11-36076924 Tap interface: {'index': '3', 'name': 'tapb5575e58-72', 'uuid': 'b5575e58-72ba-4e4b-a59d-0871c3ba552e', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919:ctest-VN1-08136919', '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-40076022:ctest-VN1-08136919', 'vm_uuid': 'a43f22dd-0760-4226-984e-0dda1501c420', 'vm_name': 'ctest-VM11-36076924', 'ip_addr': '131.156.149.67', 'mac_addr': '02:b5:57:5e:58:72', '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-40076022:b5575e58-72ba-4e4b-a59d-0871c3ba552e', 'sg_uuid_list': ['04e0b803-e904-4be7-99b3-db64e5796e1b'], 'static_route_list': None, 'vm_project_uuid': '33d32817-c000-4f6c-84a4-9be12ee8ab43', '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-03-19 02:50:19,136 - DEBUG - Agent 10.0.0.240 vrf name: default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919:ctest-VN1-08136919
2026-03-19 02:50:19,143 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919:ctest-VN1-08136919', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.252:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-03-19 02:50:19,223 - DEBUG - VM ctest-VM11-36076924 : Tap interface tapb5575e58-72 is set to Active
2026-03-19 02:50:19,224 - DEBUG - Tap interface tapb5575e58-72 detail : {'index': '3', 'name': 'tapb5575e58-72', 'uuid': 'b5575e58-72ba-4e4b-a59d-0871c3ba552e', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919:ctest-VN1-08136919', '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-40076022:ctest-VN1-08136919', 'vm_uuid': 'a43f22dd-0760-4226-984e-0dda1501c420', 'vm_name': 'ctest-VM11-36076924', 'ip_addr': '131.156.149.67', 'mac_addr': '02:b5:57:5e:58:72', '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-40076022:b5575e58-72ba-4e4b-a59d-0871c3ba552e', 'sg_uuid_list': ['04e0b803-e904-4be7-99b3-db64e5796e1b'], 'static_route_list': None, 'vm_project_uuid': '33d32817-c000-4f6c-84a4-9be12ee8ab43', '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-03-19 02:50:19,330 - DEBUG - Starting Layer 2 verification in Agent
2026-03-19 02:50:19,372 - DEBUG - Layer 2 path is seen for VM MAC 02:b5:57:5e:58:72 in agent 10.0.0.240
2026-03-19 02:50:19,372 - DEBUG - Active layer 2 route in agent is present for VMI tapb5575e58-72
2026-03-19 02:50:19,372 - DEBUG - L2 label(29) matches bw route table and interface table
2026-03-19 02:50:19,407 - DEBUG - VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 is found in Agent of node 10.0.0.240
2026-03-19 02:50:19,530 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 is consistent in agent 10.0.0.240
2026-03-19 02:50:19,531 - DEBUG - Route for VM IP ['131.156.149.67'] is consistent in agent 10.0.0.240
2026-03-19 02:50:19,531 - DEBUG - VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 verification for VM ctest-VM11-36076924 in Agent 10.0.0.240 passed
2026-03-19 02:50:19,643 - DEBUG - Starting all layer 2 verification in agent 10.0.0.240
2026-03-19 02:50:19,657 - DEBUG - Route for VM MAC 02:b5:57:5e:58:72 is consistent in agent 10.0.0.240
2026-03-19 02:50:22,850 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1020ms')
2026-03-19 02:50:22,850 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-36076924 failed!
2026-03-19 02:50:22,865 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 is 131.156.149.65 and allocation pool is NOT set
2026-03-19 02:50:26,944 - 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-03-19 02:50:26,944 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-36076924 failed!
2026-03-19 02:50:26,961 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 is 131.156.149.65 and allocation pool is NOT set
2026-03-19 02:50:31,039 - 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-03-19 02:50:31,039 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-36076924 failed!
2026-03-19 02:50:31,052 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 is 131.156.149.65 and allocation pool is NOT set
2026-03-19 02:50:35,139 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms')
2026-03-19 02:50:35,139 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-36076924 failed!
2026-03-19 02:50:35,153 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 is 131.156.149.65 and allocation pool is NOT set
2026-03-19 02:50:39,237 - 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-03-19 02:50:39,238 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-36076924 failed!
2026-03-19 02:50:39,255 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 is 131.156.149.65 and allocation pool is NOT set
2026-03-19 02:50:43,338 - 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-03-19 02:50:43,338 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-36076924 failed!
2026-03-19 02:50:43,353 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 is 131.156.149.65 and allocation pool is NOT set
2026-03-19 02:50:47,431 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms')
2026-03-19 02:50:47,431 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-36076924 failed!
2026-03-19 02:50:47,445 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 is 131.156.149.65 and allocation pool is NOT set
2026-03-19 02:50:51,527 - 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-03-19 02:50:51,527 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-36076924 failed!
2026-03-19 02:50:51,542 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 is 131.156.149.65 and allocation pool is NOT set
2026-03-19 02:50:55,616 - 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-03-19 02:50:55,616 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-36076924 failed!
2026-03-19 02:50:55,629 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 is 131.156.149.65 and allocation pool is NOT set
2026-03-19 02:50:59,713 - 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-03-19 02:50:59,713 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-36076924 failed!
2026-03-19 02:50:59,726 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 is 131.156.149.65 and allocation pool is NOT set
2026-03-19 02:51:03,816 - 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-03-19 02:51:03,816 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-36076924 failed!
2026-03-19 02:51:03,830 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 is 131.156.149.65 and allocation pool is NOT set
2026-03-19 02:51:05,898 - 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=9.13 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.90 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.899/5.512/9.126/3.613 ms')
2026-03-19 02:51:05,899 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-36076924 passed
2026-03-19 02:51:05,899 - INFO - VM ctest-VM11-36076924 verifications in Compute nodes passed
2026-03-19 02:51:06,170 - DEBUG - Validated VM route 131.156.149.67 in vrouter of 10.0.0.240
2026-03-19 02:51:06,178 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-03-19 02:51:06,178 - INFO - Validated routes of VM ctest-VM11-36076924 in all vrouters
2026-03-19 02:51:06,603 - DEBUG - Starting all layer2 verification in 10.0.0.242 Control Node
2026-03-19 02:51:06,725 - DEBUG - Layer2 route found for VM MAC 02:b5:57:5e:58:72 in Control-node 10.0.0.242
2026-03-19 02:51:06,725 - DEBUG - L2 Label for VM ctest-VM11-36076924 same between Control-node 10.0.0.242 and Agent, Expected: 29, Seen: 29
2026-03-19 02:51:06,725 - DEBUG - Starting all layer2 verification in 10.0.0.41 Control Node
2026-03-19 02:51:06,851 - DEBUG - Layer2 route found for VM MAC 02:b5:57:5e:58:72 in Control-node 10.0.0.41
2026-03-19 02:51:06,851 - DEBUG - L2 Label for VM ctest-VM11-36076924 same between Control-node 10.0.0.41 and Agent, Expected: 29, Seen: 29
2026-03-19 02:51:06,851 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node
2026-03-19 02:51:06,977 - DEBUG - Layer2 route found for VM MAC 02:b5:57:5e:58:72 in Control-node 10.0.0.27
2026-03-19 02:51:06,977 - DEBUG - L2 Label for VM ctest-VM11-36076924 same between Control-node 10.0.0.27 and Agent, Expected: 29, Seen: 29
2026-03-19 02:51:06,977 - INFO - Verification in Control-nodes for VM ctest-VM11-36076924 passed
2026-03-19 02:51:06,977 - DEBUG - Verifying the vm in opserver
2026-03-19 02:51:06,978 - DEBUG - Verifying in collector 10.0.0.27 ...
2026-03-19 02:51:06,978 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/a43f22dd-0760-4226-984e-0dda1501c420?flat
2026-03-19 02:51:06,991 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-40076022:b5575e58-72ba-4e4b-a59d-0871c3ba552e?flat
2026-03-19 02:51:07,076 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-40076022:b5575e58-72ba-4e4b-a59d-0871c3ba552e?flat
2026-03-19 02:51:07,094 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/a43f22dd-0760-4226-984e-0dda1501c420?flat
2026-03-19 02:51:07,108 - DEBUG - Verifying vm in vn uve
2026-03-19 02:51:07,108 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-40076022:b5575e58-72ba-4e4b-a59d-0871c3ba552e?flat
2026-03-19 02:51:07,132 - DEBUG - VM uve shows interface as {'__T': 1773888641214690, 'rx_vlan': 65535, 'uuid': 'b5575e58-72ba-4e4b-a59d-0871c3ba552e', 'label': 25, 'fixed_ip4_list': ['131.156.149.67'], 'ip6_active': False, 'active': True, 'ip6_address': '::', 'tx_vlan': 65535, 'gateway': '131.156.149.65', 'vn_uuid': '973228db-25e2-491e-adac-2dc9ec9a391e', 'port_mirror_enabled': False, 'virtual_network': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919', 'l2_active': True, 'ip_address': '131.156.149.67', 'mac_address': '02:b5:57:5e:58:72', 'is_health_check_active': True, 'vhostuser_mode': 0, 'ip4_active': True, 'admin_state': True, 'vm_uuid': 'a43f22dd-0760-4226-984e-0dda1501c420', 'vm_name': 'ctest-VM11-36076924'}
2026-03-19 02:51:07,132 - DEBUG - VM uve shows ip address as ['131.156.149.67', '::']
2026-03-19 02:51:07,132 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919
2026-03-19 02:51:07,132 - INFO - Verifying through opserver in 10.0.0.41
2026-03-19 02:51:07,132 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919?flat
2026-03-19 02:51:07,155 - DEBUG - expected vm list a43f22dd-0760-4226-984e-0dda1501c420
2026-03-19 02:51:07,155 - DEBUG - Extracted vm list ['a43f22dd-0760-4226-984e-0dda1501c420']
2026-03-19 02:51:07,155 - INFO - VM a43f22dd-0760-4226-984e-0dda1501c420 is present in default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919
2026-03-19 02:51:07,155 - DEBUG - Verifying vm in vrouter uve
2026-03-19 02:51:07,155 - DEBUG - Getting info from collector 10.0.0.27..
2026-03-19 02:51:07,155 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/a43f22dd-0760-4226-984e-0dda1501c420?flat
2026-03-19 02:51:07,172 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5493-2']
2026-03-19 02:51:07,172 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:51:07,213 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:51:07,241 - DEBUG - VM a43f22dd-0760-4226-984e-0dda1501c420 is present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:51:07,241 - DEBUG - Validated that VM ctest-VM11-36076924 is in Vrouter an-jenkins-deploy-platform-ansible-os-5493-2 UVE
2026-03-19 02:51:07,241 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-03-19 02:51:07,250 - DEBUG - Expected tap interface of VM uuid a43f22dd-0760-4226-984e-0dda1501c420 is default-domain:ctest-TestPolicyAcl-40076022:b5575e58-72ba-4e4b-a59d-0871c3ba552e
2026-03-19 02:51:07,250 - DEBUG - Expected VN of VM uuid a43f22dd-0760-4226-984e-0dda1501c420 is default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919
2026-03-19 02:51:07,250 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:51:07,290 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:51:07,325 - DEBUG - VM a43f22dd-0760-4226-984e-0dda1501c420 is present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:51:07,325 - INFO - tap interface default-domain:ctest-TestPolicyAcl-40076022:b5575e58-72ba-4e4b-a59d-0871c3ba552e of vm a43f22dd-0760-4226-984e-0dda1501c420 is present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:51:07,326 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 of vm a43f22dd-0760-4226-984e-0dda1501c420 is present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:51:07,326 - DEBUG - Verifying the a43f22dd-0760-4226-984e-0dda1501c420 virtual network link through opserver 10.0.0.41
2026-03-19 02:51:07,326 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines
2026-03-19 02:51:07,339 - DEBUG - VM link and name as {'name': 'a43f22dd-0760-4226-984e-0dda1501c420', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-machine/a43f22dd-0760-4226-984e-0dda1501c420?flat'}
2026-03-19 02:51:07,339 - DEBUG - Verifying the a43f22dd-0760-4226-984e-0dda1501c420 virtual network link through opserver 10.0.0.242
2026-03-19 02:51:07,339 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machines
2026-03-19 02:51:07,354 - DEBUG - VM link and name as {'name': 'a43f22dd-0760-4226-984e-0dda1501c420', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-machine/a43f22dd-0760-4226-984e-0dda1501c420?flat'}
2026-03-19 02:51:07,354 - DEBUG - Verifying the a43f22dd-0760-4226-984e-0dda1501c420 virtual network link through opserver 10.0.0.27
2026-03-19 02:51:07,354 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-03-19 02:51:07,368 - DEBUG - VM link and name as {'name': 'a43f22dd-0760-4226-984e-0dda1501c420', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/a43f22dd-0760-4226-984e-0dda1501c420?flat'}
2026-03-19 02:51:07,368 - INFO - VM ctest-VM11-36076924 validations in Opserver passed
2026-03-19 02:51:07,465 - DEBUG - VM is in ACTIVE state now
2026-03-19 02:51:07,465 - INFO - VM name : ctest-VM21-27800099
2026-03-19 02:51:07,556 - DEBUG - VM ctest-VM21-27800099 ID is a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20
2026-03-19 02:51:07,653 - DEBUG - VM is in ACTIVE state now
2026-03-19 02:51:07,653 - DEBUG - VM ctest-VM21-27800099 launched on Node an-jenkins-deploy-platform-ansible-os-5493-1
2026-03-19 02:51:07,771 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20
2026-03-19 02:51:07,782 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20
2026-03-19 02:51:07,792 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/d7f980c1-3538-4a34-aef6-072a19468654
2026-03-19 02:51:07,801 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20
2026-03-19 02:51:07,808 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/d7f980c1-3538-4a34-aef6-072a19468654
2026-03-19 02:51:07,819 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/0a5b0e9f-dde4-4963-96c0-0a004cc8e36e
2026-03-19 02:51:07,829 - DEBUG - Verifying in api server 10.0.0.27
2026-03-19 02:51:07,829 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20
2026-03-19 02:51:07,838 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/d7f980c1-3538-4a34-aef6-072a19468654
2026-03-19 02:51:07,849 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/0a5b0e9f-dde4-4963-96c0-0a004cc8e36e
2026-03-19 02:51:07,859 - INFO - VM ctest-VM21-27800099 verfication in all API Servers passed
2026-03-19 02:51:07,879 - DEBUG - VM ctest-VM21-27800099 Tap interface: {'index': '3', 'name': 'tapd7f980c1-35', 'uuid': 'd7f980c1-3538-4a34-aef6-072a19468654', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134:ctest-VN2-37764134', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134', 'vm_uuid': 'a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20', 'vm_name': 'ctest-VM21-27800099', 'ip_addr': '216.208.137.195', 'mac_addr': '02:d7:f9:80:c1:35', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '42', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-40076022:d7f980c1-3538-4a34-aef6-072a19468654', 'sg_uuid_list': ['04e0b803-e904-4be7-99b3-db64e5796e1b'], 'static_route_list': None, 'vm_project_uuid': '33d32817-c000-4f6c-84a4-9be12ee8ab43', '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-03-19 02:51:07,879 - DEBUG - Agent 10.0.0.23 vrf name: default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134:ctest-VN2-37764134
2026-03-19 02:51:07,884 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134:ctest-VN2-37764134', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134', 'table_label': '-1', 'vxlan_id': '15', '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-03-19 02:51:07,976 - DEBUG - VM ctest-VM21-27800099 : Tap interface tapd7f980c1-35 is set to Active
2026-03-19 02:51:07,976 - DEBUG - Tap interface tapd7f980c1-35 detail : {'index': '3', 'name': 'tapd7f980c1-35', 'uuid': 'd7f980c1-3538-4a34-aef6-072a19468654', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134:ctest-VN2-37764134', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134', 'vm_uuid': 'a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20', 'vm_name': 'ctest-VM21-27800099', 'ip_addr': '216.208.137.195', 'mac_addr': '02:d7:f9:80:c1:35', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.3', 'service_vlan_list': None, 'os_ifindex': '42', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestPolicyAcl-40076022:d7f980c1-3538-4a34-aef6-072a19468654', 'sg_uuid_list': ['04e0b803-e904-4be7-99b3-db64e5796e1b'], 'static_route_list': None, 'vm_project_uuid': '33d32817-c000-4f6c-84a4-9be12ee8ab43', '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-03-19 02:51:08,089 - DEBUG - Starting Layer 2 verification in Agent
2026-03-19 02:51:08,102 - DEBUG - Layer 2 path is seen for VM MAC 02:d7:f9:80:c1:35 in agent 10.0.0.23
2026-03-19 02:51:08,102 - DEBUG - Active layer 2 route in agent is present for VMI tapd7f980c1-35
2026-03-19 02:51:08,102 - DEBUG - L2 label(29) matches bw route table and interface table
2026-03-19 02:51:08,114 - DEBUG - VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134 is found in Agent of node 10.0.0.23
2026-03-19 02:51:08,260 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134 is consistent in agent 10.0.0.23
2026-03-19 02:51:08,260 - DEBUG - Route for VM IP ['216.208.137.195'] is consistent in agent 10.0.0.23
2026-03-19 02:51:08,260 - DEBUG - VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134 verification for VM ctest-VM21-27800099 in Agent 10.0.0.23 passed
2026-03-19 02:51:08,374 - DEBUG - Starting all layer 2 verification in agent 10.0.0.23
2026-03-19 02:51:08,387 - DEBUG - Route for VM MAC 02:d7:f9:80:c1:35 is consistent in agent 10.0.0.23
2026-03-19 02:51:09,566 - 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=5.61 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.00 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.996/4.300/5.605/1.304 ms')
2026-03-19 02:51:09,566 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-27800099 passed
2026-03-19 02:51:09,566 - INFO - VM ctest-VM21-27800099 verifications in Compute nodes passed
2026-03-19 02:51:09,793 - DEBUG - Validated VM route 216.208.137.195 in vrouter of 10.0.0.23
2026-03-19 02:51:09,800 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-03-19 02:51:09,800 - INFO - Validated routes of VM ctest-VM21-27800099 in all vrouters
2026-03-19 02:51:10,212 - DEBUG - Starting all layer2 verification in 10.0.0.242 Control Node
2026-03-19 02:51:10,334 - DEBUG - Layer2 route found for VM MAC 02:d7:f9:80:c1:35 in Control-node 10.0.0.242
2026-03-19 02:51:10,334 - DEBUG - L2 Label for VM ctest-VM21-27800099 same between Control-node 10.0.0.242 and Agent, Expected: 29, Seen: 29
2026-03-19 02:51:10,334 - DEBUG - Starting all layer2 verification in 10.0.0.41 Control Node
2026-03-19 02:51:10,459 - DEBUG - Layer2 route found for VM MAC 02:d7:f9:80:c1:35 in Control-node 10.0.0.41
2026-03-19 02:51:10,459 - DEBUG - L2 Label for VM ctest-VM21-27800099 same between Control-node 10.0.0.41 and Agent, Expected: 29, Seen: 29
2026-03-19 02:51:10,460 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node
2026-03-19 02:51:10,580 - DEBUG - Layer2 route found for VM MAC 02:d7:f9:80:c1:35 in Control-node 10.0.0.27
2026-03-19 02:51:10,580 - DEBUG - L2 Label for VM ctest-VM21-27800099 same between Control-node 10.0.0.27 and Agent, Expected: 29, Seen: 29
2026-03-19 02:51:10,580 - INFO - Verification in Control-nodes for VM ctest-VM21-27800099 passed
2026-03-19 02:51:10,580 - DEBUG - Verifying the vm in opserver
2026-03-19 02:51:10,580 - DEBUG - Verifying in collector 10.0.0.27 ...
2026-03-19 02:51:10,580 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20?flat
2026-03-19 02:51:10,595 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-40076022:d7f980c1-3538-4a34-aef6-072a19468654?flat
2026-03-19 02:51:10,613 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-40076022:d7f980c1-3538-4a34-aef6-072a19468654?flat
2026-03-19 02:51:10,633 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20?flat
2026-03-19 02:51:10,650 - DEBUG - Verifying vm in vn uve
2026-03-19 02:51:10,650 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-40076022:d7f980c1-3538-4a34-aef6-072a19468654?flat
2026-03-19 02:51:10,670 - DEBUG - VM uve shows interface as {'__T': 1773888642441071, 'rx_vlan': 65535, 'uuid': 'd7f980c1-3538-4a34-aef6-072a19468654', 'label': 25, 'fixed_ip4_list': ['216.208.137.195'], 'ip6_active': False, 'active': True, 'ip6_address': '::', 'tx_vlan': 65535, 'gateway': '216.208.137.193', 'vn_uuid': 'f599b983-9d72-42d9-af6f-eb7a10eddafa', 'port_mirror_enabled': False, 'virtual_network': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134', 'l2_active': True, 'ip_address': '216.208.137.195', 'mac_address': '02:d7:f9:80:c1:35', 'is_health_check_active': True, 'vhostuser_mode': 0, 'ip4_active': True, 'admin_state': True, 'vm_uuid': 'a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20', 'vm_name': 'ctest-VM21-27800099'}
2026-03-19 02:51:10,670 - DEBUG - VM uve shows ip address as ['216.208.137.195', '::']
2026-03-19 02:51:10,670 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134
2026-03-19 02:51:10,670 - INFO - Verifying through opserver in 10.0.0.41
2026-03-19 02:51:10,671 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134?flat
2026-03-19 02:51:10,696 - DEBUG - expected vm list a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20
2026-03-19 02:51:10,696 - DEBUG - Extracted vm list ['a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20']
2026-03-19 02:51:10,696 - INFO - VM a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is present in default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134
2026-03-19 02:51:10,696 - DEBUG - Verifying vm in vrouter uve
2026-03-19 02:51:10,696 - DEBUG - Getting info from collector 10.0.0.27..
2026-03-19 02:51:10,696 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20?flat
2026-03-19 02:51:10,712 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5493-1']
2026-03-19 02:51:10,712 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-1:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:51:10,747 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-1?flat
2026-03-19 02:51:10,775 - DEBUG - VM a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is present in vrouter an-jenkins-deploy-platform-ansible-os-5493-1 uve
2026-03-19 02:51:10,775 - DEBUG - Validated that VM ctest-VM21-27800099 is in Vrouter an-jenkins-deploy-platform-ansible-os-5493-1 UVE
2026-03-19 02:51:10,775 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-03-19 02:51:10,783 - DEBUG - Expected tap interface of VM uuid a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is default-domain:ctest-TestPolicyAcl-40076022:d7f980c1-3538-4a34-aef6-072a19468654
2026-03-19 02:51:10,783 - DEBUG - Expected VN of VM uuid a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134
2026-03-19 02:51:10,783 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-1:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:51:10,825 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-1?flat
2026-03-19 02:51:10,854 - DEBUG - VM a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is present in vrouter an-jenkins-deploy-platform-ansible-os-5493-1 uve
2026-03-19 02:51:10,854 - INFO - tap interface default-domain:ctest-TestPolicyAcl-40076022:d7f980c1-3538-4a34-aef6-072a19468654 of vm a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is present in vrouter an-jenkins-deploy-platform-ansible-os-5493-1 uve
2026-03-19 02:51:10,854 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134 of vm a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is present in vrouter an-jenkins-deploy-platform-ansible-os-5493-1 uve
2026-03-19 02:51:10,855 - DEBUG - Verifying the a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 virtual network link through opserver 10.0.0.41
2026-03-19 02:51:10,855 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines
2026-03-19 02:51:10,867 - DEBUG - VM link and name as {'name': 'a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-machine/a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20?flat'}
2026-03-19 02:51:10,867 - DEBUG - Verifying the a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 virtual network link through opserver 10.0.0.242
2026-03-19 02:51:10,867 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machines
2026-03-19 02:51:10,886 - DEBUG - VM link and name as {'name': 'a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-machine/a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20?flat'}
2026-03-19 02:51:10,886 - DEBUG - Verifying the a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 virtual network link through opserver 10.0.0.27
2026-03-19 02:51:10,886 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-03-19 02:51:10,899 - DEBUG - VM link and name as {'name': 'a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20?flat'}
2026-03-19 02:51:10,899 - INFO - VM ctest-VM21-27800099 validations in Opserver passed
2026-03-19 02:51:10,988 - DEBUG - VM is in ACTIVE state now
2026-03-19 02:51:10,988 - INFO - VM name : ctest-VM31-43112959
2026-03-19 02:51:11,079 - DEBUG - VM ctest-VM31-43112959 ID is 0b120d06-3a6f-4f49-84f7-e14273b10d67
2026-03-19 02:51:11,165 - DEBUG - VM is in ACTIVE state now
2026-03-19 02:51:11,165 - DEBUG - VM ctest-VM31-43112959 launched on Node an-jenkins-deploy-platform-ansible-os-5493-2
2026-03-19 02:51:11,268 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b120d06-3a6f-4f49-84f7-e14273b10d67
2026-03-19 02:51:11,278 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b120d06-3a6f-4f49-84f7-e14273b10d67
2026-03-19 02:51:11,287 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/b886f6a8-390e-4a48-b7bd-c37f38946d43
2026-03-19 02:51:11,299 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b120d06-3a6f-4f49-84f7-e14273b10d67
2026-03-19 02:51:11,310 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/b886f6a8-390e-4a48-b7bd-c37f38946d43
2026-03-19 02:51:11,323 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/34ed5f8f-c9c9-4cda-bfc8-dbff580bf193
2026-03-19 02:51:11,340 - DEBUG - Verifying in api server 10.0.0.27
2026-03-19 02:51:11,340 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b120d06-3a6f-4f49-84f7-e14273b10d67
2026-03-19 02:51:11,355 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/b886f6a8-390e-4a48-b7bd-c37f38946d43
2026-03-19 02:51:11,367 - DEBUG - Requesting: http://10.0.0.27:8082/instance-ip/34ed5f8f-c9c9-4cda-bfc8-dbff580bf193
2026-03-19 02:51:11,375 - INFO - VM ctest-VM31-43112959 verfication in all API Servers passed
2026-03-19 02:51:11,395 - DEBUG - VM ctest-VM31-43112959 Tap interface: {'index': '4', 'name': 'tapb886f6a8-39', 'uuid': 'b886f6a8-390e-4a48-b7bd-c37f38946d43', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025:ctest-VN3-02987025', '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': '16', 'vn_name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025', 'vm_uuid': '0b120d06-3a6f-4f49-84f7-e14273b10d67', 'vm_name': 'ctest-VM31-43112959', 'ip_addr': '6.112.58.131', 'mac_addr': '02:b8:86:f6:a8:39', '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-40076022:b886f6a8-390e-4a48-b7bd-c37f38946d43', 'sg_uuid_list': ['04e0b803-e904-4be7-99b3-db64e5796e1b'], 'static_route_list': None, 'vm_project_uuid': '33d32817-c000-4f6c-84a4-9be12ee8ab43', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-03-19 02:51:11,395 - DEBUG - Agent 10.0.0.240 vrf name: default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025:ctest-VN3-02987025
2026-03-19 02:51:11,408 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025:ctest-VN3-02987025', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025', 'table_label': '-1', 'vxlan_id': '16', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.252:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-03-19 02:51:11,491 - DEBUG - VM ctest-VM31-43112959 : Tap interface tapb886f6a8-39 is set to Active
2026-03-19 02:51:11,491 - DEBUG - Tap interface tapb886f6a8-39 detail : {'index': '4', 'name': 'tapb886f6a8-39', 'uuid': 'b886f6a8-390e-4a48-b7bd-c37f38946d43', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025:ctest-VN3-02987025', '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': '16', 'vn_name': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025', 'vm_uuid': '0b120d06-3a6f-4f49-84f7-e14273b10d67', 'vm_name': 'ctest-VM31-43112959', 'ip_addr': '6.112.58.131', 'mac_addr': '02:b8:86:f6:a8:39', '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-40076022:b886f6a8-390e-4a48-b7bd-c37f38946d43', 'sg_uuid_list': ['04e0b803-e904-4be7-99b3-db64e5796e1b'], 'static_route_list': None, 'vm_project_uuid': '33d32817-c000-4f6c-84a4-9be12ee8ab43', 'admin_state': 'Enabled', 'flow_key_idx': '41', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2026-03-19 02:51:11,606 - DEBUG - Starting Layer 2 verification in Agent
2026-03-19 02:51:11,618 - DEBUG - Layer 2 path is seen for VM MAC 02:b8:86:f6:a8:39 in agent 10.0.0.240
2026-03-19 02:51:11,619 - DEBUG - Active layer 2 route in agent is present for VMI tapb886f6a8-39
2026-03-19 02:51:11,619 - DEBUG - L2 label(36) matches bw route table and interface table
2026-03-19 02:51:11,631 - DEBUG - VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025 is found in Agent of node 10.0.0.240
2026-03-19 02:51:11,758 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025 is consistent in agent 10.0.0.240
2026-03-19 02:51:11,758 - DEBUG - Route for VM IP ['6.112.58.131'] is consistent in agent 10.0.0.240
2026-03-19 02:51:11,758 - DEBUG - VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025 verification for VM ctest-VM31-43112959 in Agent 10.0.0.240 passed
2026-03-19 02:51:11,875 - DEBUG - Starting all layer 2 verification in agent 10.0.0.240
2026-03-19 02:51:11,884 - DEBUG - Route for VM MAC 02:b8:86:f6:a8:39 is consistent in agent 10.0.0.240
2026-03-19 02:51:13,050 - 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.21 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.691 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.691/1.950/3.210/1.259 ms')
2026-03-19 02:51:13,050 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-43112959 passed
2026-03-19 02:51:13,050 - INFO - VM ctest-VM31-43112959 verifications in Compute nodes passed
2026-03-19 02:51:13,276 - DEBUG - Validated VM route 6.112.58.131 in vrouter of 10.0.0.240
2026-03-19 02:51:13,282 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2026-03-19 02:51:13,282 - INFO - Validated routes of VM ctest-VM31-43112959 in all vrouters
2026-03-19 02:51:13,781 - DEBUG - Starting all layer2 verification in 10.0.0.41 Control Node
2026-03-19 02:51:13,912 - DEBUG - Layer2 route found for VM MAC 02:b8:86:f6:a8:39 in Control-node 10.0.0.41
2026-03-19 02:51:13,912 - DEBUG - L2 Label for VM ctest-VM31-43112959 same between Control-node 10.0.0.41 and Agent, Expected: 36, Seen: 36
2026-03-19 02:51:13,912 - DEBUG - Starting all layer2 verification in 10.0.0.27 Control Node
2026-03-19 02:51:14,032 - DEBUG - Layer2 route found for VM MAC 02:b8:86:f6:a8:39 in Control-node 10.0.0.27
2026-03-19 02:51:14,032 - DEBUG - L2 Label for VM ctest-VM31-43112959 same between Control-node 10.0.0.27 and Agent, Expected: 36, Seen: 36
2026-03-19 02:51:14,032 - INFO - Verification in Control-nodes for VM ctest-VM31-43112959 passed
2026-03-19 02:51:14,033 - DEBUG - Verifying the vm in opserver
2026-03-19 02:51:14,033 - DEBUG - Verifying in collector 10.0.0.27 ...
2026-03-19 02:51:14,033 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/0b120d06-3a6f-4f49-84f7-e14273b10d67?flat
2026-03-19 02:51:14,051 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-40076022:b886f6a8-390e-4a48-b7bd-c37f38946d43?flat
2026-03-19 02:51:14,077 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-40076022:b886f6a8-390e-4a48-b7bd-c37f38946d43?flat
2026-03-19 02:51:14,095 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/0b120d06-3a6f-4f49-84f7-e14273b10d67?flat
2026-03-19 02:51:14,112 - DEBUG - Verifying vm in vn uve
2026-03-19 02:51:14,112 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-40076022:b886f6a8-390e-4a48-b7bd-c37f38946d43?flat
2026-03-19 02:51:14,133 - DEBUG - VM uve shows interface as {'__T': 1773888641214714, 'rx_vlan': 65535, 'uuid': 'b886f6a8-390e-4a48-b7bd-c37f38946d43', 'label': 32, 'fixed_ip4_list': ['6.112.58.131'], 'ip6_active': False, 'active': True, 'ip6_address': '::', 'tx_vlan': 65535, 'gateway': '6.112.58.129', 'vn_uuid': 'a8a9ce7d-453e-43f7-827d-be9fb84d1263', 'port_mirror_enabled': False, 'virtual_network': 'default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025', 'l2_active': True, 'ip_address': '6.112.58.131', 'mac_address': '02:b8:86:f6:a8:39', 'is_health_check_active': True, 'vhostuser_mode': 0, 'ip4_active': True, 'admin_state': True, 'vm_uuid': '0b120d06-3a6f-4f49-84f7-e14273b10d67', 'vm_name': 'ctest-VM31-43112959'}
2026-03-19 02:51:14,133 - DEBUG - VM uve shows ip address as ['6.112.58.131', '::']
2026-03-19 02:51:14,133 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025
2026-03-19 02:51:14,133 - INFO - Verifying through opserver in 10.0.0.41
2026-03-19 02:51:14,134 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025?flat
2026-03-19 02:51:14,163 - DEBUG - expected vm list 0b120d06-3a6f-4f49-84f7-e14273b10d67
2026-03-19 02:51:14,163 - DEBUG - Extracted vm list ['0b120d06-3a6f-4f49-84f7-e14273b10d67']
2026-03-19 02:51:14,163 - INFO - VM 0b120d06-3a6f-4f49-84f7-e14273b10d67 is present in default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025
2026-03-19 02:51:14,164 - DEBUG - Verifying vm in vrouter uve
2026-03-19 02:51:14,164 - DEBUG - Getting info from collector 10.0.0.27..
2026-03-19 02:51:14,164 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machine/0b120d06-3a6f-4f49-84f7-e14273b10d67?flat
2026-03-19 02:51:14,180 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-5493-2']
2026-03-19 02:51:14,180 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:51:14,219 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:51:14,252 - DEBUG - VM 0b120d06-3a6f-4f49-84f7-e14273b10d67 is present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:51:14,252 - DEBUG - Validated that VM ctest-VM31-43112959 is in Vrouter an-jenkins-deploy-platform-ansible-os-5493-2 UVE
2026-03-19 02:51:14,252 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2026-03-19 02:51:14,260 - DEBUG - Expected tap interface of VM uuid 0b120d06-3a6f-4f49-84f7-e14273b10d67 is default-domain:ctest-TestPolicyAcl-40076022:b886f6a8-390e-4a48-b7bd-c37f38946d43
2026-03-19 02:51:14,260 - DEBUG - Expected VN of VM uuid 0b120d06-3a6f-4f49-84f7-e14273b10d67 is default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025
2026-03-19 02:51:14,260 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:51:14,300 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:51:14,330 - DEBUG - VM 0b120d06-3a6f-4f49-84f7-e14273b10d67 is present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:51:14,330 - INFO - tap interface default-domain:ctest-TestPolicyAcl-40076022:b886f6a8-390e-4a48-b7bd-c37f38946d43 of vm 0b120d06-3a6f-4f49-84f7-e14273b10d67 is present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:51:14,330 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025 of vm 0b120d06-3a6f-4f49-84f7-e14273b10d67 is present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:51:14,330 - DEBUG - Verifying the 0b120d06-3a6f-4f49-84f7-e14273b10d67 virtual network link through opserver 10.0.0.41
2026-03-19 02:51:14,331 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines
2026-03-19 02:51:14,348 - DEBUG - VM link and name as {'name': '0b120d06-3a6f-4f49-84f7-e14273b10d67', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-machine/0b120d06-3a6f-4f49-84f7-e14273b10d67?flat'}
2026-03-19 02:51:14,348 - DEBUG - Verifying the 0b120d06-3a6f-4f49-84f7-e14273b10d67 virtual network link through opserver 10.0.0.242
2026-03-19 02:51:14,348 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machines
2026-03-19 02:51:14,363 - DEBUG - VM link and name as {'name': '0b120d06-3a6f-4f49-84f7-e14273b10d67', 'href': 'http://10.0.0.242:8081/analytics/uves/virtual-machine/0b120d06-3a6f-4f49-84f7-e14273b10d67?flat'}
2026-03-19 02:51:14,363 - DEBUG - Verifying the 0b120d06-3a6f-4f49-84f7-e14273b10d67 virtual network link through opserver 10.0.0.27
2026-03-19 02:51:14,363 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-03-19 02:51:14,375 - DEBUG - VM link and name as {'name': '0b120d06-3a6f-4f49-84f7-e14273b10d67', 'href': 'http://10.0.0.27:8081/analytics/uves/virtual-machine/0b120d06-3a6f-4f49-84f7-e14273b10d67?flat'}
2026-03-19 02:51:14,375 - INFO - VM ctest-VM31-43112959 validations in Opserver passed
2026-03-19 02:51:14,375 - INFO - Waiting for VM ctest-VM11-36076924 to be up..
2026-03-19 02:51:14,376 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-19 02:51:14,376 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a43f22dd-0760-4226-984e-0dda1501c420
2026-03-19 02:51:14,385 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/b5575e58-72ba-4e4b-a59d-0871c3ba552e
2026-03-19 02:51:15,550 - 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=13.1 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=4.66 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 = 4.661/8.878/13.096/4.217 ms')
2026-03-19 02:51:15,550 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-36076924 passed
2026-03-19 02:51:15,623 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-19 02:51:15,623 - DEBUG - Waiting to SSH to VM ctest-VM11-36076924, IP 131.156.149.67, Port 22
2026-03-19 02:51:15,690 - 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-03-19 02:51:15,781 - DEBUG - VM ctest-VM11-36076924 is NOT ready for SSH connections, VM status: ACTIVE
2026-03-19 02:51:20,781 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-19 02:51:20,781 - DEBUG - Waiting to SSH to VM ctest-VM11-36076924, IP 131.156.149.67, Port 22
2026-03-19 02:51:20,840 - 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-03-19 02:51:20,930 - DEBUG - VM ctest-VM11-36076924 is NOT ready for SSH connections, VM status: ACTIVE
2026-03-19 02:51:25,931 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-19 02:51:25,931 - DEBUG - Waiting to SSH to VM ctest-VM11-36076924, IP 131.156.149.67, Port 22
2026-03-19 02:51:25,998 - 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-03-19 02:51:26,118 - DEBUG - VM ctest-VM11-36076924 is NOT ready for SSH connections, VM status: ACTIVE
2026-03-19 02:51:31,119 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-19 02:51:31,120 - DEBUG - Waiting to SSH to VM ctest-VM11-36076924, IP 131.156.149.67, Port 22
2026-03-19 02:51:31,275 - DEBUG - VM ctest-VM11-36076924 is ready for SSH connections
2026-03-19 02:51:31,276 - INFO - Waiting for VM ctest-VM21-27800099 to be up..
2026-03-19 02:51:31,276 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-19 02:51:31,276 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20
2026-03-19 02:51:31,287 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/d7f980c1-3538-4a34-aef6-072a19468654
2026-03-19 02:51:32,452 - 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.79 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.508 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.508/1.651/2.794/1.143 ms')
2026-03-19 02:51:32,452 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-27800099 passed
2026-03-19 02:51:32,523 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-19 02:51:32,523 - DEBUG - Waiting to SSH to VM ctest-VM21-27800099, IP 216.208.137.195, Port 22
2026-03-19 02:51:32,693 - DEBUG - VM ctest-VM21-27800099 is ready for SSH connections
2026-03-19 02:51:32,693 - INFO - Waiting for VM ctest-VM31-43112959 to be up..
2026-03-19 02:51:32,693 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-19 02:51:32,694 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b120d06-3a6f-4f49-84f7-e14273b10d67
2026-03-19 02:51:32,702 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/b886f6a8-390e-4a48-b7bd-c37f38946d43
2026-03-19 02:51:33,884 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.36 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.490 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.490/1.427/2.364/0.937 ms')
2026-03-19 02:51:33,884 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-43112959 passed
2026-03-19 02:51:33,951 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-03-19 02:51:33,951 - DEBUG - Waiting to SSH to VM ctest-VM31-43112959, IP 6.112.58.131, Port 22
2026-03-19 02:51:34,118 - DEBUG - VM ctest-VM31-43112959 is ready for SSH connections
2026-03-19 02:51:34,118 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 216.208.137.195, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-03-19 02:51:34,118 - DEBUG - ping -s 56 -c 3 -W 1 216.208.137.195
2026-03-19 02:51:54,551 - DEBUG - PING 216.208.137.195 (216.208.137.195) 56(84) bytes of data.
64 bytes from 216.208.137.195: icmp_req=1 ttl=63 time=8.91 ms
64 bytes from 216.208.137.195: icmp_req=2 ttl=63 time=1.27 ms
64 bytes from 216.208.137.195: icmp_req=3 ttl=63 time=1.56 ms
--- 216.208.137.195 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 1.270/3.918/8.916/3.536 ms
2026-03-19 02:51:54,551 - INFO - Ping to IP 216.208.137.195 from VM ctest-VM11-36076924 passed
2026-03-19 02:51:54,552 - INFO - Test with src as VN and dst as policy PASSED
2026-03-19 02:51:54,552 - INFO - Deleting VM ctest-VM31-43112959
2026-03-19 02:51:54,620 - DEBUG - Verifying in api server 10.0.0.27
2026-03-19 02:51:54,620 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b120d06-3a6f-4f49-84f7-e14273b10d67
2026-03-19 02:51:54,629 - DEBUG - VM ID 0b120d06-3a6f-4f49-84f7-e14273b10d67 of VM ctest-VM31-43112959 is still found in API Server
2026-03-19 02:51:56,629 - DEBUG - Verifying in api server 10.0.0.27
2026-03-19 02:51:56,629 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b120d06-3a6f-4f49-84f7-e14273b10d67
2026-03-19 02:51:56,636 - DEBUG - Response Code: 404
2026-03-19 02:51:56,636 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b120d06-3a6f-4f49-84f7-e14273b10d67
2026-03-19 02:51:56,641 - DEBUG - Response Code: 404
2026-03-19 02:51:56,641 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/0b120d06-3a6f-4f49-84f7-e14273b10d67
2026-03-19 02:51:56,646 - DEBUG - Response Code: 404
2026-03-19 02:51:56,647 - INFO - VM ctest-VM31-43112959 is fully removed in API-Server
2026-03-19 02:51:56,798 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-03-19 02:51:56,836 - DEBUG - Validated that vrouter 10.0.0.23 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025
2026-03-19 02:51:56,846 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025
2026-03-19 02:51:56,846 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025
2026-03-19 02:51:56,846 - INFO - VM ctest-VM31-43112959 is removed in Compute, and routes are removed in all compute nodes
2026-03-19 02:51:57,076 - INFO - Routes for VM ctest-VM31-43112959 is removed in all control-nodes
2026-03-19 02:51:57,076 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:51:57,110 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:51:57,138 - DEBUG - VM 0b120d06-3a6f-4f49-84f7-e14273b10d67 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:51:57,138 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:51:57,139 - DEBUG - interface for vm 0b120d06-3a6f-4f49-84f7-e14273b10d67 deleted from vrouter uve ...
2026-03-19 02:51:57,139 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:00,139 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:00,176 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:52:00,206 - DEBUG - VM 0b120d06-3a6f-4f49-84f7-e14273b10d67 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:52:00,206 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:00,206 - DEBUG - interface for vm 0b120d06-3a6f-4f49-84f7-e14273b10d67 deleted from vrouter uve ...
2026-03-19 02:52:00,206 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:03,207 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:03,244 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:52:03,275 - DEBUG - VM 0b120d06-3a6f-4f49-84f7-e14273b10d67 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:52:03,275 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:03,275 - DEBUG - interface for vm 0b120d06-3a6f-4f49-84f7-e14273b10d67 deleted from vrouter uve ...
2026-03-19 02:52:03,275 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:06,275 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:06,309 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:52:06,343 - DEBUG - VM 0b120d06-3a6f-4f49-84f7-e14273b10d67 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:52:06,343 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:06,343 - DEBUG - interface for vm 0b120d06-3a6f-4f49-84f7-e14273b10d67 deleted from vrouter uve ...
2026-03-19 02:52:06,343 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:09,344 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:09,390 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:52:09,420 - DEBUG - VM 0b120d06-3a6f-4f49-84f7-e14273b10d67 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:52:09,420 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:09,420 - DEBUG - interface for vm 0b120d06-3a6f-4f49-84f7-e14273b10d67 deleted from vrouter uve ...
2026-03-19 02:52:09,420 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:12,421 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:12,458 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:52:12,498 - DEBUG - VM 0b120d06-3a6f-4f49-84f7-e14273b10d67 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:52:12,498 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:12,498 - DEBUG - interface for vm 0b120d06-3a6f-4f49-84f7-e14273b10d67 deleted from vrouter uve ...
2026-03-19 02:52:12,498 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:12,498 - DEBUG - Validated that VM 0b120d06-3a6f-4f49-84f7-e14273b10d67 is removed in Vrouter UVE
2026-03-19 02:52:12,499 - DEBUG - Verifying through opserver in 10.0.0.41
2026-03-19 02:52:12,499 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025?flat
2026-03-19 02:52:12,519 - DEBUG - 0b120d06-3a6f-4f49-84f7-e14273b10d67 BM not in default-domain:ctest-TestPolicyAcl-40076022:ctest-VN3-02987025 uve
2026-03-19 02:52:12,519 - DEBUG - Validated that VM 0b120d06-3a6f-4f49-84f7-e14273b10d67 is not present in the VN UVE
2026-03-19 02:52:12,519 - DEBUG - Verifying the 0b120d06-3a6f-4f49-84f7-e14273b10d67 virtual network link through opserver 10.0.0.41
2026-03-19 02:52:12,519 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines
2026-03-19 02:52:12,535 - INFO - 0b120d06-3a6f-4f49-84f7-e14273b10d67 vm uve deleted from opserver
2026-03-19 02:52:12,535 - DEBUG - Verifying the 0b120d06-3a6f-4f49-84f7-e14273b10d67 virtual network link through opserver 10.0.0.242
2026-03-19 02:52:12,535 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machines
2026-03-19 02:52:12,549 - INFO - 0b120d06-3a6f-4f49-84f7-e14273b10d67 vm uve deleted from opserver
2026-03-19 02:52:12,549 - DEBUG - Verifying the 0b120d06-3a6f-4f49-84f7-e14273b10d67 virtual network link through opserver 10.0.0.27
2026-03-19 02:52:12,549 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-03-19 02:52:12,562 - INFO - 0b120d06-3a6f-4f49-84f7-e14273b10d67 vm uve deleted from opserver
2026-03-19 02:52:12,562 - INFO - Validated that VM 0b120d06-3a6f-4f49-84f7-e14273b10d67 is removed from Opserver
2026-03-19 02:52:12,562 - INFO - Deleting VM ctest-VM21-27800099
2026-03-19 02:52:12,649 - DEBUG - Verifying in api server 10.0.0.27
2026-03-19 02:52:12,649 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20
2026-03-19 02:52:12,657 - DEBUG - VM ID a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 of VM ctest-VM21-27800099 is still found in API Server
2026-03-19 02:52:14,657 - DEBUG - Verifying in api server 10.0.0.27
2026-03-19 02:52:14,657 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20
2026-03-19 02:52:14,664 - DEBUG - Response Code: 404
2026-03-19 02:52:14,664 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20
2026-03-19 02:52:14,670 - DEBUG - Response Code: 404
2026-03-19 02:52:14,671 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20
2026-03-19 02:52:14,676 - DEBUG - Response Code: 404
2026-03-19 02:52:14,676 - INFO - VM ctest-VM21-27800099 is fully removed in API-Server
2026-03-19 02:52:14,841 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-03-19 02:52:14,880 - DEBUG - Validated that vrouter 10.0.0.23 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134
2026-03-19 02:52:14,890 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134
2026-03-19 02:52:14,890 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134
2026-03-19 02:52:14,891 - INFO - VM ctest-VM21-27800099 is removed in Compute, and routes are removed in all compute nodes
2026-03-19 02:52:15,095 - INFO - Routes for VM ctest-VM21-27800099 is removed in all control-nodes
2026-03-19 02:52:15,095 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-1:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:15,139 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-1?flat
2026-03-19 02:52:15,169 - DEBUG - VM a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-1 uve
2026-03-19 02:52:15,169 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:15,169 - DEBUG - interface for vm a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 deleted from vrouter uve ...
2026-03-19 02:52:15,169 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:18,170 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-1:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:18,214 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-1?flat
2026-03-19 02:52:18,248 - DEBUG - VM a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-1 uve
2026-03-19 02:52:18,248 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:18,248 - DEBUG - interface for vm a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 deleted from vrouter uve ...
2026-03-19 02:52:18,248 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:21,250 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-1:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:21,286 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-1?flat
2026-03-19 02:52:21,317 - DEBUG - VM a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-1 uve
2026-03-19 02:52:21,318 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:21,318 - DEBUG - interface for vm a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 deleted from vrouter uve ...
2026-03-19 02:52:21,318 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:24,318 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-1:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:24,355 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-1?flat
2026-03-19 02:52:24,386 - DEBUG - VM a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-1 uve
2026-03-19 02:52:24,386 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:24,387 - DEBUG - interface for vm a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 deleted from vrouter uve ...
2026-03-19 02:52:24,387 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:27,387 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-1:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:27,439 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-1?flat
2026-03-19 02:52:27,476 - DEBUG - VM a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-1 uve
2026-03-19 02:52:27,476 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:27,477 - DEBUG - interface for vm a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 deleted from vrouter uve ...
2026-03-19 02:52:27,477 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:30,477 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-1:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:30,515 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-1?flat
2026-03-19 02:52:30,542 - DEBUG - VM a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-1 uve
2026-03-19 02:52:30,542 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:30,542 - DEBUG - interface for vm a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 deleted from vrouter uve ...
2026-03-19 02:52:30,542 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:33,543 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-1:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:33,576 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-1?flat
2026-03-19 02:52:33,606 - DEBUG - VM a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-1 uve
2026-03-19 02:52:33,606 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:33,606 - DEBUG - interface for vm a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 deleted from vrouter uve ...
2026-03-19 02:52:33,606 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:36,607 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-1:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:36,642 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-1?flat
2026-03-19 02:52:36,671 - DEBUG - VM a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-1 uve
2026-03-19 02:52:36,671 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:36,671 - DEBUG - interface for vm a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 deleted from vrouter uve ...
2026-03-19 02:52:36,671 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:39,672 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-1:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:39,708 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-1?flat
2026-03-19 02:52:39,739 - DEBUG - VM a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-1 uve
2026-03-19 02:52:39,739 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:39,739 - DEBUG - interface for vm a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 deleted from vrouter uve ...
2026-03-19 02:52:39,739 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:42,740 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-1:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:42,783 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-1?flat
2026-03-19 02:52:42,814 - DEBUG - VM a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5493-1 uve
2026-03-19 02:52:42,814 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:42,814 - DEBUG - interface for vm a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 deleted from vrouter uve ...
2026-03-19 02:52:42,814 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:42,814 - DEBUG - Validated that VM a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is removed in Vrouter UVE
2026-03-19 02:52:42,814 - DEBUG - Verifying through opserver in 10.0.0.41
2026-03-19 02:52:42,814 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134?flat
2026-03-19 02:52:42,835 - DEBUG - a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 BM not in default-domain:ctest-TestPolicyAcl-40076022:ctest-VN2-37764134 uve
2026-03-19 02:52:42,835 - DEBUG - Validated that VM a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is not present in the VN UVE
2026-03-19 02:52:42,835 - DEBUG - Verifying the a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 virtual network link through opserver 10.0.0.41
2026-03-19 02:52:42,835 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines
2026-03-19 02:52:42,849 - INFO - a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 vm uve deleted from opserver
2026-03-19 02:52:42,849 - DEBUG - Verifying the a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 virtual network link through opserver 10.0.0.242
2026-03-19 02:52:42,849 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machines
2026-03-19 02:52:42,862 - INFO - a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 vm uve deleted from opserver
2026-03-19 02:52:42,862 - DEBUG - Verifying the a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 virtual network link through opserver 10.0.0.27
2026-03-19 02:52:42,862 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-03-19 02:52:42,875 - INFO - a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 vm uve deleted from opserver
2026-03-19 02:52:42,875 - INFO - Validated that VM a42bb7c7-dc68-47f5-b8f8-7d8e0cb76a20 is removed from Opserver
2026-03-19 02:52:42,875 - INFO - Deleting VM ctest-VM11-36076924
2026-03-19 02:52:42,951 - DEBUG - Verifying in api server 10.0.0.27
2026-03-19 02:52:42,952 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a43f22dd-0760-4226-984e-0dda1501c420
2026-03-19 02:52:42,960 - DEBUG - VM ID a43f22dd-0760-4226-984e-0dda1501c420 of VM ctest-VM11-36076924 is still found in API Server
2026-03-19 02:52:44,961 - DEBUG - Verifying in api server 10.0.0.27
2026-03-19 02:52:44,962 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a43f22dd-0760-4226-984e-0dda1501c420
2026-03-19 02:52:44,968 - DEBUG - Response Code: 404
2026-03-19 02:52:44,969 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a43f22dd-0760-4226-984e-0dda1501c420
2026-03-19 02:52:44,974 - DEBUG - Response Code: 404
2026-03-19 02:52:44,975 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/a43f22dd-0760-4226-984e-0dda1501c420
2026-03-19 02:52:44,981 - DEBUG - Response Code: 404
2026-03-19 02:52:44,981 - INFO - VM ctest-VM11-36076924 is fully removed in API-Server
2026-03-19 02:52:45,153 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2026-03-19 02:52:45,192 - DEBUG - Validated that vrouter 10.0.0.23 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919
2026-03-19 02:52:45,203 - DEBUG - Validated that vrouter 10.0.0.240 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919
2026-03-19 02:52:45,203 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919
2026-03-19 02:52:45,203 - INFO - VM ctest-VM11-36076924 is removed in Compute, and routes are removed in all compute nodes
2026-03-19 02:52:45,393 - INFO - Routes for VM ctest-VM11-36076924 is removed in all control-nodes
2026-03-19 02:52:45,393 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:45,435 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:52:45,464 - DEBUG - VM a43f22dd-0760-4226-984e-0dda1501c420 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:52:45,464 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:45,464 - DEBUG - interface for vm a43f22dd-0760-4226-984e-0dda1501c420 deleted from vrouter uve ...
2026-03-19 02:52:45,464 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:48,465 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:48,502 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:52:48,535 - DEBUG - VM a43f22dd-0760-4226-984e-0dda1501c420 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:52:48,535 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:48,535 - DEBUG - interface for vm a43f22dd-0760-4226-984e-0dda1501c420 deleted from vrouter uve ...
2026-03-19 02:52:48,535 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:51,536 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:51,567 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:52:51,595 - DEBUG - VM a43f22dd-0760-4226-984e-0dda1501c420 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:52:51,595 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:51,595 - DEBUG - interface for vm a43f22dd-0760-4226-984e-0dda1501c420 deleted from vrouter uve ...
2026-03-19 02:52:51,595 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:54,596 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:54,646 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:52:54,686 - DEBUG - VM a43f22dd-0760-4226-984e-0dda1501c420 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:52:54,686 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:54,686 - DEBUG - interface for vm a43f22dd-0760-4226-984e-0dda1501c420 deleted from vrouter uve ...
2026-03-19 02:52:54,686 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:52:57,687 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:52:57,723 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:52:57,758 - DEBUG - VM a43f22dd-0760-4226-984e-0dda1501c420 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:52:57,758 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:52:57,758 - DEBUG - interface for vm a43f22dd-0760-4226-984e-0dda1501c420 deleted from vrouter uve ...
2026-03-19 02:52:57,758 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:53:00,760 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:53:00,809 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:53:00,841 - DEBUG - VM a43f22dd-0760-4226-984e-0dda1501c420 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:53:00,841 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:53:00,841 - DEBUG - interface for vm a43f22dd-0760-4226-984e-0dda1501c420 deleted from vrouter uve ...
2026-03-19 02:53:00,842 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:53:03,842 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:53:03,877 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:53:03,905 - DEBUG - VM a43f22dd-0760-4226-984e-0dda1501c420 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:53:03,905 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:53:03,906 - DEBUG - interface for vm a43f22dd-0760-4226-984e-0dda1501c420 deleted from vrouter uve ...
2026-03-19 02:53:03,906 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:53:06,906 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:53:06,943 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:53:06,971 - DEBUG - VM a43f22dd-0760-4226-984e-0dda1501c420 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:53:06,971 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:53:06,971 - DEBUG - interface for vm a43f22dd-0760-4226-984e-0dda1501c420 deleted from vrouter uve ...
2026-03-19 02:53:06,971 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:53:09,972 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:53:10,007 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:53:10,036 - DEBUG - VM a43f22dd-0760-4226-984e-0dda1501c420 is still present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:53:10,036 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:53:10,036 - DEBUG - interface for vm a43f22dd-0760-4226-984e-0dda1501c420 deleted from vrouter uve ...
2026-03-19 02:53:10,036 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:53:13,036 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-5493-2:Compute:contrail-vrouter-agent:0?flat
2026-03-19 02:53:13,076 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-5493-2?flat
2026-03-19 02:53:13,109 - DEBUG - VM a43f22dd-0760-4226-984e-0dda1501c420 is not present in vrouter an-jenkins-deploy-platform-ansible-os-5493-2 uve
2026-03-19 02:53:13,109 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2026-03-19 02:53:13,109 - DEBUG - interface for vm a43f22dd-0760-4226-984e-0dda1501c420 deleted from vrouter uve ...
2026-03-19 02:53:13,109 - DEBUG - Deleted interface not in error interface list ...
2026-03-19 02:53:13,109 - DEBUG - Validated that VM a43f22dd-0760-4226-984e-0dda1501c420 is removed in Vrouter UVE
2026-03-19 02:53:13,109 - DEBUG - Verifying through opserver in 10.0.0.41
2026-03-19 02:53:13,109 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919?flat
2026-03-19 02:53:13,129 - DEBUG - a43f22dd-0760-4226-984e-0dda1501c420 BM not in default-domain:ctest-TestPolicyAcl-40076022:ctest-VN1-08136919 uve
2026-03-19 02:53:13,129 - DEBUG - Validated that VM a43f22dd-0760-4226-984e-0dda1501c420 is not present in the VN UVE
2026-03-19 02:53:13,129 - DEBUG - Verifying the a43f22dd-0760-4226-984e-0dda1501c420 virtual network link through opserver 10.0.0.41
2026-03-19 02:53:13,129 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines
2026-03-19 02:53:13,146 - DEBUG - No links retuned for a43f22dd-0760-4226-984e-0dda1501c420
2026-03-19 02:53:13,146 - INFO - a43f22dd-0760-4226-984e-0dda1501c420 vm uve deleted from opserver
2026-03-19 02:53:13,146 - DEBUG - Verifying the a43f22dd-0760-4226-984e-0dda1501c420 virtual network link through opserver 10.0.0.242
2026-03-19 02:53:13,146 - DEBUG - Requesting: http://10.0.0.242:8081/analytics/uves/virtual-machines
2026-03-19 02:53:13,163 - DEBUG - No links retuned for a43f22dd-0760-4226-984e-0dda1501c420
2026-03-19 02:53:13,163 - INFO - a43f22dd-0760-4226-984e-0dda1501c420 vm uve deleted from opserver
2026-03-19 02:53:13,163 - DEBUG - Verifying the a43f22dd-0760-4226-984e-0dda1501c420 virtual network link through opserver 10.0.0.27
2026-03-19 02:53:13,163 - DEBUG - Requesting: http://10.0.0.27:8081/analytics/uves/virtual-machines
2026-03-19 02:53:13,177 - DEBUG - No links retuned for a43f22dd-0760-4226-984e-0dda1501c420
2026-03-19 02:53:13,177 - INFO - a43f22dd-0760-4226-984e-0dda1501c420 vm uve deleted from opserver
2026-03-19 02:53:13,177 - INFO - Validated that VM a43f22dd-0760-4226-984e-0dda1501c420 is removed from Opserver
2026-03-19 02:53:13,178 - DEBUG - Detaching the Policy for VN :ctest-VN2-37764134
2026-03-19 02:53:13,178 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:53:13,188 - DEBUG - Requesting: http://10.0.0.41:8082/domain/cb4acbf6-9e94-415a-a3f1-1be95317ba76
2026-03-19 02:53:13,205 - DEBUG - Requesting: http://10.0.0.41:8082/project/33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:53:13,317 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/f599b983-9d72-42d9-af6f-eb7a10eddafa
2026-03-19 02:53:13,332 - DEBUG - Requesting: http://10.0.0.41:8082/network-policy/18bd932a-3253-47d3-bcbe-8958d9bb463a
2026-03-19 02:53:13,503 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAcl-40076022', 'ctest-policy21-53403451']] from ctest-VN2-37764134
2026-03-19 02:53:13,503 - DEBUG - Detaching the Policy for VN :ctest-VN1-08136919
2026-03-19 02:53:13,503 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:53:13,510 - DEBUG - Requesting: http://10.0.0.41:8082/domain/cb4acbf6-9e94-415a-a3f1-1be95317ba76
2026-03-19 02:53:13,521 - DEBUG - Requesting: http://10.0.0.41:8082/project/33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:53:13,626 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-network/973228db-25e2-491e-adac-2dc9ec9a391e
2026-03-19 02:53:13,790 - INFO - Detached Policy from ctest-VN1-08136919
2026-03-19 02:53:13,845 - INFO - Deleted policy ctest-policy13-68666429
2026-03-19 02:53:13,887 - INFO - Deleted policy ctest-policy21-53403451
2026-03-19 02:53:13,948 - INFO - Deleted policy ctest-policy12-20371825
2026-03-19 02:53:13,948 - INFO - Deleting VN ctest-VN3-02987025
2026-03-19 02:53:14,112 - DEBUG - Response for deleting network ()
2026-03-19 02:53:14,112 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/fb2b8122-128b-452f-80eb-6ce9a1a4966f
2026-03-19 02:53:14,118 - DEBUG - Response Code: 404
2026-03-19 02:53:14,118 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:53:14,125 - DEBUG - Requesting: http://10.0.0.41:8082/domain/cb4acbf6-9e94-415a-a3f1-1be95317ba76
2026-03-19 02:53:14,136 - DEBUG - Requesting: http://10.0.0.41:8082/project/33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:53:14,240 - INFO - Validated that VN ctest-VN3-02987025 is not found in API Server
2026-03-19 02:53:14,251 - DEBUG - VN ctest-VN3-02987025 is not present in Agent 10.0.0.23
2026-03-19 02:53:14,261 - DEBUG - VN ctest-VN3-02987025 is not present in Agent 10.0.0.240
2026-03-19 02:53:14,261 - INFO - Validated that VN ctest-VN3-02987025 is not in any agent
2026-03-19 02:53:14,291 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-02987025 info
2026-03-19 02:53:14,291 - INFO - Deleting VN ctest-VN2-37764134
2026-03-19 02:53:14,518 - DEBUG - Response for deleting network ()
2026-03-19 02:53:14,518 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/f2783530-971a-4e7f-b179-2b57de9e4fa8
2026-03-19 02:53:14,526 - DEBUG - Response Code: 404
2026-03-19 02:53:14,527 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:53:14,536 - DEBUG - Requesting: http://10.0.0.41:8082/domain/cb4acbf6-9e94-415a-a3f1-1be95317ba76
2026-03-19 02:53:14,548 - DEBUG - Requesting: http://10.0.0.41:8082/project/33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:53:14,647 - INFO - Validated that VN ctest-VN2-37764134 is not found in API Server
2026-03-19 02:53:14,659 - DEBUG - VN ctest-VN2-37764134 is not present in Agent 10.0.0.23
2026-03-19 02:53:14,671 - DEBUG - VN ctest-VN2-37764134 is not present in Agent 10.0.0.240
2026-03-19 02:53:14,671 - INFO - Validated that VN ctest-VN2-37764134 is not in any agent
2026-03-19 02:53:14,703 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-37764134 info
2026-03-19 02:53:14,704 - INFO - Deleting VN ctest-VN1-08136919
2026-03-19 02:53:14,915 - DEBUG - Response for deleting network ()
2026-03-19 02:53:14,915 - DEBUG - Requesting: http://10.0.0.41:8082/routing-instance/b8bf32f6-d25f-4832-8eb2-0950aaca88a3
2026-03-19 02:53:14,921 - DEBUG - Response Code: 404
2026-03-19 02:53:14,921 - DEBUG - Requesting: http://10.0.0.41:8082/domains
2026-03-19 02:53:14,942 - DEBUG - Requesting: http://10.0.0.41:8082/domain/cb4acbf6-9e94-415a-a3f1-1be95317ba76
2026-03-19 02:53:15,000 - DEBUG - Requesting: http://10.0.0.41:8082/project/33d32817-c000-4f6c-84a4-9be12ee8ab43
2026-03-19 02:53:15,092 - INFO - Validated that VN ctest-VN1-08136919 is not found in API Server
2026-03-19 02:53:15,102 - DEBUG - VN ctest-VN1-08136919 is not present in Agent 10.0.0.23
2026-03-19 02:53:15,113 - DEBUG - VN ctest-VN1-08136919 is not present in Agent 10.0.0.240
2026-03-19 02:53:15,113 - INFO - Validated that VN ctest-VN1-08136919 is not in any agent
2026-03-19 02:53:15,146 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-08136919 info
2026-03-19 02:53:15,238 - INFO - IPAM: ipam3 is not found in API Server
2026-03-19 02:53:15,261 - INFO - IPAM:ipam3 is not found in control node
2026-03-19 02:53:15,398 - INFO - IPAM: ipam2 is not found in API Server
2026-03-19 02:53:15,421 - INFO - IPAM:ipam2 is not found in control node
2026-03-19 02:53:15,566 - INFO - IPAM: ipam1 is not found in API Server
2026-03-19 02:53:15,589 - INFO - IPAM:ipam1 is not found in control node
2026-03-19 02:53:16,880 - DEBUG - No XMPP flaps were noticed during the test
2026-03-19 02:53:16,880 - INFO - END TEST : test_policy_inheritance_src_vn_dst_pol : PASSED[0:03:13]
2026-03-19 02:53:16,880 - INFO - --------------------------------------------------------------------------------
2026-03-19 02:53:17,663 - INFO - Deleted project: ctest-TestPolicyAcl-40076022, ID : 33d32817-c000-4f6c-84a4-9be12ee8ab43