So this is interesting:
I assume you meant these entries in log4j2.xml, which I changed the following:
<Logger level=INFO name=openhab.event.RuleStatusInfoEvent/>
<Logger level=INFO name=openhab.event.RuleAddedEvent/>
<Logger level=INFO name=openhab.event.RuleRemovedEvent/>
This now results in a log which, to my surprise, leaves lots of trails on the rule that’s to be executed at start level 100 (“startup_enable_rules”)…
23:11:01.333 [INFO ] [openhab.event.RuleStatusInfoEvent ] - RolladenProxy-1 updated: RUNNING
23:11:01.337 [INFO ] [openhab.event.RuleStatusInfoEvent ] - RolladenProxy-1 updated: IDLE
23:11:02.581 [INFO ] [openhab.event.RuleStatusInfoEvent ] - startup_enable_rules updated: RUNNING
23:11:02.587 [INFO ] [openhab.event.RuleStatusInfoEvent ] - system_start_notification updated: RUNNING
23:11:02.748 [INFO ] [openhab.event.RuleStatusInfoEvent ] - RolladenProxy-1 updated: RUNNING
23:11:02.754 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Rolladen_Leo_links_Stop' changed from OFF to NULL
23:11:02.755 [INFO ] [enhab.core.model.script.notifications] - Systemstart abgeschlossen.
23:11:02.762 [INFO ] [openhab.event.RuleStatusInfoEvent ] - RolladenProxy-1 updated: IDLE
23:11:02.767 [INFO ] [openhab.event.RuleStatusInfoEvent ] - system_start_notification updated: IDLE
23:11:03.629 [INFO ] [openhab.event.RuleStatusInfoEvent ] - RolladenProxy-1 updated: RUNNING
23:11:03.634 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Rolladen_Leo_Mitte_Stop' changed from OFF to NULL
23:11:03.641 [INFO ] [openhab.event.RuleStatusInfoEvent ] - RolladenProxy-1 updated: IDLE
23:11:03.696 [WARN ] [org.openhab.rule.startup_enable_rules] - Enable Rule: Executed.
23:11:03.700 [INFO ] [openhab.event.RuleStatusInfoEvent ] - startup_enable_rules updated: IDLE
23:11:04.869 [INFO ] [openhab.event.RuleStatusInfoEvent ] - update_variable_briefkasten updated: RUNNING
23:11:04.879 [INFO ] [openhab.event.ItemCommandEvent ] - Item 'Variable_Briefkasten' received command REFRESH
23:11:04.882 [INFO ] [openhab.event.RuleStatusInfoEvent ] - update_variable_briefkasten updated: IDLE
… but no trace at all of the rule that’s supposed to run at start level 40 (“startup_disable_rules”).
Because this looked rather odd to me, I tried the same, but changed the rule from “triggered when level 40 is reached” to “triggered when level 50 is reached”. Surprisingly, same result: Rule not triggered / no trace at all in the log.
Only after having set the rule to “triggered when level 70 is reached” it did get executed (but, as mentioned in my very first post above, too late, as the first of many messages on items were already being fired):
23:32:11.484 [INFO ] [openhab.event.RuleStatusInfoEvent ] - startup_disable_rules updated: RUNNING
23:32:11.492 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Bambus_Feuchtigkeitssensor_BatteryLevel' changed from NULL to 81.0
23:32:11.522 [INFO ] [openhab.event.RuleStatusInfoEvent ] - thing_status_check updated: UNINITIALIZED (DISABLED)
23:32:11.578 [INFO ] [openhab.event.RuleStatusInfoEvent ] - thing_status_notification updated: UNINITIALIZED (DISABLED)
23:32:11.711 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Lichtsensor_Garten_LowBattery' changed from NULL to OFF
23:32:11.734 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Messstecker_4_Schalter' changed from NULL to OPEN
23:32:11.770 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Thermostat_Garten_Unreach' changed from NULL to OFF
23:32:11.790 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'mRolladenLeoMitte' changed from NULL to ON
23:32:11.801 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Flur_Signalleuchte_Unreach' changed from NULL to OFF
23:32:11.830 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'mKaltwasserzaehler' changed from NULL to ON
23:32:11.841 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Systeminfo_Storage_UsedPercent' changed from NULL to 26.6
23:32:11.860 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'CCU2_Variable_Anwesend' changed from NULL to ON
23:32:11.880 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'mLichtGarten' changed from NULL to ON
23:32:11.900 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Schaltstecker_Lampe_Leo_Unreach' changed from NULL to OFF
23:32:11.930 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Rolladen_Kueche_Links' changed from NULL to 0
23:32:11.957 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Thermostat_Leo_Humidity' changed from NULL to 47
23:32:11.990 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'warmwasserzaehler_gesamt' changed from NULL to 30.41
23:32:12.024 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Licht_Garten_Unreach' changed from NULL to OFF
23:32:12.122 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Rolladen_Bad_Stop' changed from NULL to OFF
23:32:12.149 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Haustur_Taster_1_Kurzer_Druck' changed from NULL to OFF
23:32:12.170 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Lichtschalter_Kueche_Unreach' changed from NULL to OFF
23:32:12.190 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Haustur_Taster_LowBattery' changed from NULL to OFF
23:32:12.202 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Messstecker_4_Leistung_heute' changed from NULL to 0.343
23:32:12.234 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Rolladen_Leo_Rechts' changed from NULL to 100
23:32:12.258 [INFO ] [nhab.event.GroupItemStateChangedEvent] - Item 'Rolladen_Leo' changed from UNDEF to 100 through Rolladen_Leo_Rechts
23:32:12.273 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Dimmstecker_Wohnzimmer_Unreach' changed from NULL to OFF
23:32:12.290 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Rolladen_Schlafzimmer_Stop' changed from NULL to OFF
23:32:12.312 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Rolladen_Wohnzimmer_Rechts_Unreach' changed from NULL to OFF
23:32:12.323 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Stehlampe' changed from NULL to 30
23:32:12.334 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'mLichtschalterKueche' changed from NULL to ON
23:32:12.358 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'mSchaltsteckerLampeLeo' changed from NULL to ON
23:32:12.403 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Systeminfo_CPUTemperature' changed from NULL to 54.2
23:32:12.430 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Rolladen_Kueche_Rechts_Unreach' changed from NULL to OFF
23:32:12.442 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ThermostatLeo_9_STATE' changed from NULL to 0.0
23:32:12.468 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Messstecker_1_Schalter' changed from NULL to OPEN
23:32:12.484 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Rolladen_Wohnzimmer_Rechts_Stop' changed from NULL to OFF
23:32:12.517 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'mNousMesssteckerWaschmaschine' changed from NULL to ON
23:32:13.231 [WARN ] [penhab.rule.thing_status_notification] - Gardena Smart System Account - Thing online.
23:32:13.905 [INFO ] [openhab.event.RuleStatusInfoEvent ] - konvertierung_gardena_ventilstatus updated: IDLE
23:32:13.913 [INFO ] [openhab.event.RuleStatusInfoEvent ] - konvertierung_gardena_ventilstatus updated: RUNNING
23:32:13.919 [WARN ] [rg.openhab.rule.startup_disable_rules] - Disable Rule: Executed.
23:32:13.943 [INFO ] [openhab.event.RuleStatusInfoEvent ] - startup_disable_rules updated: IDLE
So my best guess would be: The trigger “when level 40 and 50 is reached” are not working? Though this would be somewhat odd, as I believe someone else would’ve noticed as well by now?
Edit: Just to be absolutely sure that I’m not messing something up myself I just created a completely empty rule, triggered by system state 40. Same here: No trace of the rule being run in the log.