WARP2 Beta-Firmware mit OCPP-Unterstützung

rtrbt · November 2, 2022 at 10:27

On 10/29/2022 at 2:11 PM, poohnet said:

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

Die Zählerwerte sind gefühlt das komplizierteste an OCPP. Grundlegend läuft das so, dass der Server der Wallbox vorgeben kann, welche Zählerwerte periodisch (clock-aligned, also z.b. "immer zur vollen Stunde") und in einem Intervall während Transaktionen ("sampled", also z.B. "nach Transaktionsbeginn minütlich bis Transaktionsende") übertragen werden sollen. Außerdem konfiguriert der Server die Periode bzw. Intervalllänge.

Je nach der Konfiguration muss die Wallbox dann von sich aus MeterValues-Nachrichten schicken, die diese Werte beinhalten. D.h. das ist der Teil, den entweder eCarUp nicht konfiguriert (wüsste spontan auch nicht, wo im eCarUp-Webinterface die Werte angezeigt werden sollten), oder der bei uns kaputt ist.

Zusätzlich dazu kann eine StopTransaction-Nachricht auch Zählerwerte enthalten (wieder jeweils verschiedene für clock-aligned und sampled!), die dann über die gesamte Transaktion gesammelt werden müssen. Den Teil unterstützen wir im Moment nicht, weil dann je nach Server-Konfiguration der RAM einfach zu klein ist (z.b. wenn man sekündlich alle Werte aufzeichen soll und eine Transaktion ja beliebig lange dauern kann). Glücklicherweise gibt es Konfigurationswerte mit denen ich dem Server mitteilen kann, wie viele Messwerte maximal in einer StopTransaction-Nachricht enthalten sein können. Das habe ich einfach auf 0 gesetzt.

Außerdem gibt es noch die direkten Energiewerte in Start- und StopTransaction. Die müssen immer enthalten sein und sind einfach der Zählerstand beim Start bzw. Ende der Transaktion.

poohnet · November 2, 2022 at 13:40

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

rtrbt · November 4, 2022 at 08:15

On 11/2/2022 at 2:40 PM, poohnet said:

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

Wie hast du es hier geschafft, einen zweiten RemoteStartTransaction.req zu schicken, wenn schon eine Transaktion läuft? Ich habe das gestern Nachmittag probiert, eCarUp lässt mich aber wenn eine Transaktion läuft (dann steht in deren Webinterface als Status "Besetzt"), nicht nochmal auf "Activate" klicken, stattdessen kann ich nur mit "Deactivate" ein RemoteStopTransaction.req schicken.

Abgesehen davon muss ich nochmal die Spezifikation überfliegen, wie genau ein RemoteStartTransaction zu beantworten ist, wenn schon eine Transaktion läuft.

On 11/2/2022 at 2:40 PM, poohnet said:

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

Das war kaputt. Weil ich da direkt von TRANSACTION nach IDLE gehe, schicke ich nie die StopTransaction-Nachricht raus. Habe ich gerade gefixt.

poohnet · November 4, 2022 at 09:30

1 hour ago, rtrbt said:

Wie hast du es hier geschafft, einen zweiten RemoteStartTransaction.req zu schicken, wenn schon eine Transaktion läuft? Ich habe das gestern Nachmittag probiert, eCarUp lässt mich aber wenn eine Transaktion läuft (dann steht in deren Webinterface als Status "Besetzt"), nicht nochmal auf "Activate" klicken, stattdessen kann ich nur mit "Deactivate" ein RemoteStopTransaction.req schicken.

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

bearbeitet November 4, 2022 at 09:31 von poohnet

poohnet · November 7, 2022 at 10:57

On 11/4/2022 at 9:15 AM, rtrbt said:
On 11/2/2022 at 2:40 PM, poohnet said:
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
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

rtrbt · November 7, 2022 at 12:30

Ah sorry, hatte ich schon am Freitag: https://github.com/Tinkerforge/tfocpp/commit/0db43d863f58b7b84914b4c37d060ec3f62006ef

Habe gerade esp32-firmware gepusht, sollte jetzt auf den aktuellen tfocpp-Commit verweisen.

poohnet · November 9, 2022 at 08:44

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

bearbeitet November 9, 2022 at 10:12 von poohnet

rtrbt · November 10, 2022 at 14:37

Ich habe erstmal eingebaut, dass der interne Zustand der OCPP-Implementierung im Webinterface angezeigt wird: https://github.com/Tinkerforge/esp32-firmware/commit/49f9e241514325584d251e108ddff23f12b6bcb4

Ich versuche jetzt nochmal, deine Probleme zu reproduzieren, vielleicht bekommen wir mit den zusätzlichen Ausgaben mehr raus.

poohnet · November 11, 2022 at 07:58

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

rtrbt · November 11, 2022 at 10:51

Ich teste selber noch gegen andere OCPP-Server, aber ansonsten wars das. Wir versuchen außerdem an das offizielle OCPP-Test-Tool zu kommen, das dauert aber vermutlich noch etwas.

rtrbt · November 11, 2022 at 14:32

Kurzes Update: Ich schaffe es jetzt auch auf verschiedene Arten, dass eCarUp-App und Webseite einen anderen Zustand anzeigen als tatsächlich der Fall ist (im Extremfall sogar dass eine Transaktion läuft aber eCarUp sagt das die schon beendet wurde).

Aber es gibt auch eine gute Nachricht: wss funktioniert wieder. Das muss auf deren Servern für ein paar Tage kaputt gewesen sein.

poohnet · November 12, 2022 at 14:27

23 hours ago, rtrbt said:

Kurzes Update: Ich schaffe es jetzt auch auf verschiedene Arten, dass eCarUp-App und Webseite einen anderen Zustand anzeigen als tatsächlich der Fall ist (im Extremfall sogar dass eine Transaktion läuft aber eCarUp sagt das die schon beendet wurde).

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?

23 hours ago, rtrbt said:

Aber es gibt auch eine gute Nachricht: wss funktioniert wieder.

Ja, kann ich bestätigen 😀

rtrbt · November 14, 2022 at 15:54

The plot thickens: Ich habe mir einen Switch mit Port-Mirroring besorgt um den Traffic zwischen eCarUp und dem WARP Charger mitlesen zu können und folgendes festgestellt: Wenn ich eine Transaktion per App/Webseite starte, dann stoppe (das Auto angeschlossen lasse) und wieder starte funktioniert alles wie erwartet. Wenn ich aber die zweite Transaktion stoppe, dauert das im eCarUp-Webinterface ein paar Sekunden länger, der Status springt dann aber auf "verbunden" um. Laut Wireshark schickt eCarUp dann aber nie den RemoteStopTransaction.req, deshalb läuft die Transaktion bei uns weiter. Wenn ich dann z.B. das Auto abziehe, antwortet eCarUp nie auf den StopTransaction.req.

Mit dem Switch konnte ich jetzt sehen, dass die Pakete wirklich nicht ankommen (bei RemoteStartTransaction) bzw. rausgehen aber von eCarUp nicht bestätigt werden (StopTransaktion). D.h. nach meinem Verständnis liegt das Problem nicht bei uns. Ich habe den eCarUp-Leuten mal einen Bugreport geschickt.

poohnet · November 14, 2022 at 17:49

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

rtrbt · November 15, 2022 at 10:34

SteVe kannst du auch in Docker laufen lassen, mache ich zumindest so.

rtrbt · November 17, 2022 at 14:08

Es gibt jetzt eine (vorkompilierte ;) ) zweite OCPP-Betaversion. Ich habe dafür einen eigenen Thread aufgemacht, da auch einige andere Features dazugekommen sind:

OCPP-Feedback gerne weiter hier.

poohnet · November 25, 2022 at 07:22

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

rtrbt · November 25, 2022 at 10:34

3 hours ago, poohnet said:

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

Das stimmt, das ist aber okay. Die Wallbox wartet nur eine gewisse Zeit auf ein NFC-Tag, bevor sie nach Finishing geht. Aus Finishing kannst du aber mit einem Tag (oder RemoteStartTransaction, also z.B. per App) wieder nach Preparing gehen. Das passiert in deinem Fall auch, nur dass RemoteStartTransaction sofort nach Transaction geht weil das Kabel auch angesteckt ist.

Folgendes ist aber sehr interessant: Beim Übergang nach Transaction sollte ein StartTransaction.req geschickt werden, das passiert aber nicht. Das ist auf meiner Seite kaputt. Ich sehe mir das nochmal in Ruhe an (Änderungen an dem Zustands-Automaten sind immer etwas heikel).

poohnet · November 25, 2022 at 13:08

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

rtrbt · November 25, 2022 at 14:10

Habe den Fix gleich in OCPP-Beta 3 gepackt.

poohnet · November 25, 2022 at 19:41

Wow, super 🤩

Ich baue dann morgen mal eine neue Version und teste weiter…

Gruß und ein schönes Wochenende
Thomas

poohnet · November 26, 2022 at 12:47

Moin @rtrbt,

so, ich habe heute Vormittag eine ganze Reihe Tests gemacht und die gute Nachricht ist, dass im Normalfall nun anscheinend alles richtig funktioniert! Vielen Dank dafür 🙂

Allerdings habe ich es (mit einer zugegebenermaßen nicht ganz sauberen Vorhensweise) trotzdem reproduzierbar geschafft, WARP und eCarUp doch noch aus dem Tritt zu bringen. Wenn man nämlich eine Ladung über die eCarUp-App startet und diese anschließend wieder beendet, ohne zuvor EVCC freigegeben zu haben (also letztendlich gar nicht lädt), dann bleibt der Ladevorgang in der App weiterhin aktiv und WARP bekommt wieder keine Heartbeats mehr:

2022-11-26 12:20:34,132  Sending Heartbeat.req. 197608 197608 257608 60000
2022-11-26 12:20:34,230  Received Heartbeat.conf for connector 0
2022-11-26 12:21:33,961  Sending Heartbeat.req. 257669 257669 317669 60000
2022-11-26 12:21:34,059  Received Heartbeat.conf for connector 0
2022-11-26 12:21:46,422  IDLE -> NO_TAG
2022-11-26 12:21:46,423  Sending StatusNotification.req: Status Preparing for connector 1
2022-11-26 12:21:46,512  Received StatusNotification.conf for connector 1
2022-11-26 12:21:46,885  Charger state changed from 0 to 1
2022-11-26 12:22:17,600  Wrote last uptime to flash
2022-11-26 12:22:20,942  Received RemoteStartTransaction.req for connector 1 and tag TV2DVLAZELX3FLCHVS5A
2022-11-26 12:22:20,942  Sending RemoteStartTransaction.conf Accepted
2022-11-26 12:22:20,958  NO_TAG -> TRANSACTION
2022-11-26 12:22:21,001  Sending StartTransaction.req at connector 1 for tag TV2DVLAZELX3FLCHVS5A at 741.195 kWh.
2022-11-26 12:22:21,007  Sending StatusNotification.req: Status SuspendedEVSE for connector 1
2022-11-26 12:22:21,022  Evaluating charging profiles
2022-11-26 12:22:21,022      Connector 1
2022-11-26 12:22:21,023      Profile evaluation done. Distributing limit
2022-11-26 12:22:21,033      Currents distributed:
2022-11-26 12:22:21,033      	ConnID	Allowed	Phases	MinRate
2022-11-26 12:22:21,044      	0	32.000	3	0.000
2022-11-26 12:22:21,044      	1	32.000	3	0.000
2022-11-26 12:22:21,045      Next check: never
2022-11-26 12:22:21,055  
2022-11-26 12:22:21,055  Setting connector 1 limit to 32000
2022-11-26 12:22:21,106  Received StartTransaction.conf for connector 1
2022-11-26 12:22:21,172  Evaluating charging profiles
2022-11-26 12:22:21,173      Connector 1
2022-11-26 12:22:21,173      Profile evaluation done. Distributing limit
2022-11-26 12:22:21,183      Currents distributed:
2022-11-26 12:22:21,183      	ConnID	Allowed	Phases	MinRate
2022-11-26 12:22:21,194      	0	32.000	3	0.000
2022-11-26 12:22:21,195      	1	32.000	3	0.000
2022-11-26 12:22:21,195      Next check: never
2022-11-26 12:22:21,205  
2022-11-26 12:22:21,206  Setting connector 1 limit to 32000
2022-11-26 12:22:21,263  Received StatusNotification.conf for connector 1
2022-11-26 12:22:33,910  Sending Heartbeat.req. 317677 317677 377677 60000
2022-11-26 12:22:34,002  Received Heartbeat.conf for connector 0
2022-11-26 12:23:33,985  Sending Heartbeat.req. 377754 377754 437754 60000
2022-11-26 12:23:34,088  Received Heartbeat.conf for connector 0
2022-11-26 12:23:48,441  Received RemoteStopTransaction.req for txn 1669461741
2022-11-26 12:23:48,442  TRANSACTION -> FINISHING_UNLOCKED
2022-11-26 12:23:48,476  Sending StatusNotification.req: Status Finishing for connector 1
2022-11-26 12:23:48,522  Sending StopTransaction.req at connector 1 for tag  at 741.195 kWh. StopReason 7
2022-11-26 12:23:48,553  Sending RemoteStopTransaction.conf Accepted (connector 0)
2022-11-26 12:23:48,570  Evaluating charging profiles
2022-11-26 12:23:48,571      Connector 1
2022-11-26 12:23:48,571      Profile evaluation done. Distributing limit
2022-11-26 12:23:48,581      Currents distributed:
2022-11-26 12:23:48,582      	ConnID	Allowed	Phases	MinRate
2022-11-26 12:23:48,592      	0	32.000	3	0.000
2022-11-26 12:23:48,593      	1	32.000	3	0.000
2022-11-26 12:23:48,593      Next check: never
2022-11-26 12:23:48,603  
2022-11-26 12:23:48,604  Setting connector 1 limit to 32000
2022-11-26 12:23:48,658  Received StatusNotification.conf for connector 1
2022-11-26 12:24:33,899  Sending Heartbeat.req. 437757 437757 497757 60000
2022-11-26 12:25:33,998  Sending Heartbeat.req. 497857 497857 557857 60000
2022-11-26 12:25:51,441  FINISHING_UNLOCKED -> IDLE
2022-11-26 12:25:51,442  Sending StatusNotification.req: Status Available for connector 1
2022-11-26 12:25:52,015  Charger state changed from 1 to 0
2022-11-26 12:26:34,021  Sending Heartbeat.req. 557879 557879 617879 60000

Beim Zurücksetzen des OCPP-Status bin ich dann auch noch über einen bösen Bug gestolpert, denn dieser scheint in der aktuellen Version nun einem Factory-Reset gleichzukommen. Jedenfalls war anschließend meine gesamte Konfiguration inkl. aller aufgezeichneter Ladevorgänge weg... 😢

Nachdem ich alles wieder richtig eingestellt hatte, hat es übrigens noch fast 20 Minuten gedauert, bis WARP und eCarUp wieder "in sync" waren. Die App hat nämlich im Minutentakt weiterhin "RemoteStopTransaction.req" gesendet, die aber von WARP zurückgewiesen wurden. Irgendwann hat das dann aber aufgehört und die Box wurde wieder als "frei" aufgeführt.

                 16,564  OCPP WEBSOCKET CONNECTED
                 16,642  Sending BootNotification.req. 16641 0 60000 60000
                 16,744  Received BootNotification.conf for connector 0. Interval 60
2022-11-26 12:39:00,000  Sending StatusNotification.req: Status Available for connector 0
2022-11-26 12:39:00,011  Sending StatusNotification.req: Status Available for connector 1
2022-11-26 12:39:00,030  Sending StatusNotification.req: Status Available for connector 0
2022-11-26 12:39:00,046  Evaluating charging profiles
2022-11-26 12:39:00,047      Connector 1
2022-11-26 12:39:00,047      Profile evaluation done. Distributing limit
2022-11-26 12:39:00,057      Currents distributed:
2022-11-26 12:39:00,057      	ConnID	Allowed	Phases	MinRate
2022-11-26 12:39:00,068      	0	32.000	3	0.000
2022-11-26 12:39:00,069      	1	32.000	3	0.000
2022-11-26 12:39:00,069      Next check: never
2022-11-26 12:39:00,079  
2022-11-26 12:39:00,079  Setting connector 1 limit to 32000
2022-11-26 12:39:00,147  Received StatusNotification.conf for connector 0
2022-11-26 12:39:00,287  Received StatusNotification.conf for connector 1
2022-11-26 12:39:00,399  Received StatusNotification.conf for connector 0
2022-11-26 12:39:04,077  NTP synchronized at 20,446!
2022-11-26 12:39:24,242  Received RemoteStopTransaction.req for txn 1669461741
2022-11-26 12:39:24,242  Sending RemoteStopTransaction.conf Rejected (unknown transaction id)
2022-11-26 12:39:49,672  This is warp2-XSS (warp2-XSS), a WARP2 Charger Pro 11kW
2022-11-26 12:40:00,344  Sending Heartbeat.req. 76713 76713 136713 60000
2022-11-26 12:40:00,442  Received Heartbeat.conf for connector 0
2022-11-26 12:40:25,495  Received RemoteStopTransaction.req for txn 1669461741
2022-11-26 12:40:25,495  Sending RemoteStopTransaction.conf Rejected (unknown transaction id)
2022-11-26 12:40:59,951  Sending Heartbeat.req. 136763 136763 196763 60000
2022-11-26 12:41:01,946  Received Heartbeat.conf for connector 0
2022-11-26 12:41:29,315  Received RemoteStopTransaction.req for txn 1669461741
2022-11-26 12:41:29,315  Sending RemoteStopTransaction.conf Rejected (unknown transaction id)
2022-11-26 12:42:00,084  Sending Heartbeat.req. 196843 196843 256843 60000
2022-11-26 12:42:00,182  Received Heartbeat.conf for connector 0
2022-11-26 12:42:29,979  Received RemoteStopTransaction.req for txn 1669461741
2022-11-26 12:42:29,979  Sending RemoteStopTransaction.conf Rejected (unknown transaction id)

Gruß Thomas

poohnet · November 26, 2022 at 19:36

'n Abend @rtrbt,

ich habe mich heute Nachmittag mal etwas mit SteVe beschäftigt und kann das Problem auch hier reproduzieren. Wenn zwischen "RemoteStartTransaction" und "RemoteStopTransaction" kein "Sending StatusNotification.req: Status Charging for connector 1" gesendet wird, dann wird "RemoteStopTransaction" zwar mit "Accepted" quittiert, die Transaktion bleibt aber offen.

Weitere "RemoteStopTransaction" werden von WARP dann mit "unknown transaction id" rejected.

Ist das evtl. eine Definitionslücke in der Spezifikation?

Gruß Thomas

rtrbt · November 28, 2022 at 08:43

On 11/26/2022 at 1:47 PM, poohnet said:

Beim Zurücksetzen des OCPP-Status bin ich dann auch noch über einen bösen Bug gestolpert, denn dieser scheint in der aktuellen Version nun einem Factory-Reset gleichzukommen. Jedenfalls war anschließend meine gesamte Konfiguration inkl. aller aufgezeichneter Ladevorgänge weg... 😢

Das ist natürlich fatal, kann ich hier aber nicht reproduzieren. Kannst du das nochmal ausprobieren?

On 11/26/2022 at 1:47 PM, poohnet said:

Wenn man nämlich eine Ladung über die eCarUp-App startet und diese anschließend wieder beendet, ohne zuvor EVCC freigegeben zu haben (also letztendlich gar nicht lädt), dann bleibt der Ladevorgang in der App weiterhin aktiv und WARP bekommt wieder keine Heartbeats mehr

Das müsste wieder der Bug sein, den ich eCarUp gemeldet habe: Mein StopTransaction.req wird nicht bestätigt und dann läuft der Zustand auf der WARP und bei eCarUp auseinander. Ich muss da nochmal mit Wireshark draufgucken, das Timing sieht bei dir interessant aus. Da du das auch mit SteVe erzeugen kannst, ist das natürlich noch seltsamer. Sehe ich mir mal an.

poohnet · November 28, 2022 at 17:57

9 hours ago, rtrbt said:

Das ist natürlich fatal, kann ich hier aber nicht reproduzieren. Kannst du das nochmal ausprobieren?

Hab ich mich noch nicht getraut, aber wahrscheinlich laufe ich demnächst eh wieder in eine Situation, bei dem ich den OCPP-Status zurücksetzen muss… 🙃

9 hours ago, rtrbt said:

Da du das auch mit SteVe erzeugen kannst, ist das natürlich noch seltsamer.

Ich probiere das morgen Nachmittag nochmal aus und hänge die Logs an.

WARP2 Beta-Firmware mit OCPP-Unterstützung

Recommended Posts

Top Posters In This Topic

Popular Days

Top Posters In This Topic

Popular Days

Popular Posts

rtrbt

rtrbt

rtrbt

Posted Images

Join the conversation