2025-11-02 16:24:29,779 - INFO - Domain Default found not creating 2025-11-02 16:24:29,954 - INFO - Project ctest-TestRouters-87041041 not found, creating it 2025-11-02 16:24:30,458 - INFO - Created Project:ctest-TestRouters-87041041, ID : 7e5d9e64-c0b9-4f95-b49d-90501ffa9c1c 2025-11-02 16:24:32,115 - INFO - ================================================================================ 2025-11-02 16:24:32,116 - INFO - STARTING TEST : test_basic_router_behavior 2025-11-02 16:24:32,116 - 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-02 16:24:32,389 - DEBUG - Nothing to compare xmpp stats {'10.0.0.48': {'10.20.0.17': '0'}} with 2025-11-02 16:24:32,390 - INFO - Initial checks done. Running the testcase now 2025-11-02 16:24:32,390 - INFO - 2025-11-02 16:24:33,530 - DEBUG - Response for create_network : {'network': {'id': 'a812c48b-af04-4cfa-9480-ca00e89cb14a', 'name': 'ctest-vn1-53434566', 'tenant_id': '7e5d9e64c0b94f95b49d90501ffa9c1c', 'project_id': '7e5d9e64c0b94f95b49d90501ffa9c1c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-87041041', 'ctest-vn1-53434566'], 'port_security_enabled': True, 'description': ''}} 2025-11-02 16:24:33,764 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ebbd6ba6-cc8b-4597-8b07-62ba7d4a6fdf', 'name': '', 'tenant_id': '7e5d9e64c0b94f95b49d90501ffa9c1c', 'network_id': 'a812c48b-af04-4cfa-9480-ca00e89cb14a', 'ip_version': 4, 'cidr': '142.246.31.64/26', 'allocation_pools': [{'start': '142.246.31.66', 'end': '142.246.31.126'}], 'gateway_ip': '142.246.31.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '142.246.31.66', 'tags': [], 'project_id': '7e5d9e64c0b94f95b49d90501ffa9c1c'}} 2025-11-02 16:24:33,785 - INFO - Created VN ctest-vn1-53434566 2025-11-02 16:24:33,840 - DEBUG - VN ctest-vn1-53434566 UUID is a812c48b-af04-4cfa-9480-ca00e89cb14a 2025-11-02 16:24:34,142 - DEBUG - Response for create_network : {'network': {'id': '1aed4bbf-5a73-433c-8d64-2a574219e0b4', 'name': 'ctest-vn2-40443261', 'tenant_id': '7e5d9e64c0b94f95b49d90501ffa9c1c', 'project_id': '7e5d9e64c0b94f95b49d90501ffa9c1c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-87041041', 'ctest-vn2-40443261'], 'port_security_enabled': True, 'description': ''}} 2025-11-02 16:24:34,353 - DEBUG - Response for create_subnet : {'subnet': {'id': '3f84d2ed-c653-4d56-b114-898fafe70571', 'name': '', 'tenant_id': '7e5d9e64c0b94f95b49d90501ffa9c1c', 'network_id': '1aed4bbf-5a73-433c-8d64-2a574219e0b4', 'ip_version': 4, 'cidr': '6.235.145.64/26', 'allocation_pools': [{'start': '6.235.145.66', 'end': '6.235.145.126'}], 'gateway_ip': '6.235.145.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '6.235.145.66', 'tags': [], 'project_id': '7e5d9e64c0b94f95b49d90501ffa9c1c'}} 2025-11-02 16:24:34,373 - INFO - Created VN ctest-vn2-40443261 2025-11-02 16:24:34,424 - DEBUG - VN ctest-vn2-40443261 UUID is 1aed4bbf-5a73-433c-8d64-2a574219e0b4 2025-11-02 16:24:34,598 - DEBUG - Services list from nova: [, , ] 2025-11-02 16:24:35,702 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4594-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4594-1) 2025-11-02 16:24:37,095 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4594-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4594-1) 2025-11-02 16:24:37,224 - INFO - Adding interface with subnet_id ebbd6ba6-cc8b-4597-8b07-62ba7d4a6fdf, port_id None to router e00ca3d7-0166-4ae0-a398-719397a122ec 2025-11-02 16:24:37,568 - INFO - Waiting for VM ctest-vn1-vm1-32281221 to be up.. 2025-11-02 16:24:37,679 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-02 16:24:42,758 - DEBUG - VM is in ACTIVE state now 2025-11-02 16:24:42,759 - INFO - VM name : ctest-vn1-vm1-32281221 2025-11-02 16:24:42,853 - DEBUG - VM ctest-vn1-vm1-32281221 ID is ccafbc3c-7548-4af6-ad1a-3d7d1b452876 2025-11-02 16:24:42,872 - DEBUG - VM ctest-vn1-vm1-32281221 launched on Node cn-jenkins-deploy-platform-ansible-os-4594-1 2025-11-02 16:24:42,956 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/ccafbc3c-7548-4af6-ad1a-3d7d1b452876 2025-11-02 16:24:43,295 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/ccafbc3c-7548-4af6-ad1a-3d7d1b452876 2025-11-02 16:24:43,327 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/68a57218-6e93-402b-b153-3555750c546f 2025-11-02 16:24:46,583 - 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-11-02 16:24:46,583 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-32281221 failed! 2025-11-02 16:24:46,644 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-87041041:ctest-vn1-53434566 is 142.246.31.65 and allocation pool is NOT set 2025-11-02 16:24:48,707 - 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.74 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.13 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.131/3.933/6.735/2.802 ms') 2025-11-02 16:24:48,707 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-32281221 passed 2025-11-02 16:24:48,872 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:24:48,872 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-32281221, IP 142.246.31.67, Port 22 2025-11-02 16:24:49,046 - DEBUG - VM ctest-vn1-vm1-32281221 is ready for SSH connections 2025-11-02 16:24:49,047 - INFO - Waiting for VM ctest-vn2-vm1-96206127 to be up.. 2025-11-02 16:24:49,147 - DEBUG - VM is in ACTIVE state now 2025-11-02 16:24:49,147 - INFO - VM name : ctest-vn2-vm1-96206127 2025-11-02 16:24:49,238 - DEBUG - VM ctest-vn2-vm1-96206127 ID is dff972c3-a38c-4af7-a467-366c81baba36 2025-11-02 16:24:49,238 - DEBUG - VM ctest-vn2-vm1-96206127 launched on Node cn-jenkins-deploy-platform-ansible-os-4594-1 2025-11-02 16:24:49,324 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/dff972c3-a38c-4af7-a467-366c81baba36 2025-11-02 16:24:49,333 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/b3c24bfa-78d8-4916-b7b0-f8e1ef26acfc 2025-11-02 16:24:50,581 - 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=7.34 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.520 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.520/3.927/7.335/3.407 ms') 2025-11-02 16:24:50,581 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-96206127 passed 2025-11-02 16:24:50,736 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:24:50,736 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-96206127, IP 6.235.145.67, Port 22 2025-11-02 16:24:50,892 - DEBUG - VM ctest-vn2-vm1-96206127 is ready for SSH connections 2025-11-02 16:24:50,892 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:24:50,893 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 6.235.145.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.48, gateway password: c0ntrail123 2025-11-02 16:24:50,893 - DEBUG - ping -s 56 -c 3 -W 1 6.235.145.67 2025-11-02 16:24:55,963 - DEBUG - PING 6.235.145.67 (6.235.145.67): 56 data bytes --- 6.235.145.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-02 16:24:55,963 - WARNING - Ping to IP 6.235.145.67 from VM ctest-vn1-vm1-32281221 failed 2025-11-02 16:24:55,963 - INFO - Adding interface with subnet_id 3f84d2ed-c653-4d56-b114-898fafe70571, port_id None to router e00ca3d7-0166-4ae0-a398-719397a122ec 2025-11-02 16:24:56,225 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:24:56,225 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 6.235.145.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.48, gateway password: c0ntrail123 2025-11-02 16:24:56,225 - DEBUG - ping -s 56 -c 3 -W 1 6.235.145.67 2025-11-02 16:24:59,449 - DEBUG - PING 6.235.145.67 (6.235.145.67): 56 data bytes 64 bytes from 6.235.145.67: seq=1 ttl=63 time=3.275 ms 64 bytes from 6.235.145.67: seq=2 ttl=63 time=0.897 ms --- 6.235.145.67 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 0.897/2.086/3.275 ms 2025-11-02 16:24:59,449 - WARNING - Ping to IP 6.235.145.67 from VM ctest-vn1-vm1-32281221 failed 2025-11-02 16:25:00,451 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 6.235.145.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.48, gateway password: c0ntrail123 2025-11-02 16:25:00,451 - DEBUG - ping -s 56 -c 3 -W 1 6.235.145.67 2025-11-02 16:25:02,673 - DEBUG - PING 6.235.145.67 (6.235.145.67): 56 data bytes 64 bytes from 6.235.145.67: seq=0 ttl=63 time=1.651 ms 64 bytes from 6.235.145.67: seq=1 ttl=63 time=0.790 ms 64 bytes from 6.235.145.67: seq=2 ttl=63 time=0.968 ms --- 6.235.145.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.790/1.136/1.651 ms 2025-11-02 16:25:02,673 - INFO - Ping to IP 6.235.145.67 from VM ctest-vn1-vm1-32281221 passed 2025-11-02 16:25:02,673 - INFO - Deleting interface with subnet_id ebbd6ba6-cc8b-4597-8b07-62ba7d4a6fdf, port_id None from router e00ca3d7-0166-4ae0-a398-719397a122ec 2025-11-02 16:25:02,800 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:25:02,800 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 6.235.145.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.48, gateway password: c0ntrail123 2025-11-02 16:25:02,800 - DEBUG - ping -s 56 -c 3 -W 1 6.235.145.67 2025-11-02 16:25:06,029 - DEBUG - PING 6.235.145.67 (6.235.145.67): 56 data bytes --- 6.235.145.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-02 16:25:06,029 - WARNING - Ping to IP 6.235.145.67 from VM ctest-vn1-vm1-32281221 failed 2025-11-02 16:25:06,029 - INFO - Adding interface with subnet_id ebbd6ba6-cc8b-4597-8b07-62ba7d4a6fdf, port_id None to router e00ca3d7-0166-4ae0-a398-719397a122ec 2025-11-02 16:25:06,254 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-02 16:25:06,254 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 6.235.145.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.48, gateway password: c0ntrail123 2025-11-02 16:25:06,254 - DEBUG - ping -s 56 -c 3 -W 1 6.235.145.67 2025-11-02 16:25:08,484 - DEBUG - PING 6.235.145.67 (6.235.145.67): 56 data bytes 64 bytes from 6.235.145.67: seq=0 ttl=63 time=1.510 ms 64 bytes from 6.235.145.67: seq=1 ttl=63 time=0.819 ms 64 bytes from 6.235.145.67: seq=2 ttl=63 time=0.875 ms --- 6.235.145.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.819/1.068/1.510 ms 2025-11-02 16:25:08,484 - INFO - Ping to IP 6.235.145.67 from VM ctest-vn1-vm1-32281221 passed 2025-11-02 16:25:08,485 - INFO - Deleting interface with subnet_id 3f84d2ed-c653-4d56-b114-898fafe70571, port_id None from router e00ca3d7-0166-4ae0-a398-719397a122ec 2025-11-02 16:25:08,596 - INFO - Deleting interface with subnet_id ebbd6ba6-cc8b-4597-8b07-62ba7d4a6fdf, port_id None from router e00ca3d7-0166-4ae0-a398-719397a122ec 2025-11-02 16:25:08,784 - INFO - Deleting VM ctest-vn2-vm1-96206127 2025-11-02 16:25:08,887 - INFO - Deleting VM ctest-vn1-vm1-32281221 2025-11-02 16:25:08,983 - INFO - Deleting VN ctest-vn2-40443261 2025-11-02 16:25:09,029 - DEBUG - VN 1aed4bbf-5a73-433c-8d64-2a574219e0b4 still in use: Unable to complete operation on network 1aed4bbf-5a73-433c-8d64-2a574219e0b4. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-ec97b939-8204-4b4c-aeda-5eeeff99050d'] 2025-11-02 16:25:09,029 - WARNING - Deleting VN ctest-vn2-40443261 failed..Will retry 2025-11-02 16:25:11,157 - DEBUG - Response for deleting network () 2025-11-02 16:25:11,157 - INFO - Deleting VN ctest-vn1-53434566 2025-11-02 16:25:11,292 - DEBUG - Response for deleting network () 2025-11-02 16:25:11,569 - DEBUG - No XMPP flaps were noticed during the test
2025-11-02 16:25:11,569 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:39]
2025-11-02 16:25:11,569 - INFO - -------------------------------------------------------------------------------- 2025-11-02 16:25:12,325 - INFO - Deleted project: ctest-TestRouters-87041041, ID : 7e5d9e64-c0b9-4f95-b49d-90501ffa9c1c