is in ACTIVE state now
2025-08-11 17:50:41,146 - INFO - VM name : ctest-TestBGPaasZone-22781225-64215591
2025-08-11 17:50:41,222 - DEBUG - VM ctest-TestBGPaasZone-22781225-64215591 ID is b7ec02cc-6c8a-4bc7-adc4-e09f06675dec
2025-08-11 17:50:41,222 - DEBUG - VM ctest-TestBGPaasZone-22781225-64215591 launched on Node an-jenkins-deploy-platform-ansible-os-3816-1
2025-08-11 17:50:41,304 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine/b7ec02cc-6c8a-4bc7-adc4-e09f06675dec
2025-08-11 17:50:41,313 - DEBUG - Requesting: http://10.0.0.19:8082/virtual-machine-interface/05be7bff-0fd7-4ca3-b893-6a21c4b4fa31
2025-08-11 17:50:42,509 - 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.26 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=2.64 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.636/2.950/3.264/0.314 ms')
2025-08-11 17:50:42,509 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-22781225-64215591 passed
2025-08-11 17:50:42,622 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 17:50:42,622 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-22781225-64215591, IP 133.188.65.197, Port 22
2025-08-11 17:50:42,682 - 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': ''}
2025-08-11 17:50:42,748 - DEBUG - VM ctest-TestBGPaasZone-22781225-64215591 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-11 17:50:47,748 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 17:50:47,748 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-22781225-64215591, IP 133.188.65.197, Port 22
2025-08-11 17:50:47,921 - DEBUG - VM ctest-TestBGPaasZone-22781225-64215591 is ready for SSH connections
2025-08-11 17:50:48,050 - INFO - Created control node zone ctest-test-zone-67206779(64c7bad2-debf-4a79-ab50-1f36a1c01ff6)
2025-08-11 17:50:48,398 - INFO - Created control node zone ctest-test-zone-02552350(c62bd4ce-623f-4ad3-9b82-43a51de479eb)
2025-08-11 17:50:48,738 - INFO - Created control node zone ctest-test-zone-54207669(787f85dd-5b7d-4c4f-8bc7-c8f50a87233f)
2025-08-11 17:50:49,010 - INFO - Creating BGPaaS ctest-TestBGPaasZone-22781225-97981399
2025-08-11 17:50:49,082 - INFO - BGPaaS: ctest-TestBGPaasZone-22781225-97981399(8d3252e0-021b-4f71-bc86-1bd989b6e5ed)
2025-08-11 17:50:49,083 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/8d3252e0-021b-4f71-bc86-1bd989b6e5ed
2025-08-11 17:50:49,093 - INFO - verify_in_api_server passed for BGPaaS obj 8d3252e0-021b-4f71-bc86-1bd989b6e5ed
2025-08-11 17:50:49,093 - INFO - BGPaaS(8d3252e0-021b-4f71-bc86-1bd989b6e5ed): verify_on_setup passed
2025-08-11 17:50:49,093 - INFO - We will configure BGP on the VM
2025-08-11 17:50:49,510 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 17:50:49,510 - INFO - Configuring BGP on ctest-TestBGPaasZone-22781225-56993254
2025-08-11 17:50:49,511 - 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 133.188.65.195;
protocol bgp bgp_1{
local as 65000;
neighbor 133.188.65.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 133.188.65.195;
}
protocol bfd {
neighbor 133.188.65.193 local 133.188.65.195 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.141, gateway password: c0ntrail123
2025-08-11 17:50:49,511 - 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 133.188.65.195;
protocol bgp bgp_1{
local as 65000;
neighbor 133.188.65.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 133.188.65.195;
}
protocol bfd {
neighbor 133.188.65.193 local 133.188.65.195 multihop on;
}
EOS
2025-08-11 17:50:56,671 - DEBUG - None
2025-08-11 17:50:56,671 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 133.188.65.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 133.188.65.195;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.141, gateway password: c0ntrail123
2025-08-11 17:50:56,671 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 133.188.65.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 133.188.65.195;
}
EOS
2025-08-11 17:50:57,154 - DEBUG - None
2025-08-11 17:50:57,155 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.141, gateway password: c0ntrail123
2025-08-11 17:50:57,155 - DEBUG - service bird restart
2025-08-11 17:50:58,739 - DEBUG - bird stop/waiting
bird start/running, process 1437
2025-08-11 17:50:58,740 - INFO - Attaching VMI 5734ea67-c066-4a41-9aeb-5f288b261ce9 to BGPaaS 8d3252e0-021b-4f71-bc86-1bd989b6e5ed
2025-08-11 17:50:58,810 - INFO - Attaching the VMI ee91ef5d-6b29-4df2-b44a-8b792b832a06 to the BGPaaS 8d3252e0-021b-4f71-bc86-1bd989b6e5ed object
2025-08-11 17:50:58,810 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 133.188.65.212 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.141, gateway password: c0ntrail123
2025-08-11 17:50:58,811 - DEBUG - sudo ip addr add 133.188.65.212 dev eth0
2025-08-11 17:50:59,447 - DEBUG - None
2025-08-11 17:50:59,506 - INFO - Creating BGPaaS ctest-TestBGPaasZone-22781225-18296070
2025-08-11 17:50:59,574 - INFO - BGPaaS: ctest-TestBGPaasZone-22781225-18296070(ba741a84-002b-4e45-9622-5ba5989de3b8)
2025-08-11 17:50:59,574 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/ba741a84-002b-4e45-9622-5ba5989de3b8
2025-08-11 17:50:59,583 - INFO - verify_in_api_server passed for BGPaaS obj ba741a84-002b-4e45-9622-5ba5989de3b8
2025-08-11 17:50:59,583 - INFO - BGPaaS(ba741a84-002b-4e45-9622-5ba5989de3b8): verify_on_setup passed
2025-08-11 17:50:59,583 - INFO - We will configure BGP on the VM
2025-08-11 17:50:59,978 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 17:50:59,978 - INFO - Configuring BGP on ctest-TestBGPaasZone-22781225-89608325
2025-08-11 17:50:59,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 133.188.65.196;
protocol bgp bgp_1{
local as 65000;
neighbor 133.188.65.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 133.188.65.196;
}
protocol bfd {
neighbor 133.188.65.193 local 133.188.65.196 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123
2025-08-11 17:50:59,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 133.188.65.196;
protocol bgp bgp_1{
local as 65000;
neighbor 133.188.65.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 133.188.65.196;
}
protocol bfd {
neighbor 133.188.65.193 local 133.188.65.196 multihop on;
}
EOS
2025-08-11 17:51:04,864 - DEBUG - None
2025-08-11 17:51:04,864 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 133.188.65.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 133.188.65.196;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123
2025-08-11 17:51:04,864 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 133.188.65.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 133.188.65.196;
}
EOS
2025-08-11 17:51:05,347 - DEBUG - None
2025-08-11 17:51:05,347 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123
2025-08-11 17:51:05,347 - DEBUG - service bird restart
2025-08-11 17:51:06,932 - DEBUG - bird stop/waiting
bird start/running, process 1480
2025-08-11 17:51:06,932 - INFO - Attaching VMI 9d18da60-fbe9-4714-9648-4f5e507afdcd to BGPaaS ba741a84-002b-4e45-9622-5ba5989de3b8
2025-08-11 17:51:07,006 - INFO - Attaching the VMI c2f85155-5c94-461a-a5e7-58482f163a3e to the BGPaaS ba741a84-002b-4e45-9622-5ba5989de3b8 object
2025-08-11 17:51:07,007 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 133.188.65.212 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.249, gateway password: c0ntrail123
2025-08-11 17:51:07,007 - DEBUG - sudo ip addr add 133.188.65.212 dev eth0
2025-08-11 17:51:07,612 - DEBUG - None
2025-08-11 17:51:07,612 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 17:51:07,625 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.193']
2025-08-11 17:51:13,640 - INFO - BGPaaS session seen in control-node 10.20.0.193
2025-08-11 17:51:13,640 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 17:51:13,640 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-11 17:51:13,654 - INFO - BGPaaS session seen in control-node 10.20.0.129
2025-08-11 17:51:13,660 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 17:51:13,673 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.193']
2025-08-11 17:51:19,688 - INFO - BGPaaS session seen in control-node 10.20.0.193
2025-08-11 17:51:19,688 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 17:51:19,688 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-11 17:51:19,703 - INFO - BGPaaS session seen in control-node 10.20.0.129
2025-08-11 17:51:19,709 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 133.188.65.212, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.141, gateway password: c0ntrail123
2025-08-11 17:51:19,709 - DEBUG - ping -s 56 -c 3 -W 1 133.188.65.212
2025-08-11 17:51:25,770 - DEBUG - PING 133.188.65.212 (133.188.65.212) 56(84) bytes of data.
64 bytes from 133.188.65.212: icmp_seq=1 ttl=64 time=9.55 ms
64 bytes from 133.188.65.212: icmp_seq=2 ttl=64 time=1.16 ms
64 bytes from 133.188.65.212: icmp_seq=3 ttl=64 time=1.35 ms
--- 133.188.65.212 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.167/4.025/9.550/3.907 ms
2025-08-11 17:51:25,771 - INFO - Ping to IP 133.188.65.212 from VM ctest-TestBGPaasZone-22781225-64215591 passed
2025-08-11 17:51:25,771 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 17:51:25,786 - INFO - BGPaaS session seen in control-node 10.20.0.193
2025-08-11 17:51:25,786 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 17:51:25,786 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-11 17:51:25,801 - INFO - BGPaaS session seen in control-node 10.20.0.129
2025-08-11 17:51:25,807 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 17:51:25,819 - INFO - BGPaaS session seen in control-node 10.20.0.193
2025-08-11 17:51:25,819 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-11 17:51:25,819 - DEBUG - Requesting: http://10.20.0.129:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-11 17:51:25,833 - INFO - BGPaaS session seen in control-node 10.20.0.129
2025-08-11 17:51:25,839 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 133.188.65.212, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.141, gateway password: c0ntrail123
2025-08-11 17:51:25,839 - DEBUG - ping -s 56 -c 3 -W 1 133.188.65.212
2025-08-11 17:51:28,290 - DEBUG - PING 133.188.65.212 (133.188.65.212) 56(84) bytes of data.
64 bytes from 133.188.65.212: icmp_seq=1 ttl=64 time=5.13 ms
64 bytes from 133.188.65.212: icmp_seq=2 ttl=64 time=1.14 ms
64 bytes from 133.188.65.212: icmp_seq=3 ttl=64 time=0.965 ms
--- 133.188.65.212 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 0.965/2.416/5.138/1.926 ms
2025-08-11 17:51:28,290 - INFO - Ping to IP 133.188.65.212 from VM ctest-TestBGPaasZone-22781225-64215591 passed
2025-08-11 17:51:28,290 - INFO - Detaching VMI 9d18da60-fbe9-4714-9648-4f5e507afdcd from BGPaaS ba741a84-002b-4e45-9622-5ba5989de3b8
2025-08-11 17:51:28,362 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-22781225-18296070(ba741a84-002b-4e45-9622-5ba5989de3b8)
2025-08-11 17:51:28,432 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/ba741a84-002b-4e45-9622-5ba5989de3b8
2025-08-11 17:51:28,440 - DEBUG - Response Code: 404
2025-08-11 17:51:28,440 - DEBUG - BGPaaS: ba741a84-002b-4e45-9622-5ba5989de3b8 deleted from api server
2025-08-11 17:51:28,440 - INFO - BGPaaS(ba741a84-002b-4e45-9622-5ba5989de3b8): verify_on_cleanup passed
2025-08-11 17:51:28,440 - INFO - Detaching VMI 5734ea67-c066-4a41-9aeb-5f288b261ce9 from BGPaaS 8d3252e0-021b-4f71-bc86-1bd989b6e5ed
2025-08-11 17:51:28,542 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-22781225-97981399(8d3252e0-021b-4f71-bc86-1bd989b6e5ed)
2025-08-11 17:51:28,613 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/8d3252e0-021b-4f71-bc86-1bd989b6e5ed
2025-08-11 17:51:28,632 - DEBUG - Response Code: 404
2025-08-11 17:51:28,632 - DEBUG - BGPaaS: 8d3252e0-021b-4f71-bc86-1bd989b6e5ed deleted from api server
2025-08-11 17:51:28,632 - INFO - BGPaaS(8d3252e0-021b-4f71-bc86-1bd989b6e5ed): verify_on_cleanup passed
2025-08-11 17:51:28,632 - INFO - Deleting Control node zone ctest-test-zone-54207669(787f85dd-5b7d-4c4f-8bc7-c8f50a87233f)
2025-08-11 17:51:28,780 - INFO - Deleting Control node zone ctest-test-zone-02552350(c62bd4ce-623f-4ad3-9b82-43a51de479eb)
2025-08-11 17:51:28,936 - INFO - Deleting Control node zone ctest-test-zone-67206779(64c7bad2-debf-4a79-ab50-1f36a1c01ff6)
2025-08-11 17:51:29,079 - INFO - Deleting VM ctest-TestBGPaasZone-22781225-64215591
2025-08-11 17:51:29,143 - INFO - Deleting VM ctest-TestBGPaasZone-22781225-89608325
2025-08-11 17:51:29,199 - INFO - Deleting VM ctest-TestBGPaasZone-22781225-56993254
2025-08-11 17:51:29,256 - INFO - Deleting VN ctest-cnz_vn-00034516
2025-08-11 17:51:29,293 - DEBUG - VN cd9f728c-1646-44e3-9700-32e8bc1e3099 still in use: Unable to complete operation on network cd9f728c-1646-44e3-9700-32e8bc1e3099. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-9c0d6969-bf46-4766-99d3-017ab629d329']
2025-08-11 17:51:29,293 - WARNING - Deleting VN ctest-cnz_vn-00034516 failed..Will retry
2025-08-11 17:51:31,458 - DEBUG - Response for deleting network ()
2025-08-11 17:51:32,698 - DEBUG - Skipping xmpp flap check
2025-08-11 17:51:32,698 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:02:47]
2025-08-11 17:51:32,698 - INFO - --------------------------------------------------------------------------------
2025-08-11 17:51:33,396 - INFO - Deleted project: ctest-TestBGPaasZone-22781225, ID : dbfd353b-bf5b-4f7d-a533-f313a022e76e