2026-02-09 13:34:02,450 - INFO - Domain Default found not creating 2026-02-09 13:34:02,674 - INFO - Project ctest-TestRouters-46332699 not found, creating it 2026-02-09 13:34:03,228 - INFO - Created Project:ctest-TestRouters-46332699, ID : dd396e73-ff5d-42a6-b05b-f5b0765a39d0 2026-02-09 13:34:05,109 - INFO - ================================================================================ 2026-02-09 13:34:05,109 - INFO - STARTING TEST : test_basic_router_behavior 2026-02-09 13:34:05,110 - 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-09 13:34:05,394 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.14': '0'}} with 2026-02-09 13:34:05,394 - INFO - Initial checks done. Running the testcase now 2026-02-09 13:34:05,394 - INFO - 2026-02-09 13:34:06,082 - DEBUG - Response for create_network : {'network': {'id': 'cd98cc5b-c4c0-4c96-bd43-63d76a484a12', 'name': 'ctest-vn1-82381422', 'tenant_id': 'dd396e73ff5d42a6b05bf5b0765a39d0', 'project_id': 'dd396e73ff5d42a6b05bf5b0765a39d0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-46332699', 'ctest-vn1-82381422'], 'port_security_enabled': True, 'description': ''}} 2026-02-09 13:34:06,363 - DEBUG - Response for create_subnet : {'subnet': {'id': '5a987d7e-908d-4a5d-a4c7-ae1a381284c8', 'name': '', 'tenant_id': 'dd396e73ff5d42a6b05bf5b0765a39d0', 'network_id': 'cd98cc5b-c4c0-4c96-bd43-63d76a484a12', 'ip_version': 4, 'cidr': '220.124.239.128/26', 'allocation_pools': [{'start': '220.124.239.130', 'end': '220.124.239.190'}], 'gateway_ip': '220.124.239.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '220.124.239.130', 'tags': [], 'project_id': 'dd396e73ff5d42a6b05bf5b0765a39d0'}} 2026-02-09 13:34:06,389 - INFO - Created VN ctest-vn1-82381422 2026-02-09 13:34:06,447 - DEBUG - VN ctest-vn1-82381422 UUID is cd98cc5b-c4c0-4c96-bd43-63d76a484a12 2026-02-09 13:34:06,860 - DEBUG - Response for create_network : {'network': {'id': '6cc70937-19cb-4b4a-8db7-4365297ce17a', 'name': 'ctest-vn2-96658074', 'tenant_id': 'dd396e73ff5d42a6b05bf5b0765a39d0', 'project_id': 'dd396e73ff5d42a6b05bf5b0765a39d0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-46332699', 'ctest-vn2-96658074'], 'port_security_enabled': True, 'description': ''}} 2026-02-09 13:34:07,075 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a4aa034c-1ad2-48c1-a835-f3ea8681b61b', 'name': '', 'tenant_id': 'dd396e73ff5d42a6b05bf5b0765a39d0', 'network_id': '6cc70937-19cb-4b4a-8db7-4365297ce17a', 'ip_version': 4, 'cidr': '163.123.201.64/26', 'allocation_pools': [{'start': '163.123.201.66', 'end': '163.123.201.126'}], 'gateway_ip': '163.123.201.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '163.123.201.66', 'tags': [], 'project_id': 'dd396e73ff5d42a6b05bf5b0765a39d0'}} 2026-02-09 13:34:07,106 - INFO - Created VN ctest-vn2-96658074 2026-02-09 13:34:07,163 - DEBUG - VN ctest-vn2-96658074 UUID is 6cc70937-19cb-4b4a-8db7-4365297ce17a 2026-02-09 13:34:07,363 - DEBUG - Services list from nova: [, , ] 2026-02-09 13:34:08,749 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5245-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5245-1) 2026-02-09 13:34:10,231 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5245-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5245-1) 2026-02-09 13:34:10,330 - INFO - Adding interface with subnet_id 5a987d7e-908d-4a5d-a4c7-ae1a381284c8, port_id None to router 1042de6f-c199-4429-99fb-e95b9aaa8066 2026-02-09 13:34:10,804 - INFO - Waiting for VM ctest-vn1-vm1-06932535 to be up.. 2026-02-09 13:34:11,037 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-02-09 13:34:16,218 - DEBUG - VM is in ACTIVE state now 2026-02-09 13:34:16,218 - INFO - VM name : ctest-vn1-vm1-06932535 2026-02-09 13:34:16,369 - DEBUG - VM ctest-vn1-vm1-06932535 ID is ed3082be-0e41-4180-96b2-27c6f003a77b 2026-02-09 13:34:16,408 - DEBUG - VM ctest-vn1-vm1-06932535 launched on Node cn-jenkins-deploy-platform-ansible-os-5245-1 2026-02-09 13:34:16,542 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/ed3082be-0e41-4180-96b2-27c6f003a77b 2026-02-09 13:34:16,894 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/ed3082be-0e41-4180-96b2-27c6f003a77b 2026-02-09 13:34:16,958 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/232613fe-7ee4-43a4-9025-a2a2065616ee 2026-02-09 13:34:20,267 - 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 1003ms') 2026-02-09 13:34:20,267 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-06932535 failed! 2026-02-09 13:34:20,331 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-46332699:ctest-vn1-82381422 is 220.124.239.129 and allocation pool is NOT set 2026-02-09 13:34:24,419 - 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=2 ttl=63 time=9.80 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1024ms\r\nrtt min/avg/max/mdev = 9.799/9.799/9.799/0.000 ms') 2026-02-09 13:34:24,419 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-06932535 passed 2026-02-09 13:34:24,591 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-09 13:34:24,591 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-06932535, IP 220.124.239.131, Port 22 2026-02-09 13:34:24,773 - DEBUG - VM ctest-vn1-vm1-06932535 is ready for SSH connections 2026-02-09 13:34:24,774 - INFO - Waiting for VM ctest-vn2-vm1-05743407 to be up.. 2026-02-09 13:34:24,888 - DEBUG - VM is in ACTIVE state now 2026-02-09 13:34:24,888 - INFO - VM name : ctest-vn2-vm1-05743407 2026-02-09 13:34:25,039 - DEBUG - VM ctest-vn2-vm1-05743407 ID is 99ca68e8-219b-4398-a408-323004d6bb9d 2026-02-09 13:34:25,039 - DEBUG - VM ctest-vn2-vm1-05743407 launched on Node cn-jenkins-deploy-platform-ansible-os-5245-1 2026-02-09 13:34:25,175 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/99ca68e8-219b-4398-a408-323004d6bb9d 2026-02-09 13:34:25,189 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/f7e9739d-f0c3-40aa-b513-24473f5a0311 2026-02-09 13:34:26,476 - 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=6.42 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.51 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 = 1.508/3.962/6.416/2.454 ms') 2026-02-09 13:34:26,477 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-05743407 passed 2026-02-09 13:34:26,647 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-09 13:34:26,647 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-05743407, IP 163.123.201.67, Port 22 2026-02-09 13:34:26,831 - DEBUG - VM ctest-vn2-vm1-05743407 is ready for SSH connections 2026-02-09 13:34:26,831 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-09 13:34:26,831 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 163.123.201.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2026-02-09 13:34:26,831 - DEBUG - ping -s 56 -c 3 -W 1 163.123.201.67 2026-02-09 13:34:32,299 - DEBUG - PING 163.123.201.67 (163.123.201.67): 56 data bytes --- 163.123.201.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-02-09 13:34:32,300 - WARNING - Ping to IP 163.123.201.67 from VM ctest-vn1-vm1-06932535 failed 2026-02-09 13:34:32,300 - INFO - Adding interface with subnet_id a4aa034c-1ad2-48c1-a835-f3ea8681b61b, port_id None to router 1042de6f-c199-4429-99fb-e95b9aaa8066 2026-02-09 13:34:32,775 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-09 13:34:32,775 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 163.123.201.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2026-02-09 13:34:32,775 - DEBUG - ping -s 56 -c 3 -W 1 163.123.201.67 2026-02-09 13:34:35,131 - DEBUG - PING 163.123.201.67 (163.123.201.67): 56 data bytes 64 bytes from 163.123.201.67: seq=0 ttl=63 time=5.171 ms 64 bytes from 163.123.201.67: seq=1 ttl=63 time=2.168 ms 64 bytes from 163.123.201.67: seq=2 ttl=63 time=1.485 ms --- 163.123.201.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.485/2.941/5.171 ms 2026-02-09 13:34:35,131 - INFO - Ping to IP 163.123.201.67 from VM ctest-vn1-vm1-06932535 passed 2026-02-09 13:34:35,132 - INFO - Deleting interface with subnet_id 5a987d7e-908d-4a5d-a4c7-ae1a381284c8, port_id None from router 1042de6f-c199-4429-99fb-e95b9aaa8066 2026-02-09 13:34:35,329 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-09 13:34:35,329 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 163.123.201.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2026-02-09 13:34:35,329 - DEBUG - ping -s 56 -c 3 -W 1 163.123.201.67 2026-02-09 13:34:38,633 - DEBUG - PING 163.123.201.67 (163.123.201.67): 56 data bytes --- 163.123.201.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-02-09 13:34:38,633 - WARNING - Ping to IP 163.123.201.67 from VM ctest-vn1-vm1-06932535 failed 2026-02-09 13:34:38,633 - INFO - Adding interface with subnet_id 5a987d7e-908d-4a5d-a4c7-ae1a381284c8, port_id None to router 1042de6f-c199-4429-99fb-e95b9aaa8066 2026-02-09 13:34:39,004 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-02-09 13:34:39,004 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 163.123.201.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2026-02-09 13:34:39,004 - DEBUG - ping -s 56 -c 3 -W 1 163.123.201.67 2026-02-09 13:34:41,327 - DEBUG - PING 163.123.201.67 (163.123.201.67): 56 data bytes 64 bytes from 163.123.201.67: seq=0 ttl=63 time=2.086 ms 64 bytes from 163.123.201.67: seq=1 ttl=63 time=1.303 ms 64 bytes from 163.123.201.67: seq=2 ttl=63 time=1.414 ms --- 163.123.201.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.303/1.601/2.086 ms 2026-02-09 13:34:41,327 - INFO - Ping to IP 163.123.201.67 from VM ctest-vn1-vm1-06932535 passed 2026-02-09 13:34:41,327 - INFO - Deleting interface with subnet_id a4aa034c-1ad2-48c1-a835-f3ea8681b61b, port_id None from router 1042de6f-c199-4429-99fb-e95b9aaa8066 2026-02-09 13:34:41,542 - INFO - Deleting interface with subnet_id 5a987d7e-908d-4a5d-a4c7-ae1a381284c8, port_id None from router 1042de6f-c199-4429-99fb-e95b9aaa8066 2026-02-09 13:34:41,847 - INFO - Deleting VM ctest-vn2-vm1-05743407 2026-02-09 13:34:41,985 - INFO - Deleting VM ctest-vn1-vm1-06932535 2026-02-09 13:34:42,087 - INFO - Deleting VN ctest-vn2-96658074 2026-02-09 13:34:42,140 - DEBUG - VN 6cc70937-19cb-4b4a-8db7-4365297ce17a still in use: Unable to complete operation on network 6cc70937-19cb-4b4a-8db7-4365297ce17a. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9bc855f7-8b0d-4e27-9842-83517750a808'] 2026-02-09 13:34:42,140 - WARNING - Deleting VN ctest-vn2-96658074 failed..Will retry 2026-02-09 13:34:44,320 - DEBUG - Response for deleting network () 2026-02-09 13:34:44,320 - INFO - Deleting VN ctest-vn1-82381422 2026-02-09 13:34:44,478 - DEBUG - Response for deleting network () 2026-02-09 13:34:44,759 - DEBUG - No XMPP flaps were noticed during the test
2026-02-09 13:34:44,759 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:39]
2026-02-09 13:34:44,759 - INFO - -------------------------------------------------------------------------------- 2026-02-09 13:34:45,565 - INFO - Deleted project: ctest-TestRouters-46332699, ID : dd396e73-ff5d-42a6-b05b-f5b0765a39d0