is in ACTIVE state now
2025-08-11 07:46:32,446 - INFO - VM name : ctest-TestBGPaasZone-05588947-38311253
2025-08-11 07:46:32,515 - DEBUG - VM ctest-TestBGPaasZone-05588947-38311253 ID is 0e2e3778-49d9-44ce-9b43-9410510b62c0
2025-08-11 07:46:32,515 - DEBUG - VM ctest-TestBGPaasZone-05588947-38311253 launched on Node an-jenkins-deploy-platform-ansible-os-3798-1
2025-08-11 07:46:32,588 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/0e2e3778-49d9-44ce-9b43-9410510b62c0
2025-08-11 07:46:32,597 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/68623e9c-17fb-4926-aac5-954766248798
2025-08-11 07:46:33,789 - 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.28 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.387 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 = 0.387/1.834/3.281/1.447 ms')
2025-08-11 07:46:33,789 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-05588947-38311253 passed
2025-08-11 07:46:33,897 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:46:33,897 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-05588947-38311253, IP 84.50.166.69, Port 22
2025-08-11 07:46:34,057 - DEBUG - VM ctest-TestBGPaasZone-05588947-38311253 is ready for SSH connections
2025-08-11 07:46:34,184 - INFO - Created control node zone ctest-test-zone-12622499(a7cb41c4-4ada-44cd-9cd4-d706d586fdf0)
2025-08-11 07:46:34,516 - INFO - Created control node zone ctest-test-zone-14259168(e41e0f11-364d-41dc-92ae-5aae32ab07fa)
2025-08-11 07:46:34,857 - INFO - Created control node zone ctest-test-zone-86264357(b969e15b-17a4-4ffb-896c-43eee3dc074b)
2025-08-11 07:46:35,128 - INFO - Creating BGPaaS ctest-TestBGPaasZone-05588947-21210633
2025-08-11 07:46:35,196 - INFO - BGPaaS: ctest-TestBGPaasZone-05588947-21210633(c9c5026a-7363-44c9-b780-dda700bd1e15)
2025-08-11 07:46:35,197 - DEBUG - Requesting: http://10.0.0.254:8082/bgp-as-a-service/c9c5026a-7363-44c9-b780-dda700bd1e15
2025-08-11 07:46:35,206 - INFO - verify_in_api_server passed for BGPaaS obj c9c5026a-7363-44c9-b780-dda700bd1e15
2025-08-11 07:46:35,206 - INFO - BGPaaS(c9c5026a-7363-44c9-b780-dda700bd1e15): verify_on_setup passed
2025-08-11 07:46:35,206 - INFO - We will configure BGP on the VM
2025-08-11 07:46:35,605 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:46:35,605 - INFO - Configuring BGP on ctest-TestBGPaasZone-05588947-60097410
2025-08-11 07:46:35,605 - 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 84.50.166.67;
protocol bgp bgp_1{
local as 65000;
neighbor 84.50.166.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 84.50.166.67;
}
protocol bfd {
neighbor 84.50.166.65 local 84.50.166.67 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.49, gateway password: c0ntrail123
2025-08-11 07:46:35,605 - 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 84.50.166.67;
protocol bgp bgp_1{
local as 65000;
neighbor 84.50.166.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 84.50.166.67;
}
protocol bfd {
neighbor 84.50.166.65 local 84.50.166.67 multihop on;
}
EOS
2025-08-11 07:46:46,745 - DEBUG - None
2025-08-11 07:46:46,745 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 84.50.166.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 84.50.166.67;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.49, gateway password: c0ntrail123
2025-08-11 07:46:46,745 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 84.50.166.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 84.50.166.67;
}
EOS
2025-08-11 07:46:47,652 - DEBUG - None
2025-08-11 07:46:47,652 - 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-08-11 07:46:47,652 - DEBUG - service bird restart
2025-08-11 07:46:49,329 - DEBUG - bird stop/waiting
bird start/running, process 1483
2025-08-11 07:46:49,330 - INFO - Attaching VMI 69bc6148-f7ea-42a4-becd-15d7991311aa to BGPaaS c9c5026a-7363-44c9-b780-dda700bd1e15
2025-08-11 07:46:49,408 - INFO - Attaching the VMI 62076e00-41e1-4e14-85e3-622e7bde1a8a to the BGPaaS c9c5026a-7363-44c9-b780-dda700bd1e15 object
2025-08-11 07:46:49,408 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 84.50.166.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.49, gateway password: c0ntrail123
2025-08-11 07:46:49,408 - DEBUG - sudo ip addr add 84.50.166.84 dev eth0
2025-08-11 07:46:50,004 - DEBUG - None
2025-08-11 07:46:50,064 - INFO - Creating BGPaaS ctest-TestBGPaasZone-05588947-81559131
2025-08-11 07:46:50,145 - INFO - BGPaaS: ctest-TestBGPaasZone-05588947-81559131(aa5fcd54-c4f7-4ac2-9ddd-0510f7149217)
2025-08-11 07:46:50,145 - DEBUG - Requesting: http://10.0.0.254:8082/bgp-as-a-service/aa5fcd54-c4f7-4ac2-9ddd-0510f7149217
2025-08-11 07:46:50,156 - INFO - verify_in_api_server passed for BGPaaS obj aa5fcd54-c4f7-4ac2-9ddd-0510f7149217
2025-08-11 07:46:50,156 - INFO - BGPaaS(aa5fcd54-c4f7-4ac2-9ddd-0510f7149217): verify_on_setup passed
2025-08-11 07:46:50,156 - INFO - We will configure BGP on the VM
2025-08-11 07:46:50,561 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:46:50,561 - INFO - Configuring BGP on ctest-TestBGPaasZone-05588947-14059180
2025-08-11 07:46:50,561 - 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 84.50.166.68;
protocol bgp bgp_1{
local as 65000;
neighbor 84.50.166.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 84.50.166.68;
}
protocol bfd {
neighbor 84.50.166.65 local 84.50.166.68 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-11 07:46:50,561 - 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 84.50.166.68;
protocol bgp bgp_1{
local as 65000;
neighbor 84.50.166.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 84.50.166.68;
}
protocol bfd {
neighbor 84.50.166.65 local 84.50.166.68 multihop on;
}
EOS
2025-08-11 07:46:55,285 - DEBUG - None
2025-08-11 07:46:55,286 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 84.50.166.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 84.50.166.68;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-11 07:46:55,286 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 84.50.166.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 84.50.166.68;
}
EOS
2025-08-11 07:46:55,735 - DEBUG - None
2025-08-11 07:46:55,735 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-11 07:46:55,735 - DEBUG - service bird restart
2025-08-11 07:46:57,213 - DEBUG - bird stop/waiting
bird start/running, process 1483
2025-08-11 07:46:57,213 - INFO - Attaching VMI 19c2ce64-d743-4547-aeea-8da4eec3b8de to BGPaaS aa5fcd54-c4f7-4ac2-9ddd-0510f7149217
2025-08-11 07:46:57,288 - INFO - Attaching the VMI e275423d-0f9d-4374-a218-6b740c20855f to the BGPaaS aa5fcd54-c4f7-4ac2-9ddd-0510f7149217 object
2025-08-11 07:46:57,289 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 84.50.166.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-11 07:46:57,289 - DEBUG - sudo ip addr add 84.50.166.84 dev eth0
2025-08-11 07:46:57,869 - DEBUG - None
2025-08-11 07:46:57,869 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:46:57,882 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-08-11 07:46:57,883 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:46:57,883 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-11 07:46:57,896 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-08-11 07:46:57,901 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:46:57,913 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.17']
2025-08-11 07:47:03,929 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.17']
2025-08-11 07:47:09,943 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-08-11 07:47:09,944 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:47:09,944 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-11 07:47:09,957 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-08-11 07:47:09,963 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 84.50.166.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.49, gateway password: c0ntrail123
2025-08-11 07:47:09,963 - DEBUG - ping -s 56 -c 3 -W 1 84.50.166.84
2025-08-11 07:47:16,060 - DEBUG - PING 84.50.166.84 (84.50.166.84) 56(84) bytes of data.
64 bytes from 84.50.166.84: icmp_seq=1 ttl=64 time=5.98 ms
64 bytes from 84.50.166.84: icmp_seq=2 ttl=64 time=0.798 ms
64 bytes from 84.50.166.84: icmp_seq=3 ttl=64 time=0.693 ms
--- 84.50.166.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 0.693/2.491/5.982/2.468 ms
2025-08-11 07:47:16,060 - INFO - Ping to IP 84.50.166.84 from VM ctest-TestBGPaasZone-05588947-38311253 passed
2025-08-11 07:47:16,060 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:47:16,074 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-08-11 07:47:16,074 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:47:16,074 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-11 07:47:16,088 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-08-11 07:47:16,094 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:47:16,106 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-08-11 07:47:16,106 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 07:47:16,106 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-11 07:47:16,121 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-08-11 07:47:16,127 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 84.50.166.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.49, gateway password: c0ntrail123
2025-08-11 07:47:16,127 - DEBUG - ping -s 56 -c 3 -W 1 84.50.166.84
2025-08-11 07:47:18,566 - DEBUG - PING 84.50.166.84 (84.50.166.84) 56(84) bytes of data.
64 bytes from 84.50.166.84: icmp_seq=1 ttl=64 time=17.7 ms
64 bytes from 84.50.166.84: icmp_seq=2 ttl=64 time=0.754 ms
64 bytes from 84.50.166.84: icmp_seq=3 ttl=64 time=0.745 ms
--- 84.50.166.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 0.745/6.411/17.736/8.008 ms
2025-08-11 07:47:18,566 - INFO - Ping to IP 84.50.166.84 from VM ctest-TestBGPaasZone-05588947-38311253 passed
2025-08-11 07:47:18,566 - INFO - Detaching VMI 19c2ce64-d743-4547-aeea-8da4eec3b8de from BGPaaS aa5fcd54-c4f7-4ac2-9ddd-0510f7149217
2025-08-11 07:47:18,641 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-05588947-81559131(aa5fcd54-c4f7-4ac2-9ddd-0510f7149217)
2025-08-11 07:47:18,739 - DEBUG - Requesting: http://10.0.0.254:8082/bgp-as-a-service/aa5fcd54-c4f7-4ac2-9ddd-0510f7149217
2025-08-11 07:47:18,746 - DEBUG - Response Code: 404
2025-08-11 07:47:18,746 - DEBUG - BGPaaS: aa5fcd54-c4f7-4ac2-9ddd-0510f7149217 deleted from api server
2025-08-11 07:47:18,746 - INFO - BGPaaS(aa5fcd54-c4f7-4ac2-9ddd-0510f7149217): verify_on_cleanup passed
2025-08-11 07:47:18,746 - INFO - Detaching VMI 69bc6148-f7ea-42a4-becd-15d7991311aa from BGPaaS c9c5026a-7363-44c9-b780-dda700bd1e15
2025-08-11 07:47:18,825 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-05588947-21210633(c9c5026a-7363-44c9-b780-dda700bd1e15)
2025-08-11 07:47:18,881 - DEBUG - Requesting: http://10.0.0.254:8082/bgp-as-a-service/c9c5026a-7363-44c9-b780-dda700bd1e15
2025-08-11 07:47:18,891 - DEBUG - Response Code: 404
2025-08-11 07:47:18,891 - DEBUG - BGPaaS: c9c5026a-7363-44c9-b780-dda700bd1e15 deleted from api server
2025-08-11 07:47:18,891 - INFO - BGPaaS(c9c5026a-7363-44c9-b780-dda700bd1e15): verify_on_cleanup passed
2025-08-11 07:47:18,891 - INFO - Deleting Control node zone ctest-test-zone-86264357(b969e15b-17a4-4ffb-896c-43eee3dc074b)
2025-08-11 07:47:19,028 - INFO - Deleting Control node zone ctest-test-zone-14259168(e41e0f11-364d-41dc-92ae-5aae32ab07fa)
2025-08-11 07:47:19,156 - INFO - Deleting Control node zone ctest-test-zone-12622499(a7cb41c4-4ada-44cd-9cd4-d706d586fdf0)
2025-08-11 07:47:19,290 - INFO - Deleting VM ctest-TestBGPaasZone-05588947-38311253
2025-08-11 07:47:19,352 - INFO - Deleting VM ctest-TestBGPaasZone-05588947-14059180
2025-08-11 07:47:19,408 - INFO - Deleting VM ctest-TestBGPaasZone-05588947-60097410
2025-08-11 07:47:19,467 - INFO - Deleting VN ctest-cnz_vn-82970155
2025-08-11 07:47:19,507 - DEBUG - VN 0e4d0558-462e-496f-8c05-43639ae9ea7e still in use: Unable to complete operation on network 0e4d0558-462e-496f-8c05-43639ae9ea7e. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-8898215c-1c78-406d-a05c-0128a426a4b0']
2025-08-11 07:47:19,507 - WARNING - Deleting VN ctest-cnz_vn-82970155 failed..Will retry
2025-08-11 07:47:21,696 - DEBUG - Response for deleting network ()
2025-08-11 07:47:22,944 - DEBUG - Skipping xmpp flap check
2025-08-11 07:47:22,945 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:02:47]
2025-08-11 07:47:22,945 - INFO - --------------------------------------------------------------------------------
2025-08-11 07:47:23,671 - INFO - Deleted project: ctest-TestBGPaasZone-05588947, ID : 6b247a5f-11b5-4153-80be-5eaa234b9417