is in ACTIVE state now
2025-10-30 12:05:11,238 - INFO - VM name : ctest-TestBGPaasZone-54863644-85390272
2025-10-30 12:05:11,339 - DEBUG - VM ctest-TestBGPaasZone-54863644-85390272 ID is 27a40c6f-9fff-444f-9e50-6ab98c6248b9
2025-10-30 12:05:11,339 - DEBUG - VM ctest-TestBGPaasZone-54863644-85390272 launched on Node an-jenkins-deploy-platform-ansible-os-4541-2
2025-10-30 12:05:11,433 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/27a40c6f-9fff-444f-9e50-6ab98c6248b9
2025-10-30 12:05:11,448 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/84e132e0-09c2-4238-a33f-73af60c6da1a
2025-10-30 12:05:12,626 - 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.76 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.425 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.425/2.093/3.762/1.668 ms')
2025-10-30 12:05:12,627 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-54863644-85390272 passed
2025-10-30 12:05:12,698 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 12:05:12,698 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-54863644-85390272, IP 178.197.6.197, Port 22
2025-10-30 12:05:12,864 - DEBUG - VM ctest-TestBGPaasZone-54863644-85390272 is ready for SSH connections
2025-10-30 12:05:13,009 - INFO - Created control node zone ctest-test-zone-28144457(cc538ee2-692a-4f09-aed6-647396e708f1)
2025-10-30 12:05:13,377 - INFO - Created control node zone ctest-test-zone-61904647(8c6af5a0-96b8-41dc-b180-d2b180a1be1c)
2025-10-30 12:05:13,737 - INFO - Created control node zone ctest-test-zone-56504395(df2d1c99-0cd8-4d4a-abd5-4c236aa6e93e)
2025-10-30 12:05:14,029 - INFO - Creating BGPaaS ctest-TestBGPaasZone-54863644-38038912
2025-10-30 12:05:14,113 - INFO - BGPaaS: ctest-TestBGPaasZone-54863644-38038912(0e15a021-1af1-47d8-80fd-007e18296d26)
2025-10-30 12:05:14,113 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/0e15a021-1af1-47d8-80fd-007e18296d26
2025-10-30 12:05:14,122 - INFO - verify_in_api_server passed for BGPaaS obj 0e15a021-1af1-47d8-80fd-007e18296d26
2025-10-30 12:05:14,122 - INFO - BGPaaS(0e15a021-1af1-47d8-80fd-007e18296d26): verify_on_setup passed
2025-10-30 12:05:14,122 - INFO - We will configure BGP on the VM
2025-10-30 12:05:14,581 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 12:05:14,581 - INFO - Configuring BGP on ctest-TestBGPaasZone-54863644-88168282
2025-10-30 12:05:14,581 - 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 178.197.6.195;
protocol bgp bgp_1{
local as 65000;
neighbor 178.197.6.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 178.197.6.195;
}
protocol bfd {
neighbor 178.197.6.193 local 178.197.6.195 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-10-30 12:05:14,581 - 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 178.197.6.195;
protocol bgp bgp_1{
local as 65000;
neighbor 178.197.6.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 178.197.6.195;
}
protocol bfd {
neighbor 178.197.6.193 local 178.197.6.195 multihop on;
}
EOS
2025-10-30 12:05:28,840 - DEBUG - None
2025-10-30 12:05:28,841 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 178.197.6.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 178.197.6.195;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-10-30 12:05:28,841 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 178.197.6.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 178.197.6.195;
}
EOS
2025-10-30 12:05:30,022 - DEBUG - None
2025-10-30 12:05:30,023 - 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-10-30 12:05:30,023 - DEBUG - service bird restart
2025-10-30 12:05:32,587 - DEBUG - bird stop/waiting
bird start/running, process 1438
2025-10-30 12:05:32,588 - INFO - Attaching VMI 3da4c323-1db3-46b2-b431-038c9c9ebfef to BGPaaS 0e15a021-1af1-47d8-80fd-007e18296d26
2025-10-30 12:05:32,673 - INFO - Attaching the VMI e2fbdd05-3460-4e06-8a63-d1da3259944f to the BGPaaS 0e15a021-1af1-47d8-80fd-007e18296d26 object
2025-10-30 12:05:32,673 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 178.197.6.212 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-10-30 12:05:32,673 - DEBUG - sudo ip addr add 178.197.6.212 dev eth0
2025-10-30 12:05:33,419 - DEBUG - None
2025-10-30 12:05:33,489 - INFO - Creating BGPaaS ctest-TestBGPaasZone-54863644-07505173
2025-10-30 12:05:33,577 - INFO - BGPaaS: ctest-TestBGPaasZone-54863644-07505173(3e7589a7-a5fd-4f5b-846a-2e2339f5b067)
2025-10-30 12:05:33,577 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/3e7589a7-a5fd-4f5b-846a-2e2339f5b067
2025-10-30 12:05:33,588 - INFO - verify_in_api_server passed for BGPaaS obj 3e7589a7-a5fd-4f5b-846a-2e2339f5b067
2025-10-30 12:05:33,588 - INFO - BGPaaS(3e7589a7-a5fd-4f5b-846a-2e2339f5b067): verify_on_setup passed
2025-10-30 12:05:33,588 - INFO - We will configure BGP on the VM
2025-10-30 12:05:34,021 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 12:05:34,021 - INFO - Configuring BGP on ctest-TestBGPaasZone-54863644-35176255
2025-10-30 12:05:34,021 - 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 178.197.6.196;
protocol bgp bgp_1{
local as 65000;
neighbor 178.197.6.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 178.197.6.196;
}
protocol bfd {
neighbor 178.197.6.193 local 178.197.6.196 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-10-30 12:05:34,021 - 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 178.197.6.196;
protocol bgp bgp_1{
local as 65000;
neighbor 178.197.6.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 178.197.6.196;
}
protocol bfd {
neighbor 178.197.6.193 local 178.197.6.196 multihop on;
}
EOS
2025-10-30 12:05:41,033 - DEBUG - None
2025-10-30 12:05:41,034 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 178.197.6.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 178.197.6.196;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-10-30 12:05:41,034 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 178.197.6.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 178.197.6.196;
}
EOS
2025-10-30 12:05:41,621 - DEBUG - None
2025-10-30 12:05:41,621 - 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-10-30 12:05:41,621 - DEBUG - service bird restart
2025-10-30 12:05:43,709 - DEBUG - bird stop/waiting
bird start/running, process 1434
2025-10-30 12:05:43,709 - INFO - Attaching VMI 14ac1afa-ff01-4247-b856-63a121c95408 to BGPaaS 3e7589a7-a5fd-4f5b-846a-2e2339f5b067
2025-10-30 12:05:43,784 - INFO - Attaching the VMI 8a121426-6c0a-4534-9249-055acca7d744 to the BGPaaS 3e7589a7-a5fd-4f5b-846a-2e2339f5b067 object
2025-10-30 12:05:43,785 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 178.197.6.212 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-10-30 12:05:43,785 - DEBUG - sudo ip addr add 178.197.6.212 dev eth0
2025-10-30 12:05:44,602 - DEBUG - None
2025-10-30 12:05:44,602 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 12:05:44,617 - INFO - BGPaaS session seen in control-node 10.20.0.128
2025-10-30 12:05:44,617 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 12:05:44,617 - DEBUG - Requesting: http://10.20.0.131:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-30 12:05:44,636 - INFO - BGPaaS session seen in control-node 10.20.0.131
2025-10-30 12:05:44,642 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 12:05:44,658 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.128']
2025-10-30 12:05:50,677 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.128']
2025-10-30 12:05:56,694 - INFO - BGPaaS session seen in control-node 10.20.0.128
2025-10-30 12:05:56,696 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 12:05:56,696 - DEBUG - Requesting: http://10.20.0.131:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-30 12:05:56,712 - INFO - BGPaaS session seen in control-node 10.20.0.131
2025-10-30 12:05:56,720 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 178.197.6.212, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-10-30 12:05:56,720 - DEBUG - ping -s 56 -c 3 -W 1 178.197.6.212
2025-10-30 12:06:03,026 - DEBUG - PING 178.197.6.212 (178.197.6.212) 56(84) bytes of data.
64 bytes from 178.197.6.212: icmp_seq=1 ttl=64 time=8.55 ms
64 bytes from 178.197.6.212: icmp_seq=2 ttl=64 time=1.27 ms
64 bytes from 178.197.6.212: icmp_seq=3 ttl=64 time=1.55 ms
--- 178.197.6.212 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.276/3.795/8.558/3.369 ms
2025-10-30 12:06:03,026 - INFO - Ping to IP 178.197.6.212 from VM ctest-TestBGPaasZone-54863644-85390272 passed
2025-10-30 12:06:03,026 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 12:06:03,045 - INFO - BGPaaS session seen in control-node 10.20.0.128
2025-10-30 12:06:03,045 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 12:06:03,046 - DEBUG - Requesting: http://10.20.0.131:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-30 12:06:03,062 - INFO - BGPaaS session seen in control-node 10.20.0.131
2025-10-30 12:06:03,069 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 12:06:03,083 - INFO - BGPaaS session seen in control-node 10.20.0.128
2025-10-30 12:06:03,084 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-30 12:06:03,084 - DEBUG - Requesting: http://10.20.0.131:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-30 12:06:03,099 - INFO - BGPaaS session seen in control-node 10.20.0.131
2025-10-30 12:06:03,107 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 178.197.6.212, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-10-30 12:06:03,107 - DEBUG - ping -s 56 -c 3 -W 1 178.197.6.212
2025-10-30 12:06:05,614 - DEBUG - PING 178.197.6.212 (178.197.6.212) 56(84) bytes of data.
64 bytes from 178.197.6.212: icmp_seq=1 ttl=64 time=1.47 ms
64 bytes from 178.197.6.212: icmp_seq=2 ttl=64 time=1.10 ms
64 bytes from 178.197.6.212: icmp_seq=3 ttl=64 time=1.13 ms
--- 178.197.6.212 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 1.103/1.237/1.476/0.171 ms
2025-10-30 12:06:05,614 - INFO - Ping to IP 178.197.6.212 from VM ctest-TestBGPaasZone-54863644-85390272 passed
2025-10-30 12:06:05,615 - INFO - Detaching VMI 14ac1afa-ff01-4247-b856-63a121c95408 from BGPaaS 3e7589a7-a5fd-4f5b-846a-2e2339f5b067
2025-10-30 12:06:05,696 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-54863644-07505173(3e7589a7-a5fd-4f5b-846a-2e2339f5b067)
2025-10-30 12:06:05,779 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/3e7589a7-a5fd-4f5b-846a-2e2339f5b067
2025-10-30 12:06:05,795 - DEBUG - Response Code: 404
2025-10-30 12:06:05,796 - DEBUG - BGPaaS: 3e7589a7-a5fd-4f5b-846a-2e2339f5b067 deleted from api server
2025-10-30 12:06:05,796 - INFO - BGPaaS(3e7589a7-a5fd-4f5b-846a-2e2339f5b067): verify_on_cleanup passed
2025-10-30 12:06:05,796 - INFO - Detaching VMI 3da4c323-1db3-46b2-b431-038c9c9ebfef from BGPaaS 0e15a021-1af1-47d8-80fd-007e18296d26
2025-10-30 12:06:05,913 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-54863644-38038912(0e15a021-1af1-47d8-80fd-007e18296d26)
2025-10-30 12:06:06,007 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/0e15a021-1af1-47d8-80fd-007e18296d26
2025-10-30 12:06:06,020 - DEBUG - Response Code: 404
2025-10-30 12:06:06,020 - DEBUG - BGPaaS: 0e15a021-1af1-47d8-80fd-007e18296d26 deleted from api server
2025-10-30 12:06:06,020 - INFO - BGPaaS(0e15a021-1af1-47d8-80fd-007e18296d26): verify_on_cleanup passed
2025-10-30 12:06:06,020 - INFO - Deleting Control node zone ctest-test-zone-56504395(df2d1c99-0cd8-4d4a-abd5-4c236aa6e93e)
2025-10-30 12:06:06,180 - INFO - Deleting Control node zone ctest-test-zone-61904647(8c6af5a0-96b8-41dc-b180-d2b180a1be1c)
2025-10-30 12:06:06,333 - INFO - Deleting Control node zone ctest-test-zone-28144457(cc538ee2-692a-4f09-aed6-647396e708f1)
2025-10-30 12:06:06,489 - INFO - Deleting VM ctest-TestBGPaasZone-54863644-85390272
2025-10-30 12:06:06,572 - INFO - Deleting VM ctest-TestBGPaasZone-54863644-35176255
2025-10-30 12:06:06,637 - INFO - Deleting VM ctest-TestBGPaasZone-54863644-88168282
2025-10-30 12:06:06,711 - INFO - Deleting VN ctest-cnz_vn-32818050
2025-10-30 12:06:06,766 - DEBUG - VN ce5259fd-e9ae-40b1-9c6e-78ecf29a19c0 still in use: Unable to complete operation on network ce5259fd-e9ae-40b1-9c6e-78ecf29a19c0. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-9dc43a1c-196b-47f1-b538-3b1bace42cf7']
2025-10-30 12:06:06,766 - WARNING - Deleting VN ctest-cnz_vn-32818050 failed..Will retry
2025-10-30 12:06:09,041 - DEBUG - Response for deleting network ()
2025-10-30 12:06:10,326 - DEBUG - Skipping xmpp flap check
2025-10-30 12:06:10,326 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:26]
2025-10-30 12:06:10,326 - INFO - --------------------------------------------------------------------------------
2025-10-30 12:06:11,162 - INFO - Deleted project: ctest-TestBGPaasZone-54863644, ID : 0e0a6216-cdd0-49f5-841c-2c6ac76ab014