2025-11-13 18:23:57,362 - INFO - Domain Default found not creating 2025-11-13 18:23:57,664 - INFO - Project ctest-TestRouters-00388562 not found, creating it 2025-11-13 18:23:58,290 - INFO - Created Project:ctest-TestRouters-00388562, ID : a5fed556-779e-445b-a964-697944dc86d5 2025-11-13 18:24:00,576 - INFO - ================================================================================ 2025-11-13 18:24:00,576 - INFO - STARTING TEST : test_basic_router_behavior 2025-11-13 18:24:00,576 - 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-11-13 18:24:00,897 - DEBUG - Nothing to compare xmpp stats {'10.0.0.50': {'10.20.0.254': '0'}} with 2025-11-13 18:24:00,897 - INFO - Initial checks done. Running the testcase now 2025-11-13 18:24:00,897 - INFO - 2025-11-13 18:24:01,646 - DEBUG - Response for create_network : {'network': {'id': 'ba59e68f-470d-4a07-85b5-ce8d9f60e399', 'name': 'ctest-vn1-71664380', 'tenant_id': 'a5fed556779e445ba964697944dc86d5', 'project_id': 'a5fed556779e445ba964697944dc86d5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-00388562', 'ctest-vn1-71664380'], 'port_security_enabled': True, 'description': ''}} 2025-11-13 18:24:01,897 - DEBUG - Response for create_subnet : {'subnet': {'id': '2cf72ca2-ed51-449f-a2b1-d7a524cefc41', 'name': '', 'tenant_id': 'a5fed556779e445ba964697944dc86d5', 'network_id': 'ba59e68f-470d-4a07-85b5-ce8d9f60e399', 'ip_version': 4, 'cidr': '37.156.126.0/26', 'allocation_pools': [{'start': '37.156.126.2', 'end': '37.156.126.62'}], 'gateway_ip': '37.156.126.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '37.156.126.2', 'tags': [], 'project_id': 'a5fed556779e445ba964697944dc86d5'}} 2025-11-13 18:24:01,953 - INFO - Created VN ctest-vn1-71664380 2025-11-13 18:24:02,016 - DEBUG - VN ctest-vn1-71664380 UUID is ba59e68f-470d-4a07-85b5-ce8d9f60e399 2025-11-13 18:24:02,589 - DEBUG - Response for create_network : {'network': {'id': '3d8037db-c4fb-48a9-86f6-fb26de370b2e', 'name': 'ctest-vn2-48142618', 'tenant_id': 'a5fed556779e445ba964697944dc86d5', 'project_id': 'a5fed556779e445ba964697944dc86d5', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-00388562', 'ctest-vn2-48142618'], 'port_security_enabled': True, 'description': ''}} 2025-11-13 18:24:02,756 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a4220a20-d3c6-4351-8a41-024b2a9de58c', 'name': '', 'tenant_id': 'a5fed556779e445ba964697944dc86d5', 'network_id': '3d8037db-c4fb-48a9-86f6-fb26de370b2e', 'ip_version': 4, 'cidr': '222.115.42.192/26', 'allocation_pools': [{'start': '222.115.42.194', 'end': '222.115.42.254'}], 'gateway_ip': '222.115.42.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '222.115.42.194', 'tags': [], 'project_id': 'a5fed556779e445ba964697944dc86d5'}} 2025-11-13 18:24:02,770 - INFO - Created VN ctest-vn2-48142618 2025-11-13 18:24:02,824 - DEBUG - VN ctest-vn2-48142618 UUID is 3d8037db-c4fb-48a9-86f6-fb26de370b2e 2025-11-13 18:24:03,097 - DEBUG - Services list from nova: [, , ] 2025-11-13 18:24:04,394 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4726-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4726-1) 2025-11-13 18:24:05,646 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4726-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4726-1) 2025-11-13 18:24:05,709 - INFO - Adding interface with subnet_id 2cf72ca2-ed51-449f-a2b1-d7a524cefc41, port_id None to router 743a7a3f-de9a-4094-a210-811a51dc1fac 2025-11-13 18:24:05,986 - INFO - Waiting for VM ctest-vn1-vm1-42142740 to be up.. 2025-11-13 18:24:06,058 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-13 18:24:11,244 - DEBUG - VM is in ACTIVE state now 2025-11-13 18:24:11,244 - INFO - VM name : ctest-vn1-vm1-42142740 2025-11-13 18:24:11,377 - DEBUG - VM ctest-vn1-vm1-42142740 ID is 3a30490f-e2cb-4e46-8b70-5b7411ace51e 2025-11-13 18:24:11,419 - DEBUG - VM ctest-vn1-vm1-42142740 launched on Node cn-jenkins-deploy-platform-ansible-os-4726-1 2025-11-13 18:24:11,604 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/3a30490f-e2cb-4e46-8b70-5b7411ace51e 2025-11-13 18:24:11,996 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/3a30490f-e2cb-4e46-8b70-5b7411ace51e 2025-11-13 18:24:12,084 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/50481d18-4ade-42e6-b95a-b3b3cacef313 2025-11-13 18:24:15,407 - 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=8.10 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1010ms\r\nrtt min/avg/max/mdev = 8.102/8.102/8.102/0.000 ms') 2025-11-13 18:24:15,407 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-42142740 passed 2025-11-13 18:24:15,560 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-13 18:24:15,560 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-42142740, IP 37.156.126.3, Port 22 2025-11-13 18:24:15,737 - DEBUG - VM ctest-vn1-vm1-42142740 is ready for SSH connections 2025-11-13 18:24:15,737 - INFO - Waiting for VM ctest-vn2-vm1-95695335 to be up.. 2025-11-13 18:24:15,897 - DEBUG - VM is in ACTIVE state now 2025-11-13 18:24:15,897 - INFO - VM name : ctest-vn2-vm1-95695335 2025-11-13 18:24:16,026 - DEBUG - VM ctest-vn2-vm1-95695335 ID is b008b026-8dda-49be-8458-2c4dccf3466b 2025-11-13 18:24:16,026 - DEBUG - VM ctest-vn2-vm1-95695335 launched on Node cn-jenkins-deploy-platform-ansible-os-4726-1 2025-11-13 18:24:16,134 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/b008b026-8dda-49be-8458-2c4dccf3466b 2025-11-13 18:24:16,148 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/aab87883-c3b5-4194-93d4-5dad33e9cb66 2025-11-13 18:24:17,455 - 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.49 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.889 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.889/2.689/4.490/1.800 ms') 2025-11-13 18:24:17,456 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-95695335 passed 2025-11-13 18:24:17,625 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-13 18:24:17,625 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-95695335, IP 222.115.42.195, Port 22 2025-11-13 18:24:17,831 - DEBUG - VM ctest-vn2-vm1-95695335 is ready for SSH connections 2025-11-13 18:24:17,831 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-13 18:24:17,831 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 222.115.42.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2025-11-13 18:24:17,831 - DEBUG - ping -s 56 -c 3 -W 1 222.115.42.195 2025-11-13 18:24:23,036 - DEBUG - PING 222.115.42.195 (222.115.42.195): 56 data bytes --- 222.115.42.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-13 18:24:23,037 - WARNING - Ping to IP 222.115.42.195 from VM ctest-vn1-vm1-42142740 failed 2025-11-13 18:24:23,037 - INFO - Adding interface with subnet_id a4220a20-d3c6-4351-8a41-024b2a9de58c, port_id None to router 743a7a3f-de9a-4094-a210-811a51dc1fac 2025-11-13 18:24:23,408 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-13 18:24:23,408 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 222.115.42.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2025-11-13 18:24:23,408 - DEBUG - ping -s 56 -c 3 -W 1 222.115.42.195 2025-11-13 18:24:26,584 - DEBUG - PING 222.115.42.195 (222.115.42.195): 56 data bytes 64 bytes from 222.115.42.195: seq=1 ttl=63 time=5.768 ms 64 bytes from 222.115.42.195: seq=2 ttl=63 time=2.477 ms --- 222.115.42.195 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 2.477/4.122/5.768 ms 2025-11-13 18:24:26,584 - WARNING - Ping to IP 222.115.42.195 from VM ctest-vn1-vm1-42142740 failed 2025-11-13 18:24:27,586 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 222.115.42.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2025-11-13 18:24:27,586 - DEBUG - ping -s 56 -c 3 -W 1 222.115.42.195 2025-11-13 18:24:29,761 - DEBUG - PING 222.115.42.195 (222.115.42.195): 56 data bytes 64 bytes from 222.115.42.195: seq=0 ttl=63 time=2.168 ms 64 bytes from 222.115.42.195: seq=1 ttl=63 time=1.774 ms 64 bytes from 222.115.42.195: seq=2 ttl=63 time=1.998 ms --- 222.115.42.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.774/1.980/2.168 ms 2025-11-13 18:24:29,761 - INFO - Ping to IP 222.115.42.195 from VM ctest-vn1-vm1-42142740 passed 2025-11-13 18:24:29,761 - INFO - Deleting interface with subnet_id 2cf72ca2-ed51-449f-a2b1-d7a524cefc41, port_id None from router 743a7a3f-de9a-4094-a210-811a51dc1fac 2025-11-13 18:24:30,003 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-13 18:24:30,003 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 222.115.42.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2025-11-13 18:24:30,003 - DEBUG - ping -s 56 -c 3 -W 1 222.115.42.195 2025-11-13 18:24:33,170 - DEBUG - PING 222.115.42.195 (222.115.42.195): 56 data bytes --- 222.115.42.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-13 18:24:33,171 - WARNING - Ping to IP 222.115.42.195 from VM ctest-vn1-vm1-42142740 failed 2025-11-13 18:24:33,171 - INFO - Adding interface with subnet_id 2cf72ca2-ed51-449f-a2b1-d7a524cefc41, port_id None to router 743a7a3f-de9a-4094-a210-811a51dc1fac 2025-11-13 18:24:33,479 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-13 18:24:33,480 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 222.115.42.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2025-11-13 18:24:33,480 - DEBUG - ping -s 56 -c 3 -W 1 222.115.42.195 2025-11-13 18:24:35,626 - DEBUG - PING 222.115.42.195 (222.115.42.195): 56 data bytes 64 bytes from 222.115.42.195: seq=0 ttl=63 time=1.419 ms 64 bytes from 222.115.42.195: seq=1 ttl=63 time=1.420 ms 64 bytes from 222.115.42.195: seq=2 ttl=63 time=2.121 ms --- 222.115.42.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.419/1.653/2.121 ms 2025-11-13 18:24:35,626 - INFO - Ping to IP 222.115.42.195 from VM ctest-vn1-vm1-42142740 passed 2025-11-13 18:24:35,626 - INFO - Deleting interface with subnet_id a4220a20-d3c6-4351-8a41-024b2a9de58c, port_id None from router 743a7a3f-de9a-4094-a210-811a51dc1fac 2025-11-13 18:24:35,837 - INFO - Deleting interface with subnet_id 2cf72ca2-ed51-449f-a2b1-d7a524cefc41, port_id None from router 743a7a3f-de9a-4094-a210-811a51dc1fac 2025-11-13 18:24:36,039 - INFO - Deleting VM ctest-vn2-vm1-95695335 2025-11-13 18:24:36,112 - INFO - Deleting VM ctest-vn1-vm1-42142740 2025-11-13 18:24:36,192 - INFO - Deleting VN ctest-vn2-48142618 2025-11-13 18:24:36,232 - DEBUG - VN 3d8037db-c4fb-48a9-86f6-fb26de370b2e still in use: Unable to complete operation on network 3d8037db-c4fb-48a9-86f6-fb26de370b2e. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-b0ffe533-d637-48bd-a8a8-f2366925bb76'] 2025-11-13 18:24:36,233 - WARNING - Deleting VN ctest-vn2-48142618 failed..Will retry 2025-11-13 18:24:38,378 - DEBUG - Response for deleting network () 2025-11-13 18:24:38,378 - INFO - Deleting VN ctest-vn1-71664380 2025-11-13 18:24:38,492 - DEBUG - Response for deleting network () 2025-11-13 18:24:38,790 - DEBUG - No XMPP flaps were noticed during the test
2025-11-13 18:24:38,790 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:38]
2025-11-13 18:24:38,790 - INFO - -------------------------------------------------------------------------------- 2025-11-13 18:24:39,580 - INFO - Deleted project: ctest-TestRouters-00388562, ID : a5fed556-779e-445b-a964-697944dc86d5