2025-11-18 14:48:50,634 - INFO - Domain Default found not creating 2025-11-18 14:48:51,194 - INFO - Project ctest-TestSvcRegr-68801294 not found, creating it 2025-11-18 14:48:52,004 - INFO - Created Project:ctest-TestSvcRegr-68801294, ID : 5390ca0b-2f10-406a-85e9-9f95d700efd8 2025-11-18 14:48:53,677 - INFO - ================================================================================ 2025-11-18 14:48:53,677 - INFO - STARTING TEST : test_svc_in_network_datapath 2025-11-18 14:48:53,952 - DEBUG - Nothing to compare xmpp stats {'10.0.0.33': {'10.20.0.5': '0'}} with 2025-11-18 14:48:53,952 - INFO - Initial checks done. Running the testcase now 2025-11-18 14:48:53,952 - INFO - 2025-11-18 14:48:54,573 - DEBUG - Response for create_network : {'network': {'id': 'c986a5d1-ce9f-49be-b940-0675316e9b41', 'name': 'ctest-mgmt_vn-54560937', 'tenant_id': '5390ca0b2f10406a85e99f95d700efd8', 'project_id': '5390ca0b2f10406a85e99f95d700efd8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-68801294', 'ctest-mgmt_vn-54560937'], 'port_security_enabled': True, 'description': ''}} 2025-11-18 14:48:54,765 - DEBUG - Response for create_subnet : {'subnet': {'id': '04f80164-1e5e-4018-bb59-48ef2e947db9', 'name': '', 'tenant_id': '5390ca0b2f10406a85e99f95d700efd8', 'network_id': 'c986a5d1-ce9f-49be-b940-0675316e9b41', 'ip_version': 4, 'cidr': '157.178.227.64/26', 'allocation_pools': [{'start': '157.178.227.66', 'end': '157.178.227.126'}], 'gateway_ip': '157.178.227.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '157.178.227.66', 'tags': [], 'project_id': '5390ca0b2f10406a85e99f95d700efd8'}} 2025-11-18 14:48:54,790 - INFO - Created VN ctest-mgmt_vn-54560937 2025-11-18 14:48:54,847 - DEBUG - VN ctest-mgmt_vn-54560937 UUID is c986a5d1-ce9f-49be-b940-0675316e9b41 2025-11-18 14:48:54,992 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-11-18 14:48:55,295 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-11-18 14:48:55,335 - DEBUG - Requesting: http://10.0.0.33:8082/domain/b498e8f4-7605-4ec1-81af-703b4297c23e 2025-11-18 14:48:55,349 - DEBUG - Requesting: http://10.0.0.33:8082/project/5390ca0b-2f10-406a-85e9-9f95d700efd8 2025-11-18 14:48:55,424 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/c986a5d1-ce9f-49be-b940-0675316e9b41 2025-11-18 14:48:55,437 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/c986a5d1-ce9f-49be-b940-0675316e9b41 2025-11-18 14:48:55,447 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/47dc9476-b7b4-44c7-8b91-bd758746caf2 2025-11-18 14:48:55,455 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/47dc9476-b7b4-44c7-8b91-bd758746caf2 2025-11-18 14:48:55,464 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/5937656b-699c-4e45-9613-7d4c1dba14cf 2025-11-18 14:48:55,473 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-11-18 14:48:55,473 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/c986a5d1-ce9f-49be-b940-0675316e9b41 2025-11-18 14:48:55,483 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/47dc9476-b7b4-44c7-8b91-bd758746caf2 2025-11-18 14:48:55,508 - INFO - Verified VN network id 12 for VN c986a5d1-ce9f-49be-b940-0675316e9b41 2025-11-18 14:48:55,508 - INFO - Verifications in API Server for VN ctest-mgmt_vn-54560937 passed 2025-11-18 14:48:55,508 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/c986a5d1-ce9f-49be-b940-0675316e9b41 2025-11-18 14:48:55,520 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/47dc9476-b7b4-44c7-8b91-bd758746caf2 2025-11-18 14:48:55,530 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/47dc9476-b7b4-44c7-8b91-bd758746caf2 2025-11-18 14:48:55,539 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/5937656b-699c-4e45-9613-7d4c1dba14cf 2025-11-18 14:48:55,555 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68801294', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '14521476369036036542', 'uuid-lslong': '13348676395936422721'}, 'enable': 'true', 'created': '2025-11-18T14:48:54', 'last-modified': '2025-11-18T14:48:54', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.829374'} 2025-11-18 14:48:55,563 - DEBUG - Route Targets: ['target:64512:8000004'] 2025-11-18 14:48:55,563 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-54560937 passed 2025-11-18 14:48:55,563 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-54560937 in API_Server ====== 2025-11-18 14:48:55,563 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-11-18 14:48:55,570 - DEBUG - Requesting: http://10.0.0.33:8082/domain/b498e8f4-7605-4ec1-81af-703b4297c23e 2025-11-18 14:48:55,583 - DEBUG - Requesting: http://10.0.0.33:8082/project/5390ca0b-2f10-406a-85e9-9f95d700efd8 2025-11-18 14:48:55,663 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/c986a5d1-ce9f-49be-b940-0675316e9b41 2025-11-18 14:48:55,676 - DEBUG - =>VN ctest-mgmt_vn-54560937 has no policy to be verified 2025-11-18 14:48:55,676 - DEBUG - Verifying the vn in opserver 2025-11-18 14:48:55,676 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937 virtual network link through opserver 10.0.0.33 2025-11-18 14:48:55,676 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-11-18 14:48:55,999 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-11-18 14:48:56,083 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937?flat'} 2025-11-18 14:48:56,084 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937 is found in opserver 2025-11-18 14:48:56,096 - DEBUG - Do not have enough data to verify VN in agent 2025-11-18 14:48:56,101 - DEBUG - VRF ids for VN ctest-mgmt_vn-54560937: {} 2025-11-18 14:48:56,340 - DEBUG - Response for create_network : {'network': {'id': '76c52773-7501-47c7-bb46-82160bdce5b5', 'name': 'ctest-left_vn-23097408', 'tenant_id': '5390ca0b2f10406a85e99f95d700efd8', 'project_id': '5390ca0b2f10406a85e99f95d700efd8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-68801294', 'ctest-left_vn-23097408'], 'port_security_enabled': True, 'description': ''}} 2025-11-18 14:48:56,553 - DEBUG - Response for create_subnet : {'subnet': {'id': '0f73dda2-7fee-402f-8c3c-9422c3dfa552', 'name': '', 'tenant_id': '5390ca0b2f10406a85e99f95d700efd8', 'network_id': '76c52773-7501-47c7-bb46-82160bdce5b5', 'ip_version': 4, 'cidr': '202.206.11.128/26', 'allocation_pools': [{'start': '202.206.11.130', 'end': '202.206.11.190'}], 'gateway_ip': '202.206.11.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '202.206.11.130', 'tags': [], 'project_id': '5390ca0b2f10406a85e99f95d700efd8'}} 2025-11-18 14:48:56,577 - INFO - Created VN ctest-left_vn-23097408 2025-11-18 14:48:56,642 - DEBUG - VN ctest-left_vn-23097408 UUID is 76c52773-7501-47c7-bb46-82160bdce5b5 2025-11-18 14:48:56,778 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-11-18 14:48:56,786 - DEBUG - Requesting: http://10.0.0.33:8082/domain/b498e8f4-7605-4ec1-81af-703b4297c23e 2025-11-18 14:48:56,798 - DEBUG - Requesting: http://10.0.0.33:8082/project/5390ca0b-2f10-406a-85e9-9f95d700efd8 2025-11-18 14:48:56,875 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/76c52773-7501-47c7-bb46-82160bdce5b5 2025-11-18 14:48:56,889 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/76c52773-7501-47c7-bb46-82160bdce5b5 2025-11-18 14:48:56,902 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b4b480f4-31c3-49b2-877c-310abeaea247 2025-11-18 14:48:56,913 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b4b480f4-31c3-49b2-877c-310abeaea247 2025-11-18 14:48:56,925 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/09702d86-39ac-4185-b126-0efe0ff2b6a6 2025-11-18 14:48:56,935 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-11-18 14:48:56,935 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/76c52773-7501-47c7-bb46-82160bdce5b5 2025-11-18 14:48:56,949 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b4b480f4-31c3-49b2-877c-310abeaea247 2025-11-18 14:48:56,970 - INFO - Verified VN network id 13 for VN 76c52773-7501-47c7-bb46-82160bdce5b5 2025-11-18 14:48:56,971 - INFO - Verifications in API Server for VN ctest-left_vn-23097408 passed 2025-11-18 14:48:56,971 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/76c52773-7501-47c7-bb46-82160bdce5b5 2025-11-18 14:48:56,984 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b4b480f4-31c3-49b2-877c-310abeaea247 2025-11-18 14:48:56,993 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b4b480f4-31c3-49b2-877c-310abeaea247 2025-11-18 14:48:57,003 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/09702d86-39ac-4185-b126-0efe0ff2b6a6 2025-11-18 14:48:57,019 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68801294', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '8558290043725236167', 'uuid-lslong': '13494616364662187445'}, 'enable': 'true', 'created': '2025-11-18T14:48:56', 'last-modified': '2025-11-18T14:48:56', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.520858'} 2025-11-18 14:48:57,028 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-11-18 14:48:57,028 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-23097408 passed 2025-11-18 14:48:57,028 - DEBUG - ====Verifying policy data for ctest-left_vn-23097408 in API_Server ====== 2025-11-18 14:48:57,028 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-11-18 14:48:57,034 - DEBUG - Requesting: http://10.0.0.33:8082/domain/b498e8f4-7605-4ec1-81af-703b4297c23e 2025-11-18 14:48:57,047 - DEBUG - Requesting: http://10.0.0.33:8082/project/5390ca0b-2f10-406a-85e9-9f95d700efd8 2025-11-18 14:48:57,135 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/76c52773-7501-47c7-bb46-82160bdce5b5 2025-11-18 14:48:57,148 - DEBUG - =>VN ctest-left_vn-23097408 has no policy to be verified 2025-11-18 14:48:57,148 - DEBUG - Verifying the vn in opserver 2025-11-18 14:48:57,148 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408 virtual network link through opserver 10.0.0.33 2025-11-18 14:48:57,148 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-11-18 14:48:57,159 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408?flat'} 2025-11-18 14:48:57,160 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408 is found in opserver 2025-11-18 14:48:57,171 - DEBUG - Do not have enough data to verify VN in agent 2025-11-18 14:48:57,176 - DEBUG - VRF ids for VN ctest-left_vn-23097408: {} 2025-11-18 14:48:57,378 - DEBUG - Response for create_network : {'network': {'id': '0fe047b2-d64b-4e7a-be50-e7dd683ced3c', 'name': 'ctest-right_vn-60350874', 'tenant_id': '5390ca0b2f10406a85e99f95d700efd8', 'project_id': '5390ca0b2f10406a85e99f95d700efd8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-68801294', 'ctest-right_vn-60350874'], 'port_security_enabled': True, 'description': ''}} 2025-11-18 14:48:57,542 - DEBUG - Response for create_subnet : {'subnet': {'id': '1c3a587a-4a33-46ac-b13a-720dfbae997d', 'name': '', 'tenant_id': '5390ca0b2f10406a85e99f95d700efd8', 'network_id': '0fe047b2-d64b-4e7a-be50-e7dd683ced3c', 'ip_version': 4, 'cidr': '108.173.93.0/26', 'allocation_pools': [{'start': '108.173.93.2', 'end': '108.173.93.62'}], 'gateway_ip': '108.173.93.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '108.173.93.2', 'tags': [], 'project_id': '5390ca0b2f10406a85e99f95d700efd8'}} 2025-11-18 14:48:57,580 - INFO - Created VN ctest-right_vn-60350874 2025-11-18 14:48:57,634 - DEBUG - VN ctest-right_vn-60350874 UUID is 0fe047b2-d64b-4e7a-be50-e7dd683ced3c 2025-11-18 14:48:57,792 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-11-18 14:48:57,800 - DEBUG - Requesting: http://10.0.0.33:8082/domain/b498e8f4-7605-4ec1-81af-703b4297c23e 2025-11-18 14:48:57,815 - DEBUG - Requesting: http://10.0.0.33:8082/project/5390ca0b-2f10-406a-85e9-9f95d700efd8 2025-11-18 14:48:57,904 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/0fe047b2-d64b-4e7a-be50-e7dd683ced3c 2025-11-18 14:48:57,918 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/0fe047b2-d64b-4e7a-be50-e7dd683ced3c 2025-11-18 14:48:57,931 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/a7d953ed-3bae-4986-9159-c52679cd6218 2025-11-18 14:48:57,944 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/a7d953ed-3bae-4986-9159-c52679cd6218 2025-11-18 14:48:57,954 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/640534f1-2be9-43e1-a623-3670b463caac 2025-11-18 14:48:57,965 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-11-18 14:48:57,965 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/0fe047b2-d64b-4e7a-be50-e7dd683ced3c 2025-11-18 14:48:57,978 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/a7d953ed-3bae-4986-9159-c52679cd6218 2025-11-18 14:48:57,997 - INFO - Verified VN network id 14 for VN 0fe047b2-d64b-4e7a-be50-e7dd683ced3c 2025-11-18 14:48:57,999 - INFO - Verifications in API Server for VN ctest-right_vn-60350874 passed 2025-11-18 14:48:57,999 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/0fe047b2-d64b-4e7a-be50-e7dd683ced3c 2025-11-18 14:48:58,010 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/a7d953ed-3bae-4986-9159-c52679cd6218 2025-11-18 14:48:58,019 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/a7d953ed-3bae-4986-9159-c52679cd6218 2025-11-18 14:48:58,030 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/640534f1-2be9-43e1-a623-3670b463caac 2025-11-18 14:48:58,046 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-68801294:ctest-right_vn-60350874', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-68801294', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1143993138777116282', 'uuid-lslong': '13713715803465772348'}, 'enable': 'true', 'created': '2025-11-18T14:48:57', 'last-modified': '2025-11-18T14:48:57', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.549697'} 2025-11-18 14:48:58,053 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-11-18 14:48:58,054 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-60350874 passed 2025-11-18 14:48:58,054 - DEBUG - ====Verifying policy data for ctest-right_vn-60350874 in API_Server ====== 2025-11-18 14:48:58,054 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-11-18 14:48:58,062 - DEBUG - Requesting: http://10.0.0.33:8082/domain/b498e8f4-7605-4ec1-81af-703b4297c23e 2025-11-18 14:48:58,074 - DEBUG - Requesting: http://10.0.0.33:8082/project/5390ca0b-2f10-406a-85e9-9f95d700efd8 2025-11-18 14:48:58,155 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/0fe047b2-d64b-4e7a-be50-e7dd683ced3c 2025-11-18 14:48:58,168 - DEBUG - =>VN ctest-right_vn-60350874 has no policy to be verified 2025-11-18 14:48:58,168 - DEBUG - Verifying the vn in opserver 2025-11-18 14:48:58,168 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-68801294:ctest-right_vn-60350874 virtual network link through opserver 10.0.0.33 2025-11-18 14:48:58,168 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-11-18 14:48:58,182 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-right_vn-60350874', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-68801294:ctest-right_vn-60350874?flat'} 2025-11-18 14:48:58,183 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-68801294:ctest-right_vn-60350874 is found in opserver 2025-11-18 14:48:58,195 - DEBUG - Do not have enough data to verify VN in agent 2025-11-18 14:48:58,202 - DEBUG - VRF ids for VN ctest-right_vn-60350874: {} 2025-11-18 14:48:58,297 - DEBUG - Services list from nova: [, , ] 2025-11-18 14:48:59,451 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4756-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4756-1) 2025-11-18 14:49:00,760 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4756-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4756-1) 2025-11-18 14:49:00,760 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-92581563'] 2025-11-18 14:49:01,158 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-92581563'] created succesfully 2025-11-18 14:49:01,507 - DEBUG - Image by name cirros_in_net not found 2025-11-18 14:49:01,507 - DEBUG - Installing image cirros_in_net 2025-11-18 14:49:01,507 - 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-18 14:49:01,508 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-11-18 14:49:01,508 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-11-18 14:49:01,532 - 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-18 14:49:01,646 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz 2025-11-18 14:49:01,647 - DEBUG - Unzip image 2025-11-18 14:49:01,647 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz 2025-11-18 14:49:01,857 - DEBUG - Try to create image in user context 2025-11-18 14:49:02,175 - DEBUG - Image has been created in user context 2025-11-18 14:49:02,175 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk 2025-11-18 14:49:02,197 - DEBUG - Downloaded image has been removed 2025-11-18 14:49:02,217 - DEBUG - Uploaded image status = active 2025-11-18 14:49:03,675 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4756-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4756-1) 2025-11-18 14:49:03,675 - INFO - Waiting for VM ctest-pt_svm0-13516399 to be up.. 2025-11-18 14:49:03,736 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-18 14:49:08,841 - DEBUG - VM is in ACTIVE state now 2025-11-18 14:49:08,841 - INFO - VM name : ctest-pt_svm0-13516399 2025-11-18 14:49:08,936 - DEBUG - VM ctest-pt_svm0-13516399 ID is 0bd69048-d97b-4ab7-82ff-b44e0000b2fa 2025-11-18 14:49:08,961 - DEBUG - VM ctest-pt_svm0-13516399 launched on Node cn-jenkins-deploy-platform-ansible-os-4756-1 2025-11-18 14:49:09,254 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/0bd69048-d97b-4ab7-82ff-b44e0000b2fa 2025-11-18 14:49:09,265 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/5528b16e-635b-4e67-bf23-4d74ca5955a7 2025-11-18 14:49:09,276 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/edbc10f1-4dda-48d6-9c62-37a04bac560c 2025-11-18 14:49:09,288 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/fa401dee-d188-43f0-8dbb-371e846da066 2025-11-18 14:49:12,599 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms') 2025-11-18 14:49:12,600 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-13516399 failed! 2025-11-18 14:49:12,658 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937 is 157.178.227.65 and allocation pool is NOT set 2025-11-18 14:49:16,725 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1002ms') 2025-11-18 14:49:16,725 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-13516399 failed! 2025-11-18 14:49:16,782 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937 is 157.178.227.65 and allocation pool is NOT set 2025-11-18 14:49:20,853 - 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 1004ms') 2025-11-18 14:49:20,853 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-13516399 failed! 2025-11-18 14:49:20,918 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937 is 157.178.227.65 and allocation pool is NOT set 2025-11-18 14:49:25,015 - 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 1030ms') 2025-11-18 14:49:25,015 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-13516399 failed! 2025-11-18 14:49:25,074 - DEBUG - Gateway for vn default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937 is 157.178.227.65 and allocation pool is NOT set 2025-11-18 14:49:29,140 - 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=2 ttl=63 time=6.50 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 6.504/6.504/6.504/0.000 ms') 2025-11-18 14:49:29,140 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-pt_svm0-13516399 passed 2025-11-18 14:49:29,294 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:49:29,294 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-13516399, IP 157.178.227.67, Port 22 2025-11-18 14:49:29,360 - 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-18 14:49:29,512 - DEBUG - VM ctest-pt_svm0-13516399 is NOT ready for SSH connections, VM status: ACTIVE 2025-11-18 14:49:34,513 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:49:34,513 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-13516399, IP 157.178.227.67, Port 22 2025-11-18 14:49:34,570 - 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-18 14:49:34,671 - DEBUG - VM ctest-pt_svm0-13516399 is NOT ready for SSH connections, VM status: ACTIVE 2025-11-18 14:49:39,672 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:49:39,672 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-13516399, IP 157.178.227.67, Port 22 2025-11-18 14:49:39,740 - 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-18 14:49:39,838 - DEBUG - VM ctest-pt_svm0-13516399 is NOT ready for SSH connections, VM status: ACTIVE 2025-11-18 14:49:44,839 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:49:44,840 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-13516399, IP 157.178.227.67, Port 22 2025-11-18 14:49:44,911 - 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-18 14:49:45,018 - DEBUG - VM ctest-pt_svm0-13516399 is NOT ready for SSH connections, VM status: ACTIVE 2025-11-18 14:49:50,019 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:49:50,019 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-13516399, IP 157.178.227.67, Port 22 2025-11-18 14:49:50,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-18 14:49:50,185 - DEBUG - VM ctest-pt_svm0-13516399 is NOT ready for SSH connections, VM status: ACTIVE 2025-11-18 14:49:55,186 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:49:55,186 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-13516399, IP 157.178.227.67, Port 22 2025-11-18 14:49:55,251 - 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-18 14:49:55,341 - DEBUG - VM ctest-pt_svm0-13516399 is NOT ready for SSH connections, VM status: ACTIVE 2025-11-18 14:50:00,342 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:50:00,342 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-13516399, IP 157.178.227.67, Port 22 2025-11-18 14:50:00,512 - DEBUG - VM ctest-pt_svm0-13516399 is ready for SSH connections 2025-11-18 14:50:00,674 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:50:00,674 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-13516399, IP 157.178.227.67, Port 22 2025-11-18 14:50:00,844 - DEBUG - VM ctest-pt_svm0-13516399 is ready for SSH connections 2025-11-18 14:50:01,002 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:50:01,002 - DEBUG - Waiting to SSH to VM ctest-pt_svm0-13516399, IP 157.178.227.67, Port 22 2025-11-18 14:50:01,168 - DEBUG - VM ctest-pt_svm0-13516399 is ready for SSH connections 2025-11-18 14:50:01,175 - DEBUG - Creating service instance: ['default-domain', 'ctest-TestSvcRegr-68801294', 'ctest-si-33953072'] 2025-11-18 14:50:02,129 - INFO - Waiting for VM ctest-left_vm-69491084 to be up.. 2025-11-18 14:50:02,254 - DEBUG - VM is in ACTIVE state now 2025-11-18 14:50:02,254 - INFO - VM name : ctest-left_vm-69491084 2025-11-18 14:50:02,370 - DEBUG - VM ctest-left_vm-69491084 ID is cea5c1f3-31d6-4132-b60d-fb69df708d31 2025-11-18 14:50:02,370 - DEBUG - VM ctest-left_vm-69491084 launched on Node cn-jenkins-deploy-platform-ansible-os-4756-1 2025-11-18 14:50:02,510 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/cea5c1f3-31d6-4132-b60d-fb69df708d31 2025-11-18 14:50:02,526 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/09f998ab-5f01-498a-ac54-ced9207fbfa2 2025-11-18 14:50:03,810 - 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=4.85 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.579 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.579/2.712/4.846/2.133 ms') 2025-11-18 14:50:03,810 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-left_vm-69491084 passed 2025-11-18 14:50:03,970 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:50:03,970 - DEBUG - Waiting to SSH to VM ctest-left_vm-69491084, IP 202.206.11.131, Port 22 2025-11-18 14:50:04,139 - DEBUG - VM ctest-left_vm-69491084 is ready for SSH connections 2025-11-18 14:50:04,140 - INFO - Waiting for VM ctest-right_vm-44365540 to be up.. 2025-11-18 14:50:04,248 - DEBUG - VM is in ACTIVE state now 2025-11-18 14:50:04,248 - INFO - VM name : ctest-right_vm-44365540 2025-11-18 14:50:04,358 - DEBUG - VM ctest-right_vm-44365540 ID is d17376a6-bd0f-4455-8653-f2143ae33e41 2025-11-18 14:50:04,358 - DEBUG - VM ctest-right_vm-44365540 launched on Node cn-jenkins-deploy-platform-ansible-os-4756-1 2025-11-18 14:50:04,463 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/d17376a6-bd0f-4455-8653-f2143ae33e41 2025-11-18 14:50:04,477 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/0d2f4a3b-75ad-4789-8315-1aeef14839dc 2025-11-18 14:50:05,728 - 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.19 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.521 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.521/1.857/3.194/1.336 ms') 2025-11-18 14:50:05,728 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-right_vm-44365540 passed 2025-11-18 14:50:05,890 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:50:05,890 - DEBUG - Waiting to SSH to VM ctest-right_vm-44365540, IP 108.173.93.3, Port 22 2025-11-18 14:50:06,070 - DEBUG - VM ctest-right_vm-44365540 is ready for SSH connections 2025-11-18 14:50:06,077 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408, 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-68801294:ctest-right_vn-60350874, 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-68801294:ctest-si-33953072'], 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-18 14:50:06,522 - INFO - Attaching policy ctest-policy-68165334 to vn ctest-left_vn-23097408 2025-11-18 14:50:07,073 - INFO - Attaching policy ctest-policy-68165334 to vn ctest-right_vn-60350874 2025-11-18 14:50:07,570 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-92581563'] created succesfully 2025-11-18 14:50:07,805 - DEBUG - Service instance: ['default-domain', 'ctest-TestSvcRegr-68801294', 'ctest-si-33953072'] created succesfully 2025-11-18 14:50:07,806 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-11-18 14:50:07,813 - DEBUG - Requesting: http://10.0.0.33:8082/domain/b498e8f4-7605-4ec1-81af-703b4297c23e 2025-11-18 14:50:07,826 - DEBUG - Requesting: http://10.0.0.33:8082/project/5390ca0b-2f10-406a-85e9-9f95d700efd8 2025-11-18 14:50:07,913 - DEBUG - Requesting: http://10.0.0.33:8082/service-instance/f5cfdbb7-078a-4f64-860a-5d0e137545a4 2025-11-18 14:50:07,926 - DEBUG - SI 'ctest-si-33953072' has service template ref to ctest-service_template_1-92581563 2025-11-18 14:50:07,926 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-11-18 14:50:07,933 - DEBUG - Requesting: http://10.0.0.33:8082/domain/b498e8f4-7605-4ec1-81af-703b4297c23e 2025-11-18 14:50:07,945 - DEBUG - Requesting: http://10.0.0.33:8082/project/5390ca0b-2f10-406a-85e9-9f95d700efd8 2025-11-18 14:50:08,031 - DEBUG - Requesting: http://10.0.0.33:8082/service-instance/f5cfdbb7-078a-4f64-860a-5d0e137545a4 2025-11-18 14:50:08,042 - DEBUG - Requesting: http://10.0.0.33:8082/port-tuple/fdac33ec-d338-4516-b867-97019b5908ba 2025-11-18 14:50:08,050 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/5528b16e-635b-4e67-bf23-4d74ca5955a7 2025-11-18 14:50:08,061 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/edbc10f1-4dda-48d6-9c62-37a04bac560c 2025-11-18 14:50:08,072 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/fa401dee-d188-43f0-8dbb-371e846da066 2025-11-18 14:50:08,337 - DEBUG - VM is in ACTIVE state now 2025-11-18 14:50:08,845 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/0bd69048-d97b-4ab7-82ff-b44e0000b2fa 2025-11-18 14:50:08,855 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/5528b16e-635b-4e67-bf23-4d74ca5955a7 2025-11-18 14:50:08,868 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/edbc10f1-4dda-48d6-9c62-37a04bac560c 2025-11-18 14:50:08,878 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/fa401dee-d188-43f0-8dbb-371e846da066 2025-11-18 14:50:08,889 - DEBUG - Requesting: http://10.0.0.33:8082/instance-ip/60ed14a1-fa47-41a4-b0fa-f339b35e21c3 2025-11-18 14:50:08,898 - DEBUG - Requesting: http://10.0.0.33:8082/instance-ip/7e10a8c2-5d66-4ec9-b897-7c6701d1558d 2025-11-18 14:50:08,908 - DEBUG - Requesting: http://10.0.0.33:8082/instance-ip/b4d0a87e-7026-4e6d-ae1a-12b637151eca 2025-11-18 14:50:08,918 - DEBUG - Requesting: http://10.0.0.33:8082/instance-ip/0260db3d-abc8-407b-a03e-4bec04a5da0a 2025-11-18 14:50:08,927 - DEBUG - Requesting: http://10.0.0.33:8082/instance-ip/43b6bf83-4dec-46cc-99ab-8402e64cad03 2025-11-18 14:50:08,936 - DEBUG - Requesting: http://10.0.0.33:8082/instance-ip/ebed6804-7c8d-4ee3-b864-86fb4c6f4763 2025-11-18 14:50:08,950 - DEBUG - Requesting: http://10.0.0.33:8082/instance-ip/6011e9b5-c491-4ab3-86dd-a272e48a561c 2025-11-18 14:50:08,958 - DEBUG - Requesting: http://10.0.0.33:8082/instance-ip/7808f1a5-5fd2-47a7-885b-4afbc4396b2a 2025-11-18 14:50:08,967 - DEBUG - Requesting: http://10.0.0.33:8082/instance-ip/7c490a88-0d3a-4667-8423-9ad733540905 2025-11-18 14:50:09,416 - DEBUG - VM is in ACTIVE state now 2025-11-18 14:50:09,417 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/0bd69048-d97b-4ab7-82ff-b44e0000b2fa 2025-11-18 14:50:09,426 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/5528b16e-635b-4e67-bf23-4d74ca5955a7 2025-11-18 14:50:09,437 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/edbc10f1-4dda-48d6-9c62-37a04bac560c 2025-11-18 14:50:09,448 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/fa401dee-d188-43f0-8dbb-371e846da066 2025-11-18 14:50:10,693 - 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.09 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=1.09 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 = 1.091/1.091/1.092/0.000 ms') 2025-11-18 14:50:10,693 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-pt_svm0-13516399 passed 2025-11-18 14:50:10,741 - DEBUG - VM ctest-pt_svm0-13516399 Tap interface: {'index': '6', 'name': 'tapfa401dee-d1', 'uuid': 'fa401dee-d188-43f0-8dbb-371e846da066', 'vrf_name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408:ctest-left_vn-23097408', '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': '13', 'vn_name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408', 'vm_uuid': '0bd69048-d97b-4ab7-82ff-b44e0000b2fa', 'vm_name': 'ctest-pt_svm0-13516399', 'ip_addr': '202.206.11.132', 'mac_addr': '02:fa:40:1d:ee:d1', '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-68801294:fa401dee-d188-43f0-8dbb-371e846da066', 'sg_uuid_list': ['472b3608-b645-43c0-8a93-c6b924c7fcb8'], 'static_route_list': None, 'vm_project_uuid': '5390ca0b-2f10-406a-85e9-9f95d700efd8', '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': '5528b16e-635b-4e67-bf23-4d74ca5955a7', '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-18 14:50:10,741 - DEBUG - Agent 10.0.0.33 vrf name: default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408:ctest-left_vn-23097408 2025-11-18 14:50:10,749 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408:ctest-left_vn-23097408', 'ucindex': '2', 'mcindex': '2', 'l2index': '2', 'source': 'Config; ', 'uc6index': '2', 'vn': 'default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408', 'table_label': '-1', 'vxlan_id': '13', 'evpnindex': '2', 'brindex': '2', 'mplsindex': '0', 'RD': '10.20.0.5:2', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-11-18 14:50:10,924 - DEBUG - VM ctest-pt_svm0-13516399 : Tap interface tapfa401dee-d1 is set to Active 2025-11-18 14:50:10,924 - DEBUG - Tap interface tapfa401dee-d1 detail : {'index': '6', 'name': 'tapfa401dee-d1', 'uuid': 'fa401dee-d188-43f0-8dbb-371e846da066', 'vrf_name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408:ctest-left_vn-23097408', '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': '13', 'vn_name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408', 'vm_uuid': '0bd69048-d97b-4ab7-82ff-b44e0000b2fa', 'vm_name': 'ctest-pt_svm0-13516399', 'ip_addr': '202.206.11.132', 'mac_addr': '02:fa:40:1d:ee:d1', '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-68801294:fa401dee-d188-43f0-8dbb-371e846da066', 'sg_uuid_list': ['472b3608-b645-43c0-8a93-c6b924c7fcb8'], 'static_route_list': None, 'vm_project_uuid': '5390ca0b-2f10-406a-85e9-9f95d700efd8', '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': '5528b16e-635b-4e67-bf23-4d74ca5955a7', '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-18 14:50:11,122 - DEBUG - Starting Layer 2 verification in Agent 2025-11-18 14:50:11,133 - DEBUG - Layer 2 path is seen for VM MAC 02:fa:40:1d:ee:d1 in agent 10.0.0.33 2025-11-18 14:50:11,133 - DEBUG - Active layer 2 route in agent is present for VMI tapfa401dee-d1 2025-11-18 14:50:11,133 - DEBUG - L2 label(48) matches bw route table and interface table 2025-11-18 14:50:11,140 - DEBUG - VN default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408 is found in Agent of node 10.0.0.33 2025-11-18 14:50:11,358 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408 is consistent in agent 10.0.0.33 2025-11-18 14:50:11,359 - DEBUG - Route for VM IP ['202.206.11.132'] is consistent in agent 10.0.0.33 2025-11-18 14:50:11,359 - DEBUG - VN default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408 verification for VM ctest-pt_svm0-13516399 in Agent 10.0.0.33 passed 2025-11-18 14:50:11,557 - DEBUG - Starting all layer 2 verification in agent 10.0.0.33 2025-11-18 14:50:11,570 - DEBUG - Route for VM MAC 02:fa:40:1d:ee:d1 is consistent in agent 10.0.0.33 2025-11-18 14:50:11,593 - DEBUG - VM ctest-pt_svm0-13516399 Tap interface: {'index': '5', 'name': 'tapedbc10f1-4d', 'uuid': 'edbc10f1-4dda-48d6-9c62-37a04bac560c', 'vrf_name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937:ctest-mgmt_vn-54560937', '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': '12', 'vn_name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937', 'vm_uuid': '0bd69048-d97b-4ab7-82ff-b44e0000b2fa', 'vm_name': 'ctest-pt_svm0-13516399', 'ip_addr': '157.178.227.67', 'mac_addr': '02:ed:bc:10:f1:4d', '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-68801294:edbc10f1-4dda-48d6-9c62-37a04bac560c', 'sg_uuid_list': ['472b3608-b645-43c0-8a93-c6b924c7fcb8'], 'static_route_list': None, 'vm_project_uuid': '5390ca0b-2f10-406a-85e9-9f95d700efd8', '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-18 14:50:11,593 - DEBUG - Agent 10.0.0.33 vrf name: default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937:ctest-mgmt_vn-54560937 2025-11-18 14:50:11,599 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937:ctest-mgmt_vn-54560937', 'ucindex': '4', 'mcindex': '4', 'l2index': '4', 'source': 'Config; ', 'uc6index': '4', 'vn': 'default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937', 'table_label': '-1', 'vxlan_id': '12', 'evpnindex': '4', 'brindex': '4', 'mplsindex': '0', 'RD': '10.20.0.5:4', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-11-18 14:50:11,768 - DEBUG - VM ctest-pt_svm0-13516399 : Tap interface tapedbc10f1-4d is set to Active 2025-11-18 14:50:11,768 - DEBUG - Tap interface tapedbc10f1-4d detail : {'index': '5', 'name': 'tapedbc10f1-4d', 'uuid': 'edbc10f1-4dda-48d6-9c62-37a04bac560c', 'vrf_name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937:ctest-mgmt_vn-54560937', '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': '12', 'vn_name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937', 'vm_uuid': '0bd69048-d97b-4ab7-82ff-b44e0000b2fa', 'vm_name': 'ctest-pt_svm0-13516399', 'ip_addr': '157.178.227.67', 'mac_addr': '02:ed:bc:10:f1:4d', '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-68801294:edbc10f1-4dda-48d6-9c62-37a04bac560c', 'sg_uuid_list': ['472b3608-b645-43c0-8a93-c6b924c7fcb8'], 'static_route_list': None, 'vm_project_uuid': '5390ca0b-2f10-406a-85e9-9f95d700efd8', '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-18 14:50:11,966 - DEBUG - Starting Layer 2 verification in Agent 2025-11-18 14:50:11,980 - DEBUG - Layer 2 path is seen for VM MAC 02:ed:bc:10:f1:4d in agent 10.0.0.33 2025-11-18 14:50:11,980 - DEBUG - Active layer 2 route in agent is present for VMI tapedbc10f1-4d 2025-11-18 14:50:11,980 - DEBUG - L2 label(43) matches bw route table and interface table 2025-11-18 14:50:11,986 - DEBUG - VN default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937 is found in Agent of node 10.0.0.33 2025-11-18 14:50:12,200 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937 is consistent in agent 10.0.0.33 2025-11-18 14:50:12,200 - DEBUG - Route for VM IP ['157.178.227.67'] is consistent in agent 10.0.0.33 2025-11-18 14:50:12,200 - DEBUG - VN default-domain:ctest-TestSvcRegr-68801294:ctest-mgmt_vn-54560937 verification for VM ctest-pt_svm0-13516399 in Agent 10.0.0.33 passed 2025-11-18 14:50:12,406 - DEBUG - Starting all layer 2 verification in agent 10.0.0.33 2025-11-18 14:50:12,422 - DEBUG - Route for VM MAC 02:ed:bc:10:f1:4d is consistent in agent 10.0.0.33 2025-11-18 14:50:12,451 - DEBUG - VM ctest-pt_svm0-13516399 Tap interface: {'index': '7', 'name': 'tap5528b16e-63', 'uuid': '5528b16e-635b-4e67-bf23-4d74ca5955a7', 'vrf_name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-right_vn-60350874:ctest-right_vn-60350874', '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': '14', 'vn_name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-right_vn-60350874', 'vm_uuid': '0bd69048-d97b-4ab7-82ff-b44e0000b2fa', 'vm_name': 'ctest-pt_svm0-13516399', 'ip_addr': '108.173.93.4', 'mac_addr': '02:55:28:b1:6e:63', '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-68801294:5528b16e-635b-4e67-bf23-4d74ca5955a7', 'sg_uuid_list': ['472b3608-b645-43c0-8a93-c6b924c7fcb8'], 'static_route_list': None, 'vm_project_uuid': '5390ca0b-2f10-406a-85e9-9f95d700efd8', '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': 'fa401dee-d188-43f0-8dbb-371e846da066', '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-18 14:50:12,452 - DEBUG - Agent 10.0.0.33 vrf name: default-domain:ctest-TestSvcRegr-68801294:ctest-right_vn-60350874:ctest-right_vn-60350874 2025-11-18 14:50:12,461 - DEBUG - Agent VRF Object : {'vrf_list': [{'name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-right_vn-60350874:ctest-right_vn-60350874', 'ucindex': '3', 'mcindex': '3', 'l2index': '3', 'source': 'Config; ', 'uc6index': '3', 'vn': 'default-domain:ctest-TestSvcRegr-68801294:ctest-right_vn-60350874', 'table_label': '-1', 'vxlan_id': '14', 'evpnindex': '3', 'brindex': '3', 'mplsindex': '0', 'RD': '10.20.0.5:3', 'mac_aging_time': '0', 'layer2_control_word': 'false', 'forwarding_vrf': None, 'hbf_rintf': '-1', 'hbf_lintf': '-1'}]} 2025-11-18 14:50:12,630 - DEBUG - VM ctest-pt_svm0-13516399 : Tap interface tap5528b16e-63 is set to Active 2025-11-18 14:50:12,631 - DEBUG - Tap interface tap5528b16e-63 detail : {'index': '7', 'name': 'tap5528b16e-63', 'uuid': '5528b16e-635b-4e67-bf23-4d74ca5955a7', 'vrf_name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-right_vn-60350874:ctest-right_vn-60350874', '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': '14', 'vn_name': 'default-domain:ctest-TestSvcRegr-68801294:ctest-right_vn-60350874', 'vm_uuid': '0bd69048-d97b-4ab7-82ff-b44e0000b2fa', 'vm_name': 'ctest-pt_svm0-13516399', 'ip_addr': '108.173.93.4', 'mac_addr': '02:55:28:b1:6e:63', '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-68801294:5528b16e-635b-4e67-bf23-4d74ca5955a7', 'sg_uuid_list': ['472b3608-b645-43c0-8a93-c6b924c7fcb8'], 'static_route_list': None, 'vm_project_uuid': '5390ca0b-2f10-406a-85e9-9f95d700efd8', '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': 'fa401dee-d188-43f0-8dbb-371e846da066', '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-18 14:50:12,834 - DEBUG - Starting Layer 2 verification in Agent 2025-11-18 14:50:12,847 - DEBUG - Layer 2 path is seen for VM MAC 02:55:28:b1:6e:63 in agent 10.0.0.33 2025-11-18 14:50:12,848 - DEBUG - Active layer 2 route in agent is present for VMI tap5528b16e-63 2025-11-18 14:50:12,848 - DEBUG - L2 label(53) matches bw route table and interface table 2025-11-18 14:50:12,854 - DEBUG - VN default-domain:ctest-TestSvcRegr-68801294:ctest-right_vn-60350874 is found in Agent of node 10.0.0.33 2025-11-18 14:50:13,084 - DEBUG - VRF IDs of VN default-domain:ctest-TestSvcRegr-68801294:ctest-right_vn-60350874 is consistent in agent 10.0.0.33 2025-11-18 14:50:13,084 - DEBUG - Route for VM IP ['108.173.93.4'] is consistent in agent 10.0.0.33 2025-11-18 14:50:13,084 - DEBUG - VN default-domain:ctest-TestSvcRegr-68801294:ctest-right_vn-60350874 verification for VM ctest-pt_svm0-13516399 in Agent 10.0.0.33 passed 2025-11-18 14:50:13,290 - DEBUG - Starting all layer 2 verification in agent 10.0.0.33 2025-11-18 14:50:13,303 - DEBUG - Route for VM MAC 02:55:28:b1:6e:63 is consistent in agent 10.0.0.33 2025-11-18 14:50:13,304 - INFO - VM ctest-pt_svm0-13516399 verifications in Compute nodes passed 2025-11-18 14:50:13,304 - DEBUG - The SVMs in the SI are : [] 2025-11-18 14:50:13,304 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/0bd69048-d97b-4ab7-82ff-b44e0000b2fa 2025-11-18 14:50:13,319 - DEBUG - Service VM for SI 'ctest-si-33953072' is launched 2025-11-18 14:50:13,319 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-11-18 14:50:13,326 - DEBUG - Requesting: http://10.0.0.33:8082/domain/b498e8f4-7605-4ec1-81af-703b4297c23e 2025-11-18 14:50:13,341 - DEBUG - Requesting: http://10.0.0.33:8082/project/5390ca0b-2f10-406a-85e9-9f95d700efd8 2025-11-18 14:50:13,427 - DEBUG - Requesting: http://10.0.0.33:8082/service-instance/f5cfdbb7-078a-4f64-860a-5d0e137545a4 2025-11-18 14:50:13,438 - DEBUG - SI ctest-si-33953072 has Port Tuple: ['ctest-port_tuple-07177984'] 2025-11-18 14:50:13,550 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-68801294:ctest-left_vn-23097408 2025-11-18 14:50:13,698 - INFO - VMI and/or RI refs are present for VN default-domain:ctest-TestSvcRegr-68801294:ctest-right_vn-60350874 2025-11-18 14:50:13,864 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:50:13,877 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:50:13,878 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:50:13,889 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:50:13,889 - INFO - Route to 108.173.93.3 seen in VRF:5 on cn-jenkins-deploy-platform-ansible-os-4756-1, and SI ctest-si-33953072 is seen as the NH 2025-11-18 14:50:14,057 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:50:14,067 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:50:14,067 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:50:14,073 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:50:14,073 - INFO - Route to 202.206.11.131 seen in VRF:6 on cn-jenkins-deploy-platform-ansible-os-4756-1, and SI ctest-si-33953072 is seen as the NH 2025-11-18 14:50:14,074 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:50:14,074 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 14:50:14,074 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 108.173.93.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2025-11-18 14:50:14,074 - DEBUG - ping -s 56 -c 3 -W 1 108.173.93.3 2025-11-18 14:50:18,271 - DEBUG - PING 108.173.93.3 (108.173.93.3): 56 data bytes 64 bytes from 108.173.93.3: seq=0 ttl=61 time=7.386 ms 64 bytes from 108.173.93.3: seq=1 ttl=61 time=2.770 ms 64 bytes from 108.173.93.3: seq=2 ttl=61 time=4.395 ms --- 108.173.93.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 2.770/4.850/7.386 ms 2025-11-18 14:50:18,272 - INFO - Ping to IP 108.173.93.3 from VM ctest-left_vm-69491084 passed 2025-11-18 14:50:18,272 - INFO - Dettaching policy ctest-policy-68165334 from vn ctest-right_vn-60350874 2025-11-18 14:50:18,489 - INFO - Dettaching policy ctest-policy-68165334 from vn ctest-left_vn-23097408 2025-11-18 14:50:18,796 - INFO - Deleted policy ctest-policy-68165334 2025-11-18 14:50:18,797 - DEBUG - Deleting service instance: ['default-domain', 'ctest-TestSvcRegr-68801294', 'ctest-si-33953072'] 2025-11-18 14:50:18,934 - INFO - Deleted SI ['default-domain', 'ctest-TestSvcRegr-68801294', 'ctest-si-33953072'] 2025-11-18 14:50:18,934 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-11-18 14:50:18,942 - DEBUG - Requesting: http://10.0.0.33:8082/domain/b498e8f4-7605-4ec1-81af-703b4297c23e 2025-11-18 14:50:18,979 - DEBUG - Requesting: http://10.0.0.33:8082/project/5390ca0b-2f10-406a-85e9-9f95d700efd8 2025-11-18 14:50:19,087 - DEBUG - Service instance ctest-si-33953072 removed from api server 2025-11-18 14:50:19,087 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/0bd69048-d97b-4ab7-82ff-b44e0000b2fa 2025-11-18 14:50:19,102 - DEBUG - All Service VMs unlinked from SI ctest-si-33953072 2025-11-18 14:50:19,108 - DEBUG - 0 svc intances found in all projects. They are [] 2025-11-18 14:50:19,108 - DEBUG - 0 svc intances found in current project. They are [] 2025-11-18 14:50:19,158 - DEBUG - 0 svc intances found in all projects. They are [] 2025-11-18 14:50:19,158 - DEBUG - 0 svc intances found in current project. They are [] 2025-11-18 14:50:19,158 - INFO - Deleting VM ctest-pt_svm0-13516399 2025-11-18 14:50:19,264 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-92581563'] 2025-11-18 14:50:19,378 - INFO - Service template: ['default-domain', 'ctest-service_template_1-92581563'] deleted successfully. 2025-11-18 14:50:19,378 - INFO - Deleting VM ctest-right_vm-44365540 2025-11-18 14:50:19,491 - INFO - Deleting VM ctest-left_vm-69491084 2025-11-18 14:50:19,652 - INFO - Deleting VN ctest-right_vn-60350874 2025-11-18 14:50:19,713 - DEBUG - VN 0fe047b2-d64b-4e7a-be50-e7dd683ced3c still in use: Unable to complete operation on network 0fe047b2-d64b-4e7a-be50-e7dd683ced3c. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-96f0bd30-b9fd-48ff-842f-30fbc714b395'] 2025-11-18 14:50:19,713 - WARNING - Deleting VN ctest-right_vn-60350874 failed..Will retry 2025-11-18 14:50:21,848 - DEBUG - Response for deleting network () 2025-11-18 14:50:21,848 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/a7d953ed-3bae-4986-9159-c52679cd6218 2025-11-18 14:50:21,857 - DEBUG - Response Code: 404 2025-11-18 14:50:21,857 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-11-18 14:50:21,864 - DEBUG - Requesting: http://10.0.0.33:8082/domain/b498e8f4-7605-4ec1-81af-703b4297c23e 2025-11-18 14:50:21,925 - DEBUG - Requesting: http://10.0.0.33:8082/project/5390ca0b-2f10-406a-85e9-9f95d700efd8 2025-11-18 14:50:22,002 - INFO - Validated that VN ctest-right_vn-60350874 is not found in API Server 2025-11-18 14:50:22,016 - DEBUG - VN ctest-right_vn-60350874 is not present in Agent 10.0.0.33 2025-11-18 14:50:22,016 - INFO - Validated that VN ctest-right_vn-60350874 is not in any agent 2025-11-18 14:50:22,037 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-60350874 info 2025-11-18 14:50:22,037 - INFO - Deleting VN ctest-left_vn-23097408 2025-11-18 14:50:22,183 - DEBUG - Response for deleting network () 2025-11-18 14:50:22,184 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/b4b480f4-31c3-49b2-877c-310abeaea247 2025-11-18 14:50:22,189 - DEBUG - Response Code: 404 2025-11-18 14:50:22,189 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-11-18 14:50:22,197 - DEBUG - Requesting: http://10.0.0.33:8082/domain/b498e8f4-7605-4ec1-81af-703b4297c23e 2025-11-18 14:50:22,255 - DEBUG - Requesting: http://10.0.0.33:8082/project/5390ca0b-2f10-406a-85e9-9f95d700efd8 2025-11-18 14:50:22,337 - INFO - Validated that VN ctest-left_vn-23097408 is not found in API Server 2025-11-18 14:50:22,349 - DEBUG - VN ctest-left_vn-23097408 is not present in Agent 10.0.0.33 2025-11-18 14:50:22,349 - INFO - Validated that VN ctest-left_vn-23097408 is not in any agent 2025-11-18 14:50:22,366 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-23097408 info 2025-11-18 14:50:22,366 - INFO - Deleting VN ctest-mgmt_vn-54560937 2025-11-18 14:50:22,503 - DEBUG - Response for deleting network () 2025-11-18 14:50:22,503 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/47dc9476-b7b4-44c7-8b91-bd758746caf2 2025-11-18 14:50:22,510 - DEBUG - Response Code: 404 2025-11-18 14:50:22,511 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-11-18 14:50:22,519 - DEBUG - Requesting: http://10.0.0.33:8082/domain/b498e8f4-7605-4ec1-81af-703b4297c23e 2025-11-18 14:50:22,575 - DEBUG - Requesting: http://10.0.0.33:8082/project/5390ca0b-2f10-406a-85e9-9f95d700efd8 2025-11-18 14:50:22,665 - INFO - Validated that VN ctest-mgmt_vn-54560937 is not found in API Server 2025-11-18 14:50:22,676 - DEBUG - VN ctest-mgmt_vn-54560937 is not present in Agent 10.0.0.33 2025-11-18 14:50:22,676 - INFO - Validated that VN ctest-mgmt_vn-54560937 is not in any agent 2025-11-18 14:50:22,693 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-54560937 info 2025-11-18 14:50:22,957 - DEBUG - No XMPP flaps were noticed during the test 2025-11-18 14:50:22,957 - INFO -
2025-11-18 14:50:22,957 - INFO - END TEST : test_svc_in_network_datapath : PASSED[0:01:29]
2025-11-18 14:50:22,957 - INFO - -------------------------------------------------------------------------------- 2025-11-18 14:50:23,764 - INFO - Deleted project: ctest-TestSvcRegr-68801294, ID : 5390ca0b-2f10-406a-85e9-9f95d700efd8