Wemo error

idkpmiller · January 8, 2018, 6:01am

I see the following error sporadically in the logs; any ideas?


==> /var/log/openhab2/openhab.log <==
2018-01-08 18:30:00.191 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.handleCommand()' on 'org.eclipse.smarthome.binding.wemo.handler.WemoLightHandler@5ad5d8': Could not send command to WeMo Bridge
java.lang.RuntimeException: Could not send command to WeMo Bridge
	at org.eclipse.smarthome.binding.wemo.handler.WemoLightHandler.handleCommand(WemoLightHandler.java:287) [212:org.eclipse.smarthome.binding.wemo:0.10.0.b1]
	at sun.reflect.GeneratedMethodAccessor163.invoke(Unknown Source) ~[?:?]
	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) [109:org.eclipse.smarthome.core:0.10.0.b1]
	at org.eclipse.smarthome.core.internal.common.InvocationHandlerSync.invoke(InvocationHandlerSync.java:59) [109:org.eclipse.smarthome.core:0.10.0.b1]
	at com.sun.proxy.$Proxy149.handleCommand(Unknown Source) [212:org.eclipse.smarthome.binding.wemo:0.10.0.b1]
	at org.eclipse.smarthome.core.thing.internal.profiles.ProfileCallbackImpl.handleCommand(ProfileCallbackImpl.java:72) [116:org.eclipse.smarthome.core.thing:0.10.0.b1]
	at org.eclipse.smarthome.core.thing.internal.profiles.SystemDefaultProfile.onCommandFromItem(SystemDefaultProfile.java:49) [116:org.eclipse.smarthome.core.thing:0.10.0.b1]
	at sun.reflect.GeneratedMethodAccessor162.invoke(Unknown Source) ~[?:?]
	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) [109:org.eclipse.smarthome.core:0.10.0.b1]
	at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [109:org.eclipse.smarthome.core:0.10.0.b1]
	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) [?:?]
Caused by: java.lang.RuntimeException: Could not call WeMo
	at org.eclipse.smarthome.binding.wemo.internal.http.WemoHttpCall.executeCall(WemoHttpCall.java:46) ~[?:?]
	at org.eclipse.smarthome.binding.wemo.handler.WemoLightHandler.handleCommand(WemoLightHandler.java:275) ~[?:?]
	... 17 more
Caused by: java.io.IOException: java.util.concurrent.ExecutionException: java.io.EOFException: HttpConnectionOverHTTP@1f3b5df(l:/192.168.0.4:34522 <-> r:/192.168.0.138:49154,closed=false)[HttpChannelOverHTTP@69bab5(exchange=HttpExchange@6e7471 req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@18882ab(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@1779cc8{s=START}],recv=HttpReceiverOverHTTP@2a28c1(rsp=IDLE,failure=null)[HttpParser{s=CLOSED,0 of -1}]]]
	at org.eclipse.smarthome.io.net.http.HttpUtil.executeUrlAndGetReponse(HttpUtil.java:253) ~[?:?]
	at org.eclipse.smarthome.io.net.http.HttpUtil.executeUrl(HttpUtil.java:157) ~[?:?]
	at org.eclipse.smarthome.io.net.http.HttpUtil.executeUrl(HttpUtil.java:130) ~[?:?]
	at org.eclipse.smarthome.binding.wemo.internal.http.WemoHttpCall.executeCall(WemoHttpCall.java:42) ~[?:?]
	at org.eclipse.smarthome.binding.wemo.handler.WemoLightHandler.handleCommand(WemoLightHandler.java:275) ~[?:?]
	... 17 more
Caused by: java.util.concurrent.ExecutionException: java.io.EOFException: HttpConnectionOverHTTP@1f3b5df(l:/192.168.0.4:34522 <-> r:/192.168.0.138:49154,closed=false)[HttpChannelOverHTTP@69bab5(exchange=HttpExchange@6e7471 req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@18882ab(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@1779cc8{s=START}],recv=HttpReceiverOverHTTP@2a28c1(rsp=IDLE,failure=null)[HttpParser{s=CLOSED,0 of -1}]]]
	at org.eclipse.jetty.client.util.FutureResponseListener.getResult(FutureResponseListener.java:118) ~[?:?]
	at org.eclipse.jetty.client.util.FutureResponseListener.get(FutureResponseListener.java:110) ~[?:?]
	at org.eclipse.jetty.client.HttpRequest.send(HttpRequest.java:676) ~[?:?]
	at org.eclipse.smarthome.io.net.http.HttpUtil.executeUrlAndGetReponse(HttpUtil.java:244) ~[?:?]
	at org.eclipse.smarthome.io.net.http.HttpUtil.executeUrl(HttpUtil.java:157) ~[?:?]
	at org.eclipse.smarthome.io.net.http.HttpUtil.executeUrl(HttpUtil.java:130) ~[?:?]
	at org.eclipse.smarthome.binding.wemo.internal.http.WemoHttpCall.executeCall(WemoHttpCall.java:42) ~[?:?]
	at org.eclipse.smarthome.binding.wemo.handler.WemoLightHandler.handleCommand(WemoLightHandler.java:275) ~[?:?]
	... 17 more
Caused by: java.io.EOFException: HttpConnectionOverHTTP@1f3b5df(l:/192.168.0.4:34522 <-> r:/192.168.0.138:49154,closed=false)[HttpChannelOverHTTP@69bab5(exchange=HttpExchange@6e7471 req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@18882ab(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@1779cc8{s=START}],recv=HttpReceiverOverHTTP@2a28c1(rsp=IDLE,failure=null)[HttpParser{s=CLOSED,0 of -1}]]]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.earlyEOF(HttpReceiverOverHTTP.java:300) ~[?:?]
	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:1392) ~[?:?]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.shutdown(HttpReceiverOverHTTP.java:185) ~[?:?]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.process(HttpReceiverOverHTTP.java:132) ~[?:?]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.receive(HttpReceiverOverHTTP.java:70) ~[?:?]
	at org.eclipse.jetty.client.http.HttpChannelOverHTTP.receive(HttpChannelOverHTTP.java:90) ~[?:?]
	at org.eclipse.jetty.client.http.HttpConnectionOverHTTP.onFillable(HttpConnectionOverHTTP.java:115) ~[?:?]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) ~[?:?]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108) ~[?:?]
	at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) ~[?:?]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) ~[?:?]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) ~[?:?]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) ~[?:?]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) ~[?:?]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) ~[?:?]
	... 1 more

Thanks

Paul

hmerk · January 9, 2018, 10:25pm

This is nothing to really worry about, as it is just telling that the WeMo device was not reachable for whatever reason.
I am in the middle of a major restructuring of the WeMo Binding, which should give it a better error handling and even support for additional devices.
Will post on a new thread once it is ready for testing.