2026-10-05 05:06:08,218 - INFO - Domain Default found not creating 2026-10-05 05:06:08,413 - INFO - Project ctest-TestBGPaaSlocalAS-02283734 not found, creating it 2026-10-05 05:06:09,026 - INFO - Created Project:ctest-TestBGPaaSlocalAS-02283734, ID : 6a1d62ed-313c-4191-9e27-ad7d3ade9c91 2026-10-05 05:06:11,016 - DEBUG - Services list from nova: [, , , ] 2026-10-05 05:06:11,016 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-6343-1', 'an-jenkins-deploy-platform-ansible-os-6343-2']} 2026-10-05 05:06:11,019 - INFO - ================================================================================ 2026-10-05 05:06:11,020 - INFO - STARTING TEST : test_basic_bgpaas_local_as 2026-10-05 05:06:11,020 - 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-10-05 05:06:12,343 - DEBUG - Skipping xmpp flap check 2026-10-05 05:06:12,344 - INFO - Initial checks done. Running the testcase now 2026-10-05 05:06:12,344 - INFO - 2026-10-05 05:06:13,206 - DEBUG - Response for create_network : {'network': {'id': '0b8124fb-2e48-4fb3-a821-c50511152321', 'name': 'ctest-bgpaas_vn-45010532', 'tenant_id': '6a1d62ed313c41919e27ad7d3ade9c91', 'project_id': '6a1d62ed313c41919e27ad7d3ade9c91', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBGPaaSlocalAS-02283734', 'ctest-bgpaas_vn-45010532'], 'port_security_enabled': True, 'description': ''}} 2026-10-05 05:06:13,503 - DEBUG - Response for create_subnet : {'subnet': {'id': '32422777-54e0-4a8c-b340-68d8830dcf9a', 'name': '', 'tenant_id': '6a1d62ed313c41919e27ad7d3ade9c91', 'network_id': '0b8124fb-2e48-4fb3-a821-c50511152321', 'ip_version': 4, 'cidr': '165.92.42.64/26', 'allocation_pools': [{'start': '165.92.42.66', 'end': '165.92.42.126'}], 'gateway_ip': '165.92.42.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '165.92.42.66', 'tags': [], 'project_id': '6a1d62ed313c41919e27ad7d3ade9c91'}} 2026-10-05 05:06:13,528 - INFO - Created VN ctest-bgpaas_vn-45010532 2026-10-05 05:06:13,544 - DEBUG - VN ctest-bgpaas_vn-45010532 UUID is 0b8124fb-2e48-4fb3-a821-c50511152321 2026-10-05 05:06:14,326 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6343-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6343-1) 2026-10-05 05:06:14,511 - DEBUG - Image by name ubuntu-bird not found 2026-10-05 05:06:14,512 - DEBUG - Installing image ubuntu-bird 2026-10-05 05:06:14,512 - 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-10-05 05:06:14,512 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//ubuntu-bird.vmdk.gz 2026-10-05 05:06:14,512 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//ubuntu-bird.vmdk.gz 2026-10-05 05:06:14,526 - 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-10-05 05:06:17,257 - DEBUG - Image has been downloaded to /contrail-test/images/ubuntu-bird.vmdk.gz 2026-10-05 05:06:17,257 - DEBUG - Unzip image 2026-10-05 05:06:17,257 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/ubuntu-bird.vmdk.gz 2026-10-05 05:06:29,441 - DEBUG - Try to create image in user context 2026-10-05 05:06:38,048 - DEBUG - Image has been created in user context 2026-10-05 05:06:38,048 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/ubuntu-bird.vmdk 2026-10-05 05:06:38,218 - DEBUG - Downloaded image has been removed 2026-10-05 05:06:38,246 - DEBUG - Uploaded image status = active 2026-10-05 05:06:38,819 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-6343-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-6343-2) 2026-10-05 05:06:38,819 - INFO - Waiting for VM test_vm to be up.. 2026-10-05 05:06:38,913 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:06:44,011 - DEBUG - VM is in ACTIVE state now 2026-10-05 05:06:44,012 - INFO - VM name : test_vm 2026-10-05 05:06:44,106 - DEBUG - VM test_vm ID is a93f7a27-706b-4153-9a9f-50cc8f1d817a 2026-10-05 05:06:44,135 - DEBUG - VM test_vm launched on Node an-jenkins-deploy-platform-ansible-os-6343-1 2026-10-05 05:06:44,232 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-machine/a93f7a27-706b-4153-9a9f-50cc8f1d817a 2026-10-05 05:06:44,611 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-machine/a93f7a27-706b-4153-9a9f-50cc8f1d817a 2026-10-05 05:06:44,647 - DEBUG - Requesting: http://10.0.0.190:8082/virtual-machine-interface/3d4a8927-1954-4f26-b85e-5b405f8e16bb 2026-10-05 05:06:47,871 - DEBUG - (True, 'PING 169.254.0.7 (169.254.0.7) 56(84) bytes of data.\r\n\r\n--- 169.254.0.7 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1030ms') 2026-10-05 05:06:47,872 - DEBUG - Ping to Metadata IP 169.254.0.7 of VM test_vm failed! 2026-10-05 05:06:47,889 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-02283734:ctest-bgpaas_vn-45010532 is 165.92.42.65 and allocation pool is NOT set 2026-10-05 05:06:51,970 - DEBUG - (True, 'PING 169.254.0.7 (169.254.0.7) 56(84) bytes of data.\r\n\r\n--- 169.254.0.7 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1013ms') 2026-10-05 05:06:51,970 - DEBUG - Ping to Metadata IP 169.254.0.7 of VM test_vm failed! 2026-10-05 05:06:51,989 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-02283734:ctest-bgpaas_vn-45010532 is 165.92.42.65 and allocation pool is NOT set 2026-10-05 05:06:56,062 - DEBUG - (True, 'PING 169.254.0.7 (169.254.0.7) 56(84) bytes of data.\r\n\r\n--- 169.254.0.7 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1006ms') 2026-10-05 05:06:56,063 - DEBUG - Ping to Metadata IP 169.254.0.7 of VM test_vm failed! 2026-10-05 05:06:56,081 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaSlocalAS-02283734:ctest-bgpaas_vn-45010532 is 165.92.42.65 and allocation pool is NOT set 2026-10-05 05:06:58,153 - DEBUG - (True, 'PING 169.254.0.7 (169.254.0.7) 56(84) bytes of data.\r\n64 bytes from 169.254.0.7: icmp_seq=1 ttl=63 time=6.52 ms\r\n64 bytes from 169.254.0.7: icmp_seq=2 ttl=63 time=3.10 ms\r\n\r\n--- 169.254.0.7 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 3.096/4.807/6.519/1.711 ms') 2026-10-05 05:06:58,153 - INFO - Ping to Metadata IP 169.254.0.7 of VM test_vm passed 2026-10-05 05:06:58,225 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-05 05:06:58,225 - DEBUG - Waiting to SSH to VM test_vm, IP 165.92.42.67, Port 22 2026-10-05 05:06:58,293 - DEBUG - Error on ssh to cirros@169.254.0.7:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.7/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.7/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.7/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2026-10-05 05:06:58,395 - DEBUG - VM test_vm is NOT ready for SSH connections, VM status: ACTIVE 2026-10-05 05:07:03,396 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-05 05:07:03,396 - DEBUG - Waiting to SSH to VM test_vm, IP 165.92.42.67, Port 22 2026-10-05 05:07:03,568 - DEBUG - VM test_vm is ready for SSH connections 2026-10-05 05:07:03,568 - INFO - Waiting for VM bgpaas_vm1 to be up.. 2026-10-05 05:07:03,629 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:07:08,706 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:07:13,852 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:07:18,926 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:07:24,152 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:07:29,210 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:07:34,266 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:07:39,336 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:07:44,401 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:07:49,470 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:07:54,536 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:07:59,601 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:08:04,663 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:08:09,734 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:08:14,793 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:08:19,850 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:08:24,914 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:08:29,970 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:08:35,043 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:08:40,106 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:08:45,163 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:08:50,236 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:08:55,291 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:09:00,365 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:09:05,425 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:09:10,493 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:09:15,553 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:09:20,631 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:09:25,701 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:09:30,760 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:09:35,829 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-05 05:09:35,829 - ERROR - VM is not in ACTIVE state 2026-10-05 05:09:35,927 - ERROR - Fatal Nova Exception while getting VM detail 2026-10-05 05:09:35,927 - DEBUG - None 2026-10-05 05:09:35,934 - INFO - Skip interface_detach for VM bgpaas_vm1 in state BUILD 2026-10-05 05:09:35,934 - INFO - Deleting VM bgpaas_vm1 2026-10-05 05:09:35,934 - ERROR - VM bgpaas_vm1 has failed to come up 2026-10-05 05:09:35,934 - ERROR - Nova failed to get host of the VM 2026-10-05 05:09:35,935 - ERROR - VM bgpaas_vm1 has failed to come up 2026-10-05 05:09:35,935 - ERROR - Nova failed to get host of the VM 2026-10-05 05:09:36,151 - INFO - Deleting VM test_vm 2026-10-05 05:09:36,263 - INFO - Deleting VN ctest-bgpaas_vn-45010532 2026-10-05 05:09:36,312 - DEBUG - VN 0b8124fb-2e48-4fb3-a821-c50511152321 still in use: Unable to complete operation on network 0b8124fb-2e48-4fb3-a821-c50511152321. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-b896d457-54f9-4143-a203-de0f0e5a1b07'] 2026-10-05 05:09:36,312 - WARNING - Deleting VN ctest-bgpaas_vn-45010532 failed..Will retry 2026-10-05 05:09:38,387 - DEBUG - VN 0b8124fb-2e48-4fb3-a821-c50511152321 still in use: Unable to complete operation on network 0b8124fb-2e48-4fb3-a821-c50511152321. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-67569f08-2920-49db-ab3f-eb56dcc22664'] 2026-10-05 05:09:38,387 - WARNING - Deleting VN ctest-bgpaas_vn-45010532 failed..Will retry 2026-10-05 05:09:40,683 - DEBUG - Response for deleting network () 2026-10-05 05:09:42,008 - ERROR - AssertionError Python 3.9.25: /usr/bin/python3 Mon Oct 5 05:09:35 2026 A problem occurred in a Python script. Here is the sequence of function calls leading up to the error, in the order they occurred. /contrail-test/tcutils/wrappers.py in wrapper(self=, *args=(), **kwargs={}) 78 log.info('Initial checks done. Running the testcase now') 79 log.info('') 80 result = function(self, *args, **kwargs) 81 if self.inputs.upgrade: 82 pid = os.getpid() result = None function = self = args = () kwargs = {} /contrail-test/serial_scripts/bgpaas/test_bgpaas_local_as.py in test_basic_bgpaas_local_as(self=) 31 2. Make sure BGP with vm comes up. 32 ''' 33 ret_dict = self.config_basic(image_name='ubuntu-bird') 34 vn_fixture = ret_dict['vn_fixture'] 35 test_vm = ret_dict['test_vm'] ret_dict undefined self = self.config_basic = > image_name undefined /contrail-test/serial_scripts/bgpaas/base.py in config_basic(self=, image_name='ubuntu-bird') 47 48 assert test_vm.wait_till_vm_is_up() 49 assert bgpaas_vm1.wait_till_vm_is_up() 50 ret_dict = { 51 'vn_fixture': vn_fixture, bgpaas_vm1 = bgpaas_vm1.wait_till_vm_is_up = > AssertionError: __cause__ = None __class__ = __context__ = None __delattr__ = __dict__ = {} __dir__ = __doc__ = 'Assertion failed.' __eq__ = __format__ = __ge__ = __getattribute__ = __gt__ = __hash__ = __init__ = __init_subclass__ = __le__ = __lt__ = __ne__ = __new__ = __reduce__ = __reduce_ex__ = __repr__ = __setattr__ = __setstate__ = __sizeof__ = __str__ = __subclasshook__ = __suppress_context__ = False __traceback__ = args = () with_traceback = The above is a description of an error in a Python program. Here is the original traceback: Traceback (most recent call last): File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper result = function(self, *args, **kwargs) File "/contrail-test/serial_scripts/bgpaas/test_bgpaas_local_as.py", line 33, in test_basic_bgpaas_local_as ret_dict = self.config_basic(image_name='ubuntu-bird') File "/contrail-test/serial_scripts/bgpaas/base.py", line 49, in config_basic assert bgpaas_vm1.wait_till_vm_is_up() AssertionError 2026-10-05 05:09:42,008 - DEBUG - Skipping xmpp flap check 2026-10-05 05:09:42,008 - INFO -
2026-10-05 05:09:42,008 - INFO - END TEST : test_basic_bgpaas_local_as : FAILED[0:03:31]
2026-10-05 05:09:42,008 - INFO - -------------------------------------------------------------------------------- 2026-10-05 05:09:42,883 - INFO - Deleted project: ctest-TestBGPaaSlocalAS-02283734, ID : 6a1d62ed-313c-4191-9e27-ad7d3ade9c91