2025-09-15 03:42:20,681 - INFO - Domain Default found not creating 2025-09-15 03:42:20,816 - INFO - Project ctest-TestBGPaasZone-32962351 not found, creating it 2025-09-15 03:42:21,340 - INFO - Created Project:ctest-TestBGPaasZone-32962351, ID : d2f09c46-5155-4d83-8011-c9bc6a72c7bf 2025-09-15 03:42:22,806 - DEBUG - Services list from nova: [, , , ] 2025-09-15 03:42:22,806 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-4110-1', 'an-jenkins-deploy-platform-ansible-os-4110-2']} 2025-09-15 03:42:22,808 - INFO - ================================================================================ 2025-09-15 03:42:22,808 - INFO - STARTING TEST : test_bgp_control_node_zone 2025-09-15 03:42:24,083 - DEBUG - Skipping xmpp flap check 2025-09-15 03:42:24,084 - INFO - Initial checks done. Running the testcase now 2025-09-15 03:42:24,084 - INFO - 2025-09-15 03:42:24,084 - INFO - executing bgp_control_zone test 2025-09-15 03:42:24,756 - DEBUG - Response for create_network : {'network': {'id': 'ad6ee2a7-64bc-412b-a8ad-08f23fed269d', 'name': 'ctest-cnz_vn-97004119', 'tenant_id': 'd2f09c4651554d838011c9bc6a72c7bf', 'project_id': 'd2f09c4651554d838011c9bc6a72c7bf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBGPaasZone-32962351', 'ctest-cnz_vn-97004119'], 'port_security_enabled': True, 'description': ''}} 2025-09-15 03:42:25,075 - DEBUG - Response for create_subnet : {'subnet': {'id': '38e49f1e-5cd3-4189-86b2-a177be34cc02', 'name': '', 'tenant_id': 'd2f09c4651554d838011c9bc6a72c7bf', 'network_id': 'ad6ee2a7-64bc-412b-a8ad-08f23fed269d', 'ip_version': 4, 'cidr': '81.177.73.0/26', 'allocation_pools': [{'start': '81.177.73.2', 'end': '81.177.73.62'}], 'gateway_ip': '81.177.73.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '81.177.73.2', 'tags': [], 'project_id': 'd2f09c4651554d838011c9bc6a72c7bf'}} 2025-09-15 03:42:25,111 - INFO - Created VN ctest-cnz_vn-97004119 2025-09-15 03:42:25,125 - DEBUG - VN ctest-cnz_vn-97004119 UUID is ad6ee2a7-64bc-412b-a8ad-08f23fed269d 2025-09-15 03:42:26,325 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4110-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4110-1) 2025-09-15 03:42:27,803 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4110-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4110-2) 2025-09-15 03:42:29,464 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4110-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4110-1) 2025-09-15 03:42:29,465 - INFO - Waiting for VM ctest-TestBGPaasZone-32962351-57038105 to be up.. 2025-09-15 03:42:29,595 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-09-15 03:42:34,736 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-09-15 03:42:39,825 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-09-15 03:42:44,917 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-09-15 03:42:50,009 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-09-15 03:42:55,101 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-09-15 03:43:00,241 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-09-15 03:43:05,332 - DEBUG - VM is in ACTIVE state now 2025-09-15 03:43:05,332 - INFO - VM name : ctest-TestBGPaasZone-32962351-57038105 2025-09-15 03:43:05,416 - DEBUG - VM ctest-TestBGPaasZone-32962351-57038105 ID is 032e1b2a-daeb-41bf-888b-8bcb5913801d 2025-09-15 03:43:05,442 - DEBUG - VM ctest-TestBGPaasZone-32962351-57038105 launched on Node an-jenkins-deploy-platform-ansible-os-4110-1 2025-09-15 03:43:05,521 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/032e1b2a-daeb-41bf-888b-8bcb5913801d 2025-09-15 03:43:05,823 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/032e1b2a-daeb-41bf-888b-8bcb5913801d 2025-09-15 03:43:05,858 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/62fb3a39-690b-4c86-84ae-8a989b525cb1 2025-09-15 03:43:09,048 - 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') 2025-09-15 03:43:09,048 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:43:09,065 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:43:13,147 - 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') 2025-09-15 03:43:13,147 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:43:13,161 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:43:17,232 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-09-15 03:43:17,232 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:43:17,246 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:43:21,341 - 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 1031ms') 2025-09-15 03:43:21,341 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:43:21,355 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:43:25,432 - 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') 2025-09-15 03:43:25,433 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:43:25,448 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:43:29,527 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-09-15 03:43:29,527 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:43:29,542 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:43:33,626 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms') 2025-09-15 03:43:33,626 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:43:33,645 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:43:37,721 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2025-09-15 03:43:37,722 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:43:37,740 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:43:41,811 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2025-09-15 03:43:41,812 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:43:41,827 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:43:45,910 - 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') 2025-09-15 03:43:45,911 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:43:45,926 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:43:50,009 - 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') 2025-09-15 03:43:50,009 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:43:50,024 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:43:54,097 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2025-09-15 03:43:54,097 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:43:54,112 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:43:58,192 - 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') 2025-09-15 03:43:58,193 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:43:58,208 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:44:02,298 - 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') 2025-09-15 03:44:02,298 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:44:02,314 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:44:06,388 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-09-15 03:44:06,388 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:44:06,403 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:44:10,481 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms') 2025-09-15 03:44:10,481 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:44:10,495 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:44:14,583 - 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 1031ms') 2025-09-15 03:44:14,583 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:44:14,597 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:44:18,675 - 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') 2025-09-15 03:44:18,675 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 failed! 2025-09-15 03:44:18,692 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-97004119 is 81.177.73.1 and allocation pool is NOT set 2025-09-15 03:44:20,755 - 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.84 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.67 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.667/3.752/4.837/1.085 ms') 2025-09-15 03:44:20,755 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-57038105 passed 2025-09-15 03:44:20,831 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:44:20,831 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-57038105, IP 81.177.73.3, Port 22 2025-09-15 03:44:20,896 - 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': ''} 2025-09-15 03:44:21,018 - DEBUG - VM ctest-TestBGPaasZone-32962351-57038105 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:44:26,019 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:44:26,019 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-57038105, IP 81.177.73.3, Port 22 2025-09-15 03:44:26,085 - 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': ''} 2025-09-15 03:44:26,171 - DEBUG - VM ctest-TestBGPaasZone-32962351-57038105 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:44:31,172 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:44:31,172 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-57038105, IP 81.177.73.3, Port 22 2025-09-15 03:44:31,226 - 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': ''} 2025-09-15 03:44:31,320 - DEBUG - VM ctest-TestBGPaasZone-32962351-57038105 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:44:36,321 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:44:36,321 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-57038105, IP 81.177.73.3, Port 22 2025-09-15 03:44:36,389 - 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': ''} 2025-09-15 03:44:36,476 - DEBUG - VM ctest-TestBGPaasZone-32962351-57038105 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:44:41,477 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:44:41,478 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-57038105, IP 81.177.73.3, Port 22 2025-09-15 03:44:41,535 - 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': ''} 2025-09-15 03:44:41,644 - DEBUG - VM ctest-TestBGPaasZone-32962351-57038105 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:44:46,645 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:44:46,645 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-57038105, IP 81.177.73.3, Port 22 2025-09-15 03:44:46,713 - 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': ''} 2025-09-15 03:44:46,820 - DEBUG - VM ctest-TestBGPaasZone-32962351-57038105 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:44:51,820 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:44:51,821 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-57038105, IP 81.177.73.3, Port 22 2025-09-15 03:44:51,880 - 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': ''} 2025-09-15 03:44:51,988 - DEBUG - VM ctest-TestBGPaasZone-32962351-57038105 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:44:56,989 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:44:56,989 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-57038105, IP 81.177.73.3, Port 22 2025-09-15 03:44:57,062 - 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': ''} 2025-09-15 03:44:57,172 - DEBUG - VM ctest-TestBGPaasZone-32962351-57038105 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:45:02,173 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:45:02,173 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-57038105, IP 81.177.73.3, Port 22 2025-09-15 03:45:02,345 - DEBUG - VM ctest-TestBGPaasZone-32962351-57038105 is ready for SSH connections 2025-09-15 03:45:02,345 - INFO - Waiting for VM ctest-TestBGPaasZone-32962351-43340246 to be up.. 2025-09-15 03:45:02,447 - DEBUG - VM is in ACTIVE state now 2025-09-15 03:45:02,448 - INFO - VM name : ctest-TestBGPaasZone-32962351-43340246 2025-09-15 03:45:02,536 - DEBUG - VM ctest-TestBGPaasZone-32962351-43340246 ID is 071c25c3-7400-402b-9c4c-66752d9ccfc7 2025-09-15 03:45:02,536 - DEBUG - VM ctest-TestBGPaasZone-32962351-43340246 launched on Node an-jenkins-deploy-platform-ansible-os-4110-2 2025-09-15 03:45:02,641 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/071c25c3-7400-402b-9c4c-66752d9ccfc7 2025-09-15 03:45:02,652 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/c55e97ad-b8ef-44ae-8159-39d31b5c695e 2025-09-15 03:45:03,822 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=4.17 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.511 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.511/2.338/4.166/1.827 ms') 2025-09-15 03:45:03,822 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-43340246 passed 2025-09-15 03:45:03,893 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:45:03,893 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-43340246, IP 81.177.73.4, Port 22 2025-09-15 03:45:04,065 - DEBUG - VM ctest-TestBGPaasZone-32962351-43340246 is ready for SSH connections 2025-09-15 03:45:04,065 - INFO - Waiting for VM ctest-TestBGPaasZone-32962351-96200962 to be up.. 2025-09-15 03:45:04,173 - DEBUG - VM is in ACTIVE state now 2025-09-15 03:45:04,173 - INFO - VM name : ctest-TestBGPaasZone-32962351-96200962 2025-09-15 03:45:04,257 - DEBUG - VM ctest-TestBGPaasZone-32962351-96200962 ID is ec9cd782-79e3-449d-a32c-cde6051672bf 2025-09-15 03:45:04,257 - DEBUG - VM ctest-TestBGPaasZone-32962351-96200962 launched on Node an-jenkins-deploy-platform-ansible-os-4110-1 2025-09-15 03:45:04,338 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/ec9cd782-79e3-449d-a32c-cde6051672bf 2025-09-15 03:45:04,349 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/8c55a1cd-58fa-436c-8deb-441d6a94bd92 2025-09-15 03:45:05,516 - 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=1.82 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.62 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 = 1.618/1.721/1.824/0.103 ms') 2025-09-15 03:45:05,516 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-32962351-96200962 passed 2025-09-15 03:45:05,593 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:45:05,593 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-96200962, IP 81.177.73.5, Port 22 2025-09-15 03:45:05,765 - DEBUG - VM ctest-TestBGPaasZone-32962351-96200962 is ready for SSH connections 2025-09-15 03:45:05,899 - INFO - Created control node zone ctest-test-zone-72805033(025f2dbe-4707-4102-9756-03e98f752393) 2025-09-15 03:45:06,256 - INFO - Created control node zone ctest-test-zone-47902855(e1b9c776-447e-480d-bb1a-212a9373fda6) 2025-09-15 03:45:06,627 - INFO - Created control node zone ctest-test-zone-57210261(f47c8334-fd28-4e58-a058-cc3382aee4a5) 2025-09-15 03:45:06,907 - INFO - Creating BGPaaS ctest-TestBGPaasZone-32962351-62213341 2025-09-15 03:45:06,988 - INFO - BGPaaS: ctest-TestBGPaasZone-32962351-62213341(69844a4d-6e03-451f-a917-9959d9b546f3) 2025-09-15 03:45:06,988 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/69844a4d-6e03-451f-a917-9959d9b546f3 2025-09-15 03:45:07,289 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/69844a4d-6e03-451f-a917-9959d9b546f3 2025-09-15 03:45:07,329 - INFO - verify_in_api_server passed for BGPaaS obj 69844a4d-6e03-451f-a917-9959d9b546f3 2025-09-15 03:45:07,329 - INFO - BGPaaS(69844a4d-6e03-451f-a917-9959d9b546f3): verify_on_setup passed 2025-09-15 03:45:07,329 - INFO - We will configure BGP on the VM 2025-09-15 03:45:07,591 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:45:07,592 - INFO - Configuring BGP on ctest-TestBGPaasZone-32962351-57038105 2025-09-15 03:45:07,592 - 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 81.177.73.3; protocol bgp bgp_1{ local as 65000; neighbor 81.177.73.1 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 81.177.73.3; } protocol bfd { neighbor 81.177.73.1 local 81.177.73.3 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:45:07,592 - 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 81.177.73.3; protocol bgp bgp_1{ local as 65000; neighbor 81.177.73.1 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 81.177.73.3; } protocol bfd { neighbor 81.177.73.1 local 81.177.73.3 multihop on; } EOS 2025-09-15 03:45:19,165 - DEBUG - None 2025-09-15 03:45:19,165 - 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 81.177.73.3; protocol bgp bgp_1{ local as 65000; neighbor 81.177.73.1 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 81.177.73.3; } protocol bfd { neighbor 81.177.73.1 local 81.177.73.3 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:45:19,165 - 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 81.177.73.3; protocol bgp bgp_1{ local as 65000; neighbor 81.177.73.1 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 81.177.73.3; } protocol bfd { neighbor 81.177.73.1 local 81.177.73.3 multihop on; } EOS 2025-09-15 03:45:20,254 - DEBUG - None 2025-09-15 03:45:20,254 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:45:20,254 - DEBUG - service bird restart 2025-09-15 03:45:23,202 - DEBUG - bird stop/waiting bird start/running, process 1428 2025-09-15 03:45:23,202 - INFO - Attaching VMI 62fb3a39-690b-4c86-84ae-8a989b525cb1 to BGPaaS 69844a4d-6e03-451f-a917-9959d9b546f3 2025-09-15 03:45:23,280 - INFO - Attaching the VMI 032e1b2a-daeb-41bf-888b-8bcb5913801d to the BGPaaS 69844a4d-6e03-451f-a917-9959d9b546f3 object 2025-09-15 03:45:23,280 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 81.177.73.20 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:45:23,280 - DEBUG - sudo ip addr add 81.177.73.20 dev eth0 2025-09-15 03:45:24,568 - DEBUG - None 2025-09-15 03:45:24,627 - INFO - Creating BGPaaS ctest-TestBGPaasZone-32962351-30191200 2025-09-15 03:45:24,707 - INFO - BGPaaS: ctest-TestBGPaasZone-32962351-30191200(f51eeb7f-920c-42e4-b1e8-b9c4fa14e6de) 2025-09-15 03:45:24,708 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/f51eeb7f-920c-42e4-b1e8-b9c4fa14e6de 2025-09-15 03:45:24,718 - INFO - verify_in_api_server passed for BGPaaS obj f51eeb7f-920c-42e4-b1e8-b9c4fa14e6de 2025-09-15 03:45:24,718 - INFO - BGPaaS(f51eeb7f-920c-42e4-b1e8-b9c4fa14e6de): verify_on_setup passed 2025-09-15 03:45:24,718 - INFO - We will configure BGP on the VM 2025-09-15 03:45:24,971 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:45:24,971 - INFO - Configuring BGP on ctest-TestBGPaasZone-32962351-43340246 2025-09-15 03:45:24,972 - 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 81.177.73.4; protocol bgp bgp_1{ local as 65000; neighbor 81.177.73.2 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 81.177.73.4; } protocol bfd { neighbor 81.177.73.2 local 81.177.73.4 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.137, gateway password: c0ntrail123 2025-09-15 03:45:24,972 - 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 81.177.73.4; protocol bgp bgp_1{ local as 65000; neighbor 81.177.73.2 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 81.177.73.4; } protocol bfd { neighbor 81.177.73.2 local 81.177.73.4 multihop on; } EOS 2025-09-15 03:45:31,363 - DEBUG - None 2025-09-15 03:45:31,363 - 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 81.177.73.4; protocol bgp bgp_1{ local as 65000; neighbor 81.177.73.2 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 81.177.73.4; } protocol bfd { neighbor 81.177.73.2 local 81.177.73.4 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.137, gateway password: c0ntrail123 2025-09-15 03:45:31,363 - 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 81.177.73.4; protocol bgp bgp_1{ local as 65000; neighbor 81.177.73.2 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 81.177.73.4; } protocol bfd { neighbor 81.177.73.2 local 81.177.73.4 multihop on; } EOS 2025-09-15 03:45:31,993 - DEBUG - None 2025-09-15 03:45:31,993 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.137, gateway password: c0ntrail123 2025-09-15 03:45:31,993 - DEBUG - service bird restart 2025-09-15 03:45:34,066 - DEBUG - bird stop/waiting bird start/running, process 1442 2025-09-15 03:45:34,066 - INFO - Attaching VMI c55e97ad-b8ef-44ae-8159-39d31b5c695e to BGPaaS f51eeb7f-920c-42e4-b1e8-b9c4fa14e6de 2025-09-15 03:45:34,139 - INFO - Attaching the VMI 071c25c3-7400-402b-9c4c-66752d9ccfc7 to the BGPaaS f51eeb7f-920c-42e4-b1e8-b9c4fa14e6de object 2025-09-15 03:45:34,140 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 81.177.73.20 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.137, gateway password: c0ntrail123 2025-09-15 03:45:34,140 - DEBUG - sudo ip addr add 81.177.73.20 dev eth0 2025-09-15 03:45:34,898 - DEBUG - None 2025-09-15 03:45:34,899 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:45:34,915 - INFO - BGPaaS session seen in control-node 10.20.0.25 2025-09-15 03:45:34,921 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:45:34,939 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.14'] 2025-09-15 03:45:40,957 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.14'] 2025-09-15 03:45:46,974 - INFO - BGPaaS session seen in control-node 10.20.0.14 2025-09-15 03:45:46,982 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 81.177.73.20, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:45:46,982 - DEBUG - ping -s 56 -c 3 -W 1 81.177.73.20 2025-09-15 03:45:53,223 - DEBUG - PING 81.177.73.20 (81.177.73.20) 56(84) bytes of data. 64 bytes from 81.177.73.20: icmp_seq=1 ttl=64 time=8.53 ms 64 bytes from 81.177.73.20: icmp_seq=2 ttl=64 time=1.17 ms 64 bytes from 81.177.73.20: icmp_seq=3 ttl=64 time=1.73 ms --- 81.177.73.20 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.171/3.815/8.537/3.346 ms 2025-09-15 03:45:53,223 - INFO - Ping to IP 81.177.73.20 from VM ctest-TestBGPaasZone-32962351-96200962 passed 2025-09-15 03:45:54,035 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:45:54,035 - DEBUG - service bird restart 2025-09-15 03:45:55,567 - DEBUG - bird stop/waiting bird start/running, process 1464 2025-09-15 03:45:55,568 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.137, gateway password: c0ntrail123 2025-09-15 03:45:55,568 - DEBUG - service bird restart 2025-09-15 03:45:57,214 - DEBUG - bird stop/waiting bird start/running, process 1469 2025-09-15 03:45:57,214 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:45:57,214 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_BgpNeighborReq?domain=&ip_address= 2025-09-15 03:45:57,233 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.129'] 2025-09-15 03:46:03,234 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_BgpNeighborReq?domain=&ip_address= 2025-09-15 03:46:03,247 - INFO - BGPaaS session seen in control-node 10.20.0.129 2025-09-15 03:46:03,253 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:46:03,269 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.14'] 2025-09-15 03:46:09,290 - INFO - BGPaaS session seen in control-node 10.20.0.14 2025-09-15 03:46:09,301 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 81.177.73.20, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:46:09,301 - DEBUG - ping -s 56 -c 3 -W 1 81.177.73.20 2025-09-15 03:46:11,802 - DEBUG - PING 81.177.73.20 (81.177.73.20) 56(84) bytes of data. 64 bytes from 81.177.73.20: icmp_seq=1 ttl=64 time=4.66 ms 64 bytes from 81.177.73.20: icmp_seq=2 ttl=64 time=0.927 ms 64 bytes from 81.177.73.20: icmp_seq=3 ttl=64 time=0.765 ms --- 81.177.73.20 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2003ms rtt min/avg/max/mdev = 0.765/2.117/4.660/1.799 ms 2025-09-15 03:46:11,802 - INFO - Ping to IP 81.177.73.20 from VM ctest-TestBGPaasZone-32962351-96200962 passed 2025-09-15 03:46:12,103 - INFO - We will configure BGP on the VM 2025-09-15 03:46:12,339 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:46:12,340 - INFO - Configuring BGP on ctest-TestBGPaasZone-32962351-57038105 2025-09-15 03:46:12,340 - 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 81.177.73.3; protocol bgp bgp_1{ local as 65000; neighbor 81.177.73.1 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 81.177.73.3; } protocol bfd { neighbor 81.177.73.1 local 81.177.73.3 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:46:12,340 - 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 81.177.73.3; protocol bgp bgp_1{ local as 65000; neighbor 81.177.73.1 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 81.177.73.3; } protocol bfd { neighbor 81.177.73.1 local 81.177.73.3 multihop on; } EOS 2025-09-15 03:46:12,794 - DEBUG - None 2025-09-15 03:46:12,794 - 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 81.177.73.3; protocol bgp bgp_1{ local as 65000; neighbor 81.177.73.1 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 81.177.73.3; } protocol bfd { neighbor 81.177.73.1 local 81.177.73.3 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:46:12,794 - 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 81.177.73.3; protocol bgp bgp_1{ local as 65000; neighbor 81.177.73.1 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 81.177.73.3; } protocol bfd { neighbor 81.177.73.1 local 81.177.73.3 multihop on; } EOS 2025-09-15 03:46:13,286 - DEBUG - None 2025-09-15 03:46:13,287 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:46:13,287 - DEBUG - service bird restart 2025-09-15 03:46:14,992 - DEBUG - bird stop/waiting bird start/running, process 1496 2025-09-15 03:46:14,993 - INFO - Attaching VMI 62fb3a39-690b-4c86-84ae-8a989b525cb1 to BGPaaS 69844a4d-6e03-451f-a917-9959d9b546f3 2025-09-15 03:46:15,011 - INFO - Attaching the VMI 032e1b2a-daeb-41bf-888b-8bcb5913801d to the BGPaaS 69844a4d-6e03-451f-a917-9959d9b546f3 object 2025-09-15 03:46:15,011 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 81.177.73.20 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:46:15,011 - DEBUG - sudo ip addr add 81.177.73.20 dev eth0 2025-09-15 03:46:15,670 - DEBUG - RTNETLINK answers: File exists 2025-09-15 03:46:15,670 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:46:15,670 - DEBUG - service bird restart 2025-09-15 03:46:17,298 - DEBUG - bird stop/waiting bird start/running, process 1523 2025-09-15 03:46:17,298 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.137, gateway password: c0ntrail123 2025-09-15 03:46:17,298 - DEBUG - service bird restart 2025-09-15 03:46:19,119 - DEBUG - bird stop/waiting bird start/running, process 1491 2025-09-15 03:46:19,120 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:46:19,134 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25'] 2025-09-15 03:46:25,149 - INFO - BGPaaS session seen in control-node 10.20.0.25 2025-09-15 03:46:25,156 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:46:25,172 - INFO - BGPaaS session seen in control-node 10.20.0.14 2025-09-15 03:46:25,179 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 81.177.73.20, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:46:25,179 - DEBUG - ping -s 56 -c 3 -W 1 81.177.73.20 2025-09-15 03:46:27,639 - DEBUG - PING 81.177.73.20 (81.177.73.20) 56(84) bytes of data. 64 bytes from 81.177.73.20: icmp_seq=1 ttl=64 time=1.05 ms 64 bytes from 81.177.73.20: icmp_seq=2 ttl=64 time=1.06 ms 64 bytes from 81.177.73.20: icmp_seq=3 ttl=64 time=0.889 ms --- 81.177.73.20 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 0.889/1.001/1.066/0.087 ms 2025-09-15 03:46:27,639 - INFO - Ping to IP 81.177.73.20 from VM ctest-TestBGPaasZone-32962351-96200962 passed 2025-09-15 03:46:27,639 - INFO - Detaching VMI 62fb3a39-690b-4c86-84ae-8a989b525cb1 from BGPaaS 69844a4d-6e03-451f-a917-9959d9b546f3 2025-09-15 03:46:27,716 - INFO - Detaching VMI c55e97ad-b8ef-44ae-8159-39d31b5c695e from BGPaaS f51eeb7f-920c-42e4-b1e8-b9c4fa14e6de 2025-09-15 03:46:27,803 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-32962351-30191200(f51eeb7f-920c-42e4-b1e8-b9c4fa14e6de) 2025-09-15 03:46:27,858 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/f51eeb7f-920c-42e4-b1e8-b9c4fa14e6de 2025-09-15 03:46:27,866 - DEBUG - Response Code: 404 2025-09-15 03:46:27,866 - DEBUG - BGPaaS: f51eeb7f-920c-42e4-b1e8-b9c4fa14e6de deleted from api server 2025-09-15 03:46:27,866 - INFO - BGPaaS(f51eeb7f-920c-42e4-b1e8-b9c4fa14e6de): verify_on_cleanup passed 2025-09-15 03:46:27,866 - INFO - Detaching VMI 62fb3a39-690b-4c86-84ae-8a989b525cb1 from BGPaaS 69844a4d-6e03-451f-a917-9959d9b546f3 2025-09-15 03:46:27,884 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-32962351-62213341(69844a4d-6e03-451f-a917-9959d9b546f3) 2025-09-15 03:46:27,955 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/69844a4d-6e03-451f-a917-9959d9b546f3 2025-09-15 03:46:27,973 - DEBUG - Response Code: 404 2025-09-15 03:46:27,973 - DEBUG - BGPaaS: 69844a4d-6e03-451f-a917-9959d9b546f3 deleted from api server 2025-09-15 03:46:27,973 - INFO - BGPaaS(69844a4d-6e03-451f-a917-9959d9b546f3): verify_on_cleanup passed 2025-09-15 03:46:27,974 - INFO - Deleting Control node zone ctest-test-zone-57210261(f47c8334-fd28-4e58-a058-cc3382aee4a5) 2025-09-15 03:46:28,133 - INFO - Deleting Control node zone ctest-test-zone-47902855(e1b9c776-447e-480d-bb1a-212a9373fda6) 2025-09-15 03:46:28,290 - INFO - Deleting Control node zone ctest-test-zone-72805033(025f2dbe-4707-4102-9756-03e98f752393) 2025-09-15 03:46:28,376 - INFO - Deleting VM ctest-TestBGPaasZone-32962351-96200962 2025-09-15 03:46:28,490 - INFO - Deleting VM ctest-TestBGPaasZone-32962351-43340246 2025-09-15 03:46:28,560 - INFO - Deleting VM ctest-TestBGPaasZone-32962351-57038105 2025-09-15 03:46:28,634 - INFO - Deleting VN ctest-cnz_vn-97004119 2025-09-15 03:46:28,677 - DEBUG - VN ad6ee2a7-64bc-412b-a8ad-08f23fed269d still in use: Unable to complete operation on network ad6ee2a7-64bc-412b-a8ad-08f23fed269d. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-8c312726-0c65-4b85-a526-d4bf8014a158'] 2025-09-15 03:46:28,677 - WARNING - Deleting VN ctest-cnz_vn-97004119 failed..Will retry 2025-09-15 03:46:30,870 - DEBUG - Response for deleting network () 2025-09-15 03:46:32,161 - DEBUG - Skipping xmpp flap check
2025-09-15 03:46:32,161 - INFO - END TEST : test_bgp_control_node_zone : PASSED[0:04:10]
2025-09-15 03:46:32,162 - INFO - -------------------------------------------------------------------------------- 2025-09-15 03:46:32,165 - INFO - ================================================================================ 2025-09-15 03:46:32,165 - INFO - STARTING TEST : test_bgp_control_node_zones_from_single_vnf 2025-09-15 03:46:33,464 - DEBUG - Skipping xmpp flap check 2025-09-15 03:46:33,465 - INFO - Initial checks done. Running the testcase now 2025-09-15 03:46:33,465 - INFO - 2025-09-15 03:46:33,465 - INFO - executing bgp_control_zone agent restart test 2025-09-15 03:46:33,823 - DEBUG - Response for create_network : {'network': {'id': '28927392-7d5b-47cb-8411-f4e6e93aa8fc', 'name': 'ctest-cnz_vn-11189067', 'tenant_id': 'd2f09c4651554d838011c9bc6a72c7bf', 'project_id': 'd2f09c4651554d838011c9bc6a72c7bf', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBGPaasZone-32962351', 'ctest-cnz_vn-11189067'], 'port_security_enabled': True, 'description': ''}} 2025-09-15 03:46:34,051 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b66f23f6-7fa1-4d57-a7aa-47750b003d2b', 'name': '', 'tenant_id': 'd2f09c4651554d838011c9bc6a72c7bf', 'network_id': '28927392-7d5b-47cb-8411-f4e6e93aa8fc', 'ip_version': 4, 'cidr': '201.158.79.64/26', 'allocation_pools': [{'start': '201.158.79.66', 'end': '201.158.79.126'}], 'gateway_ip': '201.158.79.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '201.158.79.66', 'tags': [], 'project_id': 'd2f09c4651554d838011c9bc6a72c7bf'}} 2025-09-15 03:46:34,076 - INFO - Created VN ctest-cnz_vn-11189067 2025-09-15 03:46:34,092 - DEBUG - VN ctest-cnz_vn-11189067 UUID is 28927392-7d5b-47cb-8411-f4e6e93aa8fc 2025-09-15 03:46:35,366 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4110-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4110-2) 2025-09-15 03:46:36,852 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4110-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4110-1) 2025-09-15 03:46:38,347 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4110-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4110-2) 2025-09-15 03:46:38,347 - INFO - Waiting for VM ctest-TestBGPaasZone-32962351-85238619 to be up.. 2025-09-15 03:46:38,441 - DEBUG - VM is in ACTIVE state now 2025-09-15 03:46:38,441 - INFO - VM name : ctest-TestBGPaasZone-32962351-85238619 2025-09-15 03:46:38,530 - DEBUG - VM ctest-TestBGPaasZone-32962351-85238619 ID is 673cb3ba-0794-4850-832e-048938b9638b 2025-09-15 03:46:38,530 - DEBUG - VM ctest-TestBGPaasZone-32962351-85238619 launched on Node an-jenkins-deploy-platform-ansible-os-4110-2 2025-09-15 03:46:38,637 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/673cb3ba-0794-4850-832e-048938b9638b 2025-09-15 03:46:38,648 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/abf71bf4-364d-420d-8844-6d5b3b71bfdb 2025-09-15 03:46:41,821 - 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') 2025-09-15 03:46:41,821 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:46:41,837 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:46:45,917 - 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 1008ms') 2025-09-15 03:46:45,917 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:46:45,933 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:46:50,009 - 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 1012ms') 2025-09-15 03:46:50,009 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:46:50,023 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:46:54,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 1010ms') 2025-09-15 03:46:54,107 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:46:54,121 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:46:58,200 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-09-15 03:46:58,200 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:46:58,213 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:47:02,299 - 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') 2025-09-15 03:47:02,299 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:47:02,313 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:47:06,397 - 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') 2025-09-15 03:47:06,397 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:47:06,417 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:47:10,496 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2025-09-15 03:47:10,496 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:47:10,509 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:47:14,588 - 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') 2025-09-15 03:47:14,589 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:47:14,604 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:47:18,679 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms') 2025-09-15 03:47:18,679 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:47:18,692 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:47:22,778 - 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') 2025-09-15 03:47:22,778 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:47:22,792 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:47:26,875 - 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') 2025-09-15 03:47:26,875 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:47:26,917 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:47:31,008 - 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') 2025-09-15 03:47:31,008 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:47:31,024 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:47:35,097 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2025-09-15 03:47:35,097 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:47:35,113 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:47:39,191 - 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') 2025-09-15 03:47:39,192 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:47:39,209 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:47:43,290 - 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 1012ms') 2025-09-15 03:47:43,290 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:47:43,303 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:47:47,388 - 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') 2025-09-15 03:47:47,388 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:47:47,404 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:47:51,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 1009ms') 2025-09-15 03:47:51,479 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:47:51,492 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:47:55,581 - 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') 2025-09-15 03:47:55,581 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:47:55,596 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:47:59,669 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2025-09-15 03:47:59,669 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 failed! 2025-09-15 03:47:59,683 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-32962351:ctest-cnz_vn-11189067 is 201.158.79.65 and allocation pool is NOT set 2025-09-15 03:48:01,755 - 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.84 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.78 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.780/3.811/4.843/1.031 ms') 2025-09-15 03:48:01,756 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-85238619 passed 2025-09-15 03:48:01,837 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:48:01,837 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-85238619, IP 201.158.79.67, Port 22 2025-09-15 03:48:01,905 - 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': ''} 2025-09-15 03:48:02,087 - DEBUG - VM ctest-TestBGPaasZone-32962351-85238619 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:48:07,088 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:48:07,088 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-85238619, IP 201.158.79.67, Port 22 2025-09-15 03:48:07,156 - 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': ''} 2025-09-15 03:48:07,258 - DEBUG - VM ctest-TestBGPaasZone-32962351-85238619 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:48:12,259 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:48:12,259 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-85238619, IP 201.158.79.67, Port 22 2025-09-15 03:48:12,324 - 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': ''} 2025-09-15 03:48:12,408 - DEBUG - VM ctest-TestBGPaasZone-32962351-85238619 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:48:17,409 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:48:17,410 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-85238619, IP 201.158.79.67, Port 22 2025-09-15 03:48:17,467 - 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': ''} 2025-09-15 03:48:17,553 - DEBUG - VM ctest-TestBGPaasZone-32962351-85238619 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:48:22,554 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:48:22,554 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-85238619, IP 201.158.79.67, Port 22 2025-09-15 03:48:22,621 - 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': ''} 2025-09-15 03:48:22,748 - DEBUG - VM ctest-TestBGPaasZone-32962351-85238619 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:48:27,749 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:48:27,749 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-85238619, IP 201.158.79.67, Port 22 2025-09-15 03:48:27,820 - 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': ''} 2025-09-15 03:48:27,910 - DEBUG - VM ctest-TestBGPaasZone-32962351-85238619 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:48:32,911 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:48:32,911 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-85238619, IP 201.158.79.67, Port 22 2025-09-15 03:48:32,984 - 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': ''} 2025-09-15 03:48:33,080 - DEBUG - VM ctest-TestBGPaasZone-32962351-85238619 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:48:38,081 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:48:38,081 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-85238619, IP 201.158.79.67, Port 22 2025-09-15 03:48:38,149 - 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': ''} 2025-09-15 03:48:38,233 - DEBUG - VM ctest-TestBGPaasZone-32962351-85238619 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:48:43,233 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:48:43,233 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-85238619, IP 201.158.79.67, Port 22 2025-09-15 03:48:43,301 - 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': ''} 2025-09-15 03:48:43,383 - DEBUG - VM ctest-TestBGPaasZone-32962351-85238619 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:48:48,384 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:48:48,384 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-85238619, IP 201.158.79.67, Port 22 2025-09-15 03:48:48,452 - 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': ''} 2025-09-15 03:48:48,539 - DEBUG - VM ctest-TestBGPaasZone-32962351-85238619 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:48:53,539 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:48:53,539 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-85238619, IP 201.158.79.67, Port 22 2025-09-15 03:48:53,609 - 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': ''} 2025-09-15 03:48:53,695 - DEBUG - VM ctest-TestBGPaasZone-32962351-85238619 is NOT ready for SSH connections, VM status: ACTIVE 2025-09-15 03:48:58,696 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:48:58,696 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-85238619, IP 201.158.79.67, Port 22 2025-09-15 03:48:58,870 - DEBUG - VM ctest-TestBGPaasZone-32962351-85238619 is ready for SSH connections 2025-09-15 03:48:58,870 - INFO - Waiting for VM ctest-TestBGPaasZone-32962351-02905359 to be up.. 2025-09-15 03:48:58,958 - DEBUG - VM is in ACTIVE state now 2025-09-15 03:48:58,958 - INFO - VM name : ctest-TestBGPaasZone-32962351-02905359 2025-09-15 03:48:59,043 - DEBUG - VM ctest-TestBGPaasZone-32962351-02905359 ID is e1ad077a-955a-4d35-8ab1-1901379400e2 2025-09-15 03:48:59,044 - DEBUG - VM ctest-TestBGPaasZone-32962351-02905359 launched on Node an-jenkins-deploy-platform-ansible-os-4110-1 2025-09-15 03:48:59,129 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/e1ad077a-955a-4d35-8ab1-1901379400e2 2025-09-15 03:48:59,164 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/08c3dfba-804e-498d-8af6-fd6a0d9c9fcc 2025-09-15 03:49:00,326 - 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.28 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.399 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.399/1.838/3.278/1.439 ms') 2025-09-15 03:49:00,326 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-32962351-02905359 passed 2025-09-15 03:49:00,393 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:49:00,393 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-02905359, IP 201.158.79.68, Port 22 2025-09-15 03:49:00,551 - DEBUG - VM ctest-TestBGPaasZone-32962351-02905359 is ready for SSH connections 2025-09-15 03:49:00,551 - INFO - Waiting for VM ctest-TestBGPaasZone-32962351-75717564 to be up.. 2025-09-15 03:49:00,635 - DEBUG - VM is in ACTIVE state now 2025-09-15 03:49:00,635 - INFO - VM name : ctest-TestBGPaasZone-32962351-75717564 2025-09-15 03:49:00,716 - DEBUG - VM ctest-TestBGPaasZone-32962351-75717564 ID is 2ba5afa7-7a1a-42e2-9c9c-e28c7d51472f 2025-09-15 03:49:00,717 - DEBUG - VM ctest-TestBGPaasZone-32962351-75717564 launched on Node an-jenkins-deploy-platform-ansible-os-4110-2 2025-09-15 03:49:00,807 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/2ba5afa7-7a1a-42e2-9c9c-e28c7d51472f 2025-09-15 03:49:00,818 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/a0ae3eb5-e71c-407c-8567-7f5f335b51d8 2025-09-15 03:49:01,986 - 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.42 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.34 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 = 1.339/2.380/3.421/1.041 ms') 2025-09-15 03:49:01,986 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-32962351-75717564 passed 2025-09-15 03:49:02,061 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:49:02,061 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-32962351-75717564, IP 201.158.79.69, Port 22 2025-09-15 03:49:02,232 - DEBUG - VM ctest-TestBGPaasZone-32962351-75717564 is ready for SSH connections 2025-09-15 03:49:02,367 - INFO - Created control node zone ctest-test-zone-83698837(5e9f4274-dffc-4eb8-b11c-7677cbca5cf0) 2025-09-15 03:49:02,735 - INFO - Created control node zone ctest-test-zone-59290965(8b90885c-df6f-4940-9b75-bbdccdb1f6e1) 2025-09-15 03:49:03,088 - INFO - Created control node zone ctest-test-zone-00027356(ef200433-f3f0-46b2-9e49-387b613085e0) 2025-09-15 03:49:03,367 - INFO - Creating BGPaaS ctest-TestBGPaasZone-32962351-95693864 2025-09-15 03:49:03,444 - INFO - BGPaaS: ctest-TestBGPaasZone-32962351-95693864(6920e228-e16e-4026-9553-f6564a21d232) 2025-09-15 03:49:03,444 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/6920e228-e16e-4026-9553-f6564a21d232 2025-09-15 03:49:03,458 - INFO - verify_in_api_server passed for BGPaaS obj 6920e228-e16e-4026-9553-f6564a21d232 2025-09-15 03:49:03,458 - INFO - BGPaaS(6920e228-e16e-4026-9553-f6564a21d232): verify_on_setup passed 2025-09-15 03:49:03,458 - INFO - We will configure BGP on the VM 2025-09-15 03:49:03,892 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:49:03,892 - INFO - Configuring BGP on ctest-TestBGPaasZone-32962351-85238619 2025-09-15 03:49:03,892 - 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 201.158.79.67; protocol bgp bgp_1{ local as 65000; neighbor 201.158.79.65 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 201.158.79.67; } protocol bfd { neighbor 201.158.79.65 local 201.158.79.67 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.137, gateway password: c0ntrail123 2025-09-15 03:49:03,892 - 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 201.158.79.67; protocol bgp bgp_1{ local as 65000; neighbor 201.158.79.65 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 201.158.79.67; } protocol bfd { neighbor 201.158.79.65 local 201.158.79.67 multihop on; } EOS 2025-09-15 03:49:20,372 - DEBUG - None 2025-09-15 03:49:20,372 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS protocol bgp bgp_2{ local as 65000; neighbor 201.158.79.66 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 201.158.79.67; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.137, gateway password: c0ntrail123 2025-09-15 03:49:20,372 - DEBUG - cat >> /etc/bird/bird.conf << EOS protocol bgp bgp_2{ local as 65000; neighbor 201.158.79.66 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 201.158.79.67; } EOS 2025-09-15 03:49:21,397 - DEBUG - None 2025-09-15 03:49:21,398 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.137, gateway password: c0ntrail123 2025-09-15 03:49:21,398 - DEBUG - service bird restart 2025-09-15 03:49:24,941 - DEBUG - bird stop/waiting bird start/running, process 1437 2025-09-15 03:49:24,942 - INFO - Attaching VMI abf71bf4-364d-420d-8844-6d5b3b71bfdb to BGPaaS 6920e228-e16e-4026-9553-f6564a21d232 2025-09-15 03:49:25,020 - INFO - Attaching the VMI 673cb3ba-0794-4850-832e-048938b9638b to the BGPaaS 6920e228-e16e-4026-9553-f6564a21d232 object 2025-09-15 03:49:25,020 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 201.158.79.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.137, gateway password: c0ntrail123 2025-09-15 03:49:25,020 - DEBUG - sudo ip addr add 201.158.79.84 dev eth0 2025-09-15 03:49:26,430 - DEBUG - None 2025-09-15 03:49:26,495 - INFO - Creating BGPaaS ctest-TestBGPaasZone-32962351-40047619 2025-09-15 03:49:26,575 - INFO - BGPaaS: ctest-TestBGPaasZone-32962351-40047619(4206897a-8427-46be-b48e-5487bdb87445) 2025-09-15 03:49:26,576 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/4206897a-8427-46be-b48e-5487bdb87445 2025-09-15 03:49:26,588 - INFO - verify_in_api_server passed for BGPaaS obj 4206897a-8427-46be-b48e-5487bdb87445 2025-09-15 03:49:26,588 - INFO - BGPaaS(4206897a-8427-46be-b48e-5487bdb87445): verify_on_setup passed 2025-09-15 03:49:26,588 - INFO - We will configure BGP on the VM 2025-09-15 03:49:27,027 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:49:27,028 - INFO - Configuring BGP on ctest-TestBGPaasZone-32962351-02905359 2025-09-15 03:49:27,028 - 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 201.158.79.68; protocol bgp bgp_1{ local as 65000; neighbor 201.158.79.65 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 201.158.79.68; } protocol bfd { neighbor 201.158.79.65 local 201.158.79.68 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:49:27,028 - 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 201.158.79.68; protocol bgp bgp_1{ local as 65000; neighbor 201.158.79.65 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 201.158.79.68; } protocol bfd { neighbor 201.158.79.65 local 201.158.79.68 multihop on; } EOS 2025-09-15 03:49:33,793 - DEBUG - None 2025-09-15 03:49:33,793 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS protocol bgp bgp_2{ local as 65000; neighbor 201.158.79.66 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 201.158.79.68; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:49:33,793 - DEBUG - cat >> /etc/bird/bird.conf << EOS protocol bgp bgp_2{ local as 65000; neighbor 201.158.79.66 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 201.158.79.68; } EOS 2025-09-15 03:49:34,286 - DEBUG - None 2025-09-15 03:49:34,287 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:49:34,287 - DEBUG - service bird restart 2025-09-15 03:49:35,866 - DEBUG - bird stop/waiting bird start/running, process 1438 2025-09-15 03:49:35,866 - INFO - Attaching VMI 08c3dfba-804e-498d-8af6-fd6a0d9c9fcc to BGPaaS 4206897a-8427-46be-b48e-5487bdb87445 2025-09-15 03:49:35,939 - INFO - Attaching the VMI e1ad077a-955a-4d35-8ab1-1901379400e2 to the BGPaaS 4206897a-8427-46be-b48e-5487bdb87445 object 2025-09-15 03:49:35,939 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 201.158.79.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123 2025-09-15 03:49:35,940 - DEBUG - sudo ip addr add 201.158.79.84 dev eth0 2025-09-15 03:49:36,556 - DEBUG - None 2025-09-15 03:49:36,556 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:49:36,578 - INFO - BGPaaS session seen in control-node 10.20.0.25 2025-09-15 03:49:36,578 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:49:36,594 - INFO - BGPaaS session seen in control-node 10.20.0.14 2025-09-15 03:49:36,600 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:49:36,626 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25'] 2025-09-15 03:49:42,642 - INFO - BGPaaS session seen in control-node 10.20.0.25 2025-09-15 03:49:42,642 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:49:42,657 - INFO - BGPaaS session seen in control-node 10.20.0.14 2025-09-15 03:49:42,664 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 201.158.79.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.137, gateway password: c0ntrail123 2025-09-15 03:49:42,664 - DEBUG - ping -s 56 -c 3 -W 1 201.158.79.84 2025-09-15 03:49:49,991 - DEBUG - PING 201.158.79.84 (201.158.79.84) 56(84) bytes of data. 64 bytes from 201.158.79.84: icmp_seq=1 ttl=64 time=9.89 ms 64 bytes from 201.158.79.84: icmp_seq=2 ttl=64 time=1.16 ms 64 bytes from 201.158.79.84: icmp_seq=3 ttl=64 time=1.25 ms --- 201.158.79.84 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2009ms rtt min/avg/max/mdev = 1.167/4.105/9.898/4.096 ms 2025-09-15 03:49:49,991 - INFO - Ping to IP 201.158.79.84 from VM ctest-TestBGPaasZone-32962351-75717564 passed 2025-09-15 03:49:49,992 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:49:50,014 - INFO - BGPaaS session seen in control-node 10.20.0.25 2025-09-15 03:49:50,015 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:49:50,031 - INFO - BGPaaS session seen in control-node 10.20.0.14 2025-09-15 03:49:50,038 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:49:50,050 - INFO - BGPaaS session seen in control-node 10.20.0.25 2025-09-15 03:49:50,050 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-15 03:49:50,066 - INFO - BGPaaS session seen in control-node 10.20.0.14 2025-09-15 03:49:50,073 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 201.158.79.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.137, gateway password: c0ntrail123 2025-09-15 03:49:50,073 - DEBUG - ping -s 56 -c 3 -W 1 201.158.79.84 2025-09-15 03:49:52,596 - DEBUG - PING 201.158.79.84 (201.158.79.84) 56(84) bytes of data. 64 bytes from 201.158.79.84: icmp_seq=1 ttl=64 time=4.27 ms 64 bytes from 201.158.79.84: icmp_seq=2 ttl=64 time=1.46 ms 64 bytes from 201.158.79.84: icmp_seq=3 ttl=64 time=1.83 ms --- 201.158.79.84 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2005ms rtt min/avg/max/mdev = 1.463/2.523/4.277/1.250 ms 2025-09-15 03:49:52,597 - INFO - Ping to IP 201.158.79.84 from VM ctest-TestBGPaasZone-32962351-75717564 passed 2025-09-15 03:49:52,597 - INFO - Detaching VMI 08c3dfba-804e-498d-8af6-fd6a0d9c9fcc from BGPaaS 4206897a-8427-46be-b48e-5487bdb87445 2025-09-15 03:49:52,671 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-32962351-40047619(4206897a-8427-46be-b48e-5487bdb87445) 2025-09-15 03:49:52,747 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/4206897a-8427-46be-b48e-5487bdb87445 2025-09-15 03:49:52,754 - DEBUG - Response Code: 404 2025-09-15 03:49:52,754 - DEBUG - BGPaaS: 4206897a-8427-46be-b48e-5487bdb87445 deleted from api server 2025-09-15 03:49:52,754 - INFO - BGPaaS(4206897a-8427-46be-b48e-5487bdb87445): verify_on_cleanup passed 2025-09-15 03:49:52,754 - INFO - Detaching VMI abf71bf4-364d-420d-8844-6d5b3b71bfdb from BGPaaS 6920e228-e16e-4026-9553-f6564a21d232 2025-09-15 03:49:52,860 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-32962351-95693864(6920e228-e16e-4026-9553-f6564a21d232) 2025-09-15 03:49:52,941 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/6920e228-e16e-4026-9553-f6564a21d232 2025-09-15 03:49:52,949 - DEBUG - Response Code: 404 2025-09-15 03:49:52,950 - DEBUG - BGPaaS: 6920e228-e16e-4026-9553-f6564a21d232 deleted from api server 2025-09-15 03:49:52,950 - INFO - BGPaaS(6920e228-e16e-4026-9553-f6564a21d232): verify_on_cleanup passed 2025-09-15 03:49:52,950 - INFO - Deleting Control node zone ctest-test-zone-00027356(ef200433-f3f0-46b2-9e49-387b613085e0) 2025-09-15 03:49:53,123 - INFO - Deleting Control node zone ctest-test-zone-59290965(8b90885c-df6f-4940-9b75-bbdccdb1f6e1) 2025-09-15 03:49:53,269 - INFO - Deleting Control node zone ctest-test-zone-83698837(5e9f4274-dffc-4eb8-b11c-7677cbca5cf0) 2025-09-15 03:49:53,408 - INFO - Deleting VM ctest-TestBGPaasZone-32962351-75717564 2025-09-15 03:49:53,510 - INFO - Deleting VM ctest-TestBGPaasZone-32962351-02905359 2025-09-15 03:49:53,589 - INFO - Deleting VM ctest-TestBGPaasZone-32962351-85238619 2025-09-15 03:49:53,725 - INFO - Deleting VN ctest-cnz_vn-11189067 2025-09-15 03:49:53,775 - DEBUG - VN 28927392-7d5b-47cb-8411-f4e6e93aa8fc still in use: Unable to complete operation on network 28927392-7d5b-47cb-8411-f4e6e93aa8fc. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-49881797-198a-4fa5-99c1-7844386d5ee2'] 2025-09-15 03:49:53,775 - WARNING - Deleting VN ctest-cnz_vn-11189067 failed..Will retry 2025-09-15 03:49:56,026 - DEBUG - Response for deleting network () 2025-09-15 03:49:57,298 - DEBUG - Skipping xmpp flap check
2025-09-15 03:49:57,299 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:25]
2025-09-15 03:49:57,299 - INFO - -------------------------------------------------------------------------------- 2025-09-15 03:49:58,073 - INFO - Deleted project: ctest-TestBGPaasZone-32962351, ID : d2f09c46-5155-4d83-8011-c9bc6a72c7bf