is in ACTIVE state now
2025-10-14 13:17:41,909 - INFO - VM name : ctest-TestBGPaasZone-14607823-46072692
2025-10-14 13:17:41,999 - DEBUG - VM ctest-TestBGPaasZone-14607823-46072692 ID is d1f41a04-104f-44c0-b7fd-7ec787c314f6
2025-10-14 13:17:42,000 - DEBUG - VM ctest-TestBGPaasZone-14607823-46072692 launched on Node an-jenkins-deploy-platform-ansible-os-4312-1
2025-10-14 13:17:42,092 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/d1f41a04-104f-44c0-b7fd-7ec787c314f6
2025-10-14 13:17:42,107 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/043d360f-b60d-481a-b89a-fd01a135684f
2025-10-14 13:17:43,280 - 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.64 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.74 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 1.735/2.687/3.639/0.952 ms')
2025-10-14 13:17:43,280 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-14607823-46072692 passed
2025-10-14 13:17:43,362 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-14 13:17:43,362 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-14607823-46072692, IP 144.87.185.5, Port 22
2025-10-14 13:17:43,531 - DEBUG - VM ctest-TestBGPaasZone-14607823-46072692 is ready for SSH connections
2025-10-14 13:17:43,674 - INFO - Created control node zone ctest-test-zone-04113248(ba115592-c239-4c0f-a60e-f1b2095bcdad)
2025-10-14 13:17:44,066 - INFO - Created control node zone ctest-test-zone-76816004(d5367c06-5cf2-4356-a78f-dbdcc0491432)
2025-10-14 13:17:44,438 - INFO - Created control node zone ctest-test-zone-07688953(85b85727-caaa-4704-af53-ffd409205365)
2025-10-14 13:17:44,738 - INFO - Creating BGPaaS ctest-TestBGPaasZone-14607823-08744379
2025-10-14 13:17:44,826 - INFO - BGPaaS: ctest-TestBGPaasZone-14607823-08744379(817f1f27-cf2f-42db-8623-c537e940036c)
2025-10-14 13:17:44,826 - DEBUG - Requesting: http://10.0.0.254:8082/bgp-as-a-service/817f1f27-cf2f-42db-8623-c537e940036c
2025-10-14 13:17:44,837 - INFO - verify_in_api_server passed for BGPaaS obj 817f1f27-cf2f-42db-8623-c537e940036c
2025-10-14 13:17:44,837 - INFO - BGPaaS(817f1f27-cf2f-42db-8623-c537e940036c): verify_on_setup passed
2025-10-14 13:17:44,837 - INFO - We will configure BGP on the VM
2025-10-14 13:17:45,270 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-14 13:17:45,270 - INFO - Configuring BGP on ctest-TestBGPaasZone-14607823-58597579
2025-10-14 13:17:45,270 - 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 144.87.185.3;
protocol bgp bgp_1{
local as 65000;
neighbor 144.87.185.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 144.87.185.3;
}
protocol bfd {
neighbor 144.87.185.1 local 144.87.185.3 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-10-14 13:17:45,271 - 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 144.87.185.3;
protocol bgp bgp_1{
local as 65000;
neighbor 144.87.185.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 144.87.185.3;
}
protocol bfd {
neighbor 144.87.185.1 local 144.87.185.3 multihop on;
}
EOS
2025-10-14 13:18:06,257 - DEBUG - None
2025-10-14 13:18:06,257 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 144.87.185.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 144.87.185.3;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-10-14 13:18:06,257 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 144.87.185.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 144.87.185.3;
}
EOS
2025-10-14 13:18:07,523 - DEBUG - None
2025-10-14 13:18:07,524 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-10-14 13:18:07,524 - DEBUG - service bird restart
2025-10-14 13:18:11,304 - DEBUG - bird stop/waiting
bird start/running, process 1430
2025-10-14 13:18:11,304 - INFO - Attaching VMI 4d6e797c-bbfc-4688-b319-6f8e2ecbf457 to BGPaaS 817f1f27-cf2f-42db-8623-c537e940036c
2025-10-14 13:18:11,390 - INFO - Attaching the VMI 65d39d96-8bc5-4fbe-a403-0e4c6b9e8f04 to the BGPaaS 817f1f27-cf2f-42db-8623-c537e940036c object
2025-10-14 13:18:11,390 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 144.87.185.20 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-10-14 13:18:11,390 - DEBUG - sudo ip addr add 144.87.185.20 dev eth0
2025-10-14 13:18:13,464 - DEBUG - None
2025-10-14 13:18:13,530 - INFO - Creating BGPaaS ctest-TestBGPaasZone-14607823-11144886
2025-10-14 13:18:13,610 - INFO - BGPaaS: ctest-TestBGPaasZone-14607823-11144886(e7c93a9d-3f49-41f8-80d3-de1b3a7c0102)
2025-10-14 13:18:13,610 - DEBUG - Requesting: http://10.0.0.254:8082/bgp-as-a-service/e7c93a9d-3f49-41f8-80d3-de1b3a7c0102
2025-10-14 13:18:13,620 - INFO - verify_in_api_server passed for BGPaaS obj e7c93a9d-3f49-41f8-80d3-de1b3a7c0102
2025-10-14 13:18:13,620 - INFO - BGPaaS(e7c93a9d-3f49-41f8-80d3-de1b3a7c0102): verify_on_setup passed
2025-10-14 13:18:13,620 - INFO - We will configure BGP on the VM
2025-10-14 13:18:14,070 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-14 13:18:14,070 - INFO - Configuring BGP on ctest-TestBGPaasZone-14607823-26896567
2025-10-14 13:18:14,070 - 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 144.87.185.4;
protocol bgp bgp_1{
local as 65000;
neighbor 144.87.185.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 144.87.185.4;
}
protocol bfd {
neighbor 144.87.185.1 local 144.87.185.4 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2025-10-14 13:18:14,070 - 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 144.87.185.4;
protocol bgp bgp_1{
local as 65000;
neighbor 144.87.185.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 144.87.185.4;
}
protocol bfd {
neighbor 144.87.185.1 local 144.87.185.4 multihop on;
}
EOS
2025-10-14 13:18:19,118 - DEBUG - None
2025-10-14 13:18:19,118 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 144.87.185.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 144.87.185.4;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2025-10-14 13:18:19,119 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 144.87.185.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 144.87.185.4;
}
EOS
2025-10-14 13:18:19,662 - DEBUG - None
2025-10-14 13:18:19,663 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2025-10-14 13:18:19,663 - DEBUG - service bird restart
2025-10-14 13:18:21,346 - DEBUG - bird stop/waiting
bird start/running, process 1446
2025-10-14 13:18:21,346 - INFO - Attaching VMI 55d07cb3-47af-4312-8da2-b01d633f36af to BGPaaS e7c93a9d-3f49-41f8-80d3-de1b3a7c0102
2025-10-14 13:18:21,435 - INFO - Attaching the VMI ba03f587-8d72-4762-b6e0-88529fea56fa to the BGPaaS e7c93a9d-3f49-41f8-80d3-de1b3a7c0102 object
2025-10-14 13:18:21,435 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 144.87.185.20 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.22, gateway password: c0ntrail123
2025-10-14 13:18:21,436 - DEBUG - sudo ip addr add 144.87.185.20 dev eth0
2025-10-14 13:18:22,075 - DEBUG - None
2025-10-14 13:18:22,075 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-14 13:18:22,075 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-14 13:18:22,091 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-10-14 13:18:22,092 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-14 13:18:22,110 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-10-14 13:18:22,116 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-14 13:18:22,116 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-14 13:18:22,129 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.14']
2025-10-14 13:18:28,131 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-14 13:18:28,145 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.14']
2025-10-14 13:18:34,147 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-14 13:18:34,165 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-10-14 13:18:34,166 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-14 13:18:34,186 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-10-14 13:18:34,193 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 144.87.185.20, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-10-14 13:18:34,193 - DEBUG - ping -s 56 -c 3 -W 1 144.87.185.20
2025-10-14 13:18:41,845 - DEBUG - PING 144.87.185.20 (144.87.185.20) 56(84) bytes of data.
64 bytes from 144.87.185.20: icmp_seq=1 ttl=64 time=11.8 ms
64 bytes from 144.87.185.20: icmp_seq=2 ttl=64 time=1.00 ms
64 bytes from 144.87.185.20: icmp_seq=3 ttl=64 time=1.20 ms
--- 144.87.185.20 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.003/4.671/11.806/5.045 ms
2025-10-14 13:18:41,845 - INFO - Ping to IP 144.87.185.20 from VM ctest-TestBGPaasZone-14607823-46072692 passed
2025-10-14 13:18:41,845 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-14 13:18:41,845 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-14 13:18:41,860 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-10-14 13:18:41,860 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-14 13:18:41,880 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-10-14 13:18:41,887 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-14 13:18:41,887 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-14 13:18:41,900 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-10-14 13:18:41,900 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-14 13:18:41,922 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-10-14 13:18:41,929 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 144.87.185.20, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-10-14 13:18:41,929 - DEBUG - ping -s 56 -c 3 -W 1 144.87.185.20
2025-10-14 13:18:44,505 - DEBUG - PING 144.87.185.20 (144.87.185.20) 56(84) bytes of data.
64 bytes from 144.87.185.20: icmp_seq=1 ttl=64 time=1.83 ms
64 bytes from 144.87.185.20: icmp_seq=2 ttl=64 time=1.38 ms
64 bytes from 144.87.185.20: icmp_seq=3 ttl=64 time=1.12 ms
--- 144.87.185.20 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.127/1.449/1.833/0.291 ms
2025-10-14 13:18:44,505 - INFO - Ping to IP 144.87.185.20 from VM ctest-TestBGPaasZone-14607823-46072692 passed
2025-10-14 13:18:44,505 - INFO - Detaching VMI 55d07cb3-47af-4312-8da2-b01d633f36af from BGPaaS e7c93a9d-3f49-41f8-80d3-de1b3a7c0102
2025-10-14 13:18:44,594 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-14607823-11144886(e7c93a9d-3f49-41f8-80d3-de1b3a7c0102)
2025-10-14 13:18:44,661 - DEBUG - Requesting: http://10.0.0.254:8082/bgp-as-a-service/e7c93a9d-3f49-41f8-80d3-de1b3a7c0102
2025-10-14 13:18:44,695 - DEBUG - Response Code: 404
2025-10-14 13:18:44,696 - DEBUG - BGPaaS: e7c93a9d-3f49-41f8-80d3-de1b3a7c0102 deleted from api server
2025-10-14 13:18:44,696 - INFO - BGPaaS(e7c93a9d-3f49-41f8-80d3-de1b3a7c0102): verify_on_cleanup passed
2025-10-14 13:18:44,696 - INFO - Detaching VMI 4d6e797c-bbfc-4688-b319-6f8e2ecbf457 from BGPaaS 817f1f27-cf2f-42db-8623-c537e940036c
2025-10-14 13:18:44,786 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-14607823-08744379(817f1f27-cf2f-42db-8623-c537e940036c)
2025-10-14 13:18:44,855 - DEBUG - Requesting: http://10.0.0.254:8082/bgp-as-a-service/817f1f27-cf2f-42db-8623-c537e940036c
2025-10-14 13:18:44,862 - DEBUG - Response Code: 404
2025-10-14 13:18:44,863 - DEBUG - BGPaaS: 817f1f27-cf2f-42db-8623-c537e940036c deleted from api server
2025-10-14 13:18:44,863 - INFO - BGPaaS(817f1f27-cf2f-42db-8623-c537e940036c): verify_on_cleanup passed
2025-10-14 13:18:44,863 - INFO - Deleting Control node zone ctest-test-zone-07688953(85b85727-caaa-4704-af53-ffd409205365)
2025-10-14 13:18:45,025 - INFO - Deleting Control node zone ctest-test-zone-76816004(d5367c06-5cf2-4356-a78f-dbdcc0491432)
2025-10-14 13:18:45,220 - INFO - Deleting Control node zone ctest-test-zone-04113248(ba115592-c239-4c0f-a60e-f1b2095bcdad)
2025-10-14 13:18:45,411 - INFO - Deleting VM ctest-TestBGPaasZone-14607823-46072692
2025-10-14 13:18:45,496 - INFO - Deleting VM ctest-TestBGPaasZone-14607823-26896567
2025-10-14 13:18:45,568 - INFO - Deleting VM ctest-TestBGPaasZone-14607823-58597579
2025-10-14 13:18:45,632 - INFO - Deleting VN ctest-cnz_vn-39673709
2025-10-14 13:18:45,677 - DEBUG - VN a4341679-7c88-4f43-b30a-0bb492a43e1a still in use: Unable to complete operation on network a4341679-7c88-4f43-b30a-0bb492a43e1a. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f33853aa-b417-476e-858e-760d5e713a86']
2025-10-14 13:18:45,677 - WARNING - Deleting VN ctest-cnz_vn-39673709 failed..Will retry
2025-10-14 13:18:47,919 - DEBUG - Response for deleting network ()
2025-10-14 13:18:49,204 - DEBUG - Skipping xmpp flap check
2025-10-14 13:18:49,205 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:50]
2025-10-14 13:18:49,205 - INFO - --------------------------------------------------------------------------------
2025-10-14 13:18:49,981 - INFO - Deleted project: ctest-TestBGPaasZone-14607823, ID : d7099770-148a-4d71-9d94-8f1b0fc6a6a6