2026-01-26 02:24:10,723 - INFO - Domain Default found not creating 2026-01-26 02:24:10,891 - INFO - Project ctest-TestRouters-53707415 not found, creating it 2026-01-26 02:24:11,479 - INFO - Created Project:ctest-TestRouters-53707415, ID : 0e483305-69e7-4441-bb9c-79724c31d743 2026-01-26 02:24:12,953 - INFO - ================================================================================ 2026-01-26 02:24:12,953 - INFO - STARTING TEST : test_basic_router_behavior 2026-01-26 02:24:12,953 - 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-01-26 02:24:14,214 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.129': '0', '10.20.0.25': '0'}, '10.0.0.130': {'10.20.0.25': '0', '10.20.0.129': '0'}} with 2026-01-26 02:24:14,214 - INFO - Initial checks done. Running the testcase now 2026-01-26 02:24:14,214 - INFO - 2026-01-26 02:24:14,867 - DEBUG - Response for create_network : {'network': {'id': 'db1d411c-0c49-49a0-8e3f-bc56ac196c43', 'name': 'ctest-vn1-67356099', 'tenant_id': '0e48330569e74441bb9c79724c31d743', 'project_id': '0e48330569e74441bb9c79724c31d743', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-53707415', 'ctest-vn1-67356099'], 'port_security_enabled': True, 'description': ''}} 2026-01-26 02:24:15,177 - DEBUG - Response for create_subnet : {'subnet': {'id': '20696e3f-86db-4d6d-b6da-540b5b5a3e1a', 'name': '', 'tenant_id': '0e48330569e74441bb9c79724c31d743', 'network_id': 'db1d411c-0c49-49a0-8e3f-bc56ac196c43', 'ip_version': 4, 'cidr': '129.37.41.128/26', 'allocation_pools': [{'start': '129.37.41.130', 'end': '129.37.41.190'}], 'gateway_ip': '129.37.41.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '129.37.41.130', 'tags': [], 'project_id': '0e48330569e74441bb9c79724c31d743'}} 2026-01-26 02:24:15,199 - INFO - Created VN ctest-vn1-67356099 2026-01-26 02:24:15,210 - DEBUG - VN ctest-vn1-67356099 UUID is db1d411c-0c49-49a0-8e3f-bc56ac196c43 2026-01-26 02:24:15,562 - DEBUG - Response for create_network : {'network': {'id': 'e9bf7f42-c03b-47ce-96aa-60ed59a6b155', 'name': 'ctest-vn2-71010824', 'tenant_id': '0e48330569e74441bb9c79724c31d743', 'project_id': '0e48330569e74441bb9c79724c31d743', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-53707415', 'ctest-vn2-71010824'], 'port_security_enabled': True, 'description': ''}} 2026-01-26 02:24:15,793 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a5d0e189-7999-45ea-b780-8460526d3376', 'name': '', 'tenant_id': '0e48330569e74441bb9c79724c31d743', 'network_id': 'e9bf7f42-c03b-47ce-96aa-60ed59a6b155', 'ip_version': 4, 'cidr': '15.246.149.64/26', 'allocation_pools': [{'start': '15.246.149.66', 'end': '15.246.149.126'}], 'gateway_ip': '15.246.149.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '15.246.149.66', 'tags': [], 'project_id': '0e48330569e74441bb9c79724c31d743'}} 2026-01-26 02:24:15,813 - INFO - Created VN ctest-vn2-71010824 2026-01-26 02:24:15,825 - DEBUG - VN ctest-vn2-71010824 UUID is e9bf7f42-c03b-47ce-96aa-60ed59a6b155 2026-01-26 02:24:15,995 - DEBUG - Services list from nova: [, , , ] 2026-01-26 02:24:17,018 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5076-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5076-1) 2026-01-26 02:24:18,282 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5076-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5076-2) 2026-01-26 02:24:18,383 - INFO - Adding interface with subnet_id 20696e3f-86db-4d6d-b6da-540b5b5a3e1a, port_id None to router a49b2718-2549-465d-9801-ba4715c70653 2026-01-26 02:24:18,733 - INFO - Waiting for VM ctest-vn1-vm1-83197094 to be up.. 2026-01-26 02:24:18,863 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-26 02:24:23,962 - DEBUG - VM is in ACTIVE state now 2026-01-26 02:24:23,962 - INFO - VM name : ctest-vn1-vm1-83197094 2026-01-26 02:24:24,046 - DEBUG - VM ctest-vn1-vm1-83197094 ID is 6d6e03f5-1179-4504-8c5c-6b2f1aa8e54a 2026-01-26 02:24:24,075 - DEBUG - VM ctest-vn1-vm1-83197094 launched on Node an-jenkins-deploy-platform-ansible-os-5076-1 2026-01-26 02:24:24,161 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/6d6e03f5-1179-4504-8c5c-6b2f1aa8e54a 2026-01-26 02:24:24,481 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/6d6e03f5-1179-4504-8c5c-6b2f1aa8e54a 2026-01-26 02:24:24,516 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/323a3294-43cd-48ac-8ccd-8677eb50d453 2026-01-26 02:24:27,693 - 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 1013ms') 2026-01-26 02:24:27,693 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-83197094 failed! 2026-01-26 02:24:27,706 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-53707415:ctest-vn1-67356099 is 129.37.41.129 and allocation pool is NOT set 2026-01-26 02:24:31,791 - 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=6.19 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1012ms\r\nrtt min/avg/max/mdev = 6.188/6.188/6.188/0.000 ms') 2026-01-26 02:24:31,791 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-83197094 passed 2026-01-26 02:24:31,859 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:24:31,859 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-83197094, IP 129.37.41.131, Port 22 2026-01-26 02:24:32,030 - DEBUG - VM ctest-vn1-vm1-83197094 is ready for SSH connections 2026-01-26 02:24:32,030 - INFO - Waiting for VM ctest-vn2-vm1-02911513 to be up.. 2026-01-26 02:24:32,125 - DEBUG - VM is in ACTIVE state now 2026-01-26 02:24:32,125 - INFO - VM name : ctest-vn2-vm1-02911513 2026-01-26 02:24:32,208 - DEBUG - VM ctest-vn2-vm1-02911513 ID is 3be47535-daa1-4304-a638-626c813b63a7 2026-01-26 02:24:32,208 - DEBUG - VM ctest-vn2-vm1-02911513 launched on Node an-jenkins-deploy-platform-ansible-os-5076-2 2026-01-26 02:24:32,290 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/3be47535-daa1-4304-a638-626c813b63a7 2026-01-26 02:24:32,300 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/0ddc6848-318a-4536-939d-7713378ba530 2026-01-26 02:24:33,492 - 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.18 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.448 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.448/1.814/3.181/1.366 ms') 2026-01-26 02:24:33,492 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn2-vm1-02911513 passed 2026-01-26 02:24:33,561 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:24:33,561 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-02911513, IP 15.246.149.67, Port 22 2026-01-26 02:24:33,718 - DEBUG - VM ctest-vn2-vm1-02911513 is ready for SSH connections 2026-01-26 02:24:33,718 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:24:33,718 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 15.246.149.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-01-26 02:24:33,718 - DEBUG - ping -s 56 -c 3 -W 1 15.246.149.67 2026-01-26 02:24:37,699 - DEBUG - PING 15.246.149.67 (15.246.149.67): 56 data bytes --- 15.246.149.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-01-26 02:24:37,700 - WARNING - Ping to IP 15.246.149.67 from VM ctest-vn1-vm1-83197094 failed 2026-01-26 02:24:37,700 - INFO - Adding interface with subnet_id a5d0e189-7999-45ea-b780-8460526d3376, port_id None to router a49b2718-2549-465d-9801-ba4715c70653 2026-01-26 02:24:38,155 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:24:38,155 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 15.246.149.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-01-26 02:24:38,156 - DEBUG - ping -s 56 -c 3 -W 1 15.246.149.67 2026-01-26 02:24:40,399 - DEBUG - PING 15.246.149.67 (15.246.149.67): 56 data bytes 64 bytes from 15.246.149.67: seq=0 ttl=63 time=4.951 ms 64 bytes from 15.246.149.67: seq=1 ttl=63 time=1.388 ms 64 bytes from 15.246.149.67: seq=2 ttl=63 time=1.745 ms --- 15.246.149.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.388/2.694/4.951 ms 2026-01-26 02:24:40,399 - INFO - Ping to IP 15.246.149.67 from VM ctest-vn1-vm1-83197094 passed 2026-01-26 02:24:40,399 - INFO - Deleting interface with subnet_id 20696e3f-86db-4d6d-b6da-540b5b5a3e1a, port_id None from router a49b2718-2549-465d-9801-ba4715c70653 2026-01-26 02:24:40,594 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:24:40,594 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 15.246.149.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-01-26 02:24:40,594 - DEBUG - ping -s 56 -c 3 -W 1 15.246.149.67 2026-01-26 02:24:43,821 - DEBUG - PING 15.246.149.67 (15.246.149.67): 56 data bytes --- 15.246.149.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-01-26 02:24:43,822 - WARNING - Ping to IP 15.246.149.67 from VM ctest-vn1-vm1-83197094 failed 2026-01-26 02:24:43,822 - INFO - Adding interface with subnet_id 20696e3f-86db-4d6d-b6da-540b5b5a3e1a, port_id None to router a49b2718-2549-465d-9801-ba4715c70653 2026-01-26 02:24:44,195 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:24:44,195 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 15.246.149.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-01-26 02:24:44,195 - DEBUG - ping -s 56 -c 3 -W 1 15.246.149.67 2026-01-26 02:24:46,395 - DEBUG - PING 15.246.149.67 (15.246.149.67): 56 data bytes 64 bytes from 15.246.149.67: seq=0 ttl=63 time=4.038 ms 64 bytes from 15.246.149.67: seq=1 ttl=63 time=1.389 ms 64 bytes from 15.246.149.67: seq=2 ttl=63 time=1.291 ms --- 15.246.149.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.291/2.239/4.038 ms 2026-01-26 02:24:46,395 - INFO - Ping to IP 15.246.149.67 from VM ctest-vn1-vm1-83197094 passed 2026-01-26 02:24:46,395 - INFO - Deleting interface with subnet_id a5d0e189-7999-45ea-b780-8460526d3376, port_id None from router a49b2718-2549-465d-9801-ba4715c70653 2026-01-26 02:24:46,633 - INFO - Deleting interface with subnet_id 20696e3f-86db-4d6d-b6da-540b5b5a3e1a, port_id None from router a49b2718-2549-465d-9801-ba4715c70653 2026-01-26 02:24:46,923 - INFO - Deleting VM ctest-vn2-vm1-02911513 2026-01-26 02:24:47,003 - INFO - Deleting VM ctest-vn1-vm1-83197094 2026-01-26 02:24:47,071 - INFO - Deleting VN ctest-vn2-71010824 2026-01-26 02:24:47,111 - DEBUG - VN e9bf7f42-c03b-47ce-96aa-60ed59a6b155 still in use: Unable to complete operation on network e9bf7f42-c03b-47ce-96aa-60ed59a6b155. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-f9b279d9-1fac-4bd9-96c9-4c9f61f148b0'] 2026-01-26 02:24:47,111 - WARNING - Deleting VN ctest-vn2-71010824 failed..Will retry 2026-01-26 02:24:49,296 - DEBUG - Response for deleting network () 2026-01-26 02:24:49,297 - INFO - Deleting VN ctest-vn1-67356099 2026-01-26 02:24:49,471 - DEBUG - Response for deleting network () 2026-01-26 02:24:50,722 - DEBUG - No XMPP flaps were noticed during the test
2026-01-26 02:24:50,722 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:38]
2026-01-26 02:24:50,722 - INFO - -------------------------------------------------------------------------------- 2026-01-26 02:24:51,457 - INFO - Deleted project: ctest-TestRouters-53707415, ID : 0e483305-69e7-4441-bb9c-79724c31d743