2026-02-20 02:38:52,804 - INFO - Domain Default found not creating 2026-02-20 02:38:52,983 - INFO - Project ctest-TestRouters-25362745 not found, creating it 2026-02-20 02:38:53,444 - INFO - Created Project:ctest-TestRouters-25362745, ID : e63f4f37-2927-4f7b-b27d-6c51869e279b 2026-02-20 02:38:54,968 - INFO - ================================================================================ 2026-02-20 02:38:54,968 - INFO - STARTING TEST : test_basic_router_behavior 2026-02-20 02:38:54,968 - 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-20 02:38:55,231 - DEBUG - Nothing to compare xmpp stats {'10.0.0.27': {'10.20.0.14': '0'}} with 2026-02-20 02:38:55,231 - INFO - Initial checks done. Running the testcase now 2026-02-20 02:38:55,231 - INFO - 2026-02-20 02:38:55,792 - DEBUG - Response for create_network : {'network': {'id': '84bade83-05f8-4498-aefe-deb68e9b2935', 'name': 'ctest-vn1-15245445', 'tenant_id': 'e63f4f3729274f7bb27d6c51869e279b', 'project_id': 'e63f4f3729274f7bb27d6c51869e279b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-25362745', 'ctest-vn1-15245445'], 'port_security_enabled': True, 'description': ''}} 2026-02-20 02:38:55,970 - DEBUG - Response for create_subnet : {'subnet': {'id': '3b37bad5-6820-4e2f-bbb6-8d663d5e26dd', 'name': '', 'tenant_id': 'e63f4f3729274f7bb27d6c51869e279b', 'network_id': '84bade83-05f8-4498-aefe-deb68e9b2935', 'ip_version': 4, 'cidr': '201.198.215.64/26', 'allocation_pools': [{'start': '201.198.215.66', 'end': '201.198.215.126'}], 'gateway_ip': '201.198.215.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '201.198.215.66', 'tags': [], 'project_id': 'e63f4f3729274f7bb27d6c51869e279b'}} 2026-02-20 02:38:55,988 - INFO - Created VN ctest-vn1-15245445 2026-02-20 02:38:56,038 - DEBUG - VN ctest-vn1-15245445 UUID is 84bade83-05f8-4498-aefe-deb68e9b2935 2026-02-20 02:38:56,378 - DEBUG - Response for create_network : {'network': {'id': '51e14bf0-504a-4f30-a617-fe77110ae2ea', 'name': 'ctest-vn2-91200694', 'tenant_id': 'e63f4f3729274f7bb27d6c51869e279b', 'project_id': 'e63f4f3729274f7bb27d6c51869e279b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-25362745', 'ctest-vn2-91200694'], 'port_security_enabled': True, 'description': ''}} 2026-02-20 02:38:56,575 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ec7bb70c-7b64-4749-a8e5-0a91a24f06b4', 'name': '', 'tenant_id': 'e63f4f3729274f7bb27d6c51869e279b', 'network_id': '51e14bf0-504a-4f30-a617-fe77110ae2ea', 'ip_version': 4, 'cidr': '158.77.228.192/26', 'allocation_pools': [{'start': '158.77.228.194', 'end': '158.77.228.254'}], 'gateway_ip': '158.77.228.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '158.77.228.194', 'tags': [], 'project_id': 'e63f4f3729274f7bb27d6c51869e279b'}} 2026-02-20 02:38:56,595 - INFO - Created VN ctest-vn2-91200694 2026-02-20 02:38:56,650 - DEBUG - VN ctest-vn2-91200694 UUID is 51e14bf0-504a-4f30-a617-fe77110ae2ea 2026-02-20 02:38:56,813 - DEBUG - Services list from nova: [, , ] 2026-02-20 02:38:57,966 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5367-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5367-1) 2026-02-20 02:38:59,084 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5367-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5367-1) 2026-02-20 02:38:59,151 - INFO - Adding interface with subnet_id 3b37bad5-6820-4e2f-bbb6-8d663d5e26dd, port_id None to router 153c3ba5-14e0-40f3-8747-b99934ae4fb7 2026-02-20 02:38:59,433 - INFO - Waiting for VM ctest-vn1-vm1-10865227 to be up.. 2026-02-20 02:38:59,522 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-02-20 02:39:04,599 - DEBUG - VM is in ACTIVE state now 2026-02-20 02:39:04,599 - INFO - VM name : ctest-vn1-vm1-10865227 2026-02-20 02:39:04,668 - DEBUG - VM ctest-vn1-vm1-10865227 ID is 9ff898e8-be41-44af-bb99-966df7dab940 2026-02-20 02:39:04,685 - DEBUG - VM ctest-vn1-vm1-10865227 launched on Node cn-jenkins-deploy-platform-ansible-os-5367-1 2026-02-20 02:39:04,768 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/9ff898e8-be41-44af-bb99-966df7dab940 2026-02-20 02:39:05,072 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/9ff898e8-be41-44af-bb99-966df7dab940 2026-02-20 02:39:05,102 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/f7818d08-6bb0-44b9-ad56-5acd3a1ee1b2 2026-02-20 02:39:08,367 - 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 1030ms') 2026-02-20 02:39:08,367 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-10865227 failed! 2026-02-20 02:39:08,422 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-25362745:ctest-vn1-15245445 is 201.198.215.65 and allocation pool is NOT set 2026-02-20 02:39:10,482 - 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.27 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.834 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.834/2.552/4.270/1.718 ms') 2026-02-20 02:39:10,482 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-10865227 passed 2026-02-20 02:39:10,630 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-20 02:39:10,630 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-10865227, IP 201.198.215.67, Port 22 2026-02-20 02:39:10,804 - DEBUG - VM ctest-vn1-vm1-10865227 is ready for SSH connections 2026-02-20 02:39:10,805 - INFO - Waiting for VM ctest-vn2-vm1-18985476 to be up.. 2026-02-20 02:39:10,881 - DEBUG - VM is in ACTIVE state now 2026-02-20 02:39:10,881 - INFO - VM name : ctest-vn2-vm1-18985476 2026-02-20 02:39:10,957 - DEBUG - VM ctest-vn2-vm1-18985476 ID is e49bef60-4faa-4e3b-bc34-1560f9a1ea4e 2026-02-20 02:39:10,958 - DEBUG - VM ctest-vn2-vm1-18985476 launched on Node cn-jenkins-deploy-platform-ansible-os-5367-1 2026-02-20 02:39:11,039 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/e49bef60-4faa-4e3b-bc34-1560f9a1ea4e 2026-02-20 02:39:11,047 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/081eec2c-dcf2-4be8-b1c0-93a9c156325b 2026-02-20 02:39:12,279 - 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.16 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.384 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.384/1.770/3.156/1.386 ms') 2026-02-20 02:39:12,279 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-18985476 passed 2026-02-20 02:39:12,434 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-20 02:39:12,434 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-18985476, IP 158.77.228.195, Port 22 2026-02-20 02:39:12,604 - DEBUG - VM ctest-vn2-vm1-18985476 is ready for SSH connections 2026-02-20 02:39:12,604 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-20 02:39:12,604 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 158.77.228.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2026-02-20 02:39:12,605 - DEBUG - ping -s 56 -c 3 -W 1 158.77.228.195 2026-02-20 02:39:17,605 - DEBUG - PING 158.77.228.195 (158.77.228.195): 56 data bytes --- 158.77.228.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-02-20 02:39:17,605 - WARNING - Ping to IP 158.77.228.195 from VM ctest-vn1-vm1-10865227 failed 2026-02-20 02:39:17,605 - INFO - Adding interface with subnet_id ec7bb70c-7b64-4749-a8e5-0a91a24f06b4, port_id None to router 153c3ba5-14e0-40f3-8747-b99934ae4fb7 2026-02-20 02:39:17,868 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-20 02:39:17,868 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 158.77.228.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2026-02-20 02:39:17,869 - DEBUG - ping -s 56 -c 3 -W 1 158.77.228.195 2026-02-20 02:39:21,081 - DEBUG - PING 158.77.228.195 (158.77.228.195): 56 data bytes 64 bytes from 158.77.228.195: seq=1 ttl=63 time=2.628 ms 64 bytes from 158.77.228.195: seq=2 ttl=63 time=1.168 ms --- 158.77.228.195 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.168/1.898/2.628 ms 2026-02-20 02:39:21,081 - WARNING - Ping to IP 158.77.228.195 from VM ctest-vn1-vm1-10865227 failed 2026-02-20 02:39:22,082 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 158.77.228.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2026-02-20 02:39:22,082 - DEBUG - ping -s 56 -c 3 -W 1 158.77.228.195 2026-02-20 02:39:24,297 - DEBUG - PING 158.77.228.195 (158.77.228.195): 56 data bytes 64 bytes from 158.77.228.195: seq=0 ttl=63 time=1.425 ms 64 bytes from 158.77.228.195: seq=1 ttl=63 time=0.779 ms 64 bytes from 158.77.228.195: seq=2 ttl=63 time=0.763 ms --- 158.77.228.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.763/0.989/1.425 ms 2026-02-20 02:39:24,297 - INFO - Ping to IP 158.77.228.195 from VM ctest-vn1-vm1-10865227 passed 2026-02-20 02:39:24,297 - INFO - Deleting interface with subnet_id 3b37bad5-6820-4e2f-bbb6-8d663d5e26dd, port_id None from router 153c3ba5-14e0-40f3-8747-b99934ae4fb7 2026-02-20 02:39:24,423 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-20 02:39:24,423 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 158.77.228.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2026-02-20 02:39:24,423 - DEBUG - ping -s 56 -c 3 -W 1 158.77.228.195 2026-02-20 02:39:27,636 - DEBUG - PING 158.77.228.195 (158.77.228.195): 56 data bytes --- 158.77.228.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-02-20 02:39:27,636 - WARNING - Ping to IP 158.77.228.195 from VM ctest-vn1-vm1-10865227 failed 2026-02-20 02:39:27,636 - INFO - Adding interface with subnet_id 3b37bad5-6820-4e2f-bbb6-8d663d5e26dd, port_id None to router 153c3ba5-14e0-40f3-8747-b99934ae4fb7 2026-02-20 02:39:27,841 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-20 02:39:27,841 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 158.77.228.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2026-02-20 02:39:27,842 - DEBUG - ping -s 56 -c 3 -W 1 158.77.228.195 2026-02-20 02:39:30,072 - DEBUG - PING 158.77.228.195 (158.77.228.195): 56 data bytes 64 bytes from 158.77.228.195: seq=0 ttl=63 time=1.917 ms 64 bytes from 158.77.228.195: seq=1 ttl=63 time=1.197 ms 64 bytes from 158.77.228.195: seq=2 ttl=63 time=1.025 ms --- 158.77.228.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.025/1.379/1.917 ms 2026-02-20 02:39:30,072 - INFO - Ping to IP 158.77.228.195 from VM ctest-vn1-vm1-10865227 passed 2026-02-20 02:39:30,072 - INFO - Deleting interface with subnet_id ec7bb70c-7b64-4749-a8e5-0a91a24f06b4, port_id None from router 153c3ba5-14e0-40f3-8747-b99934ae4fb7 2026-02-20 02:39:30,205 - INFO - Deleting interface with subnet_id 3b37bad5-6820-4e2f-bbb6-8d663d5e26dd, port_id None from router 153c3ba5-14e0-40f3-8747-b99934ae4fb7 2026-02-20 02:39:30,376 - INFO - Deleting VM ctest-vn2-vm1-18985476 2026-02-20 02:39:30,459 - INFO - Deleting VM ctest-vn1-vm1-10865227 2026-02-20 02:39:30,532 - INFO - Deleting VN ctest-vn2-91200694 2026-02-20 02:39:30,572 - DEBUG - VN 51e14bf0-504a-4f30-a617-fe77110ae2ea still in use: Unable to complete operation on network 51e14bf0-504a-4f30-a617-fe77110ae2ea. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-7a9e6865-4952-4695-9cdb-6d5d07d41480'] 2026-02-20 02:39:30,573 - WARNING - Deleting VN ctest-vn2-91200694 failed..Will retry 2026-02-20 02:39:32,676 - DEBUG - Response for deleting network () 2026-02-20 02:39:32,677 - INFO - Deleting VN ctest-vn1-15245445 2026-02-20 02:39:32,776 - DEBUG - Response for deleting network () 2026-02-20 02:39:33,031 - DEBUG - No XMPP flaps were noticed during the test
2026-02-20 02:39:33,031 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:39]
2026-02-20 02:39:33,031 - INFO - -------------------------------------------------------------------------------- 2026-02-20 02:39:33,738 - INFO - Deleted project: ctest-TestRouters-25362745, ID : e63f4f37-2927-4f7b-b27d-6c51869e279b