2026-05-28 16:51:37,657 - INFO - Domain Default found not creating 2026-05-28 16:51:37,861 - INFO - Project ctest-TestRouters-75355911 not found, creating it 2026-05-28 16:51:38,408 - INFO - Created Project:ctest-TestRouters-75355911, ID : 453a57d2-17c2-4a38-b674-7935d1ac44db 2026-05-28 16:51:40,201 - INFO - ================================================================================ 2026-05-28 16:51:40,201 - INFO - STARTING TEST : test_basic_router_behavior 2026-05-28 16:51:40,201 - 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-28 16:51:40,479 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.18': '0'}} with 2026-05-28 16:51:40,480 - INFO - Initial checks done. Running the testcase now 2026-05-28 16:51:40,480 - INFO - 2026-05-28 16:51:41,182 - DEBUG - Response for create_network : {'network': {'id': '6f584edb-eb62-485d-82bd-b47874185f0e', 'name': 'ctest-vn1-68794162', 'tenant_id': '453a57d217c24a38b6747935d1ac44db', 'project_id': '453a57d217c24a38b6747935d1ac44db', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-75355911', 'ctest-vn1-68794162'], 'port_security_enabled': True, 'description': ''}} 2026-05-28 16:51:41,418 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a8718893-da9f-4a83-acd6-d912ce1da576', 'name': '', 'tenant_id': '453a57d217c24a38b6747935d1ac44db', 'network_id': '6f584edb-eb62-485d-82bd-b47874185f0e', 'ip_version': 4, 'cidr': '204.48.42.0/26', 'allocation_pools': [{'start': '204.48.42.2', 'end': '204.48.42.62'}], 'gateway_ip': '204.48.42.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '204.48.42.2', 'tags': [], 'project_id': '453a57d217c24a38b6747935d1ac44db'}} 2026-05-28 16:51:41,439 - INFO - Created VN ctest-vn1-68794162 2026-05-28 16:51:41,497 - DEBUG - VN ctest-vn1-68794162 UUID is 6f584edb-eb62-485d-82bd-b47874185f0e 2026-05-28 16:51:41,834 - DEBUG - Response for create_network : {'network': {'id': 'e5aa0daf-e299-4a23-a8e1-4ec4977ceaa8', 'name': 'ctest-vn2-89779636', 'tenant_id': '453a57d217c24a38b6747935d1ac44db', 'project_id': '453a57d217c24a38b6747935d1ac44db', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-75355911', 'ctest-vn2-89779636'], 'port_security_enabled': True, 'description': ''}} 2026-05-28 16:51:42,090 - DEBUG - Response for create_subnet : {'subnet': {'id': '3574589e-61e0-48af-b58b-085cd3877c37', 'name': '', 'tenant_id': '453a57d217c24a38b6747935d1ac44db', 'network_id': 'e5aa0daf-e299-4a23-a8e1-4ec4977ceaa8', 'ip_version': 4, 'cidr': '115.181.172.192/26', 'allocation_pools': [{'start': '115.181.172.194', 'end': '115.181.172.254'}], 'gateway_ip': '115.181.172.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '115.181.172.194', 'tags': [], 'project_id': '453a57d217c24a38b6747935d1ac44db'}} 2026-05-28 16:51:42,116 - INFO - Created VN ctest-vn2-89779636 2026-05-28 16:51:42,169 - DEBUG - VN ctest-vn2-89779636 UUID is e5aa0daf-e299-4a23-a8e1-4ec4977ceaa8 2026-05-28 16:51:42,374 - DEBUG - Services list from nova: [, , ] 2026-05-28 16:51:43,629 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5847-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5847-1) 2026-05-28 16:51:44,937 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5847-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5847-1) 2026-05-28 16:51:45,035 - INFO - Adding interface with subnet_id a8718893-da9f-4a83-acd6-d912ce1da576, port_id None to router 1b4feaa9-1714-41b7-87f7-44c694c75032 2026-05-28 16:51:45,402 - INFO - Waiting for VM ctest-vn1-vm1-61235856 to be up.. 2026-05-28 16:51:45,526 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-05-28 16:51:50,617 - DEBUG - VM is in ACTIVE state now 2026-05-28 16:51:50,617 - INFO - VM name : ctest-vn1-vm1-61235856 2026-05-28 16:51:50,702 - DEBUG - VM ctest-vn1-vm1-61235856 ID is 50e0274a-88da-4fc3-8d63-3824ab265a5f 2026-05-28 16:51:50,732 - DEBUG - VM ctest-vn1-vm1-61235856 launched on Node cn-jenkins-deploy-platform-ansible-os-5847-1 2026-05-28 16:51:50,829 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/50e0274a-88da-4fc3-8d63-3824ab265a5f 2026-05-28 16:51:51,137 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/50e0274a-88da-4fc3-8d63-3824ab265a5f 2026-05-28 16:51:51,184 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/745acf4e-bdfc-40b5-8b60-57ca45622531 2026-05-28 16:51:54,436 - 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 1000ms') 2026-05-28 16:51:54,436 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-61235856 failed! 2026-05-28 16:51:54,493 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-75355911:ctest-vn1-68794162 is 204.48.42.1 and allocation pool is NOT set 2026-05-28 16:51:56,561 - 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.78 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.65 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.645/3.213/4.782/1.568 ms') 2026-05-28 16:51:56,561 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-61235856 passed 2026-05-28 16:51:56,717 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-28 16:51:56,717 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-61235856, IP 204.48.42.3, Port 22 2026-05-28 16:51:56,878 - DEBUG - VM ctest-vn1-vm1-61235856 is ready for SSH connections 2026-05-28 16:51:56,879 - INFO - Waiting for VM ctest-vn2-vm1-71744624 to be up.. 2026-05-28 16:51:56,968 - DEBUG - VM is in ACTIVE state now 2026-05-28 16:51:56,968 - INFO - VM name : ctest-vn2-vm1-71744624 2026-05-28 16:51:57,058 - DEBUG - VM ctest-vn2-vm1-71744624 ID is ea5288d5-4e75-4de4-87c3-9a7088ec0e55 2026-05-28 16:51:57,059 - DEBUG - VM ctest-vn2-vm1-71744624 launched on Node cn-jenkins-deploy-platform-ansible-os-5847-1 2026-05-28 16:51:57,154 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/ea5288d5-4e75-4de4-87c3-9a7088ec0e55 2026-05-28 16:51:57,163 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/e678755e-4c2d-4e57-a651-09bebf3a58af 2026-05-28 16:51:58,411 - 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.91 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.544 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.544/2.228/3.913/1.684 ms') 2026-05-28 16:51:58,411 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-71744624 passed 2026-05-28 16:51:58,565 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-28 16:51:58,566 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-71744624, IP 115.181.172.195, Port 22 2026-05-28 16:51:58,735 - DEBUG - VM ctest-vn2-vm1-71744624 is ready for SSH connections 2026-05-28 16:51:58,735 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-28 16:51:58,735 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 115.181.172.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-05-28 16:51:58,735 - DEBUG - ping -s 56 -c 3 -W 1 115.181.172.195 2026-05-28 16:52:03,861 - DEBUG - PING 115.181.172.195 (115.181.172.195): 56 data bytes --- 115.181.172.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-05-28 16:52:03,861 - WARNING - Ping to IP 115.181.172.195 from VM ctest-vn1-vm1-61235856 failed 2026-05-28 16:52:03,861 - INFO - Adding interface with subnet_id 3574589e-61e0-48af-b58b-085cd3877c37, port_id None to router 1b4feaa9-1714-41b7-87f7-44c694c75032 2026-05-28 16:52:04,141 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-28 16:52:04,142 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 115.181.172.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-05-28 16:52:04,142 - DEBUG - ping -s 56 -c 3 -W 1 115.181.172.195 2026-05-28 16:52:07,419 - DEBUG - PING 115.181.172.195 (115.181.172.195): 56 data bytes 64 bytes from 115.181.172.195: seq=1 ttl=63 time=4.937 ms 64 bytes from 115.181.172.195: seq=2 ttl=63 time=1.266 ms --- 115.181.172.195 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.266/3.101/4.937 ms 2026-05-28 16:52:07,419 - WARNING - Ping to IP 115.181.172.195 from VM ctest-vn1-vm1-61235856 failed 2026-05-28 16:52:08,420 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 115.181.172.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-05-28 16:52:08,420 - DEBUG - ping -s 56 -c 3 -W 1 115.181.172.195 2026-05-28 16:52:10,694 - DEBUG - PING 115.181.172.195 (115.181.172.195): 56 data bytes 64 bytes from 115.181.172.195: seq=0 ttl=63 time=1.591 ms 64 bytes from 115.181.172.195: seq=1 ttl=63 time=1.886 ms 64 bytes from 115.181.172.195: seq=2 ttl=63 time=1.359 ms --- 115.181.172.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.359/1.612/1.886 ms 2026-05-28 16:52:10,694 - INFO - Ping to IP 115.181.172.195 from VM ctest-vn1-vm1-61235856 passed 2026-05-28 16:52:10,694 - INFO - Deleting interface with subnet_id a8718893-da9f-4a83-acd6-d912ce1da576, port_id None from router 1b4feaa9-1714-41b7-87f7-44c694c75032 2026-05-28 16:52:10,906 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-28 16:52:10,906 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 115.181.172.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-05-28 16:52:10,906 - DEBUG - ping -s 56 -c 3 -W 1 115.181.172.195 2026-05-28 16:52:14,171 - DEBUG - PING 115.181.172.195 (115.181.172.195): 56 data bytes --- 115.181.172.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-05-28 16:52:14,172 - WARNING - Ping to IP 115.181.172.195 from VM ctest-vn1-vm1-61235856 failed 2026-05-28 16:52:14,172 - INFO - Adding interface with subnet_id a8718893-da9f-4a83-acd6-d912ce1da576, port_id None to router 1b4feaa9-1714-41b7-87f7-44c694c75032 2026-05-28 16:52:14,432 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-05-28 16:52:14,432 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 115.181.172.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-05-28 16:52:14,432 - DEBUG - ping -s 56 -c 3 -W 1 115.181.172.195 2026-05-28 16:52:16,660 - DEBUG - PING 115.181.172.195 (115.181.172.195): 56 data bytes 64 bytes from 115.181.172.195: seq=0 ttl=63 time=1.899 ms 64 bytes from 115.181.172.195: seq=1 ttl=63 time=0.901 ms 64 bytes from 115.181.172.195: seq=2 ttl=63 time=1.214 ms --- 115.181.172.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.901/1.338/1.899 ms 2026-05-28 16:52:16,660 - INFO - Ping to IP 115.181.172.195 from VM ctest-vn1-vm1-61235856 passed 2026-05-28 16:52:16,660 - INFO - Deleting interface with subnet_id 3574589e-61e0-48af-b58b-085cd3877c37, port_id None from router 1b4feaa9-1714-41b7-87f7-44c694c75032 2026-05-28 16:52:16,823 - INFO - Deleting interface with subnet_id a8718893-da9f-4a83-acd6-d912ce1da576, port_id None from router 1b4feaa9-1714-41b7-87f7-44c694c75032 2026-05-28 16:52:17,062 - INFO - Deleting VM ctest-vn2-vm1-71744624 2026-05-28 16:52:17,147 - INFO - Deleting VM ctest-vn1-vm1-61235856 2026-05-28 16:52:17,261 - INFO - Deleting VN ctest-vn2-89779636 2026-05-28 16:52:17,306 - DEBUG - VN e5aa0daf-e299-4a23-a8e1-4ec4977ceaa8 still in use: Unable to complete operation on network e5aa0daf-e299-4a23-a8e1-4ec4977ceaa8. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-bc448b2f-f25c-456e-818c-0ec2169997af'] 2026-05-28 16:52:17,306 - WARNING - Deleting VN ctest-vn2-89779636 failed..Will retry 2026-05-28 16:52:19,431 - DEBUG - Response for deleting network () 2026-05-28 16:52:19,431 - INFO - Deleting VN ctest-vn1-68794162 2026-05-28 16:52:19,547 - DEBUG - Response for deleting network () 2026-05-28 16:52:19,810 - DEBUG - No XMPP flaps were noticed during the test
2026-05-28 16:52:19,810 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:39]
2026-05-28 16:52:19,810 - INFO - -------------------------------------------------------------------------------- 2026-05-28 16:52:20,526 - INFO - Deleted project: ctest-TestRouters-75355911, ID : 453a57d2-17c2-4a38-b674-7935d1ac44db