is in ACTIVE state now
2025-10-30 04:03:55,156 - INFO - VM name : ctest-TestBGPaasZone-10275657-75005059
2025-10-30 04:03:55,237 - DEBUG - VM ctest-TestBGPaasZone-10275657-75005059 ID is 8362c5c8-96a6-468c-93d5-aa82542e0b8c
2025-10-30 04:03:55,237 - DEBUG - VM ctest-TestBGPaasZone-10275657-75005059 launched on Node an-jenkins-deploy-platform-ansible-os-4535-1
2025-10-30 04:03:55,327 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/8362c5c8-96a6-468c-93d5-aa82542e0b8c
2025-10-30 04:03:55,337 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/23d7dab2-a4fd-4de8-9403-bb8fb8c45b1e
2025-10-30 04:03:56,498 - 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.38 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.85 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 = 1.846/2.614/3.382/0.768 ms')
2025-10-30 04:03:56,498 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-10275657-75005059 passed
2025-10-30 04:03:56,571 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 04:03:56,571 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-10275657-75005059, IP 12.142.4.197, Port 22
2025-10-30 04:03:56,750 - DEBUG - VM ctest-TestBGPaasZone-10275657-75005059 is ready for SSH connections
2025-10-30 04:03:56,884 - INFO - Created control node zone ctest-test-zone-71256264(02da60b4-8a12-434e-833f-19578d4ff966)
2025-10-30 04:03:57,232 - INFO - Created control node zone ctest-test-zone-56560211(51de316b-626c-4572-97d5-df755007774a)
2025-10-30 04:03:57,592 - INFO - Created control node zone ctest-test-zone-00512352(f20986ff-b77c-405d-8f16-c7eb6ecb04d3)
2025-10-30 04:03:57,876 - INFO - Creating BGPaaS ctest-TestBGPaasZone-10275657-14332572
2025-10-30 04:03:57,953 - INFO - BGPaaS: ctest-TestBGPaasZone-10275657-14332572(a3fe7488-5d33-4554-b31b-adb131919874)
2025-10-30 04:03:57,953 - DEBUG - Requesting: http://10.0.0.242:8082/bgp-as-a-service/a3fe7488-5d33-4554-b31b-adb131919874
2025-10-30 04:03:57,964 - INFO - verify_in_api_server passed for BGPaaS obj a3fe7488-5d33-4554-b31b-adb131919874
2025-10-30 04:03:57,964 - INFO - BGPaaS(a3fe7488-5d33-4554-b31b-adb131919874): verify_on_setup passed
2025-10-30 04:03:57,964 - INFO - We will configure BGP on the VM
2025-10-30 04:03:58,405 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 04:03:58,405 - INFO - Configuring BGP on ctest-TestBGPaasZone-10275657-62302743
2025-10-30 04:03:58,405 - 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 12.142.4.195;
protocol bgp bgp_1{
local as 65000;
neighbor 12.142.4.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 12.142.4.195;
}
protocol bfd {
neighbor 12.142.4.193 local 12.142.4.195 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.129, gateway password: c0ntrail123
2025-10-30 04:03:58,405 - 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 12.142.4.195;
protocol bgp bgp_1{
local as 65000;
neighbor 12.142.4.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 12.142.4.195;
}
protocol bfd {
neighbor 12.142.4.193 local 12.142.4.195 multihop on;
}
EOS
2025-10-30 04:04:12,466 - DEBUG - None
2025-10-30 04:04:12,467 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 12.142.4.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 12.142.4.195;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.129, gateway password: c0ntrail123
2025-10-30 04:04:12,467 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 12.142.4.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 12.142.4.195;
}
EOS
2025-10-30 04:04:13,512 - DEBUG - None
2025-10-30 04:04:13,512 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.129, gateway password: c0ntrail123
2025-10-30 04:04:13,512 - DEBUG - service bird restart
2025-10-30 04:04:16,818 - DEBUG - bird stop/waiting
bird start/running, process 1442
2025-10-30 04:04:16,818 - INFO - Attaching VMI e771e22d-9c70-4ece-a551-005689e20017 to BGPaaS a3fe7488-5d33-4554-b31b-adb131919874
2025-10-30 04:04:16,904 - INFO - Attaching the VMI a1382307-cbd5-4a67-8c61-25357bf28e0d to the BGPaaS a3fe7488-5d33-4554-b31b-adb131919874 object
2025-10-30 04:04:16,905 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 12.142.4.212 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.129, gateway password: c0ntrail123
2025-10-30 04:04:16,905 - DEBUG - sudo ip addr add 12.142.4.212 dev eth0
2025-10-30 04:04:17,660 - DEBUG - None
2025-10-30 04:04:17,724 - INFO - Creating BGPaaS ctest-TestBGPaasZone-10275657-89522829
2025-10-30 04:04:17,800 - INFO - BGPaaS: ctest-TestBGPaasZone-10275657-89522829(eb2a60bc-789e-42ab-bfae-75f67681d12c)
2025-10-30 04:04:17,801 - DEBUG - Requesting: http://10.0.0.242:8082/bgp-as-a-service/eb2a60bc-789e-42ab-bfae-75f67681d12c
2025-10-30 04:04:17,811 - INFO - verify_in_api_server passed for BGPaaS obj eb2a60bc-789e-42ab-bfae-75f67681d12c
2025-10-30 04:04:17,811 - INFO - BGPaaS(eb2a60bc-789e-42ab-bfae-75f67681d12c): verify_on_setup passed
2025-10-30 04:04:17,811 - INFO - We will configure BGP on the VM
2025-10-30 04:04:18,232 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 04:04:18,233 - INFO - Configuring BGP on ctest-TestBGPaasZone-10275657-04698185
2025-10-30 04:04:18,233 - 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 12.142.4.196;
protocol bgp bgp_1{
local as 65000;
neighbor 12.142.4.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 12.142.4.196;
}
protocol bfd {
neighbor 12.142.4.193 local 12.142.4.196 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-10-30 04:04:18,233 - 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 12.142.4.196;
protocol bgp bgp_1{
local as 65000;
neighbor 12.142.4.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 12.142.4.196;
}
protocol bfd {
neighbor 12.142.4.193 local 12.142.4.196 multihop on;
}
EOS
2025-10-30 04:04:23,978 - DEBUG - None
2025-10-30 04:04:23,978 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 12.142.4.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 12.142.4.196;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-10-30 04:04:23,978 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 12.142.4.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 12.142.4.196;
}
EOS
2025-10-30 04:04:24,456 - DEBUG - None
2025-10-30 04:04:24,456 - 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-30 04:04:24,456 - DEBUG - service bird restart
2025-10-30 04:04:26,065 - DEBUG - bird stop/waiting
bird start/running, process 1443
2025-10-30 04:04:26,065 - INFO - Attaching VMI 21ff9665-4376-4017-8826-22cd17000f02 to BGPaaS eb2a60bc-789e-42ab-bfae-75f67681d12c
2025-10-30 04:04:26,152 - INFO - Attaching the VMI 6f084fb9-a7ca-40bb-b636-803e2884a74b to the BGPaaS eb2a60bc-789e-42ab-bfae-75f67681d12c object
2025-10-30 04:04:26,153 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 12.142.4.212 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-10-30 04:04:26,153 - DEBUG - sudo ip addr add 12.142.4.212 dev eth0
2025-10-30 04:04:26,791 - DEBUG - None
2025-10-30 04:04:26,791 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 04:04:26,808 - INFO - BGPaaS session seen in control-node 10.20.0.131
2025-10-30 04:04:26,808 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 04:04:26,827 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-10-30 04:04:26,833 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 04:04:26,848 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.131']
2025-10-30 04:04:32,868 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.131']
2025-10-30 04:04:38,884 - INFO - BGPaaS session seen in control-node 10.20.0.131
2025-10-30 04:04:38,885 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 04:04:38,903 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-10-30 04:04:38,909 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 12.142.4.212, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.129, gateway password: c0ntrail123
2025-10-30 04:04:38,909 - DEBUG - ping -s 56 -c 3 -W 1 12.142.4.212
2025-10-30 04:04:45,471 - DEBUG - PING 12.142.4.212 (12.142.4.212) 56(84) bytes of data.
64 bytes from 12.142.4.212: icmp_seq=1 ttl=64 time=8.36 ms
64 bytes from 12.142.4.212: icmp_seq=2 ttl=64 time=1.38 ms
64 bytes from 12.142.4.212: icmp_seq=3 ttl=64 time=1.35 ms
--- 12.142.4.212 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.359/3.703/8.363/3.295 ms
2025-10-30 04:04:45,471 - INFO - Ping to IP 12.142.4.212 from VM ctest-TestBGPaasZone-10275657-75005059 passed
2025-10-30 04:04:45,472 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 04:04:45,485 - INFO - BGPaaS session seen in control-node 10.20.0.131
2025-10-30 04:04:45,486 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 04:04:45,504 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-10-30 04:04:45,511 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 04:04:45,528 - INFO - BGPaaS session seen in control-node 10.20.0.131
2025-10-30 04:04:45,528 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 04:04:45,546 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-10-30 04:04:45,553 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 12.142.4.212, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.129, gateway password: c0ntrail123
2025-10-30 04:04:45,553 - DEBUG - ping -s 56 -c 3 -W 1 12.142.4.212
2025-10-30 04:04:48,103 - DEBUG - PING 12.142.4.212 (12.142.4.212) 56(84) bytes of data.
64 bytes from 12.142.4.212: icmp_seq=1 ttl=64 time=5.20 ms
64 bytes from 12.142.4.212: icmp_seq=2 ttl=64 time=0.804 ms
64 bytes from 12.142.4.212: icmp_seq=3 ttl=64 time=1.19 ms
--- 12.142.4.212 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 0.804/2.400/5.200/1.986 ms
2025-10-30 04:04:48,103 - INFO - Ping to IP 12.142.4.212 from VM ctest-TestBGPaasZone-10275657-75005059 passed
2025-10-30 04:04:48,104 - INFO - Detaching VMI 21ff9665-4376-4017-8826-22cd17000f02 from BGPaaS eb2a60bc-789e-42ab-bfae-75f67681d12c
2025-10-30 04:04:48,180 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-10275657-89522829(eb2a60bc-789e-42ab-bfae-75f67681d12c)
2025-10-30 04:04:48,255 - DEBUG - Requesting: http://10.0.0.242:8082/bgp-as-a-service/eb2a60bc-789e-42ab-bfae-75f67681d12c
2025-10-30 04:04:48,263 - DEBUG - Response Code: 404
2025-10-30 04:04:48,263 - DEBUG - BGPaaS: eb2a60bc-789e-42ab-bfae-75f67681d12c deleted from api server
2025-10-30 04:04:48,263 - INFO - BGPaaS(eb2a60bc-789e-42ab-bfae-75f67681d12c): verify_on_cleanup passed
2025-10-30 04:04:48,263 - INFO - Detaching VMI e771e22d-9c70-4ece-a551-005689e20017 from BGPaaS a3fe7488-5d33-4554-b31b-adb131919874
2025-10-30 04:04:48,364 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-10275657-14332572(a3fe7488-5d33-4554-b31b-adb131919874)
2025-10-30 04:04:48,436 - DEBUG - Requesting: http://10.0.0.242:8082/bgp-as-a-service/a3fe7488-5d33-4554-b31b-adb131919874
2025-10-30 04:04:48,452 - DEBUG - Response Code: 404
2025-10-30 04:04:48,452 - DEBUG - BGPaaS: a3fe7488-5d33-4554-b31b-adb131919874 deleted from api server
2025-10-30 04:04:48,452 - INFO - BGPaaS(a3fe7488-5d33-4554-b31b-adb131919874): verify_on_cleanup passed
2025-10-30 04:04:48,452 - INFO - Deleting Control node zone ctest-test-zone-00512352(f20986ff-b77c-405d-8f16-c7eb6ecb04d3)
2025-10-30 04:04:48,610 - INFO - Deleting Control node zone ctest-test-zone-56560211(51de316b-626c-4572-97d5-df755007774a)
2025-10-30 04:04:48,761 - INFO - Deleting Control node zone ctest-test-zone-71256264(02da60b4-8a12-434e-833f-19578d4ff966)
2025-10-30 04:04:48,904 - INFO - Deleting VM ctest-TestBGPaasZone-10275657-75005059
2025-10-30 04:04:48,987 - INFO - Deleting VM ctest-TestBGPaasZone-10275657-04698185
2025-10-30 04:04:49,053 - INFO - Deleting VM ctest-TestBGPaasZone-10275657-62302743
2025-10-30 04:04:49,148 - INFO - Deleting VN ctest-cnz_vn-18310608
2025-10-30 04:04:49,195 - DEBUG - VN 1a5ab3e4-eee2-4b92-a064-1f3c65b8a8c3 still in use: Unable to complete operation on network 1a5ab3e4-eee2-4b92-a064-1f3c65b8a8c3. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-fa5d91ff-5606-4bd7-8a50-aa5ae6c12339']
2025-10-30 04:04:49,195 - WARNING - Deleting VN ctest-cnz_vn-18310608 failed..Will retry
2025-10-30 04:04:51,429 - DEBUG - Response for deleting network ()
2025-10-30 04:04:52,706 - DEBUG - Skipping xmpp flap check
2025-10-30 04:04:52,706 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:22]
2025-10-30 04:04:52,706 - INFO - --------------------------------------------------------------------------------
2025-10-30 04:04:53,466 - INFO - Deleted project: ctest-TestBGPaasZone-10275657, ID : 6500ea42-243e-409b-873f-c62e609afe53