2026-01-29 08:30:50,455 - INFO - Domain Default found not creating 2026-01-29 08:30:50,628 - INFO - Project ctest-TestSvcRegr-38857456 not found, creating it 2026-01-29 08:30:51,082 - INFO - Created Project:ctest-TestSvcRegr-38857456, ID : 1e2c4483-8c81-45d7-a792-f4006122385e 2026-01-29 08:30:52,623 - INFO - ================================================================================ 2026-01-29 08:30:52,623 - INFO - STARTING TEST : test_svc_in_network_datapath 2026-01-29 08:30:52,885 - DEBUG - Nothing to compare xmpp stats {'10.0.0.141': {'10.20.0.254': '0'}} with 2026-01-29 08:30:52,885 - INFO - Initial checks done. Running the testcase now 2026-01-29 08:30:52,885 - INFO - 2026-01-29 08:30:53,440 - DEBUG - Response for create_network : {'network': {'id': 'a76a7158-e06b-4c58-ab8e-778b21cf6c5e', 'name': 'ctest-mgmt_vn-78691678', 'tenant_id': '1e2c44838c8145d7a792f4006122385e', 'project_id': '1e2c44838c8145d7a792f4006122385e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38857456', 'ctest-mgmt_vn-78691678'], 'port_security_enabled': True, 'description': ''}} 2026-01-29 08:30:53,643 - DEBUG - Response for create_subnet : {'subnet': {'id': '1ce84ad2-5249-47cc-ba9d-ff345f5f19f3', 'name': '', 'tenant_id': '1e2c44838c8145d7a792f4006122385e', 'network_id': 'a76a7158-e06b-4c58-ab8e-778b21cf6c5e', 'ip_version': 4, 'cidr': '7.70.29.0/26', 'allocation_pools': [{'start': '7.70.29.2', 'end': '7.70.29.62'}], 'gateway_ip': '7.70.29.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '7.70.29.2', 'tags': [], 'project_id': '1e2c44838c8145d7a792f4006122385e'}} 2026-01-29 08:30:53,661 - INFO - Created VN ctest-mgmt_vn-78691678 2026-01-29 08:30:53,715 - DEBUG - VN ctest-mgmt_vn-78691678 UUID is a76a7158-e06b-4c58-ab8e-778b21cf6c5e 2026-01-29 08:30:53,823 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-29 08:30:54,123 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-29 08:30:54,154 - DEBUG - Requesting: http://10.0.0.141:8082/domain/c67e3248-40b7-4e37-895a-96967858f3e8 2026-01-29 08:30:54,167 - DEBUG - Requesting: http://10.0.0.141:8082/project/1e2c4483-8c81-45d7-a792-f4006122385e 2026-01-29 08:30:54,240 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/a76a7158-e06b-4c58-ab8e-778b21cf6c5e 2026-01-29 08:30:54,250 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/a76a7158-e06b-4c58-ab8e-778b21cf6c5e 2026-01-29 08:30:54,258 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/07134f8b-aab9-4e65-8d89-9d43e091ba2e 2026-01-29 08:30:54,264 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/07134f8b-aab9-4e65-8d89-9d43e091ba2e 2026-01-29 08:30:54,271 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/c53d9add-6146-4b0b-9ec8-57c9f7a6bf42 2026-01-29 08:30:54,277 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-01-29 08:30:54,277 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/a76a7158-e06b-4c58-ab8e-778b21cf6c5e 2026-01-29 08:30:54,286 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/07134f8b-aab9-4e65-8d89-9d43e091ba2e 2026-01-29 08:30:54,301 - INFO - Verified VN network id 11 for VN a76a7158-e06b-4c58-ab8e-778b21cf6c5e 2026-01-29 08:30:54,301 - INFO - Verifications in API Server for VN ctest-mgmt_vn-78691678 passed 2026-01-29 08:30:54,301 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/a76a7158-e06b-4c58-ab8e-778b21cf6c5e 2026-01-29 08:30:54,310 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/07134f8b-aab9-4e65-8d89-9d43e091ba2e 2026-01-29 08:30:54,318 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/07134f8b-aab9-4e65-8d89-9d43e091ba2e 2026-01-29 08:30:54,325 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/c53d9add-6146-4b0b-9ec8-57c9f7a6bf42 2026-01-29 08:30:54,338 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38857456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12063579178401483864', 'uuid-lslong': '12361949466629991518'}, 'enable': 'true', 'created': '2026-01-29T08:30:53', 'last-modified': '2026-01-29T08:30:53', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.721864'} 2026-01-29 08:30:54,343 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-01-29 08:30:54,343 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-78691678 passed 2026-01-29 08:30:54,344 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-78691678 in API_Server ====== 2026-01-29 08:30:54,344 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-29 08:30:54,349 - DEBUG - Requesting: http://10.0.0.141:8082/domain/c67e3248-40b7-4e37-895a-96967858f3e8 2026-01-29 08:30:54,359 - DEBUG - Requesting: http://10.0.0.141:8082/project/1e2c4483-8c81-45d7-a792-f4006122385e 2026-01-29 08:30:54,430 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/a76a7158-e06b-4c58-ab8e-778b21cf6c5e 2026-01-29 08:30:54,440 - DEBUG - =>VN ctest-mgmt_vn-78691678 has no policy to be verified 2026-01-29 08:30:54,440 - DEBUG - Verifying the vn in opserver 2026-01-29 08:30:54,440 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678 virtual network link through opserver 10.0.0.141 2026-01-29 08:30:54,441 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks 2026-01-29 08:30:54,735 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks 2026-01-29 08:30:54,802 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678?flat'} 2026-01-29 08:30:54,802 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678 is found in opserver 2026-01-29 08:30:54,813 - DEBUG - Do not have enough data to verify VN in agent 2026-01-29 08:30:54,819 - DEBUG - VRF ids for VN ctest-mgmt_vn-78691678: {} 2026-01-29 08:30:55,001 - DEBUG - Response for create_network : {'network': {'id': '6a8630c7-5813-4565-909d-9c9d3a82bf42', 'name': 'ctest-left_vn-60669171', 'tenant_id': '1e2c44838c8145d7a792f4006122385e', 'project_id': '1e2c44838c8145d7a792f4006122385e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38857456', 'ctest-left_vn-60669171'], 'port_security_enabled': True, 'description': ''}} 2026-01-29 08:30:55,185 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c5beb941-24a6-4025-8709-891e9d4c63c7', 'name': '', 'tenant_id': '1e2c44838c8145d7a792f4006122385e', 'network_id': '6a8630c7-5813-4565-909d-9c9d3a82bf42', 'ip_version': 4, 'cidr': '91.149.210.192/26', 'allocation_pools': [{'start': '91.149.210.194', 'end': '91.149.210.254'}], 'gateway_ip': '91.149.210.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '91.149.210.194', 'tags': [], 'project_id': '1e2c44838c8145d7a792f4006122385e'}} 2026-01-29 08:30:55,203 - INFO - Created VN ctest-left_vn-60669171 2026-01-29 08:30:55,255 - DEBUG - VN ctest-left_vn-60669171 UUID is 6a8630c7-5813-4565-909d-9c9d3a82bf42 2026-01-29 08:30:55,367 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-29 08:30:55,373 - DEBUG - Requesting: http://10.0.0.141:8082/domain/c67e3248-40b7-4e37-895a-96967858f3e8 2026-01-29 08:30:55,384 - DEBUG - Requesting: http://10.0.0.141:8082/project/1e2c4483-8c81-45d7-a792-f4006122385e 2026-01-29 08:30:55,451 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/6a8630c7-5813-4565-909d-9c9d3a82bf42 2026-01-29 08:30:55,462 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/6a8630c7-5813-4565-909d-9c9d3a82bf42 2026-01-29 08:30:55,474 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/16dbd170-e862-4460-ab58-49632843fc15 2026-01-29 08:30:55,481 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/16dbd170-e862-4460-ab58-49632843fc15 2026-01-29 08:30:55,488 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/0fec1901-7e20-403e-944a-fe6339c71624 2026-01-29 08:30:55,495 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-01-29 08:30:55,495 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/6a8630c7-5813-4565-909d-9c9d3a82bf42 2026-01-29 08:30:55,504 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/16dbd170-e862-4460-ab58-49632843fc15 2026-01-29 08:30:55,519 - INFO - Verified VN network id 12 for VN 6a8630c7-5813-4565-909d-9c9d3a82bf42 2026-01-29 08:30:55,520 - INFO - Verifications in API Server for VN ctest-left_vn-60669171 passed 2026-01-29 08:30:55,520 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/6a8630c7-5813-4565-909d-9c9d3a82bf42 2026-01-29 08:30:55,530 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/16dbd170-e862-4460-ab58-49632843fc15 2026-01-29 08:30:55,538 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/16dbd170-e862-4460-ab58-49632843fc15 2026-01-29 08:30:55,544 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/0fec1901-7e20-403e-944a-fe6339c71624 2026-01-29 08:30:55,557 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38857456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '7675876247633872229', 'uuid-lslong': '10420657311910641474'}, 'enable': 'true', 'created': '2026-01-29T08:30:54', 'last-modified': '2026-01-29T08:30:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.403925'} 2026-01-29 08:30:55,563 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-01-29 08:30:55,563 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-60669171 passed 2026-01-29 08:30:55,564 - DEBUG - ====Verifying policy data for ctest-left_vn-60669171 in API_Server ====== 2026-01-29 08:30:55,564 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-29 08:30:55,570 - DEBUG - Requesting: http://10.0.0.141:8082/domain/c67e3248-40b7-4e37-895a-96967858f3e8 2026-01-29 08:30:55,583 - DEBUG - Requesting: http://10.0.0.141:8082/project/1e2c4483-8c81-45d7-a792-f4006122385e 2026-01-29 08:30:55,651 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/6a8630c7-5813-4565-909d-9c9d3a82bf42 2026-01-29 08:30:55,660 - DEBUG - =>VN ctest-left_vn-60669171 has no policy to be verified 2026-01-29 08:30:55,660 - DEBUG - Verifying the vn in opserver 2026-01-29 08:30:55,660 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171 virtual network link through opserver 10.0.0.141 2026-01-29 08:30:55,660 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks 2026-01-29 08:30:55,670 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171?flat'} 2026-01-29 08:30:55,670 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171 is found in opserver 2026-01-29 08:30:55,680 - DEBUG - Do not have enough data to verify VN in agent 2026-01-29 08:30:55,685 - DEBUG - VRF ids for VN ctest-left_vn-60669171: {} 2026-01-29 08:30:55,873 - DEBUG - Response for create_network : {'network': {'id': '85964dca-c921-4d8e-bad1-2742fe98dae1', 'name': 'ctest-right_vn-60729012', 'tenant_id': '1e2c44838c8145d7a792f4006122385e', 'project_id': '1e2c44838c8145d7a792f4006122385e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-38857456', 'ctest-right_vn-60729012'], 'port_security_enabled': True, 'description': ''}} 2026-01-29 08:30:56,022 - DEBUG - Response for create_subnet : {'subnet': {'id': 'adad3fc6-1103-4d4f-9ec5-e1e79e6e6399', 'name': '', 'tenant_id': '1e2c44838c8145d7a792f4006122385e', 'network_id': '85964dca-c921-4d8e-bad1-2742fe98dae1', 'ip_version': 4, 'cidr': '113.206.209.128/26', 'allocation_pools': [{'start': '113.206.209.130', 'end': '113.206.209.190'}], 'gateway_ip': '113.206.209.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '113.206.209.130', 'tags': [], 'project_id': '1e2c44838c8145d7a792f4006122385e'}} 2026-01-29 08:30:56,040 - INFO - Created VN ctest-right_vn-60729012 2026-01-29 08:30:56,095 - DEBUG - VN ctest-right_vn-60729012 UUID is 85964dca-c921-4d8e-bad1-2742fe98dae1 2026-01-29 08:30:56,202 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-29 08:30:56,208 - DEBUG - Requesting: http://10.0.0.141:8082/domain/c67e3248-40b7-4e37-895a-96967858f3e8 2026-01-29 08:30:56,221 - DEBUG - Requesting: http://10.0.0.141:8082/project/1e2c4483-8c81-45d7-a792-f4006122385e 2026-01-29 08:30:56,293 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/85964dca-c921-4d8e-bad1-2742fe98dae1 2026-01-29 08:30:56,302 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/85964dca-c921-4d8e-bad1-2742fe98dae1 2026-01-29 08:30:56,313 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/be625b90-414a-440f-9ff0-b410c0d48533 2026-01-29 08:30:56,320 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/be625b90-414a-440f-9ff0-b410c0d48533 2026-01-29 08:30:56,328 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/8afca96e-d855-4cfc-86cc-b93b1d4b93d6 2026-01-29 08:30:56,336 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-01-29 08:30:56,336 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/85964dca-c921-4d8e-bad1-2742fe98dae1 2026-01-29 08:30:56,345 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/be625b90-414a-440f-9ff0-b410c0d48533 2026-01-29 08:30:56,360 - INFO - Verified VN network id 13 for VN 85964dca-c921-4d8e-bad1-2742fe98dae1 2026-01-29 08:30:56,360 - INFO - Verifications in API Server for VN ctest-right_vn-60729012 passed 2026-01-29 08:30:56,361 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/85964dca-c921-4d8e-bad1-2742fe98dae1 2026-01-29 08:30:56,371 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/be625b90-414a-440f-9ff0-b410c0d48533 2026-01-29 08:30:56,377 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/be625b90-414a-440f-9ff0-b410c0d48533 2026-01-29 08:30:56,384 - DEBUG - Requesting: http://10.0.0.141:8082/route-target/8afca96e-d855-4cfc-86cc-b93b1d4b93d6 2026-01-29 08:30:56,397 - DEBUG - Control-node 10.0.0.141 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-38857456:ctest-right_vn-60729012', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-38857456', 'owner-access': '7', 'group': 'reader', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '9625966786904149390', 'uuid-lslong': '13461583929879878369'}, 'enable': 'true', 'created': '2026-01-29T08:30:55', 'last-modified': '2026-01-29T08:30:55', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.400672'} 2026-01-29 08:30:56,404 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-01-29 08:30:56,404 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-60729012 passed 2026-01-29 08:30:56,405 - DEBUG - ====Verifying policy data for ctest-right_vn-60729012 in API_Server ====== 2026-01-29 08:30:56,405 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-29 08:30:56,411 - DEBUG - Requesting: http://10.0.0.141:8082/domain/c67e3248-40b7-4e37-895a-96967858f3e8 2026-01-29 08:30:56,422 - DEBUG - Requesting: http://10.0.0.141:8082/project/1e2c4483-8c81-45d7-a792-f4006122385e 2026-01-29 08:30:56,497 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-network/85964dca-c921-4d8e-bad1-2742fe98dae1 2026-01-29 08:30:56,508 - DEBUG - =>VN ctest-right_vn-60729012 has no policy to be verified 2026-01-29 08:30:56,508 - DEBUG - Verifying the vn in opserver 2026-01-29 08:30:56,508 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-38857456:ctest-right_vn-60729012 virtual network link through opserver 10.0.0.141 2026-01-29 08:30:56,508 - DEBUG - Requesting: http://10.0.0.141:8081/analytics/uves/virtual-networks 2026-01-29 08:30:56,519 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-right_vn-60729012', 'href': 'http://10.0.0.141:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-38857456:ctest-right_vn-60729012?flat'} 2026-01-29 08:30:56,519 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-38857456:ctest-right_vn-60729012 is found in opserver 2026-01-29 08:30:56,530 - DEBUG - Do not have enough data to verify VN in agent 2026-01-29 08:30:56,535 - DEBUG - VRF ids for VN ctest-right_vn-60729012: {} 2026-01-29 08:30:56,604 - DEBUG - Services list from nova: [, , ] 2026-01-29 08:30:57,664 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5121-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5121-1) 2026-01-29 08:30:58,882 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5121-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5121-1) 2026-01-29 08:30:58,882 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-70255352'] 2026-01-29 08:30:59,243 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-70255352'] created succesfully 2026-01-29 08:30:59,653 - DEBUG - Image by name cirros_in_net not found 2026-01-29 08:30:59,653 - DEBUG - Installing image cirros_in_net 2026-01-29 08:30:59,653 - 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-29 08:30:59,654 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2026-01-29 08:30:59,654 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2026-01-29 08:30:59,669 - 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-29 08:30:59,798 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz 2026-01-29 08:30:59,799 - DEBUG - Unzip image 2026-01-29 08:30:59,799 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz 2026-01-29 08:30:59,952 - DEBUG - Try to create image in user context 2026-01-29 08:31:00,203 - DEBUG - Image has been created in user context 2026-01-29 08:31:00,203 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk 2026-01-29 08:31:00,227 - DEBUG - Downloaded image has been removed 2026-01-29 08:31:00,243 - DEBUG - Uploaded image status = active 2026-01-29 08:31:01,476 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5121-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5121-1) 2026-01-29 08:31:01,477 - INFO - Waiting for VM ctest-pt_svm0-89162875 to be up.. 2026-01-29 08:31:01,531 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-29 08:31:06,632 - DEBUG - VM is in ACTIVE state now 2026-01-29 08:31:06,632 - INFO - VM name : ctest-pt_svm0-89162875 2026-01-29 08:31:06,731 - DEBUG - VM ctest-pt_svm0-89162875 ID is 4e11c375-c58b-4473-9699-3fb7cee5ac8f 2026-01-29 08:31:06,755 - DEBUG - VM ctest-pt_svm0-89162875 launched on Node cn-jenkins-deploy-platform-ansible-os-5121-1 2026-01-29 08:31:07,069 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/4e11c375-c58b-4473-9699-3fb7cee5ac8f 2026-01-29 08:31:07,080 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/78e619b6-3476-41fe-8d68-cb27e743c0f0 2026-01-29 08:31:07,093 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/e2bc1308-d2a5-4a75-9ef6-0033aa173741 2026-01-29 08:31:07,107 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/fe06813e-14dc-4f1e-8249-5d3275c04414 2026-01-29 08:31:10,413 - 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-29 08:31:10,413 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89162875 failed! 2026-01-29 08:31:10,467 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678 is 7.70.29.1 and allocation pool is NOT set 2026-01-29 08:31:14,545 - 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') 2026-01-29 08:31:14,545 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89162875 failed! 2026-01-29 08:31:14,599 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678 is 7.70.29.1 and allocation pool is NOT set 2026-01-29 08:31:18,664 - 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 1010ms') 2026-01-29 08:31:18,664 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89162875 failed! 2026-01-29 08:31:18,719 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678 is 7.70.29.1 and allocation pool is NOT set 2026-01-29 08:31:22,803 - 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 1022ms') 2026-01-29 08:31:22,803 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89162875 failed! 2026-01-29 08:31:22,855 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678 is 7.70.29.1 and allocation pool is NOT set 2026-01-29 08:31:24,913 - 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.726 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.726/2.488/4.251/1.762 ms') 2026-01-29 08:31:24,913 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-89162875 passed 2026-01-29 08:31:25,067 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:25,067 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-89162875, IP 7.70.29.3, Port 22 2026-01-29 08:31:25,133 - 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-29 08:31:25,218 - DEBUG - VM ctest-pt_svm0-89162875 is NOT ready for SSH connections, VM status: ACTIVE 2026-01-29 08:31:30,219 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:30,219 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-89162875, IP 7.70.29.3, Port 22 2026-01-29 08:31:30,274 - 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-29 08:31:30,361 - DEBUG - VM ctest-pt_svm0-89162875 is NOT ready for SSH connections, VM status: ACTIVE 2026-01-29 08:31:35,362 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:35,363 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-89162875, IP 7.70.29.3, Port 22 2026-01-29 08:31:35,419 - 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-29 08:31:35,502 - DEBUG - VM ctest-pt_svm0-89162875 is NOT ready for SSH connections, VM status: ACTIVE 2026-01-29 08:31:40,502 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:40,503 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-89162875, IP 7.70.29.3, Port 22 2026-01-29 08:31:40,569 - 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-29 08:31:40,650 - DEBUG - VM ctest-pt_svm0-89162875 is NOT ready for SSH connections, VM status: ACTIVE 2026-01-29 08:31:45,651 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:45,651 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-89162875, IP 7.70.29.3, Port 22 2026-01-29 08:31:45,807 - DEBUG - VM ctest-pt_svm0-89162875 is ready for SSH connections 2026-01-29 08:31:45,959 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:45,959 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-89162875, IP 7.70.29.3, Port 22 2026-01-29 08:31:46,111 - DEBUG - VM ctest-pt_svm0-89162875 is ready for SSH connections 2026-01-29 08:31:46,263 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:46,263 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-89162875, IP 7.70.29.3, Port 22 2026-01-29 08:31:46,415 - DEBUG - VM ctest-pt_svm0-89162875 is ready for SSH connections 2026-01-29 08:31:46,421 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSvcRegr-38857456', 'ctest-si-74565478'] 2026-01-29 08:31:47,307 - INFO - Waiting for VM ctest-left_vm-56031501 to be up.. 2026-01-29 08:31:47,382 - DEBUG - VM is in ACTIVE state now 2026-01-29 08:31:47,382 - INFO - VM name : ctest-left_vm-56031501 2026-01-29 08:31:47,457 - DEBUG - VM ctest-left_vm-56031501 ID is 7cf6a2ec-52cb-4fa1-b79c-becf516d4160 2026-01-29 08:31:47,457 - DEBUG - VM ctest-left_vm-56031501 launched on Node cn-jenkins-deploy-platform-ansible-os-5121-1 2026-01-29 08:31:47,532 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/7cf6a2ec-52cb-4fa1-b79c-becf516d4160 2026-01-29 08:31:47,540 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/524dcf15-d3d8-41ef-a22b-4303ce7cf42b 2026-01-29 08:31:48,783 - 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.04 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.380 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.380/1.709/3.039/1.329 ms') 2026-01-29 08:31:48,783 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-56031501 passed 2026-01-29 08:31:48,931 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:48,931 - DEBUG - Waiting to SSH to VM ctest-left_vm-56031501, IP 91.149.210.195, Port 22 2026-01-29 08:31:49,087 - DEBUG - VM ctest-left_vm-56031501 is ready for SSH connections 2026-01-29 08:31:49,087 - INFO - Waiting for VM ctest-right_vm-11873012 to be up.. 2026-01-29 08:31:49,193 - DEBUG - VM is in ACTIVE state now 2026-01-29 08:31:49,193 - INFO - VM name : ctest-right_vm-11873012 2026-01-29 08:31:49,268 - DEBUG - VM ctest-right_vm-11873012 ID is 7dd72624-8ef2-49e5-a459-4312b2784f38 2026-01-29 08:31:49,269 - DEBUG - VM ctest-right_vm-11873012 launched on Node cn-jenkins-deploy-platform-ansible-os-5121-1 2026-01-29 08:31:49,345 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/7dd72624-8ef2-49e5-a459-4312b2784f38 2026-01-29 08:31:49,353 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/92ba7600-c125-460c-b6e3-df50bb233467 2026-01-29 08:31:50,592 - 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.69 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.416 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.416/1.550/2.685/1.134 ms') 2026-01-29 08:31:50,592 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-11873012 passed 2026-01-29 08:31:50,743 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:50,743 - DEBUG - Waiting to SSH to VM ctest-right_vm-11873012, IP 113.206.209.131, Port 22 2026-01-29 08:31:50,899 - DEBUG - VM ctest-right_vm-11873012 is ready for SSH connections 2026-01-29 08:31:50,904 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171, 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-38857456:ctest-right_vn-60729012, 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-38857456:ctest-si-74565478'], 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-29 08:31:51,315 - INFO - Attaching policy ctest-policy-91464136 to vn ctest-left_vn-60669171 2026-01-29 08:31:51,839 - INFO - Attaching policy ctest-policy-91464136 to vn ctest-right_vn-60729012 2026-01-29 08:31:52,275 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-70255352'] created succesfully 2026-01-29 08:31:52,487 - DEBUG - Service instance: ['default-domain', 'ctest-TestSvcRegr-38857456', 'ctest-si-74565478'] created succesfully 2026-01-29 08:31:52,487 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-29 08:31:52,494 - DEBUG - Requesting: http://10.0.0.141:8082/domain/c67e3248-40b7-4e37-895a-96967858f3e8 2026-01-29 08:31:52,508 - DEBUG - Requesting: http://10.0.0.141:8082/project/1e2c4483-8c81-45d7-a792-f4006122385e 2026-01-29 08:31:52,579 - DEBUG - Requesting: http://10.0.0.141:8082/service-instance/3ffecad4-5d2b-4e72-bcbf-52af3b61823a 2026-01-29 08:31:52,590 - DEBUG - SI 'ctest-si-74565478' has service template ref to ctest-service_template_1-70255352 2026-01-29 08:31:52,590 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-29 08:31:52,595 - DEBUG - Requesting: http://10.0.0.141:8082/domain/c67e3248-40b7-4e37-895a-96967858f3e8 2026-01-29 08:31:52,604 - DEBUG - Requesting: http://10.0.0.141:8082/project/1e2c4483-8c81-45d7-a792-f4006122385e 2026-01-29 08:31:52,671 - DEBUG - Requesting: http://10.0.0.141:8082/service-instance/3ffecad4-5d2b-4e72-bcbf-52af3b61823a 2026-01-29 08:31:52,679 - DEBUG - Requesting: http://10.0.0.141:8082/port-tuple/c6345ad3-f801-4cd8-953f-68c009a3d6ac 2026-01-29 08:31:52,685 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/78e619b6-3476-41fe-8d68-cb27e743c0f0 2026-01-29 08:31:52,694 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/e2bc1308-d2a5-4a75-9ef6-0033aa173741 2026-01-29 08:31:52,706 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/fe06813e-14dc-4f1e-8249-5d3275c04414 2026-01-29 08:31:52,922 - DEBUG - VM is in ACTIVE state now 2026-01-29 08:31:53,327 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/4e11c375-c58b-4473-9699-3fb7cee5ac8f 2026-01-29 08:31:53,335 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/78e619b6-3476-41fe-8d68-cb27e743c0f0 2026-01-29 08:31:53,344 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/e2bc1308-d2a5-4a75-9ef6-0033aa173741 2026-01-29 08:31:53,355 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/fe06813e-14dc-4f1e-8249-5d3275c04414 2026-01-29 08:31:53,366 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/89e1e7f9-353e-4e1a-b16b-e8ec87a40e10 2026-01-29 08:31:53,376 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/c82fe171-0a66-4dd5-80c0-93642277efe3 2026-01-29 08:31:53,383 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/ff618c57-06b8-42ae-9a9a-e775764c9a0c 2026-01-29 08:31:53,390 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/1ccc2bfb-21b2-41a1-a27c-d6da04bfc488 2026-01-29 08:31:53,397 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/73c1d3b0-3cdc-4fdd-80f6-ebfadf46c0a5 2026-01-29 08:31:53,404 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/b56c41a5-4f6e-4ad5-bc59-8cf955f84cfe 2026-01-29 08:31:53,413 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/54d40cc1-9fbe-4fc3-85ee-ff595e3659fe 2026-01-29 08:31:53,421 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/9b9eca96-e7d8-4c0b-aa46-e353a432f64a 2026-01-29 08:31:53,429 - DEBUG - Requesting: http://10.0.0.141:8082/instance-ip/a9df69f2-b665-4865-a31e-ced3bf4cf42f 2026-01-29 08:31:53,821 - DEBUG - VM is in ACTIVE state now 2026-01-29 08:31:53,821 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/4e11c375-c58b-4473-9699-3fb7cee5ac8f 2026-01-29 08:31:53,828 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/78e619b6-3476-41fe-8d68-cb27e743c0f0 2026-01-29 08:31:53,837 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/e2bc1308-d2a5-4a75-9ef6-0033aa173741 2026-01-29 08:31:53,847 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/fe06813e-14dc-4f1e-8249-5d3275c04414 2026-01-29 08:31:55,086 - 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.05 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.632 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.632/0.839/1.047/0.207 ms') 2026-01-29 08:31:55,086 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-89162875 passed 2026-01-29 08:31:55,125 - DEBUG - VM ctest-pt_svm0-89162875 Tap interface: {'index': '6', 'name': 'tape2bc1308-d2', 'uuid': 'e2bc1308-d2a5-4a75-9ef6-0033aa173741', 'vrf_name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171:ctest-left_vn-60669171', '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': '12', 'vn_name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171', 'vm_uuid': '4e11c375-c58b-4473-9699-3fb7cee5ac8f', 'vm_name': 'ctest-pt_svm0-89162875', 'ip_addr': '91.149.210.196', 'mac_addr': '02:e2:bc:13:08:d2', '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-38857456:e2bc1308-d2a5-4a75-9ef6-0033aa173741', 'sg_uuid_list': ['c86b0a62-85c1-46b7-a9e3-21e4a886538e'], 'static_route_list': None, 'vm_project_uuid': '1e2c4483-8c81-45d7-a792-f4006122385e', 'admin_state': 'Enabled', 'flow_key_idx': '50', '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': '78e619b6-3476-41fe-8d68-cb27e743c0f0', '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-29 08:31:55,125 - DEBUG - Agent 10.0.0.141 vrf name: default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171:ctest-left_vn-60669171 2026-01-29 08:31:55,131 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171:ctest-left_vn-60669171', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171', 'table_label': '-1', 'vxlan_id': '12', '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'}]} 2026-01-29 08:31:55,299 - DEBUG - VM ctest-pt_svm0-89162875 : Tap interface tape2bc1308-d2 is set to Active 2026-01-29 08:31:55,300 - DEBUG - Tap interface tape2bc1308-d2 detail : {'index': '6', 'name': 'tape2bc1308-d2', 'uuid': 'e2bc1308-d2a5-4a75-9ef6-0033aa173741', 'vrf_name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171:ctest-left_vn-60669171', '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': '12', 'vn_name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171', 'vm_uuid': '4e11c375-c58b-4473-9699-3fb7cee5ac8f', 'vm_name': 'ctest-pt_svm0-89162875', 'ip_addr': '91.149.210.196', 'mac_addr': '02:e2:bc:13:08:d2', '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-38857456:e2bc1308-d2a5-4a75-9ef6-0033aa173741', 'sg_uuid_list': ['c86b0a62-85c1-46b7-a9e3-21e4a886538e'], 'static_route_list': None, 'vm_project_uuid': '1e2c4483-8c81-45d7-a792-f4006122385e', 'admin_state': 'Enabled', 'flow_key_idx': '50', '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': '78e619b6-3476-41fe-8d68-cb27e743c0f0', '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-29 08:31:55,495 - DEBUG - Starting Layer 2 verification in Agent 2026-01-29 08:31:55,509 - DEBUG - Layer 2 path is seen for VM MAC 02:e2:bc:13:08:d2 in agent 10.0.0.141 2026-01-29 08:31:55,509 - DEBUG - Active layer 2 route in agent is present for VMI tape2bc1308-d2 2026-01-29 08:31:55,509 - DEBUG - L2 label(48) matches bw route table and interface table 2026-01-29 08:31:55,515 - DEBUG - VN default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171 is found in Agent of node 10.0.0.141 2026-01-29 08:31:55,722 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171 is consistent in agent 10.0.0.141 2026-01-29 08:31:55,723 - DEBUG - Route for VM IP ['91.149.210.196'] is consistent in agent 10.0.0.141 2026-01-29 08:31:55,723 - DEBUG - VN default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171 verification for VM ctest-pt_svm0-89162875 in Agent 10.0.0.141 passed 2026-01-29 08:31:55,915 - DEBUG - Starting all layer 2 verification in agent 10.0.0.141 2026-01-29 08:31:55,925 - DEBUG - Route for VM MAC 02:e2:bc:13:08:d2 is consistent in agent 10.0.0.141 2026-01-29 08:31:55,944 - DEBUG - VM ctest-pt_svm0-89162875 Tap interface: {'index': '5', 'name': 'tapfe06813e-14', 'uuid': 'fe06813e-14dc-4f1e-8249-5d3275c04414', 'vrf_name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678:ctest-mgmt_vn-78691678', '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': '11', 'vn_name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678', 'vm_uuid': '4e11c375-c58b-4473-9699-3fb7cee5ac8f', 'vm_name': 'ctest-pt_svm0-89162875', 'ip_addr': '7.70.29.3', 'mac_addr': '02:fe:06:81:3e:14', '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-38857456:fe06813e-14dc-4f1e-8249-5d3275c04414', 'sg_uuid_list': ['c86b0a62-85c1-46b7-a9e3-21e4a886538e'], 'static_route_list': None, 'vm_project_uuid': '1e2c4483-8c81-45d7-a792-f4006122385e', '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-29 08:31:55,944 - DEBUG - Agent 10.0.0.141 vrf name: default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678:ctest-mgmt_vn-78691678 2026-01-29 08:31:55,948 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678:ctest-mgmt_vn-78691678', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678', 'table_label': '-1', 'vxlan_id': '11', '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'}]} 2026-01-29 08:31:56,155 - DEBUG - VM ctest-pt_svm0-89162875 : Tap interface tapfe06813e-14 is set to Active 2026-01-29 08:31:56,155 - DEBUG - Tap interface tapfe06813e-14 detail : {'index': '5', 'name': 'tapfe06813e-14', 'uuid': 'fe06813e-14dc-4f1e-8249-5d3275c04414', 'vrf_name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678:ctest-mgmt_vn-78691678', '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': '11', 'vn_name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678', 'vm_uuid': '4e11c375-c58b-4473-9699-3fb7cee5ac8f', 'vm_name': 'ctest-pt_svm0-89162875', 'ip_addr': '7.70.29.3', 'mac_addr': '02:fe:06:81:3e:14', '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-38857456:fe06813e-14dc-4f1e-8249-5d3275c04414', 'sg_uuid_list': ['c86b0a62-85c1-46b7-a9e3-21e4a886538e'], 'static_route_list': None, 'vm_project_uuid': '1e2c4483-8c81-45d7-a792-f4006122385e', '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-29 08:31:56,351 - DEBUG - Starting Layer 2 verification in Agent 2026-01-29 08:31:56,361 - DEBUG - Layer 2 path is seen for VM MAC 02:fe:06:81:3e:14 in agent 10.0.0.141 2026-01-29 08:31:56,361 - DEBUG - Active layer 2 route in agent is present for VMI tapfe06813e-14 2026-01-29 08:31:56,361 - DEBUG - L2 label(43) matches bw route table and interface table 2026-01-29 08:31:56,367 - DEBUG - VN default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678 is found in Agent of node 10.0.0.141 2026-01-29 08:31:56,572 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678 is consistent in agent 10.0.0.141 2026-01-29 08:31:56,572 - DEBUG - Route for VM IP ['7.70.29.3'] is consistent in agent 10.0.0.141 2026-01-29 08:31:56,572 - DEBUG - VN default-domain:ctest-TestSvcRegr-38857456:ctest-mgmt_vn-78691678 verification for VM ctest-pt_svm0-89162875 in Agent 10.0.0.141 passed 2026-01-29 08:31:56,763 - DEBUG - Starting all layer 2 verification in agent 10.0.0.141 2026-01-29 08:31:56,773 - DEBUG - Route for VM MAC 02:fe:06:81:3e:14 is consistent in agent 10.0.0.141 2026-01-29 08:31:56,791 - DEBUG - VM ctest-pt_svm0-89162875 Tap interface: {'index': '7', 'name': 'tap78e619b6-34', 'uuid': '78e619b6-3476-41fe-8d68-cb27e743c0f0', 'vrf_name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-right_vn-60729012:ctest-right_vn-60729012', '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': '13', 'vn_name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-right_vn-60729012', 'vm_uuid': '4e11c375-c58b-4473-9699-3fb7cee5ac8f', 'vm_name': 'ctest-pt_svm0-89162875', 'ip_addr': '113.206.209.132', 'mac_addr': '02:78:e6:19:b6:34', '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-38857456:78e619b6-3476-41fe-8d68-cb27e743c0f0', 'sg_uuid_list': ['c86b0a62-85c1-46b7-a9e3-21e4a886538e'], 'static_route_list': None, 'vm_project_uuid': '1e2c4483-8c81-45d7-a792-f4006122385e', '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': 'e2bc1308-d2a5-4a75-9ef6-0033aa173741', '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-29 08:31:56,791 - DEBUG - Agent 10.0.0.141 vrf name: default-domain:ctest-TestSvcRegr-38857456:ctest-right_vn-60729012:ctest-right_vn-60729012 2026-01-29 08:31:56,796 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-right_vn-60729012:ctest-right_vn-60729012', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSvcRegr-38857456:ctest-right_vn-60729012', 'table_label': '-1', 'vxlan_id': '13', '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'}]} 2026-01-29 08:31:56,999 - DEBUG - VM ctest-pt_svm0-89162875 : Tap interface tap78e619b6-34 is set to Active 2026-01-29 08:31:56,999 - DEBUG - Tap interface tap78e619b6-34 detail : {'index': '7', 'name': 'tap78e619b6-34', 'uuid': '78e619b6-3476-41fe-8d68-cb27e743c0f0', 'vrf_name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-right_vn-60729012:ctest-right_vn-60729012', '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': '13', 'vn_name': 'default-domain:ctest-TestSvcRegr-38857456:ctest-right_vn-60729012', 'vm_uuid': '4e11c375-c58b-4473-9699-3fb7cee5ac8f', 'vm_name': 'ctest-pt_svm0-89162875', 'ip_addr': '113.206.209.132', 'mac_addr': '02:78:e6:19:b6:34', '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-38857456:78e619b6-3476-41fe-8d68-cb27e743c0f0', 'sg_uuid_list': ['c86b0a62-85c1-46b7-a9e3-21e4a886538e'], 'static_route_list': None, 'vm_project_uuid': '1e2c4483-8c81-45d7-a792-f4006122385e', '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': 'e2bc1308-d2a5-4a75-9ef6-0033aa173741', '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-29 08:31:57,195 - DEBUG - Starting Layer 2 verification in Agent 2026-01-29 08:31:57,208 - DEBUG - Layer 2 path is seen for VM MAC 02:78:e6:19:b6:34 in agent 10.0.0.141 2026-01-29 08:31:57,208 - DEBUG - Active layer 2 route in agent is present for VMI tap78e619b6-34 2026-01-29 08:31:57,208 - DEBUG - L2 label(53) matches bw route table and interface table 2026-01-29 08:31:57,214 - DEBUG - VN default-domain:ctest-TestSvcRegr-38857456:ctest-right_vn-60729012 is found in Agent of node 10.0.0.141 2026-01-29 08:31:57,428 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-38857456:ctest-right_vn-60729012 is consistent in agent 10.0.0.141 2026-01-29 08:31:57,428 - DEBUG - Route for VM IP ['113.206.209.132'] is consistent in agent 10.0.0.141 2026-01-29 08:31:57,428 - DEBUG - VN default-domain:ctest-TestSvcRegr-38857456:ctest-right_vn-60729012 verification for VM ctest-pt_svm0-89162875 in Agent 10.0.0.141 passed 2026-01-29 08:31:57,619 - DEBUG - Starting all layer 2 verification in agent 10.0.0.141 2026-01-29 08:31:57,632 - DEBUG - Route for VM MAC 02:78:e6:19:b6:34 is consistent in agent 10.0.0.141 2026-01-29 08:31:57,632 - INFO - VM ctest-pt_svm0-89162875 verifications in Compute nodes passed 2026-01-29 08:31:57,633 - DEBUG - The SVMs in the SI are : [] 2026-01-29 08:31:57,633 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/4e11c375-c58b-4473-9699-3fb7cee5ac8f 2026-01-29 08:31:57,640 - DEBUG - Service VM for SI 'ctest-si-74565478' is launched 2026-01-29 08:31:57,640 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-29 08:31:57,645 - DEBUG - Requesting: http://10.0.0.141:8082/domain/c67e3248-40b7-4e37-895a-96967858f3e8 2026-01-29 08:31:57,656 - DEBUG - Requesting: http://10.0.0.141:8082/project/1e2c4483-8c81-45d7-a792-f4006122385e 2026-01-29 08:31:57,726 - DEBUG - Requesting: http://10.0.0.141:8082/service-instance/3ffecad4-5d2b-4e72-bcbf-52af3b61823a 2026-01-29 08:31:57,735 - DEBUG - SI ctest-si-74565478 has Port Tuple: ['ctest-port_tuple-77897123'] 2026-01-29 08:31:57,835 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-38857456:ctest-left_vn-60669171 2026-01-29 08:31:57,979 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-38857456:ctest-right_vn-60729012 2026-01-29 08:31:58,133 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:58,140 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:58,141 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:58,146 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:58,146 - INFO - Route to 113.206.209.131 seen in VRF:5 on cn-jenkins-deploy-platform-ansible-os-5121-1, and SI ctest-si-74565478 is seen as the NH 2026-01-29 08:31:58,301 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:58,308 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:58,308 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:58,313 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:58,313 - INFO - Route to 91.149.210.195 seen in VRF:6 on cn-jenkins-deploy-platform-ansible-os-5121-1, and SI ctest-si-74565478 is seen as the NH 2026-01-29 08:31:58,313 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:58,313 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 08:31:58,313 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 113.206.209.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2026-01-29 08:31:58,313 - DEBUG - ping -s 56 -c 3 -W 1 113.206.209.131 2026-01-29 08:32:02,451 - DEBUG - PING 113.206.209.131 (113.206.209.131): 56 data bytes 64 bytes from 113.206.209.131: seq=0 ttl=61 time=7.839 ms 64 bytes from 113.206.209.131: seq=1 ttl=61 time=1.426 ms 64 bytes from 113.206.209.131: seq=2 ttl=61 time=1.841 ms --- 113.206.209.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.426/3.702/7.839 ms 2026-01-29 08:32:02,451 - INFO - Ping to IP 113.206.209.131 from VM ctest-left_vm-56031501 passed 2026-01-29 08:32:02,452 - INFO - Dettaching policy ctest-policy-91464136 from vn ctest-right_vn-60729012 2026-01-29 08:32:02,663 - INFO - Dettaching policy ctest-policy-91464136 from vn ctest-left_vn-60669171 2026-01-29 08:32:02,940 - INFO - Deleted policy ctest-policy-91464136 2026-01-29 08:32:02,940 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSvcRegr-38857456', 'ctest-si-74565478'] 2026-01-29 08:32:03,032 - INFO - Deleted SI ['default-domain', 'ctest-TestSvcRegr-38857456', 'ctest-si-74565478'] 2026-01-29 08:32:03,032 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-29 08:32:03,037 - DEBUG - Requesting: http://10.0.0.141:8082/domain/c67e3248-40b7-4e37-895a-96967858f3e8 2026-01-29 08:32:03,054 - DEBUG - Requesting: http://10.0.0.141:8082/project/1e2c4483-8c81-45d7-a792-f4006122385e 2026-01-29 08:32:03,134 - DEBUG - Service instance ctest-si-74565478 removed from api server 2026-01-29 08:32:03,134 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/4e11c375-c58b-4473-9699-3fb7cee5ac8f 2026-01-29 08:32:03,146 - DEBUG - All Service VMs unlinked from SI ctest-si-74565478 2026-01-29 08:32:03,151 - DEBUG - 0 svc intances found in all projects. They are [] 2026-01-29 08:32:03,151 - DEBUG - 0 svc intances found in current project. They are [] 2026-01-29 08:32:03,199 - DEBUG - 0 svc intances found in all projects. They are [] 2026-01-29 08:32:03,199 - DEBUG - 0 svc intances found in current project. They are [] 2026-01-29 08:32:03,199 - INFO - Deleting VM ctest-pt_svm0-89162875 2026-01-29 08:32:03,275 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-70255352'] 2026-01-29 08:32:03,383 - INFO - Service template: ['default-domain', 'ctest-service_template_1-70255352'] deleted successfully. 2026-01-29 08:32:03,383 - INFO - Deleting VM ctest-right_vm-11873012 2026-01-29 08:32:03,480 - INFO - Deleting VM ctest-left_vm-56031501 2026-01-29 08:32:03,575 - INFO - Deleting VN ctest-right_vn-60729012 2026-01-29 08:32:03,633 - DEBUG - VN 85964dca-c921-4d8e-bad1-2742fe98dae1 still in use: Unable to complete operation on network 85964dca-c921-4d8e-bad1-2742fe98dae1. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-400f8df5-22c7-44ec-9fad-270979610dfb'] 2026-01-29 08:32:03,633 - WARNING - Deleting VN ctest-right_vn-60729012 failed..Will retry 2026-01-29 08:32:05,728 - DEBUG - Response for deleting network () 2026-01-29 08:32:05,728 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/be625b90-414a-440f-9ff0-b410c0d48533 2026-01-29 08:32:05,737 - DEBUG - Response Code: 404 2026-01-29 08:32:05,737 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-29 08:32:05,743 - DEBUG - Requesting: http://10.0.0.141:8082/domain/c67e3248-40b7-4e37-895a-96967858f3e8 2026-01-29 08:32:05,798 - DEBUG - Requesting: http://10.0.0.141:8082/project/1e2c4483-8c81-45d7-a792-f4006122385e 2026-01-29 08:32:05,872 - INFO - Validated that VN ctest-right_vn-60729012 is not found in API Server 2026-01-29 08:32:05,881 - DEBUG - VN ctest-right_vn-60729012 is not present in Agent 10.0.0.141 2026-01-29 08:32:05,881 - INFO - Validated that VN ctest-right_vn-60729012 is not in any agent 2026-01-29 08:32:05,895 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-60729012 info 2026-01-29 08:32:05,895 - INFO - Deleting VN ctest-left_vn-60669171 2026-01-29 08:32:05,993 - DEBUG - Response for deleting network () 2026-01-29 08:32:05,993 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/16dbd170-e862-4460-ab58-49632843fc15 2026-01-29 08:32:06,000 - DEBUG - Response Code: 404 2026-01-29 08:32:06,000 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-29 08:32:06,006 - DEBUG - Requesting: http://10.0.0.141:8082/domain/c67e3248-40b7-4e37-895a-96967858f3e8 2026-01-29 08:32:06,060 - DEBUG - Requesting: http://10.0.0.141:8082/project/1e2c4483-8c81-45d7-a792-f4006122385e 2026-01-29 08:32:06,132 - INFO - Validated that VN ctest-left_vn-60669171 is not found in API Server 2026-01-29 08:32:06,143 - DEBUG - VN ctest-left_vn-60669171 is not present in Agent 10.0.0.141 2026-01-29 08:32:06,144 - INFO - Validated that VN ctest-left_vn-60669171 is not in any agent 2026-01-29 08:32:06,159 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-60669171 info 2026-01-29 08:32:06,159 - INFO - Deleting VN ctest-mgmt_vn-78691678 2026-01-29 08:32:06,278 - DEBUG - Response for deleting network () 2026-01-29 08:32:06,278 - DEBUG - Requesting: http://10.0.0.141:8082/routing-instance/07134f8b-aab9-4e65-8d89-9d43e091ba2e 2026-01-29 08:32:06,289 - DEBUG - Response Code: 404 2026-01-29 08:32:06,289 - DEBUG - Requesting: http://10.0.0.141:8082/domains 2026-01-29 08:32:06,293 - DEBUG - Requesting: http://10.0.0.141:8082/domain/c67e3248-40b7-4e37-895a-96967858f3e8 2026-01-29 08:32:06,344 - DEBUG - Requesting: http://10.0.0.141:8082/project/1e2c4483-8c81-45d7-a792-f4006122385e 2026-01-29 08:32:06,420 - INFO - Validated that VN ctest-mgmt_vn-78691678 is not found in API Server 2026-01-29 08:32:06,430 - DEBUG - VN ctest-mgmt_vn-78691678 is not present in Agent 10.0.0.141 2026-01-29 08:32:06,430 - INFO - Validated that VN ctest-mgmt_vn-78691678 is not in any agent 2026-01-29 08:32:06,444 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-78691678 info 2026-01-29 08:32:06,696 - DEBUG - No XMPP flaps were noticed during the test 2026-01-29 08:32:06,696 - INFO -
2026-01-29 08:32:06,696 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:14]
2026-01-29 08:32:06,696 - INFO - -------------------------------------------------------------------------------- 2026-01-29 08:32:07,407 - INFO - Deleted project: ctest-TestSvcRegr-38857456, ID : 1e2c4483-8c81-45d7-a792-f4006122385e