2025-11-02 16:27:10,595 - INFO - Domain Default found not creating 2025-11-02 16:27:10,761 - INFO - Project ctest-TestSvcRegr-77435045 not found, creating it 2025-11-02 16:27:11,221 - INFO - Created Project:ctest-TestSvcRegr-77435045, ID : 9817df9d-3462-4bfe-80ba-86fa5cb97a0d 2025-11-02 16:27:12,799 - INFO - ================================================================================ 2025-11-02 16:27:12,799 - INFO - STARTING TEST : test_svc_in_network_datapath 2025-11-02 16:27:13,053 - DEBUG - Nothing to compare xmpp stats {'10.0.0.48': {'10.20.0.17': '0'}} with 2025-11-02 16:27:13,054 - INFO - Initial checks done. Running the testcase now 2025-11-02 16:27:13,054 - INFO - 2025-11-02 16:27:13,653 - DEBUG - Response for create_network : {'network': {'id': 'af88bd11-e018-427f-8cac-88cb51c028bd', 'name': 'ctest-mgmt_vn-08345400', 'tenant_id': '9817df9d34624bfe80ba86fa5cb97a0d', 'project_id': '9817df9d34624bfe80ba86fa5cb97a0d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-77435045', 'ctest-mgmt_vn-08345400'], 'port_security_enabled': True, 'description': ''}} 2025-11-02 16:27:13,832 - DEBUG - Response for create_subnet : {'subnet': {'id': '034c0d83-74fb-4746-b990-ef2f6abecbc7', 'name': '', 'tenant_id': '9817df9d34624bfe80ba86fa5cb97a0d', 'network_id': 'af88bd11-e018-427f-8cac-88cb51c028bd', 'ip_version': 4, 'cidr': '130.247.248.0/26', 'allocation_pools': [{'start': '130.247.248.2', 'end': '130.247.248.62'}], 'gateway_ip': '130.247.248.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '130.247.248.2', 'tags': [], 'project_id': '9817df9d34624bfe80ba86fa5cb97a0d'}} 2025-11-02 16:27:13,851 - INFO - Created VN ctest-mgmt_vn-08345400 2025-11-02 16:27:13,904 - DEBUG - VN ctest-mgmt_vn-08345400 UUID is af88bd11-e018-427f-8cac-88cb51c028bd 2025-11-02 16:27:14,018 - DEBUG - Requesting: http://10.0.0.48:8082/domains 2025-11-02 16:27:14,354 - DEBUG - Requesting: http://10.0.0.48:8082/domains 2025-11-02 16:27:14,385 - DEBUG - Requesting: http://10.0.0.48:8082/domain/b1118436-1b87-42aa-8ee9-d27497dd1d27 2025-11-02 16:27:14,399 - DEBUG - Requesting: http://10.0.0.48:8082/project/9817df9d-3462-4bfe-80ba-86fa5cb97a0d 2025-11-02 16:27:14,477 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/af88bd11-e018-427f-8cac-88cb51c028bd 2025-11-02 16:27:14,487 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/af88bd11-e018-427f-8cac-88cb51c028bd 2025-11-02 16:27:14,497 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/1cf61fa6-da8a-46da-8b99-a3e332c5f0f1 2025-11-02 16:27:14,504 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/1cf61fa6-da8a-46da-8b99-a3e332c5f0f1 2025-11-02 16:27:14,511 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/0c577e10-25c4-42e1-a3c8-0cad3e750976 2025-11-02 16:27:14,518 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-11-02 16:27:14,518 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/af88bd11-e018-427f-8cac-88cb51c028bd 2025-11-02 16:27:14,527 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/1cf61fa6-da8a-46da-8b99-a3e332c5f0f1 2025-11-02 16:27:14,541 - INFO - Verified VN network id 11 for VN af88bd11-e018-427f-8cac-88cb51c028bd 2025-11-02 16:27:14,542 - INFO - Verifications in API Server for VN ctest-mgmt_vn-08345400 passed 2025-11-02 16:27:14,542 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/af88bd11-e018-427f-8cac-88cb51c028bd 2025-11-02 16:27:14,551 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/1cf61fa6-da8a-46da-8b99-a3e332c5f0f1 2025-11-02 16:27:14,557 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/1cf61fa6-da8a-46da-8b99-a3e332c5f0f1 2025-11-02 16:27:14,563 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/0c577e10-25c4-42e1-a3c8-0cad3e750976 2025-11-02 16:27:14,575 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-77435045', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '12648567437941817983', 'uuid-lslong': '10136627268135430333'}, 'enable': 'true', 'created': '2025-11-02T16:27:13', 'last-modified': '2025-11-02T16:27:13', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.769677'} 2025-11-02 16:27:14,581 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-11-02 16:27:14,581 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-08345400 passed 2025-11-02 16:27:14,581 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-08345400 in API_Server ====== 2025-11-02 16:27:14,581 - DEBUG - Requesting: http://10.0.0.48:8082/domains 2025-11-02 16:27:14,587 - DEBUG - Requesting: http://10.0.0.48:8082/domain/b1118436-1b87-42aa-8ee9-d27497dd1d27 2025-11-02 16:27:14,598 - DEBUG - Requesting: http://10.0.0.48:8082/project/9817df9d-3462-4bfe-80ba-86fa5cb97a0d 2025-11-02 16:27:14,670 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/af88bd11-e018-427f-8cac-88cb51c028bd 2025-11-02 16:27:14,681 - DEBUG - =>VN ctest-mgmt_vn-08345400 has no policy to be verified 2025-11-02 16:27:14,681 - DEBUG - Verifying the vn in opserver 2025-11-02 16:27:14,681 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400 virtual network link through opserver 10.0.0.48 2025-11-02 16:27:14,681 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks 2025-11-02 16:27:15,017 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks 2025-11-02 16:27:15,082 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400?flat'} 2025-11-02 16:27:15,082 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400 is found in opserver 2025-11-02 16:27:15,093 - DEBUG - Do not have enough data to verify VN in agent 2025-11-02 16:27:15,098 - DEBUG - VRF ids for VN ctest-mgmt_vn-08345400: {} 2025-11-02 16:27:15,310 - DEBUG - Response for create_network : {'network': {'id': 'e85a293f-cb56-42ac-828d-13f4403b0c88', 'name': 'ctest-left_vn-59309973', 'tenant_id': '9817df9d34624bfe80ba86fa5cb97a0d', 'project_id': '9817df9d34624bfe80ba86fa5cb97a0d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-77435045', 'ctest-left_vn-59309973'], 'port_security_enabled': True, 'description': ''}} 2025-11-02 16:27:15,489 - DEBUG - Response for create_subnet : {'subnet': {'id': '689dc848-b2bd-48ec-9f52-a4abec01052f', 'name': '', 'tenant_id': '9817df9d34624bfe80ba86fa5cb97a0d', 'network_id': 'e85a293f-cb56-42ac-828d-13f4403b0c88', 'ip_version': 4, 'cidr': '162.96.217.0/26', 'allocation_pools': [{'start': '162.96.217.2', 'end': '162.96.217.62'}], 'gateway_ip': '162.96.217.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '162.96.217.2', 'tags': [], 'project_id': '9817df9d34624bfe80ba86fa5cb97a0d'}} 2025-11-02 16:27:15,507 - INFO - Created VN ctest-left_vn-59309973 2025-11-02 16:27:15,560 - DEBUG - VN ctest-left_vn-59309973 UUID is e85a293f-cb56-42ac-828d-13f4403b0c88 2025-11-02 16:27:15,671 - DEBUG - Requesting: http://10.0.0.48:8082/domains 2025-11-02 16:27:15,675 - DEBUG - Requesting: http://10.0.0.48:8082/domain/b1118436-1b87-42aa-8ee9-d27497dd1d27 2025-11-02 16:27:15,685 - DEBUG - Requesting: http://10.0.0.48:8082/project/9817df9d-3462-4bfe-80ba-86fa5cb97a0d 2025-11-02 16:27:15,757 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/e85a293f-cb56-42ac-828d-13f4403b0c88 2025-11-02 16:27:15,767 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/e85a293f-cb56-42ac-828d-13f4403b0c88 2025-11-02 16:27:15,776 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/455976ed-6a37-4cc5-aff5-0cca63da2fb7 2025-11-02 16:27:15,782 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/455976ed-6a37-4cc5-aff5-0cca63da2fb7 2025-11-02 16:27:15,787 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/9cd6be48-3b48-4c7a-b0fc-b7d843eab0d8 2025-11-02 16:27:15,792 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-11-02 16:27:15,792 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/e85a293f-cb56-42ac-828d-13f4403b0c88 2025-11-02 16:27:15,799 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/455976ed-6a37-4cc5-aff5-0cca63da2fb7 2025-11-02 16:27:15,812 - INFO - Verified VN network id 12 for VN e85a293f-cb56-42ac-828d-13f4403b0c88 2025-11-02 16:27:15,812 - INFO - Verifications in API Server for VN ctest-left_vn-59309973 passed 2025-11-02 16:27:15,812 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/e85a293f-cb56-42ac-828d-13f4403b0c88 2025-11-02 16:27:15,820 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/455976ed-6a37-4cc5-aff5-0cca63da2fb7 2025-11-02 16:27:15,826 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/455976ed-6a37-4cc5-aff5-0cca63da2fb7 2025-11-02 16:27:15,832 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/9cd6be48-3b48-4c7a-b0fc-b7d843eab0d8 2025-11-02 16:27:15,842 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-77435045:ctest-left_vn-59309973', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-77435045', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '16742739918674346668', 'uuid-lslong': '9407197136417393800'}, 'enable': 'true', 'created': '2025-11-02T16:27:15', 'last-modified': '2025-11-02T16:27:15', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.379992'} 2025-11-02 16:27:15,847 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-11-02 16:27:15,847 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-59309973 passed 2025-11-02 16:27:15,847 - DEBUG - ====Verifying policy data for ctest-left_vn-59309973 in API_Server ====== 2025-11-02 16:27:15,847 - DEBUG - Requesting: http://10.0.0.48:8082/domains 2025-11-02 16:27:15,852 - DEBUG - Requesting: http://10.0.0.48:8082/domain/b1118436-1b87-42aa-8ee9-d27497dd1d27 2025-11-02 16:27:15,863 - DEBUG - Requesting: http://10.0.0.48:8082/project/9817df9d-3462-4bfe-80ba-86fa5cb97a0d 2025-11-02 16:27:15,931 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/e85a293f-cb56-42ac-828d-13f4403b0c88 2025-11-02 16:27:15,940 - DEBUG - =>VN ctest-left_vn-59309973 has no policy to be verified 2025-11-02 16:27:15,940 - DEBUG - Verifying the vn in opserver 2025-11-02 16:27:15,940 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-77435045:ctest-left_vn-59309973 virtual network link through opserver 10.0.0.48 2025-11-02 16:27:15,940 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks 2025-11-02 16:27:15,950 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-77435045:ctest-left_vn-59309973', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-77435045:ctest-left_vn-59309973?flat'} 2025-11-02 16:27:15,950 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-77435045:ctest-left_vn-59309973 is found in opserver 2025-11-02 16:27:15,959 - DEBUG - Do not have enough data to verify VN in agent 2025-11-02 16:27:15,963 - DEBUG - VRF ids for VN ctest-left_vn-59309973: {} 2025-11-02 16:27:16,141 - DEBUG - Response for create_network : {'network': {'id': '17dc51db-0fd2-4d6e-81c7-bf50ac707a08', 'name': 'ctest-right_vn-27389229', 'tenant_id': '9817df9d34624bfe80ba86fa5cb97a0d', 'project_id': '9817df9d34624bfe80ba86fa5cb97a0d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-77435045', 'ctest-right_vn-27389229'], 'port_security_enabled': True, 'description': ''}} 2025-11-02 16:27:16,310 - DEBUG - Response for create_subnet : {'subnet': {'id': '6568eb55-2513-4c3b-a632-1e6a2d735d0a', 'name': '', 'tenant_id': '9817df9d34624bfe80ba86fa5cb97a0d', 'network_id': '17dc51db-0fd2-4d6e-81c7-bf50ac707a08', 'ip_version': 4, 'cidr': '162.8.141.64/26', 'allocation_pools': [{'start': '162.8.141.66', 'end': '162.8.141.126'}], 'gateway_ip': '162.8.141.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '162.8.141.66', 'tags': [], 'project_id': '9817df9d34624bfe80ba86fa5cb97a0d'}} 2025-11-02 16:27:16,328 - INFO - Created VN ctest-right_vn-27389229 2025-11-02 16:27:16,380 - DEBUG - VN ctest-right_vn-27389229 UUID is 17dc51db-0fd2-4d6e-81c7-bf50ac707a08 2025-11-02 16:27:16,488 - DEBUG - Requesting: http://10.0.0.48:8082/domains 2025-11-02 16:27:16,493 - DEBUG - Requesting: http://10.0.0.48:8082/domain/b1118436-1b87-42aa-8ee9-d27497dd1d27 2025-11-02 16:27:16,504 - DEBUG - Requesting: http://10.0.0.48:8082/project/9817df9d-3462-4bfe-80ba-86fa5cb97a0d 2025-11-02 16:27:16,580 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/17dc51db-0fd2-4d6e-81c7-bf50ac707a08 2025-11-02 16:27:16,590 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/17dc51db-0fd2-4d6e-81c7-bf50ac707a08 2025-11-02 16:27:16,599 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/24b389f3-551b-4524-b8d7-6f7f04e66ec7 2025-11-02 16:27:16,606 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/24b389f3-551b-4524-b8d7-6f7f04e66ec7 2025-11-02 16:27:16,612 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/98937123-56fc-4380-80ad-147d56086ffb 2025-11-02 16:27:16,617 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-11-02 16:27:16,617 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/17dc51db-0fd2-4d6e-81c7-bf50ac707a08 2025-11-02 16:27:16,625 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/24b389f3-551b-4524-b8d7-6f7f04e66ec7 2025-11-02 16:27:16,637 - INFO - Verified VN network id 13 for VN 17dc51db-0fd2-4d6e-81c7-bf50ac707a08 2025-11-02 16:27:16,637 - INFO - Verifications in API Server for VN ctest-right_vn-27389229 passed 2025-11-02 16:27:16,637 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/17dc51db-0fd2-4d6e-81c7-bf50ac707a08 2025-11-02 16:27:16,646 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/24b389f3-551b-4524-b8d7-6f7f04e66ec7 2025-11-02 16:27:16,652 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/24b389f3-551b-4524-b8d7-6f7f04e66ec7 2025-11-02 16:27:16,658 - DEBUG - Requesting: http://10.0.0.48:8082/route-target/98937123-56fc-4380-80ad-147d56086ffb 2025-11-02 16:27:16,670 - DEBUG - Control-node 10.0.0.48 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-77435045:ctest-right_vn-27389229', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-77435045', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1719339159053815150', 'uuid-lslong': '9351653504469465608'}, 'enable': 'true', 'created': '2025-11-02T16:27:16', 'last-modified': '2025-11-02T16:27:16', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.392943'} 2025-11-02 16:27:16,675 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-11-02 16:27:16,675 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-27389229 passed 2025-11-02 16:27:16,675 - DEBUG - ====Verifying policy data for ctest-right_vn-27389229 in API_Server ====== 2025-11-02 16:27:16,676 - DEBUG - Requesting: http://10.0.0.48:8082/domains 2025-11-02 16:27:16,682 - DEBUG - Requesting: http://10.0.0.48:8082/domain/b1118436-1b87-42aa-8ee9-d27497dd1d27 2025-11-02 16:27:16,694 - DEBUG - Requesting: http://10.0.0.48:8082/project/9817df9d-3462-4bfe-80ba-86fa5cb97a0d 2025-11-02 16:27:16,763 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-network/17dc51db-0fd2-4d6e-81c7-bf50ac707a08 2025-11-02 16:27:16,772 - DEBUG - =>VN ctest-right_vn-27389229 has no policy to be verified 2025-11-02 16:27:16,772 - DEBUG - Verifying the vn in opserver 2025-11-02 16:27:16,772 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-77435045:ctest-right_vn-27389229 virtual network link through opserver 10.0.0.48 2025-11-02 16:27:16,772 - DEBUG - Requesting: http://10.0.0.48:8081/analytics/uves/virtual-networks 2025-11-02 16:27:16,782 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-77435045:ctest-right_vn-27389229', 'href': 'http://10.0.0.48:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-77435045:ctest-right_vn-27389229?flat'} 2025-11-02 16:27:16,782 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-77435045:ctest-right_vn-27389229 is found in opserver 2025-11-02 16:27:16,792 - DEBUG - Do not have enough data to verify VN in agent 2025-11-02 16:27:16,796 - DEBUG - VRF ids for VN ctest-right_vn-27389229: {} 2025-11-02 16:27:16,855 - DEBUG - Services list from nova: [, , ] 2025-11-02 16:27:18,049 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4594-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4594-1) 2025-11-02 16:27:19,347 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4594-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4594-1) 2025-11-02 16:27:19,347 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-13021229'] 2025-11-02 16:27:19,724 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-13021229'] created succesfully 2025-11-02 16:27:20,115 - DEBUG - Image by name cirros_in_net not found 2025-11-02 16:27:20,115 - DEBUG - Installing image cirros_in_net 2025-11-02 16:27:20,115 - DEBUG - Image info {'name': 'cirros-in-net.vmdk.gz', 'webserver': '', 'location': '/images/converts/', 'username': 'cirros', 'password': 'gocubsgo', 'type': 'os', 'flavor': 'contrail_flavor_tiny', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hw_scsi_model=virtio-scsi --property hw_disk_bus_model=virtio-scsi --property hw_disk_bus=virtio', 'name_docker': 'phusion-baseimage-enablesshd'} 2025-11-02 16:27:20,116 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-11-02 16:27:20,116 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-11-02 16:27:20,138 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz -O /contrail-test/images/cirros-in-net.vmdk.gz 2025-11-02 16:27:20,272 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz 2025-11-02 16:27:20,273 - DEBUG - Unzip image 2025-11-02 16:27:20,273 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz 2025-11-02 16:27:20,476 - DEBUG - Try to create image in user context 2025-11-02 16:27:20,729 - DEBUG - Image has been created in user context 2025-11-02 16:27:20,730 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk 2025-11-02 16:27:20,753 - DEBUG - Downloaded image has been removed 2025-11-02 16:27:20,774 - DEBUG - Uploaded image status = active 2025-11-02 16:27:22,120 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4594-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4594-1) 2025-11-02 16:27:22,121 - INFO - Waiting for VM ctest-pt_svm0-14390544 to be up.. 2025-11-02 16:27:22,174 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-02 16:27:27,255 - DEBUG - VM is in ACTIVE state now 2025-11-02 16:27:27,255 - INFO - VM name : ctest-pt_svm0-14390544 2025-11-02 16:27:27,341 - DEBUG - VM ctest-pt_svm0-14390544 ID is 7b8f64a3-d388-49c7-9a87-e98b7da1e8c3 2025-11-02 16:27:27,362 - DEBUG - VM ctest-pt_svm0-14390544 launched on Node cn-jenkins-deploy-platform-ansible-os-4594-1 2025-11-02 16:27:27,609 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/7b8f64a3-d388-49c7-9a87-e98b7da1e8c3 2025-11-02 16:27:27,617 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/34c9ebbc-37a2-4cc0-9aa6-da1d889d776b 2025-11-02 16:27:27,629 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/75595281-208a-40db-9620-a1e0fbca3d41 2025-11-02 16:27:27,638 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/bac724a6-da5a-47c7-b97c-fc9e55c7125b 2025-11-02 16:27:30,904 - 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 1001ms') 2025-11-02 16:27:30,904 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-14390544 failed! 2025-11-02 16:27:30,960 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400 is 130.247.248.1 and allocation pool is NOT set 2025-11-02 16:27:35,032 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-11-02 16:27:35,032 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-14390544 failed! 2025-11-02 16:27:35,092 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400 is 130.247.248.1 and allocation pool is NOT set 2025-11-02 16:27:39,159 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-11-02 16:27:39,159 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-14390544 failed! 2025-11-02 16:27:39,212 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400 is 130.247.248.1 and allocation pool is NOT set 2025-11-02 16:27:43,285 - 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') 2025-11-02 16:27:43,285 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-14390544 failed! 2025-11-02 16:27:43,340 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400 is 130.247.248.1 and allocation pool is NOT set 2025-11-02 16:27:45,400 - 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=9.42 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.744 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.744/5.079/9.415/4.335 ms') 2025-11-02 16:27:45,400 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-14390544 passed 2025-11-02 16:27:45,556 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:27:45,556 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-14390544, IP 130.247.248.3, Port 22 2025-11-02 16:27:45,628 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-11-02 16:27:45,717 - DEBUG - VM ctest-pt_svm0-14390544 is NOT ready for SSH connections, VM status: ACTIVE 2025-11-02 16:27:50,718 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:27:50,718 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-14390544, IP 130.247.248.3, Port 22 2025-11-02 16:27:50,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': ''} 2025-11-02 16:27:50,882 - DEBUG - VM ctest-pt_svm0-14390544 is NOT ready for SSH connections, VM status: ACTIVE 2025-11-02 16:27:55,883 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:27:55,883 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-14390544, IP 130.247.248.3, Port 22 2025-11-02 16:27:55,939 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-11-02 16:27:56,023 - DEBUG - VM ctest-pt_svm0-14390544 is NOT ready for SSH connections, VM status: ACTIVE 2025-11-02 16:28:01,024 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:01,024 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-14390544, IP 130.247.248.3, Port 22 2025-11-02 16:28:01,091 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-11-02 16:28:01,174 - DEBUG - VM ctest-pt_svm0-14390544 is NOT ready for SSH connections, VM status: ACTIVE 2025-11-02 16:28:06,175 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:06,175 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-14390544, IP 130.247.248.3, Port 22 2025-11-02 16:28:06,242 - DEBUG - Error on ssh to cirros@169.254.0.5:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.5/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.5/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.5/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-11-02 16:28:06,324 - DEBUG - VM ctest-pt_svm0-14390544 is NOT ready for SSH connections, VM status: ACTIVE 2025-11-02 16:28:11,324 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:11,324 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-14390544, IP 130.247.248.3, Port 22 2025-11-02 16:28:11,483 - DEBUG - VM ctest-pt_svm0-14390544 is ready for SSH connections 2025-11-02 16:28:11,636 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:11,636 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-14390544, IP 130.247.248.3, Port 22 2025-11-02 16:28:11,792 - DEBUG - VM ctest-pt_svm0-14390544 is ready for SSH connections 2025-11-02 16:28:11,948 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:11,948 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-14390544, IP 130.247.248.3, Port 22 2025-11-02 16:28:12,104 - DEBUG - VM ctest-pt_svm0-14390544 is ready for SSH connections 2025-11-02 16:28:12,110 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSvcRegr-77435045', 'ctest-si-21308321'] 2025-11-02 16:28:13,028 - INFO - Waiting for VM ctest-left_vm-29376680 to be up.. 2025-11-02 16:28:13,105 - DEBUG - VM is in ACTIVE state now 2025-11-02 16:28:13,105 - INFO - VM name : ctest-left_vm-29376680 2025-11-02 16:28:13,205 - DEBUG - VM ctest-left_vm-29376680 ID is 467e59a1-74e1-4260-a060-c89763c1a5aa 2025-11-02 16:28:13,205 - DEBUG - VM ctest-left_vm-29376680 launched on Node cn-jenkins-deploy-platform-ansible-os-4594-1 2025-11-02 16:28:13,291 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/467e59a1-74e1-4260-a060-c89763c1a5aa 2025-11-02 16:28:13,299 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/2cc5985a-0156-4e35-9b47-afebbe5b1067 2025-11-02 16:28:14,547 - 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.21 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.528 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.528/1.868/3.208/1.340 ms') 2025-11-02 16:28:14,547 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-29376680 passed 2025-11-02 16:28:14,704 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:14,704 - DEBUG - Waiting to SSH to VM ctest-left_vm-29376680, IP 162.96.217.3, Port 22 2025-11-02 16:28:14,871 - DEBUG - VM ctest-left_vm-29376680 is ready for SSH connections 2025-11-02 16:28:14,871 - INFO - Waiting for VM ctest-right_vm-15625544 to be up.. 2025-11-02 16:28:14,953 - DEBUG - VM is in ACTIVE state now 2025-11-02 16:28:14,953 - INFO - VM name : ctest-right_vm-15625544 2025-11-02 16:28:15,025 - DEBUG - VM ctest-right_vm-15625544 ID is efd581b6-6d45-4408-8d1e-c18982c0efd2 2025-11-02 16:28:15,025 - DEBUG - VM ctest-right_vm-15625544 launched on Node cn-jenkins-deploy-platform-ansible-os-4594-1 2025-11-02 16:28:15,097 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/efd581b6-6d45-4408-8d1e-c18982c0efd2 2025-11-02 16:28:15,104 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/fcca6fd8-f9d5-46a7-9f88-82792cbd82e5 2025-11-02 16:28:16,338 - 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.42 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.480 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.480/1.952/3.424/1.472 ms') 2025-11-02 16:28:16,339 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-15625544 passed 2025-11-02 16:28:16,492 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:16,492 - DEBUG - Waiting to SSH to VM ctest-right_vm-15625544, IP 162.8.141.67, Port 22 2025-11-02 16:28:16,648 - DEBUG - VM ctest-right_vm-15625544 is ready for SSH connections 2025-11-02 16:28:16,653 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-77435045:ctest-left_vn-59309973, 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-77435045:ctest-right_vn-27389229, 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-77435045:ctest-si-21308321'], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-11-02 16:28:17,080 - INFO - Attaching policy ctest-policy-84925430 to vn ctest-left_vn-59309973 2025-11-02 16:28:17,616 - INFO - Attaching policy ctest-policy-84925430 to vn ctest-right_vn-27389229 2025-11-02 16:28:18,060 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-13021229'] created succesfully 2025-11-02 16:28:18,304 - DEBUG - Service instance: ['default-domain', 'ctest-TestSvcRegr-77435045', 'ctest-si-21308321'] created succesfully 2025-11-02 16:28:18,304 - DEBUG - Requesting: http://10.0.0.48:8082/domains 2025-11-02 16:28:18,311 - DEBUG - Requesting: http://10.0.0.48:8082/domain/b1118436-1b87-42aa-8ee9-d27497dd1d27 2025-11-02 16:28:18,322 - DEBUG - Requesting: http://10.0.0.48:8082/project/9817df9d-3462-4bfe-80ba-86fa5cb97a0d 2025-11-02 16:28:18,394 - DEBUG - Requesting: http://10.0.0.48:8082/service-instance/89ff6a9f-013a-4f6e-b679-8faa5b3944d0 2025-11-02 16:28:18,405 - DEBUG - SI 'ctest-si-21308321' has service template ref to ctest-service_template_1-13021229 2025-11-02 16:28:18,405 - DEBUG - Requesting: http://10.0.0.48:8082/domains 2025-11-02 16:28:18,412 - DEBUG - Requesting: http://10.0.0.48:8082/domain/b1118436-1b87-42aa-8ee9-d27497dd1d27 2025-11-02 16:28:18,422 - DEBUG - Requesting: http://10.0.0.48:8082/project/9817df9d-3462-4bfe-80ba-86fa5cb97a0d 2025-11-02 16:28:18,494 - DEBUG - Requesting: http://10.0.0.48:8082/service-instance/89ff6a9f-013a-4f6e-b679-8faa5b3944d0 2025-11-02 16:28:18,504 - DEBUG - Requesting: http://10.0.0.48:8082/port-tuple/eadbcf63-ba01-4483-957c-168cd291efd1 2025-11-02 16:28:18,511 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/34c9ebbc-37a2-4cc0-9aa6-da1d889d776b 2025-11-02 16:28:18,529 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/75595281-208a-40db-9620-a1e0fbca3d41 2025-11-02 16:28:18,540 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/bac724a6-da5a-47c7-b97c-fc9e55c7125b 2025-11-02 16:28:18,777 - DEBUG - VM is in ACTIVE state now 2025-11-02 16:28:19,180 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/7b8f64a3-d388-49c7-9a87-e98b7da1e8c3 2025-11-02 16:28:19,188 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/34c9ebbc-37a2-4cc0-9aa6-da1d889d776b 2025-11-02 16:28:19,199 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/75595281-208a-40db-9620-a1e0fbca3d41 2025-11-02 16:28:19,209 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/bac724a6-da5a-47c7-b97c-fc9e55c7125b 2025-11-02 16:28:19,217 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/361315c4-c117-4dcf-b61d-6086c7a2f402 2025-11-02 16:28:19,224 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/4c612c65-6305-42de-bc29-7177cd725e7f 2025-11-02 16:28:19,230 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/df923f19-206b-45fb-a6f4-1ba860b69dad 2025-11-02 16:28:19,236 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/133b0a2d-2487-4f5b-9285-718a41d7eff4 2025-11-02 16:28:19,242 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/612356d7-d04d-4e63-aebe-dd9b32226beb 2025-11-02 16:28:19,249 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/aef41e85-8f19-4b60-ace0-83fad5ccf5fa 2025-11-02 16:28:19,256 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/4153fb91-7a70-47b5-a87e-fbe8d69598bf 2025-11-02 16:28:19,261 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/8f13691b-62a5-4ff6-951f-898ef8b1e8fa 2025-11-02 16:28:19,268 - DEBUG - Requesting: http://10.0.0.48:8082/instance-ip/fb2179ed-7154-45eb-9dee-b9419035df6f 2025-11-02 16:28:19,636 - DEBUG - VM is in ACTIVE state now 2025-11-02 16:28:19,636 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/7b8f64a3-d388-49c7-9a87-e98b7da1e8c3 2025-11-02 16:28:19,644 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/34c9ebbc-37a2-4cc0-9aa6-da1d889d776b 2025-11-02 16:28:19,653 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/75595281-208a-40db-9620-a1e0fbca3d41 2025-11-02 16:28:19,663 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/bac724a6-da5a-47c7-b97c-fc9e55c7125b 2025-11-02 16:28:20,905 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n64 bytes from 169.254.0.6: icmp_seq=1 ttl=63 time=1.00 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.926 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.926/0.963/1.001/0.037 ms') 2025-11-02 16:28:20,905 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-14390544 passed 2025-11-02 16:28:20,941 - DEBUG - VM ctest-pt_svm0-14390544 Tap interface: {'index': '6', 'name': 'tap75595281-20', 'uuid': '75595281-208a-40db-9620-a1e0fbca3d41', 'vrf_name': 'default-domain:ctest-TestSvcRegr-77435045:ctest-left_vn-59309973:ctest-left_vn-59309973', '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-77435045:ctest-left_vn-59309973', 'vm_uuid': '7b8f64a3-d388-49c7-9a87-e98b7da1e8c3', 'vm_name': 'ctest-pt_svm0-14390544', 'ip_addr': '162.96.217.4', 'mac_addr': '02:75:59:52:81:20', '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-77435045:75595281-208a-40db-9620-a1e0fbca3d41', 'sg_uuid_list': ['1bc8c387-2d6a-412c-89b8-669c8bd69d3e'], 'static_route_list': None, 'vm_project_uuid': '9817df9d-3462-4bfe-80ba-86fa5cb97a0d', 'admin_state': 'Enabled', 'flow_key_idx': '42', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '34c9ebbc-37a2-4cc0-9aa6-da1d889d776b', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-11-02 16:28:20,941 - DEBUG - Agent 10.0.0.48 vrf name: default-domain:ctest-TestSvcRegr-77435045:ctest-left_vn-59309973:ctest-left_vn-59309973 2025-11-02 16:28:20,945 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-77435045:ctest-left_vn-59309973:ctest-left_vn-59309973', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestSvcRegr-77435045:ctest-left_vn-59309973', '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'}]} 2025-11-02 16:28:21,120 - DEBUG - VM ctest-pt_svm0-14390544 : Tap interface tap75595281-20 is set to Active 2025-11-02 16:28:21,120 - DEBUG - Tap interface tap75595281-20 detail : {'index': '6', 'name': 'tap75595281-20', 'uuid': '75595281-208a-40db-9620-a1e0fbca3d41', 'vrf_name': 'default-domain:ctest-TestSvcRegr-77435045:ctest-left_vn-59309973:ctest-left_vn-59309973', '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-77435045:ctest-left_vn-59309973', 'vm_uuid': '7b8f64a3-d388-49c7-9a87-e98b7da1e8c3', 'vm_name': 'ctest-pt_svm0-14390544', 'ip_addr': '162.96.217.4', 'mac_addr': '02:75:59:52:81:20', '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-77435045:75595281-208a-40db-9620-a1e0fbca3d41', 'sg_uuid_list': ['1bc8c387-2d6a-412c-89b8-669c8bd69d3e'], 'static_route_list': None, 'vm_project_uuid': '9817df9d-3462-4bfe-80ba-86fa5cb97a0d', 'admin_state': 'Enabled', 'flow_key_idx': '42', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '34c9ebbc-37a2-4cc0-9aa6-da1d889d776b', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-11-02 16:28:21,316 - DEBUG - Starting Layer 2 verification in Agent 2025-11-02 16:28:21,327 - DEBUG - Layer 2 path is seen for VM MAC 02:75:59:52:81:20 in agent 10.0.0.48 2025-11-02 16:28:21,328 - DEBUG - Active layer 2 route in agent is present for VMI tap75595281-20 2025-11-02 16:28:21,328 - DEBUG - L2 label(48) matches bw route table and interface table 2025-11-02 16:28:21,333 - DEBUG - VN default-domain:ctest-TestSvcRegr-77435045:ctest-left_vn-59309973 is found in Agent of node 10.0.0.48 2025-11-02 16:28:21,544 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-77435045:ctest-left_vn-59309973 is consistent in agent 10.0.0.48 2025-11-02 16:28:21,545 - DEBUG - Route for VM IP ['162.96.217.4'] is consistent in agent 10.0.0.48 2025-11-02 16:28:21,545 - DEBUG - VN default-domain:ctest-TestSvcRegr-77435045:ctest-left_vn-59309973 verification for VM ctest-pt_svm0-14390544 in Agent 10.0.0.48 passed 2025-11-02 16:28:21,736 - DEBUG - Starting all layer 2 verification in agent 10.0.0.48 2025-11-02 16:28:21,746 - DEBUG - Route for VM MAC 02:75:59:52:81:20 is consistent in agent 10.0.0.48 2025-11-02 16:28:21,763 - DEBUG - VM ctest-pt_svm0-14390544 Tap interface: {'index': '5', 'name': 'tapbac724a6-da', 'uuid': 'bac724a6-da5a-47c7-b97c-fc9e55c7125b', 'vrf_name': 'default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400:ctest-mgmt_vn-08345400', '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-77435045:ctest-mgmt_vn-08345400', 'vm_uuid': '7b8f64a3-d388-49c7-9a87-e98b7da1e8c3', 'vm_name': 'ctest-pt_svm0-14390544', 'ip_addr': '130.247.248.3', 'mac_addr': '02:ba:c7:24:a6:da', '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-77435045:bac724a6-da5a-47c7-b97c-fc9e55c7125b', 'sg_uuid_list': ['1bc8c387-2d6a-412c-89b8-669c8bd69d3e'], 'static_route_list': None, 'vm_project_uuid': '9817df9d-3462-4bfe-80ba-86fa5cb97a0d', 'admin_state': 'Enabled', 'flow_key_idx': '43', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-11-02 16:28:21,763 - DEBUG - Agent 10.0.0.48 vrf name: default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400:ctest-mgmt_vn-08345400 2025-11-02 16:28:21,768 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400:ctest-mgmt_vn-08345400', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400', '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'}]} 2025-11-02 16:28:21,970 - DEBUG - VM ctest-pt_svm0-14390544 : Tap interface tapbac724a6-da is set to Active 2025-11-02 16:28:21,970 - DEBUG - Tap interface tapbac724a6-da detail : {'index': '5', 'name': 'tapbac724a6-da', 'uuid': 'bac724a6-da5a-47c7-b97c-fc9e55c7125b', 'vrf_name': 'default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400:ctest-mgmt_vn-08345400', '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-77435045:ctest-mgmt_vn-08345400', 'vm_uuid': '7b8f64a3-d388-49c7-9a87-e98b7da1e8c3', 'vm_name': 'ctest-pt_svm0-14390544', 'ip_addr': '130.247.248.3', 'mac_addr': '02:ba:c7:24:a6:da', '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-77435045:bac724a6-da5a-47c7-b97c-fc9e55c7125b', 'sg_uuid_list': ['1bc8c387-2d6a-412c-89b8-669c8bd69d3e'], 'static_route_list': None, 'vm_project_uuid': '9817df9d-3462-4bfe-80ba-86fa5cb97a0d', 'admin_state': 'Enabled', 'flow_key_idx': '43', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '00000000-0000-0000-0000-000000000000', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-11-02 16:28:22,164 - DEBUG - Starting Layer 2 verification in Agent 2025-11-02 16:28:22,175 - DEBUG - Layer 2 path is seen for VM MAC 02:ba:c7:24:a6:da in agent 10.0.0.48 2025-11-02 16:28:22,175 - DEBUG - Active layer 2 route in agent is present for VMI tapbac724a6-da 2025-11-02 16:28:22,175 - DEBUG - L2 label(43) matches bw route table and interface table 2025-11-02 16:28:22,181 - DEBUG - VN default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400 is found in Agent of node 10.0.0.48 2025-11-02 16:28:22,389 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400 is consistent in agent 10.0.0.48 2025-11-02 16:28:22,390 - DEBUG - Route for VM IP ['130.247.248.3'] is consistent in agent 10.0.0.48 2025-11-02 16:28:22,390 - DEBUG - VN default-domain:ctest-TestSvcRegr-77435045:ctest-mgmt_vn-08345400 verification for VM ctest-pt_svm0-14390544 in Agent 10.0.0.48 passed 2025-11-02 16:28:22,580 - DEBUG - Starting all layer 2 verification in agent 10.0.0.48 2025-11-02 16:28:22,590 - DEBUG - Route for VM MAC 02:ba:c7:24:a6:da is consistent in agent 10.0.0.48 2025-11-02 16:28:22,611 - DEBUG - VM ctest-pt_svm0-14390544 Tap interface: {'index': '7', 'name': 'tap34c9ebbc-37', 'uuid': '34c9ebbc-37a2-4cc0-9aa6-da1d889d776b', 'vrf_name': 'default-domain:ctest-TestSvcRegr-77435045:ctest-right_vn-27389229:ctest-right_vn-27389229', '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-77435045:ctest-right_vn-27389229', 'vm_uuid': '7b8f64a3-d388-49c7-9a87-e98b7da1e8c3', 'vm_name': 'ctest-pt_svm0-14390544', 'ip_addr': '162.8.141.68', 'mac_addr': '02:34:c9:eb:bc:37', '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-77435045:34c9ebbc-37a2-4cc0-9aa6-da1d889d776b', 'sg_uuid_list': ['1bc8c387-2d6a-412c-89b8-669c8bd69d3e'], 'static_route_list': None, 'vm_project_uuid': '9817df9d-3462-4bfe-80ba-86fa5cb97a0d', 'admin_state': 'Enabled', 'flow_key_idx': '28', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '75595281-208a-40db-9620-a1e0fbca3d41', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-11-02 16:28:22,611 - DEBUG - Agent 10.0.0.48 vrf name: default-domain:ctest-TestSvcRegr-77435045:ctest-right_vn-27389229:ctest-right_vn-27389229 2025-11-02 16:28:22,617 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-77435045:ctest-right_vn-27389229:ctest-right_vn-27389229', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSvcRegr-77435045:ctest-right_vn-27389229', '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'}]} 2025-11-02 16:28:22,824 - DEBUG - VM ctest-pt_svm0-14390544 : Tap interface tap34c9ebbc-37 is set to Active 2025-11-02 16:28:22,824 - DEBUG - Tap interface tap34c9ebbc-37 detail : {'index': '7', 'name': 'tap34c9ebbc-37', 'uuid': '34c9ebbc-37a2-4cc0-9aa6-da1d889d776b', 'vrf_name': 'default-domain:ctest-TestSvcRegr-77435045:ctest-right_vn-27389229:ctest-right_vn-27389229', '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-77435045:ctest-right_vn-27389229', 'vm_uuid': '7b8f64a3-d388-49c7-9a87-e98b7da1e8c3', 'vm_name': 'ctest-pt_svm0-14390544', 'ip_addr': '162.8.141.68', 'mac_addr': '02:34:c9:eb:bc:37', '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-77435045:34c9ebbc-37a2-4cc0-9aa6-da1d889d776b', 'sg_uuid_list': ['1bc8c387-2d6a-412c-89b8-669c8bd69d3e'], 'static_route_list': None, 'vm_project_uuid': '9817df9d-3462-4bfe-80ba-86fa5cb97a0d', 'admin_state': 'Enabled', 'flow_key_idx': '28', 'allowed_address_pair_list': None, 'ip6_addr': '::', 'local_preference': '0', 'tx_vlan_id': '-1', 'rx_vlan_id': '-1', 'parent_interface': None, 'subnet': '--NA--', 'sub_type': 'Tap', 'vrf_assign_acl_uuid': '--NA--', 'vmi_type': 'Virtual Machine', 'transport': 'Ethernet', 'logical_interface_uuid': '00000000-0000-0000-0000-000000000000', 'flood_unknown_unicast': 'false', 'physical_device': None, 'physical_interface': None, 'fixed_ip4_list': None, 'fixed_ip6_list': None, 'fat_flow_list': None, 'metadata_ip_active': 'Active', 'service_health_check_ip': '0.0.0.0', 'alias_ip_list': None, 'drop_new_flows': 'false', 'bridge_domain_list': [], 'vmi_tag_list': None, 'policy_set_acl_list': None, 'slo_list': None, 'vhostuser_mode': '0', 'si_other_end_vmi': '75595281-208a-40db-9620-a1e0fbca3d41', 'cfg_igmp_enable': 'false', 'igmp_enabled': 'false', 'max_flows': '0', 'policy_set_fwaas_list': None, 'bond_interface_list': None, 'mac_ip_learning_enable': 'false', 'mac_ip_list': []} 2025-11-02 16:28:23,016 - DEBUG - Starting Layer 2 verification in Agent 2025-11-02 16:28:23,028 - DEBUG - Layer 2 path is seen for VM MAC 02:34:c9:eb:bc:37 in agent 10.0.0.48 2025-11-02 16:28:23,028 - DEBUG - Active layer 2 route in agent is present for VMI tap34c9ebbc-37 2025-11-02 16:28:23,028 - DEBUG - L2 label(53) matches bw route table and interface table 2025-11-02 16:28:23,034 - DEBUG - VN default-domain:ctest-TestSvcRegr-77435045:ctest-right_vn-27389229 is found in Agent of node 10.0.0.48 2025-11-02 16:28:23,243 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-77435045:ctest-right_vn-27389229 is consistent in agent 10.0.0.48 2025-11-02 16:28:23,244 - DEBUG - Route for VM IP ['162.8.141.68'] is consistent in agent 10.0.0.48 2025-11-02 16:28:23,244 - DEBUG - VN default-domain:ctest-TestSvcRegr-77435045:ctest-right_vn-27389229 verification for VM ctest-pt_svm0-14390544 in Agent 10.0.0.48 passed 2025-11-02 16:28:23,444 - DEBUG - Starting all layer 2 verification in agent 10.0.0.48 2025-11-02 16:28:23,455 - DEBUG - Route for VM MAC 02:34:c9:eb:bc:37 is consistent in agent 10.0.0.48 2025-11-02 16:28:23,456 - INFO - VM ctest-pt_svm0-14390544 verifications in Compute nodes passed 2025-11-02 16:28:23,456 - DEBUG - The SVMs in the SI are : [] 2025-11-02 16:28:23,456 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/7b8f64a3-d388-49c7-9a87-e98b7da1e8c3 2025-11-02 16:28:23,464 - DEBUG - Service VM for SI 'ctest-si-21308321' is launched 2025-11-02 16:28:23,464 - DEBUG - Requesting: http://10.0.0.48:8082/domains 2025-11-02 16:28:23,469 - DEBUG - Requesting: http://10.0.0.48:8082/domain/b1118436-1b87-42aa-8ee9-d27497dd1d27 2025-11-02 16:28:23,481 - DEBUG - Requesting: http://10.0.0.48:8082/project/9817df9d-3462-4bfe-80ba-86fa5cb97a0d 2025-11-02 16:28:23,551 - DEBUG - Requesting: http://10.0.0.48:8082/service-instance/89ff6a9f-013a-4f6e-b679-8faa5b3944d0 2025-11-02 16:28:23,559 - DEBUG - SI ctest-si-21308321 has Port Tuple: ['ctest-port_tuple-49778369'] 2025-11-02 16:28:23,664 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-77435045:ctest-left_vn-59309973 2025-11-02 16:28:23,808 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-77435045:ctest-right_vn-27389229 2025-11-02 16:28:23,962 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:23,969 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:23,970 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:23,975 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:23,975 - INFO - Route to 162.8.141.67 seen in VRF:5 on cn-jenkins-deploy-platform-ansible-os-4594-1, and SI ctest-si-21308321 is seen as the NH 2025-11-02 16:28:24,130 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:24,137 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:24,137 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:24,143 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:24,143 - INFO - Route to 162.96.217.3 seen in VRF:6 on cn-jenkins-deploy-platform-ansible-os-4594-1, and SI ctest-si-21308321 is seen as the NH 2025-11-02 16:28:24,143 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:24,143 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:28:24,143 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 162.8.141.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.48, gateway password: c0ntrail123 2025-11-02 16:28:24,144 - DEBUG - ping -s 56 -c 3 -W 1 162.8.141.67 2025-11-02 16:28:28,227 - DEBUG - PING 162.8.141.67 (162.8.141.67): 56 data bytes 64 bytes from 162.8.141.67: seq=0 ttl=61 time=5.906 ms 64 bytes from 162.8.141.67: seq=1 ttl=61 time=1.810 ms 64 bytes from 162.8.141.67: seq=2 ttl=61 time=1.591 ms --- 162.8.141.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.591/3.102/5.906 ms 2025-11-02 16:28:28,227 - INFO - Ping to IP 162.8.141.67 from VM ctest-left_vm-29376680 passed 2025-11-02 16:28:28,227 - INFO - Dettaching policy ctest-policy-84925430 from vn ctest-right_vn-27389229 2025-11-02 16:28:28,436 - INFO - Dettaching policy ctest-policy-84925430 from vn ctest-left_vn-59309973 2025-11-02 16:28:28,712 - INFO - Deleted policy ctest-policy-84925430 2025-11-02 16:28:28,712 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSvcRegr-77435045', 'ctest-si-21308321'] 2025-11-02 16:28:28,801 - INFO - Deleted SI ['default-domain', 'ctest-TestSvcRegr-77435045', 'ctest-si-21308321'] 2025-11-02 16:28:28,802 - DEBUG - Requesting: http://10.0.0.48:8082/domains 2025-11-02 16:28:28,807 - DEBUG - Requesting: http://10.0.0.48:8082/domain/b1118436-1b87-42aa-8ee9-d27497dd1d27 2025-11-02 16:28:28,820 - DEBUG - Requesting: http://10.0.0.48:8082/project/9817df9d-3462-4bfe-80ba-86fa5cb97a0d 2025-11-02 16:28:28,897 - DEBUG - Service instance ctest-si-21308321 removed from api server 2025-11-02 16:28:28,897 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/7b8f64a3-d388-49c7-9a87-e98b7da1e8c3 2025-11-02 16:28:28,905 - DEBUG - All Service VMs unlinked from SI ctest-si-21308321 2025-11-02 16:28:28,909 - DEBUG - 0 svc intances found in all projects. They are [] 2025-11-02 16:28:28,909 - DEBUG - 0 svc intances found in current project. They are [] 2025-11-02 16:28:28,956 - DEBUG - 0 svc intances found in all projects. They are [] 2025-11-02 16:28:28,956 - DEBUG - 0 svc intances found in current project. They are [] 2025-11-02 16:28:28,956 - INFO - Deleting VM ctest-pt_svm0-14390544 2025-11-02 16:28:29,036 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-13021229'] 2025-11-02 16:28:29,132 - INFO - Service template: ['default-domain', 'ctest-service_template_1-13021229'] deleted successfully. 2025-11-02 16:28:29,132 - INFO - Deleting VM ctest-right_vm-15625544 2025-11-02 16:28:29,215 - INFO - Deleting VM ctest-left_vm-29376680 2025-11-02 16:28:29,309 - INFO - Deleting VN ctest-right_vn-27389229 2025-11-02 16:28:29,358 - DEBUG - VN 17dc51db-0fd2-4d6e-81c7-bf50ac707a08 still in use: Unable to complete operation on network 17dc51db-0fd2-4d6e-81c7-bf50ac707a08. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-d79daab9-4fcf-4ccb-99f2-984c2837a97e'] 2025-11-02 16:28:29,358 - WARNING - Deleting VN ctest-right_vn-27389229 failed..Will retry 2025-11-02 16:28:31,456 - DEBUG - Response for deleting network () 2025-11-02 16:28:31,456 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/24b389f3-551b-4524-b8d7-6f7f04e66ec7 2025-11-02 16:28:31,465 - DEBUG - Response Code: 404 2025-11-02 16:28:31,465 - DEBUG - Requesting: http://10.0.0.48:8082/domains 2025-11-02 16:28:31,470 - DEBUG - Requesting: http://10.0.0.48:8082/domain/b1118436-1b87-42aa-8ee9-d27497dd1d27 2025-11-02 16:28:31,523 - DEBUG - Requesting: http://10.0.0.48:8082/project/9817df9d-3462-4bfe-80ba-86fa5cb97a0d 2025-11-02 16:28:31,599 - INFO - Validated that VN ctest-right_vn-27389229 is not found in API Server 2025-11-02 16:28:31,610 - DEBUG - VN ctest-right_vn-27389229 is not present in Agent 10.0.0.48 2025-11-02 16:28:31,610 - INFO - Validated that VN ctest-right_vn-27389229 is not in any agent 2025-11-02 16:28:31,630 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-27389229 info 2025-11-02 16:28:31,630 - INFO - Deleting VN ctest-left_vn-59309973 2025-11-02 16:28:31,742 - DEBUG - Response for deleting network () 2025-11-02 16:28:31,742 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/455976ed-6a37-4cc5-aff5-0cca63da2fb7 2025-11-02 16:28:31,749 - DEBUG - Response Code: 404 2025-11-02 16:28:31,749 - DEBUG - Requesting: http://10.0.0.48:8082/domains 2025-11-02 16:28:31,754 - DEBUG - Requesting: http://10.0.0.48:8082/domain/b1118436-1b87-42aa-8ee9-d27497dd1d27 2025-11-02 16:28:31,810 - DEBUG - Requesting: http://10.0.0.48:8082/project/9817df9d-3462-4bfe-80ba-86fa5cb97a0d 2025-11-02 16:28:31,893 - INFO - Validated that VN ctest-left_vn-59309973 is not found in API Server 2025-11-02 16:28:31,903 - DEBUG - VN ctest-left_vn-59309973 is not present in Agent 10.0.0.48 2025-11-02 16:28:31,903 - INFO - Validated that VN ctest-left_vn-59309973 is not in any agent 2025-11-02 16:28:31,917 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-59309973 info 2025-11-02 16:28:31,917 - INFO - Deleting VN ctest-mgmt_vn-08345400 2025-11-02 16:28:32,043 - DEBUG - Response for deleting network () 2025-11-02 16:28:32,043 - DEBUG - Requesting: http://10.0.0.48:8082/routing-instance/1cf61fa6-da8a-46da-8b99-a3e332c5f0f1 2025-11-02 16:28:32,056 - DEBUG - Response Code: 404 2025-11-02 16:28:32,056 - DEBUG - Requesting: http://10.0.0.48:8082/domains 2025-11-02 16:28:32,062 - DEBUG - Requesting: http://10.0.0.48:8082/domain/b1118436-1b87-42aa-8ee9-d27497dd1d27 2025-11-02 16:28:32,120 - DEBUG - Requesting: http://10.0.0.48:8082/project/9817df9d-3462-4bfe-80ba-86fa5cb97a0d 2025-11-02 16:28:32,205 - INFO - Validated that VN ctest-mgmt_vn-08345400 is not found in API Server 2025-11-02 16:28:32,217 - DEBUG - VN ctest-mgmt_vn-08345400 is not present in Agent 10.0.0.48 2025-11-02 16:28:32,217 - INFO - Validated that VN ctest-mgmt_vn-08345400 is not in any agent 2025-11-02 16:28:32,232 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-08345400 info 2025-11-02 16:28:32,488 - DEBUG - No XMPP flaps were noticed during the test 2025-11-02 16:28:32,488 - INFO -
2025-11-02 16:28:32,489 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:20]
2025-11-02 16:28:32,489 - INFO - -------------------------------------------------------------------------------- 2025-11-02 16:28:33,251 - INFO - Deleted project: ctest-TestSvcRegr-77435045, ID : 9817df9d-3462-4bfe-80ba-86fa5cb97a0d