is in ACTIVE state now
2026-06-08 04:23:52,600 - INFO - VM name : ctest-TestBGPaasZone-41459311-81877859
2026-06-08 04:23:52,691 - DEBUG - VM ctest-TestBGPaasZone-41459311-81877859 ID is ad863745-9fa7-46ea-a323-b9d32481ec83
2026-06-08 04:23:52,691 - DEBUG - VM ctest-TestBGPaasZone-41459311-81877859 launched on Node an-jenkins-deploy-platform-ansible-os-5901-1
2026-06-08 04:23:52,777 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/ad863745-9fa7-46ea-a323-b9d32481ec83
2026-06-08 04:23:52,792 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/f9f408fa-51fb-4484-b8f7-6ba9d1eea865
2026-06-08 04:23:53,977 - 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=3.40 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.623 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 = 0.623/2.013/3.404/1.390 ms')
2026-06-08 04:23:53,977 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-TestBGPaasZone-41459311-81877859 passed
2026-06-08 04:23:54,053 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 04:23:54,053 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-41459311-81877859, IP 215.121.196.197, Port 22
2026-06-08 04:23:54,221 - DEBUG - VM ctest-TestBGPaasZone-41459311-81877859 is ready for SSH connections
2026-06-08 04:23:54,367 - INFO - Created control node zone ctest-test-zone-40907615(90207b37-10a4-4480-9be8-ebb8a35828e6)
2026-06-08 04:23:54,731 - INFO - Created control node zone ctest-test-zone-23762351(3838675a-17d0-474f-9259-08062efcdeb6)
2026-06-08 04:23:55,115 - INFO - Created control node zone ctest-test-zone-78959605(d63c4fe1-0f49-40a1-8244-81de98e3865c)
2026-06-08 04:23:55,407 - INFO - Creating BGPaaS ctest-TestBGPaasZone-41459311-95887068
2026-06-08 04:23:55,495 - INFO - BGPaaS: ctest-TestBGPaasZone-41459311-95887068(aa8c32c6-1a8e-4e70-bf14-3819f06cab17)
2026-06-08 04:23:55,495 - DEBUG - Requesting: http://10.0.0.33:8082/bgp-as-a-service/aa8c32c6-1a8e-4e70-bf14-3819f06cab17
2026-06-08 04:23:55,505 - INFO - verify_in_api_server passed for BGPaaS obj aa8c32c6-1a8e-4e70-bf14-3819f06cab17
2026-06-08 04:23:55,505 - INFO - BGPaaS(aa8c32c6-1a8e-4e70-bf14-3819f06cab17): verify_on_setup passed
2026-06-08 04:23:55,505 - INFO - We will configure BGP on the VM
2026-06-08 04:23:55,931 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 04:23:55,931 - INFO - Configuring BGP on ctest-TestBGPaasZone-41459311-74069642
2026-06-08 04:23:55,931 - 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 215.121.196.195;
protocol bgp bgp_1{
local as 65000;
neighbor 215.121.196.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 215.121.196.195;
}
protocol bfd {
neighbor 215.121.196.193 local 215.121.196.195 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2026-06-08 04:23:55,931 - 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 215.121.196.195;
protocol bgp bgp_1{
local as 65000;
neighbor 215.121.196.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 215.121.196.195;
}
protocol bfd {
neighbor 215.121.196.193 local 215.121.196.195 multihop on;
}
EOS
2026-06-08 04:24:12,497 - DEBUG - None
2026-06-08 04:24:12,498 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 215.121.196.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 215.121.196.195;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2026-06-08 04:24:12,499 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 215.121.196.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 215.121.196.195;
}
EOS
2026-06-08 04:24:13,737 - DEBUG - None
2026-06-08 04:24:13,737 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2026-06-08 04:24:13,737 - DEBUG - service bird restart
2026-06-08 04:24:17,517 - DEBUG - bird stop/waiting
bird start/running, process 1434
2026-06-08 04:24:17,518 - INFO - Attaching VMI f09a5f05-9251-4622-b85e-2db178b76868 to BGPaaS aa8c32c6-1a8e-4e70-bf14-3819f06cab17
2026-06-08 04:24:17,615 - INFO - Attaching the VMI 8a088ceb-a489-4f3d-9f4b-8fa269511efe to the BGPaaS aa8c32c6-1a8e-4e70-bf14-3819f06cab17 object
2026-06-08 04:24:17,615 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 215.121.196.212 dev eth0, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2026-06-08 04:24:17,615 - DEBUG - sudo ip addr add 215.121.196.212 dev eth0
2026-06-08 04:24:19,315 - DEBUG - None
2026-06-08 04:24:19,379 - INFO - Creating BGPaaS ctest-TestBGPaasZone-41459311-09895878
2026-06-08 04:24:19,467 - INFO - BGPaaS: ctest-TestBGPaasZone-41459311-09895878(64402392-6464-47d6-9932-7621a41b5368)
2026-06-08 04:24:19,467 - DEBUG - Requesting: http://10.0.0.33:8082/bgp-as-a-service/64402392-6464-47d6-9932-7621a41b5368
2026-06-08 04:24:19,478 - INFO - verify_in_api_server passed for BGPaaS obj 64402392-6464-47d6-9932-7621a41b5368
2026-06-08 04:24:19,478 - INFO - BGPaaS(64402392-6464-47d6-9932-7621a41b5368): verify_on_setup passed
2026-06-08 04:24:19,478 - INFO - We will configure BGP on the VM
2026-06-08 04:24:19,927 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 04:24:19,927 - INFO - Configuring BGP on ctest-TestBGPaasZone-41459311-37065808
2026-06-08 04:24:19,927 - 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 215.121.196.196;
protocol bgp bgp_1{
local as 65000;
neighbor 215.121.196.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 215.121.196.196;
}
protocol bfd {
neighbor 215.121.196.193 local 215.121.196.196 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-06-08 04:24:19,927 - 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 215.121.196.196;
protocol bgp bgp_1{
local as 65000;
neighbor 215.121.196.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 215.121.196.196;
}
protocol bfd {
neighbor 215.121.196.193 local 215.121.196.196 multihop on;
}
EOS
2026-06-08 04:24:26,805 - DEBUG - None
2026-06-08 04:24:26,805 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 215.121.196.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 215.121.196.196;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-06-08 04:24:26,805 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 215.121.196.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 215.121.196.196;
}
EOS
2026-06-08 04:24:27,463 - DEBUG - None
2026-06-08 04:24:27,464 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-06-08 04:24:27,464 - DEBUG - service bird restart
2026-06-08 04:24:29,369 - DEBUG - bird stop/waiting
bird start/running, process 1440
2026-06-08 04:24:29,369 - INFO - Attaching VMI a5567a34-f8d0-4e06-8f0b-6c837a24964f to BGPaaS 64402392-6464-47d6-9932-7621a41b5368
2026-06-08 04:24:29,471 - INFO - Attaching the VMI c5dc11fa-930b-4f55-95a9-7949bdc2c260 to the BGPaaS 64402392-6464-47d6-9932-7621a41b5368 object
2026-06-08 04:24:29,471 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 215.121.196.212 dev eth0, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-06-08 04:24:29,471 - DEBUG - sudo ip addr add 215.121.196.212 dev eth0
2026-06-08 04:24:30,219 - DEBUG - None
2026-06-08 04:24:30,219 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 04:24:30,234 - INFO - BGPaaS session seen in control-node 10.20.0.25
2026-06-08 04:24:30,235 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 04:24:30,250 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-06-08 04:24:30,256 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 04:24:30,273 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25']
2026-06-08 04:24:36,292 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25']
2026-06-08 04:24:42,311 - INFO - BGPaaS session seen in control-node 10.20.0.25
2026-06-08 04:24:42,312 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 04:24:42,325 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-06-08 04:24:42,331 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 215.121.196.212, host_string: ubuntu@169.254.0.6, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2026-06-08 04:24:42,331 - DEBUG - ping -s 56 -c 3 -W 1 215.121.196.212
2026-06-08 04:24:50,540 - DEBUG - PING 215.121.196.212 (215.121.196.212) 56(84) bytes of data.
64 bytes from 215.121.196.212: icmp_seq=1 ttl=64 time=14.9 ms
64 bytes from 215.121.196.212: icmp_seq=2 ttl=64 time=1.66 ms
64 bytes from 215.121.196.212: icmp_seq=3 ttl=64 time=1.52 ms
--- 215.121.196.212 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.529/6.062/14.997/6.318 ms
2026-06-08 04:24:50,540 - INFO - Ping to IP 215.121.196.212 from VM ctest-TestBGPaasZone-41459311-81877859 passed
2026-06-08 04:24:50,540 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 04:24:50,554 - INFO - BGPaaS session seen in control-node 10.20.0.25
2026-06-08 04:24:50,554 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 04:24:50,569 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-06-08 04:24:50,578 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 04:24:50,591 - INFO - BGPaaS session seen in control-node 10.20.0.25
2026-06-08 04:24:50,591 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-08 04:24:50,606 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-06-08 04:24:50,613 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 215.121.196.212, host_string: ubuntu@169.254.0.6, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2026-06-08 04:24:50,613 - DEBUG - ping -s 56 -c 3 -W 1 215.121.196.212
2026-06-08 04:24:53,289 - DEBUG - PING 215.121.196.212 (215.121.196.212) 56(84) bytes of data.
64 bytes from 215.121.196.212: icmp_seq=1 ttl=64 time=4.40 ms
64 bytes from 215.121.196.212: icmp_seq=2 ttl=64 time=2.24 ms
64 bytes from 215.121.196.212: icmp_seq=3 ttl=64 time=1.77 ms
--- 215.121.196.212 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.775/2.808/4.407/1.147 ms
2026-06-08 04:24:53,289 - INFO - Ping to IP 215.121.196.212 from VM ctest-TestBGPaasZone-41459311-81877859 passed
2026-06-08 04:24:53,289 - INFO - Detaching VMI a5567a34-f8d0-4e06-8f0b-6c837a24964f from BGPaaS 64402392-6464-47d6-9932-7621a41b5368
2026-06-08 04:24:53,371 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-41459311-09895878(64402392-6464-47d6-9932-7621a41b5368)
2026-06-08 04:24:53,474 - DEBUG - Requesting: http://10.0.0.33:8082/bgp-as-a-service/64402392-6464-47d6-9932-7621a41b5368
2026-06-08 04:24:53,545 - DEBUG - Response Code: 404
2026-06-08 04:24:53,545 - DEBUG - BGPaaS: 64402392-6464-47d6-9932-7621a41b5368 deleted from api server
2026-06-08 04:24:53,545 - INFO - BGPaaS(64402392-6464-47d6-9932-7621a41b5368): verify_on_cleanup passed
2026-06-08 04:24:53,545 - INFO - Detaching VMI f09a5f05-9251-4622-b85e-2db178b76868 from BGPaaS aa8c32c6-1a8e-4e70-bf14-3819f06cab17
2026-06-08 04:24:53,639 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-41459311-95887068(aa8c32c6-1a8e-4e70-bf14-3819f06cab17)
2026-06-08 04:24:53,775 - DEBUG - Requesting: http://10.0.0.33:8082/bgp-as-a-service/aa8c32c6-1a8e-4e70-bf14-3819f06cab17
2026-06-08 04:24:53,782 - DEBUG - Response Code: 404
2026-06-08 04:24:53,782 - DEBUG - BGPaaS: aa8c32c6-1a8e-4e70-bf14-3819f06cab17 deleted from api server
2026-06-08 04:24:53,782 - INFO - BGPaaS(aa8c32c6-1a8e-4e70-bf14-3819f06cab17): verify_on_cleanup passed
2026-06-08 04:24:53,782 - INFO - Deleting Control node zone ctest-test-zone-78959605(d63c4fe1-0f49-40a1-8244-81de98e3865c)
2026-06-08 04:24:53,962 - INFO - Deleting Control node zone ctest-test-zone-23762351(3838675a-17d0-474f-9259-08062efcdeb6)
2026-06-08 04:24:54,119 - INFO - Deleting Control node zone ctest-test-zone-40907615(90207b37-10a4-4480-9be8-ebb8a35828e6)
2026-06-08 04:24:54,287 - INFO - Deleting VM ctest-TestBGPaasZone-41459311-81877859
2026-06-08 04:24:54,362 - INFO - Deleting VM ctest-TestBGPaasZone-41459311-37065808
2026-06-08 04:24:54,427 - INFO - Deleting VM ctest-TestBGPaasZone-41459311-74069642
2026-06-08 04:24:54,497 - INFO - Deleting VN ctest-cnz_vn-20817177
2026-06-08 04:24:54,538 - DEBUG - VN d561b5a6-da1b-4732-b7ab-e35d93c16ffa still in use: Unable to complete operation on network d561b5a6-da1b-4732-b7ab-e35d93c16ffa. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-6fc75bfb-d979-447c-823c-82a41f8d69cb']
2026-06-08 04:24:54,538 - WARNING - Deleting VN ctest-cnz_vn-20817177 failed..Will retry
2026-06-08 04:24:56,741 - DEBUG - Response for deleting network ()
2026-06-08 04:24:58,036 - DEBUG - Skipping xmpp flap check
2026-06-08 04:24:58,036 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:44]
2026-06-08 04:24:58,037 - INFO - --------------------------------------------------------------------------------
2026-06-08 04:24:58,867 - INFO - Deleted project: ctest-TestBGPaasZone-41459311, ID : 3022ff1b-e980-4e3b-8fd4-5c518226bc87