is in ACTIVE state now
2025-10-02 03:38:07,489 - INFO - VM name : ctest-TestBGPaasZone-83084478-34779767
2025-10-02 03:38:07,578 - DEBUG - VM ctest-TestBGPaasZone-83084478-34779767 ID is a1f4a5de-79ba-4cd6-bf25-fadfe417d050
2025-10-02 03:38:07,578 - DEBUG - VM ctest-TestBGPaasZone-83084478-34779767 launched on Node an-jenkins-deploy-platform-ansible-os-4210-1
2025-10-02 03:38:07,669 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine/a1f4a5de-79ba-4cd6-bf25-fadfe417d050
2025-10-02 03:38:07,681 - DEBUG - Requesting: http://10.0.0.49:8082/virtual-machine-interface/fbee9fec-8ff3-476b-9302-7be7869b973c
2025-10-02 03:38:08,850 - 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.25 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.783 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 = 0.783/2.015/3.247/1.232 ms')
2025-10-02 03:38:08,850 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-83084478-34779767 passed
2025-10-02 03:38:08,919 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-02 03:38:08,919 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-83084478-34779767, IP 46.195.94.133, Port 22
2025-10-02 03:38:09,198 - DEBUG - VM ctest-TestBGPaasZone-83084478-34779767 is ready for SSH connections
2025-10-02 03:38:09,334 - INFO - Created control node zone ctest-test-zone-45202178(462d0675-1ac8-4e1d-91e6-a0c1ef21e46e)
2025-10-02 03:38:09,687 - INFO - Created control node zone ctest-test-zone-53719591(eb843210-e00b-4e18-a718-6fcf2a7272ec)
2025-10-02 03:38:10,038 - INFO - Created control node zone ctest-test-zone-03003161(5bb2b555-22f5-4848-b7fb-22326dfeade1)
2025-10-02 03:38:10,322 - INFO - Creating BGPaaS ctest-TestBGPaasZone-83084478-35030579
2025-10-02 03:38:10,394 - INFO - BGPaaS: ctest-TestBGPaasZone-83084478-35030579(02a5bb1d-2587-4014-b8a8-0179cd0f9d56)
2025-10-02 03:38:10,395 - DEBUG - Requesting: http://10.0.0.56:8082/bgp-as-a-service/02a5bb1d-2587-4014-b8a8-0179cd0f9d56
2025-10-02 03:38:10,404 - INFO - verify_in_api_server passed for BGPaaS obj 02a5bb1d-2587-4014-b8a8-0179cd0f9d56
2025-10-02 03:38:10,404 - INFO - BGPaaS(02a5bb1d-2587-4014-b8a8-0179cd0f9d56): verify_on_setup passed
2025-10-02 03:38:10,404 - INFO - We will configure BGP on the VM
2025-10-02 03:38:10,834 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-02 03:38:10,835 - INFO - Configuring BGP on ctest-TestBGPaasZone-83084478-54019484
2025-10-02 03:38:10,835 - 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 46.195.94.131;
protocol bgp bgp_1{
local as 65000;
neighbor 46.195.94.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 46.195.94.131;
}
protocol bfd {
neighbor 46.195.94.129 local 46.195.94.131 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2025-10-02 03:38:10,835 - 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 46.195.94.131;
protocol bgp bgp_1{
local as 65000;
neighbor 46.195.94.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 46.195.94.131;
}
protocol bfd {
neighbor 46.195.94.129 local 46.195.94.131 multihop on;
}
EOS
2025-10-02 03:38:23,327 - DEBUG - None
2025-10-02 03:38:23,328 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 46.195.94.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 46.195.94.131;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2025-10-02 03:38:23,328 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 46.195.94.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 46.195.94.131;
}
EOS
2025-10-02 03:38:24,235 - DEBUG - None
2025-10-02 03:38:24,235 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2025-10-02 03:38:24,235 - DEBUG - service bird restart
2025-10-02 03:38:27,131 - DEBUG - bird stop/waiting
bird start/running, process 1438
2025-10-02 03:38:27,131 - INFO - Attaching VMI ad593358-2aeb-45f5-8d49-87f0834645bb to BGPaaS 02a5bb1d-2587-4014-b8a8-0179cd0f9d56
2025-10-02 03:38:27,206 - INFO - Attaching the VMI 16cdb51f-e811-4559-812b-10448ef385b0 to the BGPaaS 02a5bb1d-2587-4014-b8a8-0179cd0f9d56 object
2025-10-02 03:38:27,207 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 46.195.94.148 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2025-10-02 03:38:27,207 - DEBUG - sudo ip addr add 46.195.94.148 dev eth0
2025-10-02 03:38:28,108 - DEBUG - None
2025-10-02 03:38:28,170 - INFO - Creating BGPaaS ctest-TestBGPaasZone-83084478-01529643
2025-10-02 03:38:28,270 - INFO - BGPaaS: ctest-TestBGPaasZone-83084478-01529643(5971e823-fdaa-4b46-a6a7-758a5b4a571b)
2025-10-02 03:38:28,271 - DEBUG - Requesting: http://10.0.0.56:8082/bgp-as-a-service/5971e823-fdaa-4b46-a6a7-758a5b4a571b
2025-10-02 03:38:28,281 - INFO - verify_in_api_server passed for BGPaaS obj 5971e823-fdaa-4b46-a6a7-758a5b4a571b
2025-10-02 03:38:28,281 - INFO - BGPaaS(5971e823-fdaa-4b46-a6a7-758a5b4a571b): verify_on_setup passed
2025-10-02 03:38:28,281 - INFO - We will configure BGP on the VM
2025-10-02 03:38:28,719 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-02 03:38:28,719 - INFO - Configuring BGP on ctest-TestBGPaasZone-83084478-32212757
2025-10-02 03:38:28,719 - 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 46.195.94.132;
protocol bgp bgp_1{
local as 65000;
neighbor 46.195.94.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 46.195.94.132;
}
protocol bfd {
neighbor 46.195.94.129 local 46.195.94.132 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123
2025-10-02 03:38:28,719 - 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 46.195.94.132;
protocol bgp bgp_1{
local as 65000;
neighbor 46.195.94.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 46.195.94.132;
}
protocol bfd {
neighbor 46.195.94.129 local 46.195.94.132 multihop on;
}
EOS
2025-10-02 03:38:34,228 - DEBUG - None
2025-10-02 03:38:34,229 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 46.195.94.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 46.195.94.132;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123
2025-10-02 03:38:34,229 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 46.195.94.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 46.195.94.132;
}
EOS
2025-10-02 03:38:34,762 - DEBUG - None
2025-10-02 03:38:34,762 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123
2025-10-02 03:38:34,763 - DEBUG - service bird restart
2025-10-02 03:38:36,569 - DEBUG - bird stop/waiting
bird start/running, process 1440
2025-10-02 03:38:36,569 - INFO - Attaching VMI 83211b9e-e526-48fe-b0b7-b2a907903ab5 to BGPaaS 5971e823-fdaa-4b46-a6a7-758a5b4a571b
2025-10-02 03:38:36,650 - INFO - Attaching the VMI cd6c5e67-12c2-4b60-b278-32dd503b2567 to the BGPaaS 5971e823-fdaa-4b46-a6a7-758a5b4a571b object
2025-10-02 03:38:36,651 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 46.195.94.148 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123
2025-10-02 03:38:36,651 - DEBUG - sudo ip addr add 46.195.94.148 dev eth0
2025-10-02 03:38:37,353 - DEBUG - None
2025-10-02 03:38:37,353 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-02 03:38:37,369 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-10-02 03:38:37,369 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-02 03:38:37,369 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-02 03:38:37,393 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-10-02 03:38:37,399 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-02 03:38:37,412 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25']
2025-10-02 03:38:43,431 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25']
2025-10-02 03:38:49,445 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-10-02 03:38:49,446 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-02 03:38:49,446 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-02 03:38:49,466 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-10-02 03:38:49,473 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 46.195.94.148, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2025-10-02 03:38:49,473 - DEBUG - ping -s 56 -c 3 -W 1 46.195.94.148
2025-10-02 03:38:55,239 - DEBUG - PING 46.195.94.148 (46.195.94.148) 56(84) bytes of data.
64 bytes from 46.195.94.148: icmp_seq=1 ttl=64 time=7.75 ms
64 bytes from 46.195.94.148: icmp_seq=2 ttl=64 time=1.12 ms
64 bytes from 46.195.94.148: icmp_seq=3 ttl=64 time=1.20 ms
--- 46.195.94.148 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.126/3.362/7.752/3.104 ms
2025-10-02 03:38:55,239 - INFO - Ping to IP 46.195.94.148 from VM ctest-TestBGPaasZone-83084478-34779767 passed
2025-10-02 03:38:55,239 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-02 03:38:55,252 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-10-02 03:38:55,252 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-02 03:38:55,252 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-02 03:38:55,270 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-10-02 03:38:55,275 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-02 03:38:55,287 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-10-02 03:38:55,287 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-10-02 03:38:55,287 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-10-02 03:38:55,307 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-10-02 03:38:55,313 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 46.195.94.148, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.33, gateway password: c0ntrail123
2025-10-02 03:38:55,313 - DEBUG - ping -s 56 -c 3 -W 1 46.195.94.148
2025-10-02 03:38:57,757 - DEBUG - PING 46.195.94.148 (46.195.94.148) 56(84) bytes of data.
64 bytes from 46.195.94.148: icmp_seq=1 ttl=64 time=3.66 ms
64 bytes from 46.195.94.148: icmp_seq=2 ttl=64 time=0.702 ms
64 bytes from 46.195.94.148: icmp_seq=3 ttl=64 time=0.704 ms
--- 46.195.94.148 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 0.702/1.691/3.667/1.397 ms
2025-10-02 03:38:57,757 - INFO - Ping to IP 46.195.94.148 from VM ctest-TestBGPaasZone-83084478-34779767 passed
2025-10-02 03:38:57,757 - INFO - Detaching VMI 83211b9e-e526-48fe-b0b7-b2a907903ab5 from BGPaaS 5971e823-fdaa-4b46-a6a7-758a5b4a571b
2025-10-02 03:38:57,838 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-83084478-01529643(5971e823-fdaa-4b46-a6a7-758a5b4a571b)
2025-10-02 03:38:57,908 - DEBUG - Requesting: http://10.0.0.56:8082/bgp-as-a-service/5971e823-fdaa-4b46-a6a7-758a5b4a571b
2025-10-02 03:38:57,926 - DEBUG - Response Code: 404
2025-10-02 03:38:57,927 - DEBUG - BGPaaS: 5971e823-fdaa-4b46-a6a7-758a5b4a571b deleted from api server
2025-10-02 03:38:57,927 - INFO - BGPaaS(5971e823-fdaa-4b46-a6a7-758a5b4a571b): verify_on_cleanup passed
2025-10-02 03:38:57,927 - INFO - Detaching VMI ad593358-2aeb-45f5-8d49-87f0834645bb from BGPaaS 02a5bb1d-2587-4014-b8a8-0179cd0f9d56
2025-10-02 03:38:58,022 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-83084478-35030579(02a5bb1d-2587-4014-b8a8-0179cd0f9d56)
2025-10-02 03:38:58,089 - DEBUG - Requesting: http://10.0.0.56:8082/bgp-as-a-service/02a5bb1d-2587-4014-b8a8-0179cd0f9d56
2025-10-02 03:38:58,099 - DEBUG - Response Code: 404
2025-10-02 03:38:58,099 - DEBUG - BGPaaS: 02a5bb1d-2587-4014-b8a8-0179cd0f9d56 deleted from api server
2025-10-02 03:38:58,099 - INFO - BGPaaS(02a5bb1d-2587-4014-b8a8-0179cd0f9d56): verify_on_cleanup passed
2025-10-02 03:38:58,099 - INFO - Deleting Control node zone ctest-test-zone-03003161(5bb2b555-22f5-4848-b7fb-22326dfeade1)
2025-10-02 03:38:58,251 - INFO - Deleting Control node zone ctest-test-zone-53719591(eb843210-e00b-4e18-a718-6fcf2a7272ec)
2025-10-02 03:38:58,396 - INFO - Deleting Control node zone ctest-test-zone-45202178(462d0675-1ac8-4e1d-91e6-a0c1ef21e46e)
2025-10-02 03:38:58,547 - INFO - Deleting VM ctest-TestBGPaasZone-83084478-34779767
2025-10-02 03:38:58,612 - INFO - Deleting VM ctest-TestBGPaasZone-83084478-32212757
2025-10-02 03:38:58,677 - INFO - Deleting VM ctest-TestBGPaasZone-83084478-54019484
2025-10-02 03:38:58,741 - INFO - Deleting VN ctest-cnz_vn-55951712
2025-10-02 03:38:58,780 - DEBUG - VN 48704fcb-84f5-40df-add5-7f38d4f9555b still in use: Unable to complete operation on network 48704fcb-84f5-40df-add5-7f38d4f9555b. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-10170333-4016-4775-9743-346138683e10']
2025-10-02 03:38:58,781 - WARNING - Deleting VN ctest-cnz_vn-55951712 failed..Will retry
2025-10-02 03:39:00,999 - DEBUG - Response for deleting network ()
2025-10-02 03:39:02,290 - DEBUG - Skipping xmpp flap check
2025-10-02 03:39:02,291 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:03]
2025-10-02 03:39:02,291 - INFO - --------------------------------------------------------------------------------
2025-10-02 03:39:02,997 - INFO - Deleted project: ctest-TestBGPaasZone-83084478, ID : c3a5c8a4-e0e8-4c14-8800-c2d93a0d2fff