2026-07-18 15:37:09,906 - INFO - Domain Default found not creating 2026-07-18 15:37:10,061 - INFO - Project ctest-TestRoutersBasic-33005064 not found, creating it 2026-07-18 15:37:10,576 - INFO - Created Project:ctest-TestRoutersBasic-33005064, ID : cc2a5108-ae33-4424-815f-41acea11bf3c 2026-07-18 15:37:12,118 - INFO - ================================================================================ 2026-07-18 15:37:12,119 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2026-07-18 15:37:12,119 - 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 2026-07-18 15:37:12,391 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.254': '0'}} with 2026-07-18 15:37:12,391 - INFO - Initial checks done. Running the testcase now 2026-07-18 15:37:12,391 - INFO - 2026-07-18 15:37:13,299 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-33005064 2026-07-18 15:37:13,394 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-33005064 2026-07-18 15:37:13,717 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-33005064', 'default']} 2026-07-18 15:37:14,021 - DEBUG - Response for create_network : {'network': {'id': 'a78eb5ae-5b83-41d2-9a83-9eff45a77afd', 'name': 'ctest-vn-50319167', 'tenant_id': 'cc2a5108ae334424815f41acea11bf3c', 'project_id': 'cc2a5108ae334424815f41acea11bf3c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-33005064', 'ctest-vn-50319167'], 'port_security_enabled': True, 'description': ''}} 2026-07-18 15:37:14,237 - DEBUG - Response for create_subnet : {'subnet': {'id': '305280f0-fa51-4c27-a8bc-e77c274ac06e', 'name': '', 'tenant_id': 'cc2a5108ae334424815f41acea11bf3c', 'network_id': 'a78eb5ae-5b83-41d2-9a83-9eff45a77afd', 'ip_version': 4, 'cidr': '173.83.206.128/26', 'allocation_pools': [{'start': '173.83.206.130', 'end': '173.83.206.190'}], 'gateway_ip': '173.83.206.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '173.83.206.130', 'tags': [], 'project_id': 'cc2a5108ae334424815f41acea11bf3c'}} 2026-07-18 15:37:14,254 - INFO - Created VN ctest-vn-50319167 2026-07-18 15:37:14,306 - DEBUG - VN ctest-vn-50319167 UUID is a78eb5ae-5b83-41d2-9a83-9eff45a77afd 2026-07-18 15:37:14,682 - DEBUG - Response for create_network : {'network': {'id': '75f99cc4-4abf-4ca5-9f38-bc2ac0026977', 'name': 'ctest-ext_vn-14539914', 'tenant_id': 'cc2a5108ae334424815f41acea11bf3c', 'project_id': 'cc2a5108ae334424815f41acea11bf3c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-33005064', 'ctest-ext_vn-14539914'], 'port_security_enabled': True, 'description': ''}} 2026-07-18 15:37:14,918 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c60fb9dc-fd7c-4e9b-8b27-e9f88af1e182', 'name': '', 'tenant_id': 'cc2a5108ae334424815f41acea11bf3c', 'network_id': '75f99cc4-4abf-4ca5-9f38-bc2ac0026977', 'ip_version': 4, 'cidr': '71.65.24.192/26', 'allocation_pools': [{'start': '71.65.24.194', 'end': '71.65.24.254'}], 'gateway_ip': '71.65.24.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '71.65.24.194', 'tags': [], 'project_id': 'cc2a5108ae334424815f41acea11bf3c'}} 2026-07-18 15:37:14,936 - INFO - Created VN ctest-ext_vn-14539914 2026-07-18 15:37:14,990 - DEBUG - VN ctest-ext_vn-14539914 UUID is 75f99cc4-4abf-4ca5-9f38-bc2ac0026977 2026-07-18 15:37:15,156 - DEBUG - Services list from nova: [, , ] 2026-07-18 15:37:15,648 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6106-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6106-1) 2026-07-18 15:37:16,087 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6106-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6106-1) 2026-07-18 15:37:16,161 - INFO - Setting gateway for router ecf765d7-1e47-4bf6-83e2-e952fc9a13cb to network 75f99cc4-4abf-4ca5-9f38-bc2ac0026977 2026-07-18 15:37:16,211 - INFO - Adding interface with subnet_id 305280f0-fa51-4c27-a8bc-e77c274ac06e, port_id None to router ecf765d7-1e47-4bf6-83e2-e952fc9a13cb 2026-07-18 15:37:16,458 - INFO - Waiting for VM ctest-TestRoutersBasic-33005064-33812478 to be up.. 2026-07-18 15:37:16,525 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-18 15:37:21,626 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-18 15:37:26,726 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-18 15:37:31,813 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-18 15:37:36,900 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-18 15:37:42,035 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-18 15:37:47,113 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-18 15:37:52,200 - DEBUG - VM is in ACTIVE state now 2026-07-18 15:37:52,200 - INFO - VM name : ctest-TestRoutersBasic-33005064-33812478 2026-07-18 15:37:52,287 - DEBUG - VM ctest-TestRoutersBasic-33005064-33812478 ID is 74469e70-25af-4469-98b5-26f5d8a67c14 2026-07-18 15:37:52,308 - DEBUG - VM ctest-TestRoutersBasic-33005064-33812478 launched on Node cn-jenkins-deploy-platform-ansible-os-6106-1 2026-07-18 15:37:52,389 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/74469e70-25af-4469-98b5-26f5d8a67c14 2026-07-18 15:37:52,725 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/74469e70-25af-4469-98b5-26f5d8a67c14 2026-07-18 15:37:52,758 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/e49d7e09-6fd8-45aa-9123-d751037f49a6 2026-07-18 15:37:56,025 - 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 1018ms') 2026-07-18 15:37:56,025 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-33005064-33812478 failed! 2026-07-18 15:37:56,082 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-33005064:ctest-vn-50319167 is 173.83.206.129 and allocation pool is NOT set 2026-07-18 15:37:58,142 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=9.14 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.399 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.399/4.771/9.143/4.372 ms') 2026-07-18 15:37:58,142 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-33005064-33812478 passed 2026-07-18 15:37:58,298 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-18 15:37:58,298 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-33005064-33812478, IP 173.83.206.131, Port 22 2026-07-18 15:37:58,457 - DEBUG - VM ctest-TestRoutersBasic-33005064-33812478 is ready for SSH connections 2026-07-18 15:37:58,458 - INFO - Waiting for VM ctest-TestRoutersBasic-33005064-45275437 to be up.. 2026-07-18 15:37:58,534 - DEBUG - VM is in ACTIVE state now 2026-07-18 15:37:58,534 - INFO - VM name : ctest-TestRoutersBasic-33005064-45275437 2026-07-18 15:37:58,614 - DEBUG - VM ctest-TestRoutersBasic-33005064-45275437 ID is 9fd0236f-9d0b-4367-9465-4bca4f0e3157 2026-07-18 15:37:58,614 - DEBUG - VM ctest-TestRoutersBasic-33005064-45275437 launched on Node cn-jenkins-deploy-platform-ansible-os-6106-1 2026-07-18 15:37:58,697 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/9fd0236f-9d0b-4367-9465-4bca4f0e3157 2026-07-18 15:37:58,707 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/0527d352-4ad3-4e09-8200-83e48a03f42a 2026-07-18 15:38:01,975 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2026-07-18 15:38:01,975 - DEBUG - Ping to Metadata IP 169.254.0.6 of VM ctest-TestRoutersBasic-33005064-45275437 failed! 2026-07-18 15:38:02,034 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-33005064:ctest-ext_vn-14539914 is 71.65.24.193 and allocation pool is NOT set 2026-07-18 15:38:04,099 - 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=3.28 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.679 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.679/1.978/3.277/1.299 ms') 2026-07-18 15:38:04,099 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-TestRoutersBasic-33005064-45275437 passed 2026-07-18 15:38:04,258 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-18 15:38:04,258 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-33005064-45275437, IP 71.65.24.196, Port 22 2026-07-18 15:38:04,436 - DEBUG - VM ctest-TestRoutersBasic-33005064-45275437 is ready for SSH connections 2026-07-18 15:38:04,436 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-18 15:38:04,436 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 71.65.24.196, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-07-18 15:38:04,437 - DEBUG - ping -s 56 -c 3 -W 1 71.65.24.196 2026-07-18 15:38:07,438 - DEBUG - PING 71.65.24.196 (71.65.24.196): 56 data bytes 64 bytes from 71.65.24.196: seq=0 ttl=62 time=7.937 ms 64 bytes from 71.65.24.196: seq=1 ttl=62 time=1.039 ms 64 bytes from 71.65.24.196: seq=2 ttl=62 time=1.389 ms --- 71.65.24.196 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.039/3.455/7.937 ms 2026-07-18 15:38:07,438 - INFO - Ping to IP 71.65.24.196 from VM ctest-TestRoutersBasic-33005064-33812478 passed 2026-07-18 15:38:07,438 - INFO - Deleting interface with subnet_id 305280f0-fa51-4c27-a8bc-e77c274ac06e, port_id None from router ecf765d7-1e47-4bf6-83e2-e952fc9a13cb 2026-07-18 15:38:07,666 - INFO - Deleting VM ctest-TestRoutersBasic-33005064-45275437 2026-07-18 15:38:07,763 - INFO - Deleting VM ctest-TestRoutersBasic-33005064-33812478 2026-07-18 15:38:07,856 - INFO - Deleting VN ctest-ext_vn-14539914 2026-07-18 15:38:07,939 - DEBUG - VN 75f99cc4-4abf-4ca5-9f38-bc2ac0026977 still in use: Unable to complete operation on network 75f99cc4-4abf-4ca5-9f38-bc2ac0026977. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-3dc69276-1c66-4d37-8de8-f947c07883c9'] 2026-07-18 15:38:07,939 - WARNING - Deleting VN ctest-ext_vn-14539914 failed..Will retry 2026-07-18 15:38:10,082 - DEBUG - Response for deleting network () 2026-07-18 15:38:10,083 - INFO - Deleting VN ctest-vn-50319167 2026-07-18 15:38:10,191 - DEBUG - Response for deleting network () 2026-07-18 15:38:10,461 - DEBUG - No XMPP flaps were noticed during the test
2026-07-18 15:38:10,461 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:58]
2026-07-18 15:38:10,461 - INFO - -------------------------------------------------------------------------------- 2026-07-18 15:38:11,226 - INFO - Deleted project: ctest-TestRoutersBasic-33005064, ID : cc2a5108-ae33-4424-815f-41acea11bf3c