is in ACTIVE state now
2026-06-15 03:31:46,237 - INFO - VM name : ctest-TestBGPaasZone-64705949-49023426
2026-06-15 03:31:46,326 - DEBUG - VM ctest-TestBGPaasZone-64705949-49023426 ID is 8fa54944-a072-48cc-b250-097d7d5a952a
2026-06-15 03:31:46,326 - DEBUG - VM ctest-TestBGPaasZone-64705949-49023426 launched on Node an-jenkins-deploy-platform-ansible-os-5940-2
2026-06-15 03:31:46,430 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine/8fa54944-a072-48cc-b250-097d7d5a952a
2026-06-15 03:31:46,449 - DEBUG - Requesting: http://10.0.0.65:8082/virtual-machine-interface/dece2ac4-3781-4a72-8815-067b7fbe3883
2026-06-15 03:31:47,629 - 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.36 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.355/2.637/3.920/1.282 ms')
2026-06-15 03:31:47,630 - INFO - Ping to Metadata IP 169.254.0.6 of VM ctest-TestBGPaasZone-64705949-49023426 passed
2026-06-15 03:31:47,696 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-15 03:31:47,696 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-64705949-49023426, IP 121.11.154.133, Port 22
2026-06-15 03:31:47,871 - DEBUG - VM ctest-TestBGPaasZone-64705949-49023426 is ready for SSH connections
2026-06-15 03:31:48,016 - INFO - Created control node zone ctest-test-zone-73524795(c284b94c-0763-47b7-981e-e57b2e7e0338)
2026-06-15 03:31:48,376 - INFO - Created control node zone ctest-test-zone-37035092(c3b1faa3-1a94-4305-8550-4672bf219e2c)
2026-06-15 03:31:48,740 - INFO - Created control node zone ctest-test-zone-23398236(6ee159ec-c050-424e-9adf-a8390f049bf9)
2026-06-15 03:31:49,032 - INFO - Creating BGPaaS ctest-TestBGPaasZone-64705949-72498793
2026-06-15 03:31:49,108 - INFO - BGPaaS: ctest-TestBGPaasZone-64705949-72498793(a28fa92b-4eee-4481-b454-8c448b4184e7)
2026-06-15 03:31:49,108 - DEBUG - Requesting: http://10.0.0.23:8082/bgp-as-a-service/a28fa92b-4eee-4481-b454-8c448b4184e7
2026-06-15 03:31:49,121 - INFO - verify_in_api_server passed for BGPaaS obj a28fa92b-4eee-4481-b454-8c448b4184e7
2026-06-15 03:31:49,121 - INFO - BGPaaS(a28fa92b-4eee-4481-b454-8c448b4184e7): verify_on_setup passed
2026-06-15 03:31:49,121 - INFO - We will configure BGP on the VM
2026-06-15 03:31:49,556 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-15 03:31:49,556 - INFO - Configuring BGP on ctest-TestBGPaasZone-64705949-71857132
2026-06-15 03:31:49,556 - 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 121.11.154.131;
protocol bgp bgp_1{
local as 65000;
neighbor 121.11.154.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 121.11.154.131;
}
protocol bfd {
neighbor 121.11.154.129 local 121.11.154.131 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123
2026-06-15 03:31:49,556 - 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 121.11.154.131;
protocol bgp bgp_1{
local as 65000;
neighbor 121.11.154.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 121.11.154.131;
}
protocol bfd {
neighbor 121.11.154.129 local 121.11.154.131 multihop on;
}
EOS
2026-06-15 03:31:58,392 - DEBUG - None
2026-06-15 03:31:58,393 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 121.11.154.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 121.11.154.131;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123
2026-06-15 03:31:58,393 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 121.11.154.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 121.11.154.131;
}
EOS
2026-06-15 03:31:59,091 - DEBUG - None
2026-06-15 03:31:59,091 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123
2026-06-15 03:31:59,092 - DEBUG - service bird restart
2026-06-15 03:32:00,643 - DEBUG - bird stop/waiting
bird start/running, process 1426
2026-06-15 03:32:00,643 - INFO - Attaching VMI b9bbd4a9-e2f9-4fce-87df-1fda26a95f95 to BGPaaS a28fa92b-4eee-4481-b454-8c448b4184e7
2026-06-15 03:32:00,720 - INFO - Attaching the VMI 51eeb81a-7e64-4910-bd8e-e8700b8a225d to the BGPaaS a28fa92b-4eee-4481-b454-8c448b4184e7 object
2026-06-15 03:32:00,720 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 121.11.154.148 dev eth0, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123
2026-06-15 03:32:00,720 - DEBUG - sudo ip addr add 121.11.154.148 dev eth0
2026-06-15 03:32:01,505 - DEBUG - None
2026-06-15 03:32:01,572 - INFO - Creating BGPaaS ctest-TestBGPaasZone-64705949-20326326
2026-06-15 03:32:01,656 - INFO - BGPaaS: ctest-TestBGPaasZone-64705949-20326326(64b66081-45f3-4234-b59b-d7c91094bcec)
2026-06-15 03:32:01,656 - DEBUG - Requesting: http://10.0.0.23:8082/bgp-as-a-service/64b66081-45f3-4234-b59b-d7c91094bcec
2026-06-15 03:32:01,677 - INFO - verify_in_api_server passed for BGPaaS obj 64b66081-45f3-4234-b59b-d7c91094bcec
2026-06-15 03:32:01,678 - INFO - BGPaaS(64b66081-45f3-4234-b59b-d7c91094bcec): verify_on_setup passed
2026-06-15 03:32:01,678 - INFO - We will configure BGP on the VM
2026-06-15 03:32:02,164 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-15 03:32:02,164 - INFO - Configuring BGP on ctest-TestBGPaasZone-64705949-40862071
2026-06-15 03:32:02,164 - 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 121.11.154.132;
protocol bgp bgp_1{
local as 65000;
neighbor 121.11.154.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 121.11.154.132;
}
protocol bfd {
neighbor 121.11.154.129 local 121.11.154.132 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.141, gateway password: c0ntrail123
2026-06-15 03:32:02,164 - 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 121.11.154.132;
protocol bgp bgp_1{
local as 65000;
neighbor 121.11.154.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 121.11.154.132;
}
protocol bfd {
neighbor 121.11.154.129 local 121.11.154.132 multihop on;
}
EOS
2026-06-15 03:32:05,217 - DEBUG - None
2026-06-15 03:32:05,218 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 121.11.154.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 121.11.154.132;
}
EOS
, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.141, gateway password: c0ntrail123
2026-06-15 03:32:05,218 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 121.11.154.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 121.11.154.132;
}
EOS
2026-06-15 03:32:05,480 - DEBUG - None
2026-06-15 03:32:05,480 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.141, gateway password: c0ntrail123
2026-06-15 03:32:05,480 - DEBUG - service bird restart
2026-06-15 03:32:06,225 - DEBUG - bird stop/waiting
bird start/running, process 1484
2026-06-15 03:32:06,226 - INFO - Attaching VMI 4941ee87-94ef-4595-bd99-417e058f0af4 to BGPaaS 64b66081-45f3-4234-b59b-d7c91094bcec
2026-06-15 03:32:06,300 - INFO - Attaching the VMI 1dea6a8f-6e1a-495d-9c59-98a454da98f9 to the BGPaaS 64b66081-45f3-4234-b59b-d7c91094bcec object
2026-06-15 03:32:06,300 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 121.11.154.148 dev eth0, host_string: ubuntu@169.254.0.5, password: ubuntugateway: ubuntu@10.0.0.141, gateway password: c0ntrail123
2026-06-15 03:32:06,300 - DEBUG - sudo ip addr add 121.11.154.148 dev eth0
2026-06-15 03:32:06,576 - DEBUG - None
2026-06-15 03:32:06,576 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-15 03:32:06,577 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-06-15 03:32:06,592 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25']
2026-06-15 03:32:12,592 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-06-15 03:32:12,606 - INFO - BGPaaS session seen in control-node 10.20.0.25
2026-06-15 03:32:12,607 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-15 03:32:12,607 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-06-15 03:32:12,637 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-06-15 03:32:12,643 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-15 03:32:12,643 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-06-15 03:32:12,657 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25']
2026-06-15 03:32:18,658 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-06-15 03:32:18,674 - INFO - BGPaaS session seen in control-node 10.20.0.25
2026-06-15 03:32:18,674 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-15 03:32:18,674 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-06-15 03:32:18,689 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-06-15 03:32:18,696 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 121.11.154.148, host_string: ubuntu@169.254.0.6, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123
2026-06-15 03:32:18,696 - DEBUG - ping -s 56 -c 3 -W 1 121.11.154.148
2026-06-15 03:32:22,812 - DEBUG - PING 121.11.154.148 (121.11.154.148) 56(84) bytes of data.
64 bytes from 121.11.154.148: icmp_seq=1 ttl=64 time=7.25 ms
64 bytes from 121.11.154.148: icmp_seq=2 ttl=64 time=1.66 ms
64 bytes from 121.11.154.148: icmp_seq=3 ttl=64 time=1.31 ms
--- 121.11.154.148 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 1.313/3.410/7.255/2.722 ms
2026-06-15 03:32:22,812 - INFO - Ping to IP 121.11.154.148 from VM ctest-TestBGPaasZone-64705949-49023426 passed
2026-06-15 03:32:22,812 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-15 03:32:22,812 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-06-15 03:32:22,828 - INFO - BGPaaS session seen in control-node 10.20.0.25
2026-06-15 03:32:22,828 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-15 03:32:22,828 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-06-15 03:32:22,844 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-06-15 03:32:22,852 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-15 03:32:22,852 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-06-15 03:32:22,869 - INFO - BGPaaS session seen in control-node 10.20.0.25
2026-06-15 03:32:22,869 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2026-06-15 03:32:22,869 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address=
2026-06-15 03:32:22,886 - INFO - BGPaaS session seen in control-node 10.20.0.193
2026-06-15 03:32:22,892 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 121.11.154.148, host_string: ubuntu@169.254.0.6, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123
2026-06-15 03:32:22,893 - DEBUG - ping -s 56 -c 3 -W 1 121.11.154.148
2026-06-15 03:32:25,168 - DEBUG - PING 121.11.154.148 (121.11.154.148) 56(84) bytes of data.
64 bytes from 121.11.154.148: icmp_seq=1 ttl=64 time=2.10 ms
64 bytes from 121.11.154.148: icmp_seq=2 ttl=64 time=1.06 ms
64 bytes from 121.11.154.148: icmp_seq=3 ttl=64 time=1.68 ms
--- 121.11.154.148 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.069/1.621/2.108/0.426 ms
2026-06-15 03:32:25,168 - INFO - Ping to IP 121.11.154.148 from VM ctest-TestBGPaasZone-64705949-49023426 passed
2026-06-15 03:32:25,168 - INFO - Detaching VMI 4941ee87-94ef-4595-bd99-417e058f0af4 from BGPaaS 64b66081-45f3-4234-b59b-d7c91094bcec
2026-06-15 03:32:25,244 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-64705949-20326326(64b66081-45f3-4234-b59b-d7c91094bcec)
2026-06-15 03:32:25,297 - DEBUG - Requesting: http://10.0.0.23:8082/bgp-as-a-service/64b66081-45f3-4234-b59b-d7c91094bcec
2026-06-15 03:32:25,307 - DEBUG - Response Code: 404
2026-06-15 03:32:25,307 - DEBUG - BGPaaS: 64b66081-45f3-4234-b59b-d7c91094bcec deleted from api server
2026-06-15 03:32:25,307 - INFO - BGPaaS(64b66081-45f3-4234-b59b-d7c91094bcec): verify_on_cleanup passed
2026-06-15 03:32:25,307 - INFO - Detaching VMI b9bbd4a9-e2f9-4fce-87df-1fda26a95f95 from BGPaaS a28fa92b-4eee-4481-b454-8c448b4184e7
2026-06-15 03:32:25,400 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-64705949-72498793(a28fa92b-4eee-4481-b454-8c448b4184e7)
2026-06-15 03:32:25,453 - DEBUG - Requesting: http://10.0.0.23:8082/bgp-as-a-service/a28fa92b-4eee-4481-b454-8c448b4184e7
2026-06-15 03:32:25,463 - DEBUG - Response Code: 404
2026-06-15 03:32:25,463 - DEBUG - BGPaaS: a28fa92b-4eee-4481-b454-8c448b4184e7 deleted from api server
2026-06-15 03:32:25,463 - INFO - BGPaaS(a28fa92b-4eee-4481-b454-8c448b4184e7): verify_on_cleanup passed
2026-06-15 03:32:25,463 - INFO - Deleting Control node zone ctest-test-zone-23398236(6ee159ec-c050-424e-9adf-a8390f049bf9)
2026-06-15 03:32:25,623 - INFO - Deleting Control node zone ctest-test-zone-37035092(c3b1faa3-1a94-4305-8550-4672bf219e2c)
2026-06-15 03:32:25,761 - INFO - Deleting Control node zone ctest-test-zone-73524795(c284b94c-0763-47b7-981e-e57b2e7e0338)
2026-06-15 03:32:25,904 - INFO - Deleting VM ctest-TestBGPaasZone-64705949-49023426
2026-06-15 03:32:25,975 - INFO - Deleting VM ctest-TestBGPaasZone-64705949-40862071
2026-06-15 03:32:26,046 - INFO - Deleting VM ctest-TestBGPaasZone-64705949-71857132
2026-06-15 03:32:26,111 - INFO - Deleting VN ctest-cnz_vn-60155354
2026-06-15 03:32:26,149 - DEBUG - VN 99dd14dd-843e-4732-8066-a83aaf85234c still in use: Unable to complete operation on network 99dd14dd-843e-4732-8066-a83aaf85234c. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-cc8ac9c6-5df1-431d-970e-8d1dbf6acfff']
2026-06-15 03:32:26,149 - WARNING - Deleting VN ctest-cnz_vn-60155354 failed..Will retry
2026-06-15 03:32:28,266 - DEBUG - VN 99dd14dd-843e-4732-8066-a83aaf85234c still in use: Unable to complete operation on network 99dd14dd-843e-4732-8066-a83aaf85234c. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-aa8ec64a-60f5-48cb-81ef-fdcff4d45428']
2026-06-15 03:32:28,266 - WARNING - Deleting VN ctest-cnz_vn-60155354 failed..Will retry
2026-06-15 03:32:30,432 - DEBUG - Response for deleting network ()
2026-06-15 03:32:31,690 - DEBUG - Skipping xmpp flap check
2026-06-15 03:32:31,690 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:02:48]
2026-06-15 03:32:31,690 - INFO - --------------------------------------------------------------------------------
2026-06-15 03:32:32,476 - INFO - Deleted project: ctest-TestBGPaasZone-64705949, ID : 36435154-a313-4099-a5f2-4f323ef4462a