2026-07-19 18:45:27,217 - INFO - Domain Default found not creating 2026-07-19 18:45:27,395 - INFO - Project ctest-TestRouters-19933978 not found, creating it 2026-07-19 18:45:27,893 - INFO - Created Project:ctest-TestRouters-19933978, ID : 442621ea-986d-4a65-a522-3bbf3c76f8a8 2026-07-19 18:45:29,456 - INFO - ================================================================================ 2026-07-19 18:45:29,457 - INFO - STARTING TEST : test_basic_router_behavior 2026-07-19 18:45:29,457 - 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-07-19 18:45:29,724 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.17': '0'}} with 2026-07-19 18:45:29,724 - INFO - Initial checks done. Running the testcase now 2026-07-19 18:45:29,724 - INFO - 2026-07-19 18:45:30,323 - DEBUG - Response for create_network : {'network': {'id': 'be8a4919-e782-45e4-a765-457d62818e69', 'name': 'ctest-vn1-58689378', 'tenant_id': '442621ea986d4a65a5223bbf3c76f8a8', 'project_id': '442621ea986d4a65a5223bbf3c76f8a8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-19933978', 'ctest-vn1-58689378'], 'port_security_enabled': True, 'description': ''}} 2026-07-19 18:45:30,493 - DEBUG - Response for create_subnet : {'subnet': {'id': '24b1de8a-d11d-408c-bd81-e820e57d7807', 'name': '', 'tenant_id': '442621ea986d4a65a5223bbf3c76f8a8', 'network_id': 'be8a4919-e782-45e4-a765-457d62818e69', 'ip_version': 4, 'cidr': '76.161.96.128/26', 'allocation_pools': [{'start': '76.161.96.130', 'end': '76.161.96.190'}], 'gateway_ip': '76.161.96.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '76.161.96.130', 'tags': [], 'project_id': '442621ea986d4a65a5223bbf3c76f8a8'}} 2026-07-19 18:45:30,510 - INFO - Created VN ctest-vn1-58689378 2026-07-19 18:45:30,563 - DEBUG - VN ctest-vn1-58689378 UUID is be8a4919-e782-45e4-a765-457d62818e69 2026-07-19 18:45:30,916 - DEBUG - Response for create_network : {'network': {'id': '9c85c6dc-e445-464b-9eea-11c049309842', 'name': 'ctest-vn2-85378168', 'tenant_id': '442621ea986d4a65a5223bbf3c76f8a8', 'project_id': '442621ea986d4a65a5223bbf3c76f8a8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-19933978', 'ctest-vn2-85378168'], 'port_security_enabled': True, 'description': ''}} 2026-07-19 18:45:31,129 - DEBUG - Response for create_subnet : {'subnet': {'id': '2cfe0e09-aed6-4991-87e2-449cb97becd3', 'name': '', 'tenant_id': '442621ea986d4a65a5223bbf3c76f8a8', 'network_id': '9c85c6dc-e445-464b-9eea-11c049309842', 'ip_version': 4, 'cidr': '211.14.76.64/26', 'allocation_pools': [{'start': '211.14.76.66', 'end': '211.14.76.126'}], 'gateway_ip': '211.14.76.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '211.14.76.66', 'tags': [], 'project_id': '442621ea986d4a65a5223bbf3c76f8a8'}} 2026-07-19 18:45:31,147 - INFO - Created VN ctest-vn2-85378168 2026-07-19 18:45:31,200 - DEBUG - VN ctest-vn2-85378168 UUID is 9c85c6dc-e445-464b-9eea-11c049309842 2026-07-19 18:45:31,675 - DEBUG - Services list from nova: [, , ] 2026-07-19 18:45:32,056 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6109-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6109-1) 2026-07-19 18:45:32,503 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6109-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6109-1) 2026-07-19 18:45:32,586 - INFO - Adding interface with subnet_id 24b1de8a-d11d-408c-bd81-e820e57d7807, port_id None to router aec8c8be-f7c7-4a9d-8f08-36e82f7dfbc9 2026-07-19 18:45:32,861 - INFO - Waiting for VM ctest-vn1-vm1-68870729 to be up.. 2026-07-19 18:45:32,920 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-19 18:45:38,003 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-19 18:45:43,076 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-19 18:45:48,150 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-19 18:45:53,227 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-19 18:45:58,305 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-19 18:46:03,381 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-07-19 18:46:08,463 - DEBUG - VM is in ACTIVE state now 2026-07-19 18:46:08,463 - INFO - VM name : ctest-vn1-vm1-68870729 2026-07-19 18:46:08,536 - DEBUG - VM ctest-vn1-vm1-68870729 ID is 4c24fa5b-7e0e-49f5-996b-8c655e1c027f 2026-07-19 18:46:08,557 - DEBUG - VM ctest-vn1-vm1-68870729 launched on Node cn-jenkins-deploy-platform-ansible-os-6109-1 2026-07-19 18:46:08,637 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/4c24fa5b-7e0e-49f5-996b-8c655e1c027f 2026-07-19 18:46:08,972 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/4c24fa5b-7e0e-49f5-996b-8c655e1c027f 2026-07-19 18:46:09,004 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/0ae7a90f-8b59-49d9-a697-0e2cfa9be40a 2026-07-19 18:46:12,278 - 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 1021ms') 2026-07-19 18:46:12,278 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-68870729 failed! 2026-07-19 18:46:12,336 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-19933978:ctest-vn1-58689378 is 76.161.96.129 and allocation pool is NOT set 2026-07-19 18:46:14,395 - 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=12.4 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.67 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.665/7.018/12.371/5.353 ms') 2026-07-19 18:46:14,395 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-68870729 passed 2026-07-19 18:46:14,543 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-19 18:46:14,544 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-68870729, IP 76.161.96.131, Port 22 2026-07-19 18:46:14,609 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-07-19 18:46:14,683 - DEBUG - VM ctest-vn1-vm1-68870729 is NOT ready for SSH connections, VM status: ACTIVE 2026-07-19 18:46:19,684 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-19 18:46:19,684 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-68870729, IP 76.161.96.131, Port 22 2026-07-19 18:46:19,843 - DEBUG - VM ctest-vn1-vm1-68870729 is ready for SSH connections 2026-07-19 18:46:19,843 - INFO - Waiting for VM ctest-vn2-vm1-74374532 to be up.. 2026-07-19 18:46:19,919 - DEBUG - VM is in ACTIVE state now 2026-07-19 18:46:19,919 - INFO - VM name : ctest-vn2-vm1-74374532 2026-07-19 18:46:19,997 - DEBUG - VM ctest-vn2-vm1-74374532 ID is 9db04885-1c97-47d7-b97f-aeebeb778a04 2026-07-19 18:46:19,997 - DEBUG - VM ctest-vn2-vm1-74374532 launched on Node cn-jenkins-deploy-platform-ansible-os-6109-1 2026-07-19 18:46:20,070 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/9db04885-1c97-47d7-b97f-aeebeb778a04 2026-07-19 18:46:20,079 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/98cb1891-ea23-4089-b12d-1c93f08ecd73 2026-07-19 18:46:21,323 - 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.36 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.449 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.449/1.905/3.361/1.456 ms') 2026-07-19 18:46:21,323 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-74374532 passed 2026-07-19 18:46:21,476 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-19 18:46:21,476 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-74374532, IP 211.14.76.67, Port 22 2026-07-19 18:46:21,635 - DEBUG - VM ctest-vn2-vm1-74374532 is ready for SSH connections 2026-07-19 18:46:21,635 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-19 18:46:21,635 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 211.14.76.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-07-19 18:46:21,636 - DEBUG - ping -s 56 -c 3 -W 1 211.14.76.67 2026-07-19 18:46:26,651 - DEBUG - PING 211.14.76.67 (211.14.76.67): 56 data bytes --- 211.14.76.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-07-19 18:46:26,651 - WARNING - Ping to IP 211.14.76.67 from VM ctest-vn1-vm1-68870729 failed 2026-07-19 18:46:26,651 - INFO - Adding interface with subnet_id 2cfe0e09-aed6-4991-87e2-449cb97becd3, port_id None to router aec8c8be-f7c7-4a9d-8f08-36e82f7dfbc9 2026-07-19 18:46:26,953 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-19 18:46:26,954 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 211.14.76.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-07-19 18:46:26,954 - DEBUG - ping -s 56 -c 3 -W 1 211.14.76.67 2026-07-19 18:46:30,098 - DEBUG - PING 211.14.76.67 (211.14.76.67): 56 data bytes 64 bytes from 211.14.76.67: seq=1 ttl=63 time=3.305 ms 64 bytes from 211.14.76.67: seq=2 ttl=63 time=1.233 ms --- 211.14.76.67 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.233/2.269/3.305 ms 2026-07-19 18:46:30,098 - WARNING - Ping to IP 211.14.76.67 from VM ctest-vn1-vm1-68870729 failed 2026-07-19 18:46:31,098 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 211.14.76.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-07-19 18:46:31,099 - DEBUG - ping -s 56 -c 3 -W 1 211.14.76.67 2026-07-19 18:46:33,215 - DEBUG - PING 211.14.76.67 (211.14.76.67): 56 data bytes 64 bytes from 211.14.76.67: seq=0 ttl=63 time=2.351 ms 64 bytes from 211.14.76.67: seq=1 ttl=63 time=1.082 ms 64 bytes from 211.14.76.67: seq=2 ttl=63 time=1.033 ms --- 211.14.76.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.033/1.488/2.351 ms 2026-07-19 18:46:33,215 - INFO - Ping to IP 211.14.76.67 from VM ctest-vn1-vm1-68870729 passed 2026-07-19 18:46:33,215 - INFO - Deleting interface with subnet_id 24b1de8a-d11d-408c-bd81-e820e57d7807, port_id None from router aec8c8be-f7c7-4a9d-8f08-36e82f7dfbc9 2026-07-19 18:46:33,364 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-19 18:46:33,364 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 211.14.76.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-07-19 18:46:33,365 - DEBUG - ping -s 56 -c 3 -W 1 211.14.76.67 2026-07-19 18:46:36,482 - DEBUG - PING 211.14.76.67 (211.14.76.67): 56 data bytes --- 211.14.76.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-07-19 18:46:36,482 - WARNING - Ping to IP 211.14.76.67 from VM ctest-vn1-vm1-68870729 failed 2026-07-19 18:46:36,482 - INFO - Adding interface with subnet_id 24b1de8a-d11d-408c-bd81-e820e57d7807, port_id None to router aec8c8be-f7c7-4a9d-8f08-36e82f7dfbc9 2026-07-19 18:46:36,694 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-07-19 18:46:36,694 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 211.14.76.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-07-19 18:46:36,694 - DEBUG - ping -s 56 -c 3 -W 1 211.14.76.67 2026-07-19 18:46:38,820 - DEBUG - PING 211.14.76.67 (211.14.76.67): 56 data bytes 64 bytes from 211.14.76.67: seq=0 ttl=63 time=1.972 ms 64 bytes from 211.14.76.67: seq=1 ttl=63 time=1.049 ms 64 bytes from 211.14.76.67: seq=2 ttl=63 time=1.043 ms --- 211.14.76.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.043/1.354/1.972 ms 2026-07-19 18:46:38,820 - INFO - Ping to IP 211.14.76.67 from VM ctest-vn1-vm1-68870729 passed 2026-07-19 18:46:38,820 - INFO - Deleting interface with subnet_id 2cfe0e09-aed6-4991-87e2-449cb97becd3, port_id None from router aec8c8be-f7c7-4a9d-8f08-36e82f7dfbc9 2026-07-19 18:46:38,944 - INFO - Deleting interface with subnet_id 24b1de8a-d11d-408c-bd81-e820e57d7807, port_id None from router aec8c8be-f7c7-4a9d-8f08-36e82f7dfbc9 2026-07-19 18:46:39,174 - INFO - Deleting VM ctest-vn2-vm1-74374532 2026-07-19 18:46:39,253 - INFO - Deleting VM ctest-vn1-vm1-68870729 2026-07-19 18:46:39,330 - INFO - Deleting VN ctest-vn2-85378168 2026-07-19 18:46:39,361 - DEBUG - VN 9c85c6dc-e445-464b-9eea-11c049309842 still in use: Unable to complete operation on network 9c85c6dc-e445-464b-9eea-11c049309842. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-44341257-ef44-4c34-adfd-e069b36aafee'] 2026-07-19 18:46:39,361 - WARNING - Deleting VN ctest-vn2-85378168 failed..Will retry 2026-07-19 18:46:41,503 - DEBUG - Response for deleting network () 2026-07-19 18:46:41,503 - INFO - Deleting VN ctest-vn1-58689378 2026-07-19 18:46:41,603 - DEBUG - Response for deleting network () 2026-07-19 18:46:41,862 - DEBUG - No XMPP flaps were noticed during the test
2026-07-19 18:46:41,862 - INFO - END TEST : test_basic_router_behavior : PASSED[0:01:12]
2026-07-19 18:46:41,862 - INFO - -------------------------------------------------------------------------------- 2026-07-19 18:46:42,576 - INFO - Deleted project: ctest-TestRouters-19933978, ID : 442621ea-986d-4a65-a522-3bbf3c76f8a8