is in ACTIVE state now
2025-09-18 03:55:43,346 - INFO - VM name : ctest-TestBGPaasZone-76447129-16635860
2025-09-18 03:55:43,441 - DEBUG - VM ctest-TestBGPaasZone-76447129-16635860 ID is a08221bc-4734-410c-bdb8-247272fa95e2
2025-09-18 03:55:43,441 - DEBUG - VM ctest-TestBGPaasZone-76447129-16635860 launched on Node an-jenkins-deploy-platform-ansible-os-4130-2
2025-09-18 03:55:43,532 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine/a08221bc-4734-410c-bdb8-247272fa95e2
2025-09-18 03:55:43,543 - DEBUG - Requesting: http://10.0.0.41:8082/virtual-machine-interface/c6a7fb09-f9bf-4f68-80dc-d295924ef2df
2025-09-18 03:55:44,710 - 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.20 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.452 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.452/2.325/4.198/1.873 ms')
2025-09-18 03:55:44,710 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-TestBGPaasZone-76447129-16635860 passed
2025-09-18 03:55:44,783 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-18 03:55:44,783 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-76447129-16635860, IP 103.214.203.133, Port 22
2025-09-18 03:55:44,851 - 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-09-18 03:55:44,941 - DEBUG - VM ctest-TestBGPaasZone-76447129-16635860 is NOT ready for SSH connections, VM status: ACTIVE
2025-09-18 03:55:49,942 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-18 03:55:49,942 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-76447129-16635860, IP 103.214.203.133, Port 22
2025-09-18 03:55:50,001 - 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-09-18 03:55:50,092 - DEBUG - VM ctest-TestBGPaasZone-76447129-16635860 is NOT ready for SSH connections, VM status: ACTIVE
2025-09-18 03:55:55,093 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-18 03:55:55,093 - DEBUG - Waiting to SSH to VM ctest-TestBGPaasZone-76447129-16635860, IP 103.214.203.133, Port 22
2025-09-18 03:55:55,259 - DEBUG - VM ctest-TestBGPaasZone-76447129-16635860 is ready for SSH connections
2025-09-18 03:55:55,405 - INFO - Created control node zone ctest-test-zone-05250969(3709e357-1846-4d9f-b835-c42b016e39f9)
2025-09-18 03:55:55,761 - INFO - Created control node zone ctest-test-zone-99304253(758e68bc-78ba-4384-b6ba-0f27299a9144)
2025-09-18 03:55:56,133 - INFO - Created control node zone ctest-test-zone-61781657(3e2d7e78-d83f-468b-8fbb-106ab52f630a)
2025-09-18 03:55:56,413 - INFO - Creating BGPaaS ctest-TestBGPaasZone-76447129-06470091
2025-09-18 03:55:56,493 - INFO - BGPaaS: ctest-TestBGPaasZone-76447129-06470091(bad8568e-2546-4a6d-9c10-cd6e95da3e63)
2025-09-18 03:55:56,494 - DEBUG - Requesting: http://10.0.0.56:8082/bgp-as-a-service/bad8568e-2546-4a6d-9c10-cd6e95da3e63
2025-09-18 03:55:56,507 - INFO - verify_in_api_server passed for BGPaaS obj bad8568e-2546-4a6d-9c10-cd6e95da3e63
2025-09-18 03:55:56,507 - INFO - BGPaaS(bad8568e-2546-4a6d-9c10-cd6e95da3e63): verify_on_setup passed
2025-09-18 03:55:56,507 - INFO - We will configure BGP on the VM
2025-09-18 03:55:56,945 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-18 03:55:56,946 - INFO - Configuring BGP on ctest-TestBGPaasZone-76447129-93601462
2025-09-18 03:55:56,946 - 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 103.214.203.131;
protocol bgp bgp_1{
local as 65000;
neighbor 103.214.203.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 103.214.203.131;
}
protocol bfd {
neighbor 103.214.203.129 local 103.214.203.131 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-09-18 03:55:56,946 - 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 103.214.203.131;
protocol bgp bgp_1{
local as 65000;
neighbor 103.214.203.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 103.214.203.131;
}
protocol bfd {
neighbor 103.214.203.129 local 103.214.203.131 multihop on;
}
EOS
2025-09-18 03:56:14,570 - DEBUG - None
2025-09-18 03:56:14,570 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 103.214.203.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 103.214.203.131;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-09-18 03:56:14,571 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 103.214.203.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 103.214.203.131;
}
EOS
2025-09-18 03:56:15,117 - DEBUG - None
2025-09-18 03:56:15,117 - 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-09-18 03:56:15,118 - DEBUG - service bird restart
2025-09-18 03:56:16,756 - DEBUG - bird stop/waiting
bird start/running, process 1435
2025-09-18 03:56:16,756 - INFO - Attaching VMI 0a9e166f-98d6-4c34-bf0a-48ff8b58b88d to BGPaaS bad8568e-2546-4a6d-9c10-cd6e95da3e63
2025-09-18 03:56:16,833 - INFO - Attaching the VMI e7c3aef0-a306-4c08-a8df-de3a37d8935b to the BGPaaS bad8568e-2546-4a6d-9c10-cd6e95da3e63 object
2025-09-18 03:56:16,834 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 103.214.203.148 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-09-18 03:56:16,834 - DEBUG - sudo ip addr add 103.214.203.148 dev eth0
2025-09-18 03:56:17,488 - DEBUG - None
2025-09-18 03:56:17,549 - INFO - Creating BGPaaS ctest-TestBGPaasZone-76447129-99364999
2025-09-18 03:56:17,625 - INFO - BGPaaS: ctest-TestBGPaasZone-76447129-99364999(08392f3e-92df-4d88-b5f5-871db3bb965d)
2025-09-18 03:56:17,626 - DEBUG - Requesting: http://10.0.0.56:8082/bgp-as-a-service/08392f3e-92df-4d88-b5f5-871db3bb965d
2025-09-18 03:56:17,666 - INFO - verify_in_api_server passed for BGPaaS obj 08392f3e-92df-4d88-b5f5-871db3bb965d
2025-09-18 03:56:17,666 - INFO - BGPaaS(08392f3e-92df-4d88-b5f5-871db3bb965d): verify_on_setup passed
2025-09-18 03:56:17,667 - INFO - We will configure BGP on the VM
2025-09-18 03:56:18,122 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-18 03:56:18,122 - INFO - Configuring BGP on ctest-TestBGPaasZone-76447129-39861545
2025-09-18 03:56:18,122 - 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 103.214.203.132;
protocol bgp bgp_1{
local as 65000;
neighbor 103.214.203.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 103.214.203.132;
}
protocol bfd {
neighbor 103.214.203.129 local 103.214.203.132 multihop on;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-09-18 03:56:18,122 - 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 103.214.203.132;
protocol bgp bgp_1{
local as 65000;
neighbor 103.214.203.129 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 103.214.203.132;
}
protocol bfd {
neighbor 103.214.203.129 local 103.214.203.132 multihop on;
}
EOS
2025-09-18 03:56:33,631 - DEBUG - None
2025-09-18 03:56:33,632 - DEBUG - Running remote_cmd, Cmd : cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 103.214.203.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 103.214.203.132;
}
EOS
, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-09-18 03:56:33,632 - DEBUG - cat >> /etc/bird/bird.conf << EOS
protocol bgp bgp_2{
local as 65000;
neighbor 103.214.203.130 as 64512;
export where source = RTS_STATIC;
export all;
multihop;
#export all;
hold time 90;
bfd on;
source address 103.214.203.132;
}
EOS
2025-09-18 03:56:34,149 - DEBUG - None
2025-09-18 03:56:34,149 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-09-18 03:56:34,149 - DEBUG - service bird restart
2025-09-18 03:56:36,024 - DEBUG - bird stop/waiting
bird start/running, process 1442
2025-09-18 03:56:36,024 - INFO - Attaching VMI a5514d64-d221-481f-956b-39f7aeecf93c to BGPaaS 08392f3e-92df-4d88-b5f5-871db3bb965d
2025-09-18 03:56:36,097 - INFO - Attaching the VMI 629495ed-9981-4c08-bbfe-dc49a134b77a to the BGPaaS 08392f3e-92df-4d88-b5f5-871db3bb965d object
2025-09-18 03:56:36,097 - DEBUG - Running remote_cmd, Cmd : sudo ip addr add 103.214.203.148 dev eth0, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.61, gateway password: c0ntrail123
2025-09-18 03:56:36,098 - DEBUG - sudo ip addr add 103.214.203.148 dev eth0
2025-09-18 03:56:36,823 - DEBUG - None
2025-09-18 03:56:36,823 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-18 03:56:36,838 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-09-18 03:56:36,838 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-18 03:56:36,857 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-09-18 03:56:36,863 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-18 03:56:36,878 - ERROR - Not enough BGPaaS sessions seen in control-nodes ['10.20.0.25']
2025-09-18 03:56:42,896 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-09-18 03:56:42,896 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-18 03:56:42,916 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-09-18 03:56:42,923 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 103.214.203.148, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-09-18 03:56:42,923 - DEBUG - ping -s 56 -c 3 -W 1 103.214.203.148
2025-09-18 03:56:54,642 - DEBUG - PING 103.214.203.148 (103.214.203.148) 56(84) bytes of data.
64 bytes from 103.214.203.148: icmp_seq=1 ttl=64 time=6.50 ms
64 bytes from 103.214.203.148: icmp_seq=2 ttl=64 time=0.936 ms
64 bytes from 103.214.203.148: icmp_seq=3 ttl=64 time=0.805 ms
--- 103.214.203.148 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2005ms
rtt min/avg/max/mdev = 0.805/2.749/6.508/2.658 ms
2025-09-18 03:56:54,642 - INFO - Ping to IP 103.214.203.148 from VM ctest-TestBGPaasZone-76447129-16635860 passed
2025-09-18 03:56:54,642 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-18 03:56:54,659 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-09-18 03:56:54,660 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-18 03:56:54,680 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-09-18 03:56:54,688 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-18 03:56:54,706 - INFO - BGPaaS session seen in control-node 10.20.0.25
2025-09-18 03:56:54,707 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set
2025-09-18 03:56:54,726 - INFO - BGPaaS session seen in control-node 10.20.0.17
2025-09-18 03:56:54,732 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 103.214.203.148, host_string: ubuntu@169.254.0.4, password: ubuntugateway: ubuntu@10.0.0.27, gateway password: c0ntrail123
2025-09-18 03:56:54,732 - DEBUG - ping -s 56 -c 3 -W 1 103.214.203.148
2025-09-18 03:56:57,187 - DEBUG - PING 103.214.203.148 (103.214.203.148) 56(84) bytes of data.
64 bytes from 103.214.203.148: icmp_seq=1 ttl=64 time=1.63 ms
64 bytes from 103.214.203.148: icmp_seq=2 ttl=64 time=0.851 ms
64 bytes from 103.214.203.148: icmp_seq=3 ttl=64 time=0.928 ms
--- 103.214.203.148 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 2003ms
rtt min/avg/max/mdev = 0.851/1.139/1.638/0.354 ms
2025-09-18 03:56:57,187 - INFO - Ping to IP 103.214.203.148 from VM ctest-TestBGPaasZone-76447129-16635860 passed
2025-09-18 03:56:57,188 - INFO - Detaching VMI a5514d64-d221-481f-956b-39f7aeecf93c from BGPaaS 08392f3e-92df-4d88-b5f5-871db3bb965d
2025-09-18 03:56:57,269 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-76447129-99364999(08392f3e-92df-4d88-b5f5-871db3bb965d)
2025-09-18 03:56:57,332 - DEBUG - Requesting: http://10.0.0.56:8082/bgp-as-a-service/08392f3e-92df-4d88-b5f5-871db3bb965d
2025-09-18 03:56:57,340 - DEBUG - Response Code: 404
2025-09-18 03:56:57,340 - DEBUG - BGPaaS: 08392f3e-92df-4d88-b5f5-871db3bb965d deleted from api server
2025-09-18 03:56:57,340 - INFO - BGPaaS(08392f3e-92df-4d88-b5f5-871db3bb965d): verify_on_cleanup passed
2025-09-18 03:56:57,340 - INFO - Detaching VMI 0a9e166f-98d6-4c34-bf0a-48ff8b58b88d from BGPaaS bad8568e-2546-4a6d-9c10-cd6e95da3e63
2025-09-18 03:56:57,425 - INFO - Deleting BGPaaS ctest-TestBGPaasZone-76447129-06470091(bad8568e-2546-4a6d-9c10-cd6e95da3e63)
2025-09-18 03:56:57,481 - DEBUG - Requesting: http://10.0.0.56:8082/bgp-as-a-service/bad8568e-2546-4a6d-9c10-cd6e95da3e63
2025-09-18 03:56:57,489 - DEBUG - Response Code: 404
2025-09-18 03:56:57,490 - DEBUG - BGPaaS: bad8568e-2546-4a6d-9c10-cd6e95da3e63 deleted from api server
2025-09-18 03:56:57,490 - INFO - BGPaaS(bad8568e-2546-4a6d-9c10-cd6e95da3e63): verify_on_cleanup passed
2025-09-18 03:56:57,490 - INFO - Deleting Control node zone ctest-test-zone-61781657(3e2d7e78-d83f-468b-8fbb-106ab52f630a)
2025-09-18 03:56:57,641 - INFO - Deleting Control node zone ctest-test-zone-99304253(758e68bc-78ba-4384-b6ba-0f27299a9144)
2025-09-18 03:56:57,819 - INFO - Deleting Control node zone ctest-test-zone-05250969(3709e357-1846-4d9f-b835-c42b016e39f9)
2025-09-18 03:56:58,003 - INFO - Deleting VM ctest-TestBGPaasZone-76447129-16635860
2025-09-18 03:56:58,168 - INFO - Deleting VM ctest-TestBGPaasZone-76447129-39861545
2025-09-18 03:56:58,256 - INFO - Deleting VM ctest-TestBGPaasZone-76447129-93601462
2025-09-18 03:56:58,333 - INFO - Deleting VN ctest-cnz_vn-27177780
2025-09-18 03:56:58,376 - DEBUG - VN 535231c5-5e7d-42f6-bcb7-e589fcbd42a4 still in use: Unable to complete operation on network 535231c5-5e7d-42f6-bcb7-e589fcbd42a4. There are one or more ports still in use on the network.
Neutron server returns request_ids: ['req-a7cca40a-7c5a-474c-88d3-5c7208e3b71f']
2025-09-18 03:56:58,376 - WARNING - Deleting VN ctest-cnz_vn-27177780 failed..Will retry
2025-09-18 03:57:00,607 - DEBUG - Response for deleting network ()
2025-09-18 03:57:01,913 - DEBUG - Skipping xmpp flap check
2025-09-18 03:57:01,913 - INFO - END TEST : test_bgp_control_node_zones_from_single_vnf : PASSED[0:04:21]
2025-09-18 03:57:01,913 - INFO - --------------------------------------------------------------------------------
2025-09-18 03:57:02,884 - INFO - Deleted project: ctest-TestBGPaasZone-76447129, ID : c324f0b1-77af-4b12-b7ec-3a17cfa09c36