2026-04-21 16:54:47,602 - INFO - Domain Default found not creating 2026-04-21 16:54:47,809 - INFO - Project ctest-TestRouters-15155254 not found, creating it 2026-04-21 16:54:48,278 - INFO - Created Project:ctest-TestRouters-15155254, ID : b0cde525-7a99-4538-b933-c69bdd77ce1f 2026-04-21 16:54:49,822 - INFO - ================================================================================ 2026-04-21 16:54:49,822 - INFO - STARTING TEST : test_basic_router_behavior 2026-04-21 16:54:49,823 - 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-04-21 16:54:50,086 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.254': '0'}} with 2026-04-21 16:54:50,086 - INFO - Initial checks done. Running the testcase now 2026-04-21 16:54:50,087 - INFO - 2026-04-21 16:54:50,631 - DEBUG - Response for create_network : {'network': {'id': 'f97d3b1d-b252-46f8-86ac-2002b47956d6', 'name': 'ctest-vn1-36913455', 'tenant_id': 'b0cde5257a994538b933c69bdd77ce1f', 'project_id': 'b0cde5257a994538b933c69bdd77ce1f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-15155254', 'ctest-vn1-36913455'], 'port_security_enabled': True, 'description': ''}} 2026-04-21 16:54:50,818 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e8385ac1-d8c0-42de-864f-43a1a8c5d0af', 'name': '', 'tenant_id': 'b0cde5257a994538b933c69bdd77ce1f', 'network_id': 'f97d3b1d-b252-46f8-86ac-2002b47956d6', 'ip_version': 4, 'cidr': '56.190.174.0/26', 'allocation_pools': [{'start': '56.190.174.2', 'end': '56.190.174.62'}], 'gateway_ip': '56.190.174.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '56.190.174.2', 'tags': [], 'project_id': 'b0cde5257a994538b933c69bdd77ce1f'}} 2026-04-21 16:54:50,835 - INFO - Created VN ctest-vn1-36913455 2026-04-21 16:54:50,888 - DEBUG - VN ctest-vn1-36913455 UUID is f97d3b1d-b252-46f8-86ac-2002b47956d6 2026-04-21 16:54:51,216 - DEBUG - Response for create_network : {'network': {'id': 'c4613e52-1fac-4aca-9be9-8c3e03a4c0d9', 'name': 'ctest-vn2-30212983', 'tenant_id': 'b0cde5257a994538b933c69bdd77ce1f', 'project_id': 'b0cde5257a994538b933c69bdd77ce1f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-15155254', 'ctest-vn2-30212983'], 'port_security_enabled': True, 'description': ''}} 2026-04-21 16:54:51,400 - DEBUG - Response for create_subnet : {'subnet': {'id': '4fc266d9-afba-4f94-b97e-be9d038eb5a0', 'name': '', 'tenant_id': 'b0cde5257a994538b933c69bdd77ce1f', 'network_id': 'c4613e52-1fac-4aca-9be9-8c3e03a4c0d9', 'ip_version': 4, 'cidr': '28.206.91.64/26', 'allocation_pools': [{'start': '28.206.91.66', 'end': '28.206.91.126'}], 'gateway_ip': '28.206.91.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '28.206.91.66', 'tags': [], 'project_id': 'b0cde5257a994538b933c69bdd77ce1f'}} 2026-04-21 16:54:51,432 - INFO - Created VN ctest-vn2-30212983 2026-04-21 16:54:51,484 - DEBUG - VN ctest-vn2-30212983 UUID is c4613e52-1fac-4aca-9be9-8c3e03a4c0d9 2026-04-21 16:54:51,643 - DEBUG - Services list from nova: [, , ] 2026-04-21 16:54:52,924 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5662-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5662-1) 2026-04-21 16:54:54,106 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-5662-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-5662-1) 2026-04-21 16:54:54,192 - INFO - Adding interface with subnet_id e8385ac1-d8c0-42de-864f-43a1a8c5d0af, port_id None to router 9e851322-483d-4feb-9152-db69b23f3355 2026-04-21 16:54:54,492 - INFO - Waiting for VM ctest-vn1-vm1-92038007 to be up.. 2026-04-21 16:54:54,600 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-04-21 16:54:59,682 - DEBUG - VM is in ACTIVE state now 2026-04-21 16:54:59,682 - INFO - VM name : ctest-vn1-vm1-92038007 2026-04-21 16:54:59,764 - DEBUG - VM ctest-vn1-vm1-92038007 ID is dde34381-de7b-442d-8fc4-143700f75567 2026-04-21 16:54:59,785 - DEBUG - VM ctest-vn1-vm1-92038007 launched on Node cn-jenkins-deploy-platform-ansible-os-5662-1 2026-04-21 16:54:59,869 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/dde34381-de7b-442d-8fc4-143700f75567 2026-04-21 16:55:00,193 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/dde34381-de7b-442d-8fc4-143700f75567 2026-04-21 16:55:00,231 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/09889a3e-0fea-44b8-9471-00f417210e8c 2026-04-21 16:55:03,491 - 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 1005ms') 2026-04-21 16:55:03,491 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-92038007 failed! 2026-04-21 16:55:03,548 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-15155254:ctest-vn1-36913455 is 56.190.174.1 and allocation pool is NOT set 2026-04-21 16:55:05,613 - 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.29 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.514 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.514/1.899/3.285/1.385 ms') 2026-04-21 16:55:05,613 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-92038007 passed 2026-04-21 16:55:05,760 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-21 16:55:05,760 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-92038007, IP 56.190.174.3, Port 22 2026-04-21 16:55:05,922 - DEBUG - VM ctest-vn1-vm1-92038007 is ready for SSH connections 2026-04-21 16:55:05,922 - INFO - Waiting for VM ctest-vn2-vm1-89948155 to be up.. 2026-04-21 16:55:06,008 - DEBUG - VM is in ACTIVE state now 2026-04-21 16:55:06,008 - INFO - VM name : ctest-vn2-vm1-89948155 2026-04-21 16:55:06,096 - DEBUG - VM ctest-vn2-vm1-89948155 ID is 56bb0eb6-dd21-4c92-9e87-df5590e233fe 2026-04-21 16:55:06,096 - DEBUG - VM ctest-vn2-vm1-89948155 launched on Node cn-jenkins-deploy-platform-ansible-os-5662-1 2026-04-21 16:55:06,172 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/56bb0eb6-dd21-4c92-9e87-df5590e233fe 2026-04-21 16:55:06,180 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/82ae2cf3-54f1-4dee-bdf0-a78dce26a4f0 2026-04-21 16:55:07,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=2.97 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.475 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.475/1.724/2.974/1.249 ms') 2026-04-21 16:55:07,410 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-89948155 passed 2026-04-21 16:55:07,564 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-21 16:55:07,564 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-89948155, IP 28.206.91.67, Port 22 2026-04-21 16:55:07,723 - DEBUG - VM ctest-vn2-vm1-89948155 is ready for SSH connections 2026-04-21 16:55:07,723 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-21 16:55:07,723 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 28.206.91.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-04-21 16:55:07,723 - DEBUG - ping -s 56 -c 3 -W 1 28.206.91.67 2026-04-21 16:55:12,757 - DEBUG - PING 28.206.91.67 (28.206.91.67): 56 data bytes --- 28.206.91.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-04-21 16:55:12,757 - WARNING - Ping to IP 28.206.91.67 from VM ctest-vn1-vm1-92038007 failed 2026-04-21 16:55:12,757 - INFO - Adding interface with subnet_id 4fc266d9-afba-4f94-b97e-be9d038eb5a0, port_id None to router 9e851322-483d-4feb-9152-db69b23f3355 2026-04-21 16:55:12,989 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-21 16:55:12,989 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 28.206.91.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-04-21 16:55:12,989 - DEBUG - ping -s 56 -c 3 -W 1 28.206.91.67 2026-04-21 16:55:16,197 - DEBUG - PING 28.206.91.67 (28.206.91.67): 56 data bytes 64 bytes from 28.206.91.67: seq=1 ttl=63 time=2.993 ms 64 bytes from 28.206.91.67: seq=2 ttl=63 time=0.999 ms --- 28.206.91.67 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 0.999/1.996/2.993 ms 2026-04-21 16:55:16,197 - WARNING - Ping to IP 28.206.91.67 from VM ctest-vn1-vm1-92038007 failed 2026-04-21 16:55:17,198 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 28.206.91.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-04-21 16:55:17,198 - DEBUG - ping -s 56 -c 3 -W 1 28.206.91.67 2026-04-21 16:55:19,406 - DEBUG - PING 28.206.91.67 (28.206.91.67): 56 data bytes 64 bytes from 28.206.91.67: seq=0 ttl=63 time=1.338 ms 64 bytes from 28.206.91.67: seq=1 ttl=63 time=0.925 ms 64 bytes from 28.206.91.67: seq=2 ttl=63 time=0.975 ms --- 28.206.91.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.925/1.079/1.338 ms 2026-04-21 16:55:19,406 - INFO - Ping to IP 28.206.91.67 from VM ctest-vn1-vm1-92038007 passed 2026-04-21 16:55:19,407 - INFO - Deleting interface with subnet_id e8385ac1-d8c0-42de-864f-43a1a8c5d0af, port_id None from router 9e851322-483d-4feb-9152-db69b23f3355 2026-04-21 16:55:19,527 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-21 16:55:19,527 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 28.206.91.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-04-21 16:55:19,527 - DEBUG - ping -s 56 -c 3 -W 1 28.206.91.67 2026-04-21 16:55:22,753 - DEBUG - PING 28.206.91.67 (28.206.91.67): 56 data bytes --- 28.206.91.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-04-21 16:55:22,753 - WARNING - Ping to IP 28.206.91.67 from VM ctest-vn1-vm1-92038007 failed 2026-04-21 16:55:22,753 - INFO - Adding interface with subnet_id e8385ac1-d8c0-42de-864f-43a1a8c5d0af, port_id None to router 9e851322-483d-4feb-9152-db69b23f3355 2026-04-21 16:55:22,954 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-04-21 16:55:22,954 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 28.206.91.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-04-21 16:55:22,954 - DEBUG - ping -s 56 -c 3 -W 1 28.206.91.67 2026-04-21 16:55:25,151 - DEBUG - PING 28.206.91.67 (28.206.91.67): 56 data bytes 64 bytes from 28.206.91.67: seq=0 ttl=63 time=1.608 ms 64 bytes from 28.206.91.67: seq=1 ttl=63 time=1.014 ms 64 bytes from 28.206.91.67: seq=2 ttl=63 time=0.824 ms --- 28.206.91.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.824/1.148/1.608 ms 2026-04-21 16:55:25,151 - INFO - Ping to IP 28.206.91.67 from VM ctest-vn1-vm1-92038007 passed 2026-04-21 16:55:25,151 - INFO - Deleting interface with subnet_id 4fc266d9-afba-4f94-b97e-be9d038eb5a0, port_id None from router 9e851322-483d-4feb-9152-db69b23f3355 2026-04-21 16:55:25,266 - INFO - Deleting interface with subnet_id e8385ac1-d8c0-42de-864f-43a1a8c5d0af, port_id None from router 9e851322-483d-4feb-9152-db69b23f3355 2026-04-21 16:55:25,440 - INFO - Deleting VM ctest-vn2-vm1-89948155 2026-04-21 16:55:25,507 - INFO - Deleting VM ctest-vn1-vm1-92038007 2026-04-21 16:55:25,574 - INFO - Deleting VN ctest-vn2-30212983 2026-04-21 16:55:25,613 - DEBUG - VN c4613e52-1fac-4aca-9be9-8c3e03a4c0d9 still in use: Unable to complete operation on network c4613e52-1fac-4aca-9be9-8c3e03a4c0d9. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-d1865725-8608-40fd-93c5-19395528165d'] 2026-04-21 16:55:25,613 - WARNING - Deleting VN ctest-vn2-30212983 failed..Will retry 2026-04-21 16:55:27,708 - DEBUG - Response for deleting network () 2026-04-21 16:55:27,708 - INFO - Deleting VN ctest-vn1-36913455 2026-04-21 16:55:27,818 - DEBUG - Response for deleting network () 2026-04-21 16:55:28,073 - DEBUG - No XMPP flaps were noticed during the test
2026-04-21 16:55:28,074 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:39]
2026-04-21 16:55:28,074 - INFO - -------------------------------------------------------------------------------- 2026-04-21 16:55:28,816 - INFO - Deleted project: ctest-TestRouters-15155254, ID : b0cde525-7a99-4538-b933-c69bdd77ce1f