2026-09-10 09:35:17,993 - INFO - Domain Default found not creating 2026-09-10 09:35:18,190 - INFO - Project ctest-TestRP-29021339 not found, creating it 2026-09-10 09:35:18,701 - INFO - Created Project:ctest-TestRP-29021339, ID : 9ca9ee20-4dcb-4411-9b94-5d5e61a54792 2026-09-10 09:35:20,668 - DEBUG - Services list from nova: [, , ] 2026-09-10 09:35:20,668 - DEBUG - Hosts: {'nova': ['cn-jenkins-deploy-platform-ansible-os-6273-1']} 2026-09-10 09:35:20,670 - INFO - ================================================================================ 2026-09-10 09:35:20,670 - INFO - STARTING TEST : test_rp_interface 2026-09-10 09:35:20,670 - 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-10 09:35:20,941 - DEBUG - Nothing to compare xmpp stats {'10.0.0.161': {'10.20.0.254': '0'}} with 2026-09-10 09:35:20,941 - INFO - Initial checks done. Running the testcase now 2026-09-10 09:35:20,941 - INFO - 2026-09-10 09:35:21,643 - DEBUG - Response for create_network : {'network': {'id': 'ea543cbc-b5f3-4f6b-a5df-dee565cc9a8f', 'name': 'ctest-bgpaas_vn-02574831', 'tenant_id': '9ca9ee204dcb44119b945d5e61a54792', 'project_id': '9ca9ee204dcb44119b945d5e61a54792', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-29021339', 'ctest-bgpaas_vn-02574831'], 'port_security_enabled': True, 'description': ''}} 2026-09-10 09:35:21,878 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b2281aa1-235d-4e34-92a1-c1cfd1bdd686', 'name': '', 'tenant_id': '9ca9ee204dcb44119b945d5e61a54792', 'network_id': 'ea543cbc-b5f3-4f6b-a5df-dee565cc9a8f', 'ip_version': 4, 'cidr': '210.93.81.192/26', 'allocation_pools': [{'start': '210.93.81.194', 'end': '210.93.81.254'}], 'gateway_ip': '210.93.81.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '210.93.81.194', 'tags': [], 'project_id': '9ca9ee204dcb44119b945d5e61a54792'}} 2026-09-10 09:35:21,900 - INFO - Created VN ctest-bgpaas_vn-02574831 2026-09-10 09:35:21,953 - DEBUG - VN ctest-bgpaas_vn-02574831 UUID is ea543cbc-b5f3-4f6b-a5df-dee565cc9a8f 2026-09-10 09:35:22,481 - DEBUG - Response for create_network : {'network': {'id': 'c2923c42-1bd2-45a0-8c8a-542e704b7038', 'name': 'ctest-bgpaas_vn-71715106', 'tenant_id': '9ca9ee204dcb44119b945d5e61a54792', 'project_id': '9ca9ee204dcb44119b945d5e61a54792', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-29021339', 'ctest-bgpaas_vn-71715106'], 'port_security_enabled': True, 'description': ''}} 2026-09-10 09:35:22,711 - DEBUG - Response for create_subnet : {'subnet': {'id': '9415490f-4354-4b9c-93d6-e25ab7abe484', 'name': '', 'tenant_id': '9ca9ee204dcb44119b945d5e61a54792', 'network_id': 'c2923c42-1bd2-45a0-8c8a-542e704b7038', 'ip_version': 4, 'cidr': '213.25.106.64/26', 'allocation_pools': [{'start': '213.25.106.66', 'end': '213.25.106.126'}], 'gateway_ip': '213.25.106.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '213.25.106.66', 'tags': [], 'project_id': '9ca9ee204dcb44119b945d5e61a54792'}} 2026-09-10 09:35:22,736 - INFO - Created VN ctest-bgpaas_vn-71715106 2026-09-10 09:35:22,793 - DEBUG - VN ctest-bgpaas_vn-71715106 UUID is c2923c42-1bd2-45a0-8c8a-542e704b7038 2026-09-10 09:35:23,564 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6273-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6273-1) 2026-09-10 09:35:24,010 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6273-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6273-1) 2026-09-10 09:35:24,010 - INFO - Waiting for VM test_vm to be up.. 2026-09-10 09:35:24,055 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-10 09:35:29,128 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-10 09:35:34,229 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-10 09:35:39,329 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-10 09:35:44,419 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-10 09:35:49,517 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-10 09:35:54,614 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-10 09:35:59,696 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-10 09:36:04,797 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-09-10 09:36:09,884 - DEBUG - VM is in ACTIVE state now 2026-09-10 09:36:09,885 - INFO - VM name : test_vm 2026-09-10 09:36:09,983 - DEBUG - VM test_vm ID is 1bdad6bb-c6d5-48a7-91a7-301937efa1b2 2026-09-10 09:36:10,005 - DEBUG - VM test_vm launched on Node cn-jenkins-deploy-platform-ansible-os-6273-1 2026-09-10 09:36:10,099 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/1bdad6bb-c6d5-48a7-91a7-301937efa1b2 2026-09-10 09:36:10,486 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/1bdad6bb-c6d5-48a7-91a7-301937efa1b2 2026-09-10 09:36:10,522 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine-interface/05f05b4f-7771-4fe0-9bbc-0eaa1d970540 2026-09-10 09:36:13,804 - 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=5.40 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1027ms\r\nrtt min/avg/max/mdev = 5.401/5.401/5.401/0.000 ms') 2026-09-10 09:36:13,804 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed 2026-09-10 09:36:13,958 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-10 09:36:13,958 - DEBUG - Waiting to SSH to VM test_vm, IP 210.93.81.195, Port 22 2026-09-10 09:36:14,024 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-09-10 09:36:14,111 - DEBUG - VM test_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-09-10 09:36:19,112 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-10 09:36:19,112 - DEBUG - Waiting to SSH to VM test_vm, IP 210.93.81.195, Port 22 2026-09-10 09:36:19,296 - DEBUG - VM test_vm is ready for SSH connections 2026-09-10 09:36:19,297 - INFO - Waiting for VM test2_vm to be up.. 2026-09-10 09:36:19,393 - DEBUG - VM is in ACTIVE state now 2026-09-10 09:36:19,393 - INFO - VM name : test2_vm 2026-09-10 09:36:19,495 - DEBUG - VM test2_vm ID is fafe0158-548f-437a-96c2-0b1f55468547 2026-09-10 09:36:19,495 - DEBUG - VM test2_vm launched on Node cn-jenkins-deploy-platform-ansible-os-6273-1 2026-09-10 09:36:19,592 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine/fafe0158-548f-437a-96c2-0b1f55468547 2026-09-10 09:36:19,604 - DEBUG - Requesting: http://10.0.0.161:8082/virtual-machine-interface/286a0823-6e8f-4a09-9654-c0c0b318048f 2026-09-10 09:36:20,867 - 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.78 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=14.0 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 = 3.777/8.879/13.981/5.102 ms') 2026-09-10 09:36:20,867 - INFO - Ping to Metadata IP 169.254.0.4 of VM test2_vm passed 2026-09-10 09:36:21,021 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-10 09:36:21,022 - DEBUG - Waiting to SSH to VM test2_vm, IP 213.25.106.67, Port 22 2026-09-10 09:36:21,192 - DEBUG - VM test2_vm is ready for SSH connections 2026-09-10 09:36:21,365 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-10 09:36:21,372 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-29021339:ctest-bgpaas_vn-02574831:ctest-bgpaas_vn-02574831.inet.0 2026-09-10 09:36:22,383 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-10 09:36:22,391 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-29021339:ctest-bgpaas_vn-02574831:ctest-bgpaas_vn-02574831.inet.0 2026-09-10 09:36:22,403 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-09-10 09:36:22,403 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 213.25.106.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.161, gateway password: c0ntrail123 2026-09-10 09:36:22,404 - DEBUG - ping -s 56 -c 3 -W 1 213.25.106.67 2026-09-10 09:36:26,468 - DEBUG - PING 213.25.106.67 (213.25.106.67): 56 data bytes 64 bytes from 213.25.106.67: seq=0 ttl=63 time=5.186 ms 64 bytes from 213.25.106.67: seq=1 ttl=63 time=1.636 ms 64 bytes from 213.25.106.67: seq=2 ttl=63 time=0.986 ms --- 213.25.106.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 0.986/2.602/5.186 ms 2026-09-10 09:36:26,468 - INFO - Ping to IP 213.25.106.67 from VM test_vm passed 2026-09-10 09:36:26,579 - INFO - Deleting VM test2_vm 2026-09-10 09:36:26,680 - INFO - Deleting VM test_vm 2026-09-10 09:36:26,766 - INFO - Deleting VN ctest-bgpaas_vn-71715106 2026-09-10 09:36:26,839 - DEBUG - VN c2923c42-1bd2-45a0-8c8a-542e704b7038 still in use: Unable to complete operation on network c2923c42-1bd2-45a0-8c8a-542e704b7038. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-6134b79d-5afa-4514-a385-38f65bef00f4'] 2026-09-10 09:36:26,839 - WARNING - Deleting VN ctest-bgpaas_vn-71715106 failed..Will retry 2026-09-10 09:36:29,039 - DEBUG - Response for deleting network () 2026-09-10 09:36:29,039 - INFO - Deleting VN ctest-bgpaas_vn-02574831 2026-09-10 09:36:29,186 - DEBUG - Response for deleting network () 2026-09-10 09:36:29,473 - DEBUG - No XMPP flaps were noticed during the test
2026-09-10 09:36:29,473 - INFO - END TEST : test_rp_interface : PASSED[0:01:09]
2026-09-10 09:36:29,473 - INFO - -------------------------------------------------------------------------------- 2026-09-10 09:36:30,296 - INFO - Deleted project: ctest-TestRP-29021339, ID : 9ca9ee20-4dcb-4411-9b94-5d5e61a54792