Feedback Nightly 101.1

[Stivmaster]

@LenaK

danke, Sofortladen funktioniert nun wieder. Auch PV laden wird gestartet, wird aber sofort wieder beendet. Ich denke aber aufgrund zu wenig PV Leistung. Hab das Limit zum testen auf 50W Überschuss gestellt.

Code: Alles auswählen

2023-01-13 09:51:21,196 - {control.algorithm.algorithm:26} - {DEBUG:MainThread} - # Algorithmus-Start
2023-01-13 09:51:21,197 - {control.chargepoint:349} - {INFO:MainThread} - LP 3: Ladevorgang wird nach Ablauf der Abschaltverzögerung 60s gestoppt.
2023-01-13 09:51:21,198 - {control.counter:313} - {DEBUG:MainThread} - LP3 Switch-Off-Threshold prüfen: EVU 3937.5W, freigegebener Überschuss 1380.0W, Einspeisegrenze 0W
2023-01-13 09:51:21,198 - {control.counter:320} - {DEBUG:MainThread} - Relevante Leistung für Löschen der Abschaltschwelle: 2557.5W, Schwelle: 50W
2023-01-13 09:51:21,198 - {control.algorithm.algorithm:32} - {DEBUG:MainThread} - **Mindestrom setzen**
2023-01-13 09:51:21,201 - {control.algorithm.min_current:18} - {DEBUG:MainThread} - Mode-Tuple (<Chargemode.PV_CHARGING: 'pv_charging'>, <Chargemode.PV_CHARGING: 'pv_charging'>, False), Zähler 0
2023-01-13 09:51:21,202 - {control.counter:124} - {DEBUG:MainThread} - Zähler 0: [25.22, 24.93, 25.799999999999997]A verbleibende Ströme, 18052.82W verbleibende Leistung
2023-01-13 09:51:21,202 - {control.algorithm.common:87} - {DEBUG:MainThread} - LP3: Stromstärke 6A
2023-01-13 09:51:21,203 - {control.algorithm.algorithm:34} - {DEBUG:MainThread} - **Sollstrom setzen**
2023-01-13 09:51:21,206 - {control.counter:159} - {DEBUG:MainThread} - Überschuss zur PV-geführten Ladung: 0W
2023-01-13 09:51:21,206 - {control.counter:407} - {DEBUG:MainThread} - Zähler 0: Begrenzung der verbleibenden Leistung auf 0W
2023-01-13 09:51:21,206 - {control.algorithm.algorithm:45} - {DEBUG:MainThread} - **Keine Leistung für PV-geführtes Laden übrig.**
2023-01-13 09:51:21,207 - {control.process:22} - {DEBUG:MainThread} - # Ladung starten.
2023-01-13 09:51:21,208 - {control.chargelog:52} - {DEBUG:MainThread} - imported_since_mode_switch 201.89999999990687 counter 3012761.96
2023-01-13 09:51:21,212 - {control.process:106} - {INFO:MainThread} - LP3: set current 6 A
2023-01-13 09:51:21,214 - {soc.modules.common.component_context:24} - {DEBUG:cp3} - Update Komponente ['Ladepunkt']
2023-01-13 09:51:21,219 - {soc.modules.common.component_context:24} - {DEBUG:cp4} - Update Komponente ['Ladepunkt']
2023-01-13 09:51:21,230 - {control.counter:141} - {DEBUG:MainThread} - 0W reservierte EVU-Leistung, 1380.0W freigegebene EVU-Leistung
2023-01-13 09:51:21,429 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=update, args=(), kwargs={})
2023-01-13 09:51:30,507 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})
2023-01-13 09:51:30,508 - {root:61} - {INFO:MainThread} - # ***Start*** 
2023-01-13 09:51:30,573 - {soc.modules.common.component_context:51} - {DEBUG:device0} - Update Komponenten ['Kostal Plenticore Zähler (KSEM)', 'Kostal WR Hausdach']
2023-01-13 09:51:30,576 - {soc.modules.common.component_context:51} - {DEBUG:device1} - Update Komponenten ['Kostal Plenticore Wechselrichter']
2023-01-13 09:51:30,580 - {soc.modules.common.component_context:24} - {DEBUG:cp3} - Update Komponente ['Ladepunkt']
2023-01-13 09:51:30,585 - {soc.modules.common.component_context:24} - {DEBUG:cp4} - Update Komponente ['Ladepunkt']
2023-01-13 09:51:30,600 - {soc.modules.common.store._api:26} - {DEBUG:device1} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-493, exported=44445788.0, dc_power=None)
2023-01-13 09:51:30,692 - {modules.common.simcount._simcount:38} - {DEBUG:device0} - Previous state: SimCounterState(timestamp=1673599881.0098543, power=3937.5, imported=380.0804157425683, exported=85.34212846414445)
2023-01-13 09:51:30,693 - {modules.common.simcount._calculate:13} - {DEBUG:device0} - time passed: 0.00268958, power1: 3937.5, power2: 3805.2
2023-01-13 09:51:30,693 - {modules.common.simcount._calculate:29} - {DEBUG:device0} - power did not change sign. Total energy: 10.4123
2023-01-13 09:51:30,693 - {modules.common.simcount._simcount:47} - {DEBUG:device0} - imported: 10.4123 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1673599890.6923552, power=3805.199951171875, imported=390.4927350168952, exported=85.34212846414445)
2023-01-13 09:51:30,694 - {soc.modules.common.store._api:26} - {DEBUG:device0} - Raw data CounterState(voltages=[229.91000366210938, 227.3400115966797, 227.22401428222656], powers=[1298.5, 1290.300048828125, 1157.7000732421875], currents=[5.74500036239624, 6.308000087738037, 5.179000377655029], power_factors=[1.0, 1.0, 1.0], imported=390.4927350168952, exported=85.34212846414445, power=3805.199951171875, frequency=49.99800109863281)
2023-01-13 09:51:30,708 - {soc.modules.common.store._api:26} - {DEBUG:device0} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-426, exported=39664752.0, dc_power=None)
2023-01-13 09:51:30,713 - {soc.modules.common.component_context:24} - {DEBUG:component1} - Update Komponente ['Kostal WR Hausdach']
2023-01-13 09:51:30,713 - {soc.modules.common.store._api:26} - {DEBUG:component1} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-426, exported=39664752.0, dc_power=None)
2023-01-13 09:51:30,714 - {soc.modules.common.store._api:30} - {DEBUG:component1} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-426, exported=39664752.0, dc_power=None)
2023-01-13 09:51:30,717 - {soc.modules.common.component_context:24} - {DEBUG:component2} - Update Komponente ['Kostal Plenticore Wechselrichter']
2023-01-13 09:51:30,718 - {soc.modules.common.store._api:26} - {DEBUG:component2} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-493, exported=44445788.0, dc_power=None)
2023-01-13 09:51:30,718 - {soc.modules.common.store._api:30} - {DEBUG:component2} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-493, exported=44445788.0, dc_power=None)
2023-01-13 09:51:30,732 - {soc.modules.common.component_context:24} - {DEBUG:cp3} - Update Komponente ['Ladepunkt']
2023-01-13 09:51:30,734 - {soc.modules.common.component_context:24} - {DEBUG:cp4} - Update Komponente ['Ladepunkt']
2023-01-13 09:51:30,777 - {soc.modules.common.component_context:24} - {DEBUG:component0} - Update Komponente ['Kostal Plenticore Zähler (KSEM)']
2023-01-13 09:51:30,778 - {soc.modules.common.store._api:30} - {DEBUG:component0} - Saving CounterState(voltages=[229.91000366210938, 227.3400115966797, 227.22401428222656], powers=[1298.5, 1290.300048828125, 1157.7000732421875], currents=[5.74500036239624, 6.308000087738037, 5.179000377655029], power_factors=[1.0, 1.0, 1.0], imported=390.4927350168952, exported=85.34212846414445, power=3805.199951171875, frequency=49.99800109863281)
2023-01-13 09:51:30,891 - {control.counter:87} - {DEBUG:MainThread} - Verbleibende Ströme an Zähler 0: [31.299999999999997, 30.76, 31.869999999999997]
2023-01-13 09:51:30,892 - {control.counter:116} - {DEBUG:MainThread} - Verbleibende Leistung an Zähler 0: 22327.420048828124
2023-01-13 09:51:30,894 - {control.chargepoint:696} - {DEBUG:MainThread} - EV-Phasenzahl beschränkt die nutzbaren Phasen auf 3
2023-01-13 09:51:30,894 - {control.chargepoint:687} - {DEBUG:MainThread} - Phasenzahl Lademodus: 3
2023-01-13 09:51:30,896 - {control.ev:618} - {DEBUG:MainThread} - Keine Ladung, da keine Zeitfenster für Zeitladen konfiguriert sind.
2023-01-13 09:51:30,898 - {control.ev:364} - {DEBUG:MainThread} - Änderung des Lademodus :False
2023-01-13 09:51:30,900 - {control.chargepoint:862} - {DEBUG:MainThread} - LP 3, EV: Standard-Fahrzeug (EV-Nr.0): Theoretisch benötigter Strom 6A, Lademodus pv_charging, Submodus: Chargemode.PV_CHARGING, Phasen: 3, Priorität: False, max. Ist-Strom: 6.07
2023-01-13 09:51:30,902 - {control.chargepoint:349} - {INFO:MainThread} - LP 4: Keine Ladung, da kein Auto angesteckt ist.
2023-01-13 09:51:30,906 - {control.data:297} - {DEBUG:MainThread} - all
{'get': {'power': 0}, 'config': {'configured': False}, 'set': {'charging_power_left': 0, 'switch_on_soc_reached': False}}
2023-01-13 09:51:30,908 - {control.data:270} - {DEBUG:MainThread} - cp_all_data
AllChargepointData(get=AllGet(daily_imported=6178757.08, daily_exported=0, power=4132.62, imported=6178826.91, exported=0))
2023-01-13 09:51:30,908 - {control.data:297} - {DEBUG:MainThread} - cp3
ChargepointData(get=Get(charge_state=True, connected_vehicle=ConnectedVehicle(config=ConnectedConfig(average_consumption=17, charge_template=0, chargemode='stop', current_plan=0, ev_template=0, priority=False), info=ConnectedInfo(id=0, name='Ladepunkt'), soc=49.0), currents=[6.05, 6.07, 6.05], daily_imported=3012704.1, daily_exported=0, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=3012773.93, phases_in_use=3, plug_state=True, power=4132.62, rfid_timestamp=None, rfid=None, state_str=None, voltages=[229.79, 226.77, 227.09]), set=Set(change_ev_permitted=[True, ''], charging_ev=0, charging_ev_prev=0, current=6, energy_to_charge=0, loadmanagement_available=True, log=Log(chargemode_log_entry='pv_charging', imported_at_mode_switch=3012560.06, imported_at_plugtime=3012391.11, imported_since_mode_switch=201.89999999990687, imported_since_plugged=370.85000000009313, range_charged=1.1876470588229817, time_charged='0:05', timestamp_start_charging='01/13/2023, 09:46:40'), manual_lock=False, phases_to_use=3, plug_state_prev=True, plug_time='01/13/2023, 09:36:30', required_power=4140, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0x713ddf70>), config=Config(connection_module={'type': 'external_openwb', 'name': 'Externe openWB', 'configuration': {'ip_address': '192.168.1.165', 'duo_num': 1}}, power_module={}, ev=0, name='Duo LP1', type='external_openwb', template=0, connected_phases=3, phase_1=1, auto_phase_switch_hw=False, control_pilot_interruption_hw=False, id=3))
2023-01-13 09:51:30,909 - {control.data:297} - {DEBUG:MainThread} - cp4
ChargepointData(get=Get(charge_state=False, connected_vehicle=ConnectedVehicle(config=ConnectedConfig(average_consumption=17, charge_template=0, chargemode='stop', current_plan=0, ev_template=0, priority=False), info=ConnectedInfo(id=0, name='Ladepunkt'), soc=49.0), currents=[0.0, 0.0, 0.0], daily_imported=3166052.98, daily_exported=0, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=3166052.98, phases_in_use=0, plug_state=False, power=0, rfid_timestamp=None, rfid=None, state_str='Keine Ladung, da kein Auto angesteckt ist.', voltages=[227.09, 229.66, 226.94]), set=Set(change_ev_permitted=False, charging_ev=-1, charging_ev_prev=-1, current=0, energy_to_charge=0, loadmanagement_available=True, log=Log(chargemode_log_entry='_', imported_at_mode_switch=0, imported_at_plugtime=0, imported_since_mode_switch=0, imported_since_plugged=0, range_charged=0, time_charged='00:00', timestamp_start_charging=None), manual_lock=False, phases_to_use=0, plug_state_prev=False, plug_time=None, required_power=0, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0x713e0e98>), config=Config(connection_module={'type': 'external_openwb', 'name': 'Externe openWB', 'configuration': {'ip_address': '192.168.1.165', 'duo_num': 2}}, power_module={}, ev=0, name='DUO LP2', type='external_openwb', template=0, connected_phases=3, phase_1=2, auto_phase_switch_hw=False, control_pilot_interruption_hw=False, id=4))
2023-01-13 09:51:30,910 - {control.data:297} - {DEBUG:MainThread} - cpt0
CpTemplateData(autolock=Autolock(active=False, plans={}, wait_for_charging_end=False), id=0, max_current_multi_phases=32, max_current_single_phase=32, name='Standard Ladepunkt-Vorlage', rfid_enabling=False, valid_tags=[])
2023-01-13 09:51:30,913 - {control.data:297} - {DEBUG:MainThread} - counter0
{'set': {'reserved_surplus': 0, 'released_surplus': 1380.0, 'raw_power_left': 22327.420048828124, 'surplus_power_left': 0, 'raw_currents_left': [31.299999999999997, 30.76, 31.869999999999997]}, 'get': {'daily_exported': 77.75985152811886, 'daily_imported': 324.9771831478705, 'fault_str': 'Kein Fehler.', 'fault_state': 0, 'voltages': [229.91, 227.34, 227.22], 'currents': [5.75, 6.31, 5.18], 'powers': [1298.5, 1290.3, 1157.7], 'power_factors': [1.0, 1.0, 1.0], 'imported': 390.4927350168952, 'exported': 85.34212846414445, 'power': 3805.199951171875, 'frequency': 49.99800109863281}, 'config': {'max_currents': [32, 32, 32], 'max_total_power': 22000}}
2023-01-13 09:51:30,915 - {control.data:274} - {DEBUG:MainThread} - counter_all_data
CounterAllData(get=Get(hierarchy=[{'id': 0, 'type': 'counter', 'children': [{'id': 1, 'type': 'inverter', 'children': []}, {'id': 2, 'type': 'inverter', 'children': []}, {'id': 3, 'type': 'cp', 'children': []}, {'id': 4, 'type': 'cp', 'children': []}]}]), set=Set(loadmanagement_active=False, home_consumption=591.5799511718751, invalid_home_consumption=0, daily_yield_home_consumption=119.48688183464895))
2023-01-13 09:51:30,915 - {control.data:297} - {DEBUG:MainThread} - ct0
ChargeTemplateData(name='Standard-Ladeprofil-Vorlage', disable_after_unplug=False, prio=False, load_default=False, time_charging=TimeCharging(active=False, plans={}), chargemode=Chargemode(selected='stop', pv_charging=PvCharging(min_soc_current=10, min_current=0, feed_in_limit=False, min_soc=0, max_soc=100), scheduled_charging=ScheduledCharging(plans={}), instant_charging=InstantCharging(current=10, limit=Limit(selected='none', amount=1000, soc=50))))
2023-01-13 09:51:30,916 - {control.data:297} - {DEBUG:MainThread} - ct1
ChargeTemplateData(name='Zoe', disable_after_unplug=False, prio=False, load_default=False, time_charging=TimeCharging(active=True, plans={}), chargemode=Chargemode(selected='pv_charging', pv_charging=PvCharging(min_soc_current=10, min_current=0, feed_in_limit=False, min_soc=0, max_soc=85), scheduled_charging=ScheduledCharging(plans={}), instant_charging=InstantCharging(current=16, limit=Limit(selected='soc', amount=1000, soc=85))))
2023-01-13 09:51:30,916 - {control.data:297} - {DEBUG:MainThread} - ct2
ChargeTemplateData(name='Tesla', disable_after_unplug=False, prio=False, load_default=False, time_charging=TimeCharging(active=True, plans={}), chargemode=Chargemode(selected='pv_charging', pv_charging=PvCharging(min_soc_current=10, min_current=0, feed_in_limit=False, min_soc=0, max_soc=100), scheduled_charging=ScheduledCharging(plans={}), instant_charging=InstantCharging(current=16, limit=Limit(selected='none', amount=1000, soc=100))))
2023-01-13 09:51:30,916 - {control.data:297} - {DEBUG:MainThread} - ev0
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='Tesla', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=17, min_current=6, max_current_single_phase=16, battery_capacity=82, nominal_difference=2), et_num=0)), control_parameter=ControlParameter(required_current=6, required_currents=[6, 6, 6], phases=3, prio=False, timestamp_switch_on_off='01/13/2023, 09:50:30', timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, submode=<Chargemode.PV_CHARGING: 'pv_charging'>, chargemode=<Chargemode.PV_CHARGING: 'pv_charging'>, used_amount_instant_charging=0, imported_at_plan_start=0, current_plan=None), charge_template=2, ev_template=2, name='Standard-Fahrzeug', tag_id=[], get=Get(soc=49.0, soc_timestamp='01/13/2023, 09:47:41', force_soc_update=False, range=269.56445, fault_state=0, fault_str='Kein Fehler.'))
2023-01-13 09:51:30,917 - {control.data:297} - {DEBUG:MainThread} - et0
EvTemplateData(name='Standard-Fahrzeug-Vorlage', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=17, min_current=6, max_current_single_phase=32, battery_capacity=82, nominal_difference=2)
2023-01-13 09:51:30,917 - {control.data:297} - {DEBUG:MainThread} - et1
EvTemplateData(name='Zoe', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=17, min_current=8, max_current_single_phase=16, battery_capacity=50, nominal_difference=2)
2023-01-13 09:51:30,918 - {control.data:297} - {DEBUG:MainThread} - et2
EvTemplateData(name='Tesla', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=17, min_current=6, max_current_single_phase=16, battery_capacity=82, nominal_difference=2)
2023-01-13 09:51:30,918 - {control.data:278} - {DEBUG:MainThread} - general_data
GeneralData(chargemode_config=ChargemodeConfig(instant_charging=InstantCharging(phases_to_use=3), pv_charging=PvCharging(bat_prio=True, charging_power_reserve=200, control_range=[-230, 0], feed_in_yield=15000, phase_switch_delay=7, phases_to_use=3, rundown_power=1000, rundown_soc=50, switch_off_delay=60, switch_off_soc=40, switch_off_threshold=50, switch_on_delay=30, switch_on_soc=60, switch_on_threshold=50), scheduled_charging=ScheduledCharging(phases_to_use=3), standby=Standby(phases_to_use=3), stop=Stop(phases_to_use=1), time_charging=TimeCharging(phases_to_use=3), unbalanced_load_limit=18, unbalanced_load=False), control_interval=10, extern_display_mode='local', extern=False, external_buttons_hw=False, grid_protection_active=False, grid_protection_configured=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-01-13 09:51:30,918 - {control.data:297} - {DEBUG:MainThread} - graph
{'config': {'duration': 120}}
2023-01-13 09:51:30,919 - {control.data:280} - {DEBUG:MainThread} - optional_data
OptionalData(et=Et(active=False, config=EtConfig(max_price=0, provider={}), get=EtGet(price=0, price_list=[])), int_display=InternalDisplay(active=False, on_if_plugged_in=True, pin_active=False, pin_code='0000', standby=60, theme='cards'), led=Led(active=False), rfid=Rfid(active=False))
2023-01-13 09:51:30,919 - {control.data:297} - {DEBUG:MainThread} - all
{'get': {'power': -919, 'exported': 84110540.0, 'daily_exported': 384.0, 'monthly_exported': 0, 'yearly_exported': 0}, 'config': {'configured': True}}
2023-01-13 09:51:30,919 - {control.data:297} - {DEBUG:MainThread} - pv1
{'get': {'daily_exported': 176.0, 'monthly_exported': 0, 'yearly_exported': 0, 'fault_str': 'Kein Fehler.', 'fault_state': 0, 'power': -426, 'exported': 39664752.0, 'currents': [0.0, 0.0, 0.0]}, 'config': {'max_ac_out': 10000}}
2023-01-13 09:51:30,919 - {control.data:297} - {DEBUG:MainThread} - pv2
{'get': {'daily_exported': 208.0, 'monthly_exported': 0, 'yearly_exported': 0, 'fault_str': 'Kein Fehler.', 'fault_state': 0, 'power': -493, 'exported': 44445788.0, 'currents': [0.0, 0.0, 0.0]}, 'config': {'max_ac_out': 10000}}
2023-01-13 09:51:30,920 - {control.data:297} - {DEBUG:MainThread} - system
{'update_in_progress': False, 'perform_update': False, 'boot_done': True, 'ip_address': '192.168.1.191', 'current_commit': '2023-01-13 09:17:59 +0100 [ca5eca86]', 'current_branch': 'master', 'current_branch_commit': '2023-01-13 09:17:59 +0100 [ca5eca86]', 'current_missing_commits': [], 'dataprotection_acknowledged': False, 'debug_level': 10, 'release_train': 'master', 'version': '1.99.101.1', 'datastore_version': 6, 'lastlivevaluesJson': {'timestamp': 1673599881, 'time': '09:51:21', 'grid': 3.938, 'house-power': 0.668, 'charging-all': 4.13, 'pv-all': 0.861, 'cp3-power': 4.13, 'cp4-power': 0.0, 'ev0-soc': 49.0}, 'module_update_completed': True}
2023-01-13 09:51:30,921 - {control.data:310} - {DEBUG:MainThread} - device0
{'name': 'Kostal Plenticore', 'type': 'kostal_plenticore', 'id': 0, 'configuration': {'ip_address': '192.168.1.215'}}
2023-01-13 09:51:30,921 - {control.data:312} - {DEBUG:MainThread} - component0
{'name': 'Kostal Plenticore Zähler (KSEM)', 'type': 'counter', 'id': 0, 'configuration': {}}
2023-01-13 09:51:30,921 - {control.data:312} - {DEBUG:MainThread} - component1
{'name': 'Kostal WR Hausdach', 'type': 'inverter', 'id': 1, 'configuration': {}}
2023-01-13 09:51:30,921 - {control.data:310} - {DEBUG:MainThread} - device1
{'name': 'Kostal WR Scheunen', 'type': 'kostal_plenticore', 'id': 1, 'configuration': {'ip_address': '192.168.1.216'}}
2023-01-13 09:51:30,921 - {control.data:312} - {DEBUG:MainThread} - component2
{'name': 'Kostal Plenticore Wechselrichter', 'type': 'inverter', 'id': 2, 'configuration': {}}
2023-01-13 09:51:30,922 - {control.data:284} - {DEBUG:MainThread} - 

[Gero]

Ich bekomme nun den Fehler mit dem daily_exported auch als Hausverbrauch:

Code: Alles auswählen

2023-01-12 11:55:01,462 - {helpermodules.setdata:287} - {ERROR:Thread-6} - Payload ungültig: Topic openWB/set/counter/set/daily_yield_home_consumption, Payload -8573135.910214795 liegt in keinem der angegebenen Wertebereiche.

Und dann habe ich gestern mal meine beiden openWBs in der 2.0 angelegt und mal Autos geladen. Hat soweit funktioniert, nur der 1phasige Ion wollte an der Buchse nicht laden. Aber irgendwie finde ich dazu nichts in den Logs. Also habe ich die custom wieder zum Master gemacht und die remote-Ladepunkte aus der 2.0er gelöscht. Das mag sie aber nicht - das nächste Mal werde ich sie wohl auf MQTT umstellen.

Code: Alles auswählen

2023-01-12 16:30:35,362 - {helpermodules.setdata:528} - {WARNING:Thread-6} - Kein Ladepunkt 5 mit gültiger Konfiguration gefunden.
2023-01-12 16:30:35,674 - {helpermodules.setdata:528} - {WARNING:Thread-6} - Kein Ladepunkt 4 mit gültiger Konfiguration gefunden.

Und dann wird mein main.log irgendwie nicht größer:

Code: Alles auswählen

pi@raspi:/var/www/html/openWB$ cd ramdisk/
pi@raspi:/var/www/html/openWB/ramdisk$ ls -l main.log
-rw-rw-rw- 1 openwb openwb 139008 13. Jan 10:05 main.log
pi@raspi:/var/www/html/openWB/ramdisk$ tail -f main.log
2023-01-12 16:32:32,119 - {helpermodules.setdata:528} - {WARNING:Thread-6} - Kein Ladepunkt 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:32:32,120 - {helpermodules.setdata:528} - {WARNING:Thread-6} - Kein Ladepunkt 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:32:32,121 - {helpermodules.setdata:528} - {WARNING:Thread-6} - Kein Ladepunkt 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:32:32,122 - {helpermodules.setdata:528} - {WARNING:Thread-6} - Kein Ladepunkt 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:32:32,123 - {helpermodules.setdata:528} - {WARNING:Thread-6} - Kein Ladepunkt 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:32:53,913 - {helpermodules.setdata:811} - {WARNING:Thread-6} - Kein Zähler 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:32:53,915 - {helpermodules.setdata:811} - {WARNING:Thread-6} - Kein Zähler 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:34:07,775 - {helpermodules.setdata:811} - {WARNING:Thread-6} - Kein Zähler 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:34:21,683 - {helpermodules.setdata:811} - {WARNING:Thread-6} - Kein Zähler 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:34:23,772 - {helpermodules.setdata:811} - {WARNING:Thread-6} - Kein Zähler 4 mit gültiger Konfiguration gefunden.

Version: 2023-01-12 09:55:58 +0100 [4e127a60]

[tobox]

Danke!

Gibt es eigentlich ein Tool, um den letzten **Start**-Durchlauf sauber aus der main.log zu bekommen? Ich mache das immer so, aber das ist schon eine ziemliche Krücke...

Code: Alles auswählen

tac /var/www/html/openWB/ramdisk/main.log | grep -A 10000 '\*Start\*' | tail -n +2 | grep -B 10000 -m 1 '\*Start\*' | tac 

[LenaK]

nochmal zum Thema "kein Zeitladen nach erfolgreichem Zielladen"
Version master 2023-01-12 16:40:37 +0100 [2bc7920f]
Es startet noch nicht.
20230112_oWB_2.0_kein_Zeitladen.jpg

Einstellungen:
20230112_oWB_2.0_kein_Zeitladen_2.jpg

Hier gibt es noch einen Fehler
File "/var/www/html/openWB/packages/control/general.py", line 156, in get_phases_chargemode
return getattr(self.data.chargemode_config, chargemode).phases_to_use
TypeError: getattr(): attribute name must be string

Code: Alles auswählen

2023-01-12 22:15:22,897 - {control.algorithm.algorithm:26} - {DEBUG:MainThread} - # Algorithmus-Start
2023-01-12 22:15:22,898 - {control.general:158} - {ERROR:MainThread} - Fehler im General-Modul
Traceback (most recent call last):
  File "/var/www/html/openWB/packages/control/general.py", line 156, in get_phases_chargemode
    return getattr(self.data.chargemode_config, chargemode).phases_to_use
TypeError: getattr(): attribute name must be string
2023-01-12 22:15:22,911 - {control.algorithm.algorithm:32} - {DEBUG:MainThread} - **Mindestrom setzen**
2023-01-12 22:15:22,918 - {control.algorithm.min_current:18} - {DEBUG:MainThread} - Mode-Tuple (<Chargemode.SCHEDULED_CHARGING: 'scheduled_charging'>, <Chargemode.PV_CHARGING: 'pv_charging'>, True), Zähler 0
2023-01-12 22:15:22,922 - {control.algorithm.algorithm:34} - {DEBUG:MainThread} - **Sollstrom setzen**
2023-01-12 22:15:22,953 - {control.counter:159} - {DEBUG:MainThread} - Überschuss zur PV-geführten Ladung: 0W
2023-01-12 22:15:22,954 - {control.counter:407} - {DEBUG:MainThread} - Zähler 0: Begrenzung der verbleibenden Leistung auf 0W
2023-01-12 22:15:22,955 - {control.chargepoint:349} - {INFO:MainThread} - LP 5: Die Ladung kann nicht gestartet werden, da die Einschaltschwelle 900W nicht erreicht wird.
2023-01-12 22:15:22,955 - {control.algorithm.algorithm:45} - {DEBUG:MainThread} - **Keine Leistung für PV-geführtes Laden übrig.**
2023-01-12 22:15:22,956 - {control.process:22} - {DEBUG:MainThread} - # Ladung starten.
2023-01-12 22:15:22,960 - {control.process:106} - {INFO:MainThread} - LP5: set current 0 A
2023-01-12 22:15:22,964 - {soc.modules.common.component_context:24} - {DEBUG:cp5} - Update Komponente ['Ladepunkt']
2023-01-12 22:15:22,972 - {soc.modules.common.component_context:24} - {DEBUG:cp11} - Update Komponente ['Ladepunkt']
2023-01-12 22:15:22,977 - {urllib3.connectionpool:227} - {DEBUG:cp5} - Starting new HTTP connection (1): 192.168.110.145:80
2023-01-12 22:15:22,999 - {urllib3.connectionpool:227} - {DEBUG:cp11} - Starting new HTTP connection (1): 192.168.110.82:80
2023-01-12 22:15:23,021 - {urllib3.connectionpool:452} - {DEBUG:cp5} - http://192.168.110.145:80 "GET /setCurrent?current=0 HTTP/1.1" 200 29
2023-01-12 22:15:23,025 - {soc.modules.common.req:11} - {DEBUG:cp5} - Get-Response: S0_set current to given value
2023-01-12 22:15:23,038 - {urllib3.connectionpool:452} - {DEBUG:cp11} - http://192.168.110.82:80 "GET /setCurrent?current=0 HTTP/1.1" 200 29
2023-01-12 22:15:23,044 - {soc.modules.common.req:11} - {DEBUG:cp11} - Get-Response: S0_set current to given value
2023-01-12 22:15:23,048 - {control.counter:141} - {DEBUG:MainThread} - 0W reservierte EVU-Leistung, 0W freigegebene EVU-Leistung
2023-01-12 22:15:23,401 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=update, args=(), kwargs={})
2023-01-12 22:15:30,605 - {schedule:660} - {DEBUG:MainThread} - Running job Job(interval=1, unit=minutes, do=handler10Sec, args=(), kwargs={})
2023-01-12 22:15:30,606 - {root:61} - {INFO:MainThread} - # ***Start*** 
2023-01-12 22:15:30,672 - {modules.devices.carlo_gavazzi.device:47} - {DEBUG:device0} - Start device reading {'component0': <modules.devices.carlo_gavazzi.counter.CarloGavazziCounter object at 0x7260c478>}
2023-01-12 22:15:30,672 - {soc.modules.common.component_context:51} - {DEBUG:device4} - Update Komponenten ['PV1_Süd_Json-WR']
2023-01-12 22:15:30,673 - {soc.modules.common.component_context:24} - {DEBUG:device0} - Update Komponente ['Carlo Gavazzi EVU-Punkt']
2023-01-12 22:15:30,673 - {soc.modules.common.component_context:51} - {DEBUG:device5} - Update Komponenten ['PV2_Dach_MQTT']
2023-01-12 22:15:30,680 - {urllib3.connectionpool:227} - {DEBUG:device4} - Starting new HTTP connection (1): 192.168.110.151:80
2023-01-12 22:15:30,681 - {soc.modules.common.component_context:24} - {DEBUG:cp5} - Update Komponente ['Ladepunkt']
2023-01-12 22:15:30,681 - {soc.modules.common.component_context:24} - {DEBUG:cp11} - Update Komponente ['Ladepunkt']
2023-01-12 22:15:30,682 - {modules.devices.mqtt.device:47} - {DEBUG:device5} - MQTT-Module müssen nicht ausgelesen werden.
2023-01-12 22:15:30,691 - {urllib3.connectionpool:227} - {DEBUG:cp5} - Starting new HTTP connection (1): 192.168.110.145:80
2023-01-12 22:15:30,697 - {urllib3.connectionpool:227} - {DEBUG:cp11} - Starting new HTTP connection (1): 192.168.110.82:80
2023-01-12 22:15:30,716 - {urllib3.connectionpool:452} - {DEBUG:cp11} - http://192.168.110.82:80 "GET /getParameters HTTP/1.1" 200 386
2023-01-12 22:15:30,718 - {soc.modules.common.req:11} - {DEBUG:cp11} - Get-Response: {"type":"parameters","list":[{"vehicleState":1,"evseState":false,"maxCurrent":32,"actualCurrent":0,"actualCurrentMA":0,"actualPower":0,"duration":0,"alwaysActive":true,"lastActionUser":"","lastActionUID":"","energy":0,"mileage":0,"meterReading":3896.29,"currentP1":0,"currentP2":0,"currentP3":0,"voltageP1":235.492,"voltageP2":235.77,"voltageP3":234.7839,"useMeter":true,"RFIDUID":""}]}
2023-01-12 22:15:30,719 - {soc.modules.common.store._api:26} - {DEBUG:cp11} - Raw data ChargepointState(voltages=[235.492, 235.77, 234.7839], currents=[0, 0, 0], power_factors=[0.0, 0.0, 0.0], imported=3896290.0, exported=0, power=0, phases_in_use=1, charge_state=False, plug_state=False, rfid=None)
2023-01-12 22:15:30,735 - {urllib3.connectionpool:452} - {DEBUG:cp5} - http://192.168.110.145:80 "GET /getParameters HTTP/1.1" 200 400
2023-01-12 22:15:30,738 - {soc.modules.common.req:11} - {DEBUG:cp5} - Get-Response: {"type":"parameters","list":[{"vehicleState":2,"evseState":false,"maxCurrent":20,"actualCurrent":0,"actualCurrentMA":0,"actualPower":0,"duration":933000,"alwaysActive":true,"lastActionUser":"API","lastActionUID":"API","energy":1,"mileage":5,"meterReading":2774.31,"currentP1":0,"currentP2":0,"currentP3":0,"voltageP1":234.9747,"voltageP2":235.6424,"voltageP3":234.3217,"useMeter":true,"RFIDUID":""}]}
2023-01-12 22:15:30,740 - {soc.modules.common.store._api:26} - {DEBUG:cp5} - Raw data ChargepointState(voltages=[234.9747, 235.6424, 234.3217], currents=[0, 0, 0], power_factors=[0.0, 0.0, 0.0], imported=2774310.0, exported=0, power=0, phases_in_use=1, charge_state=False, plug_state=True, rfid=None)
2023-01-12 22:15:30,804 - {modules.common.simcount._simcount:38} - {DEBUG:device0} - Previous state: SimCounterState(timestamp=1673558120.90692, power=526.3, imported=2182874.410525175, exported=1686828.9433132384)
2023-01-12 22:15:30,804 - {modules.common.simcount._calculate:13} - {DEBUG:device0} - time passed: 0.00274917, power1: 526.3, power2: 469.1
2023-01-12 22:15:30,805 - {modules.common.simcount._calculate:29} - {DEBUG:device0} - power did not change sign. Total energy: 1.36826
2023-01-12 22:15:30,805 - {modules.common.simcount._simcount:47} - {DEBUG:device0} - imported: 1.36826 Wh, exported: 0 Wh, new state: SimCounterState(timestamp=1673558130.8039267, power=469.1, imported=2182875.778786358, exported=1686828.9433132384)
2023-01-12 22:15:30,807 - {soc.modules.common.store._api:26} - {DEBUG:device0} - Raw data CounterState(voltages=[235.2, 235.5, 234.6], powers=[285.7, 63.5, 119.9], currents=[1.485, 0.457, 1.561], power_factors=[0.0, 0.0, 0.0], imported=2182875.778786358, exported=1686828.9433132384, power=469.1, frequency=50.0)
2023-01-12 22:15:30,860 - {urllib3.connectionpool:452} - {DEBUG:device4} - http://192.168.110.151:80 "GET /cm?cmnd=status%208 HTTP/1.1" 200 None
2023-01-12 22:15:30,865 - {soc.modules.common.req:11} - {DEBUG:device4} - Get-Response: {"StatusSNS":{"Time":"2023-01-12T22:15:31","ENERGY":{"TotalStartTime":"2021-08-15T19:55:58","Total":816.434,"Yesterday":0.121,"Today":0.060,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":236,"Current":0.000}}}
2023-01-12 22:15:32,392 - {soc.modules.common.store._api:26} - {DEBUG:device4} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-0.0, exported=816434.0, dc_power=None)
2023-01-12 22:15:32,399 - {soc.modules.common.component_context:24} - {DEBUG:cp5} - Update Komponente ['Ladepunkt']
2023-01-12 22:15:32,402 - {soc.modules.common.store._api:30} - {DEBUG:cp5} - Saving ChargepointState(voltages=[234.9747, 235.6424, 234.3217], currents=[0, 0, 0], power_factors=[0.0, 0.0, 0.0], imported=2774310.0, exported=0, power=0, phases_in_use=1, charge_state=False, plug_state=True, rfid=None)
2023-01-12 22:15:32,404 - {soc.modules.common.component_context:24} - {DEBUG:cp11} - Update Komponente ['Ladepunkt']
2023-01-12 22:15:32,407 - {soc.modules.common.store._api:30} - {DEBUG:cp11} - Saving ChargepointState(voltages=[235.492, 235.77, 234.7839], currents=[0, 0, 0], power_factors=[0.0, 0.0, 0.0], imported=3896290.0, exported=0, power=0, phases_in_use=1, charge_state=False, plug_state=False, rfid=None)
2023-01-12 22:15:32,415 - {soc.modules.common.component_context:24} - {DEBUG:component10} - Update Komponente ['PV1_Süd_Json-WR']
2023-01-12 22:15:32,418 - {soc.modules.common.store._api:26} - {DEBUG:component10} - Raw data InverterState(currents=[0.0, 0.0, 0.0], power=-0.0, exported=816434.0, dc_power=None)
2023-01-12 22:15:32,419 - {soc.modules.common.store._api:30} - {DEBUG:component10} - Saving InverterState(currents=[0.0, 0.0, 0.0], power=-0.0, exported=816434.0, dc_power=None)
2023-01-12 22:15:32,421 - {soc.modules.common.component_context:24} - {DEBUG:component12} - Update Komponente ['PV2_Dach_MQTT']
2023-01-12 22:15:32,589 - {soc.modules.common.component_context:24} - {DEBUG:component0} - Update Komponente ['Carlo Gavazzi EVU-Punkt']
2023-01-12 22:15:32,590 - {soc.modules.common.store._api:30} - {DEBUG:component0} - Saving CounterState(voltages=[235.2, 235.5, 234.6], powers=[285.7, 63.5, 119.9], currents=[1.485, 0.457, 1.561], power_factors=[0.0, 0.0, 0.0], imported=2182875.778786358, exported=1686828.9433132384, power=469.1, frequency=50.0)
2023-01-12 22:15:32,791 - {control.counter:87} - {DEBUG:MainThread} - Verbleibende Ströme an Zähler 0: [32.51, 33.54, 32.44]
2023-01-12 22:15:32,792 - {control.counter:116} - {DEBUG:MainThread} - Verbleibende Leistung an Zähler 0: 24530.9
2023-01-12 22:15:32,803 - {control.chargepoint:696} - {DEBUG:MainThread} - EV-Phasenzahl beschränkt die nutzbaren Phasen auf 1
2023-01-12 22:15:32,804 - {control.chargepoint:687} - {DEBUG:MainThread} - Phasenzahl Lademodus: 1
2023-01-12 22:15:32,805 - {control.ev:741} - {DEBUG:MainThread} - Plan-Nr. 1: Differenz zum Start -932.804773s, Dauer 0.0h, Termin heute verpasst: False
2023-01-12 22:15:32,805 - {control.ev:787} - {DEBUG:MainThread} - Verwendeter Plan: Zielladen
2023-01-12 22:15:32,810 - {control.ev:364} - {DEBUG:MainThread} - Änderung des Lademodus :False
2023-01-12 22:15:32,811 - {control.chargepoint:856} - {DEBUG:MainThread} - LP 5, EV: i3_60Ah_Nr.1 (EV-Nr.1): Theoretisch benötigter Strom 6A, Lademodus scheduled_charging, Submodus: Chargemode.PV_CHARGING, Phasen: 1, Priorität: True, max. Ist-Strom: 0
2023-01-12 22:15:32,814 - {control.chargepoint:349} - {INFO:MainThread} - LP 5: Falls vorhanden wird mit EVU-Überschuss geladen, da der Ziel-Soc bereits erreicht wurde.
2023-01-12 22:15:32,818 - {control.chargepoint:349} - {INFO:MainThread} - LP 11: Keine Ladung, da kein Auto angesteckt ist.
2023-01-12 22:15:32,827 - {control.data:297} - {DEBUG:MainThread} - all
{'get': {'power': 0}, 'config': {'configured': False}, 'set': {'charging_power_left': 0, 'switch_on_soc_reached': False}}
2023-01-12 22:15:32,830 - {control.data:270} - {DEBUG:MainThread} - cp_all_data
AllChargepointData(get=AllGet(daily_imported=9630.0, daily_exported=0, power=0, imported=6670600.0, exported=0))
2023-01-12 22:15:32,831 - {control.data:297} - {DEBUG:MainThread} - cp5
ChargepointData(get=Get(charge_state=False, connected_vehicle=ConnectedVehicle(config=ConnectedConfig(average_consumption=17, charge_template=0, chargemode='stop', current_plan=0, ev_template=0, priority=False), info=ConnectedInfo(id=0, name='Ladepunkt'), soc=70), currents=[0, 0, 0], daily_imported=9630.0, daily_exported=0, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=2774310.0, phases_in_use=1, plug_state=True, power=0, rfid_timestamp=None, rfid=None, state_str='Falls vorhanden wird mit EVU-Überschuss geladen, da der Ziel-Soc bereits erreicht wurde.', voltages=[234.97, 235.64, 234.32]), set=Set(change_ev_permitted=[True, ''], charging_ev=1, charging_ev_prev=1, current=0, energy_to_charge=0, loadmanagement_available=True, log=Log(chargemode_log_entry='scheduled_charging', imported_at_mode_switch=2773300.0, imported_at_plugtime=2773300.0, imported_since_mode_switch=1000.0, imported_since_plugged=1010.0, range_charged=5.0, time_charged='0:15', timestamp_start_charging='01/12/2023, 21:47:02'), manual_lock=False, phases_to_use=1, plug_state_prev=True, plug_time='01/12/2023, 21:08:02', required_power=1380.0, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0x71d85ec8>), config=Config(connection_module={'type': 'smartwb', 'name': 'smartWB / EVSE-Wifi (>= v1.x.x/v2.x.x)', 'configuration': {'ip_address': '192.168.110.145', 'timeout': 2}}, power_module={}, ev=1, name='smartWB WLAN 20A', type='smartwb', template=0, connected_phases=3, phase_1=1, auto_phase_switch_hw=False, control_pilot_interruption_hw=True, id=5))
2023-01-12 22:15:32,832 - {control.data:297} - {DEBUG:MainThread} - cp11
ChargepointData(get=Get(charge_state=False, connected_vehicle=ConnectedVehicle(config=ConnectedConfig(average_consumption=17, charge_template=0, chargemode='stop', current_plan=0, ev_template=0, priority=False), info=ConnectedInfo(id=0, name='Ladepunkt'), soc=72), currents=[0, 0, 0], daily_imported=0.0, daily_exported=0, exported=0, fault_str='Kein Fehler.', fault_state=0, imported=3896290.0, phases_in_use=1, plug_state=False, power=0, rfid_timestamp=None, rfid=None, state_str='Keine Ladung, da kein Auto angesteckt ist.', voltages=[235.49, 235.77, 234.78]), set=Set(change_ev_permitted=[True, ''], charging_ev=-1, charging_ev_prev=-1, current=0, energy_to_charge=0, loadmanagement_available=True, log=Log(chargemode_log_entry='_', imported_at_mode_switch=0, imported_at_plugtime=0, imported_since_mode_switch=0, imported_since_plugged=0, range_charged=0, time_charged='00:00', timestamp_start_charging=None), manual_lock=False, phases_to_use=1, plug_state_prev=False, plug_time=None, required_power=0, rfid=None, target_current=0, charging_ev_data=<control.ev.Ev object at 0x71d2bca0>), config=Config(connection_module={'type': 'smartwb', 'name': 'smartWB / EVSE-Wifi (>= v1.x.x/v2.x.x)', 'configuration': {'ip_address': '192.168.110.82', 'timeout': 2}}, power_module={}, ev=2, name='smartWB LAN 32A', type='smartwb', template=0, connected_phases=3, phase_1=1, auto_phase_switch_hw=False, control_pilot_interruption_hw=True, id=11))
2023-01-12 22:15:32,833 - {control.data:297} - {DEBUG:MainThread} - cpt0
CpTemplateData(autolock=Autolock(active=False, plans={'0': AutolockPlan(active=False, frequency=Frequency(selected='daily', once=['2021-11-01', '2021-11-05'], weekly=[False, False, False, False, False, False, False]), time=['07:00', '16:00'], name='Standard Autolock-Plan')}, wait_for_charging_end=False), id=0, max_current_multi_phases=32, max_current_single_phase=32, name='Standard Ladepunkt-Vorlage', rfid_enabling=False, valid_tags=[])
2023-01-12 22:15:32,838 - {control.data:297} - {DEBUG:MainThread} - counter0
{'set': {'reserved_surplus': 0, 'released_surplus': 0, 'raw_power_left': 24530.9, 'surplus_power_left': 0, 'consumption_left': 24017.8, 'raw_currents_left': [32.51, 33.54, 32.44]}, 'get': {'daily_exported': 0.0, 'daily_imported': 24888.7958314733, 'voltages': [235.2, 235.5, 234.6], 'currents': [1.49, 0.46, 1.56], 'powers': [285.7, 63.5, 119.9], 'power_factors': [0.0, 0.0, 0.0], 'imported': 2182875.778786358, 'exported': 1686828.9433132384, 'power': 469.1, 'frequency': 50.0, 'fault_str': 'Kein Fehler.', 'fault_state': 0}, 'config': {'max_currents': [35, 35, 35], 'max_total_power': 25000}}
2023-01-12 22:15:32,839 - {control.data:274} - {DEBUG:MainThread} - counter_all_data
CounterAllData(get=Get(hierarchy=[{'id': 0, 'type': 'counter', 'children': [{'id': 5, 'type': 'cp', 'children': []}, {'id': 11, 'type': 'cp', 'children': []}, {'id': 10, 'type': 'inverter', 'children': []}, {'id': 12, 'type': 'inverter', 'children': []}]}]), set=Set(loadmanagement_active=False, home_consumption=469.1, invalid_home_consumption=0, daily_yield_home_consumption=15552.7958314733))
2023-01-12 22:15:32,840 - {control.data:297} - {DEBUG:MainThread} - ct0
ChargeTemplateData(name='Standard-Ladeprofil-Vorlage', disable_after_unplug=False, prio=True, load_default=False, time_charging=TimeCharging(active=False, plans={'5': TimeChargingPlan(active=False, frequency=Frequency(selected='daily', once=['2021-11-01', '2021-11-05'], weekly=[False, False, False, False, False, False, False]), time=['06:45', '07:15'], name='Zeitladen-Standard', current=10, limit=Limit(selected='soc', amount=1000, soc=70))}), chargemode=Chargemode(selected='instant_charging', pv_charging=PvCharging(min_soc_current=12, min_current=0, feed_in_limit=False, min_soc=0, max_soc=90), scheduled_charging=ScheduledCharging(plans={'3': ScheduledChargingPlan(active=False, frequency=Frequency(selected='daily', once=['2021-11-01', '2021-11-05'], weekly=[False, False, False, False, False, False, False]), current=14, name='Zielladen-Standard', limit=ScheduledLimit(selected='soc', amount=10000, soc_limit=90, soc_scheduled=80), time='06:30')}), instant_charging=InstantCharging(current=14, limit=Limit(selected='soc', amount=7000, soc=85))))
2023-01-12 22:15:32,841 - {control.data:297} - {DEBUG:MainThread} - ct1
ChargeTemplateData(name='Ladeprofil 01', disable_after_unplug=False, prio=True, load_default=False, time_charging=TimeCharging(active=True, plans={'4': TimeChargingPlan(active=True, frequency=Frequency(selected='daily', once=['2022-11-20', '2022-11-20'], weekly=[False, False, False, False, False, False, False]), time=['22:15', '22:25'], name='Morgensladen', current=12, limit=Limit(selected='none', amount=1000, soc=90))}), chargemode=Chargemode(selected='scheduled_charging', pv_charging=PvCharging(min_soc_current=12, min_current=14, feed_in_limit=False, min_soc=20, max_soc=100), scheduled_charging=ScheduledCharging(plans={'1': ScheduledChargingPlan(active=True, frequency=Frequency(selected='daily', once=['2021-11-01', '2021-11-05'], weekly=[False, False, False, False, False, False, False]), current=14, name='Zielladen', limit=ScheduledLimit(selected='soc', amount=4000, soc_limit=95, soc_scheduled=70), time='22:00')}), instant_charging=InstantCharging(current=18, limit=Limit(selected='none', amount=6000, soc=90))))
2023-01-12 22:15:32,842 - {control.data:297} - {DEBUG:MainThread} - ev0
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='Standard-Fahrzeug-Vorlage', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=17, min_current=6, max_current_single_phase=32, battery_capacity=82, nominal_difference=2), et_num=0)), control_parameter=ControlParameter(required_current=0, required_currents=[0.0, 0.0, 0.0], phases=1, prio=True, timestamp_switch_on_off=None, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, submode='stop', chargemode='stop', used_amount_instant_charging=0, imported_at_plan_start=0, current_plan=None), charge_template=0, ev_template=0, name='Standard-Fahrzeug', tag_id=[], get=Get(soc=0, soc_timestamp='', force_soc_update=False, range=0, fault_state=0, fault_str=''))
2023-01-12 22:15:32,855 - {control.data:297} - {DEBUG:MainThread} - ev1
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='i3_1p', max_current_multi_phases=16, max_phases=1, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=20, min_current=6, max_current_single_phase=32, battery_capacity=16, nominal_difference=2), et_num=0)), control_parameter=ControlParameter(required_current=6, required_currents=[6, 0.0, 0.0], phases=1, prio=True, timestamp_switch_on_off=None, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, submode=<Chargemode.PV_CHARGING: 'pv_charging'>, chargemode=<Chargemode.SCHEDULED_CHARGING: 'scheduled_charging'>, used_amount_instant_charging=0, imported_at_plan_start=0, current_plan='Zielladen'), charge_template=1, ev_template=1, name='i3_60Ah_Nr.1', tag_id=[], get=Get(soc=70, soc_timestamp='01/12/2023, 22:01:53', force_soc_update=False, range=71.0, fault_state=0, fault_str='Kein Fehler.'))
2023-01-12 22:15:32,860 - {control.data:297} - {DEBUG:MainThread} - ev2
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='i3_1p', max_current_multi_phases=16, max_phases=1, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=20, min_current=6, max_current_single_phase=32, battery_capacity=16, nominal_difference=2), et_num=0)), control_parameter=ControlParameter(required_current=0, required_currents=[0.0, 0.0, 0.0], phases=1, prio=True, timestamp_switch_on_off=None, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, submode='stop', chargemode='stop', used_amount_instant_charging=0, imported_at_plan_start=0, current_plan=None), charge_template=0, ev_template=1, name='i3_60Ah_Nr.2', tag_id=[], get=Get(soc=72, soc_timestamp='01/12/2023, 22:08:13', force_soc_update=False, range=71.0, fault_state=0, fault_str='Kein Fehler.'))
2023-01-12 22:15:32,861 - {control.data:297} - {DEBUG:MainThread} - ev3
EvData(set=Set(ev_template=EvTemplate(data=EvTemplateData(name='i3_3p', 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=18, min_current=6, max_current_single_phase=32, battery_capacity=40, nominal_difference=2), et_num=0)), control_parameter=ControlParameter(required_current=0, required_currents=[0.0, 0.0, 0.0], phases=1, prio=True, timestamp_switch_on_off=None, timestamp_auto_phase_switch=None, timestamp_perform_phase_switch=None, submode='stop', chargemode='stop', used_amount_instant_charging=0, imported_at_plan_start=0, current_plan=None), charge_template=0, ev_template=2, name='i3_120Ah', tag_id=[], get=Get(soc=0, soc_timestamp='', force_soc_update=False, range=0, fault_state=0, fault_str=''))
2023-01-12 22:15:32,862 - {control.data:297} - {DEBUG:MainThread} - et0
EvTemplateData(name='Standard-Fahrzeug-Vorlage', max_current_multi_phases=16, max_phases=3, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=17, min_current=6, max_current_single_phase=32, battery_capacity=82, nominal_difference=2)
2023-01-12 22:15:32,862 - {control.data:297} - {DEBUG:MainThread} - et1
EvTemplateData(name='i3_1p', max_current_multi_phases=16, max_phases=1, phase_switch_pause=2, prevent_phase_switch=False, prevent_charge_stop=False, control_pilot_interruption=False, control_pilot_interruption_duration=4, average_consump=20, min_current=6, max_current_single_phase=32, battery_capacity=16, nominal_difference=2)
2023-01-12 22:15:32,863 - {control.data:297} - {DEBUG:MainThread} - et2
EvTemplateData(name='i3_3p', 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=18, min_current=6, max_current_single_phase=32, battery_capacity=40, nominal_difference=2)
2023-01-12 22:15:32,864 - {control.data:278} - {DEBUG:MainThread} - general_data
GeneralData(chargemode_config=ChargemodeConfig(instant_charging=InstantCharging(phases_to_use=3), pv_charging=PvCharging(bat_prio=False, charging_power_reserve=200, control_range=[0, 230], feed_in_yield=0, phase_switch_delay=7, phases_to_use=3, rundown_power=1000, rundown_soc=50, switch_off_delay=20, switch_off_soc=40, switch_off_threshold=1000, switch_on_delay=5, switch_on_soc=60, switch_on_threshold=900), scheduled_charging=ScheduledCharging(phases_to_use=0), standby=Standby(phases_to_use=3), stop=Stop(phases_to_use=1), time_charging=TimeCharging(phases_to_use=3), unbalanced_load_limit=20, unbalanced_load=False), control_interval=10, extern_display_mode='local', extern=False, external_buttons_hw=False, grid_protection_active=False, grid_protection_configured=False, grid_protection_random_stop=0, grid_protection_timestamp='', mqtt_bridge=False, price_kwh=0.3, range_unit='km', ripple_control_receiver=RippleControlReceiver(configured=False, r1_active=False, r2_active=False))
2023-01-12 22:15:32,865 - {control.data:297} - {DEBUG:MainThread} - graph
{'config': {'duration': 120}}
2023-01-12 22:15:32,865 - {control.data:280} - {DEBUG:MainThread} - optional_data
OptionalData(et=Et(active=False, config=EtConfig(max_price=0, provider={}), get=EtGet(price=0, price_list=[])), int_display=InternalDisplay(active=False, on_if_plugged_in=True, pin_active=False, pin_code='0000', standby=60, theme='cards'), led=Led(active=False), rfid=Rfid(active=False))
2023-01-12 22:15:32,866 - {control.data:297} - {DEBUG:MainThread} - all
{'get': {'power': 0.0, 'exported': 1357476.0, 'daily_exported': 294.0, 'monthly_exported': 0, 'yearly_exported': 0}, 'config': {'configured': True}}
2023-01-12 22:15:32,866 - {control.data:297} - {DEBUG:MainThread} - pv10
{'get': {'daily_exported': 60.0, 'monthly_exported': 0, 'yearly_exported': 0, 'fault_str': 'Kein Fehler.', 'fault_state': 0, 'power': -0.0, 'currents': [0.0, 0.0, 0.0], 'exported': 816434.0}, 'config': {'max_ac_out': 5000}}
2023-01-12 22:15:32,867 - {control.data:297} - {DEBUG:MainThread} - pv12
{'get': {'daily_exported': 234, 'monthly_exported': 0, 'yearly_exported': 0, 'fault_str': 'Kein Fehler.', 'fault_state': 0, 'power': 0, 'exported': 541042}, 'config': {'max_ac_out': 5000}}
2023-01-12 22:15:32,868 - {control.data:297} - {DEBUG:MainThread} - system
{'update_in_progress': False, 'perform_update': False, 'boot_done': True, 'ip_address': '192.168.110.51', 'dataprotection_acknowledged': False, 'debug_level': 10, 'release_train': 'master', 'version': '1.99.101.1', 'lastlivevaluesJson': {'timestamp': 1673558123, 'time': '22:15:23', 'grid': 0.526, 'house-power': 0.526, 'charging-all': 0.0, 'pv-all': -0.0, 'cp5-power': 0.0, 'cp11-power': 0.0, 'ev1-soc': 70, 'ev2-soc': 72}, 'current_commit': '2023-01-12 16:40:37 +0100 [2bc7920f]', 'current_missing_commits': [], 'current_branch': 'master', 'current_branch_commit': '2023-01-12 16:40:37 +0100 [2bc7920f]', 'datastore_version': 6, 'module_update_completed': True}
2023-01-12 22:15:32,868 - {control.data:310} - {DEBUG:MainThread} - device0
{'name': 'Carlo Gavazzi EVU', 'type': 'carlo_gavazzi', 'id': 0, 'configuration': {'ip_address': '192.168.110.11'}}
2023-01-12 22:15:32,870 - {control.data:312} - {DEBUG:MainThread} - component0
{'name': 'Carlo Gavazzi EVU-Punkt', 'type': 'counter', 'id': 0, 'configuration': {}}
2023-01-12 22:15:32,871 - {control.data:310} - {DEBUG:MainThread} - device4
{'name': 'PV1_Süd', 'type': 'json', 'id': 4, 'configuration': {'url': 'http://192.168.110.151/cm?cmnd=status%208'}}
2023-01-12 22:15:32,872 - {control.data:312} - {DEBUG:MainThread} - component10
{'name': 'PV1_Süd_Json-WR', 'type': 'inverter', 'id': 10, 'configuration': {'jq_power': '.StatusSNS.ENERGY.Power', 'jq_exported': '(.StatusSNS.ENERGY.Total)*1000'}}
2023-01-12 22:15:32,872 - {control.data:310} - {DEBUG:MainThread} - device5
{'name': 'PV2_Dach', 'type': 'mqtt', 'id': 5, 'configuration': {}}
2023-01-12 22:15:32,873 - {control.data:312} - {DEBUG:MainThread} - component12
{'name': 'PV2_Dach_MQTT', 'type': 'inverter', 'id': 12, 'configuration': {}}
2023-01-12 22:15:32,873 - {control.data:284} - {DEBUG:MainThread} - 

btw (SoC)
Das mit der neuen SoC-Abfrage ist noch nicht optimal. Ich habe hier z.B. beim i3 eine SoC-Erhöhung nach Ladeende von 2%, die aufgrund des Ladestops nicht mehr erfasst wird.

Idee:
Noch 10min Nachlauf mit der 5min-Abfrage einbauen (und die manuelle Vorganbe des Abrufintervalls - rleidner würde die alte Lösung auch als PR für 2.0 bauen, wenn ihr wollt.)

Bugs sind behoben.

[LenaK]

Und dann habe ich gestern mal meine beiden openWBs in der 2.0 angelegt und mal Autos geladen. Hat soweit funktioniert, nur der 1phasige Ion wollte an der Buchse nicht laden. Aber irgendwie finde ich dazu nichts in den Logs. Also habe ich die custom wieder zum Master gemacht und die remote-Ladepunkte aus der 2.0er gelöscht. Das mag sie aber nicht - das nächste Mal werde ich sie wohl auf MQTT umstellen.

Code: Alles auswählen

2023-01-12 16:30:35,362 - {helpermodules.setdata:528} - {WARNING:Thread-6} - Kein Ladepunkt 5 mit gültiger Konfiguration gefunden.
2023-01-12 16:30:35,674 - {helpermodules.setdata:528} - {WARNING:Thread-6} - Kein Ladepunkt 4 mit gültiger Konfiguration gefunden.

Die 1.9 brauchen einen Moment bis sie aus dem NurLP Modus wechseln. Die 2.0 nimmt die Daten, die noch gesendet werden aber nicht mehr an, wenn die LP gelöscht wurden. Das macht aber nichts. Sollte beim nächsten Einrichten wieder funktionieren.

Und dann wird mein main.log irgendwie nicht größer:

Code: Alles auswählen

pi@raspi:/var/www/html/openWB$ cd ramdisk/
pi@raspi:/var/www/html/openWB/ramdisk$ ls -l main.log
-rw-rw-rw- 1 openwb openwb 139008 13. Jan 10:05 main.log
pi@raspi:/var/www/html/openWB/ramdisk$ tail -f main.log
2023-01-12 16:32:32,119 - {helpermodules.setdata:528} - {WARNING:Thread-6} - Kein Ladepunkt 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:32:32,120 - {helpermodules.setdata:528} - {WARNING:Thread-6} - Kein Ladepunkt 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:32:32,121 - {helpermodules.setdata:528} - {WARNING:Thread-6} - Kein Ladepunkt 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:32:32,122 - {helpermodules.setdata:528} - {WARNING:Thread-6} - Kein Ladepunkt 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:32:32,123 - {helpermodules.setdata:528} - {WARNING:Thread-6} - Kein Ladepunkt 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:32:53,913 - {helpermodules.setdata:811} - {WARNING:Thread-6} - Kein Zähler 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:32:53,915 - {helpermodules.setdata:811} - {WARNING:Thread-6} - Kein Zähler 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:34:07,775 - {helpermodules.setdata:811} - {WARNING:Thread-6} - Kein Zähler 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:34:21,683 - {helpermodules.setdata:811} - {WARNING:Thread-6} - Kein Zähler 4 mit gültiger Konfiguration gefunden.
2023-01-12 16:34:23,772 - {helpermodules.setdata:811} - {WARNING:Thread-6} - Kein Zähler 4 mit gültiger Konfiguration gefunden.

Version: 2023-01-12 09:55:58 +0100 [4e127a60]

Loglevel auf Details gestellt?

[Gero]

Loglevel auf Details gestellt?

Hab' ich nun gemacht. Komisch finde ich den timestamp aus dem main.log von 10:05 und den letzen Eintrag im log von 16:34. Das bedeutet ja, dass die Datei zwar geschrieben wird, sich aber in der Datei nichts ändert.

update: Nun mit loglevel Detail wird wieder geschrieben Sogar deutlich mehr als 10.000 Zeilen:

Code: Alles auswählen

pi@raspi:/var/www/html/openWB/ramdisk$ wc -l main.log
12312 main.log

Mir erscheint es nun, dass die 10.000 Zeilen Begrenzung bei "normalem" logging dazu führt, dass keine neuen Zeilen angehängt werden bzw. das rotieren nicht so funktioniert, wie es soll. Im Detail-Logging scheint es so zu sein, dass keine 10.000 Zeilen-Begrenzung implementiert ist.

[Stivmaster]

Da jetzt endlich mal die Sonne rausgekommen ist, kann ich für meinen Fall Entwarnung geben, PV laden läuft auch.

[Gero]

Nun mit loglevel Detail wird wieder geschrieben Sogar deutlich mehr als 10.000 Zeilen:

sind wieder weniger geworden. Wrap-Around scheint bei "Detail" zu funktionieren.

Code: Alles auswählen

pi@raspi:/var/www/html/openWB/ramdisk$ wc -l main.log
11212 main.log

[rleidner]

Mögliches Problem beim Neustart:
Ich starte nach Änderungen relativ häufig owb2 neu mit

Code: Alles auswählen

sudo systemctl restart openwb2

Nach so seinem "Neustart" zum falschen Zeitpunkt war die aktuelle daily_log Datei leer (Länge 0).
In der Folge gab es alle 5 Minuten diesen Fehler:

Code: Alles auswählen

2023-01-13 14:30:02,121 - {helpermodules.measurement_log:27} - {ERROR:MainThread} - Fehler im Werte-Logging-Modul
Traceback (most recent call last):
  File "/var/www/html/openWB/packages/helpermodules/measurement_log.py", line 24, in measurement_log_daily
    totals = save_log("daily")
  File "/var/www/html/openWB/packages/helpermodules/measurement_log.py", line 195, in save_log
    content = json.load(jsonFile)
  File "/usr/lib/python3.9/json/__init__.py", line 293, in load
    return loads(fp.read(),
  File "/usr/lib/python3.9/json/__init__.py", line 346, in loads
    return _default_decoder.decode(s)
  File "/usr/lib/python3.9/json/decoder.py", line 337, in decode
    obj, end = self.raw_decode(s, idx=_w(s, 0).end())
  File "/usr/lib/python3.9/json/decoder.py", line 355, in raw_decode
    raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)

Der Fehler verschwand, nachdem dies in die leere Datei geschrieben wird:

Code: Alles auswählen

{
  "entries": [
  ],
  "totals": {
  }
}

Damit fehlt die Historie aber es wird dem nächsten 2-min Lauf die Datei weitergeführt.
Hier könnte man - ähnlich wie Editoren - mit einer backup-Datei arbeiten und diese nehmen, wenn der master unbrauchbar ist.

[StEFfen_10]

Hat noch jemand ein Problem mit verkehrt berechneten Hausverbrauch ?

Eigentlich dürften es doch nur 19,36 kWh sein und nicht der Gesamtverbrauch
Denn:
Gesamtverbrauch (PV+Netzbezug) 47,45 kWh
Hiervon 28,1 kWh für Autoladung
Bleiben dann ja noch 19,36 kWh Hausverbrauch.