Closed mdkeil closed 11 months ago
Kein Log. Bis dahin machen wir hier erstmal zu.
Geht mir mit zwei LPs auch so. Es wird plan als auch Standard Soc ignoriert und geladen bis das Auto nicht mehr will. Ich schau, dass ihr einen Debug Log bekommt.
Mein zweiter LP ist ein schaltbarer sonoff R2 mit tasmota geflasht, wo bei Bedarf zusätzlich mein Ladeziegel angeschlossen wird.
Hier mal mein Log.
Eingestellt waren im Plan 45% mit Abfahrt 16:15 Uhr. Manuell beendet habe ich dann bei 49%.
Hier sollte er doch eigentlich aufhören?
Dez 14 15:56:00 hades evcc[2655751]: [lp-2 ] DEBUG 2023/12/14 15:56:00 vehicle soc: 48%
Dez 14 15:56:00 hades evcc[2655751]: [lp-2 ] DEBUG 2023/12/14 15:56:00 vehicle range: 88km
Dez 14 15:56:00 hades evcc[2655751]: [lp-2 ] DEBUG 2023/12/14 15:56:00 plan: charge 0s starting at 0001-01-01 00:53:28 +0053 LMT until 2023-12-14 16:15:00 +0100 CET (power: 11040W, avg cost: NaN)
Dez 14 15:56:00 hades evcc[2655751]: [lp-2 ] DEBUG 2023/12/14 15:56:00 plan: continuing for remaining -7m57s
Dez 14 15:56:09 hades evcc[2655751]: [site ] DEBUG 2023/12/14 15:56:09 ----
Mein Log gibt es wohl erst am Samstag.. hab morgen frei und muss nicht zwingend laden.
@premultiply log habe ich oben eingefügt.. nachdem erneut der geplante soc durchgeraucht ist und auch das Auto den aktuellen Soc > planned Soc gemeldet hat (soc estimated war natürlich ebenfalls > planned soc), habe ich manuell gestopt. Was auch wieder aufgefallen ist, nachdem das Auto angeschlossen wurde, das die letze Planung nicht gelöscht wurde..
Ich bin dann erstmal wieder von der nightly weg.
@premultiply Gibt es hierzu schon was neues und braucht ihr noch was?
Mit Version 0.122.1 (19df9236)
hat sich nichts geändert.
/cc @andig
Es fehlt auch noch die Konfig mit evcc dump --cfg
...
@premultiply config habe ich oben eingefügt, obgleich es für dieses Problem meiner Ansicht nach keine relevanten Punkte enthält.
Es fehlt auch noch die Konfig mit
evcc dump --cfg
...
HIer meine, wüsste aber auch nicht was da relevantes drin steht:
Es wird weiter geladen weil Ladeziel nicht erreicht. Alles wie erwartet.
Dann verstehe ich hier was komplett nicht.
Im Tab Ankunft ist Ziel SoC 80% gesetzt.
Wenn ich jetzt im Ladeplaner 45% einstelle, dann muss er doch aufhören wenn kein PV Überschuss da ist?
Genau das passiert nämlich nicht.
Außerdem werden die 80% auch nicht eingehalten. Da sind bei mir aktuell 70 % eingetragen und das Auto war heute voll.
Es wird weiter geladen weil Ladeziel nicht erreicht. Alles wie erwartet.
nicht korrekt!
Ladeziel 65%
[site ] DEBUG 2023/12/15 14:00:19 set my_peugeot plan soc: 65 @ 2023-12-15 17:00:00 +0100 CET
Ladung beginnt
[lp-1 ] INFO 2023/12/15 14:00:40 start charging ->
[lp-1 ] DEBUG 2023/12/15 14:00:40 wake-up timer: stop
[lp-1 ] DEBUG 2023/12/15 14:00:40 soc estimated: 57.00% (vehicle: 57.00%)
[lp-1 ] DEBUG 2023/12/15 14:00:40 vehicle soc: 57%
[lp-1 ] DEBUG 2023/12/15 14:00:40 vehicle range: 144km
[lp-1 ] DEBUG 2023/12/15 14:00:40 plan: charge 22m13s starting at 2023-12-15 14:00:41 +0100 CET until 2023-12-15 17:00:00 +0100 CET (power: 11040W, avg cost: 0.236)
danach habe ich dann manuell abgebrochen-- Ladeziel ist lange überschritten..
[site ] DEBUG 2023/12/15 14:32:00 ----
[lp-1 ] DEBUG 2023/12/15 14:32:00 charge power: 10536W
[site ] DEBUG 2023/12/15 14:32:00 pv power: 889W
[site ] DEBUG 2023/12/15 14:32:00 battery soc: 20%
[site ] DEBUG 2023/12/15 14:32:00 battery power: 0W
[site ] DEBUG 2023/12/15 14:32:00 grid power: 10447W
[site ] DEBUG 2023/12/15 14:32:00 site power: 10547W
[lp-1 ] DEBUG 2023/12/15 14:32:00 charge currents: [15 15.1 15.3]A
[lp-1 ] DEBUG 2023/12/15 14:32:00 detected active phases: 3p
[lp-1 ] DEBUG 2023/12/15 14:32:00 charge total import: 6140.460kWh
[lp-1 ] DEBUG 2023/12/15 14:32:00 charger status: C
[lp-1 ] DEBUG 2023/12/15 14:32:00 soc estimated: 69.76% (vehicle: 68.00%)
[lp-1 ] DEBUG 2023/12/15 14:32:00 vehicle soc: 70%
[lp-1 ] DEBUG 2023/12/15 14:32:00 vehicle range: 186km
[lp-1 ] DEBUG 2023/12/15 14:32:00 plan: charge 0s starting at 0001-01-01 00:53:28 +0053 LMT until 2023-12-15 17:00:00 +0100 CET (power: 11040W, avg cost: NaN)
[lp-1 ] DEBUG 2023/12/15 14:32:00 plan: continuing for remaining -11m3s
Verstehe ich nicht. Dein Log oben sagt:
Dez 14 15:56:00 hades evcc[2655751]: [lp-2 ] DEBUG 2023/12/14 15:56:00 vehicle soc: 48%
Dez 14 15:56:00 hades evcc[2655751]: [lp-2 ] DEBUG 2023/12/14 15:56:00 vehicle range: 88km
Dez 14 15:56:00 hades evcc[2655751]: [lp-2 ] DEBUG 2023/12/14 15:56:00 plan: charge 0s starting at 0001-01-01 00:53:28 +0053 LMT until 2023-12-14 16:15:00 +0100 CET (power: 11040W, avg cost: NaN)
Dez 14 15:56:00 hades evcc[2655751]: [lp-2 ] DEBUG 2023/12/14 15:56:00 plan: continuing for remaining -7m57s
Da ist der soc also erst bei 48%. Laut Screenshot ist dein Session Limit 80%. Wenns genug PV und/oder Batterie gibt wird also bis 80% weiter geladen. Da wir das eh im nächsten Release nochmal komplett umkrempeln bitte neues Issue falls es dann noch auftritt.
Wenns genug PV und/oder Batterie gibt wird also bis 80% weiter geladen.
es wurde bei @deadrabbit87 aber mit 11kW weitergeladen, ohne dass es PV-Überschuss / Batterie gegeben hat.
PS: Mein Screenshot oben hatt ich wieder rausgenommen, da ich erst von einer späteren Ladesession ein log hochgeladen habe-- das Verhalten war aber das gleiche..
PPS: @deadrabbit87 und ich haben unterschiedliche Screenshots/Logs gepostet.. die aber das gleiche fehlerhafte Verhalten wiederspiegeln-- dies tritt auch nur in der nightly auf.
... und es wird auch nicht nur bis 80% weiter geladen, sondern auch darüber hinaus.
Das spiegelt jetzt mein Log nicht wieder, kann ich aber gerne bei Bedarf nachreichen.
Das spiegelt jetzt mein Log nicht wieder,
Macht bitte ein sauberes issue, mit klarer Fehlerbeschreibung, passendem Logfile. Ich habe leider keine Muße das Problem zu reverseengineeren.
Das muss doch mit diesem issue zusammenhängen, dass eine aktive Ladeplanung im nightly aktuell nicht bei erreichen einer SOC-Grenze (Session oder "global") automatisch beendet wird. Ich habe bei mir als Workaround im Auto direkt den maxSoc eingestellt bis das Problem behoben ist.. Ich habe momentan auch keine Idee, wodurch dies verursacht wird..
@andig @deadrabbit87
Ich glaube, ich habe einen möglichen Ansatzpunkt gefunden:
Ich habe in der Nacht via Planer auf 90% SOC laden lassen, maxSOC stand auf 80%.
Die Ladung wurde dieses Mal auch korrekt bei 90% beendet..
[lp-1 ] INFO 2023/12/20 05:02:08 stop charging <-
[lp-1 ] DEBUG 2023/12/20 05:02:08 charge total import: 6261.540kWh
[lp-1 ] DEBUG 2023/12/20 05:02:08 soc estimated: 90.02% (vehicle: 90.00%)
[lp-1 ] DEBUG 2023/12/20 05:02:08 vehicle soc: 90%
[lp-1 ] DEBUG 2023/12/20 05:02:08 vehicle range: 284km
[lp-1 ] DEBUG 2023/12/20 05:02:08 plan: charge 0s starting at 0001-01-01 00:53:28 +0053 LMT until 2023-12-20 07:00:00 +0100 CET (power: 11040W, avg cost: NaN)
[lp-1 ] DEBUG 2023/12/20 05:02:08 limitSoc reached: 90.0% > 80%
Unterschied zu den vorherigen Ladevorgängen war, dass der Session-SOC immer kleiner maxSOC war.. Ich habe das Gefühl, dass bei aktiver Ladung durch den Planer diese erst beendet wird, wenn der maxSOC erreicht ist, was ja auch passen würde, wenn nach dem Erreichen des Session-SOC ausreichend PV/Battery zur Verfügung stehen würde-- In der Nacht ist es aber meistens dunkel ;)
@mdkeil @andig
Ich kann dazu auch etwas beitragen. Mit dem Gedanken im Hinterkopf ein neues Issue aufzumachen, habe ich heute Nacht beide Fahrzeuge (Zoe & 208) mit einem planSoc und einen limitSoc laden lassen. Bei der Zoe waren beide Socs auf 80% gesetzt und beim 208 auf 70%.
Beide Fahrzeuge habe die Ladung dann bei 90% Soc gestoppt.
Mir sind diese 90% beim 208er schon vorher aufgefallen, dachte jedoch, dass die Ladung das Fahrzeug selbst beendet hat.
Dem ist aber nicht so, evcc ignoriert beide gesetzten Socs und stoppt die Ladung beim 90%:
Dez 20 06:27:26 hades evcc[1704017]: [lp-2 ] DEBUG 2023/12/20 06:27:26 limitSoc reached: 90.0% > 80%
und lp-1:
Dez 20 06:27:16 hades evcc[1704017]: [lp-1 ] DEBUG 2023/12/20 06:27:16 limitSoc reached: 89.8% > 70%
Siehe auch log.
@andig Soll ich nochmal ein issue aufmachen wo ich nur meine Konfiguration und Log anfüge?
Ok, dann hat es bei mir zufälliger Weise übereingestimmt, dass der Session-SOC ebenfalls auf 90% stand-- Nun die Frage, warum die geplante Ladung bis 90% durchzieht..
Edit: ich muss nachher mal schauen, was bei meinem Fahrzeug unter Planer/Ankunft/Standard Ladelimit eingestellt ist.. sollte da 90% drinnen stehen, würde dies zumindest den limitSoc von 90% erklären.
@mdkeil Wie ich oben schon geschrieben habe, ist da mir bei keinem lp 90% eingestellt. Beim 208 ist der limitSoc auf 70% und bei der Zoe auf 80%.
@andig Woher könnten die 90% kommen? Ich denke, dass ihr das irgendwo mal im Code setzt und das dann verwendet wird.
Mögt ihr mal die Antwort von http://evcc.local:7070/api/state
posten, wenn das wieder auftritt?
@naltatis Gerne. planSoc und limitSoc waren hier beim lp-0
auf 50% gesetzt:
Screenshot habe ich kurz nach dem manuellen beenden der Ladung gemacht.
Wie du vermutlich siehst, war hier der aktuelle SoC vom Fahrzeug bei 55 %. Gestartet habe ich das Zielladen bei 45 % SoC.
Ich hab heute nach auch noch mal einen Test gemacht:
Planungsziel: 75% Ladelimit: 80% Ladestand nachher: 77%
Heißt, er hat 2% mehr geladen als erwartet. Das "Problem" hier ist, dass wir nach Erreichen des Ziels in das normale PV Verhalten zurückfallen und der Disable Timer (bei mir gerade 15m weil Winter) erst abläuft, bevor er abschaltet. Heißt die 15m auf 4,2kW (3p min) machen hier die Differenz.
Ich seh das nicht als großes Problem. Allerdings wäre meine und vmtl. auch die Erwartungshaltung der meisten Nutzer, dass nach dem Erreichen des Ziels hart beendet werden sollte, wenn gerade keine Sonne scheint. Fühlt sich präziser an. Mit dem jetzigen Verhalten werden wir zwangsläufig immer mal wieder verwunderte Nutzermeldungen bekommen.
@andig kann man das einfach mit "Timer überspringen" an der Stelle lösen?
Das Verhalten wäre auch nicht schlimm.. bei @deadrabbit87 und mir ist er aber nicht in den "PV-Modus" zurück gesprungen, was den disable-timer aktivieren würde. Wenn es wieder weniger windig ist und ich meine Fahrzeuge nicht mehr bis 90%+ lade, mache ich mal weitere Test-- vielleicht hat sich das "Problem" bis dato auch erledigt. Ich update auf jeden Fall solange weiterhin täglich auf das aktuelle nightly.
@naltatis Wie von @mdkeil schon geschrieben, sind die 2-3 % nicht so schlimm. Das ist mir schon immer aufgefallen, dachte jedoch, dass das mit dem estimater zusammenhängt. Aber mit dem PV-Modus klingt das logisch.
Das war auch ein wenig missverständlich dargestellt von mir, weil ich hier nur bis 56% weiter laden habe lassen und dann manuell beendet habe. Ich bin mir jedoch sicher, dass er ohne mein eingreifen bis 90% weiter geladen hätte.
In meinem Fall wird da einfach komplett was ignoriert und immer bis 90% weiter geladen (auch wenn z.B. nur 50 % eingestellt ist).
Das ist auch nur seit eurem Umbau der Fall.
Soweit ich die PR verfolgen konnte, sollte sich auch mit der Version von heute Nacht da nichts ändern.
@deadrabbit87 das mit den 90% ist wirklich merkwürdig. Die Ausgabe vom State sieht ok aus. Da sind die Limits wie erwartet.
Kannst du mal sqlite3 ~/.evcc/evcc.db ".dump 'settings'"
ausführen und das Ergebnis hier reinpacken?
Alternative auch deine evcc.db
hier posten (Achtung Ladehistorie) oder mir per Slack schicken?
Heißt, er hat 2% mehr geladen als erwartet. Das "Problem" hier ist, dass wir nach Erreichen des Ziels in das normale PV Verhalten zurückfallen und der Disable Timer (bei mir gerade 15m weil Winter) erst abläuft, bevor er abschaltet.
@andig kann man das einfach mit "Timer überspringen" an der Stelle lösen?
Im master ist das so gelöst:
// minimum or target charging
case lp.minSocNotReached() || plannerActive:
err = lp.fastCharging()
lp.resetPhaseTimer()
lp.elapsePVTimer() // let PV mode disable immediately afterwards
Es gibt also nicht diesen Punkt, wo der planner nicht mehr aktiv ist, solange er aktiv ist wird allerdings der Timer zurück gesetzt. Deshalb versteht ich eigentlich nicht, wie nach aktivem Planner noch ein Timer aktiv sein kann?
Gibts dazu ein Logfile?
@naltatis Ich hab dir die evcc.db
geschickt.
Trotzdem hier noch die Ausgabe:
PRAGMA foreign_keys=OFF;
BEGIN TRANSACTION;
CREATE TABLE `settings` (`key` text,`value` text,PRIMARY KEY (`key`));
INSERT INTO settings VALUES('savings.started','2023-01-17T19:30:08+01:00');
INSERT INTO settings VALUES('savings.gridCharged','0.14482558694916306');
INSERT INTO settings VALUES('savings.gridCost','0.04617724218209299');
INSERT INTO settings VALUES('savings.gridSavedCost','0.045518401975366475');
INSERT INTO settings VALUES('savings.selfConsumptionCharged','0.21516479415308515');
INSERT INTO settings VALUES('savings.selfConsumptionCost','0.023668127356839378');
INSERT INTO settings VALUES('vehicle.0.targetSoc','70');
INSERT INTO settings VALUES('vehicle.0.targetEnergy','0');
INSERT INTO settings VALUES('vehicle.0.minSoc','0');
INSERT INTO settings VALUES('vehicle.0.targetTime','0001-01-01T00:00:00Z');
INSERT INTO settings VALUES('vehicle.2.targetSoc','80');
INSERT INTO settings VALUES('vehicle.2.targetEnergy','0');
INSERT INTO settings VALUES('vehicle.2.minSoc','0');
INSERT INTO settings VALUES('vehicle.2.targetTime','0001-01-01T00:00:00Z');
INSERT INTO settings VALUES('vehicle.1.targetSoc','60');
INSERT INTO settings VALUES('vehicle.1.targetEnergy','0');
INSERT INTO settings VALUES('vehicle.1.minSoc','0');
INSERT INTO settings VALUES('vehicle.1.targetTime','0001-01-01T00:00:00Z');
COMMIT;
Gibts dazu ein Logfile?
@andig Ja, siehe hier. Ist der Log von meinem Beitrag hier.
Oder meinst du ein anderes Logfile?
Gibts einen relevanten Timestamp wo das beendet hätte werden sollen damit ich das nicht suchen muss?
Beim lp-1
hier:
Dez 20 04:59:46 hades evcc[1704017]: [lp-1 ] DEBUG 2023/12/20 04:59:46 vehicle soc: 70%
und beim lp-2
hier:
Dez 20 05:13:26 hades evcc[1704017]: [lp-2 ] DEBUG 2023/12/20 05:13:26 vehicle soc: 80%
Ab da hätte der Ladevorgang gestoppt werden müssen. Wie oben schon geschrieben, war planSoc und targetSoc beim lp-1
auf 70 % gesetzt und beim lp-2
auf 80%.
Mit evcc version 0.122.1 (b61546f2)
hat heute Nacht das Zielladen wie gewünscht funktioniert.
Ich beobachte das mal, ob der Fehler damit behoben ist.
Mir ist beim Update aufgefallen, das ich noch targetSoc
und minSoc
in der yaml hatte.
Ich kann das nicht beurteilen, aber eventuell hing der Fehler auch damit zusammen. @mdkeil Wie ist das bei dir?
targetSoc und minSOC sind bei mir entweder auskommentiert oder bereits entfernt. Ich hatte in der Nacht nur via smartcost laden müssen mit Session Soc auf 90%.. Ladung wurde bei 92% beendet, was ich als normal bezeichnen würde.. wobei es nicht aussagekräftig ist, da sonst die Ladung bis 90% durchgerauscht ist.. ich schaue in der nächsten Woche weiter, wo ich wohl wieder nur bis 80% laden werde.
Ich mache mal zu. Wenns wieder passiert bitte mit Logfile melden.
Describe the bug
Seit einigen Tagen fällt mir auf, das trotz eingestelltem Ladeziel (80%) der ZielSOC morgens teilweise auf 85-90% landet. Ich polle den SOC (via flobz) alle 5min. Ich konnte also auch im flobz-Log feststellen, welcher SOC während der Ladung vom Auto gemeldet wurde. Dabei habe ich festgestellt, dass auch wenn das Auto bereits > 80% meldet, die Ladung nicht gestoppt wird.
Steps to reproduce
siehe oben; aus dem Bauch heraus würde ich sagen, dass dieses Verhalten erst aufgetreten ist, nachdem ich die poll-time (when connected) auf 5min gesetzt habe, um die Vorklimatisierung zu detektieren.. und dadurch die Regelung durcheinander zu kommen scheint.
Configuration details
Log details
20231215_evcc.log.txt
What type of operating system are you running?
Docker container
Version
0.122.1 nightly