Update after a few additional days of watching. The device has gone “unavailable”, sometimes for only a few minutes, other times multiple hours. There have been several unavailable periods per day for the last several days.
Btw, i did not mention, but my AppleTV is connected over switched ethernet to network and to my HA server.
Logs seem to show some issues. On inspection, I can see that my two battery-powered matter-over-thread devices are nodes 1 (Aqara P2 motion sensor) and 7 (Aqara P2 door/window sensor). I see numerous “node could not be discovered” messages, but offhand, I don’t know what it means. I’m sharing in case any has any insights.
2024-06-19 10:11:52.675 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:11:52.684 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:12:40.565 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:12:40.571 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:14:24.627 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:14:24.630 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:15:19.757 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:15:19.761 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:19:44.198 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:19:45.166 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:19:45.170 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:20:49.418 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:20:49.423 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:25:42.562 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:25:42.567 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:28:18.458 (Dummy-2) CHIP_ERROR [chip.native.DMG] Subscription Liveness timeout with SubscriptionID = 0x705901cd, Peer = 01:0000000000000007
2024-06-19 10:28:18.459 (MainThread) INFO [matter_server.server.device_controller.node_7] Previous subscription failed with Error: 50, re-subscribing in 0 ms...
2024-06-19 10:29:28.596 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:216423387 on exchange 25297i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-19 10:29:35.385 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-06-19 10:30:41.889 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:216423388 on exchange 25298i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-19 10:30:52.114 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-06-19 10:30:52.115 (Dummy-2) CHIP_ERROR [chip.native.DMG] Failed to establish CASE for re-subscription with error 'src/protocols/secure_channel/CASESession.cpp:560: CHIP Error 0x00000032: Timeout'
2024-06-19 10:30:52.117 (MainThread) INFO [matter_server.server.device_controller.node_7] Previous subscription failed with Error: 50, re-subscribing in 8939 ms...
2024-06-19 10:31:13.390 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:31:13.391 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:32:12.831 (Dummy-2) CHIP_ERROR [chip.native.DMG] Time out! failed to receive report data from Exchange: 25300i with Node: <0000000000000007, 1>
2024-06-19 10:32:12.833 (MainThread) INFO [matter_server.server.device_controller.node_7] Previous subscription failed with Error: 50, re-subscribing in 7312 ms...
2024-06-19 10:32:12.833 (MainThread) INFO [matter_server.server.device_controller] Marked node 7 as unavailable
2024-06-19 10:33:30.882 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:216423393 on exchange 25301i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-19 10:33:39.673 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-06-19 10:35:01.796 (Dummy-2) CHIP_ERROR [chip.native.DMG] Time out! failed to receive report data from Exchange: 25303i with Node: <0000000000000007, 1>
2024-06-19 10:35:01.797 (MainThread) INFO [matter_server.server.device_controller.node_7] Previous subscription failed with Error: 50, re-subscribing in 18552 ms...
2024-06-19 10:35:56.974 (Dummy-2) CHIP_ERROR [chip.native.DMG] Time out! failed to receive report data from Exchange: 25305i with Node: <0000000000000007, 1>
2024-06-19 10:35:56.976 (MainThread) INFO [matter_server.server.device_controller.node_7] Previous subscription failed with Error: 50, re-subscribing in 14472 ms...
2024-06-19 10:36:47.096 (Dummy-2) CHIP_ERROR [chip.native.DMG] Time out! failed to receive report data from Exchange: 25307i with Node: <0000000000000007, 1>
2024-06-19 10:36:47.098 (MainThread) INFO [matter_server.server.device_controller.node_7] Previous subscription failed with Error: 50, re-subscribing in 23343 ms...
2024-06-19 10:39:17.044 (Dummy-2) CHIP_ERROR [chip.native.DMG] Time out! failed to receive report data from Exchange: 25309i with Node: <0000000000000007, 1>
2024-06-19 10:39:17.046 (MainThread) INFO [matter_server.server.device_controller.node_7] Previous subscription failed with Error: 50, re-subscribing in 66835 ms...
2024-06-19 10:40:56.374 (Dummy-2) CHIP_ERROR [chip.native.DMG] Time out! failed to receive report data from Exchange: 25311i with Node: <0000000000000007, 1>
2024-06-19 10:40:56.377 (MainThread) INFO [matter_server.server.device_controller.node_7] Previous subscription failed with Error: 50, re-subscribing in 57727 ms...
2024-06-19 10:42:59.580 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:216423404 on exchange 25312i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-19 10:43:11.534 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-06-19 10:44:19.188 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:216423405 on exchange 25313i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-19 10:44:28.263 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-06-19 10:44:28.263 (Dummy-2) CHIP_ERROR [chip.native.DMG] Failed to establish CASE for re-subscription with error 'src/protocols/secure_channel/CASESession.cpp:560: CHIP Error 0x00000032: Timeout'
2024-06-19 10:44:28.265 (MainThread) INFO [matter_server.server.device_controller.node_7] Previous subscription failed with Error: 50, re-subscribing in 157440 ms...
2024-06-19 10:48:40.476 (Dummy-2) CHIP_ERROR [chip.native.DMG] Time out! failed to receive report data from Exchange: 25315i with Node: <0000000000000007, 1>
2024-06-19 10:48:40.478 (MainThread) INFO [matter_server.server.device_controller.node_7] Previous subscription failed with Error: 50, re-subscribing in 252612 ms...
2024-06-19 10:49:02.816 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:49:02.820 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:49:47.209 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:50:26.101 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:50:26.107 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:51:58.793 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:51:58.799 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:53:07.268 (MainThread) INFO [matter_server.server.device_controller] Node 7 discovered using fallback ping
2024-06-19 10:53:07.269 (MainThread) INFO [matter_server.server.device_controller.node_7] Setting-up node...
2024-06-19 10:53:09.096 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:53:09.106 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:54:11.245 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:54:11.252 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:54:14.622 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:216423408 on exchange 25316i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-19 10:54:24.199 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-06-19 10:55:29.265 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:216423409 on exchange 25317i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-19 10:55:40.925 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-06-19 10:55:40.929 (Dummy-2) CHIP_ERROR [chip.native.DMG] Failed to establish CASE for re-subscription with error 'src/protocols/secure_channel/CASESession.cpp:560: CHIP Error 0x00000032: Timeout'
2024-06-19 10:55:40.931 (MainThread) INFO [matter_server.server.device_controller.node_7] Previous subscription failed with Error: 50, re-subscribing in 411755 ms...
2024-06-19 10:55:43.933 (MainThread) INFO [matter_server.server.sdk.node_7] Attempting to establish CASE session... (attempt 2 of 2)
2024-06-19 10:56:48.985 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:216423410 on exchange 25318i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-19 10:57:00.860 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-06-19 10:57:43.317 (MainThread) INFO [matter_server.server.device_controller.node_7] Setting up attributes and events subscription.
2024-06-19 10:57:48.658 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:57:48.659 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 10:58:57.472 (Dummy-2) CHIP_ERROR [chip.native.DMG] Time out! failed to receive report data from Exchange: 25320i with Node: <0000000000000007, 1>
2024-06-19 11:00:36.449 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:122232369 on exchange 25322i with Node: <0000000000000007, 1> sendCount: 4 max retries: 4
2024-06-19 11:01:03.210 (MainThread) INFO [matter_server.server.device_controller.node_7] Subscription succeeded with report interval [0, 300]
2024-06-19 11:31:06.227 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 12:01:09.234 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 12:14:00.241 (MainThread) INFO [matter_server.server.device_controller.mdns] Node 8 re-discovered on MDNS
2024-06-19 12:14:00.242 (MainThread) INFO [matter_server.server.device_controller.node_8] Setting-up node...
2024-06-19 12:14:00.252 (MainThread) INFO [matter_server.server.device_controller.node_8] Setting up attributes and events subscription.
2024-06-19 12:14:06.634 (MainThread) INFO [matter_server.server.device_controller.node_8] Subscription succeeded with report interval [0, 60]
2024-06-19 12:31:12.243 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 13:01:15.253 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 13:31:18.263 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 14:01:21.270 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 14:31:24.281 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 14:58:32.017 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 14:58:32.020 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's
2024-06-19 15:01:27.294 (MainThread) INFO [matter_server.server.device_controller.node_1] Node could not be discovered on the network, returning cached IP's