2026-02-16 16:21:37,677 - INFO - Domain Default found not creating 2026-02-16 16:21:37,842 - INFO - Project ctest-TestRouters-34331354 not found, creating it 2026-02-16 16:21:38,333 - INFO - Created Project:ctest-TestRouters-34331354, ID : e1b4767f-fb47-4f5d-b9d8-a104f5776481 2026-02-16 16:21:40,158 - INFO - ================================================================================ 2026-02-16 16:21:40,158 - INFO - STARTING TEST : test_basic_router_behavior 2026-02-16 16:21:40,159 - 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-16 16:21:40,411 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.254': '0'}} with 2026-02-16 16:21:40,411 - INFO - Initial checks done. Running the testcase now 2026-02-16 16:21:40,411 - INFO - 2026-02-16 16:21:40,972 - DEBUG - Response for create_network : {'network': {'id': 'ea3352d0-d052-4752-b47d-236998b2105b', 'name': 'ctest-vn1-71005299', 'tenant_id': 'e1b4767ffb474f5db9d8a104f5776481', 'project_id': 'e1b4767ffb474f5db9d8a104f5776481', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-34331354', 'ctest-vn1-71005299'], 'port_security_enabled': True, 'description': ''}} 2026-02-16 16:21:41,143 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b7a21e0e-1549-4d1f-ab3d-aa3fa27a5f17', 'name': '', 'tenant_id': 'e1b4767ffb474f5db9d8a104f5776481', 'network_id': 'ea3352d0-d052-4752-b47d-236998b2105b', 'ip_version': 4, 'cidr': '100.201.141.64/26', 'allocation_pools': [{'start': '100.201.141.66', 'end': '100.201.141.126'}], 'gateway_ip': '100.201.141.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '100.201.141.66', 'tags': [], 'project_id': 'e1b4767ffb474f5db9d8a104f5776481'}} 2026-02-16 16:21:41,163 - INFO - Created VN ctest-vn1-71005299 2026-02-16 16:21:41,215 - DEBUG - VN ctest-vn1-71005299 UUID is ea3352d0-d052-4752-b47d-236998b2105b 2026-02-16 16:21:41,519 - DEBUG - Response for create_network : {'network': {'id': '692fbd0f-8a0a-4406-839f-9dce2d6315c7', 'name': 'ctest-vn2-12315787', 'tenant_id': 'e1b4767ffb474f5db9d8a104f5776481', 'project_id': 'e1b4767ffb474f5db9d8a104f5776481', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-34331354', 'ctest-vn2-12315787'], 'port_security_enabled': True, 'description': ''}} 2026-02-16 16:21:41,769 - DEBUG - Response for create_subnet : {'subnet': {'id': '214b96f4-490e-48f8-8e5e-75a63fdce226', 'name': '', 'tenant_id': 'e1b4767ffb474f5db9d8a104f5776481', 'network_id': '692fbd0f-8a0a-4406-839f-9dce2d6315c7', 'ip_version': 4, 'cidr': '31.120.151.64/26', 'allocation_pools': [{'start': '31.120.151.66', 'end': '31.120.151.126'}], 'gateway_ip': '31.120.151.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '31.120.151.66', 'tags': [], 'project_id': 'e1b4767ffb474f5db9d8a104f5776481'}} 2026-02-16 16:21:41,787 - INFO - Created VN ctest-vn2-12315787 2026-02-16 16:21:41,842 - DEBUG - VN ctest-vn2-12315787 UUID is 692fbd0f-8a0a-4406-839f-9dce2d6315c7 2026-02-16 16:21:42,048 - DEBUG - Services list from nova: [, , ] 2026-02-16 16:21:43,114 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5337-30), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5337-30) 2026-02-16 16:21:44,363 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5337-30), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5337-30) 2026-02-16 16:21:44,437 - INFO - Adding interface with subnet_id b7a21e0e-1549-4d1f-ab3d-aa3fa27a5f17, port_id None to router cee7ac85-11a1-4e16-9422-8afffe1e7178 2026-02-16 16:21:44,729 - INFO - Waiting for VM ctest-vn1-vm1-44178799 to be up.. 2026-02-16 16:21:44,844 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-02-16 16:21:49,922 - DEBUG - VM is in ACTIVE state now 2026-02-16 16:21:49,922 - INFO - VM name : ctest-vn1-vm1-44178799 2026-02-16 16:21:50,021 - DEBUG - VM ctest-vn1-vm1-44178799 ID is 35a42ff4-c0c2-444a-89e3-c1e8ee809326 2026-02-16 16:21:50,041 - DEBUG - VM ctest-vn1-vm1-44178799 launched on Node cn-jenkins-deploy-platform-ansible-os-5337-30 2026-02-16 16:21:50,116 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/35a42ff4-c0c2-444a-89e3-c1e8ee809326 2026-02-16 16:21:50,415 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/35a42ff4-c0c2-444a-89e3-c1e8ee809326 2026-02-16 16:21:50,462 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/e574a6fc-6a83-47f7-8d6d-9eb5a9f16063 2026-02-16 16:21:53,717 - 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 1015ms') 2026-02-16 16:21:53,717 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-44178799 failed! 2026-02-16 16:21:53,771 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-34331354:ctest-vn1-71005299 is 100.201.141.65 and allocation pool is NOT set 2026-02-16 16:21:55,834 - 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.15 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.41 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.405/3.276/4.148/0.871 ms') 2026-02-16 16:21:55,834 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-44178799 passed 2026-02-16 16:21:55,982 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-16 16:21:55,982 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-44178799, IP 100.201.141.67, Port 22 2026-02-16 16:21:56,138 - DEBUG - VM ctest-vn1-vm1-44178799 is ready for SSH connections 2026-02-16 16:21:56,138 - INFO - Waiting for VM ctest-vn2-vm1-77600544 to be up.. 2026-02-16 16:21:56,210 - DEBUG - VM is in ACTIVE state now 2026-02-16 16:21:56,210 - INFO - VM name : ctest-vn2-vm1-77600544 2026-02-16 16:21:56,277 - DEBUG - VM ctest-vn2-vm1-77600544 ID is 1cd5b834-6e58-44df-9b17-c55cef84f3e5 2026-02-16 16:21:56,277 - DEBUG - VM ctest-vn2-vm1-77600544 launched on Node cn-jenkins-deploy-platform-ansible-os-5337-30 2026-02-16 16:21:56,349 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/1cd5b834-6e58-44df-9b17-c55cef84f3e5 2026-02-16 16:21:56,357 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/b86d2573-9f3f-41b6-a741-b273cb6c80dd 2026-02-16 16:21:57,593 - 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=4.32 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.80 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 = 1.800/3.059/4.318/1.259 ms') 2026-02-16 16:21:57,594 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-77600544 passed 2026-02-16 16:21:57,742 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-16 16:21:57,742 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-77600544, IP 31.120.151.67, Port 22 2026-02-16 16:21:57,898 - DEBUG - VM ctest-vn2-vm1-77600544 is ready for SSH connections 2026-02-16 16:21:57,898 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-16 16:21:57,899 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.120.151.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-02-16 16:21:57,899 - DEBUG - ping -s 56 -c 3 -W 1 31.120.151.67 2026-02-16 16:22:02,952 - DEBUG - PING 31.120.151.67 (31.120.151.67): 56 data bytes --- 31.120.151.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-02-16 16:22:02,952 - WARNING - Ping to IP 31.120.151.67 from VM ctest-vn1-vm1-44178799 failed 2026-02-16 16:22:02,953 - INFO - Adding interface with subnet_id 214b96f4-490e-48f8-8e5e-75a63fdce226, port_id None to router cee7ac85-11a1-4e16-9422-8afffe1e7178 2026-02-16 16:22:03,278 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-16 16:22:03,279 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.120.151.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-02-16 16:22:03,279 - DEBUG - ping -s 56 -c 3 -W 1 31.120.151.67 2026-02-16 16:22:06,507 - DEBUG - PING 31.120.151.67 (31.120.151.67): 56 data bytes 64 bytes from 31.120.151.67: seq=1 ttl=63 time=3.099 ms 64 bytes from 31.120.151.67: seq=2 ttl=63 time=0.918 ms --- 31.120.151.67 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 0.918/2.008/3.099 ms 2026-02-16 16:22:06,507 - WARNING - Ping to IP 31.120.151.67 from VM ctest-vn1-vm1-44178799 failed 2026-02-16 16:22:07,508 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.120.151.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-02-16 16:22:07,508 - DEBUG - ping -s 56 -c 3 -W 1 31.120.151.67 2026-02-16 16:22:09,710 - DEBUG - PING 31.120.151.67 (31.120.151.67): 56 data bytes 64 bytes from 31.120.151.67: seq=0 ttl=63 time=1.472 ms 64 bytes from 31.120.151.67: seq=1 ttl=63 time=0.834 ms 64 bytes from 31.120.151.67: seq=2 ttl=63 time=0.847 ms --- 31.120.151.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.834/1.051/1.472 ms 2026-02-16 16:22:09,710 - INFO - Ping to IP 31.120.151.67 from VM ctest-vn1-vm1-44178799 passed 2026-02-16 16:22:09,710 - INFO - Deleting interface with subnet_id b7a21e0e-1549-4d1f-ab3d-aa3fa27a5f17, port_id None from router cee7ac85-11a1-4e16-9422-8afffe1e7178 2026-02-16 16:22:09,815 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-16 16:22:09,816 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.120.151.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-02-16 16:22:09,816 - DEBUG - ping -s 56 -c 3 -W 1 31.120.151.67 2026-02-16 16:22:13,033 - DEBUG - PING 31.120.151.67 (31.120.151.67): 56 data bytes --- 31.120.151.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-02-16 16:22:13,033 - WARNING - Ping to IP 31.120.151.67 from VM ctest-vn1-vm1-44178799 failed 2026-02-16 16:22:13,034 - INFO - Adding interface with subnet_id b7a21e0e-1549-4d1f-ab3d-aa3fa27a5f17, port_id None to router cee7ac85-11a1-4e16-9422-8afffe1e7178 2026-02-16 16:22:13,225 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-16 16:22:13,225 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.120.151.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-02-16 16:22:13,225 - DEBUG - ping -s 56 -c 3 -W 1 31.120.151.67 2026-02-16 16:22:15,438 - DEBUG - PING 31.120.151.67 (31.120.151.67): 56 data bytes 64 bytes from 31.120.151.67: seq=0 ttl=63 time=1.692 ms 64 bytes from 31.120.151.67: seq=1 ttl=63 time=1.032 ms 64 bytes from 31.120.151.67: seq=2 ttl=63 time=0.833 ms --- 31.120.151.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.833/1.185/1.692 ms 2026-02-16 16:22:15,438 - INFO - Ping to IP 31.120.151.67 from VM ctest-vn1-vm1-44178799 passed 2026-02-16 16:22:15,438 - INFO - Deleting interface with subnet_id 214b96f4-490e-48f8-8e5e-75a63fdce226, port_id None from router cee7ac85-11a1-4e16-9422-8afffe1e7178 2026-02-16 16:22:15,557 - INFO - Deleting interface with subnet_id b7a21e0e-1549-4d1f-ab3d-aa3fa27a5f17, port_id None from router cee7ac85-11a1-4e16-9422-8afffe1e7178 2026-02-16 16:22:15,757 - INFO - Deleting VM ctest-vn2-vm1-77600544 2026-02-16 16:22:15,844 - INFO - Deleting VM ctest-vn1-vm1-44178799 2026-02-16 16:22:15,925 - INFO - Deleting VN ctest-vn2-12315787 2026-02-16 16:22:15,965 - DEBUG - VN 692fbd0f-8a0a-4406-839f-9dce2d6315c7 still in use: Unable to complete operation on network 692fbd0f-8a0a-4406-839f-9dce2d6315c7. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-95e104da-6174-4595-a0fb-45ae01bfde98'] 2026-02-16 16:22:15,965 - WARNING - Deleting VN ctest-vn2-12315787 failed..Will retry 2026-02-16 16:22:18,073 - DEBUG - Response for deleting network () 2026-02-16 16:22:18,073 - INFO - Deleting VN ctest-vn1-71005299 2026-02-16 16:22:18,168 - DEBUG - Response for deleting network () 2026-02-16 16:22:18,428 - DEBUG - No XMPP flaps were noticed during the test
2026-02-16 16:22:18,428 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:38]
2026-02-16 16:22:18,428 - INFO - -------------------------------------------------------------------------------- 2026-02-16 16:22:19,179 - INFO - Deleted project: ctest-TestRouters-34331354, ID : e1b4767f-fb47-4f5d-b9d8-a104f5776481