home-assistant / addons

:heavy_plus_sign: Docker add-ons for Home Assistant
https://home-assistant.io/hassio/
Apache License 2.0
1.51k stars 1.47k forks source link

Matter devices stop updating state, temporarily resolved by restart of Matter Server #3740

Open jnooraga opened 2 weeks ago

jnooraga commented 2 weeks ago

Describe the issue you are experiencing

Seeing multiple matter-over-thread devices stop updating state in Home Assistant after the 6.4.1 update. This can be temporarily resolved by restarting Matter Server

What type of installation are you running?

Home Assistant OS

Which operating system are you running on?

Home Assistant Operating System

Which add-on are you reporting an issue with?

Matter Server

What is the version of the add-on?

6.4.1

Steps to reproduce the issue

  1. Matter Server 6.4.1 running under HAOS 13.1/HA Supervisor 2024.08.0/HA Core 2024.8.3
  2. Have Matter-over-Thread devices registered in HomeAssistant
  3. wait ...

System Health information

N/A

Anything in the Supervisor logs that might be useful for us?

No response

Anything in the add-on logs that might be useful for us?

s6-rc: info: service legacy-services: starting
s6-rc: info: service legacy-services successfully started
[16:17:17] INFO: Using 'enp0s18' as primary network interface.
[16:17:17] INFO: Successfully send discovery information to Home Assistant.
2024-08-30 16:17:17.768 (MainThread) INFO [matter_server.server.stack] Initializing CHIP/Matter Logging...
2024-08-30 16:17:17.768 (MainThread) INFO [matter_server.server.stack] Initializing CHIP/Matter Controller Stack...
[1725059837.779297][126:126] CHIP:CTL: Setting attestation nonce to random value
[1725059837.779399][126:126] CHIP:CTL: Setting CSR nonce to random value
[1725059837.779735][126:126] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs
[1725059837.779791][126:126] CHIP:DL: writing settings to file (/tmp/chip_kvs-Po7OKK)
[1725059837.779818][126:126] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs)
[1725059837.779900][126:126] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_factory.ini
[1725059837.779948][126:126] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_config.ini
[1725059837.779960][126:126] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_counters.ini
[1725059837.780011][126:126] CHIP:DL: writing settings to file (/data/chip_counters.ini-S6pquJ)
[1725059837.780078][126:126] CHIP:DL: renamed tmp file to file (/data/chip_counters.ini)
[1725059837.780088][126:126] CHIP:DL: NVS set: chip-counters/reboot-count = 139 (0x8B)
[1725059837.780198][126:126] CHIP:DL: Got Ethernet interface: enp0s18
[1725059837.780242][126:126] CHIP:DL: Found the primary Ethernet interface:enp0s18
[1725059837.780332][126:126] CHIP:DL: Failed to get WiFi interface
[1725059837.780364][126:126] CHIP:DL: Failed to reset WiFi statistic counts
2024-08-30 16:17:17.780 (MainThread) INFO [chip.storage] Initializing persistent storage from file: /data/chip.json
2024-08-30 16:17:17.780 (MainThread) INFO [chip.storage] Loading configuration from /data/chip.json...
2024-08-30 16:17:17.807 (MainThread) INFO [chip.CertificateAuthority] Loading certificate authorities from storage...
2024-08-30 16:17:17.807 (MainThread) INFO [chip.CertificateAuthority] New CertificateAuthority at index 1
2024-08-30 16:17:17.807 (MainThread) INFO [chip.CertificateAuthority] Loading fabric admins from storage...
2024-08-30 16:17:17.807 (MainThread) INFO [chip.FabricAdmin] New FabricAdmin: FabricId: 0x0000000000000002, VendorId = 0x134B
2024-08-30 16:17:17.807 (MainThread) INFO [matter_server.server.stack] CHIP Controller Stack initialized.
2024-08-30 16:17:17.808 (MainThread) INFO [matter_server.server.server] Starting the Matter Server...
2024-08-30 16:17:17.809 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetching the latest PAA root certificates from DCL.
2024-08-30 16:17:19.368 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetched 62 PAA root certificates from DCL.
2024-08-30 16:17:19.368 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetching the latest PAA root certificates from Git.
2024-08-30 16:17:19.600 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetched 2 PAA root certificates from Git.
2024-08-30 16:17:19.600 (MainThread) INFO [chip.FabricAdmin] Allocating new controller with CaIndex: 1, FabricId: 0x0000000000000002, NodeId: 0x000000000001B669, CatTags: []
2024-08-30 16:17:19.646 (MainThread) INFO [matter_server.server.vendor_info] Loading vendor info from storage.
2024-08-30 16:17:19.648 (MainThread) INFO [matter_server.server.vendor_info] Loaded 238 vendors from storage.
2024-08-30 16:17:19.648 (MainThread) INFO [matter_server.server.vendor_info] Fetching the latest vendor info from DCL.
2024-08-30 16:17:19.791 (MainThread) INFO [matter_server.server.vendor_info] Fetched 239 vendors from DCL.
2024-08-30 16:17:19.791 (MainThread) INFO [matter_server.server.vendor_info] Saving vendor info to storage.
2024-08-30 16:17:19.796 (MainThread) INFO [matter_server.server.device_controller] Loaded 9 nodes from stored configuration
2024-08-30 16:17:19.798 (MainThread) INFO [matter_server.server.server] Matter Server successfully initialized.
2024-08-30 16:17:20.687 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:17> Discovered on mDNS
2024-08-30 16:17:20.688 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:16> Discovered on mDNS
2024-08-30 16:17:20.688 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:12> Discovered on mDNS
2024-08-30 16:17:20.688 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:10> Discovered on mDNS
2024-08-30 16:17:20.688 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:15> Discovered on mDNS
2024-08-30 16:17:20.688 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:14> Discovered on mDNS
2024-08-30 16:17:20.688 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:13> Discovered on mDNS
2024-08-30 16:17:20.688 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:9> Discovered on mDNS
2024-08-30 16:17:20.688 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:11> Discovered on mDNS
2024-08-30 16:17:20.688 (MainThread) INFO [matter_server.server.device_controller] <Node:17> Setting-up node...
2024-08-30 16:17:20.688 (MainThread) INFO [matter_server.server.device_controller] <Node:16> Setting-up node...
2024-08-30 16:17:20.688 (MainThread) INFO [matter_server.server.device_controller] <Node:12> Setting-up node...
2024-08-30 16:17:20.688 (MainThread) INFO [matter_server.server.device_controller] <Node:10> Setting-up node...
2024-08-30 16:17:20.688 (MainThread) INFO [matter_server.server.device_controller] <Node:15> Setting-up node...
2024-08-30 16:17:20.689 (Dummy-2) CHIP_ERROR [chip.native.DIS] Re-using pending resolve entry before reply was received.
2024-08-30 16:17:26.966 (MainThread) INFO [matter_server.server.device_controller] <Node:16> Setting up attributes and events subscription.
2024-08-30 16:17:28.006 (MainThread) INFO [matter_server.server.device_controller] <Node:15> Setting up attributes and events subscription.
2024-08-30 16:17:41.141 (MainThread) INFO [matter_server.server.device_controller] <Node:16> Subscription succeeded with report interval [0, 300]
2024-08-30 16:17:41.141 (MainThread) INFO [matter_server.server.device_controller] <Node:14> Setting-up node...
2024-08-30 16:17:43.087 (MainThread) INFO [matter_server.server.device_controller] <Node:15> Subscription succeeded with report interval [0, 300]
2024-08-30 16:17:43.087 (MainThread) INFO [matter_server.server.device_controller] <Node:13> Setting-up node...
2024-08-30 16:17:47.261 (MainThread) INFO [matter_server.server.device_controller] <Node:14> Setting up attributes and events subscription.
2024-08-30 16:17:51.691 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2024-08-30 16:17:51.691 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2024-08-30 16:18:00.341 (MainThread) INFO [matter_server.server.device_controller] <Node:14> Subscription succeeded with report interval [0, 300]
2024-08-30 16:18:00.342 (MainThread) INFO [matter_server.server.device_controller] <Node:9> Setting-up node...
2024-08-30 16:18:05.688 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:0000000000000011]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 16:18:05.689 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:000000000000000C]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 16:18:05.690 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:000000000000000A]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 16:18:08.689 (MainThread) INFO [matter_server.server.sdk] <Node:17> Attempting to establish CASE session... (attempt 2 of 2)
2024-08-30 16:18:08.690 (MainThread) INFO [matter_server.server.sdk] <Node:12> Attempting to establish CASE session... (attempt 2 of 2)
2024-08-30 16:18:08.690 (MainThread) INFO [matter_server.server.sdk] <Node:10> Attempting to establish CASE session... (attempt 2 of 2)
2024-08-30 16:18:08.690 (Dummy-2) CHIP_ERROR [chip.native.DIS] Re-using pending resolve entry before reply was received.
2024-08-30 16:18:08.691 (Dummy-2) CHIP_ERROR [chip.native.DIS] Re-using pending resolve entry before reply was received.
2024-08-30 16:18:12.778 (MainThread) INFO [matter_server.server.device_controller] <Node:17> Setting up attributes and events subscription.
2024-08-30 16:18:26.528 (MainThread) INFO [matter_server.server.device_controller] <Node:17> Subscription succeeded with report interval [0, 300]
2024-08-30 16:18:26.528 (MainThread) INFO [matter_server.server.device_controller] <Node:11> Setting-up node...
2024-08-30 16:18:28.088 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:000000000000000D]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 16:18:31.089 (MainThread) INFO [matter_server.server.sdk] <Node:13> Attempting to establish CASE session... (attempt 2 of 2)
2024-08-30 16:18:31.090 (Dummy-2) CHIP_ERROR [chip.native.DIS] Re-using pending resolve entry before reply was received.
2024-08-30 16:18:39.691 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2024-08-30 16:18:45.342 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:0000000000000009]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 16:18:48.343 (MainThread) INFO [matter_server.server.sdk] <Node:9> Attempting to establish CASE session... (attempt 2 of 2)
2024-08-30 16:18:53.690 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:000000000000000C]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 16:18:53.690 (MainThread) WARNING [matter_server.server.device_controller] <Node:12> Setup for node failed: Unable to establish CASE session with Node 12
2024-08-30 16:18:53.691 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:000000000000000A]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 16:18:53.691 (MainThread) WARNING [matter_server.server.device_controller] <Node:10> Setup for node failed: Unable to establish CASE session with Node 10
2024-08-30 16:18:57.532 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2024-08-30 16:19:02.092 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2024-08-30 16:19:11.529 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:000000000000000B]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 16:19:14.532 (MainThread) INFO [matter_server.server.sdk] <Node:11> Attempting to establish CASE session... (attempt 2 of 2)
2024-08-30 16:19:16.090 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:000000000000000D]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 16:19:16.090 (MainThread) WARNING [matter_server.server.device_controller] <Node:13> Setup for node failed: Unable to establish CASE session with Node 13
2024-08-30 16:19:19.349 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2024-08-30 16:19:33.345 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:0000000000000009]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 16:19:33.345 (MainThread) WARNING [matter_server.server.device_controller] <Node:9> Setup for node failed: Unable to establish CASE session with Node 9
2024-08-30 16:19:45.541 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2024-08-30 16:19:59.533 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:000000000000000B]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 16:19:59.533 (MainThread) WARNING [matter_server.server.device_controller] <Node:11> Setup for node failed: Unable to establish CASE session with Node 11
2024-08-30 16:20:11.045 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2024-08-30 17:17:22.801 (MainThread) INFO [matter_server.server.device_controller] <Node:9> Node could not be discovered on the network, returning cached IP's
2024-08-30 17:17:25.803 (MainThread) INFO [matter_server.server.device_controller] <Node:10> Node could not be discovered on the network, returning cached IP's
2024-08-30 17:17:28.805 (MainThread) INFO [matter_server.server.device_controller] <Node:11> Node could not be discovered on the network, returning cached IP's
2024-08-30 17:17:31.807 (MainThread) INFO [matter_server.server.device_controller] <Node:12> Node could not be discovered on the network, returning cached IP's
2024-08-30 17:17:34.809 (MainThread) INFO [matter_server.server.device_controller] <Node:13> Node could not be discovered on the network, returning cached IP's
2024-08-30 17:47:35.518 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:9> Discovered on mDNS
2024-08-30 17:47:35.518 (MainThread) INFO [matter_server.server.device_controller] <Node:9> Setting-up node...
2024-08-30 17:47:37.815 (MainThread) INFO [matter_server.server.device_controller] <Node:9> Node could not be discovered on the network, returning cached IP's
2024-08-30 17:47:38.491 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:10> Discovered on mDNS
2024-08-30 17:47:38.491 (MainThread) INFO [matter_server.server.device_controller] <Node:10> Setting-up node...
2024-08-30 17:47:40.817 (MainThread) INFO [matter_server.server.device_controller] <Node:10> Node could not be discovered on the network, returning cached IP's
2024-08-30 17:47:43.820 (MainThread) INFO [matter_server.server.device_controller] <Node:11> Node could not be discovered on the network, returning cached IP's
2024-08-30 17:47:44.562 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:12> Discovered on mDNS
2024-08-30 17:47:44.562 (MainThread) INFO [matter_server.server.device_controller] <Node:12> Setting-up node...
2024-08-30 17:47:46.821 (MainThread) INFO [matter_server.server.device_controller] <Node:12> Node could not be discovered on the network, returning cached IP's
2024-08-30 17:47:47.597 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:13> Discovered on mDNS
2024-08-30 17:47:47.597 (MainThread) INFO [matter_server.server.device_controller] <Node:13> Setting-up node...
2024-08-30 17:47:47.597 (Dummy-2) CHIP_ERROR [chip.native.DIS] Re-using pending resolve entry before reply was received.
2024-08-30 17:47:49.823 (MainThread) INFO [matter_server.server.device_controller] <Node:13> Node could not be discovered on the network, returning cached IP's
2024-08-30 17:48:09.492 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2024-08-30 17:48:15.566 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2024-08-30 17:48:18.600 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2024-08-30 17:48:20.519 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:0000000000000009]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 17:48:23.491 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:000000000000000A]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 17:48:23.521 (MainThread) INFO [matter_server.server.sdk] <Node:9> Attempting to establish CASE session... (attempt 2 of 2)
2024-08-30 17:48:26.492 (MainThread) INFO [matter_server.server.sdk] <Node:10> Attempting to establish CASE session... (attempt 2 of 2)
2024-08-30 17:48:29.563 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:000000000000000C]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 17:48:32.564 (MainThread) INFO [matter_server.server.sdk] <Node:12> Attempting to establish CASE session... (attempt 2 of 2)
2024-08-30 17:48:32.597 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:000000000000000D]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 17:48:35.598 (MainThread) INFO [matter_server.server.sdk] <Node:13> Attempting to establish CASE session... (attempt 2 of 2)
2024-08-30 17:48:35.598 (Dummy-2) CHIP_ERROR [chip.native.DIS] Re-using pending resolve entry before reply was received.
2024-08-30 17:48:57.495 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2024-08-30 17:49:03.567 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2024-08-30 17:49:06.601 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2024-08-30 17:49:08.522 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:0000000000000009]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 17:49:08.523 (MainThread) WARNING [matter_server.server.device_controller] <Node:9> Setup for node failed: Unable to establish CASE session with Node 9
2024-08-30 17:49:11.493 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:000000000000000A]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 17:49:11.493 (MainThread) WARNING [matter_server.server.device_controller] <Node:10> Setup for node failed: Unable to establish CASE session with Node 10
2024-08-30 17:49:17.565 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:000000000000000C]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 17:49:17.566 (MainThread) WARNING [matter_server.server.device_controller] <Node:12> Setup for node failed: Unable to establish CASE session with Node 12
2024-08-30 17:49:20.600 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:000000000000000D]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-08-30 17:49:20.600 (MainThread) WARNING [matter_server.server.device_controller] <Node:13> Setup for node failed: Unable to establish CASE session with Node 13
2024-08-30 17:49:34.815 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2024-08-30 18:00:47.478 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:13> Discovered on mDNS
2024-08-30 18:00:47.478 (MainThread) INFO [matter_server.server.device_controller] <Node:13> Setting-up node...
2024-08-30 18:00:49.264 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:11> Discovered on mDNS
2024-08-30 18:00:49.264 (MainThread) INFO [matter_server.server.device_controller] <Node:11> Setting-up node...
2024-08-30 18:00:50.187 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:9> Discovered on mDNS
2024-08-30 18:00:50.187 (MainThread) INFO [matter_server.server.device_controller] <Node:9> Setting-up node...
2024-08-30 18:00:51.983 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:10> Discovered on mDNS
2024-08-30 18:00:51.983 (MainThread) INFO [matter_server.server.device_controller] <Node:10> Setting-up node...
2024-08-30 18:00:53.072 (MainThread) INFO [matter_server.server.device_controller] <Node:13> Setting up attributes and events subscription.
2024-08-30 18:00:54.687 (MainThread) INFO [matter_server.server.device_controller] <Node:11> Setting up attributes and events subscription.
2024-08-30 18:00:55.758 (MainThread) INFO [matter_server.server.device_controller] <Node:9> Setting up attributes and events subscription.
2024-08-30 18:00:57.557 (MainThread) INFO [matter_server.server.device_controller] <Node:10> Setting up attributes and events subscription.
2024-08-30 18:00:58.684 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:12> Discovered on mDNS
2024-08-30 18:00:58.684 (MainThread) INFO [matter_server.server.device_controller] <Node:12> Setting-up node...
2024-08-30 18:01:04.322 (MainThread) INFO [matter_server.server.device_controller] <Node:12> Setting up attributes and events subscription.
2024-08-30 18:01:08.720 (MainThread) INFO [matter_server.server.device_controller] <Node:9> Subscription succeeded with report interval [0, 300]
2024-08-30 18:01:15.080 (MainThread) INFO [matter_server.server.device_controller] <Node:10> Subscription succeeded with report interval [0, 300]
2024-08-30 18:01:23.379 (MainThread) INFO [matter_server.server.device_controller] <Node:12> Subscription succeeded with report interval [0, 300]
2024-08-30 18:01:24.538 (MainThread) INFO [matter_server.server.device_controller] <Node:11> Subscription succeeded with report interval [0, 300]
2024-08-30 18:01:38.589 (MainThread) INFO [matter_server.server.device_controller] <Node:13> Subscription succeeded with report interval [0, 300]

Additional information

No response

agners commented 2 weeks ago

What devices do you have? Is this true for all devices at once or only some? Are the affected devices Thread devices? If so, what Thread Border Routers are you using?

jnooraga commented 2 weeks ago

I have only Aqara P2 Window and Door Sensors, 9 of them, which are all matter-over-thread. This appears to only impact some of them.

The border router is a Homepod Mini. They were all paired with HomeKit first and then paired to HomeAssistant. The state of the impacted devices changes as expected in HomeKit, but that is not reflected in HomeAssistant.

jnooraga commented 1 week ago

Reverting to Matter Server 6.3.1 has ameliorated the issue.

MrMuetze commented 1 week ago

I think I've also encountered the issue. Matter server 6.4.2 with 10 devices (Eve Thermos, Energy, Door and Window, Weather, all via Thread). This night my Eve Weather values (temperature, humidity) have stopped updating in HA. Everything worked fine before. The values are reported as up-to-date, but are stuck for a couple hours now.

CleanShot 2024-09-04 at 10 30 27@2x

In Apple Home (Home App, Eve App) everything is working as expected and the values are correct. Border routers are an Apple TV and a HomePod mini.

I am happy to provide more information. :)

Edit: Here is the latest log. The Eve Weather is reported as Node 18. There is one Eve Energy that is currently offline which is expected (Node 14).

core_matter_server_2024-09-04T08-41-17.746Z.log

salos1982 commented 1 week ago

One my device also stopped responding after the update to 6.4.2. The second one (the same lamp) works I don't know why. Also the not working one is not possible to add again. On the final step I have an error

MrMuetze commented 1 week ago

Hm I’ve reverted to 6.4.1 and right now all devices started working again. A simple restart of the Matter server or HA has not helped before, so the downgrade seems to have been the key here. I’ll keep monitoring the situation.

MrMuetze commented 1 week ago

For anyone else that is finding this issue, I think it might be a duplicate of: https://github.com/home-assistant/core/issues/124503

I am currently attempting the solution suggested there.