2025-11-21 18:33:46,954 - INFO - Domain Default found not creating 2025-11-21 18:33:47,134 - INFO - Project ctest-TestRouters-34000847 not found, creating it 2025-11-21 18:33:47,615 - INFO - Created Project:ctest-TestRouters-34000847, ID : 4a4024be-4408-4db2-87ba-3f16557c5957 2025-11-21 18:33:49,380 - INFO - ================================================================================ 2025-11-21 18:33:49,380 - INFO - STARTING TEST : test_basic_router_behavior 2025-11-21 18:33:49,380 - 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-21 18:33:49,638 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.18': '0'}} with 2025-11-21 18:33:49,638 - INFO - Initial checks done. Running the testcase now 2025-11-21 18:33:49,638 - INFO - 2025-11-21 18:33:50,268 - DEBUG - Response for create_network : {'network': {'id': '8285630f-a798-484f-9094-d438e9c6c5ea', 'name': 'ctest-vn1-74998766', 'tenant_id': '4a4024be44084db287ba3f16557c5957', 'project_id': '4a4024be44084db287ba3f16557c5957', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-34000847', 'ctest-vn1-74998766'], 'port_security_enabled': True, 'description': ''}} 2025-11-21 18:33:50,485 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ba8bb2f7-8c56-4d63-9adc-3b3a656416d2', 'name': '', 'tenant_id': '4a4024be44084db287ba3f16557c5957', 'network_id': '8285630f-a798-484f-9094-d438e9c6c5ea', 'ip_version': 4, 'cidr': '71.146.10.128/26', 'allocation_pools': [{'start': '71.146.10.130', 'end': '71.146.10.190'}], 'gateway_ip': '71.146.10.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '71.146.10.130', 'tags': [], 'project_id': '4a4024be44084db287ba3f16557c5957'}} 2025-11-21 18:33:50,503 - INFO - Created VN ctest-vn1-74998766 2025-11-21 18:33:50,556 - DEBUG - VN ctest-vn1-74998766 UUID is 8285630f-a798-484f-9094-d438e9c6c5ea 2025-11-21 18:33:50,860 - DEBUG - Response for create_network : {'network': {'id': '1dbe4615-3c00-42d0-be4d-b16024c561ce', 'name': 'ctest-vn2-36041236', 'tenant_id': '4a4024be44084db287ba3f16557c5957', 'project_id': '4a4024be44084db287ba3f16557c5957', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRouters-34000847', 'ctest-vn2-36041236'], 'port_security_enabled': True, 'description': ''}} 2025-11-21 18:33:51,046 - DEBUG - Response for create_subnet : {'subnet': {'id': 'dc412ef3-c9b6-4dd3-b819-40414a3bac64', 'name': '', 'tenant_id': '4a4024be44084db287ba3f16557c5957', 'network_id': '1dbe4615-3c00-42d0-be4d-b16024c561ce', 'ip_version': 4, 'cidr': '148.83.245.64/26', 'allocation_pools': [{'start': '148.83.245.66', 'end': '148.83.245.126'}], 'gateway_ip': '148.83.245.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '148.83.245.66', 'tags': [], 'project_id': '4a4024be44084db287ba3f16557c5957'}} 2025-11-21 18:33:51,068 - INFO - Created VN ctest-vn2-36041236 2025-11-21 18:33:51,120 - DEBUG - VN ctest-vn2-36041236 UUID is 1dbe4615-3c00-42d0-be4d-b16024c561ce 2025-11-21 18:33:51,338 - DEBUG - Services list from nova: [, , ] 2025-11-21 18:33:52,525 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4793-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4793-1) 2025-11-21 18:33:53,809 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-4793-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-4793-1) 2025-11-21 18:33:53,890 - INFO - Adding interface with subnet_id ba8bb2f7-8c56-4d63-9adc-3b3a656416d2, port_id None to router d388e51c-ac74-4765-9c0d-881a2a9035c6 2025-11-21 18:33:54,212 - INFO - Waiting for VM ctest-vn1-vm1-66358743 to be up.. 2025-11-21 18:33:54,321 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-11-21 18:33:59,407 - DEBUG - VM is in ACTIVE state now 2025-11-21 18:33:59,407 - INFO - VM name : ctest-vn1-vm1-66358743 2025-11-21 18:33:59,488 - DEBUG - VM ctest-vn1-vm1-66358743 ID is 3bb38625-568b-4101-a2df-569841401c44 2025-11-21 18:33:59,509 - DEBUG - VM ctest-vn1-vm1-66358743 launched on Node cn-jenkins-deploy-platform-ansible-os-4793-1 2025-11-21 18:33:59,589 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/3bb38625-568b-4101-a2df-569841401c44 2025-11-21 18:33:59,924 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/3bb38625-568b-4101-a2df-569841401c44 2025-11-21 18:33:59,959 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/388c9d7b-6008-4a7d-abba-7853605a743e 2025-11-21 18:34:03,223 - 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 1014ms') 2025-11-21 18:34:03,223 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-66358743 failed! 2025-11-21 18:34:03,280 - DEBUG - Gateway for vn default-domain:ctest-TestRouters-34000847:ctest-vn1-74998766 is 71.146.10.129 and allocation pool is NOT set 2025-11-21 18:34:05,347 - 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=4.15 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.516 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.516/2.335/4.154/1.819 ms') 2025-11-21 18:34:05,347 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm1-66358743 passed 2025-11-21 18:34:05,500 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-21 18:34:05,501 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-66358743, IP 71.146.10.131, Port 22 2025-11-21 18:34:05,690 - DEBUG - VM ctest-vn1-vm1-66358743 is ready for SSH connections 2025-11-21 18:34:05,690 - INFO - Waiting for VM ctest-vn2-vm1-49407239 to be up.. 2025-11-21 18:34:05,785 - DEBUG - VM is in ACTIVE state now 2025-11-21 18:34:05,785 - INFO - VM name : ctest-vn2-vm1-49407239 2025-11-21 18:34:05,869 - DEBUG - VM ctest-vn2-vm1-49407239 ID is 3890a955-41aa-48ea-8ae8-6bde46d95f9e 2025-11-21 18:34:05,869 - DEBUG - VM ctest-vn2-vm1-49407239 launched on Node cn-jenkins-deploy-platform-ansible-os-4793-1 2025-11-21 18:34:05,958 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine/3890a955-41aa-48ea-8ae8-6bde46d95f9e 2025-11-21 18:34:05,968 - DEBUG - Requesting: http://10.0.0.22:8082/virtual-machine-interface/54771194-3ed1-4030-9689-f3d0e39b8b0b 2025-11-21 18:34:07,212 - 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.06 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.515 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.515/1.788/3.062/1.273 ms') 2025-11-21 18:34:07,212 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn2-vm1-49407239 passed 2025-11-21 18:34:07,364 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-21 18:34:07,364 - DEBUG - Waiting to SSH to VM ctest-vn2-vm1-49407239, IP 148.83.245.67, Port 22 2025-11-21 18:34:07,524 - DEBUG - VM ctest-vn2-vm1-49407239 is ready for SSH connections 2025-11-21 18:34:07,524 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-21 18:34:07,524 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 148.83.245.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-11-21 18:34:07,524 - DEBUG - ping -s 56 -c 3 -W 1 148.83.245.67 2025-11-21 18:34:12,561 - DEBUG - PING 148.83.245.67 (148.83.245.67): 56 data bytes --- 148.83.245.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-21 18:34:12,561 - WARNING - Ping to IP 148.83.245.67 from VM ctest-vn1-vm1-66358743 failed 2025-11-21 18:34:12,562 - INFO - Adding interface with subnet_id dc412ef3-c9b6-4dd3-b819-40414a3bac64, port_id None to router d388e51c-ac74-4765-9c0d-881a2a9035c6 2025-11-21 18:34:12,820 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-21 18:34:12,820 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 148.83.245.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-11-21 18:34:12,821 - DEBUG - ping -s 56 -c 3 -W 1 148.83.245.67 2025-11-21 18:34:16,042 - DEBUG - PING 148.83.245.67 (148.83.245.67): 56 data bytes 64 bytes from 148.83.245.67: seq=1 ttl=63 time=3.115 ms 64 bytes from 148.83.245.67: seq=2 ttl=63 time=0.964 ms --- 148.83.245.67 ping statistics --- 3 packets transmitted, 2 packets received, 33% packet loss round-trip min/avg/max = 0.964/2.039/3.115 ms 2025-11-21 18:34:16,042 - WARNING - Ping to IP 148.83.245.67 from VM ctest-vn1-vm1-66358743 failed 2025-11-21 18:34:17,043 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 148.83.245.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-11-21 18:34:17,043 - DEBUG - ping -s 56 -c 3 -W 1 148.83.245.67 2025-11-21 18:34:19,263 - DEBUG - PING 148.83.245.67 (148.83.245.67): 56 data bytes 64 bytes from 148.83.245.67: seq=0 ttl=63 time=2.197 ms 64 bytes from 148.83.245.67: seq=1 ttl=63 time=0.884 ms 64 bytes from 148.83.245.67: seq=2 ttl=63 time=1.097 ms --- 148.83.245.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.884/1.392/2.197 ms 2025-11-21 18:34:19,263 - INFO - Ping to IP 148.83.245.67 from VM ctest-vn1-vm1-66358743 passed 2025-11-21 18:34:19,263 - INFO - Deleting interface with subnet_id ba8bb2f7-8c56-4d63-9adc-3b3a656416d2, port_id None from router d388e51c-ac74-4765-9c0d-881a2a9035c6 2025-11-21 18:34:19,403 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-21 18:34:19,403 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 148.83.245.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-11-21 18:34:19,403 - DEBUG - ping -s 56 -c 3 -W 1 148.83.245.67 2025-11-21 18:34:22,616 - DEBUG - PING 148.83.245.67 (148.83.245.67): 56 data bytes --- 148.83.245.67 ping statistics --- 3 packets transmitted, 0 packets received, 100% packet loss 2025-11-21 18:34:22,616 - WARNING - Ping to IP 148.83.245.67 from VM ctest-vn1-vm1-66358743 failed 2025-11-21 18:34:22,617 - INFO - Adding interface with subnet_id ba8bb2f7-8c56-4d63-9adc-3b3a656416d2, port_id None to router d388e51c-ac74-4765-9c0d-881a2a9035c6 2025-11-21 18:34:22,853 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-11-21 18:34:22,853 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 148.83.245.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-11-21 18:34:22,853 - DEBUG - ping -s 56 -c 3 -W 1 148.83.245.67 2025-11-21 18:34:25,070 - DEBUG - PING 148.83.245.67 (148.83.245.67): 56 data bytes 64 bytes from 148.83.245.67: seq=0 ttl=63 time=1.849 ms 64 bytes from 148.83.245.67: seq=1 ttl=63 time=1.046 ms 64 bytes from 148.83.245.67: seq=2 ttl=63 time=0.916 ms --- 148.83.245.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.916/1.270/1.849 ms 2025-11-21 18:34:25,070 - INFO - Ping to IP 148.83.245.67 from VM ctest-vn1-vm1-66358743 passed 2025-11-21 18:34:25,071 - INFO - Deleting interface with subnet_id dc412ef3-c9b6-4dd3-b819-40414a3bac64, port_id None from router d388e51c-ac74-4765-9c0d-881a2a9035c6 2025-11-21 18:34:25,228 - INFO - Deleting interface with subnet_id ba8bb2f7-8c56-4d63-9adc-3b3a656416d2, port_id None from router d388e51c-ac74-4765-9c0d-881a2a9035c6 2025-11-21 18:34:25,421 - INFO - Deleting VM ctest-vn2-vm1-49407239 2025-11-21 18:34:25,511 - INFO - Deleting VM ctest-vn1-vm1-66358743 2025-11-21 18:34:25,593 - INFO - Deleting VN ctest-vn2-36041236 2025-11-21 18:34:25,628 - DEBUG - VN 1dbe4615-3c00-42d0-be4d-b16024c561ce still in use: Unable to complete operation on network 1dbe4615-3c00-42d0-be4d-b16024c561ce. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-2f2b0319-45c8-4a60-a124-ccf85273b164'] 2025-11-21 18:34:25,628 - WARNING - Deleting VN ctest-vn2-36041236 failed..Will retry 2025-11-21 18:34:27,739 - DEBUG - Response for deleting network () 2025-11-21 18:34:27,739 - INFO - Deleting VN ctest-vn1-74998766 2025-11-21 18:34:27,860 - DEBUG - Response for deleting network () 2025-11-21 18:34:28,119 - DEBUG - No XMPP flaps were noticed during the test
2025-11-21 18:34:28,119 - INFO - END TEST : test_basic_router_behavior : PASSED[0:00:39]
2025-11-21 18:34:28,119 - INFO - -------------------------------------------------------------------------------- 2025-11-21 18:34:28,853 - INFO - Deleted project: ctest-TestRouters-34000847, ID : 4a4024be-4408-4db2-87ba-3f16557c5957