2026-09-16 10:05:39,477 - INFO - Domain Default found not creating 2026-09-16 10:05:39,635 - INFO - Project ctest-TestRoutersBasic-61576266 not found, creating it 2026-09-16 10:05:40,124 - INFO - Created Project:ctest-TestRoutersBasic-61576266, ID : cedc00ed-fe5d-4ffa-92d7-f44799d5fe94 2026-09-16 10:05:41,800 - INFO - ================================================================================ 2026-09-16 10:05:41,800 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2026-09-16 10:05:41,800 - 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-09-16 10:05:42,056 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.17': '0'}} with 2026-09-16 10:05:42,056 - INFO - Initial checks done. Running the testcase now 2026-09-16 10:05:42,056 - INFO - 2026-09-16 10:05:42,837 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-61576266 2026-09-16 10:05:42,938 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-61576266 2026-09-16 10:05:43,269 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-61576266', 'default']} 2026-09-16 10:05:43,567 - DEBUG - Response for create_network : {'network': {'id': 'e9a6d9e6-8ffb-40e1-ab20-c176aef31d40', 'name': 'ctest-vn-24465556', 'tenant_id': 'cedc00edfe5d4ffa92d7f44799d5fe94', 'project_id': 'cedc00edfe5d4ffa92d7f44799d5fe94', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-61576266', 'ctest-vn-24465556'], 'port_security_enabled': True, 'description': ''}} 2026-09-16 10:05:43,761 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b331fbf1-98f9-4f6e-b43e-da185b26ed9f', 'name': '', 'tenant_id': 'cedc00edfe5d4ffa92d7f44799d5fe94', 'network_id': 'e9a6d9e6-8ffb-40e1-ab20-c176aef31d40', 'ip_version': 4, 'cidr': '143.14.108.64/26', 'allocation_pools': [{'start': '143.14.108.66', 'end': '143.14.108.126'}], 'gateway_ip': '143.14.108.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '143.14.108.66', 'tags': [], 'project_id': 'cedc00edfe5d4ffa92d7f44799d5fe94'}} 2026-09-16 10:05:43,778 - INFO - Created VN ctest-vn-24465556 2026-09-16 10:05:43,830 - DEBUG - VN ctest-vn-24465556 UUID is e9a6d9e6-8ffb-40e1-ab20-c176aef31d40 2026-09-16 10:05:44,151 - DEBUG - Response for create_network : {'network': {'id': 'b46445cb-6605-4a7a-9449-d2600a28d42f', 'name': 'ctest-ext_vn-17879039', 'tenant_id': 'cedc00edfe5d4ffa92d7f44799d5fe94', 'project_id': 'cedc00edfe5d4ffa92d7f44799d5fe94', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-61576266', 'ctest-ext_vn-17879039'], 'port_security_enabled': True, 'description': ''}} 2026-09-16 10:05:44,353 - DEBUG - Response for create_subnet : {'subnet': {'id': '36df4e5e-94a2-4abf-8552-3ed128083504', 'name': '', 'tenant_id': 'cedc00edfe5d4ffa92d7f44799d5fe94', 'network_id': 'b46445cb-6605-4a7a-9449-d2600a28d42f', 'ip_version': 4, 'cidr': '26.239.25.192/26', 'allocation_pools': [{'start': '26.239.25.194', 'end': '26.239.25.254'}], 'gateway_ip': '26.239.25.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '26.239.25.194', 'tags': [], 'project_id': 'cedc00edfe5d4ffa92d7f44799d5fe94'}} 2026-09-16 10:05:44,370 - INFO - Created VN ctest-ext_vn-17879039 2026-09-16 10:05:44,422 - DEBUG - VN ctest-ext_vn-17879039 UUID is b46445cb-6605-4a7a-9449-d2600a28d42f 2026-09-16 10:05:44,628 - DEBUG - Services list from nova: [, , ] 2026-09-16 10:05:44,972 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6289-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6289-1) 2026-09-16 10:05:45,372 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6289-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6289-1) 2026-09-16 10:05:45,463 - INFO - Setting gateway for router 1907c592-319d-403d-8875-a6aa76618f96 to network b46445cb-6605-4a7a-9449-d2600a28d42f 2026-09-16 10:05:45,547 - INFO - Adding interface with subnet_id b331fbf1-98f9-4f6e-b43e-da185b26ed9f, port_id None to router 1907c592-319d-403d-8875-a6aa76618f96 2026-09-16 10:05:45,914 - INFO - Waiting for VM ctest-TestRoutersBasic-61576266-51554653 to be up.. 2026-09-16 10:05:45,976 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 10:05:51,053 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 10:05:56,192 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 10:06:01,278 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 10:06:06,359 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 10:06:11,447 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 10:06:16,526 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 10:06:21,610 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-16 10:06:26,696 - DEBUG - VM is in ACTIVE state now 2026-09-16 10:06:26,696 - INFO - VM name : ctest-TestRoutersBasic-61576266-51554653 2026-09-16 10:06:26,778 - DEBUG - VM ctest-TestRoutersBasic-61576266-51554653 ID is 1f99ada8-2808-48ab-806f-bad398c374d0 2026-09-16 10:06:26,803 - DEBUG - VM ctest-TestRoutersBasic-61576266-51554653 launched on Node cn-jenkins-deploy-platform-ansible-os-6289-1 2026-09-16 10:06:26,909 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/1f99ada8-2808-48ab-806f-bad398c374d0 2026-09-16 10:06:27,323 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/1f99ada8-2808-48ab-806f-bad398c374d0 2026-09-16 10:06:27,356 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/a169b9af-04e2-4d40-b4b8-8763ffaf1ace 2026-09-16 10:06:30,608 - 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=2 ttl=63 time=14.8 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1006ms\r\nrtt min/avg/max/mdev = 14.830/14.830/14.830/0.000 ms') 2026-09-16 10:06:30,608 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-61576266-51554653 passed 2026-09-16 10:06:30,762 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-16 10:06:30,762 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-61576266-51554653, IP 143.14.108.67, Port 22 2026-09-16 10:06:30,935 - DEBUG - VM ctest-TestRoutersBasic-61576266-51554653 is ready for SSH connections 2026-09-16 10:06:30,936 - INFO - Waiting for VM ctest-TestRoutersBasic-61576266-46587092 to be up.. 2026-09-16 10:06:31,011 - DEBUG - VM is in ACTIVE state now 2026-09-16 10:06:31,011 - INFO - VM name : ctest-TestRoutersBasic-61576266-46587092 2026-09-16 10:06:31,096 - DEBUG - VM ctest-TestRoutersBasic-61576266-46587092 ID is 441a9c77-764c-4fcf-8b9a-8cc88a2b0214 2026-09-16 10:06:31,097 - DEBUG - VM ctest-TestRoutersBasic-61576266-46587092 launched on Node cn-jenkins-deploy-platform-ansible-os-6289-1 2026-09-16 10:06:31,178 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/441a9c77-764c-4fcf-8b9a-8cc88a2b0214 2026-09-16 10:06:31,187 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/7fd33d4d-bbfc-4d5e-802d-206217397f66 2026-09-16 10:06:32,437 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n64 bytes from 169.254.0.6: icmp_seq=1 ttl=63 time=3.63 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.503 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.503/2.067/3.632/1.564 ms') 2026-09-16 10:06:32,437 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-TestRoutersBasic-61576266-46587092 passed 2026-09-16 10:06:32,590 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-16 10:06:32,590 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-61576266-46587092, IP 26.239.25.196, Port 22 2026-09-16 10:06:32,759 - DEBUG - VM ctest-TestRoutersBasic-61576266-46587092 is ready for SSH connections 2026-09-16 10:06:32,760 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-16 10:06:32,760 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 26.239.25.196, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-09-16 10:06:32,760 - DEBUG - ping -s 56 -c 3 -W 1 26.239.25.196 2026-09-16 10:06:35,783 - DEBUG - PING 26.239.25.196 (26.239.25.196): 56 data bytes 64 bytes from 26.239.25.196: seq=0 ttl=62 time=7.574 ms 64 bytes from 26.239.25.196: seq=1 ttl=62 time=1.381 ms 64 bytes from 26.239.25.196: seq=2 ttl=62 time=1.089 ms --- 26.239.25.196 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.089/3.348/7.574 ms 2026-09-16 10:06:35,784 - INFO - Ping to IP 26.239.25.196 from VM ctest-TestRoutersBasic-61576266-51554653 passed 2026-09-16 10:06:35,784 - INFO - Deleting interface with subnet_id b331fbf1-98f9-4f6e-b43e-da185b26ed9f, port_id None from router 1907c592-319d-403d-8875-a6aa76618f96 2026-09-16 10:06:35,997 - INFO - Deleting VM ctest-TestRoutersBasic-61576266-46587092 2026-09-16 10:06:36,075 - INFO - Deleting VM ctest-TestRoutersBasic-61576266-51554653 2026-09-16 10:06:36,151 - INFO - Deleting VN ctest-ext_vn-17879039 2026-09-16 10:06:36,228 - DEBUG - VN b46445cb-6605-4a7a-9449-d2600a28d42f still in use: Unable to complete operation on network b46445cb-6605-4a7a-9449-d2600a28d42f. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-1f8be40b-c786-490d-bbf6-a617b42d28cc'] 2026-09-16 10:06:36,228 - WARNING - Deleting VN ctest-ext_vn-17879039 failed..Will retry 2026-09-16 10:06:38,407 - DEBUG - Response for deleting network () 2026-09-16 10:06:38,407 - INFO - Deleting VN ctest-vn-24465556 2026-09-16 10:06:38,520 - DEBUG - Response for deleting network () 2026-09-16 10:06:38,776 - DEBUG - No XMPP flaps were noticed during the test
2026-09-16 10:06:38,776 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:57]
2026-09-16 10:06:38,776 - INFO - -------------------------------------------------------------------------------- 2026-09-16 10:06:39,524 - INFO - Deleted project: ctest-TestRoutersBasic-61576266, ID : cedc00ed-fe5d-4ffa-92d7-f44799d5fe94