2025-10-08 15:26:39,849 - INFO - Domain Default found not creating
2025-10-08 15:26:40,074 - INFO - Project ctest-TestBGPaasZone-61754549 not found, creating it
2025-10-08 15:26:40,743 - INFO - Created Project:ctest-TestBGPaasZone-61754549, ID : 78c3211e-c302-4a01-831f-7df65b79cef8
2025-10-08 15:26:42,533 - DEBUG - Services list from nova: [, , , ]
2025-10-08 15:26:42,533 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-4236-2', 'an-jenkins-deploy-platform-ansible-os-4236-1']}
2025-10-08 15:26:42,536 - INFO - ================================================================================
2025-10-08 15:26:42,536 - INFO - STARTING TEST : test_bgp_control_node_zone
2025-10-08 15:26:43,820 - DEBUG - Skipping xmpp flap check
2025-10-08 15:26:43,820 - INFO - Initial checks done. Running the testcase now
2025-10-08 15:26:43,820 - INFO -
2025-10-08 15:26:43,820 - INFO - executing bgp_control_zone test
2025-10-08 15:26:44,572 - DEBUG - Response for create_network : {'network': {'id': '7725aa57-028e-4e64-ab88-905b14c757ef', 'name': 'ctest-cnz_vn-19249773', 'tenant_id': '78c3211ec3024a01831f7df65b79cef8', 'project_id': '78c3211ec3024a01831f7df65b79cef8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBGPaasZone-61754549', 'ctest-cnz_vn-19249773'], 'port_security_enabled': True, 'description': ''}}
2025-10-08 15:26:44,840 - DEBUG - Response for create_subnet : {'subnet': {'id': '404be6af-c23c-4790-8fec-7ccf5fe99042', 'name': '', 'tenant_id': '78c3211ec3024a01831f7df65b79cef8', 'network_id': '7725aa57-028e-4e64-ab88-905b14c757ef', 'ip_version': 4, 'cidr': '77.128.111.64/26', 'allocation_pools': [{'start': '77.128.111.66', 'end': '77.128.111.126'}], 'gateway_ip': '77.128.111.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '77.128.111.66', 'tags': [], 'project_id': '78c3211ec3024a01831f7df65b79cef8'}}
2025-10-08 15:26:44,867 - INFO - Created VN ctest-cnz_vn-19249773
2025-10-08 15:26:44,882 - DEBUG - VN ctest-cnz_vn-19249773 UUID is 7725aa57-028e-4e64-ab88-905b14c757ef
2025-10-08 15:26:46,277 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-2)
2025-10-08 15:26:48,016 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-1)
2025-10-08 15:26:49,925 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-2)
2025-10-08 15:26:49,925 - INFO - Waiting for VM ctest-TestBGPaasZone-61754549-07223319 to be up..
2025-10-08 15:26:50,097 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-10-08 15:26:55,252 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-10-08 15:27:00,352 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-10-08 15:27:05,482 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-10-08 15:27:10,585 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-10-08 15:27:15,681 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-10-08 15:27:20,797 - DEBUG - VM is still in BUILD state, Expected: ACTIVE
2025-10-08 15:27:25,902 - DEBUG - VM is in ACTIVE state now
2025-10-08 15:27:25,902 - INFO - VM name : ctest-TestBGPaasZone-61754549-07223319
2025-10-08 15:27:26,004 - DEBUG - VM ctest-TestBGPaasZone-61754549-07223319 ID is 2008f0ab-3e85-452e-a32f-4da79944d3fe
2025-10-08 15:27:26,046 - DEBUG - VM ctest-TestBGPaasZone-61754549-07223319 launched on Node an-jenkins-deploy-platform-ansible-os-4236-2
2025-10-08 15:27:26,140 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/2008f0ab-3e85-452e-a32f-4da79944d3fe
2025-10-08 15:27:26,464 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/2008f0ab-3e85-452e-a32f-4da79944d3fe
2025-10-08 15:27:26,514 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/168c612d-4c2c-4c4a-9af0-230e4ac8f4c7
2025-10-08 15:27:29,704 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms')
2025-10-08 15:27:29,704 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:27:29,721 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:27:33,806 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1022ms')
2025-10-08 15:27:33,806 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:27:33,821 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:27:37,899 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1008ms')
2025-10-08 15:27:37,899 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:27:37,920 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:27:41,993 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms')
2025-10-08 15:27:41,994 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:27:42,012 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:27:46,087 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms')
2025-10-08 15:27:46,087 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:27:46,103 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:27:50,179 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms')
2025-10-08 15:27:50,180 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:27:50,197 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:27:54,281 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1020ms')
2025-10-08 15:27:54,281 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:27:54,297 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:27:58,375 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms')
2025-10-08 15:27:58,375 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:27:58,391 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:28:02,470 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms')
2025-10-08 15:28:02,471 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:28:02,486 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:28:06,562 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms')
2025-10-08 15:28:06,562 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:28:06,579 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:28:10,658 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1022ms')
2025-10-08 15:28:10,658 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:28:10,677 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:28:14,760 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1024ms')
2025-10-08 15:28:14,760 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:28:14,776 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:28:18,852 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1017ms')
2025-10-08 15:28:18,852 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:28:18,867 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:28:22,946 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms')
2025-10-08 15:28:22,946 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:28:22,961 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:28:27,042 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms')
2025-10-08 15:28:27,042 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:28:27,058 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:28:31,146 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms')
2025-10-08 15:28:31,146 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:28:31,162 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:28:35,241 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2025-10-08 15:28:35,241 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:28:35,258 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:28:39,330 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2025-10-08 15:28:39,330 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 failed!
2025-10-08 15:28:39,348 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-19249773 is 77.128.111.65 and allocation pool is NOT set
2025-10-08 15:28:41,416 - 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.31 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.78 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.778/2.544/3.311/0.766 ms')
2025-10-08 15:28:41,416 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-07223319 passed
2025-10-08 15:28:41,492 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:28:41,492 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-07223319, IP 77.128.111.67, Port 22
2025-10-08 15:28:41,559 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:28:41,743 - DEBUG - VM ctest-TestBGPaasZone-61754549-07223319 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:28:46,744 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:28:46,744 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-07223319, IP 77.128.111.67, Port 22
2025-10-08 15:28:46,811 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:28:46,920 - DEBUG - VM ctest-TestBGPaasZone-61754549-07223319 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:28:51,921 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:28:51,922 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-07223319, IP 77.128.111.67, Port 22
2025-10-08 15:28:51,981 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:28:52,099 - DEBUG - VM ctest-TestBGPaasZone-61754549-07223319 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:28:57,103 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:28:57,103 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-07223319, IP 77.128.111.67, Port 22
2025-10-08 15:28:57,171 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:28:57,272 - DEBUG - VM ctest-TestBGPaasZone-61754549-07223319 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:29:02,273 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:29:02,273 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-07223319, IP 77.128.111.67, Port 22
2025-10-08 15:29:02,343 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:29:02,441 - DEBUG - VM ctest-TestBGPaasZone-61754549-07223319 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:29:07,441 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:29:07,442 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-07223319, IP 77.128.111.67, Port 22
2025-10-08 15:29:07,521 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:29:07,619 - DEBUG - VM ctest-TestBGPaasZone-61754549-07223319 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:29:12,620 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:29:12,620 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-07223319, IP 77.128.111.67, Port 22
2025-10-08 15:29:12,685 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:29:12,783 - DEBUG - VM ctest-TestBGPaasZone-61754549-07223319 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:29:17,783 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:29:17,784 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-07223319, IP 77.128.111.67, Port 22
2025-10-08 15:29:17,850 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:29:17,947 - DEBUG - VM ctest-TestBGPaasZone-61754549-07223319 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:29:22,948 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:29:22,948 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-07223319, IP 77.128.111.67, Port 22
2025-10-08 15:29:23,015 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:29:23,109 - DEBUG - VM ctest-TestBGPaasZone-61754549-07223319 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:29:28,110 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:29:28,110 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-07223319, IP 77.128.111.67, Port 22
2025-10-08 15:29:28,168 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:29:28,270 - DEBUG - VM ctest-TestBGPaasZone-61754549-07223319 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:29:33,272 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:29:33,272 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-07223319, IP 77.128.111.67, Port 22
2025-10-08 15:29:33,446 - DEBUG - VM ctest-TestBGPaasZone-61754549-07223319 is ready for SSH connections
2025-10-08 15:29:33,446 - INFO - Waiting for VM ctest-TestBGPaasZone-61754549-74524862 to be up..
2025-10-08 15:29:33,549 - DEBUG - VM is in ACTIVE state now
2025-10-08 15:29:33,549 - INFO - VM name : ctest-TestBGPaasZone-61754549-74524862
2025-10-08 15:29:33,647 - DEBUG - VM ctest-TestBGPaasZone-61754549-74524862 ID is bd94f622-7ddc-4b89-8ad8-2242eb12639d
2025-10-08 15:29:33,647 - DEBUG - VM ctest-TestBGPaasZone-61754549-74524862 launched on Node an-jenkins-deploy-platform-ansible-os-4236-1
2025-10-08 15:29:33,747 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/bd94f622-7ddc-4b89-8ad8-2242eb12639d
2025-10-08 15:29:33,760 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/204adeca-af05-4dcf-95ed-8425d0d60e83
2025-10-08 15:29:34,924 - 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.45 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.528 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.528/1.990/3.452/1.462 ms')
2025-10-08 15:29:34,924 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-74524862 passed
2025-10-08 15:29:34,998 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:29:34,999 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-74524862, IP 77.128.111.68, Port 22
2025-10-08 15:29:35,155 - DEBUG - VM ctest-TestBGPaasZone-61754549-74524862 is ready for SSH connections
2025-10-08 15:29:35,155 - INFO - Waiting for VM ctest-TestBGPaasZone-61754549-90789457 to be up..
2025-10-08 15:29:35,260 - DEBUG - VM is in ACTIVE state now
2025-10-08 15:29:35,261 - INFO - VM name : ctest-TestBGPaasZone-61754549-90789457
2025-10-08 15:29:35,387 - DEBUG - VM ctest-TestBGPaasZone-61754549-90789457 ID is 515570cd-a2d1-4b24-afe4-f962da9d5eee
2025-10-08 15:29:35,387 - DEBUG - VM ctest-TestBGPaasZone-61754549-90789457 launched on Node an-jenkins-deploy-platform-ansible-os-4236-2
2025-10-08 15:29:35,486 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/515570cd-a2d1-4b24-afe4-f962da9d5eee
2025-10-08 15:29:35,500 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/89687c43-b7cb-47ef-8dd8-a2389f5c21b0
2025-10-08 15:29:36,682 - 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.51 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.714 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.714/3.612/6.510/2.898 ms')
2025-10-08 15:29:36,682 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-61754549-90789457 passed
2025-10-08 15:29:36,754 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:29:36,754 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-90789457, IP 77.128.111.69, Port 22
2025-10-08 15:29:36,917 - DEBUG - VM ctest-TestBGPaasZone-61754549-90789457 is ready for SSH connections
2025-10-08 15:29:37,056 - INFO - Created control node zone ctest-test-zone-32479602(71d75741-aa67-4bf3-a790-0e9f87a3764b)
2025-10-08 15:29:37,428 - INFO - Created control node zone ctest-test-zone-44616570(da585102-18e1-4ac5-ac38-8b2f6dbbc2a9)
2025-10-08 15:29:37,833 - INFO - Created control node zone ctest-test-zone-75009100(09d93cec-e088-49da-951b-adacffcb89ff)
2025-10-08 15:29:38,152 - INFO - Creating BGPaaS ctest-TestBGPaasZone-61754549-81676017
2025-10-08 15:29:38,252 - INFO - BGPaaS: ctest-TestBGPaasZone-61754549-81676017(dd42f9b5-6537-40c0-860c-d826184a6215)
2025-10-08 15:29:38,252 - DEBUG - Requesting: http://10.0.0.133:8082/bgp-as-a-service/dd42f9b5-6537-40c0-860c-d826184a6215
2025-10-08 15:29:38,577 - DEBUG - Requesting: http://10.0.0.133:8082/bgp-as-a-service/dd42f9b5-6537-40c0-860c-d826184a6215
2025-10-08 15:29:38,613 - INFO - verify_in_api_server passed for BGPaaS obj dd42f9b5-6537-40c0-860c-d826184a6215
2025-10-08 15:29:38,613 - INFO - BGPaaS(dd42f9b5-6537-40c0-860c-d826184a6215): verify_on_setup passed
2025-10-08 15:29:38,613 - INFO - We will configure BGP on the VM
2025-10-08 15:29:38,912 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:29:38,912 - INFO - Configuring BGP on ctest-TestBGPaasZone-61754549-07223319
2025-10-08 15:29:38,913 - 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 77.128.111.67;
protocol bgp bgp_1{
local as 65000;
neighbor 77.128.111.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 77.128.111.67;
}
protocol bfd {
neighbor 77.128.111.65 local 77.128.111.67 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:29:38,913 - 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 77.128.111.67;
protocol bgp bgp_1{
local as 65000;
neighbor 77.128.111.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 77.128.111.67;
}
protocol bfd {
neighbor 77.128.111.65 local 77.128.111.67 multihop on;
}
EOS
2025-10-08 15:29:52,154 - DEBUG - None
2025-10-08 15:29:52,155 - 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 77.128.111.67;
protocol bgp bgp_1{
local as 65000;
neighbor 77.128.111.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 77.128.111.67;
}
protocol bfd {
neighbor 77.128.111.65 local 77.128.111.67 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:29:52,155 - 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 77.128.111.67;
protocol bgp bgp_1{
local as 65000;
neighbor 77.128.111.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 77.128.111.67;
}
protocol bfd {
neighbor 77.128.111.65 local 77.128.111.67 multihop on;
}
EOS
2025-10-08 15:29:53,172 - DEBUG - None
2025-10-08 15:29:53,172 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:29:53,173 - DEBUG - service bird restart
2025-10-08 15:29:56,532 - DEBUG - bird stop/waiting
bird start/running, process 1440
2025-10-08 15:29:56,533 - INFO - Attaching VMI 168c612d-4c2c-4c4a-9af0-230e4ac8f4c7 to BGPaaS dd42f9b5-6537-40c0-860c-d826184a6215
2025-10-08 15:29:56,612 - INFO - Attaching the VMI 2008f0ab-3e85-452e-a32f-4da79944d3fe to the BGPaaS dd42f9b5-6537-40c0-860c-d826184a6215 object
2025-10-08 15:29:56,612 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 77.128.111.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:29:56,612 - DEBUG - sudo ip addr add 77.128.111.84 dev eth0
2025-10-08 15:29:57,468 - DEBUG - None
2025-10-08 15:29:57,540 - INFO - Creating BGPaaS ctest-TestBGPaasZone-61754549-14301783
2025-10-08 15:29:57,628 - INFO - BGPaaS: ctest-TestBGPaasZone-61754549-14301783(52f1b2cd-db58-4dff-a54b-85a2882d838c)
2025-10-08 15:29:57,628 - DEBUG - Requesting: http://10.0.0.133:8082/bgp-as-a-service/52f1b2cd-db58-4dff-a54b-85a2882d838c
2025-10-08 15:29:57,640 - INFO - verify_in_api_server passed for BGPaaS obj 52f1b2cd-db58-4dff-a54b-85a2882d838c
2025-10-08 15:29:57,640 - INFO - BGPaaS(52f1b2cd-db58-4dff-a54b-85a2882d838c): verify_on_setup passed
2025-10-08 15:29:57,641 - INFO - We will configure BGP on the VM
2025-10-08 15:29:57,932 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:29:57,932 - INFO - Configuring BGP on ctest-TestBGPaasZone-61754549-74524862
2025-10-08 15:29:57,933 - 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 77.128.111.68;
protocol bgp bgp_1{
local as 65000;
neighbor 77.128.111.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 77.128.111.68;
}
protocol bfd {
neighbor 77.128.111.66 local 77.128.111.68 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-10-08 15:29:57,933 - 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 77.128.111.68;
protocol bgp bgp_1{
local as 65000;
neighbor 77.128.111.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 77.128.111.68;
}
protocol bfd {
neighbor 77.128.111.66 local 77.128.111.68 multihop on;
}
EOS
2025-10-08 15:30:03,687 - DEBUG - None
2025-10-08 15:30:03,688 - 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 77.128.111.68;
protocol bgp bgp_1{
local as 65000;
neighbor 77.128.111.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 77.128.111.68;
}
protocol bfd {
neighbor 77.128.111.66 local 77.128.111.68 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-10-08 15:30:03,688 - 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 77.128.111.68;
protocol bgp bgp_1{
local as 65000;
neighbor 77.128.111.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 77.128.111.68;
}
protocol bfd {
neighbor 77.128.111.66 local 77.128.111.68 multihop on;
}
EOS
2025-10-08 15:30:04,184 - DEBUG - None
2025-10-08 15:30:04,184 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-10-08 15:30:04,185 - DEBUG - service bird restart
2025-10-08 15:30:05,809 - DEBUG - bird stop/waiting
bird start/running, process 1441
2025-10-08 15:30:05,809 - INFO - Attaching VMI 204adeca-af05-4dcf-95ed-8425d0d60e83 to BGPaaS 52f1b2cd-db58-4dff-a54b-85a2882d838c
2025-10-08 15:30:05,900 - INFO - Attaching the VMI bd94f622-7ddc-4b89-8ad8-2242eb12639d to the BGPaaS 52f1b2cd-db58-4dff-a54b-85a2882d838c object
2025-10-08 15:30:05,900 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 77.128.111.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-10-08 15:30:05,901 - DEBUG - sudo ip addr add 77.128.111.84 dev eth0
2025-10-08 15:30:06,534 - DEBUG - None
2025-10-08 15:30:06,534 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:30:06,534 - DEBUG - Requesting: http://10.20.0.200:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-08 15:30:06,557 - INFO - BGPaaS session seen in control-node 10.20.0.200
2025-10-08 15:30:06,564 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:30:06,589 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.252']
2025-10-08 15:30:12,611 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.252']
2025-10-08 15:30:18,638 - INFO - BGPaaS session seen in control-node 10.20.0.252
2025-10-08 15:30:18,648 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 77.128.111.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:30:18,648 - DEBUG - ping -s 56 -c 3 -W 1 77.128.111.84
2025-10-08 15:30:24,868 - DEBUG - PING 77.128.111.84 (77.128.111.84) 56(84) bytes of data.
64 bytes from 77.128.111.84: icmp_seq=1 ttl=64 time=9.65 ms
64 bytes from 77.128.111.84: icmp_seq=2 ttl=64 time=1.36 ms
64 bytes from 77.128.111.84: icmp_seq=3 ttl=64 time=1.09 ms
--- 77.128.111.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.090/4.034/9.651/3.973 ms
2025-10-08 15:30:24,869 - INFO - Ping to IP 77.128.111.84 from VM ctest-TestBGPaasZone-61754549-90789457 passed
2025-10-08 15:30:25,712 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:30:25,712 - DEBUG - service bird restart
2025-10-08 15:30:27,446 - DEBUG - bird stop/waiting
bird start/running, process 1468
2025-10-08 15:30:27,446 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-10-08 15:30:27,446 - DEBUG - service bird restart
2025-10-08 15:30:29,083 - DEBUG - bird stop/waiting
bird start/running, process 1468
2025-10-08 15:30:29,083 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:30:29,108 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.249']
2025-10-08 15:30:35,130 - INFO - BGPaaS session seen in control-node 10.20.0.249
2025-10-08 15:30:35,137 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:30:35,157 - INFO - BGPaaS session seen in control-node 10.20.0.252
2025-10-08 15:30:35,163 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 77.128.111.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:30:35,164 - DEBUG - ping -s 56 -c 3 -W 1 77.128.111.84
2025-10-08 15:30:37,640 - DEBUG - PING 77.128.111.84 (77.128.111.84) 56(84) bytes of data.
64 bytes from 77.128.111.84: icmp_seq=1 ttl=64 time=2.87 ms
64 bytes from 77.128.111.84: icmp_seq=2 ttl=64 time=1.19 ms
64 bytes from 77.128.111.84: icmp_seq=3 ttl=64 time=1.21 ms
--- 77.128.111.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 1.192/1.762/2.879/0.789 ms
2025-10-08 15:30:37,640 - INFO - Ping to IP 77.128.111.84 from VM ctest-TestBGPaasZone-61754549-90789457 passed
2025-10-08 15:30:37,979 - INFO - We will configure BGP on the VM
2025-10-08 15:30:38,276 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:30:38,276 - INFO - Configuring BGP on ctest-TestBGPaasZone-61754549-07223319
2025-10-08 15:30:38,277 - 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 77.128.111.67;
protocol bgp bgp_1{
local as 65000;
neighbor 77.128.111.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 77.128.111.67;
}
protocol bfd {
neighbor 77.128.111.65 local 77.128.111.67 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:30:38,277 - 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 77.128.111.67;
protocol bgp bgp_1{
local as 65000;
neighbor 77.128.111.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 77.128.111.67;
}
protocol bfd {
neighbor 77.128.111.65 local 77.128.111.67 multihop on;
}
EOS
2025-10-08 15:30:38,746 - DEBUG - None
2025-10-08 15:30:38,746 - 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 77.128.111.67;
protocol bgp bgp_1{
local as 65000;
neighbor 77.128.111.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 77.128.111.67;
}
protocol bfd {
neighbor 77.128.111.65 local 77.128.111.67 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:30:38,746 - 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 77.128.111.67;
protocol bgp bgp_1{
local as 65000;
neighbor 77.128.111.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 77.128.111.67;
}
protocol bfd {
neighbor 77.128.111.65 local 77.128.111.67 multihop on;
}
EOS
2025-10-08 15:30:39,246 - DEBUG - None
2025-10-08 15:30:39,246 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:30:39,246 - DEBUG - service bird restart
2025-10-08 15:30:40,914 - DEBUG - bird stop/waiting
bird start/running, process 1500
2025-10-08 15:30:40,914 - INFO - Attaching VMI 168c612d-4c2c-4c4a-9af0-230e4ac8f4c7 to BGPaaS dd42f9b5-6537-40c0-860c-d826184a6215
2025-10-08 15:30:40,937 - INFO - Attaching the VMI 2008f0ab-3e85-452e-a32f-4da79944d3fe to the BGPaaS dd42f9b5-6537-40c0-860c-d826184a6215 object
2025-10-08 15:30:40,938 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 77.128.111.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:30:40,938 - DEBUG - sudo ip addr add 77.128.111.84 dev eth0
2025-10-08 15:30:41,638 - DEBUG - RTNETLINK answers: File exists
2025-10-08 15:30:41,638 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:30:41,638 - DEBUG - service bird restart
2025-10-08 15:30:43,319 - DEBUG - bird stop/waiting
bird start/running, process 1527
2025-10-08 15:30:43,319 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-10-08 15:30:43,319 - DEBUG - service bird restart
2025-10-08 15:30:44,904 - DEBUG - bird stop/waiting
bird start/running, process 1490
2025-10-08 15:30:44,905 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:30:44,905 - DEBUG - Requesting: http://10.20.0.200:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-08 15:30:44,921 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.200']
2025-10-08 15:30:50,922 - DEBUG - Requesting: http://10.20.0.200:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-08 15:30:50,937 - INFO - BGPaaS session seen in control-node 10.20.0.200
2025-10-08 15:30:50,943 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:30:50,961 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.252']
2025-10-08 15:30:56,981 - INFO - BGPaaS session seen in control-node 10.20.0.252
2025-10-08 15:30:56,988 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 77.128.111.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:30:56,989 - DEBUG - ping -s 56 -c 3 -W 1 77.128.111.84
2025-10-08 15:30:59,448 - DEBUG - PING 77.128.111.84 (77.128.111.84) 56(84) bytes of data.
64 bytes from 77.128.111.84: icmp_seq=1 ttl=64 time=3.31 ms
64 bytes from 77.128.111.84: icmp_seq=2 ttl=64 time=1.21 ms
64 bytes from 77.128.111.84: icmp_seq=3 ttl=64 time=1.78 ms
--- 77.128.111.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.210/2.100/3.312/0.889 ms
2025-10-08 15:30:59,448 - INFO - Ping to IP 77.128.111.84 from VM ctest-TestBGPaasZone-61754549-90789457 passed
2025-10-08 15:30:59,448 - INFO - Detaching VMI 168c612d-4c2c-4c4a-9af0-230e4ac8f4c7 from BGPaaS dd42f9b5-6537-40c0-860c-d826184a6215
2025-10-08 15:30:59,525 - INFO - Detaching VMI 204adeca-af05-4dcf-95ed-8425d0d60e83 from BGPaaS 52f1b2cd-db58-4dff-a54b-85a2882d838c
2025-10-08 15:30:59,644 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-61754549-14301783(52f1b2cd-db58-4dff-a54b-85a2882d838c)
2025-10-08 15:30:59,728 - DEBUG - Requesting: http://10.0.0.133:8082/bgp-as-a-service/52f1b2cd-db58-4dff-a54b-85a2882d838c
2025-10-08 15:30:59,738 - DEBUG - Response Code: 404
2025-10-08 15:30:59,739 - DEBUG - BGPaaS: 52f1b2cd-db58-4dff-a54b-85a2882d838c deleted from api server
2025-10-08 15:30:59,739 - INFO - BGPaaS(52f1b2cd-db58-4dff-a54b-85a2882d838c): verify_on_cleanup passed
2025-10-08 15:30:59,739 - INFO - Detaching VMI 168c612d-4c2c-4c4a-9af0-230e4ac8f4c7 from BGPaaS dd42f9b5-6537-40c0-860c-d826184a6215
2025-10-08 15:30:59,767 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-61754549-81676017(dd42f9b5-6537-40c0-860c-d826184a6215)
2025-10-08 15:30:59,870 - DEBUG - Requesting: http://10.0.0.133:8082/bgp-as-a-service/dd42f9b5-6537-40c0-860c-d826184a6215
2025-10-08 15:30:59,883 - DEBUG - Response Code: 404
2025-10-08 15:30:59,883 - DEBUG - BGPaaS: dd42f9b5-6537-40c0-860c-d826184a6215 deleted from api server
2025-10-08 15:30:59,883 - INFO - BGPaaS(dd42f9b5-6537-40c0-860c-d826184a6215): verify_on_cleanup passed
2025-10-08 15:30:59,883 - INFO - Deleting Control node zone ctest-test-zone-75009100(09d93cec-e088-49da-951b-adacffcb89ff)
2025-10-08 15:31:00,049 - INFO - Deleting Control node zone ctest-test-zone-44616570(da585102-18e1-4ac5-ac38-8b2f6dbbc2a9)
2025-10-08 15:31:00,214 - INFO - Deleting Control node zone ctest-test-zone-32479602(71d75741-aa67-4bf3-a790-0e9f87a3764b)
2025-10-08 15:31:00,310 - INFO - Deleting VM ctest-TestBGPaasZone-61754549-90789457
2025-10-08 15:31:00,414 - INFO - Deleting VM ctest-TestBGPaasZone-61754549-74524862
2025-10-08 15:31:00,531 - INFO - Deleting VM ctest-TestBGPaasZone-61754549-07223319
2025-10-08 15:31:00,629 - INFO - Deleting VN ctest-cnz_vn-19249773
2025-10-08 15:31:00,694 - DEBUG - VN 7725aa57-028e-4e64-ab88-905b14c757ef still in use: Unable to complete operation on network 7725aa57-028e-4e64-ab88-905b14c757ef. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-952b62a7-7438-4efd-a919-23351b2d8cc4']
2025-10-08 15:31:00,695 - WARNING - Deleting VN ctest-cnz_vn-19249773 failed..Will retry
2025-10-08 15:31:02,904 - DEBUG - Response for deleting network ()
2025-10-08 15:31:04,228 - DEBUG - Skipping xmpp flap check
2025-10-08 15:31:04,229 - INFO - END TEST : test_bgp_control_node_zone : PASSED[0:04:22]
2025-10-08 15:31:04,229 - INFO - --------------------------------------------------------------------------------
2025-10-08 15:31:04,233 - INFO - ================================================================================
2025-10-08 15:31:04,233 - INFO - STARTING TEST : test_bgp_control_node_zones_from_single_vnf
2025-10-08 15:31:05,501 - DEBUG - Skipping xmpp flap check
2025-10-08 15:31:05,501 - INFO - Initial checks done. Running the testcase now
2025-10-08 15:31:05,501 - INFO -
2025-10-08 15:31:05,501 - INFO - executing bgp_control_zone agent restart test
2025-10-08 15:31:05,974 - DEBUG - Response for create_network : {'network': {'id': '4553a24e-d62a-444c-8265-c41dd2040701', 'name': 'ctest-cnz_vn-59748032', 'tenant_id': '78c3211ec3024a01831f7df65b79cef8', 'project_id': '78c3211ec3024a01831f7df65b79cef8', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBGPaasZone-61754549', 'ctest-cnz_vn-59748032'], 'port_security_enabled': True, 'description': ''}}
2025-10-08 15:31:06,243 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e6757ada-e2f3-4f0c-bd63-6652272e433f', 'name': '', 'tenant_id': '78c3211ec3024a01831f7df65b79cef8', 'network_id': '4553a24e-d62a-444c-8265-c41dd2040701', 'ip_version': 4, 'cidr': '74.216.133.64/26', 'allocation_pools': [{'start': '74.216.133.66', 'end': '74.216.133.126'}], 'gateway_ip': '74.216.133.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '74.216.133.66', 'tags': [], 'project_id': '78c3211ec3024a01831f7df65b79cef8'}}
2025-10-08 15:31:06,270 - INFO - Created VN ctest-cnz_vn-59748032
2025-10-08 15:31:06,283 - DEBUG - VN ctest-cnz_vn-59748032 UUID is 4553a24e-d62a-444c-8265-c41dd2040701
2025-10-08 15:31:07,837 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-1)
2025-10-08 15:31:09,784 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-2)
2025-10-08 15:31:11,499 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4236-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4236-1)
2025-10-08 15:31:11,499 - INFO - Waiting for VM ctest-TestBGPaasZone-61754549-14796462 to be up..
2025-10-08 15:31:11,619 - DEBUG - VM is in ACTIVE state now
2025-10-08 15:31:11,620 - INFO - VM name : ctest-TestBGPaasZone-61754549-14796462
2025-10-08 15:31:11,740 - DEBUG - VM ctest-TestBGPaasZone-61754549-14796462 ID is 566207a0-f520-4352-b80c-dd02e1c23b02
2025-10-08 15:31:11,740 - DEBUG - VM ctest-TestBGPaasZone-61754549-14796462 launched on Node an-jenkins-deploy-platform-ansible-os-4236-1
2025-10-08 15:31:11,844 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/566207a0-f520-4352-b80c-dd02e1c23b02
2025-10-08 15:31:11,856 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/f64531ce-ef16-4acb-9fa8-220f5570324c
2025-10-08 15:31:15,069 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms')
2025-10-08 15:31:15,069 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:31:15,087 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:31:19,169 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1018ms')
2025-10-08 15:31:19,169 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:31:19,196 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:31:23,264 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1003ms')
2025-10-08 15:31:23,264 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:31:23,280 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:31:27,353 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2025-10-08 15:31:27,353 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:31:27,375 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:31:31,456 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms')
2025-10-08 15:31:31,456 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:31:31,471 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:31:35,553 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms')
2025-10-08 15:31:35,553 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:31:35,573 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:31:39,643 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms')
2025-10-08 15:31:39,643 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:31:39,663 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:31:43,738 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms')
2025-10-08 15:31:43,738 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:31:43,754 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:31:47,841 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1023ms')
2025-10-08 15:31:47,841 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:31:47,891 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:31:51,964 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms')
2025-10-08 15:31:51,964 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:31:51,981 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:31:56,064 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms')
2025-10-08 15:31:56,064 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:31:56,082 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:32:00,160 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms')
2025-10-08 15:32:00,160 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:32:00,177 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:32:04,256 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1014ms')
2025-10-08 15:32:04,256 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:32:04,275 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:32:08,354 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1010ms')
2025-10-08 15:32:08,354 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:32:08,372 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:32:12,453 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1015ms')
2025-10-08 15:32:12,453 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:32:12,472 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:32:16,543 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1004ms')
2025-10-08 15:32:16,543 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:32:16,561 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:32:20,640 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms')
2025-10-08 15:32:20,640 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 failed!
2025-10-08 15:32:20,657 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaasZone-61754549:ctest-cnz_vn-59748032 is 74.216.133.65 and allocation pool is NOT set
2025-10-08 15:32:22,720 - 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.26 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.61 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.608/2.434/3.261/0.826 ms')
2025-10-08 15:32:22,720 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-14796462 passed
2025-10-08 15:32:22,809 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:32:22,809 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-14796462, IP 74.216.133.67, Port 22
2025-10-08 15:32:22,867 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:32:23,056 - DEBUG - VM ctest-TestBGPaasZone-61754549-14796462 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:32:28,057 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:32:28,057 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-14796462, IP 74.216.133.67, Port 22
2025-10-08 15:32:28,130 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:32:28,276 - DEBUG - VM ctest-TestBGPaasZone-61754549-14796462 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:32:33,277 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:32:33,277 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-14796462, IP 74.216.133.67, Port 22
2025-10-08 15:32:33,346 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:32:33,446 - DEBUG - VM ctest-TestBGPaasZone-61754549-14796462 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:32:38,446 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:32:38,447 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-14796462, IP 74.216.133.67, Port 22
2025-10-08 15:32:38,513 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:32:38,640 - DEBUG - VM ctest-TestBGPaasZone-61754549-14796462 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:32:43,641 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:32:43,641 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-14796462, IP 74.216.133.67, Port 22
2025-10-08 15:32:43,709 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:32:43,806 - DEBUG - VM ctest-TestBGPaasZone-61754549-14796462 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:32:48,807 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:32:48,807 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-14796462, IP 74.216.133.67, Port 22
2025-10-08 15:32:48,863 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:32:48,956 - DEBUG - VM ctest-TestBGPaasZone-61754549-14796462 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:32:53,956 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:32:53,957 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-14796462, IP 74.216.133.67, Port 22
2025-10-08 15:32:54,016 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:32:54,128 - DEBUG - VM ctest-TestBGPaasZone-61754549-14796462 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:32:59,128 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:32:59,129 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-14796462, IP 74.216.133.67, Port 22
2025-10-08 15:32:59,188 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:32:59,293 - DEBUG - VM ctest-TestBGPaasZone-61754549-14796462 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:33:04,294 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:33:04,294 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-14796462, IP 74.216.133.67, Port 22
2025-10-08 15:33:04,352 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-10-08 15:33:04,456 - DEBUG - VM ctest-TestBGPaasZone-61754549-14796462 is NOT ready for SSH connections, VM status: ACTIVE
2025-10-08 15:33:09,457 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:33:09,457 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-14796462, IP 74.216.133.67, Port 22
2025-10-08 15:33:09,630 - DEBUG - VM ctest-TestBGPaasZone-61754549-14796462 is ready for SSH connections
2025-10-08 15:33:09,630 - INFO - Waiting for VM ctest-TestBGPaasZone-61754549-68562946 to be up..
2025-10-08 15:33:09,729 - DEBUG - VM is in ACTIVE state now
2025-10-08 15:33:09,729 - INFO - VM name : ctest-TestBGPaasZone-61754549-68562946
2025-10-08 15:33:09,825 - DEBUG - VM ctest-TestBGPaasZone-61754549-68562946 ID is b953a51a-92a6-4551-bcda-9969db2118a6
2025-10-08 15:33:09,825 - DEBUG - VM ctest-TestBGPaasZone-61754549-68562946 launched on Node an-jenkins-deploy-platform-ansible-os-4236-2
2025-10-08 15:33:09,921 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/b953a51a-92a6-4551-bcda-9969db2118a6
2025-10-08 15:33:09,959 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/c2e05e18-4f84-4fc0-a50d-4c0316e289d9
2025-10-08 15:33:11,132 - 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=9.37 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.73 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.732/5.550/9.368/3.818 ms')
2025-10-08 15:33:11,132 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-TestBGPaasZone-61754549-68562946 passed
2025-10-08 15:33:11,201 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:33:11,202 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-68562946, IP 74.216.133.68, Port 22
2025-10-08 15:33:11,374 - DEBUG - VM ctest-TestBGPaasZone-61754549-68562946 is ready for SSH connections
2025-10-08 15:33:11,374 - INFO - Waiting for VM ctest-TestBGPaasZone-61754549-57365663 to be up..
2025-10-08 15:33:11,472 - DEBUG - VM is in ACTIVE state now
2025-10-08 15:33:11,473 - INFO - VM name : ctest-TestBGPaasZone-61754549-57365663
2025-10-08 15:33:11,566 - DEBUG - VM ctest-TestBGPaasZone-61754549-57365663 ID is 89c78baa-a077-4b8e-b02a-0320db0a6a98
2025-10-08 15:33:11,566 - DEBUG - VM ctest-TestBGPaasZone-61754549-57365663 launched on Node an-jenkins-deploy-platform-ansible-os-4236-1
2025-10-08 15:33:11,666 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine/89c78baa-a077-4b8e-b02a-0320db0a6a98
2025-10-08 15:33:11,677 - DEBUG - Requesting: http://10.0.0.242:8082/virtual-machine-interface/56d814c0-cc86-4f33-926f-720ca4bae904
2025-10-08 15:33:12,849 - 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=1.09 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.090/2.522/3.955/1.432 ms')
2025-10-08 15:33:12,849 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-61754549-57365663 passed
2025-10-08 15:33:12,930 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:33:12,930 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61754549-57365663, IP 74.216.133.69, Port 22
2025-10-08 15:33:13,096 - DEBUG - VM ctest-TestBGPaasZone-61754549-57365663 is ready for SSH connections
2025-10-08 15:33:13,244 - INFO - Created control node zone ctest-test-zone-41006099(1b0cefab-f70d-4362-976f-68776c305354)
2025-10-08 15:33:13,628 - INFO - Created control node zone ctest-test-zone-35227080(1ca0b48b-22a0-4c5c-920f-cfed16143a9e)
2025-10-08 15:33:14,004 - INFO - Created control node zone ctest-test-zone-04526041(138c840d-f721-40c7-a64f-074a42aef3c8)
2025-10-08 15:33:14,308 - INFO - Creating BGPaaS ctest-TestBGPaasZone-61754549-23081849
2025-10-08 15:33:14,392 - INFO - BGPaaS: ctest-TestBGPaasZone-61754549-23081849(6c9ecb19-d585-43f0-8ad2-f88010cc08c9)
2025-10-08 15:33:14,392 - DEBUG - Requesting: http://10.0.0.133:8082/bgp-as-a-service/6c9ecb19-d585-43f0-8ad2-f88010cc08c9
2025-10-08 15:33:14,405 - INFO - verify_in_api_server passed for BGPaaS obj 6c9ecb19-d585-43f0-8ad2-f88010cc08c9
2025-10-08 15:33:14,405 - INFO - BGPaaS(6c9ecb19-d585-43f0-8ad2-f88010cc08c9): verify_on_setup passed
2025-10-08 15:33:14,405 - INFO - We will configure BGP on the VM
2025-10-08 15:33:14,836 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:33:14,836 - INFO - Configuring BGP on ctest-TestBGPaasZone-61754549-14796462
2025-10-08 15:33:14,837 - 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 74.216.133.67;
protocol bgp bgp_1{
local as 65000;
neighbor 74.216.133.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 74.216.133.67;
}
protocol bfd {
neighbor 74.216.133.65 local 74.216.133.67 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-10-08 15:33:14,837 - 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 74.216.133.67;
protocol bgp bgp_1{
local as 65000;
neighbor 74.216.133.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 74.216.133.67;
}
protocol bfd {
neighbor 74.216.133.65 local 74.216.133.67 multihop on;
}
EOS
2025-10-08 15:33:27,582 - DEBUG - None
2025-10-08 15:33:27,583 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 74.216.133.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 74.216.133.67;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-10-08 15:33:27,583 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 74.216.133.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 74.216.133.67;
}
EOS
2025-10-08 15:33:28,577 - DEBUG - None
2025-10-08 15:33:28,577 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-10-08 15:33:28,577 - DEBUG - service bird restart
2025-10-08 15:33:30,814 - DEBUG - bird stop/waiting
bird start/running, process 1443
2025-10-08 15:33:30,815 - INFO - Attaching VMI f64531ce-ef16-4acb-9fa8-220f5570324c to BGPaaS 6c9ecb19-d585-43f0-8ad2-f88010cc08c9
2025-10-08 15:33:30,916 - INFO - Attaching the VMI 566207a0-f520-4352-b80c-dd02e1c23b02 to the BGPaaS 6c9ecb19-d585-43f0-8ad2-f88010cc08c9 object
2025-10-08 15:33:30,917 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 74.216.133.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-10-08 15:33:30,917 - DEBUG - sudo ip addr add 74.216.133.84 dev eth0
2025-10-08 15:33:31,536 - DEBUG - None
2025-10-08 15:33:31,600 - INFO - Creating BGPaaS ctest-TestBGPaasZone-61754549-87613174
2025-10-08 15:33:31,676 - INFO - BGPaaS: ctest-TestBGPaasZone-61754549-87613174(43bc023a-a9d8-4109-8a0d-6921657be6a7)
2025-10-08 15:33:31,676 - DEBUG - Requesting: http://10.0.0.133:8082/bgp-as-a-service/43bc023a-a9d8-4109-8a0d-6921657be6a7
2025-10-08 15:33:31,689 - INFO - verify_in_api_server passed for BGPaaS obj 43bc023a-a9d8-4109-8a0d-6921657be6a7
2025-10-08 15:33:31,689 - INFO - BGPaaS(43bc023a-a9d8-4109-8a0d-6921657be6a7): verify_on_setup passed
2025-10-08 15:33:31,689 - INFO - We will configure BGP on the VM
2025-10-08 15:33:32,156 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:33:32,156 - INFO - Configuring BGP on ctest-TestBGPaasZone-61754549-68562946
2025-10-08 15:33:32,156 - 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 74.216.133.68;
protocol bgp bgp_1{
local as 65000;
neighbor 74.216.133.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 74.216.133.68;
}
protocol bfd {
neighbor 74.216.133.65 local 74.216.133.68 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:33:32,157 - 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 74.216.133.68;
protocol bgp bgp_1{
local as 65000;
neighbor 74.216.133.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 74.216.133.68;
}
protocol bfd {
neighbor 74.216.133.65 local 74.216.133.68 multihop on;
}
EOS
2025-10-08 15:33:38,661 - DEBUG - None
2025-10-08 15:33:38,661 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 74.216.133.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 74.216.133.68;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:33:38,661 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 74.216.133.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 74.216.133.68;
}
EOS
2025-10-08 15:33:39,174 - DEBUG - None
2025-10-08 15:33:39,174 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:33:39,174 - DEBUG - service bird restart
2025-10-08 15:33:41,096 - DEBUG - bird stop/waiting
bird start/running, process 1440
2025-10-08 15:33:41,096 - INFO - Attaching VMI c2e05e18-4f84-4fc0-a50d-4c0316e289d9 to BGPaaS 43bc023a-a9d8-4109-8a0d-6921657be6a7
2025-10-08 15:33:41,184 - INFO - Attaching the VMI b953a51a-92a6-4551-bcda-9969db2118a6 to the BGPaaS 43bc023a-a9d8-4109-8a0d-6921657be6a7 object
2025-10-08 15:33:41,184 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 74.216.133.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-10-08 15:33:41,184 - DEBUG - sudo ip addr add 74.216.133.84 dev eth0
2025-10-08 15:33:41,877 - DEBUG - None
2025-10-08 15:33:41,877 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:33:41,878 - DEBUG - Requesting: http://10.20.0.200:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-08 15:33:41,891 - INFO - BGPaaS session seen in control-node 10.20.0.200
2025-10-08 15:33:41,892 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:33:41,910 - INFO - BGPaaS session seen in control-node 10.20.0.252
2025-10-08 15:33:41,921 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:33:41,921 - DEBUG - Requesting: http://10.20.0.200:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-08 15:33:41,938 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.200']
2025-10-08 15:33:47,938 - DEBUG - Requesting: http://10.20.0.200:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-08 15:33:47,953 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.200']
2025-10-08 15:33:53,954 - DEBUG - Requesting: http://10.20.0.200:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-08 15:33:53,969 - INFO - BGPaaS session seen in control-node 10.20.0.200
2025-10-08 15:33:53,969 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:33:53,988 - INFO - BGPaaS session seen in control-node 10.20.0.252
2025-10-08 15:33:53,997 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 74.216.133.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-10-08 15:33:53,997 - DEBUG - ping -s 56 -c 3 -W 1 74.216.133.84
2025-10-08 15:34:00,060 - DEBUG - PING 74.216.133.84 (74.216.133.84) 56(84) bytes of data.
64 bytes from 74.216.133.84: icmp_seq=1 ttl=64 time=8.23 ms
64 bytes from 74.216.133.84: icmp_seq=2 ttl=64 time=1.52 ms
64 bytes from 74.216.133.84: icmp_seq=3 ttl=64 time=1.21 ms
--- 74.216.133.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.218/3.658/8.230/3.235 ms
2025-10-08 15:34:00,060 - INFO - Ping to IP 74.216.133.84 from VM ctest-TestBGPaasZone-61754549-57365663 passed
2025-10-08 15:34:00,061 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:34:00,061 - DEBUG - Requesting: http://10.20.0.200:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-08 15:34:00,076 - INFO - BGPaaS session seen in control-node 10.20.0.200
2025-10-08 15:34:00,077 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:34:00,096 - INFO - BGPaaS session seen in control-node 10.20.0.252
2025-10-08 15:34:00,104 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:34:00,104 - DEBUG - Requesting: http://10.20.0.200:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-08 15:34:00,121 - INFO - BGPaaS session seen in control-node 10.20.0.200
2025-10-08 15:34:00,122 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-08 15:34:00,142 - INFO - BGPaaS session seen in control-node 10.20.0.252
2025-10-08 15:34:00,149 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 74.216.133.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-10-08 15:34:00,150 - DEBUG - ping -s 56 -c 3 -W 1 74.216.133.84
2025-10-08 15:34:02,631 - DEBUG - PING 74.216.133.84 (74.216.133.84) 56(84) bytes of data.
64 bytes from 74.216.133.84: icmp_seq=1 ttl=64 time=4.65 ms
64 bytes from 74.216.133.84: icmp_seq=2 ttl=64 time=0.783 ms
64 bytes from 74.216.133.84: icmp_seq=3 ttl=64 time=0.933 ms
--- 74.216.133.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 0.783/2.123/4.655/1.791 ms
2025-10-08 15:34:02,631 - INFO - Ping to IP 74.216.133.84 from VM ctest-TestBGPaasZone-61754549-57365663 passed
2025-10-08 15:34:02,631 - INFO - Detaching VMI c2e05e18-4f84-4fc0-a50d-4c0316e289d9 from BGPaaS 43bc023a-a9d8-4109-8a0d-6921657be6a7
2025-10-08 15:34:02,716 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-61754549-87613174(43bc023a-a9d8-4109-8a0d-6921657be6a7)
2025-10-08 15:34:02,795 - DEBUG - Requesting: http://10.0.0.133:8082/bgp-as-a-service/43bc023a-a9d8-4109-8a0d-6921657be6a7
2025-10-08 15:34:02,806 - DEBUG - Response Code: 404
2025-10-08 15:34:02,807 - DEBUG - BGPaaS: 43bc023a-a9d8-4109-8a0d-6921657be6a7 deleted from api server
2025-10-08 15:34:02,807 - INFO - BGPaaS(43bc023a-a9d8-4109-8a0d-6921657be6a7): verify_on_cleanup passed
2025-10-08 15:34:02,807 - INFO - Detaching VMI f64531ce-ef16-4acb-9fa8-220f5570324c from BGPaaS 6c9ecb19-d585-43f0-8ad2-f88010cc08c9
2025-10-08 15:34:02,925 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-61754549-23081849(6c9ecb19-d585-43f0-8ad2-f88010cc08c9)
2025-10-08 15:34:02,997 - DEBUG - Requesting: http://10.0.0.133:8082/bgp-as-a-service/6c9ecb19-d585-43f0-8ad2-f88010cc08c9
2025-10-08 15:34:03,003 - DEBUG - Response Code: 404
2025-10-08 15:34:03,003 - DEBUG - BGPaaS: 6c9ecb19-d585-43f0-8ad2-f88010cc08c9 deleted from api server
2025-10-08 15:34:03,003 - INFO - BGPaaS(6c9ecb19-d585-43f0-8ad2-f88010cc08c9): verify_on_cleanup passed
2025-10-08 15:34:03,004 - INFO - Deleting Control node zone ctest-test-zone-04526041(138c840d-f721-40c7-a64f-074a42aef3c8)
2025-10-08 15:34:03,173 - INFO - Deleting Control node zone ctest-test-zone-35227080(1ca0b48b-22a0-4c5c-920f-cfed16143a9e)
2025-10-08 15:34:03,336 - INFO - Deleting Control node zone ctest-test-zone-41006099(1b0cefab-f70d-4362-976f-68776c305354)
2025-10-08 15:34:03,499 - INFO - Deleting VM ctest-TestBGPaasZone-61754549-57365663
2025-10-08 15:34:03,617 - INFO - Deleting VM ctest-TestBGPaasZone-61754549-68562946
2025-10-08 15:34:03,701 - INFO - Deleting VM ctest-TestBGPaasZone-61754549-14796462
2025-10-08 15:34:03,787 - INFO - Deleting VN ctest-cnz_vn-59748032
2025-10-08 15:34:03,846 - DEBUG - VN 4553a24e-d62a-444c-8265-c41dd2040701 still in use: Unable to complete operation on network 4553a24e-d62a-444c-8265-c41dd2040701. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-466a8f64-21d7-4ea4-9205-4929e00ede4b']
2025-10-08 15:34:03,847 - WARNING - Deleting VN ctest-cnz_vn-59748032 failed..Will retry
2025-10-08 15:34:06,163 - DEBUG - Response for deleting network ()
2025-10-08 15:34:07,428 - DEBUG - Skipping xmpp flap check
2025-10-08 15:34:07,428 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:03]
2025-10-08 15:34:07,428 - INFO - --------------------------------------------------------------------------------
2025-10-08 15:34:08,377 - INFO - Deleted project: ctest-TestBGPaasZone-61754549, ID : 78c3211e-c302-4a01-831f-7df65b79cef8