[SOLVED] OH 2.5 M1 to M3 update - encountered issues

Tags: #<Tag:0x00007fc3f0fdfed8>
  • Platform information:
    • Hardware: RPi 3
    • openHAB version:OH 2.5.0~M3-1
  • Issue of the topic: issues after update from 2.5 M1 to M3

Good evening, after “playing around” with OH over the last few months I updated today from 2.5 M1 to M3 and now encounter several mainly “no shows” in Basic UI.
From the Astro Binding several Sun and Moon related data is not showing anymore (Azimuth, Elevation, Radiation, Phase age and Moon distance) and some of the power plug measurement data graphs are not being updated either although the log shows the changes so I believe the interaction with the persistence rrd4j is not working correctly anymore.

Although I would appreciate (and finally need) any help I can get to fix this I was wondering what I should have done differently in the first place to avoid this situation like preparing back-up (I do have of the files I created) reading any change log of the versions before the update (where would these be found?) and the log file itself after the restart.
Did I miss the “How to update the system - guide”?
What would be the right balance between “never change a running system” and “apply updates soon to resolve any found and resolved issue”?

In case any answer to any of the above matters is already available I would appreciate to be pointed in the right direction.

Log entries in code fences are always welcomed. :wink:

I’ll try

2019-09-09 23:56:41.098 [ERROR] [core.karaf.internal.FeatureInstaller] - Failed installing 'openhab-action-mail, openhab-misc-restdocs'
2019-09-09 23:56:51.347 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve item for widget org.eclipse.smarthome.model.sitemap.Text
2019-09-09 23:56:51.352 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve item 'Azimuth' for widget org.eclipse.smarthome.model.sitemap.Text
2019-09-09 23:56:51.367 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve item 'Elevation' for widget org.eclipse.smarthome.model.sitemap.Text
2019-09-09 23:56:51.379 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve item 'Total_Radiation' for widget org.eclipse.smarthome.model.sitemap.Text
2019-09-09 23:56:51.389 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve item 'Diffuse_Radiation' for widget org.eclipse.smarthome.model.sitemap.Text
2019-09-09 23:56:51.398 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve visibility item Total_Radiation for widget org.eclipse.smarthome.model.sitemap.Text
2019-09-09 23:56:51.400 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve item 'Total_Radiation' for widget org.eclipse.smarthome.model.sitemap.Text
2019-09-09 23:56:51.425 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve item 'Moon_Age' for widget org.eclipse.smarthome.model.sitemap.Text
2019-09-09 23:56:51.440 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve item 'Moon_Distance' for widget org.eclipse.smarthome.model.sitemap.Text


2019-09-09 23:56:51.493 [WARN ] [basic.internal.render.SwitchRenderer] - Cannot determine item type of 'gChartInterval'
org.eclipse.smarthome.core.items.ItemNotFoundException: Item 'gChartInterval' could not be found in the item registry


	at org.eclipse.smarthome.core.internal.items.ItemRegistryImpl.getItem(ItemRegistryImpl.java:82) ~[?:?]

	at org.eclipse.smarthome.ui.internal.items.ItemUIRegistryImpl.getItem(ItemUIRegistryImpl.java:835) ~[?:?]


	at org.openhab.ui.basic.internal.render.SwitchRenderer.renderWidget(SwitchRenderer.java:85) [247:org.openhab.ui.basic:2.5.0.M3]

	at org.openhab.ui.basic.internal.render.PageRenderer.renderWidget(PageRenderer.java:194) [247:org.openhab.ui.basic:2.5.0.M3]

	at org.openhab.ui.basic.internal.render.PageRenderer.processChildren(PageRenderer.java:160) [247:org.openhab.ui.basic:2.5.0.M3]

	at org.openhab.ui.basic.internal.render.PageRenderer.processChildren(PageRenderer.java:181) [247:org.openhab.ui.basic:2.5.0.M3]

	at org.openhab.ui.basic.internal.render.PageRenderer.processPage(PageRenderer.java:124) [247:org.openhab.ui.basic:2.5.0.M3]

	at org.openhab.ui.basic.internal.servlet.WebAppServlet.service(WebAppServlet.java:189) [247:org.openhab.ui.basic:2.5.0.M3]

	at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) [31:javax.servlet-api:3.1.0]

	at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:873) [90:org.eclipse.jetty.servlet:9.4.18.v20190429]

	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:542) [90:org.eclipse.jetty.servlet:9.4.18.v20190429]

	at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [193:org.ops4j.pax.web.pax-web-jetty:7.2.10]

	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:146) [89:org.eclipse.jetty.server:9.4.18.v20190429]

	at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) [87:org.eclipse.jetty.security:9.4.18.v20190429]

	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [89:org.eclipse.jetty.server:9.4.18.v20190429]

	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:257) [89:org.eclipse.jetty.server:9.4.18.v20190429]

	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1700) [89:org.eclipse.jetty.server:9.4.18.v20190429]

	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [89:org.eclipse.jetty.server:9.4.18.v20190429]

	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345) [89:org.eclipse.jetty.server:9.4.18.v20190429]

	at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:293) [193:org.ops4j.pax.web.pax-web-jetty:7.2.10]

	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) [89:org.eclipse.jetty.server:9.4.18.v20190429]

	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480) [90:org.eclipse.jetty.servlet:9.4.18.v20190429]

	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1667) [89:org.eclipse.jetty.server:9.4.18.v20190429]

	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) [89:org.eclipse.jetty.server:9.4.18.v20190429]

	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247) [89:org.eclipse.jetty.server:9.4.18.v20190429]

	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) [89:org.eclipse.jetty.server:9.4.18.v20190429]

	at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [193:org.ops4j.pax.web.pax-web-jetty:7.2.10]

	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [89:org.eclipse.jetty.server:9.4.18.v20190429]

	at org.eclipse.jetty.server.Server.handle(Server.java:505) [89:org.eclipse.jetty.server:9.4.18.v20190429]

	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:370) [89:org.eclipse.jetty.server:9.4.18.v20190429]

	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:267) [89:org.eclipse.jetty.server:9.4.18.v20190429]

	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305) [80:org.eclipse.jetty.io:9.4.18.v20190429]

	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) [80:org.eclipse.jetty.io:9.4.18.v20190429]

	at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117) [80:org.eclipse.jetty.io:9.4.18.v20190429]

	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) [92:org.eclipse.jetty.util:9.4.18.v20190429]

	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) [92:org.eclipse.jetty.util:9.4.18.v20190429]

	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [92:org.eclipse.jetty.util:9.4.18.v20190429]

	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) [92:org.eclipse.jetty.util:9.4.18.v20190429]

	at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) [92:org.eclipse.jetty.util:9.4.18.v20190429]

	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:698) [92:org.eclipse.jetty.util:9.4.18.v20190429]

	at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:804) [92:org.eclipse.jetty.util:9.4.18.v20190429]

	at java.lang.Thread.run(Thread.java:748) [?:?]

2019-09-09 23:56:51.508 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve item 'gChartInterval' for widget org.eclipse.smarthome.model.sitemap.Switch

2019-09-09 23:56:51.510 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve item for widget org.eclipse.smarthome.model.sitemap.Switch

2019-09-09 23:56:51.512 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve item 'gChartInterval' for widget org.eclipse.smarthome.model.sitemap.Switch

2019-09-09 23:56:51.515 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve item 'gChartInterval' for widget org.eclipse.smarthome.model.sitemap.Switch

2019-09-09 23:56:51.517 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve item 'gChartInterval' for widget org.eclipse.smarthome.model.sitemap.Switch

2019-09-09 23:56:51.520 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve visibility item gChartInterval for widget org.eclipse.smarthome.model.sitemap.Chart

2019-09-09 23:56:51.522 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve visibility item gChartInterval for widget org.eclipse.smarthome.model.sitemap.Chart

2019-09-09 23:56:51.525 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve visibility item gChartInterval for widget org.eclipse.smarthome.model.sitemap.Chart

2019-09-09 23:56:51.527 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve visibility item gChartInterval for widget org.eclipse.smarthome.model.sitemap.Chart

2019-09-09 23:56:51.529 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve visibility item gChartInterval for widget org.eclipse.smarthome.model.sitemap.Chart

2019-09-09 23:56:51.532 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve visibility item gChartInterval for widget org.eclipse.smarthome.model.sitemap.Chart

2019-09-09 23:56:51.534 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve visibility item gChartInterval for widget org.eclipse.smarthome.model.sitemap.Chart

2019-09-09 23:56:51.536 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve visibility item gChartInterval for widget org.eclipse.smarthome.model.sitemap.Chart

2019-09-09 23:56:51.539 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve visibility item gChartInterval for widget org.eclipse.smarthome.model.sitemap.Chart

2019-09-09 23:56:51.541 [ERROR] [ui.internal.items.ItemUIRegistryImpl] - Cannot retrieve visibility item gChartInterval for widget org.eclipse.smarthome.model.sitemap.Chart

You read the warnings, right? The restdocs moved in 2.5M2 It is now under ui

Nobody will take time to read all THAT without code fences!!

thanks for the comments and links
I will do some homework now

1 Like

These entries are saying that you have sitemap entries for Items that cannot be found in the system.
You may have created them with xxx.items files or with PaperUI, so where to look for why the Items are missing rather depends.
openhab.log should show you if there are troubles loading xxx.items files at boot or edit time.

thanks for your reply
I created these items via files and under M1 they were just working fine

Hey Thorsten
You are doing great so far, no worries, we’ll get things sorted. Were all those errors encountered when you first fired up OpenHAB after the upgrade? Sometimes things start in a funny order and OpenHAB needs to run for a bit to stablize or even sometimes get restarted to get it together and run. Have you tried restarting OpenHAB once or twice?

Hi Thorsten,
I saw the same errors after upgrading to M3. In my case it was enough to save my .items files again (without making any changes) and everything was ok after that.

Perhaps the system started up out of sequence. A restart of OH may have resolved this too.

thanks for all the comments
I did start the system several times and also deleted the cache but all this did not seem to resolve the issue
the error messages remained and I started looking at the issues one at a time starting with mail and restdocs
the errors remained although I tried to install and uninstall them via Paper UI
then I edited the addons.cfg file and although the Rest UI appeared Paper and Basic UI were not available anymore
the same with mail under binding, suddenly mqtt did not work anymore (which was not in the addons.cfg file)
so I went and added all addons that I presently use in the config file
in addition I left the system running for some time
and now it seems to work again

Upgraded to M3 from M1 and now same symptom as above, embedded MQTT broker is showing configuration error. Also the Generic MQTT Thing is in ERROR.
Why do I need to edit addons.cfg manually. All was working great before the update.
How do I make it work again without errors?

2019-09-14 21:48:50.083 [ERROR] [org.openhab.io.mqttembeddedbroker ] - bundle org.openhab.io.mqttembeddedbroker:2.5.0.M3 (293)[org.openhab.io.mqttembeddedbroker.internal.EmbeddedBrokerService(343)] : The activate method has thrown an exception

java.lang.IllegalStateException: The file is locked: nio:/var/lib/openhab2/mqttembedded.bin [1.4.199/7]

@Gargam3L0,
unfortunately I do not have any experience with the embedded MQTT broker
but to add to my comments above:
it seems that there are two places with configuration files for addons

  1. addons.cfg (/etc/openhab2/services/addons.cfg)
  2. addons.config (/var/lib/openhab2/config/org/openhab/addons.config) (in principle not to be edited)
    and to on top there is a database where the configuration through PaperUI is saved.

How I do understand the matter is that with the update from M1 to M2 (and M3) some locations were changed (I have been affected by mail and restdocs)

changes to the configuration through Paper UI and addons.cfg need some time to update the running configuration and even restarts might be necessary.

Keep in mind that if you do any configuration in the addons.cfg file this seems to over-wright the Paper UI configuration.

Applying changes might need time and patience

Looking under


you might find more support

Thank you for replying.
Anyway, I found that the fastest and easiest way was to go back to the MQTT v.1x Binding and the good old mosquitto broker. With this combination all is working again.
As a non technical user, I am starting to get really frustrated with OH when claimed easy updates break a working system and there is no guide as to how to make it work again.

Perhaps after 2.5.0 is released you need to move to the stable version. The latest stable version now is 2.4. You are running an unstable version and complaining about the lack of stability.
In my opinion the unstable version here is many times more stable than the Home Assistant official releases.

Hi,

Just to let you know: I changed the path to the persistence file (i.e.: /srv/openhab2-conf/persistence/mqttembedded.bin( of the Embedded System Broker and it came online again.

Kurt