2025-11-20 13:37:18,859 - INFO - Domain Default found not creating 2025-11-20 13:37:19,046 - INFO - Project ctest-TestRouters-97929370 not found, creating it 2025-11-20 13:37:19,532 - INFO - Created Project:ctest-TestRouters-97929370, ID : 8e867a48-a585-4586-9c95-faa93786967b 2025-11-20 13:37:21,070 - INFO - ================================================================================ 2025-11-20 13:37:21,070 - INFO - STARTING TEST : test_basic_router_behavior 2025-11-20 13:37:21,070 - 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 2025-11-20 13:37:21,329 - DEBUG - Nothing to compare xmpp stats {'10.0.0.128': {'10.20.0.241': '0'}} with 2025-11-20 13:37:21,329 - INFO - Initial checks done. Running the testcase now 2025-11-20 13:37:21,329 - INFO - 2025-11-20 13:37:21,905 - DEBUG - Response for create_network : {'network': {'id': 'ced41e66-7ae8-4a58-9fe4-6e9f0111418f', 'name': 'ctest-vn1-55342011', 'tenant_id': '8e867a48a58545869c95faa93786967b', 'project_id': '8e867a48a58545869c95faa93786967b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-97929370', 'ctest-vn1-55342011'], 'port_security_enabled': True, 'description': ''}} 2025-11-20 13:37:22,127 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c8e33a1d-da62-4c5b-ba59-306beb549f46', 'name': '', 'tenant_id': '8e867a48a58545869c95faa93786967b', 'network_id': 'ced41e66-7ae8-4a58-9fe4-6e9f0111418f', 'ip_version': 4, 'cidr': '76.136.189.64/26', 'allocation_pools': [{'start': '76.136.189.66', 'end': '76.136.189.126'}], 'gateway_ip': '76.136.189.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '76.136.189.66', 'tags': [], 'project_id': '8e867a48a58545869c95faa93786967b'}} 2025-11-20 13:37:22,145 - INFO - Created VN ctest-vn1-55342011 2025-11-20 13:37:22,198 - DEBUG - VN ctest-vn1-55342011 UUID is ced41e66-7ae8-4a58-9fe4-6e9f0111418f 2025-11-20 13:37:22,522 - DEBUG - Response for create_network : {'network': {'id': '88e4d0dd-aedb-4613-8d47-dfc17e210a75', 'name': 'ctest-vn2-10862084', 'tenant_id': '8e867a48a58545869c95faa93786967b', 'project_id': '8e867a48a58545869c95faa93786967b', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-97929370', 'ctest-vn2-10862084'], 'port_security_enabled': True, 'description': ''}} 2025-11-20 13:37:22,739 - DEBUG - Response for create_subnet : {'subnet': {'id': '34905d58-2221-4b14-b5df-0cf1f2dee968', 'name': '', 'tenant_id': '8e867a48a58545869c95faa93786967b', 'network_id': '88e4d0dd-aedb-4613-8d47-dfc17e210a75', 'ip_version': 4, 'cidr': '213.98.127.192/26', 'allocation_pools': [{'start': '213.98.127.194', 'end': '213.98.127.254'}], 'gateway_ip': '213.98.127.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '213.98.127.194', 'tags': [], 'project_id': '8e867a48a58545869c95faa93786967b'}} 2025-11-20 13:37:22,759 - INFO - Created VN ctest-vn2-10862084 2025-11-20 13:37:22,814 - DEBUG - VN ctest-vn2-10862084 UUID is 88e4d0dd-aedb-4613-8d47-dfc17e210a75 2025-11-20 13:37:23,017 - DEBUG - Services list from nova: [, , ] 2025-11-20 13:37:24,015 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4775-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4775-1) 2025-11-20 13:37:25,269 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4775-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4775-1) 2025-11-20 13:37:25,355 - INFO - Adding interface with subnet_id c8e33a1d-da62-4c5b-ba59-306beb549f46, port_id None to router 4a5218a0-a128-484e-8f9a-acbbf62f3d74 2025-11-20 13:37:25,682 - INFO - Waiting for VM ctest-vn1-vm1-36106226 to be up.. 2025-11-20 13:37:25,768 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-20 13:37:30,943 - DEBUG - VM is in ACTIVE state now 2025-11-20 13:37:30,943 - INFO - VM name : ctest-vn1-vm1-36106226 2025-11-20 13:37:31,079 - DEBUG - VM ctest-vn1-vm1-36106226 ID is b137db2a-5ad9-4a9f-b9e8-76f202ab5204 2025-11-20 13:37:31,107 - DEBUG - VM ctest-vn1-vm1-36106226 launched on Node cn-jenkins-deploy-platform-ansible-os-4775-1 2025-11-20 13:37:31,200 - DEBUG - Requesting: http://10.0.0.128:8082/virtual-machine/b137db2a-5ad9-4a9f-b9e8-76f202ab5204 2025-11-20 13:37:31,492 - DEBUG - Requesting: http://10.0.0.128:8082/virtual-machine/b137db2a-5ad9-4a9f-b9e8-76f202ab5204 2025-11-20 13:37:31,526 - DEBUG - Requesting: http://10.0.0.128:8082/virtual-machine-interface/986dc54e-65db-4b6f-89f0-e7a6f8a02720 2025-11-20 13:37:34,779 - 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 1026ms') 2025-11-20 13:37:34,779 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-36106226 failed! 2025-11-20 13:37:34,834 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-97929370:ctest-vn1-55342011 is 76.136.189.65 and allocation pool is NOT set 2025-11-20 13:37:36,896 - 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.06 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.561 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.561/1.809/3.057/1.248 ms') 2025-11-20 13:37:36,896 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-36106226 passed 2025-11-20 13:37:37,046 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-20 13:37:37,046 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-36106226, IP 76.136.189.67, Port 22 2025-11-20 13:37:37,198 - DEBUG - VM ctest-vn1-vm1-36106226 is ready for SSH connections 2025-11-20 13:37:37,198 - INFO - Waiting for VM ctest-vn2-vm1-73065165 to be up.. 2025-11-20 13:37:37,270 - DEBUG - VM is in ACTIVE state now 2025-11-20 13:37:37,270 - INFO - VM name : ctest-vn2-vm1-73065165 2025-11-20 13:37:37,339 - DEBUG - VM ctest-vn2-vm1-73065165 ID is 12bafb68-4507-4846-b30b-b512bc4bec63 2025-11-20 13:37:37,340 - DEBUG - VM ctest-vn2-vm1-73065165 launched on Node cn-jenkins-deploy-platform-ansible-os-4775-1 2025-11-20 13:37:37,410 - DEBUG - Requesting: http://10.0.0.128:8082/virtual-machine/12bafb68-4507-4846-b30b-b512bc4bec63 2025-11-20 13:37:37,419 - DEBUG - Requesting: http://10.0.0.128:8082/virtual-machine-interface/c9b45669-8bf9-4bc8-8945-bef1d37cc141 2025-11-20 13:37:38,654 - 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.61 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.518 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.518/2.062/3.607/1.544 ms') 2025-11-20 13:37:38,654 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-73065165 passed 2025-11-20 13:37:38,802 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-20 13:37:38,802 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-73065165, IP 213.98.127.195, Port 22 2025-11-20 13:37:38,957 - DEBUG - VM ctest-vn2-vm1-73065165 is ready for SSH connections 2025-11-20 13:37:38,957 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-20 13:37:38,957 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 213.98.127.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.128, gateway password: c0ntrail123 2025-11-20 13:37:38,957 - DEBUG - ping -s 56 -c 3 -W 1 213.98.127.195 2025-11-20 13:37:43,933 - DEBUG - PING 213.98.127.195 (213.98.127.195): 56 data bytes --- 213.98.127.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-20 13:37:43,933 - WARNING - Ping to IP 213.98.127.195 from VM ctest-vn1-vm1-36106226 failed 2025-11-20 13:37:43,933 - INFO - Adding interface with subnet_id 34905d58-2221-4b14-b5df-0cf1f2dee968, port_id None to router 4a5218a0-a128-484e-8f9a-acbbf62f3d74 2025-11-20 13:37:44,167 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-20 13:37:44,167 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 213.98.127.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.128, gateway password: c0ntrail123 2025-11-20 13:37:44,167 - DEBUG - ping -s 56 -c 3 -W 1 213.98.127.195 2025-11-20 13:37:47,372 - DEBUG - PING 213.98.127.195 (213.98.127.195): 56 data bytes 64 bytes from 213.98.127.195: seq=1 ttl=63 time=2.799 ms 64 bytes from 213.98.127.195: seq=2 ttl=63 time=0.932 ms --- 213.98.127.195 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 0.932/1.865/2.799 ms 2025-11-20 13:37:47,372 - WARNING - Ping to IP 213.98.127.195 from VM ctest-vn1-vm1-36106226 failed 2025-11-20 13:37:48,373 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 213.98.127.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.128, gateway password: c0ntrail123 2025-11-20 13:37:48,374 - DEBUG - ping -s 56 -c 3 -W 1 213.98.127.195 2025-11-20 13:37:50,589 - DEBUG - PING 213.98.127.195 (213.98.127.195): 56 data bytes 64 bytes from 213.98.127.195: seq=0 ttl=63 time=1.372 ms 64 bytes from 213.98.127.195: seq=1 ttl=63 time=0.865 ms 64 bytes from 213.98.127.195: seq=2 ttl=63 time=0.934 ms --- 213.98.127.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.865/1.057/1.372 ms 2025-11-20 13:37:50,589 - INFO - Ping to IP 213.98.127.195 from VM ctest-vn1-vm1-36106226 passed 2025-11-20 13:37:50,589 - INFO - Deleting interface with subnet_id c8e33a1d-da62-4c5b-ba59-306beb549f46, port_id None from router 4a5218a0-a128-484e-8f9a-acbbf62f3d74 2025-11-20 13:37:50,715 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-20 13:37:50,715 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 213.98.127.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.128, gateway password: c0ntrail123 2025-11-20 13:37:50,715 - DEBUG - ping -s 56 -c 3 -W 1 213.98.127.195 2025-11-20 13:37:53,970 - DEBUG - PING 213.98.127.195 (213.98.127.195): 56 data bytes --- 213.98.127.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-20 13:37:53,970 - WARNING - Ping to IP 213.98.127.195 from VM ctest-vn1-vm1-36106226 failed 2025-11-20 13:37:53,970 - INFO - Adding interface with subnet_id c8e33a1d-da62-4c5b-ba59-306beb549f46, port_id None to router 4a5218a0-a128-484e-8f9a-acbbf62f3d74 2025-11-20 13:37:54,190 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-20 13:37:54,190 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 213.98.127.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.128, gateway password: c0ntrail123 2025-11-20 13:37:54,190 - DEBUG - ping -s 56 -c 3 -W 1 213.98.127.195 2025-11-20 13:37:56,407 - DEBUG - PING 213.98.127.195 (213.98.127.195): 56 data bytes 64 bytes from 213.98.127.195: seq=0 ttl=63 time=1.487 ms 64 bytes from 213.98.127.195: seq=1 ttl=63 time=0.909 ms 64 bytes from 213.98.127.195: seq=2 ttl=63 time=0.831 ms --- 213.98.127.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.831/1.075/1.487 ms 2025-11-20 13:37:56,408 - INFO - Ping to IP 213.98.127.195 from VM ctest-vn1-vm1-36106226 passed 2025-11-20 13:37:56,408 - INFO - Deleting interface with subnet_id 34905d58-2221-4b14-b5df-0cf1f2dee968, port_id None from router 4a5218a0-a128-484e-8f9a-acbbf62f3d74 2025-11-20 13:37:56,528 - INFO - Deleting interface with subnet_id c8e33a1d-da62-4c5b-ba59-306beb549f46, port_id None from router 4a5218a0-a128-484e-8f9a-acbbf62f3d74 2025-11-20 13:37:56,709 - INFO - Deleting VM ctest-vn2-vm1-73065165 2025-11-20 13:37:56,787 - INFO - Deleting VM ctest-vn1-vm1-36106226 2025-11-20 13:37:56,869 - INFO - Deleting VN ctest-vn2-10862084 2025-11-20 13:37:56,904 - DEBUG - VN 88e4d0dd-aedb-4613-8d47-dfc17e210a75 still in use: Unable to complete operation on network 88e4d0dd-aedb-4613-8d47-dfc17e210a75. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-c19004ca-abb9-4c76-a97a-cff6765b9758'] 2025-11-20 13:37:56,904 - WARNING - Deleting VN ctest-vn2-10862084 failed..Will retry 2025-11-20 13:37:59,005 - DEBUG - Response for deleting network () 2025-11-20 13:37:59,005 - INFO - Deleting VN ctest-vn1-55342011 2025-11-20 13:37:59,106 - DEBUG - Response for deleting network () 2025-11-20 13:37:59,363 - DEBUG - No XMPP flaps were noticed during the test
2025-11-20 13:37:59,363 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:38]
2025-11-20 13:37:59,363 - INFO - -------------------------------------------------------------------------------- 2025-11-20 13:38:00,029 - INFO - Deleted project: ctest-TestRouters-97929370, ID : 8e867a48-a585-4586-9c95-faa93786967b