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