is in ACTIVE state now
2025-08-15 11:20:40,034 - INFO - VM name : ctest-TestBGPaasZone-95319068-82741512
2025-08-15 11:20:40,105 - DEBUG - VM ctest-TestBGPaasZone-95319068-82741512 ID is 4bdf4910-5190-404b-8fcb-fcd5d1c14ca7
2025-08-15 11:20:40,105 - DEBUG - VM ctest-TestBGPaasZone-95319068-82741512 launched on Node an-jenkins-deploy-platform-ansible-os-3861-1
2025-08-15 11:20:40,186 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/4bdf4910-5190-404b-8fcb-fcd5d1c14ca7
2025-08-15 11:20:40,196 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/6221b700-77aa-4c9e-8180-0729e5e590cd
2025-08-15 11:20:41,355 - 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.83 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.21 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.211/2.518/3.826/1.307 ms')
2025-08-15 11:20:41,355 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-95319068-82741512 passed
2025-08-15 11:20:41,428 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-15 11:20:41,428 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-95319068-82741512, IP 159.108.51.69, Port 22
2025-08-15 11:20:41,602 - DEBUG - VM ctest-TestBGPaasZone-95319068-82741512 is ready for SSH connections
2025-08-15 11:20:41,735 - INFO - Created control node zone ctest-test-zone-65247791(36bf23b3-aed4-4bc7-a6c0-7ff356b67fa0)
2025-08-15 11:20:42,075 - INFO - Created control node zone ctest-test-zone-70815894(8f8e52d1-08fc-4255-bb67-bb5c3db2b52b)
2025-08-15 11:20:42,435 - INFO - Created control node zone ctest-test-zone-56751523(7f0fc584-5791-4375-b780-53261a33eb34)
2025-08-15 11:20:42,707 - INFO - Creating BGPaaS ctest-TestBGPaasZone-95319068-46220860
2025-08-15 11:20:42,775 - INFO - BGPaaS: ctest-TestBGPaasZone-95319068-46220860(8050dd58-24f5-4bc6-9479-11f9d3c51241)
2025-08-15 11:20:42,776 - DEBUG - Requesting: http://10.0.0.50:8082/bgp-as-a-service/8050dd58-24f5-4bc6-9479-11f9d3c51241
2025-08-15 11:20:42,784 - INFO - verify_in_api_server passed for BGPaaS obj 8050dd58-24f5-4bc6-9479-11f9d3c51241
2025-08-15 11:20:42,784 - INFO - BGPaaS(8050dd58-24f5-4bc6-9479-11f9d3c51241): verify_on_setup passed
2025-08-15 11:20:42,785 - INFO - We will configure BGP on the VM
2025-08-15 11:20:43,183 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-15 11:20:43,184 - INFO - Configuring BGP on ctest-TestBGPaasZone-95319068-82751539
2025-08-15 11:20:43,184 - 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 159.108.51.67;
protocol bgp bgp_1{
local as 65000;
neighbor 159.108.51.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 159.108.51.67;
}
protocol bfd {
neighbor 159.108.51.65 local 159.108.51.67 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-08-15 11:20:43,184 - 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 159.108.51.67;
protocol bgp bgp_1{
local as 65000;
neighbor 159.108.51.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 159.108.51.67;
}
protocol bfd {
neighbor 159.108.51.65 local 159.108.51.67 multihop on;
}
EOS
2025-08-15 11:20:59,245 - DEBUG - None
2025-08-15 11:20:59,245 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 159.108.51.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 159.108.51.67;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-08-15 11:20:59,245 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 159.108.51.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 159.108.51.67;
}
EOS
2025-08-15 11:20:59,753 - DEBUG - None
2025-08-15 11:20:59,753 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-08-15 11:20:59,753 - DEBUG - service bird restart
2025-08-15 11:21:01,501 - DEBUG - bird stop/waiting
bird start/running, process 1437
2025-08-15 11:21:01,501 - INFO - Attaching VMI e69f4e55-bf19-4062-bbbb-5d2667658296 to BGPaaS 8050dd58-24f5-4bc6-9479-11f9d3c51241
2025-08-15 11:21:01,579 - INFO - Attaching the VMI 2ac3f0a2-b7da-43ed-911b-4cecc12bfb74 to the BGPaaS 8050dd58-24f5-4bc6-9479-11f9d3c51241 object
2025-08-15 11:21:01,580 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 159.108.51.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-08-15 11:21:01,580 - DEBUG - sudo ip addr add 159.108.51.84 dev eth0
2025-08-15 11:21:02,297 - DEBUG - None
2025-08-15 11:21:02,359 - INFO - Creating BGPaaS ctest-TestBGPaasZone-95319068-19275970
2025-08-15 11:21:02,435 - INFO - BGPaaS: ctest-TestBGPaasZone-95319068-19275970(dc727021-5b2c-4bdd-9563-3b4678560737)
2025-08-15 11:21:02,436 - DEBUG - Requesting: http://10.0.0.50:8082/bgp-as-a-service/dc727021-5b2c-4bdd-9563-3b4678560737
2025-08-15 11:21:02,446 - INFO - verify_in_api_server passed for BGPaaS obj dc727021-5b2c-4bdd-9563-3b4678560737
2025-08-15 11:21:02,446 - INFO - BGPaaS(dc727021-5b2c-4bdd-9563-3b4678560737): verify_on_setup passed
2025-08-15 11:21:02,446 - INFO - We will configure BGP on the VM
2025-08-15 11:21:02,875 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-15 11:21:02,875 - INFO - Configuring BGP on ctest-TestBGPaasZone-95319068-13317317
2025-08-15 11:21:02,876 - 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 159.108.51.68;
protocol bgp bgp_1{
local as 65000;
neighbor 159.108.51.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 159.108.51.68;
}
protocol bfd {
neighbor 159.108.51.65 local 159.108.51.68 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.242, gateway password: c0ntrail123
2025-08-15 11:21:02,876 - 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 159.108.51.68;
protocol bgp bgp_1{
local as 65000;
neighbor 159.108.51.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 159.108.51.68;
}
protocol bfd {
neighbor 159.108.51.65 local 159.108.51.68 multihop on;
}
EOS
2025-08-15 11:21:12,837 - DEBUG - None
2025-08-15 11:21:12,837 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 159.108.51.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 159.108.51.68;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.242, gateway password: c0ntrail123
2025-08-15 11:21:12,837 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 159.108.51.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 159.108.51.68;
}
EOS
2025-08-15 11:21:13,318 - DEBUG - None
2025-08-15 11:21:13,319 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.242, gateway password: c0ntrail123
2025-08-15 11:21:13,319 - DEBUG - service bird restart
2025-08-15 11:21:14,927 - DEBUG - bird stop/waiting
bird start/running, process 1482
2025-08-15 11:21:14,927 - INFO - Attaching VMI 401b2852-7f9d-4865-b21d-9f111aea1367 to BGPaaS dc727021-5b2c-4bdd-9563-3b4678560737
2025-08-15 11:21:14,999 - INFO - Attaching the VMI 211d2dba-a781-4a09-ac23-9c03bc4b6736 to the BGPaaS dc727021-5b2c-4bdd-9563-3b4678560737 object
2025-08-15 11:21:15,000 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 159.108.51.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.242, gateway password: c0ntrail123
2025-08-15 11:21:15,000 - DEBUG - sudo ip addr add 159.108.51.84 dev eth0
2025-08-15 11:21:15,675 - DEBUG - None
2025-08-15 11:21:15,675 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-15 11:21:15,688 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-08-15 11:21:15,688 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-15 11:21:15,702 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-08-15 11:21:15,707 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-15 11:21:15,718 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.254']
2025-08-15 11:21:21,742 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.254']
2025-08-15 11:21:27,762 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-08-15 11:21:27,762 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-15 11:21:27,776 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-08-15 11:21:27,781 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 159.108.51.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-08-15 11:21:27,781 - DEBUG - ping -s 56 -c 3 -W 1 159.108.51.84
2025-08-15 11:21:39,494 - DEBUG - PING 159.108.51.84 (159.108.51.84) 56(84) bytes of data.
64 bytes from 159.108.51.84: icmp_seq=1 ttl=64 time=7.71 ms
64 bytes from 159.108.51.84: icmp_seq=2 ttl=64 time=0.875 ms
64 bytes from 159.108.51.84: icmp_seq=3 ttl=64 time=0.825 ms
--- 159.108.51.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 0.825/3.138/7.714/3.235 ms
2025-08-15 11:21:39,494 - INFO - Ping to IP 159.108.51.84 from VM ctest-TestBGPaasZone-95319068-82741512 passed
2025-08-15 11:21:39,494 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-15 11:21:39,507 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-08-15 11:21:39,507 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-15 11:21:39,523 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-08-15 11:21:39,528 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-15 11:21:39,539 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-08-15 11:21:39,539 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-15 11:21:39,553 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-08-15 11:21:39,558 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 159.108.51.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2025-08-15 11:21:39,558 - DEBUG - ping -s 56 -c 3 -W 1 159.108.51.84
2025-08-15 11:21:42,031 - DEBUG - PING 159.108.51.84 (159.108.51.84) 56(84) bytes of data.
64 bytes from 159.108.51.84: icmp_seq=1 ttl=64 time=5.30 ms
64 bytes from 159.108.51.84: icmp_seq=2 ttl=64 time=1.10 ms
64 bytes from 159.108.51.84: icmp_seq=3 ttl=64 time=1.01 ms
--- 159.108.51.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.017/2.477/5.305/2.000 ms
2025-08-15 11:21:42,031 - INFO - Ping to IP 159.108.51.84 from VM ctest-TestBGPaasZone-95319068-82741512 passed
2025-08-15 11:21:42,031 - INFO - Detaching VMI 401b2852-7f9d-4865-b21d-9f111aea1367 from BGPaaS dc727021-5b2c-4bdd-9563-3b4678560737
2025-08-15 11:21:42,099 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-95319068-19275970(dc727021-5b2c-4bdd-9563-3b4678560737)
2025-08-15 11:21:42,170 - DEBUG - Requesting: http://10.0.0.50:8082/bgp-as-a-service/dc727021-5b2c-4bdd-9563-3b4678560737
2025-08-15 11:21:42,178 - DEBUG - Response Code: 404
2025-08-15 11:21:42,182 - DEBUG - BGPaaS: dc727021-5b2c-4bdd-9563-3b4678560737 deleted from api server
2025-08-15 11:21:42,182 - INFO - BGPaaS(dc727021-5b2c-4bdd-9563-3b4678560737): verify_on_cleanup passed
2025-08-15 11:21:42,182 - INFO - Detaching VMI e69f4e55-bf19-4062-bbbb-5d2667658296 from BGPaaS 8050dd58-24f5-4bc6-9479-11f9d3c51241
2025-08-15 11:21:42,280 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-95319068-46220860(8050dd58-24f5-4bc6-9479-11f9d3c51241)
2025-08-15 11:21:42,340 - DEBUG - Requesting: http://10.0.0.50:8082/bgp-as-a-service/8050dd58-24f5-4bc6-9479-11f9d3c51241
2025-08-15 11:21:42,353 - DEBUG - Response Code: 404
2025-08-15 11:21:42,353 - DEBUG - BGPaaS: 8050dd58-24f5-4bc6-9479-11f9d3c51241 deleted from api server
2025-08-15 11:21:42,353 - INFO - BGPaaS(8050dd58-24f5-4bc6-9479-11f9d3c51241): verify_on_cleanup passed
2025-08-15 11:21:42,354 - INFO - Deleting Control node zone ctest-test-zone-56751523(7f0fc584-5791-4375-b780-53261a33eb34)
2025-08-15 11:21:42,506 - INFO - Deleting Control node zone ctest-test-zone-70815894(8f8e52d1-08fc-4255-bb67-bb5c3db2b52b)
2025-08-15 11:21:42,654 - INFO - Deleting Control node zone ctest-test-zone-65247791(36bf23b3-aed4-4bc7-a6c0-7ff356b67fa0)
2025-08-15 11:21:42,794 - INFO - Deleting VM ctest-TestBGPaasZone-95319068-82741512
2025-08-15 11:21:42,861 - INFO - Deleting VM ctest-TestBGPaasZone-95319068-13317317
2025-08-15 11:21:42,914 - INFO - Deleting VM ctest-TestBGPaasZone-95319068-82751539
2025-08-15 11:21:42,972 - INFO - Deleting VN ctest-cnz_vn-84982429
2025-08-15 11:21:43,009 - DEBUG - VN 2d13f14d-59aa-408b-a3e3-2bdfd80750ca still in use: Unable to complete operation on network 2d13f14d-59aa-408b-a3e3-2bdfd80750ca. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-93ac9ddb-57af-4862-b76a-c54d17d72592']
2025-08-15 11:21:43,010 - WARNING - Deleting VN ctest-cnz_vn-84982429 failed..Will retry
2025-08-15 11:21:45,192 - DEBUG - Response for deleting network ()
2025-08-15 11:21:46,438 - DEBUG - Skipping xmpp flap check
2025-08-15 11:21:46,438 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:19]
2025-08-15 11:21:46,438 - INFO - --------------------------------------------------------------------------------
2025-08-15 11:21:47,721 - INFO - Deleted project: ctest-TestBGPaasZone-95319068, ID : c2540352-7d37-47f1-a6dc-f3c7490becda