is in ACTIVE state now
2025-08-04 04:12:09,491 - INFO - VM name : ctest-TestBGPaasZone-18045522-32437868
2025-08-04 04:12:09,603 - DEBUG - VM ctest-TestBGPaasZone-18045522-32437868 ID is 3097fe4f-f0d1-41e0-91f7-03e592bc986c
2025-08-04 04:12:09,603 - DEBUG - VM ctest-TestBGPaasZone-18045522-32437868 launched on Node an-jenkins-deploy-platform-ansible-os-3750-1
2025-08-04 04:12:09,700 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine/3097fe4f-f0d1-41e0-91f7-03e592bc986c
2025-08-04 04:12:09,715 - DEBUG - Requesting: http://10.0.0.61:8082/virtual-machine-interface/52f47efe-db62-4788-8b71-9f7327ac3d66
2025-08-04 04:12:10,945 - 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=4.35 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.70 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 = 1.699/3.024/4.349/1.325 ms')
2025-08-04 04:12:10,945 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-18045522-32437868 passed
2025-08-04 04:12:11,075 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 04:12:11,076 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-18045522-32437868, IP 114.176.242.197, Port 22
2025-08-04 04:12:11,252 - DEBUG - VM ctest-TestBGPaasZone-18045522-32437868 is ready for SSH connections
2025-08-04 04:12:11,412 - INFO - Created control node zone ctest-test-zone-28141810(30fe7b1d-9d64-4398-bef8-cfe1469cb291)
2025-08-04 04:12:11,808 - INFO - Created control node zone ctest-test-zone-62900102(aa306210-cb5e-4b8d-ad18-122ab7edb4ab)
2025-08-04 04:12:12,184 - INFO - Created control node zone ctest-test-zone-52276744(31034f9a-6235-40df-9971-ccaf19940583)
2025-08-04 04:12:12,484 - INFO - Creating BGPaaS ctest-TestBGPaasZone-18045522-55513932
2025-08-04 04:12:12,572 - INFO - BGPaaS: ctest-TestBGPaasZone-18045522-55513932(197b3c55-d8d8-42f0-bcae-d2a83d9b94f5)
2025-08-04 04:12:12,572 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/197b3c55-d8d8-42f0-bcae-d2a83d9b94f5
2025-08-04 04:12:12,585 - INFO - verify_in_api_server passed for BGPaaS obj 197b3c55-d8d8-42f0-bcae-d2a83d9b94f5
2025-08-04 04:12:12,585 - INFO - BGPaaS(197b3c55-d8d8-42f0-bcae-d2a83d9b94f5): verify_on_setup passed
2025-08-04 04:12:12,585 - INFO - We will configure BGP on the VM
2025-08-04 04:12:13,040 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 04:12:13,040 - INFO - Configuring BGP on ctest-TestBGPaasZone-18045522-28520261
2025-08-04 04:12:13,040 - 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 114.176.242.195;
protocol bgp bgp_1{
local as 65000;
neighbor 114.176.242.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 114.176.242.195;
}
protocol bfd {
neighbor 114.176.242.193 local 114.176.242.195 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-04 04:12:13,040 - 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 114.176.242.195;
protocol bgp bgp_1{
local as 65000;
neighbor 114.176.242.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 114.176.242.195;
}
protocol bfd {
neighbor 114.176.242.193 local 114.176.242.195 multihop on;
}
EOS
2025-08-04 04:12:28,388 - DEBUG - None
2025-08-04 04:12:28,388 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 114.176.242.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 114.176.242.195;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-04 04:12:28,388 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 114.176.242.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 114.176.242.195;
}
EOS
2025-08-04 04:12:29,591 - DEBUG - None
2025-08-04 04:12:29,592 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-04 04:12:29,592 - DEBUG - service bird restart
2025-08-04 04:12:32,305 - DEBUG - bird stop/waiting
bird start/running, process 1444
2025-08-04 04:12:32,305 - INFO - Attaching VMI 100b5061-2af3-4821-a447-2e7b04c65e56 to BGPaaS 197b3c55-d8d8-42f0-bcae-d2a83d9b94f5
2025-08-04 04:12:32,396 - INFO - Attaching the VMI 566162ec-e158-447e-bd37-98de26d40e3b to the BGPaaS 197b3c55-d8d8-42f0-bcae-d2a83d9b94f5 object
2025-08-04 04:12:32,396 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 114.176.242.212 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-04 04:12:32,396 - DEBUG - sudo ip addr add 114.176.242.212 dev eth0
2025-08-04 04:12:33,087 - DEBUG - None
2025-08-04 04:12:33,156 - INFO - Creating BGPaaS ctest-TestBGPaasZone-18045522-73476203
2025-08-04 04:12:33,244 - INFO - BGPaaS: ctest-TestBGPaasZone-18045522-73476203(cb4cd80c-10db-44b5-b7a0-a0d72cb3e725)
2025-08-04 04:12:33,244 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/cb4cd80c-10db-44b5-b7a0-a0d72cb3e725
2025-08-04 04:12:33,258 - INFO - verify_in_api_server passed for BGPaaS obj cb4cd80c-10db-44b5-b7a0-a0d72cb3e725
2025-08-04 04:12:33,258 - INFO - BGPaaS(cb4cd80c-10db-44b5-b7a0-a0d72cb3e725): verify_on_setup passed
2025-08-04 04:12:33,258 - INFO - We will configure BGP on the VM
2025-08-04 04:12:33,724 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 04:12:33,724 - INFO - Configuring BGP on ctest-TestBGPaasZone-18045522-23966095
2025-08-04 04:12:33,724 - 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 114.176.242.196;
protocol bgp bgp_1{
local as 65000;
neighbor 114.176.242.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 114.176.242.196;
}
protocol bfd {
neighbor 114.176.242.193 local 114.176.242.196 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-04 04:12:33,724 - 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 114.176.242.196;
protocol bgp bgp_1{
local as 65000;
neighbor 114.176.242.193 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 114.176.242.196;
}
protocol bfd {
neighbor 114.176.242.193 local 114.176.242.196 multihop on;
}
EOS
2025-08-04 04:12:39,019 - DEBUG - None
2025-08-04 04:12:39,019 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 114.176.242.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 114.176.242.196;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-04 04:12:39,020 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 114.176.242.194 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 114.176.242.196;
}
EOS
2025-08-04 04:12:39,529 - DEBUG - None
2025-08-04 04:12:39,529 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-04 04:12:39,529 - DEBUG - service bird restart
2025-08-04 04:12:41,194 - DEBUG - bird stop/waiting
bird start/running, process 1440
2025-08-04 04:12:41,194 - INFO - Attaching VMI efac2573-5660-4127-86b0-561eca3a9979 to BGPaaS cb4cd80c-10db-44b5-b7a0-a0d72cb3e725
2025-08-04 04:12:41,280 - INFO - Attaching the VMI 1e655998-cedc-403c-889e-0dfec96be5fc to the BGPaaS cb4cd80c-10db-44b5-b7a0-a0d72cb3e725 object
2025-08-04 04:12:41,280 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 114.176.242.212 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-04 04:12:41,281 - DEBUG - sudo ip addr add 114.176.242.212 dev eth0
2025-08-04 04:12:41,956 - DEBUG - None
2025-08-04 04:12:41,957 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 04:12:41,957 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-04 04:12:41,973 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.193']
2025-08-04 04:12:47,973 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-04 04:12:48,000 - INFO - BGPaaS session seen in control-node 10.20.0.193
2025-08-04 04:12:48,000 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 04:12:48,021 - INFO - BGPaaS session seen in control-node 10.20.0.129
2025-08-04 04:12:48,030 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 04:12:48,030 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-04 04:12:48,048 - INFO - BGPaaS session seen in control-node 10.20.0.193
2025-08-04 04:12:48,049 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 04:12:48,067 - INFO - BGPaaS session seen in control-node 10.20.0.129
2025-08-04 04:12:48,077 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 114.176.242.212, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-04 04:12:48,077 - DEBUG - ping -s 56 -c 3 -W 1 114.176.242.212
2025-08-04 04:12:54,945 - DEBUG - PING 114.176.242.212 (114.176.242.212) 56(84) bytes of data.
64 bytes from 114.176.242.212: icmp_seq=1 ttl=64 time=12.5 ms
64 bytes from 114.176.242.212: icmp_seq=2 ttl=64 time=1.19 ms
64 bytes from 114.176.242.212: icmp_seq=3 ttl=64 time=1.75 ms
--- 114.176.242.212 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.194/5.181/12.599/5.250 ms
2025-08-04 04:12:54,945 - INFO - Ping to IP 114.176.242.212 from VM ctest-TestBGPaasZone-18045522-32437868 passed
2025-08-04 04:12:54,945 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 04:12:54,946 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-04 04:12:54,963 - INFO - BGPaaS session seen in control-node 10.20.0.193
2025-08-04 04:12:54,964 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 04:12:54,985 - INFO - BGPaaS session seen in control-node 10.20.0.129
2025-08-04 04:12:54,996 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 04:12:54,996 - DEBUG - Requesting: http://10.20.0.193:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-04 04:12:55,011 - INFO - BGPaaS session seen in control-node 10.20.0.193
2025-08-04 04:12:55,011 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-04 04:12:55,032 - INFO - BGPaaS session seen in control-node 10.20.0.129
2025-08-04 04:12:55,039 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 114.176.242.212, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.41, gateway password: c0ntrail123
2025-08-04 04:12:55,039 - DEBUG - ping -s 56 -c 3 -W 1 114.176.242.212
2025-08-04 04:12:57,596 - DEBUG - PING 114.176.242.212 (114.176.242.212) 56(84) bytes of data.
64 bytes from 114.176.242.212: icmp_seq=1 ttl=64 time=4.74 ms
64 bytes from 114.176.242.212: icmp_seq=2 ttl=64 time=1.21 ms
64 bytes from 114.176.242.212: icmp_seq=3 ttl=64 time=1.47 ms
--- 114.176.242.212 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.218/2.480/4.747/1.606 ms
2025-08-04 04:12:57,596 - INFO - Ping to IP 114.176.242.212 from VM ctest-TestBGPaasZone-18045522-32437868 passed
2025-08-04 04:12:57,597 - INFO - Detaching VMI efac2573-5660-4127-86b0-561eca3a9979 from BGPaaS cb4cd80c-10db-44b5-b7a0-a0d72cb3e725
2025-08-04 04:12:57,680 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-18045522-73476203(cb4cd80c-10db-44b5-b7a0-a0d72cb3e725)
2025-08-04 04:12:57,763 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/cb4cd80c-10db-44b5-b7a0-a0d72cb3e725
2025-08-04 04:12:57,778 - DEBUG - Response Code: 404
2025-08-04 04:12:57,778 - DEBUG - BGPaaS: cb4cd80c-10db-44b5-b7a0-a0d72cb3e725 deleted from api server
2025-08-04 04:12:57,779 - INFO - BGPaaS(cb4cd80c-10db-44b5-b7a0-a0d72cb3e725): verify_on_cleanup passed
2025-08-04 04:12:57,779 - INFO - Detaching VMI 100b5061-2af3-4821-a447-2e7b04c65e56 from BGPaaS 197b3c55-d8d8-42f0-bcae-d2a83d9b94f5
2025-08-04 04:12:57,916 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-18045522-55513932(197b3c55-d8d8-42f0-bcae-d2a83d9b94f5)
2025-08-04 04:12:58,011 - DEBUG - Requesting: http://10.0.0.27:8082/bgp-as-a-service/197b3c55-d8d8-42f0-bcae-d2a83d9b94f5
2025-08-04 04:12:58,024 - DEBUG - Response Code: 404
2025-08-04 04:12:58,025 - DEBUG - BGPaaS: 197b3c55-d8d8-42f0-bcae-d2a83d9b94f5 deleted from api server
2025-08-04 04:12:58,025 - INFO - BGPaaS(197b3c55-d8d8-42f0-bcae-d2a83d9b94f5): verify_on_cleanup passed
2025-08-04 04:12:58,027 - INFO - Deleting Control node zone ctest-test-zone-52276744(31034f9a-6235-40df-9971-ccaf19940583)
2025-08-04 04:12:58,203 - INFO - Deleting Control node zone ctest-test-zone-62900102(aa306210-cb5e-4b8d-ad18-122ab7edb4ab)
2025-08-04 04:12:58,368 - INFO - Deleting Control node zone ctest-test-zone-28141810(30fe7b1d-9d64-4398-bef8-cfe1469cb291)
2025-08-04 04:12:58,526 - INFO - Deleting VM ctest-TestBGPaasZone-18045522-32437868
2025-08-04 04:12:58,615 - INFO - Deleting VM ctest-TestBGPaasZone-18045522-23966095
2025-08-04 04:12:58,685 - INFO - Deleting VM ctest-TestBGPaasZone-18045522-28520261
2025-08-04 04:12:58,756 - INFO - Deleting VN ctest-cnz_vn-51176830
2025-08-04 04:12:58,818 - DEBUG - VN 5a614a87-97f5-4cf7-9599-75d4ad499f01 still in use: Unable to complete operation on network 5a614a87-97f5-4cf7-9599-75d4ad499f01. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-c9670b8b-9c23-4038-8251-6234abbadb13']
2025-08-04 04:12:58,818 - WARNING - Deleting VN ctest-cnz_vn-51176830 failed..Will retry
2025-08-04 04:13:01,067 - DEBUG - Response for deleting network ()
2025-08-04 04:13:02,348 - DEBUG - Skipping xmpp flap check
2025-08-04 04:13:02,348 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:22]
2025-08-04 04:13:02,348 - INFO - --------------------------------------------------------------------------------
2025-08-04 04:13:03,175 - INFO - Deleted project: ctest-TestBGPaasZone-18045522, ID : ee4fb3d1-8248-49e6-bb7c-2c8cb5582ae6