2025-07-24 05:12:03,475 - INFO - Domain Default found not creating
2025-07-24 05:12:03,712 - INFO - Project ctest-TestBGPaasZone-57862338 not found, creating it
2025-07-24 05:12:04,631 - INFO - Created Project:ctest-TestBGPaasZone-57862338, ID : 59bd121b-70e2-4a0f-a0c0-bf43b12f5a24
2025-07-24 05:12:06,425 - DEBUG - Services list from nova: [, , , ]
2025-07-24 05:12:06,425 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-3712-1', 'an-jenkins-deploy-platform-ansible-os-3712-2']}
2025-07-24 05:12:06,427 - INFO - ================================================================================
2025-07-24 05:12:06,427 - INFO - STARTING TEST : test_bgp_control_node_zone
2025-07-24 05:12:07,101 - DEBUG - Skipping xmpp flap check
2025-07-24 05:12:07,102 - INFO - Initial checks done. Running the testcase now
2025-07-24 05:12:07,102 - INFO -
2025-07-24 05:12:07,102 - INFO - executing bgp_control_zone test
2025-07-24 05:12:07,901 - DEBUG - Response for create_network : {'network': {'id': '454a6ffe-e78d-46a8-a8cb-06032e9f3c09', 'name': 'ctest-cnz_vn-94832083', 'tenant_id': '59bd121b70e24a0fa0c0bf43b12f5a24', 'project_id': '59bd121b70e24a0fa0c0bf43b12f5a24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBGPaasZone-57862338', 'ctest-cnz_vn-94832083'], 'port_security_enabled': True, 'description': ''}}
2025-07-24 05:12:08,244 - DEBUG - Response for create_subnet : {'subnet': {'id': '67cf722d-c1ea-4780-bbe7-103c083f537c', 'name': '', 'tenant_id': '59bd121b70e24a0fa0c0bf43b12f5a24', 'network_id': '454a6ffe-e78d-46a8-a8cb-06032e9f3c09', 'ip_version': 4, 'cidr': '51.104.173.192/26', 'allocation_pools': [{'start': '51.104.173.194', 'end': '51.104.173.254'}], 'gateway_ip': '51.104.173.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '51.104.173.194', 'tags': [], 'project_id': '59bd121b70e24a0fa0c0bf43b12f5a24'}}
2025-07-24 05:12:08,272 - INFO - Created VN ctest-cnz_vn-94832083
2025-07-24 05:12:08,287 - DEBUG - VN ctest-cnz_vn-94832083 UUID is 454a6ffe-e78d-46a8-a8cb-06032e9f3c09
2025-07-24 05:12:09,884 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3712-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3712-1)
2025-07-24 05:12:11,506 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3712-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3712-2)
2025-07-24 05:12:13,527 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3712-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3712-1)
2025-07-24 05:12:13,527 - INFO - Waiting for VM ctest-TestBGPaasZone-57862338-82637184 to be up..
2025-07-24 05:12:13,683 - DEBUG - VM is in ACTIVE state now
2025-07-24 05:12:13,684 - INFO - VM name : ctest-TestBGPaasZone-57862338-82637184
2025-07-24 05:12:13,851 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 ID is a094468d-459f-4abf-8a5e-4d400e649f0b
2025-07-24 05:12:13,906 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 launched on Node an-jenkins-deploy-platform-ansible-os-3712-1
2025-07-24 05:12:14,077 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/a094468d-459f-4abf-8a5e-4d400e649f0b
2025-07-24 05:12:14,504 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/a094468d-459f-4abf-8a5e-4d400e649f0b
2025-07-24 05:12:14,565 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/f68fce6f-f2b2-4fbf-903b-943c68a8a198
2025-07-24 05:12:17,864 - 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-07-24 05:12:17,864 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:12:17,886 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:12:21,955 - 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-07-24 05:12:21,956 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:12:21,976 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:12:26,019 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1021ms')
2025-07-24 05:12:26,019 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:12:26,040 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:12:30,084 - 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')
2025-07-24 05:12:30,084 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:12:30,104 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:12:34,151 - 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-07-24 05:12:34,151 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:12:34,176 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:12:38,217 - 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-07-24 05:12:38,217 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:12:38,238 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:12:42,281 - 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-07-24 05:12:42,281 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:12:42,305 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:12:46,340 - 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-07-24 05:12:46,340 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:12:46,361 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:12:50,406 - 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 1024ms')
2025-07-24 05:12:50,406 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:12:50,427 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:12:54,471 - 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-07-24 05:12:54,472 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:12:54,497 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:12:58,539 - 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-07-24 05:12:58,539 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:12:58,559 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:13:02,600 - 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-07-24 05:13:02,600 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:13:02,631 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:13:06,665 - 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')
2025-07-24 05:13:06,666 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:13:06,686 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:13:10,730 - 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-07-24 05:13:10,730 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:13:10,759 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:13:14,785 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1007ms')
2025-07-24 05:13:14,785 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:13:14,806 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:13:18,852 - 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-07-24 05:13:18,852 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:13:18,875 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:13:22,921 - 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-07-24 05:13:22,922 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:13:22,943 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:13:26,989 - 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-07-24 05:13:26,989 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:13:27,010 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:13:31,045 - 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-07-24 05:13:31,045 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:13:31,064 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:13:35,113 - 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')
2025-07-24 05:13:35,113 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:13:35,132 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:13:39,173 - 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-07-24 05:13:39,173 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:13:39,194 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:13:43,233 - 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-07-24 05:13:43,233 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:13:43,256 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:13:47,304 - 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')
2025-07-24 05:13:47,304 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:13:47,323 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:13:51,363 - 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-07-24 05:13:51,363 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:13:51,386 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:13:55,429 - 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')
2025-07-24 05:13:55,429 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:13:55,451 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:13:59,490 - 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-07-24 05:13:59,491 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:13:59,511 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:14:03,562 - 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-07-24 05:14:03,562 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 failed!
2025-07-24 05:14:03,588 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-94832083 is 51.104.173.193 and allocation pool is NOT set
2025-07-24 05:14:07,626 - 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=4.48 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1004ms\r\nrtt min/avg/max/mdev = 4.478/4.478/4.478/0.000 ms')
2025-07-24 05:14:07,626 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-82637184 passed
2025-07-24 05:14:07,755 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:14:07,755 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-82637184, IP 51.104.173.195, Port 22
2025-07-24 05:14:07,782 - 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-07-24 05:14:07,891 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:14:12,892 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:14:12,892 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-82637184, IP 51.104.173.195, Port 22
2025-07-24 05:14:12,939 - 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-07-24 05:14:13,057 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:14:18,058 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:14:18,058 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-82637184, IP 51.104.173.195, Port 22
2025-07-24 05:14:18,084 - 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-07-24 05:14:18,191 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:14:23,192 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:14:23,192 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-82637184, IP 51.104.173.195, Port 22
2025-07-24 05:14:23,218 - 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-07-24 05:14:23,326 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:14:28,326 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:14:28,327 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-82637184, IP 51.104.173.195, Port 22
2025-07-24 05:14:28,353 - 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-07-24 05:14:28,459 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:14:33,460 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:14:33,460 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-82637184, IP 51.104.173.195, Port 22
2025-07-24 05:14:33,487 - 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-07-24 05:14:33,601 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:14:38,602 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:14:38,602 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-82637184, IP 51.104.173.195, Port 22
2025-07-24 05:14:38,628 - 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-07-24 05:14:38,737 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:14:43,739 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:14:43,739 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-82637184, IP 51.104.173.195, Port 22
2025-07-24 05:14:43,796 - 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-07-24 05:14:43,904 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:14:48,905 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:14:48,905 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-82637184, IP 51.104.173.195, Port 22
2025-07-24 05:14:48,931 - 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-07-24 05:14:49,034 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:14:54,035 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:14:54,035 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-82637184, IP 51.104.173.195, Port 22
2025-07-24 05:14:54,061 - 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-07-24 05:14:54,174 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:14:59,174 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:14:59,175 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-82637184, IP 51.104.173.195, Port 22
2025-07-24 05:14:59,201 - 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-07-24 05:14:59,304 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:15:04,305 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:15:04,305 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-82637184, IP 51.104.173.195, Port 22
2025-07-24 05:15:04,330 - 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-07-24 05:15:04,440 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:15:09,441 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:15:09,442 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-82637184, IP 51.104.173.195, Port 22
2025-07-24 05:15:09,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-07-24 05:15:09,581 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:15:14,583 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:15:14,583 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-82637184, IP 51.104.173.195, Port 22
2025-07-24 05:15:14,608 - 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-07-24 05:15:14,713 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:15:19,714 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:15:19,714 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-82637184, IP 51.104.173.195, Port 22
2025-07-24 05:15:19,803 - DEBUG - VM ctest-TestBGPaasZone-57862338-82637184 is ready for SSH connections
2025-07-24 05:15:19,803 - INFO - Waiting for VM ctest-TestBGPaasZone-57862338-64331155 to be up..
2025-07-24 05:15:19,907 - DEBUG - VM is in ACTIVE state now
2025-07-24 05:15:19,908 - INFO - VM name : ctest-TestBGPaasZone-57862338-64331155
2025-07-24 05:15:20,006 - DEBUG - VM ctest-TestBGPaasZone-57862338-64331155 ID is d0a70945-ad57-4257-9856-8dce7b780f00
2025-07-24 05:15:20,006 - DEBUG - VM ctest-TestBGPaasZone-57862338-64331155 launched on Node an-jenkins-deploy-platform-ansible-os-3712-2
2025-07-24 05:15:20,123 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/d0a70945-ad57-4257-9856-8dce7b780f00
2025-07-24 05:15:20,137 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/9fdaf3c6-6734-4a9d-8854-1db02b7e3377
2025-07-24 05:15:21,338 - 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.06 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.756 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.756/2.408/4.060/1.652 ms')
2025-07-24 05:15:21,338 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-64331155 passed
2025-07-24 05:15:21,463 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:15:21,463 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-64331155, IP 51.104.173.196, Port 22
2025-07-24 05:15:21,537 - DEBUG - VM ctest-TestBGPaasZone-57862338-64331155 is ready for SSH connections
2025-07-24 05:15:21,537 - INFO - Waiting for VM ctest-TestBGPaasZone-57862338-56004025 to be up..
2025-07-24 05:15:21,627 - DEBUG - VM is in ACTIVE state now
2025-07-24 05:15:21,627 - INFO - VM name : ctest-TestBGPaasZone-57862338-56004025
2025-07-24 05:15:21,741 - DEBUG - VM ctest-TestBGPaasZone-57862338-56004025 ID is 6b6a3293-ae09-41fc-9959-15e32086fd28
2025-07-24 05:15:21,741 - DEBUG - VM ctest-TestBGPaasZone-57862338-56004025 launched on Node an-jenkins-deploy-platform-ansible-os-3712-1
2025-07-24 05:15:21,862 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/6b6a3293-ae09-41fc-9959-15e32086fd28
2025-07-24 05:15:21,876 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/054ad690-c507-4945-8dc1-618da8cb8dd8
2025-07-24 05:15:23,092 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=6.00 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.958 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.958/3.479/6.000/2.521 ms')
2025-07-24 05:15:23,092 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-57862338-56004025 passed
2025-07-24 05:15:23,219 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:15:23,219 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-56004025, IP 51.104.173.197, Port 22
2025-07-24 05:15:23,243 - 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': ''}
2025-07-24 05:15:23,343 - DEBUG - VM ctest-TestBGPaasZone-57862338-56004025 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:15:28,344 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:15:28,344 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-56004025, IP 51.104.173.197, Port 22
2025-07-24 05:15:28,432 - DEBUG - VM ctest-TestBGPaasZone-57862338-56004025 is ready for SSH connections
2025-07-24 05:15:28,592 - INFO - Created control node zone ctest-test-zone-14650300(6261b9cf-3bdf-4258-a2cf-efc76487e74c)
2025-07-24 05:15:28,995 - INFO - Created control node zone ctest-test-zone-47460615(b08a2ece-1e65-4533-9fa8-254a0af6f236)
2025-07-24 05:15:29,380 - INFO - Created control node zone ctest-test-zone-70664062(83c0e51f-3764-4da1-bebd-a179eb952430)
2025-07-24 05:15:29,696 - INFO - Creating BGPaaS ctest-TestBGPaasZone-57862338-90580724
2025-07-24 05:15:29,784 - INFO - BGPaaS: ctest-TestBGPaasZone-57862338-90580724(b7ccbad4-ba71-4bf9-8a81-611c60120cfd)
2025-07-24 05:15:29,784 - DEBUG - Requesting: http://10.0.0.19:8082/bgp-as-a-service/b7ccbad4-ba71-4bf9-8a81-611c60120cfd
2025-07-24 05:15:30,097 - DEBUG - Requesting: http://10.0.0.19:8082/bgp-as-a-service/b7ccbad4-ba71-4bf9-8a81-611c60120cfd
2025-07-24 05:15:30,138 - INFO - verify_in_api_server passed for BGPaaS obj b7ccbad4-ba71-4bf9-8a81-611c60120cfd
2025-07-24 05:15:30,138 - INFO - BGPaaS(b7ccbad4-ba71-4bf9-8a81-611c60120cfd): verify_on_setup passed
2025-07-24 05:15:30,138 - INFO - We will configure BGP on the VM
2025-07-24 05:15:30,432 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:15:30,432 - INFO - Configuring BGP on ctest-TestBGPaasZone-57862338-82637184
2025-07-24 05:15:30,432 - 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 51.104.173.195;
protocol bgp bgp_1{
local as 65000;
neighbor 51.104.173.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 51.104.173.195;
}
protocol bfd {
neighbor 51.104.173.193 local 51.104.173.195 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:15:30,433 - 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 51.104.173.195;
protocol bgp bgp_1{
local as 65000;
neighbor 51.104.173.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 51.104.173.195;
}
protocol bfd {
neighbor 51.104.173.193 local 51.104.173.195 multihop on;
}
EOS
2025-07-24 05:15:51,609 - DEBUG - None
2025-07-24 05:15:51,610 - 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 51.104.173.195;
protocol bgp bgp_1{
local as 65000;
neighbor 51.104.173.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 51.104.173.195;
}
protocol bfd {
neighbor 51.104.173.193 local 51.104.173.195 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:15:51,610 - 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 51.104.173.195;
protocol bgp bgp_1{
local as 65000;
neighbor 51.104.173.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 51.104.173.195;
}
protocol bfd {
neighbor 51.104.173.193 local 51.104.173.195 multihop on;
}
EOS
2025-07-24 05:15:53,682 - DEBUG - None
2025-07-24 05:15:53,682 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:15:53,682 - DEBUG - service bird restart
2025-07-24 05:15:59,050 - DEBUG - bird stop/waiting
bird start/running, process 1434
2025-07-24 05:15:59,050 - INFO - Attaching VMI f68fce6f-f2b2-4fbf-903b-943c68a8a198 to BGPaaS b7ccbad4-ba71-4bf9-8a81-611c60120cfd
2025-07-24 05:15:59,152 - INFO - Attaching the VMI a094468d-459f-4abf-8a5e-4d400e649f0b to the BGPaaS b7ccbad4-ba71-4bf9-8a81-611c60120cfd object
2025-07-24 05:15:59,152 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 51.104.173.212 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:15:59,152 - DEBUG - sudo ip addr add 51.104.173.212 dev eth0
2025-07-24 05:16:00,037 - DEBUG - None
2025-07-24 05:16:00,112 - INFO - Creating BGPaaS ctest-TestBGPaasZone-57862338-64601267
2025-07-24 05:16:00,216 - INFO - BGPaaS: ctest-TestBGPaasZone-57862338-64601267(e8d22702-4dd5-4bae-b767-cbcb14ea50fb)
2025-07-24 05:16:00,216 - DEBUG - Requesting: http://10.0.0.19:8082/bgp-as-a-service/e8d22702-4dd5-4bae-b767-cbcb14ea50fb
2025-07-24 05:16:00,233 - INFO - verify_in_api_server passed for BGPaaS obj e8d22702-4dd5-4bae-b767-cbcb14ea50fb
2025-07-24 05:16:00,233 - INFO - BGPaaS(e8d22702-4dd5-4bae-b767-cbcb14ea50fb): verify_on_setup passed
2025-07-24 05:16:00,233 - INFO - We will configure BGP on the VM
2025-07-24 05:16:00,556 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:16:00,556 - INFO - Configuring BGP on ctest-TestBGPaasZone-57862338-64331155
2025-07-24 05:16:00,556 - 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 51.104.173.196;
protocol bgp bgp_1{
local as 65000;
neighbor 51.104.173.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 51.104.173.196;
}
protocol bfd {
neighbor 51.104.173.194 local 51.104.173.196 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2025-07-24 05:16:00,557 - 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 51.104.173.196;
protocol bgp bgp_1{
local as 65000;
neighbor 51.104.173.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 51.104.173.196;
}
protocol bfd {
neighbor 51.104.173.194 local 51.104.173.196 multihop on;
}
EOS
2025-07-24 05:16:05,773 - DEBUG - None
2025-07-24 05:16:05,773 - 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 51.104.173.196;
protocol bgp bgp_1{
local as 65000;
neighbor 51.104.173.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 51.104.173.196;
}
protocol bfd {
neighbor 51.104.173.194 local 51.104.173.196 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2025-07-24 05:16:05,773 - 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 51.104.173.196;
protocol bgp bgp_1{
local as 65000;
neighbor 51.104.173.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 51.104.173.196;
}
protocol bfd {
neighbor 51.104.173.194 local 51.104.173.196 multihop on;
}
EOS
2025-07-24 05:16:06,338 - DEBUG - None
2025-07-24 05:16:06,339 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2025-07-24 05:16:06,339 - DEBUG - service bird restart
2025-07-24 05:16:08,397 - DEBUG - bird stop/waiting
bird start/running, process 1441
2025-07-24 05:16:08,397 - INFO - Attaching VMI 9fdaf3c6-6734-4a9d-8854-1db02b7e3377 to BGPaaS e8d22702-4dd5-4bae-b767-cbcb14ea50fb
2025-07-24 05:16:08,484 - INFO - Attaching the VMI d0a70945-ad57-4257-9856-8dce7b780f00 to the BGPaaS e8d22702-4dd5-4bae-b767-cbcb14ea50fb object
2025-07-24 05:16:08,484 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 51.104.173.212 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2025-07-24 05:16:08,484 - DEBUG - sudo ip addr add 51.104.173.212 dev eth0
2025-07-24 05:16:09,231 - DEBUG - None
2025-07-24 05:16:09,231 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:16:09,231 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:16:09,248 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-07-24 05:16:09,256 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:16:09,257 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:16:09,278 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25']
2025-07-24 05:16:15,280 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:16:15,299 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-07-24 05:16:15,308 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 51.104.173.212, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:16:15,309 - DEBUG - ping -s 56 -c 3 -W 1 51.104.173.212
2025-07-24 05:16:23,276 - DEBUG - PING 51.104.173.212 (51.104.173.212) 56(84) bytes of data.
64 bytes from 51.104.173.212: icmp_seq=1 ttl=64 time=9.63 ms
64 bytes from 51.104.173.212: icmp_seq=2 ttl=64 time=1.25 ms
64 bytes from 51.104.173.212: icmp_seq=3 ttl=64 time=1.52 ms
--- 51.104.173.212 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.252/4.136/9.632/3.887 ms
2025-07-24 05:16:23,276 - INFO - Ping to IP 51.104.173.212 from VM ctest-TestBGPaasZone-57862338-56004025 passed
2025-07-24 05:16:24,200 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:16:24,200 - DEBUG - service bird restart
2025-07-24 05:16:26,358 - DEBUG - bird stop/waiting
bird start/running, process 1461
2025-07-24 05:16:26,359 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2025-07-24 05:16:26,359 - DEBUG - service bird restart
2025-07-24 05:16:28,303 - DEBUG - bird stop/waiting
bird start/running, process 1468
2025-07-24 05:16:28,303 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:16:28,304 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:16:28,325 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.129']
2025-07-24 05:16:34,327 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:16:34,347 - INFO - BGPaaS session seen in control-node 10.20.0.129
2025-07-24 05:16:34,356 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:16:34,356 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:16:34,373 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-07-24 05:16:34,381 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 51.104.173.212, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:16:34,381 - DEBUG - ping -s 56 -c 3 -W 1 51.104.173.212
2025-07-24 05:16:37,008 - DEBUG - PING 51.104.173.212 (51.104.173.212) 56(84) bytes of data.
64 bytes from 51.104.173.212: icmp_seq=1 ttl=64 time=1.99 ms
64 bytes from 51.104.173.212: icmp_seq=2 ttl=64 time=2.19 ms
64 bytes from 51.104.173.212: icmp_seq=3 ttl=64 time=1.07 ms
--- 51.104.173.212 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.074/1.753/2.192/0.486 ms
2025-07-24 05:16:37,008 - INFO - Ping to IP 51.104.173.212 from VM ctest-TestBGPaasZone-57862338-56004025 passed
2025-07-24 05:16:37,363 - INFO - We will configure BGP on the VM
2025-07-24 05:16:37,612 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:16:37,612 - INFO - Configuring BGP on ctest-TestBGPaasZone-57862338-82637184
2025-07-24 05:16:37,612 - 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 51.104.173.195;
protocol bgp bgp_1{
local as 65000;
neighbor 51.104.173.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 51.104.173.195;
}
protocol bfd {
neighbor 51.104.173.193 local 51.104.173.195 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:16:37,612 - 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 51.104.173.195;
protocol bgp bgp_1{
local as 65000;
neighbor 51.104.173.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 51.104.173.195;
}
protocol bfd {
neighbor 51.104.173.193 local 51.104.173.195 multihop on;
}
EOS
2025-07-24 05:16:38,243 - DEBUG - None
2025-07-24 05:16:38,243 - 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 51.104.173.195;
protocol bgp bgp_1{
local as 65000;
neighbor 51.104.173.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 51.104.173.195;
}
protocol bfd {
neighbor 51.104.173.193 local 51.104.173.195 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:16:38,243 - 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 51.104.173.195;
protocol bgp bgp_1{
local as 65000;
neighbor 51.104.173.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 51.104.173.195;
}
protocol bfd {
neighbor 51.104.173.193 local 51.104.173.195 multihop on;
}
EOS
2025-07-24 05:16:38,890 - DEBUG - None
2025-07-24 05:16:38,891 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:16:38,891 - DEBUG - service bird restart
2025-07-24 05:16:41,095 - DEBUG - bird stop/waiting
bird start/running, process 1493
2025-07-24 05:16:41,096 - INFO - Attaching VMI f68fce6f-f2b2-4fbf-903b-943c68a8a198 to BGPaaS b7ccbad4-ba71-4bf9-8a81-611c60120cfd
2025-07-24 05:16:41,122 - INFO - Attaching the VMI a094468d-459f-4abf-8a5e-4d400e649f0b to the BGPaaS b7ccbad4-ba71-4bf9-8a81-611c60120cfd object
2025-07-24 05:16:41,123 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 51.104.173.212 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:16:41,123 - DEBUG - sudo ip addr add 51.104.173.212 dev eth0
2025-07-24 05:16:42,033 - DEBUG - RTNETLINK answers: File exists
2025-07-24 05:16:42,033 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:16:42,033 - DEBUG - service bird restart
2025-07-24 05:16:44,243 - DEBUG - bird stop/waiting
bird start/running, process 1520
2025-07-24 05:16:44,243 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2025-07-24 05:16:44,243 - DEBUG - service bird restart
2025-07-24 05:16:46,275 - DEBUG - bird stop/waiting
bird start/running, process 1490
2025-07-24 05:16:46,275 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:16:46,275 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:16:46,296 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.17']
2025-07-24 05:16:52,297 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:16:52,320 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-07-24 05:16:52,330 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:16:52,330 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:16:52,350 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-07-24 05:16:52,358 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 51.104.173.212, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:16:52,358 - DEBUG - ping -s 56 -c 3 -W 1 51.104.173.212
2025-07-24 05:16:54,978 - DEBUG - PING 51.104.173.212 (51.104.173.212) 56(84) bytes of data.
64 bytes from 51.104.173.212: icmp_seq=1 ttl=64 time=2.98 ms
64 bytes from 51.104.173.212: icmp_seq=2 ttl=64 time=1.17 ms
64 bytes from 51.104.173.212: icmp_seq=3 ttl=64 time=1.52 ms
--- 51.104.173.212 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.172/1.892/2.982/0.785 ms
2025-07-24 05:16:54,978 - INFO - Ping to IP 51.104.173.212 from VM ctest-TestBGPaasZone-57862338-56004025 passed
2025-07-24 05:16:54,978 - INFO - Detaching VMI f68fce6f-f2b2-4fbf-903b-943c68a8a198 from BGPaaS b7ccbad4-ba71-4bf9-8a81-611c60120cfd
2025-07-24 05:16:55,064 - INFO - Detaching VMI 9fdaf3c6-6734-4a9d-8854-1db02b7e3377 from BGPaaS e8d22702-4dd5-4bae-b767-cbcb14ea50fb
2025-07-24 05:16:55,185 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-57862338-64601267(e8d22702-4dd5-4bae-b767-cbcb14ea50fb)
2025-07-24 05:16:55,290 - DEBUG - Requesting: http://10.0.0.19:8082/bgp-as-a-service/e8d22702-4dd5-4bae-b767-cbcb14ea50fb
2025-07-24 05:16:55,308 - DEBUG - Response Code: 404
2025-07-24 05:16:55,308 - DEBUG - BGPaaS: e8d22702-4dd5-4bae-b767-cbcb14ea50fb deleted from api server
2025-07-24 05:16:55,308 - INFO - BGPaaS(e8d22702-4dd5-4bae-b767-cbcb14ea50fb): verify_on_cleanup passed
2025-07-24 05:16:55,308 - INFO - Detaching VMI f68fce6f-f2b2-4fbf-903b-943c68a8a198 from BGPaaS b7ccbad4-ba71-4bf9-8a81-611c60120cfd
2025-07-24 05:16:55,356 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-57862338-90580724(b7ccbad4-ba71-4bf9-8a81-611c60120cfd)
2025-07-24 05:16:55,462 - DEBUG - Requesting: http://10.0.0.19:8082/bgp-as-a-service/b7ccbad4-ba71-4bf9-8a81-611c60120cfd
2025-07-24 05:16:55,471 - DEBUG - Response Code: 404
2025-07-24 05:16:55,472 - DEBUG - BGPaaS: b7ccbad4-ba71-4bf9-8a81-611c60120cfd deleted from api server
2025-07-24 05:16:55,472 - INFO - BGPaaS(b7ccbad4-ba71-4bf9-8a81-611c60120cfd): verify_on_cleanup passed
2025-07-24 05:16:55,472 - INFO - Deleting Control node zone ctest-test-zone-70664062(83c0e51f-3764-4da1-bebd-a179eb952430)
2025-07-24 05:16:55,662 - INFO - Deleting Control node zone ctest-test-zone-47460615(b08a2ece-1e65-4533-9fa8-254a0af6f236)
2025-07-24 05:16:55,829 - INFO - Deleting Control node zone ctest-test-zone-14650300(6261b9cf-3bdf-4258-a2cf-efc76487e74c)
2025-07-24 05:16:55,922 - INFO - Deleting VM ctest-TestBGPaasZone-57862338-56004025
2025-07-24 05:16:56,017 - INFO - Deleting VM ctest-TestBGPaasZone-57862338-64331155
2025-07-24 05:16:56,093 - INFO - Deleting VM ctest-TestBGPaasZone-57862338-82637184
2025-07-24 05:16:56,215 - INFO - Deleting VN ctest-cnz_vn-94832083
2025-07-24 05:16:56,275 - DEBUG - VN 454a6ffe-e78d-46a8-a8cb-06032e9f3c09 still in use: Unable to complete operation on network 454a6ffe-e78d-46a8-a8cb-06032e9f3c09. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-af3fa575-5161-40de-92ac-49e1b8fd17ae']
2025-07-24 05:16:56,276 - WARNING - Deleting VN ctest-cnz_vn-94832083 failed..Will retry
2025-07-24 05:16:58,577 - DEBUG - Response for deleting network ()
2025-07-24 05:16:59,938 - DEBUG - Skipping xmpp flap check
2025-07-24 05:16:59,938 - INFO - END TEST : test_bgp_control_node_zone : PASSED[0:04:53]
2025-07-24 05:16:59,938 - INFO - --------------------------------------------------------------------------------
2025-07-24 05:16:59,942 - INFO - ================================================================================
2025-07-24 05:16:59,942 - INFO - STARTING TEST : test_bgp_control_node_zones_from_single_vnf
2025-07-24 05:17:01,279 - DEBUG - Skipping xmpp flap check
2025-07-24 05:17:01,280 - INFO - Initial checks done. Running the testcase now
2025-07-24 05:17:01,280 - INFO -
2025-07-24 05:17:01,280 - INFO - executing bgp_control_zone agent restart test
2025-07-24 05:17:01,763 - DEBUG - Response for create_network : {'network': {'id': 'b8bf5d43-debe-4834-86ae-a3df95372aae', 'name': 'ctest-cnz_vn-82670844', 'tenant_id': '59bd121b70e24a0fa0c0bf43b12f5a24', 'project_id': '59bd121b70e24a0fa0c0bf43b12f5a24', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBGPaasZone-57862338', 'ctest-cnz_vn-82670844'], 'port_security_enabled': True, 'description': ''}}
2025-07-24 05:17:02,137 - DEBUG - Response for create_subnet : {'subnet': {'id': 'add5b3e3-3250-48bd-b60d-500e5114c190', 'name': '', 'tenant_id': '59bd121b70e24a0fa0c0bf43b12f5a24', 'network_id': 'b8bf5d43-debe-4834-86ae-a3df95372aae', 'ip_version': 4, 'cidr': '165.142.168.128/26', 'allocation_pools': [{'start': '165.142.168.130', 'end': '165.142.168.190'}], 'gateway_ip': '165.142.168.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '165.142.168.130', 'tags': [], 'project_id': '59bd121b70e24a0fa0c0bf43b12f5a24'}}
2025-07-24 05:17:02,170 - INFO - Created VN ctest-cnz_vn-82670844
2025-07-24 05:17:02,190 - DEBUG - VN ctest-cnz_vn-82670844 UUID is b8bf5d43-debe-4834-86ae-a3df95372aae
2025-07-24 05:17:03,804 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3712-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3712-2)
2025-07-24 05:17:05,647 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3712-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3712-1)
2025-07-24 05:17:07,796 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3712-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3712-2)
2025-07-24 05:17:07,797 - INFO - Waiting for VM ctest-TestBGPaasZone-57862338-36406129 to be up..
2025-07-24 05:17:07,929 - DEBUG - VM is in ACTIVE state now
2025-07-24 05:17:07,930 - INFO - VM name : ctest-TestBGPaasZone-57862338-36406129
2025-07-24 05:17:08,040 - DEBUG - VM ctest-TestBGPaasZone-57862338-36406129 ID is d9afc1d2-9a79-4465-9622-495f14ebd732
2025-07-24 05:17:08,040 - DEBUG - VM ctest-TestBGPaasZone-57862338-36406129 launched on Node an-jenkins-deploy-platform-ansible-os-3712-2
2025-07-24 05:17:08,189 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/d9afc1d2-9a79-4465-9622-495f14ebd732
2025-07-24 05:17:08,204 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/3879d971-e1a1-4b28-9073-8cecc8a16604
2025-07-24 05:17:11,560 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1007ms')
2025-07-24 05:17:11,560 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:17:11,581 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:17:15,655 - 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-07-24 05:17:15,655 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:17:15,676 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:17:19,753 - 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-07-24 05:17:19,753 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:17:19,773 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:17:23,845 - 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-07-24 05:17:23,845 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:17:23,867 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:17:27,941 - 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-07-24 05:17:27,942 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:17:27,963 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:17:32,039 - 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-07-24 05:17:32,039 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:17:32,061 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:17:36,135 - 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')
2025-07-24 05:17:36,135 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:17:36,156 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:17:40,228 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1005ms')
2025-07-24 05:17:40,228 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:17:40,247 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:17:44,329 - 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-07-24 05:17:44,329 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:17:44,352 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:17:48,419 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1007ms')
2025-07-24 05:17:48,419 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:17:48,444 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:17:52,520 - 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-07-24 05:17:52,521 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:17:52,539 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:17:56,614 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1007ms')
2025-07-24 05:17:56,614 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:17:56,638 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:18:00,712 - 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-07-24 05:18:00,712 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:18:00,735 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:18:04,801 - 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-07-24 05:18:04,801 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:18:04,822 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:18:08,904 - 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-07-24 05:18:08,904 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:18:08,928 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:18:13,001 - 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')
2025-07-24 05:18:13,001 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:18:13,022 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:18:17,095 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1007ms')
2025-07-24 05:18:17,095 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:18:17,115 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:18:21,195 - 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-07-24 05:18:21,195 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:18:21,219 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:18:25,315 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1032ms')
2025-07-24 05:18:25,315 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:18:25,337 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:18:29,413 - 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-07-24 05:18:29,413 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:18:29,436 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:18:33,510 - 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-07-24 05:18:33,510 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 failed!
2025-07-24 05:18:33,531 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-57862338:ctest-cnz_vn-82670844 is 165.142.168.129 and allocation pool is NOT set
2025-07-24 05:18:35,604 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=6.42 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.665/4.541/6.417/1.876 ms')
2025-07-24 05:18:35,604 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-36406129 passed
2025-07-24 05:18:35,728 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:18:35,728 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-36406129, IP 165.142.168.131, Port 22
2025-07-24 05:18:35,797 - 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-07-24 05:18:35,921 - DEBUG - VM ctest-TestBGPaasZone-57862338-36406129 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:18:40,921 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:18:40,922 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-36406129, IP 165.142.168.131, Port 22
2025-07-24 05:18:41,005 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-07-24 05:18:41,110 - DEBUG - VM ctest-TestBGPaasZone-57862338-36406129 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:18:46,111 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:18:46,111 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-36406129, IP 165.142.168.131, Port 22
2025-07-24 05:18:46,182 - 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-07-24 05:18:46,304 - DEBUG - VM ctest-TestBGPaasZone-57862338-36406129 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:18:51,304 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:18:51,304 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-36406129, IP 165.142.168.131, Port 22
2025-07-24 05:18:51,378 - 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-07-24 05:18:51,482 - DEBUG - VM ctest-TestBGPaasZone-57862338-36406129 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:18:56,483 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:18:56,484 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-36406129, IP 165.142.168.131, Port 22
2025-07-24 05:18:56,554 - 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-07-24 05:18:56,659 - DEBUG - VM ctest-TestBGPaasZone-57862338-36406129 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:19:01,659 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:19:01,660 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-36406129, IP 165.142.168.131, Port 22
2025-07-24 05:19:01,729 - 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-07-24 05:19:01,869 - DEBUG - VM ctest-TestBGPaasZone-57862338-36406129 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:19:06,870 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:19:06,871 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-36406129, IP 165.142.168.131, Port 22
2025-07-24 05:19:06,937 - 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-07-24 05:19:07,076 - DEBUG - VM ctest-TestBGPaasZone-57862338-36406129 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:19:12,077 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:19:12,077 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-36406129, IP 165.142.168.131, Port 22
2025-07-24 05:19:12,146 - 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-07-24 05:19:12,268 - DEBUG - VM ctest-TestBGPaasZone-57862338-36406129 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:19:17,269 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:19:17,269 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-36406129, IP 165.142.168.131, Port 22
2025-07-24 05:19:17,338 - 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-07-24 05:19:17,444 - DEBUG - VM ctest-TestBGPaasZone-57862338-36406129 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:19:22,444 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:19:22,445 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-36406129, IP 165.142.168.131, Port 22
2025-07-24 05:19:22,514 - 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-07-24 05:19:22,624 - DEBUG - VM ctest-TestBGPaasZone-57862338-36406129 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:19:27,625 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:19:27,625 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-36406129, IP 165.142.168.131, Port 22
2025-07-24 05:19:27,694 - 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-07-24 05:19:27,790 - DEBUG - VM ctest-TestBGPaasZone-57862338-36406129 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:19:32,792 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:19:32,792 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-36406129, IP 165.142.168.131, Port 22
2025-07-24 05:19:32,862 - 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-07-24 05:19:32,950 - DEBUG - VM ctest-TestBGPaasZone-57862338-36406129 is NOT ready for SSH connections, VM status: ACTIVE
2025-07-24 05:19:37,951 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:19:37,951 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-36406129, IP 165.142.168.131, Port 22
2025-07-24 05:19:38,121 - DEBUG - VM ctest-TestBGPaasZone-57862338-36406129 is ready for SSH connections
2025-07-24 05:19:38,122 - INFO - Waiting for VM ctest-TestBGPaasZone-57862338-02114896 to be up..
2025-07-24 05:19:38,218 - DEBUG - VM is in ACTIVE state now
2025-07-24 05:19:38,218 - INFO - VM name : ctest-TestBGPaasZone-57862338-02114896
2025-07-24 05:19:38,317 - DEBUG - VM ctest-TestBGPaasZone-57862338-02114896 ID is f5c7a846-a8da-4b9c-ab69-937fbda58dd9
2025-07-24 05:19:38,317 - DEBUG - VM ctest-TestBGPaasZone-57862338-02114896 launched on Node an-jenkins-deploy-platform-ansible-os-3712-1
2025-07-24 05:19:38,415 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/f5c7a846-a8da-4b9c-ab69-937fbda58dd9
2025-07-24 05:19:38,458 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/c8b34a48-5e0f-45f5-a700-54a5318bad0c
2025-07-24 05:19:39,738 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=5.16 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.74 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 = 1.741/3.448/5.155/1.707 ms')
2025-07-24 05:19:39,738 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-57862338-02114896 passed
2025-07-24 05:19:39,865 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:19:39,865 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-02114896, IP 165.142.168.132, Port 22
2025-07-24 05:19:40,038 - DEBUG - VM ctest-TestBGPaasZone-57862338-02114896 is ready for SSH connections
2025-07-24 05:19:40,038 - INFO - Waiting for VM ctest-TestBGPaasZone-57862338-23119867 to be up..
2025-07-24 05:19:40,135 - DEBUG - VM is in ACTIVE state now
2025-07-24 05:19:40,135 - INFO - VM name : ctest-TestBGPaasZone-57862338-23119867
2025-07-24 05:19:40,225 - DEBUG - VM ctest-TestBGPaasZone-57862338-23119867 ID is 866976af-167c-4ef3-abd2-6f61219584c4
2025-07-24 05:19:40,225 - DEBUG - VM ctest-TestBGPaasZone-57862338-23119867 launched on Node an-jenkins-deploy-platform-ansible-os-3712-2
2025-07-24 05:19:40,323 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/866976af-167c-4ef3-abd2-6f61219584c4
2025-07-24 05:19:40,337 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/9827d70c-6aff-4a59-b79a-16e89cb4e480
2025-07-24 05:19:41,579 - 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=4.46 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.50 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.499/2.977/4.455/1.478 ms')
2025-07-24 05:19:41,579 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-57862338-23119867 passed
2025-07-24 05:19:41,705 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:19:41,705 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57862338-23119867, IP 165.142.168.133, Port 22
2025-07-24 05:19:41,881 - DEBUG - VM ctest-TestBGPaasZone-57862338-23119867 is ready for SSH connections
2025-07-24 05:19:42,040 - INFO - Created control node zone ctest-test-zone-08178017(b3b47d6e-1f1e-41a9-bed5-dd0da41b82f4)
2025-07-24 05:19:42,428 - INFO - Created control node zone ctest-test-zone-42330520(cd224224-2941-41ec-bff1-6fa65614ab9d)
2025-07-24 05:19:42,828 - INFO - Created control node zone ctest-test-zone-08122082(ebb64756-81f7-4d76-833a-2ff44c2c47df)
2025-07-24 05:19:43,140 - INFO - Creating BGPaaS ctest-TestBGPaasZone-57862338-44529559
2025-07-24 05:19:43,228 - INFO - BGPaaS: ctest-TestBGPaasZone-57862338-44529559(b37fa4c9-5918-4484-9ba1-876f0435baa8)
2025-07-24 05:19:43,228 - DEBUG - Requesting: http://10.0.0.19:8082/bgp-as-a-service/b37fa4c9-5918-4484-9ba1-876f0435baa8
2025-07-24 05:19:43,240 - INFO - verify_in_api_server passed for BGPaaS obj b37fa4c9-5918-4484-9ba1-876f0435baa8
2025-07-24 05:19:43,240 - INFO - BGPaaS(b37fa4c9-5918-4484-9ba1-876f0435baa8): verify_on_setup passed
2025-07-24 05:19:43,240 - INFO - We will configure BGP on the VM
2025-07-24 05:19:43,720 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:19:43,720 - INFO - Configuring BGP on ctest-TestBGPaasZone-57862338-36406129
2025-07-24 05:19:43,720 - 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 165.142.168.131;
protocol bgp bgp_1{
local as 65000;
neighbor 165.142.168.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 165.142.168.131;
}
protocol bfd {
neighbor 165.142.168.129 local 165.142.168.131 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2025-07-24 05:19:43,720 - 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 165.142.168.131;
protocol bgp bgp_1{
local as 65000;
neighbor 165.142.168.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 165.142.168.131;
}
protocol bfd {
neighbor 165.142.168.129 local 165.142.168.131 multihop on;
}
EOS
2025-07-24 05:20:00,246 - DEBUG - None
2025-07-24 05:20:00,247 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 165.142.168.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 165.142.168.131;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2025-07-24 05:20:00,247 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 165.142.168.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 165.142.168.131;
}
EOS
2025-07-24 05:20:01,512 - DEBUG - None
2025-07-24 05:20:01,512 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2025-07-24 05:20:01,512 - DEBUG - service bird restart
2025-07-24 05:20:05,560 - DEBUG - bird stop/waiting
bird start/running, process 1435
2025-07-24 05:20:05,560 - INFO - Attaching VMI 3879d971-e1a1-4b28-9073-8cecc8a16604 to BGPaaS b37fa4c9-5918-4484-9ba1-876f0435baa8
2025-07-24 05:20:05,648 - INFO - Attaching the VMI d9afc1d2-9a79-4465-9622-495f14ebd732 to the BGPaaS b37fa4c9-5918-4484-9ba1-876f0435baa8 object
2025-07-24 05:20:05,648 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 165.142.168.148 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2025-07-24 05:20:05,648 - DEBUG - sudo ip addr add 165.142.168.148 dev eth0
2025-07-24 05:20:07,363 - DEBUG - None
2025-07-24 05:20:07,439 - INFO - Creating BGPaaS ctest-TestBGPaasZone-57862338-69666963
2025-07-24 05:20:07,528 - INFO - BGPaaS: ctest-TestBGPaasZone-57862338-69666963(3fa6735e-f88f-441d-be08-53c1365654b0)
2025-07-24 05:20:07,528 - DEBUG - Requesting: http://10.0.0.19:8082/bgp-as-a-service/3fa6735e-f88f-441d-be08-53c1365654b0
2025-07-24 05:20:07,545 - INFO - verify_in_api_server passed for BGPaaS obj 3fa6735e-f88f-441d-be08-53c1365654b0
2025-07-24 05:20:07,545 - INFO - BGPaaS(3fa6735e-f88f-441d-be08-53c1365654b0): verify_on_setup passed
2025-07-24 05:20:07,545 - INFO - We will configure BGP on the VM
2025-07-24 05:20:08,028 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:20:08,028 - INFO - Configuring BGP on ctest-TestBGPaasZone-57862338-02114896
2025-07-24 05:20:08,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 165.142.168.132;
protocol bgp bgp_1{
local as 65000;
neighbor 165.142.168.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 165.142.168.132;
}
protocol bfd {
neighbor 165.142.168.129 local 165.142.168.132 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:20:08,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 165.142.168.132;
protocol bgp bgp_1{
local as 65000;
neighbor 165.142.168.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 165.142.168.132;
}
protocol bfd {
neighbor 165.142.168.129 local 165.142.168.132 multihop on;
}
EOS
2025-07-24 05:20:14,968 - DEBUG - None
2025-07-24 05:20:14,968 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 165.142.168.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 165.142.168.132;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:20:14,968 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 165.142.168.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 165.142.168.132;
}
EOS
2025-07-24 05:20:15,629 - DEBUG - None
2025-07-24 05:20:15,629 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:20:15,630 - DEBUG - service bird restart
2025-07-24 05:20:17,990 - DEBUG - bird stop/waiting
bird start/running, process 1437
2025-07-24 05:20:17,990 - INFO - Attaching VMI c8b34a48-5e0f-45f5-a700-54a5318bad0c to BGPaaS 3fa6735e-f88f-441d-be08-53c1365654b0
2025-07-24 05:20:18,080 - INFO - Attaching the VMI f5c7a846-a8da-4b9c-ab69-937fbda58dd9 to the BGPaaS 3fa6735e-f88f-441d-be08-53c1365654b0 object
2025-07-24 05:20:18,080 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 165.142.168.148 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-07-24 05:20:18,081 - DEBUG - sudo ip addr add 165.142.168.148 dev eth0
2025-07-24 05:20:18,930 - DEBUG - None
2025-07-24 05:20:18,930 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:20:18,930 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:20:18,951 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-07-24 05:20:18,951 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:20:18,952 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:20:18,974 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-07-24 05:20:18,982 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:20:18,982 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:20:19,000 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.17']
2025-07-24 05:20:25,003 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:20:25,029 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.17']
2025-07-24 05:20:31,032 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:20:31,052 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-07-24 05:20:31,052 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:20:31,053 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:20:31,071 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-07-24 05:20:31,079 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 165.142.168.148, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2025-07-24 05:20:31,079 - DEBUG - ping -s 56 -c 3 -W 1 165.142.168.148
2025-07-24 05:20:38,135 - DEBUG - PING 165.142.168.148 (165.142.168.148) 56(84) bytes of data.
64 bytes from 165.142.168.148: icmp_seq=1 ttl=64 time=11.0 ms
64 bytes from 165.142.168.148: icmp_seq=2 ttl=64 time=1.65 ms
64 bytes from 165.142.168.148: icmp_seq=3 ttl=64 time=1.67 ms
--- 165.142.168.148 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2006ms
rtt min/avg/max/mdev = 1.655/4.805/11.087/4.442 ms
2025-07-24 05:20:38,135 - INFO - Ping to IP 165.142.168.148 from VM ctest-TestBGPaasZone-57862338-23119867 passed
2025-07-24 05:20:38,135 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:20:38,135 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:20:38,157 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-07-24 05:20:38,158 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:20:38,158 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:20:38,179 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-07-24 05:20:38,188 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:20:38,188 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:20:38,209 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-07-24 05:20:38,210 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-24 05:20:38,210 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-07-24 05:20:38,229 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-07-24 05:20:38,238 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 165.142.168.148, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2025-07-24 05:20:38,238 - DEBUG - ping -s 56 -c 3 -W 1 165.142.168.148
2025-07-24 05:20:40,806 - DEBUG - PING 165.142.168.148 (165.142.168.148) 56(84) bytes of data.
64 bytes from 165.142.168.148: icmp_seq=1 ttl=64 time=5.62 ms
64 bytes from 165.142.168.148: icmp_seq=2 ttl=64 time=1.36 ms
64 bytes from 165.142.168.148: icmp_seq=3 ttl=64 time=1.06 ms
--- 165.142.168.148 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.066/2.687/5.629/2.083 ms
2025-07-24 05:20:40,806 - INFO - Ping to IP 165.142.168.148 from VM ctest-TestBGPaasZone-57862338-23119867 passed
2025-07-24 05:20:40,806 - INFO - Detaching VMI c8b34a48-5e0f-45f5-a700-54a5318bad0c from BGPaaS 3fa6735e-f88f-441d-be08-53c1365654b0
2025-07-24 05:20:40,900 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-57862338-69666963(3fa6735e-f88f-441d-be08-53c1365654b0)
2025-07-24 05:20:41,019 - DEBUG - Requesting: http://10.0.0.19:8082/bgp-as-a-service/3fa6735e-f88f-441d-be08-53c1365654b0
2025-07-24 05:20:41,101 - DEBUG - Response Code: 404
2025-07-24 05:20:41,102 - DEBUG - BGPaaS: 3fa6735e-f88f-441d-be08-53c1365654b0 deleted from api server
2025-07-24 05:20:41,102 - INFO - BGPaaS(3fa6735e-f88f-441d-be08-53c1365654b0): verify_on_cleanup passed
2025-07-24 05:20:41,102 - INFO - Detaching VMI 3879d971-e1a1-4b28-9073-8cecc8a16604 from BGPaaS b37fa4c9-5918-4484-9ba1-876f0435baa8
2025-07-24 05:20:41,208 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-57862338-44529559(b37fa4c9-5918-4484-9ba1-876f0435baa8)
2025-07-24 05:20:41,330 - DEBUG - Requesting: http://10.0.0.19:8082/bgp-as-a-service/b37fa4c9-5918-4484-9ba1-876f0435baa8
2025-07-24 05:20:41,345 - DEBUG - Response Code: 404
2025-07-24 05:20:41,345 - DEBUG - BGPaaS: b37fa4c9-5918-4484-9ba1-876f0435baa8 deleted from api server
2025-07-24 05:20:41,345 - INFO - BGPaaS(b37fa4c9-5918-4484-9ba1-876f0435baa8): verify_on_cleanup passed
2025-07-24 05:20:41,345 - INFO - Deleting Control node zone ctest-test-zone-08122082(ebb64756-81f7-4d76-833a-2ff44c2c47df)
2025-07-24 05:20:41,547 - INFO - Deleting Control node zone ctest-test-zone-42330520(cd224224-2941-41ec-bff1-6fa65614ab9d)
2025-07-24 05:20:41,734 - INFO - Deleting Control node zone ctest-test-zone-08178017(b3b47d6e-1f1e-41a9-bed5-dd0da41b82f4)
2025-07-24 05:20:41,907 - INFO - Deleting VM ctest-TestBGPaasZone-57862338-23119867
2025-07-24 05:20:42,016 - INFO - Deleting VM ctest-TestBGPaasZone-57862338-02114896
2025-07-24 05:20:42,110 - INFO - Deleting VM ctest-TestBGPaasZone-57862338-36406129
2025-07-24 05:20:42,191 - INFO - Deleting VN ctest-cnz_vn-82670844
2025-07-24 05:20:42,248 - DEBUG - VN b8bf5d43-debe-4834-86ae-a3df95372aae still in use: Unable to complete operation on network b8bf5d43-debe-4834-86ae-a3df95372aae. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-0c1a20b3-8104-4a6f-9726-d2d80d6de5dd']
2025-07-24 05:20:42,248 - WARNING - Deleting VN ctest-cnz_vn-82670844 failed..Will retry
2025-07-24 05:20:44,484 - DEBUG - Response for deleting network ()
2025-07-24 05:20:45,807 - DEBUG - Skipping xmpp flap check
2025-07-24 05:20:45,807 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:46]
2025-07-24 05:20:45,807 - INFO - --------------------------------------------------------------------------------
2025-07-24 05:20:46,692 - INFO - Deleted project: ctest-TestBGPaasZone-57862338, ID : 59bd121b-70e2-4a0f-a0c0-bf43b12f5a24