openHAB don't start after 2.5.6-2 (Release Build) update

Setup, Configuration and Use Installation
1
  • Platform information:
    • Hardware: RPI 3
    • openHAB version: 2.5.6-2

Just before that update everything was running smoothly…
Currently, at the startup I get:

2020-07-04 13:04:29.568 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'America/Montreal'.
2020-07-04 13:04:29.615 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Location set to '46.763246599999995,-71.2179116'.
2020-07-04 13:04:29.620 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'fr_CA'.
2020-07-04 13:04:29.622 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Measurement system set to 'SIUnits'.
2020-07-04 13:04:38.345 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'xxxx.items' (note: one line for each items file)
2020-07-04 13:04:52.326 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'xxxx.rules' (note: one line for each rules file)
2020-07-04 13:06:09.826 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2020-07-04 13:06:13.135 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.168.2.25:8080
2020-07-04 13:06:13.139 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.168.2.25:8443
2020-07-04 13:06:16.138 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Started HABmin servlet at /habmin
2020-07-04 13:06:16.472 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2020-07-04 13:06:16.833 [INFO ] [openhab.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui

After that, nothing else happened. I set the log file to ERROR level but I get nothing more.
Where I should look? What could jammed after that update? I’m use to keep my configuration up to date with automated function from Openhabian. First time I get that kind of issue.

update
I put the log file to DEBUG: There is the result i got after everything “stalled”:

2020-07-04 13:26:28.643 [DEBUG] [rg.quartz.core.QuartzSchedulerThread] - batch acquisition of 0 triggers
2020-07-04 13:26:54.509 [DEBUG] [rg.quartz.core.QuartzSchedulerThread] - batch acquisition of 0 triggers
2020-07-04 13:26:55.125 [DEBUG] [org.apache.felix.configadmin        ] getConfiguration(pid=org.openhab.addons)
2020-07-04 13:26:55.126 [DEBUG] [org.apache.felix.configadmin        ] - Found cached configuration org.openhab.addons bound to mvn:org.openhab.core.bundles/org.openhab.core.karaf/2.5.0

After that I get this block repeating indefinitely

2020-07-04 13:27:55.141 [DEBUG] [org.apache.felix.configadmin        ] getConfiguration(pid=org.ops4j.pax.url.mvn, location=null)
2020-07-04 13:27:55.143 [DEBUG] [org.apache.felix.configadmin        ] - Found cached configuration org.ops4j.pax.url.mvn bound to ?
2020-07-04 13:27:55.145 [DEBUG] [org.apache.felix.configadmin        ] - getConfiguration(pid=org.ops4j.pax.url.mvn, location=null)
2020-07-04 13:27:55.147 [DEBUG] [org.apache.felix.configadmin        ] - Found cached configuration org.ops4j.pax.url.mvn bound to ?
2020-07-04 13:28:16.101 [DEBUG] [rg.quartz.core.QuartzSchedulerThread] - batch acquisition of 0 triggers
2020-07-04 13:28:44.263 [DEBUG] [rg.quartz.core.QuartzSchedulerThread] - batch acquisition of 0 triggers
2020-07-04 13:28:55.150 [DEBUG] [org.apache.felix.configadmin        ] - getConfiguration(pid=org.openhab.addons)
2020-07-04 13:28:55.152 [DEBUG] [org.apache.felix.configadmin        ] - Found cached configuration org.openhab.addons bound to mvn:org.openhab.core.bundles/org.openhab.core.karaf/2.5.0

In between the previous bloc, I get sometimes this bloc:

2020-07-04 13:56:48.802 [DEBUG] [org.eclipse.jetty.server.session    ] - org.eclipse.jetty.server.session.SessionHandler25095359==dftMaxIdleSec=600 scavenging sessions
2020-07-04 13:56:48.805 [DEBUG] [org.eclipse.jetty.server.session    ] - org.eclipse.jetty.server.session.SessionHandler25095359==dftMaxIdleSec=600 scavenging session ids []
2020-07-04 13:56:48.808 [DEBUG] [org.eclipse.jetty.server.session    ] - org.eclipse.jetty.server.session.DefaultSessionCache@8d6788[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false] checking expiration on []

That mean absolutely nothing to me. Any help will be appreciated.

2

Did you try a second restart?

3

I tried several restart but same behaviour.

I finally put back my last full backup version 2.5.4-1 and did the update again to 2.5.6-2 from there. This time, it worked. However I loose some functionnality I debugged over the last two weeks.

I don’t know what happen with the first update. Something went wrong.

1 Like
4

Do you use the mail binding by chance?

5

Yes I did. Work correctly. I use it to get some alarm like low spa water temperature (useful during winter to avoid freezing) for exemple.

6

Interesting. I had a similar issue and the mail binding turned out to be holding up the start cycle.

7

Do you configure the mail binding by the way of paperUI?