Tibber Pulse Verbindung reißt ab

[machristoph1]

Mir ist jetzt mehrfach die Tibber Pulse Verbindung zu evcc abgerissen, zuletzt währende dem Laden (siehe Log)
Es war offensichtlich kein Pulse Problem, in der App gibt es zum Zeitpunkt des Abrisses kontinuierliche Messdaten.
Was dabei insbesondere stört, ist, dass sich evcc danach nicht wieder in einen "Normalzustand" bewegen kann.
Ich muss den Dienst neu starten, damit es wieder funktioniert.
Irgendeine Chance, dass evcc das selbst merkt und die Verbindung zum Pulse neu initialisiert?

Jan 07 23:47:07 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:47:07 ----
Jan 07 23:47:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:07 charge power: 19470W
Jan 07 23:47:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:07 charge currents: [29.1 29 29.2]A
Jan 07 23:47:07 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:47:07 grid power: 20724W
Jan 07 23:47:07 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:47:07 grid currents: [0 0 0]A
Jan 07 23:47:07 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:47:07 pv power: 0W
Jan 07 23:47:07 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:47:07 site power: 20724W
Jan 07 23:47:07 ras``picm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:07 detected active phases: 3p
Jan 07 23:47:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:07 charge total import: 13713.794kWh
Jan 07 23:47:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:07 charger status: C
Jan 07 23:47:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:07 soc estimated: 77.93% (vehicle: 65.00%)
Jan 07 23:47:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:07 vehicle soc: 78%
Jan 07 23:47:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:07 vehicle range: 267km
Jan 07 23:47:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:07 plan: charge 10m49s between 2025-01-07 23:47:08 +0100 CET until 2025-01-08 07:00:00 +0100 CET (power: 22080W, avg cost: 0.272)
Jan 07 23:47:28 raspicm evcc[1577]: [pulse ] ERROR 2025/01/07 23:47:28 Message: unable to start stream api:afeb520a2ce24c9ab87154ebcc9a6e5b for device afeb520a-2ce2-4c9a-b871-54ebcc9a6e5b; userId 73f50685-248e-419b-bfca-9163490dbe58, Locations: [{Line:1 Column:28}], Extensions: map[], Path: [liveMeasurement]
Jan 07 23:47:37 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:47:37 ----
Jan 07 23:47:37 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:37 charge power: 19480W
Jan 07 23:47:37 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:37 charge currents: [29 29 29.2]A
Jan 07 23:47:37 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:47:37 grid power: 20731W
Jan 07 23:47:37 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:47:37 grid currents: [0 0 0]A
Jan 07 23:47:37 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:47:37 pv power: 0W
Jan 07 23:47:37 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:47:37 site power: 20731W
Jan 07 23:47:37 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:37 detected active phases: 3p
Jan 07 23:47:37 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:37 charge total import: 13713.950kWh
Jan 07 23:47:37 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:37 charger status: C
Jan 07 23:47:37 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:37 soc estimated: 78.07% (vehicle: 65.00%)
Jan 07 23:47:37 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:37 vehicle soc: 78%
Jan 07 23:47:37 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:37 vehicle range: 267km
Jan 07 23:47:37 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:47:37 plan: charge 10m24s between 2025-01-07 23:47:38 +0100 CET until 2025-01-08 07:00:00 +0100 CET (power: 22080W, avg cost: 0.272)
Jan 07 23:48:07 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:48:07 ----
Jan 07 23:48:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:48:07 charge power: 19450W
Jan 07 23:48:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:48:07 charge currents: [29.1 29 29.2]A
Jan 07 23:48:07 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:48:07 grid power: 20731W
Jan 07 23:48:07 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:48:07 grid currents: [0 0 0]A
Jan 07 23:48:07 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:48:07 pv power: 0W
Jan 07 23:48:07 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:48:07 site power: 20731W
Jan 07 23:48:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:48:07 detected active phases: 3p
Jan 07 23:48:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:48:07 charge total import: 13714.114kWh
Jan 07 23:48:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:48:07 charger status: C
Jan 07 23:48:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:48:07 soc estimated: 78.21% (vehicle: 65.00%)
Jan 07 23:48:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:48:07 vehicle soc: 78%
Jan 07 23:48:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:48:07 vehicle range: 267km
Jan 07 23:48:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:48:07 plan: charge 9m57s between 2025-01-07 23:48:07 +0100 CET until 2025-01-08 07:00:00 +0100 CET (power: 22080W, avg cost: 0.272)
Jan 07 23:48:37 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:48:37 ----
Jan 07 23:48:37 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:48:37 charge power: 19450W
Jan 07 23:48:37 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:48:37 charge currents: [29.1 29 29.1]A
Jan 07 23:48:37 raspicm evcc[1577]: [site  ] ERROR 2025/01/07 23:48:37 grid power: outdated
Jan 07 23:49:07 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:49:07 ----
Jan 07 23:49:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:49:07 charge power: 19440W
Jan 07 23:49:07 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:49:07 charge currents: [28.9 29.1 29.2]A
Jan 07 23:49:07 raspicm evcc[1577]: [site  ] ERROR 2025/01/07 23:49:07 grid power: outdated
Jan 07 23:49:37 raspicm evcc[1577]: [site  ] DEBUG 2025/01/07 23:49:37 ----
Jan 07 23:49:37 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:49:37 charge power: 19480W
Jan 07 23:49:37 raspicm evcc[1577]: [lp-1  ] DEBUG 2025/01/07 23:49:37 charge currents: [29.1 29 29.1]A
Jan 07 23:49:38 raspicm evcc[1577]: [site  ] ERROR 2025/01/07 23:49:38 grid power: outdated
``

[machristoph1]

Hatte heute wieder den Fall, dass der Pulse-Stream abgerissen ist und die Tibber-Verbindung sich dann nicht mehr aufgebaut hat.
Jan 10 15:49:51 raspicm evcc[21759]: [pulse ] ERROR 2025/01/10 15:49:51 Message: unable to start stream api:afeb520a2ce> und evcc dann einen Neustart benötigt.

Ich hatte gesehen, dass hier #7314 wohl eine Lösung für ein ähnlich gelagertes Problem implementiert wurde. Könnte man das evtl. auf diese Art Verbindungsproblem erweitern?

[andig]

Gibts ein Ticket bei Tibber zu diesem API-Problem? Falls es ad hoc reproduzierbar ist könnte man mit dem Debugger rein schauen. Ohne wirds schwer...

[machristoph1]

Danke. Nein, bisher kein Ticket von mir. Aber das könnte ich natürlich mal lostreten. Habe ich noch nicht gemacht, aber ich schaue es mir mal an. Vielleicht hat ja jemand ein ähnliches Problem reportet.
Ad hoc reproduzierbar ist es leider nicht. Ich lasse den Pulse jetzt mal mit Trace laufen, vielleicht finde ich dann was.

[machristoph1]

Habe bei Tibber beim Support eine Nachricht hinterlassen, habe aber Zweifel, dass die darauf reagieren. Ein Ticket-System für Problem oder so scheint es nicht zu geben.

[andig]

Das Problem gabs ja nicht immer- irgendwas muss sich also geändert haben.

[krtimo]

Hallo,
gibt es diesbezüglich inzwischen Neuigkeiten? Ich habe die gleichen Probleme, nach einem Neustart von evcc gehts für 1 - 2 Tage wieder, bevor die Fehlermeldung "grid power: outdated" wieder kommt.
Viele Grüße, Timo

[machristoph1]

Hier ein Trace von dem Moment, wo die Verbindung abreist

Jan 13 16:37:58 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:37:58 {"id":"a78f8ac7-66fd-416b-bbfd-87ddd2bda307","type":"next","payload":{"data":{"liveMeasurement":{"power":0,"powerProduction":0,"lastMeterConsumption":26089.6032,"lastMeterProduction":0,"currentL1":null,"currentL2":null,"currentL3":null}}}} server
Jan 13 16:38:08 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:08 {"id":"a78f8ac7-66fd-416b-bbfd-87ddd2bda307","type":"complete"} client
Jan 13 16:38:08 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:08 {"type":"connection_init","payload":{"token":"***"}} client
Jan 13 16:38:08 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:08 {"type":"connection_ack"} server
Jan 13 16:38:08 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:08 {"id":"f584dea0-820e-4c85-ac9f-1a6042a32116","type":"subscribe","payload":{"query":"subscription ($homeId:ID!){liveMeasurement(homeId: $homeId){power,powerProduction,lastMeterConsumption,lastMeterProduction,currentL1,currentL2,currentL3}}","variables":{"homeId":"***"}}} client
Jan 13 16:38:08 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:08 {"id":"f584dea0-820e-4c85-ac9f-1a6042a32116","type":"next","payload":{"errors":[{"message":"unable to start stream api:afeb520a2ce24c9ab87154ebcc9a6e5b for device afeb520a-2ce2-4c9a-b871-54ebcc9a6e5b; userId 73f50685-248e-419b-bfca-9163490dbe58","locations":[{"line":1,"column":28}],"path":["liveMeasurement"]}]}} server
Jan 13 16:38:08 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:08 {"id":"f584dea0-820e-4c85-ac9f-1a6042a32116","type":"complete"} server
Jan 13 16:38:08 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:08 no running subscription. exiting... client
Jan 13 16:38:08 raspicm evcc[14020]: [pulse ] ERROR 2025/01/13 16:38:08 Message: unable to start stream api:afeb520a2ce24c9ab87154ebcc9a6e5b for device afeb520a-2ce2-4c9a-b871-54ebcc9a6e5b; userId 73f50685-248e-419b-bfca-9163490dbe58, Locations: [{Line:1 Column:28}], Extensions: map[], Path: [liveMeasurement]
Jan 13 16:38:09 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:09 {"type":"connection_init","payload":{"token":"***"}} client
Jan 13 16:38:09 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:09 {"type":"connection_init","payload":{"token":"***"}} client
Jan 13 16:38:09 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:09 {"type":"connection_init","payload":{"token":"***"}} client
Jan 13 16:38:09 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:09 {"type":"connection_init","payload":{"token":"***"}} client
Jan 13 16:38:09 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:09 {"type":"connection_init","payload":{"token":"***"}} client
Jan 13 16:38:09 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:09 {"type":"connection_init","payload":{"token":"***"}} client
Jan 13 16:38:10 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:10 {"type":"connection_init","payload":{"token":"***"}} client
Jan 13 16:38:10 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:10 {"type":"connection_init","payload":{"token":"***"}} client
Jan 13 16:38:10 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:10 {"type":"connection_init","payload":{"token":"***"}} client
Jan 13 16:38:10 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:10 {"type":"connection_init","payload":{"token":"***"}} client
Jan 13 16:38:10 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:10 {"type":"connection_init","payload":{"token":"***"}} client
Jan 13 16:38:10 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:10 {"type":"connection_init","payload":{"token":"***"}} client
Jan 13 16:38:11 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:11 {"type":"connection_init","payload":{"token":"***"}} client
Jan 13 16:38:11 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:11 {"type":"connection_ack"} server
Jan 13 16:38:11 raspicm evcc[14020]: [pulse ] TRACE 2025/01/13 16:38:11 no running subscription. exiting... client

16:37:58 geht noch, 16:38:08 nicht mehr. Ganz interessant ist, dass ich ungefähr zum gleichen Zeitpunkt auf die Oberfläche zugegriffen habe. Kann aber nicht exakt sagen, ob das genau der Zeitpunkt war.

[andig]

Für mich sieht das wie ein interner Fehler im Server aus. Als würde er plötzlich den Client nicht mehr kennen oder hätte keine Daten für ihn.

[machristoph1]

Ja, kann gut sein. Leider sehr lästig, weil man immer evcc neu starten muss.

Verstehe absolut, wenn ihr es nicht für sinnvoll haltet, solche Probleme in EVCC zu kompensieren. Finde allerdings so etwas wie einen Neustart der Netzbezugsmessung oder einen sich selbst Neustarten der Software, wenn es zu einem grid outdated Fehler kommt, tatsächlich ganz hilfreich.

[machristoph1]

Es ist auf jeden Fall unregelmäßig. Freitag hatte ich es tagsüber zweimal und in der Nacht zu Samstag auch noch zweimal. Seitdem nicht (sehe das jetzt, weil ich im 10-Minuten-Takt den Log überprüfen lasse und das Ergebnis dieser Prüfung separat mitlogge).
Interessant war, dass der Fehler Samstagnacht zweimal direkt hintereinander aufgetreten ist. EVCC war abgeschmiert mit grid power outdated. Nach dem Neustart war das 10 Minuten später direkt wieder passiert und seitdem Ruhe...
Das könnte vielleicht doch eher ein Problem auf Tibberseite sein...

[pschwed]

ich bin jetzt wieder auf 0.131.8, danach hat sich was den Pulse angeht einiges geändert. Siehe hier db8acb4
Sobald ich Probleme bekomme, sage ich hier bescheid

[andig]

Freitag hatte ich es tagsüber zweimal

Das ist ehrlich gesagt alles irrelevant. Auch wenn der Fehler von Tibber kommt- wir würden ihn gerne behandeln. Das geht aber nicht wenn wir ihn nicht im Debugger reproduzieren können. Und das fehlt... Alternative: Ticket bei Tibber und die beheben ihn...

[pschwed]

sicher, dass EVCC die Subscription nicht selbstständig terminiert (siehe meine logs oben)?

[machristoph1]

Freitag hatte ich es tagsüber zweimal

Alternative: Ticket bei Tibber und die beheben ihn...

Gibt leider keine Möglichkeit für ein Ticket bei Tibber. Und reproduzieren ist halt bei so was schwierig.

[andig]

Siehe #17925. Log eines aktuellen Nightlies könnte hilfreich sein- Update bitte im Issue.