2025-12-10 17:05:48,393 - INFO - Domain Default found not creating 2025-12-10 17:05:48,549 - INFO - Project ctest-TestRoutersBasic-73301891 not found, creating it 2025-12-10 17:05:49,000 - INFO - Created Project:ctest-TestRoutersBasic-73301891, ID : 8378767d-aa4d-44bf-866a-9f7cda5c0216 2025-12-10 17:05:50,469 - INFO - ================================================================================ 2025-12-10 17:05:50,469 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2025-12-10 17:05:50,469 - 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 2025-12-10 17:05:50,721 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.25': '0'}} with 2025-12-10 17:05:50,721 - INFO - Initial checks done. Running the testcase now 2025-12-10 17:05:50,721 - INFO - 2025-12-10 17:05:51,433 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-73301891 2025-12-10 17:05:51,528 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-73301891 2025-12-10 17:05:51,844 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-73301891', 'default']} 2025-12-10 17:05:52,139 - DEBUG - Response for create_network : {'network': {'id': '4d07a9dc-6017-4d06-b8d7-a78d406a50a9', 'name': 'ctest-vn-44078967', 'tenant_id': '8378767daa4d44bf866a9f7cda5c0216', 'project_id': '8378767daa4d44bf866a9f7cda5c0216', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-73301891', 'ctest-vn-44078967'], 'port_security_enabled': True, 'description': ''}} 2025-12-10 17:05:52,309 - DEBUG - Response for create_subnet : {'subnet': {'id': '0e28b149-1ca6-44e9-a567-d27741b0a86d', 'name': '', 'tenant_id': '8378767daa4d44bf866a9f7cda5c0216', 'network_id': '4d07a9dc-6017-4d06-b8d7-a78d406a50a9', 'ip_version': 4, 'cidr': '116.177.29.64/26', 'allocation_pools': [{'start': '116.177.29.66', 'end': '116.177.29.126'}], 'gateway_ip': '116.177.29.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '116.177.29.66', 'tags': [], 'project_id': '8378767daa4d44bf866a9f7cda5c0216'}} 2025-12-10 17:05:52,326 - INFO - Created VN ctest-vn-44078967 2025-12-10 17:05:52,384 - DEBUG - VN ctest-vn-44078967 UUID is 4d07a9dc-6017-4d06-b8d7-a78d406a50a9 2025-12-10 17:05:52,715 - DEBUG - Response for create_network : {'network': {'id': '3f0e4358-8012-46a8-a1f9-6ac70a4b952e', 'name': 'ctest-ext_vn-54586348', 'tenant_id': '8378767daa4d44bf866a9f7cda5c0216', 'project_id': '8378767daa4d44bf866a9f7cda5c0216', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-73301891', 'ctest-ext_vn-54586348'], 'port_security_enabled': True, 'description': ''}} 2025-12-10 17:05:52,932 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ea157296-0594-4794-befe-0496879fb929', 'name': '', 'tenant_id': '8378767daa4d44bf866a9f7cda5c0216', 'network_id': '3f0e4358-8012-46a8-a1f9-6ac70a4b952e', 'ip_version': 4, 'cidr': '47.76.65.64/26', 'allocation_pools': [{'start': '47.76.65.66', 'end': '47.76.65.126'}], 'gateway_ip': '47.76.65.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '47.76.65.66', 'tags': [], 'project_id': '8378767daa4d44bf866a9f7cda5c0216'}} 2025-12-10 17:05:52,949 - INFO - Created VN ctest-ext_vn-54586348 2025-12-10 17:05:53,000 - DEBUG - VN ctest-ext_vn-54586348 UUID is 3f0e4358-8012-46a8-a1f9-6ac70a4b952e 2025-12-10 17:05:53,210 - DEBUG - Services list from nova: [, , ] 2025-12-10 17:05:54,218 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4877-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4877-1) 2025-12-10 17:05:55,394 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4877-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4877-1) 2025-12-10 17:05:55,501 - INFO - Setting gateway for router dbbe5576-8a47-4e83-bff5-407ca9719a79 to network 3f0e4358-8012-46a8-a1f9-6ac70a4b952e 2025-12-10 17:05:55,579 - INFO - Adding interface with subnet_id 0e28b149-1ca6-44e9-a567-d27741b0a86d, port_id None to router dbbe5576-8a47-4e83-bff5-407ca9719a79 2025-12-10 17:05:55,928 - INFO - Waiting for VM ctest-TestRoutersBasic-73301891-16056284 to be up.. 2025-12-10 17:05:56,038 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-12-10 17:06:01,113 - DEBUG - VM is in ACTIVE state now 2025-12-10 17:06:01,113 - INFO - VM name : ctest-TestRoutersBasic-73301891-16056284 2025-12-10 17:06:01,185 - DEBUG - VM ctest-TestRoutersBasic-73301891-16056284 ID is 0191a535-d33a-4666-a311-739671179d81 2025-12-10 17:06:01,202 - DEBUG - VM ctest-TestRoutersBasic-73301891-16056284 launched on Node cn-jenkins-deploy-platform-ansible-os-4877-1 2025-12-10 17:06:01,275 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/0191a535-d33a-4666-a311-739671179d81 2025-12-10 17:06:01,568 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/0191a535-d33a-4666-a311-739671179d81 2025-12-10 17:06:01,603 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/46741b09-c96d-4ae8-8765-85cdff1ca768 2025-12-10 17:06:04,855 - 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 1014ms') 2025-12-10 17:06:04,855 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-73301891-16056284 failed! 2025-12-10 17:06:04,908 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-73301891:ctest-vn-44078967 is 116.177.29.65 and allocation pool is NOT set 2025-12-10 17:06:06,968 - 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.11 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.476 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.476/1.795/3.114/1.319 ms') 2025-12-10 17:06:06,968 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-73301891-16056284 passed 2025-12-10 17:06:07,120 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-10 17:06:07,120 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-73301891-16056284, IP 116.177.29.67, Port 22 2025-12-10 17:06:07,275 - DEBUG - VM ctest-TestRoutersBasic-73301891-16056284 is ready for SSH connections 2025-12-10 17:06:07,275 - INFO - Waiting for VM ctest-TestRoutersBasic-73301891-39979783 to be up.. 2025-12-10 17:06:07,353 - DEBUG - VM is in ACTIVE state now 2025-12-10 17:06:07,354 - INFO - VM name : ctest-TestRoutersBasic-73301891-39979783 2025-12-10 17:06:07,428 - DEBUG - VM ctest-TestRoutersBasic-73301891-39979783 ID is b049d175-1c23-4392-ad11-3f77aa94f4cd 2025-12-10 17:06:07,428 - DEBUG - VM ctest-TestRoutersBasic-73301891-39979783 launched on Node cn-jenkins-deploy-platform-ansible-os-4877-1 2025-12-10 17:06:07,501 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/b049d175-1c23-4392-ad11-3f77aa94f4cd 2025-12-10 17:06:07,509 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/bca750ae-b114-4f5b-bc9d-c15412ce3952 2025-12-10 17:06:08,745 - 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=3.25 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.402 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.402/1.825/3.249/1.423 ms') 2025-12-10 17:06:08,745 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestRoutersBasic-73301891-39979783 passed 2025-12-10 17:06:08,896 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-10 17:06:08,896 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-73301891-39979783, IP 47.76.65.67, Port 22 2025-12-10 17:06:09,052 - DEBUG - VM ctest-TestRoutersBasic-73301891-39979783 is ready for SSH connections 2025-12-10 17:06:09,052 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-10 17:06:09,052 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 47.76.65.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.41, gateway password: c0ntrail123 2025-12-10 17:06:09,052 - DEBUG - ping -s 56 -c 3 -W 1 47.76.65.67 2025-12-10 17:06:12,784 - DEBUG - PING 47.76.65.67 (47.76.65.67): 56 data bytes 64 bytes from 47.76.65.67: seq=0 ttl=62 time=4.496 ms 64 bytes from 47.76.65.67: seq=1 ttl=62 time=0.982 ms 64 bytes from 47.76.65.67: seq=2 ttl=62 time=0.946 ms --- 47.76.65.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.946/2.141/4.496 ms 2025-12-10 17:06:12,784 - INFO - Ping to IP 47.76.65.67 from VM ctest-TestRoutersBasic-73301891-16056284 passed 2025-12-10 17:06:12,784 - INFO - Deleting interface with subnet_id 0e28b149-1ca6-44e9-a567-d27741b0a86d, port_id None from router dbbe5576-8a47-4e83-bff5-407ca9719a79 2025-12-10 17:06:12,972 - INFO - Deleting VM ctest-TestRoutersBasic-73301891-39979783 2025-12-10 17:06:13,052 - INFO - Deleting VM ctest-TestRoutersBasic-73301891-16056284 2025-12-10 17:06:13,143 - INFO - Deleting VN ctest-ext_vn-54586348 2025-12-10 17:06:13,209 - DEBUG - VN 3f0e4358-8012-46a8-a1f9-6ac70a4b952e still in use: Unable to complete operation on network 3f0e4358-8012-46a8-a1f9-6ac70a4b952e. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-4de4eef0-6321-4f63-95ce-87c29c05b3b7'] 2025-12-10 17:06:13,210 - WARNING - Deleting VN ctest-ext_vn-54586348 failed..Will retry 2025-12-10 17:06:15,308 - DEBUG - Response for deleting network () 2025-12-10 17:06:15,308 - INFO - Deleting VN ctest-vn-44078967 2025-12-10 17:06:15,399 - DEBUG - Response for deleting network () 2025-12-10 17:06:15,660 - DEBUG - No XMPP flaps were noticed during the test
2025-12-10 17:06:15,660 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:25]
2025-12-10 17:06:15,660 - INFO - -------------------------------------------------------------------------------- 2025-12-10 17:06:16,332 - INFO - Deleted project: ctest-TestRoutersBasic-73301891, ID : 8378767d-aa4d-44bf-866a-9f7cda5c0216