Lutron OH2 binding

dan12345 · February 7, 2017, 1:45pm

thanks, Tom. I’ve been using something similar (but with Expect scripts). The really useful thing would be to be able to monitor Lutron keypads/scenes/etc too. I’m toying with scripts that achieve that, but it all gets a bit messy - clearly a case where a binding is the better approach.

Morridoor · February 7, 2017, 1:49pm

agreed.

I have yet to pull the trigger and download eclipse and start hacking away with java but have not had time.

Will update when I have!

actong88 · February 8, 2017, 8:34pm

Sorry for taking so long. I’ve built a jar that should account for the different prompt in HomeWorks QS, though I can’t test it myself. It’s available here if anyone wants to try it.

dan12345 · February 10, 2017, 11:40am

Many thanks, that’s brilliant.

Have started testing - getting somewhat confusing results which I’m trying to get on top of, but there is one more immediate issue.

The binding starts working fine - autodiscovery works as it should, and I can then add keypads as Things. But after a while the binding fails with an error and the bridge Thing goes offline. A few minutes later the bridge is autodiscovered again and appears in the inbox (but the original bridge Thing remains offline).

The logs look like this:

2017-02-09 18:24:27.197 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-09 18:24:27.197 [ERROR] [nding.lutron.handler.IPBridgeHandler] - Communication error, will try to reconnect
java.io.IOException: Could not write to stream
	at org.openhab.binding.lutron.internal.net.TelnetSession.writeLine(TelnetSession.java:218)[211:org.openhab.binding.lutron:2.1.0.201702082009]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.sendCommands(IPBridgeHandler.java:198)[211:org.openhab.binding.lutron:2.1.0.201702082009]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.access$2(IPBridgeHandler.java:190)[211:org.openhab.binding.lutron:2.1.0.201702082009]
	at org.openhab.binding.lutron.handler.IPBridgeHandler$3.run(IPBridgeHandler.java:176)[211:org.openhab.binding.lutron:2.1.0.201702082009]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-02-09 18:24:27.198 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Keepalive timeout, attempting to reconnect to the bridge
2017-02-09 18:24:27.200 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Disconnecting from bridge
2017-02-09 18:24:27.200 [ERROR] [nding.lutron.handler.IPBridgeHandler] - Error disconnecting
java.net.SocketException: Socket closed
	at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:118)[:1.8.0_121]
	at java.net.SocketOutputStream.write(SocketOutputStream.java:155)[:1.8.0_121]
	at java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:82)[:1.8.0_121]
	at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:140)[:1.8.0_121]
	at java.io.FilterOutputStream.close(FilterOutputStream.java:158)[:1.8.0_121]
	at org.apache.commons.net.telnet.TelnetClient._closeOutputStream(TelnetClient.java:86)[186:org.apache.commons.net:3.2.0]
	at org.apache.commons.net.telnet.TelnetOutputStream.close(TelnetOutputStream.java:155)[186:org.apache.commons.net:3.2.0]
	at org.apache.commons.net.telnet.TelnetClient.disconnect(TelnetClient.java:127)[186:org.apache.commons.net:3.2.0]
	at org.openhab.binding.lutron.internal.net.TelnetSession.close(TelnetSession.java:116)[211:org.openhab.binding.lutron:2.1.0.201702082009]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.disconnect(IPBridgeHandler.java:235)[211:org.openhab.binding.lutron:2.1.0.201702082009]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.reconnect(IPBridgeHandler.java:245)[211:org.openhab.binding.lutron:2.1.0.201702082009]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.sendCommands(IPBridgeHandler.java:206)[211:org.openhab.binding.lutron:2.1.0.201702082009]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.access$2(IPBridgeHandler.java:190)[211:org.openhab.binding.lutron:2.1.0.201702082009]
	at org.openhab.binding.lutron.handler.IPBridgeHandler$3.run(IPBridgeHandler.java:176)[211:org.openhab.binding.lutron:2.1.0.201702082009]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-02-09 18:37:07.508 [DEBUG] [RadioRA2MainRepeaterDiscoveryService] - Scanning for RadioRA2 main repeaters
2017-02-09 18:37:08.522 [DEBUG] [RadioRA2MainRepeaterDiscoveryService] - Discovered main repeater lutron:ipbridge:00DE1DFF

Any help you can provide would be much appreciated!

actong88 · February 10, 2017, 12:37pm

Yeah, the reconnect logic when the binding loses connection to the bridge needs some TLC. The log output helps, thanks. I’ll see what I can do to make that more robust.

actong88 · February 11, 2017, 8:12pm

The log shows that the binding lost communications with the bridge. I made new version of the binding that should hopefully allow it to recover from this situation. Note it only tries to reconnect once every 5 minutes.

dan12345 · February 12, 2017, 10:10pm

thank you!

Can I just check which version I should use? I would think v2, but that’s marked as the older of the two versions…

r27 · February 13, 2017, 1:52am

Can someone clarify which version of lutron bridge will work with lutron binding ? - I am looking to buy this one - Lutron P-BDG-PKG2W ?

davidger · February 13, 2017, 4:13am

That one will not work. For the Caseta line, you will need the Pro version of the SmartBridge that allows Telnet access to be enabled.

The pro version of the kit you are considering: P-BDGPRO-PKG1W
The hub only: L-BDGPRO-WH or L-BDGPRO2-WH

If it is important to you to have the newer hub, make sure the packaging says it is made to work with HomeKit.

actong88 · February 13, 2017, 12:26pm

Yes, the v2 one. I probably didn’t select the right branch when I created the github release, but the jar should still be good. I also fixed the release tag to point at the correct commit.

dan12345 · February 13, 2017, 2:20pm

Cool - thank you. Will run for a while and let you know how it goes.

dan12345 · February 13, 2017, 3:05pm

one quick observation: the reconnection works perfectly, it does leave a phantom new bridge in my inbox (which I can of course ignore or delete).

actong88 · February 13, 2017, 3:58pm

Hmm, that’s strange. The discovery service is always running in the background, and it uses the device serial number to determine if the bridge was already discovered or not. I’m not sure why it would rediscover the same bridge (with presumably the same serial number), or why this would be related to the binding losing connection with the bridge.

dan12345 · February 13, 2017, 4:33pm

Unfortunately it permanently lost the connection again after two hours:

2017-02-13 16:12:40.683 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 16:12:40.683 [ERROR] [nding.lutron.handler.IPBridgeHandler] - Communication error, will try to reconnect
java.io.IOException: Could not write to stream
	at org.openhab.binding.lutron.internal.net.TelnetSession.writeLine(TelnetSession.java:234)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.sendCommands(IPBridgeHandler.java:206)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.access$2(IPBridgeHandler.java:198)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at org.openhab.binding.lutron.handler.IPBridgeHandler$4.run(IPBridgeHandler.java:184)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-02-13 16:12:40.684 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Keepalive timeout, attempting to reconnect to the bridge
2017-02-13 16:12:40.685 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Disconnecting from bridge
2017-02-13 16:12:40.685 [ERROR] [nding.lutron.handler.IPBridgeHandler] - Error disconnecting
java.net.SocketException: Socket closed
	at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:118)[:1.8.0_121]
	at java.net.SocketOutputStream.write(SocketOutputStream.java:155)[:1.8.0_121]
	at java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:82)[:1.8.0_121]
	at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:140)[:1.8.0_121]
	at java.io.FilterOutputStream.close(FilterOutputStream.java:158)[:1.8.0_121]
	at org.apache.commons.net.telnet.TelnetClient._closeOutputStream(TelnetClient.java:86)[186:org.apache.commons.net:3.2.0]
	at org.apache.commons.net.telnet.TelnetOutputStream.close(TelnetOutputStream.java:155)[186:org.apache.commons.net:3.2.0]
	at org.apache.commons.net.telnet.TelnetClient.disconnect(TelnetClient.java:127)[186:org.apache.commons.net:3.2.0]
	at org.openhab.binding.lutron.internal.net.TelnetSession.close(TelnetSession.java:118)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.disconnect(IPBridgeHandler.java:243)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.reconnect(IPBridgeHandler.java:253)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.sendCommands(IPBridgeHandler.java:214)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.access$2(IPBridgeHandler.java:198)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at org.openhab.binding.lutron.handler.IPBridgeHandler$4.run(IPBridgeHandler.java:184)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-02-13 16:12:40.685 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Connecting to bridge at 192.168.1.139
2017-02-13 16:12:40.687 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Disconnecting from bridge

I have to then reconnect manually by resaving the Things file

Dan

actong88 · February 13, 2017, 4:47pm

Can you post more of the log before that (and after, if anything)? I’m curious why the timeout would happen 1 ms after sending the SYSTEM message. Does the machine running OH go to sleep by any chance?

dan12345 · February 13, 2017, 4:54pm

Sure, here’s the complete logs from installing the new binding to the error. At that point I had two keypad Things defined (integration IDs 12 and 13) but no items. OH2 running on an always-on Linux PC. Other logs indicate no problem with other bindings around that time.

You’ll see a fair few errors in the log - it looks like there are differences in parameters between RadioRA and QS so when (earlier on, and not reflected in these logs) I was playing with items, they weren’t quite working correctly. I’ll put together a proper summary of this for you in the next couple of days.

Log:

2017-02-13 14:05:02.735 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:05:02.735 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:05:02.737 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:05:02.737 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:05:02.739 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:05:02.739 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:05:02.741 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:05:02.741 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:05:02.743 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,12,86,9,0
2017-02-13 14:05:02.833 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,12,87,9,0
2017-02-13 14:07:40.651 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 14:07:40.743 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 14:09:55.686 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,13,81,9
2017-02-13 14:09:55.686 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,13,82,9
2017-02-13 14:09:55.686 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,13,83,9
2017-02-13 14:09:55.686 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,13,84,9
2017-02-13 14:09:55.686 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,13,85,9
2017-02-13 14:09:55.686 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,13,86,9
2017-02-13 14:09:55.686 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,13,87,9
2017-02-13 14:09:55.686 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,13,81,9
2017-02-13 14:09:55.686 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,13,82,9
2017-02-13 14:09:55.686 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,13,83,9
2017-02-13 14:09:55.686 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,13,84,9
2017-02-13 14:09:55.686 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,13,85,9
2017-02-13 14:09:55.686 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,13,86,9
2017-02-13 14:09:55.686 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,13,87,9
2017-02-13 14:09:55.687 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,12,81,9
2017-02-13 14:09:55.687 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,12,82,9
2017-02-13 14:09:55.687 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,12,83,9
2017-02-13 14:09:55.687 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,12,84,9
2017-02-13 14:09:55.687 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,12,85,9
2017-02-13 14:09:55.687 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,12,86,9
2017-02-13 14:09:55.687 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,12,87,9
2017-02-13 14:09:55.687 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,12,81,9
2017-02-13 14:09:55.687 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,12,82,9
2017-02-13 14:09:55.687 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,12,83,9
2017-02-13 14:09:55.687 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,12,84,9
2017-02-13 14:09:55.688 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,12,85,9
2017-02-13 14:09:55.688 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,12,86,9
2017-02-13 14:09:55.688 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?DEVICE,12,87,9
2017-02-13 14:09:55.750 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:09:55.751 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:09:55.753 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,13,82,9,0
2017-02-13 14:09:55.755 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:09:55.755 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:09:55.757 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,13,84,9,0
2017-02-13 14:09:55.759 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:09:55.759 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:09:55.762 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,13,86,9,0
2017-02-13 14:09:55.851 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,13,87,9,0
2017-02-13 14:09:55.853 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:09:55.853 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:09:55.855 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,13,82,9,0
2017-02-13 14:09:55.857 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:09:55.857 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:09:55.859 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,13,84,9,0
2017-02-13 14:09:55.861 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:09:55.862 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:09:55.951 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,13,86,9,0
2017-02-13 14:09:55.953 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,13,87,9,0
2017-02-13 14:09:55.955 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:09:55.955 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:09:55.957 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:09:55.957 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:09:55.959 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:09:55.959 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:09:55.962 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:09:55.962 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:09:56.050 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:09:56.050 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:09:56.053 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,12,86,9,0
2017-02-13 14:09:56.055 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,12,87,9,0
2017-02-13 14:09:56.057 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:09:56.057 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:09:56.059 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:09:56.059 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:09:56.061 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:09:56.061 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:09:56.150 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:09:56.151 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:09:56.152 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~ERROR,4
2017-02-13 14:09:56.152 [INFO ] [nding.lutron.handler.IPBridgeHandler] - Ignoring message ~ERROR,4
2017-02-13 14:09:56.155 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,12,86,9,0
2017-02-13 14:09:56.157 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,12,87,9,0
2017-02-13 14:12:40.651 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 14:12:40.661 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 14:17:41.784 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 14:17:41.879 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 14:22:40.684 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 14:22:40.697 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 14:27:40.651 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 14:27:40.715 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 14:31:51.178 [DEBUG] [RadioRA2MainRepeaterDiscoveryService] - Scanning for RadioRA2 main repeaters
2017-02-13 14:31:52.191 [DEBUG] [RadioRA2MainRepeaterDiscoveryService] - Discovered main repeater lutron:ipbridge:00DE1DFF
2017-02-13 14:31:54.193 [DEBUG] [RadioRA2MainRepeaterDiscoveryService] - Timed out waiting for response; presumably all repeaters have already responded
2017-02-13 14:32:40.651 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 14:32:40.732 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 14:37:40.689 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 14:37:40.750 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 14:42:40.726 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 14:42:40.768 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 14:47:40.672 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 14:47:40.686 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 14:52:40.707 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 14:52:40.804 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 14:57:40.651 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 14:57:40.722 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 15:01:51.178 [DEBUG] [RadioRA2MainRepeaterDiscoveryService] - Scanning for RadioRA2 main repeaters
2017-02-13 15:01:52.193 [DEBUG] [RadioRA2MainRepeaterDiscoveryService] - Discovered main repeater lutron:ipbridge:00DE1DFF
2017-02-13 15:01:54.195 [DEBUG] [RadioRA2MainRepeaterDiscoveryService] - Timed out waiting for response; presumably all repeaters have already responded
2017-02-13 15:02:40.672 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 15:02:40.739 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 15:07:41.405 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 15:07:41.457 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 15:12:40.651 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 15:12:40.675 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 15:17:40.785 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 15:17:40.793 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 15:22:40.651 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 15:22:40.711 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 15:27:40.651 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 15:27:40.729 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 15:31:51.178 [DEBUG] [RadioRA2MainRepeaterDiscoveryService] - Scanning for RadioRA2 main repeaters
2017-02-13 15:31:52.192 [DEBUG] [RadioRA2MainRepeaterDiscoveryService] - Discovered main repeater lutron:ipbridge:00DE1DFF
2017-02-13 15:31:54.195 [DEBUG] [RadioRA2MainRepeaterDiscoveryService] - Timed out waiting for response; presumably all repeaters have already responded
2017-02-13 15:32:40.691 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 15:32:40.747 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 15:37:40.790 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 15:37:40.865 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 15:42:40.663 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 15:42:40.683 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 15:47:40.651 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 15:47:40.701 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 15:52:40.651 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 15:52:40.718 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 15:57:40.677 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 15:57:40.736 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 16:01:51.178 [DEBUG] [RadioRA2MainRepeaterDiscoveryService] - Scanning for RadioRA2 main repeaters
2017-02-13 16:01:52.192 [DEBUG] [RadioRA2MainRepeaterDiscoveryService] - Discovered main repeater lutron:ipbridge:00DE1DFF
2017-02-13 16:01:54.195 [DEBUG] [RadioRA2MainRepeaterDiscoveryService] - Timed out waiting for response; presumably all repeaters have already responded
2017-02-13 16:02:40.651 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 16:02:40.754 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 16:07:40.651 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 16:07:40.672 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~SYSTEM,08/09/2012,13:41:28
2017-02-13 16:08:22.132 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,13,6,3
2017-02-13 16:08:22.138 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~OUTPUT,22,1,100.00
2017-02-13 16:08:22.138 [INFO ] [nding.lutron.handler.IPBridgeHandler] - No thing configured for integration ID 22
2017-02-13 16:08:22.141 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~OUTPUT,23,1,100.00
2017-02-13 16:08:22.141 [INFO ] [nding.lutron.handler.IPBridgeHandler] - No thing configured for integration ID 23
2017-02-13 16:08:22.623 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,13,86,9,1
2017-02-13 16:08:22.626 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Received message ~DEVICE,13,88,9,0
2017-02-13 16:08:22.626 [ERROR] [binding.lutron.handler.KeypadHandler] - Unknown component 88
2017-02-13 16:12:40.683 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Sending command ?SYSTEM,10
2017-02-13 16:12:40.683 [ERROR] [nding.lutron.handler.IPBridgeHandler] - Communication error, will try to reconnect
java.io.IOException: Could not write to stream
	at org.openhab.binding.lutron.internal.net.TelnetSession.writeLine(TelnetSession.java:234)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.sendCommands(IPBridgeHandler.java:206)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.access$2(IPBridgeHandler.java:198)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at org.openhab.binding.lutron.handler.IPBridgeHandler$4.run(IPBridgeHandler.java:184)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-02-13 16:12:40.684 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Keepalive timeout, attempting to reconnect to the bridge
2017-02-13 16:12:40.685 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Disconnecting from bridge
2017-02-13 16:12:40.685 [ERROR] [nding.lutron.handler.IPBridgeHandler] - Error disconnecting
java.net.SocketException: Socket closed
	at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:118)[:1.8.0_121]
	at java.net.SocketOutputStream.write(SocketOutputStream.java:155)[:1.8.0_121]
	at java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:82)[:1.8.0_121]
	at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:140)[:1.8.0_121]
	at java.io.FilterOutputStream.close(FilterOutputStream.java:158)[:1.8.0_121]
	at org.apache.commons.net.telnet.TelnetClient._closeOutputStream(TelnetClient.java:86)[186:org.apache.commons.net:3.2.0]
	at org.apache.commons.net.telnet.TelnetOutputStream.close(TelnetOutputStream.java:155)[186:org.apache.commons.net:3.2.0]
	at org.apache.commons.net.telnet.TelnetClient.disconnect(TelnetClient.java:127)[186:org.apache.commons.net:3.2.0]
	at org.openhab.binding.lutron.internal.net.TelnetSession.close(TelnetSession.java:118)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.disconnect(IPBridgeHandler.java:243)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.reconnect(IPBridgeHandler.java:253)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.sendCommands(IPBridgeHandler.java:214)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at org.openhab.binding.lutron.handler.IPBridgeHandler.access$2(IPBridgeHandler.java:198)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at org.openhab.binding.lutron.handler.IPBridgeHandler$4.run(IPBridgeHandler.java:184)[212:org.openhab.binding.lutron:2.1.0.201702112004]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-02-13 16:12:40.685 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Connecting to bridge at 192.168.1.139
2017-02-13 16:12:40.687 [DEBUG] [nding.lutron.handler.IPBridgeHandler] - Disconnecting from bridge

russell · February 16, 2017, 6:44am

So I’m reading over this, and I just want to double-triple check. I just installed a lot of Lutron Caseta switches in my condo. But it sounds like Lutron Caseta does not work with OpenHAB.

Is this true? And why? Will it ever be supported?

actong88 · February 16, 2017, 9:34pm

It should work if you have the Smart Bridge Pro. The non-pro version does not enable telnet access as far as I know.

russell · February 17, 2017, 12:59am

that’s a bummer, as it looks like they discontinued that model. Look like you have to buy it through a reseller or used.

actong88 · February 17, 2017, 2:12pm

I don’t think it’s discontinued. Lutron has always positioned the pro model as a dealer-only item for professional installation and not meant to be sold directly to consumers. You can certainly find the pro model for sale, though; just not in your typical big box store.