2026-09-19 12:03:31,183 - INFO - Domain Default found not creating 2026-09-19 12:03:31,361 - INFO - Project ctest-TestRouters-26370530 not found, creating it 2026-09-19 12:03:31,861 - INFO - Created Project:ctest-TestRouters-26370530, ID : 6281b2cf-46ef-410d-8f13-5704c900302d 2026-09-19 12:03:33,458 - INFO - ================================================================================ 2026-09-19 12:03:33,458 - INFO - STARTING TEST : test_basic_router_behavior 2026-09-19 12:03:33,458 - 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-09-19 12:03:33,714 - DEBUG - Nothing to compare xmpp stats {'10.0.0.33': {'10.20.0.17': '0'}} with 2026-09-19 12:03:33,714 - INFO - Initial checks done. Running the testcase now 2026-09-19 12:03:33,714 - INFO - 2026-09-19 12:03:34,331 - DEBUG - Response for create_network : {'network': {'id': '20d6b324-3419-489f-b220-b924fbee531e', 'name': 'ctest-vn1-18521783', 'tenant_id': '6281b2cf46ef410d8f135704c900302d', 'project_id': '6281b2cf46ef410d8f135704c900302d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-26370530', 'ctest-vn1-18521783'], 'port_security_enabled': True, 'description': ''}} 2026-09-19 12:03:34,664 - DEBUG - Response for create_subnet : {'subnet': {'id': '3ace021f-f771-4c20-80c1-f6f019f199ce', 'name': '', 'tenant_id': '6281b2cf46ef410d8f135704c900302d', 'network_id': '20d6b324-3419-489f-b220-b924fbee531e', 'ip_version': 4, 'cidr': '167.141.224.128/26', 'allocation_pools': [{'start': '167.141.224.130', 'end': '167.141.224.190'}], 'gateway_ip': '167.141.224.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '167.141.224.130', 'tags': [], 'project_id': '6281b2cf46ef410d8f135704c900302d'}} 2026-09-19 12:03:34,683 - INFO - Created VN ctest-vn1-18521783 2026-09-19 12:03:34,736 - DEBUG - VN ctest-vn1-18521783 UUID is 20d6b324-3419-489f-b220-b924fbee531e 2026-09-19 12:03:35,053 - DEBUG - Response for create_network : {'network': {'id': '2e3c99cd-6829-435e-aad2-3be21ea2233d', 'name': 'ctest-vn2-00580802', 'tenant_id': '6281b2cf46ef410d8f135704c900302d', 'project_id': '6281b2cf46ef410d8f135704c900302d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-26370530', 'ctest-vn2-00580802'], 'port_security_enabled': True, 'description': ''}} 2026-09-19 12:03:35,291 - DEBUG - Response for create_subnet : {'subnet': {'id': '025dc26d-c38f-44d0-8522-cff0860ed3bb', 'name': '', 'tenant_id': '6281b2cf46ef410d8f135704c900302d', 'network_id': '2e3c99cd-6829-435e-aad2-3be21ea2233d', 'ip_version': 4, 'cidr': '112.149.112.128/26', 'allocation_pools': [{'start': '112.149.112.130', 'end': '112.149.112.190'}], 'gateway_ip': '112.149.112.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '112.149.112.130', 'tags': [], 'project_id': '6281b2cf46ef410d8f135704c900302d'}} 2026-09-19 12:03:35,310 - INFO - Created VN ctest-vn2-00580802 2026-09-19 12:03:35,364 - DEBUG - VN ctest-vn2-00580802 UUID is 2e3c99cd-6829-435e-aad2-3be21ea2233d 2026-09-19 12:03:35,659 - DEBUG - Services list from nova: [, , ] 2026-09-19 12:03:36,031 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6311-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6311-1) 2026-09-19 12:03:36,658 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6311-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6311-1) 2026-09-19 12:03:36,726 - INFO - Adding interface with subnet_id 3ace021f-f771-4c20-80c1-f6f019f199ce, port_id None to router e58bd969-6673-4530-be00-7bb9ec5a3fc8 2026-09-19 12:03:37,001 - INFO - Waiting for VM ctest-vn1-vm1-64200629 to be up.. 2026-09-19 12:03:37,067 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-19 12:03:42,196 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-19 12:03:47,275 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-19 12:03:52,358 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-19 12:03:57,433 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-19 12:04:02,519 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-19 12:04:07,603 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-19 12:04:12,685 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-19 12:04:17,780 - DEBUG - VM is in ACTIVE state now 2026-09-19 12:04:17,780 - INFO - VM name : ctest-vn1-vm1-64200629 2026-09-19 12:04:17,873 - DEBUG - VM ctest-vn1-vm1-64200629 ID is f3fb7342-5cbf-472e-ba70-125f918e9738 2026-09-19 12:04:17,894 - DEBUG - VM ctest-vn1-vm1-64200629 launched on Node cn-jenkins-deploy-platform-ansible-os-6311-1 2026-09-19 12:04:17,993 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/f3fb7342-5cbf-472e-ba70-125f918e9738 2026-09-19 12:04:18,332 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/f3fb7342-5cbf-472e-ba70-125f918e9738 2026-09-19 12:04:18,367 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/1dba674f-6e75-4a9c-afa2-53a03ac90cf8 2026-09-19 12:04:21,636 - 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 1021ms') 2026-09-19 12:04:21,636 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-64200629 failed! 2026-09-19 12:04:21,692 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-26370530:ctest-vn1-18521783 is 167.141.224.129 and allocation pool is NOT set 2026-09-19 12:04:23,754 - 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.43 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.457 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.457/1.944/3.431/1.487 ms') 2026-09-19 12:04:23,755 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-64200629 passed 2026-09-19 12:04:23,904 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-19 12:04:23,904 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-64200629, IP 167.141.224.131, Port 22 2026-09-19 12:04:24,059 - DEBUG - VM ctest-vn1-vm1-64200629 is ready for SSH connections 2026-09-19 12:04:24,060 - INFO - Waiting for VM ctest-vn2-vm1-06437164 to be up.. 2026-09-19 12:04:24,143 - DEBUG - VM is in ACTIVE state now 2026-09-19 12:04:24,144 - INFO - VM name : ctest-vn2-vm1-06437164 2026-09-19 12:04:24,230 - DEBUG - VM ctest-vn2-vm1-06437164 ID is 81508588-7cec-4878-94da-e294d6130c21 2026-09-19 12:04:24,231 - DEBUG - VM ctest-vn2-vm1-06437164 launched on Node cn-jenkins-deploy-platform-ansible-os-6311-1 2026-09-19 12:04:24,308 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/81508588-7cec-4878-94da-e294d6130c21 2026-09-19 12:04:24,317 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/b6d58695-bdc7-4855-acd7-0e5ca43c365a 2026-09-19 12:04:25,557 - 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.45 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.591 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.591/2.019/3.447/1.428 ms') 2026-09-19 12:04:25,557 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-06437164 passed 2026-09-19 12:04:25,712 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-19 12:04:25,712 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-06437164, IP 112.149.112.131, Port 22 2026-09-19 12:04:25,878 - DEBUG - VM ctest-vn2-vm1-06437164 is ready for SSH connections 2026-09-19 12:04:25,878 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-19 12:04:25,878 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 112.149.112.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2026-09-19 12:04:25,878 - DEBUG - ping -s 56 -c 3 -W 1 112.149.112.131 2026-09-19 12:04:30,892 - DEBUG - PING 112.149.112.131 (112.149.112.131): 56 data bytes --- 112.149.112.131 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-09-19 12:04:31,136 - WARNING - Ping to IP 112.149.112.131 from VM ctest-vn1-vm1-64200629 failed 2026-09-19 12:04:31,136 - INFO - Adding interface with subnet_id 025dc26d-c38f-44d0-8522-cff0860ed3bb, port_id None to router e58bd969-6673-4530-be00-7bb9ec5a3fc8 2026-09-19 12:04:31,460 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-19 12:04:31,461 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 112.149.112.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2026-09-19 12:04:31,461 - DEBUG - ping -s 56 -c 3 -W 1 112.149.112.131 2026-09-19 12:04:34,606 - DEBUG - PING 112.149.112.131 (112.149.112.131): 56 data bytes 64 bytes from 112.149.112.131: seq=1 ttl=63 time=3.945 ms 64 bytes from 112.149.112.131: seq=2 ttl=63 time=1.202 ms --- 112.149.112.131 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.202/2.573/3.945 ms 2026-09-19 12:04:34,606 - WARNING - Ping to IP 112.149.112.131 from VM ctest-vn1-vm1-64200629 failed 2026-09-19 12:04:35,607 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 112.149.112.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2026-09-19 12:04:35,607 - DEBUG - ping -s 56 -c 3 -W 1 112.149.112.131 2026-09-19 12:04:37,724 - DEBUG - PING 112.149.112.131 (112.149.112.131): 56 data bytes 64 bytes from 112.149.112.131: seq=0 ttl=63 time=2.257 ms 64 bytes from 112.149.112.131: seq=1 ttl=63 time=1.139 ms 64 bytes from 112.149.112.131: seq=2 ttl=63 time=1.064 ms --- 112.149.112.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.064/1.486/2.257 ms 2026-09-19 12:04:37,724 - INFO - Ping to IP 112.149.112.131 from VM ctest-vn1-vm1-64200629 passed 2026-09-19 12:04:37,724 - INFO - Deleting interface with subnet_id 3ace021f-f771-4c20-80c1-f6f019f199ce, port_id None from router e58bd969-6673-4530-be00-7bb9ec5a3fc8 2026-09-19 12:04:37,874 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-19 12:04:37,874 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 112.149.112.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2026-09-19 12:04:37,875 - DEBUG - ping -s 56 -c 3 -W 1 112.149.112.131 2026-09-19 12:04:41,024 - DEBUG - PING 112.149.112.131 (112.149.112.131): 56 data bytes --- 112.149.112.131 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-09-19 12:04:41,025 - WARNING - Ping to IP 112.149.112.131 from VM ctest-vn1-vm1-64200629 failed 2026-09-19 12:04:41,025 - INFO - Adding interface with subnet_id 3ace021f-f771-4c20-80c1-f6f019f199ce, port_id None to router e58bd969-6673-4530-be00-7bb9ec5a3fc8 2026-09-19 12:04:41,291 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-19 12:04:41,291 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 112.149.112.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2026-09-19 12:04:41,291 - DEBUG - ping -s 56 -c 3 -W 1 112.149.112.131 2026-09-19 12:04:43,429 - DEBUG - PING 112.149.112.131 (112.149.112.131): 56 data bytes 64 bytes from 112.149.112.131: seq=0 ttl=63 time=2.699 ms 64 bytes from 112.149.112.131: seq=1 ttl=63 time=1.083 ms 64 bytes from 112.149.112.131: seq=2 ttl=63 time=1.682 ms --- 112.149.112.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.083/1.821/2.699 ms 2026-09-19 12:04:43,430 - INFO - Ping to IP 112.149.112.131 from VM ctest-vn1-vm1-64200629 passed 2026-09-19 12:04:43,430 - INFO - Deleting interface with subnet_id 025dc26d-c38f-44d0-8522-cff0860ed3bb, port_id None from router e58bd969-6673-4530-be00-7bb9ec5a3fc8 2026-09-19 12:04:43,582 - INFO - Deleting interface with subnet_id 3ace021f-f771-4c20-80c1-f6f019f199ce, port_id None from router e58bd969-6673-4530-be00-7bb9ec5a3fc8 2026-09-19 12:04:43,796 - INFO - Deleting VM ctest-vn2-vm1-06437164 2026-09-19 12:04:43,884 - INFO - Deleting VM ctest-vn1-vm1-64200629 2026-09-19 12:04:43,968 - INFO - Deleting VN ctest-vn2-00580802 2026-09-19 12:04:44,006 - DEBUG - VN 2e3c99cd-6829-435e-aad2-3be21ea2233d still in use: Unable to complete operation on network 2e3c99cd-6829-435e-aad2-3be21ea2233d. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-435ba85e-2eb8-4842-abfe-0e5e1232bb0a'] 2026-09-19 12:04:44,006 - WARNING - Deleting VN ctest-vn2-00580802 failed..Will retry 2026-09-19 12:04:46,148 - DEBUG - Response for deleting network () 2026-09-19 12:04:46,149 - INFO - Deleting VN ctest-vn1-18521783 2026-09-19 12:04:46,189 - DEBUG - VN 20d6b324-3419-489f-b220-b924fbee531e still in use: Unable to complete operation on network 20d6b324-3419-489f-b220-b924fbee531e. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-1d1f8e3f-8fdc-4185-9921-8619d6e60b0b'] 2026-09-19 12:04:46,189 - WARNING - Deleting VN ctest-vn1-18521783 failed..Will retry 2026-09-19 12:04:48,309 - DEBUG - Response for deleting network () 2026-09-19 12:04:48,575 - DEBUG - No XMPP flaps were noticed during the test
2026-09-19 12:04:48,575 - INFO - END TEST : test_basic_router_behavior : PASSED[0:01:15]
2026-09-19 12:04:48,575 - INFO - -------------------------------------------------------------------------------- 2026-09-19 12:04:49,316 - INFO - Deleted project: ctest-TestRouters-26370530, ID : 6281b2cf-46ef-410d-8f13-5704c900302d