2025-10-23 11:48:00,315 - INFO - Domain Default found not creating 2025-10-23 11:48:00,453 - INFO - Project ctest-TestSvcRegr-58213737 not found, creating it 2025-10-23 11:48:00,892 - INFO - Created Project:ctest-TestSvcRegr-58213737, ID : e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7 2025-10-23 11:48:02,255 - INFO - ================================================================================ 2025-10-23 11:48:02,255 - INFO - STARTING TEST : test_svc_in_network_datapath 2025-10-23 11:48:02,513 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.254': '0'}} with 2025-10-23 11:48:02,513 - INFO - Initial checks done. Running the testcase now 2025-10-23 11:48:02,513 - INFO - 2025-10-23 11:48:03,039 - DEBUG - Response for create_network : {'network': {'id': 'f9ffab08-e87b-4e32-8013-33586b570ed6', 'name': 'ctest-mgmt_vn-74774997', 'tenant_id': 'e3bacacbc1d344fbbbc33fcadcb231b7', 'project_id': 'e3bacacbc1d344fbbbc33fcadcb231b7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-58213737', 'ctest-mgmt_vn-74774997'], 'port_security_enabled': True, 'description': ''}} 2025-10-23 11:48:03,189 - DEBUG - Response for create_subnet : {'subnet': {'id': '372548f7-3b48-43cc-a6c2-ba3848a41927', 'name': '', 'tenant_id': 'e3bacacbc1d344fbbbc33fcadcb231b7', 'network_id': 'f9ffab08-e87b-4e32-8013-33586b570ed6', 'ip_version': 4, 'cidr': '142.126.163.192/26', 'allocation_pools': [{'start': '142.126.163.194', 'end': '142.126.163.254'}], 'gateway_ip': '142.126.163.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '142.126.163.194', 'tags': [], 'project_id': 'e3bacacbc1d344fbbbc33fcadcb231b7'}} 2025-10-23 11:48:03,206 - INFO - Created VN ctest-mgmt_vn-74774997 2025-10-23 11:48:03,260 - DEBUG - VN ctest-mgmt_vn-74774997 UUID is f9ffab08-e87b-4e32-8013-33586b570ed6 2025-10-23 11:48:03,361 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-23 11:48:03,651 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-23 11:48:03,678 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f59110b8-b47c-4445-b066-d9d82f0bda48 2025-10-23 11:48:03,689 - DEBUG - Requesting: http://10.0.0.38:8082/project/e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7 2025-10-23 11:48:03,757 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f9ffab08-e87b-4e32-8013-33586b570ed6 2025-10-23 11:48:03,768 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f9ffab08-e87b-4e32-8013-33586b570ed6 2025-10-23 11:48:03,777 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6c3e0cdd-a904-4b39-8a3d-24eb5eb569c8 2025-10-23 11:48:03,784 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6c3e0cdd-a904-4b39-8a3d-24eb5eb569c8 2025-10-23 11:48:03,792 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/9ea91e94-6735-4ed9-9d34-05c2a7b17b24 2025-10-23 11:48:03,798 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-23 11:48:03,798 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f9ffab08-e87b-4e32-8013-33586b570ed6 2025-10-23 11:48:03,807 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6c3e0cdd-a904-4b39-8a3d-24eb5eb569c8 2025-10-23 11:48:03,820 - INFO - Verified VN network id 9 for VN f9ffab08-e87b-4e32-8013-33586b570ed6 2025-10-23 11:48:03,820 - INFO - Verifications in API Server for VN ctest-mgmt_vn-74774997 passed 2025-10-23 11:48:03,820 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f9ffab08-e87b-4e32-8013-33586b570ed6 2025-10-23 11:48:03,829 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6c3e0cdd-a904-4b39-8a3d-24eb5eb569c8 2025-10-23 11:48:03,836 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6c3e0cdd-a904-4b39-8a3d-24eb5eb569c8 2025-10-23 11:48:03,843 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/9ea91e94-6735-4ed9-9d34-05c2a7b17b24 2025-10-23 11:48:03,854 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58213737', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '18014305089253756466', 'uuid-lslong': '9228776516263284438'}, 'enable': 'true', 'created': '2025-10-23T11:48:03', 'last-modified': '2025-10-23T11:48:03', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.692857'} 2025-10-23 11:48:03,861 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-10-23 11:48:03,861 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-74774997 passed 2025-10-23 11:48:03,861 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-74774997 in API_Server ====== 2025-10-23 11:48:03,861 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-23 11:48:03,865 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f59110b8-b47c-4445-b066-d9d82f0bda48 2025-10-23 11:48:03,876 - DEBUG - Requesting: http://10.0.0.38:8082/project/e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7 2025-10-23 11:48:03,944 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/f9ffab08-e87b-4e32-8013-33586b570ed6 2025-10-23 11:48:03,952 - DEBUG - =>VN ctest-mgmt_vn-74774997 has no policy to be verified 2025-10-23 11:48:03,952 - DEBUG - Verifying the vn in opserver 2025-10-23 11:48:03,952 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997 virtual network link through opserver 10.0.0.38 2025-10-23 11:48:03,952 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-10-23 11:48:04,239 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-10-23 11:48:04,292 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997?flat'} 2025-10-23 11:48:04,292 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997 is found in opserver 2025-10-23 11:48:04,302 - DEBUG - Do not have enough data to verify VN in agent 2025-10-23 11:48:04,307 - DEBUG - VRF ids for VN ctest-mgmt_vn-74774997: {} 2025-10-23 11:48:04,470 - DEBUG - Response for create_network : {'network': {'id': '586a259b-346a-4ebe-b9b4-28e0c2131dfd', 'name': 'ctest-left_vn-88455430', 'tenant_id': 'e3bacacbc1d344fbbbc33fcadcb231b7', 'project_id': 'e3bacacbc1d344fbbbc33fcadcb231b7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-58213737', 'ctest-left_vn-88455430'], 'port_security_enabled': True, 'description': ''}} 2025-10-23 11:48:04,647 - DEBUG - Response for create_subnet : {'subnet': {'id': '572c40fe-176c-4994-be01-b0044d6e6574', 'name': '', 'tenant_id': 'e3bacacbc1d344fbbbc33fcadcb231b7', 'network_id': '586a259b-346a-4ebe-b9b4-28e0c2131dfd', 'ip_version': 4, 'cidr': '77.132.63.64/26', 'allocation_pools': [{'start': '77.132.63.66', 'end': '77.132.63.126'}], 'gateway_ip': '77.132.63.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '77.132.63.66', 'tags': [], 'project_id': 'e3bacacbc1d344fbbbc33fcadcb231b7'}} 2025-10-23 11:48:04,663 - INFO - Created VN ctest-left_vn-88455430 2025-10-23 11:48:04,716 - DEBUG - VN ctest-left_vn-88455430 UUID is 586a259b-346a-4ebe-b9b4-28e0c2131dfd 2025-10-23 11:48:04,822 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-23 11:48:04,826 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f59110b8-b47c-4445-b066-d9d82f0bda48 2025-10-23 11:48:04,835 - DEBUG - Requesting: http://10.0.0.38:8082/project/e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7 2025-10-23 11:48:04,906 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/586a259b-346a-4ebe-b9b4-28e0c2131dfd 2025-10-23 11:48:04,916 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/586a259b-346a-4ebe-b9b4-28e0c2131dfd 2025-10-23 11:48:04,926 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/1876510f-8a8b-4ba8-8edf-ee01b527860f 2025-10-23 11:48:04,931 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/1876510f-8a8b-4ba8-8edf-ee01b527860f 2025-10-23 11:48:04,936 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/7739f17c-f528-436c-aa94-601da0cdd479 2025-10-23 11:48:04,942 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-10-23 11:48:04,943 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/586a259b-346a-4ebe-b9b4-28e0c2131dfd 2025-10-23 11:48:04,951 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/1876510f-8a8b-4ba8-8edf-ee01b527860f 2025-10-23 11:48:04,962 - INFO - Verified VN network id 10 for VN 586a259b-346a-4ebe-b9b4-28e0c2131dfd 2025-10-23 11:48:04,962 - INFO - Verifications in API Server for VN ctest-left_vn-88455430 passed 2025-10-23 11:48:04,962 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/586a259b-346a-4ebe-b9b4-28e0c2131dfd 2025-10-23 11:48:04,969 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/1876510f-8a8b-4ba8-8edf-ee01b527860f 2025-10-23 11:48:04,974 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/1876510f-8a8b-4ba8-8edf-ee01b527860f 2025-10-23 11:48:04,979 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/7739f17c-f528-436c-aa94-601da0cdd479 2025-10-23 11:48:04,988 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58213737', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6370945971398528702', 'uuid-lslong': '13381365338618404349'}, 'enable': 'true', 'created': '2025-10-23T11:48:04', 'last-modified': '2025-10-23T11:48:04', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.367723'} 2025-10-23 11:48:04,993 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-10-23 11:48:04,993 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-88455430 passed 2025-10-23 11:48:04,993 - DEBUG - ====Verifying policy data for ctest-left_vn-88455430 in API_Server ====== 2025-10-23 11:48:04,993 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-23 11:48:04,998 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f59110b8-b47c-4445-b066-d9d82f0bda48 2025-10-23 11:48:05,007 - DEBUG - Requesting: http://10.0.0.38:8082/project/e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7 2025-10-23 11:48:05,070 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/586a259b-346a-4ebe-b9b4-28e0c2131dfd 2025-10-23 11:48:05,078 - DEBUG - =>VN ctest-left_vn-88455430 has no policy to be verified 2025-10-23 11:48:05,078 - DEBUG - Verifying the vn in opserver 2025-10-23 11:48:05,078 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430 virtual network link through opserver 10.0.0.38 2025-10-23 11:48:05,078 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-10-23 11:48:05,087 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430?flat'} 2025-10-23 11:48:05,087 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430 is found in opserver 2025-10-23 11:48:05,095 - DEBUG - Do not have enough data to verify VN in agent 2025-10-23 11:48:05,099 - DEBUG - VRF ids for VN ctest-left_vn-88455430: {} 2025-10-23 11:48:05,255 - DEBUG - Response for create_network : {'network': {'id': 'ec68191e-ba43-46ff-98f4-59b8cc2f06fa', 'name': 'ctest-right_vn-41319204', 'tenant_id': 'e3bacacbc1d344fbbbc33fcadcb231b7', 'project_id': 'e3bacacbc1d344fbbbc33fcadcb231b7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-58213737', 'ctest-right_vn-41319204'], 'port_security_enabled': True, 'description': ''}} 2025-10-23 11:48:05,509 - DEBUG - Response for create_subnet : {'subnet': {'id': '4bae05a9-a0ba-45c8-9323-59720d2d3716', 'name': '', 'tenant_id': 'e3bacacbc1d344fbbbc33fcadcb231b7', 'network_id': 'ec68191e-ba43-46ff-98f4-59b8cc2f06fa', 'ip_version': 4, 'cidr': '160.14.245.192/26', 'allocation_pools': [{'start': '160.14.245.194', 'end': '160.14.245.254'}], 'gateway_ip': '160.14.245.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '160.14.245.194', 'tags': [], 'project_id': 'e3bacacbc1d344fbbbc33fcadcb231b7'}} 2025-10-23 11:48:05,529 - INFO - Created VN ctest-right_vn-41319204 2025-10-23 11:48:05,584 - DEBUG - VN ctest-right_vn-41319204 UUID is ec68191e-ba43-46ff-98f4-59b8cc2f06fa 2025-10-23 11:48:05,686 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-23 11:48:05,691 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f59110b8-b47c-4445-b066-d9d82f0bda48 2025-10-23 11:48:05,701 - DEBUG - Requesting: http://10.0.0.38:8082/project/e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7 2025-10-23 11:48:05,783 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/ec68191e-ba43-46ff-98f4-59b8cc2f06fa 2025-10-23 11:48:05,793 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/ec68191e-ba43-46ff-98f4-59b8cc2f06fa 2025-10-23 11:48:05,801 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/22a4e88b-fa3d-4e9f-bd2e-8f7274d5bf06 2025-10-23 11:48:05,811 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/22a4e88b-fa3d-4e9f-bd2e-8f7274d5bf06 2025-10-23 11:48:05,820 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/dc5cf942-3838-4083-91d1-9fb39a1d4538 2025-10-23 11:48:05,826 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-23 11:48:05,826 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/ec68191e-ba43-46ff-98f4-59b8cc2f06fa 2025-10-23 11:48:05,838 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/22a4e88b-fa3d-4e9f-bd2e-8f7274d5bf06 2025-10-23 11:48:05,855 - INFO - Verified VN network id 11 for VN ec68191e-ba43-46ff-98f4-59b8cc2f06fa 2025-10-23 11:48:05,855 - INFO - Verifications in API Server for VN ctest-right_vn-41319204 passed 2025-10-23 11:48:05,855 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/ec68191e-ba43-46ff-98f4-59b8cc2f06fa 2025-10-23 11:48:05,862 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/22a4e88b-fa3d-4e9f-bd2e-8f7274d5bf06 2025-10-23 11:48:05,873 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/22a4e88b-fa3d-4e9f-bd2e-8f7274d5bf06 2025-10-23 11:48:05,880 - DEBUG - Requesting: http://10.0.0.38:8082/route-target/dc5cf942-3838-4083-91d1-9fb39a1d4538 2025-10-23 11:48:05,891 - DEBUG - Control-node 10.0.0.38 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-58213737:ctest-right_vn-41319204', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-58213737', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17034893210293585663', 'uuid-lslong': '11021532838316934906'}, 'enable': 'true', 'created': '2025-10-23T11:48:05', 'last-modified': '2025-10-23T11:48:05', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.421176'} 2025-10-23 11:48:05,896 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-10-23 11:48:05,896 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-41319204 passed 2025-10-23 11:48:05,897 - DEBUG - ====Verifying policy data for ctest-right_vn-41319204 in API_Server ====== 2025-10-23 11:48:05,897 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-23 11:48:05,902 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f59110b8-b47c-4445-b066-d9d82f0bda48 2025-10-23 11:48:05,914 - DEBUG - Requesting: http://10.0.0.38:8082/project/e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7 2025-10-23 11:48:05,982 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-network/ec68191e-ba43-46ff-98f4-59b8cc2f06fa 2025-10-23 11:48:05,989 - DEBUG - =>VN ctest-right_vn-41319204 has no policy to be verified 2025-10-23 11:48:05,989 - DEBUG - Verifying the vn in opserver 2025-10-23 11:48:05,990 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-58213737:ctest-right_vn-41319204 virtual network link through opserver 10.0.0.38 2025-10-23 11:48:05,990 - DEBUG - Requesting: http://10.0.0.38:8081/analytics/uves/virtual-networks 2025-10-23 11:48:05,998 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-right_vn-41319204', 'href': 'http://10.0.0.38:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-58213737:ctest-right_vn-41319204?flat'} 2025-10-23 11:48:05,998 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-58213737:ctest-right_vn-41319204 is found in opserver 2025-10-23 11:48:06,006 - DEBUG - Do not have enough data to verify VN in agent 2025-10-23 11:48:06,009 - DEBUG - VRF ids for VN ctest-right_vn-41319204: {} 2025-10-23 11:48:06,060 - DEBUG - Services list from nova: [, , ] 2025-10-23 11:48:07,034 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4459-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4459-1) 2025-10-23 11:48:08,090 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4459-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4459-1) 2025-10-23 11:48:08,090 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-49731981'] 2025-10-23 11:48:08,515 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-49731981'] created succesfully 2025-10-23 11:48:08,790 - DEBUG - Image by name cirros_in_net not found 2025-10-23 11:48:08,790 - DEBUG - Installing image cirros_in_net 2025-10-23 11:48:08,790 - 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-10-23 11:48:08,790 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-10-23 11:48:08,790 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-10-23 11:48:08,803 - 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-10-23 11:48:08,916 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz 2025-10-23 11:48:08,916 - DEBUG - Unzip image 2025-10-23 11:48:08,916 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz 2025-10-23 11:48:09,066 - DEBUG - Try to create image in user context 2025-10-23 11:48:09,292 - DEBUG - Image has been created in user context 2025-10-23 11:48:09,292 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk 2025-10-23 11:48:09,307 - DEBUG - Downloaded image has been removed 2025-10-23 11:48:09,323 - DEBUG - Uploaded image status = active 2025-10-23 11:48:10,393 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4459-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4459-1) 2025-10-23 11:48:10,394 - INFO - Waiting for VM ctest-pt_svm0-13740328 to be up.. 2025-10-23 11:48:10,431 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-23 11:48:15,503 - DEBUG - VM is in ACTIVE state now 2025-10-23 11:48:15,503 - INFO - VM name : ctest-pt_svm0-13740328 2025-10-23 11:48:15,567 - DEBUG - VM ctest-pt_svm0-13740328 ID is e7679924-efe4-481b-8a43-dc469d4db430 2025-10-23 11:48:15,589 - DEBUG - VM ctest-pt_svm0-13740328 launched on Node cn-jenkins-deploy-platform-ansible-os-4459-1 2025-10-23 11:48:15,794 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/e7679924-efe4-481b-8a43-dc469d4db430 2025-10-23 11:48:15,801 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/05fdcda0-5f96-445d-bec4-4671d583f442 2025-10-23 11:48:15,808 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/631d17ee-b70f-4f8e-85fe-d22186856709 2025-10-23 11:48:15,816 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/832d6700-b407-426a-9cb0-dd31583d6b66 2025-10-23 11:48:19,087 - 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-10-23 11:48:19,087 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-13740328 failed! 2025-10-23 11:48:19,140 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997 is 142.126.163.193 and allocation pool is NOT set 2025-10-23 11:48:23,213 - 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 1019ms') 2025-10-23 11:48:23,214 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-13740328 failed! 2025-10-23 11:48:23,268 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997 is 142.126.163.193 and allocation pool is NOT set 2025-10-23 11:48:27,341 - 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 1020ms') 2025-10-23 11:48:27,342 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-13740328 failed! 2025-10-23 11:48:27,400 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997 is 142.126.163.193 and allocation pool is NOT set 2025-10-23 11:48:31,470 - 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') 2025-10-23 11:48:31,471 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-13740328 failed! 2025-10-23 11:48:31,524 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997 is 142.126.163.193 and allocation pool is NOT set 2025-10-23 11:48:33,580 - 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.25 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.839 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 = 0.839/2.544/4.250/1.705 ms') 2025-10-23 11:48:33,580 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-13740328 passed 2025-10-23 11:48:33,728 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:48:33,728 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-13740328, IP 142.126.163.195, Port 22 2025-10-23 11:48:33,793 - 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-10-23 11:48:33,866 - DEBUG - VM ctest-pt_svm0-13740328 is NOT ready for SSH connections, VM status: ACTIVE 2025-10-23 11:48:38,867 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:48:38,867 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-13740328, IP 142.126.163.195, Port 22 2025-10-23 11:48:38,923 - 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-10-23 11:48:39,002 - DEBUG - VM ctest-pt_svm0-13740328 is NOT ready for SSH connections, VM status: ACTIVE 2025-10-23 11:48:44,003 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:48:44,003 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-13740328, IP 142.126.163.195, Port 22 2025-10-23 11:48:44,059 - 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-10-23 11:48:44,134 - DEBUG - VM ctest-pt_svm0-13740328 is NOT ready for SSH connections, VM status: ACTIVE 2025-10-23 11:48:49,135 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:48:49,135 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-13740328, IP 142.126.163.195, Port 22 2025-10-23 11:48:49,191 - 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-10-23 11:48:49,259 - DEBUG - VM ctest-pt_svm0-13740328 is NOT ready for SSH connections, VM status: ACTIVE 2025-10-23 11:48:54,261 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:48:54,261 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-13740328, IP 142.126.163.195, Port 22 2025-10-23 11:48:54,412 - DEBUG - VM ctest-pt_svm0-13740328 is ready for SSH connections 2025-10-23 11:48:54,560 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:48:54,560 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-13740328, IP 142.126.163.195, Port 22 2025-10-23 11:48:54,719 - DEBUG - VM ctest-pt_svm0-13740328 is ready for SSH connections 2025-10-23 11:48:54,872 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:48:54,872 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-13740328, IP 142.126.163.195, Port 22 2025-10-23 11:48:55,030 - DEBUG - VM ctest-pt_svm0-13740328 is ready for SSH connections 2025-10-23 11:48:55,035 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSvcRegr-58213737', 'ctest-si-84416322'] 2025-10-23 11:48:55,892 - INFO - Waiting for VM ctest-left_vm-72948324 to be up.. 2025-10-23 11:48:55,960 - DEBUG - VM is in ACTIVE state now 2025-10-23 11:48:55,960 - INFO - VM name : ctest-left_vm-72948324 2025-10-23 11:48:56,035 - DEBUG - VM ctest-left_vm-72948324 ID is 7681163b-d515-4ab5-9b3e-5dbd4dad63a6 2025-10-23 11:48:56,036 - DEBUG - VM ctest-left_vm-72948324 launched on Node cn-jenkins-deploy-platform-ansible-os-4459-1 2025-10-23 11:48:56,101 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/7681163b-d515-4ab5-9b3e-5dbd4dad63a6 2025-10-23 11:48:56,108 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/f3b1569c-2bfb-4ade-8804-8731b883dc1e 2025-10-23 11:48:57,361 - 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.99 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.347 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.347/1.669/2.991/1.322 ms') 2025-10-23 11:48:57,361 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-72948324 passed 2025-10-23 11:48:57,508 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:48:57,508 - DEBUG - Waiting to SSH to VM ctest-left_vm-72948324, IP 77.132.63.67, Port 22 2025-10-23 11:48:57,659 - DEBUG - VM ctest-left_vm-72948324 is ready for SSH connections 2025-10-23 11:48:57,659 - INFO - Waiting for VM ctest-right_vm-59843743 to be up.. 2025-10-23 11:48:57,721 - DEBUG - VM is in ACTIVE state now 2025-10-23 11:48:57,722 - INFO - VM name : ctest-right_vm-59843743 2025-10-23 11:48:57,783 - DEBUG - VM ctest-right_vm-59843743 ID is 01c23646-a1eb-4715-a8ac-1d1d50ae5e0f 2025-10-23 11:48:57,783 - DEBUG - VM ctest-right_vm-59843743 launched on Node cn-jenkins-deploy-platform-ansible-os-4459-1 2025-10-23 11:48:57,844 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/01c23646-a1eb-4715-a8ac-1d1d50ae5e0f 2025-10-23 11:48:57,851 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/4f594d9e-a2cc-4ceb-9bf1-f8b7dcb671de 2025-10-23 11:48:59,078 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.55 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.334 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.334/1.443/2.553/1.109 ms') 2025-10-23 11:48:59,078 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-59843743 passed 2025-10-23 11:48:59,224 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:48:59,224 - DEBUG - Waiting to SSH to VM ctest-right_vm-59843743, IP 160.14.245.195, Port 22 2025-10-23 11:48:59,380 - DEBUG - VM ctest-right_vm-59843743 is ready for SSH connections 2025-10-23 11:48:59,384 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430, 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-58213737:ctest-right_vn-41319204, 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-58213737:ctest-si-84416322'], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-23 11:48:59,808 - INFO - Attaching policy ctest-policy-74283029 to vn ctest-left_vn-88455430 2025-10-23 11:49:00,336 - INFO - Attaching policy ctest-policy-74283029 to vn ctest-right_vn-41319204 2025-10-23 11:49:00,800 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-49731981'] created succesfully 2025-10-23 11:49:01,024 - DEBUG - Service instance: ['default-domain', 'ctest-TestSvcRegr-58213737', 'ctest-si-84416322'] created succesfully 2025-10-23 11:49:01,024 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-23 11:49:01,029 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f59110b8-b47c-4445-b066-d9d82f0bda48 2025-10-23 11:49:01,040 - DEBUG - Requesting: http://10.0.0.38:8082/project/e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7 2025-10-23 11:49:01,108 - DEBUG - Requesting: http://10.0.0.38:8082/service-instance/ad350aab-1699-43ea-be8e-dfaa1021c228 2025-10-23 11:49:01,116 - DEBUG - SI 'ctest-si-84416322' has service template ref to ctest-service_template_1-49731981 2025-10-23 11:49:01,116 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-23 11:49:01,121 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f59110b8-b47c-4445-b066-d9d82f0bda48 2025-10-23 11:49:01,176 - DEBUG - Requesting: http://10.0.0.38:8082/project/e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7 2025-10-23 11:49:01,247 - DEBUG - Requesting: http://10.0.0.38:8082/service-instance/ad350aab-1699-43ea-be8e-dfaa1021c228 2025-10-23 11:49:01,254 - DEBUG - Requesting: http://10.0.0.38:8082/port-tuple/73b4f232-f937-495a-ba73-31307bb441e7 2025-10-23 11:49:01,261 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/05fdcda0-5f96-445d-bec4-4671d583f442 2025-10-23 11:49:01,270 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/631d17ee-b70f-4f8e-85fe-d22186856709 2025-10-23 11:49:01,279 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/832d6700-b407-426a-9cb0-dd31583d6b66 2025-10-23 11:49:01,474 - DEBUG - VM is in ACTIVE state now 2025-10-23 11:49:01,876 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/e7679924-efe4-481b-8a43-dc469d4db430 2025-10-23 11:49:01,882 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/05fdcda0-5f96-445d-bec4-4671d583f442 2025-10-23 11:49:01,891 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/631d17ee-b70f-4f8e-85fe-d22186856709 2025-10-23 11:49:01,901 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/832d6700-b407-426a-9cb0-dd31583d6b66 2025-10-23 11:49:01,909 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/1173f390-c0c6-4bc4-adf3-30ff73e1fcc4 2025-10-23 11:49:01,916 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/b0fd0c9d-fe80-43d3-9b4c-54ee1c7a86c2 2025-10-23 11:49:01,922 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/b7b062cc-5e0d-4d38-bfbd-e53626ea3dce 2025-10-23 11:49:01,929 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/56181fbf-f027-4944-bd51-4fe8b0e292b9 2025-10-23 11:49:01,934 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/bb930a8a-fd2c-4d2a-8654-a34cf2195e9a 2025-10-23 11:49:01,939 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/c462dfa4-ef75-4f55-a0fc-3296b121eb5e 2025-10-23 11:49:01,946 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/5b3778e1-eda9-47ce-a28f-e14b5c23acc9 2025-10-23 11:49:01,952 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/8f9b02d5-29ca-4275-b480-3eac46296363 2025-10-23 11:49:01,959 - DEBUG - Requesting: http://10.0.0.38:8082/instance-ip/f9166253-ff8a-4eab-9454-14c986512c01 2025-10-23 11:49:02,287 - DEBUG - VM is in ACTIVE state now 2025-10-23 11:49:02,287 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/e7679924-efe4-481b-8a43-dc469d4db430 2025-10-23 11:49:02,294 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/05fdcda0-5f96-445d-bec4-4671d583f442 2025-10-23 11:49:02,302 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/631d17ee-b70f-4f8e-85fe-d22186856709 2025-10-23 11:49:02,310 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/832d6700-b407-426a-9cb0-dd31583d6b66 2025-10-23 11:49:03,541 - 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=0.970 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.446 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.446/0.708/0.970/0.262 ms') 2025-10-23 11:49:03,541 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-13740328 passed 2025-10-23 11:49:03,574 - DEBUG - VM ctest-pt_svm0-13740328 Tap interface: {'index': '6', 'name': 'tap631d17ee-b7', 'uuid': '631d17ee-b70f-4f8e-85fe-d22186856709', 'vrf_name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430:ctest-left_vn-88455430', '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': '10', 'vn_name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430', 'vm_uuid': 'e7679924-efe4-481b-8a43-dc469d4db430', 'vm_name': 'ctest-pt_svm0-13740328', 'ip_addr': '77.132.63.68', 'mac_addr': '02:63:1d:17:ee:b7', '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-58213737:631d17ee-b70f-4f8e-85fe-d22186856709', 'sg_uuid_list': ['6d675009-b2b1-4b92-8330-53f679c61db1'], 'static_route_list': None, 'vm_project_uuid': 'e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7', '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': '05fdcda0-5f96-445d-bec4-4671d583f442', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-23 11:49:03,574 - DEBUG - Agent 10.0.0.38 vrf name: default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430:ctest-left_vn-88455430 2025-10-23 11:49:03,578 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430:ctest-left_vn-88455430', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430', 'table_label': '-1', 'vxlan_id': '10', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.254:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-10-23 11:49:03,738 - DEBUG - VM ctest-pt_svm0-13740328 : Tap interface tap631d17ee-b7 is set to Active 2025-10-23 11:49:03,739 - DEBUG - Tap interface tap631d17ee-b7 detail : {'index': '6', 'name': 'tap631d17ee-b7', 'uuid': '631d17ee-b70f-4f8e-85fe-d22186856709', 'vrf_name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430:ctest-left_vn-88455430', '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': '10', 'vn_name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430', 'vm_uuid': 'e7679924-efe4-481b-8a43-dc469d4db430', 'vm_name': 'ctest-pt_svm0-13740328', 'ip_addr': '77.132.63.68', 'mac_addr': '02:63:1d:17:ee:b7', '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-58213737:631d17ee-b70f-4f8e-85fe-d22186856709', 'sg_uuid_list': ['6d675009-b2b1-4b92-8330-53f679c61db1'], 'static_route_list': None, 'vm_project_uuid': 'e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7', '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': '05fdcda0-5f96-445d-bec4-4671d583f442', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-23 11:49:03,932 - DEBUG - Starting Layer 2 verification in Agent 2025-10-23 11:49:03,941 - DEBUG - Layer 2 path is seen for VM MAC 02:63:1d:17:ee:b7 in agent 10.0.0.38 2025-10-23 11:49:03,941 - DEBUG - Active layer 2 route in agent is present for VMI tap631d17ee-b7 2025-10-23 11:49:03,942 - DEBUG - L2 label(48) matches bw route table and interface table 2025-10-23 11:49:03,946 - DEBUG - VN default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430 is found in Agent of node 10.0.0.38 2025-10-23 11:49:04,150 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430 is consistent in agent 10.0.0.38 2025-10-23 11:49:04,150 - DEBUG - Route for VM IP ['77.132.63.68'] is consistent in agent 10.0.0.38 2025-10-23 11:49:04,150 - DEBUG - VN default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430 verification for VM ctest-pt_svm0-13740328 in Agent 10.0.0.38 passed 2025-10-23 11:49:04,344 - DEBUG - Starting all layer 2 verification in agent 10.0.0.38 2025-10-23 11:49:04,353 - DEBUG - Route for VM MAC 02:63:1d:17:ee:b7 is consistent in agent 10.0.0.38 2025-10-23 11:49:04,368 - DEBUG - VM ctest-pt_svm0-13740328 Tap interface: {'index': '5', 'name': 'tap832d6700-b4', 'uuid': '832d6700-b407-426a-9cb0-dd31583d6b66', 'vrf_name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997:ctest-mgmt_vn-74774997', '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': '9', 'vn_name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997', 'vm_uuid': 'e7679924-efe4-481b-8a43-dc469d4db430', 'vm_name': 'ctest-pt_svm0-13740328', 'ip_addr': '142.126.163.195', 'mac_addr': '02:83:2d:67:00:b4', '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-58213737:832d6700-b407-426a-9cb0-dd31583d6b66', 'sg_uuid_list': ['6d675009-b2b1-4b92-8330-53f679c61db1'], 'static_route_list': None, 'vm_project_uuid': 'e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7', '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-10-23 11:49:04,368 - DEBUG - Agent 10.0.0.38 vrf name: default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997:ctest-mgmt_vn-74774997 2025-10-23 11:49:04,372 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997:ctest-mgmt_vn-74774997', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997', 'table_label': '-1', 'vxlan_id': '9', 'evpnindex': '4', 'brindex': '4', 'mplsindex': '0', 'RD': '10.20.0.254:4', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-10-23 11:49:04,573 - DEBUG - VM ctest-pt_svm0-13740328 : Tap interface tap832d6700-b4 is set to Active 2025-10-23 11:49:04,573 - DEBUG - Tap interface tap832d6700-b4 detail : {'index': '5', 'name': 'tap832d6700-b4', 'uuid': '832d6700-b407-426a-9cb0-dd31583d6b66', 'vrf_name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997:ctest-mgmt_vn-74774997', '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': '9', 'vn_name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997', 'vm_uuid': 'e7679924-efe4-481b-8a43-dc469d4db430', 'vm_name': 'ctest-pt_svm0-13740328', 'ip_addr': '142.126.163.195', 'mac_addr': '02:83:2d:67:00:b4', '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-58213737:832d6700-b407-426a-9cb0-dd31583d6b66', 'sg_uuid_list': ['6d675009-b2b1-4b92-8330-53f679c61db1'], 'static_route_list': None, 'vm_project_uuid': 'e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7', '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-10-23 11:49:04,768 - DEBUG - Starting Layer 2 verification in Agent 2025-10-23 11:49:04,776 - DEBUG - Layer 2 path is seen for VM MAC 02:83:2d:67:00:b4 in agent 10.0.0.38 2025-10-23 11:49:04,776 - DEBUG - Active layer 2 route in agent is present for VMI tap832d6700-b4 2025-10-23 11:49:04,776 - DEBUG - L2 label(43) matches bw route table and interface table 2025-10-23 11:49:04,781 - DEBUG - VN default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997 is found in Agent of node 10.0.0.38 2025-10-23 11:49:04,984 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997 is consistent in agent 10.0.0.38 2025-10-23 11:49:04,984 - DEBUG - Route for VM IP ['142.126.163.195'] is consistent in agent 10.0.0.38 2025-10-23 11:49:04,984 - DEBUG - VN default-domain:ctest-TestSvcRegr-58213737:ctest-mgmt_vn-74774997 verification for VM ctest-pt_svm0-13740328 in Agent 10.0.0.38 passed 2025-10-23 11:49:05,176 - DEBUG - Starting all layer 2 verification in agent 10.0.0.38 2025-10-23 11:49:05,184 - DEBUG - Route for VM MAC 02:83:2d:67:00:b4 is consistent in agent 10.0.0.38 2025-10-23 11:49:05,199 - DEBUG - VM ctest-pt_svm0-13740328 Tap interface: {'index': '7', 'name': 'tap05fdcda0-5f', 'uuid': '05fdcda0-5f96-445d-bec4-4671d583f442', 'vrf_name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-right_vn-41319204:ctest-right_vn-41319204', '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': '11', 'vn_name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-right_vn-41319204', 'vm_uuid': 'e7679924-efe4-481b-8a43-dc469d4db430', 'vm_name': 'ctest-pt_svm0-13740328', 'ip_addr': '160.14.245.196', 'mac_addr': '02:05:fd:cd:a0:5f', '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-58213737:05fdcda0-5f96-445d-bec4-4671d583f442', 'sg_uuid_list': ['6d675009-b2b1-4b92-8330-53f679c61db1'], 'static_route_list': None, 'vm_project_uuid': 'e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7', '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': '631d17ee-b70f-4f8e-85fe-d22186856709', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-23 11:49:05,199 - DEBUG - Agent 10.0.0.38 vrf name: default-domain:ctest-TestSvcRegr-58213737:ctest-right_vn-41319204:ctest-right_vn-41319204 2025-10-23 11:49:05,203 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-right_vn-41319204:ctest-right_vn-41319204', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSvcRegr-58213737:ctest-right_vn-41319204', 'table_label': '-1', 'vxlan_id': '11', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.254:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-10-23 11:49:05,402 - DEBUG - VM ctest-pt_svm0-13740328 : Tap interface tap05fdcda0-5f is set to Active 2025-10-23 11:49:05,403 - DEBUG - Tap interface tap05fdcda0-5f detail : {'index': '7', 'name': 'tap05fdcda0-5f', 'uuid': '05fdcda0-5f96-445d-bec4-4671d583f442', 'vrf_name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-right_vn-41319204:ctest-right_vn-41319204', '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': '11', 'vn_name': 'default-domain:ctest-TestSvcRegr-58213737:ctest-right_vn-41319204', 'vm_uuid': 'e7679924-efe4-481b-8a43-dc469d4db430', 'vm_name': 'ctest-pt_svm0-13740328', 'ip_addr': '160.14.245.196', 'mac_addr': '02:05:fd:cd:a0:5f', '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-58213737:05fdcda0-5f96-445d-bec4-4671d583f442', 'sg_uuid_list': ['6d675009-b2b1-4b92-8330-53f679c61db1'], 'static_route_list': None, 'vm_project_uuid': 'e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7', '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': '631d17ee-b70f-4f8e-85fe-d22186856709', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-10-23 11:49:05,596 - DEBUG - Starting Layer 2 verification in Agent 2025-10-23 11:49:05,605 - DEBUG - Layer 2 path is seen for VM MAC 02:05:fd:cd:a0:5f in agent 10.0.0.38 2025-10-23 11:49:05,606 - DEBUG - Active layer 2 route in agent is present for VMI tap05fdcda0-5f 2025-10-23 11:49:05,606 - DEBUG - L2 label(53) matches bw route table and interface table 2025-10-23 11:49:05,610 - DEBUG - VN default-domain:ctest-TestSvcRegr-58213737:ctest-right_vn-41319204 is found in Agent of node 10.0.0.38 2025-10-23 11:49:05,813 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-58213737:ctest-right_vn-41319204 is consistent in agent 10.0.0.38 2025-10-23 11:49:05,813 - DEBUG - Route for VM IP ['160.14.245.196'] is consistent in agent 10.0.0.38 2025-10-23 11:49:05,814 - DEBUG - VN default-domain:ctest-TestSvcRegr-58213737:ctest-right_vn-41319204 verification for VM ctest-pt_svm0-13740328 in Agent 10.0.0.38 passed 2025-10-23 11:49:06,008 - DEBUG - Starting all layer 2 verification in agent 10.0.0.38 2025-10-23 11:49:06,017 - DEBUG - Route for VM MAC 02:05:fd:cd:a0:5f is consistent in agent 10.0.0.38 2025-10-23 11:49:06,017 - INFO - VM ctest-pt_svm0-13740328 verifications in Compute nodes passed 2025-10-23 11:49:06,017 - DEBUG - The SVMs in the SI are : [] 2025-10-23 11:49:06,018 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/e7679924-efe4-481b-8a43-dc469d4db430 2025-10-23 11:49:06,024 - DEBUG - Service VM for SI 'ctest-si-84416322' is launched 2025-10-23 11:49:06,025 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-23 11:49:06,029 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f59110b8-b47c-4445-b066-d9d82f0bda48 2025-10-23 11:49:06,078 - DEBUG - Requesting: http://10.0.0.38:8082/project/e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7 2025-10-23 11:49:06,149 - DEBUG - Requesting: http://10.0.0.38:8082/service-instance/ad350aab-1699-43ea-be8e-dfaa1021c228 2025-10-23 11:49:06,156 - DEBUG - SI ctest-si-84416322 has Port Tuple: ['ctest-port_tuple-00750203'] 2025-10-23 11:49:06,260 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-58213737:ctest-left_vn-88455430 2025-10-23 11:49:06,404 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-58213737:ctest-right_vn-41319204 2025-10-23 11:49:06,553 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:49:06,560 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:49:06,560 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:49:06,564 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:49:06,564 - INFO - Route to 160.14.245.195 seen in VRF:5 on cn-jenkins-deploy-platform-ansible-os-4459-1, and SI ctest-si-84416322 is seen as the NH 2025-10-23 11:49:06,721 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:49:06,727 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:49:06,727 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:49:06,732 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:49:06,732 - INFO - Route to 77.132.63.67 seen in VRF:6 on cn-jenkins-deploy-platform-ansible-os-4459-1, and SI ctest-si-84416322 is seen as the NH 2025-10-23 11:49:06,732 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:49:06,732 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-23 11:49:06,732 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 160.14.245.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2025-10-23 11:49:06,732 - DEBUG - ping -s 56 -c 3 -W 1 160.14.245.195 2025-10-23 11:49:10,680 - DEBUG - PING 160.14.245.195 (160.14.245.195): 56 data bytes 64 bytes from 160.14.245.195: seq=0 ttl=61 time=5.221 ms 64 bytes from 160.14.245.195: seq=1 ttl=61 time=1.483 ms 64 bytes from 160.14.245.195: seq=2 ttl=61 time=1.343 ms --- 160.14.245.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.343/2.682/5.221 ms 2025-10-23 11:49:10,680 - INFO - Ping to IP 160.14.245.195 from VM ctest-left_vm-72948324 passed 2025-10-23 11:49:10,681 - INFO - Dettaching policy ctest-policy-74283029 from vn ctest-right_vn-41319204 2025-10-23 11:49:10,868 - INFO - Dettaching policy ctest-policy-74283029 from vn ctest-left_vn-88455430 2025-10-23 11:49:11,118 - INFO - Deleted policy ctest-policy-74283029 2025-10-23 11:49:11,118 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSvcRegr-58213737', 'ctest-si-84416322'] 2025-10-23 11:49:11,203 - INFO - Deleted SI ['default-domain', 'ctest-TestSvcRegr-58213737', 'ctest-si-84416322'] 2025-10-23 11:49:11,203 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-23 11:49:11,209 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f59110b8-b47c-4445-b066-d9d82f0bda48 2025-10-23 11:49:11,228 - DEBUG - Requesting: http://10.0.0.38:8082/project/e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7 2025-10-23 11:49:11,316 - DEBUG - Service instance ctest-si-84416322 removed from api server 2025-10-23 11:49:11,316 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/e7679924-efe4-481b-8a43-dc469d4db430 2025-10-23 11:49:11,322 - DEBUG - All Service VMs unlinked from SI ctest-si-84416322 2025-10-23 11:49:11,325 - DEBUG - 0 svc intances found in all projects. They are [] 2025-10-23 11:49:11,326 - DEBUG - 0 svc intances found in current project. They are [] 2025-10-23 11:49:11,371 - DEBUG - 0 svc intances found in all projects. They are [] 2025-10-23 11:49:11,371 - DEBUG - 0 svc intances found in current project. They are [] 2025-10-23 11:49:11,372 - INFO - Deleting VM ctest-pt_svm0-13740328 2025-10-23 11:49:11,450 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-49731981'] 2025-10-23 11:49:11,555 - INFO - Service template: ['default-domain', 'ctest-service_template_1-49731981'] deleted successfully. 2025-10-23 11:49:11,557 - INFO - Deleting VM ctest-right_vm-59843743 2025-10-23 11:49:11,661 - INFO - Deleting VM ctest-left_vm-72948324 2025-10-23 11:49:11,762 - INFO - Deleting VN ctest-right_vn-41319204 2025-10-23 11:49:11,803 - DEBUG - VN ec68191e-ba43-46ff-98f4-59b8cc2f06fa still in use: Unable to complete operation on network ec68191e-ba43-46ff-98f4-59b8cc2f06fa. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9fb05513-ce9d-431b-9c6c-24bd884d3701'] 2025-10-23 11:49:11,803 - WARNING - Deleting VN ctest-right_vn-41319204 failed..Will retry 2025-10-23 11:49:13,885 - DEBUG - Response for deleting network () 2025-10-23 11:49:13,886 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/22a4e88b-fa3d-4e9f-bd2e-8f7274d5bf06 2025-10-23 11:49:13,891 - DEBUG - Response Code: 404 2025-10-23 11:49:13,891 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-23 11:49:13,896 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f59110b8-b47c-4445-b066-d9d82f0bda48 2025-10-23 11:49:13,949 - DEBUG - Requesting: http://10.0.0.38:8082/project/e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7 2025-10-23 11:49:14,017 - INFO - Validated that VN ctest-right_vn-41319204 is not found in API Server 2025-10-23 11:49:14,025 - DEBUG - VN ctest-right_vn-41319204 is not present in Agent 10.0.0.38 2025-10-23 11:49:14,025 - INFO - Validated that VN ctest-right_vn-41319204 is not in any agent 2025-10-23 11:49:14,040 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-41319204 info 2025-10-23 11:49:14,040 - INFO - Deleting VN ctest-left_vn-88455430 2025-10-23 11:49:14,133 - DEBUG - Response for deleting network () 2025-10-23 11:49:14,133 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/1876510f-8a8b-4ba8-8edf-ee01b527860f 2025-10-23 11:49:14,139 - DEBUG - Response Code: 404 2025-10-23 11:49:14,139 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-23 11:49:14,144 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f59110b8-b47c-4445-b066-d9d82f0bda48 2025-10-23 11:49:14,200 - DEBUG - Requesting: http://10.0.0.38:8082/project/e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7 2025-10-23 11:49:14,266 - INFO - Validated that VN ctest-left_vn-88455430 is not found in API Server 2025-10-23 11:49:14,274 - DEBUG - VN ctest-left_vn-88455430 is not present in Agent 10.0.0.38 2025-10-23 11:49:14,274 - INFO - Validated that VN ctest-left_vn-88455430 is not in any agent 2025-10-23 11:49:14,286 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-88455430 info 2025-10-23 11:49:14,286 - INFO - Deleting VN ctest-mgmt_vn-74774997 2025-10-23 11:49:14,376 - DEBUG - Response for deleting network () 2025-10-23 11:49:14,376 - DEBUG - Requesting: http://10.0.0.38:8082/routing-instance/6c3e0cdd-a904-4b39-8a3d-24eb5eb569c8 2025-10-23 11:49:14,380 - DEBUG - Response Code: 404 2025-10-23 11:49:14,381 - DEBUG - Requesting: http://10.0.0.38:8082/domains 2025-10-23 11:49:14,384 - DEBUG - Requesting: http://10.0.0.38:8082/domain/f59110b8-b47c-4445-b066-d9d82f0bda48 2025-10-23 11:49:14,436 - DEBUG - Requesting: http://10.0.0.38:8082/project/e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7 2025-10-23 11:49:14,501 - INFO - Validated that VN ctest-mgmt_vn-74774997 is not found in API Server 2025-10-23 11:49:14,510 - DEBUG - VN ctest-mgmt_vn-74774997 is not present in Agent 10.0.0.38 2025-10-23 11:49:14,510 - INFO - Validated that VN ctest-mgmt_vn-74774997 is not in any agent 2025-10-23 11:49:14,523 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-74774997 info 2025-10-23 11:49:14,780 - DEBUG - No XMPP flaps were noticed during the test 2025-10-23 11:49:14,780 - INFO -
2025-10-23 11:49:14,780 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:12]
2025-10-23 11:49:14,780 - INFO - -------------------------------------------------------------------------------- 2025-10-23 11:49:15,454 - INFO - Deleted project: ctest-TestSvcRegr-58213737, ID : e3bacacb-c1d3-44fb-bbc3-3fcadcb231b7