2026-06-04 03:23:55,683 - INFO - Domain Default found not creating
2026-06-04 03:23:55,918 - INFO - Project ctest-TestRP-53787993 not found, creating it
2026-06-04 03:23:56,542 - INFO - Created Project:ctest-TestRP-53787993, ID : 6719d4a9-60f2-4397-a1af-068253eaa1bd
2026-06-04 03:23:58,423 - DEBUG - Services list from nova: [, , , ]
2026-06-04 03:23:58,424 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-5884-2', 'an-jenkins-deploy-platform-ansible-os-5884-1']}
2026-06-04 03:23:58,426 - INFO - ================================================================================
2026-06-04 03:23:58,426 - INFO - STARTING TEST : test_rp_interface
2026-06-04 03:23:58,427 - 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-06-04 03:23:59,712 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.23': {'10.20.0.25': '0', '10.20.0.193': '0'}} with
2026-06-04 03:23:59,712 - INFO - Initial checks done. Running the testcase now
2026-06-04 03:23:59,712 - INFO -
2026-06-04 03:24:00,505 - DEBUG - Response for create_network : {'network': {'id': '9ebb534f-a261-45e6-b1a5-491ddd8f3da4', 'name': 'ctest-bgpaas_vn-46446566', 'tenant_id': '6719d4a960f24397a1af068253eaa1bd', 'project_id': '6719d4a960f24397a1af068253eaa1bd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-53787993', 'ctest-bgpaas_vn-46446566'], 'port_security_enabled': True, 'description': ''}}
2026-06-04 03:24:00,781 - DEBUG - Response for create_subnet : {'subnet': {'id': '72515f72-0f09-4fc7-bad6-b67a5dc4281d', 'name': '', 'tenant_id': '6719d4a960f24397a1af068253eaa1bd', 'network_id': '9ebb534f-a261-45e6-b1a5-491ddd8f3da4', 'ip_version': 4, 'cidr': '186.236.144.192/26', 'allocation_pools': [{'start': '186.236.144.194', 'end': '186.236.144.254'}], 'gateway_ip': '186.236.144.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '186.236.144.194', 'tags': [], 'project_id': '6719d4a960f24397a1af068253eaa1bd'}}
2026-06-04 03:24:00,819 - INFO - Created VN ctest-bgpaas_vn-46446566
2026-06-04 03:24:00,831 - DEBUG - VN ctest-bgpaas_vn-46446566 UUID is 9ebb534f-a261-45e6-b1a5-491ddd8f3da4
2026-06-04 03:24:01,482 - DEBUG - Response for create_network : {'network': {'id': 'cc0dc03c-25ce-4701-a1f2-99abb28c3c85', 'name': 'ctest-bgpaas_vn-45014088', 'tenant_id': '6719d4a960f24397a1af068253eaa1bd', 'project_id': '6719d4a960f24397a1af068253eaa1bd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-53787993', 'ctest-bgpaas_vn-45014088'], 'port_security_enabled': True, 'description': ''}}
2026-06-04 03:24:01,783 - DEBUG - Response for create_subnet : {'subnet': {'id': '52f270e9-982f-4419-9569-11854236a58b', 'name': '', 'tenant_id': '6719d4a960f24397a1af068253eaa1bd', 'network_id': 'cc0dc03c-25ce-4701-a1f2-99abb28c3c85', 'ip_version': 4, 'cidr': '114.86.242.64/26', 'allocation_pools': [{'start': '114.86.242.66', 'end': '114.86.242.126'}], 'gateway_ip': '114.86.242.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '114.86.242.66', 'tags': [], 'project_id': '6719d4a960f24397a1af068253eaa1bd'}}
2026-06-04 03:24:01,815 - INFO - Created VN ctest-bgpaas_vn-45014088
2026-06-04 03:24:01,832 - DEBUG - VN ctest-bgpaas_vn-45014088 UUID is cc0dc03c-25ce-4701-a1f2-99abb28c3c85
2026-06-04 03:24:03,508 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5884-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5884-2)
2026-06-04 03:24:05,386 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5884-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5884-1)
2026-06-04 03:24:05,386 - INFO - Waiting for VM test_vm to be up..
2026-06-04 03:24:05,497 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-04 03:24:10,606 - DEBUG - VM is in ACTIVE state now
2026-06-04 03:24:10,606 - INFO - VM name : test_vm
2026-06-04 03:24:10,711 - DEBUG - VM test_vm ID is 4a5e3fcd-7429-45ac-95ab-482cc6434790
2026-06-04 03:24:10,743 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-5884-2
2026-06-04 03:24:10,852 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/4a5e3fcd-7429-45ac-95ab-482cc6434790
2026-06-04 03:24:11,203 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/4a5e3fcd-7429-45ac-95ab-482cc6434790
2026-06-04 03:24:11,263 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/f01c2fb6-d16e-4ff6-bf01-f520f94e261b
2026-06-04 03:24:14,470 - 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 1033ms')
2026-06-04 03:24:14,470 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2026-06-04 03:24:14,488 - DEBUG - Gateway for vn default-domain:ctest-TestRP-53787993:ctest-bgpaas_vn-46446566 is 186.236.144.193 and allocation pool is NOT set
2026-06-04 03:24:16,551 - 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=8.23 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.469 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.469/4.350/8.232/3.881 ms')
2026-06-04 03:24:16,551 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2026-06-04 03:24:16,624 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-04 03:24:16,624 - DEBUG - Waiting to SSH to VM test_vm, IP 186.236.144.195, Port 22
2026-06-04 03:24:16,781 - DEBUG - VM test_vm is ready for SSH connections
2026-06-04 03:24:16,781 - INFO - Waiting for VM test2_vm to be up..
2026-06-04 03:24:16,905 - DEBUG - VM is in ACTIVE state now
2026-06-04 03:24:16,906 - INFO - VM name : test2_vm
2026-06-04 03:24:17,030 - DEBUG - VM test2_vm ID is b2da5d8c-d203-49cd-8afa-defbe2886326
2026-06-04 03:24:17,030 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-5884-1
2026-06-04 03:24:17,135 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/b2da5d8c-d203-49cd-8afa-defbe2886326
2026-06-04 03:24:17,147 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/fa8d2444-28bd-45d5-a1e7-8c6af97b795a
2026-06-04 03:24:18,335 - 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.44 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.78 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.783/5.109/7.436/2.326 ms')
2026-06-04 03:24:18,335 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2026-06-04 03:24:18,406 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-04 03:24:18,406 - DEBUG - Waiting to SSH to VM test2_vm, IP 114.86.242.67, Port 22
2026-06-04 03:24:18,476 - 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-06-04 03:24:18,587 - DEBUG - VM test2_vm is NOT ready for SSH connections, VM status: ACTIVE
2026-06-04 03:24:23,588 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-04 03:24:23,588 - DEBUG - Waiting to SSH to VM test2_vm, IP 114.86.242.67, Port 22
2026-06-04 03:24:23,745 - DEBUG - VM test2_vm is ready for SSH connections
2026-06-04 03:24:23,903 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-04 03:24:23,923 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-53787993:ctest-bgpaas_vn-46446566:ctest-bgpaas_vn-46446566.inet.0
2026-06-04 03:24:23,939 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-53787993:ctest-bgpaas_vn-46446566:ctest-bgpaas_vn-46446566.inet.0
2026-06-04 03:24:23,953 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-04 03:24:23,953 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 114.86.242.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2026-06-04 03:24:23,953 - DEBUG - ping -s 56 -c 3 -W 1 114.86.242.67
2026-06-04 03:24:27,948 - DEBUG - PING 114.86.242.67 (114.86.242.67): 56 data bytes
64 bytes from 114.86.242.67: seq=0 ttl=63 time=7.146 ms
64 bytes from 114.86.242.67: seq=1 ttl=63 time=1.604 ms
64 bytes from 114.86.242.67: seq=2 ttl=63 time=2.589 ms
--- 114.86.242.67 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.604/3.779/7.146 ms
2026-06-04 03:24:27,948 - INFO - Ping to IP 114.86.242.67 from VM test_vm passed
2026-06-04 03:24:28,057 - INFO - Deleting VM test2_vm
2026-06-04 03:24:28,194 - INFO - Deleting VM test_vm
2026-06-04 03:24:28,281 - INFO - Deleting VN ctest-bgpaas_vn-45014088
2026-06-04 03:24:28,334 - DEBUG - VN cc0dc03c-25ce-4701-a1f2-99abb28c3c85 still in use: Unable to complete operation on network cc0dc03c-25ce-4701-a1f2-99abb28c3c85. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-9e98f1b5-f1f0-4aff-bdf7-bf7dcb22f5db']
2026-06-04 03:24:28,335 - WARNING - Deleting VN ctest-bgpaas_vn-45014088 failed..Will retry
2026-06-04 03:24:30,511 - DEBUG - Response for deleting network ()
2026-06-04 03:24:30,511 - INFO - Deleting VN ctest-bgpaas_vn-46446566
2026-06-04 03:24:30,765 - DEBUG - Response for deleting network ()
2026-06-04 03:24:32,085 - DEBUG - No XMPP flaps were noticed during the test
2026-06-04 03:24:32,086 - INFO - END TEST : test_rp_interface : PASSED[0:00:34]
2026-06-04 03:24:32,086 - INFO - --------------------------------------------------------------------------------
2026-06-04 03:24:32,092 - INFO - ================================================================================
2026-06-04 03:24:32,092 - INFO - STARTING TEST : test_rp_interface_ext_community
2026-06-04 03:24:32,092 - 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-06-04 03:24:33,400 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.23': {'10.20.0.25': '0', '10.20.0.193': '0'}} with
2026-06-04 03:24:33,400 - INFO - Initial checks done. Running the testcase now
2026-06-04 03:24:33,400 - INFO -
2026-06-04 03:24:33,671 - DEBUG - Response for create_network : {'network': {'id': '05f2e138-cd99-4617-bc92-a6df18c374da', 'name': 'ctest-bgpaas_vn-40016914', 'tenant_id': '6719d4a960f24397a1af068253eaa1bd', 'project_id': '6719d4a960f24397a1af068253eaa1bd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-53787993', 'ctest-bgpaas_vn-40016914'], 'port_security_enabled': True, 'description': ''}}
2026-06-04 03:24:33,913 - DEBUG - Response for create_subnet : {'subnet': {'id': '699dbca3-43f5-41dc-817f-74329ccac34d', 'name': '', 'tenant_id': '6719d4a960f24397a1af068253eaa1bd', 'network_id': '05f2e138-cd99-4617-bc92-a6df18c374da', 'ip_version': 4, 'cidr': '140.171.237.128/26', 'allocation_pools': [{'start': '140.171.237.130', 'end': '140.171.237.190'}], 'gateway_ip': '140.171.237.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '140.171.237.130', 'tags': [], 'project_id': '6719d4a960f24397a1af068253eaa1bd'}}
2026-06-04 03:24:33,941 - INFO - Created VN ctest-bgpaas_vn-40016914
2026-06-04 03:24:33,955 - DEBUG - VN ctest-bgpaas_vn-40016914 UUID is 05f2e138-cd99-4617-bc92-a6df18c374da
2026-06-04 03:24:34,513 - DEBUG - Response for create_network : {'network': {'id': '3a5cc3c5-4474-4dc3-85a4-6efa6c5127d8', 'name': 'ctest-bgpaas_vn-73453257', 'tenant_id': '6719d4a960f24397a1af068253eaa1bd', 'project_id': '6719d4a960f24397a1af068253eaa1bd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-53787993', 'ctest-bgpaas_vn-73453257'], 'port_security_enabled': True, 'description': ''}}
2026-06-04 03:24:34,793 - DEBUG - Response for create_subnet : {'subnet': {'id': '0a113828-ff92-4a94-966d-cb0324b29f07', 'name': '', 'tenant_id': '6719d4a960f24397a1af068253eaa1bd', 'network_id': '3a5cc3c5-4474-4dc3-85a4-6efa6c5127d8', 'ip_version': 4, 'cidr': '45.24.223.192/26', 'allocation_pools': [{'start': '45.24.223.194', 'end': '45.24.223.254'}], 'gateway_ip': '45.24.223.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '45.24.223.194', 'tags': [], 'project_id': '6719d4a960f24397a1af068253eaa1bd'}}
2026-06-04 03:24:34,823 - INFO - Created VN ctest-bgpaas_vn-73453257
2026-06-04 03:24:34,836 - DEBUG - VN ctest-bgpaas_vn-73453257 UUID is 3a5cc3c5-4474-4dc3-85a4-6efa6c5127d8
2026-06-04 03:24:36,309 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5884-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5884-2)
2026-06-04 03:24:37,954 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5884-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5884-1)
2026-06-04 03:24:37,954 - INFO - Waiting for VM test_vm to be up..
2026-06-04 03:24:38,082 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-04 03:24:43,230 - DEBUG - VM is in ACTIVE state now
2026-06-04 03:24:43,230 - INFO - VM name : test_vm
2026-06-04 03:24:43,354 - DEBUG - VM test_vm ID is 23e44b8d-a802-4eb1-9e28-3333c4bd62e1
2026-06-04 03:24:43,354 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-5884-2
2026-06-04 03:24:43,471 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/23e44b8d-a802-4eb1-9e28-3333c4bd62e1
2026-06-04 03:24:43,486 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/4f59225e-c836-40a2-9a17-cc1b9865b6f6
2026-06-04 03:24:46,688 - 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 1026ms')
2026-06-04 03:24:46,688 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2026-06-04 03:24:46,702 - DEBUG - Gateway for vn default-domain:ctest-TestRP-53787993:ctest-bgpaas_vn-40016914 is 140.171.237.129 and allocation pool is NOT set
2026-06-04 03:24:48,767 - 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=8.29 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.301 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.301/4.295/8.289/3.994 ms')
2026-06-04 03:24:48,767 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2026-06-04 03:24:48,841 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-04 03:24:48,842 - DEBUG - Waiting to SSH to VM test_vm, IP 140.171.237.131, Port 22
2026-06-04 03:24:49,031 - DEBUG - VM test_vm is ready for SSH connections
2026-06-04 03:24:49,031 - INFO - Waiting for VM test2_vm to be up..
2026-06-04 03:24:49,143 - DEBUG - VM is in ACTIVE state now
2026-06-04 03:24:49,143 - INFO - VM name : test2_vm
2026-06-04 03:24:49,246 - DEBUG - VM test2_vm ID is 69e00c1f-81fa-41c6-a3ee-08bbe2a928a9
2026-06-04 03:24:49,246 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-5884-1
2026-06-04 03:24:49,365 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/69e00c1f-81fa-41c6-a3ee-08bbe2a928a9
2026-06-04 03:24:49,386 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/35cfff33-5286-4d70-aa0b-21bba7397f4e
2026-06-04 03:24:50,561 - 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.50 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.263 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.263/2.883/5.503/2.620 ms')
2026-06-04 03:24:50,561 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2026-06-04 03:24:50,630 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-04 03:24:50,630 - DEBUG - Waiting to SSH to VM test2_vm, IP 45.24.223.195, Port 22
2026-06-04 03:24:50,799 - DEBUG - VM test2_vm is ready for SSH connections
2026-06-04 03:24:50,973 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-04 03:24:50,995 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-53787993:ctest-bgpaas_vn-40016914:ctest-bgpaas_vn-40016914.inet.0
2026-06-04 03:24:51,009 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-53787993:ctest-bgpaas_vn-40016914:ctest-bgpaas_vn-40016914.inet.0
2026-06-04 03:24:51,025 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-04 03:24:51,025 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 45.24.223.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2026-06-04 03:24:51,025 - DEBUG - ping -s 56 -c 3 -W 1 45.24.223.195
2026-06-04 03:24:54,731 - DEBUG - PING 45.24.223.195 (45.24.223.195): 56 data bytes
64 bytes from 45.24.223.195: seq=0 ttl=63 time=8.250 ms
64 bytes from 45.24.223.195: seq=1 ttl=63 time=1.520 ms
64 bytes from 45.24.223.195: seq=2 ttl=63 time=1.280 ms
--- 45.24.223.195 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.280/3.683/8.250 ms
2026-06-04 03:24:54,731 - INFO - Ping to IP 45.24.223.195 from VM test_vm passed
2026-06-04 03:24:54,925 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-04 03:24:54,940 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-53787993:ctest-bgpaas_vn-40016914:ctest-bgpaas_vn-40016914.inet.0
2026-06-04 03:24:55,954 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-04 03:24:55,975 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-53787993:ctest-bgpaas_vn-40016914:ctest-bgpaas_vn-40016914.inet.0
2026-06-04 03:24:55,996 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-53787993:ctest-bgpaas_vn-40016914:ctest-bgpaas_vn-40016914.inet.0
2026-06-04 03:24:56,305 - INFO - Deleting VM test2_vm
2026-06-04 03:24:56,406 - INFO - Deleting VM test_vm
2026-06-04 03:24:56,510 - INFO - Deleting VN ctest-bgpaas_vn-73453257
2026-06-04 03:24:56,560 - DEBUG - VN 3a5cc3c5-4474-4dc3-85a4-6efa6c5127d8 still in use: Unable to complete operation on network 3a5cc3c5-4474-4dc3-85a4-6efa6c5127d8. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-a01c87eb-92f4-42a8-b849-b878fa037284']
2026-06-04 03:24:56,560 - WARNING - Deleting VN ctest-bgpaas_vn-73453257 failed..Will retry
2026-06-04 03:24:58,738 - DEBUG - Response for deleting network ()
2026-06-04 03:24:58,738 - INFO - Deleting VN ctest-bgpaas_vn-40016914
2026-06-04 03:24:58,937 - DEBUG - Response for deleting network ()
2026-06-04 03:25:00,244 - DEBUG - No XMPP flaps were noticed during the test
2026-06-04 03:25:00,244 - INFO - --------------------------------------------------------------------------------
2026-06-04 03:25:00,248 - INFO - ================================================================================
2026-06-04 03:25:00,248 - INFO - STARTING TEST : test_rp_interface_static
2026-06-04 03:25:00,248 - 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-06-04 03:25:01,585 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.25': '0', '10.20.0.193': '0'}, '10.0.0.23': {'10.20.0.25': '0', '10.20.0.193': '0'}} with
2026-06-04 03:25:01,585 - INFO - Initial checks done. Running the testcase now
2026-06-04 03:25:01,585 - INFO -
2026-06-04 03:25:01,964 - DEBUG - Response for create_network : {'network': {'id': 'fafe43c5-1f56-4454-bb1f-d4aa78ffb24e', 'name': 'ctest-bgpaas_vn-59966755', 'tenant_id': '6719d4a960f24397a1af068253eaa1bd', 'project_id': '6719d4a960f24397a1af068253eaa1bd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-53787993', 'ctest-bgpaas_vn-59966755'], 'port_security_enabled': True, 'description': ''}}
2026-06-04 03:25:02,272 - DEBUG - Response for create_subnet : {'subnet': {'id': 'ce722ec2-e865-41a1-a958-705dfdc4e74c', 'name': '', 'tenant_id': '6719d4a960f24397a1af068253eaa1bd', 'network_id': 'fafe43c5-1f56-4454-bb1f-d4aa78ffb24e', 'ip_version': 4, 'cidr': '162.124.70.64/26', 'allocation_pools': [{'start': '162.124.70.66', 'end': '162.124.70.126'}], 'gateway_ip': '162.124.70.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '162.124.70.66', 'tags': [], 'project_id': '6719d4a960f24397a1af068253eaa1bd'}}
2026-06-04 03:25:02,304 - INFO - Created VN ctest-bgpaas_vn-59966755
2026-06-04 03:25:02,318 - DEBUG - VN ctest-bgpaas_vn-59966755 UUID is fafe43c5-1f56-4454-bb1f-d4aa78ffb24e
2026-06-04 03:25:02,906 - DEBUG - Response for create_network : {'network': {'id': '3a1d38f1-114e-4f6a-99f9-bfa5a0b5de38', 'name': 'ctest-bgpaas_vn-03319408', 'tenant_id': '6719d4a960f24397a1af068253eaa1bd', 'project_id': '6719d4a960f24397a1af068253eaa1bd', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-53787993', 'ctest-bgpaas_vn-03319408'], 'port_security_enabled': True, 'description': ''}}
2026-06-04 03:25:03,218 - DEBUG - Response for create_subnet : {'subnet': {'id': '6e90feec-d3d3-47d8-bd22-645ca2e61772', 'name': '', 'tenant_id': '6719d4a960f24397a1af068253eaa1bd', 'network_id': '3a1d38f1-114e-4f6a-99f9-bfa5a0b5de38', 'ip_version': 4, 'cidr': '125.209.143.192/26', 'allocation_pools': [{'start': '125.209.143.194', 'end': '125.209.143.254'}], 'gateway_ip': '125.209.143.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '125.209.143.194', 'tags': [], 'project_id': '6719d4a960f24397a1af068253eaa1bd'}}
2026-06-04 03:25:03,257 - INFO - Created VN ctest-bgpaas_vn-03319408
2026-06-04 03:25:03,269 - DEBUG - VN ctest-bgpaas_vn-03319408 UUID is 3a1d38f1-114e-4f6a-99f9-bfa5a0b5de38
2026-06-04 03:25:04,818 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5884-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5884-2)
2026-06-04 03:25:06,556 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5884-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5884-1)
2026-06-04 03:25:06,556 - INFO - Waiting for VM test_vm to be up..
2026-06-04 03:25:06,669 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-04 03:25:11,791 - DEBUG - VM is in ACTIVE state now
2026-06-04 03:25:11,791 - INFO - VM name : test_vm
2026-06-04 03:25:11,891 - DEBUG - VM test_vm ID is ea911b56-61c4-4b53-bc37-45007bf5db27
2026-06-04 03:25:11,891 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-5884-2
2026-06-04 03:25:11,998 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/ea911b56-61c4-4b53-bc37-45007bf5db27
2026-06-04 03:25:12,011 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/cba9b235-9620-430e-aadb-bc3b1ca546fb
2026-06-04 03:25:15,206 - 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-06-04 03:25:15,206 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2026-06-04 03:25:15,222 - DEBUG - Gateway for vn default-domain:ctest-TestRP-53787993:ctest-bgpaas_vn-59966755 is 162.124.70.65 and allocation pool is NOT set
2026-06-04 03:25:17,293 - 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=9.54 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.466 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.466/5.001/9.536/4.535 ms')
2026-06-04 03:25:17,293 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2026-06-04 03:25:17,369 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-04 03:25:17,369 - DEBUG - Waiting to SSH to VM test_vm, IP 162.124.70.67, Port 22
2026-06-04 03:25:17,540 - DEBUG - VM test_vm is ready for SSH connections
2026-06-04 03:25:17,540 - INFO - Waiting for VM test2_vm to be up..
2026-06-04 03:25:17,676 - DEBUG - VM is in ACTIVE state now
2026-06-04 03:25:17,677 - INFO - VM name : test2_vm
2026-06-04 03:25:17,788 - DEBUG - VM test2_vm ID is c958d609-a426-455e-b3ef-a71306f13144
2026-06-04 03:25:17,788 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-5884-1
2026-06-04 03:25:17,890 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/c958d609-a426-455e-b3ef-a71306f13144
2026-06-04 03:25:17,903 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/efb9dd23-540f-443f-8ca2-de8247a7005c
2026-06-04 03:25:19,076 - 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=8.73 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.685 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.685/4.709/8.734/4.024 ms')
2026-06-04 03:25:19,076 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2026-06-04 03:25:19,150 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-04 03:25:19,150 - DEBUG - Waiting to SSH to VM test2_vm, IP 125.209.143.195, Port 22
2026-06-04 03:25:19,219 - 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-06-04 03:25:19,317 - DEBUG - VM test2_vm is NOT ready for SSH connections, VM status: ACTIVE
2026-06-04 03:25:24,317 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-04 03:25:24,317 - DEBUG - Waiting to SSH to VM test2_vm, IP 125.209.143.195, Port 22
2026-06-04 03:25:24,487 - DEBUG - VM test2_vm is ready for SSH connections
2026-06-04 03:25:24,597 - INFO - Created InterfaceRouteTable ['default-domain', 'ctest-TestRP-53787993', 'ctest-int_table_right-93367351'](UUID 64fc7adc-0678-4507-bdb4-640666178af0), prefixes : ['8.147.127.64/26']
2026-06-04 03:25:24,669 - INFO - Added intf route table 64fc7adc-0678-4507-bdb4-640666178af0 to port cba9b235-9620-430e-aadb-bc3b1ca546fb
2026-06-04 03:25:24,878 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-53787993:ctest-bgpaas_vn-59966755:ctest-bgpaas_vn-59966755.inet.0
2026-06-04 03:25:24,894 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-53787993:ctest-bgpaas_vn-59966755:ctest-bgpaas_vn-59966755.inet.0
2026-06-04 03:25:24,911 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-04 03:25:24,911 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 125.209.143.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2026-06-04 03:25:24,911 - DEBUG - ping -s 56 -c 3 -W 1 125.209.143.195
2026-06-04 03:25:28,956 - DEBUG - PING 125.209.143.195 (125.209.143.195): 56 data bytes
64 bytes from 125.209.143.195: seq=0 ttl=63 time=5.972 ms
64 bytes from 125.209.143.195: seq=1 ttl=63 time=1.697 ms
64 bytes from 125.209.143.195: seq=2 ttl=63 time=1.584 ms
--- 125.209.143.195 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.584/3.084/5.972 ms
2026-06-04 03:25:28,956 - INFO - Ping to IP 125.209.143.195 from VM test_vm passed
2026-06-04 03:25:29,068 - INFO - Deleting VM test2_vm
2026-06-04 03:25:29,189 - INFO - Deleting VM test_vm
2026-06-04 03:25:29,293 - INFO - Deleting VN ctest-bgpaas_vn-03319408
2026-06-04 03:25:29,350 - DEBUG - VN 3a1d38f1-114e-4f6a-99f9-bfa5a0b5de38 still in use: Unable to complete operation on network 3a1d38f1-114e-4f6a-99f9-bfa5a0b5de38. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-dce53a79-8eb9-42ee-bb8f-d2c3ce27351e']
2026-06-04 03:25:29,350 - WARNING - Deleting VN ctest-bgpaas_vn-03319408 failed..Will retry
2026-06-04 03:25:31,560 - DEBUG - Response for deleting network ()
2026-06-04 03:25:31,560 - INFO - Deleting VN ctest-bgpaas_vn-59966755
2026-06-04 03:25:31,788 - DEBUG - Response for deleting network ()
2026-06-04 03:25:33,098 - DEBUG - No XMPP flaps were noticed during the test
2026-06-04 03:25:33,098 - INFO - END TEST : test_rp_interface_static : PASSED[0:00:33]
2026-06-04 03:25:33,098 - INFO - --------------------------------------------------------------------------------
2026-06-04 03:25:33,903 - INFO - Deleted project: ctest-TestRP-53787993, ID : 6719d4a9-60f2-4397-a1af-068253eaa1bd