2026-03-24 20:29:49,337 - INFO - Domain Default found not creating 2026-03-24 20:29:49,498 - INFO - Project ctest-TestRoutersBasic-76311449 not found, creating it 2026-03-24 20:29:49,942 - INFO - Created Project:ctest-TestRoutersBasic-76311449, ID : 42c2aaa3-752a-41ba-a4ec-30bd778a64d2 2026-03-24 20:29:51,388 - INFO - ================================================================================ 2026-03-24 20:29:51,388 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2026-03-24 20:29:51,388 - 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-03-24 20:29:51,655 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.17': '0'}} with 2026-03-24 20:29:51,655 - INFO - Initial checks done. Running the testcase now 2026-03-24 20:29:51,655 - INFO - 2026-03-24 20:29:52,948 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-76311449 2026-03-24 20:29:53,048 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-76311449 2026-03-24 20:29:53,364 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-76311449', 'default']} 2026-03-24 20:29:53,656 - DEBUG - Response for create_network : {'network': {'id': '473be021-91b3-495e-943c-9203e567593f', 'name': 'ctest-vn-42917464', 'tenant_id': '42c2aaa3752a41baa4ec30bd778a64d2', 'project_id': '42c2aaa3752a41baa4ec30bd778a64d2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-76311449', 'ctest-vn-42917464'], 'port_security_enabled': True, 'description': ''}} 2026-03-24 20:29:53,827 - DEBUG - Response for create_subnet : {'subnet': {'id': '4d41b661-737e-4dc6-a480-01350e13b1ae', 'name': '', 'tenant_id': '42c2aaa3752a41baa4ec30bd778a64d2', 'network_id': '473be021-91b3-495e-943c-9203e567593f', 'ip_version': 4, 'cidr': '174.183.140.0/26', 'allocation_pools': [{'start': '174.183.140.2', 'end': '174.183.140.62'}], 'gateway_ip': '174.183.140.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '174.183.140.2', 'tags': [], 'project_id': '42c2aaa3752a41baa4ec30bd778a64d2'}} 2026-03-24 20:29:53,844 - INFO - Created VN ctest-vn-42917464 2026-03-24 20:29:53,896 - DEBUG - VN ctest-vn-42917464 UUID is 473be021-91b3-495e-943c-9203e567593f 2026-03-24 20:29:54,219 - DEBUG - Response for create_network : {'network': {'id': '81910adb-84e9-4756-9d54-6156338621ed', 'name': 'ctest-ext_vn-54066720', 'tenant_id': '42c2aaa3752a41baa4ec30bd778a64d2', 'project_id': '42c2aaa3752a41baa4ec30bd778a64d2', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-76311449', 'ctest-ext_vn-54066720'], 'port_security_enabled': True, 'description': ''}} 2026-03-24 20:29:54,360 - DEBUG - Response for create_subnet : {'subnet': {'id': '43cae267-e297-42ba-b8a3-22533e1699dd', 'name': '', 'tenant_id': '42c2aaa3752a41baa4ec30bd778a64d2', 'network_id': '81910adb-84e9-4756-9d54-6156338621ed', 'ip_version': 4, 'cidr': '150.167.162.192/26', 'allocation_pools': [{'start': '150.167.162.194', 'end': '150.167.162.254'}], 'gateway_ip': '150.167.162.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '150.167.162.194', 'tags': [], 'project_id': '42c2aaa3752a41baa4ec30bd778a64d2'}} 2026-03-24 20:29:54,379 - INFO - Created VN ctest-ext_vn-54066720 2026-03-24 20:29:54,436 - DEBUG - VN ctest-ext_vn-54066720 UUID is 81910adb-84e9-4756-9d54-6156338621ed 2026-03-24 20:29:54,635 - DEBUG - Services list from nova: [, , ] 2026-03-24 20:29:55,626 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5536-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5536-1) 2026-03-24 20:29:56,800 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5536-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5536-1) 2026-03-24 20:29:56,879 - INFO - Setting gateway for router b59458cf-230c-4047-a286-61188b6ba491 to network 81910adb-84e9-4756-9d54-6156338621ed 2026-03-24 20:29:56,962 - INFO - Adding interface with subnet_id 4d41b661-737e-4dc6-a480-01350e13b1ae, port_id None to router b59458cf-230c-4047-a286-61188b6ba491 2026-03-24 20:29:57,393 - INFO - Waiting for VM ctest-TestRoutersBasic-76311449-48206299 to be up.. 2026-03-24 20:29:57,574 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-24 20:30:02,660 - DEBUG - VM is in ACTIVE state now 2026-03-24 20:30:02,660 - INFO - VM name : ctest-TestRoutersBasic-76311449-48206299 2026-03-24 20:30:02,738 - DEBUG - VM ctest-TestRoutersBasic-76311449-48206299 ID is 0593a892-895b-4f31-a1c8-ac39b13fce3a 2026-03-24 20:30:02,758 - DEBUG - VM ctest-TestRoutersBasic-76311449-48206299 launched on Node cn-jenkins-deploy-platform-ansible-os-5536-1 2026-03-24 20:30:02,837 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/0593a892-895b-4f31-a1c8-ac39b13fce3a 2026-03-24 20:30:03,135 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/0593a892-895b-4f31-a1c8-ac39b13fce3a 2026-03-24 20:30:03,169 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/a8c97241-182c-434a-ab8b-d58229b50c69 2026-03-24 20:30:06,434 - 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 1027ms') 2026-03-24 20:30:06,434 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-76311449-48206299 failed! 2026-03-24 20:30:06,488 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-76311449:ctest-vn-42917464 is 174.183.140.1 and allocation pool is NOT set 2026-03-24 20:30:08,547 - 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=2.88 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.411 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.411/1.643/2.876/1.232 ms') 2026-03-24 20:30:08,547 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-76311449-48206299 passed 2026-03-24 20:30:08,696 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-24 20:30:08,696 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-76311449-48206299, IP 174.183.140.3, Port 22 2026-03-24 20:30:08,851 - DEBUG - VM ctest-TestRoutersBasic-76311449-48206299 is ready for SSH connections 2026-03-24 20:30:08,851 - INFO - Waiting for VM ctest-TestRoutersBasic-76311449-07501343 to be up.. 2026-03-24 20:30:08,919 - DEBUG - VM is in ACTIVE state now 2026-03-24 20:30:08,919 - INFO - VM name : ctest-TestRoutersBasic-76311449-07501343 2026-03-24 20:30:08,990 - DEBUG - VM ctest-TestRoutersBasic-76311449-07501343 ID is ccf922b4-ebe2-4b07-9024-df56c3fae4ba 2026-03-24 20:30:08,990 - DEBUG - VM ctest-TestRoutersBasic-76311449-07501343 launched on Node cn-jenkins-deploy-platform-ansible-os-5536-1 2026-03-24 20:30:09,061 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/ccf922b4-ebe2-4b07-9024-df56c3fae4ba 2026-03-24 20:30:09,068 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/5b37e0af-b6a3-4d6a-8098-5c28ff2b226a 2026-03-24 20:30:10,297 - 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=4.34 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.869 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.869/2.602/4.336/1.733 ms') 2026-03-24 20:30:10,297 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestRoutersBasic-76311449-07501343 passed 2026-03-24 20:30:10,444 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-24 20:30:10,444 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-76311449-07501343, IP 150.167.162.195, Port 22 2026-03-24 20:30:10,603 - DEBUG - VM ctest-TestRoutersBasic-76311449-07501343 is ready for SSH connections 2026-03-24 20:30:10,603 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-24 20:30:10,603 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 150.167.162.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-03-24 20:30:10,603 - DEBUG - ping -s 56 -c 3 -W 1 150.167.162.195 2026-03-24 20:30:14,640 - DEBUG - PING 150.167.162.195 (150.167.162.195): 56 data bytes 64 bytes from 150.167.162.195: seq=0 ttl=62 time=6.922 ms 64 bytes from 150.167.162.195: seq=1 ttl=62 time=1.038 ms 64 bytes from 150.167.162.195: seq=2 ttl=62 time=0.835 ms --- 150.167.162.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.835/2.931/6.922 ms 2026-03-24 20:30:14,640 - INFO - Ping to IP 150.167.162.195 from VM ctest-TestRoutersBasic-76311449-48206299 passed 2026-03-24 20:30:14,640 - INFO - Deleting interface with subnet_id 4d41b661-737e-4dc6-a480-01350e13b1ae, port_id None from router b59458cf-230c-4047-a286-61188b6ba491 2026-03-24 20:30:14,865 - INFO - Deleting VM ctest-TestRoutersBasic-76311449-07501343 2026-03-24 20:30:14,929 - INFO - Deleting VM ctest-TestRoutersBasic-76311449-48206299 2026-03-24 20:30:15,011 - INFO - Deleting VN ctest-ext_vn-54066720 2026-03-24 20:30:15,070 - DEBUG - VN 81910adb-84e9-4756-9d54-6156338621ed still in use: Unable to complete operation on network 81910adb-84e9-4756-9d54-6156338621ed. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-5b7f3491-65ed-4699-af2a-c5c664b812cd'] 2026-03-24 20:30:15,070 - WARNING - Deleting VN ctest-ext_vn-54066720 failed..Will retry 2026-03-24 20:30:17,152 - DEBUG - Response for deleting network () 2026-03-24 20:30:17,153 - INFO - Deleting VN ctest-vn-42917464 2026-03-24 20:30:17,237 - DEBUG - Response for deleting network () 2026-03-24 20:30:17,493 - DEBUG - No XMPP flaps were noticed during the test
2026-03-24 20:30:17,493 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:26]
2026-03-24 20:30:17,493 - INFO - -------------------------------------------------------------------------------- 2026-03-24 20:30:18,164 - INFO - Deleted project: ctest-TestRoutersBasic-76311449, ID : 42c2aaa3-752a-41ba-a4ec-30bd778a64d2