Error executing service: ServiceCall lock.lock

011V32 commented 1 month ago

System Health details

System Information

version	core-2024.5.4
installation_type	Home Assistant OS
dev	false
hassio	true
docker	true
user	root
virtualenv	false
python_version	3.12.2
os_name	Linux
os_version	6.6.29-haos
arch	x86_64
timezone	Europe/Vienna
config_dir	/config

Home Assistant Community Store

Home Assistant Cloud

Home Assistant Supervisor

Dashboards

Recorder

Solcast PV Forecast

can_reach_server | ok -- | -- used_requests | 6 rooftop_site_count | 1

Spotify

api_endpoint_reachable | ok -- | --

Checklist

[X] I have enabled debug logging for my installation.
[X] I have filled out the issue template to the best of my ability.
[X] This issue only contains 1 issue (if you have multiple issues, open one issue for each issue).
[X] This issue is not a duplicate issue of currently previous issues..

Describe the issue

Error in the log. See Debug log.

Reproduction steps

I use HomeKit and have integrated this lock. At 7:30 it automatically locks the door if it is not locked.

Debug logs

Logger: homeassistant.core
Source: core.py:2758
First occurred: May 23, 2024 at 07:30:20 (1 occurrences)
Last logged: May 23, 2024 at 07:30:20

Error executing service: <ServiceCall lock.lock (c:01HYHYTYF7S23TG94TMVSPBE2M): entity_id=['lock.nuki_3575b2fc_lock']>
Traceback (most recent call last):
  File "/usr/local/lib/python3.12/site-packages/pyNukiBT/nuki.py", line 366, in _send_command
    msg = await self._notify_future
          ^^^^^^^^^^^^^^^^^^^^^^^^^
asyncio.exceptions.CancelledError

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/core.py", line 2758, in _run_service_call_catch_exceptions
    await coro_or_task
  File "/usr/src/homeassistant/homeassistant/core.py", line 2779, in _execute_service
    return await target(service_call)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 975, in entity_service_call
    single_response = await _handle_entity_call(
                      ^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 1047, in _handle_entity_call
    result = await task
             ^^^^^^^^^^
  File "/usr/src/homeassistant/homeassistant/components/lock/__init__.py", line 213, in async_handle_lock_service
    await self.async_lock(**self.add_default_code(kwargs))
  File "/config/custom_components/hass_nuki_bt/lock.py", line 49, in async_lock
    await self.async_lock_action(NukiLockConst.LockAction.LOCK)
  File "/config/custom_components/hass_nuki_bt/entity.py", line 76, in async_lock_action
    msg = await self.device.lock_action(action, name_suffix=user_name)
          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/pyNukiBT/nuki.py", line 464, in lock_action
    msg = await self._send_encrtypted_command(
          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/pyNukiBT/nuki.py", line 193, in _send_encrtypted_command
    return await self._send_command(
           ^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/pyNukiBT/nuki.py", line 365, in _send_command
    async with async_timeout.timeout(self.command_response_timeout):
  File "/usr/local/lib/python3.12/site-packages/async_timeout/__init__.py", line 141, in __aexit__
    self._do_exit(exc_type)
  File "/usr/local/lib/python3.12/site-packages/async_timeout/__init__.py", line 228, in _do_exit
    raise asyncio.TimeoutError
TimeoutError

Diagnostics dump

No response

ronengr commented 1 month ago

It seems there is an issue sending the command at the BT level. Is the Nuki device close enough to the BT dongle? are there any wireless interferences?

011V32 commented 1 month ago

I use an Olimex PoE ESP32 as Bluetooth Proxy which is approx. 1.5m away from the lock.

ronengr commented 1 month ago

how is the BT signal strength? maybe there are some wireless interferences?

You can try to increase the timeout in hass_nuki_bt (unfortunately not user configurable at the moment, so you will have to change the code) or you can try to change the energy-saving mode in the nuki lock, and increase the BT scan interval of the lock.

another option is to add some retry mechanism, but i'm not sure if it should be in the integration or in the automation. what do you think?

011V32 commented 1 month ago

The signal strength varies from -45 to -105:

I trie next week a longer cable for the BT Proxy so I get closer to the lock, but there are no real disturbanced in the near... I then report back. Thx so far.

ronengr commented 4 weeks ago

@011V32 I think that -105 is a bit low, and can cause sporadic issues. I've noticed that when i have -90 and below I get a lot more connection issues than when it is above -70

Anyway, I've just released a new version (0.0.14) that improves the retry mechanism, so even if you have sporadic connection issues with the new version things should work a lot better.

Please let me know if the new version fixed your issue.

011V32 commented 4 weeks ago

The issue is also with good condition. Atm i'm on holiday. I'll check it next week. Thx

011V32 commented 3 weeks ago

With the version 0.0.14 I have no issues so far. 👍 Thx.

ronengr / hass_nuki_bt