2026-05-14 13:37:03,601 - INFO - Domain Default found not creating 2026-05-14 13:37:03,788 - INFO - Project ctest-TestRouters-54967654 not found, creating it 2026-05-14 13:37:04,289 - INFO - Created Project:ctest-TestRouters-54967654, ID : 61e3b7fe-a728-42b2-b485-5c99a228a8fe 2026-05-14 13:37:05,905 - INFO - ================================================================================ 2026-05-14 13:37:05,906 - INFO - STARTING TEST : test_basic_router_behavior 2026-05-14 13:37:05,906 - INFO - TEST DESCRIPTION : Validate a router is able to route packets between two VNs Create a router Create 2 VNs, and a VM in each Add router port from each VN Ping between VMs 2026-05-14 13:37:06,167 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.254': '0'}} with 2026-05-14 13:37:06,167 - INFO - Initial checks done. Running the testcase now 2026-05-14 13:37:06,167 - INFO - 2026-05-14 13:37:06,824 - DEBUG - Response for create_network : {'network': {'id': '8a9af598-67c5-47a0-adce-ef5b1f896e64', 'name': 'ctest-vn1-75801875', 'tenant_id': '61e3b7fea72842b2b4855c99a228a8fe', 'project_id': '61e3b7fea72842b2b4855c99a228a8fe', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-54967654', 'ctest-vn1-75801875'], 'port_security_enabled': True, 'description': ''}} 2026-05-14 13:37:07,057 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c1998af3-b7f9-4b35-b932-1fcfde373b59', 'name': '', 'tenant_id': '61e3b7fea72842b2b4855c99a228a8fe', 'network_id': '8a9af598-67c5-47a0-adce-ef5b1f896e64', 'ip_version': 4, 'cidr': '205.0.232.192/26', 'allocation_pools': [{'start': '205.0.232.194', 'end': '205.0.232.254'}], 'gateway_ip': '205.0.232.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '205.0.232.194', 'tags': [], 'project_id': '61e3b7fea72842b2b4855c99a228a8fe'}} 2026-05-14 13:37:07,087 - INFO - Created VN ctest-vn1-75801875 2026-05-14 13:37:07,141 - DEBUG - VN ctest-vn1-75801875 UUID is 8a9af598-67c5-47a0-adce-ef5b1f896e64 2026-05-14 13:37:07,507 - DEBUG - Response for create_network : {'network': {'id': '7669159d-f68d-4b94-8f0d-7ad79734b213', 'name': 'ctest-vn2-37856873', 'tenant_id': '61e3b7fea72842b2b4855c99a228a8fe', 'project_id': '61e3b7fea72842b2b4855c99a228a8fe', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-54967654', 'ctest-vn2-37856873'], 'port_security_enabled': True, 'description': ''}} 2026-05-14 13:37:07,730 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bc6327b2-d69d-4ac2-b09f-deb889335a96', 'name': '', 'tenant_id': '61e3b7fea72842b2b4855c99a228a8fe', 'network_id': '7669159d-f68d-4b94-8f0d-7ad79734b213', 'ip_version': 4, 'cidr': '43.170.250.0/26', 'allocation_pools': [{'start': '43.170.250.2', 'end': '43.170.250.62'}], 'gateway_ip': '43.170.250.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '43.170.250.2', 'tags': [], 'project_id': '61e3b7fea72842b2b4855c99a228a8fe'}} 2026-05-14 13:37:07,750 - INFO - Created VN ctest-vn2-37856873 2026-05-14 13:37:07,805 - DEBUG - VN ctest-vn2-37856873 UUID is 7669159d-f68d-4b94-8f0d-7ad79734b213 2026-05-14 13:37:08,007 - DEBUG - Services list from nova: [, , ] 2026-05-14 13:37:09,096 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5784-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5784-1) 2026-05-14 13:37:10,447 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5784-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5784-1) 2026-05-14 13:37:10,522 - INFO - Adding interface with subnet_id c1998af3-b7f9-4b35-b932-1fcfde373b59, port_id None to router 8df316a3-a991-4aae-aa96-40613cc40d28 2026-05-14 13:37:10,843 - INFO - Waiting for VM ctest-vn1-vm1-63964536 to be up.. 2026-05-14 13:37:11,016 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-05-14 13:37:16,104 - DEBUG - VM is in ACTIVE state now 2026-05-14 13:37:16,104 - INFO - VM name : ctest-vn1-vm1-63964536 2026-05-14 13:37:16,189 - DEBUG - VM ctest-vn1-vm1-63964536 ID is 64625922-d76b-4128-9850-e5419ac4a4d3 2026-05-14 13:37:16,209 - DEBUG - VM ctest-vn1-vm1-63964536 launched on Node cn-jenkins-deploy-platform-ansible-os-5784-1 2026-05-14 13:37:16,305 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/64625922-d76b-4128-9850-e5419ac4a4d3 2026-05-14 13:37:16,610 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/64625922-d76b-4128-9850-e5419ac4a4d3 2026-05-14 13:37:16,648 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/148b3e9f-9622-426e-994b-a04e1bbe1283 2026-05-14 13:37:19,892 - 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 1002ms') 2026-05-14 13:37:19,892 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-63964536 failed! 2026-05-14 13:37:19,950 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-54967654:ctest-vn1-75801875 is 205.0.232.193 and allocation pool is NOT set 2026-05-14 13:37:22,024 - 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=4.90 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.12 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 = 2.121/3.511/4.901/1.390 ms') 2026-05-14 13:37:22,024 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-63964536 passed 2026-05-14 13:37:22,173 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-14 13:37:22,174 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-63964536, IP 205.0.232.195, Port 22 2026-05-14 13:37:22,340 - DEBUG - VM ctest-vn1-vm1-63964536 is ready for SSH connections 2026-05-14 13:37:22,340 - INFO - Waiting for VM ctest-vn2-vm1-54847632 to be up.. 2026-05-14 13:37:22,424 - DEBUG - VM is in ACTIVE state now 2026-05-14 13:37:22,424 - INFO - VM name : ctest-vn2-vm1-54847632 2026-05-14 13:37:22,509 - DEBUG - VM ctest-vn2-vm1-54847632 ID is e78610f1-47c0-419f-85c3-9b53918d5d8c 2026-05-14 13:37:22,509 - DEBUG - VM ctest-vn2-vm1-54847632 launched on Node cn-jenkins-deploy-platform-ansible-os-5784-1 2026-05-14 13:37:22,594 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/e78610f1-47c0-419f-85c3-9b53918d5d8c 2026-05-14 13:37:22,602 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/af7638c8-4da4-4e66-8d35-5cb7b3a5ab37 2026-05-14 13:37:23,841 - 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=3.21 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.917 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.917/2.064/3.211/1.147 ms') 2026-05-14 13:37:23,841 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-54847632 passed 2026-05-14 13:37:23,993 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-14 13:37:23,994 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-54847632, IP 43.170.250.3, Port 22 2026-05-14 13:37:24,148 - DEBUG - VM ctest-vn2-vm1-54847632 is ready for SSH connections 2026-05-14 13:37:24,149 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-14 13:37:24,149 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 43.170.250.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-05-14 13:37:24,149 - DEBUG - ping -s 56 -c 3 -W 1 43.170.250.3 2026-05-14 13:37:29,212 - DEBUG - PING 43.170.250.3 (43.170.250.3): 56 data bytes --- 43.170.250.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-05-14 13:37:29,212 - WARNING - Ping to IP 43.170.250.3 from VM ctest-vn1-vm1-63964536 failed 2026-05-14 13:37:29,212 - INFO - Adding interface with subnet_id bc6327b2-d69d-4ac2-b09f-deb889335a96, port_id None to router 8df316a3-a991-4aae-aa96-40613cc40d28 2026-05-14 13:37:29,462 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-14 13:37:29,462 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 43.170.250.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-05-14 13:37:29,462 - DEBUG - ping -s 56 -c 3 -W 1 43.170.250.3 2026-05-14 13:37:32,683 - DEBUG - PING 43.170.250.3 (43.170.250.3): 56 data bytes 64 bytes from 43.170.250.3: seq=1 ttl=63 time=3.624 ms 64 bytes from 43.170.250.3: seq=2 ttl=63 time=0.997 ms --- 43.170.250.3 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 0.997/2.310/3.624 ms 2026-05-14 13:37:32,683 - WARNING - Ping to IP 43.170.250.3 from VM ctest-vn1-vm1-63964536 failed 2026-05-14 13:37:33,684 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 43.170.250.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-05-14 13:37:33,684 - DEBUG - ping -s 56 -c 3 -W 1 43.170.250.3 2026-05-14 13:37:35,909 - DEBUG - PING 43.170.250.3 (43.170.250.3): 56 data bytes 64 bytes from 43.170.250.3: seq=0 ttl=63 time=1.630 ms 64 bytes from 43.170.250.3: seq=1 ttl=63 time=1.002 ms 64 bytes from 43.170.250.3: seq=2 ttl=63 time=0.899 ms --- 43.170.250.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.899/1.177/1.630 ms 2026-05-14 13:37:35,909 - INFO - Ping to IP 43.170.250.3 from VM ctest-vn1-vm1-63964536 passed 2026-05-14 13:37:35,909 - INFO - Deleting interface with subnet_id c1998af3-b7f9-4b35-b932-1fcfde373b59, port_id None from router 8df316a3-a991-4aae-aa96-40613cc40d28 2026-05-14 13:37:36,032 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-14 13:37:36,032 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 43.170.250.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-05-14 13:37:36,032 - DEBUG - ping -s 56 -c 3 -W 1 43.170.250.3 2026-05-14 13:37:39,244 - DEBUG - PING 43.170.250.3 (43.170.250.3): 56 data bytes --- 43.170.250.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-05-14 13:37:39,244 - WARNING - Ping to IP 43.170.250.3 from VM ctest-vn1-vm1-63964536 failed 2026-05-14 13:37:39,244 - INFO - Adding interface with subnet_id c1998af3-b7f9-4b35-b932-1fcfde373b59, port_id None to router 8df316a3-a991-4aae-aa96-40613cc40d28 2026-05-14 13:37:39,472 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-14 13:37:39,472 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 43.170.250.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-05-14 13:37:39,472 - DEBUG - ping -s 56 -c 3 -W 1 43.170.250.3 2026-05-14 13:37:41,684 - DEBUG - PING 43.170.250.3 (43.170.250.3): 56 data bytes 64 bytes from 43.170.250.3: seq=0 ttl=63 time=1.500 ms 64 bytes from 43.170.250.3: seq=1 ttl=63 time=1.016 ms 64 bytes from 43.170.250.3: seq=2 ttl=63 time=1.017 ms --- 43.170.250.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.016/1.177/1.500 ms 2026-05-14 13:37:41,684 - INFO - Ping to IP 43.170.250.3 from VM ctest-vn1-vm1-63964536 passed 2026-05-14 13:37:41,684 - INFO - Deleting interface with subnet_id bc6327b2-d69d-4ac2-b09f-deb889335a96, port_id None from router 8df316a3-a991-4aae-aa96-40613cc40d28 2026-05-14 13:37:41,811 - INFO - Deleting interface with subnet_id c1998af3-b7f9-4b35-b932-1fcfde373b59, port_id None from router 8df316a3-a991-4aae-aa96-40613cc40d28 2026-05-14 13:37:42,035 - INFO - Deleting VM ctest-vn2-vm1-54847632 2026-05-14 13:37:42,122 - INFO - Deleting VM ctest-vn1-vm1-63964536 2026-05-14 13:37:42,212 - INFO - Deleting VN ctest-vn2-37856873 2026-05-14 13:37:42,255 - DEBUG - VN 7669159d-f68d-4b94-8f0d-7ad79734b213 still in use: Unable to complete operation on network 7669159d-f68d-4b94-8f0d-7ad79734b213. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-733de7e6-9608-4690-a862-105dd1a0373b'] 2026-05-14 13:37:42,255 - WARNING - Deleting VN ctest-vn2-37856873 failed..Will retry 2026-05-14 13:37:44,377 - DEBUG - Response for deleting network () 2026-05-14 13:37:44,377 - INFO - Deleting VN ctest-vn1-75801875 2026-05-14 13:37:44,496 - DEBUG - Response for deleting network () 2026-05-14 13:37:44,751 - DEBUG - No XMPP flaps were noticed during the test
2026-05-14 13:37:44,751 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:39]
2026-05-14 13:37:44,751 - INFO - -------------------------------------------------------------------------------- 2026-05-14 13:37:45,451 - INFO - Deleted project: ctest-TestRouters-54967654, ID : 61e3b7fe-a728-42b2-b485-5c99a228a8fe