2026-06-18 03:26:11,844 - INFO - Domain Default found not creating 2026-06-18 03:26:12,004 - INFO - Project ctest-TestBGPaasZone-85196154 not found, creating it 2026-06-18 03:26:12,480 - INFO - Created Project:ctest-TestBGPaasZone-85196154, ID : 614173f6-bdca-427e-a1b5-dd09f4837695 2026-06-18 03:26:14,162 - DEBUG - Services list from nova: [, , , ] 2026-06-18 03:26:14,162 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-5973-2', 'an-jenkins-deploy-platform-ansible-os-5973-1']} 2026-06-18 03:26:14,164 - INFO - ================================================================================ 2026-06-18 03:26:14,164 - INFO - STARTING TEST : test_bgp_control_node_zone 2026-06-18 03:26:14,788 - DEBUG - Skipping xmpp flap check 2026-06-18 03:26:14,788 - INFO - Initial checks done. Running the testcase now 2026-06-18 03:26:14,788 - INFO - 2026-06-18 03:26:14,788 - INFO - executing bgp_control_zone test 2026-06-18 03:26:15,462 - DEBUG - Response for create_network : {'network': {'id': 'ff93b436-6d55-4d88-840d-7a87e8e525ec', 'name': 'ctest-cnz_vn-18587255', 'tenant_id': '614173f6bdca427ea1b5dd09f4837695', 'project_id': '614173f6bdca427ea1b5dd09f4837695', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBGPaasZone-85196154', 'ctest-cnz_vn-18587255'], 'port_security_enabled': True, 'description': ''}} 2026-06-18 03:26:15,738 - DEBUG - Response for create_subnet : {'subnet': {'id': '78dbd577-2eb5-4577-a82d-60404f98fc50', 'name': '', 'tenant_id': '614173f6bdca427ea1b5dd09f4837695', 'network_id': 'ff93b436-6d55-4d88-840d-7a87e8e525ec', 'ip_version': 4, 'cidr': '102.175.185.64/26', 'allocation_pools': [{'start': '102.175.185.66', 'end': '102.175.185.126'}], 'gateway_ip': '102.175.185.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '102.175.185.66', 'tags': [], 'project_id': '614173f6bdca427ea1b5dd09f4837695'}} 2026-06-18 03:26:15,756 - INFO - Created VN ctest-cnz_vn-18587255 2026-06-18 03:26:15,767 - DEBUG - VN ctest-cnz_vn-18587255 UUID is ff93b436-6d55-4d88-840d-7a87e8e525ec 2026-06-18 03:26:17,183 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5973-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5973-2) 2026-06-18 03:26:18,923 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5973-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5973-1) 2026-06-18 03:26:20,232 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5973-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5973-2) 2026-06-18 03:26:20,232 - INFO - Waiting for VM ctest-TestBGPaasZone-85196154-66946797 to be up.. 2026-06-18 03:26:20,287 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-06-18 03:26:25,417 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-06-18 03:26:30,546 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-06-18 03:26:35,680 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-06-18 03:26:40,776 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-06-18 03:26:45,848 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-06-18 03:26:50,931 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-06-18 03:26:56,027 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-06-18 03:27:01,109 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-06-18 03:27:06,191 - DEBUG - VM is in ACTIVE state now 2026-06-18 03:27:06,191 - INFO - VM name : ctest-TestBGPaasZone-85196154-66946797 2026-06-18 03:27:06,267 - DEBUG - VM ctest-TestBGPaasZone-85196154-66946797 ID is b5b76272-e25e-4254-9280-4987c648081b 2026-06-18 03:27:06,291 - DEBUG - VM ctest-TestBGPaasZone-85196154-66946797 launched on Node an-jenkins-deploy-platform-ansible-os-5973-2 2026-06-18 03:27:06,370 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/b5b76272-e25e-4254-9280-4987c648081b 2026-06-18 03:27:06,708 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/b5b76272-e25e-4254-9280-4987c648081b 2026-06-18 03:27:06,737 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/26431e56-ed99-4c6a-ad3f-cdbdd4edb986 2026-06-18 03:27:09,881 - 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 1029ms') 2026-06-18 03:27:09,881 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-66946797 failed! 2026-06-18 03:27:09,893 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-18587255 is 102.175.185.65 and allocation pool is NOT set 2026-06-18 03:27:13,945 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms') 2026-06-18 03:27:13,945 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-66946797 failed! 2026-06-18 03:27:13,956 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-18587255 is 102.175.185.65 and allocation pool is NOT set 2026-06-18 03:27:18,004 - 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 1025ms') 2026-06-18 03:27:18,004 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-66946797 failed! 2026-06-18 03:27:18,016 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-18587255 is 102.175.185.65 and allocation pool is NOT set 2026-06-18 03:27:22,036 - 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 1000ms') 2026-06-18 03:27:22,036 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-66946797 failed! 2026-06-18 03:27:22,050 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-18587255 is 102.175.185.65 and allocation pool is NOT set 2026-06-18 03:27:26,102 - 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 1030ms') 2026-06-18 03:27:26,102 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-66946797 failed! 2026-06-18 03:27:26,114 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-18587255 is 102.175.185.65 and allocation pool is NOT set 2026-06-18 03:27:30,165 - 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 1030ms') 2026-06-18 03:27:30,165 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-66946797 failed! 2026-06-18 03:27:30,180 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-18587255 is 102.175.185.65 and allocation pool is NOT set 2026-06-18 03:27:34,224 - 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 1028ms') 2026-06-18 03:27:34,224 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-66946797 failed! 2026-06-18 03:27:34,238 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-18587255 is 102.175.185.65 and allocation pool is NOT set 2026-06-18 03:27:38,264 - 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-06-18 03:27:38,264 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-66946797 failed! 2026-06-18 03:27:38,278 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-18587255 is 102.175.185.65 and allocation pool is NOT set 2026-06-18 03:27:42,324 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1026ms') 2026-06-18 03:27:42,324 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-66946797 failed! 2026-06-18 03:27:42,337 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-18587255 is 102.175.185.65 and allocation pool is NOT set 2026-06-18 03:27:46,353 - 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 1001ms') 2026-06-18 03:27:46,353 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-66946797 failed! 2026-06-18 03:27:46,366 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-18587255 is 102.175.185.65 and allocation pool is NOT set 2026-06-18 03:27:50,387 - 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 1004ms') 2026-06-18 03:27:50,387 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-66946797 failed! 2026-06-18 03:27:50,399 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-18587255 is 102.175.185.65 and allocation pool is NOT set 2026-06-18 03:27:54,453 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1032ms') 2026-06-18 03:27:54,453 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-66946797 failed! 2026-06-18 03:27:54,467 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-18587255 is 102.175.185.65 and allocation pool is NOT set 2026-06-18 03:27:58,514 - 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=2 ttl=63 time=8.43 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1026ms\r\nrtt min/avg/max/mdev = 8.428/8.428/8.428/0.000 ms') 2026-06-18 03:27:58,514 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-66946797 passed 2026-06-18 03:27:58,582 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:27:58,583 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-66946797, IP 102.175.185.67, Port 22 2026-06-18 03:27:58,607 - 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-06-18 03:27:58,688 - DEBUG - VM ctest-TestBGPaasZone-85196154-66946797 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:28:03,688 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:28:03,688 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-66946797, IP 102.175.185.67, Port 22 2026-06-18 03:28:03,712 - 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-06-18 03:28:03,799 - DEBUG - VM ctest-TestBGPaasZone-85196154-66946797 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:28:08,799 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:28:08,799 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-66946797, IP 102.175.185.67, Port 22 2026-06-18 03:28:08,814 - 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-06-18 03:28:08,887 - DEBUG - VM ctest-TestBGPaasZone-85196154-66946797 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:28:13,888 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:28:13,888 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-66946797, IP 102.175.185.67, Port 22 2026-06-18 03:28:13,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-06-18 03:28:14,002 - DEBUG - VM ctest-TestBGPaasZone-85196154-66946797 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:28:19,003 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:28:19,004 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-66946797, IP 102.175.185.67, Port 22 2026-06-18 03:28:19,029 - 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-06-18 03:28:19,115 - DEBUG - VM ctest-TestBGPaasZone-85196154-66946797 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:28:24,116 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:28:24,116 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-66946797, IP 102.175.185.67, Port 22 2026-06-18 03:28:24,141 - 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-06-18 03:28:24,234 - DEBUG - VM ctest-TestBGPaasZone-85196154-66946797 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:28:29,235 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:28:29,235 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-66946797, IP 102.175.185.67, Port 22 2026-06-18 03:28:29,259 - 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-06-18 03:28:29,343 - DEBUG - VM ctest-TestBGPaasZone-85196154-66946797 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:28:34,344 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:28:34,344 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-66946797, IP 102.175.185.67, Port 22 2026-06-18 03:28:34,430 - DEBUG - VM ctest-TestBGPaasZone-85196154-66946797 is ready for SSH connections 2026-06-18 03:28:34,430 - INFO - Waiting for VM ctest-TestBGPaasZone-85196154-72149788 to be up.. 2026-06-18 03:28:34,512 - DEBUG - VM is in ACTIVE state now 2026-06-18 03:28:34,512 - INFO - VM name : ctest-TestBGPaasZone-85196154-72149788 2026-06-18 03:28:34,581 - DEBUG - VM ctest-TestBGPaasZone-85196154-72149788 ID is f7495db3-c5cb-4ed4-80c1-02a8ca280d6b 2026-06-18 03:28:34,581 - DEBUG - VM ctest-TestBGPaasZone-85196154-72149788 launched on Node an-jenkins-deploy-platform-ansible-os-5973-1 2026-06-18 03:28:34,649 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/f7495db3-c5cb-4ed4-80c1-02a8ca280d6b 2026-06-18 03:28:34,658 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/aabb1ee9-7b07-4e53-95ec-abd72ca2f5de 2026-06-18 03:28:35,773 - 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.72 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.760 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.760/2.241/3.723/1.481 ms') 2026-06-18 03:28:35,773 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-72149788 passed 2026-06-18 03:28:35,835 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:28:35,835 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-72149788, IP 102.175.185.68, Port 22 2026-06-18 03:28:35,905 - DEBUG - VM ctest-TestBGPaasZone-85196154-72149788 is ready for SSH connections 2026-06-18 03:28:35,905 - INFO - Waiting for VM ctest-TestBGPaasZone-85196154-31025968 to be up.. 2026-06-18 03:28:35,975 - DEBUG - VM is in ACTIVE state now 2026-06-18 03:28:35,975 - INFO - VM name : ctest-TestBGPaasZone-85196154-31025968 2026-06-18 03:28:36,047 - DEBUG - VM ctest-TestBGPaasZone-85196154-31025968 ID is 602689cc-5771-4f43-948d-392027c3e433 2026-06-18 03:28:36,048 - DEBUG - VM ctest-TestBGPaasZone-85196154-31025968 launched on Node an-jenkins-deploy-platform-ansible-os-5973-2 2026-06-18 03:28:36,125 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/602689cc-5771-4f43-948d-392027c3e433 2026-06-18 03:28:36,133 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/a551023f-b52f-4ed4-9649-6ddf2ade8431 2026-06-18 03:28:37,248 - 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.53 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.11 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 = 2.106/2.319/2.532/0.213 ms') 2026-06-18 03:28:37,249 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-85196154-31025968 passed 2026-06-18 03:28:37,328 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:28:37,328 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-31025968, IP 102.175.185.69, Port 22 2026-06-18 03:28:37,342 - 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-06-18 03:28:37,419 - DEBUG - VM ctest-TestBGPaasZone-85196154-31025968 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:28:42,420 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:28:42,420 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-31025968, IP 102.175.185.69, Port 22 2026-06-18 03:28:42,495 - DEBUG - VM ctest-TestBGPaasZone-85196154-31025968 is ready for SSH connections 2026-06-18 03:28:42,620 - INFO - Created control node zone ctest-test-zone-37028239(afecde39-7194-4e52-8655-45419e2c2a25) 2026-06-18 03:28:42,956 - INFO - Created control node zone ctest-test-zone-09233013(002e0717-09ee-41f0-b852-231906ede08c) 2026-06-18 03:28:43,308 - INFO - Created control node zone ctest-test-zone-52534015(e4208f01-d88d-4c5f-81a4-9e3d68b76f5c) 2026-06-18 03:28:43,584 - INFO - Creating BGPaaS ctest-TestBGPaasZone-85196154-66447079 2026-06-18 03:28:43,652 - INFO - BGPaaS: ctest-TestBGPaasZone-85196154-66447079(b3b0db7d-f28c-4610-be70-ab666b67f1e8) 2026-06-18 03:28:43,652 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/b3b0db7d-f28c-4610-be70-ab666b67f1e8 2026-06-18 03:28:43,992 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/b3b0db7d-f28c-4610-be70-ab666b67f1e8 2026-06-18 03:28:44,040 - INFO - verify_in_api_server passed for BGPaaS obj b3b0db7d-f28c-4610-be70-ab666b67f1e8 2026-06-18 03:28:44,040 - INFO - BGPaaS(b3b0db7d-f28c-4610-be70-ab666b67f1e8): verify_on_setup passed 2026-06-18 03:28:44,040 - INFO - We will configure BGP on the VM 2026-06-18 03:28:44,288 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:28:44,288 - INFO - Configuring BGP on ctest-TestBGPaasZone-85196154-66946797 2026-06-18 03:28:44,288 - 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 102.175.185.67; protocol bgp bgp_1{ local as 65000; neighbor 102.175.185.65 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 102.175.185.67; } protocol bfd { neighbor 102.175.185.65 local 102.175.185.67 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:28:44,288 - 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 102.175.185.67; protocol bgp bgp_1{ local as 65000; neighbor 102.175.185.65 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 102.175.185.67; } protocol bfd { neighbor 102.175.185.65 local 102.175.185.67 multihop on; } EOS 2026-06-18 03:28:47,262 - DEBUG - None 2026-06-18 03:28:47,262 - 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 102.175.185.67; protocol bgp bgp_1{ local as 65000; neighbor 102.175.185.65 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 102.175.185.67; } protocol bfd { neighbor 102.175.185.65 local 102.175.185.67 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:28:47,262 - 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 102.175.185.67; protocol bgp bgp_1{ local as 65000; neighbor 102.175.185.65 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 102.175.185.67; } protocol bfd { neighbor 102.175.185.65 local 102.175.185.67 multihop on; } EOS 2026-06-18 03:28:47,565 - DEBUG - None 2026-06-18 03:28:47,565 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:28:47,565 - DEBUG - service bird restart 2026-06-18 03:28:48,374 - DEBUG - bird stop/waiting bird start/running, process 1433 2026-06-18 03:28:48,375 - INFO - Attaching VMI 26431e56-ed99-4c6a-ad3f-cdbdd4edb986 to BGPaaS b3b0db7d-f28c-4610-be70-ab666b67f1e8 2026-06-18 03:28:48,444 - INFO - Attaching the VMI b5b76272-e25e-4254-9280-4987c648081b to the BGPaaS b3b0db7d-f28c-4610-be70-ab666b67f1e8 object 2026-06-18 03:28:48,444 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 102.175.185.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:28:48,444 - DEBUG - sudo ip addr add 102.175.185.84 dev eth0 2026-06-18 03:28:48,759 - DEBUG - None 2026-06-18 03:28:48,816 - INFO - Creating BGPaaS ctest-TestBGPaasZone-85196154-93499874 2026-06-18 03:28:48,892 - INFO - BGPaaS: ctest-TestBGPaasZone-85196154-93499874(36e9c585-4779-4c06-86ab-54b3b084c566) 2026-06-18 03:28:48,892 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/36e9c585-4779-4c06-86ab-54b3b084c566 2026-06-18 03:28:48,901 - INFO - verify_in_api_server passed for BGPaaS obj 36e9c585-4779-4c06-86ab-54b3b084c566 2026-06-18 03:28:48,901 - INFO - BGPaaS(36e9c585-4779-4c06-86ab-54b3b084c566): verify_on_setup passed 2026-06-18 03:28:48,902 - INFO - We will configure BGP on the VM 2026-06-18 03:28:49,128 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:28:49,128 - INFO - Configuring BGP on ctest-TestBGPaasZone-85196154-72149788 2026-06-18 03:28:49,128 - 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 102.175.185.68; protocol bgp bgp_1{ local as 65000; neighbor 102.175.185.66 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 102.175.185.68; } protocol bfd { neighbor 102.175.185.66 local 102.175.185.68 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2026-06-18 03:28:49,128 - 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 102.175.185.68; protocol bgp bgp_1{ local as 65000; neighbor 102.175.185.66 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 102.175.185.68; } protocol bfd { neighbor 102.175.185.66 local 102.175.185.68 multihop on; } EOS 2026-06-18 03:28:51,147 - DEBUG - None 2026-06-18 03:28:51,148 - 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 102.175.185.68; protocol bgp bgp_1{ local as 65000; neighbor 102.175.185.66 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 102.175.185.68; } protocol bfd { neighbor 102.175.185.66 local 102.175.185.68 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2026-06-18 03:28:51,148 - 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 102.175.185.68; protocol bgp bgp_1{ local as 65000; neighbor 102.175.185.66 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 102.175.185.68; } protocol bfd { neighbor 102.175.185.66 local 102.175.185.68 multihop on; } EOS 2026-06-18 03:28:51,403 - DEBUG - None 2026-06-18 03:28:51,403 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2026-06-18 03:28:51,403 - DEBUG - service bird restart 2026-06-18 03:28:52,110 - DEBUG - bird stop/waiting bird start/running, process 1480 2026-06-18 03:28:52,110 - INFO - Attaching VMI aabb1ee9-7b07-4e53-95ec-abd72ca2f5de to BGPaaS 36e9c585-4779-4c06-86ab-54b3b084c566 2026-06-18 03:28:52,180 - INFO - Attaching the VMI f7495db3-c5cb-4ed4-80c1-02a8ca280d6b to the BGPaaS 36e9c585-4779-4c06-86ab-54b3b084c566 object 2026-06-18 03:28:52,180 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 102.175.185.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2026-06-18 03:28:52,180 - DEBUG - sudo ip addr add 102.175.185.84 dev eth0 2026-06-18 03:28:52,447 - DEBUG - None 2026-06-18 03:28:52,447 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:28:52,447 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:28:52,466 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25'] 2026-06-18 03:28:58,469 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:28:58,483 - INFO - BGPaaS session seen in control-node 10.20.0.25 2026-06-18 03:28:58,491 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:28:58,491 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:28:58,504 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.193'] 2026-06-18 03:29:04,505 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:29:04,519 - INFO - BGPaaS session seen in control-node 10.20.0.193 2026-06-18 03:29:04,524 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 102.175.185.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:29:04,524 - DEBUG - ping -s 56 -c 3 -W 1 102.175.185.84 2026-06-18 03:29:08,857 - DEBUG - PING 102.175.185.84 (102.175.185.84) 56(84) bytes of data. 64 bytes from 102.175.185.84: icmp_seq=1 ttl=64 time=4.51 ms 64 bytes from 102.175.185.84: icmp_seq=2 ttl=64 time=1.32 ms 64 bytes from 102.175.185.84: icmp_seq=3 ttl=64 time=1.56 ms --- 102.175.185.84 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.326/2.468/4.511/1.448 ms 2026-06-18 03:29:08,857 - INFO - Ping to IP 102.175.185.84 from VM ctest-TestBGPaasZone-85196154-31025968 passed 2026-06-18 03:29:09,640 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:29:09,640 - DEBUG - service bird restart 2026-06-18 03:29:10,427 - DEBUG - bird stop/waiting bird start/running, process 1460 2026-06-18 03:29:10,428 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2026-06-18 03:29:10,428 - DEBUG - service bird restart 2026-06-18 03:29:11,125 - DEBUG - bird stop/waiting bird start/running, process 1507 2026-06-18 03:29:11,125 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:29:11,125 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:29:11,140 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.129'] 2026-06-18 03:29:17,142 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:29:17,156 - INFO - BGPaaS session seen in control-node 10.20.0.129 2026-06-18 03:29:17,162 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:29:17,162 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:29:17,175 - INFO - BGPaaS session seen in control-node 10.20.0.193 2026-06-18 03:29:17,181 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 102.175.185.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:29:17,181 - DEBUG - ping -s 56 -c 3 -W 1 102.175.185.84 2026-06-18 03:29:19,446 - DEBUG - PING 102.175.185.84 (102.175.185.84) 56(84) bytes of data. 64 bytes from 102.175.185.84: icmp_seq=1 ttl=64 time=3.22 ms 64 bytes from 102.175.185.84: icmp_seq=2 ttl=64 time=1.30 ms 64 bytes from 102.175.185.84: icmp_seq=3 ttl=64 time=1.03 ms --- 102.175.185.84 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2003ms rtt min/avg/max/mdev = 1.035/1.854/3.222/0.973 ms 2026-06-18 03:29:19,446 - INFO - Ping to IP 102.175.185.84 from VM ctest-TestBGPaasZone-85196154-31025968 passed 2026-06-18 03:29:19,733 - INFO - We will configure BGP on the VM 2026-06-18 03:29:19,920 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:29:19,920 - INFO - Configuring BGP on ctest-TestBGPaasZone-85196154-66946797 2026-06-18 03:29:19,920 - 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 102.175.185.67; protocol bgp bgp_1{ local as 65000; neighbor 102.175.185.65 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 102.175.185.67; } protocol bfd { neighbor 102.175.185.65 local 102.175.185.67 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:29:19,920 - 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 102.175.185.67; protocol bgp bgp_1{ local as 65000; neighbor 102.175.185.65 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 102.175.185.67; } protocol bfd { neighbor 102.175.185.65 local 102.175.185.67 multihop on; } EOS 2026-06-18 03:29:20,144 - DEBUG - None 2026-06-18 03:29:20,144 - 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 102.175.185.67; protocol bgp bgp_1{ local as 65000; neighbor 102.175.185.65 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 102.175.185.67; } protocol bfd { neighbor 102.175.185.65 local 102.175.185.67 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:29:20,144 - 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 102.175.185.67; protocol bgp bgp_1{ local as 65000; neighbor 102.175.185.65 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 102.175.185.67; } protocol bfd { neighbor 102.175.185.65 local 102.175.185.67 multihop on; } EOS 2026-06-18 03:29:20,420 - DEBUG - None 2026-06-18 03:29:20,420 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:29:20,420 - DEBUG - service bird restart 2026-06-18 03:29:21,222 - DEBUG - bird stop/waiting bird start/running, process 1492 2026-06-18 03:29:21,222 - INFO - Attaching VMI 26431e56-ed99-4c6a-ad3f-cdbdd4edb986 to BGPaaS b3b0db7d-f28c-4610-be70-ab666b67f1e8 2026-06-18 03:29:21,250 - INFO - Attaching the VMI b5b76272-e25e-4254-9280-4987c648081b to the BGPaaS b3b0db7d-f28c-4610-be70-ab666b67f1e8 object 2026-06-18 03:29:21,250 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 102.175.185.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:29:21,250 - DEBUG - sudo ip addr add 102.175.185.84 dev eth0 2026-06-18 03:29:21,536 - DEBUG - RTNETLINK answers: File exists 2026-06-18 03:29:21,536 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:29:21,536 - DEBUG - service bird restart 2026-06-18 03:29:22,294 - DEBUG - bird stop/waiting bird start/running, process 1519 2026-06-18 03:29:22,294 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2026-06-18 03:29:22,294 - DEBUG - service bird restart 2026-06-18 03:29:22,934 - DEBUG - bird stop/waiting bird start/running, process 1529 2026-06-18 03:29:22,935 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:29:22,935 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:29:22,948 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25'] 2026-06-18 03:29:28,949 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:29:28,965 - INFO - BGPaaS session seen in control-node 10.20.0.25 2026-06-18 03:29:28,974 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:29:28,974 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:29:28,987 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.193'] 2026-06-18 03:29:34,988 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:29:35,001 - INFO - BGPaaS session seen in control-node 10.20.0.193 2026-06-18 03:29:35,006 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 102.175.185.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:29:35,007 - DEBUG - ping -s 56 -c 3 -W 1 102.175.185.84 2026-06-18 03:29:37,245 - DEBUG - PING 102.175.185.84 (102.175.185.84) 56(84) bytes of data. 64 bytes from 102.175.185.84: icmp_seq=1 ttl=64 time=2.33 ms 64 bytes from 102.175.185.84: icmp_seq=2 ttl=64 time=1.06 ms 64 bytes from 102.175.185.84: icmp_seq=3 ttl=64 time=1.12 ms --- 102.175.185.84 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.063/1.505/2.330/0.584 ms 2026-06-18 03:29:37,245 - INFO - Ping to IP 102.175.185.84 from VM ctest-TestBGPaasZone-85196154-31025968 passed 2026-06-18 03:29:37,245 - INFO - Detaching VMI 26431e56-ed99-4c6a-ad3f-cdbdd4edb986 from BGPaaS b3b0db7d-f28c-4610-be70-ab666b67f1e8 2026-06-18 03:29:37,312 - INFO - Detaching VMI aabb1ee9-7b07-4e53-95ec-abd72ca2f5de from BGPaaS 36e9c585-4779-4c06-86ab-54b3b084c566 2026-06-18 03:29:37,396 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-85196154-93499874(36e9c585-4779-4c06-86ab-54b3b084c566) 2026-06-18 03:29:37,439 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/36e9c585-4779-4c06-86ab-54b3b084c566 2026-06-18 03:29:37,445 - DEBUG - Response Code: 404 2026-06-18 03:29:37,445 - DEBUG - BGPaaS: 36e9c585-4779-4c06-86ab-54b3b084c566 deleted from api server 2026-06-18 03:29:37,445 - INFO - BGPaaS(36e9c585-4779-4c06-86ab-54b3b084c566): verify_on_cleanup passed 2026-06-18 03:29:37,445 - INFO - Detaching VMI 26431e56-ed99-4c6a-ad3f-cdbdd4edb986 from BGPaaS b3b0db7d-f28c-4610-be70-ab666b67f1e8 2026-06-18 03:29:37,472 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-85196154-66447079(b3b0db7d-f28c-4610-be70-ab666b67f1e8) 2026-06-18 03:29:37,531 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/b3b0db7d-f28c-4610-be70-ab666b67f1e8 2026-06-18 03:29:37,538 - DEBUG - Response Code: 404 2026-06-18 03:29:37,538 - DEBUG - BGPaaS: b3b0db7d-f28c-4610-be70-ab666b67f1e8 deleted from api server 2026-06-18 03:29:37,538 - INFO - BGPaaS(b3b0db7d-f28c-4610-be70-ab666b67f1e8): verify_on_cleanup passed 2026-06-18 03:29:37,539 - INFO - Deleting Control node zone ctest-test-zone-52534015(e4208f01-d88d-4c5f-81a4-9e3d68b76f5c) 2026-06-18 03:29:37,678 - INFO - Deleting Control node zone ctest-test-zone-09233013(002e0717-09ee-41f0-b852-231906ede08c) 2026-06-18 03:29:37,822 - INFO - Deleting Control node zone ctest-test-zone-37028239(afecde39-7194-4e52-8655-45419e2c2a25) 2026-06-18 03:29:37,899 - INFO - Deleting VM ctest-TestBGPaasZone-85196154-31025968 2026-06-18 03:29:37,985 - INFO - Deleting VM ctest-TestBGPaasZone-85196154-72149788 2026-06-18 03:29:38,055 - INFO - Deleting VM ctest-TestBGPaasZone-85196154-66946797 2026-06-18 03:29:38,122 - INFO - Deleting VN ctest-cnz_vn-18587255 2026-06-18 03:29:38,164 - DEBUG - VN ff93b436-6d55-4d88-840d-7a87e8e525ec still in use: Unable to complete operation on network ff93b436-6d55-4d88-840d-7a87e8e525ec. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-b7266063-2947-4b16-93b6-9d28493752ef'] 2026-06-18 03:29:38,164 - WARNING - Deleting VN ctest-cnz_vn-18587255 failed..Will retry 2026-06-18 03:29:40,222 - DEBUG - VN ff93b436-6d55-4d88-840d-7a87e8e525ec still in use: Unable to complete operation on network ff93b436-6d55-4d88-840d-7a87e8e525ec. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-2e8277e1-0301-4353-8a81-79073d9543a9'] 2026-06-18 03:29:40,222 - WARNING - Deleting VN ctest-cnz_vn-18587255 failed..Will retry 2026-06-18 03:29:42,442 - DEBUG - Response for deleting network () 2026-06-18 03:29:43,725 - DEBUG - Skipping xmpp flap check
2026-06-18 03:29:43,725 - INFO - END TEST : test_bgp_control_node_zone : PASSED[0:03:29]
2026-06-18 03:29:43,725 - INFO - -------------------------------------------------------------------------------- 2026-06-18 03:29:43,728 - INFO - ================================================================================ 2026-06-18 03:29:43,728 - INFO - STARTING TEST : test_bgp_control_node_zones_from_single_vnf 2026-06-18 03:29:45,014 - DEBUG - Skipping xmpp flap check 2026-06-18 03:29:45,014 - INFO - Initial checks done. Running the testcase now 2026-06-18 03:29:45,014 - INFO - 2026-06-18 03:29:45,014 - INFO - executing bgp_control_zone agent restart test 2026-06-18 03:29:45,304 - DEBUG - Response for create_network : {'network': {'id': '4f3cb180-94c0-4a1a-a938-d21685a80941', 'name': 'ctest-cnz_vn-53177587', 'tenant_id': '614173f6bdca427ea1b5dd09f4837695', 'project_id': '614173f6bdca427ea1b5dd09f4837695', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBGPaasZone-85196154', 'ctest-cnz_vn-53177587'], 'port_security_enabled': True, 'description': ''}} 2026-06-18 03:29:45,570 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd556816d-a61d-4526-91a4-de75f263b172', 'name': '', 'tenant_id': '614173f6bdca427ea1b5dd09f4837695', 'network_id': '4f3cb180-94c0-4a1a-a938-d21685a80941', 'ip_version': 4, 'cidr': '62.68.65.128/26', 'allocation_pools': [{'start': '62.68.65.130', 'end': '62.68.65.190'}], 'gateway_ip': '62.68.65.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '62.68.65.130', 'tags': [], 'project_id': '614173f6bdca427ea1b5dd09f4837695'}} 2026-06-18 03:29:45,594 - INFO - Created VN ctest-cnz_vn-53177587 2026-06-18 03:29:45,608 - DEBUG - VN ctest-cnz_vn-53177587 UUID is 4f3cb180-94c0-4a1a-a938-d21685a80941 2026-06-18 03:29:47,044 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5973-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5973-1) 2026-06-18 03:29:48,380 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5973-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5973-2) 2026-06-18 03:29:49,643 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-5973-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-5973-1) 2026-06-18 03:29:49,643 - INFO - Waiting for VM ctest-TestBGPaasZone-85196154-68366756 to be up.. 2026-06-18 03:29:49,702 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-06-18 03:29:54,803 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-06-18 03:29:59,881 - DEBUG - VM is in ACTIVE state now 2026-06-18 03:29:59,881 - INFO - VM name : ctest-TestBGPaasZone-85196154-68366756 2026-06-18 03:29:59,960 - DEBUG - VM ctest-TestBGPaasZone-85196154-68366756 ID is 30be0b6f-97de-478c-b2d3-3256a3674658 2026-06-18 03:29:59,960 - DEBUG - VM ctest-TestBGPaasZone-85196154-68366756 launched on Node an-jenkins-deploy-platform-ansible-os-5973-1 2026-06-18 03:30:00,049 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/30be0b6f-97de-478c-b2d3-3256a3674658 2026-06-18 03:30:00,061 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/b8cb7369-7947-4139-adda-97b85c28c9cd 2026-06-18 03:30:03,237 - 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-06-18 03:30:03,237 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-68366756 failed! 2026-06-18 03:30:03,249 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-53177587 is 62.68.65.129 and allocation pool is NOT set 2026-06-18 03:30:07,328 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms') 2026-06-18 03:30:07,328 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-68366756 failed! 2026-06-18 03:30:07,342 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-53177587 is 62.68.65.129 and allocation pool is NOT set 2026-06-18 03:30:11,433 - 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 1025ms') 2026-06-18 03:30:11,434 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-68366756 failed! 2026-06-18 03:30:11,448 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-53177587 is 62.68.65.129 and allocation pool is NOT set 2026-06-18 03:30:15,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 1013ms') 2026-06-18 03:30:15,525 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-68366756 failed! 2026-06-18 03:30:15,537 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-53177587 is 62.68.65.129 and allocation pool is NOT set 2026-06-18 03:30:19,622 - 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-06-18 03:30:19,622 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-68366756 failed! 2026-06-18 03:30:19,634 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-53177587 is 62.68.65.129 and allocation pool is NOT set 2026-06-18 03:30:23,717 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms') 2026-06-18 03:30:23,718 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-68366756 failed! 2026-06-18 03:30:23,734 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-53177587 is 62.68.65.129 and allocation pool is NOT set 2026-06-18 03:30:27,813 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2026-06-18 03:30:27,813 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-68366756 failed! 2026-06-18 03:30:27,828 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-53177587 is 62.68.65.129 and allocation pool is NOT set 2026-06-18 03:30:31,908 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms') 2026-06-18 03:30:31,908 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-68366756 failed! 2026-06-18 03:30:31,923 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-53177587 is 62.68.65.129 and allocation pool is NOT set 2026-06-18 03:30:36,005 - 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-06-18 03:30:36,005 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-68366756 failed! 2026-06-18 03:30:36,018 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-53177587 is 62.68.65.129 and allocation pool is NOT set 2026-06-18 03:30:40,107 - 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 1027ms') 2026-06-18 03:30:40,108 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-68366756 failed! 2026-06-18 03:30:40,120 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-85196154:ctest-cnz_vn-53177587 is 62.68.65.129 and allocation pool is NOT set 2026-06-18 03:30:42,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=8.17 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.779 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.779/4.476/8.174/3.697 ms') 2026-06-18 03:30:42,183 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-68366756 passed 2026-06-18 03:30:42,253 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:30:42,254 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-68366756, IP 62.68.65.131, Port 22 2026-06-18 03:30:42,322 - 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-06-18 03:30:42,412 - DEBUG - VM ctest-TestBGPaasZone-85196154-68366756 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:30:47,413 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:30:47,413 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-68366756, IP 62.68.65.131, Port 22 2026-06-18 03:30:47,482 - 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-06-18 03:30:47,555 - DEBUG - VM ctest-TestBGPaasZone-85196154-68366756 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:30:52,556 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:30:52,556 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-68366756, IP 62.68.65.131, Port 22 2026-06-18 03:30:52,626 - 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-06-18 03:30:52,716 - DEBUG - VM ctest-TestBGPaasZone-85196154-68366756 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:30:57,716 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:30:57,717 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-68366756, IP 62.68.65.131, Port 22 2026-06-18 03:30:57,785 - 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-06-18 03:30:57,870 - DEBUG - VM ctest-TestBGPaasZone-85196154-68366756 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:31:02,870 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:31:02,871 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-68366756, IP 62.68.65.131, Port 22 2026-06-18 03:31:02,938 - 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-06-18 03:31:03,030 - DEBUG - VM ctest-TestBGPaasZone-85196154-68366756 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:31:08,031 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:31:08,031 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-68366756, IP 62.68.65.131, Port 22 2026-06-18 03:31:08,098 - 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-06-18 03:31:08,180 - DEBUG - VM ctest-TestBGPaasZone-85196154-68366756 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:31:13,181 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:31:13,181 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-68366756, IP 62.68.65.131, Port 22 2026-06-18 03:31:13,347 - DEBUG - VM ctest-TestBGPaasZone-85196154-68366756 is ready for SSH connections 2026-06-18 03:31:13,347 - INFO - Waiting for VM ctest-TestBGPaasZone-85196154-54199884 to be up.. 2026-06-18 03:31:13,428 - DEBUG - VM is in ACTIVE state now 2026-06-18 03:31:13,428 - INFO - VM name : ctest-TestBGPaasZone-85196154-54199884 2026-06-18 03:31:13,514 - DEBUG - VM ctest-TestBGPaasZone-85196154-54199884 ID is 1cbbc7ef-1370-42ae-9f09-9cdc1cd5af03 2026-06-18 03:31:13,514 - DEBUG - VM ctest-TestBGPaasZone-85196154-54199884 launched on Node an-jenkins-deploy-platform-ansible-os-5973-2 2026-06-18 03:31:13,593 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/1cbbc7ef-1370-42ae-9f09-9cdc1cd5af03 2026-06-18 03:31:13,602 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/13967259-66f9-44e4-9dd9-4be92aaf5f28 2026-06-18 03:31:14,774 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=2.84 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.28 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 = 1.277/2.057/2.837/0.780 ms') 2026-06-18 03:31:14,774 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-85196154-54199884 passed 2026-06-18 03:31:14,842 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:31:14,842 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-54199884, IP 62.68.65.132, Port 22 2026-06-18 03:31:15,017 - DEBUG - VM ctest-TestBGPaasZone-85196154-54199884 is ready for SSH connections 2026-06-18 03:31:15,017 - INFO - Waiting for VM ctest-TestBGPaasZone-85196154-09226175 to be up.. 2026-06-18 03:31:15,158 - DEBUG - VM is in ACTIVE state now 2026-06-18 03:31:15,159 - INFO - VM name : ctest-TestBGPaasZone-85196154-09226175 2026-06-18 03:31:15,258 - DEBUG - VM ctest-TestBGPaasZone-85196154-09226175 ID is eb1e54a2-b6a0-41af-b0a2-ff127feba8ac 2026-06-18 03:31:15,258 - DEBUG - VM ctest-TestBGPaasZone-85196154-09226175 launched on Node an-jenkins-deploy-platform-ansible-os-5973-1 2026-06-18 03:31:15,356 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/eb1e54a2-b6a0-41af-b0a2-ff127feba8ac 2026-06-18 03:31:15,365 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/a159cf63-84d5-4a36-9699-df8c95e5a251 2026-06-18 03:31:16,554 - 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=3.30 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.27 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 = 1.266/2.283/3.301/1.017 ms') 2026-06-18 03:31:16,554 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-85196154-09226175 passed 2026-06-18 03:31:16,624 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:31:16,624 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-09226175, IP 62.68.65.133, Port 22 2026-06-18 03:31:16,689 - 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-06-18 03:31:16,764 - DEBUG - VM ctest-TestBGPaasZone-85196154-09226175 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:31:21,765 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:31:21,765 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-09226175, IP 62.68.65.133, Port 22 2026-06-18 03:31:21,833 - 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-06-18 03:31:21,918 - DEBUG - VM ctest-TestBGPaasZone-85196154-09226175 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:31:26,918 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:31:26,918 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-09226175, IP 62.68.65.133, Port 22 2026-06-18 03:31:26,985 - 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-06-18 03:31:27,062 - DEBUG - VM ctest-TestBGPaasZone-85196154-09226175 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:31:32,063 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:31:32,063 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-09226175, IP 62.68.65.133, Port 22 2026-06-18 03:31:32,129 - 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-06-18 03:31:32,209 - DEBUG - VM ctest-TestBGPaasZone-85196154-09226175 is NOT ready for SSH connections, VM status: ACTIVE 2026-06-18 03:31:37,210 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:31:37,210 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-85196154-09226175, IP 62.68.65.133, Port 22 2026-06-18 03:31:37,368 - DEBUG - VM ctest-TestBGPaasZone-85196154-09226175 is ready for SSH connections 2026-06-18 03:31:37,500 - INFO - Created control node zone ctest-test-zone-30086477(041e97e4-cc73-4227-afa8-87b0fa91e0b3) 2026-06-18 03:31:37,836 - INFO - Created control node zone ctest-test-zone-49378628(e1c45b81-6cec-4208-8186-7afe0eabb515) 2026-06-18 03:31:38,176 - INFO - Created control node zone ctest-test-zone-25509714(acef0762-a709-4cd0-bd9f-9a4701c06f83) 2026-06-18 03:31:38,460 - INFO - Creating BGPaaS ctest-TestBGPaasZone-85196154-41796278 2026-06-18 03:31:38,536 - INFO - BGPaaS: ctest-TestBGPaasZone-85196154-41796278(458bf468-5d84-4d68-a8d2-8916283809b4) 2026-06-18 03:31:38,537 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/458bf468-5d84-4d68-a8d2-8916283809b4 2026-06-18 03:31:38,547 - INFO - verify_in_api_server passed for BGPaaS obj 458bf468-5d84-4d68-a8d2-8916283809b4 2026-06-18 03:31:38,548 - INFO - BGPaaS(458bf468-5d84-4d68-a8d2-8916283809b4): verify_on_setup passed 2026-06-18 03:31:38,548 - INFO - We will configure BGP on the VM 2026-06-18 03:31:38,960 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:31:38,960 - INFO - Configuring BGP on ctest-TestBGPaasZone-85196154-68366756 2026-06-18 03:31:38,960 - 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 62.68.65.131; protocol bgp bgp_1{ local as 65000; neighbor 62.68.65.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 62.68.65.131; } protocol bfd { neighbor 62.68.65.129 local 62.68.65.131 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2026-06-18 03:31:38,961 - 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 62.68.65.131; protocol bgp bgp_1{ local as 65000; neighbor 62.68.65.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 62.68.65.131; } protocol bfd { neighbor 62.68.65.129 local 62.68.65.131 multihop on; } EOS 2026-06-18 03:31:42,225 - DEBUG - None 2026-06-18 03:31:42,225 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS protocol bgp bgp_2{ local as 65000; neighbor 62.68.65.130 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 62.68.65.131; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2026-06-18 03:31:42,225 - DEBUG - cat >> /etc/bird/bird.conf << EOS protocol bgp bgp_2{ local as 65000; neighbor 62.68.65.130 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 62.68.65.131; } EOS 2026-06-18 03:31:42,493 - DEBUG - None 2026-06-18 03:31:42,494 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2026-06-18 03:31:42,494 - DEBUG - service bird restart 2026-06-18 03:31:43,227 - DEBUG - bird stop/waiting bird start/running, process 1480 2026-06-18 03:31:43,227 - INFO - Attaching VMI b8cb7369-7947-4139-adda-97b85c28c9cd to BGPaaS 458bf468-5d84-4d68-a8d2-8916283809b4 2026-06-18 03:31:43,308 - INFO - Attaching the VMI 30be0b6f-97de-478c-b2d3-3256a3674658 to the BGPaaS 458bf468-5d84-4d68-a8d2-8916283809b4 object 2026-06-18 03:31:43,308 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 62.68.65.148 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2026-06-18 03:31:43,309 - DEBUG - sudo ip addr add 62.68.65.148 dev eth0 2026-06-18 03:31:43,614 - DEBUG - None 2026-06-18 03:31:43,676 - INFO - Creating BGPaaS ctest-TestBGPaasZone-85196154-07633735 2026-06-18 03:31:43,756 - INFO - BGPaaS: ctest-TestBGPaasZone-85196154-07633735(a397d090-6118-4199-ae13-474cbaab87b9) 2026-06-18 03:31:43,756 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/a397d090-6118-4199-ae13-474cbaab87b9 2026-06-18 03:31:43,766 - INFO - verify_in_api_server passed for BGPaaS obj a397d090-6118-4199-ae13-474cbaab87b9 2026-06-18 03:31:43,766 - INFO - BGPaaS(a397d090-6118-4199-ae13-474cbaab87b9): verify_on_setup passed 2026-06-18 03:31:43,766 - INFO - We will configure BGP on the VM 2026-06-18 03:31:44,196 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:31:44,196 - INFO - Configuring BGP on ctest-TestBGPaasZone-85196154-54199884 2026-06-18 03:31:44,196 - 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 62.68.65.132; protocol bgp bgp_1{ local as 65000; neighbor 62.68.65.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 62.68.65.132; } protocol bfd { neighbor 62.68.65.129 local 62.68.65.132 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:31:44,196 - 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 62.68.65.132; protocol bgp bgp_1{ local as 65000; neighbor 62.68.65.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 62.68.65.132; } protocol bfd { neighbor 62.68.65.129 local 62.68.65.132 multihop on; } EOS 2026-06-18 03:31:47,466 - DEBUG - None 2026-06-18 03:31:47,466 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS protocol bgp bgp_2{ local as 65000; neighbor 62.68.65.130 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 62.68.65.132; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:31:47,466 - DEBUG - cat >> /etc/bird/bird.conf << EOS protocol bgp bgp_2{ local as 65000; neighbor 62.68.65.130 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 62.68.65.132; } EOS 2026-06-18 03:31:47,719 - DEBUG - None 2026-06-18 03:31:47,720 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:31:47,720 - DEBUG - service bird restart 2026-06-18 03:31:48,461 - DEBUG - bird stop/waiting bird start/running, process 1481 2026-06-18 03:31:48,461 - INFO - Attaching VMI 13967259-66f9-44e4-9dd9-4be92aaf5f28 to BGPaaS a397d090-6118-4199-ae13-474cbaab87b9 2026-06-18 03:31:48,540 - INFO - Attaching the VMI 1cbbc7ef-1370-42ae-9f09-9cdc1cd5af03 to the BGPaaS a397d090-6118-4199-ae13-474cbaab87b9 object 2026-06-18 03:31:48,540 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 62.68.65.148 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123 2026-06-18 03:31:48,540 - DEBUG - sudo ip addr add 62.68.65.148 dev eth0 2026-06-18 03:31:48,811 - DEBUG - None 2026-06-18 03:31:48,811 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:31:48,811 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:31:48,825 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25'] 2026-06-18 03:31:54,827 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:31:54,844 - INFO - BGPaaS session seen in control-node 10.20.0.25 2026-06-18 03:31:54,845 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:31:54,845 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:31:54,859 - INFO - BGPaaS session seen in control-node 10.20.0.193 2026-06-18 03:31:54,865 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:31:54,865 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:31:54,878 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25'] 2026-06-18 03:32:00,879 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:32:00,892 - INFO - BGPaaS session seen in control-node 10.20.0.25 2026-06-18 03:32:00,892 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:32:00,893 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:32:00,909 - INFO - BGPaaS session seen in control-node 10.20.0.193 2026-06-18 03:32:00,916 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 62.68.65.148, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2026-06-18 03:32:00,917 - DEBUG - ping -s 56 -c 3 -W 1 62.68.65.148 2026-06-18 03:32:04,763 - DEBUG - PING 62.68.65.148 (62.68.65.148) 56(84) bytes of data. 64 bytes from 62.68.65.148: icmp_seq=1 ttl=64 time=4.34 ms 64 bytes from 62.68.65.148: icmp_seq=2 ttl=64 time=1.10 ms 64 bytes from 62.68.65.148: icmp_seq=3 ttl=64 time=1.29 ms --- 62.68.65.148 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.100/2.243/4.340/1.485 ms 2026-06-18 03:32:04,763 - INFO - Ping to IP 62.68.65.148 from VM ctest-TestBGPaasZone-85196154-09226175 passed 2026-06-18 03:32:04,763 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:32:04,763 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:32:04,777 - INFO - BGPaaS session seen in control-node 10.20.0.25 2026-06-18 03:32:04,778 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:32:04,778 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:32:04,792 - INFO - BGPaaS session seen in control-node 10.20.0.193 2026-06-18 03:32:04,799 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:32:04,799 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:32:04,812 - INFO - BGPaaS session seen in control-node 10.20.0.25 2026-06-18 03:32:04,812 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-06-18 03:32:04,812 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-06-18 03:32:04,825 - INFO - BGPaaS session seen in control-node 10.20.0.193 2026-06-18 03:32:04,835 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 62.68.65.148, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123 2026-06-18 03:32:04,835 - DEBUG - ping -s 56 -c 3 -W 1 62.68.65.148 2026-06-18 03:32:07,074 - DEBUG - PING 62.68.65.148 (62.68.65.148) 56(84) bytes of data. 64 bytes from 62.68.65.148: icmp_seq=1 ttl=64 time=1.91 ms 64 bytes from 62.68.65.148: icmp_seq=2 ttl=64 time=1.29 ms 64 bytes from 62.68.65.148: icmp_seq=3 ttl=64 time=1.22 ms --- 62.68.65.148 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2003ms rtt min/avg/max/mdev = 1.226/1.479/1.913/0.309 ms 2026-06-18 03:32:07,074 - INFO - Ping to IP 62.68.65.148 from VM ctest-TestBGPaasZone-85196154-09226175 passed 2026-06-18 03:32:07,074 - INFO - Detaching VMI 13967259-66f9-44e4-9dd9-4be92aaf5f28 from BGPaaS a397d090-6118-4199-ae13-474cbaab87b9 2026-06-18 03:32:07,152 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-85196154-07633735(a397d090-6118-4199-ae13-474cbaab87b9) 2026-06-18 03:32:07,218 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/a397d090-6118-4199-ae13-474cbaab87b9 2026-06-18 03:32:07,234 - DEBUG - Response Code: 404 2026-06-18 03:32:07,234 - DEBUG - BGPaaS: a397d090-6118-4199-ae13-474cbaab87b9 deleted from api server 2026-06-18 03:32:07,234 - INFO - BGPaaS(a397d090-6118-4199-ae13-474cbaab87b9): verify_on_cleanup passed 2026-06-18 03:32:07,234 - INFO - Detaching VMI b8cb7369-7947-4139-adda-97b85c28c9cd from BGPaaS 458bf468-5d84-4d68-a8d2-8916283809b4 2026-06-18 03:32:07,324 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-85196154-41796278(458bf468-5d84-4d68-a8d2-8916283809b4) 2026-06-18 03:32:07,383 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/458bf468-5d84-4d68-a8d2-8916283809b4 2026-06-18 03:32:07,389 - DEBUG - Response Code: 404 2026-06-18 03:32:07,389 - DEBUG - BGPaaS: 458bf468-5d84-4d68-a8d2-8916283809b4 deleted from api server 2026-06-18 03:32:07,389 - INFO - BGPaaS(458bf468-5d84-4d68-a8d2-8916283809b4): verify_on_cleanup passed 2026-06-18 03:32:07,389 - INFO - Deleting Control node zone ctest-test-zone-25509714(acef0762-a709-4cd0-bd9f-9a4701c06f83) 2026-06-18 03:32:07,533 - INFO - Deleting Control node zone ctest-test-zone-49378628(e1c45b81-6cec-4208-8186-7afe0eabb515) 2026-06-18 03:32:07,668 - INFO - Deleting Control node zone ctest-test-zone-30086477(041e97e4-cc73-4227-afa8-87b0fa91e0b3) 2026-06-18 03:32:07,806 - INFO - Deleting VM ctest-TestBGPaasZone-85196154-09226175 2026-06-18 03:32:07,888 - INFO - Deleting VM ctest-TestBGPaasZone-85196154-54199884 2026-06-18 03:32:07,958 - INFO - Deleting VM ctest-TestBGPaasZone-85196154-68366756 2026-06-18 03:32:08,033 - INFO - Deleting VN ctest-cnz_vn-53177587 2026-06-18 03:32:08,079 - DEBUG - VN 4f3cb180-94c0-4a1a-a938-d21685a80941 still in use: Unable to complete operation on network 4f3cb180-94c0-4a1a-a938-d21685a80941. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-ce7958eb-bdec-47b8-ac1a-28150103fc22'] 2026-06-18 03:32:08,079 - WARNING - Deleting VN ctest-cnz_vn-53177587 failed..Will retry 2026-06-18 03:32:10,152 - DEBUG - VN 4f3cb180-94c0-4a1a-a938-d21685a80941 still in use: Unable to complete operation on network 4f3cb180-94c0-4a1a-a938-d21685a80941. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-491b64c0-2498-44c5-8153-d61ce5a185c6'] 2026-06-18 03:32:10,152 - WARNING - Deleting VN ctest-cnz_vn-53177587 failed..Will retry 2026-06-18 03:32:12,324 - DEBUG - Response for deleting network () 2026-06-18 03:32:13,578 - DEBUG - Skipping xmpp flap check
2026-06-18 03:32:13,578 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:02:30]
2026-06-18 03:32:13,578 - INFO - -------------------------------------------------------------------------------- 2026-06-18 03:32:14,373 - INFO - Deleted project: ctest-TestBGPaasZone-85196154, ID : 614173f6-bdca-427e-a1b5-dd09f4837695