search

home-assistant / core

:house_with_garden: Open source home automation that puts local control and privacy first.
https://www.home-assistant.io
Apache License 2.0
72.16k stars 30.2k forks source link

ZHA network unstable on 2021.8.4 #54312

Closed RuminationsCode closed 2 years ago

RuminationsCode commented 3 years ago

The problem

Upon upgrding to 2021.8.4 (or any earlier 8.X release) ZHA is unstable with a Conbee II stick. Downgrading to 7.2 immediately resolves the issue. Roughly 5 minutes after booting Home Assistant logs the errors below, zigbee devices on the network then respond slowly and recover until the errors occur again 5 minutes later. I had also tried upgrading the firmware on the Conbee II to the latest and tried with and without a USB extension cable. The downgrade to 7.2 is the only solution.

No response to 'Command.aps_data_indication' command with seq id '0x7c' 6:56:20 PM – (WARNING) /usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py Duplicate or delayed response for 0x:7c sequence 6:56:20 PM – (WARNING) /usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py

What is version of Home Assistant Core has the issue?

2021.8.4

What was the last working version of Home Assistant Core?

2021.7.2

What type of installation are you running?

Home Assistant Container

Integration causing the issue

ZHA

Link to integration documentation on our website

https://www.home-assistant.io/integrations/zha/

Example YAML snippet

No response

Anything in the logs that might be useful for us?

This occurs shortly after boot

2021-08-09 18:55:49 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE8CA](TS0505B): Attempting to checkin with device - missed checkins: 1
2021-08-09 18:55:49 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 92 under 93 request id, data: b'005c000400'
2021-08-09 18:55:49 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 93, 0, <DeconzAddressEndpoint address_mode=2 address=0xE8CA endpoint=1>, 260, 0, 1, b'\x00\\\x00\x04\x00', 2, 0)
2021-08-09 18:55:49 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x194F](TRADFRI Signal Repeater): Attempting to checkin with device - missed checkins: 1
2021-08-09 18:55:49 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 94 under 95 request id, data: b'005e000400'
2021-08-09 18:55:49 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 93]
2021-08-09 18:55:49 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 95, 0, <DeconzAddressEndpoint address_mode=2 address=0x194F endpoint=1>, 260, 0, 1, b'\x00^\x00\x04\x00', 2, 0)
2021-08-09 18:55:49 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 95]
2021-08-09 18:55:50 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2021-08-09 18:55:50 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2021-08-09 18:55:50 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 93: e1
2021-08-09 18:55:50 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x5d 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xe8ca endpoint=1>, status: 0xe1
2021-08-09 18:55:50 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Error while sending 93 req id frame: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
2021-08-09 18:55:50 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2021-08-09 18:55:50 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2021-08-09 18:55:50 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 95: e1
2021-08-09 18:55:50 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x5f 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x194f endpoint=1>, status: 0xe1
2021-08-09 18:55:50 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Error while sending 95 req id frame: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
2021-08-09 18:55:50 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x0000](ConBee II): Attempting to checkin with device - missed checkins: 1
2021-08-09 18:55:50 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x0000](ConBee II): does not have a mandatory basic cluster

Followed later by

2021-08-09 18:56:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-08-09 18:56:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0]
2021-08-09 18:56:20 WARNING (MainThread) [zigpy_deconz.api] Duplicate or delayed response for 0x:7c sequence
2021-08-09 18:56:20 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [34, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0xfffd>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x9fbd>, 0, 0, 0, b'\xafK\xce\x05\xff\xff.!\x00\x00\x00', 0, 175, 156, 233, 78, 64, 0, -66]
2021-08-09 18:56:20 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 175 under 96 request id, data: b'af004bce05ffff2e210000000000'
2021-08-09 18:56:20 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x7c'
2021-08-09 18:56:20 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (29, 96, 0, <DeconzAddressEndpoint address_mode=2 address=0x9FBD endpoint=0>, 0, <ZDOCmd.NWK_addr_rsp: 0x8000>, 0, b'\xaf\x00K\xce\x05\xff\xff.!\x00\x00\x00\x00\x00', 2, 0)
2021-08-09 18:56:21 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 96]
2021-08-09 18:56:21 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
2021-08-09 18:56:21 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,)
2021-08-09 18:56:21 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 96: d0
2021-08-09 18:56:21 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0x60 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x9fbd endpoint=0>, status: 0xd0
2021-08-09 18:56:21 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Error while sending 96 req id frame: TXStatus.NWK_ROUTE_DISCOVERY_FAILED

Additional information

No response

probot-home-assistant[bot] commented 3 years ago

Hey there @dmulcahey, @adminiuga, mind taking a look at this issue as it has been labeled with an integration (zha) you are listed as a code owner for? Thanks! (message by CodeOwnersMention)

zha documentation zha source (message by IssueLinks)

northalpha commented 3 years ago

Can confirm this behaviour:

Logger: zigpy_deconz.api
Source: /usr/local/lib/python3.9/site-packages/zigpy_deconz/api.py:307
First occurred: 8. August 2021, 14:54:41 (18 occurrences)
Last logged: 7:37:21

No response to 'Command.aps_data_indication' command with seq id '0x04'
No response to 'Command.aps_data_indication' command with seq id '0x37'
No response to 'Command.aps_data_indication' command with seq id '0x5d'
No response to 'Command.aps_data_indication' command with seq id '0xd9'
No response to 'Command.aps_data_indication' command with seq id '0x22'
Logger: zigpy.topology
Source: /usr/local/lib/python3.9/site-packages/zigpy/topology.py:65
First occurred: 9:41:35 (1 occurrences)
Last logged: 9:41:35

Cancelled topology scanning task
Logger: zigpy_deconz.zigbee.application
Source: /usr/local/lib/python3.9/site-packages/zigpy_deconz/zigbee/application.py:55
First occurred: 9:44:18 (13 occurrences)
Last logged: 11:14:18

No watchdog response
mbo18 commented 3 years ago

Are your conbee II up to date? Mine is updated to latest firmware and I do not have these issues

Adminiuga commented 3 years ago 
No response to 'Command.aps_data_indication' command with seq id '0x5d'

Logger: zigpy_deconz.zigbee.application Source: /usr/local/lib/python3.9/site-packages/zigpy_deconz/zigbee/application.py:55 First occurred: 9:44:18 (13 occurrences) Last logged: 11:14:18

No watchdog response

In your case, no response means stick stop responding completely to any commands and not responding to watchdog request. This is different from original OP. There weren't any meaningful changes in zigpy-deconz between the releases, other than a one liner bug fix. What hardware is this on?

Adminiuga commented 3 years ago

@RuminationsCode the presence of TXStatus.MAC_CHANNEL_ACCESS_FAILURE indicates heavy RF interference. ConBee II are quite sensitive to it and you may need to change the channel to 25 or 15 (using deCONZ) , use an usb extension cable, preferably on USB2 port, and keeping away it from any other usb3 devices and sources of interferenc like wifi, BT any keyboard dongles etc

samueltardieu commented 3 years ago

@Adminiuga I have also noticed a lot of network instability since switching to 2021.8.0, using a ConBee I on a Odroid N2+ (up-to-date ConBee I with a USB extension cable and no USB3 device). I'm not home right now but I can see several ZigBee sensors as unavailable for several hours at a time, and ZigBee presence detectors were flaky half of the time. Nothing has changed in my hardware or software configuration since 2021.7.x (including no new wireless devices), and as the OP, the switch to 2021.8.0 was the starting point of those ZigBee issues and the successive 2021.8.x versions have not made the situation better.

Adminiuga commented 3 years ago

Copy zha folder from 2021.7 into custom_component and run it this way?

RuminationsCode commented 3 years ago

As I was trying to tshoot this one I removed the USB cable to rule things out so maybe we ignore the MAC CHANNEL ACCESS FAILURE and write that off to that change. The timestamps of that error don't line up with devices becoming unresponsive but just thought it might be contributing.

I'll try copying the ZHA folder across from 2021.7 today to see how that goes but I did also see some watchdog errors at times, not always but i did see them while using the USB Extension Cable post upgrade. Thats why I tried updating the firmware on the conbee.

I've also confirmed again that moving back to 2021.7 instantly resolves the issue. I'll let you know how copying the zha folder goes.

RuminationsCode commented 3 years ago

I upgraded to 8.4 again and reinstalled the USB extension cable. I had tried copying the ZHA folder but saw it wasn't loaded due to lacking a version key. No more MAC CHANNEL ACCESS FAILURES. I got a single No response to 'Command.aps_data_indication' command with seq id '0x04' but things seem to be working as normal. I'll keep monitoring it for today to see if it manifests and if it comes back ill work on getting ZHA in as a custom component

RuminationsCode commented 3 years ago

Issue has re-occured. Even with the 2021.7 code added in as a custom component i get the same behaviour. An error is logged, zigbee network is unrepsonsive for a few seconds, then after its processed something successfully its fine till the next error. I did also get a watchdog to co-incide with this the first time it happened but not the second. The only change to make this start happening is moving to 2021.8.X:

2021-08-10 10:54:22 WARNING (MainThread) [zigpy_deconz.api] Duplicate or delayed response for 0x:51 sequence 2021-08-10 10:54:22 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [54, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, , 1, , 11, 260, 768, b'\x18\xcf\x01\x08\x00\x000\x01\x07\x00\x00!\x99\x00\x03\x00\x00!l)\x04\x00\x00!=\r\x02@\x00 \x00', 0, 175, 255, 186, 173, 64, 0, -67] 2021-08-10 10:54:22 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x51' 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 209 under 210 request id, data: b'00d1000000' 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 210, 0, , 260, 6, 1, b'\x00\xd1\x00\x00\x00', 2, 0) 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 210] 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0] 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,) 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0] 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 210: 00 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0xd2 'aps_data_confirm' for , status: 0x00 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1) 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [31, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, , 1, , 11, 260, 6, b'\x18\xd1\x01\x00\x00\x00\x10\x00', 0, 175, 255, 191, 173, 64, 0, -64] 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from , ep: 11, profile: 0x0104, cluster_id: 0x0006, data: b'18d1010000001000' 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 211 under 212 request id, data: b'00d3000000' 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 212, 0, , 260, 8, 1, b'\x00\xd3\x00\x00\x00', 2, 0) 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 212] 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0] 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,) 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 212: 00 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0xd4 'aps_data_confirm' for , status: 0x00 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0] 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1) 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [31, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, , 1, , 11, 260, 8, b'\x18\xd3\x01\x00\x00\x00 \xfe', 0, 175, 255, 191, 173, 64, 0, -65] 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from , ep: 11, profile: 0x0104, cluster_id: 0x0008, data: b'18d30100000020fe' 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 213 under 214 request id, data: b'00d50008000700030004000240' 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (28, 214, 0, , 260, 768, 1, b'\x00\xd5\x00\x08\x00\x07\x00\x03\x00\x04\x00\x02@', 2, 0) 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 214] 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0] 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,) 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 214: 00 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0xd6 'aps_data_confirm' for , status: 0x00 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0] 2021-08-10 10:54:42 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1) 2021-08-10 10:54:46 WARNING (MainThread) [zigpy_deconz.api] No response to 'Command.aps_data_indication' command with seq id '0x5a' 2021-08-10 10:54:54 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [52, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, , 1, , 11, 260, 768, b'\x18\xd5\x01\x08\x00\x000\x02\x07\x00\x00!n\x01\x03\x00\x00!\x1cu\x04\x00\x00!\xfbh\x02@\x86', 0, 175, 255, 191, 173, 64, 0, -64] 2021-08-10 10:54:54 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0] 2021-08-10 10:54:54 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0] 2021-08-10 10:54:55 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1) 2021-08-10 10:54:55 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 42>, , 1, , 2, 260, 1030, b'\x08\xcc\n\x00\x00\x18\x00', 0, 175, 255, 195, 173, 64, 0, -61] 2021-08-10 10:54:55 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 204 under 215 request id, data: b'18cc0b0a00' 2021-08-10 10:54:55 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from , ep: 2, profile: 0x0104, cluster_id: 0x0406, data: b'08cc0a00001800' 2021-08-10 10:54:55 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1) 2021-08-10 10:54:55 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [30, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, , 1, , 2, 260, 1030, b'\x18\xcd\n\x00\x00\x18\x00', 0, 175, 255, 195, 173, 64, 0, -56] 2021-08-10 10:54:55 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from , ep: 2, profile: 0x0104, cluster_id: 0x0406, data: b'18cd0a00001800' 2021-08-10 10:54:55 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 215, 0, , 260, 1030, 1, b'\x18\xcc\x0b\n\x00', 2, 0) 2021-08-10 10:54:55 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 215] 2021-08-10 10:54:55 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0] 2021-08-10 10:54:55 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,) 2021-08-10 10:54:55 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 215: 00 2021-08-10 10:54:55 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0xd7 'aps_data_confirm' for , status: 0x00 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 216 under 217 request id, data: b'00d8000000' 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 217, 0, , 260, 6, 1, b'\x00\xd8\x00\x00\x00', 2, 0) 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 217] 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0] 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,) 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 217: 00 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0xd9 'aps_data_confirm' for , status: 0x00 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0] 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1) 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [31, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, , 1, , 11, 260, 6, b'\x18\xd8\x01\x00\x00\x00\x10\x00', 0, 175, 248, 198, 173, 64, 0, -73] 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from , ep: 11, profile: 0x0104, cluster_id: 0x0006, data: b'18d8010000001000' 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 218 under 219 request id, data: b'00da000000' 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (20, 219, 0, , 260, 8, 1, b'\x00\xda\x00\x00\x00', 2, 0) 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 219] 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0] 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,) 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 219: 00 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0xdb 'aps_data_confirm' for , status: 0x00 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0] 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1) 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [31, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, , 1, , 11, 260, 8, b'\x18\xda\x01\x00\x00\x00 \x8a', 0, 175, 252, 198, 173, 64, 0, -73] 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from , ep: 11, profile: 0x0104, cluster_id: 0x0008, data: b'18da01000000208a' 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 220 under 221 request id, data: b'00dc0008000700030004000240' 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (28, 221, 0, , 260, 768, 1, b'\x00\xdc\x00\x08\x00\x07\x00\x03\x00\x04\x00\x02@', 2, 0) 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 221] 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0] 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,) 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 221: 00 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0xdd 'aps_data_confirm' for , status: 0x00 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0] 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1) 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [54, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, , 1, , 11, 260, 768, b'\x18\xdc\x01\x08\x00\x000\x01\x07\x00\x00!\x99\x00\x03\x00\x00!\x125\x04\x00\x00!\x82`\x02@\x00 \x00', 0, 175, 255, 198, 173, 64, 0, -73] 2021-08-10 10:55:18 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from , ep: 11, profile: 0x0104, cluster_id: 0x0300, data: b'18dc0108000030010700002199000300002112350400002182600240002000' 2021-08-10 10:55:27 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 222 under 223 request id, data: b'01de01' 2021-08-10 10:55:27 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (18, 223, 0, , 260, 6, 1, b'\x01\xde\x01', 2, 0) 2021-08-10 10:55:27 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 223] 2021-08-10 10:55:27 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0] 2021-08-10 10:55:27 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,) 2021-08-10 10:55:27 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 223: 00 2021-08-10 10:55:27 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0xdf 'aps_data_confirm' for , status: 0x00 2021-08-10 10:55:27 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0] 2021-08-10 10:55:27 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1) 2021-08-10 10:55:27 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, , 1, , 11, 260, 6, b'\x18\xde\x0b\x01\x00', 0, 175, 255, 202, 173, 64, 0, -65] 2021-08-10 10:55:27 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from , ep: 11, profile: 0x0104, cluster_id: 0x0006, data: b'18de0b0100' 2021-08-10 10:55:28 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Sending Zigbee request with tsn 224 under 225 request id, data: b'01e000' 2021-08-10 10:55:28 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_request (18, 225, 0, , 260, 6, 1, b'\x01\xe0\x00', 2, 0) 2021-08-10 10:55:28 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 225] 2021-08-10 10:55:28 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0] 2021-08-10 10:55:28 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_confirm (0,) 2021-08-10 10:55:28 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 225: 00 2021-08-10 10:55:28 DEBUG (MainThread) [zigpy_deconz.api] Request id: 0xe1 'aps_data_confirm' for , status: 0x00 2021-08-10 10:55:28 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0] 2021-08-10 10:55:28 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1) 2021-08-10 10:55:28 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, , 1, , 11, 260, 6, b'\x18\xe0\x0b\x00\x00', 0, 175, 255, 202, 173, 64, 0, -64] 2021-08-10 10:55:28 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from , ep: 11, profile: 0x0104, cluster_id: 0x0006, data: b'18e00b0000' 2021-08-10 10:55:30 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0] 2021-08-10 10:55:30 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1) 2021-08-10 10:55:30 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0] 2021-08-10 10:55:30 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [31, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 42>, , 1, , 1, 260, 1026, b'\x18\x9c\n\x00\x00)\x8c\t', 0, 175, 255, 202, 173, 64, 0, -41] 2021-08-10 10:55:30 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0402, data: b'189c0a0000298c09' 2021-08-10 10:55:30 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1) 2021-08-10 10:55:30 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 170>, 0] 2021-08-10 10:55:30 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [31, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|2: 42>, , 1, , 1, 260, 1029, b'\x18\x9d\n\x00\x00!\xb8\x0e', 0, 175, 255, 202, 173, 64, 0, -41] 2021-08-10 10:55:30 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0405, data: b'189d0a000021b80e' 2021-08-10 10:55:30 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1) 2021-08-10 10:55:30 DEBUG (MainThread) [zigpy_deconz.api] APS data indication response: [40, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, , 1, , 1, 260, 1027, b'\x18\x9e\n\x00\x00)\x00\x04\x14\x00(\xff\x10\x00)\x07(', 0, 175, 255, 202, 173, 64, 0, -41] 2021-08-10 10:55:30 DEBUG (MainThread) [zigpy_deconz.api] 'aps_data_indication' response from , ep: 1, profile: 0x0104, cluster_id: 0x0403, data: b'189e0a0000290004140028ff1000290728' 2021-08-10 10:55:30 DEBUG (MainThread) [zigpy_deconz.api] Command Command.aps_data_indication (1, 1)

RuminationsCode commented 3 years ago

Just a note, I rolled back to 2021.7.4, no errors, no delays in zigbee at all. The main symptom I see on 8.4 is sometimes a sensor updates slowly or it may take several seconds for a light to turn on when the instruction is sent. I'm really at a loss for whats changing between the versions given copying ZHA across didn't prevent the issue.

RuminationsCode commented 3 years ago

After more digging I think its the Kasa integration causing it. Saw another thread that was causing delays and since disabling it the issue seems to have disappeared. Will report back

carlosCastro99 commented 3 years ago

I had the same. My temperature sensors were the most affected. I had nice daily temp graphics, now its completely messed. Rolling back to 7.2 didn't make any difference.

serlinGi commented 3 years ago

I don't repeat everything that is already in this discussion but I confirm that same problems in the last 2 weeks; I use ZHA with conbeeII and it was very stable from the first day I adopted this solution, several months ago. I also confirm that the most affected of my 24 devices are the endpoint sensors. My system: Raspberry Pi4, HomeAssistant.OS supervised

samueltardieu commented 3 years ago

I replaced my ConBee I with a ConBee II that I had around yesterday and kept the same network key, etc. so that everything is still paired. The problems continue, temperature/humidity/pressure sensors, some switches and door sensors are also affected and some events are missed (and when they do they seem to be for several minutes or at least dozens of seconds – when a door sensor doesn't transmit its new state, closing and reopening the door a few times right away doesn't seem to make the events get through). Everything was rock solid up to 2021.7.x.

MattWestb commented 3 years ago

For all deCONZ radios is recommended (of DE) updating the firmware on the device then they have fixed some (but not all fund) bugs https://github.com/dresden-elektronik/deconz-rest-plugin/wiki/Firmware-Changelog and the USB fix is most importation for CornBee II.

Also the CornBee II looks being tricky with the timing of the communication and if some integrations i having problems in HA also the communication with CornBee II is getting problems but its more fixes for that in the new firmware.

samueltardieu commented 3 years ago

I have updated the ConBee II with the latest fimware using deconz before switching to it two days ago. The ConBee II is used with an USB extension cable, with no USB 3 device connected to the Odroid N2+.

I see that there is a new one dated from today, I will update right now and update this thread if the situation becomes better.

carlosCastro99 commented 3 years ago

I updated mine today. I don't see any visible changes in the behaviors mentioned above. I tried to downgrade to 2021.7.2 but no luck - I'm wondering if this can be related to the OS upgrade and not the core. (I'm using Rpi4 with HASS OS)

sdbcs commented 3 years ago

I see a similar behaviour as described initially. My setup was HomeAssistant on a Raspberry Pi 4 with ZHA integration. I used an XBee3 with the ZHA integration. At one point after one recent update (Core and Supervisor) the ZigBee functionality became unreliable. So I bought a ConBee II and tried it with the ZHA installation. This did not work. I switched to use the ConBee with the deConz Gateway addon and deConz integration using the gateway, ZigBee did not work either. To make sure the ConBee hardware is operational I did setup a separate deConz Gateway on a spare Raspberry Pi 2. This proved the ConBee working. I configured the deConz integration on the Pi 4 to use the external Zigbee gateway and ZigBee was working again with HA on the Pi 4. So my suspicion is that not the ZHA integration is broken but something else within HA which has a negative effect on the ZHA integration.

f45tb00t commented 3 years ago

Hello,

I can confirm the issue. But instead of a Stick, I am using the sonoff zigbee bridge flashed via tasmota and reachable via TCP.

I was wondering why all battery stauts of the sensors were not show. Rebooted the sonoff bridge, rebooted HA. Nothing helped.

For the Aqara sensors I needed to press the button to connect them again. I never had this issues before.

Adminiuga commented 3 years ago

Sonoff is a complete different issue. By anyone with ConBee, have you tried downgrading Home-assistant OS to earlier version?

atmezferix commented 3 years ago

I'm also having issue with devices dropping off recently, but I can't pin point it to what version it started (currently on 2021.8.8. I use the ZZH 2562R dongle and have been having no issue for months but now all device seem to be coming unavailable frequently and need to be reconnected for them to work again. ZHA must of been upgraded at some point as I have never noticed the unavailable time in seconds menus that have appear when you click configure. Maybe there is a bug in this ???? I rebooted HA earlier and all my device are available again, but I don't know how long for.

max5962 commented 3 years ago

Same issue here. My zigate through "zigpy_zigate" is completely unstable and even unusable.

My temp sensors do not work anymore and my Ikea smart plug respond only few second after initialize.

Adminiuga commented 3 years ago

What hardware? Update to the latest HA version Debug logs with the problem

max5962 commented 3 years ago

What hardware? Update to the latest HA version Debug logs with the problem

Zigate on synology NAS Was working very well since 2 years.

I tried 2021.8.* 2021.9.0 same issue. Rollback to 2021.7.4 is better ( temps sensor is working now, but smartPlug is only working fews seconds )

I'm changing logger.yaml :)

Adminiuga commented 3 years ago

Hrm, there were no changes in zigpy-zigate at all. And since rolling back does not solve the issue, i wonder is anything else has changed in ha core

max5962 commented 3 years ago

Hrm, there were no changes in zigpy-zigate at all. And since rolling back does not solve the issue, i wonder is anything else has changed in ha core

Here some logs in full debug (trying to off an ikea smart plug ) in 2021.7.4 version. im tring to update again to 2021.9.0 in order to do the same tests

2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] Loading application state
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd0f4:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd0f4:1:0x0000] Attribute id: 5 value: lumi.sensor_magnet.aq2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x320d:1:0x0000] Attribute id: 4 value: IKEA of Sweden
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x320d:1:0x0000] Attribute id: 5 value: TRADFRI control outlet
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd42d:1:0x0000] Attribute id: 4 value: IKEA of Sweden
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd42d:1:0x0000] Attribute id: 5 value: TRADFRI control outlet
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd8c2:1:0x0000] Attribute id: 4 value: IKEA of Sweden
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd8c2:1:0x0000] Attribute id: 5 value: TRADFRI control outlet
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xb8c9:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xb8c9:1:0x0000] Attribute id: 5 value: lumi.weather
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x7e70:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x7e70:1:0x0000] Attribute id: 5 value: lumi.sensor_magnet.aq2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x8016:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xaf42:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xaf42:1:0x0000] Attribute id: 5 value: lumi.sensor_motion.aq2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2ff5:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2ff5:1:0x0000] Attribute id: 5 value: lumi.sensor_motion.aq2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2d81:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2d81:1:0x0000] Attribute id: 5 value: lumi.sensor_magnet.aq2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa88b:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x857c:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x857c:1:0x0000] Attribute id: 5 value: lumi.sensor_magnet.aq2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xfc09:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xfc09:1:0x0000] Attribute id: 5 value: lumi.sensor_magnet.aq2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x1a37:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x1a37:1:0x0000] Attribute id: 5 value: lumi.weather
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xf961:1:0x0000] Attribute id: 4 value: IKEA of Sweden
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xf961:1:0x0000] Attribute id: 5 value: TRADFRI remote control
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x544e:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x544e:1:0x0000] Attribute id: 5 value: lumi.weather
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa9b2:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa9b2:1:0x0000] Attribute id: 5 value: lumi.sensor_motion.aq2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x8016:1:0x0000] Attribute id: 5 value: lumi.weather
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa88b:1:0x0000] Attribute id: 5 value: lumi.weather
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.sensor_magnet.aq2 (00:15:8d:00:01:de:63:da)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.magnet_aq2.MagnetAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:01:de:63:da: <class 'zhaquirks.xiaomi.aqara.magnet_aq2.MagnetAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for IKEA of Sweden TRADFRI control outlet (00:0d:6f:ff:fe:10:6f:86)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.ikea.tradfriplug.TradfriPlug'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:0d:6f:ff:fe:10:6f:86: <class 'zhaquirks.ikea.tradfriplug.TradfriPlug'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for IKEA of Sweden TRADFRI control outlet (00:0d:6f:ff:fe:10:79:b7)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.ikea.tradfriplug.TradfriPlug'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:0d:6f:ff:fe:10:79:b7: <class 'zhaquirks.ikea.tradfriplug.TradfriPlug'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for IKEA of Sweden TRADFRI control outlet (00:0d:6f:ff:fe:10:6e:35)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.ikea.tradfriplug.TradfriPlug'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:0d:6f:ff:fe:10:6e:35: <class 'zhaquirks.ikea.tradfriplug.TradfriPlug'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.weather (00:15:8d:00:02:2c:af:79)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.weather.Weather2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:02:2c:af:79: <class 'zhaquirks.xiaomi.aqara.weather.Weather2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.sensor_magnet.aq2 (00:15:8d:00:01:e4:59:a2)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.magnet_aq2.MagnetAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:01:e4:59:a2: <class 'zhaquirks.xiaomi.aqara.magnet_aq2.MagnetAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.weather (00:15:8d:00:02:01:b1:e5)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.weather.Weather2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:02:01:b1:e5: <class 'zhaquirks.xiaomi.aqara.weather.Weather2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.sensor_motion.aq2 (00:15:8d:00:02:25:37:9a)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.motion_aq2b.MotionAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.motion_aq2.MotionAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:02:25:37:9a: <class 'zhaquirks.xiaomi.aqara.motion_aq2.MotionAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.sensor_motion.aq2 (00:15:8d:00:02:25:15:7c)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.motion_aq2b.MotionAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.motion_aq2.MotionAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:02:25:15:7c: <class 'zhaquirks.xiaomi.aqara.motion_aq2.MotionAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.sensor_magnet.aq2 (00:15:8d:00:01:b9:7d:f3)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.magnet_aq2.MagnetAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:01:b9:7d:f3: <class 'zhaquirks.xiaomi.aqara.magnet_aq2.MagnetAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.weather (00:15:8d:00:02:01:b6:de)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.weather.Weather2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:02:01:b6:de: <class 'zhaquirks.xiaomi.aqara.weather.Weather2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.sensor_magnet.aq2 (00:15:8d:00:01:b7:b5:a2)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.magnet_aq2.MagnetAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:01:b7:b5:a2: <class 'zhaquirks.xiaomi.aqara.magnet_aq2.MagnetAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.sensor_magnet.aq2 (00:15:8d:00:01:b9:7d:50)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.magnet_aq2.MagnetAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:01:b9:7d:50: <class 'zhaquirks.xiaomi.aqara.magnet_aq2.MagnetAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.weather (00:15:8d:00:02:2c:75:19)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.weather.Weather2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:02:2c:75:19: <class 'zhaquirks.xiaomi.aqara.weather.Weather2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for IKEA of Sweden TRADFRI remote control (d0:cf:5e:ff:fe:ff:c3:dd)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.ikea.fivebtnremotezha.IkeaTradfriRemote2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Fail because profile_id mismatch on at least one endpoint
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.ikea.fivebtnremotezha.IkeaTradfriRemote'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Fail because profile_id mismatch on at least one endpoint
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.ikea.fivebtnremote.IkeaTradfriRemote'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for d0:cf:5e:ff:fe:ff:c3:dd: <class 'zhaquirks.ikea.fivebtnremote.IkeaTradfriRemote'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.weather (00:15:8d:00:01:b9:7f:5b)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.weather.Weather2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:01:b9:7f:5b: <class 'zhaquirks.xiaomi.aqara.weather.Weather2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LUMI lumi.sensor_motion.aq2 (00:15:8d:00:06:ba:80:4a)
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.motion_aq2b.MotionAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.motion_aq2.MotionAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.quirks.registry] Found custom device replacement for 00:15:8d:00:06:ba:80:4a: <class 'zhaquirks.xiaomi.aqara.motion_aq2.MotionAQ2'>
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd0f4:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd0f4:1:0x0000] Attribute id: 5 value: lumi.sensor_magnet.aq2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd0f4:1:0x0000] Attribute id: 1 value: 3
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x320d:1:0x0000] Attribute id: 4 value: IKEA of Sweden
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x320d:1:0x0000] Attribute id: 5 value: TRADFRI control outlet
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd42d:1:0x0000] Attribute id: 4 value: IKEA of Sweden
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd42d:1:0x0000] Attribute id: 5 value: TRADFRI control outlet
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd8c2:1:0x0000] Attribute id: 4 value: IKEA of Sweden
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd8c2:1:0x0000] Attribute id: 5 value: TRADFRI control outlet
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xb8c9:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xb8c9:1:0x0000] Attribute id: 5 value: lumi.weather
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xb8c9:1:0x0000] Attribute id: 1 value: 3
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x7e70:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x7e70:1:0x0000] Attribute id: 5 value: lumi.sensor_magnet.aq2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x7e70:1:0x0000] Attribute id: 1 value: 3
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x8016:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xaf42:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xaf42:1:0x0000] Attribute id: 5 value: lumi.sensor_motion.aq2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xaf42:1:0x0000] Attribute id: 1 value: 5
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xaf42:1:0x0500] Attribute id: 1 value: 13
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2ff5:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2ff5:1:0x0500] Attribute id: 1 value: 13
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2ff5:1:0x0000] Attribute id: 5 value: lumi.sensor_motion.aq2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2ff5:1:0x0000] Attribute id: 1 value: 5
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2d81:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2d81:1:0x0000] Attribute id: 5 value: lumi.sensor_magnet.aq2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2d81:1:0x0000] Attribute id: 1 value: 3
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa88b:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x857c:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x857c:1:0x0000] Attribute id: 5 value: lumi.sensor_magnet.aq2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x857c:1:0x0000] Attribute id: 1 value: 3
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xfc09:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xfc09:1:0x0000] Attribute id: 5 value: lumi.sensor_magnet.aq2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xfc09:1:0x0000] Attribute id: 1 value: 3
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x1a37:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x1a37:1:0x0000] Attribute id: 5 value: lumi.weather
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x1a37:1:0x0000] Attribute id: 1 value: 3
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xf961:1:0x0000] Attribute id: 4 value: IKEA of Sweden
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xf961:1:0x0000] Attribute id: 5 value: TRADFRI remote control
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xfc09:1:0x0000] Attribute id: 65281 value: b'\x01!\xc7\x0b\x03(\x18\x04!\xa8C\x05!k\x01\x06$\x04\x00\x00\x01\x01\n!\x00\x00d\x10\x01'
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xfc09:1:0x0001] Attribute id: 32 value: 30
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xfc09:1:0x0001] Attribute id: 33 value: 139
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x544e:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xf961:1:0x0001] Attribute id: 32 value: 27
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xf961:1:0x0001] Attribute id: 33 value: 94
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x544e:1:0x0000] Attribute id: 5 value: lumi.weather
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x544e:1:0x0000] Attribute id: 1 value: 3
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa9b2:1:0x0000] Attribute id: 4 value: LUMI
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa9b2:1:0x0000] Attribute id: 5 value: lumi.sensor_motion.aq2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa9b2:1:0x0000] Attribute id: 1 value: 5
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa9b2:1:0x0500] Attribute id: 1 value: 13
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x857c:1:0x0000] Attribute id: 65281 value: b'\x01!\xbd\x0b\x03(\x16\x04!\xa8\x13\x05!\xd9\x01\x06$\x0f\x00\x0e\x00\x00\n!\x00\x00d\x10\x00'
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x857c:1:0x0001] Attribute id: 32 value: 30.1
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x857c:1:0x0001] Attribute id: 33 value: 132
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xaf42:1:0x0500] Attribute id: 0 value: 0
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xaf42:1:0x0406] Attribute id: 0 value: 0
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x8016:1:0x0000] Attribute id: 1 value: 3
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x8016:1:0x0000] Attribute id: 5 value: lumi.weather
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa88b:1:0x0000] Attribute id: 5 value: lumi.weather
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa88b:1:0x0000] Attribute id: 1 value: 3
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x7e70:1:0x0000] Attribute id: 65281 value: b'\x01!\xe5\x0b\x03(\x19\x04!\xa8\x13\x05!\x7f\x00\x06$\x10\x00\x0f\x00\x00\n!\x00\x00d\x10\x00'
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x7e70:1:0x0001] Attribute id: 32 value: 30.4
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x7e70:1:0x0001] Attribute id: 33 value: 161
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd0f4:1:0x0001] Attribute id: 32 value: 30.2
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd0f4:1:0x0001] Attribute id: 33 value: 146
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd0f4:1:0x0000] Attribute id: 65281 value: b'\x04!\xa8C\n!\xc2\xd8'
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa9b2:1:0x0000] Attribute id: 65281 value: b'\x01!\xbd\x0b\x03(\x1e\x04!\xa8C\x05!\x12\x00\x06$&\x00\x00\x00\x00\n!\r2d\x10\x00\x0b!\x1e\x03'
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa9b2:1:0x0001] Attribute id: 32 value: 30.1
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa9b2:1:0x0001] Attribute id: 33 value: 132
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x1a37:1:0x0000] Attribute id: 65281 value: b'\x01!\xa9\x0b\x04!\xa8C\x05!\x8a\x02\x06$\x01\x00\x00\x00\x00d)g\x08e!\xf6\x18f+\x03\x8f\x01\x00\n!\r2'
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x1a37:1:0x0001] Attribute id: 32 value: 29.9
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x1a37:1:0x0001] Attribute id: 33 value: 118
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x544e:1:0x0000] Attribute id: 65281 value: b'\x01!\xbd\x0b\x04!\xa8C\x05!\x03\x00\x06$\x01\x00\x00\x00\x00d)&\x08e!\xe7\x1bf+\x18\x8f\x01\x00\n!-\xd4'
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x544e:1:0x0001] Attribute id: 32 value: 30.1
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x544e:1:0x0001] Attribute id: 33 value: 132
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa88b:1:0x0000] Attribute id: 65281 value: b'\x01!\xc7\x0b\x04!\xa8C\x05!\x03\x00\x06$\x01\x00\x00\x00\x00d)\x81\x08e!G\x19f+\xef\x8e\x01\x00\n!-\xd4'
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa88b:1:0x0001] Attribute id: 32 value: 30.1
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa88b:1:0x0001] Attribute id: 33 value: 139
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xaf42:1:0x0000] Attribute id: 65281 value: b'\x01!\xf9\x0b\x03(\x15\x04!\xa8C\x05!U\x01\x06$\x01\x00\x00\x00\x00\n!\r2d\x10\x00\x0b!<\x00'
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xaf42:1:0x0001] Attribute id: 32 value: 30.6
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xaf42:1:0x0001] Attribute id: 33 value: 175
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xaf42:1:0x0400] Attribute id: 0 value: 17782.512503836435
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2ff5:1:0x0500] Attribute id: 0 value: 0
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x1a37:1:0x0402] Attribute id: 0 value: 2178
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x1a37:1:0x0403] Attribute id: 0 value: 1020
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x1a37:1:0x0405] Attribute id: 0 value: 6226
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x8016:1:0x0000] Attribute id: 65281 value: b'\x01!\xbd\x0b\x04!\xa8C\x05!\x04\x00\x06$\x01\x00\x00\x00\x00d)D\x08e!\xdd\x1af+\xc9\x8e\x01\x00\n!\r2'
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x8016:1:0x0001] Attribute id: 32 value: 30.1
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x8016:1:0x0001] Attribute id: 33 value: 132
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x8016:1:0x0402] Attribute id: 0 value: 2116
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x8016:1:0x0405] Attribute id: 0 value: 6877
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x8016:1:0x0403] Attribute id: 0 value: 1020.89
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x544e:1:0x0402] Attribute id: 0 value: 2089
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x544e:1:0x0403] Attribute id: 0 value: 1021
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x544e:1:0x0405] Attribute id: 0 value: 7145
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2ff5:1:0x0406] Attribute id: 0 value: 0
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2ff5:1:0x0000] Attribute id: 65281 value: b'\x01!\xbd\x0b\x03(\x1b\x04!\xa8C\x05!H\x01\x06$\x03\x00\x00\x00\x00\n!-\xd4d\x10\x00\x0b!\xad\x00'
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2ff5:1:0x0001] Attribute id: 32 value: 30.1
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2ff5:1:0x0001] Attribute id: 33 value: 132
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2ff5:1:0x0400] Attribute id: 0 value: 22381.461031287956
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa88b:1:0x0402] Attribute id: 0 value: 2177
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa88b:1:0x0405] Attribute id: 0 value: 6512
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa88b:1:0x0403] Attribute id: 0 value: 1020
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x320d:1:0x0006] Attribute id: 0 value: 0
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xb8c9:1:0x0000] Attribute id: 65281 value: b'\x01!\xef\x0b\x04!\xa8C\x05!\x13\x00\x06$\x01\x00\x00\x00\x00d)t\x08e!2\x1af+\xf8\x8e\x01\x00\n!-\xd4'
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xb8c9:1:0x0001] Attribute id: 32 value: 30.6
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xb8c9:1:0x0001] Attribute id: 33 value: 168
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xb8c9:1:0x0402] Attribute id: 0 value: 2164
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xb8c9:1:0x0405] Attribute id: 0 value: 6706
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xb8c9:1:0x0403] Attribute id: 0 value: 1021.36
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd8c2:1:0x0006] Attribute id: 0 value: 1
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xd42d:1:0x0006] Attribute id: 0 value: 1
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2d81:1:0x0000] Attribute id: 65281 value: b'\x01!\xdb\x0b\x03(\x1c\x04!\xa8C\x05!\x92\x00\x06$\x01\x00\x00\x00\x00\n!\r2d\x10\x00'
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2d81:1:0x0001] Attribute id: 32 value: 30.4
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0x2d81:1:0x0001] Attribute id: 33 value: 154
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa9b2:1:0x0400] Attribute id: 0 value: 18921.946026904803
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa9b2:1:0x0406] Attribute id: 0 value: 1
2021-09-02 13:17:20 DEBUG (MainThread) [zigpy.appdb] [0xa9b2:1:0x0500] Attribute id: 0 value: 0
2021-09-02 13:17:21 INFO (MainThread) [zigpy.ota.provider] Trådfri: OTA provider enabled
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy.ota.provider] Trådfri: Finished downloading firmware update list
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Connection made
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0002 b'01'
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x00\x02\x00\x01\x02\x01'
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x10\x02\x12\x02\x10\x02\x11\x02\x12\x02\x11'
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800000078500000002000000
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8000 b'000000020000' LQI:0
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 0, 2, b'\x00\x00']
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0016 b'28c38b62'
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x00\x16\x00\x04\x10(\xc3\x8bb'
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x10\x16\x02\x10\x02\x14\x10(\xc3\x8bb'
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0010 b''
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x00\x10\x00\x00\x10'
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x10\x10\x02\x10\x02\x10\x10'
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800000079100000016000000
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8000 b'000000160000' LQI:0
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 0, 22, b'\x00\x00']
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800000079700000010000000
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8000 b'000000100000' LQI:0
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 0, 16, b'\x00\x00']
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80100005880003031d00
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8010 b'0003031d' LQI:0
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [3, 797]
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0009 b''
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x00\t\x00\x00\t'
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x10\x02\x19\x02\x10\x02\x10\x02\x19'
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800000078e00000009000000
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8000 b'000000090000' LQI:0
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 0, 9, b'\x00\x00']
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80090016be000000158d0001edf8367996233ad01702bfbfa30b00
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8009 b'000000158d0001edf8367996233ad01702bfbfa30b' LQI:0
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0x0000, 00:15:8d:00:01:ed:f8:36, 31126, 2538570137225641891, 11]
2021-09-02 13:17:22 WARNING (MainThread) [zigpy.application] Device is partially initialized: <ZiGateDevice model='ZiGate USB-TTL 3.1d' manuf='ZiGate' nwk=0x0000 ieee=00:15:8d:00:01:ed:f8:36 is_initialized=False>
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0x320D, 260, 6, 1, 1, 1, b'\x00\x01\x00\x00\x00', True, False)
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'02320d0101000601040000050001000000'
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\x1e\x022\r\x01\x01\x00\x06\x01\x04\x00\x00\x05\x00\x01\x00\x00\x00'
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\x1e\x02\x122\x02\x1d\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10\x02\x11\x02\x10\x02\x10\x02\x10'
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0xD42D, 260, 6, 1, 1, 2, b'\x00\x02\x00\x00\x00', True, False)
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80000007ae001c0530000000
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8000 b'001c05300000' LQI:0
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 28, 1328, b'\x00\x00']
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 87020008cad4010102320d1cb2
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8702 b'd4010102320d1c' LQI:178
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [212, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x320d>, 28]
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.zigbee.application] APS Data confirm Fail 28 212
2021-09-02 13:17:22 WARNING (MainThread) [zigpy_zigate.zigbee.application] Unexpected message send failure
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'02d42d0101000601040000050002000000'
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\xdb\x02\xd4-\x01\x01\x00\x06\x01\x04\x00\x00\x05\x00\x02\x00\x00\x00'
2021-09-02 13:17:22 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\xdb\x02\x12\xd4-\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10\x02\x12\x02\x10\x02\x10\x02\x10'
2021-09-02 13:17:23 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80000007af001d0530000000
2021-09-02 13:17:23 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8000 b'001d05300000' LQI:0
2021-09-02 13:17:23 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 29, 1328, b'\x00\x00']
2021-09-02 13:17:23 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 87020008fbe1010102d42d1d71
2021-09-02 13:17:23 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8702 b'e1010102d42d1d' LQI:113
2021-09-02 13:17:23 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [225, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xd42d>, 29]
2021-09-02 13:17:23 DEBUG (MainThread) [zigpy_zigate.zigbee.application] APS Data confirm Fail 29 225
2021-09-02 13:17:27 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0xD8C2, 260, 6, 1, 1, 3, b'\x00\x03\x00\x00\x00', True, False)
2021-09-02 13:17:27 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'02d8c20101000601040000050003000000'
2021-09-02 13:17:27 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x119\x02\xd8\xc2\x01\x01\x00\x06\x01\x04\x00\x00\x05\x00\x03\x00\x00\x00'
2021-09-02 13:17:27 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x119\x02\x12\xd8\xc2\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10\x02\x13\x02\x10\x02\x10\x02\x10'
2021-09-02 13:17:28 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80000007ac001e0530000000
2021-09-02 13:17:28 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8000 b'001e05300000' LQI:0
2021-09-02 13:17:28 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 30, 1328, b'\x00\x00']
2021-09-02 13:17:28 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 87020008edd4010102d8c21eb2
2021-09-02 13:17:28 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8702 b'd4010102d8c21e' LQI:178
2021-09-02 13:17:28 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [212, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xd8c2>, 30]
2021-09-02 13:17:28 DEBUG (MainThread) [zigpy_zigate.zigbee.application] APS Data confirm Fail 30 212
2021-09-02 13:17:28 WARNING (MainThread) [zigpy_zigate.zigbee.application] Unexpected message send failure
2021-09-02 13:17:32 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 870100056cd000320d00
2021-09-02 13:17:32 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8701 b'd000320d' LQI:0
2021-09-02 13:17:32 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [208, 0]
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0x320D, 260, 6, 1, 1, 4, b'\x00\x04\x00\x00\x00', True, False)
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'02320d0101000601040000050004000000'
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\x1b\x022\r\x01\x01\x00\x06\x01\x04\x00\x00\x05\x00\x04\x00\x00\x00'
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\x1b\x02\x122\x02\x1d\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10\x02\x14\x02\x10\x02\x10\x02\x10'
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0xD42D, 260, 6, 1, 1, 5, b'\x00\x05\x00\x00\x00', True, False)
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0xD8C2, 260, 6, 1, 1, 6, b'\x00\x06\x00\x00\x00', True, False)
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80000007ad001f0530000000
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8000 b'001f05300000' LQI:0
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 31, 1328, b'\x00\x00']
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 870200087bd4010102320d1f00
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8702 b'd4010102320d1f' LQI:0
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [212, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0x320d>, 31]
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] APS Data confirm Fail 31 212
2021-09-02 13:17:33 WARNING (MainThread) [zigpy_zigate.zigbee.application] Unexpected message send failure
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'02d42d0101000601040000050005000000'
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\xdc\x02\xd4-\x01\x01\x00\x06\x01\x04\x00\x00\x05\x00\x05\x00\x00\x00'
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\xdc\x02\x12\xd4-\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10\x02\x15\x02\x10\x02\x10\x02\x10'
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800000079200200530000000
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8000 b'002005300000' LQI:0
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 32, 1328, b'\x00\x00']
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'02d8c20101000601040000050006000000'
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11<\x02\xd8\xc2\x01\x01\x00\x06\x01\x04\x00\x00\x05\x00\x06\x00\x00\x00'
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11<\x02\x12\xd8\xc2\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10\x02\x16\x02\x10\x02\x10\x02\x10'
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800000079300210530000000
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8000 b'002105300000' LQI:0
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 33, 1328, b'\x00\x00']
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8702000860d4010102d8c22100
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8702 b'd4010102d8c221' LQI:0
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [212, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xd8c2>, 33]
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] APS Data confirm Fail 33 212
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800200163e0001040006010102d42d02000018050100000010015d
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040006010102d42d0200001805010000001001' LQI:93
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 6, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xd42d>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x05\x01\x00\x00\x00\x10\x01']
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy.zcl] [0xd42d:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=5 command_id=Command.Read_Attributes_rsp>
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800200163e0001040006010102d42d02000018050100000010015d
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040006010102d42d0200001805010000001001' LQI:93
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 6, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xd42d>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x05\x01\x00\x00\x00\x10\x01']
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy.zcl] [0xd42d:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=5 command_id=Command.Read_Attributes_rsp>
2021-09-02 13:17:33 DEBUG (MainThread) [zigpy.zcl] [0xd42d:1:0x0006] ZCL request 0x0001: [[ReadAttributeRecord(attrid=0, status=<Status.SUCCESS: 0>, value=<TypeValue type=Bool, value=Bool.true>)]]
2021-09-02 13:17:38 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8701000549d000d8c200
2021-09-02 13:17:38 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8701 b'd000d8c2' LQI:0
2021-09-02 13:17:38 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [208, 0]
2021-09-02 13:17:43 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 870100056cd000320d00
2021-09-02 13:17:43 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8701 b'd000320d' LQI:0
2021-09-02 13:17:43 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [208, 0]
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020016b90001040400010102a9b2020000181e0a0000214d0057
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040400010102a9b2020000181e0a0000214d00' LQI:87
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1024, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x1e\n\x00\x00!M\x00']
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=30 command_id=Command.Report_Attributes>
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=77>)]]
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] Attribute report received: measured_value=77
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020016b90001040400010102a9b2020000181e0a0000214d0057
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040400010102a9b2020000181e0a0000214d00' LQI:87
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1024, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x1e\n\x00\x00!M\x00']
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=30 command_id=Command.Report_Attributes>
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=77>)]]
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] Attribute report received: measured_value=77
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020015c50001040406010102a9b2020000181f0a000018015a
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040406010102a9b2020000181f0a00001801' LQI:90
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1030, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x1f\n\x00\x00\x18\x01']
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=31 command_id=Command.Report_Attributes>
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=bitmap8, value=bitmap8.1>)]]
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] Attribute report received: occupancy=1
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020015c50001040406010102a9b2020000181f0a000018015a
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040406010102a9b2020000181f0a00001801' LQI:90
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1030, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18\x1f\n\x00\x00\x18\x01']
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=31 command_id=Command.Report_Attributes>
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=bitmap8, value=bitmap8.1>)]]
2021-09-02 13:18:24 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] Attribute report received: occupancy=1
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020016b60001040400010102a9b202000018200a0000214b0060
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040400010102a9b202000018200a0000214b00' LQI:96
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1024, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18 \n\x00\x00!K\x00']
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=32 command_id=Command.Report_Attributes>
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=75>)]]
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] Attribute report received: measured_value=75
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020016b60001040400010102a9b202000018200a0000214b0060
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040400010102a9b202000018200a0000214b00' LQI:96
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1024, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18 \n\x00\x00!K\x00']
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=32 command_id=Command.Report_Attributes>
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=75>)]]
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] Attribute report received: measured_value=75
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020015fc0001040406010102a9b202000018210a000018015d
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040406010102a9b202000018210a00001801' LQI:93
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1030, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18!\n\x00\x00\x18\x01']
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=33 command_id=Command.Report_Attributes>
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=bitmap8, value=bitmap8.1>)]]
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] Attribute report received: occupancy=1
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020015fc0001040406010102a9b202000018210a000018015d
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040406010102a9b202000018210a00001801' LQI:93
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1030, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18!\n\x00\x00\x18\x01']
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=33 command_id=Command.Report_Attributes>
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=bitmap8, value=bitmap8.1>)]]
2021-09-02 13:19:30 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] Attribute report received: occupancy=1
2021-09-02 13:19:46 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0xD8C2, 260, 6, 1, 1, 7, b'\x01\x07\x00', True, False)
2021-09-02 13:19:46 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'02d8c2010100060104000003010700'
2021-09-02 13:19:46 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x0f$\x02\xd8\xc2\x01\x01\x00\x06\x01\x04\x00\x00\x03\x01\x07\x00'
2021-09-02 13:19:46 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x02\x1f$\x02\x12\xd8\xc2\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x13\x02\x11\x02\x17\x02\x10'
2021-09-02 13:19:46 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800000079000220530000000
2021-09-02 13:19:46 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8000 b'002205300000' LQI:0
2021-09-02 13:19:46 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 34, 1328, b'\x00\x00']
2021-09-02 13:19:46 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 87020008d1d4010102d8c222b2
2021-09-02 13:19:46 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8702 b'd4010102d8c222' LQI:178
2021-09-02 13:19:46 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [212, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xd8c2>, 34]
2021-09-02 13:19:46 DEBUG (MainThread) [zigpy_zigate.zigbee.application] APS Data confirm Fail 34 212
2021-09-02 13:19:56 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8701000549d000d8c200
2021-09-02 13:19:56 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8701 b'd000d8c2' LQI:0
2021-09-02 13:19:56 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [208, 0]
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020015ec0001040006010102d8c2020000082c0a000010015d
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040006010102d8c2020000082c0a00001001' LQI:93
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 6, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xd8c2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x08,\n\x00\x00\x10\x01']
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy.zcl] [0xd8c2:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=44 command_id=Command.Report_Attributes>
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy.zcl] [0xd8c2:1:0x0006] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=Bool, value=Bool.true>)]]
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy.zcl] [0xd8c2:1:0x0006] Attribute report received: on_off=1
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0xD8C2, 260, 6, 1, 1, 44, b'\x18,\x0b\n\x00', False, False)
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'02d8c2010100060104000005182c0b0a00'
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x11\x0f\x02\xd8\xc2\x01\x01\x00\x06\x01\x04\x00\x00\x05\x18,\x0b\n\x00'
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x11\x02\x1f\x02\x12\xd8\xc2\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x18,\x02\x1b\x02\x1a\x02\x10'
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020015ec0001040006010102d8c2020000082c0a000010015d
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040006010102d8c2020000082c0a00001001' LQI:93
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 6, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xd8c2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x08,\n\x00\x00\x10\x01']
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy.zcl] [0xd8c2:1:0x0006] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=44 command_id=Command.Report_Attributes>
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy.zcl] [0xd8c2:1:0x0006] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=Bool, value=Bool.true>)]]
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy.zcl] [0xd8c2:1:0x0006] Attribute report received: on_off=1
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy.util] Duplicate 44 TSN
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800000079600240530000000
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8000 b'002405300000' LQI:0
2021-09-02 13:20:33 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 36, 1328, b'\x00\x00']
2021-09-02 13:20:34 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8702000857e1010102d8c22300
2021-09-02 13:20:34 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8702 b'e1010102d8c223' LQI:0
2021-09-02 13:20:34 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [225, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xd8c2>, 35]
2021-09-02 13:20:34 DEBUG (MainThread) [zigpy_zigate.zigbee.application] APS Data confirm Fail 35 225
2021-09-02 13:20:34 WARNING (MainThread) [zigpy_zigate.zigbee.application] Unexpected message send failure
2021-09-02 13:20:34 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8702000850e1010102d8c22400
2021-09-02 13:20:34 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8702 b'e1010102d8c224' LQI:0
2021-09-02 13:20:34 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [225, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xd8c2>, 36]
2021-09-02 13:20:34 DEBUG (MainThread) [zigpy_zigate.zigbee.application] APS Data confirm Fail 36 225
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020016890001040400010102a9b202000018220a0000214b005d
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040400010102a9b202000018220a0000214b00' LQI:93
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1024, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18"\n\x00\x00!K\x00']
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=34 command_id=Command.Report_Attributes>
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=75>)]]
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] Attribute report received: measured_value=75
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020016890001040400010102a9b202000018220a0000214b005d
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040400010102a9b202000018220a0000214b00' LQI:93
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1024, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18"\n\x00\x00!K\x00']
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=34 command_id=Command.Report_Attributes>
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=75>)]]
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] Attribute report received: measured_value=75
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020015fe0001040406010102a9b202000018230a000018015d
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040406010102a9b202000018230a00001801' LQI:93
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1030, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18#\n\x00\x00\x18\x01']
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=35 command_id=Command.Report_Attributes>
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=bitmap8, value=bitmap8.1>)]]
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] Attribute report received: occupancy=1
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020015fe0001040406010102a9b202000018230a000018015d
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040406010102a9b202000018230a00001801' LQI:93
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1030, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18#\n\x00\x00\x18\x01']
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=35 command_id=Command.Report_Attributes>
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=bitmap8, value=bitmap8.1>)]]
2021-09-02 13:20:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] Attribute report received: occupancy=1
2021-09-02 13:20:39 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0xD8C2, 260, 6, 1, 1, 8, b'\x01\x08\x00', True, False)
2021-09-02 13:20:39 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'02d8c2010100060104000003010800'
2021-09-02 13:20:39 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x0f+\x02\xd8\xc2\x01\x01\x00\x06\x01\x04\x00\x00\x03\x01\x08\x00'
2021-09-02 13:20:39 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x02\x1f+\x02\x12\xd8\xc2\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x13\x02\x11\x02\x18\x02\x10'
2021-09-02 13:20:39 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800000079700250530000000
2021-09-02 13:20:39 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8000 b'002505300000' LQI:0
2021-09-02 13:20:39 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 37, 1328, b'\x00\x00']
2021-09-02 13:20:39 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8011000800a7d8c20100062300
2021-09-02 13:20:39 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8011 b'a7d8c201000623' LQI:0
2021-09-02 13:20:39 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [167, 0xd8c2, 1, 6, 35]
2021-09-02 13:20:39 DEBUG (MainThread) [zigpy_zigate.zigbee.application] ACK Data received 35 167
2021-09-02 13:20:39 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 8702000851e1010102d8c22500
2021-09-02 13:20:39 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8702 b'e1010102d8c225' LQI:0
2021-09-02 13:20:39 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [225, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xd8c2>, 37]
2021-09-02 13:20:39 DEBUG (MainThread) [zigpy_zigate.zigbee.application] APS Data confirm Fail 37 225
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020016bf0001040400010102a9b202000018240a000021460060
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040400010102a9b202000018240a0000214600' LQI:96
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1024, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18$\n\x00\x00!F\x00']
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=36 command_id=Command.Report_Attributes>
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=70>)]]
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] Attribute report received: measured_value=70
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020016bf0001040400010102a9b202000018240a000021460060
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040400010102a9b202000018240a0000214600' LQI:96
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1024, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18$\n\x00\x00!F\x00']
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=36 command_id=Command.Report_Attributes>
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=70>)]]
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] Attribute report received: measured_value=70
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020015c30001040406010102a9b202000018250a0000180166
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040406010102a9b202000018250a00001801' LQI:102
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1030, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18%\n\x00\x00\x18\x01']
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=37 command_id=Command.Report_Attributes>
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=bitmap8, value=bitmap8.1>)]]
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] Attribute report received: occupancy=1
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020015c30001040406010102a9b202000018250a0000180166
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040406010102a9b202000018250a00001801' LQI:102
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1030, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18%\n\x00\x00\x18\x01']
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=37 command_id=Command.Report_Attributes>
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=bitmap8, value=bitmap8.1>)]]
2021-09-02 13:21:37 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] Attribute report received: occupancy=1
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.zigbee.application] request (0xD8C2, 260, 6, 1, 1, 9, b'\x01\t\x00', True, False)
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.uart] Send: 0x0530 b'02d8c2010100060104000003010900'
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.uart] Frame to send: b'\x050\x00\x0f*\x02\xd8\xc2\x01\x01\x00\x06\x01\x04\x00\x00\x03\x01\t\x00'
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.uart] Frame escaped: b'\x02\x150\x02\x10\x02\x1f*\x02\x12\xd8\xc2\x02\x11\x02\x11\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x13\x02\x11\x02\x19\x02\x10'
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800000079400260530000000
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8000 b'002605300000' LQI:0
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 38, 1328, b'\x00\x00']
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 87020008d5d4010102d8c226b2
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8702 b'd4010102d8c226' LQI:178
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [212, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xd8c2>, 38]
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.zigbee.application] APS Data confirm Fail 38 212
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 87010005990000d8c200
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8701 b'0000d8c2' LQI:0
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 0]
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020015fa0001040406010102a9b202000018270a000018015d
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040406010102a9b202000018270a00001801' LQI:93
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1030, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b"\x18'\n\x00\x00\x18\x01"]
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=39 command_id=Command.Report_Attributes>
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=bitmap8, value=bitmap8.1>)]]
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] Attribute report received: occupancy=1
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 80020015fa0001040406010102a9b202000018270a000018015d
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040406010102a9b202000018270a00001801' LQI:93
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1030, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b"\x18'\n\x00\x00\x18\x01"]
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=39 command_id=Command.Report_Attributes>
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=bitmap8, value=bitmap8.1>)]]
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0406] Attribute report received: occupancy=1
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800200168b0001040400010102a9b202000018260a0000214d005d
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040400010102a9b202000018260a0000214d00' LQI:93
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1024, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18&\n\x00\x00!M\x00']
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=38 command_id=Command.Report_Attributes>
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=77>)]]
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] Attribute report received: measured_value=77
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.uart] Frame received: 800200168b0001040400010102a9b202000018260a0000214d005d
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.api] data received 0x8002 b'0001040400010102a9b202000018260a0000214d00' LQI:93
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy_zigate.zigbee.application] zigate_callback_handler [0, 260, 1024, 1, 1, <Address address_mode=ADDRESS_MODE.NWK address=0xa9b2>, <Address address_mode=ADDRESS_MODE.NWK address=0x0000>, b'\x18&\n\x00\x00!M\x00']
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=38 command_id=Command.Report_Attributes>
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] ZCL request 0x000a: [[Attribute(attrid=0, value=<TypeValue type=uint16_t, value=77>)]]
2021-09-02 13:22:40 DEBUG (MainThread) [zigpy.zcl] [0xa9b2:1:0x0400] Attribute report received: measured_value=77
``

EDIT : after upgraded again to 2021.9.0, it seems working partially better ( motion and sensor OK, plug : KO ) 
I see interesting information :

2021-09-02 13:34:44 DEBUG (MainThread) [zigpy.appdb] Current database version is v4 2021-09-02 13:34:44 INFO (MainThread) [zigpy.appdb] Migrating database from v4 to v5 2021-09-02 13:34:56 INFO (MainThread) [zigpy.appdb] Migrating database from v5 to v6



Is possible that migration failed ?
Adminiuga commented 3 years ago

Unlikely. But going from 2021.8 to earlier versions may need rejoining some devices, especially if the address has changed while being on the newer version.

Your log looks fine, but sometimes Ikea stops forwarding packets. Power cycle all Ikea's and press button on each aqara sensor and confirm they are online. If they are offline, they won't rejoin automatically because aqara

atmezferix commented 3 years ago

I have sensors dropping off a lot lately and took another look at them tonight.

I'm using a lot of my Samsung Smartthings devices and popping the battery out of them brings the motion sensors back online. I've replaced a lot of batteries in them thinking it might be because of that. But i've discovered tonight that the Centralite 3321-S Door/Windows sensor the batteries were down to 2V and I'd only recently replaced them.

I've replaced them again tonight and remove the battery strategy by ballob which I couldn't get working just in case that might be doing something.

Could something be polling them to often and causing them to go flat ?

I will monitor the battery life from now and report back.

DanPatten commented 3 years ago

Both of my aqara opple remotes (4 button and 6 button) stopped sending events, attempting to reconnect them caused them to work briefly and then stop working again. Having very strange behavior overall, going to try and downgrade.

carlosCastro99 commented 3 years ago

Any news, this is driving me crazy...

Adminiuga commented 3 years ago

Upgrade to hassos 6.4

sdbcs commented 3 years ago

Us upgrading to 6.4 just a wild guess or did anything relevant change?

MattWestb commented 3 years ago

Some kernel updates that have making problems with some USB devices https://github.com/home-assistant/operating-system/releases.

max5962 commented 3 years ago

I tried. No improvement ☹️ I think I will have to rebuild all ZigBee network 😕

chrismdann commented 3 years ago

Zha is nothing but problems. I can't get 90% of window sensors to report correctly. Sensors show temp updated but not open close updates. After adding again, drops within 24 hours.

Adminiuga commented 3 years ago

Don't use xiaomi? Because xiaomi does drop off the network with incompatible routers.

MattWestb commented 3 years ago

@chrismdann I have some Aqara door / window sensor and they is working great the forcing them using good routers (IKEA Zigbee 3 lights and outlets) and is not leaving the network then the coordinator is off line and is reporting very fast to HA.

Recommending: Force them using good routers (= no old OSRAM plugs and so on) and not the coordinator (blocking it having direct children if its possible) and your problems is very likely being solved.

DanPatten commented 3 years ago

I am dropping ZHA and moving everything over to zigbee2mqtt.

samueltardieu commented 2 years ago

I am dropping ZHA and moving everything over to zigbee2mqtt.

Did it help with this issue?

github-actions[bot] commented 2 years ago

There hasn't been any activity on this issue recently. Due to the high number of incoming GitHub notifications, we have to clean some of the old issues, as many of them have already been resolved with the latest updates. Please make sure to update to the latest Home Assistant version and check if that solves the issue. Let us know if that works for you by adding a comment 👍 This issue has now been marked as stale and will be closed if no further activity occurs. Thank you for your contributions.