2026-09-29 17:53:46,860 - INFO - Domain Default found not creating 2026-09-29 17:53:47,028 - INFO - Project ctest-TestRP-95117766 not found, creating it 2026-09-29 17:53:47,514 - INFO - Created Project:ctest-TestRP-95117766, ID : 2b77eaba-5210-41fd-b754-ed2fd8133a22 2026-09-29 17:53:49,313 - DEBUG - Services list from nova: [, , ] 2026-09-29 17:53:49,313 - DEBUG - Hosts: {'nova': ['cn-jenkins-deploy-platform-ansible-os-6328-1']} 2026-09-29 17:53:49,315 - INFO - ================================================================================ 2026-09-29 17:53:49,316 - INFO - STARTING TEST : test_rp_interface 2026-09-29 17:53:49,316 - INFO - TEST DESCRIPTION : 1. Create a routing policy with interface match. 2. Launch VMs. 3. Attach policy to VN and confirm if policy takes hold. 2026-09-29 17:53:49,583 - DEBUG - Nothing to compare xmpp stats {'10.0.0.189': {'10.20.0.25': '0'}} with 2026-09-29 17:53:49,583 - INFO - Initial checks done. Running the testcase now 2026-09-29 17:53:49,583 - INFO - 2026-09-29 17:53:50,214 - DEBUG - Response for create_network : {'network': {'id': 'f497e89a-67a9-4097-b11e-b5fe1f070cba', 'name': 'ctest-bgpaas_vn-62630353', 'tenant_id': '2b77eaba521041fdb754ed2fd8133a22', 'project_id': '2b77eaba521041fdb754ed2fd8133a22', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-95117766', 'ctest-bgpaas_vn-62630353'], 'port_security_enabled': True, 'description': ''}} 2026-09-29 17:53:50,434 - DEBUG - Response for create_subnet : {'subnet': {'id': '04854656-2a1b-4f0e-9272-b5e51ebc1b8d', 'name': '', 'tenant_id': '2b77eaba521041fdb754ed2fd8133a22', 'network_id': 'f497e89a-67a9-4097-b11e-b5fe1f070cba', 'ip_version': 4, 'cidr': '147.154.51.192/26', 'allocation_pools': [{'start': '147.154.51.194', 'end': '147.154.51.254'}], 'gateway_ip': '147.154.51.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '147.154.51.194', 'tags': [], 'project_id': '2b77eaba521041fdb754ed2fd8133a22'}} 2026-09-29 17:53:50,452 - INFO - Created VN ctest-bgpaas_vn-62630353 2026-09-29 17:53:50,507 - DEBUG - VN ctest-bgpaas_vn-62630353 UUID is f497e89a-67a9-4097-b11e-b5fe1f070cba 2026-09-29 17:53:51,014 - DEBUG - Response for create_network : {'network': {'id': '258c73d5-a946-4577-b5fa-5099722148a1', 'name': 'ctest-bgpaas_vn-60783953', 'tenant_id': '2b77eaba521041fdb754ed2fd8133a22', 'project_id': '2b77eaba521041fdb754ed2fd8133a22', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-95117766', 'ctest-bgpaas_vn-60783953'], 'port_security_enabled': True, 'description': ''}} 2026-09-29 17:53:51,206 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c5b7082a-a28f-45a6-89ec-30fe71135308', 'name': '', 'tenant_id': '2b77eaba521041fdb754ed2fd8133a22', 'network_id': '258c73d5-a946-4577-b5fa-5099722148a1', 'ip_version': 4, 'cidr': '59.152.21.192/26', 'allocation_pools': [{'start': '59.152.21.194', 'end': '59.152.21.254'}], 'gateway_ip': '59.152.21.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '59.152.21.194', 'tags': [], 'project_id': '2b77eaba521041fdb754ed2fd8133a22'}} 2026-09-29 17:53:51,223 - INFO - Created VN ctest-bgpaas_vn-60783953 2026-09-29 17:53:51,279 - DEBUG - VN ctest-bgpaas_vn-60783953 UUID is 258c73d5-a946-4577-b5fa-5099722148a1 2026-09-29 17:53:52,004 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6328-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6328-1) 2026-09-29 17:53:52,385 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6328-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6328-1) 2026-09-29 17:53:52,385 - INFO - Waiting for VM test_vm to be up.. 2026-09-29 17:53:52,424 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-29 17:53:57,493 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-29 17:54:02,567 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-29 17:54:07,634 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-29 17:54:12,709 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-29 17:54:17,793 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-29 17:54:22,867 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-29 17:54:27,953 - DEBUG - VM is in ACTIVE state now 2026-09-29 17:54:27,953 - INFO - VM name : test_vm 2026-09-29 17:54:28,024 - DEBUG - VM test_vm ID is df2d5909-d766-4953-a228-c05e917091af 2026-09-29 17:54:28,044 - DEBUG - VM test_vm launched on Node cn-jenkins-deploy-platform-ansible-os-6328-1 2026-09-29 17:54:28,114 - DEBUG - Requesting: http://10.0.0.189:8082/virtual-machine/df2d5909-d766-4953-a228-c05e917091af 2026-09-29 17:54:28,477 - DEBUG - Requesting: http://10.0.0.189:8082/virtual-machine/df2d5909-d766-4953-a228-c05e917091af 2026-09-29 17:54:28,510 - DEBUG - Requesting: http://10.0.0.189:8082/virtual-machine-interface/b126d838-d96a-4f66-9dd0-39a1b290f731 2026-09-29 17:54:31,761 - 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 1010ms') 2026-09-29 17:54:31,761 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed! 2026-09-29 17:54:31,815 - DEBUG - Gateway for vn default-domain:ctest-TestRP-95117766:ctest-bgpaas_vn-62630353 is 147.154.51.193 and allocation pool is NOT set 2026-09-29 17:54:33,873 - 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.49 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.409 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.409/1.947/3.486/1.538 ms') 2026-09-29 17:54:33,873 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed 2026-09-29 17:54:34,019 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-29 17:54:34,019 - DEBUG - Waiting to SSH to VM test_vm, IP 147.154.51.195, Port 22 2026-09-29 17:54:34,185 - DEBUG - VM test_vm is ready for SSH connections 2026-09-29 17:54:34,185 - INFO - Waiting for VM test2_vm to be up.. 2026-09-29 17:54:34,258 - DEBUG - VM is in ACTIVE state now 2026-09-29 17:54:34,258 - INFO - VM name : test2_vm 2026-09-29 17:54:34,327 - DEBUG - VM test2_vm ID is a4b8a959-3a1e-46e5-affc-b8eb5bb41b4c 2026-09-29 17:54:34,327 - DEBUG - VM test2_vm launched on Node cn-jenkins-deploy-platform-ansible-os-6328-1 2026-09-29 17:54:34,397 - DEBUG - Requesting: http://10.0.0.189:8082/virtual-machine/a4b8a959-3a1e-46e5-affc-b8eb5bb41b4c 2026-09-29 17:54:34,405 - DEBUG - Requesting: http://10.0.0.189:8082/virtual-machine-interface/10f2960a-58ac-4ecc-bacf-2ae1941f6ef1 2026-09-29 17:54:37,684 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1025ms') 2026-09-29 17:54:37,685 - DEBUG - Ping to Metadata IP 169.254.0.4 of VM test2_vm failed! 2026-09-29 17:54:37,743 - DEBUG - Gateway for vn default-domain:ctest-TestRP-95117766:ctest-bgpaas_vn-60783953 is 59.152.21.193 and allocation pool is NOT set 2026-09-29 17:54:39,802 - 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.78 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.19 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 = 1.193/2.988/4.784/1.795 ms') 2026-09-29 17:54:39,802 - INFO - Ping to Metadata IP 169.254.0.4 of VM test2_vm passed 2026-09-29 17:54:39,955 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-29 17:54:39,955 - DEBUG - Waiting to SSH to VM test2_vm, IP 59.152.21.195, Port 22 2026-09-29 17:54:40,022 - DEBUG - Error on ssh to cirros@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-09-29 17:54:40,093 - DEBUG - VM test2_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-09-29 17:54:45,094 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-29 17:54:45,095 - DEBUG - Waiting to SSH to VM test2_vm, IP 59.152.21.195, Port 22 2026-09-29 17:54:45,251 - DEBUG - VM test2_vm is ready for SSH connections 2026-09-29 17:54:45,399 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-29 17:54:45,406 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-95117766:ctest-bgpaas_vn-62630353:ctest-bgpaas_vn-62630353.inet.0 2026-09-29 17:54:46,418 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-29 17:54:46,424 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-95117766:ctest-bgpaas_vn-62630353:ctest-bgpaas_vn-62630353.inet.0 2026-09-29 17:54:46,436 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-29 17:54:46,436 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 59.152.21.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.189, gateway password: c0ntrail123 2026-09-29 17:54:46,436 - DEBUG - ping -s 56 -c 3 -W 1 59.152.21.195 2026-09-29 17:54:50,387 - DEBUG - PING 59.152.21.195 (59.152.21.195): 56 data bytes 64 bytes from 59.152.21.195: seq=0 ttl=63 time=4.579 ms 64 bytes from 59.152.21.195: seq=1 ttl=63 time=0.954 ms 64 bytes from 59.152.21.195: seq=2 ttl=63 time=0.842 ms --- 59.152.21.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.842/2.125/4.579 ms 2026-09-29 17:54:50,388 - INFO - Ping to IP 59.152.21.195 from VM test_vm passed 2026-09-29 17:54:50,459 - INFO - Deleting VM test2_vm 2026-09-29 17:54:50,529 - INFO - Deleting VM test_vm 2026-09-29 17:54:50,595 - INFO - Deleting VN ctest-bgpaas_vn-60783953 2026-09-29 17:54:50,628 - DEBUG - VN 258c73d5-a946-4577-b5fa-5099722148a1 still in use: Unable to complete operation on network 258c73d5-a946-4577-b5fa-5099722148a1. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-daf172e1-8cb8-46c2-84b6-dffdc35ca35a'] 2026-09-29 17:54:50,628 - WARNING - Deleting VN ctest-bgpaas_vn-60783953 failed..Will retry 2026-09-29 17:54:52,775 - DEBUG - Response for deleting network () 2026-09-29 17:54:52,775 - INFO - Deleting VN ctest-bgpaas_vn-62630353 2026-09-29 17:54:52,885 - DEBUG - Response for deleting network () 2026-09-29 17:54:53,157 - DEBUG - No XMPP flaps were noticed during the test
2026-09-29 17:54:53,157 - INFO - END TEST : test_rp_interface : PASSED[0:01:04]
2026-09-29 17:54:53,157 - INFO - -------------------------------------------------------------------------------- 2026-09-29 17:54:53,895 - INFO - Deleted project: ctest-TestRP-95117766, ID : 2b77eaba-5210-41fd-b754-ed2fd8133a22