is in ACTIVE state now
2025-10-23 04:04:07,793 - INFO - VM name : ctest-TestBGPaasZone-57692225-86877389
2025-10-23 04:04:07,880 - DEBUG - VM ctest-TestBGPaasZone-57692225-86877389 ID is 210e43eb-70f4-497a-b0fd-4b0542f89413
2025-10-23 04:04:07,880 - DEBUG - VM ctest-TestBGPaasZone-57692225-86877389 launched on Node an-jenkins-deploy-platform-ansible-os-4448-1
2025-10-23 04:04:07,964 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine/210e43eb-70f4-497a-b0fd-4b0542f89413
2025-10-23 04:04:07,974 - DEBUG - Requesting: http://10.0.0.133:8082/virtual-machine-interface/e762c053-b3fd-462a-8027-286e2414c1fc
2025-10-23 04:04:09,277 - 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.29 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=143 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 3.294/73.172/143.050/69.878 ms')
2025-10-23 04:04:09,277 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-57692225-86877389 passed
2025-10-23 04:04:09,344 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 04:04:09,344 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57692225-86877389, IP 145.225.192.5, Port 22
2025-10-23 04:04:09,516 - DEBUG - VM ctest-TestBGPaasZone-57692225-86877389 is ready for SSH connections
2025-10-23 04:04:09,644 - INFO - Created control node zone ctest-test-zone-52770172(6d9ea58c-a388-4b57-9117-a35d6c47e2f5)
2025-10-23 04:04:09,988 - INFO - Created control node zone ctest-test-zone-29857667(362d23cc-c334-4fa1-9296-3d7441ca5188)
2025-10-23 04:04:10,344 - INFO - Created control node zone ctest-test-zone-52935971(e01b4cef-b75d-48aa-887c-36b01dcfd402)
2025-10-23 04:04:10,620 - INFO - Creating BGPaaS ctest-TestBGPaasZone-57692225-83046637
2025-10-23 04:04:10,692 - INFO - BGPaaS: ctest-TestBGPaasZone-57692225-83046637(4a0e30f7-6dbc-4761-a674-b9b7e137da40)
2025-10-23 04:04:10,692 - DEBUG - Requesting: http://10.0.0.41:8082/bgp-as-a-service/4a0e30f7-6dbc-4761-a674-b9b7e137da40
2025-10-23 04:04:10,703 - INFO - verify_in_api_server passed for BGPaaS obj 4a0e30f7-6dbc-4761-a674-b9b7e137da40
2025-10-23 04:04:10,703 - INFO - BGPaaS(4a0e30f7-6dbc-4761-a674-b9b7e137da40): verify_on_setup passed
2025-10-23 04:04:10,703 - INFO - We will configure BGP on the VM
2025-10-23 04:04:11,128 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 04:04:11,128 - INFO - Configuring BGP on ctest-TestBGPaasZone-57692225-83601419
2025-10-23 04:04:11,128 - 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 145.225.192.3;
protocol bgp bgp_1{
local as 65000;
neighbor 145.225.192.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 145.225.192.3;
}
protocol bfd {
neighbor 145.225.192.1 local 145.225.192.3 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.48, gateway password: c0ntrail123
2025-10-23 04:04:11,128 - 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 145.225.192.3;
protocol bgp bgp_1{
local as 65000;
neighbor 145.225.192.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 145.225.192.3;
}
protocol bfd {
neighbor 145.225.192.1 local 145.225.192.3 multihop on;
}
EOS
2025-10-23 04:04:26,820 - DEBUG - None
2025-10-23 04:04:26,820 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 145.225.192.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 145.225.192.3;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.48, gateway password: c0ntrail123
2025-10-23 04:04:26,820 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 145.225.192.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 145.225.192.3;
}
EOS
2025-10-23 04:04:27,989 - DEBUG - None
2025-10-23 04:04:27,990 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.48, gateway password: c0ntrail123
2025-10-23 04:04:27,990 - DEBUG - service bird restart
2025-10-23 04:04:31,872 - DEBUG - bird stop/waiting
bird start/running, process 1435
2025-10-23 04:04:31,873 - INFO - Attaching VMI c5e2c1ee-5d99-4f4b-bf13-3b0679477c86 to BGPaaS 4a0e30f7-6dbc-4761-a674-b9b7e137da40
2025-10-23 04:04:31,944 - INFO - Attaching the VMI 6146a685-798a-42ca-ba57-cc69c9633741 to the BGPaaS 4a0e30f7-6dbc-4761-a674-b9b7e137da40 object
2025-10-23 04:04:31,944 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 145.225.192.20 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.48, gateway password: c0ntrail123
2025-10-23 04:04:31,945 - DEBUG - sudo ip addr add 145.225.192.20 dev eth0
2025-10-23 04:04:33,764 - DEBUG - None
2025-10-23 04:04:33,824 - INFO - Creating BGPaaS ctest-TestBGPaasZone-57692225-28307447
2025-10-23 04:04:33,896 - INFO - BGPaaS: ctest-TestBGPaasZone-57692225-28307447(c193d3e1-c014-4ba1-b32d-9d783345fc21)
2025-10-23 04:04:33,896 - DEBUG - Requesting: http://10.0.0.41:8082/bgp-as-a-service/c193d3e1-c014-4ba1-b32d-9d783345fc21
2025-10-23 04:04:33,906 - INFO - verify_in_api_server passed for BGPaaS obj c193d3e1-c014-4ba1-b32d-9d783345fc21
2025-10-23 04:04:33,906 - INFO - BGPaaS(c193d3e1-c014-4ba1-b32d-9d783345fc21): verify_on_setup passed
2025-10-23 04:04:33,906 - INFO - We will configure BGP on the VM
2025-10-23 04:04:34,320 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 04:04:34,320 - INFO - Configuring BGP on ctest-TestBGPaasZone-57692225-86579264
2025-10-23 04:04:34,320 - 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 145.225.192.4;
protocol bgp bgp_1{
local as 65000;
neighbor 145.225.192.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 145.225.192.4;
}
protocol bfd {
neighbor 145.225.192.1 local 145.225.192.4 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.49, gateway password: c0ntrail123
2025-10-23 04:04:34,320 - 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 145.225.192.4;
protocol bgp bgp_1{
local as 65000;
neighbor 145.225.192.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 145.225.192.4;
}
protocol bfd {
neighbor 145.225.192.1 local 145.225.192.4 multihop on;
}
EOS
2025-10-23 04:04:40,495 - DEBUG - None
2025-10-23 04:04:40,496 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 145.225.192.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 145.225.192.4;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.49, gateway password: c0ntrail123
2025-10-23 04:04:40,496 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 145.225.192.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 145.225.192.4;
}
EOS
2025-10-23 04:04:41,051 - DEBUG - None
2025-10-23 04:04:41,051 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.49, gateway password: c0ntrail123
2025-10-23 04:04:41,051 - DEBUG - service bird restart
2025-10-23 04:04:42,995 - DEBUG - bird stop/waiting
bird start/running, process 1443
2025-10-23 04:04:42,995 - INFO - Attaching VMI 6aa2b031-d095-49d7-ba20-0cf3a948b3a9 to BGPaaS c193d3e1-c014-4ba1-b32d-9d783345fc21
2025-10-23 04:04:43,072 - INFO - Attaching the VMI 8d07c83b-94fd-4036-99b0-bef27e8ce77a to the BGPaaS c193d3e1-c014-4ba1-b32d-9d783345fc21 object
2025-10-23 04:04:43,072 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 145.225.192.20 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.49, gateway password: c0ntrail123
2025-10-23 04:04:43,072 - DEBUG - sudo ip addr add 145.225.192.20 dev eth0
2025-10-23 04:04:43,840 - DEBUG - None
2025-10-23 04:04:43,841 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 04:04:43,856 - INFO - BGPaaS session seen in control-node 10.20.0.129
2025-10-23 04:04:43,857 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 04:04:43,877 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-10-23 04:04:43,883 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 04:04:43,897 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.129']
2025-10-23 04:04:49,915 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.129']
2025-10-23 04:04:55,932 - INFO - BGPaaS session seen in control-node 10.20.0.129
2025-10-23 04:04:55,932 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 04:04:55,952 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-10-23 04:04:55,959 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 145.225.192.20, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.48, gateway password: c0ntrail123
2025-10-23 04:04:55,959 - DEBUG - ping -s 56 -c 3 -W 1 145.225.192.20
2025-10-23 04:05:03,387 - DEBUG - PING 145.225.192.20 (145.225.192.20) 56(84) bytes of data.
64 bytes from 145.225.192.20: icmp_seq=1 ttl=64 time=10.3 ms
64 bytes from 145.225.192.20: icmp_seq=2 ttl=64 time=1.31 ms
64 bytes from 145.225.192.20: icmp_seq=3 ttl=64 time=1.16 ms
--- 145.225.192.20 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2006ms
rtt min/avg/max/mdev = 1.165/4.278/10.356/4.298 ms
2025-10-23 04:05:03,387 - INFO - Ping to IP 145.225.192.20 from VM ctest-TestBGPaasZone-57692225-86877389 passed
2025-10-23 04:05:03,387 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 04:05:03,416 - INFO - BGPaaS session seen in control-node 10.20.0.129
2025-10-23 04:05:03,417 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 04:05:03,439 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-10-23 04:05:03,446 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 04:05:03,468 - INFO - BGPaaS session seen in control-node 10.20.0.129
2025-10-23 04:05:03,470 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-23 04:05:03,492 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-10-23 04:05:03,504 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 145.225.192.20, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.48, gateway password: c0ntrail123
2025-10-23 04:05:03,504 - DEBUG - ping -s 56 -c 3 -W 1 145.225.192.20
2025-10-23 04:05:06,080 - DEBUG - PING 145.225.192.20 (145.225.192.20) 56(84) bytes of data.
64 bytes from 145.225.192.20: icmp_seq=1 ttl=64 time=1.44 ms
64 bytes from 145.225.192.20: icmp_seq=2 ttl=64 time=0.849 ms
64 bytes from 145.225.192.20: icmp_seq=3 ttl=64 time=1.00 ms
--- 145.225.192.20 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 0.849/1.099/1.447/0.256 ms
2025-10-23 04:05:06,080 - INFO - Ping to IP 145.225.192.20 from VM ctest-TestBGPaasZone-57692225-86877389 passed
2025-10-23 04:05:06,080 - INFO - Detaching VMI 6aa2b031-d095-49d7-ba20-0cf3a948b3a9 from BGPaaS c193d3e1-c014-4ba1-b32d-9d783345fc21
2025-10-23 04:05:06,164 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-57692225-28307447(c193d3e1-c014-4ba1-b32d-9d783345fc21)
2025-10-23 04:05:06,241 - DEBUG - Requesting: http://10.0.0.41:8082/bgp-as-a-service/c193d3e1-c014-4ba1-b32d-9d783345fc21
2025-10-23 04:05:06,350 - DEBUG - Response Code: 404
2025-10-23 04:05:06,350 - DEBUG - BGPaaS: c193d3e1-c014-4ba1-b32d-9d783345fc21 deleted from api server
2025-10-23 04:05:06,350 - INFO - BGPaaS(c193d3e1-c014-4ba1-b32d-9d783345fc21): verify_on_cleanup passed
2025-10-23 04:05:06,350 - INFO - Detaching VMI c5e2c1ee-5d99-4f4b-bf13-3b0679477c86 from BGPaaS 4a0e30f7-6dbc-4761-a674-b9b7e137da40
2025-10-23 04:05:06,432 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-57692225-83046637(4a0e30f7-6dbc-4761-a674-b9b7e137da40)
2025-10-23 04:05:06,509 - DEBUG - Requesting: http://10.0.0.41:8082/bgp-as-a-service/4a0e30f7-6dbc-4761-a674-b9b7e137da40
2025-10-23 04:05:06,515 - DEBUG - Response Code: 404
2025-10-23 04:05:06,515 - DEBUG - BGPaaS: 4a0e30f7-6dbc-4761-a674-b9b7e137da40 deleted from api server
2025-10-23 04:05:06,516 - INFO - BGPaaS(4a0e30f7-6dbc-4761-a674-b9b7e137da40): verify_on_cleanup passed
2025-10-23 04:05:06,516 - INFO - Deleting Control node zone ctest-test-zone-52935971(e01b4cef-b75d-48aa-887c-36b01dcfd402)
2025-10-23 04:05:06,687 - INFO - Deleting Control node zone ctest-test-zone-29857667(362d23cc-c334-4fa1-9296-3d7441ca5188)
2025-10-23 04:05:06,843 - INFO - Deleting Control node zone ctest-test-zone-52770172(6d9ea58c-a388-4b57-9117-a35d6c47e2f5)
2025-10-23 04:05:06,986 - INFO - Deleting VM ctest-TestBGPaasZone-57692225-86877389
2025-10-23 04:05:07,080 - INFO - Deleting VM ctest-TestBGPaasZone-57692225-86579264
2025-10-23 04:05:07,160 - INFO - Deleting VM ctest-TestBGPaasZone-57692225-83601419
2025-10-23 04:05:07,232 - INFO - Deleting VN ctest-cnz_vn-18933714
2025-10-23 04:05:07,276 - DEBUG - VN 5f3db125-2c5d-4503-bfc6-c5d6da279db9 still in use: Unable to complete operation on network 5f3db125-2c5d-4503-bfc6-c5d6da279db9. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-4c405d4c-7454-4591-becb-140e380b6c10']
2025-10-23 04:05:07,276 - WARNING - Deleting VN ctest-cnz_vn-18933714 failed..Will retry
2025-10-23 04:05:09,493 - DEBUG - Response for deleting network ()
2025-10-23 04:05:10,778 - DEBUG - Skipping xmpp flap check
2025-10-23 04:05:10,778 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:45]
2025-10-23 04:05:10,779 - INFO - --------------------------------------------------------------------------------
2025-10-23 04:05:11,635 - INFO - Deleted project: ctest-TestBGPaasZone-57692225, ID : 8ce3fc94-9659-4ac0-b72e-01862be9f0e1