2026-03-23 04:05:33,360 - INFO - Domain Default found not creating 2026-03-23 04:05:33,677 - INFO - Project ctest-TestBGPaasZone-88137159 not found, creating it 2026-03-23 04:05:34,261 - INFO - Created Project:ctest-TestBGPaasZone-88137159, ID : 75e3162c-0fc6-49c9-bdf7-2fe98496205a 2026-03-23 04:05:36,321 - DEBUG - Services list from nova: [, , , ] 2026-03-23 04:05:36,322 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-5514-2', 'an-jenkins-deploy-platform-ansible-os-5514-1']} 2026-03-23 04:05:36,324 - INFO - ================================================================================ 2026-03-23 04:05:36,324 - INFO - STARTING TEST : test_bgp_control_node_zone 2026-03-23 04:05:37,610 - DEBUG - Skipping xmpp flap check 2026-03-23 04:05:37,611 - INFO - Initial checks done. Running the testcase now 2026-03-23 04:05:37,612 - INFO - 2026-03-23 04:05:37,612 - INFO - executing bgp_control_zone test 2026-03-23 04:05:38,525 - DEBUG - Response for create_network : {'network': {'id': '76ffa957-fa73-46fd-8a32-c21ca3f359ee', 'name': 'ctest-cnz_vn-71251029', 'tenant_id': '75e3162c0fc649c9bdf72fe98496205a', 'project_id': '75e3162c0fc649c9bdf72fe98496205a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBGPaasZone-88137159', 'ctest-cnz_vn-71251029'], 'port_security_enabled': True, 'description': ''}} 2026-03-23 04:05:39,017 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fc4471ef-20dc-4b3e-adc1-e7bd4ac6df90', 'name': '', 'tenant_id': '75e3162c0fc649c9bdf72fe98496205a', 'network_id': '76ffa957-fa73-46fd-8a32-c21ca3f359ee', 'ip_version': 4, 'cidr': '168.128.252.128/26', 'allocation_pools': [{'start': '168.128.252.130', 'end': '168.128.252.190'}], 'gateway_ip': '168.128.252.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '168.128.252.130', 'tags': [], 'project_id': '75e3162c0fc649c9bdf72fe98496205a'}} 2026-03-23 04:05:39,044 - INFO - Created VN ctest-cnz_vn-71251029 2026-03-23 04:05:39,060 - DEBUG - VN ctest-cnz_vn-71251029 UUID is 76ffa957-fa73-46fd-8a32-c21ca3f359ee 2026-03-23 04:05:40,476 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5514-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5514-2) 2026-03-23 04:05:42,284 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5514-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5514-1) 2026-03-23 04:05:44,421 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5514-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5514-2) 2026-03-23 04:05:44,421 - INFO - Waiting for VM ctest-TestBGPaasZone-88137159-54779880 to be up.. 2026-03-23 04:05:44,596 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-23 04:05:49,765 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-23 04:05:54,930 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-23 04:06:00,027 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-23 04:06:05,157 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-23 04:06:10,257 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-23 04:06:15,359 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-23 04:06:20,460 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-03-23 04:06:25,571 - DEBUG - VM is in ACTIVE state now 2026-03-23 04:06:25,571 - INFO - VM name : ctest-TestBGPaasZone-88137159-54779880 2026-03-23 04:06:25,672 - DEBUG - VM ctest-TestBGPaasZone-88137159-54779880 ID is f4019151-529c-4545-a695-b20dec0b416f 2026-03-23 04:06:25,704 - DEBUG - VM ctest-TestBGPaasZone-88137159-54779880 launched on Node an-jenkins-deploy-platform-ansible-os-5514-2 2026-03-23 04:06:25,798 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/f4019151-529c-4545-a695-b20dec0b416f 2026-03-23 04:06:26,144 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/f4019151-529c-4545-a695-b20dec0b416f 2026-03-23 04:06:26,189 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/7caa2eb9-d02d-4c64-b459-786399f0e58e 2026-03-23 04:06:29,384 - 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-03-23 04:06:29,385 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:06:29,399 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:06:33,479 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2026-03-23 04:06:33,479 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:06:33,493 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:06:37,579 - 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-03-23 04:06:37,579 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:06:37,597 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:06:41,673 - 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 1006ms') 2026-03-23 04:06:41,673 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:06:41,691 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:06:45,764 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2026-03-23 04:06:45,764 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:06:45,783 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:06:49,859 - 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-03-23 04:06:49,859 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:06:49,874 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:06:53,959 - 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 1020ms') 2026-03-23 04:06:53,959 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:06:53,975 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:06:58,056 - 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-03-23 04:06:58,057 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:06:58,074 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:07:02,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 1014ms') 2026-03-23 04:07:02,153 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:07:02,173 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:07:06,247 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2026-03-23 04:07:06,247 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:07:06,263 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:07:10,347 - 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-03-23 04:07:10,347 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:07:10,363 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:07:14,436 - 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 1013ms') 2026-03-23 04:07:14,436 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:07:14,455 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:07:18,534 - 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-03-23 04:07:18,534 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:07:18,550 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:07:22,634 - 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-03-23 04:07:22,634 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:07:22,649 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:07:26,724 - 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 1013ms') 2026-03-23 04:07:26,724 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:07:26,740 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:07:30,826 - 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 1016ms') 2026-03-23 04:07:30,826 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:07:30,841 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:07:34,916 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2026-03-23 04:07:34,916 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:07:34,942 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:07:39,019 - 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') 2026-03-23 04:07:39,019 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:07:39,038 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:07:43,108 - 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 1009ms') 2026-03-23 04:07:43,108 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:07:43,126 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:07:47,205 - 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-03-23 04:07:47,205 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:07:47,221 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:07:51,308 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms') 2026-03-23 04:07:51,308 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 failed! 2026-03-23 04:07:51,327 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-71251029 is 168.128.252.129 and allocation pool is NOT set 2026-03-23 04:07:53,389 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=3.02 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.844 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.844/1.930/3.016/1.086 ms') 2026-03-23 04:07:53,389 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-54779880 passed 2026-03-23 04:07:53,460 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:07:53,460 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-54779880, IP 168.128.252.131, Port 22 2026-03-23 04:07:53,528 - DEBUG - Error on ssh to ubuntu@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-03-23 04:07:53,681 - DEBUG - VM ctest-TestBGPaasZone-88137159-54779880 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:07:58,683 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:07:58,683 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-54779880, IP 168.128.252.131, Port 22 2026-03-23 04:07:58,752 - DEBUG - Error on ssh to ubuntu@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-03-23 04:07:58,855 - DEBUG - VM ctest-TestBGPaasZone-88137159-54779880 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:08:03,856 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:08:03,856 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-54779880, IP 168.128.252.131, Port 22 2026-03-23 04:08:03,923 - DEBUG - Error on ssh to ubuntu@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-03-23 04:08:04,064 - DEBUG - VM ctest-TestBGPaasZone-88137159-54779880 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:08:09,065 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:08:09,065 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-54779880, IP 168.128.252.131, Port 22 2026-03-23 04:08:09,135 - DEBUG - Error on ssh to ubuntu@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-03-23 04:08:09,240 - DEBUG - VM ctest-TestBGPaasZone-88137159-54779880 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:08:14,240 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:08:14,241 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-54779880, IP 168.128.252.131, Port 22 2026-03-23 04:08:14,308 - DEBUG - Error on ssh to ubuntu@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-03-23 04:08:14,415 - DEBUG - VM ctest-TestBGPaasZone-88137159-54779880 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:08:19,416 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:08:19,416 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-54779880, IP 168.128.252.131, Port 22 2026-03-23 04:08:19,484 - DEBUG - Error on ssh to ubuntu@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-03-23 04:08:19,585 - DEBUG - VM ctest-TestBGPaasZone-88137159-54779880 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:08:24,586 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:08:24,586 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-54779880, IP 168.128.252.131, Port 22 2026-03-23 04:08:24,657 - DEBUG - Error on ssh to ubuntu@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-03-23 04:08:24,761 - DEBUG - VM ctest-TestBGPaasZone-88137159-54779880 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:08:29,761 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:08:29,762 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-54779880, IP 168.128.252.131, Port 22 2026-03-23 04:08:29,833 - DEBUG - Error on ssh to ubuntu@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-03-23 04:08:29,957 - DEBUG - VM ctest-TestBGPaasZone-88137159-54779880 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:08:34,959 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:08:34,959 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-54779880, IP 168.128.252.131, Port 22 2026-03-23 04:08:35,031 - DEBUG - Error on ssh to ubuntu@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-03-23 04:08:35,130 - DEBUG - VM ctest-TestBGPaasZone-88137159-54779880 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:08:40,131 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:08:40,132 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-54779880, IP 168.128.252.131, Port 22 2026-03-23 04:08:40,199 - DEBUG - Error on ssh to ubuntu@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-03-23 04:08:40,316 - DEBUG - VM ctest-TestBGPaasZone-88137159-54779880 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:08:45,317 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:08:45,317 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-54779880, IP 168.128.252.131, Port 22 2026-03-23 04:08:45,384 - DEBUG - Error on ssh to ubuntu@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-03-23 04:08:45,494 - DEBUG - VM ctest-TestBGPaasZone-88137159-54779880 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:08:50,494 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:08:50,494 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-54779880, IP 168.128.252.131, Port 22 2026-03-23 04:08:50,668 - DEBUG - VM ctest-TestBGPaasZone-88137159-54779880 is ready for SSH connections 2026-03-23 04:08:50,669 - INFO - Waiting for VM ctest-TestBGPaasZone-88137159-53663238 to be up.. 2026-03-23 04:08:50,775 - DEBUG - VM is in ACTIVE state now 2026-03-23 04:08:50,775 - INFO - VM name : ctest-TestBGPaasZone-88137159-53663238 2026-03-23 04:08:50,883 - DEBUG - VM ctest-TestBGPaasZone-88137159-53663238 ID is 7df40b42-c87a-433b-93c2-4db66054f827 2026-03-23 04:08:50,883 - DEBUG - VM ctest-TestBGPaasZone-88137159-53663238 launched on Node an-jenkins-deploy-platform-ansible-os-5514-1 2026-03-23 04:08:50,989 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/7df40b42-c87a-433b-93c2-4db66054f827 2026-03-23 04:08:51,002 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/6de618ac-7769-4e45-9cbe-23b4fdefcc81 2026-03-23 04:08:52,183 - 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.80 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.628 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.628/2.712/4.796/2.084 ms') 2026-03-23 04:08:52,183 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-53663238 passed 2026-03-23 04:08:52,258 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:08:52,258 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-53663238, IP 168.128.252.132, Port 22 2026-03-23 04:08:52,434 - DEBUG - VM ctest-TestBGPaasZone-88137159-53663238 is ready for SSH connections 2026-03-23 04:08:52,434 - INFO - Waiting for VM ctest-TestBGPaasZone-88137159-85264197 to be up.. 2026-03-23 04:08:52,543 - DEBUG - VM is in ACTIVE state now 2026-03-23 04:08:52,543 - INFO - VM name : ctest-TestBGPaasZone-88137159-85264197 2026-03-23 04:08:52,679 - DEBUG - VM ctest-TestBGPaasZone-88137159-85264197 ID is 8046283e-65cf-4eed-8685-830d1ad690b1 2026-03-23 04:08:52,680 - DEBUG - VM ctest-TestBGPaasZone-88137159-85264197 launched on Node an-jenkins-deploy-platform-ansible-os-5514-2 2026-03-23 04:08:52,804 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/8046283e-65cf-4eed-8685-830d1ad690b1 2026-03-23 04:08:52,814 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/dc534482-4afe-4910-84a4-d878aba0eb07 2026-03-23 04:08:53,973 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=6.27 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.593 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.593/3.432/6.271/2.839 ms') 2026-03-23 04:08:53,973 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-88137159-85264197 passed 2026-03-23 04:08:54,045 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:08:54,045 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-85264197, IP 168.128.252.133, Port 22 2026-03-23 04:08:54,215 - DEBUG - VM ctest-TestBGPaasZone-88137159-85264197 is ready for SSH connections 2026-03-23 04:08:54,351 - INFO - Created control node zone ctest-test-zone-21585013(db471d5a-52d5-4ff5-bd75-11bc248b8e5e) 2026-03-23 04:08:54,711 - INFO - Created control node zone ctest-test-zone-89290351(189e1ad0-91bf-401b-a656-3e7684093085) 2026-03-23 04:08:55,067 - INFO - Created control node zone ctest-test-zone-17103458(23279198-f7e7-4450-9163-ab771b6c4764) 2026-03-23 04:08:55,363 - INFO - Creating BGPaaS ctest-TestBGPaasZone-88137159-29341318 2026-03-23 04:08:55,447 - INFO - BGPaaS: ctest-TestBGPaasZone-88137159-29341318(a550c983-075e-4aed-8b68-fefe333ad6d0) 2026-03-23 04:08:55,447 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/a550c983-075e-4aed-8b68-fefe333ad6d0 2026-03-23 04:08:55,769 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/a550c983-075e-4aed-8b68-fefe333ad6d0 2026-03-23 04:08:55,817 - INFO - verify_in_api_server passed for BGPaaS obj a550c983-075e-4aed-8b68-fefe333ad6d0 2026-03-23 04:08:55,817 - INFO - BGPaaS(a550c983-075e-4aed-8b68-fefe333ad6d0): verify_on_setup passed 2026-03-23 04:08:55,818 - INFO - We will configure BGP on the VM 2026-03-23 04:08:56,091 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:08:56,091 - INFO - Configuring BGP on ctest-TestBGPaasZone-88137159-54779880 2026-03-23 04:08:56,091 - DEBUG - Running remote_cmd, Cmd : cat > /etc/bird/bird.conf << EOS protocol device { scan time 10; # Scan interfaces every 10 seconds } protocol kernel { persist; # Don't remove routes on bird shutdown scan time 20; # Scan kernel routing table every 20 seconds import all; # Default is import all #export all; # Default is export none } protocol direct { interface "eth*"; } router id 168.128.252.131; protocol bgp bgp_1{ local as 65000; neighbor 168.128.252.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 168.128.252.131; } protocol bfd { neighbor 168.128.252.129 local 168.128.252.131 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:08:56,091 - DEBUG - cat > /etc/bird/bird.conf << EOS protocol device { scan time 10; # Scan interfaces every 10 seconds } protocol kernel { persist; # Don't remove routes on bird shutdown scan time 20; # Scan kernel routing table every 20 seconds import all; # Default is import all #export all; # Default is export none } protocol direct { interface "eth*"; } router id 168.128.252.131; protocol bgp bgp_1{ local as 65000; neighbor 168.128.252.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 168.128.252.131; } protocol bfd { neighbor 168.128.252.129 local 168.128.252.131 multihop on; } EOS 2026-03-23 04:09:11,087 - DEBUG - None 2026-03-23 04:09:11,088 - DEBUG - Running remote_cmd, Cmd : cat > /etc/bird/bird.conf << EOS protocol device { scan time 10; # Scan interfaces every 10 seconds } protocol kernel { persist; # Don't remove routes on bird shutdown scan time 20; # Scan kernel routing table every 20 seconds import all; # Default is import all #export all; # Default is export none } protocol direct { interface "eth*"; } router id 168.128.252.131; protocol bgp bgp_1{ local as 65000; neighbor 168.128.252.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 168.128.252.131; } protocol bfd { neighbor 168.128.252.129 local 168.128.252.131 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:09:11,088 - DEBUG - cat > /etc/bird/bird.conf << EOS protocol device { scan time 10; # Scan interfaces every 10 seconds } protocol kernel { persist; # Don't remove routes on bird shutdown scan time 20; # Scan kernel routing table every 20 seconds import all; # Default is import all #export all; # Default is export none } protocol direct { interface "eth*"; } router id 168.128.252.131; protocol bgp bgp_1{ local as 65000; neighbor 168.128.252.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 168.128.252.131; } protocol bfd { neighbor 168.128.252.129 local 168.128.252.131 multihop on; } EOS 2026-03-23 04:09:12,101 - DEBUG - None 2026-03-23 04:09:12,101 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:09:12,101 - DEBUG - service bird restart 2026-03-23 04:09:15,565 - DEBUG - bird stop/waiting bird start/running, process 1431 2026-03-23 04:09:15,565 - INFO - Attaching VMI 7caa2eb9-d02d-4c64-b459-786399f0e58e to BGPaaS a550c983-075e-4aed-8b68-fefe333ad6d0 2026-03-23 04:09:15,643 - INFO - Attaching the VMI f4019151-529c-4545-a695-b20dec0b416f to the BGPaaS a550c983-075e-4aed-8b68-fefe333ad6d0 object 2026-03-23 04:09:15,643 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 168.128.252.148 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:09:15,644 - DEBUG - sudo ip addr add 168.128.252.148 dev eth0 2026-03-23 04:09:17,163 - DEBUG - None 2026-03-23 04:09:17,231 - INFO - Creating BGPaaS ctest-TestBGPaasZone-88137159-39451124 2026-03-23 04:09:17,315 - INFO - BGPaaS: ctest-TestBGPaasZone-88137159-39451124(c7fafd24-6805-4d36-86b7-189fd87d3d2a) 2026-03-23 04:09:17,315 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/c7fafd24-6805-4d36-86b7-189fd87d3d2a 2026-03-23 04:09:17,328 - INFO - verify_in_api_server passed for BGPaaS obj c7fafd24-6805-4d36-86b7-189fd87d3d2a 2026-03-23 04:09:17,328 - INFO - BGPaaS(c7fafd24-6805-4d36-86b7-189fd87d3d2a): verify_on_setup passed 2026-03-23 04:09:17,328 - INFO - We will configure BGP on the VM 2026-03-23 04:09:17,599 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:09:17,599 - INFO - Configuring BGP on ctest-TestBGPaasZone-88137159-53663238 2026-03-23 04:09:17,599 - DEBUG - Running remote_cmd, Cmd : cat > /etc/bird/bird.conf << EOS protocol device { scan time 10; # Scan interfaces every 10 seconds } protocol kernel { persist; # Don't remove routes on bird shutdown scan time 20; # Scan kernel routing table every 20 seconds import all; # Default is import all #export all; # Default is export none } protocol direct { interface "eth*"; } router id 168.128.252.132; protocol bgp bgp_1{ local as 65000; neighbor 168.128.252.130 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 168.128.252.132; } protocol bfd { neighbor 168.128.252.130 local 168.128.252.132 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-23 04:09:17,600 - DEBUG - cat > /etc/bird/bird.conf << EOS protocol device { scan time 10; # Scan interfaces every 10 seconds } protocol kernel { persist; # Don't remove routes on bird shutdown scan time 20; # Scan kernel routing table every 20 seconds import all; # Default is import all #export all; # Default is export none } protocol direct { interface "eth*"; } router id 168.128.252.132; protocol bgp bgp_1{ local as 65000; neighbor 168.128.252.130 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 168.128.252.132; } protocol bfd { neighbor 168.128.252.130 local 168.128.252.132 multihop on; } EOS 2026-03-23 04:09:24,042 - DEBUG - None 2026-03-23 04:09:24,042 - DEBUG - Running remote_cmd, Cmd : cat > /etc/bird/bird.conf << EOS protocol device { scan time 10; # Scan interfaces every 10 seconds } protocol kernel { persist; # Don't remove routes on bird shutdown scan time 20; # Scan kernel routing table every 20 seconds import all; # Default is import all #export all; # Default is export none } protocol direct { interface "eth*"; } router id 168.128.252.132; protocol bgp bgp_1{ local as 65000; neighbor 168.128.252.130 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 168.128.252.132; } protocol bfd { neighbor 168.128.252.130 local 168.128.252.132 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-23 04:09:24,042 - DEBUG - cat > /etc/bird/bird.conf << EOS protocol device { scan time 10; # Scan interfaces every 10 seconds } protocol kernel { persist; # Don't remove routes on bird shutdown scan time 20; # Scan kernel routing table every 20 seconds import all; # Default is import all #export all; # Default is export none } protocol direct { interface "eth*"; } router id 168.128.252.132; protocol bgp bgp_1{ local as 65000; neighbor 168.128.252.130 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 168.128.252.132; } protocol bfd { neighbor 168.128.252.130 local 168.128.252.132 multihop on; } EOS 2026-03-23 04:09:24,657 - DEBUG - None 2026-03-23 04:09:24,657 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-23 04:09:24,657 - DEBUG - service bird restart 2026-03-23 04:09:26,705 - DEBUG - bird stop/waiting bird start/running, process 1441 2026-03-23 04:09:26,705 - INFO - Attaching VMI 6de618ac-7769-4e45-9cbe-23b4fdefcc81 to BGPaaS c7fafd24-6805-4d36-86b7-189fd87d3d2a 2026-03-23 04:09:26,787 - INFO - Attaching the VMI 7df40b42-c87a-433b-93c2-4db66054f827 to the BGPaaS c7fafd24-6805-4d36-86b7-189fd87d3d2a object 2026-03-23 04:09:26,787 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 168.128.252.148 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-23 04:09:26,787 - DEBUG - sudo ip addr add 168.128.252.148 dev eth0 2026-03-23 04:09:27,623 - DEBUG - None 2026-03-23 04:09:27,623 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:09:27,645 - INFO - BGPaaS session seen in control-node 10.20.0.14 2026-03-23 04:09:27,654 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:09:27,654 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-03-23 04:09:27,670 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25'] 2026-03-23 04:09:33,671 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-03-23 04:09:33,688 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25'] 2026-03-23 04:09:39,689 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-03-23 04:09:39,706 - INFO - BGPaaS session seen in control-node 10.20.0.25 2026-03-23 04:09:39,714 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 168.128.252.148, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:09:39,714 - DEBUG - ping -s 56 -c 3 -W 1 168.128.252.148 2026-03-23 04:09:46,404 - DEBUG - PING 168.128.252.148 (168.128.252.148) 56(84) bytes of data. 64 bytes from 168.128.252.148: icmp_seq=1 ttl=64 time=7.99 ms 64 bytes from 168.128.252.148: icmp_seq=2 ttl=64 time=1.40 ms 64 bytes from 168.128.252.148: icmp_seq=3 ttl=64 time=1.81 ms --- 168.128.252.148 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2005ms rtt min/avg/max/mdev = 1.402/3.737/7.999/3.018 ms 2026-03-23 04:09:46,405 - INFO - Ping to IP 168.128.252.148 from VM ctest-TestBGPaasZone-88137159-85264197 passed 2026-03-23 04:09:47,231 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:09:47,231 - DEBUG - service bird restart 2026-03-23 04:09:49,023 - DEBUG - bird stop/waiting bird start/running, process 1467 2026-03-23 04:09:49,023 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-23 04:09:49,024 - DEBUG - service bird restart 2026-03-23 04:09:51,093 - DEBUG - bird stop/waiting bird start/running, process 1468 2026-03-23 04:09:51,093 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:09:51,114 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.18'] 2026-03-23 04:09:57,132 - INFO - BGPaaS session seen in control-node 10.20.0.18 2026-03-23 04:09:57,141 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:09:57,141 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-03-23 04:09:57,158 - INFO - BGPaaS session seen in control-node 10.20.0.25 2026-03-23 04:09:57,170 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 168.128.252.148, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:09:57,171 - DEBUG - ping -s 56 -c 3 -W 1 168.128.252.148 2026-03-23 04:09:59,667 - DEBUG - PING 168.128.252.148 (168.128.252.148) 56(84) bytes of data. 64 bytes from 168.128.252.148: icmp_seq=1 ttl=64 time=3.31 ms 64 bytes from 168.128.252.148: icmp_seq=2 ttl=64 time=0.981 ms 64 bytes from 168.128.252.148: icmp_seq=3 ttl=64 time=1.17 ms --- 168.128.252.148 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 0.981/1.822/3.313/1.057 ms 2026-03-23 04:09:59,667 - INFO - Ping to IP 168.128.252.148 from VM ctest-TestBGPaasZone-88137159-85264197 passed 2026-03-23 04:09:59,960 - INFO - We will configure BGP on the VM 2026-03-23 04:10:00,199 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:10:00,199 - INFO - Configuring BGP on ctest-TestBGPaasZone-88137159-54779880 2026-03-23 04:10:00,200 - DEBUG - Running remote_cmd, Cmd : cat > /etc/bird/bird.conf << EOS protocol device { scan time 10; # Scan interfaces every 10 seconds } protocol kernel { persist; # Don't remove routes on bird shutdown scan time 20; # Scan kernel routing table every 20 seconds import all; # Default is import all #export all; # Default is export none } protocol direct { interface "eth*"; } router id 168.128.252.131; protocol bgp bgp_1{ local as 65000; neighbor 168.128.252.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 168.128.252.131; } protocol bfd { neighbor 168.128.252.129 local 168.128.252.131 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:10:00,200 - DEBUG - cat > /etc/bird/bird.conf << EOS protocol device { scan time 10; # Scan interfaces every 10 seconds } protocol kernel { persist; # Don't remove routes on bird shutdown scan time 20; # Scan kernel routing table every 20 seconds import all; # Default is import all #export all; # Default is export none } protocol direct { interface "eth*"; } router id 168.128.252.131; protocol bgp bgp_1{ local as 65000; neighbor 168.128.252.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 168.128.252.131; } protocol bfd { neighbor 168.128.252.129 local 168.128.252.131 multihop on; } EOS 2026-03-23 04:10:00,722 - DEBUG - None 2026-03-23 04:10:00,722 - DEBUG - Running remote_cmd, Cmd : cat > /etc/bird/bird.conf << EOS protocol device { scan time 10; # Scan interfaces every 10 seconds } protocol kernel { persist; # Don't remove routes on bird shutdown scan time 20; # Scan kernel routing table every 20 seconds import all; # Default is import all #export all; # Default is export none } protocol direct { interface "eth*"; } router id 168.128.252.131; protocol bgp bgp_1{ local as 65000; neighbor 168.128.252.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 168.128.252.131; } protocol bfd { neighbor 168.128.252.129 local 168.128.252.131 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:10:00,722 - DEBUG - cat > /etc/bird/bird.conf << EOS protocol device { scan time 10; # Scan interfaces every 10 seconds } protocol kernel { persist; # Don't remove routes on bird shutdown scan time 20; # Scan kernel routing table every 20 seconds import all; # Default is import all #export all; # Default is export none } protocol direct { interface "eth*"; } router id 168.128.252.131; protocol bgp bgp_1{ local as 65000; neighbor 168.128.252.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 168.128.252.131; } protocol bfd { neighbor 168.128.252.129 local 168.128.252.131 multihop on; } EOS 2026-03-23 04:10:01,250 - DEBUG - None 2026-03-23 04:10:01,251 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:10:01,251 - DEBUG - service bird restart 2026-03-23 04:10:03,143 - DEBUG - bird stop/waiting bird start/running, process 1499 2026-03-23 04:10:03,143 - INFO - Attaching VMI 7caa2eb9-d02d-4c64-b459-786399f0e58e to BGPaaS a550c983-075e-4aed-8b68-fefe333ad6d0 2026-03-23 04:10:03,166 - INFO - Attaching the VMI f4019151-529c-4545-a695-b20dec0b416f to the BGPaaS a550c983-075e-4aed-8b68-fefe333ad6d0 object 2026-03-23 04:10:03,167 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 168.128.252.148 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:10:03,167 - DEBUG - sudo ip addr add 168.128.252.148 dev eth0 2026-03-23 04:10:03,953 - DEBUG - RTNETLINK answers: File exists 2026-03-23 04:10:03,953 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:10:03,953 - DEBUG - service bird restart 2026-03-23 04:10:05,813 - DEBUG - bird stop/waiting bird start/running, process 1526 2026-03-23 04:10:05,813 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-23 04:10:05,813 - DEBUG - service bird restart 2026-03-23 04:10:07,826 - DEBUG - bird stop/waiting bird start/running, process 1490 2026-03-23 04:10:07,827 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:10:07,851 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.14'] 2026-03-23 04:10:13,874 - INFO - BGPaaS session seen in control-node 10.20.0.14 2026-03-23 04:10:13,885 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:10:13,885 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-03-23 04:10:13,906 - INFO - BGPaaS session seen in control-node 10.20.0.25 2026-03-23 04:10:13,917 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 168.128.252.148, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:10:13,917 - DEBUG - ping -s 56 -c 3 -W 1 168.128.252.148 2026-03-23 04:10:16,440 - DEBUG - PING 168.128.252.148 (168.128.252.148) 56(84) bytes of data. 64 bytes from 168.128.252.148: icmp_seq=1 ttl=64 time=1.94 ms 64 bytes from 168.128.252.148: icmp_seq=2 ttl=64 time=1.04 ms 64 bytes from 168.128.252.148: icmp_seq=3 ttl=64 time=1.21 ms --- 168.128.252.148 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.043/1.399/1.946/0.394 ms 2026-03-23 04:10:16,440 - INFO - Ping to IP 168.128.252.148 from VM ctest-TestBGPaasZone-88137159-85264197 passed 2026-03-23 04:10:16,440 - INFO - Detaching VMI 7caa2eb9-d02d-4c64-b459-786399f0e58e from BGPaaS a550c983-075e-4aed-8b68-fefe333ad6d0 2026-03-23 04:10:16,515 - INFO - Detaching VMI 6de618ac-7769-4e45-9cbe-23b4fdefcc81 from BGPaaS c7fafd24-6805-4d36-86b7-189fd87d3d2a 2026-03-23 04:10:16,607 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-88137159-39451124(c7fafd24-6805-4d36-86b7-189fd87d3d2a) 2026-03-23 04:10:16,659 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/c7fafd24-6805-4d36-86b7-189fd87d3d2a 2026-03-23 04:10:16,692 - DEBUG - Response Code: 404 2026-03-23 04:10:16,692 - DEBUG - BGPaaS: c7fafd24-6805-4d36-86b7-189fd87d3d2a deleted from api server 2026-03-23 04:10:16,692 - INFO - BGPaaS(c7fafd24-6805-4d36-86b7-189fd87d3d2a): verify_on_cleanup passed 2026-03-23 04:10:16,692 - INFO - Detaching VMI 7caa2eb9-d02d-4c64-b459-786399f0e58e from BGPaaS a550c983-075e-4aed-8b68-fefe333ad6d0 2026-03-23 04:10:16,731 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-88137159-29341318(a550c983-075e-4aed-8b68-fefe333ad6d0) 2026-03-23 04:10:16,811 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/a550c983-075e-4aed-8b68-fefe333ad6d0 2026-03-23 04:10:16,818 - DEBUG - Response Code: 404 2026-03-23 04:10:16,818 - DEBUG - BGPaaS: a550c983-075e-4aed-8b68-fefe333ad6d0 deleted from api server 2026-03-23 04:10:16,818 - INFO - BGPaaS(a550c983-075e-4aed-8b68-fefe333ad6d0): verify_on_cleanup passed 2026-03-23 04:10:16,818 - INFO - Deleting Control node zone ctest-test-zone-17103458(23279198-f7e7-4450-9163-ab771b6c4764) 2026-03-23 04:10:16,968 - INFO - Deleting Control node zone ctest-test-zone-89290351(189e1ad0-91bf-401b-a656-3e7684093085) 2026-03-23 04:10:17,139 - INFO - Deleting Control node zone ctest-test-zone-21585013(db471d5a-52d5-4ff5-bd75-11bc248b8e5e) 2026-03-23 04:10:17,225 - INFO - Deleting VM ctest-TestBGPaasZone-88137159-85264197 2026-03-23 04:10:17,317 - INFO - Deleting VM ctest-TestBGPaasZone-88137159-53663238 2026-03-23 04:10:17,412 - INFO - Deleting VM ctest-TestBGPaasZone-88137159-54779880 2026-03-23 04:10:17,501 - INFO - Deleting VN ctest-cnz_vn-71251029 2026-03-23 04:10:17,555 - DEBUG - VN 76ffa957-fa73-46fd-8a32-c21ca3f359ee still in use: Unable to complete operation on network 76ffa957-fa73-46fd-8a32-c21ca3f359ee. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-3e29be5a-4fbf-42c0-928a-9e4d971a6c3b'] 2026-03-23 04:10:17,556 - WARNING - Deleting VN ctest-cnz_vn-71251029 failed..Will retry 2026-03-23 04:10:19,832 - DEBUG - Response for deleting network () 2026-03-23 04:10:21,114 - DEBUG - Skipping xmpp flap check
2026-03-23 04:10:21,114 - INFO - END TEST : test_bgp_control_node_zone : PASSED[0:04:45]
2026-03-23 04:10:21,114 - INFO - -------------------------------------------------------------------------------- 2026-03-23 04:10:21,117 - INFO - ================================================================================ 2026-03-23 04:10:21,117 - INFO - STARTING TEST : test_bgp_control_node_zones_from_single_vnf 2026-03-23 04:10:22,397 - DEBUG - Skipping xmpp flap check 2026-03-23 04:10:22,397 - INFO - Initial checks done. Running the testcase now 2026-03-23 04:10:22,397 - INFO - 2026-03-23 04:10:22,398 - INFO - executing bgp_control_zone agent restart test 2026-03-23 04:10:22,714 - DEBUG - Response for create_network : {'network': {'id': '762230fb-982a-4d8b-8e79-eefc8f45a611', 'name': 'ctest-cnz_vn-09311380', 'tenant_id': '75e3162c0fc649c9bdf72fe98496205a', 'project_id': '75e3162c0fc649c9bdf72fe98496205a', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBGPaasZone-88137159', 'ctest-cnz_vn-09311380'], 'port_security_enabled': True, 'description': ''}} 2026-03-23 04:10:22,941 - DEBUG - Response for create_subnet : {'subnet': {'id': '6e608371-cb98-4bec-b5e9-a779e4ad1f49', 'name': '', 'tenant_id': '75e3162c0fc649c9bdf72fe98496205a', 'network_id': '762230fb-982a-4d8b-8e79-eefc8f45a611', 'ip_version': 4, 'cidr': '136.228.43.128/26', 'allocation_pools': [{'start': '136.228.43.130', 'end': '136.228.43.190'}], 'gateway_ip': '136.228.43.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '136.228.43.130', 'tags': [], 'project_id': '75e3162c0fc649c9bdf72fe98496205a'}} 2026-03-23 04:10:22,974 - INFO - Created VN ctest-cnz_vn-09311380 2026-03-23 04:10:22,988 - DEBUG - VN ctest-cnz_vn-09311380 UUID is 762230fb-982a-4d8b-8e79-eefc8f45a611 2026-03-23 04:10:24,380 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5514-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5514-1) 2026-03-23 04:10:26,096 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5514-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5514-2) 2026-03-23 04:10:27,752 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5514-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5514-1) 2026-03-23 04:10:27,752 - INFO - Waiting for VM ctest-TestBGPaasZone-88137159-75155130 to be up.. 2026-03-23 04:10:27,856 - DEBUG - VM is in ACTIVE state now 2026-03-23 04:10:27,856 - INFO - VM name : ctest-TestBGPaasZone-88137159-75155130 2026-03-23 04:10:27,973 - DEBUG - VM ctest-TestBGPaasZone-88137159-75155130 ID is 4039e047-3662-42b0-b6f3-802fa94ca59d 2026-03-23 04:10:27,973 - DEBUG - VM ctest-TestBGPaasZone-88137159-75155130 launched on Node an-jenkins-deploy-platform-ansible-os-5514-1 2026-03-23 04:10:28,097 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/4039e047-3662-42b0-b6f3-802fa94ca59d 2026-03-23 04:10:28,110 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/cd0219b6-a009-429c-a08e-020914f9557f 2026-03-23 04:10:31,330 - 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 1021ms') 2026-03-23 04:10:31,330 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:10:31,348 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:10:35,418 - 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 1009ms') 2026-03-23 04:10:35,419 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:10:35,434 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:10:39,525 - 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-03-23 04:10:39,525 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:10:39,576 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:10:43,649 - 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 1005ms') 2026-03-23 04:10:43,649 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:10:43,665 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:10:47,746 - 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 1007ms') 2026-03-23 04:10:47,746 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:10:47,762 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:10:51,841 - 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') 2026-03-23 04:10:51,841 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:10:51,859 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:10:55,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 1013ms') 2026-03-23 04:10:55,930 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:10:55,945 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:11:00,032 - 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-03-23 04:11:00,032 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:11:00,047 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:11:04,129 - 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 1013ms') 2026-03-23 04:11:04,130 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:11:04,145 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:11:08,223 - 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 1003ms') 2026-03-23 04:11:08,223 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:11:08,236 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:11:12,320 - 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 1021ms') 2026-03-23 04:11:12,320 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:11:12,336 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:11:16,416 - 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 1013ms') 2026-03-23 04:11:16,416 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:11:16,434 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:11:20,509 - 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 1009ms') 2026-03-23 04:11:20,510 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:11:20,524 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:11:24,610 - 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-03-23 04:11:24,610 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:11:24,626 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:11:28,705 - 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') 2026-03-23 04:11:28,706 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:11:28,723 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:11:32,795 - 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 1009ms') 2026-03-23 04:11:32,795 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:11:32,811 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:11:36,898 - 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-03-23 04:11:36,898 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:11:36,914 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:11:40,992 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2026-03-23 04:11:40,993 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:11:41,008 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:11:45,087 - 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') 2026-03-23 04:11:45,087 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:11:45,102 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:11:49,184 - 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 1021ms') 2026-03-23 04:11:49,184 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:11:49,200 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:11:53,277 - 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') 2026-03-23 04:11:53,278 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:11:53,295 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:11:57,378 - 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') 2026-03-23 04:11:57,378 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:11:57,393 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:12:01,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 1009ms') 2026-03-23 04:12:01,470 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 failed! 2026-03-23 04:12:01,496 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-88137159:ctest-cnz_vn-09311380 is 136.228.43.129 and allocation pool is NOT set 2026-03-23 04:12:03,564 - 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.42 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.474 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.474/1.946/3.419/1.472 ms') 2026-03-23 04:12:03,565 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-75155130 passed 2026-03-23 04:12:03,639 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:12:03,639 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-75155130, IP 136.228.43.131, Port 22 2026-03-23 04:12:03,706 - DEBUG - Error on ssh to ubuntu@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-03-23 04:12:04,313 - DEBUG - VM ctest-TestBGPaasZone-88137159-75155130 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:12:09,313 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:12:09,314 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-75155130, IP 136.228.43.131, Port 22 2026-03-23 04:12:09,875 - DEBUG - Error on ssh to ubuntu@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-03-23 04:12:10,005 - DEBUG - VM ctest-TestBGPaasZone-88137159-75155130 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:12:15,006 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:12:15,006 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-75155130, IP 136.228.43.131, Port 22 2026-03-23 04:12:15,704 - DEBUG - Error on ssh to ubuntu@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-03-23 04:12:15,830 - DEBUG - VM ctest-TestBGPaasZone-88137159-75155130 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:12:20,830 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:12:20,830 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-75155130, IP 136.228.43.131, Port 22 2026-03-23 04:12:21,394 - DEBUG - Error on ssh to ubuntu@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-03-23 04:12:21,506 - DEBUG - VM ctest-TestBGPaasZone-88137159-75155130 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:12:26,506 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:12:26,506 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-75155130, IP 136.228.43.131, Port 22 2026-03-23 04:12:27,078 - DEBUG - Error on ssh to ubuntu@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-03-23 04:12:27,199 - DEBUG - VM ctest-TestBGPaasZone-88137159-75155130 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:12:32,200 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:12:32,200 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-75155130, IP 136.228.43.131, Port 22 2026-03-23 04:12:33,802 - DEBUG - Error on ssh to ubuntu@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-03-23 04:12:33,917 - DEBUG - VM ctest-TestBGPaasZone-88137159-75155130 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:12:38,918 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:12:38,919 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-75155130, IP 136.228.43.131, Port 22 2026-03-23 04:12:39,781 - DEBUG - Error on ssh to ubuntu@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-03-23 04:12:39,881 - DEBUG - VM ctest-TestBGPaasZone-88137159-75155130 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:12:44,883 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:12:44,883 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-75155130, IP 136.228.43.131, Port 22 2026-03-23 04:12:45,908 - DEBUG - Error on ssh to ubuntu@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-03-23 04:12:46,052 - DEBUG - VM ctest-TestBGPaasZone-88137159-75155130 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:12:51,052 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:12:51,052 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-75155130, IP 136.228.43.131, Port 22 2026-03-23 04:12:51,983 - DEBUG - Error on ssh to ubuntu@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-03-23 04:12:52,088 - DEBUG - VM ctest-TestBGPaasZone-88137159-75155130 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:12:57,089 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:12:57,089 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-75155130, IP 136.228.43.131, Port 22 2026-03-23 04:12:58,005 - DEBUG - Error on ssh to ubuntu@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-03-23 04:12:58,191 - DEBUG - VM ctest-TestBGPaasZone-88137159-75155130 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:13:03,192 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:13:03,192 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-75155130, IP 136.228.43.131, Port 22 2026-03-23 04:13:03,263 - DEBUG - Error on ssh to ubuntu@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-03-23 04:13:03,386 - DEBUG - VM ctest-TestBGPaasZone-88137159-75155130 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:13:08,387 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:13:08,387 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-75155130, IP 136.228.43.131, Port 22 2026-03-23 04:13:08,455 - DEBUG - Error on ssh to ubuntu@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-03-23 04:13:08,575 - DEBUG - VM ctest-TestBGPaasZone-88137159-75155130 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:13:13,575 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:13:13,575 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-75155130, IP 136.228.43.131, Port 22 2026-03-23 04:13:13,750 - DEBUG - VM ctest-TestBGPaasZone-88137159-75155130 is ready for SSH connections 2026-03-23 04:13:13,750 - INFO - Waiting for VM ctest-TestBGPaasZone-88137159-88735321 to be up.. 2026-03-23 04:13:13,852 - DEBUG - VM is in ACTIVE state now 2026-03-23 04:13:13,852 - INFO - VM name : ctest-TestBGPaasZone-88137159-88735321 2026-03-23 04:13:13,957 - DEBUG - VM ctest-TestBGPaasZone-88137159-88735321 ID is 3d2c09a0-f767-48ac-a556-4b73cfd51fae 2026-03-23 04:13:13,957 - DEBUG - VM ctest-TestBGPaasZone-88137159-88735321 launched on Node an-jenkins-deploy-platform-ansible-os-5514-2 2026-03-23 04:13:14,066 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/3d2c09a0-f767-48ac-a556-4b73cfd51fae 2026-03-23 04:13:14,110 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/6cc1cd5f-9567-48d1-b6f0-de3a6382a3c7 2026-03-23 04:13:15,285 - 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.16 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.729 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.729/2.946/5.163/2.217 ms') 2026-03-23 04:13:15,285 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-88137159-88735321 passed 2026-03-23 04:13:15,357 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:13:15,358 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-88735321, IP 136.228.43.132, Port 22 2026-03-23 04:13:15,525 - DEBUG - VM ctest-TestBGPaasZone-88137159-88735321 is ready for SSH connections 2026-03-23 04:13:15,526 - INFO - Waiting for VM ctest-TestBGPaasZone-88137159-33459196 to be up.. 2026-03-23 04:13:15,629 - DEBUG - VM is in ACTIVE state now 2026-03-23 04:13:15,630 - INFO - VM name : ctest-TestBGPaasZone-88137159-33459196 2026-03-23 04:13:15,727 - DEBUG - VM ctest-TestBGPaasZone-88137159-33459196 ID is c515d79e-909b-4b4c-8964-e35d62b5c867 2026-03-23 04:13:15,727 - DEBUG - VM ctest-TestBGPaasZone-88137159-33459196 launched on Node an-jenkins-deploy-platform-ansible-os-5514-1 2026-03-23 04:13:15,836 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/c515d79e-909b-4b4c-8964-e35d62b5c867 2026-03-23 04:13:15,848 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/2f35a6f1-d061-4fe1-96bb-13c6f35d3fe2 2026-03-23 04:13:17,023 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=2.69 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.553 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.553/1.623/2.693/1.070 ms') 2026-03-23 04:13:17,023 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-88137159-33459196 passed 2026-03-23 04:13:17,092 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:13:17,093 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-33459196, IP 136.228.43.133, Port 22 2026-03-23 04:13:17,166 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-03-23 04:13:17,259 - DEBUG - VM ctest-TestBGPaasZone-88137159-33459196 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:13:22,260 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:13:22,260 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-33459196, IP 136.228.43.133, Port 22 2026-03-23 04:13:22,330 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-03-23 04:13:22,442 - DEBUG - VM ctest-TestBGPaasZone-88137159-33459196 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:13:27,443 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:13:27,443 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-33459196, IP 136.228.43.133, Port 22 2026-03-23 04:13:27,511 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-03-23 04:13:27,625 - DEBUG - VM ctest-TestBGPaasZone-88137159-33459196 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:13:32,625 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:13:32,626 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-33459196, IP 136.228.43.133, Port 22 2026-03-23 04:13:32,694 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-03-23 04:13:32,789 - DEBUG - VM ctest-TestBGPaasZone-88137159-33459196 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:13:37,790 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:13:37,790 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-33459196, IP 136.228.43.133, Port 22 2026-03-23 04:13:37,859 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-03-23 04:13:37,960 - DEBUG - VM ctest-TestBGPaasZone-88137159-33459196 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:13:42,961 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:13:42,961 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-33459196, IP 136.228.43.133, Port 22 2026-03-23 04:13:43,030 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-03-23 04:13:43,129 - DEBUG - VM ctest-TestBGPaasZone-88137159-33459196 is NOT ready for SSH connections, VM status: ACTIVE 2026-03-23 04:13:48,130 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:13:48,130 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-88137159-33459196, IP 136.228.43.133, Port 22 2026-03-23 04:13:48,306 - DEBUG - VM ctest-TestBGPaasZone-88137159-33459196 is ready for SSH connections 2026-03-23 04:13:48,443 - INFO - Created control node zone ctest-test-zone-20594062(c05562f6-f629-40e0-bd15-22e8285db6a5) 2026-03-23 04:13:48,803 - INFO - Created control node zone ctest-test-zone-13426085(653be1a6-65cc-4df9-a92c-b473034a907c) 2026-03-23 04:13:49,155 - INFO - Created control node zone ctest-test-zone-36628669(485c932b-dd96-4bc6-8ce0-ac333c1b836d) 2026-03-23 04:13:49,427 - INFO - Creating BGPaaS ctest-TestBGPaasZone-88137159-67040770 2026-03-23 04:13:49,511 - INFO - BGPaaS: ctest-TestBGPaasZone-88137159-67040770(48b04928-feae-445b-ae31-18c3de28ed4e) 2026-03-23 04:13:49,511 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/48b04928-feae-445b-ae31-18c3de28ed4e 2026-03-23 04:13:49,524 - INFO - verify_in_api_server passed for BGPaaS obj 48b04928-feae-445b-ae31-18c3de28ed4e 2026-03-23 04:13:49,524 - INFO - BGPaaS(48b04928-feae-445b-ae31-18c3de28ed4e): verify_on_setup passed 2026-03-23 04:13:49,525 - INFO - We will configure BGP on the VM 2026-03-23 04:13:49,983 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:13:49,983 - INFO - Configuring BGP on ctest-TestBGPaasZone-88137159-75155130 2026-03-23 04:13:49,983 - DEBUG - Running remote_cmd, Cmd : cat > /etc/bird/bird.conf << EOS protocol device { scan time 10; # Scan interfaces every 10 seconds } protocol kernel { persist; # Don't remove routes on bird shutdown scan time 20; # Scan kernel routing table every 20 seconds import all; # Default is import all #export all; # Default is export none } protocol direct { interface "eth*"; } router id 136.228.43.131; protocol bgp bgp_1{ local as 65000; neighbor 136.228.43.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 136.228.43.131; } protocol bfd { neighbor 136.228.43.129 local 136.228.43.131 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-23 04:13:49,983 - DEBUG - cat > /etc/bird/bird.conf << EOS protocol device { scan time 10; # Scan interfaces every 10 seconds } protocol kernel { persist; # Don't remove routes on bird shutdown scan time 20; # Scan kernel routing table every 20 seconds import all; # Default is import all #export all; # Default is export none } protocol direct { interface "eth*"; } router id 136.228.43.131; protocol bgp bgp_1{ local as 65000; neighbor 136.228.43.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 136.228.43.131; } protocol bfd { neighbor 136.228.43.129 local 136.228.43.131 multihop on; } EOS 2026-03-23 04:14:01,279 - DEBUG - None 2026-03-23 04:14:01,279 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS protocol bgp bgp_2{ local as 65000; neighbor 136.228.43.130 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 136.228.43.131; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-23 04:14:01,279 - DEBUG - cat >> /etc/bird/bird.conf << EOS protocol bgp bgp_2{ local as 65000; neighbor 136.228.43.130 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 136.228.43.131; } EOS 2026-03-23 04:14:02,008 - DEBUG - None 2026-03-23 04:14:02,008 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-23 04:14:02,008 - DEBUG - service bird restart 2026-03-23 04:14:04,156 - DEBUG - bird stop/waiting bird start/running, process 1441 2026-03-23 04:14:04,156 - INFO - Attaching VMI cd0219b6-a009-429c-a08e-020914f9557f to BGPaaS 48b04928-feae-445b-ae31-18c3de28ed4e 2026-03-23 04:14:04,247 - INFO - Attaching the VMI 4039e047-3662-42b0-b6f3-802fa94ca59d to the BGPaaS 48b04928-feae-445b-ae31-18c3de28ed4e object 2026-03-23 04:14:04,248 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 136.228.43.148 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-23 04:14:04,248 - DEBUG - sudo ip addr add 136.228.43.148 dev eth0 2026-03-23 04:14:05,132 - DEBUG - None 2026-03-23 04:14:05,195 - INFO - Creating BGPaaS ctest-TestBGPaasZone-88137159-17918765 2026-03-23 04:14:05,271 - INFO - BGPaaS: ctest-TestBGPaasZone-88137159-17918765(3cf7ff5c-2289-42f9-9b70-e2f64f88e123) 2026-03-23 04:14:05,272 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/3cf7ff5c-2289-42f9-9b70-e2f64f88e123 2026-03-23 04:14:05,652 - INFO - verify_in_api_server passed for BGPaaS obj 3cf7ff5c-2289-42f9-9b70-e2f64f88e123 2026-03-23 04:14:05,652 - INFO - BGPaaS(3cf7ff5c-2289-42f9-9b70-e2f64f88e123): verify_on_setup passed 2026-03-23 04:14:05,652 - INFO - We will configure BGP on the VM 2026-03-23 04:14:06,091 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:14:06,091 - INFO - Configuring BGP on ctest-TestBGPaasZone-88137159-88735321 2026-03-23 04:14:06,091 - DEBUG - Running remote_cmd, Cmd : cat > /etc/bird/bird.conf << EOS protocol device { scan time 10; # Scan interfaces every 10 seconds } protocol kernel { persist; # Don't remove routes on bird shutdown scan time 20; # Scan kernel routing table every 20 seconds import all; # Default is import all #export all; # Default is export none } protocol direct { interface "eth*"; } router id 136.228.43.132; protocol bgp bgp_1{ local as 65000; neighbor 136.228.43.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 136.228.43.132; } protocol bfd { neighbor 136.228.43.129 local 136.228.43.132 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:14:06,091 - DEBUG - cat > /etc/bird/bird.conf << EOS protocol device { scan time 10; # Scan interfaces every 10 seconds } protocol kernel { persist; # Don't remove routes on bird shutdown scan time 20; # Scan kernel routing table every 20 seconds import all; # Default is import all #export all; # Default is export none } protocol direct { interface "eth*"; } router id 136.228.43.132; protocol bgp bgp_1{ local as 65000; neighbor 136.228.43.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 136.228.43.132; } protocol bfd { neighbor 136.228.43.129 local 136.228.43.132 multihop on; } EOS 2026-03-23 04:14:12,082 - DEBUG - None 2026-03-23 04:14:12,082 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS protocol bgp bgp_2{ local as 65000; neighbor 136.228.43.130 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 136.228.43.132; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:14:12,082 - DEBUG - cat >> /etc/bird/bird.conf << EOS protocol bgp bgp_2{ local as 65000; neighbor 136.228.43.130 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 136.228.43.132; } EOS 2026-03-23 04:14:12,613 - DEBUG - None 2026-03-23 04:14:12,614 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:14:12,614 - DEBUG - service bird restart 2026-03-23 04:14:14,461 - DEBUG - bird stop/waiting bird start/running, process 1440 2026-03-23 04:14:14,461 - INFO - Attaching VMI 6cc1cd5f-9567-48d1-b6f0-de3a6382a3c7 to BGPaaS 3cf7ff5c-2289-42f9-9b70-e2f64f88e123 2026-03-23 04:14:14,535 - INFO - Attaching the VMI 3d2c09a0-f767-48ac-a556-4b73cfd51fae to the BGPaaS 3cf7ff5c-2289-42f9-9b70-e2f64f88e123 object 2026-03-23 04:14:14,535 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 136.228.43.148 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123 2026-03-23 04:14:14,535 - DEBUG - sudo ip addr add 136.228.43.148 dev eth0 2026-03-23 04:14:15,300 - DEBUG - None 2026-03-23 04:14:15,300 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:14:15,315 - INFO - BGPaaS session seen in control-node 10.20.0.14 2026-03-23 04:14:15,316 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:14:15,316 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-03-23 04:14:15,333 - INFO - BGPaaS session seen in control-node 10.20.0.25 2026-03-23 04:14:15,340 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:14:15,354 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.14'] 2026-03-23 04:14:21,371 - INFO - BGPaaS session seen in control-node 10.20.0.14 2026-03-23 04:14:21,372 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:14:21,372 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-03-23 04:14:21,390 - INFO - BGPaaS session seen in control-node 10.20.0.25 2026-03-23 04:14:21,396 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 136.228.43.148, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-23 04:14:21,397 - DEBUG - ping -s 56 -c 3 -W 1 136.228.43.148 2026-03-23 04:14:33,022 - DEBUG - PING 136.228.43.148 (136.228.43.148) 56(84) bytes of data. 64 bytes from 136.228.43.148: icmp_seq=1 ttl=64 time=10.3 ms 64 bytes from 136.228.43.148: icmp_seq=2 ttl=64 time=1.73 ms 64 bytes from 136.228.43.148: icmp_seq=3 ttl=64 time=1.67 ms --- 136.228.43.148 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2005ms rtt min/avg/max/mdev = 1.675/4.592/10.370/4.086 ms 2026-03-23 04:14:33,023 - INFO - Ping to IP 136.228.43.148 from VM ctest-TestBGPaasZone-88137159-33459196 passed 2026-03-23 04:14:33,023 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:14:33,038 - INFO - BGPaaS session seen in control-node 10.20.0.14 2026-03-23 04:14:33,038 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:14:33,039 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-03-23 04:14:33,055 - INFO - BGPaaS session seen in control-node 10.20.0.25 2026-03-23 04:14:33,062 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:14:33,076 - INFO - BGPaaS session seen in control-node 10.20.0.14 2026-03-23 04:14:33,076 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-03-23 04:14:33,077 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-03-23 04:14:33,091 - INFO - BGPaaS session seen in control-node 10.20.0.25 2026-03-23 04:14:33,099 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 136.228.43.148, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123 2026-03-23 04:14:33,099 - DEBUG - ping -s 56 -c 3 -W 1 136.228.43.148 2026-03-23 04:14:35,737 - DEBUG - PING 136.228.43.148 (136.228.43.148) 56(84) bytes of data. 64 bytes from 136.228.43.148: icmp_seq=1 ttl=64 time=6.04 ms 64 bytes from 136.228.43.148: icmp_seq=2 ttl=64 time=1.11 ms 64 bytes from 136.228.43.148: icmp_seq=3 ttl=64 time=1.28 ms --- 136.228.43.148 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.118/2.814/6.046/2.286 ms 2026-03-23 04:14:35,737 - INFO - Ping to IP 136.228.43.148 from VM ctest-TestBGPaasZone-88137159-33459196 passed 2026-03-23 04:14:35,737 - INFO - Detaching VMI 6cc1cd5f-9567-48d1-b6f0-de3a6382a3c7 from BGPaaS 3cf7ff5c-2289-42f9-9b70-e2f64f88e123 2026-03-23 04:14:35,815 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-88137159-17918765(3cf7ff5c-2289-42f9-9b70-e2f64f88e123) 2026-03-23 04:14:35,878 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/3cf7ff5c-2289-42f9-9b70-e2f64f88e123 2026-03-23 04:14:35,888 - DEBUG - Response Code: 404 2026-03-23 04:14:35,888 - DEBUG - BGPaaS: 3cf7ff5c-2289-42f9-9b70-e2f64f88e123 deleted from api server 2026-03-23 04:14:35,888 - INFO - BGPaaS(3cf7ff5c-2289-42f9-9b70-e2f64f88e123): verify_on_cleanup passed 2026-03-23 04:14:35,888 - INFO - Detaching VMI cd0219b6-a009-429c-a08e-020914f9557f from BGPaaS 48b04928-feae-445b-ae31-18c3de28ed4e 2026-03-23 04:14:35,987 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-88137159-67040770(48b04928-feae-445b-ae31-18c3de28ed4e) 2026-03-23 04:14:36,049 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/48b04928-feae-445b-ae31-18c3de28ed4e 2026-03-23 04:14:36,064 - DEBUG - Response Code: 404 2026-03-23 04:14:36,064 - DEBUG - BGPaaS: 48b04928-feae-445b-ae31-18c3de28ed4e deleted from api server 2026-03-23 04:14:36,064 - INFO - BGPaaS(48b04928-feae-445b-ae31-18c3de28ed4e): verify_on_cleanup passed 2026-03-23 04:14:36,064 - INFO - Deleting Control node zone ctest-test-zone-36628669(485c932b-dd96-4bc6-8ce0-ac333c1b836d) 2026-03-23 04:14:36,250 - INFO - Deleting Control node zone ctest-test-zone-13426085(653be1a6-65cc-4df9-a92c-b473034a907c) 2026-03-23 04:14:36,402 - INFO - Deleting Control node zone ctest-test-zone-20594062(c05562f6-f629-40e0-bd15-22e8285db6a5) 2026-03-23 04:14:36,569 - INFO - Deleting VM ctest-TestBGPaasZone-88137159-33459196 2026-03-23 04:14:36,661 - INFO - Deleting VM ctest-TestBGPaasZone-88137159-88735321 2026-03-23 04:14:36,745 - INFO - Deleting VM ctest-TestBGPaasZone-88137159-75155130 2026-03-23 04:14:36,850 - INFO - Deleting VN ctest-cnz_vn-09311380 2026-03-23 04:14:36,903 - DEBUG - VN 762230fb-982a-4d8b-8e79-eefc8f45a611 still in use: Unable to complete operation on network 762230fb-982a-4d8b-8e79-eefc8f45a611. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-ae4192a3-0761-49d1-bd11-1d312615c608'] 2026-03-23 04:14:36,903 - WARNING - Deleting VN ctest-cnz_vn-09311380 failed..Will retry 2026-03-23 04:14:39,153 - DEBUG - Response for deleting network () 2026-03-23 04:14:40,453 - DEBUG - Skipping xmpp flap check
2026-03-23 04:14:40,453 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:04:19]
2026-03-23 04:14:40,453 - INFO - -------------------------------------------------------------------------------- 2026-03-23 04:14:41,270 - INFO - Deleted project: ctest-TestBGPaasZone-88137159, ID : 75e3162c-0fc6-49c9-bdf7-2fe98496205a