SMA Sunnyboy/Tripower Daten werden seltsam angezeigt

[mrinas]

Hm. Dann brauchen wir wohl wirklich mal logs vor m Zeitpunkt wenn das Auftritt. Hat jemand eine Idee wie man das Problem reproduzieren kann?
Würde gerne in den Logs sehen welche Daten vom WR zum Zeitpunkt des Problems geliefert werden. Dann kann man das hoffentlich irgendwie abfangen.

Das Problem ist, dass die Peaks relativ selten auftauchen. Im der Mainlog-Standarteinstellung ist davon nichts zu sehen und bei Details häufen sich schnell große Datenmengen an. Ich weiß nicht, wie groß das Log sein darf, ohne dass die SD volläuft.

Ich hatte hier viewtopic.php?p=103725#p103725 schon mal ein Detaillog bei den Peaks eingestellt, weiß aber nicht, ob das hilfreich war.

So, ich glaub ich bin da einer ganz großen Sache auf der Spur. Naja wohl eher nicht. Aber vielleicht hab ich zumindest eine Spur welche uns helfen könnte das Problem zu finden. Also keine ganz große Sache, sondern mehr ein paar Semmelbrösel auf dem Weg durch den Wald.

Ich hab' mir dein Log mal angeschaut und filtere das Log auf zwei markante Zeilen aus dem Log:
Hier wird der Wechselrichter per Modbus ausgelesen, wir sehen welche Daten ankommen:

Code: Alles auswählen

 {modules.devices.sma_sunny_boy.inverter:73} - {DEBUG:device1}

Und das sind die Werte nach der Verarbeitung und was dann tatsächlich gespeichert wird (u.a. Gerät und Komponenten, ggf. auch noch die Verrechnung mit dem Speicher je nach Hierarchie für einen HybridWR)

Code: Alles auswählen

{modules.common.store._api:30} - {INFO:component2}

Zur Orientierung in den Logs noch folgendes, device 1 ist das Gerät vom Typ SMA SunnyBoy/TriPower

Code: Alles auswählen

{DEBUG:device1} - Start device reading {'component1': <modules.devices.sma_sunny_boy.bat_smart_energy.SunnyBoySmartEnergyBat

und componente 2 dann der eigentliche Wechselrichter

Code: Alles auswählen

{DEBUG:component2} - Update Komponente ['Sma Sunny Boy/Tripower Wechselrichter']

Diese beiden Zeilen wiederholen sich alle 10s, man kann sehen wie ein wenig PV Leistung vorhanden ist und die Zähler nach und nach ganz langsam steigen:

Code: Alles auswählen

2024-03-01 11:17:52,207 - {modules.devices.sma_sunny_boy.inverter:73} - {DEBUG:device1} - WR 192.168.188.74: InverterState(currents=[0.0, 0.0, 0.0], power=18, exported=4929341, dc_power=-736)
2024-03-01 11:17:52,435 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-685.87, exported=5028532.18, dc_power=None)
2024-03-01 11:18:01,129 - {modules.devices.sma_sunny_boy.inverter:73} - {DEBUG:device1} - WR 192.168.188.74: InverterState(currents=[0.0, 0.0, 0.0], power=31, exported=4929341, dc_power=-751)
2024-03-01 11:18:01,183 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-687.93, exported=5028533.82, dc_power=None)
2024-03-01 11:18:11,743 - {modules.devices.sma_sunny_boy.inverter:73} - {DEBUG:device1} - WR 192.168.188.74: InverterState(currents=[0.0, 0.0, 0.0], power=16, exported=4929341, dc_power=-737)
2024-03-01 11:18:11,781 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-683.84, exported=5028535.91, dc_power=None)
2024-03-01 11:18:21,404 - {modules.devices.sma_sunny_boy.inverter:73} - {DEBUG:device1} - WR 192.168.188.74: InverterState(currents=[0.0, 0.0, 0.0], power=-17, exported=4929341, dc_power=-715)
2024-03-01 11:18:21,442 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-662.0, exported=5028537.86, dc_power=None)
2024-03-01 11:18:31,978 - {modules.devices.sma_sunny_boy.inverter:73} - {DEBUG:device1} - WR 192.168.188.74: InverterState(currents=[0.0, 0.0, 0.0], power=-100, exported=4929341, dc_power=-716)
2024-03-01 11:18:32,014 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-666.89, exported=5028539.64, dc_power=None)
2024-03-01 11:18:41,650 - {modules.devices.sma_sunny_boy.inverter:73} - {DEBUG:device1} - WR 192.168.188.74: InverterState(currents=[0.0, 0.0, 0.0], power=-75, exported=4929341, dc_power=-702)
2024-03-01 11:18:41,684 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-641.89, exported=5028541.16, dc_power=None)

Danach, also nach 2024-03-01 11:18:53,371 wirds dann interessant. Die erste Zeile zum Device 1 (Auslesen per Modbus) bleibt aus, im Log sieht mann das hier ein Timeout stattfindet.
Dennoch, und das ist m.E. unerwartet, wird nach der Verarbeitung sowohl PV Produktion als auch ein höherer Zählerstand gespeichert. Um das zu verdeutlichen hab' ich noch den Fehler ({pymodbus.client.sync:218} - {ERROR:device1}) mit in den Filter aufgenommen, sieht dann so aus. Sowohl Power als auch exported steigen permanent. Woher? Der WR lieferte ja keine Daten.

Code: Alles auswählen

2024-03-01 11:18:32,014 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-666.89, exported=5028539.64, dc_power=None)
2024-03-01 11:18:41,650 - {modules.devices.sma_sunny_boy.inverter:73} - {DEBUG:device1} - WR 192.168.188.74: InverterState(currents=[0.0, 0.0, 0.0], power=-75, exported=4929341, dc_power=-702)
2024-03-01 11:18:41,684 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-641.89, exported=5028541.16, dc_power=None)
2024-03-01 11:18:53,310 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:18:53,371 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-1208.78, exported=5127741.32, dc_power=None)
2024-03-01 11:19:04,022 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:19:04,083 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-1775.67, exported=5226941.48, dc_power=None)
2024-03-01 11:19:13,772 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:19:13,842 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-2342.56, exported=5326141.640000001, dc_power=None)
2024-03-01 11:19:23,493 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:19:23,562 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-2909.45, exported=5425341.800000001, dc_power=None)
2024-03-01 11:19:33,230 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:19:33,294 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-3476.3399999999997, exported=5524541.960000001, dc_power=None)
2024-03-01 11:19:43,956 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:19:44,018 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-4043.2299999999996, exported=5623742.120000001, dc_power=None)
2024-03-01 11:19:53,775 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:19:53,838 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-4610.12, exported=5722942.280000001, dc_power=None)
2024-03-01 11:20:05,401 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:20:05,464 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-5177.01, exported=5822142.440000001, dc_power=None)
2024-03-01 11:20:13,167 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:20:13,232 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-5743.900000000001, exported=5921342.6000000015, dc_power=None)
2024-03-01 11:20:23,894 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:20:23,964 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-6310.790000000001, exported=6020542.760000002, dc_power=None)
2024-03-01 11:20:33,618 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:20:33,686 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-6877.680000000001, exported=6119742.920000002, dc_power=None)
2024-03-01 11:20:43,294 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:20:43,360 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-7444.5700000000015, exported=6218943.080000002, dc_power=None)
2024-03-01 11:20:54,049 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:20:54,106 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-8011.460000000002, exported=6318143.240000002, dc_power=None)
2024-03-01 11:21:03,767 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:21:03,839 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-8578.350000000002, exported=6417343.400000002, dc_power=None)
2024-03-01 11:21:13,441 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:21:13,509 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-9145.240000000002, exported=6516543.560000002, dc_power=None)
2024-03-01 11:21:23,127 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:21:23,198 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-9712.130000000001, exported=6615743.7200000025, dc_power=None)
2024-03-01 11:21:33,914 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:21:33,978 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-10279.02, exported=6714943.880000003, dc_power=None)
2024-03-01 11:21:43,596 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:21:43,668 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-10845.91, exported=6814144.040000003, dc_power=None)
2024-03-01 11:21:53,290 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:21:53,347 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-11412.8, exported=6913344.200000003, dc_power=None)
2024-03-01 11:22:04,031 - {pymodbus.client.sync:218} - {ERROR:device1} - Connection to (192.168.188.74, 502) failed: timed out
2024-03-01 11:22:04,171 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-11979.689999999999, exported=7012544.360000003, dc_power=None)
2024-03-01 11:22:11,764 - {modules.devices.sma_sunny_boy.inverter:73} - {DEBUG:device1} - WR 192.168.188.74: InverterState(currents=[0.0, 0.0, 0.0], power=-75, exported=4929345, dc_power=-857)
2024-03-01 11:22:11,800 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-784.9, exported=5028582.29, dc_power=None)
2024-03-01 11:22:21,372 - {modules.devices.sma_sunny_boy.inverter:73} - {DEBUG:device1} - WR 192.168.188.74: InverterState(currents=[0.0, 0.0, 0.0], power=-665, exported=4929346, dc_power=-861)

Warum der Algorithmus hier Leistung sieht wo keine ausgelesen wird, und warum diese konstant steigt, kann ich leider nicht erklären.
Die Leistung steigt alle 10s um 566,89W.

Und das ist genau die verbleibende Speicherleistung

Code: Alles auswählen

2024-03-01 11:18:41,850 - {control.bat_all:221} - {DEBUG:MainThread} - Verbleibende Speicher-Leistung: 566.89W

Ich würde sagen dass die Berechnung bei Hybridspeichern (Speicher in der Hierarchie im Wechselrichter) aus dem Tritt kommt wenn der WR keine Daten liefert.

Lena, kannst Du uns hier helfen und auf Basis dieser Erkenntnis einen Blick in das verlinkte Log werfen? Vielleicht können wir somit endlich die Peaks der SMA Wechselrichter beim wöchentlichen (?) Neustart des Diagnosesystems adressieren.

[ID4U]

Das Problem ist, dass die Peaks relativ selten auftauchen.

Bei mir treten diese IMMER beim wöchentlichen "Neustart Diagnosesystem" des WR auf (Nicht-Hybrid). Seit Umstieg auf die Software 2.x.

[ID4U]

Ich würde sagen dass die Berechnung bei Hybridspeichern (Speicher in der Hierarchie im Wechselrichter) aus dem Tritt kommt wenn der WR keine Daten liefert.

Auch bei Nicht-Hybrid (STP8.0-AV) treten die Peaks auf (siehe meine Beiträge oben). Allerdings habe ich zusätzlich einen AC-Speicher (SBS2.5). Könnte das bei dem Problem eine Rolle spielen? Diesen habe ich etwa zeitgleich mit Umstieg auf die Software 2.x nachgerüstet.

Dessen Werte sind jedoch unauffällig. Es ist immer die PV-Leistung welche die Peaks erzeugt. Und auch immer nur wenn der PV-WR sein Diagnosesystem neu startet. Beim Batterie-WR passt immer alles.

Ich werde heute abend einfach mal eine Weile die Netzwerkverbindung zum PV-WR kappen und schauen ob ich damit auch die Peaks erzeugen kann. Wie kann ich sonst noch helfen?

[mrinas]

Auch bei Nicht-Hybrid (STP8.0-AV) treten die Peaks auf (siehe meine Beiträge oben). Allerdings habe ich zusätzlich einen AC-Speicher (SBS2.5). Könnte das bei dem Problem eine Rolle spielen? Diesen habe ich etwa zeitgleich mit Umstieg auf die Software 2.x nachgerüstet.

Dessen Werte sind jedoch unauffällig. Es ist immer die PV-Leistung welche die Peaks erzeugt. Und auch immer nur wenn der PV-WR sein Diagnosesystem neu startet. Beim Batterie-WR passt immer alles.

Ich werde heute abend einfach mal eine Weile die Netzwerkverbindung zum PV-WR kappen und schauen ob ich damit auch die Peaks erzeugen kann. Wie kann ich sonst noch helfen?

Mach' doch mal bitte folgendes:
* Auf den aktuellen Master aktualisieren, hier wird der Zählerstand vom SMA WR ausgelesen und nicht mehr berechnet
* Loglevel auf Ausführlich/Debug stellen
* Netzwerkverbindung am WR kappen
* Logs hier teilen

Jetzt haben wir ja zumindest eine Idee wonach wir schauen können, wäre Interessant was es in diesem Log dann zu sehen gibt.

[ID4U]

Ok, werde ich heute abend machen.

[ID4U]

Ein erster Test mit master vom 15.07.2024 (also die garantiert fehlerbehaftete Version): Eine LAN-Unterbrechung zum WR von fast 20 Minuten zeigt keine Auffälligkeiten. Es wird kein Peak erzeugt; sämtliche Werte nach dem Wiedereinstecken des LAN-Kabels sind plausibel.

Während der LAN-Trennung bleiben die Werte "power" und "exported" bei den alten Werten stehen. Es wird auch nichts hochgezählt. Erst nach dem Wiedereinstecken des LAN-Kabels werden die Werte aktualisiert. Also eigentlich alles so wie es sein soll.

Mit einer LAN-Unterbrechung kann ich die Peaks also nicht provozieren. Das einzige was mir nun noch einfällt ist den WR einmal komplett neu zu starten. Denn dann wird ja auch das Dignosesystem neu gestartet.

BTW: Außer im Log meldet die openWB nirgendwo das die Verbindung zum WR unterbrochen wurde. Auch unter Status wird kein Fehler gemeldet. Ist das normal und so gewollt oder war die Unterbrechung von etwa 20 Minuten noch zu kurz?

[mrinas]

Hm, hätte jetzt schon erwartet dass es nach 10s direkt die Fehlerbehandlung im Status gibt.

[ID4U]

Ja, ich eigentlich auch. Es bleiben im Theme (colors), via MQTT und im Status einfach die alten Werte stehen. Auch noch nach fast 20 Minuten.

Habe nun mal den WR komplett neu gestartet. Also AC und DC getrennt und nach einer Pause wieder eingeschaltet. Ebenfalls bisher alles normal und keine Peaks.

Jetzt muß ich wohl eine Woche warten bis der WR sein Diagnosesystem von selbst neu startet. Denn genau dann ist der Fehler mit den Peaks ja immer absolut verlässlich aufgetreten. Ich hoffe nur das dies dann nicht wie üblich nachts um etwa 01:30 Uhr passiert. Denn soweit reicht das Log wohl nicht zurück (ich kann nur die letzten paar Stunden sehen).

Oder hast Du noch eine andere Idee?

[ID4U]

Interessant ist auch das es immer etwas über 51.000.000 kW sind (bei mir genau 51.320.245,856 kW):

Mein Peak heute Nacht (also nach genau einer Woche) durch den "Neustart Diagnosesystem" ist übrigens mit 51.487.180,88 kW angegeben:

peak.jpg (120.85 KiB) 410 mal betrachtet

[mrinas]

Denke wir sollten nochmal Logs mit dem aktuellen master von der Problemsituation besorgen um zu verstehen was da genau passiert.