2026-02-04 21:39:19,159 - INFO - Domain Default found not creating 2026-02-04 21:39:19,432 - INFO - Project ctest-TestRouters-44123072 not found, creating it 2026-02-04 21:39:19,902 - INFO - Created Project:ctest-TestRouters-44123072, ID : ca1ca86b-1d46-4369-9f3d-dc069b7f793c 2026-02-04 21:39:22,013 - INFO - ================================================================================ 2026-02-04 21:39:22,014 - INFO - STARTING TEST : test_basic_router_behavior 2026-02-04 21:39:22,015 - 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 2026-02-04 21:39:22,291 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.17': '0'}} with 2026-02-04 21:39:22,291 - INFO - Initial checks done. Running the testcase now 2026-02-04 21:39:22,293 - INFO - 2026-02-04 21:39:22,914 - DEBUG - Response for create_network : {'network': {'id': '61ba62e6-bb23-4cc5-a9d5-e5fe9321155a', 'name': 'ctest-vn1-76619908', 'tenant_id': 'ca1ca86b1d4643699f3ddc069b7f793c', 'project_id': 'ca1ca86b1d4643699f3ddc069b7f793c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-44123072', 'ctest-vn1-76619908'], 'port_security_enabled': True, 'description': ''}} 2026-02-04 21:39:23,124 - DEBUG - Response for create_subnet : {'subnet': {'id': '87b83ef7-3f11-4951-9cde-0b2e7f086748', 'name': '', 'tenant_id': 'ca1ca86b1d4643699f3ddc069b7f793c', 'network_id': '61ba62e6-bb23-4cc5-a9d5-e5fe9321155a', 'ip_version': 4, 'cidr': '207.93.219.0/26', 'allocation_pools': [{'start': '207.93.219.2', 'end': '207.93.219.62'}], 'gateway_ip': '207.93.219.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '207.93.219.2', 'tags': [], 'project_id': 'ca1ca86b1d4643699f3ddc069b7f793c'}} 2026-02-04 21:39:23,141 - INFO - Created VN ctest-vn1-76619908 2026-02-04 21:39:23,194 - DEBUG - VN ctest-vn1-76619908 UUID is 61ba62e6-bb23-4cc5-a9d5-e5fe9321155a 2026-02-04 21:39:23,516 - DEBUG - Response for create_network : {'network': {'id': '08d9654e-a688-4c5c-a4fa-dcee74533235', 'name': 'ctest-vn2-42839075', 'tenant_id': 'ca1ca86b1d4643699f3ddc069b7f793c', 'project_id': 'ca1ca86b1d4643699f3ddc069b7f793c', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-44123072', 'ctest-vn2-42839075'], 'port_security_enabled': True, 'description': ''}} 2026-02-04 21:39:23,668 - DEBUG - Response for create_subnet : {'subnet': {'id': '61645513-d730-41bf-8ac8-b63d7af65104', 'name': '', 'tenant_id': 'ca1ca86b1d4643699f3ddc069b7f793c', 'network_id': '08d9654e-a688-4c5c-a4fa-dcee74533235', 'ip_version': 4, 'cidr': '58.71.23.64/26', 'allocation_pools': [{'start': '58.71.23.66', 'end': '58.71.23.126'}], 'gateway_ip': '58.71.23.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '58.71.23.66', 'tags': [], 'project_id': 'ca1ca86b1d4643699f3ddc069b7f793c'}} 2026-02-04 21:39:23,689 - INFO - Created VN ctest-vn2-42839075 2026-02-04 21:39:23,746 - DEBUG - VN ctest-vn2-42839075 UUID is 08d9654e-a688-4c5c-a4fa-dcee74533235 2026-02-04 21:39:23,945 - DEBUG - Services list from nova: [, , ] 2026-02-04 21:39:25,087 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5210-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5210-1) 2026-02-04 21:39:26,239 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5210-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5210-1) 2026-02-04 21:39:26,305 - INFO - Adding interface with subnet_id 87b83ef7-3f11-4951-9cde-0b2e7f086748, port_id None to router d36c7000-6ff6-43c4-8d59-6a68b64bf132 2026-02-04 21:39:26,583 - INFO - Waiting for VM ctest-vn1-vm1-72012392 to be up.. 2026-02-04 21:39:26,669 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-02-04 21:39:31,741 - DEBUG - VM is in ACTIVE state now 2026-02-04 21:39:31,741 - INFO - VM name : ctest-vn1-vm1-72012392 2026-02-04 21:39:31,827 - DEBUG - VM ctest-vn1-vm1-72012392 ID is f57cab92-2df6-44fb-9a41-526b71585e0f 2026-02-04 21:39:31,846 - DEBUG - VM ctest-vn1-vm1-72012392 launched on Node cn-jenkins-deploy-platform-ansible-os-5210-1 2026-02-04 21:39:31,924 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/f57cab92-2df6-44fb-9a41-526b71585e0f 2026-02-04 21:39:32,221 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/f57cab92-2df6-44fb-9a41-526b71585e0f 2026-02-04 21:39:32,253 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/8989d079-e871-41ee-8f75-d1e5562241ac 2026-02-04 21:39:35,513 - 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') 2026-02-04 21:39:35,514 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-72012392 failed! 2026-02-04 21:39:35,566 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-44123072:ctest-vn1-76619908 is 207.93.219.1 and allocation pool is NOT set 2026-02-04 21:39:37,628 - 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.14 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.534 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.534/1.835/3.136/1.301 ms') 2026-02-04 21:39:37,628 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-72012392 passed 2026-02-04 21:39:37,786 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-04 21:39:37,786 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-72012392, IP 207.93.219.3, Port 22 2026-02-04 21:39:37,942 - DEBUG - VM ctest-vn1-vm1-72012392 is ready for SSH connections 2026-02-04 21:39:37,942 - INFO - Waiting for VM ctest-vn2-vm1-12240040 to be up.. 2026-02-04 21:39:38,024 - DEBUG - VM is in ACTIVE state now 2026-02-04 21:39:38,024 - INFO - VM name : ctest-vn2-vm1-12240040 2026-02-04 21:39:38,095 - DEBUG - VM ctest-vn2-vm1-12240040 ID is fb5cd992-a220-46ed-8765-671cb59c7ef0 2026-02-04 21:39:38,095 - DEBUG - VM ctest-vn2-vm1-12240040 launched on Node cn-jenkins-deploy-platform-ansible-os-5210-1 2026-02-04 21:39:38,172 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/fb5cd992-a220-46ed-8765-671cb59c7ef0 2026-02-04 21:39:38,180 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/c18c63d6-3ce3-4f84-9033-fcdc67c7b01c 2026-02-04 21:39:39,409 - 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.544 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.544/1.866/3.189/1.322 ms') 2026-02-04 21:39:39,409 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-12240040 passed 2026-02-04 21:39:39,519 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-04 21:39:39,520 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-12240040, IP 58.71.23.67, Port 22 2026-02-04 21:39:39,678 - DEBUG - VM ctest-vn2-vm1-12240040 is ready for SSH connections 2026-02-04 21:39:39,678 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-04 21:39:39,678 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 58.71.23.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-02-04 21:39:39,678 - DEBUG - ping -s 56 -c 3 -W 1 58.71.23.67 2026-02-04 21:39:44,652 - DEBUG - PING 58.71.23.67 (58.71.23.67): 56 data bytes --- 58.71.23.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-02-04 21:39:44,652 - WARNING - Ping to IP 58.71.23.67 from VM ctest-vn1-vm1-72012392 failed 2026-02-04 21:39:44,652 - INFO - Adding interface with subnet_id 61645513-d730-41bf-8ac8-b63d7af65104, port_id None to router d36c7000-6ff6-43c4-8d59-6a68b64bf132 2026-02-04 21:39:44,883 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-04 21:39:44,883 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 58.71.23.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-02-04 21:39:44,883 - DEBUG - ping -s 56 -c 3 -W 1 58.71.23.67 2026-02-04 21:39:47,090 - DEBUG - PING 58.71.23.67 (58.71.23.67): 56 data bytes 64 bytes from 58.71.23.67: seq=0 ttl=63 time=2.963 ms 64 bytes from 58.71.23.67: seq=1 ttl=63 time=1.086 ms 64 bytes from 58.71.23.67: seq=2 ttl=63 time=1.235 ms --- 58.71.23.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.086/1.761/2.963 ms 2026-02-04 21:39:47,091 - INFO - Ping to IP 58.71.23.67 from VM ctest-vn1-vm1-72012392 passed 2026-02-04 21:39:47,091 - INFO - Deleting interface with subnet_id 87b83ef7-3f11-4951-9cde-0b2e7f086748, port_id None from router d36c7000-6ff6-43c4-8d59-6a68b64bf132 2026-02-04 21:39:47,206 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-04 21:39:47,206 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 58.71.23.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-02-04 21:39:47,206 - DEBUG - ping -s 56 -c 3 -W 1 58.71.23.67 2026-02-04 21:39:50,407 - DEBUG - PING 58.71.23.67 (58.71.23.67): 56 data bytes --- 58.71.23.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-02-04 21:39:50,407 - WARNING - Ping to IP 58.71.23.67 from VM ctest-vn1-vm1-72012392 failed 2026-02-04 21:39:50,407 - INFO - Adding interface with subnet_id 87b83ef7-3f11-4951-9cde-0b2e7f086748, port_id None to router d36c7000-6ff6-43c4-8d59-6a68b64bf132 2026-02-04 21:39:50,608 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-04 21:39:50,608 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 58.71.23.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-02-04 21:39:50,608 - DEBUG - ping -s 56 -c 3 -W 1 58.71.23.67 2026-02-04 21:39:53,813 - DEBUG - PING 58.71.23.67 (58.71.23.67): 56 data bytes 64 bytes from 58.71.23.67: seq=1 ttl=63 time=1.525 ms 64 bytes from 58.71.23.67: seq=2 ttl=63 time=0.780 ms --- 58.71.23.67 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 0.780/1.152/1.525 ms 2026-02-04 21:39:53,814 - WARNING - Ping to IP 58.71.23.67 from VM ctest-vn1-vm1-72012392 failed 2026-02-04 21:39:54,814 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 58.71.23.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-02-04 21:39:54,814 - DEBUG - ping -s 56 -c 3 -W 1 58.71.23.67 2026-02-04 21:39:57,039 - DEBUG - PING 58.71.23.67 (58.71.23.67): 56 data bytes 64 bytes from 58.71.23.67: seq=0 ttl=63 time=2.411 ms 64 bytes from 58.71.23.67: seq=1 ttl=63 time=0.883 ms 64 bytes from 58.71.23.67: seq=2 ttl=63 time=1.060 ms --- 58.71.23.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.883/1.451/2.411 ms 2026-02-04 21:39:57,039 - INFO - Ping to IP 58.71.23.67 from VM ctest-vn1-vm1-72012392 passed 2026-02-04 21:39:57,039 - INFO - Deleting interface with subnet_id 61645513-d730-41bf-8ac8-b63d7af65104, port_id None from router d36c7000-6ff6-43c4-8d59-6a68b64bf132 2026-02-04 21:39:57,149 - INFO - Deleting interface with subnet_id 87b83ef7-3f11-4951-9cde-0b2e7f086748, port_id None from router d36c7000-6ff6-43c4-8d59-6a68b64bf132 2026-02-04 21:39:57,309 - INFO - Deleting VM ctest-vn2-vm1-12240040 2026-02-04 21:39:57,376 - INFO - Deleting VM ctest-vn1-vm1-72012392 2026-02-04 21:39:57,447 - INFO - Deleting VN ctest-vn2-42839075 2026-02-04 21:39:57,481 - DEBUG - VN 08d9654e-a688-4c5c-a4fa-dcee74533235 still in use: Unable to complete operation on network 08d9654e-a688-4c5c-a4fa-dcee74533235. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-22cd98f4-2da8-4e2c-9108-987775d7440a'] 2026-02-04 21:39:57,481 - WARNING - Deleting VN ctest-vn2-42839075 failed..Will retry 2026-02-04 21:39:59,582 - DEBUG - Response for deleting network () 2026-02-04 21:39:59,582 - INFO - Deleting VN ctest-vn1-76619908 2026-02-04 21:39:59,670 - DEBUG - Response for deleting network () 2026-02-04 21:39:59,927 - DEBUG - No XMPP flaps were noticed during the test
2026-02-04 21:39:59,927 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:37]
2026-02-04 21:39:59,927 - INFO - -------------------------------------------------------------------------------- 2026-02-04 21:40:00,619 - INFO - Deleted project: ctest-TestRouters-44123072, ID : ca1ca86b-1d46-4369-9f3d-dc069b7f793c