2026-11-02 02:47:51,836 - INFO - Domain Default found not creating 2026-11-02 02:47:52,021 - INFO - Project ctest-TestRoutersBasic-06269532 not found, creating it 2026-11-02 02:47:52,663 - INFO - Created Project:ctest-TestRoutersBasic-06269532, ID : 139e80eb-ae85-4853-817d-35a05cf79dda 2026-11-02 02:47:54,493 - INFO - ================================================================================ 2026-11-02 02:47:54,493 - INFO - STARTING TEST : test_basic_snat_behavior_without_external_connectivity 2026-11-02 02:47:54,493 - 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-11-02 02:47:55,797 - DEBUG - Nothing to compare xmpp stats {'10.0.0.161': {'10.20.0.254': '0', '10.20.0.17': '0'}, '10.0.0.162': {'10.20.0.254': '0', '10.20.0.17': '0'}} with 2026-11-02 02:47:55,797 - INFO - Initial checks done. Running the testcase now 2026-11-02 02:47:55,797 - INFO - 2026-11-02 02:47:56,754 - INFO - Default SG to be edited for allow all on project: ctest-TestRoutersBasic-06269532 2026-11-02 02:47:56,827 - INFO - Adding rules to the default security group in Project ctest-TestRoutersBasic-06269532 2026-11-02 02:47:57,149 - DEBUG - Reading firewall policy {'fq_name': ['default-domain', 'ctest-TestRoutersBasic-06269532', 'default']} 2026-11-02 02:47:57,556 - DEBUG - Response for create_network : {'network': {'id': '6e607bc7-d6b7-42a7-b5dc-d5aa034cf1a0', 'name': 'ctest-vn-76222583', 'tenant_id': '139e80ebae854853817d35a05cf79dda', 'project_id': '139e80ebae854853817d35a05cf79dda', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-06269532', 'ctest-vn-76222583'], 'port_security_enabled': True, 'description': ''}} 2026-11-02 02:47:57,840 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a36793dd-0100-4f3b-bf89-117ddb09256f', 'name': '', 'tenant_id': '139e80ebae854853817d35a05cf79dda', 'network_id': '6e607bc7-d6b7-42a7-b5dc-d5aa034cf1a0', 'ip_version': 4, 'cidr': '23.231.113.64/26', 'allocation_pools': [{'start': '23.231.113.66', 'end': '23.231.113.126'}], 'gateway_ip': '23.231.113.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '23.231.113.66', 'tags': [], 'project_id': '139e80ebae854853817d35a05cf79dda'}} 2026-11-02 02:47:57,861 - INFO - Created VN ctest-vn-76222583 2026-11-02 02:47:57,873 - DEBUG - VN ctest-vn-76222583 UUID is 6e607bc7-d6b7-42a7-b5dc-d5aa034cf1a0 2026-11-02 02:47:58,298 - DEBUG - Response for create_network : {'network': {'id': '1abbf5d5-f736-4aef-82a0-f00781968647', 'name': 'ctest-ext_vn-06498728', 'tenant_id': '139e80ebae854853817d35a05cf79dda', 'project_id': '139e80ebae854853817d35a05cf79dda', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': True, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRoutersBasic-06269532', 'ctest-ext_vn-06498728'], 'port_security_enabled': True, 'description': ''}} 2026-11-02 02:47:58,557 - DEBUG - Response for create_subnet : {'subnet': {'id': '0a0f287e-626f-4ec6-b4a1-4673c03d3bfd', 'name': '', 'tenant_id': '139e80ebae854853817d35a05cf79dda', 'network_id': '1abbf5d5-f736-4aef-82a0-f00781968647', 'ip_version': 4, 'cidr': '135.101.183.192/26', 'allocation_pools': [{'start': '135.101.183.194', 'end': '135.101.183.254'}], 'gateway_ip': '135.101.183.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '135.101.183.194', 'tags': [], 'project_id': '139e80ebae854853817d35a05cf79dda'}} 2026-11-02 02:47:58,590 - INFO - Created VN ctest-ext_vn-06498728 2026-11-02 02:47:58,605 - DEBUG - VN ctest-ext_vn-06498728 UUID is 1abbf5d5-f736-4aef-82a0-f00781968647 2026-11-02 02:47:58,791 - DEBUG - Services list from nova: [, , , ] 2026-11-02 02:47:59,261 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6461-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6461-1) 2026-11-02 02:47:59,722 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6461-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6461-2) 2026-11-02 02:47:59,885 - INFO - Setting gateway for router 58483c28-af59-47de-9735-80531f9bd8d3 to network 1abbf5d5-f736-4aef-82a0-f00781968647 2026-11-02 02:47:59,983 - INFO - Adding interface with subnet_id a36793dd-0100-4f3b-bf89-117ddb09256f, port_id None to router 58483c28-af59-47de-9735-80531f9bd8d3 2026-11-02 02:48:00,452 - INFO - Waiting for VM ctest-TestRoutersBasic-06269532-88180429 to be up.. 2026-11-02 02:48:00,547 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-11-02 02:48:05,635 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-11-02 02:48:10,730 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-11-02 02:48:15,823 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-11-02 02:48:20,906 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-11-02 02:48:26,003 - DEBUG - VM is in ACTIVE state now 2026-11-02 02:48:26,003 - INFO - VM name : ctest-TestRoutersBasic-06269532-88180429 2026-11-02 02:48:26,092 - DEBUG - VM ctest-TestRoutersBasic-06269532-88180429 ID is 617dd680-5d9e-4a50-bf21-83c537f66a24 2026-11-02 02:48:26,125 - DEBUG - VM ctest-TestRoutersBasic-06269532-88180429 launched on Node an-jenkins-deploy-platform-ansible-os-6461-1 2026-11-02 02:48:26,207 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/617dd680-5d9e-4a50-bf21-83c537f66a24 2026-11-02 02:48:26,577 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/617dd680-5d9e-4a50-bf21-83c537f66a24 2026-11-02 02:48:26,612 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/720a2d7e-d439-4263-a427-38bf9e5e7c23 2026-11-02 02:48:29,788 - 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 1014ms') 2026-11-02 02:48:29,788 - DEBUG - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-06269532-88180429 failed! 2026-11-02 02:48:29,803 - DEBUG - Gateway for vn default-domain:ctest-TestRoutersBasic-06269532:ctest-vn-76222583 is 23.231.113.65 and allocation pool is NOT set 2026-11-02 02:48:31,875 - 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=6.80 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.543 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.543/3.672/6.802/3.129 ms') 2026-11-02 02:48:31,875 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-06269532-88180429 passed 2026-11-02 02:48:31,950 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-11-02 02:48:31,950 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-06269532-88180429, IP 23.231.113.67, Port 22 2026-11-02 02:48:32,121 - DEBUG - VM ctest-TestRoutersBasic-06269532-88180429 is ready for SSH connections 2026-11-02 02:48:32,121 - INFO - Waiting for VM ctest-TestRoutersBasic-06269532-16896288 to be up.. 2026-11-02 02:48:32,204 - DEBUG - VM is in ACTIVE state now 2026-11-02 02:48:32,204 - INFO - VM name : ctest-TestRoutersBasic-06269532-16896288 2026-11-02 02:48:32,290 - DEBUG - VM ctest-TestRoutersBasic-06269532-16896288 ID is 05d5ea81-b746-4ef1-9958-8a00c858a91f 2026-11-02 02:48:32,290 - DEBUG - VM ctest-TestRoutersBasic-06269532-16896288 launched on Node an-jenkins-deploy-platform-ansible-os-6461-2 2026-11-02 02:48:32,376 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/05d5ea81-b746-4ef1-9958-8a00c858a91f 2026-11-02 02:48:32,387 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/0e15df35-16a4-4ee8-a7d9-e590c99cca96 2026-11-02 02:48:33,552 - 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=5.83 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.455 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.455/3.141/5.827/2.686 ms') 2026-11-02 02:48:33,552 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestRoutersBasic-06269532-16896288 passed 2026-11-02 02:48:33,622 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-11-02 02:48:33,622 - DEBUG - Waiting to SSH to VM ctest-TestRoutersBasic-06269532-16896288, IP 135.101.183.196, Port 22 2026-11-02 02:48:33,789 - DEBUG - VM ctest-TestRoutersBasic-06269532-16896288 is ready for SSH connections 2026-11-02 02:48:33,790 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-11-02 02:48:33,790 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 135.101.183.196, host_string: cirros@169.254.0.5, password: cubswin:)gateway: ubuntu@10.0.0.161, gateway password: c0ntrail123 2026-11-02 02:48:33,790 - DEBUG - ping -s 56 -c 3 -W 1 135.101.183.196 2026-11-02 02:48:36,725 - DEBUG - PING 135.101.183.196 (135.101.183.196): 56 data bytes 64 bytes from 135.101.183.196: seq=0 ttl=62 time=7.438 ms 64 bytes from 135.101.183.196: seq=1 ttl=62 time=5.031 ms 64 bytes from 135.101.183.196: seq=2 ttl=62 time=1.155 ms --- 135.101.183.196 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.155/4.541/7.438 ms 2026-11-02 02:48:36,725 - INFO - Ping to IP 135.101.183.196 from VM ctest-TestRoutersBasic-06269532-88180429 passed 2026-11-02 02:48:36,725 - INFO - Deleting interface with subnet_id a36793dd-0100-4f3b-bf89-117ddb09256f, port_id None from router 58483c28-af59-47de-9735-80531f9bd8d3 2026-11-02 02:48:37,095 - INFO - Deleting VM ctest-TestRoutersBasic-06269532-16896288 2026-11-02 02:48:37,198 - INFO - Deleting VM ctest-TestRoutersBasic-06269532-88180429 2026-11-02 02:48:37,316 - INFO - Deleting VN ctest-ext_vn-06498728 2026-11-02 02:48:37,426 - DEBUG - VN 1abbf5d5-f736-4aef-82a0-f00781968647 still in use: Unable to complete operation on network 1abbf5d5-f736-4aef-82a0-f00781968647. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-6e7b5778-3594-4d10-b34f-8169d457b518'] 2026-11-02 02:48:37,426 - WARNING - Deleting VN ctest-ext_vn-06498728 failed..Will retry 2026-11-02 02:48:39,467 - DEBUG - VN 1abbf5d5-f736-4aef-82a0-f00781968647 still in use: Unable to complete operation on network 1abbf5d5-f736-4aef-82a0-f00781968647. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-0bce1ab1-cd45-4466-b86f-b3a1a92d6e43'] 2026-11-02 02:48:39,468 - WARNING - Deleting VN ctest-ext_vn-06498728 failed..Will retry 2026-11-02 02:48:41,650 - DEBUG - Response for deleting network () 2026-11-02 02:48:41,650 - INFO - Deleting VN ctest-vn-76222583 2026-11-02 02:48:41,873 - DEBUG - Response for deleting network () 2026-11-02 02:48:43,223 - DEBUG - No XMPP flaps were noticed during the test
2026-11-02 02:48:43,223 - INFO - END TEST : test_basic_snat_behavior_without_external_connectivity : PASSED[0:00:49]
2026-11-02 02:48:43,223 - INFO - -------------------------------------------------------------------------------- 2026-11-02 02:48:44,137 - INFO - Deleted project: ctest-TestRoutersBasic-06269532, ID : 139e80eb-ae85-4853-817d-35a05cf79dda