2026-01-21 19:26:02,256 - INFO - Domain Default found not creating 2026-01-21 19:26:02,438 - INFO - Project ctest-TestRoutersBasic-27716195 not found, creating it 2026-01-21 19:26:03,049 - INFO - Created Project:ctest-TestRoutersBasic-27716195, ID : 60d389b4-a09b-425c-ad7b-cba94a167cb6 2026-01-21 19:26:04,714 - INFO - ================================================================================ 2026-01-21 19:26:04,714 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2026-01-21 19:26:04,714 - 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-01-21 19:26:06,031 - WARNING - Test is running with cores: {'10.0.0.22': ['core.contrail-collec.1.cn-jenkins-deploy-platform-ansible-os-5053-2.1769021933']} 2026-01-21 19:26:06,043 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.18': '0', '10.20.0.14': '0'}, '10.0.0.27': {'10.20.0.18': '0', '10.20.0.254': '0'}} with 2026-01-21 19:26:06,043 - INFO - Initial checks done. Running the testcase now 2026-01-21 19:26:06,043 - INFO - 2026-01-21 19:26:06,803 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-27716195 2026-01-21 19:26:06,867 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-27716195 2026-01-21 19:26:07,189 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-27716195', 'default']} 2026-01-21 19:26:07,678 - DEBUG - Response for create_network : {'network': {'id': '34390b52-50e1-49e3-8112-f2eba4e2293e', 'name': 'ctest-vn-22000862', 'tenant_id': '60d389b4a09b425cad7bcba94a167cb6', 'project_id': '60d389b4a09b425cad7bcba94a167cb6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-27716195', 'ctest-vn-22000862'], 'port_security_enabled': True, 'description': ''}} 2026-01-21 19:26:08,014 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c3093847-19f2-400b-8493-f4ac5c64f200', 'name': '', 'tenant_id': '60d389b4a09b425cad7bcba94a167cb6', 'network_id': '34390b52-50e1-49e3-8112-f2eba4e2293e', 'ip_version': 4, 'cidr': '123.61.44.128/26', 'allocation_pools': [{'start': '123.61.44.130', 'end': '123.61.44.190'}], 'gateway_ip': '123.61.44.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '123.61.44.130', 'tags': [], 'project_id': '60d389b4a09b425cad7bcba94a167cb6'}} 2026-01-21 19:26:08,041 - INFO - Created VN ctest-vn-22000862 2026-01-21 19:26:08,057 - DEBUG - VN ctest-vn-22000862 UUID is 34390b52-50e1-49e3-8112-f2eba4e2293e 2026-01-21 19:26:08,512 - DEBUG - Response for create_network : {'network': {'id': 'e4ca1412-c128-4158-836a-e16c8840fb62', 'name': 'ctest-ext_vn-83256654', 'tenant_id': '60d389b4a09b425cad7bcba94a167cb6', 'project_id': '60d389b4a09b425cad7bcba94a167cb6', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-27716195', 'ctest-ext_vn-83256654'], 'port_security_enabled': True, 'description': ''}} 2026-01-21 19:26:08,809 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ad6e9ae7-4d6f-43e9-9cdf-ba8e89d0efc2', 'name': '', 'tenant_id': '60d389b4a09b425cad7bcba94a167cb6', 'network_id': 'e4ca1412-c128-4158-836a-e16c8840fb62', 'ip_version': 4, 'cidr': '164.64.71.128/26', 'allocation_pools': [{'start': '164.64.71.130', 'end': '164.64.71.190'}], 'gateway_ip': '164.64.71.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '164.64.71.130', 'tags': [], 'project_id': '60d389b4a09b425cad7bcba94a167cb6'}} 2026-01-21 19:26:08,830 - INFO - Created VN ctest-ext_vn-83256654 2026-01-21 19:26:08,845 - DEBUG - VN ctest-ext_vn-83256654 UUID is e4ca1412-c128-4158-836a-e16c8840fb62 2026-01-21 19:26:09,013 - DEBUG - Services list from nova: [, , , ] 2026-01-21 19:26:10,100 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5053-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5053-1) 2026-01-21 19:26:11,597 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5053-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5053-2) 2026-01-21 19:26:11,718 - INFO - Setting gateway for router 65e4cf2e-8031-4845-9c5f-6c7e108fdb01 to network e4ca1412-c128-4158-836a-e16c8840fb62 2026-01-21 19:26:11,843 - INFO - Adding interface with subnet_id c3093847-19f2-400b-8493-f4ac5c64f200, port_id None to router 65e4cf2e-8031-4845-9c5f-6c7e108fdb01 2026-01-21 19:26:12,689 - INFO - Waiting for VM ctest-TestRoutersBasic-27716195-04385919 to be up.. 2026-01-21 19:26:12,864 - DEBUG - VM is in ACTIVE state now 2026-01-21 19:26:12,865 - INFO - VM name : ctest-TestRoutersBasic-27716195-04385919 2026-01-21 19:26:13,032 - DEBUG - VM ctest-TestRoutersBasic-27716195-04385919 ID is 48bb945a-24be-4438-a853-2225123cc4bb 2026-01-21 19:26:13,093 - DEBUG - VM ctest-TestRoutersBasic-27716195-04385919 launched on Node an-jenkins-deploy-platform-ansible-os-5053-1 2026-01-21 19:26:13,302 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/48bb945a-24be-4438-a853-2225123cc4bb 2026-01-21 19:26:13,833 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/48bb945a-24be-4438-a853-2225123cc4bb 2026-01-21 19:26:13,926 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/dfb5026c-2150-4c8d-b4f9-21e1e720448e 2026-01-21 19:26:17,190 - 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 1024ms') 2026-01-21 19:26:17,190 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-27716195-04385919 failed! 2026-01-21 19:26:17,208 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-27716195:ctest-vn-22000862 is 123.61.44.129 and allocation pool is NOT set 2026-01-21 19:26:21,292 - 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 1024ms') 2026-01-21 19:26:21,292 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-27716195-04385919 failed! 2026-01-21 19:26:21,306 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-27716195:ctest-vn-22000862 is 123.61.44.129 and allocation pool is NOT set 2026-01-21 19:26:23,367 - 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.405 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.405/1.757/3.110/1.352 ms') 2026-01-21 19:26:23,367 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-27716195-04385919 passed 2026-01-21 19:26:23,479 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-21 19:26:23,479 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-27716195-04385919, IP 123.61.44.131, Port 22 2026-01-21 19:26:23,650 - DEBUG - VM ctest-TestRoutersBasic-27716195-04385919 is ready for SSH connections 2026-01-21 19:26:23,650 - INFO - Waiting for VM ctest-TestRoutersBasic-27716195-34526852 to be up.. 2026-01-21 19:26:23,761 - DEBUG - VM is in ACTIVE state now 2026-01-21 19:26:23,761 - INFO - VM name : ctest-TestRoutersBasic-27716195-34526852 2026-01-21 19:26:23,850 - DEBUG - VM ctest-TestRoutersBasic-27716195-34526852 ID is 990838d4-2cea-4b26-99da-a9202f5e6cdc 2026-01-21 19:26:23,850 - DEBUG - VM ctest-TestRoutersBasic-27716195-34526852 launched on Node an-jenkins-deploy-platform-ansible-os-5053-2 2026-01-21 19:26:23,957 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/990838d4-2cea-4b26-99da-a9202f5e6cdc 2026-01-21 19:26:23,968 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/dbd0ead0-e334-427b-b5df-236fb03fc2aa 2026-01-21 19:26:25,171 - 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.98 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.348 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.348/1.661/2.975/1.313 ms') 2026-01-21 19:26:25,171 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-27716195-34526852 passed 2026-01-21 19:26:25,287 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-21 19:26:25,287 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-27716195-34526852, IP 164.64.71.131, Port 22 2026-01-21 19:26:25,439 - DEBUG - VM ctest-TestRoutersBasic-27716195-34526852 is ready for SSH connections 2026-01-21 19:26:25,439 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-21 19:26:25,439 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 164.64.71.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2026-01-21 19:26:25,439 - DEBUG - ping -s 56 -c 3 -W 1 164.64.71.131 2026-01-21 19:26:29,146 - DEBUG - PING 164.64.71.131 (164.64.71.131): 56 data bytes 64 bytes from 164.64.71.131: seq=0 ttl=62 time=8.982 ms 64 bytes from 164.64.71.131: seq=1 ttl=62 time=0.995 ms 64 bytes from 164.64.71.131: seq=2 ttl=62 time=1.588 ms --- 164.64.71.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.995/3.855/8.982 ms 2026-01-21 19:26:29,146 - INFO - Ping to IP 164.64.71.131 from VM ctest-TestRoutersBasic-27716195-04385919 passed 2026-01-21 19:26:29,146 - INFO - Deleting interface with subnet_id c3093847-19f2-400b-8493-f4ac5c64f200, port_id None from router 65e4cf2e-8031-4845-9c5f-6c7e108fdb01 2026-01-21 19:26:29,605 - INFO - Deleting VM ctest-TestRoutersBasic-27716195-34526852 2026-01-21 19:26:29,800 - INFO - Deleting VM ctest-TestRoutersBasic-27716195-04385919 2026-01-21 19:26:29,928 - INFO - Deleting VN ctest-ext_vn-83256654 2026-01-21 19:26:30,137 - DEBUG - VN e4ca1412-c128-4158-836a-e16c8840fb62 still in use: Unable to complete operation on network e4ca1412-c128-4158-836a-e16c8840fb62. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-e7fbb389-ae4b-4e0c-b50e-13452e46babc'] 2026-01-21 19:26:30,137 - WARNING - Deleting VN ctest-ext_vn-83256654 failed..Will retry 2026-01-21 19:26:32,356 - DEBUG - Response for deleting network () 2026-01-21 19:26:32,356 - INFO - Deleting VN ctest-vn-22000862 2026-01-21 19:26:32,534 - DEBUG - Response for deleting network () 2026-01-21 19:26:33,872 - DEBUG - No XMPP flaps were noticed during the test
2026-01-21 19:26:33,872 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:29]
2026-01-21 19:26:33,872 - INFO - -------------------------------------------------------------------------------- 2026-01-21 19:26:34,679 - INFO - Deleted project: ctest-TestRoutersBasic-27716195, ID : 60d389b4-a09b-425c-ad7b-cba94a167cb6