poohnet

Wow, super 🤩 Ich baue dann morgen mal eine neue Version und teste weiter… Gruß und ein schönes Wochenende Thomas

Ok, das ist tatsächlich interessant. Ich habe nochmal in die alten Logs geschaut und kann bestätigen, dass überall da, wo es ein Problem beim Start des Ladevorgangs gab, kein "StartTransaction.req" geschickt wurde. Ich versuche mal, ob ich das durch eine längere Wartezeit zwischen Verbinden und Start reproduziert bekomme...

Moin @rtrbt, nachdem ich jetzt einige erfolgreiche Ladevorgänge über eCarUp durchführen konnte, ist gestern Abend wieder ein ähnliches Problem wie in Test 1 aufgetreten, das nur durch ein Zurücksetzen des OCPP-Status gelöst werden konnte: 17:38 - Fahrzeug verbunden --> eCarUp-Status wechselt von "Frei" auf "Verbunden" ✔️ 17:40 - Ladung per EVCC freigeschaltet (Min+PV) --> EVCC-Status wechselt auf "Ladebereit, warte auf Fahrzeug" ✔️ 17:40 - Ladung per eCarUp-App gestartet --> WARP startet Ladung, eCarUp-Status wechselt kurz auf "Besetzt", springt dann aber auf "Verbunden" zurück ❌ 17:43 - Fahrzeug getrennt --> keine Heartbeats mehr ❌ 17:46 - WARP neugestartet --> "RESTORED StopTransaction.req" wird bei jedem Neustart gesendet, es kommen aber trotzdem keine Heartbeats ❌ 17:49 - OCPP-Status zurückgesetzt u. WARP neugestartet --> Heartbeats kommen wieder an ✔️ Wenn ich das Protokoll richtig interpretiere, dann war die Zeit zwischen dem Verbinden des Fahrzeugs und dem Start der Ladung über die eCarUp-App zu lang, sodass nach dem Status "Preparing" schon ein "Finishing" geschickt wurde: 2022-11-24 17:38:10,993 Sending Heartbeat.req. 525637161 525637161 525697161 60000 2022-11-24 17:38:11,093 Received Heartbeat.conf for connector 0 2022-11-24 17:38:19,790 IDLE -> NO_TAG 2022-11-24 17:38:19,790 Sending StatusNotification.req: Status Preparing for connector 1 2022-11-24 17:38:19,888 Received StatusNotification.conf for connector 1 2022-11-24 17:38:20,206 Charger state changed from 0 to 1 2022-11-24 17:39:10,899 Sending Heartbeat.req. 525697161 525697161 525757161 60000 2022-11-24 17:39:11,000 Received Heartbeat.conf for connector 0 2022-11-24 17:39:19,797 Tag timeout! 2022-11-24 17:39:19,797 NO_TAG -> FINISHING_UNLOCKED 2022-11-24 17:39:19,798 Sending StatusNotification.req: Status Finishing for connector 1 2022-11-24 17:39:19,907 Received StatusNotification.conf for connector 1 2022-11-24 17:40:10,901 Sending Heartbeat.req. 525757163 525757163 525817163 60000 2022-11-24 17:40:10,997 Received Heartbeat.conf for connector 0 2022-11-24 17:40:33,080 Evaluating charging profiles 2022-11-24 17:40:33,080 Connector 1 2022-11-24 17:40:33,080 Profile evaluation done. Distributing limit 2022-11-24 17:40:33,091 Currents distributed: 2022-11-24 17:40:33,091 ConnID Allowed Phases MinRate 2022-11-24 17:40:33,102 0 32.000 3 0.000 2022-11-24 17:40:33,102 1 32.000 3 0.000 2022-11-24 17:40:33,102 Next check: never 2022-11-24 17:40:33,113 2022-11-24 17:40:33,113 Setting connector 1 limit to 32000 2022-11-24 17:40:55,180 Received RemoteStartTransaction.req for connector 1 and tag E7ZYQ3X3G2FAAVJTWWDA 2022-11-24 17:40:55,180 Sending RemoteStartTransaction.conf Accepted 2022-11-24 17:40:55,196 FINISHING_UNLOCKED -> TRANSACTION 2022-11-24 17:40:55,263 Sending StatusNotification.req: Status SuspendedEVSE for connector 1 2022-11-24 17:40:55,364 Received StatusNotification.conf for connector 1 2022-11-24 17:40:55,371 Sending StatusNotification.req: Status SuspendedEV for connector 1 2022-11-24 17:40:55,460 Received StatusNotification.conf for connector 1 2022-11-24 17:40:56,303 Charger state changed from 1 to 2 2022-11-24 17:40:56,379 Tracked start of charge. 2022-11-24 17:40:57,230 Sending StatusNotification.req: Status Charging for connector 1 2022-11-24 17:40:57,318 Received StatusNotification.conf for connector 1 2022-11-24 17:40:57,385 Charger state changed from 2 to 3 2022-11-24 17:41:10,929 Sending Heartbeat.req. 525817189 525817189 525877189 60000 2022-11-24 17:41:11,021 Received Heartbeat.conf for connector 0 2022-11-24 17:42:10,977 Sending Heartbeat.req. 525877259 525877259 525937259 60000 2022-11-24 17:42:11,077 Received Heartbeat.conf for connector 0 2022-11-24 17:43:07,152 Sending StatusNotification.req: Status SuspendedEV for connector 1 2022-11-24 17:43:07,252 Received StatusNotification.conf for connector 1 2022-11-24 17:43:07,404 Charger state changed from 3 to 2 2022-11-24 17:43:10,932 Sending Heartbeat.req. 525937292 525937292 525997292 60000 2022-11-24 17:43:11,035 Received Heartbeat.conf for connector 0 2022-11-24 17:43:11,205 Unexpected EVSEState 0 while Connector is in state 8. Aborting transaction! 2022-11-24 17:43:11,205 TRANSACTION -> FINISHING_NO_CABLE_UNLOCKED 2022-11-24 17:43:11,251 Sending StopTransaction.req at connector 1 for tag E7ZYQ3X3G2FAAVJTWWDA at 739.726 kWh. StopReason 1 2022-11-24 17:43:11,285 Sending StatusNotification.req: Status Finishing for connector 1 2022-11-24 17:43:11,302 Evaluating charging profiles 2022-11-24 17:43:11,302 Connector 1 2022-11-24 17:43:11,303 Profile evaluation done. Distributing limit 2022-11-24 17:43:11,313 Currents distributed: 2022-11-24 17:43:11,313 ConnID Allowed Phases MinRate 2022-11-24 17:43:11,324 0 32.000 3 0.000 2022-11-24 17:43:11,324 1 32.000 3 0.000 2022-11-24 17:43:11,335 Next check: never 2022-11-24 17:43:11,335 2022-11-24 17:43:11,335 Setting connector 1 limit to 32000 2022-11-24 17:43:11,398 Charger state changed from 2 to 0 2022-11-24 17:43:11,486 Tracked end of charge. 2022-11-24 17:43:11,639 FINISHING_NO_CABLE_UNLOCKED -> IDLE 2022-11-24 17:43:11,639 Sending StatusNotification.req: Status Available for connector 1 2022-11-24 17:44:10,948 Sending Heartbeat.req. 525997344 525997344 526057344 60000 2022-11-24 17:45:11,011 Sending Heartbeat.req. 526057407 526057407 526117407 60000 Kann es sein, dass WARP und eCarUp hier aus dem Tritt kommen? Gruß Thomas

Ah, jetzt, ja… 😉 Hut ab, ihr treibt ja wirklich einen immensen Aufwand, die Bugs zu jagen! Interessant ist aber, dass sich eCarUp und WARP so verheddern, dass nur ein Zurücksetzen des OCPP-Status das Problem löst. Ich bin gespannt, was die Jungs zu dem Bugreport sagen… In der Zwischenzeit werde ich jetzt mal zwei Dinge testen: „normale“ Ladevorgänge, d. h. verbinden, starten, stoppen, trennen. Mal schau‘n, ob ich den Fall aus Test 2 auch irgendwie reproduziert bekomme. Aufbau einer VM mit SteVe und gleiche Tests Gruß Thomas

Sehr gut 👍 Ich habe es heute Morgen auch nochmal probiert und konnte den Fehlerfall aus Test 1 wieder reproduzieren. Man muss nur eine Ladung starten, beenden und wieder starten, ohne das Ladekabel zwischenzeitlich zu trennen. Brauchst du das Debug-Log mit den neuen Informationen zum OCPP-Zustand? Ja, kann ich bestätigen 😀

Vielen Dank für deine Unterstützung Erik, ich werd's am Wochenende testen. Habt ihr zwischenzeitlich eigentlich noch weitere Rückmeldungen zu OCPP bekommen? Oder bin ich der einzige, der das testet? Gruß Thomas

Moin @rtrbt, leider sind beide Probleme bei meinen gestrigen Tests mit dem aktuellen Entwicklungsstand erneut aufgetreten 🙁 Nur zur Sicherheit (damit wir keine Phantome jagen), bei mir gibt's ja zwei Besonderheiten: WARP1 Smart umgerüstet auf ESP32-Ethernet-Brick Zählerstände werden per MQTT (values_update, phases_update etc.) geschickt, d. h. es ist kein interner Zähler verbaut Test 1 18:24 - Fahrzeug verbunden --> eCarUp-Status wechselt von "Frei" auf "Verbunden" ✔️ 18:24 - Ladung per EVCC freigeschaltet (Min+PV) --> EVCC-Status wechselt auf "Ladebereit, warte auf Fahrzeug" ✔️ 18:25 - Ladung per eCarUp-App gestartet --> WARP startet Ladung, eCarUp-Status wechselt von "Verbunden" auf "Besetzt" ✔️ 18:35 - Ladung per eCarUp-App beendet --> WARP stoppt Ladung, eCarUp-Status wechselt von "Besetzt" auf "Verbunden" ✔️ 18:35 - neue Ladung per eCarUp-App gestartet --> WARP startet Ladung, eCarUp-Status wechselt kurz auf "Besetzt", springt dann aber auf "Verbunden" zurück ❌ 18:37 - Ladung per EVCC abgebrochen, Fahrzeug getrennt --> keine Heartbeats mehr ❌ 18:41 - WARP 2x neugestartet --> "RESTORED StopTransaction.req" wird bei jedem Neustart gesendet, es kommen aber trotzdem keine Heartbeats ❌ 18:46 - OCPP-Status zurückgesetzt u. WARP neugestartet --> Heartbeats kommen wieder an ✔️ Test 2 18:48 - Fahrzeug verbunden --> eCarUp-Status wechselt von "Frei" auf "Verbunden" ✔️ 18:48 - Ladung per EVCC freigeschaltet (Min+PV) --> EVCC-Status wechselt auf "Ladebereit, warte auf Fahrzeug" ✔️ 18:49 - Ladung per eCarUp-App gestartet --> WARP startet Ladung, eCarUp-Status wechselt von "Verbunden" auf "Besetzt" ✔️ 18:59 - Ladung per eCarUp-App beendet --> WARP stoppt Ladung, eCarUp-Status bleibt bei "Besetzt" und Ladezeit läuft weiter ❌ 19:00 - Ladung erneut per eCarUp-App beendet --> keine Änderung ❌ 19:01 - Fahrzeug getrennt --> keine Heartbeats mehr, Status bleibt "Besetzt", Ladezeit läuft weiter ❌ 19:03 - WARP neugestartet --> "RESTORED StopTransaction.req" erfolgreich gesendet, Heartbeats kommen wieder an ✔️ Hast du noch irgendwelche Ideen? Gerne können wir hierzu auch mal telefonieren... Vielen Dank und Gruß Thomas test1.txt test2.txt

Das war kaputt. Weil ich da direkt von TRANSACTION nach IDLE gehe, schicke ich nie die StopTransaction-Nachricht raus. Habe ich gerade gefixt. Moin @rtrbt, gibst du Bescheid, sobald der Fix im Repo ist? Dann teste ich diesen gerne... Danke & Gruß Thomas

Moin, an L1 hängt die Stromversorgung der WARP-Komponenten, d. h. wenn du die Phase wegnimmst, dann ist die Box aus. L2 und L3 werden IMHO durch das Schütz nur durchgeschaltet… Gruß Thomas

Tja, wenn ich das wüsste 😂 Ladekabel verbunden, EVCC freigeschaltet, Ladevorgang über eCarUp-App gestartet. WARP startet und der Status bei eCarUp geht kurz auf "Besetzt", springt nach ein paar Sekunden aber wieder zurück auf "Verbunden", obwohl das Fahrzeug geladen wird. Ich habe dann einfach nochmal auf "Starten" geklickt. Erst nach Trennen und Neuverbinden des Fahrzeugs konnte der Ladevorgang dann richtig gestartet werden, sodass WARP und eCarUp "in sync" waren...

Danke Erik, das klingt sehr interessant 🙂 Ich habe in den letzten Tagen übrigens mal etwas getestet und das Ergebnis war "so halbwegs" erfolgreich. Folgendes Testszenario habe ich mehrfach hintereinander an unterschiedlichen Tagen durchgespielt, i. d. R. ohne WARP zwischendurch neuzustarten. Ladekabel verbinden EVCC freischalten (Min+PV) --> Status "Ladebereit, Warte auf Fahrzeug" Ladevorgang in eCarUp-App starten (10-15 Minuten) --> WARP startet Nach Ende des Ladevorgangs Ladekabel trennen Meistens hat dies auch richtig funktioniert, allerdings hatte ich zweimal den Fall, dass der Ladevorgang zwar gestartet wurde, eCarUp dies aber nicht mitbekommen hat. Der Status geht dann kurz auf "Besetzt", nach einigen Sekunden aber wieder auf "Verbunden". obwohl das Auto geladen wird. Erst nach einer kompletten Wiederholung des o. g. Ablaufs (d. h. einem Neuverbinden) hat es korrekt funktioniert. Hier die zugehörigen Logs: 2022-11-01 09:55:05,898 Sending Heartbeat.req. 63316158 63316158 63376158 60000 2022-11-01 09:55:06,005 Received message [3, "1057", {"currentTime":"2022-11-01T0 (len 55) 2022-11-01 09:55:06,006 Received Heartbeat.conf for connector 0 2022-11-01 09:56:05,958 Sending Heartbeat.req. 63376234 63376234 63436234 60000 2022-11-01 09:56:06,062 Received message [3, "1058", {"currentTime":"2022-11-01T0 (len 55) 2022-11-01 09:56:06,064 Received Heartbeat.conf for connector 0 2022-11-01 09:56:33,506 IDLE -> NO_TAG 2022-11-01 09:56:33,507 Sending StatusNotification.req: Status Preparing for connector 1 2022-11-01 09:56:33,610 Received message [3, "1059", {}] (len 15) 2022-11-01 09:56:33,611 Received StatusNotification.conf for connector 1 2022-11-01 09:56:33,950 Charger state changed from 0 to 1 2022-11-01 09:57:05,952 Sending Heartbeat.req. 63436303 63436303 63496303 60000 2022-11-01 09:57:06,089 Received message [3, "1060", {"currentTime":"2022-11-01T0 (len 55) 2022-11-01 09:57:06,090 Received Heartbeat.conf for connector 0 2022-11-01 09:57:34,491 Tag timeout! 2022-11-01 09:57:34,491 NO_TAG -> FINISHING_UNLOCKED 2022-11-01 09:57:34,492 Sending StatusNotification.req: Status Finishing for connector 1 2022-11-01 09:57:34,602 Received message [3, "1061", {}] (len 15) 2022-11-01 09:57:34,603 Received StatusNotification.conf for connector 1 2022-11-01 09:57:56,929 Received message [2, "9b0e0004-6590-7235-bc49-999d0fdf276 (len 119) 2022-11-01 09:57:56,930 Received RemoteStartTransaction.req for connector 1 and tag S226J6TYXMWLADFAASWA 2022-11-01 09:57:56,941 Sending RemoteStartTransaction.conf Accepted 2022-11-01 09:57:56,958 FINISHING_UNLOCKED -> TRANSACTION 2022-11-01 09:57:57,008 Sending StatusNotification.req: Status SuspendedEVSE for connector 1 2022-11-01 09:57:57,073 Received message [3, "1062", {}] (len 15) 2022-11-01 09:57:57,073 Received StatusNotification.conf for connector 1 2022-11-01 09:57:57,112 Sending StatusNotification.req: Status SuspendedEV for connector 1 2022-11-01 09:57:57,216 Received message [3, "1063", {}] (len 15) 2022-11-01 09:57:57,216 Received StatusNotification.conf for connector 1 2022-11-01 09:57:57,865 Charger state changed from 1 to 2 2022-11-01 09:57:57,918 Tracked start of charge. 2022-11-01 09:57:59,148 Sending StatusNotification.req: Status Charging for connector 1 2022-11-01 09:57:59,242 Received message [3, "1064", {}] (len 15) 2022-11-01 09:57:59,243 Received StatusNotification.conf for connector 1 2022-11-01 09:57:59,920 Charger state changed from 2 to 3 2022-11-01 09:58:06,908 Sending Heartbeat.req. 63496360 63496360 63556360 60000 2022-11-01 09:58:07,009 Received message [3, "1065", {"currentTime":"2022-11-01T0 (len 55) 2022-11-01 09:58:07,010 Received Heartbeat.conf for connector 0 2022-11-01 09:58:36,029 Received message [2, "74670004-79cd-2c49-1e43-8e81533d30f (len 119) 2022-11-01 09:58:36,030 Received RemoteStartTransaction.req for connector 1 and tag B7YKBU4DF5A8G5FE3VVQ 2022-11-01 09:58:36,041 Sending RemoteStartTransaction.conf Accepted 2022-11-01 09:58:36,057 Ignoring remote start transaction in state TRANSACTION 2022-11-01 09:59:06,946 Sending Heartbeat.req. 63556419 63556419 63616419 60000 2022-11-01 09:59:07,051 Received message [3, "1066", {"currentTime":"2022-11-01T0 (len 55) 2022-11-01 09:59:07,052 Received Heartbeat.conf for connector 0 2022-11-01 09:59:12,320 Sending StatusNotification.req: Status SuspendedEVSE for connector 1 2022-11-01 09:59:12,424 Received message [3, "1067", {}] (len 15) 2022-11-01 09:59:12,425 Received StatusNotification.conf for connector 1 2022-11-01 09:59:12,876 Charger state changed from 3 to 1 2022-11-01 09:59:23,267 Sending StatusNotification.req: Status SuspendedEV for connector 1 2022-11-01 09:59:23,388 Received message [3, "1068", {}] (len 15) 2022-11-01 09:59:23,389 Received StatusNotification.conf for connector 1 2022-11-01 09:59:23,877 Charger state changed from 1 to 2 2022-11-01 09:59:24,275 Sending StatusNotification.req: Status Charging for connector 1 2022-11-01 09:59:24,377 Received message [3, "1069", {}] (len 15) 2022-11-01 09:59:24,378 Received StatusNotification.conf for connector 1 2022-11-01 09:59:24,896 Charger state changed from 2 to 3 2022-11-01 09:59:28,119 Sending StatusNotification.req: Status SuspendedEVSE for connector 1 2022-11-01 09:59:28,221 Received message [3, "1070", {}] (len 15) 2022-11-01 09:59:28,221 Received StatusNotification.conf for connector 1 2022-11-01 09:59:28,914 Charger state changed from 3 to 1 2022-11-01 10:00:00,049 Unexpected EVSEState 0 while Connector is in state 8. Aborting transaction! 2022-11-01 10:00:00,049 TRANSACTION -> IDLE 2022-11-01 10:00:00,061 Sending StatusNotification.req: Status Available for connector 1 2022-11-01 10:00:00,157 Received message [3, "1071", {}] (len 15) 2022-11-01 10:00:00,158 Received StatusNotification.conf for connector 1 2022-11-01 10:00:00,914 Charger state changed from 1 to 0 2022-11-01 10:00:00,968 Tracked end of charge. 2022-11-01 10:00:06,896 Sending Heartbeat.req. 63616431 63616431 63676431 60000 2022-11-01 10:00:07,004 Received message [3, "1072", {"currentTime":"2022-11-01T0 (len 55) 2022-11-01 10:00:07,005 Received Heartbeat.conf for connector 0 2022-11-01 10:00:13,909 IDLE -> NO_TAG 2022-11-01 10:00:13,910 Sending StatusNotification.req: Status Preparing for connector 1 2022-11-01 10:00:14,000 Received message [3, "1073", {}] (len 15) 2022-11-01 10:00:14,001 Received StatusNotification.conf for connector 1 2022-11-01 10:00:14,042 Charger state changed from 0 to 1 2022-11-01 10:00:35,415 Received message [2, "39ad0004-65b1-2d48-9145-919c1e2ba66 (len 119) 2022-11-01 10:00:35,416 Received RemoteStartTransaction.req for connector 1 and tag V5Y2L8A38YEC7QHAZBLQ 2022-11-01 10:00:35,427 Sending RemoteStartTransaction.conf Accepted 2022-11-01 10:00:35,442 NO_TAG -> TRANSACTION 2022-11-01 10:00:35,469 Sending StartTransaction.req at connector 1 for tag V5Y2L8A38YEC7QHAZBLQ at 725.496 kWh. 2022-11-01 10:00:35,535 Sending StatusNotification.req: Status SuspendedEVSE for connector 1 2022-11-01 10:00:35,663 Received message [3, "1074", {"idTagInfo":{"status":"Acce (len 115) 2022-11-01 10:00:35,679 Received StartTransaction.conf for connector 1 2022-11-01 10:00:35,844 Received message [3, "1075", {}] (len 15) 2022-11-01 10:00:35,845 Received StatusNotification.conf for connector 1 2022-11-01 10:00:35,856 Sending StatusNotification.req: Status SuspendedEV for connector 1 2022-11-01 10:00:35,961 Received message [3, "1076", {}] (len 15) 2022-11-01 10:00:35,962 Received StatusNotification.conf for connector 1 2022-11-01 10:00:36,044 Charger state changed from 1 to 2 2022-11-01 10:00:36,102 Tracked start of charge. 2022-11-01 10:00:36,609 Sending StatusNotification.req: Status Charging for connector 1 2022-11-01 10:00:36,703 Received message [3, "1077", {}] (len 15) 2022-11-01 10:00:36,705 Received StatusNotification.conf for connector 1 2022-11-01 10:00:37,107 Charger state changed from 2 to 3 2022-11-01 10:01:06,917 Sending Heartbeat.req. 63676468 63676468 63736468 60000 2022-11-01 10:01:07,042 Received message [3, "1078", {"currentTime":"2022-11-01T0 (len 55) 2022-11-01 10:01:07,044 Received Heartbeat.conf for connector 0 2022-11-01 10:02:06,961 Sending Heartbeat.req. 63736568 63736568 63796568 60000 2022-11-01 10:02:07,067 Received message [3, "1079", {"currentTime":"2022-11-01T0 (len 55) 2022-11-01 10:02:07,068 Received Heartbeat.conf for connector 0 2022-11-01 10:03:06,915 Sending Heartbeat.req. 63796600 63796600 63856600 60000 2022-11-01 10:03:07,019 Received message [3, "1080", {"currentTime":"2022-11-01T0 (len 55) 2022-11-01 10:03:07,020 Received Heartbeat.conf for connector 0 2022-11-01 10:04:06,903 Sending Heartbeat.req. 63856619 63856619 63916619 60000 2022-11-01 10:04:07,008 Received message [3, "1081", {"currentTime":"2022-11-01T0 (len 55) 2022-11-01 10:04:07,009 Received Heartbeat.conf for connector 0 2022-11-01 10:05:06,925 Sending Heartbeat.req. 63916662 63916662 63976662 60000 2022-11-01 10:05:07,013 Received message [3, "1082", {"currentTime":"2022-11-01T0 (len 55) 2022-11-01 10:05:07,014 Received Heartbeat.conf for connector 0 Einmal ist dann auch das ursprüngliche Problem aufgetreten, d. h. die Ladezeit war abgelaufen, WARP hat aber nicht abgeschaltet. Irgendwann blieben dann auch die Heartbeats aus, sodass eCarUp die Ladestation als nicht verfügbar klassifiziert hat. Nach einem Reboot wurde das zwischengespeicherte "StopTransaction"-Paket gesendet und anscheinend auch korrekt verarbeitet. Hier die zugehörigen Logs: 2022-10-31 15:31:14,379 Received message [3, "2780", {}] (len 15) 2022-10-31 15:31:14,380 Received StatusNotification.conf for connector 1 2022-10-31 15:31:14,391 Unexpected EVSEState 4 while Connector is in state 10 2022-10-31 15:31:14,475 Charger state changed from 3 to 1 2022-10-31 15:31:17,899 Sending Heartbeat.req. 165830587 165830587 165890587 60000 2022-10-31 15:32:17,120 Received message [2, "4da20005-6f14-60d0-8346-80e19ff359c (len 98) 2022-10-31 15:32:17,121 Received RemoteStopTransaction.req 2022-10-31 15:32:17,131 Sending RemoteStopTransaction.conf Rejected (unknown connector id) 2022-10-31 15:32:17,957 Sending Heartbeat.req. 165890645 165890645 165950645 60000 2022-10-31 15:33:17,839 Received message [2, "d6310005-8917-3663-f049-a04793fe7d6 (len 98) 2022-10-31 15:33:17,840 Received RemoteStopTransaction.req 2022-10-31 15:33:17,850 Sending RemoteStopTransaction.conf Rejected (unknown connector id) 2022-10-31 15:33:18,055 Sending Heartbeat.req. 165950743 165950743 166010743 60000 ... 2022-10-31 15:51:27,380 Received message [2, "f0b30005-7be3-e583-864f-8b3ac57c467 (len 98) 2022-10-31 15:51:27,381 Received RemoteStopTransaction.req 2022-10-31 15:51:27,391 Sending RemoteStopTransaction.conf Rejected (unknown connector id) 2022-10-31 15:52:19,001 Sending Heartbeat.req. 167091689 167091689 167151689 60000 2022-10-31 15:52:28,393 Received message [2, "513c0005-67d3-90e3-8940-83d0d39c212 (len 98) 2022-10-31 15:52:28,394 Received RemoteStopTransaction.req 2022-10-31 15:52:28,404 Sending RemoteStopTransaction.conf Rejected (unknown connector id) 2022-10-31 15:53:19,039 Sending Heartbeat.req. 167151727 167151727 167211727 60000 2022-10-31 15:53:28,409 Received message [2, "0c550005-22c8-66ea-e64c-b1b8f6dbf4e (len 98) 2022-10-31 15:53:28,410 Received RemoteStopTransaction.req 2022-10-31 15:53:28,420 Sending RemoteStopTransaction.conf Rejected (unknown connector id) 2022-10-31 15:54:19,103 Sending Heartbeat.req. 167211791 167211791 167271791 60000 2022-10-31 15:54:28,521 Received message [2, "ec750005-faf1-e631-7c45-86d5fd0bdc5 (len 98) 2022-10-31 15:54:28,522 Received RemoteStopTransaction.req 2022-10-31 15:54:28,532 Sending RemoteStopTransaction.conf Rejected (unknown connector id) 2022-10-31 15:55:10,168 FINISHING_UNLOCKED -> IDLE 2022-10-31 15:55:10,168 Sending StatusNotification.req: Status Available for connector 1 2022-10-31 15:55:10,960 Charger state changed from 1 to 0 2022-10-31 15:55:11,007 Tracked end of charge. 2022-10-31 15:55:19,202 Sending Heartbeat.req. 167271890 167271890 167331890 60000 2022-10-31 15:55:28,614 Received message [2, "907c0005-2321-be55-c047-90c97772a5a (len 98) 2022-10-31 15:55:28,614 Received RemoteStopTransaction.req 2022-10-31 15:55:28,625 Sending RemoteStopTransaction.conf Rejected (unknown connector id) 2022-10-31 15:56:19,219 Sending Heartbeat.req. 167331907 167331907 167391907 60000 2022-10-31 15:57:19,258 Sending Heartbeat.req. 167391947 167391947 167451947 60000 2022-10-31 15:58:19,317 Sending Heartbeat.req. 167452005 167452005 167512005 60000 2022-10-31 15:59:19,322 Sending Heartbeat.req. 167512010 167512010 167572010 60000 2022-10-31 16:00:19,407 Sending Heartbeat.req. 167572095 167572095 167632095 60000 2022-10-31 16:01:19,408 Sending Heartbeat.req. 167632096 167632096 167692096 60000 2022-10-31 16:02:19,432 Sending Heartbeat.req. 167692120 167692120 167752120 60000 2022-10-31 16:03:19,476 Sending Heartbeat.req. 167752165 167752165 167812165 60000 2022-10-31 16:04:19,490 Sending Heartbeat.req. 167812178 167812178 167872178 60000 2022-10-31 16:05:19,586 Sending Heartbeat.req. 167872274 167872274 167932274 60000 2022-10-31 16:06:19,602 Sending Heartbeat.req. 167932290 167932290 167992290 60000 2022-10-31 16:07:19,647 Sending Heartbeat.req. 167992336 167992336 168052336 60000 2022-10-31 16:08:19,743 Sending Heartbeat.req. 168052431 168052431 168112431 60000 2022-10-31 16:09:19,772 Sending Heartbeat.req. 168112460 168112460 168172460 60000 2022-10-31 16:10:19,847 Sending Heartbeat.req. 168172535 168172535 168232535 60000 2022-10-31 16:11:19,871 Sending Heartbeat.req. 168232559 168232559 168292559 60000 2022-10-31 16:12:19,912 Sending Heartbeat.req. 168292600 168292600 168352600 60000 2022-10-31 16:13:19,981 Sending Heartbeat.req. 168352669 168352669 168412669 60000 2022-10-31 16:14:20,023 Sending Heartbeat.req. 168412711 168412711 168472711 60000 8,375 Network connected. Stopping soft AP 8,404 MQTT: Connected to broker. 16,689 OCPP WEBSOCKET CONNECTED 16,776 Sending BootNotification.req. 16776 0 60000 60000 16,927 Received message [3, "1", {"status":"Accepted","currentTi (len 86) 16,928 Received BootNotification.conf for connector 0. Interval 60 2022-10-31 16:16:18,000 Sending StatusNotification.req: Status Available for charge point 2022-10-31 16:16:18,011 Sending StatusNotification.req: Status Available for connector 1 2022-10-31 16:16:18,055 Sending RESTORED StopTransaction.req for tag LNAL6KL446L2TFWMRGBQ at 725.466 kWh. Reason 11 2022-10-31 16:16:18,165 Received message [3, "4", {"idTagInfo":{"status":"Accepte (len 85) 2022-10-31 16:16:18,184 Received StopTransaction.conf for connector 0 2022-10-31 16:16:18,359 Received message [3, "2", {}] (len 12) 2022-10-31 16:16:18,360 Received StatusNotification.conf for connector 0 2022-10-31 16:16:18,562 Received message [3, "3", {}] (len 12) 2022-10-31 16:16:18,563 Received StatusNotification.conf for connector 1 2022-10-31 16:16:23,338 NTP synchronized at 21,511! 2022-10-31 16:17:08,316 This is warp2-XSS (warp2-XSS), a WARP2 Charger Pro 11kW 2022-10-31 16:17:18,630 Sending Heartbeat.req. 76803 76803 136803 60000 2022-10-31 16:17:18,730 Received message [3, "5", {"currentTime":"2022-10-31T15:1 (len 52) 2022-10-31 16:17:18,731 Received Heartbeat.conf for connector 0 2022-10-31 16:18:17,933 Sending Heartbeat.req. 136849 136849 196849 60000 2022-10-31 16:18:18,034 Received message [3, "6", {"currentTime":"2022-10-31T15:1 (len 52) 2022-10-31 16:18:18,035 Received Heartbeat.conf for connector 0 2022-10-31 16:19:17,977 Sending Heartbeat.req. 196939 196939 256939 60000 2022-10-31 16:19:18,070 Received message [3, "7", {"currentTime":"2022-10-31T15:1 (len 52) 2022-10-31 16:19:18,071 Received Heartbeat.conf for connector 0 Vielleicht helfen euch die Logs ja weiter. Falls hilfreich, so kann ich die Tests gerne auch nochmal mit deaktiviertem EVCC durchführen... Gruß Thomas

Könnte da evtl. ein Zusammenhang mit meinem (gelösten) Problem bestehen? Ein Update der EVSE-Kalibrierung hat seinerzeit geholfen...

So, habe gerade einen Factory Reset durchgeführt und nun funktioniert's (erstmal) wieder: 7,292 MQTT: Connected to broker. 16,601 OCPP WEBSOCKET CONNECTED 16,654 Sending BootNotification.req. 16654 0 60000 60000 16,812 Received message [3, "1", {"status":"Accepted","currentTi (len 86) 16,814 Received BootNotification.conf for connector 0. Interval 60 2022-10-29 14:06:44,000 Sending StatusNotification.req: Status Available for charge point 2022-10-29 14:06:44,011 Sending StatusNotification.req: Status Available for connector 1 2022-10-29 14:06:44,143 Received message [3, "2", {}] (len 12) 2022-10-29 14:06:44,144 Received StatusNotification.conf for connector 0 2022-10-29 14:06:44,341 Received message [3, "3", {}] (len 12) 2022-10-29 14:06:44,342 Received StatusNotification.conf for connector 1 2022-10-29 14:06:49,514 NTP synchronized at 22,316! 2022-10-29 14:07:33,579 This is warp2-XSS (warp2-XSS), a WARP2 Charger Pro 11kW 2022-10-29 14:07:43,927 Sending Heartbeat.req. 76729 76729 136729 60000 2022-10-29 14:07:44,022 Received message [3, "4", {"currentTime":"2022-10-29T12:0 (len 52) 2022-10-29 14:07:44,023 Received Heartbeat.conf for connector 0 Mal schau'n, ob ich das ursprüngliche Problem in den nächsten Tagen reproduziert bekomme. Bliebe nur die Frage offen, warum die Datei "/ocpp/txn_msg" nicht gelöscht werden konnte. Gibt es rein technisch gesehen schon, habe aber noch nicht rausgefunden wie eCarUp das konfigurieren will. Wie funktioniert die Übertragung des Zählerstands denn grundsätzlich? Wenn ich meinen Wagen unterwegs über die eCharge-App lade, dann erhalte ich auch regelmäßige Updates des Zählerstands... Gruß Thomas

So, ich bin nun einen Schritt weiter (am Abgrund). Ich habe den CommandHandler wie folgt umgebaut und festgestellt, dass die Datei "txn_msg" im Directory /ocpp nicht gelöscht werden kann: api.addCommand("ocpp/reset", &reset, {}, [](){ logger.printfln("Reset OCPP..."); int i = 0; File root = LittleFS.open("/ocpp"); File file; while (file = root.openNextFile()) { i++; String filename = file.path(); file.close(); logger.printfln("Found file '%s' ...", filename.c_str()); bool result = LittleFS.remove(filename); logger.printfln("File %s removed.", result ? "successfully" : "NOT"); } logger.printfln("No of files: %d", i); } Und das ist das Ergebnis: 20,128 Reset OCPP... 20,140 Found file '/ocpp/txn_msg' ... 20,144 File NOT removed. 20,155 No of files: 1 2022-10-28 20:41:34,310 NTP synchronized at 25,137! 2022-10-28 20:41:56,679 Reset OCPP... 2022-10-28 20:41:56,691 Found file '/ocpp/txn_msg' ... 2022-10-28 20:41:56,695 File NOT removed. 2022-10-28 20:41:56,706 No of files: 1 Hast du irgendeine Idee, warum die Datei nicht gelöscht werden kann? Ist sie vielleicht noch durch irgendetwas im Hintergrund geöffnet? Danke & Gruß Thomas

ARGH - irgendwie ist dieses OCPP-Paket sehr hartnäckig. Ich habe mehrfach den "großen roten Knopf" gedrückt, POSTs auf die URL /ocpp/reset abgesetzt (die auch mit 200 bestätigt werden) und selbstverständlich anschließend auch neugestartet... ... aber keine Chance, WARP schickt das Paket nach der Boot-Notification immer wieder 😡 7,966 Network connected. Stopping soft AP 7,996 MQTT: Connected to broker. 16,699 OCPP WEBSOCKET CONNECTED 16,786 Sending BootNotification.req. 16786 0 60000 60000 16,978 Received message [3, "1", {"status":"Accepted","currentTi (len 86) 16,979 Received BootNotification.conf for connector 0. Interval 60 2022-10-28 18:42:07,000 Sending StatusNotification.req: Status Available for charge point 2022-10-28 18:42:07,011 Sending StatusNotification.req: Status Available for connector 1 2022-10-28 18:42:07,028 Sending StatusNotification.req: Status Available for charge point 2022-10-28 18:42:07,133 Received message [3, "2", {}] (len 12) 2022-10-28 18:42:07,134 Received StatusNotification.conf for connector 0 2022-10-28 18:42:07,250 Sending RESTORED StopTransaction.req for tag at 724.372 kWh. Reason 11 2022-10-28 18:42:12,354 NTP synchronized at 21,601! 2022-10-28 18:42:57,249 This is warp2-XSS (warp2-XSS), a WARP2 Charger Pro 11kW Interessanterweise wird die Box dann für ein paar Minuten bei eCarUp als "Verfügbar" angezeigt und in dieser Zeit kann ich auch einen Ladevorgang starten. Leider ist mein Wagen erst morgen wieder da, daher konnte ich nicht prüfen, ob er tatsächlich auch lädt: 2022-10-28 18:48:55,243 Received message [2, "801e0004-57ba-a602-aa4a-a9f19683782 (len 119) 2022-10-28 18:48:55,244 Received RemoteStartTransaction.req for connector 1 and tag 6EFSK4CX9MUJPGN35Q9Q 2022-10-28 18:48:55,255 Sending RemoteStartTransaction.conf Accepted 2022-10-28 18:48:55,270 IDLE -> NO_PLUG 2022-10-28 18:48:55,339 Sending StatusNotification.req: Status Preparing for connector 1 2022-10-28 18:49:55,364 Cable timeout! 2022-10-28 18:49:55,364 NO_PLUG -> IDLE 2022-10-28 18:49:55,364 Sending StatusNotification.req: Status Available for connector 1 Allerdings hilft auch dieser neue Startversuch nicht, das OCPP-Paket loszuwerden, d. h. auch nach einem weiteren Reboot wird es wieder geschickt, sodass die Heartbeats weiterhin ausbleiben. Evtl. machen ich morgen mal einen Factory Reset... Gruß und ein schönes Wochenende Thomas

Das ist sicherlich keine schlechte Idee (gerade jetzt in der Betaphase), denn selbst das Trennen der Stromversorgung hat nicht geholfen und die WARP wird bei eCarUp nun als "inaktiv" angezeigt. Baut ihr auch eine regelmäßige Übertragung des Zählerstands mit ein, z. B. alle fünf Minuten? Gruß Thomas

'n Abend @rtrbt, heute Abend habe ich mal einen größeren Test gemacht und irgendwie scheint die OCPP-Integration jetzt kaputt zu sein 🙁 Zunächst hat eCarUp zwar den Status (frei, verbunden) korrekt erkannt, die Ladung konnte aber nicht gestartet werden. Auf der Statusseite stand bei "Fahrzeugstatus" nur "Ladebereit" und bei "Erlaubter Ladestrom" "6.000 A durch externe Steuerung" (das ist die Vorgabe von EVCC). Nach einem Neustart von WARP stand da dann "6.000 A durch externe Steuerung, OCPP", d. h. in den letzten 24 Stunden ist da anscheinend irgendein Status durcheinander gekommen. Leider habe ich versäumt, das Log vor dem Reboot zu sichern, die Ladung konnte aber korrekt gestartet werden. Während des Ladevorgangs hat eCarUp allerdings keine Verbrauchsdaten erhalten und nach knapp einer Stunde habe ich versucht, die Ladung über die App zu beenden. WARP hat die Ladung zwar beendet, aber trotz Trennen des Fahrzeugs zeigten sowohl die App als auch die Statusseite von eCarUp weiterhin einen aktiven Ladevorgang an. Hier die zugehörigen Logs: 2022-10-27 18:50:47,907 Sending Heartbeat.req. 5121096 5121096 5181096 60000 2022-10-27 18:50:48,004 Received message [3, "104", {"currentTime":"2022-10-27T16 (len 54) 2022-10-27 18:50:48,005 Received Heartbeat.conf for connector 0 2022-10-27 18:51:47,979 Sending Heartbeat.req. 5181184 5181184 5241184 60000 2022-10-27 18:51:48,073 Received message [3, "105", {"currentTime":"2022-10-27T16 (len 54) 2022-10-27 18:51:48,074 Received Heartbeat.conf for connector 0 2022-10-27 18:52:47,991 Sending Heartbeat.req. 5241281 5241281 5301281 60000 2022-10-27 18:52:48,083 Received message [3, "106", {"currentTime":"2022-10-27T16 (len 54) 2022-10-27 18:52:48,084 Received Heartbeat.conf for connector 0 2022-10-27 18:52:56,085 httpd_ws_recv_frame failed to get frame len with -1 2022-10-27 18:53:02,946 Received message [2, "01ed0005-08a5-6f36-fd40-bc08ac60a2a (len 98) 2022-10-27 18:53:02,947 Received RemoteStopTransaction.req 2022-10-27 18:53:02,957 TRANSACTION -> FINISHING_UNLOCKED 2022-10-27 18:53:02,958 Attempting to send stop transaction but next stop reason is none! 2022-10-27 18:53:03,020 Unexpected EVSEState 4 while Connector is in state 10 2022-10-27 18:53:03,021 Sending StatusNotification.req: Status Finishing for connector 1 2022-10-27 18:53:03,045 Sending StopTransaction.req at connector 1 for tag at 724.372 kWh. StopReason 11 2022-10-27 18:53:03,046 Sending RemoteStopTransaction.conf Accepted 2022-10-27 18:53:03,173 Received message [3, "107", {}] (len 14) 2022-10-27 18:53:03,174 Received StatusNotification.conf for connector 1 2022-10-27 18:53:03,228 Charger state changed from 3 to 1 2022-10-27 18:53:34,433 httpd_ws_recv_frame failed to get frame len with -1 2022-10-27 18:53:48,926 Sending Heartbeat.req. 5301312 5301312 5361312 60000 2022-10-27 18:54:08,939 Received message [2, "ffa70005-7c3e-0c8b-4646-afbadeb3bd6 (len 98) 2022-10-27 18:54:08,940 Received RemoteStopTransaction.req 2022-10-27 18:54:08,950 Sending RemoteStopTransaction.conf Rejected (unknown connector id) 2022-10-27 18:54:48,938 Sending Heartbeat.req. 5361323 5361323 5421323 60000 2022-10-27 18:54:58,065 httpd_ws_recv_frame failed to get frame len with -1 2022-10-27 18:55:10,252 FINISHING_UNLOCKED -> IDLE 2022-10-27 18:55:10,252 Sending StatusNotification.req: Status Available for connector 1 2022-10-27 18:55:10,271 Charger state changed from 1 to 0 2022-10-27 18:55:10,399 Tracked end of charge. 2022-10-27 18:55:47,799 Received message [2, "25f80005-7ecf-5792-d849-9de1773d94f (len 98) 2022-10-27 18:55:47,799 Received RemoteStopTransaction.req 2022-10-27 18:55:47,810 Sending RemoteStopTransaction.conf Rejected (unknown connector id) 2022-10-27 18:55:48,999 Sending Heartbeat.req. 5421384 5421384 5481384 60000 2022-10-27 18:56:49,082 Sending Heartbeat.req. 5481468 5481468 5541468 60000 2022-10-27 18:57:11,585 httpd_ws_recv_frame failed to get frame len with -1 2022-10-27 18:57:22,769 Received message [2, "35ec0005-870f-c876-8048-873033ee44f (len 98) 2022-10-27 18:57:22,770 Received RemoteStopTransaction.req 2022-10-27 18:57:22,780 Sending RemoteStopTransaction.conf Rejected (unknown connector id) 2022-10-27 18:57:49,127 Sending Heartbeat.req. 5541512 5541512 5601512 60000 2022-10-27 18:58:49,135 Sending Heartbeat.req. 5601520 5601520 5661520 60000 2022-10-27 18:59:49,199 Sending Heartbeat.req. 5661584 5661584 5721584 60000 2022-10-27 19:00:49,280 Sending Heartbeat.req. 5721665 5721665 5781665 60000 2022-10-27 19:01:21,991 httpd_ws_recv_frame failed to get frame len with -1 2022-10-27 19:01:49,312 Sending Heartbeat.req. 5781698 5781698 5841698 60000 2022-10-27 19:02:27,943 httpd_ws_recv_frame failed to get frame len with -1 2022-10-27 19:02:49,393 Sending Heartbeat.req. 5841778 5841778 5901778 60000 2022-10-27 19:03:49,422 Sending Heartbeat.req. 5901808 5901808 5961808 60000 2022-10-27 19:04:17,867 Received message [2, "84f20005-2483-7b6c-ed4f-ac31ec0372e (len 98) 2022-10-27 19:04:17,868 Received RemoteStopTransaction.req 2022-10-27 19:04:17,878 Sending RemoteStopTransaction.conf Rejected (unknown connector id) Auch ein Reboot hat zunächst keine Verbesserung gebracht: 7,457 Network connected. Stopping soft AP 7,492 MQTT: Connected to broker. 16,503 OCPP WEBSOCKET CONNECTED 16,599 Sending BootNotification.req. 16599 0 60000 60000 16,769 Received message [3, "1", {"status":"Accepted","currentTi (len 86) 16,770 Received BootNotification.conf for connector 0. Interval 60 2022-10-27 19:05:45,000 Sending StatusNotification.req: Status Available for charge point 2022-10-27 19:05:45,011 Sending StatusNotification.req: Status Available for connector 1 2022-10-27 19:05:45,053 Sending RESTORED StopTransaction.req for tag at 724.372 kWh. Reason 11 2022-10-27 19:05:50,724 NTP synchronized at 21,611! 2022-10-27 19:06:19,241 Received message [2, "05730005-c292-ed6c-3b41-ab8466e0405 (len 98) 2022-10-27 19:06:19,242 Received RemoteStopTransaction.req 2022-10-27 19:06:19,253 Sending RemoteStopTransaction.conf Rejected (unknown connector id) 2022-10-27 19:06:35,427 This is warp2-XSS (warp2-XSS), a WARP2 Charger Pro 11kW 2022-10-27 19:07:21,046 Received message [2, "0a6e0005-2535-627c-294f-be7c7cf77d5 (len 98) 2022-10-27 19:07:21,046 Received RemoteStopTransaction.req 2022-10-27 19:07:21,057 Sending RemoteStopTransaction.conf Rejected (unknown connector id) 2022-10-27 19:08:23,309 Received message [2, "efda0005-a43f-907a-734f-aafbbf68c7a (len 98) 2022-10-27 19:08:23,310 Received RemoteStopTransaction.req 2022-10-27 19:08:23,321 Sending RemoteStopTransaction.conf Rejected (unknown connector id) Nach etwa 15 Minuten hat eCarUp die Ladung dann als abgebrochen erkannt (ohne endgültige Verbrauchswerte). Seitdem baut WARP aber (auch trotz weiterem Reboot) keine korrekte OCPP-Kommunikation mehr auf - die ersten Boot- und Statusmeldungen werden zwar noch geschickt, es kommen aber keine Heartbeats mehr: 7,328 Network connected. Stopping soft AP 7,357 MQTT: Connected to broker. 16,525 OCPP WEBSOCKET CONNECTED 16,603 Sending BootNotification.req. 16603 0 60000 60000 16,732 Received message [3, "1", {"status":"Accepted","currentTi (len 86) 16,733 Received BootNotification.conf for connector 0. Interval 60 2022-10-27 19:24:16,000 Sending StatusNotification.req: Status Available for charge point 2022-10-27 19:24:16,011 Sending StatusNotification.req: Status Available for connector 1 2022-10-27 19:24:16,083 Sending RESTORED StopTransaction.req for tag at 724.372 kWh. Reason 11 2022-10-27 19:24:21,635 NTP synchronized at 22,277! 2022-10-27 19:25:05,687 This is warp2-XSS (warp2-XSS), a WARP2 Charger Pro 11kW 2022-10-27 19:29:00,664 Wrote last uptime to flash Kann man dieses "RESTORED StopTransaction.req"-Paket irgendwie löschen? Vielen Dank & Gruß Thomas

Hi @rtrbt, ich hab's natürlich jetzt doch gerade schon ausprobiert - und es funktioniert! 😍 Die Verbindung wird nun auch über wss hergestellt, die Ladung kann über die eCarUp gestartet und beendet werden und die Ladung erscheint anschließend auch mit korrektem Verbrauch in der App. Selbst die Leistungsvorgabe durch WARP und EVCC wird korrekt berücksichtigt. Der Vollständigkeit halber hier noch das Log (diesmal mit korrektem Zeitstempel). Vielleicht könnt ihr da mal drüberschauen und die (vereinzelten) Fehler prüfen... 7,739 Network connected. Stopping soft AP 7,771 MQTT: Connected to broker. 16,460 OCPP WEBSOCKET CONNECTED 16,538 Sending BootNotification.req. 16538 0 60000 60000 16,686 Received message [3, "1", {"status":"Accepted","currentTi (len 86) 16,687 Received BootNotification.conf for connector 0. Interval 60 2022-10-26 18:31:25,000 Sending StatusNotification.req: Status Available for charge point 2022-10-26 18:31:25,011 Sending StatusNotification.req: Status Available for connector 1 2022-10-26 18:31:25,141 Received message [3, "2", {}] (len 12) 2022-10-26 18:31:25,142 Received StatusNotification.conf for connector 0 2022-10-26 18:31:25,341 Received message [3, "3", {}] (len 12) 2022-10-26 18:31:25,342 Received StatusNotification.conf for connector 1 2022-10-26 18:31:31,450 NTP synchronized at 22,647! 2022-10-26 18:32:15,062 This is warp2-XSS (warp2-XSS), a WARP2 Charger Pro 11kW 2022-10-26 18:32:25,345 Sending Heartbeat.req. 76542 76542 136542 60000 2022-10-26 18:32:25,438 Received message [3, "4", {"currentTime":"2022-10-26T16:3 (len 52) 2022-10-26 18:32:25,440 Received Heartbeat.conf for connector 0 2022-10-26 18:33:24,993 Sending Heartbeat.req. 136641 136641 196641 60000 2022-10-26 18:33:25,081 Received message [3, "5", {"currentTime":"2022-10-26T16:3 (len 52) 2022-10-26 18:33:25,082 Received Heartbeat.conf for connector 0 2022-10-26 18:34:16,602 IDLE -> NO_TAG 2022-10-26 18:34:16,602 Sending StatusNotification.req: Status Preparing for connector 1 2022-10-26 18:34:16,694 Received message [3, "6", {}] (len 12) 2022-10-26 18:34:16,695 Received StatusNotification.conf for connector 1 2022-10-26 18:34:17,022 Charger state changed from 0 to 1 2022-10-26 18:34:24,946 Sending Heartbeat.req. 196687 196687 256687 60000 2022-10-26 18:34:25,050 Received message [3, "7", {"currentTime":"2022-10-26T16:3 (len 52) 2022-10-26 18:34:25,051 Received Heartbeat.conf for connector 0 2022-10-26 18:34:51,173 Received message [2, "3c420004-530b-0035-3a45-8acd9a3e1bd (len 119) 2022-10-26 18:34:51,174 Received RemoteStartTransaction.req for connector 1 and tag Q9H5ZXVP2NL54FNRVGYQ 2022-10-26 18:34:51,184 Sending RemoteStartTransaction.conf Accepted 2022-10-26 18:34:51,200 NO_TAG -> TRANSACTION 2022-10-26 18:34:51,271 Sending StartTransaction.req at connector 1 for tag Q9H5ZXVP2NL54FNRVGYQ at 722.847 kWh. 2022-10-26 18:34:51,275 Sending StatusNotification.req: Status SuspendedEVSE for connector 1 2022-10-26 18:34:51,389 Received message [3, "8", {"idTagInfo":{"status":"Accepte (len 112) 2022-10-26 18:34:51,403 Sending StatusNotification.req: Status SuspendedEV for connector 1 2022-10-26 18:34:51,405 Received StartTransaction.conf for connector 1 2022-10-26 18:34:51,597 Received message [3, "9", {}] (len 12) 2022-10-26 18:34:51,597 Received StatusNotification.conf for connector 1 2022-10-26 18:34:51,707 Received message [3, "10", {}] (len 13) 2022-10-26 18:34:51,708 Received StatusNotification.conf for connector 1 2022-10-26 18:34:51,964 Charger state changed from 1 to 2 2022-10-26 18:34:52,017 Tracked start of charge. 2022-10-26 18:35:24,929 Sending Heartbeat.req. 256732 256732 316732 60000 2022-10-26 18:35:25,018 Received message [3, "11", {"currentTime":"2022-10-26T16: (len 53) 2022-10-26 18:35:25,019 Received Heartbeat.conf for connector 0 2022-10-26 18:36:09,356 Wrote last uptime to flash 2022-10-26 18:36:19,837 Sending StatusNotification.req: Status Charging for connector 1 2022-10-26 18:36:19,925 Received message [3, "12", {}] (len 13) 2022-10-26 18:36:19,926 Received StatusNotification.conf for connector 1 2022-10-26 18:36:20,008 Charger state changed from 2 to 3 2022-10-26 18:36:24,983 Sending Heartbeat.req. 316816 316816 376816 60000 2022-10-26 18:36:25,096 Received message [3, "13", {"currentTime":"2022-10-26T16: (len 53) 2022-10-26 18:36:25,097 Received Heartbeat.conf for connector 0 2022-10-26 18:36:29,198 httpd_ws_recv_frame failed to get frame len with -1 2022-10-26 18:37:24,919 Sending Heartbeat.req. 376860 376860 436860 60000 2022-10-26 18:37:25,011 Received message [3, "14", {"currentTime":"2022-10-26T16: (len 53) 2022-10-26 18:37:25,012 Received Heartbeat.conf for connector 0 2022-10-26 18:37:39,477 httpd_ws_recv_frame failed to get frame len with -1 2022-10-26 18:38:24,975 Sending Heartbeat.req. 436938 436938 496938 60000 2022-10-26 18:38:25,071 Received message [3, "15", {"currentTime":"2022-10-26T16: (len 53) 2022-10-26 18:38:25,072 Received Heartbeat.conf for connector 0 2022-10-26 18:39:00,707 httpd_ws_recv_frame failed to get frame len with -1 2022-10-26 18:39:24,954 Sending Heartbeat.req. 497001 497001 557001 60000 2022-10-26 18:39:25,056 Received message [3, "16", {"currentTime":"2022-10-26T16: (len 53) 2022-10-26 18:39:25,057 Received Heartbeat.conf for connector 0 2022-10-26 18:39:49,809 httpd_ws_recv_frame failed to get frame len with -1 2022-10-26 18:40:24,979 Sending Heartbeat.req. 557094 557094 617094 60000 2022-10-26 18:40:25,962 Received message [3, "17", {"currentTime":"2022-10-26T16: (len 53) 2022-10-26 18:40:25,963 Received Heartbeat.conf for connector 0 2022-10-26 18:41:06,471 httpd_ws_recv_frame failed to get frame len with -1 2022-10-26 18:41:25,010 Sending Heartbeat.req. 617099 617099 677099 60000 2022-10-26 18:41:25,109 Received message [3, "18", {"currentTime":"2022-10-26T16: (len 53) 2022-10-26 18:41:25,110 Received Heartbeat.conf for connector 0 2022-10-26 18:42:25,890 Sending Heartbeat.req. 677099 677099 737099 60000 2022-10-26 18:42:25,991 Received message [3, "19", {"currentTime":"2022-10-26T16: (len 53) 2022-10-26 18:42:25,992 Received Heartbeat.conf for connector 0 2022-10-26 18:43:13,782 httpd_ws_recv_frame failed to get frame len with -1 2022-10-26 18:43:25,973 Sending Heartbeat.req. 737185 737185 797185 60000 2022-10-26 18:43:26,076 Received message [3, "20", {"currentTime":"2022-10-26T16: (len 53) 2022-10-26 18:43:26,077 Received Heartbeat.conf for connector 0 2022-10-26 18:44:25,919 Sending Heartbeat.req. 797219 797219 857219 60000 2022-10-26 18:44:26,022 Received message [3, "21", {"currentTime":"2022-10-26T16: (len 53) 2022-10-26 18:44:26,023 Received Heartbeat.conf for connector 0 2022-10-26 18:44:58,764 httpd_ws_recv_frame failed to get frame len with -1 2022-10-26 18:45:25,940 Sending Heartbeat.req. 857273 857273 917273 60000 2022-10-26 18:45:26,032 Received message [3, "22", {"currentTime":"2022-10-26T16: (len 53) 2022-10-26 18:45:26,033 Received Heartbeat.conf for connector 0 2022-10-26 18:45:31,579 httpd_ws_recv_frame failed to get frame len with -1 2022-10-26 18:45:32,247 Received message [2, "0e890005-5be3-88d4-6a47-b4475a35fa3 (len 98) 2022-10-26 18:45:32,248 Received RemoteStopTransaction.req 2022-10-26 18:45:32,258 TRANSACTION -> FINISHING_UNLOCKED 2022-10-26 18:45:32,259 Attempting to send stop transaction but next stop reason is none! 2022-10-26 18:45:32,312 Sending StopTransaction.req at connector 1 for tag Q9H5ZXVP2NL54FNRVGYQ at 723.112 kWh. StopReason 11 2022-10-26 18:45:32,313 Sending RemoteStopTransaction.conf Accepted 2022-10-26 18:45:32,325 Unexpected EVSEState 4 while Connector is in state 10 2022-10-26 18:45:32,327 Sending StatusNotification.req: Status Finishing for connector 1 2022-10-26 18:45:32,430 Received message [3, "23", {"idTagInfo":{"status":"Accept (len 86) 2022-10-26 18:45:32,447 Unexpected EVSEState 4 while Connector is in state 10 2022-10-26 18:45:32,449 Received StopTransaction.conf for connector 1 2022-10-26 18:45:32,646 Received message [3, "24", {}] (len 13) 2022-10-26 18:45:32,647 Received StatusNotification.conf for connector 1 2022-10-26 18:45:32,694 Charger state changed from 3 to 1 2022-10-26 18:45:45,104 FINISHING_UNLOCKED -> IDLE 2022-10-26 18:45:45,104 Sending StatusNotification.req: Status Available for connector 1 2022-10-26 18:45:45,208 Received message [3, "25", {}] (len 13) 2022-10-26 18:45:45,208 Received StatusNotification.conf for connector 1 2022-10-26 18:45:45,695 Charger state changed from 1 to 0 2022-10-26 18:45:45,740 Tracked end of charge. 2022-10-26 18:46:25,903 Sending Heartbeat.req. 917281 917281 977281 60000 2022-10-26 18:46:26,004 Received message [3, "26", {"currentTime":"2022-10-26T16: (len 53) 2022-10-26 18:46:26,005 Received Heartbeat.conf for connector 0 2022-10-26 18:47:25,954 Sending Heartbeat.req. 977348 977348 1037348 60000 2022-10-26 18:47:26,045 Received message [3, "27", {"currentTime":"2022-10-26T16: (len 53) 2022-10-26 18:47:26,046 Received Heartbeat.conf for connector 0 Vielen Dank nochmals für eure immer tolle und zeitnahe Unterstützung! Gruß Thomas

Danke Erik (auch für die Erklärung zu OCPP) 🙂 Ich ziehe eure letzten Anpassungen heute Abend mal nach und teste dann voraussichtlich morgen...

So, die ersten Tests waren so halbwegs erfolgreich. Ich habe einen eCarUp-Account angelegt und zunächst die wss-URL (wss://www.ecarup.com/api/Ocpp16/xxxx) verwendet. Leider erhalte ich damit nur die Fehlermeldung "OCPP WEBSOCKET DISCONNECTED". Die Umstellung von wss auf ws hat das Problem dann aber erstmal (temporär) gelöst, WARP verbindet sich und erscheint sowohl im eCarUp-Portal als auch in der App - und zwar direkt mit dem richtigen Status (frei bzw. verbunden) 🙂 Leider lässt sich aber die Ladung nicht starten. EVCC ist freigegeben und die OCPP-Startanforderung kommt auch an - nur es passiert nix. Interessant ist auch der Zeitstempel der OCPP-Meldungen, diese scheinen etliche Jahre aus der Zukunft zu kommen: 8,343 Network connected. Stopping soft AP 8,381 MQTT: Connected to broker. 16,188 OCPP WEBSOCKET CONNECTED 16,201 Sending BootNotification.req. 16201 0 60000 60000 16,351 Received message [3, "1", {"status":"Accepted","currentTi (len 86) 16,352 Received BootNotification.conf for connector 0. Interval 60 2028-02-29 09:29:49,000 Sending StatusNotification.req: Status Available for charge point 2028-02-29 09:29:49,001 Sending StatusNotification.req: Status Preparing for connector 1 2028-02-29 09:29:49,138 Received message [3, "2", {}] (len 12) 2028-02-29 09:29:49,139 Received StatusNotification.conf for connector 0 2028-02-29 09:29:49,344 Received message [3, "3", {}] (len 12) 2028-02-29 09:29:49,344 Received StatusNotification.conf for connector 1 2022-10-25 15:54:13,808 NTP synchronized at 24,778! 2022-10-25 15:54:55,650 This is warp2-XSS (warp2-XSS), a WARP2 Charger Pro 11kW 2022-10-25 15:54:55,730 Tag timeout! 2022-10-25 15:54:55,730 NO_TAG -> FINISHING_UNLOCKED 2022-10-25 15:54:55,731 Sending StatusNotification.req: Status Finishing for connector 1 2022-10-25 15:54:55,838 Received message [3, "4", {}] (len 12) 2022-10-25 15:54:55,839 Received StatusNotification.conf for connector 1 2022-10-25 15:55:05,287 Sending Heartbeat.req. 76258 76258 136258 60000 2022-10-25 15:55:05,381 Received message [3, "5", {"currentTime":"2022-10-25T13:5 (len 52) 2022-10-25 15:55:05,382 Received Heartbeat.conf for connector 0 2028-02-29 09:31:48,907 Sending Heartbeat.req. 136271 136271 196271 60000 2028-02-29 09:31:49,009 Received message [3, "6", {"currentTime":"2022-10-25T13:5 (len 52) 2028-02-29 09:31:49,010 Received Heartbeat.conf for connector 0 2028-02-29 09:31:56,360 Received message [2, "15ed0004-bf5d-47d0-5242-b4e5ec3cc10 (len 119) 2028-02-29 09:31:56,361 Received RemoteStartTransaction.req for connector 1 and tag 5PXVQ7NNERYRYRJKGDBQ 2028-02-29 09:31:56,372 Sending RemoteStartTransaction.conf Accepted 2028-02-29 09:31:56,386 FINISHING_UNLOCKED -> TRANSACTION 2028-02-29 09:31:56,421 Sending StatusNotification.req: Status SuspendedEVSE for connector 1 2028-02-29 09:31:56,495 Received message [3, "7", {}] (len 12) 2028-02-29 09:31:56,495 Received StatusNotification.conf for connector 1 2028-02-29 09:32:39,023 Received message [2, "5df50004-4331-3795-c346-bdee912be3c (len 119) 2028-02-29 09:32:39,024 Received RemoteStartTransaction.req for connector 1 and tag 6PE6E4DYL7RCZGAYDFXQ 2028-02-29 09:32:39,035 Sending RemoteStartTransaction.conf Accepted 2028-02-29 09:32:39,050 Ignoring remote start transaction in state TRANSACTION 2028-02-29 09:32:48,898 Sending Heartbeat.req. 196282 196282 256282 60000 2028-02-29 09:32:49,011 Received message [3, "8", {"currentTime":"2022-10-25T13:5 (len 52) 2028-02-29 09:32:49,012 Received Heartbeat.conf for connector 0 So sieht der EVSE aktuell aus: @rtrbt, hast du vielleicht noch eine spontane Idee? EDIT: Was mir noch aufgefallen ist: Die Heartbeats kommen für Connector 0, die Steuerungsbefehle für Connector 1. Gibt's da vielleicht noch irgendwo ein "+/-1 Problem"? Oder muss der Ladepunkt bei eCarUp noch weiter konfiguriert werden? Vielen Dank & Gruß Thomas

Yeah, das hat funktioniert 🙂 Jetzt werde ich das mal testen... Vielen Dank @rtrbt!

Moin @rtrbt, kein Problem, besten Dank. Die reguläre WARP2-Konfiguration lässt sich erfolgreich bauen, aber wenn ich das Modul "EVSE_V2" durch "EVSE" austausche, dann erhalte ich leider eine ganze Menge Compilefehler. Einige lassen sich durch kleine Codeanpassungen beheben, aber spätestens bei "get_ocpp_current()" bzw. "set_ocpp_current()" hört's dann doch auf, da diese Methoden bislang nur in "EVSE_V2" implementiert sind. Zieht ihr das noch in das Modul "EVSE" nach oder muss/soll ich mich da selbst drum kümmern? Vielen Dank & Gruß Thomas

Danke für die Blumen, @floho Ich halte meinen Fork des Repos eigentlich immer relativ aktuell, d. h. ich kann hier gerne auch die kompilierte Firmware zur Verfügung stellen. "WARP1 on Steroids" gefällt mir 🙃 Gruß Thomas

Hallo @Little_Company, ich habe die vorhandenen Abstandshalter lediglich etwas versetzt, sodass die Aussparung für den Taster frei bleibt; der Stecker der Stromversorgung passt dann durch den länglichen Schlitz (unterhalb des Bricks). Der Umbau selbst war eine Sache von 1 1/2 Stunden und die Kosten von ca. 60,-€ für den ESP32-Ethernet-Brick sind m. E. überschaubar. Allerdings kann man nicht die hier im Forum gepostete Beta-Firmware für WARP2 verwenden (wegen EVSE_V2), sodass man diese definitiv entsprechend anpassen und selbst bauen muss. Aber auch das ist kein Hexenwerk… Leider ist der Code für das OCPP-Modul noch nicht im GitHub-Repository, daher konnte ich diese Funktionalität bislang noch nicht testen. Ich gehe aber davon aus, dass @rtrbt das Repo in den nächsten Tagen entsprechend aktualisieren wird. Das „normale“ Laden inkl. Steuerung durch EVCC funktioniert aber problemlos. Ich halte dich auf dem Laufenden… Gruß Thomas

Kein Problem: Die größte Herausforderung (nach 20 Jahren "Lötabstinenz") war tatsächlich, den Stecker der Stromversorgung vom ESP32-Brick abzulöten und auf dem ESP32-Ethernet-Brick wieder anzulöten. Ansonsten habe ich lediglich ein paar neue Löcher in die Abdeckplatte gebohrt und die Abstandshalter entsprechend versetzt, sodass der größere Brick draufpasst, ohne die Aussparung des Tasters zu überdecken. Danach noch die Firmware entsprechend angepasst (d. h. Modul EVSE anstelle von EVSE_V2 verwenden) und fertig... 🙃 EDIT: So sieht das jetzt aus.