2025-10-21 18:42:32,330 - INFO - Domain Default found not creating 2025-10-21 18:42:32,484 - INFO - Project ctest-TestRouters-58614574 not found, creating it 2025-10-21 18:42:32,952 - INFO - Created Project:ctest-TestRouters-58614574, ID : de6a6b72-6c19-48fd-b6ee-26c7c4634fac 2025-10-21 18:42:34,755 - INFO - ================================================================================ 2025-10-21 18:42:34,755 - INFO - STARTING TEST : test_basic_router_behavior 2025-10-21 18:42:34,755 - 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-21 18:42:35,011 - DEBUG - Nothing to compare xmpp stats {'10.0.0.141': {'10.20.0.17': '0'}} with 2025-10-21 18:42:35,011 - INFO - Initial checks done. Running the testcase now 2025-10-21 18:42:35,011 - INFO - 2025-10-21 18:42:35,625 - DEBUG - Response for create_network : {'network': {'id': '4a3d52ac-b722-4e76-9f2d-d77ffc97b396', 'name': 'ctest-vn1-03437413', 'tenant_id': 'de6a6b726c1948fdb6ee26c7c4634fac', 'project_id': 'de6a6b726c1948fdb6ee26c7c4634fac', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-58614574', 'ctest-vn1-03437413'], 'port_security_enabled': True, 'description': ''}} 2025-10-21 18:42:35,844 - DEBUG - Response for create_subnet : {'subnet': {'id': '4538c0ec-7cd8-4167-a6e9-766569722b75', 'name': '', 'tenant_id': 'de6a6b726c1948fdb6ee26c7c4634fac', 'network_id': '4a3d52ac-b722-4e76-9f2d-d77ffc97b396', 'ip_version': 4, 'cidr': '222.194.71.64/26', 'allocation_pools': [{'start': '222.194.71.66', 'end': '222.194.71.126'}], 'gateway_ip': '222.194.71.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '222.194.71.66', 'tags': [], 'project_id': 'de6a6b726c1948fdb6ee26c7c4634fac'}} 2025-10-21 18:42:35,864 - INFO - Created VN ctest-vn1-03437413 2025-10-21 18:42:35,921 - DEBUG - VN ctest-vn1-03437413 UUID is 4a3d52ac-b722-4e76-9f2d-d77ffc97b396 2025-10-21 18:42:36,233 - DEBUG - Response for create_network : {'network': {'id': '858abd33-1647-4ca8-ae02-d054cf06a56b', 'name': 'ctest-vn2-41952154', 'tenant_id': 'de6a6b726c1948fdb6ee26c7c4634fac', 'project_id': 'de6a6b726c1948fdb6ee26c7c4634fac', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-58614574', 'ctest-vn2-41952154'], 'port_security_enabled': True, 'description': ''}} 2025-10-21 18:42:36,433 - DEBUG - Response for create_subnet : {'subnet': {'id': '1cc866f0-ca41-474b-92b9-4aa2d1d91740', 'name': '', 'tenant_id': 'de6a6b726c1948fdb6ee26c7c4634fac', 'network_id': '858abd33-1647-4ca8-ae02-d054cf06a56b', 'ip_version': 4, 'cidr': '113.58.171.192/26', 'allocation_pools': [{'start': '113.58.171.194', 'end': '113.58.171.254'}], 'gateway_ip': '113.58.171.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '113.58.171.194', 'tags': [], 'project_id': 'de6a6b726c1948fdb6ee26c7c4634fac'}} 2025-10-21 18:42:36,450 - INFO - Created VN ctest-vn2-41952154 2025-10-21 18:42:36,506 - DEBUG - VN ctest-vn2-41952154 UUID is 858abd33-1647-4ca8-ae02-d054cf06a56b 2025-10-21 18:42:36,716 - DEBUG - Services list from nova: [, , ] 2025-10-21 18:42:37,794 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4403-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4403-1) 2025-10-21 18:42:39,144 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4403-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4403-1) 2025-10-21 18:42:39,204 - INFO - Adding interface with subnet_id 4538c0ec-7cd8-4167-a6e9-766569722b75, port_id None to router ad72eca8-3069-459e-a3fc-f2249b1c545b 2025-10-21 18:42:39,504 - INFO - Waiting for VM ctest-vn1-vm1-93185418 to be up.. 2025-10-21 18:42:39,612 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-21 18:42:44,690 - DEBUG - VM is in ACTIVE state now 2025-10-21 18:42:44,690 - INFO - VM name : ctest-vn1-vm1-93185418 2025-10-21 18:42:44,765 - DEBUG - VM ctest-vn1-vm1-93185418 ID is 3e714d4d-ae46-4312-bee8-857db3b8e39f 2025-10-21 18:42:44,786 - DEBUG - VM ctest-vn1-vm1-93185418 launched on Node cn-jenkins-deploy-platform-ansible-os-4403-1 2025-10-21 18:42:44,868 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/3e714d4d-ae46-4312-bee8-857db3b8e39f 2025-10-21 18:42:45,167 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/3e714d4d-ae46-4312-bee8-857db3b8e39f 2025-10-21 18:42:45,204 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/6a3049db-ac5b-4436-9bbe-0ad745683e36 2025-10-21 18:42:48,461 - 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-10-21 18:42:48,461 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-93185418 failed! 2025-10-21 18:42:48,518 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-58614574:ctest-vn1-03437413 is 222.194.71.65 and allocation pool is NOT set 2025-10-21 18:42:50,583 - 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.52 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.494 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.494/2.009/3.524/1.515 ms') 2025-10-21 18:42:50,583 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-93185418 passed 2025-10-21 18:42:50,738 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-21 18:42:50,738 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-93185418, IP 222.194.71.67, Port 22 2025-10-21 18:42:50,901 - DEBUG - VM ctest-vn1-vm1-93185418 is ready for SSH connections 2025-10-21 18:42:50,902 - INFO - Waiting for VM ctest-vn2-vm1-98419397 to be up.. 2025-10-21 18:42:50,988 - DEBUG - VM is in ACTIVE state now 2025-10-21 18:42:50,988 - INFO - VM name : ctest-vn2-vm1-98419397 2025-10-21 18:42:51,063 - DEBUG - VM ctest-vn2-vm1-98419397 ID is 7c98ce18-4192-4997-80ef-d774349b2565 2025-10-21 18:42:51,063 - DEBUG - VM ctest-vn2-vm1-98419397 launched on Node cn-jenkins-deploy-platform-ansible-os-4403-1 2025-10-21 18:42:51,135 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/7c98ce18-4192-4997-80ef-d774349b2565 2025-10-21 18:42:51,143 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/3e3c8b16-65e9-4c2a-aeca-4a957ac17390 2025-10-21 18:42:52,379 - 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.19 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.619 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.619/1.905/3.192/1.286 ms') 2025-10-21 18:42:52,379 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-98419397 passed 2025-10-21 18:42:52,530 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-21 18:42:52,530 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-98419397, IP 113.58.171.195, Port 22 2025-10-21 18:42:52,693 - DEBUG - VM ctest-vn2-vm1-98419397 is ready for SSH connections 2025-10-21 18:42:52,693 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-21 18:42:52,693 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 113.58.171.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2025-10-21 18:42:52,693 - DEBUG - ping -s 56 -c 3 -W 1 113.58.171.195 2025-10-21 18:42:57,764 - DEBUG - PING 113.58.171.195 (113.58.171.195): 56 data bytes --- 113.58.171.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-10-21 18:42:57,764 - WARNING - Ping to IP 113.58.171.195 from VM ctest-vn1-vm1-93185418 failed 2025-10-21 18:42:57,764 - INFO - Adding interface with subnet_id 1cc866f0-ca41-474b-92b9-4aa2d1d91740, port_id None to router ad72eca8-3069-459e-a3fc-f2249b1c545b 2025-10-21 18:42:58,026 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-21 18:42:58,026 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 113.58.171.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2025-10-21 18:42:58,026 - DEBUG - ping -s 56 -c 3 -W 1 113.58.171.195 2025-10-21 18:43:01,242 - DEBUG - PING 113.58.171.195 (113.58.171.195): 56 data bytes 64 bytes from 113.58.171.195: seq=1 ttl=63 time=3.749 ms 64 bytes from 113.58.171.195: seq=2 ttl=63 time=1.233 ms --- 113.58.171.195 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.233/2.491/3.749 ms 2025-10-21 18:43:01,243 - WARNING - Ping to IP 113.58.171.195 from VM ctest-vn1-vm1-93185418 failed 2025-10-21 18:43:02,243 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 113.58.171.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2025-10-21 18:43:02,243 - DEBUG - ping -s 56 -c 3 -W 1 113.58.171.195 2025-10-21 18:43:04,452 - DEBUG - PING 113.58.171.195 (113.58.171.195): 56 data bytes 64 bytes from 113.58.171.195: seq=0 ttl=63 time=1.842 ms 64 bytes from 113.58.171.195: seq=1 ttl=63 time=1.343 ms 64 bytes from 113.58.171.195: seq=2 ttl=63 time=1.094 ms --- 113.58.171.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.094/1.426/1.842 ms 2025-10-21 18:43:04,453 - INFO - Ping to IP 113.58.171.195 from VM ctest-vn1-vm1-93185418 passed 2025-10-21 18:43:04,453 - INFO - Deleting interface with subnet_id 4538c0ec-7cd8-4167-a6e9-766569722b75, port_id None from router ad72eca8-3069-459e-a3fc-f2249b1c545b 2025-10-21 18:43:04,570 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-21 18:43:04,570 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 113.58.171.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2025-10-21 18:43:04,570 - DEBUG - ping -s 56 -c 3 -W 1 113.58.171.195 2025-10-21 18:43:07,771 - DEBUG - PING 113.58.171.195 (113.58.171.195): 56 data bytes --- 113.58.171.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-10-21 18:43:07,771 - WARNING - Ping to IP 113.58.171.195 from VM ctest-vn1-vm1-93185418 failed 2025-10-21 18:43:07,772 - INFO - Adding interface with subnet_id 4538c0ec-7cd8-4167-a6e9-766569722b75, port_id None to router ad72eca8-3069-459e-a3fc-f2249b1c545b 2025-10-21 18:43:08,009 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-21 18:43:08,009 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 113.58.171.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.141, gateway password: c0ntrail123 2025-10-21 18:43:08,010 - DEBUG - ping -s 56 -c 3 -W 1 113.58.171.195 2025-10-21 18:43:10,217 - DEBUG - PING 113.58.171.195 (113.58.171.195): 56 data bytes 64 bytes from 113.58.171.195: seq=0 ttl=63 time=1.368 ms 64 bytes from 113.58.171.195: seq=1 ttl=63 time=1.195 ms 64 bytes from 113.58.171.195: seq=2 ttl=63 time=1.100 ms --- 113.58.171.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.100/1.221/1.368 ms 2025-10-21 18:43:10,218 - INFO - Ping to IP 113.58.171.195 from VM ctest-vn1-vm1-93185418 passed 2025-10-21 18:43:10,218 - INFO - Deleting interface with subnet_id 1cc866f0-ca41-474b-92b9-4aa2d1d91740, port_id None from router ad72eca8-3069-459e-a3fc-f2249b1c545b 2025-10-21 18:43:10,357 - INFO - Deleting interface with subnet_id 4538c0ec-7cd8-4167-a6e9-766569722b75, port_id None from router ad72eca8-3069-459e-a3fc-f2249b1c545b 2025-10-21 18:43:10,565 - INFO - Deleting VM ctest-vn2-vm1-98419397 2025-10-21 18:43:10,642 - INFO - Deleting VM ctest-vn1-vm1-93185418 2025-10-21 18:43:10,722 - INFO - Deleting VN ctest-vn2-41952154 2025-10-21 18:43:10,765 - DEBUG - VN 858abd33-1647-4ca8-ae02-d054cf06a56b still in use: Unable to complete operation on network 858abd33-1647-4ca8-ae02-d054cf06a56b. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-45dd4730-0332-4ce0-9b63-719baedc82b9'] 2025-10-21 18:43:10,765 - WARNING - Deleting VN ctest-vn2-41952154 failed..Will retry 2025-10-21 18:43:12,879 - DEBUG - Response for deleting network () 2025-10-21 18:43:12,879 - INFO - Deleting VN ctest-vn1-03437413 2025-10-21 18:43:12,992 - DEBUG - Response for deleting network () 2025-10-21 18:43:13,251 - DEBUG - No XMPP flaps were noticed during the test
2025-10-21 18:43:13,251 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:39]
2025-10-21 18:43:13,252 - INFO - -------------------------------------------------------------------------------- 2025-10-21 18:43:13,985 - INFO - Deleted project: ctest-TestRouters-58614574, ID : de6a6b72-6c19-48fd-b6ee-26c7c4634fac