Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

ISSUE: Stuck on Deleting or Adding #270

Open
daelsc opened this issue Mar 19, 2022 · 148 comments
Open

ISSUE: Stuck on Deleting or Adding #270

daelsc opened this issue Mar 19, 2022 · 148 comments
Labels

Comments

@daelsc
Copy link

daelsc commented Mar 19, 2022

Describe the bug
When adding / removing Code3 from the Home Assistant dashboard the PIN Status is stuck on Deleting or Adding. All of the other code #'s work fine, can add and delete without issue.

Environment (please complete the following information):

HassOS
Unraid VM
Home Assistant 2022.3.5
zwave_js
Yale YRD-256

Logs
Paste your error logs here.

Screenshots

keymaster

Additional context

Otherwise works great!

@dcf1999
Copy link

dcf1999 commented Mar 25, 2022

I'm having the same issue. Only happens on one of my child locks and only 3 code slots. I deleted the lock and re-added it and the same thing happens on the same slots for the same lock. I'm trying to look at the logs, but I don't know where the logs are located.

@dcf1999
Copy link

dcf1999 commented Mar 27, 2022

OK I figured out how to "fix" it, or at least manually push the codes to the door. Once done, the slot shows it as "connected" or "deleted". Once I did this fix, I could change the code, disable or enable the code and it works as it should. It's almost like there was a some sort of communication problem with just those code slots and for some reason Keymaster couldn't get the lock to change the code. and when I manually pushed the code to the lock, it fixed the problem.

Here is what I did:

  1. Create a new script with the following code:
alias: Manual Set Lock Codes
sequence:
  - service: zwave_js.set_lock_usercode
    data:
      code_slot: 15 (what ever your slot is)
      usercode: 1234 (or whatever your code is)
    entity_id: lock.xxxxxx (entity ID of your lock)
mode: single
  1. Save the script
  2. Click "run script"

Sometimes I had to play around with it or maybe it just took a few minutes to work. The first slot worked right away and keymaster changed it to "connected"

The second slot I had to un-enable it, then run the script. At that point, keymaster showed it as "deleted" and when I enabled it, it showed "Adding" then shortly after "Connected"

The third slot took a few times of running the script and enabling / disabling the slot in keymaster.

Maybe it just takes time to cycle through the system when you run the script? I don't know, but as of now, it works as it should.

Oh and finally, it seems obviously, but if you have multiple slots that need to be done, make sure you change them in the script after the previous slot is fixed and save the script before running it.

Hope that all makes sense. Good luck. Any questions, please ask.

@daelsc
Copy link
Author

daelsc commented Mar 27, 2022

@dcf1999 That WORKED! I had a similar experience as you, had to do it a few times but eventually, it got back to a working state. Thank you so much.

@chelming
Copy link

chelming commented Apr 8, 2022

I ended up forcing the automations "synchronize _______" and that fixed it for me.

@tykeal
Copy link
Contributor

tykeal commented Apr 19, 2022

I just acquired a lock from someone that had been doing manual code management on the lock itself. I ran into this same problem. My solution was to disable the offending slot and then use the developer tools / services to do the following:

call keymaster.clear_code with the lock entity_id and the code_slot defined. Once this was done, I could enter my target code and set to enable and it picked up properly from then on.

Keymaster definitely has a bit of an issue when taking over a lock that has been in use in some way outside of Keymaster management.

@firstof9
Copy link
Collaborator

Keymaster definitely has a bit of an issue when taking over a lock that has been in use in some way outside of Keymaster management.

Which is funny because it's just the zwave commands wrapped up.

I just acquired a lock from someone that had been doing manual code management on the lock itself.

I've noticed this mostly happening on specific brands of lock, Yale and Schlage. Kwikset seems to work flawlessly out of the box. 🤷‍♂️

@tykeal
Copy link
Contributor

tykeal commented Apr 19, 2022

I just acquired a lock from someone that had been doing manual code management on the lock itself.

I've noticed this mostly happening on specific brands of lock, Yale and Schlage. Kwikset seems to work flawlessly out of the box. man_shrugging

The one I acquired was a Kwikset :-/ It's happened to me with two different previously used Kwiksets.

@firstof9
Copy link
Collaborator

Strange, I've never had any issues with my Kwikset.

@tykeal
Copy link
Contributor

tykeal commented Apr 21, 2022

@firstof9 I only seem to have the issue with Kwikset locks if they've been used outside of Keymaster. Or in the case of one lock, a different installation of HA & Keymaster that I moved locations of! It's all around codes already being in the lock and Keymaster for some reason doesn't pick them up properly though I've seen it actually get the codes but it seems to get stuck in a weird state of deleting/adding them.

@dewayneshorts
Copy link

I am having this issue but with Every user code.

@alsoszaa
Copy link

If you are still stuck...
I have the Schlage BE469ZP (Allegion) and have had this problem and have been trying to fix it using all the tips found with no progress. Finally here is what I did to fix my issue. I had been using the red enroll/un-enroll button in the back with the battery. I changed that method to:

Remove Z-Wave Device:

  1. In Home Assistant, got to Configuration -> Devices & Services and find your Z-Wave JS integration
  2. Click "Configure". In the Diagnostics box click "Remove Device"
  3. Since I have been using the (Enroll / Un-Enroll) button near the battery, I used this to remove it
  4. Home Assistant should now show the lock removed.
  5. Reboot (not just reset) Home Assistant Host.

Add lock again. This time use the code route

  1. In Home Assistant, got to Configuration -> Devices & Services and find your Z-Wave JS integration
  2. Click "Configure". click the "+ Add Device" at the bottom
  3. Go to your door and use the manufacturer recommended way to remove (enroll) your lock. For me (schlage BE469) I pushed the schlage button with the lock extended, typed in my programming code then when I saw the orange flashing light I pressed 0. then it takes about 5-10 seconds to add the lock
  4. Your door should be added

From this point I tried the method suggested here of manually clearing the code slots thanks to @tykeal.

I honestly am not so sure that the button in the back with the battery is secure. I think the way I wrote above to add the lock in Z-Wave JS made all the difference.

@eboline
Copy link

eboline commented Apr 24, 2022

Hoping someone can help!

I also have the Schalge BE469ZP (Allegion) and have exluded it and removed all keymaster integrations 3 times now and set everything back up using @alsoszaa method of adding the lock back in. The lock connects just fine in home assistant, but I can not get this integration to work.

After installing the the integration in HACS and then adding the integration in HA, I get 11 entities (10 code slots and the network sensor). When I view those 11 entities though, the 10 card slots show as "unavailable" and the network entity shows just a "-" under status.

I'm guessing this is why, if I go through the rest of the set up process, I am unable to clear and reset codes, either in the UI or through the developer tools.

Please be gentle as I am new to HA, but would greatly appreciate any help that can be offered. Other Zwave devices are working just fine, when these sensors are generated through the integration, they are all showing as unavailable.

Thanks in advance!

@firstof9
Copy link
Collaborator

firstof9 commented Apr 24, 2022

the network entity shows just a "-"

If you go to dev tools -> settings -> info under system health, what does the keymaster info say?

Example:
image

@eboline
Copy link

eboline commented Apr 24, 2022

Thanks for the response!

Mine is showing exactly what you have in your example.

@firstof9
Copy link
Collaborator

Once the network status goes 'on' it should attempt to pull your codes from the lock and make the sensors available.

@eboline
Copy link

eboline commented Apr 24, 2022 via email

@firstof9
Copy link
Collaborator

Try enabling debugging to see if there's any errors happening.

@eboline
Copy link

eboline commented Apr 24, 2022 via email

@eboline
Copy link

eboline commented Apr 25, 2022

So I went to Dev Tools -> Services and selected the "logger.set_level" service and put "custom_components.keymaster: debug" and pressed "Call Service." The box turned green and I got no error messages on the screen. I then went to Configuration -> Settings -> Logs tab. I don't see anything related to keymaster, but maybe I'm not reading it correctly. Here's what comes up:

image

@firstof9
Copy link
Collaborator

View your home-assistant.log file in the /config directory.

@tommywatson
Copy link

tommywatson commented Apr 26, 2022

I'm having the same issue, I tried the dev tools clear code and I'm getting this in my logs;

2022-04-25 23:00:45 DEBUG (MainThread) [custom_components.keymaster] Clear Code service: <ServiceCall keymaster.clear_code (c:2cf6a44af212f2d59815c2859bdbafd4): code_slot=1, entity_id=lock.west>
2022-04-25 23:00:45 DEBUG (MainThread) [custom_components.keymaster.services] Attempting to call clear_usercode...
2022-04-25 23:00:45 ERROR (MainThread) [custom_components.keymaster.services] Error calling zwave_js.clear_lock_usercode service call: userIdStatus for code slot 1 not found
2022-04-25 23:00:45 ERROR (MainThread) [homeassistant.helpers.script.websocket_api_script] websocket_api script: Error executing script. Unexpected error for call_service at pos 1: userIdStatus for code slot 1 not found
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/helpers/script.py", line 379, in _async_step
    await getattr(self, handler)()
  File "/usr/src/homeassistant/homeassistant/helpers/script.py", line 582, in _async_call_service_step
    await service_task
  File "/usr/src/homeassistant/homeassistant/core.py", line 1634, in async_call
    task.result()
  File "/usr/src/homeassistant/homeassistant/core.py", line 1671, in _execute_service
    await cast(Callable[[ServiceCall], Awaitable[None]], handler.job.target)(
  File "/config/custom_components/keymaster/__init__.py", line 245, in _clear_code
    await clear_code(hass, entity_id, code_slot)
  File "/config/custom_components/keymaster/services.py", line 192, in clear_code
    await call_service(
  File "/config/custom_components/keymaster/services.py", line 94, in call_service
    raise err
  File "/config/custom_components/keymaster/services.py", line 89, in call_service
    await hass.services.async_call(
  File "/usr/src/homeassistant/homeassistant/core.py", line 1634, in async_call
    task.result()
  File "/usr/src/homeassistant/homeassistant/core.py", line 1671, in _execute_service
    await cast(Callable[[ServiceCall], Awaitable[None]], handler.job.target)(
  File "/usr/src/homeassistant/homeassistant/helpers/entity_platform.py", line 692, in handle_service
    await service.entity_service_call(
  File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 677, in entity_service_call
    future.result()  # pop exception if have
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 955, in async_request_call
    await coro
  File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 714, in _handle_entity_call
    await result
  File "/usr/src/homeassistant/homeassistant/components/zwave_js/lock.py", line 134, in async_clear_lock_usercode
    await clear_usercode(self.info.node, code_slot)
  File "/usr/local/lib/python3.9/site-packages/zwave_js_server/util/lock.py", line 122, in clear_usercode
    value = get_code_slot_value(node, code_slot, LOCK_USERCODE_STATUS_PROPERTY)
  File "/usr/local/lib/python3.9/site-packages/zwave_js_server/util/lock.py", line 32, in get_code_slot_value
    raise NotFoundError(f"{property_name} for code slot {code_slot} not found")
zwave_js_server.exceptions.NotFoundError: userIdStatus for code slot 1 not found
2022-04-25 23:00:45 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [281473088680672] Error handling message: Unknown error (unknown_error)
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/websocket_api/decorators.py", line 27, in _handle_async_response
    await func(hass, connection, msg)
  File "/usr/src/homeassistant/homeassistant/components/websocket_api/commands.py", line 630, in handle_execute_script
    await script_obj.async_run(msg.get("variables"), context=context)
  File "/usr/src/homeassistant/homeassistant/helpers/script.py", line 1275, in async_run
    await asyncio.shield(run.async_run())
  File "/usr/src/homeassistant/homeassistant/helpers/script.py", line 359, in async_run
    await self._async_step(log_exceptions=False)
  File "/usr/src/homeassistant/homeassistant/helpers/script.py", line 379, in _async_step
    await getattr(self, handler)()
  File "/usr/src/homeassistant/homeassistant/helpers/script.py", line 582, in _async_call_service_step
    await service_task
  File "/usr/src/homeassistant/homeassistant/core.py", line 1634, in async_call
    task.result()
  File "/usr/src/homeassistant/homeassistant/core.py", line 1671, in _execute_service
    await cast(Callable[[ServiceCall], Awaitable[None]], handler.job.target)(
  File "/config/custom_components/keymaster/__init__.py", line 245, in _clear_code
    await clear_code(hass, entity_id, code_slot)
  File "/config/custom_components/keymaster/services.py", line 192, in clear_code
    await call_service(
  File "/config/custom_components/keymaster/services.py", line 94, in call_service
    raise err
  File "/config/custom_components/keymaster/services.py", line 89, in call_service
    await hass.services.async_call(
  File "/usr/src/homeassistant/homeassistant/core.py", line 1634, in async_call
    task.result()
  File "/usr/src/homeassistant/homeassistant/core.py", line 1671, in _execute_service
    await cast(Callable[[ServiceCall], Awaitable[None]], handler.job.target)(
  File "/usr/src/homeassistant/homeassistant/helpers/entity_platform.py", line 692, in handle_service
    await service.entity_service_call(
  File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 677, in entity_service_call
    future.result()  # pop exception if have
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 955, in async_request_call
    await coro
  File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 714, in _handle_entity_call
    await result
  File "/usr/src/homeassistant/homeassistant/components/zwave_js/lock.py", line 134, in async_clear_lock_usercode
    await clear_usercode(self.info.node, code_slot)
  File "/usr/local/lib/python3.9/site-packages/zwave_js_server/util/lock.py", line 122, in clear_usercode
    value = get_code_slot_value(node, code_slot, LOCK_USERCODE_STATUS_PROPERTY)
  File "/usr/local/lib/python3.9/site-packages/zwave_js_server/util/lock.py", line 32, in get_code_slot_value
    raise NotFoundError(f"{property_name} for code slot {code_slot} not found")
zwave_js_server.exceptions.NotFoundError: userIdStatus for code slot 1 not found

@firstof9
Copy link
Collaborator

This means your lock didn't complete the interview.

@tommywatson
Copy link

Thanks, weird as the lock was working for months, I added another lock and it seems to be having issues. I'll try re-interviewing.
Thanks.

@eboline
Copy link

eboline commented Apr 26, 2022 via email

@firstof9
Copy link
Collaborator

Still showing the code slots as unavailable.

Need those logs to help you find out why.

@eboline
Copy link

eboline commented Apr 29, 2022

Good morning, sorry the delay. I've been out of town on work. Are these the logs you are hoping to see:

2022-04-24 22:06:30 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration keymaster which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2022-04-24 22:06:30 WARNING (SyncWorker_0) [homeassistant.loader] We found a custom integration hacs which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2022-04-24 22:06:41 WARNING (MainThread) [hass_nabucasa.cloud_api] Fetched https://alexa-api.nabucasa.com/access_token (400)
2022-04-24 22:06:57 WARNING (MainThread) [homeassistant.setup] Setup of zone is taking over 10 seconds.
2022-04-24 22:06:57 WARNING (MainThread) [homeassistant.setup] Setup of input_text is taking over 10 seconds.
2022-04-24 22:06:57 WARNING (MainThread) [homeassistant.setup] Setup of timer is taking over 10 seconds.
2022-04-24 22:06:57 WARNING (MainThread) [homeassistant.setup] Setup of input_boolean is taking over 10 seconds.
2022-04-24 22:06:57 WARNING (MainThread) [homeassistant.setup] Setup of input_datetime is taking over 10 seconds.
2022-04-24 22:06:57 WARNING (MainThread) [homeassistant.setup] Setup of input_button is taking over 10 seconds.
2022-04-24 22:06:57 WARNING (MainThread) [homeassistant.setup] Setup of input_number is taking over 10 seconds.
2022-04-24 22:06:57 WARNING (MainThread) [homeassistant.setup] Setup of counter is taking over 10 seconds.
2022-04-24 22:06:57 WARNING (MainThread) [homeassistant.setup] Setup of input_select is taking over 10 seconds.
2022-04-24 22:06:57 WARNING (MainThread) [homeassistant.components.scene] Setup of scene platform homeassistant is taking over 10 seconds.
2022-04-24 22:06:57 WARNING (MainThread) [homeassistant.components.update] Setup of update platform hassio is taking over 10 seconds.
2022-04-24 22:06:57 WARNING (MainThread) [homeassistant.components.lock] Setup of lock platform template is taking over 10 seconds.
2022-04-24 22:06:58 WARNING (MainThread) [homeassistant.setup] Setup of tplink is taking over 10 seconds.
2022-04-24 22:07:04 WARNING (MainThread) [homeassistant.components.dlna_dms] Failed connecting to recently alive device at http://192.168.1.100:2869/upnphost/udhisapi.dll?content=uuid:2c8242fa-27f3-48b4-b5f0-ef1046ea3df7: UpnpActionResponseError('Error during async_call(), status: 500, upnp error: 501 (Action Failed)')
2022-04-24 22:07:12 WARNING (MainThread) [homeassistant.components.zha.core.channels.base] [0x6805:1:0x0300]: async_initialize: all attempts have failed: [DeliveryError('[0x6805:1:0x0300]: Message send failure'), DeliveryError('[0x6805:1:0x0300]: Message send failure'), DeliveryError('[0x6805:1:0x0300]: Message send failure'), DeliveryError('[0x6805:1:0x0300]: Message send failure')]
2022-04-24 22:07:13 WARNING (MainThread) [homeassistant.components.zha.core.channels.base] [0x6805:1:0x0008]: async_initialize: all attempts have failed: [DeliveryError('[0x6805:1:0x0008]: Message send failure'), DeliveryError('[0x6805:1:0x0008]: Message send failure'), DeliveryError('[0x6805:1:0x0008]: Message send failure'), DeliveryError('[0x6805:1:0x0008]: Message send failure')]
2022-04-24 22:07:53 WARNING (MainThread) [homeassistant.components.zha.core.channels.base] [0x6805:1:0x0006]: async_initialize: all attempts have failed: [DeliveryError('[0x6805:1:0x0006]: Message send failure'), DeliveryError('[0x6805:1:0x0006]: Message send failure'), DeliveryError('[0x6805:1:0x0006]: Message send failure'), DeliveryError('[0x6805:1:0x0006]: Message send failure')]
2022-04-24 22:09:03 WARNING (MainThread) [homeassistant.components.zha.core.channels.base] [0x81EF:1:0x0300]: async_initialize: all attempts have failed: [TimeoutError(), TimeoutError(), TimeoutError(), TimeoutError()]
2022-04-24 22:09:04 WARNING (MainThread) [homeassistant.components.zha.core.channels.base] [0x81EF:1:0x0008]: async_initialize: all attempts have failed: [TimeoutError(), TimeoutError(), TimeoutError(), TimeoutError()]
2022-04-24 22:09:04 WARNING (MainThread) [homeassistant.components.zha.core.channels.base] [0x81EF:1:0x0006]: async_initialize: all attempts have failed: [TimeoutError(), TimeoutError(), TimeoutError(), TimeoutError()]
2022-04-24 22:11:00 WARNING (MainThread) [homeassistant.components.zha.core.channels.base] [0x81EF:1:0x0702]: async_initialize: all attempts have failed: [TimeoutError(), TimeoutError(), TimeoutError(), TimeoutError()]
2022-04-24 22:11:08 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x06'
2022-04-24 22:33:40 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:33:41 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:33:41 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:33:45 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:33:45 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:39:13 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:39:14 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:39:14 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:39:18 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:39:18 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:50:28 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:50:28 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:50:28 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:50:34 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:50:34 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:53:13 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:53:13 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:53:13 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:53:13 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:53:18 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 22:53:18 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-24 23:13:30 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xd9'
2022-04-24 23:14:06 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xe8'
2022-04-25 00:15:57 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x06'
2022-04-25 00:24:30 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x16'
2022-04-25 00:35:53 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x56'
2022-04-25 01:12:36 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xcb'
2022-04-25 01:18:24 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x66'
2022-04-25 01:20:12 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xb1'
2022-04-25 01:31:10 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x8a'
2022-04-25 01:47:36 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x53'
2022-04-25 02:23:15 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xad'
2022-04-25 02:37:50 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x1f'
2022-04-25 02:59:19 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x3a'
2022-04-25 03:26:18 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xdf'
2022-04-25 03:44:30 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x8d'
2022-04-25 03:53:39 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xb5'
2022-04-25 04:11:02 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x5a'
2022-04-25 04:18:03 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xf7'
2022-04-25 04:29:21 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x23'
2022-04-25 04:32:19 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x5e'
2022-04-25 05:13:20 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x85'
2022-04-25 05:16:09 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xbd'
2022-04-25 05:22:45 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x84'
2022-04-25 05:26:47 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xdb'
2022-04-25 05:32:24 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x69'
2022-04-25 06:04:12 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xc5'
2022-04-25 06:35:27 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xe9'
2022-04-25 06:53:07 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 06:53:12 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 06:55:33 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 06:56:42 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 06:56:45 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 06:57:22 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 07:00:12 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 07:00:12 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 07:00:19 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 07:04:29 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 07:04:29 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 07:04:35 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 07:08:18 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 07:08:18 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 07:31:11 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 07:31:16 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 07:33:48 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 07:33:53 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 07:38:30 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xda'
2022-04-25 07:58:15 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xeb'
2022-04-25 08:11:06 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 08:11:06 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 08:11:11 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 08:13:53 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x83'
2022-04-25 08:14:31 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 08:14:31 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 08:14:37 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 08:35:33 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xdb'
2022-04-25 08:41:33 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x70'
2022-04-25 09:10:59 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 09:10:59 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 09:11:04 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 09:14:11 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 09:14:11 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 09:14:15 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 09:38:00 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x55'
2022-04-25 09:44:36 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xcd'
2022-04-25 10:01:52 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x8c'
2022-04-25 10:04:22 ERROR (MainThread) [homeassistant.components.xbox] Error requesting xbox data: [Errno 104] Connection reset by peer
2022-04-25 10:09:37 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x71'
2022-04-25 10:10:00 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:10:14 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:10:39 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:17:03 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:17:18 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:20:40 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 503, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:20:47 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 503, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:21:11 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:21:47 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:24:30 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xbe'
2022-04-25 10:28:01 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:28:22 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:28:35 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 503, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:29:18 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:30:07 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:31:24 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:34:00 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:37:12 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 10:37:13 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 10:37:18 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 10:38:33 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-25 10:40:39 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 10:40:43 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 11:42:54 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x8a'
2022-04-25 11:48:59 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 11:48:59 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 11:49:17 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 11:54:48 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 11:54:53 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 12:45:21 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xf1'
2022-04-25 13:02:04 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:02:05 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:02:13 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:02:19 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:02:20 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:03:00 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:03:00 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:03:05 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:08:06 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:08:11 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:24:22 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:25:06 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:26:33 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:26:33 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:26:41 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:33:41 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:33:41 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:33:46 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:36:52 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:36:52 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:37:03 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:39:15 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xf9'
2022-04-25 13:44:02 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:44:02 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:44:18 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:44:30 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x6c'
2022-04-25 13:45:48 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:45:48 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:45:52 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 13:45:53 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 14:19:13 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xe9'
2022-04-25 14:34:54 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x9d'
2022-04-25 14:50:15 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x20'
2022-04-25 14:51:10 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x46'
2022-04-25 14:56:29 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xd3'
2022-04-25 16:02:54 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x56'
2022-04-25 16:11:15 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x69'
2022-04-25 16:47:52 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-25 16:48:00 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xc0'
2022-04-25 16:48:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-25 16:57:55 ERROR (MainThread) [homeassistant.components.xbox] Error requesting xbox data: 503, message='Service Unavailable', url=URL('https://peoplehub.xboxlive.com/users/me/people/batch/decoration/preferredcolor,detail,multiplayersummary,presencedetail')
2022-04-25 16:58:15 ERROR (MainThread) [homeassistant.components.xbox] Error requesting xbox data: 503, message='Service Unavailable', url=URL('https://peoplehub.xboxlive.com/users/me/people/social/decoration/preferredcolor,detail,multiplayersummary,presencedetail')
2022-04-25 17:04:30 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x8b'
2022-04-25 17:13:53 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-25 17:14:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-25 17:14:53 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-25 17:15:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-25 17:15:53 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-25 17:18:42 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xc8'
2022-04-25 17:19:41 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 17:19:58 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 17:19:58 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 17:20:08 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 17:22:33 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 17:50:59 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 17:50:59 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 17:51:20 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 17:51:50 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 17:51:58 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 17:56:44 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 17:57:36 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x48'
2022-04-25 17:59:12 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 17:59:12 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 18:01:08 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 18:01:08 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 18:01:14 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 18:01:28 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 18:01:28 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 18:01:35 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 18:02:32 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 18:02:32 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 18:07:01 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xbd'
2022-04-25 18:12:34 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 18:12:34 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 18:17:01 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 18:17:13 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 18:23:39 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 18:23:40 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 18:23:50 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 18:28:34 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xc0'
2022-04-25 19:39:52 WARNING (Thread-7) [pychromecast.socket_client] [TV(192.168.1.133):8009] Heartbeat timeout, resetting connection
2022-04-25 19:40:22 ERROR (Thread-7) [pychromecast.socket_client] [TV(192.168.1.133):8009] Failed to connect to service ServiceInfo(type='host', data=('192.168.1.133', 8009)), retrying in 5.0s
2022-04-25 19:53:06 ERROR (MainThread) [homeassistant.components.xbox] Error requesting xbox data: 503, message='Service Unavailable', url=URL('https://peoplehub.xboxlive.com/users/me/people/batch/decoration/preferredcolor,detail,multiplayersummary,presencedetail')
2022-04-25 20:02:52 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 20:02:52 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 20:09:16 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 20:09:41 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 20:12:05 ERROR (MainThread) [homeassistant.components.xbox] Error requesting xbox data: 503, message='Service Unavailable', url=URL('https://peoplehub.xboxlive.com/users/me/people/social/decoration/preferredcolor,detail,multiplayersummary,presencedetail')
2022-04-25 21:07:32 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 21:08:06 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 21:08:06 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 21:08:45 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-25 21:44:28 WARNING (MainThread) [async_upnp_client.profiles.profile] Failed (re-)subscribing to: uuid:1625b5fd-f112-41c5-85ea-cabfa7a89993, reason: UpnpConnectionTimeoutError('', None)
2022-04-25 21:44:33 WARNING (MainThread) [async_upnp_client.profiles.profile] Failed (re-)subscribing to: uuid:a2682af6-d6ff-40c6-bd0c-fdc41aef1260, reason: UpnpConnectionTimeoutError('', None)
2022-04-25 21:54:49 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x34'
2022-04-25 22:58:05 ERROR (MainThread) [homeassistant.core] Error executing service: <ServiceCall switch.turn_off (c:1a41e6e3a80995e1800573307dc60893): entity_id=['switch.bonus_room_switch']>
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/core.py", line 1652, in catch_exceptions
    await coro_or_task
  File "/usr/src/homeassistant/homeassistant/core.py", line 1671, in _execute_service
    await cast(Callable[[ServiceCall], Awaitable[None]], handler.job.target)(
  File "/usr/src/homeassistant/homeassistant/helpers/entity_component.py", line 204, in handle_service
    await service.entity_service_call(
  File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 677, in entity_service_call
    future.result()  # pop exception if have
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 955, in async_request_call
    await coro
  File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 714, in _handle_entity_call
    await result
  File "/usr/src/homeassistant/homeassistant/components/zwave_js/switch.py", line 83, in async_turn_off
    await self.info.node.async_set_value(self._target_value, False)
  File "/usr/local/lib/python3.9/site-packages/zwave_js_server/model/node/__init__.py", line 441, in async_set_value
    result = await self.async_send_command(
  File "/usr/local/lib/python3.9/site-packages/zwave_js_server/model/node/__init__.py", line 398, in async_send_command
    result = await self.client.async_send_command(message, **kwargs)
  File "/usr/local/lib/python3.9/site-packages/zwave_js_server/client.py", line 103, in async_send_command
    return await future
zwave_js_server.exceptions.FailedZWaveCommand: Z-Wave error 204: Failed to send the command after 3 attempts (Status NoAck) (ZW0204)
2022-04-25 23:09:51 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x76'
2022-04-25 23:14:04 ERROR (MainThread) [custom_components.hacs] HACS is disabled - HacsDisabledReason.RATE_LIMIT
2022-04-25 23:16:38 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x16'
2022-04-25 23:18:30 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x3b'
2022-04-25 23:24:51 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xb4'
2022-04-25 23:34:08 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x61'
2022-04-26 00:24:54 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xbc'
2022-04-26 00:32:36 ERROR (MainThread) [homeassistant.components.tplink.coordinator] Error fetching 192.168.1.108 data: Unable to connect to the device: 192.168.1.108
2022-04-26 01:02:27 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xbc'
2022-04-26 02:17:34 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xc0'
2022-04-26 06:20:16 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x10'
2022-04-26 06:27:00 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x35'
2022-04-26 06:27:22 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x4e'
2022-04-26 06:28:48 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x68'
2022-04-26 06:51:32 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 06:51:37 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 06:53:22 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 06:53:39 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 06:59:29 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 06:59:33 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 07:01:13 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 07:01:13 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 07:59:34 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 07:59:34 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 08:16:13 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 08:16:14 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 10:10:27 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x08'
2022-04-26 10:34:06 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x51'
2022-04-26 11:15:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x9c'
2022-04-26 11:36:43 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 11:36:49 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 13:19:12 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xb2'
2022-04-26 14:30:08 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 14:34:43 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 14:35:35 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 14:35:37 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 14:36:17 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 14:36:19 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 14:48:25 ERROR (MainThread) [homeassistant.components.xbox] Error requesting xbox data: 503, message='Service Unavailable', url=URL('https://peoplehub.xboxlive.com/users/me/people/batch/decoration/preferredcolor,detail,multiplayersummary,presencedetail')
2022-04-26 15:17:58 WARNING (MainThread) [hass_nabucasa.cloud_api] Fetched https://alexa-api.nabucasa.com/access_token (400)
2022-04-26 15:17:58 ERROR (MainThread) [homeassistant] Error doing job: Task exception was never retrieved
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/cloud/alexa_config.py", line 367, in _handle_entity_registry_updated
    await self._sync_helper(to_update, to_remove)
  File "/usr/src/homeassistant/homeassistant/components/cloud/alexa_config.py", line 311, in _sync_helper
    await self.async_get_access_token()
  File "/usr/src/homeassistant/homeassistant/components/cloud/alexa_config.py", line 173, in async_get_access_token
    raise alexa_errors.RequireRelink
homeassistant.components.alexa.errors.RequireRelink
2022-04-26 15:19:34 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xf4'
2022-04-26 16:05:48 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 16:05:50 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 16:05:56 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 16:09:49 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 16:09:51 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 16:09:58 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 16:13:44 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xba'
2022-04-26 16:20:31 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 16:20:33 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 16:26:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xa3'
2022-04-26 17:04:53 WARNING (Thread-7) [pychromecast.socket_client] [TV(192.168.1.133):8009] Heartbeat timeout, resetting connection
2022-04-26 17:05:11 ERROR (Thread-7) [pychromecast.socket_client] [TV(192.168.1.133):8009] Failed to connect to service ServiceInfo(type='host', data=('192.168.1.133', 8009)), retrying in 5.0s
2022-04-26 17:07:49 WARNING (MainThread) [async_upnp_client.profiles.profile] Failed (re-)subscribing to: uuid:baf11aa6-c5ac-11ec-8000-800000000000, reason: UpnpConnectionError("Cannot connect to host 192.168.1.133:52323 ssl:default [Connect call failed ('192.168.1.133', 52323)]", None)
2022-04-26 17:07:52 WARNING (MainThread) [async_upnp_client.profiles.profile] Failed (re-)subscribing to: uuid:baf66e0c-c5ac-11ec-8000-800000000000, reason: UpnpConnectionError("Cannot connect to host 192.168.1.133:52323 ssl:default [Connect call failed ('192.168.1.133', 52323)]", None)
2022-04-26 17:07:55 WARNING (MainThread) [async_upnp_client.profiles.profile] Failed (re-)subscribing to: uuid:bafabba6-c5ac-11ec-8000-800000000000, reason: UpnpConnectionError("Cannot connect to host 192.168.1.133:52323 ssl:default [Connect call failed ('192.168.1.133', 52323)]", None)
2022-04-26 17:16:54 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 17:16:56 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 17:29:00 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 17:29:02 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 17:33:19 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 17:42:17 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 17:42:19 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 17:50:50 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 17:50:52 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 17:51:09 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 19:16:28 ERROR (MainThread) [homeassistant.components.xbox] Error requesting xbox data: 503, message='Service Unavailable', url=URL('https://peoplehub.xboxlive.com/users/me/people/batch/decoration/preferredcolor,detail,multiplayersummary,presencedetail')
2022-04-26 19:28:58 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 19:29:00 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 19:29:07 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 19:29:27 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 19:29:29 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 19:29:36 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 19:35:31 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 19:35:34 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 19:41:59 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 19:42:01 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 19:42:40 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 19:42:49 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 19:44:51 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xde'
2022-04-26 20:03:48 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 20:03:50 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 20:04:41 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 20:04:43 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 20:07:57 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 20:08:03 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 20:08:30 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 20:08:32 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 20:08:38 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 20:11:27 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xd7'
2022-04-26 20:25:15 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xca'
2022-04-26 20:34:31 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 20:34:33 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 21:51:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x9d'
2022-04-26 21:53:40 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x0e'
2022-04-26 22:15:20 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 22:16:05 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 22:16:05 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-26 23:21:02 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x49'
2022-04-26 23:34:24 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x8b'
2022-04-26 23:43:45 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x5a'
2022-04-27 00:21:13 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x2f'
2022-04-27 02:43:33 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x9e'
2022-04-27 03:18:48 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x4c'
2022-04-27 04:39:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x38'
2022-04-27 05:52:42 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x62'
2022-04-27 07:01:50 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:01:58 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:04:19 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:04:21 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:04:48 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-27 07:04:50 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:04:52 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:05:04 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-27 07:05:28 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:06:33 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-27 07:06:47 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-27 07:08:36 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-27 07:08:53 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-27 07:09:18 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-27 07:10:25 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:10:40 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:12:49 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:12:51 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:13:14 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:13:24 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:13:30 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:13:32 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:13:38 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:13:44 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 07:19:40 WARNING (MainThread) [hass_nabucasa.iot] Cannot connect because unable to refresh token: Too many requests
2022-04-27 07:19:44 WARNING (MainThread) [hass_nabucasa.iot] Cannot connect because unable to refresh token: Too many requests
2022-04-27 07:19:56 WARNING (MainThread) [hass_nabucasa.iot] Cannot connect because unable to refresh token: Too many requests
2022-04-27 07:20:15 WARNING (MainThread) [hass_nabucasa.iot] Cannot connect because unable to refresh token: Too many requests
2022-04-27 07:20:54 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 503, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-27 07:43:16 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xee'
2022-04-27 08:34:02 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xe2'
2022-04-27 08:51:52 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xb0'
2022-04-27 08:54:15 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x16'
2022-04-27 09:30:12 ERROR (MainThread) [homeassistant.components.speedtestdotnet] Error fetching speedtestdotnet data: Unable to connect to servers to test latency.
2022-04-27 09:46:54 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x06'
2022-04-27 10:54:21 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x70'
2022-04-27 11:06:20 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x9d'
2022-04-27 11:57:06 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xaf'
2022-04-27 13:09:15 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x94'
2022-04-27 13:17:23 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 13:17:25 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 13:17:36 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 13:20:40 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 13:20:42 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 13:20:48 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 13:22:12 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 13:22:14 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 13:22:20 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 13:31:10 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xee'
2022-04-27 13:38:38 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xaa'
2022-04-27 13:49:14 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 13:49:58 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 13:53:01 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 14:17:06 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x7f'
2022-04-27 14:23:47 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x11'
2022-04-27 14:29:24 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xa3'
2022-04-27 14:37:50 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x81'
2022-04-27 15:20:10 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x81'
2022-04-27 15:20:11 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x8a'
2022-04-27 16:10:56 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x6c'
2022-04-27 16:14:29 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xbe'
2022-04-27 16:21:51 ERROR (MainThread) [homeassistant.components.xbox] Error requesting xbox data: 503, message='Service Unavailable', url=URL('https://peoplehub.xboxlive.com/users/me/people/social/decoration/preferredcolor,detail,multiplayersummary,presencedetail')
2022-04-27 16:48:56 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 16:48:58 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 17:10:36 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 17:10:38 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 17:14:08 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 17:14:10 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 17:14:16 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 17:14:23 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 17:16:39 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 17:18:29 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 17:18:31 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 17:26:16 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x0e'
2022-04-27 17:52:28 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x92'
2022-04-27 18:24:31 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x95'
2022-04-27 18:29:19 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x12'
2022-04-27 18:31:15 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x58'
2022-04-27 18:41:30 ERROR (MainThread) [homeassistant.components.xbox] Error requesting xbox data: 503, message='Service Unavailable', url=URL('https://peoplehub.xboxlive.com/users/me/people/social/decoration/preferredcolor,detail,multiplayersummary,presencedetail')
2022-04-27 18:43:14 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x6b'
2022-04-27 18:53:27 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 18:53:29 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 18:54:17 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 18:54:31 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 18:54:33 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 18:55:26 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 18:55:28 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 18:55:35 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 18:56:31 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 18:56:33 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 18:56:39 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 18:58:02 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 18:58:13 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 20:27:10 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x8c'
2022-04-27 21:01:30 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 21:01:43 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 21:34:48 ERROR (MainThread) [homeassistant.components.xbox] Error requesting xbox data: 503, message='Service Unavailable', url=URL('https://peoplehub.xboxlive.com/users/me/people/batch/decoration/preferredcolor,detail,multiplayersummary,presencedetail')
2022-04-27 22:21:39 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x81'
2022-04-27 22:25:00 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 22:25:20 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 22:25:20 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 22:26:07 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 22:26:07 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 22:26:09 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 22:26:09 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 22:26:38 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 22:26:38 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-27 22:48:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:49:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:50:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:50:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:51:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:51:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:52:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:52:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:53:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:53:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:54:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:54:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:55:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:55:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:56:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:56:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:57:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xc0'
2022-04-27 22:57:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:57:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:58:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:58:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:59:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 22:59:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:00:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:00:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:01:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:01:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:02:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:02:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:03:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:03:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:04:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:04:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:05:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:05:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:06:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:06:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:07:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:07:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:08:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:08:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:09:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:09:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:10:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:10:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:11:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:11:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:12:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:12:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:13:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:13:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:14:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:14:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:15:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:15:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:16:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:16:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:17:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:17:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:18:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:18:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:19:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:19:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:20:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:20:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:21:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:21:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:22:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:22:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:23:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:23:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:24:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:24:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:25:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:25:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:26:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:26:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:27:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:27:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:28:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:28:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:29:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:29:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:30:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:30:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:31:26 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:31:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:32:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:32:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:33:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:33:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:34:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:34:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:35:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:35:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:36:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:36:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:37:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:37:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:38:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:38:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:39:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:39:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:40:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:40:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:41:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:41:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:42:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:42:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:43:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:43:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:44:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:44:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:45:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:45:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:46:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:46:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:47:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:47:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:48:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:48:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:49:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:49:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:50:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:50:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:51:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:51:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:52:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:52:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:53:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:53:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:54:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:54:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:55:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:55:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:56:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:56:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:57:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:57:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:58:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:58:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:59:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-27 23:59:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:00:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:00:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:01:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:01:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:02:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:02:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:03:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:03:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:04:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:04:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:05:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:05:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:06:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:06:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:07:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:07:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:08:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:08:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:09:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:09:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:10:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:10:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:11:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:11:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:12:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:12:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:13:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:13:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:14:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:14:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:15:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:15:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:16:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:16:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:17:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:17:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:18:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:18:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:19:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x0c'
2022-04-28 00:19:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:19:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:20:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:20:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:21:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:21:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:22:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:22:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:23:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:23:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:24:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:24:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:25:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:25:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:26:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:26:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:27:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:27:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:28:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:28:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:29:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:29:51 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xc9'
2022-04-28 00:29:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:30:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:30:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:31:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:31:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:32:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:32:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:33:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:33:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:34:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:34:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:35:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:35:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:36:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:36:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:37:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:37:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:38:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:38:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:39:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:39:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:40:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:40:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:41:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:41:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:42:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:42:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:43:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:43:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:44:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:44:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:45:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:45:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:46:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:46:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:47:28 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:47:58 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:48:28 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:48:58 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:49:28 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:49:58 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:50:28 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 00:50:58 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 01:11:28 WARNING (MainThread) [homeassistant.helpers.entity] Update of binary_sensor.living_room_occupancy is taking over 10 seconds
2022-04-28 01:25:48 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xf7'
2022-04-28 01:29:22 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x3e'
2022-04-28 01:40:37 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x19'
2022-04-28 02:20:08 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xe3'
2022-04-28 03:06:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xf1'
2022-04-28 04:01:40 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xe0'
2022-04-28 04:11:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x76'
2022-04-28 04:26:21 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x88'
2022-04-28 04:28:40 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xb4'
2022-04-28 06:57:17 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 06:57:19 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 06:57:25 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 07:00:09 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 07:00:14 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 07:00:55 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 07:00:58 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 07:05:55 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x12'
2022-04-28 07:26:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x0d'
2022-04-28 08:08:58 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x5e'
2022-04-28 08:15:30 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x18'
2022-04-28 08:24:30 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xe8'
2022-04-28 08:27:05 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x34'
2022-04-28 09:12:01 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x94'
2022-04-28 10:15:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xb9'
2022-04-28 10:27:27 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xd0'
2022-04-28 10:30:48 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x3b'
2022-04-28 10:35:40 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xaf'
2022-04-28 10:36:28 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xcc'
2022-04-28 10:47:48 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x06'
2022-04-28 10:55:52 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xc0'
2022-04-28 12:01:24 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 12:04:38 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 12:06:19 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 12:06:21 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 12:07:18 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 12:07:21 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 12:19:43 WARNING (Thread-7) [pychromecast.socket_client] [TV(192.168.1.133):8009] Heartbeat timeout, resetting connection
2022-04-28 12:20:13 ERROR (Thread-7) [pychromecast.socket_client] [TV(192.168.1.133):8009] Failed to connect to service ServiceInfo(type='host', data=('192.168.1.133', 8009)), retrying in 5.0s
2022-04-28 12:24:14 WARNING (MainThread) [hass_nabucasa.iot] Unable to connect: 504, message='Invalid response status', url=URL('wss://cloud.nabucasa.com/websocket')
2022-04-28 12:40:25 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xdb'
2022-04-28 13:16:36 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 13:17:06 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 13:17:36 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 13:18:06 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 13:23:06 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 13:23:36 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 13:24:06 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 13:24:36 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 13:25:06 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 13:25:36 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 13:26:06 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 13:26:36 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 13:27:06 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 13:56:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x08'
2022-04-28 14:10:52 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x77'
2022-04-28 14:27:37 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 14:28:11 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x26'
2022-04-28 15:01:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x31'
2022-04-28 15:01:38 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x46'
2022-04-28 15:21:14 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 15:21:16 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 15:23:07 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 15:23:37 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.bedroom_lamp_electrical_measurement is taking over 10 seconds
2022-04-28 15:38:07 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 15:52:24 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x31'
2022-04-28 15:57:58 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 16:42:40 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x0a'
2022-04-28 16:43:33 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 16:44:13 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x4a'
2022-04-28 17:02:14 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 17:02:16 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 17:03:45 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 17:03:47 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 17:33:56 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x36'
2022-04-28 17:43:32 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 17:43:34 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 17:43:50 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 17:51:44 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 17:51:46 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 18:24:42 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x5f'
2022-04-28 19:09:28 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xa1'
2022-04-28 19:15:28 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x3c'
2022-04-28 20:00:51 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 20:01:07 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 20:37:50 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x69'
2022-04-28 21:28:52 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 21:28:55 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 21:31:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x45'
2022-04-28 21:52:39 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 21:52:41 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 21:52:47 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 21:57:03 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 21:57:05 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 21:57:11 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 22:02:06 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 22:02:08 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 22:02:08 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 22:03:36 WARNING (Thread-3) [homeassistant.components.mqtt] Disconnected from MQTT server core-mosquitto:1883 (7)
2022-04-28 22:38:32 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x20'
2022-04-28 22:38:44 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 22:43:23 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 22:43:23 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 22:43:25 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 22:43:25 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 22:44:47 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-28 22:49:40 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x6f'
2022-04-28 22:52:45 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xe3'
2022-04-28 23:09:13 ERROR (MainThread) [homeassistant.components.tplink.coordinator] Error fetching 192.168.1.101 data: Unable to connect to the device: 192.168.1.101
2022-04-29 00:20:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x45'
2022-04-29 01:10:50 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x14'
2022-04-29 01:30:12 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x24'
2022-04-29 02:10:43 ERROR (MainThread) [homeassistant.components.tplink.coordinator] Error fetching 192.168.1.101 data: Unable to connect to the device: 192.168.1.101
2022-04-29 02:15:06 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x7e'
2022-04-29 02:26:44 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x7e'
2022-04-29 02:29:52 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xef'
2022-04-29 02:30:23 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x0f'
2022-04-29 02:52:22 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x0a'
2022-04-29 03:22:33 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xdd'
2022-04-29 03:43:08 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xb9'
2022-04-29 04:30:00 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x5b'
2022-04-29 04:33:54 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xb2'
2022-04-29 05:24:40 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xa8'
2022-04-29 05:30:56 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x55'
2022-04-29 05:58:17 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xdb'
2022-04-29 06:10:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x3f'
2022-04-29 06:11:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x60'
2022-04-29 06:34:44 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xdb'
2022-04-29 06:44:54 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xe1'
2022-04-29 07:06:12 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x33'
2022-04-29 07:14:58 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 07:15:00 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 07:15:06 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 07:16:04 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xfc'
2022-04-29 07:20:03 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 07:20:05 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 07:23:46 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 07:23:48 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 07:23:55 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 07:24:20 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 07:24:28 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 07:26:32 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 07:26:34 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 07:26:40 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 07:44:30 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x25'
2022-04-29 07:52:21 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xfc'
2022-04-29 08:11:51 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 08:11:53 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 08:11:59 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 08:15:55 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 08:15:57 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 08:16:46 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 08:19:07 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0xa5'
2022-04-29 08:31:05 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running
2022-04-29 08:31:07 WARNING (MainThread) [homeassistant.components.automation.back_door_opens] Back Door Opens: Already running

@firstof9
Copy link
Collaborator

These contain no debug logs from keymaster.

@eboline
Copy link

eboline commented Apr 29, 2022

I double checked my configuration.yaml file to ensure that logging was enable using the instructions above. Then I called the service through Dev Tools -> Services from the instruction and got a green check mark. This is what was added to the log when I called the service:

2022-04-29 08:49:14 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:49:19 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:49:24 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:49:29 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:49:34 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:49:39 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:49:44 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:49:49 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:49:54 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:49:59 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:50:04 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:50:09 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:50:14 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:50:19 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:50:24 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:50:29 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:50:34 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:50:39 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:50:44 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:50:49 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:50:54 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:50:59 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:51:04 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:51:09 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:51:14 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:51:19 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:51:24 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:51:29 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:51:34 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:51:39 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:51:44 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:51:49 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:51:54 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:51:59 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:52:04 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:52:09 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:52:14 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 08:52:19 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)

@firstof9
Copy link
Collaborator

Try making your configuration yaml similar to this:

logger:
  default: error
  logs:
     custom_components.keymaster: debug

and restart Home Assistant, this will give a better run down of what's going on.

@eboline
Copy link

eboline commented Apr 29, 2022

I really appreciate your help with this Chris!

I updated the configuration.yaml file as you showed above and restarted HA. Here's what is in the log now:

2022-04-29 09:03:38 WARNING (SyncWorker_0) [homeassistant.loader] We found a custom integration hacs which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2022-04-29 09:03:38 WARNING (SyncWorker_1) [homeassistant.loader] We found a custom integration keymaster which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant
2022-04-29 09:04:31 INFO (MainThread) [custom_components.keymaster] Version v0.0.79 is starting, if you have any issues please report them here: https://github.com/FutureTense/keymaster
2022-04-29 09:04:32 DEBUG (MainThread) [custom_components.keymaster.binary_sensor] Connected to zwave_js network
2022-04-29 09:04:32 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:04:35 ERROR (MainThread) [zigpy.application] Couldn't start application
2022-04-29 09:04:35 ERROR (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start deCONZ = dresden elektronik deCONZ protocol: ConBee I/II, RaspBee I/II coordinator
Traceback (most recent call last):
  File "/usr/local/lib/python3.9/asyncio/tasks.py", line 492, in wait_for
    fut.result()
asyncio.exceptions.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 182, in async_initialize
    self.application_controller = await app_controller_cls.new(
  File "/usr/local/lib/python3.9/site-packages/zigpy/application.py", line 69, in new
    await app.startup(auto_form)
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/zigbee/application.py", line 67, in startup
    self.version = await self._api.version()
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 463, in version
    (self._proto_ver,) = await self[NetworkParameter.protocol_version]
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 428, in read_parameter
    r = await self._command(Command.read_parameter, 1 + len(data), param, data)
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 313, in _command
    return await asyncio.wait_for(fut, timeout=COMMAND_TIMEOUT)
  File "/usr/local/lib/python3.9/asyncio/tasks.py", line 494, in wait_for
    raise exceptions.TimeoutError() from exc
asyncio.exceptions.TimeoutError
2022-04-29 09:04:35 ERROR (MainThread) [homeassistant.components.automation.living_room_lights_remote] Unknown error while setting up trigger (empty result) Living Room Lights Remote
2022-04-29 09:04:35 ERROR (MainThread) [homeassistant.components.automation.living_room_lights_remote] Unknown error while setting up trigger (empty result) Living Room Lights Remote
2022-04-29 09:04:35 ERROR (MainThread) [homeassistant.components.automation.living_room_lights_remote] Unknown error while setting up trigger (empty result) Living Room Lights Remote
2022-04-29 09:04:35 ERROR (MainThread) [homeassistant.components.automation.living_room_lights_remote] Unknown error while setting up trigger (empty result) Living Room Lights Remote
2022-04-29 09:04:37 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:04:41 ERROR (MainThread) [zigpy.application] Couldn't start application
2022-04-29 09:04:41 ERROR (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start deCONZ = dresden elektronik deCONZ protocol: ConBee I/II, RaspBee I/II coordinator
Traceback (most recent call last):
  File "/usr/local/lib/python3.9/asyncio/tasks.py", line 492, in wait_for
    fut.result()
asyncio.exceptions.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 182, in async_initialize
    self.application_controller = await app_controller_cls.new(
  File "/usr/local/lib/python3.9/site-packages/zigpy/application.py", line 69, in new
    await app.startup(auto_form)
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/zigbee/application.py", line 67, in startup
    self.version = await self._api.version()
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 463, in version
    (self._proto_ver,) = await self[NetworkParameter.protocol_version]
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 428, in read_parameter
    r = await self._command(Command.read_parameter, 1 + len(data), param, data)
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 313, in _command
    return await asyncio.wait_for(fut, timeout=COMMAND_TIMEOUT)
  File "/usr/local/lib/python3.9/asyncio/tasks.py", line 494, in wait_for
    raise exceptions.TimeoutError() from exc
asyncio.exceptions.TimeoutError
2022-04-29 09:04:42 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:04:47 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:04:52 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:04:54 ERROR (MainThread) [zigpy.application] Couldn't start application
2022-04-29 09:04:54 ERROR (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start deCONZ = dresden elektronik deCONZ protocol: ConBee I/II, RaspBee I/II coordinator
Traceback (most recent call last):
  File "/usr/local/lib/python3.9/asyncio/tasks.py", line 492, in wait_for
    fut.result()
asyncio.exceptions.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 182, in async_initialize
    self.application_controller = await app_controller_cls.new(
  File "/usr/local/lib/python3.9/site-packages/zigpy/application.py", line 69, in new
    await app.startup(auto_form)
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/zigbee/application.py", line 67, in startup
    self.version = await self._api.version()
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 463, in version
    (self._proto_ver,) = await self[NetworkParameter.protocol_version]
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 428, in read_parameter
    r = await self._command(Command.read_parameter, 1 + len(data), param, data)
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 313, in _command
    return await asyncio.wait_for(fut, timeout=COMMAND_TIMEOUT)
  File "/usr/local/lib/python3.9/asyncio/tasks.py", line 494, in wait_for
    raise exceptions.TimeoutError() from exc
asyncio.exceptions.TimeoutError
2022-04-29 09:04:57 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:05:02 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:05:07 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:05:12 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:05:16 ERROR (MainThread) [zigpy.application] Couldn't start application
2022-04-29 09:05:16 ERROR (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start deCONZ = dresden elektronik deCONZ protocol: ConBee I/II, RaspBee I/II coordinator
Traceback (most recent call last):
  File "/usr/local/lib/python3.9/asyncio/tasks.py", line 492, in wait_for
    fut.result()
asyncio.exceptions.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 182, in async_initialize
    self.application_controller = await app_controller_cls.new(
  File "/usr/local/lib/python3.9/site-packages/zigpy/application.py", line 69, in new
    await app.startup(auto_form)
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/zigbee/application.py", line 67, in startup
    self.version = await self._api.version()
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 463, in version
    (self._proto_ver,) = await self[NetworkParameter.protocol_version]
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 428, in read_parameter
    r = await self._command(Command.read_parameter, 1 + len(data), param, data)
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 313, in _command
    return await asyncio.wait_for(fut, timeout=COMMAND_TIMEOUT)
  File "/usr/local/lib/python3.9/asyncio/tasks.py", line 494, in wait_for
    raise exceptions.TimeoutError() from exc
asyncio.exceptions.TimeoutError
2022-04-29 09:05:17 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:05:22 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:05:27 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:05:32 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:05:40 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:05:45 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:05:50 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:05:55 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:05:58 ERROR (MainThread) [zigpy.application] Couldn't start application
2022-04-29 09:05:58 ERROR (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start deCONZ = dresden elektronik deCONZ protocol: ConBee I/II, RaspBee I/II coordinator
Traceback (most recent call last):
  File "/usr/local/lib/python3.9/asyncio/tasks.py", line 492, in wait_for
    fut.result()
asyncio.exceptions.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 182, in async_initialize
    self.application_controller = await app_controller_cls.new(
  File "/usr/local/lib/python3.9/site-packages/zigpy/application.py", line 69, in new
    await app.startup(auto_form)
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/zigbee/application.py", line 67, in startup
    self.version = await self._api.version()
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 463, in version
    (self._proto_ver,) = await self[NetworkParameter.protocol_version]
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 428, in read_parameter
    r = await self._command(Command.read_parameter, 1 + len(data), param, data)
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 313, in _command
    return await asyncio.wait_for(fut, timeout=COMMAND_TIMEOUT)
  File "/usr/local/lib/python3.9/asyncio/tasks.py", line 494, in wait_for
    raise exceptions.TimeoutError() from exc
asyncio.exceptions.TimeoutError
2022-04-29 09:06:00 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:06:05 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:06:10 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:06:15 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:06:20 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:06:25 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:06:30 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:06:35 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:06:40 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:06:45 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:06:50 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:06:55 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:07:00 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:07:05 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:07:10 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:07:15 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:07:20 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:07:20 ERROR (MainThread) [zigpy.application] Couldn't start application
2022-04-29 09:07:20 ERROR (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start deCONZ = dresden elektronik deCONZ protocol: ConBee I/II, RaspBee I/II coordinator
Traceback (most recent call last):
  File "/usr/local/lib/python3.9/asyncio/tasks.py", line 492, in wait_for
    fut.result()
asyncio.exceptions.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 182, in async_initialize
    self.application_controller = await app_controller_cls.new(
  File "/usr/local/lib/python3.9/site-packages/zigpy/application.py", line 69, in new
    await app.startup(auto_form)
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/zigbee/application.py", line 67, in startup
    self.version = await self._api.version()
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 463, in version
    (self._proto_ver,) = await self[NetworkParameter.protocol_version]
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 428, in read_parameter
    r = await self._command(Command.read_parameter, 1 + len(data), param, data)
  File "/usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py", line 313, in _command
    return await asyncio.wait_for(fut, timeout=COMMAND_TIMEOUT)
  File "/usr/local/lib/python3.9/asyncio/tasks.py", line 494, in wait_for
    raise exceptions.TimeoutError() from exc
asyncio.exceptions.TimeoutError
2022-04-29 09:07:25 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:07:30 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)
2022-04-29 09:07:35 DEBUG (MainThread) [custom_components.keymaster] Finished fetching keymaster data in 0.000 seconds (success: True)

@firstof9
Copy link
Collaborator

image

@DarthBill13
Copy link

DarthBill13 commented Jul 9, 2022

Having the same issues with my Schlage lock , Keymaster, and Z-wave. I have a Zooz ZST10 Z-Wave S2 USB Stick that I use on my HA. I just requested a firmware update from the manufacturer. They make you request it, it's not immediately available on their site. Hopefully this will fix some of the incomplete interview issues. Here is the link for those interested: https://www.support.getzooz.com/kb/article/1126-how-to-perform-an-ota-firmware-update-on-zooz-devices-in-home-assistant/

@DiverJim
Copy link

I finally got beyond "stuck on adding or deleting" today. I noticed that some of the sensors on my locks were "unknown". I have deleted, re-added etc the locks before but not today. I just kept re-interviewing them, again and again until the codeslots where working. Re-interviewed, re-started, and eventually both were working without add/removing from z-wave. Screenshots are a lock before/after
front_door
front_door_after

@eboline
Copy link

eboline commented Oct 11, 2022 via email

@raub21
Copy link

raub21 commented Oct 12, 2022

After trying most of the suggestions mentioned here and removing the schlage lock connect many times and adding it again, I finally succeeded to connect the pins. To do it or to test it, do the following:
1- If your lock is reset it to default, it should come with 2 codes. create 4 codes during the keymaster configurations
2- after configuring keymaster and paste the code for the lovelace in the dashboard, add new user with its pin in the code 3 or code 4. Dont attempt to do that in code 1 or code 2. it will stuck in adding.
3- you can delete the default codes using service tab either with zwave clear code or keymaster.

@arthurEspooner
Copy link

nope, still does not work for many people. i have a yale and two schlages i tested with 2" from the hub. i can start from scratch, re-interview, anything you can think of, it doesn't work.

i created a new HA to test this with all the various fixes and still nothing works. the locks get stuck on adding/deleting. some people can get a random slot to work, or add codes manually first then add to keymaster, but what's the point? you're introducing a layer of complexity for no reason.

this issue goes back 1-2 years with various brands. threads get closed with no real resolution and there are multiple threads open with this issue. i would not trust this integration until this is fixed.

@raub21
Copy link

raub21 commented Oct 19, 2022

nope, still does not work for many people. i have a yale and two schlages i tested with 2" from the hub. i can start from scratch, re-interview, anything you can think of, it doesn't work.

i created a new HA to test this with all the various fixes and still nothing works. the locks get stuck on adding/deleting. some people can get a random slot to work, or add codes manually first then add to keymaster, but what's the point? you're introducing a layer of complexity for no reason.

this issue goes back 1-2 years with various brands. threads get closed with no real resolution and there are multiple threads open with this issue. i would not trust this integration until this is fixed.

Did you delete all the default codes that come with the lock? I was experiencing the same issue for weeks. I finally resolved by deleting all the codes and then went to the keymaster and start creating the users and their codes. I dont remember if I had to restart HA.

Update:
I just added the my second schlage door and did not have to factory reset it. After installing keymaster, I could not have it to work properly. I restarted HA. By the way, I am using zwave js integration. So, I went to the developer tools<services and issue the command : keymaster.refresh_codes. After that, you might have to wait a little bit. Then go to zwave js and check your lock is showing User code section. Check to see all the codes and the slots. Make notes of that. You may have to enable some slots. You can also delete some them from there. Or to services and issue keymaster.clear_code. After that, go the lovelace for keymaster and enter your codes with users and should connect.

@firstof9 firstof9 mentioned this issue Nov 24, 2022
@cmatte
Copy link

cmatte commented Jan 7, 2023

If it is of any help for anyone else, seeing I've been banging my head too on this issue for a while, I have solved it with the workaround automation I shared here!

@boojew
Copy link

boojew commented Jan 26, 2023

I am having the same issue described above. I am on 2023.1.7 on my main instance running zwavejs getting events over WS from an rpi that has my zwave stick running HASS 2023.1.7 w/ zwavejs there. My zwave network is (from what I can see) super stable. My lock is a BE469ZP Here is what I have found:

  1. In debug mode, keymaster gets the pins from slots 1-9 that are set.
  2. using the zwavejs HASS service, I can set the pins of 1-9 w/o issue
  3. Keymaster shows pins 1-3 in adding/deleting and those pins never get set. Zwavejs doesnt even get the event.
  4. Setting pins 4-9 in keymaster works properly

So seems like a bug in keymaster dealing with those first 3 pins

@augster
Copy link

augster commented Feb 19, 2023

Hopefully this helps as I was having the same issues as everyone else here and this resolved the problem for me:
Lockset: Schlage BE469ZP
using Z-Wave JS integration

  1. include your lock on the z wave network
  2. click the include/exclude button on the lockset (inside battery coder)
  3. re-interview the lock from HA
  4. All data should now come in and pins should start working.

solution found on reddit: https://www.reddit.com/r/homeassistant/comments/tuxdu9/schlage_connect_lock_cannot_set_usercodes/

@kars85
Copy link

kars85 commented Feb 22, 2023

Oof - that sounds like a painful process for someone with a lot of locks paired with automations already tied to them. I have five BE469ZP's, and they're finicky to pair short of removing the control board and battery pack and re-adding right next to the controller. Wish only step 3 was necessary for slots 1-3 to work and not be stuck in deleting/adding state.

@firstof9
Copy link
Collaborator

A re-interview can be done in place, you don't have to move the lock anywhere.

@kars85
Copy link

kars85 commented Feb 23, 2023

A re-interview can be done in place, you don't have to move the lock anywhere.

Thanks - there's a known issue with the BE469ZP that causes re-interviews to lose all nodeinfo details until a magic amount of tries fixes it.

zwave-js/node-zwave-js#3484

Used it as a chance to update my stick's firmware, so not all was lost. Unfortunately, after getting it reinterviewed correctly, the lock is still stuck on deleting. I don't think these locks are solid candidates for tweaking with. If it works with basic functionality, I'd leave it be.

@nuditarian
Copy link

nuditarian commented Mar 13, 2023

I had been having this same issue, all codes stuck adding/deleting. Background, had working config with 2 x Schlage B468, zwave-js and key master. zwave-js handled by 2 x raspberry pi with zooz USB ZST10 sticks. I accidentally cleared zwave config on one of the zooz sticks while upgrading firmware, and when I added everything back in I could never get key master to properly handle codes for the door that had to be re-configured. Long story short, for troubleshooting, in zwave-js, make sure your lock has the User Code command class (go to device detail, Node section, you should see "User Code v1" or some such along with other details. If you don't see it, re-interview the node, and you might need to tick the "Reset security classes" checkbox. I had just gone through a reconfig (remove key master integration, exclude lock, re-add lock, re-add key master integration), and it was still broken. Confirmed User Code class was absent, re-interviewed one time, User Code class showed up, all is fixed.

I have to wonder if Keymaster could show an intelligent error when trying to handle codes if the "User Code" command class is absent.

@firstof9 firstof9 pinned this issue Apr 21, 2023
@mgulick
Copy link

mgulick commented Oct 15, 2023

Having this issue on my Yale Assure 2 lock after swapping out the ZW2 module for the ZW3 module (in hopes of making my z-wave connection more reliable). I deleted the existing keymaster integration and created a new one. The code slots set through keymaster are actually being set in the lock, but keymaster doesn't reflect that (stuck on "Adding"). I can manually use both the zwave_js.{set_lock_usercode,clear_lock_usercode} and the keymaster.{add_code,clear_code) services to set and clear codes, and those both succeed (the "Call Service" button changes to a checkmark after a few seconds).

When setting the code through the UI, the debug logs show the error message "DEBUG: Code slot NNN not enabled":

2023-10-13 18:04:48.289 DEBUG (MainThread) [custom_components.keymaster] Add Code service: <ServiceCall keymaster.add_code (c:01HCNH468QJ4FJNSW6ARVV98A9): entity_id=lock.assure_2_keyless_touch_screen_deadbolt, code_slot=2, usercode=12345>
2023-10-13 18:04:48.290 DEBUG (MainThread) [custom_components.keymaster.services] Attempting to call set_usercode...
2023-10-13 18:04:52.782 DEBUG (MainThread) [custom_components.keymaster] DEBUG: Code slot 1 not enabled
2023-10-13 18:04:52.782 DEBUG (MainThread) [custom_components.keymaster] DEBUG: Code slot 2 not enabled
2023-10-13 18:04:52.782 DEBUG (MainThread) [custom_components.keymaster] DEBUG: Code slot 3 not enabled
2023-10-13 18:04:52.782 DEBUG (MainThread) [custom_components.keymaster] DEBUG: Code slot 4 not enabled
2023-10-13 18:04:52.782 DEBUG (MainThread) [custom_components.keymaster] DEBUG: Code slot 5 not enabled
...
2023-10-13 18:04:52.839 DEBUG (MainThread) [custom_components.keymaster] DEBUG: Code slot 496 not enabled
2023-10-13 18:04:52.839 DEBUG (MainThread) [custom_components.keymaster] DEBUG: Code slot 497 not enabled
2023-10-13 18:04:52.839 DEBUG (MainThread) [custom_components.keymaster] DEBUG: Code slot 498 not enabled
2023-10-13 18:04:52.839 DEBUG (MainThread) [custom_components.keymaster] DEBUG: Code slot 499 not enabled
2023-10-13 18:04:52.839 DEBUG (MainThread) [custom_components.keymaster] DEBUG: Code slot 500 not enabled

I've tried resetting all of the code slots manually through the service calls. This succeeds, but doesn't resolve the issue with setting the codes through the UI. Any idea what causes the "Code slot NNN not enabled" message?

@mgulick
Copy link

mgulick commented Oct 16, 2023

I tried debugging this a little more. It looks like the "Code slot NNN not enabled" is coming from here:

_LOGGER.debug("DEBUG: Code slot %s not enabled", code_slot)
, which is calling into zwave_js here: https://github.com/home-assistant-libs/zwave-js-server-python/blob/ca30b7558eeac921582e3dabd8d0591d3f4364bc/zwave_js_server/util/lock.py#L80. I didn't see any reference to this function in the zwave_js debug output.

Here is the zwave_js debug output when adding a new usercode. The zwave_js messages show success in setting the usercode. However there is no mention of the get_usercode function which keymaster is calling. Any idea how to debug this further?

home-assistant_zwave_js_2023-10-16T13-17-29.568Z.log

@firstof9
Copy link
Collaborator

Check to make sure the code slot is enabled via the zwavejs_ui control panel.

@mgulick
Copy link

mgulick commented Oct 16, 2023

Check to make sure the code slot is enabled via the zwavejs_ui control panel.

I'm using the official Z-Wave JS add-on, not the Z-Wave JS UI unfortunately. I don't see anything about code slot in the list of enabled or disabled entities, nor in the zwave-js configuration page. Is there any other way to look this up?

@firstof9
Copy link
Collaborator

Only way I know how.

@mgulick
Copy link

mgulick commented Oct 16, 2023

I switched from the Z-Wave JS add-on to the Z-Wave JS UI add-on (following https://www.home-assistant.io/integrations/zwave_js/#how-do-i-switch-between-the-official-z-wave-js-add-on-and-the-z-wave-js-ui-add-on), and that seems to have fixed the problem. I can add and remove codes as expected.

I didn't try switching back to Z-Wave JS to see if it was the switching that fixed it, or Z-Wave JS UI. Thanks for the suggestion!

@drawks
Copy link

drawks commented Jan 7, 2024

A re-interview can be done in place, you don't have to move the lock anywhere.

Thanks - there's a known issue with the BE469ZP that causes re-interviews to lose all nodeinfo details until a magic amount of tries fixes it.

zwave-js/node-zwave-js#3484

Just spent a crazy amount of time trying to get this working, until I found your comment. Sure enough everything started working fine after a series of re-interviews and rebooting my HA server. Fortunately I don't have a frequent need to update pins, set schedules for them etc and that is the primary thing that this bug impacts.

At any rate @FutureTense maybe consider marking this issue as resolved or won't fix and add a note in the docs?

@mgulick
Copy link

mgulick commented Apr 19, 2024

Sorry to reply to this thread again, but I'm having this issue again, although I've found a workaround and I wanted to get your thoughts on it.

Removing existing codes works. They do not get stuck. However when adding a code, KeyMaster gets stuck at "Adding". I do get a notification saying that the code has been added, and the code actually works on the door lock. However KeyMaster doesn't know that the code was successfully added, and remains at "Adding". The problem with this is that when I disable a code slot, the code isn't actually disabled, I assume because KeyMaster thinks it was never actually enabled.

The first workaround that I found was to go into Z-Wave JS UI, go to the User Code config, and click "Refresh Values". This works. It takes a while to refresh all of the values though, and generates a lot of traffic with the lock. When I look at the events in Z-Wave JS UI, I can see all code slots being refreshed, from slot 1 to 500, and it takes several minutes to complete.

A shorter way that I've found to refresh this value is by issuing the following service call from HA:

service: zwave_js.invoke_cc_api
target:
  entity_id: lock.assure_2_keyless_touch_screen_deadbolt
data:
  command_class: "99"
  method_name: get
  parameters:
    - 5

The parameter '5' is the code slot to refresh. After issuing this service call, KeyMaster immediately updates from "Adding" to "Connected".

I'm wondering if this is a bug in zwave_js, and if I should report it there?

@mgulick
Copy link

mgulick commented Aug 5, 2024

In case anybody needs it, I've permanently worked around this issue by creating an automation which triggers on the user code add zwave-js notification event, and then issues a zwave-js CC command to refesh the specific slot which generated the event. The device_id in the trigger is the device id for the lock.

alias: Fix Keymaster User Code Add
description: ""
trigger:
  - platform: device
    device_id: ebcc82a6df7be991068406c54e9be498
    domain: zwave_js
    type: event.notification.notification
    command_class: 113
    type.: "6"
    event: "14"
condition: []
action:
  - delay:
      hours: 0
      minutes: 0
      seconds: 5
      milliseconds: 0
  - service: notify.mike_notify
    metadata: {}
    data:
      message: Detected User Code Add {{ trigger.event.data.parameters.data[1] }}
    enabled: true
  - service: zwave_js.invoke_cc_api
    target:
      entity_id: lock.assure_2_keyless_touch_screen_deadbolt
    data:
      command_class: "99"
      method_name: get
      parameters:
        - "{{ trigger.event.data.parameters.data[1] }}"
mode: single

@xyg0t3
Copy link

xyg0t3 commented Aug 31, 2024

I'll just add how I got mine working. Called the clear lock user code svc on slot 1. Then all my slots started working again. Good luck.

@mgulick
Copy link

mgulick commented Sep 17, 2024

I'll just add how I got mine working. Called the clear lock user code svc on slot 1. Then all my slots started working again. Good luck.

Unfortunately this doesn't resolve the issue for me.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Projects
None yet
Development

No branches or pull requests