2025-10-21 17:49:09,596 - INFO - Domain Default found not creating 2025-10-21 17:49:09,861 - INFO - Project ctest-TestRouters-60236361 not found, creating it 2025-10-21 17:49:10,420 - INFO - Created Project:ctest-TestRouters-60236361, ID : 1a917588-e5ba-4a58-b685-c3603229f3bb 2025-10-21 17:49:12,288 - INFO - ================================================================================ 2025-10-21 17:49:12,289 - INFO - STARTING TEST : test_basic_router_behavior 2025-10-21 17:49:12,289 - 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-21 17:49:12,592 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.254': '0'}} with 2025-10-21 17:49:12,592 - INFO - Initial checks done. Running the testcase now 2025-10-21 17:49:12,592 - INFO - 2025-10-21 17:49:13,210 - DEBUG - Response for create_network : {'network': {'id': 'b8863b69-73ed-445c-9db7-1044f359f5cb', 'name': 'ctest-vn1-21344776', 'tenant_id': '1a917588e5ba4a58b685c3603229f3bb', 'project_id': '1a917588e5ba4a58b685c3603229f3bb', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-60236361', 'ctest-vn1-21344776'], 'port_security_enabled': True, 'description': ''}} 2025-10-21 17:49:13,438 - DEBUG - Response for create_subnet : {'subnet': {'id': '2737a45c-c610-43bc-b596-4e2ac5cd211d', 'name': '', 'tenant_id': '1a917588e5ba4a58b685c3603229f3bb', 'network_id': 'b8863b69-73ed-445c-9db7-1044f359f5cb', 'ip_version': 4, 'cidr': '25.11.188.0/26', 'allocation_pools': [{'start': '25.11.188.2', 'end': '25.11.188.62'}], 'gateway_ip': '25.11.188.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '25.11.188.2', 'tags': [], 'project_id': '1a917588e5ba4a58b685c3603229f3bb'}} 2025-10-21 17:49:13,454 - INFO - Created VN ctest-vn1-21344776 2025-10-21 17:49:13,509 - DEBUG - VN ctest-vn1-21344776 UUID is b8863b69-73ed-445c-9db7-1044f359f5cb 2025-10-21 17:49:13,915 - DEBUG - Response for create_network : {'network': {'id': 'c6dc00e2-ef2a-44aa-9032-99d4b97e8a59', 'name': 'ctest-vn2-54362849', 'tenant_id': '1a917588e5ba4a58b685c3603229f3bb', 'project_id': '1a917588e5ba4a58b685c3603229f3bb', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-60236361', 'ctest-vn2-54362849'], 'port_security_enabled': True, 'description': ''}} 2025-10-21 17:49:14,196 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c9c25b1d-7bb3-4dbd-a130-c592419e48a9', 'name': '', 'tenant_id': '1a917588e5ba4a58b685c3603229f3bb', 'network_id': 'c6dc00e2-ef2a-44aa-9032-99d4b97e8a59', 'ip_version': 4, 'cidr': '64.41.30.0/26', 'allocation_pools': [{'start': '64.41.30.2', 'end': '64.41.30.62'}], 'gateway_ip': '64.41.30.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '64.41.30.2', 'tags': [], 'project_id': '1a917588e5ba4a58b685c3603229f3bb'}} 2025-10-21 17:49:14,215 - INFO - Created VN ctest-vn2-54362849 2025-10-21 17:49:14,269 - DEBUG - VN ctest-vn2-54362849 UUID is c6dc00e2-ef2a-44aa-9032-99d4b97e8a59 2025-10-21 17:49:14,506 - DEBUG - Services list from nova: [, , ] 2025-10-21 17:49:16,023 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4401-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4401-1) 2025-10-21 17:49:17,580 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4401-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4401-1) 2025-10-21 17:49:17,657 - INFO - Adding interface with subnet_id 2737a45c-c610-43bc-b596-4e2ac5cd211d, port_id None to router f18341ee-cde1-4d4e-8448-37dd52a40db3 2025-10-21 17:49:17,954 - INFO - Waiting for VM ctest-vn1-vm1-47643734 to be up.. 2025-10-21 17:49:18,044 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-21 17:49:23,153 - DEBUG - VM is in ACTIVE state now 2025-10-21 17:49:23,153 - INFO - VM name : ctest-vn1-vm1-47643734 2025-10-21 17:49:23,254 - DEBUG - VM ctest-vn1-vm1-47643734 ID is 6db94af0-83c4-4e0c-bcb1-668f850d8fe1 2025-10-21 17:49:23,279 - DEBUG - VM ctest-vn1-vm1-47643734 launched on Node cn-jenkins-deploy-platform-ansible-os-4401-1 2025-10-21 17:49:23,385 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/6db94af0-83c4-4e0c-bcb1-668f850d8fe1 2025-10-21 17:49:23,717 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/6db94af0-83c4-4e0c-bcb1-668f850d8fe1 2025-10-21 17:49:23,773 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/24c879ce-4ef5-480d-9123-bd90fbf8594d 2025-10-21 17:49:27,074 - 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 1014ms') 2025-10-21 17:49:27,075 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-47643734 failed! 2025-10-21 17:49:27,137 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-60236361:ctest-vn1-21344776 is 25.11.188.1 and allocation pool is NOT set 2025-10-21 17:49:29,213 - 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=4.79 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.42 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.424/3.104/4.785/1.680 ms') 2025-10-21 17:49:29,213 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-47643734 passed 2025-10-21 17:49:29,397 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-21 17:49:29,397 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-47643734, IP 25.11.188.3, Port 22 2025-10-21 17:49:29,571 - DEBUG - VM ctest-vn1-vm1-47643734 is ready for SSH connections 2025-10-21 17:49:29,571 - INFO - Waiting for VM ctest-vn2-vm1-98955585 to be up.. 2025-10-21 17:49:29,684 - DEBUG - VM is in ACTIVE state now 2025-10-21 17:49:29,684 - INFO - VM name : ctest-vn2-vm1-98955585 2025-10-21 17:49:29,796 - DEBUG - VM ctest-vn2-vm1-98955585 ID is 0e7e1075-3ff9-4f15-9c3e-997b08a894ae 2025-10-21 17:49:29,796 - DEBUG - VM ctest-vn2-vm1-98955585 launched on Node cn-jenkins-deploy-platform-ansible-os-4401-1 2025-10-21 17:49:29,895 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/0e7e1075-3ff9-4f15-9c3e-997b08a894ae 2025-10-21 17:49:29,904 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/d9022dd4-a27f-411a-8890-38101927c6f8 2025-10-21 17:49:31,180 - 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.72 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.07 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.069/2.893/4.717/1.824 ms') 2025-10-21 17:49:31,180 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-98955585 passed 2025-10-21 17:49:31,357 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-21 17:49:31,357 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-98955585, IP 64.41.30.3, Port 22 2025-10-21 17:49:31,532 - DEBUG - VM ctest-vn2-vm1-98955585 is ready for SSH connections 2025-10-21 17:49:31,532 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-21 17:49:31,532 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 64.41.30.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2025-10-21 17:49:31,532 - DEBUG - ping -s 56 -c 3 -W 1 64.41.30.3 2025-10-21 17:49:36,969 - DEBUG - PING 64.41.30.3 (64.41.30.3): 56 data bytes --- 64.41.30.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-10-21 17:49:36,970 - WARNING - Ping to IP 64.41.30.3 from VM ctest-vn1-vm1-47643734 failed 2025-10-21 17:49:36,970 - INFO - Adding interface with subnet_id c9c25b1d-7bb3-4dbd-a130-c592419e48a9, port_id None to router f18341ee-cde1-4d4e-8448-37dd52a40db3 2025-10-21 17:49:37,343 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-21 17:49:37,343 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 64.41.30.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2025-10-21 17:49:37,343 - DEBUG - ping -s 56 -c 3 -W 1 64.41.30.3 2025-10-21 17:49:39,550 - DEBUG - PING 64.41.30.3 (64.41.30.3): 56 data bytes 64 bytes from 64.41.30.3: seq=0 ttl=63 time=3.440 ms 64 bytes from 64.41.30.3: seq=1 ttl=63 time=2.232 ms 64 bytes from 64.41.30.3: seq=2 ttl=63 time=1.366 ms --- 64.41.30.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.366/2.346/3.440 ms 2025-10-21 17:49:39,550 - INFO - Ping to IP 64.41.30.3 from VM ctest-vn1-vm1-47643734 passed 2025-10-21 17:49:39,550 - INFO - Deleting interface with subnet_id 2737a45c-c610-43bc-b596-4e2ac5cd211d, port_id None from router f18341ee-cde1-4d4e-8448-37dd52a40db3 2025-10-21 17:49:39,763 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-21 17:49:39,763 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 64.41.30.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2025-10-21 17:49:39,763 - DEBUG - ping -s 56 -c 3 -W 1 64.41.30.3 2025-10-21 17:49:43,021 - DEBUG - PING 64.41.30.3 (64.41.30.3): 56 data bytes --- 64.41.30.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-10-21 17:49:43,021 - WARNING - Ping to IP 64.41.30.3 from VM ctest-vn1-vm1-47643734 failed 2025-10-21 17:49:43,022 - INFO - Adding interface with subnet_id 2737a45c-c610-43bc-b596-4e2ac5cd211d, port_id None to router f18341ee-cde1-4d4e-8448-37dd52a40db3 2025-10-21 17:49:43,348 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-21 17:49:43,348 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 64.41.30.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2025-10-21 17:49:43,348 - DEBUG - ping -s 56 -c 3 -W 1 64.41.30.3 2025-10-21 17:49:45,652 - DEBUG - PING 64.41.30.3 (64.41.30.3): 56 data bytes 64 bytes from 64.41.30.3: seq=0 ttl=63 time=1.529 ms 64 bytes from 64.41.30.3: seq=1 ttl=63 time=2.134 ms 64 bytes from 64.41.30.3: seq=2 ttl=63 time=2.124 ms --- 64.41.30.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.529/1.929/2.134 ms 2025-10-21 17:49:45,652 - INFO - Ping to IP 64.41.30.3 from VM ctest-vn1-vm1-47643734 passed 2025-10-21 17:49:45,652 - INFO - Deleting interface with subnet_id c9c25b1d-7bb3-4dbd-a130-c592419e48a9, port_id None from router f18341ee-cde1-4d4e-8448-37dd52a40db3 2025-10-21 17:49:45,882 - INFO - Deleting interface with subnet_id 2737a45c-c610-43bc-b596-4e2ac5cd211d, port_id None from router f18341ee-cde1-4d4e-8448-37dd52a40db3 2025-10-21 17:49:46,064 - INFO - Deleting VM ctest-vn2-vm1-98955585 2025-10-21 17:49:46,130 - INFO - Deleting VM ctest-vn1-vm1-47643734 2025-10-21 17:49:46,202 - INFO - Deleting VN ctest-vn2-54362849 2025-10-21 17:49:46,250 - DEBUG - VN c6dc00e2-ef2a-44aa-9032-99d4b97e8a59 still in use: Unable to complete operation on network c6dc00e2-ef2a-44aa-9032-99d4b97e8a59. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-8fc28a14-9e50-44db-aa9b-a6fabc7b5903'] 2025-10-21 17:49:46,250 - WARNING - Deleting VN ctest-vn2-54362849 failed..Will retry 2025-10-21 17:49:48,485 - DEBUG - Response for deleting network () 2025-10-21 17:49:48,485 - INFO - Deleting VN ctest-vn1-21344776 2025-10-21 17:49:48,632 - DEBUG - Response for deleting network () 2025-10-21 17:49:48,926 - DEBUG - No XMPP flaps were noticed during the test
2025-10-21 17:49:48,927 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:36]
2025-10-21 17:49:48,927 - INFO - -------------------------------------------------------------------------------- 2025-10-21 17:49:49,880 - INFO - Deleted project: ctest-TestRouters-60236361, ID : 1a917588-e5ba-4a58-b685-c3603229f3bb