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
71.89k stars 30.12k forks source link

Platform zha does not generate unique IDs - ZHA/SkyConnect/Multipan #99522

Closed Anto79-ops closed 1 year ago

Anto79-ops commented 1 year ago

The problem

hi

updated to 2023.9.0b3 and my ZHA network is not working because the "Platform zha does not generate unique IDs" error.

here's the log errors in chronological order:

Logger: bellows.uart
Source: runner.py:186
First occurred: 5:34:11 PM (1 occurrences)
Last logged: 5:34:11 PM

Lost serial connection: ConnectionResetError('Remote server closed connection')
Logger: zigpy.application
Source: /usr/local/lib/python3.11/site-packages/zigpy/application.py:198
First occurred: 5:34:11 PM (1 occurrences)
Last logged: 5:34:11 PM

Couldn't start application
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 195, in startup
    await self.connect()
  File "/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py", line 141, in connect
    await ezsp.startup_reset()
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/__init__.py", line 151, in startup_reset
    await self._gw.wait_for_startup_reset()
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 175, in wait_for_startup_reset
    await self._startup_reset_future
ConnectionResetError: Remote server closed connection

from here, ALL my entities (about 240 of them) show in these errors. This is just a sample.

Logger: homeassistant.components.binary_sensor
Source: helpers/entity_platform.py:610
Integration: Binary sensor (documentation, issues)
First occurred: 5:34:58 PM (29 occurrences)
Last logged: 5:34:58 PM

Platform zha does not generate unique IDs. ID 54:ef:44:10:00:5c:bc:80-1-1280 already exists - ignoring binary_sensor.lumi_lumi_motion_ac02_motion_4
Platform zha does not generate unique IDs. ID 00:12:4b:00:24:44:be:88-1-1280 already exists - ignoring binary_sensor.ewelink_ms01_motion
Platform zha does not generate unique IDs. ID 00:15:8d:00:09:43:67:44-1-6 already exists - ignoring binary_sensor.lumi_lumi_sensor_magnet_aq2_opening_5
Platform zha does not generate unique IDs. ID 54:ef:44:10:00:83:87:3f-1-1030 already exists - ignoring binary_sensor.lumi_lumi_motion_ac02_occupancy_7
Platform zha does not generate unique IDs. ID 54:ef:44:10:00:83:87:3f-1-1280 already exists - ignoring binary_sensor.lumi_lumi_motion_ac02_motion_5

So ZHA is up and running, but none of my automations are running because the entities are being ignored.

What version of Home Assistant Core has the issue?

2023.9.0b3

What was the last working version of Home Assistant Core?

2023.9.0b2

What type of installation are you running?

Home Assistant OS

Integration causing the issue

ZHA Zigbee

Link to integration documentation on our website

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

Diagnostics information

Example YAML snippet

No response

Anything in the logs that might be useful for us?

No response

Additional information

No response

home-assistant[bot] commented 1 year ago

Hey there @dmulcahey, @adminiuga, @puddly, 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!

Code owner commands Code owners of `zha` can trigger bot actions by commenting: - `@home-assistant close` Closes the issue. - `@home-assistant rename Awesome new title` Renames the issue. - `@home-assistant reopen` Reopen the issue. - `@home-assistant unassign zha` Removes the current integration label and assignees on the issue, add the integration domain after the command.

(message by CodeOwnersMention)

zha documentation zha source (message by IssueLinks)

Anto79-ops commented 1 year ago

thinking that a restart of core would temporary solve the issue, that is what I did but that created other, different issues when my system came back online. Here are the logs for that, below.

You'll see towards the end of the logs that it goes into some "discarding" loop logging it over 800 times in minutes.

Finally, after completely shutting down my system (supervisor/host and thus core), and restarting. My system and ZHA were working normally again.

2023-09-02 18:39:43.666 ERROR (MainThread) [zigpy.application] Couldn't start application
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 268, in reset
    return await self._reset_future
           ^^^^^^^^^^^^^^^^^^^^^^^^
asyncio.exceptions.CancelledError

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

Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 195, in startup
    await self.connect()
  File "/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py", line 141, in connect
    await ezsp.startup_reset()
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/__init__.py", line 159, in startup_reset
    await self.reset()
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/__init__.py", line 185, in reset
    await self._gw.reset()
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 267, in reset
    async with asyncio_timeout(RESET_TIMEOUT):
  File "/usr/local/lib/python3.11/asyncio/timeouts.py", line 111, in __aexit__
    raise TimeoutError from exc_val
TimeoutError
2023-09-02 18:39:44.795 WARNING (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis coordinator (attempt 1 of 3)
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 268, in reset
    return await self._reset_future
           ^^^^^^^^^^^^^^^^^^^^^^^^
asyncio.exceptions.CancelledError

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

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 226, in async_initialize
    await self.application_controller.startup(auto_form=True)
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 195, in startup
    await self.connect()
  File "/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py", line 141, in connect
    await ezsp.startup_reset()
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/__init__.py", line 159, in startup_reset
    await self.reset()
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/__init__.py", line 185, in reset
    await self._gw.reset()
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 267, in reset
    async with asyncio_timeout(RESET_TIMEOUT):
  File "/usr/local/lib/python3.11/asyncio/timeouts.py", line 111, in __aexit__
    raise TimeoutError from exc_val
TimeoutError
2023-09-02 18:39:47.948 ERROR (MainThread) [zigpy.application] Couldn't start application
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 195, in startup
    await self.connect()
  File "/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py", line 138, in connect
    await ezsp.connect(use_thread=self.config[CONF_USE_THREAD])
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/__init__.py", line 179, in connect
    self._gw = await bellows.uart.connect(self._config, self, use_thread=use_thread)
               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 414, in connect
    protocol, _ = await _connect(config, application)
                  ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 385, in _connect
    transport, protocol = await zigpy.serial.create_serial_connection(
                          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/zigpy/serial.py", line 36, in create_serial_connection
    transport, protocol = await loop.create_connection(
                          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 1085, in create_connection
    raise exceptions[0]
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 1069, in create_connection
    sock = await self._connect_sock(
           ^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 973, in _connect_sock
    await self.sock_connect(sock, address)
  File "/usr/local/lib/python3.11/asyncio/selector_events.py", line 628, in sock_connect
    return await fut
           ^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/selector_events.py", line 668, in _sock_connect_cb
    raise OSError(err, f'Connect call failed {address}')
ConnectionRefusedError: [Errno 111] Connect call failed ('172.30.32.1', 9999)
2023-09-02 18:39:47.971 WARNING (MainThread) [homeassistant.components.zha.core.gateway] Couldn't start EZSP = Silicon Labs EmberZNet protocol: Elelabs, HUSBZB-1, Telegesis coordinator (attempt 2 of 3)
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 195, in startup
    await self.connect()
  File "/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py", line 138, in connect
    await ezsp.connect(use_thread=self.config[CONF_USE_THREAD])
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/__init__.py", line 179, in connect
    self._gw = await bellows.uart.connect(self._config, self, use_thread=use_thread)
               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 414, in connect
    protocol, _ = await _connect(config, application)
                  ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 385, in _connect
    transport, protocol = await zigpy.serial.create_serial_connection(
                          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/zigpy/serial.py", line 36, in create_serial_connection
    transport, protocol = await loop.create_connection(
                          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 1085, in create_connection
    raise exceptions[0]
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 1069, in create_connection
    sock = await self._connect_sock(
           ^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 973, in _connect_sock
    await self.sock_connect(sock, address)
  File "/usr/local/lib/python3.11/asyncio/selector_events.py", line 628, in sock_connect
    return await fut
           ^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/selector_events.py", line 668, in _sock_connect_cb
    raise OSError(err, f'Connect call failed {address}')
ConnectionRefusedError: [Errno 111] Connect call failed ('172.30.32.1', 9999)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 226, in async_initialize
    await self.application_controller.startup(auto_form=True)
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 199, in startup
    await self.shutdown()
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 404, in shutdown
    await self._dblistener.shutdown()
  File "/usr/local/lib/python3.11/site-packages/zigpy/appdb.py", line 187, in shutdown
    await self._set_isolation_level(None)
  File "/usr/local/lib/python3.11/site-packages/zigpy/appdb.py", line 205, in _set_isolation_level
    await self._db._execute(lambda: setattr(self._db, "isolation_level", level))
  File "/usr/local/lib/python3.11/site-packages/aiosqlite/core.py", line 126, in _execute
    raise ValueError("Connection closed")
ValueError: Connection closed
Logger: zigpy.appdb
Source: runner.py:186
First occurred: 6:39:55 PM (795 occurrences)
Last logged: 6:51:13 PM

Discarding _save_device event
Discarding _network_backup_removed event
Discarding _network_backup_created event
Discarding _save_device_last_seen event
Discarding _save_attribute event
2023-09-02 18:39:55.766 WARNING (MainThread) [zigpy.appdb] Discarding _save_device event
2023-09-02 18:39:55.858 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:39:55.864 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:39:55.866 WARNING (MainThread) [zigpy.appdb] Discarding _save_device event
2023-09-02 18:39:59.329 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:39:59.360 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:04.475 WARNING (MainThread) [zigpy.appdb] Discarding _network_backup_removed event
2023-09-02 18:40:04.484 WARNING (MainThread) [zigpy.appdb] Discarding _network_backup_created event
2023-09-02 18:40:05.160 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:05.164 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:07.694 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:07.701 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:07.774 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:07.779 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:09.844 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:40:09.894 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:09.901 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:09.907 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:09.910 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:09.914 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:10.689 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:10.695 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:12.495 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:40:12.553 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:12.562 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:12.946 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:40:13.002 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:13.007 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:13.786 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:13.790 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:13.801 WARNING (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0x36C3:1:0x0006]: async_initialize: all attempts have failed: [DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>')]
2023-09-02 18:40:14.094 WARNING (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xD74D:1:0x0008]: async_initialize: all attempts have failed: [DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>')]
2023-09-02 18:40:14.521 WARNING (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xA9CD:1:0x0006]: async_initialize: all attempts have failed: [DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>')]
2023-09-02 18:40:14.716 WARNING (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xA9CD:1:0x0008]: async_initialize: all attempts have failed: [DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>')]
2023-09-02 18:40:14.911 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:40:14.967 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:14.973 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:14.977 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:14.981 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:14.983 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:15.087 WARNING (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xD74D:1:0x0006]: async_initialize: all attempts have failed: [DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>')]
2023-09-02 18:40:15.421 WARNING (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0x9B24:1:0x0006]: async_initialize: all attempts have failed: [DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>')]
2023-09-02 18:40:15.936 WARNING (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0xD2D5:1:0x0006]: async_initialize: all attempts have failed: [DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>')]
2023-09-02 18:40:17.150 WARNING (MainThread) [homeassistant.components.zha.core.cluster_handlers] [0x9B24:1:0x0008]: async_initialize: all attempts have failed: [DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>')]
2023-09-02 18:40:21.124 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:40:21.195 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:26.090 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:40:26.117 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:26.194 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:29.671 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:40:29.681 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:29.700 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:29.712 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:29.718 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:29.724 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:37.467 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:40:37.504 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:37.785 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:37.789 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:38.688 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:40:38.739 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:38.746 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:39.924 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:42.551 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:40:42.616 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:42.620 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:42.623 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:42.626 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:42.629 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:44.990 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:47.957 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:47.963 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:51.407 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:40:51.442 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:51.447 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:51.451 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:51.457 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:51.463 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:55.946 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:40:55.985 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:40:55.991 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:40:59.733 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:05.275 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:05.278 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:07.704 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:07.708 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:07.856 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:07.860 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:07.884 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:07.888 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:10.853 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:10.856 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:12.633 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:16.438 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:41:16.503 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:21.467 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:22.030 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:41:22.058 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:22.123 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:41:22.192 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:25.466 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:41:25.523 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:25.530 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:25.534 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:25.537 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:25.540 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:25.705 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:41:25.757 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:25.763 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:27.360 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:41:27.407 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:27.412 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:27.416 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:27.421 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:27.427 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:32.647 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:41:32.700 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:33.677 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:41:37.346 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:41:37.354 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:41:37.397 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:37.407 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:37.414 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:37.417 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:38.120 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:38.123 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:45.792 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:45.798 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:45.805 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:45.808 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:50.715 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:41:50.763 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:50.771 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:50.774 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:50.777 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:50.781 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:52.403 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:41:52.457 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:52.460 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:52.463 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:52.467 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:52.508 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:41:55.545 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:55.557 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:41:55.609 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:41:55.614 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:41:57.434 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:42:05.164 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:42:05.170 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:42:07.706 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:42:07.710 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:42:08.064 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:42:08.067 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:42:20.785 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:42:25.410 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:42:25.520 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:42:25.526 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:42:35.480 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:42:35.537 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:42:35.547 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:42:35.551 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:42:35.557 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:42:35.564 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:42:36.006 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:42:36.058 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:42:36.064 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:48:28.439 ERROR (MainThread) [zigpy.application] Couldn't start application
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 195, in startup
    await self.connect()
  File "/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py", line 138, in connect
    await ezsp.connect(use_thread=self.config[CONF_USE_THREAD])
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/__init__.py", line 179, in connect
    self._gw = await bellows.uart.connect(self._config, self, use_thread=use_thread)
               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 414, in connect
    protocol, _ = await _connect(config, application)
                  ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 385, in _connect
    transport, protocol = await zigpy.serial.create_serial_connection(
                          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/zigpy/serial.py", line 36, in create_serial_connection
    transport, protocol = await loop.create_connection(
                          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 1085, in create_connection
    raise exceptions[0]
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 1069, in create_connection
    sock = await self._connect_sock(
           ^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 973, in _connect_sock
    await self.sock_connect(sock, address)
  File "/usr/local/lib/python3.11/asyncio/selector_events.py", line 628, in sock_connect
    return await fut
           ^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/selector_events.py", line 668, in _sock_connect_cb
    raise OSError(err, f'Connect call failed {address}')
2023-09-02 18:48:30.650 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:48:30.659 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:48:34.631 ERROR (MainThread) [zigpy.application] Couldn't start application
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 195, in startup
    await self.connect()
  File "/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py", line 138, in connect
    await ezsp.connect(use_thread=self.config[CONF_USE_THREAD])
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/__init__.py", line 179, in connect
    self._gw = await bellows.uart.connect(self._config, self, use_thread=use_thread)
               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 414, in connect
    protocol, _ = await _connect(config, application)
                  ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 385, in _connect
    transport, protocol = await zigpy.serial.create_serial_connection(
                          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/zigpy/serial.py", line 36, in create_serial_connection
    transport, protocol = await loop.create_connection(
                          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 1085, in create_connection
    raise exceptions[0]
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 1069, in create_connection
    sock = await self._connect_sock(
           ^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 973, in _connect_sock
    await self.sock_connect(sock, address)
  File "/usr/local/lib/python3.11/asyncio/selector_events.py", line 628, in sock_connect
    return await fut
           ^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/selector_events.py", line 668, in _sock_connect_cb
    raise OSError(err, f'Connect call failed {address}')
ConnectionRefusedError: [Errno 111] Connect call failed ('172.30.32.1', 9999)
2023-09-02 18:48:39.728 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:48:39.732 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:48:39.789 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:48:39.792 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:48:43.379 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:48:43.419 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:48:43.428 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:48:43.431 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:48:43.434 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:48:43.437 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:48:45.936 ERROR (MainThread) [zigpy.application] Couldn't start application
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 195, in startup
    await self.connect()
  File "/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py", line 138, in connect
    await ezsp.connect(use_thread=self.config[CONF_USE_THREAD])
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/__init__.py", line 179, in connect
    self._gw = await bellows.uart.connect(self._config, self, use_thread=use_thread)
               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 414, in connect
    protocol, _ = await _connect(config, application)
                  ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 385, in _connect
    transport, protocol = await zigpy.serial.create_serial_connection(
                          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/zigpy/serial.py", line 36, in create_serial_connection
    transport, protocol = await loop.create_connection(
                          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 1085, in create_connection
    raise exceptions[0]
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 1069, in create_connection
    sock = await self._connect_sock(
           ^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 973, in _connect_sock
    await self.sock_connect(sock, address)
  File "/usr/local/lib/python3.11/asyncio/selector_events.py", line 628, in sock_connect
    return await fut
           ^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/selector_events.py", line 668, in _sock_connect_cb
    raise OSError(err, f'Connect call failed {address}')
ConnectionRefusedError: [Errno 111] Connect call failed ('172.30.32.1', 9999)
2023-09-02 18:48:53.360 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_relays event
2023-09-02 18:48:53.412 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:48:53.417 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:49:05.274 WARNING (MainThread) [zigpy.appdb] Discarding _save_device_last_seen event
2023-09-02 18:49:05.280 WARNING (MainThread) [zigpy.appdb] Discarding _save_attribute event
2023-09-02 18:49:07.317 ERROR (MainThread) [zigpy.application] Couldn't start application
Traceback (most recent call last):
  File "/usr/local/lib/python3.11/site-packages/zigpy/application.py", line 195, in startup
    await self.connect()
  File "/usr/local/lib/python3.11/site-packages/bellows/zigbee/application.py", line 138, in connect
    await ezsp.connect(use_thread=self.config[CONF_USE_THREAD])
  File "/usr/local/lib/python3.11/site-packages/bellows/ezsp/__init__.py", line 179, in connect
    self._gw = await bellows.uart.connect(self._config, self, use_thread=use_thread)
               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 414, in connect
    protocol, _ = await _connect(config, application)
                  ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/bellows/uart.py", line 385, in _connect
    transport, protocol = await zigpy.serial.create_serial_connection(
                          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/site-packages/zigpy/serial.py", line 36, in create_serial_connection
    transport, protocol = await loop.create_connection(
                          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 1085, in create_connection
    raise exceptions[0]
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 1069, in create_connection
    sock = await self._connect_sock(
           ^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/base_events.py", line 973, in _connect_sock
    await self.sock_connect(sock, address)
  File "/usr/local/lib/python3.11/asyncio/selector_events.py", line 628, in sock_connect
    return await fut
           ^^^^^^^^^
  File "/usr/local/lib/python3.11/asyncio/selector_events.py", line 668, in _sock_connect_cb
    raise OSError(err, f'Connect call failed {address}')
ConnectionRefusedError: [Errno 111] Connect call failed ('172.30.32.1', 9999)
Anto79-ops commented 1 year ago

Perhaps this 2 stage ZHA initializing introduced here caused ZHA to load twice or something?

Anto79-ops commented 1 year ago

Just restarted core, and the unique ID issue came back. My ZHA system is down, again on b3.

So definitely not a one-off issue and seems to happen on restarts/reboot only. That is if Zha happens to boot up successfully, then ZHA keeps working until the next restart

Anto79-ops commented 1 year ago

Updating to b4, the issue did not appear. I don't know if this means it's resolved or may happen again another time.