addons icon indicating copy to clipboard operation
addons copied to clipboard

Published 20 hours ago • verified publisher icon home-assistant

Eve energy device is permanently offline

Open hajar97 opened this issue 8 months ago • 6 comments

Describe the issue you are experiencing

Starting from version 6.0 or another one released from May my Eve Energy and door sensor devices are permanently offline. They work perfectly well in HomeKit and in Alexa. But stopped working in HA. I tried removing them and adding again. First it was not easy as by default adding them didn’t work and I had to do some unusually many trial and error to finally succeed. After adding them they worked for 10 mins or so and then went offline. With each full restart of HA server these devices appear for 10 mins and then go permanently offline.

As my thread border router I use AppleTV (latest firmware) that is connected using ethernet cable same as HA server (latest updates). Before release in May it used to work flawlessly. Though I found some threads of people with the same issues going earlier in the year.

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

Steps to reproduce the issue

Open list of Matter devices in HA and they all appear offline. Same if going to Matter Server web page.

System Health information

No repairs

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

None

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

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
[06:29:32] INFO: Starting Matter Server...
s6-rc: info: service legacy-services successfully started
[06:29:32] INFO: Using 'enp6s18' as primary network interface.
[06:29:32] INFO: Successfully send discovery information to Home Assistant.
2024-06-12 06:29:34.028 (MainThread) INFO [matter_server.server.stack] Initializing CHIP/Matter Logging...
2024-06-12 06:29:34.029 (MainThread) INFO [matter_server.server.stack] Initializing CHIP/Matter Controller Stack...
[1718166574.051127][126:126] CHIP:CTL: Setting attestation nonce to random value
[1718166574.051347][126:126] CHIP:CTL: Setting CSR nonce to random value
[1718166574.052159][126:126] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs
[1718166574.052275][126:126] CHIP:DL: writing settings to file (/tmp/chip_kvs-THG8XI)
[1718166574.052347][126:126] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs)
[1718166574.052536][126:126] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_factory.ini
[1718166574.052664][126:126] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_config.ini
[1718166574.052720][126:126] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /data/chip_counters.ini
[1718166574.052881][126:126] CHIP:DL: writing settings to file (/data/chip_counters.ini-K8nI9I)
[1718166574.053079][126:126] CHIP:DL: renamed tmp file to file (/data/chip_counters.ini)
[1718166574.053102][126:126] CHIP:DL: NVS set: chip-counters/reboot-count = 52 (0x34)
[1718166574.053400][126:126] CHIP:DL: Got Ethernet interface: enp6s18
[1718166574.053545][126:126] CHIP:DL: Found the primary Ethernet interface:enp6s18
[1718166574.053899][126:126] CHIP:DL: Failed to get WiFi interface
[1718166574.053911][126:126] CHIP:DL: Failed to reset WiFi statistic counts
2024-06-12 06:29:34.054 (MainThread) WARNING [PersistentStorage] Initializing persistent storage from file: /data/chip.json
2024-06-12 06:29:34.054 (MainThread) WARNING [PersistentStorage] Loading configuration from /data/chip.json...
2024-06-12 06:29:34.115 (MainThread) WARNING [CertificateAuthorityManager] Loading certificate authorities from storage...
2024-06-12 06:29:34.115 (MainThread) WARNING [CertificateAuthority] New CertificateAuthority at index 1
2024-06-12 06:29:34.116 (MainThread) WARNING [CertificateAuthority] Loading fabric admins from storage...
2024-06-12 06:29:34.116 (MainThread) WARNING [FabricAdmin] New FabricAdmin: FabricId: 0x0000000000000002, VendorId = 0x134B
2024-06-12 06:29:34.116 (MainThread) INFO [matter_server.server.stack] CHIP Controller Stack initialized.
2024-06-12 06:29:34.117 (MainThread) INFO [matter_server.server.server] Starting the Matter Server...
2024-06-12 06:29:34.119 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetching the latest PAA root certificates from DCL.
2024-06-12 06:29:37.770 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetched 58 PAA root certificates from DCL.
2024-06-12 06:29:46.063 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetched 74 PAA root certificates from Test DCL.
2024-06-12 06:29:46.063 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetching the latest PAA root certificates from Git.
2024-06-12 06:30:47.925 (MainThread) INFO [matter_server.server.helpers.paa_certificates] Fetched 5 PAA root certificates from Git.
2024-06-12 06:30:47.926 (MainThread) WARNING [FabricAdmin] Allocating new controller with CaIndex: 1, FabricId: 0x0000000000000002, NodeId: 0x000000000001B669, CatTags: []
2024-06-12 06:30:48.009 (MainThread) INFO [matter_server.server.device_controller] Loaded 3 nodes from stored configuration
2024-06-12 06:30:48.013 (MainThread) INFO [matter_server.server.vendor_info] Loading vendor info from storage.
2024-06-12 06:30:48.015 (MainThread) INFO [matter_server.server.vendor_info] Loaded 200 vendors from storage.
2024-06-12 06:30:48.016 (MainThread) INFO [matter_server.server.vendor_info] Fetching the latest vendor info from DCL.
2024-06-12 06:30:48.405 (MainThread) INFO [matter_server.server.vendor_info] Fetched 201 vendors from DCL.
2024-06-12 06:30:48.405 (MainThread) INFO [matter_server.server.vendor_info] Saving vendor info to storage.
2024-06-12 06:30:49.736 (MainThread) INFO [matter_server.server.device_controller.mdns] Node 40 discovered on MDNS
2024-06-12 06:30:49.736 (MainThread) INFO [matter_server.server.device_controller.mdns] Node 37 discovered on MDNS
2024-06-12 06:30:49.736 (MainThread) INFO [matter_server.server.device_controller.node_40] Setting-up node...
2024-06-12 06:30:49.737 (MainThread) INFO [matter_server.server.device_controller.node_37] Setting-up node...
2024-06-12 06:31:21.143 (MainThread) INFO [matter_server.server.device_controller] Interviewing node: 34
2024-06-12 06:31:32.773 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:134707281 on exchange 42912i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-12 06:31:32.803 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:134707282 on exchange 42913i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-12 06:31:38.448 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 1
2024-06-12 06:31:38.450 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 1
2024-06-12 06:31:49.147 (MainThread) INFO [matter_server.server.device_controller.node_34] Node could not be discovered on the network, returning cached IP's
2024-06-12 06:31:49.254 (MainThread) INFO [matter_server.server.device_controller.node_34] Node could not be discovered on the network, returning cached IP's
2024-06-12 06:31:52.144 (Dummy-2) CHIP_ERROR [chip.native.DIS] Timeout waiting for mDNS resolution.
2024-06-12 06:32:06.143 (Dummy-2) CHIP_ERROR [chip.native.DIS] OperationalSessionSetup[1:0000000000000022]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:119: CHIP Error 0x00000032: Timeout
2024-06-12 06:32:06.144 (MainThread) ERROR [matter_server.server.client_handler] [139754657663696] Error while handling: interview_node (node 34): Failed to interview node 34
2024-06-12 06:32:18.342 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:134707284 on exchange 42915i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-12 06:32:19.002 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:134707283 on exchange 42914i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-12 06:32:26.960 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 1
2024-06-12 06:32:26.960 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 1
2024-06-12 06:32:29.961 (MainThread) INFO [matter_server.server.sdk.node_40] Attempting to establish CASE session... (attempt 2 of 2)
2024-06-12 06:32:29.962 (MainThread) INFO [matter_server.server.sdk.node_37] Attempting to establish CASE session... (attempt 2 of 2)
2024-06-12 06:33:09.854 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:134707286 on exchange 42917i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-12 06:33:12.051 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:134707285 on exchange 42916i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-12 06:33:18.673 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 1
2024-06-12 06:33:18.675 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 1
2024-06-12 06:33:59.490 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:134707288 on exchange 42919i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-12 06:34:01.521 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:134707287 on exchange 42918i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-12 06:34:07.184 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 1
2024-06-12 06:34:07.185 (MainThread) WARNING [matter_server.server.device_controller.node_40] Setup for node failed: Unable to establish CASE session with Node 40
2024-06-12 06:34:07.186 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 1
2024-06-12 06:34:07.186 (MainThread) WARNING [matter_server.server.device_controller.node_37] Setup for node failed: Unable to establish CASE session with Node 37
2024-06-12 06:37:39.569 (MainThread) INFO [matter_server.server.device_controller.mdns] Node 37 discovered on MDNS
2024-06-12 06:37:39.569 (MainThread) INFO [matter_server.server.device_controller.mdns] Node 40 discovered on MDNS
2024-06-12 06:37:39.569 (MainThread) INFO [matter_server.server.device_controller.node_37] Setting-up node...
2024-06-12 06:37:39.571 (MainThread) INFO [matter_server.server.device_controller.node_40] Setting-up node...
2024-06-12 06:38:20.578 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:134707289 on exchange 42920i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-12 06:38:21.382 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:134707290 on exchange 42921i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-12 06:38:28.280 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 1
2024-06-12 06:38:28.282 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 1
2024-06-12 06:39:11.019 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:134707292 on exchange 42923i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-12 06:39:12.390 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:134707291 on exchange 42922i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-12 06:39:16.792 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 1
2024-06-12 06:39:16.793 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 1
2024-06-12 06:39:19.794 (MainThread) INFO [matter_server.server.sdk.node_37] Attempting to establish CASE session... (attempt 2 of 2)
2024-06-12 06:39:19.795 (MainThread) INFO [matter_server.server.sdk.node_40] Attempting to establish CASE session... (attempt 2 of 2)
2024-06-12 06:40:01.378 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:134707293 on exchange 42924i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-12 06:40:02.513 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:134707294 on exchange 42925i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-12 06:40:08.506 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 1
2024-06-12 06:40:08.508 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 1
2024-06-12 06:40:50.206 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:134707296 on exchange 42927i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-12 06:40:50.231 (Dummy-2) CHIP_ERROR [chip.native.EM] Failed to Send CHIP MessageCounter:134707295 on exchange 42926i with Node: <0000000000000000, 0> sendCount: 4 max retries: 4
2024-06-12 06:40:57.016 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 1
2024-06-12 06:40:57.017 (MainThread) WARNING [matter_server.server.device_controller.node_37] Setup for node failed: Unable to establish CASE session with Node 37
2024-06-12 06:40:57.019 (Dummy-2) CHIP_ERROR [chip.native.SC] CASESession timed out while waiting for a response from the peer. Current state was 1
2024-06-12 06:40:57.019 (MainThread) WARNING [matter_server.server.device_controller.node_40] Setup for node failed: Unable to establish CASE session with Node 40

Additional information

No response

hajar97 avatar Jun 12 '24 04:06 hajar97