2025-12-08 10:10:08,689 - INFO - Domain Default found not creating 2025-12-08 10:10:08,852 - INFO - Project ctest-TestRouters-33445351 not found, creating it 2025-12-08 10:10:09,327 - INFO - Created Project:ctest-TestRouters-33445351, ID : fdc1e086-6fa6-4db2-b9f9-e240f4516e0f 2025-12-08 10:10:10,831 - INFO - ================================================================================ 2025-12-08 10:10:10,831 - INFO - STARTING TEST : test_basic_router_behavior 2025-12-08 10:10:10,831 - 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-12-08 10:10:11,089 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.14': '0'}} with 2025-12-08 10:10:11,089 - INFO - Initial checks done. Running the testcase now 2025-12-08 10:10:11,089 - INFO - 2025-12-08 10:10:11,702 - DEBUG - Response for create_network : {'network': {'id': '1d335cee-79a7-439c-a9d1-397ac8c82d8a', 'name': 'ctest-vn1-16069292', 'tenant_id': 'fdc1e0866fa64db2b9f9e240f4516e0f', 'project_id': 'fdc1e0866fa64db2b9f9e240f4516e0f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-33445351', 'ctest-vn1-16069292'], 'port_security_enabled': True, 'description': ''}} 2025-12-08 10:10:11,892 - DEBUG - Response for create_subnet : {'subnet': {'id': '9413d34a-e82a-465e-97ae-8d5b19fe2b79', 'name': '', 'tenant_id': 'fdc1e0866fa64db2b9f9e240f4516e0f', 'network_id': '1d335cee-79a7-439c-a9d1-397ac8c82d8a', 'ip_version': 4, 'cidr': '173.178.33.192/26', 'allocation_pools': [{'start': '173.178.33.194', 'end': '173.178.33.254'}], 'gateway_ip': '173.178.33.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '173.178.33.194', 'tags': [], 'project_id': 'fdc1e0866fa64db2b9f9e240f4516e0f'}} 2025-12-08 10:10:11,912 - INFO - Created VN ctest-vn1-16069292 2025-12-08 10:10:11,971 - DEBUG - VN ctest-vn1-16069292 UUID is 1d335cee-79a7-439c-a9d1-397ac8c82d8a 2025-12-08 10:10:12,309 - DEBUG - Response for create_network : {'network': {'id': 'd152a7a9-b9a6-4c12-853b-35393639ba23', 'name': 'ctest-vn2-70348421', 'tenant_id': 'fdc1e0866fa64db2b9f9e240f4516e0f', 'project_id': 'fdc1e0866fa64db2b9f9e240f4516e0f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-33445351', 'ctest-vn2-70348421'], 'port_security_enabled': True, 'description': ''}} 2025-12-08 10:10:12,578 - DEBUG - Response for create_subnet : {'subnet': {'id': '791e41aa-0569-4702-96b6-f80601c60961', 'name': '', 'tenant_id': 'fdc1e0866fa64db2b9f9e240f4516e0f', 'network_id': 'd152a7a9-b9a6-4c12-853b-35393639ba23', 'ip_version': 4, 'cidr': '82.41.66.0/26', 'allocation_pools': [{'start': '82.41.66.2', 'end': '82.41.66.62'}], 'gateway_ip': '82.41.66.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '82.41.66.2', 'tags': [], 'project_id': 'fdc1e0866fa64db2b9f9e240f4516e0f'}} 2025-12-08 10:10:12,598 - INFO - Created VN ctest-vn2-70348421 2025-12-08 10:10:12,651 - DEBUG - VN ctest-vn2-70348421 UUID is d152a7a9-b9a6-4c12-853b-35393639ba23 2025-12-08 10:10:12,823 - DEBUG - Services list from nova: [, , ] 2025-12-08 10:10:13,895 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4856-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4856-1) 2025-12-08 10:10:15,251 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4856-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4856-1) 2025-12-08 10:10:15,341 - INFO - Adding interface with subnet_id 9413d34a-e82a-465e-97ae-8d5b19fe2b79, port_id None to router 53833138-ba4d-4a24-9a97-f360503ca034 2025-12-08 10:10:15,645 - INFO - Waiting for VM ctest-vn1-vm1-56761762 to be up.. 2025-12-08 10:10:15,735 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-12-08 10:10:20,813 - DEBUG - VM is in ACTIVE state now 2025-12-08 10:10:20,813 - INFO - VM name : ctest-vn1-vm1-56761762 2025-12-08 10:10:20,888 - DEBUG - VM ctest-vn1-vm1-56761762 ID is 37ea3b89-d502-4536-aa8a-431f3455155e 2025-12-08 10:10:20,909 - DEBUG - VM ctest-vn1-vm1-56761762 launched on Node cn-jenkins-deploy-platform-ansible-os-4856-1 2025-12-08 10:10:20,982 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/37ea3b89-d502-4536-aa8a-431f3455155e 2025-12-08 10:10:21,276 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/37ea3b89-d502-4536-aa8a-431f3455155e 2025-12-08 10:10:21,311 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/83e915cb-648c-4017-8946-1891743e3d4f 2025-12-08 10:10:24,562 - 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 1019ms') 2025-12-08 10:10:24,562 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-56761762 failed! 2025-12-08 10:10:24,615 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-33445351:ctest-vn1-16069292 is 173.178.33.193 and allocation pool is NOT set 2025-12-08 10:10:26,685 - 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.05 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.643 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 = 0.643/2.345/4.047/1.702 ms') 2025-12-08 10:10:26,685 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-56761762 passed 2025-12-08 10:10:26,843 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-08 10:10:26,843 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-56761762, IP 173.178.33.195, Port 22 2025-12-08 10:10:27,007 - DEBUG - VM ctest-vn1-vm1-56761762 is ready for SSH connections 2025-12-08 10:10:27,008 - INFO - Waiting for VM ctest-vn2-vm1-46728892 to be up.. 2025-12-08 10:10:27,083 - DEBUG - VM is in ACTIVE state now 2025-12-08 10:10:27,084 - INFO - VM name : ctest-vn2-vm1-46728892 2025-12-08 10:10:27,163 - DEBUG - VM ctest-vn2-vm1-46728892 ID is a79aab03-8ee9-4e7d-8387-3672233d0c51 2025-12-08 10:10:27,163 - DEBUG - VM ctest-vn2-vm1-46728892 launched on Node cn-jenkins-deploy-platform-ansible-os-4856-1 2025-12-08 10:10:27,241 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/a79aab03-8ee9-4e7d-8387-3672233d0c51 2025-12-08 10:10:27,250 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/75e5d59b-b3ea-41fe-8e92-7590917a6d4b 2025-12-08 10:10:28,493 - 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=2.65 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.448 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 = 0.448/1.550/2.653/1.102 ms') 2025-12-08 10:10:28,493 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-46728892 passed 2025-12-08 10:10:28,643 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-08 10:10:28,643 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-46728892, IP 82.41.66.3, Port 22 2025-12-08 10:10:28,803 - DEBUG - VM ctest-vn2-vm1-46728892 is ready for SSH connections 2025-12-08 10:10:28,803 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-08 10:10:28,803 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 82.41.66.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-12-08 10:10:28,803 - DEBUG - ping -s 56 -c 3 -W 1 82.41.66.3 2025-12-08 10:10:33,469 - DEBUG - PING 82.41.66.3 (82.41.66.3): 56 data bytes --- 82.41.66.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-12-08 10:10:33,469 - WARNING - Ping to IP 82.41.66.3 from VM ctest-vn1-vm1-56761762 failed 2025-12-08 10:10:33,469 - INFO - Adding interface with subnet_id 791e41aa-0569-4702-96b6-f80601c60961, port_id None to router 53833138-ba4d-4a24-9a97-f360503ca034 2025-12-08 10:10:33,705 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-08 10:10:33,705 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 82.41.66.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-12-08 10:10:33,705 - DEBUG - ping -s 56 -c 3 -W 1 82.41.66.3 2025-12-08 10:10:36,915 - DEBUG - PING 82.41.66.3 (82.41.66.3): 56 data bytes 64 bytes from 82.41.66.3: seq=1 ttl=63 time=3.034 ms 64 bytes from 82.41.66.3: seq=2 ttl=63 time=1.090 ms --- 82.41.66.3 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.090/2.062/3.034 ms 2025-12-08 10:10:36,915 - WARNING - Ping to IP 82.41.66.3 from VM ctest-vn1-vm1-56761762 failed 2025-12-08 10:10:37,916 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 82.41.66.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-12-08 10:10:37,916 - DEBUG - ping -s 56 -c 3 -W 1 82.41.66.3 2025-12-08 10:10:40,119 - DEBUG - PING 82.41.66.3 (82.41.66.3): 56 data bytes 64 bytes from 82.41.66.3: seq=0 ttl=63 time=1.488 ms 64 bytes from 82.41.66.3: seq=1 ttl=63 time=0.972 ms 64 bytes from 82.41.66.3: seq=2 ttl=63 time=0.981 ms --- 82.41.66.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.972/1.147/1.488 ms 2025-12-08 10:10:40,120 - INFO - Ping to IP 82.41.66.3 from VM ctest-vn1-vm1-56761762 passed 2025-12-08 10:10:40,120 - INFO - Deleting interface with subnet_id 9413d34a-e82a-465e-97ae-8d5b19fe2b79, port_id None from router 53833138-ba4d-4a24-9a97-f360503ca034 2025-12-08 10:10:40,228 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-08 10:10:40,228 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 82.41.66.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-12-08 10:10:40,229 - DEBUG - ping -s 56 -c 3 -W 1 82.41.66.3 2025-12-08 10:10:43,440 - DEBUG - PING 82.41.66.3 (82.41.66.3): 56 data bytes --- 82.41.66.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-12-08 10:10:43,440 - WARNING - Ping to IP 82.41.66.3 from VM ctest-vn1-vm1-56761762 failed 2025-12-08 10:10:43,440 - INFO - Adding interface with subnet_id 9413d34a-e82a-465e-97ae-8d5b19fe2b79, port_id None to router 53833138-ba4d-4a24-9a97-f360503ca034 2025-12-08 10:10:43,632 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-08 10:10:43,632 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 82.41.66.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-12-08 10:10:43,632 - DEBUG - ping -s 56 -c 3 -W 1 82.41.66.3 2025-12-08 10:10:45,862 - DEBUG - PING 82.41.66.3 (82.41.66.3): 56 data bytes 64 bytes from 82.41.66.3: seq=0 ttl=63 time=1.394 ms 64 bytes from 82.41.66.3: seq=1 ttl=63 time=0.877 ms 64 bytes from 82.41.66.3: seq=2 ttl=63 time=0.958 ms --- 82.41.66.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.877/1.076/1.394 ms 2025-12-08 10:10:45,862 - INFO - Ping to IP 82.41.66.3 from VM ctest-vn1-vm1-56761762 passed 2025-12-08 10:10:45,862 - INFO - Deleting interface with subnet_id 791e41aa-0569-4702-96b6-f80601c60961, port_id None from router 53833138-ba4d-4a24-9a97-f360503ca034 2025-12-08 10:10:45,966 - INFO - Deleting interface with subnet_id 9413d34a-e82a-465e-97ae-8d5b19fe2b79, port_id None from router 53833138-ba4d-4a24-9a97-f360503ca034 2025-12-08 10:10:46,133 - INFO - Deleting VM ctest-vn2-vm1-46728892 2025-12-08 10:10:46,207 - INFO - Deleting VM ctest-vn1-vm1-56761762 2025-12-08 10:10:46,276 - INFO - Deleting VN ctest-vn2-70348421 2025-12-08 10:10:46,309 - DEBUG - VN d152a7a9-b9a6-4c12-853b-35393639ba23 still in use: Unable to complete operation on network d152a7a9-b9a6-4c12-853b-35393639ba23. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-d7dcc1d2-efec-4987-a923-39c38daefd09'] 2025-12-08 10:10:46,310 - WARNING - Deleting VN ctest-vn2-70348421 failed..Will retry 2025-12-08 10:10:48,409 - DEBUG - Response for deleting network () 2025-12-08 10:10:48,409 - INFO - Deleting VN ctest-vn1-16069292 2025-12-08 10:10:48,525 - DEBUG - Response for deleting network () 2025-12-08 10:10:48,780 - DEBUG - No XMPP flaps were noticed during the test
2025-12-08 10:10:48,780 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:38]
2025-12-08 10:10:48,780 - INFO - -------------------------------------------------------------------------------- 2025-12-08 10:10:49,470 - INFO - Deleted project: ctest-TestRouters-33445351, ID : fdc1e086-6fa6-4db2-b9f9-e240f4516e0f