2026-09-09 15:23:49,770 - INFO - Domain Default found not creating 2026-09-09 15:23:49,924 - INFO - Project ctest-TestRoutersBasic-38837428 not found, creating it 2026-09-09 15:23:50,403 - INFO - Created Project:ctest-TestRoutersBasic-38837428, ID : 4989ebce-0aa7-4643-b076-1dd511cb57e6 2026-09-09 15:23:51,956 - INFO - ================================================================================ 2026-09-09 15:23:51,956 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2026-09-09 15:23:51,956 - 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-09-09 15:23:52,217 - DEBUG - Nothing to compare xmpp stats {'10.0.0.185': {'10.20.0.17': '0'}} with 2026-09-09 15:23:52,217 - INFO - Initial checks done. Running the testcase now 2026-09-09 15:23:52,217 - INFO - 2026-09-09 15:23:52,991 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-38837428 2026-09-09 15:23:53,087 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-38837428 2026-09-09 15:23:53,422 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-38837428', 'default']} 2026-09-09 15:23:53,736 - DEBUG - Response for create_network : {'network': {'id': '275b4770-416f-4fbe-bccd-68e6a70722c7', 'name': 'ctest-vn-39883214', 'tenant_id': '4989ebce0aa74643b0761dd511cb57e6', 'project_id': '4989ebce0aa74643b0761dd511cb57e6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-38837428', 'ctest-vn-39883214'], 'port_security_enabled': True, 'description': ''}} 2026-09-09 15:23:53,899 - DEBUG - Response for create_subnet : {'subnet': {'id': '0e75a01d-5dfe-4eb1-99b9-14983963f248', 'name': '', 'tenant_id': '4989ebce0aa74643b0761dd511cb57e6', 'network_id': '275b4770-416f-4fbe-bccd-68e6a70722c7', 'ip_version': 4, 'cidr': '20.133.50.192/26', 'allocation_pools': [{'start': '20.133.50.194', 'end': '20.133.50.254'}], 'gateway_ip': '20.133.50.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20.133.50.194', 'tags': [], 'project_id': '4989ebce0aa74643b0761dd511cb57e6'}} 2026-09-09 15:23:53,917 - INFO - Created VN ctest-vn-39883214 2026-09-09 15:23:53,970 - DEBUG - VN ctest-vn-39883214 UUID is 275b4770-416f-4fbe-bccd-68e6a70722c7 2026-09-09 15:23:54,354 - DEBUG - Response for create_network : {'network': {'id': 'c1a3437d-0559-473e-8f55-dd19f76daf61', 'name': 'ctest-ext_vn-36024625', 'tenant_id': '4989ebce0aa74643b0761dd511cb57e6', 'project_id': '4989ebce0aa74643b0761dd511cb57e6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-38837428', 'ctest-ext_vn-36024625'], 'port_security_enabled': True, 'description': ''}} 2026-09-09 15:23:54,542 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd93638ae-a41a-4f76-88ad-1cb7bb1e987f', 'name': '', 'tenant_id': '4989ebce0aa74643b0761dd511cb57e6', 'network_id': 'c1a3437d-0559-473e-8f55-dd19f76daf61', 'ip_version': 4, 'cidr': '57.148.16.128/26', 'allocation_pools': [{'start': '57.148.16.130', 'end': '57.148.16.190'}], 'gateway_ip': '57.148.16.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '57.148.16.130', 'tags': [], 'project_id': '4989ebce0aa74643b0761dd511cb57e6'}} 2026-09-09 15:23:54,558 - INFO - Created VN ctest-ext_vn-36024625 2026-09-09 15:23:54,610 - DEBUG - VN ctest-ext_vn-36024625 UUID is c1a3437d-0559-473e-8f55-dd19f76daf61 2026-09-09 15:23:54,808 - DEBUG - Services list from nova: [, , ] 2026-09-09 15:23:55,150 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6270-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6270-1) 2026-09-09 15:23:55,540 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6270-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6270-1) 2026-09-09 15:23:55,611 - INFO - Setting gateway for router 746d8977-6080-42c0-82d1-55ed66393ed1 to network c1a3437d-0559-473e-8f55-dd19f76daf61 2026-09-09 15:23:55,676 - INFO - Adding interface with subnet_id 0e75a01d-5dfe-4eb1-99b9-14983963f248, port_id None to router 746d8977-6080-42c0-82d1-55ed66393ed1 2026-09-09 15:23:55,982 - INFO - Waiting for VM ctest-TestRoutersBasic-38837428-44478033 to be up.. 2026-09-09 15:23:56,051 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-09 15:24:01,138 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-09 15:24:06,220 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-09 15:24:11,325 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-09 15:24:16,473 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-09 15:24:21,558 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-09 15:24:26,643 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-09 15:24:31,723 - DEBUG - VM is in ACTIVE state now 2026-09-09 15:24:31,723 - INFO - VM name : ctest-TestRoutersBasic-38837428-44478033 2026-09-09 15:24:31,797 - DEBUG - VM ctest-TestRoutersBasic-38837428-44478033 ID is eec9fe3d-5865-4dd5-9428-4e95e9a7c527 2026-09-09 15:24:31,817 - DEBUG - VM ctest-TestRoutersBasic-38837428-44478033 launched on Node cn-jenkins-deploy-platform-ansible-os-6270-1 2026-09-09 15:24:31,891 - DEBUG - Requesting: http://10.0.0.185:8082/virtual-machine/eec9fe3d-5865-4dd5-9428-4e95e9a7c527 2026-09-09 15:24:32,223 - DEBUG - Requesting: http://10.0.0.185:8082/virtual-machine/eec9fe3d-5865-4dd5-9428-4e95e9a7c527 2026-09-09 15:24:32,255 - DEBUG - Requesting: http://10.0.0.185:8082/virtual-machine-interface/d66cb735-67bd-4a0e-8bcb-7a84e01f3a6b 2026-09-09 15:24:35,532 - 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-09-09 15:24:35,532 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-38837428-44478033 failed! 2026-09-09 15:24:35,618 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-38837428:ctest-vn-39883214 is 20.133.50.193 and allocation pool is NOT set 2026-09-09 15:24:37,677 - 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=4.17 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.583 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.583/2.377/4.172/1.794 ms') 2026-09-09 15:24:37,677 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-38837428-44478033 passed 2026-09-09 15:24:37,826 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-09 15:24:37,826 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-38837428-44478033, IP 20.133.50.195, Port 22 2026-09-09 15:24:38,004 - DEBUG - VM ctest-TestRoutersBasic-38837428-44478033 is ready for SSH connections 2026-09-09 15:24:38,004 - INFO - Waiting for VM ctest-TestRoutersBasic-38837428-76462163 to be up.. 2026-09-09 15:24:38,081 - DEBUG - VM is in ACTIVE state now 2026-09-09 15:24:38,081 - INFO - VM name : ctest-TestRoutersBasic-38837428-76462163 2026-09-09 15:24:38,154 - DEBUG - VM ctest-TestRoutersBasic-38837428-76462163 ID is 236b83f5-341d-45dc-8b4c-f23a7422e0c6 2026-09-09 15:24:38,155 - DEBUG - VM ctest-TestRoutersBasic-38837428-76462163 launched on Node cn-jenkins-deploy-platform-ansible-os-6270-1 2026-09-09 15:24:38,234 - DEBUG - Requesting: http://10.0.0.185:8082/virtual-machine/236b83f5-341d-45dc-8b4c-f23a7422e0c6 2026-09-09 15:24:38,245 - DEBUG - Requesting: http://10.0.0.185:8082/virtual-machine-interface/e4dbfdfb-1700-40ce-959d-8c9587a6d402 2026-09-09 15:24:41,516 - 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 1017ms') 2026-09-09 15:24:41,516 - DEBUG - Ping to Metadata IP 169.254.0.6 of VM ctest-TestRoutersBasic-38837428-76462163 failed! 2026-09-09 15:24:41,570 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-38837428:ctest-ext_vn-36024625 is 57.148.16.129 and allocation pool is NOT set 2026-09-09 15:24:43,633 - 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.73 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.456 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.456/2.094/3.733/1.638 ms') 2026-09-09 15:24:43,633 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-TestRoutersBasic-38837428-76462163 passed 2026-09-09 15:24:43,782 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-09 15:24:43,783 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-38837428-76462163, IP 57.148.16.132, Port 22 2026-09-09 15:24:43,954 - DEBUG - VM ctest-TestRoutersBasic-38837428-76462163 is ready for SSH connections 2026-09-09 15:24:43,954 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-09 15:24:43,955 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 57.148.16.132, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.185, gateway password: c0ntrail123 2026-09-09 15:24:43,955 - DEBUG - ping -s 56 -c 3 -W 1 57.148.16.132 2026-09-09 15:24:46,921 - DEBUG - PING 57.148.16.132 (57.148.16.132): 56 data bytes 64 bytes from 57.148.16.132: seq=0 ttl=62 time=7.814 ms 64 bytes from 57.148.16.132: seq=1 ttl=62 time=1.270 ms 64 bytes from 57.148.16.132: seq=2 ttl=62 time=1.100 ms --- 57.148.16.132 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.100/3.394/7.814 ms 2026-09-09 15:24:46,922 - INFO - Ping to IP 57.148.16.132 from VM ctest-TestRoutersBasic-38837428-44478033 passed 2026-09-09 15:24:46,922 - INFO - Deleting interface with subnet_id 0e75a01d-5dfe-4eb1-99b9-14983963f248, port_id None from router 746d8977-6080-42c0-82d1-55ed66393ed1 2026-09-09 15:24:47,153 - INFO - Deleting VM ctest-TestRoutersBasic-38837428-76462163 2026-09-09 15:24:47,230 - INFO - Deleting VM ctest-TestRoutersBasic-38837428-44478033 2026-09-09 15:24:47,309 - INFO - Deleting VN ctest-ext_vn-36024625 2026-09-09 15:24:47,377 - DEBUG - VN c1a3437d-0559-473e-8f55-dd19f76daf61 still in use: Unable to complete operation on network c1a3437d-0559-473e-8f55-dd19f76daf61. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-7a0ba673-0651-4213-aad3-9632ee7594dd'] 2026-09-09 15:24:47,377 - WARNING - Deleting VN ctest-ext_vn-36024625 failed..Will retry 2026-09-09 15:24:49,535 - DEBUG - Response for deleting network () 2026-09-09 15:24:49,535 - INFO - Deleting VN ctest-vn-39883214 2026-09-09 15:24:49,652 - DEBUG - Response for deleting network () 2026-09-09 15:24:49,908 - DEBUG - No XMPP flaps were noticed during the test
2026-09-09 15:24:49,908 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:58]
2026-09-09 15:24:49,908 - INFO - -------------------------------------------------------------------------------- 2026-09-09 15:24:50,681 - INFO - Deleted project: ctest-TestRoutersBasic-38837428, ID : 4989ebce-0aa7-4643-b076-1dd511cb57e6