is in ACTIVE state now
2026-04-30 04:33:53,735 - INFO - VM name : ctest-TestBGPaasZone-07190232-22298788
2026-04-30 04:33:53,846 - DEBUG - VM ctest-TestBGPaasZone-07190232-22298788 ID is 250cbe23-43d1-46d7-95fc-5a7d0dba76c2
2026-04-30 04:33:53,846 - DEBUG - VM ctest-TestBGPaasZone-07190232-22298788 launched on Node an-jenkins-deploy-platform-ansible-os-5725-1
2026-04-30 04:33:53,945 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/250cbe23-43d1-46d7-95fc-5a7d0dba76c2
2026-04-30 04:33:53,958 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/314d17c0-b96a-4c01-ba50-609a1196c543
2026-04-30 04:33:55,156 - 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=2.51 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=1.36 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 1.360/1.936/2.512/0.576 ms')
2026-04-30 04:33:55,156 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-TestBGPaasZone-07190232-22298788 passed
2026-04-30 04:33:55,229 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 04:33:55,229 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-07190232-22298788, IP 132.5.172.5, Port 22
2026-04-30 04:33:55,298 - 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-30 04:33:55,405 - DEBUG - VM ctest-TestBGPaasZone-07190232-22298788 is NOT ready for SSH connections, VM status: ACTIVE
2026-04-30 04:34:00,406 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 04:34:00,406 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-07190232-22298788, IP 132.5.172.5, Port 22
2026-04-30 04:34:00,584 - DEBUG - VM ctest-TestBGPaasZone-07190232-22298788 is ready for SSH connections
2026-04-30 04:34:00,767 - INFO - Created control node zone ctest-test-zone-75588663(b0e7c6b3-0e70-4618-99b3-3f41381ba0ee)
2026-04-30 04:34:01,170 - INFO - Created control node zone ctest-test-zone-42698449(dd944180-13e2-4678-a9a2-e6a171cad728)
2026-04-30 04:34:01,570 - INFO - Created control node zone ctest-test-zone-68374515(02306a2f-7276-4632-a71f-dd5093812fd0)
2026-04-30 04:34:01,890 - INFO - Creating BGPaaS ctest-TestBGPaasZone-07190232-58858449
2026-04-30 04:34:01,986 - INFO - BGPaaS: ctest-TestBGPaasZone-07190232-58858449(1497b37e-1084-498b-96df-42b808f98f5e)
2026-04-30 04:34:01,986 - DEBUG - Requesting: http://10.0.0.73:8082/bgp-as-a-service/1497b37e-1084-498b-96df-42b808f98f5e
2026-04-30 04:34:01,998 - INFO - verify_in_api_server passed for BGPaaS obj 1497b37e-1084-498b-96df-42b808f98f5e
2026-04-30 04:34:01,998 - INFO - BGPaaS(1497b37e-1084-498b-96df-42b808f98f5e): verify_on_setup passed
2026-04-30 04:34:01,998 - INFO - We will configure BGP on the VM
2026-04-30 04:34:02,450 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 04:34:02,450 - INFO - Configuring BGP on ctest-TestBGPaasZone-07190232-91920340
2026-04-30 04:34:02,450 - 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 132.5.172.3;
protocol bgp bgp_1{
local as 65000;
neighbor 132.5.172.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 132.5.172.3;
}
protocol bfd {
neighbor 132.5.172.1 local 132.5.172.3 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-30 04:34:02,451 - 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 132.5.172.3;
protocol bgp bgp_1{
local as 65000;
neighbor 132.5.172.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 132.5.172.3;
}
protocol bfd {
neighbor 132.5.172.1 local 132.5.172.3 multihop on;
}
EOS
2026-04-30 04:34:20,922 - DEBUG - None
2026-04-30 04:34:20,922 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 132.5.172.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 132.5.172.3;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-30 04:34:20,922 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 132.5.172.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 132.5.172.3;
}
EOS
2026-04-30 04:34:22,318 - DEBUG - None
2026-04-30 04:34:22,318 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-30 04:34:22,318 - DEBUG - service bird restart
2026-04-30 04:34:26,277 - DEBUG - bird stop/waiting
bird start/running, process 1436
2026-04-30 04:34:26,277 - INFO - Attaching VMI 695a3e00-2b31-4d04-97e6-c0b52bdec17a to BGPaaS 1497b37e-1084-498b-96df-42b808f98f5e
2026-04-30 04:34:26,366 - INFO - Attaching the VMI 60f937a5-2a60-405c-9df8-83ae993bd6e2 to the BGPaaS 1497b37e-1084-498b-96df-42b808f98f5e object
2026-04-30 04:34:26,366 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 132.5.172.20 dev eth0, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-30 04:34:26,366 - DEBUG - sudo ip addr add 132.5.172.20 dev eth0
2026-04-30 04:34:27,573 - DEBUG - None
2026-04-30 04:34:27,642 - INFO - Creating BGPaaS ctest-TestBGPaasZone-07190232-69454824
2026-04-30 04:34:27,726 - INFO - BGPaaS: ctest-TestBGPaasZone-07190232-69454824(8b5fc706-8a4f-43e8-9c90-e43a9d79d2e1)
2026-04-30 04:34:27,726 - DEBUG - Requesting: http://10.0.0.73:8082/bgp-as-a-service/8b5fc706-8a4f-43e8-9c90-e43a9d79d2e1
2026-04-30 04:34:27,736 - INFO - verify_in_api_server passed for BGPaaS obj 8b5fc706-8a4f-43e8-9c90-e43a9d79d2e1
2026-04-30 04:34:27,736 - INFO - BGPaaS(8b5fc706-8a4f-43e8-9c90-e43a9d79d2e1): verify_on_setup passed
2026-04-30 04:34:27,737 - INFO - We will configure BGP on the VM
2026-04-30 04:34:28,190 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 04:34:28,190 - INFO - Configuring BGP on ctest-TestBGPaasZone-07190232-84849098
2026-04-30 04:34:28,190 - 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 132.5.172.4;
protocol bgp bgp_1{
local as 65000;
neighbor 132.5.172.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 132.5.172.4;
}
protocol bfd {
neighbor 132.5.172.1 local 132.5.172.4 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2026-04-30 04:34:28,190 - 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 132.5.172.4;
protocol bgp bgp_1{
local as 65000;
neighbor 132.5.172.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 132.5.172.4;
}
protocol bfd {
neighbor 132.5.172.1 local 132.5.172.4 multihop on;
}
EOS
2026-04-30 04:34:34,738 - DEBUG - None
2026-04-30 04:34:34,739 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 132.5.172.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 132.5.172.4;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2026-04-30 04:34:34,739 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 132.5.172.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 132.5.172.4;
}
EOS
2026-04-30 04:34:35,293 - DEBUG - None
2026-04-30 04:34:35,293 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2026-04-30 04:34:35,293 - DEBUG - service bird restart
2026-04-30 04:34:37,164 - DEBUG - bird stop/waiting
bird start/running, process 1439
2026-04-30 04:34:37,164 - INFO - Attaching VMI 836cbaf8-1704-4912-903f-f8bbb43ebf37 to BGPaaS 8b5fc706-8a4f-43e8-9c90-e43a9d79d2e1
2026-04-30 04:34:37,258 - INFO - Attaching the VMI 2ffd6f32-5bac-46af-9016-f8cea7ef5500 to the BGPaaS 8b5fc706-8a4f-43e8-9c90-e43a9d79d2e1 object
2026-04-30 04:34:37,258 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 132.5.172.20 dev eth0, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2026-04-30 04:34:37,258 - DEBUG - sudo ip addr add 132.5.172.20 dev eth0
2026-04-30 04:34:38,032 - DEBUG - None
2026-04-30 04:34:38,033 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 04:34:38,050 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-04-30 04:34:38,051 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 04:34:38,051 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-04-30 04:34:38,065 - INFO - BGPaaS session seen in control-node 10.20.0.129
2026-04-30 04:34:38,074 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 04:34:38,092 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.193']
2026-04-30 04:34:44,106 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.193']
2026-04-30 04:34:50,136 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-04-30 04:34:50,138 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 04:34:50,138 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-04-30 04:34:50,155 - INFO - BGPaaS session seen in control-node 10.20.0.129
2026-04-30 04:34:50,162 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 132.5.172.20, host_string: ubuntu@169.254.0.6, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-30 04:34:50,162 - DEBUG - ping -s 56 -c 3 -W 1 132.5.172.20
2026-04-30 04:35:01,909 - DEBUG - PING 132.5.172.20 (132.5.172.20) 56(84) bytes of data.
64 bytes from 132.5.172.20: icmp_seq=1 ttl=64 time=9.47 ms
64 bytes from 132.5.172.20: icmp_seq=2 ttl=64 time=2.15 ms
64 bytes from 132.5.172.20: icmp_seq=3 ttl=64 time=1.94 ms
--- 132.5.172.20 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.942/4.524/9.476/3.502 ms
2026-04-30 04:35:01,909 - INFO - Ping to IP 132.5.172.20 from VM ctest-TestBGPaasZone-07190232-22298788 passed
2026-04-30 04:35:01,909 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 04:35:01,924 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-04-30 04:35:01,924 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 04:35:01,924 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-04-30 04:35:01,941 - INFO - BGPaaS session seen in control-node 10.20.0.129
2026-04-30 04:35:01,948 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 04:35:01,962 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-04-30 04:35:01,962 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-30 04:35:01,962 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-04-30 04:35:01,979 - INFO - BGPaaS session seen in control-node 10.20.0.129
2026-04-30 04:35:01,987 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 132.5.172.20, host_string: ubuntu@169.254.0.6, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-30 04:35:01,987 - DEBUG - ping -s 56 -c 3 -W 1 132.5.172.20
2026-04-30 04:35:04,660 - DEBUG - PING 132.5.172.20 (132.5.172.20) 56(84) bytes of data.
64 bytes from 132.5.172.20: icmp_seq=1 ttl=64 time=4.82 ms
64 bytes from 132.5.172.20: icmp_seq=2 ttl=64 time=1.96 ms
64 bytes from 132.5.172.20: icmp_seq=3 ttl=64 time=1.76 ms
--- 132.5.172.20 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.767/2.850/4.822/1.397 ms
2026-04-30 04:35:04,660 - INFO - Ping to IP 132.5.172.20 from VM ctest-TestBGPaasZone-07190232-22298788 passed
2026-04-30 04:35:04,660 - INFO - Detaching VMI 836cbaf8-1704-4912-903f-f8bbb43ebf37 from BGPaaS 8b5fc706-8a4f-43e8-9c90-e43a9d79d2e1
2026-04-30 04:35:04,754 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-07190232-69454824(8b5fc706-8a4f-43e8-9c90-e43a9d79d2e1)
2026-04-30 04:35:04,820 - DEBUG - Requesting: http://10.0.0.73:8082/bgp-as-a-service/8b5fc706-8a4f-43e8-9c90-e43a9d79d2e1
2026-04-30 04:35:04,855 - DEBUG - Response Code: 404
2026-04-30 04:35:04,855 - DEBUG - BGPaaS: 8b5fc706-8a4f-43e8-9c90-e43a9d79d2e1 deleted from api server
2026-04-30 04:35:04,855 - INFO - BGPaaS(8b5fc706-8a4f-43e8-9c90-e43a9d79d2e1): verify_on_cleanup passed
2026-04-30 04:35:04,855 - INFO - Detaching VMI 695a3e00-2b31-4d04-97e6-c0b52bdec17a from BGPaaS 1497b37e-1084-498b-96df-42b808f98f5e
2026-04-30 04:35:04,950 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-07190232-58858449(1497b37e-1084-498b-96df-42b808f98f5e)
2026-04-30 04:35:05,049 - DEBUG - Requesting: http://10.0.0.73:8082/bgp-as-a-service/1497b37e-1084-498b-96df-42b808f98f5e
2026-04-30 04:35:05,058 - DEBUG - Response Code: 404
2026-04-30 04:35:05,058 - DEBUG - BGPaaS: 1497b37e-1084-498b-96df-42b808f98f5e deleted from api server
2026-04-30 04:35:05,058 - INFO - BGPaaS(1497b37e-1084-498b-96df-42b808f98f5e): verify_on_cleanup passed
2026-04-30 04:35:05,058 - INFO - Deleting Control node zone ctest-test-zone-68374515(02306a2f-7276-4632-a71f-dd5093812fd0)
2026-04-30 04:35:05,257 - INFO - Deleting Control node zone ctest-test-zone-42698449(dd944180-13e2-4678-a9a2-e6a171cad728)
2026-04-30 04:35:05,482 - INFO - Deleting Control node zone ctest-test-zone-75588663(b0e7c6b3-0e70-4618-99b3-3f41381ba0ee)
2026-04-30 04:35:05,752 - INFO - Deleting VM ctest-TestBGPaasZone-07190232-22298788
2026-04-30 04:35:05,887 - INFO - Deleting VM ctest-TestBGPaasZone-07190232-84849098
2026-04-30 04:35:05,986 - INFO - Deleting VM ctest-TestBGPaasZone-07190232-91920340
2026-04-30 04:35:06,093 - INFO - Deleting VN ctest-cnz_vn-95348366
2026-04-30 04:35:06,175 - DEBUG - VN 7148061d-6587-49ff-83ab-fd672f5603f2 still in use: Unable to complete operation on network 7148061d-6587-49ff-83ab-fd672f5603f2. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-25ab2d6c-8a09-41d3-81a1-b7a54fe43a97']
2026-04-30 04:35:06,176 - WARNING - Deleting VN ctest-cnz_vn-95348366 failed..Will retry
2026-04-30 04:35:08,409 - DEBUG - Response for deleting network ()
2026-04-30 04:35:09,734 - DEBUG - Skipping xmpp flap check
2026-04-30 04:35:09,734 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:04:07]
2026-04-30 04:35:09,734 - INFO - --------------------------------------------------------------------------------
2026-04-30 04:35:10,605 - INFO - Deleted project: ctest-TestBGPaasZone-07190232, ID : f9cafb29-ca79-426b-8ed6-2dcbc22c2476