2025-11-06 02:06:18,428 - INFO - Domain Default found not creating 2025-11-06 02:06:18,581 - INFO - Project ctest-TestRouters-56401805 not found, creating it 2025-11-06 02:06:19,108 - INFO - Created Project:ctest-TestRouters-56401805, ID : 7f2a7357-8356-4c0d-b441-f0b5f0ad48fe 2025-11-06 02:06:20,668 - INFO - ================================================================================ 2025-11-06 02:06:20,669 - INFO - STARTING TEST : test_basic_router_behavior 2025-11-06 02:06:20,669 - 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-06 02:06:20,933 - DEBUG - Nothing to compare xmpp stats {'10.0.0.249': {'10.20.0.201': '0'}} with 2025-11-06 02:06:20,934 - INFO - Initial checks done. Running the testcase now 2025-11-06 02:06:20,934 - INFO - 2025-11-06 02:06:21,551 - DEBUG - Response for create_network : {'network': {'id': 'e76d1f3f-8130-467d-945d-a0d57ee5b141', 'name': 'ctest-vn1-36288962', 'tenant_id': '7f2a735783564c0db441f0b5f0ad48fe', 'project_id': '7f2a735783564c0db441f0b5f0ad48fe', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-56401805', 'ctest-vn1-36288962'], 'port_security_enabled': True, 'description': ''}} 2025-11-06 02:06:21,780 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e20709d0-cadf-4de6-9a00-92336d4ca034', 'name': '', 'tenant_id': '7f2a735783564c0db441f0b5f0ad48fe', 'network_id': 'e76d1f3f-8130-467d-945d-a0d57ee5b141', 'ip_version': 4, 'cidr': '162.216.105.0/26', 'allocation_pools': [{'start': '162.216.105.2', 'end': '162.216.105.62'}], 'gateway_ip': '162.216.105.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '162.216.105.2', 'tags': [], 'project_id': '7f2a735783564c0db441f0b5f0ad48fe'}} 2025-11-06 02:06:21,803 - INFO - Created VN ctest-vn1-36288962 2025-11-06 02:06:21,859 - DEBUG - VN ctest-vn1-36288962 UUID is e76d1f3f-8130-467d-945d-a0d57ee5b141 2025-11-06 02:06:22,231 - DEBUG - Response for create_network : {'network': {'id': 'c779bef3-9619-4e72-8c4e-fb246cedc01f', 'name': 'ctest-vn2-28878911', 'tenant_id': '7f2a735783564c0db441f0b5f0ad48fe', 'project_id': '7f2a735783564c0db441f0b5f0ad48fe', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-56401805', 'ctest-vn2-28878911'], 'port_security_enabled': True, 'description': ''}} 2025-11-06 02:06:22,430 - DEBUG - Response for create_subnet : {'subnet': {'id': '55d16c84-c9ad-45f4-9922-a8d1c03e481e', 'name': '', 'tenant_id': '7f2a735783564c0db441f0b5f0ad48fe', 'network_id': 'c779bef3-9619-4e72-8c4e-fb246cedc01f', 'ip_version': 4, 'cidr': '53.145.232.128/26', 'allocation_pools': [{'start': '53.145.232.130', 'end': '53.145.232.190'}], 'gateway_ip': '53.145.232.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '53.145.232.130', 'tags': [], 'project_id': '7f2a735783564c0db441f0b5f0ad48fe'}} 2025-11-06 02:06:22,452 - INFO - Created VN ctest-vn2-28878911 2025-11-06 02:06:22,506 - DEBUG - VN ctest-vn2-28878911 UUID is c779bef3-9619-4e72-8c4e-fb246cedc01f 2025-11-06 02:06:22,733 - DEBUG - Services list from nova: [, , ] 2025-11-06 02:06:23,840 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4661-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4661-1) 2025-11-06 02:06:25,449 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4661-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4661-1) 2025-11-06 02:06:25,519 - INFO - Adding interface with subnet_id e20709d0-cadf-4de6-9a00-92336d4ca034, port_id None to router 164cc570-9513-48dd-8814-fb8b9bb67266 2025-11-06 02:06:25,777 - INFO - Waiting for VM ctest-vn1-vm1-54723450 to be up.. 2025-11-06 02:06:25,882 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-06 02:06:30,966 - DEBUG - VM is in ACTIVE state now 2025-11-06 02:06:30,966 - INFO - VM name : ctest-vn1-vm1-54723450 2025-11-06 02:06:31,077 - DEBUG - VM ctest-vn1-vm1-54723450 ID is 15f428a3-774d-4c0e-a633-402c670607e5 2025-11-06 02:06:31,107 - DEBUG - VM ctest-vn1-vm1-54723450 launched on Node cn-jenkins-deploy-platform-ansible-os-4661-1 2025-11-06 02:06:31,195 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/15f428a3-774d-4c0e-a633-402c670607e5 2025-11-06 02:06:31,496 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/15f428a3-774d-4c0e-a633-402c670607e5 2025-11-06 02:06:31,546 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/5f71709f-bb16-4dc9-aef9-cdfd76c3ccb9 2025-11-06 02:06:34,830 - 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 1023ms') 2025-11-06 02:06:34,831 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-54723450 failed! 2025-11-06 02:06:34,886 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-56401805:ctest-vn1-36288962 is 162.216.105.1 and allocation pool is NOT set 2025-11-06 02:06:36,948 - 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=16.1 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.79 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 = 1.794/8.930/16.066/7.136 ms') 2025-11-06 02:06:36,948 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-54723450 passed 2025-11-06 02:06:37,107 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-06 02:06:37,107 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-54723450, IP 162.216.105.3, Port 22 2025-11-06 02:06:37,262 - DEBUG - VM ctest-vn1-vm1-54723450 is ready for SSH connections 2025-11-06 02:06:37,262 - INFO - Waiting for VM ctest-vn2-vm1-27105867 to be up.. 2025-11-06 02:06:37,349 - DEBUG - VM is in ACTIVE state now 2025-11-06 02:06:37,350 - INFO - VM name : ctest-vn2-vm1-27105867 2025-11-06 02:06:37,452 - DEBUG - VM ctest-vn2-vm1-27105867 ID is 6977b250-81a7-4982-995e-71a79c525fd2 2025-11-06 02:06:37,452 - DEBUG - VM ctest-vn2-vm1-27105867 launched on Node cn-jenkins-deploy-platform-ansible-os-4661-1 2025-11-06 02:06:37,537 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/6977b250-81a7-4982-995e-71a79c525fd2 2025-11-06 02:06:37,548 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/c158c0a4-8f65-4b58-8d18-601b079a4a2a 2025-11-06 02:06:38,793 - 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.09 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.593 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.593/1.843/3.093/1.250 ms') 2025-11-06 02:06:38,793 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-27105867 passed 2025-11-06 02:06:38,942 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-06 02:06:38,943 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-27105867, IP 53.145.232.131, Port 22 2025-11-06 02:06:39,117 - DEBUG - VM ctest-vn2-vm1-27105867 is ready for SSH connections 2025-11-06 02:06:39,117 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-06 02:06:39,117 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 53.145.232.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2025-11-06 02:06:39,117 - DEBUG - ping -s 56 -c 3 -W 1 53.145.232.131 2025-11-06 02:06:44,137 - DEBUG - PING 53.145.232.131 (53.145.232.131): 56 data bytes --- 53.145.232.131 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-06 02:06:44,137 - WARNING - Ping to IP 53.145.232.131 from VM ctest-vn1-vm1-54723450 failed 2025-11-06 02:06:44,137 - INFO - Adding interface with subnet_id 55d16c84-c9ad-45f4-9922-a8d1c03e481e, port_id None to router 164cc570-9513-48dd-8814-fb8b9bb67266 2025-11-06 02:06:44,409 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-06 02:06:44,410 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 53.145.232.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2025-11-06 02:06:44,410 - DEBUG - ping -s 56 -c 3 -W 1 53.145.232.131 2025-11-06 02:06:47,636 - DEBUG - PING 53.145.232.131 (53.145.232.131): 56 data bytes 64 bytes from 53.145.232.131: seq=1 ttl=63 time=2.998 ms 64 bytes from 53.145.232.131: seq=2 ttl=63 time=1.587 ms --- 53.145.232.131 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.587/2.292/2.998 ms 2025-11-06 02:06:47,636 - WARNING - Ping to IP 53.145.232.131 from VM ctest-vn1-vm1-54723450 failed 2025-11-06 02:06:48,638 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 53.145.232.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2025-11-06 02:06:48,638 - DEBUG - ping -s 56 -c 3 -W 1 53.145.232.131 2025-11-06 02:06:50,888 - DEBUG - PING 53.145.232.131 (53.145.232.131): 56 data bytes 64 bytes from 53.145.232.131: seq=0 ttl=63 time=1.969 ms 64 bytes from 53.145.232.131: seq=1 ttl=63 time=1.154 ms 64 bytes from 53.145.232.131: seq=2 ttl=63 time=1.352 ms --- 53.145.232.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.154/1.491/1.969 ms 2025-11-06 02:06:50,888 - INFO - Ping to IP 53.145.232.131 from VM ctest-vn1-vm1-54723450 passed 2025-11-06 02:06:50,888 - INFO - Deleting interface with subnet_id e20709d0-cadf-4de6-9a00-92336d4ca034, port_id None from router 164cc570-9513-48dd-8814-fb8b9bb67266 2025-11-06 02:06:51,044 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-06 02:06:51,045 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 53.145.232.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2025-11-06 02:06:51,045 - DEBUG - ping -s 56 -c 3 -W 1 53.145.232.131 2025-11-06 02:06:54,269 - DEBUG - PING 53.145.232.131 (53.145.232.131): 56 data bytes --- 53.145.232.131 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-06 02:06:54,270 - WARNING - Ping to IP 53.145.232.131 from VM ctest-vn1-vm1-54723450 failed 2025-11-06 02:06:54,270 - INFO - Adding interface with subnet_id e20709d0-cadf-4de6-9a00-92336d4ca034, port_id None to router 164cc570-9513-48dd-8814-fb8b9bb67266 2025-11-06 02:06:54,508 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-06 02:06:54,509 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 53.145.232.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2025-11-06 02:06:54,509 - DEBUG - ping -s 56 -c 3 -W 1 53.145.232.131 2025-11-06 02:06:56,717 - DEBUG - PING 53.145.232.131 (53.145.232.131): 56 data bytes 64 bytes from 53.145.232.131: seq=0 ttl=63 time=1.620 ms 64 bytes from 53.145.232.131: seq=1 ttl=63 time=1.317 ms 64 bytes from 53.145.232.131: seq=2 ttl=63 time=1.199 ms --- 53.145.232.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.199/1.378/1.620 ms 2025-11-06 02:06:56,718 - INFO - Ping to IP 53.145.232.131 from VM ctest-vn1-vm1-54723450 passed 2025-11-06 02:06:56,718 - INFO - Deleting interface with subnet_id 55d16c84-c9ad-45f4-9922-a8d1c03e481e, port_id None from router 164cc570-9513-48dd-8814-fb8b9bb67266 2025-11-06 02:06:56,831 - INFO - Deleting interface with subnet_id e20709d0-cadf-4de6-9a00-92336d4ca034, port_id None from router 164cc570-9513-48dd-8814-fb8b9bb67266 2025-11-06 02:06:57,025 - INFO - Deleting VM ctest-vn2-vm1-27105867 2025-11-06 02:06:57,109 - INFO - Deleting VM ctest-vn1-vm1-54723450 2025-11-06 02:06:57,184 - INFO - Deleting VN ctest-vn2-28878911 2025-11-06 02:06:57,225 - DEBUG - VN c779bef3-9619-4e72-8c4e-fb246cedc01f still in use: Unable to complete operation on network c779bef3-9619-4e72-8c4e-fb246cedc01f. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-21bac1b0-d039-4c42-b2d6-f0f873f73f8a'] 2025-11-06 02:06:57,225 - WARNING - Deleting VN ctest-vn2-28878911 failed..Will retry 2025-11-06 02:06:59,329 - DEBUG - Response for deleting network () 2025-11-06 02:06:59,329 - INFO - Deleting VN ctest-vn1-36288962 2025-11-06 02:06:59,437 - DEBUG - Response for deleting network () 2025-11-06 02:06:59,700 - DEBUG - No XMPP flaps were noticed during the test
2025-11-06 02:06:59,700 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:39]
2025-11-06 02:06:59,701 - INFO - -------------------------------------------------------------------------------- 2025-11-06 02:07:00,394 - INFO - Deleted project: ctest-TestRouters-56401805, ID : 7f2a7357-8356-4c0d-b441-f0b5f0ad48fe