2025-10-13 02:24:46,363 - INFO - Domain Default found not creating 2025-10-13 02:24:46,490 - INFO - Project ctest-TestPolicyAcl-56827226 not found, creating it 2025-10-13 02:24:46,966 - INFO - Created Project:ctest-TestPolicyAcl-56827226, ID : 225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:24:48,587 - INFO - ================================================================================ 2025-10-13 02:24:48,588 - INFO - STARTING TEST : test_policy_inheritance_src_vn_dst_pol 2025-10-13 02:24:48,588 - INFO - TEST DESCRIPTION : Test cases to test policy inheritance 2025-10-13 02:24:49,840 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.27': {'10.20.0.18': '0', '10.20.0.129': '0'}} with 2025-10-13 02:24:49,840 - INFO - Initial checks done. Running the testcase now 2025-10-13 02:24:49,840 - INFO - 2025-10-13 02:24:51,148 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:24:51,442 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:24:51,470 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c 2025-10-13 02:24:51,486 - DEBUG - Requesting: http://10.0.0.22:8082/project/225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:24:51,583 - DEBUG - Requesting: http://10.0.0.22:8082/network-ipam/a9e6fc57-6ba9-4174-8ad8-9386cd70cc67 2025-10-13 02:24:51,592 - INFO - Verifications in API Server for IPAM: ipam1 passed 2025-10-13 02:24:51,597 - DEBUG - Control-node 10.0.0.22 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56827226:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12242750089451880820', 'uuid-lslong': '10004908779385179239'}, 'enable': 'true', 'created': '2025-10-13T02:24:50', 'last-modified': '2025-10-13T02:24:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.130156'} 2025-10-13 02:24:51,601 - DEBUG - Control-node 10.0.0.19 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56827226:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12242750089451880820', 'uuid-lslong': '10004908779385179239'}, 'enable': 'true', 'created': '2025-10-13T02:24:50', 'last-modified': '2025-10-13T02:24:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.147378'} 2025-10-13 02:24:51,607 - DEBUG - Control-node 10.0.0.41 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56827226:ipam1', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12242750089451880820', 'uuid-lslong': '10004908779385179239'}, 'enable': 'true', 'created': '2025-10-13T02:24:50', 'last-modified': '2025-10-13T02:24:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.151953'} 2025-10-13 02:24:51,607 - INFO - Verifications in Control node for IPAM: ipam1 passed 2025-10-13 02:24:51,607 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:24:51,612 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c 2025-10-13 02:24:51,621 - DEBUG - Requesting: http://10.0.0.22:8082/project/225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:24:51,710 - DEBUG - Requesting: http://10.0.0.22:8082/network-ipam/bf578889-e63e-4935-ba9c-b3fbc557b8c0 2025-10-13 02:24:51,716 - INFO - Verifications in API Server for IPAM: ipam2 passed 2025-10-13 02:24:51,720 - DEBUG - Control-node 10.0.0.22 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56827226:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13787638910072801589', 'uuid-lslong': '13446820481350482112'}, 'enable': 'true', 'created': '2025-10-13T02:24:50', 'last-modified': '2025-10-13T02:24:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.938416'} 2025-10-13 02:24:51,726 - DEBUG - Control-node 10.0.0.19 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56827226:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13787638910072801589', 'uuid-lslong': '13446820481350482112'}, 'enable': 'true', 'created': '2025-10-13T02:24:50', 'last-modified': '2025-10-13T02:24:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.944019'} 2025-10-13 02:24:51,731 - DEBUG - Control-node 10.0.0.41 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56827226:ipam2', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13787638910072801589', 'uuid-lslong': '13446820481350482112'}, 'enable': 'true', 'created': '2025-10-13T02:24:50', 'last-modified': '2025-10-13T02:24:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.949055'} 2025-10-13 02:24:51,731 - INFO - Verifications in Control node for IPAM: ipam2 passed 2025-10-13 02:24:51,731 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:24:51,736 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c 2025-10-13 02:24:51,746 - DEBUG - Requesting: http://10.0.0.22:8082/project/225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:24:51,835 - DEBUG - Requesting: http://10.0.0.22:8082/network-ipam/9bb0c1c2-8bb4-4ba2-b55b-abbd55a9f534 2025-10-13 02:24:51,854 - INFO - Verifications in API Server for IPAM: ipam3 passed 2025-10-13 02:24:51,859 - DEBUG - Control-node 10.0.0.22 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56827226:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11218679713091570594', 'uuid-lslong': '13068227573419996468'}, 'enable': 'true', 'created': '2025-10-13T02:24:50', 'last-modified': '2025-10-13T02:24:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.739832'} 2025-10-13 02:24:51,864 - DEBUG - Control-node 10.0.0.19 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56827226:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11218679713091570594', 'uuid-lslong': '13068227573419996468'}, 'enable': 'true', 'created': '2025-10-13T02:24:50', 'last-modified': '2025-10-13T02:24:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.742750'} 2025-10-13 02:24:51,869 - DEBUG - Control-node 10.0.0.41 : IPAM object is : {'node_name': 'network-ipam:default-domain:ctest-TestPolicyAcl-56827226:ipam3', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11218679713091570594', 'uuid-lslong': '13068227573419996468'}, 'enable': 'true', 'created': '2025-10-13T02:24:50', 'last-modified': '2025-10-13T02:24:51', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.753658'} 2025-10-13 02:24:51,869 - INFO - Verifications in Control node for IPAM: ipam3 passed 2025-10-13 02:24:52,148 - DEBUG - Response for create_network : {'network': {'id': 'c0f8c094-04bd-4906-a426-3ac028d94a1c', 'name': 'ctest-VN1-19204817', 'tenant_id': '225e66974a334311bd72480fc04d1c57', 'project_id': '225e66974a334311bd72480fc04d1c57', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-56827226', 'ctest-VN1-19204817'], 'port_security_enabled': True, 'description': ''}} 2025-10-13 02:24:52,385 - DEBUG - Response for create_subnet : {'subnet': {'id': '72f9b6e7-00bd-4d23-8ef8-ee926ee36cfd', 'name': '', 'tenant_id': '225e66974a334311bd72480fc04d1c57', 'network_id': 'c0f8c094-04bd-4906-a426-3ac028d94a1c', 'ip_version': 4, 'cidr': '121.134.135.192/26', 'allocation_pools': [{'start': '121.134.135.194', 'end': '121.134.135.254'}], 'gateway_ip': '121.134.135.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '121.134.135.194', 'tags': [], 'project_id': '225e66974a334311bd72480fc04d1c57'}} 2025-10-13 02:24:52,404 - INFO - Created VN ctest-VN1-19204817 2025-10-13 02:24:52,417 - DEBUG - VN ctest-VN1-19204817 UUID is c0f8c094-04bd-4906-a426-3ac028d94a1c 2025-10-13 02:24:52,560 - DEBUG - Fetched VN: default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817(c0f8c094-04bd-4906-a426-3ac028d94a1c) with subnets ['121.134.135.192/26'] 2025-10-13 02:24:52,836 - DEBUG - Response for create_network : {'network': {'id': '19e813f3-7931-4110-825c-6188b557f6f1', 'name': 'ctest-VN2-46363676', 'tenant_id': '225e66974a334311bd72480fc04d1c57', 'project_id': '225e66974a334311bd72480fc04d1c57', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-56827226', 'ctest-VN2-46363676'], 'port_security_enabled': True, 'description': ''}} 2025-10-13 02:24:53,053 - DEBUG - Response for create_subnet : {'subnet': {'id': '19a679ef-9030-4e25-ba44-deda2d87d24e', 'name': '', 'tenant_id': '225e66974a334311bd72480fc04d1c57', 'network_id': '19e813f3-7931-4110-825c-6188b557f6f1', 'ip_version': 4, 'cidr': '28.182.194.0/26', 'allocation_pools': [{'start': '28.182.194.2', 'end': '28.182.194.62'}], 'gateway_ip': '28.182.194.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '28.182.194.2', 'tags': [], 'project_id': '225e66974a334311bd72480fc04d1c57'}} 2025-10-13 02:24:53,072 - INFO - Created VN ctest-VN2-46363676 2025-10-13 02:24:53,082 - DEBUG - VN ctest-VN2-46363676 UUID is 19e813f3-7931-4110-825c-6188b557f6f1 2025-10-13 02:24:53,511 - DEBUG - Response for create_network : {'network': {'id': '4aafe4f1-0342-47d0-9ebb-7195e657045b', 'name': 'ctest-VN3-64334383', 'tenant_id': '225e66974a334311bd72480fc04d1c57', 'project_id': '225e66974a334311bd72480fc04d1c57', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-56827226', 'ctest-VN3-64334383'], 'port_security_enabled': True, 'description': ''}} 2025-10-13 02:24:53,746 - DEBUG - Response for create_subnet : {'subnet': {'id': '0c8db93c-4ae2-4d52-8b82-9cd070af70cb', 'name': '', 'tenant_id': '225e66974a334311bd72480fc04d1c57', 'network_id': '4aafe4f1-0342-47d0-9ebb-7195e657045b', 'ip_version': 4, 'cidr': '14.255.217.0/26', 'allocation_pools': [{'start': '14.255.217.2', 'end': '14.255.217.62'}], 'gateway_ip': '14.255.217.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '14.255.217.2', 'tags': [], 'project_id': '225e66974a334311bd72480fc04d1c57'}} 2025-10-13 02:24:53,766 - INFO - Created VN ctest-VN3-64334383 2025-10-13 02:24:53,780 - DEBUG - VN ctest-VN3-64334383 UUID is 4aafe4f1-0342-47d0-9ebb-7195e657045b 2025-10-13 02:24:53,839 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:24:53,850 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c 2025-10-13 02:24:53,867 - DEBUG - Requesting: http://10.0.0.22:8082/project/225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:24:53,961 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c0f8c094-04bd-4906-a426-3ac028d94a1c 2025-10-13 02:24:53,972 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c0f8c094-04bd-4906-a426-3ac028d94a1c 2025-10-13 02:24:53,982 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e150dfbc-2a1d-4014-a0ef-188dc7c48cd6 2025-10-13 02:24:53,988 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e150dfbc-2a1d-4014-a0ef-188dc7c48cd6 2025-10-13 02:24:53,993 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/fc16a7d5-6ab8-412b-8e55-53d163ba3773 2025-10-13 02:24:53,998 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-13 02:24:53,998 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c0f8c094-04bd-4906-a426-3ac028d94a1c 2025-10-13 02:24:54,006 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e150dfbc-2a1d-4014-a0ef-188dc7c48cd6 2025-10-13 02:24:54,021 - INFO - Verified VN network id 13 for VN c0f8c094-04bd-4906-a426-3ac028d94a1c 2025-10-13 02:24:54,021 - INFO - Verifications in API Server for VN ctest-VN1-19204817 passed 2025-10-13 02:24:54,021 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c0f8c094-04bd-4906-a426-3ac028d94a1c 2025-10-13 02:24:54,028 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e150dfbc-2a1d-4014-a0ef-188dc7c48cd6 2025-10-13 02:24:54,035 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e150dfbc-2a1d-4014-a0ef-188dc7c48cd6 2025-10-13 02:24:54,042 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/fc16a7d5-6ab8-412b-8e55-53d163ba3773 2025-10-13 02:24:54,051 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13905075591473613062', 'uuid-lslong': '11828206068328647196'}, 'enable': 'true', 'created': '2025-10-13T02:24:52', 'last-modified': '2025-10-13T02:24:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.718244'} 2025-10-13 02:24:54,056 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-13 02:24:54,062 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13905075591473613062', 'uuid-lslong': '11828206068328647196'}, 'enable': 'true', 'created': '2025-10-13T02:24:52', 'last-modified': '2025-10-13T02:24:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.733218'} 2025-10-13 02:24:54,067 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-13 02:24:54,072 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13905075591473613062', 'uuid-lslong': '11828206068328647196'}, 'enable': 'true', 'created': '2025-10-13T02:24:52', 'last-modified': '2025-10-13T02:24:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.741877'} 2025-10-13 02:24:54,077 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-13 02:24:54,077 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN1-19204817 passed 2025-10-13 02:24:54,077 - DEBUG - ====Verifying policy data for ctest-VN1-19204817 in API_Server ====== 2025-10-13 02:24:54,077 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:24:54,083 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c 2025-10-13 02:24:54,093 - DEBUG - Requesting: http://10.0.0.22:8082/project/225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:24:54,191 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c0f8c094-04bd-4906-a426-3ac028d94a1c 2025-10-13 02:24:54,200 - DEBUG - =>VN ctest-VN1-19204817 has no policy to be verified 2025-10-13 02:24:54,200 - DEBUG - Verifying the vn in opserver 2025-10-13 02:24:54,201 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 virtual network link through opserver 10.0.0.22 2025-10-13 02:24:54,201 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-10-13 02:24:54,494 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-10-13 02:24:54,547 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817?flat'} 2025-10-13 02:24:54,547 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 virtual network link through opserver 10.0.0.19 2025-10-13 02:24:54,547 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2025-10-13 02:24:54,858 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2025-10-13 02:24:54,949 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817?flat'} 2025-10-13 02:24:54,949 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 virtual network link through opserver 10.0.0.41 2025-10-13 02:24:54,949 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks 2025-10-13 02:24:55,255 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks 2025-10-13 02:24:55,308 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817?flat'} 2025-10-13 02:24:55,308 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 is found in opserver 2025-10-13 02:24:55,326 - DEBUG - Do not have enough data to verify VN in agent 2025-10-13 02:24:55,334 - DEBUG - VRF ids for VN ctest-VN1-19204817: {} 2025-10-13 02:24:55,334 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:24:55,340 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c 2025-10-13 02:24:55,352 - DEBUG - Requesting: http://10.0.0.22:8082/project/225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:24:55,441 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/19e813f3-7931-4110-825c-6188b557f6f1 2025-10-13 02:24:55,452 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/19e813f3-7931-4110-825c-6188b557f6f1 2025-10-13 02:24:55,460 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/07f663cd-f36f-4d61-8982-0b5b360e3566 2025-10-13 02:24:55,465 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/07f663cd-f36f-4d61-8982-0b5b360e3566 2025-10-13 02:24:55,471 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/26e62536-b4b1-4551-ac13-1b60b82a1493 2025-10-13 02:24:55,477 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-10-13 02:24:55,477 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/19e813f3-7931-4110-825c-6188b557f6f1 2025-10-13 02:24:55,487 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/07f663cd-f36f-4d61-8982-0b5b360e3566 2025-10-13 02:24:55,513 - INFO - Verified VN network id 14 for VN 19e813f3-7931-4110-825c-6188b557f6f1 2025-10-13 02:24:55,513 - INFO - Verifications in API Server for VN ctest-VN2-46363676 passed 2025-10-13 02:24:55,513 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/19e813f3-7931-4110-825c-6188b557f6f1 2025-10-13 02:24:55,525 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/07f663cd-f36f-4d61-8982-0b5b360e3566 2025-10-13 02:24:55,533 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/07f663cd-f36f-4d61-8982-0b5b360e3566 2025-10-13 02:24:55,543 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/26e62536-b4b1-4551-ac13-1b60b82a1493 2025-10-13 02:24:55,555 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1866763981976322320', 'uuid-lslong': '9393490162573899505'}, 'enable': 'true', 'created': '2025-10-13T02:24:52', 'last-modified': '2025-10-13T02:24:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.545930'} 2025-10-13 02:24:55,562 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-10-13 02:24:55,569 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1866763981976322320', 'uuid-lslong': '9393490162573899505'}, 'enable': 'true', 'created': '2025-10-13T02:24:52', 'last-modified': '2025-10-13T02:24:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.560746'} 2025-10-13 02:24:55,576 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-10-13 02:24:55,583 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1866763981976322320', 'uuid-lslong': '9393490162573899505'}, 'enable': 'true', 'created': '2025-10-13T02:24:52', 'last-modified': '2025-10-13T02:24:52', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.572305'} 2025-10-13 02:24:55,588 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-10-13 02:24:55,588 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN2-46363676 passed 2025-10-13 02:24:55,589 - DEBUG - ====Verifying policy data for ctest-VN2-46363676 in API_Server ====== 2025-10-13 02:24:55,589 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:24:55,597 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c 2025-10-13 02:24:55,613 - DEBUG - Requesting: http://10.0.0.22:8082/project/225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:24:55,710 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/19e813f3-7931-4110-825c-6188b557f6f1 2025-10-13 02:24:55,720 - DEBUG - =>VN ctest-VN2-46363676 has no policy to be verified 2025-10-13 02:24:55,720 - DEBUG - Verifying the vn in opserver 2025-10-13 02:24:55,720 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676 virtual network link through opserver 10.0.0.22 2025-10-13 02:24:55,720 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-10-13 02:24:55,784 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676?flat'} 2025-10-13 02:24:55,784 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676 virtual network link through opserver 10.0.0.19 2025-10-13 02:24:55,785 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2025-10-13 02:24:55,855 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676?flat'} 2025-10-13 02:24:55,855 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676 virtual network link through opserver 10.0.0.41 2025-10-13 02:24:55,855 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks 2025-10-13 02:24:55,921 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676?flat'} 2025-10-13 02:24:55,921 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676 is found in opserver 2025-10-13 02:24:55,954 - DEBUG - Do not have enough data to verify VN in agent 2025-10-13 02:24:55,962 - DEBUG - VRF ids for VN ctest-VN2-46363676: {} 2025-10-13 02:24:55,962 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:24:55,968 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c 2025-10-13 02:24:55,979 - DEBUG - Requesting: http://10.0.0.22:8082/project/225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:24:56,063 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4aafe4f1-0342-47d0-9ebb-7195e657045b 2025-10-13 02:24:56,075 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4aafe4f1-0342-47d0-9ebb-7195e657045b 2025-10-13 02:24:56,084 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/742cc23c-b907-49d8-95fe-dcfaa1f03118 2025-10-13 02:24:56,091 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/742cc23c-b907-49d8-95fe-dcfaa1f03118 2025-10-13 02:24:56,099 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/6d9827a3-fd70-4055-bb91-ad5153624912 2025-10-13 02:24:56,108 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-13 02:24:56,108 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4aafe4f1-0342-47d0-9ebb-7195e657045b 2025-10-13 02:24:56,120 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/742cc23c-b907-49d8-95fe-dcfaa1f03118 2025-10-13 02:24:56,137 - INFO - Verified VN network id 15 for VN 4aafe4f1-0342-47d0-9ebb-7195e657045b 2025-10-13 02:24:56,138 - INFO - Verifications in API Server for VN ctest-VN3-64334383 passed 2025-10-13 02:24:56,138 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4aafe4f1-0342-47d0-9ebb-7195e657045b 2025-10-13 02:24:56,147 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/742cc23c-b907-49d8-95fe-dcfaa1f03118 2025-10-13 02:24:56,154 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/742cc23c-b907-49d8-95fe-dcfaa1f03118 2025-10-13 02:24:56,161 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/6d9827a3-fd70-4055-bb91-ad5153624912 2025-10-13 02:24:56,173 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5381771803523958736', 'uuid-lslong': '11437860567266034779'}, 'enable': 'true', 'created': '2025-10-13T02:24:53', 'last-modified': '2025-10-13T02:24:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.490061'} 2025-10-13 02:24:56,179 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-13 02:24:56,185 - DEBUG - Control-node 10.0.0.19 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5381771803523958736', 'uuid-lslong': '11437860567266034779'}, 'enable': 'true', 'created': '2025-10-13T02:24:53', 'last-modified': '2025-10-13T02:24:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.501632'} 2025-10-13 02:24:56,191 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-13 02:24:56,197 - DEBUG - Control-node 10.0.0.41 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestPolicyAcl-56827226', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5381771803523958736', 'uuid-lslong': '11437860567266034779'}, 'enable': 'true', 'created': '2025-10-13T02:24:53', 'last-modified': '2025-10-13T02:24:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:02.513548'} 2025-10-13 02:24:56,202 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-13 02:24:56,202 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-VN3-64334383 passed 2025-10-13 02:24:56,202 - DEBUG - ====Verifying policy data for ctest-VN3-64334383 in API_Server ====== 2025-10-13 02:24:56,202 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:24:56,208 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c 2025-10-13 02:24:56,218 - DEBUG - Requesting: http://10.0.0.22:8082/project/225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:24:56,307 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/4aafe4f1-0342-47d0-9ebb-7195e657045b 2025-10-13 02:24:56,317 - DEBUG - =>VN ctest-VN3-64334383 has no policy to be verified 2025-10-13 02:24:56,317 - DEBUG - Verifying the vn in opserver 2025-10-13 02:24:56,317 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383 virtual network link through opserver 10.0.0.22 2025-10-13 02:24:56,317 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks 2025-10-13 02:24:56,367 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383?flat'} 2025-10-13 02:24:56,367 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383 virtual network link through opserver 10.0.0.19 2025-10-13 02:24:56,367 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-networks 2025-10-13 02:24:56,424 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383?flat'} 2025-10-13 02:24:56,424 - DEBUG - Verifying the default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383 virtual network link through opserver 10.0.0.41 2025-10-13 02:24:56,424 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-networks 2025-10-13 02:24:56,474 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383?flat'} 2025-10-13 02:24:56,474 - INFO - Validated that VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383 is found in opserver 2025-10-13 02:24:56,493 - DEBUG - Do not have enough data to verify VN in agent 2025-10-13 02:24:56,504 - DEBUG - VRF ids for VN ctest-VN3-64334383: {} 2025-10-13 02:24:56,510 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817, 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-56827226:ctest-VN2-46363676, 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-13 02:24:56,782 - DEBUG - No destination network defined 2025-10-13 02:24:56,782 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = icmp, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676, 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-56827226:ctest-policy13-94823132, 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-13 02:24:57,044 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817, 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-56827226:ctest-VN3-64334383, 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-13 02:24:57,256 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:24:57,262 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c 2025-10-13 02:24:57,273 - DEBUG - Requesting: http://10.0.0.22:8082/project/225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:24:57,367 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c0f8c094-04bd-4906-a426-3ac028d94a1c 2025-10-13 02:24:57,379 - DEBUG - Setup step: Associating the policy to VN' 2025-10-13 02:24:57,540 - INFO - Associated Policy to ctest-VN1-19204817 2025-10-13 02:24:57,540 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:24:57,558 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c 2025-10-13 02:24:57,574 - DEBUG - Requesting: http://10.0.0.22:8082/project/225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:24:57,657 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/19e813f3-7931-4110-825c-6188b557f6f1 2025-10-13 02:24:57,667 - DEBUG - Setup step: Associating the policy to VN' 2025-10-13 02:24:57,763 - DEBUG - Response for mapping policy(s) with vn {'network': {'id': '19e813f3-7931-4110-825c-6188b557f6f1', 'name': 'ctest-VN2-46363676', 'tenant_id': '225e66974a334311bd72480fc04d1c57', 'project_id': '225e66974a334311bd72480fc04d1c57', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': ['19a679ef-9030-4e25-ba44-deda2d87d24e'], 'fq_name': ['default-domain', 'ctest-TestPolicyAcl-56827226', 'ctest-VN2-46363676'], 'policys': [['default-domain', 'ctest-TestPolicyAcl-56827226', 'ctest-policy21-86286567']], 'subnet_ipam': [{'subnet_cidr': '28.182.194.0/26', 'ipam_fq_name': ['default-domain', 'ctest-TestPolicyAcl-56827226', 'ipam2']}], 'port_security_enabled': True, 'description': ''}} 2025-10-13 02:24:57,850 - DEBUG - Associated Policy:[['default-domain', 'ctest-TestPolicyAcl-56827226', 'ctest-policy21-86286567']] to ctest-VN2-46363676 2025-10-13 02:24:57,952 - DEBUG - Services list from nova: [, , , ] 2025-10-13 02:24:58,942 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4296-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4296-1) 2025-10-13 02:25:00,200 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4296-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4296-2) 2025-10-13 02:25:01,543 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4296-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4296-1) 2025-10-13 02:25:01,639 - DEBUG - VM is in ACTIVE state now 2025-10-13 02:25:01,640 - INFO - VM name : ctest-VM11-85646793 2025-10-13 02:25:01,723 - DEBUG - VM ctest-VM11-85646793 ID is 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 2025-10-13 02:25:01,813 - DEBUG - VM is in ACTIVE state now 2025-10-13 02:25:01,838 - DEBUG - VM ctest-VM11-85646793 launched on Node an-jenkins-deploy-platform-ansible-os-4296-1 2025-10-13 02:25:01,963 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/76d2ca90-576f-4e04-bccf-9c0a636d4aa8 2025-10-13 02:25:02,370 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/76d2ca90-576f-4e04-bccf-9c0a636d4aa8 2025-10-13 02:25:02,418 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/76d2ca90-576f-4e04-bccf-9c0a636d4aa8 2025-10-13 02:25:02,429 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/960ce36a-f8bf-429a-924b-06d7d81ecf2f 2025-10-13 02:25:02,439 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/76d2ca90-576f-4e04-bccf-9c0a636d4aa8 2025-10-13 02:25:02,449 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/960ce36a-f8bf-429a-924b-06d7d81ecf2f 2025-10-13 02:25:02,467 - DEBUG - Requesting: http://10.0.0.41:8082/instance-ip/5a0ddc3d-5fb4-4c66-a983-009fe974c83a 2025-10-13 02:25:02,483 - DEBUG - Verifying in api server 10.0.0.41 2025-10-13 02:25:02,483 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/76d2ca90-576f-4e04-bccf-9c0a636d4aa8 2025-10-13 02:25:02,494 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/960ce36a-f8bf-429a-924b-06d7d81ecf2f 2025-10-13 02:25:02,506 - DEBUG - Requesting: http://10.0.0.41:8082/instance-ip/5a0ddc3d-5fb4-4c66-a983-009fe974c83a 2025-10-13 02:25:02,516 - INFO - VM ctest-VM11-85646793 verfication in all API Servers passed 2025-10-13 02:25:02,532 - DEBUG - VM ctest-VM11-85646793 Tap interface: {'index': '3', 'name': 'tap960ce36a-f8', 'uuid': '960ce36a-f8bf-429a-924b-06d7d81ecf2f', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817:ctest-VN1-19204817', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '13', 'vn_name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817', 'vm_uuid': '76d2ca90-576f-4e04-bccf-9c0a636d4aa8', 'vm_name': 'ctest-VM11-85646793', 'ip_addr': '121.134.135.195', 'mac_addr': '02:96:0c:e3:6a:f8', '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-56827226:960ce36a-f8bf-429a-924b-06d7d81ecf2f', 'sg_uuid_list': ['cfb18e31-f28a-4824-89d2-4a14edf64678'], 'static_route_list': None, 'vm_project_uuid': '225e6697-4a33-4311-bd72-480fc04d1c57', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-13 02:25:02,532 - DEBUG - Agent 10.0.0.249 vrf name: default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817:ctest-VN1-19204817 2025-10-13 02:25:02,538 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817:ctest-VN1-19204817', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817', 'table_label': '-1', 'vxlan_id': '13', '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-13 02:25:02,627 - DEBUG - VM ctest-VM11-85646793 : Tap interface tap960ce36a-f8 is set to Active 2025-10-13 02:25:02,628 - DEBUG - Tap interface tap960ce36a-f8 detail : {'index': '3', 'name': 'tap960ce36a-f8', 'uuid': '960ce36a-f8bf-429a-924b-06d7d81ecf2f', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817:ctest-VN1-19204817', 'active': 'Active', 'ipv4_active': 'Active', 'l2_active': 'L2 Active', 'ip6_active': 'Ipv6 Inactive < no-ipv6-addr >', 'health_check_active': 'Active', 'dhcp_service': 'Enable', 'dhcp_service_v6': 'Disable', 'dns_service': 'Enable', 'type': 'vport', 'label': '25', 'l2_label': '29', 'vxlan_id': '13', 'vn_name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817', 'vm_uuid': '76d2ca90-576f-4e04-bccf-9c0a636d4aa8', 'vm_name': 'ctest-VM11-85646793', 'ip_addr': '121.134.135.195', 'mac_addr': '02:96:0c:e3:6a:f8', '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-56827226:960ce36a-f8bf-429a-924b-06d7d81ecf2f', 'sg_uuid_list': ['cfb18e31-f28a-4824-89d2-4a14edf64678'], 'static_route_list': None, 'vm_project_uuid': '225e6697-4a33-4311-bd72-480fc04d1c57', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-13 02:25:02,741 - DEBUG - Starting Layer 2 verification in Agent 2025-10-13 02:25:02,758 - DEBUG - Layer 2 path is seen for VM MAC 02:96:0c:e3:6a:f8 in agent 10.0.0.249 2025-10-13 02:25:02,758 - DEBUG - Active layer 2 route in agent is present for VMI tap960ce36a-f8 2025-10-13 02:25:02,758 - DEBUG - L2 label(29) matches bw route table and interface table 2025-10-13 02:25:02,808 - DEBUG - VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 is found in Agent of node 10.0.0.249 2025-10-13 02:25:02,952 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 is consistent in agent 10.0.0.249 2025-10-13 02:25:02,952 - DEBUG - Route for VM IP ['121.134.135.195'] is consistent in agent 10.0.0.249 2025-10-13 02:25:02,952 - DEBUG - VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 verification for VM ctest-VM11-85646793 in Agent 10.0.0.249 passed 2025-10-13 02:25:03,069 - DEBUG - Starting all layer 2 verification in agent 10.0.0.249 2025-10-13 02:25:03,084 - DEBUG - Route for VM MAC 02:96:0c:e3:6a:f8 is consistent in agent 10.0.0.249 2025-10-13 02:25:06,268 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2025-10-13 02:25:06,268 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-85646793 failed! 2025-10-13 02:25:06,282 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 is 121.134.135.193 and allocation pool is NOT set 2025-10-13 02:25:10,363 - 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-13 02:25:10,363 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-85646793 failed! 2025-10-13 02:25:10,380 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 is 121.134.135.193 and allocation pool is NOT set 2025-10-13 02:25:14,468 - 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-13 02:25:14,469 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-85646793 failed! 2025-10-13 02:25:14,483 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 is 121.134.135.193 and allocation pool is NOT set 2025-10-13 02:25:18,557 - 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-13 02:25:18,557 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-85646793 failed! 2025-10-13 02:25:18,572 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 is 121.134.135.193 and allocation pool is NOT set 2025-10-13 02:25:22,654 - 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-13 02:25:22,654 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-85646793 failed! 2025-10-13 02:25:22,667 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 is 121.134.135.193 and allocation pool is NOT set 2025-10-13 02:25:26,758 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1027ms') 2025-10-13 02:25:26,758 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-85646793 failed! 2025-10-13 02:25:26,773 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 is 121.134.135.193 and allocation pool is NOT set 2025-10-13 02:25:30,850 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-10-13 02:25:30,850 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-85646793 failed! 2025-10-13 02:25:30,863 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 is 121.134.135.193 and allocation pool is NOT set 2025-10-13 02:25:34,938 - 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-13 02:25:34,938 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-85646793 failed! 2025-10-13 02:25:34,951 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 is 121.134.135.193 and allocation pool is NOT set 2025-10-13 02:25:39,044 - 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 1030ms') 2025-10-13 02:25:39,044 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-85646793 failed! 2025-10-13 02:25:39,059 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 is 121.134.135.193 and allocation pool is NOT set 2025-10-13 02:25:43,130 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2025-10-13 02:25:43,130 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-85646793 failed! 2025-10-13 02:25:43,147 - DEBUG - Gateway for vn default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 is 121.134.135.193 and allocation pool is NOT set 2025-10-13 02:25:45,217 - 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=7.01 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=5.71 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 = 5.707/6.357/7.007/0.650 ms') 2025-10-13 02:25:45,217 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-85646793 passed 2025-10-13 02:25:45,217 - INFO - VM ctest-VM11-85646793 verifications in Compute nodes passed 2025-10-13 02:25:45,456 - DEBUG - Validated VM route 121.134.135.195 in vrouter of 10.0.0.249 2025-10-13 02:25:45,463 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-10-13 02:25:45,463 - INFO - Validated routes of VM ctest-VM11-85646793 in all vrouters 2025-10-13 02:25:45,904 - DEBUG - Starting all layer2 verification in 10.0.0.19 Control Node 2025-10-13 02:25:46,026 - DEBUG - Layer2 route found for VM MAC 02:96:0c:e3:6a:f8 in Control-node 10.0.0.19 2025-10-13 02:25:46,026 - DEBUG - L2 Label for VM ctest-VM11-85646793 same between Control-node 10.0.0.19 and Agent, Expected: 29, Seen: 29 2025-10-13 02:25:46,026 - DEBUG - Starting all layer2 verification in 10.0.0.41 Control Node 2025-10-13 02:25:46,147 - DEBUG - Layer2 route found for VM MAC 02:96:0c:e3:6a:f8 in Control-node 10.0.0.41 2025-10-13 02:25:46,147 - DEBUG - L2 Label for VM ctest-VM11-85646793 same between Control-node 10.0.0.41 and Agent, Expected: 29, Seen: 29 2025-10-13 02:25:46,147 - INFO - Verification in Control-nodes for VM ctest-VM11-85646793 passed 2025-10-13 02:25:46,147 - DEBUG - Verifying the vm in opserver 2025-10-13 02:25:46,147 - DEBUG - Verifying in collector 10.0.0.41 ... 2025-10-13 02:25:46,147 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/76d2ca90-576f-4e04-bccf-9c0a636d4aa8?flat 2025-10-13 02:25:46,161 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56827226:960ce36a-f8bf-429a-924b-06d7d81ecf2f?flat 2025-10-13 02:25:46,178 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56827226:960ce36a-f8bf-429a-924b-06d7d81ecf2f?flat 2025-10-13 02:25:46,193 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/76d2ca90-576f-4e04-bccf-9c0a636d4aa8?flat 2025-10-13 02:25:46,207 - DEBUG - Verifying vm in vn uve 2025-10-13 02:25:46,207 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56827226:960ce36a-f8bf-429a-924b-06d7d81ecf2f?flat 2025-10-13 02:25:46,224 - DEBUG - VM uve shows interface as {'admin_state': True, 'active': True, 'label': 25, 'ip4_active': True, 'uuid': '960ce36a-f8bf-429a-924b-06d7d81ecf2f', 'fixed_ip4_list': ['121.134.135.195'], 'gateway': '121.134.135.193', 'vhostuser_mode': 0, 'tx_vlan': 65535, 'mac_address': '02:96:0c:e3:6a:f8', 'port_mirror_enabled': False, 'vn_uuid': 'c0f8c094-04bd-4906-a426-3ac028d94a1c', 'vm_uuid': '76d2ca90-576f-4e04-bccf-9c0a636d4aa8', 'is_health_check_active': True, 'vm_name': 'ctest-VM11-85646793', 'ip6_active': False, 'virtual_network': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817', 'rx_vlan': 65535, 'ip6_address': '::', '__T': 1760322326079029, 'ip_address': '121.134.135.195', 'l2_active': True} 2025-10-13 02:25:46,224 - DEBUG - VM uve shows ip address as ['121.134.135.195', '::'] 2025-10-13 02:25:46,224 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 2025-10-13 02:25:46,224 - INFO - Verifying through opserver in 10.0.0.22 2025-10-13 02:25:46,224 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817?flat 2025-10-13 02:25:46,243 - DEBUG - expected vm list 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 2025-10-13 02:25:46,243 - DEBUG - Extracted vm list ['76d2ca90-576f-4e04-bccf-9c0a636d4aa8'] 2025-10-13 02:25:46,243 - INFO - VM 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is present in default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 2025-10-13 02:25:46,243 - DEBUG - Verifying vm in vrouter uve 2025-10-13 02:25:46,243 - DEBUG - Getting info from collector 10.0.0.41.. 2025-10-13 02:25:46,244 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/76d2ca90-576f-4e04-bccf-9c0a636d4aa8?flat 2025-10-13 02:25:46,259 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4296-1'] 2025-10-13 02:25:46,259 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:25:46,288 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:25:46,318 - DEBUG - VM 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:25:46,318 - DEBUG - Validated that VM ctest-VM11-85646793 is in Vrouter an-jenkins-deploy-platform-ansible-os-4296-1 UVE 2025-10-13 02:25:46,318 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-10-13 02:25:46,330 - DEBUG - Expected tap interface of VM uuid 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is default-domain:ctest-TestPolicyAcl-56827226:960ce36a-f8bf-429a-924b-06d7d81ecf2f 2025-10-13 02:25:46,330 - DEBUG - Expected VN of VM uuid 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 2025-10-13 02:25:46,330 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:25:46,363 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:25:46,397 - DEBUG - VM 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:25:46,397 - INFO - tap interface default-domain:ctest-TestPolicyAcl-56827226:960ce36a-f8bf-429a-924b-06d7d81ecf2f of vm 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:25:46,397 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 of vm 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:25:46,397 - DEBUG - Verifying the 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 virtual network link through opserver 10.0.0.22 2025-10-13 02:25:46,397 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines 2025-10-13 02:25:46,409 - DEBUG - VM link and name as {'name': '76d2ca90-576f-4e04-bccf-9c0a636d4aa8', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-machine/76d2ca90-576f-4e04-bccf-9c0a636d4aa8?flat'} 2025-10-13 02:25:46,409 - DEBUG - Verifying the 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 virtual network link through opserver 10.0.0.19 2025-10-13 02:25:46,409 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines 2025-10-13 02:25:46,423 - DEBUG - VM link and name as {'name': '76d2ca90-576f-4e04-bccf-9c0a636d4aa8', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-machine/76d2ca90-576f-4e04-bccf-9c0a636d4aa8?flat'} 2025-10-13 02:25:46,423 - DEBUG - Verifying the 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 virtual network link through opserver 10.0.0.41 2025-10-13 02:25:46,423 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines 2025-10-13 02:25:46,434 - DEBUG - VM link and name as {'name': '76d2ca90-576f-4e04-bccf-9c0a636d4aa8', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-machine/76d2ca90-576f-4e04-bccf-9c0a636d4aa8?flat'} 2025-10-13 02:25:46,435 - INFO - VM ctest-VM11-85646793 validations in Opserver passed 2025-10-13 02:25:46,518 - DEBUG - VM is in ACTIVE state now 2025-10-13 02:25:46,518 - INFO - VM name : ctest-VM21-43835572 2025-10-13 02:25:46,590 - DEBUG - VM ctest-VM21-43835572 ID is e6b27acb-f7c5-425a-9be6-f904636ea67d 2025-10-13 02:25:46,672 - DEBUG - VM is in ACTIVE state now 2025-10-13 02:25:46,672 - DEBUG - VM ctest-VM21-43835572 launched on Node an-jenkins-deploy-platform-ansible-os-4296-2 2025-10-13 02:25:46,754 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/e6b27acb-f7c5-425a-9be6-f904636ea67d 2025-10-13 02:25:46,764 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/e6b27acb-f7c5-425a-9be6-f904636ea67d 2025-10-13 02:25:46,771 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/8c568de7-44b1-42cd-929a-313b85b8c78a 2025-10-13 02:25:46,852 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/e6b27acb-f7c5-425a-9be6-f904636ea67d 2025-10-13 02:25:46,860 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/8c568de7-44b1-42cd-929a-313b85b8c78a 2025-10-13 02:25:46,870 - DEBUG - Requesting: http://10.0.0.41:8082/instance-ip/7c769637-50b4-43b3-b5ee-fd600990f80d 2025-10-13 02:25:46,880 - DEBUG - Verifying in api server 10.0.0.41 2025-10-13 02:25:46,880 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/e6b27acb-f7c5-425a-9be6-f904636ea67d 2025-10-13 02:25:46,886 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/8c568de7-44b1-42cd-929a-313b85b8c78a 2025-10-13 02:25:46,896 - DEBUG - Requesting: http://10.0.0.41:8082/instance-ip/7c769637-50b4-43b3-b5ee-fd600990f80d 2025-10-13 02:25:46,904 - INFO - VM ctest-VM21-43835572 verfication in all API Servers passed 2025-10-13 02:25:46,922 - DEBUG - VM ctest-VM21-43835572 Tap interface: {'index': '3', 'name': 'tap8c568de7-44', 'uuid': '8c568de7-44b1-42cd-929a-313b85b8c78a', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676:ctest-VN2-46363676', '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-56827226:ctest-VN2-46363676', 'vm_uuid': 'e6b27acb-f7c5-425a-9be6-f904636ea67d', 'vm_name': 'ctest-VM21-43835572', 'ip_addr': '28.182.194.3', 'mac_addr': '02:8c:56:8d:e7:44', '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-56827226:8c568de7-44b1-42cd-929a-313b85b8c78a', 'sg_uuid_list': ['cfb18e31-f28a-4824-89d2-4a14edf64678'], 'static_route_list': None, 'vm_project_uuid': '225e6697-4a33-4311-bd72-480fc04d1c57', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-13 02:25:46,922 - DEBUG - Agent 10.0.0.27 vrf name: default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676:ctest-VN2-46363676 2025-10-13 02:25:46,927 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676:ctest-VN2-46363676', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.201:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-10-13 02:25:47,002 - DEBUG - VM ctest-VM21-43835572 : Tap interface tap8c568de7-44 is set to Active 2025-10-13 02:25:47,002 - DEBUG - Tap interface tap8c568de7-44 detail : {'index': '3', 'name': 'tap8c568de7-44', 'uuid': '8c568de7-44b1-42cd-929a-313b85b8c78a', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676:ctest-VN2-46363676', '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-56827226:ctest-VN2-46363676', 'vm_uuid': 'e6b27acb-f7c5-425a-9be6-f904636ea67d', 'vm_name': 'ctest-VM21-43835572', 'ip_addr': '28.182.194.3', 'mac_addr': '02:8c:56:8d:e7:44', '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-56827226:8c568de7-44b1-42cd-929a-313b85b8c78a', 'sg_uuid_list': ['cfb18e31-f28a-4824-89d2-4a14edf64678'], 'static_route_list': None, 'vm_project_uuid': '225e6697-4a33-4311-bd72-480fc04d1c57', 'admin_state': 'Enabled', 'flow_key_idx': '31', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-13 02:25:47,117 - DEBUG - Starting Layer 2 verification in Agent 2025-10-13 02:25:47,130 - DEBUG - Layer 2 path is seen for VM MAC 02:8c:56:8d:e7:44 in agent 10.0.0.27 2025-10-13 02:25:47,130 - DEBUG - Active layer 2 route in agent is present for VMI tap8c568de7-44 2025-10-13 02:25:47,130 - DEBUG - L2 label(29) matches bw route table and interface table 2025-10-13 02:25:47,149 - DEBUG - VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676 is found in Agent of node 10.0.0.27 2025-10-13 02:25:47,244 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676 is consistent in agent 10.0.0.27 2025-10-13 02:25:47,244 - DEBUG - Route for VM IP ['28.182.194.3'] is consistent in agent 10.0.0.27 2025-10-13 02:25:47,244 - DEBUG - VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676 verification for VM ctest-VM21-43835572 in Agent 10.0.0.27 passed 2025-10-13 02:25:47,362 - DEBUG - Starting all layer 2 verification in agent 10.0.0.27 2025-10-13 02:25:47,373 - DEBUG - Route for VM MAC 02:8c:56:8d:e7:44 is consistent in agent 10.0.0.27 2025-10-13 02:25:48,552 - 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=4.15 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=4.43 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 4.149/4.291/4.433/0.142 ms') 2025-10-13 02:25:48,552 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-43835572 passed 2025-10-13 02:25:48,552 - INFO - VM ctest-VM21-43835572 verifications in Compute nodes passed 2025-10-13 02:25:48,787 - DEBUG - Validated VM route 28.182.194.3 in vrouter of 10.0.0.27 2025-10-13 02:25:48,796 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-10-13 02:25:48,796 - INFO - Validated routes of VM ctest-VM21-43835572 in all vrouters 2025-10-13 02:25:49,232 - DEBUG - Starting all layer2 verification in 10.0.0.19 Control Node 2025-10-13 02:25:49,356 - DEBUG - Layer2 route found for VM MAC 02:8c:56:8d:e7:44 in Control-node 10.0.0.19 2025-10-13 02:25:49,356 - DEBUG - L2 Label for VM ctest-VM21-43835572 same between Control-node 10.0.0.19 and Agent, Expected: 29, Seen: 29 2025-10-13 02:25:49,356 - DEBUG - Starting all layer2 verification in 10.0.0.41 Control Node 2025-10-13 02:25:49,477 - DEBUG - Layer2 route found for VM MAC 02:8c:56:8d:e7:44 in Control-node 10.0.0.41 2025-10-13 02:25:49,478 - DEBUG - L2 Label for VM ctest-VM21-43835572 same between Control-node 10.0.0.41 and Agent, Expected: 29, Seen: 29 2025-10-13 02:25:49,478 - INFO - Verification in Control-nodes for VM ctest-VM21-43835572 passed 2025-10-13 02:25:49,478 - DEBUG - Verifying the vm in opserver 2025-10-13 02:25:49,478 - DEBUG - Verifying in collector 10.0.0.41 ... 2025-10-13 02:25:49,478 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/e6b27acb-f7c5-425a-9be6-f904636ea67d?flat 2025-10-13 02:25:49,492 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56827226:8c568de7-44b1-42cd-929a-313b85b8c78a?flat 2025-10-13 02:25:49,510 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56827226:8c568de7-44b1-42cd-929a-313b85b8c78a?flat 2025-10-13 02:25:49,528 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/e6b27acb-f7c5-425a-9be6-f904636ea67d?flat 2025-10-13 02:25:49,545 - DEBUG - Verifying vm in vn uve 2025-10-13 02:25:49,545 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56827226:8c568de7-44b1-42cd-929a-313b85b8c78a?flat 2025-10-13 02:25:49,563 - DEBUG - VM uve shows interface as {'admin_state': True, 'active': True, 'label': 25, 'ip4_active': True, 'uuid': '8c568de7-44b1-42cd-929a-313b85b8c78a', 'fixed_ip4_list': ['28.182.194.3'], 'gateway': '28.182.194.1', 'vhostuser_mode': 0, 'tx_vlan': 65535, 'mac_address': '02:8c:56:8d:e7:44', 'port_mirror_enabled': False, 'vn_uuid': '19e813f3-7931-4110-825c-6188b557f6f1', 'vm_uuid': 'e6b27acb-f7c5-425a-9be6-f904636ea67d', 'is_health_check_active': True, 'vm_name': 'ctest-VM21-43835572', 'ip6_active': False, 'virtual_network': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676', 'rx_vlan': 65535, 'ip6_address': '::', '__T': 1760322325171366, 'ip_address': '28.182.194.3', 'l2_active': True} 2025-10-13 02:25:49,563 - DEBUG - VM uve shows ip address as ['28.182.194.3', '::'] 2025-10-13 02:25:49,563 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676 2025-10-13 02:25:49,563 - INFO - Verifying through opserver in 10.0.0.22 2025-10-13 02:25:49,564 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676?flat 2025-10-13 02:25:49,583 - DEBUG - expected vm list e6b27acb-f7c5-425a-9be6-f904636ea67d 2025-10-13 02:25:49,583 - DEBUG - Extracted vm list ['e6b27acb-f7c5-425a-9be6-f904636ea67d'] 2025-10-13 02:25:49,583 - INFO - VM e6b27acb-f7c5-425a-9be6-f904636ea67d is present in default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676 2025-10-13 02:25:49,583 - DEBUG - Verifying vm in vrouter uve 2025-10-13 02:25:49,583 - DEBUG - Getting info from collector 10.0.0.41.. 2025-10-13 02:25:49,583 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/e6b27acb-f7c5-425a-9be6-f904636ea67d?flat 2025-10-13 02:25:49,600 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4296-2'] 2025-10-13 02:25:49,600 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-2:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:25:49,632 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-2?flat 2025-10-13 02:25:49,659 - DEBUG - VM e6b27acb-f7c5-425a-9be6-f904636ea67d is present in vrouter an-jenkins-deploy-platform-ansible-os-4296-2 uve 2025-10-13 02:25:49,659 - DEBUG - Validated that VM ctest-VM21-43835572 is in Vrouter an-jenkins-deploy-platform-ansible-os-4296-2 UVE 2025-10-13 02:25:49,659 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-10-13 02:25:49,669 - DEBUG - Expected tap interface of VM uuid e6b27acb-f7c5-425a-9be6-f904636ea67d is default-domain:ctest-TestPolicyAcl-56827226:8c568de7-44b1-42cd-929a-313b85b8c78a 2025-10-13 02:25:49,669 - DEBUG - Expected VN of VM uuid e6b27acb-f7c5-425a-9be6-f904636ea67d is default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676 2025-10-13 02:25:49,670 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-2:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:25:49,705 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-2?flat 2025-10-13 02:25:49,732 - DEBUG - VM e6b27acb-f7c5-425a-9be6-f904636ea67d is present in vrouter an-jenkins-deploy-platform-ansible-os-4296-2 uve 2025-10-13 02:25:49,732 - INFO - tap interface default-domain:ctest-TestPolicyAcl-56827226:8c568de7-44b1-42cd-929a-313b85b8c78a of vm e6b27acb-f7c5-425a-9be6-f904636ea67d is present in vrouter an-jenkins-deploy-platform-ansible-os-4296-2 uve 2025-10-13 02:25:49,732 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676 of vm e6b27acb-f7c5-425a-9be6-f904636ea67d is present in vrouter an-jenkins-deploy-platform-ansible-os-4296-2 uve 2025-10-13 02:25:49,732 - DEBUG - Verifying the e6b27acb-f7c5-425a-9be6-f904636ea67d virtual network link through opserver 10.0.0.22 2025-10-13 02:25:49,732 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines 2025-10-13 02:25:49,749 - DEBUG - VM link and name as {'name': 'e6b27acb-f7c5-425a-9be6-f904636ea67d', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-machine/e6b27acb-f7c5-425a-9be6-f904636ea67d?flat'} 2025-10-13 02:25:49,749 - DEBUG - Verifying the e6b27acb-f7c5-425a-9be6-f904636ea67d virtual network link through opserver 10.0.0.19 2025-10-13 02:25:49,749 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines 2025-10-13 02:25:49,765 - DEBUG - VM link and name as {'name': 'e6b27acb-f7c5-425a-9be6-f904636ea67d', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-machine/e6b27acb-f7c5-425a-9be6-f904636ea67d?flat'} 2025-10-13 02:25:49,765 - DEBUG - Verifying the e6b27acb-f7c5-425a-9be6-f904636ea67d virtual network link through opserver 10.0.0.41 2025-10-13 02:25:49,765 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines 2025-10-13 02:25:49,779 - DEBUG - VM link and name as {'name': 'e6b27acb-f7c5-425a-9be6-f904636ea67d', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-machine/e6b27acb-f7c5-425a-9be6-f904636ea67d?flat'} 2025-10-13 02:25:49,779 - INFO - VM ctest-VM21-43835572 validations in Opserver passed 2025-10-13 02:25:49,869 - DEBUG - VM is in ACTIVE state now 2025-10-13 02:25:49,869 - INFO - VM name : ctest-VM31-96524295 2025-10-13 02:25:49,948 - DEBUG - VM ctest-VM31-96524295 ID is 747768a8-b56f-4d9f-981c-a2d09bc1284b 2025-10-13 02:25:50,037 - DEBUG - VM is in ACTIVE state now 2025-10-13 02:25:50,038 - DEBUG - VM ctest-VM31-96524295 launched on Node an-jenkins-deploy-platform-ansible-os-4296-1 2025-10-13 02:25:50,111 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/747768a8-b56f-4d9f-981c-a2d09bc1284b 2025-10-13 02:25:50,124 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/747768a8-b56f-4d9f-981c-a2d09bc1284b 2025-10-13 02:25:50,132 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/cb01f5c9-8740-411a-b1f3-a364508ec925 2025-10-13 02:25:50,145 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/747768a8-b56f-4d9f-981c-a2d09bc1284b 2025-10-13 02:25:50,152 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/cb01f5c9-8740-411a-b1f3-a364508ec925 2025-10-13 02:25:50,161 - DEBUG - Requesting: http://10.0.0.41:8082/instance-ip/69bc3ace-b95e-40ea-8469-9bfd70ac1172 2025-10-13 02:25:50,171 - DEBUG - Verifying in api server 10.0.0.41 2025-10-13 02:25:50,171 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/747768a8-b56f-4d9f-981c-a2d09bc1284b 2025-10-13 02:25:50,177 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/cb01f5c9-8740-411a-b1f3-a364508ec925 2025-10-13 02:25:50,188 - DEBUG - Requesting: http://10.0.0.41:8082/instance-ip/69bc3ace-b95e-40ea-8469-9bfd70ac1172 2025-10-13 02:25:50,197 - INFO - VM ctest-VM31-96524295 verfication in all API Servers passed 2025-10-13 02:25:50,216 - DEBUG - VM ctest-VM31-96524295 Tap interface: {'index': '4', 'name': 'tapcb01f5c9-87', 'uuid': 'cb01f5c9-8740-411a-b1f3-a364508ec925', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383:ctest-VN3-64334383', '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': '31', 'l2_label': '35', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383', 'vm_uuid': '747768a8-b56f-4d9f-981c-a2d09bc1284b', 'vm_name': 'ctest-VM31-96524295', 'ip_addr': '14.255.217.3', 'mac_addr': '02:cb:01:f5:c9:87', '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-56827226:cb01f5c9-8740-411a-b1f3-a364508ec925', 'sg_uuid_list': ['cfb18e31-f28a-4824-89d2-4a14edf64678'], 'static_route_list': None, 'vm_project_uuid': '225e6697-4a33-4311-bd72-480fc04d1c57', 'admin_state': 'Enabled', 'flow_key_idx': '38', '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-13 02:25:50,216 - DEBUG - Agent 10.0.0.249 vrf name: default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383:ctest-VN3-64334383 2025-10-13 02:25:50,221 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383:ctest-VN3-64334383', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383', 'table_label': '-1', 'vxlan_id': '15', '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-13 02:25:50,303 - DEBUG - VM ctest-VM31-96524295 : Tap interface tapcb01f5c9-87 is set to Active 2025-10-13 02:25:50,304 - DEBUG - Tap interface tapcb01f5c9-87 detail : {'index': '4', 'name': 'tapcb01f5c9-87', 'uuid': 'cb01f5c9-8740-411a-b1f3-a364508ec925', 'vrf_name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383:ctest-VN3-64334383', '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': '31', 'l2_label': '35', 'vxlan_id': '15', 'vn_name': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383', 'vm_uuid': '747768a8-b56f-4d9f-981c-a2d09bc1284b', 'vm_name': 'ctest-VM31-96524295', 'ip_addr': '14.255.217.3', 'mac_addr': '02:cb:01:f5:c9:87', '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-56827226:cb01f5c9-8740-411a-b1f3-a364508ec925', 'sg_uuid_list': ['cfb18e31-f28a-4824-89d2-4a14edf64678'], 'static_route_list': None, 'vm_project_uuid': '225e6697-4a33-4311-bd72-480fc04d1c57', 'admin_state': 'Enabled', 'flow_key_idx': '38', '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-13 02:25:50,418 - DEBUG - Starting Layer 2 verification in Agent 2025-10-13 02:25:50,431 - DEBUG - Layer 2 path is seen for VM MAC 02:cb:01:f5:c9:87 in agent 10.0.0.249 2025-10-13 02:25:50,432 - DEBUG - Active layer 2 route in agent is present for VMI tapcb01f5c9-87 2025-10-13 02:25:50,432 - DEBUG - L2 label(35) matches bw route table and interface table 2025-10-13 02:25:50,450 - DEBUG - VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383 is found in Agent of node 10.0.0.249 2025-10-13 02:25:50,543 - DEBUG - VRF IDs of VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383 is consistent in agent 10.0.0.249 2025-10-13 02:25:50,543 - DEBUG - Route for VM IP ['14.255.217.3'] is consistent in agent 10.0.0.249 2025-10-13 02:25:50,543 - DEBUG - VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383 verification for VM ctest-VM31-96524295 in Agent 10.0.0.249 passed 2025-10-13 02:25:50,663 - DEBUG - Starting all layer 2 verification in agent 10.0.0.249 2025-10-13 02:25:50,676 - DEBUG - Route for VM MAC 02:cb:01:f5:c9:87 is consistent in agent 10.0.0.249 2025-10-13 02:25:51,864 - 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=7.45 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.621 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.621/4.033/7.446/3.412 ms') 2025-10-13 02:25:51,864 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-96524295 passed 2025-10-13 02:25:51,864 - INFO - VM ctest-VM31-96524295 verifications in Compute nodes passed 2025-10-13 02:25:52,063 - DEBUG - Validated VM route 14.255.217.3 in vrouter of 10.0.0.249 2025-10-13 02:25:52,068 - DEBUG - Remote Nh validation passed. Expected Tunnel, label: None, 0; Got None,0 2025-10-13 02:25:52,068 - INFO - Validated routes of VM ctest-VM31-96524295 in all vrouters 2025-10-13 02:25:52,605 - DEBUG - Starting all layer2 verification in 10.0.0.19 Control Node 2025-10-13 02:25:52,723 - DEBUG - Layer2 route found for VM MAC 02:cb:01:f5:c9:87 in Control-node 10.0.0.19 2025-10-13 02:25:52,723 - DEBUG - L2 Label for VM ctest-VM31-96524295 same between Control-node 10.0.0.19 and Agent, Expected: 35, Seen: 35 2025-10-13 02:25:52,723 - DEBUG - Starting all layer2 verification in 10.0.0.41 Control Node 2025-10-13 02:25:52,845 - DEBUG - Layer2 route found for VM MAC 02:cb:01:f5:c9:87 in Control-node 10.0.0.41 2025-10-13 02:25:52,845 - DEBUG - L2 Label for VM ctest-VM31-96524295 same between Control-node 10.0.0.41 and Agent, Expected: 35, Seen: 35 2025-10-13 02:25:52,845 - INFO - Verification in Control-nodes for VM ctest-VM31-96524295 passed 2025-10-13 02:25:52,845 - DEBUG - Verifying the vm in opserver 2025-10-13 02:25:52,845 - DEBUG - Verifying in collector 10.0.0.41 ... 2025-10-13 02:25:52,845 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/747768a8-b56f-4d9f-981c-a2d09bc1284b?flat 2025-10-13 02:25:52,861 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56827226:cb01f5c9-8740-411a-b1f3-a364508ec925?flat 2025-10-13 02:25:52,878 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56827226:cb01f5c9-8740-411a-b1f3-a364508ec925?flat 2025-10-13 02:25:52,894 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/747768a8-b56f-4d9f-981c-a2d09bc1284b?flat 2025-10-13 02:25:52,909 - DEBUG - Verifying vm in vn uve 2025-10-13 02:25:52,909 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machine-interface/default-domain:ctest-TestPolicyAcl-56827226:cb01f5c9-8740-411a-b1f3-a364508ec925?flat 2025-10-13 02:25:52,929 - DEBUG - VM uve shows interface as {'admin_state': True, 'active': True, 'label': 31, 'ip4_active': True, 'uuid': 'cb01f5c9-8740-411a-b1f3-a364508ec925', 'fixed_ip4_list': ['14.255.217.3'], 'gateway': '14.255.217.1', 'vhostuser_mode': 0, 'tx_vlan': 65535, 'mac_address': '02:cb:01:f5:c9:87', 'port_mirror_enabled': False, 'vn_uuid': '4aafe4f1-0342-47d0-9ebb-7195e657045b', 'vm_uuid': '747768a8-b56f-4d9f-981c-a2d09bc1284b', 'is_health_check_active': True, 'vm_name': 'ctest-VM31-96524295', 'ip6_active': False, 'virtual_network': 'default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383', 'rx_vlan': 65535, 'ip6_address': '::', '__T': 1760322326079073, 'ip_address': '14.255.217.3', 'l2_active': True} 2025-10-13 02:25:52,930 - DEBUG - VM uve shows ip address as ['14.255.217.3', '::'] 2025-10-13 02:25:52,930 - DEBUG - VM uve shows virtual network as default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383 2025-10-13 02:25:52,930 - INFO - Verifying through opserver in 10.0.0.22 2025-10-13 02:25:52,930 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383?flat 2025-10-13 02:25:52,948 - DEBUG - expected vm list 747768a8-b56f-4d9f-981c-a2d09bc1284b 2025-10-13 02:25:52,948 - DEBUG - Extracted vm list ['747768a8-b56f-4d9f-981c-a2d09bc1284b'] 2025-10-13 02:25:52,948 - INFO - VM 747768a8-b56f-4d9f-981c-a2d09bc1284b is present in default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383 2025-10-13 02:25:52,948 - DEBUG - Verifying vm in vrouter uve 2025-10-13 02:25:52,948 - DEBUG - Getting info from collector 10.0.0.41.. 2025-10-13 02:25:52,948 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machine/747768a8-b56f-4d9f-981c-a2d09bc1284b?flat 2025-10-13 02:25:52,964 - DEBUG - VM uve shows vrouter as ['an-jenkins-deploy-platform-ansible-os-4296-1'] 2025-10-13 02:25:52,964 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:25:52,994 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:25:53,021 - DEBUG - VM 747768a8-b56f-4d9f-981c-a2d09bc1284b is present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:25:53,021 - DEBUG - Validated that VM ctest-VM31-96524295 is in Vrouter an-jenkins-deploy-platform-ansible-os-4296-1 UVE 2025-10-13 02:25:53,021 - DEBUG - Verifying vm tap interface/vn in vrouter uve 2025-10-13 02:25:53,028 - DEBUG - Expected tap interface of VM uuid 747768a8-b56f-4d9f-981c-a2d09bc1284b is default-domain:ctest-TestPolicyAcl-56827226:cb01f5c9-8740-411a-b1f3-a364508ec925 2025-10-13 02:25:53,028 - DEBUG - Expected VN of VM uuid 747768a8-b56f-4d9f-981c-a2d09bc1284b is default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383 2025-10-13 02:25:53,028 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:25:53,061 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:25:53,094 - DEBUG - VM 747768a8-b56f-4d9f-981c-a2d09bc1284b is present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:25:53,094 - INFO - tap interface default-domain:ctest-TestPolicyAcl-56827226:cb01f5c9-8740-411a-b1f3-a364508ec925 of vm 747768a8-b56f-4d9f-981c-a2d09bc1284b is present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:25:53,094 - DEBUG - Connected network default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383 of vm 747768a8-b56f-4d9f-981c-a2d09bc1284b is present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:25:53,094 - DEBUG - Verifying the 747768a8-b56f-4d9f-981c-a2d09bc1284b virtual network link through opserver 10.0.0.22 2025-10-13 02:25:53,094 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines 2025-10-13 02:25:53,107 - DEBUG - VM link and name as {'name': '747768a8-b56f-4d9f-981c-a2d09bc1284b', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-machine/747768a8-b56f-4d9f-981c-a2d09bc1284b?flat'} 2025-10-13 02:25:53,107 - DEBUG - Verifying the 747768a8-b56f-4d9f-981c-a2d09bc1284b virtual network link through opserver 10.0.0.19 2025-10-13 02:25:53,107 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines 2025-10-13 02:25:53,119 - DEBUG - VM link and name as {'name': '747768a8-b56f-4d9f-981c-a2d09bc1284b', 'href': 'http://10.0.0.19:8081/analytics/uves/virtual-machine/747768a8-b56f-4d9f-981c-a2d09bc1284b?flat'} 2025-10-13 02:25:53,119 - DEBUG - Verifying the 747768a8-b56f-4d9f-981c-a2d09bc1284b virtual network link through opserver 10.0.0.41 2025-10-13 02:25:53,119 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines 2025-10-13 02:25:53,131 - DEBUG - VM link and name as {'name': '747768a8-b56f-4d9f-981c-a2d09bc1284b', 'href': 'http://10.0.0.41:8081/analytics/uves/virtual-machine/747768a8-b56f-4d9f-981c-a2d09bc1284b?flat'} 2025-10-13 02:25:53,131 - INFO - VM ctest-VM31-96524295 validations in Opserver passed 2025-10-13 02:25:53,132 - INFO - Waiting for VM ctest-VM11-85646793 to be up.. 2025-10-13 02:25:53,132 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-13 02:25:53,132 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/76d2ca90-576f-4e04-bccf-9c0a636d4aa8 2025-10-13 02:25:53,142 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/960ce36a-f8bf-429a-924b-06d7d81ecf2f 2025-10-13 02:25:54,306 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=3.20 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=8.98 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 3.197/6.088/8.979/2.891 ms') 2025-10-13 02:25:54,306 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM11-85646793 passed 2025-10-13 02:25:54,379 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-13 02:25:54,379 - DEBUG - Waiting to SSH to VM ctest-VM11-85646793, IP 121.134.135.195, Port 22 2025-10-13 02:25:54,445 - 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-13 02:25:54,524 - DEBUG - VM ctest-VM11-85646793 is NOT ready for SSH connections, VM status: ACTIVE 2025-10-13 02:25:59,524 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-13 02:25:59,525 - DEBUG - Waiting to SSH to VM ctest-VM11-85646793, IP 121.134.135.195, Port 22 2025-10-13 02:25:59,593 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-10-13 02:25:59,689 - DEBUG - VM ctest-VM11-85646793 is NOT ready for SSH connections, VM status: ACTIVE 2025-10-13 02:26:04,690 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-13 02:26:04,690 - DEBUG - Waiting to SSH to VM ctest-VM11-85646793, IP 121.134.135.195, Port 22 2025-10-13 02:26:04,872 - DEBUG - VM ctest-VM11-85646793 is ready for SSH connections 2025-10-13 02:26:04,872 - INFO - Waiting for VM ctest-VM21-43835572 to be up.. 2025-10-13 02:26:04,872 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-13 02:26:04,872 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/e6b27acb-f7c5-425a-9be6-f904636ea67d 2025-10-13 02:26:04,882 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/8c568de7-44b1-42cd-929a-313b85b8c78a 2025-10-13 02:26:06,091 - 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.50 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=50.6 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 = 2.502/26.538/50.574/24.036 ms') 2025-10-13 02:26:06,091 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-VM21-43835572 passed 2025-10-13 02:26:06,160 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-13 02:26:06,160 - DEBUG - Waiting to SSH to VM ctest-VM21-43835572, IP 28.182.194.3, Port 22 2025-10-13 02:26:06,330 - DEBUG - VM ctest-VM21-43835572 is ready for SSH connections 2025-10-13 02:26:06,330 - INFO - Waiting for VM ctest-VM31-96524295 to be up.. 2025-10-13 02:26:06,330 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-13 02:26:06,330 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/747768a8-b56f-4d9f-981c-a2d09bc1284b 2025-10-13 02:26:06,339 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/cb01f5c9-8740-411a-b1f3-a364508ec925 2025-10-13 02:26:07,500 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.87 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.83 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 = 2.831/2.848/2.866/0.017 ms') 2025-10-13 02:26:07,500 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-VM31-96524295 passed 2025-10-13 02:26:07,568 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-13 02:26:07,568 - DEBUG - Waiting to SSH to VM ctest-VM31-96524295, IP 14.255.217.3, Port 22 2025-10-13 02:26:07,633 - 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-13 02:26:07,711 - DEBUG - VM ctest-VM31-96524295 is NOT ready for SSH connections, VM status: ACTIVE 2025-10-13 02:26:12,712 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-13 02:26:12,712 - DEBUG - Waiting to SSH to VM ctest-VM31-96524295, IP 14.255.217.3, Port 22 2025-10-13 02:26:12,878 - DEBUG - VM ctest-VM31-96524295 is ready for SSH connections 2025-10-13 02:26:12,878 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 28.182.194.3, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2025-10-13 02:26:12,878 - DEBUG - ping -s 56 -c 3 -W 1 28.182.194.3 2025-10-13 02:27:02,255 - DEBUG - PING 28.182.194.3 (28.182.194.3) 56(84) bytes of data. 64 bytes from 28.182.194.3: icmp_req=1 ttl=63 time=8.20 ms 64 bytes from 28.182.194.3: icmp_req=2 ttl=63 time=1.16 ms 64 bytes from 28.182.194.3: icmp_req=3 ttl=63 time=1.93 ms --- 28.182.194.3 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2005ms rtt min/avg/max/mdev = 1.169/3.767/8.202/3.151 ms 2025-10-13 02:27:02,255 - INFO - Ping to IP 28.182.194.3 from VM ctest-VM11-85646793 passed 2025-10-13 02:27:02,256 - INFO - Test with src as VN and dst as policy PASSED 2025-10-13 02:27:02,256 - INFO - Deleting VM ctest-VM31-96524295 2025-10-13 02:27:02,336 - DEBUG - Verifying in api server 10.0.0.41 2025-10-13 02:27:02,337 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/747768a8-b56f-4d9f-981c-a2d09bc1284b 2025-10-13 02:27:02,346 - DEBUG - VM ID 747768a8-b56f-4d9f-981c-a2d09bc1284b of VM ctest-VM31-96524295 is still found in API Server 2025-10-13 02:27:04,347 - DEBUG - Verifying in api server 10.0.0.41 2025-10-13 02:27:04,347 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/747768a8-b56f-4d9f-981c-a2d09bc1284b 2025-10-13 02:27:04,355 - DEBUG - Response Code: 404 2025-10-13 02:27:04,355 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/747768a8-b56f-4d9f-981c-a2d09bc1284b 2025-10-13 02:27:04,361 - DEBUG - Response Code: 404 2025-10-13 02:27:04,362 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/747768a8-b56f-4d9f-981c-a2d09bc1284b 2025-10-13 02:27:04,367 - DEBUG - Response Code: 404 2025-10-13 02:27:04,367 - INFO - VM ctest-VM31-96524295 is fully removed in API-Server 2025-10-13 02:27:04,520 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-10-13 02:27:04,558 - DEBUG - Validated that vrouter 10.0.0.249 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383 2025-10-13 02:27:04,570 - DEBUG - Validated that vrouter 10.0.0.27 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383 2025-10-13 02:27:04,570 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383 2025-10-13 02:27:04,570 - INFO - VM ctest-VM31-96524295 is removed in Compute, and routes are removed in all compute nodes 2025-10-13 02:27:04,811 - INFO - Routes for VM ctest-VM31-96524295 is removed in all control-nodes 2025-10-13 02:27:04,812 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:04,844 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:27:04,872 - DEBUG - VM 747768a8-b56f-4d9f-981c-a2d09bc1284b is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:27:04,872 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:04,873 - DEBUG - interface for vm 747768a8-b56f-4d9f-981c-a2d09bc1284b deleted from vrouter uve ... 2025-10-13 02:27:04,873 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:07,873 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:07,904 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:27:07,931 - DEBUG - VM 747768a8-b56f-4d9f-981c-a2d09bc1284b is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:27:07,931 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:07,931 - DEBUG - interface for vm 747768a8-b56f-4d9f-981c-a2d09bc1284b deleted from vrouter uve ... 2025-10-13 02:27:07,931 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:10,932 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:10,974 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:27:11,003 - DEBUG - VM 747768a8-b56f-4d9f-981c-a2d09bc1284b is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:27:11,003 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:11,003 - DEBUG - interface for vm 747768a8-b56f-4d9f-981c-a2d09bc1284b deleted from vrouter uve ... 2025-10-13 02:27:11,003 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:14,003 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:14,033 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:27:14,061 - DEBUG - VM 747768a8-b56f-4d9f-981c-a2d09bc1284b is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:27:14,061 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:14,061 - DEBUG - interface for vm 747768a8-b56f-4d9f-981c-a2d09bc1284b deleted from vrouter uve ... 2025-10-13 02:27:14,061 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:17,062 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:17,092 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:27:17,119 - DEBUG - VM 747768a8-b56f-4d9f-981c-a2d09bc1284b is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:27:17,119 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:17,119 - DEBUG - interface for vm 747768a8-b56f-4d9f-981c-a2d09bc1284b deleted from vrouter uve ... 2025-10-13 02:27:17,119 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:20,120 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:20,150 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:27:20,179 - DEBUG - VM 747768a8-b56f-4d9f-981c-a2d09bc1284b is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:27:20,179 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:20,180 - DEBUG - interface for vm 747768a8-b56f-4d9f-981c-a2d09bc1284b deleted from vrouter uve ... 2025-10-13 02:27:20,180 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:23,181 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:23,213 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:27:23,241 - DEBUG - VM 747768a8-b56f-4d9f-981c-a2d09bc1284b is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:27:23,241 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:23,241 - DEBUG - interface for vm 747768a8-b56f-4d9f-981c-a2d09bc1284b deleted from vrouter uve ... 2025-10-13 02:27:23,241 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:26,242 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:26,286 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:27:26,313 - DEBUG - VM 747768a8-b56f-4d9f-981c-a2d09bc1284b is not present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:27:26,313 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:26,313 - DEBUG - interface for vm 747768a8-b56f-4d9f-981c-a2d09bc1284b deleted from vrouter uve ... 2025-10-13 02:27:26,313 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:26,314 - DEBUG - Validated that VM 747768a8-b56f-4d9f-981c-a2d09bc1284b is removed in Vrouter UVE 2025-10-13 02:27:26,314 - DEBUG - Verifying through opserver in 10.0.0.22 2025-10-13 02:27:26,314 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383?flat 2025-10-13 02:27:26,330 - DEBUG - 747768a8-b56f-4d9f-981c-a2d09bc1284b BM not in default-domain:ctest-TestPolicyAcl-56827226:ctest-VN3-64334383 uve 2025-10-13 02:27:26,330 - DEBUG - Validated that VM 747768a8-b56f-4d9f-981c-a2d09bc1284b is not present in the VN UVE 2025-10-13 02:27:26,330 - DEBUG - Verifying the 747768a8-b56f-4d9f-981c-a2d09bc1284b virtual network link through opserver 10.0.0.22 2025-10-13 02:27:26,330 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines 2025-10-13 02:27:26,344 - INFO - 747768a8-b56f-4d9f-981c-a2d09bc1284b vm uve deleted from opserver 2025-10-13 02:27:26,344 - DEBUG - Verifying the 747768a8-b56f-4d9f-981c-a2d09bc1284b virtual network link through opserver 10.0.0.19 2025-10-13 02:27:26,344 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines 2025-10-13 02:27:26,357 - INFO - 747768a8-b56f-4d9f-981c-a2d09bc1284b vm uve deleted from opserver 2025-10-13 02:27:26,357 - DEBUG - Verifying the 747768a8-b56f-4d9f-981c-a2d09bc1284b virtual network link through opserver 10.0.0.41 2025-10-13 02:27:26,357 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines 2025-10-13 02:27:26,369 - INFO - 747768a8-b56f-4d9f-981c-a2d09bc1284b vm uve deleted from opserver 2025-10-13 02:27:26,369 - INFO - Validated that VM 747768a8-b56f-4d9f-981c-a2d09bc1284b is removed from Opserver 2025-10-13 02:27:26,369 - INFO - Deleting VM ctest-VM21-43835572 2025-10-13 02:27:26,450 - DEBUG - Verifying in api server 10.0.0.41 2025-10-13 02:27:26,450 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/e6b27acb-f7c5-425a-9be6-f904636ea67d 2025-10-13 02:27:26,458 - DEBUG - VM ID e6b27acb-f7c5-425a-9be6-f904636ea67d of VM ctest-VM21-43835572 is still found in API Server 2025-10-13 02:27:28,459 - DEBUG - Verifying in api server 10.0.0.41 2025-10-13 02:27:28,459 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/e6b27acb-f7c5-425a-9be6-f904636ea67d 2025-10-13 02:27:28,467 - DEBUG - Response Code: 404 2025-10-13 02:27:28,467 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/e6b27acb-f7c5-425a-9be6-f904636ea67d 2025-10-13 02:27:28,475 - DEBUG - Response Code: 404 2025-10-13 02:27:28,475 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/e6b27acb-f7c5-425a-9be6-f904636ea67d 2025-10-13 02:27:28,480 - DEBUG - Response Code: 404 2025-10-13 02:27:28,481 - INFO - VM ctest-VM21-43835572 is fully removed in API-Server 2025-10-13 02:27:28,632 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-10-13 02:27:28,666 - DEBUG - Validated that vrouter 10.0.0.249 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676 2025-10-13 02:27:28,675 - DEBUG - Validated that vrouter 10.0.0.27 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676 2025-10-13 02:27:28,675 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676 2025-10-13 02:27:28,675 - INFO - VM ctest-VM21-43835572 is removed in Compute, and routes are removed in all compute nodes 2025-10-13 02:27:28,874 - INFO - Routes for VM ctest-VM21-43835572 is removed in all control-nodes 2025-10-13 02:27:28,874 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-2:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:28,906 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-2?flat 2025-10-13 02:27:28,939 - DEBUG - VM e6b27acb-f7c5-425a-9be6-f904636ea67d is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-2 uve 2025-10-13 02:27:28,939 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:28,940 - DEBUG - interface for vm e6b27acb-f7c5-425a-9be6-f904636ea67d deleted from vrouter uve ... 2025-10-13 02:27:28,940 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:31,940 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-2:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:31,974 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-2?flat 2025-10-13 02:27:32,002 - DEBUG - VM e6b27acb-f7c5-425a-9be6-f904636ea67d is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-2 uve 2025-10-13 02:27:32,002 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:32,002 - DEBUG - interface for vm e6b27acb-f7c5-425a-9be6-f904636ea67d deleted from vrouter uve ... 2025-10-13 02:27:32,002 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:35,003 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-2:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:35,039 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-2?flat 2025-10-13 02:27:35,074 - DEBUG - VM e6b27acb-f7c5-425a-9be6-f904636ea67d is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-2 uve 2025-10-13 02:27:35,074 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:35,074 - DEBUG - interface for vm e6b27acb-f7c5-425a-9be6-f904636ea67d deleted from vrouter uve ... 2025-10-13 02:27:35,074 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:38,075 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-2:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:38,105 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-2?flat 2025-10-13 02:27:38,131 - DEBUG - VM e6b27acb-f7c5-425a-9be6-f904636ea67d is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-2 uve 2025-10-13 02:27:38,131 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:38,131 - DEBUG - interface for vm e6b27acb-f7c5-425a-9be6-f904636ea67d deleted from vrouter uve ... 2025-10-13 02:27:38,131 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:41,132 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-2:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:41,164 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-2?flat 2025-10-13 02:27:41,191 - DEBUG - VM e6b27acb-f7c5-425a-9be6-f904636ea67d is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-2 uve 2025-10-13 02:27:41,191 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:41,191 - DEBUG - interface for vm e6b27acb-f7c5-425a-9be6-f904636ea67d deleted from vrouter uve ... 2025-10-13 02:27:41,191 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:44,192 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-2:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:44,224 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-2?flat 2025-10-13 02:27:44,254 - DEBUG - VM e6b27acb-f7c5-425a-9be6-f904636ea67d is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-2 uve 2025-10-13 02:27:44,254 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:44,254 - DEBUG - interface for vm e6b27acb-f7c5-425a-9be6-f904636ea67d deleted from vrouter uve ... 2025-10-13 02:27:44,254 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:47,255 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-2:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:47,289 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-2?flat 2025-10-13 02:27:47,319 - DEBUG - VM e6b27acb-f7c5-425a-9be6-f904636ea67d is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-2 uve 2025-10-13 02:27:47,319 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:47,319 - DEBUG - interface for vm e6b27acb-f7c5-425a-9be6-f904636ea67d deleted from vrouter uve ... 2025-10-13 02:27:47,319 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:50,319 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-2:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:50,350 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-2?flat 2025-10-13 02:27:50,378 - DEBUG - VM e6b27acb-f7c5-425a-9be6-f904636ea67d is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-2 uve 2025-10-13 02:27:50,378 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:50,379 - DEBUG - interface for vm e6b27acb-f7c5-425a-9be6-f904636ea67d deleted from vrouter uve ... 2025-10-13 02:27:50,379 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:53,379 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-2:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:53,411 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-2?flat 2025-10-13 02:27:53,442 - DEBUG - VM e6b27acb-f7c5-425a-9be6-f904636ea67d is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-2 uve 2025-10-13 02:27:53,442 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:53,442 - DEBUG - interface for vm e6b27acb-f7c5-425a-9be6-f904636ea67d deleted from vrouter uve ... 2025-10-13 02:27:53,442 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:56,443 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-2:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:56,475 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-2?flat 2025-10-13 02:27:56,501 - DEBUG - VM e6b27acb-f7c5-425a-9be6-f904636ea67d is not present in vrouter an-jenkins-deploy-platform-ansible-os-4296-2 uve 2025-10-13 02:27:56,501 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:56,501 - DEBUG - interface for vm e6b27acb-f7c5-425a-9be6-f904636ea67d deleted from vrouter uve ... 2025-10-13 02:27:56,501 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:27:56,501 - DEBUG - Validated that VM e6b27acb-f7c5-425a-9be6-f904636ea67d is removed in Vrouter UVE 2025-10-13 02:27:56,502 - DEBUG - Verifying through opserver in 10.0.0.22 2025-10-13 02:27:56,502 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676?flat 2025-10-13 02:27:56,520 - DEBUG - e6b27acb-f7c5-425a-9be6-f904636ea67d BM not in default-domain:ctest-TestPolicyAcl-56827226:ctest-VN2-46363676 uve 2025-10-13 02:27:56,520 - DEBUG - Validated that VM e6b27acb-f7c5-425a-9be6-f904636ea67d is not present in the VN UVE 2025-10-13 02:27:56,520 - DEBUG - Verifying the e6b27acb-f7c5-425a-9be6-f904636ea67d virtual network link through opserver 10.0.0.22 2025-10-13 02:27:56,520 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines 2025-10-13 02:27:56,532 - INFO - e6b27acb-f7c5-425a-9be6-f904636ea67d vm uve deleted from opserver 2025-10-13 02:27:56,532 - DEBUG - Verifying the e6b27acb-f7c5-425a-9be6-f904636ea67d virtual network link through opserver 10.0.0.19 2025-10-13 02:27:56,532 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines 2025-10-13 02:27:56,543 - INFO - e6b27acb-f7c5-425a-9be6-f904636ea67d vm uve deleted from opserver 2025-10-13 02:27:56,543 - DEBUG - Verifying the e6b27acb-f7c5-425a-9be6-f904636ea67d virtual network link through opserver 10.0.0.41 2025-10-13 02:27:56,543 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines 2025-10-13 02:27:56,561 - INFO - e6b27acb-f7c5-425a-9be6-f904636ea67d vm uve deleted from opserver 2025-10-13 02:27:56,561 - INFO - Validated that VM e6b27acb-f7c5-425a-9be6-f904636ea67d is removed from Opserver 2025-10-13 02:27:56,561 - INFO - Deleting VM ctest-VM11-85646793 2025-10-13 02:27:56,632 - DEBUG - Verifying in api server 10.0.0.41 2025-10-13 02:27:56,632 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/76d2ca90-576f-4e04-bccf-9c0a636d4aa8 2025-10-13 02:27:56,642 - DEBUG - VM ID 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 of VM ctest-VM11-85646793 is still found in API Server 2025-10-13 02:27:58,642 - DEBUG - Verifying in api server 10.0.0.41 2025-10-13 02:27:58,642 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/76d2ca90-576f-4e04-bccf-9c0a636d4aa8 2025-10-13 02:27:58,650 - DEBUG - Response Code: 404 2025-10-13 02:27:58,650 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/76d2ca90-576f-4e04-bccf-9c0a636d4aa8 2025-10-13 02:27:58,656 - DEBUG - Response Code: 404 2025-10-13 02:27:58,656 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/76d2ca90-576f-4e04-bccf-9c0a636d4aa8 2025-10-13 02:27:58,663 - DEBUG - Response Code: 404 2025-10-13 02:27:58,663 - INFO - VM ctest-VM11-85646793 is fully removed in API-Server 2025-10-13 02:27:58,815 - DEBUG - Skipping VM-deletion-check in nova db since mysql_token is not found 2025-10-13 02:27:58,848 - DEBUG - Validated that vrouter 10.0.0.249 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 2025-10-13 02:27:58,856 - DEBUG - Validated that vrouter 10.0.0.27 does not have VMs route for VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 2025-10-13 02:27:58,856 - INFO - Validated that all vrouters do not have VMs route for VN default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 2025-10-13 02:27:58,857 - INFO - VM ctest-VM11-85646793 is removed in Compute, and routes are removed in all compute nodes 2025-10-13 02:27:59,052 - INFO - Routes for VM ctest-VM11-85646793 is removed in all control-nodes 2025-10-13 02:27:59,052 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:27:59,084 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:27:59,114 - DEBUG - VM 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:27:59,114 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:27:59,114 - DEBUG - interface for vm 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 deleted from vrouter uve ... 2025-10-13 02:27:59,114 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:28:02,115 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:28:02,150 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:28:02,179 - DEBUG - VM 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:28:02,179 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:28:02,179 - DEBUG - interface for vm 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 deleted from vrouter uve ... 2025-10-13 02:28:02,179 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:28:05,180 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:28:05,213 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:28:05,241 - DEBUG - VM 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:28:05,241 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:28:05,241 - DEBUG - interface for vm 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 deleted from vrouter uve ... 2025-10-13 02:28:05,241 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:28:08,242 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:28:08,273 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:28:08,306 - DEBUG - VM 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:28:08,306 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:28:08,306 - DEBUG - interface for vm 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 deleted from vrouter uve ... 2025-10-13 02:28:08,306 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:28:11,307 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:28:11,338 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:28:11,366 - DEBUG - VM 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:28:11,366 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:28:11,366 - DEBUG - interface for vm 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 deleted from vrouter uve ... 2025-10-13 02:28:11,366 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:28:14,367 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:28:14,399 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:28:14,432 - DEBUG - VM 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:28:14,432 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:28:14,432 - DEBUG - interface for vm 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 deleted from vrouter uve ... 2025-10-13 02:28:14,432 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:28:17,433 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:28:17,465 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:28:17,493 - DEBUG - VM 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:28:17,493 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:28:17,493 - DEBUG - interface for vm 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 deleted from vrouter uve ... 2025-10-13 02:28:17,493 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:28:20,494 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:28:20,525 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:28:20,552 - DEBUG - VM 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:28:20,552 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:28:20,552 - DEBUG - interface for vm 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 deleted from vrouter uve ... 2025-10-13 02:28:20,552 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:28:23,553 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:28:23,584 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:28:23,615 - DEBUG - VM 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is still present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:28:23,615 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:28:23,615 - DEBUG - interface for vm 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 deleted from vrouter uve ... 2025-10-13 02:28:23,615 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:28:26,616 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/generator/an-jenkins-deploy-platform-ansible-os-4296-1:Compute:contrail-vrouter-agent:0?flat 2025-10-13 02:28:26,649 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/vrouter/an-jenkins-deploy-platform-ansible-os-4296-1?flat 2025-10-13 02:28:26,679 - DEBUG - VM 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is not present in vrouter an-jenkins-deploy-platform-ansible-os-4296-1 uve 2025-10-13 02:28:26,679 - DEBUG - Verifying if the vm interface deleted from vroter uve... 2025-10-13 02:28:26,679 - DEBUG - interface for vm 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 deleted from vrouter uve ... 2025-10-13 02:28:26,679 - DEBUG - Deleted interface not in error interface list ... 2025-10-13 02:28:26,679 - DEBUG - Validated that VM 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is removed in Vrouter UVE 2025-10-13 02:28:26,679 - DEBUG - Verifying through opserver in 10.0.0.22 2025-10-13 02:28:26,679 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817?flat 2025-10-13 02:28:26,697 - DEBUG - 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 BM not in default-domain:ctest-TestPolicyAcl-56827226:ctest-VN1-19204817 uve 2025-10-13 02:28:26,697 - DEBUG - Validated that VM 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is not present in the VN UVE 2025-10-13 02:28:26,697 - DEBUG - Verifying the 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 virtual network link through opserver 10.0.0.22 2025-10-13 02:28:26,697 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-machines 2025-10-13 02:28:26,709 - DEBUG - No links retuned for 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 2025-10-13 02:28:26,709 - INFO - 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 vm uve deleted from opserver 2025-10-13 02:28:26,709 - DEBUG - Verifying the 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 virtual network link through opserver 10.0.0.19 2025-10-13 02:28:26,709 - DEBUG - Requesting: http://10.0.0.19:8081/analytics/uves/virtual-machines 2025-10-13 02:28:26,720 - DEBUG - No links retuned for 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 2025-10-13 02:28:26,720 - INFO - 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 vm uve deleted from opserver 2025-10-13 02:28:26,720 - DEBUG - Verifying the 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 virtual network link through opserver 10.0.0.41 2025-10-13 02:28:26,720 - DEBUG - Requesting: http://10.0.0.41:8081/analytics/uves/virtual-machines 2025-10-13 02:28:26,735 - DEBUG - No links retuned for 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 2025-10-13 02:28:26,735 - INFO - 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 vm uve deleted from opserver 2025-10-13 02:28:26,735 - INFO - Validated that VM 76d2ca90-576f-4e04-bccf-9c0a636d4aa8 is removed from Opserver 2025-10-13 02:28:26,735 - DEBUG - Detaching the Policy for VN :ctest-VN2-46363676 2025-10-13 02:28:26,735 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:28:26,741 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c 2025-10-13 02:28:26,780 - DEBUG - Requesting: http://10.0.0.22:8082/project/225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:28:26,878 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/19e813f3-7931-4110-825c-6188b557f6f1 2025-10-13 02:28:26,889 - DEBUG - Requesting: http://10.0.0.22:8082/network-policy/21046fdb-22e5-4c62-9f8a-75e2d85ac017 2025-10-13 02:28:27,046 - DEBUG - Detached Policy:[['default-domain', 'ctest-TestPolicyAcl-56827226', 'ctest-policy21-86286567']] from ctest-VN2-46363676 2025-10-13 02:28:27,047 - DEBUG - Detaching the Policy for VN :ctest-VN1-19204817 2025-10-13 02:28:27,047 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:28:27,052 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c 2025-10-13 02:28:27,065 - DEBUG - Requesting: http://10.0.0.22:8082/project/225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:28:27,181 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/c0f8c094-04bd-4906-a426-3ac028d94a1c 2025-10-13 02:28:27,364 - INFO - Detached Policy from ctest-VN1-19204817 2025-10-13 02:28:27,399 - INFO - Deleted policy ctest-policy13-94823132 2025-10-13 02:28:27,448 - INFO - Deleted policy ctest-policy21-86286567 2025-10-13 02:28:27,486 - INFO - Deleted policy ctest-policy12-84848498 2025-10-13 02:28:27,487 - INFO - Deleting VN ctest-VN3-64334383 2025-10-13 02:28:27,653 - DEBUG - Response for deleting network () 2025-10-13 02:28:27,654 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/742cc23c-b907-49d8-95fe-dcfaa1f03118 2025-10-13 02:28:27,660 - DEBUG - Response Code: 404 2025-10-13 02:28:27,660 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:28:27,664 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c 2025-10-13 02:28:27,673 - DEBUG - Requesting: http://10.0.0.22:8082/project/225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:28:27,786 - INFO - Validated that VN ctest-VN3-64334383 is not found in API Server 2025-10-13 02:28:27,803 - DEBUG - VN ctest-VN3-64334383 is not present in Agent 10.0.0.249 2025-10-13 02:28:27,817 - DEBUG - VN ctest-VN3-64334383 is not present in Agent 10.0.0.27 2025-10-13 02:28:27,817 - INFO - Validated that VN ctest-VN3-64334383 is not in any agent 2025-10-13 02:28:27,860 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN3-64334383 info 2025-10-13 02:28:27,860 - INFO - Deleting VN ctest-VN2-46363676 2025-10-13 02:28:28,036 - DEBUG - Response for deleting network () 2025-10-13 02:28:28,036 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/07f663cd-f36f-4d61-8982-0b5b360e3566 2025-10-13 02:28:28,041 - DEBUG - Response Code: 404 2025-10-13 02:28:28,041 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:28:28,046 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c 2025-10-13 02:28:28,057 - DEBUG - Requesting: http://10.0.0.22:8082/project/225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:28:28,147 - INFO - Validated that VN ctest-VN2-46363676 is not found in API Server 2025-10-13 02:28:28,157 - DEBUG - VN ctest-VN2-46363676 is not present in Agent 10.0.0.249 2025-10-13 02:28:28,169 - DEBUG - VN ctest-VN2-46363676 is not present in Agent 10.0.0.27 2025-10-13 02:28:28,169 - INFO - Validated that VN ctest-VN2-46363676 is not in any agent 2025-10-13 02:28:28,199 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN2-46363676 info 2025-10-13 02:28:28,199 - INFO - Deleting VN ctest-VN1-19204817 2025-10-13 02:28:28,375 - DEBUG - Response for deleting network () 2025-10-13 02:28:28,375 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e150dfbc-2a1d-4014-a0ef-188dc7c48cd6 2025-10-13 02:28:28,382 - DEBUG - Response Code: 404 2025-10-13 02:28:28,382 - DEBUG - Requesting: http://10.0.0.22:8082/domains 2025-10-13 02:28:28,387 - DEBUG - Requesting: http://10.0.0.22:8082/domain/3f9f083c-1111-458a-a630-ac922d5b730c 2025-10-13 02:28:28,442 - DEBUG - Requesting: http://10.0.0.22:8082/project/225e6697-4a33-4311-bd72-480fc04d1c57 2025-10-13 02:28:28,531 - INFO - Validated that VN ctest-VN1-19204817 is not found in API Server 2025-10-13 02:28:28,541 - DEBUG - VN ctest-VN1-19204817 is not present in Agent 10.0.0.249 2025-10-13 02:28:28,553 - DEBUG - VN ctest-VN1-19204817 is not present in Agent 10.0.0.27 2025-10-13 02:28:28,553 - INFO - Validated that VN ctest-VN1-19204817 is not in any agent 2025-10-13 02:28:28,580 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-VN1-19204817 info 2025-10-13 02:28:28,672 - INFO - IPAM: ipam3 is not found in API Server 2025-10-13 02:28:28,690 - INFO - IPAM:ipam3 is not found in control node 2025-10-13 02:28:28,820 - INFO - IPAM: ipam2 is not found in API Server 2025-10-13 02:28:28,840 - INFO - IPAM:ipam2 is not found in control node 2025-10-13 02:28:28,972 - INFO - IPAM: ipam1 is not found in API Server 2025-10-13 02:28:28,993 - INFO - IPAM:ipam1 is not found in control node 2025-10-13 02:28:30,261 - DEBUG - No XMPP flaps were noticed during the test

2025-10-13 02:28:30,261 - INFO - END TEST : test_policy_inheritance_src_vn_dst_pol : PASSED[0:03:42]

2025-10-13 02:28:30,261 - INFO - -------------------------------------------------------------------------------- 2025-10-13 02:28:30,998 - INFO - Deleted project: ctest-TestPolicyAcl-56827226, ID : 225e6697-4a33-4311-bd72-480fc04d1c57