2025-08-18 15:37:02,183 - INFO - Domain Default found not creating 2025-08-18 15:37:02,336 - INFO - Project ctest-TestRouters-08337268 not found, creating it 2025-08-18 15:37:02,826 - INFO - Created Project:ctest-TestRouters-08337268, ID : 5c2f9b9b-564c-454b-83cd-015e0159335e 2025-08-18 15:37:04,437 - INFO - ================================================================================ 2025-08-18 15:37:04,438 - INFO - STARTING TEST : test_basic_router_behavior 2025-08-18 15:37:04,438 - 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-18 15:37:04,699 - DEBUG - Nothing to compare xmpp stats {'10.0.0.254': {'10.0.0.254': '0'}} with 2025-08-18 15:37:04,699 - INFO - Initial checks done. Running the testcase now 2025-08-18 15:37:04,700 - INFO - 2025-08-18 15:37:05,334 - DEBUG - Response for create_network : {'network': {'id': 'c00d51d1-a17d-4670-af5d-2f68be1cdc65', 'name': 'ctest-vn1-50834540', 'tenant_id': '5c2f9b9b564c454b83cd015e0159335e', 'project_id': '5c2f9b9b564c454b83cd015e0159335e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-08337268', 'ctest-vn1-50834540'], 'port_security_enabled': True, 'description': ''}} 2025-08-18 15:37:05,527 - DEBUG - Response for create_subnet : {'subnet': {'id': '82cab816-48f5-4b20-ac14-5ef25fd4d562', 'name': '', 'tenant_id': '5c2f9b9b564c454b83cd015e0159335e', 'network_id': 'c00d51d1-a17d-4670-af5d-2f68be1cdc65', 'ip_version': 4, 'cidr': '6.238.162.128/26', 'allocation_pools': [{'start': '6.238.162.130', 'end': '6.238.162.190'}], 'gateway_ip': '6.238.162.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '6.238.162.130', 'tags': [], 'project_id': '5c2f9b9b564c454b83cd015e0159335e'}} 2025-08-18 15:37:05,546 - INFO - Created VN ctest-vn1-50834540 2025-08-18 15:37:05,598 - DEBUG - VN ctest-vn1-50834540 UUID is c00d51d1-a17d-4670-af5d-2f68be1cdc65 2025-08-18 15:37:05,921 - DEBUG - Response for create_network : {'network': {'id': 'bcf22097-ca68-489a-8a78-4c7a3bde2e81', 'name': 'ctest-vn2-23599963', 'tenant_id': '5c2f9b9b564c454b83cd015e0159335e', 'project_id': '5c2f9b9b564c454b83cd015e0159335e', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-08337268', 'ctest-vn2-23599963'], 'port_security_enabled': True, 'description': ''}} 2025-08-18 15:37:06,095 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b5fa180c-031e-4e92-9572-1d809cc4ca4d', 'name': '', 'tenant_id': '5c2f9b9b564c454b83cd015e0159335e', 'network_id': 'bcf22097-ca68-489a-8a78-4c7a3bde2e81', 'ip_version': 4, 'cidr': '49.226.186.0/26', 'allocation_pools': [{'start': '49.226.186.2', 'end': '49.226.186.62'}], 'gateway_ip': '49.226.186.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '49.226.186.2', 'tags': [], 'project_id': '5c2f9b9b564c454b83cd015e0159335e'}} 2025-08-18 15:37:06,112 - INFO - Created VN ctest-vn2-23599963 2025-08-18 15:37:06,161 - DEBUG - VN ctest-vn2-23599963 UUID is bcf22097-ca68-489a-8a78-4c7a3bde2e81 2025-08-18 15:37:06,360 - DEBUG - Services list from nova: [, , ] 2025-08-18 15:37:07,445 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3878-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3878-1) 2025-08-18 15:37:08,816 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-3878-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-3878-1) 2025-08-18 15:37:08,894 - INFO - Adding interface with subnet_id 82cab816-48f5-4b20-ac14-5ef25fd4d562, port_id None to router fea18d86-5015-4830-8166-46c3534bf29f 2025-08-18 15:37:09,206 - INFO - Waiting for VM ctest-vn1-vm1-87955057 to be up.. 2025-08-18 15:37:09,317 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-18 15:37:14,398 - DEBUG - VM is in ACTIVE state now 2025-08-18 15:37:14,399 - INFO - VM name : ctest-vn1-vm1-87955057 2025-08-18 15:37:14,481 - DEBUG - VM ctest-vn1-vm1-87955057 ID is dff08fd3-0bf4-4416-9101-7b199ff41006 2025-08-18 15:37:14,500 - DEBUG - VM ctest-vn1-vm1-87955057 launched on Node cn-jenkins-deploy-platform-ansible-os-3878-1. 2025-08-18 15:37:14,584 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/dff08fd3-0bf4-4416-9101-7b199ff41006 2025-08-18 15:37:14,930 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/dff08fd3-0bf4-4416-9101-7b199ff41006 2025-08-18 15:37:14,973 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/819f4868-7da5-49e4-80a0-93619d3e10c8 2025-08-18 15:37:18,239 - 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 1026ms') 2025-08-18 15:37:18,239 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-87955057 failed! 2025-08-18 15:37:18,310 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-08337268:ctest-vn1-50834540 is 6.238.162.129 and allocation pool is NOT set 2025-08-18 15:37:20,375 - 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=7.69 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.474 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.474/4.082/7.691/3.608 ms') 2025-08-18 15:37:20,375 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-87955057 passed 2025-08-18 15:37:20,534 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-18 15:37:20,534 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-87955057, IP 6.238.162.131, Port 22 2025-08-18 15:37:20,699 - DEBUG - VM ctest-vn1-vm1-87955057 is ready for SSH connections 2025-08-18 15:37:20,699 - INFO - Waiting for VM ctest-vn2-vm1-13308103 to be up.. 2025-08-18 15:37:20,786 - DEBUG - VM is in ACTIVE state now 2025-08-18 15:37:20,786 - INFO - VM name : ctest-vn2-vm1-13308103 2025-08-18 15:37:20,859 - DEBUG - VM ctest-vn2-vm1-13308103 ID is a672316b-b81d-4dfa-bd78-c590f9a8f940 2025-08-18 15:37:20,859 - DEBUG - VM ctest-vn2-vm1-13308103 launched on Node cn-jenkins-deploy-platform-ansible-os-3878-1. 2025-08-18 15:37:20,939 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine/a672316b-b81d-4dfa-bd78-c590f9a8f940 2025-08-18 15:37:20,947 - DEBUG - Requesting: http://10.0.0.254:8082/virtual-machine-interface/09a2ccaa-611a-4823-84a8-143aff501af6 2025-08-18 15:37:22,183 - 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.77 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.438 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.438/2.104/3.770/1.666 ms') 2025-08-18 15:37:22,183 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-13308103 passed 2025-08-18 15:37:22,334 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-18 15:37:22,334 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-13308103, IP 49.226.186.3, Port 22 2025-08-18 15:37:22,505 - DEBUG - VM ctest-vn2-vm1-13308103 is ready for SSH connections 2025-08-18 15:37:22,505 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-18 15:37:22,505 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 49.226.186.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-08-18 15:37:22,505 - DEBUG - ping -s 56 -c 3 -W 1 49.226.186.3 2025-08-18 15:37:27,178 - DEBUG - PING 49.226.186.3 (49.226.186.3): 56 data bytes --- 49.226.186.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-08-18 15:37:27,179 - WARNING - Ping to IP 49.226.186.3 from VM ctest-vn1-vm1-87955057 failed 2025-08-18 15:37:27,179 - INFO - Adding interface with subnet_id b5fa180c-031e-4e92-9572-1d809cc4ca4d, port_id None to router fea18d86-5015-4830-8166-46c3534bf29f 2025-08-18 15:37:27,459 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-18 15:37:27,460 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 49.226.186.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-08-18 15:37:27,460 - DEBUG - ping -s 56 -c 3 -W 1 49.226.186.3 2025-08-18 15:37:30,683 - DEBUG - PING 49.226.186.3 (49.226.186.3): 56 data bytes 64 bytes from 49.226.186.3: seq=1 ttl=63 time=2.523 ms 64 bytes from 49.226.186.3: seq=2 ttl=63 time=0.927 ms --- 49.226.186.3 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 0.927/1.725/2.523 ms 2025-08-18 15:37:30,683 - WARNING - Ping to IP 49.226.186.3 from VM ctest-vn1-vm1-87955057 failed 2025-08-18 15:37:31,685 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 49.226.186.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-08-18 15:37:31,685 - DEBUG - ping -s 56 -c 3 -W 1 49.226.186.3 2025-08-18 15:37:33,897 - DEBUG - PING 49.226.186.3 (49.226.186.3): 56 data bytes 64 bytes from 49.226.186.3: seq=0 ttl=63 time=1.528 ms 64 bytes from 49.226.186.3: seq=1 ttl=63 time=0.732 ms 64 bytes from 49.226.186.3: seq=2 ttl=63 time=1.005 ms --- 49.226.186.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.732/1.088/1.528 ms 2025-08-18 15:37:33,897 - INFO - Ping to IP 49.226.186.3 from VM ctest-vn1-vm1-87955057 passed 2025-08-18 15:37:33,898 - INFO - Deleting interface with subnet_id 82cab816-48f5-4b20-ac14-5ef25fd4d562, port_id None from router fea18d86-5015-4830-8166-46c3534bf29f 2025-08-18 15:37:34,033 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-18 15:37:34,033 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 49.226.186.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-08-18 15:37:34,033 - DEBUG - ping -s 56 -c 3 -W 1 49.226.186.3 2025-08-18 15:37:37,248 - DEBUG - PING 49.226.186.3 (49.226.186.3): 56 data bytes --- 49.226.186.3 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-08-18 15:37:37,248 - WARNING - Ping to IP 49.226.186.3 from VM ctest-vn1-vm1-87955057 failed 2025-08-18 15:37:37,248 - INFO - Adding interface with subnet_id 82cab816-48f5-4b20-ac14-5ef25fd4d562, port_id None to router fea18d86-5015-4830-8166-46c3534bf29f 2025-08-18 15:37:37,488 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-18 15:37:37,488 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 49.226.186.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.254, gateway password: c0ntrail123 2025-08-18 15:37:37,488 - DEBUG - ping -s 56 -c 3 -W 1 49.226.186.3 2025-08-18 15:37:39,695 - DEBUG - PING 49.226.186.3 (49.226.186.3): 56 data bytes 64 bytes from 49.226.186.3: seq=0 ttl=63 time=1.345 ms 64 bytes from 49.226.186.3: seq=1 ttl=63 time=0.773 ms 64 bytes from 49.226.186.3: seq=2 ttl=63 time=0.834 ms --- 49.226.186.3 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.773/0.984/1.345 ms 2025-08-18 15:37:39,696 - INFO - Ping to IP 49.226.186.3 from VM ctest-vn1-vm1-87955057 passed 2025-08-18 15:37:39,696 - INFO - Deleting interface with subnet_id b5fa180c-031e-4e92-9572-1d809cc4ca4d, port_id None from router fea18d86-5015-4830-8166-46c3534bf29f 2025-08-18 15:37:39,822 - INFO - Deleting interface with subnet_id 82cab816-48f5-4b20-ac14-5ef25fd4d562, port_id None from router fea18d86-5015-4830-8166-46c3534bf29f 2025-08-18 15:37:40,026 - INFO - Deleting VM ctest-vn2-vm1-13308103 2025-08-18 15:37:40,103 - INFO - Deleting VM ctest-vn1-vm1-87955057 2025-08-18 15:37:40,179 - INFO - Deleting VN ctest-vn2-23599963 2025-08-18 15:37:40,219 - DEBUG - VN bcf22097-ca68-489a-8a78-4c7a3bde2e81 still in use: Unable to complete operation on network bcf22097-ca68-489a-8a78-4c7a3bde2e81. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-8a4605fe-4d2e-416a-bc84-0883979d074a'] 2025-08-18 15:37:40,219 - WARNING - Deleting VN ctest-vn2-23599963 failed..Will retry 2025-08-18 15:37:42,318 - DEBUG - Response for deleting network () 2025-08-18 15:37:42,318 - INFO - Deleting VN ctest-vn1-50834540 2025-08-18 15:37:42,435 - DEBUG - Response for deleting network () 2025-08-18 15:37:42,699 - DEBUG - No XMPP flaps were noticed during the test
2025-08-18 15:37:42,699 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:38]
2025-08-18 15:37:42,699 - INFO - -------------------------------------------------------------------------------- 2025-08-18 15:37:43,446 - INFO - Deleted project: ctest-TestRouters-08337268, ID : 5c2f9b9b-564c-454b-83cd-015e0159335e