restoreOnStartup randomly skips Items

Tags: #<Tag:0x00007f6170bb3068>

Hi folks,

Summary of my problem:
I reboot every night, but every night, some states are not “restsoredOnStartup”.
There was a value for Bathroom_Lightmode_Afternoon_CeilingLight_Color in the DB, but it was not used to restore the state after reboot. It did the other day, and most other items did restore properly today. My Theorie: Every bootup, some random items do not restore.

And here is the long story where I try to show how I come to this believe:

I run 2.5.5-1 on ubuntu and influxdb
I use InfluxDB version 1.6.4 on binding 1.14 (that came with the install).

Strategies {
    everyMinute : "0 * * * * ?"
    everyHour   : "0 0 * * * ?"
    everyDay    : "0 0 0 * * ?"
    default = everyChange 
}
Items {
    * : strategy = everyChange, restoreOnStartup
    G_Monitoring : strategy = everyMinute
}

Here is part of my shutdown-log, cannot see any errors…

2020-06-27 03:03:37.191 [temChannelLinkRemovedEvent] - Link 'Livingroom_AmazonEcho_PlayAlarmSound => amazonechocontrol:echo:account1:G090QU068362063B:playAlarmSound' has been removed.
2020-06-27 03:03:37.191 [temChannelLinkRemovedEvent] - Link 'Kitchen_Coffeemachine_ProgressState => homeconnect:CoffeeMaker:BOSCH-CTL636EB6-68A40E26C64A:program_progress_state' has been removed.
2020-06-27 03:03:37.193 [temChannelLinkRemovedEvent] - Link 'Kitchen_CeilingLight_Dimmer2 => shelly:shellyrgbw2-white:662176:channel3#brightness' has been removed.
2020-06-27 03:03:37.193 [temChannelLinkRemovedEvent] - Link 'Bathroom_AmazonEcho_Title => amazonechocontrol:echo:account1:bathroom:title' has been removed.
2020-06-27 03:03:37.193 [temChannelLinkRemovedEvent] - Link 'Salon_RollershutterWindow_Watts => shelly:shelly25-roller:690d9b:meter#currentWatts' has been removed.
2020-06-27 03:03:37.194 [temChannelLinkRemovedEvent] - Link 'Terasse_Outlet_Switch => shelly:shelly25-relay:691114:relay1#output' has been removed.
2020-06-27 03:03:37.194 [temChannelLinkRemovedEvent] - Link 'Outside_LEDiHF_AlarmBurglar2 => zwave:device:ef00bd02:node8:alarm_burglar2' has been removed.
2020-06-27 03:03:37.194 [temChannelLinkRemovedEvent] - Link 'Stairs_EGOG_Light_hasLatency => network:pingdevice:172_16_21_13:latency' has been removed.
2020-06-27 03:03:37.194 [temChannelLinkRemovedEvent] - Link 'Masterbedroom_Rollershutter_OverTemperature => shelly:shelly25-roller:693096:roller#overtemperature' has been removed.
2020-06-27 03:03:37.195 [temChannelLinkRemovedEvent] - Link 'Kitchen_Roborok_ActionFanControl => miio:vacuum:0F918BCD:actions#fan' has been removed.
2020-06-27 03:03:37.195 [temChannelLinkRemovedEvent] - Link 'Livingroom_AmazonEcho_LastVoiceCommand => amazonechocontrol:echo:account1:G090QU068362063B:lastVoiceCommand' has been removed.
2020-06-27 03:03:37.195 [temChannelLinkRemovedEvent] - Link 'Dining_CeilingLightBulb4_isOnline => network:pingdevice:172_16_6_15:online' has been removed.
2020-06-27 03:03:37.196 [temChannelLinkRemovedEvent] - Link 'Basement_Outlets_Washer_AlarmTemp => shelly:shellyplug:db4614:relay#overtemperature' has been removed.
2020-06-27 03:03:37.196 [temChannelLinkRemovedEvent] - Link 'weather_Hours09_SnowVolume => openweathermap:weather-and-forecast:3ab20771:local:forecastHours09#snow' has been removed.
2020-06-27 03:03:37.196 [temChannelLinkRemovedEvent] - Link 'Doorbell_DoorbellMontage => doorbird:d210x:790342c2:doorbellMontage' has been removed.
2020-06-27 03:03:37.196 [temChannelLinkRemovedEvent] - Link 'Bathroom_BasinLight_totalKWH => shelly:shelly1pm:b1daea:meter#totalKWH' has been removed.
2020-06-27 03:03:37.197 [temChannelLinkRemovedEvent] - Link 'Bathroom_Rollershutter_TotalKWH => shelly:shelly25-roller:69002d:meter#totalKWH' has been removed.
2020-06-27 03:03:37.197 [temChannelLinkRemovedEvent] - Link 'Livingroom_AmazonEcho_NotificationVolume => amazonechocontrol:echo:account1:G090QU068362063B:notificationVolume' has been removed.
2020-06-27 03:03:37.197 [temChannelLinkRemovedEvent] - Link 'Kitchen_AmazonEcho_MediaLength => amazonechocontrol:echoshow:account1:G000WV0484720QNP:mediaLength' has been removed.
2020-06-27 03:03:37.243 [hingStatusInfoChangedEvent] - 'exec:command:1bd51b8a' changed from ONLINE to UNINITIALIZED
2020-06-27 03:03:37.244 [hingStatusInfoChangedEvent] - 'exec:command:1bd51b8a' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-06-27 03:03:37.245 [hingStatusInfoChangedEvent] - 'telegram:telegramBot:familygroup' changed from ONLINE to UNINITIALIZED
2020-06-27 03:03:37.247 [hingStatusInfoChangedEvent] - 'telegram:telegramBot:familygroup' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-06-27 03:03:37.255 [hingStatusInfoChangedEvent] - 'unifi:wirelessClient:nataschasIphone' changed from ONLINE to UNINITIALIZED
2020-06-27 03:03:37.260 [hingStatusInfoChangedEvent] - 'unifi:wirelessClient:nataschasIphone' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-06-27 03:03:37.264 [hingStatusInfoChangedEvent] - 'telegram:telegramBot:bot_natascha' changed from ONLINE to UNINITIALIZED
2020-06-27 03:03:37.271 [hingStatusInfoChangedEvent] - 'telegram:telegramBot:bot_natascha' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-06-27 03:03:37.278 [hingStatusInfoChangedEvent] - 'unifi:wirelessClient:philsIphone' changed from ONLINE to UNINITIALIZED
2020-06-27 03:03:37.283 [hingStatusInfoChangedEvent] - 'unifi:wirelessClient:philsIphone' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-06-27 03:03:37.284 [hingStatusInfoChangedEvent] - 'unifi:site:539ca669' changed from ONLINE to UNINITIALIZED
2020-06-27 03:03:37.289 [hingStatusInfoChangedEvent] - 'unifi:site:539ca669' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-06-27 03:03:37.289 [hingStatusInfoChangedEvent] - 'unifi:controller:controller' changed from ONLINE to UNINITIALIZED
2020-06-27 03:03:37.344 [hingStatusInfoChangedEvent] - 'unifi:controller:controller' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-06-27 03:03:37.349 [hingStatusInfoChangedEvent] - 'exec:command:d12b0da5' changed from ONLINE to UNINITIALIZED
2020-06-27 03:03:37.365 [hingStatusInfoChangedEvent] - 'exec:command:d12b0da5' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-06-27 03:03:37.369 [hingStatusInfoChangedEvent] - 'exec:command:d4e77833' changed from ONLINE to UNINITIALIZED
2020-06-27 03:03:37.374 [hingStatusInfoChangedEvent] - 'exec:command:d4e77833' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-06-27 03:03:37.385 [hingStatusInfoChangedEvent] - 'telegram:telegramBot:bot_phiL' changed from ONLINE to UNINITIALIZED
2020-06-27 03:03:37.390 [hingStatusInfoChangedEvent] - 'telegram:telegramBot:bot_phiL' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-06-27 03:03:37.395 [hingStatusInfoChangedEvent] - 'exec:command:b6ca65e2' changed from ONLINE to UNINITIALIZED
2020-06-27 03:03:37.400 [hingStatusInfoChangedEvent] - 'exec:command:b6ca65e2' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-06-27 03:03:37.426 [hingStatusInfoChangedEvent] - 'exec:command:00282d69' changed from ONLINE to UNINITIALIZED
2020-06-27 03:03:37.432 [hingStatusInfoChangedEvent] - 'exec:command:00282d69' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2020-06-27 03:09:51.773 [vent.ItemStateChangedEvent] - Dining_SensorPresence_Presence changed from NULL to 0
2020-06-27 03:09:53.878 [hingStatusInfoChangedEvent] - 'unifi:wirelessClient:nataschasIphone' changed from UNINITIALIZED

I assume it restoreonstartup is working based on those logs:

2020-06-27 03:12:07.379 [vent.ItemStateChangedEvent] - Kitchen_Outlet_Freezer_MeterKWh changed from NULL to 234.68
2020-06-27 03:12:07.393 [vent.ItemStateChangedEvent] - Masterbedroom_SensorDoor_Luminance changed from NULL to 0.0
2020-06-27 03:12:07.427 [vent.ItemStateChangedEvent] - Dining_CeilingLightBulb1_Dimmer changed from NULL to 0
2020-06-27 03:12:07.436 [vent.ItemStateChangedEvent] - Ugfloor_SensorEntryDoor_Temperature changed from NULL to 22.8
2020-06-27 03:12:07.455 [vent.ItemStateChangedEvent] - Livingroom_Outlet_Refrigerator_MeterKWh changed from NULL to 65.49
2020-06-27 03:12:07.482 [vent.ItemStateChangedEvent] - Masterbedroom_Lightmode_Sleep_PaxLight_Natascha_Switch changed from NULL to ON
2020-06-27 03:12:07.529 [vent.ItemStateChangedEvent] - Dining_Settings_Rules_Rollershutter_UpAfterDawn changed from NULL to OFF
2020-06-27 03:12:07.546 [vent.ItemStateChangedEvent] - Masterbedroom_Lightmode_Afternoon_PaxLight_Natascha_Switch changed from NULL to ON
2020-06-27 03:12:07.597 [vent.ItemStateChangedEvent] - Dining_Lightmode_Evening_CeilingLightBulb3_Dimmer changed from NULL to 64.0
2020-06-27 03:12:07.611 [vent.ItemStateChangedEvent] - Dining_TableCeiling_Motion changed from NULL to OFF
2020-06-27 03:12:07.632 [vent.ItemStateChangedEvent] - Bathroom_Lightmode_Morning_CeilingLight_Switch changed from NULL to ON
2020-06-27 03:12:07.741 [vent.ItemStateChangedEvent] - Dining_Settings_Rules_Lightning changed from NULL to ON
2020-06-27 03:12:07.788 [vent.ItemStateChangedEvent] - Dining_Lightmode_Afternoon_CeilingLightBulb3_Dimmer changed from NULL to 28.0
2020-06-27 03:12:07.794 [vent.ItemStateChangedEvent] - Livingroom_AmazonEcho_StartRoutine changed from NULL to 
2020-06-27 03:12:07.836 [vent.ItemStateChangedEvent] - Masterbedroom_Lightmode_Day_PaxLight_Natascha_Cold_Dimmer changed from NULL to 0.0
2020-06-27 03:12:07.870 [vent.ItemStateChangedEvent] - Dining_Settings_LightsOnMinimumTime changed from NULL to 2.0
2020-06-27 03:12:07.878 [vent.ItemStateChangedEvent] - Bathroom_Lightmode_Save changed from NULL to OFF
2020-06-27 03:12:07.894 [vent.ItemStateChangedEvent] - Lukasbedroom_UnderBedLights_Scene changed from NULL to ALL_OFF
2020-06-27 03:12:07.904 [vent.ItemStateChangedEvent] - Masterbedroom_Lightmode_Sleep_PaxLight_Natascha_Warm_Dimmer changed from NULL to 2.0
2020-06-27 03:12:07.951 [vent.ItemStateChangedEvent] - Dining_Lightmode_Day_CeilingLightBulb1_Dimmer changed from NULL to 76.0
2020-06-27 03:12:07.963 [vent.ItemStateChangedEvent] - Masterbedroom_Lights_Scene changed from NULL to ALL_OFF
2020-06-27 03:12:07.989 [vent.ItemStateChangedEvent] - Bathroom_Lightmode_Bath_CeilingLight_Color changed from NULL to 0,0,100
2020-06-27 03:12:08.004 [vent.ItemStateChangedEvent] - Salon_Settings_Rules_Rollershutter_DownAfterDusk changed from NULL to ON
2020-06-27 03:12:08.016 [vent.ItemStateChangedEvent] - Masterbedroom_SensorDoor_Temperature changed from NULL to 26.8
2020-06-27 03:12:08.024 [vent.ItemStateChangedEvent] - Wc_Lightmode_Day_MirrorLight_Dimmer changed from NULL to 100.0
2020-06-27 03:12:08.054 [vent.ItemStateChangedEvent] - Bathroom_SensorMirror_Luminance changed from NULL to 0.0
2020-06-27 03:12:08.092 [vent.ItemStateChangedEvent] - Livingroom_AmazonEcho_Volume changed from NULL to 25.0
2020-06-27 03:12:08.153 [vent.ItemStateChangedEvent] - Wc_Sensor_Motion changed from NULL to OFF
2020-06-27 03:12:08.180 [vent.ItemStateChangedEvent] - Shinobi_Server_Doorbird_Record changed from NULL to OFF
2020-06-27 03:12:08.202 [vent.ItemStateChangedEvent] - Dining_Rollershutter_hasAlreadyBeenOpenedByFirstMotion changed from NULL to OFF
2020-06-27 03:12:08.249 [vent.ItemStateChangedEvent] - Dining_Settings_LuminanceLimit changed from NULL to 494.0
2020-06-27 03:12:08.259 [vent.ItemStateChangedEvent] - Away_mode changed from NULL to OFF
2020-06-27 03:12:08.290 [vent.ItemStateChangedEvent] - Masterbedroom_Settings_Rules_Rollershutter_UpAfterDawn changed from NULL to OFF
2020-06-27 03:12:08.315 [vent.ItemStateChangedEvent] - Bathroom_Lightmode_Night_CeilingLight_Color changed from NULL to 0,0,100
2020-06-27 03:12:08.345 [vent.ItemStateChangedEvent] - Dining_Lightmode_Evening_CeilingLight_Dimmer changed from NULL to 100.0
2020-06-27 03:12:08.358 [vent.ItemStateChangedEvent] - Livingroom_AmazonEcho_Radio changed from NULL to OFF
2020-06-27 03:12:08.423 [vent.ItemStateChangedEvent] - Basement_Switch_Door_Switch changed from NULL to ON
2020-06-27 03:12:08.471 [vent.ItemStateChangedEvent] - Dining_Lightmode_Night_CeilingLightBulb3_Dimmer changed from NULL to 13.0
2020-06-27 03:12:08.548 [vent.ItemStateChangedEvent] - Salon_Lightmode_Day_CeilingLight_Dimmer changed from NULL to 100.0
2020-06-27 03:12:08.580 [vent.ItemStateChangedEvent] - Outside_LEDiHF_Dimmer changed from NULL to 0.0
2020-06-27 03:12:08.593 [vent.ItemStateChangedEvent] - Kitchen_Settings_Rules_Lightning changed from NULL to ON
2020-06-27 03:12:08.604 [vent.ItemStateChangedEvent] - Dining_Lightmode_Evening_CeilingLightBulb2_Dimmer changed from NULL to 57.0
2020-06-27 03:12:08.642 [vent.ItemStateChangedEvent] - Salon_Lightmode_Afternoon_CeilingLightBulb_Dimmer changed from NULL to 100
2020-06-27 03:12:08.654 [vent.ItemStateChangedEvent] - Ugfloor_Settings_Rules_Lightning changed from NULL to ON
2020-06-27 03:12:08.691 [vent.ItemStateChangedEvent] - Livingroom_AmazonEcho_AscendingAlarm changed from NULL to OFF
2020-06-27 03:12:08.700 [vent.ItemStateChangedEvent] - Dining_Settings_Rules_Rollershutter_UpAt10 changed from NULL to OFF
2020-06-27 03:12:08.706 [vent.ItemStateChangedEvent] - Masterbedroom_Lightmode_Fuck_PaxLight_Natascha_Warm_Dimmer changed from NULL to 100.0
2020-06-27 03:12:08.717 [vent.ItemStateChangedEvent] - Bathroom_Lightmode_Day_BasinLight_Switch changed from NULL to ON
2020-06-27 03:12:08.734 [vent.ItemStateChangedEvent] - Dining_Settings_Rules_Rollershutter_UpAtMotion changed from NULL to ON
2020-06-27 03:12:08.745 [vent.ItemStateChangedEvent] - Doorbird_Ring changed from NULL to OFF
2020-06-27 03:12:08.752 [vent.ItemStateChangedEvent] - Wc_Sensor_Luminance changed from NULL to 0.0
2020-06-27 03:12:08.763 [vent.ItemStateChangedEvent] - Kitchen_Lights_Scene changed from NULL to ALL_OFF
2020-06-27 03:12:08.794 [vent.ItemStateChangedEvent] - Masterbedroom_Lightmode_Fuck_PaxLight_Natascha_Switch changed from NULL to OFF
2020-06-27 03:12:08.824 [vent.ItemStateChangedEvent] - Masterbedroom_Lightmode_Evening_PaxLight_Natascha_Warm_Dimmer changed from NULL to 44.0
2020-06-27 03:12:08.904 [vent.ItemStateChangedEvent] - Livingroom_AmazonEcho_NotificationVolume changed from NULL to 60.0
2020-06-27 03:12:08.932 [vent.ItemStateChangedEvent] - Bathroom_CeilingLight_Dimmer changed from NULL to 0.0
2020-06-27 03:12:08.954 [vent.ItemStateChangedEvent] - Livingroom_Settings_Rules_Rollershutter_UpAt8 changed from NULL to OFF
2020-06-27 03:12:09.063 [vent.ItemStateChangedEvent] - Kitchen_AmazonEcho_StartRoutine changed from NULL to 
2020-06-27 03:12:09.079 [vent.ItemStateChangedEvent] - Bathroom_Lightmode_Night_BasinLight_Switch changed from NULL to ON
2020-06-27 03:12:09.154 [vent.ItemStateChangedEvent] - Lukasbedroom_UnderBedLightStripe_FullColor changed from NULL to White
2020-06-27 03:12:09.161 [vent.ItemStateChangedEvent] - Wc_Lightmode_Morning_MirrorLight_Dimmer changed from NULL to 100.0
2020-06-27 03:12:09.205 [ome.event.ItemCommandEvent] - Item 'Kitchen_Outlet_Coffeemachine_MeterWatts' received command 3.40
2020-06-27 03:12:09.206 [vent.ItemStateChangedEvent] - Kitchen_Outlet_Coffeemachine_MeterWatts changed from 2.40 to 3.40
2020-06-27 03:12:09.237 [vent.ItemStateChangedEvent] - Salon_Settings_LightsOnMinimumTime changed from NULL to 10.0
2020-06-27 03:12:09.260 [vent.ItemStateChangedEvent] - Kitchen_Lightmode_Morning_SinkLightLeft_Color changed from NULL to 0,0,100
2020-06-27 03:12:09.275 [vent.ItemStateChangedEvent] - Wc_Lights_Scene changed from NULL to ALL_OFF
2020-06-27 03:12:09.295 [vent.ItemStateChangedEvent] - Salon_CeilingLight_Dimmer changed from NULL to 100.0
2020-06-27 03:12:09.355 [vent.ItemStateChangedEvent] - Kitchen_Settings_Rules_Rollershutter_UpAfterDawn changed from NULL to OFF
2020-06-27 03:12:09.365 [vent.ItemStateChangedEvent] - Livingroom_Settings_Rules_Rollershutter_DownForTV changed from NULL to ON
2020-06-27 03:12:09.371 [vent.ItemStateChangedEvent] - Bathroom_Lightmode_Bath_CeilingLight_Dimmer changed from NULL to 36.0
2020-06-27 03:12:09.403 [vent.ItemStateChangedEvent] - Basement_CeilingLightLeft_Switch changed from NULL to ON
2020-06-27 03:12:09.408 [vent.ItemStateChangedEvent] - Masterbedroom_Lightmode_Afternoon_PaxLight_Natascha_Cold_Dimmer changed from NULL to 100.0
2020-06-27 03:12:09.424 [vent.ItemStateChangedEvent] - Dining_Lightmode_Morning_CeilingLightBulb1_Dimmer changed from NULL to 36.0
2020-06-27 03:12:09.430 [vent.ItemStateChangedEvent] - Dining_Lightmode_Day_CeilingLight_Switch changed from NULL to ON
2020-06-27 03:12:09.437 [vent.ItemStateChangedEvent] - Bathroom_Lightmode_Evening_CeilingLight_Dimmer changed from NULL to 100.0
2020-06-27 03:12:09.458 [vent.ItemStateChangedEvent] - Dining_Lightmode_Night_CeilingLight_Switch changed from NULL to ON
2020-06-27 03:12:09.474 [vent.ItemStateChangedEvent] - Kitchen_Settings_Rules_Rollershutter_UpAt8 changed from NULL to OFF
2020-06-27 03:12:09.530 [vent.ItemStateChangedEvent] - Bathroom_Settings_Rules_Rollershutter_UpAfterDawn changed from NULL to OFF
2020-06-27 03:12:09.596 [vent.ItemStateChangedEvent] - Kitchen_Lightmode_Morning_CeilingLight_Dimmer changed from NULL to 69.0
2020-06-27 03:12:09.611 [vent.ItemStateChangedEvent] - Salon_Lightmode_Evening_CeilingLight_Dimmer changed from NULL to 100.0
2020-06-27 03:12:09.642 [vent.ItemStateChangedEvent] - Ugfloor_Lightmode_Afternoon_WallStripe_Dimmer changed from NULL to 100.0
2020-06-27 03:12:09.654 [vent.ItemStateChangedEvent] - Bathroom_Lightmode_Morning_CeilingLight_Colortemp changed from NULL to 55.0
2020-06-27 03:12:09.667 [vent.ItemStateChangedEvent] - Masterbedroom_Settings_Rules_Rollershutter_DownAfterDusk changed from NULL to ON
2020-06-27 03:12:09.710 [vent.ItemStateChangedEvent] - Wc_Settings_LeavingTime changed from NULL to 3.0 

Here todays example where one item is missing:

    Color  Bathroom_Lightmode_Afternoon_CeilingLight_Color         "CeilingLight Farbe"           (G_Bathroom_Lightmode_Afternoon)
    Dimmer Bathroom_Lightmode_Afternoon_CeilingLight_Colortemp "CeilingLight Farbtemperatur"  (G_Bathroom_Lightmode_Afteroon)

in the Logs after restart:
Only one, the COLORTEMP, of the two items received an update, the other one, the Color is not mentioned in the restart-log from this morning and remained null.

2020-06-27 03:12:12.788 [vent.ItemStateChangedEvent] - Livingroom_AmazonEcho_StartCommand changed from NULL to 
2020-06-27 03:12:12.856 [vent.ItemStateChangedEvent] - Salon_Lightmode_Morning_CeilingLightBulb_Dimmer changed from NULL to 73
2020-06-27 03:12:12.887 [vent.ItemStateChangedEvent] - Bathroom_Lightmode_Afternoon_CeilingLight_Colortemp changed from NULL to 74.0
2020-06-27 03:12:12.899 [vent.ItemStateChangedEvent] - Kitchen_AmazonEcho_ImageUrl changed from NULL to 
2020-06-27 03:12:12.911 [vent.ItemStateChangedEvent] - Kitchen_AmazonEcho_Title changed from NULL to 
2020-06-27 03:12:12.924 [vent.ItemStateChangedEvent] - Ugfloor_Luminance_Natural changed from NULL to 0.0

I checked the logs off the other days and it looks like it works the other days, just not today:

Here is a bootup-sequence from a different day -

2020-06-25 03:10:41.586 [vent.ItemStateChangedEvent] - Kitchen_Lightmode_Night_CeilingLight_Dimmer changed from NULL to 1.0
2020-06-25 03:10:41.696 [vent.ItemStateChangedEvent] - Masterbedroom_Lightmode_Night_PaxLight_Natascha_Warm_Dimmer changed from NULL to 17.0
2020-06-25 03:10:41.716 [vent.ItemStateChangedEvent] - Masterbedroom_Luminance_Natural changed from NULL to 0.0
2020-06-25 03:10:41.775 [vent.ItemStateChangedEvent] - Ugfloor_Settings_LightsOnMinimumTime changed from NULL to 3.0
2020-06-25 03:10:41.830 [vent.ItemStateChangedEvent] - Salon_Lightmode_Evening_CeilingLight_Switch changed from NULL to ON
-->2020-06-25 03:10:41.849 [vent.ItemStateChangedEvent] - Bathroom_Lightmode_Afternoon_CeilingLight_Color changed from NULL to 0,0,100
2020-06-25 03:10:41.916 [vent.ItemStateChangedEvent] - Masterbedroom_Settings_LuminanceLimit changed from NULL to 549.0
2020-06-25 03:10:41.988 [vent.ItemStateChangedEvent] - Livingroom_AmazonEcho_BluetoothMAC changed from NULL to 
2020-06-25 03:10:42.044 [vent.ItemStateChangedEvent] - Ugfloor_SensorEntryDoor_Luminance changed from NULL to 0.0
2020-06-25 03:10:42.105 [vent.ItemStateChangedEvent] - Bathroom_Settings_Rules_Lightning changed from NULL to ON
2020-06-25 03:10:42.167 [vent.ItemStateChangedEvent] - Livingroom_AmazonEcho_AmazonMusicTrackId changed from NULL to 
2020-06-25 03:10:42.183 [vent.ItemStateChangedEvent] - Lukasbedroom_OverBedLightStripe_Timer changed from NULL to 0.0

here you can see the Color has been set, but probably other items have not. It looks like it is skipping items randomly.

For debugging reasons, I have tested some tcpdump to see if I can spot something, but every change is immediately (well, in batches every 5 sec) transferred to the influxDB database:


And is visible:

DB shows expected content:
image

Conclusion: this morning, there was a value for Bathroom_Lightmode_Afternoon_CeilingLight_Color in the DB, but it was not used to restore the state after reboot.

Thanks to everybody who tooks the time to read through this :slight_smile:
Any Idea what to do?
PhiL

You do NOT usually see Item state changed events in the events.log for restore-on-startup. I suspect the logs you are looking at are for bindings updating Items from real devices status, as they initialize.

My guess is that restore is not working at all for you, cannot see why.

Any clues from persistence startup in openhab.log?

Hmm I thought influxDB couldnt be used to restore on startup…I dont know why though. Maybe its because usually its MapDB which is mentioned in regards to restore on startup.

I sort of thought the same thing maybe. So I double checked the InfluxDB binding docs, which (both versions actually) state:

The persisted values can be queried from within openHAB.

However they do not explicitly state support for restoreOnStartup as some of the others do (I was thinking about, as you say, mapdb in particular)…

Hmm…

Well, that perspective was rather unexpected :slight_smile:
I am using Openhab with (only) influxDB since years. But I agree, I never dared to restard, restarts were always a mess… Maybe … that is connected :slight_smile: Thanks for that!

It is not not working… the item at hand was receiving the correct value yesterday and a lot of other switches and values have been restored successfully today. But maybe it is just not designed, the switchs last state is more than 10 days ago etc…

I have installed the MapDB binding and copied the influxdb.persist to mapdb.persist, removed the “restoreOnStartup” in the influxdb. I had the mapdb.persist set up to everyMinute for a couple of minutes to make sure, all values are in the mapDB at least once, then back to everyChange. I’ll report tomorrow.

or, I don’t wait and just restart.
I cannot find a single item that has state null.

Thanks to you guys. Saved the day!

2 Likes

Great news! Thanks for reporting back.

I just wanted to point out, I heard @rlkoshak mention before (credit to him, I am just parroting) that the perception by many people seems that you cannot run multiple persistence services in parallel, but this is simply not true. Even if you already know this, I thought it might be worth repeating, for benefit of others.

I am about to do my own persistence set up, and I think I will do something along these lines (MapDB + Graphite / Carbon in my case, is what I am thinking).

InfluxDB absolutely can be used for restoreOnStartup. But I do not recommend it because:

  • it’s going to be slower than an embedded database like MapDB
  • there are boot dependencies, when the machine reboots InfluxDB has to be fully started and accepting connections before openHAB tries to connect to it for restoreOnStartup
  • it requires you to use up lots of unnecessary space saving all the changes to Items where all you care about is the most recent change (this can be mitigated a little bit with retention policies).

For restoreOnStartup, MapDB is the superior choice over any external database and it’s superior to rrd4j because it supports all Item types, not just number type Items.

I know you probably don’t mean it this way, but just in case anywone is confuised, MapDB only stores one value for each Item, the most recently saved value.

I don’t want to get too far off topic but see Design Pattern: Group Based Persistence and see the list of addons for the list of supported databases openHAB can read/write to. Graphite is not among them so you will probably have to write a new add-on to support it. For time series type databases, most OH users use InfluxDB.

Yes, I am well aware. I have some personal considerations which have me looking at Graphite, as opposed to the nowadays seemingly very popular InfluxDB. At any rate, I am still researching and your link contains as always much more good and relevant info, so I will add it to my reading list. :slight_smile:

Okay thanks for clearing this one up for me… I somehow knew there was something about influxdb not beeing the best idea for this purpose :smiley:

I personally add: “it may not restore 100% reliably if you have thousands of items that also happen to partially have old changes when stored external system”.