is in ACTIVE state now
2025-08-06 09:47:16,630 - INFO - VM name : ctest-TestBGPaasZone-14997557-84304780
2025-08-06 09:47:16,737 - DEBUG - VM ctest-TestBGPaasZone-14997557-84304780 ID is 4868add8-fa26-423d-b9b2-479c280e61c0
2025-08-06 09:47:16,737 - DEBUG - VM ctest-TestBGPaasZone-14997557-84304780 launched on Node an-jenkins-deploy-platform-ansible-os-3761-2
2025-08-06 09:47:16,836 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/4868add8-fa26-423d-b9b2-479c280e61c0
2025-08-06 09:47:16,850 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/f263f6db-ac13-49dd-857b-2bf09b945193
2025-08-06 09:47:18,078 - 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.48 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.484 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.484/1.983/3.482/1.499 ms')
2025-08-06 09:47:18,078 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-14997557-84304780 passed
2025-08-06 09:47:18,192 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-06 09:47:18,192 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-14997557-84304780, IP 102.108.190.69, Port 22
2025-08-06 09:47:18,260 - DEBUG - Error on ssh to ubuntu@169.254.0.4:22, result: /bin/bash: connect: Connection refused
/bin/bash: line 1: /dev/tcp/169.254.0.4/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.4/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.4/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''}
2025-08-06 09:47:18,347 - DEBUG - VM ctest-TestBGPaasZone-14997557-84304780 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-06 09:47:23,348 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-06 09:47:23,348 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-14997557-84304780, IP 102.108.190.69, Port 22
2025-08-06 09:47:23,521 - DEBUG - VM ctest-TestBGPaasZone-14997557-84304780 is ready for SSH connections
2025-08-06 09:47:23,662 - INFO - Created control node zone ctest-test-zone-17647581(02d8c2f3-36ac-4c23-bb39-895413c8d3b0)
2025-08-06 09:47:24,047 - INFO - Created control node zone ctest-test-zone-30408880(acc1a078-72d0-4940-8fd3-30cc0c69efd1)
2025-08-06 09:47:24,418 - INFO - Created control node zone ctest-test-zone-25926567(d17817c2-f895-4cdf-bf8e-c75255a527e1)
2025-08-06 09:47:24,710 - INFO - Creating BGPaaS ctest-TestBGPaasZone-14997557-18738901
2025-08-06 09:47:24,791 - INFO - BGPaaS: ctest-TestBGPaasZone-14997557-18738901(8bd9da5e-392c-4a37-b403-86a6ca9b8926)
2025-08-06 09:47:24,791 - DEBUG - Requesting: http://10.0.0.48:8082/bgp-as-a-service/8bd9da5e-392c-4a37-b403-86a6ca9b8926
2025-08-06 09:47:24,805 - INFO - verify_in_api_server passed for BGPaaS obj 8bd9da5e-392c-4a37-b403-86a6ca9b8926
2025-08-06 09:47:24,805 - INFO - BGPaaS(8bd9da5e-392c-4a37-b403-86a6ca9b8926): verify_on_setup passed
2025-08-06 09:47:24,805 - INFO - We will configure BGP on the VM
2025-08-06 09:47:25,274 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-06 09:47:25,275 - INFO - Configuring BGP on ctest-TestBGPaasZone-14997557-84997487
2025-08-06 09:47:25,275 - 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 102.108.190.67;
protocol bgp bgp_1{
local as 65000;
neighbor 102.108.190.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 102.108.190.67;
}
protocol bfd {
neighbor 102.108.190.65 local 102.108.190.67 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-06 09:47:25,275 - 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 102.108.190.67;
protocol bgp bgp_1{
local as 65000;
neighbor 102.108.190.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 102.108.190.67;
}
protocol bfd {
neighbor 102.108.190.65 local 102.108.190.67 multihop on;
}
EOS
2025-08-06 09:47:40,011 - DEBUG - None
2025-08-06 09:47:40,011 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 102.108.190.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 102.108.190.67;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-06 09:47:40,011 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 102.108.190.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 102.108.190.67;
}
EOS
2025-08-06 09:47:41,096 - DEBUG - None
2025-08-06 09:47:41,096 - 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-06 09:47:41,096 - DEBUG - service bird restart
2025-08-06 09:47:44,029 - DEBUG - bird stop/waiting
bird start/running, process 1441
2025-08-06 09:47:44,029 - INFO - Attaching VMI f96e359e-c0fe-43e2-a316-23a195c052b9 to BGPaaS 8bd9da5e-392c-4a37-b403-86a6ca9b8926
2025-08-06 09:47:44,114 - INFO - Attaching the VMI 072976ad-197b-41ff-8b7b-c5632d78f757 to the BGPaaS 8bd9da5e-392c-4a37-b403-86a6ca9b8926 object
2025-08-06 09:47:44,114 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 102.108.190.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-06 09:47:44,115 - DEBUG - sudo ip addr add 102.108.190.84 dev eth0
2025-08-06 09:47:44,999 - DEBUG - None
2025-08-06 09:47:45,066 - INFO - Creating BGPaaS ctest-TestBGPaasZone-14997557-90037763
2025-08-06 09:47:45,166 - INFO - BGPaaS: ctest-TestBGPaasZone-14997557-90037763(efdc1593-040a-47e3-9776-8431a48814bf)
2025-08-06 09:47:45,167 - DEBUG - Requesting: http://10.0.0.48:8082/bgp-as-a-service/efdc1593-040a-47e3-9776-8431a48814bf
2025-08-06 09:47:45,179 - INFO - verify_in_api_server passed for BGPaaS obj efdc1593-040a-47e3-9776-8431a48814bf
2025-08-06 09:47:45,180 - INFO - BGPaaS(efdc1593-040a-47e3-9776-8431a48814bf): verify_on_setup passed
2025-08-06 09:47:45,180 - INFO - We will configure BGP on the VM
2025-08-06 09:47:45,650 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-06 09:47:45,650 - INFO - Configuring BGP on ctest-TestBGPaasZone-14997557-56201462
2025-08-06 09:47:45,650 - 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 102.108.190.68;
protocol bgp bgp_1{
local as 65000;
neighbor 102.108.190.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 102.108.190.68;
}
protocol bfd {
neighbor 102.108.190.65 local 102.108.190.68 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.49, gateway password: c0ntrail123
2025-08-06 09:47:45,650 - 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 102.108.190.68;
protocol bgp bgp_1{
local as 65000;
neighbor 102.108.190.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 102.108.190.68;
}
protocol bfd {
neighbor 102.108.190.65 local 102.108.190.68 multihop on;
}
EOS
2025-08-06 09:47:50,509 - DEBUG - None
2025-08-06 09:47:50,509 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 102.108.190.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 102.108.190.68;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.49, gateway password: c0ntrail123
2025-08-06 09:47:50,509 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 102.108.190.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 102.108.190.68;
}
EOS
2025-08-06 09:47:50,956 - DEBUG - None
2025-08-06 09:47:50,957 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.49, gateway password: c0ntrail123
2025-08-06 09:47:50,957 - DEBUG - service bird restart
2025-08-06 09:47:52,436 - DEBUG - bird stop/waiting
bird start/running, process 1439
2025-08-06 09:47:52,436 - INFO - Attaching VMI b82ec8ce-d96a-4153-9b5d-86124dafa08b to BGPaaS efdc1593-040a-47e3-9776-8431a48814bf
2025-08-06 09:47:52,522 - INFO - Attaching the VMI 3320e224-7020-4e9c-8398-8b82a769ab86 to the BGPaaS efdc1593-040a-47e3-9776-8431a48814bf object
2025-08-06 09:47:52,523 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 102.108.190.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.49, gateway password: c0ntrail123
2025-08-06 09:47:52,523 - DEBUG - sudo ip addr add 102.108.190.84 dev eth0
2025-08-06 09:47:53,093 - DEBUG - None
2025-08-06 09:47:53,093 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-06 09:47:53,094 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-06 09:47:53,111 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-08-06 09:47:53,112 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-06 09:47:53,133 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-08-06 09:47:53,140 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-06 09:47:53,140 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-06 09:47:53,169 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.254']
2025-08-06 09:47:59,171 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-06 09:47:59,189 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.254']
2025-08-06 09:48:05,189 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-06 09:48:05,207 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-08-06 09:48:05,208 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-06 09:48:05,233 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-08-06 09:48:05,243 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 102.108.190.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-06 09:48:05,243 - DEBUG - ping -s 56 -c 3 -W 1 102.108.190.84
2025-08-06 09:48:11,924 - DEBUG - PING 102.108.190.84 (102.108.190.84) 56(84) bytes of data.
64 bytes from 102.108.190.84: icmp_seq=1 ttl=64 time=8.32 ms
64 bytes from 102.108.190.84: icmp_seq=2 ttl=64 time=1.13 ms
64 bytes from 102.108.190.84: icmp_seq=3 ttl=64 time=1.19 ms
--- 102.108.190.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.130/3.548/8.322/3.375 ms
2025-08-06 09:48:11,924 - INFO - Ping to IP 102.108.190.84 from VM ctest-TestBGPaasZone-14997557-84304780 passed
2025-08-06 09:48:11,924 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-06 09:48:11,924 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-06 09:48:11,940 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-08-06 09:48:11,940 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-06 09:48:11,964 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-08-06 09:48:11,972 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-06 09:48:11,972 - DEBUG - Requesting: http://10.20.0.254:8083/Snh_BgpNeighborReq?domain=&ip_address=
2025-08-06 09:48:11,988 - INFO - BGPaaS session seen in control-node 10.20.0.254
2025-08-06 09:48:11,988 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-06 09:48:12,011 - INFO - BGPaaS session seen in control-node 10.20.0.14
2025-08-06 09:48:12,018 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 102.108.190.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.57, gateway password: c0ntrail123
2025-08-06 09:48:12,018 - DEBUG - ping -s 56 -c 3 -W 1 102.108.190.84
2025-08-06 09:48:14,660 - DEBUG - PING 102.108.190.84 (102.108.190.84) 56(84) bytes of data.
64 bytes from 102.108.190.84: icmp_seq=1 ttl=64 time=2.39 ms
64 bytes from 102.108.190.84: icmp_seq=2 ttl=64 time=1.11 ms
64 bytes from 102.108.190.84: icmp_seq=3 ttl=64 time=1.08 ms
--- 102.108.190.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.085/1.533/2.398/0.612 ms
2025-08-06 09:48:14,660 - INFO - Ping to IP 102.108.190.84 from VM ctest-TestBGPaasZone-14997557-84304780 passed
2025-08-06 09:48:14,660 - INFO - Detaching VMI b82ec8ce-d96a-4153-9b5d-86124dafa08b from BGPaaS efdc1593-040a-47e3-9776-8431a48814bf
2025-08-06 09:48:14,746 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-14997557-90037763(efdc1593-040a-47e3-9776-8431a48814bf)
2025-08-06 09:48:14,838 - DEBUG - Requesting: http://10.0.0.48:8082/bgp-as-a-service/efdc1593-040a-47e3-9776-8431a48814bf
2025-08-06 09:48:14,847 - DEBUG - Response Code: 404
2025-08-06 09:48:14,847 - DEBUG - BGPaaS: efdc1593-040a-47e3-9776-8431a48814bf deleted from api server
2025-08-06 09:48:14,847 - INFO - BGPaaS(efdc1593-040a-47e3-9776-8431a48814bf): verify_on_cleanup passed
2025-08-06 09:48:14,847 - INFO - Detaching VMI f96e359e-c0fe-43e2-a316-23a195c052b9 from BGPaaS 8bd9da5e-392c-4a37-b403-86a6ca9b8926
2025-08-06 09:48:14,947 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-14997557-18738901(8bd9da5e-392c-4a37-b403-86a6ca9b8926)
2025-08-06 09:48:15,040 - DEBUG - Requesting: http://10.0.0.48:8082/bgp-as-a-service/8bd9da5e-392c-4a37-b403-86a6ca9b8926
2025-08-06 09:48:15,047 - DEBUG - Response Code: 404
2025-08-06 09:48:15,048 - DEBUG - BGPaaS: 8bd9da5e-392c-4a37-b403-86a6ca9b8926 deleted from api server
2025-08-06 09:48:15,048 - INFO - BGPaaS(8bd9da5e-392c-4a37-b403-86a6ca9b8926): verify_on_cleanup passed
2025-08-06 09:48:15,048 - INFO - Deleting Control node zone ctest-test-zone-25926567(d17817c2-f895-4cdf-bf8e-c75255a527e1)
2025-08-06 09:48:15,208 - INFO - Deleting Control node zone ctest-test-zone-30408880(acc1a078-72d0-4940-8fd3-30cc0c69efd1)
2025-08-06 09:48:15,373 - INFO - Deleting Control node zone ctest-test-zone-17647581(02d8c2f3-36ac-4c23-bb39-895413c8d3b0)
2025-08-06 09:48:15,534 - INFO - Deleting VM ctest-TestBGPaasZone-14997557-84304780
2025-08-06 09:48:15,626 - INFO - Deleting VM ctest-TestBGPaasZone-14997557-56201462
2025-08-06 09:48:15,713 - INFO - Deleting VM ctest-TestBGPaasZone-14997557-84997487
2025-08-06 09:48:15,788 - INFO - Deleting VN ctest-cnz_vn-50071459
2025-08-06 09:48:15,837 - DEBUG - VN 0a3ba433-c27f-4968-b86e-0f2e2d349121 still in use: Unable to complete operation on network 0a3ba433-c27f-4968-b86e-0f2e2d349121. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-a0cae6be-15da-48a5-9fac-81bedfca0c66']
2025-08-06 09:48:15,837 - WARNING - Deleting VN ctest-cnz_vn-50071459 failed..Will retry
2025-08-06 09:48:18,050 - DEBUG - Response for deleting network ()
2025-08-06 09:48:19,314 - DEBUG - Skipping xmpp flap check
2025-08-06 09:48:19,314 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:03:23]
2025-08-06 09:48:19,314 - INFO - --------------------------------------------------------------------------------
2025-08-06 09:48:20,140 - INFO - Deleted project: ctest-TestBGPaasZone-14997557, ID : 702b2625-39e2-41c9-8cae-ef765ffd0e9b