2025-07-31 12:08:42,558 - INFO - Domain Default found not creating 2025-07-31 12:08:42,718 - INFO - Project ctest-TestRoutersBasic-93292812 not found, creating it 2025-07-31 12:08:43,202 - INFO - Created Project:ctest-TestRoutersBasic-93292812, ID : d82951a3-9e4d-4a52-9afa-14fa8ac313a1 2025-07-31 12:08:44,719 - INFO - ================================================================================ 2025-07-31 12:08:44,719 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2025-07-31 12:08:44,719 - 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-07-31 12:08:44,985 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.17': '0'}} with 2025-07-31 12:08:44,985 - INFO - Initial checks done. Running the testcase now 2025-07-31 12:08:44,985 - INFO - 2025-07-31 12:08:45,690 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-93292812 2025-07-31 12:08:45,791 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-93292812 2025-07-31 12:08:46,119 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-93292812', 'default']} 2025-07-31 12:08:46,428 - DEBUG - Response for create_network : {'network': {'id': '2a5da47f-013c-4d7e-8c89-abcbbf8d1262', 'name': 'ctest-vn-31814392', 'tenant_id': 'd82951a39e4d4a529afa14fa8ac313a1', 'project_id': 'd82951a39e4d4a529afa14fa8ac313a1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-93292812', 'ctest-vn-31814392'], 'port_security_enabled': True, 'description': ''}} 2025-07-31 12:08:46,617 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a3b1fe04-7ac1-4509-a6ea-ab6b32e6e147', 'name': '', 'tenant_id': 'd82951a39e4d4a529afa14fa8ac313a1', 'network_id': '2a5da47f-013c-4d7e-8c89-abcbbf8d1262', 'ip_version': 4, 'cidr': '73.155.3.128/26', 'allocation_pools': [{'start': '73.155.3.130', 'end': '73.155.3.190'}], 'gateway_ip': '73.155.3.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '73.155.3.130', 'tags': [], 'project_id': 'd82951a39e4d4a529afa14fa8ac313a1'}} 2025-07-31 12:08:46,635 - INFO - Created VN ctest-vn-31814392 2025-07-31 12:08:46,687 - DEBUG - VN ctest-vn-31814392 UUID is 2a5da47f-013c-4d7e-8c89-abcbbf8d1262 2025-07-31 12:08:47,004 - DEBUG - Response for create_network : {'network': {'id': '8a83a0ff-1211-4029-b9b5-e84a4fc75a54', 'name': 'ctest-ext_vn-74691636', 'tenant_id': 'd82951a39e4d4a529afa14fa8ac313a1', 'project_id': 'd82951a39e4d4a529afa14fa8ac313a1', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-93292812', 'ctest-ext_vn-74691636'], 'port_security_enabled': True, 'description': ''}} 2025-07-31 12:08:47,174 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd67f401d-508f-4371-ae3e-2a3d46aebe39', 'name': '', 'tenant_id': 'd82951a39e4d4a529afa14fa8ac313a1', 'network_id': '8a83a0ff-1211-4029-b9b5-e84a4fc75a54', 'ip_version': 4, 'cidr': '47.224.82.192/26', 'allocation_pools': [{'start': '47.224.82.194', 'end': '47.224.82.254'}], 'gateway_ip': '47.224.82.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '47.224.82.194', 'tags': [], 'project_id': 'd82951a39e4d4a529afa14fa8ac313a1'}} 2025-07-31 12:08:47,193 - INFO - Created VN ctest-ext_vn-74691636 2025-07-31 12:08:47,247 - DEBUG - VN ctest-ext_vn-74691636 UUID is 8a83a0ff-1211-4029-b9b5-e84a4fc75a54 2025-07-31 12:08:47,416 - DEBUG - Services list from nova: [, , ] 2025-07-31 12:08:48,447 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3734-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3734-1) 2025-07-31 12:08:49,682 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3734-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3734-1) 2025-07-31 12:08:49,767 - INFO - Setting gateway for router 397a332c-911d-4465-8918-a5f2b1771ab2 to network 8a83a0ff-1211-4029-b9b5-e84a4fc75a54 2025-07-31 12:08:49,850 - INFO - Adding interface with subnet_id a3b1fe04-7ac1-4509-a6ea-ab6b32e6e147, port_id None to router 397a332c-911d-4465-8918-a5f2b1771ab2 2025-07-31 12:08:50,293 - INFO - Waiting for VM ctest-TestRoutersBasic-93292812-30112945 to be up.. 2025-07-31 12:08:50,434 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-07-31 12:08:55,511 - DEBUG - VM is in ACTIVE state now 2025-07-31 12:08:55,511 - INFO - VM name : ctest-TestRoutersBasic-93292812-30112945 2025-07-31 12:08:55,584 - DEBUG - VM ctest-TestRoutersBasic-93292812-30112945 ID is edef59e5-459a-49f6-bc34-3781b7063a96 2025-07-31 12:08:55,611 - DEBUG - VM ctest-TestRoutersBasic-93292812-30112945 launched on Node cn-jenkins-deploy-platform-ansible-os-3734-1 2025-07-31 12:08:55,683 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/edef59e5-459a-49f6-bc34-3781b7063a96 2025-07-31 12:08:55,980 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/edef59e5-459a-49f6-bc34-3781b7063a96 2025-07-31 12:08:56,019 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/b15b5e18-99da-4514-a641-a3b2ee197a8f 2025-07-31 12:08:59,280 - 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-07-31 12:08:59,281 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-93292812-30112945 failed! 2025-07-31 12:08:59,339 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-93292812:ctest-vn-31814392 is 73.155.3.129 and allocation pool is NOT set 2025-07-31 12:09:01,404 - 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.00 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.433 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.433/1.717/3.001/1.284 ms') 2025-07-31 12:09:01,404 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-93292812-30112945 passed 2025-07-31 12:09:01,559 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-31 12:09:01,559 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-93292812-30112945, IP 73.155.3.131, Port 22 2025-07-31 12:09:01,715 - DEBUG - VM ctest-TestRoutersBasic-93292812-30112945 is ready for SSH connections 2025-07-31 12:09:01,715 - INFO - Waiting for VM ctest-TestRoutersBasic-93292812-27717317 to be up.. 2025-07-31 12:09:01,795 - DEBUG - VM is in ACTIVE state now 2025-07-31 12:09:01,795 - INFO - VM name : ctest-TestRoutersBasic-93292812-27717317 2025-07-31 12:09:01,873 - DEBUG - VM ctest-TestRoutersBasic-93292812-27717317 ID is f3b42692-6706-4d07-88a7-b0e6e4a37c63 2025-07-31 12:09:01,873 - DEBUG - VM ctest-TestRoutersBasic-93292812-27717317 launched on Node cn-jenkins-deploy-platform-ansible-os-3734-1 2025-07-31 12:09:01,950 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/f3b42692-6706-4d07-88a7-b0e6e4a37c63 2025-07-31 12:09:01,960 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/b4e58f3b-5c3e-4ef5-ac05-e5b48008c9d6 2025-07-31 12:09:03,206 - 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.33 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.512 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.512/1.922/3.332/1.410 ms') 2025-07-31 12:09:03,206 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestRoutersBasic-93292812-27717317 passed 2025-07-31 12:09:03,367 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-31 12:09:03,367 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-93292812-27717317, IP 47.224.82.195, Port 22 2025-07-31 12:09:03,530 - DEBUG - VM ctest-TestRoutersBasic-93292812-27717317 is ready for SSH connections 2025-07-31 12:09:03,531 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-31 12:09:03,531 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 47.224.82.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2025-07-31 12:09:03,531 - DEBUG - ping -s 56 -c 3 -W 1 47.224.82.195 2025-07-31 12:09:07,210 - DEBUG - PING 47.224.82.195 (47.224.82.195): 56 data bytes 64 bytes from 47.224.82.195: seq=0 ttl=62 time=5.023 ms 64 bytes from 47.224.82.195: seq=1 ttl=62 time=0.826 ms 64 bytes from 47.224.82.195: seq=2 ttl=62 time=0.899 ms --- 47.224.82.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.826/2.249/5.023 ms 2025-07-31 12:09:07,211 - INFO - Ping to IP 47.224.82.195 from VM ctest-TestRoutersBasic-93292812-30112945 passed 2025-07-31 12:09:07,211 - INFO - Deleting interface with subnet_id a3b1fe04-7ac1-4509-a6ea-ab6b32e6e147, port_id None from router 397a332c-911d-4465-8918-a5f2b1771ab2 2025-07-31 12:09:07,422 - INFO - Deleting VM ctest-TestRoutersBasic-93292812-27717317 2025-07-31 12:09:07,497 - INFO - Deleting VM ctest-TestRoutersBasic-93292812-30112945 2025-07-31 12:09:07,572 - INFO - Deleting VN ctest-ext_vn-74691636 2025-07-31 12:09:07,646 - DEBUG - VN 8a83a0ff-1211-4029-b9b5-e84a4fc75a54 still in use: Unable to complete operation on network 8a83a0ff-1211-4029-b9b5-e84a4fc75a54. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-d8bab5b9-accf-4c81-bd81-3df4cef00c96'] 2025-07-31 12:09:07,646 - WARNING - Deleting VN ctest-ext_vn-74691636 failed..Will retry 2025-07-31 12:09:09,760 - DEBUG - Response for deleting network () 2025-07-31 12:09:09,761 - INFO - Deleting VN ctest-vn-31814392 2025-07-31 12:09:09,907 - DEBUG - Response for deleting network () 2025-07-31 12:09:10,162 - DEBUG - No XMPP flaps were noticed during the test
2025-07-31 12:09:10,162 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:26]
2025-07-31 12:09:10,162 - INFO - -------------------------------------------------------------------------------- 2025-07-31 12:09:10,884 - INFO - Deleted project: ctest-TestRoutersBasic-93292812, ID : d82951a3-9e4d-4a52-9afa-14fa8ac313a1