is in ACTIVE state now
2025-08-28 03:48:54,542 - INFO - VM name : ctest-TestBGPaasZone-78530566-45706895
2025-08-28 03:48:54,619 - DEBUG - VM ctest-TestBGPaasZone-78530566-45706895 ID is 4fa5f37b-e975-4728-8513-4394f5b21771
2025-08-28 03:48:54,619 - DEBUG - VM ctest-TestBGPaasZone-78530566-45706895 launched on Node an-jenkins-deploy-platform-ansible-os-3956-2
2025-08-28 03:48:54,695 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/4fa5f37b-e975-4728-8513-4394f5b21771
2025-08-28 03:48:54,704 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/5e9510ce-da70-4dc6-a6b9-7ad70a4ea1f0
2025-08-28 03:48:55,862 - 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=1.45 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=1.09 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.086/1.267/1.448/0.181 ms')
2025-08-28 03:48:55,862 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-78530566-45706895 passed
2025-08-28 03:48:55,929 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-28 03:48:55,929 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-78530566-45706895, IP 98.196.109.69, Port 22
2025-08-28 03:48:55,996 - 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-28 03:48:56,075 - DEBUG - VM ctest-TestBGPaasZone-78530566-45706895 is NOT ready for SSH connections, VM status: ACTIVE
2025-08-28 03:49:01,076 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-28 03:49:01,077 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-78530566-45706895, IP 98.196.109.69, Port 22
2025-08-28 03:49:01,233 - DEBUG - VM ctest-TestBGPaasZone-78530566-45706895 is ready for SSH connections
2025-08-28 03:49:01,369 - INFO - Created control node zone ctest-test-zone-16997314(93caad4f-f605-4c3e-b57a-bfb13e52993a)
2025-08-28 03:49:01,713 - INFO - Created control node zone ctest-test-zone-52461788(2ec0364e-1e0a-4b4d-955c-74402a131f0a)
2025-08-28 03:49:02,073 - INFO - Created control node zone ctest-test-zone-38735361(21997825-4dce-4db1-9832-feea2374e19a)
2025-08-28 03:49:02,349 - INFO - Creating BGPaaS ctest-TestBGPaasZone-78530566-57736542
2025-08-28 03:49:02,425 - INFO - BGPaaS: ctest-TestBGPaasZone-78530566-57736542(c550954b-e5e3-4a1d-880c-2450e5f2266e)
2025-08-28 03:49:02,426 - DEBUG - Requesting: http://10.0.0.19:8082/bgp-as-a-service/c550954b-e5e3-4a1d-880c-2450e5f2266e
2025-08-28 03:49:02,436 - INFO - verify_in_api_server passed for BGPaaS obj c550954b-e5e3-4a1d-880c-2450e5f2266e
2025-08-28 03:49:02,436 - INFO - BGPaaS(c550954b-e5e3-4a1d-880c-2450e5f2266e): verify_on_setup passed
2025-08-28 03:49:02,436 - INFO - We will configure BGP on the VM
2025-08-28 03:49:02,869 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-28 03:49:02,869 - INFO - Configuring BGP on ctest-TestBGPaasZone-78530566-46476649
2025-08-28 03:49:02,869 - 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 98.196.109.67;
protocol bgp bgp_1{
local as 65000;
neighbor 98.196.109.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 98.196.109.67;
}
protocol bfd {
neighbor 98.196.109.65 local 98.196.109.67 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-08-28 03:49:02,869 - 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 98.196.109.67;
protocol bgp bgp_1{
local as 65000;
neighbor 98.196.109.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 98.196.109.67;
}
protocol bfd {
neighbor 98.196.109.65 local 98.196.109.67 multihop on;
}
EOS
2025-08-28 03:49:10,909 - DEBUG - None
2025-08-28 03:49:10,909 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 98.196.109.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 98.196.109.67;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-08-28 03:49:10,909 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 98.196.109.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 98.196.109.67;
}
EOS
2025-08-28 03:49:11,371 - DEBUG - None
2025-08-28 03:49:11,371 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-08-28 03:49:11,371 - DEBUG - service bird restart
2025-08-28 03:49:12,986 - DEBUG - bird stop/waiting
bird start/running, process 1480
2025-08-28 03:49:12,986 - INFO - Attaching VMI ac632f51-d4eb-45a7-abae-3599a701efe9 to BGPaaS c550954b-e5e3-4a1d-880c-2450e5f2266e
2025-08-28 03:49:13,061 - INFO - Attaching the VMI b2ddca12-e6a5-4e75-99c3-6d12327c575e to the BGPaaS c550954b-e5e3-4a1d-880c-2450e5f2266e object
2025-08-28 03:49:13,061 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 98.196.109.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-08-28 03:49:13,061 - DEBUG - sudo ip addr add 98.196.109.84 dev eth0
2025-08-28 03:49:13,620 - DEBUG - None
2025-08-28 03:49:13,681 - INFO - Creating BGPaaS ctest-TestBGPaasZone-78530566-73112458
2025-08-28 03:49:13,753 - INFO - BGPaaS: ctest-TestBGPaasZone-78530566-73112458(f2924298-11ef-4b48-9dfc-5f9687314c99)
2025-08-28 03:49:13,753 - DEBUG - Requesting: http://10.0.0.19:8082/bgp-as-a-service/f2924298-11ef-4b48-9dfc-5f9687314c99
2025-08-28 03:49:13,763 - INFO - verify_in_api_server passed for BGPaaS obj f2924298-11ef-4b48-9dfc-5f9687314c99
2025-08-28 03:49:13,763 - INFO - BGPaaS(f2924298-11ef-4b48-9dfc-5f9687314c99): verify_on_setup passed
2025-08-28 03:49:13,763 - INFO - We will configure BGP on the VM
2025-08-28 03:49:14,169 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-28 03:49:14,169 - INFO - Configuring BGP on ctest-TestBGPaasZone-78530566-55524083
2025-08-28 03:49:14,169 - 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 98.196.109.68;
protocol bgp bgp_1{
local as 65000;
neighbor 98.196.109.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 98.196.109.68;
}
protocol bfd {
neighbor 98.196.109.65 local 98.196.109.68 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-08-28 03:49:14,169 - 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 98.196.109.68;
protocol bgp bgp_1{
local as 65000;
neighbor 98.196.109.65 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 98.196.109.68;
}
protocol bfd {
neighbor 98.196.109.65 local 98.196.109.68 multihop on;
}
EOS
2025-08-28 03:49:19,070 - DEBUG - None
2025-08-28 03:49:19,071 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 98.196.109.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 98.196.109.68;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-08-28 03:49:19,071 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 98.196.109.66 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 98.196.109.68;
}
EOS
2025-08-28 03:49:19,583 - DEBUG - None
2025-08-28 03:49:19,583 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-08-28 03:49:19,583 - DEBUG - service bird restart
2025-08-28 03:49:21,239 - DEBUG - bird stop/waiting
bird start/running, process 1478
2025-08-28 03:49:21,239 - INFO - Attaching VMI 6796716f-2113-453e-b199-ef105116e956 to BGPaaS f2924298-11ef-4b48-9dfc-5f9687314c99
2025-08-28 03:49:21,313 - INFO - Attaching the VMI 9102362b-9d29-43e3-b39d-405d86f9e3fc to the BGPaaS f2924298-11ef-4b48-9dfc-5f9687314c99 object
2025-08-28 03:49:21,313 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 98.196.109.84 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.65, gateway password: c0ntrail123
2025-08-28 03:49:21,313 - DEBUG - sudo ip addr add 98.196.109.84 dev eth0
2025-08-28 03:49:21,951 - DEBUG - None
2025-08-28 03:49:21,951 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-28 03:49:21,967 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-08-28 03:49:21,968 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-28 03:49:21,989 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-08-28 03:49:21,996 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-28 03:49:22,012 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25']
2025-08-28 03:49:28,027 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25']
2025-08-28 03:49:34,044 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-08-28 03:49:34,044 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-28 03:49:34,062 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-08-28 03:49:34,068 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 98.196.109.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-08-28 03:49:34,068 - DEBUG - ping -s 56 -c 3 -W 1 98.196.109.84
2025-08-28 03:49:39,968 - DEBUG - PING 98.196.109.84 (98.196.109.84) 56(84) bytes of data.
64 bytes from 98.196.109.84: icmp_seq=1 ttl=64 time=7.58 ms
64 bytes from 98.196.109.84: icmp_seq=2 ttl=64 time=1.29 ms
64 bytes from 98.196.109.84: icmp_seq=3 ttl=64 time=1.27 ms
--- 98.196.109.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 1.275/3.383/7.582/2.969 ms
2025-08-28 03:49:39,968 - INFO - Ping to IP 98.196.109.84 from VM ctest-TestBGPaasZone-78530566-45706895 passed
2025-08-28 03:49:39,968 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-28 03:49:39,984 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-08-28 03:49:39,985 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-28 03:49:40,002 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-08-28 03:49:40,008 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-28 03:49:40,020 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-08-28 03:49:40,020 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-08-28 03:49:40,035 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-08-28 03:49:40,040 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 98.196.109.84, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-08-28 03:49:40,040 - DEBUG - ping -s 56 -c 3 -W 1 98.196.109.84
2025-08-28 03:49:42,452 - DEBUG - PING 98.196.109.84 (98.196.109.84) 56(84) bytes of data.
64 bytes from 98.196.109.84: icmp_seq=1 ttl=64 time=1.99 ms
64 bytes from 98.196.109.84: icmp_seq=2 ttl=64 time=1.29 ms
64 bytes from 98.196.109.84: icmp_seq=3 ttl=64 time=1.15 ms
--- 98.196.109.84 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2004ms
rtt min/avg/max/mdev = 1.156/1.480/1.996/0.371 ms
2025-08-28 03:49:42,452 - INFO - Ping to IP 98.196.109.84 from VM ctest-TestBGPaasZone-78530566-45706895 passed
2025-08-28 03:49:42,452 - INFO - Detaching VMI 6796716f-2113-453e-b199-ef105116e956 from BGPaaS f2924298-11ef-4b48-9dfc-5f9687314c99
2025-08-28 03:49:42,533 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-78530566-73112458(f2924298-11ef-4b48-9dfc-5f9687314c99)
2025-08-28 03:49:42,601 - DEBUG - Requesting: http://10.0.0.19:8082/bgp-as-a-service/f2924298-11ef-4b48-9dfc-5f9687314c99
2025-08-28 03:49:42,614 - DEBUG - Response Code: 404
2025-08-28 03:49:42,614 - DEBUG - BGPaaS: f2924298-11ef-4b48-9dfc-5f9687314c99 deleted from api server
2025-08-28 03:49:42,614 - INFO - BGPaaS(f2924298-11ef-4b48-9dfc-5f9687314c99): verify_on_cleanup passed
2025-08-28 03:49:42,614 - INFO - Detaching VMI ac632f51-d4eb-45a7-abae-3599a701efe9 from BGPaaS c550954b-e5e3-4a1d-880c-2450e5f2266e
2025-08-28 03:49:42,721 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-78530566-57736542(c550954b-e5e3-4a1d-880c-2450e5f2266e)
2025-08-28 03:49:42,829 - DEBUG - Requesting: http://10.0.0.19:8082/bgp-as-a-service/c550954b-e5e3-4a1d-880c-2450e5f2266e
2025-08-28 03:49:42,842 - DEBUG - Response Code: 404
2025-08-28 03:49:42,842 - DEBUG - BGPaaS: c550954b-e5e3-4a1d-880c-2450e5f2266e deleted from api server
2025-08-28 03:49:42,842 - INFO - BGPaaS(c550954b-e5e3-4a1d-880c-2450e5f2266e): verify_on_cleanup passed
2025-08-28 03:49:42,851 - INFO - Deleting Control node zone ctest-test-zone-38735361(21997825-4dce-4db1-9832-feea2374e19a)
2025-08-28 03:49:43,015 - INFO - Deleting Control node zone ctest-test-zone-52461788(2ec0364e-1e0a-4b4d-955c-74402a131f0a)
2025-08-28 03:49:43,179 - INFO - Deleting Control node zone ctest-test-zone-16997314(93caad4f-f605-4c3e-b57a-bfb13e52993a)
2025-08-28 03:49:43,334 - INFO - Deleting VM ctest-TestBGPaasZone-78530566-45706895
2025-08-28 03:49:43,409 - INFO - Deleting VM ctest-TestBGPaasZone-78530566-55524083
2025-08-28 03:49:43,508 - INFO - Deleting VM ctest-TestBGPaasZone-78530566-46476649
2025-08-28 03:49:43,590 - INFO - Deleting VN ctest-cnz_vn-11580371
2025-08-28 03:49:43,635 - DEBUG - VN 4c39335a-392c-43ee-b656-f8418c359db2 still in use: Unable to complete operation on network 4c39335a-392c-43ee-b656-f8418c359db2. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-255e60f1-75c3-45e6-bb97-3fa8ef79b19f']
2025-08-28 03:49:43,635 - WARNING - Deleting VN ctest-cnz_vn-11580371 failed..Will retry
2025-08-28 03:49:45,821 - DEBUG - Response for deleting network ()
2025-08-28 03:49:47,082 - DEBUG - Skipping xmpp flap check
2025-08-28 03:49:47,082 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:02:46]
2025-08-28 03:49:47,082 - INFO - --------------------------------------------------------------------------------
2025-08-28 03:49:47,808 - INFO - Deleted project: ctest-TestBGPaasZone-78530566, ID : b611c859-440a-4873-8c13-9a54c97d8313