2025-06-30 16:19:17,097 - INFO - Domain Default found not creating 2025-06-30 16:19:17,238 - INFO - Project ctest-TestRoutersBasic-30718394 not found, creating it 2025-06-30 16:19:17,694 - INFO - Created Project:ctest-TestRoutersBasic-30718394, ID : 32c00d6b-b5a8-4c28-ba25-3f7044d638c8 2025-06-30 16:19:19,154 - INFO - ================================================================================ 2025-06-30 16:19:19,155 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2025-06-30 16:19:19,155 - INFO - TEST DESCRIPTION : Create an external network, a router set router-gateway to external network launch a private network and attach it to router validate left vm pinging right vm through Snat 2025-06-30 16:19:19,411 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.254': '0'}} with 2025-06-30 16:19:19,411 - INFO - Initial checks done. Running the testcase now 2025-06-30 16:19:19,411 - INFO - 2025-06-30 16:19:20,114 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-30718394 2025-06-30 16:19:20,209 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-30718394 2025-06-30 16:19:20,529 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-30718394', 'default']} 2025-06-30 16:19:20,815 - DEBUG - Response for create_network : {'network': {'id': '936fc78a-d9bc-4ee0-a604-1dd388bb0614', 'name': 'ctest-vn-03708721', 'tenant_id': '32c00d6bb5a84c28ba253f7044d638c8', 'project_id': '32c00d6bb5a84c28ba253f7044d638c8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-30718394', 'ctest-vn-03708721'], 'port_security_enabled': True, 'description': ''}} 2025-06-30 16:19:21,002 - DEBUG - Response for create_subnet : {'subnet': {'id': '09abd5c8-b27f-4e9f-a1ff-01cd68da0fc9', 'name': '', 'tenant_id': '32c00d6bb5a84c28ba253f7044d638c8', 'network_id': '936fc78a-d9bc-4ee0-a604-1dd388bb0614', 'ip_version': 4, 'cidr': '82.155.143.128/26', 'allocation_pools': [{'start': '82.155.143.130', 'end': '82.155.143.190'}], 'gateway_ip': '82.155.143.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '82.155.143.130', 'tags': [], 'project_id': '32c00d6bb5a84c28ba253f7044d638c8'}} 2025-06-30 16:19:21,021 - INFO - Created VN ctest-vn-03708721 2025-06-30 16:19:21,077 - DEBUG - VN ctest-vn-03708721 UUID is 936fc78a-d9bc-4ee0-a604-1dd388bb0614 2025-06-30 16:19:21,393 - DEBUG - Response for create_network : {'network': {'id': '953f4c86-e369-4ec7-80ad-a0d3fbba40c2', 'name': 'ctest-ext_vn-81251160', 'tenant_id': '32c00d6bb5a84c28ba253f7044d638c8', 'project_id': '32c00d6bb5a84c28ba253f7044d638c8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-30718394', 'ctest-ext_vn-81251160'], 'port_security_enabled': True, 'description': ''}} 2025-06-30 16:19:21,543 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b8cf53bb-5919-4f8e-b654-bcad9f86533f', 'name': '', 'tenant_id': '32c00d6bb5a84c28ba253f7044d638c8', 'network_id': '953f4c86-e369-4ec7-80ad-a0d3fbba40c2', 'ip_version': 4, 'cidr': '112.55.252.64/26', 'allocation_pools': [{'start': '112.55.252.66', 'end': '112.55.252.126'}], 'gateway_ip': '112.55.252.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '112.55.252.66', 'tags': [], 'project_id': '32c00d6bb5a84c28ba253f7044d638c8'}} 2025-06-30 16:19:21,559 - INFO - Created VN ctest-ext_vn-81251160 2025-06-30 16:19:21,613 - DEBUG - VN ctest-ext_vn-81251160 UUID is 953f4c86-e369-4ec7-80ad-a0d3fbba40c2 2025-06-30 16:19:21,814 - DEBUG - Services list from nova: [, , ] 2025-06-30 16:19:22,788 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3581-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3581-1) 2025-06-30 16:19:24,046 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3581-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3581-1) 2025-06-30 16:19:24,125 - INFO - Setting gateway for router 84edf93a-f9cd-4563-8bb1-a9a484bfc4dd to network 953f4c86-e369-4ec7-80ad-a0d3fbba40c2 2025-06-30 16:19:24,198 - INFO - Adding interface with subnet_id 09abd5c8-b27f-4e9f-a1ff-01cd68da0fc9, port_id None to router 84edf93a-f9cd-4563-8bb1-a9a484bfc4dd 2025-06-30 16:19:24,612 - INFO - Waiting for VM ctest-TestRoutersBasic-30718394-53424928 to be up.. 2025-06-30 16:19:24,771 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-06-30 16:19:29,856 - DEBUG - VM is in ACTIVE state now 2025-06-30 16:19:29,856 - INFO - VM name : ctest-TestRoutersBasic-30718394-53424928 2025-06-30 16:19:29,929 - DEBUG - VM ctest-TestRoutersBasic-30718394-53424928 ID is a8052f96-7742-4c92-b9e3-911e4bd4893f 2025-06-30 16:19:29,948 - DEBUG - VM ctest-TestRoutersBasic-30718394-53424928 launched on Node cn-jenkins-deploy-platform-ansible-os-3581-1 2025-06-30 16:19:30,022 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/a8052f96-7742-4c92-b9e3-911e4bd4893f 2025-06-30 16:19:30,322 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/a8052f96-7742-4c92-b9e3-911e4bd4893f 2025-06-30 16:19:30,351 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/ce329acf-e1de-48b4-99f3-8725f8951cf6 2025-06-30 16:19:33,607 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms') 2025-06-30 16:19:33,607 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-30718394-53424928 failed! 2025-06-30 16:19:33,665 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-30718394:ctest-vn-03708721 is 82.155.143.129 and allocation pool is NOT set 2025-06-30 16:19:35,730 - 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.06 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.441 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.441/2.250/4.060/1.809 ms') 2025-06-30 16:19:35,730 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-30718394-53424928 passed 2025-06-30 16:19:35,905 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-30 16:19:35,905 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-30718394-53424928, IP 82.155.143.131, Port 22 2025-06-30 16:19:36,066 - DEBUG - VM ctest-TestRoutersBasic-30718394-53424928 is ready for SSH connections 2025-06-30 16:19:36,067 - INFO - Waiting for VM ctest-TestRoutersBasic-30718394-87053378 to be up.. 2025-06-30 16:19:36,143 - DEBUG - VM is in ACTIVE state now 2025-06-30 16:19:36,143 - INFO - VM name : ctest-TestRoutersBasic-30718394-87053378 2025-06-30 16:19:36,212 - DEBUG - VM ctest-TestRoutersBasic-30718394-87053378 ID is e17802dc-af56-4496-8e03-10ee2a0c086a 2025-06-30 16:19:36,212 - DEBUG - VM ctest-TestRoutersBasic-30718394-87053378 launched on Node cn-jenkins-deploy-platform-ansible-os-3581-1 2025-06-30 16:19:36,280 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/e17802dc-af56-4496-8e03-10ee2a0c086a 2025-06-30 16:19:36,289 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/bcf20418-9a9a-4789-802c-39e8b75624ab 2025-06-30 16:19:37,526 - 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=2.85 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.394 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.394/1.619/2.845/1.225 ms') 2025-06-30 16:19:37,526 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestRoutersBasic-30718394-87053378 passed 2025-06-30 16:19:37,677 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-30 16:19:37,677 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-30718394-87053378, IP 112.55.252.67, Port 22 2025-06-30 16:19:37,837 - DEBUG - VM ctest-TestRoutersBasic-30718394-87053378 is ready for SSH connections 2025-06-30 16:19:37,837 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-30 16:19:37,837 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 112.55.252.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-06-30 16:19:37,837 - DEBUG - ping -s 56 -c 3 -W 1 112.55.252.67 2025-06-30 16:19:41,519 - DEBUG - PING 112.55.252.67 (112.55.252.67): 56 data bytes 64 bytes from 112.55.252.67: seq=0 ttl=62 time=4.112 ms 64 bytes from 112.55.252.67: seq=1 ttl=62 time=0.922 ms 64 bytes from 112.55.252.67: seq=2 ttl=62 time=1.272 ms --- 112.55.252.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.922/2.102/4.112 ms 2025-06-30 16:19:41,519 - INFO - Ping to IP 112.55.252.67 from VM ctest-TestRoutersBasic-30718394-53424928 passed 2025-06-30 16:19:41,519 - INFO - Deleting interface with subnet_id 09abd5c8-b27f-4e9f-a1ff-01cd68da0fc9, port_id None from router 84edf93a-f9cd-4563-8bb1-a9a484bfc4dd 2025-06-30 16:19:41,713 - INFO - Deleting VM ctest-TestRoutersBasic-30718394-87053378 2025-06-30 16:19:41,802 - INFO - Deleting VM ctest-TestRoutersBasic-30718394-53424928 2025-06-30 16:19:41,893 - INFO - Deleting VN ctest-ext_vn-81251160 2025-06-30 16:19:41,970 - DEBUG - VN 953f4c86-e369-4ec7-80ad-a0d3fbba40c2 still in use: Unable to complete operation on network 953f4c86-e369-4ec7-80ad-a0d3fbba40c2. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-41f1ead4-1329-416c-a98a-6dad474859e0'] 2025-06-30 16:19:41,970 - WARNING - Deleting VN ctest-ext_vn-81251160 failed..Will retry 2025-06-30 16:19:44,085 - DEBUG - Response for deleting network () 2025-06-30 16:19:44,085 - INFO - Deleting VN ctest-vn-03708721 2025-06-30 16:19:44,207 - DEBUG - Response for deleting network () 2025-06-30 16:19:44,469 - DEBUG - No XMPP flaps were noticed during the test
2025-06-30 16:19:44,469 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:25]
2025-06-30 16:19:44,470 - INFO - -------------------------------------------------------------------------------- 2025-06-30 16:19:45,198 - INFO - Deleted project: ctest-TestRoutersBasic-30718394, ID : 32c00d6b-b5a8-4c28-ba25-3f7044d638c8