2025-07-23 14:58:12,489 - INFO - Domain Default found not creating 2025-07-23 14:58:12,677 - INFO - Project ctest-TestSvcRegr-50540265 not found, creating it 2025-07-23 14:58:13,194 - INFO - Created Project:ctest-TestSvcRegr-50540265, ID : f2429a2e-ec24-458e-ba57-8c1708a2953c 2025-07-23 14:58:14,740 - INFO - ================================================================================ 2025-07-23 14:58:14,740 - INFO - STARTING TEST : test_svc_in_network_datapath 2025-07-23 14:58:15,007 - DEBUG - Nothing to compare xmpp stats {'10.0.0.129': {'10.20.0.25': '0'}} with 2025-07-23 14:58:15,007 - INFO - Initial checks done. Running the testcase now 2025-07-23 14:58:15,007 - INFO - 2025-07-23 14:58:15,569 - DEBUG - Response for create_network : {'network': {'id': 'b103d47c-ea7c-467d-b1a4-58418588c470', 'name': 'ctest-mgmt_vn-17976114', 'tenant_id': 'f2429a2eec24458eba578c1708a2953c', 'project_id': 'f2429a2eec24458eba578c1708a2953c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-50540265', 'ctest-mgmt_vn-17976114'], 'port_security_enabled': True, 'description': ''}} 2025-07-23 14:58:15,806 - DEBUG - Response for create_subnet : {'subnet': {'id': '01292874-7cea-4d21-8d03-f2f249122811', 'name': '', 'tenant_id': 'f2429a2eec24458eba578c1708a2953c', 'network_id': 'b103d47c-ea7c-467d-b1a4-58418588c470', 'ip_version': 4, 'cidr': '76.239.242.64/26', 'allocation_pools': [{'start': '76.239.242.66', 'end': '76.239.242.126'}], 'gateway_ip': '76.239.242.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '76.239.242.66', 'tags': [], 'project_id': 'f2429a2eec24458eba578c1708a2953c'}} 2025-07-23 14:58:15,827 - INFO - Created VN ctest-mgmt_vn-17976114 2025-07-23 14:58:15,885 - DEBUG - VN ctest-mgmt_vn-17976114 UUID is b103d47c-ea7c-467d-b1a4-58418588c470 2025-07-23 14:58:15,996 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2025-07-23 14:58:16,299 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2025-07-23 14:58:16,329 - DEBUG - Requesting: http://10.0.0.129:8082/domain/eb013d9f-4d4e-4e11-bece-55bf7e4da03b 2025-07-23 14:58:16,347 - DEBUG - Requesting: http://10.0.0.129:8082/project/f2429a2e-ec24-458e-ba57-8c1708a2953c 2025-07-23 14:58:16,429 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/b103d47c-ea7c-467d-b1a4-58418588c470 2025-07-23 14:58:16,441 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/b103d47c-ea7c-467d-b1a4-58418588c470 2025-07-23 14:58:16,454 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/d236ed99-c39d-44bd-9ebc-b54b353b6ba6 2025-07-23 14:58:16,463 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/d236ed99-c39d-44bd-9ebc-b54b353b6ba6 2025-07-23 14:58:16,472 - DEBUG - Requesting: http://10.0.0.129:8082/route-target/c30d46cd-59e0-4783-9375-5b1cfa9c7928 2025-07-23 14:58:16,481 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-07-23 14:58:16,481 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/b103d47c-ea7c-467d-b1a4-58418588c470 2025-07-23 14:58:16,495 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/d236ed99-c39d-44bd-9ebc-b54b353b6ba6 2025-07-23 14:58:16,516 - INFO - Verified VN network id 12 for VN b103d47c-ea7c-467d-b1a4-58418588c470 2025-07-23 14:58:16,516 - INFO - Verifications in API Server for VN ctest-mgmt_vn-17976114 passed 2025-07-23 14:58:16,516 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/b103d47c-ea7c-467d-b1a4-58418588c470 2025-07-23 14:58:16,527 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/d236ed99-c39d-44bd-9ebc-b54b353b6ba6 2025-07-23 14:58:16,535 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/d236ed99-c39d-44bd-9ebc-b54b353b6ba6 2025-07-23 14:58:16,543 - DEBUG - Requesting: http://10.0.0.129:8082/route-target/c30d46cd-59e0-4783-9375-5b1cfa9c7928 2025-07-23 14:58:16,559 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-50540265', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12755272202618422909', 'uuid-lslong': '12800453079330243696'}, 'enable': 'true', 'created': '2025-07-23T14:58:15', 'last-modified': '2025-07-23T14:58:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.786953'} 2025-07-23 14:58:16,567 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-07-23 14:58:16,567 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-17976114 passed 2025-07-23 14:58:16,567 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-17976114 in API_Server ====== 2025-07-23 14:58:16,567 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2025-07-23 14:58:16,574 - DEBUG - Requesting: http://10.0.0.129:8082/domain/eb013d9f-4d4e-4e11-bece-55bf7e4da03b 2025-07-23 14:58:16,590 - DEBUG - Requesting: http://10.0.0.129:8082/project/f2429a2e-ec24-458e-ba57-8c1708a2953c 2025-07-23 14:58:16,674 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/b103d47c-ea7c-467d-b1a4-58418588c470 2025-07-23 14:58:16,687 - DEBUG - =>VN ctest-mgmt_vn-17976114 has no policy to be verified 2025-07-23 14:58:16,687 - DEBUG - Verifying the vn in opserver 2025-07-23 14:58:16,687 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114 virtual network link through opserver 10.0.0.129 2025-07-23 14:58:16,687 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks 2025-07-23 14:58:16,981 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks 2025-07-23 14:58:17,042 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114?flat'} 2025-07-23 14:58:17,043 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114 is found in opserver 2025-07-23 14:58:17,056 - DEBUG - Do not have enough data to verify VN in agent 2025-07-23 14:58:17,063 - DEBUG - VRF ids for VN ctest-mgmt_vn-17976114: {} 2025-07-23 14:58:17,263 - DEBUG - Response for create_network : {'network': {'id': 'd5745427-9aa8-451d-88cf-90c7c5be8157', 'name': 'ctest-left_vn-86875216', 'tenant_id': 'f2429a2eec24458eba578c1708a2953c', 'project_id': 'f2429a2eec24458eba578c1708a2953c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-50540265', 'ctest-left_vn-86875216'], 'port_security_enabled': True, 'description': ''}} 2025-07-23 14:58:17,447 - DEBUG - Response for create_subnet : {'subnet': {'id': '242451c8-e97d-49a5-bde9-70d2db87c6a0', 'name': '', 'tenant_id': 'f2429a2eec24458eba578c1708a2953c', 'network_id': 'd5745427-9aa8-451d-88cf-90c7c5be8157', 'ip_version': 4, 'cidr': '6.27.81.192/26', 'allocation_pools': [{'start': '6.27.81.194', 'end': '6.27.81.254'}], 'gateway_ip': '6.27.81.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '6.27.81.194', 'tags': [], 'project_id': 'f2429a2eec24458eba578c1708a2953c'}} 2025-07-23 14:58:17,466 - INFO - Created VN ctest-left_vn-86875216 2025-07-23 14:58:17,521 - DEBUG - VN ctest-left_vn-86875216 UUID is d5745427-9aa8-451d-88cf-90c7c5be8157 2025-07-23 14:58:17,636 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2025-07-23 14:58:17,643 - DEBUG - Requesting: http://10.0.0.129:8082/domain/eb013d9f-4d4e-4e11-bece-55bf7e4da03b 2025-07-23 14:58:17,657 - DEBUG - Requesting: http://10.0.0.129:8082/project/f2429a2e-ec24-458e-ba57-8c1708a2953c 2025-07-23 14:58:17,733 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/d5745427-9aa8-451d-88cf-90c7c5be8157 2025-07-23 14:58:17,745 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/d5745427-9aa8-451d-88cf-90c7c5be8157 2025-07-23 14:58:17,756 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/920dd634-9c3d-4ee6-b66e-b2f9cdf89649 2025-07-23 14:58:17,764 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/920dd634-9c3d-4ee6-b66e-b2f9cdf89649 2025-07-23 14:58:17,772 - DEBUG - Requesting: http://10.0.0.129:8082/route-target/5603597d-a75c-4bf6-b56e-2aa5b7931ec9 2025-07-23 14:58:17,781 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-07-23 14:58:17,781 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/d5745427-9aa8-451d-88cf-90c7c5be8157 2025-07-23 14:58:17,793 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/920dd634-9c3d-4ee6-b66e-b2f9cdf89649 2025-07-23 14:58:17,813 - INFO - Verified VN network id 13 for VN d5745427-9aa8-451d-88cf-90c7c5be8157 2025-07-23 14:58:17,814 - INFO - Verifications in API Server for VN ctest-left_vn-86875216 passed 2025-07-23 14:58:17,814 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/d5745427-9aa8-451d-88cf-90c7c5be8157 2025-07-23 14:58:17,827 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/920dd634-9c3d-4ee6-b66e-b2f9cdf89649 2025-07-23 14:58:17,836 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/920dd634-9c3d-4ee6-b66e-b2f9cdf89649 2025-07-23 14:58:17,847 - DEBUG - Requesting: http://10.0.0.129:8082/route-target/5603597d-a75c-4bf6-b56e-2aa5b7931ec9 2025-07-23 14:58:17,862 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-50540265', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15381011156452263197', 'uuid-lslong': '9858257297027793239'}, 'enable': 'true', 'created': '2025-07-23T14:58:17', 'last-modified': '2025-07-23T14:58:17', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.456532'} 2025-07-23 14:58:17,870 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-07-23 14:58:17,870 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-86875216 passed 2025-07-23 14:58:17,870 - DEBUG - ====Verifying policy data for ctest-left_vn-86875216 in API_Server ====== 2025-07-23 14:58:17,870 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2025-07-23 14:58:17,879 - DEBUG - Requesting: http://10.0.0.129:8082/domain/eb013d9f-4d4e-4e11-bece-55bf7e4da03b 2025-07-23 14:58:17,897 - DEBUG - Requesting: http://10.0.0.129:8082/project/f2429a2e-ec24-458e-ba57-8c1708a2953c 2025-07-23 14:58:17,977 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/d5745427-9aa8-451d-88cf-90c7c5be8157 2025-07-23 14:58:17,990 - DEBUG - =>VN ctest-left_vn-86875216 has no policy to be verified 2025-07-23 14:58:17,990 - DEBUG - Verifying the vn in opserver 2025-07-23 14:58:17,991 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216 virtual network link through opserver 10.0.0.129 2025-07-23 14:58:17,991 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks 2025-07-23 14:58:18,004 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216?flat'} 2025-07-23 14:58:18,004 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216 is found in opserver 2025-07-23 14:58:18,017 - DEBUG - Do not have enough data to verify VN in agent 2025-07-23 14:58:18,023 - DEBUG - VRF ids for VN ctest-left_vn-86875216: {} 2025-07-23 14:58:18,226 - DEBUG - Response for create_network : {'network': {'id': 'cc661377-8942-40f6-9b21-f507d68b0fc4', 'name': 'ctest-right_vn-21720077', 'tenant_id': 'f2429a2eec24458eba578c1708a2953c', 'project_id': 'f2429a2eec24458eba578c1708a2953c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-50540265', 'ctest-right_vn-21720077'], 'port_security_enabled': True, 'description': ''}} 2025-07-23 14:58:18,450 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f045f7c4-e45f-404a-bebe-1181c8ea2a4d', 'name': '', 'tenant_id': 'f2429a2eec24458eba578c1708a2953c', 'network_id': 'cc661377-8942-40f6-9b21-f507d68b0fc4', 'ip_version': 4, 'cidr': '75.138.68.64/26', 'allocation_pools': [{'start': '75.138.68.66', 'end': '75.138.68.126'}], 'gateway_ip': '75.138.68.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '75.138.68.66', 'tags': [], 'project_id': 'f2429a2eec24458eba578c1708a2953c'}} 2025-07-23 14:58:18,469 - INFO - Created VN ctest-right_vn-21720077 2025-07-23 14:58:18,521 - DEBUG - VN ctest-right_vn-21720077 UUID is cc661377-8942-40f6-9b21-f507d68b0fc4 2025-07-23 14:58:18,641 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2025-07-23 14:58:18,648 - DEBUG - Requesting: http://10.0.0.129:8082/domain/eb013d9f-4d4e-4e11-bece-55bf7e4da03b 2025-07-23 14:58:18,663 - DEBUG - Requesting: http://10.0.0.129:8082/project/f2429a2e-ec24-458e-ba57-8c1708a2953c 2025-07-23 14:58:18,735 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/cc661377-8942-40f6-9b21-f507d68b0fc4 2025-07-23 14:58:18,746 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/cc661377-8942-40f6-9b21-f507d68b0fc4 2025-07-23 14:58:18,759 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/e4abcd65-41ec-440c-90d1-53dca8c3bea7 2025-07-23 14:58:18,769 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/e4abcd65-41ec-440c-90d1-53dca8c3bea7 2025-07-23 14:58:18,780 - DEBUG - Requesting: http://10.0.0.129:8082/route-target/88c1924a-7541-4aaf-848f-78fee3d5badf 2025-07-23 14:58:18,789 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-07-23 14:58:18,789 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/cc661377-8942-40f6-9b21-f507d68b0fc4 2025-07-23 14:58:18,801 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/e4abcd65-41ec-440c-90d1-53dca8c3bea7 2025-07-23 14:58:18,818 - INFO - Verified VN network id 14 for VN cc661377-8942-40f6-9b21-f507d68b0fc4 2025-07-23 14:58:18,818 - INFO - Verifications in API Server for VN ctest-right_vn-21720077 passed 2025-07-23 14:58:18,818 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/cc661377-8942-40f6-9b21-f507d68b0fc4 2025-07-23 14:58:18,829 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/e4abcd65-41ec-440c-90d1-53dca8c3bea7 2025-07-23 14:58:18,839 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/e4abcd65-41ec-440c-90d1-53dca8c3bea7 2025-07-23 14:58:18,848 - DEBUG - Requesting: http://10.0.0.129:8082/route-target/88c1924a-7541-4aaf-848f-78fee3d5badf 2025-07-23 14:58:18,863 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-50540265', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14728481035486642422', 'uuid-lslong': '11178485164123295684'}, 'enable': 'true', 'created': '2025-07-23T14:58:18', 'last-modified': '2025-07-23T14:58:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.443264'} 2025-07-23 14:58:18,874 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-07-23 14:58:18,874 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-21720077 passed 2025-07-23 14:58:18,874 - DEBUG - ====Verifying policy data for ctest-right_vn-21720077 in API_Server ====== 2025-07-23 14:58:18,874 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2025-07-23 14:58:18,883 - DEBUG - Requesting: http://10.0.0.129:8082/domain/eb013d9f-4d4e-4e11-bece-55bf7e4da03b 2025-07-23 14:58:18,898 - DEBUG - Requesting: http://10.0.0.129:8082/project/f2429a2e-ec24-458e-ba57-8c1708a2953c 2025-07-23 14:58:19,021 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-network/cc661377-8942-40f6-9b21-f507d68b0fc4 2025-07-23 14:58:19,037 - DEBUG - =>VN ctest-right_vn-21720077 has no policy to be verified 2025-07-23 14:58:19,037 - DEBUG - Verifying the vn in opserver 2025-07-23 14:58:19,037 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077 virtual network link through opserver 10.0.0.129 2025-07-23 14:58:19,037 - DEBUG - Requesting: http://10.0.0.129:8081/analytics/uves/virtual-networks 2025-07-23 14:58:19,049 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077', 'href': 'http://10.0.0.129:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077?flat'} 2025-07-23 14:58:19,049 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077 is found in opserver 2025-07-23 14:58:19,061 - DEBUG - Do not have enough data to verify VN in agent 2025-07-23 14:58:19,070 - DEBUG - VRF ids for VN ctest-right_vn-21720077: {} 2025-07-23 14:58:19,130 - DEBUG - Services list from nova: [, , ] 2025-07-23 14:58:20,207 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3705-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3705-1) 2025-07-23 14:58:21,373 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3705-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3705-1) 2025-07-23 14:58:21,373 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-11856688'] 2025-07-23 14:58:21,757 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-11856688'] created succesfully 2025-07-23 14:58:22,115 - DEBUG - Image by name cirros_in_net not found 2025-07-23 14:58:22,115 - DEBUG - Installing image cirros_in_net 2025-07-23 14:58:22,115 - DEBUG - Image info {'name': 'cirros-in-net.vmdk.gz', 'webserver': '', 'location': '/images/converts/', 'username': 'cirros', 'password': 'gocubsgo', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hw_scsi_model=virtio-scsi --property hw_disk_bus_model=virtio-scsi --property hw_disk_bus=virtio', 'name_docker': 'phusion-baseimage-enablesshd'} 2025-07-23 14:58:22,115 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-07-23 14:58:22,115 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-07-23 14:58:22,127 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz -O /contrail-test/images/cirros-in-net.vmdk.gz 2025-07-23 14:58:22,240 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz 2025-07-23 14:58:22,240 - DEBUG - Unzip image 2025-07-23 14:58:22,240 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz 2025-07-23 14:58:22,422 - DEBUG - Try to create image in user context 2025-07-23 14:58:22,684 - DEBUG - Image has been created in user context 2025-07-23 14:58:22,685 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk 2025-07-23 14:58:22,702 - DEBUG - Downloaded image has been removed 2025-07-23 14:58:22,722 - DEBUG - Uploaded image status = active 2025-07-23 14:58:23,917 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3705-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3705-1) 2025-07-23 14:58:23,918 - INFO - Waiting for VM ctest-pt_svm0-40135722 to be up.. 2025-07-23 14:58:23,965 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-07-23 14:58:29,084 - DEBUG - VM is in ACTIVE state now 2025-07-23 14:58:29,084 - INFO - VM name : ctest-pt_svm0-40135722 2025-07-23 14:58:29,183 - DEBUG - VM ctest-pt_svm0-40135722 ID is 4ef67c3e-b29a-48da-b26d-32cae65e4425 2025-07-23 14:58:29,217 - DEBUG - VM ctest-pt_svm0-40135722 launched on Node cn-jenkins-deploy-platform-ansible-os-3705-1 2025-07-23 14:58:29,500 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/4ef67c3e-b29a-48da-b26d-32cae65e4425 2025-07-23 14:58:29,512 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/0b197eb2-dfd7-4100-b599-a365a8f67180 2025-07-23 14:58:29,524 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/230c8bdc-4cb4-43e5-b637-45a3b9688f5f 2025-07-23 14:58:29,536 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/a28819dc-7805-415e-84ea-0244ae4454b6 2025-07-23 14:58:32,846 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-07-23 14:58:32,846 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-40135722 failed! 2025-07-23 14:58:32,909 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114 is 76.239.242.65 and allocation pool is NOT set 2025-07-23 14:58:36,968 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1002ms') 2025-07-23 14:58:36,968 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-40135722 failed! 2025-07-23 14:58:37,025 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114 is 76.239.242.65 and allocation pool is NOT set 2025-07-23 14:58:41,097 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2025-07-23 14:58:41,097 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-40135722 failed! 2025-07-23 14:58:41,153 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114 is 76.239.242.65 and allocation pool is NOT set 2025-07-23 14:58:45,226 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1009ms') 2025-07-23 14:58:45,227 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-40135722 failed! 2025-07-23 14:58:45,285 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114 is 76.239.242.65 and allocation pool is NOT set 2025-07-23 14:58:47,352 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=4.06 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.753 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.753/2.405/4.057/1.652 ms') 2025-07-23 14:58:47,352 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-40135722 passed 2025-07-23 14:58:47,505 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:58:47,505 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-40135722, IP 76.239.242.67, Port 22 2025-07-23 14:58:47,572 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-07-23 14:58:47,662 - DEBUG - VM ctest-pt_svm0-40135722 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-23 14:58:52,663 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:58:52,663 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-40135722, IP 76.239.242.67, Port 22 2025-07-23 14:58:52,721 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-07-23 14:58:52,824 - DEBUG - VM ctest-pt_svm0-40135722 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-23 14:58:57,825 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:58:57,825 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-40135722, IP 76.239.242.67, Port 22 2025-07-23 14:58:57,880 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-07-23 14:58:57,958 - DEBUG - VM ctest-pt_svm0-40135722 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-23 14:59:02,959 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:59:02,960 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-40135722, IP 76.239.242.67, Port 22 2025-07-23 14:59:03,017 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-07-23 14:59:03,106 - DEBUG - VM ctest-pt_svm0-40135722 is NOT ready for SSH connections, VM status: ACTIVE 2025-07-23 14:59:08,107 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:59:08,107 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-40135722, IP 76.239.242.67, Port 22 2025-07-23 14:59:08,264 - DEBUG - VM ctest-pt_svm0-40135722 is ready for SSH connections 2025-07-23 14:59:08,417 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:59:08,417 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-40135722, IP 76.239.242.67, Port 22 2025-07-23 14:59:08,568 - DEBUG - VM ctest-pt_svm0-40135722 is ready for SSH connections 2025-07-23 14:59:08,721 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:59:08,721 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-40135722, IP 76.239.242.67, Port 22 2025-07-23 14:59:08,883 - DEBUG - VM ctest-pt_svm0-40135722 is ready for SSH connections 2025-07-23 14:59:08,891 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSvcRegr-50540265', 'ctest-si-75339413'] 2025-07-23 14:59:09,845 - INFO - Waiting for VM ctest-left_vm-89081685 to be up.. 2025-07-23 14:59:09,977 - DEBUG - VM is in ACTIVE state now 2025-07-23 14:59:09,977 - INFO - VM name : ctest-left_vm-89081685 2025-07-23 14:59:10,062 - DEBUG - VM ctest-left_vm-89081685 ID is 7f57f4b4-704b-404e-978c-1daa8870f9a3 2025-07-23 14:59:10,062 - DEBUG - VM ctest-left_vm-89081685 launched on Node cn-jenkins-deploy-platform-ansible-os-3705-1 2025-07-23 14:59:10,161 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/7f57f4b4-704b-404e-978c-1daa8870f9a3 2025-07-23 14:59:10,173 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/8da5f395-919c-4cf7-bc68-48c0fbaf7ce3 2025-07-23 14:59:11,440 - 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.91 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.382 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.382/1.647/2.912/1.265 ms') 2025-07-23 14:59:11,440 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-89081685 passed 2025-07-23 14:59:11,597 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:59:11,597 - DEBUG - Waiting to SSH to VM ctest-left_vm-89081685, IP 6.27.81.195, Port 22 2025-07-23 14:59:11,768 - DEBUG - VM ctest-left_vm-89081685 is ready for SSH connections 2025-07-23 14:59:11,768 - INFO - Waiting for VM ctest-right_vm-48182073 to be up.. 2025-07-23 14:59:11,866 - DEBUG - VM is in ACTIVE state now 2025-07-23 14:59:11,867 - INFO - VM name : ctest-right_vm-48182073 2025-07-23 14:59:11,965 - DEBUG - VM ctest-right_vm-48182073 ID is d9ecd504-7a9f-4898-99b1-0c06a15bbf16 2025-07-23 14:59:11,965 - DEBUG - VM ctest-right_vm-48182073 launched on Node cn-jenkins-deploy-platform-ansible-os-3705-1 2025-07-23 14:59:12,059 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/d9ecd504-7a9f-4898-99b1-0c06a15bbf16 2025-07-23 14:59:12,071 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/e52e19ce-d815-4171-90c9-bc7aba2f34ed 2025-07-23 14:59:13,322 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=3.05 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.477 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.477/1.765/3.054/1.288 ms') 2025-07-23 14:59:13,322 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-48182073 passed 2025-07-23 14:59:13,477 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:59:13,477 - DEBUG - Waiting to SSH to VM ctest-right_vm-48182073, IP 75.138.68.67, Port 22 2025-07-23 14:59:13,643 - DEBUG - VM ctest-right_vm-48182073 is ready for SSH connections 2025-07-23 14:59:13,654 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = 65535], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = 65535], action_list = simple_action = pass, gateway_name = None, apply_service = ['default-domain:ctest-TestSvcRegr-50540265:ctest-si-75339413'], 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-07-23 14:59:14,105 - INFO - Attaching policy ctest-policy-42980632 to vn ctest-left_vn-86875216 2025-07-23 14:59:14,645 - INFO - Attaching policy ctest-policy-42980632 to vn ctest-right_vn-21720077 2025-07-23 14:59:15,097 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-11856688'] created succesfully 2025-07-23 14:59:15,325 - DEBUG - Service instance: ['default-domain', 'ctest-TestSvcRegr-50540265', 'ctest-si-75339413'] created succesfully 2025-07-23 14:59:15,325 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2025-07-23 14:59:15,332 - DEBUG - Requesting: http://10.0.0.129:8082/domain/eb013d9f-4d4e-4e11-bece-55bf7e4da03b 2025-07-23 14:59:15,350 - DEBUG - Requesting: http://10.0.0.129:8082/project/f2429a2e-ec24-458e-ba57-8c1708a2953c 2025-07-23 14:59:15,431 - DEBUG - Requesting: http://10.0.0.129:8082/service-instance/f439c1cc-0302-4b25-a6b9-bbadb1103637 2025-07-23 14:59:15,446 - DEBUG - SI 'ctest-si-75339413' has service template ref to ctest-service_template_1-11856688 2025-07-23 14:59:15,446 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2025-07-23 14:59:15,454 - DEBUG - Requesting: http://10.0.0.129:8082/domain/eb013d9f-4d4e-4e11-bece-55bf7e4da03b 2025-07-23 14:59:15,468 - DEBUG - Requesting: http://10.0.0.129:8082/project/f2429a2e-ec24-458e-ba57-8c1708a2953c 2025-07-23 14:59:15,548 - DEBUG - Requesting: http://10.0.0.129:8082/service-instance/f439c1cc-0302-4b25-a6b9-bbadb1103637 2025-07-23 14:59:15,559 - DEBUG - Requesting: http://10.0.0.129:8082/port-tuple/64d1a261-7559-4557-9919-1eb7c793ea66 2025-07-23 14:59:15,567 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/0b197eb2-dfd7-4100-b599-a365a8f67180 2025-07-23 14:59:15,579 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/230c8bdc-4cb4-43e5-b637-45a3b9688f5f 2025-07-23 14:59:15,592 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/a28819dc-7805-415e-84ea-0244ae4454b6 2025-07-23 14:59:15,820 - DEBUG - VM is in ACTIVE state now 2025-07-23 14:59:16,317 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/4ef67c3e-b29a-48da-b26d-32cae65e4425 2025-07-23 14:59:16,325 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/0b197eb2-dfd7-4100-b599-a365a8f67180 2025-07-23 14:59:16,338 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/230c8bdc-4cb4-43e5-b637-45a3b9688f5f 2025-07-23 14:59:16,349 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/a28819dc-7805-415e-84ea-0244ae4454b6 2025-07-23 14:59:16,361 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/2618beab-dbc9-4fba-86e5-0b8617ae1536 2025-07-23 14:59:16,370 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/ac322b80-d67f-469f-8dc9-559206014eec 2025-07-23 14:59:16,379 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/e5701af0-4987-443b-8d59-e0aa944b9116 2025-07-23 14:59:16,387 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/0deedba1-c666-46cf-a132-64aee4182d6e 2025-07-23 14:59:16,396 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/d7027fd4-2474-4f44-9063-4fd7cf61e64a 2025-07-23 14:59:16,405 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/f3ae2e67-9cc5-460a-a8e3-35a79f9b3302 2025-07-23 14:59:16,415 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/00b9c2d5-a850-4585-9c71-a9754d9244eb 2025-07-23 14:59:16,425 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/24e6cce0-c84f-410d-b09c-5fbc4d9bf9a8 2025-07-23 14:59:16,434 - DEBUG - Requesting: http://10.0.0.129:8082/instance-ip/bfb0fbd7-f0a1-4a06-a697-3a53cb885d2c 2025-07-23 14:59:16,841 - DEBUG - VM is in ACTIVE state now 2025-07-23 14:59:16,841 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/4ef67c3e-b29a-48da-b26d-32cae65e4425 2025-07-23 14:59:16,853 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/0b197eb2-dfd7-4100-b599-a365a8f67180 2025-07-23 14:59:16,867 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/230c8bdc-4cb4-43e5-b637-45a3b9688f5f 2025-07-23 14:59:16,881 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine-interface/a28819dc-7805-415e-84ea-0244ae4454b6 2025-07-23 14:59:18,136 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n64 bytes from 169.254.0.6: icmp_seq=1 ttl=63 time=1.13 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.562 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.562/0.845/1.129/0.283 ms') 2025-07-23 14:59:18,136 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-40135722 passed 2025-07-23 14:59:18,174 - DEBUG - VM ctest-pt_svm0-40135722 Tap interface: {'index': '6', 'name': 'tap0b197eb2-df', 'uuid': '0b197eb2-dfd7-4100-b599-a365a8f67180', 'vrf_name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216:ctest-left_vn-86875216', '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': '44', 'l2_label': '48', 'vxlan_id': '13', 'vn_name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216', 'vm_uuid': '4ef67c3e-b29a-48da-b26d-32cae65e4425', 'vm_name': 'ctest-pt_svm0-40135722', 'ip_addr': '6.27.81.196', 'mac_addr': '02:0b:19:7e:b2:df', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.6', 'service_vlan_list': None, 'os_ifindex': '41', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-50540265:0b197eb2-dfd7-4100-b599-a365a8f67180', 'sg_uuid_list': ['bc90fe67-84e9-4fc2-9186-c0ff6e016235'], 'static_route_list': None, 'vm_project_uuid': 'f2429a2e-ec24-458e-ba57-8c1708a2953c', 'admin_state': 'Enabled', 'flow_key_idx': '42', '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': 'a28819dc-7805-415e-84ea-0244ae4454b6', '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-07-23 14:59:18,175 - DEBUG - Agent 10.0.0.129 vrf name: default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216:ctest-left_vn-86875216 2025-07-23 14:59:18,181 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216:ctest-left_vn-86875216', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216', 'table_label': '-1', 'vxlan_id': '13', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.25:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-07-23 14:59:18,345 - DEBUG - VM ctest-pt_svm0-40135722 : Tap interface tap0b197eb2-df is set to Active 2025-07-23 14:59:18,345 - DEBUG - Tap interface tap0b197eb2-df detail : {'index': '6', 'name': 'tap0b197eb2-df', 'uuid': '0b197eb2-dfd7-4100-b599-a365a8f67180', 'vrf_name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216:ctest-left_vn-86875216', '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': '44', 'l2_label': '48', 'vxlan_id': '13', 'vn_name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216', 'vm_uuid': '4ef67c3e-b29a-48da-b26d-32cae65e4425', 'vm_name': 'ctest-pt_svm0-40135722', 'ip_addr': '6.27.81.196', 'mac_addr': '02:0b:19:7e:b2:df', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.6', 'service_vlan_list': None, 'os_ifindex': '41', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-50540265:0b197eb2-dfd7-4100-b599-a365a8f67180', 'sg_uuid_list': ['bc90fe67-84e9-4fc2-9186-c0ff6e016235'], 'static_route_list': None, 'vm_project_uuid': 'f2429a2e-ec24-458e-ba57-8c1708a2953c', 'admin_state': 'Enabled', 'flow_key_idx': '42', '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': 'a28819dc-7805-415e-84ea-0244ae4454b6', '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-07-23 14:59:18,537 - DEBUG - Starting Layer 2 verification in Agent 2025-07-23 14:59:18,551 - DEBUG - Layer 2 path is seen for VM MAC 02:0b:19:7e:b2:df in agent 10.0.0.129 2025-07-23 14:59:18,551 - DEBUG - Active layer 2 route in agent is present for VMI tap0b197eb2-df 2025-07-23 14:59:18,551 - DEBUG - L2 label(48) matches bw route table and interface table 2025-07-23 14:59:18,557 - DEBUG - VN default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216 is found in Agent of node 10.0.0.129 2025-07-23 14:59:18,775 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216 is consistent in agent 10.0.0.129 2025-07-23 14:59:18,775 - DEBUG - Route for VM IP ['6.27.81.196'] is consistent in agent 10.0.0.129 2025-07-23 14:59:18,775 - DEBUG - VN default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216 verification for VM ctest-pt_svm0-40135722 in Agent 10.0.0.129 passed 2025-07-23 14:59:18,977 - DEBUG - Starting all layer 2 verification in agent 10.0.0.129 2025-07-23 14:59:18,991 - DEBUG - Route for VM MAC 02:0b:19:7e:b2:df is consistent in agent 10.0.0.129 2025-07-23 14:59:19,013 - DEBUG - VM ctest-pt_svm0-40135722 Tap interface: {'index': '5', 'name': 'tap230c8bdc-4c', 'uuid': '230c8bdc-4cb4-43e5-b637-45a3b9688f5f', 'vrf_name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114:ctest-mgmt_vn-17976114', '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': '39', 'l2_label': '43', 'vxlan_id': '12', 'vn_name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114', 'vm_uuid': '4ef67c3e-b29a-48da-b26d-32cae65e4425', 'vm_name': 'ctest-pt_svm0-40135722', 'ip_addr': '76.239.242.67', 'mac_addr': '02:23:0c:8b:dc:4c', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '40', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-50540265:230c8bdc-4cb4-43e5-b637-45a3b9688f5f', 'sg_uuid_list': ['bc90fe67-84e9-4fc2-9186-c0ff6e016235'], 'static_route_list': None, 'vm_project_uuid': 'f2429a2e-ec24-458e-ba57-8c1708a2953c', 'admin_state': 'Enabled', 'flow_key_idx': '43', '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-07-23 14:59:19,013 - DEBUG - Agent 10.0.0.129 vrf name: default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114:ctest-mgmt_vn-17976114 2025-07-23 14:59:19,019 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114:ctest-mgmt_vn-17976114', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114', 'table_label': '-1', 'vxlan_id': '12', 'evpnindex': '4', 'brindex': '4', 'mplsindex': '0', 'RD': '10.20.0.25:4', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-07-23 14:59:19,189 - DEBUG - VM ctest-pt_svm0-40135722 : Tap interface tap230c8bdc-4c is set to Active 2025-07-23 14:59:19,189 - DEBUG - Tap interface tap230c8bdc-4c detail : {'index': '5', 'name': 'tap230c8bdc-4c', 'uuid': '230c8bdc-4cb4-43e5-b637-45a3b9688f5f', 'vrf_name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114:ctest-mgmt_vn-17976114', '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': '39', 'l2_label': '43', 'vxlan_id': '12', 'vn_name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114', 'vm_uuid': '4ef67c3e-b29a-48da-b26d-32cae65e4425', 'vm_name': 'ctest-pt_svm0-40135722', 'ip_addr': '76.239.242.67', 'mac_addr': '02:23:0c:8b:dc:4c', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '40', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-50540265:230c8bdc-4cb4-43e5-b637-45a3b9688f5f', 'sg_uuid_list': ['bc90fe67-84e9-4fc2-9186-c0ff6e016235'], 'static_route_list': None, 'vm_project_uuid': 'f2429a2e-ec24-458e-ba57-8c1708a2953c', 'admin_state': 'Enabled', 'flow_key_idx': '43', '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-07-23 14:59:19,395 - DEBUG - Starting Layer 2 verification in Agent 2025-07-23 14:59:19,408 - DEBUG - Layer 2 path is seen for VM MAC 02:23:0c:8b:dc:4c in agent 10.0.0.129 2025-07-23 14:59:19,408 - DEBUG - Active layer 2 route in agent is present for VMI tap230c8bdc-4c 2025-07-23 14:59:19,408 - DEBUG - L2 label(43) matches bw route table and interface table 2025-07-23 14:59:19,414 - DEBUG - VN default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114 is found in Agent of node 10.0.0.129 2025-07-23 14:59:19,624 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114 is consistent in agent 10.0.0.129 2025-07-23 14:59:19,625 - DEBUG - Route for VM IP ['76.239.242.67'] is consistent in agent 10.0.0.129 2025-07-23 14:59:19,625 - DEBUG - VN default-domain:ctest-TestSvcRegr-50540265:ctest-mgmt_vn-17976114 verification for VM ctest-pt_svm0-40135722 in Agent 10.0.0.129 passed 2025-07-23 14:59:19,821 - DEBUG - Starting all layer 2 verification in agent 10.0.0.129 2025-07-23 14:59:19,832 - DEBUG - Route for VM MAC 02:23:0c:8b:dc:4c is consistent in agent 10.0.0.129 2025-07-23 14:59:19,856 - DEBUG - VM ctest-pt_svm0-40135722 Tap interface: {'index': '7', 'name': 'tapa28819dc-78', 'uuid': 'a28819dc-7805-415e-84ea-0244ae4454b6', 'vrf_name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077:ctest-right_vn-21720077', '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': '49', 'l2_label': '53', 'vxlan_id': '14', 'vn_name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077', 'vm_uuid': '4ef67c3e-b29a-48da-b26d-32cae65e4425', 'vm_name': 'ctest-pt_svm0-40135722', 'ip_addr': '75.138.68.68', 'mac_addr': '02:a2:88:19:dc:78', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.7', 'service_vlan_list': None, 'os_ifindex': '42', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-50540265:a28819dc-7805-415e-84ea-0244ae4454b6', 'sg_uuid_list': ['bc90fe67-84e9-4fc2-9186-c0ff6e016235'], 'static_route_list': None, 'vm_project_uuid': 'f2429a2e-ec24-458e-ba57-8c1708a2953c', 'admin_state': 'Enabled', 'flow_key_idx': '28', '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': '0b197eb2-dfd7-4100-b599-a365a8f67180', '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-07-23 14:59:19,856 - DEBUG - Agent 10.0.0.129 vrf name: default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077:ctest-right_vn-21720077 2025-07-23 14:59:19,863 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077:ctest-right_vn-21720077', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.25:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-07-23 14:59:20,029 - DEBUG - VM ctest-pt_svm0-40135722 : Tap interface tapa28819dc-78 is set to Active 2025-07-23 14:59:20,029 - DEBUG - Tap interface tapa28819dc-78 detail : {'index': '7', 'name': 'tapa28819dc-78', 'uuid': 'a28819dc-7805-415e-84ea-0244ae4454b6', 'vrf_name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077:ctest-right_vn-21720077', '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': '49', 'l2_label': '53', 'vxlan_id': '14', 'vn_name': 'default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077', 'vm_uuid': '4ef67c3e-b29a-48da-b26d-32cae65e4425', 'vm_name': 'ctest-pt_svm0-40135722', 'ip_addr': '75.138.68.68', 'mac_addr': '02:a2:88:19:dc:78', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.7', 'service_vlan_list': None, 'os_ifindex': '42', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'default-domain:ctest-TestSvcRegr-50540265:a28819dc-7805-415e-84ea-0244ae4454b6', 'sg_uuid_list': ['bc90fe67-84e9-4fc2-9186-c0ff6e016235'], 'static_route_list': None, 'vm_project_uuid': 'f2429a2e-ec24-458e-ba57-8c1708a2953c', 'admin_state': 'Enabled', 'flow_key_idx': '28', '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': '0b197eb2-dfd7-4100-b599-a365a8f67180', '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-07-23 14:59:20,233 - DEBUG - Starting Layer 2 verification in Agent 2025-07-23 14:59:20,245 - DEBUG - Layer 2 path is seen for VM MAC 02:a2:88:19:dc:78 in agent 10.0.0.129 2025-07-23 14:59:20,245 - DEBUG - Active layer 2 route in agent is present for VMI tapa28819dc-78 2025-07-23 14:59:20,245 - DEBUG - L2 label(53) matches bw route table and interface table 2025-07-23 14:59:20,253 - DEBUG - VN default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077 is found in Agent of node 10.0.0.129 2025-07-23 14:59:20,470 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077 is consistent in agent 10.0.0.129 2025-07-23 14:59:20,470 - DEBUG - Route for VM IP ['75.138.68.68'] is consistent in agent 10.0.0.129 2025-07-23 14:59:20,471 - DEBUG - VN default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077 verification for VM ctest-pt_svm0-40135722 in Agent 10.0.0.129 passed 2025-07-23 14:59:20,669 - DEBUG - Starting all layer 2 verification in agent 10.0.0.129 2025-07-23 14:59:20,682 - DEBUG - Route for VM MAC 02:a2:88:19:dc:78 is consistent in agent 10.0.0.129 2025-07-23 14:59:20,682 - INFO - VM ctest-pt_svm0-40135722 verifications in Compute nodes passed 2025-07-23 14:59:20,682 - DEBUG - The SVMs in the SI are : [] 2025-07-23 14:59:20,682 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/4ef67c3e-b29a-48da-b26d-32cae65e4425 2025-07-23 14:59:20,692 - DEBUG - Service VM for SI 'ctest-si-75339413' is launched 2025-07-23 14:59:20,692 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2025-07-23 14:59:20,700 - DEBUG - Requesting: http://10.0.0.129:8082/domain/eb013d9f-4d4e-4e11-bece-55bf7e4da03b 2025-07-23 14:59:20,714 - DEBUG - Requesting: http://10.0.0.129:8082/project/f2429a2e-ec24-458e-ba57-8c1708a2953c 2025-07-23 14:59:20,792 - DEBUG - Requesting: http://10.0.0.129:8082/service-instance/f439c1cc-0302-4b25-a6b9-bbadb1103637 2025-07-23 14:59:20,806 - DEBUG - SI ctest-si-75339413 has Port Tuple: ['ctest-port_tuple-08214294'] 2025-07-23 14:59:20,917 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-50540265:ctest-left_vn-86875216 2025-07-23 14:59:21,069 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-50540265:ctest-right_vn-21720077 2025-07-23 14:59:21,238 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:59:21,248 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:59:21,248 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:59:21,256 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:59:21,256 - INFO - Route to 75.138.68.67 seen in VRF:5 on cn-jenkins-deploy-platform-ansible-os-3705-1, and SI ctest-si-75339413 is seen as the NH 2025-07-23 14:59:21,422 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:59:21,434 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:59:21,434 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:59:21,443 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:59:21,443 - INFO - Route to 6.27.81.195 seen in VRF:6 on cn-jenkins-deploy-platform-ansible-os-3705-1, and SI ctest-si-75339413 is seen as the NH 2025-07-23 14:59:21,443 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:59:21,443 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-23 14:59:21,443 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 75.138.68.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.129, gateway password: c0ntrail123 2025-07-23 14:59:21,444 - DEBUG - ping -s 56 -c 3 -W 1 75.138.68.67 2025-07-23 14:59:25,463 - DEBUG - PING 75.138.68.67 (75.138.68.67): 56 data bytes 64 bytes from 75.138.68.67: seq=0 ttl=61 time=6.150 ms 64 bytes from 75.138.68.67: seq=1 ttl=61 time=1.574 ms 64 bytes from 75.138.68.67: seq=2 ttl=61 time=1.429 ms --- 75.138.68.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.429/3.051/6.150 ms 2025-07-23 14:59:25,463 - INFO - Ping to IP 75.138.68.67 from VM ctest-left_vm-89081685 passed 2025-07-23 14:59:25,464 - INFO - Dettaching policy ctest-policy-42980632 from vn ctest-right_vn-21720077 2025-07-23 14:59:25,689 - INFO - Dettaching policy ctest-policy-42980632 from vn ctest-left_vn-86875216 2025-07-23 14:59:26,002 - INFO - Deleted policy ctest-policy-42980632 2025-07-23 14:59:26,003 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSvcRegr-50540265', 'ctest-si-75339413'] 2025-07-23 14:59:26,105 - INFO - Deleted SI ['default-domain', 'ctest-TestSvcRegr-50540265', 'ctest-si-75339413'] 2025-07-23 14:59:26,105 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2025-07-23 14:59:26,112 - DEBUG - Requesting: http://10.0.0.129:8082/domain/eb013d9f-4d4e-4e11-bece-55bf7e4da03b 2025-07-23 14:59:26,128 - DEBUG - Requesting: http://10.0.0.129:8082/project/f2429a2e-ec24-458e-ba57-8c1708a2953c 2025-07-23 14:59:26,210 - DEBUG - Service instance ctest-si-75339413 removed from api server 2025-07-23 14:59:26,210 - DEBUG - Requesting: http://10.0.0.129:8082/virtual-machine/4ef67c3e-b29a-48da-b26d-32cae65e4425 2025-07-23 14:59:26,219 - DEBUG - All Service VMs unlinked from SI ctest-si-75339413 2025-07-23 14:59:26,227 - DEBUG - 0 svc intances found in all projects. They are [] 2025-07-23 14:59:26,227 - DEBUG - 0 svc intances found in current project. They are [] 2025-07-23 14:59:26,277 - DEBUG - 0 svc intances found in all projects. They are [] 2025-07-23 14:59:26,277 - DEBUG - 0 svc intances found in current project. They are [] 2025-07-23 14:59:26,277 - INFO - Deleting VM ctest-pt_svm0-40135722 2025-07-23 14:59:26,370 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-11856688'] 2025-07-23 14:59:26,473 - INFO - Service template: ['default-domain', 'ctest-service_template_1-11856688'] deleted successfully. 2025-07-23 14:59:26,473 - INFO - Deleting VM ctest-right_vm-48182073 2025-07-23 14:59:26,555 - INFO - Deleting VM ctest-left_vm-89081685 2025-07-23 14:59:26,650 - INFO - Deleting VN ctest-right_vn-21720077 2025-07-23 14:59:26,700 - DEBUG - VN cc661377-8942-40f6-9b21-f507d68b0fc4 still in use: Unable to complete operation on network cc661377-8942-40f6-9b21-f507d68b0fc4. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-07d93a70-13c9-4a08-9f93-c7b6f76ef97b'] 2025-07-23 14:59:26,700 - WARNING - Deleting VN ctest-right_vn-21720077 failed..Will retry 2025-07-23 14:59:28,828 - DEBUG - Response for deleting network () 2025-07-23 14:59:28,829 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/e4abcd65-41ec-440c-90d1-53dca8c3bea7 2025-07-23 14:59:28,837 - DEBUG - Response Code: 404 2025-07-23 14:59:28,838 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2025-07-23 14:59:28,846 - DEBUG - Requesting: http://10.0.0.129:8082/domain/eb013d9f-4d4e-4e11-bece-55bf7e4da03b 2025-07-23 14:59:28,905 - DEBUG - Requesting: http://10.0.0.129:8082/project/f2429a2e-ec24-458e-ba57-8c1708a2953c 2025-07-23 14:59:28,981 - INFO - Validated that VN ctest-right_vn-21720077 is not found in API Server 2025-07-23 14:59:28,993 - DEBUG - VN ctest-right_vn-21720077 is not present in Agent 10.0.0.129 2025-07-23 14:59:28,993 - INFO - Validated that VN ctest-right_vn-21720077 is not in any agent 2025-07-23 14:59:29,014 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-21720077 info 2025-07-23 14:59:29,014 - INFO - Deleting VN ctest-left_vn-86875216 2025-07-23 14:59:29,136 - DEBUG - Response for deleting network () 2025-07-23 14:59:29,136 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/920dd634-9c3d-4ee6-b66e-b2f9cdf89649 2025-07-23 14:59:29,142 - DEBUG - Response Code: 404 2025-07-23 14:59:29,143 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2025-07-23 14:59:29,150 - DEBUG - Requesting: http://10.0.0.129:8082/domain/eb013d9f-4d4e-4e11-bece-55bf7e4da03b 2025-07-23 14:59:29,208 - DEBUG - Requesting: http://10.0.0.129:8082/project/f2429a2e-ec24-458e-ba57-8c1708a2953c 2025-07-23 14:59:29,282 - INFO - Validated that VN ctest-left_vn-86875216 is not found in API Server 2025-07-23 14:59:29,294 - DEBUG - VN ctest-left_vn-86875216 is not present in Agent 10.0.0.129 2025-07-23 14:59:29,294 - INFO - Validated that VN ctest-left_vn-86875216 is not in any agent 2025-07-23 14:59:29,316 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-86875216 info 2025-07-23 14:59:29,316 - INFO - Deleting VN ctest-mgmt_vn-17976114 2025-07-23 14:59:29,437 - DEBUG - Response for deleting network () 2025-07-23 14:59:29,437 - DEBUG - Requesting: http://10.0.0.129:8082/routing-instance/d236ed99-c39d-44bd-9ebc-b54b353b6ba6 2025-07-23 14:59:29,445 - DEBUG - Response Code: 404 2025-07-23 14:59:29,446 - DEBUG - Requesting: http://10.0.0.129:8082/domains 2025-07-23 14:59:29,452 - DEBUG - Requesting: http://10.0.0.129:8082/domain/eb013d9f-4d4e-4e11-bece-55bf7e4da03b 2025-07-23 14:59:29,506 - DEBUG - Requesting: http://10.0.0.129:8082/project/f2429a2e-ec24-458e-ba57-8c1708a2953c 2025-07-23 14:59:29,581 - INFO - Validated that VN ctest-mgmt_vn-17976114 is not found in API Server 2025-07-23 14:59:29,594 - DEBUG - VN ctest-mgmt_vn-17976114 is not present in Agent 10.0.0.129 2025-07-23 14:59:29,594 - INFO - Validated that VN ctest-mgmt_vn-17976114 is not in any agent 2025-07-23 14:59:29,612 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-17976114 info 2025-07-23 14:59:29,868 - DEBUG - No XMPP flaps were noticed during the test 2025-07-23 14:59:29,868 - INFO -
2025-07-23 14:59:29,868 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:15]
2025-07-23 14:59:29,868 - INFO - -------------------------------------------------------------------------------- 2025-07-23 14:59:30,620 - INFO - Deleted project: ctest-TestSvcRegr-50540265, ID : f2429a2e-ec24-458e-ba57-8c1708a2953c