is in ACTIVE state now
2025-08-08 09:18:35,685 - INFO - VM name : ctest-TestBGPaasZone-68879374-72263054
2025-08-08 09:18:35,787 - DEBUG - VM ctest-TestBGPaasZone-68879374-72263054 ID is 280965df-1a97-416c-b453-88bf74bc89e1
2025-08-08 09:18:35,788 - DEBUG - VM ctest-TestBGPaasZone-68879374-72263054 launched on Node an-jenkins-deploy-platform-ansible-os-3781-1
2025-08-08 09:18:35,885 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/280965df-1a97-416c-b453-88bf74bc89e1
2025-08-08 09:18:35,898 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/31062f38-4715-4161-84b8-8642234d6d9e
2025-08-08 09:18:37,121 - 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=6.09 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=3.24 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.242/4.665/6.089/1.423 ms')
2025-08-08 09:18:37,121 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-68879374-72263054 passed
2025-08-08 09:18:37,234 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-08 09:18:37,234 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-68879374-72263054, IP 197.164.169.197, Port 22
2025-08-08 09:18:37,401 - DEBUG - VM ctest-TestBGPaasZone-68879374-72263054 is ready for SSH connections
2025-08-08 09:18:37,544 - INFO - Created control node zone ctest-test-zone-07365630(a709cbf0-f2ae-47eb-9c77-da38c0278c4f)
2025-08-08 09:18:37,920 - INFO - Created control node zone ctest-test-zone-78644577(2c49db88-78f5-4d6e-8a8c-a4af8fb58d57)
2025-08-08 09:18:38,300 - INFO - Created control node zone ctest-test-zone-98252108(5dfe6a27-8078-459d-a345-4fa287f87d49)
2025-08-08 09:18:38,616 - INFO - Creating BGPaaS ctest-TestBGPaasZone-68879374-95589888
2025-08-08 09:18:38,704 - INFO - BGPaaS: ctest-TestBGPaasZone-68879374-95589888(62ecaef5-879d-4004-a4d1-0b10e37bce93)
2025-08-08 09:18:38,704 - DEBUG - Requesting: http://10.0.0.50:8082/bgp-as-a-service/62ecaef5-879d-4004-a4d1-0b10e37bce93
2025-08-08 09:18:38,719 - INFO - verify_in_api_server passed for BGPaaS obj 62ecaef5-879d-4004-a4d1-0b10e37bce93
2025-08-08 09:18:38,719 - INFO - BGPaaS(62ecaef5-879d-4004-a4d1-0b10e37bce93): verify_on_setup passed
2025-08-08 09:18:38,719 - INFO - We will configure BGP on the VM
2025-08-08 09:18:39,180 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-08 09:18:39,180 - INFO - Configuring BGP on ctest-TestBGPaasZone-68879374-13202247
2025-08-08 09:18:39,180 - 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 197.164.169.195;
protocol bgp bgp_1{
local as 65000;
neighbor 197.164.169.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 197.164.169.195;
}
protocol bfd {
neighbor 197.164.169.193 local 197.164.169.195 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-08 09:18:39,180 - 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 197.164.169.195;
protocol bgp bgp_1{
local as 65000;
neighbor 197.164.169.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 197.164.169.195;
}
protocol bfd {
neighbor 197.164.169.193 local 197.164.169.195 multihop on;
}
EOS
2025-08-08 09:18:44,831 - DEBUG - None
2025-08-08 09:18:44,831 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 197.164.169.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 197.164.169.195;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-08 09:18:44,832 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 197.164.169.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 197.164.169.195;
}
EOS
2025-08-08 09:18:45,333 - DEBUG - None
2025-08-08 09:18:45,333 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-08 09:18:45,334 - DEBUG - service bird restart
2025-08-08 09:18:46,985 - DEBUG - bird stop/waiting
bird start/running, process 1433
2025-08-08 09:18:46,985 - INFO - Attaching VMI 4e0be4ba-0aee-44bb-8f97-16c8743b0f37 to BGPaaS 62ecaef5-879d-4004-a4d1-0b10e37bce93
2025-08-08 09:18:47,088 - INFO - Attaching the VMI 89b931f5-dd34-417f-a426-c3a8c7f66c82 to the BGPaaS 62ecaef5-879d-4004-a4d1-0b10e37bce93 object
2025-08-08 09:18:47,089 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 197.164.169.212 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-08 09:18:47,089 - DEBUG - sudo ip addr add 197.164.169.212 dev eth0
2025-08-08 09:18:47,781 - DEBUG - None
2025-08-08 09:18:47,856 - INFO - Creating BGPaaS ctest-TestBGPaasZone-68879374-06915952
2025-08-08 09:18:47,936 - INFO - BGPaaS: ctest-TestBGPaasZone-68879374-06915952(02cfa8f7-288a-4609-b214-948575574cec)
2025-08-08 09:18:47,936 - DEBUG - Requesting: http://10.0.0.50:8082/bgp-as-a-service/02cfa8f7-288a-4609-b214-948575574cec
2025-08-08 09:18:47,950 - INFO - verify_in_api_server passed for BGPaaS obj 02cfa8f7-288a-4609-b214-948575574cec
2025-08-08 09:18:47,950 - INFO - BGPaaS(02cfa8f7-288a-4609-b214-948575574cec): verify_on_setup passed
2025-08-08 09:18:47,950 - INFO - We will configure BGP on the VM
2025-08-08 09:18:48,408 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-08 09:18:48,408 - INFO - Configuring BGP on ctest-TestBGPaasZone-68879374-91840508
2025-08-08 09:18:48,408 - 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 197.164.169.196;
protocol bgp bgp_1{
local as 65000;
neighbor 197.164.169.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 197.164.169.196;
}
protocol bfd {
neighbor 197.164.169.193 local 197.164.169.196 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-08-08 09:18:48,409 - 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 197.164.169.196;
protocol bgp bgp_1{
local as 65000;
neighbor 197.164.169.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 197.164.169.196;
}
protocol bfd {
neighbor 197.164.169.193 local 197.164.169.196 multihop on;
}
EOS
2025-08-08 09:18:55,636 - DEBUG - None
2025-08-08 09:18:55,636 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 197.164.169.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 197.164.169.196;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-08-08 09:18:55,636 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 197.164.169.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 197.164.169.196;
}
EOS
2025-08-08 09:18:56,236 - DEBUG - None
2025-08-08 09:18:56,236 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-08-08 09:18:56,236 - DEBUG - service bird restart
2025-08-08 09:18:58,270 - DEBUG - bird stop/waiting
bird start/running, process 1445
2025-08-08 09:18:58,270 - INFO - Attaching VMI 72016930-f3cf-446b-bdab-a9e5dbb282d3 to BGPaaS 02cfa8f7-288a-4609-b214-948575574cec
2025-08-08 09:18:58,348 - INFO - Attaching the VMI 5f5c518f-434c-4996-a0f1-70ba928efb4d to the BGPaaS 02cfa8f7-288a-4609-b214-948575574cec object
2025-08-08 09:18:58,348 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 197.164.169.212 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-08-08 09:18:58,349 - DEBUG - sudo ip addr add 197.164.169.212 dev eth0
2025-08-08 09:18:59,143 - DEBUG - None
2025-08-08 09:18:59,143 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-08 09:18:59,161 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-08-08 09:18:59,161 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-08 09:18:59,181 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-08-08 09:18:59,190 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-08 09:18:59,211 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.254']
2025-08-08 09:19:05,233 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-08-08 09:19:05,233 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-08 09:19:05,252 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-08-08 09:19:05,261 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 197.164.169.212, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-08 09:19:05,261 - DEBUG - ping -s 56 -c 3 -W 1 197.164.169.212
2025-08-08 09:19:11,745 - DEBUG - PING 197.164.169.212 (197.164.169.212) 56(84) bytes of data.
64 bytes from 197.164.169.212: icmp_seq=1 ttl=64 time=9.00 ms
64 bytes from 197.164.169.212: icmp_seq=2 ttl=64 time=1.49 ms
64 bytes from 197.164.169.212: icmp_seq=3 ttl=64 time=0.936 ms
--- 197.164.169.212 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 0.936/3.811/9.002/3.677 ms
2025-08-08 09:19:11,745 - INFO - Ping to IP 197.164.169.212 from VM ctest-TestBGPaasZone-68879374-72263054 passed
2025-08-08 09:19:11,746 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-08 09:19:11,770 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-08-08 09:19:11,771 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-08 09:19:11,792 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-08-08 09:19:11,800 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-08 09:19:11,819 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-08-08 09:19:11,820 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-08 09:19:11,839 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-08-08 09:19:11,846 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 197.164.169.212, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-08 09:19:11,847 - DEBUG - ping -s 56 -c 3 -W 1 197.164.169.212
2025-08-08 09:19:14,408 - DEBUG - PING 197.164.169.212 (197.164.169.212) 56(84) bytes of data.
64 bytes from 197.164.169.212: icmp_seq=1 ttl=64 time=5.84 ms
64 bytes from 197.164.169.212: icmp_seq=2 ttl=64 time=1.42 ms
64 bytes from 197.164.169.212: icmp_seq=3 ttl=64 time=1.62 ms
--- 197.164.169.212 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.425/2.966/5.844/2.036 ms
2025-08-08 09:19:14,408 - INFO - Ping to IP 197.164.169.212 from VM ctest-TestBGPaasZone-68879374-72263054 passed
2025-08-08 09:19:14,408 - INFO - Detaching VMI 72016930-f3cf-446b-bdab-a9e5dbb282d3 from BGPaaS 02cfa8f7-288a-4609-b214-948575574cec
2025-08-08 09:19:14,488 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-68879374-06915952(02cfa8f7-288a-4609-b214-948575574cec)
2025-08-08 09:19:14,556 - DEBUG - Requesting: http://10.0.0.50:8082/bgp-as-a-service/02cfa8f7-288a-4609-b214-948575574cec
2025-08-08 09:19:14,602 - DEBUG - Response Code: 404
2025-08-08 09:19:14,602 - DEBUG - BGPaaS: 02cfa8f7-288a-4609-b214-948575574cec deleted from api server
2025-08-08 09:19:14,602 - INFO - BGPaaS(02cfa8f7-288a-4609-b214-948575574cec): verify_on_cleanup passed
2025-08-08 09:19:14,602 - INFO - Detaching VMI 4e0be4ba-0aee-44bb-8f97-16c8743b0f37 from BGPaaS 62ecaef5-879d-4004-a4d1-0b10e37bce93
2025-08-08 09:19:14,720 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-68879374-95589888(62ecaef5-879d-4004-a4d1-0b10e37bce93)
2025-08-08 09:19:14,803 - DEBUG - Requesting: http://10.0.0.50:8082/bgp-as-a-service/62ecaef5-879d-4004-a4d1-0b10e37bce93
2025-08-08 09:19:14,810 - DEBUG - Response Code: 404
2025-08-08 09:19:14,810 - DEBUG - BGPaaS: 62ecaef5-879d-4004-a4d1-0b10e37bce93 deleted from api server
2025-08-08 09:19:14,810 - INFO - BGPaaS(62ecaef5-879d-4004-a4d1-0b10e37bce93): verify_on_cleanup passed
2025-08-08 09:19:14,811 - INFO - Deleting Control node zone ctest-test-zone-98252108(5dfe6a27-8078-459d-a345-4fa287f87d49)
2025-08-08 09:19:14,977 - INFO - Deleting Control node zone ctest-test-zone-78644577(2c49db88-78f5-4d6e-8a8c-a4af8fb58d57)
2025-08-08 09:19:15,122 - INFO - Deleting Control node zone ctest-test-zone-07365630(a709cbf0-f2ae-47eb-9c77-da38c0278c4f)
2025-08-08 09:19:15,268 - INFO - Deleting VM ctest-TestBGPaasZone-68879374-72263054
2025-08-08 09:19:15,337 - INFO - Deleting VM ctest-TestBGPaasZone-68879374-91840508
2025-08-08 09:19:15,412 - INFO - Deleting VM ctest-TestBGPaasZone-68879374-13202247
2025-08-08 09:19:15,488 - INFO - Deleting VN ctest-cnz_vn-12349721
2025-08-08 09:19:15,549 - DEBUG - VN e2b69345-9522-48db-a9a2-4828049b8f63 still in use: Unable to complete operation on network e2b69345-9522-48db-a9a2-4828049b8f63. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-3ded1002-2841-41e5-9e87-baed0037c6f8']
2025-08-08 09:19:15,549 - WARNING - Deleting VN ctest-cnz_vn-12349721 failed..Will retry
2025-08-08 09:19:17,779 - DEBUG - Response for deleting network ()
2025-08-08 09:19:19,061 - DEBUG - Skipping xmpp flap check
2025-08-08 09:19:19,061 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:08]
2025-08-08 09:19:19,062 - INFO - --------------------------------------------------------------------------------
2025-08-08 09:19:19,798 - INFO - Deleted project: ctest-TestBGPaasZone-68879374, ID : 0084be4e-3f19-44d4-ad58-75e75b9753bc