2026-03-26 03:01:36,440 - INFO - Domain Default found not creating 2026-03-26 03:01:36,661 - INFO - Project ctest-TestRP-32789171 not found, creating it 2026-03-26 03:01:37,314 - INFO - Created Project:ctest-TestRP-32789171, ID : 85e89fdc-39cf-4752-ba06-ace0eae25d97 2026-03-26 03:01:39,321 - DEBUG - Services list from nova: [, , , ] 2026-03-26 03:01:39,321 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-5545-2', 'an-jenkins-deploy-platform-ansible-os-5545-1']} 2026-03-26 03:01:39,323 - INFO - ================================================================================ 2026-03-26 03:01:39,323 - INFO - STARTING TEST : test_rp_interface 2026-03-26 03:01:39,323 - 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-03-26 03:01:40,640 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.18': '0', '10.20.0.14': '0'}, '10.0.0.23': {'10.20.0.254': '0', '10.20.0.18': '0'}} with 2026-03-26 03:01:40,640 - INFO - Initial checks done. Running the testcase now 2026-03-26 03:01:40,640 - INFO - 2026-03-26 03:01:41,399 - DEBUG - Response for create_network : {'network': {'id': '5dd9b922-c377-4458-8041-cb285cb646d0', 'name': 'ctest-bgpaas_vn-58820042', 'tenant_id': '85e89fdc39cf4752ba06ace0eae25d97', 'project_id': '85e89fdc39cf4752ba06ace0eae25d97', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-32789171', 'ctest-bgpaas_vn-58820042'], 'port_security_enabled': True, 'description': ''}} 2026-03-26 03:01:41,665 - DEBUG - Response for create_subnet : {'subnet': {'id': '9f30245d-fb18-412d-bdef-0fd4bd472efc', 'name': '', 'tenant_id': '85e89fdc39cf4752ba06ace0eae25d97', 'network_id': '5dd9b922-c377-4458-8041-cb285cb646d0', 'ip_version': 4, 'cidr': '72.16.40.64/26', 'allocation_pools': [{'start': '72.16.40.66', 'end': '72.16.40.126'}], 'gateway_ip': '72.16.40.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '72.16.40.66', 'tags': [], 'project_id': '85e89fdc39cf4752ba06ace0eae25d97'}} 2026-03-26 03:01:41,689 - INFO - Created VN ctest-bgpaas_vn-58820042 2026-03-26 03:01:41,700 - DEBUG - VN ctest-bgpaas_vn-58820042 UUID is 5dd9b922-c377-4458-8041-cb285cb646d0 2026-03-26 03:01:42,217 - DEBUG - Response for create_network : {'network': {'id': 'f8afe3de-98fc-453c-9af3-e4b49ffc832f', 'name': 'ctest-bgpaas_vn-49785849', 'tenant_id': '85e89fdc39cf4752ba06ace0eae25d97', 'project_id': '85e89fdc39cf4752ba06ace0eae25d97', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-32789171', 'ctest-bgpaas_vn-49785849'], 'port_security_enabled': True, 'description': ''}} 2026-03-26 03:01:42,509 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c43ea56e-f497-4467-9cbc-2137d5217538', 'name': '', 'tenant_id': '85e89fdc39cf4752ba06ace0eae25d97', 'network_id': 'f8afe3de-98fc-453c-9af3-e4b49ffc832f', 'ip_version': 4, 'cidr': '93.28.163.64/26', 'allocation_pools': [{'start': '93.28.163.66', 'end': '93.28.163.126'}], 'gateway_ip': '93.28.163.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '93.28.163.66', 'tags': [], 'project_id': '85e89fdc39cf4752ba06ace0eae25d97'}} 2026-03-26 03:01:42,534 - INFO - Created VN ctest-bgpaas_vn-49785849 2026-03-26 03:01:42,549 - DEBUG - VN ctest-bgpaas_vn-49785849 UUID is f8afe3de-98fc-453c-9af3-e4b49ffc832f 2026-03-26 03:01:43,984 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5545-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5545-2) 2026-03-26 03:01:45,494 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5545-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5545-1) 2026-03-26 03:01:45,494 - INFO - Waiting for VM test_vm to be up.. 2026-03-26 03:01:45,589 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-26 03:01:50,679 - DEBUG - VM is in ACTIVE state now 2026-03-26 03:01:50,679 - INFO - VM name : test_vm 2026-03-26 03:01:50,773 - DEBUG - VM test_vm ID is 87b32e68-88e1-4716-9563-96d072b953b2 2026-03-26 03:01:50,797 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-5545-2 2026-03-26 03:01:50,887 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/87b32e68-88e1-4716-9563-96d072b953b2 2026-03-26 03:01:51,531 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/87b32e68-88e1-4716-9563-96d072b953b2 2026-03-26 03:01:51,593 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/517e65e3-c439-4b67-92c9-c306447f84ed 2026-03-26 03:01:54,791 - 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 1025ms') 2026-03-26 03:01:54,791 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed! 2026-03-26 03:01:54,804 - DEBUG - Gateway for vn default-domain:ctest-TestRP-32789171:ctest-bgpaas_vn-58820042 is 72.16.40.65 and allocation pool is NOT set 2026-03-26 03:01:56,876 - 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.34 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.505 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.505/2.424/4.343/1.919 ms') 2026-03-26 03:01:56,876 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed 2026-03-26 03:01:56,951 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:01:56,952 - DEBUG - Waiting to SSH to VM test_vm, IP 72.16.40.67, Port 22 2026-03-26 03:01:57,125 - DEBUG - VM test_vm is ready for SSH connections 2026-03-26 03:01:57,125 - INFO - Waiting for VM test2_vm to be up.. 2026-03-26 03:01:57,224 - DEBUG - VM is in ACTIVE state now 2026-03-26 03:01:57,224 - INFO - VM name : test2_vm 2026-03-26 03:01:57,304 - DEBUG - VM test2_vm ID is 6663c396-e9db-4722-a891-a97816d24992 2026-03-26 03:01:57,305 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-5545-1 2026-03-26 03:01:57,396 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/6663c396-e9db-4722-a891-a97816d24992 2026-03-26 03:01:57,414 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/02804e9a-18f4-43c6-a686-41203d75e479 2026-03-26 03:01:58,585 - 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.21 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.448 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.448/1.826/3.205/1.378 ms') 2026-03-26 03:01:58,585 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed 2026-03-26 03:01:58,657 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:01:58,657 - DEBUG - Waiting to SSH to VM test2_vm, IP 93.28.163.67, Port 22 2026-03-26 03:01:58,815 - DEBUG - VM test2_vm is ready for SSH connections 2026-03-26 03:01:58,979 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:01:58,992 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-32789171:ctest-bgpaas_vn-58820042:ctest-bgpaas_vn-58820042.inet.0 2026-03-26 03:01:59,005 - DEBUG - Requesting: http://10.20.0.18:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-32789171:ctest-bgpaas_vn-58820042:ctest-bgpaas_vn-58820042.inet.0 2026-03-26 03:01:59,021 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:01:59,021 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 93.28.163.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.23, gateway password: c0ntrail123 2026-03-26 03:01:59,021 - DEBUG - ping -s 56 -c 3 -W 1 93.28.163.67 2026-03-26 03:02:03,010 - DEBUG - PING 93.28.163.67 (93.28.163.67): 56 data bytes 64 bytes from 93.28.163.67: seq=0 ttl=63 time=5.851 ms 64 bytes from 93.28.163.67: seq=1 ttl=63 time=1.102 ms 64 bytes from 93.28.163.67: seq=2 ttl=63 time=1.929 ms --- 93.28.163.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.102/2.960/5.851 ms 2026-03-26 03:02:03,010 - INFO - Ping to IP 93.28.163.67 from VM test_vm passed 2026-03-26 03:02:03,122 - INFO - Deleting VM test2_vm 2026-03-26 03:02:03,202 - INFO - Deleting VM test_vm 2026-03-26 03:02:03,283 - INFO - Deleting VN ctest-bgpaas_vn-49785849 2026-03-26 03:02:03,333 - DEBUG - VN f8afe3de-98fc-453c-9af3-e4b49ffc832f still in use: Unable to complete operation on network f8afe3de-98fc-453c-9af3-e4b49ffc832f. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-95a8feab-da03-4f61-a5ae-0baa293e900f'] 2026-03-26 03:02:03,333 - WARNING - Deleting VN ctest-bgpaas_vn-49785849 failed..Will retry 2026-03-26 03:02:05,510 - DEBUG - Response for deleting network () 2026-03-26 03:02:05,510 - INFO - Deleting VN ctest-bgpaas_vn-58820042 2026-03-26 03:02:05,721 - DEBUG - Response for deleting network () 2026-03-26 03:02:07,001 - DEBUG - No XMPP flaps were noticed during the test
2026-03-26 03:02:07,001 - INFO - END TEST : test_rp_interface : PASSED[0:00:28]
2026-03-26 03:02:07,001 - INFO - -------------------------------------------------------------------------------- 2026-03-26 03:02:07,005 - INFO - ================================================================================ 2026-03-26 03:02:07,005 - INFO - STARTING TEST : test_rp_interface_ext_community 2026-03-26 03:02:07,006 - 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-03-26 03:02:08,299 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.18': '0', '10.20.0.14': '0'}, '10.0.0.23': {'10.20.0.254': '0', '10.20.0.18': '0'}} with 2026-03-26 03:02:08,299 - INFO - Initial checks done. Running the testcase now 2026-03-26 03:02:08,299 - INFO - 2026-03-26 03:02:08,619 - DEBUG - Response for create_network : {'network': {'id': 'c38fe874-37a3-4ff3-8126-c74fddcd7020', 'name': 'ctest-bgpaas_vn-09005048', 'tenant_id': '85e89fdc39cf4752ba06ace0eae25d97', 'project_id': '85e89fdc39cf4752ba06ace0eae25d97', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-32789171', 'ctest-bgpaas_vn-09005048'], 'port_security_enabled': True, 'description': ''}} 2026-03-26 03:02:08,884 - DEBUG - Response for create_subnet : {'subnet': {'id': '4bbf73e3-92ac-43e3-81c2-62445bd5b536', 'name': '', 'tenant_id': '85e89fdc39cf4752ba06ace0eae25d97', 'network_id': 'c38fe874-37a3-4ff3-8126-c74fddcd7020', 'ip_version': 4, 'cidr': '159.44.187.192/26', 'allocation_pools': [{'start': '159.44.187.194', 'end': '159.44.187.254'}], 'gateway_ip': '159.44.187.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '159.44.187.194', 'tags': [], 'project_id': '85e89fdc39cf4752ba06ace0eae25d97'}} 2026-03-26 03:02:08,914 - INFO - Created VN ctest-bgpaas_vn-09005048 2026-03-26 03:02:08,926 - DEBUG - VN ctest-bgpaas_vn-09005048 UUID is c38fe874-37a3-4ff3-8126-c74fddcd7020 2026-03-26 03:02:09,465 - DEBUG - Response for create_network : {'network': {'id': 'fa30e5ed-68cc-488e-971c-ebb1d2a198d3', 'name': 'ctest-bgpaas_vn-94165508', 'tenant_id': '85e89fdc39cf4752ba06ace0eae25d97', 'project_id': '85e89fdc39cf4752ba06ace0eae25d97', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-32789171', 'ctest-bgpaas_vn-94165508'], 'port_security_enabled': True, 'description': ''}} 2026-03-26 03:02:09,766 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ae990ff6-b852-48fd-b9f5-cb569ce18766', 'name': '', 'tenant_id': '85e89fdc39cf4752ba06ace0eae25d97', 'network_id': 'fa30e5ed-68cc-488e-971c-ebb1d2a198d3', 'ip_version': 4, 'cidr': '222.253.118.128/26', 'allocation_pools': [{'start': '222.253.118.130', 'end': '222.253.118.190'}], 'gateway_ip': '222.253.118.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '222.253.118.130', 'tags': [], 'project_id': '85e89fdc39cf4752ba06ace0eae25d97'}} 2026-03-26 03:02:09,791 - INFO - Created VN ctest-bgpaas_vn-94165508 2026-03-26 03:02:09,806 - DEBUG - VN ctest-bgpaas_vn-94165508 UUID is fa30e5ed-68cc-488e-971c-ebb1d2a198d3 2026-03-26 03:02:11,288 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5545-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5545-2) 2026-03-26 03:02:12,954 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5545-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5545-1) 2026-03-26 03:02:12,954 - INFO - Waiting for VM test_vm to be up.. 2026-03-26 03:02:13,049 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-26 03:02:18,133 - DEBUG - VM is in ACTIVE state now 2026-03-26 03:02:18,133 - INFO - VM name : test_vm 2026-03-26 03:02:18,215 - DEBUG - VM test_vm ID is 594c5004-0867-4c80-970e-05b11462c3f7 2026-03-26 03:02:18,215 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-5545-2 2026-03-26 03:02:18,299 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/594c5004-0867-4c80-970e-05b11462c3f7 2026-03-26 03:02:18,310 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/1da5a233-99dd-4587-b8f5-1b5079d54c6e 2026-03-26 03:02:21,478 - 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 1007ms') 2026-03-26 03:02:21,478 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed! 2026-03-26 03:02:21,495 - DEBUG - Gateway for vn default-domain:ctest-TestRP-32789171:ctest-bgpaas_vn-09005048 is 159.44.187.193 and allocation pool is NOT set 2026-03-26 03:02:23,557 - 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=2.66 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.423 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.423/1.540/2.658/1.117 ms') 2026-03-26 03:02:23,557 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed 2026-03-26 03:02:23,628 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:02:23,628 - DEBUG - Waiting to SSH to VM test_vm, IP 159.44.187.195, Port 22 2026-03-26 03:02:23,797 - DEBUG - VM test_vm is ready for SSH connections 2026-03-26 03:02:23,797 - INFO - Waiting for VM test2_vm to be up.. 2026-03-26 03:02:23,889 - DEBUG - VM is in ACTIVE state now 2026-03-26 03:02:23,890 - INFO - VM name : test2_vm 2026-03-26 03:02:23,988 - DEBUG - VM test2_vm ID is 4e78d07e-b7ad-408e-83ae-5ce0cb206d03 2026-03-26 03:02:23,988 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-5545-1 2026-03-26 03:02:24,086 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/4e78d07e-b7ad-408e-83ae-5ce0cb206d03 2026-03-26 03:02:24,098 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/39139307-cbda-4bed-913b-d2b130c0f0cc 2026-03-26 03:02:25,255 - 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.74 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.40 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.402/3.571/5.741/2.169 ms') 2026-03-26 03:02:25,255 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed 2026-03-26 03:02:25,324 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:02:25,324 - DEBUG - Waiting to SSH to VM test2_vm, IP 222.253.118.131, Port 22 2026-03-26 03:02:25,479 - DEBUG - VM test2_vm is ready for SSH connections 2026-03-26 03:02:25,639 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:02:25,650 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-32789171:ctest-bgpaas_vn-09005048:ctest-bgpaas_vn-09005048.inet.0 2026-03-26 03:02:25,662 - DEBUG - Requesting: http://10.20.0.18:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-32789171:ctest-bgpaas_vn-09005048:ctest-bgpaas_vn-09005048.inet.0 2026-03-26 03:02:25,675 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:02:25,675 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 222.253.118.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.23, gateway password: c0ntrail123 2026-03-26 03:02:25,676 - DEBUG - ping -s 56 -c 3 -W 1 222.253.118.131 2026-03-26 03:02:29,358 - DEBUG - PING 222.253.118.131 (222.253.118.131): 56 data bytes 64 bytes from 222.253.118.131: seq=0 ttl=63 time=6.394 ms 64 bytes from 222.253.118.131: seq=1 ttl=63 time=1.399 ms 64 bytes from 222.253.118.131: seq=2 ttl=63 time=1.650 ms --- 222.253.118.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.399/3.147/6.394 ms 2026-03-26 03:02:29,359 - INFO - Ping to IP 222.253.118.131 from VM test_vm passed 2026-03-26 03:02:29,507 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:02:29,526 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-32789171:ctest-bgpaas_vn-09005048:ctest-bgpaas_vn-09005048.inet.0 2026-03-26 03:02:29,545 - DEBUG - Requesting: http://10.20.0.18:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-32789171:ctest-bgpaas_vn-09005048:ctest-bgpaas_vn-09005048.inet.0 2026-03-26 03:02:29,806 - INFO - Deleting VM test2_vm 2026-03-26 03:02:29,884 - INFO - Deleting VM test_vm 2026-03-26 03:02:29,970 - INFO - Deleting VN ctest-bgpaas_vn-94165508 2026-03-26 03:02:30,013 - DEBUG - VN fa30e5ed-68cc-488e-971c-ebb1d2a198d3 still in use: Unable to complete operation on network fa30e5ed-68cc-488e-971c-ebb1d2a198d3. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-af1ef571-3a6f-4ce2-aa15-9f01ece3c1ac'] 2026-03-26 03:02:30,013 - WARNING - Deleting VN ctest-bgpaas_vn-94165508 failed..Will retry 2026-03-26 03:02:32,192 - DEBUG - Response for deleting network () 2026-03-26 03:02:32,192 - INFO - Deleting VN ctest-bgpaas_vn-09005048 2026-03-26 03:02:32,355 - DEBUG - Response for deleting network () 2026-03-26 03:02:33,642 - DEBUG - No XMPP flaps were noticed during the test
2026-03-26 03:02:33,642 - INFO - END TEST : test_rp_interface_ext_community : PASSED[0:00:26]
2026-03-26 03:02:33,642 - INFO - -------------------------------------------------------------------------------- 2026-03-26 03:02:33,647 - INFO - ================================================================================ 2026-03-26 03:02:33,647 - INFO - STARTING TEST : test_rp_interface_static 2026-03-26 03:02:33,647 - 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-03-26 03:02:34,909 - DEBUG - Nothing to compare xmpp stats {'10.0.0.38': {'10.20.0.18': '0', '10.20.0.14': '0'}, '10.0.0.23': {'10.20.0.254': '0', '10.20.0.18': '0'}} with 2026-03-26 03:02:34,909 - INFO - Initial checks done. Running the testcase now 2026-03-26 03:02:34,909 - INFO - 2026-03-26 03:02:35,205 - DEBUG - Response for create_network : {'network': {'id': '95eecd2d-f981-4ad4-b6fb-6e700ffe2a49', 'name': 'ctest-bgpaas_vn-90154389', 'tenant_id': '85e89fdc39cf4752ba06ace0eae25d97', 'project_id': '85e89fdc39cf4752ba06ace0eae25d97', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-32789171', 'ctest-bgpaas_vn-90154389'], 'port_security_enabled': True, 'description': ''}} 2026-03-26 03:02:35,458 - DEBUG - Response for create_subnet : {'subnet': {'id': '5d86dc7a-9eaa-483d-899e-eb33e6964d12', 'name': '', 'tenant_id': '85e89fdc39cf4752ba06ace0eae25d97', 'network_id': '95eecd2d-f981-4ad4-b6fb-6e700ffe2a49', 'ip_version': 4, 'cidr': '214.152.18.64/26', 'allocation_pools': [{'start': '214.152.18.66', 'end': '214.152.18.126'}], 'gateway_ip': '214.152.18.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '214.152.18.66', 'tags': [], 'project_id': '85e89fdc39cf4752ba06ace0eae25d97'}} 2026-03-26 03:02:35,490 - INFO - Created VN ctest-bgpaas_vn-90154389 2026-03-26 03:02:35,506 - DEBUG - VN ctest-bgpaas_vn-90154389 UUID is 95eecd2d-f981-4ad4-b6fb-6e700ffe2a49 2026-03-26 03:02:36,129 - DEBUG - Response for create_network : {'network': {'id': '9a6ac310-7bc2-41f3-8b99-23ecd2730ff4', 'name': 'ctest-bgpaas_vn-83198587', 'tenant_id': '85e89fdc39cf4752ba06ace0eae25d97', 'project_id': '85e89fdc39cf4752ba06ace0eae25d97', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-32789171', 'ctest-bgpaas_vn-83198587'], 'port_security_enabled': True, 'description': ''}} 2026-03-26 03:02:36,356 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e21bdb2e-a803-49d6-91cb-69d9301de330', 'name': '', 'tenant_id': '85e89fdc39cf4752ba06ace0eae25d97', 'network_id': '9a6ac310-7bc2-41f3-8b99-23ecd2730ff4', 'ip_version': 4, 'cidr': '114.247.59.64/26', 'allocation_pools': [{'start': '114.247.59.66', 'end': '114.247.59.126'}], 'gateway_ip': '114.247.59.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '114.247.59.66', 'tags': [], 'project_id': '85e89fdc39cf4752ba06ace0eae25d97'}} 2026-03-26 03:02:36,383 - INFO - Created VN ctest-bgpaas_vn-83198587 2026-03-26 03:02:36,393 - DEBUG - VN ctest-bgpaas_vn-83198587 UUID is 9a6ac310-7bc2-41f3-8b99-23ecd2730ff4 2026-03-26 03:02:37,958 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5545-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5545-2) 2026-03-26 03:02:39,591 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5545-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5545-1) 2026-03-26 03:02:39,591 - INFO - Waiting for VM test_vm to be up.. 2026-03-26 03:02:39,691 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-26 03:02:44,775 - DEBUG - VM is in ACTIVE state now 2026-03-26 03:02:44,775 - INFO - VM name : test_vm 2026-03-26 03:02:44,859 - DEBUG - VM test_vm ID is 74b6eeba-9850-423c-8830-ea2cd0ad3545 2026-03-26 03:02:44,859 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-5545-2 2026-03-26 03:02:44,939 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/74b6eeba-9850-423c-8830-ea2cd0ad3545 2026-03-26 03:02:44,948 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/e411a5a9-c11a-41cb-bb95-c6ed7e14e739 2026-03-26 03:02:48,136 - 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 1035ms') 2026-03-26 03:02:48,137 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed! 2026-03-26 03:02:48,154 - DEBUG - Gateway for vn default-domain:ctest-TestRP-32789171:ctest-bgpaas_vn-90154389 is 214.152.18.65 and allocation pool is NOT set 2026-03-26 03:02:50,212 - 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.29 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.471 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.471/2.880/5.289/2.409 ms') 2026-03-26 03:02:50,213 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed 2026-03-26 03:02:50,285 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:02:50,285 - DEBUG - Waiting to SSH to VM test_vm, IP 214.152.18.67, Port 22 2026-03-26 03:02:50,445 - DEBUG - VM test_vm is ready for SSH connections 2026-03-26 03:02:50,445 - INFO - Waiting for VM test2_vm to be up.. 2026-03-26 03:02:50,556 - DEBUG - VM is in ACTIVE state now 2026-03-26 03:02:50,557 - INFO - VM name : test2_vm 2026-03-26 03:02:50,666 - DEBUG - VM test2_vm ID is 95c88d23-2c68-4414-9d46-ea554db3576c 2026-03-26 03:02:50,666 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-5545-1 2026-03-26 03:02:50,755 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/95c88d23-2c68-4414-9d46-ea554db3576c 2026-03-26 03:02:50,766 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/c7ea2541-ffbf-4e07-afef-4a2068ec6dcb 2026-03-26 03:02:51,923 - 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=2.95 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.486 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.486/1.717/2.948/1.231 ms') 2026-03-26 03:02:51,923 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed 2026-03-26 03:02:51,992 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:02:51,992 - DEBUG - Waiting to SSH to VM test2_vm, IP 114.247.59.67, Port 22 2026-03-26 03:02:52,158 - DEBUG - VM test2_vm is ready for SSH connections 2026-03-26 03:02:52,255 - INFO - Created InterfaceRouteTable ['default-domain', 'ctest-TestRP-32789171', 'ctest-int_table_right-11011617'](UUID 6d543656-c199-4a20-bce7-692583baec79), prefixes : ['223.135.233.64/26'] 2026-03-26 03:02:52,323 - INFO - Added intf route table 6d543656-c199-4a20-bce7-692583baec79 to port e411a5a9-c11a-41cb-bb95-c6ed7e14e739 2026-03-26 03:02:52,537 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-32789171:ctest-bgpaas_vn-90154389:ctest-bgpaas_vn-90154389.inet.0 2026-03-26 03:02:52,553 - DEBUG - Requesting: http://10.20.0.18:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-32789171:ctest-bgpaas_vn-90154389:ctest-bgpaas_vn-90154389.inet.0 2026-03-26 03:02:52,567 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-26 03:02:52,568 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 114.247.59.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.23, gateway password: c0ntrail123 2026-03-26 03:02:52,568 - DEBUG - ping -s 56 -c 3 -W 1 114.247.59.67 2026-03-26 03:02:56,232 - DEBUG - PING 114.247.59.67 (114.247.59.67): 56 data bytes 64 bytes from 114.247.59.67: seq=0 ttl=63 time=6.200 ms 64 bytes from 114.247.59.67: seq=1 ttl=63 time=1.060 ms 64 bytes from 114.247.59.67: seq=2 ttl=63 time=1.065 ms --- 114.247.59.67 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.060/2.775/6.200 ms 2026-03-26 03:02:56,232 - INFO - Ping to IP 114.247.59.67 from VM test_vm passed 2026-03-26 03:02:56,340 - INFO - Deleting VM test2_vm 2026-03-26 03:02:56,425 - INFO - Deleting VM test_vm 2026-03-26 03:02:56,501 - INFO - Deleting VN ctest-bgpaas_vn-83198587 2026-03-26 03:02:56,553 - DEBUG - VN 9a6ac310-7bc2-41f3-8b99-23ecd2730ff4 still in use: Unable to complete operation on network 9a6ac310-7bc2-41f3-8b99-23ecd2730ff4. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-bf29ac6b-4091-41f9-a6f2-db48c2035069'] 2026-03-26 03:02:56,554 - WARNING - Deleting VN ctest-bgpaas_vn-83198587 failed..Will retry 2026-03-26 03:02:58,703 - DEBUG - Response for deleting network () 2026-03-26 03:02:58,703 - INFO - Deleting VN ctest-bgpaas_vn-90154389 2026-03-26 03:02:58,886 - DEBUG - Response for deleting network () 2026-03-26 03:03:00,174 - DEBUG - No XMPP flaps were noticed during the test
2026-03-26 03:03:00,174 - INFO - END TEST : test_rp_interface_static : PASSED[0:00:27]
2026-03-26 03:03:00,174 - INFO - -------------------------------------------------------------------------------- 2026-03-26 03:03:01,034 - INFO - Deleted project: ctest-TestRP-32789171, ID : 85e89fdc-39cf-4752-ba06-ace0eae25d97