search

home-assistant / addons

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

After update to 6.4.2 one my matter bulb stops discovered. #3747

Closed salos1982 closed 1 month ago

salos1982 commented 2 months ago

Describe the issue you are experiencing

I have 2 the same bulbs and one of them stops responding after updating the matter server to 6.4.2. When I tried to add it again the bulb was discovered and started connecting but it showed an error in the final. Rollback to 6.4.1 helped me to add the bulb again and now it is working.

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.2

Steps to reproduce the issue

Update addon to 6.4.2 with already added devices.

System Health information

System Information

version core-2024.8.3
installation_type Home Assistant OS
dev false
hassio true
docker true
user root
virtualenv false
python_version 3.12.4
os_name Linux
os_version 6.6.46-haos
arch x86_64
timezone Europe/Warsaw
config_dir /config
Home Assistant Community Store GitHub API | ok -- | -- GitHub Content | ok GitHub Web | ok HACS Data | ok GitHub API Calls Remaining | 5000 Installed Version | 2.0.0 Stage | running Available Repositories | 1392 Downloaded Repositories | 2
Home Assistant Cloud logged_in | false -- | -- can_reach_cert_server | ok can_reach_cloud_auth | ok can_reach_cloud | ok
Home Assistant Supervisor host_os | Home Assistant OS 13.1 -- | -- update_channel | stable supervisor_version | supervisor-2024.08.0 agent_version | 1.6.0 docker_version | 26.1.4 disk_total | 234.0 GB disk_used | 29.2 GB healthy | true supported | true host_connectivity | true supervisor_connectivity | true ntp_synchronized | true virtualization | board | generic-x86-64 supervisor_api | ok version_api | ok installed_addons | Silicon Labs Multiprotocol (2.4.5), Terminal & SSH (9.14.0), Matter Server (6.4.2), Studio Code Server (5.15.0), Duck DNS (1.18.0), NGINX Home Assistant SSL proxy (3.10.1), eWeLink Smart Home (1.4.3), Plex Media Server (3.5.2), Mosquitto broker (6.4.1), Whisper (2.1.2), Piper (1.5.2), openWakeWord (1.10.0), ESPHome (2024.8.3), vosk (1.6.1), Rhasspy Assistant (2.5.11), Double Take (1.13.1), Exadel CompreFace (1.1.0), Matter Server (6.4.1)
Dashboards dashboards | 2 -- | -- resources | 0 views | 0 mode | storage
Recorder oldest_recorder_run | 26 августа 2024 г. в 16:32 -- | -- current_recorder_run | 4 сентября 2024 г. в 11:30 estimated_db_size | 495.95 MiB database_engine | sqlite database_version | 3.45.3

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

2024-09-04 10:55:53.543 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state running
2024-09-04 10:55:53.543 INFO (MainThread) [supervisor.resolution.checks.base] Run check for disabled_data_disk/system
2024-09-04 10:55:53.544 INFO (MainThread) [supervisor.resolution.checks.base] Run check for docker_config/system
2024-09-04 10:55:53.544 INFO (MainThread) [supervisor.resolution.checks.base] Run check for free_space/system
2024-09-04 10:55:53.544 INFO (MainThread) [supervisor.resolution.checks.base] Run check for ipv4_connection_problem/system
2024-09-04 10:55:53.545 INFO (MainThread) [supervisor.resolution.checks.base] Run check for security/core
2024-09-04 10:55:53.545 INFO (MainThread) [supervisor.resolution.checks.base] Run check for pwned/addon
2024-09-04 10:55:53.595 INFO (MainThread) [supervisor.resolution.checks.base] Run check for multiple_data_disks/system
2024-09-04 10:55:53.595 INFO (MainThread) [supervisor.resolution.checks.base] Run check for trust/supervisor
2024-09-04 10:55:53.603 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_failed/dns_server
2024-09-04 10:55:53.603 INFO (MainThread) [supervisor.resolution.checks.base] Run check for dns_server_ipv6_error/dns_server
2024-09-04 10:55:53.604 INFO (MainThread) [supervisor.resolution.check] System checks complete
2024-09-04 10:55:53.604 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running
2024-09-04 10:55:53.674 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
2024-09-04 10:55:53.675 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state running
2024-09-04 10:55:53.675 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete
2024-09-04 10:56:59.425 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
2024-09-04 10:58:35.741 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/rhasspy/hassio-addons repository
2024-09-04 10:58:35.745 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/zigbee2mqtt/hassio-zigbee2mqtt repository
2024-09-04 10:58:35.748 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/jakowenko/double-take-hassio-addons repository
2024-09-04 10:58:35.753 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/music-assistant/home-assistant-addon repository
2024-09-04 10:58:35.757 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/hassio-addons/repository repository
2024-09-04 10:58:35.760 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/CoolKit-Technologies/ha-addon repository
2024-09-04 10:58:35.762 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/esphome/home-assistant-addon repository
2024-09-04 10:58:35.768 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/home-assistant/addons repository
2024-09-04 10:58:36.715 INFO (MainThread) [supervisor.store] Loading add-ons from store: 100 all - 0 new - 0 remove
2024-09-04 10:58:36.715 INFO (MainThread) [supervisor.store] Loading add-ons from store: 100 all - 0 new - 0 remove
2024-09-04 10:58:45.702 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
2024-09-04 11:04:37.657 INFO (MainThread) [supervisor.host.info] Updating local host information
2024-09-04 11:04:38.054 INFO (MainThread) [supervisor.host.services] Updating service information
2024-09-04 11:04:38.058 INFO (MainThread) [supervisor.host.network] Updating local network information
2024-09-04 11:04:38.147 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information
2024-09-04 11:04:38.151 INFO (MainThread) [supervisor.host.manager] Host information reload completed
2024-09-04 11:08:07.350 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
2024-09-04 11:08:07.400 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode
2024-09-04 11:08:07.404 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
2024-09-04 11:15:28.011 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
2024-09-04 11:15:28.060 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode
2024-09-04 11:15:28.062 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
2024-09-04 11:16:16.238 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API error: Cannot proxy websocket message of unsupported type: 257
2024-09-04 11:16:16.239 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed
2024-09-04 11:17:36.979 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/zigbee2mqtt/hassio-zigbee2mqtt repository
2024-09-04 11:17:36.982 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/jakowenko/double-take-hassio-addons repository
2024-09-04 11:17:36.987 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/music-assistant/home-assistant-addon repository
2024-09-04 11:17:36.991 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/hassio-addons/repository repository
2024-09-04 11:17:36.995 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/CoolKit-Technologies/ha-addon repository
2024-09-04 11:17:37.002 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/esphome/home-assistant-addon repository
2024-09-04 11:17:37.010 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/home-assistant/addons repository
2024-09-04 11:17:37.091 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/rhasspy/hassio-addons repository
2024-09-04 11:17:38.177 INFO (MainThread) [supervisor.store] Loading add-ons from store: 101 all - 1 new - 0 remove
2024-09-04 11:17:38.178 INFO (MainThread) [supervisor.store] Loading add-ons from store: 101 all - 0 new - 0 remove
2024-09-04 11:19:10.408 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/rhasspy/hassio-addons repository
2024-09-04 11:19:10.410 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/zigbee2mqtt/hassio-zigbee2mqtt repository
2024-09-04 11:19:10.414 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/jakowenko/double-take-hassio-addons repository
2024-09-04 11:19:10.419 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/music-assistant/home-assistant-addon repository
2024-09-04 11:19:10.425 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/hassio-addons/repository repository
2024-09-04 11:19:10.431 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/CoolKit-Technologies/ha-addon repository
2024-09-04 11:19:10.438 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/esphome/home-assistant-addon repository
2024-09-04 11:19:10.451 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/home-assistant/addons repository
2024-09-04 11:19:11.424 INFO (MainThread) [supervisor.store] Loading add-ons from store: 101 all - 0 new - 0 remove
2024-09-04 11:19:11.424 INFO (MainThread) [supervisor.store] Loading add-ons from store: 101 all - 0 new - 0 remove
2024-09-04 11:19:22.219 INFO (MainThread) [supervisor.addons.addon] No local_matter_server addon Docker image homeassistant/amd64-addon-matter-server found
2024-09-04 11:19:22.219 INFO (MainThread) [supervisor.docker.interface] Downloading docker image homeassistant/amd64-addon-matter-server with tag 6.4.1.
2024-09-04 11:19:24.908 INFO (MainThread) [supervisor.addons.addon] Creating Home Assistant add-on data folder /data/addons/data/local_matter_server
2024-09-04 11:19:24.909 INFO (MainThread) [supervisor.docker.interface] Downloading docker image homeassistant/amd64-addon-matter-server with tag 6.4.1.
2024-09-04 11:19:26.069 INFO (MainThread) [supervisor.addons.manager] Add-on 'local_matter_server' successfully installed
2024-09-04 11:19:33.618 INFO (SyncWorker_7) [supervisor.addons.addon] Creating Home Assistant add-on config folder /data/addon_configs/local_matter_server
2024-09-04 11:19:33.836 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on homeassistant/amd64-addon-matter-server with version 6.4.1
2024-09-04 11:19:34.665 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from local_matter_server
2024-09-04 11:19:34.803 INFO (MainThread) [supervisor.discovery] Sending discovery to Home Assistant matter from local_matter_server
2024-09-04 11:19:34.815 INFO (MainThread) [supervisor.discovery] Discovery ac7dc6d78cd045c58ac96d587d5799ac message send
2024-09-04 11:22:35.945 INFO (SyncWorker_2) [supervisor.docker.manager] Stopping addon_core_matter_server application
2024-09-04 11:22:40.411 INFO (SyncWorker_2) [supervisor.docker.manager] Cleaning addon_core_matter_server application
2024-09-04 11:22:41.306 INFO (MainThread) [supervisor.docker.addon] Starting Docker add-on homeassistant/amd64-addon-matter-server with version 6.4.2
2024-09-04 11:22:42.210 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from core_matter_server
2024-09-04 11:28:45.891 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
2024-09-04 11:30:11.545 INFO (SyncWorker_7) [supervisor.docker.manager] Restarting homeassistant
2024-09-04 11:30:11.600 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed
2024-09-04 11:30:11.603 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed
2024-09-04 11:30:11.606 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for f4f71350_ewelink_smart_home_slug closed
2024-09-04 11:30:11.608 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API for a0d7b954_vscode closed
2024-09-04 11:30:57.937 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready
2024-09-04 11:30:59.784 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state running
2024-09-04 11:30:59.868 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
2024-09-04 11:31:02.946 INFO (MainThread) [supervisor.homeassistant.core] Home Assistant Core state changed to APIState(core_state='NOT_RUNNING', offline_db_migration=False)
2024-09-04 11:31:04.259 INFO (MainThread) [supervisor.auth] Auth request from 'core_mosquitto' for 'homeassistant'
2024-09-04 11:31:04.265 INFO (MainThread) [supervisor.auth] Home Assistant not running, checking cache
2024-09-04 11:31:09.143 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
2024-09-04 11:31:09.144 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode
2024-09-04 11:31:09.146 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
2024-09-04 11:31:09.147 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
2024-09-04 11:31:09.149 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode
2024-09-04 11:31:09.150 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
2024-09-04 11:31:09.151 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_vscode
2024-09-04 11:31:09.152 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
2024-09-04 11:31:09.153 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
2024-09-04 11:31:10.506 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
2024-09-04 11:31:10.508 INFO (MainThread) [supervisor.api.proxy] WebSocket access from f4f71350_ewelink_smart_home_slug
2024-09-04 11:31:10.512 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
2024-09-04 11:31:13.149 INFO (MainThread) [supervisor.homeassistant.core] Home Assistant Core state changed to APIState(core_state='RUNNING', offline_db_migration=False)
2024-09-04 11:31:13.149 INFO (MainThread) [supervisor.homeassistant.core] Detect a running Home Assistant instance

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

-----------------------------------------------------------

 Add-on: Matter Server
 Matter WebSocket Server for Home Assistant Matter support.
-----------------------------------------------------------
 Add-on version: 6.4.2
 You are running the latest version of this add-on.
 System: Home Assistant OS 13.1  (amd64 / generic-x86-64)
 Home Assistant Core: 2024.8.3
 Home Assistant Supervisor: 2024.08.0
-----------------------------------------------------------
 Please, share the above information when looking for help
 or support in, e.g., GitHub, forums or the Discord chat.
-----------------------------------------------------------
s6-rc: info: service banner successfully started
s6-rc: info: service matter-server: starting
s6-rc: info: service matter-server successfully started
s6-rc: info: service legacy-services: starting
[11:22:41] INFO: Starting Matter Server...
s6-rc: info: service legacy-services successfully started
[11:22:42] INFO: Using 'enp1s0' as primary network interface.
[11:22:42] INFO: Successfully send discovery information to Home Assistant.
2024-09-04 11:22:43.611 (MainThread) INFO [matter_server.server.stack] Initializing CHIP/Matter Logging...
2024-09-04 11:22:43.611 (MainThread) INFO [matter_server.server.stack] Initializing CHIP/Matter Controller Stack...
[1725441763.632948][126:126] CHIP:CTL: Setting attestation nonce to random value
[1725441763.633174][126:126] CHIP:CTL: Setting CSR nonce to random value
[1725441763.633888][126:126] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs
[1725441763.633980][126:126] CHIP:DL: writing settings to file (/tmp/chip_kvs-fWtf9K)
[1725441763.634032][126:126] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs)
[1725441763.634207][126:126] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_factory.ini
[1725441763.634306][126:126] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_config.ini
[1725441763.634337][126:126] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_counters.ini
[1725441763.634455][126:126] CHIP:DL: writing settings to file (/data/chip_counters.ini-weHh4J)
[1725441763.634593][126:126] CHIP:DL: renamed tmp file to file (/data/chip_counters.ini)
[1725441763.634601][126:126] CHIP:DL: NVS set: chip-counters/reboot-count = 35 (0x23)
[1725441763.635093][126:126] CHIP:DL: Got Ethernet interface: enp1s0
[1725441763.635399][126:126] CHIP:DL: Found the primary Ethernet interface:enp1s0
[1725441763.635702][126:126] CHIP:DL: Got WiFi interface: wlp2s0
[1725441763.635714][126:126] CHIP:DL: Failed to reset WiFi statistic counts
2024-09-04 11:22:43.635 (MainThread) INFO [chip.storage] Initializing persistent storage from file: /data/chip.json
2024-09-04 11:22:43.636 (MainThread) INFO [chip.storage] Loading configuration from /data/chip.json...
2024-09-04 11:22:43.691 (MainThread) INFO [chip.CertificateAuthority] Loading certificate authorities from storage...
2024-09-04 11:22:43.691 (MainThread) INFO [chip.CertificateAuthority] New CertificateAuthority at index 1
2024-09-04 11:22:43.692 (MainThread) INFO [chip.CertificateAuthority] Loading fabric admins from storage...
2024-09-04 11:22:43.692 (MainThread) INFO [chip.FabricAdmin] New FabricAdmin: FabricId: 0x0000000000000002, VendorId = 0x134B
2024-09-04 11:22:43.692 (MainThread) INFO [matter_server.server.stack] CHIP Controller Stack initialized.
2024-09-04 11:22:43.692 (MainThread) INFO [matter_server.server.server] Starting the Matter Server...
2024-09-04 11:22:43.695 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetching the latest PAA root certificates from DCL.
2024-09-04 11:22:47.157 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetched 62 PAA root certificates from DCL.
2024-09-04 11:22:47.158 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetching the latest PAA root certificates from Git.
2024-09-04 11:22:47.416 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetched 2 PAA root certificates from Git.
2024-09-04 11:22:47.417 (MainThread) INFO [chip.FabricAdmin] Allocating new controller with CaIndex: 1, FabricId: 0x0000000000000002, NodeId: 0x000000000001B669, CatTags: []
2024-09-04 11:22:47.504 (Dummy-2) CHIP_ERROR [chip.native.DIS] Failed to advertise records: src/inet/UDPEndPointImplSockets.cpp:416: OS Error 0x02000065: Network is unreachable
2024-09-04 11:22:47.505 (Dummy-2) CHIP_ERROR [chip.native.DIS] Failed to advertise records: src/inet/UDPEndPointImplSockets.cpp:416: OS Error 0x02000065: Network is unreachable
2024-09-04 11:22:47.518 (MainThread) INFO [matter_server.server.vendor_info] Loading vendor info from storage.
2024-09-04 11:22:47.521 (MainThread) INFO [matter_server.server.vendor_info] Loaded 241 vendors from storage.
2024-09-04 11:22:47.521 (MainThread) INFO [matter_server.server.vendor_info] Fetching the latest vendor info from DCL.
2024-09-04 11:22:47.819 (MainThread) INFO [matter_server.server.vendor_info] Fetched 242 vendors from DCL.
2024-09-04 11:22:47.820 (MainThread) INFO [matter_server.server.vendor_info] Saving vendor info to storage.
2024-09-04 11:22:47.836 (MainThread) INFO [matter_server.server.device_controller] Loaded 4 nodes from stored configuration
2024-09-04 11:22:47.844 (MainThread) INFO [matter_server.server.server] Matter Server successfully initialized.
2024-09-04 11:22:48.433 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:1> Discovered on mDNS
2024-09-04 11:22:48.433 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:5> Discovered on mDNS
2024-09-04 11:22:48.434 (MainThread) INFO [matter_server.server.device_controller] <Node:1> Setting-up node...
2024-09-04 11:22:48.434 (MainThread) INFO [matter_server.server.device_controller] <Node:5> Setting-up node...
2024-09-04 11:22:49.453 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:3> Discovered on mDNS
2024-09-04 11:22:49.454 (MainThread) INFO [matter_server.server.device_controller] <Node:3> Setting-up node...
2024-09-04 11:22:49.794 (MainThread) INFO [matter_server.server.device_controller] <Node:1> Setting up attributes and events subscription.
2024-09-04 11:22:51.159 (MainThread) INFO [matter_server.server.device_controller] <Node:5> Setting up attributes and events subscription.
2024-09-04 11:22:52.887 (MainThread) INFO [matter_server.server.device_controller] <Node:3> Setting up attributes and events subscription.
2024-09-04 11:22:54.459 (MainThread) INFO [matter_server.server.device_controller] <Node:5> Subscription succeeded with report interval [0, 300]
2024-09-04 11:22:55.700 (MainThread) INFO [matter_server.server.device_controller] <Node:3> Subscription succeeded with report interval [0, 60]
2024-09-04 11:22:56.825 (Dummy-2) CHIP_ERROR [chip.native.DMG] Time out! failed to receive report data from Exchange: 19702i with Node: <0000000000000001, 1>
2024-09-04 11:22:56.827 (MainThread) WARNING [matter_server.server.device_controller] <Node:1> Unable to subscribe to Node: src/app/ReadClient.cpp:682: CHIP Error 0x00000032: Timeout
2024-09-04 11:25:08.421 (MainThread) INFO [matter_server.server.device_controller] Starting Matter commissioning using Node ID 9 and IP fe80::dad6:68ff:fe0a:dc82%enp1s0.
2024-09-04 11:25:10.286 (Dummy-2) INFO [chip.ChipDeviceCtrl] Established secure session with Device
2024-09-04 11:25:15.841 (Dummy-2) INFO [chip.ChipDeviceCtrl] Commissioning complete
2024-09-04 11:25:15.842 (MainThread) INFO [matter_server.server.device_controller] Matter commissioning of Node ID 9 successful.
2024-09-04 11:25:15.842 (MainThread) INFO [matter_server.server.device_controller] Interviewing node: 9
2024-09-04 11:25:18.174 (MainThread) INFO [matter_server.server.device_controller] <Node:9> Setting-up node...
2024-09-04 11:25:18.176 (MainThread) INFO [matter_server.server.device_controller] <Node:9> Setting up attributes and events subscription.
2024-09-04 11:25:18.726 (MainThread) INFO [matter_server.server.device_controller] <Node:9> No new update found.
2024-09-04 11:25:19.447 (MainThread) INFO [matter_server.server.device_controller] <Node:9> Subscription succeeded with report interval [0, 60]
2024-09-04 11:25:19.447 (MainThread) INFO [matter_server.server.device_controller] Commissioning of Node ID 9 completed.
2024-09-04 11:26:23.671 (Dummy-2) CHIP_ERROR [chip.native.DMG] Subscription Liveness timeout with SubscriptionID = 0xe78009ef, Peer = 01:0000000000000009
2024-09-04 11:26:23.672 (MainThread) INFO [matter_server.server.device_controller] <Node:9> Previous subscription failed with Error: 50, re-subscribing in 0 ms...
2024-09-04 11:26:25.797 (MainThread) INFO [matter_server.server.device_controller] <Node:9> Re-Subscription succeeded
2024-09-04 11:28:45.907 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:1> Discovered on mDNS
2024-09-04 11:28:45.908 (MainThread) INFO [matter_server.server.device_controller] <Node:1> Setting-up node...
2024-09-04 11:28:45.910 (MainThread) INFO [matter_server.server.device_controller] <Node:1> Setting up attributes and events subscription.
2024-09-04 11:29:00.449 (MainThread) INFO [matter_server.server.device_controller] <Node:1> The SDK is communicating with the device using fd83:830d:1df2:1:1699:d63e:5d5c:f03f
2024-09-04 11:29:49.309 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:46575545 on exchange 19731i with Node: <0000000000000001, 1> sendCount: 4 max retries: 4
2024-09-04 11:29:58.391 (Dummy-2) CHIP_ERROR [chip.native.DMG] Time out! failed to receive report data from Exchange: 19731i with Node: <0000000000000001, 1>
2024-09-04 11:29:58.392 (MainThread) WARNING [matter_server.server.device_controller] <Node:1> Unable to subscribe to Node: src/app/ReadClient.cpp:682: CHIP Error 0x00000032: Timeout
2024-09-04 11:31:00.606 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:1> Discovered on mDNS
2024-09-04 11:31:00.606 (MainThread) INFO [matter_server.server.device_controller] <Node:1> Setting-up node...
2024-09-04 11:31:00.939 (MainThread) INFO [matter_server.server.device_controller] <Node:1> Setting up attributes and events subscription.
2024-09-04 11:31:06.973 (MainThread) INFO [matter_server.server.device_controller] <Node:1> Subscription succeeded with report interval [0, 60]
2024-09-04 11:33:02.438 (MainThread) INFO [matter_server.server.device_controller] <Node:9> No new update found.
2024-09-04 11:33:18.829 (MainThread) INFO [matter_server.server.device_controller] <Node:9> No new update found.
2024-09-04 11:41:23.089 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:9> Activity on mDNS, trigger resubscribe

Additional information

No response

agners commented 2 months ago

The update to 6.4.2 (PR #3744) only added additional settings, there is no actual change to the Matter Server. So think of it just like a restart. Maybe a restart would have brought the device back too? :thinking:

Can you update again, and if the device doesn't come back try a restart?

salos1982 commented 2 months ago

I tried restarting the matter server, restarting the home assistant but it did not help.

Only rollback to previous version helped. It's very strange that one of the bulbs works fine and the second one is not.

agners commented 2 months ago

That don't really make sense, as there is really no relevant change with the 6.4.2 update. Unless the storage corrupted somehow :thinking:

Can you try to reproduce this?

salos1982 commented 2 months ago

Yes, you are right. The issue was not in the update. Now I had a blackout and after it only 2 bulbs were recovered because they used matter over wifi. 2 devices with Matter over thread are marked offline and I do not know how to restore them without adding anew.

salos1982 commented 2 months ago

New update. my devices were discovered after some hours after blackout. But it takes quite a lot of time

github-actions[bot] commented 1 month ago

This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.