2026-10-12 02:36:49,926 - INFO - Domain Default found not creating 2026-10-12 02:36:50,068 - INFO - Project ctest-TestRoutersBasic-60134174 not found, creating it 2026-10-12 02:36:50,611 - INFO - Created Project:ctest-TestRoutersBasic-60134174, ID : 1be5bcd0-16fc-462f-8aaa-d2e7e6218988 2026-10-12 02:36:52,130 - INFO - ================================================================================ 2026-10-12 02:36:52,130 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2026-10-12 02:36:52,130 - 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-10-12 02:36:53,394 - DEBUG - Nothing to compare xmpp stats {'10.0.0.188': {'10.20.0.30': '0', '10.20.0.25': '0'}, '10.0.0.189': {'10.20.0.30': '0', '10.20.0.25': '0'}} with 2026-10-12 02:36:53,394 - INFO - Initial checks done. Running the testcase now 2026-10-12 02:36:53,394 - INFO - 2026-10-12 02:36:54,240 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-60134174 2026-10-12 02:36:54,294 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-60134174 2026-10-12 02:36:54,599 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-60134174', 'default']} 2026-10-12 02:36:54,955 - DEBUG - Response for create_network : {'network': {'id': 'c044e468-48c8-4a26-9bcf-d77cb587163c', 'name': 'ctest-vn-94312656', 'tenant_id': '1be5bcd016fc462f8aaad2e7e6218988', 'project_id': '1be5bcd016fc462f8aaad2e7e6218988', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-60134174', 'ctest-vn-94312656'], 'port_security_enabled': True, 'description': ''}} 2026-10-12 02:36:55,239 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e4d3c97b-d90c-4b73-bfcd-4e89b2bebfd7', 'name': '', 'tenant_id': '1be5bcd016fc462f8aaad2e7e6218988', 'network_id': 'c044e468-48c8-4a26-9bcf-d77cb587163c', 'ip_version': 4, 'cidr': '197.195.51.0/26', 'allocation_pools': [{'start': '197.195.51.2', 'end': '197.195.51.62'}], 'gateway_ip': '197.195.51.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '197.195.51.2', 'tags': [], 'project_id': '1be5bcd016fc462f8aaad2e7e6218988'}} 2026-10-12 02:36:55,256 - INFO - Created VN ctest-vn-94312656 2026-10-12 02:36:55,268 - DEBUG - VN ctest-vn-94312656 UUID is c044e468-48c8-4a26-9bcf-d77cb587163c 2026-10-12 02:36:55,629 - DEBUG - Response for create_network : {'network': {'id': 'fc756d88-4709-4704-a1c7-4bfc44e257a5', 'name': 'ctest-ext_vn-54633236', 'tenant_id': '1be5bcd016fc462f8aaad2e7e6218988', 'project_id': '1be5bcd016fc462f8aaad2e7e6218988', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-60134174', 'ctest-ext_vn-54633236'], 'port_security_enabled': True, 'description': ''}} 2026-10-12 02:36:55,887 - DEBUG - Response for create_subnet : {'subnet': {'id': 'eac75e19-8179-496f-841e-fe6e9d510a20', 'name': '', 'tenant_id': '1be5bcd016fc462f8aaad2e7e6218988', 'network_id': 'fc756d88-4709-4704-a1c7-4bfc44e257a5', 'ip_version': 4, 'cidr': '206.22.23.128/26', 'allocation_pools': [{'start': '206.22.23.130', 'end': '206.22.23.190'}], 'gateway_ip': '206.22.23.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '206.22.23.130', 'tags': [], 'project_id': '1be5bcd016fc462f8aaad2e7e6218988'}} 2026-10-12 02:36:55,906 - INFO - Created VN ctest-ext_vn-54633236 2026-10-12 02:36:55,918 - DEBUG - VN ctest-ext_vn-54633236 UUID is fc756d88-4709-4704-a1c7-4bfc44e257a5 2026-10-12 02:36:56,059 - DEBUG - Services list from nova: [, , , ] 2026-10-12 02:36:56,453 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6357-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6357-1) 2026-10-12 02:36:56,803 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6357-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6357-2) 2026-10-12 02:36:56,933 - INFO - Setting gateway for router 0dd152d0-f2d6-485e-bc54-b66c93bf0e30 to network fc756d88-4709-4704-a1c7-4bfc44e257a5 2026-10-12 02:36:57,005 - INFO - Adding interface with subnet_id e4d3c97b-d90c-4b73-bfcd-4e89b2bebfd7, port_id None to router 0dd152d0-f2d6-485e-bc54-b66c93bf0e30 2026-10-12 02:36:57,425 - INFO - Waiting for VM ctest-TestRoutersBasic-60134174-87618735 to be up.. 2026-10-12 02:36:57,496 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-12 02:37:02,564 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-12 02:37:07,638 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-12 02:37:12,708 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-12 02:37:17,776 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-12 02:37:22,854 - DEBUG - VM is in ACTIVE state now 2026-10-12 02:37:22,854 - INFO - VM name : ctest-TestRoutersBasic-60134174-87618735 2026-10-12 02:37:22,922 - DEBUG - VM ctest-TestRoutersBasic-60134174-87618735 ID is 0c18ac39-3103-40dd-8533-2a76094035da 2026-10-12 02:37:22,944 - DEBUG - VM ctest-TestRoutersBasic-60134174-87618735 launched on Node an-jenkins-deploy-platform-ansible-os-6357-1 2026-10-12 02:37:23,022 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/0c18ac39-3103-40dd-8533-2a76094035da 2026-10-12 02:37:23,387 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/0c18ac39-3103-40dd-8533-2a76094035da 2026-10-12 02:37:23,432 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/2d5dcfca-10b6-4f27-8bd6-53797949221c 2026-10-12 02:37:26,616 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1026ms') 2026-10-12 02:37:26,616 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-60134174-87618735 failed! 2026-10-12 02:37:26,635 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-60134174:ctest-vn-94312656 is 197.195.51.1 and allocation pool is NOT set 2026-10-12 02:37:28,695 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=10.3 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.418 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.418/5.356/10.295/4.938 ms') 2026-10-12 02:37:28,695 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-60134174-87618735 passed 2026-10-12 02:37:28,757 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-12 02:37:28,757 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-60134174-87618735, IP 197.195.51.3, Port 22 2026-10-12 02:37:28,930 - DEBUG - VM ctest-TestRoutersBasic-60134174-87618735 is ready for SSH connections 2026-10-12 02:37:28,930 - INFO - Waiting for VM ctest-TestRoutersBasic-60134174-28530376 to be up.. 2026-10-12 02:37:29,008 - DEBUG - VM is in ACTIVE state now 2026-10-12 02:37:29,008 - INFO - VM name : ctest-TestRoutersBasic-60134174-28530376 2026-10-12 02:37:29,079 - DEBUG - VM ctest-TestRoutersBasic-60134174-28530376 ID is 29ea440c-8279-4365-9e6c-0a3a6d21edd9 2026-10-12 02:37:29,080 - DEBUG - VM ctest-TestRoutersBasic-60134174-28530376 launched on Node an-jenkins-deploy-platform-ansible-os-6357-2 2026-10-12 02:37:29,142 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/29ea440c-8279-4365-9e6c-0a3a6d21edd9 2026-10-12 02:37:29,151 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/0ca33001-ac29-4614-87f0-a915b63b5966 2026-10-12 02:37:30,309 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=4.19 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.392 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.392/2.292/4.192/1.900 ms') 2026-10-12 02:37:30,309 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-60134174-28530376 passed 2026-10-12 02:37:30,373 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-12 02:37:30,373 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-60134174-28530376, IP 206.22.23.132, Port 22 2026-10-12 02:37:30,540 - DEBUG - VM ctest-TestRoutersBasic-60134174-28530376 is ready for SSH connections 2026-10-12 02:37:30,541 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-12 02:37:30,541 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 206.22.23.132, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.188, gateway password: c0ntrail123 2026-10-12 02:37:30,541 - DEBUG - ping -s 56 -c 3 -W 1 206.22.23.132 2026-10-12 02:37:33,099 - DEBUG - PING 206.22.23.132 (206.22.23.132): 56 data bytes 64 bytes from 206.22.23.132: seq=0 ttl=62 time=10.426 ms 64 bytes from 206.22.23.132: seq=1 ttl=62 time=0.938 ms 64 bytes from 206.22.23.132: seq=2 ttl=62 time=1.301 ms --- 206.22.23.132 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.938/4.221/10.426 ms 2026-10-12 02:37:33,099 - INFO - Ping to IP 206.22.23.132 from VM ctest-TestRoutersBasic-60134174-87618735 passed 2026-10-12 02:37:33,099 - INFO - Deleting interface with subnet_id e4d3c97b-d90c-4b73-bfcd-4e89b2bebfd7, port_id None from router 0dd152d0-f2d6-485e-bc54-b66c93bf0e30 2026-10-12 02:37:33,385 - INFO - Deleting VM ctest-TestRoutersBasic-60134174-28530376 2026-10-12 02:37:33,491 - INFO - Deleting VM ctest-TestRoutersBasic-60134174-87618735 2026-10-12 02:37:33,569 - INFO - Deleting VN ctest-ext_vn-54633236 2026-10-12 02:37:33,673 - DEBUG - VN fc756d88-4709-4704-a1c7-4bfc44e257a5 still in use: Unable to complete operation on network fc756d88-4709-4704-a1c7-4bfc44e257a5. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-43c375a6-8007-4cb7-a190-66bd8220121d'] 2026-10-12 02:37:33,673 - WARNING - Deleting VN ctest-ext_vn-54633236 failed..Will retry 2026-10-12 02:37:35,838 - DEBUG - Response for deleting network () 2026-10-12 02:37:35,838 - INFO - Deleting VN ctest-vn-94312656 2026-10-12 02:37:35,960 - DEBUG - Response for deleting network () 2026-10-12 02:37:37,229 - DEBUG - No XMPP flaps were noticed during the test
2026-10-12 02:37:37,229 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:45]
2026-10-12 02:37:37,229 - INFO - -------------------------------------------------------------------------------- 2026-10-12 02:37:37,975 - INFO - Deleted project: ctest-TestRoutersBasic-60134174, ID : 1be5bcd0-16fc-462f-8aaa-d2e7e6218988