HarmonyClient is not connected

Andrew_Pawelski · August 30, 2019, 11:25pm

Since upgrading to M2 i have an issue when sending throught he harmony binding.

Hub says its online, commands still work through remote and Alexa.

Have I missed something simple in the update?


==> /var/log/openhab2/openhab.log <==

2019-08-31 08:53:22.524 [WARN ] [b.internal.handler.HarmonyHubHandler] - Cannot send command 'Watch TV' on harmonyhub:hub:HarmonyHub:currentActivity because HarmonyClient is not connected```

Andrew_Pawelski · August 31, 2019, 8:23am

An uninstall and reinstall of the binding has fixed this

Andrew_Pawelski · September 8, 2019, 8:12am

Unfortunately this hasn’t resolved my issue. If I uninstall and reinstall the binging it works immediately but very temporary before same issue - anyone else experiencing this behaviour?

1900 · September 21, 2019, 2:36am

I have the same issue M1 was fine.

M2 and M3 go offline and report

[b.internal.handler.HarmonyHubHandler] - Cannot send command ‘mycommand’ on harmonyhub:hub:Back:currentActivity because HarmonyClient is not connected

Andrew_Pawelski · September 22, 2019, 1:35am

Weirdly mine seems to have come good on its own

mvo · September 22, 2019, 3:25am

Which are the harmony firmware version?

1900 · September 26, 2019, 11:10pm

My hab has these (every 10 mins) and worked for a week
I restarted the binding after it would not connect
it works again after the restart but may have caused a bunch of couldnt stop http thread errors

2019-09-26 01:37:18.955 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-09-26 01:37:18.958 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 33025
2019-09-26 01:47:18.962 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-09-26 01:47:18.964 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 45845
2019-09-26 01:57:18.965 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-09-26 01:57:18.969 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 36473
2019-09-26 02:07:18.971 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-09-26 02:07:18.973 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 34059

tonight openhab couldnt control it.

so i restarted the binding (via exec binding)

2019-09-26 18:55:42.511 [INFO ] [script.openhabclientTrigger exec ON ] - Run:bundle:restart org.openhab.binding.harmonyhub
2019-09-26 18:55:52.051 [DEBUG] [org.openhab.binding.harmonyhub ] - BundleEvent STOPPING - org.openhab.binding.harmonyhub
2019-09-26 18:55:52.061 [DEBUG] [org.openhab.binding.harmonyhub ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory, org.eclipse.smarthome.core.thing.type.ChannelTypeProvider}={service.id=368, service.bundleid=346, service.scope=bundle, component.name=org.openhab.binding.harmonyhub.internal.HarmonyHubHandlerFactory, component.id=219} - org.openhab.binding.harmonyhub
2019-09-26 18:55:52.204 [DEBUG] [org.openhab.binding.harmonyhub ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=377, service.bundleid=346, service.scope=singleton} - org.openhab.binding.harmonyhub
2019-09-26 18:55:52.263 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at 192.168.25.131
java.io.IOException: java.nio.channels.AsynchronousCloseException
at com.digitaldan.harmony.HarmonyClient.getDiscoveryFromHost(HarmonyClient.java:186) ~[?:?]
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:122) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-09-26 18:55:52.270 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-26 18:55:57.268 [WARN ] [core.thing.internal.ThingManagerImpl] - Disposing handler for thing ‘harmonyhub:hub:Back’ takes more than 5000ms.
2019-09-26 18:55:57.274 [DEBUG] [org.openhab.binding.harmonyhub ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=372, service.bundleid=346, service.scope=singleton} - org.openhab.binding.harmonyhub
2019-09-26 18:55:57.290 [DEBUG] [org.openhab.binding.harmonyhub ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=369, service.bundleid=346, service.scope=bundle, component.name=org.openhab.binding.harmonyhub.internal.discovery.HarmonyHubDiscoveryService, component.id=220} - org.openhab.binding.harmonyhub
2019-09-26 18:55:57.295 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Stop HarmonyHub background discovery
2019-09-26 18:55:57.319 [DEBUG] [org.openhab.binding.harmonyhub ] - BundleEvent STOPPED - org.openhab.binding.harmonyhub
2019-09-26 18:55:57.323 [DEBUG] [org.openhab.binding.harmonyhub ] - BundleEvent STARTING - org.openhab.binding.harmonyhub
2019-09-26 18:55:57.327 [DEBUG] [org.openhab.binding.harmonyhub ] - BundleEvent STARTED - org.openhab.binding.harmonyhub
2019-09-26 18:55:57.341 [DEBUG] [org.openhab.binding.harmonyhub ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory, org.eclipse.smarthome.core.thing.type.ChannelTypeProvider}={service.id=517, service.bundleid=346, service.scope=bundle, component.name=org.openhab.binding.harmonyhub.internal.HarmonyHubHandlerFactory, component.id=306} - org.openhab.binding.harmonyhub
2019-09-26 18:55:57.349 [DEBUG] [org.openhab.binding.harmonyhub ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=518, service.bundleid=346, service.scope=bundle, component.name=org.openhab.binding.harmonyhub.internal.discovery.HarmonyHubDiscoveryService, component.id=307} - org.openhab.binding.harmonyhub
2019-09-26 18:55:57.352 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Start Harmony Hub background discovery
2019-09-26 18:55:57.353 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Start Scan
2019-09-26 18:55:57.355 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-09-26 18:55:57.356 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 34063
2019-09-26 18:55:57.390 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - HarmonyDeviceDiscoveryService org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler@b010da
2019-09-26 18:55:57.394 [DEBUG] [org.openhab.binding.harmonyhub ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=519, service.bundleid=346, service.scope=singleton} - org.openhab.binding.harmonyhub
2019-09-26 18:55:57.423 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status UNKNOWN
2019-09-26 18:55:57.433 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - HarmonyDeviceDiscoveryService org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler@121dc0d
2019-09-26 18:55:57.444 [DEBUG] [org.openhab.binding.harmonyhub ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=520, service.bundleid=346, service.scope=singleton} - org.openhab.binding.harmonyhub
2019-09-26 18:55:57.491 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status UNKNOWN
2019-09-26 18:55:57.822 [INFO ] [thome.model.script.openhabclient_Run] - Result:
2019-09-26 18:55:57.945 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192******9
2019-09-26 18:55:58.363 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192******1
2019-09-26 18:55:58.395 [INFO ] [thome.model.script.openhabclient_Run] - Runmore:bundle:status org.openhab.binding.harmonyhub
2019-09-26 18:55:59.317 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-09-26 18:55:59.319 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Frontroom
2019-09-26 18:55:59.802 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-09-26 18:55:59.804 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Back
2019-09-26 18:56:01.072 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48514647:buttonPress
2019-09-26 18:56:01.078 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48515108:buttonPress
2019-09-26 18:56:01.083 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48514648:buttonPress
2019-09-26 18:56:01.088 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48514646:buttonPress
2019-09-26 18:56:01.093 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784988:buttonPress
2019-09-26 18:56:01.097 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784987:buttonPress
2019-09-26 18:56:01.101 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785978:buttonPress
2019-09-26 18:56:01.105 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785142:buttonPress
2019-09-26 18:56:01.109 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785063:buttonPress
2019-09-26 18:56:01.119 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785011:buttonPress
2019-09-26 18:56:02.637 [INFO ] [thome.model.script.openhabclient_Run] - Result:Active
2019-09-26 18:56:22.284 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@224971]@1402d2f{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@224971-221219,5,main]
2019-09-26 18:56:22.287 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@224971]@1402d2f{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@224971-221216,5,main]
2019-09-26 18:56:22.290 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@224971]@1402d2f{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@224971-221266,5,main]
2019-09-26 18:56:22.292 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@224971]@1402d2f{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@224971-221120,5,main]
2019-09-26 18:56:22.295 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@224971]@1402d2f{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@224971-221192,5,main]
2019-09-26 18:56:22.297 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@224971]@1402d2f{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@224971-221307,5,main]
2019-09-26 18:56:22.300 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@224971]@1402d2f{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@224971-221227,5,main]
2019-09-26 18:56:22.303 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@224971]@1402d2f{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@224971-221158,5,main]
2019-09-26 18:56:22.305 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@224971]@1402d2f{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@224971-221119,5,main]
2019-09-26 18:56:22.308 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@224971]@1402d2f{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@224971-221184,5,main]
2019-09-26 18:56:22.310 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@224971]@1402d2f{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@224971-221260,5,main]
2019-09-26 18:56:22.312 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@224971]@1402d2f{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@224971-221153,5,main]
2019-09-26 18:56:22.315 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@224971]@1402d2f{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@224971-221121,5,main]

digitaldan · September 27, 2019, 4:12am

I’ll take a look, thanks. When the binding was not working, and before you restarted, did you see any errors in the logs?

1900 · September 29, 2019, 11:58am

2019-09-28 19:43:34.082 [INFO ] [e.smarthome.model.script.dodirectvtv] - Perform dodirectvtv Rule setting ON
2019-09-28 19:43:34.093 [INFO ] [ipse.smarthome.model.script.doLights] - Perform on-off - DirecTV setting ON DirecTV=ON DirecTV (Type=SwitchItem, State=ON, Label=DirecTV, Category=null, Tags=[Switchable], Groups=[FamilyRoom, Lights])
2019-09-28 19:43:34.138 [WARN ] [b.internal.handler.HarmonyHubHandler] - Cannot send command ‘DirecTV’ on harmonyhub:hub:Back:currentActivity because HarmonyClient is not connected
2019-09-28 19:44:13.876 [INFO ] [e.smarthome.model.script.dodirectvtv] - Perform dodirectvtv Rule setting ON
2019-09-28 19:44:13.887 [INFO ] [ipse.smarthome.model.script.doLights] - Perform on-off - DirecTV setting ON DirecTV=ON DirecTV (Type=SwitchItem, State=ON, Label=DirecTV, Category=null, Tags=[Switchable], Groups=[FamilyRoom, Lights])
2019-09-28 19:44:13.930 [WARN ] [b.internal.handler.HarmonyHubHandler] - Cannot send command ‘DirecTV’ on harmonyhub:hub:Back:currentActivity because HarmonyClient is not connected
2019-09-28 19:44:34.834 [INFO ] [rnal.service.RemoteControllerService] - Using WebSocket interface
2019-09-28 19:45:20.053 [INFO ] [clipse.smarthome.model.script.Office] - sensor_1_motion changed ON
2019-09-28 19:45:20.084 [INFO ] [clipse.smarthome.model.script.Office] - sensor_1_motion ON light should be on
2019-09-28 19:45:20.121 [INFO ] [ipse.smarthome.model.script.doLights] - Perform on-off - Office setting ON DeskSwitch=ON DeskSwitch (Type=SwitchItem, State=ON, Label=Office, Category=null, Tags=[thing=zwave:device:1685e5947ba:node3, Lighting], Groups=[AllItems, Office, Lights])
2019-09-28 19:45:20.125 [INFO ] [model.script.MemberofAllItemschanged] - DeskSwitch=ON previousState=OFF DeskSwitch (Type=SwitchItem, State=ON, Label=Office, Category=null, Tags=[thing=zwave:device:1685e5947ba:node3, Lighting], Groups=[AllItems, Office, Lights])
2019-09-28 19:46:50.601 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-09-28 19:46:50.605 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 35027
2019-09-28 19:47:19.445 [INFO ] [clipse.smarthome.model.script.Office] - sensor_1_motion changed OFF
2019-09-28 19:49:51.676 [WARN ] [org.eclipse.jetty.io.ManagedSelector] - java.nio.channels.ClosedSelectorException
2019-09-28 19:49:51.679 [WARN ] [l.protocol.RemoteControllerWebSocket] - WebSocketClient failure: java.lang.InterruptedException
2019-09-28 19:56:50.607 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-09-28 19:56:50.610 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 35003
2019-09-28 19:58:28.179 [INFO ] [model.script.MemberofAllItemschanged] - DeskSwitch=OFF previousState=ON DeskSwitch (Type=SwitchItem, State=OFF, Label=Office, Category=null, Tags=[thing=zwave:device:1685e5947ba:node3, Lighting], Groups=[AllItems, Office, Lights])
2019-09-28 19:58:28.195 [INFO ] [ipse.smarthome.model.script.doLights] - Perform on-off - Office setting OFF DeskSwitch=OFF DeskSwitch (Type=SwitchItem, State=OFF, Label=Office, Category=null, Tags=[thing=zwave:device:1685e5947ba:node3, Lighting], Groups=[AllItems, Office, Lights])
2019-09-28 20:06:50.612 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-09-28 20:06:50.615 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 38257
2019-09-28 20:08:17.991 [INFO ] [lipse.smarthome.model.script.Weather] - Temperature evolved of -2.178 degrees.
2019-09-28 20:16:50.617 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-09-28 20:16:50.620 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 46467

1900 · September 29, 2019, 11:59am

2019-09-28 09:34:43.195 [INFO ] [rnal.service.RemoteControllerService] - Using None interface
2019-09-28 09:34:43.204 [INFO ] [rnal.service.RemoteControllerService] - Using None interface
2019-09-28 09:36:50.244 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-09-28 09:36:50.247 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 36665
2019-09-28 09:38:25.833 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-28 09:39:25.821 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.131
2019-09-28 09:39:25.826 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{FAILED,8<=0<=200,i=0,r=-1,q=228}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$170/970967@1336977
2019-09-28 09:39:25.829 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at 192.168.25.131
java.io.IOException: org.eclipse.jetty.io.ManagedSelector$$Lambda$170/970967@1336977
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:119) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-09-28 09:39:25.842 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-28 09:40:36.206 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.131
2019-09-28 09:40:36.484 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-09-28 09:40:36.486 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Back
2019-09-28 09:40:36.503 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating current activity to PowerOff
2019-09-28 09:40:41.665 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784987:buttonPress
2019-09-28 09:40:41.682 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784988:buttonPress
2019-09-28 09:40:41.698 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785978:buttonPress
2019-09-28 09:40:41.715 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785142:buttonPress
2019-09-28 09:40:41.730 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785063:buttonPress
2019-09-28 09:40:42.114 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785011:buttonPress
2019-09-28 09:40:42.122 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating harmonyhub:hub:Back:currentActivity
2019-09-28 09:41:28.011 [INFO ] [model.script.MemberofAllItemschanged] - DeskSwitch=OFF previousState=ON DeskSwitch (Type=SwitchItem, State=OFF, Label=Office, Category=null, Tags=[thing=zwave:device:1685e5947ba:node3, Lighting], Groups=[AllItems, Office, Lights])
2019-09-28 09:41:28.016 [INFO ] [ipse.smarthome.model.script.doLights] - Perform on-off - Office setting OFF DeskSwitch=OFF DeskSwitch (Type=SwitchItem, State=OFF, Label=Office, Category=null, Tags=[thing=zwave:device:1685e5947ba:node3, Lighting], Groups=[AllItems, Office, Lights])
2019-09-28 09:43:46.597 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-28 09:44:46.589 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.131
2019-09-28 09:44:46.594 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{FAILED,8<=0<=200,i=0,r=-1,q=241}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$170/970967@1ea9df1
2019-09-28 09:44:46.597 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at 192.168.25.131
java.io.IOException: org.eclipse.jetty.io.ManagedSelector$$Lambda$170/970967@1ea9df1
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:119) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-09-28 09:44:46.609 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-28 09:45:56.850 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.131
2019-09-28 09:45:57.159 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-09-28 09:45:57.161 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Back
2019-09-28 09:45:57.177 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating current activity to PowerOff
2019-09-28 09:46:02.346 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784988:buttonPress
2019-09-28 09:46:02.356 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784987:buttonPress
2019-09-28 09:46:02.368 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785978:buttonPress
2019-09-28 09:46:02.378 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785063:buttonPress
2019-09-28 09:46:02.389 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785142:buttonPress
2019-09-28 09:46:02.399 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785011:buttonPress
2019-09-28 09:46:02.409 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating harmonyhub:hub:Back:currentActivity
2019-09-28 09:46:50.250 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-09-28 09:46:50.253 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 34191
2019-09-28 09:51:26.220 [INFO ] [model.script.MemberofAllItemschanged] - BathroomFan=OFF previousState=ON BathroomFan (Type=SwitchItem, State=OFF, Label=Bathroom Fan, Category=null, Tags=[Fan, thing=zwave:device:1685e5947ba:node30, Switchable], Groups=[AllItems, FamilyRoom, Fan])
2019-09-28 09:51:47.405 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-28 09:52:47.395 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.131
2019-09-28 09:52:47.400 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{FAILED,8<=0<=200,i=0,r=-1,q=257}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$170/970967@153fd72
2019-09-28 09:52:47.402 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at 192.168.25.131
java.io.IOException: org.eclipse.jetty.io.ManagedSelector$$Lambda$170/970967@153fd72
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:119) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-09-28 09:52:47.412 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-28 09:53:57.925 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.131
2019-09-28 09:53:58.197 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-09-28 09:53:58.199 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Back
2019-09-28 09:53:58.241 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating current activity to PowerOff
2019-09-28 09:54:03.372 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784988:buttonPress
2019-09-28 09:54:03.383 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785142:buttonPress
2019-09-28 09:54:03.394 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784987:buttonPress
2019-09-28 09:54:03.405 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785978:buttonPress
2019-09-28 09:54:03.416 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785063:buttonPress
2019-09-28 09:54:03.426 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785011:buttonPress
2019-09-28 09:54:03.437 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating harmonyhub:hub:Back:currentActivity
2019-09-28 09:56:50.256 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-09-28 09:56:50.258 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 41243
2019-09-28 09:59:48.373 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-28 10:00:48.365 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.131
2019-09-28 10:00:48.370 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{FAILED,8<=0<=200,i=0,r=-1,q=271}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$170/970967@1e7c107
2019-09-28 10:00:48.373 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at 192.168.25.131
java.io.IOException: org.eclipse.jetty.io.ManagedSelector$$Lambda$170/970967@1e7c107
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:119) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-09-28 10:00:48.386 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-28 10:01:58.900 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.131
2019-09-28 10:01:59.185 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-09-28 10:01:59.188 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Back
2019-09-28 10:01:59.211 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating current activity to PowerOff
2019-09-28 10:02:04.372 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784988:buttonPress
2019-09-28 10:02:04.385 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784987:buttonPress
2019-09-28 10:02:04.395 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785978:buttonPress
2019-09-28 10:02:04.405 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785142:buttonPress
2019-09-28 10:02:04.415 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785011:buttonPress
2019-09-28 10:02:04.426 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785063:buttonPress
2019-09-28 10:02:04.437 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating harmonyhub:hub:Back:currentActivity
2019-09-28 10:06:50.261 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-09-28 10:06:50.264 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 37525

1900 · September 29, 2019, 12:00pm

2019-09-28 10:26:50.280 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 37177
2019-09-28 10:31:52.360 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-28 10:32:52.354 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.131
2019-09-28 10:32:52.358 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{FAILED,8<=0<=200,i=0,r=-1,q=326}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$170/970967@1868076
2019-09-28 10:32:52.361 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at 192.168.25.131
java.io.IOException: org.eclipse.jetty.io.ManagedSelector$$Lambda$170/970967@1868076
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:119) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-09-28 10:32:52.371 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-28 10:33:52.369 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.131
2019-09-28 10:33:52.572 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-09-28 10:33:52.575 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Back
2019-09-28 10:33:52.576 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784988:buttonPress
2019-09-28 10:33:52.589 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784987:buttonPress
2019-09-28 10:33:52.598 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785142:buttonPress
2019-09-28 10:33:52.607 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785063:buttonPress
2019-09-28 10:33:52.616 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785978:buttonPress
2019-09-28 10:33:52.624 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating current activity to PowerOff
2019-09-28 10:33:52.625 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785011:buttonPress
2019-09-28 10:33:52.635 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating harmonyhub:hub:Back:currentActivity
2019-09-28 10:36:50.284 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-09-28 10:36:50.287 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 34407
2019-09-28 10:37:58.658 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-28 10:38:58.650 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.131
2019-09-28 10:38:58.655 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{FAILED,8<=0<=200,i=0,r=-1,q=341}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$170/970967@fd3b6e
2019-09-28 10:38:58.658 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at 192.168.25.131
java.io.IOException: org.eclipse.jetty.io.ManagedSelector$$Lambda$170/970967@fd3b6e
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:119) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-09-28 10:38:58.669 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-28 10:40:03.860 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.131
2019-09-28 10:40:34.003 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37282,5,main]
2019-09-28 10:40:34.007 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37289,5,main]
2019-09-28 10:40:34.010 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37288,5,main]
2019-09-28 10:40:34.013 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37230,5,main]
2019-09-28 10:40:34.017 [WARN ] [e.jetty.util.thread.QueuedThreadPool] -

…

[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37327,5,main]
2019-09-28 10:40:34.223 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37290,5,main]
2019-09-28 10:40:34.224 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37317,5,main]
2019-09-28 10:40:34.225 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37268,5,main]
2019-09-28 10:40:34.226 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37271,5,main]
2019-09-28 10:40:34.228 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37272,5,main]
2019-09-28 10:40:34.229 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37186,5,main]
2019-09-28 10:40:34.230 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37294,5,main]
2019-09-28 10:40:34.232 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37297,5,main]
2019-09-28 10:40:34.233 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37313,5,main]
2019-09-28 10:40:34.234 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37229,5,main]
2019-09-28 10:40:34.235 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37181,5,main]
2019-09-28 10:40:34.236 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37224,5,main]
2019-09-28 10:40:34.237 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37248,5,main]
2019-09-28 10:40:34.238 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37309,5,main]
2019-09-28 10:40:34.239 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37214,5,main]
2019-09-28 10:40:34.240 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37270,5,main]
2019-09-28 10:40:34.241 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37179,5,main]
2019-09-28 10:40:34.242 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37321,5,main]
2019-09-28 10:40:34.243 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37182,5,main]
2019-09-28 10:40:34.244 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37217,5,main]
2019-09-28 10:40:34.245 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37220,5,main]
2019-09-28 10:40:34.246 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37192,5,main]
2019-09-28 10:40:34.247 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37244,5,main]
2019-09-28 10:40:34.248 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=165<=200,i=0,r=-1,q=165}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-37338,5,main]
2019-09-28 10:40:34.300 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at 192.168.25.131
java.io.IOException: Insufficient configured threads: required=200 < max=200 for QueuedThreadPool[HttpClient@1df9e50]@1e91160{STARTED,8<=168<=200,i=1,r=4,q=0}[ReservedThreadExecutor@2166ed{s=0/4,p=0}]
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:119) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-09-28 10:40:34.307 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-28 10:41:35.603 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.131
2019-09-28 10:41:35.848 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-09-28 10:41:35.850 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Back
2019-09-28 10:41:35.873 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating current activity to PowerOff
2019-09-28 10:41:35.944 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784988:buttonPress
2019-09-28 10:41:35.954 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784987:buttonPress
2019-09-28 10:41:35.963 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785142:buttonPress
2019-09-28 10:41:35.970 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785978:buttonPress
2019-09-28 10:41:35.978 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785011:buttonPress
2019-09-28 10:41:35.989 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785063:buttonPress
2019-09-28 10:41:35.996 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating harmonyhub:hub:Back:currentActivity
2019-09-28 10:43:56.015 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-28 10:44:56.101 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.131
2019-09-28 10:44:56.105 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{FAILED,8<=0<=200,i=0,r=-1,q=358}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$170/970967@11d2cd7
2019-09-28 10:44:56.108 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at 192.168.25.131
java.io.IOException: org.eclipse.jetty.io.ManagedSelector$$Lambda$170/970967@11d2cd7
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:119) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-09-28 10:44:56.118 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-28 10:46:10.769 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.131
2019-09-28 10:46:11.052 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-09-28 10:46:11.054 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Back

the prev were just while running (and flailing )

this is a bundle restart - and a reconnect - yay -

2019-09-29 08:01:16.781 [INFO ] [script.openhabclientTrigger exec ON ] - Run:bundle:restart org.openhab.binding.harmonyhub
2019-09-29 08:01:22.477 [DEBUG] [org.openhab.binding.harmonyhub ] - BundleEvent STOPPING - org.openhab.binding.harmonyhub
2019-09-29 08:01:22.485 [DEBUG] [org.openhab.binding.harmonyhub ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory, org.eclipse.smarthome.core.thing.type.ChannelTypeProvider}={service.id=378, service.bundleid=346, service.scope=bundle, component.name=org.openhab.binding.harmonyhub.internal.HarmonyHubHandlerFactory, component.id=219} - org.openhab.binding.harmonyhub
2019-09-29 08:01:22.632 [DEBUG] [org.openhab.binding.harmonyhub ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=380, service.bundleid=346, service.scope=singleton} - org.openhab.binding.harmonyhub
2019-09-29 08:01:22.689 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at 192.168.25.131
java.io.IOException: java.nio.channels.AsynchronousCloseException
at com.digitaldan.harmony.HarmonyClient.getDiscoveryFromHost(HarmonyClient.java:186) ~[?:?]
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:122) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-09-29 08:01:22.696 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-09-29 08:01:27.697 [WARN ] [core.thing.internal.ThingManagerImpl] - Disposing handler for thing ‘harmonyhub:hub:Back’ takes more than 5000ms.
2019-09-29 08:01:27.711 [DEBUG] [org.openhab.binding.harmonyhub ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=379, service.bundleid=346, service.scope=singleton} - org.openhab.binding.harmonyhub
2019-09-29 08:01:27.736 [DEBUG] [org.openhab.binding.harmonyhub ] - ServiceEvent UNREGISTERING - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=381, service.bundleid=346, service.scope=bundle, component.name=org.openhab.binding.harmonyhub.internal.discovery.HarmonyHubDiscoveryService, component.id=220} - org.openhab.binding.harmonyhub
2019-09-29 08:01:27.748 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Stop HarmonyHub background discovery
2019-09-29 08:01:27.759 [DEBUG] [org.openhab.binding.harmonyhub ] - BundleEvent STOPPED - org.openhab.binding.harmonyhub
2019-09-29 08:01:27.771 [DEBUG] [org.openhab.binding.harmonyhub ] - BundleEvent STARTING - org.openhab.binding.harmonyhub
2019-09-29 08:01:27.812 [DEBUG] [org.openhab.binding.harmonyhub ] - BundleEvent STARTED - org.openhab.binding.harmonyhub
2019-09-29 08:01:27.831 [DEBUG] [org.openhab.binding.harmonyhub ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory, org.eclipse.smarthome.core.thing.type.ChannelTypeProvider}={service.id=492, service.bundleid=346, service.scope=bundle, component.name=org.openhab.binding.harmonyhub.internal.HarmonyHubHandlerFactory, component.id=286} - org.openhab.binding.harmonyhub
2019-09-29 08:01:27.844 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - HarmonyDeviceDiscoveryService org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler@50cb9e
2019-09-29 08:01:27.847 [DEBUG] [org.openhab.binding.harmonyhub ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=493, service.bundleid=346, service.scope=singleton} - org.openhab.binding.harmonyhub
2019-09-29 08:01:27.872 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status UNKNOWN
2019-09-29 08:01:27.889 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - HarmonyDeviceDiscoveryService org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler@11c61
2019-09-29 08:01:27.893 [DEBUG] [org.openhab.binding.harmonyhub ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=494, service.bundleid=346, service.scope=singleton} - org.openhab.binding.harmonyhub
2019-09-29 08:01:27.916 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status UNKNOWN
2019-09-29 08:01:27.931 [DEBUG] [org.openhab.binding.harmonyhub ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=495, service.bundleid=346, service.scope=bundle, component.name=org.openhab.binding.harmonyhub.internal.discovery.HarmonyHubDiscoveryService, component.id=287} - org.openhab.binding.harmonyhub
2019-09-29 08:01:27.932 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.129
2019-09-29 08:01:27.935 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Start Harmony Hub background discovery
2019-09-29 08:01:27.937 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Start Scan
2019-09-29 08:01:27.941 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-09-29 08:01:27.942 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 46301
2019-09-29 08:01:28.401 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host 192.168.25.131
2019-09-29 08:01:29.780 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-09-29 08:01:29.781 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Frontroom
2019-09-29 08:01:29.784 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48514647:buttonPress
2019-09-29 08:01:29.790 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48514646:buttonPress
2019-09-29 08:01:29.794 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48514648:buttonPress
2019-09-29 08:01:29.795 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48515108:buttonPress
2019-09-29 08:01:29.800 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating harmonyhub:hub:Frontroom:currentActivity
2019-09-29 08:01:29.837 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating current activity to PowerOff
2019-09-29 08:01:30.331 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784988:buttonPress
2019-09-29 08:01:30.339 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784987:buttonPress
2019-09-29 08:01:30.341 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-09-29 08:01:30.342 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Back
2019-09-29 08:01:30.554 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785978:buttonPress
2019-09-29 08:01:30.556 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785011:buttonPress
2019-09-29 08:01:30.571 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785142:buttonPress
2019-09-29 08:01:30.571 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785063:buttonPress
2019-09-29 08:01:30.584 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating harmonyhub:hub:Back:currentActivity
2019-09-29 08:01:30.701 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating current activity to DirecTV
2019-09-29 08:01:52.713 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-38872,5,main]
2019-09-29 08:01:52.716 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-38740,5,main]
2019-09-29 08:01:52.719 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-38761,5,main]
2019-09-29 08:01:52.721 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1df9e50]@1e91160{STOPPING,8<=198<=200,i=0,r=-1,q=199}[NO_TRY] Couldn’t stop Thread[HttpClient@1df9e50-38877,5,main]

digitaldan · October 5, 2019, 8:37pm

Hi, i made a small change to use the built in jetty client in OH, which may help. Can you try using a test version i have posted here ?

EznBN · October 7, 2019, 5:57pm

Hi - I have the same problem and I would love to test it - except I dont know how to. I downloaded the jar file to /usr/share/openhab2/addons (ref: Location of addons folder ) and OpenHAB only found the old/original binding binding-harmonyhub - 2.5.0.M2

(I also double-checked IP address in case that is an issue - my Hub is allocated fixed IP address in the network so it should not be the issue)

EznBN · October 14, 2019, 7:41pm

ok eventually I managed to work out how to do this…These are the steps I took

removed Harmony related Things
To uninstall the existing binding - I went into the console to remove it:

ssh -p 8101 openhab@localhost
default password = habopen
bundle:list |grep armony
bundle:uninstall xxx (the first digits of the returned line above - where you see the line number for line with Harmony/harmony)
logout

Did the steps to clear cache

sudo systemctl stop openhab2
sudo openhab-cli clean-cache
sudo reboot

after reboot - checked Paper UI - Things and Binding to make sure Harmony Binding is gone
Go to /usr/share/openhab2/addons (or the appropriate addon directory as per https://www.openhab.org/docs/installation/linux.html#file-locations )
sudo wget https://github.com/digitaldan/openhab2-addons/releases/download/harmony-2.5-jetty-fix-1/org.openhab.binding.harmonyhub-2.5.0-SNAPSHOT.jar
OpenHab automatically detects the new binding, go to Paper UI, and you should be able to add your Harmony Hub from the Inbox
I had to go to Configuration -> Things to enable the Harmony Hub (the alarm clock icon)
Done

if you want to confirm the version:
10. go back to console again (see Step2)
11. bundle:list |grep armony
12. you should see the version - I have “2.5.0.201909271708” - 20190927 should be the date Dan posted the update

Good luck, if you are reading this

Andrew_Pawelski · October 15, 2019, 10:38pm

@digitaldan Ive got the jar installed - ive found an uninstall and reinstall fixed the problem for the immediate - will monitor for a while and feed back if any issues.

Celaeno1 · October 19, 2019, 3:51pm

@digitaldan

I’ve tried your test version also, but I get about 20-25 times a day:

Could not connect: Disconnected

2019-10-18 22:04:11.651 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected
2019-10-18 23:39:53.306 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected
2019-10-19 01:23:28.346 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected
2019-10-19 02:58:48.779 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected
2019-10-19 03:16:37.964 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected
2019-10-19 03:21:57.031 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected
2019-10-19 07:46:07.274 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected
2019-10-19 07:52:58.235 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected
2019-10-19 08:44:26.070 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected
2019-10-19 10:24:58.024 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected
2019-10-19 10:35:18.302 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected
2019-10-19 11:15:36.411 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected
2019-10-19 13:48:15.094 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected
2019-10-19 13:58:34.513 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected
2019-10-19 15:07:24.213 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected
2019-10-19 17:24:13.718 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected
2019-10-19 17:29:33.051 [hingStatusInfoChangedEvent] - 'harmonyhub:hub:HarmonyHub' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect: Disconnected

One minute later it connects again. OK That’s a feature, I know about it. But how to get rid of these errors?

1900 · October 22, 2019, 12:31am

After M4 - not better ( not sure if your http threading? commit made it into M4 )

( I had some stability under M3 - I deleted the things and then added Bridge definition in a things file - )

This is from M4

2019-10-21 15:31:29.796 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-10-21 15:31:29.800 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 33071
2019-10-21 15:32:46.273 [INFO ] [lipse.smarthome.model.script.Weather] - Temperature evolved of 0.44 degrees.
2019-10-21 15:41:29.804 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-10-21 15:41:29.807 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 40307
2019-10-21 15:51:29.810 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-10-21 15:51:29.812 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 37451
2019-10-21 15:58:55.751 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-10-21 15:59:55.737 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host Harmonyfront
2019-10-21 15:59:55.741 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1a06430]@14a73ef{FAILED,8<=0<=200,i=0,r=-1,q=73}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$169/14589188@139bbca
2019-10-21 15:59:55.744 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at Harmonyfront
java.io.IOException: org.eclipse.jetty.io.ManagedSelector$$Lambda$169/14589188@139bbca
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:119) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-10-21 15:59:55.757 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-10-21 16:00:16.002 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-10-21 16:01:12.219 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host Harmonyfront
2019-10-21 16:01:12.487 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-10-21 16:01:12.489 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Frontroom
2019-10-21 16:01:12.497 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating current activity to PowerOff
2019-10-21 16:01:12.821 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48514647:buttonPress
2019-10-21 16:01:12.826 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48514648:buttonPress
2019-10-21 16:01:12.831 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48514646:buttonPress
2019-10-21 16:01:12.836 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48515108:buttonPress
2019-10-21 16:01:12.841 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating harmonyhub:hub:Frontroom:currentActivity
2019-10-21 16:01:15.991 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host HarmonyBack
2019-10-21 16:01:15.995 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@126b46f]@1fdc24d{FAILED,8<=0<=200,i=0,r=-1,q=89}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$169/14589188@1e15a78
2019-10-21 16:01:15.997 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at HarmonyBack
java.io.IOException: org.eclipse.jetty.io.ManagedSelector$$Lambda$169/14589188@1e15a78
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:119) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-10-21 16:01:16.009 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-10-21 16:01:29.813 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-10-21 16:01:29.816 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 40345
2019-10-21 16:02:32.374 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host HarmonyBack
2019-10-21 16:02:32.676 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-10-21 16:02:32.677 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Back
2019-10-21 16:02:32.687 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating current activity to PowerOff
2019-10-21 16:02:33.032 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784988:buttonPress
2019-10-21 16:02:33.037 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785978:buttonPress
2019-10-21 16:02:33.041 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785142:buttonPress
2019-10-21 16:02:33.045 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785011:buttonPress
2019-10-21 16:02:33.049 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785063:buttonPress
2019-10-21 16:02:33.053 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784987:buttonPress
2019-10-21 16:02:33.057 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating harmonyhub:hub:Back:currentActivity
2019-10-21 16:04:22.560 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-10-21 16:05:22.549 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host Harmonyfront
2019-10-21 16:05:22.553 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1a06430]@14a73ef{FAILED,8<=0<=200,i=0,r=-1,q=88}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$169/14589188@11ee528
2019-10-21 16:05:22.556 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at Harmonyfront
java.io.IOException: org.eclipse.jetty.io.ManagedSelector$$Lambda$169/14589188@11ee528
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:119) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-10-21 16:05:22.567 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-10-21 16:05:42.744 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-10-21 16:06:33.073 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host Harmonyfront
2019-10-21 16:06:33.214 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-10-21 16:06:33.234 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Frontroom
2019-10-21 16:06:33.243 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating current activity to PowerOff
2019-10-21 16:06:33.374 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48514648:buttonPress
2019-10-21 16:06:33.380 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48514646:buttonPress
2019-10-21 16:06:33.385 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48515108:buttonPress
2019-10-21 16:06:33.391 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48514647:buttonPress
2019-10-21 16:06:33.444 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating harmonyhub:hub:Frontroom:currentActivity
2019-10-21 16:06:42.737 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host HarmonyBack
2019-10-21 16:06:42.741 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@126b46f]@1fdc24d{FAILED,8<=0<=200,i=0,r=-1,q=100}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$169/14589188@66ae3a
2019-10-21 16:06:42.744 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at HarmonyBack
java.io.IOException: org.eclipse.jetty.io.ManagedSelector$$Lambda$169/14589188@66ae3a
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:119) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-10-21 16:06:42.755 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-10-21 16:07:53.280 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host HarmonyBack
2019-10-21 16:07:53.481 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-10-21 16:07:53.482 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Back
2019-10-21 16:07:53.491 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating current activity to PowerOff
2019-10-21 16:07:53.625 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784988:buttonPress
2019-10-21 16:07:53.630 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784987:buttonPress
2019-10-21 16:07:53.634 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785978:buttonPress
2019-10-21 16:07:53.634 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785063:buttonPress
2019-10-21 16:07:53.638 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785011:buttonPress
2019-10-21 16:07:53.638 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785142:buttonPress
2019-10-21 16:07:53.642 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating harmonyhub:hub:Back:currentActivity
2019-10-21 16:09:43.325 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-10-21 16:10:43.315 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host Harmonyfront
2019-10-21 16:10:43.319 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1a06430]@14a73ef{FAILED,8<=0<=200,i=0,r=-1,q=102}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$169/14589188@6422da
2019-10-21 16:10:43.326 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at Harmonyfront
java.io.IOException: org.eclipse.jetty.io.ManagedSelector$$Lambda$169/14589188@6422da
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:119) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-10-21 16:10:43.337 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-10-21 16:11:03.595 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-10-21 16:11:29.821 [DEBUG] [discovery.HarmonyHubDiscoveryService] - StartScan called
2019-10-21 16:11:29.824 [DEBUG] [discovery.HarmonyHubDiscoveryService] - Creating Harmony server on port 40699
2019-10-21 16:11:53.677 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host Harmonyfront
2019-10-21 16:11:53.838 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-10-21 16:11:53.840 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Frontroom
2019-10-21 16:11:53.855 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating current activity to PowerOff
2019-10-21 16:11:53.985 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48514647:buttonPress
2019-10-21 16:11:53.991 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48515108:buttonPress
2019-10-21 16:11:53.997 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48514648:buttonPress
2019-10-21 16:11:54.003 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Frontroom:48514646:buttonPress
2019-10-21 16:11:54.008 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating harmonyhub:hub:Frontroom:currentActivity
2019-10-21 16:12:03.591 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host HarmonyBack
2019-10-21 16:12:03.597 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@126b46f]@1fdc24d{FAILED,8<=0<=200,i=0,r=-1,q=116}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$169/14589188@19d0fe1
2019-10-21 16:12:03.600 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at HarmonyBack
java.io.IOException: org.eclipse.jetty.io.ManagedSelector$$Lambda$169/14589188@19d0fe1
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:119) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-10-21 16:12:03.614 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-10-21 16:13:13.864 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host HarmonyBack
2019-10-21 16:13:14.054 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-10-21 16:13:14.056 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Back
2019-10-21 16:13:14.066 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating current activity to PowerOff
2019-10-21 16:13:14.170 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784987:buttonPress
2019-10-21 16:13:14.176 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31784988:buttonPress
2019-10-21 16:13:14.181 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785063:buttonPress
2019-10-21 16:13:14.185 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785142:buttonPress
2019-10-21 16:13:14.189 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785978:buttonPress
2019-10-21 16:13:14.194 [DEBUG] [nternal.handler.HarmonyDeviceHandler] - Updating harmonyhub:device:Back:31785011:buttonPress
2019-10-21 16:13:14.198 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating harmonyhub:hub:Back:currentActivity
2019-10-21 16:15:03.958 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-10-21 16:16:03.945 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host Harmonyfront
2019-10-21 16:16:03.949 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@1a06430]@14a73ef{FAILED,8<=0<=200,i=0,r=-1,q=117}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$169/14589188@182f3e7
2019-10-21 16:16:03.953 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Could not connect to HarmonyHub at Harmonyfront
java.io.IOException: org.eclipse.jetty.io.ManagedSelector$$Lambda$169/14589188@182f3e7
at com.digitaldan.harmony.HarmonyClient.connect(HarmonyClient.java:119) ~[?:?]
at org.openhab.binding.harmonyhub.internal.handler.HarmonyHubHandler.connect(HarmonyHubHandler.java:281) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:?]
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) [?:?]
2019-10-21 16:16:03.965 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-10-21 16:16:24.140 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status OFFLINE
2019-10-21 16:17:14.345 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Connecting: host Harmonyfront
2019-10-21 16:17:14.516 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating listeners with status ONLINE
2019-10-21 16:17:14.518 [DEBUG] [covery.HarmonyDeviceDiscoveryService] - getting devices on Frontroom
2019-10-21 16:17:14.528 [DEBUG] [b.internal.handler.HarmonyHubHandler] - Updating current activity to PowerOff

scootaash · May 6, 2020, 4:37am

Hi. Did you manage to get this to work? I have a similar issue
thanks

Simon_Thorsted · March 2, 2021, 12:12pm

I have a similar issue too , anybody got Harmonyhub working

Andrew_Pawelski · March 3, 2021, 11:32am

I have harmony hub working on oh3