ich habe ein Problem mit meiner OpenWB Custom und meinem Ioniq5. Mit der Firmware-Version 1.9.x hat die Phasenumschaltung immer problemlos funktioniert. Doch seit einem Update (oder einer anderen Änderung) klappt es nicht mehr.
Die Umschaltung wird mehrfach versucht, aber sie klappt einfach nicht. Die Anzahl der Phasen bleibt immer so, wie sie gerade ist.
Ich bin mir nicht sicher, wie man die Logs hier technisch vernünftig einfügt. Kann mir jemand erklären, wie ich die relevanten Logs extrahieren und hier im Forum posten kann?
Code: Alles auswählen
2024-06-30 16:30:20,036 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2024-06-30 16:30:20,043 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})
2024-06-30 16:30:20,044 - {root:75} - {INFO:MainThread} - # ***Start***
2024-06-30 16:30:20,100 - {modules.common.component_context:54} - {DEBUG:device0} - Update Komponenten ['SMA Home Manager Zähler']
2024-06-30 16:30:20,102 - {modules.devices.sma_sunny_boy.device:72} - {DEBUG:device1} - Start device reading {'component2': <modules.devices.sma_sunny_boy.inverter.SmaSunnyBoyInverter object at 0x70427418>}
2024-06-30 16:30:20,105 - {modules.common.component_context:25} - {DEBUG:set values cp3} - Update Komponente ['Ladepunkt']
2024-06-30 16:30:20,111 - {modules.common.component_context:25} - {DEBUG:device1} - Update Komponente ['Sma Sunny Boy/Tripower Wechselrichter']
2024-06-30 16:30:20,503 - {modules.common.component_context:25} - {DEBUG:device0} - Update Komponente ['SMA Home Manager Zähler']
2024-06-30 16:30:20,504 - {modules.common.store._api:26} - {DEBUG:device0} - Raw data CounterState(currents=[-18.122, -17.708, -18.51], powers=[-4287.4, -4199.0, -4340.5], voltages=[238.208, 238.145, 235.885], power_factors=[0.996, 0.999, 0.997], imported=33694622.9, exported=90690056.7, power=-12826.9, frequency=49.994)
2024-06-30 16:30:20,514 - {modules.devices.sma_shm.device:28} - {DEBUG:device0} - All components updated
2024-06-30 16:30:20,839 - {modules.devices.sma_sunny_boy.inverter:75} - {DEBUG:device1} - WR 192.168.178.45: InverterState(currents=[0.0, 0.0, 0.0], power=-13208, exported=110299019, dc_power=-13514)
2024-06-30 16:30:20,840 - {modules.common.store._api:26} - {DEBUG:device1} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-13208, exported=110299019, dc_power=-13514)
2024-06-30 16:30:20,843 - {modules.common.component_context:25} - {DEBUG:component2} - Update Komponente ['Sma Sunny Boy/Tripower Wechselrichter']
2024-06-30 16:30:20,845 - {modules.common.component_context:25} - {DEBUG:update values cp3} - Update Komponente ['Ladepunkt']
2024-06-30 16:30:20,845 - {modules.common.store._api:26} - {DEBUG:component2} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-13208, exported=110299019, dc_power=None)
2024-06-30 16:30:20,853 - {modules.common.store._api:31} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-13208, exported=110299019, dc_power=None)
2024-06-30 16:30:20,894 - {modules.common.component_context:25} - {DEBUG:component0} - Update Komponente ['SMA Home Manager Zähler']
2024-06-30 16:30:20,895 - {modules.common.store._api:31} - {INFO:component0} - Saving CounterState(currents=[-18.122, -17.708, -18.51], powers=[-4287.4, -4199.0, -4340.5], voltages=[238.208, 238.145, 235.885], power_factors=[0.996, 0.999, 0.997], imported=33694622.9, exported=90690056.7, power=-12826.9, frequency=49.994)
2024-06-30 16:30:20,994 - {control.counter:146} - {INFO:MainThread} - Verbleibende Ströme an Zähler 0: [34.120000000000005, 33.71, 34.510000000000005]A
2024-06-30 16:30:20,995 - {control.counter:169} - {INFO:MainThread} - Verbleibende Leistung an Zähler 0: 75826.9W
2024-06-30 16:30:21,004 - {control.chargepoint.chargepoint:557} - {DEBUG:MainThread} - EV-Phasenzahl beschränkt die nutzbaren Phasen auf 3
2024-06-30 16:30:21,010 - {control.chargepoint.chargepoint:543} - {DEBUG:MainThread} - Umschaltung wird durchgeführt, Phasenzahl nicht ändern 3
2024-06-30 16:30:21,020 - {control.chargepoint.chargepoint:682} - {INFO:MainThread} - LP 3, EV: Standard-Fahrzeug (EV-Nr.0): Theoretisch benötigter Strom 6A, Lademodus pv_charging, Submodus: Chargemode.INSTANT_CHARGING, Phasen: 3, Priorität: False, max. Ist-Strom: 0.0
2024-06-30 16:30:21,035 - {modules.common.simcount._simcount:42} - {DEBUG:MainThread} - Previous state: SimCounterState(timestamp=1719757802.6670532, power=431.10999999999876, imported=6908919.73215039, exported=0)
2024-06-30 16:30:21,036 - {modules.common.simcount._calculate:11} - {DEBUG:MainThread} - time passed: 0.00510227, power1: 431.11, power2: 381.1
2024-06-30 16:30:21,037 - {modules.common.simcount._calculate:27} - {DEBUG:MainThread} - power did not change sign. Total energy: 2.07206
2024-06-30 16:30:21,039 - {modules.common.simcount._simcount:65} - {DEBUG:MainThread} - imported: 2.07206 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1719757821.0352342, power=381.10000000000036, imported=6908921.804208762, exported=0)
2024-06-30 16:30:21,043 - {control.data:236} - {INFO:MainThread} - bat_all_data
BatAllData(config=Config(configured=False), get=Get(soc=0, daily_exported=0.0, daily_imported=0.0, fault_str='Kein Fehler.', fault_state=0, imported=0, exported=0, power=0), set=Set(charging_power_left=0, regulate_up=False))
2024-06-30 16:30:21,049 - {control.data:237} - {INFO:MainThread} - cp_all_data
AllChargepointData(get=AllGet(daily_imported=18192.0, daily_exported=0.0, power=0, imported=5516090.82, exported=0))
2024-06-30 16:30:21,051 - {control.data:266} - {INFO:MainThread} - cp3
ChargepointData(control_parameter=ControlParameter(chargemode=<Chargemode.PV_CHARGING: 'pv_charging'>, current_plan=None, failed_phase_switches=1, imported_at_plan_start=None, imported_instant_charging=None, limit=None, phases=3, prio=False, required_current=6, required_currents=[6, 6, 6], state=2, submode=<Chargemode.INSTANT_CHARGING: 'instant_charging'>, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=1719757802.683794, 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=67), currents=[0.0, 0.0, 0.0], daily_imported=18192.0, daily_exported=0.0, evse_current=0, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=5516090.82, phases_in_use=1, plug_state=True, power=0, rfid_timestamp=None, rfid=None, serial_number='21207013', soc=None, soc_timestamp=None, state_str=None, vehicle_id=None, voltages=[237.86, 237.98, 235.69]), set=Set(charging_ev=0, charging_ev_prev=0, current=0, energy_to_charge=0, loadmanagement_available=True, log=Log(chargemode_log_entry='pv_charging', costs=0, imported_at_mode_switch=5514778.81, imported_at_plugtime=5514778.81, imported_since_mode_switch=1308.1000000005588, imported_since_plugged=1308.1000000005588, range_charged=6.540500000002794, time_charged='0:26', timestamp_start_charging=1719756221.466914, ev=0, prio=False, rfid=None, serial_number=None), manual_lock=False, phases_to_use=3, plug_state_prev=True, plug_time=1719756161.601283, required_power=4140, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0x70d1c880>), config=Config(configuration={'mode': 'series', 'ip_address': 'localhost', 'duo_num': 0}, ev=0, name='Interne openWB', type='internal_openwb', template=0, connected_phases=3, phase_1=1, auto_phase_switch_hw=True, control_pilot_interruption_hw=False, id=3))
2024-06-30 16:30:21,052 - {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', disable_after_unplug=False, valid_tags=[])
2024-06-30 16:30:21,054 - {control.data:266} - {INFO:MainThread} - counter0
CounterData(config=Config(max_currents=[16, 16, 16], max_total_power=63000), get=Get(powers=[-4287.4, -4199.0, -4340.5], currents=[-18.12, -17.71, -18.51], voltages=[238.21, 238.15, 235.88], power_factors=[1.0, 1.0, 1.0], unbalanced_load=0, frequency=49.994, daily_exported=14419.0, daily_imported=10477.0, imported=33694622.9, exported=90690056.7, fault_state=0, fault_str='Kein Fehler.', power=-12826.9), set=Set(error_counter=0, reserved_surplus=11040, released_surplus=0, raw_power_left=75826.9, raw_currents_left=[34.120000000000005, 33.71, 34.510000000000005], surplus_power_left=0, state_str=''))
2024-06-30 16:30:21,055 - {control.data:241} - {INFO:MainThread} - counter_all_data
CounterAllData(config=Config(home_consumption_source_id=None, reserve_for_not_charging=False), get=Get(hierarchy=[{'id': 0, 'type': 'counter', 'children': [{'id': 2, 'type': 'inverter', 'children': []}, {'id': 3, 'type': 'cp', 'children': []}]}]), set=Set(loadmanagement_active=False, home_consumption=381.10000000000036, smarthome_power_excluded_from_home_consumption=0, invalid_home_consumption=0, daily_yield_home_consumption=12969.0, imported_home_consumption=6908921.804208762, disengageable_smarthome_power=0))
2024-06-30 16:30:21,057 - {control.data:266} - {INFO:MainThread} - ct0
ChargeTemplateData(name='Standard-Lade-Profil', prio=False, load_default=False, et=Et(active=False, max_price=0.0002), time_charging=TimeCharging(active=False, plans={}), chargemode=Chargemode(selected='pv_charging', pv_charging=PvCharging(min_soc_current=6, min_current=6, feed_in_limit=False, min_soc=0, max_soc=101), scheduled_charging=ScheduledCharging(plans={'0': ScheduledChargingPlan(active=True, frequency=Frequency(selected='once', once='2024-06-29', weekly=[False, False, False, False, False, False, False]), current=10, name='neuer Zielladen-Plan', limit=ScheduledLimit(selected='soc', amount=1000, soc_limit=100, soc_scheduled=100), time='10:00')}), instant_charging=InstantCharging(current=6, limit=Limit(selected='soc', amount=100000, soc=85))))
2024-06-30 16:30:21,058 - {control.data:266} - {INFO:MainThread} - ev0
EvData(set=Set(soc_error_counter=0), charge_template=0, ev_template=0, name='Standard-Fahrzeug', tag_id=[], get=Get(soc=67, soc_timestamp=1719757556.533352, force_soc_update=False, range=250.0, fault_state=0, fault_str='Kein Fehler.'))
2024-06-30 16:30:21,059 - {control.data:266} - {INFO:MainThread} - et0
EvTemplateData(name='Standard-Fahrzeug-Profil', max_current_multi_phases=16, max_phases=3, phase_switch_pause=30, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=10, average_consump=20000, min_current=6, max_current_single_phase=16, battery_capacity=73000, efficiency=90, nominal_difference=2, keep_charge_active_duration=60)
2024-06-30 16:30:21,061 - {control.data:245} - {INFO:MainThread} - general_data
GeneralData(chargemode_config=ChargemodeConfig(instant_charging=InstantCharging(phases_to_use=1), pv_charging=PvCharging(bat_power_reserve=200, bat_power_reserve_active=True, control_range=[0, 230], feed_in_yield=0, phases_to_use=0, bat_power_discharge=1000, bat_power_discharge_active=True, min_bat_soc=50, bat_mode='ev_mode', switch_off_delay=120, switch_off_threshold=50, switch_on_delay=10, switch_on_threshold=1500), retry_failed_phase_switches=True, scheduled_charging=ScheduledCharging(phases_to_use=3, phases_to_use_pv=0), time_charging=TimeCharging(phases_to_use=3), unbalanced_load_limit=18, unbalanced_load=False, phase_switch_delay=7), control_interval=20, 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, prices=Prices(bat=0, cp=0, grid=0.00025, pv=6e-05), range_unit='km', ripple_control_receiver=RippleControlReceiver(get=RippleControlReceiverGet(fault_state=0, fault_str='Kein Fehler.', override_value=100), module=None, overrice_reference=<OverrideReference.CHARGEPOINT: 'chargepoint'>))
2024-06-30 16:30:21,063 - {control.data:246} - {INFO:MainThread} - general_data-display
primary
2024-06-30 16:30:21,064 - {control.data:247} - {INFO:MainThread} - graph_data
GraphData(config=Config(duration=120))
2024-06-30 16:30:21,065 - {control.data:248} - {INFO:MainThread} - optional_data
OptionalData(et=Et(get=EtGet(fault_state=0, fault_str='Kein Fehler.', prices={'1719756000': 9.100000000000001e-07, '1719759600': 3.666e-05, '1719763200': 7.002e-05, '1719766800': 8.726e-05, '1719770400': 9.99e-05, '1719774000': 0.00010239, '1719777600': 0.00010216, '1719781200': 9.301e-05, '1719784800': 9.488e-05, '1719788400': 8.431999999999999e-05, '1719792000': 7.819e-05, '1719795600': 7.801000000000001e-05, '1719799200': 7.562999999999999e-05, '1719802800': 8.081999999999999e-05, '1719806400': 0.00012219, '1719810000': 0.00014130000000000002, '1719813600': 0.00014236, '1719817200': 0.00011556999999999999, '1719820800': 0.00010701000000000001, '1719824400': 9.167000000000001e-05, '1719828000': 7.738e-05, '1719831600': 6.99e-05, '1719835200': 3.9920000000000004e-05, '1719838800': 3.461e-05, '1719842400': 3.7630000000000004e-05, '1719846000': 6.220000000000001e-05, '1719849600': 7.804e-05, '1719853200': 0.00010247, '1719856800': 0.00016679, '1719860400': 0.0001291, '1719864000': 0.00010751, '1719867600': 8.787999999999999e-05})), int_display=InternalDisplay(active=True, on_if_plugged_in=True, pin_active=False, pin_code='0000', standby=60, theme=<modules.display_themes.cards.config.CardsDisplayTheme object at 0x6faf8700>), led=Led(active=False), rfid=Rfid(active=False))
2024-06-30 16:30:21,066 - {control.data:266} - {INFO:MainThread} - pv2
PvData(config=Config(max_ac_out=25000), get=Get(currents=[0.0, 0.0, 0.0], daily_exported=35255.0, monthly_exported=3669021, yearly_exported=12364197, exported=110299019, fault_state=0, fault_str='Kein Fehler.', power=-13208))
2024-06-30 16:30:21,066 - {control.data:250} - {INFO:MainThread} - pv_all_data
PvAllData(config=Config(configured=True), get=Get(daily_exported=35255.0, fault_str='Kein Fehler.', fault_state=0, monthly_exported=3669021, yearly_exported=12364197, exported=110299019, power=-13208))
2024-06-30 16:30:21,067 - {control.data:266} - {INFO:MainThread} - system
{'update_in_progress': False, 'perform_update': False, 'backup_cloud': {'backup_before_update': True}, 'ip_address': '192.168.178.83', 'current_commit': '2024-06-28 11:54:05 +0200 [c67e9eb99]', 'current_branch': 'master', 'current_branch_commit': '2024-06-28 11:54:05 +0200 [c67e9eb99]', 'current_missing_commits': [], 'datastore_version': 48, 'dataprotection_acknowledged': True, 'usage_terms_acknowledged': True, 'debug_level': 10, 'release_train': 'master', 'version': '2.1.5-Alpha.1', 'boot_done': True, 'lastlivevaluesJson': {'timestamp': 1719757802, 'time': '16:30:02', 'grid': -8.881, 'house-power': 0.431, 'charging-all': 3.555, 'pv-all': 12.867, 'cp3-power': 3.555, 'ev0-soc': 67}, 'module_update_completed': True}
2024-06-30 16:30:21,067 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'name': 'SMA Home Manager', 'type': 'sma_shm', 'id': 0, 'configuration': <modules.devices.sma_shm.config.SpeedwireComponentConfiguration object at 0x70d1c1f0>}
2024-06-30 16:30:21,067 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {}
2024-06-30 16:30:21,067 - {control.data:279} - {INFO:MainThread} - device0
{'name': 'SMA Home Manager', 'type': 'sma_shm', 'id': 0, 'configuration': {}}
2024-06-30 16:30:21,068 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'name': 'SMA Home Manager Zähler', 'type': 'counter', 'id': 0, 'configuration': <modules.devices.sma_shm.config.SmaHomeManagerCounterConfiguration object at 0x7041af40>}
2024-06-30 16:30:21,068 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'serials': None}
2024-06-30 16:30:21,068 - {control.data:281} - {INFO:MainThread} - component0
{'name': 'SMA Home Manager Zähler', 'type': 'counter', 'id': 0, 'configuration': {'serials': None}}
2024-06-30 16:30:21,069 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'name': 'SMA Sunny Boy/Tripower', 'type': 'sma_sunny_boy', 'id': 1, 'configuration': <modules.devices.sma_sunny_boy.config.SmaSunnyBoyConfiguration object at 0x70d1cf88>}
2024-06-30 16:30:21,069 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'ip_address': '192.168.178.45', 'port': 502}
2024-06-30 16:30:21,070 - {control.data:279} - {INFO:MainThread} - device1
{'name': 'SMA Sunny Boy/Tripower', 'type': 'sma_sunny_boy', 'id': 1, 'configuration': {'ip_address': '192.168.178.45', 'port': 502}}
2024-06-30 16:30:21,072 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'name': 'Sma Sunny Boy/Tripower Wechselrichter', 'type': 'inverter', 'id': 2, 'configuration': <modules.devices.sma_sunny_boy.config.SmaSunnyBoyInverterConfiguration object at 0x704274d8>}
2024-06-30 16:30:21,073 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'hybrid': False, 'version': 0, 'modbus_id': 3}
2024-06-30 16:30:21,075 - {control.data:281} - {INFO:MainThread} - component2
{'name': 'Sma Sunny Boy/Tripower Wechselrichter', 'type': 'inverter', 'id': 2, 'configuration': {'hybrid': False, 'version': 0, 'modbus_id': 3}}
2024-06-30 16:30:21,078 - {control.data:253} - {INFO:MainThread} -
2024-06-30 16:30:21,079 - {control.algorithm.algorithm:25} - {INFO:MainThread} - # Algorithmus
2024-06-30 16:30:21,080 - {control.algorithm.algorithm:30} - {INFO:MainThread} - **Mindestrom setzen**
2024-06-30 16:30:21,082 - {control.algorithm.min_current:20} - {INFO:MainThread} - Mode-Tuple Chargemode.PV_CHARGING - Chargemode.INSTANT_CHARGING - False, Zähler 0
2024-06-30 16:30:21,085 - {control.algorithm.min_current:34} - {DEBUG:MainThread} - LP3: Stromstärke 6A. Zuteilung ohne Berücksichtigung im Lastmanagement, da kein Ladestart zu erwarten ist und Reserve für nicht-ladende inaktiv.
2024-06-30 16:30:21,087 - {control.algorithm.algorithm:32} - {INFO:MainThread} - **Sollstrom setzen**
2024-06-30 16:30:21,090 - {control.algorithm.filter_chargepoints:102} - {DEBUG:MainThread} - Geordnete Ladepunkte [3]
2024-06-30 16:30:21,091 - {control.algorithm.filter_chargepoints:48} - {INFO:MainThread} - LP 3: Lädt nicht, daher keine weitere Berücksichtigung
2024-06-30 16:30:21,093 - {control.counter:250} - {DEBUG:MainThread} - Anpassen des Regelbereichs 115.0W
2024-06-30 16:30:21,093 - {control.counter:218} - {INFO:MainThread} - Überschuss zur PV-geführten Ladung: 12941.899999999994W
2024-06-30 16:30:21,094 - {control.counter:514} - {DEBUG:MainThread} - Zähler 0: Begrenzung der verbleibenden Leistung auf 12941.899999999994W
2024-06-30 16:30:21,095 - {control.algorithm.algorithm:38} - {INFO:MainThread} - **PV-geführten Strom setzen**
2024-06-30 16:30:21,097 - {control.algorithm.filter_chargepoints:102} - {DEBUG:MainThread} - Geordnete Ladepunkte [3]
2024-06-30 16:30:21,098 - {control.algorithm.filter_chargepoints:48} - {INFO:MainThread} - LP 3: Lädt nicht, daher keine weitere Berücksichtigung
2024-06-30 16:30:21,102 - {control.process:23} - {INFO:MainThread} - # Ladung starten.
2024-06-30 16:30:21,114 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'chargemode_log_entry': 'pv_charging', 'costs': 0, 'imported_at_mode_switch': 5514778.81, 'imported_at_plugtime': 5514778.81, 'imported_since_mode_switch': 1308.1000000005588, 'imported_since_plugged': 1312.0100000007078, 'range_charged': 6.540500000002794, 'time_charged': '0:26', 'timestamp_start_charging': 1719756221.466914, 'ev': 0, 'prio': False, 'rfid': None, 'serial_number': None}
2024-06-30 16:30:21,118 - {control.chargepoint.chargepoint:557} - {DEBUG:MainThread} - EV-Phasenzahl beschränkt die nutzbaren Phasen auf 3
2024-06-30 16:30:21,119 - {control.chargepoint.chargepoint:83} - {INFO:MainThread} - LP 3: Umschaltung von 1 auf 3 Phasen, dafür wird die Ladung unterbrochen.
2024-06-30 16:30:21,121 - {control.process:111} - {INFO:MainThread} - LP3: set current 0 A, state PERFORMING_PHASE_SWITCH
2024-06-30 16:30:21,123 - {modules.common.component_context:25} - {DEBUG:set current cp3} - Update Komponente ['Ladepunkt']
2024-06-30 16:30:21,144 - {control.counter:194} - {INFO:MainThread} - 11040W reservierte EVU-Leistung, 0W freigegebene EVU-Leistung
2024-06-30 16:30:25,395 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2024-06-30 16:30:30,412 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2024-06-30 16:30:30,415 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})
2024-06-30 16:30:30,416 - {root:75} - {INFO:MainThread} - # ***Start***
2024-06-30 16:30:35,444 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2024-06-30 16:30:40,460 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2024-06-30 16:30:40,463 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})
2024-06-30 16:30:40,465 - {root:75} - {INFO:MainThread} - # ***Start***
2024-06-30 16:30:40,541 - {modules.common.component_context:54} - {DEBUG:device0} - Update Komponenten ['SMA Home Manager Zähler']
2024-06-30 16:30:40,543 - {modules.devices.sma_sunny_boy.device:72} - {DEBUG:device1} - Start device reading {'component2': <modules.devices.sma_sunny_boy.inverter.SmaSunnyBoyInverter object at 0x70427418>}
2024-06-30 16:30:40,545 - {modules.common.component_context:25} - {DEBUG:set values cp3} - Update Komponente ['Ladepunkt']
2024-06-30 16:30:40,551 - {modules.common.component_context:25} - {DEBUG:device1} - Update Komponente ['Sma Sunny Boy/Tripower Wechselrichter']
2024-06-30 16:30:41,141 - {modules.devices.sma_sunny_boy.inverter:75} - {DEBUG:device1} - WR 192.168.178.45: InverterState(currents=[0.0, 0.0, 0.0], power=-13332, exported=110299093, dc_power=-13651)
2024-06-30 16:30:41,141 - {modules.common.store._api:26} - {DEBUG:device1} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-13332, exported=110299093, dc_power=-13651)
2024-06-30 16:30:41,498 - {modules.common.component_context:25} - {DEBUG:device0} - Update Komponente ['SMA Home Manager Zähler']
2024-06-30 16:30:41,500 - {modules.common.store._api:26} - {DEBUG:device0} - Raw data CounterState(currents=[-18.356, -17.986, -18.719], powers=[-4345.8, -4234.2, -4390.2], voltages=[238.342, 236.434, 235.935], power_factors=[0.996, 0.999, 0.997], imported=33694622.9, exported=90690132.5, power=-12970.2, frequency=49.98)
2024-06-30 16:30:41,505 - {modules.devices.sma_shm.device:28} - {DEBUG:device0} - All components updated
2024-06-30 16:30:41,517 - {modules.common.component_context:25} - {DEBUG:component2} - Update Komponente ['Sma Sunny Boy/Tripower Wechselrichter']
2024-06-30 16:30:41,520 - {modules.common.store._api:26} - {DEBUG:component2} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-13332, exported=110299093, dc_power=None)
2024-06-30 16:30:41,522 - {modules.common.component_context:25} - {DEBUG:update values cp3} - Update Komponente ['Ladepunkt']
2024-06-30 16:30:41,522 - {modules.common.store._api:31} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-13332, exported=110299093, dc_power=None)
2024-06-30 16:30:41,567 - {modules.common.component_context:25} - {DEBUG:component0} - Update Komponente ['SMA Home Manager Zähler']
2024-06-30 16:30:41,569 - {modules.common.store._api:31} - {INFO:component0} - Saving CounterState(currents=[-18.356, -17.986, -18.719], powers=[-4345.8, -4234.2, -4390.2], voltages=[238.342, 236.434, 235.935], power_factors=[0.996, 0.999, 0.997], imported=33694622.9, exported=90690132.5, power=-12970.2, frequency=49.98)
2024-06-30 16:30:41,695 - {control.counter:146} - {INFO:MainThread} - Verbleibende Ströme an Zähler 0: [34.36, 33.989999999999995, 34.72]A
2024-06-30 16:30:41,695 - {control.counter:169} - {INFO:MainThread} - Verbleibende Leistung an Zähler 0: 75970.2W
2024-06-30 16:30:41,705 - {control.chargepoint.chargepoint:557} - {DEBUG:MainThread} - EV-Phasenzahl beschränkt die nutzbaren Phasen auf 3
2024-06-30 16:30:41,707 - {control.chargepoint.chargepoint:543} - {DEBUG:MainThread} - Umschaltung wird durchgeführt, Phasenzahl nicht ändern 3
2024-06-30 16:30:41,715 - {control.chargepoint.chargepoint:682} - {INFO:MainThread} - LP 3, EV: Standard-Fahrzeug (EV-Nr.0): Theoretisch benötigter Strom 6A, Lademodus pv_charging, Submodus: Chargemode.INSTANT_CHARGING, Phasen: 3, Priorität: False, max. Ist-Strom: 0.0
2024-06-30 16:30:41,730 - {modules.common.simcount._simcount:42} - {DEBUG:MainThread} - Previous state: SimCounterState(timestamp=1719757821.0352342, power=381.10000000000036, imported=6908921.804208762, exported=0)
2024-06-30 16:30:41,737 - {modules.common.simcount._calculate:11} - {DEBUG:MainThread} - time passed: 0.00574853, power1: 381.1, power2: 361.8
2024-06-30 16:30:41,737 - {modules.common.simcount._calculate:27} - {DEBUG:MainThread} - power did not change sign. Total energy: 2.13529
2024-06-30 16:30:41,739 - {modules.common.simcount._simcount:65} - {DEBUG:MainThread} - imported: 2.13529 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1719757841.729945, power=361.7999999999993, imported=6908923.939500513, exported=0)
2024-06-30 16:30:41,743 - {control.data:236} - {INFO:MainThread} - bat_all_data
BatAllData(config=Config(configured=False), get=Get(soc=0, daily_exported=0.0, daily_imported=0.0, fault_str='Kein Fehler.', fault_state=0, imported=0, exported=0, power=0), set=Set(charging_power_left=0, regulate_up=False))
2024-06-30 16:30:41,744 - {control.data:237} - {INFO:MainThread} - cp_all_data
AllChargepointData(get=AllGet(daily_imported=18192.0, daily_exported=0.0, power=0, imported=5516090.82, exported=0))
2024-06-30 16:30:41,745 - {control.data:266} - {INFO:MainThread} - cp3
ChargepointData(control_parameter=ControlParameter(chargemode=<Chargemode.PV_CHARGING: 'pv_charging'>, current_plan=None, failed_phase_switches=1, imported_at_plan_start=None, imported_instant_charging=None, limit=None, phases=3, prio=False, required_current=6, required_currents=[6, 6, 6], state=2, submode=<Chargemode.INSTANT_CHARGING: 'instant_charging'>, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=1719757802.683794, 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=67), currents=[0.0, 0.0, 0.0], daily_imported=18192.0, daily_exported=0.0, evse_current=0, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=5516090.82, phases_in_use=1, plug_state=True, power=0, rfid_timestamp=None, rfid=None, serial_number='21207013', soc=None, soc_timestamp=None, state_str=None, vehicle_id=None, voltages=[237.86, 236.25, 236.13]), set=Set(charging_ev=0, charging_ev_prev=0, current=0, energy_to_charge=0, loadmanagement_available=True, log=Log(chargemode_log_entry='pv_charging', costs=0, imported_at_mode_switch=5514778.81, imported_at_plugtime=5514778.81, imported_since_mode_switch=1308.1000000005588, imported_since_plugged=1312.0100000007078, range_charged=6.540500000002794, time_charged='0:26', timestamp_start_charging=1719756221.466914, ev=0, prio=False, rfid=None, serial_number=None), manual_lock=False, phases_to_use=3, plug_state_prev=True, plug_time=1719756161.601283, required_power=4140, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0x6fae2be0>), config=Config(configuration={'mode': 'series', 'ip_address': 'localhost', 'duo_num': 0}, ev=0, name='Interne openWB', type='internal_openwb', template=0, connected_phases=3, phase_1=1, auto_phase_switch_hw=True, control_pilot_interruption_hw=False, id=3))
2024-06-30 16:30:41,747 - {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', disable_after_unplug=False, valid_tags=[])
2024-06-30 16:30:41,748 - {control.data:266} - {INFO:MainThread} - counter0
CounterData(config=Config(max_currents=[16, 16, 16], max_total_power=63000), get=Get(powers=[-4345.8, -4234.2, -4390.2], currents=[-18.36, -17.99, -18.72], voltages=[238.34, 236.43, 235.94], power_factors=[1.0, 1.0, 1.0], unbalanced_load=0, frequency=49.98, daily_exported=14419.0, daily_imported=10477.0, imported=33694622.9, exported=90690132.5, fault_state=0, fault_str='Kein Fehler.', power=-12970.2), set=Set(error_counter=0, reserved_surplus=11040, released_surplus=0, raw_power_left=75970.2, raw_currents_left=[34.36, 33.989999999999995, 34.72], surplus_power_left=0, state_str=''))
2024-06-30 16:30:41,749 - {control.data:241} - {INFO:MainThread} - counter_all_data
CounterAllData(config=Config(home_consumption_source_id=None, reserve_for_not_charging=False), get=Get(hierarchy=[{'id': 0, 'type': 'counter', 'children': [{'id': 2, 'type': 'inverter', 'children': []}, {'id': 3, 'type': 'cp', 'children': []}]}]), set=Set(loadmanagement_active=False, home_consumption=361.7999999999993, smarthome_power_excluded_from_home_consumption=0, invalid_home_consumption=0, daily_yield_home_consumption=12969.0, imported_home_consumption=6908923.939500513, disengageable_smarthome_power=0))
2024-06-30 16:30:41,750 - {control.data:266} - {INFO:MainThread} - ct0
ChargeTemplateData(name='Standard-Lade-Profil', prio=False, load_default=False, et=Et(active=False, max_price=0.0002), time_charging=TimeCharging(active=False, plans={}), chargemode=Chargemode(selected='pv_charging', pv_charging=PvCharging(min_soc_current=6, min_current=6, feed_in_limit=False, min_soc=0, max_soc=101), scheduled_charging=ScheduledCharging(plans={'0': ScheduledChargingPlan(active=True, frequency=Frequency(selected='once', once='2024-06-29', weekly=[False, False, False, False, False, False, False]), current=10, name='neuer Zielladen-Plan', limit=ScheduledLimit(selected='soc', amount=1000, soc_limit=100, soc_scheduled=100), time='10:00')}), instant_charging=InstantCharging(current=6, limit=Limit(selected='soc', amount=100000, soc=85))))
2024-06-30 16:30:41,751 - {control.data:266} - {INFO:MainThread} - ev0
EvData(set=Set(soc_error_counter=0), charge_template=0, ev_template=0, name='Standard-Fahrzeug', tag_id=[], get=Get(soc=67, soc_timestamp=1719757556.533352, force_soc_update=False, range=250.0, fault_state=0, fault_str='Kein Fehler.'))
2024-06-30 16:30:41,752 - {control.data:266} - {INFO:MainThread} - et0
EvTemplateData(name='Standard-Fahrzeug-Profil', max_current_multi_phases=16, max_phases=3, phase_switch_pause=30, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=10, average_consump=20000, min_current=6, max_current_single_phase=16, battery_capacity=73000, efficiency=90, nominal_difference=2, keep_charge_active_duration=60)
2024-06-30 16:30:41,754 - {control.data:245} - {INFO:MainThread} - general_data
GeneralData(chargemode_config=ChargemodeConfig(instant_charging=InstantCharging(phases_to_use=1), pv_charging=PvCharging(bat_power_reserve=200, bat_power_reserve_active=True, control_range=[0, 230], feed_in_yield=0, phases_to_use=0, bat_power_discharge=1000, bat_power_discharge_active=True, min_bat_soc=50, bat_mode='ev_mode', switch_off_delay=120, switch_off_threshold=50, switch_on_delay=10, switch_on_threshold=1500), retry_failed_phase_switches=True, scheduled_charging=ScheduledCharging(phases_to_use=3, phases_to_use_pv=0), time_charging=TimeCharging(phases_to_use=3), unbalanced_load_limit=18, unbalanced_load=False, phase_switch_delay=7), control_interval=20, 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, prices=Prices(bat=0, cp=0, grid=0.00025, pv=6e-05), range_unit='km', ripple_control_receiver=RippleControlReceiver(get=RippleControlReceiverGet(fault_state=0, fault_str='Kein Fehler.', override_value=100), module=None, overrice_reference=<OverrideReference.CHARGEPOINT: 'chargepoint'>))
2024-06-30 16:30:41,756 - {control.data:246} - {INFO:MainThread} - general_data-display
primary
2024-06-30 16:30:41,757 - {control.data:247} - {INFO:MainThread} - graph_data
GraphData(config=Config(duration=120))
2024-06-30 16:30:41,758 - {control.data:248} - {INFO:MainThread} - optional_data
OptionalData(et=Et(get=EtGet(fault_state=0, fault_str='Kein Fehler.', prices={'1719756000': 9.100000000000001e-07, '1719759600': 3.666e-05, '1719763200': 7.002e-05, '1719766800': 8.726e-05, '1719770400': 9.99e-05, '1719774000': 0.00010239, '1719777600': 0.00010216, '1719781200': 9.301e-05, '1719784800': 9.488e-05, '1719788400': 8.431999999999999e-05, '1719792000': 7.819e-05, '1719795600': 7.801000000000001e-05, '1719799200': 7.562999999999999e-05, '1719802800': 8.081999999999999e-05, '1719806400': 0.00012219, '1719810000': 0.00014130000000000002, '1719813600': 0.00014236, '1719817200': 0.00011556999999999999, '1719820800': 0.00010701000000000001, '1719824400': 9.167000000000001e-05, '1719828000': 7.738e-05, '1719831600': 6.99e-05, '1719835200': 3.9920000000000004e-05, '1719838800': 3.461e-05, '1719842400': 3.7630000000000004e-05, '1719846000': 6.220000000000001e-05, '1719849600': 7.804e-05, '1719853200': 0.00010247, '1719856800': 0.00016679, '1719860400': 0.0001291, '1719864000': 0.00010751, '1719867600': 8.787999999999999e-05})), int_display=InternalDisplay(active=True, on_if_plugged_in=True, pin_active=False, pin_code='0000', standby=60, theme=<modules.display_themes.cards.config.CardsDisplayTheme object at 0x6fad7538>), led=Led(active=False), rfid=Rfid(active=False))
2024-06-30 16:30:41,759 - {control.data:266} - {INFO:MainThread} - pv2
PvData(config=Config(max_ac_out=25000), get=Get(currents=[0.0, 0.0, 0.0], daily_exported=35255.0, monthly_exported=3669021, yearly_exported=12364197, exported=110299093, fault_state=0, fault_str='Kein Fehler.', power=-13332))
2024-06-30 16:30:41,760 - {control.data:250} - {INFO:MainThread} - pv_all_data
PvAllData(config=Config(configured=True), get=Get(daily_exported=35255.0, fault_str='Kein Fehler.', fault_state=0, monthly_exported=3669021, yearly_exported=12364197, exported=110299093, power=-13332))
2024-06-30 16:30:41,761 - {control.data:266} - {INFO:MainThread} - system
{'update_in_progress': False, 'perform_update': False, 'backup_cloud': {'backup_before_update': True}, 'ip_address': '192.168.178.83', 'current_commit': '2024-06-28 11:54:05 +0200 [c67e9eb99]', 'current_branch': 'master', 'current_branch_commit': '2024-06-28 11:54:05 +0200 [c67e9eb99]', 'current_missing_commits': [], 'datastore_version': 48, 'dataprotection_acknowledged': True, 'usage_terms_acknowledged': True, 'debug_level': 10, 'release_train': 'master', 'version': '2.1.5-Alpha.1', 'boot_done': True, 'lastlivevaluesJson': {'timestamp': 1719757821, 'time': '16:30:21', 'grid': -12.827, 'house-power': 0.381, 'charging-all': 0.0, 'pv-all': 13.208, 'cp3-power': 0.0, 'ev0-soc': 67}, 'module_update_completed': True}
2024-06-30 16:30:41,762 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'name': 'SMA Home Manager', 'type': 'sma_shm', 'id': 0, 'configuration': <modules.devices.sma_shm.config.SpeedwireComponentConfiguration object at 0x70d1c1f0>}
2024-06-30 16:30:41,763 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {}
2024-06-30 16:30:41,764 - {control.data:279} - {INFO:MainThread} - device0
{'name': 'SMA Home Manager', 'type': 'sma_shm', 'id': 0, 'configuration': {}}
2024-06-30 16:30:41,765 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'name': 'SMA Home Manager Zähler', 'type': 'counter', 'id': 0, 'configuration': <modules.devices.sma_shm.config.SmaHomeManagerCounterConfiguration object at 0x7041af40>}
2024-06-30 16:30:41,768 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'serials': None}
2024-06-30 16:30:41,769 - {control.data:281} - {INFO:MainThread} - component0
{'name': 'SMA Home Manager Zähler', 'type': 'counter', 'id': 0, 'configuration': {'serials': None}}
2024-06-30 16:30:41,770 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'name': 'SMA Sunny Boy/Tripower', 'type': 'sma_sunny_boy', 'id': 1, 'configuration': <modules.devices.sma_sunny_boy.config.SmaSunnyBoyConfiguration object at 0x70d1cf88>}
2024-06-30 16:30:41,770 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'ip_address': '192.168.178.45', 'port': 502}
2024-06-30 16:30:41,771 - {control.data:279} - {INFO:MainThread} - device1
{'name': 'SMA Sunny Boy/Tripower', 'type': 'sma_sunny_boy', 'id': 1, 'configuration': {'ip_address': '192.168.178.45', 'port': 502}}
2024-06-30 16:30:41,771 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'name': 'Sma Sunny Boy/Tripower Wechselrichter', 'type': 'inverter', 'id': 2, 'configuration': <modules.devices.sma_sunny_boy.config.SmaSunnyBoyInverterConfiguration object at 0x704274d8>}
2024-06-30 16:30:41,771 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'hybrid': False, 'version': 0, 'modbus_id': 3}
2024-06-30 16:30:41,772 - {control.data:281} - {INFO:MainThread} - component2
{'name': 'Sma Sunny Boy/Tripower Wechselrichter', 'type': 'inverter', 'id': 2, 'configuration': {'hybrid': False, 'version': 0, 'modbus_id': 3}}
2024-06-30 16:30:41,772 - {control.data:253} - {INFO:MainThread} -
2024-06-30 16:30:41,772 - {control.algorithm.algorithm:25} - {INFO:MainThread} - # Algorithmus
2024-06-30 16:30:41,773 - {control.algorithm.algorithm:30} - {INFO:MainThread} - **Mindestrom setzen**
2024-06-30 16:30:41,774 - {control.algorithm.min_current:20} - {INFO:MainThread} - Mode-Tuple Chargemode.PV_CHARGING - Chargemode.INSTANT_CHARGING - False, Zähler 0
2024-06-30 16:30:41,774 - {control.algorithm.min_current:34} - {DEBUG:MainThread} - LP3: Stromstärke 6A. Zuteilung ohne Berücksichtigung im Lastmanagement, da kein Ladestart zu erwarten ist und Reserve für nicht-ladende inaktiv.
2024-06-30 16:30:41,775 - {control.algorithm.algorithm:32} - {INFO:MainThread} - **Sollstrom setzen**
2024-06-30 16:30:41,777 - {control.algorithm.filter_chargepoints:102} - {DEBUG:MainThread} - Geordnete Ladepunkte [3]
2024-06-30 16:30:41,777 - {control.algorithm.filter_chargepoints:48} - {INFO:MainThread} - LP 3: Lädt nicht, daher keine weitere Berücksichtigung
2024-06-30 16:30:41,777 - {control.counter:250} - {DEBUG:MainThread} - Anpassen des Regelbereichs 115.0W
2024-06-30 16:30:41,778 - {control.counter:218} - {INFO:MainThread} - Überschuss zur PV-geführten Ladung: 13085.199999999997W
2024-06-30 16:30:41,778 - {control.counter:514} - {DEBUG:MainThread} - Zähler 0: Begrenzung der verbleibenden Leistung auf 13085.199999999997W
2024-06-30 16:30:41,778 - {control.algorithm.algorithm:38} - {INFO:MainThread} - **PV-geführten Strom setzen**
2024-06-30 16:30:41,779 - {control.algorithm.filter_chargepoints:102} - {DEBUG:MainThread} - Geordnete Ladepunkte [3]
2024-06-30 16:30:41,780 - {control.algorithm.filter_chargepoints:48} - {INFO:MainThread} - LP 3: Lädt nicht, daher keine weitere Berücksichtigung
2024-06-30 16:30:41,782 - {control.process:23} - {INFO:MainThread} - # Ladung starten.
2024-06-30 16:30:41,783 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'chargemode_log_entry': 'pv_charging', 'costs': 0, 'imported_at_mode_switch': 5514778.81, 'imported_at_plugtime': 5514778.81, 'imported_since_mode_switch': 1308.1000000005588, 'imported_since_plugged': 1312.0100000007078, 'range_charged': 6.540500000002794, 'time_charged': '0:26', 'timestamp_start_charging': 1719756221.466914, 'ev': 0, 'prio': False, 'rfid': None, 'serial_number': None}
2024-06-30 16:30:41,791 - {control.chargepoint.chargepoint:431} - {DEBUG:MainThread} - phase switch running
2024-06-30 16:30:41,795 - {control.process:111} - {INFO:MainThread} - LP3: set current 6 A, state WAIT_FOR_USING_PHASES
2024-06-30 16:30:41,796 - {modules.common.component_context:25} - {DEBUG:set current cp3} - Update Komponente ['Ladepunkt']
2024-06-30 16:30:41,836 - {control.counter:194} - {INFO:MainThread} - 0W reservierte EVU-Leistung, 0W freigegebene EVU-Leistung
2024-06-30 16:30:42,082 - {helpermodules.changed_values_handler:119} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/3/control_parameter/state, Payload 3, vorherige Payload: 2
2024-06-30 16:30:42,083 - {helpermodules.changed_values_handler:119} - {DEBUG:MainThread} - Topic openWB/set/chargepoint/3/control_parameter/timestamp_perform_phase_switch, Payload None, vorherige Payload: 1719757802.683794
2024-06-30 16:30:45,096 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2024-06-30 16:30:50,110 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2024-06-30 16:30:50,113 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})
2024-06-30 16:30:50,114 - {root:75} - {INFO:MainThread} - # ***Start***
2024-06-30 16:30:55,140 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2024-06-30 16:31:00,160 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=smarthome_handler, args=(), kwargs={})
2024-06-30 16:31:00,163 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})
2024-06-30 16:31:00,164 - {root:75} - {INFO:MainThread} - # ***Start***
2024-06-30 16:31:00,217 - {modules.common.component_context:54} - {DEBUG:device0} - Update Komponenten ['SMA Home Manager Zähler']
2024-06-30 16:31:00,221 - {modules.devices.sma_sunny_boy.device:72} - {DEBUG:device1} - Start device reading {'component2': <modules.devices.sma_sunny_boy.inverter.SmaSunnyBoyInverter object at 0x70427418>}
2024-06-30 16:31:00,223 - {modules.common.component_context:25} - {DEBUG:set values cp3} - Update Komponente ['Ladepunkt']
2024-06-30 16:31:00,229 - {modules.common.component_context:25} - {DEBUG:device1} - Update Komponente ['Sma Sunny Boy/Tripower Wechselrichter']
2024-06-30 16:31:00,492 - {modules.common.component_context:25} - {DEBUG:device0} - Update Komponente ['SMA Home Manager Zähler']
2024-06-30 16:31:00,492 - {modules.common.store._api:26} - {DEBUG:device0} - Raw data CounterState(currents=[-7.987, -12.545, -13.295], powers=[-1824.2, -2934.6, -3119.5], voltages=[236.603, 236.206, 235.792], power_factors=[0.994, 0.996, 0.999], imported=33694622.9, exported=90690191.30000001, power=-7878.3, frequency=49.974)
2024-06-30 16:31:00,494 - {modules.devices.sma_shm.device:28} - {DEBUG:device0} - All components updated
2024-06-30 16:31:00,821 - {modules.devices.sma_sunny_boy.inverter:75} - {DEBUG:device1} - WR 192.168.178.45: InverterState(currents=[0.0, 0.0, 0.0], power=-9517, exported=110299161, dc_power=-9737)
2024-06-30 16:31:00,822 - {modules.common.store._api:26} - {DEBUG:device1} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-9517, exported=110299161, dc_power=-9737)
2024-06-30 16:31:00,824 - {modules.common.component_context:25} - {DEBUG:component2} - Update Komponente ['Sma Sunny Boy/Tripower Wechselrichter']
2024-06-30 16:31:00,826 - {modules.common.store._api:26} - {DEBUG:component2} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-9517, exported=110299161, dc_power=None)
2024-06-30 16:31:00,828 - {modules.common.store._api:31} - {INFO:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-9517, exported=110299161, dc_power=None)
2024-06-30 16:31:00,827 - {modules.common.component_context:25} - {DEBUG:update values cp3} - Update Komponente ['Ladepunkt']
2024-06-30 16:31:00,859 - {modules.common.component_context:25} - {DEBUG:component0} - Update Komponente ['SMA Home Manager Zähler']
2024-06-30 16:31:00,859 - {modules.common.store._api:31} - {INFO:component0} - Saving CounterState(currents=[-7.987, -12.545, -13.295], powers=[-1824.2, -2934.6, -3119.5], voltages=[236.603, 236.206, 235.792], power_factors=[0.994, 0.996, 0.999], imported=33694622.9, exported=90690191.30000001, power=-7878.3, frequency=49.974)
2024-06-30 16:31:01,025 - {control.counter:146} - {INFO:MainThread} - Verbleibende Ströme an Zähler 0: [29.09, 28.57, 29.29]A
2024-06-30 16:31:01,029 - {control.counter:169} - {INFO:MainThread} - Verbleibende Leistung an Zähler 0: 72078.58W
2024-06-30 16:31:01,033 - {control.chargepoint.chargepoint:557} - {DEBUG:MainThread} - EV-Phasenzahl beschränkt die nutzbaren Phasen auf 3
2024-06-30 16:31:01,041 - {control.chargepoint.chargepoint:682} - {INFO:MainThread} - LP 3, EV: Standard-Fahrzeug (EV-Nr.0): Theoretisch benötigter Strom 6A, Lademodus pv_charging, Submodus: Chargemode.INSTANT_CHARGING, Phasen: 3, Priorität: False, max. Ist-Strom: 5.1
2024-06-30 16:31:01,049 - {modules.common.simcount._simcount:42} - {DEBUG:MainThread} - Previous state: SimCounterState(timestamp=1719757841.729945, power=361.7999999999993, imported=6908923.939500513, exported=0)
2024-06-30 16:31:01,053 - {modules.common.simcount._calculate:11} - {DEBUG:MainThread} - time passed: 0.00536646, power1: 361.8, power2: 438.42
2024-06-30 16:31:01,058 - {modules.common.simcount._calculate:27} - {DEBUG:MainThread} - power did not change sign. Total energy: 2.14718
2024-06-30 16:31:01,061 - {modules.common.simcount._simcount:65} - {DEBUG:MainThread} - imported: 2.14718 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1719757861.0492074, power=438.41999999999916, imported=6908926.086675546, exported=0)
2024-06-30 16:31:01,067 - {control.data:236} - {INFO:MainThread} - bat_all_data
BatAllData(config=Config(configured=False), get=Get(soc=0, daily_exported=0.0, daily_imported=0.0, fault_str='Kein Fehler.', fault_state=0, imported=0, exported=0, power=0), set=Set(charging_power_left=0, regulate_up=False))
2024-06-30 16:31:01,067 - {control.data:237} - {INFO:MainThread} - cp_all_data
AllChargepointData(get=AllGet(daily_imported=18192.0, daily_exported=0.0, power=1200.28, imported=5516092.77, exported=0))
2024-06-30 16:31:01,069 - {control.data:266} - {INFO:MainThread} - cp3
ChargepointData(control_parameter=ControlParameter(chargemode=<Chargemode.PV_CHARGING: 'pv_charging'>, current_plan=None, failed_phase_switches=1, imported_at_plan_start=None, imported_instant_charging=None, limit=None, phases=3, prio=False, required_current=6, required_currents=[6, 6, 6], state=3, submode=<Chargemode.INSTANT_CHARGING: 'instant_charging'>, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, timestamp_switch_on_off=None), 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=67), currents=[5.1, 0.03, 0.0], daily_imported=18192.0, daily_exported=0.0, evse_current=600, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=5516092.77, phases_in_use=1, plug_state=True, power=1200.28, rfid_timestamp=None, rfid=None, serial_number='21207013', soc=None, soc_timestamp=None, state_str=None, vehicle_id=None, voltages=[235.83, 236.19, 235.72]), set=Set(charging_ev=0, charging_ev_prev=0, current=6, energy_to_charge=0, loadmanagement_available=True, log=Log(chargemode_log_entry='pv_charging', costs=0, imported_at_mode_switch=5514778.81, imported_at_plugtime=5514778.81, imported_since_mode_switch=1308.1000000005588, imported_since_plugged=1312.0100000007078, range_charged=6.540500000002794, time_charged='0:26', timestamp_start_charging=1719756221.466914, ev=0, prio=False, rfid=None, serial_number=None), manual_lock=False, phases_to_use=3, plug_state_prev=True, plug_time=1719756161.601283, required_power=4140, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0x7044d178>), config=Config(configuration={'mode': 'series', 'ip_address': 'localhost', 'duo_num': 0}, ev=0, name='Interne openWB', type='internal_openwb', template=0, connected_phases=3, phase_1=1, auto_phase_switch_hw=True, control_pilot_interruption_hw=False, id=3))
2024-06-30 16:31:01,072 - {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', disable_after_unplug=False, valid_tags=[])
2024-06-30 16:31:01,073 - {control.data:266} - {INFO:MainThread} - counter0
CounterData(config=Config(max_currents=[16, 16, 16], max_total_power=63000), get=Get(powers=[-1824.2, -2934.6, -3119.5], currents=[-7.99, -12.54, -13.29], voltages=[236.6, 236.21, 235.79], power_factors=[0.99, 1.0, 1.0], unbalanced_load=0, frequency=49.974, daily_exported=14419.0, daily_imported=10477.0, imported=33694622.9, exported=90690191.30000001, fault_state=0, fault_str='Kein Fehler.', power=-7878.3), set=Set(error_counter=0, reserved_surplus=0, released_surplus=0, raw_power_left=72078.58, raw_currents_left=[29.09, 28.57, 29.29], surplus_power_left=0, state_str=''))
2024-06-30 16:31:01,077 - {control.data:241} - {INFO:MainThread} - counter_all_data
CounterAllData(config=Config(home_consumption_source_id=None, reserve_for_not_charging=False), get=Get(hierarchy=[{'id': 0, 'type': 'counter', 'children': [{'id': 2, 'type': 'inverter', 'children': []}, {'id': 3, 'type': 'cp', 'children': []}]}]), set=Set(loadmanagement_active=False, home_consumption=438.41999999999916, smarthome_power_excluded_from_home_consumption=0, invalid_home_consumption=0, daily_yield_home_consumption=12969.0, imported_home_consumption=6908926.086675546, disengageable_smarthome_power=0))
2024-06-30 16:31:01,083 - {control.data:266} - {INFO:MainThread} - ct0
ChargeTemplateData(name='Standard-Lade-Profil', prio=False, load_default=False, et=Et(active=False, max_price=0.0002), time_charging=TimeCharging(active=False, plans={}), chargemode=Chargemode(selected='pv_charging', pv_charging=PvCharging(min_soc_current=6, min_current=6, feed_in_limit=False, min_soc=0, max_soc=101), scheduled_charging=ScheduledCharging(plans={'0': ScheduledChargingPlan(active=True, frequency=Frequency(selected='once', once='2024-06-29', weekly=[False, False, False, False, False, False, False]), current=10, name='neuer Zielladen-Plan', limit=ScheduledLimit(selected='soc', amount=1000, soc_limit=100, soc_scheduled=100), time='10:00')}), instant_charging=InstantCharging(current=6, limit=Limit(selected='soc', amount=100000, soc=85))))
2024-06-30 16:31:01,087 - {control.data:266} - {INFO:MainThread} - ev0
EvData(set=Set(soc_error_counter=0), charge_template=0, ev_template=0, name='Standard-Fahrzeug', tag_id=[], get=Get(soc=67, soc_timestamp=1719757859.723621, force_soc_update=False, range=250.0, fault_state=0, fault_str='Kein Fehler.'))
2024-06-30 16:31:01,093 - {control.data:266} - {INFO:MainThread} - et0
EvTemplateData(name='Standard-Fahrzeug-Profil', max_current_multi_phases=16, max_phases=3, phase_switch_pause=30, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=10, average_consump=20000, min_current=6, max_current_single_phase=16, battery_capacity=73000, efficiency=90, nominal_difference=2, keep_charge_active_duration=60)
2024-06-30 16:31:01,094 - {control.data:245} - {INFO:MainThread} - general_data
GeneralData(chargemode_config=ChargemodeConfig(instant_charging=InstantCharging(phases_to_use=1), pv_charging=PvCharging(bat_power_reserve=200, bat_power_reserve_active=True, control_range=[0, 230], feed_in_yield=0, phases_to_use=0, bat_power_discharge=1000, bat_power_discharge_active=True, min_bat_soc=50, bat_mode='ev_mode', switch_off_delay=120, switch_off_threshold=50, switch_on_delay=10, switch_on_threshold=1500), retry_failed_phase_switches=True, scheduled_charging=ScheduledCharging(phases_to_use=3, phases_to_use_pv=0), time_charging=TimeCharging(phases_to_use=3), unbalanced_load_limit=18, unbalanced_load=False, phase_switch_delay=7), control_interval=20, 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, prices=Prices(bat=0, cp=0, grid=0.00025, pv=6e-05), range_unit='km', ripple_control_receiver=RippleControlReceiver(get=RippleControlReceiverGet(fault_state=0, fault_str='Kein Fehler.', override_value=100), module=None, overrice_reference=<OverrideReference.CHARGEPOINT: 'chargepoint'>))
2024-06-30 16:31:01,096 - {control.data:246} - {INFO:MainThread} - general_data-display
primary
2024-06-30 16:31:01,101 - {control.data:247} - {INFO:MainThread} - graph_data
GraphData(config=Config(duration=120))
2024-06-30 16:31:01,102 - {control.data:248} - {INFO:MainThread} - optional_data
OptionalData(et=Et(get=EtGet(fault_state=0, fault_str='Kein Fehler.', prices={'1719756000': 9.100000000000001e-07, '1719759600': 3.666e-05, '1719763200': 7.002e-05, '1719766800': 8.726e-05, '1719770400': 9.99e-05, '1719774000': 0.00010239, '1719777600': 0.00010216, '1719781200': 9.301e-05, '1719784800': 9.488e-05, '1719788400': 8.431999999999999e-05, '1719792000': 7.819e-05, '1719795600': 7.801000000000001e-05, '1719799200': 7.562999999999999e-05, '1719802800': 8.081999999999999e-05, '1719806400': 0.00012219, '1719810000': 0.00014130000000000002, '1719813600': 0.00014236, '1719817200': 0.00011556999999999999, '1719820800': 0.00010701000000000001, '1719824400': 9.167000000000001e-05, '1719828000': 7.738e-05, '1719831600': 6.99e-05, '1719835200': 3.9920000000000004e-05, '1719838800': 3.461e-05, '1719842400': 3.7630000000000004e-05, '1719846000': 6.220000000000001e-05, '1719849600': 7.804e-05, '1719853200': 0.00010247, '1719856800': 0.00016679, '1719860400': 0.0001291, '1719864000': 0.00010751, '1719867600': 8.787999999999999e-05})), int_display=InternalDisplay(active=True, on_if_plugged_in=True, pin_active=False, pin_code='0000', standby=60, theme=<modules.display_themes.cards.config.CardsDisplayTheme object at 0x6f088268>), led=Led(active=False), rfid=Rfid(active=False))
2024-06-30 16:31:01,103 - {control.data:266} - {INFO:MainThread} - pv2
PvData(config=Config(max_ac_out=25000), get=Get(currents=[0.0, 0.0, 0.0], daily_exported=35255.0, monthly_exported=3669021, yearly_exported=12364197, exported=110299161, fault_state=0, fault_str='Kein Fehler.', power=-9517))
2024-06-30 16:31:01,104 - {control.data:250} - {INFO:MainThread} - pv_all_data
PvAllData(config=Config(configured=True), get=Get(daily_exported=35255.0, fault_str='Kein Fehler.', fault_state=0, monthly_exported=3669021, yearly_exported=12364197, exported=110299161, power=-9517))
2024-06-30 16:31:01,105 - {control.data:266} - {INFO:MainThread} - system
{'update_in_progress': False, 'perform_update': False, 'backup_cloud': {'backup_before_update': True}, 'ip_address': '192.168.178.83', 'current_commit': '2024-06-28 11:54:05 +0200 [c67e9eb99]', 'current_branch': 'master', 'current_branch_commit': '2024-06-28 11:54:05 +0200 [c67e9eb99]', 'current_missing_commits': [], 'datastore_version': 48, 'dataprotection_acknowledged': True, 'usage_terms_acknowledged': True, 'debug_level': 10, 'release_train': 'master', 'version': '2.1.5-Alpha.1', 'boot_done': True, 'lastlivevaluesJson': {'timestamp': 1719757841, 'time': '16:30:41', 'grid': -12.97, 'house-power': 0.362, 'charging-all': 0.0, 'pv-all': 13.332, 'cp3-power': 0.0, 'ev0-soc': 67}, 'module_update_completed': True}
2024-06-30 16:31:01,106 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'name': 'SMA Home Manager', 'type': 'sma_shm', 'id': 0, 'configuration': <modules.devices.sma_shm.config.SpeedwireComponentConfiguration object at 0x70d1c1f0>}
2024-06-30 16:31:01,107 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {}
2024-06-30 16:31:01,108 - {control.data:279} - {INFO:MainThread} - device0
{'name': 'SMA Home Manager', 'type': 'sma_shm', 'id': 0, 'configuration': {}}
2024-06-30 16:31:01,109 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'name': 'SMA Home Manager Zähler', 'type': 'counter', 'id': 0, 'configuration': <modules.devices.sma_shm.config.SmaHomeManagerCounterConfiguration object at 0x7041af40>}
2024-06-30 16:31:01,110 - {dataclass_utils._dataclass_asdict:23} - {DEBUG:MainThread} - {'serials': None}
2024-06-30 16:31:01,112 - {control.data:281} - {INFO:MainThread} - component0