rtrbt Posted November 2, 2022 at 10:27 AM Author Posted November 2, 2022 at 10:27 AM 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. Quote
poohnet Posted November 2, 2022 at 01:40 PM Posted November 2, 2022 at 01:40 PM 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 Quote
rtrbt Posted November 4, 2022 at 08:15 AM Author Posted November 4, 2022 at 08:15 AM 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. Quote
poohnet Posted November 4, 2022 at 09:30 AM Posted November 4, 2022 at 09:30 AM (edited) 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... Edited November 4, 2022 at 09:31 AM by poohnet Quote
poohnet Posted November 7, 2022 at 10:57 AM Posted November 7, 2022 at 10:57 AM 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 Quote
rtrbt Posted November 7, 2022 at 12:30 PM Author Posted November 7, 2022 at 12:30 PM 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. 1 Quote
poohnet Posted November 9, 2022 at 08:44 AM Posted November 9, 2022 at 08:44 AM (edited) 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 Edited November 9, 2022 at 10:12 AM by poohnet Quote
rtrbt Posted November 10, 2022 at 02:37 PM Author Posted November 10, 2022 at 02:37 PM 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. Quote
poohnet Posted November 11, 2022 at 07:58 AM Posted November 11, 2022 at 07:58 AM 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 Quote
rtrbt Posted November 11, 2022 at 10:51 AM Author Posted November 11, 2022 at 10:51 AM 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. Quote
rtrbt Posted November 11, 2022 at 02:32 PM Author Posted November 11, 2022 at 02:32 PM 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. Quote
poohnet Posted November 12, 2022 at 02:27 PM Posted November 12, 2022 at 02:27 PM 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 😀 Quote
rtrbt Posted November 14, 2022 at 03:54 PM Author Posted November 14, 2022 at 03:54 PM 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. Quote
poohnet Posted November 14, 2022 at 05:49 PM Posted November 14, 2022 at 05:49 PM 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 Quote
rtrbt Posted November 15, 2022 at 10:34 AM Author Posted November 15, 2022 at 10:34 AM SteVe kannst du auch in Docker laufen lassen, mache ich zumindest so. Quote
rtrbt Posted November 17, 2022 at 02:08 PM Author Posted November 17, 2022 at 02:08 PM 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. 1 Quote
poohnet Posted November 25, 2022 at 07:22 AM Posted November 25, 2022 at 07:22 AM 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 Quote
rtrbt Posted November 25, 2022 at 10:34 AM Author Posted November 25, 2022 at 10:34 AM 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). Quote
poohnet Posted November 25, 2022 at 01:08 PM Posted November 25, 2022 at 01:08 PM 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... Quote
rtrbt Posted November 25, 2022 at 02:10 PM Author Posted November 25, 2022 at 02:10 PM Habe den Fix gleich in OCPP-Beta 3 gepackt. 1 Quote
poohnet Posted November 25, 2022 at 07:41 PM Posted November 25, 2022 at 07:41 PM Wow, super 🤩 Ich baue dann morgen mal eine neue Version und teste weiter… Gruß und ein schönes Wochenende Thomas Quote
poohnet Posted November 26, 2022 at 12:47 PM Posted November 26, 2022 at 12:47 PM 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 Quote
poohnet Posted November 26, 2022 at 07:36 PM Posted November 26, 2022 at 07:36 PM '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 Quote
rtrbt Posted November 28, 2022 at 08:43 AM Author Posted November 28, 2022 at 08:43 AM 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. Quote
poohnet Posted November 28, 2022 at 05:57 PM Posted November 28, 2022 at 05:57 PM 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. Quote
Recommended Posts
Join the conversation
You can post now and register later. If you have an account, sign in now to post with your account.