2026-08-22 08:40:06,229 - INFO - Domain Default found not creating 2026-08-22 08:40:06,442 - INFO - Project ctest-TestRoutersBasic-86097309 not found, creating it 2026-08-22 08:40:07,016 - INFO - Created Project:ctest-TestRoutersBasic-86097309, ID : 47ffa737-1e46-4c04-87c2-45959920ea71 2026-08-22 08:40:08,956 - INFO - ================================================================================ 2026-08-22 08:40:08,956 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2026-08-22 08:40:08,957 - 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-08-22 08:40:09,242 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.25': '0'}} with 2026-08-22 08:40:09,242 - INFO - Initial checks done. Running the testcase now 2026-08-22 08:40:09,242 - INFO - 2026-08-22 08:40:10,137 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-86097309 2026-08-22 08:40:10,236 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-86097309 2026-08-22 08:40:10,596 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-86097309', 'default']} 2026-08-22 08:40:10,950 - DEBUG - Response for create_network : {'network': {'id': 'a8939260-4710-4536-8ea6-e6c59aade22d', 'name': 'ctest-vn-80433923', 'tenant_id': '47ffa7371e464c0487c245959920ea71', 'project_id': '47ffa7371e464c0487c245959920ea71', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-86097309', 'ctest-vn-80433923'], 'port_security_enabled': True, 'description': ''}} 2026-08-22 08:40:11,187 - DEBUG - Response for create_subnet : {'subnet': {'id': '376a5aed-6a7e-4d6c-8fb6-e3de291bf068', 'name': '', 'tenant_id': '47ffa7371e464c0487c245959920ea71', 'network_id': 'a8939260-4710-4536-8ea6-e6c59aade22d', 'ip_version': 4, 'cidr': '178.41.93.0/26', 'allocation_pools': [{'start': '178.41.93.2', 'end': '178.41.93.62'}], 'gateway_ip': '178.41.93.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '178.41.93.2', 'tags': [], 'project_id': '47ffa7371e464c0487c245959920ea71'}} 2026-08-22 08:40:11,211 - INFO - Created VN ctest-vn-80433923 2026-08-22 08:40:11,264 - DEBUG - VN ctest-vn-80433923 UUID is a8939260-4710-4536-8ea6-e6c59aade22d 2026-08-22 08:40:11,650 - DEBUG - Response for create_network : {'network': {'id': '97336fa7-fd75-415e-9fb4-e081d53b6395', 'name': 'ctest-ext_vn-68530807', 'tenant_id': '47ffa7371e464c0487c245959920ea71', 'project_id': '47ffa7371e464c0487c245959920ea71', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-86097309', 'ctest-ext_vn-68530807'], 'port_security_enabled': True, 'description': ''}} 2026-08-22 08:40:11,869 - DEBUG - Response for create_subnet : {'subnet': {'id': '2d45c022-ad38-4b33-9769-74400dc3e304', 'name': '', 'tenant_id': '47ffa7371e464c0487c245959920ea71', 'network_id': '97336fa7-fd75-415e-9fb4-e081d53b6395', 'ip_version': 4, 'cidr': '26.142.67.192/26', 'allocation_pools': [{'start': '26.142.67.194', 'end': '26.142.67.254'}], 'gateway_ip': '26.142.67.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '26.142.67.194', 'tags': [], 'project_id': '47ffa7371e464c0487c245959920ea71'}} 2026-08-22 08:40:11,889 - INFO - Created VN ctest-ext_vn-68530807 2026-08-22 08:40:11,948 - DEBUG - VN ctest-ext_vn-68530807 UUID is 97336fa7-fd75-415e-9fb4-e081d53b6395 2026-08-22 08:40:12,125 - DEBUG - Services list from nova: [, , ] 2026-08-22 08:40:12,499 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6206-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6206-1) 2026-08-22 08:40:12,911 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6206-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6206-1) 2026-08-22 08:40:13,004 - INFO - Setting gateway for router 208d77ab-274e-49ea-9939-469c887756a9 to network 97336fa7-fd75-415e-9fb4-e081d53b6395 2026-08-22 08:40:13,069 - INFO - Adding interface with subnet_id 376a5aed-6a7e-4d6c-8fb6-e3de291bf068, port_id None to router 208d77ab-274e-49ea-9939-469c887756a9 2026-08-22 08:40:13,413 - INFO - Waiting for VM ctest-TestRoutersBasic-86097309-69432767 to be up.. 2026-08-22 08:40:13,503 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-22 08:40:18,604 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-22 08:40:23,755 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-22 08:40:28,836 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-22 08:40:33,920 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-22 08:40:39,005 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-22 08:40:44,093 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-22 08:40:49,184 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-22 08:40:54,282 - DEBUG - VM is in ACTIVE state now 2026-08-22 08:40:54,283 - INFO - VM name : ctest-TestRoutersBasic-86097309-69432767 2026-08-22 08:40:54,369 - DEBUG - VM ctest-TestRoutersBasic-86097309-69432767 ID is 1f87cd57-cba5-44f0-825c-0581b1a07733 2026-08-22 08:40:54,393 - DEBUG - VM ctest-TestRoutersBasic-86097309-69432767 launched on Node cn-jenkins-deploy-platform-ansible-os-6206-1 2026-08-22 08:40:54,480 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/1f87cd57-cba5-44f0-825c-0581b1a07733 2026-08-22 08:40:54,854 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/1f87cd57-cba5-44f0-825c-0581b1a07733 2026-08-22 08:40:54,890 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/d97c86c8-767c-481d-b6a1-bb945598fea8 2026-08-22 08:40:58,168 - 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 1025ms') 2026-08-22 08:40:58,168 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-86097309-69432767 failed! 2026-08-22 08:40:58,224 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-86097309:ctest-vn-80433923 is 178.41.93.1 and allocation pool is NOT set 2026-08-22 08:41:00,288 - 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=6.04 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.893 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.893/3.467/6.041/2.574 ms') 2026-08-22 08:41:00,288 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-86097309-69432767 passed 2026-08-22 08:41:00,444 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-22 08:41:00,444 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-86097309-69432767, IP 178.41.93.3, Port 22 2026-08-22 08:41:00,613 - DEBUG - VM ctest-TestRoutersBasic-86097309-69432767 is ready for SSH connections 2026-08-22 08:41:00,613 - INFO - Waiting for VM ctest-TestRoutersBasic-86097309-15922823 to be up.. 2026-08-22 08:41:00,699 - DEBUG - VM is in ACTIVE state now 2026-08-22 08:41:00,699 - INFO - VM name : ctest-TestRoutersBasic-86097309-15922823 2026-08-22 08:41:00,779 - DEBUG - VM ctest-TestRoutersBasic-86097309-15922823 ID is 4b528c35-d313-4896-80ae-48b7f5bf6982 2026-08-22 08:41:00,779 - DEBUG - VM ctest-TestRoutersBasic-86097309-15922823 launched on Node cn-jenkins-deploy-platform-ansible-os-6206-1 2026-08-22 08:41:00,860 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/4b528c35-d313-4896-80ae-48b7f5bf6982 2026-08-22 08:41:00,870 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/cabdda35-8ec4-44de-9410-1b892643d45c 2026-08-22 08:41:02,119 - 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=7.22 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=1.14 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.142/4.180/7.218/3.038 ms') 2026-08-22 08:41:02,120 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-TestRoutersBasic-86097309-15922823 passed 2026-08-22 08:41:02,276 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-22 08:41:02,276 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-86097309-15922823, IP 26.142.67.196, Port 22 2026-08-22 08:41:02,451 - DEBUG - VM ctest-TestRoutersBasic-86097309-15922823 is ready for SSH connections 2026-08-22 08:41:02,451 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-22 08:41:02,451 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 26.142.67.196, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-08-22 08:41:02,451 - DEBUG - ping -s 56 -c 3 -W 1 26.142.67.196 2026-08-22 08:41:05,490 - DEBUG - PING 26.142.67.196 (26.142.67.196): 56 data bytes 64 bytes from 26.142.67.196: seq=0 ttl=62 time=7.298 ms 64 bytes from 26.142.67.196: seq=1 ttl=62 time=1.156 ms 64 bytes from 26.142.67.196: seq=2 ttl=62 time=1.218 ms --- 26.142.67.196 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.156/3.224/7.298 ms 2026-08-22 08:41:05,490 - INFO - Ping to IP 26.142.67.196 from VM ctest-TestRoutersBasic-86097309-69432767 passed 2026-08-22 08:41:05,490 - INFO - Deleting interface with subnet_id 376a5aed-6a7e-4d6c-8fb6-e3de291bf068, port_id None from router 208d77ab-274e-49ea-9939-469c887756a9 2026-08-22 08:41:05,721 - INFO - Deleting VM ctest-TestRoutersBasic-86097309-15922823 2026-08-22 08:41:05,804 - INFO - Deleting VM ctest-TestRoutersBasic-86097309-69432767 2026-08-22 08:41:05,891 - INFO - Deleting VN ctest-ext_vn-68530807 2026-08-22 08:41:05,965 - DEBUG - VN 97336fa7-fd75-415e-9fb4-e081d53b6395 still in use: Unable to complete operation on network 97336fa7-fd75-415e-9fb4-e081d53b6395. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-5091cc53-b58c-416f-a179-2ca8cf783311'] 2026-08-22 08:41:05,966 - WARNING - Deleting VN ctest-ext_vn-68530807 failed..Will retry 2026-08-22 08:41:08,164 - DEBUG - Response for deleting network () 2026-08-22 08:41:08,164 - INFO - Deleting VN ctest-vn-80433923 2026-08-22 08:41:08,331 - DEBUG - Response for deleting network () 2026-08-22 08:41:08,602 - DEBUG - No XMPP flaps were noticed during the test
2026-08-22 08:41:08,602 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:01:00]
2026-08-22 08:41:08,602 - INFO - -------------------------------------------------------------------------------- 2026-08-22 08:41:09,423 - INFO - Deleted project: ctest-TestRoutersBasic-86097309, ID : 47ffa737-1e46-4c04-87c2-45959920ea71