2025-08-04 03:06:38,495 - INFO - Domain Default found not creating
2025-08-04 03:06:38,681 - INFO - Project ctest-TestRP-64334385 not found, creating it
2025-08-04 03:06:39,240 - INFO - Created Project:ctest-TestRP-64334385, ID : d2e7072c-f7b6-4b27-a88e-e5b466cd8bc8
2025-08-04 03:06:40,829 - DEBUG - Services list from nova: [, , , ]
2025-08-04 03:06:40,829 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-3750-2', 'an-jenkins-deploy-platform-ansible-os-3750-1']}
2025-08-04 03:06:40,832 - INFO - ================================================================================
2025-08-04 03:06:40,832 - INFO - STARTING TEST : test_rp_interface
2025-08-04 03:06:40,832 - 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.
2025-08-04 03:06:42,116 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.57': {'10.20.0.193': '0', '10.20.0.18': '0'}} with
2025-08-04 03:06:42,116 - INFO - Initial checks done. Running the testcase now
2025-08-04 03:06:42,116 - INFO -
2025-08-04 03:06:42,835 - DEBUG - Response for create_network : {'network': {'id': 'e0d95316-a532-405e-bf4b-e9d5d103b386', 'name': 'ctest-bgpaas_vn-68281961', 'tenant_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'project_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-64334385', 'ctest-bgpaas_vn-68281961'], 'port_security_enabled': True, 'description': ''}}
2025-08-04 03:06:43,129 - DEBUG - Response for create_subnet : {'subnet': {'id': '2a057a49-3e6e-465e-a5f5-74ca3b866561', 'name': '', 'tenant_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'network_id': 'e0d95316-a532-405e-bf4b-e9d5d103b386', 'ip_version': 4, 'cidr': '78.235.81.0/26', 'allocation_pools': [{'start': '78.235.81.2', 'end': '78.235.81.62'}], 'gateway_ip': '78.235.81.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '78.235.81.2', 'tags': [], 'project_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8'}}
2025-08-04 03:06:43,155 - INFO - Created VN ctest-bgpaas_vn-68281961
2025-08-04 03:06:43,170 - DEBUG - VN ctest-bgpaas_vn-68281961 UUID is e0d95316-a532-405e-bf4b-e9d5d103b386
2025-08-04 03:06:43,736 - DEBUG - Response for create_network : {'network': {'id': '89960f21-df9d-49f6-bbe0-88d3cf1e4e79', 'name': 'ctest-bgpaas_vn-94531191', 'tenant_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'project_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-64334385', 'ctest-bgpaas_vn-94531191'], 'port_security_enabled': True, 'description': ''}}
2025-08-04 03:06:44,002 - DEBUG - Response for create_subnet : {'subnet': {'id': '6faf9a83-fc93-4063-bf27-64eeddd2e467', 'name': '', 'tenant_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'network_id': '89960f21-df9d-49f6-bbe0-88d3cf1e4e79', 'ip_version': 4, 'cidr': '106.222.241.128/26', 'allocation_pools': [{'start': '106.222.241.130', 'end': '106.222.241.190'}], 'gateway_ip': '106.222.241.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '106.222.241.130', 'tags': [], 'project_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8'}}
2025-08-04 03:06:44,025 - INFO - Created VN ctest-bgpaas_vn-94531191
2025-08-04 03:06:44,042 - DEBUG - VN ctest-bgpaas_vn-94531191 UUID is 89960f21-df9d-49f6-bbe0-88d3cf1e4e79
2025-08-04 03:06:45,585 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3750-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3750-2)
2025-08-04 03:06:46,993 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3750-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3750-1)
2025-08-04 03:06:46,993 - INFO - Waiting for VM test_vm to be up..
2025-08-04 03:06:47,127 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-08-04 03:06:52,213 - DEBUG - VM is in ACTIVE state now
2025-08-04 03:06:52,213 - INFO - VM name : test_vm
2025-08-04 03:06:52,299 - DEBUG - VM test_vm ID is e2948422-1643-4890-9a12-e8eeff909830
2025-08-04 03:06:52,325 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-3750-2
2025-08-04 03:06:52,420 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/e2948422-1643-4890-9a12-e8eeff909830
2025-08-04 03:06:52,723 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/e2948422-1643-4890-9a12-e8eeff909830
2025-08-04 03:06:52,759 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine-interface/9afcca15-885b-4951-8320-3548a49cee43
2025-08-04 03:06:56,002 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms')
2025-08-04 03:06:56,002 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2025-08-04 03:06:56,021 - DEBUG - Gateway for vn default-domain:ctest-TestRP-64334385:ctest-bgpaas_vn-68281961 is 78.235.81.1 and allocation pool is NOT set
2025-08-04 03:06:58,085 - 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.17 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.529 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.529/2.350/4.171/1.821 ms')
2025-08-04 03:06:58,085 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2025-08-04 03:06:58,207 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 03:06:58,207 - DEBUG - Waiting to SSH to VM test_vm, IP 78.235.81.3, Port 22
2025-08-04 03:06:58,369 - DEBUG - VM test_vm is ready for SSH connections
2025-08-04 03:06:58,369 - INFO - Waiting for VM test2_vm to be up..
2025-08-04 03:06:58,457 - DEBUG - VM is in ACTIVE state now
2025-08-04 03:06:58,458 - INFO - VM name : test2_vm
2025-08-04 03:06:58,549 - DEBUG - VM test2_vm ID is 59e9a7c5-9204-4ae6-b6e5-d76c4d7274d1
2025-08-04 03:06:58,549 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-3750-1
2025-08-04 03:06:58,641 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/59e9a7c5-9204-4ae6-b6e5-d76c4d7274d1
2025-08-04 03:06:58,654 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine-interface/77f0a80a-e36a-4463-b180-5d83b7fe5580
2025-08-04 03:06:59,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=6.37 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.493 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.493/3.432/6.371/2.939 ms')
2025-08-04 03:06:59,868 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2025-08-04 03:06:59,991 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 03:06:59,991 - DEBUG - Waiting to SSH to VM test2_vm, IP 106.222.241.131, Port 22
2025-08-04 03:07:00,161 - DEBUG - VM test2_vm is ready for SSH connections
2025-08-04 03:07:00,360 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 03:07:00,408 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 03:07:00,408 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 106.222.241.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-04 03:07:00,408 - DEBUG - ping -s 56 -c 3 -W 1 106.222.241.131
2025-08-04 03:07:04,350 - DEBUG - PING 106.222.241.131 (106.222.241.131): 56 data bytes
64 bytes from 106.222.241.131: seq=0 ttl=63 time=6.708 ms
64 bytes from 106.222.241.131: seq=1 ttl=63 time=1.245 ms
64 bytes from 106.222.241.131: seq=2 ttl=63 time=1.024 ms
--- 106.222.241.131 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.024/2.992/6.708 ms
2025-08-04 03:07:04,350 - INFO - Ping to IP 106.222.241.131 from VM test_vm passed
2025-08-04 03:07:04,486 - INFO - Deleting VM test2_vm
2025-08-04 03:07:04,579 - INFO - Deleting VM test_vm
2025-08-04 03:07:04,668 - INFO - Deleting VN ctest-bgpaas_vn-94531191
2025-08-04 03:07:04,712 - DEBUG - VN 89960f21-df9d-49f6-bbe0-88d3cf1e4e79 still in use: Unable to complete operation on network 89960f21-df9d-49f6-bbe0-88d3cf1e4e79. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-83298c43-030d-473b-b921-f5548bb2ae52']
2025-08-04 03:07:04,712 - WARNING - Deleting VN ctest-bgpaas_vn-94531191 failed..Will retry
2025-08-04 03:07:06,915 - DEBUG - Response for deleting network ()
2025-08-04 03:07:06,915 - INFO - Deleting VN ctest-bgpaas_vn-68281961
2025-08-04 03:07:07,104 - DEBUG - Response for deleting network ()
2025-08-04 03:07:08,397 - DEBUG - No XMPP flaps were noticed during the test
2025-08-04 03:07:08,397 - INFO - END TEST : test_rp_interface : PASSED[0:00:28]
2025-08-04 03:07:08,397 - INFO - --------------------------------------------------------------------------------
2025-08-04 03:07:08,400 - INFO - ================================================================================
2025-08-04 03:07:08,400 - INFO - STARTING TEST : test_rp_interface_ext_community
2025-08-04 03:07:08,400 - 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.
2025-08-04 03:07:09,693 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.57': {'10.20.0.193': '0', '10.20.0.18': '0'}} with
2025-08-04 03:07:09,693 - INFO - Initial checks done. Running the testcase now
2025-08-04 03:07:09,693 - INFO -
2025-08-04 03:07:10,038 - DEBUG - Response for create_network : {'network': {'id': 'b9f36668-e6b4-4edf-9a83-6fe500529dc4', 'name': 'ctest-bgpaas_vn-26375521', 'tenant_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'project_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-64334385', 'ctest-bgpaas_vn-26375521'], 'port_security_enabled': True, 'description': ''}}
2025-08-04 03:07:10,292 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e8874c06-06f3-45a5-99f9-f36241ea3750', 'name': '', 'tenant_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'network_id': 'b9f36668-e6b4-4edf-9a83-6fe500529dc4', 'ip_version': 4, 'cidr': '22.226.155.64/26', 'allocation_pools': [{'start': '22.226.155.66', 'end': '22.226.155.126'}], 'gateway_ip': '22.226.155.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '22.226.155.66', 'tags': [], 'project_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8'}}
2025-08-04 03:07:10,321 - INFO - Created VN ctest-bgpaas_vn-26375521
2025-08-04 03:07:10,336 - DEBUG - VN ctest-bgpaas_vn-26375521 UUID is b9f36668-e6b4-4edf-9a83-6fe500529dc4
2025-08-04 03:07:10,924 - DEBUG - Response for create_network : {'network': {'id': '8db270e3-051e-4b18-8117-d0af7a801a65', 'name': 'ctest-bgpaas_vn-94350806', 'tenant_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'project_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-64334385', 'ctest-bgpaas_vn-94350806'], 'port_security_enabled': True, 'description': ''}}
2025-08-04 03:07:11,196 - DEBUG - Response for create_subnet : {'subnet': {'id': '7cb959e4-3e82-4296-b9c2-ff18da42a0dd', 'name': '', 'tenant_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'network_id': '8db270e3-051e-4b18-8117-d0af7a801a65', 'ip_version': 4, 'cidr': '108.186.232.64/26', 'allocation_pools': [{'start': '108.186.232.66', 'end': '108.186.232.126'}], 'gateway_ip': '108.186.232.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '108.186.232.66', 'tags': [], 'project_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8'}}
2025-08-04 03:07:11,227 - INFO - Created VN ctest-bgpaas_vn-94350806
2025-08-04 03:07:11,246 - DEBUG - VN ctest-bgpaas_vn-94350806 UUID is 8db270e3-051e-4b18-8117-d0af7a801a65
2025-08-04 03:07:12,736 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3750-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3750-2)
2025-08-04 03:07:14,248 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3750-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3750-1)
2025-08-04 03:07:14,248 - INFO - Waiting for VM test_vm to be up..
2025-08-04 03:07:14,365 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-08-04 03:07:19,458 - DEBUG - VM is in ACTIVE state now
2025-08-04 03:07:19,458 - INFO - VM name : test_vm
2025-08-04 03:07:19,550 - DEBUG - VM test_vm ID is bcb02c4d-5f1c-4c33-8385-244b142cbf5d
2025-08-04 03:07:19,550 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-3750-2
2025-08-04 03:07:19,649 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/bcb02c4d-5f1c-4c33-8385-244b142cbf5d
2025-08-04 03:07:19,664 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine-interface/bce6f730-de99-493a-ab43-c349a89a43ba
2025-08-04 03:07:22,919 - 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 1032ms')
2025-08-04 03:07:22,919 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2025-08-04 03:07:22,936 - DEBUG - Gateway for vn default-domain:ctest-TestRP-64334385:ctest-bgpaas_vn-26375521 is 22.226.155.65 and allocation pool is NOT set
2025-08-04 03:07:25,000 - 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.85 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.393 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.393/2.620/4.847/2.227 ms')
2025-08-04 03:07:25,000 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2025-08-04 03:07:25,123 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 03:07:25,123 - DEBUG - Waiting to SSH to VM test_vm, IP 22.226.155.67, Port 22
2025-08-04 03:07:25,280 - DEBUG - VM test_vm is ready for SSH connections
2025-08-04 03:07:25,280 - INFO - Waiting for VM test2_vm to be up..
2025-08-04 03:07:25,391 - DEBUG - VM is in ACTIVE state now
2025-08-04 03:07:25,391 - INFO - VM name : test2_vm
2025-08-04 03:07:25,504 - DEBUG - VM test2_vm ID is fc4f2fc2-8e9b-46ed-8953-cfc965787b03
2025-08-04 03:07:25,504 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-3750-1
2025-08-04 03:07:25,651 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/fc4f2fc2-8e9b-46ed-8953-cfc965787b03
2025-08-04 03:07:25,665 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine-interface/d5d57308-d5f1-4cf1-a12e-3772773fb2ba
2025-08-04 03:07:26,905 - 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.19 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.748 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.748/2.967/5.187/2.219 ms')
2025-08-04 03:07:26,906 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2025-08-04 03:07:27,023 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 03:07:27,023 - DEBUG - Waiting to SSH to VM test2_vm, IP 108.186.232.67, Port 22
2025-08-04 03:07:27,196 - DEBUG - VM test2_vm is ready for SSH connections
2025-08-04 03:07:27,376 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 03:07:27,422 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 03:07:27,422 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 108.186.232.67, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-04 03:07:27,422 - DEBUG - ping -s 56 -c 3 -W 1 108.186.232.67
2025-08-04 03:07:31,089 - DEBUG - PING 108.186.232.67 (108.186.232.67): 56 data bytes
64 bytes from 108.186.232.67: seq=0 ttl=63 time=5.458 ms
64 bytes from 108.186.232.67: seq=1 ttl=63 time=1.225 ms
64 bytes from 108.186.232.67: seq=2 ttl=63 time=1.336 ms
--- 108.186.232.67 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.225/2.673/5.458 ms
2025-08-04 03:07:31,089 - INFO - Ping to IP 108.186.232.67 from VM test_vm passed
2025-08-04 03:07:31,332 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 03:07:31,731 - INFO - Deleting VM test2_vm
2025-08-04 03:07:31,817 - INFO - Deleting VM test_vm
2025-08-04 03:07:31,903 - INFO - Deleting VN ctest-bgpaas_vn-94350806
2025-08-04 03:07:31,952 - DEBUG - VN 8db270e3-051e-4b18-8117-d0af7a801a65 still in use: Unable to complete operation on network 8db270e3-051e-4b18-8117-d0af7a801a65. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f902cb91-320b-432d-864d-b84cc3b43e08']
2025-08-04 03:07:31,953 - WARNING - Deleting VN ctest-bgpaas_vn-94350806 failed..Will retry
2025-08-04 03:07:34,171 - DEBUG - Response for deleting network ()
2025-08-04 03:07:34,171 - INFO - Deleting VN ctest-bgpaas_vn-26375521
2025-08-04 03:07:34,356 - DEBUG - Response for deleting network ()
2025-08-04 03:07:35,666 - DEBUG - No XMPP flaps were noticed during the test
2025-08-04 03:07:35,667 - INFO - --------------------------------------------------------------------------------
2025-08-04 03:07:35,672 - INFO - ================================================================================
2025-08-04 03:07:35,672 - INFO - STARTING TEST : test_rp_interface_static
2025-08-04 03:07:35,673 - 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.
2025-08-04 03:07:36,961 - DEBUG - Nothing to compare xmpp stats {'10.0.0.41': {'10.20.0.18': '0', '10.20.0.129': '0'}, '10.0.0.57': {'10.20.0.193': '0', '10.20.0.18': '0'}} with
2025-08-04 03:07:36,961 - INFO - Initial checks done. Running the testcase now
2025-08-04 03:07:36,961 - INFO -
2025-08-04 03:07:37,289 - DEBUG - Response for create_network : {'network': {'id': '79781a3e-b145-4b43-9e03-5616b1cda748', 'name': 'ctest-bgpaas_vn-28824713', 'tenant_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'project_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-64334385', 'ctest-bgpaas_vn-28824713'], 'port_security_enabled': True, 'description': ''}}
2025-08-04 03:07:37,489 - DEBUG - Response for create_subnet : {'subnet': {'id': '47332b00-6f59-46ea-a5b0-7d9665ad0e66', 'name': '', 'tenant_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'network_id': '79781a3e-b145-4b43-9e03-5616b1cda748', 'ip_version': 4, 'cidr': '183.153.100.192/26', 'allocation_pools': [{'start': '183.153.100.194', 'end': '183.153.100.254'}], 'gateway_ip': '183.153.100.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '183.153.100.194', 'tags': [], 'project_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8'}}
2025-08-04 03:07:37,518 - INFO - Created VN ctest-bgpaas_vn-28824713
2025-08-04 03:07:37,531 - DEBUG - VN ctest-bgpaas_vn-28824713 UUID is 79781a3e-b145-4b43-9e03-5616b1cda748
2025-08-04 03:07:38,130 - DEBUG - Response for create_network : {'network': {'id': 'f564a106-3d93-4247-b656-8e13007b9a2f', 'name': 'ctest-bgpaas_vn-12471263', 'tenant_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'project_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-64334385', 'ctest-bgpaas_vn-12471263'], 'port_security_enabled': True, 'description': ''}}
2025-08-04 03:07:38,389 - DEBUG - Response for create_subnet : {'subnet': {'id': '77dc4e19-efea-4ad2-a190-163d09239605', 'name': '', 'tenant_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8', 'network_id': 'f564a106-3d93-4247-b656-8e13007b9a2f', 'ip_version': 4, 'cidr': '162.48.159.128/26', 'allocation_pools': [{'start': '162.48.159.130', 'end': '162.48.159.190'}], 'gateway_ip': '162.48.159.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '162.48.159.130', 'tags': [], 'project_id': 'd2e7072cf7b64b27a88ee5b466cd8bc8'}}
2025-08-04 03:07:38,414 - INFO - Created VN ctest-bgpaas_vn-12471263
2025-08-04 03:07:38,429 - DEBUG - VN ctest-bgpaas_vn-12471263 UUID is f564a106-3d93-4247-b656-8e13007b9a2f
2025-08-04 03:07:39,769 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3750-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3750-2)
2025-08-04 03:07:41,153 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3750-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3750-1)
2025-08-04 03:07:41,153 - INFO - Waiting for VM test_vm to be up..
2025-08-04 03:07:41,267 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-08-04 03:07:46,376 - DEBUG - VM is in ACTIVE state now
2025-08-04 03:07:46,376 - INFO - VM name : test_vm
2025-08-04 03:07:46,479 - DEBUG - VM test_vm ID is cf92d7eb-912b-458b-95ff-cb6c5684d6a1
2025-08-04 03:07:46,479 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-3750-2
2025-08-04 03:07:46,564 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/cf92d7eb-912b-458b-95ff-cb6c5684d6a1
2025-08-04 03:07:46,576 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine-interface/3859f162-414c-4a61-a2ad-6cd2b918e4af
2025-08-04 03:07:49,827 - 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 1011ms')
2025-08-04 03:07:49,827 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed!
2025-08-04 03:07:49,849 - DEBUG - Gateway for vn default-domain:ctest-TestRP-64334385:ctest-bgpaas_vn-28824713 is 183.153.100.193 and allocation pool is NOT set
2025-08-04 03:07:51,917 - 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.46 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.441 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.441/1.948/3.456/1.507 ms')
2025-08-04 03:07:51,917 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed
2025-08-04 03:07:52,038 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 03:07:52,039 - DEBUG - Waiting to SSH to VM test_vm, IP 183.153.100.195, Port 22
2025-08-04 03:07:52,208 - DEBUG - VM test_vm is ready for SSH connections
2025-08-04 03:07:52,208 - INFO - Waiting for VM test2_vm to be up..
2025-08-04 03:07:52,299 - DEBUG - VM is in ACTIVE state now
2025-08-04 03:07:52,299 - INFO - VM name : test2_vm
2025-08-04 03:07:52,418 - DEBUG - VM test2_vm ID is 32c2e75e-f78c-4fda-afdc-047065d19017
2025-08-04 03:07:52,419 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-3750-1
2025-08-04 03:07:52,521 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/32c2e75e-f78c-4fda-afdc-047065d19017
2025-08-04 03:07:52,534 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine-interface/6a32b3a7-9917-40f2-980c-b9948c750d63
2025-08-04 03:07:53,745 - 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=3.14 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 = 3.140/3.976/4.812/0.836 ms')
2025-08-04 03:07:53,745 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed
2025-08-04 03:07:53,871 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 03:07:53,871 - DEBUG - Waiting to SSH to VM test2_vm, IP 162.48.159.131, Port 22
2025-08-04 03:07:53,940 - 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': ''}
2025-08-04 03:07:54,036 - DEBUG - VM test2_vm is NOT ready for SSH connections, VM status: ACTIVE
2025-08-04 03:07:59,037 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 03:07:59,038 - DEBUG - Waiting to SSH to VM test2_vm, IP 162.48.159.131, Port 22
2025-08-04 03:07:59,208 - DEBUG - VM test2_vm is ready for SSH connections
2025-08-04 03:07:59,316 - INFO - Created InterfaceRouteTable ['default-domain', 'ctest-TestRP-64334385', 'ctest-int_table_right-99878292'](UUID 735d0f6f-5108-4b8b-bd03-e08ed18bd2f2), prefixes : ['55.160.168.192/26']
2025-08-04 03:07:59,400 - INFO - Added intf route table 735d0f6f-5108-4b8b-bd03-e08ed18bd2f2 to port 3859f162-414c-4a61-a2ad-6cd2b918e4af
2025-08-04 03:07:59,678 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 03:07:59,678 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 162.48.159.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-04 03:07:59,678 - DEBUG - ping -s 56 -c 3 -W 1 162.48.159.131
2025-08-04 03:08:03,349 - DEBUG - PING 162.48.159.131 (162.48.159.131): 56 data bytes
64 bytes from 162.48.159.131: seq=0 ttl=63 time=6.104 ms
64 bytes from 162.48.159.131: seq=1 ttl=63 time=1.196 ms
64 bytes from 162.48.159.131: seq=2 ttl=63 time=1.395 ms
--- 162.48.159.131 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 1.196/2.898/6.104 ms
2025-08-04 03:08:03,349 - INFO - Ping to IP 162.48.159.131 from VM test_vm passed
2025-08-04 03:08:03,495 - INFO - Deleting VM test2_vm
2025-08-04 03:08:03,590 - INFO - Deleting VM test_vm
2025-08-04 03:08:03,693 - INFO - Deleting VN ctest-bgpaas_vn-12471263
2025-08-04 03:08:03,741 - DEBUG - VN f564a106-3d93-4247-b656-8e13007b9a2f still in use: Unable to complete operation on network f564a106-3d93-4247-b656-8e13007b9a2f. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-c5a4c0f5-8103-4bec-b970-3c1f8839e383']
2025-08-04 03:08:03,741 - WARNING - Deleting VN ctest-bgpaas_vn-12471263 failed..Will retry
2025-08-04 03:08:05,925 - DEBUG - Response for deleting network ()
2025-08-04 03:08:05,925 - INFO - Deleting VN ctest-bgpaas_vn-28824713
2025-08-04 03:08:06,116 - DEBUG - Response for deleting network ()
2025-08-04 03:08:07,384 - DEBUG - No XMPP flaps were noticed during the test
2025-08-04 03:08:07,384 - INFO - END TEST : test_rp_interface_static : PASSED[0:00:32]
2025-08-04 03:08:07,384 - INFO - --------------------------------------------------------------------------------
2025-08-04 03:08:08,205 - INFO - Deleted project: ctest-TestRP-64334385, ID : d2e7072c-f7b6-4b27-a88e-e5b466cd8bc8