2025-06-02 02:26:11,281 - INFO - Domain Default found not creating 2025-06-02 02:26:11,436 - INFO - Project ctest-TestRoutersBasic-32066686 not found, creating it 2025-06-02 02:26:11,902 - INFO - Created Project:ctest-TestRoutersBasic-32066686, ID : e642f087-f0d8-42be-86be-00f044a667a5 2025-06-02 02:26:13,420 - INFO - ================================================================================ 2025-06-02 02:26:13,420 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2025-06-02 02:26:13,420 - 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-06-02 02:26:13,680 - DEBUG - Nothing to compare xmpp stats {'10.0.0.242': {'10.20.0.254': '0'}} with 2025-06-02 02:26:13,680 - INFO - Initial checks done. Running the testcase now 2025-06-02 02:26:13,680 - INFO - 2025-06-02 02:26:14,452 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-32066686 2025-06-02 02:26:14,547 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-32066686 2025-06-02 02:26:14,867 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-32066686', 'default']} 2025-06-02 02:26:15,302 - DEBUG - Response for create_network : {'network': {'id': 'ac717ad5-3d3b-4b8c-9254-4bec0093eb34', 'name': 'ctest-vn-83240227', 'tenant_id': 'e642f087f0d842be86be00f044a667a5', 'project_id': 'e642f087f0d842be86be00f044a667a5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-32066686', 'ctest-vn-83240227'], 'port_security_enabled': True, 'description': ''}} 2025-06-02 02:26:15,508 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f3227082-a326-4dcc-bc7e-4063402f136c', 'name': '', 'tenant_id': 'e642f087f0d842be86be00f044a667a5', 'network_id': 'ac717ad5-3d3b-4b8c-9254-4bec0093eb34', 'ip_version': 4, 'cidr': '95.86.62.128/26', 'allocation_pools': [{'start': '95.86.62.130', 'end': '95.86.62.190'}], 'gateway_ip': '95.86.62.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '95.86.62.130', 'tags': [], 'project_id': 'e642f087f0d842be86be00f044a667a5'}} 2025-06-02 02:26:15,528 - INFO - Created VN ctest-vn-83240227 2025-06-02 02:26:15,583 - DEBUG - VN ctest-vn-83240227 UUID is ac717ad5-3d3b-4b8c-9254-4bec0093eb34 2025-06-02 02:26:15,930 - DEBUG - Response for create_network : {'network': {'id': 'e7b67f4d-9357-4377-8bed-bc20ea968ba5', 'name': 'ctest-ext_vn-10895776', 'tenant_id': 'e642f087f0d842be86be00f044a667a5', 'project_id': 'e642f087f0d842be86be00f044a667a5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-32066686', 'ctest-ext_vn-10895776'], 'port_security_enabled': True, 'description': ''}} 2025-06-02 02:26:16,129 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ac678dde-f3ea-403b-8ed5-68332420ec32', 'name': '', 'tenant_id': 'e642f087f0d842be86be00f044a667a5', 'network_id': 'e7b67f4d-9357-4377-8bed-bc20ea968ba5', 'ip_version': 4, 'cidr': '120.17.63.128/26', 'allocation_pools': [{'start': '120.17.63.130', 'end': '120.17.63.190'}], 'gateway_ip': '120.17.63.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '120.17.63.130', 'tags': [], 'project_id': 'e642f087f0d842be86be00f044a667a5'}} 2025-06-02 02:26:16,147 - INFO - Created VN ctest-ext_vn-10895776 2025-06-02 02:26:16,199 - DEBUG - VN ctest-ext_vn-10895776 UUID is e7b67f4d-9357-4377-8bed-bc20ea968ba5 2025-06-02 02:26:16,403 - DEBUG - Services list from nova: [, , ] 2025-06-02 02:26:17,539 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3384-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3384-1) 2025-06-02 02:26:18,775 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3384-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3384-1) 2025-06-02 02:26:18,847 - INFO - Setting gateway for router 6da3da02-9e91-4c37-8f24-049dc1c53e23 to network e7b67f4d-9357-4377-8bed-bc20ea968ba5 2025-06-02 02:26:18,908 - INFO - Adding interface with subnet_id f3227082-a326-4dcc-bc7e-4063402f136c, port_id None to router 6da3da02-9e91-4c37-8f24-049dc1c53e23 2025-06-02 02:26:19,343 - INFO - Waiting for VM ctest-TestRoutersBasic-32066686-52192540 to be up.. 2025-06-02 02:26:19,519 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-06-02 02:26:24,614 - DEBUG - VM is in ACTIVE state now 2025-06-02 02:26:24,614 - INFO - VM name : ctest-TestRoutersBasic-32066686-52192540 2025-06-02 02:26:24,690 - DEBUG - VM ctest-TestRoutersBasic-32066686-52192540 ID is 915cb298-ed74-4d0b-8409-0717c16067d5 2025-06-02 02:26:24,714 - DEBUG - VM ctest-TestRoutersBasic-32066686-52192540 launched on Node cn-jenkins-deploy-platform-ansible-os-3384-1 2025-06-02 02:26:24,796 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/915cb298-ed74-4d0b-8409-0717c16067d5 2025-06-02 02:26:25,132 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/915cb298-ed74-4d0b-8409-0717c16067d5 2025-06-02 02:26:25,176 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/240fb7d0-58a1-4c9c-9510-a61a65d94de0 2025-06-02 02:26:28,436 - 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 1007ms') 2025-06-02 02:26:28,436 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-32066686-52192540 failed! 2025-06-02 02:26:28,491 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-32066686:ctest-vn-83240227 is 95.86.62.129 and allocation pool is NOT set 2025-06-02 02:26:30,558 - 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.80 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.553 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.553/1.675/2.797/1.122 ms') 2025-06-02 02:26:30,559 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-32066686-52192540 passed 2025-06-02 02:26:30,715 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-02 02:26:30,715 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-32066686-52192540, IP 95.86.62.131, Port 22 2025-06-02 02:26:30,874 - DEBUG - VM ctest-TestRoutersBasic-32066686-52192540 is ready for SSH connections 2025-06-02 02:26:30,874 - INFO - Waiting for VM ctest-TestRoutersBasic-32066686-61549756 to be up.. 2025-06-02 02:26:30,973 - DEBUG - VM is in ACTIVE state now 2025-06-02 02:26:30,973 - INFO - VM name : ctest-TestRoutersBasic-32066686-61549756 2025-06-02 02:26:31,054 - DEBUG - VM ctest-TestRoutersBasic-32066686-61549756 ID is 4b3b1979-49b4-4870-bd12-b4489d42b13f 2025-06-02 02:26:31,054 - DEBUG - VM ctest-TestRoutersBasic-32066686-61549756 launched on Node cn-jenkins-deploy-platform-ansible-os-3384-1 2025-06-02 02:26:31,127 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/4b3b1979-49b4-4870-bd12-b4489d42b13f 2025-06-02 02:26:31,136 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/9331666b-9870-46a5-bf30-ec89e68b6496 2025-06-02 02:26:32,378 - 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=4.83 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.22 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 = 2.218/3.522/4.827/1.304 ms') 2025-06-02 02:26:32,378 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestRoutersBasic-32066686-61549756 passed 2025-06-02 02:26:32,559 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-02 02:26:32,559 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-32066686-61549756, IP 120.17.63.131, Port 22 2025-06-02 02:26:32,714 - DEBUG - VM ctest-TestRoutersBasic-32066686-61549756 is ready for SSH connections 2025-06-02 02:26:32,714 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-06-02 02:26:32,714 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 120.17.63.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.242, gateway password: c0ntrail123 2025-06-02 02:26:32,714 - DEBUG - ping -s 56 -c 3 -W 1 120.17.63.131 2025-06-02 02:26:36,439 - DEBUG - PING 120.17.63.131 (120.17.63.131): 56 data bytes 64 bytes from 120.17.63.131: seq=0 ttl=62 time=5.082 ms 64 bytes from 120.17.63.131: seq=1 ttl=62 time=0.761 ms 64 bytes from 120.17.63.131: seq=2 ttl=62 time=1.059 ms --- 120.17.63.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.761/2.300/5.082 ms 2025-06-02 02:26:36,439 - INFO - Ping to IP 120.17.63.131 from VM ctest-TestRoutersBasic-32066686-52192540 passed 2025-06-02 02:26:36,439 - INFO - Deleting interface with subnet_id f3227082-a326-4dcc-bc7e-4063402f136c, port_id None from router 6da3da02-9e91-4c37-8f24-049dc1c53e23 2025-06-02 02:26:36,677 - INFO - Deleting VM ctest-TestRoutersBasic-32066686-61549756 2025-06-02 02:26:36,759 - INFO - Deleting VM ctest-TestRoutersBasic-32066686-52192540 2025-06-02 02:26:36,844 - INFO - Deleting VN ctest-ext_vn-10895776 2025-06-02 02:26:36,915 - DEBUG - VN e7b67f4d-9357-4377-8bed-bc20ea968ba5 still in use: Unable to complete operation on network e7b67f4d-9357-4377-8bed-bc20ea968ba5. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-7f175643-b0a8-4c66-a3f9-3af0b874f752'] 2025-06-02 02:26:36,916 - WARNING - Deleting VN ctest-ext_vn-10895776 failed..Will retry 2025-06-02 02:26:39,010 - DEBUG - Response for deleting network () 2025-06-02 02:26:39,011 - INFO - Deleting VN ctest-vn-83240227 2025-06-02 02:26:39,110 - DEBUG - Response for deleting network () 2025-06-02 02:26:39,364 - DEBUG - No XMPP flaps were noticed during the test
2025-06-02 02:26:39,364 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:26]
2025-06-02 02:26:39,364 - INFO - -------------------------------------------------------------------------------- 2025-06-02 02:26:40,346 - INFO - Deleted project: ctest-TestRoutersBasic-32066686, ID : e642f087-f0d8-42be-86be-00f044a667a5