2025-09-22 15:46:42,885 - INFO - Domain Default found not creating 2025-09-22 15:46:43,185 - INFO - Project ctest-TestRouters-85971029 not found, creating it 2025-09-22 15:46:43,719 - INFO - Created Project:ctest-TestRouters-85971029, ID : 32023a4f-e580-4be3-bc53-83659ebf0b17 2025-09-22 15:46:45,289 - INFO - ================================================================================ 2025-09-22 15:46:45,290 - INFO - STARTING TEST : test_basic_router_behavior 2025-09-22 15:46:45,290 - 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-09-22 15:46:45,546 - DEBUG - Nothing to compare xmpp stats {'10.0.0.254': {'10.20.0.14': '0'}} with 2025-09-22 15:46:45,546 - INFO - Initial checks done. Running the testcase now 2025-09-22 15:46:45,546 - INFO - 2025-09-22 15:46:46,101 - DEBUG - Response for create_network : {'network': {'id': 'edd84553-23fe-4d01-ad62-81169bbc5919', 'name': 'ctest-vn1-26190595', 'tenant_id': '32023a4fe5804be3bc5383659ebf0b17', 'project_id': '32023a4fe5804be3bc5383659ebf0b17', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-85971029', 'ctest-vn1-26190595'], 'port_security_enabled': True, 'description': ''}} 2025-09-22 15:46:46,260 - DEBUG - Response for create_subnet : {'subnet': {'id': '1ef0a817-5d7e-4481-bc06-25c9134d9fc9', 'name': '', 'tenant_id': '32023a4fe5804be3bc5383659ebf0b17', 'network_id': 'edd84553-23fe-4d01-ad62-81169bbc5919', 'ip_version': 4, 'cidr': '174.80.138.128/26', 'allocation_pools': [{'start': '174.80.138.130', 'end': '174.80.138.190'}], 'gateway_ip': '174.80.138.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '174.80.138.130', 'tags': [], 'project_id': '32023a4fe5804be3bc5383659ebf0b17'}} 2025-09-22 15:46:46,278 - INFO - Created VN ctest-vn1-26190595 2025-09-22 15:46:46,333 - DEBUG - VN ctest-vn1-26190595 UUID is edd84553-23fe-4d01-ad62-81169bbc5919 2025-09-22 15:46:46,664 - DEBUG - Response for create_network : {'network': {'id': '0e524ba0-7204-4918-a6c0-f5818201758d', 'name': 'ctest-vn2-41271271', 'tenant_id': '32023a4fe5804be3bc5383659ebf0b17', 'project_id': '32023a4fe5804be3bc5383659ebf0b17', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-85971029', 'ctest-vn2-41271271'], 'port_security_enabled': True, 'description': ''}} 2025-09-22 15:46:46,840 - DEBUG - Response for create_subnet : {'subnet': {'id': '7fa95d4e-b6e0-4817-9ec4-d6d82be7ada8', 'name': '', 'tenant_id': '32023a4fe5804be3bc5383659ebf0b17', 'network_id': '0e524ba0-7204-4918-a6c0-f5818201758d', 'ip_version': 4, 'cidr': '65.35.85.64/26', 'allocation_pools': [{'start': '65.35.85.66', 'end': '65.35.85.126'}], 'gateway_ip': '65.35.85.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '65.35.85.66', 'tags': [], 'project_id': '32023a4fe5804be3bc5383659ebf0b17'}} 2025-09-22 15:46:46,860 - INFO - Created VN ctest-vn2-41271271 2025-09-22 15:46:46,913 - DEBUG - VN ctest-vn2-41271271 UUID is 0e524ba0-7204-4918-a6c0-f5818201758d 2025-09-22 15:46:47,090 - DEBUG - Services list from nova: [, , ] 2025-09-22 15:46:48,245 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4170-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4170-1) 2025-09-22 15:46:49,448 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4170-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4170-1) 2025-09-22 15:46:49,508 - INFO - Adding interface with subnet_id 1ef0a817-5d7e-4481-bc06-25c9134d9fc9, port_id None to router a15c5e42-790f-41d5-a143-b2019cc116c5 2025-09-22 15:46:49,757 - INFO - Waiting for VM ctest-vn1-vm1-80968627 to be up.. 2025-09-22 15:46:49,851 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-09-22 15:46:54,934 - DEBUG - VM is in ACTIVE state now 2025-09-22 15:46:54,934 - INFO - VM name : ctest-vn1-vm1-80968627 2025-09-22 15:46:55,007 - DEBUG - VM ctest-vn1-vm1-80968627 ID is 2704b0b0-4cb2-4c5b-9f16-4c81b6bbced1 2025-09-22 15:46:55,026 - DEBUG - VM ctest-vn1-vm1-80968627 launched on Node cn-jenkins-deploy-platform-ansible-os-4170-1 2025-09-22 15:46:55,110 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/2704b0b0-4cb2-4c5b-9f16-4c81b6bbced1 2025-09-22 15:46:55,421 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/2704b0b0-4cb2-4c5b-9f16-4c81b6bbced1 2025-09-22 15:46:55,451 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/bfaf0e7c-7079-4ba7-830e-ec8f6fb232b1 2025-09-22 15:46:58,696 - 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 1006ms') 2025-09-22 15:46:58,696 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-80968627 failed! 2025-09-22 15:46:58,753 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-85971029:ctest-vn1-26190595 is 174.80.138.129 and allocation pool is NOT set 2025-09-22 15:47:00,818 - 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=2.80 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.542 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 = 0.542/1.670/2.798/1.128 ms') 2025-09-22 15:47:00,818 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-80968627 passed 2025-09-22 15:47:00,969 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-22 15:47:00,969 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-80968627, IP 174.80.138.131, Port 22 2025-09-22 15:47:01,128 - DEBUG - VM ctest-vn1-vm1-80968627 is ready for SSH connections 2025-09-22 15:47:01,128 - INFO - Waiting for VM ctest-vn2-vm1-09809549 to be up.. 2025-09-22 15:47:01,211 - DEBUG - VM is in ACTIVE state now 2025-09-22 15:47:01,212 - INFO - VM name : ctest-vn2-vm1-09809549 2025-09-22 15:47:01,294 - DEBUG - VM ctest-vn2-vm1-09809549 ID is 0fdd75c1-9549-4abb-bf1e-8f5804857ca9 2025-09-22 15:47:01,294 - DEBUG - VM ctest-vn2-vm1-09809549 launched on Node cn-jenkins-deploy-platform-ansible-os-4170-1 2025-09-22 15:47:01,376 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/0fdd75c1-9549-4abb-bf1e-8f5804857ca9 2025-09-22 15:47:01,388 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/b3835546-d0b4-4cac-8a71-e3e910283788 2025-09-22 15:47:02,639 - 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.14 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.679 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.679/1.907/3.136/1.228 ms') 2025-09-22 15:47:02,639 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-09809549 passed 2025-09-22 15:47:02,789 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-22 15:47:02,789 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-09809549, IP 65.35.85.67, Port 22 2025-09-22 15:47:02,944 - DEBUG - VM ctest-vn2-vm1-09809549 is ready for SSH connections 2025-09-22 15:47:02,944 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-22 15:47:02,944 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 65.35.85.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-09-22 15:47:02,944 - DEBUG - ping -s 56 -c 3 -W 1 65.35.85.67 2025-09-22 15:47:07,650 - DEBUG - PING 65.35.85.67 (65.35.85.67): 56 data bytes --- 65.35.85.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-09-22 15:47:07,650 - WARNING - Ping to IP 65.35.85.67 from VM ctest-vn1-vm1-80968627 failed 2025-09-22 15:47:07,650 - INFO - Adding interface with subnet_id 7fa95d4e-b6e0-4817-9ec4-d6d82be7ada8, port_id None to router a15c5e42-790f-41d5-a143-b2019cc116c5 2025-09-22 15:47:07,903 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-22 15:47:07,903 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 65.35.85.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-09-22 15:47:07,903 - DEBUG - ping -s 56 -c 3 -W 1 65.35.85.67 2025-09-22 15:47:11,117 - DEBUG - PING 65.35.85.67 (65.35.85.67): 56 data bytes 64 bytes from 65.35.85.67: seq=1 ttl=63 time=3.654 ms 64 bytes from 65.35.85.67: seq=2 ttl=63 time=1.222 ms --- 65.35.85.67 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.222/2.438/3.654 ms 2025-09-22 15:47:11,118 - WARNING - Ping to IP 65.35.85.67 from VM ctest-vn1-vm1-80968627 failed 2025-09-22 15:47:12,119 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 65.35.85.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-09-22 15:47:12,119 - DEBUG - ping -s 56 -c 3 -W 1 65.35.85.67 2025-09-22 15:47:14,324 - DEBUG - PING 65.35.85.67 (65.35.85.67): 56 data bytes 64 bytes from 65.35.85.67: seq=0 ttl=63 time=1.437 ms 64 bytes from 65.35.85.67: seq=1 ttl=63 time=1.273 ms 64 bytes from 65.35.85.67: seq=2 ttl=63 time=0.809 ms --- 65.35.85.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.809/1.173/1.437 ms 2025-09-22 15:47:14,324 - INFO - Ping to IP 65.35.85.67 from VM ctest-vn1-vm1-80968627 passed 2025-09-22 15:47:14,324 - INFO - Deleting interface with subnet_id 1ef0a817-5d7e-4481-bc06-25c9134d9fc9, port_id None from router a15c5e42-790f-41d5-a143-b2019cc116c5 2025-09-22 15:47:14,463 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-22 15:47:14,463 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 65.35.85.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-09-22 15:47:14,463 - DEBUG - ping -s 56 -c 3 -W 1 65.35.85.67 2025-09-22 15:47:17,691 - DEBUG - PING 65.35.85.67 (65.35.85.67): 56 data bytes --- 65.35.85.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-09-22 15:47:17,691 - WARNING - Ping to IP 65.35.85.67 from VM ctest-vn1-vm1-80968627 failed 2025-09-22 15:47:17,691 - INFO - Adding interface with subnet_id 1ef0a817-5d7e-4481-bc06-25c9134d9fc9, port_id None to router a15c5e42-790f-41d5-a143-b2019cc116c5 2025-09-22 15:47:17,908 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-22 15:47:17,908 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 65.35.85.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-09-22 15:47:17,908 - DEBUG - ping -s 56 -c 3 -W 1 65.35.85.67 2025-09-22 15:47:20,136 - DEBUG - PING 65.35.85.67 (65.35.85.67): 56 data bytes 64 bytes from 65.35.85.67: seq=0 ttl=63 time=1.944 ms 64 bytes from 65.35.85.67: seq=1 ttl=63 time=1.057 ms 64 bytes from 65.35.85.67: seq=2 ttl=63 time=0.825 ms --- 65.35.85.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.825/1.275/1.944 ms 2025-09-22 15:47:20,136 - INFO - Ping to IP 65.35.85.67 from VM ctest-vn1-vm1-80968627 passed 2025-09-22 15:47:20,137 - INFO - Deleting interface with subnet_id 7fa95d4e-b6e0-4817-9ec4-d6d82be7ada8, port_id None from router a15c5e42-790f-41d5-a143-b2019cc116c5 2025-09-22 15:47:20,268 - INFO - Deleting interface with subnet_id 1ef0a817-5d7e-4481-bc06-25c9134d9fc9, port_id None from router a15c5e42-790f-41d5-a143-b2019cc116c5 2025-09-22 15:47:20,446 - INFO - Deleting VM ctest-vn2-vm1-09809549 2025-09-22 15:47:20,519 - INFO - Deleting VM ctest-vn1-vm1-80968627 2025-09-22 15:47:20,608 - INFO - Deleting VN ctest-vn2-41271271 2025-09-22 15:47:20,659 - DEBUG - VN 0e524ba0-7204-4918-a6c0-f5818201758d still in use: Unable to complete operation on network 0e524ba0-7204-4918-a6c0-f5818201758d. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-302e44fc-34c7-45c3-bbfd-14e60712ef89'] 2025-09-22 15:47:20,659 - WARNING - Deleting VN ctest-vn2-41271271 failed..Will retry 2025-09-22 15:47:22,768 - DEBUG - Response for deleting network () 2025-09-22 15:47:22,768 - INFO - Deleting VN ctest-vn1-26190595 2025-09-22 15:47:22,892 - DEBUG - Response for deleting network () 2025-09-22 15:47:23,153 - DEBUG - No XMPP flaps were noticed during the test
2025-09-22 15:47:23,154 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:38]
2025-09-22 15:47:23,154 - INFO - -------------------------------------------------------------------------------- 2025-09-22 15:47:23,835 - INFO - Deleted project: ctest-TestRouters-85971029, ID : 32023a4f-e580-4be3-bc53-83659ebf0b17