Seite 1 von 2
openWB-Pro ladet nicht
Verfasst: Sa Aug 27, 2022 11:13 am
von jub
Hallo,
ich habe schon ein Support Ticket erstellt, aber vielleicht gibt es übers WE ein Tipp aus dem Forum (ich sollte nämlich das Auto bald laden, hab aber die Starkstromdose für die Wallbox geopfert...).
Die openWB-Pro (Limit wurde auf 16A und 3 Phasen am Poti geändert) wurde vom Elektriker angeschlossen, ohne LAN und bei seinem Test ging es nicht - ein Relais hat kurz geklackt. Ich war dann für ein paar Tage weg und habe erst dann mit dem Auto getestet. Da dann aber mit Netzwerk und einem raspberry Pi als Steuergerät. Bei mir dann dasselbe, zumindest manchmal hat das Relais kurz geschalten, das Auto blieb aber im Initialisierungsmodus. Auf der Statusseite sehe ich auch keine Änderung bei EV Present, oder Heartbeat, Current Limit steht immer bei 10 A.
Ich bin ein Freund vom updaten, stimmt es, dass man auf die Pro Version nicht direkt zugreifen kann und ein Update starten kann? Die Wallbox hat 0.9.14.
Ich habe schon im Forum gesucht und an den Kabelverbindungen gewackelt, alles fest - achja, die LED1 ist grün, LED2 macht nichts, LED3 blinkt rot. Ich habe leider das Logfile nicht auf diesem Rechner, nur als Screenshot nun als Text:
Debug Output:
Aug 26 19:02:43 openWBPro-575262 iec61851d(251]: [19:02:43.045] [INFO]EV
connected. CP state "B'
Aug 26 19:02:43 openWBPro-575262 iec61851d(251]: [19:02:43.048] [INFO] main loop:
close lock
Aug 26 19:02:43 openWBPro-575262 iec61851d[251]: [19:02:43.054] [INFO]
IEC STATE CONTACTOR OPEN LOCK CLOSE
Aug 26 19:02:43 openWBPro-575262 pwmd[270]: [19:02:43.055] [INFO] main loop: set
new CP state: B (CP (+) 9024 (-) 46 mV)
Aug 26 19:02:44 openWBPro-575262 iec61851d[251]: [19:02:44.052] [INFO] main loop:
setting new duty cycle 16.667%
Aug 26 19:02:44 openWBPro-575262 pwmd[270]: [19:02:44.057] [INFO] changed CP
enable 1, duty cycle 16.660 %
Aug 26 19:02:48 openWBPro-575262 iec61851d[251]: [19:02:48.252] [INFO] main loop:
close contactor
Aug 26 19:02:48 openWBPro-575262 pwmd[270]: [19:02:48.261] [INFO] main loop: set
new CP state: C (CP (+) 6044 (-) -11874 mV)
Aug 26 19:02:48 openWBPro-575262 iec61851d(251]: [19:02:48.462] [INFO] main loop:
setting new duty cycle 0.000%
Aug 26 19:02:48 openWBPro-575262 pwmd[270]: [19:02:48.469] [INFO] changed CP
enable 1, duty cycle 0.000 %
Aug 26 19:02:48 openWBPro-575262 pwmd[270]: [19:02:48.501] [INFO] main loop: set
new CP state: F (CP (+)-31 (-) -11874 mV)
Aug 26 19:02:48 openWBPro-575262 iec61851d[251]: [19:02:48.540] [INFO]EV
disconnected. CP state 'F'
Aug 26 19:02:48 openWBPro-575262 iec61851d(251]: [19:02:48.764] ERROR]
Timeout 500 ms reached before state:
IEC STATE CONTACTOR OPEN LOCK CLOSE (2) ->
IEC STATE CONTACTOR CLOSE LOCK_ CLOSE (3) (target
IEC STATE CONTACTOR OPEN LOCK OPEN (1)
Aug 26 19:02:48 openWBPro-575262 iec61851d[251]: [19:02:48.764] [INFO]forcing
safe state
Aug 26 19:02:48 openWBPro-575262 iec61851d(251]: [19:02:48.771] (ERROR] main
loop: new failure IEC FAILURE TIMEOUT (wait for 4000)
Aug 26 19:02:48 openWBPro-575262 iec61851d(251]: [19:02:48.771] [INFO] main loop:
oben lock
............
............
hlc contactor state target: 0
Aug 26 21:17:24 openWBPro-575262 meteringd([256]: [21:17:24.039] [ERROR] error
querying meter initially: Connection timed out
Aug 26 21:17:24 openWBPro-575262 meteringd[256]: [21:17:24.695] [ERROR] error
querying meter initially: Connection timed out
Aug 26 21:17:24 openWBPro-575262 meteringd[256]: [21:17:24.696] [INFO] Starting
meteringd 0.6
Aug 26 21:17:25 openWBPro-575262 ocppd[300]: [21:17:25.096] [ERROR]
GetAvailabilityFlags() failed to open file '/var/lib/ocppd/ocpp_data.json' (falling back to
"Operative"): No such file or directory
Aug 26 21:17:25 openWBPro-575262 ocppd[300]: [21:17:25.101] [INFO]/usr/bin/ocppd
(ocppd 0.11)
Aug 26 21:17:25 openWBPro-575262 ocppd[300]: [21:17:25.107] [INFO] using local
state directory '/var/lib/ocppd'
Aug 26 21:17:25 openWBPro-575262 ocppd[300]: [21:17:25.113] [INFO] upload thread
started
Aug 26 21:17:25 openWBPro-575262 ocppd[300]: [21:17:25.138] [INFO]
GetGlobalConfigurationValue(".ocpp.enable")got'false'
Aug 26 21:17:25 openWBPro-575262 ocppd[300]: [21:17:25.138] [INFO] ocpp disabled
by config, terminating (doing nothing forever)
Aug 26 21:17:25 openWBPro-575262 updated[302]: [21:17:25.401] [INFO] Starting
updated 0.3
Aug 26 21:17:25 openWBPro-575262 sh[315]: Warning: Unable to locate configuration
directory, default config not loaded
Aug 26 21:59:26 openWBPro-575262 iec61851d[251]: [21:59:26.236] [INFO]EV
connected, CP state 'B'
Aug 26 21:59:26 openWBPro-575262 iec61851d[251]: [21:59:26.239] [INFO] main loop:
close lock
Aug 26 21:59:26 openWBPro-575262 iec61851d[251]: [21:59:26.242] [INFO]
IEC STATE CONTACTOR OPEN LOCK CLOSE
Aug 26 21:59:26 openWBPro-575262 pwmd(267]: [21:59:26.246] [INFO] main loop: set
new CP state: B (CP (+) 9015 (-) 7 mV)
Aug 26 21:59:27 openWBPro-575262 iec61851d[251]: [21:59:27.248] [INFO] main loop:
setting new duty cycle 16.667%
Aug 26 21:59:27 openWBPro-575262 pwmd(267]: [(21:59:27.263] [INFO] changed CP
enable 1, duty cycle 16.660 %
Aug 26 21:59:31 openWBPro-575262 iec61851d(251l: (21:59:31.5481 [INFO]mainlo0p:
close contactor
Aug 26 21:59:31 openWBPro-575262 pwmd2671: (21:59:31.555] (INFO] main loop: set
new CP state: C (CP (+) 6035 (-) -11922 mV)
Aug 26 21:59:31 openWBPro-575262 iec61851d(251]: [21:59:31.768] [INFO] main loop:
setting new duty cycle 0.000%
Aug 26 21:59:31 openWBPro-575262 pwmd[267]: [21:59:31.775] [INFO] changed CP
enable 1, duty cycle 0.000 %
Aug 26 21:59:31 openWBPro-575262 pwmd(267]: [21:59:31.805] [INFO] main loop: set
new CP state: F (CP (+) -21 (-)-11912 mV)
Aug 26 21:59:31 openWBPro-575262 iec61851d[251]: [21:59:31.851] [INFO]EV
disconnected. CP state 'F'
Aug 26 21:59:32 openWBPro-575262 iec61851d[251]: [21:59:32.053] (ERROR]
Timeout 500 ms reached before state:
IEC STATE CONTACTOR OPEN LOCK CLOSE (2) ->
IEC_STATE_CONTACTOR_CLOSE_LOCK_CLOSE(3)(target
IEC STATE CONTACTOR OPEN LOCK OPEN (1)
Aug 26 21:59:32 openWBPro-575262 iec61851d[251]: [21:59:32.054] [INFO] forcing
safe state
Aug 26 21:59:32 openWBPro-575262 iec61851d(251]: [21:59:32.0601 (ERROR] main
loop: new failure IEC FAILURE TIMEOUT (wait for 4000)
Aug 26 21:59:32 openWBPro-575262 iec61851d(251]: [21:59:32.0601 [INFO1 main lo0D:
open lock
Aug 26 21:59:32 openWBPro-575262 iec61851d[251]: [21:59:32.080] [INFO]
IEC STATE CONTACTOR OPEN LOCK OPEN
Aug 26 21:59:36 openWBPro-575262 iec61851d[251]: [21:59:36.077] [INFO] main loop:
Failure stop
Copyright © openWB GmbH & Co. KG
mir wäre nichts Falsches aufgefallen, nur bei Electricity Meter hätte ich gemeint, kommen keine Daten daher (Timeout), die aber nötig sein könnten. Auf der Seite der openWB-Pro finde ich den Eastron.
Danke euch!
lg, jürgen
Re: openWB-Pro ladet nicht
Verfasst: Mo Aug 29, 2022 1:09 pm
von jub
Ich glaube, der Fehler liegt beim SDM72-Zähler: ich sehe da keinen Telefonhörer, also keine Datenübertragung. Hoffentlich brauchts da keinen neuen, sondern es ist ein Konfigurationsproblem…
Falls es was Neues gibt, werd ichs hier reinschreiben.
Re: openWB-Pro ladet nicht
Verfasst: Mi Aug 31, 2022 4:04 pm
von b3n
Bei mir ist auch ein ähnliches Problem:
Auf einer Wallbox blinkt das Energymeter und alle Menüeinträge laufen fortwährend durch. Electricy Meter steht "enabled" typ eastron, aber der Status ist "Not Available" das Laden funktioniert auch gar nicht.
charging stack Version ist 0.9.14 nach Update.
Auf einer anderen Wallbox klappt zwar das laden per ein / ausschalten über die Standalone / bzw. Master WB. Bei der wird 1ph 6A geladen, nachdem in der Master Box das Laden freigegeben wurde und ausgeschaltet, wenn ausgeschaltet wird. Die Stromvorgabe scheint auch korrekt in der Pro zu stehen, nur die Phasen stimmen jedoch nicht.
Der Support hat mir daraufhin geschrieben:
Bitte in den Einstellungen für Min+PV die Phasigkeit der Option "Automatische Umschaltung" auf einphasig stellen und speichern.
Nun den Modus Min+PV wählen, die Ladung wird kurz unterbrechen.
Nach ca. einer Minute wieder auf Sofort Laden ändern. Die Ladung wird binnen einer Minute erneut kurz unterbrechen und nun dreiphasig starten.
Das Triggern kann nach Neustarts oder Updates erforderlich sein. Die automatische Umschaltung hat mehrere Sicherheitseinrichtungen in Soft- und Hardware die dies verursachen können.
Nach Neustart ging das dann tatsächlich, die Phasenumschaltung von Sofort-Laden 3ph auf PV-Laden 1ph. Nach Änderung von PV wieder auf Automatik Modus wird wohl nicht in der Master WB registriert, dass eine Ladung stattfindet und die Phasenvorgabe verbleibt dann bei 1ph 6A. Das macht die Pro dann auch konsequenterweise.
Auf der Master WB wird weder der Zählerstand ausgelesen, noch die Ladeleistung noch die Ladung registriert. Im Status-Bereich steht überall 0. Konsequenterweise wird dann die trotzdem gezogene Ladeleistung dem Hausverbrauch zugerechnet. Das hat bei mir auch noch zu keinem Zeitpunkt funktioniert.
Es scheint zumindest one-way werden die Infos an die Pro gespielt und verarbeitet, aber zurück kommt nichts an. Auch der gelbe „EV eingesteckt“ Stecker bzw. grün wenns lädt erscheint nicht. Auch die Phasen 1 oder 3 werden nicht im Log, bzw. Echtzeitdarstellung angezeigt. Nur die Ladeleistungs-Stromvorgabe wird durchgereicht.
Die üblichen Problemlöseversuche sind ohne Erfolg und mein Support Ticket läuft auch schon über einen Monat.
Vielleicht hat ja jemand ähnliche Schwierigkeiten und Lösungsideen mit der Pro Steuerung und der Automatikumschaltung oder mit dem Energy Meter /bzw. dessen Verkabelung oder Anschluss in der Pro?
Re: openWB-Pro ladet nicht
Verfasst: Mi Aug 31, 2022 4:41 pm
von openWB
Hier kann ausschließlich der Support helfen.
Ich gehe hier aber von einer Verkettung von Problemen aus und hoffe das dies separiert mitgeteilt wird.
Mehrere Themen in einem Ticket sind erfahrungsgemäß nicht hilfreich.
Re: openWB-Pro ladet nicht
Verfasst: Mi Aug 31, 2022 5:41 pm
von jub
Ich vermute, dass der Support ziemlich viel Arbeit hat - aber: bei einem Problem ist es doch Balsam, wenn man was tun kann - da ist jeder Tipp wertvoll, um mitzuhelfen, und den Support ev. entlastet (weil man selber eine Lösung gefunden hat oder das Problem zumindest einschränken konnte).
Ich habe jetzt aus dem vorherigen Beitrag von b3n mehr erfahren, als vom Support und bin ihm - b3n - dankbar (auch wenn mich die Dauer des Tickets nicht fröhlich stimmt).
Es würde schon helfen, wenn man die Info von Hardware-Kennern bekommt, dass die Anzeige "Not available" kein Grund zum Nicht-Laden ist - für mich ist das ein Indiz, dass etwas mit der Hardware nicht stimmt (was andere hier im Forum ebenfalls beschrieben, dass der Zähler kaputt und getauscht wurde) - in meinem Fall kann ich die Software eher ausschließen, weil eben der Elektriker auch keine Messung (von Anfang an) mit dem Simulator starten konnte (plus keine Datenverbindung zur Wallbox).
Ist es ein Hardwareproblem kann ich nichts machen und brauche Ersatz - und ich möchte nicht tagelang warten müssen, bis diese Entscheidung getroffen wurde, ob es einen neuen Zähler (oder gar Box) braucht, der dann erst verschickt wird, wenn das für mich als Laie die offensichtlichste Erklärung ist. Ich bin etwas frustriert....
Re: openWB-Pro ladet nicht
Verfasst: Mi Aug 31, 2022 5:49 pm
von aiole
Frust nützt wie immer - NICHTS. Wenn der Support dran ist, helfen weitere Beobachtung des Problems, solide Beschreibungen, screenshots u.a..
Re: openWB-Pro ladet nicht
Verfasst: Mi Aug 31, 2022 7:27 pm
von jub
Versteh ich schon auch.... zwischenzeitlich bekam ich vom Support (dafür Danke, dass es um diese Zeit noch Hilfe gibt) den Hinweis, dass es die Modbus ID 1 beim SDM72 sein muss (war fälschlicherweise 105). Jetzt sehe ich den Zähler und Werte - das Auto lädt zwar noch nicht, aber da kann ich jetzt aktiv werden und meinerseits den Fehler in den Settings suchen. Und schon bin ich zufriedener! Das meinte ich - eine kleine Info zur richtigen ID und schon sieht es viel besser aus
Re: openWB-Pro ladet nicht
Verfasst: Mi Aug 31, 2022 9:50 pm
von aiole
Mmh, m.E. ist das keine "kleine" (einfach auf der Hand liegende) Info, sondern eine wichtige, die
a) Erfahrung und
b) fachintensive Beschäftigung mit Deinem Problem bedingt.
Das solltest Du entsprechend schätzen, womit ich meine, dass oWB eben nicht der übliche 0815-OEM ist, wie Du es anfänglich kommuniziert hast.
Probleme können immer auftreten. Die Frage ist, in welcher Form man sie löst. Konstruktiv hat sich bis dato am besten bewährt.
Berichte bitte zur weiteren Funktion.
VG
Re: openWB-Pro ladet nicht
Verfasst: Do Sep 01, 2022 4:35 am
von jub
Ich werde versuchen, die Abläufe hier weiter zu dokumentieren, in der Hoffnung, dass es anderen bei der Fehlersuche hilft, aber auch gemeinschaftlich eine Lösung möglich sein könnte.
Also, der Zähler braucht die ID 1 - das ist wichtig
Ich werde heute Abend mit einer OpenWB 1.9 die Ansteuerung probieren.
Jetzt sind wir soweit, dass das Auto kurz erkannt wird, bleibt aber im Initialisierungsmodus, die Box klackst, und erkennt das Auto nicht mehr. Ich habe schon mehrere Einstellungen mit 1 oder 3 Phasen, mit oder ohne Umschaltung probiert. Das Auto wird erst nach einem Neustart wieder kurz erkannt.
Was jetzt auch funktioniert, ist die Programmierung über connected.php - ein Update ging, heartbeat aus und einschalten auch, im Logfile sieht man, dass der Heartbeat gefehlt hat (bei 21:10 Uhr):
Code: Alles auswählen
Debug Output:
Aug 31 18:35:55 openWBPro-575262 hwinitd[144]: [09:05:16.319] [INFO] Starting hwinitd 0.2
Aug 31 18:35:55 openWBPro-575262 hwinitd[144]: [09:05:16.370] [INFO] Shutting down with exit code 0
Aug 31 18:35:59 openWBPro-575262 rotaryencd[234]: [18:35:59.799] [INFO] Entry[0] phase_count 1 current_limit 6
Aug 31 18:35:59 openWBPro-575262 rotaryencd[234]: [18:35:59.803] [INFO] Entry[1] phase_count 1 current_limit 10
Aug 31 18:35:59 openWBPro-575262 rotaryencd[234]: [18:35:59.803] [INFO] Entry[2] phase_count 1 current_limit 13
Aug 31 18:35:59 openWBPro-575262 rotaryencd[234]: [18:35:59.803] [INFO] Entry[3] phase_count 1 current_limit 16
Aug 31 18:35:59 openWBPro-575262 rotaryencd[234]: [18:35:59.804] [INFO] Entry[4] phase_count 1 current_limit 20
Aug 31 18:35:59 openWBPro-575262 rotaryencd[234]: [18:35:59.804] [INFO] Entry[5] phase_count 1 current_limit 32
Aug 31 18:35:59 openWBPro-575262 rotaryencd[234]: [18:35:59.804] [INFO] Entry[6] phase_count 1 current_limit 40
Aug 31 18:35:59 openWBPro-575262 rotaryencd[234]: [18:35:59.804] [INFO] Entry[7] phase_count 1 current_limit 63
Aug 31 18:35:59 openWBPro-575262 rotaryencd[234]: [18:35:59.804] [INFO] Entry[8] phase_count 3 current_limit 6
Aug 31 18:35:59 openWBPro-575262 rotaryencd[234]: [18:35:59.805] [INFO] Entry[9] phase_count 3 current_limit 10
Aug 31 18:35:59 openWBPro-575262 rotaryencd[234]: [18:35:59.805] [INFO] Entry[10] phase_count 3 current_limit 13
Aug 31 18:35:59 openWBPro-575262 rotaryencd[234]: [18:35:59.805] [INFO] Entry[11] phase_count 3 current_limit 16
Aug 31 18:35:59 openWBPro-575262 rotaryencd[234]: [18:35:59.806] [INFO] Entry[12] phase_count 3 current_limit 20
Aug 31 18:35:59 openWBPro-575262 rotaryencd[234]: [18:35:59.808] [INFO] Entry[13] phase_count 3 current_limit 32
Aug 31 18:35:59 openWBPro-575262 rotaryencd[234]: [18:35:59.846] [INFO] Entry[14] phase_count 3 current_limit 40
Aug 31 18:35:59 openWBPro-575262 rotaryencd[234]: [18:35:59.847] [INFO] Entry[15] phase_count 3 current_limit 63
Aug 31 18:36:00 openWBPro-575262 rotaryencd[234]: [18:36:00.118] [INFO] Publish grid current limit 16 A, phase count 3
Aug 31 18:36:00 openWBPro-575262 rotaryencd[234]: [18:36:00.153] [INFO] shutting down with exit code 0
Aug 31 18:36:00 openWBPro-575262 watchdogd[239]: [18:36:00.601] [INFO] enter init loop [ ]
Aug 31 18:36:01 openWBPro-575262 gpioauthd[243]: [18:36:01.250] [INFO] starting main loop with 0 fds in poll_fds
Aug 31 18:36:01 openWBPro-575262 configd[242]: [18:36:01.324] [INFO] ConfigD module initialized
Aug 31 18:36:01 openWBPro-575262 configd[242]: [18:36:01.358] [INFO] Disconnected from the MQTT broker
Aug 31 18:36:01 openWBPro-575262 configd[242]: [18:36:01.361] [WARN] MQTT connection to broker lost, reconnecting
Aug 31 18:36:01 openWBPro-575262 configd[242]: [18:36:01.447] [INFO] ConfigD module stopped
Aug 31 18:36:01 openWBPro-575262 configd[242]: [18:36:01.448] [INFO] Disconnected from the MQTT broker
Aug 31 18:36:01 openWBPro-575262 configd[242]: [18:36:01.448] [INFO] Stopped MQTT module
Aug 31 18:36:01 openWBPro-575262 iec61851d[249]: [18:36:01.489] [INFO] /usr/bin/iec61851d (iec61851d 0.11)
Aug 31 18:36:01 openWBPro-575262 iec61851d[249]: [18:36:01.520] [INFO] enabling IEC_INIT_STATE_CABLE_CURRENT_LIMIT
Aug 31 18:36:01 openWBPro-575262 iec61851d[249]: [18:36:01.559] [INFO] option from config: force_wake_up = true
Aug 31 18:36:01 openWBPro-575262 iec61851d[249]: [18:36:01.593] [INFO] option from config: wake_up_after_timeout = false
Aug 31 18:36:01 openWBPro-575262 iec61851d[249]: [18:36:01.651] [INFO] switch3to1phase/switch_delay = 10000 ms
Aug 31 18:36:01 openWBPro-575262 hilinkd[248]: [18:36:01.705] [INFO] No APN configuration in config file
Aug 31 18:36:01 openWBPro-575262 hilinkd[248]: [18:36:01.711] [INFO] Shutting down (exit code 0)
Aug 31 18:36:01 openWBPro-575262 iso15118d[252]: [18:36:01.854] [INFO] /usr/bin/iso15118d (iso15118d 0.11)
Aug 31 18:36:02 openWBPro-575262 lockctrld[254]: [18:36:01.993] [INFO] starting init loop
Aug 31 18:36:02 openWBPro-575262 lockctrld[254]: [18:36:02.009] [INFO] worker thread started
Aug 31 18:36:02 openWBPro-575262 iso15118d[252]: [18:36:02.042] [INFO] Configured HLC protocols: 0x4
Aug 31 18:36:02 openWBPro-575262 iso15118d[252]: [18:36:02.047] [INFO] Charging type: basic
Aug 31 18:36:02 openWBPro-575262 iso15118d[252]: [18:36:02.134] [INFO] TCP server on eth1 is listening on port [fe80::201:87ff:fe13:34f9%3]:61341
Aug 31 18:36:02 openWBPro-575262 iso15118d[252]: [18:36:02.135] [INFO] starting socket server(s)
Aug 31 18:36:02 openWBPro-575262 powerctrld[259]: [18:36:02.269] [INFO] Use relay 0 for primary contactor
Aug 31 18:36:02 openWBPro-575262 powerctrld[259]: [18:36:02.396] [INFO] using gpio 131 as primary contactor feedback gpio
Aug 31 18:36:02 openWBPro-575262 meteringd[256]: [18:36:02.407] [INFO] using metering protocol 'eastron', timeout 2000 ms
Aug 31 18:36:02 openWBPro-575262 meteringd[256]: [18:36:02.442] [INFO] Port: /dev/ttymxc0, Baudrate: 9600, Parity: N
Aug 31 18:36:02 openWBPro-575262 meteringd[256]: [18:36:02.442] [INFO] enabling local echo suppression
Aug 31 18:36:02 openWBPro-575262 powerctrld[259]: [18:36:02.517] [INFO] primary contactor feedback type = NO
Aug 31 18:36:02 openWBPro-575262 powerctrld[259]: [18:36:02.518] [INFO] Use relay 1 for secondary contactor
Aug 31 18:36:02 openWBPro-575262 meteringd[256]: [18:36:02.542] [INFO] Eastron SDM72D-M meter detected (S/N: 21939645, HW Revision: v2, 3P4W)
Aug 31 18:36:02 openWBPro-575262 meteringd[256]: [18:36:02.548] [INFO] Starting meteringd 0.6
Aug 31 18:36:02 openWBPro-575262 powerctrld[259]: [18:36:02.585] [INFO] using gpio 130 as secondary contactor feedback gpio
Aug 31 18:36:02 openWBPro-575262 powerctrld[259]: [18:36:02.704] [INFO] secondary contactor feedback type = NO
.....
.....
WBPro-575262 iec61851d[251]: [21:02:29.483] [INFO] enabling IEC_INIT_STATE_CP_STATE
Aug 31 21:02:29 openWBPro-575262 iec61851d[251]: [21:02:29.532] [INFO] init states achieved, setting initial safe state
Aug 31 21:02:29 openWBPro-575262 iec61851d[251]: [21:02:29.533] [INFO] forcing safe state
Aug 31 21:02:29 openWBPro-575262 iec61851d[251]: [21:02:29.533] [INFO] IEC_STATE_CONTACTOR_OPEN_LOCK_OPEN
Aug 31 21:02:29 openWBPro-575262 iec61851d[251]: [21:02:29.534] [INFO] Charging: Basic only (free) [AC]
Aug 31 21:02:29 openWBPro-575262 iec61851d[251]: [21:02:29.574] [INFO] Cable: non-pluggable, fixed
Aug 31 21:02:29 openWBPro-575262 iec61851d[251]: [21:02:29.574] [INFO] Current limits - Grid: 16 A, EVSE: 32 A, Cable: 32 A, Failsafe: -1 A
Aug 31 21:02:29 openWBPro-575262 iec61851d[251]: [21:02:29.575] [INFO] Ventilation: disabled,internal, always accept CP state D
Aug 31 21:02:29 openWBPro-575262 iec61851d[251]: [21:02:29.575] [INFO] entering main loop
Aug 31 21:02:29 openWBPro-575262 iec61851d[251]: [21:02:29.622] [INFO] main loop: setting new duty cycle 100.000%
Aug 31 21:02:29 openWBPro-575262 rgbledd[278]: [21:02:29.629] [INFO] starting main loop
Aug 31 21:02:29 openWBPro-575262 rfidd[275]: [21:02:29.710] [INFO] starting rfidd 0.12 (RFID disabled)
Aug 31 21:02:29 openWBPro-575262 slacd[281]: [21:02:29.646] [INFO] /usr/bin/slacd (slacd 0.13)
Aug 31 21:02:29 openWBPro-575262 slacd[281]: [21:02:29.708] [INFO] Basic charging -> disable SLAC
Aug 31 21:02:29 openWBPro-575262 proximityd[264]: [21:02:29.807] [INFO] main loop: set new cable limit: -1 (PP 3295 mV)
Aug 31 21:02:30 openWBPro-575262 ventilationd[286]: [21:02:30.036] [INFO] ventilation control: disabled, internal
Aug 31 21:02:30 openWBPro-575262 ventilationd[286]: [21:02:30.045] [INFO] starting main loop
Aug 31 21:02:31 openWBPro-575262 ocppd[300]: [21:02:31.075] [ERROR] GetAvailabilityFlags() failed to open file '/var/lib/ocppd/ocpp_data.json' (falling back to "Operative"): No such file or directory
Aug 31 21:02:31 openWBPro-575262 ocppd[300]: [21:02:31.088] [INFO] /usr/bin/ocppd (ocppd 0.11)
Aug 31 21:02:31 openWBPro-575262 ocppd[300]: [21:02:31.103] [INFO] using local state directory '/var/lib/ocppd'
Aug 31 21:02:31 openWBPro-575262 ocppd[300]: [21:02:31.105] [INFO] upload thread started
Aug 31 21:02:31 openWBPro-575262 ocppd[300]: [21:02:31.119] [INFO] GetGlobalConfigurationValue(".ocpp.enable") got 'false'
Aug 31 21:02:31 openWBPro-575262 ocppd[300]: [21:02:31.119] [INFO] ocpp disabled by config, terminating (doing nothing forever)
Aug 31 21:02:31 openWBPro-575262 updated[302]: [21:02:31.550] [INFO] Starting updated 0.3
Aug 31 21:02:31 openWBPro-575262 sh[315]: Warning: Unable to locate configuration directory, default config not loaded.
Wed Aug 31 21:10:07 2022: Heartbeat Fehler seit 82 Sekunden keine Verbindung, Stoppe Ladung.
Wed Aug 31 21:10:10 2022: Heartbeat Fehler seit 84 Sekunden keine Verbindung, Stoppe Ladung.
Wed Aug 31 21:10:14 2022: Heartbeat Fehler seit 86 Sekunden keine Verbindung, Stoppe Ladung.
Wed Aug 31 21:10:17 2022: Heartbeat Fehler seit 88 Sekunden keine Verbindung, Stoppe Ladung.
Wed Aug 31 21:10:20 2022: Heartbeat Fehler seit 90 Sekunden keine Verbindung, Stoppe Ladung.
Wed Aug 31 21:10:23 2022: Heartbeat Fehler seit 92 Sekunden keine Verbindung, Stoppe Ladung.
Wed Aug 31 21:10:26 2022: Heartbeat Fehler seit 94 Sekunden keine Verbindung, Stoppe Ladung.
Wed Aug 31 21:10:29 2022: Heartbeat Fehler seit 96 Sekunden keine Verbindung, Stoppe Ladung.
Wed Aug 31 21:10:32 2022: Heartbeat Fehler seit 98 Sekunden keine Verbindung, Stoppe Ladung.
Wed Aug 31 21:10:36 2022: Heartbeat Fehler seit 100 Sekunden keine Verbindung, Stoppe Ladung.
Wed Aug 31 21:10:39 2022: Heartbeat Fehler seit 102 Sekunden keine Verbindung, Stoppe Ladung.
Wed Aug 31 21:10:42 2022: Heartbeat Fehler seit 104 Sekunden keine Verbindung, Stoppe Ladung.
Wed Aug 31 21:10:45 2022: Heartbeat Fehler seit 106 Sekunden keine Verbindung, Stoppe Ladung.
Wed Aug 31 21:10:48 2022: Heartbeat Fehler seit 108 Sekunden keine Verbindung, Stoppe Ladung.
Wed Aug 31 21:10:51 2022: Heartbeat Fehler seit 110 Sekunden keine Verbindung, Stoppe Ladung.
Wed Aug 31 21:10:51 2022: Heartbeat Fehler seit 110 Sekunden keine Verbindung, Stoppe Ladung.
Aug 31 21:21:49 openWBPro-575262 pwmd[267]: [21:21:49.565] [INFO] main loop: set new CP state: B (CP (+) 9015 (-) 36 mV)
Aug 31 21:21:49 openWBPro-575262 iec61851d[251]: [21:21:49.572] [INFO] EV connected, CP state 'B'
Aug 31 21:21:49 openWBPro-575262 iec61851d[251]: [21:21:49.573] [INFO] main loop: close lock
Aug 31 21:21:49 openWBPro-575262 iec61851d[251]: [21:21:49.580] [INFO] IEC_STATE_CONTACTOR_OPEN_LOCK_CLOSE
Aug 31 21:22:10 openWBPro-575262 iec61851d[251]: [21:22:10.966] [INFO] main loop: setting new duty cycle 17.667%
Aug 31 21:22:10 openWBPro-575262 pwmd[267]: [21:22:10.983] [INFO] changed CP enable 1, duty cycle 17.660 %
Aug 31 21:22:12 openWBPro-575262 pwmd[267]: [21:22:12.971] [INFO] main loop: set new CP state: C (CP (+) 6063 (-) -11865 mV)
Aug 31 21:22:13 openWBPro-575262 iec61851d[251]: [21:22:12.978] [INFO] main loop: close contactor
Aug 31 21:22:13 openWBPro-575262 iec61851d[251]: [21:22:13.210] [INFO] main loop: setting new duty cycle 0.000%
Aug 31 21:22:13 openWBPro-575262 pwmd[267]: [21:22:13.226] [INFO] changed CP enable 1, duty cycle 0.000 %
Aug 31 21:22:13 openWBPro-575262 pwmd[267]: [21:22:13.253] [INFO] main loop: set new CP state: F (CP (+) -2 (-) -11865 mV)
Aug 31 21:22:13 openWBPro-575262 iec61851d[251]: [21:22:13.297] [INFO] EV disconnected, CP state 'F'
Aug 31 21:22:13 openWBPro-575262 iec61851d[251]: [21:22:13.480] [ERROR] Timeout 500 ms reached before state: IEC_STATE_CONTACTOR_OPEN_LOCK_CLOSE (2) -> IEC_STATE_CONTACTOR_CLOSE_LOCK_CLOSE (3) (target IEC_STATE_CONTACTOR_OPEN_LOCK_OPEN (1)
Aug 31 21:22:13 openWBPro-575262 iec61851d[251]: [21:22:13.480] [INFO] forcing safe state
Aug 31 21:22:13 openWBPro-575262 iec61851d[251]: [21:22:13.488] [ERROR] main loop: new failure IEC_FAILURE_TIMEOUT (wait for 4000)
Aug 31 21:22:13 openWBPro-575262 iec61851d[251]: [21:22:13.488] [INFO] main loop: open lock
Aug 31 21:22:13 openWBPro-575262 iec61851d[251]: [21:22:13.511] [INFO] IEC_STATE_CONTACTOR_OPEN_LOCK_OPEN
Aug 31 21:22:17 openWBPro-575262 iec61851d[251]: [21:22:17.497] [INFO] main loop: Failure stop
Re: openWB-Pro ladet nicht
Verfasst: Fr Sep 02, 2022 7:13 pm
von jub
ich komme leider nicht weiter.
Das Auto wird erst an der openWB-Pro erkannt, wenn ich sie vom Strom genommen habe. Wenn ich auf Laden gehe, schaltet das Relais kurz, der Control Pilot State geht von A auf F und dann wird kein Auto mehr erkannt. Ich wiederhole mich zwar, aber dieses Verhalten hatte der Elektriker auch - deshalb schließe ich eine fehlerhafte Konfig und Probleme mit dem Auto aus. Das Kabel wurde neu gezogen und ersetzt das alte - die CEE 16 Dose war am selben Platz und hatte keine Probleme, also denke ich, die Hauserdung ist ok.
Laut Support könnte die PE nicht gut sein, der Elektriker wollte kommen, hat es aber nicht mehr geschafft - gibt es für mich eine Möglichkeit, die Erdung zu testen?
Aus dem Logfile ergibt sich nichts neues. Ich habe auch schon mit den Settings (Phasenumschaltung deaktiviert, nur eine Phase, CP deaktiviert) experimentiert, in Anlehnung an b3n Hinweis.
Die Auto-Wallbox Kommunikation startet mit State A, bricht dann aber vor/beim Wechsel auf C (ab da laden möglich wäre) ab.
In Verwendung habe ich openWB 2.0 (aber das Logfile kommt direkt von der Wallbox)
Code: Alles auswählen
Fri Sep 2 18:48:42 UTC 2022 start updating
Fri Sep 2 18:48:42 UTC 2022 start downloading image
Fri Sep 2 18:48:42 UTC 2022 main update started
Fri Sep 2 18:48:42 UTC 2022 actual version: 3
Fri Sep 2 18:48:42 UTC 2022 main update end
Fri Sep 2 18:48:42 UTC 2022 deleting files
Fri Sep 2 18:48:43 UTC 2022 end updating
Sep 2 18:47:26 openWBPro-575262 hwinitd[144]: [09:05:16.211] [INFO] Starting hwinitd 0.2
Sep 2 18:47:26 openWBPro-575262 hwinitd[144]: [09:05:16.240] [INFO] Shutting down with exit code 0
Sep 2 18:47:30 openWBPro-575262 rotaryencd[235]: [18:47:30.914] [INFO] Entry[0] phase_count 1 current_limit 6
Sep 2 18:47:30 openWBPro-575262 rotaryencd[235]: [18:47:30.918] [INFO] Entry[1] phase_count 1 current_limit 10
Sep 2 18:47:30 openWBPro-575262 rotaryencd[235]: [18:47:30.920] [INFO] Entry[2] phase_count 1 current_limit 13
Sep 2 18:47:30 openWBPro-575262 rotaryencd[235]: [18:47:30.925] [INFO] Entry[3] phase_count 1 current_limit 16
Sep 2 18:47:30 openWBPro-575262 rotaryencd[235]: [18:47:30.927] [INFO] Entry[4] phase_count 1 current_limit 20
Sep 2 18:47:30 openWBPro-575262 rotaryencd[235]: [18:47:30.928] [INFO] Entry[5] phase_count 1 current_limit 32
Sep 2 18:47:30 openWBPro-575262 rotaryencd[235]: [18:47:30.929] [INFO] Entry[6] phase_count 1 current_limit 40
Sep 2 18:47:30 openWBPro-575262 rotaryencd[235]: [18:47:30.930] [INFO] Entry[7] phase_count 1 current_limit 63
Sep 2 18:47:30 openWBPro-575262 rotaryencd[235]: [18:47:30.930] [INFO] Entry[8] phase_count 3 current_limit 6
Sep 2 18:47:30 openWBPro-575262 rotaryencd[235]: [18:47:30.933] [INFO] Entry[9] phase_count 3 current_limit 10
Sep 2 18:47:30 openWBPro-575262 rotaryencd[235]: [18:47:30.933] [INFO] Entry[10] phase_count 3 current_limit 13
Sep 2 18:47:30 openWBPro-575262 rotaryencd[235]: [18:47:30.934] [INFO] Entry[11] phase_count 3 current_limit 16
Sep 2 18:47:30 openWBPro-575262 rotaryencd[235]: [18:47:30.934] [INFO] Entry[12] phase_count 3 current_limit 20
Sep 2 18:47:30 openWBPro-575262 rotaryencd[235]: [18:47:30.965] [INFO] Entry[13] phase_count 3 current_limit 32
Sep 2 18:47:30 openWBPro-575262 rotaryencd[235]: [18:47:30.966] [INFO] Entry[14] phase_count 3 current_limit 40
Sep 2 18:47:30 openWBPro-575262 rotaryencd[235]: [18:47:30.966] [INFO] Entry[15] phase_count 3 current_limit 63
Sep 2 18:47:31 openWBPro-575262 rotaryencd[235]: [18:47:31.383] [INFO] Publish grid current limit 16 A, phase count 3
Sep 2 18:47:31 openWBPro-575262 rotaryencd[235]: [18:47:31.388] [INFO] shutting down with exit code 0
Sep 2 18:47:31 openWBPro-575262 watchdogd[242]: [18:47:31.705] [INFO] enter init loop [ ]
Sep 2 18:47:32 openWBPro-575262 gpioauthd[249]: [18:47:32.092] [INFO] starting main loop with 0 fds in poll_fds
Sep 2 18:47:32 openWBPro-575262 configd[248]: [18:47:32.170] [INFO] ConfigD module initialized
Sep 2 18:47:32 openWBPro-575262 configd[248]: [18:47:32.245] [INFO] Disconnected from the MQTT broker
Sep 2 18:47:32 openWBPro-575262 configd[248]: [18:47:32.245] [WARN] MQTT connection to broker lost, reconnecting
Sep 2 18:47:32 openWBPro-575262 configd[248]: [18:47:32.304] [INFO] ConfigD module stopped
Sep 2 18:47:32 openWBPro-575262 configd[248]: [18:47:32.310] [INFO] Disconnected from the MQTT broker
Sep 2 18:47:32 openWBPro-575262 configd[248]: [18:47:32.315] [INFO] Stopped MQTT module
Sep 2 18:47:32 openWBPro-575262 iec61851d[252]: [18:47:32.442] [INFO] /usr/bin/iec61851d (iec61851d 0.11)
Sep 2 18:47:32 openWBPro-575262 iec61851d[252]: [18:47:32.519] [INFO] enabling IEC_INIT_STATE_CABLE_CURRENT_LIMIT
Sep 2 18:47:32 openWBPro-575262 iec61851d[252]: [18:47:32.528] [INFO] option from config: force_wake_up = true
Sep 2 18:47:32 openWBPro-575262 iec61851d[252]: [18:47:32.530] [INFO] option from config: wake_up_after_timeout = false
Sep 2 18:47:32 openWBPro-575262 iec61851d[252]: [18:47:32.533] [INFO] switch3to1phase/switch_delay = 10000 ms
Sep 2 18:47:32 openWBPro-575262 hilinkd[250]: [18:47:32.653] [INFO] No APN configuration in config file
Sep 2 18:47:32 openWBPro-575262 hilinkd[250]: [18:47:32.678] [INFO] Shutting down (exit code 0)
Sep 2 18:47:32 openWBPro-575262 lockctrld[256]: [18:47:32.915] [INFO] starting init loop
Sep 2 18:47:32 openWBPro-575262 lockctrld[256]: [18:47:32.929] [INFO] worker thread started
Sep 2 18:47:33 openWBPro-575262 iso15118d[254]: [18:47:32.901] [INFO] /usr/bin/iso15118d (iso15118d 0.11)
Sep 2 18:47:33 openWBPro-575262 iso15118d[254]: [18:47:33.092] [INFO] Configured HLC protocols: 0x4
Sep 2 18:47:33 openWBPro-575262 iso15118d[254]: [18:47:33.093] [INFO] Charging type: basic
Sep 2 18:47:33 openWBPro-575262 powerctrld[265]: [18:47:33.230] [INFO] Use relay 0 for primary contactor
Sep 2 18:47:33 openWBPro-575262 iso15118d[254]: [18:47:33.214] [INFO] TCP server on eth1 is listening on port [fe80::201:87ff:fe13:34f9%3]:61341
Sep 2 18:47:33 openWBPro-575262 iso15118d[254]: [18:47:33.293] [INFO] starting socket server(s)
Sep 2 18:47:33 openWBPro-575262 powerctrld[265]: [18:47:33.298] [INFO] using gpio 131 as primary contactor feedback gpio
Sep 2 18:47:33 openWBPro-575262 meteringd[260]: [18:47:33.321] [INFO] using metering protocol 'eastron', timeout 2000 ms
Sep 2 18:47:33 openWBPro-575262 powerctrld[265]: [18:47:33.379] [INFO] primary contactor feedback type = NO
Sep 2 18:47:33 openWBPro-575262 meteringd[260]: [18:47:33.380] [INFO] Port: /dev/ttymxc0, Baudrate: 9600, Parity: N
Sep 2 18:47:33 openWBPro-575262 meteringd[260]: [18:47:33.381] [INFO] enabling local echo suppression
Sep 2 18:47:33 openWBPro-575262 powerctrld[265]: [18:47:33.384] [INFO] Use relay 1 for secondary contactor
Sep 2 18:47:33 openWBPro-575262 powerctrld[265]: [18:47:33.460] [INFO] using gpio 130 as secondary contactor feedback gpio
Sep 2 18:47:33 openWBPro-575262 powerctrld[265]: [18:47:33.544] [INFO] secondary contactor feedback type = NO
Sep 2 18:47:33 openWBPro-575262 powerctrld[265]: [18:47:33.685] [INFO] starting init loop with 2 fds in poll_fds
Sep 2 18:47:33 openWBPro-575262 proximityd[267]: [18:47:33.729] [INFO] starting init loop
Sep 2 18:47:33 openWBPro-575262 powerctrld[265]: [18:47:33.726] [INFO] Maximum phase count: 3
Sep 2 18:47:33 openWBPro-575262 powerctrld[265]: [18:47:33.796] [INFO] Feature switch3to1phase: possible
Sep 2 18:47:33 openWBPro-575262 powerctrld[265]: [18:47:33.814] [INFO] starting main loop with 2 fds in poll_fds
Sep 2 18:47:33 openWBPro-575262 pwmd[271]: [18:47:33.918] [INFO] Using PWM7/0
Sep 2 18:47:33 openWBPro-575262 meteringd[260]: [18:47:33.948] [ERROR] error querying meter initially: Connection timed out
Sep 2 18:47:33 openWBPro-575262 pwmd[271]: [18:47:33.963] [INFO] starting init loop
Sep 2 18:47:34 openWBPro-575262 watchdogd[242]: [18:47:34.135] [INFO] enter main loop
Sep 2 18:47:34 openWBPro-575262 recloserd[275]: [18:47:34.237] [INFO] recloser not enabled in config file
Sep 2 18:47:34 openWBPro-575262 proximityd[267]: [18:47:34.270] [INFO] starting main loop
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.270] [INFO] starting initialization
Sep 2 18:47:34 openWBPro-575262 lockctrld[256]: [18:47:34.279] [INFO] starting main loop with 0 fds in poll_fds
Sep 2 18:47:34 openWBPro-575262 pwmd[271]: [18:47:34.319] [INFO] starting main loop
Sep 2 18:47:34 openWBPro-575262 pwmd[271]: [18:47:34.350] [INFO] changed CP enable 1, duty cycle 100.000 %
Sep 2 18:47:34 openWBPro-575262 recloserd[275]: [18:47:34.247] [INFO] shutting down with exit code 0
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.325] [INFO] entering init loop
Sep 2 18:47:34 openWBPro-575262 pwmd[271]: [18:47:34.380] [INFO] main loop: set new CP state: A (CP (+) 12023 (-) 17 mV)
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.388] [INFO] enabling IEC_INIT_STATE_GRID_CURRENT_LIMIT
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.389] [INFO] enabling IEC_INIT_STATE_PLUG_LOCK
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.390] [INFO] enabling IEC_INIT_STATE_EMERGENCY_SHUTDOWN
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.449] [INFO] enabling IEC_INIT_STATE_CONTACTOR
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.475] [WARN] Ignoring hlc contactor state target: 0
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.496] [INFO] enabling IEC_INIT_STATE_DIODE_PRESENT
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.498] [INFO] enabling IEC_INIT_STATE_CP_STATE
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.503] [INFO] init states achieved, setting initial safe state
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.503] [INFO] forcing safe state
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.504] [INFO] IEC_STATE_CONTACTOR_OPEN_LOCK_OPEN
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.504] [INFO] Charging: Basic only (free) [AC]
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.504] [INFO] Cable: non-pluggable, fixed
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.560] [INFO] Current limits - Grid: 16 A, EVSE: 32 A, Cable: 32 A, Failsafe: -1 A
Sep 2 18:47:34 openWBPro-575262 meteringd[260]: [18:47:34.589] [ERROR] error querying meter initially: Connection timed out
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.574] [INFO] Ventilation: disabled,internal, always accept CP state D
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.605] [INFO] entering main loop
Sep 2 18:47:34 openWBPro-575262 iec61851d[252]: [18:47:34.609] [INFO] main loop: setting new duty cycle 100.000%
Sep 2 18:47:34 openWBPro-575262 rfidd[277]: [18:47:34.633] [INFO] starting rfidd 0.12 (RFID disabled)
Sep 2 18:47:34 openWBPro-575262 proximityd[267]: [18:47:34.778] [INFO] main loop: set new cable limit: -1 (PP 3292 mV)
Sep 2 18:47:34 openWBPro-575262 slacd[282]: [18:47:34.731] [INFO] /usr/bin/slacd (slacd 0.13)
Sep 2 18:47:34 openWBPro-575262 slacd[282]: [18:47:34.831] [INFO] Basic charging -> disable SLAC
Sep 2 18:47:34 openWBPro-575262 rgbledd[278]: [18:47:34.966] [INFO] starting main loop
Sep 2 18:47:35 openWBPro-575262 meteringd[260]: [18:47:35.245] [ERROR] error querying meter initially: Connection timed out
Sep 2 18:47:35 openWBPro-575262 ventilationd[287]: [18:47:35.264] [INFO] ventilation control: disabled, internal
Sep 2 18:47:35 openWBPro-575262 ventilationd[287]: [18:47:35.359] [INFO] starting main loop
Sep 2 18:47:35 openWBPro-575262 meteringd[260]: [18:47:35.886] [ERROR] error querying meter initially: Connection timed out
Sep 2 18:47:36 openWBPro-575262 ocppd[300]: [18:47:36.179] [ERROR] GetAvailabilityFlags() failed to open file '/var/lib/ocppd/ocpp_data.json' (falling back to "Operative"): No such file or directory
Sep 2 18:47:36 openWBPro-575262 ocppd[300]: [18:47:36.190] [INFO] /usr/bin/ocppd (ocppd 0.11)
Sep 2 18:47:36 openWBPro-575262 ocppd[300]: [18:47:36.211] [INFO] using local state directory '/var/lib/ocppd'
Sep 2 18:47:36 openWBPro-575262 ocppd[300]: [18:47:36.214] [INFO] upload thread started
Sep 2 18:47:36 openWBPro-575262 ocppd[300]: [18:47:36.236] [INFO] GetGlobalConfigurationValue(".ocpp.enable") got 'false'
Sep 2 18:47:36 openWBPro-575262 ocppd[300]: [18:47:36.238] [INFO] ocpp disabled by config, terminating (doing nothing forever)
Sep 2 18:47:36 openWBPro-575262 updated[302]: [18:47:36.458] [INFO] Starting updated 0.3
Sep 2 18:47:36 openWBPro-575262 meteringd[260]: [18:47:36.527] [ERROR] error querying meter initially: Connection timed out
Sep 2 18:47:36 openWBPro-575262 meteringd[260]: [18:47:36.622] [INFO] Starting meteringd 0.6
Sep 2 18:47:37 openWBPro-575262 sh[315]: Warning: Unable to locate configuration directory, default config not loaded.
Sep 2 18:55:16 openWBPro-575262 iec61851d[252]: [18:55:16.947] [INFO] EV connected, CP state 'B'
Sep 2 18:55:16 openWBPro-575262 iec61851d[252]: [18:55:16.949] [INFO] main loop: close lock
Sep 2 18:55:16 openWBPro-575262 pwmd[271]: [18:55:16.955] [INFO] main loop: set new CP state: B (CP (+) 9005 (-) 36 mV)
Sep 2 18:55:16 openWBPro-575262 iec61851d[252]: [18:55:16.958] [INFO] IEC_STATE_CONTACTOR_OPEN_LOCK_CLOSE
Sep 2 18:55:17 openWBPro-575262 iec61851d[252]: [18:55:17.960] [INFO] main loop: setting new duty cycle 16.667%
Sep 2 18:55:17 openWBPro-575262 pwmd[271]: [18:55:17.966] [INFO] changed CP enable 1, duty cycle 16.660 %
Sep 2 18:55:22 openWBPro-575262 iec61851d[252]: [18:55:22.264] [INFO] main loop: close contactor
Sep 2 18:55:22 openWBPro-575262 pwmd[271]: [18:55:22.272] [INFO] main loop: set new CP state: C (CP (+) 6025 (-) -11644 mV)
Sep 2 18:55:22 openWBPro-575262 iec61851d[252]: [18:55:22.482] [INFO] main loop: setting new duty cycle 0.000%
Sep 2 18:55:22 openWBPro-575262 pwmd[271]: [18:55:22.496] [INFO] changed CP enable 1, duty cycle 0.000 %
Sep 2 18:55:22 openWBPro-575262 pwmd[271]: [18:55:22.526] [INFO] main loop: set new CP state: F (CP (+) -31 (-) -11874 mV)
Sep 2 18:55:22 openWBPro-575262 iec61851d[252]: [18:55:22.573] [INFO] EV disconnected, CP state 'F'
Sep 2 18:55:22 openWBPro-575262 iec61851d[252]: [18:55:22.776] [ERROR] Timeout 500 ms reached before state: IEC_STATE_CONTACTOR_OPEN_LOCK_CLOSE (2) -> IEC_STATE_CONTACTOR_CLOSE_LOCK_CLOSE (3) (target IEC_STATE_CONTACTOR_OPEN_LOCK_OPEN (1)
Sep 2 18:55:22 openWBPro-575262 iec61851d[252]: [18:55:22.776] [INFO] forcing safe state
Sep 2 18:55:22 openWBPro-575262 iec61851d[252]: [18:55:22.785] [ERROR] main loop: new failure IEC_FAILURE_TIMEOUT (wait for 4000)
Sep 2 18:55:22 openWBPro-575262 iec61851d[252]: [18:55:22.785] [INFO] main loop: open lock
Sep 2 18:55:22 openWBPro-575262 iec61851d[252]: [18:55:22.806] [INFO] IEC_STATE_CONTACTOR_OPEN_LOCK_OPEN
Sep 2 18:55:26 openWBPro-575262 iec61851d[252]: [18:55:26.795] [INFO] main loop: Failure stop