Preparing the openHAB 2.2 release


(Igor) #61

No - just 10 minutes after upgrade went :slight_smile:


(Paul Muldoon) #62

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

(Mark) #63

@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) #64

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) #65

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 Kreuzer) #66

@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).


MQTT Binding not working with 2.2
(Juelicher) #67

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…


(Thorbjørn Skistad) #68

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.


(Martin van Wingerden) #69

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


(Thorbjørn Skistad) #70

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


(Juelicher) #71

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…


(Simon Merschjohann) #72

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.?


(Martin van Wingerden) #73

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


(Simon Merschjohann) #74

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


(Simon Merschjohann) #75

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


(Victor) #76

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'.


(Ben Clark) #77

Could you check the bottom of the following command:

sudo journalctl -u openhab2 -b

(Victor) #78

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'.


(Victor) #79

Is this sufficient? Any other info needed?

Start feeling like a fresh install is needed :frowning:


(Ben Clark) #80

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

What is your java -version?