is in ACTIVE state now
2025-07-23 17:36:10,195 - INFO - VM name : ctest-TestBGPaasZone-71890785-84937893
2025-07-23 17:36:10,277 - DEBUG - VM ctest-TestBGPaasZone-71890785-84937893 ID is 071828e0-2b3c-46e9-ab46-21ba90740069
2025-07-23 17:36:10,277 - DEBUG - VM ctest-TestBGPaasZone-71890785-84937893 launched on Node an-jenkins-deploy-platform-ansible-os-3704-1
2025-07-23 17:36:10,373 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine/071828e0-2b3c-46e9-ab46-21ba90740069
2025-07-23 17:36:10,386 - DEBUG - Requesting: http://10.0.0.73:8082/virtual-machine-interface/b1e752d5-3f3d-451a-8ca5-da06bbd0cb7b
2025-07-23 17:36:11,604 - 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.39 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.47 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.469/2.430/3.391/0.961 ms')
2025-07-23 17:36:11,604 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-71890785-84937893 passed
2025-07-23 17:36:11,721 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-23 17:36:11,721 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-71890785-84937893, IP 150.170.97.69, Port 22
2025-07-23 17:36:11,879 - DEBUG - VM ctest-TestBGPaasZone-71890785-84937893 is ready for SSH connections
2025-07-23 17:36:12,031 - INFO - Created control node zone ctest-test-zone-56207262(c263a410-b0ca-46b4-888a-aaabf748fd7b)
2025-07-23 17:36:12,395 - INFO - Created control node zone ctest-test-zone-93435569(305c15d1-bd2e-4182-bcf6-a5dbc50c5f4e)
2025-07-23 17:36:12,755 - INFO - Created control node zone ctest-test-zone-43981187(e05f8fcb-d0a7-4228-a141-90e1c4b8808c)
2025-07-23 17:36:13,047 - INFO - Creating BGPaaS ctest-TestBGPaasZone-71890785-07618985
2025-07-23 17:36:13,127 - INFO - BGPaaS: ctest-TestBGPaasZone-71890785-07618985(f700b6be-ecfe-45dc-8604-3941f3b324e5)
2025-07-23 17:36:13,128 - DEBUG - Requesting: http://10.0.0.23:8082/bgp-as-a-service/f700b6be-ecfe-45dc-8604-3941f3b324e5
2025-07-23 17:36:13,140 - INFO - verify_in_api_server passed for BGPaaS obj f700b6be-ecfe-45dc-8604-3941f3b324e5
2025-07-23 17:36:13,140 - INFO - BGPaaS(f700b6be-ecfe-45dc-8604-3941f3b324e5): verify_on_setup passed
2025-07-23 17:36:13,140 - INFO - We will configure BGP on the VM
2025-07-23 17:36:13,603 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-23 17:36:13,603 - INFO - Configuring BGP on ctest-TestBGPaasZone-71890785-05121491
2025-07-23 17:36:13,604 - 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 150.170.97.67;
protocol bgp bgp_1{
local as 65000;
neighbor 150.170.97.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 150.170.97.67;
}
protocol bfd {
neighbor 150.170.97.65 local 150.170.97.67 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2025-07-23 17:36:13,604 - 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 150.170.97.67;
protocol bgp bgp_1{
local as 65000;
neighbor 150.170.97.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 150.170.97.67;
}
protocol bfd {
neighbor 150.170.97.65 local 150.170.97.67 multihop on;
}
EOS
2025-07-23 17:36:26,043 - DEBUG - None
2025-07-23 17:36:26,044 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 150.170.97.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 150.170.97.67;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2025-07-23 17:36:26,044 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 150.170.97.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 150.170.97.67;
}
EOS
2025-07-23 17:36:27,073 - DEBUG - None
2025-07-23 17:36:27,073 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2025-07-23 17:36:27,073 - DEBUG - service bird restart
2025-07-23 17:36:30,033 - DEBUG - bird stop/waiting
bird start/running, process 1430
2025-07-23 17:36:30,034 - INFO - Attaching VMI ed0fc410-a114-46e4-b2d2-ae73cae8e39d to BGPaaS f700b6be-ecfe-45dc-8604-3941f3b324e5
2025-07-23 17:36:30,111 - INFO - Attaching the VMI df94f832-33ab-4ffc-9f30-eafeaec9330b to the BGPaaS f700b6be-ecfe-45dc-8604-3941f3b324e5 object
2025-07-23 17:36:30,111 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 150.170.97.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2025-07-23 17:36:30,111 - DEBUG - sudo ip addr add 150.170.97.84 dev eth0
2025-07-23 17:36:31,396 - DEBUG - None
2025-07-23 17:36:31,459 - INFO - Creating BGPaaS ctest-TestBGPaasZone-71890785-66861187
2025-07-23 17:36:31,539 - INFO - BGPaaS: ctest-TestBGPaasZone-71890785-66861187(610a502e-833e-4e0e-8412-82bc6fbd2399)
2025-07-23 17:36:31,539 - DEBUG - Requesting: http://10.0.0.23:8082/bgp-as-a-service/610a502e-833e-4e0e-8412-82bc6fbd2399
2025-07-23 17:36:31,551 - INFO - verify_in_api_server passed for BGPaaS obj 610a502e-833e-4e0e-8412-82bc6fbd2399
2025-07-23 17:36:31,551 - INFO - BGPaaS(610a502e-833e-4e0e-8412-82bc6fbd2399): verify_on_setup passed
2025-07-23 17:36:31,552 - INFO - We will configure BGP on the VM
2025-07-23 17:36:31,975 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-23 17:36:31,975 - INFO - Configuring BGP on ctest-TestBGPaasZone-71890785-77450425
2025-07-23 17:36:31,975 - 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 150.170.97.68;
protocol bgp bgp_1{
local as 65000;
neighbor 150.170.97.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 150.170.97.68;
}
protocol bfd {
neighbor 150.170.97.65 local 150.170.97.68 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123
2025-07-23 17:36:31,976 - 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 150.170.97.68;
protocol bgp bgp_1{
local as 65000;
neighbor 150.170.97.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 150.170.97.68;
}
protocol bfd {
neighbor 150.170.97.65 local 150.170.97.68 multihop on;
}
EOS
2025-07-23 17:36:37,256 - DEBUG - None
2025-07-23 17:36:37,256 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 150.170.97.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 150.170.97.68;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123
2025-07-23 17:36:37,256 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 150.170.97.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 150.170.97.68;
}
EOS
2025-07-23 17:36:37,760 - DEBUG - None
2025-07-23 17:36:37,760 - 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-07-23 17:36:37,761 - DEBUG - service bird restart
2025-07-23 17:36:39,477 - DEBUG - bird stop/waiting
bird start/running, process 1438
2025-07-23 17:36:39,477 - INFO - Attaching VMI 486dc378-7040-4382-b995-78c01e9963ed to BGPaaS 610a502e-833e-4e0e-8412-82bc6fbd2399
2025-07-23 17:36:39,559 - INFO - Attaching the VMI ac5d157e-6d13-4cb5-be96-ada5c86cd7af to the BGPaaS 610a502e-833e-4e0e-8412-82bc6fbd2399 object
2025-07-23 17:36:39,559 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 150.170.97.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.130, gateway password: c0ntrail123
2025-07-23 17:36:39,560 - DEBUG - sudo ip addr add 150.170.97.84 dev eth0
2025-07-23 17:36:40,213 - DEBUG - None
2025-07-23 17:36:40,213 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-23 17:36:40,228 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-07-23 17:36:40,229 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-23 17:36:40,247 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-07-23 17:36:40,262 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-23 17:36:40,277 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.17']
2025-07-23 17:36:46,292 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.17']
2025-07-23 17:36:52,308 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-07-23 17:36:52,308 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-23 17:36:52,327 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-07-23 17:36:52,333 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 150.170.97.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2025-07-23 17:36:52,333 - DEBUG - ping -s 56 -c 3 -W 1 150.170.97.84
2025-07-23 17:36:58,882 - DEBUG - PING 150.170.97.84 (150.170.97.84) 56(84) bytes of data.
64 bytes from 150.170.97.84: icmp_seq=1 ttl=64 time=11.4 ms
64 bytes from 150.170.97.84: icmp_seq=2 ttl=64 time=3.53 ms
64 bytes from 150.170.97.84: icmp_seq=3 ttl=64 time=1.78 ms
--- 150.170.97.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.788/5.580/11.416/4.188 ms
2025-07-23 17:36:58,882 - INFO - Ping to IP 150.170.97.84 from VM ctest-TestBGPaasZone-71890785-84937893 passed
2025-07-23 17:36:58,882 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-23 17:36:58,898 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-07-23 17:36:58,899 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-23 17:36:58,921 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-07-23 17:36:58,936 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-23 17:36:58,953 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-07-23 17:36:58,953 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-07-23 17:36:58,972 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-07-23 17:36:58,980 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 150.170.97.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.50, gateway password: c0ntrail123
2025-07-23 17:36:58,980 - DEBUG - ping -s 56 -c 3 -W 1 150.170.97.84
2025-07-23 17:37:01,580 - DEBUG - PING 150.170.97.84 (150.170.97.84) 56(84) bytes of data.
64 bytes from 150.170.97.84: icmp_seq=1 ttl=64 time=5.03 ms
64 bytes from 150.170.97.84: icmp_seq=2 ttl=64 time=1.82 ms
64 bytes from 150.170.97.84: icmp_seq=3 ttl=64 time=2.15 ms
--- 150.170.97.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.828/3.005/5.033/1.441 ms
2025-07-23 17:37:01,580 - INFO - Ping to IP 150.170.97.84 from VM ctest-TestBGPaasZone-71890785-84937893 passed
2025-07-23 17:37:01,580 - INFO - Detaching VMI 486dc378-7040-4382-b995-78c01e9963ed from BGPaaS 610a502e-833e-4e0e-8412-82bc6fbd2399
2025-07-23 17:37:01,670 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-71890785-66861187(610a502e-833e-4e0e-8412-82bc6fbd2399)
2025-07-23 17:37:01,750 - DEBUG - Requesting: http://10.0.0.23:8082/bgp-as-a-service/610a502e-833e-4e0e-8412-82bc6fbd2399
2025-07-23 17:37:01,757 - DEBUG - Response Code: 404
2025-07-23 17:37:01,757 - DEBUG - BGPaaS: 610a502e-833e-4e0e-8412-82bc6fbd2399 deleted from api server
2025-07-23 17:37:01,757 - INFO - BGPaaS(610a502e-833e-4e0e-8412-82bc6fbd2399): verify_on_cleanup passed
2025-07-23 17:37:01,757 - INFO - Detaching VMI ed0fc410-a114-46e4-b2d2-ae73cae8e39d from BGPaaS f700b6be-ecfe-45dc-8604-3941f3b324e5
2025-07-23 17:37:01,883 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-71890785-07618985(f700b6be-ecfe-45dc-8604-3941f3b324e5)
2025-07-23 17:37:01,949 - DEBUG - Requesting: http://10.0.0.23:8082/bgp-as-a-service/f700b6be-ecfe-45dc-8604-3941f3b324e5
2025-07-23 17:37:01,956 - DEBUG - Response Code: 404
2025-07-23 17:37:01,956 - DEBUG - BGPaaS: f700b6be-ecfe-45dc-8604-3941f3b324e5 deleted from api server
2025-07-23 17:37:01,956 - INFO - BGPaaS(f700b6be-ecfe-45dc-8604-3941f3b324e5): verify_on_cleanup passed
2025-07-23 17:37:01,958 - INFO - Deleting Control node zone ctest-test-zone-43981187(e05f8fcb-d0a7-4228-a141-90e1c4b8808c)
2025-07-23 17:37:02,124 - INFO - Deleting Control node zone ctest-test-zone-93435569(305c15d1-bd2e-4182-bcf6-a5dbc50c5f4e)
2025-07-23 17:37:02,271 - INFO - Deleting Control node zone ctest-test-zone-56207262(c263a410-b0ca-46b4-888a-aaabf748fd7b)
2025-07-23 17:37:02,423 - INFO - Deleting VM ctest-TestBGPaasZone-71890785-84937893
2025-07-23 17:37:02,492 - INFO - Deleting VM ctest-TestBGPaasZone-71890785-77450425
2025-07-23 17:37:02,565 - INFO - Deleting VM ctest-TestBGPaasZone-71890785-05121491
2025-07-23 17:37:02,646 - INFO - Deleting VN ctest-cnz_vn-79709597
2025-07-23 17:37:02,697 - DEBUG - VN fc65dece-48dd-4feb-88ce-d91c900787e9 still in use: Unable to complete operation on network fc65dece-48dd-4feb-88ce-d91c900787e9. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-a9bc1053-b253-4ef4-aa9c-3e44f2f0f414']
2025-07-23 17:37:02,697 - WARNING - Deleting VN ctest-cnz_vn-79709597 failed..Will retry
2025-07-23 17:37:04,921 - DEBUG - Response for deleting network ()
2025-07-23 17:37:06,244 - DEBUG - Skipping xmpp flap check
2025-07-23 17:37:06,244 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:02:57]
2025-07-23 17:37:06,244 - INFO - --------------------------------------------------------------------------------
2025-07-23 17:37:06,971 - INFO - Deleted project: ctest-TestBGPaasZone-71890785, ID : 48d19642-7066-48f2-9cb4-5d363048d2ec