2026-02-11 14:14:10,627 - INFO - Domain Default found not creating 2026-02-11 14:14:10,823 - INFO - Project ctest-TestRouters-06252064 not found, creating it 2026-02-11 14:14:11,334 - INFO - Created Project:ctest-TestRouters-06252064, ID : 3d145847-702c-4d9e-b40e-d35b63a2528e 2026-02-11 14:14:12,970 - INFO - ================================================================================ 2026-02-11 14:14:12,970 - INFO - STARTING TEST : test_basic_router_behavior 2026-02-11 14:14:12,970 - 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-02-11 14:14:13,226 - DEBUG - Nothing to compare xmpp stats {'10.0.0.128': {'10.20.0.254': '0'}} with 2026-02-11 14:14:13,226 - INFO - Initial checks done. Running the testcase now 2026-02-11 14:14:13,226 - INFO - 2026-02-11 14:14:13,825 - DEBUG - Response for create_network : {'network': {'id': '9e49af41-f011-4a62-a565-c872a034895b', 'name': 'ctest-vn1-20865577', 'tenant_id': '3d145847702c4d9eb40ed35b63a2528e', 'project_id': '3d145847702c4d9eb40ed35b63a2528e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-06252064', 'ctest-vn1-20865577'], 'port_security_enabled': True, 'description': ''}} 2026-02-11 14:14:14,035 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b711ba99-bbf5-49cb-8e05-e6fa7eec4b89', 'name': '', 'tenant_id': '3d145847702c4d9eb40ed35b63a2528e', 'network_id': '9e49af41-f011-4a62-a565-c872a034895b', 'ip_version': 4, 'cidr': '196.65.170.0/26', 'allocation_pools': [{'start': '196.65.170.2', 'end': '196.65.170.62'}], 'gateway_ip': '196.65.170.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '196.65.170.2', 'tags': [], 'project_id': '3d145847702c4d9eb40ed35b63a2528e'}} 2026-02-11 14:14:14,058 - INFO - Created VN ctest-vn1-20865577 2026-02-11 14:14:14,112 - DEBUG - VN ctest-vn1-20865577 UUID is 9e49af41-f011-4a62-a565-c872a034895b 2026-02-11 14:14:14,456 - DEBUG - Response for create_network : {'network': {'id': '8667f10e-c2ac-4e61-891b-fc78fc21af00', 'name': 'ctest-vn2-23543413', 'tenant_id': '3d145847702c4d9eb40ed35b63a2528e', 'project_id': '3d145847702c4d9eb40ed35b63a2528e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-06252064', 'ctest-vn2-23543413'], 'port_security_enabled': True, 'description': ''}} 2026-02-11 14:14:14,723 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f93b1093-bb04-42c2-998f-5226066362c1', 'name': '', 'tenant_id': '3d145847702c4d9eb40ed35b63a2528e', 'network_id': '8667f10e-c2ac-4e61-891b-fc78fc21af00', 'ip_version': 4, 'cidr': '111.158.25.64/26', 'allocation_pools': [{'start': '111.158.25.66', 'end': '111.158.25.126'}], 'gateway_ip': '111.158.25.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '111.158.25.66', 'tags': [], 'project_id': '3d145847702c4d9eb40ed35b63a2528e'}} 2026-02-11 14:14:14,742 - INFO - Created VN ctest-vn2-23543413 2026-02-11 14:14:14,800 - DEBUG - VN ctest-vn2-23543413 UUID is 8667f10e-c2ac-4e61-891b-fc78fc21af00 2026-02-11 14:14:14,978 - DEBUG - Services list from nova: [, , ] 2026-02-11 14:14:16,036 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5294-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5294-1) 2026-02-11 14:14:17,338 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5294-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5294-1) 2026-02-11 14:14:17,441 - INFO - Adding interface with subnet_id b711ba99-bbf5-49cb-8e05-e6fa7eec4b89, port_id None to router 3730f8c5-d471-4df2-8cec-2991ca9401d3 2026-02-11 14:14:17,752 - INFO - Waiting for VM ctest-vn1-vm1-06403475 to be up.. 2026-02-11 14:14:17,873 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-02-11 14:14:22,964 - DEBUG - VM is in ACTIVE state now 2026-02-11 14:14:22,964 - INFO - VM name : ctest-vn1-vm1-06403475 2026-02-11 14:14:23,052 - DEBUG - VM ctest-vn1-vm1-06403475 ID is ab401da2-4517-4db1-8e53-53392ab30632 2026-02-11 14:14:23,076 - DEBUG - VM ctest-vn1-vm1-06403475 launched on Node cn-jenkins-deploy-platform-ansible-os-5294-1 2026-02-11 14:14:23,156 - DEBUG - Requesting: http://10.0.0.128:8082/virtual-machine/ab401da2-4517-4db1-8e53-53392ab30632 2026-02-11 14:14:23,471 - DEBUG - Requesting: http://10.0.0.128:8082/virtual-machine/ab401da2-4517-4db1-8e53-53392ab30632 2026-02-11 14:14:23,511 - DEBUG - Requesting: http://10.0.0.128:8082/virtual-machine-interface/b64eada1-36ee-4403-92e6-621bddf001bf 2026-02-11 14:14:26,761 - 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 1010ms') 2026-02-11 14:14:26,761 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-06403475 failed! 2026-02-11 14:14:26,816 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-06252064:ctest-vn1-20865577 is 196.65.170.1 and allocation pool is NOT set 2026-02-11 14:14:28,878 - 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.94 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.07 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 = 1.074/2.507/3.940/1.433 ms') 2026-02-11 14:14:28,878 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-06403475 passed 2026-02-11 14:14:29,048 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 14:14:29,048 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-06403475, IP 196.65.170.3, Port 22 2026-02-11 14:14:29,214 - DEBUG - VM ctest-vn1-vm1-06403475 is ready for SSH connections 2026-02-11 14:14:29,214 - INFO - Waiting for VM ctest-vn2-vm1-01034355 to be up.. 2026-02-11 14:14:29,302 - DEBUG - VM is in ACTIVE state now 2026-02-11 14:14:29,303 - INFO - VM name : ctest-vn2-vm1-01034355 2026-02-11 14:14:29,386 - DEBUG - VM ctest-vn2-vm1-01034355 ID is 5f5ce47c-ece2-4ea0-87b0-b41dc5eb0b09 2026-02-11 14:14:29,386 - DEBUG - VM ctest-vn2-vm1-01034355 launched on Node cn-jenkins-deploy-platform-ansible-os-5294-1 2026-02-11 14:14:29,466 - DEBUG - Requesting: http://10.0.0.128:8082/virtual-machine/5f5ce47c-ece2-4ea0-87b0-b41dc5eb0b09 2026-02-11 14:14:29,475 - DEBUG - Requesting: http://10.0.0.128:8082/virtual-machine-interface/c1348742-40e0-4086-8e4d-d7057790497a 2026-02-11 14:14:30,720 - 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.24 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.63 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 = 2.625/2.933/3.242/0.308 ms') 2026-02-11 14:14:30,720 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-01034355 passed 2026-02-11 14:14:30,872 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 14:14:30,872 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-01034355, IP 111.158.25.67, Port 22 2026-02-11 14:14:31,028 - DEBUG - VM ctest-vn2-vm1-01034355 is ready for SSH connections 2026-02-11 14:14:31,028 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 14:14:31,028 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 111.158.25.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.128, gateway password: c0ntrail123 2026-02-11 14:14:31,028 - DEBUG - ping -s 56 -c 3 -W 1 111.158.25.67 2026-02-11 14:14:36,243 - DEBUG - PING 111.158.25.67 (111.158.25.67): 56 data bytes --- 111.158.25.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-02-11 14:14:36,244 - WARNING - Ping to IP 111.158.25.67 from VM ctest-vn1-vm1-06403475 failed 2026-02-11 14:14:36,244 - INFO - Adding interface with subnet_id f93b1093-bb04-42c2-998f-5226066362c1, port_id None to router 3730f8c5-d471-4df2-8cec-2991ca9401d3 2026-02-11 14:14:36,520 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 14:14:36,521 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 111.158.25.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.128, gateway password: c0ntrail123 2026-02-11 14:14:36,521 - DEBUG - ping -s 56 -c 3 -W 1 111.158.25.67 2026-02-11 14:14:39,741 - DEBUG - PING 111.158.25.67 (111.158.25.67): 56 data bytes 64 bytes from 111.158.25.67: seq=1 ttl=63 time=2.752 ms 64 bytes from 111.158.25.67: seq=2 ttl=63 time=1.145 ms --- 111.158.25.67 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.145/1.948/2.752 ms 2026-02-11 14:14:39,741 - WARNING - Ping to IP 111.158.25.67 from VM ctest-vn1-vm1-06403475 failed 2026-02-11 14:14:40,742 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 111.158.25.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.128, gateway password: c0ntrail123 2026-02-11 14:14:40,742 - DEBUG - ping -s 56 -c 3 -W 1 111.158.25.67 2026-02-11 14:14:42,979 - DEBUG - PING 111.158.25.67 (111.158.25.67): 56 data bytes 64 bytes from 111.158.25.67: seq=0 ttl=63 time=1.735 ms 64 bytes from 111.158.25.67: seq=1 ttl=63 time=1.122 ms 64 bytes from 111.158.25.67: seq=2 ttl=63 time=0.983 ms --- 111.158.25.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.983/1.280/1.735 ms 2026-02-11 14:14:42,979 - INFO - Ping to IP 111.158.25.67 from VM ctest-vn1-vm1-06403475 passed 2026-02-11 14:14:42,979 - INFO - Deleting interface with subnet_id b711ba99-bbf5-49cb-8e05-e6fa7eec4b89, port_id None from router 3730f8c5-d471-4df2-8cec-2991ca9401d3 2026-02-11 14:14:43,115 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 14:14:43,116 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 111.158.25.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.128, gateway password: c0ntrail123 2026-02-11 14:14:43,116 - DEBUG - ping -s 56 -c 3 -W 1 111.158.25.67 2026-02-11 14:14:46,401 - DEBUG - PING 111.158.25.67 (111.158.25.67): 56 data bytes --- 111.158.25.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-02-11 14:14:46,402 - WARNING - Ping to IP 111.158.25.67 from VM ctest-vn1-vm1-06403475 failed 2026-02-11 14:14:46,402 - INFO - Adding interface with subnet_id b711ba99-bbf5-49cb-8e05-e6fa7eec4b89, port_id None to router 3730f8c5-d471-4df2-8cec-2991ca9401d3 2026-02-11 14:14:46,645 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-11 14:14:46,645 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 111.158.25.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.128, gateway password: c0ntrail123 2026-02-11 14:14:46,645 - DEBUG - ping -s 56 -c 3 -W 1 111.158.25.67 2026-02-11 14:14:48,878 - DEBUG - PING 111.158.25.67 (111.158.25.67): 56 data bytes 64 bytes from 111.158.25.67: seq=0 ttl=63 time=1.689 ms 64 bytes from 111.158.25.67: seq=1 ttl=63 time=1.114 ms 64 bytes from 111.158.25.67: seq=2 ttl=63 time=1.148 ms --- 111.158.25.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.114/1.317/1.689 ms 2026-02-11 14:14:48,878 - INFO - Ping to IP 111.158.25.67 from VM ctest-vn1-vm1-06403475 passed 2026-02-11 14:14:48,878 - INFO - Deleting interface with subnet_id f93b1093-bb04-42c2-998f-5226066362c1, port_id None from router 3730f8c5-d471-4df2-8cec-2991ca9401d3 2026-02-11 14:14:49,018 - INFO - Deleting interface with subnet_id b711ba99-bbf5-49cb-8e05-e6fa7eec4b89, port_id None from router 3730f8c5-d471-4df2-8cec-2991ca9401d3 2026-02-11 14:14:49,220 - INFO - Deleting VM ctest-vn2-vm1-01034355 2026-02-11 14:14:49,310 - INFO - Deleting VM ctest-vn1-vm1-06403475 2026-02-11 14:14:49,388 - INFO - Deleting VN ctest-vn2-23543413 2026-02-11 14:14:49,427 - DEBUG - VN 8667f10e-c2ac-4e61-891b-fc78fc21af00 still in use: Unable to complete operation on network 8667f10e-c2ac-4e61-891b-fc78fc21af00. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-6d32e7d1-e6de-485e-91f6-92829829e488'] 2026-02-11 14:14:49,427 - WARNING - Deleting VN ctest-vn2-23543413 failed..Will retry 2026-02-11 14:14:51,552 - DEBUG - Response for deleting network () 2026-02-11 14:14:51,552 - INFO - Deleting VN ctest-vn1-20865577 2026-02-11 14:14:51,666 - DEBUG - Response for deleting network () 2026-02-11 14:14:51,926 - DEBUG - No XMPP flaps were noticed during the test
2026-02-11 14:14:51,926 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:39]
2026-02-11 14:14:51,926 - INFO - -------------------------------------------------------------------------------- 2026-02-11 14:14:52,639 - INFO - Deleted project: ctest-TestRouters-06252064, ID : 3d145847-702c-4d9e-b40e-d35b63a2528e