Seite 3 von 25

Re: Feedback Nightly 101.1

Verfasst: Di Jan 10, 2023 4:06 pm
von LarsBr87
Habe nun einen Wechsel auf die Master gemacht und die neuste Version eingespielt. Nun kommen leider keine EVU Daten mehr. Läuft alles über das SolaX Modul. Die Werte für den Speicher und für den WR kommen aber.
Hier der Main-Log

Code: Alles auswählen

atreboot.sh started
apt configuration already up to date
install required packages with 'apt-get'...
Fehl:1 http://raspbian.raspberrypi.org/raspbian bullseye InRelease
  Temporärer Fehlschlag beim Auflösen von »raspbian.raspberrypi.org«
Fehl:2 http://archive.raspberrypi.org/debian bullseye InRelease
  Temporärer Fehlschlag beim Auflösen von »archive.raspberrypi.org«
Paketlisten werden gelesen…
W: Fehlschlag beim Holen von http://raspbian.raspberrypi.org/raspbian/dists/bullseye/InRelease Temporärer Fehlschlag beim Auflösen von »raspbian.raspberrypi.org«
W: Fehlschlag beim Holen von http://archive.raspberrypi.org/debian/dists/bullseye/InRelease Temporärer Fehlschlag beim Auflösen von »archive.raspberrypi.org«
W: Einige Indexdateien konnten nicht heruntergeladen werden. Sie wurden ignoriert oder alte an ihrer Stelle benutzt.
Paketlisten werden gelesen…
Abhängigkeitsbaum wird aufgebaut…
Statusinformationen werden eingelesen…
apache2 ist schon die neueste Version (2.4.54-1~deb11u1).
bc ist schon die neueste Version (1.07.1-2+b1).
git ist schon die neueste Version (1:2.30.2-1).
jq ist schon die neueste Version (1.6-2.1).
libapache2-mod-php ist schon die neueste Version (2:7.4+76).
mosquitto ist schon die neueste Version (2.0.11-1).
mosquitto-clients ist schon die neueste Version (2.0.11-1).
php ist schon die neueste Version (2:7.4+76).
php-curl ist schon die neueste Version (2:7.4+76).
php-gd ist schon die neueste Version (2:7.4+76).
php-json ist schon die neueste Version (2:7.4+76).
php-xml ist schon die neueste Version (2:7.4+76).
socat ist schon die neueste Version (1.7.4.1-3).
sshpass ist schon die neueste Version (1.09-1).
ssl-cert ist schon die neueste Version (1.1.0+nmu1).
sudo ist schon die neueste Version (1.9.5p2-3).
vim ist schon die neueste Version (2:8.2.2434-3+deb11u1).
python3-pip ist schon die neueste Version (20.3.4-4+rpt1+deb11u1).
0 aktualisiert, 0 neu installiert, 0 zu entfernen und 39 nicht aktualisiert.
done
openwb.cron already up to date
openwb2.service already up to date
no restore pending, normal startup
cleaning obsolete python cache folders...
python cache cleanup done
LAN/WLAN...
apache default site...
...ok
checking required apache modules...
headers (enabled by site administrator)
headers already enabled
ssl (enabled by site administrator)
ssl already enabled
proxy_wstunnel (enabled by site administrator)
proxy_wstunnel already enabled
check mosquitto installation...
mosquitto.conf already up to date
mosquitto openwb.conf already up to date
mosquitto_local.conf already up to date
mosquitto openwb_local.conf already up to date
mosquitto done
install required python packages with 'pip3'...
Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple
Requirement already satisfied: jq==1.1.3 in /home/openwb/.local/lib/python3.9/site-packages (from -r /var/www/html/openWB/requirements.txt (line 1)) (1.1.3)
Requirement already satisfied: paho_mqtt==1.6.1 in /home/openwb/.local/lib/python3.9/site-packages (from -r /var/www/html/openWB/requirements.txt (line 2)) (1.6.1)
Requirement already satisfied: pymodbus==2.5.2 in /home/openwb/.local/lib/python3.9/site-packages (from -r /var/www/html/openWB/requirements.txt (line 3)) (2.5.2)
Requirement already satisfied: pytest==6.2.5 in /home/openwb/.local/lib/python3.9/site-packages (from -r /var/www/html/openWB/requirements.txt (line 4)) (6.2.5)
Requirement already satisfied: requests_mock==1.9.3 in /home/openwb/.local/lib/python3.9/site-packages (from -r /var/www/html/openWB/requirements.txt (line 5)) (1.9.3)
Requirement already satisfied: lxml==4.7.1 in /home/openwb/.local/lib/python3.9/site-packages (from -r /var/www/html/openWB/requirements.txt (line 6)) (4.7.1)
Requirement already satisfied: aiohttp==3.7.2 in /home/openwb/.local/lib/python3.9/site-packages (from -r /var/www/html/openWB/requirements.txt (line 7)) (3.7.2)
Requirement already satisfied: schedule==1.1.0 in /home/openwb/.local/lib/python3.9/site-packages (from -r /var/www/html/openWB/requirements.txt (line 8)) (1.1.0)
Requirement already satisfied: PyJWT==2.6.0 in /home/openwb/.local/lib/python3.9/site-packages (from -r /var/www/html/openWB/requirements.txt (line 9)) (2.6.0)
Requirement already satisfied: ipparser==0.3.8 in /home/openwb/.local/lib/python3.9/site-packages (from -r /var/www/html/openWB/requirements.txt (line 10)) (0.3.8)
Requirement already satisfied: bs4==0.0.1 in /home/openwb/.local/lib/python3.9/site-packages (from -r /var/www/html/openWB/requirements.txt (line 11)) (0.0.1)
Requirement already satisfied: pkce==1.0.3 in /home/openwb/.local/lib/python3.9/site-packages (from -r /var/www/html/openWB/requirements.txt (line 12)) (1.0.3)
Requirement already satisfied: typing-extensions>=3.6.5 in /home/openwb/.local/lib/python3.9/site-packages (from aiohttp==3.7.2->-r /var/www/html/openWB/requirements.txt (line 7)) (4.4.0)
Requirement already satisfied: chardet<4.0,>=2.0 in /home/openwb/.local/lib/python3.9/site-packages (from aiohttp==3.7.2->-r /var/www/html/openWB/requirements.txt (line 7)) (3.0.4)
Requirement already satisfied: attrs>=17.3.0 in /home/openwb/.local/lib/python3.9/site-packages (from aiohttp==3.7.2->-r /var/www/html/openWB/requirements.txt (line 7)) (22.1.0)
Requirement already satisfied: multidict<7.0,>=4.5 in /home/openwb/.local/lib/python3.9/site-packages (from aiohttp==3.7.2->-r /var/www/html/openWB/requirements.txt (line 7)) (6.0.2)
Requirement already satisfied: yarl<2.0,>=1.0 in /home/openwb/.local/lib/python3.9/site-packages (from aiohttp==3.7.2->-r /var/www/html/openWB/requirements.txt (line 7)) (1.8.1)
Requirement already satisfied: async-timeout<4.0,>=3.0 in /home/openwb/.local/lib/python3.9/site-packages (from aiohttp==3.7.2->-r /var/www/html/openWB/requirements.txt (line 7)) (3.0.1)
Requirement already satisfied: beautifulsoup4 in /home/openwb/.local/lib/python3.9/site-packages (from bs4==0.0.1->-r /var/www/html/openWB/requirements.txt (line 11)) (4.11.1)
Requirement already satisfied: dnspython in /home/openwb/.local/lib/python3.9/site-packages (from ipparser==0.3.8->-r /var/www/html/openWB/requirements.txt (line 10)) (2.2.1)
Requirement already satisfied: pyserial>=3.4 in /home/openwb/.local/lib/python3.9/site-packages (from pymodbus==2.5.2->-r /var/www/html/openWB/requirements.txt (line 3)) (3.5)
Requirement already satisfied: six>=1.15.0 in /usr/lib/python3/dist-packages (from pymodbus==2.5.2->-r /var/www/html/openWB/requirements.txt (line 3)) (1.16.0)
Requirement already satisfied: pluggy<2.0,>=0.12 in /home/openwb/.local/lib/python3.9/site-packages (from pytest==6.2.5->-r /var/www/html/openWB/requirements.txt (line 4)) (1.0.0)
Requirement already satisfied: iniconfig in /home/openwb/.local/lib/python3.9/site-packages (from pytest==6.2.5->-r /var/www/html/openWB/requirements.txt (line 4)) (1.1.1)
Requirement already satisfied: py>=1.8.2 in /home/openwb/.local/lib/python3.9/site-packages (from pytest==6.2.5->-r /var/www/html/openWB/requirements.txt (line 4)) (1.11.0)
Requirement already satisfied: packaging in /home/openwb/.local/lib/python3.9/site-packages (from pytest==6.2.5->-r /var/www/html/openWB/requirements.txt (line 4)) (21.3)
Requirement already satisfied: toml in /usr/lib/python3/dist-packages (from pytest==6.2.5->-r /var/www/html/openWB/requirements.txt (line 4)) (0.10.1)
Requirement already satisfied: requests<3,>=2.3 in /usr/lib/python3/dist-packages (from requests_mock==1.9.3->-r /var/www/html/openWB/requirements.txt (line 5)) (2.25.1)
Requirement already satisfied: idna>=2.0 in /usr/lib/python3/dist-packages (from yarl<2.0,>=1.0->aiohttp==3.7.2->-r /var/www/html/openWB/requirements.txt (line 7)) (2.10)
Requirement already satisfied: soupsieve>1.2 in /home/openwb/.local/lib/python3.9/site-packages (from beautifulsoup4->bs4==0.0.1->-r /var/www/html/openWB/requirements.txt (line 11)) (2.3.2.post1)
Requirement already satisfied: pyparsing!=3.0.5,>=2.0.2 in /home/openwb/.local/lib/python3.9/site-packages (from packaging->pytest==6.2.5->-r /var/www/html/openWB/requirements.txt (line 4)) (3.0.9)
done
my IP: "192.168.200.180"
load versions...
chgrp: Zugriff auf '/var/www/html/openWB/data/restore/*' nicht möglich: Datei oder Verzeichnis nicht gefunden
chmod: Zugriff auf '/var/www/html/openWB/data/restore/*' nicht möglich: Datei oder Verzeichnis nicht gefunden
2023-01-10 15:33:50: boot done :-)
2023-01-10 15:33:51,420 - {root:117} - {DEBUG:MainThread} - Start openWB2.service
2023-01-10 15:33:51,421 - {helpermodules.update_config:320} - {DEBUG:MainThread} - Broker-Konfiguration aktualisieren
2023-01-10 15:33:52,794 - {helpermodules.update_config:348} - {DEBUG:MainThread} - Ungültiges Topic zum Startzeitpunkt: openWB/chargepoint/1/set/plug_state_prev
2023-01-10 15:33:52,798 - {helpermodules.update_config:348} - {DEBUG:MainThread} - Ungültiges Topic zum Startzeitpunkt: openWB/chargepoint/2/set/plug_state_prev
2023-01-10 15:33:52,805 - {helpermodules.update_config:348} - {DEBUG:MainThread} - Ungültiges Topic zum Startzeitpunkt: openWB/counter/5/set/reserved_surplus
2023-01-10 15:33:52,806 - {helpermodules.update_config:348} - {DEBUG:MainThread} - Ungültiges Topic zum Startzeitpunkt: openWB/counter/5/set/released_surplus
2023-01-10 15:33:52,834 - {helpermodules.update_config:348} - {DEBUG:MainThread} - Ungültiges Topic zum Startzeitpunkt: openWB/vehicle/set/vehicle_update_completed
2023-01-10 15:33:52,864 - {helpermodules.update_config:348} - {DEBUG:MainThread} - Ungültiges Topic zum Startzeitpunkt: openWB/command/command_completed
2023-01-10 15:33:52,865 - {helpermodules.update_config:348} - {DEBUG:MainThread} - Ungültiges Topic zum Startzeitpunkt: openWB/command/mqttjs_d536cf25/messages/1673361210668
2023-01-10 15:33:52,866 - {helpermodules.update_config:348} - {DEBUG:MainThread} - Ungültiges Topic zum Startzeitpunkt: openWB/set/chargepoint/2/get/voltages
2023-01-10 15:33:52,867 - {helpermodules.update_config:348} - {DEBUG:MainThread} - Ungültiges Topic zum Startzeitpunkt: openWB/set/chargepoint/1/get/voltages
2023-01-10 15:33:56,839 - {helpermodules.subdata:420} - {ERROR:Thread-5} - Fehler im subdata-Modul
Traceback (most recent call last):
  File "/var/www/html/openWB/packages/helpermodules/subdata.py", line 417, in process_chargepoint_template_topic
    var["cpt"+index].data = dataclass_from_dict(chargepoint.CpTemplateData, payload)
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 20, in dataclass_from_dict
    return cls(*[_get_argument_value(arg_spec, index, args) for index in range(1, len(arg_spec.args))])
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 20, in <listcomp>
    return cls(*[_get_argument_value(arg_spec, index, args) for index in range(1, len(arg_spec.args))])
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 36, in _get_argument_value
    return _dataclass_from_dict_recurse(value, arg_spec.annotations.get(argument_name))
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 40, in _dataclass_from_dict_recurse
    return dataclass_from_dict(requested_type, value) \
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 20, in dataclass_from_dict
    return cls(*[_get_argument_value(arg_spec, index, args) for index in range(1, len(arg_spec.args))])
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 20, in <listcomp>
    return cls(*[_get_argument_value(arg_spec, index, args) for index in range(1, len(arg_spec.args))])
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 36, in _get_argument_value
    return _dataclass_from_dict_recurse(value, arg_spec.annotations.get(argument_name))
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 41, in _dataclass_from_dict_recurse
    if isinstance(value, dict) and not issubclass(requested_type, dict) \
TypeError: issubclass() arg 1 must be a class
2023-01-10 15:33:56,845 - {helpermodules.subdata:420} - {ERROR:Thread-5} - Fehler im subdata-Modul
Traceback (most recent call last):
  File "/var/www/html/openWB/packages/helpermodules/subdata.py", line 417, in process_chargepoint_template_topic
    var["cpt"+index].data = dataclass_from_dict(chargepoint.CpTemplateData, payload)
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 20, in dataclass_from_dict
    return cls(*[_get_argument_value(arg_spec, index, args) for index in range(1, len(arg_spec.args))])
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 20, in <listcomp>
    return cls(*[_get_argument_value(arg_spec, index, args) for index in range(1, len(arg_spec.args))])
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 36, in _get_argument_value
    return _dataclass_from_dict_recurse(value, arg_spec.annotations.get(argument_name))
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 40, in _dataclass_from_dict_recurse
    return dataclass_from_dict(requested_type, value) \
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 20, in dataclass_from_dict
    return cls(*[_get_argument_value(arg_spec, index, args) for index in range(1, len(arg_spec.args))])
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 20, in <listcomp>
    return cls(*[_get_argument_value(arg_spec, index, args) for index in range(1, len(arg_spec.args))])
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 36, in _get_argument_value
    return _dataclass_from_dict_recurse(value, arg_spec.annotations.get(argument_name))
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 41, in _dataclass_from_dict_recurse
    if isinstance(value, dict) and not issubclass(requested_type, dict) \
TypeError: issubclass() arg 1 must be a class
2023-01-10 15:33:56,848 - {helpermodules.subdata:420} - {ERROR:Thread-5} - Fehler im subdata-Modul
Traceback (most recent call last):
  File "/var/www/html/openWB/packages/helpermodules/subdata.py", line 417, in process_chargepoint_template_topic
    var["cpt"+index].data = dataclass_from_dict(chargepoint.CpTemplateData, payload)
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 20, in dataclass_from_dict
    return cls(*[_get_argument_value(arg_spec, index, args) for index in range(1, len(arg_spec.args))])
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 20, in <listcomp>
    return cls(*[_get_argument_value(arg_spec, index, args) for index in range(1, len(arg_spec.args))])
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 36, in _get_argument_value
    return _dataclass_from_dict_recurse(value, arg_spec.annotations.get(argument_name))
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 40, in _dataclass_from_dict_recurse
    return dataclass_from_dict(requested_type, value) \
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 20, in dataclass_from_dict
    return cls(*[_get_argument_value(arg_spec, index, args) for index in range(1, len(arg_spec.args))])
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 20, in <listcomp>
    return cls(*[_get_argument_value(arg_spec, index, args) for index in range(1, len(arg_spec.args))])
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 36, in _get_argument_value
    return _dataclass_from_dict_recurse(value, arg_spec.annotations.get(argument_name))
  File "/var/www/html/openWB/packages/dataclass_utils/_dataclass_from_dict.py", line 41, in _dataclass_from_dict_recurse
    if isinstance(value, dict) and not issubclass(requested_type, dict) \
TypeError: issubclass() arg 1 must be a class
2023-01-10 15:33:56,937 - {helpermodules.setdata:37} - {DEBUG:Thread-6} - Subdata initialisation completed. Starting setdata loop to broker.
2023-01-10 15:34:22,441 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})
2023-01-10 15:34:22,443 - {root:61} - {INFO:MainThread} - # ***Start*** 

Re: Feedback Nightly 101.1

Verfasst: Di Jan 10, 2023 6:52 pm
von vuffiraa
vuffiraa hat geschrieben: Mo Jan 09, 2023 8:43 pm Kleiner Nachtrag zu meine vorherigen Beitrag. Das Problem scheint der MQTT-Payload eines chargepoint/template zu sein.

Code: Alles auswählen

{"autolock": {"active": false, "plans": {}, "wait_for_charging_end": false}, "id": 1, "max_current_multi_phases": 32, "max_current_single_phase": 32, "name": "Standard Ladepunkt-Vorlage", "rfid_enabling": false, "valid_tags": []}
Die Exception tritt beim Einlesen des Elements plans im autolock-Element auf. Ohne dieses Element tritt die Exception nicht auf.
Eigentlich dachte ich zuerst, hier werden die Zeitpläne konfiguriert. Diese werden aber als eigenes autolock-Subtopic im Broker abgelegt.

Warum hat die Klasse Autolock im cargepoint.py das Feld plans?

VG
Ich muß mich noch mal melden, vielleicht gibt es bei mir doch ein größeres Problem. Ich bin heute auf die Idee gekommen, mal in die Logs der Wallbox zu schauen, also OpenWB 1.9 als "nur Ladepunkt". Da gibt es im Ladepunkt Log einen Fehler, der sich regelmäßig wiederholt.

Code: Alles auswählen

**** REBOOT ****
2023-01-10 18:23:34,041 - {root:30} - ERROR - Loglevel: 0
2023-01-10 18:25:03,902 - {root:297} - ERROR - Fehler bei Ladepunkt 1
Traceback (most recent call last):
  File "/var/www/html/openWB/packages/modules/common/modbus.py", line 85, in __read_registers
    raise FaultState.error(__name__+" "+str(response))
modules.common.fault_state.FaultState: ('modules.common.modbus Modbus Error: [Input/Output] read failed: device reports readiness to read but returned no data (device disconnected or multiple access on port?)', <FaultStateLevel.ERROR: 2>)

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/var/www/html/openWB/packages/modules/chargepoints/internal_openwb/chargepoint_module.py", line 111, in get_values
    plug_state, charge_state, self.set_current_evse = self.__client.evse_client.get_plug_charge_state()
  File "/var/www/html/openWB/packages/modules/common/evse.py", line 35, in get_plug_charge_state
    1000, [ModbusDataType.UINT_16]*3, unit=self.id)
  File "/var/www/html/openWB/packages/modules/common/modbus.py", line 119, in read_holding_registers
    self.delegate.read_holding_registers, address, types, byteorder, wordorder, **kwargs
  File "/var/www/html/openWB/packages/modules/common/modbus.py", line 101, in __read_registers
    str(e)) from e
modules.common.fault_state.FaultState: ("modules.common.modbus <class 'modules.common.fault_state.FaultState'> ('modules.common.modbus Modbus Error: [Input/Output] read failed: device reports readiness to read but returned no data (device disconnected or multiple access on port?)', <FaultStateLevel.ERROR: 2>)", <FaultStateLevel.ERROR: 2>)

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/var/www/html/openWB/runs/isss.py", line 292, in update
    state, _ = self.module.get_values(phase_switch_cp_active)
  File "/var/www/html/openWB/packages/modules/chargepoints/internal_openwb/chargepoint_module.py", line 159, in get_values
    raise FaultState.error(__name__ + " " + str(type(e)) + " " + str(e)) from e
modules.common.fault_state.FaultState: ('modules.chargepoints.internal_openwb.chargepoint_module <class \'modules.common.fault_state.FaultState\'> ("modules.common.modbus <class \'modules.common.fault_state.FaultState\'> (\'modules.common.modbus Modbus Error: [Input/Output] read failed: device reports readiness to read but returned no data (device disconnected or multiple access on port?)\', <FaultStateLevel.ERROR: 2>)", <FaultStateLevel.ERROR: 2>)', <FaultStateLevel.ERROR: 2>)
Ich konnte in meinen Backups sehen, dass der Fehler schon vor dem ersten Ladevorgang von gestern im Log stand, aber auch nicht schon immer. Eventuell liegt das erste Auftreten mit dem Einstellen der Wallbox als Ladepunkt für die OpenWB 2.0 zusammen.

Ist das noch ein Konfigurationsproblem oder schon ein Hardwarefehler?

Re: Feedback Nightly 101.1

Verfasst: Di Jan 10, 2023 8:30 pm
von vuffiraa
So, jetzt fange ich hier wohl an zu spammen ;)
Bei mir wird der momentane Hausverbrauch nicht mehr im Graphen angezeigt, er ist einfach konstant auf dem letzten bekannten Wert.
Die Regellogik wird aber normal ausgeführt, sprich Zähler und WR werden ausgelesen.

Problem dürfte PR #741 sein.
Mein Fronius Smartmeter ist als EVU-Zähler konfiguriert:
Bildschirm­foto 2023-01-10 um 21.24.37.png
In packages/modules/common/store/_counter.py: PurgeCounterState.calc_virtual() wird der if-Block übersprungen, dann gibt es aber am Ende keine Werte, um einen neuen CounterState zu initialisieren und es gibt eine Exception. Früher wurde hier in meinem Fall einfach der State aus dem Input durchgereicht.

VG

Re: Feedback Nightly 101.1

Verfasst: Di Jan 10, 2023 8:47 pm
von mrinas
vuffiraa hat geschrieben: Di Jan 10, 2023 8:30 pm So, jetzt fange ich hier wohl an zu spammen ;)
Das macht nichts, dann fallen meine Posts vielleicht nicht mehr ganz so auf, nur zu :D
vuffiraa hat geschrieben: Di Jan 10, 2023 8:30 pm Bei mir wird der momentane Hausverbrauch nicht mehr im Graphen angezeigt, er ist einfach konstant auf dem letzten bekannten Wert.
Die Regellogik wird aber normal ausgeführt, sprich Zähler und WR werden ausgelesen.

Problem dürfte PR #741 sein.
Mein Fronius Smartmeter ist als EVU-Zähler konfiguriert:
Bildschirm­foto 2023-01-10 um 21.24.37.png

In packages/modules/common/store/_counter.py: PurgeCounterState.calc_virtual() wird der if-Block übersprungen, dann gibt es aber am Ende keine Werte, um einen neuen CounterState zu initialisieren und es gibt eine Exception. Früher wurde hier in meinem Fall einfach der State aus dem Input durchgereicht.

VG
Poste doch mal einen gesamten Regelzyklus von *** start *** bis zum nächsten *** start ***.

Re: Feedback Nightly 101.1

Verfasst: Di Jan 10, 2023 8:55 pm
von vuffiraa
mrinas hat geschrieben: Di Jan 10, 2023 8:47 pm Das macht nichts, dann fallen meine Posts vielleicht nicht mehr ganz so auf, nur zu :D

Poste doch mal einen gesamten Regelzyklus von *** start *** bis zum nächsten *** start ***.
Na dann, damit kann ich dienen:

Code: Alles auswählen

2023-01-10 20:49:10,957 - {root:61} - {INFO:MainThread} - # ***Start*** 
2023-01-10 20:49:10,996 - {modules.devices.fronius.device:59} - {DEBUG:device0} - Start device reading {'component0': <modules.devices.fronius.counter_sm.FroniusSmCounter object at 0x726080e8>, 'component1': <modules.devices.fronius.inverter.FroniusInverter object at 0x7260e358>}
2023-01-10 20:49:10,997 - {soc.modules.common.component_context:24} - {DEBUG:cp2} - Update Komponente ['Ladepunkt']
2023-01-10 20:49:10,998 - {soc.modules.common.component_context:51} - {DEBUG:device0} - Update Komponenten ['Fronius SM Zähler', 'Fronius Wechselrichter']
2023-01-10 20:49:11,012 - {urllib3.connectionpool:227} - {DEBUG:device0} - Starting new HTTP connection (1): 192.168.2.49:80
2023-01-10 20:49:11,147 - {urllib3.connectionpool:452} - {DEBUG:device0} - http://192.168.2.49:80 "GET /solar_api/v1/GetMeterRealtimeData.cgi?Scope=Device&DeviceId=2 HTTP/1.1" 200 2229
2023-01-10 20:49:11,149 - {soc.modules.common.req:11} - {DEBUG:device0} - Get-Response: {
   "Body" : {
      "Data" : {
         "Current_AC_Phase_1" : 0.75,
         "Current_AC_Phase_2" : 0.29399999999999998,
         "Current_AC_Phase_3" : 1.0349999999999999,
         "Details" : {
            "Manufacturer" : "Fronius",
            "Model" : "Smart Meter 63A",
            "Serial" : "xxxxxx"
         },
         "Enable" : 1,
         "EnergyReactive_VArAC_Sum_Consumed" : 6127580,
         "EnergyReactive_VArAC_Sum_Produced" : 77692310,
         "EnergyReal_WAC_Minus_Absolute" : 17587042,
         "EnergyReal_WAC_Plus_Absolute" : 0,
         "EnergyReal_WAC_Sum_Consumed" : 17587042,
         "EnergyReal_WAC_Sum_Produced" : 0,
         "Frequency_Phase_Average" : 50,
         "Meter_Location_Current" : 1,
         "PowerApparent_S_Phase_1" : 177.22500000000002,
         "PowerApparent_S_Phase_2" : 69.2958,
         "PowerApparent_S_Phase_3" : 244.67399999999998,
         "PowerApparent_S_Sum" : 414,
         "PowerFactor_Phase_1" : 0.73999999999999999,
         "PowerFactor_Phase_2" : 0.71999999999999997,
         "PowerFactor_Phase_3" : 0.85999999999999999,
         "PowerFactor_Sum" : 0.80000000000000004,
         "PowerReactive_Q_Phase_1" : -98.530000000000001,
         "PowerReactive_Q_Phase_2" : -35.82,
         "PowerReactive_Q_Phase_3" : -109.69,
         "PowerReactive_Q_Sum" : -244.03999999999999,
         "PowerReal_P_Phase_1" : -109.92,
         "PowerReal_P_Phase_2" : -37.539999999999999,
         "PowerReal_P_Phase_3" : -186.91,
         "PowerReal_P_Sum" : -334.37,
         "TimeStamp" : 1673380150,
         "Visible" : 1,
         "Voltage_AC_PhaseToPhase_12" : 408.80000000000001,
         "Voltage_AC_PhaseToPhase_23" : 408.89999999999998,
         "Voltage_AC_PhaseToPhase_31" : 409.39999999999998,
         "Voltage_AC_Phase_1" : 236.30000000000001,
         "Voltage_AC_Phase_2" : 235.69999999999999,
         "Voltage_AC_Phase_3" : 236.40000000000001
      }
   },
   "Head" : {
      "RequestArguments" : {
         "DeviceClass" : "Meter",
         "DeviceId" : "2",
         "Scope" : "Device"
      },
      "Status" : {
         "Code" : 0,
         "Reason" : "",
         "UserMessage" : ""
      },
      "Timestamp" : "2023-01-10T20:49:11+01:00"
   }
}

2023-01-10 20:49:11,151 - {modules.devices.fronius.counter_sm:67} - {DEBUG:device0} - Einbauort: MeterLocation.load
2023-01-10 20:49:11,204 - {urllib3.connectionpool:452} - {DEBUG:device0} - http://192.168.2.49:80 "GET /solar_api/v1/GetPowerFlowRealtimeData.fcgi?Scope=System HTTP/1.1" 200 899
2023-01-10 20:49:11,206 - {soc.modules.common.req:11} - {DEBUG:device0} - Get-Response: {
   "Body" : {
      "Data" : {
         "Inverters" : {
            "1" : {
               "DT" : 123,
               "E_Day" : 8067,
               "E_Total" : 53515200,
               "E_Year" : 44840.69921875,
               "P" : 0
            }
         },
         "Site" : {
            "E_Day" : 8067,
            "E_Total" : 53515200,
            "E_Year" : 44840.69921875,
            "Meter_Location" : "load",
            "Mode" : "meter",
            "P_Akku" : null,
            "P_Grid" : 334.37,
            "P_Load" : -334.37,
            "P_PV" : null,
            "rel_Autonomy" : 0,
            "rel_SelfConsumption" : null
         },
         "Version" : "12"
      }
   },
   "Head" : {
      "RequestArguments" : {},
      "Status" : {
         "Code" : 0,
         "Reason" : "",
         "UserMessage" : ""
      },
      "Timestamp" : "2023-01-10T20:49:11+01:00"
   }
}

2023-01-10 20:49:11,208 - {modules.common.simcount._simcount:38} - {DEBUG:device0} - Previous state: SimCounterState(timestamp=1673380140.5075736, power=341.33, imported=149146.61371682296, exported=152551.5524661473)
2023-01-10 20:49:11,209 - {modules.common.simcount._calculate:13} - {DEBUG:device0} - time passed: 0.00297255, power1: 341.33, power2: 334.37
2023-01-10 20:49:11,209 - {modules.common.simcount._calculate:29} - {DEBUG:device0} - power did not change sign. Total energy: 1.00428
2023-01-10 20:49:11,210 - {modules.common.simcount._simcount:47} - {DEBUG:device0} - imported: 1.00428 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1673380151.2087686, power=334.37, imported=149147.6179942483, exported=152551.5524661473)
2023-01-10 20:49:11,211 - {soc.modules.common.store._api:26} - {DEBUG:device0} - Raw data CounterState(voltages=[236.3, 235.7, 236.4], powers=[109.92, 37.54, 186.91], currents=[0.46517139229792637, 0.15927025880356385, 0.7906514382402707], power_factors=[0.74, 0.72, 0.86], imported=149147.6179942483, exported=152551.5524661473, power=334.37, frequency=50)
2023-01-10 20:49:11,221 - {urllib3.connectionpool:227} - {DEBUG:device0} - Starting new HTTP connection (1): 192.168.2.49:80
2023-01-10 20:49:11,241 - {urllib3.connectionpool:452} - {DEBUG:device0} - http://192.168.2.49:80 "GET /solar_api/v1/GetPowerFlowRealtimeData.fcgi?Scope=System HTTP/1.1" 200 899
2023-01-10 20:49:11,243 - {soc.modules.common.req:11} - {DEBUG:device0} - Get-Response: {
   "Body" : {
      "Data" : {
         "Inverters" : {
            "1" : {
               "DT" : 123,
               "E_Day" : 8067,
               "E_Total" : 53515200,
               "E_Year" : 44840.69921875,
               "P" : 0
            }
         },
         "Site" : {
            "E_Day" : 8067,
            "E_Total" : 53515200,
            "E_Year" : 44840.69921875,
            "Meter_Location" : "load",
            "Mode" : "meter",
            "P_Akku" : null,
            "P_Grid" : 334.37,
            "P_Load" : -334.37,
            "P_PV" : null,
            "rel_Autonomy" : 0,
            "rel_SelfConsumption" : null
         },
         "Version" : "12"
      }
   },
   "Head" : {
      "RequestArguments" : {},
      "Status" : {
         "Code" : 0,
         "Reason" : "",
         "UserMessage" : ""
      },
      "Timestamp" : "2023-01-10T20:49:11+01:00"
   }
}

2023-01-10 20:49:11,246 - {modules.common.simcount._simcount:38} - {DEBUG:device0} - Previous state: SimCounterState(timestamp=1673380140.5375342, power=0, imported=0.0, exported=370537.268279376)
2023-01-10 20:49:11,246 - {modules.common.simcount._calculate:13} - {DEBUG:device0} - time passed: 0.00297456, power1: 0, power2: 0
2023-01-10 20:49:11,247 - {modules.common.simcount._calculate:29} - {DEBUG:device0} - power did not change sign. Total energy: 0
2023-01-10 20:49:11,247 - {modules.common.simcount._simcount:47} - {DEBUG:device0} - imported: 0 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1673380151.2459588, power=0, imported=0.0, exported=370537.268279376)
2023-01-10 20:49:11,248 - {soc.modules.common.store._api:26} - {DEBUG:device0} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=0, exported=370537.268279376, dc_power=None)
2023-01-10 20:49:11,265 - {soc.modules.common.component_context:24} - {DEBUG:component1} - Update Komponente ['Fronius Wechselrichter']
2023-01-10 20:49:11,266 - {soc.modules.common.store._api:26} - {DEBUG:component1} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=0, exported=370537.268279376, dc_power=None)
2023-01-10 20:49:11,267 - {soc.modules.common.store._api:30} - {DEBUG:component1} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=0, exported=370537.268279376, dc_power=None)
2023-01-10 20:49:11,270 - {soc.modules.common.component_context:24} - {DEBUG:cp2} - Update Komponente ['Ladepunkt']
2023-01-10 20:49:11,306 - {soc.modules.common.component_context:24} - {DEBUG:component0} - Update Komponente ['Fronius SM Zähler']
2023-01-10 20:49:11,367 - {control.counter:87} - {DEBUG:MainThread} - Verbleibende Ströme an Zähler 0: [33.53, 33.88, 33.42]
2023-01-10 20:49:11,369 - {control.counter:116} - {DEBUG:MainThread} - Verbleibende Leistung an Zähler 0: 23725.9
2023-01-10 20:49:11,382 - {control.chargepoint:349} - {INFO:MainThread} - LP 2: Keine Ladung, da kein Auto angesteckt ist.
2023-01-10 20:49:11,394 - {control.data:297} - {DEBUG:MainThread} - all
{'get': {'power': 0}, 'config': {'configured': False}, 'set': {'charging_power_left': 0, 'switch_on_soc_reached': False}}
2023-01-10 20:49:11,403 - {control.data:270} - {DEBUG:MainThread} - cp_all_data
AllChargepointData(get=AllGet(daily_imported=0.0, daily_exported=0, power=0, imported=54766.0, exported=0))
2023-01-10 20:49:11,404 - {control.data:297} - {DEBUG:MainThread} - cp2
ChargepointData(get=Get(charge_state=False, connected_vehicle=ConnectedVehicle(config=ConnectedConfig(average_consumption=17, charge_template=0, chargemode='stop', current_plan=0, ev_template=0, priority=False), info=ConnectedInfo(id=0, name='Ladepunkt'), soc=0), currents=[0.0, 0.0, 0.0], daily_imported=0.0, daily_exported=0, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=54766.0, phases_in_use=0, plug_state=False, power=0, rfid_timestamp=None, rfid=None, state_str='Keine Ladung, da kein Auto angesteckt ist.', voltages=[235.45, 236.02, 236.0]), set=Set(change_ev_permitted=[True, ''], charging_ev=-1, charging_ev_prev=-1, current=0, energy_to_charge=0, loadmanagement_available=True, log=Log(chargemode_log_entry='_', imported_at_mode_switch=0, imported_at_plugtime=0, imported_since_mode_switch=0, imported_since_plugged=0, range_charged=0, time_charged='00:00', timestamp_start_charging=None), manual_lock=False, phases_to_use=0, plug_state_prev=False, plug_time=None, required_power=0, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0x71d901f0>), config=Config(connection_module={'type': 'external_openwb', 'name': 'Externe openWB', 'configuration': {'ip_address': '192.168.2.52', 'duo_num': 1}}, power_module={}, ev=0, name='Standard-Ladepunkt', type='external_openwb', template=0, connected_phases=3, phase_1=1, auto_phase_switch_hw=True, control_pilot_interruption_hw=True, id=2))
2023-01-10 20:49:11,406 - {control.data:297} - {DEBUG:MainThread} - cpt0
CpTemplateData(autolock=Autolock(active=False, plans={}, wait_for_charging_end=False), id=0, max_current_multi_phases=32, max_current_single_phase=32, name='Standard Ladepunkt-Vorlage', rfid_enabling=False, valid_tags=[])
2023-01-10 20:49:11,406 - {control.data:297} - {DEBUG:MainThread} - counter0
{'set': {'reserved_surplus': 0, 'released_surplus': 0, 'raw_power_left': 23725.9, 'surplus_power_left': 0, 'consumption_left': 10647.98, 'raw_currents_left': [33.53, 33.88, 33.42]}, 'get': {'daily_exported': 0.0, 'daily_imported': 0.0, 'fault_str': 'Kein Fehler.', 'fault_state': 0, 'voltages': [235.9, 235.3, 234.6], 'currents': [0.47, 0.12, 0.58], 'powers': [110.48, 28.06, 135.56], 'power_factors': [0.76, 0.63, 0.8], 'imported': 139643.79979201016, 'exported': 145838.9812123515, 'power': 274.1, 'frequency': 50}, 'config': {'max_currents': [35, 35, 35], 'max_total_power': 24000}}
2023-01-10 20:49:11,407 - {control.data:274} - {DEBUG:MainThread} - counter_all_data
CounterAllData(get=Get(hierarchy=[{'id': 0, 'type': 'counter', 'children': [{'id': 1, 'type': 'inverter', 'children': []}, {'id': 2, 'type': 'cp', 'children': []}]}]), set=Set(loadmanagement_active=False, home_consumption=274.1, invalid_home_consumption=0, daily_yield_home_consumption=8078.061))
2023-01-10 20:49:11,407 - {control.data:297} - {DEBUG:MainThread} - ct0
ChargeTemplateData(name='Standard-Ladeprofil-Vorlage', disable_after_unplug=False, prio=False, load_default=False, time_charging=TimeCharging(active=False, plans={}), chargemode=Chargemode(selected='stop', pv_charging=PvCharging(min_soc_current=10, min_current=6, feed_in_limit=False, min_soc=0, max_soc=80), scheduled_charging=ScheduledCharging(plans={}), instant_charging=InstantCharging(current=16, limit=Limit(selected='none', amount=1000, soc=80))))
2023-01-10 20:49:11,408 - {control.data:297} - {DEBUG:MainThread} - ev0
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='Standard-Fahrzeug-Vorlage', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=19, min_current=6, max_current_single_phase=32, battery_capacity=77, nominal_difference=2), et_num=0)), control_parameter=ControlParameter(required_current=0, required_currents=[0.0, 0.0, 0.0], phases=1, prio=False, timestamp_switch_on_off=None, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, submode='stop', chargemode='stop', used_amount_instant_charging=0, imported_at_plan_start=0, current_plan=None), charge_template=0, ev_template=0, name='Standard-Fahrzeug', tag_id=[], get=Get(soc=0, soc_timestamp='', force_soc_update=True, range=0, fault_state=0, fault_str=''))
2023-01-10 20:49:11,408 - {control.data:297} - {DEBUG:MainThread} - et0
EvTemplateData(name='Standard-Fahrzeug-Vorlage', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=True, control_pilot_interruption_duration=4, average_consump=21, min_current=6, max_current_single_phase=32, battery_capacity=77, nominal_difference=2)
2023-01-10 20:49:11,410 - {control.data:278} - {DEBUG:MainThread} - general_data
GeneralData(chargemode_config=ChargemodeConfig(instant_charging=InstantCharging(phases_to_use=1), pv_charging=PvCharging(bat_prio=True, charging_power_reserve=200, control_range=[-230, 0], feed_in_yield=15000, phase_switch_delay=7, phases_to_use=0, rundown_power=1000, rundown_soc=50, switch_off_delay=60, switch_off_soc=40, switch_off_threshold=50, switch_on_delay=30, switch_on_soc=60, switch_on_threshold=1500), scheduled_charging=ScheduledCharging(phases_to_use=0), standby=Standby(phases_to_use=1), stop=Stop(phases_to_use=1), time_charging=TimeCharging(phases_to_use=1), unbalanced_load_limit=18, unbalanced_load=False), control_interval=10, extern_display_mode='local', extern=False, external_buttons_hw=False, grid_protection_active=False, grid_protection_configured=True, grid_protection_random_stop=0, grid_protection_timestamp='', mqtt_bridge=False, price_kwh=0.2582, range_unit='km', ripple_control_receiver=RippleControlReceiver(configured=False, r1_active=False, r2_active=False))
2023-01-10 20:49:11,410 - {control.data:297} - {DEBUG:MainThread} - graph
{'config': {'duration': 120}}
2023-01-10 20:49:11,414 - {control.data:280} - {DEBUG:MainThread} - optional_data
OptionalData(et=Et(active=False, config=EtConfig(max_price=0, provider={}), get=EtGet(price=0, price_list=[])), int_display=InternalDisplay(active=False, on_if_plugged_in=True, pin_active=False, pin_code='0000', standby=60, theme='cards'), led=Led(active=False), rfid=Rfid(active=False))
2023-01-10 20:49:11,416 - {control.data:297} - {DEBUG:MainThread} - all
{'get': {'power': 0, 'exported': 370537.268, 'daily_exported': 8078.061, 'monthly_exported': 0, 'yearly_exported': 0}, 'config': {'configured': True}}
2023-01-10 20:49:11,416 - {control.data:297} - {DEBUG:MainThread} - pv1
{'get': {'daily_exported': 8078.061, 'monthly_exported': 0, 'yearly_exported': 0, 'fault_str': 'Kein Fehler.', 'fault_state': 0, 'power': 0, 'exported': 370537.268, 'currents': [0.0, 0.0, 0.0]}, 'config': {'max_ac_out': 0}}
2023-01-10 20:49:11,419 - {control.data:297} - {DEBUG:MainThread} - system
{'update_in_progress': False, 'perform_update': False, 'boot_done': True, 'ip_address': '192.168.2.53', 'current_commit': '2023-01-10 11:20:30 +0100 [d489ed16]', 'current_branch': 'master', 'current_branch_commit': '2023-01-10 11:20:30 +0100 [d489ed16]', 'current_missing_commits': [], 'dataprotection_acknowledged': True, 'debug_level': 10, 'release_train': 'master', 'version': '1.99.101.1', 'datastore_version': 6, 'lastlivevaluesJson': {'timestamp': 1673380140, 'time': '20:49:00', 'grid': 0.274, 'house-power': 0.274, 'charging-all': 0.0, 'pv-all': -0.0, 'cp2-power': 0.0}, 'module_update_completed': True}
2023-01-10 20:49:11,419 - {control.data:310} - {DEBUG:MainThread} - device0
{'name': 'Fronius', 'type': 'fronius', 'id': 0, 'configuration': {'ip_address': '192.168.2.49'}}
2023-01-10 20:49:11,423 - {control.data:312} - {DEBUG:MainThread} - component0
{'name': 'Fronius SM Zähler', 'type': 'counter_sm', 'id': 0, 'configuration': {'meter_id': 2, 'variant': 0}}
2023-01-10 20:49:11,424 - {control.data:312} - {DEBUG:MainThread} - component1
{'name': 'Fronius Wechselrichter', 'type': 'inverter', 'id': 1, 'configuration': {}}
2023-01-10 20:49:11,424 - {control.data:284} - {DEBUG:MainThread} - 

2023-01-10 20:49:11,424 - {control.algorithm.algorithm:26} - {DEBUG:MainThread} - # Algorithmus-Start
2023-01-10 20:49:11,425 - {control.algorithm.algorithm:32} - {DEBUG:MainThread} - **Mindestrom setzen**
2023-01-10 20:49:11,432 - {control.algorithm.algorithm:34} - {DEBUG:MainThread} - **Sollstrom setzen**
2023-01-10 20:49:11,436 - {control.counter:159} - {DEBUG:MainThread} - Überschuss zur PV-geführten Ladung: 0W
2023-01-10 20:49:11,440 - {control.counter:407} - {DEBUG:MainThread} - Zähler 0: Begrenzung der verbleibenden Leistung auf 0W
2023-01-10 20:49:11,441 - {control.algorithm.algorithm:45} - {DEBUG:MainThread} - **Keine Leistung für PV-geführtes Laden übrig.**
2023-01-10 20:49:11,441 - {control.process:22} - {DEBUG:MainThread} - # Ladung starten.
2023-01-10 20:49:11,444 - {soc.modules.common.component_context:24} - {DEBUG:cp2} - Update Komponente ['Ladepunkt']
2023-01-10 20:49:11,466 - {control.counter:141} - {DEBUG:MainThread} - 0W reservierte EVU-Leistung, 0W freigegebene EVU-Leistung
2023-01-10 20:49:11,654 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=update, args=(), kwargs={})
2023-01-10 20:49:20,716 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})
2023-01-10 20:49:20,717 - {root:61} - {INFO:MainThread} - # ***Start*** 

Re: Feedback Nightly 101.1

Verfasst: Mi Jan 11, 2023 10:15 am
von mrinas
Leider klappt das Stoppen einer PV-geführten Ladung bei Unterschreiten der Abschaltschwelle nicht.

Es werden die 180s angezählt, dann gibts eine Meldung dass die Abschaltschwelle unterschritten wurde (was nicht korrekt ist), und dann wird wieder von vorn gezählt.

Code: Alles auswählen

2023-01-11 11:11:32,916 - {root:61} - {INFO:MainThread} - # ***Start*** 
2023-01-11 11:11:32,983 - {soc.modules.common.component_context:51} - {DEBUG:device4} - Update Komponenten ['EnergyMeter 1.0']
2023-01-11 11:11:32,985 - {soc.modules.common.component_context:51} - {DEBUG:device8} - Update Komponenten ['Pumpe']
2023-01-11 11:11:32,986 - {soc.modules.common.component_context:51} - {DEBUG:device9} - Update Komponenten ['Trockner']
2023-01-11 11:11:32,989 - {soc.modules.common.component_context:24} - {DEBUG:device14} - Update Komponente ['LP1 (Garage)']
2023-01-11 11:11:32,999 - {urllib3.connectionpool:227} - {DEBUG:device8} - Starting new HTTP connection (1): 192.168.178.81:80
2023-01-11 11:11:33,003 - {urllib3.connectionpool:227} - {DEBUG:device9} - Starting new HTTP connection (1): 192.168.178.209:80
2023-01-11 11:11:33,003 - {modules.devices.sma_sunny_boy.device:72} - {DEBUG:device15} - Start device reading {'component22': <modules.devices.sma_sunny_boy.inverter.SmaSunnyBoyInverter object at 0x7261c9e8>}
2023-01-11 11:11:33,011 - {urllib3.connectionpool:272} - {DEBUG:device14} - Resetting dropped connection: 192.168.178.51
2023-01-11 11:11:33,013 - {modules.devices.sma_sunny_boy.device:72} - {DEBUG:device16} - Start device reading {'component23': <modules.devices.sma_sunny_boy.inverter.SmaSunnyBoyInverter object at 0x7261c6e8>}
2023-01-11 11:11:33,018 - {soc.modules.common.component_context:24} - {DEBUG:cp13} - Update Komponente ['Ladepunkt']
2023-01-11 11:11:33,033 - {urllib3.connectionpool:452} - {DEBUG:device14} - http://192.168.178.51:80 "GET /openWB/ramdisk/llkwh HTTP/1.1" 200 8
2023-01-11 11:11:33,036 - {soc.modules.common.req:11} - {DEBUG:device14} - Get-Response: 6060.673
2023-01-11 11:11:33,043 - {urllib3.connectionpool:452} - {DEBUG:device8} - http://192.168.178.81:80 "GET /meter/0 HTTP/1.1" 200 113
2023-01-11 11:11:33,046 - {soc.modules.common.req:11} - {DEBUG:device8} - Get-Response: {"power":0.00,"overpower":0.00,"is_valid":true,"timestamp":1673435493,"counters":[0.000, 0.000, 0.000],"total":0}
2023-01-11 11:11:33,048 - {urllib3.connectionpool:452} - {DEBUG:device14} - http://192.168.178.51:80 "GET /openWB/ramdisk/llaktuell HTTP/1.1" 200 1
2023-01-11 11:11:33,454 - {soc.modules.common.req:11} - {DEBUG:device14} - Get-Response: 0
2023-01-11 11:11:33,451 - {soc.modules.common.component_context:24} - {DEBUG:device15} - Update Komponente ['WR Garage (neu)']
2023-01-11 11:11:33,455 - {modules.common.simcount._simcount:38} - {DEBUG:device14} - Previous state: SimCounterState(timestamp=1673431881.04752, power=0.0, imported=714701.086455916, exported=0)
2023-01-11 11:11:33,456 - {urllib3.connectionpool:452} - {DEBUG:device9} - http://192.168.178.209:80 "GET /meter/0 HTTP/1.1" 200 87
2023-01-11 11:11:33,457 - {soc.modules.common.component_context:24} - {DEBUG:device16} - Update Komponente ['WR Haus (neu)']
2023-01-11 11:11:33,461 - {modules.common.simcount._simcount:38} - {DEBUG:device8} - Previous state: SimCounterState(timestamp=1673431881.8767023, power=0.0, imported=56424.15561013759, exported=0.0)
2023-01-11 11:11:33,462 - {modules.common.simcount._calculate:13} - {DEBUG:device14} - time passed: 0.00344657, power1: 0, power2: 0
2023-01-11 11:11:33,465 - {modules.common.simcount._calculate:13} - {DEBUG:device8} - time passed: 0.00321802, power1: 0, power2: 0
2023-01-11 11:11:33,466 - {soc.modules.common.req:11} - {DEBUG:device9} - Get-Response: {"power":0.00,"is_valid":true,"timestamp":0,"counters":[0.000, 0.000, 0.000],"total":0}
2023-01-11 11:11:33,467 - {modules.common.simcount._calculate:29} - {DEBUG:device14} - power did not change sign. Total energy: 0
2023-01-11 11:11:33,875 - {modules.common.simcount._simcount:47} - {DEBUG:device14} - imported: 0 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1673431893.45518, power=0.0, imported=714701.086455916, exported=0)
2023-01-11 11:11:33,467 - {modules.common.simcount._calculate:29} - {DEBUG:device8} - power did not change sign. Total energy: 0
2023-01-11 11:11:33,876 - {modules.common.simcount._simcount:38} - {DEBUG:device9} - Previous state: SimCounterState(timestamp=1673431881.4738004, power=0.0, imported=108158.61628581406, exported=0.0)
2023-01-11 11:11:33,877 - {modules.common.simcount._simcount:47} - {DEBUG:device8} - imported: 0 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1673431893.4615738, power=0.0, imported=56424.15561013759, exported=0.0)
2023-01-11 11:11:33,885 - {modules.common.simcount._calculate:13} - {DEBUG:device9} - time passed: 0.00344516, power1: 0, power2: 0
2023-01-11 11:11:33,889 - {urllib3.connectionpool:452} - {DEBUG:device14} - http://192.168.178.51:80 "GET /openWB/ramdisk/lla1 HTTP/1.1" 200 3
2023-01-11 11:11:33,889 - {soc.modules.common.store._api:26} - {DEBUG:device8} - Raw data CounterState(voltages=[230.0, 230.0, 230.0], powers=[0.0, 0.0, 0.0], currents=[0.0, 0.0, 0.0], power_factors=[0.0, 0.0, 0.0], imported=56424.15561013759, exported=0.0, power=0.0, frequency=50)
2023-01-11 11:11:33,894 - {modules.common.simcount._calculate:29} - {DEBUG:device9} - power did not change sign. Total energy: 0
2023-01-11 11:11:33,898 - {soc.modules.common.req:11} - {DEBUG:device14} - Get-Response: 0.0
2023-01-11 11:11:33,903 - {modules.common.simcount._simcount:47} - {DEBUG:device9} - imported: 0 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1673431893.8763874, power=0.0, imported=108158.61628581406, exported=0.0)
2023-01-11 11:11:33,923 - {soc.modules.common.store._api:26} - {DEBUG:device9} - Raw data CounterState(voltages=[230.0, 230.0, 230.0], powers=[0.0, 0.0, 0.0], currents=[0.0, 0.0, 0.0], power_factors=[0.0, 0.0, 0.0], imported=108158.61628581406, exported=0.0, power=0.0, frequency=50)
2023-01-11 11:11:33,928 - {urllib3.connectionpool:452} - {DEBUG:device14} - http://192.168.178.51:80 "GET /openWB/ramdisk/lla2 HTTP/1.1" 200 3
2023-01-11 11:11:33,930 - {soc.modules.common.req:11} - {DEBUG:device14} - Get-Response: 0.0
2023-01-11 11:11:33,944 - {urllib3.connectionpool:452} - {DEBUG:device14} - http://192.168.178.51:80 "GET /openWB/ramdisk/lla3 HTTP/1.1" 200 3
2023-01-11 11:11:33,946 - {soc.modules.common.req:11} - {DEBUG:device14} - Get-Response: 0.0
2023-01-11 11:11:33,947 - {soc.modules.common.store._api:26} - {DEBUG:device14} - Raw data CounterState(voltages=[230.0, 230.0, 230.0], powers=[0.0, 0.0, 0.0], currents=[0.0, 0.0, 0.0], power_factors=[0.0, 0.0, 0.0], imported=714701.086455916, exported=0, power=0.0, frequency=50)
2023-01-11 11:11:33,956 - {modules.devices.sma_sunny_boy.inverter:63} - {DEBUG:device15} - WR 192.168.178.204: InverterState(currents=[0.0, 0.0, 0.0], power=-511, exported=2533553, dc_power=-596)
2023-01-11 11:11:33,958 - {soc.modules.common.store._api:26} - {DEBUG:device15} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-511, exported=2533553, dc_power=-596)
2023-01-11 11:11:33,974 - {soc.modules.common.component_context:24} - {DEBUG:device4} - Update Komponente ['EnergyMeter 1.0']
2023-01-11 11:11:33,975 - {soc.modules.common.store._api:26} - {DEBUG:device4} - Raw data CounterState(voltages=[227.071, 224.971, 227.74], powers=[-498.9, 1045.6, 160.2], currents=[-2.209, 4.907, 0.912], power_factors=[0.998, 0.989, 0.916], imported=35956950.7, exported=73852953.0, power=706.9, frequency=50)
2023-01-11 11:11:33,976 - {modules.devices.sma_shm.device:29} - {DEBUG:device4} - All components updated
2023-01-11 11:11:34,216 - {modules.devices.sma_sunny_boy.inverter:63} - {DEBUG:device16} - WR 192.168.178.201: InverterState(currents=[0.0, 0.0, 0.0], power=-594, exported=41030872, dc_power=-599)
2023-01-11 11:11:34,217 - {soc.modules.common.store._api:26} - {DEBUG:device16} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-594, exported=41030872, dc_power=-599)
2023-01-11 11:11:34,220 - {soc.modules.common.component_context:24} - {DEBUG:component23} - Update Komponente ['WR Haus (neu)']
2023-01-11 11:11:34,221 - {soc.modules.common.store._api:26} - {DEBUG:component23} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-594, exported=41030872, dc_power=-599)
2023-01-11 11:11:34,222 - {soc.modules.common.store._api:30} - {DEBUG:component23} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-594, exported=41030872, dc_power=-599)
2023-01-11 11:11:34,223 - {soc.modules.common.component_context:24} - {DEBUG:component22} - Update Komponente ['WR Garage (neu)']
2023-01-11 11:11:34,225 - {soc.modules.common.store._api:26} - {DEBUG:component22} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-511, exported=2533553, dc_power=-596)
2023-01-11 11:11:34,226 - {soc.modules.common.store._api:30} - {DEBUG:component22} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-511, exported=2533553, dc_power=-596)
2023-01-11 11:11:34,228 - {soc.modules.common.component_context:24} - {DEBUG:component14} - Update Komponente ['Pumpe']
2023-01-11 11:11:34,229 - {soc.modules.common.store._api:30} - {DEBUG:component14} - Saving CounterState(voltages=[230.0, 230.0, 230.0], powers=[0.0, 0.0, 0.0], currents=[0.0, 0.0, 0.0], power_factors=[0.0, 0.0, 0.0], imported=56424.15561013759, exported=0.0, power=0.0, frequency=50)
2023-01-11 11:11:34,232 - {soc.modules.common.component_context:24} - {DEBUG:component15} - Update Komponente ['Trockner']
2023-01-11 11:11:34,232 - {soc.modules.common.store._api:30} - {DEBUG:component15} - Saving CounterState(voltages=[230.0, 230.0, 230.0], powers=[0.0, 0.0, 0.0], currents=[0.0, 0.0, 0.0], power_factors=[0.0, 0.0, 0.0], imported=108158.61628581406, exported=0.0, power=0.0, frequency=50)
2023-01-11 11:11:34,245 - {soc.modules.common.component_context:24} - {DEBUG:cp13} - Update Komponente ['Ladepunkt']
2023-01-11 11:11:34,252 - {soc.modules.common.component_context:24} - {DEBUG:component21} - Update Komponente ['LP1 (Garage)']
2023-01-11 11:11:34,255 - {soc.modules.common.store._api:30} - {DEBUG:component21} - Saving CounterState(voltages=[230.0, 230.0, 230.0], powers=[0.0, 0.0, 0.0], currents=[0.0, 0.0, 0.0], power_factors=[0.0, 0.0, 0.0], imported=714701.086455916, exported=0, power=0.0, frequency=50)
2023-01-11 11:11:34,351 - {soc.modules.common.component_context:24} - {DEBUG:component7} - Update Komponente ['EnergyMeter 1.0']
2023-01-11 11:11:34,352 - {soc.modules.common.store._api:30} - {DEBUG:component7} - Saving CounterState(voltages=[227.071, 224.971, 227.74], powers=[-498.9, 1045.6, 160.2], currents=[-2.209, 4.907, 0.912], power_factors=[0.998, 0.989, 0.916], imported=35956950.7, exported=73852953.0, power=706.9, frequency=50)
2023-01-11 11:11:34,442 - {control.counter:87} - {DEBUG:MainThread} - Verbleibende Ströme an Zähler 7: [36.24, 35.01, 33.12]
2023-01-11 11:11:34,443 - {control.counter:116} - {DEBUG:MainThread} - Verbleibende Leistung an Zähler 7: 24622.06
2023-01-11 11:11:34,443 - {control.counter:87} - {DEBUG:MainThread} - Verbleibende Ströme an Zähler 14: [15.0, 15.0, 15.0]
2023-01-11 11:11:34,444 - {control.counter:87} - {DEBUG:MainThread} - Verbleibende Ströme an Zähler 15: [15.0, 15.0, 15.0]
2023-01-11 11:11:34,444 - {control.counter:87} - {DEBUG:MainThread} - Verbleibende Ströme an Zähler 21: [31.0, 31.0, 31.0]
2023-01-11 11:11:34,448 - {control.chargepoint:697} - {DEBUG:MainThread} - EV-Phasenzahl beschränkt die nutzbaren Phasen auf 3
2023-01-11 11:11:34,448 - {control.chargepoint:688} - {DEBUG:MainThread} - Phasenzahl Lademodus: 1
2023-01-11 11:11:34,450 - {control.ev:618} - {DEBUG:MainThread} - Keine Ladung, da kein Zeitfenster für Zeitladen aktiv ist.
2023-01-11 11:11:34,453 - {control.ev:364} - {DEBUG:MainThread} - Änderung des Lademodus :False
2023-01-11 11:11:34,455 - {control.chargepoint:857} - {DEBUG:MainThread} - LP 13, EV: Tesla (EV-Nr.3): Theoretisch benötigter Strom 6A, Lademodus pv_charging, Submodus: Chargemode.PV_CHARGING, Phasen: 1, Priorität: False, max. Ist-Strom: 5.92
2023-01-11 11:11:34,458 - {control.data:297} - {DEBUG:MainThread} - all
{'get': {'power': 0}, 'config': {'configured': False}, 'set': {'charging_power_left': 0, 'switch_on_soc_reached': False}}
2023-01-11 11:11:34,459 - {control.data:270} - {DEBUG:MainThread} - cp_all_data
AllChargepointData(get=AllGet(daily_imported=142.09, daily_exported=0, power=1328.96, imported=4319610.84, exported=0))
2023-01-11 11:11:34,461 - {control.data:297} - {DEBUG:MainThread} - cp13
ChargepointData(get=Get(charge_state=True, connected_vehicle=ConnectedVehicle(config=ConnectedConfig(average_consumption=17, charge_template=0, chargemode='stop', current_plan=0, ev_template=0, priority=False), info=ConnectedInfo(id=0, name='Ladepunkt'), soc=64.0), currents=[5.92, 0.03, 0.03], daily_imported=142.09, daily_exported=0, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=4319610.84, phases_in_use=1, plug_state=True, power=1328.96, rfid_timestamp=None, rfid=None, state_str=None, voltages=[224.36, 228.17, 227.42]), set=Set(change_ev_permitted=[True, ''], charging_ev=3, charging_ev_prev=3, current=6, energy_to_charge=0, loadmanagement_available=True, log=Log(chargemode_log_entry='pv_charging', imported_at_mode_switch=4319457.52, imported_at_plugtime=4319457.52, imported_since_mode_switch=149.410000000149, imported_since_plugged=149.410000000149, range_charged=0.7470500000007451, time_charged='0:07', timestamp_start_charging='01/11/2023, 11:04:42'), manual_lock=False, phases_to_use=1, plug_state_prev=True, plug_time='01/11/2023, 10:41:50', required_power=1380.0, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0x709f2838>), config=Config(connection_module={'type': 'external_openwb', 'configuration': {'ip_address': '192.168.178.210', 'duo_num': 1}}, power_module={}, ev=3, name='Gartenhaus', type='external_openwb', template=0, connected_phases=3, phase_1=2, auto_phase_switch_hw=True, control_pilot_interruption_hw=True, id=13))
2023-01-11 11:11:34,462 - {control.data:297} - {DEBUG:MainThread} - cpt0
CpTemplateData(autolock=Autolock(active=False, plans={}, wait_for_charging_end=False), id=0, max_current_multi_phases=32, max_current_single_phase=32, name='Standard Ladepunkt-Vorlage', rfid_enabling=False, valid_tags=[])
2023-01-11 11:11:34,462 - {control.data:297} - {DEBUG:MainThread} - counter7
{'set': {'reserved_surplus': 0, 'released_surplus': 1380.0, 'raw_power_left': 24622.06, 'surplus_power_left': 0, 'consumption_left': 21395.8, 'raw_currents_left': [36.24, 35.01, 33.12]}, 'get': {'daily_exported': 1226.5, 'daily_imported': 4891.100000004, 'voltages': [227.07, 224.97, 227.74], 'currents': [-2.21, 4.91, 0.91], 'powers': [-498.9, 1045.6, 160.2], 'power_factors': [1.0, 0.99, 0.92], 'imported': 35956950.7, 'exported': 73852953.0, 'power': 706.9, 'fault_str': 'Kein Fehler.', 'fault_state': 0, 'frequency': 50}, 'config': {'max_currents': [35, 35, 35], 'max_total_power': 24000}}
2023-01-11 11:11:34,464 - {control.data:297} - {DEBUG:MainThread} - counter14
{'set': {'reserved_surplus': 0, 'released_surplus': 0, 'raw_power_left': None, 'surplus_power_left': 0, 'raw_currents_left': [15.0, 15.0, 15.0]}, 'get': {'daily_exported': 0.0, 'daily_imported': 0.0, 'fault_str': 'Kein Fehler.', 'fault_state': 0, 'voltages': [230.0, 230.0, 230.0], 'currents': [0.0, 0.0, 0.0], 'powers': [0.0, 0.0, 0.0], 'power_factors': [0.0, 0.0, 0.0], 'imported': 56424.15561013759, 'exported': 0.0, 'power': 0.0, 'frequency': 50}, 'config': {'max_currents': [16, 16, 16], 'max_total_power': 11000}}
2023-01-11 11:11:34,464 - {control.data:297} - {DEBUG:MainThread} - counter15
{'set': {'reserved_surplus': 0, 'released_surplus': 0, 'raw_power_left': None, 'surplus_power_left': 0, 'raw_currents_left': [15.0, 15.0, 15.0]}, 'get': {'daily_exported': 0.0, 'daily_imported': 0.0, 'fault_str': 'Kein Fehler.', 'fault_state': 0, 'voltages': [230.0, 230.0, 230.0], 'currents': [0.0, 0.0, 0.0], 'powers': [0.0, 0.0, 0.0], 'power_factors': [0.0, 0.0, 0.0], 'imported': 108158.61628581406, 'power': 0.0, 'frequency': 50, 'exported': 0.0}, 'config': {'max_currents': [16, 16, 16], 'max_total_power': 11000}}
2023-01-11 11:11:34,465 - {control.data:297} - {DEBUG:MainThread} - counter21
{'set': {'reserved_surplus': 0, 'released_surplus': 0, 'raw_power_left': None, 'surplus_power_left': 0, 'raw_currents_left': [31.0, 31.0, 31.0]}, 'get': {'daily_exported': 0, 'daily_imported': 589.1914408374, 'voltages': [230.0, 230.0, 230.0], 'currents': [0.0, 0.0, 0.0], 'powers': [0.0, 0.0, 0.0], 'power_factors': [0.0, 0.0, 0.0], 'imported': 714701.086455916, 'exported': 0, 'power': 0.0, 'frequency': 50, 'fault_str': 'Kein Fehler.', 'fault_state': 0}, 'config': {'max_currents': [32, 32, 32], 'max_total_power': 22000}}
2023-01-11 11:11:34,465 - {control.data:274} - {DEBUG:MainThread} - counter_all_data
CounterAllData(get=Get(hierarchy=[{'id': 7, 'type': 'counter', 'children': [{'id': 23, 'type': 'inverter', 'children': []}, {'id': 22, 'type': 'inverter', 'children': []}, {'id': 14, 'type': 'counter', 'children': []}, {'id': 15, 'type': 'counter', 'children': []}, {'id': 13, 'type': 'cp', 'children': []}, {'id': 21, 'type': 'counter', 'children': []}]}]), set=Set(loadmanagement_active=False, home_consumption=482.93999999999994, invalid_home_consumption=0, daily_yield_home_consumption=5805.600000004))
2023-01-11 11:11:34,465 - {control.data:297} - {DEBUG:MainThread} - ct0
ChargeTemplateData(name='Standard-Ladeprofil-Vorlage', disable_after_unplug=False, prio=False, load_default=False, time_charging=TimeCharging(active=False, plans={}), chargemode=Chargemode(selected='pv_charging', pv_charging=PvCharging(min_soc_current=6, min_current=0, feed_in_limit=False, min_soc=0, max_soc=100), scheduled_charging=ScheduledCharging(plans={}), instant_charging=InstantCharging(current=16, limit=Limit(selected='none', amount=22, soc=50))))
2023-01-11 11:11:34,466 - {control.data:297} - {DEBUG:MainThread} - ct1
ChargeTemplateData(name='Tesla', disable_after_unplug=False, prio=False, load_default=False, time_charging=TimeCharging(active=True, plans={'3': TimeChargingPlan(active=True, frequency=Frequency(selected='daily', once=['2022-11-07', '2022-11-07'], weekly=[False, False, False, False, False, False, False]), time=['01:00', '06:30'], name='Nachts', current=16, limit=Limit(selected='soc', amount=1000, soc=75))}), chargemode=Chargemode(selected='pv_charging', pv_charging=PvCharging(min_soc_current=16, min_current=0, feed_in_limit=False, min_soc=0, max_soc=95), scheduled_charging=ScheduledCharging(plans={'1': ScheduledChargingPlan(active=True, frequency=Frequency(selected='daily', once=['2021-11-01', '2021-11-05'], weekly=[False, False, False, False, False, False, False]), current=14, name='Zielladen-Standard', limit=ScheduledLimit(selected='none', amount=1000, soc_limit=65, soc_scheduled=65), time='07:00')}), instant_charging=InstantCharging(current=16, limit=Limit(selected='soc', amount=20000, soc=75))))
2023-01-11 11:11:34,468 - {control.data:297} - {DEBUG:MainThread} - ev0
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='Standard-Fahrzeug-Vorlage', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=17, min_current=6, max_current_single_phase=32, battery_capacity=82, nominal_difference=2), et_num=0)), control_parameter=ControlParameter(required_current=0, required_currents=[0.0, 0.0, 0.0], phases=1, prio=False, timestamp_switch_on_off=None, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, submode='stop', chargemode='stop', used_amount_instant_charging=0, imported_at_plan_start=0, current_plan=None), charge_template=0, ev_template=0, name='Standard-Fahrzeug', tag_id=[], get=Get(soc=0, soc_timestamp='', force_soc_update=False, range=0, fault_state=0, fault_str=''))
2023-01-11 11:11:34,468 - {control.data:297} - {DEBUG:MainThread} - ev1
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='Standard-Fahrzeug-Vorlage', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=17, min_current=6, max_current_single_phase=32, battery_capacity=82, nominal_difference=2), et_num=0)), control_parameter=ControlParameter(required_current=0, required_currents=[0.0, 0.0, 0.0], phases=0, prio=False, timestamp_switch_on_off=None, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, submode=<Chargemode.STOP: 'stop'>, chargemode=<Chargemode.STOP: 'stop'>, used_amount_instant_charging=0, imported_at_plan_start=0, current_plan=None), charge_template=0, ev_template=0, name='Standard-Fahrzeug', tag_id=[], get=Get(soc=0, soc_timestamp='', force_soc_update=False, range=0, fault_state=0, fault_str=''))
2023-01-11 11:11:34,469 - {control.data:297} - {DEBUG:MainThread} - ev2
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='Standard-Fahrzeug-Vorlage', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=17, min_current=6, max_current_single_phase=32, battery_capacity=82, nominal_difference=2), et_num=0)), control_parameter=ControlParameter(required_current=0, required_currents=[0.0, 0.0, 0.0], phases=0, prio=False, timestamp_switch_on_off=None, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, submode=<Chargemode.STOP: 'stop'>, chargemode=<Chargemode.STOP: 'stop'>, used_amount_instant_charging=0, imported_at_plan_start=0, current_plan=None), charge_template=0, ev_template=0, name='Standard-Fahrzeug', tag_id=[], get=Get(soc=0, soc_timestamp='', force_soc_update=False, range=0, fault_state=0, fault_str=''))
2023-01-11 11:11:34,469 - {control.data:297} - {DEBUG:MainThread} - ev3
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='Tesla', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=20, min_current=6, max_current_single_phase=32, battery_capacity=75, nominal_difference=2), et_num=0)), control_parameter=ControlParameter(required_current=6, required_currents=[0.0, 6, 0.0], phases=1, prio=False, timestamp_switch_on_off='01/11/2023, 11:11:22', timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, submode=<Chargemode.PV_CHARGING: 'pv_charging'>, chargemode=<Chargemode.PV_CHARGING: 'pv_charging'>, used_amount_instant_charging=0, imported_at_plan_start=0, current_plan=None), charge_template=1, ev_template=1, name='Tesla', tag_id=[], get=Get(soc=64.0, soc_timestamp='01/11/2023, 11:09:53', force_soc_update=False, range=307.8506486, fault_state=0, fault_str='Kein Fehler.'))
2023-01-11 11:11:34,472 - {control.data:297} - {DEBUG:MainThread} - et0
EvTemplateData(name='Standard-Fahrzeug-Vorlage', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=17, min_current=6, max_current_single_phase=32, battery_capacity=82, nominal_difference=2)
2023-01-11 11:11:34,472 - {control.data:297} - {DEBUG:MainThread} - et1
EvTemplateData(name='Tesla', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=20, min_current=6, max_current_single_phase=32, battery_capacity=75, nominal_difference=2)
2023-01-11 11:11:34,473 - {control.data:278} - {DEBUG:MainThread} - general_data
GeneralData(chargemode_config=ChargemodeConfig(instant_charging=InstantCharging(phases_to_use=3), pv_charging=PvCharging(bat_prio=True, charging_power_reserve=200, control_range=[-230, 0], feed_in_yield=0, phase_switch_delay=8, phases_to_use=0, rundown_power=1000, rundown_soc=50, switch_off_delay=180, switch_off_soc=40, switch_off_threshold=350, switch_on_delay=40, switch_on_soc=60, switch_on_threshold=1300), scheduled_charging=ScheduledCharging(phases_to_use=0), standby=Standby(phases_to_use=1), stop=Stop(phases_to_use=1), time_charging=TimeCharging(phases_to_use=3), unbalanced_load_limit=18, unbalanced_load=False), control_interval=10, extern_display_mode='local', extern=False, external_buttons_hw=False, grid_protection_active=False, grid_protection_configured=False, grid_protection_random_stop=0, grid_protection_timestamp='', mqtt_bridge=False, price_kwh=0.23, range_unit='km', ripple_control_receiver=RippleControlReceiver(configured=False, r1_active=False, r2_active=False))
2023-01-11 11:11:34,473 - {control.data:297} - {DEBUG:MainThread} - graph
{'config': {'duration': 120}}
2023-01-11 11:11:34,473 - {control.data:280} - {DEBUG:MainThread} - optional_data
OptionalData(et=Et(active=False, config=EtConfig(max_price=0, provider={}), get=EtGet(price=0, price_list=[])), int_display=InternalDisplay(active=False, on_if_plugged_in=True, pin_active=False, pin_code='0000', standby=60, theme='cards'), led=Led(active=False), rfid=Rfid(active=False))
2023-01-11 11:11:34,473 - {control.data:297} - {DEBUG:MainThread} - all
{'get': {'power': -1105, 'exported': 43564425, 'daily_exported': 2141, 'monthly_exported': 0, 'yearly_exported': 0}, 'config': {'configured': True}}
2023-01-11 11:11:34,474 - {control.data:297} - {DEBUG:MainThread} - pv22
{'get': {'daily_exported': 978, 'monthly_exported': 0, 'yearly_exported': 0, 'fault_str': 'Kein Fehler.', 'fault_state': 0, 'power': -511, 'exported': 2533553, 'currents': [0.0, 0.0, 0.0]}, 'config': {'max_ac_out': 0}}
2023-01-11 11:11:34,474 - {control.data:297} - {DEBUG:MainThread} - pv23
{'get': {'daily_exported': 1163, 'monthly_exported': 0, 'yearly_exported': 0, 'fault_str': 'Kein Fehler.', 'fault_state': 0, 'power': -594, 'exported': 41030872, 'currents': [0.0, 0.0, 0.0]}, 'config': {'max_ac_out': 0}}
2023-01-11 11:11:34,474 - {control.data:297} - {DEBUG:MainThread} - system
{'update_in_progress': False, 'perform_update': False, 'boot_done': True, 'ip_address': '192.168.178.99', 'dataprotection_acknowledged': True, 'debug_level': 10, 'release_train': 'master', 'lastlivevaluesJson': {'timestamp': 1673431882, 'time': '11:11:22', 'grid': 0.692, 'counter14-power': 0.0, 'counter15-power': 0.0, 'counter21-power': 0.0, 'house-power': 0.48, 'charging-all': 1.329, 'pv-all': 1.117, 'cp13-power': 1.329, 'ev3-soc': 64.0}, 'version': '1.99.101.1', 'current_commit': '2023-01-09 13:58:35 +0100 [7b73a5c1]', 'current_missing_commits': [], 'current_branch': 'master', 'current_branch_commit': '2023-01-09 13:58:35 +0100 [7b73a5c1]', 'datastore_version': 6, 'module_update_completed': True}
2023-01-11 11:11:34,475 - {control.data:310} - {DEBUG:MainThread} - device4
{'name': 'EVU', 'type': 'sma_shm', 'id': 4, 'configuration': {}}
2023-01-11 11:11:34,476 - {control.data:312} - {DEBUG:MainThread} - component7
{'name': 'EnergyMeter 1.0', 'type': 'counter', 'id': 7, 'configuration': {'serials': None}}
2023-01-11 11:11:34,477 - {control.data:310} - {DEBUG:MainThread} - device8
{'name': 'Pumpe', 'type': 'json', 'id': 8, 'configuration': {'url': 'http://192.168.178.81/meter/0'}}
2023-01-11 11:11:34,477 - {control.data:312} - {DEBUG:MainThread} - component14
{'name': 'Pumpe', 'type': 'counter', 'id': 14, 'configuration': {'jq_power': '.power', 'jq_exported': None, 'jq_imported': None}}
2023-01-11 11:11:34,477 - {control.data:310} - {DEBUG:MainThread} - device9
{'name': 'Trockner', 'type': 'json', 'id': 9, 'configuration': {'url': 'http://192.168.178.209/meter/0'}}
2023-01-11 11:11:34,477 - {control.data:312} - {DEBUG:MainThread} - component15
{'name': 'Trockner', 'type': 'counter', 'id': 15, 'configuration': {'jq_power': '.power', 'jq_exported': None, 'jq_imported': None}}
2023-01-11 11:11:34,478 - {control.data:310} - {DEBUG:MainThread} - device14
{'name': 'openWB (1.9)', 'type': 'http', 'id': 14, 'configuration': {'url': 'http://192.168.178.51'}}
2023-01-11 11:11:34,478 - {control.data:312} - {DEBUG:MainThread} - component21
{'name': 'LP1 (Garage)', 'type': 'counter', 'id': 21, 'configuration': {'current_l1_path': '/openWB/ramdisk/lla1', 'current_l2_path': '/openWB/ramdisk/lla2', 'current_l3_path': '/openWB/ramdisk/lla3', 'exported_path': None, 'imported_path': '/openWB/ramdisk/llkwh', 'power_path': '/openWB/ramdisk/llaktuell'}}
2023-01-11 11:11:34,478 - {control.data:310} - {DEBUG:MainThread} - device15
{'name': 'WR Garage (neu)', 'type': 'sma_sunny_boy', 'id': 15, 'configuration': {'ip_address': '192.168.178.204'}}
2023-01-11 11:11:34,478 - {control.data:312} - {DEBUG:MainThread} - component22
{'name': 'WR Garage (neu)', 'type': 'inverter', 'id': 22, 'configuration': {'hybrid': False, 'version': 0}}
2023-01-11 11:11:34,479 - {control.data:310} - {DEBUG:MainThread} - device16
{'name': 'WR Haus (neu)', 'type': 'sma_sunny_boy', 'id': 16, 'configuration': {'ip_address': '192.168.178.201'}}
2023-01-11 11:11:34,479 - {control.data:312} - {DEBUG:MainThread} - component23
{'name': 'WR Haus (neu)', 'type': 'inverter', 'id': 23, 'configuration': {'hybrid': False, 'version': 0}}
2023-01-11 11:11:34,479 - {control.data:284} - {DEBUG:MainThread} - 

2023-01-11 11:11:34,479 - {control.algorithm.algorithm:26} - {DEBUG:MainThread} - # Algorithmus-Start
2023-01-11 11:11:34,480 - {control.algorithm.algorithm:72} - {DEBUG:MainThread} - Ladepunkt 13: Prüfen, ob Phasenumschaltung durchgeführt werden soll.
2023-01-11 11:11:34,480 - {control.ev:483} - {DEBUG:MainThread} - Genutzter Strom: 5.92A, Überschuss: -2086.9W, benötigte neue Leistung: -3220W
2023-01-11 11:11:34,482 - {control.chargepoint:349} - {INFO:MainThread} - LP 13: Ladevorgang wird nach Ablauf der Abschaltverzögerung 180s gestoppt.
2023-01-11 11:11:34,483 - {control.counter:313} - {DEBUG:MainThread} - LP13 Switch-Off-Threshold prüfen: EVU 706.9W, freigegebener Überschuss 1380.0W, Einspeisegrenze 0W
2023-01-11 11:11:34,486 - {control.counter:320} - {DEBUG:MainThread} - Relevante Leistung für Löschen der Abschaltschwelle: -673.1W, Schwelle: 350W
2023-01-11 11:11:34,492 - {control.chargepoint:349} - {INFO:MainThread} - LP 13: Abschaltschwelle während der Verzögerung überschritten.
2023-01-11 11:11:34,493 - {control.algorithm.algorithm:32} - {DEBUG:MainThread} - **Mindestrom setzen**
2023-01-11 11:11:34,517 - {control.algorithm.min_current:18} - {DEBUG:MainThread} - Mode-Tuple (<Chargemode.PV_CHARGING: 'pv_charging'>, <Chargemode.PV_CHARGING: 'pv_charging'>, False), Zähler 7
2023-01-11 11:11:34,519 - {control.counter:124} - {DEBUG:MainThread} - Zähler 7: [36.24, 29.009999999999998, 33.12]A verbleibende Ströme, 23242.06W verbleibende Leistung
2023-01-11 11:11:34,519 - {control.algorithm.common:87} - {DEBUG:MainThread} - LP13: Stromstärke 6A
2023-01-11 11:11:34,532 - {control.algorithm.algorithm:34} - {DEBUG:MainThread} - **Sollstrom setzen**
2023-01-11 11:11:34,547 - {control.counter:159} - {DEBUG:MainThread} - Überschuss zur PV-geführten Ladung: 0W
2023-01-11 11:11:34,548 - {control.counter:407} - {DEBUG:MainThread} - Zähler 7: Begrenzung der verbleibenden Leistung auf 0W
2023-01-11 11:11:34,548 - {control.counter:407} - {DEBUG:MainThread} - Zähler 14: Begrenzung der verbleibenden Leistung auf 0W
2023-01-11 11:11:34,548 - {control.counter:407} - {DEBUG:MainThread} - Zähler 15: Begrenzung der verbleibenden Leistung auf 0W
2023-01-11 11:11:34,549 - {control.counter:407} - {DEBUG:MainThread} - Zähler 21: Begrenzung der verbleibenden Leistung auf 0W
2023-01-11 11:11:34,549 - {control.algorithm.algorithm:45} - {DEBUG:MainThread} - **Keine Leistung für PV-geführtes Laden übrig.**
2023-01-11 11:11:34,550 - {control.process:22} - {DEBUG:MainThread} - # Ladung starten.
2023-01-11 11:11:34,551 - {control.chargelog:52} - {DEBUG:MainThread} - imported_since_mode_switch 153.32000000029802 counter 4319610.84
2023-01-11 11:11:34,556 - {control.process:106} - {INFO:MainThread} - LP13: set current 6 A
2023-01-11 11:11:34,561 - {soc.modules.common.component_context:24} - {DEBUG:cp13} - Update Komponente ['Ladepunkt']
2023-01-11 11:11:34,597 - {control.counter:141} - {DEBUG:MainThread} - 0W reservierte EVU-Leistung, 0.0W freigegebene EVU-Leistung
2023-01-11 11:11:34,857 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=update, args=(), kwargs={})
2023-01-11 11:11:39,882 - {modules.utils:17} - {ERROR:MainThread} - Modul-Daten wurden noch nicht vollständig empfangen. Timeout abgelaufen, fortsetzen der Regelung.
2023-01-11 11:11:44,897 - {modules.utils:17} - {ERROR:MainThread} - Modul-Daten wurden noch nicht vollständig empfangen. Timeout abgelaufen, fortsetzen der Regelung.
2023-01-11 11:11:45,928 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})
2023-01-11 11:11:45,929 - {root:61} - {INFO:MainThread} - # ***Start*** 

Re: Feedback Nightly 101.1

Verfasst: Mi Jan 11, 2023 11:50 am
von tobox
Ich habe mit der Version 2023-01-10 11:20:30 +0100 [d489ed16] das Problem, dass der Hausverbrauch nicht mehr richtig angezeigt wird und das Lastmanagement nicht mehr funktioniert. Trotz Überschreitung einiger Grenzen wurde nicht abgeregelt. Im Log sehe ich einige seltsame Sachen wie "NoneW" verbleibende Leistung. Will da mal jemand drauf schauen?
openwb.log
(40.94 KiB) 36-mal heruntergeladen

Re: Feedback Nightly 101.1

Verfasst: Mi Jan 11, 2023 1:21 pm
von Stivmaster
Ich habe Probleme mit der Nightly 2023-01-11 13:44:22 +0100 [330b9b1f].
Weder Sofortladen noch PV laden funktioniert. Das Problem mit PV laden habe ich auch bei der Beta 2, allerdings funktionierte da noch Sofortladen.
Bei der Nightly, sobald ich auf Sofortladen drücke, steht zwar da, Ladevorgang gestartet, was aber nicht der Fall ist.

Meine Konfiguration OpenWB Duo, zwei Ladepunkte verbunden. Kostal Pletnitcore mit KSEM anbindung. Zusätzlich noch ein Kostal Piko IQ.

Code: Alles auswählen

2023-01-11 14:15:21,030 - {control.algorithm.algorithm:26} - {DEBUG:MainThread} - # Algorithmus-Start
2023-01-11 14:15:21,031 - {control.algorithm.algorithm:32} - {DEBUG:MainThread} - **Mindestrom setzen**
2023-01-11 14:15:21,033 - {control.algorithm.min_current:18} - {DEBUG:MainThread} - Mode-Tuple (<Chargemode.INSTANT_CHARGING: 'instant_charging'>, <Chargemode.INSTANT_CHARGING: 'instant_charging'>, False), Zähler 9
2023-01-11 14:15:21,035 - {control.algorithm.algorithm:34} - {DEBUG:MainThread} - **Sollstrom setzen**
2023-01-11 14:15:21,037 - {control.algorithm.filter_chargepoints:118} - {DEBUG:MainThread} - Geordnete Ladepunkte [3]
2023-01-11 14:15:21,037 - {control.algorithm.filter_chargepoints:47} - {DEBUG:MainThread} - LP 3: Keine Zuteilung des Mindeststroms, daher keine weitere Berücksichtigung
2023-01-11 14:15:21,038 - {control.counter:159} - {DEBUG:MainThread} - Überschuss zur PV-geführten Ladung: 148.70000076293945W
2023-01-11 14:15:21,040 - {control.counter:407} - {DEBUG:MainThread} - Zähler 9: Begrenzung der verbleibenden Leistung auf 148.70000076293945W
2023-01-11 14:15:21,040 - {control.algorithm.algorithm:40} - {DEBUG:MainThread} - **PV-geführten Strom setzen**
2023-01-11 14:15:21,044 - {control.process:22} - {DEBUG:MainThread} - # Ladung starten.
2023-01-11 14:15:21,046 - {control.process:93} - {ERROR:MainThread} - Reservierte Leistung kann am Algorithmus-Ende nicht 0 sein.
2023-01-11 14:15:21,050 - {control.process:106} - {INFO:MainThread} - LP3: set current 0 A
2023-01-11 14:15:21,052 - {soc.modules.common.component_context:24} - {DEBUG:cp3} - Update Komponente ['Ladepunkt']
2023-01-11 14:15:21,055 - {soc.modules.common.component_context:24} - {DEBUG:cp12} - Update Komponente ['Ladepunkt']
2023-01-11 14:15:21,070 - {control.counter:141} - {DEBUG:MainThread} - 0W reservierte EVU-Leistung, 0W freigegebene EVU-Leistung
2023-01-11 14:15:21,273 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=update, args=(), kwargs={})
2023-01-11 14:15:30,377 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})
2023-01-11 14:15:30,378 - {root:61} - {INFO:MainThread} - # ***Start*** 
2023-01-11 14:15:30,438 - {soc.modules.common.component_context:51} - {DEBUG:device4} - Update Komponenten ['Kostal Plenticore Zähler', 'Kostal Plenticore Wechselrichter']
2023-01-11 14:15:30,439 - {soc.modules.common.component_context:51} - {DEBUG:device5} - Update Komponenten ['Kostal Piko IQ']
2023-01-11 14:15:30,440 - {soc.modules.common.component_context:24} - {DEBUG:cp3} - Update Komponente ['Ladepunkt']
2023-01-11 14:15:30,442 - {soc.modules.common.component_context:24} - {DEBUG:cp12} - Update Komponente ['Ladepunkt']
2023-01-11 14:15:30,463 - {soc.modules.common.store._api:26} - {DEBUG:device5} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-254, exported=39659824.0, dc_power=None)
2023-01-11 14:15:30,536 - {modules.common.simcount._simcount:38} - {DEBUG:device4} - Previous state: SimCounterState(timestamp=1673442920.813701, power=-33.70000076293945, imported=83895.57663008501, exported=26843.62632790279)
2023-01-11 14:15:30,537 - {modules.common.simcount._calculate:13} - {DEBUG:device4} - time passed: 0.00270078, power1: -33.7, power2: -22.5
2023-01-11 14:15:30,537 - {modules.common.simcount._calculate:29} - {DEBUG:device4} - power did not change sign. Total energy: -0.0758918
2023-01-11 14:15:30,537 - {modules.common.simcount._simcount:47} - {DEBUG:device4} - imported: 0 Wh, exported: 0.0758918 Wh, new state: SimCounterState(timestamp=1673442930.5364969, power=-22.5, imported=83895.57663008501, exported=26843.702219727867)
2023-01-11 14:15:30,538 - {soc.modules.common.store._api:26} - {DEBUG:device4} - Raw data CounterState(voltages=[229.08201599121094, 228.17501831054688, 228.8380126953125], powers=[114.20000457763672, -33.5, -113.0999984741211], currents=[1.0509999990463257, -0.9200000166893005, -0.8500000238418579], power_factors=[1.0, 1.0, 1.0], imported=83895.57663008501, exported=26843.702219727867, power=-22.5, frequency=50.00300216674805)
2023-01-11 14:15:30,551 - {soc.modules.common.store._api:26} - {DEBUG:device4} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-307, exported=44439628.0, dc_power=None)
2023-01-11 14:15:30,556 - {soc.modules.common.component_context:24} - {DEBUG:component10} - Update Komponente ['Kostal Plenticore Wechselrichter']
2023-01-11 14:15:30,560 - {soc.modules.common.component_context:24} - {DEBUG:component11} - Update Komponente ['Kostal Piko IQ']
2023-01-11 14:15:30,562 - {soc.modules.common.store._api:26} - {DEBUG:component10} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-307, exported=44439628.0, dc_power=None)
2023-01-11 14:15:30,563 - {soc.modules.common.component_context:24} - {DEBUG:cp3} - Update Komponente ['Ladepunkt']
2023-01-11 14:15:30,566 - {soc.modules.common.component_context:24} - {DEBUG:cp12} - Update Komponente ['Ladepunkt']
2023-01-11 14:15:30,570 - {soc.modules.common.store._api:26} - {DEBUG:component11} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-254, exported=39659824.0, dc_power=None)
2023-01-11 14:15:30,580 - {soc.modules.common.store._api:30} - {DEBUG:component11} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-254, exported=39659824.0, dc_power=None)
2023-01-11 14:15:30,574 - {soc.modules.common.store._api:30} - {DEBUG:component10} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-307, exported=44439628.0, dc_power=None)
2023-01-11 14:15:30,619 - {soc.modules.common.component_context:24} - {DEBUG:component9} - Update Komponente ['Kostal Plenticore Zähler']
2023-01-11 14:15:30,620 - {soc.modules.common.store._api:30} - {DEBUG:component9} - Saving CounterState(voltages=[229.08201599121094, 228.17501831054688, 228.8380126953125], powers=[114.20000457763672, -33.5, -113.0999984741211], currents=[1.0509999990463257, -0.9200000166893005, -0.8500000238418579], power_factors=[1.0, 1.0, 1.0], imported=83895.57663008501, exported=26843.702219727867, power=-22.5, frequency=50.00300216674805)
2023-01-11 14:15:30,712 - {control.counter:87} - {DEBUG:MainThread} - Verbleibende Ströme an Zähler 9: [29.95, 31.92, 31.85]
2023-01-11 14:15:30,712 - {control.counter:116} - {DEBUG:MainThread} - Verbleibende Leistung an Zähler 9: 22022.5
2023-01-11 14:15:30,715 - {control.chargepoint:696} - {DEBUG:MainThread} - EV-Phasenzahl beschränkt die nutzbaren Phasen auf 3
2023-01-11 14:15:30,716 - {control.chargepoint:687} - {DEBUG:MainThread} - Phasenzahl Lademodus: 3
2023-01-11 14:15:30,717 - {control.ev:618} - {DEBUG:MainThread} - Keine Ladung, da kein Zeitfenster für Zeitladen aktiv ist.
2023-01-11 14:15:30,717 - {control.chargepoint:709} - {INFO:MainThread} - Phasenumschaltung an Ladepunkt 3 nicht möglich, da bei EV2 nach Ladestart nicht mehr umgeschaltet werden darf.
2023-01-11 14:15:30,720 - {control.ev:364} - {DEBUG:MainThread} - Änderung des Lademodus :False
2023-01-11 14:15:30,722 - {control.chargepoint:854} - {ERROR:MainThread} - Reservierte Leistung kann nicht 0 sein.
2023-01-11 14:15:30,724 - {control.chargepoint:856} - {DEBUG:MainThread} - LP 3, EV: Zoe (EV-Nr.2): Theoretisch benötigter Strom 16A, Lademodus instant_charging, Submodus: Chargemode.INSTANT_CHARGING, Phasen: 0, Priorität: False, max. Ist-Strom: 0.0
2023-01-11 14:15:30,728 - {control.chargepoint:696} - {DEBUG:MainThread} - EV-Phasenzahl beschränkt die nutzbaren Phasen auf 3
2023-01-11 14:15:30,730 - {control.chargepoint:687} - {DEBUG:MainThread} - Phasenzahl Lademodus: 3
2023-01-11 14:15:30,731 - {control.chargepoint:709} - {INFO:MainThread} - Phasenumschaltung an Ladepunkt 12 nicht möglich, da bei EV1 nach Ladestart nicht mehr umgeschaltet werden darf.
2023-01-11 14:15:30,734 - {control.ev:364} - {DEBUG:MainThread} - Änderung des Lademodus :False
2023-01-11 14:15:30,736 - {control.chargepoint:846} - {DEBUG:MainThread} - LP 12, EV: Tesla (EV-Nr.1): Lademodus stop, Submodus: Chargemode.STOP
2023-01-11 14:15:30,737 - {control.chargepoint:349} - {INFO:MainThread} - LP 12: Keine Ladung, da der Lademodus Stop aktiv ist.
2023-01-11 14:15:30,743 - {control.data:297} - {DEBUG:MainThread} - all
{'get': {'power': 0}, 'config': {'configured': False}, 'set': {'charging_power_left': 0, 'switch_on_soc_reached': False}}
2023-01-11 14:15:30,744 - {control.data:270} - {DEBUG:MainThread} - cp_all_data
AllChargepointData(get=AllGet(daily_imported=19720.71, daily_exported=0, power=0, imported=6143640.87, exported=0))
2023-01-11 14:15:30,745 - {control.data:297} - {DEBUG:MainThread} - cp3
ChargepointData(get=Get(charge_state=False, connected_vehicle=ConnectedVehicle(config=ConnectedConfig(average_consumption=17, charge_template=0, chargemode='stop', current_plan=0, ev_template=0, priority=False), info=ConnectedInfo(id=0, name='Ladepunkt'), soc=44.0), currents=[0.0, 0.0, 0.0], daily_imported=9671.88, daily_exported=0, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=3140615.97, phases_in_use=0, plug_state=True, power=0, rfid_timestamp=None, rfid=None, state_str=None, voltages=[228.03, 228.95, 228.8]), set=Set(change_ev_permitted=[True, ''], charging_ev=2, charging_ev_prev=2, current=0, energy_to_charge=0, loadmanagement_available=True, log=Log(chargemode_log_entry='_', imported_at_mode_switch=3140615.97, imported_at_plugtime=3132769.04, imported_since_mode_switch=0, imported_since_plugged=7846.930000000168, range_charged=0, time_charged='00:00', timestamp_start_charging=None), manual_lock=False, phases_to_use=3, plug_state_prev=True, plug_time='01/11/2023, 12:45:20', required_power=0.0, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0x71d12580>), config=Config(connection_module={'type': 'external_openwb', 'name': 'Externe openWB', 'configuration': {'ip_address': '192.168.1.165', 'duo_num': 2}}, power_module={}, ev=2, name='Zoe', type='external_openwb', template=0, connected_phases=3, phase_1=1, auto_phase_switch_hw=False, control_pilot_interruption_hw=False, id=3))
2023-01-11 14:15:30,747 - {control.data:297} - {DEBUG:MainThread} - cp12
ChargepointData(get=Get(charge_state=False, connected_vehicle=ConnectedVehicle(config=ConnectedConfig(average_consumption=17, charge_template=0, chargemode='stop', current_plan=0, ev_template=0, priority=False), info=ConnectedInfo(id=0, name='Ladepunkt'), soc=49.0), currents=[0.0, 0.0, 0.0], daily_imported=3003024.9, daily_exported=0, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=3003024.9, phases_in_use=0, plug_state=True, power=0, rfid_timestamp=None, rfid=None, state_str='Keine Ladung, da der Lademodus Stop aktiv ist.', voltages=[228.99, 228.67, 228.13]), set=Set(change_ev_permitted=[True, ''], charging_ev=-1, charging_ev_prev=1, current=0, energy_to_charge=0, loadmanagement_available=True, log=Log(chargemode_log_entry='_', imported_at_mode_switch=3003024.9, imported_at_plugtime=3003010.99, imported_since_mode_switch=0, imported_since_plugged=13.90999999968335, range_charged=0, time_charged='00:00', timestamp_start_charging=None), manual_lock=False, phases_to_use=3, plug_state_prev=True, plug_time='01/11/2023, 12:08:20', required_power=0.0, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0x71d12bb0>), config=Config(connection_module={'type': 'external_openwb', 'name': 'Externe openWB', 'configuration': {'ip_address': '192.168.1.165', 'duo_num': 1}}, power_module={}, ev=1, name='Tesla', type='external_openwb', template=0, connected_phases=3, phase_1=1, auto_phase_switch_hw=False, control_pilot_interruption_hw=False, id=12))
2023-01-11 14:15:30,748 - {control.data:297} - {DEBUG:MainThread} - cpt0
CpTemplateData(autolock=Autolock(active=False, plans={}, wait_for_charging_end=False), id=0, max_current_multi_phases=32, max_current_single_phase=32, name='Standard Ladepunkt-Vorlage', rfid_enabling=False, valid_tags=[])
2023-01-11 14:15:30,748 - {control.data:297} - {DEBUG:MainThread} - counter9
{'set': {'reserved_surplus': 0, 'released_surplus': 0, 'raw_power_left': 22022.5, 'surplus_power_left': 0, 'consumption_left': 22000, 'raw_currents_left': [29.95, 31.92, 31.85]}, 'get': {'daily_exported': 9626.95054522718, 'daily_imported': 23354.20875707863, 'fault_str': 'Kein Fehler.', 'fault_state': 0, 'voltages': [229.08, 228.18, 228.84], 'currents': [1.05, -0.92, -0.85], 'powers': [114.2, -33.5, -113.1], 'power_factors': [1.0, 1.0, 1.0], 'imported': 83895.57663008501, 'exported': 26843.702219727867, 'power': -22.5, 'frequency': 50.00300216674805}, 'config': {'max_currents': [32, 32, 32], 'max_total_power': 22000}}
2023-01-11 14:15:30,748 - {control.data:274} - {DEBUG:MainThread} - counter_all_data
CounterAllData(get=Get(hierarchy=[{'id': 9, 'type': 'counter', 'children': [{'id': 10, 'type': 'inverter', 'children': []}, {'id': 11, 'type': 'inverter', 'children': []}, {'id': 3, 'type': 'cp', 'children': []}, {'id': 12, 'type': 'cp', 'children': []}]}]), set=Set(loadmanagement_active=False, home_consumption=538.5, invalid_home_consumption=0, daily_yield_home_consumption=7822.548211851452))
2023-01-11 14:15:30,749 - {control.data:297} - {DEBUG:MainThread} - ct0
ChargeTemplateData(name='Standard-Ladeprofil-Vorlage', disable_after_unplug=False, prio=False, load_default=False, time_charging=TimeCharging(active=False, plans={}), chargemode=Chargemode(selected='pv_charging', pv_charging=PvCharging(min_soc_current=10, min_current=0, feed_in_limit=False, min_soc=0, max_soc=100), scheduled_charging=ScheduledCharging(plans={}), instant_charging=InstantCharging(current=10, limit=Limit(selected='none', amount=1000, soc=50))))
2023-01-11 14:15:30,751 - {control.data:297} - {DEBUG:MainThread} - ct1
ChargeTemplateData(name='Zoe', disable_after_unplug=False, prio=False, load_default=False, time_charging=TimeCharging(active=True, plans={'0': TimeChargingPlan(active=True, frequency=Frequency(selected='weekly', once=['2021-11-01', '2021-11-05'], weekly=[True, True, True, True, True, False, False]), time=['07:00', '07:30'], name='Zoe Morgens', current=8, limit=Limit(selected='none', amount=1000, soc=5)), '1': TimeChargingPlan(active=True, frequency=Frequency(selected='daily', once=['2021-11-01', '2021-11-05'], weekly=[False, False, False, False, False, False, False]), time=['20:00', '23:59'], name='Zoe Min-SOC', current=16, limit=Limit(selected='soc', amount=1000, soc=50))}), chargemode=Chargemode(selected='instant_charging', pv_charging=PvCharging(min_soc_current=6, min_current=0, feed_in_limit=False, min_soc=0, max_soc=100), scheduled_charging=ScheduledCharging(plans={}), instant_charging=InstantCharging(current=16, limit=Limit(selected='soc', amount=1000, soc=80))))
2023-01-11 14:15:30,752 - {control.data:297} - {DEBUG:MainThread} - ct2
ChargeTemplateData(name='Tesla', disable_after_unplug=False, prio=False, load_default=False, time_charging=TimeCharging(active=False, plans={'2': TimeChargingPlan(active=True, frequency=Frequency(selected='daily', once=['2021-11-01', '2021-11-05'], weekly=[False, False, False, False, False, False, False]), time=['00:00', '04:00'], name='Tesla Min SOC', current=16, limit=Limit(selected='soc', amount=1000, soc=50))}), chargemode=Chargemode(selected='stop', pv_charging=PvCharging(min_soc_current=6, min_current=0, feed_in_limit=False, min_soc=0, max_soc=100), scheduled_charging=ScheduledCharging(plans={}), instant_charging=InstantCharging(current=16, limit=Limit(selected='none', amount=1000, soc=100))))
2023-01-11 14:15:30,752 - {control.data:297} - {DEBUG:MainThread} - ev0
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='Standard-Fahrzeug-Vorlage', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=True, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=17, min_current=6, max_current_single_phase=32, battery_capacity=82, nominal_difference=2), et_num=0)), control_parameter=ControlParameter(required_current=6, required_currents=[0.0, 0.0, 0.0], phases=0, prio=False, timestamp_switch_on_off=None, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, submode='pv_charging', chargemode='pv_charging', used_amount_instant_charging=0, imported_at_plan_start=0, current_plan=None), charge_template=0, ev_template=0, name='Standard-Fahrzeug', tag_id=[], get=Get(soc=95.0, soc_timestamp='01/06/2023, 16:55:21', force_soc_update=False, range=221.0, fault_state=0, fault_str='Kein Fehler.'))
2023-01-11 14:15:30,753 - {control.data:297} - {DEBUG:MainThread} - ev1
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='Tesla', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=True, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=17, min_current=6, max_current_single_phase=32, battery_capacity=82, nominal_difference=2), et_num=0)), control_parameter=ControlParameter(required_current=0, required_currents=[0.0, 0.0, 0.0], phases=0, prio=False, timestamp_switch_on_off='01/11/2023, 12:43:10', timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, submode=<Chargemode.STOP: 'stop'>, chargemode=<Chargemode.STOP: 'stop'>, used_amount_instant_charging=0, imported_at_plan_start=0, current_plan='Tesla Min SOC'), charge_template=2, ev_template=2, name='Tesla', tag_id=[], get=Get(soc=49.0, soc_timestamp='01/11/2023, 14:09:41', force_soc_update=False, range=270.4817738, fault_state=0, fault_str='Kein Fehler.'))
2023-01-11 14:15:30,753 - {control.data:297} - {DEBUG:MainThread} - ev2
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='Zoe', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=True, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=17, min_current=8, max_current_single_phase=32, battery_capacity=50, nominal_difference=2), et_num=0)), control_parameter=ControlParameter(required_current=16, required_currents=[0.0, 0.0, 0.0], phases=0, prio=False, timestamp_switch_on_off='01/11/2023, 14:07:01', timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, submode=<Chargemode.INSTANT_CHARGING: 'instant_charging'>, chargemode=<Chargemode.INSTANT_CHARGING: 'instant_charging'>, used_amount_instant_charging=0, imported_at_plan_start=0, current_plan=None), charge_template=1, ev_template=1, name='Zoe', tag_id=[], get=Get(soc=44.0, soc_timestamp='01/11/2023, 14:09:41', force_soc_update=False, range=107.0, fault_state=0, fault_str='Kein Fehler.'))
2023-01-11 14:15:30,754 - {control.data:297} - {DEBUG:MainThread} - et0
EvTemplateData(name='Standard-Fahrzeug-Vorlage', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=True, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=17, min_current=6, max_current_single_phase=32, battery_capacity=82, nominal_difference=2)
2023-01-11 14:15:30,754 - {control.data:297} - {DEBUG:MainThread} - et1
EvTemplateData(name='Zoe', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=True, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=17, min_current=8, max_current_single_phase=32, battery_capacity=50, nominal_difference=2)
2023-01-11 14:15:30,754 - {control.data:297} - {DEBUG:MainThread} - et2
EvTemplateData(name='Tesla', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=True, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=17, min_current=6, max_current_single_phase=32, battery_capacity=82, nominal_difference=2)
2023-01-11 14:15:30,755 - {control.data:278} - {DEBUG:MainThread} - general_data
GeneralData(chargemode_config=ChargemodeConfig(instant_charging=InstantCharging(phases_to_use=3), pv_charging=PvCharging(bat_prio=False, charging_power_reserve=200, control_range=[-230, 0], feed_in_yield=20000, phase_switch_delay=1, phases_to_use=3, rundown_power=1000, rundown_soc=50, switch_off_delay=60, switch_off_soc=40, switch_off_threshold=50, switch_on_delay=5, switch_on_soc=60, switch_on_threshold=100), scheduled_charging=ScheduledCharging(phases_to_use=3), standby=Standby(phases_to_use=3), stop=Stop(phases_to_use=1), time_charging=TimeCharging(phases_to_use=3), unbalanced_load_limit=18, unbalanced_load=False), control_interval=10, extern_display_mode='local', extern=False, external_buttons_hw=False, grid_protection_active=False, grid_protection_configured=False, grid_protection_random_stop=0, grid_protection_timestamp='', mqtt_bridge=False, price_kwh=0.3, range_unit='km', ripple_control_receiver=RippleControlReceiver(configured=False, r1_active=False, r2_active=False))
2023-01-11 14:15:30,755 - {control.data:297} - {DEBUG:MainThread} - graph
{'config': {'duration': 120}}
2023-01-11 14:15:30,755 - {control.data:280} - {DEBUG:MainThread} - optional_data
OptionalData(et=Et(active=False, config=EtConfig(max_price=0, provider={}), get=EtGet(price=0, price_list=[])), int_display=InternalDisplay(active=False, on_if_plugged_in=True, pin_active=False, pin_code='0000', standby=60, theme='cards'), led=Led(active=False), rfid=Rfid(active=False))
2023-01-11 14:15:30,755 - {control.data:297} - {DEBUG:MainThread} - all
{'get': {'power': -561, 'exported': 84099416.0, 'daily_exported': 13816.0, 'monthly_exported': 0, 'yearly_exported': 0}, 'config': {'configured': True}}
2023-01-11 14:15:30,756 - {control.data:297} - {DEBUG:MainThread} - pv10
{'get': {'daily_exported': 7420.0, 'monthly_exported': 0, 'yearly_exported': 0, 'fault_str': 'Kein Fehler.', 'fault_state': 0, 'power': -307, 'exported': 44439628.0, 'currents': [0.0, 0.0, 0.0]}, 'config': {'max_ac_out': 10000}}
2023-01-11 14:15:30,758 - {control.data:297} - {DEBUG:MainThread} - pv11
{'get': {'daily_exported': 6396.0, 'monthly_exported': 0, 'yearly_exported': 0, 'fault_str': 'Kein Fehler.', 'fault_state': 0, 'power': -254, 'exported': 39659824.0, 'currents': [0.0, 0.0, 0.0]}, 'config': {'max_ac_out': 10000}}
2023-01-11 14:15:30,759 - {control.data:297} - {DEBUG:MainThread} - system
{'update_in_progress': False, 'perform_update': False, 'boot_done': True, 'ip_address': '192.168.1.191', 'current_commit': '2023-01-11 13:44:22 +0100 [330b9b1f]', 'current_branch': 'master', 'current_branch_commit': '2023-01-11 13:44:22 +0100 [330b9b1f]', 'current_missing_commits': [], 'dataprotection_acknowledged': True, 'debug_level': 10, 'release_train': 'master', 'version': '1.99.101.1', 'datastore_version': 6, 'lastlivevaluesJson': {'timestamp': 1673442921, 'time': '14:15:21', 'grid': -0.034, 'house-power': 0.538, 'charging-all': 0.0, 'pv-all': 0.572, 'cp3-power': 0.0, 'cp12-power': 0.0, 'ev1-soc': 49.0, 'ev2-soc': 44.0}, 'module_update_completed': True}
2023-01-11 14:15:30,759 - {control.data:310} - {DEBUG:MainThread} - device4
{'name': 'WR Scheune', 'type': 'kostal_plenticore', 'id': 4, 'configuration': {'ip_address': '192.168.1.216'}}
2023-01-11 14:15:30,760 - {control.data:312} - {DEBUG:MainThread} - component9
{'name': 'Kostal Plenticore Zähler', 'type': 'counter', 'id': 9, 'configuration': {}}
2023-01-11 14:15:30,760 - {control.data:312} - {DEBUG:MainThread} - component10
{'name': 'Kostal Plenticore Wechselrichter', 'type': 'inverter', 'id': 10, 'configuration': {}}
2023-01-11 14:15:30,760 - {control.data:310} - {DEBUG:MainThread} - device5
{'name': 'WR Hausdach', 'type': 'kostal_plenticore', 'id': 5, 'configuration': {'ip_address': '192.168.1.215'}}
2023-01-11 14:15:30,760 - {control.data:312} - {DEBUG:MainThread} - component11
{'name': 'Kostal Piko IQ', 'type': 'inverter', 'id': 11, 'configuration': {}}
2023-01-11 14:15:30,760 - {control.data:284} - {DEBUG:MainThread} - 
Zoe_Ladeproblem.JPG
WB_Status.JPG
Lastmanagment.JPG

Mach ich hier was falsch? Ich hab schon einiges rumprobiert, komm aber auf nichts.

Re: Feedback Nightly 101.1

Verfasst: Mi Jan 11, 2023 2:02 pm
von tobox
tobox hat geschrieben: Mi Jan 11, 2023 11:50 am Ich habe mit der Version 2023-01-10 11:20:30 +0100 [d489ed16] das Problem, dass der Hausverbrauch nicht mehr richtig angezeigt wird und das Lastmanagement nicht mehr funktioniert. Trotz Überschreitung einiger Grenzen wurde nicht abgeregelt. Im Log sehe ich einige seltsame Sachen wie "NoneW" verbleibende Leistung. Will da mal jemand drauf schauen?

openwb.log
Nach einem Neustart des EVU Flex Kit scheint erstmal wieder alles zu laufen. Trotzdem seltsam und beunruhigend. Mit diesem Fehler hätten schnell die Hauptsicherungen rausfliegen können. Ich denke, wenn OpenWB keine gültigen Daten vom Zähler bekommt, muss sie abschalten. Ob jedoch in diesem Fehlerfall vielleicht ein alter, konstanter Wert als aktueller Wert geliefert wurde, kann ich nicht beurteilen.

Re: Feedback Nightly 101.1

Verfasst: Mi Jan 11, 2023 2:36 pm
von LenaK
Bitte update machen, HV und Zähler sind gefixt.