2026-08-17 05:30:21,329 - INFO - Domain Default found not creating 2026-08-17 05:30:21,498 - INFO - Project ctest-TestRouters-78990058 not found, creating it 2026-08-17 05:30:22,003 - INFO - Created Project:ctest-TestRouters-78990058, ID : 55a76d5f-3d4c-424c-a392-5036ee883a6f 2026-08-17 05:30:23,763 - INFO - ================================================================================ 2026-08-17 05:30:23,763 - INFO - STARTING TEST : test_basic_router_behavior 2026-08-17 05:30:23,763 - 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-08-17 05:30:24,041 - DEBUG - Nothing to compare xmpp stats {'10.0.0.161': {'10.20.0.18': '0'}} with 2026-08-17 05:30:24,041 - INFO - Initial checks done. Running the testcase now 2026-08-17 05:30:24,041 - INFO - 2026-08-17 05:30:24,717 - DEBUG - Response for create_network : {'network': {'id': '886869cc-b517-454b-a177-d2fcf69753ec', 'name': 'ctest-vn1-06814987', 'tenant_id': '55a76d5f3d4c424ca3925036ee883a6f', 'project_id': '55a76d5f3d4c424ca3925036ee883a6f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-78990058', 'ctest-vn1-06814987'], 'port_security_enabled': True, 'description': ''}} 2026-08-17 05:30:24,907 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b29ff53c-3ad0-49a7-abd5-a71f173f6df6', 'name': '', 'tenant_id': '55a76d5f3d4c424ca3925036ee883a6f', 'network_id': '886869cc-b517-454b-a177-d2fcf69753ec', 'ip_version': 4, 'cidr': '188.193.179.192/26', 'allocation_pools': [{'start': '188.193.179.194', 'end': '188.193.179.254'}], 'gateway_ip': '188.193.179.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '188.193.179.194', 'tags': [], 'project_id': '55a76d5f3d4c424ca3925036ee883a6f'}} 2026-08-17 05:30:24,928 - INFO - Created VN ctest-vn1-06814987 2026-08-17 05:30:24,984 - DEBUG - VN ctest-vn1-06814987 UUID is 886869cc-b517-454b-a177-d2fcf69753ec 2026-08-17 05:30:25,337 - DEBUG - Response for create_network : {'network': {'id': 'd969f984-ff04-40b5-96ec-f3f4679beca8', 'name': 'ctest-vn2-27212167', 'tenant_id': '55a76d5f3d4c424ca3925036ee883a6f', 'project_id': '55a76d5f3d4c424ca3925036ee883a6f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-78990058', 'ctest-vn2-27212167'], 'port_security_enabled': True, 'description': ''}} 2026-08-17 05:30:25,562 - DEBUG - Response for create_subnet : {'subnet': {'id': '6d252a22-8598-4e7b-80b2-38423d605778', 'name': '', 'tenant_id': '55a76d5f3d4c424ca3925036ee883a6f', 'network_id': 'd969f984-ff04-40b5-96ec-f3f4679beca8', 'ip_version': 4, 'cidr': '101.23.137.0/26', 'allocation_pools': [{'start': '101.23.137.2', 'end': '101.23.137.62'}], 'gateway_ip': '101.23.137.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '101.23.137.2', 'tags': [], 'project_id': '55a76d5f3d4c424ca3925036ee883a6f'}} 2026-08-17 05:30:25,579 - INFO - Created VN ctest-vn2-27212167 2026-08-17 05:30:25,632 - DEBUG - VN ctest-vn2-27212167 UUID is d969f984-ff04-40b5-96ec-f3f4679beca8 2026-08-17 05:30:25,890 - DEBUG - Services list from nova: [, , ] 2026-08-17 05:30:26,313 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6169-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6169-1) 2026-08-17 05:30:26,954 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6169-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6169-1) 2026-08-17 05:30:27,029 - INFO - Adding interface with subnet_id b29ff53c-3ad0-49a7-abd5-a71f173f6df6, port_id None to router fd285a75-5834-4b4a-a6d4-7a9b89430ed1 2026-08-17 05:30:27,346 - INFO - Waiting for VM ctest-vn1-vm1-19401298 to be up.. 2026-08-17 05:30:27,407 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 05:30:32,495 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 05:30:37,595 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 05:30:42,704 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 05:30:47,785 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 05:30:52,867 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 05:30:57,974 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-17 05:31:03,067 - DEBUG - VM is in ACTIVE state now 2026-08-17 05:31:03,067 - INFO - VM name : ctest-vn1-vm1-19401298 2026-08-17 05:31:03,169 - DEBUG - VM ctest-vn1-vm1-19401298 ID is 7cc5dfb2-3da7-4f1f-9fb2-c832ac3e2eda 2026-08-17 05:31:03,192 - DEBUG - VM ctest-vn1-vm1-19401298 launched on Node cn-jenkins-deploy-platform-ansible-os-6169-1 2026-08-17 05:31:03,284 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/7cc5dfb2-3da7-4f1f-9fb2-c832ac3e2eda 2026-08-17 05:31:03,654 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/7cc5dfb2-3da7-4f1f-9fb2-c832ac3e2eda 2026-08-17 05:31:03,685 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine-interface/3c2a4a94-bb2f-4723-8aa4-4639fc41874f 2026-08-17 05:31:06,948 - 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 1020ms') 2026-08-17 05:31:06,948 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-19401298 failed! 2026-08-17 05:31:07,004 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-78990058:ctest-vn1-06814987 is 188.193.179.193 and allocation pool is NOT set 2026-08-17 05:31:09,076 - 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=15.5 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.635 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.635/8.085/15.535/7.450 ms') 2026-08-17 05:31:09,076 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-19401298 passed 2026-08-17 05:31:09,236 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 05:31:09,236 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-19401298, IP 188.193.179.195, Port 22 2026-08-17 05:31:09,302 - 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-08-17 05:31:09,396 - DEBUG - VM ctest-vn1-vm1-19401298 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-17 05:31:14,397 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 05:31:14,397 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-19401298, IP 188.193.179.195, Port 22 2026-08-17 05:31:14,570 - DEBUG - VM ctest-vn1-vm1-19401298 is ready for SSH connections 2026-08-17 05:31:14,570 - INFO - Waiting for VM ctest-vn2-vm1-85010524 to be up.. 2026-08-17 05:31:14,651 - DEBUG - VM is in ACTIVE state now 2026-08-17 05:31:14,651 - INFO - VM name : ctest-vn2-vm1-85010524 2026-08-17 05:31:14,731 - DEBUG - VM ctest-vn2-vm1-85010524 ID is d56b9d3c-e445-473a-8491-31d549e79d92 2026-08-17 05:31:14,731 - DEBUG - VM ctest-vn2-vm1-85010524 launched on Node cn-jenkins-deploy-platform-ansible-os-6169-1 2026-08-17 05:31:14,818 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/d56b9d3c-e445-473a-8491-31d549e79d92 2026-08-17 05:31:14,829 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine-interface/c1b7388f-27e1-4d1c-8436-da3d41a22293 2026-08-17 05:31:16,093 - 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=8.76 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.596 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.596/4.678/8.760/4.082 ms') 2026-08-17 05:31:16,093 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-85010524 passed 2026-08-17 05:31:16,252 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 05:31:16,252 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-85010524, IP 101.23.137.3, Port 22 2026-08-17 05:31:16,418 - DEBUG - VM ctest-vn2-vm1-85010524 is ready for SSH connections 2026-08-17 05:31:16,418 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 05:31:16,418 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 101.23.137.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.161, gateway password: c0ntrail123 2026-08-17 05:31:16,418 - DEBUG - ping -s 56 -c 3 -W 1 101.23.137.3 2026-08-17 05:31:21,486 - DEBUG - PING 101.23.137.3 (101.23.137.3): 56 data bytes --- 101.23.137.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-08-17 05:31:21,486 - WARNING - Ping to IP 101.23.137.3 from VM ctest-vn1-vm1-19401298 failed 2026-08-17 05:31:21,487 - INFO - Adding interface with subnet_id 6d252a22-8598-4e7b-80b2-38423d605778, port_id None to router fd285a75-5834-4b4a-a6d4-7a9b89430ed1 2026-08-17 05:31:21,773 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 05:31:21,773 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 101.23.137.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.161, gateway password: c0ntrail123 2026-08-17 05:31:21,773 - DEBUG - ping -s 56 -c 3 -W 1 101.23.137.3 2026-08-17 05:31:24,899 - DEBUG - PING 101.23.137.3 (101.23.137.3): 56 data bytes 64 bytes from 101.23.137.3: seq=1 ttl=63 time=3.302 ms 64 bytes from 101.23.137.3: seq=2 ttl=63 time=1.015 ms --- 101.23.137.3 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.015/2.158/3.302 ms 2026-08-17 05:31:24,900 - WARNING - Ping to IP 101.23.137.3 from VM ctest-vn1-vm1-19401298 failed 2026-08-17 05:31:25,901 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 101.23.137.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.161, gateway password: c0ntrail123 2026-08-17 05:31:25,901 - DEBUG - ping -s 56 -c 3 -W 1 101.23.137.3 2026-08-17 05:31:28,048 - DEBUG - PING 101.23.137.3 (101.23.137.3): 56 data bytes 64 bytes from 101.23.137.3: seq=0 ttl=63 time=2.802 ms 64 bytes from 101.23.137.3: seq=1 ttl=63 time=1.254 ms 64 bytes from 101.23.137.3: seq=2 ttl=63 time=1.065 ms --- 101.23.137.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.065/1.707/2.802 ms 2026-08-17 05:31:28,048 - INFO - Ping to IP 101.23.137.3 from VM ctest-vn1-vm1-19401298 passed 2026-08-17 05:31:28,048 - INFO - Deleting interface with subnet_id b29ff53c-3ad0-49a7-abd5-a71f173f6df6, port_id None from router fd285a75-5834-4b4a-a6d4-7a9b89430ed1 2026-08-17 05:31:28,199 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 05:31:28,199 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 101.23.137.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.161, gateway password: c0ntrail123 2026-08-17 05:31:28,199 - DEBUG - ping -s 56 -c 3 -W 1 101.23.137.3 2026-08-17 05:31:31,325 - DEBUG - PING 101.23.137.3 (101.23.137.3): 56 data bytes --- 101.23.137.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-08-17 05:31:31,325 - WARNING - Ping to IP 101.23.137.3 from VM ctest-vn1-vm1-19401298 failed 2026-08-17 05:31:31,325 - INFO - Adding interface with subnet_id b29ff53c-3ad0-49a7-abd5-a71f173f6df6, port_id None to router fd285a75-5834-4b4a-a6d4-7a9b89430ed1 2026-08-17 05:31:31,558 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-17 05:31:31,559 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 101.23.137.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.161, gateway password: c0ntrail123 2026-08-17 05:31:31,559 - DEBUG - ping -s 56 -c 3 -W 1 101.23.137.3 2026-08-17 05:31:33,692 - DEBUG - PING 101.23.137.3 (101.23.137.3): 56 data bytes 64 bytes from 101.23.137.3: seq=0 ttl=63 time=1.732 ms 64 bytes from 101.23.137.3: seq=1 ttl=63 time=1.199 ms 64 bytes from 101.23.137.3: seq=2 ttl=63 time=1.340 ms --- 101.23.137.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.199/1.423/1.732 ms 2026-08-17 05:31:33,692 - INFO - Ping to IP 101.23.137.3 from VM ctest-vn1-vm1-19401298 passed 2026-08-17 05:31:33,692 - INFO - Deleting interface with subnet_id 6d252a22-8598-4e7b-80b2-38423d605778, port_id None from router fd285a75-5834-4b4a-a6d4-7a9b89430ed1 2026-08-17 05:31:33,847 - INFO - Deleting interface with subnet_id b29ff53c-3ad0-49a7-abd5-a71f173f6df6, port_id None from router fd285a75-5834-4b4a-a6d4-7a9b89430ed1 2026-08-17 05:31:34,115 - INFO - Deleting VM ctest-vn2-vm1-85010524 2026-08-17 05:31:34,228 - INFO - Deleting VM ctest-vn1-vm1-19401298 2026-08-17 05:31:34,321 - INFO - Deleting VN ctest-vn2-27212167 2026-08-17 05:31:34,363 - DEBUG - VN d969f984-ff04-40b5-96ec-f3f4679beca8 still in use: Unable to complete operation on network d969f984-ff04-40b5-96ec-f3f4679beca8. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-3f26cdac-d637-4b16-906a-3444a714de69'] 2026-08-17 05:31:34,363 - WARNING - Deleting VN ctest-vn2-27212167 failed..Will retry 2026-08-17 05:31:36,546 - DEBUG - Response for deleting network () 2026-08-17 05:31:36,546 - INFO - Deleting VN ctest-vn1-06814987 2026-08-17 05:31:36,667 - DEBUG - Response for deleting network () 2026-08-17 05:31:36,941 - DEBUG - No XMPP flaps were noticed during the test
2026-08-17 05:31:36,941 - INFO - END TEST : test_basic_router_behavior : PASSED[0:01:13]
2026-08-17 05:31:36,941 - INFO - -------------------------------------------------------------------------------- 2026-08-17 05:31:37,711 - INFO - Deleted project: ctest-TestRouters-78990058, ID : 55a76d5f-3d4c-424c-a392-5036ee883a6f