Preparing the openHAB 2.2 release

ip-ua · December 16, 2017, 10:07am

No - just 10 minutes after upgrade went

ptmuldoon · December 16, 2017, 12:11pm

I’m on build 1140 and about to upgrade again,

On a 1140 yesterday, on the OH2 start, things looks Perfect. No errors, very nice clean log, But now this morning, I see some errors in the logs as follows.

Something do with Sitemaps? I don’t have anything named /home/0000 or home /00001

2017-12-16 05:47:38.979 [WARN ] [org.eclipse.jetty.server.HttpChannel] - //192.168.11.123:8080/rest/sitemaps/home/0000
javax.servlet.ServletException: javax.servlet.ServletException: A MultiException has 1 exceptions.  They are:
1. java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_865,866,1156953406) has been shut down

	at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:88) [191:org.ops4j.pax.web.pax-web-jetty:6.0.7]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [87:org.eclipse.jetty.server:9.3.22.v20171030]
	at org.eclipse.jetty.server.Server.handle(Server.java:534) [87:org.eclipse.jetty.server:9.3.22.v20171030]
	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:333) [87:org.eclipse.jetty.server:9.3.22.v20171030]
	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [87:org.eclipse.jetty.server:9.3.22.v20171030]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) [79:org.eclipse.jetty.io:9.3.22.v20171030]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108) [79:org.eclipse.jetty.io:9.3.22.v20171030]
	at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [79:org.eclipse.jetty.io:9.3.22.v20171030]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [90:org.eclipse.jetty.util:9.3.22.v20171030]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [90:org.eclipse.jetty.util:9.3.22.v20171030]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [90:org.eclipse.jetty.util:9.3.22.v20171030]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [90:org.eclipse.jetty.util:9.3.22.v20171030]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [90:org.eclipse.jetty.util:9.3.22.v20171030]
	at java.lang.Thread.run(Thread.java:748) [?:?]
Caused by: javax.servlet.ServletException: A MultiException has 1 exceptions.  They are:
1. java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_865,866,1156953406) has been shut down

	at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:489) ~[?:?]
	at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) ~[?:?]
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) ~[?:?]
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) ~[?:?]
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) ~[?:?]
	at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) ~[?:?]
	at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:848) ~[?:?]
	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:584) ~[?:?]
	at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) ~[?:?]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) ~[?:?]
	at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) ~[?:?]
	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226) ~[?:?]
	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) ~[?:?]
	at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:284) ~[?:?]
	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) ~[?:?]
	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) ~[?:?]
	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) ~[?:?]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[?:?]
	at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) ~[?:?]
	... 13 more
Caused by: org.glassfish.hk2.api.MultiException: A MultiException has 1 exceptions.  They are:
1. java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_865,866,1156953406) has been shut down

	at org.jvnet.hk2.internal.FactoryCreator.getFactoryHandle(FactoryCreator.java:106) ~[?:?]
	at org.jvnet.hk2.internal.FactoryCreator.dispose(FactoryCreator.java:173) ~[?:?]
	at org.jvnet.hk2.internal.SystemDescriptor.dispose(SystemDescriptor.java:526) ~[?:?]
	at org.glassfish.jersey.process.internal.RequestScope$Instance.remove(RequestScope.java:532) ~[?:?]
	at org.glassfish.jersey.process.internal.RequestScope$Instance.release(RequestScope.java:549) ~[?:?]
	at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:319) ~[?:?]
	at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) ~[?:?]
	at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) ~[?:?]
	at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) ~[?:?]
	at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) ~[?:?]
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) ~[?:?]
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) ~[?:?]
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) ~[?:?]
	at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) ~[?:?]
	at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:848) ~[?:?]
	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:584) ~[?:?]
	at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) ~[?:?]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) ~[?:?]
	at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) ~[?:?]
	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226) ~[?:?]
	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) ~[?:?]
	at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:284) ~[?:?]
	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) ~[?:?]
	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) ~[?:?]
	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) ~[?:?]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[?:?]
	at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) ~[?:?]
	... 13 more
Caused by: java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_865,866,1156953406) has been shut down
	at org.jvnet.hk2.internal.ServiceLocatorImpl.checkState(ServiceLocatorImpl.java:2288) ~[?:?]
	at org.jvnet.hk2.internal.ServiceLocatorImpl.getServiceHandleImpl(ServiceLocatorImpl.java:629) ~[?:?]
	at org.jvnet.hk2.internal.ServiceLocatorImpl.getServiceHandle(ServiceLocatorImpl.java:622) ~[?:?]
	at org.jvnet.hk2.internal.ServiceLocatorImpl.getServiceHandle(ServiceLocatorImpl.java:640) ~[?:?]
	at org.jvnet.hk2.internal.FactoryCreator.getFactoryHandle(FactoryCreator.java:103) ~[?:?]
	at org.jvnet.hk2.internal.FactoryCreator.dispose(FactoryCreator.java:173) ~[?:?]
	at org.jvnet.hk2.internal.SystemDescriptor.dispose(SystemDescriptor.java:526) ~[?:?]
	at org.glassfish.jersey.process.internal.RequestScope$Instance.remove(RequestScope.java:532) ~[?:?]
	at org.glassfish.jersey.process.internal.RequestScope$Instance.release(RequestScope.java:549) ~[?:?]
	at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:319) ~[?:?]
	at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) ~[?:?]
	at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) ~[?:?]
	at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) ~[?:?]
	at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) ~[?:?]
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) ~[?:?]
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) ~[?:?]
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) ~[?:?]
	at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) ~[?:?]
	at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:848) ~[?:?]
	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:584) ~[?:?]
	at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) ~[?:?]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) ~[?:?]
	at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) ~[?:?]
	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226) ~[?:?]
	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) ~[?:?]
	at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:284) ~[?:?]
	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) ~[?:?]
	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) ~[?:?]
	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) ~[?:?]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[?:?]
	at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) ~[?:?]
	... 13 more
2017-12-16 05:47:39.014 [WARN ] [org.eclipse.jetty.server.HttpChannel] - //192.168.11.123:8080/rest/sitemaps/home/00000001
javax.servlet.ServletException: javax.servlet.ServletException: A MultiException has 1 exceptions.  They are:
1. java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_865,866,1156953406) has been shut down

	at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:88) [191:org.ops4j.pax.web.pax-web-jetty:6.0.7]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [87:org.eclipse.jetty.server:9.3.22.v20171030]
	at org.eclipse.jetty.server.Server.handle(Server.java:534) [87:org.eclipse.jetty.server:9.3.22.v20171030]
	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:333) [87:org.eclipse.jetty.server:9.3.22.v20171030]
	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [87:org.eclipse.jetty.server:9.3.22.v20171030]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) [79:org.eclipse.jetty.io:9.3.22.v20171030]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108) [79:org.eclipse.jetty.io:9.3.22.v20171030]
	at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [79:org.eclipse.jetty.io:9.3.22.v20171030]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [90:org.eclipse.jetty.util:9.3.22.v20171030]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [90:org.eclipse.jetty.util:9.3.22.v20171030]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [90:org.eclipse.jetty.util:9.3.22.v20171030]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [90:org.eclipse.jetty.util:9.3.22.v20171030]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [90:org.eclipse.jetty.util:9.3.22.v20171030]
	at java.lang.Thread.run(Thread.java:748) [?:?]
Caused by: javax.servlet.ServletException: A MultiException has 1 exceptions.  They are:
1. java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_865,866,1156953406) has been shut down

mhilbush · December 16, 2017, 1:10pm

@kai Can you take look at my latest post on this issue. https://github.com/eclipse/smarthome/issues/4751

I’m trying to determine if the behavior I’m seeing is related to the issue, or if it’s something different.

juelicher · December 16, 2017, 3:46pm

It would be great, if the changes to the KNX binding from August, that prevent the resending of received telegrams would be part of the 2.2 release.

Same thing for the changes to the homekit addon, that prevent loosing the room assignment of items after the items configuration has been changed.

I looked, but there seems to be no commit in the repository for either of the changes. Booth changes address (in my view) major issues.

If it helps: I am using “org.openhab.binding.knx-1.11.0-20170827” and “org.openhab.io.homekit-2.2.0-20171103”, booth provided somewhere in this forum, without any issues.

Moxified · December 16, 2017, 4:13pm

Probably worth mention this issue: https://github.com/openhab/openhab1-addons/issues/5255

It causes changedsince rules to fail on datetime items because their time is always midnight when using jdbc persistence.

Kai · December 16, 2017, 8:55pm

@juelicher If there are PRs available for fixes, discuss the progress directly there and make sure to have them reviewed, approved and tested ahead of time. Our deadline is tonight, so there are just 2 hours left now, so I don’t see much chance for the mentioned issues. But I’d hope we will be able to create a 2.2.1 patch release in a month or two, so you might want to get them in there.

@Moxified If there isn’t even anybody working on a fix for that issue yet, that looks even worse than @juelicher’s issues… Please follow up on the issue and find someone to fix it (if you can’t do it yourself).

juelicher · December 16, 2017, 9:48pm

At least for the KNX Binding there is a pull request from August, but I am not the author, so I don‘t really see what I could do about it…

Thorbjorn_Skistad · December 16, 2017, 9:56pm

Kai just want to say, screw the deadlines: The open-source way usually is: It’s ready when it’s ready. That’s the way it’s always been, and that’s okay, cause then I can trust it. Releasing before it’s ready is the Microsoft way… and we all know how stable that is.

I’ll rather wait a few more days, then a month or two for a patch release.

martinvw · December 16, 2017, 10:07pm

Yes, but it won’t help with juts two extra days when no pull request was yet created for an issue reported in august

Thorbjorn_Skistad · December 17, 2017, 8:26am

Very true. There’s always a limit too how long one can wait for others to fix stuff.

juelicher · December 17, 2017, 4:12pm

But for the KNX issues pull requests where created in August:
https://github.com/openhab/openhab1-addons/pull/5277
https://github.com/openhab/openhab1-addons/pull/5288

Why there is no pull request for the HomeKit binding, i do not know. The issue is discussed here:
https://github.com/openhab/openhab2-addons/issues/929

I know, that openHAB is an OpenSource project and a to know, that it is unfair to criticize when I myself, not being a Java developer, do not contribute code. But it is quite frustrating, that bug fixes for solved problems do not make it into the releases even month after there has been a solution…

smerschjo · December 18, 2017, 11:22am

I just tried snapshot #1141 but I cannot download any addons from the marketplace, the log says:

2017-12-18 11:16:41.847 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'other.items'
2017-12-18 11:16:54.472 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app
2017-12-18 11:16:54.526 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2017-12-18 11:17:05.677 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Started HABmin servlet at /habmin
2017-12-18 11:17:05.723 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2017-12-18 11:17:05.833 [INFO ] [assic.internal.servlet.WebAppServlet] - Started Classic UI at /classicui/app
2017-12-18 11:17:24.472 [ERROR] [core.karaf.internal.FeatureInstaller] - Failed installing 'openhab-misc-ruleengine': Error:
	Error downloading mvn:org.eclipse.smarthome.automation/org.eclipse.smarthome.automation.api/0.10.0.b1
2017-12-18 11:17:39.221 [ERROR] [core.karaf.internal.FeatureInstaller] - Failed installing 'openhab-misc-ruleengine': Error:
	Error downloading mvn:org.eclipse.smarthome.automation/org.eclipse.smarthome.automation.api/0.10.0.b1
2017-12-18 11:18:39.615 [ERROR] [core.karaf.internal.FeatureInstaller] - Failed installing 'openhab-misc-ruleengine': Error:
	Error downloading mvn:org.eclipse.smarthome.automation/org.eclipse.smarthome.automation.api/0.10.0.b1

There are no such jars in the maven repository, do I have to add any custom addition to download the jars from jfrog etc.?

martinvw · December 18, 2017, 11:31am

I also could not download the 2.2.0-snapshot anymore maybe its related to that.

I just installed the new release and can install marketplace bindings and the rule engine (note that the rule engine is not a real marketplace binding)

cc @Kai

smerschjo · December 18, 2017, 11:51am

ah, I didn’t see that the release is already out. I will retry it with that version

smerschjo · December 18, 2017, 12:17pm

still the same issue, I cannot install the rule engine from the marketplace

Charging_Stove · December 18, 2017, 2:14pm

Updated via openhabian-config.sh

Now OH2.2 fails to start:

[15:10:40] openhab@orangepione:~$ sudo systemctl status openhab2.service
● openhab2.service - openHAB 2 - empowering the smart home
   Loaded: loaded (/usr/lib/systemd/system/openhab2.service; enabled; vendor preset: enabled)
   Active: activating (auto-restart) (Result: exit-code) since Mon 2017-12-18 15:10:48 CET; 305ms ago
     Docs: http://docs.openhab.org
           https://community.openhab.org
  Process: 30182 ExecStop=/usr/share/openhab2/runtime/bin/karaf stop (code=exited, status=1/FAILURE)
  Process: 30093 ExecStart=/usr/share/openhab2/runtime/bin/karaf $OPENHAB_STARTMODE (code=exited, status=1/FAILURE)
 Main PID: 30093 (code=exited, status=1/FAILURE)

Dec 18 15:10:48 orangepione systemd[1]: openhab2.service: Unit entered failed state.
Dec 18 15:10:48 orangepione systemd[1]: openhab2.service: Failed with result 'exit-code'.

Benjy · December 18, 2017, 2:16pm

Could you check the bottom of the following command:

sudo journalctl -u openhab2 -b

Charging_Stove · December 18, 2017, 2:20pm

This belongs to last service restart attempt.

Dec 18 15:19:05 orangepione systemd[1]: openhab2.service: Service hold-off time over, scheduling restart.
Dec 18 15:19:05 orangepione systemd[1]: Stopped openHAB 2 - empowering the smart home.
Dec 18 15:19:05 orangepione systemd[1]: Started openHAB 2 - empowering the smart home.
Dec 18 15:19:05 orangepione karaf[11395]: /usr/share/openhab2/runtime/bin/karaf: 199: [: Illegal number:
Dec 18 15:19:05 orangepione karaf[11395]: /usr/share/openhab2/runtime/bin/karaf: 199: [: Illegal number:
Dec 18 15:19:05 orangepione systemd[1]: openhab2.service: Main process exited, code=exited, status=1/FAILURE
Dec 18 15:19:06 orangepione karaf[11484]: /usr/share/openhab2/runtime/bin/karaf: 199: [: Illegal number:
Dec 18 15:19:06 orangepione karaf[11484]: /usr/share/openhab2/runtime/bin/karaf: 199: [: Illegal number:
Dec 18 15:19:06 orangepione systemd[1]: openhab2.service: Control process exited, code=exited status=1
Dec 18 15:19:06 orangepione systemd[1]: openhab2.service: Unit entered failed state.
Dec 18 15:19:06 orangepione systemd[1]: openhab2.service: Failed with result 'exit-code'.

Charging_Stove · December 18, 2017, 2:33pm

Is this sufficient? Any other info needed?

Start feeling like a fresh install is needed

Benjy · December 18, 2017, 3:10pm

This is usually caused by an incorrectly configured Java, did it update with openHAB?

What is your java -version?