2026-01-20 10:28:50,431 - INFO - Domain Default found not creating 2026-01-20 10:28:50,608 - INFO - Project ctest-TestRouters-42597079 not found, creating it 2026-01-20 10:28:51,051 - INFO - Created Project:ctest-TestRouters-42597079, ID : 02b1e973-36c3-4104-8c6e-af5fd282a2fb 2026-01-20 10:28:52,466 - INFO - ================================================================================ 2026-01-20 10:28:52,466 - INFO - STARTING TEST : test_basic_router_behavior 2026-01-20 10:28:52,466 - 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-01-20 10:28:52,725 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.254': '0'}} with 2026-01-20 10:28:52,725 - INFO - Initial checks done. Running the testcase now 2026-01-20 10:28:52,725 - INFO - 2026-01-20 10:28:53,253 - DEBUG - Response for create_network : {'network': {'id': 'a24d5abb-5981-4903-9b97-6bb188823c4e', 'name': 'ctest-vn1-10922023', 'tenant_id': '02b1e97336c341048c6eaf5fd282a2fb', 'project_id': '02b1e97336c341048c6eaf5fd282a2fb', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-42597079', 'ctest-vn1-10922023'], 'port_security_enabled': True, 'description': ''}} 2026-01-20 10:28:53,473 - DEBUG - Response for create_subnet : {'subnet': {'id': '3b9f9544-7ea8-4d3f-ac18-a13614c580e7', 'name': '', 'tenant_id': '02b1e97336c341048c6eaf5fd282a2fb', 'network_id': 'a24d5abb-5981-4903-9b97-6bb188823c4e', 'ip_version': 4, 'cidr': '55.222.228.64/26', 'allocation_pools': [{'start': '55.222.228.66', 'end': '55.222.228.126'}], 'gateway_ip': '55.222.228.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '55.222.228.66', 'tags': [], 'project_id': '02b1e97336c341048c6eaf5fd282a2fb'}} 2026-01-20 10:28:53,489 - INFO - Created VN ctest-vn1-10922023 2026-01-20 10:28:53,540 - DEBUG - VN ctest-vn1-10922023 UUID is a24d5abb-5981-4903-9b97-6bb188823c4e 2026-01-20 10:28:53,909 - DEBUG - Response for create_network : {'network': {'id': '5ac74f77-e2ed-42bf-b321-ac7f41291be5', 'name': 'ctest-vn2-12115790', 'tenant_id': '02b1e97336c341048c6eaf5fd282a2fb', 'project_id': '02b1e97336c341048c6eaf5fd282a2fb', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-42597079', 'ctest-vn2-12115790'], 'port_security_enabled': True, 'description': ''}} 2026-01-20 10:28:54,129 - DEBUG - Response for create_subnet : {'subnet': {'id': '0feb83a0-e1ac-4eb2-922c-a3dab6a08955', 'name': '', 'tenant_id': '02b1e97336c341048c6eaf5fd282a2fb', 'network_id': '5ac74f77-e2ed-42bf-b321-ac7f41291be5', 'ip_version': 4, 'cidr': '150.24.234.192/26', 'allocation_pools': [{'start': '150.24.234.194', 'end': '150.24.234.254'}], 'gateway_ip': '150.24.234.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '150.24.234.194', 'tags': [], 'project_id': '02b1e97336c341048c6eaf5fd282a2fb'}} 2026-01-20 10:28:54,146 - INFO - Created VN ctest-vn2-12115790 2026-01-20 10:28:54,200 - DEBUG - VN ctest-vn2-12115790 UUID is 5ac74f77-e2ed-42bf-b321-ac7f41291be5 2026-01-20 10:28:54,399 - DEBUG - Services list from nova: [, , ] 2026-01-20 10:28:55,514 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5048-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5048-1) 2026-01-20 10:28:56,638 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5048-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5048-1) 2026-01-20 10:28:56,698 - INFO - Adding interface with subnet_id 3b9f9544-7ea8-4d3f-ac18-a13614c580e7, port_id None to router 9cd962ea-2c3b-4251-9506-0853f22a240a 2026-01-20 10:28:57,008 - INFO - Waiting for VM ctest-vn1-vm1-28098949 to be up.. 2026-01-20 10:28:57,126 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-20 10:29:02,200 - DEBUG - VM is in ACTIVE state now 2026-01-20 10:29:02,201 - INFO - VM name : ctest-vn1-vm1-28098949 2026-01-20 10:29:02,273 - DEBUG - VM ctest-vn1-vm1-28098949 ID is d0bab34b-7f02-4113-8979-79bc44bcbefd 2026-01-20 10:29:02,294 - DEBUG - VM ctest-vn1-vm1-28098949 launched on Node cn-jenkins-deploy-platform-ansible-os-5048-1 2026-01-20 10:29:02,363 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/d0bab34b-7f02-4113-8979-79bc44bcbefd 2026-01-20 10:29:02,662 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/d0bab34b-7f02-4113-8979-79bc44bcbefd 2026-01-20 10:29:02,696 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/850fd208-62a6-4385-9b4b-d02d742cc97b 2026-01-20 10:29:05,942 - 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 1008ms') 2026-01-20 10:29:05,942 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-28098949 failed! 2026-01-20 10:29:05,996 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-42597079:ctest-vn1-10922023 is 55.222.228.65 and allocation pool is NOT set 2026-01-20 10:29:08,053 - 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=3.21 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.372 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 = 0.372/1.790/3.209/1.418 ms') 2026-01-20 10:29:08,053 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-28098949 passed 2026-01-20 10:29:08,204 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-20 10:29:08,204 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-28098949, IP 55.222.228.67, Port 22 2026-01-20 10:29:08,356 - DEBUG - VM ctest-vn1-vm1-28098949 is ready for SSH connections 2026-01-20 10:29:08,356 - INFO - Waiting for VM ctest-vn2-vm1-54313900 to be up.. 2026-01-20 10:29:08,437 - DEBUG - VM is in ACTIVE state now 2026-01-20 10:29:08,437 - INFO - VM name : ctest-vn2-vm1-54313900 2026-01-20 10:29:08,506 - DEBUG - VM ctest-vn2-vm1-54313900 ID is 05dd801f-6736-4dc3-9c77-67a984c0bee6 2026-01-20 10:29:08,506 - DEBUG - VM ctest-vn2-vm1-54313900 launched on Node cn-jenkins-deploy-platform-ansible-os-5048-1 2026-01-20 10:29:08,569 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/05dd801f-6736-4dc3-9c77-67a984c0bee6 2026-01-20 10:29:08,577 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/95bd46eb-ded0-4b9d-8cb3-55fa6e1b5813 2026-01-20 10:29:09,812 - 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=2.84 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.495 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.495/1.669/2.843/1.174 ms') 2026-01-20 10:29:09,812 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-54313900 passed 2026-01-20 10:29:09,960 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-20 10:29:09,961 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-54313900, IP 150.24.234.195, Port 22 2026-01-20 10:29:10,119 - DEBUG - VM ctest-vn2-vm1-54313900 is ready for SSH connections 2026-01-20 10:29:10,120 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-20 10:29:10,120 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 150.24.234.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-01-20 10:29:10,120 - DEBUG - ping -s 56 -c 3 -W 1 150.24.234.195 2026-01-20 10:29:14,792 - DEBUG - PING 150.24.234.195 (150.24.234.195): 56 data bytes --- 150.24.234.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-01-20 10:29:14,793 - WARNING - Ping to IP 150.24.234.195 from VM ctest-vn1-vm1-28098949 failed 2026-01-20 10:29:14,793 - INFO - Adding interface with subnet_id 0feb83a0-e1ac-4eb2-922c-a3dab6a08955, port_id None to router 9cd962ea-2c3b-4251-9506-0853f22a240a 2026-01-20 10:29:15,050 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-20 10:29:15,050 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 150.24.234.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-01-20 10:29:15,050 - DEBUG - ping -s 56 -c 3 -W 1 150.24.234.195 2026-01-20 10:29:17,275 - DEBUG - PING 150.24.234.195 (150.24.234.195): 56 data bytes 64 bytes from 150.24.234.195: seq=0 ttl=63 time=2.559 ms 64 bytes from 150.24.234.195: seq=1 ttl=63 time=0.984 ms 64 bytes from 150.24.234.195: seq=2 ttl=63 time=0.819 ms --- 150.24.234.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.819/1.454/2.559 ms 2026-01-20 10:29:17,275 - INFO - Ping to IP 150.24.234.195 from VM ctest-vn1-vm1-28098949 passed 2026-01-20 10:29:17,276 - INFO - Deleting interface with subnet_id 3b9f9544-7ea8-4d3f-ac18-a13614c580e7, port_id None from router 9cd962ea-2c3b-4251-9506-0853f22a240a 2026-01-20 10:29:17,382 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-20 10:29:17,382 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 150.24.234.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-01-20 10:29:17,382 - DEBUG - ping -s 56 -c 3 -W 1 150.24.234.195 2026-01-20 10:29:20,583 - DEBUG - PING 150.24.234.195 (150.24.234.195): 56 data bytes --- 150.24.234.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-01-20 10:29:20,583 - WARNING - Ping to IP 150.24.234.195 from VM ctest-vn1-vm1-28098949 failed 2026-01-20 10:29:20,583 - INFO - Adding interface with subnet_id 3b9f9544-7ea8-4d3f-ac18-a13614c580e7, port_id None to router 9cd962ea-2c3b-4251-9506-0853f22a240a 2026-01-20 10:29:20,777 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-20 10:29:20,777 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 150.24.234.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-01-20 10:29:20,777 - DEBUG - ping -s 56 -c 3 -W 1 150.24.234.195 2026-01-20 10:29:22,983 - DEBUG - PING 150.24.234.195 (150.24.234.195): 56 data bytes 64 bytes from 150.24.234.195: seq=0 ttl=63 time=1.411 ms 64 bytes from 150.24.234.195: seq=1 ttl=63 time=0.974 ms 64 bytes from 150.24.234.195: seq=2 ttl=63 time=0.813 ms --- 150.24.234.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.813/1.066/1.411 ms 2026-01-20 10:29:22,984 - INFO - Ping to IP 150.24.234.195 from VM ctest-vn1-vm1-28098949 passed 2026-01-20 10:29:22,984 - INFO - Deleting interface with subnet_id 0feb83a0-e1ac-4eb2-922c-a3dab6a08955, port_id None from router 9cd962ea-2c3b-4251-9506-0853f22a240a 2026-01-20 10:29:23,089 - INFO - Deleting interface with subnet_id 3b9f9544-7ea8-4d3f-ac18-a13614c580e7, port_id None from router 9cd962ea-2c3b-4251-9506-0853f22a240a 2026-01-20 10:29:23,250 - INFO - Deleting VM ctest-vn2-vm1-54313900 2026-01-20 10:29:23,330 - INFO - Deleting VM ctest-vn1-vm1-28098949 2026-01-20 10:29:23,399 - INFO - Deleting VN ctest-vn2-12115790 2026-01-20 10:29:23,439 - DEBUG - VN 5ac74f77-e2ed-42bf-b321-ac7f41291be5 still in use: Unable to complete operation on network 5ac74f77-e2ed-42bf-b321-ac7f41291be5. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-88c363d7-49eb-4469-b329-cc9109950a53'] 2026-01-20 10:29:23,439 - WARNING - Deleting VN ctest-vn2-12115790 failed..Will retry 2026-01-20 10:29:25,538 - DEBUG - Response for deleting network () 2026-01-20 10:29:25,538 - INFO - Deleting VN ctest-vn1-10922023 2026-01-20 10:29:25,631 - DEBUG - Response for deleting network () 2026-01-20 10:29:25,885 - DEBUG - No XMPP flaps were noticed during the test
2026-01-20 10:29:25,885 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:33]
2026-01-20 10:29:25,885 - INFO - -------------------------------------------------------------------------------- 2026-01-20 10:29:26,552 - INFO - Deleted project: ctest-TestRouters-42597079, ID : 02b1e973-36c3-4104-8c6e-af5fd282a2fb