2025-10-09 02:28:30,559 - INFO - Domain Default found not creating
2025-10-09 02:28:30,719 - INFO - Project ctest-TestPolicyAcl-01175662 not found, creating it
2025-10-09 02:28:31,276 - INFO - Created Project:ctest-TestPolicyAcl-01175662, ID : 0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:28:32,724 - INFO - ================================================================================
2025-10-09 02:28:32,724 - INFO - STARTING TEST : test_policy_inheritance_src_vn_dst_pol
2025-10-09 02:28:32,724 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance
2025-10-09 02:28:33,991 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.25': '0', '10.20.0.14': '0'}, '10.0.0.27': {'10.20.0.25': '0', '10.20.0.14': '0'}} with
2025-10-09 02:28:33,991 - INFO - Initial checks done. Running the testcase now
2025-10-09 02:28:33,991 - INFO -
2025-10-09 02:28:35,377 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:28:35,672 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:28:35,703 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 02:28:35,721 - DEBUG - Requesting: http://10.0.0.56:8082/project/0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:28:35,846 - DEBUG - Requesting: http://10.0.0.56:8082/network-ipam/c54142d2-b3eb-4e3a-9741-64485275ceac
2025-10-09 02:28:35,853 - INFO - Verifications in API Server for IPAM: ipam1 passed
2025-10-09 02:28:35,860 - DEBUG - Control-node 10.0.0.56 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-01175662:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14213715371687104058', 'uuid-lslong': '10899102834997186220'}, 'enable': 'true', 'created': '2025-10-09T02:28:34', 'last-modified': '2025-10-09T02:28:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.239051'}
2025-10-09 02:28:35,865 - DEBUG - Control-node 10.0.0.19 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-01175662:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14213715371687104058', 'uuid-lslong': '10899102834997186220'}, 'enable': 'true', 'created': '2025-10-09T02:28:34', 'last-modified': '2025-10-09T02:28:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.243462'}
2025-10-09 02:28:35,870 - DEBUG - Control-node 10.0.0.41 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-01175662:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14213715371687104058', 'uuid-lslong': '10899102834997186220'}, 'enable': 'true', 'created': '2025-10-09T02:28:34', 'last-modified': '2025-10-09T02:28:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.250209'}
2025-10-09 02:28:35,870 - INFO - Verifications in Control node for IPAM: ipam1 passed
2025-10-09 02:28:35,870 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:28:35,877 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 02:28:35,898 - DEBUG - Requesting: http://10.0.0.56:8082/project/0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:28:36,004 - DEBUG - Requesting: http://10.0.0.56:8082/network-ipam/6e1a92c9-1bf4-4600-9db9-10bd173a0808
2025-10-09 02:28:36,011 - INFO - Verifications in API Server for IPAM: ipam2 passed
2025-10-09 02:28:36,016 - DEBUG - Control-node 10.0.0.56 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-01175662:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7933815086021625344', 'uuid-lslong': '11365133538970699784'}, 'enable': 'true', 'created': '2025-10-09T02:28:34', 'last-modified': '2025-10-09T02:28:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.028984'}
2025-10-09 02:28:36,023 - DEBUG - Control-node 10.0.0.19 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-01175662:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7933815086021625344', 'uuid-lslong': '11365133538970699784'}, 'enable': 'true', 'created': '2025-10-09T02:28:34', 'last-modified': '2025-10-09T02:28:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.034199'}
2025-10-09 02:28:36,028 - DEBUG - Control-node 10.0.0.41 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-01175662:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7933815086021625344', 'uuid-lslong': '11365133538970699784'}, 'enable': 'true', 'created': '2025-10-09T02:28:34', 'last-modified': '2025-10-09T02:28:34', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.040573'}
2025-10-09 02:28:36,028 - INFO - Verifications in Control node for IPAM: ipam2 passed
2025-10-09 02:28:36,028 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:28:36,034 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 02:28:36,044 - DEBUG - Requesting: http://10.0.0.56:8082/project/0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:28:36,153 - DEBUG - Requesting: http://10.0.0.56:8082/network-ipam/817cd003-9038-4f29-91b2-375d1d4b293c
2025-10-09 02:28:36,160 - INFO - Verifications in API Server for IPAM: ipam3 passed
2025-10-09 02:28:36,165 - DEBUG - Control-node 10.0.0.56 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-01175662:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9330561241727913769', 'uuid-lslong': '10498514554416998716'}, 'enable': 'true', 'created': '2025-10-09T02:28:35', 'last-modified': '2025-10-09T02:28:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.823155'}
2025-10-09 02:28:36,170 - DEBUG - Control-node 10.0.0.19 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-01175662:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9330561241727913769', 'uuid-lslong': '10498514554416998716'}, 'enable': 'true', 'created': '2025-10-09T02:28:35', 'last-modified': '2025-10-09T02:28:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.825417'}
2025-10-09 02:28:36,175 - DEBUG - Control-node 10.0.0.41 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-01175662:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9330561241727913769', 'uuid-lslong': '10498514554416998716'}, 'enable': 'true', 'created': '2025-10-09T02:28:35', 'last-modified': '2025-10-09T02:28:35', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.830812'}
2025-10-09 02:28:36,175 - INFO - Verifications in Control node for IPAM: ipam3 passed
2025-10-09 02:28:36,521 - DEBUG - Response for create_network : {'network': {'id': '6b756ff9-fbf0-4379-ab79-4bddc563dd3a', 'name': 'ctest-VN1-23833463', 'tenant_id': '0b4d21789a444ef0886b5e0d37925db5', 'project_id': '0b4d21789a444ef0886b5e0d37925db5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-01175662', 'ctest-VN1-23833463'], 'port_security_enabled': True, 'description': ''}}
2025-10-09 02:28:36,809 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a5b8d20a-ef80-46cb-8823-a5843677ef34', 'name': '', 'tenant_id': '0b4d21789a444ef0886b5e0d37925db5', 'network_id': '6b756ff9-fbf0-4379-ab79-4bddc563dd3a', 'ip_version': 4, 'cidr': '47.82.173.0/26', 'allocation_pools': [{'start': '47.82.173.2', 'end': '47.82.173.62'}], 'gateway_ip': '47.82.173.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '47.82.173.2', 'tags': [], 'project_id': '0b4d21789a444ef0886b5e0d37925db5'}}
2025-10-09 02:28:36,834 - INFO - Created VN ctest-VN1-23833463
2025-10-09 02:28:36,847 - DEBUG - VN ctest-VN1-23833463 UUID is 6b756ff9-fbf0-4379-ab79-4bddc563dd3a
2025-10-09 02:28:37,022 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463(6b756ff9-fbf0-4379-ab79-4bddc563dd3a) with subnets ['47.82.173.0/26']
2025-10-09 02:28:37,352 - DEBUG - Response for create_network : {'network': {'id': '6cce07d0-5e70-46bd-a53b-674be72e0747', 'name': 'ctest-VN2-97630887', 'tenant_id': '0b4d21789a444ef0886b5e0d37925db5', 'project_id': '0b4d21789a444ef0886b5e0d37925db5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-01175662', 'ctest-VN2-97630887'], 'port_security_enabled': True, 'description': ''}}
2025-10-09 02:28:37,577 - DEBUG - Response for create_subnet : {'subnet': {'id': '30318b4c-5e08-49a2-beaf-ee8e4c05c230', 'name': '', 'tenant_id': '0b4d21789a444ef0886b5e0d37925db5', 'network_id': '6cce07d0-5e70-46bd-a53b-674be72e0747', 'ip_version': 4, 'cidr': '131.71.145.192/26', 'allocation_pools': [{'start': '131.71.145.194', 'end': '131.71.145.254'}], 'gateway_ip': '131.71.145.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '131.71.145.194', 'tags': [], 'project_id': '0b4d21789a444ef0886b5e0d37925db5'}}
2025-10-09 02:28:37,602 - INFO - Created VN ctest-VN2-97630887
2025-10-09 02:28:37,618 - DEBUG - VN ctest-VN2-97630887 UUID is 6cce07d0-5e70-46bd-a53b-674be72e0747
2025-10-09 02:28:37,988 - DEBUG - Response for create_network : {'network': {'id': '0c08194a-f1c4-4318-8e60-beab8610b92f', 'name': 'ctest-VN3-52887060', 'tenant_id': '0b4d21789a444ef0886b5e0d37925db5', 'project_id': '0b4d21789a444ef0886b5e0d37925db5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-01175662', 'ctest-VN3-52887060'], 'port_security_enabled': True, 'description': ''}}
2025-10-09 02:28:38,291 - DEBUG - Response for create_subnet : {'subnet': {'id': 'dab19512-3563-4b3b-abf4-452324ee13bc', 'name': '', 'tenant_id': '0b4d21789a444ef0886b5e0d37925db5', 'network_id': '0c08194a-f1c4-4318-8e60-beab8610b92f', 'ip_version': 4, 'cidr': '107.145.208.0/26', 'allocation_pools': [{'start': '107.145.208.2', 'end': '107.145.208.62'}], 'gateway_ip': '107.145.208.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '107.145.208.2', 'tags': [], 'project_id': '0b4d21789a444ef0886b5e0d37925db5'}}
2025-10-09 02:28:38,316 - INFO - Created VN ctest-VN3-52887060
2025-10-09 02:28:38,328 - DEBUG - VN ctest-VN3-52887060 UUID is 0c08194a-f1c4-4318-8e60-beab8610b92f
2025-10-09 02:28:38,417 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:28:38,427 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 02:28:38,442 - DEBUG - Requesting: http://10.0.0.56:8082/project/0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:28:38,532 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6b756ff9-fbf0-4379-ab79-4bddc563dd3a
2025-10-09 02:28:38,545 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6b756ff9-fbf0-4379-ab79-4bddc563dd3a
2025-10-09 02:28:38,557 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8a19d718-94d8-4634-b752-f721f5c4ed3a
2025-10-09 02:28:38,564 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8a19d718-94d8-4634-b752-f721f5c4ed3a
2025-10-09 02:28:38,572 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/b30be41f-a443-4361-a014-2df72b8a37f3
2025-10-09 02:28:38,579 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-09 02:28:38,579 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6b756ff9-fbf0-4379-ab79-4bddc563dd3a
2025-10-09 02:28:38,587 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8a19d718-94d8-4634-b752-f721f5c4ed3a
2025-10-09 02:28:38,609 - INFO - Verified VN network id 14 for VN 6b756ff9-fbf0-4379-ab79-4bddc563dd3a
2025-10-09 02:28:38,609 - INFO - Verifications in API Server for VN ctest-VN1-23833463 passed
2025-10-09 02:28:38,609 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6b756ff9-fbf0-4379-ab79-4bddc563dd3a
2025-10-09 02:28:38,622 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8a19d718-94d8-4634-b752-f721f5c4ed3a
2025-10-09 02:28:38,632 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8a19d718-94d8-4634-b752-f721f5c4ed3a
2025-10-09 02:28:38,639 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/b30be41f-a443-4361-a014-2df72b8a37f3
2025-10-09 02:28:38,650 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7743218253797802873', 'uuid-lslong': '12355990468539178298'}, 'enable': 'true', 'created': '2025-10-09T02:28:36', 'last-modified': '2025-10-09T02:28:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.906121'}
2025-10-09 02:28:38,655 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-09 02:28:38,662 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7743218253797802873', 'uuid-lslong': '12355990468539178298'}, 'enable': 'true', 'created': '2025-10-09T02:28:36', 'last-modified': '2025-10-09T02:28:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.917697'}
2025-10-09 02:28:38,667 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-09 02:28:38,672 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7743218253797802873', 'uuid-lslong': '12355990468539178298'}, 'enable': 'true', 'created': '2025-10-09T02:28:36', 'last-modified': '2025-10-09T02:28:36', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.928161'}
2025-10-09 02:28:38,677 - DEBUG - Route Targets: ['target:64512:8000004']
2025-10-09 02:28:38,678 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-23833463 passed
2025-10-09 02:28:38,678 - DEBUG - ====Verifying policy data for ctest-VN1-23833463 in API_Server ======
2025-10-09 02:28:38,678 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:28:38,684 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 02:28:38,698 - DEBUG - Requesting: http://10.0.0.56:8082/project/0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:28:38,801 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6b756ff9-fbf0-4379-ab79-4bddc563dd3a
2025-10-09 02:28:38,812 - DEBUG - =>VN ctest-VN1-23833463 has no policy to be verified
2025-10-09 02:28:38,812 - DEBUG - Verifying the vn in opserver
2025-10-09 02:28:38,812 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 virtual network link through opserver 10.0.0.56
2025-10-09 02:28:38,812 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-10-09 02:28:39,123 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-10-09 02:28:39,186 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463?flat'}
2025-10-09 02:28:39,186 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 virtual network link through opserver 10.0.0.19
2025-10-09 02:28:39,186 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-09 02:28:39,499 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-09 02:28:39,577 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463?flat'}
2025-10-09 02:28:39,577 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 virtual network link through opserver 10.0.0.41
2025-10-09 02:28:39,577 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-09 02:28:39,873 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-09 02:28:39,930 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463?flat'}
2025-10-09 02:28:39,930 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 is found in opserver
2025-10-09 02:28:39,950 - DEBUG - Do not have enough data to verify VN in agent
2025-10-09 02:28:39,958 - DEBUG - VRF ids for VN ctest-VN1-23833463: {}
2025-10-09 02:28:39,958 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:28:39,964 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 02:28:39,975 - DEBUG - Requesting: http://10.0.0.56:8082/project/0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:28:40,069 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6cce07d0-5e70-46bd-a53b-674be72e0747
2025-10-09 02:28:40,081 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6cce07d0-5e70-46bd-a53b-674be72e0747
2025-10-09 02:28:40,090 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/04b3ffdb-6083-470f-8fb5-fd926a601fd3
2025-10-09 02:28:40,097 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/04b3ffdb-6083-470f-8fb5-fd926a601fd3
2025-10-09 02:28:40,103 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/a95173b3-c6ce-424a-baa4-923fa608efe7
2025-10-09 02:28:40,110 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-09 02:28:40,110 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6cce07d0-5e70-46bd-a53b-674be72e0747
2025-10-09 02:28:40,119 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/04b3ffdb-6083-470f-8fb5-fd926a601fd3
2025-10-09 02:28:40,134 - INFO - Verified VN network id 15 for VN 6cce07d0-5e70-46bd-a53b-674be72e0747
2025-10-09 02:28:40,134 - INFO - Verifications in API Server for VN ctest-VN2-97630887 passed
2025-10-09 02:28:40,135 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6cce07d0-5e70-46bd-a53b-674be72e0747
2025-10-09 02:28:40,144 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/04b3ffdb-6083-470f-8fb5-fd926a601fd3
2025-10-09 02:28:40,152 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/04b3ffdb-6083-470f-8fb5-fd926a601fd3
2025-10-09 02:28:40,158 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/a95173b3-c6ce-424a-baa4-923fa608efe7
2025-10-09 02:28:40,170 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7840212592817620669', 'uuid-lslong': '11906223615582799687'}, 'enable': 'true', 'created': '2025-10-09T02:28:37', 'last-modified': '2025-10-09T02:28:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.666739'}
2025-10-09 02:28:40,175 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-09 02:28:40,181 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7840212592817620669', 'uuid-lslong': '11906223615582799687'}, 'enable': 'true', 'created': '2025-10-09T02:28:37', 'last-modified': '2025-10-09T02:28:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.677920'}
2025-10-09 02:28:40,186 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-09 02:28:40,192 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7840212592817620669', 'uuid-lslong': '11906223615582799687'}, 'enable': 'true', 'created': '2025-10-09T02:28:37', 'last-modified': '2025-10-09T02:28:37', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.688756'}
2025-10-09 02:28:40,198 - DEBUG - Route Targets: ['target:64512:8000005']
2025-10-09 02:28:40,198 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-97630887 passed
2025-10-09 02:28:40,198 - DEBUG - ====Verifying policy data for ctest-VN2-97630887 in API_Server ======
2025-10-09 02:28:40,198 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:28:40,204 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 02:28:40,214 - DEBUG - Requesting: http://10.0.0.56:8082/project/0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:28:40,311 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6cce07d0-5e70-46bd-a53b-674be72e0747
2025-10-09 02:28:40,322 - DEBUG - =>VN ctest-VN2-97630887 has no policy to be verified
2025-10-09 02:28:40,322 - DEBUG - Verifying the vn in opserver
2025-10-09 02:28:40,323 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887 virtual network link through opserver 10.0.0.56
2025-10-09 02:28:40,323 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-10-09 02:28:40,392 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887?flat'}
2025-10-09 02:28:40,392 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887 virtual network link through opserver 10.0.0.19
2025-10-09 02:28:40,392 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-09 02:28:40,449 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887?flat'}
2025-10-09 02:28:40,449 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887 virtual network link through opserver 10.0.0.41
2025-10-09 02:28:40,450 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-09 02:28:40,503 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887?flat'}
2025-10-09 02:28:40,503 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887 is found in opserver
2025-10-09 02:28:40,523 - DEBUG - Do not have enough data to verify VN in agent
2025-10-09 02:28:40,532 - DEBUG - VRF ids for VN ctest-VN2-97630887: {}
2025-10-09 02:28:40,533 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:28:40,546 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 02:28:40,557 - DEBUG - Requesting: http://10.0.0.56:8082/project/0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:28:40,651 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/0c08194a-f1c4-4318-8e60-beab8610b92f
2025-10-09 02:28:40,665 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/0c08194a-f1c4-4318-8e60-beab8610b92f
2025-10-09 02:28:40,678 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/0b658736-55b7-4063-80c4-2fcd7dba1627
2025-10-09 02:28:40,687 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/0b658736-55b7-4063-80c4-2fcd7dba1627
2025-10-09 02:28:40,695 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/4d19ea3b-db74-4640-be42-90466a424010
2025-10-09 02:28:40,702 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-09 02:28:40,702 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/0c08194a-f1c4-4318-8e60-beab8610b92f
2025-10-09 02:28:40,712 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/0b658736-55b7-4063-80c4-2fcd7dba1627
2025-10-09 02:28:40,728 - INFO - Verified VN network id 16 for VN 0c08194a-f1c4-4318-8e60-beab8610b92f
2025-10-09 02:28:40,729 - INFO - Verifications in API Server for VN ctest-VN3-52887060 passed
2025-10-09 02:28:40,729 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/0c08194a-f1c4-4318-8e60-beab8610b92f
2025-10-09 02:28:40,738 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/0b658736-55b7-4063-80c4-2fcd7dba1627
2025-10-09 02:28:40,745 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/0b658736-55b7-4063-80c4-2fcd7dba1627
2025-10-09 02:28:40,753 - DEBUG - Requesting: http://10.0.0.56:8082/route-target/4d19ea3b-db74-4640-be42-90466a424010
2025-10-09 02:28:40,765 - DEBUG - Control-node 10.0.0.56 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '866970737943266072', 'uuid-lslong': '10259409595047917871'}, 'enable': 'true', 'created': '2025-10-09T02:28:37', 'last-modified': '2025-10-09T02:28:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.538881'}
2025-10-09 02:28:40,770 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-09 02:28:40,777 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '866970737943266072', 'uuid-lslong': '10259409595047917871'}, 'enable': 'true', 'created': '2025-10-09T02:28:37', 'last-modified': '2025-10-09T02:28:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.543673'}
2025-10-09 02:28:40,783 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-09 02:28:40,788 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-01175662', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '866970737943266072', 'uuid-lslong': '10259409595047917871'}, 'enable': 'true', 'created': '2025-10-09T02:28:37', 'last-modified': '2025-10-09T02:28:38', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.562909'}
2025-10-09 02:28:40,794 - DEBUG - Route Targets: ['target:64512:8000006']
2025-10-09 02:28:40,794 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-52887060 passed
2025-10-09 02:28:40,795 - DEBUG - ====Verifying policy data for ctest-VN3-52887060 in API_Server ======
2025-10-09 02:28:40,795 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:28:40,803 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 02:28:40,818 - DEBUG - Requesting: http://10.0.0.56:8082/project/0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:28:40,913 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/0c08194a-f1c4-4318-8e60-beab8610b92f
2025-10-09 02:28:40,928 - DEBUG - =>VN ctest-VN3-52887060 has no policy to be verified
2025-10-09 02:28:40,928 - DEBUG - Verifying the vn in opserver
2025-10-09 02:28:40,928 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060 virtual network link through opserver 10.0.0.56
2025-10-09 02:28:40,928 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-networks
2025-10-09 02:28:40,979 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060?flat'}
2025-10-09 02:28:40,979 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060 virtual network link through opserver 10.0.0.19
2025-10-09 02:28:40,979 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks
2025-10-09 02:28:41,034 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060?flat'}
2025-10-09 02:28:41,034 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060 virtual network link through opserver 10.0.0.41
2025-10-09 02:28:41,034 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks
2025-10-09 02:28:41,092 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060?flat'}
2025-10-09 02:28:41,092 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060 is found in opserver
2025-10-09 02:28:41,113 - DEBUG - Do not have enough data to verify VN in agent
2025-10-09 02:28:41,122 - DEBUG - VRF ids for VN ctest-VN3-52887060: {}
2025-10-09 02:28:41,130 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463, 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-01175662:ctest-VN2-97630887, 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]
2025-10-09 02:28:41,402 - DEBUG - No destination network defined
2025-10-09 02:28:41,402 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887, 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-01175662:ctest-policy13-71214033, 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]
2025-10-09 02:28:41,677 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463, 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-01175662:ctest-VN3-52887060, 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]
2025-10-09 02:28:41,913 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:28:41,922 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 02:28:41,932 - DEBUG - Requesting: http://10.0.0.56:8082/project/0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:28:42,026 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6b756ff9-fbf0-4379-ab79-4bddc563dd3a
2025-10-09 02:28:42,036 - DEBUG - Setup step: Associating the policy to VN'
2025-10-09 02:28:42,217 - INFO - Associated Policy to ctest-VN1-23833463
2025-10-09 02:28:42,218 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:28:42,227 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 02:28:42,239 - DEBUG - Requesting: http://10.0.0.56:8082/project/0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:28:42,330 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6cce07d0-5e70-46bd-a53b-674be72e0747
2025-10-09 02:28:42,339 - DEBUG - Setup step: Associating the policy to VN'
2025-10-09 02:28:42,442 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '6cce07d0-5e70-46bd-a53b-674be72e0747', 'name': 'ctest-VN2-97630887', 'tenant_id': '0b4d21789a444ef0886b5e0d37925db5', 'project_id': '0b4d21789a444ef0886b5e0d37925db5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['30318b4c-5e08-49a2-beaf-ee8e4c05c230'], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-01175662', 'ctest-VN2-97630887'], 'policys': [['default-domain', 'ctest-TestPolicyAcl-01175662', 'ctest-policy21-87561441']], 'subnet_ipam': [{'subnet_cidr': '131.71.145.192/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAcl-01175662', 'ipam2']}], 'port_security_enabled': True, 'description': ''}}
2025-10-09 02:28:42,576 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAcl-01175662', 'ctest-policy21-87561441']] to ctest-VN2-97630887
2025-10-09 02:28:42,678 - DEBUG - Services list from nova: [, , , ]
2025-10-09 02:28:43,708 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4252-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4252-2)
2025-10-09 02:28:44,995 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4252-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4252-1)
2025-10-09 02:28:46,383 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4252-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4252-2)
2025-10-09 02:28:46,486 - DEBUG - VM is in ACTIVE state now
2025-10-09 02:28:46,486 - INFO - VM name : ctest-VM11-11934870
2025-10-09 02:28:46,580 - DEBUG - VM ctest-VM11-11934870 ID is c7e8a716-d4a6-4574-a159-236a64248524
2025-10-09 02:28:46,673 - DEBUG - VM is in ACTIVE state now
2025-10-09 02:28:46,697 - DEBUG - VM ctest-VM11-11934870 launched on Node an-jenkins-deploy-platform-ansible-os-4252-2
2025-10-09 02:28:46,799 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/c7e8a716-d4a6-4574-a159-236a64248524
2025-10-09 02:28:47,258 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/c7e8a716-d4a6-4574-a159-236a64248524
2025-10-09 02:28:47,328 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/c7e8a716-d4a6-4574-a159-236a64248524
2025-10-09 02:28:47,338 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/04dabded-bd3c-41cc-b851-8e215101026a
2025-10-09 02:28:47,348 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/c7e8a716-d4a6-4574-a159-236a64248524
2025-10-09 02:28:47,355 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/04dabded-bd3c-41cc-b851-8e215101026a
2025-10-09 02:28:47,365 - DEBUG - Requesting: http://10.0.0.41:8082/instance-ip/02736546-33e5-4be1-ae3e-0dc0f108622a
2025-10-09 02:28:47,378 - DEBUG - Verifying in api server 10.0.0.41
2025-10-09 02:28:47,379 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/c7e8a716-d4a6-4574-a159-236a64248524
2025-10-09 02:28:47,387 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/04dabded-bd3c-41cc-b851-8e215101026a
2025-10-09 02:28:47,398 - DEBUG - Requesting: http://10.0.0.41:8082/instance-ip/02736546-33e5-4be1-ae3e-0dc0f108622a
2025-10-09 02:28:47,406 - INFO - VM ctest-VM11-11934870 verfication in all API Servers passed
2025-10-09 02:28:47,422 - DEBUG - VM ctest-VM11-11934870 Tap interface: {'index': '3', 'name': 'tap04dabded-bd', 'uuid': '04dabded-bd3c-41cc-b851-8e215101026a', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463:ctest-VN1-23833463', '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-01175662:ctest-VN1-23833463', 'vm_uuid': 'c7e8a716-d4a6-4574-a159-236a64248524', 'vm_name': 'ctest-VM11-11934870', 'ip_addr': '47.82.173.3', 'mac_addr': '02:04:da:bd:ed:bd', '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-01175662:04dabded-bd3c-41cc-b851-8e215101026a', 'sg_uuid_list': ['942f1482-d1c3-4996-b039-9e925237ff80'], 'static_route_list': None, 'vm_project_uuid': '0b4d2178-9a44-4ef0-886b-5e0d37925db5', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2025-10-09 02:28:47,422 - DEBUG - Agent 10.0.0.27 vrf name: default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463:ctest-VN1-23833463
2025-10-09 02:28:47,426 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463:ctest-VN1-23833463', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.192:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2025-10-09 02:28:47,510 - DEBUG - VM ctest-VM11-11934870 : Tap interface tap04dabded-bd is set to Active
2025-10-09 02:28:47,511 - DEBUG - Tap interface tap04dabded-bd detail : {'index': '3', 'name': 'tap04dabded-bd', 'uuid': '04dabded-bd3c-41cc-b851-8e215101026a', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463:ctest-VN1-23833463', '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-01175662:ctest-VN1-23833463', 'vm_uuid': 'c7e8a716-d4a6-4574-a159-236a64248524', 'vm_name': 'ctest-VM11-11934870', 'ip_addr': '47.82.173.3', 'mac_addr': '02:04:da:bd:ed:bd', '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-01175662:04dabded-bd3c-41cc-b851-8e215101026a', 'sg_uuid_list': ['942f1482-d1c3-4996-b039-9e925237ff80'], 'static_route_list': None, 'vm_project_uuid': '0b4d2178-9a44-4ef0-886b-5e0d37925db5', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2025-10-09 02:28:47,622 - DEBUG - Starting Layer 2 verification in Agent
2025-10-09 02:28:47,640 - DEBUG - Layer 2 path is seen for VM MAC 02:04:da:bd:ed:bd in agent 10.0.0.27
2025-10-09 02:28:47,640 - DEBUG - Active layer 2 route in agent is present for VMI tap04dabded-bd
2025-10-09 02:28:47,640 - DEBUG - L2 label(29) matches bw route table and interface table
2025-10-09 02:28:47,655 - DEBUG - VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 is found in Agent of node 10.0.0.27
2025-10-09 02:28:47,783 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 is consistent in agent 10.0.0.27
2025-10-09 02:28:47,784 - DEBUG - Route for VM IP ['47.82.173.3'] is consistent in agent 10.0.0.27
2025-10-09 02:28:47,784 - DEBUG - VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 verification for VM ctest-VM11-11934870 in Agent 10.0.0.27 passed
2025-10-09 02:28:47,856 - DEBUG - Starting all layer 2 verification in agent 10.0.0.27
2025-10-09 02:28:48,016 - DEBUG - Route for VM MAC 02:04:da:bd:ed:bd is consistent in agent 10.0.0.27
2025-10-09 02:28:51,155 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1004ms')
2025-10-09 02:28:51,155 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11934870 failed!
2025-10-09 02:28:51,169 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 is 47.82.173.1 and allocation pool is NOT set
2025-10-09 02:28:55,256 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1021ms')
2025-10-09 02:28:55,256 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11934870 failed!
2025-10-09 02:28:55,272 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 is 47.82.173.1 and allocation pool is NOT set
2025-10-09 02:28:59,352 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2025-10-09 02:28:59,352 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11934870 failed!
2025-10-09 02:28:59,367 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 is 47.82.173.1 and allocation pool is NOT set
2025-10-09 02:29:03,445 - 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')
2025-10-09 02:29:03,445 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11934870 failed!
2025-10-09 02:29:03,460 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 is 47.82.173.1 and allocation pool is NOT set
2025-10-09 02:29:07,541 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms')
2025-10-09 02:29:07,541 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11934870 failed!
2025-10-09 02:29:07,556 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 is 47.82.173.1 and allocation pool is NOT set
2025-10-09 02:29:11,636 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2025-10-09 02:29:11,636 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11934870 failed!
2025-10-09 02:29:11,650 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 is 47.82.173.1 and allocation pool is NOT set
2025-10-09 02:29:15,730 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms')
2025-10-09 02:29:15,730 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11934870 failed!
2025-10-09 02:29:15,744 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 is 47.82.173.1 and allocation pool is NOT set
2025-10-09 02:29:19,823 - 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')
2025-10-09 02:29:19,823 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11934870 failed!
2025-10-09 02:29:19,837 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 is 47.82.173.1 and allocation pool is NOT set
2025-10-09 02:29:23,924 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1021ms')
2025-10-09 02:29:23,924 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11934870 failed!
2025-10-09 02:29:23,939 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 is 47.82.173.1 and allocation pool is NOT set
2025-10-09 02:29:28,020 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=5.63 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1018ms\r\nrtt min/avg/max/mdev = 5.633/5.633/5.633/0.000 ms')
2025-10-09 02:29:28,020 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11934870 passed
2025-10-09 02:29:28,020 - INFO - VM ctest-VM11-11934870 verifications in Compute nodes passed
2025-10-09 02:29:28,304 - DEBUG - Validated VM route 47.82.173.3 in vrouter of 10.0.0.27
2025-10-09 02:29:28,314 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2025-10-09 02:29:28,314 - INFO - Validated routes of VM ctest-VM11-11934870 in all vrouters
2025-10-09 02:29:28,783 - DEBUG - Starting all layer2 verification in 10.0.0.19 Control Node
2025-10-09 02:29:28,906 - DEBUG - Layer2 route found for VM MAC 02:04:da:bd:ed:bd in Control-node 10.0.0.19
2025-10-09 02:29:28,906 - DEBUG - L2 Label for VM ctest-VM11-11934870 same between Control-node 10.0.0.19 and Agent, Expected: 29, Seen: 29
2025-10-09 02:29:28,906 - DEBUG - Starting all layer2 verification in 10.0.0.56 Control Node
2025-10-09 02:29:29,033 - DEBUG - Layer2 route found for VM MAC 02:04:da:bd:ed:bd in Control-node 10.0.0.56
2025-10-09 02:29:29,034 - DEBUG - L2 Label for VM ctest-VM11-11934870 same between Control-node 10.0.0.56 and Agent, Expected: 29, Seen: 29
2025-10-09 02:29:29,034 - INFO - Verification in Control-nodes for VM ctest-VM11-11934870 passed
2025-10-09 02:29:29,034 - DEBUG - Verifying the vm in opserver
2025-10-09 02:29:29,034 - DEBUG - Verifying in collector 10.0.0.41 ...
2025-10-09 02:29:29,034 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/c7e8a716-d4a6-4574-a159-236a64248524?flat
2025-10-09 02:29:29,053 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-01175662:04dabded-bd3c-41cc-b851-8e215101026a?flat
2025-10-09 02:29:29,072 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-01175662:04dabded-bd3c-41cc-b851-8e215101026a?flat
2025-10-09 02:29:29,090 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/c7e8a716-d4a6-4574-a159-236a64248524?flat
2025-10-09 02:29:29,107 - DEBUG - Verifying vm in vn uve
2025-10-09 02:29:29,107 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-01175662:04dabded-bd3c-41cc-b851-8e215101026a?flat
2025-10-09 02:29:29,127 - DEBUG - VM uve shows interface as {'vn_uuid': '6b756ff9-fbf0-4379-ab79-4bddc563dd3a', 'label': 25, 'ip_address': '47.82.173.3', 'virtual_network': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463', 'gateway': '47.82.173.1', 'ip6_active': False, 'is_health_check_active': True, 'tx_vlan': 65535, 'active': True, 'admin_state': True, 'port_mirror_enabled': False, 'vhostuser_mode': 0, 'fixed_ip4_list': ['47.82.173.3'], 'l2_active': True, 'uuid': '04dabded-bd3c-41cc-b851-8e215101026a', 'vm_uuid': 'c7e8a716-d4a6-4574-a159-236a64248524', 'rx_vlan': 65535, 'mac_address': '02:04:da:bd:ed:bd', 'ip4_active': True, 'ip6_address': '::', 'vm_name': 'ctest-VM11-11934870', '__T': 1759976949724482}
2025-10-09 02:29:29,127 - DEBUG - VM uve shows ip address as ['47.82.173.3', '::']
2025-10-09 02:29:29,127 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463
2025-10-09 02:29:29,127 - INFO - Verifying through opserver in 10.0.0.56
2025-10-09 02:29:29,127 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463?flat
2025-10-09 02:29:29,151 - DEBUG - expected vm list c7e8a716-d4a6-4574-a159-236a64248524
2025-10-09 02:29:29,151 - DEBUG - Extracted vm list ['c7e8a716-d4a6-4574-a159-236a64248524']
2025-10-09 02:29:29,151 - INFO - VM c7e8a716-d4a6-4574-a159-236a64248524 is present in default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463
2025-10-09 02:29:29,152 - DEBUG - Verifying vm in vrouter uve
2025-10-09 02:29:29,152 - DEBUG - Getting info from collector 10.0.0.41..
2025-10-09 02:29:29,152 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/c7e8a716-d4a6-4574-a159-236a64248524?flat
2025-10-09 02:29:29,168 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4252-2']
2025-10-09 02:29:29,168 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-2:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:29:29,203 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-2?flat
2025-10-09 02:29:29,237 - DEBUG - VM c7e8a716-d4a6-4574-a159-236a64248524 is present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:29:29,237 - DEBUG - Validated that VM ctest-VM11-11934870 is in Vrouter an-jenkins-deploy-platform-ansible-os-4252-2 UVE
2025-10-09 02:29:29,237 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2025-10-09 02:29:29,244 - DEBUG - Expected tap interface of VM uuid c7e8a716-d4a6-4574-a159-236a64248524 is default-domain:ctest-TestPolicyAcl-01175662:04dabded-bd3c-41cc-b851-8e215101026a
2025-10-09 02:29:29,244 - DEBUG - Expected VN of VM uuid c7e8a716-d4a6-4574-a159-236a64248524 is default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463
2025-10-09 02:29:29,244 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-2:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:29:29,280 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-2?flat
2025-10-09 02:29:29,310 - DEBUG - VM c7e8a716-d4a6-4574-a159-236a64248524 is present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:29:29,310 - INFO - tap interface default-domain:ctest-TestPolicyAcl-01175662:04dabded-bd3c-41cc-b851-8e215101026a of vm c7e8a716-d4a6-4574-a159-236a64248524 is present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:29:29,311 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 of vm c7e8a716-d4a6-4574-a159-236a64248524 is present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:29:29,311 - DEBUG - Verifying the c7e8a716-d4a6-4574-a159-236a64248524 virtual network link through opserver 10.0.0.56
2025-10-09 02:29:29,311 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-machines
2025-10-09 02:29:29,323 - DEBUG - VM link and name as {'name': 'c7e8a716-d4a6-4574-a159-236a64248524', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-machine/c7e8a716-d4a6-4574-a159-236a64248524?flat'}
2025-10-09 02:29:29,323 - DEBUG - Verifying the c7e8a716-d4a6-4574-a159-236a64248524 virtual network link through opserver 10.0.0.19
2025-10-09 02:29:29,323 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-10-09 02:29:29,336 - DEBUG - VM link and name as {'name': 'c7e8a716-d4a6-4574-a159-236a64248524', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-machine/c7e8a716-d4a6-4574-a159-236a64248524?flat'}
2025-10-09 02:29:29,336 - DEBUG - Verifying the c7e8a716-d4a6-4574-a159-236a64248524 virtual network link through opserver 10.0.0.41
2025-10-09 02:29:29,336 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines
2025-10-09 02:29:29,348 - DEBUG - VM link and name as {'name': 'c7e8a716-d4a6-4574-a159-236a64248524', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-machine/c7e8a716-d4a6-4574-a159-236a64248524?flat'}
2025-10-09 02:29:29,348 - INFO - VM ctest-VM11-11934870 validations in Opserver passed
2025-10-09 02:29:29,430 - DEBUG - VM is in ACTIVE state now
2025-10-09 02:29:29,430 - INFO - VM name : ctest-VM21-85550288
2025-10-09 02:29:29,512 - DEBUG - VM ctest-VM21-85550288 ID is 5bb8c0dc-2fc4-4bf7-8754-fef68c416929
2025-10-09 02:29:29,592 - DEBUG - VM is in ACTIVE state now
2025-10-09 02:29:29,592 - DEBUG - VM ctest-VM21-85550288 launched on Node an-jenkins-deploy-platform-ansible-os-4252-1
2025-10-09 02:29:29,671 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/5bb8c0dc-2fc4-4bf7-8754-fef68c416929
2025-10-09 02:29:29,681 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/5bb8c0dc-2fc4-4bf7-8754-fef68c416929
2025-10-09 02:29:29,689 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/a4372a46-12ca-4eab-abd7-542efad7c5d0
2025-10-09 02:29:29,699 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/5bb8c0dc-2fc4-4bf7-8754-fef68c416929
2025-10-09 02:29:29,706 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/a4372a46-12ca-4eab-abd7-542efad7c5d0
2025-10-09 02:29:29,715 - DEBUG - Requesting: http://10.0.0.41:8082/instance-ip/fcdf366e-eba3-463d-a29a-e549c8903b0e
2025-10-09 02:29:29,724 - DEBUG - Verifying in api server 10.0.0.41
2025-10-09 02:29:29,724 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/5bb8c0dc-2fc4-4bf7-8754-fef68c416929
2025-10-09 02:29:29,731 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/a4372a46-12ca-4eab-abd7-542efad7c5d0
2025-10-09 02:29:29,740 - DEBUG - Requesting: http://10.0.0.41:8082/instance-ip/fcdf366e-eba3-463d-a29a-e549c8903b0e
2025-10-09 02:29:29,747 - INFO - VM ctest-VM21-85550288 verfication in all API Servers passed
2025-10-09 02:29:29,778 - DEBUG - VM ctest-VM21-85550288 Tap interface: {'index': '3', 'name': 'tapa4372a46-12', 'uuid': 'a4372a46-12ca-4eab-abd7-542efad7c5d0', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887:ctest-VN2-97630887', '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-01175662:ctest-VN2-97630887', 'vm_uuid': '5bb8c0dc-2fc4-4bf7-8754-fef68c416929', 'vm_name': 'ctest-VM21-85550288', 'ip_addr': '131.71.145.195', 'mac_addr': '02:a4:37:2a:46:12', '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-01175662:a4372a46-12ca-4eab-abd7-542efad7c5d0', 'sg_uuid_list': ['942f1482-d1c3-4996-b039-9e925237ff80'], 'static_route_list': None, 'vm_project_uuid': '0b4d2178-9a44-4ef0-886b-5e0d37925db5', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2025-10-09 02:29:29,778 - DEBUG - Agent 10.0.0.22 vrf name: default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887:ctest-VN2-97630887
2025-10-09 02:29:29,783 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887:ctest-VN2-97630887', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887', 'table_label': '-1', 'vxlan_id': '15', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.18:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2025-10-09 02:29:29,867 - DEBUG - VM ctest-VM21-85550288 : Tap interface tapa4372a46-12 is set to Active
2025-10-09 02:29:29,867 - DEBUG - Tap interface tapa4372a46-12 detail : {'index': '3', 'name': 'tapa4372a46-12', 'uuid': 'a4372a46-12ca-4eab-abd7-542efad7c5d0', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887:ctest-VN2-97630887', '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-01175662:ctest-VN2-97630887', 'vm_uuid': '5bb8c0dc-2fc4-4bf7-8754-fef68c416929', 'vm_name': 'ctest-VM21-85550288', 'ip_addr': '131.71.145.195', 'mac_addr': '02:a4:37:2a:46:12', '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-01175662:a4372a46-12ca-4eab-abd7-542efad7c5d0', 'sg_uuid_list': ['942f1482-d1c3-4996-b039-9e925237ff80'], 'static_route_list': None, 'vm_project_uuid': '0b4d2178-9a44-4ef0-886b-5e0d37925db5', 'admin_state': 'Enabled', 'flow_key_idx': '30', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []}
2025-10-09 02:29:29,983 - DEBUG - Starting Layer 2 verification in Agent
2025-10-09 02:29:29,998 - DEBUG - Layer 2 path is seen for VM MAC 02:a4:37:2a:46:12 in agent 10.0.0.22
2025-10-09 02:29:29,999 - DEBUG - Active layer 2 route in agent is present for VMI tapa4372a46-12
2025-10-09 02:29:29,999 - DEBUG - L2 label(29) matches bw route table and interface table
2025-10-09 02:29:30,008 - DEBUG - VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887 is found in Agent of node 10.0.0.22
2025-10-09 02:29:30,143 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887 is consistent in agent 10.0.0.22
2025-10-09 02:29:30,143 - DEBUG - Route for VM IP ['131.71.145.195'] is consistent in agent 10.0.0.22
2025-10-09 02:29:30,143 - DEBUG - VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887 verification for VM ctest-VM21-85550288 in Agent 10.0.0.22 passed
2025-10-09 02:29:30,259 - DEBUG - Starting all layer 2 verification in agent 10.0.0.22
2025-10-09 02:29:30,270 - DEBUG - Route for VM MAC 02:a4:37:2a:46:12 is consistent in agent 10.0.0.22
2025-10-09 02:29:31,443 - 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.78 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.16 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.155/1.966/2.778/0.811 ms')
2025-10-09 02:29:31,443 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-85550288 passed
2025-10-09 02:29:31,443 - INFO - VM ctest-VM21-85550288 verifications in Compute nodes passed
2025-10-09 02:29:31,676 - DEBUG - Validated VM route 131.71.145.195 in vrouter of 10.0.0.22
2025-10-09 02:29:31,684 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2025-10-09 02:29:31,684 - INFO - Validated routes of VM ctest-VM21-85550288 in all vrouters
2025-10-09 02:29:32,132 - DEBUG - Starting all layer2 verification in 10.0.0.19 Control Node
2025-10-09 02:29:32,258 - DEBUG - Layer2 route found for VM MAC 02:a4:37:2a:46:12 in Control-node 10.0.0.19
2025-10-09 02:29:32,258 - DEBUG - L2 Label for VM ctest-VM21-85550288 same between Control-node 10.0.0.19 and Agent, Expected: 29, Seen: 29
2025-10-09 02:29:32,258 - DEBUG - Starting all layer2 verification in 10.0.0.56 Control Node
2025-10-09 02:29:32,383 - DEBUG - Layer2 route found for VM MAC 02:a4:37:2a:46:12 in Control-node 10.0.0.56
2025-10-09 02:29:32,383 - DEBUG - L2 Label for VM ctest-VM21-85550288 same between Control-node 10.0.0.56 and Agent, Expected: 29, Seen: 29
2025-10-09 02:29:32,383 - INFO - Verification in Control-nodes for VM ctest-VM21-85550288 passed
2025-10-09 02:29:32,383 - DEBUG - Verifying the vm in opserver
2025-10-09 02:29:32,383 - DEBUG - Verifying in collector 10.0.0.41 ...
2025-10-09 02:29:32,383 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/5bb8c0dc-2fc4-4bf7-8754-fef68c416929?flat
2025-10-09 02:29:32,400 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-01175662:a4372a46-12ca-4eab-abd7-542efad7c5d0?flat
2025-10-09 02:29:32,420 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-01175662:a4372a46-12ca-4eab-abd7-542efad7c5d0?flat
2025-10-09 02:29:32,439 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/5bb8c0dc-2fc4-4bf7-8754-fef68c416929?flat
2025-10-09 02:29:32,458 - DEBUG - Verifying vm in vn uve
2025-10-09 02:29:32,459 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-01175662:a4372a46-12ca-4eab-abd7-542efad7c5d0?flat
2025-10-09 02:29:32,478 - DEBUG - VM uve shows interface as {'vhostuser_mode': 0, '__T': 1759976942506504, 'tx_vlan': 65535, 'gateway': '131.71.145.193', 'vn_uuid': '6cce07d0-5e70-46bd-a53b-674be72e0747', 'l2_active': True, 'active': True, 'ip4_active': True, 'virtual_network': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887', 'admin_state': True, 'vm_name': 'ctest-VM21-85550288', 'ip6_address': '::', 'port_mirror_enabled': False, 'uuid': 'a4372a46-12ca-4eab-abd7-542efad7c5d0', 'vm_uuid': '5bb8c0dc-2fc4-4bf7-8754-fef68c416929', 'mac_address': '02:a4:37:2a:46:12', 'fixed_ip4_list': ['131.71.145.195'], 'label': 25, 'rx_vlan': 65535, 'is_health_check_active': True, 'ip6_active': False, 'ip_address': '131.71.145.195'}
2025-10-09 02:29:32,478 - DEBUG - VM uve shows ip address as ['131.71.145.195', '::']
2025-10-09 02:29:32,478 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887
2025-10-09 02:29:32,478 - INFO - Verifying through opserver in 10.0.0.56
2025-10-09 02:29:32,478 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887?flat
2025-10-09 02:29:32,500 - DEBUG - expected vm list 5bb8c0dc-2fc4-4bf7-8754-fef68c416929
2025-10-09 02:29:32,500 - DEBUG - Extracted vm list ['5bb8c0dc-2fc4-4bf7-8754-fef68c416929']
2025-10-09 02:29:32,500 - INFO - VM 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is present in default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887
2025-10-09 02:29:32,500 - DEBUG - Verifying vm in vrouter uve
2025-10-09 02:29:32,501 - DEBUG - Getting info from collector 10.0.0.41..
2025-10-09 02:29:32,501 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/5bb8c0dc-2fc4-4bf7-8754-fef68c416929?flat
2025-10-09 02:29:32,520 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4252-1']
2025-10-09 02:29:32,520 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-1:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:29:32,562 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-1?flat
2025-10-09 02:29:32,594 - DEBUG - VM 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is present in vrouter an-jenkins-deploy-platform-ansible-os-4252-1 uve
2025-10-09 02:29:32,594 - DEBUG - Validated that VM ctest-VM21-85550288 is in Vrouter an-jenkins-deploy-platform-ansible-os-4252-1 UVE
2025-10-09 02:29:32,594 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2025-10-09 02:29:32,601 - DEBUG - Expected tap interface of VM uuid 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is default-domain:ctest-TestPolicyAcl-01175662:a4372a46-12ca-4eab-abd7-542efad7c5d0
2025-10-09 02:29:32,601 - DEBUG - Expected VN of VM uuid 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887
2025-10-09 02:29:32,601 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-1:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:29:32,637 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-1?flat
2025-10-09 02:29:32,664 - DEBUG - VM 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is present in vrouter an-jenkins-deploy-platform-ansible-os-4252-1 uve
2025-10-09 02:29:32,665 - INFO - tap interface default-domain:ctest-TestPolicyAcl-01175662:a4372a46-12ca-4eab-abd7-542efad7c5d0 of vm 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is present in vrouter an-jenkins-deploy-platform-ansible-os-4252-1 uve
2025-10-09 02:29:32,665 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887 of vm 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is present in vrouter an-jenkins-deploy-platform-ansible-os-4252-1 uve
2025-10-09 02:29:32,665 - DEBUG - Verifying the 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 virtual network link through opserver 10.0.0.56
2025-10-09 02:29:32,665 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-machines
2025-10-09 02:29:32,677 - DEBUG - VM link and name as {'name': '5bb8c0dc-2fc4-4bf7-8754-fef68c416929', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-machine/5bb8c0dc-2fc4-4bf7-8754-fef68c416929?flat'}
2025-10-09 02:29:32,677 - DEBUG - Verifying the 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 virtual network link through opserver 10.0.0.19
2025-10-09 02:29:32,677 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-10-09 02:29:32,689 - DEBUG - VM link and name as {'name': '5bb8c0dc-2fc4-4bf7-8754-fef68c416929', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-machine/5bb8c0dc-2fc4-4bf7-8754-fef68c416929?flat'}
2025-10-09 02:29:32,689 - DEBUG - Verifying the 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 virtual network link through opserver 10.0.0.41
2025-10-09 02:29:32,689 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines
2025-10-09 02:29:32,700 - DEBUG - VM link and name as {'name': '5bb8c0dc-2fc4-4bf7-8754-fef68c416929', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-machine/5bb8c0dc-2fc4-4bf7-8754-fef68c416929?flat'}
2025-10-09 02:29:32,700 - INFO - VM ctest-VM21-85550288 validations in Opserver passed
2025-10-09 02:29:32,786 - DEBUG - VM is in ACTIVE state now
2025-10-09 02:29:32,786 - INFO - VM name : ctest-VM31-55325273
2025-10-09 02:29:32,885 - DEBUG - VM ctest-VM31-55325273 ID is af0fffdd-ac32-4717-98a8-a07043f32d96
2025-10-09 02:29:32,975 - DEBUG - VM is in ACTIVE state now
2025-10-09 02:29:32,975 - DEBUG - VM ctest-VM31-55325273 launched on Node an-jenkins-deploy-platform-ansible-os-4252-2
2025-10-09 02:29:33,056 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/af0fffdd-ac32-4717-98a8-a07043f32d96
2025-10-09 02:29:33,067 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/af0fffdd-ac32-4717-98a8-a07043f32d96
2025-10-09 02:29:33,076 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/37bce9ab-6bee-44b8-a8c4-d1bdad687cfe
2025-10-09 02:29:33,086 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/af0fffdd-ac32-4717-98a8-a07043f32d96
2025-10-09 02:29:33,093 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/37bce9ab-6bee-44b8-a8c4-d1bdad687cfe
2025-10-09 02:29:33,104 - DEBUG - Requesting: http://10.0.0.41:8082/instance-ip/e02d348a-7e80-486f-ae6e-afb9ad81b44a
2025-10-09 02:29:33,113 - DEBUG - Verifying in api server 10.0.0.41
2025-10-09 02:29:33,114 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/af0fffdd-ac32-4717-98a8-a07043f32d96
2025-10-09 02:29:33,121 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/37bce9ab-6bee-44b8-a8c4-d1bdad687cfe
2025-10-09 02:29:33,133 - DEBUG - Requesting: http://10.0.0.41:8082/instance-ip/e02d348a-7e80-486f-ae6e-afb9ad81b44a
2025-10-09 02:29:33,141 - INFO - VM ctest-VM31-55325273 verfication in all API Servers passed
2025-10-09 02:29:33,158 - DEBUG - VM ctest-VM31-55325273 Tap interface: {'index': '4', 'name': 'tap37bce9ab-6b', 'uuid': '37bce9ab-6bee-44b8-a8c4-d1bdad687cfe', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060:ctest-VN3-52887060', '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-01175662:ctest-VN3-52887060', 'vm_uuid': 'af0fffdd-ac32-4717-98a8-a07043f32d96', 'vm_name': 'ctest-VM31-55325273', 'ip_addr': '107.145.208.3', 'mac_addr': '02:37:bc:e9:ab:6b', '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-01175662:37bce9ab-6bee-44b8-a8c4-d1bdad687cfe', 'sg_uuid_list': ['942f1482-d1c3-4996-b039-9e925237ff80'], 'static_route_list': None, 'vm_project_uuid': '0b4d2178-9a44-4ef0-886b-5e0d37925db5', 'admin_state': 'Enabled', 'flow_key_idx': '40', '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': []}
2025-10-09 02:29:33,158 - DEBUG - Agent 10.0.0.27 vrf name: default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060:ctest-VN3-52887060
2025-10-09 02:29:33,162 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060:ctest-VN3-52887060', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060', 'table_label': '-1', 'vxlan_id': '16', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.192:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2025-10-09 02:29:33,239 - DEBUG - VM ctest-VM31-55325273 : Tap interface tap37bce9ab-6b is set to Active
2025-10-09 02:29:33,239 - DEBUG - Tap interface tap37bce9ab-6b detail : {'index': '4', 'name': 'tap37bce9ab-6b', 'uuid': '37bce9ab-6bee-44b8-a8c4-d1bdad687cfe', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060:ctest-VN3-52887060', '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-01175662:ctest-VN3-52887060', 'vm_uuid': 'af0fffdd-ac32-4717-98a8-a07043f32d96', 'vm_name': 'ctest-VM31-55325273', 'ip_addr': '107.145.208.3', 'mac_addr': '02:37:bc:e9:ab:6b', '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-01175662:37bce9ab-6bee-44b8-a8c4-d1bdad687cfe', 'sg_uuid_list': ['942f1482-d1c3-4996-b039-9e925237ff80'], 'static_route_list': None, 'vm_project_uuid': '0b4d2178-9a44-4ef0-886b-5e0d37925db5', 'admin_state': 'Enabled', 'flow_key_idx': '40', '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': []}
2025-10-09 02:29:33,351 - DEBUG - Starting Layer 2 verification in Agent
2025-10-09 02:29:33,361 - DEBUG - Layer 2 path is seen for VM MAC 02:37:bc:e9:ab:6b in agent 10.0.0.27
2025-10-09 02:29:33,361 - DEBUG - Active layer 2 route in agent is present for VMI tap37bce9ab-6b
2025-10-09 02:29:33,361 - DEBUG - L2 label(36) matches bw route table and interface table
2025-10-09 02:29:33,374 - DEBUG - VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060 is found in Agent of node 10.0.0.27
2025-10-09 02:29:33,505 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060 is consistent in agent 10.0.0.27
2025-10-09 02:29:33,505 - DEBUG - Route for VM IP ['107.145.208.3'] is consistent in agent 10.0.0.27
2025-10-09 02:29:33,505 - DEBUG - VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060 verification for VM ctest-VM31-55325273 in Agent 10.0.0.27 passed
2025-10-09 02:29:33,628 - DEBUG - Starting all layer 2 verification in agent 10.0.0.27
2025-10-09 02:29:33,639 - DEBUG - Route for VM MAC 02:37:bc:e9:ab:6b is consistent in agent 10.0.0.27
2025-10-09 02:29:34,815 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=6.66 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.539 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.539/3.600/6.662/3.061 ms')
2025-10-09 02:29:34,815 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-55325273 passed
2025-10-09 02:29:34,815 - INFO - VM ctest-VM31-55325273 verifications in Compute nodes passed
2025-10-09 02:29:35,026 - DEBUG - Validated VM route 107.145.208.3 in vrouter of 10.0.0.27
2025-10-09 02:29:35,031 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0
2025-10-09 02:29:35,031 - INFO - Validated routes of VM ctest-VM31-55325273 in all vrouters
2025-10-09 02:29:35,572 - DEBUG - Starting all layer2 verification in 10.0.0.19 Control Node
2025-10-09 02:29:35,696 - DEBUG - Layer2 route found for VM MAC 02:37:bc:e9:ab:6b in Control-node 10.0.0.19
2025-10-09 02:29:35,696 - DEBUG - L2 Label for VM ctest-VM31-55325273 same between Control-node 10.0.0.19 and Agent, Expected: 36, Seen: 36
2025-10-09 02:29:35,696 - DEBUG - Starting all layer2 verification in 10.0.0.56 Control Node
2025-10-09 02:29:35,822 - DEBUG - Layer2 route found for VM MAC 02:37:bc:e9:ab:6b in Control-node 10.0.0.56
2025-10-09 02:29:35,822 - DEBUG - L2 Label for VM ctest-VM31-55325273 same between Control-node 10.0.0.56 and Agent, Expected: 36, Seen: 36
2025-10-09 02:29:35,822 - INFO - Verification in Control-nodes for VM ctest-VM31-55325273 passed
2025-10-09 02:29:35,822 - DEBUG - Verifying the vm in opserver
2025-10-09 02:29:35,822 - DEBUG - Verifying in collector 10.0.0.41 ...
2025-10-09 02:29:35,822 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/af0fffdd-ac32-4717-98a8-a07043f32d96?flat
2025-10-09 02:29:35,840 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-01175662:37bce9ab-6bee-44b8-a8c4-d1bdad687cfe?flat
2025-10-09 02:29:35,861 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-01175662:37bce9ab-6bee-44b8-a8c4-d1bdad687cfe?flat
2025-10-09 02:29:35,879 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/af0fffdd-ac32-4717-98a8-a07043f32d96?flat
2025-10-09 02:29:35,898 - DEBUG - Verifying vm in vn uve
2025-10-09 02:29:35,898 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-01175662:37bce9ab-6bee-44b8-a8c4-d1bdad687cfe?flat
2025-10-09 02:29:35,917 - DEBUG - VM uve shows interface as {'vn_uuid': '0c08194a-f1c4-4318-8e60-beab8610b92f', 'label': 32, 'ip_address': '107.145.208.3', 'virtual_network': 'default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060', 'gateway': '107.145.208.1', 'ip6_active': False, 'is_health_check_active': True, 'tx_vlan': 65535, 'active': True, 'admin_state': True, 'port_mirror_enabled': False, 'vhostuser_mode': 0, 'fixed_ip4_list': ['107.145.208.3'], 'l2_active': True, 'uuid': '37bce9ab-6bee-44b8-a8c4-d1bdad687cfe', 'vm_uuid': 'af0fffdd-ac32-4717-98a8-a07043f32d96', 'rx_vlan': 65535, 'mac_address': '02:37:bc:e9:ab:6b', 'ip4_active': True, 'ip6_address': '::', 'vm_name': 'ctest-VM31-55325273', '__T': 1759976949724508}
2025-10-09 02:29:35,917 - DEBUG - VM uve shows ip address as ['107.145.208.3', '::']
2025-10-09 02:29:35,917 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060
2025-10-09 02:29:35,917 - INFO - Verifying through opserver in 10.0.0.56
2025-10-09 02:29:35,917 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060?flat
2025-10-09 02:29:35,938 - DEBUG - expected vm list af0fffdd-ac32-4717-98a8-a07043f32d96
2025-10-09 02:29:35,938 - DEBUG - Extracted vm list ['af0fffdd-ac32-4717-98a8-a07043f32d96']
2025-10-09 02:29:35,938 - INFO - VM af0fffdd-ac32-4717-98a8-a07043f32d96 is present in default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060
2025-10-09 02:29:35,938 - DEBUG - Verifying vm in vrouter uve
2025-10-09 02:29:35,938 - DEBUG - Getting info from collector 10.0.0.41..
2025-10-09 02:29:35,939 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/af0fffdd-ac32-4717-98a8-a07043f32d96?flat
2025-10-09 02:29:35,955 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4252-2']
2025-10-09 02:29:35,955 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-2:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:29:35,990 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-2?flat
2025-10-09 02:29:36,025 - DEBUG - VM af0fffdd-ac32-4717-98a8-a07043f32d96 is present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:29:36,025 - DEBUG - Validated that VM ctest-VM31-55325273 is in Vrouter an-jenkins-deploy-platform-ansible-os-4252-2 UVE
2025-10-09 02:29:36,025 - DEBUG - Verifying vm tap interface/vn in vrouter uve
2025-10-09 02:29:36,032 - DEBUG - Expected tap interface of VM uuid af0fffdd-ac32-4717-98a8-a07043f32d96 is default-domain:ctest-TestPolicyAcl-01175662:37bce9ab-6bee-44b8-a8c4-d1bdad687cfe
2025-10-09 02:29:36,032 - DEBUG - Expected VN of VM uuid af0fffdd-ac32-4717-98a8-a07043f32d96 is default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060
2025-10-09 02:29:36,032 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-2:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:29:36,068 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-2?flat
2025-10-09 02:29:36,099 - DEBUG - VM af0fffdd-ac32-4717-98a8-a07043f32d96 is present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:29:36,099 - INFO - tap interface default-domain:ctest-TestPolicyAcl-01175662:37bce9ab-6bee-44b8-a8c4-d1bdad687cfe of vm af0fffdd-ac32-4717-98a8-a07043f32d96 is present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:29:36,100 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060 of vm af0fffdd-ac32-4717-98a8-a07043f32d96 is present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:29:36,100 - DEBUG - Verifying the af0fffdd-ac32-4717-98a8-a07043f32d96 virtual network link through opserver 10.0.0.56
2025-10-09 02:29:36,100 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-machines
2025-10-09 02:29:36,112 - DEBUG - VM link and name as {'name': 'af0fffdd-ac32-4717-98a8-a07043f32d96', 'href': 'http://10.0.0.56:8081/analytics/uves/virtual-machine/af0fffdd-ac32-4717-98a8-a07043f32d96?flat'}
2025-10-09 02:29:36,112 - DEBUG - Verifying the af0fffdd-ac32-4717-98a8-a07043f32d96 virtual network link through opserver 10.0.0.19
2025-10-09 02:29:36,112 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-10-09 02:29:36,125 - DEBUG - VM link and name as {'name': 'af0fffdd-ac32-4717-98a8-a07043f32d96', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-machine/af0fffdd-ac32-4717-98a8-a07043f32d96?flat'}
2025-10-09 02:29:36,125 - DEBUG - Verifying the af0fffdd-ac32-4717-98a8-a07043f32d96 virtual network link through opserver 10.0.0.41
2025-10-09 02:29:36,125 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines
2025-10-09 02:29:36,138 - DEBUG - VM link and name as {'name': 'af0fffdd-ac32-4717-98a8-a07043f32d96', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-machine/af0fffdd-ac32-4717-98a8-a07043f32d96?flat'}
2025-10-09 02:29:36,138 - INFO - VM ctest-VM31-55325273 validations in Opserver passed
2025-10-09 02:29:36,138 - INFO - Waiting for VM ctest-VM11-11934870 to be up..
2025-10-09 02:29:36,138 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-09 02:29:36,138 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/c7e8a716-d4a6-4574-a159-236a64248524
2025-10-09 02:29:36,147 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/04dabded-bd3c-41cc-b851-8e215101026a
2025-10-09 02:29:37,311 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=1.39 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=6.01 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.391/3.701/6.011/2.310 ms')
2025-10-09 02:29:37,312 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-11934870 passed
2025-10-09 02:29:37,387 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-09 02:29:37,387 - DEBUG - Waiting to SSH to VM ctest-VM11-11934870, IP 47.82.173.3, Port 22
2025-10-09 02:29:37,456 - 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': ''}
2025-10-09 02:29:37,551 - DEBUG - VM ctest-VM11-11934870 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-09 02:29:42,552 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-09 02:29:42,552 - DEBUG - Waiting to SSH to VM ctest-VM11-11934870, IP 47.82.173.3, Port 22
2025-10-09 02:29:42,609 - 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': ''}
2025-10-09 02:29:42,705 - DEBUG - VM ctest-VM11-11934870 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-09 02:29:47,706 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-09 02:29:47,706 - DEBUG - Waiting to SSH to VM ctest-VM11-11934870, IP 47.82.173.3, Port 22
2025-10-09 02:29:47,865 - DEBUG - VM ctest-VM11-11934870 is ready for SSH connections
2025-10-09 02:29:47,865 - INFO - Waiting for VM ctest-VM21-85550288 to be up..
2025-10-09 02:29:47,865 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-09 02:29:47,865 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/5bb8c0dc-2fc4-4bf7-8754-fef68c416929
2025-10-09 02:29:47,875 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/a4372a46-12ca-4eab-abd7-542efad7c5d0
2025-10-09 02:29:49,030 - 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.07 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.786 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.786/1.426/2.066/0.640 ms')
2025-10-09 02:29:49,030 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-85550288 passed
2025-10-09 02:29:49,104 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-09 02:29:49,104 - DEBUG - Waiting to SSH to VM ctest-VM21-85550288, IP 131.71.145.195, Port 22
2025-10-09 02:29:49,275 - DEBUG - VM ctest-VM21-85550288 is ready for SSH connections
2025-10-09 02:29:49,275 - INFO - Waiting for VM ctest-VM31-55325273 to be up..
2025-10-09 02:29:49,275 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-09 02:29:49,276 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/af0fffdd-ac32-4717-98a8-a07043f32d96
2025-10-09 02:29:49,286 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/37bce9ab-6bee-44b8-a8c4-d1bdad687cfe
2025-10-09 02:29:50,454 - 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.02 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.28 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.280/2.151/3.022/0.871 ms')
2025-10-09 02:29:50,454 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-55325273 passed
2025-10-09 02:29:50,523 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-09 02:29:50,523 - DEBUG - Waiting to SSH to VM ctest-VM31-55325273, IP 107.145.208.3, Port 22
2025-10-09 02:29:50,592 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-09 02:29:50,672 - DEBUG - VM ctest-VM31-55325273 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-09 02:29:55,673 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-09 02:29:55,673 - DEBUG - Waiting to SSH to VM ctest-VM31-55325273, IP 107.145.208.3, Port 22
2025-10-09 02:29:55,833 - DEBUG - VM ctest-VM31-55325273 is ready for SSH connections
2025-10-09 02:29:55,833 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 131.71.145.195, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-10-09 02:29:55,833 - DEBUG - ping -s 56 -c 3 -W 1 131.71.145.195
2025-10-09 02:30:43,768 - DEBUG - PING 131.71.145.195 (131.71.145.195) 56(84) bytes of data.
64 bytes from 131.71.145.195: icmp_req=1 ttl=63 time=8.86 ms
64 bytes from 131.71.145.195: icmp_req=2 ttl=63 time=1.47 ms
64 bytes from 131.71.145.195: icmp_req=3 ttl=63 time=1.69 ms
--- 131.71.145.195 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.474/4.009/8.861/3.432 ms
2025-10-09 02:30:43,768 - INFO - Ping to IP 131.71.145.195 from VM ctest-VM11-11934870 passed
2025-10-09 02:30:43,768 - INFO - Test with src as VN and dst as policy PASSED
2025-10-09 02:30:43,768 - INFO - Deleting VM ctest-VM31-55325273
2025-10-09 02:30:43,836 - DEBUG - Verifying in api server 10.0.0.41
2025-10-09 02:30:43,836 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/af0fffdd-ac32-4717-98a8-a07043f32d96
2025-10-09 02:30:43,845 - DEBUG - VM ID af0fffdd-ac32-4717-98a8-a07043f32d96 of VM ctest-VM31-55325273 is still found in API Server
2025-10-09 02:30:45,845 - DEBUG - Verifying in api server 10.0.0.41
2025-10-09 02:30:45,846 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/af0fffdd-ac32-4717-98a8-a07043f32d96
2025-10-09 02:30:45,853 - DEBUG - Response Code: 404
2025-10-09 02:30:45,853 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/af0fffdd-ac32-4717-98a8-a07043f32d96
2025-10-09 02:30:45,860 - DEBUG - Response Code: 404
2025-10-09 02:30:45,860 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/af0fffdd-ac32-4717-98a8-a07043f32d96
2025-10-09 02:30:45,865 - DEBUG - Response Code: 404
2025-10-09 02:30:45,865 - INFO - VM ctest-VM31-55325273 is fully removed in API-Server
2025-10-09 02:30:46,018 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-10-09 02:30:46,062 - DEBUG - Validated that vrouter 10.0.0.22 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060
2025-10-09 02:30:46,074 - DEBUG - Validated that vrouter 10.0.0.27 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060
2025-10-09 02:30:46,074 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060
2025-10-09 02:30:46,074 - INFO - VM ctest-VM31-55325273 is removed in Compute, and routes are removed in all compute nodes
2025-10-09 02:30:46,304 - INFO - Routes for VM ctest-VM31-55325273 is removed in all control-nodes
2025-10-09 02:30:46,304 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-2:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:30:46,339 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-2?flat
2025-10-09 02:30:46,373 - DEBUG - VM af0fffdd-ac32-4717-98a8-a07043f32d96 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:30:46,373 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:30:46,373 - DEBUG - interface for vm af0fffdd-ac32-4717-98a8-a07043f32d96 deleted from vrouter uve ...
2025-10-09 02:30:46,373 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:30:49,373 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-2:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:30:49,409 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-2?flat
2025-10-09 02:30:49,439 - DEBUG - VM af0fffdd-ac32-4717-98a8-a07043f32d96 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:30:49,439 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:30:49,439 - DEBUG - interface for vm af0fffdd-ac32-4717-98a8-a07043f32d96 deleted from vrouter uve ...
2025-10-09 02:30:49,439 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:30:52,440 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-2:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:30:52,475 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-2?flat
2025-10-09 02:30:52,508 - DEBUG - VM af0fffdd-ac32-4717-98a8-a07043f32d96 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:30:52,508 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:30:52,508 - DEBUG - interface for vm af0fffdd-ac32-4717-98a8-a07043f32d96 deleted from vrouter uve ...
2025-10-09 02:30:52,508 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:30:55,509 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-2:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:30:55,543 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-2?flat
2025-10-09 02:30:55,575 - DEBUG - VM af0fffdd-ac32-4717-98a8-a07043f32d96 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:30:55,575 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:30:55,575 - DEBUG - interface for vm af0fffdd-ac32-4717-98a8-a07043f32d96 deleted from vrouter uve ...
2025-10-09 02:30:55,575 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:30:58,575 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-2:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:30:58,610 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-2?flat
2025-10-09 02:30:58,640 - DEBUG - VM af0fffdd-ac32-4717-98a8-a07043f32d96 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:30:58,640 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:30:58,640 - DEBUG - interface for vm af0fffdd-ac32-4717-98a8-a07043f32d96 deleted from vrouter uve ...
2025-10-09 02:30:58,640 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:31:01,640 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-2:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:31:01,679 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-2?flat
2025-10-09 02:31:01,715 - DEBUG - VM af0fffdd-ac32-4717-98a8-a07043f32d96 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:31:01,715 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:31:01,715 - DEBUG - interface for vm af0fffdd-ac32-4717-98a8-a07043f32d96 deleted from vrouter uve ...
2025-10-09 02:31:01,715 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:31:04,716 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-2:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:31:04,752 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-2?flat
2025-10-09 02:31:04,783 - DEBUG - VM af0fffdd-ac32-4717-98a8-a07043f32d96 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:31:04,783 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:31:04,783 - DEBUG - interface for vm af0fffdd-ac32-4717-98a8-a07043f32d96 deleted from vrouter uve ...
2025-10-09 02:31:04,783 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:31:07,784 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-2:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:31:07,819 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-2?flat
2025-10-09 02:31:07,856 - DEBUG - VM af0fffdd-ac32-4717-98a8-a07043f32d96 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:31:07,856 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:31:07,856 - DEBUG - interface for vm af0fffdd-ac32-4717-98a8-a07043f32d96 deleted from vrouter uve ...
2025-10-09 02:31:07,856 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:31:10,857 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-2:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:31:10,892 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-2?flat
2025-10-09 02:31:10,921 - DEBUG - VM af0fffdd-ac32-4717-98a8-a07043f32d96 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:31:10,921 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:31:10,921 - DEBUG - interface for vm af0fffdd-ac32-4717-98a8-a07043f32d96 deleted from vrouter uve ...
2025-10-09 02:31:10,921 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:31:10,921 - DEBUG - Validated that VM af0fffdd-ac32-4717-98a8-a07043f32d96 is removed in Vrouter UVE
2025-10-09 02:31:10,921 - DEBUG - Verifying through opserver in 10.0.0.56
2025-10-09 02:31:10,921 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060?flat
2025-10-09 02:31:10,938 - DEBUG - af0fffdd-ac32-4717-98a8-a07043f32d96 BM not in default-domain:ctest-TestPolicyAcl-01175662:ctest-VN3-52887060 uve
2025-10-09 02:31:10,938 - DEBUG - Validated that VM af0fffdd-ac32-4717-98a8-a07043f32d96 is not present in the VN UVE
2025-10-09 02:31:10,938 - DEBUG - Verifying the af0fffdd-ac32-4717-98a8-a07043f32d96 virtual network link through opserver 10.0.0.56
2025-10-09 02:31:10,939 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-machines
2025-10-09 02:31:10,956 - INFO - af0fffdd-ac32-4717-98a8-a07043f32d96 vm uve deleted from opserver
2025-10-09 02:31:10,956 - DEBUG - Verifying the af0fffdd-ac32-4717-98a8-a07043f32d96 virtual network link through opserver 10.0.0.19
2025-10-09 02:31:10,957 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-10-09 02:31:10,970 - INFO - af0fffdd-ac32-4717-98a8-a07043f32d96 vm uve deleted from opserver
2025-10-09 02:31:10,970 - DEBUG - Verifying the af0fffdd-ac32-4717-98a8-a07043f32d96 virtual network link through opserver 10.0.0.41
2025-10-09 02:31:10,970 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines
2025-10-09 02:31:10,986 - INFO - af0fffdd-ac32-4717-98a8-a07043f32d96 vm uve deleted from opserver
2025-10-09 02:31:10,986 - INFO - Validated that VM af0fffdd-ac32-4717-98a8-a07043f32d96 is removed from Opserver
2025-10-09 02:31:10,986 - INFO - Deleting VM ctest-VM21-85550288
2025-10-09 02:31:11,069 - DEBUG - Verifying in api server 10.0.0.41
2025-10-09 02:31:11,069 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/5bb8c0dc-2fc4-4bf7-8754-fef68c416929
2025-10-09 02:31:11,079 - DEBUG - VM ID 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 of VM ctest-VM21-85550288 is still found in API Server
2025-10-09 02:31:13,080 - DEBUG - Verifying in api server 10.0.0.41
2025-10-09 02:31:13,080 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/5bb8c0dc-2fc4-4bf7-8754-fef68c416929
2025-10-09 02:31:13,087 - DEBUG - Response Code: 404
2025-10-09 02:31:13,087 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/5bb8c0dc-2fc4-4bf7-8754-fef68c416929
2025-10-09 02:31:13,092 - DEBUG - Response Code: 404
2025-10-09 02:31:13,093 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/5bb8c0dc-2fc4-4bf7-8754-fef68c416929
2025-10-09 02:31:13,098 - DEBUG - Response Code: 404
2025-10-09 02:31:13,098 - INFO - VM ctest-VM21-85550288 is fully removed in API-Server
2025-10-09 02:31:13,249 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-10-09 02:31:13,282 - DEBUG - Validated that vrouter 10.0.0.22 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887
2025-10-09 02:31:13,291 - DEBUG - Validated that vrouter 10.0.0.27 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887
2025-10-09 02:31:13,291 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887
2025-10-09 02:31:13,291 - INFO - VM ctest-VM21-85550288 is removed in Compute, and routes are removed in all compute nodes
2025-10-09 02:31:13,491 - INFO - Routes for VM ctest-VM21-85550288 is removed in all control-nodes
2025-10-09 02:31:13,492 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-1:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:31:13,524 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-1?flat
2025-10-09 02:31:13,553 - DEBUG - VM 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4252-1 uve
2025-10-09 02:31:13,553 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:31:13,553 - DEBUG - interface for vm 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 deleted from vrouter uve ...
2025-10-09 02:31:13,554 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:31:16,554 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-1:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:31:16,595 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-1?flat
2025-10-09 02:31:16,632 - DEBUG - VM 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4252-1 uve
2025-10-09 02:31:16,632 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:31:16,632 - DEBUG - interface for vm 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 deleted from vrouter uve ...
2025-10-09 02:31:16,632 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:31:19,633 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-1:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:31:19,666 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-1?flat
2025-10-09 02:31:19,695 - DEBUG - VM 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4252-1 uve
2025-10-09 02:31:19,695 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:31:19,695 - DEBUG - interface for vm 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 deleted from vrouter uve ...
2025-10-09 02:31:19,695 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:31:22,696 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-1:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:31:22,727 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-1?flat
2025-10-09 02:31:22,761 - DEBUG - VM 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4252-1 uve
2025-10-09 02:31:22,761 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:31:22,761 - DEBUG - interface for vm 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 deleted from vrouter uve ...
2025-10-09 02:31:22,761 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:31:25,762 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-1:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:31:25,806 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-1?flat
2025-10-09 02:31:25,836 - DEBUG - VM 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4252-1 uve
2025-10-09 02:31:25,836 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:31:25,836 - DEBUG - interface for vm 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 deleted from vrouter uve ...
2025-10-09 02:31:25,836 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:31:28,837 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-1:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:31:28,870 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-1?flat
2025-10-09 02:31:28,898 - DEBUG - VM 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4252-1 uve
2025-10-09 02:31:28,898 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:31:28,898 - DEBUG - interface for vm 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 deleted from vrouter uve ...
2025-10-09 02:31:28,898 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:31:31,899 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-1:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:31:31,937 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-1?flat
2025-10-09 02:31:31,966 - DEBUG - VM 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4252-1 uve
2025-10-09 02:31:31,966 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:31:31,966 - DEBUG - interface for vm 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 deleted from vrouter uve ...
2025-10-09 02:31:31,966 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:31:34,967 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-1:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:31:35,000 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-1?flat
2025-10-09 02:31:35,033 - DEBUG - VM 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4252-1 uve
2025-10-09 02:31:35,033 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:31:35,033 - DEBUG - interface for vm 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 deleted from vrouter uve ...
2025-10-09 02:31:35,033 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:31:35,033 - DEBUG - Validated that VM 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is removed in Vrouter UVE
2025-10-09 02:31:35,033 - DEBUG - Verifying through opserver in 10.0.0.56
2025-10-09 02:31:35,033 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887?flat
2025-10-09 02:31:35,055 - DEBUG - 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 BM not in default-domain:ctest-TestPolicyAcl-01175662:ctest-VN2-97630887 uve
2025-10-09 02:31:35,055 - DEBUG - Validated that VM 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is not present in the VN UVE
2025-10-09 02:31:35,055 - DEBUG - Verifying the 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 virtual network link through opserver 10.0.0.56
2025-10-09 02:31:35,055 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-machines
2025-10-09 02:31:35,075 - INFO - 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 vm uve deleted from opserver
2025-10-09 02:31:35,075 - DEBUG - Verifying the 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 virtual network link through opserver 10.0.0.19
2025-10-09 02:31:35,075 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-10-09 02:31:35,088 - INFO - 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 vm uve deleted from opserver
2025-10-09 02:31:35,088 - DEBUG - Verifying the 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 virtual network link through opserver 10.0.0.41
2025-10-09 02:31:35,088 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines
2025-10-09 02:31:35,100 - INFO - 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 vm uve deleted from opserver
2025-10-09 02:31:35,101 - INFO - Validated that VM 5bb8c0dc-2fc4-4bf7-8754-fef68c416929 is removed from Opserver
2025-10-09 02:31:35,101 - INFO - Deleting VM ctest-VM11-11934870
2025-10-09 02:31:35,165 - DEBUG - Verifying in api server 10.0.0.41
2025-10-09 02:31:35,165 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/c7e8a716-d4a6-4574-a159-236a64248524
2025-10-09 02:31:35,175 - DEBUG - VM ID c7e8a716-d4a6-4574-a159-236a64248524 of VM ctest-VM11-11934870 is still found in API Server
2025-10-09 02:31:37,175 - DEBUG - Verifying in api server 10.0.0.41
2025-10-09 02:31:37,176 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/c7e8a716-d4a6-4574-a159-236a64248524
2025-10-09 02:31:37,184 - DEBUG - Response Code: 404
2025-10-09 02:31:37,185 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/c7e8a716-d4a6-4574-a159-236a64248524
2025-10-09 02:31:37,191 - DEBUG - Response Code: 404
2025-10-09 02:31:37,191 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/c7e8a716-d4a6-4574-a159-236a64248524
2025-10-09 02:31:37,197 - DEBUG - Response Code: 404
2025-10-09 02:31:37,197 - INFO - VM ctest-VM11-11934870 is fully removed in API-Server
2025-10-09 02:31:37,357 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found
2025-10-09 02:31:37,395 - DEBUG - Validated that vrouter 10.0.0.22 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463
2025-10-09 02:31:37,405 - DEBUG - Validated that vrouter 10.0.0.27 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463
2025-10-09 02:31:37,405 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463
2025-10-09 02:31:37,405 - INFO - VM ctest-VM11-11934870 is removed in Compute, and routes are removed in all compute nodes
2025-10-09 02:31:37,604 - INFO - Routes for VM ctest-VM11-11934870 is removed in all control-nodes
2025-10-09 02:31:37,604 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-2:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:31:37,641 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-2?flat
2025-10-09 02:31:37,674 - DEBUG - VM c7e8a716-d4a6-4574-a159-236a64248524 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:31:37,674 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:31:37,674 - DEBUG - interface for vm c7e8a716-d4a6-4574-a159-236a64248524 deleted from vrouter uve ...
2025-10-09 02:31:37,674 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:31:40,675 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4252-2:Compute:contrail-vrouter-agent:0?flat
2025-10-09 02:31:40,711 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4252-2?flat
2025-10-09 02:31:40,740 - DEBUG - VM c7e8a716-d4a6-4574-a159-236a64248524 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4252-2 uve
2025-10-09 02:31:40,740 - DEBUG - Verifying if the vm interface deleted from vroter uve...
2025-10-09 02:31:40,740 - DEBUG - interface for vm c7e8a716-d4a6-4574-a159-236a64248524 deleted from vrouter uve ...
2025-10-09 02:31:40,740 - DEBUG - Deleted interface not in error interface list ...
2025-10-09 02:31:40,740 - DEBUG - Validated that VM c7e8a716-d4a6-4574-a159-236a64248524 is removed in Vrouter UVE
2025-10-09 02:31:40,740 - DEBUG - Verifying through opserver in 10.0.0.56
2025-10-09 02:31:40,741 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463?flat
2025-10-09 02:31:40,760 - DEBUG - c7e8a716-d4a6-4574-a159-236a64248524 BM not in default-domain:ctest-TestPolicyAcl-01175662:ctest-VN1-23833463 uve
2025-10-09 02:31:40,760 - DEBUG - Validated that VM c7e8a716-d4a6-4574-a159-236a64248524 is not present in the VN UVE
2025-10-09 02:31:40,760 - DEBUG - Verifying the c7e8a716-d4a6-4574-a159-236a64248524 virtual network link through opserver 10.0.0.56
2025-10-09 02:31:40,760 - DEBUG - Requesting: http://10.0.0.56:8081/analytics/uves/virtual-machines
2025-10-09 02:31:40,771 - DEBUG - No links retuned for c7e8a716-d4a6-4574-a159-236a64248524
2025-10-09 02:31:40,771 - INFO - c7e8a716-d4a6-4574-a159-236a64248524 vm uve deleted from opserver
2025-10-09 02:31:40,771 - DEBUG - Verifying the c7e8a716-d4a6-4574-a159-236a64248524 virtual network link through opserver 10.0.0.19
2025-10-09 02:31:40,771 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines
2025-10-09 02:31:40,784 - DEBUG - No links retuned for c7e8a716-d4a6-4574-a159-236a64248524
2025-10-09 02:31:40,784 - INFO - c7e8a716-d4a6-4574-a159-236a64248524 vm uve deleted from opserver
2025-10-09 02:31:40,784 - DEBUG - Verifying the c7e8a716-d4a6-4574-a159-236a64248524 virtual network link through opserver 10.0.0.41
2025-10-09 02:31:40,784 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines
2025-10-09 02:31:40,797 - DEBUG - No links retuned for c7e8a716-d4a6-4574-a159-236a64248524
2025-10-09 02:31:40,799 - INFO - c7e8a716-d4a6-4574-a159-236a64248524 vm uve deleted from opserver
2025-10-09 02:31:40,799 - INFO - Validated that VM c7e8a716-d4a6-4574-a159-236a64248524 is removed from Opserver
2025-10-09 02:31:40,799 - DEBUG - Detaching the Policy for VN :ctest-VN2-97630887
2025-10-09 02:31:40,799 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:31:40,805 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 02:31:40,817 - DEBUG - Requesting: http://10.0.0.56:8082/project/0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:31:40,921 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6cce07d0-5e70-46bd-a53b-674be72e0747
2025-10-09 02:31:40,935 - DEBUG - Requesting: http://10.0.0.56:8082/network-policy/f8d4ab97-8251-4704-8403-08c12907125b
2025-10-09 02:31:41,096 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAcl-01175662', 'ctest-policy21-87561441']] from ctest-VN2-97630887
2025-10-09 02:31:41,096 - DEBUG - Detaching the Policy for VN :ctest-VN1-23833463
2025-10-09 02:31:41,096 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:31:41,124 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 02:31:41,135 - DEBUG - Requesting: http://10.0.0.56:8082/project/0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:31:41,221 - DEBUG - Requesting: http://10.0.0.56:8082/virtual-network/6b756ff9-fbf0-4379-ab79-4bddc563dd3a
2025-10-09 02:31:41,433 - INFO - Detached Policy from ctest-VN1-23833463
2025-10-09 02:31:41,508 - INFO - Deleted policy ctest-policy13-71214033
2025-10-09 02:31:41,583 - INFO - Deleted policy ctest-policy21-87561441
2025-10-09 02:31:41,627 - INFO - Deleted policy ctest-policy12-87735232
2025-10-09 02:31:41,627 - INFO - Deleting VN ctest-VN3-52887060
2025-10-09 02:31:41,821 - DEBUG - Response for deleting network ()
2025-10-09 02:31:41,821 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/0b658736-55b7-4063-80c4-2fcd7dba1627
2025-10-09 02:31:41,829 - DEBUG - Response Code: 404
2025-10-09 02:31:41,829 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:31:41,842 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 02:31:41,902 - DEBUG - Requesting: http://10.0.0.56:8082/project/0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:31:41,996 - INFO - Validated that VN ctest-VN3-52887060 is not found in API Server
2025-10-09 02:31:42,006 - DEBUG - VN ctest-VN3-52887060 is not present in Agent 10.0.0.22
2025-10-09 02:31:42,015 - DEBUG - VN ctest-VN3-52887060 is not present in Agent 10.0.0.27
2025-10-09 02:31:42,015 - INFO - Validated that VN ctest-VN3-52887060 is not in any agent
2025-10-09 02:31:42,048 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-52887060 info
2025-10-09 02:31:42,048 - INFO - Deleting VN ctest-VN2-97630887
2025-10-09 02:31:42,255 - DEBUG - Response for deleting network ()
2025-10-09 02:31:42,255 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/04b3ffdb-6083-470f-8fb5-fd926a601fd3
2025-10-09 02:31:42,261 - DEBUG - Response Code: 404
2025-10-09 02:31:42,261 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:31:42,266 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 02:31:42,277 - DEBUG - Requesting: http://10.0.0.56:8082/project/0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:31:42,365 - INFO - Validated that VN ctest-VN2-97630887 is not found in API Server
2025-10-09 02:31:42,381 - DEBUG - VN ctest-VN2-97630887 is not present in Agent 10.0.0.22
2025-10-09 02:31:42,390 - DEBUG - VN ctest-VN2-97630887 is not present in Agent 10.0.0.27
2025-10-09 02:31:42,390 - INFO - Validated that VN ctest-VN2-97630887 is not in any agent
2025-10-09 02:31:42,417 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-97630887 info
2025-10-09 02:31:42,417 - INFO - Deleting VN ctest-VN1-23833463
2025-10-09 02:31:42,609 - DEBUG - Response for deleting network ()
2025-10-09 02:31:42,609 - DEBUG - Requesting: http://10.0.0.56:8082/routing-instance/8a19d718-94d8-4634-b752-f721f5c4ed3a
2025-10-09 02:31:42,618 - DEBUG - Response Code: 404
2025-10-09 02:31:42,618 - DEBUG - Requesting: http://10.0.0.56:8082/domains
2025-10-09 02:31:42,623 - DEBUG - Requesting: http://10.0.0.56:8082/domain/7ed77c0b-e642-4c13-a404-7756503cf17d
2025-10-09 02:31:42,634 - DEBUG - Requesting: http://10.0.0.56:8082/project/0b4d2178-9a44-4ef0-886b-5e0d37925db5
2025-10-09 02:31:42,724 - INFO - Validated that VN ctest-VN1-23833463 is not found in API Server
2025-10-09 02:31:42,737 - DEBUG - VN ctest-VN1-23833463 is not present in Agent 10.0.0.22
2025-10-09 02:31:42,745 - DEBUG - VN ctest-VN1-23833463 is not present in Agent 10.0.0.27
2025-10-09 02:31:42,745 - INFO - Validated that VN ctest-VN1-23833463 is not in any agent
2025-10-09 02:31:42,773 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-23833463 info
2025-10-09 02:31:42,873 - INFO - IPAM: ipam3 is not found in API Server
2025-10-09 02:31:42,895 - INFO - IPAM:ipam3 is not found in control node
2025-10-09 02:31:43,033 - INFO - IPAM: ipam2 is not found in API Server
2025-10-09 02:31:43,058 - INFO - IPAM:ipam2 is not found in control node
2025-10-09 02:31:43,214 - INFO - IPAM: ipam1 is not found in API Server
2025-10-09 02:31:43,235 - INFO - IPAM:ipam1 is not found in control node
2025-10-09 02:31:44,496 - DEBUG - No XMPP flaps were noticed during the test
2025-10-09 02:31:44,496 - INFO - END TEST : test_policy_inheritance_src_vn_dst_pol : PASSED[0:03:12]
2025-10-09 02:31:44,496 - INFO - --------------------------------------------------------------------------------
2025-10-09 02:31:45,239 - INFO - Deleted project: ctest-TestPolicyAcl-01175662, ID : 0b4d2178-9a44-4ef0-886b-5e0d37925db5