is in ACTIVE state now
2025-12-08 04:00:31,161 - INFO - VM name : ctest-TestBGPaasZone-91854096-00384241
2025-12-08 04:00:31,263 - DEBUG - VM ctest-TestBGPaasZone-91854096-00384241 ID is db750562-4316-4bd9-9fc8-a8996a47eafc
2025-12-08 04:00:31,264 - DEBUG - VM ctest-TestBGPaasZone-91854096-00384241 launched on Node an-jenkins-deploy-platform-ansible-os-4854-1
2025-12-08 04:00:31,355 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/db750562-4316-4bd9-9fc8-a8996a47eafc
2025-12-08 04:00:31,367 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/6f67bbd8-8cca-4ba4-b0f6-ff0bf8b7fd1b
2025-12-08 04:00:32,543 - 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.70 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.82 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 = 1.815/4.258/6.702/2.443 ms')
2025-12-08 04:00:32,543 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-91854096-00384241 passed
2025-12-08 04:00:32,618 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-08 04:00:32,618 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-91854096-00384241, IP 208.144.147.69, Port 22
2025-12-08 04:00:32,779 - DEBUG - VM ctest-TestBGPaasZone-91854096-00384241 is ready for SSH connections
2025-12-08 04:00:32,909 - INFO - Created control node zone ctest-test-zone-33520466(af79ccb0-7b30-40ea-915c-4814d38d256d)
2025-12-08 04:00:33,249 - INFO - Created control node zone ctest-test-zone-55981873(7b36a26a-c030-48d7-86aa-cccb1b0dafa0)
2025-12-08 04:00:33,589 - INFO - Created control node zone ctest-test-zone-57534736(e370720c-cde9-4f4a-842c-2501f794bcfd)
2025-12-08 04:00:33,873 - INFO - Creating BGPaaS ctest-TestBGPaasZone-91854096-54395751
2025-12-08 04:00:33,945 - INFO - BGPaaS: ctest-TestBGPaasZone-91854096-54395751(f1a2d191-d221-4a0c-9888-0266ad09b492)
2025-12-08 04:00:33,946 - DEBUG - Requesting: http://10.0.0.23:8082/bgp-as-a-service/f1a2d191-d221-4a0c-9888-0266ad09b492
2025-12-08 04:00:33,956 - INFO - verify_in_api_server passed for BGPaaS obj f1a2d191-d221-4a0c-9888-0266ad09b492
2025-12-08 04:00:33,956 - INFO - BGPaaS(f1a2d191-d221-4a0c-9888-0266ad09b492): verify_on_setup passed
2025-12-08 04:00:33,956 - INFO - We will configure BGP on the VM
2025-12-08 04:00:34,373 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-08 04:00:34,373 - INFO - Configuring BGP on ctest-TestBGPaasZone-91854096-99201581
2025-12-08 04:00:34,373 - 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 208.144.147.67;
protocol bgp bgp_1{
local as 65000;
neighbor 208.144.147.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 208.144.147.67;
}
protocol bfd {
neighbor 208.144.147.65 local 208.144.147.67 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-12-08 04:00:34,373 - 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 208.144.147.67;
protocol bgp bgp_1{
local as 65000;
neighbor 208.144.147.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 208.144.147.67;
}
protocol bfd {
neighbor 208.144.147.65 local 208.144.147.67 multihop on;
}
EOS
2025-12-08 04:00:40,103 - DEBUG - None
2025-12-08 04:00:40,103 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 208.144.147.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 208.144.147.67;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-12-08 04:00:40,103 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 208.144.147.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 208.144.147.67;
}
EOS
2025-12-08 04:00:40,594 - DEBUG - None
2025-12-08 04:00:40,594 - 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-12-08 04:00:40,594 - DEBUG - service bird restart
2025-12-08 04:00:42,237 - DEBUG - bird stop/waiting
bird start/running, process 1438
2025-12-08 04:00:42,237 - INFO - Attaching VMI 782ff00e-650c-44c3-bcc9-84a151793b9e to BGPaaS f1a2d191-d221-4a0c-9888-0266ad09b492
2025-12-08 04:00:42,313 - INFO - Attaching the VMI 9dc2e517-c1c4-4363-9746-dcf6a15439d0 to the BGPaaS f1a2d191-d221-4a0c-9888-0266ad09b492 object
2025-12-08 04:00:42,313 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 208.144.147.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-12-08 04:00:42,313 - DEBUG - sudo ip addr add 208.144.147.84 dev eth0
2025-12-08 04:00:42,949 - DEBUG - None
2025-12-08 04:00:43,017 - INFO - Creating BGPaaS ctest-TestBGPaasZone-91854096-65028011
2025-12-08 04:00:43,137 - INFO - BGPaaS: ctest-TestBGPaasZone-91854096-65028011(77db69a0-bf18-4180-a251-f89a8a3c9784)
2025-12-08 04:00:43,137 - DEBUG - Requesting: http://10.0.0.23:8082/bgp-as-a-service/77db69a0-bf18-4180-a251-f89a8a3c9784
2025-12-08 04:00:43,147 - INFO - verify_in_api_server passed for BGPaaS obj 77db69a0-bf18-4180-a251-f89a8a3c9784
2025-12-08 04:00:43,147 - INFO - BGPaaS(77db69a0-bf18-4180-a251-f89a8a3c9784): verify_on_setup passed
2025-12-08 04:00:43,147 - INFO - We will configure BGP on the VM
2025-12-08 04:00:43,585 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-08 04:00:43,585 - INFO - Configuring BGP on ctest-TestBGPaasZone-91854096-40583715
2025-12-08 04:00:43,585 - 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 208.144.147.68;
protocol bgp bgp_1{
local as 65000;
neighbor 208.144.147.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 208.144.147.68;
}
protocol bfd {
neighbor 208.144.147.65 local 208.144.147.68 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.129, gateway password: c0ntrail123
2025-12-08 04:00:43,585 - 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 208.144.147.68;
protocol bgp bgp_1{
local as 65000;
neighbor 208.144.147.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 208.144.147.68;
}
protocol bfd {
neighbor 208.144.147.65 local 208.144.147.68 multihop on;
}
EOS
2025-12-08 04:00:49,573 - DEBUG - None
2025-12-08 04:00:49,573 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 208.144.147.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 208.144.147.68;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.129, gateway password: c0ntrail123
2025-12-08 04:00:49,574 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 208.144.147.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 208.144.147.68;
}
EOS
2025-12-08 04:00:50,182 - DEBUG - None
2025-12-08 04:00:50,182 - 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-12-08 04:00:50,182 - DEBUG - service bird restart
2025-12-08 04:00:51,911 - DEBUG - bird stop/waiting
bird start/running, process 1440
2025-12-08 04:00:51,912 - INFO - Attaching VMI bd759db2-24c2-4f95-96de-9b4af0a376f1 to BGPaaS 77db69a0-bf18-4180-a251-f89a8a3c9784
2025-12-08 04:00:51,985 - INFO - Attaching the VMI 4caf15e7-f91b-403d-98f8-41b7007aaab2 to the BGPaaS 77db69a0-bf18-4180-a251-f89a8a3c9784 object
2025-12-08 04:00:51,985 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 208.144.147.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.129, gateway password: c0ntrail123
2025-12-08 04:00:51,985 - DEBUG - sudo ip addr add 208.144.147.84 dev eth0
2025-12-08 04:00:52,675 - DEBUG - None
2025-12-08 04:00:52,675 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-08 04:00:52,688 - INFO - BGPaaS session seen in control-node 10.20.0.18
2025-12-08 04:00:52,688 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-08 04:00:52,688 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-12-08 04:00:52,703 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-12-08 04:00:52,709 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-08 04:00:52,722 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.18']
2025-12-08 04:00:58,735 - INFO - BGPaaS session seen in control-node 10.20.0.18
2025-12-08 04:00:58,735 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-08 04:00:58,735 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-12-08 04:00:58,748 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.14']
2025-12-08 04:01:04,749 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-12-08 04:01:04,764 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-12-08 04:01:04,771 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 208.144.147.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-12-08 04:01:04,771 - DEBUG - ping -s 56 -c 3 -W 1 208.144.147.84
2025-12-08 04:01:10,812 - DEBUG - PING 208.144.147.84 (208.144.147.84) 56(84) bytes of data.
64 bytes from 208.144.147.84: icmp_seq=1 ttl=64 time=9.39 ms
64 bytes from 208.144.147.84: icmp_seq=2 ttl=64 time=1.42 ms
64 bytes from 208.144.147.84: icmp_seq=3 ttl=64 time=1.31 ms
--- 208.144.147.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.315/4.043/9.394/3.783 ms
2025-12-08 04:01:10,812 - INFO - Ping to IP 208.144.147.84 from VM ctest-TestBGPaasZone-91854096-00384241 passed
2025-12-08 04:01:10,813 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-08 04:01:10,827 - INFO - BGPaaS session seen in control-node 10.20.0.18
2025-12-08 04:01:10,827 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-08 04:01:10,827 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-12-08 04:01:10,841 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-12-08 04:01:10,847 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-08 04:01:10,861 - INFO - BGPaaS session seen in control-node 10.20.0.18
2025-12-08 04:01:10,862 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-12-08 04:01:10,862 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-12-08 04:01:10,875 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-12-08 04:01:10,881 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 208.144.147.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-12-08 04:01:10,881 - DEBUG - ping -s 56 -c 3 -W 1 208.144.147.84
2025-12-08 04:01:13,358 - DEBUG - PING 208.144.147.84 (208.144.147.84) 56(84) bytes of data.
64 bytes from 208.144.147.84: icmp_seq=1 ttl=64 time=3.53 ms
64 bytes from 208.144.147.84: icmp_seq=2 ttl=64 time=1.51 ms
64 bytes from 208.144.147.84: icmp_seq=3 ttl=64 time=1.46 ms
--- 208.144.147.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.465/2.174/3.538/0.964 ms
2025-12-08 04:01:13,358 - INFO - Ping to IP 208.144.147.84 from VM ctest-TestBGPaasZone-91854096-00384241 passed
2025-12-08 04:01:13,359 - INFO - Detaching VMI bd759db2-24c2-4f95-96de-9b4af0a376f1 from BGPaaS 77db69a0-bf18-4180-a251-f89a8a3c9784
2025-12-08 04:01:13,434 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-91854096-65028011(77db69a0-bf18-4180-a251-f89a8a3c9784)
2025-12-08 04:01:13,489 - DEBUG - Requesting: http://10.0.0.23:8082/bgp-as-a-service/77db69a0-bf18-4180-a251-f89a8a3c9784
2025-12-08 04:01:13,514 - DEBUG - Response Code: 404
2025-12-08 04:01:13,515 - DEBUG - BGPaaS: 77db69a0-bf18-4180-a251-f89a8a3c9784 deleted from api server
2025-12-08 04:01:13,515 - INFO - BGPaaS(77db69a0-bf18-4180-a251-f89a8a3c9784): verify_on_cleanup passed
2025-12-08 04:01:13,515 - INFO - Detaching VMI 782ff00e-650c-44c3-bcc9-84a151793b9e from BGPaaS f1a2d191-d221-4a0c-9888-0266ad09b492
2025-12-08 04:01:13,601 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-91854096-54395751(f1a2d191-d221-4a0c-9888-0266ad09b492)
2025-12-08 04:01:13,659 - DEBUG - Requesting: http://10.0.0.23:8082/bgp-as-a-service/f1a2d191-d221-4a0c-9888-0266ad09b492
2025-12-08 04:01:13,667 - DEBUG - Response Code: 404
2025-12-08 04:01:13,667 - DEBUG - BGPaaS: f1a2d191-d221-4a0c-9888-0266ad09b492 deleted from api server
2025-12-08 04:01:13,667 - INFO - BGPaaS(f1a2d191-d221-4a0c-9888-0266ad09b492): verify_on_cleanup passed
2025-12-08 04:01:13,667 - INFO - Deleting Control node zone ctest-test-zone-57534736(e370720c-cde9-4f4a-842c-2501f794bcfd)
2025-12-08 04:01:13,825 - INFO - Deleting Control node zone ctest-test-zone-55981873(7b36a26a-c030-48d7-86aa-cccb1b0dafa0)
2025-12-08 04:01:13,966 - INFO - Deleting Control node zone ctest-test-zone-33520466(af79ccb0-7b30-40ea-915c-4814d38d256d)
2025-12-08 04:01:14,113 - INFO - Deleting VM ctest-TestBGPaasZone-91854096-00384241
2025-12-08 04:01:14,191 - INFO - Deleting VM ctest-TestBGPaasZone-91854096-40583715
2025-12-08 04:01:14,262 - INFO - Deleting VM ctest-TestBGPaasZone-91854096-99201581
2025-12-08 04:01:14,346 - INFO - Deleting VN ctest-cnz_vn-86085796
2025-12-08 04:01:14,390 - DEBUG - VN 96f18f8b-2859-4114-8c67-9cd83c361460 still in use: Unable to complete operation on network 96f18f8b-2859-4114-8c67-9cd83c361460. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-3f8c53af-388c-4277-ac8d-72741f7f92b0']
2025-12-08 04:01:14,390 - WARNING - Deleting VN ctest-cnz_vn-86085796 failed..Will retry
2025-12-08 04:01:16,588 - DEBUG - Response for deleting network ()
2025-12-08 04:01:17,848 - DEBUG - Skipping xmpp flap check
2025-12-08 04:01:17,848 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:00]
2025-12-08 04:01:17,848 - INFO - --------------------------------------------------------------------------------
2025-12-08 04:01:19,101 - INFO - Deleted project: ctest-TestBGPaasZone-91854096, ID : eb165d14-87a7-41ec-8bbb-9f6eed140996