Issue with ZHA constantly initializing and failing

jerremyscott · December 23, 2023, 11:20pm

I’m new to HA, so I apologize in advance if I do not give all information upfront. I’m migrated all of my ST zigbee equipment off of my ST hub and directly into HA and onto the SkyConnect last weekend. A few times over the week, the ZHA integration was stuck initializing… I didn’t have time to troubleshoot, so I just rebooted HA. This resolved the issue a few times, but now it simply will not come back up. I’ve flashed the SkyConnect to the latest firmware but still, no joy…

Using the debugging option in ZHA, i get the following:

2023-12-23 09:30:16.628 ERROR (MainThread) [bellows.ezsp] NCP entered failed state. Requesting APP controller restart
2023-12-23 09:30:16.855 ERROR (MainThread) [zigpy.zcl] [0xB92E:1:0x0b04] Traceback (most recent call last):
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/init.py”, line 411, in reply
return await self._endpoint.reply(
^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/endpoint.py”, line 278, in reply
return await self.device.reply(
^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 474, in reply
return await self.request(
^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 299, in request
await self._application.request(
File “/usr/local/lib/python3.11/site-packages/zigpy/application.py”, line 829, in request
await self.send_packet(
File “/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py”, line 762, in send_packet
status, _ = await self._ezsp.sendUnicast(
^^^^^^^^^^^^^^^^^^^^^^
AttributeError: ‘NoneType’ object has no attribute ‘sendUnicast’

2023-12-23 09:30:16.856 ERROR (MainThread) [zigpy.zcl] [0xB92E:1:0x0b04] Traceback (most recent call last):
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/init.py”, line 411, in reply
return await self._endpoint.reply(
^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/endpoint.py”, line 278, in reply
return await self.device.reply(
^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 474, in reply
return await self.request(
^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 299, in request
await self._application.request(
File “/usr/local/lib/python3.11/site-packages/zigpy/application.py”, line 829, in request
await self.send_packet(
File “/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py”, line 762, in send_packet
status, _ = await self._ezsp.sendUnicast(
^^^^^^^^^^^^^^^^^^^^^^
AttributeError: ‘NoneType’ object has no attribute ‘sendUnicast’

2023-12-23 09:30:16.857 ERROR (MainThread) [zigpy.zcl] [0x0113:1:0x0b04] Traceback (most recent call last):
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/init.py”, line 411, in reply
return await self._endpoint.reply(
^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/endpoint.py”, line 278, in reply
return await self.device.reply(
^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 474, in reply
return await self.request(
^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 299, in request
await self._application.request(
File “/usr/local/lib/python3.11/site-packages/zigpy/application.py”, line 829, in request
await self.send_packet(
File “/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py”, line 762, in send_packet
status, _ = await self._ezsp.sendUnicast(
^^^^^^^^^^^^^^^^^^^^^^
AttributeError: ‘NoneType’ object has no attribute ‘sendUnicast’

2023-12-23 09:30:16.859 ERROR (MainThread) [zigpy.zcl] [0xE25B:1:0x0019] Traceback (most recent call last):
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/clusters/general.py”, line 2187, in _handle_cluster_request
await self._handle_query_next_image(
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/clusters/general.py”, line 2259, in _handle_query_next_image
await self.query_next_image_response(
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/init.py”, line 411, in reply
return await self._endpoint.reply(
^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/endpoint.py”, line 278, in reply
return await self.device.reply(
^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 474, in reply
return await self.request(
^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 299, in request
await self._application.request(
File “/usr/local/lib/python3.11/site-packages/zigpy/application.py”, line 829, in request
await self.send_packet(
File “/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py”, line 762, in send_packet
status, _ = await self._ezsp.sendUnicast(
^^^^^^^^^^^^^^^^^^^^^^
AttributeError: ‘NoneType’ object has no attribute ‘sendUnicast’

2023-12-23 09:30:16.860 ERROR (MainThread) [zigpy.zcl] [0xB92E:1:0x0b04] Traceback (most recent call last):
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/init.py”, line 411, in reply
return await self._endpoint.reply(
^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/endpoint.py”, line 278, in reply
return await self.device.reply(
^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 474, in reply
return await self.request(
^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 299, in request
await self._application.request(
File “/usr/local/lib/python3.11/site-packages/zigpy/application.py”, line 829, in request
await self.send_packet(
File “/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py”, line 762, in send_packet
status, _ = await self._ezsp.sendUnicast(
^^^^^^^^^^^^^^^^^^^^^^
AttributeError: ‘NoneType’ object has no attribute ‘sendUnicast’

2023-12-23 09:30:16.861 ERROR (MainThread) [zigpy.zcl] [0x9465:1:0x0b04] Traceback (most recent call last):
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/init.py”, line 411, in reply
return await self._endpoint.reply(
^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/endpoint.py”, line 278, in reply
return await self.device.reply(
^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 474, in reply
return await self.request(
^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 299, in request
await self._application.request(
File “/usr/local/lib/python3.11/site-packages/zigpy/application.py”, line 829, in request
await self.send_packet(
File “/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py”, line 762, in send_packet
status, _ = await self._ezsp.sendUnicast(
^^^^^^^^^^^^^^^^^^^^^^
AttributeError: ‘NoneType’ object has no attribute ‘sendUnicast’

2023-12-23 09:30:16.866 ERROR (MainThread) [zigpy.zcl] [0x9465:1:0x0b04] Traceback (most recent call last):
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/init.py”, line 411, in reply
return await self._endpoint.reply(
^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/endpoint.py”, line 278, in reply
return await self.device.reply(
^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 474, in reply
return await self.request(
^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 299, in request
await self._application.request(
File “/usr/local/lib/python3.11/site-packages/zigpy/application.py”, line 829, in request
await self.send_packet(
File “/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py”, line 762, in send_packet
status, _ = await self._ezsp.sendUnicast(
^^^^^^^^^^^^^^^^^^^^^^
AttributeError: ‘NoneType’ object has no attribute ‘sendUnicast’

2023-12-23 09:30:16.867 ERROR (MainThread) [zigpy.zcl] [0x9465:1:0x0b04] Traceback (most recent call last):
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/init.py”, line 411, in reply
return await self._endpoint.reply(
^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/endpoint.py”, line 278, in reply
return await self.device.reply(
^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 474, in reply
return await self.request(
^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 299, in request
await self._application.request(
File “/usr/local/lib/python3.11/site-packages/zigpy/application.py”, line 829, in request
await self.send_packet(
File “/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py”, line 762, in send_packet
status, _ = await self._ezsp.sendUnicast(
^^^^^^^^^^^^^^^^^^^^^^
AttributeError: ‘NoneType’ object has no attribute ‘sendUnicast’

2023-12-23 09:30:16.868 WARNING (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xA03E:11:0x0006]: ‘async_initialize’ stage failed: ‘NoneType’ object has no attribute ‘sendUnicast’
Traceback (most recent call last):
File “/usr/src/homeassistant/homeassistant/components/zha/core/helpers.py”, line 338, in wrapper
return await func(cluster_handler, *args, **kwargs)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/src/homeassistant/homeassistant/components/zha/core/cluster_handlers/init.py”, line 389, in async_initialize
await self._get_attributes(
File “/usr/src/homeassistant/homeassistant/components/zha/core/cluster_handlers/init.py”, line 491, in _get_attributes
read, _ = await self.cluster.read_attributes(
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/init.py”, line 524, in read_attributes
result = await self.read_attributes_raw(to_read, manufacturer=manufacturer)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/init.py”, line 377, in request
return await self._endpoint.request(
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/endpoint.py”, line 253, in request
return await self.device.request(
^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 299, in request
await self._application.request(
File “/usr/local/lib/python3.11/site-packages/zigpy/application.py”, line 829, in request
await self.send_packet(
File “/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py”, line 762, in send_packet
status, _ = await self._ezsp.sendUnicast(
^^^^^^^^^^^^^^^^^^^^^^
AttributeError: ‘NoneType’ object has no attribute ‘sendUnicast’
2023-12-23 09:30:16.871 WARNING (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xA03E:11:0x0300]: ‘async_initialize’ stage failed: ‘NoneType’ object has no attribute ‘sendUnicast’
Traceback (most recent call last):
File “/usr/src/homeassistant/homeassistant/components/zha/core/helpers.py”, line 338, in wrapper
return await func(cluster_handler, *args, **kwargs)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/src/homeassistant/homeassistant/components/zha/core/cluster_handlers/init.py”, line 389, in async_initialize
await self._get_attributes(
File “/usr/src/homeassistant/homeassistant/components/zha/core/cluster_handlers/init.py”, line 491, in _get_attributes
read, _ = await self.cluster.read_attributes(
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/init.py”, line 524, in read_attributes
result = await self.read_attributes_raw(to_read, manufacturer=manufacturer)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/init.py”, line 377, in request
return await self._endpoint.request(
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/endpoint.py”, line 253, in request
return await self.device.request(
^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 299, in request
await self._application.request(
File “/usr/local/lib/python3.11/site-packages/zigpy/application.py”, line 829, in request
await self.send_packet(
File “/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py”, line 762, in send_packet
status, _ = await self._ezsp.sendUnicast(
^^^^^^^^^^^^^^^^^^^^^^
AttributeError: ‘NoneType’ object has no attribute ‘sendUnicast’
2023-12-23 09:30:16.873 WARNING (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xA03E:11:0x0008]: ‘async_initialize’ stage failed: ‘NoneType’ object has no attribute ‘sendUnicast’
Traceback (most recent call last):
File “/usr/src/homeassistant/homeassistant/components/zha/core/helpers.py”, line 338, in wrapper
return await func(cluster_handler, *args, **kwargs)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/src/homeassistant/homeassistant/components/zha/core/cluster_handlers/init.py”, line 389, in async_initialize
await self._get_attributes(
File “/usr/src/homeassistant/homeassistant/components/zha/core/cluster_handlers/init.py”, line 491, in _get_attributes
read, _ = await self.cluster.read_attributes(
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/init.py”, line 524, in read_attributes
result = await self.read_attributes_raw(to_read, manufacturer=manufacturer)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/init.py”, line 377, in request
return await self._endpoint.request(
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/endpoint.py”, line 253, in request
return await self.device.request(
^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 299, in request
await self._application.request(
File “/usr/local/lib/python3.11/site-packages/zigpy/application.py”, line 829, in request
await self.send_packet(
File “/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py”, line 762, in send_packet
status, _ = await self._ezsp.sendUnicast(
^^^^^^^^^^^^^^^^^^^^^^
AttributeError: ‘NoneType’ object has no attribute ‘sendUnicast’
2023-12-23 09:30:16.876 WARNING (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xE25B:1:0x0001]: ‘async_initialize’ stage failed: ‘NoneType’ object has no attribute ‘setExtendedTimeout’
Traceback (most recent call last):
File “/usr/src/homeassistant/homeassistant/components/zha/core/helpers.py”, line 338, in wrapper
return await func(cluster_handler, *args, **kwargs)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/src/homeassistant/homeassistant/components/zha/core/cluster_handlers/init.py”, line 400, in async_initialize
await ch_specific_init(from_cache=from_cache)
File “/usr/src/homeassistant/homeassistant/components/zha/core/cluster_handlers/init.py”, line 491, in _get_attributes
read, _ = await self.cluster.read_attributes(
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/init.py”, line 524, in read_attributes
result = await self.read_attributes_raw(to_read, manufacturer=manufacturer)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/zcl/init.py”, line 377, in request
return await self._endpoint.request(
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/endpoint.py”, line 253, in request
return await self.device.request(
^^^^^^^^^^^^^^^^^^^^^^^^^^
File “/usr/local/lib/python3.11/site-packages/zigpy/device.py”, line 299, in request
await self._application.request(
File “/usr/local/lib/python3.11/site-packages/zigpy/application.py”, line 829, in request
await self.send_packet(
File “/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py”, line 750, in send_packet
await self._ezsp.setExtendedTimeout(device.ieee, True)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
AttributeError: ‘NoneType’ object has no attribute ‘setExtendedTimeout’
2023-12-23 09:30:21.183 WARNING (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0x141A:11:0x0006]: async_initialize: all attempts have failed: [TimeoutError(), ControllerError(‘ApplicationController is not running’), ControllerError(‘ApplicationController is not running’), ControllerError(‘ApplicationController is not running’)]

kingozone · December 24, 2023, 7:00pm

I am having the same issue. Tried re-flashing firmware, switching protocols, etc. After adding to HA, it will work for short period of time, only to go in to “intalizing.”

dmircea · December 28, 2023, 6:47pm

This has been driving me crazy in the last 2 days.
Short story I have upgraded HA (docker based instance on Synology) from 2023.10.5 to 2023.12.3 (and subsequently to 2023.12.4).

My ZigBee coordinator is Nortek HUSBZB-1 (running firmware 6.7.8.0 build 373, which I’ve updated yesterday just in case there was something wrong with it but this stick has been working rock solid for more than 3 years).

I’m getting this same exact message in the logs:

2023-12-28 18:42:56.486 ERROR (MainThread) [bellows.ezsp] NCP entered failed state. Requesting APP controller restart

after that the integration remains stuck in “Initializing” and never recovers. If it restart HA it works again for awhile and then runs again into the same problem.
Screenshot 2023-12-28 194250

I think I’m going to downgrade and see if it becomes again stable.

Also I see that something similar is being discussed into a bug report on GitHub here:

#105506

jerremyscott · December 30, 2023, 11:49pm

I ultimately gave up on ZHA and went to Zigbee2MQTT. It’s been solid since. I’d love to know if anyone has a resolution to the issue though. ZHA is still my preference.

gbroll · January 5, 2024, 5:09pm

Same issue here. Guess I’ll do the switch to Zigbee2MQTT as well, have been considering it for a while.

dmircea · January 6, 2024, 10:19am

So the issue is related to the newer versions 2023.12.X and 2024.1.0.

I’ve been running on 2023.11.3 since a week ago and no issues.
Also, meanwhile, I have moved to another coordinator (Sonoff ZBDongle-P) because I thought it was better supported. However the problem does not seem to be related with the coordinator HW but something in the ZHA itself.

@jerremyscott & @gbroll how is Zigbee2MQTT working? Have you noticed any lag compared to ZHA?

fleskefjes · January 6, 2024, 10:26am

Why would it lag?

gbroll · January 6, 2024, 10:38am

Downgraded to 2023.11.3 by restoring an old backup instead, and ZHA seems to operate normally again.
But after restore I can no longer see the historic sensor data in the UI, so not very happy anyways.

dmircea · January 6, 2024, 2:52pm

Unfortunately, that happened to me as well. Which triggered me to change the coordinator and upgrade the whole network.

dmircea · January 6, 2024, 2:57pm

Not an expert on these but I thought that having MQTT (Mosquitto?) in the middle between the Zigbee stack and Home Assistant will add some extra processing. Maybe not so much to notice it.

fleskefjes · January 6, 2024, 3:54pm

It will not add any noticeable lag.

wildr · January 11, 2024, 10:01am

Im having the same issue :((( Anyone found a fix ?
SONOFF Zigbee 3.0 USB Dongle Plus V2

migrate to a new radio: error
re-configure the current radio: error creating network

2024-01-10 14:45:34.990 ERROR (MainThread) [bellows.ezsp] NCP entered failed state. Requesting APP controller restart
2024-01-10 14:46:19.772 ERROR (MainThread) [homeassistant] Error doing job: Exception in callback ThreadsafeProxy.__getattr__.<locals>.func_wrapper.<locals>.check_result_wrapper() at /usr/local/lib/python3.11/site-packages/bellows/thread.py:110
IndexError: index out of range
2024-01-10 14:46:20.442 ERROR (MainThread) [homeassistant.components.homeassistant_alerts] Timeout fetching homeassistant_alerts data
2024-01-10 14:46:50.690 ERROR (MainThread) [homeassistant] Error doing job: Exception in callback ThreadsafeProxy.__getattr__.<locals>.func_wrapper.<locals>.check_result_wrapper() at /usr/local/lib/python3.11/site-packages/bellows/thread.py:110
IndexError: index out of range
2024-01-10 14:47:48.119 ERROR (MainThread) [bellows.ezsp] NCP entered failed state. Requesting APP controller restart
2024-01-10 14:47:52.392 ERROR (bellows.thread_0) [bellows.uart] Lost serial connection: SerialException('device reports readiness to read but returned no data (device disconnected or multiple access on port?)')
2024-01-10 14:49:50.181 ERROR (MainThread) [bellows.ezsp] NCP entered failed state. Requesting APP controller restart
2024-01-10 14:49:52.628 ERROR (bellows.thread_0) [bellows.uart] Lost serial connection: SerialException('device reports readiness to read but returned no data (device disconnected or multiple access on port?)')
2024-01-10 14:51:49.991 ERROR (MainThread) [bellows.ezsp] NCP entered failed state. Requesting APP controller restart
2024-01-10 14:55:19.520 ERROR (MainThread) [bellows.ezsp] NCP entered failed state. Requesting APP controller restart
2024-01-10 14:56:53.990 ERROR (MainThread) [homeassistant] Error doing job: Exception in callback SerialTransport._read_ready()
IndexError: index out of range
2024-01-10 15:02:05.178 ERROR (bellows.thread_0) [bellows.uart] Lost serial connection: SerialException('device reports readiness to read but returned no data (device disconnected or multiple access on port?)')
2024-01-10 15:04:03.147 ERROR (MainThread) [homeassistant] Error doing job: Exception in callback ThreadsafeProxy.__getattr__.<locals>.func_wrapper.<locals>.check_result_wrapper() at /usr/local/lib/python3.11/site-packages/bellows/thread.py:110
IndexError: index out of range
2024-01-10 15:06:00.501 ERROR (MainThread) [bellows.ezsp] NCP entered failed state. Requesting APP controller restart
2024-01-10 15:08:01.628 ERROR (MainThread) [bellows.ezsp] NCP entered failed state. Requesting APP controller restart
2024-01-10 15:09:29.106 ERROR (MainThread) [bellows.ezsp] NCP entered failed state. Requesting APP controller restart
2024-01-10 15:14:33.234 ERROR (MainThread) [bellows.ezsp] NCP entered failed state. Requesting APP controller restart
2024-01-10 15:19:32.089 ERROR (MainThread) [bellows.ezsp] NCP entered failed state. Requesting APP controller restart
2024-01-10 15:21:42.024 ERROR (MainThread) [homeassistant] Error doing job: Exception in callback ThreadsafeProxy.__getattr__.<locals>.func_wrapper.<locals>.check_result_wrapper() at /usr/local/lib/python3.11/site-packages/bellows/thread.py:110
IndexError: index out of range
2024-01-10 15:22:54.798 ERROR (MainThread) [bellows.ezsp] NCP entered failed state. Requesting APP controller restart
2024-01-10 15:22:59.425 WARNING (MainThread) [bellows.zigbee.application] Watchdog heartbeat timeout: EzspError('EZSP is not running')
2024-01-10 15:23:09.427 WARNING (MainThread) [bellows.zigbee.application] Watchdog heartbeat timeout: EzspError('EZSP is not running')
2024-01-10 15:23:19.428 WARNING (MainThread) [bellows.zigbee.application] Watchdog heartbeat timeout: EzspError('EZSP is not running')
2024-01-10 15:23:29.431 WARNING (MainThread) [bellows.zigbee.application] Watchdog heartbeat timeout: EzspError('EZSP is not running')
2024-01-10 15:23:39.434 WARNING (MainThread) [bellows.zigbee.application] Watchdog heartbeat timeout: EzspError('EZSP is not running')
    raise EzspError("EZSP is not running")
bellows.exception.EzspError: EZSP is not running
2024-01-10 15:25:06.076 ERROR (Recorder) [homeassistant] Error doing job: Task exception was never retrieved
asyncio.exceptions.CancelledError
    raise TimeoutError from exc_val
TimeoutError
2024-01-11 10:21:29.227 ERROR (MainThread) [aiohttp.server] Error handling request
2024-01-11 10:21:45.678 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.ERROR_INVALID_ID: 55>, b'']
2024-01-11 10:21:54.228 ERROR (MainThread) [aiohttp.server] Error handling request
asyncio.exceptions.CancelledError
    raise TimeoutError from exc_val
TimeoutError
TimeoutError
asyncio.exceptions.CancelledError
    raise TimeoutError from exc_val
TimeoutError
asyncio.exceptions.CancelledError
    raise exceptions.TimeoutError() from exc
TimeoutError
    raise RuntimeError("Failed to probe running application type")
RuntimeError: Failed to probe running application type
2024-01-11 10:29:10.993 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.ERROR_INVALID_ID: 55>, b'']
2024-01-11 10:29:12.582 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.ERROR_INVALID_ID: 55>, b'']
2024-01-11 10:29:44.844 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.ERROR_INVALID_ID: 55>, b'']
2024-01-11 10:29:50.977 ERROR (MainThread) [bellows.ezsp] NCP entered failed state. Requesting APP controller restart

dmircea · January 13, 2024, 10:27pm

Yes! Downgrade to:

lexridge · January 14, 2024, 2:25am

I am on 2023.12.04 and ZHA. While I have had my share of ZB issues over the past few months, this build seems to be fine at the moment. I have often considered switching from ZHA to Z2M, but I have a lot of devices and resetting them all to work with Z2M just isn’t going to happen unless things really get disastrous. It would take a few days to do even if everything goes smoothly, which I am certain it won’t. Besides, I just spent about a week moving all my temp/humidity sensors from MQTT to ESPHome. Ugh! I am not a fan of MQTT. While it’s useful, it can be a royal PITA sometimes, just like ZHA.

With that said, Z2M does seem to have better support and faster fixes/updates than ZHA.

aussiebish · January 14, 2024, 8:56am

My system can’t start Zigbee ZHA either. I downgraded, but it seemed to re-auto-apply, so broke again. I’ve now disabled the Update function on the Zigbee controller.

aussiebish · January 15, 2024, 8:15pm

Seems my update disablement didn’t work. An update installed yesterday, and again it’s failing. Found this error in the log:

Logger: homeassistant.helpers.dispatcher
Source: helpers/dispatcher.py:59
First occurred: January 14, 2024 at 9:12:50 AM (184 occurrences)
Last logged: January 15, 2024 at 1:16:32 PM

Unable to remove unknown dispatcher <bound method GroupProbe._reprobe_group of <homeassistant.components.zha.core.discovery.GroupProbe object at 0xffff6513bd10>>

I’ve been reverting to this as a fix: addon_core_silabs_multiprotocol_2.3.2

aussiebish · January 15, 2024, 8:32pm

Sorry, just another update. To be clear, it seems that my failure for ZHA is due to the silicon labs multiprotocol add-on. There is a known bug, which I found chatter about over here, just FYI. I’ve now gone in and skipped the current update. fingers crossed!

jerremyscott · January 25, 2024, 3:47pm

I tried both FW options (Zigbee and multi) but still had the issue. Hopefully it’ll be fixed soon.

gujjushah · January 27, 2024, 6:02am

Hi all, New to HA as well.

Faced the same issue as the OP and kept getting a ton of errors in the logs after the update in mid December 2023. ZHA would initialize but there was a tremendous lag between action and response. e,g, if i turn on a switch its would respond after like 2 mins. Even reinstalled the entire OS to eliminate issue with any add-ons but the issue started again. Reverted back to the last backup I had - 2023.12.1 and it is not flooding the logs with errors and everything functions correctly.

Hope there is a fix soon.

Also, wonder how to test any new updates as it just is a cumbersome process going back to the working version (incase the new update does not have the issues resolved) as I have to re-add all the switches.

aussiebish · February 4, 2024, 6:28am

Update: new version 2.4.4 still broken for me.