2026-03-23 17:44:05,245 - INFO - Domain Default found not creating 2026-03-23 17:44:05,459 - INFO - Project ctest-TestRouters-53611441 not found, creating it 2026-03-23 17:44:05,994 - INFO - Created Project:ctest-TestRouters-53611441, ID : 68b6c587-e4e1-450a-940b-d2f327a43e23 2026-03-23 17:44:07,762 - INFO - ================================================================================ 2026-03-23 17:44:07,762 - INFO - STARTING TEST : test_basic_router_behavior 2026-03-23 17:44:07,762 - 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-03-23 17:44:08,043 - DEBUG - Nothing to compare xmpp stats {'10.0.0.141': {'10.20.0.17': '0'}} with 2026-03-23 17:44:08,043 - INFO - Initial checks done. Running the testcase now 2026-03-23 17:44:08,043 - INFO - 2026-03-23 17:44:08,744 - DEBUG - Response for create_network : {'network': {'id': '350f8d02-e1a8-4085-b286-a16f123469e1', 'name': 'ctest-vn1-21545004', 'tenant_id': '68b6c587e4e1450a940bd2f327a43e23', 'project_id': '68b6c587e4e1450a940bd2f327a43e23', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-53611441', 'ctest-vn1-21545004'], 'port_security_enabled': True, 'description': ''}} 2026-03-23 17:44:08,980 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b8196884-1e2e-4b32-b7c9-bc12f2d7d211', 'name': '', 'tenant_id': '68b6c587e4e1450a940bd2f327a43e23', 'network_id': '350f8d02-e1a8-4085-b286-a16f123469e1', 'ip_version': 4, 'cidr': '192.151.75.128/26', 'allocation_pools': [{'start': '192.151.75.130', 'end': '192.151.75.190'}], 'gateway_ip': '192.151.75.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '192.151.75.130', 'tags': [], 'project_id': '68b6c587e4e1450a940bd2f327a43e23'}} 2026-03-23 17:44:08,998 - INFO - Created VN ctest-vn1-21545004 2026-03-23 17:44:09,052 - DEBUG - VN ctest-vn1-21545004 UUID is 350f8d02-e1a8-4085-b286-a16f123469e1 2026-03-23 17:44:09,413 - DEBUG - Response for create_network : {'network': {'id': '147e5d25-f14e-475f-b34a-2f135d0b128c', 'name': 'ctest-vn2-87030689', 'tenant_id': '68b6c587e4e1450a940bd2f327a43e23', 'project_id': '68b6c587e4e1450a940bd2f327a43e23', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-53611441', 'ctest-vn2-87030689'], 'port_security_enabled': True, 'description': ''}} 2026-03-23 17:44:09,649 - DEBUG - Response for create_subnet : {'subnet': {'id': '0e548a38-45b3-470b-88f2-330e642726b6', 'name': '', 'tenant_id': '68b6c587e4e1450a940bd2f327a43e23', 'network_id': '147e5d25-f14e-475f-b34a-2f135d0b128c', 'ip_version': 4, 'cidr': '120.33.182.64/26', 'allocation_pools': [{'start': '120.33.182.66', 'end': '120.33.182.126'}], 'gateway_ip': '120.33.182.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '120.33.182.66', 'tags': [], 'project_id': '68b6c587e4e1450a940bd2f327a43e23'}} 2026-03-23 17:44:09,668 - INFO - Created VN ctest-vn2-87030689 2026-03-23 17:44:09,720 - DEBUG - VN ctest-vn2-87030689 UUID is 147e5d25-f14e-475f-b34a-2f135d0b128c 2026-03-23 17:44:09,949 - DEBUG - Services list from nova: [, , ] 2026-03-23 17:44:11,378 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5522-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5522-1) 2026-03-23 17:44:12,710 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5522-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5522-1) 2026-03-23 17:44:12,803 - INFO - Adding interface with subnet_id b8196884-1e2e-4b32-b7c9-bc12f2d7d211, port_id None to router da585972-96f3-4072-99fa-c2392b91fa94 2026-03-23 17:44:13,040 - INFO - Waiting for VM ctest-vn1-vm1-68600565 to be up.. 2026-03-23 17:44:13,121 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-23 17:44:18,254 - DEBUG - VM is in ACTIVE state now 2026-03-23 17:44:18,254 - INFO - VM name : ctest-vn1-vm1-68600565 2026-03-23 17:44:18,366 - DEBUG - VM ctest-vn1-vm1-68600565 ID is 6bbedb4f-2f2e-47dd-8c09-82b20dcb3159 2026-03-23 17:44:18,387 - DEBUG - VM ctest-vn1-vm1-68600565 launched on Node cn-jenkins-deploy-platform-ansible-os-5522-1 2026-03-23 17:44:18,472 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/6bbedb4f-2f2e-47dd-8c09-82b20dcb3159 2026-03-23 17:44:18,823 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/6bbedb4f-2f2e-47dd-8c09-82b20dcb3159 2026-03-23 17:44:18,864 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/41913347-bcab-4d62-bbd9-a48fc699abd2 2026-03-23 17:44:22,154 - 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=2 ttl=63 time=6.31 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1030ms\r\nrtt min/avg/max/mdev = 6.314/6.314/6.314/0.000 ms') 2026-03-23 17:44:22,154 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-68600565 passed 2026-03-23 17:44:22,321 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 17:44:22,321 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-68600565, IP 192.151.75.131, Port 22 2026-03-23 17:44:22,506 - DEBUG - VM ctest-vn1-vm1-68600565 is ready for SSH connections 2026-03-23 17:44:22,506 - INFO - Waiting for VM ctest-vn2-vm1-04755928 to be up.. 2026-03-23 17:44:22,611 - DEBUG - VM is in ACTIVE state now 2026-03-23 17:44:22,611 - INFO - VM name : ctest-vn2-vm1-04755928 2026-03-23 17:44:22,722 - DEBUG - VM ctest-vn2-vm1-04755928 ID is 4d803b11-0725-432b-aaf9-86e9300d8768 2026-03-23 17:44:22,723 - DEBUG - VM ctest-vn2-vm1-04755928 launched on Node cn-jenkins-deploy-platform-ansible-os-5522-1 2026-03-23 17:44:22,834 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/4d803b11-0725-432b-aaf9-86e9300d8768 2026-03-23 17:44:22,848 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/180c5a3d-2504-4d32-accf-65aa990a27a2 2026-03-23 17:44:24,106 - 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=5.18 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.805 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.805/2.992/5.179/2.187 ms') 2026-03-23 17:44:24,107 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-04755928 passed 2026-03-23 17:44:24,277 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 17:44:24,277 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-04755928, IP 120.33.182.67, Port 22 2026-03-23 17:44:24,463 - DEBUG - VM ctest-vn2-vm1-04755928 is ready for SSH connections 2026-03-23 17:44:24,464 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 17:44:24,464 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 120.33.182.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2026-03-23 17:44:24,464 - DEBUG - ping -s 56 -c 3 -W 1 120.33.182.67 2026-03-23 17:44:29,565 - DEBUG - PING 120.33.182.67 (120.33.182.67): 56 data bytes --- 120.33.182.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-03-23 17:44:29,565 - WARNING - Ping to IP 120.33.182.67 from VM ctest-vn1-vm1-68600565 failed 2026-03-23 17:44:29,566 - INFO - Adding interface with subnet_id 0e548a38-45b3-470b-88f2-330e642726b6, port_id None to router da585972-96f3-4072-99fa-c2392b91fa94 2026-03-23 17:44:29,861 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 17:44:29,861 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 120.33.182.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2026-03-23 17:44:29,861 - DEBUG - ping -s 56 -c 3 -W 1 120.33.182.67 2026-03-23 17:44:32,990 - DEBUG - PING 120.33.182.67 (120.33.182.67): 56 data bytes 64 bytes from 120.33.182.67: seq=1 ttl=63 time=3.074 ms 64 bytes from 120.33.182.67: seq=2 ttl=63 time=1.281 ms --- 120.33.182.67 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.281/2.177/3.074 ms 2026-03-23 17:44:32,990 - WARNING - Ping to IP 120.33.182.67 from VM ctest-vn1-vm1-68600565 failed 2026-03-23 17:44:33,990 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 120.33.182.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2026-03-23 17:44:33,991 - DEBUG - ping -s 56 -c 3 -W 1 120.33.182.67 2026-03-23 17:44:36,124 - DEBUG - PING 120.33.182.67 (120.33.182.67): 56 data bytes 64 bytes from 120.33.182.67: seq=0 ttl=63 time=1.155 ms 64 bytes from 120.33.182.67: seq=1 ttl=63 time=1.422 ms 64 bytes from 120.33.182.67: seq=2 ttl=63 time=1.199 ms --- 120.33.182.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.155/1.258/1.422 ms 2026-03-23 17:44:36,124 - INFO - Ping to IP 120.33.182.67 from VM ctest-vn1-vm1-68600565 passed 2026-03-23 17:44:36,124 - INFO - Deleting interface with subnet_id b8196884-1e2e-4b32-b7c9-bc12f2d7d211, port_id None from router da585972-96f3-4072-99fa-c2392b91fa94 2026-03-23 17:44:36,286 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 17:44:36,286 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 120.33.182.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2026-03-23 17:44:36,286 - DEBUG - ping -s 56 -c 3 -W 1 120.33.182.67 2026-03-23 17:44:39,447 - DEBUG - PING 120.33.182.67 (120.33.182.67): 56 data bytes --- 120.33.182.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-03-23 17:44:39,447 - WARNING - Ping to IP 120.33.182.67 from VM ctest-vn1-vm1-68600565 failed 2026-03-23 17:44:39,447 - INFO - Adding interface with subnet_id b8196884-1e2e-4b32-b7c9-bc12f2d7d211, port_id None to router da585972-96f3-4072-99fa-c2392b91fa94 2026-03-23 17:44:39,747 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 17:44:39,747 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 120.33.182.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2026-03-23 17:44:39,747 - DEBUG - ping -s 56 -c 3 -W 1 120.33.182.67 2026-03-23 17:44:41,870 - DEBUG - PING 120.33.182.67 (120.33.182.67): 56 data bytes 64 bytes from 120.33.182.67: seq=0 ttl=63 time=1.682 ms 64 bytes from 120.33.182.67: seq=1 ttl=63 time=0.800 ms 64 bytes from 120.33.182.67: seq=2 ttl=63 time=1.326 ms --- 120.33.182.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.800/1.269/1.682 ms 2026-03-23 17:44:41,870 - INFO - Ping to IP 120.33.182.67 from VM ctest-vn1-vm1-68600565 passed 2026-03-23 17:44:41,870 - INFO - Deleting interface with subnet_id 0e548a38-45b3-470b-88f2-330e642726b6, port_id None from router da585972-96f3-4072-99fa-c2392b91fa94 2026-03-23 17:44:42,071 - INFO - Deleting interface with subnet_id b8196884-1e2e-4b32-b7c9-bc12f2d7d211, port_id None from router da585972-96f3-4072-99fa-c2392b91fa94 2026-03-23 17:44:42,244 - INFO - Deleting VM ctest-vn2-vm1-04755928 2026-03-23 17:44:42,324 - INFO - Deleting VM ctest-vn1-vm1-68600565 2026-03-23 17:44:42,421 - INFO - Deleting VN ctest-vn2-87030689 2026-03-23 17:44:42,468 - DEBUG - VN 147e5d25-f14e-475f-b34a-2f135d0b128c still in use: Unable to complete operation on network 147e5d25-f14e-475f-b34a-2f135d0b128c. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-e9480660-5e6b-4b8c-a396-98138be30012'] 2026-03-23 17:44:42,468 - WARNING - Deleting VN ctest-vn2-87030689 failed..Will retry 2026-03-23 17:44:44,624 - DEBUG - Response for deleting network () 2026-03-23 17:44:44,624 - INFO - Deleting VN ctest-vn1-21545004 2026-03-23 17:44:44,763 - DEBUG - Response for deleting network () 2026-03-23 17:44:45,059 - DEBUG - No XMPP flaps were noticed during the test
2026-03-23 17:44:45,059 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:38]
2026-03-23 17:44:45,059 - INFO - -------------------------------------------------------------------------------- 2026-03-23 17:44:45,857 - INFO - Deleted project: ctest-TestRouters-53611441, ID : 68b6c587-e4e1-450a-940b-d2f327a43e23