System broken: Suddenly all things are UNITIALIZED?

openHAB has been working well for the previous months on my Raspberry Pi 4B+. But for the last few days, after I have rebooted my pi once, all devices have remained UNITIALIZED (status details: NONE) and consequently, all items only show the initial default values.

The logs are not showing any relevant info, that’s all I got since rebooting my pi:

2020-12-03 09:52:23.473 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'someRules.rules'
2020-12-03 09:52:29.294 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'someOtherRules.rules'
...

I rebooted the pi a number of times, but it did not help. All the web interfaces work well, but they don’t show any up to date information.

Do you have any hints on how I could start to find the source of the issue?

(Just for suspicion, I also noted another weird issue: I had initialized a git repo in my /etc/openhab2 folder a long time ago, and today, git status said that any blob object was corrupt, so I had to clone the .git folder again. Still, all other configuration files are intact according to git status.)

Should I consider that my SD card could be damaged? It would not be the first time, some months ago I already had to reinstall openHABian because it could not boot any longer with a real-confusing, low-level error that, according to StackOverflow, indicated a hardware defect. However, chkdsk did not find any problems with the SD card …

Looking forward to your help!

Platform information:

  • Hardware: Raspi 4B+, ca. 1 year old
  • OS: openHABian
  • Java Runtime Environment: openHABian, I did not install any custom Java stuff
  • openHAB version: 2.5.8

Maybe file rights are no longer correct. As a first step, shutdown OH and execute

openhab-cli reset-ownership

Start OH again and see if it starts normally.

Thanks for the idea! Unfortunately, sudo openhab-cli reset-ownership && sudo reboot does not help. :frowning:

Any other ideas?

Has OH been updated?
Did you change anything, e.g. added another binding that was not used before?
How are your things, items, rules defined (UI or files)?
Can you please post a complete log, from start of OH until nothing is logged anymore? Thanks

Has OH been updated?

Not recently, the last update must have been months ago, a long time before my problems started to occur.

Did you change anything, e.g. added another binding that was not used before?

The same answer. I have not changed my configuration for months. Does a temporarily unplugged Milight Hub or Zigbee Adapter count as a change?

How are your things, items, rules defined (UI or files)?

Everything in files but a few Things in the UI. Would you like me to share my entire configuration?

Can you please post a complete log, from start of OH until nothing is logged anymore? Thanks

Is this what you meant?

$ openhab-cli showlogs --help

==> /var/log/openhab2/audit.log <==

==> /var/log/openhab2/events.log <==
2020-12-04 21:01:31.534 [temChannelLinkRemovedEvent] - Link 'Remote_C1_Group6 => mqtt:topic:mosquitto:Remote_C1:group6' has been removed.
2020-12-04 21:01:31.536 [temChannelLinkRemovedEvent] - Link 'Light_C_CouchStrip_Temperature => lifx:colormzlight:D073D52FE9DB:temperature' has been removed.
2020-12-04 21:01:31.539 [temChannelLinkRemovedEvent] - Link 'Remote_C1_Group4 => mqtt:topic:mosquitto:Remote_C1:group4' has been removed.
2020-12-04 21:01:31.541 [temChannelLinkRemovedEvent] - Link 'Light_C_WallLamp_Temperature => lifx:colorlight:D073D53DE106:temperature' has been removed.
2020-12-04 21:01:31.544 [temChannelLinkRemovedEvent] - Link 'Light_C_CouchStrip_Dimmer => lifx:colormzlight:D073D52FE9DB:color' has been removed.
2020-12-04 21:01:31.547 [temChannelLinkRemovedEvent] - Link 'Heat_C_OilRadiator => tplinksmarthome:hs110:A94FF3:switch' has been removed.
2020-12-04 21:01:31.549 [temChannelLinkRemovedEvent] - Link 'Light_C_SouthPlace_Temperature => lifx:colorlight:D073D53DE0C5:temperature' has been removed.
2020-12-04 21:01:31.552 [temChannelLinkRemovedEvent] - Link 'Weather_Temperature_F1Min => darksky:weather-and-forecast:api:local:forecastToday#min-temperature' has been removed.
2020-12-04 21:01:31.555 [temChannelLinkRemovedEvent] - Link 'Remote_C1_Group2 => mqtt:topic:mosquitto:Remote_C1:group2' has been removed.
2020-12-04 21:01:31.923 [temChannelLinkRemovedEvent] - Link 'HS1101_SwitchLed => tplinksmarthome:hs110:A94FF3:led' has been removed.

==> /var/log/openhab2/openhab.log <==
2020-12-04 21:03:36.773 [INFO ] [openhab.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2020-12-04 21:03:37.042 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 112d340b-df7e-4989-bd87-12f0a8c9013f, base URL = http://localhost:8080)
2020-12-04 21:04:21.403 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'AlarmClock_Christoph_Dimming.rules'
2020-12-04 21:04:27.153 [INFO ] [el.core.internal.ModelRepositoryImpl] - Validation issues found in configuration model 'Remote_C1.rules', using it anyway:
The value of the local variable lifxGroup is not used
2020-12-04 21:04:27.161 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Remote_C1.rules'
2020-12-04 21:04:28.127 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Weather.rules'
2020-12-04 21:04:28.543 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'OpenHabPi.sitemap.rules'
2020-12-04 21:04:29.161 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'OpenHabPi.rules'
2020-12-04 21:04:30.274 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Switch_C_Absence_Toggle.rules'

Thanks for you help! :slight_smile:

Not really. It might lead to error messages but that should not disturb the whole system in a way that nothing is working anymore.

Your openhan.log is remarably short. Mine starts with Time zone setting, locale and then the items files are loaded and only then the rules files are loaded. Item files are not even mentioned in your log. Do you have items in files? From your answers above I suspect you may have some.

You wrote “Still, all other configuration files are intact according to git status.” Did you check that the configuration files are present in /etc/openhab2?

If everything is there as it should, you can try to get rid of chached files by shutting down OH, execute

sudo openhab-cli clean-cache

and restart OH again. You may need a few restarts, not only one.

If that does not help, we need to dig deeper and go the debugging way…

Alright, here is what I tried again now:

openhabian@openhab:/etc/openhab2 $ sudo openhab-cli stop && sudo /bin/systemctl stop openhab2.service && sudo openhab-cli clean-cache
[sudo] password for openhabian: 

A systemd service configuration exists...
Use 'sudo /bin/systemctl stop openhab2.service' to stop an openHAB service
Stopping any instance of openHAB...

This command will delete the temporary files within openHAB.
May resolve issues with addon installation and configuration.
The next start of openHAB will take a bit longer.

Okay to Continue? [y/N]: y
openhabian@openhab:/etc/openhab2 $ date
Sat  5 Dec 16:10:05 CET 2020
openhabian@openhab:/etc/openhab2 $ sudo /bin/systemctl start openhab2.service
# Waited some time for everything to initialize before grabbing the logs ...
openhabian@openhab:/etc/openhab2 $ date
Sat  5 Dec 16:45:04 CET 2020
  • audit.log: empty
  • events.log:
    2020-12-05 16:14:31.988 [.ItemChannelLinkAddedEvent] - Link 'HS1101_SwitchLed-tplinksmarthome:hs110:A94FF3:led' has been added.
    2020-12-05 16:14:32.791 [.ItemChannelLinkAddedEvent] - Link 'Heat_C_OilRadiator-tplinksmarthome:hs110:A94FF3:switch' has been added.
    2020-12-05 16:14:32.806 [.ItemChannelLinkAddedEvent] - Link 'Light_C_CouchStrip_Color-lifx:colormzlight:D073D52FE9DB:color' has been added.
    2020-12-05 16:14:32.818 [.ItemChannelLinkAddedEvent] - Link 'Light_C_CouchStrip_Dimmer-lifx:colormzlight:D073D52FE9DB:color' has been added.
    2020-12-05 16:14:32.822 [.ItemChannelLinkAddedEvent] - Link 'Light_C_CouchStrip_Temperature-lifx:colormzlight:D073D52FE9DB:temperature' has been added.
    2020-12-05 16:14:32.826 [.ItemChannelLinkAddedEvent] - Link 'Light_C_WallLamp_Color-lifx:colorlight:D073D53DE106:color' has been added.
    2020-12-05 16:14:32.829 [.ItemChannelLinkAddedEvent] - Link 'Light_C_WallLamp_Dimmer-lifx:colorlight:D073D53DE106:color' has been added.
    2020-12-05 16:14:32.833 [.ItemChannelLinkAddedEvent] - Link 'Light_C_WallLamp_Temperature-lifx:colorlight:D073D53DE106:temperature' has been added.
    2020-12-05 16:14:32.838 [.ItemChannelLinkAddedEvent] - Link 'Light_C_SouthPlace_Color-lifx:colorlight:D073D53DE0C5:color' has been added.
    2020-12-05 16:14:32.842 [.ItemChannelLinkAddedEvent] - Link 'Light_C_SouthPlace_Dimmer-lifx:colorlight:D073D53DE0C5:color' has been added.
    2020-12-05 16:14:32.846 [.ItemChannelLinkAddedEvent] - Link 'Light_C_SouthPlace_Temperature-lifx:colorlight:D073D53DE0C5:temperature' has been added.
    2020-12-05 16:14:32.849 [.ItemChannelLinkAddedEvent] - Link 'Light_C_CouchCorner_Temperature-zigbee:device:06001F06:00158d00013dc7b6:00158D00013DC7B6_1_colortemperature' has been added.
    2020-12-05 16:14:32.853 [.ItemChannelLinkAddedEvent] - Link 'Light_C_CouchCorner_Dimmer-zigbee:device:06001F06:00158d00013dc7b6:00158D00013DC7B6_1_dimmer' has been added.
    2020-12-05 16:14:32.856 [.ItemChannelLinkAddedEvent] - Link 'Remote_C1_Master-mqtt:topic:mosquitto:Remote_C1:master' has been added.
    2020-12-05 16:14:32.859 [.ItemChannelLinkAddedEvent] - Link 'Remote_C1_Group1-mqtt:topic:mosquitto:Remote_C1:group1' has been added.
    2020-12-05 16:14:32.861 [.ItemChannelLinkAddedEvent] - Link 'Remote_C1_Group2-mqtt:topic:mosquitto:Remote_C1:group2' has been added.
    2020-12-05 16:14:32.864 [.ItemChannelLinkAddedEvent] - Link 'Remote_C1_Group3-mqtt:topic:mosquitto:Remote_C1:group3' has been added.
    2020-12-05 16:14:32.866 [.ItemChannelLinkAddedEvent] - Link 'Remote_C1_Group4-mqtt:topic:mosquitto:Remote_C1:group4' has been added.
    2020-12-05 16:14:32.869 [.ItemChannelLinkAddedEvent] - Link 'Remote_C1_Group5-mqtt:topic:mosquitto:Remote_C1:group5' has been added.
    2020-12-05 16:14:32.871 [.ItemChannelLinkAddedEvent] - Link 'Remote_C1_Group6-mqtt:topic:mosquitto:Remote_C1:group6' has been added.
    2020-12-05 16:14:32.874 [.ItemChannelLinkAddedEvent] - Link 'Remote_C1_Group7-mqtt:topic:mosquitto:Remote_C1:group7' has been added.
    2020-12-05 16:14:32.876 [.ItemChannelLinkAddedEvent] - Link 'Remote_C1_Group8-mqtt:topic:mosquitto:Remote_C1:group8' has been added.
    2020-12-05 16:14:32.879 [.ItemChannelLinkAddedEvent] - Link 'Sensor_OpenHabPi_Temperature-systeminfo:computer:openhab:sensors#cpuTemp' has been added.
    2020-12-05 16:14:32.881 [.ItemChannelLinkAddedEvent] - Link 'Sensor_OpenHabPi_CpuUtilizationRaw-exec:command:mpstat_cpu_utilization:output' has been added.
    2020-12-05 16:14:39.439 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from UNINITIALIZED to INITIALIZING
    2020-12-05 16:14:39.615 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x24FB' changed from UNINITIALIZED to UNINITIALIZED (BRIDGE_UNINITIALIZED)
    2020-12-05 16:14:44.600 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from INITIALIZING to OFFLINE (CONFIGURATION_PENDING): Trying to connect to the MQTT broker now, check the address, user and pasword are correct and the broker is online.
    2020-12-05 16:14:44.612 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x24FB' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
    2020-12-05 16:14:44.647 [hingStatusInfoChangedEvent] - 'espmilighthub:fut089:Auto001:0x24FB' changed from INITIALIZING to ONLINE
    2020-12-05 16:14:45.107 [hingStatusInfoChangedEvent] - 'espmilighthub:esp8266Bridge:Auto001' changed from OFFLINE (CONFIGURATION_PENDING): Trying to connect to the MQTT broker now, check the address, user and pasword are correct and the broker is online. to ONLINE
    2020-12-05 16:15:03.559 [thome.event.ExtensionEvent] - Extension 'package-standard' has been installed.
    2020-12-05 16:15:16.834 [thome.event.ExtensionEvent] - Extension 'package-standard' has been installed.
    2020-12-05 16:16:12.654 [hingStatusInfoChangedEvent] - 'zigbee:device:06001F06:00158d00013dc7b6' changed from UNINITIALIZED to UNINITIALIZED (BRIDGE_UNINITIALIZED)
    2020-12-05 16:16:12.999 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNINITIALIZED to INITIALIZING
    2020-12-05 16:16:13.133 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from INITIALIZING to UNKNOWN
    2020-12-05 16:16:13.193 [hingStatusInfoChangedEvent] - 'zigbee:device:06001F06:00158d00013dc7b6' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
    2020-12-05 16:16:13.227 [hingStatusInfoChangedEvent] - 'zigbee:device:06001F06:00158d00013dc7b6' changed from INITIALIZING to UNKNOWN
    2020-12-05 16:16:14.519 [hingStatusInfoChangedEvent] - 'mqtt:broker:mosquitto' changed from UNINITIALIZED to INITIALIZING
    2020-12-05 16:16:14.782 [hingStatusInfoChangedEvent] - 'mqtt:broker:mosquitto' changed from INITIALIZING to OFFLINE
    2020-12-05 16:16:15.741 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:16:15.748 [hingStatusInfoChangedEvent] - 'zigbee:device:06001F06:00158d00013dc7b6' changed from UNKNOWN to OFFLINE (BRIDGE_OFFLINE)
    2020-12-05 16:16:16.663 [hingStatusInfoChangedEvent] - 'lifx:colorlight:D073D53DE0C5' changed from UNINITIALIZED to INITIALIZING
    2020-12-05 16:16:17.018 [hingStatusInfoChangedEvent] - 'lifx:colorlight:D073D53DE0C5' changed from INITIALIZING to OFFLINE
    2020-12-05 16:16:17.227 [hingStatusInfoChangedEvent] - 'lifx:colormzlight:D073D52FE9DB' changed from UNINITIALIZED to INITIALIZING
    2020-12-05 16:16:17.277 [hingStatusInfoChangedEvent] - 'systeminfo:computer:openhab' changed from UNINITIALIZED to INITIALIZING
    2020-12-05 16:16:17.319 [hingStatusInfoChangedEvent] - 'lifx:colorlight:D073D53DE0C5' changed from OFFLINE to ONLINE
    2020-12-05 16:16:17.361 [hingStatusInfoChangedEvent] - 'lifx:colormzlight:D073D52FE9DB' changed from INITIALIZING to OFFLINE
    2020-12-05 16:16:17.410 [hingStatusInfoChangedEvent] - 'lifx:colorlight:D073D53DE106' changed from UNINITIALIZED to INITIALIZING
    2020-12-05 16:16:17.560 [hingStatusInfoChangedEvent] - 'lifx:colormzlight:D073D52FE9DB' changed from OFFLINE to ONLINE
    2020-12-05 16:16:17.572 [hingStatusInfoChangedEvent] - 'lifx:colorlight:D073D53DE106' changed from INITIALIZING to OFFLINE
    2020-12-05 16:16:17.575 [vent.ItemStateChangedEvent] - Light_C_SouthPlace_Color changed from NULL to 0.0,0,35
    2020-12-05 16:16:17.577 [vent.ItemStateChangedEvent] - Light_C_SouthPlace_Dimmer changed from NULL to 0.0,0,35
    2020-12-05 16:16:17.582 [vent.ItemStateChangedEvent] - Light_C_SouthPlace_Temperature changed from NULL to 84
    2020-12-05 16:16:17.589 [vent.ItemStateChangedEvent] - Light_C_SouthPlace_Color changed from 0.0,0,35 to 0.0,0,0
    2020-12-05 16:16:17.594 [vent.ItemStateChangedEvent] - Light_C_SouthPlace_Dimmer changed from 0.0,0,35 to 0.0,0,0
    2020-12-05 16:16:17.649 [vent.ItemStateChangedEvent] - Light_C_CouchStrip_Dimmer changed from NULL to 32.99794006347656,100,35
    2020-12-05 16:16:17.725 [vent.ItemStateChangedEvent] - Light_C_CouchStrip_Color changed from NULL to 32.99794006347656,100,35
    2020-12-05 16:16:17.729 [vent.ItemStateChangedEvent] - Light_C_CouchStrip_Temperature changed from NULL to 84
    2020-12-05 16:16:17.734 [vent.ItemStateChangedEvent] - Light_C_CouchStrip_Dimmer changed from 32.99794006347656,100,35 to 32.99794006347656,100,0
    2020-12-05 16:16:17.738 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:16:17.777 [vent.ItemStateChangedEvent] - Light_C_CouchStrip_Color changed from 32.99794006347656,100,35 to 32.99794006347656,100,0
    2020-12-05 16:16:17.787 [hingStatusInfoChangedEvent] - 'lifx:colorlight:D073D53DE106' changed from OFFLINE to ONLINE
    2020-12-05 16:16:17.823 [vent.ItemStateChangedEvent] - Light_C_WallLamp_Dimmer changed from NULL to 0.0,0,37
    2020-12-05 16:16:17.833 [vent.ItemStateChangedEvent] - Light_C_WallLamp_Color changed from NULL to 0.0,0,37
    2020-12-05 16:16:17.837 [vent.ItemStateChangedEvent] - Light_C_WallLamp_Temperature changed from NULL to 100
    2020-12-05 16:16:17.874 [vent.ItemStateChangedEvent] - Light_C_WallLamp_Dimmer changed from 0.0,0,37 to 0.0,0,0
    2020-12-05 16:16:17.878 [vent.ItemStateChangedEvent] - Light_C_WallLamp_Color changed from 0.0,0,37 to 0.0,0,0
    2020-12-05 16:16:18.092 [me.event.ThingUpdatedEvent] - Thing 'lifx:colormzlight:D073D52FE9DB' has been updated.
    2020-12-05 16:16:18.126 [vent.ItemStateChangedEvent] - Light_C_CouchStrip_Dimmer changed from NULL to 32.99794006347656,100,0
    2020-12-05 16:16:18.131 [vent.ItemStateChangedEvent] - Light_C_CouchStrip_Color changed from NULL to 32.99794006347656,100,0
    2020-12-05 16:16:18.141 [vent.ItemStateChangedEvent] - Light_C_CouchStrip_Temperature changed from NULL to 84
    2020-12-05 16:16:18.255 [hingStatusInfoChangedEvent] - 'tplinksmarthome:hs110:A94FF3' changed from UNINITIALIZED to INITIALIZING
    2020-12-05 16:16:18.361 [hingStatusInfoChangedEvent] - 'tplinksmarthome:hs110:A94FF3' changed from INITIALIZING to UNKNOWN
    2020-12-05 16:16:18.780 [hingStatusInfoChangedEvent] - 'tplinksmarthome:hs110:A94FF3' changed from UNKNOWN to ONLINE
    2020-12-05 16:16:18.798 [vent.ItemStateChangedEvent] - Heat_C_OilRadiator changed from NULL to ON
    2020-12-05 16:16:18.805 [vent.ItemStateChangedEvent] - HS1101_SwitchLed changed from NULL to OFF
    2020-12-05 16:16:19.063 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:16:19.799 [hingStatusInfoChangedEvent] - 'mqtt:broker:mosquitto' changed from OFFLINE to ONLINE
    2020-12-05 16:16:19.811 [me.event.ThingUpdatedEvent] - Thing 'mqtt:broker:mosquitto' has been updated.
    2020-12-05 16:16:24.238 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:16:25.456 [hingStatusInfoChangedEvent] - 'systeminfo:computer:openhab' changed from INITIALIZING to ONLINE
    2020-12-05 16:16:25.466 [vent.ItemStateChangedEvent] - Sensor_OpenHabPi_Temperature changed from NULL to 62.8
    2020-12-05 16:16:25.496 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:16:25.590 [hingStatusInfoChangedEvent] - 'exec:command:mpstat_cpu_utilization' changed from UNINITIALIZED to INITIALIZING
    2020-12-05 16:16:25.631 [hingStatusInfoChangedEvent] - 'exec:command:mpstat_cpu_utilization' changed from INITIALIZING to ONLINE
    2020-12-05 16:16:26.445 [vent.ItemStateChangedEvent] - Sensor_OpenHabPi_Temperature changed from 62.8 to 61.3
    2020-12-05 16:16:27.597 [hingStatusInfoChangedEvent] - 'mqtt:topic:mosquitto:Remote_C1' changed from UNINITIALIZED to INITIALIZING
    2020-12-05 16:16:27.763 [vent.ItemStateChangedEvent] - Sensor_OpenHabPi_CpuUtilizationRaw changed from NULL to 0.5849
    2020-12-05 16:16:27.841 [home.event.InboxAddedEvent] - Discovery Result with UID 'darksky:weather-and-forecast:api:local' has been added.
    2020-12-05 16:16:27.851 [hingStatusInfoChangedEvent] - 'darksky:weather-api:api' changed from UNINITIALIZED to INITIALIZING
    2020-12-05 16:16:27.979 [hingStatusInfoChangedEvent] - 'mqtt:topic:mosquitto:Remote_C1' changed from INITIALIZING to ONLINE
    2020-12-05 16:16:28.038 [hingStatusInfoChangedEvent] - 'darksky:weather-api:api' changed from INITIALIZING to UNKNOWN
    2020-12-05 16:16:28.068 [me.event.InboxRemovedEvent] - Discovery Result with UID 'darksky:weather-and-forecast:api:local' has been removed.
    2020-12-05 16:16:28.468 [hingStatusInfoChangedEvent] - 'darksky:weather-and-forecast:api:local' changed from UNINITIALIZED to INITIALIZING
    2020-12-05 16:16:28.988 [me.event.ThingUpdatedEvent] - Thing 'darksky:weather-and-forecast:api:local' has been updated.
    2020-12-05 16:16:29.007 [hingStatusInfoChangedEvent] - 'darksky:weather-and-forecast:api:local' changed from INITIALIZING to UNKNOWN
    2020-12-05 16:16:33.509 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:16:36.125 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:16:41.160 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:16:41.450 [vent.ItemStateChangedEvent] - Sensor_OpenHabPi_Temperature changed from 61.3 to 62.3
    2020-12-05 16:16:42.310 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:16:44.219 [hingStatusInfoChangedEvent] - 'darksky:weather-and-forecast:api:local' changed from UNKNOWN to ONLINE
    2020-12-05 16:16:44.229 [hingStatusInfoChangedEvent] - 'darksky:weather-api:api' changed from UNKNOWN to ONLINE
    2020-12-05 16:16:47.411 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:16:48.559 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:16:54.189 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:16:55.337 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:16:56.457 [vent.ItemStateChangedEvent] - Sensor_OpenHabPi_Temperature changed from 62.3 to 60.9
    2020-12-05 16:16:59.869 [vent.ItemStateChangedEvent] - Sensor_OpenHabPi_CpuUtilizationRaw changed from 0.5849 to 0.6904
    2020-12-05 16:17:00.430 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:17:04.842 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:17:12.854 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:17:13.998 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:17:19.096 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:17:20.283 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:17:25.387 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:17:26.469 [vent.ItemStateChangedEvent] - Sensor_OpenHabPi_Temperature changed from 60.9 to 61.3
    2020-12-05 16:17:26.560 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:17:31.656 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:17:31.914 [vent.ItemStateChangedEvent] - Sensor_OpenHabPi_CpuUtilizationRaw changed from 0.6904 to 0.224
    2020-12-05 16:17:32.803 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:17:37.899 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:17:39.108 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:17:41.481 [vent.ItemStateChangedEvent] - Sensor_OpenHabPi_Temperature changed from 61.3 to 60.9
    2020-12-05 16:17:44.201 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:17:45.365 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:17:50.455 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:17:51.654 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:17:56.484 [vent.ItemStateChangedEvent] - Sensor_OpenHabPi_Temperature changed from 60.9 to 60.4
    2020-12-05 16:17:56.786 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:17:57.934 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:18:03.058 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:18:03.959 [vent.ItemStateChangedEvent] - Sensor_OpenHabPi_CpuUtilizationRaw changed from 0.224 to 0.0314
    2020-12-05 16:18:04.233 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:18:09.325 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:18:10.481 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:18:15.569 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:18:16.713 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:18:21.801 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    2020-12-05 16:18:22.945 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR)
    2020-12-05 16:18:28.087 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_telegesis:06001F06' changed from OFFLINE (COMMUNICATION_ERROR) to UNKNOWN
    
  • openhab.log
    2020-12-05 16:11:25.552 [WARN ] [org.apache.felix.fileinstall        ] - Error while starting bundle: file:/usr/share/openhab2/addons/org.openhab.binding.espmilighthub-2.5.4-SNAPSHOT.jar
    org.osgi.framework.BundleException: Could not resolve module: org.openhab.binding.espmilighthub [75]
      Unresolved requirement: Import-Package: org.eclipse.smarthome.config.core
    
    	at org.eclipse.osgi.container.Module.start(Module.java:444) ~[org.eclipse.osgi-3.12.100.jar:?]
    	at org.eclipse.osgi.internal.framework.EquinoxBundle.start(EquinoxBundle.java:383) ~[org.eclipse.osgi-3.12.100.jar:?]
    	at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundle(DirectoryWatcher.java:1260) [bundleFile:3.6.4]
    	at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundles(DirectoryWatcher.java:1233) [bundleFile:3.6.4]
    	at org.apache.felix.fileinstall.internal.DirectoryWatcher.doProcess(DirectoryWatcher.java:520) [bundleFile:3.6.4]
    	at org.apache.felix.fileinstall.internal.DirectoryWatcher.process(DirectoryWatcher.java:365) [bundleFile:3.6.4]
    	at org.apache.felix.fileinstall.internal.DirectoryWatcher.run(DirectoryWatcher.java:316) [bundleFile:3.6.4]
    2020-12-05 16:14:08.679 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Christoph.sitemap'
    2020-12-05 16:14:08.768 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Home.sitemap'
    2020-12-05 16:14:08.923 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'DarkSky.things'
    2020-12-05 16:14:08.959 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.things'
    2020-12-05 16:14:09.021 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'espmilighthub.things'
    2020-12-05 16:14:09.293 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Christoph.items'
    2020-12-05 16:14:09.354 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.items'
    2020-12-05 16:14:10.083 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Weather.items'
    2020-12-05 16:14:14.780 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Location set to .
    2020-12-05 16:14:28.392 [INFO ] [el.core.internal.ModelRepositoryImpl] - Validation issues found in configuration model 'Remote_C1.rules', using it anyway:
    The value of the local variable lifxGroup is not used
    2020-12-05 16:14:28.399 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Remote_C1.rules'
    2020-12-05 16:14:29.159 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'OpenHabPi.rules'
    2020-12-05 16:14:30.154 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Switch_C_Absence_Toggle.rules'
    2020-12-05 16:14:30.639 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Weather.rules'
    2020-12-05 16:14:31.608 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'AlarmClock_Christoph_Dimming.rules'
    2020-12-05 16:14:31.907 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'OpenHabPi.sitemap.rules'
    2020-12-05 16:14:32.896 [INFO ] [thome.core.items.ManagedItemProvider] - Finished loading the items which could not have been created before.
    2020-12-05 16:14:33.585 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.178.68:8080
    2020-12-05 16:14:33.589 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.178.68:8443
    2020-12-05 16:14:39.584 [WARN ] [b.handler.EspMilightHubBridgeHandler] - An error occured when trying to talk to your EspMilightHub directly, check the HUB_IP is correct. The error was :{}
    java.io.IOException: Server returned HTTP response code: 401 for URL: http://192.168.178.67/settings
    	at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1903) ~[?:1.8.0_265]
    	at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1501) ~[?:1.8.0_265]
    	at org.openhab.binding.espmilighthub.handler.EspMilightHubBridgeHandler.putHttp(EspMilightHubBridgeHandler.java:576) [bundleFile:?]
    	at org.openhab.binding.espmilighthub.handler.EspMilightHubBridgeHandler.initialize(EspMilightHubBridgeHandler.java:609) [bundleFile:?]
    	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_265]
    	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_265]
    	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_265]
    	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_265]
    	at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:152) [bundleFile:?]
    	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:52) [bundleFile:?]
    	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_265]
    	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_265]
    2020-12-05 16:14:40.074 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
    2020-12-05 16:14:45.098 [INFO ] [b.handler.EspMilightHubBridgeHandler] - Sucessfully connected to the MQTT broker.
    2020-12-05 16:15:15.444 [INFO ] [b.handler.EspMilightHubBridgeHandler] - Sucessfully subscribed to milight/states/#
    2020-12-05 16:16:15.721 [ERROR] [ding.zigbee.handler.ZigBeeSerialPort] - Serial Error: Port /dev/ttyUSB0 does not exist.
    2020-12-05 16:16:15.729 [ERROR] [ngle.telegesis.ZigBeeDongleTelegesis] - Unable to open Telegesis serial port
    2020-12-05 16:16:15.737 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
    java.lang.NullPointerException: null
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:425) ~[?:?]
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:543) ~[?:?]
    	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_265]
    	at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_265]
    	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_265]
    2020-12-05 16:16:16.755 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
    2020-12-05 16:16:18.641 [INFO ] [ebuilder.internal.HomeBuilderServlet] - Started Home Builder at /homebuilder
    2020-12-05 16:16:19.045 [ERROR] [ding.zigbee.handler.ZigBeeSerialPort] - Serial Error: Port /dev/ttyUSB0 does not exist.
    2020-12-05 16:16:19.052 [ERROR] [ngle.telegesis.ZigBeeDongleTelegesis] - Unable to open Telegesis serial port
    2020-12-05 16:16:19.059 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
    java.lang.NullPointerException: null
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:425) ~[?:?]
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:543) ~[?:?]
    	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_265]
    	at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_265]
    	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_265]
    2020-12-05 16:16:19.479 [INFO ] [.transport.mqtt.MqttBrokerConnection] - Starting MQTT broker connection to 'localhost' with clientid 5001f6fd-ff00-4c43-bbf9-c30398cd50cc
    2020-12-05 16:16:20.796 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 112d340b-df7e-4989-bd87-12f0a8c9013f, base URL = http://localhost:8080)
    2020-12-05 16:16:22.368 [WARN ] [core.thing.internal.ThingManagerImpl] - Initializing handler for thing 'systeminfo:computer:openhab' takes more than 5000ms.
    2020-12-05 16:16:24.083 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
    2020-12-05 16:16:24.719 [ERROR] [ntime.internal.engine.RuleEngineImpl] - Rule 'Switch_C_Absence_SyncToggle': The name 'Switch_C_Absence' cannot be resolved to an item or type; line 53, column 6, length 16
    2020-12-05 16:16:24.726 [ERROR] [ntime.internal.engine.RuleEngineImpl] - Rule 'Switch_C_Absence_SyncToggle': The name 'Switch_C_Absence' cannot be resolved to an item or type; line 53, column 6, length 16
    2020-12-05 16:16:24.726 [ERROR] [ntime.internal.engine.RuleEngineImpl] - Rule 'Switch_C_Absence_SyncToggle': The name 'Switch_C_Absence' cannot be resolved to an item or type; line 53, column 6, length 16
    2020-12-05 16:16:25.199 [INFO ] [openhab.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
    2020-12-05 16:16:25.484 [ERROR] [ding.zigbee.handler.ZigBeeSerialPort] - Serial Error: Port /dev/ttyUSB0 does not exist.
    2020-12-05 16:16:25.486 [ERROR] [ngle.telegesis.ZigBeeDongleTelegesis] - Unable to open Telegesis serial port
    2020-12-05 16:16:25.490 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
    java.lang.NullPointerException: null
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:425) ~[?:?]
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:543) ~[?:?]
    	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_265]
    	at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_265]
    	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_265]
    2020-12-05 16:16:27.308 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
    2020-12-05 16:16:27.840 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'darksky:weather-and-forecast:api:local' to inbox.
    2020-12-05 16:16:28.188 [ERROR] [core.karaf.internal.FeatureInstaller] - Failed installing 'openhab-binding-exec, openhab-ui-homebuilder, openhab-misc-openhabcloud, openhab-binding-tplinksmarthome, openhab-binding-darksky, openhab-transformation-regex, openhab-ui-habpanel, openhab-binding-lifx, openhab-transformation-jsonpath, openhab-binding-zigbee, openhab-ui-restdocs, openhab-binding-mqtt, openhab-ui-basic, openhab-binding-systeminfo, openhab-ui-paper': Error restarting bundles:
    	Exception in org.eclipse.smarthome.io.rest.sse.internal.SseActivator.start() of bundle org.openhab.core.io.rest.sse.
    2020-12-05 16:16:28.376 [ERROR] [ntime.internal.engine.RuleEngineImpl] - Rule 'Sensor_OpenHabPi_CpuUtilization': The name 'Sensor_OpenHabPi_CpuUtilization' cannot be resolved to an item or type; line 5, column 2, length 31
    2020-12-05 16:16:30.495 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
    2020-12-05 16:16:35.699 [ERROR] [ding.zigbee.internal.ZigBeeDataStore] - 00158D00013DC7B6: Error reading network state: 
    com.thoughtworks.xstream.io.StreamException:  : ParseError at [row,col]:[1,1]
    Message: Premature end of file.
    	at com.thoughtworks.xstream.io.xml.StaxReader.pullNextEvent(StaxReader.java:73) ~[?:?]
    	at com.thoughtworks.xstream.io.xml.AbstractPullReader.readRealEvent(AbstractPullReader.java:148) ~[?:?]
    	at com.thoughtworks.xstream.io.xml.AbstractPullReader.readEvent(AbstractPullReader.java:141) ~[?:?]
    	at com.thoughtworks.xstream.io.xml.AbstractPullReader.move(AbstractPullReader.java:118) ~[?:?]
    	at com.thoughtworks.xstream.io.xml.AbstractPullReader.moveDown(AbstractPullReader.java:103) ~[?:?]
    	at com.thoughtworks.xstream.io.xml.StaxReader.(StaxReader.java:45) ~[?:?]
    	at com.thoughtworks.xstream.io.xml.StaxDriver.createStaxReader(StaxDriver.java:173) ~[?:?]
    	at com.thoughtworks.xstream.io.xml.StaxDriver.createReader(StaxDriver.java:92) ~[?:?]
    	at com.thoughtworks.xstream.XStream.fromXML(XStream.java:1040) ~[?:?]
    	at org.openhab.binding.zigbee.internal.ZigBeeDataStore.readNode(ZigBeeDataStore.java:132) ~[?:?]
    	at com.zsmartsystems.zigbee.database.ZigBeeNetworkDatabaseManager.startup(ZigBeeNetworkDatabaseManager.java:193) ~[?:?]
    	at com.zsmartsystems.zigbee.ZigBeeNetworkManager.initialize(ZigBeeNetworkManager.java:379) ~[?:?]
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:415) ~[?:?]
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:543) ~[?:?]
    	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:1.8.0_265]
    	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_265]
    	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_265]
    Caused by: javax.xml.stream.XMLStreamException: ParseError at [row,col]:[1,1]
    Message: Premature end of file.
    	at com.sun.org.apache.xerces.internal.impl.XMLStreamReaderImpl.next(XMLStreamReaderImpl.java:604) ~[?:1.8.0_265]
    	at com.thoughtworks.xstream.io.xml.StaxReader.pullNextEvent(StaxReader.java:58) ~[?:?]
    	... 20 more
    2020-12-05 16:16:36.118 [ERROR] [ding.zigbee.handler.ZigBeeSerialPort] - Serial Error: Port /dev/ttyUSB0 does not exist.
    2020-12-05 16:16:36.120 [ERROR] [ngle.telegesis.ZigBeeDongleTelegesis] - Unable to open Telegesis serial port
    2020-12-05 16:16:36.123 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
    java.lang.NullPointerException: null
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:425) ~[?:?]
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:543) ~[?:?]
    	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_265]
    	at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_265]
    	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_265]
    2020-12-05 16:16:41.126 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
    2020-12-05 16:16:42.303 [ERROR] [ding.zigbee.handler.ZigBeeSerialPort] - Serial Error: Port /dev/ttyUSB0 does not exist.
    2020-12-05 16:16:42.305 [ERROR] [ngle.telegesis.ZigBeeDongleTelegesis] - Unable to open Telegesis serial port
    2020-12-05 16:16:42.307 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
    java.lang.NullPointerException: null
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:425) ~[?:?]
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:543) ~[?:?]
    	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_265]
    	at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_265]
    	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_265]
    2020-12-05 16:16:47.313 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
    2020-12-05 16:16:48.548 [ERROR] [ding.zigbee.handler.ZigBeeSerialPort] - Serial Error: Port /dev/ttyUSB0 does not exist.
    2020-12-05 16:16:48.550 [ERROR] [ngle.telegesis.ZigBeeDongleTelegesis] - Unable to open Telegesis serial port
    2020-12-05 16:16:48.552 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
    java.lang.NullPointerException: null
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:425) ~[?:?]
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:543) ~[?:?]
    	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_265]
    	at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_265]
    	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_265]
    2020-12-05 16:16:53.559 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
    2020-12-05 16:16:55.329 [ERROR] [ding.zigbee.handler.ZigBeeSerialPort] - Serial Error: Port /dev/ttyUSB0 does not exist.
    2020-12-05 16:16:55.331 [ERROR] [ngle.telegesis.ZigBeeDongleTelegesis] - Unable to open Telegesis serial port
    2020-12-05 16:16:55.333 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
    java.lang.NullPointerException: null
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:425) ~[?:?]
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:543) ~[?:?]
    	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_265]
    	at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_265]
    	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_265]
    2020-12-05 16:17:00.051 [ERROR] [ntime.internal.engine.RuleEngineImpl] - Rule 'Sensor_OpenHabPi_CpuUtilization': The name 'Sensor_OpenHabPi_CpuUtilization' cannot be resolved to an item or type; line 5, column 2, length 31
    2020-12-05 16:17:00.336 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
    2020-12-05 16:17:01.567 [ERROR] [ding.zigbee.handler.ZigBeeSerialPort] - Serial Error: Port /dev/ttyUSB0 does not exist.
    2020-12-05 16:17:04.833 [ERROR] [ngle.telegesis.ZigBeeDongleTelegesis] - Unable to open Telegesis serial port
    2020-12-05 16:17:04.835 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
    java.lang.NullPointerException: null
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.initialiseZigBee(ZigBeeCoordinatorHandler.java:425) ~[?:?]
    	at org.openhab.binding.zigbee.handler.ZigBeeCoordinatorHandler.lambda$2(ZigBeeCoordinatorHandler.java:543) ~[?:?]
    	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_265]
    	at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_265]
    	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_265]
    	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_265]
    	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_265]
    2020-12-05 16:17:09.843 [INFO ] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee dongle inactivity timer. Reinitializing ZigBee
    2020-12-05 16:17:13.989 [ERROR] [ding.zigbee.handler.ZigBeeSerialPort] - Serial Error: Port /dev/ttyUSB0 does not exist.
    2020-12-05 16:17:13.993 [ERROR] [ngle.telegesis.ZigBeeDongleTelegesis] - Unable to open Telegesis serial port
    2020-12-05 16:17:13.996 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
    java.lang.NullPointerException: null
    

This time a random subset of my items has been initialized correctly and some of the toggles in the Basic UI are even working again, thanks!!! Still, I am facing a lot of issues: some items are not updated; every time I press a button in the Basic UI, it says “Offline: waiting for connection to become available” (but the button might work); and then the drive defects mentioned in my last post and below.

Did you check that the configuration files are present in /etc/openhab2?

Yes, I did and they are fine.


BTW: I just tried to sudo apt-get install tree in order to share my config folder structure with you. But when executing this command, I just got the next error, which I have never seen before:

Reading package lists... Error!
E: Encountered a section with no Package: header
E: Problem with MergeList /var/lib/apt/lists/raspbian.raspberrypi.org_raspbian_dists_buster_main_binary-armhf_Packages
E: The package lists or status file could not be parsed or opened.

This looks very much like severe damages to my SD card, doesn’t it?

Taking all your observations into account I think this would a plausible explanation.

As your configuration files are in a repository, your system should be up and running quite fast.
If you have a backup of your OH installation that you know is still “good”, it may even be a better way to restore that on a new SD card as it brings back configurations that are not in your repo (you mentioned things done via GUI as an example).

1 Like