2025-01-30 02:29:19,536 - INFO - Domain Default found not creating 2025-01-30 02:29:19,666 - INFO - Project ctest-TestRoutersBasic-48652022 not found, creating it 2025-01-30 02:29:20,104 - INFO - Created Project:ctest-TestRoutersBasic-48652022, ID : 83c6b954-ea5c-46d0-9ac7-d8e61be01473 2025-01-30 02:29:21,501 - INFO - ================================================================================ 2025-01-30 02:29:21,501 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2025-01-30 02:29:21,501 - 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-01-30 02:29:21,763 - DEBUG - Nothing to compare xmpp stats {'10.0.0.28': {'10.0.0.28': '0'}} with 2025-01-30 02:29:21,763 - INFO - Initial checks done. Running the testcase now 2025-01-30 02:29:21,763 - INFO - 2025-01-30 02:29:22,457 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-48652022 2025-01-30 02:29:22,557 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-48652022 2025-01-30 02:29:22,881 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-48652022', 'default']} 2025-01-30 02:29:23,186 - DEBUG - Response for create_network : {'network': {'id': '77adfe57-946e-4dec-a8e4-ebf456384b1b', 'name': 'ctest-vn-53844075', 'tenant_id': '83c6b954ea5c46d09ac7d8e61be01473', 'project_id': '83c6b954ea5c46d09ac7d8e61be01473', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-48652022', 'ctest-vn-53844075'], 'port_security_enabled': True, 'description': ''}} 2025-01-30 02:29:23,373 - DEBUG - Response for create_subnet : {'subnet': {'id': '85db6784-ef4e-40b4-b1c0-7c365180c9d5', 'name': '', 'tenant_id': '83c6b954ea5c46d09ac7d8e61be01473', 'network_id': '77adfe57-946e-4dec-a8e4-ebf456384b1b', 'ip_version': 4, 'cidr': '48.133.233.128/26', 'allocation_pools': [{'start': '48.133.233.130', 'end': '48.133.233.190'}], 'gateway_ip': '48.133.233.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '48.133.233.130', 'tags': [], 'project_id': '83c6b954ea5c46d09ac7d8e61be01473'}} 2025-01-30 02:29:23,391 - INFO - Created VN ctest-vn-53844075 2025-01-30 02:29:23,441 - DEBUG - VN ctest-vn-53844075 UUID is 77adfe57-946e-4dec-a8e4-ebf456384b1b 2025-01-30 02:29:24,036 - DEBUG - Response for create_network : {'network': {'id': 'a1148482-780a-4e17-b5c9-f51ee9812be2', 'name': 'ctest-ext_vn-85473232', 'tenant_id': '83c6b954ea5c46d09ac7d8e61be01473', 'project_id': '83c6b954ea5c46d09ac7d8e61be01473', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-48652022', 'ctest-ext_vn-85473232'], 'port_security_enabled': True, 'description': ''}} 2025-01-30 02:29:24,264 - DEBUG - Response for create_subnet : {'subnet': {'id': '5e5ad350-4ffd-4521-b57b-4d9605c45098', 'name': '', 'tenant_id': '83c6b954ea5c46d09ac7d8e61be01473', 'network_id': 'a1148482-780a-4e17-b5c9-f51ee9812be2', 'ip_version': 4, 'cidr': '79.17.193.0/26', 'allocation_pools': [{'start': '79.17.193.2', 'end': '79.17.193.62'}], 'gateway_ip': '79.17.193.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '79.17.193.2', 'tags': [], 'project_id': '83c6b954ea5c46d09ac7d8e61be01473'}} 2025-01-30 02:29:24,282 - INFO - Created VN ctest-ext_vn-85473232 2025-01-30 02:29:24,333 - DEBUG - VN ctest-ext_vn-85473232 UUID is a1148482-780a-4e17-b5c9-f51ee9812be2 2025-01-30 02:29:24,512 - DEBUG - Services list from nova: [, , ] 2025-01-30 02:29:25,562 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2669-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2669-1) 2025-01-30 02:29:26,775 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-2669-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-2669-1) 2025-01-30 02:29:26,859 - INFO - Setting gateway for router ff6a01c9-6306-4880-b8cc-848d8a29733e to network a1148482-780a-4e17-b5c9-f51ee9812be2 2025-01-30 02:29:26,952 - INFO - Adding interface with subnet_id 85db6784-ef4e-40b4-b1c0-7c365180c9d5, port_id None to router ff6a01c9-6306-4880-b8cc-848d8a29733e 2025-01-30 02:29:27,354 - INFO - Waiting for VM ctest-TestRoutersBasic-48652022-32473966 to be up.. 2025-01-30 02:29:27,555 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-01-30 02:29:32,630 - DEBUG - VM is in ACTIVE state now 2025-01-30 02:29:32,630 - INFO - VM name : ctest-TestRoutersBasic-48652022-32473966 2025-01-30 02:29:32,711 - DEBUG - VM ctest-TestRoutersBasic-48652022-32473966 ID is b27e5aba-e360-4649-8589-09e212aa36b5 2025-01-30 02:29:32,732 - DEBUG - VM ctest-TestRoutersBasic-48652022-32473966 launched on Node cn-jenkins-deploy-platform-ansible-os-2669-1. 2025-01-30 02:29:32,818 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine/b27e5aba-e360-4649-8589-09e212aa36b5 2025-01-30 02:29:33,118 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine/b27e5aba-e360-4649-8589-09e212aa36b5 2025-01-30 02:29:33,148 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/d09780de-af06-4f99-b356-040d4771fc03 2025-01-30 02:29:36,418 - 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 1031ms') 2025-01-30 02:29:36,418 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-48652022-32473966 failed! 2025-01-30 02:29:36,477 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-48652022:ctest-vn-53844075 is 48.133.233.129 and allocation pool is NOT set 2025-01-30 02:29:38,534 - 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.54 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.379 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.379/1.961/3.543/1.582 ms') 2025-01-30 02:29:38,534 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-48652022-32473966 passed 2025-01-30 02:29:38,641 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-01-30 02:29:38,641 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-48652022-32473966, IP 48.133.233.131, Port 22 2025-01-30 02:29:38,809 - DEBUG - VM ctest-TestRoutersBasic-48652022-32473966 is ready for SSH connections 2025-01-30 02:29:38,809 - INFO - Waiting for VM ctest-TestRoutersBasic-48652022-69672499 to be up.. 2025-01-30 02:29:38,889 - DEBUG - VM is in ACTIVE state now 2025-01-30 02:29:38,889 - INFO - VM name : ctest-TestRoutersBasic-48652022-69672499 2025-01-30 02:29:38,963 - DEBUG - VM ctest-TestRoutersBasic-48652022-69672499 ID is 8bde2be6-6e41-4189-9b76-2aa6f956f25c 2025-01-30 02:29:38,963 - DEBUG - VM ctest-TestRoutersBasic-48652022-69672499 launched on Node cn-jenkins-deploy-platform-ansible-os-2669-1. 2025-01-30 02:29:39,036 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine/8bde2be6-6e41-4189-9b76-2aa6f956f25c 2025-01-30 02:29:39,045 - DEBUG - Requesting: http://10.0.0.28:8082/virtual-machine-interface/e8f1ffb0-ec33-4916-ad0d-40ac6a4c7620 2025-01-30 02:29:40,289 - 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.73 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.556 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.556/2.142/3.728/1.586 ms') 2025-01-30 02:29:40,289 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestRoutersBasic-48652022-69672499 passed 2025-01-30 02:29:40,441 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-01-30 02:29:40,441 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-48652022-69672499, IP 79.17.193.3, Port 22 2025-01-30 02:29:40,608 - DEBUG - VM ctest-TestRoutersBasic-48652022-69672499 is ready for SSH connections 2025-01-30 02:29:40,608 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-01-30 02:29:40,608 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 79.17.193.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.28, gateway password: c0ntrail123 2025-01-30 02:29:40,608 - DEBUG - ping -s 56 -c 3 -W 1 79.17.193.3 2025-01-30 02:29:44,614 - DEBUG - PING 79.17.193.3 (79.17.193.3): 56 data bytes 64 bytes from 79.17.193.3: seq=0 ttl=62 time=5.694 ms 64 bytes from 79.17.193.3: seq=1 ttl=62 time=1.065 ms 64 bytes from 79.17.193.3: seq=2 ttl=62 time=0.848 ms --- 79.17.193.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.848/2.535/5.694 ms 2025-01-30 02:29:44,614 - INFO - Ping to IP 79.17.193.3 from VM ctest-TestRoutersBasic-48652022-32473966 passed 2025-01-30 02:29:44,614 - INFO - Deleting interface with subnet_id 85db6784-ef4e-40b4-b1c0-7c365180c9d5, port_id None from router ff6a01c9-6306-4880-b8cc-848d8a29733e 2025-01-30 02:29:44,834 - INFO - Deleting VM ctest-TestRoutersBasic-48652022-69672499 2025-01-30 02:29:44,905 - INFO - Deleting VM ctest-TestRoutersBasic-48652022-32473966 2025-01-30 02:29:44,996 - INFO - Deleting VN ctest-ext_vn-85473232 2025-01-30 02:29:45,129 - DEBUG - VN a1148482-780a-4e17-b5c9-f51ee9812be2 still in use: Unable to complete operation on network a1148482-780a-4e17-b5c9-f51ee9812be2. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-4060b9e0-684c-4136-adf8-d1925ddd6ad3'] 2025-01-30 02:29:45,129 - WARNING - Deleting VN ctest-ext_vn-85473232 failed..Will retry 2025-01-30 02:29:47,259 - DEBUG - Response for deleting network () 2025-01-30 02:29:47,260 - INFO - Deleting VN ctest-vn-53844075 2025-01-30 02:29:47,375 - DEBUG - Response for deleting network () 2025-01-30 02:29:47,630 - DEBUG - No XMPP flaps were noticed during the test
2025-01-30 02:29:47,630 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:26]
2025-01-30 02:29:47,630 - INFO - -------------------------------------------------------------------------------- 2025-01-30 02:29:48,330 - INFO - Deleted project: ctest-TestRoutersBasic-48652022, ID : 83c6b954-ea5c-46d0-9ac7-d8e61be01473