2025-11-24 17:27:51,268 - INFO - Domain Default found not creating 2025-11-24 17:27:51,534 - INFO - Project ctest-TestRouters-35278730 not found, creating it 2025-11-24 17:27:52,104 - INFO - Created Project:ctest-TestRouters-35278730, ID : a8e6f14a-7fd6-4710-b3d1-d226781ea67f 2025-11-24 17:27:54,260 - INFO - ================================================================================ 2025-11-24 17:27:54,260 - INFO - STARTING TEST : test_basic_router_behavior 2025-11-24 17:27:54,261 - 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-24 17:27:54,555 - DEBUG - Nothing to compare xmpp stats {'10.0.0.33': {'10.20.0.252': '0'}} with 2025-11-24 17:27:54,555 - INFO - Initial checks done. Running the testcase now 2025-11-24 17:27:54,555 - INFO - 2025-11-24 17:27:55,184 - DEBUG - Response for create_network : {'network': {'id': 'e493dc6f-6094-4d48-b674-d1e4b59fff59', 'name': 'ctest-vn1-35885804', 'tenant_id': 'a8e6f14a7fd64710b3d1d226781ea67f', 'project_id': 'a8e6f14a7fd64710b3d1d226781ea67f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-35278730', 'ctest-vn1-35885804'], 'port_security_enabled': True, 'description': ''}} 2025-11-24 17:27:55,339 - DEBUG - Response for create_subnet : {'subnet': {'id': '4589ca9c-8ab4-4565-b30a-fbb2817e360e', 'name': '', 'tenant_id': 'a8e6f14a7fd64710b3d1d226781ea67f', 'network_id': 'e493dc6f-6094-4d48-b674-d1e4b59fff59', 'ip_version': 4, 'cidr': '130.38.182.192/26', 'allocation_pools': [{'start': '130.38.182.194', 'end': '130.38.182.254'}], 'gateway_ip': '130.38.182.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '130.38.182.194', 'tags': [], 'project_id': 'a8e6f14a7fd64710b3d1d226781ea67f'}} 2025-11-24 17:27:55,358 - INFO - Created VN ctest-vn1-35885804 2025-11-24 17:27:55,411 - DEBUG - VN ctest-vn1-35885804 UUID is e493dc6f-6094-4d48-b674-d1e4b59fff59 2025-11-24 17:27:55,849 - DEBUG - Response for create_network : {'network': {'id': '53ad3556-f79a-41ff-9cb1-be4549b512b3', 'name': 'ctest-vn2-03497913', 'tenant_id': 'a8e6f14a7fd64710b3d1d226781ea67f', 'project_id': 'a8e6f14a7fd64710b3d1d226781ea67f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-35278730', 'ctest-vn2-03497913'], 'port_security_enabled': True, 'description': ''}} 2025-11-24 17:27:56,081 - DEBUG - Response for create_subnet : {'subnet': {'id': '91cb332c-ee8e-4b05-8d99-4871f73ebb71', 'name': '', 'tenant_id': 'a8e6f14a7fd64710b3d1d226781ea67f', 'network_id': '53ad3556-f79a-41ff-9cb1-be4549b512b3', 'ip_version': 4, 'cidr': '193.192.189.0/26', 'allocation_pools': [{'start': '193.192.189.2', 'end': '193.192.189.62'}], 'gateway_ip': '193.192.189.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '193.192.189.2', 'tags': [], 'project_id': 'a8e6f14a7fd64710b3d1d226781ea67f'}} 2025-11-24 17:27:56,105 - INFO - Created VN ctest-vn2-03497913 2025-11-24 17:27:56,163 - DEBUG - VN ctest-vn2-03497913 UUID is 53ad3556-f79a-41ff-9cb1-be4549b512b3 2025-11-24 17:27:56,394 - DEBUG - Services list from nova: [, , ] 2025-11-24 17:27:57,874 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4810-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4810-1) 2025-11-24 17:27:59,380 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4810-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4810-1) 2025-11-24 17:27:59,470 - INFO - Adding interface with subnet_id 4589ca9c-8ab4-4565-b30a-fbb2817e360e, port_id None to router 98123846-924a-45ee-b198-0af60feb21f8 2025-11-24 17:27:59,706 - INFO - Waiting for VM ctest-vn1-vm1-72714912 to be up.. 2025-11-24 17:27:59,802 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-24 17:28:04,883 - DEBUG - VM is in ACTIVE state now 2025-11-24 17:28:04,883 - INFO - VM name : ctest-vn1-vm1-72714912 2025-11-24 17:28:04,982 - DEBUG - VM ctest-vn1-vm1-72714912 ID is 38ca3673-a5f5-40d3-8369-ac1675852d61 2025-11-24 17:28:05,001 - DEBUG - VM ctest-vn1-vm1-72714912 launched on Node cn-jenkins-deploy-platform-ansible-os-4810-1 2025-11-24 17:28:05,088 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/38ca3673-a5f5-40d3-8369-ac1675852d61 2025-11-24 17:28:05,435 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/38ca3673-a5f5-40d3-8369-ac1675852d61 2025-11-24 17:28:05,508 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/3429506f-d3a7-45db-962a-ee226e1f469f 2025-11-24 17:28:08,761 - 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 1000ms') 2025-11-24 17:28:08,761 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-72714912 failed! 2025-11-24 17:28:08,836 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-35278730:ctest-vn1-35885804 is 130.38.182.193 and allocation pool is NOT set 2025-11-24 17:28:10,918 - 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=6.15 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.71 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 = 1.710/3.929/6.148/2.219 ms') 2025-11-24 17:28:10,918 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-72714912 passed 2025-11-24 17:28:11,095 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-24 17:28:11,095 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-72714912, IP 130.38.182.195, Port 22 2025-11-24 17:28:11,265 - DEBUG - VM ctest-vn1-vm1-72714912 is ready for SSH connections 2025-11-24 17:28:11,265 - INFO - Waiting for VM ctest-vn2-vm1-71295799 to be up.. 2025-11-24 17:28:11,383 - DEBUG - VM is in ACTIVE state now 2025-11-24 17:28:11,383 - INFO - VM name : ctest-vn2-vm1-71295799 2025-11-24 17:28:11,500 - DEBUG - VM ctest-vn2-vm1-71295799 ID is d7e9c4b1-8d14-4437-9e0f-5945367c08f4 2025-11-24 17:28:11,501 - DEBUG - VM ctest-vn2-vm1-71295799 launched on Node cn-jenkins-deploy-platform-ansible-os-4810-1 2025-11-24 17:28:11,620 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/d7e9c4b1-8d14-4437-9e0f-5945367c08f4 2025-11-24 17:28:11,631 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/79da7263-6914-4967-a6c8-c5f8b5b3ccc7 2025-11-24 17:28:12,919 - 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=5.36 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.00 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.998/3.676/5.355/1.678 ms') 2025-11-24 17:28:12,920 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-71295799 passed 2025-11-24 17:28:13,108 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-24 17:28:13,108 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-71295799, IP 193.192.189.3, Port 22 2025-11-24 17:28:13,305 - DEBUG - VM ctest-vn2-vm1-71295799 is ready for SSH connections 2025-11-24 17:28:13,306 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-24 17:28:13,306 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 193.192.189.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2025-11-24 17:28:13,306 - DEBUG - ping -s 56 -c 3 -W 1 193.192.189.3 2025-11-24 17:28:18,896 - DEBUG - PING 193.192.189.3 (193.192.189.3): 56 data bytes --- 193.192.189.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-24 17:28:18,897 - WARNING - Ping to IP 193.192.189.3 from VM ctest-vn1-vm1-72714912 failed 2025-11-24 17:28:18,897 - INFO - Adding interface with subnet_id 91cb332c-ee8e-4b05-8d99-4871f73ebb71, port_id None to router 98123846-924a-45ee-b198-0af60feb21f8 2025-11-24 17:28:19,320 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-24 17:28:19,321 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 193.192.189.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2025-11-24 17:28:19,321 - DEBUG - ping -s 56 -c 3 -W 1 193.192.189.3 2025-11-24 17:28:21,649 - DEBUG - PING 193.192.189.3 (193.192.189.3): 56 data bytes 64 bytes from 193.192.189.3: seq=0 ttl=63 time=7.952 ms 64 bytes from 193.192.189.3: seq=1 ttl=63 time=2.246 ms 64 bytes from 193.192.189.3: seq=2 ttl=63 time=1.417 ms --- 193.192.189.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.417/3.871/7.952 ms 2025-11-24 17:28:21,649 - INFO - Ping to IP 193.192.189.3 from VM ctest-vn1-vm1-72714912 passed 2025-11-24 17:28:21,650 - INFO - Deleting interface with subnet_id 4589ca9c-8ab4-4565-b30a-fbb2817e360e, port_id None from router 98123846-924a-45ee-b198-0af60feb21f8 2025-11-24 17:28:21,834 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-24 17:28:21,834 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 193.192.189.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2025-11-24 17:28:21,834 - DEBUG - ping -s 56 -c 3 -W 1 193.192.189.3 2025-11-24 17:28:25,079 - DEBUG - PING 193.192.189.3 (193.192.189.3): 56 data bytes --- 193.192.189.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-24 17:28:25,080 - WARNING - Ping to IP 193.192.189.3 from VM ctest-vn1-vm1-72714912 failed 2025-11-24 17:28:25,080 - INFO - Adding interface with subnet_id 4589ca9c-8ab4-4565-b30a-fbb2817e360e, port_id None to router 98123846-924a-45ee-b198-0af60feb21f8 2025-11-24 17:28:25,424 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-24 17:28:25,424 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 193.192.189.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.33, gateway password: c0ntrail123 2025-11-24 17:28:25,424 - DEBUG - ping -s 56 -c 3 -W 1 193.192.189.3 2025-11-24 17:28:27,669 - DEBUG - PING 193.192.189.3 (193.192.189.3): 56 data bytes 64 bytes from 193.192.189.3: seq=0 ttl=63 time=2.076 ms 64 bytes from 193.192.189.3: seq=1 ttl=63 time=2.038 ms 64 bytes from 193.192.189.3: seq=2 ttl=63 time=1.784 ms --- 193.192.189.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.784/1.966/2.076 ms 2025-11-24 17:28:27,669 - INFO - Ping to IP 193.192.189.3 from VM ctest-vn1-vm1-72714912 passed 2025-11-24 17:28:27,669 - INFO - Deleting interface with subnet_id 91cb332c-ee8e-4b05-8d99-4871f73ebb71, port_id None from router 98123846-924a-45ee-b198-0af60feb21f8 2025-11-24 17:28:27,842 - INFO - Deleting interface with subnet_id 4589ca9c-8ab4-4565-b30a-fbb2817e360e, port_id None from router 98123846-924a-45ee-b198-0af60feb21f8 2025-11-24 17:28:28,032 - INFO - Deleting VM ctest-vn2-vm1-71295799 2025-11-24 17:28:28,108 - INFO - Deleting VM ctest-vn1-vm1-72714912 2025-11-24 17:28:28,199 - INFO - Deleting VN ctest-vn2-03497913 2025-11-24 17:28:28,256 - DEBUG - VN 53ad3556-f79a-41ff-9cb1-be4549b512b3 still in use: Unable to complete operation on network 53ad3556-f79a-41ff-9cb1-be4549b512b3. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-6c938fd4-860f-450a-9d8d-44d8c4e91d43'] 2025-11-24 17:28:28,256 - WARNING - Deleting VN ctest-vn2-03497913 failed..Will retry 2025-11-24 17:28:30,420 - DEBUG - Response for deleting network () 2025-11-24 17:28:30,421 - INFO - Deleting VN ctest-vn1-35885804 2025-11-24 17:28:30,559 - DEBUG - Response for deleting network () 2025-11-24 17:28:30,867 - DEBUG - No XMPP flaps were noticed during the test
2025-11-24 17:28:30,868 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:36]
2025-11-24 17:28:30,868 - INFO - -------------------------------------------------------------------------------- 2025-11-24 17:28:31,742 - INFO - Deleted project: ctest-TestRouters-35278730, ID : a8e6f14a-7fd6-4710-b3d1-d226781ea67f