is in ACTIVE state now
2026-05-11 04:24:00,138 - INFO - VM name : ctest-TestBGPaasZone-40689383-11437771
2026-05-11 04:24:00,237 - DEBUG - VM ctest-TestBGPaasZone-40689383-11437771 ID is 510d8f2d-57ab-4527-b41d-0f7aa7d0fc47
2026-05-11 04:24:00,237 - DEBUG - VM ctest-TestBGPaasZone-40689383-11437771 launched on Node an-jenkins-deploy-platform-ansible-os-5767-1
2026-05-11 04:24:00,333 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine/510d8f2d-57ab-4527-b41d-0f7aa7d0fc47
2026-05-11 04:24:00,347 - DEBUG - Requesting: http://10.0.0.27:8082/virtual-machine-interface/54d4ee14-292b-4014-8236-27a09bbb9ef6
2026-05-11 04:24:01,534 - 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=3.92 ms\r\n64 bytes from 169.254.0.6: icmp_seq=2 ttl=63 time=1.62 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 = 1.615/2.766/3.917/1.151 ms')
2026-05-11 04:24:01,534 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-TestBGPaasZone-40689383-11437771 passed
2026-05-11 04:24:01,611 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 04:24:01,611 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-40689383-11437771, IP 13.71.44.5, Port 22
2026-05-11 04:24:01,682 - 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-05-11 04:24:01,787 - DEBUG - VM ctest-TestBGPaasZone-40689383-11437771 is NOT ready for SSH connections, VM status: ACTIVE
2026-05-11 04:24:06,788 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 04:24:06,788 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-40689383-11437771, IP 13.71.44.5, Port 22
2026-05-11 04:24:06,958 - DEBUG - VM ctest-TestBGPaasZone-40689383-11437771 is ready for SSH connections
2026-05-11 04:24:07,093 - INFO - Created control node zone ctest-test-zone-52011978(8fd15e27-1abf-44b1-bdea-7e4d1845c2b0)
2026-05-11 04:24:07,465 - INFO - Created control node zone ctest-test-zone-36926536(06af1710-384c-42b9-8ccb-2d149a5636c6)
2026-05-11 04:24:07,821 - INFO - Created control node zone ctest-test-zone-06934604(eff4c1e8-bccd-4059-a2b1-36c66696ee1d)
2026-05-11 04:24:08,105 - INFO - Creating BGPaaS ctest-TestBGPaasZone-40689383-80628271
2026-05-11 04:24:08,177 - INFO - BGPaaS: ctest-TestBGPaasZone-40689383-80628271(4ba64880-b71a-4700-940e-7a88bf3cd89c)
2026-05-11 04:24:08,177 - DEBUG - Requesting: http://10.0.0.141:8082/bgp-as-a-service/4ba64880-b71a-4700-940e-7a88bf3cd89c
2026-05-11 04:24:08,187 - INFO - verify_in_api_server passed for BGPaaS obj 4ba64880-b71a-4700-940e-7a88bf3cd89c
2026-05-11 04:24:08,187 - INFO - BGPaaS(4ba64880-b71a-4700-940e-7a88bf3cd89c): verify_on_setup passed
2026-05-11 04:24:08,187 - INFO - We will configure BGP on the VM
2026-05-11 04:24:08,621 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 04:24:08,621 - INFO - Configuring BGP on ctest-TestBGPaasZone-40689383-82819391
2026-05-11 04:24:08,621 - 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 13.71.44.3;
protocol bgp bgp_1{
local as 65000;
neighbor 13.71.44.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 13.71.44.3;
}
protocol bfd {
neighbor 13.71.44.1 local 13.71.44.3 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2026-05-11 04:24:08,621 - 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 13.71.44.3;
protocol bgp bgp_1{
local as 65000;
neighbor 13.71.44.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 13.71.44.3;
}
protocol bfd {
neighbor 13.71.44.1 local 13.71.44.3 multihop on;
}
EOS
2026-05-11 04:24:26,525 - DEBUG - None
2026-05-11 04:24:26,525 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 13.71.44.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 13.71.44.3;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2026-05-11 04:24:26,525 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 13.71.44.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 13.71.44.3;
}
EOS
2026-05-11 04:24:27,800 - DEBUG - None
2026-05-11 04:24:27,800 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2026-05-11 04:24:27,800 - DEBUG - service bird restart
2026-05-11 04:24:31,700 - DEBUG - bird stop/waiting
bird start/running, process 1440
2026-05-11 04:24:31,700 - INFO - Attaching VMI 78da2665-61c5-4272-9d1e-d8bb79e26168 to BGPaaS 4ba64880-b71a-4700-940e-7a88bf3cd89c
2026-05-11 04:24:31,781 - INFO - Attaching the VMI 61ed56f2-5ab4-4369-a5d4-0f05dc80a701 to the BGPaaS 4ba64880-b71a-4700-940e-7a88bf3cd89c object
2026-05-11 04:24:31,781 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 13.71.44.20 dev eth0, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2026-05-11 04:24:31,781 - DEBUG - sudo ip addr add 13.71.44.20 dev eth0
2026-05-11 04:24:32,642 - DEBUG - None
2026-05-11 04:24:32,701 - INFO - Creating BGPaaS ctest-TestBGPaasZone-40689383-61718096
2026-05-11 04:24:32,777 - INFO - BGPaaS: ctest-TestBGPaasZone-40689383-61718096(255be638-b30f-4302-b21b-4a4502b5f6fc)
2026-05-11 04:24:32,777 - DEBUG - Requesting: http://10.0.0.141:8082/bgp-as-a-service/255be638-b30f-4302-b21b-4a4502b5f6fc
2026-05-11 04:24:32,788 - INFO - verify_in_api_server passed for BGPaaS obj 255be638-b30f-4302-b21b-4a4502b5f6fc
2026-05-11 04:24:32,788 - INFO - BGPaaS(255be638-b30f-4302-b21b-4a4502b5f6fc): verify_on_setup passed
2026-05-11 04:24:32,788 - INFO - We will configure BGP on the VM
2026-05-11 04:24:33,201 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 04:24:33,201 - INFO - Configuring BGP on ctest-TestBGPaasZone-40689383-32338853
2026-05-11 04:24:33,201 - 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 13.71.44.4;
protocol bgp bgp_1{
local as 65000;
neighbor 13.71.44.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 13.71.44.4;
}
protocol bfd {
neighbor 13.71.44.1 local 13.71.44.4 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-05-11 04:24:33,201 - 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 13.71.44.4;
protocol bgp bgp_1{
local as 65000;
neighbor 13.71.44.1 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 13.71.44.4;
}
protocol bfd {
neighbor 13.71.44.1 local 13.71.44.4 multihop on;
}
EOS
2026-05-11 04:24:39,390 - DEBUG - None
2026-05-11 04:24:39,391 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 13.71.44.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 13.71.44.4;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-05-11 04:24:39,391 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 13.71.44.2 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 13.71.44.4;
}
EOS
2026-05-11 04:24:40,012 - DEBUG - None
2026-05-11 04:24:40,012 - 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-05-11 04:24:40,013 - DEBUG - service bird restart
2026-05-11 04:24:41,914 - DEBUG - bird stop/waiting
bird start/running, process 1444
2026-05-11 04:24:41,914 - INFO - Attaching VMI 008bcd2d-418a-4cfc-bb73-d66401a0b63b to BGPaaS 255be638-b30f-4302-b21b-4a4502b5f6fc
2026-05-11 04:24:41,997 - INFO - Attaching the VMI 59b700df-3865-406a-9348-bfcc54ccb26b to the BGPaaS 255be638-b30f-4302-b21b-4a4502b5f6fc object
2026-05-11 04:24:41,997 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 13.71.44.20 dev eth0, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.240, gateway password: c0ntrail123
2026-05-11 04:24:41,997 - DEBUG - sudo ip addr add 13.71.44.20 dev eth0
2026-05-11 04:24:42,784 - DEBUG - None
2026-05-11 04:24:42,784 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 04:24:42,799 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-05-11 04:24:42,799 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 04:24:42,822 - INFO - BGPaaS session seen in control-node 10.20.0.129
2026-05-11 04:24:42,829 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 04:24:42,840 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.193']
2026-05-11 04:24:48,855 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.193']
2026-05-11 04:24:54,870 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-05-11 04:24:54,871 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 04:24:54,889 - INFO - BGPaaS session seen in control-node 10.20.0.129
2026-05-11 04:24:54,896 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 13.71.44.20, host_string: ubuntu@169.254.0.6, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2026-05-11 04:24:54,896 - DEBUG - ping -s 56 -c 3 -W 1 13.71.44.20
2026-05-11 04:25:01,603 - DEBUG - PING 13.71.44.20 (13.71.44.20) 56(84) bytes of data.
64 bytes from 13.71.44.20: icmp_seq=1 ttl=64 time=11.1 ms
64 bytes from 13.71.44.20: icmp_seq=2 ttl=64 time=1.51 ms
64 bytes from 13.71.44.20: icmp_seq=3 ttl=64 time=1.41 ms
--- 13.71.44.20 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.412/4.701/11.177/4.579 ms
2026-05-11 04:25:01,603 - INFO - Ping to IP 13.71.44.20 from VM ctest-TestBGPaasZone-40689383-11437771 passed
2026-05-11 04:25:01,604 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 04:25:01,619 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-05-11 04:25:01,619 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 04:25:01,646 - INFO - BGPaaS session seen in control-node 10.20.0.129
2026-05-11 04:25:01,653 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 04:25:01,672 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-05-11 04:25:01,672 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-05-11 04:25:01,697 - INFO - BGPaaS session seen in control-node 10.20.0.129
2026-05-11 04:25:01,704 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 13.71.44.20, host_string: ubuntu@169.254.0.6, password: ubuntugateway: ubuntu@10.0.0.23, gateway password: c0ntrail123
2026-05-11 04:25:01,704 - DEBUG - ping -s 56 -c 3 -W 1 13.71.44.20
2026-05-11 04:25:04,281 - DEBUG - PING 13.71.44.20 (13.71.44.20) 56(84) bytes of data.
64 bytes from 13.71.44.20: icmp_seq=1 ttl=64 time=2.56 ms
64 bytes from 13.71.44.20: icmp_seq=2 ttl=64 time=1.74 ms
64 bytes from 13.71.44.20: icmp_seq=3 ttl=64 time=1.39 ms
--- 13.71.44.20 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.393/1.899/2.562/0.489 ms
2026-05-11 04:25:04,281 - INFO - Ping to IP 13.71.44.20 from VM ctest-TestBGPaasZone-40689383-11437771 passed
2026-05-11 04:25:04,281 - INFO - Detaching VMI 008bcd2d-418a-4cfc-bb73-d66401a0b63b from BGPaaS 255be638-b30f-4302-b21b-4a4502b5f6fc
2026-05-11 04:25:04,367 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-40689383-61718096(255be638-b30f-4302-b21b-4a4502b5f6fc)
2026-05-11 04:25:04,473 - DEBUG - Requesting: http://10.0.0.141:8082/bgp-as-a-service/255be638-b30f-4302-b21b-4a4502b5f6fc
2026-05-11 04:25:04,533 - DEBUG - Response Code: 404
2026-05-11 04:25:04,534 - DEBUG - BGPaaS: 255be638-b30f-4302-b21b-4a4502b5f6fc deleted from api server
2026-05-11 04:25:04,534 - INFO - BGPaaS(255be638-b30f-4302-b21b-4a4502b5f6fc): verify_on_cleanup passed
2026-05-11 04:25:04,534 - INFO - Detaching VMI 78da2665-61c5-4272-9d1e-d8bb79e26168 from BGPaaS 4ba64880-b71a-4700-940e-7a88bf3cd89c
2026-05-11 04:25:04,621 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-40689383-80628271(4ba64880-b71a-4700-940e-7a88bf3cd89c)
2026-05-11 04:25:04,695 - DEBUG - Requesting: http://10.0.0.141:8082/bgp-as-a-service/4ba64880-b71a-4700-940e-7a88bf3cd89c
2026-05-11 04:25:04,703 - DEBUG - Response Code: 404
2026-05-11 04:25:04,703 - DEBUG - BGPaaS: 4ba64880-b71a-4700-940e-7a88bf3cd89c deleted from api server
2026-05-11 04:25:04,703 - INFO - BGPaaS(4ba64880-b71a-4700-940e-7a88bf3cd89c): verify_on_cleanup passed
2026-05-11 04:25:04,703 - INFO - Deleting Control node zone ctest-test-zone-06934604(eff4c1e8-bccd-4059-a2b1-36c66696ee1d)
2026-05-11 04:25:04,857 - INFO - Deleting Control node zone ctest-test-zone-36926536(06af1710-384c-42b9-8ccb-2d149a5636c6)
2026-05-11 04:25:05,008 - INFO - Deleting Control node zone ctest-test-zone-52011978(8fd15e27-1abf-44b1-bdea-7e4d1845c2b0)
2026-05-11 04:25:05,162 - INFO - Deleting VM ctest-TestBGPaasZone-40689383-11437771
2026-05-11 04:25:05,246 - INFO - Deleting VM ctest-TestBGPaasZone-40689383-32338853
2026-05-11 04:25:05,322 - INFO - Deleting VM ctest-TestBGPaasZone-40689383-82819391
2026-05-11 04:25:05,459 - INFO - Deleting VN ctest-cnz_vn-00390004
2026-05-11 04:25:05,520 - DEBUG - VN f3abcf36-536b-4422-b44c-269e20f16090 still in use: Unable to complete operation on network f3abcf36-536b-4422-b44c-269e20f16090. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-f228bfc1-a835-42a7-b941-2a63ac0ccb63']
2026-05-11 04:25:05,520 - WARNING - Deleting VN ctest-cnz_vn-00390004 failed..Will retry
2026-05-11 04:25:07,722 - DEBUG - Response for deleting network ()
2026-05-11 04:25:09,040 - DEBUG - Skipping xmpp flap check
2026-05-11 04:25:09,040 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:57]
2026-05-11 04:25:09,041 - INFO - --------------------------------------------------------------------------------
2026-05-11 04:25:09,822 - INFO - Deleted project: ctest-TestBGPaasZone-40689383, ID : 00bca1a2-e68d-4fee-abbe-6007b3a6eaeb