2025-08-25 14:24:20,335 - INFO - Domain Default found not creating 2025-08-25 14:24:20,521 - INFO - Project ctest-TestRouters-72382361 not found, creating it 2025-08-25 14:24:21,053 - INFO - Created Project:ctest-TestRouters-72382361, ID : ff0f7db3-73be-40c9-a5fb-883f46cd50ea 2025-08-25 14:24:22,663 - INFO - ================================================================================ 2025-08-25 14:24:22,664 - INFO - STARTING TEST : test_basic_router_behavior 2025-08-25 14:24:22,664 - 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-08-25 14:24:22,925 - DEBUG - Nothing to compare xmpp stats {'10.0.0.254': {'10.20.0.254': '0'}} with 2025-08-25 14:24:22,925 - INFO - Initial checks done. Running the testcase now 2025-08-25 14:24:22,926 - INFO - 2025-08-25 14:24:23,571 - DEBUG - Response for create_network : {'network': {'id': 'bd1a4c2c-5f83-4563-9a30-40221af23870', 'name': 'ctest-vn1-87604292', 'tenant_id': 'ff0f7db373be40c9a5fb883f46cd50ea', 'project_id': 'ff0f7db373be40c9a5fb883f46cd50ea', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-72382361', 'ctest-vn1-87604292'], 'port_security_enabled': True, 'description': ''}} 2025-08-25 14:24:23,836 - DEBUG - Response for create_subnet : {'subnet': {'id': '379a9b43-e749-4a09-ba2c-f069b3c85d74', 'name': '', 'tenant_id': 'ff0f7db373be40c9a5fb883f46cd50ea', 'network_id': 'bd1a4c2c-5f83-4563-9a30-40221af23870', 'ip_version': 4, 'cidr': '171.200.205.128/26', 'allocation_pools': [{'start': '171.200.205.130', 'end': '171.200.205.190'}], 'gateway_ip': '171.200.205.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '171.200.205.130', 'tags': [], 'project_id': 'ff0f7db373be40c9a5fb883f46cd50ea'}} 2025-08-25 14:24:23,854 - INFO - Created VN ctest-vn1-87604292 2025-08-25 14:24:23,909 - DEBUG - VN ctest-vn1-87604292 UUID is bd1a4c2c-5f83-4563-9a30-40221af23870 2025-08-25 14:24:24,264 - DEBUG - Response for create_network : {'network': {'id': 'c8457c04-29f8-449b-aec8-b0de0e173b7e', 'name': 'ctest-vn2-13529300', 'tenant_id': 'ff0f7db373be40c9a5fb883f46cd50ea', 'project_id': 'ff0f7db373be40c9a5fb883f46cd50ea', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-72382361', 'ctest-vn2-13529300'], 'port_security_enabled': True, 'description': ''}} 2025-08-25 14:24:24,519 - DEBUG - Response for create_subnet : {'subnet': {'id': '99068a89-42b4-441e-84c2-37a036ef44d8', 'name': '', 'tenant_id': 'ff0f7db373be40c9a5fb883f46cd50ea', 'network_id': 'c8457c04-29f8-449b-aec8-b0de0e173b7e', 'ip_version': 4, 'cidr': '31.123.78.192/26', 'allocation_pools': [{'start': '31.123.78.194', 'end': '31.123.78.254'}], 'gateway_ip': '31.123.78.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '31.123.78.194', 'tags': [], 'project_id': 'ff0f7db373be40c9a5fb883f46cd50ea'}} 2025-08-25 14:24:24,541 - INFO - Created VN ctest-vn2-13529300 2025-08-25 14:24:24,597 - DEBUG - VN ctest-vn2-13529300 UUID is c8457c04-29f8-449b-aec8-b0de0e173b7e 2025-08-25 14:24:24,781 - DEBUG - Services list from nova: [, , ] 2025-08-25 14:24:25,932 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3920-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3920-1) 2025-08-25 14:24:27,409 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3920-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3920-1) 2025-08-25 14:24:27,482 - INFO - Adding interface with subnet_id 379a9b43-e749-4a09-ba2c-f069b3c85d74, port_id None to router 5794d3e5-0dd6-4f11-99f0-c5b109b622a9 2025-08-25 14:24:27,795 - INFO - Waiting for VM ctest-vn1-vm1-09888616 to be up.. 2025-08-25 14:24:27,991 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-25 14:24:33,127 - DEBUG - VM is in ACTIVE state now 2025-08-25 14:24:33,127 - INFO - VM name : ctest-vn1-vm1-09888616 2025-08-25 14:24:33,246 - DEBUG - VM ctest-vn1-vm1-09888616 ID is e49ca728-f454-4c79-b93b-b7f7d3375f24 2025-08-25 14:24:33,284 - DEBUG - VM ctest-vn1-vm1-09888616 launched on Node cn-jenkins-deploy-platform-ansible-os-3920-1 2025-08-25 14:24:33,425 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/e49ca728-f454-4c79-b93b-b7f7d3375f24 2025-08-25 14:24:33,971 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/e49ca728-f454-4c79-b93b-b7f7d3375f24 2025-08-25 14:24:34,056 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/ee476c35-998f-44dd-ae05-237bcb43ccb9 2025-08-25 14:24:37,322 - 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 1009ms') 2025-08-25 14:24:37,323 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-09888616 failed! 2025-08-25 14:24:37,381 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-72382361:ctest-vn1-87604292 is 171.200.205.129 and allocation pool is NOT set 2025-08-25 14:24:39,442 - 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.10 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.371 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.371/2.236/4.102/1.865 ms') 2025-08-25 14:24:39,443 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-09888616 passed 2025-08-25 14:24:39,601 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-25 14:24:39,601 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-09888616, IP 171.200.205.131, Port 22 2025-08-25 14:24:39,771 - DEBUG - VM ctest-vn1-vm1-09888616 is ready for SSH connections 2025-08-25 14:24:39,772 - INFO - Waiting for VM ctest-vn2-vm1-97610198 to be up.. 2025-08-25 14:24:39,849 - DEBUG - VM is in ACTIVE state now 2025-08-25 14:24:39,849 - INFO - VM name : ctest-vn2-vm1-97610198 2025-08-25 14:24:39,925 - DEBUG - VM ctest-vn2-vm1-97610198 ID is da0f9b09-ed62-4a97-ad92-c57aab58129d 2025-08-25 14:24:39,925 - DEBUG - VM ctest-vn2-vm1-97610198 launched on Node cn-jenkins-deploy-platform-ansible-os-3920-1 2025-08-25 14:24:40,009 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/da0f9b09-ed62-4a97-ad92-c57aab58129d 2025-08-25 14:24:40,022 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/1ff7ae17-3123-4cb6-a751-a5a2f27f25ba 2025-08-25 14:24:41,282 - 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.48 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.727 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.727/2.101/3.476/1.374 ms') 2025-08-25 14:24:41,283 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-97610198 passed 2025-08-25 14:24:41,441 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-25 14:24:41,441 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-97610198, IP 31.123.78.195, Port 22 2025-08-25 14:24:41,604 - DEBUG - VM ctest-vn2-vm1-97610198 is ready for SSH connections 2025-08-25 14:24:41,604 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-25 14:24:41,604 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.123.78.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-08-25 14:24:41,605 - DEBUG - ping -s 56 -c 3 -W 1 31.123.78.195 2025-08-25 14:24:46,430 - DEBUG - PING 31.123.78.195 (31.123.78.195): 56 data bytes --- 31.123.78.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-08-25 14:24:46,430 - WARNING - Ping to IP 31.123.78.195 from VM ctest-vn1-vm1-09888616 failed 2025-08-25 14:24:46,430 - INFO - Adding interface with subnet_id 99068a89-42b4-441e-84c2-37a036ef44d8, port_id None to router 5794d3e5-0dd6-4f11-99f0-c5b109b622a9 2025-08-25 14:24:46,783 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-25 14:24:46,783 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.123.78.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-08-25 14:24:46,783 - DEBUG - ping -s 56 -c 3 -W 1 31.123.78.195 2025-08-25 14:24:49,012 - DEBUG - PING 31.123.78.195 (31.123.78.195): 56 data bytes 64 bytes from 31.123.78.195: seq=0 ttl=63 time=2.804 ms 64 bytes from 31.123.78.195: seq=1 ttl=63 time=0.885 ms 64 bytes from 31.123.78.195: seq=2 ttl=63 time=0.762 ms --- 31.123.78.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.762/1.483/2.804 ms 2025-08-25 14:24:49,012 - INFO - Ping to IP 31.123.78.195 from VM ctest-vn1-vm1-09888616 passed 2025-08-25 14:24:49,012 - INFO - Deleting interface with subnet_id 379a9b43-e749-4a09-ba2c-f069b3c85d74, port_id None from router 5794d3e5-0dd6-4f11-99f0-c5b109b622a9 2025-08-25 14:24:49,143 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-25 14:24:49,143 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.123.78.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-08-25 14:24:49,143 - DEBUG - ping -s 56 -c 3 -W 1 31.123.78.195 2025-08-25 14:24:52,362 - DEBUG - PING 31.123.78.195 (31.123.78.195): 56 data bytes --- 31.123.78.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-08-25 14:24:52,362 - WARNING - Ping to IP 31.123.78.195 from VM ctest-vn1-vm1-09888616 failed 2025-08-25 14:24:52,362 - INFO - Adding interface with subnet_id 379a9b43-e749-4a09-ba2c-f069b3c85d74, port_id None to router 5794d3e5-0dd6-4f11-99f0-c5b109b622a9 2025-08-25 14:24:52,630 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-25 14:24:52,630 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 31.123.78.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-08-25 14:24:52,630 - DEBUG - ping -s 56 -c 3 -W 1 31.123.78.195 2025-08-25 14:24:54,842 - DEBUG - PING 31.123.78.195 (31.123.78.195): 56 data bytes 64 bytes from 31.123.78.195: seq=0 ttl=63 time=1.512 ms 64 bytes from 31.123.78.195: seq=1 ttl=63 time=0.951 ms 64 bytes from 31.123.78.195: seq=2 ttl=63 time=0.775 ms --- 31.123.78.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.775/1.079/1.512 ms 2025-08-25 14:24:54,842 - INFO - Ping to IP 31.123.78.195 from VM ctest-vn1-vm1-09888616 passed 2025-08-25 14:24:54,842 - INFO - Deleting interface with subnet_id 99068a89-42b4-441e-84c2-37a036ef44d8, port_id None from router 5794d3e5-0dd6-4f11-99f0-c5b109b622a9 2025-08-25 14:24:55,019 - INFO - Deleting interface with subnet_id 379a9b43-e749-4a09-ba2c-f069b3c85d74, port_id None from router 5794d3e5-0dd6-4f11-99f0-c5b109b622a9 2025-08-25 14:24:55,258 - INFO - Deleting VM ctest-vn2-vm1-97610198 2025-08-25 14:24:55,360 - INFO - Deleting VM ctest-vn1-vm1-09888616 2025-08-25 14:24:55,461 - INFO - Deleting VN ctest-vn2-13529300 2025-08-25 14:24:55,513 - DEBUG - VN c8457c04-29f8-449b-aec8-b0de0e173b7e still in use: Unable to complete operation on network c8457c04-29f8-449b-aec8-b0de0e173b7e. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-3164759f-54f2-4e09-aa7a-3072c6ba925c'] 2025-08-25 14:24:55,513 - WARNING - Deleting VN ctest-vn2-13529300 failed..Will retry 2025-08-25 14:24:57,627 - DEBUG - Response for deleting network () 2025-08-25 14:24:57,627 - INFO - Deleting VN ctest-vn1-87604292 2025-08-25 14:24:57,731 - DEBUG - Response for deleting network () 2025-08-25 14:24:57,997 - DEBUG - No XMPP flaps were noticed during the test
2025-08-25 14:24:57,997 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:35]
2025-08-25 14:24:57,997 - INFO - -------------------------------------------------------------------------------- 2025-08-25 14:24:58,713 - INFO - Deleted project: ctest-TestRouters-72382361, ID : ff0f7db3-73be-40c9-a5fb-883f46cd50ea