2025-10-09 14:06:54,784 - INFO - Domain Default found not creating 2025-10-09 14:06:54,977 - INFO - Project ctest-TestRouters-59166697 not found, creating it 2025-10-09 14:06:55,508 - INFO - Created Project:ctest-TestRouters-59166697, ID : 5e084013-6a33-4ed7-82dc-3d1dcbb75d10 2025-10-09 14:06:57,235 - INFO - ================================================================================ 2025-10-09 14:06:57,235 - INFO - STARTING TEST : test_basic_router_behavior 2025-10-09 14:06:57,235 - 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-10-09 14:06:57,508 - DEBUG - Nothing to compare xmpp stats {'10.0.0.27': {'10.20.0.254': '0'}} with 2025-10-09 14:06:57,508 - INFO - Initial checks done. Running the testcase now 2025-10-09 14:06:57,508 - INFO - 2025-10-09 14:06:58,142 - DEBUG - Response for create_network : {'network': {'id': '76216493-f864-47c0-a4b3-0a3222a60728', 'name': 'ctest-vn1-74411313', 'tenant_id': '5e0840136a334ed782dc3d1dcbb75d10', 'project_id': '5e0840136a334ed782dc3d1dcbb75d10', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-59166697', 'ctest-vn1-74411313'], 'port_security_enabled': True, 'description': ''}} 2025-10-09 14:06:58,311 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd9336b5c-609d-4f23-8d96-e1c7532bd2a1', 'name': '', 'tenant_id': '5e0840136a334ed782dc3d1dcbb75d10', 'network_id': '76216493-f864-47c0-a4b3-0a3222a60728', 'ip_version': 4, 'cidr': '178.158.207.64/26', 'allocation_pools': [{'start': '178.158.207.66', 'end': '178.158.207.126'}], 'gateway_ip': '178.158.207.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '178.158.207.66', 'tags': [], 'project_id': '5e0840136a334ed782dc3d1dcbb75d10'}} 2025-10-09 14:06:58,334 - INFO - Created VN ctest-vn1-74411313 2025-10-09 14:06:58,388 - DEBUG - VN ctest-vn1-74411313 UUID is 76216493-f864-47c0-a4b3-0a3222a60728 2025-10-09 14:06:58,693 - DEBUG - Response for create_network : {'network': {'id': 'b697cf68-678a-422a-bf01-0e0a65dddf34', 'name': 'ctest-vn2-81522672', 'tenant_id': '5e0840136a334ed782dc3d1dcbb75d10', 'project_id': '5e0840136a334ed782dc3d1dcbb75d10', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-59166697', 'ctest-vn2-81522672'], 'port_security_enabled': True, 'description': ''}} 2025-10-09 14:06:58,913 - DEBUG - Response for create_subnet : {'subnet': {'id': '8f40eee5-36e4-4dae-947f-77ddc778c5b9', 'name': '', 'tenant_id': '5e0840136a334ed782dc3d1dcbb75d10', 'network_id': 'b697cf68-678a-422a-bf01-0e0a65dddf34', 'ip_version': 4, 'cidr': '108.143.39.128/26', 'allocation_pools': [{'start': '108.143.39.130', 'end': '108.143.39.190'}], 'gateway_ip': '108.143.39.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '108.143.39.130', 'tags': [], 'project_id': '5e0840136a334ed782dc3d1dcbb75d10'}} 2025-10-09 14:06:58,932 - INFO - Created VN ctest-vn2-81522672 2025-10-09 14:06:58,988 - DEBUG - VN ctest-vn2-81522672 UUID is b697cf68-678a-422a-bf01-0e0a65dddf34 2025-10-09 14:06:59,200 - DEBUG - Services list from nova: [, , ] 2025-10-09 14:07:00,297 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4262-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4262-1) 2025-10-09 14:07:01,645 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4262-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4262-1) 2025-10-09 14:07:01,722 - INFO - Adding interface with subnet_id d9336b5c-609d-4f23-8d96-e1c7532bd2a1, port_id None to router 36c4fb94-98ac-4d08-becf-903c401df21b 2025-10-09 14:07:02,083 - INFO - Waiting for VM ctest-vn1-vm1-05848755 to be up.. 2025-10-09 14:07:02,277 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-09 14:07:07,369 - DEBUG - VM is in ACTIVE state now 2025-10-09 14:07:07,369 - INFO - VM name : ctest-vn1-vm1-05848755 2025-10-09 14:07:07,461 - DEBUG - VM ctest-vn1-vm1-05848755 ID is ab7c1692-4c44-40c0-b9e5-61bd1c1fa30a 2025-10-09 14:07:07,483 - DEBUG - VM ctest-vn1-vm1-05848755 launched on Node cn-jenkins-deploy-platform-ansible-os-4262-1 2025-10-09 14:07:07,598 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/ab7c1692-4c44-40c0-b9e5-61bd1c1fa30a 2025-10-09 14:07:07,905 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/ab7c1692-4c44-40c0-b9e5-61bd1c1fa30a 2025-10-09 14:07:07,938 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/2679e397-2a27-49ae-90e1-9d08a9ae0595 2025-10-09 14:07:11,192 - 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 1001ms') 2025-10-09 14:07:11,192 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-05848755 failed! 2025-10-09 14:07:11,248 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-59166697:ctest-vn1-74411313 is 178.158.207.65 and allocation pool is NOT set 2025-10-09 14:07:13,312 - 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=5.14 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.02 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 = 3.022/4.083/5.144/1.061 ms') 2025-10-09 14:07:13,312 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-05848755 passed 2025-10-09 14:07:13,468 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-09 14:07:13,468 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-05848755, IP 178.158.207.67, Port 22 2025-10-09 14:07:13,634 - DEBUG - VM ctest-vn1-vm1-05848755 is ready for SSH connections 2025-10-09 14:07:13,634 - INFO - Waiting for VM ctest-vn2-vm1-91302749 to be up.. 2025-10-09 14:07:13,714 - DEBUG - VM is in ACTIVE state now 2025-10-09 14:07:13,714 - INFO - VM name : ctest-vn2-vm1-91302749 2025-10-09 14:07:13,794 - DEBUG - VM ctest-vn2-vm1-91302749 ID is bba8fe5c-3911-4205-9f2d-e8ca6748a8e1 2025-10-09 14:07:13,794 - DEBUG - VM ctest-vn2-vm1-91302749 launched on Node cn-jenkins-deploy-platform-ansible-os-4262-1 2025-10-09 14:07:13,876 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/bba8fe5c-3911-4205-9f2d-e8ca6748a8e1 2025-10-09 14:07:13,885 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/5315ce72-c0d2-4355-a5c0-a55d22d00c5f 2025-10-09 14:07:15,122 - 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.96 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.474 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.474/2.216/3.958/1.742 ms') 2025-10-09 14:07:15,122 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-91302749 passed 2025-10-09 14:07:15,272 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-09 14:07:15,272 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-91302749, IP 108.143.39.131, Port 22 2025-10-09 14:07:15,442 - DEBUG - VM ctest-vn2-vm1-91302749 is ready for SSH connections 2025-10-09 14:07:15,443 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-09 14:07:15,443 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 108.143.39.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2025-10-09 14:07:15,443 - DEBUG - ping -s 56 -c 3 -W 1 108.143.39.131 2025-10-09 14:07:20,182 - DEBUG - PING 108.143.39.131 (108.143.39.131): 56 data bytes --- 108.143.39.131 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-10-09 14:07:20,182 - WARNING - Ping to IP 108.143.39.131 from VM ctest-vn1-vm1-05848755 failed 2025-10-09 14:07:20,183 - INFO - Adding interface with subnet_id 8f40eee5-36e4-4dae-947f-77ddc778c5b9, port_id None to router 36c4fb94-98ac-4d08-becf-903c401df21b 2025-10-09 14:07:20,429 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-09 14:07:20,429 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 108.143.39.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2025-10-09 14:07:20,429 - DEBUG - ping -s 56 -c 3 -W 1 108.143.39.131 2025-10-09 14:07:23,674 - DEBUG - PING 108.143.39.131 (108.143.39.131): 56 data bytes 64 bytes from 108.143.39.131: seq=1 ttl=63 time=3.315 ms 64 bytes from 108.143.39.131: seq=2 ttl=63 time=1.427 ms --- 108.143.39.131 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.427/2.371/3.315 ms 2025-10-09 14:07:23,675 - WARNING - Ping to IP 108.143.39.131 from VM ctest-vn1-vm1-05848755 failed 2025-10-09 14:07:24,676 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 108.143.39.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2025-10-09 14:07:24,676 - DEBUG - ping -s 56 -c 3 -W 1 108.143.39.131 2025-10-09 14:07:26,954 - DEBUG - PING 108.143.39.131 (108.143.39.131): 56 data bytes 64 bytes from 108.143.39.131: seq=0 ttl=63 time=1.642 ms 64 bytes from 108.143.39.131: seq=1 ttl=63 time=1.011 ms 64 bytes from 108.143.39.131: seq=2 ttl=63 time=0.901 ms --- 108.143.39.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.901/1.184/1.642 ms 2025-10-09 14:07:26,954 - INFO - Ping to IP 108.143.39.131 from VM ctest-vn1-vm1-05848755 passed 2025-10-09 14:07:26,954 - INFO - Deleting interface with subnet_id d9336b5c-609d-4f23-8d96-e1c7532bd2a1, port_id None from router 36c4fb94-98ac-4d08-becf-903c401df21b 2025-10-09 14:07:27,093 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-09 14:07:27,093 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 108.143.39.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2025-10-09 14:07:27,093 - DEBUG - ping -s 56 -c 3 -W 1 108.143.39.131 2025-10-09 14:07:30,322 - DEBUG - PING 108.143.39.131 (108.143.39.131): 56 data bytes --- 108.143.39.131 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-10-09 14:07:30,322 - WARNING - Ping to IP 108.143.39.131 from VM ctest-vn1-vm1-05848755 failed 2025-10-09 14:07:30,322 - INFO - Adding interface with subnet_id d9336b5c-609d-4f23-8d96-e1c7532bd2a1, port_id None to router 36c4fb94-98ac-4d08-becf-903c401df21b 2025-10-09 14:07:30,568 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-09 14:07:30,568 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 108.143.39.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2025-10-09 14:07:30,568 - DEBUG - ping -s 56 -c 3 -W 1 108.143.39.131 2025-10-09 14:07:32,814 - DEBUG - PING 108.143.39.131 (108.143.39.131): 56 data bytes 64 bytes from 108.143.39.131: seq=0 ttl=63 time=1.661 ms 64 bytes from 108.143.39.131: seq=1 ttl=63 time=0.871 ms 64 bytes from 108.143.39.131: seq=2 ttl=63 time=1.483 ms --- 108.143.39.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.871/1.338/1.661 ms 2025-10-09 14:07:32,814 - INFO - Ping to IP 108.143.39.131 from VM ctest-vn1-vm1-05848755 passed 2025-10-09 14:07:32,815 - INFO - Deleting interface with subnet_id 8f40eee5-36e4-4dae-947f-77ddc778c5b9, port_id None from router 36c4fb94-98ac-4d08-becf-903c401df21b 2025-10-09 14:07:32,963 - INFO - Deleting interface with subnet_id d9336b5c-609d-4f23-8d96-e1c7532bd2a1, port_id None from router 36c4fb94-98ac-4d08-becf-903c401df21b 2025-10-09 14:07:33,187 - INFO - Deleting VM ctest-vn2-vm1-91302749 2025-10-09 14:07:33,268 - INFO - Deleting VM ctest-vn1-vm1-05848755 2025-10-09 14:07:33,346 - INFO - Deleting VN ctest-vn2-81522672 2025-10-09 14:07:33,394 - DEBUG - VN b697cf68-678a-422a-bf01-0e0a65dddf34 still in use: Unable to complete operation on network b697cf68-678a-422a-bf01-0e0a65dddf34. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9ded1ec3-3eb9-487f-88ad-0ef0d0a268d7'] 2025-10-09 14:07:33,394 - WARNING - Deleting VN ctest-vn2-81522672 failed..Will retry 2025-10-09 14:07:35,530 - DEBUG - Response for deleting network () 2025-10-09 14:07:35,530 - INFO - Deleting VN ctest-vn1-74411313 2025-10-09 14:07:35,647 - DEBUG - Response for deleting network () 2025-10-09 14:07:35,912 - DEBUG - No XMPP flaps were noticed during the test
2025-10-09 14:07:35,912 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:38]
2025-10-09 14:07:35,913 - INFO - -------------------------------------------------------------------------------- 2025-10-09 14:07:36,658 - INFO - Deleted project: ctest-TestRouters-59166697, ID : 5e084013-6a33-4ed7-82dc-3d1dcbb75d10