2026-01-12 02:08:44,642 - INFO - Domain Default found not creating 2026-01-12 02:08:44,817 - INFO - Project ctest-TestSvcRegr-57569817 not found, creating it 2026-01-12 02:08:45,294 - INFO - Created Project:ctest-TestSvcRegr-57569817, ID : 5544a8b9-eaeb-4d4b-832f-28e79300cb22 2026-01-12 02:08:46,943 - INFO - ================================================================================ 2026-01-12 02:08:46,944 - INFO - STARTING TEST : test_svc_in_network_datapath 2026-01-12 02:08:47,209 - DEBUG - Nothing to compare xmpp stats {'10.0.0.141': {'10.20.0.192': '0'}} with 2026-01-12 02:08:47,209 - INFO - Initial checks done. Running the testcase now 2026-01-12 02:08:47,209 - INFO - 2026-01-12 02:08:47,858 - DEBUG - Response for create_network : {'network': {'id': 'fcfc2586-36b7-43e7-ab42-487d17c924c6', 'name': 'ctest-mgmt_vn-32673785', 'tenant_id': '5544a8b9eaeb4d4b832f28e79300cb22', 'project_id': '5544a8b9eaeb4d4b832f28e79300cb22', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-57569817', 'ctest-mgmt_vn-32673785'], 'port_security_enabled': True, 'description': ''}} 2026-01-12 02:08:48,067 - DEBUG - Response for create_subnet : {'subnet': {'id': '74a808aa-8b84-4afe-a4e3-0890bc09cab1', 'name': '', 'tenant_id': '5544a8b9eaeb4d4b832f28e79300cb22', 'network_id': 'fcfc2586-36b7-43e7-ab42-487d17c924c6', 'ip_version': 4, 'cidr': '9.26.150.0/26', 'allocation_pools': [{'start': '9.26.150.2', 'end': '9.26.150.62'}], 'gateway_ip': '9.26.150.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '9.26.150.2', 'tags': [], 'project_id': '5544a8b9eaeb4d4b832f28e79300cb22'}} 2026-01-12 02:08:48,088 - INFO - Created VN ctest-mgmt_vn-32673785 2026-01-12 02:08:48,142 - DEBUG - VN ctest-mgmt_vn-32673785 UUID is fcfc2586-36b7-43e7-ab42-487d17c924c6 2026-01-12 02:08:48,264 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-12 02:08:48,612 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-12 02:08:48,645 - DEBUG - Requesting: http://10.0.0.141:8082/domain/d749fb7f-152a-44bd-a047-307afc2e3811 2026-01-12 02:08:48,658 - DEBUG - Requesting: http://10.0.0.141:8082/project/5544a8b9-eaeb-4d4b-832f-28e79300cb22 2026-01-12 02:08:48,731 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/fcfc2586-36b7-43e7-ab42-487d17c924c6 2026-01-12 02:08:48,743 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/fcfc2586-36b7-43e7-ab42-487d17c924c6 2026-01-12 02:08:48,754 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/46bfaf4b-2450-46fd-bd5f-bdf84d4663c6 2026-01-12 02:08:48,762 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/46bfaf4b-2450-46fd-bd5f-bdf84d4663c6 2026-01-12 02:08:48,770 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/6c006f86-6d11-4d7e-8404-1c71f6d16cad 2026-01-12 02:08:48,778 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-01-12 02:08:48,778 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/fcfc2586-36b7-43e7-ab42-487d17c924c6 2026-01-12 02:08:48,791 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/46bfaf4b-2450-46fd-bd5f-bdf84d4663c6 2026-01-12 02:08:48,809 - INFO - Verified VN network id 12 for VN fcfc2586-36b7-43e7-ab42-487d17c924c6 2026-01-12 02:08:48,809 - INFO - Verifications in API Server for VN ctest-mgmt_vn-32673785 passed 2026-01-12 02:08:48,809 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/fcfc2586-36b7-43e7-ab42-487d17c924c6 2026-01-12 02:08:48,820 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/46bfaf4b-2450-46fd-bd5f-bdf84d4663c6 2026-01-12 02:08:48,831 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/46bfaf4b-2450-46fd-bd5f-bdf84d4663c6 2026-01-12 02:08:48,839 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/6c006f86-6d11-4d7e-8404-1c71f6d16cad 2026-01-12 02:08:48,853 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-57569817', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18229486650062750695', 'uuid-lslong': '12340505631055750342'}, 'enable': 'true', 'created': '2026-01-12T02:08:47', 'last-modified': '2026-01-12T02:08:48', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.817288'} 2026-01-12 02:08:48,859 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-01-12 02:08:48,860 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-32673785 passed 2026-01-12 02:08:48,860 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-32673785 in API_Server ====== 2026-01-12 02:08:48,860 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-12 02:08:48,866 - DEBUG - Requesting: http://10.0.0.141:8082/domain/d749fb7f-152a-44bd-a047-307afc2e3811 2026-01-12 02:08:48,876 - DEBUG - Requesting: http://10.0.0.141:8082/project/5544a8b9-eaeb-4d4b-832f-28e79300cb22 2026-01-12 02:08:48,944 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/fcfc2586-36b7-43e7-ab42-487d17c924c6 2026-01-12 02:08:48,955 - DEBUG - =>VN ctest-mgmt_vn-32673785 has no policy to be verified 2026-01-12 02:08:48,955 - DEBUG - Verifying the vn in opserver 2026-01-12 02:08:48,955 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785 virtual network link through opserver 10.0.0.141 2026-01-12 02:08:48,955 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks 2026-01-12 02:08:49,295 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks 2026-01-12 02:08:49,366 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785?flat'} 2026-01-12 02:08:49,366 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785 is found in opserver 2026-01-12 02:08:49,376 - DEBUG - Do not have enough data to verify VN in agent 2026-01-12 02:08:49,381 - DEBUG - VRF ids for VN ctest-mgmt_vn-32673785: {} 2026-01-12 02:08:49,561 - DEBUG - Response for create_network : {'network': {'id': '9fd404b1-5ccc-4aab-973b-7908d9a2c826', 'name': 'ctest-left_vn-63414294', 'tenant_id': '5544a8b9eaeb4d4b832f28e79300cb22', 'project_id': '5544a8b9eaeb4d4b832f28e79300cb22', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-57569817', 'ctest-left_vn-63414294'], 'port_security_enabled': True, 'description': ''}} 2026-01-12 02:08:49,739 - DEBUG - Response for create_subnet : {'subnet': {'id': '2d27b554-d542-438c-982d-dd7eb301ed68', 'name': '', 'tenant_id': '5544a8b9eaeb4d4b832f28e79300cb22', 'network_id': '9fd404b1-5ccc-4aab-973b-7908d9a2c826', 'ip_version': 4, 'cidr': '68.222.233.0/26', 'allocation_pools': [{'start': '68.222.233.2', 'end': '68.222.233.62'}], 'gateway_ip': '68.222.233.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '68.222.233.2', 'tags': [], 'project_id': '5544a8b9eaeb4d4b832f28e79300cb22'}} 2026-01-12 02:08:49,757 - INFO - Created VN ctest-left_vn-63414294 2026-01-12 02:08:49,810 - DEBUG - VN ctest-left_vn-63414294 UUID is 9fd404b1-5ccc-4aab-973b-7908d9a2c826 2026-01-12 02:08:49,930 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-12 02:08:49,936 - DEBUG - Requesting: http://10.0.0.141:8082/domain/d749fb7f-152a-44bd-a047-307afc2e3811 2026-01-12 02:08:49,948 - DEBUG - Requesting: http://10.0.0.141:8082/project/5544a8b9-eaeb-4d4b-832f-28e79300cb22 2026-01-12 02:08:50,021 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/9fd404b1-5ccc-4aab-973b-7908d9a2c826 2026-01-12 02:08:50,032 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/9fd404b1-5ccc-4aab-973b-7908d9a2c826 2026-01-12 02:08:50,041 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/7fc61d8b-157e-44d7-bc6b-00ef560ae801 2026-01-12 02:08:50,049 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/7fc61d8b-157e-44d7-bc6b-00ef560ae801 2026-01-12 02:08:50,056 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/570cd164-a24f-4844-a7ae-7eccb83e3577 2026-01-12 02:08:50,062 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-01-12 02:08:50,062 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/9fd404b1-5ccc-4aab-973b-7908d9a2c826 2026-01-12 02:08:50,070 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/7fc61d8b-157e-44d7-bc6b-00ef560ae801 2026-01-12 02:08:50,085 - INFO - Verified VN network id 13 for VN 9fd404b1-5ccc-4aab-973b-7908d9a2c826 2026-01-12 02:08:50,085 - INFO - Verifications in API Server for VN ctest-left_vn-63414294 passed 2026-01-12 02:08:50,085 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/9fd404b1-5ccc-4aab-973b-7908d9a2c826 2026-01-12 02:08:50,094 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/7fc61d8b-157e-44d7-bc6b-00ef560ae801 2026-01-12 02:08:50,101 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/7fc61d8b-157e-44d7-bc6b-00ef560ae801 2026-01-12 02:08:50,108 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/570cd164-a24f-4844-a7ae-7eccb83e3577 2026-01-12 02:08:50,121 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-57569817:ctest-left_vn-63414294', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-57569817', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11516835306905815723', 'uuid-lslong': '10897436802271070246'}, 'enable': 'true', 'created': '2026-01-12T02:08:49', 'last-modified': '2026-01-12T02:08:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.414219'} 2026-01-12 02:08:50,127 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-01-12 02:08:50,127 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-63414294 passed 2026-01-12 02:08:50,127 - DEBUG - ====Verifying policy data for ctest-left_vn-63414294 in API_Server ====== 2026-01-12 02:08:50,127 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-12 02:08:50,132 - DEBUG - Requesting: http://10.0.0.141:8082/domain/d749fb7f-152a-44bd-a047-307afc2e3811 2026-01-12 02:08:50,144 - DEBUG - Requesting: http://10.0.0.141:8082/project/5544a8b9-eaeb-4d4b-832f-28e79300cb22 2026-01-12 02:08:50,210 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/9fd404b1-5ccc-4aab-973b-7908d9a2c826 2026-01-12 02:08:50,220 - DEBUG - =>VN ctest-left_vn-63414294 has no policy to be verified 2026-01-12 02:08:50,220 - DEBUG - Verifying the vn in opserver 2026-01-12 02:08:50,220 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-57569817:ctest-left_vn-63414294 virtual network link through opserver 10.0.0.141 2026-01-12 02:08:50,220 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks 2026-01-12 02:08:50,229 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-57569817:ctest-left_vn-63414294', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-57569817:ctest-left_vn-63414294?flat'} 2026-01-12 02:08:50,230 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-57569817:ctest-left_vn-63414294 is found in opserver 2026-01-12 02:08:50,240 - DEBUG - Do not have enough data to verify VN in agent 2026-01-12 02:08:50,245 - DEBUG - VRF ids for VN ctest-left_vn-63414294: {} 2026-01-12 02:08:50,440 - DEBUG - Response for create_network : {'network': {'id': 'd67172ef-6053-4850-9fc3-caec09504e3c', 'name': 'ctest-right_vn-82557988', 'tenant_id': '5544a8b9eaeb4d4b832f28e79300cb22', 'project_id': '5544a8b9eaeb4d4b832f28e79300cb22', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-57569817', 'ctest-right_vn-82557988'], 'port_security_enabled': True, 'description': ''}} 2026-01-12 02:08:50,651 - DEBUG - Response for create_subnet : {'subnet': {'id': '16a20e87-b94a-4d09-8b38-8e4c89613e83', 'name': '', 'tenant_id': '5544a8b9eaeb4d4b832f28e79300cb22', 'network_id': 'd67172ef-6053-4850-9fc3-caec09504e3c', 'ip_version': 4, 'cidr': '199.58.216.192/26', 'allocation_pools': [{'start': '199.58.216.194', 'end': '199.58.216.254'}], 'gateway_ip': '199.58.216.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '199.58.216.194', 'tags': [], 'project_id': '5544a8b9eaeb4d4b832f28e79300cb22'}} 2026-01-12 02:08:50,671 - INFO - Created VN ctest-right_vn-82557988 2026-01-12 02:08:50,722 - DEBUG - VN ctest-right_vn-82557988 UUID is d67172ef-6053-4850-9fc3-caec09504e3c 2026-01-12 02:08:50,834 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-12 02:08:50,840 - DEBUG - Requesting: http://10.0.0.141:8082/domain/d749fb7f-152a-44bd-a047-307afc2e3811 2026-01-12 02:08:50,850 - DEBUG - Requesting: http://10.0.0.141:8082/project/5544a8b9-eaeb-4d4b-832f-28e79300cb22 2026-01-12 02:08:50,920 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/d67172ef-6053-4850-9fc3-caec09504e3c 2026-01-12 02:08:50,931 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/d67172ef-6053-4850-9fc3-caec09504e3c 2026-01-12 02:08:50,942 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/ce579f65-dce5-4d6d-81b2-4814c77cedd7 2026-01-12 02:08:50,950 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/ce579f65-dce5-4d6d-81b2-4814c77cedd7 2026-01-12 02:08:50,956 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/b7a13d72-bad5-4e59-a2c2-660d59429166 2026-01-12 02:08:50,963 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-01-12 02:08:50,963 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/d67172ef-6053-4850-9fc3-caec09504e3c 2026-01-12 02:08:50,972 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/ce579f65-dce5-4d6d-81b2-4814c77cedd7 2026-01-12 02:08:50,986 - INFO - Verified VN network id 14 for VN d67172ef-6053-4850-9fc3-caec09504e3c 2026-01-12 02:08:50,987 - INFO - Verifications in API Server for VN ctest-right_vn-82557988 passed 2026-01-12 02:08:50,987 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/d67172ef-6053-4850-9fc3-caec09504e3c 2026-01-12 02:08:50,996 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/ce579f65-dce5-4d6d-81b2-4814c77cedd7 2026-01-12 02:08:51,003 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/ce579f65-dce5-4d6d-81b2-4814c77cedd7 2026-01-12 02:08:51,010 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/b7a13d72-bad5-4e59-a2c2-660d59429166 2026-01-12 02:08:51,025 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-57569817:ctest-right_vn-82557988', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-57569817', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '15452258168923703376', 'uuid-lslong': '11512268187606470204'}, 'enable': 'true', 'created': '2026-01-12T02:08:50', 'last-modified': '2026-01-12T02:08:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.402582'} 2026-01-12 02:08:51,031 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-01-12 02:08:51,032 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-82557988 passed 2026-01-12 02:08:51,032 - DEBUG - ====Verifying policy data for ctest-right_vn-82557988 in API_Server ====== 2026-01-12 02:08:51,032 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-12 02:08:51,038 - DEBUG - Requesting: http://10.0.0.141:8082/domain/d749fb7f-152a-44bd-a047-307afc2e3811 2026-01-12 02:08:51,049 - DEBUG - Requesting: http://10.0.0.141:8082/project/5544a8b9-eaeb-4d4b-832f-28e79300cb22 2026-01-12 02:08:51,123 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/d67172ef-6053-4850-9fc3-caec09504e3c 2026-01-12 02:08:51,132 - DEBUG - =>VN ctest-right_vn-82557988 has no policy to be verified 2026-01-12 02:08:51,132 - DEBUG - Verifying the vn in opserver 2026-01-12 02:08:51,132 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-57569817:ctest-right_vn-82557988 virtual network link through opserver 10.0.0.141 2026-01-12 02:08:51,132 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks 2026-01-12 02:08:51,143 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-57569817:ctest-right_vn-82557988', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-57569817:ctest-right_vn-82557988?flat'} 2026-01-12 02:08:51,143 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-57569817:ctest-right_vn-82557988 is found in opserver 2026-01-12 02:08:51,154 - DEBUG - Do not have enough data to verify VN in agent 2026-01-12 02:08:51,158 - DEBUG - VRF ids for VN ctest-right_vn-82557988: {} 2026-01-12 02:08:51,223 - DEBUG - Services list from nova: [, , ] 2026-01-12 02:08:52,392 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4986-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4986-1) 2026-01-12 02:08:53,677 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4986-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4986-1) 2026-01-12 02:08:53,678 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-95826032'] 2026-01-12 02:08:54,046 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-95826032'] created succesfully 2026-01-12 02:08:54,471 - DEBUG - Image by name cirros_in_net not found 2026-01-12 02:08:54,472 - DEBUG - Installing image cirros_in_net 2026-01-12 02:08:54,472 - 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'} 2026-01-12 02:08:54,472 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2026-01-12 02:08:54,472 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2026-01-12 02:08:54,493 - 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 2026-01-12 02:08:54,606 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz 2026-01-12 02:08:54,607 - DEBUG - Unzip image 2026-01-12 02:08:54,607 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz 2026-01-12 02:08:54,778 - DEBUG - Try to create image in user context 2026-01-12 02:08:55,027 - DEBUG - Image has been created in user context 2026-01-12 02:08:55,027 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk 2026-01-12 02:08:55,046 - DEBUG - Downloaded image has been removed 2026-01-12 02:08:55,061 - DEBUG - Uploaded image status = active 2026-01-12 02:08:56,292 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4986-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4986-1) 2026-01-12 02:08:56,292 - INFO - Waiting for VM ctest-pt_svm0-19006466 to be up.. 2026-01-12 02:08:56,337 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-12 02:09:01,457 - DEBUG - VM is in ACTIVE state now 2026-01-12 02:09:01,457 - INFO - VM name : ctest-pt_svm0-19006466 2026-01-12 02:09:01,568 - DEBUG - VM ctest-pt_svm0-19006466 ID is 50af5888-e78b-440a-af29-8adb500cbf2b 2026-01-12 02:09:01,596 - DEBUG - VM ctest-pt_svm0-19006466 launched on Node cn-jenkins-deploy-platform-ansible-os-4986-1 2026-01-12 02:09:01,966 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/50af5888-e78b-440a-af29-8adb500cbf2b 2026-01-12 02:09:01,977 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/3425acd3-ad25-4bf7-a14b-7c4b64b76228 2026-01-12 02:09:01,990 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/4a8ae215-14bc-4693-92f2-24f07f428e71 2026-01-12 02:09:02,001 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/662eb51a-4412-4e0e-9069-2ac0749cb196 2026-01-12 02:09:05,301 - 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') 2026-01-12 02:09:05,301 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-19006466 failed! 2026-01-12 02:09:05,358 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785 is 9.26.150.1 and allocation pool is NOT set 2026-01-12 02:09:09,423 - 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 1005ms') 2026-01-12 02:09:09,423 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-19006466 failed! 2026-01-12 02:09:09,486 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785 is 9.26.150.1 and allocation pool is NOT set 2026-01-12 02:09:13,547 - 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 1005ms') 2026-01-12 02:09:13,547 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-19006466 failed! 2026-01-12 02:09:13,602 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785 is 9.26.150.1 and allocation pool is NOT set 2026-01-12 02:09:17,684 - 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 1015ms') 2026-01-12 02:09:17,684 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-19006466 failed! 2026-01-12 02:09:17,738 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785 is 9.26.150.1 and allocation pool is NOT set 2026-01-12 02:09:19,803 - 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=11.9 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=1.03 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.029/6.479/11.930/5.450 ms') 2026-01-12 02:09:19,803 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-19006466 passed 2026-01-12 02:09:19,954 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:19,954 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-19006466, IP 9.26.150.3, Port 22 2026-01-12 02:09:20,020 - 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': ''} 2026-01-12 02:09:20,158 - DEBUG - VM ctest-pt_svm0-19006466 is NOT ready for SSH connections, VM status: ACTIVE 2026-01-12 02:09:25,159 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:25,159 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-19006466, IP 9.26.150.3, Port 22 2026-01-12 02:09:25,224 - 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': ''} 2026-01-12 02:09:25,313 - DEBUG - VM ctest-pt_svm0-19006466 is NOT ready for SSH connections, VM status: ACTIVE 2026-01-12 02:09:30,314 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:30,314 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-19006466, IP 9.26.150.3, Port 22 2026-01-12 02:09:30,381 - 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': ''} 2026-01-12 02:09:30,478 - DEBUG - VM ctest-pt_svm0-19006466 is NOT ready for SSH connections, VM status: ACTIVE 2026-01-12 02:09:35,479 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:35,479 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-19006466, IP 9.26.150.3, Port 22 2026-01-12 02:09:35,552 - 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': ''} 2026-01-12 02:09:35,645 - DEBUG - VM ctest-pt_svm0-19006466 is NOT ready for SSH connections, VM status: ACTIVE 2026-01-12 02:09:40,646 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:40,646 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-19006466, IP 9.26.150.3, Port 22 2026-01-12 02:09:40,816 - DEBUG - VM ctest-pt_svm0-19006466 is ready for SSH connections 2026-01-12 02:09:40,966 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:40,966 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-19006466, IP 9.26.150.3, Port 22 2026-01-12 02:09:41,132 - DEBUG - VM ctest-pt_svm0-19006466 is ready for SSH connections 2026-01-12 02:09:41,286 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:41,286 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-19006466, IP 9.26.150.3, Port 22 2026-01-12 02:09:41,442 - DEBUG - VM ctest-pt_svm0-19006466 is ready for SSH connections 2026-01-12 02:09:41,447 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSvcRegr-57569817', 'ctest-si-78313985'] 2026-01-12 02:09:42,346 - INFO - Waiting for VM ctest-left_vm-28448731 to be up.. 2026-01-12 02:09:42,427 - DEBUG - VM is in ACTIVE state now 2026-01-12 02:09:42,427 - INFO - VM name : ctest-left_vm-28448731 2026-01-12 02:09:42,514 - DEBUG - VM ctest-left_vm-28448731 ID is 4c311583-4a68-42cf-86d6-78c25b16ff09 2026-01-12 02:09:42,514 - DEBUG - VM ctest-left_vm-28448731 launched on Node cn-jenkins-deploy-platform-ansible-os-4986-1 2026-01-12 02:09:42,604 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/4c311583-4a68-42cf-86d6-78c25b16ff09 2026-01-12 02:09:42,614 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/a3a0be5b-c927-465d-b614-12f917f418ce 2026-01-12 02:09:43,858 - 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.31 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.456 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.456/1.884/3.312/1.428 ms') 2026-01-12 02:09:43,859 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-28448731 passed 2026-01-12 02:09:44,006 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:44,006 - DEBUG - Waiting to SSH to VM ctest-left_vm-28448731, IP 68.222.233.3, Port 22 2026-01-12 02:09:44,161 - DEBUG - VM ctest-left_vm-28448731 is ready for SSH connections 2026-01-12 02:09:44,162 - INFO - Waiting for VM ctest-right_vm-58058055 to be up.. 2026-01-12 02:09:44,246 - DEBUG - VM is in ACTIVE state now 2026-01-12 02:09:44,246 - INFO - VM name : ctest-right_vm-58058055 2026-01-12 02:09:44,329 - DEBUG - VM ctest-right_vm-58058055 ID is 17a5c4b0-5c34-4438-8670-cee82184ae5a 2026-01-12 02:09:44,329 - DEBUG - VM ctest-right_vm-58058055 launched on Node cn-jenkins-deploy-platform-ansible-os-4986-1 2026-01-12 02:09:44,412 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/17a5c4b0-5c34-4438-8670-cee82184ae5a 2026-01-12 02:09:44,421 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/ad63d54a-a0e2-4927-ad63-a6124cb58c8c 2026-01-12 02:09:45,656 - 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.47 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.365 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.365/1.919/3.474/1.554 ms') 2026-01-12 02:09:45,656 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-58058055 passed 2026-01-12 02:09:45,806 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:45,806 - DEBUG - Waiting to SSH to VM ctest-right_vm-58058055, IP 199.58.216.195, Port 22 2026-01-12 02:09:45,961 - DEBUG - VM ctest-right_vm-58058055 is ready for SSH connections 2026-01-12 02:09:45,967 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-57569817:ctest-left_vn-63414294, 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-57569817:ctest-right_vn-82557988, 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-57569817:ctest-si-78313985'], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2026-01-12 02:09:46,398 - INFO - Attaching policy ctest-policy-54533450 to vn ctest-left_vn-63414294 2026-01-12 02:09:46,946 - INFO - Attaching policy ctest-policy-54533450 to vn ctest-right_vn-82557988 2026-01-12 02:09:47,390 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-95826032'] created succesfully 2026-01-12 02:09:47,610 - DEBUG - Service instance: ['default-domain', 'ctest-TestSvcRegr-57569817', 'ctest-si-78313985'] created succesfully 2026-01-12 02:09:47,610 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-12 02:09:47,616 - DEBUG - Requesting: http://10.0.0.141:8082/domain/d749fb7f-152a-44bd-a047-307afc2e3811 2026-01-12 02:09:47,629 - DEBUG - Requesting: http://10.0.0.141:8082/project/5544a8b9-eaeb-4d4b-832f-28e79300cb22 2026-01-12 02:09:47,721 - DEBUG - Requesting: http://10.0.0.141:8082/service-instance/8e0bbabf-772f-48cd-9fda-1f21c823fb5a 2026-01-12 02:09:47,736 - DEBUG - SI 'ctest-si-78313985' has service template ref to ctest-service_template_1-95826032 2026-01-12 02:09:47,736 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-12 02:09:47,741 - DEBUG - Requesting: http://10.0.0.141:8082/domain/d749fb7f-152a-44bd-a047-307afc2e3811 2026-01-12 02:09:47,754 - DEBUG - Requesting: http://10.0.0.141:8082/project/5544a8b9-eaeb-4d4b-832f-28e79300cb22 2026-01-12 02:09:47,827 - DEBUG - Requesting: http://10.0.0.141:8082/service-instance/8e0bbabf-772f-48cd-9fda-1f21c823fb5a 2026-01-12 02:09:47,837 - DEBUG - Requesting: http://10.0.0.141:8082/port-tuple/01b71bfb-6a56-427e-b5de-847a4567c0ea 2026-01-12 02:09:47,847 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/3425acd3-ad25-4bf7-a14b-7c4b64b76228 2026-01-12 02:09:47,863 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/4a8ae215-14bc-4693-92f2-24f07f428e71 2026-01-12 02:09:47,874 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/662eb51a-4412-4e0e-9069-2ac0749cb196 2026-01-12 02:09:48,109 - DEBUG - VM is in ACTIVE state now 2026-01-12 02:09:48,562 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/50af5888-e78b-440a-af29-8adb500cbf2b 2026-01-12 02:09:48,570 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/3425acd3-ad25-4bf7-a14b-7c4b64b76228 2026-01-12 02:09:48,581 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/4a8ae215-14bc-4693-92f2-24f07f428e71 2026-01-12 02:09:48,592 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/662eb51a-4412-4e0e-9069-2ac0749cb196 2026-01-12 02:09:48,603 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/989cd378-14c6-4b46-a07a-f33458dc2e50 2026-01-12 02:09:48,611 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/cce9fc59-a511-462c-8d26-363e69dce120 2026-01-12 02:09:48,618 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/cebcd9f7-bc2b-4398-9dcd-952aac3414f0 2026-01-12 02:09:48,627 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/2e73cbbc-7499-4c2a-bb18-96f6bb9081ec 2026-01-12 02:09:48,634 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/88e6dcdd-267a-4db5-876f-5dbba04a6801 2026-01-12 02:09:48,642 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/dfe3f230-5c4c-4562-b84f-d8468226d237 2026-01-12 02:09:48,650 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/2a08a209-8177-4e05-afe9-0948371806a8 2026-01-12 02:09:48,658 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/7687431a-8403-4157-ac57-6076610caf23 2026-01-12 02:09:48,667 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/b015c516-56b1-4172-9d79-eaf83f911cf0 2026-01-12 02:09:49,088 - DEBUG - VM is in ACTIVE state now 2026-01-12 02:09:49,088 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/50af5888-e78b-440a-af29-8adb500cbf2b 2026-01-12 02:09:49,096 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/3425acd3-ad25-4bf7-a14b-7c4b64b76228 2026-01-12 02:09:49,107 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/4a8ae215-14bc-4693-92f2-24f07f428e71 2026-01-12 02:09:49,117 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/662eb51a-4412-4e0e-9069-2ac0749cb196 2026-01-12 02:09:50,365 - 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.31 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.712 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.712/1.011/1.310/0.299 ms') 2026-01-12 02:09:50,365 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-19006466 passed 2026-01-12 02:09:50,401 - DEBUG - VM ctest-pt_svm0-19006466 Tap interface: {'index': '6', 'name': 'tap4a8ae215-14', 'uuid': '4a8ae215-14bc-4693-92f2-24f07f428e71', 'vrf_name': 'default-domain:ctest-TestSvcRegr-57569817:ctest-left_vn-63414294:ctest-left_vn-63414294', '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-57569817:ctest-left_vn-63414294', 'vm_uuid': '50af5888-e78b-440a-af29-8adb500cbf2b', 'vm_name': 'ctest-pt_svm0-19006466', 'ip_addr': '68.222.233.4', 'mac_addr': '02:4a:8a:e2:15:14', '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-57569817:4a8ae215-14bc-4693-92f2-24f07f428e71', 'sg_uuid_list': ['46fae7eb-5206-4961-8b7f-6465e8a72dfc'], 'static_route_list': None, 'vm_project_uuid': '5544a8b9-eaeb-4d4b-832f-28e79300cb22', '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': '662eb51a-4412-4e0e-9069-2ac0749cb196', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2026-01-12 02:09:50,401 - DEBUG - Agent 10.0.0.141 vrf name: default-domain:ctest-TestSvcRegr-57569817:ctest-left_vn-63414294:ctest-left_vn-63414294 2026-01-12 02:09:50,406 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-57569817:ctest-left_vn-63414294:ctest-left_vn-63414294', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestSvcRegr-57569817:ctest-left_vn-63414294', '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'}]} 2026-01-12 02:09:50,570 - DEBUG - VM ctest-pt_svm0-19006466 : Tap interface tap4a8ae215-14 is set to Active 2026-01-12 02:09:50,571 - DEBUG - Tap interface tap4a8ae215-14 detail : {'index': '6', 'name': 'tap4a8ae215-14', 'uuid': '4a8ae215-14bc-4693-92f2-24f07f428e71', 'vrf_name': 'default-domain:ctest-TestSvcRegr-57569817:ctest-left_vn-63414294:ctest-left_vn-63414294', '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-57569817:ctest-left_vn-63414294', 'vm_uuid': '50af5888-e78b-440a-af29-8adb500cbf2b', 'vm_name': 'ctest-pt_svm0-19006466', 'ip_addr': '68.222.233.4', 'mac_addr': '02:4a:8a:e2:15:14', '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-57569817:4a8ae215-14bc-4693-92f2-24f07f428e71', 'sg_uuid_list': ['46fae7eb-5206-4961-8b7f-6465e8a72dfc'], 'static_route_list': None, 'vm_project_uuid': '5544a8b9-eaeb-4d4b-832f-28e79300cb22', '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': '662eb51a-4412-4e0e-9069-2ac0749cb196', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2026-01-12 02:09:50,770 - DEBUG - Starting Layer 2 verification in Agent 2026-01-12 02:09:50,783 - DEBUG - Layer 2 path is seen for VM MAC 02:4a:8a:e2:15:14 in agent 10.0.0.141 2026-01-12 02:09:50,783 - DEBUG - Active layer 2 route in agent is present for VMI tap4a8ae215-14 2026-01-12 02:09:50,783 - DEBUG - L2 label(48) matches bw route table and interface table 2026-01-12 02:09:50,789 - DEBUG - VN default-domain:ctest-TestSvcRegr-57569817:ctest-left_vn-63414294 is found in Agent of node 10.0.0.141 2026-01-12 02:09:50,998 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-57569817:ctest-left_vn-63414294 is consistent in agent 10.0.0.141 2026-01-12 02:09:50,998 - DEBUG - Route for VM IP ['68.222.233.4'] is consistent in agent 10.0.0.141 2026-01-12 02:09:50,998 - DEBUG - VN default-domain:ctest-TestSvcRegr-57569817:ctest-left_vn-63414294 verification for VM ctest-pt_svm0-19006466 in Agent 10.0.0.141 passed 2026-01-12 02:09:51,194 - DEBUG - Starting all layer 2 verification in agent 10.0.0.141 2026-01-12 02:09:51,205 - DEBUG - Route for VM MAC 02:4a:8a:e2:15:14 is consistent in agent 10.0.0.141 2026-01-12 02:09:51,226 - DEBUG - VM ctest-pt_svm0-19006466 Tap interface: {'index': '5', 'name': 'tap3425acd3-ad', 'uuid': '3425acd3-ad25-4bf7-a14b-7c4b64b76228', 'vrf_name': 'default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785:ctest-mgmt_vn-32673785', '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-57569817:ctest-mgmt_vn-32673785', 'vm_uuid': '50af5888-e78b-440a-af29-8adb500cbf2b', 'vm_name': 'ctest-pt_svm0-19006466', 'ip_addr': '9.26.150.3', 'mac_addr': '02:34:25:ac:d3:ad', '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-57569817:3425acd3-ad25-4bf7-a14b-7c4b64b76228', 'sg_uuid_list': ['46fae7eb-5206-4961-8b7f-6465e8a72dfc'], 'static_route_list': None, 'vm_project_uuid': '5544a8b9-eaeb-4d4b-832f-28e79300cb22', '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': []} 2026-01-12 02:09:51,226 - DEBUG - Agent 10.0.0.141 vrf name: default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785:ctest-mgmt_vn-32673785 2026-01-12 02:09:51,231 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785:ctest-mgmt_vn-32673785', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785', 'table_label': '-1', 'vxlan_id': '12', 'evpnindex': '4', 'brindex': '4', 'mplsindex': '0', 'RD': '10.20.0.192:4', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-01-12 02:09:51,436 - DEBUG - VM ctest-pt_svm0-19006466 : Tap interface tap3425acd3-ad is set to Active 2026-01-12 02:09:51,436 - DEBUG - Tap interface tap3425acd3-ad detail : {'index': '5', 'name': 'tap3425acd3-ad', 'uuid': '3425acd3-ad25-4bf7-a14b-7c4b64b76228', 'vrf_name': 'default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785:ctest-mgmt_vn-32673785', '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-57569817:ctest-mgmt_vn-32673785', 'vm_uuid': '50af5888-e78b-440a-af29-8adb500cbf2b', 'vm_name': 'ctest-pt_svm0-19006466', 'ip_addr': '9.26.150.3', 'mac_addr': '02:34:25:ac:d3:ad', '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-57569817:3425acd3-ad25-4bf7-a14b-7c4b64b76228', 'sg_uuid_list': ['46fae7eb-5206-4961-8b7f-6465e8a72dfc'], 'static_route_list': None, 'vm_project_uuid': '5544a8b9-eaeb-4d4b-832f-28e79300cb22', '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': []} 2026-01-12 02:09:51,630 - DEBUG - Starting Layer 2 verification in Agent 2026-01-12 02:09:51,640 - DEBUG - Layer 2 path is seen for VM MAC 02:34:25:ac:d3:ad in agent 10.0.0.141 2026-01-12 02:09:51,640 - DEBUG - Active layer 2 route in agent is present for VMI tap3425acd3-ad 2026-01-12 02:09:51,640 - DEBUG - L2 label(43) matches bw route table and interface table 2026-01-12 02:09:51,645 - DEBUG - VN default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785 is found in Agent of node 10.0.0.141 2026-01-12 02:09:51,853 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785 is consistent in agent 10.0.0.141 2026-01-12 02:09:51,853 - DEBUG - Route for VM IP ['9.26.150.3'] is consistent in agent 10.0.0.141 2026-01-12 02:09:51,853 - DEBUG - VN default-domain:ctest-TestSvcRegr-57569817:ctest-mgmt_vn-32673785 verification for VM ctest-pt_svm0-19006466 in Agent 10.0.0.141 passed 2026-01-12 02:09:52,050 - DEBUG - Starting all layer 2 verification in agent 10.0.0.141 2026-01-12 02:09:52,061 - DEBUG - Route for VM MAC 02:34:25:ac:d3:ad is consistent in agent 10.0.0.141 2026-01-12 02:09:52,081 - DEBUG - VM ctest-pt_svm0-19006466 Tap interface: {'index': '7', 'name': 'tap662eb51a-44', 'uuid': '662eb51a-4412-4e0e-9069-2ac0749cb196', 'vrf_name': 'default-domain:ctest-TestSvcRegr-57569817:ctest-right_vn-82557988:ctest-right_vn-82557988', '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-57569817:ctest-right_vn-82557988', 'vm_uuid': '50af5888-e78b-440a-af29-8adb500cbf2b', 'vm_name': 'ctest-pt_svm0-19006466', 'ip_addr': '199.58.216.196', 'mac_addr': '02:66:2e:b5:1a:44', '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-57569817:662eb51a-4412-4e0e-9069-2ac0749cb196', 'sg_uuid_list': ['46fae7eb-5206-4961-8b7f-6465e8a72dfc'], 'static_route_list': None, 'vm_project_uuid': '5544a8b9-eaeb-4d4b-832f-28e79300cb22', 'admin_state': 'Enabled', 'flow_key_idx': '56', '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': '4a8ae215-14bc-4693-92f2-24f07f428e71', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2026-01-12 02:09:52,081 - DEBUG - Agent 10.0.0.141 vrf name: default-domain:ctest-TestSvcRegr-57569817:ctest-right_vn-82557988:ctest-right_vn-82557988 2026-01-12 02:09:52,087 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-57569817:ctest-right_vn-82557988:ctest-right_vn-82557988', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSvcRegr-57569817:ctest-right_vn-82557988', 'table_label': '-1', 'vxlan_id': '14', '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'}]} 2026-01-12 02:09:52,300 - DEBUG - VM ctest-pt_svm0-19006466 : Tap interface tap662eb51a-44 is set to Active 2026-01-12 02:09:52,300 - DEBUG - Tap interface tap662eb51a-44 detail : {'index': '7', 'name': 'tap662eb51a-44', 'uuid': '662eb51a-4412-4e0e-9069-2ac0749cb196', 'vrf_name': 'default-domain:ctest-TestSvcRegr-57569817:ctest-right_vn-82557988:ctest-right_vn-82557988', '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-57569817:ctest-right_vn-82557988', 'vm_uuid': '50af5888-e78b-440a-af29-8adb500cbf2b', 'vm_name': 'ctest-pt_svm0-19006466', 'ip_addr': '199.58.216.196', 'mac_addr': '02:66:2e:b5:1a:44', '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-57569817:662eb51a-4412-4e0e-9069-2ac0749cb196', 'sg_uuid_list': ['46fae7eb-5206-4961-8b7f-6465e8a72dfc'], 'static_route_list': None, 'vm_project_uuid': '5544a8b9-eaeb-4d4b-832f-28e79300cb22', 'admin_state': 'Enabled', 'flow_key_idx': '56', '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': '4a8ae215-14bc-4693-92f2-24f07f428e71', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2026-01-12 02:09:52,498 - DEBUG - Starting Layer 2 verification in Agent 2026-01-12 02:09:52,510 - DEBUG - Layer 2 path is seen for VM MAC 02:66:2e:b5:1a:44 in agent 10.0.0.141 2026-01-12 02:09:52,510 - DEBUG - Active layer 2 route in agent is present for VMI tap662eb51a-44 2026-01-12 02:09:52,511 - DEBUG - L2 label(53) matches bw route table and interface table 2026-01-12 02:09:52,516 - DEBUG - VN default-domain:ctest-TestSvcRegr-57569817:ctest-right_vn-82557988 is found in Agent of node 10.0.0.141 2026-01-12 02:09:52,735 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-57569817:ctest-right_vn-82557988 is consistent in agent 10.0.0.141 2026-01-12 02:09:52,736 - DEBUG - Route for VM IP ['199.58.216.196'] is consistent in agent 10.0.0.141 2026-01-12 02:09:52,736 - DEBUG - VN default-domain:ctest-TestSvcRegr-57569817:ctest-right_vn-82557988 verification for VM ctest-pt_svm0-19006466 in Agent 10.0.0.141 passed 2026-01-12 02:09:52,934 - DEBUG - Starting all layer 2 verification in agent 10.0.0.141 2026-01-12 02:09:52,946 - DEBUG - Route for VM MAC 02:66:2e:b5:1a:44 is consistent in agent 10.0.0.141 2026-01-12 02:09:52,946 - INFO - VM ctest-pt_svm0-19006466 verifications in Compute nodes passed 2026-01-12 02:09:52,947 - DEBUG - The SVMs in the SI are : [] 2026-01-12 02:09:52,947 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/50af5888-e78b-440a-af29-8adb500cbf2b 2026-01-12 02:09:52,955 - DEBUG - Service VM for SI 'ctest-si-78313985' is launched 2026-01-12 02:09:52,955 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-12 02:09:52,961 - DEBUG - Requesting: http://10.0.0.141:8082/domain/d749fb7f-152a-44bd-a047-307afc2e3811 2026-01-12 02:09:52,974 - DEBUG - Requesting: http://10.0.0.141:8082/project/5544a8b9-eaeb-4d4b-832f-28e79300cb22 2026-01-12 02:09:53,050 - DEBUG - Requesting: http://10.0.0.141:8082/service-instance/8e0bbabf-772f-48cd-9fda-1f21c823fb5a 2026-01-12 02:09:53,064 - DEBUG - SI ctest-si-78313985 has Port Tuple: ['ctest-port_tuple-93676422'] 2026-01-12 02:09:53,166 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-57569817:ctest-left_vn-63414294 2026-01-12 02:09:53,314 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-57569817:ctest-right_vn-82557988 2026-01-12 02:09:53,476 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:53,485 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:53,485 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:53,491 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:53,491 - INFO - Route to 199.58.216.195 seen in VRF:5 on cn-jenkins-deploy-platform-ansible-os-4986-1, and SI ctest-si-78313985 is seen as the NH 2026-01-12 02:09:53,649 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:53,657 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:53,657 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:53,663 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:53,663 - INFO - Route to 68.222.233.3 seen in VRF:6 on cn-jenkins-deploy-platform-ansible-os-4986-1, and SI ctest-si-78313985 is seen as the NH 2026-01-12 02:09:53,664 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:53,664 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-12 02:09:53,664 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 199.58.216.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2026-01-12 02:09:53,664 - DEBUG - ping -s 56 -c 3 -W 1 199.58.216.195 2026-01-12 02:09:57,793 - DEBUG - PING 199.58.216.195 (199.58.216.195): 56 data bytes 64 bytes from 199.58.216.195: seq=0 ttl=61 time=7.602 ms 64 bytes from 199.58.216.195: seq=1 ttl=61 time=2.212 ms 64 bytes from 199.58.216.195: seq=2 ttl=61 time=1.798 ms --- 199.58.216.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.798/3.870/7.602 ms 2026-01-12 02:09:57,793 - INFO - Ping to IP 199.58.216.195 from VM ctest-left_vm-28448731 passed 2026-01-12 02:09:57,794 - INFO - Dettaching policy ctest-policy-54533450 from vn ctest-right_vn-82557988 2026-01-12 02:09:58,002 - INFO - Dettaching policy ctest-policy-54533450 from vn ctest-left_vn-63414294 2026-01-12 02:09:58,293 - INFO - Deleted policy ctest-policy-54533450 2026-01-12 02:09:58,293 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSvcRegr-57569817', 'ctest-si-78313985'] 2026-01-12 02:09:58,386 - INFO - Deleted SI ['default-domain', 'ctest-TestSvcRegr-57569817', 'ctest-si-78313985'] 2026-01-12 02:09:58,387 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-12 02:09:58,397 - DEBUG - Requesting: http://10.0.0.141:8082/domain/d749fb7f-152a-44bd-a047-307afc2e3811 2026-01-12 02:09:58,407 - DEBUG - Requesting: http://10.0.0.141:8082/project/5544a8b9-eaeb-4d4b-832f-28e79300cb22 2026-01-12 02:09:58,483 - DEBUG - Service instance ctest-si-78313985 removed from api server 2026-01-12 02:09:58,483 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/50af5888-e78b-440a-af29-8adb500cbf2b 2026-01-12 02:09:58,490 - DEBUG - All Service VMs unlinked from SI ctest-si-78313985 2026-01-12 02:09:58,496 - DEBUG - 0 svc intances found in all projects. They are [] 2026-01-12 02:09:58,496 - DEBUG - 0 svc intances found in current project. They are [] 2026-01-12 02:09:58,548 - DEBUG - 0 svc intances found in all projects. They are [] 2026-01-12 02:09:58,548 - DEBUG - 0 svc intances found in current project. They are [] 2026-01-12 02:09:58,549 - INFO - Deleting VM ctest-pt_svm0-19006466 2026-01-12 02:09:58,658 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-95826032'] 2026-01-12 02:09:58,766 - INFO - Service template: ['default-domain', 'ctest-service_template_1-95826032'] deleted successfully. 2026-01-12 02:09:58,766 - INFO - Deleting VM ctest-right_vm-58058055 2026-01-12 02:09:58,872 - INFO - Deleting VM ctest-left_vm-28448731 2026-01-12 02:09:58,980 - INFO - Deleting VN ctest-right_vn-82557988 2026-01-12 02:09:59,028 - DEBUG - VN d67172ef-6053-4850-9fc3-caec09504e3c still in use: Unable to complete operation on network d67172ef-6053-4850-9fc3-caec09504e3c. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-12d8c823-9250-4a6d-820e-e8f7b4f282f5'] 2026-01-12 02:09:59,028 - WARNING - Deleting VN ctest-right_vn-82557988 failed..Will retry 2026-01-12 02:10:01,165 - DEBUG - Response for deleting network () 2026-01-12 02:10:01,165 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/ce579f65-dce5-4d6d-81b2-4814c77cedd7 2026-01-12 02:10:01,174 - DEBUG - Response Code: 404 2026-01-12 02:10:01,175 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-12 02:10:01,183 - DEBUG - Requesting: http://10.0.0.141:8082/domain/d749fb7f-152a-44bd-a047-307afc2e3811 2026-01-12 02:10:01,242 - DEBUG - Requesting: http://10.0.0.141:8082/project/5544a8b9-eaeb-4d4b-832f-28e79300cb22 2026-01-12 02:10:01,320 - INFO - Validated that VN ctest-right_vn-82557988 is not found in API Server 2026-01-12 02:10:01,335 - DEBUG - VN ctest-right_vn-82557988 is not present in Agent 10.0.0.141 2026-01-12 02:10:01,335 - INFO - Validated that VN ctest-right_vn-82557988 is not in any agent 2026-01-12 02:10:01,357 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-82557988 info 2026-01-12 02:10:01,358 - INFO - Deleting VN ctest-left_vn-63414294 2026-01-12 02:10:01,489 - DEBUG - Response for deleting network () 2026-01-12 02:10:01,489 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/7fc61d8b-157e-44d7-bc6b-00ef560ae801 2026-01-12 02:10:01,496 - DEBUG - Response Code: 404 2026-01-12 02:10:01,497 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-12 02:10:01,503 - DEBUG - Requesting: http://10.0.0.141:8082/domain/d749fb7f-152a-44bd-a047-307afc2e3811 2026-01-12 02:10:01,560 - DEBUG - Requesting: http://10.0.0.141:8082/project/5544a8b9-eaeb-4d4b-832f-28e79300cb22 2026-01-12 02:10:01,637 - INFO - Validated that VN ctest-left_vn-63414294 is not found in API Server 2026-01-12 02:10:01,651 - DEBUG - VN ctest-left_vn-63414294 is not present in Agent 10.0.0.141 2026-01-12 02:10:01,651 - INFO - Validated that VN ctest-left_vn-63414294 is not in any agent 2026-01-12 02:10:01,671 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-63414294 info 2026-01-12 02:10:01,671 - INFO - Deleting VN ctest-mgmt_vn-32673785 2026-01-12 02:10:01,794 - DEBUG - Response for deleting network () 2026-01-12 02:10:01,794 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/46bfaf4b-2450-46fd-bd5f-bdf84d4663c6 2026-01-12 02:10:01,802 - DEBUG - Response Code: 404 2026-01-12 02:10:01,802 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-12 02:10:01,809 - DEBUG - Requesting: http://10.0.0.141:8082/domain/d749fb7f-152a-44bd-a047-307afc2e3811 2026-01-12 02:10:01,863 - DEBUG - Requesting: http://10.0.0.141:8082/project/5544a8b9-eaeb-4d4b-832f-28e79300cb22 2026-01-12 02:10:01,954 - INFO - Validated that VN ctest-mgmt_vn-32673785 is not found in API Server 2026-01-12 02:10:01,965 - DEBUG - VN ctest-mgmt_vn-32673785 is not present in Agent 10.0.0.141 2026-01-12 02:10:01,965 - INFO - Validated that VN ctest-mgmt_vn-32673785 is not in any agent 2026-01-12 02:10:01,984 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-32673785 info 2026-01-12 02:10:02,263 - DEBUG - No XMPP flaps were noticed during the test 2026-01-12 02:10:02,263 - INFO -
2026-01-12 02:10:02,263 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:16]
2026-01-12 02:10:02,263 - INFO - -------------------------------------------------------------------------------- 2026-01-12 02:10:03,056 - INFO - Deleted project: ctest-TestSvcRegr-57569817, ID : 5544a8b9-eaeb-4d4b-832f-28e79300cb22