2025-11-05 06:38:04,151 - INFO - Domain Default found not creating 2025-11-05 06:38:04,332 - INFO - Project ctest-TestRouters-37623558 not found, creating it 2025-11-05 06:38:04,859 - INFO - Created Project:ctest-TestRouters-37623558, ID : e3e28163-fc3b-42fe-abee-6e9b65c2cd88 2025-11-05 06:38:06,833 - INFO - ================================================================================ 2025-11-05 06:38:06,833 - INFO - STARTING TEST : test_basic_router_behavior 2025-11-05 06:38:06,833 - 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-11-05 06:38:07,111 - DEBUG - Nothing to compare xmpp stats {'10.0.0.130': {'10.20.0.17': '0'}} with 2025-11-05 06:38:07,111 - INFO - Initial checks done. Running the testcase now 2025-11-05 06:38:07,112 - INFO - 2025-11-05 06:38:07,822 - DEBUG - Response for create_network : {'network': {'id': '2ac98011-0990-4467-bf7f-bf93bf782909', 'name': 'ctest-vn1-24681400', 'tenant_id': 'e3e28163fc3b42feabee6e9b65c2cd88', 'project_id': 'e3e28163fc3b42feabee6e9b65c2cd88', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-37623558', 'ctest-vn1-24681400'], 'port_security_enabled': True, 'description': ''}} 2025-11-05 06:38:08,000 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ea72b07e-1c68-4dd1-a9bc-551d8711ef9f', 'name': '', 'tenant_id': 'e3e28163fc3b42feabee6e9b65c2cd88', 'network_id': '2ac98011-0990-4467-bf7f-bf93bf782909', 'ip_version': 4, 'cidr': '110.63.78.128/26', 'allocation_pools': [{'start': '110.63.78.130', 'end': '110.63.78.190'}], 'gateway_ip': '110.63.78.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '110.63.78.130', 'tags': [], 'project_id': 'e3e28163fc3b42feabee6e9b65c2cd88'}} 2025-11-05 06:38:08,024 - INFO - Created VN ctest-vn1-24681400 2025-11-05 06:38:08,081 - DEBUG - VN ctest-vn1-24681400 UUID is 2ac98011-0990-4467-bf7f-bf93bf782909 2025-11-05 06:38:08,448 - DEBUG - Response for create_network : {'network': {'id': 'd738ae1f-ead0-4883-9e5f-106910517c04', 'name': 'ctest-vn2-10927708', 'tenant_id': 'e3e28163fc3b42feabee6e9b65c2cd88', 'project_id': 'e3e28163fc3b42feabee6e9b65c2cd88', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-37623558', 'ctest-vn2-10927708'], 'port_security_enabled': True, 'description': ''}} 2025-11-05 06:38:08,621 - DEBUG - Response for create_subnet : {'subnet': {'id': '308a68b8-162c-4334-be91-09ed4df12dac', 'name': '', 'tenant_id': 'e3e28163fc3b42feabee6e9b65c2cd88', 'network_id': 'd738ae1f-ead0-4883-9e5f-106910517c04', 'ip_version': 4, 'cidr': '174.206.209.192/26', 'allocation_pools': [{'start': '174.206.209.194', 'end': '174.206.209.254'}], 'gateway_ip': '174.206.209.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '174.206.209.194', 'tags': [], 'project_id': 'e3e28163fc3b42feabee6e9b65c2cd88'}} 2025-11-05 06:38:08,642 - INFO - Created VN ctest-vn2-10927708 2025-11-05 06:38:08,698 - DEBUG - VN ctest-vn2-10927708 UUID is d738ae1f-ead0-4883-9e5f-106910517c04 2025-11-05 06:38:08,904 - DEBUG - Services list from nova: [, , ] 2025-11-05 06:38:10,091 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4627-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4627-1) 2025-11-05 06:38:11,466 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4627-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4627-1) 2025-11-05 06:38:11,547 - INFO - Adding interface with subnet_id ea72b07e-1c68-4dd1-a9bc-551d8711ef9f, port_id None to router d2e06d5f-1f83-4b03-9efd-554713d9a20c 2025-11-05 06:38:11,871 - INFO - Waiting for VM ctest-vn1-vm1-36175201 to be up.. 2025-11-05 06:38:11,992 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-05 06:38:17,099 - DEBUG - VM is in ACTIVE state now 2025-11-05 06:38:17,100 - INFO - VM name : ctest-vn1-vm1-36175201 2025-11-05 06:38:17,197 - DEBUG - VM ctest-vn1-vm1-36175201 ID is a590a97b-b1bc-4f2e-9fb8-3f5428bb61f1 2025-11-05 06:38:17,227 - DEBUG - VM ctest-vn1-vm1-36175201 launched on Node cn-jenkins-deploy-platform-ansible-os-4627-1 2025-11-05 06:38:17,324 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-machine/a590a97b-b1bc-4f2e-9fb8-3f5428bb61f1 2025-11-05 06:38:17,674 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-machine/a590a97b-b1bc-4f2e-9fb8-3f5428bb61f1 2025-11-05 06:38:17,714 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-machine-interface/d4dec1b3-a2b0-410e-b79b-8f53b93f449b 2025-11-05 06:38:20,982 - 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 1017ms') 2025-11-05 06:38:20,982 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-36175201 failed! 2025-11-05 06:38:21,041 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-37623558:ctest-vn1-24681400 is 110.63.78.129 and allocation pool is NOT set 2025-11-05 06:38:23,109 - 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=7.23 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.87 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.873/4.551/7.230/2.678 ms') 2025-11-05 06:38:23,110 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-36175201 passed 2025-11-05 06:38:23,270 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-05 06:38:23,270 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-36175201, IP 110.63.78.131, Port 22 2025-11-05 06:38:23,439 - DEBUG - VM ctest-vn1-vm1-36175201 is ready for SSH connections 2025-11-05 06:38:23,439 - INFO - Waiting for VM ctest-vn2-vm1-32616459 to be up.. 2025-11-05 06:38:23,527 - DEBUG - VM is in ACTIVE state now 2025-11-05 06:38:23,527 - INFO - VM name : ctest-vn2-vm1-32616459 2025-11-05 06:38:23,615 - DEBUG - VM ctest-vn2-vm1-32616459 ID is 9d511e75-c8ab-4d1e-a3ad-0be1bdc12b35 2025-11-05 06:38:23,616 - DEBUG - VM ctest-vn2-vm1-32616459 launched on Node cn-jenkins-deploy-platform-ansible-os-4627-1 2025-11-05 06:38:23,705 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-machine/9d511e75-c8ab-4d1e-a3ad-0be1bdc12b35 2025-11-05 06:38:23,714 - DEBUG - Requesting: http://10.0.0.130:8082/virtual-machine-interface/1ff071ed-67ef-4c84-a95f-87f7a07b540f 2025-11-05 06:38:24,957 - 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.31 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.732 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.732/2.023/3.314/1.291 ms') 2025-11-05 06:38:24,957 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-32616459 passed 2025-11-05 06:38:25,106 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-05 06:38:25,106 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-32616459, IP 174.206.209.195, Port 22 2025-11-05 06:38:25,280 - DEBUG - VM ctest-vn2-vm1-32616459 is ready for SSH connections 2025-11-05 06:38:25,280 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-05 06:38:25,280 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 174.206.209.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2025-11-05 06:38:25,280 - DEBUG - ping -s 56 -c 3 -W 1 174.206.209.195 2025-11-05 06:38:30,405 - DEBUG - PING 174.206.209.195 (174.206.209.195): 56 data bytes --- 174.206.209.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-05 06:38:30,405 - WARNING - Ping to IP 174.206.209.195 from VM ctest-vn1-vm1-36175201 failed 2025-11-05 06:38:30,406 - INFO - Adding interface with subnet_id 308a68b8-162c-4334-be91-09ed4df12dac, port_id None to router d2e06d5f-1f83-4b03-9efd-554713d9a20c 2025-11-05 06:38:30,660 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-05 06:38:30,660 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 174.206.209.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2025-11-05 06:38:30,660 - DEBUG - ping -s 56 -c 3 -W 1 174.206.209.195 2025-11-05 06:38:33,896 - DEBUG - PING 174.206.209.195 (174.206.209.195): 56 data bytes 64 bytes from 174.206.209.195: seq=1 ttl=63 time=3.640 ms 64 bytes from 174.206.209.195: seq=2 ttl=63 time=1.239 ms --- 174.206.209.195 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.239/2.439/3.640 ms 2025-11-05 06:38:33,896 - WARNING - Ping to IP 174.206.209.195 from VM ctest-vn1-vm1-36175201 failed 2025-11-05 06:38:34,897 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 174.206.209.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2025-11-05 06:38:34,897 - DEBUG - ping -s 56 -c 3 -W 1 174.206.209.195 2025-11-05 06:38:37,144 - DEBUG - PING 174.206.209.195 (174.206.209.195): 56 data bytes 64 bytes from 174.206.209.195: seq=0 ttl=63 time=1.883 ms 64 bytes from 174.206.209.195: seq=1 ttl=63 time=1.177 ms 64 bytes from 174.206.209.195: seq=2 ttl=63 time=1.055 ms --- 174.206.209.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.055/1.371/1.883 ms 2025-11-05 06:38:37,144 - INFO - Ping to IP 174.206.209.195 from VM ctest-vn1-vm1-36175201 passed 2025-11-05 06:38:37,145 - INFO - Deleting interface with subnet_id ea72b07e-1c68-4dd1-a9bc-551d8711ef9f, port_id None from router d2e06d5f-1f83-4b03-9efd-554713d9a20c 2025-11-05 06:38:37,282 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-05 06:38:37,283 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 174.206.209.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2025-11-05 06:38:37,283 - DEBUG - ping -s 56 -c 3 -W 1 174.206.209.195 2025-11-05 06:38:40,527 - DEBUG - PING 174.206.209.195 (174.206.209.195): 56 data bytes --- 174.206.209.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-05 06:38:40,527 - WARNING - Ping to IP 174.206.209.195 from VM ctest-vn1-vm1-36175201 failed 2025-11-05 06:38:40,527 - INFO - Adding interface with subnet_id ea72b07e-1c68-4dd1-a9bc-551d8711ef9f, port_id None to router d2e06d5f-1f83-4b03-9efd-554713d9a20c 2025-11-05 06:38:40,760 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-05 06:38:40,760 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 174.206.209.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2025-11-05 06:38:40,760 - DEBUG - ping -s 56 -c 3 -W 1 174.206.209.195 2025-11-05 06:38:42,999 - DEBUG - PING 174.206.209.195 (174.206.209.195): 56 data bytes 64 bytes from 174.206.209.195: seq=0 ttl=63 time=1.638 ms 64 bytes from 174.206.209.195: seq=1 ttl=63 time=0.904 ms 64 bytes from 174.206.209.195: seq=2 ttl=63 time=1.099 ms --- 174.206.209.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.904/1.213/1.638 ms 2025-11-05 06:38:43,000 - INFO - Ping to IP 174.206.209.195 from VM ctest-vn1-vm1-36175201 passed 2025-11-05 06:38:43,000 - INFO - Deleting interface with subnet_id 308a68b8-162c-4334-be91-09ed4df12dac, port_id None from router d2e06d5f-1f83-4b03-9efd-554713d9a20c 2025-11-05 06:38:43,134 - INFO - Deleting interface with subnet_id ea72b07e-1c68-4dd1-a9bc-551d8711ef9f, port_id None from router d2e06d5f-1f83-4b03-9efd-554713d9a20c 2025-11-05 06:38:43,323 - INFO - Deleting VM ctest-vn2-vm1-32616459 2025-11-05 06:38:43,407 - INFO - Deleting VM ctest-vn1-vm1-36175201 2025-11-05 06:38:43,486 - INFO - Deleting VN ctest-vn2-10927708 2025-11-05 06:38:43,527 - DEBUG - VN d738ae1f-ead0-4883-9e5f-106910517c04 still in use: Unable to complete operation on network d738ae1f-ead0-4883-9e5f-106910517c04. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9aed2072-6d8c-44d7-b56a-6586abccab4e'] 2025-11-05 06:38:43,527 - WARNING - Deleting VN ctest-vn2-10927708 failed..Will retry 2025-11-05 06:38:45,640 - DEBUG - Response for deleting network () 2025-11-05 06:38:45,640 - INFO - Deleting VN ctest-vn1-24681400 2025-11-05 06:38:45,751 - DEBUG - Response for deleting network () 2025-11-05 06:38:46,022 - DEBUG - No XMPP flaps were noticed during the test
2025-11-05 06:38:46,022 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:40]
2025-11-05 06:38:46,022 - INFO - -------------------------------------------------------------------------------- 2025-11-05 06:38:46,786 - INFO - Deleted project: ctest-TestRouters-37623558, ID : e3e28163-fc3b-42fe-abee-6e9b65c2cd88