Satelbinding- instantly closing connection

namraccr · August 1, 2017, 12:00pm

This shows clearly that OH sends the 7E command and receives an empty response:

Sent:

0000   00 00 5e 00 01 18 00 1e c9 34 93 06 08 00 45 00  ..^......4....E.
0010   00 2d 7b 03 40 00 80 06 00 00 ac 18 01 0a c0 a8  .-{.@...........
0020   5b 64 ff 35 1b b2 43 0e 86 46 1b 44 27 22 50 18  [d.5..C..F.D'"P.
0030   ff 70 c9 4e 00 00 7e d8 60 fe 0d                 .p.N..~.`..

Response:

0000   00 00 5e 00 01 18 00 1e c9 34 93 06 08 00 45 00  ..^......4....E.
0010   00 28 7b 04 40 00 80 06 00 00 ac 18 01 0a c0 a8  .({.@...........
0020   5b 64 ff 35 1b b2 43 0e 86 4b 1b 44 27 22 50 11  [d.5..C..K.D'"P.
0030   ff 70 c9 49 00 00                                .p.I..

If you have any external tools that communicate to the same Satel system using the same protocol, comparing those conversations could be interesting, but it looks like everything is good here.

pinks · August 1, 2017, 12:06pm

so basically the satel doest answer to my request, since we can look into it via the software meaning the satel works this suggest that the satel just dont answer me.

since i m really a noob in this topic my question is is it possible that the satel can only communicate with 1 other device at the same time? or why else wouldnt it answer or answer empty

namraccr · August 2, 2017, 1:47am

I don’t know enough about Satel to answer that. @druciak might be able to answer.

druciak · August 2, 2017, 9:02pm

Hi guys, really interesting conversation. I learned some useful tricks
Regarding the main topic/problem: as @namraccr pointed out there must be some misconfiguration. It seems that ethm-1 does not understand what the binding says. I would suggest to check the following:

Port is correct (should be integration protocol port)
Integration protocol is enabled
Encryption is on both sides disabled or
Encryption is on both sides enabled and encryption key is same on both sides.
You don’t have anything else connected to the same port of ethm-1

In some cases reinstalling oh2 from the scratch helped, but I would try it if all other failed.

That’s true, it can communicate with only one client per port (protocol).

pinks · August 3, 2017, 7:05am

hi druciak,

if i got the right information about the satel from my system admin the configuration shoud be right.

i entered an IP-adress,an port and commented the rest out since i m told that it isnt encrypted.

i ll look closer into the matter regarding 1connection/Port.

druciak · August 3, 2017, 7:14am

I forgot about another reason that could make such problems:
5. Make sure integration protocol is enabled.

What port did you enter to OH configuration?

pinks · August 3, 2017, 7:15am

7090

druciak · August 3, 2017, 7:23am

Most likely this is not the correct port. Ports can be reconfigured in Dload X, but here are the defaults:
7090 - DloadX
7091 - GuardX / Java
7094 - Integration protocol

Usually nobody touches these defaults, so I suspect you are using DloadX port, which obviously won’t work.
You could try to put 7094 to your configuration, but if it is not enabled, it won’t work either. If this is the case, you need to ask your sys admin to enable it.

pinks · August 3, 2017, 7:24am

will try thx for the advice

pinks · August 3, 2017, 7:27am

09:26:28.252 [ERROR] [.satel.internal.protocol.Ethm1Module] - IO error occurred during connecting socket
java.net.ConnectException: Connection timed out: connect
        at java.net.DualStackPlainSocketImpl.waitForConnect(Native Method)[:1.8.0_131]
        at java.net.DualStackPlainSocketImpl.socketConnect(Unknown Source)[:1.8.0_131]
        at java.net.AbstractPlainSocketImpl.doConnect(Unknown Source)[:1.8.0_131]
        at java.net.AbstractPlainSocketImpl.connectToAddress(Unknown Source)[:1.8.0_131]
        at java.net.AbstractPlainSocketImpl.connect(Unknown Source)[:1.8.0_131]
        at java.net.PlainSocketImpl.connect(Unknown Source)[:1.8.0_131]
        at java.net.SocksSocketImpl.connect(Unknown Source)[:1.8.0_131]
        at java.net.Socket.connect(Unknown Source)[:1.8.0_131]
        at org.openhab.binding.satel.internal.protocol.Ethm1Module.connect(Ethm1Module.java:67)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at org.openhab.binding.satel.internal.protocol.SatelModule.communicationLoop(SatelModule.java:344)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at org.openhab.binding.satel.internal.protocol.SatelModule.access$1(SatelModule.java:333)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at org.openhab.binding.satel.internal.protocol.SatelModule$CommunicationWatchdog$2.run(SatelModule.java:467)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at java.lang.Thread.run(Unknown Source)[:1.8.0_131]
09:26:28.255 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
09:26:28.257 [INFO ] [.satel.internal.protocol.SatelModule] - Unhandled exception occurred in communication loop, disconnecting.
java.lang.IllegalArgumentException: timeout value is negative
        at java.lang.Thread.sleep(Native Method)[:1.8.0_131]
        at org.openhab.binding.satel.internal.protocol.SatelModule.communicationLoop(SatelModule.java:352)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at org.openhab.binding.satel.internal.protocol.SatelModule.access$1(SatelModule.java:333)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at org.openhab.binding.satel.internal.protocol.SatelModule$CommunicationWatchdog$2.run(SatelModule.java:467)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at java.lang.Thread.run(Unknown Source)[:1.8.0_131]
09:26:28.257 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread stopped
09:26:29.100 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started
09:26:29.103 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7094

druciak · August 3, 2017, 7:31am

So it looks like integration protocol is not enabled - ethm-1 does not listen on port 7094. It must be changed in Dload X and if you don’t have access to it, ask your system admin to do that. Should be straightforward.

druciak · August 3, 2017, 7:33am

Also this is weird: "java.lang.IllegalArgumentException: timeout value is negative"
What value do you have for timeout in your configuration?

pinks · August 3, 2017, 7:33am

i havent set it in the cfg file

i ll look deeper into OH maybe somewhere there is an old value stored

pinks · August 3, 2017, 7:37am

09:26:28.252 [ERROR] [.satel.internal.protocol.Ethm1Module] - IO error occurred during connecting socket
java.net.ConnectException: Connection timed out: connect
        at java.net.DualStackPlainSocketImpl.waitForConnect(Native Method)[:1.8.0_131]
        at java.net.DualStackPlainSocketImpl.socketConnect(Unknown Source)[:1.8.0_131]
        at java.net.AbstractPlainSocketImpl.doConnect(Unknown Source)[:1.8.0_131]
        at java.net.AbstractPlainSocketImpl.connectToAddress(Unknown Source)[:1.8.0_131]
        at java.net.AbstractPlainSocketImpl.connect(Unknown Source)[:1.8.0_131]
        at java.net.PlainSocketImpl.connect(Unknown Source)[:1.8.0_131]
        at java.net.SocksSocketImpl.connect(Unknown Source)[:1.8.0_131]
        at java.net.Socket.connect(Unknown Source)[:1.8.0_131]
        at org.openhab.binding.satel.internal.protocol.Ethm1Module.connect(Ethm1Module.java:67)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at org.openhab.binding.satel.internal.protocol.SatelModule.communicationLoop(SatelModule.java:344)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at org.openhab.binding.satel.internal.protocol.SatelModule.access$1(SatelModule.java:333)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at org.openhab.binding.satel.internal.protocol.SatelModule$CommunicationWatchdog$2.run(SatelModule.java:467)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at java.lang.Thread.run(Unknown Source)[:1.8.0_131]
09:26:28.255 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
09:26:28.257 [INFO ] [.satel.internal.protocol.SatelModule] - Unhandled exception occurred in communication loop, disconnecting.
java.lang.IllegalArgumentException: timeout value is negative
        at java.lang.Thread.sleep(Native Method)[:1.8.0_131]
        at org.openhab.binding.satel.internal.protocol.SatelModule.communicationLoop(SatelModule.java:352)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at org.openhab.binding.satel.internal.protocol.SatelModule.access$1(SatelModule.java:333)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at org.openhab.binding.satel.internal.protocol.SatelModule$CommunicationWatchdog$2.run(SatelModule.java:467)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at java.lang.Thread.run(Unknown Source)[:1.8.0_131]
09:26:28.257 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread stopped
09:26:29.100 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started
09:26:29.103 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7094

09:34:59.330 [INFO ] [b.core.service.AbstractActiveService] - Satel Refresh Service has been started
09:34:59.331 [DEBUG] [.binding.satel.internal.SatelBinding] - Module not initialized yet, skipping refresh
09:34:59.342 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started
09:34:59.347 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7094

09:35:04.350 [ERROR] [.satel.internal.protocol.Ethm1Module] - IO error occurred during connecting socket
java.net.SocketTimeoutException: connect timed out
        at java.net.DualStackPlainSocketImpl.waitForConnect(Native Method)[:1.8.0_131]
        at java.net.DualStackPlainSocketImpl.socketConnect(Unknown Source)[:1.8.0_131]
        at java.net.AbstractPlainSocketImpl.doConnect(Unknown Source)[:1.8.0_131]
        at java.net.AbstractPlainSocketImpl.connectToAddress(Unknown Source)[:1.8.0_131]
        at java.net.AbstractPlainSocketImpl.connect(Unknown Source)[:1.8.0_131]
        at java.net.PlainSocketImpl.connect(Unknown Source)[:1.8.0_131]
        at java.net.SocksSocketImpl.connect(Unknown Source)[:1.8.0_131]
        at java.net.Socket.connect(Unknown Source)[:1.8.0_131]
        at org.openhab.binding.satel.internal.protocol.Ethm1Module.connect(Ethm1Module.java:67)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at org.openhab.binding.satel.internal.protocol.SatelModule.communicationLoop(SatelModule.java:344)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at org.openhab.binding.satel.internal.protocol.SatelModule.access$1(SatelModule.java:333)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at org.openhab.binding.satel.internal.protocol.SatelModule$CommunicationWatchdog$2.run(SatelModule.java:467)[228:org.openhab.binding.satel:1.11.0.201707260153]
        at java.lang.Thread.run(Unknown Source)[:1.8.0_131]
09:35:04.353 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false

pinks · August 3, 2017, 9:30am

@druciak @namraccr thanks for sticking around with me here

11:26:10.567 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7F, payload = 00
11:26:10.614 [DEBUG] [.satel.internal.protocol.SatelModule] - Got response: Message: command = 7F, payload = FF FF FF FE FF FF
11:26:10.614 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: NewStatesEvent: changed = [00,01,02,03,04,05,06,07,08,09,0A,0B,0C,0D,0E,0F,10,11,12,13,14,15,16,17,19,1A,1B,1C,1D,1E,1F,20,21,22,23,24,25,26,27,28,29,2A,2B,2C,2D,2E,2F]
11:26:20.568 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7F, payload = 00
11:26:20.599 [DEBUG] [.satel.internal.protocol.SatelModule] - Got response: Message: command = 7F, payload = FF FF FF FE FF FF
11:26:20.599 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: NewStatesEvent: changed = [00,01,02,03,04,05,06,07,08,09,0A,0B,0C,0D,0E,0F,10,11,12,13,14,15,16,17,19,1A,1B,1C,1D,1E,1F,20,21,22,23,24,25,26,27,28,29,2A,2B,2C,2D,2E,2F]
11:26:30.569 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7F, payload = 00
11:26:30.610 [DEBUG] [.satel.internal.protocol.SatelModule] - Got response: Message: command = 7F, payload = FF FF FF FE FF FF
11:26:30.610 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: NewStatesEvent: changed = [00,01,02,03,04,05,06,07,08,09,0A,0B,0C,0D,0E,0F,10,11,12,13,14,15,16,17,19,1A,1B,1C,1D,1E,1F,20,21,22,23,24,25,26,27,28,29,2A,2B,2C,2D,2E,2F]
11:26:40.584 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7F, payload = 00
11:26:40.628 [DEBUG] [.satel.internal.protocol.SatelModule] - Got response: Message: command = 7F, payload = FF FF FF FE FF FF
11:26:40.628 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: NewStatesEvent: changed = [00,01,02,03,04,05,06,07,08,09,0A,0B,0C,0D,0E,0F,10,11,12,13,14,15,16,17,19,1A,1B,1C,1D,1E,1F,20,21,22,23,24,25,26,27,28,29,2A,2B,2C,2D,2E,2F]
11:26:50.585 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7F, payload = 00
11:26:50.625 [DEBUG] [.satel.internal.protocol.SatelModule] - Got response: Message: command = 7F, payload = FF FF FF FE FF FF
11:26:50.625 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: NewStatesEvent: changed = [00,01,02,03,04,05,06,07,08,09,0A,0B,0C,0D,0E,0F,10,11,12,13,14,15,16,17,19,1A,1B,1C,1D,1E,1F,20,21,22,23,24,25,26,27,28,29,2A,2B,2C,2D,2E,2F]
11:27:00.586 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7F, payload = 00
11:27:00.621 [DEBUG] [.satel.internal.protocol.SatelModule] - Got response: Message: command = 7F, payload = FF FF FF FE FF FF
11:27:00.622 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: NewStatesEvent: changed = [00,01,02,03,04,05,06,07,08,09,0A,0B,0C,0D,0E,0F,10,11,12,13,14,15,16,17,19,1A,1B,1C,1D,1E,1F,20,21,22,23,24,25,26,27,28,29,2A,2B,2C,2D,2E,2F]

it now seems to work.

for all who are interested on what the problem was and how it got solved

Porblem: systemadmin had the integration and the port enabled but noone ever thought about opening the port networkwise so all traffic got blocked

solution:pretty obvious sysadmin had to open the port