2026-06-08 11:21:47,455 - INFO - Domain Default found not creating
2026-06-08 11:21:47,606 - INFO - Project ctest-TestRP-99460230 not found, creating it
2026-06-08 11:21:48,177 - INFO - Created Project:ctest-TestRP-99460230, ID : b09e84f4-ee3d-470f-8662-dd807d94f34d
2026-06-08 11:21:49,678 - DEBUG - Services list from nova: [, , , ]
2026-06-08 11:21:49,678 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-5904-2', 'an-jenkins-deploy-platform-ansible-os-5904-1']}
2026-06-08 11:21:49,681 - INFO - ================================================================================
2026-06-08 11:21:49,681 - INFO - STARTING TEST : test_rp_interface
2026-06-08 11:21:49,682 - 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-08 11:21:50,972 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.14': '0', '10.20.0.25': '0'}, '10.0.0.19': {'10.20.0.254': '0', '10.20.0.25': '0'}} with
2026-06-08 11:21:50,972 - INFO - Initial checks done. Running the testcase now
2026-06-08 11:21:50,972 - INFO -
2026-06-08 11:21:51,653 - DEBUG - Response for create_network : {'network': {'id': 'aa994787-0ff7-4ef2-9691-05abf78cc4c3', 'name': 'ctest-bgpaas_vn-00072608', 'tenant_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'project_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-99460230', 'ctest-bgpaas_vn-00072608'], 'port_security_enabled': True, 'description': ''}}
2026-06-08 11:21:51,916 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f0113c03-ce68-4dab-bf91-12d5c1c09a76', 'name': '', 'tenant_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'network_id': 'aa994787-0ff7-4ef2-9691-05abf78cc4c3', 'ip_version': 4, 'cidr': '83.192.161.192/26', 'allocation_pools': [{'start': '83.192.161.194', 'end': '83.192.161.254'}], 'gateway_ip': '83.192.161.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '83.192.161.194', 'tags': [], 'project_id': 'b09e84f4ee3d470f8662dd807d94f34d'}}
2026-06-08 11:21:51,942 - INFO - Created VN ctest-bgpaas_vn-00072608
2026-06-08 11:21:51,954 - DEBUG - VN ctest-bgpaas_vn-00072608 UUID is aa994787-0ff7-4ef2-9691-05abf78cc4c3
2026-06-08 11:21:52,555 - DEBUG - Response for create_network : {'network': {'id': 'ca60ddea-0428-4420-ac98-466413d8a46d', 'name': 'ctest-bgpaas_vn-66713659', 'tenant_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'project_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-99460230', 'ctest-bgpaas_vn-66713659'], 'port_security_enabled': True, 'description': ''}}
2026-06-08 11:21:52,844 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd9224d1f-78e1-4e94-81a0-cf09cafaca49', 'name': '', 'tenant_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'network_id': 'ca60ddea-0428-4420-ac98-466413d8a46d', 'ip_version': 4, 'cidr': '53.31.75.192/26', 'allocation_pools': [{'start': '53.31.75.194', 'end': '53.31.75.254'}], 'gateway_ip': '53.31.75.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '53.31.75.194', 'tags': [], 'project_id': 'b09e84f4ee3d470f8662dd807d94f34d'}}
2026-06-08 11:21:52,869 - INFO - Created VN ctest-bgpaas_vn-66713659
2026-06-08 11:21:52,882 - DEBUG - VN ctest-bgpaas_vn-66713659 UUID is ca60ddea-0428-4420-ac98-466413d8a46d
2026-06-08 11:21:54,316 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5904-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5904-2)
2026-06-08 11:21:55,759 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5904-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5904-1)
2026-06-08 11:21:55,760 - INFO - Waiting for VM test_vm to be up..
2026-06-08 11:21:55,878 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-08 11:22:00,965 - DEBUG - VM is in ACTIVE state now
2026-06-08 11:22:00,966 - INFO - VM name : test_vm
2026-06-08 11:22:01,057 - DEBUG - VM test_vm ID is c24c39b5-6274-460c-b3ef-6f46c627ae74
2026-06-08 11:22:01,083 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-5904-2
2026-06-08 11:22:01,177 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/c24c39b5-6274-460c-b3ef-6f46c627ae74
2026-06-08 11:22:01,511 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/c24c39b5-6274-460c-b3ef-6f46c627ae74
2026-06-08 11:22:01,549 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/bc4c8c54-4b2d-484e-a5dd-106cd2bfc67d
2026-06-08 11:22:04,730 - 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 1015ms')
2026-06-08 11:22:04,730 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2026-06-08 11:22:04,745 - DEBUG - Gateway for vn default-domain:ctest-TestRP-99460230:ctest-bgpaas_vn-00072608 is 83.192.161.193 and allocation pool is NOT set
2026-06-08 11:22:06,807 - 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.83 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.63 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.630/4.727/7.825/3.097 ms')
2026-06-08 11:22:06,808 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2026-06-08 11:22:06,877 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 11:22:06,877 - DEBUG - Waiting to SSH to VM test_vm, IP 83.192.161.195, Port 22
2026-06-08 11:22:07,040 - DEBUG - VM test_vm is ready for SSH connections
2026-06-08 11:22:07,040 - INFO - Waiting for VM test2_vm to be up..
2026-06-08 11:22:07,132 - DEBUG - VM is in ACTIVE state now
2026-06-08 11:22:07,132 - INFO - VM name : test2_vm
2026-06-08 11:22:07,209 - DEBUG - VM test2_vm ID is 5928f0d5-465c-4a46-b791-7023e610fdf1
2026-06-08 11:22:07,209 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-5904-1
2026-06-08 11:22:07,299 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/5928f0d5-465c-4a46-b791-7023e610fdf1
2026-06-08 11:22:07,311 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/8358fded-d4ce-426b-a9df-5367e53c3729
2026-06-08 11:22:08,484 - 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.82 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.468 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.468/1.644/2.821/1.176 ms')
2026-06-08 11:22:08,484 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2026-06-08 11:22:08,552 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 11:22:08,552 - DEBUG - Waiting to SSH to VM test2_vm, IP 53.31.75.195, Port 22
2026-06-08 11:22:08,720 - DEBUG - VM test2_vm is ready for SSH connections
2026-06-08 11:22:08,883 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 11:22:08,896 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-99460230:ctest-bgpaas_vn-00072608:ctest-bgpaas_vn-00072608.inet.0
2026-06-08 11:22:08,908 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-99460230:ctest-bgpaas_vn-00072608:ctest-bgpaas_vn-00072608.inet.0
2026-06-08 11:22:08,921 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 11:22:08,922 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 53.31.75.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-06-08 11:22:08,922 - DEBUG - ping -s 56 -c 3 -W 1 53.31.75.195
2026-06-08 11:22:12,927 - DEBUG - PING 53.31.75.195 (53.31.75.195): 56 data bytes
64 bytes from 53.31.75.195: seq=0 ttl=63 time=7.781 ms
64 bytes from 53.31.75.195: seq=1 ttl=63 time=1.386 ms
64 bytes from 53.31.75.195: seq=2 ttl=63 time=1.552 ms
--- 53.31.75.195 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.386/3.573/7.781 ms
2026-06-08 11:22:12,927 - INFO - Ping to IP 53.31.75.195 from VM test_vm passed
2026-06-08 11:22:13,062 - INFO - Deleting VM test2_vm
2026-06-08 11:22:13,126 - INFO - Deleting VM test_vm
2026-06-08 11:22:13,191 - INFO - Deleting VN ctest-bgpaas_vn-66713659
2026-06-08 11:22:13,249 - DEBUG - VN ca60ddea-0428-4420-ac98-466413d8a46d still in use: Unable to complete operation on network ca60ddea-0428-4420-ac98-466413d8a46d. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-4a4b12f9-c27f-4c54-bd50-7e8776557e75']
2026-06-08 11:22:13,249 - WARNING - Deleting VN ctest-bgpaas_vn-66713659 failed..Will retry
2026-06-08 11:22:15,406 - DEBUG - Response for deleting network ()
2026-06-08 11:22:15,407 - INFO - Deleting VN ctest-bgpaas_vn-00072608
2026-06-08 11:22:15,585 - DEBUG - Response for deleting network ()
2026-06-08 11:22:16,873 - DEBUG - No XMPP flaps were noticed during the test
2026-06-08 11:22:16,873 - INFO - END TEST : test_rp_interface : PASSED[0:00:27]
2026-06-08 11:22:16,873 - INFO - --------------------------------------------------------------------------------
2026-06-08 11:22:16,876 - INFO - ================================================================================
2026-06-08 11:22:16,876 - INFO - STARTING TEST : test_rp_interface_ext_community
2026-06-08 11:22:16,876 - 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-08 11:22:18,154 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.14': '0', '10.20.0.25': '0'}, '10.0.0.19': {'10.20.0.254': '0', '10.20.0.25': '0'}} with
2026-06-08 11:22:18,154 - INFO - Initial checks done. Running the testcase now
2026-06-08 11:22:18,154 - INFO -
2026-06-08 11:22:18,433 - DEBUG - Response for create_network : {'network': {'id': 'd6a5018c-baf2-4d54-861c-61b59064ad6b', 'name': 'ctest-bgpaas_vn-99248624', 'tenant_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'project_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-99460230', 'ctest-bgpaas_vn-99248624'], 'port_security_enabled': True, 'description': ''}}
2026-06-08 11:22:18,685 - DEBUG - Response for create_subnet : {'subnet': {'id': '193c436c-f887-40c0-b617-3f898d8979c7', 'name': '', 'tenant_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'network_id': 'd6a5018c-baf2-4d54-861c-61b59064ad6b', 'ip_version': 4, 'cidr': '104.156.193.128/26', 'allocation_pools': [{'start': '104.156.193.130', 'end': '104.156.193.190'}], 'gateway_ip': '104.156.193.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '104.156.193.130', 'tags': [], 'project_id': 'b09e84f4ee3d470f8662dd807d94f34d'}}
2026-06-08 11:22:18,708 - INFO - Created VN ctest-bgpaas_vn-99248624
2026-06-08 11:22:18,722 - DEBUG - VN ctest-bgpaas_vn-99248624 UUID is d6a5018c-baf2-4d54-861c-61b59064ad6b
2026-06-08 11:22:19,269 - DEBUG - Response for create_network : {'network': {'id': '56cdeaf8-64b4-4831-920a-5864d08dbf6d', 'name': 'ctest-bgpaas_vn-33459395', 'tenant_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'project_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-99460230', 'ctest-bgpaas_vn-33459395'], 'port_security_enabled': True, 'description': ''}}
2026-06-08 11:22:19,504 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c22b3747-699c-4649-a992-75c435606471', 'name': '', 'tenant_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'network_id': '56cdeaf8-64b4-4831-920a-5864d08dbf6d', 'ip_version': 4, 'cidr': '87.160.69.192/26', 'allocation_pools': [{'start': '87.160.69.194', 'end': '87.160.69.254'}], 'gateway_ip': '87.160.69.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '87.160.69.194', 'tags': [], 'project_id': 'b09e84f4ee3d470f8662dd807d94f34d'}}
2026-06-08 11:22:19,525 - INFO - Created VN ctest-bgpaas_vn-33459395
2026-06-08 11:22:19,537 - DEBUG - VN ctest-bgpaas_vn-33459395 UUID is 56cdeaf8-64b4-4831-920a-5864d08dbf6d
2026-06-08 11:22:20,965 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5904-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5904-2)
2026-06-08 11:22:22,461 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5904-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5904-1)
2026-06-08 11:22:22,461 - INFO - Waiting for VM test_vm to be up..
2026-06-08 11:22:22,580 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-08 11:22:27,676 - DEBUG - VM is in ACTIVE state now
2026-06-08 11:22:27,676 - INFO - VM name : test_vm
2026-06-08 11:22:27,779 - DEBUG - VM test_vm ID is 50b1c2ec-4984-445a-96f3-7b9e2d55df60
2026-06-08 11:22:27,779 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-5904-2
2026-06-08 11:22:27,872 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/50b1c2ec-4984-445a-96f3-7b9e2d55df60
2026-06-08 11:22:27,883 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/7dd0bfff-0941-4c17-ac39-5c95d8cd8b51
2026-06-08 11:22:31,075 - 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-06-08 11:22:31,075 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2026-06-08 11:22:31,090 - DEBUG - Gateway for vn default-domain:ctest-TestRP-99460230:ctest-bgpaas_vn-99248624 is 104.156.193.129 and allocation pool is NOT set
2026-06-08 11:22:33,157 - 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.96 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.247 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.247/4.605/8.964/4.358 ms')
2026-06-08 11:22:33,157 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2026-06-08 11:22:33,232 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 11:22:33,232 - DEBUG - Waiting to SSH to VM test_vm, IP 104.156.193.131, Port 22
2026-06-08 11:22:33,403 - DEBUG - VM test_vm is ready for SSH connections
2026-06-08 11:22:33,403 - INFO - Waiting for VM test2_vm to be up..
2026-06-08 11:22:33,485 - DEBUG - VM is in ACTIVE state now
2026-06-08 11:22:33,485 - INFO - VM name : test2_vm
2026-06-08 11:22:33,566 - DEBUG - VM test2_vm ID is 0ba66379-c71e-457b-bd44-87547a67e155
2026-06-08 11:22:33,566 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-5904-1
2026-06-08 11:22:33,648 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/0ba66379-c71e-457b-bd44-87547a67e155
2026-06-08 11:22:33,658 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/91fdb0b0-f98d-467e-8c4a-dcd3cf626793
2026-06-08 11:22:34,816 - 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=6.05 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.901 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.901/3.475/6.050/2.574 ms')
2026-06-08 11:22:34,816 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2026-06-08 11:22:34,884 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 11:22:34,884 - DEBUG - Waiting to SSH to VM test2_vm, IP 87.160.69.195, Port 22
2026-06-08 11:22:35,046 - DEBUG - VM test2_vm is ready for SSH connections
2026-06-08 11:22:35,199 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 11:22:35,210 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-99460230:ctest-bgpaas_vn-99248624:ctest-bgpaas_vn-99248624.inet.0
2026-06-08 11:22:35,220 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-99460230:ctest-bgpaas_vn-99248624:ctest-bgpaas_vn-99248624.inet.0
2026-06-08 11:22:35,236 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 11:22:35,236 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 87.160.69.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-06-08 11:22:35,236 - DEBUG - ping -s 56 -c 3 -W 1 87.160.69.195
2026-06-08 11:22:38,991 - DEBUG - PING 87.160.69.195 (87.160.69.195): 56 data bytes
64 bytes from 87.160.69.195: seq=0 ttl=63 time=6.754 ms
64 bytes from 87.160.69.195: seq=1 ttl=63 time=1.359 ms
64 bytes from 87.160.69.195: seq=2 ttl=63 time=1.360 ms
--- 87.160.69.195 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.359/3.157/6.754 ms
2026-06-08 11:22:38,991 - INFO - Ping to IP 87.160.69.195 from VM test_vm passed
2026-06-08 11:22:39,147 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 11:22:39,158 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-99460230:ctest-bgpaas_vn-99248624:ctest-bgpaas_vn-99248624.inet.0
2026-06-08 11:22:39,173 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-99460230:ctest-bgpaas_vn-99248624:ctest-bgpaas_vn-99248624.inet.0
2026-06-08 11:22:39,438 - INFO - Deleting VM test2_vm
2026-06-08 11:22:39,498 - INFO - Deleting VM test_vm
2026-06-08 11:22:39,558 - INFO - Deleting VN ctest-bgpaas_vn-33459395
2026-06-08 11:22:39,597 - DEBUG - VN 56cdeaf8-64b4-4831-920a-5864d08dbf6d still in use: Unable to complete operation on network 56cdeaf8-64b4-4831-920a-5864d08dbf6d. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-87c19cc1-744a-46c4-85a8-1217d61909e0']
2026-06-08 11:22:39,597 - WARNING - Deleting VN ctest-bgpaas_vn-33459395 failed..Will retry
2026-06-08 11:22:41,750 - DEBUG - Response for deleting network ()
2026-06-08 11:22:41,750 - INFO - Deleting VN ctest-bgpaas_vn-99248624
2026-06-08 11:22:41,924 - DEBUG - Response for deleting network ()
2026-06-08 11:22:43,222 - DEBUG - No XMPP flaps were noticed during the test
2026-06-08 11:22:43,223 - INFO - --------------------------------------------------------------------------------
2026-06-08 11:22:43,226 - INFO - ================================================================================
2026-06-08 11:22:43,226 - INFO - STARTING TEST : test_rp_interface_static
2026-06-08 11:22:43,226 - 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-08 11:22:44,519 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.14': '0', '10.20.0.25': '0'}, '10.0.0.19': {'10.20.0.254': '0', '10.20.0.25': '0'}} with
2026-06-08 11:22:44,519 - INFO - Initial checks done. Running the testcase now
2026-06-08 11:22:44,519 - INFO -
2026-06-08 11:22:44,798 - DEBUG - Response for create_network : {'network': {'id': '6c7d47e6-02e9-4a76-9966-37e919bf9474', 'name': 'ctest-bgpaas_vn-12645180', 'tenant_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'project_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-99460230', 'ctest-bgpaas_vn-12645180'], 'port_security_enabled': True, 'description': ''}}
2026-06-08 11:22:45,015 - DEBUG - Response for create_subnet : {'subnet': {'id': '7efe893a-383e-4ace-bdbd-e064c2346b7f', 'name': '', 'tenant_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'network_id': '6c7d47e6-02e9-4a76-9966-37e919bf9474', 'ip_version': 4, 'cidr': '132.65.199.64/26', 'allocation_pools': [{'start': '132.65.199.66', 'end': '132.65.199.126'}], 'gateway_ip': '132.65.199.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '132.65.199.66', 'tags': [], 'project_id': 'b09e84f4ee3d470f8662dd807d94f34d'}}
2026-06-08 11:22:45,038 - INFO - Created VN ctest-bgpaas_vn-12645180
2026-06-08 11:22:45,048 - DEBUG - VN ctest-bgpaas_vn-12645180 UUID is 6c7d47e6-02e9-4a76-9966-37e919bf9474
2026-06-08 11:22:45,640 - DEBUG - Response for create_network : {'network': {'id': '9d938d5b-30aa-4542-b0ae-251ed89cb061', 'name': 'ctest-bgpaas_vn-34066418', 'tenant_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'project_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-99460230', 'ctest-bgpaas_vn-34066418'], 'port_security_enabled': True, 'description': ''}}
2026-06-08 11:22:45,920 - DEBUG - Response for create_subnet : {'subnet': {'id': '56dda7f6-01fb-49c8-940c-f1917f054d2d', 'name': '', 'tenant_id': 'b09e84f4ee3d470f8662dd807d94f34d', 'network_id': '9d938d5b-30aa-4542-b0ae-251ed89cb061', 'ip_version': 4, 'cidr': '62.131.62.192/26', 'allocation_pools': [{'start': '62.131.62.194', 'end': '62.131.62.254'}], 'gateway_ip': '62.131.62.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '62.131.62.194', 'tags': [], 'project_id': 'b09e84f4ee3d470f8662dd807d94f34d'}}
2026-06-08 11:22:45,941 - INFO - Created VN ctest-bgpaas_vn-34066418
2026-06-08 11:22:45,966 - DEBUG - VN ctest-bgpaas_vn-34066418 UUID is 9d938d5b-30aa-4542-b0ae-251ed89cb061
2026-06-08 11:22:47,251 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5904-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5904-2)
2026-06-08 11:22:48,549 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5904-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5904-1)
2026-06-08 11:22:48,549 - INFO - Waiting for VM test_vm to be up..
2026-06-08 11:22:48,641 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2026-06-08 11:22:53,746 - DEBUG - VM is in ACTIVE state now
2026-06-08 11:22:53,746 - INFO - VM name : test_vm
2026-06-08 11:22:53,865 - DEBUG - VM test_vm ID is e44c213f-b591-40fb-ac91-a9aa3ddfba5a
2026-06-08 11:22:53,865 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-5904-2
2026-06-08 11:22:53,958 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/e44c213f-b591-40fb-ac91-a9aa3ddfba5a
2026-06-08 11:22:53,968 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/27062667-6493-41da-8cc7-b12e830cfee6
2026-06-08 11:22:57,153 - 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 1019ms')
2026-06-08 11:22:57,153 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2026-06-08 11:22:57,164 - DEBUG - Gateway for vn default-domain:ctest-TestRP-99460230:ctest-bgpaas_vn-12645180 is 132.65.199.65 and allocation pool is NOT set
2026-06-08 11:22:59,233 - 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.36 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.56 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.555/5.457/8.360/2.902 ms')
2026-06-08 11:22:59,233 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2026-06-08 11:22:59,304 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 11:22:59,304 - DEBUG - Waiting to SSH to VM test_vm, IP 132.65.199.67, Port 22
2026-06-08 11:22:59,359 - 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-08 11:22:59,448 - DEBUG - VM test_vm is NOT ready for SSH connections, VM status: ACTIVE
2026-06-08 11:23:04,449 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 11:23:04,449 - DEBUG - Waiting to SSH to VM test_vm, IP 132.65.199.67, Port 22
2026-06-08 11:23:04,612 - DEBUG - VM test_vm is ready for SSH connections
2026-06-08 11:23:04,612 - INFO - Waiting for VM test2_vm to be up..
2026-06-08 11:23:04,693 - DEBUG - VM is in ACTIVE state now
2026-06-08 11:23:04,693 - INFO - VM name : test2_vm
2026-06-08 11:23:04,778 - DEBUG - VM test2_vm ID is 044f2422-9762-4382-aa7b-a7e15bebef41
2026-06-08 11:23:04,778 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-5904-1
2026-06-08 11:23:04,862 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/044f2422-9762-4382-aa7b-a7e15bebef41
2026-06-08 11:23:04,875 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/6fe02d76-163f-4dc6-a42b-3f253300f44b
2026-06-08 11:23:06,045 - 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.06 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.546 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.546/1.802/3.059/1.256 ms')
2026-06-08 11:23:06,045 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2026-06-08 11:23:06,117 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 11:23:06,117 - DEBUG - Waiting to SSH to VM test2_vm, IP 62.131.62.195, Port 22
2026-06-08 11:23:06,274 - DEBUG - VM test2_vm is ready for SSH connections
2026-06-08 11:23:06,380 - INFO - Created InterfaceRouteTable ['default-domain', 'ctest-TestRP-99460230', 'ctest-int_table_right-48568397'](UUID 88276c0f-abad-4e9c-bc27-8c3890a5240d), prefixes : ['81.2.89.128/26']
2026-06-08 11:23:06,451 - INFO - Added intf route table 88276c0f-abad-4e9c-bc27-8c3890a5240d to port 27062667-6493-41da-8cc7-b12e830cfee6
2026-06-08 11:23:06,674 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-99460230:ctest-bgpaas_vn-12645180:ctest-bgpaas_vn-12645180.inet.0
2026-06-08 11:23:06,686 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-99460230:ctest-bgpaas_vn-12645180:ctest-bgpaas_vn-12645180.inet.0
2026-06-08 11:23:06,701 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 11:23:06,701 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 62.131.62.195, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-06-08 11:23:06,701 - DEBUG - ping -s 56 -c 3 -W 1 62.131.62.195
2026-06-08 11:23:10,366 - DEBUG - PING 62.131.62.195 (62.131.62.195): 56 data bytes
64 bytes from 62.131.62.195: seq=0 ttl=63 time=6.506 ms
64 bytes from 62.131.62.195: seq=1 ttl=63 time=1.238 ms
64 bytes from 62.131.62.195: seq=2 ttl=63 time=1.303 ms
--- 62.131.62.195 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.238/3.015/6.506 ms
2026-06-08 11:23:10,366 - INFO - Ping to IP 62.131.62.195 from VM test_vm passed
2026-06-08 11:23:10,516 - INFO - Deleting VM test2_vm
2026-06-08 11:23:10,597 - INFO - Deleting VM test_vm
2026-06-08 11:23:10,668 - INFO - Deleting VN ctest-bgpaas_vn-34066418
2026-06-08 11:23:10,708 - DEBUG - VN 9d938d5b-30aa-4542-b0ae-251ed89cb061 still in use: Unable to complete operation on network 9d938d5b-30aa-4542-b0ae-251ed89cb061. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-d4686597-3ae6-41d6-a1d1-2c2303383161']
2026-06-08 11:23:10,708 - WARNING - Deleting VN ctest-bgpaas_vn-34066418 failed..Will retry
2026-06-08 11:23:12,876 - DEBUG - Response for deleting network ()
2026-06-08 11:23:12,876 - INFO - Deleting VN ctest-bgpaas_vn-12645180
2026-06-08 11:23:13,063 - DEBUG - Response for deleting network ()
2026-06-08 11:23:14,351 - DEBUG - No XMPP flaps were noticed during the test
2026-06-08 11:23:14,351 - INFO - END TEST : test_rp_interface_static : PASSED[0:00:31]
2026-06-08 11:23:14,351 - INFO - --------------------------------------------------------------------------------
2026-06-08 11:23:15,088 - INFO - Deleted project: ctest-TestRP-99460230, ID : b09e84f4-ee3d-470f-8662-dd807d94f34d