Smarthome: [homeatic] Bridge stays offline after initialization

Created on 19 Nov 2018  路  44Comments  路  Source: eclipse-archived/smarthome

I'm running the latest nightly docker image of openhab (openhab/openhab:2.4.0-snapshot-amd64-debian) and am trying to connect my Raspberrymatic CCU (latest version) via the homeatic binding.
The bridge does not come online though, it swtiches to offline _OFFLINE - COMMUNICATION_ERROR Connection lost_

I uploaded a trace log here: https://pastebin.com/m5JDnBJz

Binding-Homematic
Source
picture pahofmann

Most helpful comment

I added a new bridge parameter: bindAddress
Please try this version of the binding: https://drive.google.com/open?id=1rDGGl3HPo1-SWhTA2uo_nmkEM1G3PRLo

in your case, the bindAddress=0.0.0.0 and the callbackHost is the docker host IP

picture gerrieg on 19 Dec 2018
🎉3 👍3

All 44 comments

It seems it was able to connect in the last half our, I see the Homeatic devices in my inbox now.
But the Bridge still has the _Connection lost_ status.

pahofmann picture pahofmann on 19 Nov 2018

Have the same problem here. After I updated my [piv]CCU3 to 3.41.11, the Binding does not connect any more.

t Timeout java.io.IOException: java.net.SocketTimeoutException: Connect Timeout at org.openhab.binding.homematic.internal.communicator.client.XmlRpcClient.sendMessage(XmlRpcClient.java:94) ~[?:?] at org.openhab.binding.homematic.internal.communicator.client.XmlRpcClient.sendMessage(XmlRpcClient.java:98) ~[?:?] at org.openhab.binding.homematic.internal.communicator.client.XmlRpcClient.sendMessage(XmlRpcClient.java:63) ~[?:?] at org.openhab.binding.homematic.internal.communicator.client.RpcClient.getDeviceDescription(RpcClient.java:122) ~[?:?] at org.openhab.binding.homematic.internal.communicator.client.RpcClient.getGatewayInfo(RpcClient.java:220) ~[?:?] at org.openhab.binding.homematic.internal.communicator.HomematicGatewayFactory.loadGatewayInfo(HomematicGatewayFactory.java:47) ~[?:?] at org.openhab.binding.homematic.internal.communicator.HomematicGatewayFactory.createGateway(HomematicGatewayFactory.java:31) ~[?:?] at org.openhab.binding.homematic.handler.HomematicBridgeHandler.lambda$0(HomematicBridgeHandler.java:85) ~[?:?] 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) [?:?] Caused by: java.util.concurrent.ExecutionException: java.net.SocketTimeoutException: Connect Timeout 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.openhab.binding.homematic.internal.communicator.client.XmlRpcClient.sendMessage(XmlRpcClient.java:80) ~[?:?] ... 14 more Caused by: java.net.SocketTimeoutException: Connect Timeout at org.eclipse.jetty.io.ManagedSelector$ConnectTimeout.run(ManagedSelector.java:683) ~[?:?] ... 7 more 2018-11-21 08:46:19.175 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception java.lang.NullPointerException: null at org.openhab.binding.homematic.internal.discovery.HomematicDeviceDiscoveryService.waitForScanFinishing(HomematicDeviceDiscoveryService.java:174) ~[?:?] at org.openhab.binding.homematic.internal.discovery.HomematicDeviceDiscoveryService.stopScan(HomematicDeviceDiscoveryService.java:121) ~[?:?] at org.openhab.binding.homematic.handler.HomematicBridgeHandler.dispose(HomematicBridgeHandler.java:143) ~[?:?] at org.openhab.binding.homematic.handler.HomematicBridgeHandler.lambda$0(HomematicBridgeHandler.java:108) ~[?:?] 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) [?:?]

The CCU itself works fine and does communicate with alle devices without any problems. I can also reach the CCU via the webinterface and on the IP without any problems.

TB1234 picture TB1234 on 21 Nov 2018

Hey, i have the same problem.

I have updated OH2.4.0 build 1441 and it does not work anymore.
After an OH reboot, it will work for some time and the connection has been lost

````
2018-11-23 22:40:25.819 [hingStatusInfoChangedEvent] - 'homematic:bridge:3014F711A0001F98A99A2C37' changed from INITIALIZING to ONLINE

2018-11-23 22:40:26.044 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D97' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING

2018-11-23 22:40:26.062 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7DAE' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING

2018-11-23 22:40:26.115 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99BABBB' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING

2018-11-23 22:40:26.124 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B8453' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING

2018-11-23 22:40:26.171 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SWSD:3014F711A0001F98A99A2C37:000A58A9939112' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING

2018-11-23 22:40:26.197 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D97' changed from INITIALIZING to ONLINE

2018-11-23 22:40:26.237 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99BABBB' changed from INITIALIZING to ONLINE

2018-11-23 22:40:26.254 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D93' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING

2018-11-23 22:40:26.274 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7DAE' changed from INITIALIZING to ONLINE

2018-11-23 22:40:26.390 [hingStatusInfoChangedEvent] - 'homematic:HmIP-STH:3014F711A0001F98A99A2C37:000E58A99C8956' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING

2018-11-23 22:40:26.395 [hingStatusInfoChangedEvent] - 'homematic:HmIP-STH:3014F711A0001F98A99A2C37:000E58A991EFB7' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING

2018-11-23 22:40:26.412 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SWSD:3014F711A0001F98A99A2C37:000A58A993922B' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING

2018-11-23 22:40:26.498 [hingStatusInfoChangedEvent] - 'homematic:HmIP-STHO-A:3014F711A0001F98A99A2C37:0010D8A990A144' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING

2018-11-23 22:40:26.506 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SWSD:3014F711A0001F98A99A2C37:000A58A9939251' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING

2018-11-23 22:40:26.555 [hingStatusInfoChangedEvent] - 'homematic:HmIP-eTRV-2:3014F711A0001F98A99A2C37:000A18A993FD94' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING

2018-11-23 22:40:26.653 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BSM:3014F711A0001F98A99A2C37:000858A9960F14' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING

2018-11-23 22:40:26.676 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D68' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING

2018-11-23 22:40:26.694 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D5A' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING

2018-11-23 22:40:26.804 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B8453' changed from INITIALIZING to ONLINE

2018-11-23 22:40:27.980 [me.event.ThingUpdatedEvent] - Thing 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99BABBB' has been updated.

2018-11-23 22:40:28.096 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SWSD:3014F711A0001F98A99A2C37:000A58A9939112' changed from INITIALIZING to ONLINE

2018-11-23 22:40:28.397 [me.event.ThingUpdatedEvent] - Thing 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B8453' has been updated.

2018-11-23 22:40:28.441 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D93' changed from INITIALIZING to ONLINE

2018-11-23 22:40:28.661 [me.event.ThingUpdatedEvent] - Thing 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D97' has been updated.

2018-11-23 22:40:28.697 [hingStatusInfoChangedEvent] - 'homematic:HmIP-STH:3014F711A0001F98A99A2C37:000E58A99C8956' changed from INITIALIZING to ONLINE

2018-11-23 22:40:29.056 [me.event.ThingUpdatedEvent] - Thing 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7DAE' has been updated.

2018-11-23 22:40:29.206 [hingStatusInfoChangedEvent] - 'homematic:HmIP-STH:3014F711A0001F98A99A2C37:000E58A991EFB7' changed from INITIALIZING to ONLINE

2018-11-23 22:40:29.295 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-SWSD:3014F711A0001F98A99A2C37:000A58A9939112' has been updated.

2018-11-23 22:40:29.321 [vent.ItemStateChangedEvent] - HM_TempLuft01_1_Humidity changed from NULL to 46

2018-11-23 22:40:29.571 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SWSD:3014F711A0001F98A99A2C37:000A58A993922B' changed from INITIALIZING to ONLINE

2018-11-23 22:40:30.639 [me.event.ThingUpdatedEvent] - Thing 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D93' has been updated.

2018-11-23 22:40:30.673 [hingStatusInfoChangedEvent] - 'homematic:HmIP-STHO-A:3014F711A0001F98A99A2C37:0010D8A990A144' changed from INITIALIZING to ONLINE

2018-11-23 22:40:30.778 [vent.ItemStateChangedEvent] - HM_TempLuftExtern01_1_ActualTemperature changed from 3.6 to 3.50

2018-11-23 22:40:31.416 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-STH:3014F711A0001F98A99A2C37:000E58A991EFB7' has been updated.

2018-11-23 22:40:31.431 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SWSD:3014F711A0001F98A99A2C37:000A58A9939251' changed from INITIALIZING to ONLINE

2018-11-23 22:40:32.002 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-STH:3014F711A0001F98A99A2C37:000E58A99C8956' has been updated.

2018-11-23 22:40:32.031 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BSM:3014F711A0001F98A99A2C37:000858A9960F14' changed from INITIALIZING to ONLINE

2018-11-23 22:40:32.058 [hingStatusInfoChangedEvent] - 'homematic:HmIP-eTRV-2:3014F711A0001F98A99A2C37:000A18A993FD94' changed from INITIALIZING to ONLINE

2018-11-23 22:40:32.361 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-SWSD:3014F711A0001F98A99A2C37:000A58A993922B' has been updated.

2018-11-23 22:40:32.374 [vent.ItemStateChangedEvent] - HM_Heater01_1_ActualTemperature changed from 22.5 to 22.70

2018-11-23 22:40:32.441 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D68' changed from INITIALIZING to ONLINE

2018-11-23 22:40:32.656 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-SWSD:3014F711A0001F98A99A2C37:000A58A9939251' has been updated.

2018-11-23 22:40:32.792 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D5A' changed from INITIALIZING to ONLINE

2018-11-23 22:40:32.961 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-STHO-A:3014F711A0001F98A99A2C37:0010D8A990A144' has been updated.

2018-11-23 22:40:34.097 [me.event.ThingUpdatedEvent] - Thing 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D68' has been updated.

2018-11-23 22:40:34.361 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-eTRV-2:3014F711A0001F98A99A2C37:000A18A993FD94' has been updated.

2018-11-23 22:40:34.669 [me.event.ThingUpdatedEvent] - Thing 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D5A' has been updated.

2018-11-23 22:40:34.917 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-BSM:3014F711A0001F98A99A2C37:000858A9960F14' has been updated.

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

2018-11-23 22:41:10.900 [WARN ] [ommunicator.AbstractHomematicGateway] - Connection lost on gateway '3014F711A0001F98A99A2C37', cause: "No Pong received!"

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

2018-11-23 22:41:10.920 [hingStatusInfoChangedEvent] - 'homematic:bridge:3014F711A0001F98A99A2C37' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Connection lost

2018-11-23 22:41:10.936 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B8453' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2018-11-23 22:41:10.942 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99BABBB' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2018-11-23 22:41:10.955 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7DAE' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2018-11-23 22:41:10.966 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SWSD:3014F711A0001F98A99A2C37:000A58A9939112' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2018-11-23 22:41:10.981 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D97' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2018-11-23 22:41:10.990 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D93' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2018-11-23 22:41:11.003 [hingStatusInfoChangedEvent] - 'homematic:HmIP-STH:3014F711A0001F98A99A2C37:000E58A991EFB7' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2018-11-23 22:41:11.011 [hingStatusInfoChangedEvent] - 'homematic:HmIP-STH:3014F711A0001F98A99A2C37:000E58A99C8956' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2018-11-23 22:41:11.021 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SWSD:3014F711A0001F98A99A2C37:000A58A9939251' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2018-11-23 22:41:11.032 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D68' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2018-11-23 22:41:11.045 [hingStatusInfoChangedEvent] - 'homematic:HmIP-STHO-A:3014F711A0001F98A99A2C37:0010D8A990A144' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2018-11-23 22:41:11.060 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SWSD:3014F711A0001F98A99A2C37:000A58A993922B' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2018-11-23 22:41:11.066 [hingStatusInfoChangedEvent] - 'homematic:HmIP-eTRV-2:3014F711A0001F98A99A2C37:000A18A993FD94' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2018-11-23 22:41:11.077 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BSM:3014F711A0001F98A99A2C37:000858A9960F14' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2018-11-23 22:41:11.090 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D5A' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
```

B4dR4cc00n picture B4dR4cc00n on 23 Nov 2018

the HM bridge is currently offline in OH, but if i go to inbox an discover new HM devices, all other HM device goes online, but not the HM bridge.

2018-11-25 20:04:30.925 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D5A' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2018-11-25 20:04:31.093 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B8453' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2018-11-25 20:04:31.310 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99BABBB' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2018-11-25 20:04:31.894 [hingStatusInfoChangedEvent] - 'homematic:HmIP-STHO-A:3014F711A0001F98A99A2C37:0010D8A990A144' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2018-11-25 20:04:32.233 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SWSD:3014F711A0001F98A99A2C37:000A58A9939251' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2018-11-25 20:04:32.339 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SWSD:3014F711A0001F98A99A2C37:000A58A9939112' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2018-11-25 20:04:32.502 [me.event.ThingUpdatedEvent] - Thing 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B8453' has been updated.

2018-11-25 20:04:33.572 [hingStatusInfoChangedEvent] - 'homematic:HmIP-eTRV-2:3014F711A0001F98A99A2C37:000A18A993FD94' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2018-11-25 20:04:33.790 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D97' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2018-11-25 20:04:35.773 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-eTRV-2:3014F711A0001F98A99A2C37:000A18A993FD94' has been updated.

2018-11-25 20:04:35.792 [hingStatusInfoChangedEvent] - 'homematic:HmIP-STH:3014F711A0001F98A99A2C37:000E58A991EFB7' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2018-11-25 20:04:35.896 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D93' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2018-11-25 20:04:36.515 [me.event.ThingUpdatedEvent] - Thing 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D97' has been updated.

2018-11-25 20:04:47.223 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-STH:3014F711A0001F98A99A2C37:000E58A991EFB7' has been updated.

2018-11-25 20:04:47.908 [home.event.InboxAddedEvent] - Discovery Result with UID 'homematic:HmIP-BSM:3014F711A0001F98A99A2C37:000858A9960D56' has been added.

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

2018-11-25 20:04:47.907 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'homematic:HmIP-BSM:3014F711A0001F98A99A2C37:000858A9960D56' to inbox.

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

2018-11-25 20:04:49.248 [hingStatusInfoChangedEvent] - 'homematic:HmIP-STH:3014F711A0001F98A99A2C37:000E58A99C8956' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2018-11-25 20:04:49.502 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7DAE' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2018-11-25 20:04:49.697 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SWSD:3014F711A0001F98A99A2C37:000A58A993922B' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

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

2018-11-25 20:04:49.845 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'homematic:HmIP-BSM:3014F711A0001F98A99A2C37:000858A9960F62' to inbox.

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

2018-11-25 20:04:49.846 [home.event.InboxAddedEvent] - Discovery Result with UID 'homematic:HmIP-BSM:3014F711A0001F98A99A2C37:000858A9960F62' has been added.

2018-11-25 20:04:50.042 [hingStatusInfoChangedEvent] - 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7D68' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2018-11-25 20:04:50.093 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-SWSD:3014F711A0001F98A99A2C37:000A58A993922B' has been updated.

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

2018-11-25 20:04:50.092 [INFO ] [ng.homematic.internal.misc.MiscUtils] - Datapoint name '${sysVarAlarmZone1}' contains invalid characters, new Datapoint name '__sysVarAlarmZone1_'

2018-11-25 20:04:50.127 [INFO ] [ng.homematic.internal.misc.MiscUtils] - Datapoint name '${sysVarPresence}' contains invalid characters, new Datapoint name '__sysVarPresence_'

2018-11-25 20:04:50.194 [INFO ] [rnal.type.HomematicTypeGeneratorImpl] - Multiple firmware versions for device type 'HmIP-STH' found (1.8.4, 2.2.0). Make sure, all devices of the same type have the same firmware version, otherwise you MAY have channel and/or datapoint errors in the logfile

2018-11-25 20:04:50.208 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 

java.lang.IllegalArgumentException: Illegal status REMOVED. The thing was in state OFFLINE and not in REMOVING

    at org.eclipse.smarthome.core.thing.internal.ThingManagerImpl$1.ensureValidStatus(ThingManagerImpl.java:219) ~[?:?]

    at org.eclipse.smarthome.core.thing.internal.ThingManagerImpl$1.statusUpdated(ThingManagerImpl.java:184) ~[?:?]

    at org.eclipse.smarthome.core.thing.binding.BaseThingHandler.updateStatus(BaseThingHandler.java:399) ~[?:?]

    at org.eclipse.smarthome.core.thing.binding.BaseThingHandler.updateStatus(BaseThingHandler.java:423) ~[?:?]

    at org.eclipse.smarthome.core.thing.binding.BaseThingHandler.handleRemoval(BaseThingHandler.java:127) ~[?:?]

    at org.eclipse.smarthome.binding.homematic.handler.HomematicThingHandler.handleRemoval(HomematicThingHandler.java:551) ~[?:?]

    at org.eclipse.smarthome.binding.homematic.handler.HomematicBridgeHandler.setOfflineStatus(HomematicBridgeHandler.java:231) ~[?:?]

    at org.eclipse.smarthome.binding.homematic.internal.discovery.HomematicDeviceDiscoveryService.lambda$0(HomematicDeviceDiscoveryService.java:197) ~[?:?]

    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) [?:?]

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

2018-11-25 20:04:50.853 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-STH:3014F711A0001F98A99A2C37:000E58A99C8956' has been updated.

2018-11-25 20:04:51.140 [me.event.ThingUpdatedEvent] - Thing 'homematic:HMIP-eTRV:3014F711A0001F98A99A2C37:000393C99B7DAE' has been updated.

2018-11-25 20:05:02.565 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:512:node5' has been updated.

2018-11-25 20:05:02.571 [vent.ConfigStatusInfoEvent] - ConfigStatusInfo [configStatusMessages=[]]

2018-11-25 20:05:02.678 [vent.ItemStateChangedEvent] - Zwave_Rollladen04_BlindsControl changed from 100 to 0

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

2018-11-25 20:06:34.216 [ERROR] [ersey.server.ServerRuntime$Responder] - An I/O error has occurred while writing a response message entity to the container output stream.

org.glassfish.jersey.server.internal.process.MappableException: org.eclipse.jetty.io.EofException

    at org.glassfish.jersey.server.internal.MappableExceptionWrapperInterceptor.aroundWriteTo(MappableExceptionWrapperInterceptor.java:92) ~[?:?]

    at org.glassfish.jersey.message.internal.WriterInterceptorExecutor.proceed(WriterInterceptorExecutor.java:162) ~[170:org.glassfish.jersey.core.jersey-common:2.22.2]

    at org.glassfish.jersey.message.internal.MessageBodyFactory.writeTo(MessageBodyFactory.java:1130) ~[170:org.glassfish.jersey.core.jersey-common:2.22.2]

    at org.glassfish.jersey.server.ServerRuntime$Responder.writeResponse(ServerRuntime.java:711) [171:org.glassfish.jersey.core.jersey-server:2.22.2]

    at org.glassfish.jersey.server.ServerRuntime$Responder.processResponse(ServerRuntime.java:444) [171:org.glassfish.jersey.core.jersey-server:2.22.2]

    at org.glassfish.jersey.server.ServerRuntime$Responder.process(ServerRuntime.java:434) [171:org.glassfish.jersey.core.jersey-server:2.22.2]

    at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:329) [171:org.glassfish.jersey.core.jersey-server:2.22.2]

    at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271) [170:org.glassfish.jersey.core.jersey-common:2.22.2]

    at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267) [170:org.glassfish.jersey.core.jersey-common:2.22.2]

    at org.glassfish.jersey.internal.Errors.process(Errors.java:315) [170:org.glassfish.jersey.core.jersey-common:2.22.2]

    at org.glassfish.jersey.internal.Errors.process(Errors.java:297) [170:org.glassfish.jersey.core.jersey-common:2.22.2]

    at org.glassfish.jersey.internal.Errors.process(Errors.java:267) [170:org.glassfish.jersey.core.jersey-common:2.22.2]

    at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317) [170:org.glassfish.jersey.core.jersey-common:2.22.2]

    at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) [171:org.glassfish.jersey.core.jersey-server:2.22.2]

    at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) [171:org.glassfish.jersey.core.jersey-server:2.22.2]

    at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) [168:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]

    at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) [168:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]

    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) [168:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]

    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) [168:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]

    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) [168:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]

    at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) [20:com.eclipsesource.jaxrs.publisher:5.3.1.201602281253]

    at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:865) [85:org.eclipse.jetty.servlet:9.4.11.v20180605]

    at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:535) [85:org.eclipse.jetty.servlet:9.4.11.v20180605]

    at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [185:org.ops4j.pax.web.pax-web-jetty:7.2.3]

    at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:146) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) [82:org.eclipse.jetty.security:9.4.11.v20180605]

    at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:257) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:293) [185:org.ops4j.pax.web.pax-web-jetty:7.2.3]

    at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) [85:org.eclipse.jetty.servlet:9.4.11.v20180605]

    at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [185:org.ops4j.pax.web.pax-web-jetty:7.2.3]

    at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.Server.handle(Server.java:531) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) [75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) [87:org.eclipse.jetty.util:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) [87:org.eclipse.jetty.util:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [87:org.eclipse.jetty.util:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) [87:org.eclipse.jetty.util:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) [87:org.eclipse.jetty.util:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762) [87:org.eclipse.jetty.util:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680) [87:org.eclipse.jetty.util:9.4.11.v20180605]

    at java.lang.Thread.run(Thread.java:748) [?:?]

Caused by: org.eclipse.jetty.io.EofException

    at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:286) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:429) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.io.WriteFlusher.completeWrite(WriteFlusher.java:384) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.io.ChannelEndPoint$3.run(ChannelEndPoint.java:133) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) ~[87:org.eclipse.jetty.util:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:295) ~[87:org.eclipse.jetty.util:9.4.11.v20180605]

    ... 6 more

Caused by: java.io.IOException: Daten眉bergabe unterbrochen (broken pipe)

    at sun.nio.ch.FileDispatcherImpl.write0(Native Method) ~[?:?]

    at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:47) ~[?:?]

    at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:93) ~[?:?]

    at sun.nio.ch.IOUtil.write(IOUtil.java:51) ~[?:?]

    at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:471) ~[?:?]

    at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:264) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:429) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.io.WriteFlusher.completeWrite(WriteFlusher.java:384) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.io.ChannelEndPoint$3.run(ChannelEndPoint.java:133) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) ~[87:org.eclipse.jetty.util:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:295) ~[87:org.eclipse.jetty.util:9.4.11.v20180605]

    ... 6 more

2018-11-25 20:06:34.231 [ERROR] [ersey.server.ServerRuntime$Responder] - An I/O error has occurred while writing a response message entity to the container output stream.

org.glassfish.jersey.server.internal.process.MappableException: org.eclipse.jetty.io.EofException

    at org.glassfish.jersey.server.internal.MappableExceptionWrapperInterceptor.aroundWriteTo(MappableExceptionWrapperInterceptor.java:92) ~[?:?]

    at org.glassfish.jersey.message.internal.WriterInterceptorExecutor.proceed(WriterInterceptorExecutor.java:162) ~[170:org.glassfish.jersey.core.jersey-common:2.22.2]

    at org.glassfish.jersey.message.internal.MessageBodyFactory.writeTo(MessageBodyFactory.java:1130) ~[170:org.glassfish.jersey.core.jersey-common:2.22.2]

    at org.glassfish.jersey.server.ServerRuntime$Responder.writeResponse(ServerRuntime.java:711) [171:org.glassfish.jersey.core.jersey-server:2.22.2]

    at org.glassfish.jersey.server.ServerRuntime$Responder.processResponse(ServerRuntime.java:444) [171:org.glassfish.jersey.core.jersey-server:2.22.2]

    at org.glassfish.jersey.server.ServerRuntime$Responder.process(ServerRuntime.java:434) [171:org.glassfish.jersey.core.jersey-server:2.22.2]

    at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:329) [171:org.glassfish.jersey.core.jersey-server:2.22.2]

    at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271) [170:org.glassfish.jersey.core.jersey-common:2.22.2]

    at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267) [170:org.glassfish.jersey.core.jersey-common:2.22.2]

    at org.glassfish.jersey.internal.Errors.process(Errors.java:315) [170:org.glassfish.jersey.core.jersey-common:2.22.2]

    at org.glassfish.jersey.internal.Errors.process(Errors.java:297) [170:org.glassfish.jersey.core.jersey-common:2.22.2]

    at org.glassfish.jersey.internal.Errors.process(Errors.java:267) [170:org.glassfish.jersey.core.jersey-common:2.22.2]

    at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317) [170:org.glassfish.jersey.core.jersey-common:2.22.2]

    at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) [171:org.glassfish.jersey.core.jersey-server:2.22.2]

    at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) [171:org.glassfish.jersey.core.jersey-server:2.22.2]

    at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) [168:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]

    at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) [168:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]

    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) [168:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]

    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) [168:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]

    at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) [168:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]

    at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) [20:com.eclipsesource.jaxrs.publisher:5.3.1.201602281253]

    at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:865) [85:org.eclipse.jetty.servlet:9.4.11.v20180605]

    at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:535) [85:org.eclipse.jetty.servlet:9.4.11.v20180605]

    at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [185:org.ops4j.pax.web.pax-web-jetty:7.2.3]

    at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:146) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) [82:org.eclipse.jetty.security:9.4.11.v20180605]

    at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:257) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:293) [185:org.ops4j.pax.web.pax-web-jetty:7.2.3]

    at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) [85:org.eclipse.jetty.servlet:9.4.11.v20180605]

    at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [185:org.ops4j.pax.web.pax-web-jetty:7.2.3]

    at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.Server.handle(Server.java:531) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) [84:org.eclipse.jetty.server:9.4.11.v20180605]

    at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) [75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) [87:org.eclipse.jetty.util:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) [87:org.eclipse.jetty.util:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [87:org.eclipse.jetty.util:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) [87:org.eclipse.jetty.util:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) [87:org.eclipse.jetty.util:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762) [87:org.eclipse.jetty.util:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680) [87:org.eclipse.jetty.util:9.4.11.v20180605]

    at java.lang.Thread.run(Thread.java:748) [?:?]

Caused by: org.eclipse.jetty.io.EofException

    at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:286) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:429) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.io.WriteFlusher.completeWrite(WriteFlusher.java:384) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.io.ChannelEndPoint$3.run(ChannelEndPoint.java:133) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) ~[87:org.eclipse.jetty.util:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:295) ~[87:org.eclipse.jetty.util:9.4.11.v20180605]

    ... 6 more

Caused by: java.io.IOException: Daten眉bergabe unterbrochen (broken pipe)

    at sun.nio.ch.FileDispatcherImpl.write0(Native Method) ~[?:?]

    at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:47) ~[?:?]

    at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:93) ~[?:?]

    at sun.nio.ch.IOUtil.write(IOUtil.java:65) ~[?:?]

    at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:471) ~[?:?]

    at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:264) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.io.WriteFlusher.flush(WriteFlusher.java:429) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.io.WriteFlusher.completeWrite(WriteFlusher.java:384) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.io.ChannelEndPoint$3.run(ChannelEndPoint.java:133) ~[75:org.eclipse.jetty.io:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) ~[87:org.eclipse.jetty.util:9.4.11.v20180605]

    at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:295) ~[87:org.eclipse.jetty.util:9.4.11.v20180605]

    ... 6 more
B4dR4cc00n picture B4dR4cc00n on 25 Nov 2018

The homematic binding doesn't work anymore with OCCU 3.41.11-2 and above. Please see changes https://github.com/jens-maus/RaspberryMatic/releases/tag/3.41.11.20181124

"All remote API XMLRPC ports (8181, 1999, 2010, 9292, 2001, 2000) for external applications to access HomeMatic services (e.g. ReGaHss, HMIPServer, etc.) on the CCU are now proxied through the lighttpd web Server."

On my RaspberryMatic I am running cuxd. The Openhab binding is using BINRPC for cuxd.
Because of the changes in OCCU 3.41.11-2 BINRPC doesn't work anymore from external hosts.

The only solution I could find was switching back to RaspberryMatic 2.35.16.20180826.
I had to switch back to 2.35, because I had no backup for OCCU 3.* Version.
In case you have a backup for 3.* switching back to RaspberryMatic 3.37.8.20181026 might work as well.

ErnstZwingenberger picture ErnstZwingenberger on 2 Dec 2018

@ErnstZwingenberger Which version of the Homematic binding are you using. I had the same problem after updating Raspberrymatic to 3.41.11.20181125 (I am now using 3.41.11.20181126). But with version 0.10.0.201811171951 of the Homematic binding everything worked again.

MHerbst picture MHerbst on 2 Dec 2018

I am using openHAB 2.3.0 stable
binding-homematic - 2.3.0

Thanks for the hint that it works again with 0.10.0.201811171951

ErnstZwingenberger picture ErnstZwingenberger on 2 Dec 2018

@MHerbst Using a different version of the binding did not change the problem for me. After initially connecting to the CCU and the things updating with values the connection breaks down with 2.3 stable and 2.4M6 and 2.4M7. Regardless of the RaspberryMatic version I'm using. Right now I have 3.41.11.20181126 also.

SymanK83 picture SymanK83 on 9 Dec 2018

@SymanK83 With the latest RaspberryMatic versions there have been some important changes regarding security that may have an impact on the communication.
Please check the firewall settings in CCU and make sure all necessary ports are open. In addition check also the "Sicherheit" settings and disable "Authentifizierung"

MHerbst picture MHerbst on 9 Dec 2018

I've updated to the latest version (3.41.11.20181126) and set ports to open in the security settings. "Authentifizierung" is also disabledin the settings.
After rebooting everything comes online, but goes offline due to connection lost to bridge after a few seconds, this happens every time I restart:

2018-12-10 18:29:02.933 [hingStatusInfoChangedEvent] - 'hue:0220:001788624dd3:3' changed from INITIALIZING to ONLINE
2018-12-10 18:29:04.875 [hingStatusInfoChangedEvent] - 'homematic:bridge:3014f711a0001f58a992ffae' changed from INITIALIZING to ONLINE
2018-12-10 18:29:04.922 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A995016B' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.930 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A995000B' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.936 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A994FFC7' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.937 [hingStatusInfoChangedEvent] - 'homematic:HMIP-SWDO:3014f711a0001f58a992ffae:0000D7099D1F07' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.938 [hingStatusInfoChangedEvent] - 'homematic:HMIP-WRC2:3014f711a0001f58a992ffae:000198A98BB506' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.939 [hingStatusInfoChangedEvent] - 'homematic:HM-LC-Sw1-DR:3014f711a0001f58a992ffae:OEQ2311315' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.940 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAED5' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.973 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAEB2' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.975 [hingStatusInfoChangedEvent] - 'homematic:GATEWAY-EXTRAS-3014F711A0001F58A992FFAE:3014f711a0001f58a992ffae:GWE00000000' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.976 [hingStatusInfoChangedEvent] - 'homematic:HM-LC-Dim1TPBU-FM:3014f711a0001f58a992ffae:MEQ0396235' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.976 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAE25' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.977 [hingStatusInfoChangedEvent] - 'homematic:HM-LC-Dim1TPBU-FM:3014f711a0001f58a992ffae:JEQ0203894' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.978 [hingStatusInfoChangedEvent] - 'homematic:HM-LC-Sw1-DR:3014f711a0001f58a992ffae:OEQ2311440' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.979 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAE91' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.980 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAECF' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.980 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAE0B' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.981 [hingStatusInfoChangedEvent] - 'homematic:HmIP-FDT:3014f711a0001f58a992ffae:000E17099C0043' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.982 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A995027F' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.982 [hingStatusInfoChangedEvent] - 'homematic:HM-RCV-50:3014f711a0001f58a992ffae:BidCoS-RF' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.983 [hingStatusInfoChangedEvent] - 'homematic:HM-CC-RT-DN:3014f711a0001f58a992ffae:MEQ0787527' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.984 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A994FFA5' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2018-12-10 18:29:04.998 [hingStatusInfoChangedEvent] - 'homematic:HM-LC-Sw1-DR:3014f711a0001f58a992ffae:OEQ2311315' changed from INITIALIZING to ONLINE
2018-12-10 18:29:05.032 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAED5' changed from INITIALIZING to ONLINE
2018-12-10 18:29:05.044 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A995000B' changed from INITIALIZING to ONLINE
2018-12-10 18:29:05.053 [hingStatusInfoChangedEvent] - 'homematic:HMIP-WRC2:3014f711a0001f58a992ffae:000198A98BB506' changed from INITIALIZING to ONLINE
2018-12-10 18:29:05.261 [me.event.ThingUpdatedEvent] - Thing 'homematic:HM-LC-Sw1-DR:3014f711a0001f58a992ffae:OEQ2311315' has been updated.
2018-12-10 18:29:05.267 [hingStatusInfoChangedEvent] - 'homematic:HMIP-SWDO:3014f711a0001f58a992ffae:0000D7099D1F07' changed from INITIALIZING to ONLINE
2018-12-10 18:29:05.448 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAED5' has been updated.
2018-12-10 18:29:05.449 [hingStatusInfoChangedEvent] - 'homematic:GATEWAY-EXTRAS-3014F711A0001F58A992FFAE:3014f711a0001f58a992ffae:GWE00000000' changed from INITIALIZING to ONLINE
2018-12-10 18:29:05.459 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAEB2' changed from INITIALIZING to ONLINE
2018-12-10 18:29:05.499 [me.event.ThingUpdatedEvent] - Thing 'homematic:GATEWAY-EXTRAS-3014F711A0001F58A992FFAE:3014f711a0001f58a992ffae:GWE00000000' has been updated.
2018-12-10 18:29:05.523 [me.event.ThingUpdatedEvent] - Thing 'homematic:HMIP-SWDO:3014f711a0001f58a992ffae:0000D7099D1F07' has been updated.
2018-12-10 18:29:05.793 [me.event.ThingUpdatedEvent] - Thing 'homematic:HMIP-WRC2:3014f711a0001f58a992ffae:000198A98BB506' has been updated.
2018-12-10 18:29:05.822 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A994FFC7' changed from INITIALIZING to ONLINE
2018-12-10 18:29:05.869 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A995016B' changed from INITIALIZING to ONLINE
2018-12-10 18:29:05.930 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAEB2' has been updated.
2018-12-10 18:29:06.064 [hingStatusInfoChangedEvent] - 'homematic:HM-LC-Dim1TPBU-FM:3014f711a0001f58a992ffae:MEQ0396235' changed from INITIALIZING to ONLINE
2018-12-10 18:29:07.717 [me.event.ThingUpdatedEvent] - Thing 'homematic:HM-LC-Dim1TPBU-FM:3014f711a0001f58a992ffae:MEQ0396235' has been updated.
2018-12-10 18:29:07.784 [hingStatusInfoChangedEvent] - 'homematic:HM-LC-Dim1TPBU-FM:3014f711a0001f58a992ffae:JEQ0203894' changed from INITIALIZING to ONLINE
2018-12-10 18:29:07.847 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAE25' changed from INITIALIZING to ONLINE
2018-12-10 18:29:08.970 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A995000B' has been updated.
2018-12-10 18:29:09.507 [me.event.ThingUpdatedEvent] - Thing 'homematic:HM-LC-Dim1TPBU-FM:3014f711a0001f58a992ffae:JEQ0203894' has been updated.
2018-12-10 18:29:09.542 [vent.ItemStateChangedEvent] - GuestWCLightSwitch changed from NULL to OFF
2018-12-10 18:29:09.551 [GroupItemStateChangedEvent] - gLights changed from NULL to OFF through GuestWCLightSwitch
2018-12-10 18:29:09.703 [hingStatusInfoChangedEvent] - 'homematic:HM-LC-Sw1-DR:3014f711a0001f58a992ffae:OEQ2311440' changed from INITIALIZING to ONLINE
2018-12-10 18:29:09.718 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAE0B' changed from INITIALIZING to ONLINE
2018-12-10 18:29:09.821 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAE25' has been updated.
2018-12-10 18:29:10.101 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAE0B' has been updated.
2018-12-10 18:29:10.104 [me.event.ThingUpdatedEvent] - Thing 'homematic:HM-LC-Sw1-DR:3014f711a0001f58a992ffae:OEQ2311440' has been updated.
2018-12-10 18:29:10.161 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAE91' changed from INITIALIZING to ONLINE
2018-12-10 18:29:10.220 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAE91' has been updated.
2018-12-10 18:29:10.267 [hingStatusInfoChangedEvent] - 'homematic:HmIP-FDT:3014f711a0001f58a992ffae:000E17099C0043' changed from INITIALIZING to ONLINE
2018-12-10 18:29:10.377 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A994FFC7' has been updated.
2018-12-10 18:29:10.385 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A995016B' has been updated.
2018-12-10 18:29:10.393 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A995027F' changed from INITIALIZING to ONLINE
2018-12-10 18:29:10.460 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAECF' changed from INITIALIZING to ONLINE
2018-12-10 18:29:10.509 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAECF' has been updated.
2018-12-10 18:29:10.539 [hingStatusInfoChangedEvent] - 'homematic:HM-RCV-50:3014f711a0001f58a992ffae:BidCoS-RF' changed from INITIALIZING to ONLINE
2018-12-10 18:29:10.620 [hingStatusInfoChangedEvent] - 'homematic:HM-CC-RT-DN:3014f711a0001f58a992ffae:MEQ0787527' changed from INITIALIZING to ONLINE
2018-12-10 18:29:10.749 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A994FFA5' changed from INITIALIZING to ONLINE
2018-12-10 18:29:10.913 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-FDT:3014f711a0001f58a992ffae:000E17099C0043' has been updated.
2018-12-10 18:29:11.335 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A995027F' has been updated.
2018-12-10 18:29:11.373 [me.event.ThingUpdatedEvent] - Thing 'homematic:HM-CC-RT-DN:3014f711a0001f58a992ffae:MEQ0787527' has been updated.
2018-12-10 18:29:11.745 [me.event.ThingUpdatedEvent] - Thing 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A994FFA5' has been updated.
2018-12-10 18:29:11.956 [me.event.ThingUpdatedEvent] - Thing 'homematic:HM-RCV-50:3014f711a0001f58a992ffae:BidCoS-RF' has been updated.
2018-12-10 18:29:49.939 [hingStatusInfoChangedEvent] - 'homematic:bridge:3014f711a0001f58a992ffae' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Connection lost
2018-12-10 18:29:49.943 [hingStatusInfoChangedEvent] - 'homematic:HM-LC-Sw1-DR:3014f711a0001f58a992ffae:OEQ2311315' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.944 [hingStatusInfoChangedEvent] - 'homematic:GATEWAY-EXTRAS-3014F711A0001F58A992FFAE:3014f711a0001f58a992ffae:GWE00000000' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.948 [hingStatusInfoChangedEvent] - 'homematic:HMIP-SWDO:3014f711a0001f58a992ffae:0000D7099D1F07' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.949 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAEB2' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.950 [hingStatusInfoChangedEvent] - 'homematic:HM-LC-Dim1TPBU-FM:3014f711a0001f58a992ffae:MEQ0396235' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.951 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A995000B' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.953 [hingStatusInfoChangedEvent] - 'homematic:HM-LC-Dim1TPBU-FM:3014f711a0001f58a992ffae:JEQ0203894' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.955 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAE25' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.957 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAE0B' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.958 [hingStatusInfoChangedEvent] - 'homematic:HM-LC-Sw1-DR:3014f711a0001f58a992ffae:OEQ2311440' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.958 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAE91' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.959 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A995016B' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.960 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A994FFC7' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.961 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAECF' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.962 [hingStatusInfoChangedEvent] - 'homematic:HmIP-FDT:3014f711a0001f58a992ffae:000E17099C0043' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.963 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A995027F' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.964 [hingStatusInfoChangedEvent] - 'homematic:HM-CC-RT-DN:3014f711a0001f58a992ffae:MEQ0787527' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.965 [hingStatusInfoChangedEvent] - 'homematic:HmIP-BWTH:3014f711a0001f58a992ffae:000C98A994FFA5' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.965 [hingStatusInfoChangedEvent] - 'homematic:HM-RCV-50:3014f711a0001f58a992ffae:BidCoS-RF' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.966 [hingStatusInfoChangedEvent] - 'homematic:HMIP-WRC2:3014f711a0001f58a992ffae:000198A98BB506' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2018-12-10 18:29:49.967 [hingStatusInfoChangedEvent] - 'homematic:HmIP-SRH:3014f711a0001f58a992ffae:0007D8A98BAED5' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
pahofmann picture pahofmann on 10 Dec 2018

@MHerbst Thank you for the tips, unfortunately the settings were correct already.
My workaround is to assign a macvlan to my docker container. That fixes the problem, although I don鈥榯 understand what the problem is with the usual bridged network a docker container uses by default.

SymanK83 picture SymanK83 on 11 Dec 2018

@SymanK83 could you maybe share the config of your workaround?

pahofmann picture pahofmann on 11 Dec 2018

@pahofmann Of course, I hope that helps:
The entry in my things-file looks like this now (much simpler than ever before):

Bridge homematic:bridge:ccu [ gatewayAddress="192.168.174.15" ]
{
...
}

I manually created my macvlan for docker with this command:
docker network create -d macvlan --subnet=192.168.174.0/24 --gateway=192.168.174.1 -o parent=enp3s0 openhab

My docker-compose-file containing the openHAB service looks like this (I deleted some other services not relevant for this particular case). To be honest I'm not sure whether I still need the port mapping for this container, I did not try to delete them yet, as I'm just glad that the automatic control for my heating is up and running again and try to minimise further difficulties for the moment:

version: '3.1'

services:
  openhab:
    image: openhab/openhab:2.4.0.M7-amd64-debian
    container_name: openhab
    restart: always
    tty: true
    volumes:
      - /etc/localtime:/etc/localtime:ro
      - /etc/timezone:/etc/timezone:ro
      - ./conf/openhab2:/openhab/conf
      - ./log/openhab2:/openhab/userdata
      - ./conf/openhab2/addons:/openhab/addons
    networks:
        - local_backend
        - snapcast-server
        - default
    ports:
        - 9090:8080
        - 9443:8443
        - 5555:5555
        - 9125:9125
        - 9126:9126
        - 8181:8181
        - 9292:9292
        - 2000:2000
        - 2001:2001
        - 2010:2010
        - 8701:8701
        - 8101:8101
        - 43439:43439

networks:
  default:
    external:
      name: services

  snapcast-server:
    external:
      name: snapcast-server

  local_backend:
SymanK83 picture SymanK83 on 12 Dec 2018

Just FYI: It worked flawlessly until the day before yesterday, now I'm back to "Connection lost on gateway 'ccu', cause: "No Pong received!"".
I just don't get it.
Did you get any further?

SymanK83 picture SymanK83 on 15 Dec 2018

Wasn't able to get the macvlan to work yet. Keep getting an error when bringing up the container:
failed to create the macvlan port: operation not supported

Otherwise it is still the same. With host network mode it works for a minute after restarting the container, then connection to the gateway is lost.

pahofmann picture pahofmann on 15 Dec 2018

I did a bit more testing. For the one minute after reboot, when the gateway is shown online I can interact with the homeatic components just fine. After that, when die bridge shows connection status, I can for example turn light on, but not off again.

@MHerbst is there any additional debug data I could provide for troubleshooting?

pahofmann picture pahofmann on 15 Dec 2018

@pahofmann is your docker running on a NAS or on a "standard" linux machine? I had some issues with networking on a NAS (QNAP specifically).
@MHerbst If I can provide any additional information I'd be happy to as well. I'm not able to get this to work on my own.

SymanK83 picture SymanK83 on 15 Dec 2018

My docker host is a standard (arch)linux server.

Is there a way to temporarily disable the Ping/Pong check? Besides the check failing everything seems to work fine.

pahofmann picture pahofmann on 15 Dec 2018

I did not yet contribute anything to the openHAB environment yet and have not yet gathered any experience with the development environment etc., so I'm not easily able to build and use an altered version of the binding.
Looking at the sources I saw that the binding does not wait for a pong if the connected Homematic central is a CCU1. Therefore adding gatewayType="CCU" to the thing configuration surpresses this mechanism. For me the connection stays stable if I use this added parameter.
Not all of my things go online, maybe this is obvious, but I'm not familiar with the way RaspberryMatic compares to CCU1 and CCU2 communication wise.

Edit: I just saw, that although the things are shown as OFFLINE, I can control them just fine and their status is updated as well. Weird.

SymanK83 picture SymanK83 on 16 Dec 2018

@SymanK83 I don't think that I can help you with your configuration, because I have no experience with openHAB running in docker. I am using two Raspis (one for openHAB and one for Raspberrymatic) and I never had those problems.
Maybe @gerrieg has an idea or knows whether it would be an option to suppress the wait for the "pong".

MHerbst picture MHerbst on 16 Dec 2018

@MHerbst Thank you, I was not expecting particular help, I think it must be something specific to my combination of docker container and Raspberrymatic as well. Thank you none the less.
Maybe it help @pahofmann to get a running configuration if nothing else, over here it works pretty well since this morning.

SymanK83 picture SymanK83 on 16 Dec 2018

I tried it with gateway type CCU, but that did not help either unfortunately.
I did a little more digging and found out that the container bound to localhost, even if I entered the local IP of the docker host as the callback address.

With 0.0.0.0 as the callback address I can reach Port 9125 from the raspberrymatric, which I checked via SSH/telnet. But the Gateway still disconnects.

I saw that the docker container does not bind anything on port 9126 as mentioned in the documentation, could that be an indicator?

pahofmann picture pahofmann on 16 Dec 2018

I use openHab 2.4.0-amd64-debian on Debian Stretch with Docker 18.06.1-ce and RaspberryMatic 3.41.11.20181126. I use network_mode:host and i don't have any issues. I haven't tested it with a docker network yet.

gerrieg picture gerrieg on 18 Dec 2018

That is strength, I have the same settings.
Any idea how I can further troubleshoot why the "pong" isn't received/recognized?
Are there any logs I could check on the raspberrymatic side?

Can't be a general connection issue, in that case it wouldn't work fine the ~minute until the disconnect.

pahofmann picture pahofmann on 18 Dec 2018

From your description, it sounds as if you could send commands to the gateway, but you are not receiving any events. The 1 minute is the delay for the connection tracker at startup. The tracker sends the ping and seems to receive no pong.

You can enable DEBUG mode for the binding:
log:set DEBUG org.eclipse.smarthome.binding.homematic

Then create a event (not via openHab) and check whether the event is also visible in the log file.

I think the basic communication works, but the gateway can't send back events.

gerrieg picture gerrieg on 18 Dec 2018

Could you tell me the port on which the binding is listening for the "pong"?
As mentioned above, the openhab container only binds to Port 9125, which I can reach via telnet. But the documentation also mentions port 9126, which isn't utilized by my container.

I tried what you suggested after setting the log level to debug. I don't see any events in the logs though when switching the homematic items outside of openhab.
After the dis connect the XML-RPC server keeps restarting:

2018-12-18 20:57:22.007 [DEBUG] [nal.communicator.server.XmlRpcServer] - Stopping XML-RPC server
2018-12-18 20:57:22.012 [DEBUG] [nal.communicator.server.XmlRpcServer] - Initializing XML-RPC server at port 9125
2018-12-18 20:57:47.095 [DEBUG] [nal.communicator.server.XmlRpcServer] - Stopping XML-RPC server
2018-12-18 20:57:47.102 [DEBUG] [nal.communicator.server.XmlRpcServer] - Initializing XML-RPC server at port 9125
2018-12-18 20:58:12.177 [DEBUG] [nal.communicator.server.XmlRpcServer] - Stopping XML-RPC server
2018-12-18 20:58:12.184 [DEBUG] [nal.communicator.server.XmlRpcServer] - Initializing XML-RPC server at port 9125
2018-12-18 20:58:37.258 [DEBUG] [nal.communicator.server.XmlRpcServer] - Stopping XML-RPC server
2018-12-18 20:58:37.264 [DEBUG] [nal.communicator.server.XmlRpcServer] - Initializing XML-RPC server at port 9125
2018-12-18 20:59:02.347 [DEBUG] [nal.communicator.server.XmlRpcServer] - Stopping XML-RPC server
2018-12-18 20:59:02.358 [DEBUG] [nal.communicator.server.XmlRpcServer] - Initializing XML-RPC server at port 9125

pahofmann picture pahofmann on 18 Dec 2018

In your case, 9125 (XML-RPC port), but you can change it in the bridge settings if you like. 9126 is only used for BIN-RPC (CUxD). If you don't have CUxD, 9126 is not used.

OK, that's what I thought. You don't receive any events from the gateway. Either network/firewall problem or you can try another XML-RPC port.
But first try to set the callbackHost to the IP address of the docker host.

gerrieg picture gerrieg on 19 Dec 2018

That unfortunately doesn't work, as mentioned above. If I set it to the callback hostIP of the docker host, it just binds to localhost anyway.
But if I set it to 0.0.0.0 (all IPs), it works and I can reach the docker host from the raspberrymatic on port 9125 via telnet. The gateway still disconnects though.

pahofmann picture pahofmann on 19 Dec 2018

I guess the error is that callbackHost defines two things:

  • the interface to bind to
  • the IP that is sent to CCU as the RPC host

In your case, those two addresses can't be the same, but it's not possible (as far as I know) to set them separately...
If you set it to 0.0.0.0, it can be bound, but the CCU can't reach OH via this IP, obviously.
If you set it to your host IP, it can't be bound (thus the fallback to 127.0.0.1) but the communication to the CCU is correct.

oliverrahner picture oliverrahner on 19 Dec 2018
👍1

I added a new bridge parameter: bindAddress
Please try this version of the binding: https://drive.google.com/open?id=1rDGGl3HPo1-SWhTA2uo_nmkEM1G3PRLo

in your case, the bindAddress=0.0.0.0 and the callbackHost is the docker host IP

gerrieg picture gerrieg on 19 Dec 2018
🎉3 👍3

Yep, that was it!

[DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (String) value '3014f
711a0001f58a992ffae' for 'CENTRAL:0#PONG' from gateway with id '3014f711a0001f58a992ffae

Thank you very much for your support!

pahofmann picture pahofmann on 19 Dec 2018
🎉2

I just wanted to try as well, but failed to load the version of the binding which includes the bindAddress parameter. I copied it to the addons folder, cleared cache and tmp, restarted openHAB but received the following message during startup. I'm trying to get better at debugging the system and experiment with it, could you tell me what went wrong here?
2018-12-19 20:28:01.406 [WARN ] [org.apache.felix.fileinstall ] - Error while starting bundle: file:/openhab/addons/org.eclipse.smarthome.binding.homematic-0.10.0-SNAPSHOT.jar
org.osgi.framework.BundleException: Could not resolve module: org.eclipse.smarthome.binding.homematic [191]
Unresolved requirement: Import-Package: org.eclipse.jdt.annotation; resolution:="optional"
Unresolved requirement: Import-Package: org.jupnp.model.meta
at org.eclipse.osgi.container.Module.start(Module.java:444) ~[?:?]
at org.eclipse.osgi.internal.framework.EquinoxBundle.start(EquinoxBundle.java:383) ~[?:?]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundle(DirectoryWatcher.java:1260) [10:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundles(DirectoryWatcher.java:1233) [10:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.startAllBundles(DirectoryWatcher.java:1221) [10:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.doProcess(DirectoryWatcher.java:515) [10:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.process(DirectoryWatcher.java:365) [10:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.run(DirectoryWatcher.java:316) [10:org.apache.felix.fileinstall:3.6.4]
2018-12-19 20:28:01.459 [WARN ] [org.apache.felix.fileinstall ] - Error while starting bundle: file:/openhab/addons/org.eclipse.smarthome.binding.homematic-0.10.0-SNAPSHOT.jar
org.osgi.framework.BundleException: Could not resolve module: org.eclipse.smarthome.binding.homematic [191]
Unresolved requirement: Import-Package: org.eclipse.jdt.annotation; resolution:="optional"
Unresolved requirement: Import-Package: org.jupnp.model.meta
at org.eclipse.osgi.container.Module.start(Module.java:444) ~[?:?]
at org.eclipse.osgi.internal.framework.EquinoxBundle.start(EquinoxBundle.java:383) ~[?:?]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundle(DirectoryWatcher.java:1260) [10:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.startBundles(DirectoryWatcher.java:1233) [10:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.doProcess(DirectoryWatcher.java:520) [10:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.process(DirectoryWatcher.java:365) [10:org.apache.felix.fileinstall:3.6.4]
at org.apache.felix.fileinstall.internal.DirectoryWatcher.run(DirectoryWatcher.java:316) [10:org.apache.felix.fileinstall:3.6.4]

SymanK83 picture SymanK83 on 19 Dec 2018

@gerrieg Now that's what I call quick reaction ;)

@SymanK83 which OpenHAB version are you on?

oliverrahner picture oliverrahner on 19 Dec 2018

@oliverrahner I鈥榤 running 2.4.0.

SymanK83 picture SymanK83 on 20 Dec 2018

@SymanK83
Copy the new jar to the addons folder. Then, in the Karaf console write
bundle:list | grep Homematic

You should see 2 Versions of the binding and one active. For the next commands, use the ID.

bundle:stop ID_OLD_VERSION
bundle:uninstall ID_OLD_VERSION

bundle:start ID_NEW_VERSION

Then restart openHab.

gerrieg picture gerrieg on 22 Dec 2018

I added a new bridge parameter: bindAddress
Please try this version of the binding: https://drive.google.com/open?id=1rDGGl3HPo1-SWhTA2uo_nmkEM1G3PRLo

in your case, the bindAddress=0.0.0.0 and the callbackHost is the docker host IP

Thanks, that finally solves the problem with the 2.4.0 homematic binding running inside a docker setup where an isolated network is used (e.g NOT _network_mode: "host"_). Also possibly related to #6576.

I get some bundle resolution errors, but so far it's working again.
21:57:22.656 [ERROR] [.core.karaf.internal.FeatureInstaller] - Failed installing 'openhab-action-mqtt, openhab-binding-mqtt1': Error restarting bundles: Could not resolve module: org.eclipse.smarthome.binding.homematic [253] Another singleton bundle selected: osgi.identity; type="osgi.bundle"; version:Version="0.10.0.201812191738"; osgi.identity="org.eclipse.smarthome.binding.homematic"; singleton:="true"

mathismoder picture mathismoder on 22 Dec 2018

You should see 2 Versions of the binding and one active. For the next commands, use the ID.

bundle:stop ID_OLD_VERSION
bundle:uninstall ID_OLD_VERSION

bundle:start ID_NEW_VERSION

Then restart openHab.

Thank you for your contribution. Unfortunately it doesn't work for me (OH 2.3) , I've got the message;

2018-12-23 23:39:14.202 [ERROR] [org.apache.felix.fileinstall ] - Failed to install artifact: /usr/share/openhab2/addons/org.eclipse.smarthome.binding.homematic-0.10.0-SNAPSHOT.jar

Any idea?

Harlempix picture Harlempix on 23 Dec 2018

@mathismoder
Did you uninstall the old version?

@Harlempix
yes, use 2.4 and then update the Homematic jar file.

gerrieg picture gerrieg on 24 Dec 2018

yes, use 2.4 and then update the Homematic jar file.

Oh. Does it really require 2.4? I'm asking because I've installed 2.4 accidently and nothing worked anymore so I've made a rollback. Never touch a running system... But now I want to integrate the HM-driven radiators into my OH.

Harlempix picture Harlempix on 24 Dec 2018

@SymanK83
Copy the new jar to the addons folder. Then, in the Karaf console write
bundle:list | grep Homematic

You should see 2 Versions of the binding and one active. For the next commands, use the ID.

bundle:stop ID_OLD_VERSION
bundle:uninstall ID_OLD_VERSION

bundle:start ID_NEW_VERSION

Then restart openHab.

@gerrieg This worked perfectly, I'm now on the snapshot-binding and as @pahofmann already said: This solved the issue for me and my docker container now communicates with the RaspberryMatic perfectly well. Thanks for your help!

SymanK83 picture SymanK83 on 25 Dec 2018

will this be fixed in openhab 2.4.x?
When will it be available in a docker image? :-D
Openhab 2.4 completely broke my docker setup, which is somehow really annoying for a home automation system.

RayBa82 picture RayBa82 on 27 Dec 2018

@RayBa82 Although I cannot comment on the timing for the fix in the official release version I can at least tell you: The same thing happened for my system when I lost the ability to control the heating via openHAB. The above described fix to temporarily use the snapshot binding provided by @gerrieg worked perfectly for me, so you can salvage your configuration pretty easily until then.

SymanK83 picture SymanK83 on 27 Dec 2018
👍1

Hello, I would like to report, that I had the same problem as reported by @pahofmann in a different setup and that the test binding provided by @gerrieg (https://github.com/eclipse/smarthome/issues/6547#issuecomment-448686798) solved the issue.

The setup:

  • raspbian 4.14.79-v7+
  • piVCCU version: 3.41.11-12 (unstalled using kernel modules, keeping original raspbian kernel)
  • openhab version 2.4.0

What I did:

ankorth picture ankorth on 1 Jan 2019
👍1

Solved my issue, when openhab is running as service in docker swarm
https://community.openhab.org/t/deploying-oh-2-4-0-to-docker-swarm/64060/7

lukicsl picture lukicsl on 15 Jan 2019
Was this page helpful?
0 / 5 - 0 ratings

Related issues

airix1 picture airix1  路  3Comments
mherwege picture mherwege  路  10Comments
binderth picture binderth  路  4Comments
individualist picture individualist  路  3Comments
Josar picture Josar  路  7Comments