SLZB-MR1 and SLZB-MR4U - Thread device firmware update causes DoS-like effect?

Hi,

I have been running Zigbee devices via Z2M in HAssOS for a while. At some point I switched from a USB radio adapter to an SMLIGHT SLZB-MR1 to experiment with Thread as well.

I have a few Thread devices paired, mostly IKEA, and now I got notified of firmware updates being available for the first time.

Weirdly, whenever I try to launch the OTA update, this seems:

  • To make Matter Server app CPU load spike (50+%, versus maybe 1% in normal operation)
  • To cause the SLZB-MR1 becoming unresponsive (including its web UI no longer loading)
  • To crash Z2M and OTBR apps in HAssOS

Manually stopping Matter Server under HA apps seems to resolve the issue. Weirdly, Matter Server seems to respawn even without me asking it to - whether Watchdog is active or not. Strange, but probably not the main issue here.

I initially didn't see the link with FW updates so I suspected a defect in my adapter and bought an SLZB-MR4U. It shows the same behavior.

Any idea what is going on here?

Here is the log from Matter Server at the time of trying the OTA (around 09:20):

2026-06-08 09:21:19.453 (MainThread) INFO [matter_server.server.device_controller] <Node:7> Update to software version 16842756
2026-06-08 09:21:19.697 (MainThread) INFO [matter_server.server.device_controller] <Node:7> Software update found: 1.1.4 (16842756) from UpdateSource.MAIN_NET_DCL, current 1.0.9 (16777225)).
2026-06-08 09:21:19.700 (MainThread) INFO [matter_server.server.device_controller] <Node:7> Downloading update from 'https://ota.matter.ikea.com/files/4476_32775_16842756_8716f2cc-3e39-48d2-a5d6-b41e5dcc5768.ota'
2026-06-08 09:21:19.936 (MainThread) INFO [matter_server.server.ota.provider] Update file '4476_32775_16842756_8716f2cc-3e39-48d2-a5d6-b41e5dcc5768.ota' downloaded to '/config/updates/7'
2026-06-08 09:21:19.937 (MainThread) INFO [matter_server.server.device_controller] <Node:7> Starting update using OTA Provider.
2026-06-08 09:21:19.938 (MainThread) INFO [matter_server.server.ota.provider] Starting OTA Provider
2026-06-08 09:21:19.940 (MainThread) INFO [matter_server.server.ota.provider] Commission and initialize OTA Provider
2026-06-08 09:21:20.127 (Dummy-2) INFO [chip.ChipDeviceCtrl] Established secure session with Device
2026-06-08 09:21:20.553 (Dummy-2) INFO [chip.ChipDeviceCtrl] Commissioning complete
2026-06-08 09:21:20.553 (MainThread) INFO [matter_server.server.ota.provider] OTA Provider App commissioned with node id 990007.
2026-06-08 09:21:30.981 (MainThread) INFO [matter_server.server.ota.provider] Waiting for target node update state change
2026-06-08 09:21:31.052 (MainThread) INFO [matter_server.server.ota.provider] Update state changed from <UpdateStateEnum.kIdle: 1> to <UpdateStateEnum.kQuerying: 2>
2026-06-08 09:21:33.803 (MainThread) INFO [matter_server.server.ota.provider] Update state changed from <UpdateStateEnum.kQuerying: 2> to <UpdateStateEnum.kDownloading: 4>
2026-06-08 09:21:47.339 (Dummy-2) CHIP_ERROR [chip.native.EM] <<5 [E:31280r with Node: <0000000000000013, 1> S:64403 M:218959511] (S) Msg Retransmission to 1:0000000000000013 failure (max retries:4)
2026-06-08 09:22:34.161 (MainThread) INFO [matter_server.server.ota.provider] Update state changed from <UpdateStateEnum.kDownloading: 4> to <UpdateStateEnum.kIdle: 1>
2026-06-08 09:22:34.161 (MainThread) INFO [matter_server.server.ota.provider] Node 7 update state idle, assuming done.
2026-06-08 09:22:34.163 (MainThread) INFO [matter_server.server.ota.provider] OTA update finished successfully
2026-06-08 09:22:34.163 (MainThread) INFO [matter_server.server.ota.provider] Cleaning up OTA provider
2026-06-08 09:22:34.163 (MainThread) INFO [matter_server.server.ota.provider] Terminating OTA Provider
2026-06-08 09:22:34.575 (MainThread) INFO [matter_server.server.device_controller] <Node:7> Software update found: 1.1.4 (16842756) from UpdateSource.MAIN_NET_DCL, current 1.0.9 (16777225)).
2026-06-08 09:22:44.480 (MainThread) INFO [matter_server.server.device_controller] <Node:7> Software update found: 1.1.4 (16842756) from UpdateSource.MAIN_NET_DCL, current 1.0.9 (16777225)).
2026-06-08 09:22:47.694 (MainThread) INFO [matter_server.server.device_controller] <Node:7> Update to software version 16842756
2026-06-08 09:22:47.824 (MainThread) INFO [matter_server.server.device_controller] <Node:7> Software update found: 1.1.4 (16842756) from UpdateSource.MAIN_NET_DCL, current 1.0.9 (16777225)).
2026-06-08 09:22:47.825 (MainThread) INFO [matter_server.server.device_controller] <Node:7> Downloading update from 'https://ota.matter.ikea.com/files/4476_32775_16842756_8716f2cc-3e39-48d2-a5d6-b41e5dcc5768.ota'
2026-06-08 09:22:47.978 (MainThread) INFO [matter_server.server.ota.provider] Update file '4476_32775_16842756_8716f2cc-3e39-48d2-a5d6-b41e5dcc5768.ota' downloaded to '/config/updates/7'
2026-06-08 09:22:47.979 (MainThread) INFO [matter_server.server.device_controller] <Node:7> Starting update using OTA Provider.
2026-06-08 09:22:47.979 (MainThread) INFO [matter_server.server.ota.provider] Starting OTA Provider
2026-06-08 09:22:47.979 (MainThread) INFO [matter_server.server.ota.provider] Commission and initialize OTA Provider
2026-06-08 09:22:48.110 (Dummy-2) INFO [chip.ChipDeviceCtrl] Established secure session with Device
2026-06-08 09:22:48.578 (Dummy-2) INFO [chip.ChipDeviceCtrl] Commissioning complete
2026-06-08 09:22:48.579 (MainThread) INFO [matter_server.server.ota.provider] OTA Provider App commissioned with node id 990007.
2026-06-08 09:22:58.001 (Dummy-2) CHIP_ERROR [chip.native.DMG] Subscription Liveness timeout with SubscriptionID = 0x0d830ad2, Peer = 01:0000000000000013
2026-06-08 09:22:58.003 (MainThread) INFO [matter_server.server.device_controller] <Node:19> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 0
2026-06-08 09:23:29.003 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2026-06-08 09:23:29.751 (Dummy-2) CHIP_ERROR [chip.native.DMG] Subscription Liveness timeout with SubscriptionID = 0x15adc693, Peer = 01:0000000000000001
2026-06-08 09:23:29.754 (MainThread) INFO [matter_server.server.device_controller] <Node:1> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 0
2026-06-08 09:23:43.002 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:0000000000000013]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:124: CHIP Error 0x00000032: Timeout
2026-06-08 09:23:43.002 (Dummy-2) CHIP_ERROR [chip.native.DMG] Failed to establish CASE for re-subscription with error 'src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:124: CHIP Error 0x00000032: Timeout'
2026-06-08 09:23:43.007 (MainThread) INFO [matter_server.server.device_controller] <Node:19> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 1
2026-06-08 09:24:00.758 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2026-06-08 09:24:04.202 (Dummy-2) CHIP_ERROR [chip.native.DMG] Subscription Liveness timeout with SubscriptionID = 0xf3574459, Peer = 01:0000000000000011
2026-06-08 09:24:04.204 (MainThread) INFO [matter_server.server.device_controller] <Node:17> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 0
2026-06-08 09:24:05.149 (Dummy-2) CHIP_ERROR [chip.native.DMG] Subscription Liveness timeout with SubscriptionID = 0xdefb8a08, Peer = 01:0000000000000012
2026-06-08 09:24:05.152 (MainThread) INFO [matter_server.server.device_controller] <Node:18> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 0
2026-06-08 09:24:08.815 (Dummy-2) CHIP_ERROR [chip.native.DMG] Subscription Liveness timeout with SubscriptionID = 0x2c4c6280, Peer = 01:0000000000000015
2026-06-08 09:24:08.816 (Dummy-2) CHIP_ERROR [chip.native.DIS] Re-using pending resolve entry before reply was received.
2026-06-08 09:24:08.817 (MainThread) INFO [matter_server.server.device_controller] <Node:21> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 0
2026-06-08 09:24:14.753 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:0000000000000001]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:124: CHIP Error 0x00000032: Timeout
2026-06-08 09:24:14.754 (Dummy-2) CHIP_ERROR [chip.native.DMG] Failed to establish CASE for re-subscription with error 'src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:124: CHIP Error 0x00000032: Timeout'
2026-06-08 09:24:14.755 (MainThread) INFO [matter_server.server.device_controller] <Node:1> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 1
2026-06-08 09:24:19.781 (Dummy-2) CHIP_ERROR [chip.native.DIS] Re-using pending resolve entry before reply was received.
s6-rc: info: service legacy-services: stopping
s6-rc: info: service legacy-services successfully stopped
s6-rc: info: service legacy-cont-init: stopping
s6-rc: info: service matter-server: stopping
2026-06-08 09:24:20.622 (MainThread) WARNING [aiorun] Stopping the loop
2026-06-08 09:24:20.623 (MainThread) INFO [aiorun] Entering shutdown phase.
2026-06-08 09:24:20.625 (MainThread) INFO [aiorun] Executing provided shutdown_callback.
2026-06-08 09:24:20.625 (MainThread) INFO [matter_server.server.server] Stopping the Matter Server...
2026-06-08 09:24:20.627 (MainThread) INFO [matter_server.server.client_handler] [140076156930528] Connection closed by client
s6-rc: info: service legacy-cont-init successfully stopped
s6-rc: info: service fix-attrs: stopping
s6-rc: info: service fix-attrs successfully stopped
2026-06-08 09:24:20.671 (MainThread) INFO [matter_server.server.ota.provider] Cleaning up OTA provider
2026-06-08 09:24:20.672 (MainThread) INFO [matter_server.server.ota.provider] Terminating OTA Provider
2026-06-08 09:24:20.680 (MainThread) ERROR [matter_server.server.client_handler] [140076156930528] Error while handling: update_node (node 7): Error while announcing OTA Provider to node.
2026-06-08 09:24:20.685 (MainThread) INFO [matter_server.server.stack] Shutting down the Matter stack...
2026-06-08 09:24:20.686 (MainThread) CHIP_ERROR [chip.native.CTL] Shutting down the stack...
2026-06-08 09:24:20.689 (MainThread) CHIP_ERROR [chip.native.DIS] Failed to advertise records: src/lib/dnssd/minimal_mdns/Server.cpp:344: CHIP Error 0x00000046: No endpoint was available to send the message
2026-06-08 09:24:20.690 (MainThread) CHIP_ERROR [chip.native.-] src/messaging/ExchangeMgr.cpp:185: CHIP Error 0x00000007: No unsolicited message handler at src/protocols/bdx/BdxTransferServer.cpp:55
2026-06-08 09:24:20.693 (MainThread) INFO [aiorun] Waiting for executor shutdown.
2026-06-08 09:24:20.694 (MainThread) INFO [aiorun] Shutting down async generators
2026-06-08 09:24:20.694 (MainThread) INFO [aiorun] Closing the loop.
2026-06-08 09:24:20.694 (MainThread) INFO [aiorun] Leaving. Bye!
[07:24:20] INFO: matter-server service exited with code 0 (by signal 0).
s6-rc: info: service matter-server successfully stopped
s6-rc: info: service banner: stopping
s6-rc: info: service banner successfully stopped
s6-rc: info: service s6rc-oneshot-runner: stopping
s6-rc: info: service s6rc-oneshot-runner successfully stopped
s6-rc: info: service s6rc-oneshot-runner: starting
s6-rc: info: service s6rc-oneshot-runner successfully started
s6-rc: info: service fix-attrs: starting
s6-rc: info: service banner: starting
s6-rc: info: service fix-attrs successfully started
s6-rc: info: service legacy-cont-init: starting
s6-rc: info: service legacy-cont-init successfully started
-----------------------------------------------------------
 Add-on: Matter Server
 Matter WebSocket Server for Home Assistant Matter support.
-----------------------------------------------------------
 Add-on version: 8.5.0
 You are running the latest version of this add-on.
 System: Home Assistant OS 17.3  (amd64 / qemux86-64)
 Home Assistant Core: 2026.6.1
 Home Assistant Supervisor: 2026.05.1
-----------------------------------------------------------
 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
[09:24:26] INFO: Starting Matter Server...
s6-rc: info: service legacy-services successfully started
[09:24:26] INFO: Using Python Matter Server
[09:24:27] INFO: Using 'enp1s0' as primary network interface.
[09:24:27] INFO: Successfully send discovery information to Home Assistant.
2026-06-08 09:24:28.550 (MainThread) INFO [matter_server.server.stack] Initializing CHIP/Matter Logging...
2026-06-08 09:24:28.550 (MainThread) INFO [matter_server.server.stack] Initializing CHIP/Matter Controller Stack...
[1780903468.598892][117:117] CHIP:CTL: Setting attestation nonce to random value
[1780903468.599066][117:117] CHIP:CTL: Setting CSR nonce to random value
[1780903468.601791][117:117] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs
[1780903468.604691][117:117] CHIP:DL: Wrote settings to /tmp/chip_kvs
[1780903468.604846][117:117] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_factory.ini
[1780903468.605060][117:117] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_config.ini
[1780903468.605334][117:117] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_counters.ini
[1780903468.607969][117:117] CHIP:DL: Wrote settings to /data/chip_counters.ini
[1780903468.607978][117:117] CHIP:DL: NVS set: chip-counters/reboot-count = 73 (0x49)
[1780903468.608177][117:117] CHIP:DL: Got Ethernet interface: enp1s0
[1780903468.608298][117:117] CHIP:DL: Found the primary Ethernet interface:enp1s0
[1780903468.608602][117:117] CHIP:DL: Failed to get WiFi interface
[1780903468.608608][117:117] CHIP:DL: Failed to reset WiFi statistic counts
[1780903468.608610][117:117] CHIP:PAF: WiFiPAF: WiFiPAFLayer::Init()
2026-06-08 09:24:28.608 (MainThread) INFO [chip.storage] Initializing persistent storage from file: /data/chip.json
2026-06-08 09:24:28.608 (MainThread) INFO [chip.storage] Loading configuration from /data/chip.json...
2026-06-08 09:24:28.662 (MainThread) INFO [chip.CertificateAuthority] Loading certificate authorities from storage...
2026-06-08 09:24:28.662 (MainThread) INFO [chip.CertificateAuthority] New CertificateAuthority at index 1
2026-06-08 09:24:28.663 (MainThread) INFO [chip.CertificateAuthority] Loading fabric admins from storage...
2026-06-08 09:24:28.663 (MainThread) INFO [chip.FabricAdmin] New FabricAdmin: FabricId: 0x0000000000000002, VendorId = 0x134B
2026-06-08 09:24:28.663 (MainThread) INFO [matter_server.server.stack] CHIP Controller Stack initialized.
2026-06-08 09:24:28.663 (MainThread) INFO [matter_server.server.server] Matter Server initialized
2026-06-08 09:24:28.663 (MainThread) INFO [matter_server.server.server] Using 'enp1s0' as primary interface (for link-local addresses)
2026-06-08 09:24:28.663 (MainThread) INFO [matter_server.server.server] Starting the Matter Server...
2026-06-08 09:24:28.666 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Skip fetching certificates (already fetched within the last 24h).
2026-06-08 09:24:28.666 (MainThread) INFO [chip.FabricAdmin] Allocating new controller with CaIndex: 1, FabricId: 0x0000000000000002, NodeId: 0x000000000001B669, CatTags: []
2026-06-08 09:24:28.727 (MainThread) INFO [matter_server.server.vendor_info] Loading vendor info from storage.
2026-06-08 09:24:28.730 (MainThread) INFO [matter_server.server.vendor_info] Loaded 433 vendors from storage.
2026-06-08 09:24:28.730 (MainThread) INFO [matter_server.server.vendor_info] Fetching the latest vendor info from DCL.
2026-06-08 09:24:29.137 (MainThread) INFO [matter_server.server.vendor_info] Fetched 433 vendors from DCL.
2026-06-08 09:24:29.137 (MainThread) INFO [matter_server.server.vendor_info] Saving vendor info to storage.
2026-06-08 09:24:29.146 (MainThread) INFO [matter_server.server.device_controller] Loaded 17 nodes from stored configuration
2026-06-08 09:24:29.157 (MainThread) INFO [matter_server.server.server] Matter Server successfully initialized.
2026-06-08 09:24:46.623 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:19> Discovered on mDNS
2026-06-08 09:24:46.623 (MainThread) INFO [matter_server.server.device_controller] <Node:19> Setting-up node...
2026-06-08 09:24:47.125 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:19> Discovered on mDNS
2026-06-08 09:24:48.478 (MainThread) INFO [matter_server.server.device_controller] <Node:19> Setting up attributes and events subscription.
2026-06-08 09:24:51.264 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:22> Discovered on mDNS
2026-06-08 09:24:51.264 (MainThread) INFO [matter_server.server.device_controller] <Node:22> Setting-up node...
2026-06-08 09:24:51.766 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:22> Discovered on mDNS
2026-06-08 09:24:53.733 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:21> Discovered on mDNS
2026-06-08 09:24:53.733 (MainThread) INFO [matter_server.server.device_controller] <Node:21> Setting-up node...
2026-06-08 09:24:54.211 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:1> Discovered on mDNS
2026-06-08 09:24:54.211 (MainThread) INFO [matter_server.server.device_controller] <Node:1> Setting-up node...
2026-06-08 09:24:54.235 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:21> Discovered on mDNS
2026-06-08 09:24:54.608 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:17> Discovered on mDNS
2026-06-08 09:24:54.608 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:18> Discovered on mDNS
2026-06-08 09:24:54.608 (MainThread) INFO [matter_server.server.device_controller] <Node:17> Setting-up node...
2026-06-08 09:24:54.713 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:1> Discovered on mDNS
2026-06-08 09:24:54.869 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:12> Discovered on mDNS
2026-06-08 09:24:55.109 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:17> Discovered on mDNS
2026-06-08 09:24:55.195 (MainThread) INFO [matter_server.server.device_controller] <Node:19> Subscription succeeded with report interval [1, 60]
2026-06-08 09:24:55.196 (MainThread) INFO [matter_server.server.device_controller] <Node:18> Setting-up node...
2026-06-08 09:24:55.332 (MainThread) INFO [matter_server.server.device_controller] <Node:21> Setting up attributes and events subscription.
2026-06-08 09:24:55.512 (MainThread) INFO [matter_server.server.device_controller] <Node:1> Setting up attributes and events subscription.
2026-06-08 09:24:55.700 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:18> Discovered on mDNS
2026-06-08 09:24:56.432 (MainThread) INFO [matter_server.server.device_controller] <Node:17> Setting up attributes and events subscription.
2026-06-08 09:24:56.465 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:13> Discovered on mDNS
2026-06-08 09:24:58.219 (MainThread) INFO [matter_server.server.device_controller] <Node:1> Subscription succeeded with report interval [1, 60]
2026-06-08 09:24:58.219 (MainThread) INFO [matter_server.server.device_controller] <Node:12> Setting-up node...
2026-06-08 09:24:58.722 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:12> Discovered on mDNS
2026-06-08 09:24:59.093 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:3> Discovered on mDNS
2026-06-08 09:24:59.893 (MainThread) INFO [matter_server.server.device_controller] <Node:18> Setting up attributes and events subscription.
2026-06-08 09:25:00.094 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:3> Discovered on mDNS
2026-06-08 09:25:00.094 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:12> Discovered on mDNS
2026-06-08 09:25:02.351 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:10> Discovered on mDNS
2026-06-08 09:25:04.351 (MainThread) INFO [matter_server.server.device_controller] <Node:21> Subscription succeeded with report interval [1, 60]
2026-06-08 09:25:04.351 (MainThread) INFO [matter_server.server.device_controller] <Node:13> Setting-up node...
2026-06-08 09:25:04.627 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:8> Discovered on mDNS
2026-06-08 09:25:04.853 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:13> Discovered on mDNS
2026-06-08 09:25:05.587 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:6> Discovered on mDNS
2026-06-08 09:25:05.865 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:11> Discovered on mDNS
2026-06-08 09:25:06.487 (MainThread) INFO [matter_server.server.device_controller] <Node:17> Subscription succeeded with report interval [1, 60]
2026-06-08 09:25:06.488 (MainThread) INFO [matter_server.server.device_controller] <Node:3> Setting-up node...
2026-06-08 09:25:06.930 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:7> Discovered on mDNS
2026-06-08 09:25:06.991 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:3> Discovered on mDNS
2026-06-08 09:25:08.931 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:4> Discovered on mDNS
2026-06-08 09:25:09.151 (MainThread) INFO [matter_server.server.device_controller] <Node:18> Subscription succeeded with report interval [1, 60]
2026-06-08 09:25:09.151 (MainThread) INFO [matter_server.server.device_controller] <Node:10> Setting-up node...
2026-06-08 09:25:09.254 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:5> Discovered on mDNS
2026-06-08 09:25:09.653 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:10> Discovered on mDNS
2026-06-08 09:25:09.931 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:4> Discovered on mDNS
2026-06-08 09:25:09.931 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:7> Discovered on mDNS
2026-06-08 09:25:11.703 (MainThread) INFO [matter_server.server.device_controller] <Node:12> Setting up attributes and events subscription.
2026-06-08 09:25:12.996 (MainThread) INFO [matter_server.server.device_controller] <Node:22> Setting up attributes and events subscription.
2026-06-08 09:25:13.432 (MainThread) INFO [matter_server.server.device_controller] <Node:13> Setting up attributes and events subscription.
2026-06-08 09:25:16.859 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:9> Discovered on mDNS
2026-06-08 09:25:17.601 (MainThread) INFO [matter_server.server.device_controller] <Node:3> Setting up attributes and events subscription.
2026-06-08 09:25:23.646 (MainThread) INFO [matter_server.server.device_controller] <Node:3> Subscription succeeded with report interval [0, 1800]
2026-06-08 09:25:23.647 (MainThread) INFO [matter_server.server.device_controller] <Node:8> Setting-up node...
2026-06-08 09:25:24.151 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:8> Discovered on mDNS
2026-06-08 09:25:26.372 (MainThread) INFO [matter_server.server.device_controller] <Node:13> Subscription succeeded with report interval [0, 1800]
2026-06-08 09:25:26.372 (MainThread) INFO [matter_server.server.device_controller] <Node:6> Setting-up node...
2026-06-08 09:25:26.874 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:6> Discovered on mDNS
2026-06-08 09:25:28.164 (MainThread) INFO [matter_server.server.device_controller] <Node:12> Subscription succeeded with report interval [0, 1800]
2026-06-08 09:25:28.165 (MainThread) INFO [matter_server.server.device_controller] <Node:11> Setting-up node...
2026-06-08 09:25:28.667 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:11> Discovered on mDNS
2026-06-08 09:25:30.228 (MainThread) INFO [matter_server.server.device_controller] <Node:22> Subscription succeeded with report interval [0, 900]
2026-06-08 09:25:30.229 (MainThread) INFO [matter_server.server.device_controller] <Node:7> Setting-up node...
2026-06-08 09:25:30.732 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:7> Discovered on mDNS
2026-06-08 09:25:35.921 (MainThread) INFO [matter_server.server.device_controller] <Node:8> Setting up attributes and events subscription.
2026-06-08 09:25:36.513 (MainThread) INFO [matter_server.server.device_controller] <Node:10> Setting up attributes and events subscription.
2026-06-08 09:25:36.918 (MainThread) INFO [matter_server.server.device_controller] <Node:6> Setting up attributes and events subscription.
2026-06-08 09:25:38.074 (MainThread) INFO [matter_server.server.device_controller] <Node:11> Setting up attributes and events subscription.
2026-06-08 09:25:38.870 (MainThread) INFO [matter_server.server.device_controller] <Node:7> Setting up attributes and events subscription.
2026-06-08 09:25:44.383 (MainThread) INFO [matter_server.server.device_controller] <Node:6> Subscription succeeded with report interval [0, 1800]
2026-06-08 09:25:44.383 (MainThread) INFO [matter_server.server.device_controller] <Node:4> Setting-up node...
2026-06-08 09:25:44.526 (MainThread) INFO [matter_server.server.device_controller] <Node:8> Subscription succeeded with report interval [0, 1800]
2026-06-08 09:25:44.526 (MainThread) INFO [matter_server.server.device_controller] <Node:5> Setting-up node...
2026-06-08 09:25:44.887 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:4> Discovered on mDNS
2026-06-08 09:25:45.030 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:5> Discovered on mDNS
2026-06-08 09:25:45.909 (MainThread) INFO [matter_server.server.device_controller] <Node:11> Subscription succeeded with report interval [0, 1800]
2026-06-08 09:25:45.909 (MainThread) INFO [matter_server.server.device_controller] <Node:9> Setting-up node...
2026-06-08 09:25:46.028 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:5> Discovered on mDNS
2026-06-08 09:25:46.414 (MainThread) INFO [matter_server.server.device_controller.mdns] <Node:9> Discovered on mDNS
2026-06-08 09:25:48.873 (MainThread) INFO [matter_server.server.device_controller] <Node:7> Subscription succeeded with report interval [0, 1800]
2026-06-08 09:25:51.050 (MainThread) INFO [matter_server.server.device_controller] <Node:10> Subscription succeeded with report interval [0, 1800]
2026-06-08 09:25:55.451 (MainThread) INFO [matter_server.server.device_controller] <Node:5> Setting up attributes and events subscription.
2026-06-08 09:26:00.488 (MainThread) INFO [matter_server.server.device_controller] <Node:5> Subscription succeeded with report interval [0, 1800]
2026-06-08 09:26:03.426 (MainThread) INFO [matter_server.server.device_controller] <Node:9> Setting up attributes and events subscription.
2026-06-08 09:26:08.342 (MainThread) INFO [matter_server.server.device_controller] <Node:9> Subscription succeeded with report interval [0, 1800]
2026-06-08 09:26:10.516 (MainThread) INFO [matter_server.server.device_controller] <Node:4> Setting up attributes and events subscription.
2026-06-08 09:26:16.432 (MainThread) INFO [matter_server.server.device_controller] <Node:4> Subscription succeeded with report interval [0, 1800]
2026-06-08 09:30:42.197 (Dummy-2) CHIP_ERROR [chip.native.EM] <<5 [E:31390r with Node: <0000000000000013, 1> S:40479 M:137632266] (S) Msg Retransmission to 1:0000000000000013 failure (max retries:4)
2026-06-08 09:31:52.931 (Dummy-2) CHIP_ERROR [chip.native.DMG] Subscription Liveness timeout with SubscriptionID = 0x8bdf7ee8, Peer = 01:0000000000000013
2026-06-08 09:31:52.933 (MainThread) INFO [matter_server.server.device_controller] <Node:19> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 0
2026-06-08 09:31:57.979 (MainThread) INFO [matter_server.server.device_controller] <Node:19> Re-Subscription succeeded
2026-06-08 09:34:58.884 (Dummy-2) CHIP_ERROR [chip.native.EM] <<5 [E:31445r with Node: <0000000000000013, 1> S:40496 M:205754988] (S) Msg Retransmission to 1:0000000000000013 failure (max retries:4)
2026-06-08 09:36:09.226 (Dummy-2) CHIP_ERROR [chip.native.DMG] Subscription Liveness timeout with SubscriptionID = 0xcb68fe36, Peer = 01:0000000000000013
2026-06-08 09:36:09.228 (MainThread) INFO [matter_server.server.device_controller] <Node:19> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 0
2026-06-08 09:36:14.143 (MainThread) INFO [matter_server.server.device_controller] <Node:19> Re-Subscription succeeded
2026-06-08 10:46:57.224 (Dummy-2) CHIP_ERROR [chip.native.EM] <<5 [E:31859r with Node: <0000000000000013, 1> S:40497 M:232616908] (S) Msg Retransmission to 1:0000000000000013 failure (max retries:4)
2026-06-08 10:48:08.519 (Dummy-2) CHIP_ERROR [chip.native.DMG] Subscription Liveness timeout with SubscriptionID = 0xc7f4c51f, Peer = 01:0000000000000013
2026-06-08 10:48:08.521 (MainThread) INFO [matter_server.server.device_controller] <Node:19> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 0
2026-06-08 10:48:23.334 (MainThread) INFO [matter_server.server.device_controller] <Node:19> Re-Subscription succeeded
2026-06-08 10:51:55.908 (Dummy-2) CHIP_ERROR [chip.native.EM] <<5 [E:31929r with Node: <0000000000000013, 1> S:40498 M:116581674] (S) Msg Retransmission to 1:0000000000000013 failure (max retries:4)
2026-06-08 10:53:07.010 (Dummy-2) CHIP_ERROR [chip.native.DMG] Subscription Liveness timeout with SubscriptionID = 0x1ebb162e, Peer = 01:0000000000000013
2026-06-08 10:53:07.012 (MainThread) INFO [matter_server.server.device_controller] <Node:19> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 0
2026-06-08 10:53:35.202 (Dummy-2) CHIP_ERROR [chip.native.EM] <<5 [E:42806i with Node: <0000000000000000, 0> S:0 M:152914410] (U) Msg Retransmission to 0:0000000000000000 failure (max retries:4)
2026-06-08 10:53:42.433 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from peer <0000000000000013, 1>. Current state was 4
2026-06-08 10:53:42.433 (Dummy-2) CHIP_ERROR [chip.native.DMG] Failed to establish CASE for re-subscription with error 'src/protocols/secure_channel/CASESession.cpp:594: CHIP Error 0x00000032: Timeout'
2026-06-08 10:53:42.433 (MainThread) INFO [matter_server.server.device_controller] <Node:19> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 1
2026-06-08 10:54:13.329 (Dummy-2) CHIP_ERROR [chip.native.EM] <<5 [E:42807i with Node: <0000000000000000, 0> S:0 M:152914411] (U) Msg Retransmission to 0:0000000000000000 failure (max retries:4)
2026-06-08 10:54:20.963 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from peer <0000000000000013, 1>. Current state was 4
2026-06-08 10:54:20.963 (Dummy-2) CHIP_ERROR [chip.native.DMG] Failed to establish CASE for re-subscription with error 'src/protocols/secure_channel/CASESession.cpp:594: CHIP Error 0x00000032: Timeout'
2026-06-08 10:54:20.963 (MainThread) INFO [matter_server.server.device_controller] <Node:19> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 2
2026-06-08 10:54:20.963 (MainThread) INFO [matter_server.server.device_controller] <Node:19> Marked node as unavailable
2026-06-08 10:54:31.104 (MainThread) INFO [matter_server.server.device_controller] <Node:19> Re-Subscription succeeded
 Live

OTBR shows this (it crashes and tries to restart ad infinitum, I think, until I kill Matter Server):

20:12:36.457 [N] MeshForwarder-: Failed to send IPv6 UDP msg, len:124, chksum:21b5, ecn:no, to:0e6c5e8f27e485e6, sec:no, error:NoAck, prio:net, radio:15.4
20:12:36.457 [N] MeshForwarder-:     src:[fe80:0:0:0:41d:b9c2:3b3f:b2c0]:19788
20:12:36.457 [N] MeshForwarder-:     dst:[fe80:0:0:0:c6c:5e8f:27e4:85e6]:19788
20:14:18.572 [N] MeshForwarder-: Failed to send IPv6 UDP msg, len:124, chksum:d6fb, ecn:no, to:0e6c5e8f27e485e6, sec:no, error:NoAck, prio:net, radio:15.4
20:14:18.572 [N] MeshForwarder-:     src:[fe80:0:0:0:41d:b9c2:3b3f:b2c0]:19788
20:14:18.572 [N] MeshForwarder-:     dst:[fe80:0:0:0:c6c:5e8f:27e4:85e6]:19788
20:14:19.574 [N] MeshForwarder-: Failed to send IPv6 UDP msg, len:124, chksum:44b5, ecn:no, to:0e6c5e8f27e485e6, sec:no, error:NoAck, prio:net, radio:15.4
20:14:19.574 [N] MeshForwarder-:     src:[fe80:0:0:0:41d:b9c2:3b3f:b2c0]:19788
20:14:19.574 [N] MeshForwarder-:     dst:[fe80:0:0:0:c6c:5e8f:27e4:85e6]:19788
20:14:20.569 [N] MeshForwarder-: Failed to send IPv6 UDP msg, len:124, chksum:35fd, ecn:no, to:0e6c5e8f27e485e6, sec:no, error:NoAck, prio:net, radio:15.4
20:14:20.569 [N] MeshForwarder-:     src:[fe80:0:0:0:41d:b9c2:3b3f:b2c0]:19788
20:14:20.569 [N] MeshForwarder-:     dst:[fe80:0:0:0:c6c:5e8f:27e4:85e6]:19788
21:00:04.589 [N] MeshForwarder-: Failed to send IPv6 UDP msg, len:96, chksum:fcb1, ecn:no, to:0e6c5e8f27e485e6, sec:no, error:NoAck, prio:net, radio:15.4
21:00:04.589 [N] MeshForwarder-:     src:[fe80:0:0:0:41d:b9c2:3b3f:b2c0]:19788
21:00:04.589 [N] MeshForwarder-:     dst:[fe80:0:0:0:c6c:5e8f:27e4:85e6]:19788
21:00:05.398 [N] MeshForwarder-: Failed to send IPv6 UDP msg, len:96, chksum:0bf0, ecn:no, to:0e6c5e8f27e485e6, sec:no, error:NoAck, prio:net, radio:15.4
21:00:05.398 [N] MeshForwarder-:     src:[fe80:0:0:0:41d:b9c2:3b3f:b2c0]:19788
21:00:05.398 [N] MeshForwarder-:     dst:[fe80:0:0:0:c6c:5e8f:27e4:85e6]:19788
21:00:06.752 [N] MeshForwarder-: Failed to send IPv6 UDP msg, len:96, chksum:c9c3, ecn:no, to:0e6c5e8f27e485e6, sec:no, error:NoAck, prio:net, radio:15.4
21:00:06.752 [N] MeshForwarder-:     src:[fe80:0:0:0:41d:b9c2:3b3f:b2c0]:19788
21:00:06.752 [N] MeshForwarder-:     dst:[fe80:0:0:0:c6c:5e8f:27e4:85e6]:19788
21:02:12.325 [W] P-RadioSpinel-: radio tx timeout
21:02:12.334 [W] P-RadioSpinel-: RCP failure detected
21:02:12.336 [W] P-RadioSpinel-: Trying to recover (1/2)
21:02:14.337 [W] P-SpinelDrive-: Wait for response timeout
21:02:16.339 [W] P-RadioSpinel-: Wait for response timeout
21:02:16.348 [W] P-RadioSpinel-: RCP failure detected
21:02:16.351 [W] P-RadioSpinel-: Trying to recover (2/2)
21:02:18.352 [W] P-SpinelDrive-: Wait for response timeout
21:02:20.354 [W] P-RadioSpinel-: Wait for response timeout
21:02:20.364 [W] P-RadioSpinel-: RCP failure detected
21:02:20.366 [C] P-RadioSpinel-: Too many rcp failures, exiting
21:02:20.370 [C] Platform------: RecoverFromRcpFailure() at radio_spinel.cpp:2044: Failure
[09:23:01] WARNING: otbr-agent exited with code 1 (by signal 0).
Chain OTBR_FORWARD_INGRESS (0 references)
target     prot opt source               destination         
ACCEPT     all  --  anywhere             anywhere            
otbr-ingress-deny-src
otbr-ingress-deny-src-swap
otbr-ingress-allow-dst
otbr-ingress-allow-dst-swap
Chain OTBR_FORWARD_EGRESS (0 references)
target     prot opt source               destination         
ACCEPT     all  --  anywhere             anywhere            
[09:23:02] INFO: OTBR firewall/NAT64 rules teardown completed.
s6-rc: info: service legacy-services: stopping
s6-rc: info: service legacy-services successfully stopped
s6-rc: info: service otbr-agent-rest-discovery: stopping
s6-rc: info: service otbr-agent-rest-discovery successfully stopped
s6-rc: info: service otbr-agent-configure: stopping
s6-rc: info: service otbr-agent-configure successfully stopped
s6-rc: info: service otbr-agent: stopping
s6-rc: info: service otbr-agent successfully stopped
s6-rc: info: service legacy-cont-init: stopping
s6-rc: info: service banner: stopping
s6-rc: info: service socat-otbr-tcp: stopping
s6-rc: info: service banner successfully stopped
s6-rc: info: service legacy-cont-init successfully stopped
s6-rc: info: service fix-attrs: stopping
2026/06/08 09:23:02 socat[87] W exiting on signal 15
s6-rc: info: service fix-attrs successfully stopped
s6-rc: warning: service s6rc-oneshot-runner is marked as essential, not stopping it
s6-rc: info: service socat-otbr-tcp successfully stopped
[09:23:05] INFO: Stable mode enabled.
[09:23:05] INFO: The otbr-web is disabled.
[09:23:05] INFO: Enabled socat-otbr-tcp.
/run/s6/basedir/scripts/rc.init: info: hook /etc/s6-overlay/scripts/enable-check.sh exited 0
s6-rc: info: service socat-otbr-tcp: starting
s6-rc: info: service s6rc-oneshot-runner: starting
s6-rc: info: service s6rc-oneshot-runner successfully started
s6-rc: info: service fix-attrs: starting
s6-rc: info: service banner: starting
s6-rc: info: service fix-attrs successfully started
s6-rc: info: service legacy-cont-init: starting
s6-rc: info: service legacy-cont-init successfully started
[09:23:05] INFO: Starting socat TCP client for OTBR daemon...
-----------------------------------------------------------
 Add-on: OpenThread Border Router
 OpenThread Border Router add-on
-----------------------------------------------------------
 Add-on version: 3.0.1
 You are running the latest version of this add-on.
 System: Home Assistant OS 17.3  (amd64 / qemux86-64)
 Home Assistant Core: 2026.6.1
 Home Assistant Supervisor: 2026.05.1
-----------------------------------------------------------
 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 socat-otbr-tcp successfully started
s6-rc: info: service otbr-agent: starting
[09:23:06] INFO: Migrating OTBR settings if needed...
2026-06-08 09:23:06 homeassistant asyncio[247] DEBUG Using selector: EpollSelector
2026-06-08 09:23:06 homeassistant zigpy.serial[247] DEBUG Opening a serial connection to '/tmp/ttyOTBR' (baudrate=460800, xonxoff=False, rtscts=False)
2026-06-08 09:23:06 homeassistant serialx.platforms.serial_posix[247] DEBUG Locking serial port '/tmp/ttyOTBR'
2026-06-08 09:23:06 homeassistant serialx.platforms.serial_posix[247] DEBUG Configuring serial port '/tmp/ttyOTBR'
2026-06-08 09:23:06 homeassistant serialx.platforms.serial_posix[247] DEBUG Configuring serial port: TcsetattrFlags(iflag=0, oflag=0, cflag=3248, lflag=0, ispeed=4100, ospeed=4100, cc_vmin=0, cc_vtime=0) + cc=[b'\x03', b'\x1c', b'\x7f', b'\x15', b'\x04', 0, 0, b'\x00', b'\x11', b'\x13', b'\x1a', b'\x00', b'\x12', b'\x0f', b'\x17', b'\x16', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00']
2026-06-08 09:23:06 homeassistant serialx.platforms.serial_linux[247] DEBUG Setting low latency mode: True
2026-06-08 09:23:06 homeassistant serialx.platforms.serial_linux[247] DEBUG Device does not support setting low latency
2026-06-08 09:23:06 homeassistant serialx.platforms.serial_posix[247] DEBUG Setting modem pins: ModemPins[!dtr !rts]
2026-06-08 09:23:06 homeassistant serialx.platforms.serial_posix[247] DEBUG TIOCMBIC: 0x00000006
2026-06-08 09:23:06 homeassistant serialx.platforms.serial_posix[247] DEBUG Device is not a serial port, cannot set modem pins
2026-06-08 09:23:06 homeassistant zigpy.serial[247] DEBUG Connection made: <serialx.platforms.serial_linux.LinuxSerialTransport object at 0x7ff42249c250>
2026-06-08 09:23:06 homeassistant universal_silabs_flasher.spinel[247] DEBUG Sending frame SpinelFrame(header=SpinelHeader(transaction_id=0, network_link_id=0, flag=2), command_id=<CommandID.RESET: 1>, data=b'\x02')
2026-06-08 09:23:06 homeassistant universal_silabs_flasher.spinel[247] DEBUG Sending data b'~\x80\x01\x02\xea\xf0~'
2026-06-08 09:23:06 homeassistant serialx.descriptor_transport[247] DEBUG Immediately writing b'~\x80\x01\x02\xea\xf0~'
2026-06-08 09:23:06 homeassistant serialx.descriptor_transport[247] DEBUG Sent 7 of 7 bytes
2026-06-08 09:23:08 homeassistant universal_silabs_flasher.spinel[247] DEBUG Device did not respond to reset, continuing
2026-06-08 09:23:08 homeassistant universal_silabs_flasher.spinel[247] DEBUG Sending frame SpinelFrame(header=SpinelHeader(transaction_id=3, network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x08')
2026-06-08 09:23:08 homeassistant universal_silabs_flasher.spinel[247] DEBUG Sending data b'~\x83\x02\x08\xbc\x9a~'
2026-06-08 09:23:08 homeassistant serialx.descriptor_transport[247] DEBUG Immediately writing b'~\x83\x02\x08\xbc\x9a~'
2026-06-08 09:23:08 homeassistant serialx.descriptor_transport[247] DEBUG Sent 7 of 7 bytes
2026-06-08 09:23:10 homeassistant universal_silabs_flasher.spinel[247] DEBUG Failed to send SpinelFrame(header=SpinelHeader(network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x08'), trying again in 0.10s (attempt 1 of 3)
2026-06-08 09:23:10 homeassistant universal_silabs_flasher.spinel[247] DEBUG Sending frame SpinelFrame(header=SpinelHeader(transaction_id=3, network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x08')
2026-06-08 09:23:10 homeassistant universal_silabs_flasher.spinel[247] DEBUG Sending data b'~\x83\x02\x08\xbc\x9a~'
2026-06-08 09:23:10 homeassistant serialx.descriptor_transport[247] DEBUG Immediately writing b'~\x83\x02\x08\xbc\x9a~'
2026-06-08 09:23:10 homeassistant serialx.descriptor_transport[247] DEBUG Sent 7 of 7 bytes
2026-06-08 09:23:12 homeassistant universal_silabs_flasher.spinel[247] DEBUG Failed to send SpinelFrame(header=SpinelHeader(network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x08'), trying again in 0.10s (attempt 2 of 3)
2026-06-08 09:23:12 homeassistant universal_silabs_flasher.spinel[247] DEBUG Sending frame SpinelFrame(header=SpinelHeader(transaction_id=3, network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x08')
2026-06-08 09:23:12 homeassistant universal_silabs_flasher.spinel[247] DEBUG Sending data b'~\x83\x02\x08\xbc\x9a~'
2026-06-08 09:23:12 homeassistant serialx.descriptor_transport[247] DEBUG Immediately writing b'~\x83\x02\x08\xbc\x9a~'
2026-06-08 09:23:12 homeassistant serialx.descriptor_transport[247] DEBUG Sent 7 of 7 bytes
2026-06-08 09:23:14 homeassistant universal_silabs_flasher.spinel[247] DEBUG Failed to send SpinelFrame(header=SpinelHeader(network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x08'), trying again in 0.10s (attempt 3 of 3)
2026-06-08 09:23:14 homeassistant serialx.descriptor_transport[247] DEBUG Closing at the request of the application
2026-06-08 09:23:14 homeassistant serialx.descriptor_transport[247] DEBUG Backgrounding a close request: None
2026-06-08 09:23:14 homeassistant zigpy.serial[247] DEBUG Waiting for serial port to close
2026-06-08 09:23:14 homeassistant serialx.descriptor_transport[247] DEBUG Closing connection: None
2026-06-08 09:23:14 homeassistant serialx.descriptor_transport[247] DEBUG Closing file descriptor 7
2026-06-08 09:23:14 homeassistant serialx.descriptor_transport[247] DEBUG Calling protocol `connection_lost` with exc=None
2026-06-08 09:23:14 homeassistant zigpy.serial[247] DEBUG Connection lost: None
Traceback (most recent call last):
  File "/usr/local/lib/python3.13/dist-packages/universal_silabs_flasher/spinel.py", line 260, in send_frame
    return await asyncio.shield(future)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
asyncio.exceptions.CancelledError
The above exception was the direct cause of the following exception:
Traceback (most recent call last):
  File "/usr/local/bin/migrate_otbr_settings.py", line 228, in <module>
    asyncio.run(main())
    ~~~~~~~~~~~^^^^^^^^
  File "/usr/lib/python3.13/asyncio/runners.py", line 195, in run
    return runner.run(main)
           ~~~~~~~~~~^^^^^^
  File "/usr/lib/python3.13/asyncio/runners.py", line 118, in run
    return self._loop.run_until_complete(task)
           ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^
  File "/usr/lib/python3.13/asyncio/base_events.py", line 725, in run_until_complete
    return future.result()
           ~~~~~~~~~~~~~^^
  File "/usr/local/bin/migrate_otbr_settings.py", line 156, in main
    hwaddr = await get_adapter_hardware_addr(
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
    ...<3 lines>...
    )
    ^
  File "/usr/local/bin/migrate_otbr_settings.py", line 103, in get_adapter_hardware_addr
    rsp = await protocol.send_command(
          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
    ...<2 lines>...
    )
    ^
  File "/usr/local/lib/python3.13/dist-packages/universal_silabs_flasher/spinel.py", line 292, in send_command
    return await self.send_frame(frame, **kwargs)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.13/dist-packages/universal_silabs_flasher/spinel.py", line 259, in send_frame
    async with asyncio_timeout(timeout):
               ~~~~~~~~~~~~~~~^^^^^^^^^
  File "/usr/lib/python3.13/asyncio/timeouts.py", line 116, in __aexit__
    raise TimeoutError from exc_val
TimeoutError
[09:23:14] WARNING: otbr-agent exited with code 1 (by signal 0).
Chain OTBR_FORWARD_INGRESS (0 references)
target     prot opt source               destination         
ACCEPT     all  --  anywhere             anywhere            
otbr-ingress-deny-src
otbr-ingress-deny-src-swap
otbr-ingress-allow-dst
otbr-ingress-allow-dst-swap
Chain OTBR_FORWARD_EGRESS (0 references)
target     prot opt source               destination         
ACCEPT     all  --  anywhere             anywhere            
[09:23:14] INFO: OTBR firewall/NAT64 rules teardown completed.
s6-svlisten: fatal: some services reported permanent failure or their supervisor died
s6-rc: warning: unable to start service otbr-agent: command exited 1
/run/s6/basedir/scripts/rc.init: warning: s6-rc failed to properly bring all the services up! Check your logs (in /run/uncaught-logs/current if you have in-container logging) for more information.
s6-rc: info: service legacy-cont-init: stopping
s6-rc: info: service banner: stopping
/run/s6/basedir/scripts/rc.init: fatal: stopping the container.
s6-rc: info: service socat-otbr-tcp: stopping
s6-rc: info: service banner successfully stopped
2026/06/08 09:23:14 socat[85] W exiting on signal 15
s6-rc: info: service socat-otbr-tcp successfully stopped
s6-rc: info: service legacy-cont-init successfully stopped
s6-rc: info: service fix-attrs: stopping
s6-rc: info: service fix-attrs successfully stopped
s6-rc: warning: service s6rc-oneshot-runner is marked as essential, not stopping it
[09:23:18] INFO: Stable mode enabled.
[09:23:18] INFO: The otbr-web is disabled.
[09:23:18] INFO: Enabled socat-otbr-tcp.
/run/s6/basedir/scripts/rc.init: info: hook /etc/s6-overlay/scripts/enable-check.sh exited 0
s6-rc: info: service socat-otbr-tcp: starting
s6-rc: info: service s6rc-oneshot-runner: starting
s6-rc: info: service s6rc-oneshot-runner successfully started
s6-rc: info: service fix-attrs: starting
s6-rc: info: service banner: starting
s6-rc: info: service fix-attrs successfully started
s6-rc: info: service legacy-cont-init: starting
s6-rc: info: service legacy-cont-init successfully started
[09:23:18] INFO: Starting socat TCP client for OTBR daemon...
2026/06/08 09:23:18 socat[86] W connect(7, AF=2 192.168.213.203:6638, 16): Connection refused
2026/06/08 09:23:18 socat[86] E tcp:192.168.213.203:6638: Connection refused
-----------------------------------------------------------
 Add-on: OpenThread Border Router
 OpenThread Border Router add-on
-----------------------------------------------------------
 Add-on version: 3.0.1
 You are running the latest version of this add-on.
 System: Home Assistant OS 17.3  (amd64 / qemux86-64)
 Home Assistant Core: 2026.6.1
s6-rc: info: service banner successfully started
 Home Assistant Supervisor: 2026.05.1
-----------------------------------------------------------
 Please, share the above information when looking for help
 or support in, e.g., GitHub, forums or the Discord chat.
-----------------------------------------------------------
[09:23:19] INFO: Starting socat TCP client for OTBR daemon...
2026/06/08 09:23:19 socat[171] W connect(7, AF=2 192.168.213.203:6638, 16): Connection refused
2026/06/08 09:23:19 socat[171] E tcp:192.168.213.203:6638: Connection refused
[09:23:21] INFO: Starting socat TCP client for OTBR daemon...
s6-rc: info: service socat-otbr-tcp successfully started
s6-rc: info: service otbr-agent: starting
[09:23:21] INFO: Migrating OTBR settings if needed...
2026-06-08 09:23:21 homeassistant asyncio[275] DEBUG Using selector: EpollSelector
2026-06-08 09:23:21 homeassistant zigpy.serial[275] DEBUG Opening a serial connection to '/tmp/ttyOTBR' (baudrate=460800, xonxoff=False, rtscts=False)
2026-06-08 09:23:21 homeassistant serialx.platforms.serial_posix[275] DEBUG Locking serial port '/tmp/ttyOTBR'
2026-06-08 09:23:21 homeassistant serialx.platforms.serial_posix[275] DEBUG Configuring serial port '/tmp/ttyOTBR'
2026-06-08 09:23:21 homeassistant serialx.platforms.serial_posix[275] DEBUG Configuring serial port: TcsetattrFlags(iflag=0, oflag=0, cflag=3248, lflag=0, ispeed=4100, ospeed=4100, cc_vmin=0, cc_vtime=0) + cc=[b'\x03', b'\x1c', b'\x7f', b'\x15', b'\x04', 0, 0, b'\x00', b'\x11', b'\x13', b'\x1a', b'\x00', b'\x12', b'\x0f', b'\x17', b'\x16', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00', b'\x00']
2026-06-08 09:23:21 homeassistant serialx.platforms.serial_linux[275] DEBUG Setting low latency mode: True
2026-06-08 09:23:21 homeassistant serialx.platforms.serial_linux[275] DEBUG Device does not support setting low latency
2026-06-08 09:23:21 homeassistant serialx.platforms.serial_posix[275] DEBUG Setting modem pins: ModemPins[!dtr !rts]
2026-06-08 09:23:21 homeassistant serialx.platforms.serial_posix[275] DEBUG TIOCMBIC: 0x00000006
2026-06-08 09:23:21 homeassistant serialx.platforms.serial_posix[275] DEBUG Device is not a serial port, cannot set modem pins
2026-06-08 09:23:21 homeassistant zigpy.serial[275] DEBUG Connection made: <serialx.platforms.serial_linux.LinuxSerialTransport object at 0x7f67e3148250>
2026-06-08 09:23:21 homeassistant universal_silabs_flasher.spinel[275] DEBUG Sending frame SpinelFrame(header=SpinelHeader(transaction_id=0, network_link_id=0, flag=2), command_id=<CommandID.RESET: 1>, data=b'\x02')
2026-06-08 09:23:21 homeassistant universal_silabs_flasher.spinel[275] DEBUG Sending data b'~\x80\x01\x02\xea\xf0~'
2026-06-08 09:23:21 homeassistant serialx.descriptor_transport[275] DEBUG Immediately writing b'~\x80\x01\x02\xea\xf0~'
2026-06-08 09:23:21 homeassistant serialx.descriptor_transport[275] DEBUG Sent 7 of 7 bytes
2026-06-08 09:23:23 homeassistant universal_silabs_flasher.spinel[275] DEBUG Device did not respond to reset, continuing
2026-06-08 09:23:23 homeassistant universal_silabs_flasher.spinel[275] DEBUG Sending frame SpinelFrame(header=SpinelHeader(transaction_id=3, network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x08')
2026-06-08 09:23:23 homeassistant universal_silabs_flasher.spinel[275] DEBUG Sending data b'~\x83\x02\x08\xbc\x9a~'
2026-06-08 09:23:23 homeassistant serialx.descriptor_transport[275] DEBUG Immediately writing b'~\x83\x02\x08\xbc\x9a~'
2026-06-08 09:23:23 homeassistant serialx.descriptor_transport[275] DEBUG Sent 7 of 7 bytes
2026-06-08 09:23:25 homeassistant universal_silabs_flasher.spinel[275] DEBUG Failed to send SpinelFrame(header=SpinelHeader(network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x08'), trying again in 0.10s (attempt 1 of 3)
2026-06-08 09:23:25 homeassistant universal_silabs_flasher.spinel[275] DEBUG Sending frame SpinelFrame(header=SpinelHeader(transaction_id=3, network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x08')
2026-06-08 09:23:25 homeassistant universal_silabs_flasher.spinel[275] DEBUG Sending data b'~\x83\x02\x08\xbc\x9a~'
2026-06-08 09:23:25 homeassistant serialx.descriptor_transport[275] DEBUG Immediately writing b'~\x83\x02\x08\xbc\x9a~'
2026-06-08 09:23:25 homeassistant serialx.descriptor_transport[275] DEBUG Sent 7 of 7 bytes
2026-06-08 09:23:27 homeassistant universal_silabs_flasher.spinel[275] DEBUG Failed to send SpinelFrame(header=SpinelHeader(network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x08'), trying again in 0.10s (attempt 2 of 3)
2026-06-08 09:23:27 homeassistant universal_silabs_flasher.spinel[275] DEBUG Sending frame SpinelFrame(header=SpinelHeader(transaction_id=3, network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x08')
2026-06-08 09:23:27 homeassistant universal_silabs_flasher.spinel[275] DEBUG Sending data b'~\x83\x02\x08\xbc\x9a~'
2026-06-08 09:23:27 homeassistant serialx.descriptor_transport[275] DEBUG Immediately writing b'~\x83\x02\x08\xbc\x9a~'
2026-06-08 09:23:27 homeassistant serialx.descriptor_transport[275] DEBUG Sent 7 of 7 bytes
2026/06/08 09:23:28 socat[185] W connect(7, AF=2 192.168.213.203:6638, 16): Connection refused
2026/06/08 09:23:28 socat[185] E tcp:192.168.213.203:6638: Connection refused
2026-06-08 09:23:28 homeassistant serialx.descriptor_transport[275] DEBUG Event loop woke up reader
2026-06-08 09:23:28 homeassistant serialx.descriptor_transport[275] DEBUG Received b''
2026-06-08 09:23:28 homeassistant serialx.descriptor_transport[275] DEBUG Backgrounding a close request: OSError(5, 'device disconnected or in use by another process')
2026-06-08 09:23:28 homeassistant serialx.descriptor_transport[275] DEBUG Closing connection: OSError(5, 'device disconnected or in use by another process')
2026-06-08 09:23:28 homeassistant serialx.descriptor_transport[275] DEBUG Closing file descriptor 7
2026-06-08 09:23:28 homeassistant serialx.descriptor_transport[275] DEBUG Calling protocol `connection_lost` with exc=OSError(5, 'device disconnected or in use by another process')
2026-06-08 09:23:28 homeassistant zigpy.serial[275] DEBUG Connection lost: OSError(5, 'device disconnected or in use by another process')
[09:23:28] INFO: Starting socat TCP client for OTBR daemon...
2026/06/08 09:23:28 socat[278] W connect(7, AF=2 192.168.213.203:6638, 16): Connection refused
2026/06/08 09:23:28 socat[278] E tcp:192.168.213.203:6638: Connection refused
[09:23:29] INFO: Starting socat TCP client for OTBR daemon...
2026-06-08 09:23:29 homeassistant universal_silabs_flasher.spinel[275] DEBUG Failed to send SpinelFrame(header=SpinelHeader(network_link_id=0, flag=2), command_id=<CommandID.PROP_VALUE_GET: 2>, data=b'\x08'), trying again in 0.10s (attempt 3 of 3)
2026-06-08 09:23:29 homeassistant zigpy.serial[275] DEBUG Waiting for serial port to close
Traceback (most recent call last):
  File "/usr/local/lib/python3.13/dist-packages/universal_silabs_flasher/spinel.py", line 260, in send_frame
    return await asyncio.shield(future)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
asyncio.exceptions.CancelledError
The above exception was the direct cause of the following exception:
Traceback (most recent call last):
  File "/usr/local/bin/migrate_otbr_settings.py", line 228, in <module>
    asyncio.run(main())
    ~~~~~~~~~~~^^^^^^^^
  File "/usr/lib/python3.13/asyncio/runners.py", line 195, in run
    return runner.run(main)
           ~~~~~~~~~~^^^^^^
  File "/usr/lib/python3.13/asyncio/runners.py", line 118, in run
    return self._loop.run_until_complete(task)
           ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^
  File "/usr/lib/python3.13/asyncio/base_events.py", line 725, in run_until_complete
    return future.result()
           ~~~~~~~~~~~~~^^
  File "/usr/local/bin/migrate_otbr_settings.py", line 156, in main
    hwaddr = await get_adapter_hardware_addr(
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
    ...<3 lines>...
    )
    ^
  File "/usr/local/bin/migrate_otbr_settings.py", line 103, in get_adapter_hardware_addr
    rsp = await protocol.send_command(
          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
    ...<2 lines>...
    )
    ^
  File "/usr/local/lib/python3.13/dist-packages/universal_silabs_flasher/spinel.py", line 292, in send_command
    return await self.send_frame(frame, **kwargs)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.13/dist-packages/universal_silabs_flasher/spinel.py", line 259, in send_frame
    async with asyncio_timeout(timeout):
               ~~~~~~~~~~~~~~~^^^^^^^^^
  File "/usr/lib/python3.13/asyncio/timeouts.py", line 116, in __aexit__
    raise TimeoutError from exc_val
TimeoutError
[09:23:30] WARNING: otbr-agent exited with code 1 (by signal 0).

Z2M also crashes and goes into a restart loop:

[2026-06-08 09:23:01] error: 	zh:zstack:znp: Socket error Error: read ECONNRESET
[2026-06-08 09:23:01] error: 	z2m: Adapter disconnected, stopping
[09:23:02] INFO: Preparing to start...
[09:23:02] INFO: Socat not enabled
[09:23:03] INFO: Starting Zigbee2MQTT...
Starting Zigbee2MQTT without watchdog.
[2026-06-08 09:23:07] error: 	zh:zstack:znp: Socket error Error: connect ECONNREFUSED 192.168.213.203:7638
[2026-06-08 09:23:07] error: 	z2m: Error while starting zigbee-herdsman
[2026-06-08 09:23:07] error: 	z2m: Failed to start zigbee-herdsman
[2026-06-08 09:23:07] error: 	z2m: Check https://www.zigbee2mqtt.io/guide/installation/20_zigbee2mqtt-fails-to-start_crashes-runtime.html for possible solutions
[2026-06-08 09:23:07] error: 	z2m: Exiting...
[2026-06-08 09:23:07] error: 	z2m: Error: Error while opening socket
    at Socket.<anonymous> (/app/node_modules/.pnpm/[email protected]/node_modules/zigbee-herdsman/src/adapter/z-stack/znp/znp.ts:160:24)
    at Socket.emit (node:events:531:35)
    at emitErrorNT (node:internal/streams/destroy:170:8)
    at emitErrorCloseNT (node:internal/streams/destroy:129:3)
    at processTicksAndRejections (node:internal/process/task_queues:89:21)

I have downgraded Matter Server to 8.4.0. I still need to re-add some devices (4x GRILLPLATS) to the Thread network, but so far I have no more crashes.