2026-01-29 02:08:50,265 - INFO - Domain Default found not creating 2026-01-29 02:08:50,452 - INFO - Project ctest-TestRouters-71714009 not found, creating it 2026-01-29 02:08:50,952 - INFO - Created Project:ctest-TestRouters-71714009, ID : d3772f0c-4340-4f54-ae04-4c1d75f295c4 2026-01-29 02:08:52,657 - INFO - ================================================================================ 2026-01-29 02:08:52,657 - INFO - STARTING TEST : test_basic_router_behavior 2026-01-29 02:08:52,657 - 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-01-29 02:08:52,917 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.192': '0'}} with 2026-01-29 02:08:52,917 - INFO - Initial checks done. Running the testcase now 2026-01-29 02:08:52,917 - INFO - 2026-01-29 02:08:54,201 - DEBUG - Response for create_network : {'network': {'id': '60fe9127-25ee-4d9f-8d82-fe12d8bbc337', 'name': 'ctest-vn1-59047634', 'tenant_id': 'd3772f0c43404f54ae044c1d75f295c4', 'project_id': 'd3772f0c43404f54ae044c1d75f295c4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-71714009', 'ctest-vn1-59047634'], 'port_security_enabled': True, 'description': ''}} 2026-01-29 02:08:54,398 - DEBUG - Response for create_subnet : {'subnet': {'id': '4f7ba41f-c4ba-4ef7-8199-28a5daee6e5f', 'name': '', 'tenant_id': 'd3772f0c43404f54ae044c1d75f295c4', 'network_id': '60fe9127-25ee-4d9f-8d82-fe12d8bbc337', 'ip_version': 4, 'cidr': '113.100.160.0/26', 'allocation_pools': [{'start': '113.100.160.2', 'end': '113.100.160.62'}], 'gateway_ip': '113.100.160.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '113.100.160.2', 'tags': [], 'project_id': 'd3772f0c43404f54ae044c1d75f295c4'}} 2026-01-29 02:08:54,416 - INFO - Created VN ctest-vn1-59047634 2026-01-29 02:08:54,467 - DEBUG - VN ctest-vn1-59047634 UUID is 60fe9127-25ee-4d9f-8d82-fe12d8bbc337 2026-01-29 02:08:54,793 - DEBUG - Response for create_network : {'network': {'id': '92af7315-3b04-4352-af22-0b75e711f721', 'name': 'ctest-vn2-95387367', 'tenant_id': 'd3772f0c43404f54ae044c1d75f295c4', 'project_id': 'd3772f0c43404f54ae044c1d75f295c4', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-71714009', 'ctest-vn2-95387367'], 'port_security_enabled': True, 'description': ''}} 2026-01-29 02:08:54,978 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f34b4144-e0c5-499d-8065-c53dfb08ba83', 'name': '', 'tenant_id': 'd3772f0c43404f54ae044c1d75f295c4', 'network_id': '92af7315-3b04-4352-af22-0b75e711f721', 'ip_version': 4, 'cidr': '146.35.108.64/26', 'allocation_pools': [{'start': '146.35.108.66', 'end': '146.35.108.126'}], 'gateway_ip': '146.35.108.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '146.35.108.66', 'tags': [], 'project_id': 'd3772f0c43404f54ae044c1d75f295c4'}} 2026-01-29 02:08:54,997 - INFO - Created VN ctest-vn2-95387367 2026-01-29 02:08:55,063 - DEBUG - VN ctest-vn2-95387367 UUID is 92af7315-3b04-4352-af22-0b75e711f721 2026-01-29 02:08:55,238 - DEBUG - Services list from nova: [, , ] 2026-01-29 02:08:56,365 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5113-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5113-1) 2026-01-29 02:08:57,732 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5113-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5113-1) 2026-01-29 02:08:57,799 - INFO - Adding interface with subnet_id 4f7ba41f-c4ba-4ef7-8199-28a5daee6e5f, port_id None to router e65f522f-d72b-4d6d-b7c2-6f7a2f8cd3c3 2026-01-29 02:08:58,110 - INFO - Waiting for VM ctest-vn1-vm1-80406949 to be up.. 2026-01-29 02:08:58,215 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-29 02:09:03,293 - DEBUG - VM is in ACTIVE state now 2026-01-29 02:09:03,293 - INFO - VM name : ctest-vn1-vm1-80406949 2026-01-29 02:09:03,374 - DEBUG - VM ctest-vn1-vm1-80406949 ID is a36f0f3e-1830-40c5-8315-1364ddada07b 2026-01-29 02:09:03,396 - DEBUG - VM ctest-vn1-vm1-80406949 launched on Node cn-jenkins-deploy-platform-ansible-os-5113-1 2026-01-29 02:09:03,487 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/a36f0f3e-1830-40c5-8315-1364ddada07b 2026-01-29 02:09:03,828 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/a36f0f3e-1830-40c5-8315-1364ddada07b 2026-01-29 02:09:03,865 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/c13e6b54-4440-476c-bbfe-f6b96cb751cf 2026-01-29 02:09:07,143 - 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 1023ms') 2026-01-29 02:09:07,144 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-80406949 failed! 2026-01-29 02:09:07,199 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-71714009:ctest-vn1-59047634 is 113.100.160.1 and allocation pool is NOT set 2026-01-29 02:09:09,258 - 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.49 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.802 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 = 0.802/2.648/4.494/1.846 ms') 2026-01-29 02:09:09,258 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-80406949 passed 2026-01-29 02:09:09,415 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 02:09:09,415 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-80406949, IP 113.100.160.3, Port 22 2026-01-29 02:09:09,585 - DEBUG - VM ctest-vn1-vm1-80406949 is ready for SSH connections 2026-01-29 02:09:09,585 - INFO - Waiting for VM ctest-vn2-vm1-57039752 to be up.. 2026-01-29 02:09:09,679 - DEBUG - VM is in ACTIVE state now 2026-01-29 02:09:09,680 - INFO - VM name : ctest-vn2-vm1-57039752 2026-01-29 02:09:09,764 - DEBUG - VM ctest-vn2-vm1-57039752 ID is 1842b00c-8fbe-4afb-a053-5b27356d5a22 2026-01-29 02:09:09,764 - DEBUG - VM ctest-vn2-vm1-57039752 launched on Node cn-jenkins-deploy-platform-ansible-os-5113-1 2026-01-29 02:09:09,853 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/1842b00c-8fbe-4afb-a053-5b27356d5a22 2026-01-29 02:09:09,863 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/7a60a3f5-6f75-424c-88b7-8636d9aa0ef1 2026-01-29 02:09:11,110 - 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.64 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.499 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.499/2.070/3.642/1.571 ms') 2026-01-29 02:09:11,110 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-57039752 passed 2026-01-29 02:09:11,263 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 02:09:11,263 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-57039752, IP 146.35.108.67, Port 22 2026-01-29 02:09:11,419 - DEBUG - VM ctest-vn2-vm1-57039752 is ready for SSH connections 2026-01-29 02:09:11,419 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 02:09:11,419 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 146.35.108.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-01-29 02:09:11,419 - DEBUG - ping -s 56 -c 3 -W 1 146.35.108.67 2026-01-29 02:09:16,522 - DEBUG - PING 146.35.108.67 (146.35.108.67): 56 data bytes --- 146.35.108.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-01-29 02:09:16,522 - WARNING - Ping to IP 146.35.108.67 from VM ctest-vn1-vm1-80406949 failed 2026-01-29 02:09:16,522 - INFO - Adding interface with subnet_id f34b4144-e0c5-499d-8065-c53dfb08ba83, port_id None to router e65f522f-d72b-4d6d-b7c2-6f7a2f8cd3c3 2026-01-29 02:09:16,788 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 02:09:16,788 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 146.35.108.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-01-29 02:09:16,788 - DEBUG - ping -s 56 -c 3 -W 1 146.35.108.67 2026-01-29 02:09:20,020 - DEBUG - PING 146.35.108.67 (146.35.108.67): 56 data bytes 64 bytes from 146.35.108.67: seq=1 ttl=63 time=2.808 ms 64 bytes from 146.35.108.67: seq=2 ttl=63 time=1.057 ms --- 146.35.108.67 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.057/1.932/2.808 ms 2026-01-29 02:09:20,020 - WARNING - Ping to IP 146.35.108.67 from VM ctest-vn1-vm1-80406949 failed 2026-01-29 02:09:21,021 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 146.35.108.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-01-29 02:09:21,021 - DEBUG - ping -s 56 -c 3 -W 1 146.35.108.67 2026-01-29 02:09:23,255 - DEBUG - PING 146.35.108.67 (146.35.108.67): 56 data bytes 64 bytes from 146.35.108.67: seq=0 ttl=63 time=1.592 ms 64 bytes from 146.35.108.67: seq=1 ttl=63 time=1.066 ms 64 bytes from 146.35.108.67: seq=2 ttl=63 time=0.898 ms --- 146.35.108.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.898/1.185/1.592 ms 2026-01-29 02:09:23,255 - INFO - Ping to IP 146.35.108.67 from VM ctest-vn1-vm1-80406949 passed 2026-01-29 02:09:23,255 - INFO - Deleting interface with subnet_id 4f7ba41f-c4ba-4ef7-8199-28a5daee6e5f, port_id None from router e65f522f-d72b-4d6d-b7c2-6f7a2f8cd3c3 2026-01-29 02:09:23,380 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 02:09:23,380 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 146.35.108.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-01-29 02:09:23,380 - DEBUG - ping -s 56 -c 3 -W 1 146.35.108.67 2026-01-29 02:09:26,607 - DEBUG - PING 146.35.108.67 (146.35.108.67): 56 data bytes --- 146.35.108.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-01-29 02:09:26,608 - WARNING - Ping to IP 146.35.108.67 from VM ctest-vn1-vm1-80406949 failed 2026-01-29 02:09:26,608 - INFO - Adding interface with subnet_id 4f7ba41f-c4ba-4ef7-8199-28a5daee6e5f, port_id None to router e65f522f-d72b-4d6d-b7c2-6f7a2f8cd3c3 2026-01-29 02:09:26,830 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 02:09:26,830 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 146.35.108.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-01-29 02:09:26,830 - DEBUG - ping -s 56 -c 3 -W 1 146.35.108.67 2026-01-29 02:09:29,058 - DEBUG - PING 146.35.108.67 (146.35.108.67): 56 data bytes 64 bytes from 146.35.108.67: seq=0 ttl=63 time=1.770 ms 64 bytes from 146.35.108.67: seq=1 ttl=63 time=0.918 ms 64 bytes from 146.35.108.67: seq=2 ttl=63 time=0.924 ms --- 146.35.108.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.918/1.204/1.770 ms 2026-01-29 02:09:29,059 - INFO - Ping to IP 146.35.108.67 from VM ctest-vn1-vm1-80406949 passed 2026-01-29 02:09:29,059 - INFO - Deleting interface with subnet_id f34b4144-e0c5-499d-8065-c53dfb08ba83, port_id None from router e65f522f-d72b-4d6d-b7c2-6f7a2f8cd3c3 2026-01-29 02:09:29,177 - INFO - Deleting interface with subnet_id 4f7ba41f-c4ba-4ef7-8199-28a5daee6e5f, port_id None from router e65f522f-d72b-4d6d-b7c2-6f7a2f8cd3c3 2026-01-29 02:09:29,351 - INFO - Deleting VM ctest-vn2-vm1-57039752 2026-01-29 02:09:29,437 - INFO - Deleting VM ctest-vn1-vm1-80406949 2026-01-29 02:09:29,508 - INFO - Deleting VN ctest-vn2-95387367 2026-01-29 02:09:29,549 - DEBUG - VN 92af7315-3b04-4352-af22-0b75e711f721 still in use: Unable to complete operation on network 92af7315-3b04-4352-af22-0b75e711f721. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-6b0171c6-2eb2-42e0-8de8-9e663f5029e5'] 2026-01-29 02:09:29,549 - WARNING - Deleting VN ctest-vn2-95387367 failed..Will retry 2026-01-29 02:09:31,661 - DEBUG - Response for deleting network () 2026-01-29 02:09:31,661 - INFO - Deleting VN ctest-vn1-59047634 2026-01-29 02:09:31,778 - DEBUG - Response for deleting network () 2026-01-29 02:09:32,037 - DEBUG - No XMPP flaps were noticed during the test
2026-01-29 02:09:32,037 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:40]
2026-01-29 02:09:32,037 - INFO - -------------------------------------------------------------------------------- 2026-01-29 02:09:32,756 - INFO - Deleted project: ctest-TestRouters-71714009, ID : d3772f0c-4340-4f54-ae04-4c1d75f295c4