PV-Laden: Schwingen anstatt abschalten bei zu wenig PV-Leistung

mrinas · Beitrag von **mrinas** » Do Dez 21, 2023 1:34 pm

Nein, die Logs sind eher kurzlebig, vorallem auf Debug. Gibt eine Begrenzung auf eine gewisse Anzahl Zeilen.

heidanei · Beitrag von **heidanei** » Do Dez 21, 2023 1:44 pm

mrinas hat geschrieben: ↑Do Dez 21, 2023 1:34 pm Nein, die Logs sind eher kurzlebig, vorallem auf Debug. Gibt eine Begrenzung auf eine gewisse Anzahl Zeilen.

Danke! Schade, dann bleibt wohl nur abwarten bis das Wetter wieder besser ist und ich grad daheim bin und Zeit hab... leider eine seltene Konstellation.

Viele Grüße, Michael

MichaO · Beitrag von **MichaO** » Fr Dez 22, 2023 6:59 am

Moin,
hab so ein Schwingen auch bei der 1.9x im Sommer schon beobachtet und gemeldet (u.a. viewtopic.php?p=90919#p90919)... wurde bislang keine Lösung kommuniziert. Aus meiner Sicht ist der Regelkreis zu einfach gehalten und die Regelschleife - zumindest bei der 1.9 - unglücklich geschrieben, da nicht nur geregelt, sondern auch abgefragt wird. Das sollte meiner Meinung nach asynchron laufen. Ob das das Problem löst, kann ich nicht sagen.

heidanei · Beitrag von **heidanei** » Mo Dez 25, 2023 12:03 pm

Hallo zusammen!

Heute zum Glück mal wieder etwas Sonnenschein und ich hab einen weiteren Versuch gestartet. Leider sagen mir dieDebug-Logs nicht allzuviel, vielleicht kann jemand von euch mal drauf schauen der sich besser mit der Regelschleife auskennt.
Gegeben: Nach wie vor die SmartWB von oben, PV-Laden, jetzt die Einschaltschwelle auf 1,5kW gesetzt, Einschaltverzögerung 30sek, Ausschaltverzögerung - um das Takten etwas zu verlangsamen - auf 480s erhöht.

Beim ersten Bild ist die Ausschaltverzögerung nach 480s abgelaufen (12:16:01):

Hier ist jetzt abgeschaltet (12:16:20):

Nach 30sek. wird wieder eingeschaltet obwohl der PV-Überschuss nicht ausreicht:

Um 12:17:31 wieder 3,69kW und die Ausschaltverzögerung von 480sek. läuft von neuem.

Hier der Auszug aus dem Log, IMO vom relevanten Bereich:
Im Regeldurchlauf um 12:16:10 sagt er noch **Keine Leistung für PV-geführtes Laden übrig**
Im nächsten Durchlauf um 12:16:20 dann "LP 1: Die Ladung wird gestartet, sobald nach 30s die Einschaltverzögerung abgelaufen ist."
... und die Ladung wird dann auch nach 30s. gestartet obwohl die Bedingung noch nicht erreicht ist.
Leider kann ich aus dem Debug-Log nicht wirklich rauslesen woran es liegt. Kann jemand von euch helfen?

Code: Alles auswählen

2023-12-25 12:16:10,403 - {root:74} - {INFO:MainThread} - # ***Start*** 

2023-12-25 12:16:10,412 - {modules.devices.openwb_flex.device:50} - {DEBUG:device0} - Start device reading {'component0': <modules.devices.openwb_flex.counter.EvuKitFlex object at 0x7fec2d58d4f0>}
2023-12-25 12:16:10,413 - {modules.common.component_context:25} - {DEBUG:device0} - Update Komponente ['EVU-Kit flex']
2023-12-25 12:16:10,413 - {modules.devices.sma_sunny_boy.device:72} - {DEBUG:device1} - Start device reading {'component2': <modules.devices.sma_sunny_boy.inverter.SmaSunnyBoyInverter object at 0x7fec26dcc760>}
2023-12-25 12:16:10,414 - {modules.common.component_context:25} - {DEBUG:set values cp1} - Update Komponente ['Ladepunkt']
2023-12-25 12:16:10,417 - {urllib3.connectionpool:227} - {DEBUG:set values cp1} - Starting new HTTP connection (1): 192.168.1.82:80
2023-12-25 12:16:10,419 - {modules.common.component_context:25} - {DEBUG:device1} - Update Komponente ['Sma Sunny Boy/Tripower Wechselrichter']
2023-12-25 12:16:10,443 - {urllib3.connectionpool:452} - {DEBUG:set values cp1} - http://192.168.1.82:80 "GET /getParameters HTTP/1.1" 200 440
2023-12-25 12:16:10,444 - {modules.common.req:11} - {DEBUG:set values cp1} - Get-Response: {"type":"parameters","list":[{"vehicleState":3,"evseState":true,"maxCurrent":16,"actualCurrent":6,"actualCurrentMA":600,"actualPower":3.67,"duration":2019000,"alwaysActive":true,"lastActionUser":"vehicle","lastActionUID":"vehicle","energy":2.81,"mileage":18.7,"meterReading":2695.72,"currentP1":6.032392,"currentP2":4.763788,"currentP3":5.247105,"voltageP1":228.5186,"voltageP2":229.5418,"voltageP3":229.2764,"useMeter":true,"RFIDUID":""}]}
2023-12-25 12:16:10,444 - {modules.common.store._api:26} - {DEBUG:set values cp1} - Raw data ChargepointState(currents=[6.032392, 4.763788, 5.247105], powers=[1378.5137744912, 1093.4884723384, 1203.037344822], voltages=[228.5186, 229.5418, 229.2764], frequency=50, imported=2695720.0, exported=0, power=3670.0, phases_in_use=3, charge_state=True, plug_state=True, rfid=None, rfid_timestamp=None, power_factors=[0.0, 0.0, 0.0], soc=None, soc_timestamp=None, evse_current=None, vehicle_id=None)
2023-12-25 12:16:10,897 - {modules.common.simcount._simcount:37} - {DEBUG:device0} - Previous state: SimCounterState(timestamp=1703502961.077718, power=2370.1271362304688, imported=82495.73879230821, exported=29433.432754515434)
2023-12-25 12:16:10,897 - {modules.common.simcount._calculate:11} - {DEBUG:device0} - time passed: 0.00272761, power1: 2370.13, power2: 2333.63
2023-12-25 12:16:10,897 - {modules.common.simcount._calculate:27} - {DEBUG:device0} - power did not change sign. Total energy: 6.415
2023-12-25 12:16:10,897 - {modules.common.simcount._simcount:46} - {DEBUG:device0} - imported: 6.415 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1703502970.8970995, power=2333.6319580078125, imported=82502.15379299084, exported=29433.432754515434)
2023-12-25 12:16:10,898 - {modules.common.store._api:26} - {DEBUG:device0} - Raw data CounterState(currents=[4.129059791564941, 2.5628843307495117, 3.8393774032592773], powers=[920.2191772460938, 541.1864624023438, 872.226318359375], voltages=[229.40951538085938, 229.78579711914062, 229.9252166748047], power_factors=[0.9765422344207764, 0.9204603433609009, 0.999189555644989], imported=82502.15379299084, exported=29433.432754515434, power=2333.6319580078125, frequency=49.943992614746094)
2023-12-25 12:16:10,932 - {modules.devices.sma_sunny_boy.inverter:72} - {DEBUG:device1} - WR 192.168.1.71: InverterState(currents=[0.0, 0.0, 0.0], power=-1789, exported=117078169, dc_power=-1794)
2023-12-25 12:16:10,932 - {modules.common.store._api:26} - {DEBUG:device1} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-1789, exported=117078169, dc_power=-1794)
2023-12-25 12:16:10,936 - {modules.common.component_context:25} - {DEBUG:update values cp1} - Update Komponente ['Ladepunkt']
2023-12-25 12:16:10,936 - {modules.common.store._api:30} - {INFO:update values cp1} - Saving ChargepointState(currents=[6.032392, 4.763788, 5.247105], powers=[1378.5137744912, 1093.4884723384, 1203.037344822], voltages=[228.5186, 229.5418, 229.2764], frequency=50, imported=2695720.0, exported=0, power=3670.0, phases_in_use=3, charge_state=True, plug_state=True, rfid=None, rfid_timestamp=None, power_factors=[0.0, 0.0, 0.0], soc=None, soc_timestamp=None, evse_current=None, vehicle_id=None)
2023-12-25 12:16:10,937 - {modules.common.component_context:25} - {DEBUG:component2} - Update Komponente ['Sma Sunny Boy/Tripower Wechselrichter']
2023-12-25 12:16:10,937 - {modules.common.store._api:26} - {DEBUG:component2} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-1789, exported=117078169, dc_power=None)
2023-12-25 12:16:10,937 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-1789, exported=117078169, dc_power=None)
2023-12-25 12:16:10,965 - {modules.common.component_context:25} - {DEBUG:component0} - Update Komponente ['EVU-Kit flex']
2023-12-25 12:16:10,965 - {modules.common.store._api:30} - {INFO:component0} - Saving CounterState(currents=[4.129059791564941, 2.5628843307495117, 3.8393774032592773], powers=[920.2191772460938, 541.1864624023438, 872.226318359375], voltages=[229.40951538085938, 229.78579711914062, 229.9252166748047], power_factors=[0.9765422344207764, 0.9204603433609009, 0.999189555644989], imported=82502.15379299084, exported=29433.432754515434, power=2333.6319580078125, frequency=49.943992614746094)
2023-12-25 12:16:11,003 - {modules.ripple_control_receiver:30} - {DEBUG:MainThread} - RSE1-Status: False, RSE2-Status: False
2023-12-25 12:16:11,008 - {control.counter:138} - {INFO:MainThread} - Verbleibende Ströme an Zähler 0: [36.9, 37.2, 36.41]A
2023-12-25 12:16:11,008 - {control.counter:161} - {INFO:MainThread} - Verbleibende Leistung an Zähler 0: 25336.368041992188W
2023-12-25 12:16:11,008 - {control.chargepoint.chargepoint:730} - {WARNING:MainThread} - Das Fahrzeug darf nur geändert werden, wenn noch nicht geladen wurde.
2023-12-25 12:16:11,009 - {control.chargepoint.chargepoint:525} - {DEBUG:MainThread} - EV-Phasenzahl beschränkt die nutzbaren Phasen auf 3
2023-12-25 12:16:11,009 - {control.chargepoint.chargepoint:651} - {INFO:MainThread} - LP 1, EV: iX1 (EV-Nr.1): Theoretisch benötigter Strom 6A, Lademodus pv_charging, Submodus: Chargemode.PV_CHARGING, Phasen: 3, Priorität: False, max. Ist-Strom: 6.03
2023-12-25 12:16:11,010 - {modules.common.simcount._simcount:37} - {DEBUG:MainThread} - Previous state: SimCounterState(timestamp=1703502961.2473986, power=472.12713623046875, imported=94340.09419612566, exported=0)
2023-12-25 12:16:11,011 - {modules.common.simcount._calculate:11} - {DEBUG:MainThread} - time passed: 0.00271201, power1: 472.127, power2: 452.632
2023-12-25 12:16:11,011 - {modules.common.simcount._calculate:27} - {DEBUG:MainThread} - power did not change sign. Total energy: 1.25398
2023-12-25 12:16:11,011 - {modules.common.simcount._simcount:46} - {DEBUG:MainThread} - imported: 1.25398 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1703502971.010617, power=452.6319580078125, imported=94341.34817182108, exported=0)
2023-12-25 12:16:11,016 - {control.data:236} - {INFO:MainThread} - bat_all_data
BatAllData(config=Config(configured=False), get=Get(soc=0, daily_exported=0, daily_imported=0, fault_str='Kein Fehler.', fault_state=0, imported=0, exported=0, power=0), set=Set(charging_power_left=0, regulate_up=False, switch_on_soc_reached=False))
2023-12-25 12:16:11,016 - {control.data:237} - {INFO:MainThread} - cp_all_data
AllChargepointData(get=AllGet(daily_imported=2740.0, daily_exported=0, power=3670.0, imported=2695720.0, exported=0))
2023-12-25 12:16:11,017 - {control.data:266} - {INFO:MainThread} - cp1
ChargepointData(control_parameter=ControlParameter(chargemode=<Chargemode.PV_CHARGING: 'pv_charging'>, current_plan=None, failed_phase_switches=0, imported_at_plan_start=None, imported_instant_charging=None, limit=None, phases=3, prio=False, required_current=6, required_currents=[6, 6, 6], state=5, submode=<Chargemode.PV_CHARGING: 'pv_charging'>, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, timestamp_switch_on_off=1703502481.335903), 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=56), currents=[6.03, 4.76, 5.25], daily_imported=2740.0, daily_exported=0, evse_current=None, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=2695720.0, phases_in_use=3, plug_state=True, power=3670.0, rfid_timestamp=None, rfid=None, soc=None, soc_timestamp=None, state_str=None, vehicle_id=None, voltages=[228.52, 229.54, 229.28]), set=Set(change_ev_permitted=[False, 'Das Fahrzeug darf nur geändert werden, wenn noch nicht geladen wurde.                             Bitte abstecken, dann wird das gewählte Fahrzeug verwendet.'], charging_ev=2, charging_ev_prev=2, current=6, energy_to_charge=0, loadmanagement_available=True, log=Log(chargemode_log_entry='pv_charging', imported_at_mode_switch=2692910.0, imported_at_plugtime=2692910.0, imported_since_mode_switch=2810.0, imported_since_plugged=2810.0, range_charged=16.52941176470588, time_charged='0:33', timestamp_start_charging=1703500951.535271), manual_lock=False, phases_to_use=1, plug_state_prev=True, plug_time=1703500901.265379, required_power=4140, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0x7fec24328460>), config=Config(configuration={'ip_address': '192.168.1.82', 'timeout': 2}, ev=1, name='Garage', type='smartwb', template=1, connected_phases=3, phase_1=1, auto_phase_switch_hw=False, control_pilot_interruption_hw=True, id=1))
2023-12-25 12:16:11,017 - {control.data:266} - {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-Profil', rfid_enabling=False, valid_tags=[])
2023-12-25 12:16:11,018 - {control.data:266} - {INFO:MainThread} - cpt1
CpTemplateData(autolock=Autolock(active=False, plans={}, wait_for_charging_end=False), id=1, max_current_multi_phases=16, max_current_single_phase=16, name='smartWB', rfid_enabling=False, valid_tags=[])
2023-12-25 12:16:11,018 - {control.data:266} - {INFO:MainThread} - counter0
CounterData(config=Config(max_currents=[35, 35, 35], max_total_power=24000), get=Get(powers=[920.22, 541.19, 872.23], currents=[4.13, 2.56, 3.84], voltages=[229.41, 229.79, 229.93], power_factors=[0.98, 0.92, 1.0], unbalanced_load=0, frequency=49.943992614746094, daily_exported=3160.410247970758, daily_imported=4074.43371902483, imported=82502.15379299084, exported=29433.432754515434, fault_state=0, fault_str='Kein Fehler.', power=2333.6319580078125), set=Set(error_counter=0, reserved_surplus=0, released_surplus=4140, raw_power_left=25336.368041992188, raw_currents_left=[36.9, 37.2, 36.41], surplus_power_left=0, state_str=''))
2023-12-25 12:16:11,022 - {control.data:241} - {INFO:MainThread} - counter_all_data
CounterAllData(config=Config(reserve_for_not_charging=False), get=Get(hierarchy=[{'id': 0, 'type': 'counter', 'children': [{'id': 1, 'type': 'cp', 'children': []}, {'id': 2, 'type': 'inverter', 'children': []}]}]), set=Set(loadmanagement_active=False, home_consumption=452.6319580078125, invalid_home_consumption=0, daily_yield_home_consumption=6087.41227714959, imported_home_consumption=94341.34817182108, disengageable_smarthome_power=0))
2023-12-25 12:16:11,022 - {control.data:266} - {INFO:MainThread} - ct0
ChargeTemplateData(name='Standard-Lade-Profil', 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=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-12-25 12:16:11,023 - {control.data:266} - {INFO:MainThread} - ct1
ChargeTemplateData(name='i3', 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=50, max_soc=80), scheduled_charging=ScheduledCharging(plans={}), instant_charging=InstantCharging(current=10, limit=Limit(selected='soc', amount=1000, soc=80))))
2023-12-25 12:16:11,023 - {control.data:266} - {INFO:MainThread} - ct2
ChargeTemplateData(name='iX1', 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=40, max_soc=101), scheduled_charging=ScheduledCharging(plans={}), instant_charging=InstantCharging(current=6, limit=Limit(selected='none', amount=1000, soc=80))))
2023-12-25 12:16:11,023 - {control.data:266} - {INFO:MainThread} - ev0
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='Standard-Fahrzeug-Profil', 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, efficiency=90, nominal_difference=1, keep_charge_active_duration=40), et_num=0), soc_error_counter=0), charge_template=0, ev_template=0, name='Standard-Fahrzeug', tag_id=[], get=Get(soc=0, soc_timestamp=0, force_soc_update=False, range=0, fault_state=0, fault_str='Kein Fehler.'))
2023-12-25 12:16:11,024 - {control.data:266} - {INFO:MainThread} - ev1
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='i3', 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=16500, min_current=6, max_current_single_phase=16, battery_capacity=38000, efficiency=86, nominal_difference=1, keep_charge_active_duration=40), et_num=0), soc_error_counter=0), charge_template=1, ev_template=1, name='i3', tag_id=[], get=Get(soc=52, soc_timestamp=1703502132.116945, force_soc_update=False, range=97.0, fault_state=0, fault_str='Kein Fehler.'))
2023-12-25 12:16:11,024 - {control.data:266} - {INFO:MainThread} - ev2
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='iX1', 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=62000, efficiency=90, nominal_difference=2, keep_charge_active_duration=40), et_num=0), soc_error_counter=0), charge_template=2, ev_template=2, name='iX1', tag_id=[], get=Get(soc=56, soc_timestamp=1703502696.850873, force_soc_update=False, range=223.0, fault_state=0, fault_str='Kein Fehler.'))
2023-12-25 12:16:11,024 - {control.data:266} - {INFO:MainThread} - et0
EvTemplateData(name='Standard-Fahrzeug-Profil', 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=82000, efficiency=90, nominal_difference=1, keep_charge_active_duration=40)
2023-12-25 12:16:11,025 - {control.data:266} - {INFO:MainThread} - et1
EvTemplateData(name='i3', 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=16000, min_current=6, max_current_single_phase=16, battery_capacity=38000, efficiency=86, nominal_difference=2, keep_charge_active_duration=40)
2023-12-25 12:16:11,026 - {control.data:266} - {INFO:MainThread} - et2
EvTemplateData(name='iX1', 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=62000, efficiency=90, nominal_difference=2, keep_charge_active_duration=40)
2023-12-25 12:16:11,026 - {control.data:245} - {INFO: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=[400, 0], feed_in_yield=15000, phase_switch_delay=7, phases_to_use=3, rundown_power=1000, rundown_soc=50, switch_off_delay=480, 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=3), time_charging=TimeCharging(phases_to_use=3), unbalanced_load_limit=20, unbalanced_load=True), control_interval=10, extern_display_mode='primary', 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.3, range_unit='km', ripple_control_receiver=RippleControlReceiver(configured=True, r1_active=False, r2_active=False))
2023-12-25 12:16:11,027 - {control.data:246} - {INFO:MainThread} - general_data-display
primary
2023-12-25 12:16:11,027 - {control.data:247} - {INFO:MainThread} - graph_data
GraphData(config=Config(duration=120))
2023-12-25 12:16:11,027 - {control.data:248} - {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=False, on_if_plugged_in=True, pin_active=False, pin_code='0000', standby=60, theme=<modules.display_themes.cards.config.CardsDisplayTheme object at 0x7fec24393310>), led=Led(active=False), rfid=Rfid(active=False))
2023-12-25 12:16:11,027 - {control.data:266} - {INFO:MainThread} - pv2
PvData(config=Config(max_ac_out=9000), get=Get(currents=[0.0, 0.0, 0.0], daily_exported=7922, monthly_exported=0, yearly_exported=0, exported=117078169, fault_state=0, fault_str='Kein Fehler.', power=-1789))
2023-12-25 12:16:11,027 - {control.data:250} - {INFO:MainThread} - pv_all_data
PvAllData(config=Config(configured=True), get=Get(daily_exported=7922, monthly_exported=0, yearly_exported=0, exported=117078169, power=-1789))
2023-12-25 12:16:11,027 - {control.data:266} - {INFO:MainThread} - system
{'update_in_progress': False, 'perform_update': False, 'ip_address': '192.168.1.80', 'current_commit': '2023-12-22 12:29:57 +0100 [2d75c6556]', 'current_branch': 'master', 'current_branch_commit': '2023-12-22 12:29:57 +0100 [2d75c6556]', 'current_missing_commits': [], 'boot_done': True, 'dataprotection_acknowledged': True, 'release_train': 'master', 'version': '2.1.3-Alpha.2', 'usage_terms_acknowledged': True, 'lastlivevaluesJson': {'timestamp': 1703502961, 'time': '12:16:01', 'grid': 2.37, 'house-power': 0.472, 'charging-all': 3.67, 'pv-all': 1.772, 'cp1-power': 3.67, 'ev1-soc': 52, 'ev2-soc': 56}, 'datastore_version': 31, 'debug_level': 10, 'module_update_completed': True}
2023-12-25 12:16:11,028 - {control.data:279} - {INFO:MainThread} - device0
{'name': 'openWB-Kit flex', 'type': 'openwb_flex', 'id': 0, 'configuration': {'port': 8899, 'ip_address': '192.168.1.81'}}
2023-12-25 12:16:11,028 - {control.data:281} - {INFO:MainThread} - component0
{'name': 'EVU-Kit flex', 'type': 'counter', 'id': 0, 'configuration': {'id': 115, 'version': 2}}
2023-12-25 12:16:11,029 - {control.data:279} - {INFO:MainThread} - device1
{'name': 'SMA Sunny Boy/Tripower', 'type': 'sma_sunny_boy', 'id': 1, 'configuration': {'ip_address': '192.168.1.71'}}
2023-12-25 12:16:11,032 - {control.data:281} - {INFO:MainThread} - component2
{'name': 'Sma Sunny Boy/Tripower Wechselrichter', 'type': 'inverter', 'id': 2, 'configuration': {'hybrid': False, 'version': 0}}
2023-12-25 12:16:11,033 - {control.data:253} - {INFO:MainThread} - 

2023-12-25 12:16:11,033 - {control.algorithm.algorithm:25} - {INFO:MainThread} - # Algorithmus
2023-12-25 12:16:11,033 - {control.counter:197} - {INFO:MainThread} - Überschuss zur PV-geführten Ladung: 2333.6319580078125W
2023-12-25 12:16:11,034 - {control.counter:360} - {DEBUG:MainThread} - LP1 Switch-Off-Threshold prüfen: -1806.3680419921875W, Schwelle: 50W, freigegebener Überschuss 4140W, Einspeisegrenze 0W
2023-12-25 12:16:11,034 - {control.chargepoint.chargepoint:83} - {INFO:MainThread} - LP 1: Ladevorgang nach Ablauf der Abschaltverzögerung gestoppt.
2023-12-25 12:16:11,034 - {control.algorithm.algorithm:30} - {INFO:MainThread} - **Mindestrom setzen**
2023-12-25 12:16:11,035 - {control.algorithm.min_current:18} - {INFO:MainThread} - Mode-Tuple Chargemode.PV_CHARGING - Chargemode.PV_CHARGING - False, Zähler 0
2023-12-25 12:16:11,037 - {control.algorithm.algorithm:32} - {INFO:MainThread} - **Sollstrom setzen**
2023-12-25 12:16:11,038 - {control.counter:210} - {INFO:MainThread} - Überschuss zur PV-geführten Ladung: -797.263916015625W
2023-12-25 12:16:11,038 - {control.counter:465} - {DEBUG:MainThread} - Zähler 0: Begrenzung der verbleibenden Leistung auf -797.263916015625W
2023-12-25 12:16:11,038 - {control.chargepoint.chargepoint:83} - {INFO:MainThread} - LP 1: Die Ladung kann nicht gestartet werden, da die Einschaltschwelle nicht erreicht wird.
2023-12-25 12:16:11,038 - {control.algorithm.algorithm:43} - {INFO:MainThread} - **Keine Leistung für PV-geführtes Laden übrig.**
2023-12-25 12:16:11,039 - {control.process:23} - {INFO:MainThread} - # Ladung starten.
2023-12-25 12:16:11,039 - {control.chargelog:53} - {DEBUG:MainThread} - imported_since_mode_switch 2810.0 counter 2695720.0
2023-12-25 12:16:11,039 - {control.process:111} - {INFO:MainThread} - LP1: set current 0 A, state NO_CHARGING_ALLOWED
2023-12-25 12:16:11,040 - {modules.common.component_context:25} - {DEBUG:set current cp1} - Update Komponente ['Ladepunkt']
2023-12-25 12:16:11,042 - {urllib3.connectionpool:227} - {DEBUG:set current cp1} - Starting new HTTP connection (1): 192.168.1.82:80
2023-12-25 12:16:11,135 - {urllib3.connectionpool:452} - {DEBUG:set current cp1} - http://192.168.1.82:80 "GET /setCurrent?current=0 HTTP/1.1" 200 29
2023-12-25 12:16:11,136 - {modules.common.req:11} - {DEBUG:set current cp1} - Get-Response: S0_set current to given value
2023-12-25 12:16:11,139 - {control.counter:186} - {INFO:MainThread} - 0W reservierte EVU-Leistung, 0W freigegebene EVU-Leistung
2023-12-25 12:16:11,227 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/1/control_parameter/state, Payload 0, vorherige Payload: 5
2023-12-25 12:16:11,228 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/1/control_parameter/timestamp_switch_on_off, Payload None, vorherige Payload: 1703502481.335903
2023-12-25 12:16:11,229 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2023-12-25 12:16:15,254 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2023-12-25 12:16:20,261 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})

2023-12-25 12:16:20,262 - {root:74} - {INFO:MainThread} - # ***Start*** 

2023-12-25 12:16:20,270 - {modules.devices.openwb_flex.device:50} - {DEBUG:device0} - Start device reading {'component0': <modules.devices.openwb_flex.counter.EvuKitFlex object at 0x7fec2d58d4f0>}
2023-12-25 12:16:20,270 - {modules.common.component_context:25} - {DEBUG:device0} - Update Komponente ['EVU-Kit flex']
2023-12-25 12:16:20,272 - {modules.devices.sma_sunny_boy.device:72} - {DEBUG:device1} - Start device reading {'component2': <modules.devices.sma_sunny_boy.inverter.SmaSunnyBoyInverter object at 0x7fec26dcc760>}
2023-12-25 12:16:20,273 - {modules.common.component_context:25} - {DEBUG:set values cp1} - Update Komponente ['Ladepunkt']
2023-12-25 12:16:20,276 - {urllib3.connectionpool:227} - {DEBUG:set values cp1} - Starting new HTTP connection (1): 192.168.1.82:80
2023-12-25 12:16:20,291 - {modules.common.component_context:25} - {DEBUG:device1} - Update Komponente ['Sma Sunny Boy/Tripower Wechselrichter']
2023-12-25 12:16:20,303 - {urllib3.connectionpool:452} - {DEBUG:set values cp1} - http://192.168.1.82:80 "GET /getParameters HTTP/1.1" 200 407
2023-12-25 12:16:20,304 - {modules.common.req:11} - {DEBUG:set values cp1} - Get-Response: {"type":"parameters","list":[{"vehicleState":2,"evseState":false,"maxCurrent":16,"actualCurrent":0,"actualCurrentMA":0,"actualPower":0,"duration":2022000,"alwaysActive":true,"lastActionUser":"API","lastActionUID":"API","energy":2.81,"mileage":18.8,"meterReading":2695.73,"currentP1":0,"currentP2":0,"currentP3":0,"voltageP1":230.2081,"voltageP2":231.1272,"voltageP3":229.1916,"useMeter":true,"RFIDUID":""}]}
2023-12-25 12:16:20,304 - {modules.common.store._api:26} - {DEBUG:set values cp1} - Raw data ChargepointState(currents=[0, 0, 0], powers=[0.0, 0.0, 0.0], voltages=[230.2081, 231.1272, 229.1916], frequency=50, imported=2695730.0, exported=0, power=0, phases_in_use=1, charge_state=False, plug_state=True, rfid=None, rfid_timestamp=None, power_factors=[0.0, 0.0, 0.0], soc=None, soc_timestamp=None, evse_current=None, vehicle_id=None)
2023-12-25 12:16:20,756 - {modules.common.simcount._simcount:37} - {DEBUG:device0} - Previous state: SimCounterState(timestamp=1703502970.8970995, power=2333.6319580078125, imported=82502.15379299084, exported=29433.432754515434)
2023-12-25 12:16:20,756 - {modules.common.simcount._calculate:11} - {DEBUG:device0} - time passed: 0.00273858, power1: 2333.63, power2: -1358.02
2023-12-25 12:16:20,756 - {modules.common.simcount._calculate:24} - {DEBUG:device0} - power changed sign. Total energy: 1.3359, exported energy: -0.684047
2023-12-25 12:16:20,756 - {modules.common.simcount._simcount:46} - {DEBUG:device0} - imported: 2.01995 Wh, exported: 0.684047 Wh, new state: SimCounterState(timestamp=1703502980.7559998, power=-1358.0170288085938, imported=82504.17374153133, exported=29434.116801621672)
2023-12-25 12:16:20,757 - {modules.common.store._api:26} - {DEBUG:device0} - Raw data CounterState(currents=[-2.0333304405212402, -2.449211597442627, -1.4800819158554077], powers=[-462.8064270019531, -556.5111694335938, -338.6994323730469], voltages=[229.25616455078125, 230.69482421875, 229.66983032226562], power_factors=[-0.9883987903594971, -0.9834429025650024, -0.9981078505516052], imported=82504.17374153133, exported=29434.116801621672, power=-1358.0170288085938, frequency=49.946895599365234)
2023-12-25 12:16:20,874 - {modules.devices.sma_sunny_boy.inverter:72} - {DEBUG:device1} - WR 192.168.1.71: InverterState(currents=[0.0, 0.0, 0.0], power=-1786, exported=117078174, dc_power=-1787)
2023-12-25 12:16:20,874 - {modules.common.store._api:26} - {DEBUG:device1} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-1786, exported=117078174, dc_power=-1787)
2023-12-25 12:16:20,876 - {modules.common.component_context:25} - {DEBUG:update values cp1} - Update Komponente ['Ladepunkt']
2023-12-25 12:16:20,876 - {modules.common.store._api:30} - {INFO:update values cp1} - Saving ChargepointState(currents=[0, 0, 0], powers=[0.0, 0.0, 0.0], voltages=[230.2081, 231.1272, 229.1916], frequency=50, imported=2695730.0, exported=0, power=0, phases_in_use=1, charge_state=False, plug_state=True, rfid=None, rfid_timestamp=None, power_factors=[0.0, 0.0, 0.0], soc=None, soc_timestamp=None, evse_current=None, vehicle_id=None)
2023-12-25 12:16:20,878 - {modules.common.component_context:25} - {DEBUG:component2} - Update Komponente ['Sma Sunny Boy/Tripower Wechselrichter']
2023-12-25 12:16:20,879 - {modules.common.store._api:26} - {DEBUG:component2} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-1786, exported=117078174, dc_power=None)
2023-12-25 12:16:20,879 - {modules.common.store._api:30} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-1786, exported=117078174, dc_power=None)
2023-12-25 12:16:20,908 - {modules.common.component_context:25} - {DEBUG:component0} - Update Komponente ['EVU-Kit flex']
2023-12-25 12:16:20,909 - {modules.common.store._api:30} - {INFO:component0} - Saving CounterState(currents=[-2.0333304405212402, -2.449211597442627, -1.4800819158554077], powers=[-462.8064270019531, -556.5111694335938, -338.6994323730469], voltages=[229.25616455078125, 230.69482421875, 229.66983032226562], power_factors=[-0.9883987903594971, -0.9834429025650024, -0.9981078505516052], imported=82504.17374153133, exported=29434.116801621672, power=-1358.0170288085938, frequency=49.946895599365234)
2023-12-25 12:16:20,929 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/1/control_parameter/state, Payload 0, vorherige Payload: 5
2023-12-25 12:16:20,929 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/1/control_parameter/timestamp_switch_on_off, Payload None, vorherige Payload: 1703502481.335903
2023-12-25 12:16:20,949 - {modules.ripple_control_receiver:30} - {DEBUG:MainThread} - RSE1-Status: False, RSE2-Status: False
2023-12-25 12:16:20,950 - {control.counter:138} - {INFO:MainThread} - Verbleibende Ströme an Zähler 0: [37.03, 37.45, 36.48]A
2023-12-25 12:16:20,950 - {control.counter:161} - {INFO:MainThread} - Verbleibende Leistung an Zähler 0: 25358.017028808594W
2023-12-25 12:16:20,952 - {control.chargepoint.chargepoint:730} - {WARNING:MainThread} - Das Fahrzeug darf nur geändert werden, wenn noch nicht geladen wurde.
2023-12-25 12:16:20,952 - {control.chargepoint.chargepoint:525} - {DEBUG:MainThread} - EV-Phasenzahl beschränkt die nutzbaren Phasen auf 3
2023-12-25 12:16:20,952 - {control.chargepoint.chargepoint:543} - {INFO:MainThread} - Phasenumschaltung an Ladepunkt 1 wird durch die Hardware nicht unterstützt.
2023-12-25 12:16:20,953 - {control.chargepoint.chargepoint:651} - {INFO:MainThread} - LP 1, EV: iX1 (EV-Nr.1): Theoretisch benötigter Strom 6A, Lademodus pv_charging, Submodus: Chargemode.PV_CHARGING, Phasen: 1, Priorität: False, max. Ist-Strom: 0
2023-12-25 12:16:20,954 - {modules.common.simcount._simcount:37} - {DEBUG:MainThread} - Previous state: SimCounterState(timestamp=1703502971.010617, power=452.6319580078125, imported=94341.34817182108, exported=0)
2023-12-25 12:16:20,954 - {modules.common.simcount._calculate:11} - {DEBUG:MainThread} - time passed: 0.00276208, power1: 452.632, power2: 427.983
2023-12-25 12:16:20,954 - {modules.common.simcount._calculate:27} - {DEBUG:MainThread} - power did not change sign. Total energy: 1.21616
2023-12-25 12:16:20,954 - {modules.common.simcount._simcount:46} - {DEBUG:MainThread} - imported: 1.21616 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1703502980.9541054, power=427.98297119140625, imported=94342.56433630687, exported=0)
2023-12-25 12:16:20,954 - {control.data:236} - {INFO:MainThread} - bat_all_data
BatAllData(config=Config(configured=False), get=Get(soc=0, daily_exported=0, daily_imported=0, fault_str='Kein Fehler.', fault_state=0, imported=0, exported=0, power=0), set=Set(charging_power_left=0, regulate_up=False, switch_on_soc_reached=False))
2023-12-25 12:16:20,955 - {control.data:237} - {INFO:MainThread} - cp_all_data
AllChargepointData(get=AllGet(daily_imported=2740.0, daily_exported=0, power=0, imported=2695730.0, exported=0))
2023-12-25 12:16:20,955 - {control.data:266} - {INFO:MainThread} - cp1
ChargepointData(control_parameter=ControlParameter(chargemode=<Chargemode.PV_CHARGING: 'pv_charging'>, current_plan=None, failed_phase_switches=0, imported_at_plan_start=None, imported_instant_charging=None, limit=None, phases=1, prio=False, required_current=6, required_currents=[6, 0.0, 0.0], state=0, submode=<Chargemode.PV_CHARGING: 'pv_charging'>, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, timestamp_switch_on_off=None), 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=56), currents=[0, 0, 0], daily_imported=2740.0, daily_exported=0, evse_current=None, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=2695730.0, phases_in_use=1, plug_state=True, power=0, rfid_timestamp=None, rfid=None, soc=None, soc_timestamp=None, state_str=None, vehicle_id=None, voltages=[230.21, 231.13, 229.19]), set=Set(change_ev_permitted=[False, 'Das Fahrzeug darf nur geändert werden, wenn noch nicht geladen wurde.                             Bitte abstecken, dann wird das gewählte Fahrzeug verwendet.'], 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=2692910.0, imported_at_plugtime=2692910.0, imported_since_mode_switch=2810.0, imported_since_plugged=2810.0, range_charged=16.52941176470588, time_charged='0:33', timestamp_start_charging=1703500951.535271), manual_lock=False, phases_to_use=1, plug_state_prev=True, plug_time=1703500901.265379, required_power=1380.0, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0x7fec243e1040>), config=Config(configuration={'ip_address': '192.168.1.82', 'timeout': 2}, ev=1, name='Garage', type='smartwb', template=1, connected_phases=3, phase_1=1, auto_phase_switch_hw=False, control_pilot_interruption_hw=True, id=1))
2023-12-25 12:16:20,955 - {control.data:266} - {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-Profil', rfid_enabling=False, valid_tags=[])
2023-12-25 12:16:20,955 - {control.data:266} - {INFO:MainThread} - cpt1
CpTemplateData(autolock=Autolock(active=False, plans={}, wait_for_charging_end=False), id=1, max_current_multi_phases=16, max_current_single_phase=16, name='smartWB', rfid_enabling=False, valid_tags=[])
2023-12-25 12:16:20,957 - {control.data:266} - {INFO:MainThread} - counter0
CounterData(config=Config(max_currents=[35, 35, 35], max_total_power=24000), get=Get(powers=[-462.81, -556.51, -338.7], currents=[-2.03, -2.45, -1.48], voltages=[229.26, 230.69, 229.67], power_factors=[-0.99, -0.98, -1.0], unbalanced_load=0, frequency=49.946895599365234, daily_exported=3160.410247970758, daily_imported=4074.43371902483, imported=82504.17374153133, exported=29434.116801621672, fault_state=0, fault_str='Kein Fehler.', power=-1358.0170288085938), set=Set(error_counter=0, reserved_surplus=0, released_surplus=0, raw_power_left=25358.017028808594, raw_currents_left=[37.03, 37.45, 36.48], surplus_power_left=0, state_str=''))
2023-12-25 12:16:20,958 - {control.data:241} - {INFO:MainThread} - counter_all_data
CounterAllData(config=Config(reserve_for_not_charging=False), get=Get(hierarchy=[{'id': 0, 'type': 'counter', 'children': [{'id': 1, 'type': 'cp', 'children': []}, {'id': 2, 'type': 'inverter', 'children': []}]}]), set=Set(loadmanagement_active=False, home_consumption=427.98297119140625, invalid_home_consumption=0, daily_yield_home_consumption=6087.41227714959, imported_home_consumption=94342.56433630687, disengageable_smarthome_power=0))
2023-12-25 12:16:20,958 - {control.data:266} - {INFO:MainThread} - ct0
ChargeTemplateData(name='Standard-Lade-Profil', 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=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-12-25 12:16:20,958 - {control.data:266} - {INFO:MainThread} - ct1
ChargeTemplateData(name='i3', 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=50, max_soc=80), scheduled_charging=ScheduledCharging(plans={}), instant_charging=InstantCharging(current=10, limit=Limit(selected='soc', amount=1000, soc=80))))
2023-12-25 12:16:20,959 - {control.data:266} - {INFO:MainThread} - ct2
ChargeTemplateData(name='iX1', 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=40, max_soc=101), scheduled_charging=ScheduledCharging(plans={}), instant_charging=InstantCharging(current=6, limit=Limit(selected='none', amount=1000, soc=80))))
2023-12-25 12:16:20,959 - {control.data:266} - {INFO:MainThread} - ev0
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='Standard-Fahrzeug-Profil', 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, efficiency=90, nominal_difference=1, keep_charge_active_duration=40), et_num=0), soc_error_counter=0), charge_template=0, ev_template=0, name='Standard-Fahrzeug', tag_id=[], get=Get(soc=0, soc_timestamp=0, force_soc_update=False, range=0, fault_state=0, fault_str='Kein Fehler.'))
2023-12-25 12:16:20,959 - {control.data:266} - {INFO:MainThread} - ev1
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='i3', 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=16500, min_current=6, max_current_single_phase=16, battery_capacity=38000, efficiency=86, nominal_difference=1, keep_charge_active_duration=40), et_num=0), soc_error_counter=0), charge_template=1, ev_template=1, name='i3', tag_id=[], get=Get(soc=52, soc_timestamp=1703502132.116945, force_soc_update=False, range=97.0, fault_state=0, fault_str='Kein Fehler.'))
2023-12-25 12:16:20,960 - {control.data:266} - {INFO:MainThread} - ev2
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='iX1', 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=62000, efficiency=90, nominal_difference=2, keep_charge_active_duration=40), et_num=0), soc_error_counter=0), charge_template=2, ev_template=2, name='iX1', tag_id=[], get=Get(soc=56, soc_timestamp=1703502696.850873, force_soc_update=False, range=223.0, fault_state=0, fault_str='Kein Fehler.'))
2023-12-25 12:16:20,961 - {control.data:266} - {INFO:MainThread} - et0
EvTemplateData(name='Standard-Fahrzeug-Profil', 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=82000, efficiency=90, nominal_difference=1, keep_charge_active_duration=40)
2023-12-25 12:16:20,961 - {control.data:266} - {INFO:MainThread} - et1
EvTemplateData(name='i3', 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=16000, min_current=6, max_current_single_phase=16, battery_capacity=38000, efficiency=86, nominal_difference=2, keep_charge_active_duration=40)
2023-12-25 12:16:20,961 - {control.data:266} - {INFO:MainThread} - et2
EvTemplateData(name='iX1', 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=62000, efficiency=90, nominal_difference=2, keep_charge_active_duration=40)
2023-12-25 12:16:20,961 - {control.data:245} - {INFO: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=[400, 0], feed_in_yield=15000, phase_switch_delay=7, phases_to_use=3, rundown_power=1000, rundown_soc=50, switch_off_delay=480, 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=3), time_charging=TimeCharging(phases_to_use=3), unbalanced_load_limit=20, unbalanced_load=True), control_interval=10, extern_display_mode='primary', 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.3, range_unit='km', ripple_control_receiver=RippleControlReceiver(configured=True, r1_active=False, r2_active=False))
2023-12-25 12:16:20,962 - {control.data:246} - {INFO:MainThread} - general_data-display
primary
2023-12-25 12:16:20,962 - {control.data:247} - {INFO:MainThread} - graph_data
GraphData(config=Config(duration=120))
2023-12-25 12:16:20,962 - {control.data:248} - {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=False, on_if_plugged_in=True, pin_active=False, pin_code='0000', standby=60, theme=<modules.display_themes.cards.config.CardsDisplayTheme object at 0x7fec244792b0>), led=Led(active=False), rfid=Rfid(active=False))
2023-12-25 12:16:20,963 - {control.data:266} - {INFO:MainThread} - pv2
PvData(config=Config(max_ac_out=9000), get=Get(currents=[0.0, 0.0, 0.0], daily_exported=7922, monthly_exported=0, yearly_exported=0, exported=117078174, fault_state=0, fault_str='Kein Fehler.', power=-1786))
2023-12-25 12:16:20,963 - {control.data:250} - {INFO:MainThread} - pv_all_data
PvAllData(config=Config(configured=True), get=Get(daily_exported=7922, monthly_exported=0, yearly_exported=0, exported=117078174, power=-1786))
2023-12-25 12:16:20,963 - {control.data:266} - {INFO:MainThread} - system
{'update_in_progress': False, 'perform_update': False, 'ip_address': '192.168.1.80', 'current_commit': '2023-12-22 12:29:57 +0100 [2d75c6556]', 'current_branch': 'master', 'current_branch_commit': '2023-12-22 12:29:57 +0100 [2d75c6556]', 'current_missing_commits': [], 'boot_done': True, 'dataprotection_acknowledged': True, 'release_train': 'master', 'version': '2.1.3-Alpha.2', 'usage_terms_acknowledged': True, 'lastlivevaluesJson': {'timestamp': 1703502971, 'time': '12:16:11', 'grid': 2.334, 'house-power': 0.453, 'charging-all': 3.67, 'pv-all': 1.789, 'cp1-power': 3.67, 'ev1-soc': 52, 'ev2-soc': 56}, 'datastore_version': 31, 'debug_level': 10, 'module_update_completed': True}
2023-12-25 12:16:20,964 - {control.data:279} - {INFO:MainThread} - device0
{'name': 'openWB-Kit flex', 'type': 'openwb_flex', 'id': 0, 'configuration': {'port': 8899, 'ip_address': '192.168.1.81'}}
2023-12-25 12:16:20,964 - {control.data:281} - {INFO:MainThread} - component0
{'name': 'EVU-Kit flex', 'type': 'counter', 'id': 0, 'configuration': {'id': 115, 'version': 2}}
2023-12-25 12:16:20,964 - {control.data:279} - {INFO:MainThread} - device1
{'name': 'SMA Sunny Boy/Tripower', 'type': 'sma_sunny_boy', 'id': 1, 'configuration': {'ip_address': '192.168.1.71'}}
2023-12-25 12:16:20,964 - {control.data:281} - {INFO:MainThread} - component2
{'name': 'Sma Sunny Boy/Tripower Wechselrichter', 'type': 'inverter', 'id': 2, 'configuration': {'hybrid': False, 'version': 0}}
2023-12-25 12:16:20,965 - {control.data:253} - {INFO:MainThread} - 

2023-12-25 12:16:20,965 - {control.algorithm.algorithm:25} - {INFO:MainThread} - # Algorithmus
2023-12-25 12:16:20,965 - {control.algorithm.algorithm:30} - {INFO:MainThread} - **Mindestrom setzen**
2023-12-25 12:16:20,965 - {control.algorithm.min_current:18} - {INFO:MainThread} - Mode-Tuple Chargemode.PV_CHARGING - Chargemode.PV_CHARGING - False, Zähler 0
2023-12-25 12:16:20,966 - {control.algorithm.algorithm:32} - {INFO:MainThread} - **Sollstrom setzen**
2023-12-25 12:16:20,966 - {control.counter:210} - {INFO:MainThread} - Überschuss zur PV-geführten Ladung: 2916.0340576171875W
2023-12-25 12:16:20,966 - {control.counter:465} - {DEBUG:MainThread} - Zähler 0: Begrenzung der verbleibenden Leistung auf 2916.0340576171875W
2023-12-25 12:16:20,966 - {control.chargepoint.chargepoint:83} - {INFO:MainThread} - LP 1: Die Ladung wird gestartet, sobald nach 30s die Einschaltverzögerung abgelaufen ist.
2023-12-25 12:16:20,967 - {control.algorithm.algorithm:38} - {INFO:MainThread} - **PV-geführten Strom setzen**
2023-12-25 12:16:20,967 - {control.algorithm.filter_chargepoints:116} - {DEBUG:MainThread} - Geordnete Ladepunkte [1]
2023-12-25 12:16:20,967 - {control.algorithm.filter_chargepoints:45} - {INFO:MainThread} - LP 1: Keine Zuteilung des Mindeststroms, daher keine weitere Berücksichtigung
2023-12-25 12:16:20,967 - {control.process:23} - {INFO:MainThread} - # Ladung starten.
2023-12-25 12:16:20,968 - {control.process:111} - {INFO:MainThread} - LP1: set current 0 A, state SWITCH_ON_DELAY
2023-12-25 12:16:20,969 - {modules.common.component_context:25} - {DEBUG:set current cp1} - Update Komponente ['Ladepunkt']
2023-12-25 12:16:20,974 - {urllib3.connectionpool:227} - {DEBUG:set current cp1} - Starting new HTTP connection (1): 192.168.1.82:80
2023-12-25 12:16:20,996 - {urllib3.connectionpool:452} - {DEBUG:set current cp1} - http://192.168.1.82:80 "GET /setCurrent?current=0 HTTP/1.1" 200 29
2023-12-25 12:16:20,997 - {modules.common.req:11} - {DEBUG:set current cp1} - Get-Response: S0_set current to given value
2023-12-25 12:16:20,998 - {control.counter:186} - {INFO:MainThread} - 1500W reservierte EVU-Leistung, 0W freigegebene EVU-Leistung
2023-12-25 12:16:21,085 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/1/control_parameter/phases, Payload 1, vorherige Payload: 3
2023-12-25 12:16:21,086 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/1/control_parameter/required_current, Payload 16, vorherige Payload: 6
2023-12-25 12:16:21,087 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/1/control_parameter/state, Payload 6, vorherige Payload: 0
2023-12-25 12:16:21,087 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/1/control_parameter/timestamp_switch_on_off, Payload 1703502980.96688, vorherige Payload: None
2023-12-25 12:16:21,088 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2023-12-25 12:16:25,107 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2023-12-25 12:16:30,114 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})

Besten Dank im Vorraus!

Ach ja, und noch viele Grüße und schöne Weihnachen!

heidanei

heidanei · Beitrag von **heidanei** » Mi Dez 27, 2023 1:29 pm

ICH KAPIER's NICHT!!!

Heute openWB mit dem originalen "RELEASE" Image (30.11.2023 12:30 Uhr) auf einem Raspi 3B komplett neu aufgesetzt, Konfiguration komplett neu, diesmal das andere Auto, Ergebnis gleich!

Ladeeinstellung:

13:56:01: Abschaltung (nach eingestellten 120s Verzögerung) aufgrund zu wenig PV-Leistung

13:56:11: abgeschaltet, Melldung "Ladung wird gestartet, sobald nach 30s die Einschaltverzögerung abgelaufen ist." - WARUM NUR???

13:56:41: Laden wird gestartet

Code: Alles auswählen

2023-12-27 13:56:00,948 - {root:74} - {INFO:MainThread} - # ***Start*** 
2023-12-27 13:56:00,963 - {modules.devices.openwb_flex.device:50} - {DEBUG:device1} - Start device reading {'component0': <modules.devices.openwb_flex.counter.EvuKitFlex object at 0xf22e5610>}
2023-12-27 13:56:00,963 - {modules.common.component_context:24} - {DEBUG:device1} - Update Komponente ['EVU-Kit flex']
2023-12-27 13:56:00,965 - {modules.devices.sma_sunny_boy.device:72} - {DEBUG:device2} - Start device reading {'component1': <modules.devices.sma_sunny_boy.inverter.SmaSunnyBoyInverter object at 0xf22cb088>}
2023-12-27 13:56:00,966 - {modules.common.component_context:24} - {DEBUG:set values cp2} - Update Komponente ['Ladepunkt']
2023-12-27 13:56:00,970 - {urllib3.connectionpool:227} - {DEBUG:set values cp2} - Starting new HTTP connection (1): 192.168.1.82:80
2023-12-27 13:56:00,978 - {modules.common.component_context:24} - {DEBUG:device2} - Update Komponente ['Sma Sunny Boy/Tripower Wechselrichter']
2023-12-27 13:56:00,996 - {urllib3.connectionpool:452} - {DEBUG:set values cp2} - http://192.168.1.82:80 "GET /getParameters HTTP/1.1" 200 437
2023-12-27 13:56:00,997 - {modules.common.req:11} - {DEBUG:set values cp2} - Get-Response: {"type":"parameters","list":[{"vehicleState":3,"evseState":true,"maxCurrent":16,"actualCurrent":6,"actualCurrentMA":600,"actualPower":4.16,"duration":854000,"alwaysActive":true,"lastActionUser":"vehicle","lastActionUID":"vehicle","energy":0.99,"mileage":6.6,"meterReading":2720.12,"currentP1":5.960157,"currentP2":5.844405,"currentP3":6.25983,"voltageP1":230.8353,"voltageP2":231.1406,"voltageP3":233.1166,"useMeter":true,"RFIDUID":""}]}
2023-12-27 13:56:00,998 - {modules.common.store._api:26} - {DEBUG:set values cp2} - Raw data ChargepointState(currents=[5.960157, 5.844405, 6.25983], powers=[1375.8146291420999, 1350.8792783430001, 1459.270286178], voltages=[230.8353, 231.1406, 233.1166], frequency=50, imported=2720120.0, exported=0, power=4160.0, phases_in_use=3, charge_state=True, plug_state=True, rfid=None, power_factors=[0.0, 0.0, 0.0], soc=None, soc_timestamp=None, evse_current=None)
2023-12-27 13:56:01,444 - {modules.common.simcount._simcount:38} - {DEBUG:device1} - Previous state: SimCounterState(timestamp=1703681751.3853736, power=192.66118621826172, imported=7089.313922674456, exported=8882.803319513401)
2023-12-27 13:56:01,445 - {modules.common.simcount._calculate:13} - {DEBUG:device1} - time passed: 0.00279431, power1: 192.661, power2: 246.632
2023-12-27 13:56:01,445 - {modules.common.simcount._calculate:29} - {DEBUG:device1} - power did not change sign. Total energy: 0.613761
2023-12-27 13:56:01,446 - {modules.common.simcount._simcount:47} - {DEBUG:device1} - imported: 0.613761 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1703681761.4448893, power=246.6323013305664, imported=7089.927683749642, exported=8882.803319513401)
2023-12-27 13:56:01,446 - {modules.common.store._api:26} - {DEBUG:device1} - Raw data CounterState(currents=[-1.12360417842865, 1.5148950815200806, 1.0895500183105469], powers=[-58.205238342285156, 258.07257080078125, 46.76496887207031], voltages=[231.42481994628906, 231.74375915527344, 233.931884765625], power_factors=[-0.2031785249710083, 0.6960097551345825, 0.18198396265506744], imported=7089.927683749642, exported=8882.803319513401, power=246.6323013305664, frequency=49.90970230102539)
2023-12-27 13:56:01,580 - {modules.devices.sma_sunny_boy.inverter:72} - {DEBUG:device2} - WR 192.168.1.71: InverterState(currents=[0.0, 0.0, 0.0], power=-4652, exported=117122598, dc_power=-4708)
2023-12-27 13:56:01,580 - {modules.common.store._api:26} - {DEBUG:device2} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-4652, exported=117122598, dc_power=-4708)
2023-12-27 13:56:01,585 - {modules.common.component_context:24} - {DEBUG:component1} - Update Komponente ['Sma Sunny Boy/Tripower Wechselrichter']
2023-12-27 13:56:01,587 - {modules.common.store._api:26} - {DEBUG:component1} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-4652, exported=117122598, dc_power=None)
2023-12-27 13:56:01,587 - {modules.common.store._api:30} - {INFO:component1} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-4652, exported=117122598, dc_power=None)
2023-12-27 13:56:01,589 - {modules.common.component_context:24} - {DEBUG:update values cp2} - Update Komponente ['Ladepunkt']
2023-12-27 13:56:01,590 - {modules.common.store._api:30} - {INFO:update values cp2} - Saving ChargepointState(currents=[5.960157, 5.844405, 6.25983], powers=[1375.8146291420999, 1350.8792783430001, 1459.270286178], voltages=[230.8353, 231.1406, 233.1166], frequency=50, imported=2720120.0, exported=0, power=4160.0, phases_in_use=3, charge_state=True, plug_state=True, rfid=None, power_factors=[0.0, 0.0, 0.0], soc=None, soc_timestamp=None, evse_current=None)
2023-12-27 13:56:01,643 - {modules.common.component_context:24} - {DEBUG:component0} - Update Komponente ['EVU-Kit flex']
2023-12-27 13:56:01,644 - {modules.common.store._api:30} - {INFO:component0} - Saving CounterState(currents=[-1.12360417842865, 1.5148950815200806, 1.0895500183105469], powers=[-58.205238342285156, 258.07257080078125, 46.76496887207031], voltages=[231.42481994628906, 231.74375915527344, 233.931884765625], power_factors=[-0.2031785249710083, 0.6960097551345825, 0.18198396265506744], imported=7089.927683749642, exported=8882.803319513401, power=246.6323013305664, frequency=49.90970230102539)
2023-12-27 13:56:01,729 - {control.counter:138} - {INFO:MainThread} - Verbleibende Ströme an Zähler 0: [42.08, 39.33, 40.17]A
2023-12-27 13:56:01,731 - {control.counter:161} - {INFO:MainThread} - Verbleibende Leistung an Zähler 0: 27913.367698669434W
2023-12-27 13:56:01,731 - {control.chargepoint.chargepoint:699} - {DEBUG:MainThread} - EV-Phasenzahl beschränkt die nutzbaren Phasen auf 3
2023-12-27 13:56:01,733 - {control.chargepoint.chargepoint:898} - {INFO:MainThread} - LP 2, EV: iX1 (EV-Nr.2): Theoretisch benötigter Strom 6A, Lademodus pv_charging, Submodus: Chargemode.PV_CHARGING, Phasen: 3, Priorität: False, max. Ist-Strom: 6.26
2023-12-27 13:56:01,735 - {modules.common.simcount._simcount:38} - {DEBUG:MainThread} - Previous state: SimCounterState(timestamp=1703681751.641619, power=683.6611862182617, imported=13853.52818163624, exported=0)
2023-12-27 13:56:01,736 - {modules.common.simcount._calculate:13} - {DEBUG:MainThread} - time passed: 0.00280389, power1: 683.661, power2: 738.632
2023-12-27 13:56:01,738 - {modules.common.simcount._calculate:29} - {DEBUG:MainThread} - power did not change sign. Total energy: 1.99397
2023-12-27 13:56:01,738 - {modules.common.simcount._simcount:47} - {DEBUG:MainThread} - imported: 1.99397 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1703681761.735611, power=738.6323013305664, imported=13855.522156508137, exported=0)
2023-12-27 13:56:01,740 - {control.data:236} - {INFO:MainThread} - bat_all_data
BatAllData(config=Config(configured=False), get=Get(soc=0, daily_exported=0, daily_imported=0, fault_str='Kein Fehler.', fault_state=0, imported=0, exported=0, power=0), set=Set(charging_power_left=0, switch_on_soc_reached=False))
2023-12-27 13:56:01,740 - {control.data:237} - {INFO:MainThread} - cp_all_data
AllChargepointData(get=AllGet(daily_imported=12380.0, daily_exported=0, power=4160.0, imported=2720120.0, exported=0))
2023-12-27 13:56:01,741 - {control.data:266} - {INFO:MainThread} - cp2
ChargepointData(control_parameter=ControlParameter(chargemode=<Chargemode.PV_CHARGING: 'pv_charging'>, current_plan=None, imported_at_plan_start=None, imported_instant_charging=None, limit=None, phases=3, prio=False, required_current=6, required_currents=[6, 6, 6], state=5, submode=<Chargemode.PV_CHARGING: 'pv_charging'>, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, timestamp_switch_on_off='12/27/2023, 13:54:00'), 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=70), currents=[5.96, 5.84, 6.26], daily_imported=12380.0, daily_exported=0, evse_current=None, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=2720120.0, phases_in_use=3, plug_state=True, power=4160.0, rfid_timestamp=None, rfid=None, soc=None, soc_timestamp=None, state_str=None, voltages=[230.84, 231.14, 233.12]), set=Set(change_ev_permitted=[True, ''], charging_ev=2, charging_ev_prev=2, current=6, energy_to_charge=0, loadmanagement_available=True, log=Log(chargemode_log_entry='pv_charging', imported_at_mode_switch=2707670.0, imported_at_plugtime=2707670.0, imported_since_mode_switch=12430.0, imported_since_plugged=12430.0, range_charged=69.05555555555556, time_charged='2:52', timestamp_start_charging='12/27/2023, 11:03:11'), manual_lock=False, phases_to_use=3, plug_state_prev=True, plug_time='12/26/2023, 11:25:10', required_power=4140, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0xf1a6dc70>), config=Config(configuration={'ip_address': '192.168.1.82', 'timeout': 2}, ev=2, name='smartWB', type='smartwb', template=1, connected_phases=3, phase_1=1, auto_phase_switch_hw=False, control_pilot_interruption_hw=False, id=2))
2023-12-27 13:56:01,742 - {control.data:266} - {INFO: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-Profil', rfid_enabling=False, valid_tags=[])
2023-12-27 13:56:01,742 - {control.data:266} - {INFO:MainThread} - cpt1
CpTemplateData(autolock=Autolock(active=False, plans={}, wait_for_charging_end=False), id=1, max_current_multi_phases=16, max_current_single_phase=16, name='smartWB', rfid_enabling=False, valid_tags=[])
2023-12-27 13:56:01,743 - {control.data:266} - {INFO:MainThread} - counter0
CounterData(config=Config(max_currents=[35, 35, 35], max_total_power=24000), get=Get(powers=[-58.21, 258.07, 46.76], currents=[-1.12, 1.51, 1.09], voltages=[231.42, 231.74, 233.93], power_factors=[-0.2, 0.7, 0.18], unbalanced_load=0, frequency=49.90970230102539, daily_exported=4885.916403547702, daily_imported=3577.611487456033, imported=7089.927683749642, exported=8882.803319513401, fault_state=0, fault_str='Kein Fehler.', power=246.6323013305664), set=Set(error_counter=0, reserved_surplus=0, released_surplus=4140, raw_power_left=27913.367698669434, raw_currents_left=[42.08, 39.33, 40.17], surplus_power_left=0, state_str=''))
2023-12-27 13:56:01,743 - {control.data:241} - {INFO:MainThread} - counter_all_data
CounterAllData(config=Config(reserve_for_not_charging=False), 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=738.6323013305664, invalid_home_consumption=0, daily_yield_home_consumption=8000.897269497167, imported_home_consumption=13855.522156508137, disengageable_smarthome_power=0))
2023-12-27 13:56:01,744 - {control.data:266} - {INFO:MainThread} - ct0
ChargeTemplateData(name='Standard-Lade-Profil', 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=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-12-27 13:56:01,744 - {control.data:266} - {INFO:MainThread} - ct1
ChargeTemplateData(name='i3', 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=80), scheduled_charging=ScheduledCharging(plans={}), instant_charging=InstantCharging(current=10, limit=Limit(selected='soc', amount=1000, soc=80))))
2023-12-27 13:56:01,745 - {control.data:266} - {INFO:MainThread} - ct2
ChargeTemplateData(name='iX1', 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=101), scheduled_charging=ScheduledCharging(plans={}), instant_charging=InstantCharging(current=10, limit=Limit(selected='none', amount=1000, soc=100))))
2023-12-27 13:56:01,745 - {control.data:266} - {INFO:MainThread} - ev0
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='Standard-Fahrzeug-Profil', 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, efficiency=90, nominal_difference=1, keep_charge_active_duration=40), et_num=0), soc_error_counter=0), charge_template=0, ev_template=0, name='Standard-Fahrzeug', tag_id=[], get=Get(soc=0, soc_timestamp=None, force_soc_update=False, range=0, fault_state=0, fault_str='Kein Fehler.'))
2023-12-27 13:56:01,745 - {control.data:266} - {INFO:MainThread} - ev1
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='Standard-Fahrzeug-Profil', 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, efficiency=90, nominal_difference=1, keep_charge_active_duration=40), et_num=0), soc_error_counter=0), charge_template=1, ev_template=1, name='i3', tag_id=[], get=Get(soc=75, soc_timestamp='12/27/2023, 11:16:23', force_soc_update=False, range=142.0, fault_state=0, fault_str='Kein Fehler.'))
2023-12-27 13:56:01,746 - {control.data:266} - {INFO:MainThread} - ev2
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='iX1', 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=18000, min_current=6, max_current_single_phase=16, battery_capacity=64000, efficiency=90, nominal_difference=2, keep_charge_active_duration=40), et_num=0), soc_error_counter=2), charge_template=2, ev_template=2, name='iX1', tag_id=[], get=Get(soc=70, soc_timestamp='12/27/2023, 13:54:59', force_soc_update=False, range=277.0, fault_state=2, fault_str="<class 'RuntimeError'> "))
2023-12-27 13:56:01,746 - {control.data:266} - {INFO:MainThread} - et0
EvTemplateData(name='Standard-Fahrzeug-Profil', 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, efficiency=90, nominal_difference=1, keep_charge_active_duration=40)
2023-12-27 13:56:01,746 - {control.data:266} - {INFO:MainThread} - et1
EvTemplateData(name='i3', 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=38000, efficiency=90, nominal_difference=2, keep_charge_active_duration=40)
2023-12-27 13:56:01,747 - {control.data:266} - {INFO:MainThread} - et2
EvTemplateData(name='iX1', 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=18000, min_current=6, max_current_single_phase=16, battery_capacity=64000, efficiency=90, nominal_difference=2, keep_charge_active_duration=40)
2023-12-27 13:56:01,753 - {control.data:245} - {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=[-230, 0], feed_in_yield=15000, phase_switch_delay=7, phases_to_use=3, rundown_power=1000, rundown_soc=50, switch_off_delay=120, switch_off_soc=40, switch_off_threshold=50, switch_on_delay=30, switch_on_soc=60, switch_on_threshold=1400), scheduled_charging=ScheduledCharging(phases_to_use=3), time_charging=TimeCharging(phases_to_use=3), unbalanced_load_limit=18, unbalanced_load=False), control_interval=10, extern_display_mode='primary', 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.3, range_unit='km', ripple_control_receiver=RippleControlReceiver(configured=False, r1_active=False, r2_active=False))
2023-12-27 13:56:01,754 - {control.data:246} - {INFO:MainThread} - general_data-display
primary
2023-12-27 13:56:01,756 - {control.data:247} - {INFO:MainThread} - graph_data
GraphData(config=Config(duration=120))
2023-12-27 13:56:01,758 - {control.data:248} - {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=False, on_if_plugged_in=True, pin_active=False, pin_code='0000', standby=60, theme=<modules.display_themes.cards.config.CardsDisplayTheme object at 0xf10c9310>), led=Led(active=False), rfid=Rfid(active=False))
2023-12-27 13:56:01,758 - {control.data:266} - {INFO:MainThread} - pv1
PvData(config=Config(max_ac_out=9000), get=Get(currents=[0.0, 0.0, 0.0], daily_exported=21573, monthly_exported=0, yearly_exported=0, exported=117122598, fault_state=0, fault_str='Kein Fehler.', power=-4652))
2023-12-27 13:56:01,759 - {control.data:250} - {INFO:MainThread} - pv_all_data
PvAllData(config=Config(configured=True), get=Get(daily_exported=21573, monthly_exported=0, yearly_exported=0, exported=117122598, power=-4652))
2023-12-27 13:56:01,759 - {control.data:266} - {INFO:MainThread} - system
{'update_in_progress': False, 'perform_update': False, 'ip_address': '192.168.1.137', 'current_commit': '2023-11-30 12:30:45 +0100 [285f5a5fb]', 'current_branch': 'Release', 'current_branch_commit': '2023-11-30 12:30:45 +0100 [285f5a5fb]', 'current_missing_commits': [], 'datastore_version': 29, 'dataprotection_acknowledged': True, 'usage_terms_acknowledged': True, 'debug_level': 10, 'release_train': 'master', 'version': '2.1.2-Patch.1', 'boot_done': True, 'lastlivevaluesJson': {'timestamp': 1703681751, 'time': '13:55:51', 'grid': 0.193, 'house-power': 0.684, 'charging-all': 4.17, 'pv-all': 4.661, 'cp2-power': 4.17, 'ev1-soc': 75, 'ev2-soc': 70}, 'module_update_completed': True}
2023-12-27 13:56:01,760 - {control.data:279} - {INFO:MainThread} - device1
{'name': 'openWB-Kit flex', 'type': 'openwb_flex', 'id': 1, 'configuration': {'port': 8899, 'ip_address': '192.168.1.81'}}
2023-12-27 13:56:01,761 - {control.data:281} - {INFO:MainThread} - component0
{'name': 'EVU-Kit flex', 'type': 'counter', 'id': 0, 'configuration': {'id': 115, 'version': 2}}
2023-12-27 13:56:01,761 - {control.data:279} - {INFO:MainThread} - device2
{'name': 'SMA Sunny Boy/Tripower', 'type': 'sma_sunny_boy', 'id': 2, 'configuration': {'ip_address': '192.168.1.71'}}
2023-12-27 13:56:01,762 - {control.data:281} - {INFO:MainThread} - component1
{'name': 'Sma Sunny Boy/Tripower Wechselrichter', 'type': 'inverter', 'id': 1, 'configuration': {'hybrid': False, 'version': 0}}
2023-12-27 13:56:01,763 - {control.data:253} - {INFO:MainThread} - 

2023-12-27 13:56:01,764 - {control.algorithm.algorithm:25} - {INFO:MainThread} - # Algorithmus
2023-12-27 13:56:01,765 - {control.counter:198} - {INFO:MainThread} - Überschuss zur PV-geführten Ladung: 131.6323013305664W
2023-12-27 13:56:01,765 - {control.counter:352} - {DEBUG:MainThread} - LP2 Switch-Off-Threshold prüfen: -4008.3676986694336W, Schwelle: 50W, freigegebener Überschuss 4140W, Einspeisegrenze 0W
2023-12-27 13:56:01,767 - {control.chargepoint.chargepoint:277} - {INFO:MainThread} - LP 2: Ladevorgang nach Ablauf der Abschaltverzögerung gestoppt.
2023-12-27 13:56:01,767 - {control.algorithm.algorithm:30} - {INFO:MainThread} - **Mindestrom setzen**
2023-12-27 13:56:01,768 - {control.algorithm.min_current:18} - {INFO:MainThread} - Mode-Tuple Chargemode.PV_CHARGING - Chargemode.PV_CHARGING - False, Zähler 0
2023-12-27 13:56:01,769 - {control.algorithm.algorithm:32} - {INFO:MainThread} - **Sollstrom setzen**
2023-12-27 13:56:01,771 - {control.counter:211} - {INFO:MainThread} - Überschuss zur PV-geführten Ladung: 3798.3676986694336W
2023-12-27 13:56:01,772 - {control.counter:457} - {DEBUG:MainThread} - Zähler 0: Begrenzung der verbleibenden Leistung auf 3798.3676986694336W
2023-12-27 13:56:01,773 - {control.chargepoint.chargepoint:277} - {INFO:MainThread} - LP 2: Die Ladung kann nicht gestartet werden, da die Einschaltschwelle nicht erreicht wird.
2023-12-27 13:56:01,773 - {control.algorithm.algorithm:38} - {INFO:MainThread} - **PV-geführten Strom setzen**
2023-12-27 13:56:01,774 - {control.algorithm.filter_chargepoints:117} - {DEBUG:MainThread} - Geordnete Ladepunkte [2]
2023-12-27 13:56:01,775 - {control.algorithm.filter_chargepoints:46} - {INFO:MainThread} - LP 2: Keine Zuteilung des Mindeststroms, daher keine weitere Berücksichtigung
2023-12-27 13:56:01,777 - {control.process:23} - {INFO:MainThread} - # Ladung starten.
2023-12-27 13:56:01,778 - {control.chargelog:52} - {DEBUG:MainThread} - imported_since_mode_switch 12450.0 counter 2720120.0
2023-12-27 13:56:01,783 - {control.process:111} - {INFO:MainThread} - LP2: set current 0 A, state NO_CHARGING_ALLOWED
2023-12-27 13:56:01,791 - {modules.common.component_context:24} - {DEBUG:set current cp2} - Update Komponente ['Ladepunkt']
2023-12-27 13:56:01,798 - {urllib3.connectionpool:227} - {DEBUG:set current cp2} - Starting new HTTP connection (1): 192.168.1.82:80
2023-12-27 13:56:01,883 - {urllib3.connectionpool:452} - {DEBUG:set current cp2} - http://192.168.1.82:80 "GET /setCurrent?current=0 HTTP/1.1" 200 29
2023-12-27 13:56:01,884 - {modules.common.req:11} - {DEBUG:set current cp2} - Get-Response: S0_set current to given value
2023-12-27 13:56:01,886 - {control.counter:186} - {INFO:MainThread} - 0W reservierte EVU-Leistung, 0W freigegebene EVU-Leistung
2023-12-27 13:56:02,072 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/2/control_parameter/required_current, Payload 16, vorherige Payload: 6
2023-12-27 13:56:02,080 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/2/control_parameter/state, Payload 0, vorherige Payload: 5
2023-12-27 13:56:02,091 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/2/control_parameter/timestamp_switch_on_off, Payload None, vorherige Payload: 12/27/2023, 13:54:00
2023-12-27 13:56:02,093 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2023-12-27 13:56:05,119 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2023-12-27 13:56:10,128 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})
2023-12-27 13:56:10,129 - {root:74} - {INFO:MainThread} - # ***Start*** 
2023-12-27 13:56:10,145 - {modules.devices.openwb_flex.device:50} - {DEBUG:device1} - Start device reading {'component0': <modules.devices.openwb_flex.counter.EvuKitFlex object at 0xf22e5610>}
2023-12-27 13:56:10,145 - {modules.common.component_context:24} - {DEBUG:device1} - Update Komponente ['EVU-Kit flex']
2023-12-27 13:56:10,146 - {modules.devices.sma_sunny_boy.device:72} - {DEBUG:device2} - Start device reading {'component1': <modules.devices.sma_sunny_boy.inverter.SmaSunnyBoyInverter object at 0xf22cb088>}
2023-12-27 13:56:10,147 - {modules.common.component_context:24} - {DEBUG:set values cp2} - Update Komponente ['Ladepunkt']
2023-12-27 13:56:10,153 - {urllib3.connectionpool:227} - {DEBUG:set values cp2} - Starting new HTTP connection (1): 192.168.1.82:80
2023-12-27 13:56:10,156 - {modules.common.component_context:24} - {DEBUG:device2} - Update Komponente ['Sma Sunny Boy/Tripower Wechselrichter']
2023-12-27 13:56:10,178 - {urllib3.connectionpool:452} - {DEBUG:set values cp2} - http://192.168.1.82:80 "GET /getParameters HTTP/1.1" 200 403
2023-12-27 13:56:10,179 - {modules.common.req:11} - {DEBUG:set values cp2} - Get-Response: {"type":"parameters","list":[{"vehicleState":2,"evseState":false,"maxCurrent":16,"actualCurrent":0,"actualCurrentMA":0,"actualPower":0,"duration":859000,"alwaysActive":true,"lastActionUser":"API","lastActionUID":"API","energy":0.99,"mileage":6.6,"meterReading":2720.12,"currentP1":0,"currentP2":0,"currentP3":0,"voltageP1":232.7545,"voltageP2":232.302,"voltageP3":234.506,"useMeter":true,"RFIDUID":""}]}
2023-12-27 13:56:10,180 - {modules.common.store._api:26} - {DEBUG:set values cp2} - Raw data ChargepointState(currents=[0, 0, 0], powers=[0.0, 0.0, 0.0], voltages=[232.7545, 232.302, 234.506], frequency=50, imported=2720120.0, exported=0, power=0, phases_in_use=1, charge_state=False, plug_state=True, rfid=None, power_factors=[0.0, 0.0, 0.0], soc=None, soc_timestamp=None, evse_current=None)
2023-12-27 13:56:10,623 - {modules.common.simcount._simcount:38} - {DEBUG:device1} - Previous state: SimCounterState(timestamp=1703681761.4448893, power=246.6323013305664, imported=7089.927683749642, exported=8882.803319513401)
2023-12-27 13:56:10,624 - {modules.common.simcount._calculate:13} - {DEBUG:device1} - time passed: 0.0025497, power1: 246.632, power2: -3930.43
2023-12-27 13:56:10,624 - {modules.common.simcount._calculate:26} - {DEBUG:device1} - power changed sign. Total energy: -4.6963, exported energy: -4.71486
2023-12-27 13:56:10,624 - {modules.common.simcount._simcount:47} - {DEBUG:device1} - imported: 0.0185647 Wh, exported: 4.71486 Wh, new state: SimCounterState(timestamp=1703681770.6238263, power=-3930.4290771484375, imported=7089.94624849277, exported=8887.518181252704)
2023-12-27 13:56:10,625 - {modules.common.store._api:26} - {DEBUG:device1} - Raw data CounterState(currents=[-6.200578689575195, -4.709894180297852, -6.026656150817871], powers=[-1439.91552734375, -1085.6741943359375, -1404.83935546875], voltages=[232.2760009765625, 231.85903930664062, 234.20989990234375], power_factors=[-0.9980988502502441, -0.9979097247123718, -0.9987198114395142], imported=7089.94624849277, exported=8887.518181252704, power=-3930.4290771484375, frequency=49.927513122558594)
2023-12-27 13:56:10,775 - {modules.devices.sma_sunny_boy.inverter:72} - {DEBUG:device2} - WR 192.168.1.71: InverterState(currents=[0.0, 0.0, 0.0], power=-4653, exported=117122610, dc_power=-4706)
2023-12-27 13:56:10,775 - {modules.common.store._api:26} - {DEBUG:device2} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-4653, exported=117122610, dc_power=-4706)
2023-12-27 13:56:10,777 - {modules.common.component_context:24} - {DEBUG:component1} - Update Komponente ['Sma Sunny Boy/Tripower Wechselrichter']
2023-12-27 13:56:10,778 - {modules.common.component_context:24} - {DEBUG:update values cp2} - Update Komponente ['Ladepunkt']
2023-12-27 13:56:10,778 - {modules.common.store._api:26} - {DEBUG:component1} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-4653, exported=117122610, dc_power=None)
2023-12-27 13:56:10,778 - {modules.common.store._api:30} - {INFO:update values cp2} - Saving ChargepointState(currents=[0, 0, 0], powers=[0.0, 0.0, 0.0], voltages=[232.7545, 232.302, 234.506], frequency=50, imported=2720120.0, exported=0, power=0, phases_in_use=1, charge_state=False, plug_state=True, rfid=None, power_factors=[0.0, 0.0, 0.0], soc=None, soc_timestamp=None, evse_current=None)
2023-12-27 13:56:10,779 - {modules.common.store._api:30} - {INFO:component1} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-4653, exported=117122610, dc_power=None)
2023-12-27 13:56:10,824 - {modules.common.component_context:24} - {DEBUG:component0} - Update Komponente ['EVU-Kit flex']
2023-12-27 13:56:10,825 - {modules.common.store._api:30} - {INFO:component0} - Saving CounterState(currents=[-6.200578689575195, -4.709894180297852, -6.026656150817871], powers=[-1439.91552734375, -1085.6741943359375, -1404.83935546875], voltages=[232.2760009765625, 231.85903930664062, 234.20989990234375], power_factors=[-0.9980988502502441, -0.9979097247123718, -0.9987198114395142], imported=7089.94624849277, exported=8887.518181252704, power=-3930.4290771484375, frequency=49.927513122558594)
2023-12-27 13:56:10,861 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/2/control_parameter/required_current, Payload 16, vorherige Payload: 6
2023-12-27 13:56:10,862 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/2/control_parameter/state, Payload 0, vorherige Payload: 5
2023-12-27 13:56:10,862 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/2/control_parameter/timestamp_switch_on_off, Payload None, vorherige Payload: 12/27/2023, 13:54:00
2023-12-27 13:56:10,916 - {control.counter:138} - {INFO:MainThread} - Verbleibende Ströme an Zähler 0: [41.2, 39.71, 41.03]A
2023-12-27 13:56:10,917 - {control.counter:161} - {INFO:MainThread} - Verbleibende Leistung an Zähler 0: 27930.429077148438W
2023-12-27 13:56:10,920 - {control.chargepoint.chargepoint:699} - {DEBUG:MainThread} - EV-Phasenzahl beschränkt die nutzbaren Phasen auf 3
2023-12-27 13:56:10,922 - {control.chargepoint.chargepoint:717} - {INFO:MainThread} - Phasenumschaltung an Ladepunkt 2 wird durch die Hardware nicht unterstützt.
2023-12-27 13:56:10,931 - {control.chargepoint.chargepoint:898} - {INFO:MainThread} - LP 2, EV: iX1 (EV-Nr.2): Theoretisch benötigter Strom 6A, Lademodus pv_charging, Submodus: Chargemode.PV_CHARGING, Phasen: 1, Priorität: False, max. Ist-Strom: 0
2023-12-27 13:56:10,936 - {modules.common.simcount._simcount:38} - {DEBUG:MainThread} - Previous state: SimCounterState(timestamp=1703681761.735611, power=738.6323013305664, imported=13855.522156508137, exported=0)
2023-12-27 13:56:10,937 - {modules.common.simcount._calculate:13} - {DEBUG:MainThread} - time passed: 0.00255573, power1: 738.632, power2: 722.571
2023-12-27 13:56:10,938 - {modules.common.simcount._calculate:29} - {DEBUG:MainThread} - power did not change sign. Total energy: 1.86722
2023-12-27 13:56:10,938 - {modules.common.simcount._simcount:47} - {DEBUG:MainThread} - imported: 1.86722 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1703681770.9362316, power=722.5709228515625, imported=13857.389375474815, exported=0)
2023-12-27 13:56:10,939 - {control.data:236} - {INFO:MainThread} - bat_all_data
BatAllData(config=Config(configured=False), get=Get(soc=0, daily_exported=0, daily_imported=0, fault_str='Kein Fehler.', fault_state=0, imported=0, exported=0, power=0), set=Set(charging_power_left=0, switch_on_soc_reached=False))
2023-12-27 13:56:10,940 - {control.data:237} - {INFO:MainThread} - cp_all_data
AllChargepointData(get=AllGet(daily_imported=12380.0, daily_exported=0, power=0, imported=2720120.0, exported=0))
2023-12-27 13:56:10,941 - {control.data:266} - {INFO:MainThread} - cp2
ChargepointData(control_parameter=ControlParameter(chargemode=<Chargemode.PV_CHARGING: 'pv_charging'>, current_plan=None, imported_at_plan_start=None, imported_instant_charging=None, limit=None, phases=1, prio=False, required_current=6, required_currents=[6, 0.0, 0.0], state=0, submode=<Chargemode.PV_CHARGING: 'pv_charging'>, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, timestamp_switch_on_off=None), 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=70), currents=[0, 0, 0], daily_imported=12380.0, daily_exported=0, evse_current=None, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=2720120.0, phases_in_use=1, plug_state=True, power=0, rfid_timestamp=None, rfid=None, soc=None, soc_timestamp=None, state_str=None, voltages=[232.75, 232.3, 234.51]), 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=2707670.0, imported_at_plugtime=2707670.0, imported_since_mode_switch=12450.0, imported_since_plugged=12450.0, range_charged=69.16666666666667, time_charged='2:52', timestamp_start_charging='12/27/2023, 11:03:11'), manual_lock=False, phases_to_use=3, plug_state_prev=True, plug_time='12/26/2023, 11:25:10', required_power=1380.0, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0xf1a6d6e8>), config=Config(configuration={'ip_address': '192.168.1.82', 'timeout': 2}, ev=2, name='smartWB', type='smartwb', template=1, connected_phases=3, phase_1=1, auto_phase_switch_hw=False, control_pilot_interruption_hw=False, id=2))
2023-12-27 13:56:10,941 - {control.data:266} - {INFO: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-Profil', rfid_enabling=False, valid_tags=[])
2023-12-27 13:56:10,941 - {control.data:266} - {INFO:MainThread} - cpt1
CpTemplateData(autolock=Autolock(active=False, plans={}, wait_for_charging_end=False), id=1, max_current_multi_phases=16, max_current_single_phase=16, name='smartWB', rfid_enabling=False, valid_tags=[])
2023-12-27 13:56:10,941 - {control.data:266} - {INFO:MainThread} - counter0
CounterData(config=Config(max_currents=[35, 35, 35], max_total_power=24000), get=Get(powers=[-1439.92, -1085.67, -1404.84], currents=[-6.2, -4.71, -6.03], voltages=[232.28, 231.86, 234.21], power_factors=[-1.0, -1.0, -1.0], unbalanced_load=0, frequency=49.927513122558594, daily_exported=4885.916403547702, daily_imported=3577.611487456033, imported=7089.94624849277, exported=8887.518181252704, fault_state=0, fault_str='Kein Fehler.', power=-3930.4290771484375), set=Set(error_counter=0, reserved_surplus=0, released_surplus=0, raw_power_left=27930.429077148438, raw_currents_left=[41.2, 39.71, 41.03], surplus_power_left=0, state_str=''))
2023-12-27 13:56:10,942 - {control.data:241} - {INFO:MainThread} - counter_all_data
CounterAllData(config=Config(reserve_for_not_charging=False), 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=722.5709228515625, invalid_home_consumption=0, daily_yield_home_consumption=8000.897269497167, imported_home_consumption=13857.389375474815, disengageable_smarthome_power=0))
2023-12-27 13:56:10,942 - {control.data:266} - {INFO:MainThread} - ct0
ChargeTemplateData(name='Standard-Lade-Profil', 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=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-12-27 13:56:10,943 - {control.data:266} - {INFO:MainThread} - ct1
ChargeTemplateData(name='i3', 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=80), scheduled_charging=ScheduledCharging(plans={}), instant_charging=InstantCharging(current=10, limit=Limit(selected='soc', amount=1000, soc=80))))
2023-12-27 13:56:10,943 - {control.data:266} - {INFO:MainThread} - ct2
ChargeTemplateData(name='iX1', 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=101), scheduled_charging=ScheduledCharging(plans={}), instant_charging=InstantCharging(current=10, limit=Limit(selected='none', amount=1000, soc=100))))
2023-12-27 13:56:10,944 - {control.data:266} - {INFO:MainThread} - ev0
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='Standard-Fahrzeug-Profil', 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, efficiency=90, nominal_difference=1, keep_charge_active_duration=40), et_num=0), soc_error_counter=0), charge_template=0, ev_template=0, name='Standard-Fahrzeug', tag_id=[], get=Get(soc=0, soc_timestamp=None, force_soc_update=False, range=0, fault_state=0, fault_str='Kein Fehler.'))
2023-12-27 13:56:10,944 - {control.data:266} - {INFO:MainThread} - ev1
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='Standard-Fahrzeug-Profil', 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, efficiency=90, nominal_difference=1, keep_charge_active_duration=40), et_num=0), soc_error_counter=0), charge_template=1, ev_template=1, name='i3', tag_id=[], get=Get(soc=75, soc_timestamp='12/27/2023, 11:16:23', force_soc_update=False, range=142.0, fault_state=0, fault_str='Kein Fehler.'))
2023-12-27 13:56:10,944 - {control.data:266} - {INFO:MainThread} - ev2
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='iX1', 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=18000, min_current=6, max_current_single_phase=16, battery_capacity=64000, efficiency=90, nominal_difference=2, keep_charge_active_duration=40), et_num=0), soc_error_counter=2), charge_template=2, ev_template=2, name='iX1', tag_id=[], get=Get(soc=70, soc_timestamp='12/27/2023, 13:54:59', force_soc_update=False, range=277.0, fault_state=2, fault_str="<class 'RuntimeError'> "))
2023-12-27 13:56:10,944 - {control.data:266} - {INFO:MainThread} - et0
EvTemplateData(name='Standard-Fahrzeug-Profil', 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, efficiency=90, nominal_difference=1, keep_charge_active_duration=40)
2023-12-27 13:56:10,945 - {control.data:266} - {INFO:MainThread} - et1
EvTemplateData(name='i3', 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=38000, efficiency=90, nominal_difference=2, keep_charge_active_duration=40)
2023-12-27 13:56:10,945 - {control.data:266} - {INFO:MainThread} - et2
EvTemplateData(name='iX1', 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=18000, min_current=6, max_current_single_phase=16, battery_capacity=64000, efficiency=90, nominal_difference=2, keep_charge_active_duration=40)
2023-12-27 13:56:10,945 - {control.data:245} - {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=[-230, 0], feed_in_yield=15000, phase_switch_delay=7, phases_to_use=3, rundown_power=1000, rundown_soc=50, switch_off_delay=120, switch_off_soc=40, switch_off_threshold=50, switch_on_delay=30, switch_on_soc=60, switch_on_threshold=1400), scheduled_charging=ScheduledCharging(phases_to_use=3), time_charging=TimeCharging(phases_to_use=3), unbalanced_load_limit=18, unbalanced_load=False), control_interval=10, extern_display_mode='primary', 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.3, range_unit='km', ripple_control_receiver=RippleControlReceiver(configured=False, r1_active=False, r2_active=False))
2023-12-27 13:56:10,946 - {control.data:246} - {INFO:MainThread} - general_data-display
primary
2023-12-27 13:56:10,946 - {control.data:247} - {INFO:MainThread} - graph_data
GraphData(config=Config(duration=120))
2023-12-27 13:56:10,946 - {control.data:248} - {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=False, on_if_plugged_in=True, pin_active=False, pin_code='0000', standby=60, theme=<modules.display_themes.cards.config.CardsDisplayTheme object at 0xf10fd9e8>), led=Led(active=False), rfid=Rfid(active=False))
2023-12-27 13:56:10,947 - {control.data:266} - {INFO:MainThread} - pv1
PvData(config=Config(max_ac_out=9000), get=Get(currents=[0.0, 0.0, 0.0], daily_exported=21573, monthly_exported=0, yearly_exported=0, exported=117122610, fault_state=0, fault_str='Kein Fehler.', power=-4653))
2023-12-27 13:56:10,947 - {control.data:250} - {INFO:MainThread} - pv_all_data
PvAllData(config=Config(configured=True), get=Get(daily_exported=21573, monthly_exported=0, yearly_exported=0, exported=117122610, power=-4653))
2023-12-27 13:56:10,947 - {control.data:266} - {INFO:MainThread} - system
{'update_in_progress': False, 'perform_update': False, 'ip_address': '192.168.1.137', 'current_commit': '2023-11-30 12:30:45 +0100 [285f5a5fb]', 'current_branch': 'Release', 'current_branch_commit': '2023-11-30 12:30:45 +0100 [285f5a5fb]', 'current_missing_commits': [], 'datastore_version': 29, 'dataprotection_acknowledged': True, 'usage_terms_acknowledged': True, 'debug_level': 10, 'release_train': 'master', 'version': '2.1.2-Patch.1', 'boot_done': True, 'lastlivevaluesJson': {'timestamp': 1703681761, 'time': '13:56:01', 'grid': 0.247, 'house-power': 0.739, 'charging-all': 4.16, 'pv-all': 4.652, 'cp2-power': 4.16, 'ev1-soc': 75, 'ev2-soc': 70}, 'module_update_completed': True}
2023-12-27 13:56:10,947 - {control.data:279} - {INFO:MainThread} - device1
{'name': 'openWB-Kit flex', 'type': 'openwb_flex', 'id': 1, 'configuration': {'port': 8899, 'ip_address': '192.168.1.81'}}
2023-12-27 13:56:10,948 - {control.data:281} - {INFO:MainThread} - component0
{'name': 'EVU-Kit flex', 'type': 'counter', 'id': 0, 'configuration': {'id': 115, 'version': 2}}
2023-12-27 13:56:10,948 - {control.data:279} - {INFO:MainThread} - device2
{'name': 'SMA Sunny Boy/Tripower', 'type': 'sma_sunny_boy', 'id': 2, 'configuration': {'ip_address': '192.168.1.71'}}
2023-12-27 13:56:10,949 - {control.data:281} - {INFO:MainThread} - component1
{'name': 'Sma Sunny Boy/Tripower Wechselrichter', 'type': 'inverter', 'id': 1, 'configuration': {'hybrid': False, 'version': 0}}
2023-12-27 13:56:10,950 - {control.data:253} - {INFO:MainThread} - 

2023-12-27 13:56:10,950 - {control.algorithm.algorithm:25} - {INFO:MainThread} - # Algorithmus
2023-12-27 13:56:10,950 - {control.algorithm.algorithm:30} - {INFO:MainThread} - **Mindestrom setzen**
2023-12-27 13:56:10,951 - {control.algorithm.min_current:18} - {INFO:MainThread} - Mode-Tuple Chargemode.PV_CHARGING - Chargemode.PV_CHARGING - False, Zähler 0
2023-12-27 13:56:10,952 - {control.algorithm.algorithm:32} - {INFO:MainThread} - **Sollstrom setzen**
2023-12-27 13:56:10,952 - {control.counter:211} - {INFO:MainThread} - Überschuss zur PV-geführten Ladung: 3815.4290771484375W
2023-12-27 13:56:10,953 - {control.counter:457} - {DEBUG:MainThread} - Zähler 0: Begrenzung der verbleibenden Leistung auf 3815.4290771484375W
2023-12-27 13:56:10,953 - {control.chargepoint.chargepoint:277} - {INFO:MainThread} - LP 2: Die Ladung wird gestartet, sobald nach 30s die Einschaltverzögerung abgelaufen ist.
2023-12-27 13:56:10,953 - {control.algorithm.algorithm:38} - {INFO:MainThread} - **PV-geführten Strom setzen**
2023-12-27 13:56:10,954 - {control.algorithm.filter_chargepoints:117} - {DEBUG:MainThread} - Geordnete Ladepunkte [2]
2023-12-27 13:56:10,954 - {control.algorithm.filter_chargepoints:46} - {INFO:MainThread} - LP 2: Keine Zuteilung des Mindeststroms, daher keine weitere Berücksichtigung
2023-12-27 13:56:10,955 - {control.process:23} - {INFO:MainThread} - # Ladung starten.
2023-12-27 13:56:10,956 - {control.process:111} - {INFO:MainThread} - LP2: set current 0 A, state SWITCH_ON_DELAY
2023-12-27 13:56:10,957 - {modules.common.component_context:24} - {DEBUG:set current cp2} - Update Komponente ['Ladepunkt']
2023-12-27 13:56:10,960 - {urllib3.connectionpool:227} - {DEBUG:set current cp2} - Starting new HTTP connection (1): 192.168.1.82:80
2023-12-27 13:56:11,069 - {urllib3.connectionpool:452} - {DEBUG:set current cp2} - http://192.168.1.82:80 "GET /setCurrent?current=0 HTTP/1.1" 200 29
2023-12-27 13:56:11,070 - {modules.common.req:11} - {DEBUG:set current cp2} - Get-Response: S0_set current to given value
2023-12-27 13:56:11,072 - {control.counter:186} - {INFO:MainThread} - 1400W reservierte EVU-Leistung, 0W freigegebene EVU-Leistung
2023-12-27 13:56:11,230 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/2/control_parameter/phases, Payload 1, vorherige Payload: 3
2023-12-27 13:56:11,231 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/2/control_parameter/state, Payload 6, vorherige Payload: 0
2023-12-27 13:56:11,236 - {helpermodules.changed_values_handler:120} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/2/control_parameter/timestamp_switch_on_off, Payload 12/27/2023, 13:56:10, vorherige Payload: None
2023-12-27 13:56:11,237 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2023-12-27 13:56:15,261 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2023-12-27 13:56:20,268 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})

Komplettes Log ist vorhanden, hier nur gekürzt auf den oben gezeigten Bereich wg. Beitragslänge, kann bei Bedarf die komplette Datei zur Verfügung stellen. Kann bitte das jeman anschauen, ich verstehe es leider nicht!

Viele Grüße, heidanei

mrinas · Beitrag von **mrinas** » Mi Dez 27, 2023 4:37 pm

Kann es sein dass die Einschaltschwelle in der 2.0er nicht mehr pro Phase, sondern insgesamt am EVU Punkt berücksichtigt wird? In dem Fall würde das wohl passen, es gibt - soweit ich das am Chart erkennen kann - mehr als 1,4kW Einspeisung, Ladung wird gestartet. Allerdings gibts dann nicht genug Leistung, drum wird wieder abgeschaltet.

Setz' die Einschaltschwelle doch mal auf 4,2kW oder eher 4,3kW damit das nicht gleich wieder hin- und hergeschaltet wird.

vuffiraa · Beitrag von **vuffiraa** » Mi Dez 27, 2023 5:21 pm

Vor 4 Wochen wurden die Logik zur Berechnung des Überschusses geändert. Da gibt es wohl noch Probleme. Das Schwingen ist nach meiner Ansicht eher ein Bug als ein Einstellungsproblem.

Ich hatte meine Beobachtung schon mal hier geschrieben: viewtopic.php?p=98323#p98323
Die neue Berechnung passt während der Ladung, aber berechnet ohne Ladung den verfügbaren Überschuss quasi doppelt.
Das passiert im Counter bei calc_raw_surplus() und _control_range(). Mir ist aber nicht klar, wie das insgesamt funktionieren soll, sonst hätte ich einen PR erstellt.

VG

heidanei · Beitrag von **heidanei** » Mi Dez 27, 2023 6:31 pm

Hallo ihr beiden!

Vielen Dank für die Unterstützung!

@mrinas:
Beim ersten Versuch hatte ich 4,2kW eingestellt, s.o., weil ich mir auch nicht mehr sicher war ob sich da ggü. der 1.9 was geändert hat, der Hilfetext hilft da leider nicht. Die 1,4kW hab ich dann auf Anraten von aiole (s. 2. Post) eingestellt. Unterschied hat es interessanterweise keinen gemacht! (?) Bis zum ersten Einschalten nach dem Anstecken funktoniert es auch richtig, die Ladung startet beim ersten Mal nach dem Anstecken tatsächlich erst bei 4,2kW Überschuss. Aber wenn sie dann unter die Einschaltschwelle abfällt startet sie nach der eingestellten Einschaltverzögerung wieder, auch wenn die Leistung noch nicht ausreicht.

@vuffiraa:
Vielen Dank! Das auf Deinem Screenshot sieht genauso aus wie bei mir!

Das freut mich sehr, dass ich wohl doch nicht ganz der einzige mit dem Problem bin!

Dabei bin ich für den heutigen Test extra auf die letzte Stable vom 30.11. zurückgegangen, um einen Bug in den neueren Master ausschließen zu können. D.h. der Bug müsste auch schon in dieser Stable vorhanden sein. Dann wundert mich aber dass es hier keinen größeren Aufschrei gibt wenn die elementarste Funktion von openwb buggy ist. Aber Deine Erklärung klingt logisch!

Weißt Du bis zu welcher Version der Bug noch nicht da war? Dann versuche ich morgen mal auf eine noch ältere zurückzugehen und nochmal zu probieren...

@aiole, mrinas, ...: Welche Version bzw. Datum habt ihr in eurer produktiven Installation am Laufen bei der der Bug nicht auftritt?

Viele Grüße, heidanei

mrinas · Beitrag von **mrinas** » Mi Dez 27, 2023 8:46 pm

ich hab' meine Standalone heute auf den aktuellen master (2023-12-22 12:29:57 +0100 [2d75c6556]) aktualisiert. Bin vorher auf anderen master-Releases unterwegs gewesen und hatte nie derartige Probleme.

heidanei · Beitrag von **heidanei** » Do Dez 28, 2023 9:36 am

Hi!

Danke! Die Master vom 22. hatte ich ja auch bereits probiert, auch da schaltet es stets aus und ein.

Was hast Du als Einschaltschwelle eingestellt?

@vuffiraa:
Kann es sein, dass der Bug der zu der rechnerischen Verdoppelung des PV-Überschusses führt von irgendeiner (wohl seltenen) Konfigurationseinstellung abhängig ist?

Viele Grüße, heidanei
(da ich die nächsten Tage nicht da bin erstmal wieder auf 1.9.304 zurückgestellt - die läuft hier wenigstens problemlos)

openWB Forum

PV-Laden: Schwingen anstatt abschalten bei zu wenig PV-Leistung

Re: PV-Laden: Schwingen anstatt abschalten bei zu wenig PV-Leistung

Re: PV-Laden: Schwingen anstatt abschalten bei zu wenig PV-Leistung

Re: PV-Laden: Schwingen anstatt abschalten bei zu wenig PV-Leistung

Re: PV-Laden: Schwingen anstatt abschalten bei zu wenig PV-Leistung

Re: PV-Laden: Schwingen anstatt abschalten bei zu wenig PV-Leistung

Re: PV-Laden: Schwingen anstatt abschalten bei zu wenig PV-Leistung

Re: PV-Laden: Schwingen anstatt abschalten bei zu wenig PV-Leistung

Re: PV-Laden: Schwingen anstatt abschalten bei zu wenig PV-Leistung

Re: PV-Laden: Schwingen anstatt abschalten bei zu wenig PV-Leistung

Re: PV-Laden: Schwingen anstatt abschalten bei zu wenig PV-Leistung