2025-12-04 03:10:46,516 - INFO - Domain Default found not creating 2025-12-04 03:10:46,711 - INFO - Project ctest-TestRP-51036230 not found, creating it 2025-12-04 03:10:47,368 - INFO - Created Project:ctest-TestRP-51036230, ID : be3d7e47-539a-496a-8c62-9e5cbc1aab63 2025-12-04 03:10:49,040 - DEBUG - Services list from nova: [, , , ] 2025-12-04 03:10:49,040 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-4840-1', 'an-jenkins-deploy-platform-ansible-os-4840-2']} 2025-12-04 03:10:49,042 - INFO - ================================================================================ 2025-12-04 03:10:49,042 - INFO - STARTING TEST : test_rp_interface 2025-12-04 03:10:49,043 - 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-12-04 03:10:50,326 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.209': '0', '10.20.0.254': '0'}, '10.0.0.130': {'10.20.0.192': '0', '10.20.0.209': '0'}} with 2025-12-04 03:10:50,327 - INFO - Initial checks done. Running the testcase now 2025-12-04 03:10:50,327 - INFO - 2025-12-04 03:10:51,031 - DEBUG - Response for create_network : {'network': {'id': 'fc20c3fa-9291-43a6-a50a-bd473e939f93', 'name': 'ctest-bgpaas_vn-36433876', 'tenant_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'project_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-51036230', 'ctest-bgpaas_vn-36433876'], 'port_security_enabled': True, 'description': ''}} 2025-12-04 03:10:51,371 - DEBUG - Response for create_subnet : {'subnet': {'id': '9dd0669e-8887-4668-b69c-fd80c4b26ec2', 'name': '', 'tenant_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'network_id': 'fc20c3fa-9291-43a6-a50a-bd473e939f93', 'ip_version': 4, 'cidr': '208.70.39.192/26', 'allocation_pools': [{'start': '208.70.39.194', 'end': '208.70.39.254'}], 'gateway_ip': '208.70.39.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '208.70.39.194', 'tags': [], 'project_id': 'be3d7e47539a496a8c629e5cbc1aab63'}} 2025-12-04 03:10:51,396 - INFO - Created VN ctest-bgpaas_vn-36433876 2025-12-04 03:10:51,412 - DEBUG - VN ctest-bgpaas_vn-36433876 UUID is fc20c3fa-9291-43a6-a50a-bd473e939f93 2025-12-04 03:10:51,945 - DEBUG - Response for create_network : {'network': {'id': '4f293395-adb3-4fae-8629-319e7efc8d5c', 'name': 'ctest-bgpaas_vn-50113315', 'tenant_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'project_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-51036230', 'ctest-bgpaas_vn-50113315'], 'port_security_enabled': True, 'description': ''}} 2025-12-04 03:10:52,215 - DEBUG - Response for create_subnet : {'subnet': {'id': '023d39f8-01cf-4d22-a0fa-cc7a4f64fecc', 'name': '', 'tenant_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'network_id': '4f293395-adb3-4fae-8629-319e7efc8d5c', 'ip_version': 4, 'cidr': '74.6.96.128/26', 'allocation_pools': [{'start': '74.6.96.130', 'end': '74.6.96.190'}], 'gateway_ip': '74.6.96.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '74.6.96.130', 'tags': [], 'project_id': 'be3d7e47539a496a8c629e5cbc1aab63'}} 2025-12-04 03:10:52,239 - INFO - Created VN ctest-bgpaas_vn-50113315 2025-12-04 03:10:52,252 - DEBUG - VN ctest-bgpaas_vn-50113315 UUID is 4f293395-adb3-4fae-8629-319e7efc8d5c 2025-12-04 03:10:53,802 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4840-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4840-1) 2025-12-04 03:10:55,450 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4840-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4840-2) 2025-12-04 03:10:55,451 - INFO - Waiting for VM test_vm to be up.. 2025-12-04 03:10:55,565 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-12-04 03:11:00,656 - DEBUG - VM is in ACTIVE state now 2025-12-04 03:11:00,656 - INFO - VM name : test_vm 2025-12-04 03:11:00,761 - DEBUG - VM test_vm ID is 7efd657f-1aa3-4c44-9853-498050f6baf0 2025-12-04 03:11:00,792 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-4840-1 2025-12-04 03:11:00,892 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7efd657f-1aa3-4c44-9853-498050f6baf0 2025-12-04 03:11:01,200 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7efd657f-1aa3-4c44-9853-498050f6baf0 2025-12-04 03:11:01,241 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/880b117f-eeaf-4cb0-bb18-b77f1e1a1ce9 2025-12-04 03:11:04,427 - 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') 2025-12-04 03:11:04,427 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed! 2025-12-04 03:11:04,442 - DEBUG - Gateway for vn default-domain:ctest-TestRP-51036230:ctest-bgpaas_vn-36433876 is 208.70.39.193 and allocation pool is NOT set 2025-12-04 03:11:06,512 - 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.72 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.02 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 = 2.015/3.866/5.718/1.851 ms') 2025-12-04 03:11:06,512 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed 2025-12-04 03:11:06,583 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:11:06,584 - DEBUG - Waiting to SSH to VM test_vm, IP 208.70.39.195, Port 22 2025-12-04 03:11:06,650 - 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-12-04 03:11:06,740 - DEBUG - VM test_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-12-04 03:11:11,741 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:11:11,741 - DEBUG - Waiting to SSH to VM test_vm, IP 208.70.39.195, Port 22 2025-12-04 03:11:11,912 - DEBUG - VM test_vm is ready for SSH connections 2025-12-04 03:11:11,912 - INFO - Waiting for VM test2_vm to be up.. 2025-12-04 03:11:12,001 - DEBUG - VM is in ACTIVE state now 2025-12-04 03:11:12,001 - INFO - VM name : test2_vm 2025-12-04 03:11:12,089 - DEBUG - VM test2_vm ID is dd64a8a2-ca86-41cc-83bc-dc7ee47db6dd 2025-12-04 03:11:12,090 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-4840-2 2025-12-04 03:11:12,172 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/dd64a8a2-ca86-41cc-83bc-dc7ee47db6dd 2025-12-04 03:11:12,183 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/c54f18da-8e02-41ea-959f-f6c5e7cdffb4 2025-12-04 03:11:13,346 - 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.22 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.503 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.503/1.860/3.217/1.357 ms') 2025-12-04 03:11:13,346 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed 2025-12-04 03:11:13,418 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:11:13,418 - DEBUG - Waiting to SSH to VM test2_vm, IP 74.6.96.131, Port 22 2025-12-04 03:11:13,590 - DEBUG - VM test2_vm is ready for SSH connections 2025-12-04 03:11:13,793 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:11:13,808 - DEBUG - Requesting: http://10.20.0.209:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-51036230:ctest-bgpaas_vn-36433876:ctest-bgpaas_vn-36433876.inet.0 2025-12-04 03:11:13,822 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-51036230:ctest-bgpaas_vn-36433876:ctest-bgpaas_vn-36433876.inet.0 2025-12-04 03:11:13,838 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:11:13,838 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 74.6.96.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-12-04 03:11:13,838 - DEBUG - ping -s 56 -c 3 -W 1 74.6.96.131 2025-12-04 03:11:17,864 - DEBUG - PING 74.6.96.131 (74.6.96.131): 56 data bytes 64 bytes from 74.6.96.131: seq=0 ttl=63 time=7.530 ms 64 bytes from 74.6.96.131: seq=1 ttl=63 time=1.873 ms 64 bytes from 74.6.96.131: seq=2 ttl=63 time=1.557 ms --- 74.6.96.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.557/3.653/7.530 ms 2025-12-04 03:11:17,864 - INFO - Ping to IP 74.6.96.131 from VM test_vm passed 2025-12-04 03:11:17,976 - INFO - Deleting VM test2_vm 2025-12-04 03:11:18,072 - INFO - Deleting VM test_vm 2025-12-04 03:11:18,145 - INFO - Deleting VN ctest-bgpaas_vn-50113315 2025-12-04 03:11:18,188 - DEBUG - VN 4f293395-adb3-4fae-8629-319e7efc8d5c still in use: Unable to complete operation on network 4f293395-adb3-4fae-8629-319e7efc8d5c. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-f67e9ba0-bdce-41f0-a0dc-aa521f9b94ae'] 2025-12-04 03:11:18,188 - WARNING - Deleting VN ctest-bgpaas_vn-50113315 failed..Will retry 2025-12-04 03:11:20,352 - DEBUG - Response for deleting network () 2025-12-04 03:11:20,352 - INFO - Deleting VN ctest-bgpaas_vn-36433876 2025-12-04 03:11:20,528 - DEBUG - Response for deleting network () 2025-12-04 03:11:21,822 - DEBUG - No XMPP flaps were noticed during the test
2025-12-04 03:11:21,823 - INFO - END TEST : test_rp_interface : PASSED[0:00:32]
2025-12-04 03:11:21,823 - INFO - -------------------------------------------------------------------------------- 2025-12-04 03:11:21,825 - INFO - ================================================================================ 2025-12-04 03:11:21,826 - INFO - STARTING TEST : test_rp_interface_ext_community 2025-12-04 03:11:21,826 - 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-12-04 03:11:23,131 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.209': '0', '10.20.0.254': '0'}, '10.0.0.130': {'10.20.0.192': '0', '10.20.0.209': '0'}} with 2025-12-04 03:11:23,131 - INFO - Initial checks done. Running the testcase now 2025-12-04 03:11:23,131 - INFO - 2025-12-04 03:11:23,404 - DEBUG - Response for create_network : {'network': {'id': '6919dcc2-0928-41df-9a68-ba227908a2d8', 'name': 'ctest-bgpaas_vn-57245363', 'tenant_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'project_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-51036230', 'ctest-bgpaas_vn-57245363'], 'port_security_enabled': True, 'description': ''}} 2025-12-04 03:11:23,625 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd1218334-d943-4b42-afd6-2f21619bd667', 'name': '', 'tenant_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'network_id': '6919dcc2-0928-41df-9a68-ba227908a2d8', 'ip_version': 4, 'cidr': '100.58.97.192/26', 'allocation_pools': [{'start': '100.58.97.194', 'end': '100.58.97.254'}], 'gateway_ip': '100.58.97.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '100.58.97.194', 'tags': [], 'project_id': 'be3d7e47539a496a8c629e5cbc1aab63'}} 2025-12-04 03:11:23,649 - INFO - Created VN ctest-bgpaas_vn-57245363 2025-12-04 03:11:23,662 - DEBUG - VN ctest-bgpaas_vn-57245363 UUID is 6919dcc2-0928-41df-9a68-ba227908a2d8 2025-12-04 03:11:24,238 - DEBUG - Response for create_network : {'network': {'id': '42af46e9-76b7-4a4e-8960-0e5857ad955a', 'name': 'ctest-bgpaas_vn-61762990', 'tenant_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'project_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-51036230', 'ctest-bgpaas_vn-61762990'], 'port_security_enabled': True, 'description': ''}} 2025-12-04 03:11:24,491 - DEBUG - Response for create_subnet : {'subnet': {'id': '54e7d6ae-5b7f-4426-b7f5-18c76bd31b80', 'name': '', 'tenant_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'network_id': '42af46e9-76b7-4a4e-8960-0e5857ad955a', 'ip_version': 4, 'cidr': '107.69.141.128/26', 'allocation_pools': [{'start': '107.69.141.130', 'end': '107.69.141.190'}], 'gateway_ip': '107.69.141.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '107.69.141.130', 'tags': [], 'project_id': 'be3d7e47539a496a8c629e5cbc1aab63'}} 2025-12-04 03:11:24,515 - INFO - Created VN ctest-bgpaas_vn-61762990 2025-12-04 03:11:24,528 - DEBUG - VN ctest-bgpaas_vn-61762990 UUID is 42af46e9-76b7-4a4e-8960-0e5857ad955a 2025-12-04 03:11:25,937 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4840-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4840-1) 2025-12-04 03:11:27,459 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4840-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4840-2) 2025-12-04 03:11:27,459 - INFO - Waiting for VM test_vm to be up.. 2025-12-04 03:11:27,568 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-12-04 03:11:32,667 - DEBUG - VM is in ACTIVE state now 2025-12-04 03:11:32,667 - INFO - VM name : test_vm 2025-12-04 03:11:32,761 - DEBUG - VM test_vm ID is c4318f39-e8fc-4560-b53c-3f9f784b8cd5 2025-12-04 03:11:32,761 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-4840-1 2025-12-04 03:11:32,847 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c4318f39-e8fc-4560-b53c-3f9f784b8cd5 2025-12-04 03:11:32,858 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/61a0bc0c-b43d-4618-aad3-5e68c0fe9ca6 2025-12-04 03:11:36,033 - 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 1014ms') 2025-12-04 03:11:36,033 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed! 2025-12-04 03:11:36,054 - DEBUG - Gateway for vn default-domain:ctest-TestRP-51036230:ctest-bgpaas_vn-57245363 is 100.58.97.193 and allocation pool is NOT set 2025-12-04 03:11:38,125 - 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.41 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.776 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.776/5.092/9.409/4.316 ms') 2025-12-04 03:11:38,125 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed 2025-12-04 03:11:38,194 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:11:38,194 - DEBUG - Waiting to SSH to VM test_vm, IP 100.58.97.195, Port 22 2025-12-04 03:11:38,263 - 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-12-04 03:11:38,348 - DEBUG - VM test_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-12-04 03:11:43,349 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:11:43,349 - DEBUG - Waiting to SSH to VM test_vm, IP 100.58.97.195, Port 22 2025-12-04 03:11:43,515 - DEBUG - VM test_vm is ready for SSH connections 2025-12-04 03:11:43,515 - INFO - Waiting for VM test2_vm to be up.. 2025-12-04 03:11:43,607 - DEBUG - VM is in ACTIVE state now 2025-12-04 03:11:43,607 - INFO - VM name : test2_vm 2025-12-04 03:11:43,693 - DEBUG - VM test2_vm ID is e5ed738e-14dc-46fb-82d7-be133c4f2426 2025-12-04 03:11:43,693 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-4840-2 2025-12-04 03:11:43,880 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/e5ed738e-14dc-46fb-82d7-be133c4f2426 2025-12-04 03:11:43,891 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/5dde39b8-e84d-4c48-99ad-7e72e94c8a5a 2025-12-04 03:11:45,058 - 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.54 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.612 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.612/2.078/3.544/1.466 ms') 2025-12-04 03:11:45,058 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed 2025-12-04 03:11:45,131 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:11:45,131 - DEBUG - Waiting to SSH to VM test2_vm, IP 107.69.141.131, Port 22 2025-12-04 03:11:45,302 - DEBUG - VM test2_vm is ready for SSH connections 2025-12-04 03:11:45,461 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:11:45,474 - DEBUG - Requesting: http://10.20.0.209:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-51036230:ctest-bgpaas_vn-57245363:ctest-bgpaas_vn-57245363.inet.0 2025-12-04 03:11:46,489 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:11:46,503 - DEBUG - Requesting: http://10.20.0.209:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-51036230:ctest-bgpaas_vn-57245363:ctest-bgpaas_vn-57245363.inet.0 2025-12-04 03:11:46,517 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-51036230:ctest-bgpaas_vn-57245363:ctest-bgpaas_vn-57245363.inet.0 2025-12-04 03:11:46,530 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:11:46,530 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 107.69.141.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-12-04 03:11:46,530 - DEBUG - ping -s 56 -c 3 -W 1 107.69.141.131 2025-12-04 03:11:50,241 - DEBUG - PING 107.69.141.131 (107.69.141.131): 56 data bytes 64 bytes from 107.69.141.131: seq=0 ttl=63 time=6.394 ms 64 bytes from 107.69.141.131: seq=1 ttl=63 time=1.514 ms 64 bytes from 107.69.141.131: seq=2 ttl=63 time=1.298 ms --- 107.69.141.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.298/3.068/6.394 ms 2025-12-04 03:11:50,241 - INFO - Ping to IP 107.69.141.131 from VM test_vm passed 2025-12-04 03:11:50,413 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:11:50,427 - DEBUG - Requesting: http://10.20.0.209:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-51036230:ctest-bgpaas_vn-57245363:ctest-bgpaas_vn-57245363.inet.0 2025-12-04 03:11:50,447 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-51036230:ctest-bgpaas_vn-57245363:ctest-bgpaas_vn-57245363.inet.0 2025-12-04 03:11:50,755 - INFO - Deleting VM test2_vm 2025-12-04 03:11:50,842 - INFO - Deleting VM test_vm 2025-12-04 03:11:50,924 - INFO - Deleting VN ctest-bgpaas_vn-61762990 2025-12-04 03:11:50,971 - DEBUG - VN 42af46e9-76b7-4a4e-8960-0e5857ad955a still in use: Unable to complete operation on network 42af46e9-76b7-4a4e-8960-0e5857ad955a. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-4807a2a2-3bf9-4697-b124-e8db899b3ffe'] 2025-12-04 03:11:50,972 - WARNING - Deleting VN ctest-bgpaas_vn-61762990 failed..Will retry 2025-12-04 03:11:53,176 - DEBUG - Response for deleting network () 2025-12-04 03:11:53,177 - INFO - Deleting VN ctest-bgpaas_vn-57245363 2025-12-04 03:11:53,403 - DEBUG - Response for deleting network () 2025-12-04 03:11:54,716 - DEBUG - No XMPP flaps were noticed during the test
2025-12-04 03:11:54,716 - INFO - END TEST : test_rp_interface_ext_community : PASSED[0:00:33]
2025-12-04 03:11:54,716 - INFO - -------------------------------------------------------------------------------- 2025-12-04 03:11:54,721 - INFO - ================================================================================ 2025-12-04 03:11:54,721 - INFO - STARTING TEST : test_rp_interface_static 2025-12-04 03:11:54,721 - 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-12-04 03:11:56,001 - DEBUG - Nothing to compare xmpp stats {'10.0.0.22': {'10.20.0.209': '0', '10.20.0.254': '0'}, '10.0.0.130': {'10.20.0.192': '0', '10.20.0.209': '0'}} with 2025-12-04 03:11:56,001 - INFO - Initial checks done. Running the testcase now 2025-12-04 03:11:56,001 - INFO - 2025-12-04 03:11:56,295 - DEBUG - Response for create_network : {'network': {'id': '79d85e0a-f087-4a41-b64c-a9bdff1abf3d', 'name': 'ctest-bgpaas_vn-86170769', 'tenant_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'project_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-51036230', 'ctest-bgpaas_vn-86170769'], 'port_security_enabled': True, 'description': ''}} 2025-12-04 03:11:56,625 - DEBUG - Response for create_subnet : {'subnet': {'id': '4552d38f-e14b-40c2-95bc-b416f9c0d8b7', 'name': '', 'tenant_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'network_id': '79d85e0a-f087-4a41-b64c-a9bdff1abf3d', 'ip_version': 4, 'cidr': '173.228.119.192/26', 'allocation_pools': [{'start': '173.228.119.194', 'end': '173.228.119.254'}], 'gateway_ip': '173.228.119.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '173.228.119.194', 'tags': [], 'project_id': 'be3d7e47539a496a8c629e5cbc1aab63'}} 2025-12-04 03:11:56,653 - INFO - Created VN ctest-bgpaas_vn-86170769 2025-12-04 03:11:56,668 - DEBUG - VN ctest-bgpaas_vn-86170769 UUID is 79d85e0a-f087-4a41-b64c-a9bdff1abf3d 2025-12-04 03:11:57,265 - DEBUG - Response for create_network : {'network': {'id': '1d6fbaca-4fa0-4331-b6ed-506f93a9c46d', 'name': 'ctest-bgpaas_vn-70978461', 'tenant_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'project_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestRP-51036230', 'ctest-bgpaas_vn-70978461'], 'port_security_enabled': True, 'description': ''}} 2025-12-04 03:11:57,509 - DEBUG - Response for create_subnet : {'subnet': {'id': '59372cb3-0613-4c89-82f7-5e23eeb2f99a', 'name': '', 'tenant_id': 'be3d7e47539a496a8c629e5cbc1aab63', 'network_id': '1d6fbaca-4fa0-4331-b6ed-506f93a9c46d', 'ip_version': 4, 'cidr': '17.61.61.128/26', 'allocation_pools': [{'start': '17.61.61.130', 'end': '17.61.61.190'}], 'gateway_ip': '17.61.61.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '17.61.61.130', 'tags': [], 'project_id': 'be3d7e47539a496a8c629e5cbc1aab63'}} 2025-12-04 03:11:57,540 - INFO - Created VN ctest-bgpaas_vn-70978461 2025-12-04 03:11:57,550 - DEBUG - VN ctest-bgpaas_vn-70978461 UUID is 1d6fbaca-4fa0-4331-b6ed-506f93a9c46d 2025-12-04 03:11:58,912 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4840-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4840-1) 2025-12-04 03:12:00,364 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4840-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4840-2) 2025-12-04 03:12:00,364 - INFO - Waiting for VM test_vm to be up.. 2025-12-04 03:12:00,470 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-12-04 03:12:05,556 - DEBUG - VM is in ACTIVE state now 2025-12-04 03:12:05,556 - INFO - VM name : test_vm 2025-12-04 03:12:05,645 - DEBUG - VM test_vm ID is 42fa214a-ab5e-48be-b775-0291d41ba6dc 2025-12-04 03:12:05,646 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-4840-1 2025-12-04 03:12:05,739 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/42fa214a-ab5e-48be-b775-0291d41ba6dc 2025-12-04 03:12:05,755 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/592d836b-8b56-4113-b75b-3ca7458bdbe5 2025-12-04 03:12:08,929 - 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') 2025-12-04 03:12:08,929 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed! 2025-12-04 03:12:08,948 - DEBUG - Gateway for vn default-domain:ctest-TestRP-51036230:ctest-bgpaas_vn-86170769 is 173.228.119.193 and allocation pool is NOT set 2025-12-04 03:12:11,013 - 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.13 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.16 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 = 3.155/5.642/8.130/2.487 ms') 2025-12-04 03:12:11,013 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed 2025-12-04 03:12:11,083 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:12:11,083 - DEBUG - Waiting to SSH to VM test_vm, IP 173.228.119.195, Port 22 2025-12-04 03:12:11,151 - 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-12-04 03:12:11,253 - DEBUG - VM test_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-12-04 03:12:16,253 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:12:16,253 - DEBUG - Waiting to SSH to VM test_vm, IP 173.228.119.195, Port 22 2025-12-04 03:12:16,422 - DEBUG - VM test_vm is ready for SSH connections 2025-12-04 03:12:16,423 - INFO - Waiting for VM test2_vm to be up.. 2025-12-04 03:12:16,509 - DEBUG - VM is in ACTIVE state now 2025-12-04 03:12:16,509 - INFO - VM name : test2_vm 2025-12-04 03:12:16,597 - DEBUG - VM test2_vm ID is cb5c37f8-54ae-4f4d-a870-a4cd8cf65e9b 2025-12-04 03:12:16,597 - DEBUG - VM test2_vm launched on Node an-jenkins-deploy-platform-ansible-os-4840-2 2025-12-04 03:12:16,694 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/cb5c37f8-54ae-4f4d-a870-a4cd8cf65e9b 2025-12-04 03:12:16,705 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/b63c36cf-9be2-4e4a-86a7-7796a48aa05f 2025-12-04 03:12:17,879 - 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.60 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.461 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.461/2.029/3.598/1.568 ms') 2025-12-04 03:12:17,879 - INFO - Ping to Metadata IP 169.254.0.3 of VM test2_vm passed 2025-12-04 03:12:17,950 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:12:17,950 - DEBUG - Waiting to SSH to VM test2_vm, IP 17.61.61.131, Port 22 2025-12-04 03:12:18,123 - DEBUG - VM test2_vm is ready for SSH connections 2025-12-04 03:12:18,217 - INFO - Created InterfaceRouteTable ['default-domain', 'ctest-TestRP-51036230', 'ctest-int_table_right-80476670'](UUID cd663603-6425-474a-86ff-c5c95f8df98c), prefixes : ['140.116.246.128/26'] 2025-12-04 03:12:18,285 - INFO - Added intf route table cd663603-6425-474a-86ff-c5c95f8df98c to port 592d836b-8b56-4113-b75b-3ca7458bdbe5 2025-12-04 03:12:18,492 - DEBUG - Requesting: http://10.20.0.209:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-51036230:ctest-bgpaas_vn-86170769:ctest-bgpaas_vn-86170769.inet.0 2025-12-04 03:12:18,508 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-51036230:ctest-bgpaas_vn-86170769:ctest-bgpaas_vn-86170769.inet.0 2025-12-04 03:12:18,524 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-12-04 03:12:18,524 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 17.61.61.131, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.22, gateway password: c0ntrail123 2025-12-04 03:12:18,524 - DEBUG - ping -s 56 -c 3 -W 1 17.61.61.131 2025-12-04 03:12:22,587 - DEBUG - PING 17.61.61.131 (17.61.61.131): 56 data bytes 64 bytes from 17.61.61.131: seq=0 ttl=63 time=7.622 ms 64 bytes from 17.61.61.131: seq=1 ttl=63 time=1.498 ms 64 bytes from 17.61.61.131: seq=2 ttl=63 time=1.227 ms --- 17.61.61.131 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.227/3.449/7.622 ms 2025-12-04 03:12:22,587 - INFO - Ping to IP 17.61.61.131 from VM test_vm passed 2025-12-04 03:12:22,717 - INFO - Deleting VM test2_vm 2025-12-04 03:12:22,807 - INFO - Deleting VM test_vm 2025-12-04 03:12:22,882 - INFO - Deleting VN ctest-bgpaas_vn-70978461 2025-12-04 03:12:22,927 - DEBUG - VN 1d6fbaca-4fa0-4331-b6ed-506f93a9c46d still in use: Unable to complete operation on network 1d6fbaca-4fa0-4331-b6ed-506f93a9c46d. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-bb1b7908-638e-4e0f-8f6b-dc08c783a6b4'] 2025-12-04 03:12:22,927 - WARNING - Deleting VN ctest-bgpaas_vn-70978461 failed..Will retry 2025-12-04 03:12:25,092 - DEBUG - Response for deleting network () 2025-12-04 03:12:25,092 - INFO - Deleting VN ctest-bgpaas_vn-86170769 2025-12-04 03:12:25,284 - DEBUG - Response for deleting network () 2025-12-04 03:12:26,568 - DEBUG - No XMPP flaps were noticed during the test
2025-12-04 03:12:26,568 - INFO - END TEST : test_rp_interface_static : PASSED[0:00:32]
2025-12-04 03:12:26,569 - INFO - -------------------------------------------------------------------------------- 2025-12-04 03:12:27,353 - INFO - Deleted project: ctest-TestRP-51036230, ID : be3d7e47-539a-496a-8c62-9e5cbc1aab63