[SOLVED] Problems after upgrading to 2.4. Openhab not reachable

proutska · January 3, 2019, 7:34pm

Hi,

I’m working with OpenHAB for several years now without any big problems. After an upgrade from 2.3 to 2.4 I see many errors in the log files. In daytime I get some errors after using the mobile app. #587 seems to give a solution for this, I will try this out. In night time I get different error on several bindings, in the morning I can’t reach the web UI or use the app. I have to reboot the pi. After that everything is working again until next morning.

I have searched the forums but I can’t find any information about it. Can anyone please advise me how I can solve this problem.

Thanks in advance,

Sander

Platform information:
- Hardware: Raspberry Pi 3 Model B Plus Rev 1.3
- OS: _Openhabian
- Java Runtime Environment: openjdk version “1.8.0_152”
- openHAB version: openHAB 2.4.0-1 (Release Build)

2019-01-03 00:00:00.007 [INFO ] [ng.fritzbox.internal.FritzboxBinding] - Fritzbox conditional deActivate: true
2019-01-03 00:00:00.022 [INFO ] [ng.fritzbox.internal.FritzboxBinding] - Lost connection to Fritzbox because of interrupt
2019-01-03 00:00:00.025 [INFO ] [ng.fritzbox.internal.FritzboxBinding] - Attempting connection to FritzBox on 192.168.2.252:1012...
2019-01-03 00:00:00.031 [INFO ] [ng.fritzbox.internal.FritzboxBinding] - Connected to FritzBox on 192.168.2.252:1012
2019-01-03 00:00:30.126 [INFO ] [thome.binding.astro.internal.job.Job] - Scheduled Astro event-jobs for thing astro:moon:local
2019-01-03 00:00:30.137 [INFO ] [thome.binding.astro.internal.job.Job] - Scheduled Astro event-jobs for thing astro:sun:local
2019-01-03 01:58:56.003 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
su.litvak.chromecast.api.v2.ChromeCastException: Remote socket closed
	at su.litvak.chromecast.api.v2.Channel.read(Channel.java:425) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:51) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:137) [237:org.openhab.binding.chromecast:2.4.0]
2019-01-03 01:58:56.029 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message 
2019-01-03 02:42:15.309 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
java.net.SocketException: Connection reset
	at java.net.SocketInputStream.read(SocketInputStream.java:210) ~[?:?]
	at java.net.SocketInputStream.read(SocketInputStream.java:141) ~[?:?]
	at sun.security.ssl.InputRecord.readFully(InputRecord.java:465) ~[?:?]
	at sun.security.ssl.InputRecord.read(InputRecord.java:503) ~[?:?]
	at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:983) ~[?:?]
	at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:940) ~[?:?]
	at sun.security.ssl.AppInputStream.read(AppInputStream.java:105) ~[?:?]
	at sun.security.ssl.AppInputStream.read(AppInputStream.java:71) ~[?:?]
	at su.litvak.chromecast.api.v2.Channel.read(Channel.java:423) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:51) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:137) [237:org.openhab.binding.chromecast:2.4.0]
2019-01-03 02:42:15.325 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message 
2019-01-03 02:42:23.634 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
java.net.SocketException: Connection reset
	at java.net.SocketInputStream.read(SocketInputStream.java:210) ~[?:?]
	at java.net.SocketInputStream.read(SocketInputStream.java:141) ~[?:?]
	at sun.security.ssl.InputRecord.readFully(InputRecord.java:465) ~[?:?]
	at sun.security.ssl.InputRecord.read(InputRecord.java:503) ~[?:?]
	at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:983) ~[?:?]
	at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:940) ~[?:?]
	at sun.security.ssl.AppInputStream.read(AppInputStream.java:105) ~[?:?]
	at sun.security.ssl.AppInputStream.read(AppInputStream.java:71) ~[?:?]
	at su.litvak.chromecast.api.v2.Channel.read(Channel.java:423) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:51) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:137) [237:org.openhab.binding.chromecast:2.4.0]
2019-01-03 02:42:23.643 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message 
2019-01-03 03:05:36.645 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
su.litvak.chromecast.api.v2.ChromeCastException: Remote socket closed
	at su.litvak.chromecast.api.v2.Channel.read(Channel.java:425) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:51) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:137) [237:org.openhab.binding.chromecast:2.4.0]
2019-01-03 03:05:36.655 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message 
2019-01-03 03:06:49.259 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while handling
java.net.SocketException: Broken pipe (Write failed)
	at java.net.SocketOutputStream.socketWrite0(Native Method) ~[?:?]
	at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:111) ~[?:?]
	at java.net.SocketOutputStream.write(SocketOutputStream.java:155) ~[?:?]
	at sun.security.ssl.OutputRecord.writeBuffer(OutputRecord.java:431) ~[?:?]
	at sun.security.ssl.OutputRecord.write(OutputRecord.java:417) ~[?:?]
	at sun.security.ssl.SSLSocketImpl.writeRecordInternal(SSLSocketImpl.java:886) ~[?:?]
	at sun.security.ssl.SSLSocketImpl.writeRecord(SSLSocketImpl.java:857) ~[?:?]
	at sun.security.ssl.AppOutputStream.write(AppOutputStream.java:123) ~[?:?]
	at java.io.OutputStream.write(OutputStream.java:75) ~[?:?]
	at su.litvak.chromecast.api.v2.Channel.write(Channel.java:413) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.write(Channel.java:409) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.write(Channel.java:396) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.access$000(Channel.java:51) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:200) [237:org.openhab.binding.chromecast:2.4.0]
2019-01-03 03:06:49.265 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
javax.net.ssl.SSLException: Connection has been shutdown: javax.net.ssl.SSLException: java.net.SocketException: Broken pipe (Write failed)
	at sun.security.ssl.SSLSocketImpl.checkEOF(SSLSocketImpl.java:1551) ~[?:?]
	at sun.security.ssl.AppInputStream.read(AppInputStream.java:95) ~[?:?]
	at sun.security.ssl.AppInputStream.read(AppInputStream.java:71) ~[?:?]
	at su.litvak.chromecast.api.v2.Channel.read(Channel.java:423) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:51) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:137) [237:org.openhab.binding.chromecast:2.4.0]
Caused by: javax.net.ssl.SSLException: java.net.SocketException: Broken pipe (Write failed)
	at sun.security.ssl.Alerts.getSSLException(Alerts.java:208) ~[?:?]
	at sun.security.ssl.SSLSocketImpl.fatal(SSLSocketImpl.java:1959) ~[?:?]
	at sun.security.ssl.SSLSocketImpl.fatal(SSLSocketImpl.java:1916) ~[?:?]
	at sun.security.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1880) ~[?:?]
	at sun.security.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1825) ~[?:?]
	at sun.security.ssl.AppOutputStream.write(AppOutputStream.java:128) ~[?:?]
	at java.io.OutputStream.write(OutputStream.java:75) ~[?:?]
	at su.litvak.chromecast.api.v2.Channel.write(Channel.java:413) ~[?:?]
	at su.litvak.chromecast.api.v2.Channel.write(Channel.java:409) ~[?:?]
	at su.litvak.chromecast.api.v2.Channel.write(Channel.java:396) ~[?:?]
	at su.litvak.chromecast.api.v2.Channel.access$000(Channel.java:51) ~[?:?]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:200) ~[?:?]
Caused by: java.net.SocketException: Broken pipe (Write failed)
	at java.net.SocketOutputStream.socketWrite0(Native Method) ~[?:?]
	at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:111) ~[?:?]
	at java.net.SocketOutputStream.write(SocketOutputStream.java:155) ~[?:?]
	at sun.security.ssl.OutputRecord.writeBuffer(OutputRecord.java:431) ~[?:?]
	at sun.security.ssl.OutputRecord.write(OutputRecord.java:417) ~[?:?]
	at sun.security.ssl.SSLSocketImpl.writeRecordInternal(SSLSocketImpl.java:886) ~[?:?]
	at sun.security.ssl.SSLSocketImpl.writeRecord(SSLSocketImpl.java:857) ~[?:?]
	at sun.security.ssl.AppOutputStream.write(AppOutputStream.java:123) ~[?:?]
	at java.io.OutputStream.write(OutputStream.java:75) ~[?:?]
	at su.litvak.chromecast.api.v2.Channel.write(Channel.java:413) ~[?:?]
	at su.litvak.chromecast.api.v2.Channel.write(Channel.java:409) ~[?:?]
	at su.litvak.chromecast.api.v2.Channel.write(Channel.java:396) ~[?:?]
	at su.litvak.chromecast.api.v2.Channel.access$000(Channel.java:51) ~[?:?]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:200) ~[?:?]
2019-01-03 03:06:49.316 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message 
2019-01-03 03:19:49.216 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
su.litvak.chromecast.api.v2.ChromeCastException: Remote socket closed
	at su.litvak.chromecast.api.v2.Channel.read(Channel.java:425) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:51) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:137) [237:org.openhab.binding.chromecast:2.4.0]
2019-01-03 03:19:49.222 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message 
2019-01-03 03:20:41.337 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
su.litvak.chromecast.api.v2.ChromeCastException: Remote socket closed
	at su.litvak.chromecast.api.v2.Channel.read(Channel.java:425) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:51) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:137) [237:org.openhab.binding.chromecast:2.4.0]
2019-01-03 03:20:41.344 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message 
2019-01-03 03:21:25.865 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 03:21:25.919 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 03:21:26.866 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 03:21:26.879 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 03:26:53.561 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
su.litvak.chromecast.api.v2.ChromeCastException: Remote socket closed
	at su.litvak.chromecast.api.v2.Channel.read(Channel.java:425) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:51) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:137) [237:org.openhab.binding.chromecast:2.4.0]
2019-01-03 03:26:53.569 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message 
2019-01-03 03:34:14.905 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 03:34:14.912 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 03:34:15.906 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 03:34:15.920 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully

2019-01-03 03:40:56.957 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
su.litvak.chromecast.api.v2.ChromeCastException: Remote socket closed
	at su.litvak.chromecast.api.v2.Channel.read(Channel.java:425) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:51) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:137) [237:org.openhab.binding.chromecast:2.4.0]
2019-01-03 03:40:56.962 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message 
2019-01-03 03:42:56.653 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
su.litvak.chromecast.api.v2.ChromeCastException: Remote socket closed
	at su.litvak.chromecast.api.v2.Channel.read(Channel.java:425) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:51) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:137) [237:org.openhab.binding.chromecast:2.4.0]
2019-01-03 03:42:56.669 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message 


2019-01-03 04:40:08.476 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
su.litvak.chromecast.api.v2.ChromeCastException: Remote socket closed
	at su.litvak.chromecast.api.v2.Channel.read(Channel.java:425) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:51) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:137) [237:org.openhab.binding.chromecast:2.4.0]
2019-01-03 04:40:08.480 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message 
2019-01-03 04:40:40.481 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.events.internal.EventBridge@15c45fd' takes more than 5000ms.

2019-01-03 05:08:38.749 [ERROR] [.smarthome.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2019-01-03 05:08:38.749 [ERROR] [.smarthome.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed

2019-01-03 05:12:57.536 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
su.litvak.chromecast.api.v2.ChromeCastException: Remote socket closed
	at su.litvak.chromecast.api.v2.Channel.read(Channel.java:425) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:51) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:137) [237:org.openhab.binding.chromecast:2.4.0]
2019-01-03 05:12:57.540 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message 

2019-01-03 05:32:23.957 [ERROR] [.smarthome.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2019-01-03 05:32:23.979 [ERROR] [.smarthome.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2019-01-03 05:32:23.975 [ERROR] [.smarthome.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed

2019-01-03 05:40:54.090 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
su.litvak.chromecast.api.v2.ChromeCastException: Remote socket closed
	at su.litvak.chromecast.api.v2.Channel.read(Channel.java:425) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:51) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:137) [237:org.openhab.binding.chromecast:2.4.0]
2019-01-03 05:40:54.098 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message 

2019-01-03 05:45:01.040 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.

2019-01-03 05:45:08.383 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 05:45:15.598 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 05:45:15.620 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 05:45:22.840 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.model.rule.runtime.internal.engine.RuleEngineImpl@10e0e8b' takes more than 5000ms.

2019-01-03 05:46:07.933 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 05:46:15.213 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 05:46:15.271 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 05:46:22.543 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 05:46:22.556 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 05:46:22.562 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 05:46:29.954 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.

2019-01-03 05:46:51.613 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.events.internal.EventBridge@15c45fd' takes more than 5000ms.
2019-01-03 05:46:58.760 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 05:46:58.826 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 05:46:58.829 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 05:46:58.849 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 05:47:13.164 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 05:47:13.181 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 05:47:13.278 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 05:47:20.666 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.core.internal.items.ItemUpdater@bc439f' takes more than 5000ms.

2019-01-03 05:47:35.535 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 05:47:35.543 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 05:47:42.824 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.config.discovery.internal.AutomaticInboxProcessor@1b4bfaf' takes more than 5000ms.

2019-01-03 05:48:26.846 [ERROR] [.smarthome.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2019-01-03 05:48:26.851 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.

2019-01-03 05:48:56.332 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.events.internal.EventBridge@15c45fd' takes more than 5000ms.

2019-01-03 05:52:49.287 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.


2019-01-03 05:59:27.945 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.events.internal.EventBridge@15c45fd' takes more than 5000ms.

2019-01-03 06:08:15.062 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.events.internal.EventBridge@15c45fd' takes more than 5000ms.
2019-01-03 06:09:07.323 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:09:22.274 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 06:09:22.299 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 06:09:29.747 [ERROR] [.smarthome.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2019-01-03 06:09:37.274 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:09:44.424 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 06:09:44.419 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:09:44.438 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 06:09:51.578 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 06:09:51.584 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.core.thing.internal.CommunicationManager@e7fc8f' takes more than 5000ms.
2019-01-03 06:09:59.050 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 06:09:59.061 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.

2019-01-03 06:10:51.074 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.

2019-01-03 06:11:27.556 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.events.internal.EventBridge@15c45fd' takes more than 5000ms.
2019-01-03 06:11:27.561 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 06:11:27.574 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 06:11:34.741 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 06:11:42.016 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 06:12:05.338 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:12:19.691 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:12:43.001 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 06:12:50.210 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 06:12:57.434 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.config.discovery.internal.AutomaticInboxProcessor@1b4bfaf' takes more than 5000ms.
2019-01-03 06:13:04.687 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 06:13:04.699 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:13:11.930 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 06:13:19.358 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 06:13:19.365 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:13:26.803 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:13:26.807 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 06:13:50.518 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 06:13:50.544 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:13:57.962 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 06:14:12.832 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 06:14:12.846 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 06:14:20.250 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:14:20.266 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 06:14:20.267 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading
su.litvak.chromecast.api.v2.ChromeCastException: Remote socket closed
	at su.litvak.chromecast.api.v2.Channel.read(Channel.java:425) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel.access$200(Channel.java:51) ~[237:org.openhab.binding.chromecast:2.4.0]
	at su.litvak.chromecast.api.v2.Channel$ReadThread.run(Channel.java:137) [237:org.openhab.binding.chromecast:2.4.0]
2019-01-03 06:14:20.275 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 06:14:20.277 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message 
2019-01-03 06:15:05.768 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 06:14:56.862 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:14:49.682 [ERROR] [io.openhabcloud.internal.CloudClient] - Socket.IO error: {}
io.socket.engineio.client.EngineIOException: websocket error
	at io.socket.engineio.client.Transport.onError(Transport.java:71) [246:org.openhab.io.openhabcloud:2.4.0]
	at io.socket.engineio.client.transports.WebSocket.access$500(WebSocket.java:28) [246:org.openhab.io.openhabcloud:2.4.0]
	at io.socket.engineio.client.transports.WebSocket$2$5.run(WebSocket.java:150) [246:org.openhab.io.openhabcloud:2.4.0]
	at io.socket.thread.EventThread$2.run(EventThread.java:80) [246:org.openhab.io.openhabcloud:2.4.0]
	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.io.EOFException
	at okio.RealBufferedSource.require(RealBufferedSource.java:59) ~[?:?]
	at okio.RealBufferedSource.readByte(RealBufferedSource.java:72) ~[?:?]
	at okhttp3.internal.ws.WebSocketReader.readHeader(WebSocketReader.java:115) ~[?:?]
	at okhttp3.internal.ws.WebSocketReader.processNextFrame(WebSocketReader.java:104) ~[?:?]
	at okhttp3.internal.ws.RealWebSocket.readMessage(RealWebSocket.java:97) ~[?:?]
	at okhttp3.ws.WebSocketCall.createWebSocket(WebSocketCall.java:152) ~[?:?]
	at okhttp3.ws.WebSocketCall.access$000(WebSocketCall.java:41) ~[?:?]
	at okhttp3.ws.WebSocketCall$1.onResponse(WebSocketCall.java:97) ~[?:?]
	at okhttp3.RealCall$AsyncCall.execute(RealCall.java:126) ~[?:?]
	at okhttp3.internal.NamedRunnable.run(NamedRunnable.java:32) ~[?:?]
	... 3 more
2019-01-03 06:17:18.102 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = a00570a5-2111-4dbd-a565-914e4331e517, base URL = http://localhost:8080)

2019-01-03 06:39:26.849 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 06:39:27.021 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 06:39:34.417 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:39:34.418 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 06:39:34.435 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 06:39:41.972 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 06:39:41.981 [ERROR] [.smarthome.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed

2019-01-03 06:44:17.679 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:45:01.780 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.events.internal.EventBridge@15c45fd' takes more than 5000ms.
2019-01-03 06:45:16.366 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.core.thing.internal.CommunicationManager@e7fc8f' takes more than 5000ms.
2019-01-03 06:45:24.874 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 06:45:24.926 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 06:45:32.164 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 06:45:32.187 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 06:46:09.452 [ERROR] [.smarthome.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2019-01-03 06:46:25.470 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 06:46:25.530 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 06:46:32.784 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.

2019-01-03 06:49:24.295 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.core.thing.internal.CommunicationManager@e7fc8f' takes more than 5000ms.
2019-01-03 06:49:24.357 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 06:49:46.345 [ERROR] [.smarthome.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2019-01-03 06:49:46.358 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:49:53.619 [ERROR] [.smarthome.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2019-01-03 06:50:15.998 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 06:50:16.024 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:50:16.043 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module

2019-01-03 06:54:02.958 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:55:41.266 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.core.thing.internal.CommunicationManager@e7fc8f' takes more than 5000ms.
2019-01-03 06:56:16.977 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.events.internal.EventBridge@15c45fd' takes more than 5000ms.
2019-01-03 06:56:31.392 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.events.internal.EventBridge@15c45fd' takes more than 5000ms.
2019-01-03 06:56:38.587 [ERROR] [.smarthome.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2019-01-03 06:56:45.828 [ERROR] [.smarthome.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2019-01-03 06:56:45.835 [ERROR] [.smarthome.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2019-01-03 06:56:45.854 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 06:56:45.862 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 06:56:45.935 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:56:53.256 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:56:53.297 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 06:56:53.326 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 06:57:00.704 [ERROR] [.smarthome.model.script.actions.HTTP] - Fatal transport error: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2019-01-03 06:57:15.666 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 06:57:15.708 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 06:57:24.582 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 06:57:24.636 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:57:24.663 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 06:57:31.827 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 06:57:31.832 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 06:57:31.871 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:57:38.989 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 06:57:39.003 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 06:57:53.673 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:58:00.837 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@a79e16' takes more than 5000ms.
2019-01-03 06:58:07.950 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 06:58:07.997 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 06:58:15.188 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.core.thing.internal.CommunicationManager@e7fc8f' takes more than 5000ms.
2019-01-03 06:58:15.241 [INFO ] [.satel.internal.protocol.Ethm1Module] - Connecting to ETHM-1 module at 192.168.2.200:7094
2019-01-03 06:58:15.264 [INFO ] [.satel.internal.protocol.Ethm1Module] - ETHM-1 module connected successfully
2019-01-03 06:58:22.356 [ERROR] [.satel.internal.protocol.SatelModule] - Send/receive timeout, disconnecting module.
2019-01-03 06:58:29.714 [INFO ] [.satel.internal.protocol.Ethm1Module] - Closing connection to ETHM-1 module
2019-01-03 06:58:29.772 [ERROR] [.satel.internal.protocol.SatelModule] - Unexpected exception occurred during reading a message
java.net.SocketException: Socket closed

IT-Dino · January 7, 2019, 5:44am

Same problem here. Instead of reboot: uninstall/install AVM FRITZ!Box Binding and Fritzbox TR064 Binding.
I try to do this automatic each hour.

proutska · January 7, 2019, 8:23pm

After removing addons.config as described here https://github.com/openhab/openhab-distro/issues/587#issuecomment-451155036 all the problems seems to be fixed.
There was some outdated information in this file.