2026-03-14 00:28:26,476 - INFO - Domain Default found not creating 2026-03-14 00:28:26,649 - INFO - Project ctest-TestRouters-32313861 not found, creating it 2026-03-14 00:28:27,154 - INFO - Created Project:ctest-TestRouters-32313861, ID : 4ac892e6-3a8c-4e0b-8ba3-45b8abbffb6a 2026-03-14 00:28:29,052 - INFO - ================================================================================ 2026-03-14 00:28:29,052 - INFO - STARTING TEST : test_basic_router_behavior 2026-03-14 00:28:29,052 - 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-14 00:28:29,321 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.17': '0'}} with 2026-03-14 00:28:29,321 - INFO - Initial checks done. Running the testcase now 2026-03-14 00:28:29,321 - INFO - 2026-03-14 00:28:30,062 - DEBUG - Response for create_network : {'network': {'id': '67f84e2d-37d2-4587-85d2-16943f54d97a', 'name': 'ctest-vn1-47535145', 'tenant_id': '4ac892e63a8c4e0b8ba345b8abbffb6a', 'project_id': '4ac892e63a8c4e0b8ba345b8abbffb6a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-32313861', 'ctest-vn1-47535145'], 'port_security_enabled': True, 'description': ''}} 2026-03-14 00:28:30,246 - DEBUG - Response for create_subnet : {'subnet': {'id': '73b5915b-3656-451e-a191-fac783fd3883', 'name': '', 'tenant_id': '4ac892e63a8c4e0b8ba345b8abbffb6a', 'network_id': '67f84e2d-37d2-4587-85d2-16943f54d97a', 'ip_version': 4, 'cidr': '128.50.215.64/26', 'allocation_pools': [{'start': '128.50.215.66', 'end': '128.50.215.126'}], 'gateway_ip': '128.50.215.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '128.50.215.66', 'tags': [], 'project_id': '4ac892e63a8c4e0b8ba345b8abbffb6a'}} 2026-03-14 00:28:30,267 - INFO - Created VN ctest-vn1-47535145 2026-03-14 00:28:30,319 - DEBUG - VN ctest-vn1-47535145 UUID is 67f84e2d-37d2-4587-85d2-16943f54d97a 2026-03-14 00:28:30,626 - DEBUG - Response for create_network : {'network': {'id': 'e215cf5c-5a8e-4079-87bf-a9f767532fa8', 'name': 'ctest-vn2-75361033', 'tenant_id': '4ac892e63a8c4e0b8ba345b8abbffb6a', 'project_id': '4ac892e63a8c4e0b8ba345b8abbffb6a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-32313861', 'ctest-vn2-75361033'], 'port_security_enabled': True, 'description': ''}} 2026-03-14 00:28:30,837 - DEBUG - Response for create_subnet : {'subnet': {'id': '5166c18d-2960-4cdf-b23c-bc46bc17df05', 'name': '', 'tenant_id': '4ac892e63a8c4e0b8ba345b8abbffb6a', 'network_id': 'e215cf5c-5a8e-4079-87bf-a9f767532fa8', 'ip_version': 4, 'cidr': '49.171.237.192/26', 'allocation_pools': [{'start': '49.171.237.194', 'end': '49.171.237.254'}], 'gateway_ip': '49.171.237.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '49.171.237.194', 'tags': [], 'project_id': '4ac892e63a8c4e0b8ba345b8abbffb6a'}} 2026-03-14 00:28:30,858 - INFO - Created VN ctest-vn2-75361033 2026-03-14 00:28:30,911 - DEBUG - VN ctest-vn2-75361033 UUID is e215cf5c-5a8e-4079-87bf-a9f767532fa8 2026-03-14 00:28:31,108 - DEBUG - Services list from nova: [, , ] 2026-03-14 00:28:32,151 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5468-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5468-1) 2026-03-14 00:28:33,478 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5468-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5468-1) 2026-03-14 00:28:33,566 - INFO - Adding interface with subnet_id 73b5915b-3656-451e-a191-fac783fd3883, port_id None to router 863cbf74-1191-4871-8fcd-1bd41905bfef 2026-03-14 00:28:33,873 - INFO - Waiting for VM ctest-vn1-vm1-62888333 to be up.. 2026-03-14 00:28:34,034 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-14 00:28:39,112 - DEBUG - VM is in ACTIVE state now 2026-03-14 00:28:39,112 - INFO - VM name : ctest-vn1-vm1-62888333 2026-03-14 00:28:39,187 - DEBUG - VM ctest-vn1-vm1-62888333 ID is 32eca9a7-f768-4989-b0ec-cc1c43dbeb37 2026-03-14 00:28:39,210 - DEBUG - VM ctest-vn1-vm1-62888333 launched on Node cn-jenkins-deploy-platform-ansible-os-5468-1 2026-03-14 00:28:39,292 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/32eca9a7-f768-4989-b0ec-cc1c43dbeb37 2026-03-14 00:28:39,588 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/32eca9a7-f768-4989-b0ec-cc1c43dbeb37 2026-03-14 00:28:39,626 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/a49dafe7-271e-459f-b2bc-0be760c24397 2026-03-14 00:28:42,884 - 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 1027ms') 2026-03-14 00:28:42,884 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-62888333 failed! 2026-03-14 00:28:42,943 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-32313861:ctest-vn1-47535145 is 128.50.215.65 and allocation pool is NOT set 2026-03-14 00:28:45,006 - 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=7.49 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.98 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.979/5.233/7.488/2.254 ms') 2026-03-14 00:28:45,006 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-62888333 passed 2026-03-14 00:28:45,163 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-14 00:28:45,163 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-62888333, IP 128.50.215.67, Port 22 2026-03-14 00:28:45,333 - DEBUG - VM ctest-vn1-vm1-62888333 is ready for SSH connections 2026-03-14 00:28:45,333 - INFO - Waiting for VM ctest-vn2-vm1-27619862 to be up.. 2026-03-14 00:28:45,421 - DEBUG - VM is in ACTIVE state now 2026-03-14 00:28:45,421 - INFO - VM name : ctest-vn2-vm1-27619862 2026-03-14 00:28:45,526 - DEBUG - VM ctest-vn2-vm1-27619862 ID is 0a59c4d0-d50f-4264-99c2-8a0427fd3a02 2026-03-14 00:28:45,526 - DEBUG - VM ctest-vn2-vm1-27619862 launched on Node cn-jenkins-deploy-platform-ansible-os-5468-1 2026-03-14 00:28:45,620 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/0a59c4d0-d50f-4264-99c2-8a0427fd3a02 2026-03-14 00:28:45,631 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/8419f16b-e990-4848-956d-b53ce9a8edd7 2026-03-14 00:28:46,892 - 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.91 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.415 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.415/1.660/2.905/1.245 ms') 2026-03-14 00:28:46,892 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-27619862 passed 2026-03-14 00:28:47,043 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-14 00:28:47,043 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-27619862, IP 49.171.237.195, Port 22 2026-03-14 00:28:47,202 - DEBUG - VM ctest-vn2-vm1-27619862 is ready for SSH connections 2026-03-14 00:28:47,203 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-14 00:28:47,203 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 49.171.237.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-14 00:28:47,203 - DEBUG - ping -s 56 -c 3 -W 1 49.171.237.195 2026-03-14 00:28:52,195 - DEBUG - PING 49.171.237.195 (49.171.237.195): 56 data bytes --- 49.171.237.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-03-14 00:28:52,195 - WARNING - Ping to IP 49.171.237.195 from VM ctest-vn1-vm1-62888333 failed 2026-03-14 00:28:52,195 - INFO - Adding interface with subnet_id 5166c18d-2960-4cdf-b23c-bc46bc17df05, port_id None to router 863cbf74-1191-4871-8fcd-1bd41905bfef 2026-03-14 00:28:52,454 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-14 00:28:52,454 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 49.171.237.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-14 00:28:52,455 - DEBUG - ping -s 56 -c 3 -W 1 49.171.237.195 2026-03-14 00:28:55,685 - DEBUG - PING 49.171.237.195 (49.171.237.195): 56 data bytes 64 bytes from 49.171.237.195: seq=1 ttl=63 time=3.545 ms 64 bytes from 49.171.237.195: seq=2 ttl=63 time=1.395 ms --- 49.171.237.195 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.395/2.470/3.545 ms 2026-03-14 00:28:55,685 - WARNING - Ping to IP 49.171.237.195 from VM ctest-vn1-vm1-62888333 failed 2026-03-14 00:28:56,686 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 49.171.237.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-14 00:28:56,686 - DEBUG - ping -s 56 -c 3 -W 1 49.171.237.195 2026-03-14 00:28:58,896 - DEBUG - PING 49.171.237.195 (49.171.237.195): 56 data bytes 64 bytes from 49.171.237.195: seq=0 ttl=63 time=1.465 ms 64 bytes from 49.171.237.195: seq=1 ttl=63 time=1.080 ms 64 bytes from 49.171.237.195: seq=2 ttl=63 time=0.857 ms --- 49.171.237.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.857/1.134/1.465 ms 2026-03-14 00:28:58,897 - INFO - Ping to IP 49.171.237.195 from VM ctest-vn1-vm1-62888333 passed 2026-03-14 00:28:58,897 - INFO - Deleting interface with subnet_id 73b5915b-3656-451e-a191-fac783fd3883, port_id None from router 863cbf74-1191-4871-8fcd-1bd41905bfef 2026-03-14 00:28:59,008 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-14 00:28:59,008 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 49.171.237.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-14 00:28:59,008 - DEBUG - ping -s 56 -c 3 -W 1 49.171.237.195 2026-03-14 00:29:02,215 - DEBUG - PING 49.171.237.195 (49.171.237.195): 56 data bytes --- 49.171.237.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-03-14 00:29:02,215 - WARNING - Ping to IP 49.171.237.195 from VM ctest-vn1-vm1-62888333 failed 2026-03-14 00:29:02,215 - INFO - Adding interface with subnet_id 73b5915b-3656-451e-a191-fac783fd3883, port_id None to router 863cbf74-1191-4871-8fcd-1bd41905bfef 2026-03-14 00:29:02,429 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-14 00:29:02,429 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 49.171.237.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-14 00:29:02,430 - DEBUG - ping -s 56 -c 3 -W 1 49.171.237.195 2026-03-14 00:29:04,664 - DEBUG - PING 49.171.237.195 (49.171.237.195): 56 data bytes 64 bytes from 49.171.237.195: seq=0 ttl=63 time=1.375 ms 64 bytes from 49.171.237.195: seq=1 ttl=63 time=0.775 ms 64 bytes from 49.171.237.195: seq=2 ttl=63 time=1.109 ms --- 49.171.237.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.775/1.086/1.375 ms 2026-03-14 00:29:04,664 - INFO - Ping to IP 49.171.237.195 from VM ctest-vn1-vm1-62888333 passed 2026-03-14 00:29:04,664 - INFO - Deleting interface with subnet_id 5166c18d-2960-4cdf-b23c-bc46bc17df05, port_id None from router 863cbf74-1191-4871-8fcd-1bd41905bfef 2026-03-14 00:29:04,764 - INFO - Deleting interface with subnet_id 73b5915b-3656-451e-a191-fac783fd3883, port_id None from router 863cbf74-1191-4871-8fcd-1bd41905bfef 2026-03-14 00:29:04,919 - INFO - Deleting VM ctest-vn2-vm1-27619862 2026-03-14 00:29:04,990 - INFO - Deleting VM ctest-vn1-vm1-62888333 2026-03-14 00:29:05,065 - INFO - Deleting VN ctest-vn2-75361033 2026-03-14 00:29:05,100 - DEBUG - VN e215cf5c-5a8e-4079-87bf-a9f767532fa8 still in use: Unable to complete operation on network e215cf5c-5a8e-4079-87bf-a9f767532fa8. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-326615bc-6404-4768-a1ae-5302bfa25a20'] 2026-03-14 00:29:05,100 - WARNING - Deleting VN ctest-vn2-75361033 failed..Will retry 2026-03-14 00:29:07,191 - DEBUG - Response for deleting network () 2026-03-14 00:29:07,191 - INFO - Deleting VN ctest-vn1-47535145 2026-03-14 00:29:07,282 - DEBUG - Response for deleting network () 2026-03-14 00:29:07,548 - DEBUG - No XMPP flaps were noticed during the test
2026-03-14 00:29:07,548 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:38]
2026-03-14 00:29:07,548 - INFO - -------------------------------------------------------------------------------- 2026-03-14 00:29:08,228 - INFO - Deleted project: ctest-TestRouters-32313861, ID : 4ac892e6-3a8c-4e0b-8ba3-45b8abbffb6a