2026-01-26 02:54:16,846 - INFO - Domain Default found not creating 2026-01-26 02:54:17,002 - INFO - Project ctest-TestRP-05494675 not found, creating it 2026-01-26 02:54:17,557 - INFO - Created Project:ctest-TestRP-05494675, ID : 84f762e8-df4f-4b43-8439-8d8bf15c201a 2026-01-26 02:54:19,182 - DEBUG - Services list from nova: [, , , ] 2026-01-26 02:54:19,183 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-5076-1', 'an-jenkins-deploy-platform-ansible-os-5076-2']} 2026-01-26 02:54:19,185 - INFO - ================================================================================ 2026-01-26 02:54:19,186 - INFO - STARTING TEST : test_rp_interface 2026-01-26 02:54:19,186 - 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-01-26 02:54:20,466 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.129': '0', '10.20.0.25': '0'}, '10.0.0.130': {'10.20.0.25': '0', '10.20.0.129': '0'}} with 2026-01-26 02:54:20,466 - INFO - Initial checks done. Running the testcase now 2026-01-26 02:54:20,466 - INFO - 2026-01-26 02:54:21,182 - DEBUG - Response for create_network : {'network': {'id': '4eef2168-3768-4618-aba1-fc791a3f807a', 'name': 'ctest-bgpaas_vn-23231699', 'tenant_id': '84f762e8df4f4b4384398d8bf15c201a', 'project_id': '84f762e8df4f4b4384398d8bf15c201a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-05494675', 'ctest-bgpaas_vn-23231699'], 'port_security_enabled': True, 'description': ''}} 2026-01-26 02:54:21,475 - DEBUG - Response for create_subnet : {'subnet': {'id': '91fdf4bc-7dde-4f39-8926-fbc12b554360', 'name': '', 'tenant_id': '84f762e8df4f4b4384398d8bf15c201a', 'network_id': '4eef2168-3768-4618-aba1-fc791a3f807a', 'ip_version': 4, 'cidr': '43.3.160.192/26', 'allocation_pools': [{'start': '43.3.160.194', 'end': '43.3.160.254'}], 'gateway_ip': '43.3.160.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '43.3.160.194', 'tags': [], 'project_id': '84f762e8df4f4b4384398d8bf15c201a'}} 2026-01-26 02:54:21,505 - INFO - Created VN ctest-bgpaas_vn-23231699 2026-01-26 02:54:21,516 - DEBUG - VN ctest-bgpaas_vn-23231699 UUID is 4eef2168-3768-4618-aba1-fc791a3f807a 2026-01-26 02:54:22,147 - DEBUG - Response for create_network : {'network': {'id': 'eb80ee2b-8bfb-415b-b9a3-a58f2c86d8b7', 'name': 'ctest-bgpaas_vn-69203615', 'tenant_id': '84f762e8df4f4b4384398d8bf15c201a', 'project_id': '84f762e8df4f4b4384398d8bf15c201a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-05494675', 'ctest-bgpaas_vn-69203615'], 'port_security_enabled': True, 'description': ''}} 2026-01-26 02:54:22,433 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c18dc05d-10d7-4fa7-b90b-4a67a5274102', 'name': '', 'tenant_id': '84f762e8df4f4b4384398d8bf15c201a', 'network_id': 'eb80ee2b-8bfb-415b-b9a3-a58f2c86d8b7', 'ip_version': 4, 'cidr': '34.20.106.128/26', 'allocation_pools': [{'start': '34.20.106.130', 'end': '34.20.106.190'}], 'gateway_ip': '34.20.106.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '34.20.106.130', 'tags': [], 'project_id': '84f762e8df4f4b4384398d8bf15c201a'}} 2026-01-26 02:54:22,457 - INFO - Created VN ctest-bgpaas_vn-69203615 2026-01-26 02:54:22,474 - DEBUG - VN ctest-bgpaas_vn-69203615 UUID is eb80ee2b-8bfb-415b-b9a3-a58f2c86d8b7 2026-01-26 02:54:23,853 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5076-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5076-1) 2026-01-26 02:54:25,162 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5076-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5076-2) 2026-01-26 02:54:25,162 - INFO - Waiting for VM test_vm to be up.. 2026-01-26 02:54:25,269 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-26 02:54:30,360 - DEBUG - VM is in ACTIVE state now 2026-01-26 02:54:30,360 - INFO - VM name : test_vm 2026-01-26 02:54:30,446 - DEBUG - VM test_vm ID is 7977deb4-ec88-4c15-8e15-e8bf7ead025b 2026-01-26 02:54:30,472 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-5076-1 2026-01-26 02:54:30,553 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/7977deb4-ec88-4c15-8e15-e8bf7ead025b 2026-01-26 02:54:30,847 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/7977deb4-ec88-4c15-8e15-e8bf7ead025b 2026-01-26 02:54:30,880 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/fee867dd-89ca-4f8f-a074-37af7a07f188 2026-01-26 02:54:34,056 - 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 1023ms') 2026-01-26 02:54:34,056 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed! 2026-01-26 02:54:34,073 - DEBUG - Gateway for vn default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-23231699 is 43.3.160.193 and allocation pool is NOT set 2026-01-26 02:54:36,145 - 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.48 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.16 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 = 1.164/3.322/5.480/2.158 ms') 2026-01-26 02:54:36,145 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed 2026-01-26 02:54:36,214 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:54:36,214 - DEBUG - Waiting to SSH to VM test_vm, IP 43.3.160.195, Port 22 2026-01-26 02:54:36,382 - DEBUG - VM test_vm is ready for SSH connections 2026-01-26 02:54:36,382 - INFO - Waiting for VM test2_vm to be up.. 2026-01-26 02:54:36,470 - DEBUG - VM is in ACTIVE state now 2026-01-26 02:54:36,470 - INFO - VM name : test2_vm 2026-01-26 02:54:36,553 - DEBUG - VM test2_vm ID is ed724f31-38ba-4e71-9e71-463e2fd71365 2026-01-26 02:54:36,553 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-5076-2 2026-01-26 02:54:36,631 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/ed724f31-38ba-4e71-9e71-463e2fd71365 2026-01-26 02:54:36,643 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/b7f5520d-988c-4bc6-ac4d-80c7c73d255b 2026-01-26 02:54:37,812 - 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.68 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.48 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 = 1.483/3.580/5.678/2.097 ms') 2026-01-26 02:54:37,812 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed 2026-01-26 02:54:37,881 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:54:37,881 - DEBUG - Waiting to SSH to VM test2_vm, IP 34.20.106.131, Port 22 2026-01-26 02:54:38,037 - DEBUG - VM test2_vm is ready for SSH connections 2026-01-26 02:54:38,216 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:54:38,229 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-23231699:ctest-bgpaas_vn-23231699.inet.0 2026-01-26 02:54:38,244 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-23231699:ctest-bgpaas_vn-23231699.inet.0 2026-01-26 02:54:38,256 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:54:38,257 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 34.20.106.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-01-26 02:54:38,257 - DEBUG - ping -s 56 -c 3 -W 1 34.20.106.131 2026-01-26 02:54:42,186 - DEBUG - PING 34.20.106.131 (34.20.106.131): 56 data bytes 64 bytes from 34.20.106.131: seq=0 ttl=63 time=6.391 ms 64 bytes from 34.20.106.131: seq=1 ttl=63 time=1.412 ms 64 bytes from 34.20.106.131: seq=2 ttl=63 time=1.145 ms --- 34.20.106.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.145/2.982/6.391 ms 2026-01-26 02:54:42,186 - INFO - Ping to IP 34.20.106.131 from VM test_vm passed 2026-01-26 02:54:42,313 - INFO - Deleting VM test2_vm 2026-01-26 02:54:42,387 - INFO - Deleting VM test_vm 2026-01-26 02:54:42,456 - INFO - Deleting VN ctest-bgpaas_vn-69203615 2026-01-26 02:54:42,496 - DEBUG - VN eb80ee2b-8bfb-415b-b9a3-a58f2c86d8b7 still in use: Unable to complete operation on network eb80ee2b-8bfb-415b-b9a3-a58f2c86d8b7. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-50628cda-e2d1-4b96-a828-96c4290b76bd'] 2026-01-26 02:54:42,496 - WARNING - Deleting VN ctest-bgpaas_vn-69203615 failed..Will retry 2026-01-26 02:54:44,682 - DEBUG - Response for deleting network () 2026-01-26 02:54:44,682 - INFO - Deleting VN ctest-bgpaas_vn-23231699 2026-01-26 02:54:44,869 - DEBUG - Response for deleting network () 2026-01-26 02:54:46,174 - DEBUG - No XMPP flaps were noticed during the test
2026-01-26 02:54:46,174 - INFO - END TEST : test_rp_interface : PASSED[0:00:27]
2026-01-26 02:54:46,174 - INFO - -------------------------------------------------------------------------------- 2026-01-26 02:54:46,178 - INFO - ================================================================================ 2026-01-26 02:54:46,178 - INFO - STARTING TEST : test_rp_interface_ext_community 2026-01-26 02:54:46,178 - 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-01-26 02:54:47,453 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.129': '0', '10.20.0.25': '0'}, '10.0.0.130': {'10.20.0.25': '0', '10.20.0.129': '0'}} with 2026-01-26 02:54:47,453 - INFO - Initial checks done. Running the testcase now 2026-01-26 02:54:47,453 - INFO - 2026-01-26 02:54:47,719 - DEBUG - Response for create_network : {'network': {'id': 'e4dc74c6-80d3-477b-8d9f-16090415ff18', 'name': 'ctest-bgpaas_vn-44732302', 'tenant_id': '84f762e8df4f4b4384398d8bf15c201a', 'project_id': '84f762e8df4f4b4384398d8bf15c201a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-05494675', 'ctest-bgpaas_vn-44732302'], 'port_security_enabled': True, 'description': ''}} 2026-01-26 02:54:47,918 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b8a2134c-6e60-411c-86ed-99185e371d40', 'name': '', 'tenant_id': '84f762e8df4f4b4384398d8bf15c201a', 'network_id': 'e4dc74c6-80d3-477b-8d9f-16090415ff18', 'ip_version': 4, 'cidr': '82.31.29.128/26', 'allocation_pools': [{'start': '82.31.29.130', 'end': '82.31.29.190'}], 'gateway_ip': '82.31.29.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '82.31.29.130', 'tags': [], 'project_id': '84f762e8df4f4b4384398d8bf15c201a'}} 2026-01-26 02:54:47,938 - INFO - Created VN ctest-bgpaas_vn-44732302 2026-01-26 02:54:47,949 - DEBUG - VN ctest-bgpaas_vn-44732302 UUID is e4dc74c6-80d3-477b-8d9f-16090415ff18 2026-01-26 02:54:48,478 - DEBUG - Response for create_network : {'network': {'id': '7a8e7b02-263b-435f-842a-0c15d944f257', 'name': 'ctest-bgpaas_vn-53718762', 'tenant_id': '84f762e8df4f4b4384398d8bf15c201a', 'project_id': '84f762e8df4f4b4384398d8bf15c201a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-05494675', 'ctest-bgpaas_vn-53718762'], 'port_security_enabled': True, 'description': ''}} 2026-01-26 02:54:48,721 - DEBUG - Response for create_subnet : {'subnet': {'id': '314e2301-98d6-4dd6-8e82-7a46c37abf73', 'name': '', 'tenant_id': '84f762e8df4f4b4384398d8bf15c201a', 'network_id': '7a8e7b02-263b-435f-842a-0c15d944f257', 'ip_version': 4, 'cidr': '8.217.247.192/26', 'allocation_pools': [{'start': '8.217.247.194', 'end': '8.217.247.254'}], 'gateway_ip': '8.217.247.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '8.217.247.194', 'tags': [], 'project_id': '84f762e8df4f4b4384398d8bf15c201a'}} 2026-01-26 02:54:48,742 - INFO - Created VN ctest-bgpaas_vn-53718762 2026-01-26 02:54:48,753 - DEBUG - VN ctest-bgpaas_vn-53718762 UUID is 7a8e7b02-263b-435f-842a-0c15d944f257 2026-01-26 02:54:50,048 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5076-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5076-1) 2026-01-26 02:54:51,549 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5076-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5076-2) 2026-01-26 02:54:51,549 - INFO - Waiting for VM test_vm to be up.. 2026-01-26 02:54:51,664 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-26 02:54:56,744 - DEBUG - VM is in ACTIVE state now 2026-01-26 02:54:56,744 - INFO - VM name : test_vm 2026-01-26 02:54:56,820 - DEBUG - VM test_vm ID is 25c714ef-4320-47e1-b94e-fe94600c41ec 2026-01-26 02:54:56,820 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-5076-1 2026-01-26 02:54:56,895 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/25c714ef-4320-47e1-b94e-fe94600c41ec 2026-01-26 02:54:56,905 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/9a9d18eb-7b05-4d18-ae37-b2745d487053 2026-01-26 02:55:00,080 - 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 1023ms') 2026-01-26 02:55:00,080 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed! 2026-01-26 02:55:00,096 - DEBUG - Gateway for vn default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-44732302 is 82.31.29.129 and allocation pool is NOT set 2026-01-26 02:55:02,158 - 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.01 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.568 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.568/1.786/3.005/1.218 ms') 2026-01-26 02:55:02,158 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed 2026-01-26 02:55:02,229 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:55:02,229 - DEBUG - Waiting to SSH to VM test_vm, IP 82.31.29.131, Port 22 2026-01-26 02:55:02,396 - DEBUG - VM test_vm is ready for SSH connections 2026-01-26 02:55:02,397 - INFO - Waiting for VM test2_vm to be up.. 2026-01-26 02:55:02,493 - DEBUG - VM is in ACTIVE state now 2026-01-26 02:55:02,494 - INFO - VM name : test2_vm 2026-01-26 02:55:02,587 - DEBUG - VM test2_vm ID is c91fd3c0-9eb5-4e70-bcdf-6affcf3f9c74 2026-01-26 02:55:02,587 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-5076-2 2026-01-26 02:55:02,668 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/c91fd3c0-9eb5-4e70-bcdf-6affcf3f9c74 2026-01-26 02:55:02,677 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/b16f787f-6c43-40a4-acab-039588995a27 2026-01-26 02:55:03,843 - 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=10.9 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.739 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 = 0.739/5.819/10.899/5.080 ms') 2026-01-26 02:55:03,843 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed 2026-01-26 02:55:03,913 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:55:03,913 - DEBUG - Waiting to SSH to VM test2_vm, IP 8.217.247.195, Port 22 2026-01-26 02:55:04,084 - DEBUG - VM test2_vm is ready for SSH connections 2026-01-26 02:55:04,276 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:55:04,293 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-44732302:ctest-bgpaas_vn-44732302.inet.0 2026-01-26 02:55:04,307 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-44732302:ctest-bgpaas_vn-44732302.inet.0 2026-01-26 02:55:04,319 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:55:04,319 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 8.217.247.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-01-26 02:55:04,319 - DEBUG - ping -s 56 -c 3 -W 1 8.217.247.195 2026-01-26 02:55:07,972 - DEBUG - PING 8.217.247.195 (8.217.247.195): 56 data bytes 64 bytes from 8.217.247.195: seq=0 ttl=63 time=5.829 ms 64 bytes from 8.217.247.195: seq=1 ttl=63 time=1.153 ms 64 bytes from 8.217.247.195: seq=2 ttl=63 time=1.384 ms --- 8.217.247.195 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.153/2.788/5.829 ms 2026-01-26 02:55:07,972 - INFO - Ping to IP 8.217.247.195 from VM test_vm passed 2026-01-26 02:55:08,138 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:55:08,155 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-44732302:ctest-bgpaas_vn-44732302.inet.0 2026-01-26 02:55:08,169 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-44732302:ctest-bgpaas_vn-44732302.inet.0 2026-01-26 02:55:08,445 - INFO - Deleting VM test2_vm 2026-01-26 02:55:08,513 - INFO - Deleting VM test_vm 2026-01-26 02:55:08,578 - INFO - Deleting VN ctest-bgpaas_vn-53718762 2026-01-26 02:55:08,616 - DEBUG - VN 7a8e7b02-263b-435f-842a-0c15d944f257 still in use: Unable to complete operation on network 7a8e7b02-263b-435f-842a-0c15d944f257. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-6d1f8559-85e1-4387-b419-ada3bc7872bb'] 2026-01-26 02:55:08,616 - WARNING - Deleting VN ctest-bgpaas_vn-53718762 failed..Will retry 2026-01-26 02:55:10,777 - DEBUG - Response for deleting network () 2026-01-26 02:55:10,777 - INFO - Deleting VN ctest-bgpaas_vn-44732302 2026-01-26 02:55:10,950 - DEBUG - Response for deleting network () 2026-01-26 02:55:12,210 - DEBUG - No XMPP flaps were noticed during the test
2026-01-26 02:55:12,210 - INFO - END TEST : test_rp_interface_ext_community : PASSED[0:00:26]
2026-01-26 02:55:12,210 - INFO - -------------------------------------------------------------------------------- 2026-01-26 02:55:12,212 - INFO - ================================================================================ 2026-01-26 02:55:12,212 - INFO - STARTING TEST : test_rp_interface_static 2026-01-26 02:55:12,212 - INFO - TEST DESCRIPTION : 1. Create a routing policy with interface-static match. 2. Launch VMs. 3. Attach policy to VN and confirm if policy takes hold. 2026-01-26 02:55:13,486 - DEBUG - Nothing to compare xmpp stats {'10.0.0.65': {'10.20.0.129': '0', '10.20.0.25': '0'}, '10.0.0.130': {'10.20.0.25': '0', '10.20.0.129': '0'}} with 2026-01-26 02:55:13,486 - INFO - Initial checks done. Running the testcase now 2026-01-26 02:55:13,486 - INFO - 2026-01-26 02:55:13,745 - DEBUG - Response for create_network : {'network': {'id': '31766e2f-ce26-49cf-8b46-fbe74d9ba4ce', 'name': 'ctest-bgpaas_vn-90135086', 'tenant_id': '84f762e8df4f4b4384398d8bf15c201a', 'project_id': '84f762e8df4f4b4384398d8bf15c201a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-05494675', 'ctest-bgpaas_vn-90135086'], 'port_security_enabled': True, 'description': ''}} 2026-01-26 02:55:13,962 - DEBUG - Response for create_subnet : {'subnet': {'id': '81983e1a-e614-45c1-aee8-99fe8d0eb0cb', 'name': '', 'tenant_id': '84f762e8df4f4b4384398d8bf15c201a', 'network_id': '31766e2f-ce26-49cf-8b46-fbe74d9ba4ce', 'ip_version': 4, 'cidr': '121.4.90.0/26', 'allocation_pools': [{'start': '121.4.90.2', 'end': '121.4.90.62'}], 'gateway_ip': '121.4.90.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '121.4.90.2', 'tags': [], 'project_id': '84f762e8df4f4b4384398d8bf15c201a'}} 2026-01-26 02:55:13,982 - INFO - Created VN ctest-bgpaas_vn-90135086 2026-01-26 02:55:13,994 - DEBUG - VN ctest-bgpaas_vn-90135086 UUID is 31766e2f-ce26-49cf-8b46-fbe74d9ba4ce 2026-01-26 02:55:14,494 - DEBUG - Response for create_network : {'network': {'id': 'e869e50f-dd98-4485-aaf7-bd7b89ccd3f2', 'name': 'ctest-bgpaas_vn-54215722', 'tenant_id': '84f762e8df4f4b4384398d8bf15c201a', 'project_id': '84f762e8df4f4b4384398d8bf15c201a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-05494675', 'ctest-bgpaas_vn-54215722'], 'port_security_enabled': True, 'description': ''}} 2026-01-26 02:55:14,686 - DEBUG - Response for create_subnet : {'subnet': {'id': '75d8f64c-aff6-415f-9dbc-ce38c3c15539', 'name': '', 'tenant_id': '84f762e8df4f4b4384398d8bf15c201a', 'network_id': 'e869e50f-dd98-4485-aaf7-bd7b89ccd3f2', 'ip_version': 4, 'cidr': '107.51.183.0/26', 'allocation_pools': [{'start': '107.51.183.2', 'end': '107.51.183.62'}], 'gateway_ip': '107.51.183.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '107.51.183.2', 'tags': [], 'project_id': '84f762e8df4f4b4384398d8bf15c201a'}} 2026-01-26 02:55:14,716 - INFO - Created VN ctest-bgpaas_vn-54215722 2026-01-26 02:55:14,729 - DEBUG - VN ctest-bgpaas_vn-54215722 UUID is e869e50f-dd98-4485-aaf7-bd7b89ccd3f2 2026-01-26 02:55:16,376 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5076-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5076-1) 2026-01-26 02:55:17,637 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5076-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5076-2) 2026-01-26 02:55:17,637 - INFO - Waiting for VM test_vm to be up.. 2026-01-26 02:55:17,726 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-26 02:56:22,790 - ERROR - Fatal Nova Exception while getting VM detail 2026-01-26 02:57:22,989 - DEBUG - VM is in ACTIVE state now 2026-01-26 02:57:22,990 - INFO - VM name : test_vm 2026-01-26 02:57:23,084 - DEBUG - VM test_vm ID is 712d110e-6729-44c4-b0b3-1b892a72a945 2026-01-26 02:57:23,084 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-5076-1 2026-01-26 02:57:23,171 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/712d110e-6729-44c4-b0b3-1b892a72a945 2026-01-26 02:57:23,187 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/fc294567-6f3d-48b4-af70-9aa0de07a018 2026-01-26 02:57:24,346 - 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.51 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.453 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.453/1.983/3.513/1.530 ms') 2026-01-26 02:57:24,346 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed 2026-01-26 02:57:24,413 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:57:24,413 - DEBUG - Waiting to SSH to VM test_vm, IP 121.4.90.3, Port 22 2026-01-26 02:57:24,470 - 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-01-26 02:57:24,556 - DEBUG - VM test_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-01-26 02:57:29,557 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:57:29,557 - DEBUG - Waiting to SSH to VM test_vm, IP 121.4.90.3, Port 22 2026-01-26 02:57:29,614 - 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-01-26 02:57:29,697 - DEBUG - VM test_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-01-26 02:57:34,697 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:57:34,697 - DEBUG - Waiting to SSH to VM test_vm, IP 121.4.90.3, Port 22 2026-01-26 02:57:34,863 - DEBUG - VM test_vm is ready for SSH connections 2026-01-26 02:57:34,863 - INFO - Waiting for VM test2_vm to be up.. 2026-01-26 02:57:34,942 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-26 02:57:40,017 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-26 02:57:45,111 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-01-26 02:57:50,189 - DEBUG - VM is in ACTIVE state now 2026-01-26 02:57:50,189 - INFO - VM name : test2_vm 2026-01-26 02:57:50,266 - DEBUG - VM test2_vm ID is 3e1ae25d-d46d-4de5-8804-edadd72d9fc7 2026-01-26 02:57:50,266 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-5076-2 2026-01-26 02:57:50,360 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/3e1ae25d-d46d-4de5-8804-edadd72d9fc7 2026-01-26 02:57:50,377 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/a58a108c-fe1d-426e-84fe-483f674ba9d0 2026-01-26 02:57:53,562 - 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 1022ms') 2026-01-26 02:57:53,562 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test2_vm failed! 2026-01-26 02:57:53,574 - DEBUG - Gateway for vn default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-54215722 is 107.51.183.1 and allocation pool is NOT set 2026-01-26 02:57:55,641 - 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.86 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.16 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 = 1.163/3.010/4.858/1.847 ms') 2026-01-26 02:57:55,641 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed 2026-01-26 02:57:55,709 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-01-26 02:57:55,709 - DEBUG - Waiting to SSH to VM test2_vm, IP 107.51.183.3, Port 22 2026-01-26 02:57:55,884 - DEBUG - VM test2_vm is ready for SSH connections 2026-01-26 02:57:55,972 - INFO - Created InterfaceRouteTable ['default-domain', 'ctest-TestRP-05494675', 'ctest-int_table_right-80590423'](UUID f833fdd7-b42b-4435-be24-269ffd56ebcd), prefixes : ['193.9.121.64/26'] 2026-01-26 02:57:56,044 - INFO - Added intf route table f833fdd7-b42b-4435-be24-269ffd56ebcd to port fc294567-6f3d-48b4-af70-9aa0de07a018 2026-01-26 02:57:56,240 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-90135086:ctest-bgpaas_vn-90135086.inet.0 2026-01-26 02:57:57,269 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-90135086:ctest-bgpaas_vn-90135086.inet.0 2026-01-26 02:57:58,301 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-90135086:ctest-bgpaas_vn-90135086.inet.0 2026-01-26 02:57:59,327 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-90135086:ctest-bgpaas_vn-90135086.inet.0 2026-01-26 02:58:00,355 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-90135086:ctest-bgpaas_vn-90135086.inet.0 2026-01-26 02:58:01,388 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-90135086:ctest-bgpaas_vn-90135086.inet.0 2026-01-26 02:58:02,421 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-90135086:ctest-bgpaas_vn-90135086.inet.0 2026-01-26 02:58:03,452 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-90135086:ctest-bgpaas_vn-90135086.inet.0 2026-01-26 02:58:04,494 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-90135086:ctest-bgpaas_vn-90135086.inet.0 2026-01-26 02:58:05,526 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-90135086:ctest-bgpaas_vn-90135086.inet.0 2026-01-26 02:58:06,554 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-05494675:ctest-bgpaas_vn-90135086:ctest-bgpaas_vn-90135086.inet.0 2026-01-26 02:58:06,685 - INFO - Deleting VM test2_vm 2026-01-26 02:58:06,748 - INFO - Deleting VM test_vm 2026-01-26 02:58:06,816 - INFO - Deleting VN ctest-bgpaas_vn-54215722 2026-01-26 02:58:06,855 - DEBUG - VN e869e50f-dd98-4485-aaf7-bd7b89ccd3f2 still in use: Unable to complete operation on network e869e50f-dd98-4485-aaf7-bd7b89ccd3f2. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-52d5e683-8c7b-40e5-9290-c49ca624e62e'] 2026-01-26 02:58:06,855 - WARNING - Deleting VN ctest-bgpaas_vn-54215722 failed..Will retry 2026-01-26 02:58:08,994 - DEBUG - Response for deleting network () 2026-01-26 02:58:08,994 - INFO - Deleting VN ctest-bgpaas_vn-90135086 2026-01-26 02:58:09,186 - DEBUG - Response for deleting network () 2026-01-26 02:58:09,451 - ERROR - [Errno 104] Connection reset by peer Traceback (most recent call last): File "/contrail-test/tcutils/cores.py", line 42, in get_cores_node if exists(CORE_DIR): File "/usr/local/lib/python3.9/site-packages/fabric/contrib/files.py", line 31, in exists cmd = 'stat %s' % _expand_path(path) File "/usr/local/lib/python3.9/site-packages/fabric/contrib/files.py", line 485, in _expand_path return path if is_win() else '"$(echo %s)"' % path File "/usr/local/lib/python3.9/site-packages/fabric/contrib/files.py", line 472, in is_win return '"' in run('echo "Will you echo quotation marks"') File "/usr/local/lib/python3.9/site-packages/fabric/network.py", line 692, in host_prompting_wrapper return func(*args, **kwargs) File "/usr/local/lib/python3.9/site-packages/fabric/operations.py", line 1092, in run return _run_command( File "/usr/local/lib/python3.9/site-packages/fabric/operations.py", line 935, in _run_command channel=default_channel(), command=wrapped_command, pty=pty, File "/usr/local/lib/python3.9/site-packages/fabric/state.py", line 435, in default_channel chan = _open_session() File "/usr/local/lib/python3.9/site-packages/fabric/state.py", line 420, in _open_session session = transport.open_session(timeout=env.timeout) File "/usr/local/lib/python3.9/site-packages/paramiko/transport.py", line 919, in open_session return self.open_channel( File "/usr/local/lib/python3.9/site-packages/paramiko/transport.py", line 1050, in open_channel raise e File "/usr/local/lib/python3.9/site-packages/paramiko/transport.py", line 2109, in run ptype, m = self.packetizer.read_message() File "/usr/local/lib/python3.9/site-packages/paramiko/packet.py", line 459, in read_message header = self.read_all(self.__block_size_in, check_rekey=True) File "/usr/local/lib/python3.9/site-packages/paramiko/packet.py", line 301, in read_all x = self.__socket.recv(n) File "/usr/local/lib64/python3.9/site-packages/gevent/_socketcommon.py", line 660, in recv return self._sock.recv(*args) ConnectionResetError: [Errno 104] Connection reset by peer 2026-01-26 02:58:10,221 - ERROR - AssertionError Python 3.9.21: /usr/bin/python3 Mon Jan 26 02:58:06 2026 A problem occurred in a Python script. Here is the sequence of function calls leading up to the error, in the order they occurred. /contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={}) 78 log.info('Initial checks done. Running the testcase now') 79 log.info('') 80 result = function(self, *args, **kwargs) 81 if self.inputs.upgrade: 82 pid = os.getpid() result = None function = self = args = () kwargs = {} /contrail-test/scripts/routing_policy/test_routing_policy.py in test_rp_interface_static(self=) 59 config_dicts = {'vn_fixture':ret_dict['vn_fixture'], 'from_term':'protocol', 'sub_from':'interface-static', 'to_term':'community', 'sub_to':'64512:55555'} 60 rp = self.configure_term_routing_policy(config_dicts) 61 assert self.verify_policy_in_control(ret_dict['vn_fixture'], ret_dict['test_vm'], search_value = '55555', search_ip = random_cidr), 'Search term not found in introspect' 62 assert ret_dict['test_vm'].ping_with_certainty(ret_dict['test2_vm'].vm_ip) 63 self = self.verify_policy_in_control = .deco_retry....t_rp_interface_static[sanity] id=0x7f37fb2e11c0>> ret_dict = {'test2_vm': , 'test_vm': , 'vn_fixture': } search_value undefined search_ip undefined random_cidr = '193.9.121.64/26' AssertionError: Search term not found in introspect __cause__ = None __class__ = __context__ = None __delattr__ = __dict__ = {} __dir__ = __doc__ = 'Assertion failed.' __eq__ = __format__ = __ge__ = __getattribute__ = __gt__ = __hash__ = __init__ = __init_subclass__ = __le__ = __lt__ = __ne__ = __new__ = __reduce__ = __reduce_ex__ = __repr__ = __setattr__ = __setstate__ = __sizeof__ = __str__ = __subclasshook__ = __suppress_context__ = False __traceback__ = args = ('Search term not found in introspect',) with_traceback = The above is a description of an error in a Python program. Here is the original traceback: Traceback (most recent call last): File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper result = function(self, *args, **kwargs) File "/contrail-test/scripts/routing_policy/test_routing_policy.py", line 61, in test_rp_interface_static assert self.verify_policy_in_control(ret_dict['vn_fixture'], ret_dict['test_vm'], search_value = '55555', search_ip = random_cidr), 'Search term not found in introspect' AssertionError: Search term not found in introspect 2026-01-26 02:58:10,234 - DEBUG - No XMPP flaps were noticed during the test 2026-01-26 02:58:10,234 - INFO -
2026-01-26 02:58:10,234 - INFO - END TEST : test_rp_interface_static : FAILED[0:02:58]
2026-01-26 02:58:10,234 - INFO - -------------------------------------------------------------------------------- 2026-01-26 02:58:11,039 - INFO - Deleted project: ctest-TestRP-05494675, ID : 84f762e8-df4f-4b43-8439-8d8bf15c201a