2026-06-23 03:01:03,165 - INFO - Domain Default found not creating
2026-06-23 03:01:03,301 - INFO - Project ctest-TestSvcRegr-39336828 not found, creating it
2026-06-23 03:01:03,738 - INFO - Created Project:ctest-TestSvcRegr-39336828, ID : e17f1860-c249-4236-8ae1-d3415dca927d
2026-06-23 03:01:05,134 - INFO - ================================================================================
2026-06-23 03:01:05,135 - INFO - STARTING TEST : test_svc_in_network_datapath
2026-06-23 03:01:05,392 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.17': '0'}} with
2026-06-23 03:01:05,392 - INFO - Initial checks done. Running the testcase now
2026-06-23 03:01:05,392 - INFO -
2026-06-23 03:01:05,914 - DEBUG - Response for create_network : {'network': {'id': '502aaddf-2820-4de2-8531-d139233f348a', 'name': 'ctest-mgmt_vn-41888915', 'tenant_id': 'e17f1860c24942368ae1d3415dca927d', 'project_id': 'e17f1860c24942368ae1d3415dca927d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-39336828', 'ctest-mgmt_vn-41888915'], 'port_security_enabled': True, 'description': ''}}
2026-06-23 03:01:06,055 - DEBUG - Response for create_subnet : {'subnet': {'id': '0e8ab61b-e833-4d6b-90ea-94e060726de2', 'name': '', 'tenant_id': 'e17f1860c24942368ae1d3415dca927d', 'network_id': '502aaddf-2820-4de2-8531-d139233f348a', 'ip_version': 4, 'cidr': '52.191.105.64/26', 'allocation_pools': [{'start': '52.191.105.66', 'end': '52.191.105.126'}], 'gateway_ip': '52.191.105.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '52.191.105.66', 'tags': [], 'project_id': 'e17f1860c24942368ae1d3415dca927d'}}
2026-06-23 03:01:06,069 - INFO - Created VN ctest-mgmt_vn-41888915
2026-06-23 03:01:06,119 - DEBUG - VN ctest-mgmt_vn-41888915 UUID is 502aaddf-2820-4de2-8531-d139233f348a
2026-06-23 03:01:06,209 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-23 03:01:06,528 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-23 03:01:06,551 - DEBUG - Requesting: http://10.0.0.50:8082/domain/63e6debd-e1b5-484d-ab89-80f9428c6f51
2026-06-23 03:01:06,563 - DEBUG - Requesting: http://10.0.0.50:8082/project/e17f1860-c249-4236-8ae1-d3415dca927d
2026-06-23 03:01:06,636 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/502aaddf-2820-4de2-8531-d139233f348a
2026-06-23 03:01:06,644 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/502aaddf-2820-4de2-8531-d139233f348a
2026-06-23 03:01:06,651 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/32434f38-5de8-4d92-9306-97bbbcde29fb
2026-06-23 03:01:06,657 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/32434f38-5de8-4d92-9306-97bbbcde29fb
2026-06-23 03:01:06,662 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/3c723809-0725-47a1-aadb-9acb4f63f892
2026-06-23 03:01:06,667 - DEBUG - Route Targets: ['target:64512:8000004']
2026-06-23 03:01:06,667 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/502aaddf-2820-4de2-8531-d139233f348a
2026-06-23 03:01:06,673 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/32434f38-5de8-4d92-9306-97bbbcde29fb
2026-06-23 03:01:06,683 - INFO - Verified VN network id 11 for VN 502aaddf-2820-4de2-8531-d139233f348a
2026-06-23 03:01:06,683 - INFO - Verifications in API Server for VN ctest-mgmt_vn-41888915 passed
2026-06-23 03:01:06,683 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/502aaddf-2820-4de2-8531-d139233f348a
2026-06-23 03:01:06,690 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/32434f38-5de8-4d92-9306-97bbbcde29fb
2026-06-23 03:01:06,695 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/32434f38-5de8-4d92-9306-97bbbcde29fb
2026-06-23 03:01:06,700 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/3c723809-0725-47a1-aadb-9acb4f63f892
2026-06-23 03:01:06,711 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-39336828:ctest-mgmt_vn-41888915', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-39336828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '5776620646018600418', 'uuid-lslong': '9597682324237923466'}, 'enable': 'true', 'created': '2026-06-23T03:01:05', 'last-modified': '2026-06-23T03:01:06', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.682127'}
2026-06-23 03:01:06,716 - DEBUG - Route Targets: ['target:64512:8000004']
2026-06-23 03:01:06,716 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-41888915 passed
2026-06-23 03:01:06,716 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-41888915 in API_Server ======
2026-06-23 03:01:06,716 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-23 03:01:06,721 - DEBUG - Requesting: http://10.0.0.50:8082/domain/63e6debd-e1b5-484d-ab89-80f9428c6f51
2026-06-23 03:01:06,729 - DEBUG - Requesting: http://10.0.0.50:8082/project/e17f1860-c249-4236-8ae1-d3415dca927d
2026-06-23 03:01:06,798 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/502aaddf-2820-4de2-8531-d139233f348a
2026-06-23 03:01:06,805 - DEBUG - =>VN ctest-mgmt_vn-41888915 has no policy to be verified
2026-06-23 03:01:06,805 - DEBUG - Verifying the vn in opserver
2026-06-23 03:01:06,805 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-39336828:ctest-mgmt_vn-41888915 virtual network link through opserver 10.0.0.50
2026-06-23 03:01:06,805 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-06-23 03:01:07,117 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-06-23 03:01:07,159 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-39336828:ctest-mgmt_vn-41888915', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-39336828:ctest-mgmt_vn-41888915?flat'}
2026-06-23 03:01:07,159 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-39336828:ctest-mgmt_vn-41888915 is found in opserver
2026-06-23 03:01:07,167 - DEBUG - Do not have enough data to verify VN in agent
2026-06-23 03:01:07,171 - DEBUG - VRF ids for VN ctest-mgmt_vn-41888915: {}
2026-06-23 03:01:07,318 - DEBUG - Response for create_network : {'network': {'id': 'b64e0938-1744-430a-9d76-86ecb4e47c0e', 'name': 'ctest-left_vn-73645960', 'tenant_id': 'e17f1860c24942368ae1d3415dca927d', 'project_id': 'e17f1860c24942368ae1d3415dca927d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-39336828', 'ctest-left_vn-73645960'], 'port_security_enabled': True, 'description': ''}}
2026-06-23 03:01:07,474 - DEBUG - Response for create_subnet : {'subnet': {'id': '46d62b65-dd32-4c38-8cb1-9019f1d318bd', 'name': '', 'tenant_id': 'e17f1860c24942368ae1d3415dca927d', 'network_id': 'b64e0938-1744-430a-9d76-86ecb4e47c0e', 'ip_version': 4, 'cidr': '106.79.71.64/26', 'allocation_pools': [{'start': '106.79.71.66', 'end': '106.79.71.126'}], 'gateway_ip': '106.79.71.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '106.79.71.66', 'tags': [], 'project_id': 'e17f1860c24942368ae1d3415dca927d'}}
2026-06-23 03:01:07,498 - INFO - Created VN ctest-left_vn-73645960
2026-06-23 03:01:07,548 - DEBUG - VN ctest-left_vn-73645960 UUID is b64e0938-1744-430a-9d76-86ecb4e47c0e
2026-06-23 03:01:07,660 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-23 03:01:07,666 - DEBUG - Requesting: http://10.0.0.50:8082/domain/63e6debd-e1b5-484d-ab89-80f9428c6f51
2026-06-23 03:01:07,676 - DEBUG - Requesting: http://10.0.0.50:8082/project/e17f1860-c249-4236-8ae1-d3415dca927d
2026-06-23 03:01:07,742 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/b64e0938-1744-430a-9d76-86ecb4e47c0e
2026-06-23 03:01:07,750 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/b64e0938-1744-430a-9d76-86ecb4e47c0e
2026-06-23 03:01:07,758 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/495ca9b1-aac9-41e1-aa81-78dae71c4ccd
2026-06-23 03:01:07,763 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/495ca9b1-aac9-41e1-aa81-78dae71c4ccd
2026-06-23 03:01:07,767 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/573cbc04-3edd-4224-930c-48612e25f0bf
2026-06-23 03:01:07,772 - DEBUG - Route Targets: ['target:64512:8000005']
2026-06-23 03:01:07,772 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/b64e0938-1744-430a-9d76-86ecb4e47c0e
2026-06-23 03:01:07,778 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/495ca9b1-aac9-41e1-aa81-78dae71c4ccd
2026-06-23 03:01:07,788 - INFO - Verified VN network id 12 for VN b64e0938-1744-430a-9d76-86ecb4e47c0e
2026-06-23 03:01:07,788 - INFO - Verifications in API Server for VN ctest-left_vn-73645960 passed
2026-06-23 03:01:07,788 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/b64e0938-1744-430a-9d76-86ecb4e47c0e
2026-06-23 03:01:07,795 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/495ca9b1-aac9-41e1-aa81-78dae71c4ccd
2026-06-23 03:01:07,800 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/495ca9b1-aac9-41e1-aa81-78dae71c4ccd
2026-06-23 03:01:07,805 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/573cbc04-3edd-4224-930c-48612e25f0bf
2026-06-23 03:01:07,813 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-39336828:ctest-left_vn-73645960', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-39336828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '13136447299599483658', 'uuid-lslong': '11346404662411820046'}, 'enable': 'true', 'created': '2026-06-23T03:01:07', 'last-modified': '2026-06-23T03:01:07', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.366502'}
2026-06-23 03:01:07,817 - DEBUG - Route Targets: ['target:64512:8000005']
2026-06-23 03:01:07,817 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-73645960 passed
2026-06-23 03:01:07,817 - DEBUG - ====Verifying policy data for ctest-left_vn-73645960 in API_Server ======
2026-06-23 03:01:07,817 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-23 03:01:07,821 - DEBUG - Requesting: http://10.0.0.50:8082/domain/63e6debd-e1b5-484d-ab89-80f9428c6f51
2026-06-23 03:01:07,829 - DEBUG - Requesting: http://10.0.0.50:8082/project/e17f1860-c249-4236-8ae1-d3415dca927d
2026-06-23 03:01:07,891 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/b64e0938-1744-430a-9d76-86ecb4e47c0e
2026-06-23 03:01:07,898 - DEBUG - =>VN ctest-left_vn-73645960 has no policy to be verified
2026-06-23 03:01:07,899 - DEBUG - Verifying the vn in opserver
2026-06-23 03:01:07,899 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-39336828:ctest-left_vn-73645960 virtual network link through opserver 10.0.0.50
2026-06-23 03:01:07,899 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-06-23 03:01:07,907 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-39336828:ctest-left_vn-73645960', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-39336828:ctest-left_vn-73645960?flat'}
2026-06-23 03:01:07,907 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-39336828:ctest-left_vn-73645960 is found in opserver
2026-06-23 03:01:07,914 - DEBUG - Do not have enough data to verify VN in agent
2026-06-23 03:01:07,918 - DEBUG - VRF ids for VN ctest-left_vn-73645960: {}
2026-06-23 03:01:08,071 - DEBUG - Response for create_network : {'network': {'id': '07a00ef2-4263-4a65-97f8-05d499513549', 'name': 'ctest-right_vn-83443813', 'tenant_id': 'e17f1860c24942368ae1d3415dca927d', 'project_id': 'e17f1860c24942368ae1d3415dca927d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-39336828', 'ctest-right_vn-83443813'], 'port_security_enabled': True, 'description': ''}}
2026-06-23 03:01:08,236 - DEBUG - Response for create_subnet : {'subnet': {'id': 'cfa39e23-b919-49bd-8ed1-5a77c371c1ea', 'name': '', 'tenant_id': 'e17f1860c24942368ae1d3415dca927d', 'network_id': '07a00ef2-4263-4a65-97f8-05d499513549', 'ip_version': 4, 'cidr': '30.252.148.128/26', 'allocation_pools': [{'start': '30.252.148.130', 'end': '30.252.148.190'}], 'gateway_ip': '30.252.148.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '30.252.148.130', 'tags': [], 'project_id': 'e17f1860c24942368ae1d3415dca927d'}}
2026-06-23 03:01:08,248 - INFO - Created VN ctest-right_vn-83443813
2026-06-23 03:01:08,303 - DEBUG - VN ctest-right_vn-83443813 UUID is 07a00ef2-4263-4a65-97f8-05d499513549
2026-06-23 03:01:08,392 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-23 03:01:08,396 - DEBUG - Requesting: http://10.0.0.50:8082/domain/63e6debd-e1b5-484d-ab89-80f9428c6f51
2026-06-23 03:01:08,405 - DEBUG - Requesting: http://10.0.0.50:8082/project/e17f1860-c249-4236-8ae1-d3415dca927d
2026-06-23 03:01:08,469 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/07a00ef2-4263-4a65-97f8-05d499513549
2026-06-23 03:01:08,477 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/07a00ef2-4263-4a65-97f8-05d499513549
2026-06-23 03:01:08,484 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c72da431-bb47-477b-b212-55a8860a25c0
2026-06-23 03:01:08,489 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c72da431-bb47-477b-b212-55a8860a25c0
2026-06-23 03:01:08,494 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/b0ff821a-0682-489a-ac1d-1e367874227e
2026-06-23 03:01:08,498 - DEBUG - Route Targets: ['target:64512:8000006']
2026-06-23 03:01:08,498 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/07a00ef2-4263-4a65-97f8-05d499513549
2026-06-23 03:01:08,505 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c72da431-bb47-477b-b212-55a8860a25c0
2026-06-23 03:01:08,515 - INFO - Verified VN network id 13 for VN 07a00ef2-4263-4a65-97f8-05d499513549
2026-06-23 03:01:08,515 - INFO - Verifications in API Server for VN ctest-right_vn-83443813 passed
2026-06-23 03:01:08,515 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/07a00ef2-4263-4a65-97f8-05d499513549
2026-06-23 03:01:08,521 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c72da431-bb47-477b-b212-55a8860a25c0
2026-06-23 03:01:08,526 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c72da431-bb47-477b-b212-55a8860a25c0
2026-06-23 03:01:08,530 - DEBUG - Requesting: http://10.0.0.50:8082/route-target/b0ff821a-0682-489a-ac1d-1e367874227e
2026-06-23 03:01:08,539 - DEBUG - Control-node 10.0.0.50 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-39336828:ctest-right_vn-83443813', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-39336828', 'owner-access': '7', 'group': 'admin', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '549455588197878373', 'uuid-lslong': '10950508904614802761'}, 'enable': 'true', 'created': '2026-06-23T03:01:08', 'last-modified': '2026-06-23T03:01:08', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.332863'}
2026-06-23 03:01:08,543 - DEBUG - Route Targets: ['target:64512:8000006']
2026-06-23 03:01:08,543 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-83443813 passed
2026-06-23 03:01:08,544 - DEBUG - ====Verifying policy data for ctest-right_vn-83443813 in API_Server ======
2026-06-23 03:01:08,544 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-23 03:01:08,548 - DEBUG - Requesting: http://10.0.0.50:8082/domain/63e6debd-e1b5-484d-ab89-80f9428c6f51
2026-06-23 03:01:08,557 - DEBUG - Requesting: http://10.0.0.50:8082/project/e17f1860-c249-4236-8ae1-d3415dca927d
2026-06-23 03:01:08,629 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-network/07a00ef2-4263-4a65-97f8-05d499513549
2026-06-23 03:01:08,637 - DEBUG - =>VN ctest-right_vn-83443813 has no policy to be verified
2026-06-23 03:01:08,637 - DEBUG - Verifying the vn in opserver
2026-06-23 03:01:08,637 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-39336828:ctest-right_vn-83443813 virtual network link through opserver 10.0.0.50
2026-06-23 03:01:08,637 - DEBUG - Requesting: http://10.0.0.50:8081/analytics/uves/virtual-networks
2026-06-23 03:01:08,645 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-39336828:ctest-right_vn-83443813', 'href': 'http://10.0.0.50:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-39336828:ctest-right_vn-83443813?flat'}
2026-06-23 03:01:08,645 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-39336828:ctest-right_vn-83443813 is found in opserver
2026-06-23 03:01:08,653 - DEBUG - Do not have enough data to verify VN in agent
2026-06-23 03:01:08,657 - DEBUG - VRF ids for VN ctest-right_vn-83443813: {}
2026-06-23 03:01:08,703 - DEBUG - Services list from nova: [, , ]
2026-06-23 03:01:09,711 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6023-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6023-1)
2026-06-23 03:01:11,058 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6023-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6023-1)
2026-06-23 03:01:11,058 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-86682776']
2026-06-23 03:01:11,415 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-86682776'] created succesfully
2026-06-23 03:01:11,651 - DEBUG - Image by name cirros_in_net not found
2026-06-23 03:01:11,651 - DEBUG - Installing image cirros_in_net
2026-06-23 03:01:11,651 - 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-06-23 03:01:11,651 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz
2026-06-23 03:01:11,651 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz
2026-06-23 03:01:11,664 - 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-06-23 03:01:11,760 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz
2026-06-23 03:01:11,761 - DEBUG - Unzip image
2026-06-23 03:01:11,761 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz
2026-06-23 03:01:11,900 - DEBUG - Try to create image in user context
2026-06-23 03:01:12,113 - DEBUG - Image has been created in user context
2026-06-23 03:01:12,113 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk
2026-06-23 03:01:12,128 - DEBUG - Downloaded image has been removed
2026-06-23 03:01:12,140 - DEBUG - Uploaded image status = active
2026-06-23 03:01:13,128 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6023-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6023-1)
2026-06-23 03:01:13,128 - INFO - Waiting for VM ctest-pt_svm0-44974866 to be up..
2026-06-23 03:01:13,158 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-23 03:01:18,250 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-23 03:01:23,326 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-23 03:01:28,404 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-23 03:01:33,474 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-23 03:01:38,549 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-23 03:01:43,625 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-23 03:01:48,699 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-23 03:01:53,777 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-23 03:01:58,853 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-23 03:02:03,940 - DEBUG - VM is in ACTIVE state now
2026-06-23 03:02:03,941 - INFO - VM name : ctest-pt_svm0-44974866
2026-06-23 03:02:04,024 - DEBUG - VM ctest-pt_svm0-44974866 ID is bc301e31-0b22-44fc-84ae-57bd364cb2bd
2026-06-23 03:02:04,045 - DEBUG - VM ctest-pt_svm0-44974866 launched on Node cn-jenkins-deploy-platform-ansible-os-6023-1
2026-06-23 03:02:04,282 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/bc301e31-0b22-44fc-84ae-57bd364cb2bd
2026-06-23 03:02:04,292 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/4294b52f-fdd4-44ba-89ac-181517bd6a21
2026-06-23 03:02:04,300 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/b333f5e2-92a7-4f48-baf0-ff4b84e8c477
2026-06-23 03:02:04,308 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/e9f9eb83-f921-4360-bff2-ba7cd4414083
2026-06-23 03:02:07,592 - 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-06-23 03:02:07,593 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-44974866 failed!
2026-06-23 03:02:07,651 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-39336828:ctest-mgmt_vn-41888915 is 52.191.105.65 and allocation pool is NOT set
2026-06-23 03:02:11,719 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms')
2026-06-23 03:02:11,720 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-44974866 failed!
2026-06-23 03:02:11,775 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-39336828:ctest-mgmt_vn-41888915 is 52.191.105.65 and allocation pool is NOT set
2026-06-23 03:02:15,849 - 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-06-23 03:02:15,849 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-44974866 failed!
2026-06-23 03:02:15,903 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-39336828:ctest-mgmt_vn-41888915 is 52.191.105.65 and allocation pool is NOT set
2026-06-23 03:02:17,969 - 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=5.47 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.872 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.872/3.170/5.468/2.298 ms')
2026-06-23 03:02:17,969 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-44974866 passed
2026-06-23 03:02:18,119 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:18,119 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-44974866, IP 52.191.105.67, Port 22
2026-06-23 03:02:18,185 - 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-06-23 03:02:18,272 - DEBUG - VM ctest-pt_svm0-44974866 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-23 03:02:23,272 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:23,273 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-44974866, IP 52.191.105.67, Port 22
2026-06-23 03:02:23,330 - 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-06-23 03:02:23,412 - DEBUG - VM ctest-pt_svm0-44974866 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-23 03:02:28,412 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:28,413 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-44974866, IP 52.191.105.67, Port 22
2026-06-23 03:02:28,474 - 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-06-23 03:02:28,545 - DEBUG - VM ctest-pt_svm0-44974866 is NOT ready for SSH connections, VM status: ACTIVE
2026-06-23 03:02:33,545 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:33,546 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-44974866, IP 52.191.105.67, Port 22
2026-06-23 03:02:33,703 - DEBUG - VM ctest-pt_svm0-44974866 is ready for SSH connections
2026-06-23 03:02:33,855 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:33,855 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-44974866, IP 52.191.105.67, Port 22
2026-06-23 03:02:34,010 - DEBUG - VM ctest-pt_svm0-44974866 is ready for SSH connections
2026-06-23 03:02:34,163 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:34,163 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-44974866, IP 52.191.105.67, Port 22
2026-06-23 03:02:34,318 - DEBUG - VM ctest-pt_svm0-44974866 is ready for SSH connections
2026-06-23 03:02:34,324 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSvcRegr-39336828', 'ctest-si-54618833']
2026-06-23 03:02:35,191 - INFO - Waiting for VM ctest-left_vm-39124851 to be up..
2026-06-23 03:02:35,255 - DEBUG - VM is in ACTIVE state now
2026-06-23 03:02:35,255 - INFO - VM name : ctest-left_vm-39124851
2026-06-23 03:02:35,328 - DEBUG - VM ctest-left_vm-39124851 ID is 81a1f224-1855-47d6-b944-eecede89a14c
2026-06-23 03:02:35,328 - DEBUG - VM ctest-left_vm-39124851 launched on Node cn-jenkins-deploy-platform-ansible-os-6023-1
2026-06-23 03:02:35,393 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/81a1f224-1855-47d6-b944-eecede89a14c
2026-06-23 03:02:35,403 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/dabc6645-5002-4b6b-93ef-23d91b85ab30
2026-06-23 03:02:36,635 - 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.12 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.403 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.403/1.760/3.117/1.357 ms')
2026-06-23 03:02:36,635 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-39124851 passed
2026-06-23 03:02:36,795 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:36,795 - DEBUG - Waiting to SSH to VM ctest-left_vm-39124851, IP 106.79.71.67, Port 22
2026-06-23 03:02:36,958 - DEBUG - VM ctest-left_vm-39124851 is ready for SSH connections
2026-06-23 03:02:36,958 - INFO - Waiting for VM ctest-right_vm-53155094 to be up..
2026-06-23 03:02:37,025 - DEBUG - VM is in ACTIVE state now
2026-06-23 03:02:37,025 - INFO - VM name : ctest-right_vm-53155094
2026-06-23 03:02:37,099 - DEBUG - VM ctest-right_vm-53155094 ID is 9ea7edae-fd5f-483d-b3ed-82863d786391
2026-06-23 03:02:37,099 - DEBUG - VM ctest-right_vm-53155094 launched on Node cn-jenkins-deploy-platform-ansible-os-6023-1
2026-06-23 03:02:37,175 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/9ea7edae-fd5f-483d-b3ed-82863d786391
2026-06-23 03:02:37,184 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/2c8d32a7-810d-4779-8bfc-32d6bc52501a
2026-06-23 03:02:38,418 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=3.91 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.417 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.417/2.161/3.906/1.744 ms')
2026-06-23 03:02:38,418 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-53155094 passed
2026-06-23 03:02:38,571 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:38,571 - DEBUG - Waiting to SSH to VM ctest-right_vm-53155094, IP 30.252.148.131, Port 22
2026-06-23 03:02:38,735 - DEBUG - VM ctest-right_vm-53155094 is ready for SSH connections
2026-06-23 03:02:38,740 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-39336828:ctest-left_vn-73645960, 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-39336828:ctest-right_vn-83443813, 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-39336828:ctest-si-54618833'], 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-06-23 03:02:39,159 - INFO - Attaching policy ctest-policy-50268726 to vn ctest-left_vn-73645960
2026-06-23 03:02:39,675 - INFO - Attaching policy ctest-policy-50268726 to vn ctest-right_vn-83443813
2026-06-23 03:02:40,111 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-86682776'] created succesfully
2026-06-23 03:02:40,331 - DEBUG - Service instance: ['default-domain', 'ctest-TestSvcRegr-39336828', 'ctest-si-54618833'] created succesfully
2026-06-23 03:02:40,331 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-23 03:02:40,337 - DEBUG - Requesting: http://10.0.0.50:8082/domain/63e6debd-e1b5-484d-ab89-80f9428c6f51
2026-06-23 03:02:40,348 - DEBUG - Requesting: http://10.0.0.50:8082/project/e17f1860-c249-4236-8ae1-d3415dca927d
2026-06-23 03:02:40,418 - DEBUG - Requesting: http://10.0.0.50:8082/service-instance/2166e5d3-fb51-43ca-9bee-9df159d98749
2026-06-23 03:02:40,435 - DEBUG - SI 'ctest-si-54618833' has service template ref to ctest-service_template_1-86682776
2026-06-23 03:02:40,436 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-23 03:02:40,441 - DEBUG - Requesting: http://10.0.0.50:8082/domain/63e6debd-e1b5-484d-ab89-80f9428c6f51
2026-06-23 03:02:40,463 - DEBUG - Requesting: http://10.0.0.50:8082/project/e17f1860-c249-4236-8ae1-d3415dca927d
2026-06-23 03:02:40,547 - DEBUG - Requesting: http://10.0.0.50:8082/service-instance/2166e5d3-fb51-43ca-9bee-9df159d98749
2026-06-23 03:02:40,556 - DEBUG - Requesting: http://10.0.0.50:8082/port-tuple/3315dc5d-ea94-4a88-ac66-9ac14594c800
2026-06-23 03:02:40,572 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/4294b52f-fdd4-44ba-89ac-181517bd6a21
2026-06-23 03:02:40,586 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/b333f5e2-92a7-4f48-baf0-ff4b84e8c477
2026-06-23 03:02:40,596 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/e9f9eb83-f921-4360-bff2-ba7cd4414083
2026-06-23 03:02:40,788 - DEBUG - VM is in ACTIVE state now
2026-06-23 03:02:41,159 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/bc301e31-0b22-44fc-84ae-57bd364cb2bd
2026-06-23 03:02:41,167 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/4294b52f-fdd4-44ba-89ac-181517bd6a21
2026-06-23 03:02:41,179 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/b333f5e2-92a7-4f48-baf0-ff4b84e8c477
2026-06-23 03:02:41,187 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/e9f9eb83-f921-4360-bff2-ba7cd4414083
2026-06-23 03:02:41,196 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/2e1f647b-a241-45c5-b14a-08fab06a3351
2026-06-23 03:02:41,203 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/83934d40-69ec-4d6a-a233-3c19cab8ae6e
2026-06-23 03:02:41,208 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/b2798da3-3323-4fc3-92d5-f60b3971d956
2026-06-23 03:02:41,214 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/25d4ccd4-fa8a-4efc-b1eb-3c43f807a67c
2026-06-23 03:02:41,219 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/c04ea998-886e-4125-a7b1-3f1c167a9774
2026-06-23 03:02:41,224 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/fe967b6e-3cc1-4aff-b771-8830ec24fb95
2026-06-23 03:02:41,232 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/301d5653-5116-47bd-ace3-78f2c1651991
2026-06-23 03:02:41,239 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/5528fbb5-ec5e-4020-ad73-810cf6995a1a
2026-06-23 03:02:41,245 - DEBUG - Requesting: http://10.0.0.50:8082/instance-ip/954f97fc-c612-4220-8054-fc62a6b6b154
2026-06-23 03:02:41,622 - DEBUG - VM is in ACTIVE state now
2026-06-23 03:02:41,623 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/bc301e31-0b22-44fc-84ae-57bd364cb2bd
2026-06-23 03:02:41,629 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/4294b52f-fdd4-44ba-89ac-181517bd6a21
2026-06-23 03:02:41,637 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/b333f5e2-92a7-4f48-baf0-ff4b84e8c477
2026-06-23 03:02:41,649 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/e9f9eb83-f921-4360-bff2-ba7cd4414083
2026-06-23 03:02:42,894 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n64 bytes from 169.254.0.6: icmp_seq=1 ttl=63 time=0.996 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.519 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.519/0.757/0.996/0.238 ms')
2026-06-23 03:02:42,894 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-44974866 passed
2026-06-23 03:02:42,926 - DEBUG - VM ctest-pt_svm0-44974866 Tap interface: {'index': '6', 'name': 'tapb333f5e2-92', 'uuid': 'b333f5e2-92a7-4f48-baf0-ff4b84e8c477', 'vrf_name': 'default-domain:ctest-TestSvcRegr-39336828:ctest-left_vn-73645960:ctest-left_vn-73645960', '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-39336828:ctest-left_vn-73645960', 'vm_uuid': 'bc301e31-0b22-44fc-84ae-57bd364cb2bd', 'vm_name': 'ctest-pt_svm0-44974866', 'ip_addr': '106.79.71.68', 'mac_addr': '02:b3:33:f5:e2:92', '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-39336828:b333f5e2-92a7-4f48-baf0-ff4b84e8c477', 'sg_uuid_list': ['a2687282-9f06-4bb8-b066-9574489604ba'], 'static_route_list': None, 'vm_project_uuid': 'e17f1860-c249-4236-8ae1-d3415dca927d', 'admin_state': 'Enabled', 'flow_key_idx': '30', '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': '4294b52f-fdd4-44ba-89ac-181517bd6a21', '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-06-23 03:02:42,926 - DEBUG - Agent 10.0.0.50 vrf name: default-domain:ctest-TestSvcRegr-39336828:ctest-left_vn-73645960:ctest-left_vn-73645960
2026-06-23 03:02:42,931 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-39336828:ctest-left_vn-73645960:ctest-left_vn-73645960', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestSvcRegr-39336828:ctest-left_vn-73645960', '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-06-23 03:02:43,089 - DEBUG - VM ctest-pt_svm0-44974866 : Tap interface tapb333f5e2-92 is set to Active
2026-06-23 03:02:43,090 - DEBUG - Tap interface tapb333f5e2-92 detail : {'index': '6', 'name': 'tapb333f5e2-92', 'uuid': 'b333f5e2-92a7-4f48-baf0-ff4b84e8c477', 'vrf_name': 'default-domain:ctest-TestSvcRegr-39336828:ctest-left_vn-73645960:ctest-left_vn-73645960', '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-39336828:ctest-left_vn-73645960', 'vm_uuid': 'bc301e31-0b22-44fc-84ae-57bd364cb2bd', 'vm_name': 'ctest-pt_svm0-44974866', 'ip_addr': '106.79.71.68', 'mac_addr': '02:b3:33:f5:e2:92', '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-39336828:b333f5e2-92a7-4f48-baf0-ff4b84e8c477', 'sg_uuid_list': ['a2687282-9f06-4bb8-b066-9574489604ba'], 'static_route_list': None, 'vm_project_uuid': 'e17f1860-c249-4236-8ae1-d3415dca927d', 'admin_state': 'Enabled', 'flow_key_idx': '30', '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': '4294b52f-fdd4-44ba-89ac-181517bd6a21', '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-06-23 03:02:43,279 - DEBUG - Starting Layer 2 verification in Agent
2026-06-23 03:02:43,289 - DEBUG - Layer 2 path is seen for VM MAC 02:b3:33:f5:e2:92 in agent 10.0.0.50
2026-06-23 03:02:43,289 - DEBUG - Active layer 2 route in agent is present for VMI tapb333f5e2-92
2026-06-23 03:02:43,289 - DEBUG - L2 label(48) matches bw route table and interface table
2026-06-23 03:02:43,294 - DEBUG - VN default-domain:ctest-TestSvcRegr-39336828:ctest-left_vn-73645960 is found in Agent of node 10.0.0.50
2026-06-23 03:02:43,501 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-39336828:ctest-left_vn-73645960 is consistent in agent 10.0.0.50
2026-06-23 03:02:43,502 - DEBUG - Route for VM IP ['106.79.71.68'] is consistent in agent 10.0.0.50
2026-06-23 03:02:43,502 - DEBUG - VN default-domain:ctest-TestSvcRegr-39336828:ctest-left_vn-73645960 verification for VM ctest-pt_svm0-44974866 in Agent 10.0.0.50 passed
2026-06-23 03:02:43,691 - DEBUG - Starting all layer 2 verification in agent 10.0.0.50
2026-06-23 03:02:43,702 - DEBUG - Route for VM MAC 02:b3:33:f5:e2:92 is consistent in agent 10.0.0.50
2026-06-23 03:02:43,720 - DEBUG - VM ctest-pt_svm0-44974866 Tap interface: {'index': '5', 'name': 'tape9f9eb83-f9', 'uuid': 'e9f9eb83-f921-4360-bff2-ba7cd4414083', 'vrf_name': 'default-domain:ctest-TestSvcRegr-39336828:ctest-mgmt_vn-41888915:ctest-mgmt_vn-41888915', '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-39336828:ctest-mgmt_vn-41888915', 'vm_uuid': 'bc301e31-0b22-44fc-84ae-57bd364cb2bd', 'vm_name': 'ctest-pt_svm0-44974866', 'ip_addr': '52.191.105.67', 'mac_addr': '02:e9:f9:eb:83:f9', '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-39336828:e9f9eb83-f921-4360-bff2-ba7cd4414083', 'sg_uuid_list': ['a2687282-9f06-4bb8-b066-9574489604ba'], 'static_route_list': None, 'vm_project_uuid': 'e17f1860-c249-4236-8ae1-d3415dca927d', 'admin_state': 'Enabled', 'flow_key_idx': '26', '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-06-23 03:02:43,720 - DEBUG - Agent 10.0.0.50 vrf name: default-domain:ctest-TestSvcRegr-39336828:ctest-mgmt_vn-41888915:ctest-mgmt_vn-41888915
2026-06-23 03:02:43,724 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-39336828:ctest-mgmt_vn-41888915:ctest-mgmt_vn-41888915', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSvcRegr-39336828:ctest-mgmt_vn-41888915', '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-06-23 03:02:43,924 - DEBUG - VM ctest-pt_svm0-44974866 : Tap interface tape9f9eb83-f9 is set to Active
2026-06-23 03:02:43,924 - DEBUG - Tap interface tape9f9eb83-f9 detail : {'index': '5', 'name': 'tape9f9eb83-f9', 'uuid': 'e9f9eb83-f921-4360-bff2-ba7cd4414083', 'vrf_name': 'default-domain:ctest-TestSvcRegr-39336828:ctest-mgmt_vn-41888915:ctest-mgmt_vn-41888915', '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-39336828:ctest-mgmt_vn-41888915', 'vm_uuid': 'bc301e31-0b22-44fc-84ae-57bd364cb2bd', 'vm_name': 'ctest-pt_svm0-44974866', 'ip_addr': '52.191.105.67', 'mac_addr': '02:e9:f9:eb:83:f9', '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-39336828:e9f9eb83-f921-4360-bff2-ba7cd4414083', 'sg_uuid_list': ['a2687282-9f06-4bb8-b066-9574489604ba'], 'static_route_list': None, 'vm_project_uuid': 'e17f1860-c249-4236-8ae1-d3415dca927d', 'admin_state': 'Enabled', 'flow_key_idx': '26', '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-06-23 03:02:44,127 - DEBUG - Starting Layer 2 verification in Agent
2026-06-23 03:02:44,136 - DEBUG - Layer 2 path is seen for VM MAC 02:e9:f9:eb:83:f9 in agent 10.0.0.50
2026-06-23 03:02:44,137 - DEBUG - Active layer 2 route in agent is present for VMI tape9f9eb83-f9
2026-06-23 03:02:44,137 - DEBUG - L2 label(43) matches bw route table and interface table
2026-06-23 03:02:44,142 - DEBUG - VN default-domain:ctest-TestSvcRegr-39336828:ctest-mgmt_vn-41888915 is found in Agent of node 10.0.0.50
2026-06-23 03:02:44,349 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-39336828:ctest-mgmt_vn-41888915 is consistent in agent 10.0.0.50
2026-06-23 03:02:44,349 - DEBUG - Route for VM IP ['52.191.105.67'] is consistent in agent 10.0.0.50
2026-06-23 03:02:44,349 - DEBUG - VN default-domain:ctest-TestSvcRegr-39336828:ctest-mgmt_vn-41888915 verification for VM ctest-pt_svm0-44974866 in Agent 10.0.0.50 passed
2026-06-23 03:02:44,539 - DEBUG - Starting all layer 2 verification in agent 10.0.0.50
2026-06-23 03:02:44,548 - DEBUG - Route for VM MAC 02:e9:f9:eb:83:f9 is consistent in agent 10.0.0.50
2026-06-23 03:02:44,566 - DEBUG - VM ctest-pt_svm0-44974866 Tap interface: {'index': '7', 'name': 'tap4294b52f-fd', 'uuid': '4294b52f-fdd4-44ba-89ac-181517bd6a21', 'vrf_name': 'default-domain:ctest-TestSvcRegr-39336828:ctest-right_vn-83443813:ctest-right_vn-83443813', '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-39336828:ctest-right_vn-83443813', 'vm_uuid': 'bc301e31-0b22-44fc-84ae-57bd364cb2bd', 'vm_name': 'ctest-pt_svm0-44974866', 'ip_addr': '30.252.148.132', 'mac_addr': '02:42:94:b5:2f:fd', '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-39336828:4294b52f-fdd4-44ba-89ac-181517bd6a21', 'sg_uuid_list': ['a2687282-9f06-4bb8-b066-9574489604ba'], 'static_route_list': None, 'vm_project_uuid': 'e17f1860-c249-4236-8ae1-d3415dca927d', 'admin_state': 'Enabled', 'flow_key_idx': '36', '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': 'b333f5e2-92a7-4f48-baf0-ff4b84e8c477', '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-06-23 03:02:44,566 - DEBUG - Agent 10.0.0.50 vrf name: default-domain:ctest-TestSvcRegr-39336828:ctest-right_vn-83443813:ctest-right_vn-83443813
2026-06-23 03:02:44,570 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-39336828:ctest-right_vn-83443813:ctest-right_vn-83443813', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSvcRegr-39336828:ctest-right_vn-83443813', '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-06-23 03:02:44,769 - DEBUG - VM ctest-pt_svm0-44974866 : Tap interface tap4294b52f-fd is set to Active
2026-06-23 03:02:44,769 - DEBUG - Tap interface tap4294b52f-fd detail : {'index': '7', 'name': 'tap4294b52f-fd', 'uuid': '4294b52f-fdd4-44ba-89ac-181517bd6a21', 'vrf_name': 'default-domain:ctest-TestSvcRegr-39336828:ctest-right_vn-83443813:ctest-right_vn-83443813', '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-39336828:ctest-right_vn-83443813', 'vm_uuid': 'bc301e31-0b22-44fc-84ae-57bd364cb2bd', 'vm_name': 'ctest-pt_svm0-44974866', 'ip_addr': '30.252.148.132', 'mac_addr': '02:42:94:b5:2f:fd', '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-39336828:4294b52f-fdd4-44ba-89ac-181517bd6a21', 'sg_uuid_list': ['a2687282-9f06-4bb8-b066-9574489604ba'], 'static_route_list': None, 'vm_project_uuid': 'e17f1860-c249-4236-8ae1-d3415dca927d', 'admin_state': 'Enabled', 'flow_key_idx': '36', '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': 'b333f5e2-92a7-4f48-baf0-ff4b84e8c477', '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-06-23 03:02:44,959 - DEBUG - Starting Layer 2 verification in Agent
2026-06-23 03:02:44,969 - DEBUG - Layer 2 path is seen for VM MAC 02:42:94:b5:2f:fd in agent 10.0.0.50
2026-06-23 03:02:44,969 - DEBUG - Active layer 2 route in agent is present for VMI tap4294b52f-fd
2026-06-23 03:02:44,969 - DEBUG - L2 label(53) matches bw route table and interface table
2026-06-23 03:02:44,973 - DEBUG - VN default-domain:ctest-TestSvcRegr-39336828:ctest-right_vn-83443813 is found in Agent of node 10.0.0.50
2026-06-23 03:02:45,183 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-39336828:ctest-right_vn-83443813 is consistent in agent 10.0.0.50
2026-06-23 03:02:45,184 - DEBUG - Route for VM IP ['30.252.148.132'] is consistent in agent 10.0.0.50
2026-06-23 03:02:45,184 - DEBUG - VN default-domain:ctest-TestSvcRegr-39336828:ctest-right_vn-83443813 verification for VM ctest-pt_svm0-44974866 in Agent 10.0.0.50 passed
2026-06-23 03:02:45,375 - DEBUG - Starting all layer 2 verification in agent 10.0.0.50
2026-06-23 03:02:45,385 - DEBUG - Route for VM MAC 02:42:94:b5:2f:fd is consistent in agent 10.0.0.50
2026-06-23 03:02:45,385 - INFO - VM ctest-pt_svm0-44974866 verifications in Compute nodes passed
2026-06-23 03:02:45,385 - DEBUG - The SVMs in the SI are : []
2026-06-23 03:02:45,385 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/bc301e31-0b22-44fc-84ae-57bd364cb2bd
2026-06-23 03:02:45,392 - DEBUG - Service VM for SI 'ctest-si-54618833' is launched
2026-06-23 03:02:45,392 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-23 03:02:45,398 - DEBUG - Requesting: http://10.0.0.50:8082/domain/63e6debd-e1b5-484d-ab89-80f9428c6f51
2026-06-23 03:02:45,410 - DEBUG - Requesting: http://10.0.0.50:8082/project/e17f1860-c249-4236-8ae1-d3415dca927d
2026-06-23 03:02:45,486 - DEBUG - Requesting: http://10.0.0.50:8082/service-instance/2166e5d3-fb51-43ca-9bee-9df159d98749
2026-06-23 03:02:45,498 - DEBUG - SI ctest-si-54618833 has Port Tuple: ['ctest-port_tuple-85885080']
2026-06-23 03:02:45,599 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-39336828:ctest-left_vn-73645960
2026-06-23 03:02:45,751 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-39336828:ctest-right_vn-83443813
2026-06-23 03:02:45,905 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:45,913 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:45,913 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:45,918 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:45,918 - INFO - Route to 30.252.148.131 seen in VRF:5 on cn-jenkins-deploy-platform-ansible-os-6023-1, and SI ctest-si-54618833 is seen as the NH
2026-06-23 03:02:46,074 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:46,081 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:46,081 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:46,087 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:46,087 - INFO - Route to 106.79.71.67 seen in VRF:6 on cn-jenkins-deploy-platform-ansible-os-6023-1, and SI ctest-si-54618833 is seen as the NH
2026-06-23 03:02:46,087 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:46,087 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-23 03:02:46,087 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 30.252.148.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2026-06-23 03:02:46,087 - DEBUG - ping -s 56 -c 3 -W 1 30.252.148.131
2026-06-23 03:02:49,960 - DEBUG - PING 30.252.148.131 (30.252.148.131): 56 data bytes
64 bytes from 30.252.148.131: seq=0 ttl=61 time=5.843 ms
64 bytes from 30.252.148.131: seq=1 ttl=61 time=1.765 ms
64 bytes from 30.252.148.131: seq=2 ttl=61 time=1.859 ms
--- 30.252.148.131 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.765/3.155/5.843 ms
2026-06-23 03:02:49,961 - INFO - Ping to IP 30.252.148.131 from VM ctest-left_vm-39124851 passed
2026-06-23 03:02:49,961 - INFO - Dettaching policy ctest-policy-50268726 from vn ctest-right_vn-83443813
2026-06-23 03:02:50,171 - INFO - Dettaching policy ctest-policy-50268726 from vn ctest-left_vn-73645960
2026-06-23 03:02:50,451 - INFO - Deleted policy ctest-policy-50268726
2026-06-23 03:02:50,452 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSvcRegr-39336828', 'ctest-si-54618833']
2026-06-23 03:02:50,541 - INFO - Deleted SI ['default-domain', 'ctest-TestSvcRegr-39336828', 'ctest-si-54618833']
2026-06-23 03:02:50,542 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-23 03:02:50,548 - DEBUG - Requesting: http://10.0.0.50:8082/domain/63e6debd-e1b5-484d-ab89-80f9428c6f51
2026-06-23 03:02:50,563 - DEBUG - Requesting: http://10.0.0.50:8082/project/e17f1860-c249-4236-8ae1-d3415dca927d
2026-06-23 03:02:50,631 - DEBUG - Service instance ctest-si-54618833 removed from api server
2026-06-23 03:02:50,631 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/bc301e31-0b22-44fc-84ae-57bd364cb2bd
2026-06-23 03:02:50,642 - DEBUG - All Service VMs unlinked from SI ctest-si-54618833
2026-06-23 03:02:50,646 - DEBUG - 0 svc intances found in all projects. They are []
2026-06-23 03:02:50,647 - DEBUG - 0 svc intances found in current project. They are []
2026-06-23 03:02:50,699 - DEBUG - 0 svc intances found in all projects. They are []
2026-06-23 03:02:50,699 - DEBUG - 0 svc intances found in current project. They are []
2026-06-23 03:02:50,699 - INFO - Deleting VM ctest-pt_svm0-44974866
2026-06-23 03:02:50,778 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-86682776']
2026-06-23 03:02:50,867 - INFO - Service template: ['default-domain', 'ctest-service_template_1-86682776'] deleted successfully.
2026-06-23 03:02:50,867 - INFO - Deleting VM ctest-right_vm-53155094
2026-06-23 03:02:50,968 - INFO - Deleting VM ctest-left_vm-39124851
2026-06-23 03:02:51,041 - INFO - Deleting VN ctest-right_vn-83443813
2026-06-23 03:02:51,072 - DEBUG - VN 07a00ef2-4263-4a65-97f8-05d499513549 still in use: Unable to complete operation on network 07a00ef2-4263-4a65-97f8-05d499513549. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-2b8ea807-cf9a-4147-9086-c6239d7eaaf9']
2026-06-23 03:02:51,072 - WARNING - Deleting VN ctest-right_vn-83443813 failed..Will retry
2026-06-23 03:02:53,114 - DEBUG - VN 07a00ef2-4263-4a65-97f8-05d499513549 still in use: Unable to complete operation on network 07a00ef2-4263-4a65-97f8-05d499513549. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-25520596-58dd-435f-9e68-de4db5266d49']
2026-06-23 03:02:53,114 - WARNING - Deleting VN ctest-right_vn-83443813 failed..Will retry
2026-06-23 03:02:55,201 - DEBUG - Response for deleting network ()
2026-06-23 03:02:55,201 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/c72da431-bb47-477b-b212-55a8860a25c0
2026-06-23 03:02:55,206 - DEBUG - Response Code: 404
2026-06-23 03:02:55,206 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-23 03:02:55,212 - DEBUG - Requesting: http://10.0.0.50:8082/domain/63e6debd-e1b5-484d-ab89-80f9428c6f51
2026-06-23 03:02:55,267 - DEBUG - Requesting: http://10.0.0.50:8082/project/e17f1860-c249-4236-8ae1-d3415dca927d
2026-06-23 03:02:55,338 - INFO - Validated that VN ctest-right_vn-83443813 is not found in API Server
2026-06-23 03:02:55,349 - DEBUG - VN ctest-right_vn-83443813 is not present in Agent 10.0.0.50
2026-06-23 03:02:55,349 - INFO - Validated that VN ctest-right_vn-83443813 is not in any agent
2026-06-23 03:02:55,365 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-83443813 info
2026-06-23 03:02:55,365 - INFO - Deleting VN ctest-left_vn-73645960
2026-06-23 03:02:55,450 - DEBUG - Response for deleting network ()
2026-06-23 03:02:55,450 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/495ca9b1-aac9-41e1-aa81-78dae71c4ccd
2026-06-23 03:02:55,455 - DEBUG - Response Code: 404
2026-06-23 03:02:55,455 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-23 03:02:55,460 - DEBUG - Requesting: http://10.0.0.50:8082/domain/63e6debd-e1b5-484d-ab89-80f9428c6f51
2026-06-23 03:02:55,516 - DEBUG - Requesting: http://10.0.0.50:8082/project/e17f1860-c249-4236-8ae1-d3415dca927d
2026-06-23 03:02:55,584 - INFO - Validated that VN ctest-left_vn-73645960 is not found in API Server
2026-06-23 03:02:55,593 - DEBUG - VN ctest-left_vn-73645960 is not present in Agent 10.0.0.50
2026-06-23 03:02:55,593 - INFO - Validated that VN ctest-left_vn-73645960 is not in any agent
2026-06-23 03:02:55,605 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-73645960 info
2026-06-23 03:02:55,605 - INFO - Deleting VN ctest-mgmt_vn-41888915
2026-06-23 03:02:55,717 - DEBUG - Response for deleting network ()
2026-06-23 03:02:55,717 - DEBUG - Requesting: http://10.0.0.50:8082/routing-instance/32434f38-5de8-4d92-9306-97bbbcde29fb
2026-06-23 03:02:55,722 - DEBUG - Response Code: 404
2026-06-23 03:02:55,722 - DEBUG - Requesting: http://10.0.0.50:8082/domains
2026-06-23 03:02:55,726 - DEBUG - Requesting: http://10.0.0.50:8082/domain/63e6debd-e1b5-484d-ab89-80f9428c6f51
2026-06-23 03:02:55,779 - DEBUG - Requesting: http://10.0.0.50:8082/project/e17f1860-c249-4236-8ae1-d3415dca927d
2026-06-23 03:02:55,844 - INFO - Validated that VN ctest-mgmt_vn-41888915 is not found in API Server
2026-06-23 03:02:55,853 - DEBUG - VN ctest-mgmt_vn-41888915 is not present in Agent 10.0.0.50
2026-06-23 03:02:55,853 - INFO - Validated that VN ctest-mgmt_vn-41888915 is not in any agent
2026-06-23 03:02:55,866 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-41888915 info
2026-06-23 03:02:56,124 - DEBUG - No XMPP flaps were noticed during the test
2026-06-23 03:02:56,124 - INFO -
2026-06-23 03:02:56,124 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:51]
2026-06-23 03:02:56,124 - INFO - --------------------------------------------------------------------------------
2026-06-23 03:02:56,833 - INFO - Deleted project: ctest-TestSvcRegr-39336828, ID : e17f1860-c249-4236-8ae1-d3415dca927d