2025-11-18 01:10:13,953 - INFO - Domain Default found not creating 2025-11-18 01:10:14,138 - INFO - Project ctest-TestRouters-60025480 not found, creating it 2025-11-18 01:10:14,612 - INFO - Created Project:ctest-TestRouters-60025480, ID : eab4bc97-430c-4cfd-a863-f3259980716f 2025-11-18 01:10:16,588 - INFO - ================================================================================ 2025-11-18 01:10:16,588 - INFO - STARTING TEST : test_basic_router_behavior 2025-11-18 01:10:16,588 - 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-18 01:10:16,847 - DEBUG - Nothing to compare xmpp stats {'10.0.0.242': {'10.20.0.254': '0'}} with 2025-11-18 01:10:16,847 - INFO - Initial checks done. Running the testcase now 2025-11-18 01:10:16,847 - INFO - 2025-11-18 01:10:17,548 - DEBUG - Response for create_network : {'network': {'id': '5cc9e5ca-5c19-4eb9-a508-73b01113acff', 'name': 'ctest-vn1-26553851', 'tenant_id': 'eab4bc97430c4cfda863f3259980716f', 'project_id': 'eab4bc97430c4cfda863f3259980716f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-60025480', 'ctest-vn1-26553851'], 'port_security_enabled': True, 'description': ''}} 2025-11-18 01:10:17,757 - DEBUG - Response for create_subnet : {'subnet': {'id': '9615f884-57e7-42cf-8dc2-7f7efe84b95b', 'name': '', 'tenant_id': 'eab4bc97430c4cfda863f3259980716f', 'network_id': '5cc9e5ca-5c19-4eb9-a508-73b01113acff', 'ip_version': 4, 'cidr': '103.186.121.192/26', 'allocation_pools': [{'start': '103.186.121.194', 'end': '103.186.121.254'}], 'gateway_ip': '103.186.121.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '103.186.121.194', 'tags': [], 'project_id': 'eab4bc97430c4cfda863f3259980716f'}} 2025-11-18 01:10:17,775 - INFO - Created VN ctest-vn1-26553851 2025-11-18 01:10:17,826 - DEBUG - VN ctest-vn1-26553851 UUID is 5cc9e5ca-5c19-4eb9-a508-73b01113acff 2025-11-18 01:10:18,147 - DEBUG - Response for create_network : {'network': {'id': '54d10a9a-359c-471f-8431-e2733950ed69', 'name': 'ctest-vn2-12312398', 'tenant_id': 'eab4bc97430c4cfda863f3259980716f', 'project_id': 'eab4bc97430c4cfda863f3259980716f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-60025480', 'ctest-vn2-12312398'], 'port_security_enabled': True, 'description': ''}} 2025-11-18 01:10:18,393 - DEBUG - Response for create_subnet : {'subnet': {'id': '2dbdc561-472e-4abc-987a-66cb7b64cbc3', 'name': '', 'tenant_id': 'eab4bc97430c4cfda863f3259980716f', 'network_id': '54d10a9a-359c-471f-8431-e2733950ed69', 'ip_version': 4, 'cidr': '134.202.191.192/26', 'allocation_pools': [{'start': '134.202.191.194', 'end': '134.202.191.254'}], 'gateway_ip': '134.202.191.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '134.202.191.194', 'tags': [], 'project_id': 'eab4bc97430c4cfda863f3259980716f'}} 2025-11-18 01:10:18,414 - INFO - Created VN ctest-vn2-12312398 2025-11-18 01:10:18,466 - DEBUG - VN ctest-vn2-12312398 UUID is 54d10a9a-359c-471f-8431-e2733950ed69 2025-11-18 01:10:18,651 - DEBUG - Services list from nova: [, , ] 2025-11-18 01:10:19,712 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4751-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4751-1) 2025-11-18 01:10:20,977 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4751-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4751-1) 2025-11-18 01:10:21,045 - INFO - Adding interface with subnet_id 9615f884-57e7-42cf-8dc2-7f7efe84b95b, port_id None to router c6511f3f-1ea9-4d77-891e-2ad65d1b0f42 2025-11-18 01:10:21,326 - INFO - Waiting for VM ctest-vn1-vm1-37102546 to be up.. 2025-11-18 01:10:21,407 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-18 01:10:26,483 - DEBUG - VM is in ACTIVE state now 2025-11-18 01:10:26,484 - INFO - VM name : ctest-vn1-vm1-37102546 2025-11-18 01:10:26,553 - DEBUG - VM ctest-vn1-vm1-37102546 ID is 57e053c4-547a-4ec3-ac2b-b4cb66bff112 2025-11-18 01:10:26,572 - DEBUG - VM ctest-vn1-vm1-37102546 launched on Node cn-jenkins-deploy-platform-ansible-os-4751-1 2025-11-18 01:10:26,641 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/57e053c4-547a-4ec3-ac2b-b4cb66bff112 2025-11-18 01:10:26,935 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/57e053c4-547a-4ec3-ac2b-b4cb66bff112 2025-11-18 01:10:26,973 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/fa7961ad-c853-4048-b231-d5a7254153c1 2025-11-18 01:10:30,237 - 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-11-18 01:10:30,237 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-37102546 failed! 2025-11-18 01:10:30,290 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-60025480:ctest-vn1-26553851 is 103.186.121.193 and allocation pool is NOT set 2025-11-18 01:10:32,351 - 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=5.34 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.32 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 = 2.319/3.831/5.344/1.512 ms') 2025-11-18 01:10:32,351 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-37102546 passed 2025-11-18 01:10:32,502 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 01:10:32,502 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-37102546, IP 103.186.121.195, Port 22 2025-11-18 01:10:32,657 - DEBUG - VM ctest-vn1-vm1-37102546 is ready for SSH connections 2025-11-18 01:10:32,657 - INFO - Waiting for VM ctest-vn2-vm1-06063524 to be up.. 2025-11-18 01:10:32,736 - DEBUG - VM is in ACTIVE state now 2025-11-18 01:10:32,737 - INFO - VM name : ctest-vn2-vm1-06063524 2025-11-18 01:10:32,818 - DEBUG - VM ctest-vn2-vm1-06063524 ID is 4e77e5b5-0e1e-46ef-867f-365e80c42b27 2025-11-18 01:10:32,818 - DEBUG - VM ctest-vn2-vm1-06063524 launched on Node cn-jenkins-deploy-platform-ansible-os-4751-1 2025-11-18 01:10:32,904 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/4e77e5b5-0e1e-46ef-867f-365e80c42b27 2025-11-18 01:10:32,912 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/7589f910-b925-4e37-918e-bbefc05b8b25 2025-11-18 01:10:34,152 - 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=2.87 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.598 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.598/1.733/2.869/1.135 ms') 2025-11-18 01:10:34,152 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-06063524 passed 2025-11-18 01:10:34,306 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 01:10:34,306 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-06063524, IP 134.202.191.195, Port 22 2025-11-18 01:10:34,470 - DEBUG - VM ctest-vn2-vm1-06063524 is ready for SSH connections 2025-11-18 01:10:34,470 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 01:10:34,470 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 134.202.191.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.242, gateway password: c0ntrail123 2025-11-18 01:10:34,470 - DEBUG - ping -s 56 -c 3 -W 1 134.202.191.195 2025-11-18 01:10:39,609 - DEBUG - PING 134.202.191.195 (134.202.191.195): 56 data bytes --- 134.202.191.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-18 01:10:39,609 - WARNING - Ping to IP 134.202.191.195 from VM ctest-vn1-vm1-37102546 failed 2025-11-18 01:10:39,609 - INFO - Adding interface with subnet_id 2dbdc561-472e-4abc-987a-66cb7b64cbc3, port_id None to router c6511f3f-1ea9-4d77-891e-2ad65d1b0f42 2025-11-18 01:10:39,855 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 01:10:39,855 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 134.202.191.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.242, gateway password: c0ntrail123 2025-11-18 01:10:39,856 - DEBUG - ping -s 56 -c 3 -W 1 134.202.191.195 2025-11-18 01:10:43,081 - DEBUG - PING 134.202.191.195 (134.202.191.195): 56 data bytes 64 bytes from 134.202.191.195: seq=1 ttl=63 time=3.591 ms 64 bytes from 134.202.191.195: seq=2 ttl=63 time=1.012 ms --- 134.202.191.195 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 1.012/2.301/3.591 ms 2025-11-18 01:10:43,082 - WARNING - Ping to IP 134.202.191.195 from VM ctest-vn1-vm1-37102546 failed 2025-11-18 01:10:44,082 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 134.202.191.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.242, gateway password: c0ntrail123 2025-11-18 01:10:44,083 - DEBUG - ping -s 56 -c 3 -W 1 134.202.191.195 2025-11-18 01:10:46,335 - DEBUG - PING 134.202.191.195 (134.202.191.195): 56 data bytes 64 bytes from 134.202.191.195: seq=0 ttl=63 time=1.681 ms 64 bytes from 134.202.191.195: seq=1 ttl=63 time=0.808 ms 64 bytes from 134.202.191.195: seq=2 ttl=63 time=0.964 ms --- 134.202.191.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.808/1.151/1.681 ms 2025-11-18 01:10:46,335 - INFO - Ping to IP 134.202.191.195 from VM ctest-vn1-vm1-37102546 passed 2025-11-18 01:10:46,335 - INFO - Deleting interface with subnet_id 9615f884-57e7-42cf-8dc2-7f7efe84b95b, port_id None from router c6511f3f-1ea9-4d77-891e-2ad65d1b0f42 2025-11-18 01:10:46,481 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 01:10:46,481 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 134.202.191.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.242, gateway password: c0ntrail123 2025-11-18 01:10:46,481 - DEBUG - ping -s 56 -c 3 -W 1 134.202.191.195 2025-11-18 01:10:49,719 - DEBUG - PING 134.202.191.195 (134.202.191.195): 56 data bytes --- 134.202.191.195 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-18 01:10:49,720 - WARNING - Ping to IP 134.202.191.195 from VM ctest-vn1-vm1-37102546 failed 2025-11-18 01:10:49,720 - INFO - Adding interface with subnet_id 9615f884-57e7-42cf-8dc2-7f7efe84b95b, port_id None to router c6511f3f-1ea9-4d77-891e-2ad65d1b0f42 2025-11-18 01:10:49,980 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-18 01:10:49,980 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 134.202.191.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.242, gateway password: c0ntrail123 2025-11-18 01:10:49,980 - DEBUG - ping -s 56 -c 3 -W 1 134.202.191.195 2025-11-18 01:10:52,220 - DEBUG - PING 134.202.191.195 (134.202.191.195): 56 data bytes 64 bytes from 134.202.191.195: seq=0 ttl=63 time=1.445 ms 64 bytes from 134.202.191.195: seq=1 ttl=63 time=0.967 ms 64 bytes from 134.202.191.195: seq=2 ttl=63 time=0.931 ms --- 134.202.191.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.931/1.114/1.445 ms 2025-11-18 01:10:52,221 - INFO - Ping to IP 134.202.191.195 from VM ctest-vn1-vm1-37102546 passed 2025-11-18 01:10:52,221 - INFO - Deleting interface with subnet_id 2dbdc561-472e-4abc-987a-66cb7b64cbc3, port_id None from router c6511f3f-1ea9-4d77-891e-2ad65d1b0f42 2025-11-18 01:10:52,370 - INFO - Deleting interface with subnet_id 9615f884-57e7-42cf-8dc2-7f7efe84b95b, port_id None from router c6511f3f-1ea9-4d77-891e-2ad65d1b0f42 2025-11-18 01:10:52,569 - INFO - Deleting VM ctest-vn2-vm1-06063524 2025-11-18 01:10:52,642 - INFO - Deleting VM ctest-vn1-vm1-37102546 2025-11-18 01:10:52,725 - INFO - Deleting VN ctest-vn2-12312398 2025-11-18 01:10:52,769 - DEBUG - VN 54d10a9a-359c-471f-8431-e2733950ed69 still in use: Unable to complete operation on network 54d10a9a-359c-471f-8431-e2733950ed69. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-31ee044a-4ca1-4442-a1da-253d759f2d8c'] 2025-11-18 01:10:52,770 - WARNING - Deleting VN ctest-vn2-12312398 failed..Will retry 2025-11-18 01:10:54,895 - DEBUG - Response for deleting network () 2025-11-18 01:10:54,895 - INFO - Deleting VN ctest-vn1-26553851 2025-11-18 01:10:55,027 - DEBUG - Response for deleting network () 2025-11-18 01:10:55,302 - DEBUG - No XMPP flaps were noticed during the test
2025-11-18 01:10:55,302 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:39]
2025-11-18 01:10:55,302 - INFO - -------------------------------------------------------------------------------- 2025-11-18 01:10:56,086 - INFO - Deleted project: ctest-TestRouters-60025480, ID : eab4bc97-430c-4cfd-a863-f3259980716f