2026-01-29 15:29:04,976 - INFO - Reading existing Domain with UUID 5762e81a-fedf-4255-81ca-e05b2ea83674 2026-01-29 15:29:04,990 - INFO - Using existing domain ['admin_domain'](5762e81a-fedf-4255-81ca-e05b2ea83674) 2026-01-29 15:29:05,237 - INFO - Project ctest-TestRouters-31520549 not found, creating it 2026-01-29 15:29:05,798 - INFO - Created Project:ctest-TestRouters-31520549, ID : ef4137de-f4f9-4fc2-8ab7-edcabeb30676 2026-01-29 15:29:07,662 - INFO - ================================================================================ 2026-01-29 15:29:07,663 - INFO - STARTING TEST : test_basic_router_behavior 2026-01-29 15:29:07,663 - 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-29 15:29:08,063 - DEBUG - Nothing to compare xmpp stats {'10.0.0.61': {'10.0.0.61': '0'}} with 2026-01-29 15:29:08,063 - INFO - Initial checks done. Running the testcase now 2026-01-29 15:29:08,063 - INFO - 2026-01-29 15:29:08,904 - DEBUG - Response for create_network : {'network': {'id': '40201690-fa1b-493d-8d5a-ad940fc06273', 'name': 'ctest-vn1-58874279', 'tenant_id': 'ef4137def4f94fc28ab7edcabeb30676', 'project_id': 'ef4137def4f94fc28ab7edcabeb30676', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['admin_domain', 'ctest-TestRouters-31520549', 'ctest-vn1-58874279'], 'port_security_enabled': True, 'description': '', 'created_at': None, 'updated_at': None}} 2026-01-29 15:29:09,113 - DEBUG - Response for create_subnet : {'subnet': {'id': '9f923d82-99bf-4cc4-a475-83666c3a97a8', 'name': '', 'tenant_id': 'ef4137def4f94fc28ab7edcabeb30676', 'network_id': '40201690-fa1b-493d-8d5a-ad940fc06273', 'ip_version': 4, 'cidr': '19.92.0.0/26', 'allocation_pools': [{'start': '19.92.0.2', 'end': '19.92.0.62'}], 'gateway_ip': '19.92.0.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'created_at': '2026-01-29T15:29:09.013468', 'updated_at': '2026-01-29T15:29:09.013468', 'dns_server_address': '19.92.0.2', 'tags': [], 'project_id': 'ef4137def4f94fc28ab7edcabeb30676'}} 2026-01-29 15:29:09,184 - INFO - Created VN ctest-vn1-58874279 2026-01-29 15:29:09,240 - DEBUG - VN ctest-vn1-58874279 UUID is 40201690-fa1b-493d-8d5a-ad940fc06273 2026-01-29 15:29:09,740 - DEBUG - Response for create_network : {'network': {'id': '435511c6-c0a2-4e45-b340-6f398e481379', 'name': 'ctest-vn2-57324335', 'tenant_id': 'ef4137def4f94fc28ab7edcabeb30676', 'project_id': 'ef4137def4f94fc28ab7edcabeb30676', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['admin_domain', 'ctest-TestRouters-31520549', 'ctest-vn2-57324335'], 'port_security_enabled': True, 'description': '', 'created_at': None, 'updated_at': None}} 2026-01-29 15:29:09,954 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b7be2bea-d81b-437e-817e-9f800ff2a802', 'name': '', 'tenant_id': 'ef4137def4f94fc28ab7edcabeb30676', 'network_id': '435511c6-c0a2-4e45-b340-6f398e481379', 'ip_version': 4, 'cidr': '86.148.245.192/26', 'allocation_pools': [{'start': '86.148.245.194', 'end': '86.148.245.254'}], 'gateway_ip': '86.148.245.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'created_at': '2026-01-29T15:29:09.857649', 'updated_at': '2026-01-29T15:29:09.857649', 'dns_server_address': '86.148.245.194', 'tags': [], 'project_id': 'ef4137def4f94fc28ab7edcabeb30676'}} 2026-01-29 15:29:10,018 - INFO - Created VN ctest-vn2-57324335 2026-01-29 15:29:10,072 - DEBUG - VN ctest-vn2-57324335 UUID is 435511c6-c0a2-4e45-b340-6f398e481379 2026-01-29 15:29:10,340 - DEBUG - Services list from nova: [, , ] 2026-01-29 15:29:12,352 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-juju-os-655-1), Zone: (nova:cn-jenkins-deploy-platform-juju-os-655-1) 2026-01-29 15:29:13,832 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-juju-os-655-1), Zone: (nova:cn-jenkins-deploy-platform-juju-os-655-1) 2026-01-29 15:29:13,968 - INFO - Adding interface with subnet_id 9f923d82-99bf-4cc4-a475-83666c3a97a8, port_id None to router c1c6066a-c37d-41ff-a357-c4c7f0ad942c 2026-01-29 15:29:14,474 - INFO - Waiting for VM ctest-vn1-vm1-37466107 to be up.. 2026-01-29 15:29:14,640 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-29 15:29:19,790 - DEBUG - VM is in ACTIVE state now 2026-01-29 15:29:19,791 - INFO - VM name : ctest-vn1-vm1-37466107 2026-01-29 15:29:19,942 - DEBUG - VM ctest-vn1-vm1-37466107 ID is a53348ec-2b7e-41f5-aebb-82c11dbc313f 2026-01-29 15:29:19,977 - DEBUG - VM ctest-vn1-vm1-37466107 launched on Node cn-jenkins-deploy-platform-juju-os-655-1 2026-01-29 15:29:20,144 - DEBUG - Requesting: https://10.0.0.61:8082/virtual-machine/a53348ec-2b7e-41f5-aebb-82c11dbc313f 2026-01-29 15:29:20,501 - DEBUG - Requesting: https://10.0.0.61:8082/virtual-machine/a53348ec-2b7e-41f5-aebb-82c11dbc313f 2026-01-29 15:29:20,589 - DEBUG - Requesting: https://10.0.0.61:8082/virtual-machine-interface/16942c6d-6487-45cc-875f-f05812cb9835 2026-01-29 15:29:24,043 - 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 1002ms') 2026-01-29 15:29:24,043 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-37466107 failed! 2026-01-29 15:29:24,100 - DEBUG - Gateway for vn admin_domain:ctest-TestRouters-31520549:ctest-vn1-58874279 is 19.92.0.1 and allocation pool is NOT set 2026-01-29 15:29:26,180 - 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=4.16 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/2.306/4.164/1.858 ms') 2026-01-29 15:29:26,180 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-37466107 passed 2026-01-29 15:29:26,340 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 15:29:26,340 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-37466107, IP 19.92.0.3, Port 22 2026-01-29 15:29:26,558 - DEBUG - VM ctest-vn1-vm1-37466107 is ready for SSH connections 2026-01-29 15:29:26,558 - INFO - Waiting for VM ctest-vn2-vm1-21298002 to be up.. 2026-01-29 15:29:26,687 - DEBUG - VM is in ACTIVE state now 2026-01-29 15:29:26,687 - INFO - VM name : ctest-vn2-vm1-21298002 2026-01-29 15:29:26,831 - DEBUG - VM ctest-vn2-vm1-21298002 ID is 2e40ee8a-1d4f-462a-9b0c-f60c583442a3 2026-01-29 15:29:26,831 - DEBUG - VM ctest-vn2-vm1-21298002 launched on Node cn-jenkins-deploy-platform-juju-os-655-1 2026-01-29 15:29:26,965 - DEBUG - Requesting: https://10.0.0.61:8082/virtual-machine/2e40ee8a-1d4f-462a-9b0c-f60c583442a3 2026-01-29 15:29:27,021 - DEBUG - Requesting: https://10.0.0.61:8082/virtual-machine-interface/c0ee7117-f2f9-4637-a582-244da314d76e 2026-01-29 15:29:28,479 - 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=4.06 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.24 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 = 1.240/2.651/4.063/1.411 ms') 2026-01-29 15:29:28,479 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-21298002 passed 2026-01-29 15:29:28,632 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 15:29:28,632 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-21298002, IP 86.148.245.195, Port 22 2026-01-29 15:29:28,846 - DEBUG - VM ctest-vn2-vm1-21298002 is ready for SSH connections 2026-01-29 15:29:28,846 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 15:29:28,846 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 86.148.245.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.61, gateway password: c0ntrail123 2026-01-29 15:29:28,847 - DEBUG - ping -s 56 -c 3 -W 1 86.148.245.195 2026-01-29 15:29:33,823 - DEBUG - PING 86.148.245.195 (86.148.245.195): 56 data bytes --- 86.148.245.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-01-29 15:29:33,823 - WARNING - Ping to IP 86.148.245.195 from VM ctest-vn1-vm1-37466107 failed 2026-01-29 15:29:33,823 - INFO - Adding interface with subnet_id b7be2bea-d81b-437e-817e-9f800ff2a802, port_id None to router c1c6066a-c37d-41ff-a357-c4c7f0ad942c 2026-01-29 15:29:34,227 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 15:29:34,227 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 86.148.245.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.61, gateway password: c0ntrail123 2026-01-29 15:29:34,228 - DEBUG - ping -s 56 -c 3 -W 1 86.148.245.195 2026-01-29 15:29:36,424 - DEBUG - PING 86.148.245.195 (86.148.245.195): 56 data bytes 64 bytes from 86.148.245.195: seq=0 ttl=63 time=2.389 ms 64 bytes from 86.148.245.195: seq=1 ttl=63 time=0.874 ms 64 bytes from 86.148.245.195: seq=2 ttl=63 time=0.922 ms --- 86.148.245.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.874/1.395/2.389 ms 2026-01-29 15:29:36,424 - INFO - Ping to IP 86.148.245.195 from VM ctest-vn1-vm1-37466107 passed 2026-01-29 15:29:36,424 - INFO - Deleting interface with subnet_id 9f923d82-99bf-4cc4-a475-83666c3a97a8, port_id None from router c1c6066a-c37d-41ff-a357-c4c7f0ad942c 2026-01-29 15:29:36,662 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 15:29:36,662 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 86.148.245.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.61, gateway password: c0ntrail123 2026-01-29 15:29:36,662 - DEBUG - ping -s 56 -c 3 -W 1 86.148.245.195 2026-01-29 15:29:39,875 - DEBUG - PING 86.148.245.195 (86.148.245.195): 56 data bytes --- 86.148.245.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2026-01-29 15:29:39,875 - WARNING - Ping to IP 86.148.245.195 from VM ctest-vn1-vm1-37466107 failed 2026-01-29 15:29:39,876 - INFO - Adding interface with subnet_id 9f923d82-99bf-4cc4-a475-83666c3a97a8, port_id None to router c1c6066a-c37d-41ff-a357-c4c7f0ad942c 2026-01-29 15:29:40,238 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-29 15:29:40,238 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 86.148.245.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.61, gateway password: c0ntrail123 2026-01-29 15:29:40,238 - DEBUG - ping -s 56 -c 3 -W 1 86.148.245.195 2026-01-29 15:29:42,465 - DEBUG - PING 86.148.245.195 (86.148.245.195): 56 data bytes 64 bytes from 86.148.245.195: seq=0 ttl=63 time=1.645 ms 64 bytes from 86.148.245.195: seq=1 ttl=63 time=0.979 ms 64 bytes from 86.148.245.195: seq=2 ttl=63 time=1.093 ms --- 86.148.245.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.979/1.239/1.645 ms 2026-01-29 15:29:42,466 - INFO - Ping to IP 86.148.245.195 from VM ctest-vn1-vm1-37466107 passed 2026-01-29 15:29:42,466 - INFO - Deleting interface with subnet_id b7be2bea-d81b-437e-817e-9f800ff2a802, port_id None from router c1c6066a-c37d-41ff-a357-c4c7f0ad942c 2026-01-29 15:29:42,722 - INFO - Deleting interface with subnet_id 9f923d82-99bf-4cc4-a475-83666c3a97a8, port_id None from router c1c6066a-c37d-41ff-a357-c4c7f0ad942c 2026-01-29 15:29:43,078 - INFO - Deleting VM ctest-vn2-vm1-21298002 2026-01-29 15:29:43,288 - INFO - Deleting VM ctest-vn1-vm1-37466107 2026-01-29 15:29:43,399 - INFO - Deleting VN ctest-vn2-57324335 2026-01-29 15:29:43,539 - DEBUG - VN 435511c6-c0a2-4e45-b340-6f398e481379 still in use: Unable to complete operation on network 435511c6-c0a2-4e45-b340-6f398e481379. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-51419606-ec3a-4026-bd44-668ef6f29f0d'] 2026-01-29 15:29:43,539 - WARNING - Deleting VN ctest-vn2-57324335 failed..Will retry 2026-01-29 15:29:45,717 - DEBUG - Response for deleting network () 2026-01-29 15:29:45,717 - INFO - Deleting VN ctest-vn1-58874279 2026-01-29 15:29:45,894 - DEBUG - Response for deleting network () 2026-01-29 15:29:46,285 - DEBUG - No XMPP flaps were noticed during the test
2026-01-29 15:29:46,285 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:39]
2026-01-29 15:29:46,285 - INFO - -------------------------------------------------------------------------------- 2026-01-29 15:29:47,060 - INFO - Deleted project: ctest-TestRouters-31520549, ID : ef4137de-f4f9-4fc2-8ab7-edcabeb30676