2026-03-19 02:12:18,256 - INFO - Reading existing Domain with UUID 935bc1a1-2451-4f6c-b0bc-2e43d3120885 2026-03-19 02:12:18,270 - INFO - Using existing domain ['admin_domain'](935bc1a1-2451-4f6c-b0bc-2e43d3120885) 2026-03-19 02:12:18,478 - INFO - Project ctest-TestRoutersBasic-72720907 not found, creating it 2026-03-19 02:12:19,021 - INFO - Created Project:ctest-TestRoutersBasic-72720907, ID : 5d846188-21aa-4746-9b87-2c57ffc2f363 2026-03-19 02:12:20,751 - INFO - ================================================================================ 2026-03-19 02:12:20,751 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2026-03-19 02:12:20,752 - 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-19 02:12:21,138 - DEBUG - Nothing to compare xmpp stats {'10.0.0.129': {'10.0.0.129': '0'}} with 2026-03-19 02:12:21,138 - INFO - Initial checks done. Running the testcase now 2026-03-19 02:12:21,138 - INFO - 2026-03-19 02:12:21,949 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-72720907 2026-03-19 02:12:22,049 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-72720907 2026-03-19 02:12:22,376 - DEBUG - Reading firewall policy {'fq_name': ['admin_domain', 'ctest-TestRoutersBasic-72720907', 'default']} 2026-03-19 02:12:22,897 - DEBUG - Response for create_network : {'network': {'id': '7d26626f-3228-4e7b-a8f9-a25072148e30', 'name': 'ctest-vn-19594421', 'tenant_id': '5d84618821aa47469b872c57ffc2f363', 'project_id': '5d84618821aa47469b872c57ffc2f363', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['admin_domain', 'ctest-TestRoutersBasic-72720907', 'ctest-vn-19594421'], 'port_security_enabled': True, 'description': '', 'created_at': None, 'updated_at': None}} 2026-03-19 02:12:23,106 - DEBUG - Response for create_subnet : {'subnet': {'id': '3f1148b3-a108-40fa-b474-316be85a32ee', 'name': '', 'tenant_id': '5d84618821aa47469b872c57ffc2f363', 'network_id': '7d26626f-3228-4e7b-a8f9-a25072148e30', 'ip_version': 4, 'cidr': '140.125.83.192/26', 'allocation_pools': [{'start': '140.125.83.194', 'end': '140.125.83.254'}], 'gateway_ip': '140.125.83.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'created_at': '2026-03-19T02:12:23.010807', 'updated_at': '2026-03-19T02:12:23.010807', 'dns_server_address': '140.125.83.194', 'tags': [], 'project_id': '5d84618821aa47469b872c57ffc2f363'}} 2026-03-19 02:12:23,175 - INFO - Created VN ctest-vn-19594421 2026-03-19 02:12:23,229 - DEBUG - VN ctest-vn-19594421 UUID is 7d26626f-3228-4e7b-a8f9-a25072148e30 2026-03-19 02:12:23,783 - DEBUG - Response for create_network : {'network': {'id': '216ed1cb-3530-4ec8-b966-73e0b4c2ddbf', 'name': 'ctest-ext_vn-21083133', 'tenant_id': '5d84618821aa47469b872c57ffc2f363', 'project_id': '5d84618821aa47469b872c57ffc2f363', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['admin_domain', 'ctest-TestRoutersBasic-72720907', 'ctest-ext_vn-21083133'], 'port_security_enabled': True, 'description': '', 'created_at': None, 'updated_at': None}} 2026-03-19 02:12:24,038 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd59263d8-92a7-4640-adfa-90c40851e77e', 'name': '', 'tenant_id': '5d84618821aa47469b872c57ffc2f363', 'network_id': '216ed1cb-3530-4ec8-b966-73e0b4c2ddbf', 'ip_version': 4, 'cidr': '139.97.134.0/26', 'allocation_pools': [{'start': '139.97.134.2', 'end': '139.97.134.62'}], 'gateway_ip': '139.97.134.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'created_at': '2026-03-19T02:12:23.939773', 'updated_at': '2026-03-19T02:12:23.939773', 'dns_server_address': '139.97.134.2', 'tags': [], 'project_id': '5d84618821aa47469b872c57ffc2f363'}} 2026-03-19 02:12:24,107 - INFO - Created VN ctest-ext_vn-21083133 2026-03-19 02:12:24,161 - DEBUG - VN ctest-ext_vn-21083133 UUID is 216ed1cb-3530-4ec8-b966-73e0b4c2ddbf 2026-03-19 02:12:24,439 - DEBUG - Services list from nova: [, , ] 2026-03-19 02:12:25,551 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-juju-os-686-1), Zone: (nova:cn-jenkins-deploy-platform-juju-os-686-1) 2026-03-19 02:12:26,991 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-juju-os-686-1), Zone: (nova:cn-jenkins-deploy-platform-juju-os-686-1) 2026-03-19 02:12:27,133 - INFO - Setting gateway for router 57740caf-4b48-48c6-a06f-5be750181b9d to network 216ed1cb-3530-4ec8-b966-73e0b4c2ddbf 2026-03-19 02:12:27,321 - INFO - Adding interface with subnet_id 3f1148b3-a108-40fa-b474-316be85a32ee, port_id None to router 57740caf-4b48-48c6-a06f-5be750181b9d 2026-03-19 02:12:27,851 - INFO - Waiting for VM ctest-TestRoutersBasic-72720907-21430649 to be up.. 2026-03-19 02:12:28,033 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-19 02:12:33,253 - DEBUG - VM is in ACTIVE state now 2026-03-19 02:12:33,254 - INFO - VM name : ctest-TestRoutersBasic-72720907-21430649 2026-03-19 02:12:33,403 - DEBUG - VM ctest-TestRoutersBasic-72720907-21430649 ID is 390933c2-ca61-49a4-9d27-c3c28dd3e6fd 2026-03-19 02:12:33,429 - DEBUG - VM ctest-TestRoutersBasic-72720907-21430649 launched on Node cn-jenkins-deploy-platform-juju-os-686-1 2026-03-19 02:12:33,566 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine/390933c2-ca61-49a4-9d27-c3c28dd3e6fd 2026-03-19 02:12:33,913 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine/390933c2-ca61-49a4-9d27-c3c28dd3e6fd 2026-03-19 02:12:34,002 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine-interface/94be34c8-0611-468c-90ce-a2d7d8b966d3 2026-03-19 02:12:37,492 - 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 1025ms') 2026-03-19 02:12:37,492 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-72720907-21430649 failed! 2026-03-19 02:12:37,557 - DEBUG - Gateway for vn admin_domain:ctest-TestRoutersBasic-72720907:ctest-vn-19594421 is 140.125.83.193 and allocation pool is NOT set 2026-03-19 02:12:39,648 - 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=3.58 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.494 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.494/2.034/3.575/1.540 ms') 2026-03-19 02:12:39,648 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-72720907-21430649 passed 2026-03-19 02:12:39,797 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-19 02:12:39,797 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-72720907-21430649, IP 140.125.83.195, Port 22 2026-03-19 02:12:40,011 - DEBUG - VM ctest-TestRoutersBasic-72720907-21430649 is ready for SSH connections 2026-03-19 02:12:40,011 - INFO - Waiting for VM ctest-TestRoutersBasic-72720907-34523984 to be up.. 2026-03-19 02:12:40,176 - DEBUG - VM is in ACTIVE state now 2026-03-19 02:12:40,176 - INFO - VM name : ctest-TestRoutersBasic-72720907-34523984 2026-03-19 02:12:40,304 - DEBUG - VM ctest-TestRoutersBasic-72720907-34523984 ID is e2d1e1d6-667d-49d5-8fcd-8bffe7f63737 2026-03-19 02:12:40,305 - DEBUG - VM ctest-TestRoutersBasic-72720907-34523984 launched on Node cn-jenkins-deploy-platform-juju-os-686-1 2026-03-19 02:12:40,444 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine/e2d1e1d6-667d-49d5-8fcd-8bffe7f63737 2026-03-19 02:12:40,503 - DEBUG - Requesting: https://10.0.0.129:8082/virtual-machine-interface/c73d9b96-d648-438a-9de3-b3c0bdc616b6 2026-03-19 02:12:41,954 - 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=2.69 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.586 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.586/1.635/2.685/1.049 ms') 2026-03-19 02:12:41,954 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestRoutersBasic-72720907-34523984 passed 2026-03-19 02:12:42,113 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-19 02:12:42,113 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-72720907-34523984, IP 139.97.134.3, Port 22 2026-03-19 02:12:42,327 - DEBUG - VM ctest-TestRoutersBasic-72720907-34523984 is ready for SSH connections 2026-03-19 02:12:42,327 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-19 02:12:42,327 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 139.97.134.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.129, gateway password: c0ntrail123 2026-03-19 02:12:42,327 - DEBUG - ping -s 56 -c 3 -W 1 139.97.134.3 2026-03-19 02:12:46,040 - DEBUG - PING 139.97.134.3 (139.97.134.3): 56 data bytes 64 bytes from 139.97.134.3: seq=0 ttl=62 time=5.935 ms 64 bytes from 139.97.134.3: seq=1 ttl=62 time=0.928 ms 64 bytes from 139.97.134.3: seq=2 ttl=62 time=0.930 ms --- 139.97.134.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.928/2.597/5.935 ms 2026-03-19 02:12:46,040 - INFO - Ping to IP 139.97.134.3 from VM ctest-TestRoutersBasic-72720907-21430649 passed 2026-03-19 02:12:46,040 - INFO - Deleting interface with subnet_id 3f1148b3-a108-40fa-b474-316be85a32ee, port_id None from router 57740caf-4b48-48c6-a06f-5be750181b9d 2026-03-19 02:12:46,430 - INFO - Deleting VM ctest-TestRoutersBasic-72720907-34523984 2026-03-19 02:12:46,538 - INFO - Deleting VM ctest-TestRoutersBasic-72720907-21430649 2026-03-19 02:12:46,646 - INFO - Deleting VN ctest-ext_vn-21083133 2026-03-19 02:12:46,828 - DEBUG - VN 216ed1cb-3530-4ec8-b966-73e0b4c2ddbf still in use: Unable to complete operation on network 216ed1cb-3530-4ec8-b966-73e0b4c2ddbf. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-0158b40b-cc20-449c-a8c1-395a05f40e82'] 2026-03-19 02:12:46,828 - WARNING - Deleting VN ctest-ext_vn-21083133 failed..Will retry 2026-03-19 02:12:48,993 - DEBUG - Response for deleting network () 2026-03-19 02:12:48,993 - INFO - Deleting VN ctest-vn-19594421 2026-03-19 02:12:49,158 - DEBUG - Response for deleting network () 2026-03-19 02:12:49,546 - DEBUG - No XMPP flaps were noticed during the test
2026-03-19 02:12:49,546 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:29]
2026-03-19 02:12:49,546 - INFO - -------------------------------------------------------------------------------- 2026-03-19 02:12:50,313 - INFO - Deleted project: ctest-TestRoutersBasic-72720907, ID : 5d846188-21aa-4746-9b87-2c57ffc2f363