2026-09-16 13:08:57,466 - INFO - Domain Default found not creating 2026-09-16 13:08:57,642 - INFO - Project ctest-TestRoutersBasic-69136935 not found, creating it 2026-09-16 13:08:58,134 - INFO - Created Project:ctest-TestRoutersBasic-69136935, ID : 3a5ca4db-7c3a-4724-86ab-3fbae1aee37c 2026-09-16 13:08:59,884 - INFO - ================================================================================ 2026-09-16 13:08:59,884 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2026-09-16 13:08:59,884 - 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-16 13:09:00,152 - DEBUG - Nothing to compare xmpp stats {'10.0.0.188': {'10.20.0.30': '0'}} with 2026-09-16 13:09:00,152 - INFO - Initial checks done. Running the testcase now 2026-09-16 13:09:00,152 - INFO - 2026-09-16 13:09:00,959 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-69136935 2026-09-16 13:09:01,060 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-69136935 2026-09-16 13:09:01,390 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-69136935', 'default']} 2026-09-16 13:09:01,695 - DEBUG - Response for create_network : {'network': {'id': 'ca977099-04ac-4a47-bd28-9615629876fa', 'name': 'ctest-vn-61965364', 'tenant_id': '3a5ca4db7c3a472486ab3fbae1aee37c', 'project_id': '3a5ca4db7c3a472486ab3fbae1aee37c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-69136935', 'ctest-vn-61965364'], 'port_security_enabled': True, 'description': ''}} 2026-09-16 13:09:01,870 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a01648d3-a18f-4062-b731-0ab75aa7b20a', 'name': '', 'tenant_id': '3a5ca4db7c3a472486ab3fbae1aee37c', 'network_id': 'ca977099-04ac-4a47-bd28-9615629876fa', 'ip_version': 4, 'cidr': '215.72.209.192/26', 'allocation_pools': [{'start': '215.72.209.194', 'end': '215.72.209.254'}], 'gateway_ip': '215.72.209.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '215.72.209.194', 'tags': [], 'project_id': '3a5ca4db7c3a472486ab3fbae1aee37c'}} 2026-09-16 13:09:01,889 - INFO - Created VN ctest-vn-61965364 2026-09-16 13:09:01,943 - DEBUG - VN ctest-vn-61965364 UUID is ca977099-04ac-4a47-bd28-9615629876fa 2026-09-16 13:09:02,281 - DEBUG - Response for create_network : {'network': {'id': '9d717508-01c0-40a0-a274-ee004db6609f', 'name': 'ctest-ext_vn-51068484', 'tenant_id': '3a5ca4db7c3a472486ab3fbae1aee37c', 'project_id': '3a5ca4db7c3a472486ab3fbae1aee37c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-69136935', 'ctest-ext_vn-51068484'], 'port_security_enabled': True, 'description': ''}} 2026-09-16 13:09:02,453 - DEBUG - Response for create_subnet : {'subnet': {'id': '1e618fbc-d869-42fa-a2e8-ffa937b6eb9d', 'name': '', 'tenant_id': '3a5ca4db7c3a472486ab3fbae1aee37c', 'network_id': '9d717508-01c0-40a0-a274-ee004db6609f', 'ip_version': 4, 'cidr': '120.191.130.0/26', 'allocation_pools': [{'start': '120.191.130.2', 'end': '120.191.130.62'}], 'gateway_ip': '120.191.130.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '120.191.130.2', 'tags': [], 'project_id': '3a5ca4db7c3a472486ab3fbae1aee37c'}} 2026-09-16 13:09:02,473 - INFO - Created VN ctest-ext_vn-51068484 2026-09-16 13:09:02,527 - DEBUG - VN ctest-ext_vn-51068484 UUID is 9d717508-01c0-40a0-a274-ee004db6609f 2026-09-16 13:09:02,741 - DEBUG - Services list from nova: [, , ] 2026-09-16 13:09:03,095 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6292-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6292-1) 2026-09-16 13:09:03,518 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6292-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6292-1) 2026-09-16 13:09:03,592 - INFO - Setting gateway for router 1af0c1bc-cd8e-4218-826c-69a0cf58e2d7 to network 9d717508-01c0-40a0-a274-ee004db6609f 2026-09-16 13:09:03,653 - INFO - Adding interface with subnet_id a01648d3-a18f-4062-b731-0ab75aa7b20a, port_id None to router 1af0c1bc-cd8e-4218-826c-69a0cf58e2d7 2026-09-16 13:09:03,920 - INFO - Waiting for VM ctest-TestRoutersBasic-69136935-51604602 to be up.. 2026-09-16 13:09:03,985 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 13:09:09,068 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 13:09:14,169 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 13:09:19,245 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 13:09:24,420 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 13:09:29,560 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 13:09:34,649 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 13:09:39,736 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 13:09:44,820 - DEBUG - VM is in ACTIVE state now 2026-09-16 13:09:44,820 - INFO - VM name : ctest-TestRoutersBasic-69136935-51604602 2026-09-16 13:09:44,911 - DEBUG - VM ctest-TestRoutersBasic-69136935-51604602 ID is ec5dd1a5-efe3-41cc-8232-dfa703f24468 2026-09-16 13:09:44,937 - DEBUG - VM ctest-TestRoutersBasic-69136935-51604602 launched on Node cn-jenkins-deploy-platform-ansible-os-6292-1 2026-09-16 13:09:45,033 - DEBUG - Requesting: http://10.0.0.188:8082/virtual-machine/ec5dd1a5-efe3-41cc-8232-dfa703f24468 2026-09-16 13:09:45,369 - DEBUG - Requesting: http://10.0.0.188:8082/virtual-machine/ec5dd1a5-efe3-41cc-8232-dfa703f24468 2026-09-16 13:09:45,403 - DEBUG - Requesting: http://10.0.0.188:8082/virtual-machine-interface/ff0650c8-6a01-4355-b46a-d6efaaf66acb 2026-09-16 13:09:48,663 - 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=2 ttl=63 time=8.37 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1021ms\r\nrtt min/avg/max/mdev = 8.374/8.374/8.374/0.000 ms') 2026-09-16 13:09:48,663 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-69136935-51604602 passed 2026-09-16 13:09:48,819 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-16 13:09:48,820 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-69136935-51604602, IP 215.72.209.195, Port 22 2026-09-16 13:09:48,989 - DEBUG - VM ctest-TestRoutersBasic-69136935-51604602 is ready for SSH connections 2026-09-16 13:09:48,989 - INFO - Waiting for VM ctest-TestRoutersBasic-69136935-54070682 to be up.. 2026-09-16 13:09:49,084 - DEBUG - VM is in ACTIVE state now 2026-09-16 13:09:49,084 - INFO - VM name : ctest-TestRoutersBasic-69136935-54070682 2026-09-16 13:09:49,173 - DEBUG - VM ctest-TestRoutersBasic-69136935-54070682 ID is b01ed912-c362-4aa0-b172-c5f6a8198f93 2026-09-16 13:09:49,173 - DEBUG - VM ctest-TestRoutersBasic-69136935-54070682 launched on Node cn-jenkins-deploy-platform-ansible-os-6292-1 2026-09-16 13:09:49,258 - DEBUG - Requesting: http://10.0.0.188:8082/virtual-machine/b01ed912-c362-4aa0-b172-c5f6a8198f93 2026-09-16 13:09:49,268 - DEBUG - Requesting: http://10.0.0.188:8082/virtual-machine-interface/f4a96207-6288-4169-a497-3fd73dc75970 2026-09-16 13:09:52,532 - 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=2 ttl=63 time=7.26 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1030ms\r\nrtt min/avg/max/mdev = 7.256/7.256/7.256/0.000 ms') 2026-09-16 13:09:52,532 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-TestRoutersBasic-69136935-54070682 passed 2026-09-16 13:09:52,687 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-16 13:09:52,688 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-69136935-54070682, IP 120.191.130.4, Port 22 2026-09-16 13:09:52,853 - DEBUG - VM ctest-TestRoutersBasic-69136935-54070682 is ready for SSH connections 2026-09-16 13:09:52,853 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-16 13:09:52,853 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 120.191.130.4, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.188, gateway password: c0ntrail123 2026-09-16 13:09:52,853 - DEBUG - ping -s 56 -c 3 -W 1 120.191.130.4 2026-09-16 13:09:55,902 - DEBUG - PING 120.191.130.4 (120.191.130.4): 56 data bytes 64 bytes from 120.191.130.4: seq=0 ttl=62 time=8.757 ms 64 bytes from 120.191.130.4: seq=1 ttl=62 time=1.282 ms 64 bytes from 120.191.130.4: seq=2 ttl=62 time=1.262 ms --- 120.191.130.4 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.262/3.767/8.757 ms 2026-09-16 13:09:55,903 - INFO - Ping to IP 120.191.130.4 from VM ctest-TestRoutersBasic-69136935-51604602 passed 2026-09-16 13:09:55,903 - INFO - Deleting interface with subnet_id a01648d3-a18f-4062-b731-0ab75aa7b20a, port_id None from router 1af0c1bc-cd8e-4218-826c-69a0cf58e2d7 2026-09-16 13:09:56,124 - INFO - Deleting VM ctest-TestRoutersBasic-69136935-54070682 2026-09-16 13:09:56,222 - INFO - Deleting VM ctest-TestRoutersBasic-69136935-51604602 2026-09-16 13:09:56,297 - INFO - Deleting VN ctest-ext_vn-51068484 2026-09-16 13:09:56,371 - DEBUG - VN 9d717508-01c0-40a0-a274-ee004db6609f still in use: Unable to complete operation on network 9d717508-01c0-40a0-a274-ee004db6609f. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-243b4171-7a48-4631-a795-6c99afd45f22'] 2026-09-16 13:09:56,371 - WARNING - Deleting VN ctest-ext_vn-51068484 failed..Will retry 2026-09-16 13:09:58,416 - DEBUG - VN 9d717508-01c0-40a0-a274-ee004db6609f still in use: Unable to complete operation on network 9d717508-01c0-40a0-a274-ee004db6609f. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-0f35b6d7-068a-4767-b415-81f001fe66fb'] 2026-09-16 13:09:58,416 - WARNING - Deleting VN ctest-ext_vn-51068484 failed..Will retry 2026-09-16 13:10:00,534 - DEBUG - Response for deleting network () 2026-09-16 13:10:00,535 - INFO - Deleting VN ctest-vn-61965364 2026-09-16 13:10:00,650 - DEBUG - Response for deleting network () 2026-09-16 13:10:00,925 - DEBUG - No XMPP flaps were noticed during the test
2026-09-16 13:10:00,925 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:01:01]
2026-09-16 13:10:00,925 - INFO - -------------------------------------------------------------------------------- 2026-09-16 13:10:01,733 - INFO - Deleted project: ctest-TestRoutersBasic-69136935, ID : 3a5ca4db-7c3a-4724-86ab-3fbae1aee37c