Subscription failed with CHIP Error Eve Power Plug

[continuousmining]

Describe the issue you are experiencing

Constantly loosing subscription of the plug, and the device going inactive in HA

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?

beta

Steps to reproduce the issue

Watch logs and see it drop, don't need to do anything.

System Health information

System Information

version	core-2024.12.4
installation_type	Home Assistant OS
dev	false
hassio	true
docker	true
user	root
virtualenv	false
python_version	3.13.0
os_name	Linux
os_version	6.6.33-haos
arch	x86_64
timezone	America/New_York
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.1
Stage	running
Available Repositories	1554
Downloaded Repositories	46

Home Assistant Cloud

logged_in	true
subscription_expiration	January 17, 2025 at 7:00 PM
relayer_connected	true
relayer_region	us-east-1
remote_enabled	true
remote_connected	true
alexa_enabled	true
google_enabled	true
cloud_ice_servers_enabled	true
remote_server	us-east-1-0.ui.nabu.casa
certificate_status	ready
instance_id	974c106cf31f46caa6098dff8df22fe7
can_reach_cert_server	ok
can_reach_cloud_auth	ok
can_reach_cloud	ok

Home Assistant Supervisor

host_os	Home Assistant OS 12.4
update_channel	stable
supervisor_version	supervisor-2024.12.0
agent_version	1.6.0
docker_version	26.1.4
disk_total	30.8 GB
disk_used	24.5 GB
healthy	true
supported	true
host_connectivity	true
supervisor_connectivity	true
ntp_synchronized	true
virtualization	oracle
board	ova
supervisor_api	ok
version_api	ok
installed_addons	Z-Wave JS (0.9.0), Z-Wave JS UI (3.18.0), Ring-MQTT with Video Streaming (5.8.0), Mosquitto broker (6.4.1), Advanced SSH & Web Terminal (19.0.0), Studio Code Server (5.17.3), UniFi Network Application (3.5.0), AppDaemon (0.16.7), WireGuard (0.10.2), Meross Local Broker Addon (0.0.1-alpha50), FTP (5.1.1), MQTT Explorer (browser-1.0.3), Frigate (Full Access) (0.14.1), Music Assistant Server (beta) (2.4.0b7), Matter Server (6.6.1), ESPHome Device Compiler (2024.11.3), Fusion (2024.10.1), OpenThread Border Router (2.12.2)

Dashboards

dashboards	8
resources	24
views	9
mode	storage

Recorder

oldest_recorder_run	December 5, 2024 at 2:00 AM
current_recorder_run	December 17, 2024 at 11:18 PM
estimated_db_size	385.42 MiB
database_engine	sqlite
database_version	3.45.3

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

No response

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

2-18 00:13:34.101 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:21508040 on exchange 27970i with Node: <0000000000000005, 1> sendCount: 4 max retries: 4
2024-12-18 00:13:36.957 (Dummy-2) CHIP_ERROR [chip.native.DMG] Time out! failed to receive report data from Exchange: 27970i with Node: <0000000000000005, 1>
2024-12-18 00:13:36.959 (MainThread) WARNING [matter_server.server.device_controller] Polling custom attribute(s) 1/319486977/319422472,1/319486977/319422474,1/319486977/319422473,1/319486977/319422475,1/319486977/319422478 for node 5 failed: src/app/ReadClient.cpp:682: CHIP Error 0x00000032: Timeout
2024-12-18 00:13:44.548 (Dummy-2) CHIP_ERROR [chip.native.DMG] Subscription Liveness timeout with SubscriptionID = 0x97c16dd0, Peer = 01:0000000000000005
2024-12-18 00:13:44.552 (MainThread) INFO [matter_server.server.device_controller] <Node:5> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 0
2024-12-18 00:13:54.797 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:22607666 on exchange 27971i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-12-18 00:13:58.078 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-12-18 00:13:58.079 (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-12-18 00:13:58.085 (MainThread) INFO [matter_server.server.device_controller] <Node:5> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 1
2024-12-18 00:14:16.356 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:22607667 on exchange 27972i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-12-18 00:14:19.077 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-12-18 00:14:29.347 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:22607668 on exchange 27973i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-12-18 00:14:32.353 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-12-18 00:14:32.354 (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-12-18 00:14:32.355 (MainThread) INFO [matter_server.server.device_controller] <Node:5> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 2
2024-12-18 00:14:32.356 (MainThread) INFO [matter_server.server.device_controller] <Node:5> Marked node as unavailable
2024-12-18 00:14:32.356 (MainThread) WARNING [matter_server.server.device_controller] Polling custom attribute(s) 1/319486977/319422472,1/319486977/319422474,1/319486977/319422473,1/319486977/319422475,1/319486977/319422478 for node 5 failed: src/protocols/secure_channel/CASESession.cpp:560: CHIP Error 0x00000032: Timeout
2024-12-18 00:14:49.492 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:22607669 on exchange 27974i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-12-18 00:14:53.136 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-12-18 00:15:03.476 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:22607670 on exchange 27975i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-12-18 00:15:06.407 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-12-18 00:15:06.409 (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-12-18 00:15:06.410 (MainThread) INFO [matter_server.server.device_controller] <Node:5> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 3
2024-12-18 00:15:25.098 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:22607671 on exchange 27976i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-12-18 00:15:27.954 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-12-18 00:15:27.955 (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-12-18 00:15:27.958 (MainThread) INFO [matter_server.server.device_controller] <Node:5> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 4
2024-12-18 00:15:57.643 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:22607672 on exchange 27977i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-12-18 00:16:00.673 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-12-18 00:16:00.673 (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-12-18 00:16:00.678 (MainThread) INFO [matter_server.server.device_controller] <Node:5> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 5
2024-12-18 00:16:37.885 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:22607673 on exchange 27978i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-12-18 00:16:41.261 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-12-18 00:16:51.503 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:22607674 on exchange 27979i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-12-18 00:16:54.534 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-12-18 00:16:54.536 (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-12-18 00:16:54.537 (MainThread) INFO [matter_server.server.device_controller] <Node:5> Subscription failed with CHIP Error 0x00000032: Timeout, resubscription attempt 6
2024-12-18 00:17:58.745 (MainThread) INFO [matter_server.server.device_controller] <Node:5> Re-Subscription succeeded

Additional information

The Eve Plug drops and then comes back after 6 tries. It says its on the latest firmware.

[agners]

Are you using Apple Border routers? If yes, you likelyi suffer an issue with Apple Thread border routers, tracked also here: home-assistant/core#123835.

If not, what is your Thread network setup?

[agners]

@renehagen this report is about subscription issue, which is quite different from commissioning/onboarding issue. But FWIW, "PASESession timed out while waiting for a response from the peer. Expected message type was 33" is quite well known issue and typically means that your on-boarding code is (no longer) valid. Please retry by get a new sharing code. If you still belive this is a Home Assistant Matter implementation issue, please open a new issue with your system and network details.

[renehagen]

@agners Since the device can be added to other controllers and another device also has a problem with the commissioning in Home Assistant Matter I still think it is a commissioning issue. I will investigate further and file a new issue when needed.

Thanks.

[continuousmining]

ZBT, no apple.

…

On Wed, Dec 18, 2024 at 9:17 AM renehagen ***@***.***> wrote: @agners <https://github.com/agners> Since the device can be added to other controllers and another device also has a problem with the commissioning in Home Assistant Matter I still think it is a commissioning issue. I will investigate further and file a new issue when needed. Thanks. — Reply to this email directly, view it on GitHub <#3868 (comment)>, or unsubscribe <https://github.com/notifications/unsubscribe-auth/AHDC633NG367QJFHLMK3CKT2GF7WFAVCNFSM6AAAAABTZ4PFGCVHI2DSMVQWIX3LMV43OSLTON2WKQ3PNVWWK3TUHMZDKNJRGQZTKOJXGA> . You are receiving this because you authored the thread.Message ID: ***@***.***>

[continuousmining]

Checking lots this morning. New errors and the Eve is completely dead.

2024-12-18 11:28:34.231 (MainThread) INFO [matter_server.server.device_controller] Node:5 Retrying node setup in 60 seconds...
2024-12-18 11:29:34.233 (MainThread) INFO [matter_server.server.device_controller] Node:5 Setting-up node...
2024-12-18 11:29:44.040 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:22608264 on exchange 29015i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-12-18 11:29:47.710 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-12-18 11:29:58.151 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:22608265 on exchange 29016i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-12-18 11:30:00.983 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-12-18 11:30:03.988 (MainThread) INFO [matter_server.server.sdk] Node:5 Attempting to establish CASE session... (attempt 2 of 2)
2024-12-18 11:30:14.911 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:22608266 on exchange 29017i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-12-18 11:30:17.576 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-12-18 11:30:17.577 (MainThread) WARNING [matter_server.server.device_controller] Node:5 Setup for node failed: Unable to establish CASE session with Node 5
2024-12-18 11:30:17.577 (MainThread) INFO [matter_server.server.device_controller] Node:5 Retrying node setup in 60 seconds...
2024-12-18 11:31:17.578 (MainThread) INFO [matter_server.server.device_controller] Node:5 Setting-up node...
2024-12-18 11:31:28.225 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:22608267 on exchange 29018i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-12-18 11:31:31.055 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-12-18 11:31:40.932 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:22608268 on exchange 29019i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-12-18 11:31:44.334 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-12-18 11:31:47.336 (MainThread) INFO [matter_server.server.sdk] Node:5 Attempting to establish CASE session... (attempt 2 of 2)
2024-12-18 11:31:57.900 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:22608269 on exchange 29020i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-12-18 11:32:00.810 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-12-18 11:32:11.165 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:22608270 on exchange 29021i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-12-18 11:32:14.088 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 4
2024-12-18 11:32:14.090 (MainThread) WARNING [matter_server.server.device_controller] Node:5 Setup for node failed: Unable to establish CASE session with Node 5
2024-12-18 11:32:14.091 (MainThread) INFO [matter_server.server.device_controller] Node:5 Retrying node setup in 60 seconds...

[agners]

I have multiple Eve Power Plugs connected to the latest Home Assistant Matter Server using a OpenThread Border Router with the Connect ZBT-1. So there isn't a generic issue with that combination, this must be something environment specific.

How far is your plug from the Connect ZBT-1? Did you use a USB extension cable for the Connect ZBT-1 to avoid interference etc?

Can you share the log of the OpenThread Border Router add-on?

[continuousmining]

Yes I am using the extension cable. It is not super close, the ZBT is in the basement, and this is on the 1st floor.