2026-08-25 14:54:48,111 - INFO - Domain Default found not creating 2026-08-25 14:54:48,310 - INFO - Project ctest-TestRoutersBasic-87793286 not found, creating it 2026-08-25 14:54:48,824 - INFO - Created Project:ctest-TestRoutersBasic-87793286, ID : 3405ef63-92bf-4250-9071-09d62a55f654 2026-08-25 14:54:50,629 - INFO - ================================================================================ 2026-08-25 14:54:50,630 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2026-08-25 14:54:50,630 - 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-25 14:54:50,891 - DEBUG - Nothing to compare xmpp stats {'10.0.0.187': {'10.20.0.254': '0'}} with 2026-08-25 14:54:50,891 - INFO - Initial checks done. Running the testcase now 2026-08-25 14:54:50,892 - INFO - 2026-08-25 14:54:51,668 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-87793286 2026-08-25 14:54:51,768 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-87793286 2026-08-25 14:54:52,095 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-87793286', 'default']} 2026-08-25 14:54:52,415 - DEBUG - Response for create_network : {'network': {'id': '598a8a54-047a-49dc-8e88-e14b756534d0', 'name': 'ctest-vn-60088364', 'tenant_id': '3405ef6392bf4250907109d62a55f654', 'project_id': '3405ef6392bf4250907109d62a55f654', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-87793286', 'ctest-vn-60088364'], 'port_security_enabled': True, 'description': ''}} 2026-08-25 14:54:52,626 - DEBUG - Response for create_subnet : {'subnet': {'id': '81265f0e-6939-4ed9-89d8-c55e1dcaf41d', 'name': '', 'tenant_id': '3405ef6392bf4250907109d62a55f654', 'network_id': '598a8a54-047a-49dc-8e88-e14b756534d0', 'ip_version': 4, 'cidr': '27.231.122.64/26', 'allocation_pools': [{'start': '27.231.122.66', 'end': '27.231.122.126'}], 'gateway_ip': '27.231.122.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '27.231.122.66', 'tags': [], 'project_id': '3405ef6392bf4250907109d62a55f654'}} 2026-08-25 14:54:52,643 - INFO - Created VN ctest-vn-60088364 2026-08-25 14:54:52,699 - DEBUG - VN ctest-vn-60088364 UUID is 598a8a54-047a-49dc-8e88-e14b756534d0 2026-08-25 14:54:53,048 - DEBUG - Response for create_network : {'network': {'id': '64a734e0-0487-4fea-aa5b-9178210cf785', 'name': 'ctest-ext_vn-25427576', 'tenant_id': '3405ef6392bf4250907109d62a55f654', 'project_id': '3405ef6392bf4250907109d62a55f654', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-87793286', 'ctest-ext_vn-25427576'], 'port_security_enabled': True, 'description': ''}} 2026-08-25 14:54:53,267 - DEBUG - Response for create_subnet : {'subnet': {'id': '35a21279-08fb-4d36-9d86-4538f033af85', 'name': '', 'tenant_id': '3405ef6392bf4250907109d62a55f654', 'network_id': '64a734e0-0487-4fea-aa5b-9178210cf785', 'ip_version': 4, 'cidr': '27.57.15.128/26', 'allocation_pools': [{'start': '27.57.15.130', 'end': '27.57.15.190'}], 'gateway_ip': '27.57.15.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '27.57.15.130', 'tags': [], 'project_id': '3405ef6392bf4250907109d62a55f654'}} 2026-08-25 14:54:53,285 - INFO - Created VN ctest-ext_vn-25427576 2026-08-25 14:54:53,339 - DEBUG - VN ctest-ext_vn-25427576 UUID is 64a734e0-0487-4fea-aa5b-9178210cf785 2026-08-25 14:54:53,523 - DEBUG - Services list from nova: [, , ] 2026-08-25 14:54:53,934 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6224-30), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6224-30) 2026-08-25 14:54:54,367 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6224-30), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6224-30) 2026-08-25 14:54:54,463 - INFO - Setting gateway for router df6a5ca2-63f2-4507-a918-156f47b01e5e to network 64a734e0-0487-4fea-aa5b-9178210cf785 2026-08-25 14:54:54,537 - INFO - Adding interface with subnet_id 81265f0e-6939-4ed9-89d8-c55e1dcaf41d, port_id None to router df6a5ca2-63f2-4507-a918-156f47b01e5e 2026-08-25 14:54:54,774 - INFO - Waiting for VM ctest-TestRoutersBasic-87793286-64573629 to be up.. 2026-08-25 14:54:54,846 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-25 14:54:59,928 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-25 14:55:05,013 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-25 14:55:10,174 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-25 14:55:15,265 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-25 14:55:20,351 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-25 14:55:25,446 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-25 14:55:30,534 - DEBUG - VM is in ACTIVE state now 2026-08-25 14:55:30,534 - INFO - VM name : ctest-TestRoutersBasic-87793286-64573629 2026-08-25 14:55:30,627 - DEBUG - VM ctest-TestRoutersBasic-87793286-64573629 ID is a8f3e276-2965-4826-8d0e-8dc0ad664829 2026-08-25 14:55:30,654 - DEBUG - VM ctest-TestRoutersBasic-87793286-64573629 launched on Node cn-jenkins-deploy-platform-ansible-os-6224-30 2026-08-25 14:55:30,735 - DEBUG - Requesting: http://10.0.0.187:8082/virtual-machine/a8f3e276-2965-4826-8d0e-8dc0ad664829 2026-08-25 14:55:31,059 - DEBUG - Requesting: http://10.0.0.187:8082/virtual-machine/a8f3e276-2965-4826-8d0e-8dc0ad664829 2026-08-25 14:55:31,092 - DEBUG - Requesting: http://10.0.0.187:8082/virtual-machine-interface/fa9be2bd-12af-47fa-84ad-1533403a9ef9 2026-08-25 14:55:34,371 - 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 1030ms') 2026-08-25 14:55:34,372 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-87793286-64573629 failed! 2026-08-25 14:55:34,431 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-87793286:ctest-vn-60088364 is 27.231.122.65 and allocation pool is NOT set 2026-08-25 14:55:36,498 - 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=13.8 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.653 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.653/7.211/13.770/6.558 ms') 2026-08-25 14:55:36,498 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-87793286-64573629 passed 2026-08-25 14:55:36,651 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-25 14:55:36,652 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-87793286-64573629, IP 27.231.122.67, Port 22 2026-08-25 14:55:36,817 - DEBUG - VM ctest-TestRoutersBasic-87793286-64573629 is ready for SSH connections 2026-08-25 14:55:36,817 - INFO - Waiting for VM ctest-TestRoutersBasic-87793286-52115059 to be up.. 2026-08-25 14:55:36,902 - DEBUG - VM is in ACTIVE state now 2026-08-25 14:55:36,902 - INFO - VM name : ctest-TestRoutersBasic-87793286-52115059 2026-08-25 14:55:36,983 - DEBUG - VM ctest-TestRoutersBasic-87793286-52115059 ID is 6d8e9d77-7b65-476d-be88-df51a47756b8 2026-08-25 14:55:36,984 - DEBUG - VM ctest-TestRoutersBasic-87793286-52115059 launched on Node cn-jenkins-deploy-platform-ansible-os-6224-30 2026-08-25 14:55:37,062 - DEBUG - Requesting: http://10.0.0.187:8082/virtual-machine/6d8e9d77-7b65-476d-be88-df51a47756b8 2026-08-25 14:55:37,071 - DEBUG - Requesting: http://10.0.0.187:8082/virtual-machine-interface/902d7ccb-a507-4eb5-b517-8b8fcc053484 2026-08-25 14:55:40,314 - 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 1005ms') 2026-08-25 14:55:40,314 - DEBUG - Ping to Metadata IP 169.254.0.6 of VM ctest-TestRoutersBasic-87793286-52115059 failed! 2026-08-25 14:55:40,371 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-87793286:ctest-ext_vn-25427576 is 27.57.15.129 and allocation pool is NOT set 2026-08-25 14:55:42,441 - 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=5.97 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=1.94 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 1.943/3.954/5.966/2.011 ms') 2026-08-25 14:55:42,441 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-TestRoutersBasic-87793286-52115059 passed 2026-08-25 14:55:42,595 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-25 14:55:42,595 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-87793286-52115059, IP 27.57.15.132, Port 22 2026-08-25 14:55:42,763 - DEBUG - VM ctest-TestRoutersBasic-87793286-52115059 is ready for SSH connections 2026-08-25 14:55:42,763 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-25 14:55:42,763 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 27.57.15.132, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.187, gateway password: c0ntrail123 2026-08-25 14:55:42,763 - DEBUG - ping -s 56 -c 3 -W 1 27.57.15.132 2026-08-25 14:55:45,795 - DEBUG - PING 27.57.15.132 (27.57.15.132): 56 data bytes 64 bytes from 27.57.15.132: seq=0 ttl=62 time=7.987 ms 64 bytes from 27.57.15.132: seq=1 ttl=62 time=1.322 ms 64 bytes from 27.57.15.132: seq=2 ttl=62 time=1.304 ms --- 27.57.15.132 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.304/3.537/7.987 ms 2026-08-25 14:55:45,795 - INFO - Ping to IP 27.57.15.132 from VM ctest-TestRoutersBasic-87793286-64573629 passed 2026-08-25 14:55:45,796 - INFO - Deleting interface with subnet_id 81265f0e-6939-4ed9-89d8-c55e1dcaf41d, port_id None from router df6a5ca2-63f2-4507-a918-156f47b01e5e 2026-08-25 14:55:46,036 - INFO - Deleting VM ctest-TestRoutersBasic-87793286-52115059 2026-08-25 14:55:46,123 - INFO - Deleting VM ctest-TestRoutersBasic-87793286-64573629 2026-08-25 14:55:46,210 - INFO - Deleting VN ctest-ext_vn-25427576 2026-08-25 14:55:46,302 - DEBUG - VN 64a734e0-0487-4fea-aa5b-9178210cf785 still in use: Unable to complete operation on network 64a734e0-0487-4fea-aa5b-9178210cf785. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-8ce857f6-347a-4a91-8fcc-f9d38270820b'] 2026-08-25 14:55:46,302 - WARNING - Deleting VN ctest-ext_vn-25427576 failed..Will retry 2026-08-25 14:55:48,346 - DEBUG - VN 64a734e0-0487-4fea-aa5b-9178210cf785 still in use: Unable to complete operation on network 64a734e0-0487-4fea-aa5b-9178210cf785. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-8e69a514-b2c8-4742-9902-4b62ba5b6923'] 2026-08-25 14:55:48,346 - WARNING - Deleting VN ctest-ext_vn-25427576 failed..Will retry 2026-08-25 14:55:50,478 - DEBUG - Response for deleting network () 2026-08-25 14:55:50,478 - INFO - Deleting VN ctest-vn-60088364 2026-08-25 14:55:50,633 - DEBUG - Response for deleting network () 2026-08-25 14:55:50,905 - DEBUG - No XMPP flaps were noticed during the test
2026-08-25 14:55:50,905 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:01:00]
2026-08-25 14:55:50,905 - INFO - -------------------------------------------------------------------------------- 2026-08-25 14:55:51,711 - INFO - Deleted project: ctest-TestRoutersBasic-87793286, ID : 3405ef63-92bf-4250-9071-09d62a55f654