2025-10-15 12:44:08,001 - INFO - Domain Default found not creating 2025-10-15 12:44:08,143 - INFO - Project ctest-TestRoutersBasic-49298236 not found, creating it 2025-10-15 12:44:08,574 - INFO - Created Project:ctest-TestRoutersBasic-49298236, ID : c6d2de85-b3f3-41ef-ab07-840b7b163a12 2025-10-15 12:44:09,934 - INFO - ================================================================================ 2025-10-15 12:44:09,934 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2025-10-15 12:44:09,934 - 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-10-15 12:44:10,192 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.145': '0'}} with 2025-10-15 12:44:10,192 - INFO - Initial checks done. Running the testcase now 2025-10-15 12:44:10,192 - INFO - 2025-10-15 12:44:10,871 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-49298236 2025-10-15 12:44:10,967 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-49298236 2025-10-15 12:44:11,290 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-49298236', 'default']} 2025-10-15 12:44:11,563 - DEBUG - Response for create_network : {'network': {'id': 'dc53062d-83c2-4837-bc44-37d5bac0a4dd', 'name': 'ctest-vn-86314206', 'tenant_id': 'c6d2de85b3f341efab07840b7b163a12', 'project_id': 'c6d2de85b3f341efab07840b7b163a12', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-49298236', 'ctest-vn-86314206'], 'port_security_enabled': True, 'description': ''}} 2025-10-15 12:44:11,758 - DEBUG - Response for create_subnet : {'subnet': {'id': '6c73a5e9-4fbd-4657-8af3-863b406f32a1', 'name': '', 'tenant_id': 'c6d2de85b3f341efab07840b7b163a12', 'network_id': 'dc53062d-83c2-4837-bc44-37d5bac0a4dd', 'ip_version': 4, 'cidr': '113.13.156.192/26', 'allocation_pools': [{'start': '113.13.156.194', 'end': '113.13.156.254'}], 'gateway_ip': '113.13.156.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '113.13.156.194', 'tags': [], 'project_id': 'c6d2de85b3f341efab07840b7b163a12'}} 2025-10-15 12:44:11,775 - INFO - Created VN ctest-vn-86314206 2025-10-15 12:44:11,827 - DEBUG - VN ctest-vn-86314206 UUID is dc53062d-83c2-4837-bc44-37d5bac0a4dd 2025-10-15 12:44:12,138 - DEBUG - Response for create_network : {'network': {'id': 'f29b9650-5055-4091-9b92-a92ba8c3ec14', 'name': 'ctest-ext_vn-48261760', 'tenant_id': 'c6d2de85b3f341efab07840b7b163a12', 'project_id': 'c6d2de85b3f341efab07840b7b163a12', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-49298236', 'ctest-ext_vn-48261760'], 'port_security_enabled': True, 'description': ''}} 2025-10-15 12:44:12,310 - DEBUG - Response for create_subnet : {'subnet': {'id': '15d9be4d-5514-42ce-8d21-e28ead07400a', 'name': '', 'tenant_id': 'c6d2de85b3f341efab07840b7b163a12', 'network_id': 'f29b9650-5055-4091-9b92-a92ba8c3ec14', 'ip_version': 4, 'cidr': '219.75.187.192/26', 'allocation_pools': [{'start': '219.75.187.194', 'end': '219.75.187.254'}], 'gateway_ip': '219.75.187.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '219.75.187.194', 'tags': [], 'project_id': 'c6d2de85b3f341efab07840b7b163a12'}} 2025-10-15 12:44:12,327 - INFO - Created VN ctest-ext_vn-48261760 2025-10-15 12:44:12,379 - DEBUG - VN ctest-ext_vn-48261760 UUID is f29b9650-5055-4091-9b92-a92ba8c3ec14 2025-10-15 12:44:12,574 - DEBUG - Services list from nova: [, , ] 2025-10-15 12:44:13,557 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4324-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4324-1) 2025-10-15 12:44:14,645 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4324-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4324-1) 2025-10-15 12:44:14,717 - INFO - Setting gateway for router 56d55311-5793-4797-99ed-0ef0a0f3afe8 to network f29b9650-5055-4091-9b92-a92ba8c3ec14 2025-10-15 12:44:14,774 - INFO - Adding interface with subnet_id 6c73a5e9-4fbd-4657-8af3-863b406f32a1, port_id None to router 56d55311-5793-4797-99ed-0ef0a0f3afe8 2025-10-15 12:44:15,110 - INFO - Waiting for VM ctest-TestRoutersBasic-49298236-62649691 to be up.. 2025-10-15 12:44:15,237 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-15 12:44:20,303 - DEBUG - VM is in ACTIVE state now 2025-10-15 12:44:20,303 - INFO - VM name : ctest-TestRoutersBasic-49298236-62649691 2025-10-15 12:44:20,368 - DEBUG - VM ctest-TestRoutersBasic-49298236-62649691 ID is 7e08975d-91c2-45a6-a301-77afc04cfd31 2025-10-15 12:44:20,385 - DEBUG - VM ctest-TestRoutersBasic-49298236-62649691 launched on Node cn-jenkins-deploy-platform-ansible-os-4324-1 2025-10-15 12:44:20,451 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/7e08975d-91c2-45a6-a301-77afc04cfd31 2025-10-15 12:44:20,742 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/7e08975d-91c2-45a6-a301-77afc04cfd31 2025-10-15 12:44:20,769 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/7b829cfe-159d-48c7-9e21-36a8f805fcbb 2025-10-15 12:44:24,018 - 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-10-15 12:44:24,018 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-49298236-62649691 failed! 2025-10-15 12:44:24,070 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-49298236:ctest-vn-86314206 is 113.13.156.193 and allocation pool is NOT set 2025-10-15 12:44:26,129 - 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.45 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.424 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.424/1.935/3.447/1.511 ms') 2025-10-15 12:44:26,129 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestRoutersBasic-49298236-62649691 passed 2025-10-15 12:44:26,279 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-15 12:44:26,279 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-49298236-62649691, IP 113.13.156.195, Port 22 2025-10-15 12:44:26,442 - DEBUG - VM ctest-TestRoutersBasic-49298236-62649691 is ready for SSH connections 2025-10-15 12:44:26,442 - INFO - Waiting for VM ctest-TestRoutersBasic-49298236-73850413 to be up.. 2025-10-15 12:44:26,522 - DEBUG - VM is in ACTIVE state now 2025-10-15 12:44:26,522 - INFO - VM name : ctest-TestRoutersBasic-49298236-73850413 2025-10-15 12:44:26,589 - DEBUG - VM ctest-TestRoutersBasic-49298236-73850413 ID is 148b2467-ffbb-4341-9121-a3a7544b2698 2025-10-15 12:44:26,589 - DEBUG - VM ctest-TestRoutersBasic-49298236-73850413 launched on Node cn-jenkins-deploy-platform-ansible-os-4324-1 2025-10-15 12:44:26,656 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/148b2467-ffbb-4341-9121-a3a7544b2698 2025-10-15 12:44:26,663 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/8f585ba9-3d70-4f80-ad71-08e43ca1d873 2025-10-15 12:44:27,892 - 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=2.84 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.393 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.393/1.615/2.837/1.222 ms') 2025-10-15 12:44:27,892 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestRoutersBasic-49298236-73850413 passed 2025-10-15 12:44:28,043 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-15 12:44:28,043 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-49298236-73850413, IP 219.75.187.195, Port 22 2025-10-15 12:44:28,198 - DEBUG - VM ctest-TestRoutersBasic-49298236-73850413 is ready for SSH connections 2025-10-15 12:44:28,198 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-15 12:44:28,198 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 219.75.187.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2025-10-15 12:44:28,198 - DEBUG - ping -s 56 -c 3 -W 1 219.75.187.195 2025-10-15 12:44:32,165 - DEBUG - PING 219.75.187.195 (219.75.187.195): 56 data bytes 64 bytes from 219.75.187.195: seq=0 ttl=62 time=7.321 ms 64 bytes from 219.75.187.195: seq=1 ttl=62 time=0.820 ms 64 bytes from 219.75.187.195: seq=2 ttl=62 time=0.864 ms --- 219.75.187.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.820/3.001/7.321 ms 2025-10-15 12:44:32,165 - INFO - Ping to IP 219.75.187.195 from VM ctest-TestRoutersBasic-49298236-62649691 passed 2025-10-15 12:44:32,165 - INFO - Deleting interface with subnet_id 6c73a5e9-4fbd-4657-8af3-863b406f32a1, port_id None from router 56d55311-5793-4797-99ed-0ef0a0f3afe8 2025-10-15 12:44:32,360 - INFO - Deleting VM ctest-TestRoutersBasic-49298236-73850413 2025-10-15 12:44:32,432 - INFO - Deleting VM ctest-TestRoutersBasic-49298236-62649691 2025-10-15 12:44:32,508 - INFO - Deleting VN ctest-ext_vn-48261760 2025-10-15 12:44:32,575 - DEBUG - VN f29b9650-5055-4091-9b92-a92ba8c3ec14 still in use: Unable to complete operation on network f29b9650-5055-4091-9b92-a92ba8c3ec14. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-e5d9ca73-c3b3-43e3-b48c-886ae386735a'] 2025-10-15 12:44:32,575 - WARNING - Deleting VN ctest-ext_vn-48261760 failed..Will retry 2025-10-15 12:44:34,666 - DEBUG - Response for deleting network () 2025-10-15 12:44:34,666 - INFO - Deleting VN ctest-vn-86314206 2025-10-15 12:44:34,761 - DEBUG - Response for deleting network () 2025-10-15 12:44:35,019 - DEBUG - No XMPP flaps were noticed during the test
2025-10-15 12:44:35,019 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:26]
2025-10-15 12:44:35,019 - INFO - -------------------------------------------------------------------------------- 2025-10-15 12:44:35,664 - INFO - Deleted project: ctest-TestRoutersBasic-49298236, ID : c6d2de85-b3f3-41ef-ab07-840b7b163a12