2025-08-11 21:33:37,824 - INFO - Domain Default found not creating 2025-08-11 21:33:37,944 - INFO - Project ctest-TestRoutersBasic-94375343 not found, creating it 2025-08-11 21:33:38,395 - INFO - Created Project:ctest-TestRoutersBasic-94375343, ID : 831677fd-0ff3-42f5-b7ed-e3694da9359f 2025-08-11 21:33:39,698 - INFO - ================================================================================ 2025-08-11 21:33:39,698 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2025-08-11 21:33:39,699 - 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-08-11 21:33:39,947 - DEBUG - Nothing to compare xmpp stats {'10.0.0.254': {'10.20.0.17': '0'}} with 2025-08-11 21:33:39,947 - INFO - Initial checks done. Running the testcase now 2025-08-11 21:33:39,947 - INFO - 2025-08-11 21:33:40,621 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-94375343 2025-08-11 21:33:40,714 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-94375343 2025-08-11 21:33:41,029 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-94375343', 'default']} 2025-08-11 21:33:41,303 - DEBUG - Response for create_network : {'network': {'id': '34374902-bd46-45ee-b024-f557482a417d', 'name': 'ctest-vn-63350619', 'tenant_id': '831677fd0ff342f5b7ede3694da9359f', 'project_id': '831677fd0ff342f5b7ede3694da9359f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-94375343', 'ctest-vn-63350619'], 'port_security_enabled': True, 'description': ''}} 2025-08-11 21:33:41,459 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f4298ec1-a967-4df9-aa02-5ce41edabbd8', 'name': '', 'tenant_id': '831677fd0ff342f5b7ede3694da9359f', 'network_id': '34374902-bd46-45ee-b024-f557482a417d', 'ip_version': 4, 'cidr': '95.253.36.0/26', 'allocation_pools': [{'start': '95.253.36.2', 'end': '95.253.36.62'}], 'gateway_ip': '95.253.36.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '95.253.36.2', 'tags': [], 'project_id': '831677fd0ff342f5b7ede3694da9359f'}} 2025-08-11 21:33:41,477 - INFO - Created VN ctest-vn-63350619 2025-08-11 21:33:41,530 - DEBUG - VN ctest-vn-63350619 UUID is 34374902-bd46-45ee-b024-f557482a417d 2025-08-11 21:33:41,871 - DEBUG - Response for create_network : {'network': {'id': 'a565bf8d-31cf-4cf1-b221-a7251928b14b', 'name': 'ctest-ext_vn-05343648', 'tenant_id': '831677fd0ff342f5b7ede3694da9359f', 'project_id': '831677fd0ff342f5b7ede3694da9359f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-94375343', 'ctest-ext_vn-05343648'], 'port_security_enabled': True, 'description': ''}} 2025-08-11 21:33:42,072 - DEBUG - Response for create_subnet : {'subnet': {'id': '393c3f81-32de-4e9e-809d-cd00dc041fff', 'name': '', 'tenant_id': '831677fd0ff342f5b7ede3694da9359f', 'network_id': 'a565bf8d-31cf-4cf1-b221-a7251928b14b', 'ip_version': 4, 'cidr': '139.62.218.192/26', 'allocation_pools': [{'start': '139.62.218.194', 'end': '139.62.218.254'}], 'gateway_ip': '139.62.218.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '139.62.218.194', 'tags': [], 'project_id': '831677fd0ff342f5b7ede3694da9359f'}} 2025-08-11 21:33:42,087 - INFO - Created VN ctest-ext_vn-05343648 2025-08-11 21:33:42,137 - DEBUG - VN ctest-ext_vn-05343648 UUID is a565bf8d-31cf-4cf1-b221-a7251928b14b 2025-08-11 21:33:42,321 - DEBUG - Services list from nova: [, , ] 2025-08-11 21:33:43,246 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3824-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3824-1) 2025-08-11 21:33:44,283 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3824-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3824-1) 2025-08-11 21:33:44,351 - INFO - Setting gateway for router 642f465f-458f-4445-ae49-85c0968d11d1 to network a565bf8d-31cf-4cf1-b221-a7251928b14b 2025-08-11 21:33:44,420 - INFO - Adding interface with subnet_id f4298ec1-a967-4df9-aa02-5ce41edabbd8, port_id None to router 642f465f-458f-4445-ae49-85c0968d11d1 2025-08-11 21:33:44,784 - INFO - Waiting for VM ctest-TestRoutersBasic-94375343-00952029 to be up.. 2025-08-11 21:33:44,923 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-11 21:33:49,985 - DEBUG - VM is in ACTIVE state now 2025-08-11 21:33:49,985 - INFO - VM name : ctest-TestRoutersBasic-94375343-00952029 2025-08-11 21:33:50,045 - DEBUG - VM ctest-TestRoutersBasic-94375343-00952029 ID is 82da5bea-9340-4b9d-9b2b-5ba9a1952da4 2025-08-11 21:33:50,061 - DEBUG - VM ctest-TestRoutersBasic-94375343-00952029 launched on Node cn-jenkins-deploy-platform-ansible-os-3824-1 2025-08-11 21:33:50,124 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/82da5bea-9340-4b9d-9b2b-5ba9a1952da4 2025-08-11 21:33:50,415 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/82da5bea-9340-4b9d-9b2b-5ba9a1952da4 2025-08-11 21:33:50,443 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/343b947d-e406-4a9f-931e-37db9d3ee79d 2025-08-11 21:33:53,701 - 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 1019ms') 2025-08-11 21:33:53,701 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-94375343-00952029 failed! 2025-08-11 21:33:53,758 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-94375343:ctest-vn-63350619 is 95.253.36.1 and allocation pool is NOT set 2025-08-11 21:33:55,814 - 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=2.66 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.335 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.335/1.497/2.659/1.162 ms') 2025-08-11 21:33:55,815 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-94375343-00952029 passed 2025-08-11 21:33:55,962 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-11 21:33:55,962 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-94375343-00952029, IP 95.253.36.3, Port 22 2025-08-11 21:33:56,116 - DEBUG - VM ctest-TestRoutersBasic-94375343-00952029 is ready for SSH connections 2025-08-11 21:33:56,117 - INFO - Waiting for VM ctest-TestRoutersBasic-94375343-02918258 to be up.. 2025-08-11 21:33:56,179 - DEBUG - VM is in ACTIVE state now 2025-08-11 21:33:56,179 - INFO - VM name : ctest-TestRoutersBasic-94375343-02918258 2025-08-11 21:33:56,236 - DEBUG - VM ctest-TestRoutersBasic-94375343-02918258 ID is 75e7ca60-5eea-4400-94ea-5ff6f5dcbcfb 2025-08-11 21:33:56,236 - DEBUG - VM ctest-TestRoutersBasic-94375343-02918258 launched on Node cn-jenkins-deploy-platform-ansible-os-3824-1 2025-08-11 21:33:56,298 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/75e7ca60-5eea-4400-94ea-5ff6f5dcbcfb 2025-08-11 21:33:56,305 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/5e0bee29-30c6-4240-b57e-6e4268fe1d09 2025-08-11 21:33:57,540 - 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.02 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.318 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.318/1.671/3.024/1.353 ms') 2025-08-11 21:33:57,540 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestRoutersBasic-94375343-02918258 passed 2025-08-11 21:33:57,690 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-11 21:33:57,690 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-94375343-02918258, IP 139.62.218.195, Port 22 2025-08-11 21:33:57,840 - DEBUG - VM ctest-TestRoutersBasic-94375343-02918258 is ready for SSH connections 2025-08-11 21:33:57,841 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-11 21:33:57,841 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 139.62.218.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-08-11 21:33:57,841 - DEBUG - ping -s 56 -c 3 -W 1 139.62.218.195 2025-08-11 21:34:01,774 - DEBUG - PING 139.62.218.195 (139.62.218.195): 56 data bytes 64 bytes from 139.62.218.195: seq=0 ttl=62 time=7.263 ms 64 bytes from 139.62.218.195: seq=1 ttl=62 time=0.744 ms 64 bytes from 139.62.218.195: seq=2 ttl=62 time=0.845 ms --- 139.62.218.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.744/2.950/7.263 ms 2025-08-11 21:34:01,774 - INFO - Ping to IP 139.62.218.195 from VM ctest-TestRoutersBasic-94375343-00952029 passed 2025-08-11 21:34:01,774 - INFO - Deleting interface with subnet_id f4298ec1-a967-4df9-aa02-5ce41edabbd8, port_id None from router 642f465f-458f-4445-ae49-85c0968d11d1 2025-08-11 21:34:01,965 - INFO - Deleting VM ctest-TestRoutersBasic-94375343-02918258 2025-08-11 21:34:02,021 - INFO - Deleting VM ctest-TestRoutersBasic-94375343-00952029 2025-08-11 21:34:02,110 - INFO - Deleting VN ctest-ext_vn-05343648 2025-08-11 21:34:02,167 - DEBUG - VN a565bf8d-31cf-4cf1-b221-a7251928b14b still in use: Unable to complete operation on network a565bf8d-31cf-4cf1-b221-a7251928b14b. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-f4edeac3-c8f8-4d24-908e-5a901722efa8'] 2025-08-11 21:34:02,168 - WARNING - Deleting VN ctest-ext_vn-05343648 failed..Will retry 2025-08-11 21:34:04,253 - DEBUG - Response for deleting network () 2025-08-11 21:34:04,253 - INFO - Deleting VN ctest-vn-63350619 2025-08-11 21:34:04,345 - DEBUG - Response for deleting network () 2025-08-11 21:34:04,611 - DEBUG - No XMPP flaps were noticed during the test
2025-08-11 21:34:04,611 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:25]
2025-08-11 21:34:04,611 - INFO - -------------------------------------------------------------------------------- 2025-08-11 21:34:05,243 - INFO - Deleted project: ctest-TestRoutersBasic-94375343, ID : 831677fd-0ff3-42f5-b7ed-e3694da9359f