2026-04-28 14:55:34,198 - INFO - Domain Default found not creating 2026-04-28 14:55:34,381 - INFO - Project ctest-TestRouters-83910125 not found, creating it 2026-04-28 14:55:34,883 - INFO - Created Project:ctest-TestRouters-83910125, ID : ad63af4c-6537-4f89-80c7-93d615c5451a 2026-04-28 14:55:36,393 - INFO - ================================================================================ 2026-04-28 14:55:36,393 - INFO - STARTING TEST : test_basic_router_behavior 2026-04-28 14:55:36,393 - 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-04-28 14:55:36,648 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.17': '0'}} with 2026-04-28 14:55:36,648 - INFO - Initial checks done. Running the testcase now 2026-04-28 14:55:36,648 - INFO - 2026-04-28 14:55:37,241 - DEBUG - Response for create_network : {'network': {'id': 'c7fdc235-6045-445e-8d00-4cba74006745', 'name': 'ctest-vn1-59523352', 'tenant_id': 'ad63af4c65374f8980c793d615c5451a', 'project_id': 'ad63af4c65374f8980c793d615c5451a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-83910125', 'ctest-vn1-59523352'], 'port_security_enabled': True, 'description': ''}} 2026-04-28 14:55:37,434 - DEBUG - Response for create_subnet : {'subnet': {'id': '90970097-a674-4d00-a4a4-9cc64ba859a3', 'name': '', 'tenant_id': 'ad63af4c65374f8980c793d615c5451a', 'network_id': 'c7fdc235-6045-445e-8d00-4cba74006745', 'ip_version': 4, 'cidr': '102.119.45.128/26', 'allocation_pools': [{'start': '102.119.45.130', 'end': '102.119.45.190'}], 'gateway_ip': '102.119.45.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '102.119.45.130', 'tags': [], 'project_id': 'ad63af4c65374f8980c793d615c5451a'}} 2026-04-28 14:55:37,454 - INFO - Created VN ctest-vn1-59523352 2026-04-28 14:55:37,508 - DEBUG - VN ctest-vn1-59523352 UUID is c7fdc235-6045-445e-8d00-4cba74006745 2026-04-28 14:55:37,826 - DEBUG - Response for create_network : {'network': {'id': 'e23a75c9-0f0d-4338-bb36-1059a2f7b751', 'name': 'ctest-vn2-07309140', 'tenant_id': 'ad63af4c65374f8980c793d615c5451a', 'project_id': 'ad63af4c65374f8980c793d615c5451a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-83910125', 'ctest-vn2-07309140'], 'port_security_enabled': True, 'description': ''}} 2026-04-28 14:55:37,999 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ea1de9ec-e8a4-45cb-ae93-4950e5365fa1', 'name': '', 'tenant_id': 'ad63af4c65374f8980c793d615c5451a', 'network_id': 'e23a75c9-0f0d-4338-bb36-1059a2f7b751', 'ip_version': 4, 'cidr': '135.132.62.0/26', 'allocation_pools': [{'start': '135.132.62.2', 'end': '135.132.62.62'}], 'gateway_ip': '135.132.62.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '135.132.62.2', 'tags': [], 'project_id': 'ad63af4c65374f8980c793d615c5451a'}} 2026-04-28 14:55:38,022 - INFO - Created VN ctest-vn2-07309140 2026-04-28 14:55:38,076 - DEBUG - VN ctest-vn2-07309140 UUID is e23a75c9-0f0d-4338-bb36-1059a2f7b751 2026-04-28 14:55:38,244 - DEBUG - Services list from nova: [, , ] 2026-04-28 14:55:39,309 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5704-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5704-1) 2026-04-28 14:55:40,680 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5704-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5704-1) 2026-04-28 14:55:40,754 - INFO - Adding interface with subnet_id 90970097-a674-4d00-a4a4-9cc64ba859a3, port_id None to router a4d3bacd-5f4b-43f6-ba67-cb8eecb6e708 2026-04-28 14:55:41,057 - INFO - Waiting for VM ctest-vn1-vm1-76040243 to be up.. 2026-04-28 14:55:41,166 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-04-28 14:55:46,249 - DEBUG - VM is in ACTIVE state now 2026-04-28 14:55:46,250 - INFO - VM name : ctest-vn1-vm1-76040243 2026-04-28 14:55:46,339 - DEBUG - VM ctest-vn1-vm1-76040243 ID is 6e6cedf1-91de-4e0a-9af9-1c339bd688a1 2026-04-28 14:55:46,359 - DEBUG - VM ctest-vn1-vm1-76040243 launched on Node cn-jenkins-deploy-platform-ansible-os-5704-1 2026-04-28 14:55:46,465 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/6e6cedf1-91de-4e0a-9af9-1c339bd688a1 2026-04-28 14:55:46,766 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/6e6cedf1-91de-4e0a-9af9-1c339bd688a1 2026-04-28 14:55:46,800 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/03aa62fd-0f92-43b0-8128-fce4b9d5bf2a 2026-04-28 14:55:50,037 - 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 1003ms') 2026-04-28 14:55:50,037 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-76040243 failed! 2026-04-28 14:55:50,092 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-83910125:ctest-vn1-59523352 is 102.119.45.129 and allocation pool is NOT set 2026-04-28 14:55:52,156 - 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=3.94 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.728 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.728/2.336/3.944/1.608 ms') 2026-04-28 14:55:52,156 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-76040243 passed 2026-04-28 14:55:52,304 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-28 14:55:52,304 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-76040243, IP 102.119.45.131, Port 22 2026-04-28 14:55:52,463 - DEBUG - VM ctest-vn1-vm1-76040243 is ready for SSH connections 2026-04-28 14:55:52,463 - INFO - Waiting for VM ctest-vn2-vm1-06314892 to be up.. 2026-04-28 14:55:52,545 - DEBUG - VM is in ACTIVE state now 2026-04-28 14:55:52,545 - INFO - VM name : ctest-vn2-vm1-06314892 2026-04-28 14:55:52,625 - DEBUG - VM ctest-vn2-vm1-06314892 ID is df345c93-cd3a-497f-8dff-e6b6c2a9c5f3 2026-04-28 14:55:52,625 - DEBUG - VM ctest-vn2-vm1-06314892 launched on Node cn-jenkins-deploy-platform-ansible-os-5704-1 2026-04-28 14:55:52,709 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/df345c93-cd3a-497f-8dff-e6b6c2a9c5f3 2026-04-28 14:55:52,718 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/e854c7e4-9624-4ede-94ad-8e305ff82374 2026-04-28 14:55:53,957 - 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.92 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.68 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.680/3.298/4.916/1.618 ms') 2026-04-28 14:55:53,957 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-06314892 passed 2026-04-28 14:55:54,116 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-28 14:55:54,116 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-06314892, IP 135.132.62.3, Port 22 2026-04-28 14:55:54,284 - DEBUG - VM ctest-vn2-vm1-06314892 is ready for SSH connections 2026-04-28 14:55:54,284 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-28 14:55:54,284 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 135.132.62.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-04-28 14:55:54,284 - DEBUG - ping -s 56 -c 3 -W 1 135.132.62.3 2026-04-28 14:55:59,315 - DEBUG - PING 135.132.62.3 (135.132.62.3): 56 data bytes --- 135.132.62.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-04-28 14:55:59,315 - WARNING - Ping to IP 135.132.62.3 from VM ctest-vn1-vm1-76040243 failed 2026-04-28 14:55:59,315 - INFO - Adding interface with subnet_id ea1de9ec-e8a4-45cb-ae93-4950e5365fa1, port_id None to router a4d3bacd-5f4b-43f6-ba67-cb8eecb6e708 2026-04-28 14:55:59,564 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-28 14:55:59,564 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 135.132.62.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-04-28 14:55:59,564 - DEBUG - ping -s 56 -c 3 -W 1 135.132.62.3 2026-04-28 14:56:02,797 - DEBUG - PING 135.132.62.3 (135.132.62.3): 56 data bytes 64 bytes from 135.132.62.3: seq=1 ttl=63 time=4.179 ms 64 bytes from 135.132.62.3: seq=2 ttl=63 time=1.156 ms --- 135.132.62.3 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.156/2.667/4.179 ms 2026-04-28 14:56:02,797 - WARNING - Ping to IP 135.132.62.3 from VM ctest-vn1-vm1-76040243 failed 2026-04-28 14:56:03,798 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 135.132.62.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-04-28 14:56:03,798 - DEBUG - ping -s 56 -c 3 -W 1 135.132.62.3 2026-04-28 14:56:06,020 - DEBUG - PING 135.132.62.3 (135.132.62.3): 56 data bytes 64 bytes from 135.132.62.3: seq=0 ttl=63 time=1.654 ms 64 bytes from 135.132.62.3: seq=1 ttl=63 time=1.020 ms 64 bytes from 135.132.62.3: seq=2 ttl=63 time=0.963 ms --- 135.132.62.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.963/1.212/1.654 ms 2026-04-28 14:56:06,020 - INFO - Ping to IP 135.132.62.3 from VM ctest-vn1-vm1-76040243 passed 2026-04-28 14:56:06,020 - INFO - Deleting interface with subnet_id 90970097-a674-4d00-a4a4-9cc64ba859a3, port_id None from router a4d3bacd-5f4b-43f6-ba67-cb8eecb6e708 2026-04-28 14:56:06,149 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-28 14:56:06,150 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 135.132.62.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-04-28 14:56:06,150 - DEBUG - ping -s 56 -c 3 -W 1 135.132.62.3 2026-04-28 14:56:09,367 - DEBUG - PING 135.132.62.3 (135.132.62.3): 56 data bytes --- 135.132.62.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-04-28 14:56:09,368 - WARNING - Ping to IP 135.132.62.3 from VM ctest-vn1-vm1-76040243 failed 2026-04-28 14:56:09,368 - INFO - Adding interface with subnet_id 90970097-a674-4d00-a4a4-9cc64ba859a3, port_id None to router a4d3bacd-5f4b-43f6-ba67-cb8eecb6e708 2026-04-28 14:56:09,595 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-28 14:56:09,596 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 135.132.62.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-04-28 14:56:09,596 - DEBUG - ping -s 56 -c 3 -W 1 135.132.62.3 2026-04-28 14:56:11,816 - DEBUG - PING 135.132.62.3 (135.132.62.3): 56 data bytes 64 bytes from 135.132.62.3: seq=0 ttl=63 time=1.535 ms 64 bytes from 135.132.62.3: seq=1 ttl=63 time=2.731 ms 64 bytes from 135.132.62.3: seq=2 ttl=63 time=0.825 ms --- 135.132.62.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.825/1.697/2.731 ms 2026-04-28 14:56:11,816 - INFO - Ping to IP 135.132.62.3 from VM ctest-vn1-vm1-76040243 passed 2026-04-28 14:56:11,816 - INFO - Deleting interface with subnet_id ea1de9ec-e8a4-45cb-ae93-4950e5365fa1, port_id None from router a4d3bacd-5f4b-43f6-ba67-cb8eecb6e708 2026-04-28 14:56:11,957 - INFO - Deleting interface with subnet_id 90970097-a674-4d00-a4a4-9cc64ba859a3, port_id None from router a4d3bacd-5f4b-43f6-ba67-cb8eecb6e708 2026-04-28 14:56:12,155 - INFO - Deleting VM ctest-vn2-vm1-06314892 2026-04-28 14:56:12,238 - INFO - Deleting VM ctest-vn1-vm1-76040243 2026-04-28 14:56:12,325 - INFO - Deleting VN ctest-vn2-07309140 2026-04-28 14:56:12,363 - DEBUG - VN e23a75c9-0f0d-4338-bb36-1059a2f7b751 still in use: Unable to complete operation on network e23a75c9-0f0d-4338-bb36-1059a2f7b751. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-ec3eeb9f-16bc-463f-9941-e75047a3f4c1'] 2026-04-28 14:56:12,363 - WARNING - Deleting VN ctest-vn2-07309140 failed..Will retry 2026-04-28 14:56:14,475 - DEBUG - Response for deleting network () 2026-04-28 14:56:14,475 - INFO - Deleting VN ctest-vn1-59523352 2026-04-28 14:56:14,572 - DEBUG - Response for deleting network () 2026-04-28 14:56:14,826 - DEBUG - No XMPP flaps were noticed during the test
2026-04-28 14:56:14,826 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:38]
2026-04-28 14:56:14,827 - INFO - -------------------------------------------------------------------------------- 2026-04-28 14:56:15,516 - INFO - Deleted project: ctest-TestRouters-83910125, ID : ad63af4c-6537-4f89-80c7-93d615c5451a