2026-03-05 09:03:22,004 - INFO - Domain Default found not creating 2026-03-05 09:03:22,178 - INFO - Project ctest-TestRoutersBasic-27930141 not found, creating it 2026-03-05 09:03:22,652 - INFO - Created Project:ctest-TestRoutersBasic-27930141, ID : 844a40c2-d963-49de-beca-f182a768ed27 2026-03-05 09:03:24,185 - INFO - ================================================================================ 2026-03-05 09:03:24,186 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2026-03-05 09:03:24,186 - 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-05 09:03:24,441 - DEBUG - Nothing to compare xmpp stats {'10.0.0.33': {'10.20.0.17': '0'}} with 2026-03-05 09:03:24,441 - INFO - Initial checks done. Running the testcase now 2026-03-05 09:03:24,441 - INFO - 2026-03-05 09:03:25,166 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-27930141 2026-03-05 09:03:25,263 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-27930141 2026-03-05 09:03:25,595 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-27930141', 'default']} 2026-03-05 09:03:25,892 - DEBUG - Response for create_network : {'network': {'id': '6de2eed0-9a86-4982-a859-ac4d854b3996', 'name': 'ctest-vn-46036616', 'tenant_id': '844a40c2d96349debecaf182a768ed27', 'project_id': '844a40c2d96349debecaf182a768ed27', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-27930141', 'ctest-vn-46036616'], 'port_security_enabled': True, 'description': ''}} 2026-03-05 09:03:26,100 - DEBUG - Response for create_subnet : {'subnet': {'id': '8c941aab-667a-41e5-ae66-751f207c101a', 'name': '', 'tenant_id': '844a40c2d96349debecaf182a768ed27', 'network_id': '6de2eed0-9a86-4982-a859-ac4d854b3996', 'ip_version': 4, 'cidr': '138.208.68.0/26', 'allocation_pools': [{'start': '138.208.68.2', 'end': '138.208.68.62'}], 'gateway_ip': '138.208.68.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '138.208.68.2', 'tags': [], 'project_id': '844a40c2d96349debecaf182a768ed27'}} 2026-03-05 09:03:26,118 - INFO - Created VN ctest-vn-46036616 2026-03-05 09:03:26,171 - DEBUG - VN ctest-vn-46036616 UUID is 6de2eed0-9a86-4982-a859-ac4d854b3996 2026-03-05 09:03:26,484 - DEBUG - Response for create_network : {'network': {'id': 'f260e68e-1d56-4b57-96f1-7ee04e05dbf4', 'name': 'ctest-ext_vn-01788977', 'tenant_id': '844a40c2d96349debecaf182a768ed27', 'project_id': '844a40c2d96349debecaf182a768ed27', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-27930141', 'ctest-ext_vn-01788977'], 'port_security_enabled': True, 'description': ''}} 2026-03-05 09:03:26,677 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b7d7c71e-30cf-4287-927c-ea0a9a1186ef', 'name': '', 'tenant_id': '844a40c2d96349debecaf182a768ed27', 'network_id': 'f260e68e-1d56-4b57-96f1-7ee04e05dbf4', 'ip_version': 4, 'cidr': '218.230.82.0/26', 'allocation_pools': [{'start': '218.230.82.2', 'end': '218.230.82.62'}], 'gateway_ip': '218.230.82.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '218.230.82.2', 'tags': [], 'project_id': '844a40c2d96349debecaf182a768ed27'}} 2026-03-05 09:03:26,695 - INFO - Created VN ctest-ext_vn-01788977 2026-03-05 09:03:26,747 - DEBUG - VN ctest-ext_vn-01788977 UUID is f260e68e-1d56-4b57-96f1-7ee04e05dbf4 2026-03-05 09:03:26,919 - DEBUG - Services list from nova: [, , ] 2026-03-05 09:03:27,974 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5439-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5439-1) 2026-03-05 09:03:29,210 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5439-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5439-1) 2026-03-05 09:03:29,278 - INFO - Setting gateway for router ab15c435-08df-46f8-b0c8-127e305a1d70 to network f260e68e-1d56-4b57-96f1-7ee04e05dbf4 2026-03-05 09:03:29,373 - INFO - Adding interface with subnet_id 8c941aab-667a-41e5-ae66-751f207c101a, port_id None to router ab15c435-08df-46f8-b0c8-127e305a1d70 2026-03-05 09:03:29,802 - INFO - Waiting for VM ctest-TestRoutersBasic-27930141-43636344 to be up.. 2026-03-05 09:03:29,983 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-05 09:03:35,061 - DEBUG - VM is in ACTIVE state now 2026-03-05 09:03:35,062 - INFO - VM name : ctest-TestRoutersBasic-27930141-43636344 2026-03-05 09:03:35,143 - DEBUG - VM ctest-TestRoutersBasic-27930141-43636344 ID is 54588802-9c9d-4dcc-8873-6d51c68db2d1 2026-03-05 09:03:35,164 - DEBUG - VM ctest-TestRoutersBasic-27930141-43636344 launched on Node cn-jenkins-deploy-platform-ansible-os-5439-1 2026-03-05 09:03:35,243 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/54588802-9c9d-4dcc-8873-6d51c68db2d1 2026-03-05 09:03:35,541 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/54588802-9c9d-4dcc-8873-6d51c68db2d1 2026-03-05 09:03:35,576 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/4cd139a2-f4b4-42f4-b7a0-145c0d3de5bf 2026-03-05 09:03:38,846 - 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 1022ms') 2026-03-05 09:03:38,846 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-27930141-43636344 failed! 2026-03-05 09:03:38,903 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-27930141:ctest-vn-46036616 is 138.208.68.1 and allocation pool is NOT set 2026-03-05 09:03:40,969 - 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.40 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.584 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.584/1.991/3.398/1.407 ms') 2026-03-05 09:03:40,969 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-27930141-43636344 passed 2026-03-05 09:03:41,123 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-05 09:03:41,123 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-27930141-43636344, IP 138.208.68.3, Port 22 2026-03-05 09:03:41,279 - DEBUG - VM ctest-TestRoutersBasic-27930141-43636344 is ready for SSH connections 2026-03-05 09:03:41,279 - INFO - Waiting for VM ctest-TestRoutersBasic-27930141-21341068 to be up.. 2026-03-05 09:03:41,361 - DEBUG - VM is in ACTIVE state now 2026-03-05 09:03:41,361 - INFO - VM name : ctest-TestRoutersBasic-27930141-21341068 2026-03-05 09:03:41,438 - DEBUG - VM ctest-TestRoutersBasic-27930141-21341068 ID is 0982ef5b-ad84-4a6f-95e1-d06699332e3a 2026-03-05 09:03:41,438 - DEBUG - VM ctest-TestRoutersBasic-27930141-21341068 launched on Node cn-jenkins-deploy-platform-ansible-os-5439-1 2026-03-05 09:03:41,515 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/0982ef5b-ad84-4a6f-95e1-d06699332e3a 2026-03-05 09:03:41,524 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/f48ae4ac-bbc8-493e-a383-24716381c838 2026-03-05 09:03:42,760 - 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.94 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.617 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.617/1.778/2.940/1.161 ms') 2026-03-05 09:03:42,760 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestRoutersBasic-27930141-21341068 passed 2026-03-05 09:03:42,915 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-05 09:03:42,915 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-27930141-21341068, IP 218.230.82.3, Port 22 2026-03-05 09:03:43,070 - DEBUG - VM ctest-TestRoutersBasic-27930141-21341068 is ready for SSH connections 2026-03-05 09:03:43,071 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-05 09:03:43,071 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 218.230.82.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2026-03-05 09:03:43,071 - DEBUG - ping -s 56 -c 3 -W 1 218.230.82.3 2026-03-05 09:03:46,783 - DEBUG - PING 218.230.82.3 (218.230.82.3): 56 data bytes 64 bytes from 218.230.82.3: seq=0 ttl=62 time=7.841 ms 64 bytes from 218.230.82.3: seq=1 ttl=62 time=1.277 ms 64 bytes from 218.230.82.3: seq=2 ttl=62 time=1.160 ms --- 218.230.82.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.160/3.426/7.841 ms 2026-03-05 09:03:46,783 - INFO - Ping to IP 218.230.82.3 from VM ctest-TestRoutersBasic-27930141-43636344 passed 2026-03-05 09:03:46,783 - INFO - Deleting interface with subnet_id 8c941aab-667a-41e5-ae66-751f207c101a, port_id None from router ab15c435-08df-46f8-b0c8-127e305a1d70 2026-03-05 09:03:46,981 - INFO - Deleting VM ctest-TestRoutersBasic-27930141-21341068 2026-03-05 09:03:47,070 - INFO - Deleting VM ctest-TestRoutersBasic-27930141-43636344 2026-03-05 09:03:47,173 - INFO - Deleting VN ctest-ext_vn-01788977 2026-03-05 09:03:47,248 - DEBUG - VN f260e68e-1d56-4b57-96f1-7ee04e05dbf4 still in use: Unable to complete operation on network f260e68e-1d56-4b57-96f1-7ee04e05dbf4. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-0b1a1d47-6510-43fb-8e6f-a7acc7caa00a'] 2026-03-05 09:03:47,248 - WARNING - Deleting VN ctest-ext_vn-01788977 failed..Will retry 2026-03-05 09:03:49,347 - DEBUG - Response for deleting network () 2026-03-05 09:03:49,347 - INFO - Deleting VN ctest-vn-46036616 2026-03-05 09:03:49,462 - DEBUG - Response for deleting network () 2026-03-05 09:03:49,718 - DEBUG - No XMPP flaps were noticed during the test
2026-03-05 09:03:49,718 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:25]
2026-03-05 09:03:49,718 - INFO - -------------------------------------------------------------------------------- 2026-03-05 09:03:50,462 - INFO - Deleted project: ctest-TestRoutersBasic-27930141, ID : 844a40c2-d963-49de-beca-f182a768ed27