2026-04-23 01:49:14,764 - INFO - Reading existing Domain with UUID 463c1a7f-4575-42ba-bd06-1a9f36cbf244 2026-04-23 01:49:14,773 - INFO - Using existing domain ['admin_domain'](463c1a7f-4575-42ba-bd06-1a9f36cbf244) 2026-04-23 01:49:14,977 - INFO - Project ctest-TestSvcRegr-64146939 not found, creating it 2026-04-23 01:49:15,445 - INFO - Created Project:ctest-TestSvcRegr-64146939, ID : e4a006b3-9b3d-4bbf-ad8d-946d54c83f16 2026-04-23 01:49:17,198 - INFO - ================================================================================ 2026-04-23 01:49:17,198 - INFO - STARTING TEST : test_svc_in_network_datapath 2026-04-23 01:49:17,584 - DEBUG - Nothing to compare xmpp stats {'10.0.0.129': {'10.0.0.129': '0'}} with 2026-04-23 01:49:17,584 - INFO - Initial checks done. Running the testcase now 2026-04-23 01:49:17,584 - INFO - 2026-04-23 01:49:18,436 - DEBUG - Response for create_network : {'network': {'id': '14ed1883-27ca-4ccc-8d42-47b91e5e2d83', 'name': 'ctest-mgmt_vn-96034284', 'tenant_id': 'e4a006b39b3d4bbfad8d946d54c83f16', 'project_id': 'e4a006b39b3d4bbfad8d946d54c83f16', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['admin_domain', 'ctest-TestSvcRegr-64146939', 'ctest-mgmt_vn-96034284'], 'port_security_enabled': True, 'description': '', 'created_at': None, 'updated_at': None}} 2026-04-23 01:49:18,639 - DEBUG - Response for create_subnet : {'subnet': {'id': '5631708a-11e7-440e-ab43-242a7fc523fb', 'name': '', 'tenant_id': 'e4a006b39b3d4bbfad8d946d54c83f16', 'network_id': '14ed1883-27ca-4ccc-8d42-47b91e5e2d83', 'ip_version': 4, 'cidr': '163.142.38.0/26', 'allocation_pools': [{'start': '163.142.38.2', 'end': '163.142.38.62'}], 'gateway_ip': '163.142.38.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'created_at': '2026-04-23T01:49:18.534016', 'updated_at': '2026-04-23T01:49:18.534016', 'dns_server_address': '163.142.38.2', 'tags': [], 'project_id': 'e4a006b39b3d4bbfad8d946d54c83f16'}} 2026-04-23 01:49:18,702 - INFO - Created VN ctest-mgmt_vn-96034284 2026-04-23 01:49:18,754 - DEBUG - VN ctest-mgmt_vn-96034284 UUID is 14ed1883-27ca-4ccc-8d42-47b91e5e2d83 2026-04-23 01:49:18,961 - DEBUG - Requesting: https://10.0.0.129:8082/domains 2026-04-23 01:49:19,323 - DEBUG - Requesting: https://10.0.0.129:8082/domains 2026-04-23 01:49:19,408 - DEBUG - Requesting: https://10.0.0.129:8082/domain/463c1a7f-4575-42ba-bd06-1a9f36cbf244 2026-04-23 01:49:19,475 - DEBUG - Requesting: https://10.0.0.129:8082/project/e4a006b3-9b3d-4bbf-ad8d-946d54c83f16 2026-04-23 01:49:19,551 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-network/14ed1883-27ca-4ccc-8d42-47b91e5e2d83 2026-04-23 01:49:19,609 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-network/14ed1883-27ca-4ccc-8d42-47b91e5e2d83 2026-04-23 01:49:19,668 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/55c47bc6-5a32-4b6a-b841-a919e72de8fc 2026-04-23 01:49:19,719 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/55c47bc6-5a32-4b6a-b841-a919e72de8fc 2026-04-23 01:49:19,771 - DEBUG - Requesting: https://10.0.0.129:8082/route-target/044fb171-ff3b-4f6d-9920-c849653f6d87 2026-04-23 01:49:19,823 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-04-23 01:49:19,823 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-network/14ed1883-27ca-4ccc-8d42-47b91e5e2d83 2026-04-23 01:49:19,880 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/55c47bc6-5a32-4b6a-b841-a919e72de8fc 2026-04-23 01:49:19,941 - INFO - Verified VN network id 11 for VN 14ed1883-27ca-4ccc-8d42-47b91e5e2d83 2026-04-23 01:49:19,942 - INFO - Verifications in API Server for VN ctest-mgmt_vn-96034284 passed 2026-04-23 01:49:19,942 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-network/14ed1883-27ca-4ccc-8d42-47b91e5e2d83 2026-04-23 01:49:19,996 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/55c47bc6-5a32-4b6a-b841-a919e72de8fc 2026-04-23 01:49:20,047 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/55c47bc6-5a32-4b6a-b841-a919e72de8fc 2026-04-23 01:49:20,103 - DEBUG - Requesting: https://10.0.0.129:8082/route-target/044fb171-ff3b-4f6d-9920-c849653f6d87 2026-04-23 01:49:20,203 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-64146939', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1507888401826335948', 'uuid-lslong': '10178776968214752643'}, 'enable': 'true', 'created': '2026-04-23T01:49:18', 'last-modified': '2026-04-23T01:49:18', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.601421'} 2026-04-23 01:49:20,257 - DEBUG - Route Targets: ['target:64512:8000004'] 2026-04-23 01:49:20,257 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-96034284 passed 2026-04-23 01:49:20,257 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-96034284 in API_Server ====== 2026-04-23 01:49:20,257 - DEBUG - Requesting: https://10.0.0.129:8082/domains 2026-04-23 01:49:20,311 - DEBUG - Requesting: https://10.0.0.129:8082/domain/463c1a7f-4575-42ba-bd06-1a9f36cbf244 2026-04-23 01:49:20,371 - DEBUG - Requesting: https://10.0.0.129:8082/project/e4a006b3-9b3d-4bbf-ad8d-946d54c83f16 2026-04-23 01:49:20,451 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-network/14ed1883-27ca-4ccc-8d42-47b91e5e2d83 2026-04-23 01:49:20,508 - DEBUG - =>VN ctest-mgmt_vn-96034284 has no policy to be verified 2026-04-23 01:49:20,508 - DEBUG - Verifying the vn in opserver 2026-04-23 01:49:20,508 - DEBUG - Verifying the admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284 virtual network link through opserver 10.0.0.129 2026-04-23 01:49:20,508 - DEBUG - Requesting: https://10.0.0.129:8081/analytics/uves/virtual-networks 2026-04-23 01:49:20,855 - DEBUG - Requesting: https://10.0.0.129:8081/analytics/uves/virtual-networks 2026-04-23 01:49:20,971 - DEBUG - vn link and name as {'name': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284', 'href': 'https://10.0.0.129:8081/analytics/uves/virtual-network/admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284?flat'} 2026-04-23 01:49:20,971 - INFO - Validated that VN admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284 is found in opserver 2026-04-23 01:49:21,075 - DEBUG - Do not have enough data to verify VN in agent 2026-04-23 01:49:21,127 - DEBUG - VRF ids for VN ctest-mgmt_vn-96034284: {} 2026-04-23 01:49:21,419 - DEBUG - Response for create_network : {'network': {'id': 'a95c2878-3292-4566-9e6c-824c8ae02727', 'name': 'ctest-left_vn-66053936', 'tenant_id': 'e4a006b39b3d4bbfad8d946d54c83f16', 'project_id': 'e4a006b39b3d4bbfad8d946d54c83f16', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['admin_domain', 'ctest-TestSvcRegr-64146939', 'ctest-left_vn-66053936'], 'port_security_enabled': True, 'description': '', 'created_at': None, 'updated_at': None}} 2026-04-23 01:49:21,659 - DEBUG - Response for create_subnet : {'subnet': {'id': '7e537629-2219-4f8f-a729-9048bc25322c', 'name': '', 'tenant_id': 'e4a006b39b3d4bbfad8d946d54c83f16', 'network_id': 'a95c2878-3292-4566-9e6c-824c8ae02727', 'ip_version': 4, 'cidr': '3.17.58.64/26', 'allocation_pools': [{'start': '3.17.58.66', 'end': '3.17.58.126'}], 'gateway_ip': '3.17.58.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'created_at': '2026-04-23T01:49:21.568218', 'updated_at': '2026-04-23T01:49:21.568218', 'dns_server_address': '3.17.58.66', 'tags': [], 'project_id': 'e4a006b39b3d4bbfad8d946d54c83f16'}} 2026-04-23 01:49:21,725 - INFO - Created VN ctest-left_vn-66053936 2026-04-23 01:49:21,782 - DEBUG - VN ctest-left_vn-66053936 UUID is a95c2878-3292-4566-9e6c-824c8ae02727 2026-04-23 01:49:21,977 - DEBUG - Requesting: https://10.0.0.129:8082/domains 2026-04-23 01:49:22,027 - DEBUG - Requesting: https://10.0.0.129:8082/domain/463c1a7f-4575-42ba-bd06-1a9f36cbf244 2026-04-23 01:49:22,083 - DEBUG - Requesting: https://10.0.0.129:8082/project/e4a006b3-9b3d-4bbf-ad8d-946d54c83f16 2026-04-23 01:49:22,160 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-network/a95c2878-3292-4566-9e6c-824c8ae02727 2026-04-23 01:49:22,222 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-network/a95c2878-3292-4566-9e6c-824c8ae02727 2026-04-23 01:49:22,276 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/842ee074-742c-4f8a-a85c-f45c0f7873f7 2026-04-23 01:49:22,327 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/842ee074-742c-4f8a-a85c-f45c0f7873f7 2026-04-23 01:49:22,379 - DEBUG - Requesting: https://10.0.0.129:8082/route-target/9b898c27-d1a1-4284-8939-855d0a6ecdf5 2026-04-23 01:49:22,431 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-04-23 01:49:22,432 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-network/a95c2878-3292-4566-9e6c-824c8ae02727 2026-04-23 01:49:22,496 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/842ee074-742c-4f8a-a85c-f45c0f7873f7 2026-04-23 01:49:22,560 - INFO - Verified VN network id 12 for VN a95c2878-3292-4566-9e6c-824c8ae02727 2026-04-23 01:49:22,560 - INFO - Verifications in API Server for VN ctest-left_vn-66053936 passed 2026-04-23 01:49:22,560 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-network/a95c2878-3292-4566-9e6c-824c8ae02727 2026-04-23 01:49:22,622 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/842ee074-742c-4f8a-a85c-f45c0f7873f7 2026-04-23 01:49:22,680 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/842ee074-742c-4f8a-a85c-f45c0f7873f7 2026-04-23 01:49:22,740 - DEBUG - Requesting: https://10.0.0.129:8082/route-target/9b898c27-d1a1-4284-8939-855d0a6ecdf5 2026-04-23 01:49:22,848 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-64146939', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12203673586976834918', 'uuid-lslong': '11415642420736436007'}, 'enable': 'true', 'created': '2026-04-23T01:49:21', 'last-modified': '2026-04-23T01:49:21', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.218371'} 2026-04-23 01:49:22,909 - DEBUG - Route Targets: ['target:64512:8000005'] 2026-04-23 01:49:22,909 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-66053936 passed 2026-04-23 01:49:22,909 - DEBUG - ====Verifying policy data for ctest-left_vn-66053936 in API_Server ====== 2026-04-23 01:49:22,909 - DEBUG - Requesting: https://10.0.0.129:8082/domains 2026-04-23 01:49:22,964 - DEBUG - Requesting: https://10.0.0.129:8082/domain/463c1a7f-4575-42ba-bd06-1a9f36cbf244 2026-04-23 01:49:23,023 - DEBUG - Requesting: https://10.0.0.129:8082/project/e4a006b3-9b3d-4bbf-ad8d-946d54c83f16 2026-04-23 01:49:23,147 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-network/a95c2878-3292-4566-9e6c-824c8ae02727 2026-04-23 01:49:23,213 - DEBUG - =>VN ctest-left_vn-66053936 has no policy to be verified 2026-04-23 01:49:23,213 - DEBUG - Verifying the vn in opserver 2026-04-23 01:49:23,213 - DEBUG - Verifying the admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936 virtual network link through opserver 10.0.0.129 2026-04-23 01:49:23,213 - DEBUG - Requesting: https://10.0.0.129:8081/analytics/uves/virtual-networks 2026-04-23 01:49:23,291 - DEBUG - vn link and name as {'name': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936', 'href': 'https://10.0.0.129:8081/analytics/uves/virtual-network/admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936?flat'} 2026-04-23 01:49:23,291 - INFO - Validated that VN admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936 is found in opserver 2026-04-23 01:49:23,406 - DEBUG - Do not have enough data to verify VN in agent 2026-04-23 01:49:23,463 - DEBUG - VRF ids for VN ctest-left_vn-66053936: {} 2026-04-23 01:49:23,945 - DEBUG - Response for create_network : {'network': {'id': 'b2429005-5dce-47db-80fa-fa95fe446d0b', 'name': 'ctest-right_vn-87273617', 'tenant_id': 'e4a006b39b3d4bbfad8d946d54c83f16', 'project_id': 'e4a006b39b3d4bbfad8d946d54c83f16', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['admin_domain', 'ctest-TestSvcRegr-64146939', 'ctest-right_vn-87273617'], 'port_security_enabled': True, 'description': '', 'created_at': None, 'updated_at': None}} 2026-04-23 01:49:24,303 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f123459e-cc8a-40b0-933a-732c3028571a', 'name': '', 'tenant_id': 'e4a006b39b3d4bbfad8d946d54c83f16', 'network_id': 'b2429005-5dce-47db-80fa-fa95fe446d0b', 'ip_version': 4, 'cidr': '89.239.240.0/26', 'allocation_pools': [{'start': '89.239.240.2', 'end': '89.239.240.62'}], 'gateway_ip': '89.239.240.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'created_at': '2026-04-23T01:49:24.096505', 'updated_at': '2026-04-23T01:49:24.096505', 'dns_server_address': '89.239.240.2', 'tags': [], 'project_id': 'e4a006b39b3d4bbfad8d946d54c83f16'}} 2026-04-23 01:49:24,381 - INFO - Created VN ctest-right_vn-87273617 2026-04-23 01:49:24,438 - DEBUG - VN ctest-right_vn-87273617 UUID is b2429005-5dce-47db-80fa-fa95fe446d0b 2026-04-23 01:49:24,697 - DEBUG - Requesting: https://10.0.0.129:8082/domains 2026-04-23 01:49:24,768 - DEBUG - Requesting: https://10.0.0.129:8082/domain/463c1a7f-4575-42ba-bd06-1a9f36cbf244 2026-04-23 01:49:24,836 - DEBUG - Requesting: https://10.0.0.129:8082/project/e4a006b3-9b3d-4bbf-ad8d-946d54c83f16 2026-04-23 01:49:24,968 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-network/b2429005-5dce-47db-80fa-fa95fe446d0b 2026-04-23 01:49:25,029 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-network/b2429005-5dce-47db-80fa-fa95fe446d0b 2026-04-23 01:49:25,084 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/d5f91f69-6b85-450c-95bd-5fdde91147b3 2026-04-23 01:49:25,143 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/d5f91f69-6b85-450c-95bd-5fdde91147b3 2026-04-23 01:49:25,203 - DEBUG - Requesting: https://10.0.0.129:8082/route-target/8c69a9f7-45a0-4b9e-a16e-d9f09a58632f 2026-04-23 01:49:25,267 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-04-23 01:49:25,267 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-network/b2429005-5dce-47db-80fa-fa95fe446d0b 2026-04-23 01:49:25,345 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/d5f91f69-6b85-450c-95bd-5fdde91147b3 2026-04-23 01:49:25,415 - INFO - Verified VN network id 13 for VN b2429005-5dce-47db-80fa-fa95fe446d0b 2026-04-23 01:49:25,416 - INFO - Verifications in API Server for VN ctest-right_vn-87273617 passed 2026-04-23 01:49:25,416 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-network/b2429005-5dce-47db-80fa-fa95fe446d0b 2026-04-23 01:49:25,476 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/d5f91f69-6b85-450c-95bd-5fdde91147b3 2026-04-23 01:49:25,532 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/d5f91f69-6b85-450c-95bd-5fdde91147b3 2026-04-23 01:49:25,587 - DEBUG - Requesting: https://10.0.0.129:8082/route-target/8c69a9f7-45a0-4b9e-a16e-d9f09a58632f 2026-04-23 01:49:25,717 - DEBUG - Control-node 10.0.0.129 : VN object is : {'node_name': 'virtual-network:admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-64146939', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12844987439937112027', 'uuid-lslong': '9294016303155408139'}, 'enable': 'true', 'created': '2026-04-23T01:49:23', 'last-modified': '2026-04-23T01:49:24', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:01.532567'} 2026-04-23 01:49:25,784 - DEBUG - Route Targets: ['target:64512:8000006'] 2026-04-23 01:49:25,785 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-87273617 passed 2026-04-23 01:49:25,785 - DEBUG - ====Verifying policy data for ctest-right_vn-87273617 in API_Server ====== 2026-04-23 01:49:25,785 - DEBUG - Requesting: https://10.0.0.129:8082/domains 2026-04-23 01:49:25,859 - DEBUG - Requesting: https://10.0.0.129:8082/domain/463c1a7f-4575-42ba-bd06-1a9f36cbf244 2026-04-23 01:49:25,920 - DEBUG - Requesting: https://10.0.0.129:8082/project/e4a006b3-9b3d-4bbf-ad8d-946d54c83f16 2026-04-23 01:49:26,044 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-network/b2429005-5dce-47db-80fa-fa95fe446d0b 2026-04-23 01:49:26,104 - DEBUG - =>VN ctest-right_vn-87273617 has no policy to be verified 2026-04-23 01:49:26,104 - DEBUG - Verifying the vn in opserver 2026-04-23 01:49:26,104 - DEBUG - Verifying the admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617 virtual network link through opserver 10.0.0.129 2026-04-23 01:49:26,104 - DEBUG - Requesting: https://10.0.0.129:8081/analytics/uves/virtual-networks 2026-04-23 01:49:26,163 - DEBUG - vn link and name as {'name': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617', 'href': 'https://10.0.0.129:8081/analytics/uves/virtual-network/admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617?flat'} 2026-04-23 01:49:26,163 - INFO - Validated that VN admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617 is found in opserver 2026-04-23 01:49:26,281 - DEBUG - Do not have enough data to verify VN in agent 2026-04-23 01:49:26,336 - DEBUG - VRF ids for VN ctest-right_vn-87273617: {} 2026-04-23 01:49:26,449 - DEBUG - Services list from nova: [, , ] 2026-04-23 01:49:27,911 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-juju-os-697-1), Zone: (nova:cn-jenkins-deploy-platform-juju-os-697-1) 2026-04-23 01:49:29,648 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-juju-os-697-1), Zone: (nova:cn-jenkins-deploy-platform-juju-os-697-1) 2026-04-23 01:49:29,650 - DEBUG - Creating service template: ['admin_domain', 'ctest-service_template_1-78073483'] 2026-04-23 01:49:30,034 - DEBUG - Service template: ['admin_domain', 'ctest-service_template_1-78073483'] created succesfully 2026-04-23 01:49:30,481 - DEBUG - Image by name cirros_in_net not found 2026-04-23 01:49:30,482 - DEBUG - Installing image cirros_in_net 2026-04-23 01:49:30,482 - 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-04-23 01:49:30,483 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2026-04-23 01:49:30,483 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2026-04-23 01:49:30,498 - 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-04-23 01:49:30,626 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz 2026-04-23 01:49:30,626 - DEBUG - Unzip image 2026-04-23 01:49:30,626 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz 2026-04-23 01:49:30,862 - DEBUG - Try to create image in user context 2026-04-23 01:49:32,093 - DEBUG - Image has been created in user context 2026-04-23 01:49:32,093 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk 2026-04-23 01:49:32,113 - DEBUG - Downloaded image has been removed 2026-04-23 01:49:32,129 - DEBUG - Uploaded image status = active 2026-04-23 01:49:33,514 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-juju-os-697-1), Zone: (nova:cn-jenkins-deploy-platform-juju-os-697-1) 2026-04-23 01:49:33,515 - INFO - Waiting for VM ctest-pt_svm0-22120713 to be up.. 2026-04-23 01:49:33,636 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-04-23 01:49:38,753 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-04-23 01:49:43,943 - DEBUG - VM is in ACTIVE state now 2026-04-23 01:49:43,943 - INFO - VM name : ctest-pt_svm0-22120713 2026-04-23 01:49:44,089 - DEBUG - VM ctest-pt_svm0-22120713 ID is edd42326-7018-4e8c-b78d-532f13cc9517 2026-04-23 01:49:44,116 - DEBUG - VM ctest-pt_svm0-22120713 launched on Node cn-jenkins-deploy-platform-juju-os-697-1 2026-04-23 01:49:44,495 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine/edd42326-7018-4e8c-b78d-532f13cc9517 2026-04-23 01:49:44,556 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine-interface/60ce43fa-0e80-4e62-9b46-ef63e665b509 2026-04-23 01:49:44,613 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine-interface/73896e1b-0d69-40ab-b44b-4a2acf06f12f 2026-04-23 01:49:44,672 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine-interface/94dce75a-5bb2-43d4-9df8-3318e7d91d38 2026-04-23 01:49:48,489 - 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 1029ms') 2026-04-23 01:49:48,489 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-22120713 failed! 2026-04-23 01:49:48,543 - DEBUG - Gateway for vn admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284 is 163.142.38.1 and allocation pool is NOT set 2026-04-23 01:49:52,647 - 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-04-23 01:49:52,647 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-22120713 failed! 2026-04-23 01:49:52,702 - DEBUG - Gateway for vn admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284 is 163.142.38.1 and allocation pool is NOT set 2026-04-23 01:49:56,801 - 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-04-23 01:49:56,802 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-22120713 failed! 2026-04-23 01:49:56,859 - DEBUG - Gateway for vn admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284 is 163.142.38.1 and allocation pool is NOT set 2026-04-23 01:49:58,948 - 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=8.58 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.811 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.811/4.695/8.580/3.884 ms') 2026-04-23 01:49:58,949 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-22120713 passed 2026-04-23 01:49:59,103 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:49:59,103 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-22120713, IP 163.142.38.3, Port 22 2026-04-23 01:49:59,203 - 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-04-23 01:49:59,306 - DEBUG - VM ctest-pt_svm0-22120713 is NOT ready for SSH connections, VM status: ACTIVE 2026-04-23 01:50:04,307 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:04,307 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-22120713, IP 163.142.38.3, Port 22 2026-04-23 01:50:04,397 - 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-04-23 01:50:04,496 - DEBUG - VM ctest-pt_svm0-22120713 is NOT ready for SSH connections, VM status: ACTIVE 2026-04-23 01:50:09,496 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:09,496 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-22120713, IP 163.142.38.3, Port 22 2026-04-23 01:50:09,585 - 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-04-23 01:50:09,690 - DEBUG - VM ctest-pt_svm0-22120713 is NOT ready for SSH connections, VM status: ACTIVE 2026-04-23 01:50:14,690 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:14,690 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-22120713, IP 163.142.38.3, Port 22 2026-04-23 01:50:14,776 - 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-04-23 01:50:14,891 - DEBUG - VM ctest-pt_svm0-22120713 is NOT ready for SSH connections, VM status: ACTIVE 2026-04-23 01:50:19,892 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:19,892 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-22120713, IP 163.142.38.3, Port 22 2026-04-23 01:50:20,113 - DEBUG - VM ctest-pt_svm0-22120713 is ready for SSH connections 2026-04-23 01:50:20,262 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:20,262 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-22120713, IP 163.142.38.3, Port 22 2026-04-23 01:50:20,472 - DEBUG - VM ctest-pt_svm0-22120713 is ready for SSH connections 2026-04-23 01:50:20,622 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:20,622 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-22120713, IP 163.142.38.3, Port 22 2026-04-23 01:50:20,842 - DEBUG - VM ctest-pt_svm0-22120713 is ready for SSH connections 2026-04-23 01:50:20,847 - DEBUG - Creating service instance: ['admin_domain', 'ctest-TestSvcRegr-64146939', 'ctest-si-26739755'] 2026-04-23 01:50:21,730 - INFO - Waiting for VM ctest-left_vm-13804329 to be up.. 2026-04-23 01:50:21,862 - DEBUG - VM is in ACTIVE state now 2026-04-23 01:50:21,862 - INFO - VM name : ctest-left_vm-13804329 2026-04-23 01:50:21,990 - DEBUG - VM ctest-left_vm-13804329 ID is 2db7444a-2614-4cdd-933f-af80442ab291 2026-04-23 01:50:21,990 - DEBUG - VM ctest-left_vm-13804329 launched on Node cn-jenkins-deploy-platform-juju-os-697-1 2026-04-23 01:50:22,137 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine/2db7444a-2614-4cdd-933f-af80442ab291 2026-04-23 01:50:22,199 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine-interface/af1f64e6-33f5-4d7b-bfe8-f1419a54633c 2026-04-23 01:50:23,672 - 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.69 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.373 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.373/2.030/3.688/1.657 ms') 2026-04-23 01:50:23,672 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-13804329 passed 2026-04-23 01:50:23,827 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:23,827 - DEBUG - Waiting to SSH to VM ctest-left_vm-13804329, IP 3.17.58.67, Port 22 2026-04-23 01:50:24,051 - DEBUG - VM ctest-left_vm-13804329 is ready for SSH connections 2026-04-23 01:50:24,051 - INFO - Waiting for VM ctest-right_vm-77484243 to be up.. 2026-04-23 01:50:24,194 - DEBUG - VM is in ACTIVE state now 2026-04-23 01:50:24,195 - INFO - VM name : ctest-right_vm-77484243 2026-04-23 01:50:24,338 - DEBUG - VM ctest-right_vm-77484243 ID is df5c20da-e898-42cf-9486-8170f23c3f37 2026-04-23 01:50:24,338 - DEBUG - VM ctest-right_vm-77484243 launched on Node cn-jenkins-deploy-platform-juju-os-697-1 2026-04-23 01:50:24,479 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine/df5c20da-e898-42cf-9486-8170f23c3f37 2026-04-23 01:50:24,536 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine-interface/bde98165-e92e-4be0-99e3-748b259d10a7 2026-04-23 01:50:25,978 - 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.01 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.455 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.455/1.734/3.013/1.279 ms') 2026-04-23 01:50:25,979 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-77484243 passed 2026-04-23 01:50:26,134 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:26,135 - DEBUG - Waiting to SSH to VM ctest-right_vm-77484243, IP 89.239.240.3, Port 22 2026-04-23 01:50:26,349 - DEBUG - VM ctest-right_vm-77484243 is ready for SSH connections 2026-04-23 01:50:26,355 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = 65535], application = [], dst_addresses = [subnet = None, virtual_network = admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617, 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 = ['admin_domain:ctest-TestSvcRegr-64146939:ctest-si-26739755'], 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-04-23 01:50:26,798 - INFO - Attaching policy ctest-policy-99217363 to vn ctest-left_vn-66053936 2026-04-23 01:50:27,342 - INFO - Attaching policy ctest-policy-99217363 to vn ctest-right_vn-87273617 2026-04-23 01:50:27,786 - DEBUG - Service template: ['admin_domain', 'ctest-service_template_1-78073483'] created succesfully 2026-04-23 01:50:28,014 - DEBUG - Service instance: ['admin_domain', 'ctest-TestSvcRegr-64146939', 'ctest-si-26739755'] created succesfully 2026-04-23 01:50:28,015 - DEBUG - Requesting: https://10.0.0.129:8082/domains 2026-04-23 01:50:28,067 - DEBUG - Requesting: https://10.0.0.129:8082/domain/463c1a7f-4575-42ba-bd06-1a9f36cbf244 2026-04-23 01:50:28,127 - DEBUG - Requesting: https://10.0.0.129:8082/project/e4a006b3-9b3d-4bbf-ad8d-946d54c83f16 2026-04-23 01:50:28,240 - DEBUG - Requesting: https://10.0.0.129:8082/service-instance/03b28f87-1e0d-4ae2-92bd-80b9ac7edc97 2026-04-23 01:50:28,300 - DEBUG - SI 'ctest-si-26739755' has service template ref to ctest-service_template_1-78073483 2026-04-23 01:50:28,300 - DEBUG - Requesting: https://10.0.0.129:8082/domains 2026-04-23 01:50:28,355 - DEBUG - Requesting: https://10.0.0.129:8082/domain/463c1a7f-4575-42ba-bd06-1a9f36cbf244 2026-04-23 01:50:28,411 - DEBUG - Requesting: https://10.0.0.129:8082/project/e4a006b3-9b3d-4bbf-ad8d-946d54c83f16 2026-04-23 01:50:28,484 - DEBUG - Requesting: https://10.0.0.129:8082/service-instance/03b28f87-1e0d-4ae2-92bd-80b9ac7edc97 2026-04-23 01:50:28,544 - DEBUG - Requesting: https://10.0.0.129:8082/port-tuple/08fbd50f-8ab4-4802-98cc-e7863cfadf50 2026-04-23 01:50:28,595 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine-interface/60ce43fa-0e80-4e62-9b46-ef63e665b509 2026-04-23 01:50:28,653 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine-interface/73896e1b-0d69-40ab-b44b-4a2acf06f12f 2026-04-23 01:50:28,712 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine-interface/94dce75a-5bb2-43d4-9df8-3318e7d91d38 2026-04-23 01:50:28,995 - DEBUG - VM is in ACTIVE state now 2026-04-23 01:50:29,735 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine/edd42326-7018-4e8c-b78d-532f13cc9517 2026-04-23 01:50:29,791 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine-interface/60ce43fa-0e80-4e62-9b46-ef63e665b509 2026-04-23 01:50:29,854 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine-interface/73896e1b-0d69-40ab-b44b-4a2acf06f12f 2026-04-23 01:50:29,912 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine-interface/94dce75a-5bb2-43d4-9df8-3318e7d91d38 2026-04-23 01:50:29,970 - DEBUG - Requesting: https://10.0.0.129:8082/instance-ip/32db8602-fa29-4842-8511-56628ac942d2 2026-04-23 01:50:30,023 - DEBUG - Requesting: https://10.0.0.129:8082/instance-ip/44d52739-da76-4192-aa98-ff734981baee 2026-04-23 01:50:30,079 - DEBUG - Requesting: https://10.0.0.129:8082/instance-ip/e5888e2c-17e9-45d2-be41-736748fb2686 2026-04-23 01:50:30,136 - DEBUG - Requesting: https://10.0.0.129:8082/instance-ip/415723a3-1ea8-45ac-87f5-07cce2fda9f4 2026-04-23 01:50:30,191 - DEBUG - Requesting: https://10.0.0.129:8082/instance-ip/b590e717-e29d-43dd-bbbc-c47fe8c6ce16 2026-04-23 01:50:30,247 - DEBUG - Requesting: https://10.0.0.129:8082/instance-ip/da2f12b7-b900-4161-a9e5-0d31632ae535 2026-04-23 01:50:30,304 - DEBUG - Requesting: https://10.0.0.129:8082/instance-ip/07726340-1a31-4ce6-9ca7-5402da6a49aa 2026-04-23 01:50:30,359 - DEBUG - Requesting: https://10.0.0.129:8082/instance-ip/ef90f432-cef6-4852-9f4a-715721083bef 2026-04-23 01:50:30,415 - DEBUG - Requesting: https://10.0.0.129:8082/instance-ip/f49df90f-896c-49dc-b5af-8cc418278f60 2026-04-23 01:50:30,923 - DEBUG - VM is in ACTIVE state now 2026-04-23 01:50:30,923 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine/edd42326-7018-4e8c-b78d-532f13cc9517 2026-04-23 01:50:30,979 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine-interface/60ce43fa-0e80-4e62-9b46-ef63e665b509 2026-04-23 01:50:31,038 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine-interface/73896e1b-0d69-40ab-b44b-4a2acf06f12f 2026-04-23 01:50:31,096 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine-interface/94dce75a-5bb2-43d4-9df8-3318e7d91d38 2026-04-23 01:50:32,551 - 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=2.21 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.504 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.504/1.354/2.205/0.850 ms') 2026-04-23 01:50:32,552 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-22120713 passed 2026-04-23 01:50:32,685 - DEBUG - VM ctest-pt_svm0-22120713 Tap interface: {'index': '6', 'name': 'tap94dce75a-5b', 'uuid': '94dce75a-5bb2-43d4-9df8-3318e7d91d38', 'vrf_name': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936:ctest-left_vn-66053936', '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': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936', 'vm_uuid': 'edd42326-7018-4e8c-b78d-532f13cc9517', 'vm_name': 'ctest-pt_svm0-22120713', 'ip_addr': '3.17.58.68', 'mac_addr': '02:94:dc:e7:5a:5b', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.6', 'service_vlan_list': None, 'os_ifindex': '69', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'admin_domain:ctest-TestSvcRegr-64146939:94dce75a-5bb2-43d4-9df8-3318e7d91d38', 'sg_uuid_list': ['d7815c4c-9158-4a1f-a611-ac9eebeb4b50'], 'static_route_list': None, 'vm_project_uuid': 'e4a006b3-9b3d-4bbf-ad8d-946d54c83f16', 'admin_state': 'Enabled', 'flow_key_idx': '45', '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': '60ce43fa-0e80-4e62-9b46-ef63e665b509', '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-04-23 01:50:32,685 - DEBUG - Agent 10.0.0.129 vrf name: admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936:ctest-left_vn-66053936 2026-04-23 01:50:32,739 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936:ctest-left_vn-66053936', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936', 'table_label': '-1', 'vxlan_id': '12', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.0.0.129:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-04-23 01:50:32,908 - DEBUG - VM ctest-pt_svm0-22120713 : Tap interface tap94dce75a-5b is set to Active 2026-04-23 01:50:32,909 - DEBUG - Tap interface tap94dce75a-5b detail : {'index': '6', 'name': 'tap94dce75a-5b', 'uuid': '94dce75a-5bb2-43d4-9df8-3318e7d91d38', 'vrf_name': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936:ctest-left_vn-66053936', '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': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936', 'vm_uuid': 'edd42326-7018-4e8c-b78d-532f13cc9517', 'vm_name': 'ctest-pt_svm0-22120713', 'ip_addr': '3.17.58.68', 'mac_addr': '02:94:dc:e7:5a:5b', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.6', 'service_vlan_list': None, 'os_ifindex': '69', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'admin_domain:ctest-TestSvcRegr-64146939:94dce75a-5bb2-43d4-9df8-3318e7d91d38', 'sg_uuid_list': ['d7815c4c-9158-4a1f-a611-ac9eebeb4b50'], 'static_route_list': None, 'vm_project_uuid': 'e4a006b3-9b3d-4bbf-ad8d-946d54c83f16', 'admin_state': 'Enabled', 'flow_key_idx': '45', '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': '60ce43fa-0e80-4e62-9b46-ef63e665b509', '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-04-23 01:50:33,103 - DEBUG - Starting Layer 2 verification in Agent 2026-04-23 01:50:33,121 - DEBUG - Layer 2 path is seen for VM MAC 02:94:dc:e7:5a:5b in agent 10.0.0.129 2026-04-23 01:50:33,121 - DEBUG - Active layer 2 route in agent is present for VMI tap94dce75a-5b 2026-04-23 01:50:33,122 - DEBUG - L2 label(48) matches bw route table and interface table 2026-04-23 01:50:33,171 - DEBUG - VN admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936 is found in Agent of node 10.0.0.129 2026-04-23 01:50:33,436 - DEBUG - VRF IDs of VN admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936 is consistent in agent 10.0.0.129 2026-04-23 01:50:33,436 - DEBUG - Route for VM IP ['3.17.58.68'] is consistent in agent 10.0.0.129 2026-04-23 01:50:33,436 - DEBUG - VN admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936 verification for VM ctest-pt_svm0-22120713 in Agent 10.0.0.129 passed 2026-04-23 01:50:33,586 - DEBUG - Starting all layer 2 verification in agent 10.0.0.129 2026-04-23 01:50:33,603 - DEBUG - Route for VM MAC 02:94:dc:e7:5a:5b is consistent in agent 10.0.0.129 2026-04-23 01:50:33,711 - DEBUG - VM ctest-pt_svm0-22120713 Tap interface: {'index': '5', 'name': 'tap73896e1b-0d', 'uuid': '73896e1b-0d69-40ab-b44b-4a2acf06f12f', 'vrf_name': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284:ctest-mgmt_vn-96034284', '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': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284', 'vm_uuid': 'edd42326-7018-4e8c-b78d-532f13cc9517', 'vm_name': 'ctest-pt_svm0-22120713', 'ip_addr': '163.142.38.3', 'mac_addr': '02:73:89:6e:1b:0d', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '68', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'admin_domain:ctest-TestSvcRegr-64146939:73896e1b-0d69-40ab-b44b-4a2acf06f12f', 'sg_uuid_list': ['d7815c4c-9158-4a1f-a611-ac9eebeb4b50'], 'static_route_list': None, 'vm_project_uuid': 'e4a006b3-9b3d-4bbf-ad8d-946d54c83f16', 'admin_state': 'Enabled', 'flow_key_idx': '46', '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-04-23 01:50:33,711 - DEBUG - Agent 10.0.0.129 vrf name: admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284:ctest-mgmt_vn-96034284 2026-04-23 01:50:33,759 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284:ctest-mgmt_vn-96034284', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284', 'table_label': '-1', 'vxlan_id': '11', 'evpnindex': '4', 'brindex': '4', 'mplsindex': '0', 'RD': '10.0.0.129:4', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-04-23 01:50:33,918 - DEBUG - VM ctest-pt_svm0-22120713 : Tap interface tap73896e1b-0d is set to Active 2026-04-23 01:50:33,918 - DEBUG - Tap interface tap73896e1b-0d detail : {'index': '5', 'name': 'tap73896e1b-0d', 'uuid': '73896e1b-0d69-40ab-b44b-4a2acf06f12f', 'vrf_name': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284:ctest-mgmt_vn-96034284', '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': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284', 'vm_uuid': 'edd42326-7018-4e8c-b78d-532f13cc9517', 'vm_name': 'ctest-pt_svm0-22120713', 'ip_addr': '163.142.38.3', 'mac_addr': '02:73:89:6e:1b:0d', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.5', 'service_vlan_list': None, 'os_ifindex': '68', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'admin_domain:ctest-TestSvcRegr-64146939:73896e1b-0d69-40ab-b44b-4a2acf06f12f', 'sg_uuid_list': ['d7815c4c-9158-4a1f-a611-ac9eebeb4b50'], 'static_route_list': None, 'vm_project_uuid': 'e4a006b3-9b3d-4bbf-ad8d-946d54c83f16', 'admin_state': 'Enabled', 'flow_key_idx': '46', '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-04-23 01:50:34,126 - DEBUG - Starting Layer 2 verification in Agent 2026-04-23 01:50:34,142 - DEBUG - Layer 2 path is seen for VM MAC 02:73:89:6e:1b:0d in agent 10.0.0.129 2026-04-23 01:50:34,142 - DEBUG - Active layer 2 route in agent is present for VMI tap73896e1b-0d 2026-04-23 01:50:34,142 - DEBUG - L2 label(43) matches bw route table and interface table 2026-04-23 01:50:34,195 - DEBUG - VN admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284 is found in Agent of node 10.0.0.129 2026-04-23 01:50:34,410 - DEBUG - VRF IDs of VN admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284 is consistent in agent 10.0.0.129 2026-04-23 01:50:34,411 - DEBUG - Route for VM IP ['163.142.38.3'] is consistent in agent 10.0.0.129 2026-04-23 01:50:34,411 - DEBUG - VN admin_domain:ctest-TestSvcRegr-64146939:ctest-mgmt_vn-96034284 verification for VM ctest-pt_svm0-22120713 in Agent 10.0.0.129 passed 2026-04-23 01:50:34,603 - DEBUG - Starting all layer 2 verification in agent 10.0.0.129 2026-04-23 01:50:34,615 - DEBUG - Route for VM MAC 02:73:89:6e:1b:0d is consistent in agent 10.0.0.129 2026-04-23 01:50:34,740 - DEBUG - VM ctest-pt_svm0-22120713 Tap interface: {'index': '7', 'name': 'tap60ce43fa-0e', 'uuid': '60ce43fa-0e80-4e62-9b46-ef63e665b509', 'vrf_name': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617:ctest-right_vn-87273617', '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': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617', 'vm_uuid': 'edd42326-7018-4e8c-b78d-532f13cc9517', 'vm_name': 'ctest-pt_svm0-22120713', 'ip_addr': '89.239.240.4', 'mac_addr': '02:60:ce:43:fa:0e', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.7', 'service_vlan_list': None, 'os_ifindex': '70', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'admin_domain:ctest-TestSvcRegr-64146939:60ce43fa-0e80-4e62-9b46-ef63e665b509', 'sg_uuid_list': ['d7815c4c-9158-4a1f-a611-ac9eebeb4b50'], 'static_route_list': None, 'vm_project_uuid': 'e4a006b3-9b3d-4bbf-ad8d-946d54c83f16', 'admin_state': 'Enabled', 'flow_key_idx': '31', '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': '94dce75a-5bb2-43d4-9df8-3318e7d91d38', '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-04-23 01:50:34,740 - DEBUG - Agent 10.0.0.129 vrf name: admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617:ctest-right_vn-87273617 2026-04-23 01:50:34,791 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617:ctest-right_vn-87273617', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617', 'table_label': '-1', 'vxlan_id': '13', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.0.0.129:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2026-04-23 01:50:34,969 - DEBUG - VM ctest-pt_svm0-22120713 : Tap interface tap60ce43fa-0e is set to Active 2026-04-23 01:50:34,970 - DEBUG - Tap interface tap60ce43fa-0e detail : {'index': '7', 'name': 'tap60ce43fa-0e', 'uuid': '60ce43fa-0e80-4e62-9b46-ef63e665b509', 'vrf_name': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617:ctest-right_vn-87273617', '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': 'admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617', 'vm_uuid': 'edd42326-7018-4e8c-b78d-532f13cc9517', 'vm_name': 'ctest-pt_svm0-22120713', 'ip_addr': '89.239.240.4', 'mac_addr': '02:60:ce:43:fa:0e', 'policy': 'Enable', 'fip_list': [], 'mdata_ip_addr': '169.254.0.7', 'service_vlan_list': None, 'os_ifindex': '70', 'fabric_port': 'NotFabricPort', 'alloc_linklocal_ip': 'LL-Enable', 'analyzer_name': None, 'config_name': 'admin_domain:ctest-TestSvcRegr-64146939:60ce43fa-0e80-4e62-9b46-ef63e665b509', 'sg_uuid_list': ['d7815c4c-9158-4a1f-a611-ac9eebeb4b50'], 'static_route_list': None, 'vm_project_uuid': 'e4a006b3-9b3d-4bbf-ad8d-946d54c83f16', 'admin_state': 'Enabled', 'flow_key_idx': '31', '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': '94dce75a-5bb2-43d4-9df8-3318e7d91d38', '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-04-23 01:50:35,170 - DEBUG - Starting Layer 2 verification in Agent 2026-04-23 01:50:35,236 - DEBUG - Layer 2 path is seen for VM MAC 02:60:ce:43:fa:0e in agent 10.0.0.129 2026-04-23 01:50:35,236 - DEBUG - Active layer 2 route in agent is present for VMI tap60ce43fa-0e 2026-04-23 01:50:35,237 - DEBUG - L2 label(53) matches bw route table and interface table 2026-04-23 01:50:35,294 - DEBUG - VN admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617 is found in Agent of node 10.0.0.129 2026-04-23 01:50:35,510 - DEBUG - VRF IDs of VN admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617 is consistent in agent 10.0.0.129 2026-04-23 01:50:35,510 - DEBUG - Route for VM IP ['89.239.240.4'] is consistent in agent 10.0.0.129 2026-04-23 01:50:35,510 - DEBUG - VN admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617 verification for VM ctest-pt_svm0-22120713 in Agent 10.0.0.129 passed 2026-04-23 01:50:35,702 - DEBUG - Starting all layer 2 verification in agent 10.0.0.129 2026-04-23 01:50:35,719 - DEBUG - Route for VM MAC 02:60:ce:43:fa:0e is consistent in agent 10.0.0.129 2026-04-23 01:50:35,719 - INFO - VM ctest-pt_svm0-22120713 verifications in Compute nodes passed 2026-04-23 01:50:35,719 - DEBUG - The SVMs in the SI are : [] 2026-04-23 01:50:35,719 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine/edd42326-7018-4e8c-b78d-532f13cc9517 2026-04-23 01:50:35,775 - DEBUG - Service VM for SI 'ctest-si-26739755' is launched 2026-04-23 01:50:35,775 - DEBUG - Requesting: https://10.0.0.129:8082/domains 2026-04-23 01:50:35,831 - DEBUG - Requesting: https://10.0.0.129:8082/domain/463c1a7f-4575-42ba-bd06-1a9f36cbf244 2026-04-23 01:50:35,887 - DEBUG - Requesting: https://10.0.0.129:8082/project/e4a006b3-9b3d-4bbf-ad8d-946d54c83f16 2026-04-23 01:50:35,960 - DEBUG - Requesting: https://10.0.0.129:8082/service-instance/03b28f87-1e0d-4ae2-92bd-80b9ac7edc97 2026-04-23 01:50:36,012 - DEBUG - SI ctest-si-26739755 has Port Tuple: ['ctest-port_tuple-50257170'] 2026-04-23 01:50:36,118 - INFO - VMI and/or RI refs are present for VN admin_domain:ctest-TestSvcRegr-64146939:ctest-left_vn-66053936 2026-04-23 01:50:36,262 - INFO - VMI and/or RI refs are present for VN admin_domain:ctest-TestSvcRegr-64146939:ctest-right_vn-87273617 2026-04-23 01:50:36,464 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:36,521 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:36,522 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:36,571 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:36,571 - INFO - Route to 89.239.240.3 seen in VRF:5 on cn-jenkins-deploy-platform-juju-os-697-1.englab.juniper.net, and SI ctest-si-26739755 is seen as the NH 2026-04-23 01:50:36,728 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:36,781 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:36,782 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:36,831 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:36,831 - INFO - Route to 3.17.58.67 seen in VRF:6 on cn-jenkins-deploy-platform-juju-os-697-1.englab.juniper.net, and SI ctest-si-26739755 is seen as the NH 2026-04-23 01:50:36,831 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:36,832 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-23 01:50:36,832 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 89.239.240.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.129, gateway password: c0ntrail123 2026-04-23 01:50:36,832 - DEBUG - ping -s 56 -c 3 -W 1 89.239.240.3 2026-04-23 01:50:40,890 - DEBUG - PING 89.239.240.3 (89.239.240.3): 56 data bytes 64 bytes from 89.239.240.3: seq=0 ttl=61 time=5.787 ms 64 bytes from 89.239.240.3: seq=1 ttl=61 time=1.724 ms 64 bytes from 89.239.240.3: seq=2 ttl=61 time=1.765 ms --- 89.239.240.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.724/3.092/5.787 ms 2026-04-23 01:50:40,890 - INFO - Ping to IP 89.239.240.3 from VM ctest-left_vm-13804329 passed 2026-04-23 01:50:40,890 - INFO - Dettaching policy ctest-policy-99217363 from vn ctest-right_vn-87273617 2026-04-23 01:50:41,110 - INFO - Dettaching policy ctest-policy-99217363 from vn ctest-left_vn-66053936 2026-04-23 01:50:41,415 - INFO - Deleted policy ctest-policy-99217363 2026-04-23 01:50:41,415 - DEBUG - Deleting service instance: ['admin_domain', 'ctest-TestSvcRegr-64146939', 'ctest-si-26739755'] 2026-04-23 01:50:41,521 - INFO - Deleted SI ['admin_domain', 'ctest-TestSvcRegr-64146939', 'ctest-si-26739755'] 2026-04-23 01:50:41,521 - DEBUG - Requesting: https://10.0.0.129:8082/domains 2026-04-23 01:50:41,579 - DEBUG - Requesting: https://10.0.0.129:8082/domain/463c1a7f-4575-42ba-bd06-1a9f36cbf244 2026-04-23 01:50:41,643 - DEBUG - Requesting: https://10.0.0.129:8082/project/e4a006b3-9b3d-4bbf-ad8d-946d54c83f16 2026-04-23 01:50:41,737 - DEBUG - Service instance ctest-si-26739755 removed from api server 2026-04-23 01:50:41,737 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine/edd42326-7018-4e8c-b78d-532f13cc9517 2026-04-23 01:50:41,796 - DEBUG - All Service VMs unlinked from SI ctest-si-26739755 2026-04-23 01:50:41,802 - DEBUG - 0 svc intances found in all projects. They are [] 2026-04-23 01:50:41,802 - DEBUG - 0 svc intances found in current project. They are [] 2026-04-23 01:50:41,854 - DEBUG - 0 svc intances found in all projects. They are [] 2026-04-23 01:50:41,854 - DEBUG - 0 svc intances found in current project. They are [] 2026-04-23 01:50:41,854 - INFO - Deleting VM ctest-pt_svm0-22120713 2026-04-23 01:50:41,986 - DEBUG - Deleting service template: ['admin_domain', 'ctest-service_template_1-78073483'] 2026-04-23 01:50:42,094 - INFO - Service template: ['admin_domain', 'ctest-service_template_1-78073483'] deleted successfully. 2026-04-23 01:50:42,094 - INFO - Deleting VM ctest-right_vm-77484243 2026-04-23 01:50:42,207 - INFO - Deleting VM ctest-left_vm-13804329 2026-04-23 01:50:42,327 - INFO - Deleting VN ctest-right_vn-87273617 2026-04-23 01:50:42,476 - DEBUG - VN b2429005-5dce-47db-80fa-fa95fe446d0b still in use: Unable to complete operation on network b2429005-5dce-47db-80fa-fa95fe446d0b. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-df9c13ff-deb3-4aef-a777-f10fe2521810'] 2026-04-23 01:50:42,479 - WARNING - Deleting VN ctest-right_vn-87273617 failed..Will retry 2026-04-23 01:50:44,655 - DEBUG - Response for deleting network () 2026-04-23 01:50:44,655 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/d5f91f69-6b85-450c-95bd-5fdde91147b3 2026-04-23 01:50:44,706 - DEBUG - Response Code: 404 2026-04-23 01:50:44,707 - DEBUG - Requesting: https://10.0.0.129:8082/domains 2026-04-23 01:50:44,759 - DEBUG - Requesting: https://10.0.0.129:8082/domain/463c1a7f-4575-42ba-bd06-1a9f36cbf244 2026-04-23 01:50:44,819 - DEBUG - Requesting: https://10.0.0.129:8082/project/e4a006b3-9b3d-4bbf-ad8d-946d54c83f16 2026-04-23 01:50:44,903 - INFO - Validated that VN ctest-right_vn-87273617 is not found in API Server 2026-04-23 01:50:45,003 - DEBUG - VN ctest-right_vn-87273617 is not present in Agent 10.0.0.129 2026-04-23 01:50:45,003 - INFO - Validated that VN ctest-right_vn-87273617 is not in any agent 2026-04-23 01:50:45,155 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-87273617 info 2026-04-23 01:50:45,155 - INFO - Deleting VN ctest-left_vn-66053936 2026-04-23 01:50:45,333 - DEBUG - Response for deleting network () 2026-04-23 01:50:45,333 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/842ee074-742c-4f8a-a85c-f45c0f7873f7 2026-04-23 01:50:45,390 - DEBUG - Response Code: 404 2026-04-23 01:50:45,391 - DEBUG - Requesting: https://10.0.0.129:8082/domains 2026-04-23 01:50:45,443 - DEBUG - Requesting: https://10.0.0.129:8082/domain/463c1a7f-4575-42ba-bd06-1a9f36cbf244 2026-04-23 01:50:45,500 - DEBUG - Requesting: https://10.0.0.129:8082/project/e4a006b3-9b3d-4bbf-ad8d-946d54c83f16 2026-04-23 01:50:45,580 - INFO - Validated that VN ctest-left_vn-66053936 is not found in API Server 2026-04-23 01:50:45,679 - DEBUG - VN ctest-left_vn-66053936 is not present in Agent 10.0.0.129 2026-04-23 01:50:45,679 - INFO - Validated that VN ctest-left_vn-66053936 is not in any agent 2026-04-23 01:50:45,835 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-66053936 info 2026-04-23 01:50:45,836 - INFO - Deleting VN ctest-mgmt_vn-96034284 2026-04-23 01:50:46,002 - DEBUG - Response for deleting network () 2026-04-23 01:50:46,002 - DEBUG - Requesting: https://10.0.0.129:8082/routing-instance/55c47bc6-5a32-4b6a-b841-a919e72de8fc 2026-04-23 01:50:46,054 - DEBUG - Response Code: 404 2026-04-23 01:50:46,054 - DEBUG - Requesting: https://10.0.0.129:8082/domains 2026-04-23 01:50:46,111 - DEBUG - Requesting: https://10.0.0.129:8082/domain/463c1a7f-4575-42ba-bd06-1a9f36cbf244 2026-04-23 01:50:46,171 - DEBUG - Requesting: https://10.0.0.129:8082/project/e4a006b3-9b3d-4bbf-ad8d-946d54c83f16 2026-04-23 01:50:46,252 - INFO - Validated that VN ctest-mgmt_vn-96034284 is not found in API Server 2026-04-23 01:50:46,351 - DEBUG - VN ctest-mgmt_vn-96034284 is not present in Agent 10.0.0.129 2026-04-23 01:50:46,351 - INFO - Validated that VN ctest-mgmt_vn-96034284 is not in any agent 2026-04-23 01:50:46,503 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-96034284 info 2026-04-23 01:50:46,896 - DEBUG - No XMPP flaps were noticed during the test 2026-04-23 01:50:46,896 - INFO -
2026-04-23 01:50:46,896 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:29]
2026-04-23 01:50:46,896 - INFO - -------------------------------------------------------------------------------- 2026-04-23 01:50:47,650 - INFO - Deleted project: ctest-TestSvcRegr-64146939, ID : e4a006b3-9b3d-4bbf-ad8d-946d54c83f16