2025-07-24 02:35:52,255 - INFO - Domain Default found not creating 2025-07-24 02:35:52,382 - INFO - Project ctest-TestRouters-57725376 not found, creating it 2025-07-24 02:35:52,840 - INFO - Created Project:ctest-TestRouters-57725376, ID : 767fb6fb-de10-4708-b121-50252e5e2aed 2025-07-24 02:35:54,277 - INFO - ================================================================================ 2025-07-24 02:35:54,277 - INFO - STARTING TEST : test_basic_router_behavior 2025-07-24 02:35:54,278 - 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-07-24 02:35:54,533 - DEBUG - Nothing to compare xmpp stats {'10.0.0.27': {'10.20.0.241': '0'}} with 2025-07-24 02:35:54,533 - INFO - Initial checks done. Running the testcase now 2025-07-24 02:35:54,533 - INFO - 2025-07-24 02:35:55,077 - DEBUG - Response for create_network : {'network': {'id': '01adfc1b-819f-4e39-807d-b92c9552a698', 'name': 'ctest-vn1-80215038', 'tenant_id': '767fb6fbde104708b12150252e5e2aed', 'project_id': '767fb6fbde104708b12150252e5e2aed', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-57725376', 'ctest-vn1-80215038'], 'port_security_enabled': True, 'description': ''}} 2025-07-24 02:35:55,278 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bd901117-634f-4d09-89c8-1f4cb3f94bcf', 'name': '', 'tenant_id': '767fb6fbde104708b12150252e5e2aed', 'network_id': '01adfc1b-819f-4e39-807d-b92c9552a698', 'ip_version': 4, 'cidr': '33.165.78.128/26', 'allocation_pools': [{'start': '33.165.78.130', 'end': '33.165.78.190'}], 'gateway_ip': '33.165.78.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '33.165.78.130', 'tags': [], 'project_id': '767fb6fbde104708b12150252e5e2aed'}} 2025-07-24 02:35:55,295 - INFO - Created VN ctest-vn1-80215038 2025-07-24 02:35:55,347 - DEBUG - VN ctest-vn1-80215038 UUID is 01adfc1b-819f-4e39-807d-b92c9552a698 2025-07-24 02:35:55,708 - DEBUG - Response for create_network : {'network': {'id': '190f1877-1cb4-49cb-8f15-494d238b221d', 'name': 'ctest-vn2-60922348', 'tenant_id': '767fb6fbde104708b12150252e5e2aed', 'project_id': '767fb6fbde104708b12150252e5e2aed', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-57725376', 'ctest-vn2-60922348'], 'port_security_enabled': True, 'description': ''}} 2025-07-24 02:35:55,909 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ebcdca0a-e54b-45e2-9768-823baadd1c1e', 'name': '', 'tenant_id': '767fb6fbde104708b12150252e5e2aed', 'network_id': '190f1877-1cb4-49cb-8f15-494d238b221d', 'ip_version': 4, 'cidr': '3.197.220.0/26', 'allocation_pools': [{'start': '3.197.220.2', 'end': '3.197.220.62'}], 'gateway_ip': '3.197.220.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3.197.220.2', 'tags': [], 'project_id': '767fb6fbde104708b12150252e5e2aed'}} 2025-07-24 02:35:55,929 - INFO - Created VN ctest-vn2-60922348 2025-07-24 02:35:55,983 - DEBUG - VN ctest-vn2-60922348 UUID is 190f1877-1cb4-49cb-8f15-494d238b221d 2025-07-24 02:35:56,231 - DEBUG - Services list from nova: [, , ] 2025-07-24 02:35:57,204 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3713-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3713-1) 2025-07-24 02:35:58,435 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3713-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3713-1) 2025-07-24 02:35:58,504 - INFO - Adding interface with subnet_id bd901117-634f-4d09-89c8-1f4cb3f94bcf, port_id None to router 0e918367-8836-488a-884d-7d46122cf08e 2025-07-24 02:35:58,762 - INFO - Waiting for VM ctest-vn1-vm1-28276318 to be up.. 2025-07-24 02:35:58,857 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-07-24 02:36:03,930 - DEBUG - VM is in ACTIVE state now 2025-07-24 02:36:03,930 - INFO - VM name : ctest-vn1-vm1-28276318 2025-07-24 02:36:03,994 - DEBUG - VM ctest-vn1-vm1-28276318 ID is 1f82a458-49b1-411e-a46e-525929a78f2b 2025-07-24 02:36:04,011 - DEBUG - VM ctest-vn1-vm1-28276318 launched on Node cn-jenkins-deploy-platform-ansible-os-3713-1 2025-07-24 02:36:04,077 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/1f82a458-49b1-411e-a46e-525929a78f2b 2025-07-24 02:36:04,375 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/1f82a458-49b1-411e-a46e-525929a78f2b 2025-07-24 02:36:04,406 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/03ed2c72-157f-4b65-9fd2-0453dd45449d 2025-07-24 02:36:07,665 - 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 1015ms') 2025-07-24 02:36:07,665 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-28276318 failed! 2025-07-24 02:36:07,723 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-57725376:ctest-vn1-80215038 is 33.165.78.129 and allocation pool is NOT set 2025-07-24 02:36:09,789 - 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=2.79 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.425 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.425/1.606/2.788/1.181 ms') 2025-07-24 02:36:09,789 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-28276318 passed 2025-07-24 02:36:09,943 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 02:36:09,943 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-28276318, IP 33.165.78.131, Port 22 2025-07-24 02:36:10,094 - DEBUG - VM ctest-vn1-vm1-28276318 is ready for SSH connections 2025-07-24 02:36:10,094 - INFO - Waiting for VM ctest-vn2-vm1-00293391 to be up.. 2025-07-24 02:36:10,172 - DEBUG - VM is in ACTIVE state now 2025-07-24 02:36:10,172 - INFO - VM name : ctest-vn2-vm1-00293391 2025-07-24 02:36:10,236 - DEBUG - VM ctest-vn2-vm1-00293391 ID is 2c682c66-a1c9-4927-b67a-14b2d5e859b5 2025-07-24 02:36:10,236 - DEBUG - VM ctest-vn2-vm1-00293391 launched on Node cn-jenkins-deploy-platform-ansible-os-3713-1 2025-07-24 02:36:10,301 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/2c682c66-a1c9-4927-b67a-14b2d5e859b5 2025-07-24 02:36:10,309 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/a25f3e95-d439-474f-bad9-24ac8f52f498 2025-07-24 02:36:11,557 - 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.15 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.406 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.406/1.780/3.154/1.374 ms') 2025-07-24 02:36:11,557 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-00293391 passed 2025-07-24 02:36:11,711 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 02:36:11,711 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-00293391, IP 3.197.220.3, Port 22 2025-07-24 02:36:11,867 - DEBUG - VM ctest-vn2-vm1-00293391 is ready for SSH connections 2025-07-24 02:36:11,867 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 02:36:11,867 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 3.197.220.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2025-07-24 02:36:11,867 - DEBUG - ping -s 56 -c 3 -W 1 3.197.220.3 2025-07-24 02:36:16,846 - DEBUG - PING 3.197.220.3 (3.197.220.3): 56 data bytes --- 3.197.220.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-07-24 02:36:16,847 - WARNING - Ping to IP 3.197.220.3 from VM ctest-vn1-vm1-28276318 failed 2025-07-24 02:36:16,847 - INFO - Adding interface with subnet_id ebcdca0a-e54b-45e2-9768-823baadd1c1e, port_id None to router 0e918367-8836-488a-884d-7d46122cf08e 2025-07-24 02:36:17,106 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 02:36:17,106 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 3.197.220.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2025-07-24 02:36:17,106 - DEBUG - ping -s 56 -c 3 -W 1 3.197.220.3 2025-07-24 02:36:20,295 - DEBUG - PING 3.197.220.3 (3.197.220.3): 56 data bytes 64 bytes from 3.197.220.3: seq=1 ttl=63 time=3.508 ms 64 bytes from 3.197.220.3: seq=2 ttl=63 time=0.782 ms --- 3.197.220.3 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 0.782/2.145/3.508 ms 2025-07-24 02:36:20,295 - WARNING - Ping to IP 3.197.220.3 from VM ctest-vn1-vm1-28276318 failed 2025-07-24 02:36:21,296 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 3.197.220.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2025-07-24 02:36:21,296 - DEBUG - ping -s 56 -c 3 -W 1 3.197.220.3 2025-07-24 02:36:23,492 - DEBUG - PING 3.197.220.3 (3.197.220.3): 56 data bytes 64 bytes from 3.197.220.3: seq=0 ttl=63 time=5.847 ms 64 bytes from 3.197.220.3: seq=1 ttl=63 time=0.912 ms 64 bytes from 3.197.220.3: seq=2 ttl=63 time=0.904 ms --- 3.197.220.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.904/2.554/5.847 ms 2025-07-24 02:36:23,492 - INFO - Ping to IP 3.197.220.3 from VM ctest-vn1-vm1-28276318 passed 2025-07-24 02:36:23,493 - INFO - Deleting interface with subnet_id bd901117-634f-4d09-89c8-1f4cb3f94bcf, port_id None from router 0e918367-8836-488a-884d-7d46122cf08e 2025-07-24 02:36:23,619 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 02:36:23,619 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 3.197.220.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2025-07-24 02:36:23,620 - DEBUG - ping -s 56 -c 3 -W 1 3.197.220.3 2025-07-24 02:36:26,815 - DEBUG - PING 3.197.220.3 (3.197.220.3): 56 data bytes --- 3.197.220.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-07-24 02:36:26,815 - WARNING - Ping to IP 3.197.220.3 from VM ctest-vn1-vm1-28276318 failed 2025-07-24 02:36:26,815 - INFO - Adding interface with subnet_id bd901117-634f-4d09-89c8-1f4cb3f94bcf, port_id None to router 0e918367-8836-488a-884d-7d46122cf08e 2025-07-24 02:36:27,038 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-07-24 02:36:27,039 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 3.197.220.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.27, gateway password: c0ntrail123 2025-07-24 02:36:27,039 - DEBUG - ping -s 56 -c 3 -W 1 3.197.220.3 2025-07-24 02:36:29,242 - DEBUG - PING 3.197.220.3 (3.197.220.3): 56 data bytes 64 bytes from 3.197.220.3: seq=0 ttl=63 time=2.047 ms 64 bytes from 3.197.220.3: seq=1 ttl=63 time=0.836 ms 64 bytes from 3.197.220.3: seq=2 ttl=63 time=0.920 ms --- 3.197.220.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.836/1.267/2.047 ms 2025-07-24 02:36:29,242 - INFO - Ping to IP 3.197.220.3 from VM ctest-vn1-vm1-28276318 passed 2025-07-24 02:36:29,242 - INFO - Deleting interface with subnet_id ebcdca0a-e54b-45e2-9768-823baadd1c1e, port_id None from router 0e918367-8836-488a-884d-7d46122cf08e 2025-07-24 02:36:29,369 - INFO - Deleting interface with subnet_id bd901117-634f-4d09-89c8-1f4cb3f94bcf, port_id None from router 0e918367-8836-488a-884d-7d46122cf08e 2025-07-24 02:36:29,651 - INFO - Deleting VM ctest-vn2-vm1-00293391 2025-07-24 02:36:29,729 - INFO - Deleting VM ctest-vn1-vm1-28276318 2025-07-24 02:36:29,828 - INFO - Deleting VN ctest-vn2-60922348 2025-07-24 02:36:29,867 - DEBUG - VN 190f1877-1cb4-49cb-8f15-494d238b221d still in use: Unable to complete operation on network 190f1877-1cb4-49cb-8f15-494d238b221d. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-9917eacd-e838-41a8-a3a6-65e29fca4cdd'] 2025-07-24 02:36:29,867 - WARNING - Deleting VN ctest-vn2-60922348 failed..Will retry 2025-07-24 02:36:31,962 - DEBUG - Response for deleting network () 2025-07-24 02:36:31,962 - INFO - Deleting VN ctest-vn1-80215038 2025-07-24 02:36:32,061 - DEBUG - Response for deleting network () 2025-07-24 02:36:32,317 - DEBUG - No XMPP flaps were noticed during the test
2025-07-24 02:36:32,317 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:38]
2025-07-24 02:36:32,317 - INFO - -------------------------------------------------------------------------------- 2025-07-24 02:36:32,978 - INFO - Deleted project: ctest-TestRouters-57725376, ID : 767fb6fb-de10-4708-b121-50252e5e2aed