2025-10-09 02:00:04,549 - INFO - Domain Default found not creating 2025-10-09 02:00:04,680 - INFO - Project ctest-TestRouters-39829276 not found, creating it 2025-10-09 02:00:05,132 - INFO - Created Project:ctest-TestRouters-39829276, ID : 1e84e8f6-fb7e-473c-a280-47ef7371e330 2025-10-09 02:00:06,534 - INFO - ================================================================================ 2025-10-09 02:00:06,534 - INFO - STARTING TEST : test_basic_router_behavior 2025-10-09 02:00:06,534 - 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-09 02:00:06,791 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.254': '0'}} with 2025-10-09 02:00:06,791 - INFO - Initial checks done. Running the testcase now 2025-10-09 02:00:06,791 - INFO - 2025-10-09 02:00:07,339 - DEBUG - Response for create_network : {'network': {'id': 'd4e1bd2d-ba06-4297-a3c0-70227e874b15', 'name': 'ctest-vn1-19063323', 'tenant_id': '1e84e8f6fb7e473ca28047ef7371e330', 'project_id': '1e84e8f6fb7e473ca28047ef7371e330', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-39829276', 'ctest-vn1-19063323'], 'port_security_enabled': True, 'description': ''}} 2025-10-09 02:00:07,531 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fabe9de9-f6df-431d-8ac2-a12075941c2c', 'name': '', 'tenant_id': '1e84e8f6fb7e473ca28047ef7371e330', 'network_id': 'd4e1bd2d-ba06-4297-a3c0-70227e874b15', 'ip_version': 4, 'cidr': '51.177.207.0/26', 'allocation_pools': [{'start': '51.177.207.2', 'end': '51.177.207.62'}], 'gateway_ip': '51.177.207.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '51.177.207.2', 'tags': [], 'project_id': '1e84e8f6fb7e473ca28047ef7371e330'}} 2025-10-09 02:00:07,547 - INFO - Created VN ctest-vn1-19063323 2025-10-09 02:00:07,597 - DEBUG - VN ctest-vn1-19063323 UUID is d4e1bd2d-ba06-4297-a3c0-70227e874b15 2025-10-09 02:00:07,908 - DEBUG - Response for create_network : {'network': {'id': '423a72f7-a37a-458f-ad71-422ba8c6f759', 'name': 'ctest-vn2-14035633', 'tenant_id': '1e84e8f6fb7e473ca28047ef7371e330', 'project_id': '1e84e8f6fb7e473ca28047ef7371e330', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-39829276', 'ctest-vn2-14035633'], 'port_security_enabled': True, 'description': ''}} 2025-10-09 02:00:08,090 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ea882eae-a043-4ffe-a008-8182cfb13339', 'name': '', 'tenant_id': '1e84e8f6fb7e473ca28047ef7371e330', 'network_id': '423a72f7-a37a-458f-ad71-422ba8c6f759', 'ip_version': 4, 'cidr': '21.220.1.192/26', 'allocation_pools': [{'start': '21.220.1.194', 'end': '21.220.1.254'}], 'gateway_ip': '21.220.1.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '21.220.1.194', 'tags': [], 'project_id': '1e84e8f6fb7e473ca28047ef7371e330'}} 2025-10-09 02:00:08,116 - INFO - Created VN ctest-vn2-14035633 2025-10-09 02:00:08,174 - DEBUG - VN ctest-vn2-14035633 UUID is 423a72f7-a37a-458f-ad71-422ba8c6f759 2025-10-09 02:00:08,399 - DEBUG - Services list from nova: [, , ] 2025-10-09 02:00:09,395 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4251-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4251-1) 2025-10-09 02:00:10,617 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4251-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4251-1) 2025-10-09 02:00:10,687 - INFO - Adding interface with subnet_id fabe9de9-f6df-431d-8ac2-a12075941c2c, port_id None to router 9e23bb4a-173b-43ba-bcef-3a8dfe227b56 2025-10-09 02:00:10,948 - INFO - Waiting for VM ctest-vn1-vm1-41427299 to be up.. 2025-10-09 02:00:11,055 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-09 02:00:16,128 - DEBUG - VM is in ACTIVE state now 2025-10-09 02:00:16,129 - INFO - VM name : ctest-vn1-vm1-41427299 2025-10-09 02:00:16,213 - DEBUG - VM ctest-vn1-vm1-41427299 ID is 6fa289f1-b88e-47da-8bb5-f48e8670ca2b 2025-10-09 02:00:16,234 - DEBUG - VM ctest-vn1-vm1-41427299 launched on Node cn-jenkins-deploy-platform-ansible-os-4251-1 2025-10-09 02:00:16,305 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/6fa289f1-b88e-47da-8bb5-f48e8670ca2b 2025-10-09 02:00:16,597 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/6fa289f1-b88e-47da-8bb5-f48e8670ca2b 2025-10-09 02:00:16,630 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/2d7e95c6-bcae-4f3d-9681-d5909ebfc264 2025-10-09 02:00:19,902 - 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 1031ms') 2025-10-09 02:00:19,902 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-41427299 failed! 2025-10-09 02:00:19,954 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-39829276:ctest-vn1-19063323 is 51.177.207.1 and allocation pool is NOT set 2025-10-09 02:00:22,017 - 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=3.20 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.491 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.491/1.843/3.195/1.352 ms') 2025-10-09 02:00:22,018 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-41427299 passed 2025-10-09 02:00:22,169 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-09 02:00:22,170 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-41427299, IP 51.177.207.3, Port 22 2025-10-09 02:00:22,333 - DEBUG - VM ctest-vn1-vm1-41427299 is ready for SSH connections 2025-10-09 02:00:22,333 - INFO - Waiting for VM ctest-vn2-vm1-18192983 to be up.. 2025-10-09 02:00:22,408 - DEBUG - VM is in ACTIVE state now 2025-10-09 02:00:22,408 - INFO - VM name : ctest-vn2-vm1-18192983 2025-10-09 02:00:22,478 - DEBUG - VM ctest-vn2-vm1-18192983 ID is d5dd2348-d9c5-4452-bbd5-059fc7d2e181 2025-10-09 02:00:22,478 - DEBUG - VM ctest-vn2-vm1-18192983 launched on Node cn-jenkins-deploy-platform-ansible-os-4251-1 2025-10-09 02:00:22,553 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/d5dd2348-d9c5-4452-bbd5-059fc7d2e181 2025-10-09 02:00:22,563 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/ea938605-f054-4f4a-ac4b-a17cbaf8c262 2025-10-09 02:00:23,818 - 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.28 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.481 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.481/1.882/3.284/1.401 ms') 2025-10-09 02:00:23,818 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-18192983 passed 2025-10-09 02:00:23,928 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-09 02:00:23,928 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-18192983, IP 21.220.1.195, Port 22 2025-10-09 02:00:24,085 - DEBUG - VM ctest-vn2-vm1-18192983 is ready for SSH connections 2025-10-09 02:00:24,085 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-09 02:00:24,086 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 21.220.1.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2025-10-09 02:00:24,086 - DEBUG - ping -s 56 -c 3 -W 1 21.220.1.195 2025-10-09 02:00:29,022 - DEBUG - PING 21.220.1.195 (21.220.1.195): 56 data bytes --- 21.220.1.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-10-09 02:00:29,023 - WARNING - Ping to IP 21.220.1.195 from VM ctest-vn1-vm1-41427299 failed 2025-10-09 02:00:29,023 - INFO - Adding interface with subnet_id ea882eae-a043-4ffe-a008-8182cfb13339, port_id None to router 9e23bb4a-173b-43ba-bcef-3a8dfe227b56 2025-10-09 02:00:29,245 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-09 02:00:29,245 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 21.220.1.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2025-10-09 02:00:29,245 - DEBUG - ping -s 56 -c 3 -W 1 21.220.1.195 2025-10-09 02:00:32,452 - DEBUG - PING 21.220.1.195 (21.220.1.195): 56 data bytes 64 bytes from 21.220.1.195: seq=1 ttl=63 time=3.297 ms 64 bytes from 21.220.1.195: seq=2 ttl=63 time=1.057 ms --- 21.220.1.195 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.057/2.177/3.297 ms 2025-10-09 02:00:32,452 - WARNING - Ping to IP 21.220.1.195 from VM ctest-vn1-vm1-41427299 failed 2025-10-09 02:00:33,453 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 21.220.1.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2025-10-09 02:00:33,453 - DEBUG - ping -s 56 -c 3 -W 1 21.220.1.195 2025-10-09 02:00:35,660 - DEBUG - PING 21.220.1.195 (21.220.1.195): 56 data bytes 64 bytes from 21.220.1.195: seq=0 ttl=63 time=1.658 ms 64 bytes from 21.220.1.195: seq=1 ttl=63 time=0.976 ms 64 bytes from 21.220.1.195: seq=2 ttl=63 time=0.989 ms --- 21.220.1.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.976/1.207/1.658 ms 2025-10-09 02:00:35,660 - INFO - Ping to IP 21.220.1.195 from VM ctest-vn1-vm1-41427299 passed 2025-10-09 02:00:35,660 - INFO - Deleting interface with subnet_id fabe9de9-f6df-431d-8ac2-a12075941c2c, port_id None from router 9e23bb4a-173b-43ba-bcef-3a8dfe227b56 2025-10-09 02:00:35,774 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-09 02:00:35,775 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 21.220.1.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2025-10-09 02:00:35,775 - DEBUG - ping -s 56 -c 3 -W 1 21.220.1.195 2025-10-09 02:00:38,978 - DEBUG - PING 21.220.1.195 (21.220.1.195): 56 data bytes --- 21.220.1.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-10-09 02:00:38,978 - WARNING - Ping to IP 21.220.1.195 from VM ctest-vn1-vm1-41427299 failed 2025-10-09 02:00:38,978 - INFO - Adding interface with subnet_id fabe9de9-f6df-431d-8ac2-a12075941c2c, port_id None to router 9e23bb4a-173b-43ba-bcef-3a8dfe227b56 2025-10-09 02:00:39,187 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-09 02:00:39,187 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 21.220.1.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2025-10-09 02:00:39,187 - DEBUG - ping -s 56 -c 3 -W 1 21.220.1.195 2025-10-09 02:00:41,384 - DEBUG - PING 21.220.1.195 (21.220.1.195): 56 data bytes 64 bytes from 21.220.1.195: seq=0 ttl=63 time=1.552 ms 64 bytes from 21.220.1.195: seq=1 ttl=63 time=0.977 ms 64 bytes from 21.220.1.195: seq=2 ttl=63 time=1.176 ms --- 21.220.1.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.977/1.235/1.552 ms 2025-10-09 02:00:41,384 - INFO - Ping to IP 21.220.1.195 from VM ctest-vn1-vm1-41427299 passed 2025-10-09 02:00:41,385 - INFO - Deleting interface with subnet_id ea882eae-a043-4ffe-a008-8182cfb13339, port_id None from router 9e23bb4a-173b-43ba-bcef-3a8dfe227b56 2025-10-09 02:00:41,506 - INFO - Deleting interface with subnet_id fabe9de9-f6df-431d-8ac2-a12075941c2c, port_id None from router 9e23bb4a-173b-43ba-bcef-3a8dfe227b56 2025-10-09 02:00:41,687 - INFO - Deleting VM ctest-vn2-vm1-18192983 2025-10-09 02:00:41,767 - INFO - Deleting VM ctest-vn1-vm1-41427299 2025-10-09 02:00:41,846 - INFO - Deleting VN ctest-vn2-14035633 2025-10-09 02:00:41,879 - DEBUG - VN 423a72f7-a37a-458f-ad71-422ba8c6f759 still in use: Unable to complete operation on network 423a72f7-a37a-458f-ad71-422ba8c6f759. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-daa4a35c-7c1a-49f9-a766-8da5ffe50ec2'] 2025-10-09 02:00:41,880 - WARNING - Deleting VN ctest-vn2-14035633 failed..Will retry 2025-10-09 02:00:43,969 - DEBUG - Response for deleting network () 2025-10-09 02:00:43,969 - INFO - Deleting VN ctest-vn1-19063323 2025-10-09 02:00:44,057 - DEBUG - Response for deleting network () 2025-10-09 02:00:44,318 - DEBUG - No XMPP flaps were noticed during the test
2025-10-09 02:00:44,318 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:38]
2025-10-09 02:00:44,318 - INFO - -------------------------------------------------------------------------------- 2025-10-09 02:00:44,987 - INFO - Deleted project: ctest-TestRouters-39829276, ID : 1e84e8f6-fb7e-473c-a280-47ef7371e330