Tradfri binding does not reestablish connection to gateway after connection loss

roben commented 5 years ago

Short version: If the gateway gets physically dis- and reconnected the binding does not automatically reconnect to it. To reestablish the connection manually, the IP can be changed to something else and back to the correct value again.

Long version with screenshots and logs: https://community.openhab.org/t/tradfri-binding-loses-connection-after-power-failure-gateway-reboot/47833

lukicsl commented 5 years ago

Have you tried to set the Security Code only?

roben commented 5 years ago

No, because I never had an issue with the code. Maybe you setting the code again also only caused a reconnect. It is intended to not being set after the first connection.

lukicsl commented 5 years ago

Do I read it correct: you go to Configuration > Things > Edit >TRÅDFRI Gateway and just change/change back the ip address. Question: Do you have anything visible indication (***) that Security Code has value. I have always a blank field there.

roben commented 5 years ago

you go to Configuration > Things > Edit >TRÅDFRI Gateway and just change/change back the ip address.

Yes.

Question: Do you have anything visible indication (***) that Security Code has value. I have always a blank field there.

No. It's empty after it has been processed. The real security credentials are shown when setting tools > show advanced settings (or something like this, it's in German for me).

roben commented 5 years ago

But let's stick to the connection issue here. If you have further questions, we can continue in the OH forums.

cweitkamp commented 5 years ago

The Bridge handler overwrites the "Security Code" with null after successfully establishing a connection to the gateway. Why? I will remove that line of code and do some tests.

https://github.com/eclipse/smarthome/blob/b39fe45be22b7290269f1959803e49fda91cdb6a/extensions/binding/org.eclipse.smarthome.binding.tradfri/src/main/java/org/eclipse/smarthome/binding/tradfri/handler/TradfriGatewayHandler.java#L220-L224

kaikreuzer commented 5 years ago

@cweitkamp This is by design as @roben referenced here already. Afair, the Tradfri developer docs stated that systems must not persist the security code, only the PSK.

cweitkamp commented 5 years ago

I got it. Did not read carefully enough. Nonetheless I did some more research. After restarting the bundle we ran into some timeout errors which seem to stop the handler from coming ONLINE again:

2018-08-19 14:18:33.803 [DEBUG] [tradfri.internal.TradfriCoapEndpoint] - Destroying CoAP endpoint.
java.lang.RuntimeException: Endpoint destroyed
        at org.eclipse.smarthome.binding.tradfri.internal.TradfriCoapEndpoint.destroy(TradfriCoapEndpoint.java:39) [234:org.eclipse.smarthome.binding.tradfri:0.10.0.201808191135]
        at org.eclipse.smarthome.binding.tradfri.handler.TradfriGatewayHandler.dispose(TradfriGatewayHandler.java:263) [234:org.eclipse.smarthome.binding.tradfri:0.10.0.201808191135]
        at org.eclipse.smarthome.core.thing.binding.BaseThingHandler.thingUpdated(BaseThingHandler.java:206) [101:org.eclipse.smarthome.core.thing:0.10.0.201808011124]
        at org.eclipse.smarthome.binding.tradfri.handler.TradfriGatewayHandler.thingUpdated(TradfriGatewayHandler.java:371) [234:org.eclipse.smarthome.binding.tradfri:0.10.0.201808191135]
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
        at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
        at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [94:org.eclipse.smarthome.core:0.10.0.201808011124]
        at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [94:org.eclipse.smarthome.core:0.10.0.201808011124]
        at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
        at java.lang.Thread.run(Thread.java:748) [?:?]
2018-08-19 14:18:33.822 [INFO ] [radfri.handler.TradfriGatewayHandler] - Bridge configuration updated. Updating paired things (if any).
2018-08-19 14:18:37.842 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-08-19 14:18:47.484 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-08-19 14:18:50.242 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError

I changed the order of destroying the endpoint before shutting down the client in #6079. This maybe is a fix for our issue, but I am not sure. We need some more testing.

cweitkamp commented 5 years ago

@roben @lukicsl

New ESH 291 and OH2 1340 snapshots have been release. May I ask you to do some more tests with the new version? I am curios if it works...

roben commented 5 years ago

Thanks! Can I use just the new binding jar with the current OH stable? Or is there even a way to tell OH to install the snapshot version of the binding?

cweitkamp commented 5 years ago

Both options are possible. Lets continue our discussion in the community forum.

roben commented 5 years ago

Sady it did not seem to help. I am running 234 │ Active │ 80 │ 0.10.0.201808242044 │ org.eclipse.smarthome.binding.tradfri but still had to do the manual IP change workaround to get the gateway back online.

Even more it seems that OH is now taking much longer to detect the gateway being offline. It logged 12:09:24.240 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'tradfri:gateway:gwdcefcaba8d2b' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR) some minutes after I unplugged it and reconnected it.

roben commented 5 years ago

I'll leave it in this state for now and see if it just takes some time to come back online automatically.

roben commented 5 years ago

Oh and I tried to access the binding while it was already gone but still marked as online: 12:08:55.340 [INFO ] [smarthome.event.ItemCommandEvent ] - Item 'Tradfri_Bulb_WZ_Stehlampe_gr' received command 39 12:08:55.342 [INFO ] [smarthome.event.ItemStateChangedEvent] - Tradfri_Bulb_WZ_Stehlampe_gr changed from 76 to 39

roben commented 5 years ago

15 Minutes later and still no luck. So it seems still broken to me, sorry.

cweitkamp commented 5 years ago

My I ask you to enable DEBUG or TRACE logging while testing?

roben commented 5 years ago

Of course. For which logging facility? Only org.eclipse.smarthome.binding.tradfri?

cweitkamp commented 5 years ago

Yes.

roben commented 5 years ago

First thing I noticed: 12:54:01.703 [DEBUG] [nal.discovery.TradfriDiscoveryService] - Adding device tradfri:0100:gwdcefcaba8d2b:65543 to inbox, even though it is already configured and currently online:

Update: Maybe not that important. Those are just the devices, not the gateway.

roben commented 5 years ago

Unplugged the gateway - no update.

Switched lamp with unplugged gateway:

13:00:42.024 [DEBUG] [g.tradfri.handler.TradfriThingHandler] - Sending payload: {"3311":[{"5850":0}],"3":{}}
13:00:42.025 [DEBUG] [ng.tradfri.internal.TradfriCoapClient] - Proccessing payload: {"3311":[{"5850":0}],"3":{}}
13:00:43.946 [DEBUG] [g.tradfri.handler.TradfriThingHandler] - Sending payload: {"3311":[{"5850":1}],"3":{}}
13:00:43.947 [DEBUG] [ng.tradfri.internal.TradfriCoapClient] - Proccessing payload: {"3311":[{"5850":1}],"3":{}}

Two minutes later, gateway is marked offline in Habmin:

13:01:55.902 [DEBUG] [g.tradfri.internal.TradfriCoapHandler] - CoAP onError
13:02:05.967 [DEBUG] [g.tradfri.internal.TradfriCoapHandler] - CoAP onError
13:02:16.392 [DEBUG] [g.tradfri.internal.TradfriCoapHandler] - CoAP onError
[...]

Plugged gateway back in, gateway is discovered but still marked offline:

13:03:24.018 [DEBUG] [discovery.TradfriDiscoveryParticipant] - Discovered Tradfri gateway: [ServiceInfoImpl@321876347 name: 'gw-dcefcaba8d2b._coap._udp.local.' address: '/10.0.1.167:5684 ' status: 'DNS: JmDNS-IP-1 [/10.0.1.2] state: probing 1 task: null' is persistent, has data
    version: 1.4.15]
13:03:27.617 [DEBUG] [discovery.TradfriDiscoveryParticipant] - Discovered Tradfri gateway: [ServiceInfoImpl@321876347 name: 'gw-dcefcaba8d2b._coap._udp.local.' address: '/10.0.1.167:5684 /fe80:0:0:0:deef:caff:feba:8d2b:5684 ' status: 'DNS: JmDNS-IP-1 [/10.0.1.2] state: probing 1 task: null' is persistent, has data
    version: 1.4.15]
13:03:33.991 [DEBUG] [g.tradfri.internal.TradfriCoapHandler] - CoAP onError

Setting wrong IP:

java.lang.RuntimeException: Endpoint destroyed
    at org.eclipse.smarthome.binding.tradfri.internal.TradfriCoapEndpoint.destroy(TradfriCoapEndpoint.java:39) [234:org.eclipse.smarthome.binding.tradfri:0.10.0.201808242044]
    at org.eclipse.smarthome.binding.tradfri.handler.TradfriGatewayHandler.dispose(TradfriGatewayHandler.java:259) [234:org.eclipse.smarthome.binding.tradfri:0.10.0.201808242044]
    at org.eclipse.smarthome.core.thing.binding.BaseThingHandler.handleConfigurationUpdate(BaseThingHandler.java:196) [108:org.eclipse.smarthome.core.thing:0.10.0.oh230]
    at org.eclipse.smarthome.core.thing.internal.ThingRegistryImpl.updateConfiguration(ThingRegistryImpl.java:92) [108:org.eclipse.smarthome.core.thing:0.10.0.oh230]
    at org.eclipse.smarthome.io.rest.core.internal.thing.ThingResource.updateConfiguration(ThingResource.java:425) [118:org.eclipse.smarthome.io.rest.core:0.10.0.oh230]
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
    at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
    at org.glassfish.jersey.server.model.internal.ResourceMethodInvocationHandlerFactory$1.invoke(ResourceMethodInvocationHandlerFactory.java:81) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher$1.run(AbstractJavaResourceMethodDispatcher.java:144) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.invoke(AbstractJavaResourceMethodDispatcher.java:161) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$ResponseOutInvoker.doDispatch(JavaResourceMethodDispatcherProvider.java:160) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.dispatch(AbstractJavaResourceMethodDispatcher.java:99) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:389) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:347) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:102) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:326) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271) [169:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267) [169:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.internal.Errors.process(Errors.java:315) [169:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.internal.Errors.process(Errors.java:297) [169:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.internal.Errors.process(Errors.java:267) [169:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317) [169:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) [167:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) [167:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) [167:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) [167:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) [167: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) [85:org.eclipse.jetty.servlet:9.3.21.v20170918]
    at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:584) [85:org.eclipse.jetty.servlet:9.3.21.v20170918]
    at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [183:org.ops4j.pax.web.pax-web-jetty:6.0.9]
    at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) [82:org.eclipse.jetty.security:9.3.21.v20170918]
    at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:284) [183:org.ops4j.pax.web.pax-web-jetty:6.0.9]
    at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [85:org.eclipse.jetty.servlet:9.3.21.v20170918]
    at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [183:org.ops4j.pax.web.pax-web-jetty:6.0.9]
    at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.eclipse.jetty.server.Server.handle(Server.java:534) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:333) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) [76:org.eclipse.jetty.io:9.3.21.v20170918]
    at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108) [76:org.eclipse.jetty.io:9.3.21.v20170918]
    at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [76:org.eclipse.jetty.io:9.3.21.v20170918]
    at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [87:org.eclipse.jetty.util:9.3.21.v20170918]
    at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [87:org.eclipse.jetty.util:9.3.21.v20170918]
    at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [87:org.eclipse.jetty.util:9.3.21.v20170918]
    at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [87:org.eclipse.jetty.util:9.3.21.v20170918]
    at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [87:org.eclipse.jetty.util:9.3.21.v20170918]
    at java.lang.Thread.run(Thread.java:748) [?:?]
13:04:41.695 [DEBUG] [.tradfri.internal.TradfriCoapEndpoint] - Destroying CoAP endpoint.
java.lang.RuntimeException: Endpoint destroyed
    at org.eclipse.smarthome.binding.tradfri.internal.TradfriCoapEndpoint.destroy(TradfriCoapEndpoint.java:39) [234:org.eclipse.smarthome.binding.tradfri:0.10.0.201808242044]
    at org.eclipse.smarthome.binding.tradfri.handler.TradfriGatewayHandler.dispose(TradfriGatewayHandler.java:259) [234:org.eclipse.smarthome.binding.tradfri:0.10.0.201808242044]
    at org.eclipse.smarthome.core.thing.binding.BaseThingHandler.handleConfigurationUpdate(BaseThingHandler.java:196) [108:org.eclipse.smarthome.core.thing:0.10.0.oh230]
    at org.eclipse.smarthome.core.thing.internal.ThingRegistryImpl.updateConfiguration(ThingRegistryImpl.java:92) [108:org.eclipse.smarthome.core.thing:0.10.0.oh230]
    at org.eclipse.smarthome.io.rest.core.internal.thing.ThingResource.updateConfiguration(ThingResource.java:425) [118:org.eclipse.smarthome.io.rest.core:0.10.0.oh230]
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
    at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
    at org.glassfish.jersey.server.model.internal.ResourceMethodInvocationHandlerFactory$1.invoke(ResourceMethodInvocationHandlerFactory.java:81) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher$1.run(AbstractJavaResourceMethodDispatcher.java:144) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.invoke(AbstractJavaResourceMethodDispatcher.java:161) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$ResponseOutInvoker.doDispatch(JavaResourceMethodDispatcherProvider.java:160) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.dispatch(AbstractJavaResourceMethodDispatcher.java:99) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:389) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:347) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:102) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:326) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271) [169:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267) [169:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.internal.Errors.process(Errors.java:315) [169:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.internal.Errors.process(Errors.java:297) [169:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.internal.Errors.process(Errors.java:267) [169:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317) [169:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) [170:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) [167:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) [167:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) [167:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) [167:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) [167: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) [85:org.eclipse.jetty.servlet:9.3.21.v20170918]
    at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:584) [85:org.eclipse.jetty.servlet:9.3.21.v20170918]
    at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [183:org.ops4j.pax.web.pax-web-jetty:6.0.9]
    at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) [82:org.eclipse.jetty.security:9.3.21.v20170918]
    at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:284) [183:org.ops4j.pax.web.pax-web-jetty:6.0.9]
    at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [85:org.eclipse.jetty.servlet:9.3.21.v20170918]
    at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [183:org.ops4j.pax.web.pax-web-jetty:6.0.9]
    at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.eclipse.jetty.server.Server.handle(Server.java:534) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:333) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [84:org.eclipse.jetty.server:9.3.21.v20170918]
    at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) [76:org.eclipse.jetty.io:9.3.21.v20170918]
    at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108) [76:org.eclipse.jetty.io:9.3.21.v20170918]
    at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [76:org.eclipse.jetty.io:9.3.21.v20170918]
    at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [87:org.eclipse.jetty.util:9.3.21.v20170918]
    at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [87:org.eclipse.jetty.util:9.3.21.v20170918]
    at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [87:org.eclipse.jetty.util:9.3.21.v20170918]
    at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [87:org.eclipse.jetty.util:9.3.21.v20170918]
    at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [87:org.eclipse.jetty.util:9.3.21.v20170918]
    at java.lang.Thread.run(Thread.java:748) [?:?]

Setting correct IP:

13:04:43.784 [DEBUG] [g.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9023":"1.tradfri.pool.ntp.org","9029":"1.4.15","9059":1535195083,"9060":"2018-08-25T11:04:43.041104Z","9062":0,"9061":0,"9081":"7e191d52044000e8","9082":true,"9093":0,"9083":"862-73-258","9054":0,"9073":13,"9055":0,"9200":"537e7ce9-e42e-4eb4-80eb-93319c1c8f30","9066":5,"9069":1530532122,"9107":1,"9106":0,"9077":28,"9071":1,"9072":8,"9074":17,"9080":60,"9075":28,"9076":10,"9078":1,"9079":0,"9092":0,"9105":0}
13:04:44.229 [DEBUG] [g.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: [65541,65536,65542,65540,65538,65537,65544,65543]
13:04:44.232 [DEBUG] [tradfri.handler.TradfriGatewayHandler] - onUpdate response: [65541,65536,65542,65540,65538,65537,65544,65543]
13:04:44.237 [DEBUG] [g.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"15009":[{"9003":0}],"9001":"TRADFRI remote control 2","9002":1511897934,"9020":1534305112,"9003":65541,"5750":0,"9054":0,"9019":1,"3":{"3":"1.2.214","0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","6":3,"9":60}}
13:04:44.239 [DEBUG] [tradfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"15009":[{"9003":0}],"9001":"TRADFRI remote control 2","9002":1511897934,"9020":1534305112,"9003":65541,"5750":0,"9054":0,"9019":1,"3":{"3":"1.2.214","0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","6":3,"9":60}}
13:04:44.240 [DEBUG] [nal.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwdcefcaba8d2b:65541 to inbox
13:04:44.242 [DEBUG] [g.tradfri.internal.TradfriCoapHandler] - CoAP response

GW is back online.

chriscolden commented 5 years ago

Just to add, I have the same issue. Gateway is set to a fixed ip via dhcp reservation. I use the DNS fqdn in the binding. If i change the fqdn to something else in the binding and revert back to the proper fqdn of the gateway it comes back online.

lukicsl commented 5 years ago

I was also just rebooting the openhab server serveral times yesterday and had to set/reset the ip address of gateway in order to get connected. The gateway was not touched at all (power all time on).

chriscolden commented 5 years ago

I think this needs to be reopened. I'm still having this issue. The address has been updated for the ip when I was using the fqdn which I assume is due to this commit, but its still loosing connection a staying lost until i manually change the address, save, edit the up back a save. Thanks.

kaikreuzer commented 5 years ago

@chriscolden Are you sure that you have used the latest code that includes the fix? In case you are using an openHAB snapshot, please note that this fix is only contained in it since build 1370.

chriscolden commented 5 years ago

Thanks @kaikreuzer. Im on snapshot but one behind that. I'll updated an see how I get on.

chriscolden commented 5 years ago

@kaikreuzer are you able to tell me how I can find out when fixes which are merged make it into the builds please? Save me logging issues which I think should be fix but I'm not running them yet. Thanks

kaikreuzer commented 5 years ago

https://github.com/eclipse/smarthome/wiki/ESH-used-in-openHAB

roben commented 5 years ago

I had a power outage yesterday (OH on UPS but Tradfri down) and can report that Tradfri recovered just fine in my case. Thanks!

chriscolden commented 5 years ago

I had it loose the gateway last night. Changing the ip to a false ip and back again, brought the gateway back online, therefore I dont think this has resolved the issue I'm having. Running openhab build 1376.

htreu commented 5 years ago

Reopening for @chriscolden's request in #6305:

I'm facing an issue where the Tradfri binding looses connection to the gateway after a period of time, usually within a 12 hour period.

I can solve the problem temporarily by changing the ip on the gateway and change it back to the original ip. I believe this is session issue. The session becomes stale and doesn't re initiate.

My tradfri gateway is on a static ip via dhcp reservation.

Gateway version 1.4.15

Additional note: The tradfri mobile app now has notes around this issue. They say if the app cannot connect to the gateway, swipe the app away and reopen. I believe I'm doing the equivalent by changing the ip, forcing the binding to re initiate the session with the gateway.

Please let me know if you need further information. This only started happening recently, possibly because the gateway was updated.

htreu commented 5 years ago

@chriscolden please provide log entries showing the described behaviour, thanks.

chriscolden commented 5 years ago

First apologies for the log. It's started displaying odd in the console in the last few updates.

I've turned on DEBUGGING.

Connection was already lost, it only outputs the following `16:18:43.500 [DEBUG] [g.tradfri.internal.TradfriCoapHandler] - CoAP onError

16:19:01.758 [DEBUG] [g.tradfri.internal.TradfriCoapHandler] - CoAP onError`

Changing the ip and back again results in the following.

2018-10-04 16:16:12.325 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 16:16:58.901 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 16:17:09.535 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 16:17:49.327 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 16:18:03.122 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 16:18:43.500 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 16:19:01.758 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 16:19:49.795 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 16:20:02.630 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 16:20:55.315 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 16:21:02.540 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 16:21:47.856 [DEBUG] [tradfri.internal.TradfriCoapEndpoint] - Destroying CoAP endpoint.
java.lang.RuntimeException: Endpoint destroyed
    at org.eclipse.smarthome.binding.tradfri.internal.TradfriCoapEndpoint.destroy(TradfriCoapEndpoint.java:39) [226:org.eclipse.smarthome.binding.tradfri:0.10.0.201809271800]
    at org.eclipse.smarthome.binding.tradfri.handler.TradfriGatewayHandler.dispose(TradfriGatewayHandler.java:259) [226:org.eclipse.smarthome.binding.tradfri:0.10.0.201809271800]
    at org.eclipse.smarthome.core.thing.binding.BaseThingHandler.handleConfigurationUpdate(BaseThingHandler.java:146) [107:org.eclipse.smarthome.core.thing:0.10.0.201809271800]
    at org.eclipse.smarthome.core.thing.internal.ThingRegistryImpl.updateConfiguration(ThingRegistryImpl.java:92) [107:org.eclipse.smarthome.core.thing:0.10.0.201809271800]
    at org.eclipse.smarthome.io.rest.core.internal.thing.ThingResource.updateConfiguration(ThingResource.java:436) [117:org.eclipse.smarthome.io.rest.core:0.10.0.201809271800]
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
    at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
    at org.glassfish.jersey.server.model.internal.ResourceMethodInvocationHandlerFactory$1.invoke(ResourceMethodInvocationHandlerFactory.java:81) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher$1.run(AbstractJavaResourceMethodDispatcher.java:144) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.invoke(AbstractJavaResourceMethodDispatcher.java:161) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$ResponseOutInvoker.doDispatch(JavaResourceMethodDispatcherProvider.java:160) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.dispatch(AbstractJavaResourceMethodDispatcher.java:99) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:389) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:347) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:102) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:326) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271) [168:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267) [168:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.internal.Errors.process(Errors.java:315) [168:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.internal.Errors.process(Errors.java:297) [168:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.internal.Errors.process(Errors.java:267) [168:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317) [168:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) [166:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) [166:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) [166:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) [166:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) [166:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) [20:com.eclipsesource.jaxrs.publisher:5.3.1.201602281253]
    at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:865) [85:org.eclipse.jetty.servlet:9.4.11.v20180605]
    at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:535) [85:org.eclipse.jetty.servlet:9.4.11.v20180605]
    at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [183:org.ops4j.pax.web.pax-web-jetty:7.2.3]
    at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:146) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) [82:org.eclipse.jetty.security:9.4.11.v20180605]
    at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:257) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:293) [183:org.ops4j.pax.web.pax-web-jetty:7.2.3]
    at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) [85:org.eclipse.jetty.servlet:9.4.11.v20180605]
    at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [183:org.ops4j.pax.web.pax-web-jetty:7.2.3]
    at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.Server.handle(Server.java:531) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) [75:org.eclipse.jetty.io:9.4.11.v20180605]
    at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [75:org.eclipse.jetty.io:9.4.11.v20180605]
    at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [75:org.eclipse.jetty.io:9.4.11.v20180605]
    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) [87:org.eclipse.jetty.util:9.4.11.v20180605]
    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) [87:org.eclipse.jetty.util:9.4.11.v20180605]
    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [87:org.eclipse.jetty.util:9.4.11.v20180605]
    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) [87:org.eclipse.jetty.util:9.4.11.v20180605]
    at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) [87:org.eclipse.jetty.util:9.4.11.v20180605]
    at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762) [87:org.eclipse.jetty.util:9.4.11.v20180605]
    at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680) [87:org.eclipse.jetty.util:9.4.11.v20180605]
    at java.lang.Thread.run(Thread.java:748) [?:?]
2018-10-04 16:21:56.093 [DEBUG] [tradfri.internal.TradfriCoapEndpoint] - Destroying CoAP endpoint.
java.lang.RuntimeException: Endpoint destroyed
    at org.eclipse.smarthome.binding.tradfri.internal.TradfriCoapEndpoint.destroy(TradfriCoapEndpoint.java:39) [226:org.eclipse.smarthome.binding.tradfri:0.10.0.201809271800]
    at org.eclipse.smarthome.binding.tradfri.handler.TradfriGatewayHandler.dispose(TradfriGatewayHandler.java:259) [226:org.eclipse.smarthome.binding.tradfri:0.10.0.201809271800]
    at org.eclipse.smarthome.core.thing.binding.BaseThingHandler.handleConfigurationUpdate(BaseThingHandler.java:146) [107:org.eclipse.smarthome.core.thing:0.10.0.201809271800]
    at org.eclipse.smarthome.core.thing.internal.ThingRegistryImpl.updateConfiguration(ThingRegistryImpl.java:92) [107:org.eclipse.smarthome.core.thing:0.10.0.201809271800]
    at org.eclipse.smarthome.io.rest.core.internal.thing.ThingResource.updateConfiguration(ThingResource.java:436) [117:org.eclipse.smarthome.io.rest.core:0.10.0.201809271800]
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
    at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
    at org.glassfish.jersey.server.model.internal.ResourceMethodInvocationHandlerFactory$1.invoke(ResourceMethodInvocationHandlerFactory.java:81) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher$1.run(AbstractJavaResourceMethodDispatcher.java:144) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.invoke(AbstractJavaResourceMethodDispatcher.java:161) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$ResponseOutInvoker.doDispatch(JavaResourceMethodDispatcherProvider.java:160) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.dispatch(AbstractJavaResourceMethodDispatcher.java:99) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:389) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:347) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:102) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:326) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271) [168:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267) [168:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.internal.Errors.process(Errors.java:315) [168:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.internal.Errors.process(Errors.java:297) [168:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.internal.Errors.process(Errors.java:267) [168:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317) [168:org.glassfish.jersey.core.jersey-common:2.22.2]
    at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) [169:org.glassfish.jersey.core.jersey-server:2.22.2]
    at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) [166:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) [166:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) [166:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) [166:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) [166:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
    at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) [20:com.eclipsesource.jaxrs.publisher:5.3.1.201602281253]
    at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:865) [85:org.eclipse.jetty.servlet:9.4.11.v20180605]
    at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:535) [85:org.eclipse.jetty.servlet:9.4.11.v20180605]
    at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [183:org.ops4j.pax.web.pax-web-jetty:7.2.3]
    at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:146) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) [82:org.eclipse.jetty.security:9.4.11.v20180605]
    at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:257) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:293) [183:org.ops4j.pax.web.pax-web-jetty:7.2.3]
    at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) [85:org.eclipse.jetty.servlet:9.4.11.v20180605]
    at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [183:org.ops4j.pax.web.pax-web-jetty:7.2.3]
    at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.Server.handle(Server.java:531) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) [84:org.eclipse.jetty.server:9.4.11.v20180605]
    at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) [75:org.eclipse.jetty.io:9.4.11.v20180605]
    at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [75:org.eclipse.jetty.io:9.4.11.v20180605]
    at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [75:org.eclipse.jetty.io:9.4.11.v20180605]
    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) [87:org.eclipse.jetty.util:9.4.11.v20180605]
    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) [87:org.eclipse.jetty.util:9.4.11.v20180605]
    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [87:org.eclipse.jetty.util:9.4.11.v20180605]
    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) [87:org.eclipse.jetty.util:9.4.11.v20180605]
    at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) [87:org.eclipse.jetty.util:9.4.11.v20180605]
    at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762) [87:org.eclipse.jetty.util:9.4.11.v20180605]
    at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680) [87:org.eclipse.jetty.util:9.4.11.v20180605]
    at java.lang.Thread.run(Thread.java:748) [?:?]
2018-10-04 16:21:57.490 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 16:21:58.535 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538] 
2018-10-04 16:21:58.547 [DEBUG] [radfri.handler.TradfriGatewayHandler] - onUpdate response: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538]
2018-10-04 16:21:58.572 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9074":0,"9023":"2.tradfri.pool.ntp.org","9059":1538666523,"9200":"c226cc7f-e6b9-44d7-99e1-3c4a885198b8","9060":"2018-10-04T15:22:03.041248Z","9105":0,"9080":60,"9062":0,"9083":"868-68-828","9061":0,"9077":28,"9029":"1.4.15","9054":0,"9081":"7e133d520440014d","9092":0,"9082":true,"9106":0,"9066":5,"9055":0,"9069":1530547527,"9071":1,"9075":59,"9072":3,"9073":25,"9076":10,"9078":1,"9079":0,"9093":0,"9107":0} 
2018-10-04 16:21:58.605 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]} 
2018-10-04 16:21:58.675 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]}
2018-10-04 16:21:58.707 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65537 to inbox
2018-10-04 16:21:58.730 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:21:58.742 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:21:58.754 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65546 to inbox
2018-10-04 16:21:58.783 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:21:58.804 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:21:58.832 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65547 to inbox
2018-10-04 16:21:58.855 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]} 
2018-10-04 16:21:58.891 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]}
2018-10-04 16:21:58.911 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65545 to inbox
2018-10-04 16:21:58.934 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]} 
2018-10-04 16:21:58.969 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]}
2018-10-04 16:21:58.990 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65543 to inbox
2018-10-04 16:21:59.021 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]} 
2018-10-04 16:21:59.046 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]}
2018-10-04 16:21:59.064 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65539 to inbox
2018-10-04 16:21:59.091 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:21:59.114 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:21:59.131 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65540 to inbox
2018-10-04 16:21:59.143 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:21:59.153 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:21:59.169 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65542 to inbox
2018-10-04 16:21:59.183 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:21:59.194 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:21:59.209 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65541 to inbox
2018-10-04 16:21:59.221 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:21:59.233 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:21:59.249 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65538 to inbox
2018-10-04 16:22:01.613 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]} 
2018-10-04 16:22:01.660 [DEBUG] [fri.handler.TradfriControllerHandler] - Updating thing for controllerId 65539 to state {batteryLevel: 34, batteryLow: OFF, firmwareVersion: 1.2.214, modelId: TRADFRI remote control, vendor: IKEA of Sweden}
2018-10-04 16:22:01.695 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:22:01.722 [DEBUG] [.tradfri.handler.TradfriLightHandler] - Setting state to OFF
2018-10-04 16:22:01.747 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:22:01.787 [DEBUG] [.tradfri.handler.TradfriLightHandler] - Setting state to OFF
2018-10-04 16:22:01.813 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]} 
2018-10-04 16:22:01.864 [DEBUG] [fri.handler.TradfriControllerHandler] - Updating thing for controllerId 65537 to state {batteryLevel: 47, batteryLow: OFF, firmwareVersion: 1.2.214, modelId: TRADFRI remote control, vendor: IKEA of Sweden}
2018-10-04 16:22:01.885 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:22:01.901 [DEBUG] [.tradfri.handler.TradfriLightHandler] - Setting state to OFF
2018-10-04 16:22:01.922 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:22:01.939 [DEBUG] [.tradfri.handler.TradfriLightHandler] - Setting state to OFF
2018-10-04 16:22:01.957 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]} 
2018-10-04 16:22:01.981 [DEBUG] [fri.handler.TradfriControllerHandler] - Updating thing for controllerId 65543 to state {batteryLevel: 21, batteryLow: OFF, firmwareVersion: 1.2.214, modelId: TRADFRI remote control, vendor: IKEA of Sweden}
2018-10-04 16:22:02.003 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:22:02.069 [DEBUG] [.tradfri.handler.TradfriLightHandler] - Setting state to OFF
2018-10-04 16:22:02.102 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:22:02.149 [DEBUG] [.tradfri.handler.TradfriLightHandler] - Setting state to OFF
2018-10-04 16:22:02.187 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]} 
2018-10-04 16:22:02.242 [DEBUG] [fri.handler.TradfriControllerHandler] - Updating thing for controllerId 65545 to state {batteryLevel: 60, batteryLow: OFF, firmwareVersion: 1.2.214, modelId: TRADFRI remote control, vendor: IKEA of Sweden}
2018-10-04 16:22:02.302 [INFO ] [clipse.smarthome.model.script.Global] - Notify - Landing Light Online
2018-10-04 16:22:04.470 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 16:22:04.718 [INFO ] [clipse.smarthome.model.script.Global] - Notify - Landing Light Offline
2018-10-04 16:22:56.267 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9074":0,"9023":"2.tradfri.pool.ntp.org","9059":1538666581,"9200":"c226cc7f-e6b9-44d7-99e1-3c4a885198b8","9060":"2018-10-04T15:23:01.034392Z","9105":0,"9080":60,"9062":0,"9083":"868-68-828","9061":0,"9077":28,"9029":"1.4.15","9054":0,"9081":"7e133d520440014d","9092":0,"9082":true,"9106":0,"9066":5,"9055":0,"9069":1530547527,"9071":1,"9075":59,"9072":3,"9073":25,"9076":10,"9078":1,"9079":0,"9093":0,"9107":0} 
2018-10-04 16:22:56.290 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538] 
2018-10-04 16:22:56.307 [DEBUG] [radfri.handler.TradfriGatewayHandler] - onUpdate response: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538]
2018-10-04 16:22:56.359 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]} 
2018-10-04 16:22:56.379 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]}
2018-10-04 16:22:56.411 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65537 to inbox
2018-10-04 16:22:56.433 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:22:56.450 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:22:56.475 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65546 to inbox
2018-10-04 16:22:56.517 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:22:56.529 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:22:56.542 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65547 to inbox
2018-10-04 16:22:56.570 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]} 
2018-10-04 16:22:56.604 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]}
2018-10-04 16:22:56.645 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65545 to inbox
2018-10-04 16:22:56.709 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]} 
2018-10-04 16:22:56.739 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]}
2018-10-04 16:22:56.766 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65543 to inbox
2018-10-04 16:22:56.782 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]} 
2018-10-04 16:22:56.802 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]}
2018-10-04 16:22:56.840 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65539 to inbox
2018-10-04 16:22:56.865 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:22:56.884 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:22:56.898 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65540 to inbox
2018-10-04 16:22:56.911 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:22:56.938 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:22:56.972 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65542 to inbox
2018-10-04 16:22:56.996 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:22:57.006 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:22:57.018 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65541 to inbox
2018-10-04 16:22:57.031 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:22:57.043 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:22:57.053 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65538 to inbox
2018-10-04 16:22:59.299 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:22:59.327 [DEBUG] [.tradfri.handler.TradfriLightHandler] - Setting state to OFF
2018-10-04 16:22:59.347 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:22:59.366 [DEBUG] [.tradfri.handler.TradfriLightHandler] - Setting state to OFF
2018-10-04 16:22:59.383 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:22:59.410 [DEBUG] [.tradfri.handler.TradfriLightHandler] - Setting state to OFF
2018-10-04 16:22:59.456 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]} 
2018-10-04 16:22:59.495 [DEBUG] [fri.handler.TradfriControllerHandler] - Updating thing for controllerId 65539 to state {batteryLevel: 34, batteryLow: OFF, firmwareVersion: 1.2.214, modelId: TRADFRI remote control, vendor: IKEA of Sweden}
2018-10-04 16:22:59.534 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]} 
2018-10-04 16:22:59.569 [DEBUG] [fri.handler.TradfriControllerHandler] - Updating thing for controllerId 65537 to state {batteryLevel: 47, batteryLow: OFF, firmwareVersion: 1.2.214, modelId: TRADFRI remote control, vendor: IKEA of Sweden}
2018-10-04 16:22:59.604 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:22:59.617 [DEBUG] [.tradfri.handler.TradfriLightHandler] - Setting state to OFF
2018-10-04 16:22:59.633 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]} 
2018-10-04 16:22:59.662 [DEBUG] [fri.handler.TradfriControllerHandler] - Updating thing for controllerId 65543 to state {batteryLevel: 21, batteryLow: OFF, firmwareVersion: 1.2.214, modelId: TRADFRI remote control, vendor: IKEA of Sweden}
2018-10-04 16:22:59.682 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:22:59.707 [DEBUG] [.tradfri.handler.TradfriLightHandler] - Setting state to OFF
2018-10-04 16:22:59.738 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]} 
2018-10-04 16:22:59.774 [DEBUG] [fri.handler.TradfriControllerHandler] - Updating thing for controllerId 65545 to state {batteryLevel: 60, batteryLow: OFF, firmwareVersion: 1.2.214, modelId: TRADFRI remote control, vendor: IKEA of Sweden}
2018-10-04 16:22:59.804 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Observe":0, "Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:22:59.820 [DEBUG] [.tradfri.handler.TradfriLightHandler] - Setting state to OFF
2018-10-04 16:23:00.069 [INFO ] [clipse.smarthome.model.script.Global] - Notify - Landing Light Online
2018-10-04 16:23:56.264 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9074":0,"9023":"2.tradfri.pool.ntp.org","9059":1538666641,"9200":"c226cc7f-e6b9-44d7-99e1-3c4a885198b8","9060":"2018-10-04T15:24:01.042392Z","9105":0,"9080":60,"9062":0,"9083":"868-68-828","9061":0,"9077":28,"9029":"1.4.15","9054":0,"9081":"7e133d520440014d","9092":0,"9082":true,"9106":0,"9066":5,"9055":0,"9069":1530547527,"9071":1,"9075":59,"9072":3,"9073":25,"9076":10,"9078":1,"9079":0,"9093":0,"9107":0} 
2018-10-04 16:23:56.275 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538] 
2018-10-04 16:23:56.284 [DEBUG] [radfri.handler.TradfriGatewayHandler] - onUpdate response: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538]
2018-10-04 16:23:56.306 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]} 
2018-10-04 16:23:56.316 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]}
2018-10-04 16:23:56.324 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65537 to inbox
2018-10-04 16:23:56.334 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:23:56.343 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:23:56.352 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65546 to inbox
2018-10-04 16:23:56.361 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:23:56.367 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:23:56.377 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65547 to inbox
2018-10-04 16:23:56.397 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]} 
2018-10-04 16:23:56.407 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]}
2018-10-04 16:23:56.417 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65545 to inbox
2018-10-04 16:23:56.429 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]} 
2018-10-04 16:23:56.438 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]}
2018-10-04 16:23:56.449 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65543 to inbox
2018-10-04 16:23:56.461 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]} 
2018-10-04 16:23:56.470 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]}
2018-10-04 16:23:56.481 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65539 to inbox
2018-10-04 16:23:56.493 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:23:56.502 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:23:56.512 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65540 to inbox
2018-10-04 16:23:56.523 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:23:56.532 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:23:56.545 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65542 to inbox
2018-10-04 16:23:56.556 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:23:56.565 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:23:56.575 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65541 to inbox
2018-10-04 16:23:56.587 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:23:56.596 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:23:56.607 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65538 to inbox
2018-10-04 16:24:56.265 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9074":0,"9023":"2.tradfri.pool.ntp.org","9059":1538666701,"9200":"c226cc7f-e6b9-44d7-99e1-3c4a885198b8","9060":"2018-10-04T15:25:01.049392Z","9105":0,"9080":60,"9062":0,"9083":"868-68-828","9061":0,"9077":28,"9029":"1.4.15","9054":0,"9081":"7e133d520440014d","9092":0,"9082":true,"9106":0,"9066":5,"9055":0,"9069":1530547527,"9071":1,"9075":59,"9072":3,"9073":25,"9076":10,"9078":1,"9079":0,"9093":0,"9107":0} 
2018-10-04 16:24:56.274 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538] 
2018-10-04 16:24:56.281 [DEBUG] [radfri.handler.TradfriGatewayHandler] - onUpdate response: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538]
2018-10-04 16:24:56.302 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]} 
2018-10-04 16:24:56.310 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]}
2018-10-04 16:24:56.319 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65537 to inbox
2018-10-04 16:24:56.329 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:24:56.336 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:24:56.345 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65546 to inbox
2018-10-04 16:24:56.356 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:24:56.367 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:24:56.377 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65547 to inbox
2018-10-04 16:24:56.390 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]} 
2018-10-04 16:24:56.399 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]}
2018-10-04 16:24:56.409 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65545 to inbox
2018-10-04 16:24:56.421 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]} 
2018-10-04 16:24:56.430 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]}
2018-10-04 16:24:56.440 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65543 to inbox
2018-10-04 16:24:56.453 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]} 
2018-10-04 16:24:56.462 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]}
2018-10-04 16:24:56.472 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65539 to inbox
2018-10-04 16:24:56.484 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:24:56.494 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:24:56.506 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65540 to inbox
2018-10-04 16:24:56.521 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:24:56.535 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:24:56.550 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65542 to inbox
2018-10-04 16:24:56.569 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:24:56.583 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:24:56.601 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65541 to inbox
2018-10-04 16:24:56.622 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:24:56.640 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:24:56.660 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65538 to inbox
2018-10-04 16:25:56.280 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9074":0,"9023":"2.tradfri.pool.ntp.org","9059":1538666761,"9200":"c226cc7f-e6b9-44d7-99e1-3c4a885198b8","9060":"2018-10-04T15:26:01.000856Z","9105":0,"9080":60,"9062":0,"9083":"868-68-828","9061":0,"9077":28,"9029":"1.4.15","9054":0,"9081":"7e133d520440014d","9092":0,"9082":true,"9106":0,"9066":5,"9055":0,"9069":1530547527,"9071":1,"9075":59,"9072":3,"9073":25,"9076":10,"9078":1,"9079":0,"9093":0,"9107":0} 
2018-10-04 16:25:56.302 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538] 
2018-10-04 16:25:56.316 [DEBUG] [radfri.handler.TradfriGatewayHandler] - onUpdate response: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538]
2018-10-04 16:25:56.419 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]} 
2018-10-04 16:25:56.438 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]}
2018-10-04 16:25:56.456 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65537 to inbox
2018-10-04 16:25:56.474 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:25:56.483 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:25:56.495 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65546 to inbox
2018-10-04 16:25:56.514 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:25:56.524 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:25:56.536 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65547 to inbox
2018-10-04 16:25:56.550 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]} 
2018-10-04 16:25:56.557 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]}
2018-10-04 16:25:56.567 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65545 to inbox
2018-10-04 16:25:56.582 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]} 
2018-10-04 16:25:56.591 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]}
2018-10-04 16:25:56.601 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65543 to inbox
2018-10-04 16:25:56.615 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]} 
2018-10-04 16:25:56.634 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]}
2018-10-04 16:25:56.646 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65539 to inbox
2018-10-04 16:25:56.660 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:25:56.669 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:25:56.678 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65540 to inbox
2018-10-04 16:25:56.688 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:25:56.695 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:25:56.701 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65542 to inbox
2018-10-04 16:25:56.710 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:25:56.715 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:25:56.722 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65541 to inbox
2018-10-04 16:25:56.731 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:25:56.737 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:25:56.744 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65538 to inbox
2018-10-04 16:26:56.275 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9074":0,"9023":"2.tradfri.pool.ntp.org","9059":1538666821,"9200":"c226cc7f-e6b9-44d7-99e1-3c4a885198b8","9060":"2018-10-04T15:27:01.003856Z","9105":0,"9080":60,"9062":0,"9083":"868-68-828","9061":0,"9077":28,"9029":"1.4.15","9054":0,"9081":"7e133d520440014d","9092":0,"9082":true,"9106":0,"9066":5,"9055":0,"9069":1530547527,"9071":1,"9075":59,"9072":3,"9073":25,"9076":10,"9078":1,"9079":0,"9093":0,"9107":0} 
2018-10-04 16:26:56.289 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538] 
2018-10-04 16:26:56.299 [DEBUG] [radfri.handler.TradfriGatewayHandler] - onUpdate response: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538]
2018-10-04 16:26:56.334 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]} 
2018-10-04 16:26:56.340 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]}
2018-10-04 16:26:56.347 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65537 to inbox
2018-10-04 16:26:56.357 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:26:56.363 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:26:56.371 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65546 to inbox
2018-10-04 16:26:56.379 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:26:56.385 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:26:56.392 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65547 to inbox
2018-10-04 16:26:56.401 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]} 
2018-10-04 16:26:56.406 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]}
2018-10-04 16:26:56.411 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65545 to inbox
2018-10-04 16:26:56.418 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]} 
2018-10-04 16:26:56.422 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]}
2018-10-04 16:26:56.428 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65543 to inbox
2018-10-04 16:26:56.434 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]} 
2018-10-04 16:26:56.439 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]}
2018-10-04 16:26:56.444 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65539 to inbox
2018-10-04 16:26:56.451 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:26:56.455 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:26:56.461 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65540 to inbox
2018-10-04 16:26:56.468 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:26:56.473 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:26:56.478 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65542 to inbox
2018-10-04 16:26:56.485 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:26:56.489 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:26:56.495 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65541 to inbox
2018-10-04 16:26:56.501 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:26:56.506 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:26:56.511 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65538 to inbox
2018-10-04 16:27:56.279 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9074":0,"9023":"2.tradfri.pool.ntp.org","9059":1538666881,"9200":"c226cc7f-e6b9-44d7-99e1-3c4a885198b8","9060":"2018-10-04T15:28:01.012856Z","9105":0,"9080":60,"9062":0,"9083":"868-68-828","9061":0,"9077":28,"9029":"1.4.15","9054":0,"9081":"7e133d520440014d","9092":0,"9082":true,"9106":0,"9066":5,"9055":0,"9069":1530547527,"9071":1,"9075":59,"9072":3,"9073":25,"9076":10,"9078":1,"9079":0,"9093":0,"9107":0} 
2018-10-04 16:27:56.294 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538] 
2018-10-04 16:27:56.306 [DEBUG] [radfri.handler.TradfriGatewayHandler] - onUpdate response: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538]
2018-10-04 16:27:56.338 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]} 
2018-10-04 16:27:56.351 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]}
2018-10-04 16:27:56.359 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65537 to inbox
2018-10-04 16:27:56.371 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:27:56.379 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:27:56.390 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65546 to inbox
2018-10-04 16:27:56.399 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:27:56.407 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:27:56.414 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65547 to inbox
2018-10-04 16:27:56.424 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]} 
2018-10-04 16:27:56.429 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]}
2018-10-04 16:27:56.436 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65545 to inbox
2018-10-04 16:27:56.445 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]} 
2018-10-04 16:27:56.451 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]}
2018-10-04 16:27:56.457 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65543 to inbox
2018-10-04 16:27:56.467 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]} 
2018-10-04 16:27:56.472 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]}
2018-10-04 16:27:56.478 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65539 to inbox
2018-10-04 16:27:56.486 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:27:56.492 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:27:56.499 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65540 to inbox
2018-10-04 16:27:56.508 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:27:56.514 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:27:56.521 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65542 to inbox
2018-10-04 16:27:56.530 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:27:56.536 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:27:56.543 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65541 to inbox
2018-10-04 16:27:56.552 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:27:56.558 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:27:56.565 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65538 to inbox
2018-10-04 16:28:56.285 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9074":0,"9023":"2.tradfri.pool.ntp.org","9059":1538666941,"9200":"c226cc7f-e6b9-44d7-99e1-3c4a885198b8","9060":"2018-10-04T15:29:01.020856Z","9105":0,"9080":60,"9062":0,"9083":"868-68-828","9061":0,"9077":28,"9029":"1.4.15","9054":0,"9081":"7e133d520440014d","9092":0,"9082":true,"9106":0,"9066":5,"9055":0,"9069":1530547527,"9071":1,"9075":59,"9072":3,"9073":25,"9076":10,"9078":1,"9079":0,"9093":0,"9107":0} 
2018-10-04 16:28:56.298 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538] 
2018-10-04 16:28:56.305 [DEBUG] [radfri.handler.TradfriGatewayHandler] - onUpdate response: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538]
2018-10-04 16:28:56.335 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]} 
2018-10-04 16:28:56.342 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]}
2018-10-04 16:28:56.349 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65537 to inbox
2018-10-04 16:28:56.361 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:28:56.367 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:28:56.376 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65546 to inbox
2018-10-04 16:28:56.385 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:28:56.391 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:28:56.398 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65547 to inbox
2018-10-04 16:28:56.407 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]} 
2018-10-04 16:28:56.413 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]}
2018-10-04 16:28:56.420 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65545 to inbox
2018-10-04 16:28:56.430 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]} 
2018-10-04 16:28:56.436 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]}
2018-10-04 16:28:56.444 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65543 to inbox
2018-10-04 16:28:56.452 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]} 
2018-10-04 16:28:56.459 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]}
2018-10-04 16:28:56.466 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65539 to inbox
2018-10-04 16:28:56.479 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:28:56.487 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:28:56.498 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65540 to inbox
2018-10-04 16:28:56.511 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:28:56.519 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:28:56.530 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65542 to inbox
2018-10-04 16:28:56.542 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:28:56.551 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:28:56.565 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65541 to inbox
2018-10-04 16:28:56.578 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:28:56.587 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:28:56.598 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65538 to inbox
2018-10-04 16:29:56.280 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9074":0,"9023":"2.tradfri.pool.ntp.org","9059":1538667001,"9200":"c226cc7f-e6b9-44d7-99e1-3c4a885198b8","9060":"2018-10-04T15:30:01.027856Z","9105":0,"9080":60,"9062":0,"9083":"868-68-828","9061":0,"9077":28,"9029":"1.4.15","9054":0,"9081":"7e133d520440014d","9092":0,"9082":true,"9106":0,"9066":5,"9055":0,"9069":1530547527,"9071":1,"9075":59,"9072":3,"9073":25,"9076":10,"9078":1,"9079":0,"9093":0,"9107":0} 
2018-10-04 16:29:56.293 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538] 
2018-10-04 16:29:56.301 [DEBUG] [radfri.handler.TradfriGatewayHandler] - onUpdate response: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538]
2018-10-04 16:29:56.450 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]} 
2018-10-04 16:29:56.459 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]}
2018-10-04 16:29:56.467 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65537 to inbox
2018-10-04 16:29:56.478 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:29:56.486 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:29:56.494 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65546 to inbox
2018-10-04 16:29:56.505 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 16:29:56.512 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 16:29:56.520 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65547 to inbox
2018-10-04 16:29:56.531 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]} 
2018-10-04 16:29:56.538 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]}
2018-10-04 16:29:56.547 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65545 to inbox
2018-10-04 16:29:56.556 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]} 
2018-10-04 16:29:56.563 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]}
2018-10-04 16:29:56.570 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65543 to inbox
2018-10-04 16:29:56.578 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]} 
2018-10-04 16:29:56.584 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]}
2018-10-04 16:29:56.590 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65539 to inbox
2018-10-04 16:29:56.598 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:29:56.603 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:29:56.610 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65540 to inbox
2018-10-04 16:29:56.619 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:29:56.624 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:29:56.632 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65542 to inbox
2018-10-04 16:29:56.642 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:29:56.649 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:29:56.657 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65541 to inbox
2018-10-04 16:29:56.667 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 16:29:56.674 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 16:29:56.683 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65538 to inbox

I will try and catch the log when it disconnects again and post a further update.

chriscolden commented 5 years ago

I've had the disconnect happen and got the log for around the time it went. You can clearly see it working and then finally not working. So hopefully this will help you.

2018-10-04 18:38:56.612 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9074":0,"9023":"2.tradfri.pool.ntp.org","9059":1538674742,"9200":"c226cc7f-e6b9-44d7-99e1-3c4a885198b8","9060":"2018-10-04T17:39:02.005784Z","9105":0,"9080":60,"9062":0,"9083":"868-68-828","9061":0,"9077":28,"9029":"1.4.15","9054":0,"9081":"7e133d520440014d","9092":0,"9082":true,"9106":0,"9066":5,"9055":0,"9069":1530547527,"9071":1,"9075":59,"9072":3,"9073":25,"9076":10,"9078":1,"9079":0,"9093":0,"9107":0} 
2018-10-04 18:38:56.624 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538] 
2018-10-04 18:38:56.629 [DEBUG] [radfri.handler.TradfriGatewayHandler] - onUpdate response: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538]
2018-10-04 18:38:56.657 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]} 
2018-10-04 18:38:56.668 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]}
2018-10-04 18:38:56.680 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65537 to inbox
2018-10-04 18:38:56.696 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 18:38:56.705 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Light","9002":1515794567,"9020":1538640577,"9003":65546,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":203,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 18:38:56.715 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65546 to inbox
2018-10-04 18:38:56.727 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]} 
2018-10-04 18:38:56.735 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Light","9002":1515861928,"9020":1538627926,"9003":65547,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":454,"5709":30138,"5710":26909,"5706":"efd275","9003":0}]}
2018-10-04 18:38:56.745 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65547 to inbox
2018-10-04 18:38:56.758 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]} 
2018-10-04 18:38:56.766 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]}
2018-10-04 18:38:56.776 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65545 to inbox
2018-10-04 18:38:56.788 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]} 
2018-10-04 18:38:56.797 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]}
2018-10-04 18:38:56.808 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65543 to inbox
2018-10-04 18:38:56.821 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]} 
2018-10-04 18:38:56.829 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]}
2018-10-04 18:38:56.838 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65539 to inbox
2018-10-04 18:38:56.850 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 18:38:56.858 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 1","9002":1514414960,"9020":1538616996,"9003":65540,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 18:38:56.869 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65540 to inbox
2018-10-04 18:38:56.878 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 18:38:56.883 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 3","9002":1514414986,"9020":1538616997,"9003":65542,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 18:38:56.890 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65542 to inbox
2018-10-04 18:38:56.899 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 18:38:56.904 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Bulb 2","9002":1514414978,"9020":1538616999,"9003":65541,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E14 WS opal 400lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 18:38:56.911 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65541 to inbox
2018-10-04 18:38:56.919 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]} 
2018-10-04 18:38:56.928 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Bulb","9002":1511036145,"9020":1538616998,"9003":65538,"9054":0,"5750":2,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI bulb E27 WS opal 980lm","2":"","3":"1.2.217","6":1},"3311":[{"5850":0,"5851":254,"5717":0,"5711":370,"5709":30138,"5710":26909,"5706":"f1e0b5","9003":0}]}
2018-10-04 18:38:56.935 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0220:gwa0cc2b6f9037:65538 to inbox
2018-10-04 18:39:56.617 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9074":0,"9023":"2.tradfri.pool.ntp.org","9059":1538674802,"9200":"c226cc7f-e6b9-44d7-99e1-3c4a885198b8","9060":"2018-10-04T17:40:02.015784Z","9105":0,"9080":60,"9062":0,"9083":"868-68-828","9061":0,"9077":28,"9029":"1.4.15","9054":0,"9081":"7e133d520440014d","9092":0,"9082":true,"9106":0,"9066":5,"9055":0,"9069":1530547527,"9071":1,"9075":59,"9072":3,"9073":25,"9076":10,"9078":1,"9079":0,"9093":0,"9107":0} 
2018-10-04 18:39:56.629 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538] 
2018-10-04 18:39:56.636 [DEBUG] [radfri.handler.TradfriGatewayHandler] - onUpdate response: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538]
2018-10-04 18:39:56.671 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]} 
2018-10-04 18:39:56.676 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Lounge Remote Control","9002":1511036088,"9020":1538421637,"9003":65537,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":47},"15009":[{"9003":0}]}
2018-10-04 18:39:56.684 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65537 to inbox
2018-10-04 18:39:56.692 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:39:56.697 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: 
2018-10-04 18:39:56.704 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:39:56.710 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: 
2018-10-04 18:39:56.718 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]} 
2018-10-04 18:39:56.724 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Hall Remote Control","9002":1515794507,"9020":1538649347,"9003":65545,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":60},"15009":[{"9003":0}]}
2018-10-04 18:39:56.731 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65545 to inbox
2018-10-04 18:39:56.741 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]} 
2018-10-04 18:39:56.746 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Landing Remote Control","9002":1515794185,"9020":1538604396,"9003":65543,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":21},"15009":[{"9003":0}]}
2018-10-04 18:39:56.754 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65543 to inbox
2018-10-04 18:39:56.764 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]} 
2018-10-04 18:39:56.771 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: {"9001":"Bedroom Remote Control","9002":1514414910,"9020":1538604952,"9003":65539,"9054":0,"5750":0,"9019":1,"3":{"0":"IKEA of Sweden","1":"TRADFRI remote control","2":"","3":"1.2.214","6":3,"9":34},"15009":[{"9003":0}]}
2018-10-04 18:39:56.787 [DEBUG] [al.discovery.TradfriDiscoveryService] - Adding device tradfri:0830:gwa0cc2b6f9037:65539 to inbox
2018-10-04 18:39:56.798 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:39:56.805 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: 
2018-10-04 18:39:56.815 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:39:56.823 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: 
2018-10-04 18:39:56.835 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:39:56.843 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: 
2018-10-04 18:39:56.852 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:39:56.860 [DEBUG] [radfri.handler.TradfriGatewayHandler] - requestDeviceDetails response: 
2018-10-04 18:40:00.298 [INFO ] [clipse.smarthome.model.script.Events] - Current time of day is now DUSK
2018-10-04 18:40:56.617 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: {"9074":0,"9023":"2.tradfri.pool.ntp.org","9059":1538674862,"9200":"c226cc7f-e6b9-44d7-99e1-3c4a885198b8","9060":"2018-10-04T17:41:02.022784Z","9105":0,"9080":60,"9062":0,"9083":"868-68-828","9061":0,"9077":28,"9029":"1.4.15","9054":0,"9081":"7e133d520440014d","9092":0,"9082":true,"9106":0,"9066":5,"9055":0,"9069":1530547527,"9071":1,"9075":59,"9072":3,"9073":25,"9076":10,"9078":1,"9079":0,"9093":0,"9107":0} 
2018-10-04 18:40:56.630 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538] 
2018-10-04 18:40:56.641 [DEBUG] [radfri.handler.TradfriGatewayHandler] - onUpdate response: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538]
2018-10-04 18:40:56.684 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:40:56.695 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:40:56.705 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:40:56.714 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:40:56.726 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:40:56.736 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:40:56.749 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:40:56.758 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:40:56.770 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:40:56.780 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:40:56.790 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:40:56.797 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:40:56.804 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:40:56.811 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:40:56.818 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:40:56.825 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:40:56.833 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:40:56.840 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:40:56.847 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:40:56.855 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:41:56.624 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:41:56.635 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538] 
2018-10-04 18:41:56.642 [DEBUG] [radfri.handler.TradfriGatewayHandler] - onUpdate response: [65537,65546,65547,65545,65543,65539,65540,65542,65541,65538]
2018-10-04 18:41:56.677 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:41:56.687 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:41:56.697 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:41:56.707 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:41:56.715 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:41:56.722 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:41:56.729 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:41:56.735 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:41:56.742 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:41:56.747 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:41:56.755 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:41:56.761 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:41:56.768 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:41:56.773 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:41:56.780 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:41:56.785 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:41:56.792 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:41:56.798 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:41:56.805 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:41:56.811 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 4.00
2018-10-04 18:42:56.625 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:42:56.643 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP response
options: {"Content-Format":"application/json", "Max-Age":604800}
payload:  
2018-10-04 18:42:56.652 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP error 5.00
2018-10-04 18:42:57.063 [INFO ] [clipse.smarthome.model.script.Global] - Notify - Landing Light Offline
2018-10-04 18:45:15.232 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 18:45:24.376 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 18:46:05.746 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 18:46:14.365 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 18:47:05.496 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 18:47:27.006 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 18:48:06.798 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 18:48:11.602 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 18:49:09.869 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 18:49:14.145 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 18:50:11.950 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError
2018-10-04 18:50:17.095 [DEBUG] [.tradfri.internal.TradfriCoapHandler] - CoAP onError

chriscolden commented 5 years ago

Could the code removed in this pull request have anything to do with this issue im having? https://github.com/eclipse/smarthome/pull/5631

Magiweb commented 5 years ago

Sorry I'm posting here, but I've tried registering on the community forum and I have not been able to confirm my email since I never receive the confirmation email.

I can confirm having the exact same issue. Unfortunately I'm quite new to OpenHAB and although my background is web application development, I'm still pretty green with this platform.

If there's anything I can contribute - log files etc. - please let me know. But this is definitely a issue I'm seeing as well.

kaikreuzer commented 5 years ago

@chriscolden Please always use three backticks for code sections, otherwise log files are not readable on Guithub - I've just updated your comments above accordingly.

Could the code removed in this pull request have anything to do with this issue im having? #5631

Do you have any suspicion why that might be the case? If so, give @sjka a hint as he implemented that PR.

Wrt the logs - to me it does not say much as I am not really deep in the CoAP communication. Maybe @cweitkamp or @ivivanov-bg have some idea here?

cweitkamp commented 5 years ago

According to RFC 7252 the response codes are similar to HTTP status codes.

4.00 Bad Request

5.00 Internal Server Error

Unfortunately we cannot see the causing requests inside the logs. I have added some more logging outputs for them in PR #6421 and hope to find the culprit.

chriscolden commented 5 years ago

@kaikreuzer @sjka The only reason I think its that code, as the binding goes offline with a communication error. The code which was removed throws away the coap endpoint and creates a new one when it goes offline with a communication error.

Apparently there is a known issue with Tradfri that even their own app looses connection after a period of time, you have to close and swipe it away on your phone before it will reconnect. I wonder if its a similar issue.

EDIT: Also changing the configuration of the thing and reverting it back also resolves the problem, however its a manual fix each time. Ie. change ip or dns name, change it back.

htreu commented 5 years ago

@chriscolden I did a build of the binding with the specific code from #5631 reverted. It will stop and recreate the endPoint again. Are you able to test the attached binding?

org.eclipse.smarthome.binding.tradfri-0.10.0-SNAPSHOT.jar.zip

chriscolden commented 5 years ago

@htreu I will certainly give the binding a go, however I moved my openhab and tradfri gateway to new ips the other day, and the issue seems to have gone away. I cannot see how this could be related though. How would you like me to proceed?

htreu commented 5 years ago

the issue seems to have gone away

I'm glad it works for you anyway but would like to have confirmation that this actually fixes the problem. @Magiweb could you provide feedback based on the binding referenced in https://github.com/eclipse/smarthome/issues/6065#issuecomment-434726626? Thank you very much.

Magiweb commented 5 years ago

Happy to @htreu; pure novice here though - how do I override the existing binding and replace it with the download references in the zip file in #6065 (comment)?

If you point me in the right direction with existing documentation on how to do it I'll run with that.

chriscolden commented 5 years ago

If you point me in the right direction with existing documentation on how to do it I'll run with that.

Try here You will likely also want to clean the cache ("openhab-cli clean-cache") and restart openhab. Remove the jar, clean cache again and restart openhab to remove.

Magiweb commented 5 years ago

Will do - will let you know.

boaks commented 5 years ago

Some hints from Californium Development:

I'm not sure, why the tradfri gateway starts to response with 4.00. I hope it's not californium, which starts to generate bad request. The logs will provide more info's on that, if it points to californium, just open an issue for me there.

The onError after 5.00 indicates for me, that the tradfri gateway crashs (onError seems to be caused by a timeout).

One thing, I'm not sure, if it is an issue or not: CoAP has something called "N-START 1", means "only one transaction from each side should be open between two peers". That is interpreted in a lot of different ways. Some think, it's forbidden for the other side but they don't proof their code against violations and potentially optimistically tests only the N-START 1 cases. Too frequently this means: it doesn't work reliable, if more than 1 request is pending :-). Though "onError" indicates, that the CoapHandler is used for asynchronous requests, I guess, sometimes, depending from timings, more than 1 request is pending. If I fire multiple request to my tradfri, it didn't stop to work, but it starts to behave strange. But I'm not able to reproduce the behaviour described here. In fact, I have to restart my gateway to get it working again. So, if possible, either make a test with "synchronous" calls (without CoapHandler, but this will block your thread, so I'm not sure, if it's possible), or start the next request only in the callback of the previous. And yes, californium offers configurable "congestion control", but I know, it doesn't work. So please, if, possible try to test it with means on your side :-).

boaks commented 5 years ago

Forget my hint about N-START 1. I retested it and my errors are caused by switching the lamps on and off. So, looking forward to the logs with the broken request :-).

chriscolden commented 5 years ago

Had the same thing happen again today after a reboot of my openhab server. I will wait to see if I reoccurs on its own and then try the test jar above.

Tobster77 commented 5 years ago

I tested with last binding (ESH 0.11) as part of OH 2.4 final release. No improvement. Gateway goes offline an has to be manually reconnected (in my case, I only tested retyping the credentials)

boaks commented 5 years ago

I spent the afternoon in checking the behavior of my tradfri-gateway when I restart it. It seems, that the gateway didn't persist the DTLS session and connection keys (OK, that seems to be the standard), and so all messages send to the gateway are ignored until an new handshake establishes a new session after such a restart. So far, all within the RFC6347. Then I tried to resume the session after the gateway restarted, but to my surprise, this fails! The reason is, that the gateway send a empty session id in the SERVER_HELLO, indicating that it will not support a session resumption. OK, that is not implemented scandium, I fixed it today. With that (see https://github.com/eclipse/californium/pull/845) it should now be possible to use forceResumeSessionFor, when the gateway restarts. It then executes a full- instead of a resumption-handshake.

FMPOV, if a request timesout, I would call DTLSConnector.forceResumeSessionFor and retry the job if a new request (don't try to reuse to old request instance). With the PR 845 this works much smoother than without.

I don't know, if it's worth to try to receive the "GATEWAY_REBOOT_NOTIFICATION", I think, in too many cases the reboot is unintended and so I'm not sure, if that is reliable. So I would use the timeout of the requests.

boaks commented 5 years ago

PR 845 is merged to the californium 1.0.x branch (1.0.7-SNAPSHOT). Please try it, if it improves your experience as well.

eclipse-archived / smarthome

Tradfri binding does not reestablish connection to gateway after connection loss #6065