2025-08-25 15:27:01,983 - INFO - Domain Default found not creating 2025-08-25 15:27:02,125 - INFO - Project ctest-TestRouters-88946755 not found, creating it 2025-08-25 15:27:02,599 - INFO - Created Project:ctest-TestRouters-88946755, ID : bb14b78c-52df-4058-adce-27bf7331a27d 2025-08-25 15:27:04,061 - INFO - ================================================================================ 2025-08-25 15:27:04,061 - INFO - STARTING TEST : test_basic_router_behavior 2025-08-25 15:27:04,062 - 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 15:27:04,324 - DEBUG - Nothing to compare xmpp stats {'10.0.0.254': {'10.20.0.254': '0'}} with 2025-08-25 15:27:04,324 - INFO - Initial checks done. Running the testcase now 2025-08-25 15:27:04,325 - INFO - 2025-08-25 15:27:04,950 - DEBUG - Response for create_network : {'network': {'id': '6a6fd7a0-c410-4372-a14e-e50a4a2e4bf3', 'name': 'ctest-vn1-87806243', 'tenant_id': 'bb14b78c52df4058adce27bf7331a27d', 'project_id': 'bb14b78c52df4058adce27bf7331a27d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-88946755', 'ctest-vn1-87806243'], 'port_security_enabled': True, 'description': ''}} 2025-08-25 15:27:05,174 - DEBUG - Response for create_subnet : {'subnet': {'id': '97d201d9-4d0d-402b-a3d9-8cb1103572be', 'name': '', 'tenant_id': 'bb14b78c52df4058adce27bf7331a27d', 'network_id': '6a6fd7a0-c410-4372-a14e-e50a4a2e4bf3', 'ip_version': 4, 'cidr': '49.163.151.192/26', 'allocation_pools': [{'start': '49.163.151.194', 'end': '49.163.151.254'}], 'gateway_ip': '49.163.151.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '49.163.151.194', 'tags': [], 'project_id': 'bb14b78c52df4058adce27bf7331a27d'}} 2025-08-25 15:27:05,196 - INFO - Created VN ctest-vn1-87806243 2025-08-25 15:27:05,254 - DEBUG - VN ctest-vn1-87806243 UUID is 6a6fd7a0-c410-4372-a14e-e50a4a2e4bf3 2025-08-25 15:27:05,590 - DEBUG - Response for create_network : {'network': {'id': 'd07a9084-e663-4ee6-a44c-6a5f1dbe31df', 'name': 'ctest-vn2-71210578', 'tenant_id': 'bb14b78c52df4058adce27bf7331a27d', 'project_id': 'bb14b78c52df4058adce27bf7331a27d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-88946755', 'ctest-vn2-71210578'], 'port_security_enabled': True, 'description': ''}} 2025-08-25 15:27:05,806 - DEBUG - Response for create_subnet : {'subnet': {'id': '44733e2f-d134-4981-8322-9c9e3c138d1c', 'name': '', 'tenant_id': 'bb14b78c52df4058adce27bf7331a27d', 'network_id': 'd07a9084-e663-4ee6-a44c-6a5f1dbe31df', 'ip_version': 4, 'cidr': '166.99.43.0/26', 'allocation_pools': [{'start': '166.99.43.2', 'end': '166.99.43.62'}], 'gateway_ip': '166.99.43.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '166.99.43.2', 'tags': [], 'project_id': 'bb14b78c52df4058adce27bf7331a27d'}} 2025-08-25 15:27:05,823 - INFO - Created VN ctest-vn2-71210578 2025-08-25 15:27:05,878 - DEBUG - VN ctest-vn2-71210578 UUID is d07a9084-e663-4ee6-a44c-6a5f1dbe31df 2025-08-25 15:27:06,036 - DEBUG - Services list from nova: [, , ] 2025-08-25 15:27:07,020 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3924-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3924-1) 2025-08-25 15:27:08,263 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3924-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3924-1) 2025-08-25 15:27:08,394 - INFO - Adding interface with subnet_id 97d201d9-4d0d-402b-a3d9-8cb1103572be, port_id None to router 4e3e6947-3e21-4a3b-a0c4-31b0e340c366 2025-08-25 15:27:08,748 - INFO - Waiting for VM ctest-vn1-vm1-36951649 to be up.. 2025-08-25 15:27:08,897 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-25 15:27:13,980 - DEBUG - VM is in ACTIVE state now 2025-08-25 15:27:13,980 - INFO - VM name : ctest-vn1-vm1-36951649 2025-08-25 15:27:14,051 - DEBUG - VM ctest-vn1-vm1-36951649 ID is 6c8c9e46-2995-460f-a47f-5f4239b02a1a 2025-08-25 15:27:14,072 - DEBUG - VM ctest-vn1-vm1-36951649 launched on Node cn-jenkins-deploy-platform-ansible-os-3924-1 2025-08-25 15:27:14,153 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/6c8c9e46-2995-460f-a47f-5f4239b02a1a 2025-08-25 15:27:14,474 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/6c8c9e46-2995-460f-a47f-5f4239b02a1a 2025-08-25 15:27:14,505 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/d0a33222-4ff5-4d54-a98f-a236c76d207d 2025-08-25 15:27:17,771 - 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 1004ms') 2025-08-25 15:27:17,771 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-36951649 failed! 2025-08-25 15:27:17,834 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-88946755:ctest-vn1-87806243 is 49.163.151.193 and allocation pool is NOT set 2025-08-25 15:27:19,903 - 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.21 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.398 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.398/1.805/3.213/1.407 ms') 2025-08-25 15:27:19,903 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-36951649 passed 2025-08-25 15:27:20,098 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-25 15:27:20,099 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-36951649, IP 49.163.151.195, Port 22 2025-08-25 15:27:20,269 - DEBUG - VM ctest-vn1-vm1-36951649 is ready for SSH connections 2025-08-25 15:27:20,269 - INFO - Waiting for VM ctest-vn2-vm1-31078503 to be up.. 2025-08-25 15:27:20,381 - DEBUG - VM is in ACTIVE state now 2025-08-25 15:27:20,382 - INFO - VM name : ctest-vn2-vm1-31078503 2025-08-25 15:27:20,450 - DEBUG - VM ctest-vn2-vm1-31078503 ID is 813a0f3f-c584-43ed-a96a-48c478a3de94 2025-08-25 15:27:20,451 - DEBUG - VM ctest-vn2-vm1-31078503 launched on Node cn-jenkins-deploy-platform-ansible-os-3924-1 2025-08-25 15:27:20,521 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/813a0f3f-c584-43ed-a96a-48c478a3de94 2025-08-25 15:27:20,530 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/51c9c0ba-49ab-4e96-8491-7ce90b42fa05 2025-08-25 15:27:21,781 - 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.00 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.378 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.378/1.690/3.003/1.312 ms') 2025-08-25 15:27:21,781 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-31078503 passed 2025-08-25 15:27:21,934 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-25 15:27:21,935 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-31078503, IP 166.99.43.3, Port 22 2025-08-25 15:27:22,104 - DEBUG - VM ctest-vn2-vm1-31078503 is ready for SSH connections 2025-08-25 15:27:22,104 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-25 15:27:22,104 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 166.99.43.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-08-25 15:27:22,104 - DEBUG - ping -s 56 -c 3 -W 1 166.99.43.3 2025-08-25 15:27:26,759 - DEBUG - PING 166.99.43.3 (166.99.43.3): 56 data bytes --- 166.99.43.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-08-25 15:27:26,760 - WARNING - Ping to IP 166.99.43.3 from VM ctest-vn1-vm1-36951649 failed 2025-08-25 15:27:26,760 - INFO - Adding interface with subnet_id 44733e2f-d134-4981-8322-9c9e3c138d1c, port_id None to router 4e3e6947-3e21-4a3b-a0c4-31b0e340c366 2025-08-25 15:27:27,038 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-25 15:27:27,038 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 166.99.43.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-08-25 15:27:27,038 - DEBUG - ping -s 56 -c 3 -W 1 166.99.43.3 2025-08-25 15:27:30,239 - DEBUG - PING 166.99.43.3 (166.99.43.3): 56 data bytes 64 bytes from 166.99.43.3: seq=1 ttl=63 time=2.686 ms 64 bytes from 166.99.43.3: seq=2 ttl=63 time=0.686 ms --- 166.99.43.3 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 0.686/1.686/2.686 ms 2025-08-25 15:27:30,239 - WARNING - Ping to IP 166.99.43.3 from VM ctest-vn1-vm1-36951649 failed 2025-08-25 15:27:31,239 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 166.99.43.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-08-25 15:27:31,240 - DEBUG - ping -s 56 -c 3 -W 1 166.99.43.3 2025-08-25 15:27:33,474 - DEBUG - PING 166.99.43.3 (166.99.43.3): 56 data bytes 64 bytes from 166.99.43.3: seq=0 ttl=63 time=1.579 ms 64 bytes from 166.99.43.3: seq=1 ttl=63 time=0.750 ms 64 bytes from 166.99.43.3: seq=2 ttl=63 time=1.000 ms --- 166.99.43.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.750/1.109/1.579 ms 2025-08-25 15:27:33,474 - INFO - Ping to IP 166.99.43.3 from VM ctest-vn1-vm1-36951649 passed 2025-08-25 15:27:33,474 - INFO - Deleting interface with subnet_id 97d201d9-4d0d-402b-a3d9-8cb1103572be, port_id None from router 4e3e6947-3e21-4a3b-a0c4-31b0e340c366 2025-08-25 15:27:33,642 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-25 15:27:33,642 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 166.99.43.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-08-25 15:27:33,642 - DEBUG - ping -s 56 -c 3 -W 1 166.99.43.3 2025-08-25 15:27:36,840 - DEBUG - PING 166.99.43.3 (166.99.43.3): 56 data bytes --- 166.99.43.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-08-25 15:27:36,840 - WARNING - Ping to IP 166.99.43.3 from VM ctest-vn1-vm1-36951649 failed 2025-08-25 15:27:36,840 - INFO - Adding interface with subnet_id 97d201d9-4d0d-402b-a3d9-8cb1103572be, port_id None to router 4e3e6947-3e21-4a3b-a0c4-31b0e340c366 2025-08-25 15:27:37,084 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-25 15:27:37,084 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 166.99.43.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-08-25 15:27:37,084 - DEBUG - ping -s 56 -c 3 -W 1 166.99.43.3 2025-08-25 15:27:39,283 - DEBUG - PING 166.99.43.3 (166.99.43.3): 56 data bytes 64 bytes from 166.99.43.3: seq=0 ttl=63 time=1.305 ms 64 bytes from 166.99.43.3: seq=1 ttl=63 time=0.961 ms 64 bytes from 166.99.43.3: seq=2 ttl=63 time=0.825 ms --- 166.99.43.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.825/1.030/1.305 ms 2025-08-25 15:27:39,283 - INFO - Ping to IP 166.99.43.3 from VM ctest-vn1-vm1-36951649 passed 2025-08-25 15:27:39,283 - INFO - Deleting interface with subnet_id 44733e2f-d134-4981-8322-9c9e3c138d1c, port_id None from router 4e3e6947-3e21-4a3b-a0c4-31b0e340c366 2025-08-25 15:27:39,415 - INFO - Deleting interface with subnet_id 97d201d9-4d0d-402b-a3d9-8cb1103572be, port_id None from router 4e3e6947-3e21-4a3b-a0c4-31b0e340c366 2025-08-25 15:27:39,608 - INFO - Deleting VM ctest-vn2-vm1-31078503 2025-08-25 15:27:39,684 - INFO - Deleting VM ctest-vn1-vm1-36951649 2025-08-25 15:27:39,751 - INFO - Deleting VN ctest-vn2-71210578 2025-08-25 15:27:39,792 - DEBUG - VN d07a9084-e663-4ee6-a44c-6a5f1dbe31df still in use: Unable to complete operation on network d07a9084-e663-4ee6-a44c-6a5f1dbe31df. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-01c0916a-d333-4989-8202-a9c251919091'] 2025-08-25 15:27:39,792 - WARNING - Deleting VN ctest-vn2-71210578 failed..Will retry 2025-08-25 15:27:41,906 - DEBUG - Response for deleting network () 2025-08-25 15:27:41,906 - INFO - Deleting VN ctest-vn1-87806243 2025-08-25 15:27:42,022 - DEBUG - Response for deleting network () 2025-08-25 15:27:42,279 - DEBUG - No XMPP flaps were noticed during the test
2025-08-25 15:27:42,279 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:38]
2025-08-25 15:27:42,279 - INFO - -------------------------------------------------------------------------------- 2025-08-25 15:27:42,945 - INFO - Deleted project: ctest-TestRouters-88946755, ID : bb14b78c-52df-4058-adce-27bf7331a27d