2026-10-26 04:34:32,155 - INFO - Domain Default found not creating 2026-10-26 04:34:32,345 - INFO - Project ctest-TestBGPaasZone-48219217 not found, creating it 2026-10-26 04:34:32,878 - INFO - Created Project:ctest-TestBGPaasZone-48219217, ID : 470861c3-d731-4055-a708-820cbaeed638 2026-10-26 04:34:34,647 - DEBUG - Services list from nova: [, , , ] 2026-10-26 04:34:34,647 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-6403-1', 'an-jenkins-deploy-platform-ansible-os-6403-2']} 2026-10-26 04:34:34,650 - INFO - ================================================================================ 2026-10-26 04:34:34,650 - INFO - STARTING TEST : test_bgp_control_node_zone 2026-10-26 04:34:35,930 - DEBUG - Skipping xmpp flap check 2026-10-26 04:34:35,930 - INFO - Initial checks done. Running the testcase now 2026-10-26 04:34:35,930 - INFO - 2026-10-26 04:34:35,930 - INFO - executing bgp_control_zone test 2026-10-26 04:34:36,708 - DEBUG - Response for create_network : {'network': {'id': '99b29872-0143-40bd-ad86-41ff4d249ea7', 'name': 'ctest-cnz_vn-11056015', 'tenant_id': '470861c3d7314055a708820cbaeed638', 'project_id': '470861c3d7314055a708820cbaeed638', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBGPaasZone-48219217', 'ctest-cnz_vn-11056015'], 'port_security_enabled': True, 'description': ''}} 2026-10-26 04:34:37,012 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd49530c3-c70c-4900-a39b-56cc8f95463d', 'name': '', 'tenant_id': '470861c3d7314055a708820cbaeed638', 'network_id': '99b29872-0143-40bd-ad86-41ff4d249ea7', 'ip_version': 4, 'cidr': '91.119.105.0/26', 'allocation_pools': [{'start': '91.119.105.2', 'end': '91.119.105.62'}], 'gateway_ip': '91.119.105.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '91.119.105.2', 'tags': [], 'project_id': '470861c3d7314055a708820cbaeed638'}} 2026-10-26 04:34:37,038 - INFO - Created VN ctest-cnz_vn-11056015 2026-10-26 04:34:37,050 - DEBUG - VN ctest-cnz_vn-11056015 UUID is 99b29872-0143-40bd-ad86-41ff4d249ea7 2026-10-26 04:34:37,794 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6403-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6403-1) 2026-10-26 04:34:38,306 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6403-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6403-2) 2026-10-26 04:34:38,885 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6403-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6403-1) 2026-10-26 04:34:38,885 - INFO - Waiting for VM ctest-TestBGPaasZone-48219217-81404905 to be up.. 2026-10-26 04:34:38,951 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:34:44,070 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:34:49,170 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:34:54,255 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:34:59,339 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:35:04,431 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:35:09,521 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:35:14,607 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:35:19,693 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:35:24,779 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:35:30,085 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:35:35,174 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:35:40,259 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:35:45,358 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:35:50,466 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:35:55,558 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:36:00,652 - DEBUG - VM is in ACTIVE state now 2026-10-26 04:36:00,652 - INFO - VM name : ctest-TestBGPaasZone-48219217-81404905 2026-10-26 04:36:00,744 - DEBUG - VM ctest-TestBGPaasZone-48219217-81404905 ID is 7b2ce583-ae76-4eb3-a533-84b5b46b1cd9 2026-10-26 04:36:00,772 - DEBUG - VM ctest-TestBGPaasZone-48219217-81404905 launched on Node an-jenkins-deploy-platform-ansible-os-6403-1 2026-10-26 04:36:00,860 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine/7b2ce583-ae76-4eb3-a533-84b5b46b1cd9 2026-10-26 04:36:01,241 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine/7b2ce583-ae76-4eb3-a533-84b5b46b1cd9 2026-10-26 04:36:01,280 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine-interface/c80f617b-8092-442b-aa9c-b976b6a8efe5 2026-10-26 04:36:04,500 - 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 1002ms') 2026-10-26 04:36:04,500 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:36:04,519 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:36:08,587 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1004ms') 2026-10-26 04:36:08,587 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:36:08,605 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:36:12,691 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms') 2026-10-26 04:36:12,691 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:36:12,708 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:36:16,779 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2026-10-26 04:36:16,779 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:36:16,795 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:36:20,883 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1021ms') 2026-10-26 04:36:20,883 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:36:20,899 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:36:24,979 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2026-10-26 04:36:24,979 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:36:24,994 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:36:29,075 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2026-10-26 04:36:29,075 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:36:29,094 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:36:33,165 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1008ms') 2026-10-26 04:36:33,165 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:36:33,182 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:36:37,265 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2026-10-26 04:36:37,266 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:36:37,280 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:36:41,362 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms') 2026-10-26 04:36:41,362 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:36:41,379 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:36:45,454 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2026-10-26 04:36:45,454 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:36:45,470 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:36:49,556 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms') 2026-10-26 04:36:49,556 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:36:49,574 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:36:53,645 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1009ms') 2026-10-26 04:36:53,645 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:36:53,662 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:36:57,742 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2026-10-26 04:36:57,742 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:36:57,758 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:37:01,839 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms') 2026-10-26 04:37:01,839 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:37:01,854 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:37:05,939 - 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') 2026-10-26 04:37:05,939 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:37:05,974 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:37:10,060 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1026ms') 2026-10-26 04:37:10,060 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 failed! 2026-10-26 04:37:10,076 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-11056015 is 91.119.105.1 and allocation pool is NOT set 2026-10-26 04:37:14,161 - 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=10.6 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1022ms\r\nrtt min/avg/max/mdev = 10.597/10.597/10.597/0.000 ms') 2026-10-26 04:37:14,162 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-81404905 passed 2026-10-26 04:37:14,240 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:37:14,240 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-81404905, IP 91.119.105.3, Port 22 2026-10-26 04:37:14,318 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:37:14,429 - DEBUG - VM ctest-TestBGPaasZone-48219217-81404905 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:37:19,430 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:37:19,430 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-81404905, IP 91.119.105.3, Port 22 2026-10-26 04:37:19,499 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:37:19,595 - DEBUG - VM ctest-TestBGPaasZone-48219217-81404905 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:37:24,596 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:37:24,596 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-81404905, IP 91.119.105.3, Port 22 2026-10-26 04:37:24,664 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:37:24,752 - DEBUG - VM ctest-TestBGPaasZone-48219217-81404905 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:37:29,753 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:37:29,753 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-81404905, IP 91.119.105.3, Port 22 2026-10-26 04:37:29,820 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:37:29,935 - DEBUG - VM ctest-TestBGPaasZone-48219217-81404905 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:37:34,935 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:37:34,935 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-81404905, IP 91.119.105.3, Port 22 2026-10-26 04:37:35,004 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:37:35,085 - DEBUG - VM ctest-TestBGPaasZone-48219217-81404905 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:37:40,087 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:37:40,087 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-81404905, IP 91.119.105.3, Port 22 2026-10-26 04:37:40,156 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:37:40,246 - DEBUG - VM ctest-TestBGPaasZone-48219217-81404905 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:37:45,247 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:37:45,247 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-81404905, IP 91.119.105.3, Port 22 2026-10-26 04:37:45,326 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:37:45,420 - DEBUG - VM ctest-TestBGPaasZone-48219217-81404905 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:37:50,421 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:37:50,423 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-81404905, IP 91.119.105.3, Port 22 2026-10-26 04:37:50,497 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:37:50,714 - DEBUG - VM ctest-TestBGPaasZone-48219217-81404905 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:37:55,715 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:37:55,715 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-81404905, IP 91.119.105.3, Port 22 2026-10-26 04:37:55,885 - DEBUG - VM ctest-TestBGPaasZone-48219217-81404905 is ready for SSH connections 2026-10-26 04:37:55,885 - INFO - Waiting for VM ctest-TestBGPaasZone-48219217-13951030 to be up.. 2026-10-26 04:37:55,988 - DEBUG - VM is in ACTIVE state now 2026-10-26 04:37:55,988 - INFO - VM name : ctest-TestBGPaasZone-48219217-13951030 2026-10-26 04:37:56,091 - DEBUG - VM ctest-TestBGPaasZone-48219217-13951030 ID is f02852b8-44c5-4aeb-ba7b-dd7d20c97b5d 2026-10-26 04:37:56,091 - DEBUG - VM ctest-TestBGPaasZone-48219217-13951030 launched on Node an-jenkins-deploy-platform-ansible-os-6403-2 2026-10-26 04:37:56,198 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine/f02852b8-44c5-4aeb-ba7b-dd7d20c97b5d 2026-10-26 04:37:56,209 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine-interface/1956da3c-cb3b-4b58-aae1-49faad460463 2026-10-26 04:37:57,395 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=3.18 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.549 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.549/1.863/3.177/1.314 ms') 2026-10-26 04:37:57,395 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-13951030 passed 2026-10-26 04:37:57,468 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:37:57,468 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-13951030, IP 91.119.105.4, Port 22 2026-10-26 04:37:57,640 - DEBUG - VM ctest-TestBGPaasZone-48219217-13951030 is ready for SSH connections 2026-10-26 04:37:57,641 - INFO - Waiting for VM ctest-TestBGPaasZone-48219217-92600438 to be up.. 2026-10-26 04:37:57,727 - DEBUG - VM is in ACTIVE state now 2026-10-26 04:37:57,727 - INFO - VM name : ctest-TestBGPaasZone-48219217-92600438 2026-10-26 04:37:57,825 - DEBUG - VM ctest-TestBGPaasZone-48219217-92600438 ID is 190cb52b-5d6f-4916-979c-26b4b84b2e05 2026-10-26 04:37:57,825 - DEBUG - VM ctest-TestBGPaasZone-48219217-92600438 launched on Node an-jenkins-deploy-platform-ansible-os-6403-1 2026-10-26 04:37:57,905 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine/190cb52b-5d6f-4916-979c-26b4b84b2e05 2026-10-26 04:37:57,916 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine-interface/917f7dcb-fbbe-487c-baa7-36190c1f2af4 2026-10-26 04:37:59,091 - DEBUG - (True, 'PING 169.254.0.4 (169.254.0.4) 56(84) bytes of data.\r\n64 bytes from 169.254.0.4: icmp_seq=1 ttl=63 time=3.96 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.13 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.132/3.043/3.955/0.911 ms') 2026-10-26 04:37:59,092 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-48219217-92600438 passed 2026-10-26 04:37:59,163 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:37:59,163 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-92600438, IP 91.119.105.5, Port 22 2026-10-26 04:37:59,340 - DEBUG - VM ctest-TestBGPaasZone-48219217-92600438 is ready for SSH connections 2026-10-26 04:37:59,477 - INFO - Created control node zone ctest-test-zone-79902438(6503f32a-5b99-423d-afae-fecfd81a2864) 2026-10-26 04:37:59,825 - INFO - Created control node zone ctest-test-zone-32663423(c42da3b6-a0f9-4acc-9148-348c3e174e47) 2026-10-26 04:38:00,173 - INFO - Created control node zone ctest-test-zone-40709984(b2e9ad28-981e-440e-8d8b-dbe590f36dda) 2026-10-26 04:38:00,453 - INFO - Creating BGPaaS ctest-TestBGPaasZone-48219217-55395369 2026-10-26 04:38:00,529 - INFO - BGPaaS: ctest-TestBGPaasZone-48219217-55395369(502b527f-ab11-47be-9f1d-ce0662a1180c) 2026-10-26 04:38:00,529 - DEBUG - Requesting: http://10.0.0.251:8082/bgp-as-a-service/502b527f-ab11-47be-9f1d-ce0662a1180c 2026-10-26 04:38:00,903 - DEBUG - Requesting: http://10.0.0.251:8082/bgp-as-a-service/502b527f-ab11-47be-9f1d-ce0662a1180c 2026-10-26 04:38:00,938 - INFO - verify_in_api_server passed for BGPaaS obj 502b527f-ab11-47be-9f1d-ce0662a1180c 2026-10-26 04:38:00,938 - INFO - BGPaaS(502b527f-ab11-47be-9f1d-ce0662a1180c): verify_on_setup passed 2026-10-26 04:38:00,938 - INFO - We will configure BGP on the VM 2026-10-26 04:38:01,193 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:38:01,193 - INFO - Configuring BGP on ctest-TestBGPaasZone-48219217-81404905 2026-10-26 04:38:01,193 - 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 91.119.105.3; protocol bgp bgp_1{ local as 65000; neighbor 91.119.105.1 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 91.119.105.3; } protocol bfd { neighbor 91.119.105.1 local 91.119.105.3 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:38:01,194 - 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 91.119.105.3; protocol bgp bgp_1{ local as 65000; neighbor 91.119.105.1 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 91.119.105.3; } protocol bfd { neighbor 91.119.105.1 local 91.119.105.3 multihop on; } EOS 2026-10-26 04:38:08,326 - DEBUG - None 2026-10-26 04:38:08,326 - 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 91.119.105.3; protocol bgp bgp_1{ local as 65000; neighbor 91.119.105.1 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 91.119.105.3; } protocol bfd { neighbor 91.119.105.1 local 91.119.105.3 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:38:08,327 - 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 91.119.105.3; protocol bgp bgp_1{ local as 65000; neighbor 91.119.105.1 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 91.119.105.3; } protocol bfd { neighbor 91.119.105.1 local 91.119.105.3 multihop on; } EOS 2026-10-26 04:38:08,898 - DEBUG - None 2026-10-26 04:38:08,898 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:38:08,898 - DEBUG - service bird restart 2026-10-26 04:38:10,430 - DEBUG - bird stop/waiting bird start/running, process 1427 2026-10-26 04:38:10,430 - INFO - Attaching VMI c80f617b-8092-442b-aa9c-b976b6a8efe5 to BGPaaS 502b527f-ab11-47be-9f1d-ce0662a1180c 2026-10-26 04:38:10,505 - INFO - Attaching the VMI 7b2ce583-ae76-4eb3-a533-84b5b46b1cd9 to the BGPaaS 502b527f-ab11-47be-9f1d-ce0662a1180c object 2026-10-26 04:38:10,505 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 91.119.105.20 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:38:10,505 - DEBUG - sudo ip addr add 91.119.105.20 dev eth0 2026-10-26 04:38:11,173 - DEBUG - None 2026-10-26 04:38:11,233 - INFO - Creating BGPaaS ctest-TestBGPaasZone-48219217-31846346 2026-10-26 04:38:11,309 - INFO - BGPaaS: ctest-TestBGPaasZone-48219217-31846346(29baa1bf-e0ba-4434-9eee-01b7ff0a5a0f) 2026-10-26 04:38:11,309 - DEBUG - Requesting: http://10.0.0.251:8082/bgp-as-a-service/29baa1bf-e0ba-4434-9eee-01b7ff0a5a0f 2026-10-26 04:38:11,322 - INFO - verify_in_api_server passed for BGPaaS obj 29baa1bf-e0ba-4434-9eee-01b7ff0a5a0f 2026-10-26 04:38:11,323 - INFO - BGPaaS(29baa1bf-e0ba-4434-9eee-01b7ff0a5a0f): verify_on_setup passed 2026-10-26 04:38:11,323 - INFO - We will configure BGP on the VM 2026-10-26 04:38:11,609 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:38:11,609 - INFO - Configuring BGP on ctest-TestBGPaasZone-48219217-13951030 2026-10-26 04:38:11,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 91.119.105.4; protocol bgp bgp_1{ local as 65000; neighbor 91.119.105.2 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 91.119.105.4; } protocol bfd { neighbor 91.119.105.2 local 91.119.105.4 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-10-26 04:38:11,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 91.119.105.4; protocol bgp bgp_1{ local as 65000; neighbor 91.119.105.2 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 91.119.105.4; } protocol bfd { neighbor 91.119.105.2 local 91.119.105.4 multihop on; } EOS 2026-10-26 04:38:16,162 - DEBUG - None 2026-10-26 04:38:16,162 - 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 91.119.105.4; protocol bgp bgp_1{ local as 65000; neighbor 91.119.105.2 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 91.119.105.4; } protocol bfd { neighbor 91.119.105.2 local 91.119.105.4 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-10-26 04:38:16,162 - 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 91.119.105.4; protocol bgp bgp_1{ local as 65000; neighbor 91.119.105.2 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 91.119.105.4; } protocol bfd { neighbor 91.119.105.2 local 91.119.105.4 multihop on; } EOS 2026-10-26 04:38:16,461 - DEBUG - None 2026-10-26 04:38:16,461 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-10-26 04:38:16,461 - DEBUG - service bird restart 2026-10-26 04:38:17,348 - DEBUG - bird stop/waiting bird start/running, process 1444 2026-10-26 04:38:17,348 - INFO - Attaching VMI 1956da3c-cb3b-4b58-aae1-49faad460463 to BGPaaS 29baa1bf-e0ba-4434-9eee-01b7ff0a5a0f 2026-10-26 04:38:17,429 - INFO - Attaching the VMI f02852b8-44c5-4aeb-ba7b-dd7d20c97b5d to the BGPaaS 29baa1bf-e0ba-4434-9eee-01b7ff0a5a0f object 2026-10-26 04:38:17,429 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 91.119.105.20 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-10-26 04:38:17,430 - DEBUG - sudo ip addr add 91.119.105.20 dev eth0 2026-10-26 04:38:17,854 - DEBUG - None 2026-10-26 04:38:17,854 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:38:17,854 - DEBUG - Requesting: http://10.20.0.29:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-10-26 04:38:17,882 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.29'] 2026-10-26 04:38:23,884 - DEBUG - Requesting: http://10.20.0.29:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-10-26 04:38:23,898 - INFO - BGPaaS session seen in control-node 10.20.0.29 2026-10-26 04:38:23,905 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:38:23,923 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.254'] 2026-10-26 04:38:29,941 - INFO - BGPaaS session seen in control-node 10.20.0.254 2026-10-26 04:38:29,955 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 91.119.105.20, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:38:29,956 - DEBUG - ping -s 56 -c 3 -W 1 91.119.105.20 2026-10-26 04:38:34,415 - DEBUG - PING 91.119.105.20 (91.119.105.20) 56(84) bytes of data. 64 bytes from 91.119.105.20: icmp_seq=1 ttl=64 time=8.44 ms 64 bytes from 91.119.105.20: icmp_seq=2 ttl=64 time=1.38 ms 64 bytes from 91.119.105.20: icmp_seq=3 ttl=64 time=1.43 ms --- 91.119.105.20 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.380/3.751/8.441/3.316 ms 2026-10-26 04:38:34,415 - INFO - Ping to IP 91.119.105.20 from VM ctest-TestBGPaasZone-48219217-92600438 passed 2026-10-26 04:38:35,221 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:38:35,221 - DEBUG - service bird restart 2026-10-26 04:38:36,163 - DEBUG - bird stop/waiting bird start/running, process 1473 2026-10-26 04:38:36,163 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-10-26 04:38:36,163 - DEBUG - service bird restart 2026-10-26 04:38:36,946 - DEBUG - bird stop/waiting bird start/running, process 1471 2026-10-26 04:38:36,946 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:38:36,965 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.30'] 2026-10-26 04:38:42,984 - INFO - BGPaaS session seen in control-node 10.20.0.30 2026-10-26 04:38:43,001 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:38:43,023 - INFO - BGPaaS session seen in control-node 10.20.0.254 2026-10-26 04:38:43,030 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 91.119.105.20, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:38:43,030 - DEBUG - ping -s 56 -c 3 -W 1 91.119.105.20 2026-10-26 04:38:45,335 - DEBUG - PING 91.119.105.20 (91.119.105.20) 56(84) bytes of data. 64 bytes from 91.119.105.20: icmp_seq=1 ttl=64 time=3.10 ms 64 bytes from 91.119.105.20: icmp_seq=2 ttl=64 time=1.61 ms 64 bytes from 91.119.105.20: icmp_seq=3 ttl=64 time=1.60 ms --- 91.119.105.20 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.607/2.110/3.109/0.706 ms 2026-10-26 04:38:45,335 - INFO - Ping to IP 91.119.105.20 from VM ctest-TestBGPaasZone-48219217-92600438 passed 2026-10-26 04:38:45,631 - INFO - We will configure BGP on the VM 2026-10-26 04:38:45,837 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:38:45,838 - INFO - Configuring BGP on ctest-TestBGPaasZone-48219217-81404905 2026-10-26 04:38:45,838 - 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 91.119.105.3; protocol bgp bgp_1{ local as 65000; neighbor 91.119.105.1 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 91.119.105.3; } protocol bfd { neighbor 91.119.105.1 local 91.119.105.3 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:38:45,838 - 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 91.119.105.3; protocol bgp bgp_1{ local as 65000; neighbor 91.119.105.1 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 91.119.105.3; } protocol bfd { neighbor 91.119.105.1 local 91.119.105.3 multihop on; } EOS 2026-10-26 04:38:46,145 - DEBUG - None 2026-10-26 04:38:46,145 - 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 91.119.105.3; protocol bgp bgp_1{ local as 65000; neighbor 91.119.105.1 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 91.119.105.3; } protocol bfd { neighbor 91.119.105.1 local 91.119.105.3 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:38:46,145 - 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 91.119.105.3; protocol bgp bgp_1{ local as 65000; neighbor 91.119.105.1 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 91.119.105.3; } protocol bfd { neighbor 91.119.105.1 local 91.119.105.3 multihop on; } EOS 2026-10-26 04:38:46,478 - DEBUG - None 2026-10-26 04:38:46,478 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:38:46,478 - DEBUG - service bird restart 2026-10-26 04:38:47,617 - DEBUG - bird stop/waiting bird start/running, process 1505 2026-10-26 04:38:47,617 - INFO - Attaching VMI c80f617b-8092-442b-aa9c-b976b6a8efe5 to BGPaaS 502b527f-ab11-47be-9f1d-ce0662a1180c 2026-10-26 04:38:47,640 - INFO - Attaching the VMI 7b2ce583-ae76-4eb3-a533-84b5b46b1cd9 to the BGPaaS 502b527f-ab11-47be-9f1d-ce0662a1180c object 2026-10-26 04:38:47,640 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 91.119.105.20 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:38:47,640 - DEBUG - sudo ip addr add 91.119.105.20 dev eth0 2026-10-26 04:38:48,035 - DEBUG - RTNETLINK answers: File exists 2026-10-26 04:38:48,035 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:38:48,035 - DEBUG - service bird restart 2026-10-26 04:38:48,934 - DEBUG - bird stop/waiting bird start/running, process 1532 2026-10-26 04:38:48,934 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-10-26 04:38:48,934 - DEBUG - service bird restart 2026-10-26 04:38:49,804 - DEBUG - bird stop/waiting bird start/running, process 1493 2026-10-26 04:38:49,804 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:38:49,804 - DEBUG - Requesting: http://10.20.0.29:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-10-26 04:38:49,821 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.29'] 2026-10-26 04:38:55,823 - DEBUG - Requesting: http://10.20.0.29:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-10-26 04:38:55,837 - INFO - BGPaaS session seen in control-node 10.20.0.29 2026-10-26 04:38:55,845 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:38:55,865 - INFO - BGPaaS session seen in control-node 10.20.0.254 2026-10-26 04:38:55,873 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 91.119.105.20, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:38:55,873 - DEBUG - ping -s 56 -c 3 -W 1 91.119.105.20 2026-10-26 04:38:58,168 - DEBUG - PING 91.119.105.20 (91.119.105.20) 56(84) bytes of data. 64 bytes from 91.119.105.20: icmp_seq=1 ttl=64 time=4.20 ms 64 bytes from 91.119.105.20: icmp_seq=2 ttl=64 time=1.36 ms 64 bytes from 91.119.105.20: icmp_seq=3 ttl=64 time=1.47 ms --- 91.119.105.20 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.364/2.346/4.202/1.313 ms 2026-10-26 04:38:58,168 - INFO - Ping to IP 91.119.105.20 from VM ctest-TestBGPaasZone-48219217-92600438 passed 2026-10-26 04:38:58,169 - INFO - Detaching VMI c80f617b-8092-442b-aa9c-b976b6a8efe5 from BGPaaS 502b527f-ab11-47be-9f1d-ce0662a1180c 2026-10-26 04:38:58,245 - INFO - Detaching VMI 1956da3c-cb3b-4b58-aae1-49faad460463 from BGPaaS 29baa1bf-e0ba-4434-9eee-01b7ff0a5a0f 2026-10-26 04:38:58,349 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-48219217-31846346(29baa1bf-e0ba-4434-9eee-01b7ff0a5a0f) 2026-10-26 04:38:58,406 - DEBUG - Requesting: http://10.0.0.251:8082/bgp-as-a-service/29baa1bf-e0ba-4434-9eee-01b7ff0a5a0f 2026-10-26 04:38:58,414 - DEBUG - Response Code: 404 2026-10-26 04:38:58,414 - DEBUG - BGPaaS: 29baa1bf-e0ba-4434-9eee-01b7ff0a5a0f deleted from api server 2026-10-26 04:38:58,414 - INFO - BGPaaS(29baa1bf-e0ba-4434-9eee-01b7ff0a5a0f): verify_on_cleanup passed 2026-10-26 04:38:58,414 - INFO - Detaching VMI c80f617b-8092-442b-aa9c-b976b6a8efe5 from BGPaaS 502b527f-ab11-47be-9f1d-ce0662a1180c 2026-10-26 04:38:58,458 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-48219217-55395369(502b527f-ab11-47be-9f1d-ce0662a1180c) 2026-10-26 04:38:58,540 - DEBUG - Requesting: http://10.0.0.251:8082/bgp-as-a-service/502b527f-ab11-47be-9f1d-ce0662a1180c 2026-10-26 04:38:58,546 - DEBUG - Response Code: 404 2026-10-26 04:38:58,546 - DEBUG - BGPaaS: 502b527f-ab11-47be-9f1d-ce0662a1180c deleted from api server 2026-10-26 04:38:58,546 - INFO - BGPaaS(502b527f-ab11-47be-9f1d-ce0662a1180c): verify_on_cleanup passed 2026-10-26 04:38:58,546 - INFO - Deleting Control node zone ctest-test-zone-40709984(b2e9ad28-981e-440e-8d8b-dbe590f36dda) 2026-10-26 04:38:58,713 - INFO - Deleting Control node zone ctest-test-zone-32663423(c42da3b6-a0f9-4acc-9148-348c3e174e47) 2026-10-26 04:38:58,882 - INFO - Deleting Control node zone ctest-test-zone-79902438(6503f32a-5b99-423d-afae-fecfd81a2864) 2026-10-26 04:38:58,975 - INFO - Deleting VM ctest-TestBGPaasZone-48219217-92600438 2026-10-26 04:38:59,063 - INFO - Deleting VM ctest-TestBGPaasZone-48219217-13951030 2026-10-26 04:38:59,144 - INFO - Deleting VM ctest-TestBGPaasZone-48219217-81404905 2026-10-26 04:38:59,238 - INFO - Deleting VN ctest-cnz_vn-11056015 2026-10-26 04:38:59,289 - DEBUG - VN 99b29872-0143-40bd-ad86-41ff4d249ea7 still in use: Unable to complete operation on network 99b29872-0143-40bd-ad86-41ff4d249ea7. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-a4983ab3-aae4-464e-8bf3-90fa03074caa'] 2026-10-26 04:38:59,290 - WARNING - Deleting VN ctest-cnz_vn-11056015 failed..Will retry 2026-10-26 04:39:01,340 - DEBUG - VN 99b29872-0143-40bd-ad86-41ff4d249ea7 still in use: Unable to complete operation on network 99b29872-0143-40bd-ad86-41ff4d249ea7. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-4af59039-7ddf-40f4-ad01-6c6fc5cfa033'] 2026-10-26 04:39:01,340 - WARNING - Deleting VN ctest-cnz_vn-11056015 failed..Will retry 2026-10-26 04:39:03,720 - DEBUG - Response for deleting network () 2026-10-26 04:39:05,069 - DEBUG - Skipping xmpp flap check
2026-10-26 04:39:05,069 - INFO - END TEST : test_bgp_control_node_zone : PASSED[0:04:31]
2026-10-26 04:39:05,069 - INFO - -------------------------------------------------------------------------------- 2026-10-26 04:39:05,073 - INFO - ================================================================================ 2026-10-26 04:39:05,073 - INFO - STARTING TEST : test_bgp_control_node_zones_from_single_vnf 2026-10-26 04:39:06,378 - DEBUG - Skipping xmpp flap check 2026-10-26 04:39:06,378 - INFO - Initial checks done. Running the testcase now 2026-10-26 04:39:06,378 - INFO - 2026-10-26 04:39:06,378 - INFO - executing bgp_control_zone agent restart test 2026-10-26 04:39:06,695 - DEBUG - Response for create_network : {'network': {'id': 'ee0d8f85-0b6e-4a07-aa59-619753337eaa', 'name': 'ctest-cnz_vn-52359012', 'tenant_id': '470861c3d7314055a708820cbaeed638', 'project_id': '470861c3d7314055a708820cbaeed638', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBGPaasZone-48219217', 'ctest-cnz_vn-52359012'], 'port_security_enabled': True, 'description': ''}} 2026-10-26 04:39:06,975 - DEBUG - Response for create_subnet : {'subnet': {'id': '12d2fdae-cc5c-4519-acde-e1989b858494', 'name': '', 'tenant_id': '470861c3d7314055a708820cbaeed638', 'network_id': 'ee0d8f85-0b6e-4a07-aa59-619753337eaa', 'ip_version': 4, 'cidr': '151.0.62.192/26', 'allocation_pools': [{'start': '151.0.62.194', 'end': '151.0.62.254'}], 'gateway_ip': '151.0.62.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '151.0.62.194', 'tags': [], 'project_id': '470861c3d7314055a708820cbaeed638'}} 2026-10-26 04:39:06,998 - INFO - Created VN ctest-cnz_vn-52359012 2026-10-26 04:39:07,024 - DEBUG - VN ctest-cnz_vn-52359012 UUID is ee0d8f85-0b6e-4a07-aa59-619753337eaa 2026-10-26 04:39:07,703 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6403-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6403-2) 2026-10-26 04:39:08,322 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6403-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6403-1) 2026-10-26 04:39:08,988 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6403-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6403-2) 2026-10-26 04:39:08,988 - INFO - Waiting for VM ctest-TestBGPaasZone-48219217-79531434 to be up.. 2026-10-26 04:39:09,050 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:39:14,178 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:39:19,286 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:39:24,378 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:39:29,485 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:39:34,590 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:39:39,714 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:39:44,841 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-26 04:39:49,936 - DEBUG - VM is in ACTIVE state now 2026-10-26 04:39:49,937 - INFO - VM name : ctest-TestBGPaasZone-48219217-79531434 2026-10-26 04:39:50,041 - DEBUG - VM ctest-TestBGPaasZone-48219217-79531434 ID is 9baf9581-dfba-4661-93ae-5fe41c494e8a 2026-10-26 04:39:50,042 - DEBUG - VM ctest-TestBGPaasZone-48219217-79531434 launched on Node an-jenkins-deploy-platform-ansible-os-6403-2 2026-10-26 04:39:50,153 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine/9baf9581-dfba-4661-93ae-5fe41c494e8a 2026-10-26 04:39:50,166 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine-interface/6c43dbcc-9abb-491c-854c-48d478e53ddf 2026-10-26 04:39:53,402 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2026-10-26 04:39:53,402 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 failed! 2026-10-26 04:39:53,421 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-52359012 is 151.0.62.193 and allocation pool is NOT set 2026-10-26 04:39:57,488 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2026-10-26 04:39:57,488 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 failed! 2026-10-26 04:39:57,508 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-52359012 is 151.0.62.193 and allocation pool is NOT set 2026-10-26 04:40:01,585 - 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') 2026-10-26 04:40:01,585 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 failed! 2026-10-26 04:40:01,604 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-52359012 is 151.0.62.193 and allocation pool is NOT set 2026-10-26 04:40:05,688 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1007ms') 2026-10-26 04:40:05,688 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 failed! 2026-10-26 04:40:05,705 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-52359012 is 151.0.62.193 and allocation pool is NOT set 2026-10-26 04:40:09,784 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms') 2026-10-26 04:40:09,784 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 failed! 2026-10-26 04:40:09,804 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-52359012 is 151.0.62.193 and allocation pool is NOT set 2026-10-26 04:40:13,873 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1007ms') 2026-10-26 04:40:13,873 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 failed! 2026-10-26 04:40:13,893 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-52359012 is 151.0.62.193 and allocation pool is NOT set 2026-10-26 04:40:17,976 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms') 2026-10-26 04:40:17,976 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 failed! 2026-10-26 04:40:17,997 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-52359012 is 151.0.62.193 and allocation pool is NOT set 2026-10-26 04:40:22,072 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2026-10-26 04:40:22,072 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 failed! 2026-10-26 04:40:22,091 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-52359012 is 151.0.62.193 and allocation pool is NOT set 2026-10-26 04:40:26,161 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1009ms') 2026-10-26 04:40:26,161 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 failed! 2026-10-26 04:40:26,181 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-52359012 is 151.0.62.193 and allocation pool is NOT set 2026-10-26 04:40:30,265 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms') 2026-10-26 04:40:30,265 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 failed! 2026-10-26 04:40:30,287 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-52359012 is 151.0.62.193 and allocation pool is NOT set 2026-10-26 04:40:34,362 - 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 1002ms') 2026-10-26 04:40:34,362 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 failed! 2026-10-26 04:40:34,381 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-52359012 is 151.0.62.193 and allocation pool is NOT set 2026-10-26 04:40:38,457 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1007ms') 2026-10-26 04:40:38,457 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 failed! 2026-10-26 04:40:38,479 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-52359012 is 151.0.62.193 and allocation pool is NOT set 2026-10-26 04:40:42,561 - 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') 2026-10-26 04:40:42,561 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 failed! 2026-10-26 04:40:42,581 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-52359012 is 151.0.62.193 and allocation pool is NOT set 2026-10-26 04:40:46,651 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms') 2026-10-26 04:40:46,651 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 failed! 2026-10-26 04:40:46,669 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-52359012 is 151.0.62.193 and allocation pool is NOT set 2026-10-26 04:40:50,746 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms') 2026-10-26 04:40:50,746 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 failed! 2026-10-26 04:40:50,762 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-52359012 is 151.0.62.193 and allocation pool is NOT set 2026-10-26 04:40:54,835 - 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') 2026-10-26 04:40:54,835 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 failed! 2026-10-26 04:40:54,858 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-48219217:ctest-cnz_vn-52359012 is 151.0.62.193 and allocation pool is NOT set 2026-10-26 04:40:56,930 - 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=19.4 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.518 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.518/9.945/19.372/9.427 ms') 2026-10-26 04:40:56,930 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-79531434 passed 2026-10-26 04:40:57,005 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:40:57,005 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-79531434, IP 151.0.62.195, Port 22 2026-10-26 04:40:57,072 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:40:57,174 - DEBUG - VM ctest-TestBGPaasZone-48219217-79531434 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:41:02,175 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:41:02,175 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-79531434, IP 151.0.62.195, Port 22 2026-10-26 04:41:02,240 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:41:02,340 - DEBUG - VM ctest-TestBGPaasZone-48219217-79531434 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:41:07,341 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:41:07,341 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-79531434, IP 151.0.62.195, Port 22 2026-10-26 04:41:07,408 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:41:07,501 - DEBUG - VM ctest-TestBGPaasZone-48219217-79531434 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:41:12,502 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:41:12,502 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-79531434, IP 151.0.62.195, Port 22 2026-10-26 04:41:12,568 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:41:12,663 - DEBUG - VM ctest-TestBGPaasZone-48219217-79531434 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:41:17,664 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:41:17,664 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-79531434, IP 151.0.62.195, Port 22 2026-10-26 04:41:17,737 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:41:17,830 - DEBUG - VM ctest-TestBGPaasZone-48219217-79531434 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:41:22,830 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:41:22,831 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-79531434, IP 151.0.62.195, Port 22 2026-10-26 04:41:22,906 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:41:23,009 - DEBUG - VM ctest-TestBGPaasZone-48219217-79531434 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:41:28,010 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:41:28,010 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-79531434, IP 151.0.62.195, Port 22 2026-10-26 04:41:28,082 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:41:28,183 - DEBUG - VM ctest-TestBGPaasZone-48219217-79531434 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:41:33,183 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:41:33,183 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-79531434, IP 151.0.62.195, Port 22 2026-10-26 04:41:33,277 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:41:33,372 - DEBUG - VM ctest-TestBGPaasZone-48219217-79531434 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:41:38,373 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:41:38,374 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-79531434, IP 151.0.62.195, Port 22 2026-10-26 04:41:38,579 - DEBUG - VM ctest-TestBGPaasZone-48219217-79531434 is ready for SSH connections 2026-10-26 04:41:38,579 - INFO - Waiting for VM ctest-TestBGPaasZone-48219217-40412619 to be up.. 2026-10-26 04:41:38,677 - DEBUG - VM is in ACTIVE state now 2026-10-26 04:41:38,677 - INFO - VM name : ctest-TestBGPaasZone-48219217-40412619 2026-10-26 04:41:38,770 - DEBUG - VM ctest-TestBGPaasZone-48219217-40412619 ID is 617b2ef4-0120-4e87-a90d-37280cb2e4db 2026-10-26 04:41:38,770 - DEBUG - VM ctest-TestBGPaasZone-48219217-40412619 launched on Node an-jenkins-deploy-platform-ansible-os-6403-1 2026-10-26 04:41:38,869 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine/617b2ef4-0120-4e87-a90d-37280cb2e4db 2026-10-26 04:41:38,908 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine-interface/1da1415c-87ca-49c8-a2f7-9b55aa5f9dbe 2026-10-26 04:41:40,083 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=3.02 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.612 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.612/1.818/3.024/1.206 ms') 2026-10-26 04:41:40,083 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-48219217-40412619 passed 2026-10-26 04:41:40,155 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:41:40,155 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-40412619, IP 151.0.62.196, Port 22 2026-10-26 04:41:40,331 - DEBUG - VM ctest-TestBGPaasZone-48219217-40412619 is ready for SSH connections 2026-10-26 04:41:40,332 - INFO - Waiting for VM ctest-TestBGPaasZone-48219217-11240620 to be up.. 2026-10-26 04:41:40,432 - DEBUG - VM is in ACTIVE state now 2026-10-26 04:41:40,432 - INFO - VM name : ctest-TestBGPaasZone-48219217-11240620 2026-10-26 04:41:40,536 - DEBUG - VM ctest-TestBGPaasZone-48219217-11240620 ID is ba81bc06-d2e2-4c3f-9e2f-85183e2f41c8 2026-10-26 04:41:40,537 - DEBUG - VM ctest-TestBGPaasZone-48219217-11240620 launched on Node an-jenkins-deploy-platform-ansible-os-6403-2 2026-10-26 04:41:40,642 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine/ba81bc06-d2e2-4c3f-9e2f-85183e2f41c8 2026-10-26 04:41:40,656 - DEBUG - Requesting: http://10.0.0.248:8082/virtual-machine-interface/c80ad2d3-e35e-4534-9b99-ce1cf205de17 2026-10-26 04:41:41,857 - 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.11 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=3.78 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 = 3.778/4.945/6.112/1.167 ms') 2026-10-26 04:41:41,857 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-48219217-11240620 passed 2026-10-26 04:41:41,933 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:41:41,933 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-11240620, IP 151.0.62.197, Port 22 2026-10-26 04:41:42,000 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:41:42,094 - DEBUG - VM ctest-TestBGPaasZone-48219217-11240620 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:41:47,095 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:41:47,095 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-11240620, IP 151.0.62.197, Port 22 2026-10-26 04:41:47,181 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:41:47,293 - DEBUG - VM ctest-TestBGPaasZone-48219217-11240620 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:41:52,294 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:41:52,294 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-11240620, IP 151.0.62.197, Port 22 2026-10-26 04:41:52,361 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-26 04:41:52,446 - DEBUG - VM ctest-TestBGPaasZone-48219217-11240620 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-26 04:41:57,447 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:41:57,448 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-48219217-11240620, IP 151.0.62.197, Port 22 2026-10-26 04:41:57,621 - DEBUG - VM ctest-TestBGPaasZone-48219217-11240620 is ready for SSH connections 2026-10-26 04:41:57,769 - INFO - Created control node zone ctest-test-zone-82256763(7ba60dd3-290f-4cd5-9b00-dd2da59b3ccd) 2026-10-26 04:41:58,141 - INFO - Created control node zone ctest-test-zone-35802245(268f7a50-96a2-49fe-ac29-6147352b85f2) 2026-10-26 04:41:58,529 - INFO - Created control node zone ctest-test-zone-80860664(fdc37710-9934-465f-b028-8726bd80706c) 2026-10-26 04:41:58,837 - INFO - Creating BGPaaS ctest-TestBGPaasZone-48219217-27979308 2026-10-26 04:41:58,937 - INFO - BGPaaS: ctest-TestBGPaasZone-48219217-27979308(cc911fb9-6f1b-4b81-8779-c75677802226) 2026-10-26 04:41:58,938 - DEBUG - Requesting: http://10.0.0.251:8082/bgp-as-a-service/cc911fb9-6f1b-4b81-8779-c75677802226 2026-10-26 04:41:58,951 - INFO - verify_in_api_server passed for BGPaaS obj cc911fb9-6f1b-4b81-8779-c75677802226 2026-10-26 04:41:58,952 - INFO - BGPaaS(cc911fb9-6f1b-4b81-8779-c75677802226): verify_on_setup passed 2026-10-26 04:41:58,952 - INFO - We will configure BGP on the VM 2026-10-26 04:41:59,393 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:41:59,393 - INFO - Configuring BGP on ctest-TestBGPaasZone-48219217-79531434 2026-10-26 04:41:59,394 - 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 151.0.62.195; protocol bgp bgp_1{ local as 65000; neighbor 151.0.62.193 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 151.0.62.195; } protocol bfd { neighbor 151.0.62.193 local 151.0.62.195 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-10-26 04:41:59,394 - 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 151.0.62.195; protocol bgp bgp_1{ local as 65000; neighbor 151.0.62.193 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 151.0.62.195; } protocol bfd { neighbor 151.0.62.193 local 151.0.62.195 multihop on; } EOS 2026-10-26 04:42:03,251 - DEBUG - None 2026-10-26 04:42:03,252 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS protocol bgp bgp_2{ local as 65000; neighbor 151.0.62.194 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 151.0.62.195; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-10-26 04:42:03,252 - DEBUG - cat >> /etc/bird/bird.conf << EOS protocol bgp bgp_2{ local as 65000; neighbor 151.0.62.194 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 151.0.62.195; } EOS 2026-10-26 04:42:03,569 - DEBUG - None 2026-10-26 04:42:03,569 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-10-26 04:42:03,569 - DEBUG - service bird restart 2026-10-26 04:42:04,652 - DEBUG - bird stop/waiting bird start/running, process 1437 2026-10-26 04:42:04,652 - INFO - Attaching VMI 6c43dbcc-9abb-491c-854c-48d478e53ddf to BGPaaS cc911fb9-6f1b-4b81-8779-c75677802226 2026-10-26 04:42:04,737 - INFO - Attaching the VMI 9baf9581-dfba-4661-93ae-5fe41c494e8a to the BGPaaS cc911fb9-6f1b-4b81-8779-c75677802226 object 2026-10-26 04:42:04,737 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 151.0.62.212 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-10-26 04:42:04,738 - DEBUG - sudo ip addr add 151.0.62.212 dev eth0 2026-10-26 04:42:05,202 - DEBUG - None 2026-10-26 04:42:05,269 - INFO - Creating BGPaaS ctest-TestBGPaasZone-48219217-40089877 2026-10-26 04:42:05,357 - INFO - BGPaaS: ctest-TestBGPaasZone-48219217-40089877(2ed2cb67-80be-4ebd-bccb-5f374cd64723) 2026-10-26 04:42:05,358 - DEBUG - Requesting: http://10.0.0.251:8082/bgp-as-a-service/2ed2cb67-80be-4ebd-bccb-5f374cd64723 2026-10-26 04:42:05,369 - INFO - verify_in_api_server passed for BGPaaS obj 2ed2cb67-80be-4ebd-bccb-5f374cd64723 2026-10-26 04:42:05,369 - INFO - BGPaaS(2ed2cb67-80be-4ebd-bccb-5f374cd64723): verify_on_setup passed 2026-10-26 04:42:05,369 - INFO - We will configure BGP on the VM 2026-10-26 04:42:05,873 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:42:05,873 - INFO - Configuring BGP on ctest-TestBGPaasZone-48219217-40412619 2026-10-26 04:42:05,874 - 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 151.0.62.196; protocol bgp bgp_1{ local as 65000; neighbor 151.0.62.193 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 151.0.62.196; } protocol bfd { neighbor 151.0.62.193 local 151.0.62.196 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:42:05,874 - 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 151.0.62.196; protocol bgp bgp_1{ local as 65000; neighbor 151.0.62.193 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 151.0.62.196; } protocol bfd { neighbor 151.0.62.193 local 151.0.62.196 multihop on; } EOS 2026-10-26 04:42:10,087 - DEBUG - None 2026-10-26 04:42:10,087 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS protocol bgp bgp_2{ local as 65000; neighbor 151.0.62.194 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 151.0.62.196; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:42:10,087 - DEBUG - cat >> /etc/bird/bird.conf << EOS protocol bgp bgp_2{ local as 65000; neighbor 151.0.62.194 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 151.0.62.196; } EOS 2026-10-26 04:42:10,422 - DEBUG - None 2026-10-26 04:42:10,422 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:42:10,422 - DEBUG - service bird restart 2026-10-26 04:42:11,342 - DEBUG - bird stop/waiting bird start/running, process 1436 2026-10-26 04:42:11,342 - INFO - Attaching VMI 1da1415c-87ca-49c8-a2f7-9b55aa5f9dbe to BGPaaS 2ed2cb67-80be-4ebd-bccb-5f374cd64723 2026-10-26 04:42:11,429 - INFO - Attaching the VMI 617b2ef4-0120-4e87-a90d-37280cb2e4db to the BGPaaS 2ed2cb67-80be-4ebd-bccb-5f374cd64723 object 2026-10-26 04:42:11,430 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 151.0.62.212 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123 2026-10-26 04:42:11,430 - DEBUG - sudo ip addr add 151.0.62.212 dev eth0 2026-10-26 04:42:11,821 - DEBUG - None 2026-10-26 04:42:11,821 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:42:11,821 - DEBUG - Requesting: http://10.20.0.29:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-10-26 04:42:11,833 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.29'] 2026-10-26 04:42:17,837 - DEBUG - Requesting: http://10.20.0.29:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-10-26 04:42:17,857 - INFO - BGPaaS session seen in control-node 10.20.0.29 2026-10-26 04:42:17,858 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:42:17,880 - INFO - BGPaaS session seen in control-node 10.20.0.254 2026-10-26 04:42:17,887 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:42:17,887 - DEBUG - Requesting: http://10.20.0.29:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-10-26 04:42:17,899 - INFO - BGPaaS session seen in control-node 10.20.0.29 2026-10-26 04:42:17,899 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:42:17,915 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.254'] 2026-10-26 04:42:23,938 - INFO - BGPaaS session seen in control-node 10.20.0.254 2026-10-26 04:42:23,946 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 151.0.62.212, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-10-26 04:42:23,946 - DEBUG - ping -s 56 -c 3 -W 1 151.0.62.212 2026-10-26 04:42:28,609 - DEBUG - PING 151.0.62.212 (151.0.62.212) 56(84) bytes of data. 64 bytes from 151.0.62.212: icmp_seq=1 ttl=64 time=8.29 ms 64 bytes from 151.0.62.212: icmp_seq=2 ttl=64 time=1.53 ms 64 bytes from 151.0.62.212: icmp_seq=3 ttl=64 time=1.96 ms --- 151.0.62.212 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.537/3.930/8.293/3.090 ms 2026-10-26 04:42:28,609 - INFO - Ping to IP 151.0.62.212 from VM ctest-TestBGPaasZone-48219217-11240620 passed 2026-10-26 04:42:28,609 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:42:28,609 - DEBUG - Requesting: http://10.20.0.29:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-10-26 04:42:28,622 - INFO - BGPaaS session seen in control-node 10.20.0.29 2026-10-26 04:42:28,623 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:42:28,641 - INFO - BGPaaS session seen in control-node 10.20.0.254 2026-10-26 04:42:28,650 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:42:28,650 - DEBUG - Requesting: http://10.20.0.29:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-10-26 04:42:28,677 - INFO - BGPaaS session seen in control-node 10.20.0.29 2026-10-26 04:42:28,678 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-26 04:42:28,697 - INFO - BGPaaS session seen in control-node 10.20.0.254 2026-10-26 04:42:28,706 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 151.0.62.212, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.38, gateway password: c0ntrail123 2026-10-26 04:42:28,706 - DEBUG - ping -s 56 -c 3 -W 1 151.0.62.212 2026-10-26 04:42:30,998 - DEBUG - PING 151.0.62.212 (151.0.62.212) 56(84) bytes of data. 64 bytes from 151.0.62.212: icmp_seq=1 ttl=64 time=2.96 ms 64 bytes from 151.0.62.212: icmp_seq=2 ttl=64 time=1.81 ms 64 bytes from 151.0.62.212: icmp_seq=3 ttl=64 time=1.68 ms --- 151.0.62.212 ping statistics --- 3 packets transmitted, 3 received, 0% packet loss, time 2004ms rtt min/avg/max/mdev = 1.682/2.155/2.968/0.579 ms 2026-10-26 04:42:30,998 - INFO - Ping to IP 151.0.62.212 from VM ctest-TestBGPaasZone-48219217-11240620 passed 2026-10-26 04:42:30,998 - INFO - Detaching VMI 1da1415c-87ca-49c8-a2f7-9b55aa5f9dbe from BGPaaS 2ed2cb67-80be-4ebd-bccb-5f374cd64723 2026-10-26 04:42:31,109 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-48219217-40089877(2ed2cb67-80be-4ebd-bccb-5f374cd64723) 2026-10-26 04:42:31,200 - DEBUG - Requesting: http://10.0.0.251:8082/bgp-as-a-service/2ed2cb67-80be-4ebd-bccb-5f374cd64723 2026-10-26 04:42:31,210 - DEBUG - Response Code: 404 2026-10-26 04:42:31,210 - DEBUG - BGPaaS: 2ed2cb67-80be-4ebd-bccb-5f374cd64723 deleted from api server 2026-10-26 04:42:31,210 - INFO - BGPaaS(2ed2cb67-80be-4ebd-bccb-5f374cd64723): verify_on_cleanup passed 2026-10-26 04:42:31,210 - INFO - Detaching VMI 6c43dbcc-9abb-491c-854c-48d478e53ddf from BGPaaS cc911fb9-6f1b-4b81-8779-c75677802226 2026-10-26 04:42:31,365 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-48219217-27979308(cc911fb9-6f1b-4b81-8779-c75677802226) 2026-10-26 04:42:31,480 - DEBUG - Requesting: http://10.0.0.251:8082/bgp-as-a-service/cc911fb9-6f1b-4b81-8779-c75677802226 2026-10-26 04:42:31,488 - DEBUG - Response Code: 404 2026-10-26 04:42:31,488 - DEBUG - BGPaaS: cc911fb9-6f1b-4b81-8779-c75677802226 deleted from api server 2026-10-26 04:42:31,488 - INFO - BGPaaS(cc911fb9-6f1b-4b81-8779-c75677802226): verify_on_cleanup passed 2026-10-26 04:42:31,489 - INFO - Deleting Control node zone ctest-test-zone-80860664(fdc37710-9934-465f-b028-8726bd80706c) 2026-10-26 04:42:31,695 - INFO - Deleting Control node zone ctest-test-zone-35802245(268f7a50-96a2-49fe-ac29-6147352b85f2) 2026-10-26 04:42:31,905 - INFO - Deleting Control node zone ctest-test-zone-82256763(7ba60dd3-290f-4cd5-9b00-dd2da59b3ccd) 2026-10-26 04:42:32,081 - INFO - Deleting VM ctest-TestBGPaasZone-48219217-11240620 2026-10-26 04:42:32,182 - INFO - Deleting VM ctest-TestBGPaasZone-48219217-40412619 2026-10-26 04:42:32,290 - INFO - Deleting VM ctest-TestBGPaasZone-48219217-79531434 2026-10-26 04:42:32,365 - INFO - Deleting VN ctest-cnz_vn-52359012 2026-10-26 04:42:32,411 - DEBUG - VN ee0d8f85-0b6e-4a07-aa59-619753337eaa still in use: Unable to complete operation on network ee0d8f85-0b6e-4a07-aa59-619753337eaa. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-d941d9ec-f7da-4490-99e7-4b37ef7b1937'] 2026-10-26 04:42:32,411 - WARNING - Deleting VN ctest-cnz_vn-52359012 failed..Will retry 2026-10-26 04:42:34,452 - DEBUG - VN ee0d8f85-0b6e-4a07-aa59-619753337eaa still in use: Unable to complete operation on network ee0d8f85-0b6e-4a07-aa59-619753337eaa. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-462edd53-16dc-4b7c-945d-5b6219cdaf04'] 2026-10-26 04:42:34,452 - WARNING - Deleting VN ctest-cnz_vn-52359012 failed..Will retry 2026-10-26 04:42:36,709 - DEBUG - Response for deleting network () 2026-10-26 04:42:38,022 - DEBUG - Skipping xmpp flap check
2026-10-26 04:42:38,022 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:33]
2026-10-26 04:42:38,022 - INFO - -------------------------------------------------------------------------------- 2026-10-26 04:42:38,958 - INFO - Deleted project: ctest-TestBGPaasZone-48219217, ID : 470861c3-d731-4055-a708-820cbaeed638