is in ACTIVE state now
2026-11-02 04:42:06,779 - INFO - VM name : ctest-TestBGPaasZone-57718867-67106500
2026-11-02 04:42:06,864 - DEBUG - VM ctest-TestBGPaasZone-57718867-67106500 ID is 09e73afe-5993-4a0d-9942-4f4274c404d0
2026-11-02 04:42:06,864 - DEBUG - VM ctest-TestBGPaasZone-57718867-67106500 launched on Node an-jenkins-deploy-platform-ansible-os-6461-2
2026-11-02 04:42:06,947 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine/09e73afe-5993-4a0d-9942-4f4274c404d0
2026-11-02 04:42:06,958 - DEBUG - Requesting: http://10.0.0.191:8082/virtual-machine-interface/70736cb0-35a2-4258-8ed6-40d0ade23a59
2026-11-02 04:42:08,122 - 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=4.55 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.00 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.004/2.777/4.551/1.773 ms')
2026-11-02 04:42:08,122 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-57718867-67106500 passed
2026-11-02 04:42:08,195 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-02 04:42:08,195 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57718867-67106500, IP 184.33.198.133, Port 22
2026-11-02 04:42:08,261 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-11-02 04:42:08,355 - DEBUG - VM ctest-TestBGPaasZone-57718867-67106500 is NOT ready for SSH connections, VM status: ACTIVE
2026-11-02 04:42:13,356 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-02 04:42:13,357 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-57718867-67106500, IP 184.33.198.133, Port 22
2026-11-02 04:42:13,524 - DEBUG - VM ctest-TestBGPaasZone-57718867-67106500 is ready for SSH connections
2026-11-02 04:42:13,665 - INFO - Created control node zone ctest-test-zone-61630018(582bf7e3-4ed2-4f1c-ac7d-4108e8f3403f)
2026-11-02 04:42:14,017 - INFO - Created control node zone ctest-test-zone-65001539(38afe1af-8f37-4e75-8098-d8e5108ccef9)
2026-11-02 04:42:14,377 - INFO - Created control node zone ctest-test-zone-13012483(0deb217a-f3b6-4f19-b44e-2ae54f9ac5f6)
2026-11-02 04:42:14,661 - INFO - Creating BGPaaS ctest-TestBGPaasZone-57718867-61539617
2026-11-02 04:42:14,737 - INFO - BGPaaS: ctest-TestBGPaasZone-57718867-61539617(6ab2a25e-3833-4a40-a08a-c33de691aa4f)
2026-11-02 04:42:14,737 - DEBUG - Requesting: http://10.0.0.50:8082/bgp-as-a-service/6ab2a25e-3833-4a40-a08a-c33de691aa4f
2026-11-02 04:42:14,748 - INFO - verify_in_api_server passed for BGPaaS obj 6ab2a25e-3833-4a40-a08a-c33de691aa4f
2026-11-02 04:42:14,748 - INFO - BGPaaS(6ab2a25e-3833-4a40-a08a-c33de691aa4f): verify_on_setup passed
2026-11-02 04:42:14,748 - INFO - We will configure BGP on the VM
2026-11-02 04:42:15,165 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-02 04:42:15,165 - INFO - Configuring BGP on ctest-TestBGPaasZone-57718867-74184355
2026-11-02 04:42:15,165 - 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 184.33.198.131;
protocol bgp bgp_1{
local as 65000;
neighbor 184.33.198.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 184.33.198.131;
}
protocol bfd {
neighbor 184.33.198.129 local 184.33.198.131 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.162, gateway password: c0ntrail123
2026-11-02 04:42:15,165 - 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 184.33.198.131;
protocol bgp bgp_1{
local as 65000;
neighbor 184.33.198.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 184.33.198.131;
}
protocol bfd {
neighbor 184.33.198.129 local 184.33.198.131 multihop on;
}
EOS
2026-11-02 04:42:21,969 - DEBUG - None
2026-11-02 04:42:21,969 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 184.33.198.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 184.33.198.131;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.162, gateway password: c0ntrail123
2026-11-02 04:42:21,969 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 184.33.198.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 184.33.198.131;
}
EOS
2026-11-02 04:42:22,628 - DEBUG - None
2026-11-02 04:42:22,628 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.162, gateway password: c0ntrail123
2026-11-02 04:42:22,628 - DEBUG - service bird restart
2026-11-02 04:42:24,792 - DEBUG - bird stop/waiting
bird start/running, process 1437
2026-11-02 04:42:24,793 - INFO - Attaching VMI bac992a2-b68e-47e8-89e4-62e38ae1f16a to BGPaaS 6ab2a25e-3833-4a40-a08a-c33de691aa4f
2026-11-02 04:42:24,877 - INFO - Attaching the VMI 159f4d34-2c7a-423a-b50f-d36bdc95498f to the BGPaaS 6ab2a25e-3833-4a40-a08a-c33de691aa4f object
2026-11-02 04:42:24,877 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 184.33.198.148 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.162, gateway password: c0ntrail123
2026-11-02 04:42:24,877 - DEBUG - sudo ip addr add 184.33.198.148 dev eth0
2026-11-02 04:42:25,399 - DEBUG - None
2026-11-02 04:42:25,465 - INFO - Creating BGPaaS ctest-TestBGPaasZone-57718867-81780044
2026-11-02 04:42:25,541 - INFO - BGPaaS: ctest-TestBGPaasZone-57718867-81780044(94f56fc6-d131-4d26-a23a-9301d7423085)
2026-11-02 04:42:25,541 - DEBUG - Requesting: http://10.0.0.50:8082/bgp-as-a-service/94f56fc6-d131-4d26-a23a-9301d7423085
2026-11-02 04:42:25,552 - INFO - verify_in_api_server passed for BGPaaS obj 94f56fc6-d131-4d26-a23a-9301d7423085
2026-11-02 04:42:25,552 - INFO - BGPaaS(94f56fc6-d131-4d26-a23a-9301d7423085): verify_on_setup passed
2026-11-02 04:42:25,552 - INFO - We will configure BGP on the VM
2026-11-02 04:42:25,977 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-02 04:42:25,977 - INFO - Configuring BGP on ctest-TestBGPaasZone-57718867-86388720
2026-11-02 04:42:25,978 - 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 184.33.198.132;
protocol bgp bgp_1{
local as 65000;
neighbor 184.33.198.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 184.33.198.132;
}
protocol bfd {
neighbor 184.33.198.129 local 184.33.198.132 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.161, gateway password: c0ntrail123
2026-11-02 04:42:25,978 - 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 184.33.198.132;
protocol bgp bgp_1{
local as 65000;
neighbor 184.33.198.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 184.33.198.132;
}
protocol bfd {
neighbor 184.33.198.129 local 184.33.198.132 multihop on;
}
EOS
2026-11-02 04:42:29,536 - DEBUG - None
2026-11-02 04:42:29,536 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 184.33.198.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 184.33.198.132;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.161, gateway password: c0ntrail123
2026-11-02 04:42:29,536 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 184.33.198.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 184.33.198.132;
}
EOS
2026-11-02 04:42:29,824 - DEBUG - None
2026-11-02 04:42:29,824 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.161, gateway password: c0ntrail123
2026-11-02 04:42:29,824 - DEBUG - service bird restart
2026-11-02 04:42:30,691 - DEBUG - bird stop/waiting
bird start/running, process 1440
2026-11-02 04:42:30,691 - INFO - Attaching VMI 812d0e94-4b50-4e1c-972f-9696a46b321a to BGPaaS 94f56fc6-d131-4d26-a23a-9301d7423085
2026-11-02 04:42:30,769 - INFO - Attaching the VMI 23182c73-3b52-45f6-a8ce-e0c448c302d2 to the BGPaaS 94f56fc6-d131-4d26-a23a-9301d7423085 object
2026-11-02 04:42:30,769 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 184.33.198.148 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.161, gateway password: c0ntrail123
2026-11-02 04:42:30,769 - DEBUG - sudo ip addr add 184.33.198.148 dev eth0
2026-11-02 04:42:31,127 - DEBUG - None
2026-11-02 04:42:31,127 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-02 04:42:31,144 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.17']
2026-11-02 04:42:37,161 - INFO - BGPaaS session seen in control-node 10.20.0.17
2026-11-02 04:42:37,161 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-02 04:42:37,178 - INFO - BGPaaS session seen in control-node 10.20.0.254
2026-11-02 04:42:37,185 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-02 04:42:37,200 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.17']
2026-11-02 04:42:43,218 - INFO - BGPaaS session seen in control-node 10.20.0.17
2026-11-02 04:42:43,219 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-02 04:42:43,237 - INFO - BGPaaS session seen in control-node 10.20.0.254
2026-11-02 04:42:43,252 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 184.33.198.148, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.162, gateway password: c0ntrail123
2026-11-02 04:42:43,252 - DEBUG - ping -s 56 -c 3 -W 1 184.33.198.148
2026-11-02 04:42:47,885 - DEBUG - PING 184.33.198.148 (184.33.198.148) 56(84) bytes of data.
64 bytes from 184.33.198.148: icmp_seq=1 ttl=64 time=8.02 ms
64 bytes from 184.33.198.148: icmp_seq=2 ttl=64 time=1.57 ms
64 bytes from 184.33.198.148: icmp_seq=3 ttl=64 time=1.51 ms
--- 184.33.198.148 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.515/3.703/8.020/3.052 ms
2026-11-02 04:42:47,885 - INFO - Ping to IP 184.33.198.148 from VM ctest-TestBGPaasZone-57718867-67106500 passed
2026-11-02 04:42:47,885 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-02 04:42:47,899 - INFO - BGPaaS session seen in control-node 10.20.0.17
2026-11-02 04:42:47,900 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-02 04:42:47,917 - INFO - BGPaaS session seen in control-node 10.20.0.254
2026-11-02 04:42:47,924 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-02 04:42:47,954 - INFO - BGPaaS session seen in control-node 10.20.0.17
2026-11-02 04:42:47,954 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-11-02 04:42:47,975 - INFO - BGPaaS session seen in control-node 10.20.0.254
2026-11-02 04:42:47,981 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 184.33.198.148, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.162, gateway password: c0ntrail123
2026-11-02 04:42:47,981 - DEBUG - ping -s 56 -c 3 -W 1 184.33.198.148
2026-11-02 04:42:50,248 - DEBUG - PING 184.33.198.148 (184.33.198.148) 56(84) bytes of data.
64 bytes from 184.33.198.148: icmp_seq=1 ttl=64 time=1.68 ms
64 bytes from 184.33.198.148: icmp_seq=2 ttl=64 time=1.11 ms
64 bytes from 184.33.198.148: icmp_seq=3 ttl=64 time=1.15 ms
--- 184.33.198.148 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.114/1.317/1.683/0.259 ms
2026-11-02 04:42:50,248 - INFO - Ping to IP 184.33.198.148 from VM ctest-TestBGPaasZone-57718867-67106500 passed
2026-11-02 04:42:50,248 - INFO - Detaching VMI 812d0e94-4b50-4e1c-972f-9696a46b321a from BGPaaS 94f56fc6-d131-4d26-a23a-9301d7423085
2026-11-02 04:42:50,333 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-57718867-81780044(94f56fc6-d131-4d26-a23a-9301d7423085)
2026-11-02 04:42:50,392 - DEBUG - Requesting: http://10.0.0.50:8082/bgp-as-a-service/94f56fc6-d131-4d26-a23a-9301d7423085
2026-11-02 04:42:50,403 - DEBUG - Response Code: 404
2026-11-02 04:42:50,404 - DEBUG - BGPaaS: 94f56fc6-d131-4d26-a23a-9301d7423085 deleted from api server
2026-11-02 04:42:50,404 - INFO - BGPaaS(94f56fc6-d131-4d26-a23a-9301d7423085): verify_on_cleanup passed
2026-11-02 04:42:50,404 - INFO - Detaching VMI bac992a2-b68e-47e8-89e4-62e38ae1f16a from BGPaaS 6ab2a25e-3833-4a40-a08a-c33de691aa4f
2026-11-02 04:42:50,485 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-57718867-61539617(6ab2a25e-3833-4a40-a08a-c33de691aa4f)
2026-11-02 04:42:50,548 - DEBUG - Requesting: http://10.0.0.50:8082/bgp-as-a-service/6ab2a25e-3833-4a40-a08a-c33de691aa4f
2026-11-02 04:42:50,563 - DEBUG - Response Code: 404
2026-11-02 04:42:50,563 - DEBUG - BGPaaS: 6ab2a25e-3833-4a40-a08a-c33de691aa4f deleted from api server
2026-11-02 04:42:50,563 - INFO - BGPaaS(6ab2a25e-3833-4a40-a08a-c33de691aa4f): verify_on_cleanup passed
2026-11-02 04:42:50,565 - INFO - Deleting Control node zone ctest-test-zone-13012483(0deb217a-f3b6-4f19-b44e-2ae54f9ac5f6)
2026-11-02 04:42:50,710 - INFO - Deleting Control node zone ctest-test-zone-65001539(38afe1af-8f37-4e75-8098-d8e5108ccef9)
2026-11-02 04:42:50,865 - INFO - Deleting Control node zone ctest-test-zone-61630018(582bf7e3-4ed2-4f1c-ac7d-4108e8f3403f)
2026-11-02 04:42:51,012 - INFO - Deleting VM ctest-TestBGPaasZone-57718867-67106500
2026-11-02 04:42:51,113 - INFO - Deleting VM ctest-TestBGPaasZone-57718867-86388720
2026-11-02 04:42:51,190 - INFO - Deleting VM ctest-TestBGPaasZone-57718867-74184355
2026-11-02 04:42:51,268 - INFO - Deleting VN ctest-cnz_vn-42854265
2026-11-02 04:42:51,321 - DEBUG - VN a82a2009-c47f-4309-8039-f05b87a2e1ff still in use: Unable to complete operation on network a82a2009-c47f-4309-8039-f05b87a2e1ff. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-961a05b8-2e8b-4733-8025-fea7c5100bde']
2026-11-02 04:42:51,321 - WARNING - Deleting VN ctest-cnz_vn-42854265 failed..Will retry
2026-11-02 04:42:53,361 - DEBUG - VN a82a2009-c47f-4309-8039-f05b87a2e1ff still in use: Unable to complete operation on network a82a2009-c47f-4309-8039-f05b87a2e1ff. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-ef0348f7-dde3-4959-ba8d-733a011760d3']
2026-11-02 04:42:53,361 - WARNING - Deleting VN ctest-cnz_vn-42854265 failed..Will retry
2026-11-02 04:42:55,578 - DEBUG - Response for deleting network ()
2026-11-02 04:42:56,917 - DEBUG - Skipping xmpp flap check
2026-11-02 04:42:56,917 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:17]
2026-11-02 04:42:56,917 - INFO - --------------------------------------------------------------------------------
2026-11-02 04:42:57,966 - INFO - Deleted project: ctest-TestBGPaasZone-57718867, ID : 82d26d4b-d23f-4c6f-ba0b-f1358f4cb7ae