My Openhab - Jetty Request Failed: null

Tags: #<Tag:0x00007fe73683b5b0> #<Tag:0x00007fe73683b498>

Hi,

after an update of OH2-online I lost all my DB devices (again) and had to reinstall and re-configure everything.
Now, when I use the OH iOS App (only via My Openhab) I am seeing the following error in the logs, everytime I refresh the app or switch frames:

15:26:57.522 [WARN ] [o.myopenhab.internal.MyOpenHABClient] - Jetty request 49828 failed: null
15:26:59.176 [WARN ] [o.myopenhab.internal.MyOpenHABClient] - Jetty request 49841 failed: null
15:26:59.807 [WARN ] [o.myopenhab.internal.MyOpenHABClient] - Jetty request 49848 failed: null
15:27:01.221 [WARN ] [o.myopenhab.internal.MyOpenHABClient] - Jetty request 49861 failed: null

I don’t know where the error comes from.
Any idea how to debug this? Seems like everything works fine, but I would like to identify the problem.

Thanks a lot in advance!

I can confirm this. In remote mode of the iOS app I also do get this messages in my log. As I switched over to OH2 just recently, I don’t know if this is a new error since one of the latest snapshots.

I’ve created an issue for this:

1 Like

Seems to be a more general problem: I have those WARN messages when remotely connecting via Android App:

2017-01-06 11:46:02.664 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 89017 failed: null
2017-01-06 11:46:08.836 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 89025 failed: null
2017-01-06 11:46:11.089 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 89021 failed: null
2017-01-06 11:46:11.520 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 89035 failed: null

I have seen those messages since the first day of changing from my.openhab to openhab cloud connector, currently on build #684

1 Like

I have the same problem when trying to use voice control from android app :frowning:

Same for me using HABdroid.

This is still an “issue”. Really just ignore these messages? Nobody feels “responsible”?

PS:
Using iOS when getting these messages. But they also pop up when using webinterface (BasicUI)

Sure: follow the issue:

thanks a lot :slight_smile:

I have the same issue (I think):

lassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) ~[181:org.glassfish.jersey.core.jersey-server:2.22.2]
        at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) ~[181:org.glassfish.jersey.core.jersey-server:2.22.2]
        at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) ~[178:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
        ... 31 more
Caused by: java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_3370,3371,12659234) 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) ~[180:org.glassfish.jersey.core.jersey-common:2.22.2]
        at org.glassfish.jersey.process.internal.RequestScope$Instance.release(RequestScope.java:549) ~[180:org.glassfish.jersey.core.jersey-common:2.22.2]
        at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:319) ~[180:org.glassfish.jersey.core.jersey-common:2.22.2]
        at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) ~[181:org.glassfish.jersey.core.jersey-server:2.22.2]
        at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) ~[181:org.glassfish.jersey.core.jersey-server:2.22.2]
        at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) ~[178:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
        ... 31 more
20:55:39.063 [WARN ] [core.internal.events.OSGiEventManager] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@1f33383' takes more than 5000ms.
20:55:34.886 [WARN ] [.eclipse.jetty.servlet.ServletHandler] -
javax.servlet.ServletException: A MultiException has 1 exceptions.  They are:
1. java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_3370,3371,12659234) has been shut down

        at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:489) [178:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
        at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) [178:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
        at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) [178:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
        at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) [178:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
        at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) [178:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
        at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) [15:com.eclipsesource.jaxrs.publisher:5.3.1.201602281253]
        at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:848) [88:org.eclipse.jetty.servlet:9.3.22.v20171030]
        at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:584) [88:org.eclipse.jetty.servlet:9.3.22.v20171030]
        at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [194:org.ops4j.pax.web.pax-web-jetty:6.0.7]
        at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) [87:org.eclipse.jetty.server:9.3.22.v20171030]
        at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) [85:org.eclipse.jetty.security:9.3.22.v20171030]
        at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226) [87:org.eclipse.jetty.server:9.3.22.v20171030]
        at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [87:org.eclipse.jetty.server:9.3.22.v20171030]
        at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:284) [194:org.ops4j.pax.web.pax-web-jetty:6.0.7]
        at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [88:org.eclipse.jetty.servlet:9.3.22.v20171030]
        at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [87:org.eclipse.jetty.server:9.3.22.v20171030]
        at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [87:org.eclipse.jetty.server:9.3.22.v20171030]
        at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [87:org.eclipse.jetty.server:9.3.22.v20171030]
        at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [194: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:745) [?:?]
Caused by: org.glassfish.hk2.api.MultiException: A MultiException has 1 exceptions.  They are:
1. java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_3370,3371,12659234) 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) ~[180:org.glassfish.jersey.core.jersey-common:2.22.2]
        at org.glassfish.jersey.process.internal.RequestScope$Instance.release(RequestScope.java:549) ~[180:org.glassfish.jersey.core.jersey-common:2.22.2]
        at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:319) ~[180:org.glassfish.jersey.core.jersey-common:2.22.2]
        at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) ~[181:org.glassfish.jersey.core.jersey-server:2.22.2]
        at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) ~[181:org.glassfish.jersey.core.jersey-server:2.22.2]
        at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) ~[178:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
        ... 31 more
Caused by: java.lang.IllegalStateException: ServiceLocatorImpl(__HK2_Generated_3370,3371,12659234) 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) ~[180:org.glassfish.jersey.core.jersey-common:2.22.2]
        at org.glassfish.jersey.process.internal.RequestScope$Instance.release(RequestScope.java:549) ~[180:org.glassfish.jersey.core.jersey-common:2.22.2]
        at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:319) ~[180:org.glassfish.jersey.core.jersey-common:2.22.2]
        at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) ~[181:org.glassfish.jersey.core.jersey-server:2.22.2]
        at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) ~[181:org.glassfish.jersey.core.jersey-server:2.22.2]
        at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) ~[178:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
        ... 31 more

Is there a solution? It seems this issue causes the iOS app to lose connection and I have the feeling that my rules sometimes don’t execute properly/get skipped. Is that possible?

No news/solutions on github.
Have anybody here a solution?

At least on Android that warning does not impact functionalty, so I guess it is on low priority.

Had the same issue when using astro and using the local part in the thing definition.
Changed local to the true location and issue was gone.

I think you need to make sure local and remote the values can be retrieved.

I think you need to make sure local and remote the values can be retrieved.

Please Tony what do you mean “Changed local to the true location”?

Below my current astro.things

astro:sun:home          [ geolocation="42.1859887,44.87786559999995", interval=300 ]
astro:moon:home         [ geolocation="42.1859887,44.87786559999995", interval=300 ]

i have removed the text file definition. No change (:tired_face:

This issue is driving me crazy and i am not sure that the severity is understood.
so, i will summarize. @Kai, once again the reliability of the android application and iOs is affected

When using the android application with a connection to myopenhab, some warnings are logged and the android application UI is not refreshed.

[WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 1379803 failed: null
[WARN ] [io.openhabcloud.internal.CloudClient] - Response Failure: null

Movie issue

Github issue

How can i debug a such issue?

it’s not possible to increase the log level for io.openhabcloud.internal.CloudClient, it’s not a binding

This issue was raised in several threads and it affects the android and iOS users:

I want to underline that in my current case, the sendnotifications do not work also

i have tried also the items below with no success:

  • Uninstall and Reinstall Openhabcloud Connector

  • Clear the cache

  • Updating the threads settings

Some Details about my configuration:

Release = Raspbian GNU/Linux 9 (stretch)
Kernel = Linux 4.14.79-v7+
Platform = Raspberry **Pi 3 Model B Plus** Rev 1.3
CPU Usage = 2.78% avg over 4 cpu(s) (4 core(s) x 1 socket(s))
CPU Load = 1m: 0.40, 5m: 0.37, 15m: 0.38
Memory = Free: 0.08GB (9%), Used: 0.86GB (91%), Total: 0.95GB
Swap = Free: 0.02GB (29%), Used: 0.06GB (71%), Total: 0.09GB
Root = Free: 9.36GB (67%), Used: 4.54GB (33%), Total: 14.53GB
Updates = 14 apt updates available.
Sessions = 2 sessions
Processes = 141 running processes of 32768 maximum processes
openHAB 2.5.0~S1502-1 (Build #1502)

My Openhab story: