2025-06-24 19:37:43,659 - INFO - Domain Default found not creating 2025-06-24 19:37:43,807 - INFO - Project ctest-TestSvcRegr-27215922 not found, creating it 2025-06-24 19:37:44,318 - INFO - Created Project:ctest-TestSvcRegr-27215922, ID : afb93c2b-921a-47aa-82a0-10234e1d0f2b 2025-06-24 19:37:46,551 - INFO - ================================================================================ 2025-06-24 19:37:46,551 - INFO - STARTING TEST : test_svc_in_network_datapath 2025-06-24 19:37:46,809 - DEBUG - Nothing to compare xmpp stats {'10.0.0.33': {'10.20.0.17': '0'}} with 2025-06-24 19:37:46,810 - INFO - Initial checks done. Running the testcase now 2025-06-24 19:37:46,810 - INFO - 2025-06-24 19:37:47,372 - DEBUG - Response for create_network : {'network': {'id': '1980d1f9-ac14-412c-81bf-d23e93b7b0da', 'name': 'ctest-mgmt_vn-47472286', 'tenant_id': 'afb93c2b921a47aa82a010234e1d0f2b', 'project_id': 'afb93c2b921a47aa82a010234e1d0f2b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-27215922', 'ctest-mgmt_vn-47472286'], 'port_security_enabled': True, 'description': ''}} 2025-06-24 19:37:47,565 - DEBUG - Response for create_subnet : {'subnet': {'id': '16336a64-2e25-4ff0-b18e-3aab3b4194d1', 'name': '', 'tenant_id': 'afb93c2b921a47aa82a010234e1d0f2b', 'network_id': '1980d1f9-ac14-412c-81bf-d23e93b7b0da', 'ip_version': 4, 'cidr': '199.193.24.128/26', 'allocation_pools': [{'start': '199.193.24.130', 'end': '199.193.24.190'}], 'gateway_ip': '199.193.24.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '199.193.24.130', 'tags': [], 'project_id': 'afb93c2b921a47aa82a010234e1d0f2b'}} 2025-06-24 19:37:47,583 - INFO - Created VN ctest-mgmt_vn-47472286 2025-06-24 19:37:47,638 - DEBUG - VN ctest-mgmt_vn-47472286 UUID is 1980d1f9-ac14-412c-81bf-d23e93b7b0da 2025-06-24 19:37:47,766 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-06-24 19:37:48,102 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-06-24 19:37:48,135 - DEBUG - Requesting: http://10.0.0.33:8082/domain/dc75db7d-aaef-4a5a-b932-dc19bbe78927 2025-06-24 19:37:48,149 - DEBUG - Requesting: http://10.0.0.33:8082/project/afb93c2b-921a-47aa-82a0-10234e1d0f2b 2025-06-24 19:37:48,226 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1980d1f9-ac14-412c-81bf-d23e93b7b0da 2025-06-24 19:37:48,239 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1980d1f9-ac14-412c-81bf-d23e93b7b0da 2025-06-24 19:37:48,250 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/9417e528-ea9b-4a9d-9573-b7593bb019fc 2025-06-24 19:37:48,260 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/9417e528-ea9b-4a9d-9573-b7593bb019fc 2025-06-24 19:37:48,268 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/0f4bfb60-b5b0-4363-a27e-4ce5028c860e 2025-06-24 19:37:48,276 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-06-24 19:37:48,276 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1980d1f9-ac14-412c-81bf-d23e93b7b0da 2025-06-24 19:37:48,288 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/9417e528-ea9b-4a9d-9573-b7593bb019fc 2025-06-24 19:37:48,304 - INFO - Verified VN network id 8 for VN 1980d1f9-ac14-412c-81bf-d23e93b7b0da 2025-06-24 19:37:48,304 - INFO - Verifications in API Server for VN ctest-mgmt_vn-47472286 passed 2025-06-24 19:37:48,304 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1980d1f9-ac14-412c-81bf-d23e93b7b0da 2025-06-24 19:37:48,315 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/9417e528-ea9b-4a9d-9573-b7593bb019fc 2025-06-24 19:37:48,324 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/9417e528-ea9b-4a9d-9573-b7593bb019fc 2025-06-24 19:37:48,333 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/0f4bfb60-b5b0-4363-a27e-4ce5028c860e 2025-06-24 19:37:48,346 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27215922:ctest-mgmt_vn-47472286', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27215922', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '1837699518231232812', 'uuid-lslong': '9349422517652533466'}, 'enable': 'true', 'created': '2025-06-24T19:37:47', 'last-modified': '2025-06-24T19:37:47', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.812033'} 2025-06-24 19:37:48,353 - DEBUG - Route Targets: ['target:64512:8000005'] 2025-06-24 19:37:48,353 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-mgmt_vn-47472286 passed 2025-06-24 19:37:48,353 - DEBUG - ====Verifying policy data for ctest-mgmt_vn-47472286 in API_Server ====== 2025-06-24 19:37:48,353 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-06-24 19:37:48,359 - DEBUG - Requesting: http://10.0.0.33:8082/domain/dc75db7d-aaef-4a5a-b932-dc19bbe78927 2025-06-24 19:37:48,370 - DEBUG - Requesting: http://10.0.0.33:8082/project/afb93c2b-921a-47aa-82a0-10234e1d0f2b 2025-06-24 19:37:48,446 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/1980d1f9-ac14-412c-81bf-d23e93b7b0da 2025-06-24 19:37:48,457 - DEBUG - =>VN ctest-mgmt_vn-47472286 has no policy to be verified 2025-06-24 19:37:48,457 - DEBUG - Verifying the vn in opserver 2025-06-24 19:37:48,457 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27215922:ctest-mgmt_vn-47472286 virtual network link through opserver 10.0.0.33 2025-06-24 19:37:48,457 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-06-24 19:37:48,791 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-06-24 19:37:48,849 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27215922:ctest-mgmt_vn-47472286', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27215922:ctest-mgmt_vn-47472286?flat'} 2025-06-24 19:37:48,849 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-27215922:ctest-mgmt_vn-47472286 is found in opserver 2025-06-24 19:37:48,862 - DEBUG - Do not have enough data to verify VN in agent 2025-06-24 19:37:48,867 - DEBUG - VRF ids for VN ctest-mgmt_vn-47472286: {} 2025-06-24 19:37:49,052 - DEBUG - Response for create_network : {'network': {'id': 'f1a77a43-a893-4345-a2bf-9e842ef60e67', 'name': 'ctest-left_vn-51309012', 'tenant_id': 'afb93c2b921a47aa82a010234e1d0f2b', 'project_id': 'afb93c2b921a47aa82a010234e1d0f2b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-27215922', 'ctest-left_vn-51309012'], 'port_security_enabled': True, 'description': ''}} 2025-06-24 19:37:49,229 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f99fc16b-4c2e-4b3e-9831-0fec3e3c1621', 'name': '', 'tenant_id': 'afb93c2b921a47aa82a010234e1d0f2b', 'network_id': 'f1a77a43-a893-4345-a2bf-9e842ef60e67', 'ip_version': 4, 'cidr': '48.131.242.64/26', 'allocation_pools': [{'start': '48.131.242.66', 'end': '48.131.242.126'}], 'gateway_ip': '48.131.242.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '48.131.242.66', 'tags': [], 'project_id': 'afb93c2b921a47aa82a010234e1d0f2b'}} 2025-06-24 19:37:49,248 - INFO - Created VN ctest-left_vn-51309012 2025-06-24 19:37:49,302 - DEBUG - VN ctest-left_vn-51309012 UUID is f1a77a43-a893-4345-a2bf-9e842ef60e67 2025-06-24 19:37:49,416 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-06-24 19:37:49,424 - DEBUG - Requesting: http://10.0.0.33:8082/domain/dc75db7d-aaef-4a5a-b932-dc19bbe78927 2025-06-24 19:37:49,440 - DEBUG - Requesting: http://10.0.0.33:8082/project/afb93c2b-921a-47aa-82a0-10234e1d0f2b 2025-06-24 19:37:49,531 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/f1a77a43-a893-4345-a2bf-9e842ef60e67 2025-06-24 19:37:49,548 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/f1a77a43-a893-4345-a2bf-9e842ef60e67 2025-06-24 19:37:49,562 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/45174ff3-ae59-4347-97d6-72682dc3eb48 2025-06-24 19:37:49,571 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/45174ff3-ae59-4347-97d6-72682dc3eb48 2025-06-24 19:37:49,580 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/e376452a-3006-4c92-a70c-6aae5f2cd383 2025-06-24 19:37:49,587 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-06-24 19:37:49,587 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/f1a77a43-a893-4345-a2bf-9e842ef60e67 2025-06-24 19:37:49,597 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/45174ff3-ae59-4347-97d6-72682dc3eb48 2025-06-24 19:37:49,612 - INFO - Verified VN network id 9 for VN f1a77a43-a893-4345-a2bf-9e842ef60e67 2025-06-24 19:37:49,612 - INFO - Verifications in API Server for VN ctest-left_vn-51309012 passed 2025-06-24 19:37:49,612 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/f1a77a43-a893-4345-a2bf-9e842ef60e67 2025-06-24 19:37:49,621 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/45174ff3-ae59-4347-97d6-72682dc3eb48 2025-06-24 19:37:49,629 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/45174ff3-ae59-4347-97d6-72682dc3eb48 2025-06-24 19:37:49,636 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/e376452a-3006-4c92-a70c-6aae5f2cd383 2025-06-24 19:37:49,648 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27215922:ctest-left_vn-51309012', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27215922', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '17413020915260932933', 'uuid-lslong': '11727266245256810087'}, 'enable': 'true', 'created': '2025-06-24T19:37:49', 'last-modified': '2025-06-24T19:37:49', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.456366'} 2025-06-24 19:37:49,655 - DEBUG - Route Targets: ['target:64512:8000006'] 2025-06-24 19:37:49,655 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-left_vn-51309012 passed 2025-06-24 19:37:49,655 - DEBUG - ====Verifying policy data for ctest-left_vn-51309012 in API_Server ====== 2025-06-24 19:37:49,655 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-06-24 19:37:49,661 - DEBUG - Requesting: http://10.0.0.33:8082/domain/dc75db7d-aaef-4a5a-b932-dc19bbe78927 2025-06-24 19:37:49,673 - DEBUG - Requesting: http://10.0.0.33:8082/project/afb93c2b-921a-47aa-82a0-10234e1d0f2b 2025-06-24 19:37:49,748 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/f1a77a43-a893-4345-a2bf-9e842ef60e67 2025-06-24 19:37:49,759 - DEBUG - =>VN ctest-left_vn-51309012 has no policy to be verified 2025-06-24 19:37:49,759 - DEBUG - Verifying the vn in opserver 2025-06-24 19:37:49,759 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27215922:ctest-left_vn-51309012 virtual network link through opserver 10.0.0.33 2025-06-24 19:37:49,759 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-06-24 19:37:49,770 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27215922:ctest-left_vn-51309012', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27215922:ctest-left_vn-51309012?flat'} 2025-06-24 19:37:49,770 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-27215922:ctest-left_vn-51309012 is found in opserver 2025-06-24 19:37:49,782 - DEBUG - Do not have enough data to verify VN in agent 2025-06-24 19:37:49,788 - DEBUG - VRF ids for VN ctest-left_vn-51309012: {} 2025-06-24 19:37:49,989 - DEBUG - Response for create_network : {'network': {'id': '0677c177-818c-46ed-84f7-84ec389a7dbb', 'name': 'ctest-right_vn-11555394', 'tenant_id': 'afb93c2b921a47aa82a010234e1d0f2b', 'project_id': 'afb93c2b921a47aa82a010234e1d0f2b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestSvcRegr-27215922', 'ctest-right_vn-11555394'], 'port_security_enabled': True, 'description': ''}} 2025-06-24 19:37:50,197 - DEBUG - Response for create_subnet : {'subnet': {'id': '9a8d903e-41de-4f6f-9d09-37f3f75357c0', 'name': '', 'tenant_id': 'afb93c2b921a47aa82a010234e1d0f2b', 'network_id': '0677c177-818c-46ed-84f7-84ec389a7dbb', 'ip_version': 4, 'cidr': '26.136.210.0/26', 'allocation_pools': [{'start': '26.136.210.2', 'end': '26.136.210.62'}], 'gateway_ip': '26.136.210.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '26.136.210.2', 'tags': [], 'project_id': 'afb93c2b921a47aa82a010234e1d0f2b'}} 2025-06-24 19:37:50,217 - INFO - Created VN ctest-right_vn-11555394 2025-06-24 19:37:50,270 - DEBUG - VN ctest-right_vn-11555394 UUID is 0677c177-818c-46ed-84f7-84ec389a7dbb 2025-06-24 19:37:50,381 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-06-24 19:37:50,387 - DEBUG - Requesting: http://10.0.0.33:8082/domain/dc75db7d-aaef-4a5a-b932-dc19bbe78927 2025-06-24 19:37:50,400 - DEBUG - Requesting: http://10.0.0.33:8082/project/afb93c2b-921a-47aa-82a0-10234e1d0f2b 2025-06-24 19:37:50,474 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/0677c177-818c-46ed-84f7-84ec389a7dbb 2025-06-24 19:37:50,488 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/0677c177-818c-46ed-84f7-84ec389a7dbb 2025-06-24 19:37:50,498 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/277375ed-7c80-4080-b3a4-feb387c44106 2025-06-24 19:37:50,506 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/277375ed-7c80-4080-b3a4-feb387c44106 2025-06-24 19:37:50,514 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/e34f9ab0-ed69-4895-8005-cace3886b1f4 2025-06-24 19:37:50,521 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-06-24 19:37:50,521 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/0677c177-818c-46ed-84f7-84ec389a7dbb 2025-06-24 19:37:50,531 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/277375ed-7c80-4080-b3a4-feb387c44106 2025-06-24 19:37:50,547 - INFO - Verified VN network id 10 for VN 0677c177-818c-46ed-84f7-84ec389a7dbb 2025-06-24 19:37:50,548 - INFO - Verifications in API Server for VN ctest-right_vn-11555394 passed 2025-06-24 19:37:50,548 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/0677c177-818c-46ed-84f7-84ec389a7dbb 2025-06-24 19:37:50,558 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/277375ed-7c80-4080-b3a4-feb387c44106 2025-06-24 19:37:50,565 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/277375ed-7c80-4080-b3a4-feb387c44106 2025-06-24 19:37:50,573 - DEBUG - Requesting: http://10.0.0.33:8082/route-target/e34f9ab0-ed69-4895-8005-cace3886b1f4 2025-06-24 19:37:50,587 - DEBUG - Control-node 10.0.0.33 : VN object is : {'node_name': 'virtual-network:default-domain:ctest-TestSvcRegr-27215922:ctest-right_vn-11555394', 'interests': '-', 'advertised': '-', 'dbentryflags': None, 'obj_info': {'sequence_number': '0', 'origin': 'Cassandra', 'data': {'id-perms': {'permissions': {'owner': 'ctest-TestSvcRegr-27215922', 'owner-access': '7', 'group': 'member', 'group-access': '7', 'other-access': '7'}, 'uuid': {'uuid-mslong': '466053805474858733', 'uuid-lslong': '9581272882350816699'}, 'enable': 'true', 'created': '2025-06-24T19:37:49', 'last-modified': '2025-06-24T19:37:50', 'description': None, 'user-visible': 'true', 'creator': None}}}, 'neighbors': None, 'last_modified': '00:00:00.419748'} 2025-06-24 19:37:50,594 - DEBUG - Route Targets: ['target:64512:8000007'] 2025-06-24 19:37:50,594 - INFO - On all control nodes, Config, RI and RT verification for VN ctest-right_vn-11555394 passed 2025-06-24 19:37:50,594 - DEBUG - ====Verifying policy data for ctest-right_vn-11555394 in API_Server ====== 2025-06-24 19:37:50,594 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-06-24 19:37:50,600 - DEBUG - Requesting: http://10.0.0.33:8082/domain/dc75db7d-aaef-4a5a-b932-dc19bbe78927 2025-06-24 19:37:50,614 - DEBUG - Requesting: http://10.0.0.33:8082/project/afb93c2b-921a-47aa-82a0-10234e1d0f2b 2025-06-24 19:37:50,683 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-network/0677c177-818c-46ed-84f7-84ec389a7dbb 2025-06-24 19:37:50,694 - DEBUG - =>VN ctest-right_vn-11555394 has no policy to be verified 2025-06-24 19:37:50,694 - DEBUG - Verifying the vn in opserver 2025-06-24 19:37:50,694 - DEBUG - Verifying the default-domain:ctest-TestSvcRegr-27215922:ctest-right_vn-11555394 virtual network link through opserver 10.0.0.33 2025-06-24 19:37:50,694 - DEBUG - Requesting: http://10.0.0.33:8081/analytics/uves/virtual-networks 2025-06-24 19:37:50,706 - DEBUG - vn link and name as {'name': 'default-domain:ctest-TestSvcRegr-27215922:ctest-right_vn-11555394', 'href': 'http://10.0.0.33:8081/analytics/uves/virtual-network/default-domain:ctest-TestSvcRegr-27215922:ctest-right_vn-11555394?flat'} 2025-06-24 19:37:50,706 - INFO - Validated that VN default-domain:ctest-TestSvcRegr-27215922:ctest-right_vn-11555394 is found in opserver 2025-06-24 19:37:50,719 - DEBUG - Do not have enough data to verify VN in agent 2025-06-24 19:37:50,724 - DEBUG - VRF ids for VN ctest-right_vn-11555394: {} 2025-06-24 19:37:50,831 - DEBUG - Services list from nova: [, , ] 2025-06-24 19:37:52,001 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3541-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3541-1) 2025-06-24 19:37:53,177 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3541-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3541-1) 2025-06-24 19:37:53,177 - DEBUG - Creating service template: ['default-domain', 'ctest-service_template_1-14029982'] 2025-06-24 19:37:53,541 - DEBUG - Service template: ['default-domain', 'ctest-service_template_1-14029982'] created succesfully 2025-06-24 19:37:53,800 - DEBUG - Image by name cirros_in_net not found 2025-06-24 19:37:53,800 - DEBUG - Installing image cirros_in_net 2025-06-24 19:37:53,800 - 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-06-24 19:37:53,800 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-06-24 19:37:53,801 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//cirros-in-net.vmdk.gz 2025-06-24 19:37:53,813 - 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-06-24 19:37:53,909 - DEBUG - Image has been downloaded to /contrail-test/images/cirros-in-net.vmdk.gz 2025-06-24 19:37:53,909 - DEBUG - Unzip image 2025-06-24 19:37:53,909 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/cirros-in-net.vmdk.gz 2025-06-24 19:37:54,075 - DEBUG - Try to create image in user context 2025-06-24 19:37:54,302 - DEBUG - Image has been created in user context 2025-06-24 19:37:54,302 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/cirros-in-net.vmdk 2025-06-24 19:37:54,316 - DEBUG - Downloaded image has been removed 2025-06-24 19:37:54,329 - DEBUG - Uploaded image status = active 2025-06-24 19:37:55,487 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3541-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3541-1) 2025-06-24 19:37:55,487 - INFO - Waiting for VM ctest-pt_svm0-11287350 to be up.. 2025-06-24 19:37:55,541 - DEBUG - VM is in ERROR state now 2025-06-24 19:37:55,541 - ERROR - VM in error state. 2025-06-24 19:37:55,541 - ERROR - VM is not in ACTIVE state 2025-06-24 19:37:55,621 - ERROR - Fatal Nova Exception while getting VM detail 2025-06-24 19:37:55,622 - DEBUG - None 2025-06-24 19:37:55,625 - INFO - Skip interface_detach for VM ctest-pt_svm0-11287350 in state ERROR 2025-06-24 19:37:55,625 - INFO - Deleting VM ctest-pt_svm0-11287350 2025-06-24 19:37:55,647 - ERROR - VM ctest-pt_svm0-11287350 has failed to come up 2025-06-24 19:37:55,647 - ERROR - Fault seen in nova show is: {'code': 500, 'created': '2025-06-24T19:37:55Z', 'message': 'No valid host was found. ', 'details': 'Traceback (most recent call last):\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/conductor/manager.py", line 1581, in schedule_and_build_instances\n instance_uuids, return_alternates=True)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/conductor/manager.py", line 942, in _schedule_instances\n return_alternates=return_alternates)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/scheduler/client/query.py", line 42, in select_destinations\n instance_uuids, return_objects, return_alternates)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/scheduler/rpcapi.py", line 160, in select_destinations\n return cctxt.call(ctxt, \'select_destinations\', **msg_args)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_messaging/rpc/client.py", line 192, in call\n retry=self.retry, transport_options=self.transport_options)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_messaging/transport.py", line 128, in _send\n transport_options=transport_options)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_messaging/_drivers/amqpdriver.py", line 691, in send\n transport_options=transport_options)\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_messaging/_drivers/amqpdriver.py", line 681, in _send\n raise result\nnova.exception_Remote.NoValidHost_Remote: No valid host was found. \nTraceback (most recent call last):\n\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/oslo_messaging/rpc/server.py", line 241, in inner\n return func(*args, **kwargs)\n\n File "/var/lib/kolla/venv/lib/python3.6/site-packages/nova/scheduler/manager.py", line 209, in select_destinations\n raise exception.NoValidHost(reason="")\n\nnova.exception.NoValidHost: No valid host was found. \n\n'} 2025-06-24 19:37:55,648 - DEBUG - Deleting service template: ['default-domain', 'ctest-service_template_1-14029982'] 2025-06-24 19:37:55,725 - INFO - Service template: ['default-domain', 'ctest-service_template_1-14029982'] deleted successfully. 2025-06-24 19:37:55,726 - INFO - Skip interface_detach for VM ctest-right_vm-41679560 in state BUILD 2025-06-24 19:37:55,726 - INFO - Deleting VM ctest-right_vm-41679560 2025-06-24 19:37:55,726 - ERROR - VM ctest-right_vm-41679560 has failed to come up 2025-06-24 19:37:55,726 - ERROR - Nova failed to get host of the VM 2025-06-24 19:37:55,915 - INFO - Skip interface_detach for VM ctest-left_vm-48876222 in state BUILD 2025-06-24 19:37:55,915 - INFO - Deleting VM ctest-left_vm-48876222 2025-06-24 19:37:55,915 - ERROR - VM ctest-left_vm-48876222 has failed to come up 2025-06-24 19:37:55,915 - ERROR - Nova failed to get host of the VM 2025-06-24 19:37:56,068 - INFO - Deleting VN ctest-right_vn-11555394 2025-06-24 19:37:56,164 - DEBUG - Response for deleting network () 2025-06-24 19:37:56,164 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/277375ed-7c80-4080-b3a4-feb387c44106 2025-06-24 19:37:56,170 - DEBUG - Response Code: 404 2025-06-24 19:37:56,170 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-06-24 19:37:56,179 - DEBUG - Requesting: http://10.0.0.33:8082/domain/dc75db7d-aaef-4a5a-b932-dc19bbe78927 2025-06-24 19:37:56,237 - DEBUG - Requesting: http://10.0.0.33:8082/project/afb93c2b-921a-47aa-82a0-10234e1d0f2b 2025-06-24 19:37:56,311 - INFO - Validated that VN ctest-right_vn-11555394 is not found in API Server 2025-06-24 19:37:56,326 - DEBUG - VN ctest-right_vn-11555394 is not present in Agent 10.0.0.33 2025-06-24 19:37:56,326 - INFO - Validated that VN ctest-right_vn-11555394 is not in any agent 2025-06-24 19:37:56,348 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-right_vn-11555394 info 2025-06-24 19:37:56,348 - INFO - Deleting VN ctest-left_vn-51309012 2025-06-24 19:37:56,459 - DEBUG - Response for deleting network () 2025-06-24 19:37:56,459 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/45174ff3-ae59-4347-97d6-72682dc3eb48 2025-06-24 19:37:56,465 - DEBUG - Response Code: 404 2025-06-24 19:37:56,465 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-06-24 19:37:56,475 - DEBUG - Requesting: http://10.0.0.33:8082/domain/dc75db7d-aaef-4a5a-b932-dc19bbe78927 2025-06-24 19:37:56,532 - DEBUG - Requesting: http://10.0.0.33:8082/project/afb93c2b-921a-47aa-82a0-10234e1d0f2b 2025-06-24 19:37:56,605 - INFO - Validated that VN ctest-left_vn-51309012 is not found in API Server 2025-06-24 19:37:56,617 - DEBUG - VN ctest-left_vn-51309012 is not present in Agent 10.0.0.33 2025-06-24 19:37:56,618 - INFO - Validated that VN ctest-left_vn-51309012 is not in any agent 2025-06-24 19:37:56,636 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-left_vn-51309012 info 2025-06-24 19:37:56,636 - INFO - Deleting VN ctest-mgmt_vn-47472286 2025-06-24 19:37:56,751 - DEBUG - Response for deleting network () 2025-06-24 19:37:56,751 - DEBUG - Requesting: http://10.0.0.33:8082/routing-instance/9417e528-ea9b-4a9d-9573-b7593bb019fc 2025-06-24 19:37:56,761 - DEBUG - Response Code: 404 2025-06-24 19:37:56,762 - DEBUG - Requesting: http://10.0.0.33:8082/domains 2025-06-24 19:37:56,768 - DEBUG - Requesting: http://10.0.0.33:8082/domain/dc75db7d-aaef-4a5a-b932-dc19bbe78927 2025-06-24 19:37:56,824 - DEBUG - Requesting: http://10.0.0.33:8082/project/afb93c2b-921a-47aa-82a0-10234e1d0f2b 2025-06-24 19:37:56,924 - INFO - Validated that VN ctest-mgmt_vn-47472286 is not found in API Server 2025-06-24 19:37:56,936 - DEBUG - VN ctest-mgmt_vn-47472286 is not present in Agent 10.0.0.33 2025-06-24 19:37:56,936 - INFO - Validated that VN ctest-mgmt_vn-47472286 is not in any agent 2025-06-24 19:37:56,955 - INFO - Validated that Routing instances and Config db in Control-nodes does not have VN ctest-mgmt_vn-47472286 info 2025-06-24 19:37:57,209 - ERROR - AssertionError Python 3.6.8: /usr/bin/python3 Tue Jun 24 19:37:55 2025 A problem occurred in a Python script. Here is the sequence of function calls leading up to the error, in the order they occurred. /contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={}) 78 log.info('Initial checks done. Running the testcase now') 79 log.info('') 80 result = function(self, *args, **kwargs) 81 if self.inputs.upgrade: 82 pid = os.getpid() result = None function = self = args = () kwargs = {} /contrail-test/scripts/svc_firewall/test_svc_fw.py in test_svc_in_network_datapath(self=) 24 def test_svc_in_network_datapath(self): 25 return self.verify_svc_chain(svc_img_name='cirros_in_net', service_mode='in-network', 26 create_svms=True) 27 28 create_svms undefined /contrail-test/common/servicechain/verify.py in verify_svc_chain(self=, *args=(), **kwargs={'create_svms': True, 'service_mode': 'in-network', 'svc_img_name': 'cirros_in_net'}) 318 def verify_svc_chain(self, *args, **kwargs): 319 svc_chain_info = kwargs.get('svc_chain_info') 320 ret_dict = svc_chain_info or self.config_svc_chain(*args, **kwargs) 321 proto = kwargs.get('proto', 'any') 322 left_vn_fq_name = ret_dict.get('left_vn_fixture').vn_fq_name ret_dict undefined svc_chain_info = None self = self.config_svc_chain = > args = () kwargs = {'create_svms': True, 'service_mode': 'in-network', 'svc_img_name': 'cirros_in_net'} /contrail-test/common/servicechain/config.py in config_svc_chain(self=, service_mode='in-network', service_type='firewall', max_inst=1, proto='any', svc_chain_type='parallel', src_ports=[0, 65535], dst_ports=[0, 65535], svc_img_name='cirros_in_net', st_version=2, mgmt_vn_name='ctest-mgmt_vn-47472286', mgmt_vn_subnets=['199.193.24.128/26'], mgmt_vn_fixture=, left_vn_name='ctest-left_vn-51309012', left_vn_subnets=['48.131.242.64/26'], left_vn_fixture=, right_vn_name='ctest-right_vn-11555394', right_vn_subnets=['26.136.210.0/26'], right_vn_fixture=, left_lr_child_vn_fixture=None, right_lr_child_vn_fixture=None, left_vm_name='ctest-left_vm-48876222', left_vm_fixture=, right_vm_name='ctest-right_vm-41679560', right_vm_fixture=, image_name='cirros', policy_fixture=None, st_fixture=, si_fixture=None, port_tuples_props=None, static_route=None, svm_fixtures=[], create_svms=True, evpn=False, modify_rules=False, policy_action='pass', hosts=[], **kwargs={}) 531 service_type=service_type, 532 hosts=hosts, 533 max_inst=max_inst) 534 if svc_chain_type == 'serial': 535 svm_fixtures2 = self.create_service_vms(vns, max_inst = 1 /contrail-test/common/servicechain/config.py in create_service_vms(self=, vns=[, , ], service_mode='in-network', max_inst=1, svc_img_name='cirros_in_net', service_type='firewall', hosts=[]) 337 if service_type == 'analyzer': 338 svm_fixture.disable_interface_policy() 339 self.verify_vms(svm_fixtures) 340 return svm_fixtures 341 # end create_service_vms self = self.verify_vms = > svm_fixtures = [] /contrail-test/common/servicechain/config.py in verify_vms(self=, vm_fixtures=[]) 169 def verify_vms(self, vm_fixtures): 170 for vm in vm_fixtures: 171 self.verify_vm(vm) 172 # end verify_vms 173 self = self.verify_vm = > vm = /contrail-test/common/servicechain/config.py in verify_vm(self=, vm_fixture=) 173 174 def verify_vm(self, vm_fixture): 175 assert vm_fixture.wait_till_vm_is_up(), 'VM does not seem to be up' 176 # end verify_vm 177 vm_fixture = vm_fixture.wait_till_vm_is_up = > AssertionError: VM does not seem to be up __cause__ = None __class__ = __context__ = None __delattr__ = __dict__ = {} __dir__ = __doc__ = 'Assertion failed.' __eq__ = __format__ = __ge__ = __getattribute__ = __gt__ = __hash__ = __init__ = __init_subclass__ = __le__ = __lt__ = __ne__ = __new__ = __reduce__ = __reduce_ex__ = __repr__ = __setattr__ = __setstate__ = __sizeof__ = __str__ = __subclasshook__ = __suppress_context__ = False __traceback__ = args = ('VM does not seem to be up',) with_traceback = The above is a description of an error in a Python program. Here is the original traceback: Traceback (most recent call last): File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper result = function(self, *args, **kwargs) File "/contrail-test/scripts/svc_firewall/test_svc_fw.py", line 26, in test_svc_in_network_datapath create_svms=True) File "/contrail-test/common/servicechain/verify.py", line 320, in verify_svc_chain ret_dict = svc_chain_info or self.config_svc_chain(*args, **kwargs) File "/contrail-test/common/servicechain/config.py", line 533, in config_svc_chain max_inst=max_inst) File "/contrail-test/common/servicechain/config.py", line 339, in create_service_vms self.verify_vms(svm_fixtures) File "/contrail-test/common/servicechain/config.py", line 171, in verify_vms self.verify_vm(vm) File "/contrail-test/common/servicechain/config.py", line 175, in verify_vm assert vm_fixture.wait_till_vm_is_up(), 'VM does not seem to be up' AssertionError: VM does not seem to be up 2025-06-24 19:37:57,217 - DEBUG - No XMPP flaps were noticed during the test 2025-06-24 19:37:57,217 - INFO -
2025-06-24 19:37:57,217 - INFO - END TEST : test_svc_in_network_datapath : FAILED[0:00:11]
2025-06-24 19:37:57,218 - INFO - -------------------------------------------------------------------------------- 2025-06-24 19:37:57,935 - INFO - Deleted project: ctest-TestSvcRegr-27215922, ID : afb93c2b-921a-47aa-82a0-10234e1d0f2b