2026-10-20 19:02:16,762 - INFO - Domain Default found not creating 2026-10-20 19:02:16,898 - INFO - Project ctest-TestPorts-96252740 not found, creating it 2026-10-20 19:02:17,337 - INFO - Created Project:ctest-TestPorts-96252740, ID : 62b80fe2-b3a6-4797-a7e7-4791d75a9161 2026-10-20 19:02:18,997 - INFO - ================================================================================ 2026-10-20 19:02:18,997 - INFO - STARTING TEST : test_ports_bindings 2026-10-20 19:02:18,997 - INFO - TEST DESCRIPTION : Verify that we are able to create a port with custom port bindings Steps: 1) Create Port with Port bindings profile set to {'foo': 'bar'} 2) Retrieve and verify the same is set 2026-10-20 19:02:19,256 - DEBUG - Nothing to compare xmpp stats {'10.0.0.185': {'10.20.0.25': '0'}} with 2026-10-20 19:02:19,256 - INFO - Initial checks done. Running the testcase now 2026-10-20 19:02:19,256 - INFO - 2026-10-20 19:02:19,834 - DEBUG - Response for create_network : {'network': {'id': '1bffba85-e860-499c-a472-6caf6c8d4d75', 'name': 'ctest-vn-79170726', 'tenant_id': '62b80fe2b3a64797a7e74791d75a9161', 'project_id': '62b80fe2b3a64797a7e74791d75a9161', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPorts-96252740', 'ctest-vn-79170726'], 'port_security_enabled': True, 'description': ''}} 2026-10-20 19:02:20,023 - DEBUG - Response for create_subnet : {'subnet': {'id': '9d72e9a2-efe1-4349-b61d-028f8e62876b', 'name': '', 'tenant_id': '62b80fe2b3a64797a7e74791d75a9161', 'network_id': '1bffba85-e860-499c-a472-6caf6c8d4d75', 'ip_version': 4, 'cidr': '72.214.138.0/26', 'allocation_pools': [{'start': '72.214.138.2', 'end': '72.214.138.62'}], 'gateway_ip': '72.214.138.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '72.214.138.2', 'tags': [], 'project_id': '62b80fe2b3a64797a7e74791d75a9161'}} 2026-10-20 19:02:20,037 - INFO - Created VN ctest-vn-79170726 2026-10-20 19:02:20,088 - DEBUG - VN ctest-vn-79170726 UUID is 1bffba85-e860-499c-a472-6caf6c8d4d75 2026-10-20 19:02:20,183 - DEBUG - Read virtual machine interface {'fq_name': ['default-domain', 'ctest-TestPorts-96252740', 'ctest-vmi-04546905']} 2026-10-20 19:02:20,291 - ERROR - Neutron Exception while creating port in vn with id 1bffba85-e860-499c-a472-6caf6c8d4d75 Traceback (most recent call last): File "/contrail-test/fixtures/port_fixture.py", line 85, in setUp obj = self.vnc_h.read_virtual_machine_interface(fq_name=self.fq_name) File "/contrail-test/fixtures/contrailapi.py", line 3169, in read_virtual_machine_interface return self._vnc.virtual_machine_interface_read(**kwargs) File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 52, in wrapper return func(self, *args, **kwargs) File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 700, in _object_read (args_ok, result) = self._read_args_to_id( File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 1114, in _read_args_to_id return (True, self.fq_name_to_id(res_type, fq_name)) File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 52, in wrapper return func(self, *args, **kwargs) File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 1395, in fq_name_to_id content = self._request_server(OP_POST, uri, data=json_body) File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 1126, in _request_server return self._request( File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 1180, in _request raise NoIdError('Error: oper %s url %s body %s response %s' vnc_api.exceptions.NoIdError: Unknown id: Error: oper 1 url /fqname-to-id body {"type": "virtual-machine-interface", "fq_name": ["default-domain", "ctest-TestPorts-96252740", "ctest-vmi-04546905"]} response Name ['default-domain', 'ctest-TestPorts-96252740', 'ctest-vmi-04546905'] not found During handling of the above exception, another exception occurred: Traceback (most recent call last): File "/contrail-test/fixtures/quantum_test.py", line 167, in create_port port_rsp = self.obj.create_port({'port': port_req_dict}) File "/usr/local/lib/python3.9/site-packages/neutronclient/v2_0/client.py", line 803, in create_port return self.post(self.ports_path, body=body) File "/usr/local/lib/python3.9/site-packages/neutronclient/v2_0/client.py", line 358, in post return self.do_request("POST", action, body=body, File "/usr/local/lib/python3.9/site-packages/neutronclient/v2_0/client.py", line 294, in do_request self._handle_fault_response(status_code, replybody, resp) File "/usr/local/lib/python3.9/site-packages/neutronclient/v2_0/client.py", line 269, in _handle_fault_response exception_handler_v20(status_code, error_body) File "/usr/local/lib/python3.9/site-packages/neutronclient/v2_0/client.py", line 91, in exception_handler_v20 raise client_exc(message=error_message, neutronclient.common.exceptions.Forbidden: (rule:create_port and rule:create_port:binding:profile) is disallowed by policy Neutron server returns request_ids: ['req-ca0ef6ce-dd19-4569-8585-05f9a0312367'] 2026-10-20 19:02:20,295 - INFO - Deleting VN ctest-vn-79170726 2026-10-20 19:02:20,394 - DEBUG - Response for deleting network () 2026-10-20 19:02:20,644 - ERROR - TypeError Python 3.9.25: /usr/bin/python3 Tue Oct 20 19:02:20 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/scripts/neutron/test_ports.py in test_ports_bindings(self=) 1202 bind_dict = {'foo': 'bar'} 1203 vn = self.create_vn() 1204 port = self.useFixture(PortFixture(vn.uuid, connections=self.connections, 1205 binding_profile=bind_dict)) 1206 assert port.verify_on_setup(), 'VMI %s verification has failed'%port.uuid port undefined self = self.useFixture = > global PortFixture = vn = vn.uuid = '1bffba85-e860-499c-a472-6caf6c8d4d75' connections undefined self.connections = binding_profile undefined bind_dict = {'foo': 'bar'} /usr/local/lib/python3.9/site-packages/testtools/testcase.py in useFixture(self=, fixture=) 754 # Gather_details worked, so raise the exception setUp 755 # encountered. 756 reraise(*exc_info) 757 else: 758 self.addCleanup(fixture.cleanUp) global reraise = exc_info = (, TypeError("'NoneType' object is not subscriptable"), ) /usr/local/lib/python3.9/site-packages/testtools/_compat3x.py in reraise(exc_class=, exc_obj=TypeError("'NoneType' object is not subscriptable"), exc_tb=, _marker=) 13 14 def reraise(exc_class, exc_obj, exc_tb, _marker=object()): 15 """Re-raise an exception received from sys.exc_info() or similar.""" 16 raise exc_obj.with_traceback(exc_tb) 17 exc_obj = TypeError("'NoneType' object is not subscriptable") exc_obj.with_traceback = exc_tb = /usr/local/lib/python3.9/site-packages/testtools/testcase.py in useFixture(self=, fixture=) 729 """ 730 try: 731 fixture.setUp() 732 except MultipleExceptions as e: 733 if (fixtures is not None and fixture = fixture.setUp = > /contrail-test/fixtures/port_fixture.py in setUp(self=) 88 self.vn_obj = self.vnc_api_h.virtual_network_read(id=self.vn_id) 89 if self.api_type == 'neutron': 90 self._neutron_create_port() 91 else: 92 self._contrail_create_port() self = self._neutron_create_port = > /contrail-test/fixtures/port_fixture.py in _neutron_create_port(self=) 108 binding_profile=self.binding_profile) 109 self.neutron_obj = neutron_obj 110 self.uuid = neutron_obj['id'] 111 112 def _contrail_create_port(self): self = self.uuid = None neutron_obj = None TypeError: 'NoneType' object is not subscriptable __cause__ = None __class__ = __context__ = NoIdError('Error: oper 1 url /fqname-to-id body ...s-96252740\', \'ctest-vmi-04546905\'] not found') __delattr__ = __dict__ = {} __dir__ = __doc__ = 'Inappropriate argument type.' __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 = ("'NoneType' object is not subscriptable",) 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/fixtures/port_fixture.py", line 85, in setUp obj = self.vnc_h.read_virtual_machine_interface(fq_name=self.fq_name) File "/contrail-test/fixtures/contrailapi.py", line 3169, in read_virtual_machine_interface return self._vnc.virtual_machine_interface_read(**kwargs) File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 52, in wrapper return func(self, *args, **kwargs) File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 700, in _object_read (args_ok, result) = self._read_args_to_id( File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 1114, in _read_args_to_id return (True, self.fq_name_to_id(res_type, fq_name)) File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 52, in wrapper return func(self, *args, **kwargs) File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 1395, in fq_name_to_id content = self._request_server(OP_POST, uri, data=json_body) File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 1126, in _request_server return self._request( File "/usr/local/lib/python3.9/site-packages/vnc_api/vnc_api.py", line 1180, in _request raise NoIdError('Error: oper %s url %s body %s response %s' vnc_api.exceptions.NoIdError: Unknown id: Error: oper 1 url /fqname-to-id body {"type": "virtual-machine-interface", "fq_name": ["default-domain", "ctest-TestPorts-96252740", "ctest-vmi-04546905"]} response Name ['default-domain', 'ctest-TestPorts-96252740', 'ctest-vmi-04546905'] not found During handling of the above exception, another exception occurred: Traceback (most recent call last): File "/contrail-test/tcutils/wrappers.py", line 80, in wrapper result = function(self, *args, **kwargs) File "/contrail-test/scripts/neutron/test_ports.py", line 1204, in test_ports_bindings port = self.useFixture(PortFixture(vn.uuid, connections=self.connections, File "/usr/local/lib/python3.9/site-packages/testtools/testcase.py", line 756, in useFixture reraise(*exc_info) File "/usr/local/lib/python3.9/site-packages/testtools/_compat3x.py", line 16, in reraise raise exc_obj.with_traceback(exc_tb) File "/usr/local/lib/python3.9/site-packages/testtools/testcase.py", line 731, in useFixture fixture.setUp() File "/contrail-test/fixtures/port_fixture.py", line 90, in setUp self._neutron_create_port() File "/contrail-test/fixtures/port_fixture.py", line 110, in _neutron_create_port self.uuid = neutron_obj['id'] TypeError: 'NoneType' object is not subscriptable 2026-10-20 19:02:20,651 - DEBUG - No XMPP flaps were noticed during the test 2026-10-20 19:02:20,651 - INFO -
2026-10-20 19:02:20,651 - INFO - END TEST : test_ports_bindings : FAILED[0:00:02]
2026-10-20 19:02:20,651 - INFO - -------------------------------------------------------------------------------- 2026-10-20 19:02:20,654 - INFO - ================================================================================ 2026-10-20 19:02:20,654 - INFO - STARTING TEST : test_ports_specific_subnet_ip 2026-10-20 19:02:20,654 - INFO - TEST DESCRIPTION : Create ports with specific Subnet and IP Create two ports in a VN with 2 subnets and specific IPs Attach to two VMs Ping between them should pass 2026-10-20 19:02:20,938 - DEBUG - Nothing to compare xmpp stats {'10.0.0.185': {'10.20.0.25': '0'}} with 2026-10-20 19:02:20,938 - INFO - Initial checks done. Running the testcase now 2026-10-20 19:02:20,938 - INFO - 2026-10-20 19:02:21,099 - DEBUG - Response for create_network : {'network': {'id': '9e03b467-9732-4349-8ec1-df575a9e7f6a', 'name': 'ctest-vn1-87381298', 'tenant_id': '62b80fe2b3a64797a7e74791d75a9161', 'project_id': '62b80fe2b3a64797a7e74791d75a9161', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'tags': [], 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestPorts-96252740', 'ctest-vn1-87381298'], 'port_security_enabled': True, 'description': ''}} 2026-10-20 19:02:21,242 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b490c970-e536-4e81-a670-e8c0f01ac5fd', 'name': '', 'tenant_id': '62b80fe2b3a64797a7e74791d75a9161', 'network_id': '9e03b467-9732-4349-8ec1-df575a9e7f6a', 'ip_version': 4, 'cidr': '90.81.251.192/26', 'allocation_pools': [{'start': '90.81.251.194', 'end': '90.81.251.254'}], 'gateway_ip': '90.81.251.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '90.81.251.194', 'tags': [], 'project_id': '62b80fe2b3a64797a7e74791d75a9161'}} 2026-10-20 19:02:21,427 - DEBUG - Response for create_subnet : {'subnet': {'id': '57b91798-0873-4e26-ba25-e44132c145b7', 'name': '', 'tenant_id': '62b80fe2b3a64797a7e74791d75a9161', 'network_id': '9e03b467-9732-4349-8ec1-df575a9e7f6a', 'ip_version': 4, 'cidr': '43.14.110.192/26', 'allocation_pools': [{'start': '43.14.110.194', 'end': '43.14.110.254'}], 'gateway_ip': '43.14.110.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '43.14.110.194', 'tags': [], 'project_id': '62b80fe2b3a64797a7e74791d75a9161'}} 2026-10-20 19:02:21,442 - INFO - Created VN ctest-vn1-87381298 2026-10-20 19:02:21,492 - DEBUG - VN ctest-vn1-87381298 UUID is 9e03b467-9732-4349-8ec1-df575a9e7f6a 2026-10-20 19:02:21,764 - DEBUG - Response for create_port : {'port': {'name': '84316483-8460-492e-bdaf-8369260cba46', 'id': '84316483-8460-492e-bdaf-8369260cba46', 'tenant_id': '62b80fe2b3a64797a7e74791d75a9161', 'network_id': '9e03b467-9732-4349-8ec1-df575a9e7f6a', 'mac_address': '02:84:31:64:83:84', 'binding:vnic_type': 'normal', 'binding:vif_details': {'port_filter': True}, 'binding:vif_type': 'unbound', 'binding:host_id': None, 'allowed_address_pairs': [], 'fixed_ips': [{'ip_address': '90.81.251.197', 'subnet_id': 'b490c970-e536-4e81-a670-e8c0f01ac5fd'}], 'security_groups': ['9106a17d-fca6-4b3b-966c-d8a3b213a47d'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'tags': [], 'port_security_enabled': True, 'description': '', 'project_id': '62b80fe2b3a64797a7e74791d75a9161'}} 2026-10-20 19:02:21,941 - DEBUG - Response for create_port : {'port': {'name': '9813924f-119d-44c5-9b6b-28da244ac246', 'id': '9813924f-119d-44c5-9b6b-28da244ac246', 'tenant_id': '62b80fe2b3a64797a7e74791d75a9161', 'network_id': '9e03b467-9732-4349-8ec1-df575a9e7f6a', 'mac_address': '02:98:13:92:4f:11', 'binding:vnic_type': 'normal', 'binding:vif_details': {'port_filter': True}, 'binding:vif_type': 'unbound', 'binding:host_id': None, 'allowed_address_pairs': [], 'fixed_ips': [{'ip_address': '43.14.110.197', 'subnet_id': '57b91798-0873-4e26-ba25-e44132c145b7'}], 'security_groups': ['9106a17d-fca6-4b3b-966c-d8a3b213a47d'], 'admin_state_up': True, 'device_id': '', 'device_owner': '', 'status': 'DOWN', 'tags': [], 'port_security_enabled': True, 'description': '', 'project_id': '62b80fe2b3a64797a7e74791d75a9161'}} 2026-10-20 19:02:21,994 - DEBUG - Services list from nova: [, , ] 2026-10-20 19:02:22,320 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6376-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6376-1) 2026-10-20 19:02:22,650 - INFO - VM ([]) created on node: (cn-jenkins-deploy-platform-ansible-os-6376-1), Zone: (nova:cn-jenkins-deploy-platform-ansible-os-6376-1) 2026-10-20 19:02:22,650 - INFO - Waiting for VM ctest-vn1-vm1-29410778 to be up.. 2026-10-20 19:02:22,680 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-20 19:02:27,723 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-20 19:02:32,812 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-20 19:02:37,879 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-20 19:02:42,949 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-20 19:02:48,022 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-20 19:02:53,116 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-20 19:02:58,200 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-20 19:03:03,290 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2026-10-20 19:03:08,359 - DEBUG - VM is in ACTIVE state now 2026-10-20 19:03:08,359 - INFO - VM name : ctest-vn1-vm1-29410778 2026-10-20 19:03:08,421 - DEBUG - VM ctest-vn1-vm1-29410778 ID is 20475455-f735-4028-b73e-089c144ced29 2026-10-20 19:03:08,438 - DEBUG - VM ctest-vn1-vm1-29410778 launched on Node cn-jenkins-deploy-platform-ansible-os-6376-1 2026-10-20 19:03:08,499 - DEBUG - Requesting: http://10.0.0.185:8082/virtual-machine/20475455-f735-4028-b73e-089c144ced29 2026-10-20 19:03:08,857 - DEBUG - Requesting: http://10.0.0.185:8082/virtual-machine/20475455-f735-4028-b73e-089c144ced29 2026-10-20 19:03:08,883 - DEBUG - Requesting: http://10.0.0.185:8082/virtual-machine-interface/84316483-8460-492e-bdaf-8369260cba46 2026-10-20 19:03:10,114 - 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=3.53 ms\r\n64 bytes from 169.254.0.4: icmp_seq=2 ttl=63 time=0.432 ms\r\n\r\n--- 169.254.0.4 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.432/1.981/3.531/1.549 ms') 2026-10-20 19:03:10,114 - INFO - Ping to Metadata IP 169.254.0.4 of VM ctest-vn1-vm1-29410778 passed 2026-10-20 19:03:10,260 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-20 19:03:10,261 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-29410778, IP 90.81.251.197, Port 22 2026-10-20 19:03:10,326 - DEBUG - Error on ssh to cirros@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': ''} 2026-10-20 19:03:10,396 - DEBUG - VM ctest-vn1-vm1-29410778 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-20 19:03:15,396 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-20 19:03:15,397 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-29410778, IP 90.81.251.197, Port 22 2026-10-20 19:03:15,462 - DEBUG - Error on ssh to cirros@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': ''} 2026-10-20 19:03:15,535 - DEBUG - VM ctest-vn1-vm1-29410778 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-20 19:03:20,536 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-20 19:03:20,536 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-29410778, IP 90.81.251.197, Port 22 2026-10-20 19:03:20,592 - DEBUG - Error on ssh to cirros@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': ''} 2026-10-20 19:03:20,656 - DEBUG - VM ctest-vn1-vm1-29410778 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-20 19:03:25,657 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-20 19:03:25,657 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-29410778, IP 90.81.251.197, Port 22 2026-10-20 19:03:25,712 - DEBUG - Error on ssh to cirros@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': ''} 2026-10-20 19:03:25,784 - DEBUG - VM ctest-vn1-vm1-29410778 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-20 19:03:30,784 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-20 19:03:30,785 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-29410778, IP 90.81.251.197, Port 22 2026-10-20 19:03:30,840 - DEBUG - Error on ssh to cirros@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': ''} 2026-10-20 19:03:30,908 - DEBUG - VM ctest-vn1-vm1-29410778 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-20 19:03:35,910 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-20 19:03:35,910 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-29410778, IP 90.81.251.197, Port 22 2026-10-20 19:03:35,963 - DEBUG - Error on ssh to cirros@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': ''} 2026-10-20 19:03:36,031 - DEBUG - VM ctest-vn1-vm1-29410778 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-20 19:03:41,032 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-20 19:03:41,032 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-29410778, IP 90.81.251.197, Port 22 2026-10-20 19:03:41,088 - DEBUG - Error on ssh to cirros@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': ''} 2026-10-20 19:03:41,225 - DEBUG - VM ctest-vn1-vm1-29410778 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-20 19:03:46,226 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-20 19:03:46,227 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-29410778, IP 90.81.251.197, Port 22 2026-10-20 19:03:46,288 - DEBUG - Error on ssh to cirros@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': ''} 2026-10-20 19:03:46,362 - DEBUG - VM ctest-vn1-vm1-29410778 is NOT ready for SSH connections, VM status: ACTIVE 2026-10-20 19:03:51,363 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-20 19:03:51,363 - DEBUG - Waiting to SSH to VM ctest-vn1-vm1-29410778, IP 90.81.251.197, Port 22 2026-10-20 19:03:51,524 - DEBUG - VM ctest-vn1-vm1-29410778 is ready for SSH connections 2026-10-20 19:03:51,524 - INFO - Waiting for VM ctest-vn1-vm2-50720285 to be up.. 2026-10-20 19:03:51,593 - DEBUG - VM is in ACTIVE state now 2026-10-20 19:03:51,593 - INFO - VM name : ctest-vn1-vm2-50720285 2026-10-20 19:03:51,657 - DEBUG - VM ctest-vn1-vm2-50720285 ID is ff58f489-5402-4694-b428-96a7163cdc54 2026-10-20 19:03:51,658 - DEBUG - VM ctest-vn1-vm2-50720285 launched on Node cn-jenkins-deploy-platform-ansible-os-6376-1 2026-10-20 19:03:51,724 - DEBUG - Requesting: http://10.0.0.185:8082/virtual-machine/ff58f489-5402-4694-b428-96a7163cdc54 2026-10-20 19:03:51,730 - DEBUG - Requesting: http://10.0.0.185:8082/virtual-machine-interface/9813924f-119d-44c5-9b6b-28da244ac246 2026-10-20 19:03:52,962 - 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=2.21 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.387 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 = 0.387/1.299/2.211/0.912 ms') 2026-10-20 19:03:52,962 - INFO - Ping to Metadata IP 169.254.0.3 of VM ctest-vn1-vm2-50720285 passed 2026-10-20 19:03:53,108 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-20 19:03:53,108 - DEBUG - Waiting to SSH to VM ctest-vn1-vm2-50720285, IP 43.14.110.197, Port 22 2026-10-20 19:03:53,263 - DEBUG - VM ctest-vn1-vm2-50720285 is ready for SSH connections 2026-10-20 19:03:53,263 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-20 19:03:53,263 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-20 19:03:53,263 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2026-10-20 19:03:53,263 - DEBUG - Running remote_cmd, Cmd : ping -s 56 -c 3 -W 1 90.81.251.197, host_string: cirros@169.254.0.3, password: cubswin:)gateway: ubuntu@10.0.0.185, gateway password: c0ntrail123 2026-10-20 19:03:53,264 - DEBUG - ping -s 56 -c 3 -W 1 90.81.251.197 2026-10-20 19:03:57,298 - DEBUG - PING 90.81.251.197 (90.81.251.197): 56 data bytes 64 bytes from 90.81.251.197: seq=0 ttl=63 time=1.732 ms 64 bytes from 90.81.251.197: seq=1 ttl=63 time=1.296 ms 64 bytes from 90.81.251.197: seq=2 ttl=63 time=1.009 ms --- 90.81.251.197 ping statistics --- 3 packets transmitted, 3 packets received, 0% packet loss round-trip min/avg/max = 1.009/1.345/1.732 ms 2026-10-20 19:03:57,298 - INFO - Ping to IP 90.81.251.197 from VM ctest-vn1-vm2-50720285 passed 2026-10-20 19:03:57,298 - INFO - Detaching port 9813924f-119d-44c5-9b6b-28da244ac246 from VM ctest-vn1-vm2-50720285 2026-10-20 19:03:57,353 - INFO - Deleting VM ctest-vn1-vm2-50720285 2026-10-20 19:03:57,414 - INFO - Detaching port 84316483-8460-492e-bdaf-8369260cba46 from VM ctest-vn1-vm1-29410778 2026-10-20 19:03:57,452 - INFO - Deleting VM ctest-vn1-vm1-29410778 2026-10-20 19:03:57,712 - DEBUG - Response for delete_port : () 2026-10-20 19:03:57,873 - DEBUG - Response for delete_port : () 2026-10-20 19:03:57,873 - INFO - Deleting VN ctest-vn1-87381298 2026-10-20 19:03:57,980 - DEBUG - Response for deleting network () 2026-10-20 19:03:58,239 - DEBUG - No XMPP flaps were noticed during the test
2026-10-20 19:03:58,239 - INFO - END TEST : test_ports_specific_subnet_ip : PASSED[0:01:38]
2026-10-20 19:03:58,239 - INFO - -------------------------------------------------------------------------------- 2026-10-20 19:03:59,532 - INFO - Deleted project: ctest-TestPorts-96252740, ID : 62b80fe2-b3a6-4797-a7e7-4791d75a9161