is in ACTIVE state now
2026-05-25 04:13:12,346 - INFO - VM name : ctest-TestBGPaasZone-86304717-98357539
2026-05-25 04:13:12,455 - DEBUG - VM ctest-TestBGPaasZone-86304717-98357539 ID is 447163f9-27d8-428f-832c-51a4e1122104
2026-05-25 04:13:12,455 - DEBUG - VM ctest-TestBGPaasZone-86304717-98357539 launched on Node an-jenkins-deploy-platform-ansible-os-5822-1
2026-05-25 04:13:12,580 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/447163f9-27d8-428f-832c-51a4e1122104
2026-05-25 04:13:12,592 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/669bfa10-5f83-4ee8-862d-bc0628e79693
2026-05-25 04:13:13,777 - 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.74 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=2.24 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 = 2.239/3.487/4.735/1.248 ms')
2026-05-25 04:13:13,777 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-TestBGPaasZone-86304717-98357539 passed
2026-05-25 04:13:13,860 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-25 04:13:13,860 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-86304717-98357539, IP 126.249.43.5, Port 22
2026-05-25 04:13:14,039 - DEBUG - VM ctest-TestBGPaasZone-86304717-98357539 is ready for SSH connections
2026-05-25 04:13:14,200 - INFO - Created control node zone ctest-test-zone-42795871(6f8df959-b15b-4c5e-aaab-376572c96e42)
2026-05-25 04:13:14,584 - INFO - Created control node zone ctest-test-zone-55739077(b6c4af6e-fd02-407a-adb9-2092fb14da24)
2026-05-25 04:13:14,960 - INFO - Created control node zone ctest-test-zone-15702779(8a5e6794-643f-4cf9-a848-411de46dd5a1)
2026-05-25 04:13:15,248 - INFO - Creating BGPaaS ctest-TestBGPaasZone-86304717-83787537
2026-05-25 04:13:15,332 - INFO - BGPaaS: ctest-TestBGPaasZone-86304717-83787537(02323de4-1e78-468d-86b1-da61eb8ae8cf)
2026-05-25 04:13:15,332 - DEBUG - Requesting: http://10.0.0.73:8082/bgp-as-a-service/02323de4-1e78-468d-86b1-da61eb8ae8cf
2026-05-25 04:13:15,348 - INFO - verify_in_api_server passed for BGPaaS obj 02323de4-1e78-468d-86b1-da61eb8ae8cf
2026-05-25 04:13:15,348 - INFO - BGPaaS(02323de4-1e78-468d-86b1-da61eb8ae8cf): verify_on_setup passed
2026-05-25 04:13:15,348 - INFO - We will configure BGP on the VM
2026-05-25 04:13:15,804 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-25 04:13:15,804 - INFO - Configuring BGP on ctest-TestBGPaasZone-86304717-53655321
2026-05-25 04:13:15,804 - 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 126.249.43.3;
protocol bgp bgp_1{
local as 65000;
neighbor 126.249.43.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 126.249.43.3;
}
protocol bfd {
neighbor 126.249.43.1 local 126.249.43.3 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.133, gateway password: c0ntrail123
2026-05-25 04:13:15,804 - 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 126.249.43.3;
protocol bgp bgp_1{
local as 65000;
neighbor 126.249.43.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 126.249.43.3;
}
protocol bfd {
neighbor 126.249.43.1 local 126.249.43.3 multihop on;
}
EOS
2026-05-25 04:13:34,534 - DEBUG - None
2026-05-25 04:13:34,535 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 126.249.43.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 126.249.43.3;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.133, gateway password: c0ntrail123
2026-05-25 04:13:34,535 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 126.249.43.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 126.249.43.3;
}
EOS
2026-05-25 04:13:35,775 - DEBUG - None
2026-05-25 04:13:35,776 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.133, gateway password: c0ntrail123
2026-05-25 04:13:35,776 - DEBUG - service bird restart
2026-05-25 04:13:39,677 - DEBUG - bird stop/waiting
bird start/running, process 1428
2026-05-25 04:13:39,678 - INFO - Attaching VMI ab51d967-4f96-4421-b229-2e04d3077659 to BGPaaS 02323de4-1e78-468d-86b1-da61eb8ae8cf
2026-05-25 04:13:39,760 - INFO - Attaching the VMI 13b99684-dafa-4aea-aefb-99a7452d4ce3 to the BGPaaS 02323de4-1e78-468d-86b1-da61eb8ae8cf object
2026-05-25 04:13:39,760 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 126.249.43.20 dev eth0, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.133, gateway password: c0ntrail123
2026-05-25 04:13:39,760 - DEBUG - sudo ip addr add 126.249.43.20 dev eth0
2026-05-25 04:13:41,476 - DEBUG - None
2026-05-25 04:13:41,544 - INFO - Creating BGPaaS ctest-TestBGPaasZone-86304717-81221310
2026-05-25 04:13:41,632 - INFO - BGPaaS: ctest-TestBGPaasZone-86304717-81221310(232e8570-9203-4327-b655-371994baf130)
2026-05-25 04:13:41,632 - DEBUG - Requesting: http://10.0.0.73:8082/bgp-as-a-service/232e8570-9203-4327-b655-371994baf130
2026-05-25 04:13:41,644 - INFO - verify_in_api_server passed for BGPaaS obj 232e8570-9203-4327-b655-371994baf130
2026-05-25 04:13:41,645 - INFO - BGPaaS(232e8570-9203-4327-b655-371994baf130): verify_on_setup passed
2026-05-25 04:13:41,645 - INFO - We will configure BGP on the VM
2026-05-25 04:13:42,120 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-25 04:13:42,120 - INFO - Configuring BGP on ctest-TestBGPaasZone-86304717-58735977
2026-05-25 04:13:42,120 - 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 126.249.43.4;
protocol bgp bgp_1{
local as 65000;
neighbor 126.249.43.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 126.249.43.4;
}
protocol bfd {
neighbor 126.249.43.1 local 126.249.43.4 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-05-25 04:13:42,120 - 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 126.249.43.4;
protocol bgp bgp_1{
local as 65000;
neighbor 126.249.43.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 126.249.43.4;
}
protocol bfd {
neighbor 126.249.43.1 local 126.249.43.4 multihop on;
}
EOS
2026-05-25 04:13:47,771 - DEBUG - None
2026-05-25 04:13:47,771 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 126.249.43.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 126.249.43.4;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-05-25 04:13:47,771 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 126.249.43.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 126.249.43.4;
}
EOS
2026-05-25 04:13:48,325 - DEBUG - None
2026-05-25 04:13:48,325 - 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-05-25 04:13:48,325 - DEBUG - service bird restart
2026-05-25 04:13:50,147 - DEBUG - bird stop/waiting
bird start/running, process 1439
2026-05-25 04:13:50,147 - INFO - Attaching VMI decc75a0-c2c5-4b92-86f9-1e1e0dbbd46c to BGPaaS 232e8570-9203-4327-b655-371994baf130
2026-05-25 04:13:50,228 - INFO - Attaching the VMI 4f4a7f9b-cfdf-492a-8648-875e113a43e9 to the BGPaaS 232e8570-9203-4327-b655-371994baf130 object
2026-05-25 04:13:50,228 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 126.249.43.20 dev eth0, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-05-25 04:13:50,228 - DEBUG - sudo ip addr add 126.249.43.20 dev eth0
2026-05-25 04:13:50,964 - DEBUG - None
2026-05-25 04:13:50,964 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-25 04:13:50,977 - INFO - BGPaaS session seen in control-node 10.20.0.252
2026-05-25 04:13:50,977 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-25 04:13:50,996 - INFO - BGPaaS session seen in control-node 10.20.0.249
2026-05-25 04:13:51,005 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-25 04:13:51,020 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.252']
2026-05-25 04:13:57,036 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.252']
2026-05-25 04:14:03,054 - INFO - BGPaaS session seen in control-node 10.20.0.252
2026-05-25 04:14:03,055 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-25 04:14:03,072 - INFO - BGPaaS session seen in control-node 10.20.0.249
2026-05-25 04:14:03,079 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 126.249.43.20, host_string: ubuntu@169.254.0.6, password: ubuntugateway: ubuntu@10.0.0.133, gateway password: c0ntrail123
2026-05-25 04:14:03,079 - DEBUG - ping -s 56 -c 3 -W 1 126.249.43.20
2026-05-25 04:14:10,829 - DEBUG - PING 126.249.43.20 (126.249.43.20) 56(84) bytes of data.
64 bytes from 126.249.43.20: icmp_seq=1 ttl=64 time=9.34 ms
64 bytes from 126.249.43.20: icmp_seq=2 ttl=64 time=1.34 ms
64 bytes from 126.249.43.20: icmp_seq=3 ttl=64 time=1.17 ms
--- 126.249.43.20 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.172/3.952/9.343/3.812 ms
2026-05-25 04:14:10,829 - INFO - Ping to IP 126.249.43.20 from VM ctest-TestBGPaasZone-86304717-98357539 passed
2026-05-25 04:14:10,829 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-25 04:14:10,847 - INFO - BGPaaS session seen in control-node 10.20.0.252
2026-05-25 04:14:10,848 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-25 04:14:10,868 - INFO - BGPaaS session seen in control-node 10.20.0.249
2026-05-25 04:14:10,874 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-25 04:14:10,891 - INFO - BGPaaS session seen in control-node 10.20.0.252
2026-05-25 04:14:10,891 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-25 04:14:10,913 - INFO - BGPaaS session seen in control-node 10.20.0.249
2026-05-25 04:14:10,919 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 126.249.43.20, host_string: ubuntu@169.254.0.6, password: ubuntugateway: ubuntu@10.0.0.133, gateway password: c0ntrail123
2026-05-25 04:14:10,919 - DEBUG - ping -s 56 -c 3 -W 1 126.249.43.20
2026-05-25 04:14:13,468 - DEBUG - PING 126.249.43.20 (126.249.43.20) 56(84) bytes of data.
64 bytes from 126.249.43.20: icmp_seq=1 ttl=64 time=4.39 ms
64 bytes from 126.249.43.20: icmp_seq=2 ttl=64 time=1.21 ms
64 bytes from 126.249.43.20: icmp_seq=3 ttl=64 time=1.10 ms
--- 126.249.43.20 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.103/2.237/4.392/1.525 ms
2026-05-25 04:14:13,468 - INFO - Ping to IP 126.249.43.20 from VM ctest-TestBGPaasZone-86304717-98357539 passed
2026-05-25 04:14:13,468 - INFO - Detaching VMI decc75a0-c2c5-4b92-86f9-1e1e0dbbd46c from BGPaaS 232e8570-9203-4327-b655-371994baf130
2026-05-25 04:14:13,556 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-86304717-81221310(232e8570-9203-4327-b655-371994baf130)
2026-05-25 04:14:13,646 - DEBUG - Requesting: http://10.0.0.73:8082/bgp-as-a-service/232e8570-9203-4327-b655-371994baf130
2026-05-25 04:14:13,723 - DEBUG - Response Code: 404
2026-05-25 04:14:13,723 - DEBUG - BGPaaS: 232e8570-9203-4327-b655-371994baf130 deleted from api server
2026-05-25 04:14:13,723 - INFO - BGPaaS(232e8570-9203-4327-b655-371994baf130): verify_on_cleanup passed
2026-05-25 04:14:13,723 - INFO - Detaching VMI ab51d967-4f96-4421-b229-2e04d3077659 from BGPaaS 02323de4-1e78-468d-86b1-da61eb8ae8cf
2026-05-25 04:14:13,821 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-86304717-83787537(02323de4-1e78-468d-86b1-da61eb8ae8cf)
2026-05-25 04:14:13,907 - DEBUG - Requesting: http://10.0.0.73:8082/bgp-as-a-service/02323de4-1e78-468d-86b1-da61eb8ae8cf
2026-05-25 04:14:13,915 - DEBUG - Response Code: 404
2026-05-25 04:14:13,915 - DEBUG - BGPaaS: 02323de4-1e78-468d-86b1-da61eb8ae8cf deleted from api server
2026-05-25 04:14:13,915 - INFO - BGPaaS(02323de4-1e78-468d-86b1-da61eb8ae8cf): verify_on_cleanup passed
2026-05-25 04:14:13,915 - INFO - Deleting Control node zone ctest-test-zone-15702779(8a5e6794-643f-4cf9-a848-411de46dd5a1)
2026-05-25 04:14:14,094 - INFO - Deleting Control node zone ctest-test-zone-55739077(b6c4af6e-fd02-407a-adb9-2092fb14da24)
2026-05-25 04:14:14,254 - INFO - Deleting Control node zone ctest-test-zone-42795871(6f8df959-b15b-4c5e-aaab-376572c96e42)
2026-05-25 04:14:14,424 - INFO - Deleting VM ctest-TestBGPaasZone-86304717-98357539
2026-05-25 04:14:14,561 - INFO - Deleting VM ctest-TestBGPaasZone-86304717-58735977
2026-05-25 04:14:14,662 - INFO - Deleting VM ctest-TestBGPaasZone-86304717-53655321
2026-05-25 04:14:14,755 - INFO - Deleting VN ctest-cnz_vn-18226843
2026-05-25 04:14:14,812 - DEBUG - VN a9f3f527-b778-418b-8e7b-be057be0f85f still in use: Unable to complete operation on network a9f3f527-b778-418b-8e7b-be057be0f85f. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-8568ae10-e817-4c6f-8d6c-c4f09e7a6cd0']
2026-05-25 04:14:14,812 - WARNING - Deleting VN ctest-cnz_vn-18226843 failed..Will retry
2026-05-25 04:14:17,042 - DEBUG - Response for deleting network ()
2026-05-25 04:14:18,319 - DEBUG - Skipping xmpp flap check
2026-05-25 04:14:18,319 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:48]
2026-05-25 04:14:18,319 - INFO - --------------------------------------------------------------------------------
2026-05-25 04:14:19,134 - INFO - Deleted project: ctest-TestBGPaasZone-86304717, ID : fd623040-726f-4dee-b3d1-cb805cdaa636