Wiederkehrende Fehlermeldung im Log

Fragen zur Nutzung, Features, usw..
zut
Beiträge: 567
Registriert: Di Feb 23, 2021 9:34 pm
Has thanked: 3 times
Been thanked: 4 times

Re: Wiederkehrende Fehlermeldung im Log

Beitrag von zut »

So hatte ich es bei mir auch beobachtet, und das Log scheint mir das auch zu sagen. Es taucht dann halt der Fehler oben auf, und danach ist kurz Pause. Ich werde nochmal messen, sobald es geht.
ID4U
Beiträge: 399
Registriert: Do Okt 13, 2022 4:26 pm
Been thanked: 3 times

Re: Wiederkehrende Fehlermeldung im Log

Beitrag von ID4U »

ID4U hat geschrieben: Do Jul 27, 2023 11:34 am Ich habe die Mindestzeit jetzt testweise von 40 auf 90 Sekunden heraufgesetzt. Werde das dann mal beobachten.
Damit tritt das Problem immer noch auf, der erst Peak dauert nun entsprechend länger:

1p3p.jpg
1p3p.jpg (20.72 KiB) 789 mal betrachtet
LenaK
Beiträge: 1253
Registriert: Fr Jan 22, 2021 6:40 am
Been thanked: 6 times

Re: Wiederkehrende Fehlermeldung im Log

Beitrag von LenaK »

Ohne Log kann ich das Problem leider nicht genauer analysieren. Bitte das Debuglevel auf Details stellen und das Main-Log unter System->Fehlersuche posten, direkt nachdem die zweite Umschaltung statt gefunden hat. Du kannst die Umschaltung ja auch im Sofortladen veranlassen.
zut
Beiträge: 567
Registriert: Di Feb 23, 2021 9:34 pm
Has thanked: 3 times
Been thanked: 4 times

Re: Wiederkehrende Fehlermeldung im Log

Beitrag von zut »

Eben mal mit 12A Sofortladen mit manueller Umschaltung probiert, hierbei kein Fehler. Muß den Akku etwas entlehren. Werde dann zunächst das Umschalten mit 16A Sofortladen probieren, dann mit PV-Laden, falls der Fehler nicht auftritt.
zut
Beiträge: 567
Registriert: Di Feb 23, 2021 9:34 pm
Has thanked: 3 times
Been thanked: 4 times

Re: Wiederkehrende Fehlermeldung im Log

Beitrag von zut »

Beim manuellen Umschalten bei 16A beim Sofortladen trat der Fehler ebenfalls nicht auf.
zut
Beiträge: 567
Registriert: Di Feb 23, 2021 9:34 pm
Has thanked: 3 times
Been thanked: 4 times

Re: Wiederkehrende Fehlermeldung im Log

Beitrag von zut »

Hier der aus meinr Sicht relevante Auszug aus dem Mainlog. Der relevante Punkt ist 14:13:51, wo der zweite Phasigkeitswechsel gestartet wird, obwohl davor schon zweiphasig geladen wurde.
Beginn Umschaltung 14:13:51
Beginn Umschaltung 14:13:51
Doppelumschaltung.png (17.3 KiB) 720 mal betrachtet

Code: Alles auswählen

2023-07-29 14:13:41,128 - {control.data:337} - {INFO:MainThread} - ct1
ChargeTemplateData(name='Mein PV-Laden', disable_after_unplug=False, prio=True, load_default=False, time_charging=TimeCharging(active=False, plans={'0': TimeChargingPlan(active=False, frequency=Frequency(selected='daily', once=['2021-11-01', '2021-11-05'], weekly=[False, False, False, False, False, False, False]), time=['11:00', '16:00'], name='Zeitladen-Standard', current=16, limit=Limit(selected='soc', amount=1000, soc=80))}), chargemode=Chargemode(selected='pv_charging', pv_charging=PvCharging(min_soc_current=10, min_current=0, feed_in_limit=False, min_soc=40, max_soc=88), scheduled_charging=ScheduledCharging(plans={'0': ScheduledChargingPlan(active=False, frequency=Frequency(selected='once', once='2023-07-24', weekly=[False, False, False, False, False, False, False]), current=14, name='Zielladen-Standard', limit=ScheduledLimit(selected='soc', amount=1000, soc_limit=100, soc_scheduled=50), time='07:00')}), instant_charging=InstantCharging(current=16, limit=Limit(selected='soc', amount=1000, soc=80))))
2023-07-29 14:13:41,135 - {control.data:337} - {INFO: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=17000, min_current=6, max_current_single_phase=32, battery_capacity=82000, nominal_difference=1, keep_charge_active_duration=40), et_num=0), soc_error_counter=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='stop', chargemode='stop', used_amount_instant_charging=0, imported_at_plan_start=0, current_plan=None, state=0), 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-07-29 14:13:41,138 - {control.data:337} - {INFO:MainThread} - ev2
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='e-up-Vorlage', max_current_multi_phases=16, max_phases=2, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=14000, min_current=6, max_current_single_phase=16, battery_capacity=32000, nominal_difference=1, keep_charge_active_duration=120), et_num=0), soc_error_counter=0), control_parameter=ControlParameter(required_current=6, required_currents=[6, 6, 0.0], phases=2, prio=True, timestamp_switch_on_off=None, 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, state=3), charge_template=1, ev_template=2, name='Nulli', tag_id=[], get=Get(soc=81, soc_timestamp='07/29/2023, 14:06:51', force_soc_update=False, range=231.0, fault_state=0, fault_str='Kein Fehler.'))
2023-07-29 14:13:41,140 - {control.data:337} - {INFO:MainThread} - ev3
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=17000, min_current=6, max_current_single_phase=32, battery_capacity=82000, nominal_difference=1, keep_charge_active_duration=40), et_num=0), soc_error_counter=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, state=0), 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='Kein Fehler.'))
2023-07-29 14:13:41,142 - {control.data:337} - {INFO: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=17000, min_current=6, max_current_single_phase=16, battery_capacity=32000, nominal_difference=1, keep_charge_active_duration=40)
2023-07-29 14:13:41,145 - {control.data:337} - {INFO:MainThread} - et2
EvTemplateData(name='e-up-Vorlage', max_current_multi_phases=10, max_phases=2, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=14000, min_current=6, max_current_single_phase=10, battery_capacity=32000, nominal_difference=1, keep_charge_active_duration=40)
2023-07-29 14:13:41,150 - {control.data:316} - {INFO: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=[0, 230], feed_in_yield=0, phase_switch_delay=1, phases_to_use=0, rundown_power=1000, rundown_soc=75, switch_off_delay=360, switch_off_soc=0, switch_off_threshold=6000, switch_on_delay=60, switch_on_soc=0, switch_on_threshold=1000), scheduled_charging=ScheduledCharging(phases_to_use=0), 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.429, range_unit='km', ripple_control_receiver=RippleControlReceiver(configured=False, r1_active=False, r2_active=False))
2023-07-29 14:13:41,152 - {control.data:317} - {INFO:MainThread} - general_data-display
local
2023-07-29 14:13:41,154 - {control.data:318} - {INFO:MainThread} - graph_data
GraphData(config=Config(duration=120))
2023-07-29 14:13:41,155 - {control.data:319} - {INFO:MainThread} - optional_data
OptionalData(et=Et(active=False, config=EtConfig(max_price=0, provider={}), get=EtGet(price=0, price_list=[])), int_display=InternalDisplay(active=True, on_if_plugged_in=True, pin_active=False, pin_code='0000', standby=60, theme=<modules.display_themes.cards.config.CardsDisplayTheme object at 0x6e750418>), led=Led(active=False), rfid=Rfid(active=False))
2023-07-29 14:13:41,164 - {control.data:337} - {INFO:MainThread} - pv1
PvData(config=Config(max_ac_out=5500000), get=Get(currents=[0.0, 0.0, 0.0], daily_exported=9474.0, monthly_exported=0, yearly_exported=0, exported=12170816.0, fault_state=0, fault_str='Kein Fehler.', power=-8404))
2023-07-29 14:13:41,166 - {control.data:321} - {INFO:MainThread} - pv_all_data
PvAllData(config=Config(configured=True), get=Get(daily_exported=9474.0, monthly_exported=0, yearly_exported=0, exported=12170816.0, power=-8404))
2023-07-29 14:13:41,168 - {control.data:337} - {INFO:MainThread} - system
{'update_in_progress': False, 'perform_update': False, 'ip_address': '192.168.1.102', 'current_commit': '2023-07-17 11:46:58 +0200 [da6031826]', 'current_branch': 'Release', 'current_branch_commit': '2023-07-17 11:46:58 +0200 [da6031826]', 'current_missing_commits': [], 'boot_done': True, 'datastore_version': 16, 'dataprotection_acknowledged': True, 'usage_terms_acknowledged': True, 'debug_level': 10, 'release_train': 'master', 'version': '2.0.0', 'lastlivevaluesJson': {'timestamp': 1690632811, 'time': '14:13:31', 'grid': 0.359, 'house-power': 0.297, 'charging-all': 5.609, 'pv-all': 8.397, 'cp3-power': 5.609, 'ev2-soc': 81, 'bat-all-power': 2.85, 'bat-all-soc': 86}, 'module_update_completed': True}
2023-07-29 14:13:41,172 - {control.data:350} - {INFO:MainThread} - device0
{'name': 'Kostal Plenticore', 'type': 'kostal_plenticore', 'id': 0, 'configuration': {'ip_address': '192.168.1.103'}}
2023-07-29 14:13:41,174 - {control.data:352} - {INFO:MainThread} - component0
{'name': 'Kostal Plenticore Zähler', 'type': 'counter', 'id': 0, 'configuration': {}}
2023-07-29 14:13:41,177 - {control.data:352} - {INFO:MainThread} - component1
{'name': 'Ludwig 14', 'type': 'inverter', 'id': 1, 'configuration': {}}
2023-07-29 14:13:41,180 - {control.data:352} - {INFO:MainThread} - component2
{'name': 'Kostal Plenticore Speicher', 'type': 'bat', 'id': 2, 'configuration': {}}
2023-07-29 14:13:41,183 - {control.data:324} - {INFO:MainThread} - 

2023-07-29 14:13:41,185 - {control.algorithm.algorithm:25} - {INFO:MainThread} - # Algorithmus
2023-07-29 14:13:41,187 - {control.algorithm.algorithm:31} - {INFO:MainThread} - **Mindestrom setzen**
2023-07-29 14:13:41,190 - {control.algorithm.min_current:18} - {INFO:MainThread} - Mode-Tuple Chargemode.PV_CHARGING - Chargemode.PV_CHARGING - True, Zähler 0
2023-07-29 14:13:41,192 - {control.counter:169} - {DEBUG:MainThread} - Zähler 0: [23.79, 23.78, 28.380000000000003]A verbleibende Ströme, 32500.29998168945W verbleibende Leistung
2023-07-29 14:13:41,193 - {control.algorithm.common:87} - {INFO:MainThread} - LP3: Stromstärke 6A
2023-07-29 14:13:41,196 - {control.algorithm.algorithm:33} - {INFO:MainThread} - **Sollstrom setzen**
2023-07-29 14:13:41,202 - {control.counter:211} - {INFO:MainThread} - Überschuss zur PV-geführten Ladung: 3615.2999816894517W
2023-07-29 14:13:41,203 - {control.counter:470} - {DEBUG:MainThread} - Zähler 0: Begrenzung der verbleibenden Leistung auf 3615.2999816894517W
2023-07-29 14:13:41,204 - {control.algorithm.algorithm:39} - {INFO:MainThread} - **PV-geführten Strom setzen**
2023-07-29 14:13:41,208 - {control.algorithm.filter_chargepoints:118} - {DEBUG:MainThread} - Geordnete Ladepunkte [3]
2023-07-29 14:13:41,210 - {control.algorithm.surplus_controlled:42} - {INFO:MainThread} - Mode-Tuple Chargemode.PV_CHARGING - Chargemode.PV_CHARGING - True, Zähler 0
2023-07-29 14:13:41,212 - {control.loadmanagement:82} - {DEBUG:MainThread} - Leistungsüberschreitung auf 3615.2999816894517W korrigieren: [10.0, 10.0, 0.0]
2023-07-29 14:13:41,217 - {control.counter:176} - {DEBUG:MainThread} - Zähler 0: [15.930652213718583, 15.920652213718585, 28.380000000000003]A verbleibende Ströme, 0.0W verbleibender Überschuss
2023-07-29 14:13:41,218 - {control.algorithm.common:87} - {INFO:MainThread} - LP3: Stromstärke 13.859347786281417A
2023-07-29 14:13:41,219 - {control.process:23} - {INFO:MainThread} - # Ladung starten.
2023-07-29 14:13:41,220 - {control.chargelog:52} - {DEBUG:MainThread} - imported_since_mode_switch 331.0600000000559 counter 798258.0
2023-07-29 14:13:41,229 - {control.process:117} - {INFO:MainThread} - LP3: set current 13.86 A, state WAIT_FOR_USING_PHASES
2023-07-29 14:13:41,231 - {modules.common.component_context:24} - {DEBUG:cp3} - Update Komponente ['Ladepunkt']
2023-07-29 14:13:41,249 - {control.counter:186} - {INFO:MainThread} - 0W reservierte EVU-Leistung, 0W freigegebene EVU-Leistung
2023-07-29 14:13:41,481 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=update, args=(), kwargs={})
2023-07-29 14:13:41,606 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2023-07-29 14:13:45,619 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2023-07-29 14:13:45,620 - {root:52} - {DEBUG:MainThread} - Don't start multiple instances of smarthome thread.
2023-07-29 14:13:50,631 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})
2023-07-29 14:13:50,632 - {root:64} - {INFO:MainThread} - # ***Start*** 
2023-07-29 14:13:50,701 - {modules.common.component_context:51} - {DEBUG:device0} - Update Komponenten ['Kostal Plenticore Zähler', 'Ludwig 14', 'Kostal Plenticore Speicher']
2023-07-29 14:13:50,703 - {modules.common.component_context:24} - {DEBUG:cp3} - Update Komponente ['Ladepunkt']
2023-07-29 14:13:50,719 - {modules.common.simcount._simcount:38} - {DEBUG:device0} - Previous state: SimCounterState(timestamp=1690632820.705283, power=2857, imported=35053.62795069435, exported=33165.43179125753)
2023-07-29 14:13:50,720 - {modules.common.simcount._calculate:13} - {DEBUG:device0} - time passed: 0.00278168, power1: 2857, power2: 2857
2023-07-29 14:13:50,720 - {modules.common.simcount._calculate:29} - {DEBUG:device0} - power did not change sign. Total energy: 7.94727
2023-07-29 14:13:50,721 - {modules.common.simcount._simcount:47} - {DEBUG:device0} - imported: 7.94727 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1690632830.7193458, power=2857, imported=35061.575222264444, exported=33165.43179125753)
2023-07-29 14:13:50,723 - {modules.devices.kostal_plenticore.bat:28} - {DEBUG:device0} - raw bat power 2857
2023-07-29 14:13:50,790 - {modules.common.simcount._simcount:38} - {DEBUG:device0} - Previous state: SimCounterState(timestamp=1690632820.8065295, power=347.3000183105469, imported=1324.6243056332157, exported=154992.4776796913)
2023-07-29 14:13:50,791 - {modules.common.simcount._calculate:13} - {DEBUG:device0} - time passed: 0.0027734, power1: 347.3, power2: 357.5
2023-07-29 14:13:50,791 - {modules.common.simcount._calculate:29} - {DEBUG:device0} - power did not change sign. Total energy: 0.977346
2023-07-29 14:13:50,792 - {modules.common.simcount._simcount:47} - {DEBUG:device0} - imported: 0.977346 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1690632830.7907658, power=357.5, imported=1325.6016514505827, exported=154992.4776796913)
2023-07-29 14:13:50,792 - {modules.common.store._api:26} - {DEBUG:device0} - Raw data CounterState(voltages=[223.19100952148438, 224.75201416015625, 227.66700744628906], powers=[1108.300048828125, 1141.7000732421875, -1899.300048828125], currents=[5.134000301361084, 5.194000244140625, -8.35200023651123], power_factors=[1.0, 1.0, 1.0], imported=1325.6016514505827, exported=154992.4776796913, power=357.5, frequency=49.97300338745117)
2023-07-29 14:13:50,814 - {modules.common.store._api:26} - {DEBUG:device0} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-8404, exported=12170816.0, dc_power=None)
2023-07-29 14:13:50,815 - {modules.common.store._api:26} - {DEBUG:device0} - Raw data BatState(imported=35061.575222264444, exported=33165.43179125753, power=2857, soc=87)
2023-07-29 14:13:50,819 - {modules.common.component_context:24} - {DEBUG:component1} - Update Komponente ['Ludwig 14']
2023-07-29 14:13:50,820 - {modules.common.store._api:26} - {DEBUG:component1} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-8404, exported=12170816.0, dc_power=None)
2023-07-29 14:13:50,822 - {modules.common.component_context:24} - {DEBUG:component2} - Update Komponente ['Kostal Plenticore Speicher']
2023-07-29 14:13:50,822 - {modules.common.store._api:30} - {INFO:component1} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-8404, exported=12170816.0, dc_power=None)
2023-07-29 14:13:50,825 - {modules.common.component_context:24} - {DEBUG:cp3} - Update Komponente ['Ladepunkt']
2023-07-29 14:13:50,828 - {modules.common.store._api:30} - {INFO:component2} - Saving BatState(imported=35061.575222264444, exported=33165.43179125753, power=2857, soc=87)
2023-07-29 14:13:50,881 - {modules.common.component_context:24} - {DEBUG:component0} - Update Komponente ['Kostal Plenticore Zähler']
2023-07-29 14:13:50,882 - {modules.common.store._api:30} - {INFO:component0} - Saving CounterState(voltages=[223.19100952148438, 224.75201416015625, 227.66700744628906], powers=[1108.300048828125, 1141.7000732421875, -1899.300048828125], currents=[5.134000301361084, 5.194000244140625, -8.35200023651123], power_factors=[1.0, 1.0, 1.0], imported=1325.6016514505827, exported=154992.4776796913, power=357.5, frequency=49.97300338745117)
2023-07-29 14:13:51,020 - {control.bat_all:192} - {DEBUG:MainThread} - Erlaubte Entlade-Leistung nutzen 1000W
2023-07-29 14:13:51,021 - {control.bat_all:164} - {INFO:MainThread} - 1000W verbleibende Speicher-Leistung
2023-07-29 14:13:51,022 - {control.counter:138} - {INFO:MainThread} - Verbleibende Ströme an Zähler 0: [29.81, 29.79, 28.42]A
2023-07-29 14:13:51,023 - {control.counter:161} - {INFO:MainThread} - Verbleibende Leistung an Zähler 0: 35261.28W
2023-07-29 14:13:51,025 - {control.chargepoint.chargepoint:661} - {DEBUG:MainThread} - EV-Phasenzahl beschränkt die nutzbaren Phasen auf 2
2023-07-29 14:13:51,028 - {control.chargepoint.chargepoint:853} - {INFO:MainThread} - LP 3, EV: Nulli (EV-Nr.2): Theoretisch benötigter Strom 6A, Lademodus pv_charging, Submodus: Chargemode.PV_CHARGING, Phasen: 2, Priorität: True, max. Ist-Strom: 12.98
2023-07-29 14:13:51,032 - {control.data:337} - {INFO:MainThread} - bat2
BatData(get=Get(soc=87, daily_exported=1842.08, daily_imported=3063.83, imported=35061.58, exported=33165.43, fault_state=0, fault_str='Kein Fehler.', power=2857))
2023-07-29 14:13:51,033 - {control.data:307} - {INFO:MainThread} - bat_all_data
BatAllData(config=Config(configured=True), get=Get(soc=87, daily_exported=1842.08, daily_imported=3063.83, imported=35061.58, exported=33165.43, power=2857), set=Set(charging_power_left=1000, switch_on_soc_reached=True))
2023-07-29 14:13:51,034 - {control.data:308} - {INFO:MainThread} - cp_all_data
AllChargepointData(get=AllGet(daily_imported=570.01, daily_exported=0, power=5618.78, imported=798272.95, exported=0))
2023-07-29 14:13:51,035 - {control.data:337} - {INFO:MainThread} - cp3
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, time_charging_in_use=False), info=ConnectedInfo(id=0, name='Ladepunkt'), soc=81), currents=[12.94, 12.98, 0.07], daily_imported=570.01, daily_exported=0, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=798272.95, phases_in_use=2, plug_state=True, power=5618.78, rfid_timestamp=None, rfid=None, state_str=None, voltages=[216.14, 217.84, 231.69]), set=Set(change_ev_permitted=[True, ''], charging_ev=2, charging_ev_prev=2, current=13.86, energy_to_charge=0, loadmanagement_available=True, log=Log(chargemode_log_entry='pv_charging', imported_at_mode_switch=797926.94, imported_at_plugtime=797052.98, imported_since_mode_switch=331.0600000000559, imported_since_plugged=1205.0200000000186, range_charged=2.364714285714685, time_charged='0:07', timestamp_start_charging='07/29/2023, 14:06:51'), manual_lock=False, phases_to_use=3, plug_state_prev=True, plug_time='07/28/2023, 17:25:11', required_power=2760.0, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0x6e577aa8>), config=Config(configuration={'mode': 'series', 'ip_address': 'localhost', 'duo_num': 0}, ev=2, name='Interne openWB', type='internal_openwb', template=0, connected_phases=3, phase_1=1, auto_phase_switch_hw=True, control_pilot_interruption_hw=True, id=3))
2023-07-29 14:13:51,042 - {control.data:337} - {INFO:MainThread} - cpt0
CpTemplateData(autolock=Autolock(active=False, plans={}, wait_for_charging_end=False), id=0, max_current_multi_phases=16, max_current_single_phase=16, name='Standard Ladepunkt-Vorlage', rfid_enabling=False, valid_tags=[])
2023-07-29 14:13:51,043 - {control.data:337} - {INFO:MainThread} - counter0
CounterData(config=Config(max_currents=[22, 22, 20], max_total_power=30000), get=Get(powers=[1108.3, 1141.7, -1899.3], currents=[5.13, 5.19, -8.35], voltages=[223.19, 224.75, 227.67], power_factors=[1.0, 1.0, 1.0], unbalanced_load=0, frequency=49.97300338745117, daily_exported=4381.65964235525, daily_imported=148.6428133458819, imported=1325.6016514505827, exported=154992.4776796913, fault_state=0, fault_str='Kein Fehler.', power=357.5), set=Set(error_counter=0, reserved_surplus=0, released_surplus=0, raw_power_left=35261.28, raw_currents_left=[29.81, 29.79, 28.42], surplus_power_left=0, state_str=''))
2023-07-29 14:13:51,044 - {control.data:312} - {INFO:MainThread} - counter_all_data
CounterAllData(config=Config(reserve_for_not_charging=True), get=Get(hierarchy=[{'id': 0, 'type': 'counter', 'children': [{'id': 1, 'type': 'inverter', 'children': []}, {'id': 2, 'type': 'bat', 'children': []}, {'id': 3, 'type': 'cp', 'children': []}]}]), set=Set(loadmanagement_active=False, home_consumption=285.72000000000025, invalid_home_consumption=0, daily_yield_home_consumption=3449.223170990632, disengageable_smarthome_power=0))
2023-07-29 14:13:51,046 - {control.data:337} - {INFO:MainThread} - ct0
ChargeTemplateData(name='Standard-Ladeprofil-Vorlage', disable_after_unplug=False, prio=False, load_default=False, time_charging=TimeCharging(active=False, plans={'1': TimeChargingPlan(active=False, frequency=Frequency(selected='daily', once=['2021-11-01', '2021-11-05'], weekly=[False, False, False, False, False, False, False]), time=['06:00', '07:00'], name='Zeitladen-Standard', current=16, limit=Limit(selected='none', amount=1000, soc=50))}), chargemode=Chargemode(selected='instant_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={'1': ScheduledChargingPlan(active=False, 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='amount', amount=10000, soc_limit=90, soc_scheduled=80), time='07:00')}), instant_charging=InstantCharging(current=16, limit=Limit(selected='none', amount=1000, soc=50))))
2023-07-29 14:13:51,052 - {control.data:337} - {INFO:MainThread} - ct1
ChargeTemplateData(name='Mein PV-Laden', disable_after_unplug=False, prio=True, load_default=False, time_charging=TimeCharging(active=False, plans={'0': TimeChargingPlan(active=False, frequency=Frequency(selected='daily', once=['2021-11-01', '2021-11-05'], weekly=[False, False, False, False, False, False, False]), time=['11:00', '16:00'], name='Zeitladen-Standard', current=16, limit=Limit(selected='soc', amount=1000, soc=80))}), chargemode=Chargemode(selected='pv_charging', pv_charging=PvCharging(min_soc_current=10, min_current=0, feed_in_limit=False, min_soc=40, max_soc=88), scheduled_charging=ScheduledCharging(plans={'0': ScheduledChargingPlan(active=False, frequency=Frequency(selected='once', once='2023-07-24', weekly=[False, False, False, False, False, False, False]), current=14, name='Zielladen-Standard', limit=ScheduledLimit(selected='soc', amount=1000, soc_limit=100, soc_scheduled=50), time='07:00')}), instant_charging=InstantCharging(current=16, limit=Limit(selected='soc', amount=1000, soc=80))))
2023-07-29 14:13:51,054 - {control.data:337} - {INFO: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=17000, min_current=6, max_current_single_phase=32, battery_capacity=82000, nominal_difference=1, keep_charge_active_duration=40), et_num=0), soc_error_counter=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='stop', chargemode='stop', used_amount_instant_charging=0, imported_at_plan_start=0, current_plan=None, state=0), 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-07-29 14:13:51,056 - {control.data:337} - {INFO:MainThread} - ev2
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='e-up-Vorlage', max_current_multi_phases=16, max_phases=2, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=14000, min_current=6, max_current_single_phase=16, battery_capacity=32000, nominal_difference=1, keep_charge_active_duration=120), et_num=0), soc_error_counter=0), control_parameter=ControlParameter(required_current=6, required_currents=[6, 6, 0.0], phases=2, prio=True, timestamp_switch_on_off=None, 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, state=3), charge_template=1, ev_template=2, name='Nulli', tag_id=[], get=Get(soc=81, soc_timestamp='07/29/2023, 14:06:51', force_soc_update=False, range=231.0, fault_state=0, fault_str='Kein Fehler.'))
2023-07-29 14:13:51,058 - {control.data:337} - {INFO:MainThread} - ev3
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=17000, min_current=6, max_current_single_phase=32, battery_capacity=82000, nominal_difference=1, keep_charge_active_duration=40), et_num=0), soc_error_counter=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, state=0), 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='Kein Fehler.'))
2023-07-29 14:13:51,061 - {control.data:337} - {INFO: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=17000, min_current=6, max_current_single_phase=16, battery_capacity=32000, nominal_difference=1, keep_charge_active_duration=40)
2023-07-29 14:13:51,067 - {control.data:337} - {INFO:MainThread} - et2
EvTemplateData(name='e-up-Vorlage', max_current_multi_phases=10, max_phases=2, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=14000, min_current=6, max_current_single_phase=10, battery_capacity=32000, nominal_difference=1, keep_charge_active_duration=40)
2023-07-29 14:13:51,070 - {control.data:316} - {INFO: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=[0, 230], feed_in_yield=0, phase_switch_delay=1, phases_to_use=0, rundown_power=1000, rundown_soc=75, switch_off_delay=360, switch_off_soc=0, switch_off_threshold=6000, switch_on_delay=60, switch_on_soc=0, switch_on_threshold=1000), scheduled_charging=ScheduledCharging(phases_to_use=0), 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.429, range_unit='km', ripple_control_receiver=RippleControlReceiver(configured=False, r1_active=False, r2_active=False))
2023-07-29 14:13:51,072 - {control.data:317} - {INFO:MainThread} - general_data-display
local
2023-07-29 14:13:51,073 - {control.data:318} - {INFO:MainThread} - graph_data
GraphData(config=Config(duration=120))
2023-07-29 14:13:51,074 - {control.data:319} - {INFO:MainThread} - optional_data
OptionalData(et=Et(active=False, config=EtConfig(max_price=0, provider={}), get=EtGet(price=0, price_list=[])), int_display=InternalDisplay(active=True, on_if_plugged_in=True, pin_active=False, pin_code='0000', standby=60, theme=<modules.display_themes.cards.config.CardsDisplayTheme object at 0x6e750250>), led=Led(active=False), rfid=Rfid(active=False))
2023-07-29 14:13:51,076 - {control.data:337} - {INFO:MainThread} - pv1
PvData(config=Config(max_ac_out=5500000), get=Get(currents=[0.0, 0.0, 0.0], daily_exported=9474.0, monthly_exported=0, yearly_exported=0, exported=12170816.0, fault_state=0, fault_str='Kein Fehler.', power=-8404))
2023-07-29 14:13:51,077 - {control.data:321} - {INFO:MainThread} - pv_all_data
PvAllData(config=Config(configured=True), get=Get(daily_exported=9474.0, monthly_exported=0, yearly_exported=0, exported=12170816.0, power=-8404))
2023-07-29 14:13:51,078 - {control.data:337} - {INFO:MainThread} - system
{'update_in_progress': False, 'perform_update': False, 'ip_address': '192.168.1.102', 'current_commit': '2023-07-17 11:46:58 +0200 [da6031826]', 'current_branch': 'Release', 'current_branch_commit': '2023-07-17 11:46:58 +0200 [da6031826]', 'current_missing_commits': [], 'boot_done': True, 'datastore_version': 16, 'dataprotection_acknowledged': True, 'usage_terms_acknowledged': True, 'debug_level': 10, 'release_train': 'master', 'version': '2.0.0', 'lastlivevaluesJson': {'timestamp': 1690632821, 'time': '14:13:41', 'grid': 0.347, 'house-power': 0.287, 'charging-all': 5.608, 'pv-all': 8.404, 'cp3-power': 5.608, 'ev2-soc': 81, 'bat-all-power': 2.857, 'bat-all-soc': 87}, 'module_update_completed': True}
2023-07-29 14:13:51,079 - {control.data:350} - {INFO:MainThread} - device0
{'name': 'Kostal Plenticore', 'type': 'kostal_plenticore', 'id': 0, 'configuration': {'ip_address': '192.168.1.103'}}
2023-07-29 14:13:51,090 - {control.data:352} - {INFO:MainThread} - component0
{'name': 'Kostal Plenticore Zähler', 'type': 'counter', 'id': 0, 'configuration': {}}
2023-07-29 14:13:51,092 - {control.data:352} - {INFO:MainThread} - component1
{'name': 'Ludwig 14', 'type': 'inverter', 'id': 1, 'configuration': {}}
2023-07-29 14:13:51,097 - {control.data:352} - {INFO:MainThread} - component2
{'name': 'Kostal Plenticore Speicher', 'type': 'bat', 'id': 2, 'configuration': {}}
2023-07-29 14:13:51,100 - {control.data:324} - {INFO:MainThread} - 

2023-07-29 14:13:51,101 - {control.algorithm.algorithm:25} - {INFO:MainThread} - # Algorithmus
2023-07-29 14:13:51,103 - {control.algorithm.algorithm:31} - {INFO:MainThread} - **Mindestrom setzen**
2023-07-29 14:13:51,107 - {control.algorithm.min_current:18} - {INFO:MainThread} - Mode-Tuple Chargemode.PV_CHARGING - Chargemode.PV_CHARGING - True, Zähler 0
2023-07-29 14:13:51,109 - {control.counter:169} - {DEBUG:MainThread} - Zähler 0: [23.81, 23.79, 28.42]A verbleibende Ströme, 32501.28W verbleibende Leistung
2023-07-29 14:13:51,113 - {control.algorithm.common:87} - {INFO:MainThread} - LP3: Stromstärke 6A
2023-07-29 14:13:51,115 - {control.algorithm.algorithm:33} - {INFO:MainThread} - **Sollstrom setzen**
2023-07-29 14:13:51,118 - {control.counter:211} - {INFO:MainThread} - Überschuss zur PV-geführten Ladung: 3616.279999999999W
2023-07-29 14:13:51,119 - {control.counter:470} - {DEBUG:MainThread} - Zähler 0: Begrenzung der verbleibenden Leistung auf 3616.279999999999W
2023-07-29 14:13:51,121 - {control.algorithm.algorithm:39} - {INFO:MainThread} - **PV-geführten Strom setzen**
2023-07-29 14:13:51,124 - {control.algorithm.filter_chargepoints:118} - {DEBUG:MainThread} - Geordnete Ladepunkte [3]
2023-07-29 14:13:51,126 - {control.algorithm.surplus_controlled:42} - {INFO:MainThread} - Mode-Tuple Chargemode.PV_CHARGING - Chargemode.PV_CHARGING - True, Zähler 0
2023-07-29 14:13:51,130 - {control.loadmanagement:82} - {DEBUG:MainThread} - Leistungsüberschreitung auf 3616.279999999999W korrigieren: [10.0, 10.0, 0.0]
2023-07-29 14:13:51,132 - {control.counter:176} - {DEBUG:MainThread} - Zähler 0: [15.948521739130436, 15.928521739130437, 28.42]A verbleibende Ströme, 0.0W verbleibender Überschuss
2023-07-29 14:13:51,134 - {control.algorithm.common:87} - {INFO:MainThread} - LP3: Stromstärke 13.861478260869562A
2023-07-29 14:13:51,135 - {control.process:23} - {INFO:MainThread} - # Ladung starten.
2023-07-29 14:13:51,138 - {control.chargelog:52} - {DEBUG:MainThread} - imported_since_mode_switch 346.0100000000093 counter 798272.95
2023-07-29 14:13:51,141 - {control.chargepoint.chargepoint:574} - {DEBUG:MainThread} - Lp 3: Ladung aktiv halten 120s
2023-07-29 14:13:51,144 - {control.phase_switch:36} - {DEBUG:MainThread} - Thread zur Phasenumschaltung an LP3 gestartet.
2023-07-29 14:13:51,145 - {control.chargepoint.chargepoint:578} - {DEBUG:MainThread} - start phase switch phases_to_use 3control_parameter phases 2
2023-07-29 14:13:51,148 - {control.chargepoint.chargepoint:271} - {INFO:MainThread} - LP 3: Umschaltung von 1 auf 3 Phasen.
2023-07-29 14:13:51,153 - {control.process:117} - {INFO:MainThread} - LP3: set current 0 A, state PERFORMING_PHASE_SWITCH
2023-07-29 14:13:51,162 - {modules.common.component_context:24} - {DEBUG:cp3} - Update Komponente ['Ladepunkt']
2023-07-29 14:13:51,199 - {control.counter:186} - {INFO:MainThread} - 11040W reservierte EVU-Leistung, 0W freigegebene EVU-Leistung
2023-07-29 14:13:51,477 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=update, args=(), kwargs={})
2023-07-29 14:13:51,599 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2023-07-29 14:13:55,609 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2023-07-29 14:13:56,149 - {modules.common.component_context:24} - {DEBUG:cp3} - Update Komponente ['Ladepunkt']
2023-07-29 14:13:58,048 - {modules.common.component_context:24} - {DEBUG:Thread-85320} - Update Komponente ['Ladepunkt 0']
2023-07-29 14:14:00,620 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})
2023-07-29 14:14:00,620 - {root:64} - {INFO:MainThread} - # ***Start*** 
2023-07-29 14:14:00,650 - {modules.common.component_context:51} - {DEBUG:device0} - Update Komponenten ['Kostal Plenticore Zähler', 'Ludwig 14', 'Kostal Plenticore Speicher']
2023-07-29 14:14:00,652 - {modules.common.component_context:24} - {DEBUG:cp3} - Update Komponente ['Ladepunkt']
2023-07-29 14:14:00,668 - {modules.common.simcount._simcount:38} - {DEBUG:device0} - Previous state: SimCounterState(timestamp=1690632830.7193458, power=2857, imported=35061.575222264444, exported=33165.43179125753)
2023-07-29 14:14:00,669 - {modules.common.simcount._calculate:13} - {DEBUG:device0} - time passed: 0.00276362, power1: 2857, power2: 2861
2023-07-29 14:14:00,669 - {modules.common.simcount._calculate:29} - {DEBUG:device0} - power did not change sign. Total energy: 7.90119
2023-07-29 14:14:00,669 - {modules.common.simcount._simcount:47} - {DEBUG:device0} - imported: 7.90119 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1690632840.6683838, power=2861, imported=35069.47641663225, exported=33165.43179125753)
2023-07-29 14:14:00,677 - {modules.devices.kostal_plenticore.bat:28} - {DEBUG:device0} - raw bat power 2861
2023-07-29 14:14:00,774 - {modules.common.simcount._simcount:38} - {DEBUG:device0} - Previous state: SimCounterState(timestamp=1690632830.7907658, power=357.5, imported=1325.6016514505827, exported=154992.4776796913)
2023-07-29 14:14:00,775 - {modules.common.simcount._calculate:13} - {DEBUG:device0} - time passed: 0.00277328, power1: 357.5, power2: -5409
2023-07-29 14:14:00,775 - {modules.common.simcount._calculate:26} - {DEBUG:device0} - power changed sign. Total energy: -7.00461, exported energy: -7.03534
2023-07-29 14:14:00,775 - {modules.common.simcount._simcount:47} - {DEBUG:device0} - imported: 0.0307329 Wh, exported: 7.03534 Wh, new state: SimCounterState(timestamp=1690632840.7745738, power=-5409.0, imported=1325.6323843490864, exported=154999.5130245783)
2023-07-29 14:14:00,776 - {modules.common.store._api:26} - {DEBUG:device0} - Raw data CounterState(voltages=[226.4340057373047, 227.6820068359375, 225.4960174560547], powers=[-1790.9000244140625, -1784.7000732421875, -1840.300048828125], currents=[-7.9210004806518555, -7.865000247955322, -8.13700008392334], power_factors=[1.0, 1.0, 1.0], imported=1325.6323843490864, exported=154999.5130245783, power=-5409.0, frequency=49.970001220703125)
2023-07-29 14:14:00,799 - {modules.common.store._api:26} - {DEBUG:device0} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-8409, exported=12170816.0, dc_power=None)
2023-07-29 14:14:00,800 - {modules.common.store._api:26} - {DEBUG:device0} - Raw data BatState(imported=35069.47641663225, exported=33165.43179125753, power=2861, soc=87)
2023-07-29 14:14:00,810 - {modules.common.component_context:24} - {DEBUG:component1} - Update Komponente ['Ludwig 14']
2023-07-29 14:14:00,811 - {modules.common.store._api:26} - {DEBUG:component1} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-8409, exported=12170816.0, dc_power=None)
2023-07-29 14:14:00,812 - {modules.common.store._api:30} - {INFO:component1} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-8409, exported=12170816.0, dc_power=None)
2023-07-29 14:14:00,813 - {modules.common.component_context:24} - {DEBUG:component2} - Update Komponente ['Kostal Plenticore Speicher']
2023-07-29 14:14:00,814 - {modules.common.component_context:24} - {DEBUG:cp3} - Update Komponente ['Ladepunkt']
2023-07-29 14:14:00,815 - {modules.common.store._api:30} - {INFO:component2} - Saving BatState(imported=35069.47641663225, exported=33165.43179125753, power=2861, soc=87)
2023-07-29 14:14:00,868 - {modules.common.component_context:24} - {DEBUG:component0} - Update Komponente ['Kostal Plenticore Zähler']
2023-07-29 14:14:00,870 - {modules.common.store._api:30} - {INFO:component0} - Saving CounterState(voltages=[226.4340057373047, 227.6820068359375, 225.4960174560547], powers=[-1790.9000244140625, -1784.7000732421875, -1840.300048828125], currents=[-7.9210004806518555, -7.865000247955322, -8.13700008392334], power_factors=[1.0, 1.0, 1.0], imported=1325.6323843490864, exported=154999.5130245783, power=-5409.0, frequency=49.970001220703125)
2023-07-29 14:14:01,004 - {control.bat_all:192} - {DEBUG:MainThread} - Erlaubte Entlade-Leistung nutzen 1000W
2023-07-29 14:14:01,005 - {control.bat_all:164} - {INFO:MainThread} - 1000W verbleibende Speicher-Leistung
2023-07-29 14:14:01,006 - {control.counter:138} - {INFO:MainThread} - Verbleibende Ströme an Zähler 0: [29.92, 29.87, 28.14]A
2023-07-29 14:14:01,007 - {control.counter:161} - {INFO:MainThread} - Verbleibende Leistung an Zähler 0: 35409.0W
2023-07-29 14:14:01,009 - {control.chargepoint.chargepoint:661} - {DEBUG:MainThread} - EV-Phasenzahl beschränkt die nutzbaren Phasen auf 2
2023-07-29 14:14:01,011 - {control.chargepoint.chargepoint:644} - {DEBUG:MainThread} - Phasenzahl Lademodus: 1
2023-07-29 14:14:01,014 - {control.chargepoint.chargepoint:853} - {INFO:MainThread} - LP 3, EV: Nulli (EV-Nr.2): Theoretisch benötigter Strom 6A, Lademodus pv_charging, Submodus: Chargemode.PV_CHARGING, Phasen: 1, Priorität: True, max. Ist-Strom: 0.0
2023-07-29 14:14:01,017 - {control.data:337} - {INFO:MainThread} - bat2
BatData(get=Get(soc=87, daily_exported=1842.08, daily_imported=3063.83, imported=35069.48, exported=33165.43, fault_state=0, fault_str='Kein Fehler.', power=2861))
2023-07-29 14:14:01,023 - {control.data:307} - {INFO:MainThread} - bat_all_data
BatAllData(config=Config(configured=True), get=Get(soc=87, daily_exported=1842.08, daily_imported=3063.83, imported=35069.48, exported=33165.43, power=2861), set=Set(charging_power_left=1000, switch_on_soc_reached=True))
2023-07-29 14:14:01,024 - {control.data:308} - {INFO:MainThread} - cp_all_data
AllChargepointData(get=AllGet(daily_imported=570.01, daily_exported=0, power=0, imported=798279.97, exported=0))
2023-07-29 14:14:01,026 - {control.data:337} - {INFO: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, time_charging_in_use=False), info=ConnectedInfo(id=0, name='Ladepunkt'), soc=81), currents=[0.0, 0.0, 0.0], daily_imported=570.01, daily_exported=0, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=798279.97, phases_in_use=2, plug_state=True, power=0, rfid_timestamp=None, rfid=None, state_str=None, voltages=[225.66, 226.83, 225.38]), 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='pv_charging', imported_at_mode_switch=797926.94, imported_at_plugtime=797052.98, imported_since_mode_switch=346.0100000000093, imported_since_plugged=1219.969999999972, range_charged=2.4715000000000664, time_charged='0:07', timestamp_start_charging='07/29/2023, 14:06:51'), manual_lock=False, phases_to_use=2, plug_state_prev=True, plug_time='07/28/2023, 17:25:11', required_power=1380.0, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0x6e766da8>), config=Config(configuration={'mode': 'series', 'ip_address': 'localhost', 'duo_num': 0}, ev=2, name='Interne openWB', type='internal_openwb', template=0, connected_phases=3, phase_1=1, auto_phase_switch_hw=True, control_pilot_interruption_hw=True, id=3))
2023-07-29 14:14:01,030 - {control.data:337} - {INFO:MainThread} - cpt0
CpTemplateData(autolock=Autolock(active=False, plans={}, wait_for_charging_end=False), id=0, max_current_multi_phases=16, max_current_single_phase=16, name='Standard Ladepunkt-Vorlage', rfid_enabling=False, valid_tags=[])
2023-07-29 14:14:01,032 - {control.data:337} - {INFO:MainThread} - counter0
CounterData(config=Config(max_currents=[22, 22, 20], max_total_power=30000), get=Get(powers=[-1790.9, -1784.7, -1840.3], currents=[-7.92, -7.87, -8.14], voltages=[226.43, 227.68, 225.5], power_factors=[1.0, 1.0, 1.0], unbalanced_load=0, frequency=49.970001220703125, daily_exported=4381.65964235525, daily_imported=148.6428133458819, imported=1325.6323843490864, exported=154999.5130245783, fault_state=0, fault_str='Kein Fehler.', power=-5409.0), set=Set(error_counter=0, reserved_surplus=11040, released_surplus=0, raw_power_left=35409.0, raw_currents_left=[29.92, 29.87, 28.14], surplus_power_left=0, state_str=''))
2023-07-29 14:14:01,033 - {control.data:312} - {INFO:MainThread} - counter_all_data
CounterAllData(config=Config(reserve_for_not_charging=True), get=Get(hierarchy=[{'id': 0, 'type': 'counter', 'children': [{'id': 1, 'type': 'inverter', 'children': []}, {'id': 2, 'type': 'bat', 'children': []}, {'id': 3, 'type': 'cp', 'children': []}]}]), set=Set(loadmanagement_active=False, home_consumption=139.0, invalid_home_consumption=0, daily_yield_home_consumption=3449.223170990632, disengageable_smarthome_power=0))
2023-07-29 14:14:01,037 - {control.data:337} - {INFO:MainThread} - ct0
ChargeTemplateData(name='Standard-Ladeprofil-Vorlage', disable_after_unplug=False, prio=False, load_default=False, time_charging=TimeCharging(active=False, plans={'1': TimeChargingPlan(active=False, frequency=Frequency(selected='daily', once=['2021-11-01', '2021-11-05'], weekly=[False, False, False, False, False, False, False]), time=['06:00', '07:00'], name='Zeitladen-Standard', current=16, limit=Limit(selected='none', amount=1000, soc=50))}), chargemode=Chargemode(selected='instant_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={'1': ScheduledChargingPlan(active=False, 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='amount', amount=10000, soc_limit=90, soc_scheduled=80), time='07:00')}), instant_charging=InstantCharging(current=16, limit=Limit(selected='none', amount=1000, soc=50))))
2023-07-29 14:14:01,039 - {control.data:337} - {INFO:MainThread} - ct1
ChargeTemplateData(name='Mein PV-Laden', disable_after_unplug=False, prio=True, load_default=False, time_charging=TimeCharging(active=False, plans={'0': TimeChargingPlan(active=False, frequency=Frequency(selected='daily', once=['2021-11-01', '2021-11-05'], weekly=[False, False, False, False, False, False, False]), time=['11:00', '16:00'], name='Zeitladen-Standard', current=16, limit=Limit(selected='soc', amount=1000, soc=80))}), chargemode=Chargemode(selected='pv_charging', pv_charging=PvCharging(min_soc_current=10, min_current=0, feed_in_limit=False, min_soc=40, max_soc=88), scheduled_charging=ScheduledCharging(plans={'0': ScheduledChargingPlan(active=False, frequency=Frequency(selected='once', once='2023-07-24', weekly=[False, False, False, False, False, False, False]), current=14, name='Zielladen-Standard', limit=ScheduledLimit(selected='soc', amount=1000, soc_limit=100, soc_scheduled=50), time='07:00')}), instant_charging=InstantCharging(current=16, limit=Limit(selected='soc', amount=1000, soc=80))))
2023-07-29 14:14:01,040 - {control.data:337} - {INFO: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=17000, min_current=6, max_current_single_phase=32, battery_capacity=82000, nominal_difference=1, keep_charge_active_duration=40), et_num=0), soc_error_counter=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='stop', chargemode='stop', used_amount_instant_charging=0, imported_at_plan_start=0, current_plan=None, state=0), 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-07-29 14:14:01,043 - {control.data:337} - {INFO:MainThread} - ev2
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='e-up-Vorlage', max_current_multi_phases=16, max_phases=2, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=14000, min_current=6, max_current_single_phase=16, battery_capacity=32000, nominal_difference=1, keep_charge_active_duration=120), et_num=0), soc_error_counter=0), control_parameter=ControlParameter(required_current=6, required_currents=[6, 0.0, 0.0], phases=1, prio=True, timestamp_switch_on_off=None, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch='07/29/2023, 14:13:51', 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, state=2), charge_template=1, ev_template=2, name='Nulli', tag_id=[], get=Get(soc=81, soc_timestamp='07/29/2023, 14:06:51', force_soc_update=False, range=231.0, fault_state=0, fault_str='Kein Fehler.'))
2023-07-29 14:14:01,045 - {control.data:337} - {INFO:MainThread} - ev3
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=17000, min_current=6, max_current_single_phase=32, battery_capacity=82000, nominal_difference=1, keep_charge_active_duration=40), et_num=0), soc_error_counter=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, state=0), 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='Kein Fehler.'))
2023-07-29 14:14:01,047 - {control.data:337} - {INFO: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=17000, min_current=6, max_current_single_phase=16, battery_capacity=32000, nominal_difference=1, keep_charge_active_duration=40)
2023-07-29 14:14:01,056 - {control.data:337} - {INFO:MainThread} - et2
EvTemplateData(name='e-up-Vorlage', max_current_multi_phases=10, max_phases=2, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=14000, min_current=6, max_current_single_phase=10, battery_capacity=32000, nominal_difference=1, keep_charge_active_duration=40)
2023-07-29 14:14:01,058 - {control.data:316} - {INFO: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=[0, 230], feed_in_yield=0, phase_switch_delay=1, phases_to_use=0, rundown_power=1000, rundown_soc=75, switch_off_delay=360, switch_off_soc=0, switch_off_threshold=6000, switch_on_delay=60, switch_on_soc=0, switch_on_threshold=1000), scheduled_charging=ScheduledCharging(phases_to_use=0), 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.429, range_unit='km', ripple_control_receiver=RippleControlReceiver(configured=False, r1_active=False, r2_active=False))
2023-07-29 14:14:01,059 - {control.data:317} - {INFO:MainThread} - general_data-display
local
2023-07-29 14:14:01,063 - {control.data:318} - {INFO:MainThread} - graph_data
GraphData(config=Config(duration=120))
2023-07-29 14:14:01,071 - {control.data:319} - {INFO:MainThread} - optional_data
OptionalData(et=Et(active=False, config=EtConfig(max_price=0, provider={}), get=EtGet(price=0, price_list=[])), int_display=InternalDisplay(active=True, on_if_plugged_in=True, pin_active=False, pin_code='0000', standby=60, theme=<modules.display_themes.cards.config.CardsDisplayTheme object at 0x6e77f7d8>), led=Led(active=False), rfid=Rfid(active=False))
2023-07-29 14:14:01,074 - {control.data:337} - {INFO:MainThread} - pv1
PvData(config=Config(max_ac_out=5500000), get=Get(currents=[0.0, 0.0, 0.0], daily_exported=9474.0, monthly_exported=0, yearly_exported=0, exported=12170816.0, fault_state=0, fault_str='Kein Fehler.', power=-8409))
2023-07-29 14:14:01,077 - {control.data:321} - {INFO:MainThread} - pv_all_data
PvAllData(config=Config(configured=True), get=Get(daily_exported=9474.0, monthly_exported=0, yearly_exported=0, exported=12170816.0, power=-8409))
2023-07-29 14:14:01,079 - {control.data:337} - {INFO:MainThread} - system
{'update_in_progress': False, 'perform_update': False, 'ip_address': '192.168.1.102', 'current_commit': '2023-07-17 11:46:58 +0200 [da6031826]', 'current_branch': 'Release', 'current_branch_commit': '2023-07-17 11:46:58 +0200 [da6031826]', 'current_missing_commits': [], 'boot_done': True, 'datastore_version': 16, 'dataprotection_acknowledged': True, 'usage_terms_acknowledged': True, 'debug_level': 10, 'release_train': 'master', 'version': '2.0.0', 'lastlivevaluesJson': {'timestamp': 1690632831, 'time': '14:13:51', 'grid': 0.357, 'house-power': 0.286, 'charging-all': 5.619, 'pv-all': 8.404, 'cp3-power': 5.619, 'ev2-soc': 81, 'bat-all-power': 2.857, 'bat-all-soc': 87}, 'module_update_completed': True}
2023-07-29 14:14:01,085 - {control.data:350} - {INFO:MainThread} - device0
{'name': 'Kostal Plenticore', 'type': 'kostal_plenticore', 'id': 0, 'configuration': {'ip_address': '192.168.1.103'}}
2023-07-29 14:14:01,087 - {control.data:352} - {INFO:MainThread} - component0
{'name': 'Kostal Plenticore Zähler', 'type': 'counter', 'id': 0, 'configuration': {}}
2023-07-29 14:14:01,089 - {control.data:352} - {INFO:MainThread} - component1
{'name': 'Ludwig 14', 'type': 'inverter', 'id': 1, 'configuration': {}}
2023-07-29 14:14:01,090 - {control.data:352} - {INFO:MainThread} - component2
{'name': 'Kostal Plenticore Speicher', 'type': 'bat', 'id': 2, 'configuration': {}}
2023-07-29 14:14:01,091 - {control.data:324} - {INFO:MainThread} - 

2023-07-29 14:14:01,093 - {control.algorithm.algorithm:25} - {INFO:MainThread} - # Algorithmus
2023-07-29 14:14:01,094 - {control.algorithm.algorithm:31} - {INFO:MainThread} - **Mindestrom setzen**
2023-07-29 14:14:01,100 - {control.algorithm.min_current:18} - {INFO:MainThread} - Mode-Tuple Chargemode.PV_CHARGING - Chargemode.PV_CHARGING - True, Zähler 0
2023-07-29 14:14:01,103 - {control.algorithm.algorithm:33} - {INFO:MainThread} - **Sollstrom setzen**
2023-07-29 14:14:01,109 - {control.counter:211} - {INFO:MainThread} - Überschuss zur PV-geführten Ladung: 6524.0W
2023-07-29 14:14:01,111 - {control.counter:470} - {DEBUG:MainThread} - Zähler 0: Begrenzung der verbleibenden Leistung auf 6524.0W
2023-07-29 14:14:01,113 - {control.algorithm.algorithm:39} - {INFO:MainThread} - **PV-geführten Strom setzen**
2023-07-29 14:14:01,117 - {control.algorithm.filter_chargepoints:118} - {DEBUG:MainThread} - Geordnete Ladepunkte [3]
2023-07-29 14:14:01,123 - {control.algorithm.filter_chargepoints:47} - {INFO:MainThread} - LP 3: Keine Zuteilung des Mindeststroms, daher keine weitere Berücksichtigung
2023-07-29 14:14:01,130 - {control.process:23} - {INFO:MainThread} - # Ladung starten.
2023-07-29 14:14:01,138 - {control.chargepoint.chargepoint:537} - {DEBUG:MainThread} - phase switch running
2023-07-29 14:14:01,140 - {control.process:117} - {INFO:MainThread} - LP3: set current 0 A, state WAIT_FOR_USING_PHASES
2023-07-29 14:14:01,143 - {modules.common.component_context:24} - {DEBUG:cp3} - Update Komponente ['Ladepunkt']
2023-07-29 14:14:01,177 - {control.counter:186} - {INFO:MainThread} - 0W reservierte EVU-Leistung, 0W freigegebene EVU-Leistung
2023-07-29 14:14:01,455 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=update, args=(), kwargs={})
2023-07-29 14:14:01,549 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2023-07-29 14:14:05,564 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2023-07-29 14:14:05,566 - {root:52} - {DEBUG:MainThread} - Don't start multiple instances of smarthome thread.
Dateianhänge
16A_PVladen_Umschaltung_Mainlog.txt
(709.02 KiB) 36-mal heruntergeladen
zut
Beiträge: 567
Registriert: Di Feb 23, 2021 9:34 pm
Has thanked: 3 times
Been thanked: 4 times

Re: Wiederkehrende Fehlermeldung im Log

Beitrag von zut »

Kann ich noch etwas sinnvolles beisteuern, um die Ursache der doppelten 1->2-Umschaltung aufzuklären?
zut
Beiträge: 567
Registriert: Di Feb 23, 2021 9:34 pm
Has thanked: 3 times
Been thanked: 4 times

Re: Wiederkehrende Fehlermeldung im Log

Beitrag von zut »

Auch in Release 2.1.0 erfolgt die Doppelumschaltung 1->3 beim PV-Laden.
LenaK
Beiträge: 1253
Registriert: Fr Jan 22, 2021 6:40 am
Been thanked: 6 times

Re: Wiederkehrende Fehlermeldung im Log

Beitrag von LenaK »

Sollte hiermit behoben sein: https://github.com/openWB/core/pull/1082 Es steht noch das Review aus, danach kommt es in den Master.
ID4U
Beiträge: 399
Registriert: Do Okt 13, 2022 4:26 pm
Been thanked: 3 times

Re: Wiederkehrende Fehlermeldung im Log

Beitrag von ID4U »

Super, danke!
Antworten