2026-10-26 10:43:54,572 - INFO - Domain Default found not creating 2026-10-26 10:43:54,895 - INFO - Project ctest-TestRoutersBasic-18048800 not found, creating it 2026-10-26 10:43:55,666 - INFO - Created Project:ctest-TestRoutersBasic-18048800, ID : a0da22ae-66f6-4484-8e70-057ef62f71d5 2026-10-26 10:43:58,193 - INFO - ================================================================================ 2026-10-26 10:43:58,193 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2026-10-26 10:43:58,193 - 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-26 10:43:58,485 - DEBUG - Nothing to compare xmpp stats {'10.0.0.161': {'10.20.0.17': '0'}} with 2026-10-26 10:43:58,485 - INFO - Initial checks done. Running the testcase now 2026-10-26 10:43:58,486 - INFO - 2026-10-26 10:43:59,549 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-18048800 2026-10-26 10:43:59,651 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-18048800 2026-10-26 10:44:00,010 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-18048800', 'default']} 2026-10-26 10:44:00,437 - DEBUG - Response for create_network : {'network': {'id': '0c926f01-32ec-4816-afe6-e645552c493d', 'name': 'ctest-vn-13080401', 'tenant_id': 'a0da22ae66f644848e70057ef62f71d5', 'project_id': 'a0da22ae66f644848e70057ef62f71d5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-18048800', 'ctest-vn-13080401'], 'port_security_enabled': True, 'description': ''}} 2026-10-26 10:44:00,653 - DEBUG - Response for create_subnet : {'subnet': {'id': '110f378a-913a-4240-bfd5-58c58d95293b', 'name': '', 'tenant_id': 'a0da22ae66f644848e70057ef62f71d5', 'network_id': '0c926f01-32ec-4816-afe6-e645552c493d', 'ip_version': 4, 'cidr': '98.213.60.192/26', 'allocation_pools': [{'start': '98.213.60.194', 'end': '98.213.60.254'}], 'gateway_ip': '98.213.60.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '98.213.60.194', 'tags': [], 'project_id': 'a0da22ae66f644848e70057ef62f71d5'}} 2026-10-26 10:44:00,679 - INFO - Created VN ctest-vn-13080401 2026-10-26 10:44:00,739 - DEBUG - VN ctest-vn-13080401 UUID is 0c926f01-32ec-4816-afe6-e645552c493d 2026-10-26 10:44:01,211 - DEBUG - Response for create_network : {'network': {'id': 'f2c17abd-589b-407d-bc7a-b83ab2b08c84', 'name': 'ctest-ext_vn-23873306', 'tenant_id': 'a0da22ae66f644848e70057ef62f71d5', 'project_id': 'a0da22ae66f644848e70057ef62f71d5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-18048800', 'ctest-ext_vn-23873306'], 'port_security_enabled': True, 'description': ''}} 2026-10-26 10:44:01,597 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a4dc24a3-bc7c-48bf-b9d8-8d2462329829', 'name': '', 'tenant_id': 'a0da22ae66f644848e70057ef62f71d5', 'network_id': 'f2c17abd-589b-407d-bc7a-b83ab2b08c84', 'ip_version': 4, 'cidr': '205.154.87.128/26', 'allocation_pools': [{'start': '205.154.87.130', 'end': '205.154.87.190'}], 'gateway_ip': '205.154.87.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '205.154.87.130', 'tags': [], 'project_id': 'a0da22ae66f644848e70057ef62f71d5'}} 2026-10-26 10:44:01,629 - INFO - Created VN ctest-ext_vn-23873306 2026-10-26 10:44:01,690 - DEBUG - VN ctest-ext_vn-23873306 UUID is f2c17abd-589b-407d-bc7a-b83ab2b08c84 2026-10-26 10:44:01,944 - DEBUG - Services list from nova: [, , ] 2026-10-26 10:44:03,073 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6409-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6409-1) 2026-10-26 10:44:03,886 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6409-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6409-1) 2026-10-26 10:44:03,988 - INFO - Setting gateway for router 8c67c2d0-e04c-4d56-becc-4e623ff38e48 to network f2c17abd-589b-407d-bc7a-b83ab2b08c84 2026-10-26 10:44:04,066 - INFO - Adding interface with subnet_id 110f378a-913a-4240-bfd5-58c58d95293b, port_id None to router 8c67c2d0-e04c-4d56-becc-4e623ff38e48 2026-10-26 10:44:04,494 - INFO - Waiting for VM ctest-TestRoutersBasic-18048800-62114694 to be up.. 2026-10-26 10:44:04,604 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 10:44:09,827 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 10:44:14,957 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 10:44:20,087 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 10:44:25,215 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 10:44:30,334 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 10:44:35,464 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 10:44:40,591 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 10:44:45,712 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 10:44:50,840 - DEBUG - VM is in ACTIVE state now 2026-10-26 10:44:50,840 - INFO - VM name : ctest-TestRoutersBasic-18048800-62114694 2026-10-26 10:44:50,960 - DEBUG - VM ctest-TestRoutersBasic-18048800-62114694 ID is 2a88b8e9-86d3-4a73-a0e5-fc63181f6f35 2026-10-26 10:44:50,997 - DEBUG - VM ctest-TestRoutersBasic-18048800-62114694 launched on Node cn-jenkins-deploy-platform-ansible-os-6409-1 2026-10-26 10:44:51,127 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/2a88b8e9-86d3-4a73-a0e5-fc63181f6f35 2026-10-26 10:44:51,540 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/2a88b8e9-86d3-4a73-a0e5-fc63181f6f35 2026-10-26 10:44:51,592 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine-interface/5b046baf-0ef1-4152-b3a0-48c8117f1151 2026-10-26 10:44:54,913 - 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 1020ms') 2026-10-26 10:44:54,913 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-18048800-62114694 failed! 2026-10-26 10:44:54,978 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-18048800:ctest-vn-13080401 is 98.213.60.193 and allocation pool is NOT set 2026-10-26 10:44:59,074 - 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=17.0 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1020ms\r\nrtt min/avg/max/mdev = 16.961/16.961/16.961/0.000 ms') 2026-10-26 10:44:59,074 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-18048800-62114694 passed 2026-10-26 10:44:59,199 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 10:44:59,200 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-18048800-62114694, IP 98.213.60.195, Port 22 2026-10-26 10:44:59,389 - DEBUG - VM ctest-TestRoutersBasic-18048800-62114694 is ready for SSH connections 2026-10-26 10:44:59,389 - INFO - Waiting for VM ctest-TestRoutersBasic-18048800-31784137 to be up.. 2026-10-26 10:44:59,515 - DEBUG - VM is in ACTIVE state now 2026-10-26 10:44:59,515 - INFO - VM name : ctest-TestRoutersBasic-18048800-31784137 2026-10-26 10:44:59,648 - DEBUG - VM ctest-TestRoutersBasic-18048800-31784137 ID is 5c4d1bb8-4e87-4ef9-b04d-158981cd2093 2026-10-26 10:44:59,649 - DEBUG - VM ctest-TestRoutersBasic-18048800-31784137 launched on Node cn-jenkins-deploy-platform-ansible-os-6409-1 2026-10-26 10:44:59,773 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/5c4d1bb8-4e87-4ef9-b04d-158981cd2093 2026-10-26 10:44:59,786 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine-interface/bb3c8888-c2b0-499a-a99f-2ff68d6fefb8 2026-10-26 10:45:03,068 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2026-10-26 10:45:03,068 - DEBUG - Ping to Metadata IP 169.254.0.6 of VM ctest-TestRoutersBasic-18048800-31784137 failed! 2026-10-26 10:45:03,134 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-18048800:ctest-ext_vn-23873306 is 205.154.87.129 and allocation pool is NOT set 2026-10-26 10:45:05,210 - 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=6.14 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=1.34 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 = 1.341/3.740/6.139/2.399 ms') 2026-10-26 10:45:05,210 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-TestRoutersBasic-18048800-31784137 passed 2026-10-26 10:45:05,375 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 10:45:05,375 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-18048800-31784137, IP 205.154.87.132, Port 22 2026-10-26 10:45:05,549 - DEBUG - VM ctest-TestRoutersBasic-18048800-31784137 is ready for SSH connections 2026-10-26 10:45:05,550 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 10:45:05,550 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 205.154.87.132, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.161, gateway password: c0ntrail123 2026-10-26 10:45:05,550 - DEBUG - ping -s 56 -c 3 -W 1 205.154.87.132 2026-10-26 10:45:08,954 - DEBUG - PING 205.154.87.132 (205.154.87.132): 56 data bytes 64 bytes from 205.154.87.132: seq=0 ttl=62 time=10.150 ms 64 bytes from 205.154.87.132: seq=1 ttl=62 time=1.424 ms 64 bytes from 205.154.87.132: seq=2 ttl=62 time=1.624 ms --- 205.154.87.132 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.424/4.399/10.150 ms 2026-10-26 10:45:08,954 - INFO - Ping to IP 205.154.87.132 from VM ctest-TestRoutersBasic-18048800-62114694 passed 2026-10-26 10:45:08,954 - INFO - Deleting interface with subnet_id 110f378a-913a-4240-bfd5-58c58d95293b, port_id None from router 8c67c2d0-e04c-4d56-becc-4e623ff38e48 2026-10-26 10:45:09,401 - INFO - Deleting VM ctest-TestRoutersBasic-18048800-31784137 2026-10-26 10:45:09,559 - INFO - Deleting VM ctest-TestRoutersBasic-18048800-62114694 2026-10-26 10:45:09,745 - INFO - Deleting VN ctest-ext_vn-23873306 2026-10-26 10:45:09,891 - DEBUG - VN f2c17abd-589b-407d-bc7a-b83ab2b08c84 still in use: Unable to complete operation on network f2c17abd-589b-407d-bc7a-b83ab2b08c84. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-b1a85d40-31e7-4142-842c-d82682d78dc8'] 2026-10-26 10:45:09,891 - WARNING - Deleting VN ctest-ext_vn-23873306 failed..Will retry 2026-10-26 10:45:11,952 - DEBUG - VN f2c17abd-589b-407d-bc7a-b83ab2b08c84 still in use: Unable to complete operation on network f2c17abd-589b-407d-bc7a-b83ab2b08c84. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-204ea7da-a261-4949-abbc-f84634a81fb6'] 2026-10-26 10:45:11,952 - WARNING - Deleting VN ctest-ext_vn-23873306 failed..Will retry 2026-10-26 10:45:14,170 - DEBUG - Response for deleting network () 2026-10-26 10:45:14,170 - INFO - Deleting VN ctest-vn-13080401 2026-10-26 10:45:14,379 - DEBUG - Response for deleting network () 2026-10-26 10:45:14,674 - DEBUG - No XMPP flaps were noticed during the test
2026-10-26 10:45:14,674 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:01:16]
2026-10-26 10:45:14,674 - INFO - -------------------------------------------------------------------------------- 2026-10-26 10:45:15,621 - INFO - Deleted project: ctest-TestRoutersBasic-18048800, ID : a0da22ae-66f6-4484-8e70-057ef62f71d5