is in ACTIVE state now
2026-04-03 18:39:37,701 - INFO - VM name : ctest-TestBGPaasZone-61450203-20858514
2026-04-03 18:39:37,783 - DEBUG - VM ctest-TestBGPaasZone-61450203-20858514 ID is 80b07be5-0d30-4c5f-bca6-7fd2de6cf6a5
2026-04-03 18:39:37,783 - DEBUG - VM ctest-TestBGPaasZone-61450203-20858514 launched on Node an-jenkins-deploy-platform-ansible-os-5589-1
2026-04-03 18:39:37,978 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine/80b07be5-0d30-4c5f-bca6-7fd2de6cf6a5
2026-04-03 18:39:37,989 - DEBUG - Requesting: http://10.0.0.141:8082/virtual-machine-interface/818ba459-5f46-4cb2-b8bf-0dc75a01c683
2026-04-03 18:39:39,146 - DEBUG - (True, 'PING 169.254.0.6 (169.254.0.6) 56(84) bytes of data.\r\n64 bytes from 169.254.0.6: icmp_seq=1 ttl=63 time=5.97 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=0.723 ms\r\n\r\n--- 169.254.0.6 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.723/3.347/5.972/2.624 ms')
2026-04-03 18:39:39,146 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-TestBGPaasZone-61450203-20858514 passed
2026-04-03 18:39:39,220 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 18:39:39,220 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61450203-20858514, IP 4.3.127.5, Port 22
2026-04-03 18:39:39,281 - DEBUG - Error on ssh to ubuntu@169.254.0.6:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.6/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.6/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.6/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2026-04-03 18:39:39,359 - DEBUG - VM ctest-TestBGPaasZone-61450203-20858514 is NOT ready for SSH connections, VM status: ACTIVE
2026-04-03 18:39:44,360 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 18:39:44,360 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-61450203-20858514, IP 4.3.127.5, Port 22
2026-04-03 18:39:44,534 - DEBUG - VM ctest-TestBGPaasZone-61450203-20858514 is ready for SSH connections
2026-04-03 18:39:44,675 - INFO - Created control node zone ctest-test-zone-91290978(441959cd-8f91-41a8-8686-ce8247e98fdc)
2026-04-03 18:39:45,047 - INFO - Created control node zone ctest-test-zone-67712733(4b75a86a-89c8-4998-bf01-086128a54d13)
2026-04-03 18:39:45,411 - INFO - Created control node zone ctest-test-zone-51347407(b5d236d9-99c4-4c5e-977b-a9facf428917)
2026-04-03 18:39:45,695 - INFO - Creating BGPaaS ctest-TestBGPaasZone-61450203-83947262
2026-04-03 18:39:45,767 - INFO - BGPaaS: ctest-TestBGPaasZone-61450203-83947262(cf71d203-c43d-40a2-9ab9-e9186f7650ba)
2026-04-03 18:39:45,767 - DEBUG - Requesting: http://10.0.0.22:8082/bgp-as-a-service/cf71d203-c43d-40a2-9ab9-e9186f7650ba
2026-04-03 18:39:45,777 - INFO - verify_in_api_server passed for BGPaaS obj cf71d203-c43d-40a2-9ab9-e9186f7650ba
2026-04-03 18:39:45,777 - INFO - BGPaaS(cf71d203-c43d-40a2-9ab9-e9186f7650ba): verify_on_setup passed
2026-04-03 18:39:45,777 - INFO - We will configure BGP on the VM
2026-04-03 18:39:46,187 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 18:39:46,187 - INFO - Configuring BGP on ctest-TestBGPaasZone-61450203-83548143
2026-04-03 18:39:46,188 - 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 4.3.127.3;
protocol bgp bgp_1{
local as 65000;
neighbor 4.3.127.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 4.3.127.3;
}
protocol bfd {
neighbor 4.3.127.1 local 4.3.127.3 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-03 18:39:46,188 - 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 4.3.127.3;
protocol bgp bgp_1{
local as 65000;
neighbor 4.3.127.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 4.3.127.3;
}
protocol bfd {
neighbor 4.3.127.1 local 4.3.127.3 multihop on;
}
EOS
2026-04-03 18:40:01,816 - DEBUG - None
2026-04-03 18:40:01,816 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 4.3.127.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 4.3.127.3;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-03 18:40:01,816 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 4.3.127.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 4.3.127.3;
}
EOS
2026-04-03 18:40:03,227 - DEBUG - None
2026-04-03 18:40:03,227 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-03 18:40:03,227 - DEBUG - service bird restart
2026-04-03 18:40:06,860 - DEBUG - bird stop/waiting
bird start/running, process 1438
2026-04-03 18:40:06,860 - INFO - Attaching VMI b2071b7f-bb5b-4e7b-92f4-2f34ac78813e to BGPaaS cf71d203-c43d-40a2-9ab9-e9186f7650ba
2026-04-03 18:40:06,939 - INFO - Attaching the VMI 76322b64-770c-434d-b5fd-dfc67ee8ceb6 to the BGPaaS cf71d203-c43d-40a2-9ab9-e9186f7650ba object
2026-04-03 18:40:06,939 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 4.3.127.20 dev eth0, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-03 18:40:06,940 - DEBUG - sudo ip addr add 4.3.127.20 dev eth0
2026-04-03 18:40:08,382 - DEBUG - None
2026-04-03 18:40:08,443 - INFO - Creating BGPaaS ctest-TestBGPaasZone-61450203-46705847
2026-04-03 18:40:08,519 - INFO - BGPaaS: ctest-TestBGPaasZone-61450203-46705847(f2a927ef-80be-44d6-bbab-cb8608121328)
2026-04-03 18:40:08,520 - DEBUG - Requesting: http://10.0.0.22:8082/bgp-as-a-service/f2a927ef-80be-44d6-bbab-cb8608121328
2026-04-03 18:40:08,530 - INFO - verify_in_api_server passed for BGPaaS obj f2a927ef-80be-44d6-bbab-cb8608121328
2026-04-03 18:40:08,530 - INFO - BGPaaS(f2a927ef-80be-44d6-bbab-cb8608121328): verify_on_setup passed
2026-04-03 18:40:08,530 - INFO - We will configure BGP on the VM
2026-04-03 18:40:08,943 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 18:40:08,944 - INFO - Configuring BGP on ctest-TestBGPaasZone-61450203-82934314
2026-04-03 18:40:08,944 - 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 4.3.127.4;
protocol bgp bgp_1{
local as 65000;
neighbor 4.3.127.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 4.3.127.4;
}
protocol bfd {
neighbor 4.3.127.1 local 4.3.127.4 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-04-03 18:40:08,944 - 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 4.3.127.4;
protocol bgp bgp_1{
local as 65000;
neighbor 4.3.127.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 4.3.127.4;
}
protocol bfd {
neighbor 4.3.127.1 local 4.3.127.4 multihop on;
}
EOS
2026-04-03 18:40:14,370 - DEBUG - None
2026-04-03 18:40:14,370 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 4.3.127.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 4.3.127.4;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-04-03 18:40:14,370 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 4.3.127.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 4.3.127.4;
}
EOS
2026-04-03 18:40:14,875 - DEBUG - None
2026-04-03 18:40:14,875 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-04-03 18:40:14,875 - DEBUG - service bird restart
2026-04-03 18:40:16,510 - DEBUG - bird stop/waiting
bird start/running, process 1484
2026-04-03 18:40:16,510 - INFO - Attaching VMI 5f638ae7-0466-4895-ad59-5e738f7319a2 to BGPaaS f2a927ef-80be-44d6-bbab-cb8608121328
2026-04-03 18:40:16,587 - INFO - Attaching the VMI 6e2a2264-2c78-42e1-b709-e53c3e89d1f7 to the BGPaaS f2a927ef-80be-44d6-bbab-cb8608121328 object
2026-04-03 18:40:16,587 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 4.3.127.20 dev eth0, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-04-03 18:40:16,587 - DEBUG - sudo ip addr add 4.3.127.20 dev eth0
2026-04-03 18:40:17,211 - DEBUG - None
2026-04-03 18:40:17,211 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 18:40:17,225 - INFO - BGPaaS session seen in control-node 10.20.0.17
2026-04-03 18:40:17,225 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 18:40:17,241 - INFO - BGPaaS session seen in control-node 10.20.0.254
2026-04-03 18:40:17,247 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 18:40:17,258 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.17']
2026-04-03 18:40:23,270 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.17']
2026-04-03 18:40:29,282 - INFO - BGPaaS session seen in control-node 10.20.0.17
2026-04-03 18:40:29,282 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 18:40:29,297 - INFO - BGPaaS session seen in control-node 10.20.0.254
2026-04-03 18:40:29,304 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 4.3.127.20, host_string: ubuntu@169.254.0.6, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-03 18:40:29,304 - DEBUG - ping -s 56 -c 3 -W 1 4.3.127.20
2026-04-03 18:40:35,953 - DEBUG - PING 4.3.127.20 (4.3.127.20) 56(84) bytes of data.
64 bytes from 4.3.127.20: icmp_seq=1 ttl=64 time=8.83 ms
64 bytes from 4.3.127.20: icmp_seq=2 ttl=64 time=1.71 ms
64 bytes from 4.3.127.20: icmp_seq=3 ttl=64 time=1.21 ms
--- 4.3.127.20 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.214/3.920/8.834/3.480 ms
2026-04-03 18:40:35,953 - INFO - Ping to IP 4.3.127.20 from VM ctest-TestBGPaasZone-61450203-20858514 passed
2026-04-03 18:40:35,953 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 18:40:35,964 - INFO - BGPaaS session seen in control-node 10.20.0.17
2026-04-03 18:40:35,964 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 18:40:35,981 - INFO - BGPaaS session seen in control-node 10.20.0.254
2026-04-03 18:40:35,987 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 18:40:36,001 - INFO - BGPaaS session seen in control-node 10.20.0.17
2026-04-03 18:40:36,001 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-03 18:40:36,017 - INFO - BGPaaS session seen in control-node 10.20.0.254
2026-04-03 18:40:36,022 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 4.3.127.20, host_string: ubuntu@169.254.0.6, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-03 18:40:36,022 - DEBUG - ping -s 56 -c 3 -W 1 4.3.127.20
2026-04-03 18:40:38,564 - DEBUG - PING 4.3.127.20 (4.3.127.20) 56(84) bytes of data.
64 bytes from 4.3.127.20: icmp_seq=1 ttl=64 time=8.19 ms
64 bytes from 4.3.127.20: icmp_seq=2 ttl=64 time=1.03 ms
64 bytes from 4.3.127.20: icmp_seq=3 ttl=64 time=1.75 ms
--- 4.3.127.20 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 1.037/3.662/8.192/3.216 ms
2026-04-03 18:40:38,565 - INFO - Ping to IP 4.3.127.20 from VM ctest-TestBGPaasZone-61450203-20858514 passed
2026-04-03 18:40:38,565 - INFO - Detaching VMI 5f638ae7-0466-4895-ad59-5e738f7319a2 from BGPaaS f2a927ef-80be-44d6-bbab-cb8608121328
2026-04-03 18:40:38,643 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-61450203-46705847(f2a927ef-80be-44d6-bbab-cb8608121328)
2026-04-03 18:40:38,700 - DEBUG - Requesting: http://10.0.0.22:8082/bgp-as-a-service/f2a927ef-80be-44d6-bbab-cb8608121328
2026-04-03 18:40:38,712 - DEBUG - Response Code: 404
2026-04-03 18:40:38,712 - DEBUG - BGPaaS: f2a927ef-80be-44d6-bbab-cb8608121328 deleted from api server
2026-04-03 18:40:38,712 - INFO - BGPaaS(f2a927ef-80be-44d6-bbab-cb8608121328): verify_on_cleanup passed
2026-04-03 18:40:38,712 - INFO - Detaching VMI b2071b7f-bb5b-4e7b-92f4-2f34ac78813e from BGPaaS cf71d203-c43d-40a2-9ab9-e9186f7650ba
2026-04-03 18:40:38,823 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-61450203-83947262(cf71d203-c43d-40a2-9ab9-e9186f7650ba)
2026-04-03 18:40:38,890 - DEBUG - Requesting: http://10.0.0.22:8082/bgp-as-a-service/cf71d203-c43d-40a2-9ab9-e9186f7650ba
2026-04-03 18:40:38,909 - DEBUG - Response Code: 404
2026-04-03 18:40:38,909 - DEBUG - BGPaaS: cf71d203-c43d-40a2-9ab9-e9186f7650ba deleted from api server
2026-04-03 18:40:38,909 - INFO - BGPaaS(cf71d203-c43d-40a2-9ab9-e9186f7650ba): verify_on_cleanup passed
2026-04-03 18:40:38,910 - INFO - Deleting Control node zone ctest-test-zone-51347407(b5d236d9-99c4-4c5e-977b-a9facf428917)
2026-04-03 18:40:39,053 - INFO - Deleting Control node zone ctest-test-zone-67712733(4b75a86a-89c8-4998-bf01-086128a54d13)
2026-04-03 18:40:39,199 - INFO - Deleting Control node zone ctest-test-zone-91290978(441959cd-8f91-41a8-8686-ce8247e98fdc)
2026-04-03 18:40:39,336 - INFO - Deleting VM ctest-TestBGPaasZone-61450203-20858514
2026-04-03 18:40:39,411 - INFO - Deleting VM ctest-TestBGPaasZone-61450203-82934314
2026-04-03 18:40:39,486 - INFO - Deleting VM ctest-TestBGPaasZone-61450203-83548143
2026-04-03 18:40:39,555 - INFO - Deleting VN ctest-cnz_vn-76011570
2026-04-03 18:40:39,594 - DEBUG - VN 83c1d2ab-c836-4efa-ac30-5a50e78fbccb still in use: Unable to complete operation on network 83c1d2ab-c836-4efa-ac30-5a50e78fbccb. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-0da6d6e4-5ce0-43d2-ae7e-766d58965ab0']
2026-04-03 18:40:39,594 - WARNING - Deleting VN ctest-cnz_vn-76011570 failed..Will retry
2026-04-03 18:40:41,840 - DEBUG - Response for deleting network ()
2026-04-03 18:40:43,105 - DEBUG - Skipping xmpp flap check
2026-04-03 18:40:43,105 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:39]
2026-04-03 18:40:43,105 - INFO - --------------------------------------------------------------------------------
2026-04-03 18:40:43,953 - INFO - Deleted project: ctest-TestBGPaasZone-61450203, ID : e6938e01-e35d-4175-870a-292bf7bbacb8