2026-07-17 18:06:11,915 - INFO - Domain Default found not creating 2026-07-17 18:06:12,110 - INFO - Project ctest-TestRouters-30346591 not found, creating it 2026-07-17 18:06:12,650 - INFO - Created Project:ctest-TestRouters-30346591, ID : 190aa5d9-d40c-4b2d-981e-29f7c621d634 2026-07-17 18:06:14,470 - INFO - ================================================================================ 2026-07-17 18:06:14,471 - INFO - STARTING TEST : test_basic_router_behavior 2026-07-17 18:06:14,471 - 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-07-17 18:06:14,734 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.17': '0'}} with 2026-07-17 18:06:14,734 - INFO - Initial checks done. Running the testcase now 2026-07-17 18:06:14,734 - INFO - 2026-07-17 18:06:15,380 - DEBUG - Response for create_network : {'network': {'id': '65f95b53-df22-4471-ae83-3fb895dd0501', 'name': 'ctest-vn1-08488109', 'tenant_id': '190aa5d9d40c4b2d981e29f7c621d634', 'project_id': '190aa5d9d40c4b2d981e29f7c621d634', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-30346591', 'ctest-vn1-08488109'], 'port_security_enabled': True, 'description': ''}} 2026-07-17 18:06:15,640 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a25bef6c-a5e0-43cf-b547-2af6733420c7', 'name': '', 'tenant_id': '190aa5d9d40c4b2d981e29f7c621d634', 'network_id': '65f95b53-df22-4471-ae83-3fb895dd0501', 'ip_version': 4, 'cidr': '74.251.186.0/26', 'allocation_pools': [{'start': '74.251.186.2', 'end': '74.251.186.62'}], 'gateway_ip': '74.251.186.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '74.251.186.2', 'tags': [], 'project_id': '190aa5d9d40c4b2d981e29f7c621d634'}} 2026-07-17 18:06:15,665 - INFO - Created VN ctest-vn1-08488109 2026-07-17 18:06:15,718 - DEBUG - VN ctest-vn1-08488109 UUID is 65f95b53-df22-4471-ae83-3fb895dd0501 2026-07-17 18:06:16,039 - DEBUG - Response for create_network : {'network': {'id': '5d1ec56d-885f-49e2-b809-56620e4d0e6c', 'name': 'ctest-vn2-31754929', 'tenant_id': '190aa5d9d40c4b2d981e29f7c621d634', 'project_id': '190aa5d9d40c4b2d981e29f7c621d634', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-30346591', 'ctest-vn2-31754929'], 'port_security_enabled': True, 'description': ''}} 2026-07-17 18:06:16,230 - DEBUG - Response for create_subnet : {'subnet': {'id': 'dd62eb18-f30f-4c28-a202-4a93e678d260', 'name': '', 'tenant_id': '190aa5d9d40c4b2d981e29f7c621d634', 'network_id': '5d1ec56d-885f-49e2-b809-56620e4d0e6c', 'ip_version': 4, 'cidr': '69.187.4.0/26', 'allocation_pools': [{'start': '69.187.4.2', 'end': '69.187.4.62'}], 'gateway_ip': '69.187.4.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '69.187.4.2', 'tags': [], 'project_id': '190aa5d9d40c4b2d981e29f7c621d634'}} 2026-07-17 18:06:16,248 - INFO - Created VN ctest-vn2-31754929 2026-07-17 18:06:16,302 - DEBUG - VN ctest-vn2-31754929 UUID is 5d1ec56d-885f-49e2-b809-56620e4d0e6c 2026-07-17 18:06:16,565 - DEBUG - Services list from nova: [, , ] 2026-07-17 18:06:16,969 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6099-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6099-1) 2026-07-17 18:06:17,435 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6099-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6099-1) 2026-07-17 18:06:17,520 - INFO - Adding interface with subnet_id a25bef6c-a5e0-43cf-b547-2af6733420c7, port_id None to router 79d858a5-0502-4fd8-901f-a6c3f7041fbc 2026-07-17 18:06:17,824 - INFO - Waiting for VM ctest-vn1-vm1-31956090 to be up.. 2026-07-17 18:06:17,874 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-17 18:06:22,992 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-17 18:06:28,078 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-17 18:06:33,168 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-17 18:06:38,264 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-17 18:06:43,351 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-17 18:06:48,438 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-17 18:06:53,537 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-17 18:06:58,623 - DEBUG - VM is in ACTIVE state now 2026-07-17 18:06:58,623 - INFO - VM name : ctest-vn1-vm1-31956090 2026-07-17 18:06:58,710 - DEBUG - VM ctest-vn1-vm1-31956090 ID is 8d32922c-5c86-4198-83cb-23801d4de63d 2026-07-17 18:06:58,734 - DEBUG - VM ctest-vn1-vm1-31956090 launched on Node cn-jenkins-deploy-platform-ansible-os-6099-1 2026-07-17 18:06:58,832 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/8d32922c-5c86-4198-83cb-23801d4de63d 2026-07-17 18:06:59,215 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/8d32922c-5c86-4198-83cb-23801d4de63d 2026-07-17 18:06:59,253 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/d4ce4763-a212-4446-8fda-161141bb39d3 2026-07-17 18:07:02,555 - 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 1022ms') 2026-07-17 18:07:02,555 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-31956090 failed! 2026-07-17 18:07:02,610 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-30346591:ctest-vn1-08488109 is 74.251.186.1 and allocation pool is NOT set 2026-07-17 18:07:04,683 - 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=8.02 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.99 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.993/5.504/8.015/2.511 ms') 2026-07-17 18:07:04,683 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-31956090 passed 2026-07-17 18:07:04,842 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-17 18:07:04,842 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-31956090, IP 74.251.186.3, Port 22 2026-07-17 18:07:04,911 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-07-17 18:07:05,004 - DEBUG - VM ctest-vn1-vm1-31956090 is NOT ready for SSH connections, VM status: ACTIVE 2026-07-17 18:07:10,005 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-17 18:07:10,006 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-31956090, IP 74.251.186.3, Port 22 2026-07-17 18:07:10,180 - DEBUG - VM ctest-vn1-vm1-31956090 is ready for SSH connections 2026-07-17 18:07:10,180 - INFO - Waiting for VM ctest-vn2-vm1-88400167 to be up.. 2026-07-17 18:07:10,260 - DEBUG - VM is in ACTIVE state now 2026-07-17 18:07:10,260 - INFO - VM name : ctest-vn2-vm1-88400167 2026-07-17 18:07:10,336 - DEBUG - VM ctest-vn2-vm1-88400167 ID is 6e06d906-ef91-4b0a-8b20-89f13fe4d973 2026-07-17 18:07:10,337 - DEBUG - VM ctest-vn2-vm1-88400167 launched on Node cn-jenkins-deploy-platform-ansible-os-6099-1 2026-07-17 18:07:10,415 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/6e06d906-ef91-4b0a-8b20-89f13fe4d973 2026-07-17 18:07:10,425 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/e0bf9f6e-71b1-4fcb-8f5a-938779e694ff 2026-07-17 18:07:11,676 - 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.67 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.570 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.570/2.121/3.673/1.551 ms') 2026-07-17 18:07:11,677 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-88400167 passed 2026-07-17 18:07:11,834 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-17 18:07:11,834 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-88400167, IP 69.187.4.3, Port 22 2026-07-17 18:07:12,007 - DEBUG - VM ctest-vn2-vm1-88400167 is ready for SSH connections 2026-07-17 18:07:12,008 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-17 18:07:12,008 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 69.187.4.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-07-17 18:07:12,008 - DEBUG - ping -s 56 -c 3 -W 1 69.187.4.3 2026-07-17 18:07:17,109 - DEBUG - PING 69.187.4.3 (69.187.4.3): 56 data bytes --- 69.187.4.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-07-17 18:07:17,109 - WARNING - Ping to IP 69.187.4.3 from VM ctest-vn1-vm1-31956090 failed 2026-07-17 18:07:17,109 - INFO - Adding interface with subnet_id dd62eb18-f30f-4c28-a202-4a93e678d260, port_id None to router 79d858a5-0502-4fd8-901f-a6c3f7041fbc 2026-07-17 18:07:17,386 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-17 18:07:17,386 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 69.187.4.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-07-17 18:07:17,386 - DEBUG - ping -s 56 -c 3 -W 1 69.187.4.3 2026-07-17 18:07:20,534 - DEBUG - PING 69.187.4.3 (69.187.4.3): 56 data bytes 64 bytes from 69.187.4.3: seq=1 ttl=63 time=4.438 ms 64 bytes from 69.187.4.3: seq=2 ttl=63 time=1.728 ms --- 69.187.4.3 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.728/3.083/4.438 ms 2026-07-17 18:07:20,534 - WARNING - Ping to IP 69.187.4.3 from VM ctest-vn1-vm1-31956090 failed 2026-07-17 18:07:21,534 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 69.187.4.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-07-17 18:07:21,535 - DEBUG - ping -s 56 -c 3 -W 1 69.187.4.3 2026-07-17 18:07:23,677 - DEBUG - PING 69.187.4.3 (69.187.4.3): 56 data bytes 64 bytes from 69.187.4.3: seq=0 ttl=63 time=1.704 ms 64 bytes from 69.187.4.3: seq=1 ttl=63 time=1.110 ms 64 bytes from 69.187.4.3: seq=2 ttl=63 time=1.202 ms --- 69.187.4.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.110/1.338/1.704 ms 2026-07-17 18:07:23,677 - INFO - Ping to IP 69.187.4.3 from VM ctest-vn1-vm1-31956090 passed 2026-07-17 18:07:23,677 - INFO - Deleting interface with subnet_id a25bef6c-a5e0-43cf-b547-2af6733420c7, port_id None from router 79d858a5-0502-4fd8-901f-a6c3f7041fbc 2026-07-17 18:07:23,825 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-17 18:07:23,825 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 69.187.4.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-07-17 18:07:23,825 - DEBUG - ping -s 56 -c 3 -W 1 69.187.4.3 2026-07-17 18:07:26,953 - DEBUG - PING 69.187.4.3 (69.187.4.3): 56 data bytes --- 69.187.4.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-07-17 18:07:26,953 - WARNING - Ping to IP 69.187.4.3 from VM ctest-vn1-vm1-31956090 failed 2026-07-17 18:07:26,953 - INFO - Adding interface with subnet_id a25bef6c-a5e0-43cf-b547-2af6733420c7, port_id None to router 79d858a5-0502-4fd8-901f-a6c3f7041fbc 2026-07-17 18:07:27,220 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-17 18:07:27,220 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 69.187.4.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-07-17 18:07:27,220 - DEBUG - ping -s 56 -c 3 -W 1 69.187.4.3 2026-07-17 18:07:29,358 - DEBUG - PING 69.187.4.3 (69.187.4.3): 56 data bytes 64 bytes from 69.187.4.3: seq=0 ttl=63 time=1.866 ms 64 bytes from 69.187.4.3: seq=1 ttl=63 time=1.121 ms 64 bytes from 69.187.4.3: seq=2 ttl=63 time=1.187 ms --- 69.187.4.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.121/1.391/1.866 ms 2026-07-17 18:07:29,358 - INFO - Ping to IP 69.187.4.3 from VM ctest-vn1-vm1-31956090 passed 2026-07-17 18:07:29,358 - INFO - Deleting interface with subnet_id dd62eb18-f30f-4c28-a202-4a93e678d260, port_id None from router 79d858a5-0502-4fd8-901f-a6c3f7041fbc 2026-07-17 18:07:29,508 - INFO - Deleting interface with subnet_id a25bef6c-a5e0-43cf-b547-2af6733420c7, port_id None from router 79d858a5-0502-4fd8-901f-a6c3f7041fbc 2026-07-17 18:07:29,729 - INFO - Deleting VM ctest-vn2-vm1-88400167 2026-07-17 18:07:29,819 - INFO - Deleting VM ctest-vn1-vm1-31956090 2026-07-17 18:07:29,953 - INFO - Deleting VN ctest-vn2-31754929 2026-07-17 18:07:29,993 - DEBUG - VN 5d1ec56d-885f-49e2-b809-56620e4d0e6c still in use: Unable to complete operation on network 5d1ec56d-885f-49e2-b809-56620e4d0e6c. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-57248b0f-4684-4f51-984e-7e8c12a7a7b0'] 2026-07-17 18:07:29,993 - WARNING - Deleting VN ctest-vn2-31754929 failed..Will retry 2026-07-17 18:07:32,188 - DEBUG - Response for deleting network () 2026-07-17 18:07:32,189 - INFO - Deleting VN ctest-vn1-08488109 2026-07-17 18:07:32,300 - DEBUG - Response for deleting network () 2026-07-17 18:07:32,571 - DEBUG - No XMPP flaps were noticed during the test
2026-07-17 18:07:32,571 - INFO - END TEST : test_basic_router_behavior : PASSED[0:01:18]
2026-07-17 18:07:32,571 - INFO - -------------------------------------------------------------------------------- 2026-07-17 18:07:33,369 - INFO - Deleted project: ctest-TestRouters-30346591, ID : 190aa5d9-d40c-4b2d-981e-29f7c621d634