Hi.
I have configured the ZHA component using Conbee I stick(latest FW) in a docker-based HA setup. My devices currently consist of 11 IKEA lights (GU10 + bulbs) and a couple of motion and door sensors from Xiaomi.
Everything is running as expected after I’ve just started HA. However, after a few hours, things gradually start to fall apart. Initially, devices start to respond slowly. For example, if I trigger the rule that turns on 3 GU10 spots in my home office, the first one lights up immediately, the second one after 10s, and the third one after another 10s. This is what the logs look like then:
2019-06-19 14:14:41 INFO (MainThread) [homeassistant.components.automation] Executing Kantoor Licht Aan
2019-06-19 14:14:41 INFO (MainThread) [homeassistant.helpers.script] Script Kantoor Licht Aan: Running script
2019-06-19 14:14:41 INFO (MainThread) [homeassistant.helpers.script] Script Kantoor Licht Aan: Executing step call service
2019-06-19 14:14:41 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Zigbee request with id 174, data: b'01ae01'
2019-06-19 14:14:41 DEBUG (MainThread) [zigpy_deconz.api] Command aps_data_request (18, 174, 0, <DeconzAddress address_mode=2 address=2562>, 1, 260, 6, 1, b'\x01\xae\x01', 0, 0)
2019-06-19 14:14:41 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12ce0019001200ae0002020a010401060001030001ae010000
2019-06-19 14:14:41 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12ce00090002002aae
2019-06-19 14:14:41 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, 42, 174]
2019-06-19 14:14:41 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ecf000700ae20
2019-06-19 14:14:41 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [174, 32]
2019-06-19 14:14:41 DEBUG (MainThread) [zigpy_deconz.api] Command aps_data_confirm (0,)
2019-06-19 14:14:41 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04cf0007000000
2019-06-19 14:14:41 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04cf0013000c002aae02020a01010000000000
2019-06-19 14:14:41 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 174: 00
2019-06-19 14:14:51 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Zigbee request with id 175, data: b'01af01'
2019-06-19 14:14:51 DEBUG (MainThread) [zigpy_deconz.api] Command aps_data_request (18, 175, 0, <DeconzAddress address_mode=2 address=16181>, 1, 260, 6, 1, b'\x01\xaf\x01', 0, 0)
2019-06-19 14:14:51 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12d00019001200af0002353f010401060001030001af010000
2019-06-19 14:14:51 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12d000090002002aaf
2019-06-19 14:14:51 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, 42, 175]
2019-06-19 14:14:51 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ed1000700ae20
2019-06-19 14:14:51 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [174, 32]
2019-06-19 14:14:51 DEBUG (MainThread) [zigpy_deconz.api] Command aps_data_confirm (0,)
2019-06-19 14:14:51 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04d10007000000
2019-06-19 14:14:51 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04d10013000c002aaf02353f01010000000000
2019-06-19 14:14:51 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 175: 00
2019-06-19 14:15:01 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Zigbee request with id 176, data: b'01b001'
2019-06-19 14:15:01 DEBUG (MainThread) [zigpy_deconz.api] Command aps_data_request (18, 176, 0, <DeconzAddress address_mode=2 address=26851>, 1, 260, 6, 1, b'\x01\xb0\x01', 0, 0)
2019-06-19 14:15:01 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x12d20019001200b00002e368010401060001030001b0010000
2019-06-19 14:15:01 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x12d200090002002ab0
2019-06-19 14:15:01 DEBUG (MainThread) [zigpy_deconz.api] APS data request response: [2, 42, 176]
2019-06-19 14:15:01 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0ed3000700ae20
2019-06-19 14:15:01 DEBUG (MainThread) [zigpy_deconz.api] Device state changed response: [174, 32]
2019-06-19 14:15:01 DEBUG (MainThread) [zigpy_deconz.api] Command aps_data_confirm (0,)
2019-06-19 14:15:01 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x04d30007000000
2019-06-19 14:15:01 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x04d30013000c002ab002e36801010000000000
2019-06-19 14:15:01 DEBUG (MainThread) [zigpy_deconz.api] APS data confirm response for request with id 176: 00
From there, it goes gradually worse up to the point where all devices are marked as unavailable and no longer respond to any command:
2019-06-20 10:50:40 INFO (MainThread) [homeassistant.components.automation] Executing Kantoor Licht Uit
2019-06-20 10:50:40 INFO (MainThread) [homeassistant.helpers.script] Script Kantoor Licht Uit: Running script
2019-06-20 10:50:40 INFO (MainThread) [homeassistant.helpers.script] Script Kantoor Licht Uit: Executing step call service
2019-06-20 10:54:05 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c34000c0005000280f5ffd2
2019-06-20 10:54:13 DEBUG (MainThread) [zigpy_deconz.api] Command write_parameter (5, 38, b'\x10\x0e\x00\x00')
2019-06-20 10:54:13 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x0b31000c00050026100e0000
2019-06-20 10:54:13 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0b31000800010026
2019-06-20 10:54:13 DEBUG (MainThread) [zigpy_deconz.api] Write parameter watchdog_ttl: SUCCESS
2019-06-20 10:59:04 INFO (Thread-3) [pyhap.hap_server] Got connection with ('192.168.1.66', 51075).
2019-06-20 10:59:04 INFO (Thread-27) [pyhap.hap_server] 192.168.1.66 - "POST /pair-verify HTTP/1.1" 200 -
2019-06-20 10:59:04 INFO (Thread-27) [pyhap.hap_server] 192.168.1.66 - "POST /pair-verify HTTP/1.1" 200 -
2019-06-20 10:59:04 INFO (Thread-27) [pyhap.hap_server] 192.168.1.66 - "GET /accessories HTTP/1.1" 200 -
2019-06-20 10:59:15 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.2897720432] Connection closed by client
The radio communication still seems to be somewhat in tact, as if I open the network, and try to pair a door sensor, I can see packets coming in:
2019-06-20 11:02:00 INFO (MainThread) [homeassistant.components.zha.api] Permitting joins for 60s
2019-06-20 11:02:00 DEBUG (MainThread) [zigpy_deconz.zigbee.application] Broadcast not implemented.
2019-06-20 11:02:00 DEBUG (MainThread) [zigpy_deconz.api] Command write_parameter (2, 33, b'<')
2019-06-20 11:02:00 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x0b320009000200213c
2019-06-20 11:02:00 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0b32000800010021
2019-06-20 11:02:00 DEBUG (MainThread) [zigpy_deconz.api] Write parameter permit_join: SUCCESS
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1f33000e00070038e54f270b0001
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.api] Unknown command received: 31
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1f34000e0007009dec4f270b0001
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.api] Unknown command received: 31
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1f35000e000700fe034f270b0001
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.api] Unknown command received: 31
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1f36000e000700353f4f270b0001
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.api] Unknown command received: 31
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1f37000e000700e3684f270b0001
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.api] Unknown command received: 31
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1f380015000e0091734f270b0001020a4f270b0001
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.api] Unknown command received: 31
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1f39000e000700b1764f270b0001
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.api] Unknown command received: 31
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1f3a000e000700515d4f270b0001
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.api] Unknown command received: 31
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1f3b000e000700cfa24f270b0001
2019-06-20 11:02:21 DEBUG (MainThread) [zigpy_deconz.api] Unknown command received: 31
2019-06-20 11:02:22 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1f3c000e000700b1764f270b0001
2019-06-20 11:02:22 DEBUG (MainThread) [zigpy_deconz.api] Unknown command received: 31
2019-06-20 11:02:22 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1f3d000e000700cfa24f270b0001
2019-06-20 11:02:22 DEBUG (MainThread) [zigpy_deconz.api] Unknown command received: 31
2019-06-20 11:02:22 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1f3e000e00070038e54f270b0001
2019-06-20 11:02:22 DEBUG (MainThread) [zigpy_deconz.api] Unknown command received: 31
2019-06-20 11:02:22 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1f3f000e000700353f4f270b0001
2019-06-20 11:02:22 DEBUG (MainThread) [zigpy_deconz.api] Unknown command received: 31
2019-06-20 11:02:22 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1f40000e000700e3684f270b0001
2019-06-20 11:02:22 DEBUG (MainThread) [zigpy_deconz.api] Unknown command received: 31
2019-06-20 11:02:22 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1f410015000e0091734f270b0001020a4f270b0001
2019-06-20 11:02:22 DEBUG (MainThread) [zigpy_deconz.api] Unknown command received: 31
2019-06-20 11:02:22 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1f42000e000700515d4f270b0001
2019-06-20 11:02:22 DEBUG (MainThread) [zigpy_deconz.api] Unknown command received: 31
2019-06-20 11:02:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c430012000b00038f985202008d1500bfbd
2019-06-20 11:02:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c44000c000500021dcabfbd
2019-06-20 11:02:24 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c45000c000500021dcabfbd
2019-06-20 11:02:29 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c46000c000500021dcabfbd
2019-06-20 11:02:32 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c47000c000500021dcac7be
2019-06-20 11:02:35 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c48000c000500021dcac7be
2019-06-20 11:02:38 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c49000c000500021dcabfbd
2019-06-20 11:02:41 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c4a000c000500021dcabfbd
2019-06-20 11:02:44 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c4b000c000500021dcabfbd
2019-06-20 11:02:47 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c4c000c000500021dcabfbd
2019-06-20 11:02:50 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c4d000c000500021dcabfbd
2019-06-20 11:02:53 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c4e000c000500021dcabfbd
2019-06-20 11:02:57 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c4f000c000500021dcabfbd
2019-06-20 11:03:00 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c50000c000500021dcaafbb
2019-06-20 11:03:02 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c51000c000500021dcabfbd
2019-06-20 11:03:05 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c52000c000500021dcabfbd
2019-06-20 11:03:07 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c53000c000500021dcabfbd
2019-06-20 11:03:11 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c54000c000500021dcabfbd
2019-06-20 11:03:14 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c55000c000500021dcabfbd
2019-06-20 11:03:17 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c56000c000500021dcab7bc
2019-06-20 11:03:20 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c57000c000500021dcab7bc
2019-06-20 11:03:23 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c58000c000500021dcaafbb
2019-06-20 11:03:26 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c59000c000500021dcaafbb
2019-06-20 11:03:29 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c5a000c000500021dcaafbb
2019-06-20 11:03:32 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c5b000c000500021dcaa7ba
2019-06-20 11:06:46 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x1c5c000c000500027281ffcd
2019-06-20 11:14:13 DEBUG (MainThread) [zigpy_deconz.api] Command write_parameter (5, 38, b'\x10\x0e\x00\x00')
2019-06-20 11:14:13 DEBUG (MainThread) [zigpy_deconz.uart] Send: 0x0b33000c00050026100e0000
2019-06-20 11:14:13 DEBUG (MainThread) [zigpy_deconz.uart] Frame received: 0x0b33000800010026
2019-06-20 11:14:13 DEBUG (MainThread) [zigpy_deconz.api] Write parameter watchdog_ttl: SUCCESS
Immediately after a restart of HA, things behave normally again. The time for things to go bad seem to be a few hours.
Any suggestions on what to do here, as I’m really stumped on how to fix this.