2026-08-31 04:19:40,972 - INFO - Domain Default found not creating 2026-08-31 04:19:41,175 - INFO - Project ctest-TestBGPaaSlocalAS-11189458 not found, creating it 2026-08-31 04:19:41,826 - INFO - Created Project:ctest-TestBGPaaSlocalAS-11189458, ID : ea43cc7d-0234-4c71-ab42-6fe10e5ff5d0 2026-08-31 04:19:43,520 - DEBUG - Services list from nova: [, , , ] 2026-08-31 04:19:43,521 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-6237-1', 'an-jenkins-deploy-platform-ansible-os-6237-2']} 2026-08-31 04:19:43,523 - INFO - ================================================================================ 2026-08-31 04:19:43,523 - INFO - STARTING TEST : test_basic_bgpaas_local_as 2026-08-31 04:19:43,523 - INFO - TEST DESCRIPTION : 1. Create a bgpaas vm. Configure same local-as on vm and contrail side. 2. Make sure BGP with vm comes up. 2026-08-31 04:19:44,852 - DEBUG - Skipping xmpp flap check 2026-08-31 04:19:44,852 - INFO - Initial checks done. Running the testcase now 2026-08-31 04:19:44,853 - INFO - 2026-08-31 04:19:45,714 - DEBUG - Response for create_network : {'network': {'id': '4707fd55-e2d5-4f75-9eac-8e98f97d0a94', 'name': 'ctest-bgpaas_vn-07587068', 'tenant_id': 'ea43cc7d02344c71ab426fe10e5ff5d0', 'project_id': 'ea43cc7d02344c71ab426fe10e5ff5d0', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBGPaaSlocalAS-11189458', 'ctest-bgpaas_vn-07587068'], 'port_security_enabled': True, 'description': ''}} 2026-08-31 04:19:46,033 - DEBUG - Response for create_subnet : {'subnet': {'id': '059b9220-213a-4e41-b2b1-84e705fb2463', 'name': '', 'tenant_id': 'ea43cc7d02344c71ab426fe10e5ff5d0', 'network_id': '4707fd55-e2d5-4f75-9eac-8e98f97d0a94', 'ip_version': 4, 'cidr': '194.222.7.64/26', 'allocation_pools': [{'start': '194.222.7.66', 'end': '194.222.7.126'}], 'gateway_ip': '194.222.7.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '194.222.7.66', 'tags': [], 'project_id': 'ea43cc7d02344c71ab426fe10e5ff5d0'}} 2026-08-31 04:19:46,071 - INFO - Created VN ctest-bgpaas_vn-07587068 2026-08-31 04:19:46,092 - DEBUG - VN ctest-bgpaas_vn-07587068 UUID is 4707fd55-e2d5-4f75-9eac-8e98f97d0a94 2026-08-31 04:19:46,888 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6237-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6237-1) 2026-08-31 04:19:47,011 - DEBUG - Image by name ubuntu-bird not found 2026-08-31 04:19:47,011 - DEBUG - Installing image ubuntu-bird 2026-08-31 04:19:47,011 - DEBUG - Image info {'name': 'ubuntu-bird.vmdk.gz', 'webserver': '', 'location': '/images/converts/', 'username': 'ubuntu', 'password': 'ubuntu', 'type': 'os', 'flavor': 'contrail_flavor_small', 'params': '--container-format bare --disk-format vmdk --property vmware_disktype="sparse" --property vmware_adaptertype="ide" --property hw_scsi_model=virtio-scsi --property hw_disk_bus_model=virtio-scsi --property hw_disk_bus=virtio', 'name_docker': 'phusion-baseimage-enablesshd'} 2026-08-31 04:19:47,012 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//ubuntu-bird.vmdk.gz 2026-08-31 04:19:47,012 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//ubuntu-bird.vmdk.gz 2026-08-31 04:19:47,027 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: wget -nv http://nexus.opensdn.io/repository///images/converts//ubuntu-bird.vmdk.gz -O /contrail-test/images/ubuntu-bird.vmdk.gz 2026-08-31 04:19:49,744 - DEBUG - Image has been downloaded to /contrail-test/images/ubuntu-bird.vmdk.gz 2026-08-31 04:19:49,745 - DEBUG - Unzip image 2026-08-31 04:19:49,745 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/ubuntu-bird.vmdk.gz 2026-08-31 04:20:00,727 - DEBUG - Try to create image in user context 2026-08-31 04:20:08,333 - DEBUG - Image has been created in user context 2026-08-31 04:20:08,333 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/ubuntu-bird.vmdk 2026-08-31 04:20:08,664 - DEBUG - Downloaded image has been removed 2026-08-31 04:20:08,678 - DEBUG - Uploaded image status = active 2026-08-31 04:20:09,215 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6237-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6237-2) 2026-08-31 04:20:09,215 - INFO - Waiting for VM test_vm to be up.. 2026-08-31 04:20:09,310 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-31 04:20:14,415 - DEBUG - VM is in ACTIVE state now 2026-08-31 04:20:14,415 - INFO - VM name : test_vm 2026-08-31 04:20:14,526 - DEBUG - VM test_vm ID is 79284f66-e53e-4b04-bbc4-b1ee64f61543 2026-08-31 04:20:14,555 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-6237-1 2026-08-31 04:20:14,651 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/79284f66-e53e-4b04-bbc4-b1ee64f61543 2026-08-31 04:20:14,999 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/79284f66-e53e-4b04-bbc4-b1ee64f61543 2026-08-31 04:20:15,035 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/77444006-7b56-44f6-a0d4-5fa296d201ea 2026-08-31 04:20:18,227 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1009ms') 2026-08-31 04:20:18,227 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed! 2026-08-31 04:20:18,250 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-11189458:ctest-bgpaas_vn-07587068 is 194.222.7.65 and allocation pool is NOT set 2026-08-31 04:20:22,356 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1026ms') 2026-08-31 04:20:22,356 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed! 2026-08-31 04:20:22,375 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-11189458:ctest-bgpaas_vn-07587068 is 194.222.7.65 and allocation pool is NOT set 2026-08-31 04:20:26,477 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1028ms') 2026-08-31 04:20:26,477 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM test_vm failed! 2026-08-31 04:20:26,499 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-11189458:ctest-bgpaas_vn-07587068 is 194.222.7.65 and allocation pool is NOT set 2026-08-31 04:20:28,571 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=5.41 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.10 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 3.102/4.253/5.405/1.151 ms') 2026-08-31 04:20:28,571 - INFO - Ping to Metadata IP 169.254.0.3 of VM test_vm passed 2026-08-31 04:20:28,650 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:20:28,651 - DEBUG - Waiting to SSH to VM test_vm, IP 194.222.7.67, Port 22 2026-08-31 04:20:28,724 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-31 04:20:28,847 - DEBUG - VM test_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-08-31 04:20:33,848 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:20:33,848 - DEBUG - Waiting to SSH to VM test_vm, IP 194.222.7.67, Port 22 2026-08-31 04:20:33,916 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-31 04:20:34,037 - DEBUG - VM test_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-08-31 04:20:39,038 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:20:39,038 - DEBUG - Waiting to SSH to VM test_vm, IP 194.222.7.67, Port 22 2026-08-31 04:20:39,212 - DEBUG - VM test_vm is ready for SSH connections 2026-08-31 04:20:39,212 - INFO - Waiting for VM bgpaas_vm1 to be up.. 2026-08-31 04:20:39,314 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-31 04:20:44,414 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-31 04:20:49,801 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-31 04:20:54,895 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-31 04:20:59,994 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-31 04:21:05,086 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-08-31 04:21:10,191 - DEBUG - VM is in ACTIVE state now 2026-08-31 04:21:10,191 - INFO - VM name : bgpaas_vm1 2026-08-31 04:21:10,287 - DEBUG - VM bgpaas_vm1 ID is 4f273722-6f5a-45c3-a358-04102c060459 2026-08-31 04:21:10,287 - DEBUG - VM bgpaas_vm1 launched on Node an-jenkins-deploy-platform-ansible-os-6237-2 2026-08-31 04:21:10,399 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine/4f273722-6f5a-45c3-a358-04102c060459 2026-08-31 04:21:10,414 - DEBUG - Requesting: http://10.0.0.38:8082/virtual-machine-interface/dc93a44b-3fcb-4649-841d-3351c6a75967 2026-08-31 04:21:13,615 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1020ms') 2026-08-31 04:21:13,615 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2026-08-31 04:21:13,634 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-11189458:ctest-bgpaas_vn-07587068 is 194.222.7.65 and allocation pool is NOT set 2026-08-31 04:21:17,712 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2026-08-31 04:21:17,712 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2026-08-31 04:21:17,730 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-11189458:ctest-bgpaas_vn-07587068 is 194.222.7.65 and allocation pool is NOT set 2026-08-31 04:21:21,802 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1012ms') 2026-08-31 04:21:21,802 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2026-08-31 04:21:21,819 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-11189458:ctest-bgpaas_vn-07587068 is 194.222.7.65 and allocation pool is NOT set 2026-08-31 04:21:25,911 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1025ms') 2026-08-31 04:21:25,912 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2026-08-31 04:21:25,931 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-11189458:ctest-bgpaas_vn-07587068 is 194.222.7.65 and allocation pool is NOT set 2026-08-31 04:21:30,028 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1032ms') 2026-08-31 04:21:30,028 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2026-08-31 04:21:30,054 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-11189458:ctest-bgpaas_vn-07587068 is 194.222.7.65 and allocation pool is NOT set 2026-08-31 04:21:34,130 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1008ms') 2026-08-31 04:21:34,131 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2026-08-31 04:21:34,149 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-11189458:ctest-bgpaas_vn-07587068 is 194.222.7.65 and allocation pool is NOT set 2026-08-31 04:21:38,225 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1008ms') 2026-08-31 04:21:38,225 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2026-08-31 04:21:38,241 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-11189458:ctest-bgpaas_vn-07587068 is 194.222.7.65 and allocation pool is NOT set 2026-08-31 04:21:42,315 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1008ms') 2026-08-31 04:21:42,315 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2026-08-31 04:21:42,331 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-11189458:ctest-bgpaas_vn-07587068 is 194.222.7.65 and allocation pool is NOT set 2026-08-31 04:21:46,418 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1016ms') 2026-08-31 04:21:46,418 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2026-08-31 04:21:46,435 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-11189458:ctest-bgpaas_vn-07587068 is 194.222.7.65 and allocation pool is NOT set 2026-08-31 04:21:50,517 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1009ms') 2026-08-31 04:21:50,517 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2026-08-31 04:21:50,533 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-11189458:ctest-bgpaas_vn-07587068 is 194.222.7.65 and allocation pool is NOT set 2026-08-31 04:21:54,610 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1011ms') 2026-08-31 04:21:54,610 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2026-08-31 04:21:54,629 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-11189458:ctest-bgpaas_vn-07587068 is 194.222.7.65 and allocation pool is NOT set 2026-08-31 04:21:58,706 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1005ms') 2026-08-31 04:21:58,706 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2026-08-31 04:21:58,727 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-11189458:ctest-bgpaas_vn-07587068 is 194.222.7.65 and allocation pool is NOT set 2026-08-31 04:22:02,801 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=7.96 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 1 received, 50% packet loss, time 1009ms\r\nrtt min/avg/max/mdev = 7.958/7.958/7.958/0.000 ms') 2026-08-31 04:22:02,801 - INFO - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 passed 2026-08-31 04:22:02,880 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:22:02,880 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 194.222.7.68, Port 22 2026-08-31 04:22:02,962 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-31 04:22:03,065 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-31 04:22:08,065 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:22:08,065 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 194.222.7.68, Port 22 2026-08-31 04:22:08,134 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-31 04:22:08,230 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-31 04:22:13,231 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:22:13,231 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 194.222.7.68, Port 22 2026-08-31 04:22:13,299 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-31 04:22:13,401 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-31 04:22:18,401 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:22:18,402 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 194.222.7.68, Port 22 2026-08-31 04:22:18,472 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-31 04:22:18,565 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-31 04:22:23,566 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:22:23,566 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 194.222.7.68, Port 22 2026-08-31 04:22:23,635 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-31 04:22:23,742 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-31 04:22:28,743 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:22:28,743 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 194.222.7.68, Port 22 2026-08-31 04:22:28,811 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-31 04:22:28,904 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-31 04:22:33,905 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:22:33,905 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 194.222.7.68, Port 22 2026-08-31 04:22:33,975 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-31 04:22:34,073 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-31 04:22:39,074 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:22:39,074 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 194.222.7.68, Port 22 2026-08-31 04:22:39,144 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-31 04:22:39,254 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-31 04:22:44,255 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:22:44,255 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 194.222.7.68, Port 22 2026-08-31 04:22:44,322 - DEBUG - Error on ssh to ubuntu@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-08-31 04:22:44,415 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2026-08-31 04:22:49,416 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:22:49,416 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 194.222.7.68, Port 22 2026-08-31 04:22:49,591 - DEBUG - VM bgpaas_vm1 is ready for SSH connections 2026-08-31 04:22:49,591 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:22:49,598 - INFO - Creating BGPaaS ctest-TestBGPaaSlocalAS-11189458-67848687 2026-08-31 04:22:49,686 - INFO - BGPaaS: ctest-TestBGPaaSlocalAS-11189458-67848687(a4d9cee7-dc06-4525-9702-5befa902a3ac) 2026-08-31 04:22:49,687 - DEBUG - Requesting: http://10.0.0.50:8082/bgp-as-a-service/a4d9cee7-dc06-4525-9702-5befa902a3ac 2026-08-31 04:22:50,074 - DEBUG - Requesting: http://10.0.0.50:8082/bgp-as-a-service/a4d9cee7-dc06-4525-9702-5befa902a3ac 2026-08-31 04:22:50,119 - INFO - verify_in_api_server passed for BGPaaS obj a4d9cee7-dc06-4525-9702-5befa902a3ac 2026-08-31 04:22:50,120 - INFO - BGPaaS(a4d9cee7-dc06-4525-9702-5befa902a3ac): verify_on_setup passed 2026-08-31 04:22:50,120 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:22:50,120 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 194.222.7.68, Port 22 2026-08-31 04:22:50,291 - DEBUG - VM bgpaas_vm1 is ready for SSH connections 2026-08-31 04:22:50,291 - INFO - Attaching VMI dc93a44b-3fcb-4649-841d-3351c6a75967 to BGPaaS a4d9cee7-dc06-4525-9702-5befa902a3ac 2026-08-31 04:22:50,378 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:22:50,378 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:22:50,572 - INFO - Configuring BGP on the bird 2026-08-31 04:22:50,572 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-08-31 04:22:50,573 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 194.222.7.68, Port 22 2026-08-31 04:22:50,759 - DEBUG - VM bgpaas_vm1 is ready for SSH connections 2026-08-31 04:22:50,759 - INFO - Configuring BGP on bgpaas_vm1 2026-08-31 04:22:50,759 - 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 194.222.7.68; protocol bgp bgp_1{ local as 64500; neighbor 194.222.7.65 as 425; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 194.222.7.68; } protocol bfd { neighbor 194.222.7.65 local 194.222.7.68 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.176, gateway password: c0ntrail123 2026-08-31 04:22:50,759 - 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 194.222.7.68; protocol bgp bgp_1{ local as 64500; neighbor 194.222.7.65 as 425; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 194.222.7.68; } protocol bfd { neighbor 194.222.7.65 local 194.222.7.68 multihop on; } EOS 2026-08-31 04:23:00,129 - DEBUG - None 2026-08-31 04:23:00,129 - 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 194.222.7.68; protocol bgp bgp_1{ local as 64500; neighbor 194.222.7.65 as 425; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 194.222.7.68; } protocol bfd { neighbor 194.222.7.65 local 194.222.7.68 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.176, gateway password: c0ntrail123 2026-08-31 04:23:00,129 - 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 194.222.7.68; protocol bgp bgp_1{ local as 64500; neighbor 194.222.7.65 as 425; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 194.222.7.68; } protocol bfd { neighbor 194.222.7.65 local 194.222.7.68 multihop on; } EOS 2026-08-31 04:23:01,036 - DEBUG - None 2026-08-31 04:23:01,036 - DEBUG - Running remote_cmd, Cmd : service bird restart, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.176, gateway password: c0ntrail123 2026-08-31 04:23:01,036 - DEBUG - service bird restart 2026-08-31 04:23:02,958 - DEBUG - bird stop/waiting bird start/running, process 1431 2026-08-31 04:23:02,977 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-08-31 04:23:02,996 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-08-31 04:23:09,035 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-08-31 04:23:09,050 - INFO - BGPaaS session seen in control-node 10.20.0.14 2026-08-31 04:23:09,050 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-08-31 04:23:09,066 - INFO - Detaching VMI dc93a44b-3fcb-4649-841d-3351c6a75967 from BGPaaS a4d9cee7-dc06-4525-9702-5befa902a3ac 2026-08-31 04:23:09,154 - INFO - Deleting BGPaaS ctest-TestBGPaaSlocalAS-11189458-67848687(a4d9cee7-dc06-4525-9702-5befa902a3ac) 2026-08-31 04:23:09,233 - DEBUG - Requesting: http://10.0.0.50:8082/bgp-as-a-service/a4d9cee7-dc06-4525-9702-5befa902a3ac 2026-08-31 04:23:09,245 - DEBUG - Response Code: 404 2026-08-31 04:23:09,245 - DEBUG - BGPaaS: a4d9cee7-dc06-4525-9702-5befa902a3ac deleted from api server 2026-08-31 04:23:09,245 - INFO - BGPaaS(a4d9cee7-dc06-4525-9702-5befa902a3ac): verify_on_cleanup passed 2026-08-31 04:23:09,245 - INFO - Deleting VM bgpaas_vm1 2026-08-31 04:23:09,424 - INFO - Deleting VM test_vm 2026-08-31 04:23:09,503 - INFO - Deleting VN ctest-bgpaas_vn-07587068 2026-08-31 04:23:09,550 - DEBUG - VN 4707fd55-e2d5-4f75-9eac-8e98f97d0a94 still in use: Unable to complete operation on network 4707fd55-e2d5-4f75-9eac-8e98f97d0a94. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-50cb3b9f-b60d-4c82-af1d-44c0ff0b9461'] 2026-08-31 04:23:09,551 - WARNING - Deleting VN ctest-bgpaas_vn-07587068 failed..Will retry 2026-08-31 04:23:11,602 - DEBUG - VN 4707fd55-e2d5-4f75-9eac-8e98f97d0a94 still in use: Unable to complete operation on network 4707fd55-e2d5-4f75-9eac-8e98f97d0a94. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-c251089e-6464-42a7-97c8-e71c08862388'] 2026-08-31 04:23:11,603 - WARNING - Deleting VN ctest-bgpaas_vn-07587068 failed..Will retry 2026-08-31 04:23:13,804 - DEBUG - Response for deleting network () 2026-08-31 04:23:15,132 - DEBUG - Skipping xmpp flap check
2026-08-31 04:23:15,133 - INFO - END TEST : test_basic_bgpaas_local_as : PASSED[0:03:32]
2026-08-31 04:23:15,133 - INFO - -------------------------------------------------------------------------------- 2026-08-31 04:23:16,021 - INFO - Deleted project: ctest-TestBGPaaSlocalAS-11189458, ID : ea43cc7d-0234-4c71-ab42-6fe10e5ff5d0 2026-08-31 04:27:17,153 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-08-31 04:27:23,170 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-08-31 04:27:31,072 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-08-31 04:27:37,093 - DEBUG - Requesting: http://10.20.0.25:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-08-31 04:27:37,119 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-08-31 04:27:43,141 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-08-31 04:27:56,488 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-08-31 04:31:14,310 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-08-31 04:31:20,331 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-08-31 04:31:20,404 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-08-31 04:31:24,961 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address= 2026-08-31 04:31:24,998 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address=