2025-08-02 23:10:43,730 - INFO - Domain Default found not creating 2025-08-02 23:10:43,891 - INFO - Project ctest-TestBGPaaS-36213537 not found, creating it 2025-08-02 23:10:44,600 - INFO - Created Project:ctest-TestBGPaaS-36213537, ID : 81ebd189-7b48-45fb-9278-1c1afb3a097f 2025-08-02 23:10:46,347 - INFO - Using existing project ['default-domain', 'admin'](479fef05-dd36-4231-8389-de2ae3fa83e8) 2025-08-02 23:10:47,071 - INFO - Adding rules to the default security group in Project admin 2025-08-02 23:10:47,490 - DEBUG - Services list from nova: [, , , ] 2025-08-02 23:10:47,490 - DEBUG - Hosts: {'nova': ['an-jenkins-deploy-platform-ansible-os-3743-2', 'an-jenkins-deploy-platform-ansible-os-3743-1']} 2025-08-02 23:10:47,491 - INFO - ================================================================================ 2025-08-02 23:10:47,492 - INFO - STARTING TEST : test_bgpaas_basic 2025-08-02 23:10:47,492 - INFO - TEST DESCRIPTION : 1. Create a BGPaaS object with shared attribute, IP address and ASN. 2. Launch a VM which will act as the BGPaaS client. 3. Configure BFDoBGPaaS on it. 4. Verify BGP and BFD sessions over it come up fine. Maintainer: ganeshahv@juniper.net 2025-08-02 23:10:48,149 - DEBUG - Nothing to compare xmpp stats {'10.0.0.48': {'10.20.0.17': '0', '10.20.0.14': '0'}, '10.0.0.49': {'10.20.0.17': '0', '10.20.0.14': '0'}} with 2025-08-02 23:10:48,149 - INFO - Initial checks done. Running the testcase now 2025-08-02 23:10:48,149 - INFO - 2025-08-02 23:10:48,996 - DEBUG - Response for create_network : {'network': {'id': '85e0f0d9-0aeb-4ca1-ae86-509e37d9c81a', 'name': 'ctest-bgpaas_vn-13656901', 'tenant_id': '81ebd1897b4845fb92781c1afb3a097f', 'project_id': '81ebd1897b4845fb92781c1afb3a097f', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestBGPaaS-36213537', 'ctest-bgpaas_vn-13656901'], 'port_security_enabled': True, 'description': ''}} 2025-08-02 23:10:49,330 - DEBUG - Response for create_subnet : {'subnet': {'id': '1f1754be-bbae-4b5e-8298-b641598bc622', 'name': '', 'tenant_id': '81ebd1897b4845fb92781c1afb3a097f', 'network_id': '85e0f0d9-0aeb-4ca1-ae86-509e37d9c81a', 'ip_version': 4, 'cidr': '135.92.242.128/26', 'allocation_pools': [{'start': '135.92.242.130', 'end': '135.92.242.190'}], 'gateway_ip': '135.92.242.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '135.92.242.130', 'tags': [], 'project_id': '81ebd1897b4845fb92781c1afb3a097f'}} 2025-08-02 23:10:49,366 - INFO - Created VN ctest-bgpaas_vn-13656901 2025-08-02 23:10:49,378 - DEBUG - VN ctest-bgpaas_vn-13656901 UUID is 85e0f0d9-0aeb-4ca1-ae86-509e37d9c81a 2025-08-02 23:10:49,523 - DEBUG - Image by name ubuntu-bird not found 2025-08-02 23:10:49,523 - DEBUG - Installing image ubuntu-bird 2025-08-02 23:10:49,523 - 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'} 2025-08-02 23:10:49,523 - DEBUG - Image build path http://nexus.opensdn.io/repository///images/converts//ubuntu-bird.vmdk.gz 2025-08-02 23:10:49,524 - DEBUG - Download image from web http://nexus.opensdn.io/repository///images/converts//ubuntu-bird.vmdk.gz 2025-08-02 23:10:49,533 - 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 2025-08-02 23:10:52,153 - DEBUG - Image has been downloaded to /contrail-test/images/ubuntu-bird.vmdk.gz 2025-08-02 23:10:52,155 - DEBUG - Unzip image 2025-08-02 23:10:52,155 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: gunzip -f /contrail-test/images/ubuntu-bird.vmdk.gz 2025-08-02 23:11:02,032 - DEBUG - Try to create image in user context 2025-08-02 23:11:09,032 - DEBUG - Image has been created in user context 2025-08-02 23:11:09,032 - DEBUG - Image download (local=True) (proxy=None): Execute cmd: rm -f /contrail-test/images/ubuntu-bird.vmdk 2025-08-02 23:11:09,199 - DEBUG - Downloaded image has been removed 2025-08-02 23:11:09,215 - DEBUG - Uploaded image status = active 2025-08-02 23:11:10,504 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3743-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3743-2) 2025-08-02 23:11:10,504 - INFO - Waiting for VM bgpaas_vm1 to be up.. 2025-08-02 23:11:10,563 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-02 23:11:15,724 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-02 23:11:20,880 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-02 23:11:26,021 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-02 23:11:31,137 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-02 23:11:36,266 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-02 23:11:41,396 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-02 23:11:46,500 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-02 23:11:51,801 - DEBUG - VM is in ACTIVE state now 2025-08-02 23:11:51,802 - INFO - VM name : bgpaas_vm1 2025-08-02 23:11:51,924 - DEBUG - VM bgpaas_vm1 ID is a6ca2cd4-317c-4c77-a50d-716a1c0b5b3e 2025-08-02 23:11:51,948 - DEBUG - VM bgpaas_vm1 launched on Node an-jenkins-deploy-platform-ansible-os-3743-2 2025-08-02 23:11:52,045 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/a6ca2cd4-317c-4c77-a50d-716a1c0b5b3e 2025-08-02 23:11:52,347 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine/a6ca2cd4-317c-4c77-a50d-716a1c0b5b3e 2025-08-02 23:11:52,400 - DEBUG - Requesting: http://10.0.0.50:8082/virtual-machine-interface/81e9b3e1-8c6d-41d9-8534-a8e16fd41d20 2025-08-02 23:11:55,623 - 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 1023ms') 2025-08-02 23:11:55,623 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2025-08-02 23:11:55,657 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaS-36213537:ctest-bgpaas_vn-13656901 is 135.92.242.129 and allocation pool is NOT set 2025-08-02 23:11:59,683 - 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') 2025-08-02 23:11:59,683 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2025-08-02 23:11:59,725 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaS-36213537:ctest-bgpaas_vn-13656901 is 135.92.242.129 and allocation pool is NOT set 2025-08-02 23:12:03,750 - 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') 2025-08-02 23:12:03,750 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2025-08-02 23:12:03,769 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaS-36213537:ctest-bgpaas_vn-13656901 is 135.92.242.129 and allocation pool is NOT set 2025-08-02 23:12:07,820 - 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 1027ms') 2025-08-02 23:12:07,820 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2025-08-02 23:12:07,840 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaS-36213537:ctest-bgpaas_vn-13656901 is 135.92.242.129 and allocation pool is NOT set 2025-08-02 23:12:11,888 - 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') 2025-08-02 23:12:11,888 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2025-08-02 23:12:11,917 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaS-36213537:ctest-bgpaas_vn-13656901 is 135.92.242.129 and allocation pool is NOT set 2025-08-02 23:12:15,939 - 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 1007ms') 2025-08-02 23:12:15,939 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2025-08-02 23:12:15,961 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaS-36213537:ctest-bgpaas_vn-13656901 is 135.92.242.129 and allocation pool is NOT set 2025-08-02 23:12:20,008 - 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 1029ms') 2025-08-02 23:12:20,008 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2025-08-02 23:12:20,040 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaS-36213537:ctest-bgpaas_vn-13656901 is 135.92.242.129 and allocation pool is NOT set 2025-08-02 23:12:24,067 - 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') 2025-08-02 23:12:24,067 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2025-08-02 23:12:24,101 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaS-36213537:ctest-bgpaas_vn-13656901 is 135.92.242.129 and allocation pool is NOT set 2025-08-02 23:12:28,137 - 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') 2025-08-02 23:12:28,137 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2025-08-02 23:12:28,168 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaS-36213537:ctest-bgpaas_vn-13656901 is 135.92.242.129 and allocation pool is NOT set 2025-08-02 23:12:32,197 - 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') 2025-08-02 23:12:32,197 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2025-08-02 23:12:32,232 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaS-36213537:ctest-bgpaas_vn-13656901 is 135.92.242.129 and allocation pool is NOT set 2025-08-02 23:12:36,268 - 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') 2025-08-02 23:12:36,268 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2025-08-02 23:12:36,303 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaS-36213537:ctest-bgpaas_vn-13656901 is 135.92.242.129 and allocation pool is NOT set 2025-08-02 23:12:40,323 - 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') 2025-08-02 23:12:40,323 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2025-08-02 23:12:40,356 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaS-36213537:ctest-bgpaas_vn-13656901 is 135.92.242.129 and allocation pool is NOT set 2025-08-02 23:12:44,389 - 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 1017ms') 2025-08-02 23:12:44,389 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2025-08-02 23:12:44,413 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaS-36213537:ctest-bgpaas_vn-13656901 is 135.92.242.129 and allocation pool is NOT set 2025-08-02 23:12:48,460 - 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 1021ms') 2025-08-02 23:12:48,460 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2025-08-02 23:12:48,491 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaS-36213537:ctest-bgpaas_vn-13656901 is 135.92.242.129 and allocation pool is NOT set 2025-08-02 23:12:52,519 - 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 1010ms') 2025-08-02 23:12:52,519 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 failed! 2025-08-02 23:12:52,555 - DEBUG - Gateway for vn default-domain:ctest-TestBGPaaS-36213537:ctest-bgpaas_vn-13656901 is 135.92.242.129 and allocation pool is NOT set 2025-08-02 23:12:56,583 - 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=1.95 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 = 1.954/1.954/1.954/0.000 ms') 2025-08-02 23:12:56,583 - INFO - Ping to Metadata IP 169.254.0.3 of VM bgpaas_vm1 passed 2025-08-02 23:12:56,755 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-02 23:12:56,755 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 135.92.242.131, Port 22 2025-08-02 23:12:56,779 - 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': ''} 2025-08-02 23:12:56,886 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2025-08-02 23:13:01,887 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-02 23:13:01,888 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 135.92.242.131, Port 22 2025-08-02 23:13:01,912 - 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': ''} 2025-08-02 23:13:02,026 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2025-08-02 23:13:07,027 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-02 23:13:07,028 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 135.92.242.131, Port 22 2025-08-02 23:13:07,052 - 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': ''} 2025-08-02 23:13:07,155 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2025-08-02 23:13:12,155 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-02 23:13:12,156 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 135.92.242.131, Port 22 2025-08-02 23:13:12,170 - 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': ''} 2025-08-02 23:13:12,281 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2025-08-02 23:13:17,281 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-02 23:13:17,282 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 135.92.242.131, Port 22 2025-08-02 23:13:17,307 - 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': ''} 2025-08-02 23:13:17,451 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2025-08-02 23:13:22,453 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-02 23:13:22,453 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 135.92.242.131, Port 22 2025-08-02 23:13:22,468 - 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': ''} 2025-08-02 23:13:22,602 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2025-08-02 23:13:27,603 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-02 23:13:27,603 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 135.92.242.131, Port 22 2025-08-02 23:13:27,618 - 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': ''} 2025-08-02 23:13:27,726 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2025-08-02 23:13:32,726 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-02 23:13:32,727 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 135.92.242.131, Port 22 2025-08-02 23:13:32,751 - 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': ''} 2025-08-02 23:13:32,857 - DEBUG - VM bgpaas_vm1 is NOT ready for SSH connections, VM status: ACTIVE 2025-08-02 23:13:37,858 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-02 23:13:37,858 - DEBUG - Waiting to SSH to VM bgpaas_vm1, IP 135.92.242.131, Port 22 2025-08-02 23:13:37,945 - DEBUG - VM bgpaas_vm1 is ready for SSH connections 2025-08-02 23:13:37,945 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-02 23:13:38,153 - INFO - Creating BGPaaS ctest-TestBGPaaS-36213537-81054382 2025-08-02 23:13:38,265 - INFO - BGPaaS: ctest-TestBGPaaS-36213537-81054382(7365e055-177e-4537-825a-64bd8f35b5f8) 2025-08-02 23:13:38,265 - DEBUG - Requesting: http://10.0.0.38:8082/bgp-as-a-service/7365e055-177e-4537-825a-64bd8f35b5f8 2025-08-02 23:13:38,579 - DEBUG - Requesting: http://10.0.0.38:8082/bgp-as-a-service/7365e055-177e-4537-825a-64bd8f35b5f8 2025-08-02 23:13:38,627 - INFO - verify_in_api_server passed for BGPaaS obj 7365e055-177e-4537-825a-64bd8f35b5f8 2025-08-02 23:13:38,627 - INFO - BGPaaS(7365e055-177e-4537-825a-64bd8f35b5f8): verify_on_setup passed 2025-08-02 23:13:38,627 - INFO - We will configure BGP on the VM 2025-08-02 23:13:38,627 - INFO - Configuring BGP on bgpaas_vm1 2025-08-02 23:13:38,628 - 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 135.92.242.131; protocol bgp bgp_1{ local as 64500; neighbor 135.92.242.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 135.92.242.131; } protocol bfd { neighbor 135.92.242.129 local 135.92.242.131 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.49, gateway password: c0ntrail123 2025-08-02 23:13:38,628 - 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 135.92.242.131; protocol bgp bgp_1{ local as 64500; neighbor 135.92.242.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 135.92.242.131; } protocol bfd { neighbor 135.92.242.129 local 135.92.242.131 multihop on; } EOS 2025-08-02 23:13:56,259 - DEBUG - None 2025-08-02 23:13:56,259 - 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 135.92.242.131; protocol bgp bgp_1{ local as 64500; neighbor 135.92.242.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 135.92.242.131; } protocol bfd { neighbor 135.92.242.129 local 135.92.242.131 multihop on; } EOS , host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.49, gateway password: c0ntrail123 2025-08-02 23:13:56,259 - 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 135.92.242.131; protocol bgp bgp_1{ local as 64500; neighbor 135.92.242.129 as 64512; export where source = RTS_STATIC; export all; multihop; #export all; hold time 90; bfd on; source address 135.92.242.131; } protocol bfd { neighbor 135.92.242.129 local 135.92.242.131 multihop on; } EOS 2025-08-02 23:13:57,349 - DEBUG - None 2025-08-02 23:13:57,349 - 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-02 23:13:57,349 - DEBUG - service bird restart 2025-08-02 23:14:00,501 - DEBUG - bird stop/waiting bird start/running, process 1421 2025-08-02 23:14:00,501 - INFO - Attaching the VMI to the BGPaaS object 2025-08-02 23:14:00,501 - INFO - Attaching VMI 81e9b3e1-8c6d-41d9-8534-a8e16fd41d20 to BGPaaS 7365e055-177e-4537-825a-64bd8f35b5f8 2025-08-02 23:14:00,677 - INFO - Creating Health Check ctest-TestBGPaaS-36213537-88240223 2025-08-02 23:14:00,809 - INFO - Health Check: ctest-TestBGPaaS-36213537-88240223(5840dd6e-a9ec-422c-a097-bf9e9cf813e5), type BFD 2025-08-02 23:14:00,809 - DEBUG - Requesting: http://10.0.0.38:8082/service-health-check/5840dd6e-a9ec-422c-a097-bf9e9cf813e5 2025-08-02 23:14:00,823 - INFO - verify_in_api_server passed for HC obj 5840dd6e-a9ec-422c-a097-bf9e9cf813e5 2025-08-02 23:14:00,823 - INFO - Health Check(5840dd6e-a9ec-422c-a097-bf9e9cf813e5): verify_on_setup passed 2025-08-02 23:14:00,823 - INFO - Attaching HC 5840dd6e-a9ec-422c-a097-bf9e9cf813e5 to BGPaaS 7365e055-177e-4537-825a-64bd8f35b5f8 2025-08-02 23:14:00,993 - INFO - Check HC obj 5840dd6e-a9ec-422c-a097-bf9e9cf813e5 on agent 10.0.0.49 2025-08-02 23:14:01,004 - INFO - verify_in_agent passed for HC obj 5840dd6e-a9ec-422c-a097-bf9e9cf813e5 2025-08-02 23:14:01,023 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_BgpNeighborReq?domain=&ip_address= 2025-08-02 23:14:01,049 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address= 2025-08-02 23:14:07,105 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_BgpNeighborReq?domain=&ip_address= 2025-08-02 23:14:07,124 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address= 2025-08-02 23:14:13,176 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_BgpNeighborReq?domain=&ip_address= 2025-08-02 23:14:13,193 - INFO - BGPaaS session seen in control-node 10.20.0.17 2025-08-02 23:14:13,193 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_BgpNeighborReq?domain=&ip_address= 2025-08-02 23:14:20,420 - DEBUG - Running remote_cmd, Cmd : birdc show protocols bfd1, host_string: ubuntu@169.254.0.3, password: ubuntugateway: ubuntu@10.0.0.49, gateway password: c0ntrail123 2025-08-02 23:14:20,421 - DEBUG - birdc show protocols bfd1 2025-08-02 23:14:20,895 - DEBUG - BIRD 1.4.0 ready. name proto table state since info bfd1 BFD master up 23:13:59 2025-08-02 23:14:20,895 - INFO - Detaching HC 5840dd6e-a9ec-422c-a097-bf9e9cf813e5 from BGPaaS 7365e055-177e-4537-825a-64bd8f35b5f8 2025-08-02 23:14:21,057 - INFO - Deleting Health Check ctest-TestBGPaaS-36213537-88240223(5840dd6e-a9ec-422c-a097-bf9e9cf813e5) 2025-08-02 23:14:21,117 - DEBUG - Requesting: http://10.0.0.38:8082/service-health-check/5840dd6e-a9ec-422c-a097-bf9e9cf813e5 2025-08-02 23:14:21,125 - DEBUG - Response Code: 404 2025-08-02 23:14:21,125 - DEBUG - HC: 5840dd6e-a9ec-422c-a097-bf9e9cf813e5 deleted from api server 2025-08-02 23:14:21,125 - INFO - Health Check(5840dd6e-a9ec-422c-a097-bf9e9cf813e5): verify_on_cleanup passed 2025-08-02 23:14:21,125 - INFO - Detaching VMI 81e9b3e1-8c6d-41d9-8534-a8e16fd41d20 from BGPaaS 7365e055-177e-4537-825a-64bd8f35b5f8 2025-08-02 23:14:21,221 - INFO - Deleting BGPaaS ctest-TestBGPaaS-36213537-81054382(7365e055-177e-4537-825a-64bd8f35b5f8) 2025-08-02 23:14:21,320 - DEBUG - Requesting: http://10.0.0.38:8082/bgp-as-a-service/7365e055-177e-4537-825a-64bd8f35b5f8 2025-08-02 23:14:21,338 - DEBUG - Response Code: 404 2025-08-02 23:14:21,339 - DEBUG - BGPaaS: 7365e055-177e-4537-825a-64bd8f35b5f8 deleted from api server 2025-08-02 23:14:21,339 - INFO - BGPaaS(7365e055-177e-4537-825a-64bd8f35b5f8): verify_on_cleanup passed 2025-08-02 23:14:21,339 - INFO - Deleting VM bgpaas_vm1 2025-08-02 23:14:21,473 - INFO - Deleting VN ctest-bgpaas_vn-13656901 2025-08-02 23:14:21,539 - DEBUG - VN 85e0f0d9-0aeb-4ca1-ae86-509e37d9c81a still in use: Unable to complete operation on network 85e0f0d9-0aeb-4ca1-ae86-509e37d9c81a. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-e2bc7482-340c-4f95-943f-12c17b094210'] 2025-08-02 23:14:21,539 - WARNING - Deleting VN ctest-bgpaas_vn-13656901 failed..Will retry 2025-08-02 23:14:23,837 - DEBUG - Response for deleting network () 2025-08-02 23:14:25,216 - DEBUG - No XMPP flaps were noticed during the test
2025-08-02 23:14:25,216 - INFO - END TEST : test_bgpaas_basic : PASSED[0:03:38]
2025-08-02 23:14:25,216 - INFO - -------------------------------------------------------------------------------- 2025-08-02 23:14:26,146 - INFO - Deleted project: ctest-TestBGPaaS-36213537, ID : 81ebd189-7b48-45fb-9278-1c1afb3a097f 2025-08-02 23:43:37,884 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-83141489:ctest-bgpaas_vn-01018953:ctest-bgpaas_vn-01018953.inet.0 2025-08-02 23:43:37,897 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-83141489:ctest-bgpaas_vn-01018953:ctest-bgpaas_vn-01018953.inet.0 2025-08-02 23:44:05,532 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-83141489:ctest-bgpaas_vn-26213412:ctest-bgpaas_vn-26213412.inet.0 2025-08-02 23:44:05,543 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-83141489:ctest-bgpaas_vn-26213412:ctest-bgpaas_vn-26213412.inet.0 2025-08-02 23:44:09,522 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-83141489:ctest-bgpaas_vn-26213412:ctest-bgpaas_vn-26213412.inet.0 2025-08-02 23:44:09,538 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-83141489:ctest-bgpaas_vn-26213412:ctest-bgpaas_vn-26213412.inet.0 2025-08-02 23:44:33,479 - DEBUG - Requesting: http://10.20.0.17:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-83141489:ctest-bgpaas_vn-21916692:ctest-bgpaas_vn-21916692.inet.0 2025-08-02 23:44:33,493 - DEBUG - Requesting: http://10.20.0.14:8083/Snh_ShowRouteReq?x=default-domain:ctest-TestRP-83141489:ctest-bgpaas_vn-21916692:ctest-bgpaas_vn-21916692.inet.0