is in ACTIVE state now
2026-04-06 04:20:53,698 - INFO - VM name : ctest-TestBGPaasZone-30492953-35211908
2026-04-06 04:20:53,789 - DEBUG - VM ctest-TestBGPaasZone-30492953-35211908 ID is d9cfdcbe-53af-48c5-a9bc-d936d98c694c
2026-04-06 04:20:53,789 - DEBUG - VM ctest-TestBGPaasZone-30492953-35211908 launched on Node an-jenkins-deploy-platform-ansible-os-5596-1
2026-04-06 04:20:53,875 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine/d9cfdcbe-53af-48c5-a9bc-d936d98c694c
2026-04-06 04:20:53,886 - DEBUG - Requesting: http://10.0.0.33:8082/virtual-machine-interface/d33d8911-7d49-4903-a12d-918b403260f6
2026-04-06 04:20:55,059 - DEBUG - (True, 'PING 169.254.0.5 (169.254.0.5) 56(84) bytes of data.\r\n64 bytes from 169.254.0.5: icmp_seq=1 ttl=63 time=3.83 ms\r\n64 bytes from 169.254.0.5: icmp_seq=2 ttl=63 time=0.572 ms\r\n\r\n--- 169.254.0.5 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.572/2.202/3.832/1.630 ms')
2026-04-06 04:20:55,060 - INFO - Ping to Metadata IP 169.254.0.5 of VM ctest-TestBGPaasZone-30492953-35211908 passed
2026-04-06 04:20:55,127 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-06 04:20:55,127 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-30492953-35211908, IP 5.113.215.69, Port 22
2026-04-06 04:20:55,304 - DEBUG - VM ctest-TestBGPaasZone-30492953-35211908 is ready for SSH connections
2026-04-06 04:20:55,447 - INFO - Created control node zone ctest-test-zone-63270842(0ecc57eb-1edc-4090-b29c-f25e653d9abb)
2026-04-06 04:20:55,815 - INFO - Created control node zone ctest-test-zone-19635293(64e144a8-91f9-4a99-8c36-0613cb0d1acf)
2026-04-06 04:20:56,187 - INFO - Created control node zone ctest-test-zone-52857379(96ce0241-ea22-4adc-acd6-4443378ca397)
2026-04-06 04:20:56,487 - INFO - Creating BGPaaS ctest-TestBGPaasZone-30492953-73122996
2026-04-06 04:20:56,563 - INFO - BGPaaS: ctest-TestBGPaasZone-30492953-73122996(11a86ac2-f502-429d-af89-0a8e86d75ecc)
2026-04-06 04:20:56,563 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/11a86ac2-f502-429d-af89-0a8e86d75ecc
2026-04-06 04:20:56,574 - INFO - verify_in_api_server passed for BGPaaS obj 11a86ac2-f502-429d-af89-0a8e86d75ecc
2026-04-06 04:20:56,576 - INFO - BGPaaS(11a86ac2-f502-429d-af89-0a8e86d75ecc): verify_on_setup passed
2026-04-06 04:20:56,576 - INFO - We will configure BGP on the VM
2026-04-06 04:20:57,039 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-06 04:20:57,039 - INFO - Configuring BGP on ctest-TestBGPaasZone-30492953-75236179
2026-04-06 04:20:57,039 - 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 5.113.215.67;
protocol bgp bgp_1{
local as 65000;
neighbor 5.113.215.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 5.113.215.67;
}
protocol bfd {
neighbor 5.113.215.65 local 5.113.215.67 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-06 04:20:57,039 - 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 5.113.215.67;
protocol bgp bgp_1{
local as 65000;
neighbor 5.113.215.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 5.113.215.67;
}
protocol bfd {
neighbor 5.113.215.65 local 5.113.215.67 multihop on;
}
EOS
2026-04-06 04:21:14,687 - DEBUG - None
2026-04-06 04:21:14,687 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 5.113.215.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 5.113.215.67;
}
EOS
, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-06 04:21:14,688 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 5.113.215.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 5.113.215.67;
}
EOS
2026-04-06 04:21:15,769 - DEBUG - None
2026-04-06 04:21:15,769 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-06 04:21:15,769 - DEBUG - service bird restart
2026-04-06 04:21:19,237 - DEBUG - bird stop/waiting
bird start/running, process 1429
2026-04-06 04:21:19,237 - INFO - Attaching VMI b62c9334-d2d2-491b-8730-b97fe60367e3 to BGPaaS 11a86ac2-f502-429d-af89-0a8e86d75ecc
2026-04-06 04:21:19,311 - INFO - Attaching the VMI 7e9ad10b-cc29-4d32-bf01-55b0be283779 to the BGPaaS 11a86ac2-f502-429d-af89-0a8e86d75ecc object
2026-04-06 04:21:19,311 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 5.113.215.84 dev eth0, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-06 04:21:19,311 - DEBUG - sudo ip addr add 5.113.215.84 dev eth0
2026-04-06 04:21:20,953 - DEBUG - None
2026-04-06 04:21:21,019 - INFO - Creating BGPaaS ctest-TestBGPaasZone-30492953-73541025
2026-04-06 04:21:21,103 - INFO - BGPaaS: ctest-TestBGPaasZone-30492953-73541025(73c80b8a-fd15-48b2-961f-1a2aa33f2461)
2026-04-06 04:21:21,103 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/73c80b8a-fd15-48b2-961f-1a2aa33f2461
2026-04-06 04:21:21,116 - INFO - verify_in_api_server passed for BGPaaS obj 73c80b8a-fd15-48b2-961f-1a2aa33f2461
2026-04-06 04:21:21,116 - INFO - BGPaaS(73c80b8a-fd15-48b2-961f-1a2aa33f2461): verify_on_setup passed
2026-04-06 04:21:21,117 - INFO - We will configure BGP on the VM
2026-04-06 04:21:21,547 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-06 04:21:21,547 - INFO - Configuring BGP on ctest-TestBGPaasZone-30492953-66015384
2026-04-06 04:21:21,547 - 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 5.113.215.68;
protocol bgp bgp_1{
local as 65000;
neighbor 5.113.215.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 5.113.215.68;
}
protocol bfd {
neighbor 5.113.215.65 local 5.113.215.68 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2026-04-06 04:21:21,547 - 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 5.113.215.68;
protocol bgp bgp_1{
local as 65000;
neighbor 5.113.215.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 5.113.215.68;
}
protocol bfd {
neighbor 5.113.215.65 local 5.113.215.68 multihop on;
}
EOS
2026-04-06 04:21:26,829 - DEBUG - None
2026-04-06 04:21:26,829 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 5.113.215.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 5.113.215.68;
}
EOS
, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2026-04-06 04:21:26,829 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 5.113.215.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 5.113.215.68;
}
EOS
2026-04-06 04:21:27,353 - DEBUG - None
2026-04-06 04:21:27,353 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2026-04-06 04:21:27,353 - DEBUG - service bird restart
2026-04-06 04:21:29,069 - DEBUG - bird stop/waiting
bird start/running, process 1439
2026-04-06 04:21:29,069 - INFO - Attaching VMI bf7702f4-565b-436c-9f4a-7a78987985ad to BGPaaS 73c80b8a-fd15-48b2-961f-1a2aa33f2461
2026-04-06 04:21:29,143 - INFO - Attaching the VMI 49d0a89d-6d53-42ac-aa2f-3d89636bd63f to the BGPaaS 73c80b8a-fd15-48b2-961f-1a2aa33f2461 object
2026-04-06 04:21:29,143 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 5.113.215.84 dev eth0, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.241, gateway password: c0ntrail123
2026-04-06 04:21:29,143 - DEBUG - sudo ip addr add 5.113.215.84 dev eth0
2026-04-06 04:21:29,880 - DEBUG - None
2026-04-06 04:21:29,880 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-06 04:21:29,898 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-04-06 04:21:29,901 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-06 04:21:29,928 - INFO - BGPaaS session seen in control-node 10.20.0.129
2026-04-06 04:21:29,933 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-06 04:21:29,955 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.193']
2026-04-06 04:21:35,968 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.193']
2026-04-06 04:21:41,982 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-04-06 04:21:41,983 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-06 04:21:41,997 - INFO - BGPaaS session seen in control-node 10.20.0.129
2026-04-06 04:21:42,003 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 5.113.215.84, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-06 04:21:42,003 - DEBUG - ping -s 56 -c 3 -W 1 5.113.215.84
2026-04-06 04:21:48,718 - DEBUG - PING 5.113.215.84 (5.113.215.84) 56(84) bytes of data.
64 bytes from 5.113.215.84: icmp_seq=1 ttl=64 time=10.8 ms
64 bytes from 5.113.215.84: icmp_seq=2 ttl=64 time=1.46 ms
64 bytes from 5.113.215.84: icmp_seq=3 ttl=64 time=1.16 ms
--- 5.113.215.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.160/4.481/10.817/4.481 ms
2026-04-06 04:21:48,718 - INFO - Ping to IP 5.113.215.84 from VM ctest-TestBGPaasZone-30492953-35211908 passed
2026-04-06 04:21:48,718 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-06 04:21:48,732 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-04-06 04:21:48,732 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-06 04:21:48,749 - INFO - BGPaaS session seen in control-node 10.20.0.129
2026-04-06 04:21:48,755 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-06 04:21:48,766 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-04-06 04:21:48,766 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-04-06 04:21:48,782 - INFO - BGPaaS session seen in control-node 10.20.0.129
2026-04-06 04:21:48,789 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 5.113.215.84, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.19, gateway password: c0ntrail123
2026-04-06 04:21:48,789 - DEBUG - ping -s 56 -c 3 -W 1 5.113.215.84
2026-04-06 04:21:51,352 - DEBUG - PING 5.113.215.84 (5.113.215.84) 56(84) bytes of data.
64 bytes from 5.113.215.84: icmp_seq=1 ttl=64 time=3.17 ms
64 bytes from 5.113.215.84: icmp_seq=2 ttl=64 time=2.39 ms
64 bytes from 5.113.215.84: icmp_seq=3 ttl=64 time=1.45 ms
--- 5.113.215.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.453/2.340/3.177/0.706 ms
2026-04-06 04:21:51,352 - INFO - Ping to IP 5.113.215.84 from VM ctest-TestBGPaasZone-30492953-35211908 passed
2026-04-06 04:21:51,352 - INFO - Detaching VMI bf7702f4-565b-436c-9f4a-7a78987985ad from BGPaaS 73c80b8a-fd15-48b2-961f-1a2aa33f2461
2026-04-06 04:21:51,439 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-30492953-73541025(73c80b8a-fd15-48b2-961f-1a2aa33f2461)
2026-04-06 04:21:51,510 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/73c80b8a-fd15-48b2-961f-1a2aa33f2461
2026-04-06 04:21:51,517 - DEBUG - Response Code: 404
2026-04-06 04:21:51,517 - DEBUG - BGPaaS: 73c80b8a-fd15-48b2-961f-1a2aa33f2461 deleted from api server
2026-04-06 04:21:51,518 - INFO - BGPaaS(73c80b8a-fd15-48b2-961f-1a2aa33f2461): verify_on_cleanup passed
2026-04-06 04:21:51,518 - INFO - Detaching VMI b62c9334-d2d2-491b-8730-b97fe60367e3 from BGPaaS 11a86ac2-f502-429d-af89-0a8e86d75ecc
2026-04-06 04:21:51,623 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-30492953-73122996(11a86ac2-f502-429d-af89-0a8e86d75ecc)
2026-04-06 04:21:51,715 - DEBUG - Requesting: http://10.0.0.249:8082/bgp-as-a-service/11a86ac2-f502-429d-af89-0a8e86d75ecc
2026-04-06 04:21:51,724 - DEBUG - Response Code: 404
2026-04-06 04:21:51,724 - DEBUG - BGPaaS: 11a86ac2-f502-429d-af89-0a8e86d75ecc deleted from api server
2026-04-06 04:21:51,724 - INFO - BGPaaS(11a86ac2-f502-429d-af89-0a8e86d75ecc): verify_on_cleanup passed
2026-04-06 04:21:51,724 - INFO - Deleting Control node zone ctest-test-zone-52857379(96ce0241-ea22-4adc-acd6-4443378ca397)
2026-04-06 04:21:51,895 - INFO - Deleting Control node zone ctest-test-zone-19635293(64e144a8-91f9-4a99-8c36-0613cb0d1acf)
2026-04-06 04:21:52,042 - INFO - Deleting Control node zone ctest-test-zone-63270842(0ecc57eb-1edc-4090-b29c-f25e653d9abb)
2026-04-06 04:21:52,200 - INFO - Deleting VM ctest-TestBGPaasZone-30492953-35211908
2026-04-06 04:21:52,286 - INFO - Deleting VM ctest-TestBGPaasZone-30492953-66015384
2026-04-06 04:21:52,350 - INFO - Deleting VM ctest-TestBGPaasZone-30492953-75236179
2026-04-06 04:21:52,423 - INFO - Deleting VN ctest-cnz_vn-34534267
2026-04-06 04:21:52,476 - DEBUG - VN 6e292f54-3ea3-4046-87a0-8f4f84552771 still in use: Unable to complete operation on network 6e292f54-3ea3-4046-87a0-8f4f84552771. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-3067f0be-de97-4973-8875-4fa3ad0ec9af']
2026-04-06 04:21:52,476 - WARNING - Deleting VN ctest-cnz_vn-34534267 failed..Will retry
2026-04-06 04:21:54,671 - DEBUG - Response for deleting network ()
2026-04-06 04:21:55,935 - DEBUG - Skipping xmpp flap check
2026-04-06 04:21:55,935 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:34]
2026-04-06 04:21:55,935 - INFO - --------------------------------------------------------------------------------
2026-04-06 04:21:56,790 - INFO - Deleted project: ctest-TestBGPaasZone-30492953, ID : c14b70a4-5600-4927-b0ee-d6e2a537d5a0