2026-04-30 03:22:07,116 - INFO - Domain Default found not creating
2026-04-30 03:22:07,298 - INFO - Project ctest-TestRP-02718464 not found, creating it
2026-04-30 03:22:07,922 - INFO - Created Project:ctest-TestRP-02718464, ID : de10e5c7-ff42-4f26-89f5-69453a8999b0
2026-04-30 03:22:09,543 - DEBUG - Services list from nova: [, , , ]
2026-04-30 03:22:09,543 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-5725-2', 'an-jenkins-deploy-platform-ansible-os-5725-1']}
2026-04-30 03:22:09,546 - INFO - ================================================================================
2026-04-30 03:22:09,546 - INFO - STARTING TEST : test_rp_interface
2026-04-30 03:22:09,546 - 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-04-30 03:22:10,857 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.193': '0', '10.20.0.18': '0'}, '10.0.0.241': {'10.20.0.193': '0', '10.20.0.18': '0'}} with
2026-04-30 03:22:10,857 - INFO - Initial checks done. Running the testcase now
2026-04-30 03:22:10,857 - INFO -
2026-04-30 03:22:11,550 - DEBUG - Response for create_network : {'network': {'id': 'eab6e6b2-dec2-4fb0-acdb-5aade7bd187f', 'name': 'ctest-bgpaas_vn-01949266', 'tenant_id': 'de10e5c7ff424f2689f569453a8999b0', 'project_id': 'de10e5c7ff424f2689f569453a8999b0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-02718464', 'ctest-bgpaas_vn-01949266'], 'port_security_enabled': True, 'description': ''}}
2026-04-30 03:22:11,787 - DEBUG - Response for create_subnet : {'subnet': {'id': '3fe90b04-6d8c-4ed6-95bf-8194e2c1b267', 'name': '', 'tenant_id': 'de10e5c7ff424f2689f569453a8999b0', 'network_id': 'eab6e6b2-dec2-4fb0-acdb-5aade7bd187f', 'ip_version': 4, 'cidr': '157.80.6.0/26', 'allocation_pools': [{'start': '157.80.6.2', 'end': '157.80.6.62'}], 'gateway_ip': '157.80.6.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '157.80.6.2', 'tags': [], 'project_id': 'de10e5c7ff424f2689f569453a8999b0'}}
2026-04-30 03:22:11,816 - INFO - Created VN ctest-bgpaas_vn-01949266
2026-04-30 03:22:11,831 - DEBUG - VN ctest-bgpaas_vn-01949266 UUID is eab6e6b2-dec2-4fb0-acdb-5aade7bd187f
2026-04-30 03:22:12,350 - DEBUG - Response for create_network : {'network': {'id': '7edb3119-71a3-44f4-b83e-9cef0c36ee64', 'name': 'ctest-bgpaas_vn-11645141', 'tenant_id': 'de10e5c7ff424f2689f569453a8999b0', 'project_id': 'de10e5c7ff424f2689f569453a8999b0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-02718464', 'ctest-bgpaas_vn-11645141'], 'port_security_enabled': True, 'description': ''}}
2026-04-30 03:22:12,611 - DEBUG - Response for create_subnet : {'subnet': {'id': '18d1811b-0adc-4f1d-b56c-042d39c156ec', 'name': '', 'tenant_id': 'de10e5c7ff424f2689f569453a8999b0', 'network_id': '7edb3119-71a3-44f4-b83e-9cef0c36ee64', 'ip_version': 4, 'cidr': '100.7.193.0/26', 'allocation_pools': [{'start': '100.7.193.2', 'end': '100.7.193.62'}], 'gateway_ip': '100.7.193.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '100.7.193.2', 'tags': [], 'project_id': 'de10e5c7ff424f2689f569453a8999b0'}}
2026-04-30 03:22:12,638 - INFO - Created VN ctest-bgpaas_vn-11645141
2026-04-30 03:22:12,651 - DEBUG - VN ctest-bgpaas_vn-11645141 UUID is 7edb3119-71a3-44f4-b83e-9cef0c36ee64
2026-04-30 03:22:14,023 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5725-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5725-2)
2026-04-30 03:22:15,297 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5725-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5725-1)
2026-04-30 03:22:15,298 - INFO - Waiting for VM test_vm to be up..
2026-04-30 03:22:15,386 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-04-30 03:22:20,470 - DEBUG - VM is in ACTIVE state now
2026-04-30 03:22:20,470 - INFO - VM name : test_vm
2026-04-30 03:22:20,554 - DEBUG - VM test_vm ID is b3130c84-0195-417f-bad7-a171cf3ae350
2026-04-30 03:22:20,578 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-5725-2
2026-04-30 03:22:20,661 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/b3130c84-0195-417f-bad7-a171cf3ae350
2026-04-30 03:22:20,963 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/b3130c84-0195-417f-bad7-a171cf3ae350
2026-04-30 03:22:21,000 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/b9e7aedb-e732-44e9-8af4-fcbc53b171a4
2026-04-30 03:22:24,199 - 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 1018ms')
2026-04-30 03:22:24,199 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2026-04-30 03:22:24,213 - DEBUG - Gateway for vn default-domain:ctest-TestRP-02718464:ctest-bgpaas_vn-01949266 is 157.80.6.1 and allocation pool is NOT set
2026-04-30 03:22:26,284 - 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=7.23 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.640 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.640/3.936/7.233/3.296 ms')
2026-04-30 03:22:26,284 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2026-04-30 03:22:26,361 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 03:22:26,361 - DEBUG - Waiting to SSH to VM test_vm, IP 157.80.6.3, Port 22
2026-04-30 03:22:26,527 - DEBUG - VM test_vm is ready for SSH connections
2026-04-30 03:22:26,527 - INFO - Waiting for VM test2_vm to be up..
2026-04-30 03:22:26,619 - DEBUG - VM is in ACTIVE state now
2026-04-30 03:22:26,619 - INFO - VM name : test2_vm
2026-04-30 03:22:26,701 - DEBUG - VM test2_vm ID is 4c228086-c0a3-4a6a-b617-5ca6bfdfacbb
2026-04-30 03:22:26,701 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-5725-1
2026-04-30 03:22:26,780 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/4c228086-c0a3-4a6a-b617-5ca6bfdfacbb
2026-04-30 03:22:26,791 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/df0b1cb6-b9f3-4d9b-bb32-2ad9af666f6a
2026-04-30 03:22:27,950 - 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.02 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.534 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.534/1.776/3.019/1.242 ms')
2026-04-30 03:22:27,950 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2026-04-30 03:22:28,022 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 03:22:28,023 - DEBUG - Waiting to SSH to VM test2_vm, IP 100.7.193.3, Port 22
2026-04-30 03:22:28,181 - DEBUG - VM test2_vm is ready for SSH connections
2026-04-30 03:22:28,338 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 03:22:28,350 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-02718464:ctest-bgpaas_vn-01949266:ctest-bgpaas_vn-01949266.inet.0
2026-04-30 03:22:28,360 - DEBUG - Requesting: http://10.20.0.18:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-02718464:ctest-bgpaas_vn-01949266:ctest-bgpaas_vn-01949266.inet.0
2026-04-30 03:22:28,373 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 03:22:28,373 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 100.7.193.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2026-04-30 03:22:28,373 - DEBUG - ping -s 56 -c 3 -W 1 100.7.193.3
2026-04-30 03:22:32,335 - DEBUG - PING 100.7.193.3 (100.7.193.3): 56 data bytes
64 bytes from 100.7.193.3: seq=0 ttl=63 time=7.077 ms
64 bytes from 100.7.193.3: seq=1 ttl=63 time=1.498 ms
64 bytes from 100.7.193.3: seq=2 ttl=63 time=1.707 ms
--- 100.7.193.3 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.498/3.427/7.077 ms
2026-04-30 03:22:32,335 - INFO - Ping to IP 100.7.193.3 from VM test_vm passed
2026-04-30 03:22:32,455 - INFO - Deleting VM test2_vm
2026-04-30 03:22:32,523 - INFO - Deleting VM test_vm
2026-04-30 03:22:32,595 - INFO - Deleting VN ctest-bgpaas_vn-11645141
2026-04-30 03:22:32,644 - DEBUG - VN 7edb3119-71a3-44f4-b83e-9cef0c36ee64 still in use: Unable to complete operation on network 7edb3119-71a3-44f4-b83e-9cef0c36ee64. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f87236b8-8885-4522-8157-7feee4c157c9']
2026-04-30 03:22:32,644 - WARNING - Deleting VN ctest-bgpaas_vn-11645141 failed..Will retry
2026-04-30 03:22:34,807 - DEBUG - Response for deleting network ()
2026-04-30 03:22:34,807 - INFO - Deleting VN ctest-bgpaas_vn-01949266
2026-04-30 03:22:35,020 - DEBUG - Response for deleting network ()
2026-04-30 03:22:36,332 - DEBUG - No XMPP flaps were noticed during the test
2026-04-30 03:22:36,332 - INFO - END TEST : test_rp_interface : PASSED[0:00:27]
2026-04-30 03:22:36,332 - INFO - --------------------------------------------------------------------------------
2026-04-30 03:22:36,337 - INFO - ================================================================================
2026-04-30 03:22:36,337 - INFO - STARTING TEST : test_rp_interface_ext_community
2026-04-30 03:22:36,337 - 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-04-30 03:22:37,668 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.193': '0', '10.20.0.18': '0'}, '10.0.0.241': {'10.20.0.193': '0', '10.20.0.18': '0'}} with
2026-04-30 03:22:37,668 - INFO - Initial checks done. Running the testcase now
2026-04-30 03:22:37,668 - INFO -
2026-04-30 03:22:37,996 - DEBUG - Response for create_network : {'network': {'id': '9bb88cf9-8a26-4b78-9a3b-a601e32fbf9f', 'name': 'ctest-bgpaas_vn-30520612', 'tenant_id': 'de10e5c7ff424f2689f569453a8999b0', 'project_id': 'de10e5c7ff424f2689f569453a8999b0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-02718464', 'ctest-bgpaas_vn-30520612'], 'port_security_enabled': True, 'description': ''}}
2026-04-30 03:22:38,272 - DEBUG - Response for create_subnet : {'subnet': {'id': '29f0405b-db99-41fe-b136-a254ecdc5d4b', 'name': '', 'tenant_id': 'de10e5c7ff424f2689f569453a8999b0', 'network_id': '9bb88cf9-8a26-4b78-9a3b-a601e32fbf9f', 'ip_version': 4, 'cidr': '132.153.103.192/26', 'allocation_pools': [{'start': '132.153.103.194', 'end': '132.153.103.254'}], 'gateway_ip': '132.153.103.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '132.153.103.194', 'tags': [], 'project_id': 'de10e5c7ff424f2689f569453a8999b0'}}
2026-04-30 03:22:38,295 - INFO - Created VN ctest-bgpaas_vn-30520612
2026-04-30 03:22:38,308 - DEBUG - VN ctest-bgpaas_vn-30520612 UUID is 9bb88cf9-8a26-4b78-9a3b-a601e32fbf9f
2026-04-30 03:22:38,920 - DEBUG - Response for create_network : {'network': {'id': '3a2978c6-7a02-4753-86de-1a803fd596c2', 'name': 'ctest-bgpaas_vn-10853614', 'tenant_id': 'de10e5c7ff424f2689f569453a8999b0', 'project_id': 'de10e5c7ff424f2689f569453a8999b0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-02718464', 'ctest-bgpaas_vn-10853614'], 'port_security_enabled': True, 'description': ''}}
2026-04-30 03:22:39,181 - DEBUG - Response for create_subnet : {'subnet': {'id': 'dcc0b7ae-bd3e-4e00-ae31-4f9d54af84e6', 'name': '', 'tenant_id': 'de10e5c7ff424f2689f569453a8999b0', 'network_id': '3a2978c6-7a02-4753-86de-1a803fd596c2', 'ip_version': 4, 'cidr': '3.180.152.128/26', 'allocation_pools': [{'start': '3.180.152.130', 'end': '3.180.152.190'}], 'gateway_ip': '3.180.152.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '3.180.152.130', 'tags': [], 'project_id': 'de10e5c7ff424f2689f569453a8999b0'}}
2026-04-30 03:22:39,203 - INFO - Created VN ctest-bgpaas_vn-10853614
2026-04-30 03:22:39,221 - DEBUG - VN ctest-bgpaas_vn-10853614 UUID is 3a2978c6-7a02-4753-86de-1a803fd596c2
2026-04-30 03:22:40,532 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5725-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5725-2)
2026-04-30 03:22:41,887 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5725-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5725-1)
2026-04-30 03:22:41,887 - INFO - Waiting for VM test_vm to be up..
2026-04-30 03:22:41,980 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-04-30 03:22:47,062 - DEBUG - VM is in ACTIVE state now
2026-04-30 03:22:47,062 - INFO - VM name : test_vm
2026-04-30 03:22:47,148 - DEBUG - VM test_vm ID is 062ddf17-0bbf-4d43-926a-0b2338e611ef
2026-04-30 03:22:47,148 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-5725-2
2026-04-30 03:22:47,235 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/062ddf17-0bbf-4d43-926a-0b2338e611ef
2026-04-30 03:22:47,247 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/ea6cde95-5ab2-492c-a1de-4b4403452737
2026-04-30 03:22:50,438 - 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 1017ms')
2026-04-30 03:22:50,439 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2026-04-30 03:22:50,456 - DEBUG - Gateway for vn default-domain:ctest-TestRP-02718464:ctest-bgpaas_vn-30520612 is 132.153.103.193 and allocation pool is NOT set
2026-04-30 03:22:52,531 - 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.57 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.79 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 = 2.786/3.677/4.568/0.891 ms')
2026-04-30 03:22:52,531 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2026-04-30 03:22:52,611 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 03:22:52,611 - DEBUG - Waiting to SSH to VM test_vm, IP 132.153.103.195, Port 22
2026-04-30 03:22:52,787 - DEBUG - VM test_vm is ready for SSH connections
2026-04-30 03:22:52,787 - INFO - Waiting for VM test2_vm to be up..
2026-04-30 03:22:52,867 - DEBUG - VM is in ACTIVE state now
2026-04-30 03:22:52,867 - INFO - VM name : test2_vm
2026-04-30 03:22:52,945 - DEBUG - VM test2_vm ID is 4fe56352-0ef2-46bf-9528-21c940a1025a
2026-04-30 03:22:52,945 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-5725-1
2026-04-30 03:22:53,024 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/4fe56352-0ef2-46bf-9528-21c940a1025a
2026-04-30 03:22:53,035 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/efbfa431-52ef-4879-a96d-39ad1ceb339a
2026-04-30 03:22:54,199 - 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.81 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.838 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.838/2.825/4.813/1.987 ms')
2026-04-30 03:22:54,199 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2026-04-30 03:22:54,271 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 03:22:54,271 - DEBUG - Waiting to SSH to VM test2_vm, IP 3.180.152.131, Port 22
2026-04-30 03:22:54,437 - DEBUG - VM test2_vm is ready for SSH connections
2026-04-30 03:22:54,610 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 03:22:54,622 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-02718464:ctest-bgpaas_vn-30520612:ctest-bgpaas_vn-30520612.inet.0
2026-04-30 03:22:54,632 - DEBUG - Requesting: http://10.20.0.18:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-02718464:ctest-bgpaas_vn-30520612:ctest-bgpaas_vn-30520612.inet.0
2026-04-30 03:22:54,645 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 03:22:54,645 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 3.180.152.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2026-04-30 03:22:54,646 - DEBUG - ping -s 56 -c 3 -W 1 3.180.152.131
2026-04-30 03:22:58,337 - DEBUG - PING 3.180.152.131 (3.180.152.131): 56 data bytes
64 bytes from 3.180.152.131: seq=0 ttl=63 time=7.005 ms
64 bytes from 3.180.152.131: seq=1 ttl=63 time=1.894 ms
64 bytes from 3.180.152.131: seq=2 ttl=63 time=1.871 ms
--- 3.180.152.131 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.871/3.590/7.005 ms
2026-04-30 03:22:58,337 - INFO - Ping to IP 3.180.152.131 from VM test_vm passed
2026-04-30 03:22:58,498 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 03:22:58,516 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-02718464:ctest-bgpaas_vn-30520612:ctest-bgpaas_vn-30520612.inet.0
2026-04-30 03:22:58,526 - DEBUG - Requesting: http://10.20.0.18:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-02718464:ctest-bgpaas_vn-30520612:ctest-bgpaas_vn-30520612.inet.0
2026-04-30 03:22:58,815 - INFO - Deleting VM test2_vm
2026-04-30 03:22:58,879 - INFO - Deleting VM test_vm
2026-04-30 03:22:58,966 - INFO - Deleting VN ctest-bgpaas_vn-10853614
2026-04-30 03:22:59,022 - DEBUG - VN 3a2978c6-7a02-4753-86de-1a803fd596c2 still in use: Unable to complete operation on network 3a2978c6-7a02-4753-86de-1a803fd596c2. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ee3d103a-c01e-49c5-921b-8a29ac4d468f']
2026-04-30 03:22:59,022 - WARNING - Deleting VN ctest-bgpaas_vn-10853614 failed..Will retry
2026-04-30 03:23:01,198 - DEBUG - Response for deleting network ()
2026-04-30 03:23:01,198 - INFO - Deleting VN ctest-bgpaas_vn-30520612
2026-04-30 03:23:01,439 - DEBUG - Response for deleting network ()
2026-04-30 03:23:02,730 - DEBUG - No XMPP flaps were noticed during the test
2026-04-30 03:23:02,730 - INFO - --------------------------------------------------------------------------------
2026-04-30 03:23:02,736 - INFO - ================================================================================
2026-04-30 03:23:02,736 - INFO - STARTING TEST : test_rp_interface_static
2026-04-30 03:23:02,736 - 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-04-30 03:23:04,037 - DEBUG - Nothing to compare xmpp stats {'10.0.0.19': {'10.20.0.193': '0', '10.20.0.18': '0'}, '10.0.0.241': {'10.20.0.193': '0', '10.20.0.18': '0'}} with
2026-04-30 03:23:04,037 - INFO - Initial checks done. Running the testcase now
2026-04-30 03:23:04,037 - INFO -
2026-04-30 03:23:04,333 - DEBUG - Response for create_network : {'network': {'id': '5b447571-233d-40e4-a918-cbdcc8316c91', 'name': 'ctest-bgpaas_vn-82268514', 'tenant_id': 'de10e5c7ff424f2689f569453a8999b0', 'project_id': 'de10e5c7ff424f2689f569453a8999b0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-02718464', 'ctest-bgpaas_vn-82268514'], 'port_security_enabled': True, 'description': ''}}
2026-04-30 03:23:04,554 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f17408a7-2cf8-4d48-b6a6-18eb9d75cd1d', 'name': '', 'tenant_id': 'de10e5c7ff424f2689f569453a8999b0', 'network_id': '5b447571-233d-40e4-a918-cbdcc8316c91', 'ip_version': 4, 'cidr': '159.239.239.64/26', 'allocation_pools': [{'start': '159.239.239.66', 'end': '159.239.239.126'}], 'gateway_ip': '159.239.239.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '159.239.239.66', 'tags': [], 'project_id': 'de10e5c7ff424f2689f569453a8999b0'}}
2026-04-30 03:23:04,577 - INFO - Created VN ctest-bgpaas_vn-82268514
2026-04-30 03:23:04,589 - DEBUG - VN ctest-bgpaas_vn-82268514 UUID is 5b447571-233d-40e4-a918-cbdcc8316c91
2026-04-30 03:23:05,192 - DEBUG - Response for create_network : {'network': {'id': '7f1a7027-91f9-49a0-98e6-0dd687b976f7', 'name': 'ctest-bgpaas_vn-70647730', 'tenant_id': 'de10e5c7ff424f2689f569453a8999b0', 'project_id': 'de10e5c7ff424f2689f569453a8999b0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-02718464', 'ctest-bgpaas_vn-70647730'], 'port_security_enabled': True, 'description': ''}}
2026-04-30 03:23:05,489 - DEBUG - Response for create_subnet : {'subnet': {'id': '1577cb7c-9d98-4832-9d91-4395ca9b81e8', 'name': '', 'tenant_id': 'de10e5c7ff424f2689f569453a8999b0', 'network_id': '7f1a7027-91f9-49a0-98e6-0dd687b976f7', 'ip_version': 4, 'cidr': '89.208.252.0/26', 'allocation_pools': [{'start': '89.208.252.2', 'end': '89.208.252.62'}], 'gateway_ip': '89.208.252.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '89.208.252.2', 'tags': [], 'project_id': 'de10e5c7ff424f2689f569453a8999b0'}}
2026-04-30 03:23:05,513 - INFO - Created VN ctest-bgpaas_vn-70647730
2026-04-30 03:23:05,527 - DEBUG - VN ctest-bgpaas_vn-70647730 UUID is 7f1a7027-91f9-49a0-98e6-0dd687b976f7
2026-04-30 03:23:06,908 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5725-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5725-2)
2026-04-30 03:23:08,421 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5725-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5725-1)
2026-04-30 03:23:08,421 - INFO - Waiting for VM test_vm to be up..
2026-04-30 03:23:08,535 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-04-30 03:23:13,626 - DEBUG - VM is in ACTIVE state now
2026-04-30 03:23:13,626 - INFO - VM name : test_vm
2026-04-30 03:23:13,705 - DEBUG - VM test_vm ID is 34912825-0b7f-44c3-ba2d-0455c05f6d58
2026-04-30 03:23:13,705 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-5725-2
2026-04-30 03:23:13,781 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/34912825-0b7f-44c3-ba2d-0455c05f6d58
2026-04-30 03:23:13,793 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/c090e66b-61ac-498b-aea5-5335eb1c4525
2026-04-30 03:23:16,968 - 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-04-30 03:23:16,968 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2026-04-30 03:23:16,984 - DEBUG - Gateway for vn default-domain:ctest-TestRP-02718464:ctest-bgpaas_vn-82268514 is 159.239.239.65 and allocation pool is NOT set
2026-04-30 03:23:19,050 - 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.64 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.558 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.558/2.099/3.641/1.541 ms')
2026-04-30 03:23:19,050 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2026-04-30 03:23:19,119 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 03:23:19,119 - DEBUG - Waiting to SSH to VM test_vm, IP 159.239.239.67, Port 22
2026-04-30 03:23:19,188 - 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-04-30 03:23:19,273 - DEBUG - VM test_vm is NOT ready for SSH connections, VM status: ACTIVE
2026-04-30 03:23:24,273 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 03:23:24,273 - DEBUG - Waiting to SSH to VM test_vm, IP 159.239.239.67, Port 22
2026-04-30 03:23:24,444 - DEBUG - VM test_vm is ready for SSH connections
2026-04-30 03:23:24,445 - INFO - Waiting for VM test2_vm to be up..
2026-04-30 03:23:24,531 - DEBUG - VM is in ACTIVE state now
2026-04-30 03:23:24,531 - INFO - VM name : test2_vm
2026-04-30 03:23:24,611 - DEBUG - VM test2_vm ID is 6d119fb3-c2cb-4fb7-a842-320674b97824
2026-04-30 03:23:24,611 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-5725-1
2026-04-30 03:23:24,696 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/6d119fb3-c2cb-4fb7-a842-320674b97824
2026-04-30 03:23:24,707 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/11b6ef42-9473-4e0f-8f17-029e866fc4d1
2026-04-30 03:23:25,868 - 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.78 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.400 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.400/2.090/3.780/1.690 ms')
2026-04-30 03:23:25,868 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2026-04-30 03:23:25,939 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 03:23:25,939 - DEBUG - Waiting to SSH to VM test2_vm, IP 89.208.252.3, Port 22
2026-04-30 03:23:26,102 - DEBUG - VM test2_vm is ready for SSH connections
2026-04-30 03:23:26,198 - INFO - Created InterfaceRouteTable ['default-domain', 'ctest-TestRP-02718464', 'ctest-int_table_right-15243965'](UUID def5f5f8-c3cd-4cf4-9834-a73f236bc964), prefixes : ['148.224.197.0/26']
2026-04-30 03:23:26,270 - INFO - Added intf route table def5f5f8-c3cd-4cf4-9834-a73f236bc964 to port c090e66b-61ac-498b-aea5-5335eb1c4525
2026-04-30 03:23:26,467 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-02718464:ctest-bgpaas_vn-82268514:ctest-bgpaas_vn-82268514.inet.0
2026-04-30 03:23:26,478 - DEBUG - Requesting: http://10.20.0.18:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-02718464:ctest-bgpaas_vn-82268514:ctest-bgpaas_vn-82268514.inet.0
2026-04-30 03:23:26,493 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 03:23:26,493 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 89.208.252.3, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2026-04-30 03:23:26,493 - DEBUG - ping -s 56 -c 3 -W 1 89.208.252.3
2026-04-30 03:23:30,191 - DEBUG - PING 89.208.252.3 (89.208.252.3): 56 data bytes
64 bytes from 89.208.252.3: seq=0 ttl=63 time=8.314 ms
64 bytes from 89.208.252.3: seq=1 ttl=63 time=1.542 ms
64 bytes from 89.208.252.3: seq=2 ttl=63 time=1.794 ms
--- 89.208.252.3 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.542/3.883/8.314 ms
2026-04-30 03:23:30,191 - INFO - Ping to IP 89.208.252.3 from VM test_vm passed
2026-04-30 03:23:30,307 - INFO - Deleting VM test2_vm
2026-04-30 03:23:30,381 - INFO - Deleting VM test_vm
2026-04-30 03:23:30,548 - INFO - Deleting VN ctest-bgpaas_vn-70647730
2026-04-30 03:23:30,591 - DEBUG - VN 7f1a7027-91f9-49a0-98e6-0dd687b976f7 still in use: Unable to complete operation on network 7f1a7027-91f9-49a0-98e6-0dd687b976f7. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-170692f5-256f-49ae-a620-52a4c6538018']
2026-04-30 03:23:30,591 - WARNING - Deleting VN ctest-bgpaas_vn-70647730 failed..Will retry
2026-04-30 03:23:32,769 - DEBUG - Response for deleting network ()
2026-04-30 03:23:32,769 - INFO - Deleting VN ctest-bgpaas_vn-82268514
2026-04-30 03:23:32,960 - DEBUG - Response for deleting network ()
2026-04-30 03:23:34,256 - DEBUG - No XMPP flaps were noticed during the test
2026-04-30 03:23:34,256 - INFO - END TEST : test_rp_interface_static : PASSED[0:00:32]
2026-04-30 03:23:34,257 - INFO - --------------------------------------------------------------------------------
2026-04-30 03:23:35,018 - INFO - Deleted project: ctest-TestRP-02718464, ID : de10e5c7-ff42-4f26-89f5-69453a8999b0