2026-02-11 13:11:55,177 - INFO - Domain Default found not creating
2026-02-11 13:11:55,363 - INFO - Project ctest-TestSvcRegr-46099884 not found, creating it
2026-02-11 13:11:55,824 - INFO - Created Project:ctest-TestSvcRegr-46099884, ID : df808776-367f-4bd1-b846-c1fde72ab3ab
2026-02-11 13:11:57,376 - INFO - ================================================================================
2026-02-11 13:11:57,376 - INFO - STARTING TEST : test_svc_in_network_datapath
2026-02-11 13:11:57,632 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.17': '0'}} with
2026-02-11 13:11:57,632 - INFO - Initial checks done. Running the testcase now
2026-02-11 13:11:57,632 - INFO -
2026-02-11 13:11:58,231 - DEBUG - Response for create_network : {'network': {'id': '5c0b8697-0e40-4efc-ae8e-386f54df11ef', 'name': 'ctest-mgmt_vn-32576297', 'tenant_id': 'df808776367f4bd1b846c1fde72ab3ab', 'project_id': 'df808776367f4bd1b846c1fde72ab3ab', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-46099884', 'ctest-mgmt_vn-32576297'], 'port_security_enabled': True, 'description': ''}}
2026-02-11 13:11:58,373 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f4169a63-0586-4abf-97e9-a4a39e77d3f1', 'name': '', 'tenant_id': 'df808776367f4bd1b846c1fde72ab3ab', 'network_id': '5c0b8697-0e40-4efc-ae8e-386f54df11ef', 'ip_version': 4, 'cidr': '160.4.176.64/26', 'allocation_pools': [{'start': '160.4.176.66', 'end': '160.4.176.126'}], 'gateway_ip': '160.4.176.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '160.4.176.66', 'tags': [], 'project_id': 'df808776367f4bd1b846c1fde72ab3ab'}}
2026-02-11 13:11:58,392 - INFO - Created VN ctest-mgmt_vn-32576297
2026-02-11 13:11:58,449 - DEBUG - VN ctest-mgmt_vn-32576297 UUID is 5c0b8697-0e40-4efc-ae8e-386f54df11ef
2026-02-11 13:11:58,560 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-02-11 13:11:58,880 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-02-11 13:11:58,920 - DEBUG - Requesting: http://10.0.0.22:8082/domain/67cdcd78-5bf3-428b-9cfe-8266249cb396
2026-02-11 13:11:58,932 - DEBUG - Requesting: http://10.0.0.22:8082/project/df808776-367f-4bd1-b846-c1fde72ab3ab
2026-02-11 13:11:59,008 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/5c0b8697-0e40-4efc-ae8e-386f54df11ef
2026-02-11 13:11:59,020 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/5c0b8697-0e40-4efc-ae8e-386f54df11ef
2026-02-11 13:11:59,031 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/9ea3618d-bab2-4790-9cfe-6ec567e8aa78
2026-02-11 13:11:59,039 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/9ea3618d-bab2-4790-9cfe-6ec567e8aa78
2026-02-11 13:11:59,046 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/c00637da-2d12-4921-9296-a573c1788efc
2026-02-11 13:11:59,054 - DEBUG - Route Targets: ['target:64512:8000004']
2026-02-11 13:11:59,054 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/5c0b8697-0e40-4efc-ae8e-386f54df11ef
2026-02-11 13:11:59,065 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/9ea3618d-bab2-4790-9cfe-6ec567e8aa78
2026-02-11 13:11:59,083 - INFO - Verified VN network id 11 for VN 5c0b8697-0e40-4efc-ae8e-386f54df11ef
2026-02-11 13:11:59,083 - INFO - Verifications in API Server for VN ctest-mgmt_vn-32576297 passed
2026-02-11 13:11:59,083 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/5c0b8697-0e40-4efc-ae8e-386f54df11ef
2026-02-11 13:11:59,094 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/9ea3618d-bab2-4790-9cfe-6ec567e8aa78
2026-02-11 13:11:59,104 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/9ea3618d-bab2-4790-9cfe-6ec567e8aa78
2026-02-11 13:11:59,112 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/c00637da-2d12-4921-9296-a573c1788efc
2026-02-11 13:11:59,124 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46099884', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '6632542859570466556', 'uuid-lslong': '12578052860108804591'}, 'enable': 'true', 'created': '2026-02-11T13:11:58', 'last-modified': '2026-02-11T13:11:58', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.781125'}
2026-02-11 13:11:59,130 - DEBUG - Route Targets: ['target:64512:8000004']
2026-02-11 13:11:59,130 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-32576297 passed
2026-02-11 13:11:59,130 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-32576297 in API_Server ======
2026-02-11 13:11:59,130 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-02-11 13:11:59,137 - DEBUG - Requesting: http://10.0.0.22:8082/domain/67cdcd78-5bf3-428b-9cfe-8266249cb396
2026-02-11 13:11:59,151 - DEBUG - Requesting: http://10.0.0.22:8082/project/df808776-367f-4bd1-b846-c1fde72ab3ab
2026-02-11 13:11:59,231 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/5c0b8697-0e40-4efc-ae8e-386f54df11ef
2026-02-11 13:11:59,243 - DEBUG - =>VN ctest-mgmt_vn-32576297 has no policy to be verified
2026-02-11 13:11:59,243 - DEBUG - Verifying the vn in opserver
2026-02-11 13:11:59,243 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297 virtual network link through opserver 10.0.0.22
2026-02-11 13:11:59,244 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-02-11 13:11:59,562 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-02-11 13:11:59,633 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297?flat'}
2026-02-11 13:11:59,634 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297 is found in opserver
2026-02-11 13:11:59,646 - DEBUG - Do not have enough data to verify VN in agent
2026-02-11 13:11:59,652 - DEBUG - VRF ids for VN ctest-mgmt_vn-32576297: {}
2026-02-11 13:11:59,835 - DEBUG - Response for create_network : {'network': {'id': '1f45a3c1-c086-48bb-8e6a-ed7f097b5dcc', 'name': 'ctest-left_vn-33710067', 'tenant_id': 'df808776367f4bd1b846c1fde72ab3ab', 'project_id': 'df808776367f4bd1b846c1fde72ab3ab', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-46099884', 'ctest-left_vn-33710067'], 'port_security_enabled': True, 'description': ''}}
2026-02-11 13:12:00,033 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b90c8c58-86c5-451c-a061-a13c1cf891dd', 'name': '', 'tenant_id': 'df808776367f4bd1b846c1fde72ab3ab', 'network_id': '1f45a3c1-c086-48bb-8e6a-ed7f097b5dcc', 'ip_version': 4, 'cidr': '45.198.148.192/26', 'allocation_pools': [{'start': '45.198.148.194', 'end': '45.198.148.254'}], 'gateway_ip': '45.198.148.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '45.198.148.194', 'tags': [], 'project_id': 'df808776367f4bd1b846c1fde72ab3ab'}}
2026-02-11 13:12:00,056 - INFO - Created VN ctest-left_vn-33710067
2026-02-11 13:12:00,113 - DEBUG - VN ctest-left_vn-33710067 UUID is 1f45a3c1-c086-48bb-8e6a-ed7f097b5dcc
2026-02-11 13:12:00,226 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-02-11 13:12:00,234 - DEBUG - Requesting: http://10.0.0.22:8082/domain/67cdcd78-5bf3-428b-9cfe-8266249cb396
2026-02-11 13:12:00,246 - DEBUG - Requesting: http://10.0.0.22:8082/project/df808776-367f-4bd1-b846-c1fde72ab3ab
2026-02-11 13:12:00,320 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1f45a3c1-c086-48bb-8e6a-ed7f097b5dcc
2026-02-11 13:12:00,332 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1f45a3c1-c086-48bb-8e6a-ed7f097b5dcc
2026-02-11 13:12:00,343 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6a559dcc-c82a-4159-b348-d117c8edd967
2026-02-11 13:12:00,351 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6a559dcc-c82a-4159-b348-d117c8edd967
2026-02-11 13:12:00,357 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/174fe6b6-a991-48a9-b755-84847451dafd
2026-02-11 13:12:00,363 - DEBUG - Route Targets: ['target:64512:8000005']
2026-02-11 13:12:00,363 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1f45a3c1-c086-48bb-8e6a-ed7f097b5dcc
2026-02-11 13:12:00,372 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6a559dcc-c82a-4159-b348-d117c8edd967
2026-02-11 13:12:00,387 - INFO - Verified VN network id 12 for VN 1f45a3c1-c086-48bb-8e6a-ed7f097b5dcc
2026-02-11 13:12:00,388 - INFO - Verifications in API Server for VN ctest-left_vn-33710067 passed
2026-02-11 13:12:00,388 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1f45a3c1-c086-48bb-8e6a-ed7f097b5dcc
2026-02-11 13:12:00,397 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6a559dcc-c82a-4159-b348-d117c8edd967
2026-02-11 13:12:00,405 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6a559dcc-c82a-4159-b348-d117c8edd967
2026-02-11 13:12:00,412 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/174fe6b6-a991-48a9-b755-84847451dafd
2026-02-11 13:12:00,426 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46099884:ctest-left_vn-33710067', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46099884', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '2253387241122842811', 'uuid-lslong': '10262275830792805836'}, 'enable': 'true', 'created': '2026-02-11T13:11:59', 'last-modified': '2026-02-11T13:11:59', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.435198'}
2026-02-11 13:12:00,433 - DEBUG - Route Targets: ['target:64512:8000005']
2026-02-11 13:12:00,433 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-33710067 passed
2026-02-11 13:12:00,433 - DEBUG - ====Verifying policy data for ctest-left_vn-33710067 in API_Server ======
2026-02-11 13:12:00,433 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-02-11 13:12:00,439 - DEBUG - Requesting: http://10.0.0.22:8082/domain/67cdcd78-5bf3-428b-9cfe-8266249cb396
2026-02-11 13:12:00,451 - DEBUG - Requesting: http://10.0.0.22:8082/project/df808776-367f-4bd1-b846-c1fde72ab3ab
2026-02-11 13:12:00,525 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/1f45a3c1-c086-48bb-8e6a-ed7f097b5dcc
2026-02-11 13:12:00,534 - DEBUG - =>VN ctest-left_vn-33710067 has no policy to be verified
2026-02-11 13:12:00,534 - DEBUG - Verifying the vn in opserver
2026-02-11 13:12:00,534 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46099884:ctest-left_vn-33710067 virtual network link through opserver 10.0.0.22
2026-02-11 13:12:00,534 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-02-11 13:12:00,546 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46099884:ctest-left_vn-33710067', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46099884:ctest-left_vn-33710067?flat'}
2026-02-11 13:12:00,546 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-46099884:ctest-left_vn-33710067 is found in opserver
2026-02-11 13:12:00,557 - DEBUG - Do not have enough data to verify VN in agent
2026-02-11 13:12:00,562 - DEBUG - VRF ids for VN ctest-left_vn-33710067: {}
2026-02-11 13:12:00,743 - DEBUG - Response for create_network : {'network': {'id': '9c5223a1-ff68-4a1b-b8b7-b48c3c773952', 'name': 'ctest-right_vn-68688944', 'tenant_id': 'df808776367f4bd1b846c1fde72ab3ab', 'project_id': 'df808776367f4bd1b846c1fde72ab3ab', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-46099884', 'ctest-right_vn-68688944'], 'port_security_enabled': True, 'description': ''}}
2026-02-11 13:12:00,915 - DEBUG - Response for create_subnet : {'subnet': {'id': '10f2bde6-b9e6-4158-9354-061fe05a4ddd', 'name': '', 'tenant_id': 'df808776367f4bd1b846c1fde72ab3ab', 'network_id': '9c5223a1-ff68-4a1b-b8b7-b48c3c773952', 'ip_version': 4, 'cidr': '91.187.205.64/26', 'allocation_pools': [{'start': '91.187.205.66', 'end': '91.187.205.126'}], 'gateway_ip': '91.187.205.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '91.187.205.66', 'tags': [], 'project_id': 'df808776367f4bd1b846c1fde72ab3ab'}}
2026-02-11 13:12:00,935 - INFO - Created VN ctest-right_vn-68688944
2026-02-11 13:12:00,989 - DEBUG - VN ctest-right_vn-68688944 UUID is 9c5223a1-ff68-4a1b-b8b7-b48c3c773952
2026-02-11 13:12:01,110 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-02-11 13:12:01,116 - DEBUG - Requesting: http://10.0.0.22:8082/domain/67cdcd78-5bf3-428b-9cfe-8266249cb396
2026-02-11 13:12:01,127 - DEBUG - Requesting: http://10.0.0.22:8082/project/df808776-367f-4bd1-b846-c1fde72ab3ab
2026-02-11 13:12:01,197 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9c5223a1-ff68-4a1b-b8b7-b48c3c773952
2026-02-11 13:12:01,207 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9c5223a1-ff68-4a1b-b8b7-b48c3c773952
2026-02-11 13:12:01,217 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e65788c7-a06c-4288-880d-baf514cc04ad
2026-02-11 13:12:01,224 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e65788c7-a06c-4288-880d-baf514cc04ad
2026-02-11 13:12:01,231 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/3da6b8c5-cce4-49df-8230-9fcb048e5a77
2026-02-11 13:12:01,238 - DEBUG - Route Targets: ['target:64512:8000006']
2026-02-11 13:12:01,238 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9c5223a1-ff68-4a1b-b8b7-b48c3c773952
2026-02-11 13:12:01,247 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e65788c7-a06c-4288-880d-baf514cc04ad
2026-02-11 13:12:01,260 - INFO - Verified VN network id 13 for VN 9c5223a1-ff68-4a1b-b8b7-b48c3c773952
2026-02-11 13:12:01,260 - INFO - Verifications in API Server for VN ctest-right_vn-68688944 passed
2026-02-11 13:12:01,260 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9c5223a1-ff68-4a1b-b8b7-b48c3c773952
2026-02-11 13:12:01,269 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e65788c7-a06c-4288-880d-baf514cc04ad
2026-02-11 13:12:01,276 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e65788c7-a06c-4288-880d-baf514cc04ad
2026-02-11 13:12:01,282 - DEBUG - Requesting: http://10.0.0.22:8082/route-target/3da6b8c5-cce4-49df-8230-9fcb048e5a77
2026-02-11 13:12:01,294 - DEBUG - Control-node 10.0.0.22 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-46099884:ctest-right_vn-68688944', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-46099884', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '11264104796688763419', 'uuid-lslong': '13310305738119657810'}, 'enable': 'true', 'created': '2026-02-11T13:12:00', 'last-modified': '2026-02-11T13:12:00', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.416395'}
2026-02-11 13:12:01,300 - DEBUG - Route Targets: ['target:64512:8000006']
2026-02-11 13:12:01,300 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-68688944 passed
2026-02-11 13:12:01,300 - DEBUG - ====Verifying policy data for ctest-right_vn-68688944 in API_Server ======
2026-02-11 13:12:01,300 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-02-11 13:12:01,306 - DEBUG - Requesting: http://10.0.0.22:8082/domain/67cdcd78-5bf3-428b-9cfe-8266249cb396
2026-02-11 13:12:01,316 - DEBUG - Requesting: http://10.0.0.22:8082/project/df808776-367f-4bd1-b846-c1fde72ab3ab
2026-02-11 13:12:01,390 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-network/9c5223a1-ff68-4a1b-b8b7-b48c3c773952
2026-02-11 13:12:01,401 - DEBUG - =>VN ctest-right_vn-68688944 has no policy to be verified
2026-02-11 13:12:01,401 - DEBUG - Verifying the vn in opserver
2026-02-11 13:12:01,401 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-46099884:ctest-right_vn-68688944 virtual network link through opserver 10.0.0.22
2026-02-11 13:12:01,401 - DEBUG - Requesting: http://10.0.0.22:8081/analytics/uves/virtual-networks
2026-02-11 13:12:01,411 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-46099884:ctest-right_vn-68688944', 'href': 'http://10.0.0.22:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-46099884:ctest-right_vn-68688944?flat'}
2026-02-11 13:12:01,411 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-46099884:ctest-right_vn-68688944 is found in opserver
2026-02-11 13:12:01,424 - DEBUG - Do not have enough data to verify VN in agent
2026-02-11 13:12:01,429 - DEBUG - VRF ids for VN ctest-right_vn-68688944: {}
2026-02-11 13:12:01,500 - DEBUG - Services list from nova: [, , ]
2026-02-11 13:12:02,600 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5289-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5289-1)
2026-02-11 13:12:03,838 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5289-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5289-1)
2026-02-11 13:12:03,839 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-16469252']
2026-02-11 13:12:04,216 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-16469252'] created succesfully
2026-02-11 13:12:04,692 - DEBUG - Image by name cirros_in_net not found
2026-02-11 13:12:04,692 - DEBUG - Installing image cirros_in_net
2026-02-11 13:12:04,692 - 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-02-11 13:12:04,693 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz
2026-02-11 13:12:04,693 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz
2026-02-11 13:12:04,709 - 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-02-11 13:12:04,821 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz
2026-02-11 13:12:04,821 - DEBUG - Unzip image
2026-02-11 13:12:04,821 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz
2026-02-11 13:12:05,005 - DEBUG - Try to create image in user context
2026-02-11 13:12:05,243 - DEBUG - Image has been created in user context
2026-02-11 13:12:05,243 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk
2026-02-11 13:12:05,263 - DEBUG - Downloaded image has been removed
2026-02-11 13:12:05,284 - DEBUG - Uploaded image status = active
2026-02-11 13:12:06,500 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5289-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5289-1)
2026-02-11 13:12:06,501 - INFO - Waiting for VM ctest-pt_svm0-19403082 to be up..
2026-02-11 13:12:06,555 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-02-11 13:12:11,642 - DEBUG - VM is in ACTIVE state now
2026-02-11 13:12:11,642 - INFO - VM name : ctest-pt_svm0-19403082
2026-02-11 13:12:11,723 - DEBUG - VM ctest-pt_svm0-19403082 ID is 5fd47d51-5eca-4a1d-aae7-8a6ee9204dc9
2026-02-11 13:12:11,747 - DEBUG - VM ctest-pt_svm0-19403082 launched on Node cn-jenkins-deploy-platform-ansible-os-5289-1
2026-02-11 13:12:12,018 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/5fd47d51-5eca-4a1d-aae7-8a6ee9204dc9
2026-02-11 13:12:12,027 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/2193e338-007e-4d94-9de6-7d58e936b139
2026-02-11 13:12:12,037 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/23732186-51f5-4bb5-a58b-c662f784a7a8
2026-02-11 13:12:12,047 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/3fb62d3b-89bd-41f2-9ef3-3dc12cfdb62f
2026-02-11 13:12:15,338 - 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 1016ms')
2026-02-11 13:12:15,338 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-19403082 failed!
2026-02-11 13:12:15,397 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297 is 160.4.176.65 and allocation pool is NOT set
2026-02-11 13:12:19,462 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1002ms')
2026-02-11 13:12:19,462 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-19403082 failed!
2026-02-11 13:12:19,517 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297 is 160.4.176.65 and allocation pool is NOT set
2026-02-11 13:12:23,595 - 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-02-11 13:12:23,596 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-19403082 failed!
2026-02-11 13:12:23,653 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297 is 160.4.176.65 and allocation pool is NOT set
2026-02-11 13:12:27,718 - 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 1006ms')
2026-02-11 13:12:27,718 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-19403082 failed!
2026-02-11 13:12:27,773 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297 is 160.4.176.65 and allocation pool is NOT set
2026-02-11 13:12:29,838 - 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=15.5 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.684 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.684/8.073/15.463/7.389 ms')
2026-02-11 13:12:29,838 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-19403082 passed
2026-02-11 13:12:29,993 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:12:29,993 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-19403082, IP 160.4.176.67, Port 22
2026-02-11 13:12:30,060 - 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-02-11 13:12:30,159 - DEBUG - VM ctest-pt_svm0-19403082 is NOT ready for SSH connections, VM status: ACTIVE
2026-02-11 13:12:35,159 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:12:35,159 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-19403082, IP 160.4.176.67, Port 22
2026-02-11 13:12:35,217 - 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-02-11 13:12:35,308 - DEBUG - VM ctest-pt_svm0-19403082 is NOT ready for SSH connections, VM status: ACTIVE
2026-02-11 13:12:40,309 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:12:40,309 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-19403082, IP 160.4.176.67, Port 22
2026-02-11 13:12:40,364 - 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-02-11 13:12:40,458 - DEBUG - VM ctest-pt_svm0-19403082 is NOT ready for SSH connections, VM status: ACTIVE
2026-02-11 13:12:45,459 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:12:45,459 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-19403082, IP 160.4.176.67, Port 22
2026-02-11 13:12:45,526 - 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-02-11 13:12:45,615 - DEBUG - VM ctest-pt_svm0-19403082 is NOT ready for SSH connections, VM status: ACTIVE
2026-02-11 13:12:50,616 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:12:50,616 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-19403082, IP 160.4.176.67, Port 22
2026-02-11 13:12:50,772 - DEBUG - VM ctest-pt_svm0-19403082 is ready for SSH connections
2026-02-11 13:12:50,921 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:12:50,921 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-19403082, IP 160.4.176.67, Port 22
2026-02-11 13:12:51,083 - DEBUG - VM ctest-pt_svm0-19403082 is ready for SSH connections
2026-02-11 13:12:51,228 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:12:51,229 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-19403082, IP 160.4.176.67, Port 22
2026-02-11 13:12:51,381 - DEBUG - VM ctest-pt_svm0-19403082 is ready for SSH connections
2026-02-11 13:12:51,387 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSvcRegr-46099884', 'ctest-si-48615103']
2026-02-11 13:12:52,252 - INFO - Waiting for VM ctest-left_vm-11426235 to be up..
2026-02-11 13:12:52,357 - DEBUG - VM is in ACTIVE state now
2026-02-11 13:12:52,357 - INFO - VM name : ctest-left_vm-11426235
2026-02-11 13:12:52,435 - DEBUG - VM ctest-left_vm-11426235 ID is 816bea1d-b5e3-42d2-869b-5843aa89dda1
2026-02-11 13:12:52,435 - DEBUG - VM ctest-left_vm-11426235 launched on Node cn-jenkins-deploy-platform-ansible-os-5289-1
2026-02-11 13:12:52,516 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/816bea1d-b5e3-42d2-869b-5843aa89dda1
2026-02-11 13:12:52,524 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/1296cebb-d1f2-46d8-b9ca-b8e09e1faad3
2026-02-11 13:12:53,763 - 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.421 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.421/1.703/2.985/1.282 ms')
2026-02-11 13:12:53,764 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-11426235 passed
2026-02-11 13:12:53,912 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:12:53,913 - DEBUG - Waiting to SSH to VM ctest-left_vm-11426235, IP 45.198.148.195, Port 22
2026-02-11 13:12:54,072 - DEBUG - VM ctest-left_vm-11426235 is ready for SSH connections
2026-02-11 13:12:54,072 - INFO - Waiting for VM ctest-right_vm-05642099 to be up..
2026-02-11 13:12:54,145 - DEBUG - VM is in ACTIVE state now
2026-02-11 13:12:54,145 - INFO - VM name : ctest-right_vm-05642099
2026-02-11 13:12:54,224 - DEBUG - VM ctest-right_vm-05642099 ID is 8d653169-73f5-468d-9d33-b243976feb5e
2026-02-11 13:12:54,224 - DEBUG - VM ctest-right_vm-05642099 launched on Node cn-jenkins-deploy-platform-ansible-os-5289-1
2026-02-11 13:12:54,298 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/8d653169-73f5-468d-9d33-b243976feb5e
2026-02-11 13:12:54,306 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/733abcb3-f86a-45b4-adbf-182f08cbd3c1
2026-02-11 13:12:55,546 - 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.98 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.598 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.598/1.788/2.978/1.190 ms')
2026-02-11 13:12:55,546 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-05642099 passed
2026-02-11 13:12:55,705 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:12:55,705 - DEBUG - Waiting to SSH to VM ctest-right_vm-05642099, IP 91.187.205.67, Port 22
2026-02-11 13:12:55,864 - DEBUG - VM ctest-right_vm-05642099 is ready for SSH connections
2026-02-11 13:12:55,870 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-46099884:ctest-left_vn-33710067, 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-46099884:ctest-right_vn-68688944, 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-46099884:ctest-si-48615103'], 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-02-11 13:12:56,289 - INFO - Attaching policy ctest-policy-21563680 to vn ctest-left_vn-33710067
2026-02-11 13:12:56,817 - INFO - Attaching policy ctest-policy-21563680 to vn ctest-right_vn-68688944
2026-02-11 13:12:57,277 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-16469252'] created succesfully
2026-02-11 13:12:57,496 - DEBUG - Service instance: ['default-domain', 'ctest-TestSvcRegr-46099884', 'ctest-si-48615103'] created succesfully
2026-02-11 13:12:57,497 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-02-11 13:12:57,503 - DEBUG - Requesting: http://10.0.0.22:8082/domain/67cdcd78-5bf3-428b-9cfe-8266249cb396
2026-02-11 13:12:57,517 - DEBUG - Requesting: http://10.0.0.22:8082/project/df808776-367f-4bd1-b846-c1fde72ab3ab
2026-02-11 13:12:57,598 - DEBUG - Requesting: http://10.0.0.22:8082/service-instance/b8368b29-f438-49de-b893-2846f5bf71c1
2026-02-11 13:12:57,607 - DEBUG - SI 'ctest-si-48615103' has service template ref to ctest-service_template_1-16469252
2026-02-11 13:12:57,608 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-02-11 13:12:57,613 - DEBUG - Requesting: http://10.0.0.22:8082/domain/67cdcd78-5bf3-428b-9cfe-8266249cb396
2026-02-11 13:12:57,623 - DEBUG - Requesting: http://10.0.0.22:8082/project/df808776-367f-4bd1-b846-c1fde72ab3ab
2026-02-11 13:12:57,703 - DEBUG - Requesting: http://10.0.0.22:8082/service-instance/b8368b29-f438-49de-b893-2846f5bf71c1
2026-02-11 13:12:57,718 - DEBUG - Requesting: http://10.0.0.22:8082/port-tuple/85c4ecea-deca-45e1-88b4-8815f9b7a32c
2026-02-11 13:12:57,727 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/2193e338-007e-4d94-9de6-7d58e936b139
2026-02-11 13:12:57,737 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/23732186-51f5-4bb5-a58b-c662f784a7a8
2026-02-11 13:12:57,748 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/3fb62d3b-89bd-41f2-9ef3-3dc12cfdb62f
2026-02-11 13:12:57,981 - DEBUG - VM is in ACTIVE state now
2026-02-11 13:12:58,389 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/5fd47d51-5eca-4a1d-aae7-8a6ee9204dc9
2026-02-11 13:12:58,399 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/2193e338-007e-4d94-9de6-7d58e936b139
2026-02-11 13:12:58,412 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/23732186-51f5-4bb5-a58b-c662f784a7a8
2026-02-11 13:12:58,425 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/3fb62d3b-89bd-41f2-9ef3-3dc12cfdb62f
2026-02-11 13:12:58,436 - DEBUG - Requesting: http://10.0.0.22:8082/instance-ip/7308ea85-2325-471b-9d0f-a8bdcf4a79bd
2026-02-11 13:12:58,443 - DEBUG - Requesting: http://10.0.0.22:8082/instance-ip/a6dbdeb4-6549-4000-bb09-ac29d934495c
2026-02-11 13:12:58,450 - DEBUG - Requesting: http://10.0.0.22:8082/instance-ip/e37c415c-b9dd-48a5-8f26-39c96ab88bf0
2026-02-11 13:12:58,459 - DEBUG - Requesting: http://10.0.0.22:8082/instance-ip/19c4f801-c205-4c68-927d-235a62bf1381
2026-02-11 13:12:58,467 - DEBUG - Requesting: http://10.0.0.22:8082/instance-ip/4a7a4558-e67f-43da-925e-4152e1e53365
2026-02-11 13:12:58,474 - DEBUG - Requesting: http://10.0.0.22:8082/instance-ip/a5a1ddf4-0c78-4798-af8f-5d421a69e2ad
2026-02-11 13:12:58,481 - DEBUG - Requesting: http://10.0.0.22:8082/instance-ip/37b89e3e-f0d4-48c7-b62a-4014ffb3793d
2026-02-11 13:12:58,489 - DEBUG - Requesting: http://10.0.0.22:8082/instance-ip/4e71d0ab-e5bc-4fdd-95e4-60c72465eae5
2026-02-11 13:12:58,496 - DEBUG - Requesting: http://10.0.0.22:8082/instance-ip/fc76532d-f9b0-4d82-8352-1efde4cfb852
2026-02-11 13:12:58,912 - DEBUG - VM is in ACTIVE state now
2026-02-11 13:12:58,913 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/5fd47d51-5eca-4a1d-aae7-8a6ee9204dc9
2026-02-11 13:12:58,920 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/2193e338-007e-4d94-9de6-7d58e936b139
2026-02-11 13:12:58,930 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/23732186-51f5-4bb5-a58b-c662f784a7a8
2026-02-11 13:12:58,940 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/3fb62d3b-89bd-41f2-9ef3-3dc12cfdb62f
2026-02-11 13:13:00,181 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n64 bytes from 169.254.0.6: icmp_seq=1 ttl=63 time=1.13 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.500 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.500/0.815/1.130/0.315 ms')
2026-02-11 13:13:00,181 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-19403082 passed
2026-02-11 13:13:00,217 - DEBUG - VM ctest-pt_svm0-19403082 Tap interface: {'index': '6', 'name': 'tap23732186-51', 'uuid': '23732186-51f5-4bb5-a58b-c662f784a7a8', 'vrf_name': 'default-domain:ctest-TestSvcRegr-46099884:ctest-left_vn-33710067:ctest-left_vn-33710067', '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-46099884:ctest-left_vn-33710067', 'vm_uuid': '5fd47d51-5eca-4a1d-aae7-8a6ee9204dc9', 'vm_name': 'ctest-pt_svm0-19403082', 'ip_addr': '45.198.148.196', 'mac_addr': '02:23:73:21:86:51', '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-46099884:23732186-51f5-4bb5-a58b-c662f784a7a8', 'sg_uuid_list': ['5e19d876-431b-48c3-b0e0-6501a440b77f'], 'static_route_list': None, 'vm_project_uuid': 'df808776-367f-4bd1-b846-c1fde72ab3ab', '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': '3fb62d3b-89bd-41f2-9ef3-3dc12cfdb62f', '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-02-11 13:13:00,218 - DEBUG - Agent 10.0.0.22 vrf name: default-domain:ctest-TestSvcRegr-46099884:ctest-left_vn-33710067:ctest-left_vn-33710067
2026-02-11 13:13:00,223 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-46099884:ctest-left_vn-33710067:ctest-left_vn-33710067', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestSvcRegr-46099884:ctest-left_vn-33710067', 'table_label': '-1', 'vxlan_id': '12', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.17:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-02-11 13:13:00,389 - DEBUG - VM ctest-pt_svm0-19403082 : Tap interface tap23732186-51 is set to Active
2026-02-11 13:13:00,390 - DEBUG - Tap interface tap23732186-51 detail : {'index': '6', 'name': 'tap23732186-51', 'uuid': '23732186-51f5-4bb5-a58b-c662f784a7a8', 'vrf_name': 'default-domain:ctest-TestSvcRegr-46099884:ctest-left_vn-33710067:ctest-left_vn-33710067', '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-46099884:ctest-left_vn-33710067', 'vm_uuid': '5fd47d51-5eca-4a1d-aae7-8a6ee9204dc9', 'vm_name': 'ctest-pt_svm0-19403082', 'ip_addr': '45.198.148.196', 'mac_addr': '02:23:73:21:86:51', '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-46099884:23732186-51f5-4bb5-a58b-c662f784a7a8', 'sg_uuid_list': ['5e19d876-431b-48c3-b0e0-6501a440b77f'], 'static_route_list': None, 'vm_project_uuid': 'df808776-367f-4bd1-b846-c1fde72ab3ab', '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': '3fb62d3b-89bd-41f2-9ef3-3dc12cfdb62f', '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-02-11 13:13:00,581 - DEBUG - Starting Layer 2 verification in Agent
2026-02-11 13:13:00,591 - DEBUG - Layer 2 path is seen for VM MAC 02:23:73:21:86:51 in agent 10.0.0.22
2026-02-11 13:13:00,592 - DEBUG - Active layer 2 route in agent is present for VMI tap23732186-51
2026-02-11 13:13:00,592 - DEBUG - L2 label(48) matches bw route table and interface table
2026-02-11 13:13:00,598 - DEBUG - VN default-domain:ctest-TestSvcRegr-46099884:ctest-left_vn-33710067 is found in Agent of node 10.0.0.22
2026-02-11 13:13:00,815 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-46099884:ctest-left_vn-33710067 is consistent in agent 10.0.0.22
2026-02-11 13:13:00,816 - DEBUG - Route for VM IP ['45.198.148.196'] is consistent in agent 10.0.0.22
2026-02-11 13:13:00,816 - DEBUG - VN default-domain:ctest-TestSvcRegr-46099884:ctest-left_vn-33710067 verification for VM ctest-pt_svm0-19403082 in Agent 10.0.0.22 passed
2026-02-11 13:13:01,009 - DEBUG - Starting all layer 2 verification in agent 10.0.0.22
2026-02-11 13:13:01,023 - DEBUG - Route for VM MAC 02:23:73:21:86:51 is consistent in agent 10.0.0.22
2026-02-11 13:13:01,044 - DEBUG - VM ctest-pt_svm0-19403082 Tap interface: {'index': '5', 'name': 'tap2193e338-00', 'uuid': '2193e338-007e-4d94-9de6-7d58e936b139', 'vrf_name': 'default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297:ctest-mgmt_vn-32576297', '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-46099884:ctest-mgmt_vn-32576297', 'vm_uuid': '5fd47d51-5eca-4a1d-aae7-8a6ee9204dc9', 'vm_name': 'ctest-pt_svm0-19403082', 'ip_addr': '160.4.176.67', 'mac_addr': '02:21:93:e3:38:00', '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-46099884:2193e338-007e-4d94-9de6-7d58e936b139', 'sg_uuid_list': ['5e19d876-431b-48c3-b0e0-6501a440b77f'], 'static_route_list': None, 'vm_project_uuid': 'df808776-367f-4bd1-b846-c1fde72ab3ab', '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-02-11 13:13:01,044 - DEBUG - Agent 10.0.0.22 vrf name: default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297:ctest-mgmt_vn-32576297
2026-02-11 13:13:01,050 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297:ctest-mgmt_vn-32576297', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297', 'table_label': '-1', 'vxlan_id': '11', 'evpnindex': '4', 'brindex': '4', 'mplsindex': '0', 'RD': '10.20.0.17:4', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-02-11 13:13:01,207 - DEBUG - VM ctest-pt_svm0-19403082 : Tap interface tap2193e338-00 is set to Active
2026-02-11 13:13:01,207 - DEBUG - Tap interface tap2193e338-00 detail : {'index': '5', 'name': 'tap2193e338-00', 'uuid': '2193e338-007e-4d94-9de6-7d58e936b139', 'vrf_name': 'default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297:ctest-mgmt_vn-32576297', '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-46099884:ctest-mgmt_vn-32576297', 'vm_uuid': '5fd47d51-5eca-4a1d-aae7-8a6ee9204dc9', 'vm_name': 'ctest-pt_svm0-19403082', 'ip_addr': '160.4.176.67', 'mac_addr': '02:21:93:e3:38:00', '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-46099884:2193e338-007e-4d94-9de6-7d58e936b139', 'sg_uuid_list': ['5e19d876-431b-48c3-b0e0-6501a440b77f'], 'static_route_list': None, 'vm_project_uuid': 'df808776-367f-4bd1-b846-c1fde72ab3ab', '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-02-11 13:13:01,397 - DEBUG - Starting Layer 2 verification in Agent
2026-02-11 13:13:01,406 - DEBUG - Layer 2 path is seen for VM MAC 02:21:93:e3:38:00 in agent 10.0.0.22
2026-02-11 13:13:01,406 - DEBUG - Active layer 2 route in agent is present for VMI tap2193e338-00
2026-02-11 13:13:01,407 - DEBUG - L2 label(43) matches bw route table and interface table
2026-02-11 13:13:01,412 - DEBUG - VN default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297 is found in Agent of node 10.0.0.22
2026-02-11 13:13:01,622 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297 is consistent in agent 10.0.0.22
2026-02-11 13:13:01,622 - DEBUG - Route for VM IP ['160.4.176.67'] is consistent in agent 10.0.0.22
2026-02-11 13:13:01,622 - DEBUG - VN default-domain:ctest-TestSvcRegr-46099884:ctest-mgmt_vn-32576297 verification for VM ctest-pt_svm0-19403082 in Agent 10.0.0.22 passed
2026-02-11 13:13:01,817 - DEBUG - Starting all layer 2 verification in agent 10.0.0.22
2026-02-11 13:13:01,827 - DEBUG - Route for VM MAC 02:21:93:e3:38:00 is consistent in agent 10.0.0.22
2026-02-11 13:13:01,848 - DEBUG - VM ctest-pt_svm0-19403082 Tap interface: {'index': '7', 'name': 'tap3fb62d3b-89', 'uuid': '3fb62d3b-89bd-41f2-9ef3-3dc12cfdb62f', 'vrf_name': 'default-domain:ctest-TestSvcRegr-46099884:ctest-right_vn-68688944:ctest-right_vn-68688944', '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-46099884:ctest-right_vn-68688944', 'vm_uuid': '5fd47d51-5eca-4a1d-aae7-8a6ee9204dc9', 'vm_name': 'ctest-pt_svm0-19403082', 'ip_addr': '91.187.205.68', 'mac_addr': '02:3f:b6:2d:3b:89', '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-46099884:3fb62d3b-89bd-41f2-9ef3-3dc12cfdb62f', 'sg_uuid_list': ['5e19d876-431b-48c3-b0e0-6501a440b77f'], 'static_route_list': None, 'vm_project_uuid': 'df808776-367f-4bd1-b846-c1fde72ab3ab', 'admin_state': 'Enabled', 'flow_key_idx': '55', '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': '23732186-51f5-4bb5-a58b-c662f784a7a8', '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-02-11 13:13:01,848 - DEBUG - Agent 10.0.0.22 vrf name: default-domain:ctest-TestSvcRegr-46099884:ctest-right_vn-68688944:ctest-right_vn-68688944
2026-02-11 13:13:01,852 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-46099884:ctest-right_vn-68688944:ctest-right_vn-68688944', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSvcRegr-46099884:ctest-right_vn-68688944', 'table_label': '-1', 'vxlan_id': '13', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.17:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]}
2026-02-11 13:13:02,063 - DEBUG - VM ctest-pt_svm0-19403082 : Tap interface tap3fb62d3b-89 is set to Active
2026-02-11 13:13:02,064 - DEBUG - Tap interface tap3fb62d3b-89 detail : {'index': '7', 'name': 'tap3fb62d3b-89', 'uuid': '3fb62d3b-89bd-41f2-9ef3-3dc12cfdb62f', 'vrf_name': 'default-domain:ctest-TestSvcRegr-46099884:ctest-right_vn-68688944:ctest-right_vn-68688944', '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-46099884:ctest-right_vn-68688944', 'vm_uuid': '5fd47d51-5eca-4a1d-aae7-8a6ee9204dc9', 'vm_name': 'ctest-pt_svm0-19403082', 'ip_addr': '91.187.205.68', 'mac_addr': '02:3f:b6:2d:3b:89', '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-46099884:3fb62d3b-89bd-41f2-9ef3-3dc12cfdb62f', 'sg_uuid_list': ['5e19d876-431b-48c3-b0e0-6501a440b77f'], 'static_route_list': None, 'vm_project_uuid': 'df808776-367f-4bd1-b846-c1fde72ab3ab', 'admin_state': 'Enabled', 'flow_key_idx': '55', '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': '23732186-51f5-4bb5-a58b-c662f784a7a8', '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-02-11 13:13:02,261 - DEBUG - Starting Layer 2 verification in Agent
2026-02-11 13:13:02,271 - DEBUG - Layer 2 path is seen for VM MAC 02:3f:b6:2d:3b:89 in agent 10.0.0.22
2026-02-11 13:13:02,271 - DEBUG - Active layer 2 route in agent is present for VMI tap3fb62d3b-89
2026-02-11 13:13:02,271 - DEBUG - L2 label(53) matches bw route table and interface table
2026-02-11 13:13:02,276 - DEBUG - VN default-domain:ctest-TestSvcRegr-46099884:ctest-right_vn-68688944 is found in Agent of node 10.0.0.22
2026-02-11 13:13:02,487 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-46099884:ctest-right_vn-68688944 is consistent in agent 10.0.0.22
2026-02-11 13:13:02,487 - DEBUG - Route for VM IP ['91.187.205.68'] is consistent in agent 10.0.0.22
2026-02-11 13:13:02,487 - DEBUG - VN default-domain:ctest-TestSvcRegr-46099884:ctest-right_vn-68688944 verification for VM ctest-pt_svm0-19403082 in Agent 10.0.0.22 passed
2026-02-11 13:13:02,681 - DEBUG - Starting all layer 2 verification in agent 10.0.0.22
2026-02-11 13:13:02,691 - DEBUG - Route for VM MAC 02:3f:b6:2d:3b:89 is consistent in agent 10.0.0.22
2026-02-11 13:13:02,691 - INFO - VM ctest-pt_svm0-19403082 verifications in Compute nodes passed
2026-02-11 13:13:02,691 - DEBUG - The SVMs in the SI are : []
2026-02-11 13:13:02,691 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/5fd47d51-5eca-4a1d-aae7-8a6ee9204dc9
2026-02-11 13:13:02,699 - DEBUG - Service VM for SI 'ctest-si-48615103' is launched
2026-02-11 13:13:02,699 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-02-11 13:13:02,704 - DEBUG - Requesting: http://10.0.0.22:8082/domain/67cdcd78-5bf3-428b-9cfe-8266249cb396
2026-02-11 13:13:02,715 - DEBUG - Requesting: http://10.0.0.22:8082/project/df808776-367f-4bd1-b846-c1fde72ab3ab
2026-02-11 13:13:02,788 - DEBUG - Requesting: http://10.0.0.22:8082/service-instance/b8368b29-f438-49de-b893-2846f5bf71c1
2026-02-11 13:13:02,797 - DEBUG - SI ctest-si-48615103 has Port Tuple: ['ctest-port_tuple-96016901']
2026-02-11 13:13:02,897 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-46099884:ctest-left_vn-33710067
2026-02-11 13:13:03,040 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-46099884:ctest-right_vn-68688944
2026-02-11 13:13:03,195 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:13:03,203 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:13:03,203 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:13:03,209 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:13:03,209 - INFO - Route to 91.187.205.67 seen in VRF:5 on cn-jenkins-deploy-platform-ansible-os-5289-1, and SI ctest-si-48615103 is seen as the NH
2026-02-11 13:13:03,363 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:13:03,370 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:13:03,370 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:13:03,376 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:13:03,376 - INFO - Route to 45.198.148.195 seen in VRF:6 on cn-jenkins-deploy-platform-ansible-os-5289-1, and SI ctest-si-48615103 is seen as the NH
2026-02-11 13:13:03,376 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:13:03,376 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-02-11 13:13:03,376 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 91.187.205.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2026-02-11 13:13:03,377 - DEBUG - ping -s 56 -c 3 -W 1 91.187.205.67
2026-02-11 13:13:07,382 - DEBUG - PING 91.187.205.67 (91.187.205.67): 56 data bytes
64 bytes from 91.187.205.67: seq=0 ttl=61 time=6.747 ms
64 bytes from 91.187.205.67: seq=1 ttl=61 time=1.552 ms
64 bytes from 91.187.205.67: seq=2 ttl=61 time=1.809 ms
--- 91.187.205.67 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.552/3.369/6.747 ms
2026-02-11 13:13:07,382 - INFO - Ping to IP 91.187.205.67 from VM ctest-left_vm-11426235 passed
2026-02-11 13:13:07,382 - INFO - Dettaching policy ctest-policy-21563680 from vn ctest-right_vn-68688944
2026-02-11 13:13:07,584 - INFO - Dettaching policy ctest-policy-21563680 from vn ctest-left_vn-33710067
2026-02-11 13:13:07,854 - INFO - Deleted policy ctest-policy-21563680
2026-02-11 13:13:07,854 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSvcRegr-46099884', 'ctest-si-48615103']
2026-02-11 13:13:07,954 - INFO - Deleted SI ['default-domain', 'ctest-TestSvcRegr-46099884', 'ctest-si-48615103']
2026-02-11 13:13:07,955 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-02-11 13:13:07,961 - DEBUG - Requesting: http://10.0.0.22:8082/domain/67cdcd78-5bf3-428b-9cfe-8266249cb396
2026-02-11 13:13:07,977 - DEBUG - Requesting: http://10.0.0.22:8082/project/df808776-367f-4bd1-b846-c1fde72ab3ab
2026-02-11 13:13:08,055 - DEBUG - Service instance ctest-si-48615103 removed from api server
2026-02-11 13:13:08,055 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/5fd47d51-5eca-4a1d-aae7-8a6ee9204dc9
2026-02-11 13:13:08,066 - DEBUG - All Service VMs unlinked from SI ctest-si-48615103
2026-02-11 13:13:08,073 - DEBUG - 0 svc intances found in all projects. They are []
2026-02-11 13:13:08,073 - DEBUG - 0 svc intances found in current project. They are []
2026-02-11 13:13:08,120 - DEBUG - 0 svc intances found in all projects. They are []
2026-02-11 13:13:08,120 - DEBUG - 0 svc intances found in current project. They are []
2026-02-11 13:13:08,120 - INFO - Deleting VM ctest-pt_svm0-19403082
2026-02-11 13:13:08,221 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-16469252']
2026-02-11 13:13:08,324 - INFO - Service template: ['default-domain', 'ctest-service_template_1-16469252'] deleted successfully.
2026-02-11 13:13:08,325 - INFO - Deleting VM ctest-right_vm-05642099
2026-02-11 13:13:08,431 - INFO - Deleting VM ctest-left_vm-11426235
2026-02-11 13:13:08,521 - INFO - Deleting VN ctest-right_vn-68688944
2026-02-11 13:13:08,572 - DEBUG - VN 9c5223a1-ff68-4a1b-b8b7-b48c3c773952 still in use: Unable to complete operation on network 9c5223a1-ff68-4a1b-b8b7-b48c3c773952. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-63abd589-a943-4f2e-80e8-c8ed0c1e9179']
2026-02-11 13:13:08,572 - WARNING - Deleting VN ctest-right_vn-68688944 failed..Will retry
2026-02-11 13:13:10,676 - DEBUG - Response for deleting network ()
2026-02-11 13:13:10,676 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/e65788c7-a06c-4288-880d-baf514cc04ad
2026-02-11 13:13:10,686 - DEBUG - Response Code: 404
2026-02-11 13:13:10,687 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-02-11 13:13:10,692 - DEBUG - Requesting: http://10.0.0.22:8082/domain/67cdcd78-5bf3-428b-9cfe-8266249cb396
2026-02-11 13:13:10,746 - DEBUG - Requesting: http://10.0.0.22:8082/project/df808776-367f-4bd1-b846-c1fde72ab3ab
2026-02-11 13:13:10,828 - INFO - Validated that VN ctest-right_vn-68688944 is not found in API Server
2026-02-11 13:13:10,839 - DEBUG - VN ctest-right_vn-68688944 is not present in Agent 10.0.0.22
2026-02-11 13:13:10,839 - INFO - Validated that VN ctest-right_vn-68688944 is not in any agent
2026-02-11 13:13:10,854 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-68688944 info
2026-02-11 13:13:10,854 - INFO - Deleting VN ctest-left_vn-33710067
2026-02-11 13:13:10,960 - DEBUG - Response for deleting network ()
2026-02-11 13:13:10,960 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/6a559dcc-c82a-4159-b348-d117c8edd967
2026-02-11 13:13:10,965 - DEBUG - Response Code: 404
2026-02-11 13:13:10,965 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-02-11 13:13:10,970 - DEBUG - Requesting: http://10.0.0.22:8082/domain/67cdcd78-5bf3-428b-9cfe-8266249cb396
2026-02-11 13:13:11,022 - DEBUG - Requesting: http://10.0.0.22:8082/project/df808776-367f-4bd1-b846-c1fde72ab3ab
2026-02-11 13:13:11,094 - INFO - Validated that VN ctest-left_vn-33710067 is not found in API Server
2026-02-11 13:13:11,105 - DEBUG - VN ctest-left_vn-33710067 is not present in Agent 10.0.0.22
2026-02-11 13:13:11,105 - INFO - Validated that VN ctest-left_vn-33710067 is not in any agent
2026-02-11 13:13:11,120 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-33710067 info
2026-02-11 13:13:11,120 - INFO - Deleting VN ctest-mgmt_vn-32576297
2026-02-11 13:13:11,219 - DEBUG - Response for deleting network ()
2026-02-11 13:13:11,219 - DEBUG - Requesting: http://10.0.0.22:8082/routing-instance/9ea3618d-bab2-4790-9cfe-6ec567e8aa78
2026-02-11 13:13:11,225 - DEBUG - Response Code: 404
2026-02-11 13:13:11,225 - DEBUG - Requesting: http://10.0.0.22:8082/domains
2026-02-11 13:13:11,230 - DEBUG - Requesting: http://10.0.0.22:8082/domain/67cdcd78-5bf3-428b-9cfe-8266249cb396
2026-02-11 13:13:11,281 - DEBUG - Requesting: http://10.0.0.22:8082/project/df808776-367f-4bd1-b846-c1fde72ab3ab
2026-02-11 13:13:11,353 - INFO - Validated that VN ctest-mgmt_vn-32576297 is not found in API Server
2026-02-11 13:13:11,365 - DEBUG - VN ctest-mgmt_vn-32576297 is not present in Agent 10.0.0.22
2026-02-11 13:13:11,365 - INFO - Validated that VN ctest-mgmt_vn-32576297 is not in any agent
2026-02-11 13:13:11,378 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-32576297 info
2026-02-11 13:13:11,638 - DEBUG - No XMPP flaps were noticed during the test
2026-02-11 13:13:11,639 - INFO -
2026-02-11 13:13:11,639 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:14]
2026-02-11 13:13:11,639 - INFO - --------------------------------------------------------------------------------
2026-02-11 13:13:12,366 - INFO - Deleted project: ctest-TestSvcRegr-46099884, ID : df808776-367f-4bd1-b846-c1fde72ab3ab