is in ACTIVE state now
2026-04-27 04:38:34,037 - INFO - VM name : ctest-TestBGPaasZone-80214598-69963636
2026-04-27 04:38:34,149 - DEBUG - VM ctest-TestBGPaasZone-80214598-69963636 ID is d5c2f7a1-d11e-4e84-b1af-e0a549777bf4
2026-04-27 04:38:34,149 - DEBUG - VM ctest-TestBGPaasZone-80214598-69963636 launched on Node an-jenkins-deploy-platform-ansible-os-5690-2
2026-04-27 04:38:34,267 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine/d5c2f7a1-d11e-4e84-b1af-e0a549777bf4
2026-04-27 04:38:34,280 - DEBUG - Requesting: http://10.0.0.23:8082/virtual-machine-interface/e58ffac1-b4b6-44d6-9f13-7db646ab99be
2026-04-27 04:38:35,490 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n64 bytes from 169.254.0.6: icmp_seq=1 ttl=63 time=4.03 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=1.09 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 1.085/2.559/4.033/1.474 ms')
2026-04-27 04:38:35,490 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-TestBGPaasZone-80214598-69963636 passed
2026-04-27 04:38:35,572 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 04:38:35,572 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-80214598-69963636, IP 177.203.53.133, Port 22
2026-04-27 04:38:35,639 - DEBUG - Error on ssh to ubuntu@169.254.0.6:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.6/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.6/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.6/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-04-27 04:38:35,760 - DEBUG - VM ctest-TestBGPaasZone-80214598-69963636 is NOT ready for SSH connections, VM status: ACTIVE
2026-04-27 04:38:40,761 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 04:38:40,761 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-80214598-69963636, IP 177.203.53.133, Port 22
2026-04-27 04:38:40,830 - DEBUG - Error on ssh to ubuntu@169.254.0.6:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.6/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.6/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.6/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-04-27 04:38:40,941 - DEBUG - VM ctest-TestBGPaasZone-80214598-69963636 is NOT ready for SSH connections, VM status: ACTIVE
2026-04-27 04:38:45,942 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 04:38:45,942 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-80214598-69963636, IP 177.203.53.133, Port 22
2026-04-27 04:38:46,118 - DEBUG - VM ctest-TestBGPaasZone-80214598-69963636 is ready for SSH connections
2026-04-27 04:38:46,275 - INFO - Created control node zone ctest-test-zone-79676767(99e70d93-41c3-47bb-a76b-82896d2d7f55)
2026-04-27 04:38:46,695 - INFO - Created control node zone ctest-test-zone-12847992(54d211bf-7acb-4be6-adea-25023c4d243e)
2026-04-27 04:38:47,119 - INFO - Created control node zone ctest-test-zone-55161015(d9047401-a12e-4359-a279-c0506bde021e)
2026-04-27 04:38:47,427 - INFO - Creating BGPaaS ctest-TestBGPaasZone-80214598-16557046
2026-04-27 04:38:47,519 - INFO - BGPaaS: ctest-TestBGPaasZone-80214598-16557046(fa874dd5-e142-4569-b4f6-cf0e93038509)
2026-04-27 04:38:47,519 - DEBUG - Requesting: http://10.0.0.41:8082/bgp-as-a-service/fa874dd5-e142-4569-b4f6-cf0e93038509
2026-04-27 04:38:47,537 - INFO - verify_in_api_server passed for BGPaaS obj fa874dd5-e142-4569-b4f6-cf0e93038509
2026-04-27 04:38:47,537 - INFO - BGPaaS(fa874dd5-e142-4569-b4f6-cf0e93038509): verify_on_setup passed
2026-04-27 04:38:47,537 - INFO - We will configure BGP on the VM
2026-04-27 04:38:48,039 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 04:38:48,039 - INFO - Configuring BGP on ctest-TestBGPaasZone-80214598-25996166
2026-04-27 04:38:48,039 - 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 177.203.53.131;
protocol bgp bgp_1{
local as 65000;
neighbor 177.203.53.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 177.203.53.131;
}
protocol bfd {
neighbor 177.203.53.129 local 177.203.53.131 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2026-04-27 04:38:48,039 - 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 177.203.53.131;
protocol bgp bgp_1{
local as 65000;
neighbor 177.203.53.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 177.203.53.131;
}
protocol bfd {
neighbor 177.203.53.129 local 177.203.53.131 multihop on;
}
EOS
2026-04-27 04:39:05,724 - DEBUG - None
2026-04-27 04:39:05,724 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 177.203.53.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 177.203.53.131;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2026-04-27 04:39:05,724 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 177.203.53.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 177.203.53.131;
}
EOS
2026-04-27 04:39:06,395 - DEBUG - None
2026-04-27 04:39:06,396 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2026-04-27 04:39:06,396 - DEBUG - service bird restart
2026-04-27 04:39:08,754 - DEBUG - bird stop/waiting
bird start/running, process 1437
2026-04-27 04:39:08,754 - INFO - Attaching VMI 0c2d0469-3819-4589-95fe-68efa6f8b23a to BGPaaS fa874dd5-e142-4569-b4f6-cf0e93038509
2026-04-27 04:39:08,851 - INFO - Attaching the VMI 5c1bc9f4-803a-4692-a29e-391297b14b67 to the BGPaaS fa874dd5-e142-4569-b4f6-cf0e93038509 object
2026-04-27 04:39:08,851 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 177.203.53.148 dev eth0, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2026-04-27 04:39:08,851 - DEBUG - sudo ip addr add 177.203.53.148 dev eth0
2026-04-27 04:39:09,799 - DEBUG - None
2026-04-27 04:39:09,875 - INFO - Creating BGPaaS ctest-TestBGPaasZone-80214598-63659796
2026-04-27 04:39:09,967 - INFO - BGPaaS: ctest-TestBGPaasZone-80214598-63659796(4b22e73c-797e-43b7-9dc0-281fd1a8226b)
2026-04-27 04:39:09,967 - DEBUG - Requesting: http://10.0.0.41:8082/bgp-as-a-service/4b22e73c-797e-43b7-9dc0-281fd1a8226b
2026-04-27 04:39:09,981 - INFO - verify_in_api_server passed for BGPaaS obj 4b22e73c-797e-43b7-9dc0-281fd1a8226b
2026-04-27 04:39:09,982 - INFO - BGPaaS(4b22e73c-797e-43b7-9dc0-281fd1a8226b): verify_on_setup passed
2026-04-27 04:39:09,982 - INFO - We will configure BGP on the VM
2026-04-27 04:39:10,483 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 04:39:10,483 - INFO - Configuring BGP on ctest-TestBGPaasZone-80214598-14529050
2026-04-27 04:39:10,484 - 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 177.203.53.132;
protocol bgp bgp_1{
local as 65000;
neighbor 177.203.53.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 177.203.53.132;
}
protocol bfd {
neighbor 177.203.53.129 local 177.203.53.132 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-04-27 04:39:10,484 - 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 177.203.53.132;
protocol bgp bgp_1{
local as 65000;
neighbor 177.203.53.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 177.203.53.132;
}
protocol bfd {
neighbor 177.203.53.129 local 177.203.53.132 multihop on;
}
EOS
2026-04-27 04:39:17,511 - DEBUG - None
2026-04-27 04:39:17,511 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 177.203.53.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 177.203.53.132;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-04-27 04:39:17,511 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 177.203.53.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 177.203.53.132;
}
EOS
2026-04-27 04:39:18,037 - DEBUG - None
2026-04-27 04:39:18,038 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-04-27 04:39:18,038 - DEBUG - service bird restart
2026-04-27 04:39:19,887 - DEBUG - bird stop/waiting
bird start/running, process 1432
2026-04-27 04:39:19,887 - INFO - Attaching VMI 2877a3ef-2e4d-4b84-a20e-273576b7fdab to BGPaaS 4b22e73c-797e-43b7-9dc0-281fd1a8226b
2026-04-27 04:39:19,987 - INFO - Attaching the VMI e2922429-df98-457c-b649-ba15b2e86af7 to the BGPaaS 4b22e73c-797e-43b7-9dc0-281fd1a8226b object
2026-04-27 04:39:19,987 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 177.203.53.148 dev eth0, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2026-04-27 04:39:19,987 - DEBUG - sudo ip addr add 177.203.53.148 dev eth0
2026-04-27 04:39:20,725 - DEBUG - None
2026-04-27 04:39:20,725 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 04:39:20,746 - INFO - BGPaaS session seen in control-node 10.20.0.25
2026-04-27 04:39:20,746 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 04:39:20,746 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-04-27 04:39:20,776 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-04-27 04:39:20,785 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 04:39:20,798 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25']
2026-04-27 04:39:26,815 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25']
2026-04-27 04:39:32,830 - INFO - BGPaaS session seen in control-node 10.20.0.25
2026-04-27 04:39:32,830 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 04:39:32,830 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-04-27 04:39:32,850 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-04-27 04:39:32,860 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 177.203.53.148, host_string: ubuntu@169.254.0.6, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2026-04-27 04:39:32,860 - DEBUG - ping -s 56 -c 3 -W 1 177.203.53.148
2026-04-27 04:39:40,623 - DEBUG - PING 177.203.53.148 (177.203.53.148) 56(84) bytes of data.
64 bytes from 177.203.53.148: icmp_seq=1 ttl=64 time=8.00 ms
64 bytes from 177.203.53.148: icmp_seq=2 ttl=64 time=1.27 ms
64 bytes from 177.203.53.148: icmp_seq=3 ttl=64 time=1.23 ms
--- 177.203.53.148 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2006ms
rtt min/avg/max/mdev = 1.236/3.506/8.007/3.182 ms
2026-04-27 04:39:40,623 - INFO - Ping to IP 177.203.53.148 from VM ctest-TestBGPaasZone-80214598-69963636 passed
2026-04-27 04:39:40,623 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 04:39:40,638 - INFO - BGPaaS session seen in control-node 10.20.0.25
2026-04-27 04:39:40,638 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 04:39:40,638 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-04-27 04:39:40,659 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-04-27 04:39:40,665 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 04:39:40,678 - INFO - BGPaaS session seen in control-node 10.20.0.25
2026-04-27 04:39:40,678 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-27 04:39:40,679 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-04-27 04:39:40,699 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-04-27 04:39:40,706 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 177.203.53.148, host_string: ubuntu@169.254.0.6, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2026-04-27 04:39:40,706 - DEBUG - ping -s 56 -c 3 -W 1 177.203.53.148
2026-04-27 04:39:43,220 - DEBUG - PING 177.203.53.148 (177.203.53.148) 56(84) bytes of data.
64 bytes from 177.203.53.148: icmp_seq=1 ttl=64 time=1.67 ms
64 bytes from 177.203.53.148: icmp_seq=2 ttl=64 time=1.19 ms
64 bytes from 177.203.53.148: icmp_seq=3 ttl=64 time=0.765 ms
--- 177.203.53.148 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 0.765/1.213/1.679/0.374 ms
2026-04-27 04:39:43,221 - INFO - Ping to IP 177.203.53.148 from VM ctest-TestBGPaasZone-80214598-69963636 passed
2026-04-27 04:39:43,221 - INFO - Detaching VMI 2877a3ef-2e4d-4b84-a20e-273576b7fdab from BGPaaS 4b22e73c-797e-43b7-9dc0-281fd1a8226b
2026-04-27 04:39:43,347 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-80214598-63659796(4b22e73c-797e-43b7-9dc0-281fd1a8226b)
2026-04-27 04:39:43,437 - DEBUG - Requesting: http://10.0.0.41:8082/bgp-as-a-service/4b22e73c-797e-43b7-9dc0-281fd1a8226b
2026-04-27 04:39:43,512 - DEBUG - Response Code: 404
2026-04-27 04:39:43,512 - DEBUG - BGPaaS: 4b22e73c-797e-43b7-9dc0-281fd1a8226b deleted from api server
2026-04-27 04:39:43,512 - INFO - BGPaaS(4b22e73c-797e-43b7-9dc0-281fd1a8226b): verify_on_cleanup passed
2026-04-27 04:39:43,512 - INFO - Detaching VMI 0c2d0469-3819-4589-95fe-68efa6f8b23a from BGPaaS fa874dd5-e142-4569-b4f6-cf0e93038509
2026-04-27 04:39:43,619 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-80214598-16557046(fa874dd5-e142-4569-b4f6-cf0e93038509)
2026-04-27 04:39:43,747 - DEBUG - Requesting: http://10.0.0.41:8082/bgp-as-a-service/fa874dd5-e142-4569-b4f6-cf0e93038509
2026-04-27 04:39:43,755 - DEBUG - Response Code: 404
2026-04-27 04:39:43,755 - DEBUG - BGPaaS: fa874dd5-e142-4569-b4f6-cf0e93038509 deleted from api server
2026-04-27 04:39:43,755 - INFO - BGPaaS(fa874dd5-e142-4569-b4f6-cf0e93038509): verify_on_cleanup passed
2026-04-27 04:39:43,755 - INFO - Deleting Control node zone ctest-test-zone-55161015(d9047401-a12e-4359-a279-c0506bde021e)
2026-04-27 04:39:43,976 - INFO - Deleting Control node zone ctest-test-zone-12847992(54d211bf-7acb-4be6-adea-25023c4d243e)
2026-04-27 04:39:44,163 - INFO - Deleting Control node zone ctest-test-zone-79676767(99e70d93-41c3-47bb-a76b-82896d2d7f55)
2026-04-27 04:39:44,334 - INFO - Deleting VM ctest-TestBGPaasZone-80214598-69963636
2026-04-27 04:39:44,437 - INFO - Deleting VM ctest-TestBGPaasZone-80214598-14529050
2026-04-27 04:39:44,544 - INFO - Deleting VM ctest-TestBGPaasZone-80214598-25996166
2026-04-27 04:39:44,651 - INFO - Deleting VN ctest-cnz_vn-41515428
2026-04-27 04:39:44,711 - DEBUG - VN ccd709be-2ced-477b-9af8-83619891a243 still in use: Unable to complete operation on network ccd709be-2ced-477b-9af8-83619891a243. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-82f861a9-da9f-4d2c-b01b-4921654b1a55']
2026-04-27 04:39:44,711 - WARNING - Deleting VN ctest-cnz_vn-41515428 failed..Will retry
2026-04-27 04:39:46,988 - DEBUG - Response for deleting network ()
2026-04-27 04:39:48,319 - DEBUG - Skipping xmpp flap check
2026-04-27 04:39:48,319 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:59]
2026-04-27 04:39:48,319 - INFO - --------------------------------------------------------------------------------
2026-04-27 04:39:49,219 - INFO - Deleted project: ctest-TestBGPaasZone-80214598, ID : 2becf400-e9ab-4527-9838-856109c07b91