Satelbinding- instantly closing connection

pinks · July 21, 2017, 10:18am

Hey so i have 1 problem and 1 question.
Problem first: i have the satel binding installed and set the cfg file with ip&port(no encryption),
problem is as soon as OH is connected to the satel it closes the connection.

log:

12:12:55.537 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
12:12:55.548 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
12:12:55.563 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
12:12:56.536 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
12:12:56.546 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
12:12:56.564 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
12:12:57.536 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
12:12:57.547 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
12:12:57.564 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
12:12:58.537 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
12:12:58.549 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
12:12:58.566 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
12:12:59.537 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
12:12:59.549 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
12:12:59.565 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
12:13:00.537 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
12:13:00.548 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
12:13:00.565 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module

My question am i missing somtehing in some config,or how can i stop it from closing the connection?

My question if i have the satel binding running(hopefully sometime), how do differentiate the different items of the satel.
In my case i’d like to check on the status of different door/window contacts. I’ve read that i can create items for doors like the example from the satel doc

Switch Doors1 "E16/10 MK" { satel="doors:opened:1" }

but how can i tell which item(suggesting there will be more than this one) is which door.

Greetings pinks

namraccr · July 22, 2017, 11:56pm

The question is why is it closing the connection?

To try to figure that out, drop the binding into debug mode, restart and check the logs.
eg. in the Karaf console:

log:set DEBUG org.openhab.binding.satel

pinks · July 24, 2017, 11:50am

hey namraccr, first of all thx for the help.
Second here is the log with the debug information

09:20:05.171 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
09:20:05.181 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
09:20:05.182 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7E, payload =
09:20:05.198 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
09:20:05.198 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
09:20:05.198 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread stopped
09:20:06.171 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started
09:20:06.171 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
09:20:06.181 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
09:20:06.181 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7E, payload =
09:20:06.196 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
09:20:06.196 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
09:20:06.196 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread stopped
09:20:07.172 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started

any idea on why it says connected sucessfully and in the next moment says connected =false

greetings pinks

namraccr · July 25, 2017, 2:25am

Looks to me like the first command being sent (7E) is failing, causing the communication loop to exit and restart.

You could drop the binding into TRACE mode to see if it provides any additional information.

09:20:05.171 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
09:20:05.181 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
09:20:05.182 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7E, payload =
09:20:05.198 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
09:20:05.198 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
09:20:05.198 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread stopped
09:20:06.171 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started

pinks · July 25, 2017, 5:59am

07:57:17.107 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started
07:57:17.107 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
07:57:17.119 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
07:57:17.119 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7E, payload =
07:57:17.119 [TRACE] [satel.internal.protocol.SatelMessage] - Calculated checksum = D860
07:57:17.119 [TRACE] [.satel.internal.protocol.SatelModule] - Waiting for response
07:57:17.137 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
07:57:17.138 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
07:57:17.138 [TRACE] [satel.internal.event.EventDispatcher] - Distributing to org.openhab.binding.satel.internal.protocol.Ethm1Module@2542817a
07:57:17.138 [TRACE] [satel.internal.event.EventDispatcher] - Distributing to org.openhab.binding.satel.internal.SatelBinding@2b857ba2
07:57:17.138 [TRACE] [.binding.satel.internal.SatelBinding] - Handling incoming event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
07:57:17.138 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread stopped
07:57:18.106 [TRACE] [.satel.internal.protocol.SatelModule] - Checking communication thread: true, false
07:57:18.106 [TRACE] [.satel.internal.protocol.SatelModule] - Command enqueued: Message: command = 7E, payload =
07:57:18.107 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started
07:57:18.108 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
07:57:18.120 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
07:57:18.120 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7E, payload =
07:57:18.120 [TRACE] [satel.internal.protocol.SatelMessage] - Calculated checksum = D860
07:57:18.120 [TRACE] [.satel.internal.protocol.SatelModule] - Waiting for response
07:57:18.139 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
07:57:18.139 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
07:57:18.139 [TRACE] [satel.internal.event.EventDispatcher] - Distributing to org.openhab.binding.satel.internal.protocol.Ethm1Module@2542817a
07:57:18.139 [TRACE] [satel.internal.event.EventDispatcher] - Distributing to org.openhab.binding.satel.internal.SatelBinding@2b857ba2
07:57:18.139 [TRACE] [.binding.satel.internal.SatelBinding] - Handling incoming event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
07:57:18.139 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread stopped
07:57:19.106 [TRACE] [.satel.internal.protocol.SatelModule] - Checking communication thread: true, false
07:57:19.106 [TRACE] [.satel.internal.protocol.SatelModule] - Command enqueued: Message: command = 7E, payload =
07:57:19.108 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started

Am i understanding this right that the satel never replies or openhab closes the connection before satel replies?

namraccr · July 25, 2017, 11:44am

Looks to me like satel never replies. These 4 lines:

07:57:18.120 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7E, payload =
07:57:18.120 [TRACE] [satel.internal.protocol.SatelMessage] - Calculated checksum = D860
07:57:18.120 [TRACE] [.satel.internal.protocol.SatelModule] - Waiting for response
07:57:18.139 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module

If a response were received, we should see a "Got response: " line after “Waiting for response”.

I added a little more debugging output to this test jar, if you’d like to try it.

pinks · July 25, 2017, 12:01pm

13:58:13.269 [TRACE] [.satel.internal.protocol.SatelModule] - Command enqueued: Message: command = 7E, payload =
13:58:13.271 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started
13:58:13.272 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
13:58:13.284 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
13:58:13.284 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7E, payload =
13:58:13.284 [TRACE] [satel.internal.protocol.SatelMessage] - Calculated checksum = D860
13:58:13.284 [TRACE] [.satel.internal.protocol.SatelModule] - Waiting for response
13:58:13.301 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
13:58:13.302 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
13:58:13.302 [TRACE] [satel.internal.event.EventDispatcher] - Distributing to org.openhab.binding.satel.internal.protocol.Ethm1Module@7b7c8ac5
13:58:13.302 [TRACE] [satel.internal.event.EventDispatcher] - Distributing to org.openhab.binding.satel.internal.SatelBinding@75bb6bda
13:58:13.302 [TRACE] [.binding.satel.internal.SatelBinding] - Handling incoming event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
13:58:13.302 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread stopped
13:58:14.270 [TRACE] [.satel.internal.protocol.SatelModule] - Checking communication thread: true, false
13:58:14.270 [TRACE] [.satel.internal.protocol.SatelModule] - Command enqueued: Message: command = 7E, payload =
13:58:14.272 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started
13:58:14.273 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
13:58:14.284 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
13:58:14.285 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7E, payload =
13:58:14.285 [TRACE] [satel.internal.protocol.SatelMessage] - Calculated checksum = D860
13:58:14.285 [TRACE] [.satel.internal.protocol.SatelModule] - Waiting for response
13:58:14.304 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
13:58:14.304 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
13:58:14.304 [TRACE] [satel.internal.event.EventDispatcher] - Distributing to org.openhab.binding.satel.internal.protocol.Ethm1Module@7b7c8ac5
13:58:14.304 [TRACE] [satel.internal.event.EventDispatcher] - Distributing to org.openhab.binding.satel.internal.SatelBinding@75bb6bda
13:58:14.304 [TRACE] [.binding.satel.internal.SatelBinding] - Handling incoming event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
13:58:14.304 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread stopped
13:58:15.270 [TRACE] [.satel.internal.protocol.SatelModule] - Checking communication thread: true, false
13:58:15.271 [TRACE] [.satel.internal.protocol.SatelModule] - Command enqueued: Message: command = 7E, payload =
13:58:15.273 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started
13:58:15.274 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
13:58:15.287 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
13:58:15.287 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7E, payload =
13:58:15.287 [TRACE] [satel.internal.protocol.SatelMessage] - Calculated checksum = D860
13:58:15.287 [TRACE] [.satel.internal.protocol.SatelModule] - Waiting for response
13:58:15.305 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
13:58:15.306 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
13:58:15.306 [TRACE] [satel.internal.event.EventDispatcher] - Distributing to org.openhab.binding.satel.internal.protocol.Ethm1Module@7b7c8ac5
13:58:15.306 [TRACE] [satel.internal.event.EventDispatcher] - Distributing to org.openhab.binding.satel.internal.SatelBinding@75bb6bda
13:58:15.306 [TRACE] [.binding.satel.internal.SatelBinding] - Handling incoming event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
13:58:15.306 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread stopped
13:58:16.270 [TRACE] [.satel.internal.protocol.SatelModule] - Checking communication thread: true, false
13:58:16.270 [TRACE] [.satel.internal.protocol.SatelModule] - Command enqueued: Message: command = 7E, payload =
13:58:16.273 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started
13:58:16.273 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
13:58:16.286 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
13:58:16.286 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7E, payload =
13:58:16.286 [TRACE] [satel.internal.protocol.SatelMessage] - Calculated checksum = D860
13:58:16.286 [TRACE] [.satel.internal.protocol.SatelModule] - Waiting for response
13:58:16.302 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
13:58:16.302 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
13:58:16.302 [TRACE] [satel.internal.event.EventDispatcher] - Distributing to org.openhab.binding.satel.internal.protocol.Ethm1Module@7b7c8ac5
13:58:16.303 [TRACE] [satel.internal.event.EventDispatcher] - Distributing to org.openhab.binding.satel.internal.SatelBinding@75bb6bda
13:58:16.303 [TRACE] [.binding.satel.internal.SatelBinding] - Handling incoming event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
13:58:16.303 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread stopped
13:58:17.271 [TRACE] [.satel.internal.protocol.SatelModule] - Checking communication thread: true, false
13:58:17.272 [TRACE] [.satel.internal.protocol.SatelModule] - Command enqueued: Message: command = 7E, payload =
13:58:17.274 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started
13:58:17.274 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
13:58:17.286 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
13:58:17.286 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7E, payload =
13:58:17.286 [TRACE] [satel.internal.protocol.SatelMessage] - Calculated checksum = D860
13:58:17.286 [TRACE] [.satel.internal.protocol.SatelModule] - Waiting for response
13:58:17.305 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
13:58:17.305 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
13:58:17.305 [TRACE] [satel.internal.event.EventDispatcher] - Distributing to org.openhab.binding.satel.internal.protocol.Ethm1Module@7b7c8ac5
13:58:17.305 [TRACE] [satel.internal.event.EventDispatcher] - Distributing to org.openhab.binding.satel.internal.SatelBinding@75bb6bda
13:58:17.306 [TRACE] [.binding.satel.internal.SatelBinding] - Handling incoming event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
13:58:17.306 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread stopped

i added the jar file you sent to ~openhabfolder~\addons, i restartet the server but still not connected

namraccr · July 25, 2017, 11:16pm

Well, I don’t see the new messages I added, so either I misidentified the break points, or you’re still running the old version of the binding.

Can you check the bundle version? Meanwhile I’ll take another look at the code…

namraccr · July 26, 2017, 1:57am

I found one more place in the code where we might be terminating and added a new log message there. I also updated the start method to show this DEBUG message:

Satel binding has been started using special version 20170725T2153.

pinks · July 26, 2017, 6:09am

for of your jar being active the bundle list gives me following states:

223 | Active   |  80 | 1.10.0                 | openHAB Satel Action
224 | Active   |  80 | 1.10.0                 | openHAB Satel Binding
225 | Active   |  80 | 1.11.0.201707251139    | openHAB Satel Binding
226 | Active   |  80 | 1.11.0.201707160110    | openHAB Satel Action

so it seems to me that both are active.

regarding the new code, same download link ?

kind regards

namraccr · July 26, 2017, 11:24am

Yes, the download link is the same.

You need to uninstall the 1.10.0 versions.

pinks · July 26, 2017, 1:04pm

uninstalled the action and binding from the paperui pasted your snapshort into the addons folder restartet the server

On startup:

14:59:34.867 [INFO ] [.internal.GenericScriptEngineFactory] - Activated scripting support for ECMAScript
14:59:34.981 [DEBUG] [org.openhab.binding.exec            ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={component.name=org.openhab.binding.exec.internal.ExecHandlerFactory, component.id=20, service.id=121, service.bundleid=201, service.scope=bundle} - org.openhab.binding.exec
14:59:34.983 [DEBUG] [org.openhab.binding.exec            ] - BundleEvent STARTING - org.openhab.binding.exec
14:59:34.983 [DEBUG] [org.openhab.binding.exec            ] - BundleEvent STARTED - org.openhab.binding.exec
14:59:34.987 [DEBUG] [org.openhab.binding.exec            ] - BundleEvent [unknown:512] - org.openhab.binding.exec
14:59:35.291 [ERROR] [org.openhab.action.satel            ] - FrameworkEvent ERROR - org.openhab.action.satel
org.osgi.framework.BundleException: Could not resolve module: org.openhab.action.satel [226]
  Unresolved requirement: Import-Package: org.openhab.binding.satel
    -> Export-Package: org.openhab.binding.satel; bundle-symbolic-name="org.openhab.binding.satel"; bundle-version="1.11.0.201707260153"; version="0.0.0"
       org.openhab.binding.satel [228]
         Unresolved requirement: Import-Package: gnu.io

        at org.eclipse.osgi.container.Module.start(Module.java:434)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1582)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1561)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.doContainerStartLevel(ModuleContainer.java:1533)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1476)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.framework.eventmgr.EventManager$EventThread.run(EventManager.java:340)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]

and

15:00:20.027 [ERROR] [org.openhab.binding.satel           ] - FrameworkEvent ERROR - org.openhab.binding.satel
org.osgi.framework.BundleException: Could not resolve module: org.openhab.binding.satel [228]
  Unresolved requirement: Import-Package: gnu.io

        at org.eclipse.osgi.container.Module.start(Module.java:434)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1582)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1562)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.doContainerStartLevel(ModuleContainer.java:1533)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1476)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.framework.eventmgr.EventManager$EventThread.run(EventManager.java:340)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]

furthermore it stopped the repeating connection process

EDIT:

i just checked the status of these bindings they dont appear to be active

226 | Installed |  80 | 1.11.0.201707160110    | openHAB Satel Action
228 | Installed |  80 | 1.11.0.201707260153    | openHAB Satel Binding

namraccr · July 27, 2017, 12:48am

Try checking the bindings this way:

bundle:diag 226
bundle:diag 228

I don’t know what causes this to happen, though. A quick google found a few threads in the forum, but I don’t see an obvious solution in them.

pinks · July 27, 2017, 5:41am

I get those:

openhab>  bundle:diag 226
openHAB Satel Action (226)
--------------------------
Status: Installed
Unsatisfied Requirements:
osgi.wiring.package; filter:="(osgi.wiring.package=org.openhab.binding.satel)"
osgi.wiring.package; filter:="(osgi.wiring.package=org.openhab.binding.satel.command)"


openhab>  bundle:diag 228
openHAB Satel Binding (228)
---------------------------
Status: Installed
Unsatisfied Requirements:
osgi.wiring.package; filter:="(osgi.wiring.package=gnu.io)"

namraccr · July 27, 2017, 11:40am

Have you tried restarting OH? If a couple restarts doesn’t change anything, check the status of the openhab-transport-serial feature, and install it if it’s not running. I profer this suggestion based on this thread that was reporting the gnu.io error.

feature:info | grep openhab-transport-serial
feature:install openhab-transport-serial

pinks · July 28, 2017, 5:42am

restarted ,gave me this

07:33:49.554 [ERROR] [org.openhab.action.satel            ] - FrameworkEvent ERROR - org.openhab.action.satel
org.osgi.framework.BundleException: Could not resolve module: org.openhab.action.satel [226]
  Unresolved requirement: Import-Package: org.openhab.binding.satel
    -> Export-Package: org.openhab.binding.satel; bundle-symbolic-name="org.openhab.binding.satel"; bundle-version="1.11.0.201707260153"; version="0.0.0"
       org.openhab.binding.satel [228]
         Unresolved requirement: Import-Package: gnu.io

        at org.eclipse.osgi.container.Module.start(Module.java:434)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1582)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1561)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.doContainerStartLevel(ModuleContainer.java:1533)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1476)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.framework.eventmgr.EventManager$EventThread.run(EventManager.java:340)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]

and this,

07:34:47.495 [ERROR] [org.openhab.binding.satel           ] - FrameworkEvent ERROR - org.openhab.binding.satel
org.osgi.framework.BundleException: Could not resolve module: org.openhab.binding.satel [228]
  Unresolved requirement: Import-Package: gnu.io

        at org.eclipse.osgi.container.Module.start(Module.java:434)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1582)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1562)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.doContainerStartLevel(ModuleContainer.java:1533)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1476)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
        at org.eclipse.osgi.framework.eventmgr.EventManager$EventThread.run(EventManager.java:340)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]

after i installed the feature i restarted and got these on startup

07:40:01.383 [DEBUG] [org.openhab.binding.exec            ] - BundleEvent [unknown:512] - org.openhab.binding.exec
07:40:01.385 [DEBUG] [org.openhab.binding.exec            ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={component.name=org.openhab.binding.exec.internal.ExecHandlerFactory, component.id=20, service.id=126, service.bundleid=201, service.scope=bundle} - org.openhab.binding.exec
07:40:01.386 [DEBUG] [org.openhab.binding.exec            ] - BundleEvent STARTING - org.openhab.binding.exec
07:40:01.386 [DEBUG] [org.openhab.binding.exec            ] - BundleEvent STARTED - org.openhab.binding.exec

but again the satel connection gets closed

07:41:25.094 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started
07:41:25.095 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
07:41:25.107 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
07:41:25.107 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7E, payload =
07:41:25.107 [TRACE] [satel.internal.protocol.SatelMessage] - Calculated checksum = D860
07:41:25.107 [TRACE] [.satel.internal.protocol.SatelModule] - Waiting for response
07:41:25.125 [DEBUG] [.satel.internal.protocol.SatelModule] - Response was null. Communication loop will exit.
07:41:25.125 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
07:41:25.125 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
07:41:25.125 [TRACE] [satel.internal.event.EventDispatcher] - Distributing to org.openhab.binding.satel.internal.protocol.Ethm1Module@1b35ac61
07:41:25.125 [TRACE] [satel.internal.event.EventDispatcher] - Distributing to org.openhab.binding.satel.internal.SatelBinding@1fef8869
07:41:25.125 [TRACE] [.binding.satel.internal.SatelBinding] - Handling incoming event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
07:41:25.126 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread stopped
07:41:26.092 [TRACE] [.satel.internal.protocol.SatelModule] - Checking communication thread: true, false
07:41:26.092 [TRACE] [.satel.internal.protocol.SatelModule] - Command enqueued: Message: command = 7E, payload =
07:41:26.096 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started
07:41:26.096 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
07:41:26.108 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
07:41:26.108 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7E, payload =
07:41:26.108 [TRACE] [satel.internal.protocol.SatelMessage] - Calculated checksum = D860
07:41:26.108 [TRACE] [.satel.internal.protocol.SatelModule] - Waiting for response
07:41:26.127 [DEBUG] [.satel.internal.protocol.SatelModule] - Response was null. Communication loop will exit.
07:41:26.127 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module

Edit:
set the log mode to debug and found that there was no answeer or null

07:44:59.800 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread started
07:44:59.801 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.91.100:7090
07:44:59.814 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
07:44:59.814 [DEBUG] [.satel.internal.protocol.SatelModule] - Sending message: Message: command = 7E, payload =
07:44:59.832 [DEBUG] [.satel.internal.protocol.SatelModule] - Response was null. Communication loop will exit.
07:44:59.832 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
07:44:59.832 [DEBUG] [satel.internal.event.EventDispatcher] - Distributing event: org.openhab.binding.satel.internal.event.ConnectionStatusEvent: connected = false
07:44:59.832 [DEBUG] [.satel.internal.protocol.SatelModule] - Communication thread stopped

namraccr · July 30, 2017, 12:34am

So not much left to do, then.

The only things you might try:

check your configs. make sure you’re using the right address and port
take a network sniff of the exchange to verify what’s being sent/received

pinks · July 31, 2017, 7:06am

Using wireshark to sniff but cant really read the things sent (not human readable)

but maybe you can get more information out of it. for me it seems like everything is fine if i wouldnt know better since OH sends and receives messages.

wireshark

namraccr · July 31, 2017, 11:59am

If you want to post the file I could take a look. The screen capture doesn’t show the actual data transmitted/received.

pinks · August 1, 2017, 5:54am

hope that helps