Dear All,
is the second time I pesonally face with that huge problem.
Simply Openhab3.2 is stop working at all (RPI B3)
2 Days ago all was working very stable since weeks running.
from a day to another with no new binding or rule or new items, I’ve got the problem.
I read many many other users with same problem and there is no solution at all. Nodoby say … the problem comes from these or that…
These is the main OH3 problem but nothing is clear about it.
One for all, please can someone explain very well why and how to do ???
I’m re-installing openhab but if the problem will appear again in future I think I’m going definitelly to switch to home assistant, because re-install everithing for an unknown solution problem is very frustrating
I don’t think, after 4/5 reboot, the result is always the same and mqtt has been installed since the beginning…
if is MQTT problem it has been corrupted someway but I don’t think. mqtt is a consequence of something other unkown to me
The problem is: It seems that no-one can reliably reproduce that issue. Some memory leaks have been identified in the past (and fixed), but most of the time they are hard to find.
If it works for a very long time and then suddenly grows memory usage, the best strategy might be to look what messages (especially connect/reconnect) appear more frequent before you run into OOM. My guess would be that some binding is not doing a proper re-connect.
Check if this post helps, seems some people are reporting issues with VS code extension. It can also be a rule not just a binding that can cause this, so it helps to uninstall different parts of openHab to test with and watch what is happening with the heap size which can be watched with the system info binding.
I don’t have an answer for you, but I want to provide my own observation.
I had been running OH on the Rasp 3 for a couple of years. Memory was always tight, but since the 3.x code line, it becomes unbearable. Starting from 3.1 or 3.2, after running continuously for 1 to 2 weeks, it will completely run out of memory. Even commands like reboot or shutdown no longer work; a hard power reset was necessary. I also run pi-hole on that system, and the effect was dramatic when the system is sort of alive but can no longer react to anything; all devices essentially can’t connect to the internet.
I suspect that it is a combination of bigger code bases as well as accumulated memory leaks. I use the follow add-ons: Astro, Chromecast, DSCAlarm, Ecoobee, Mail, MQTT, MyQ, Network, Sony, TPLink, Wled and ZWave.
I’ve since migrated to a Rasp 4 with 2G of RAM, and I am happy to report that I haven’t seen the same memory issue yet. It could be just regular memory expansion & collection that goes beyond the available memory in a Rasp 3 system.
If giving it more memory solved the problem the problem is almost certainly that the RPi 3 does not have enough memory to run both PiHole and openHAB. PiHole’s docs state it requires 512 MB of RAM. openHAB doesn’t list a minimum but in practice it needs around 800 MB. The RPi 3 only has 1 GB. 512 MB + 800 MB > 1 GB
This is a big reason why many on this forum are adamant about not running other stuff on the same RPi as openHAB. There can be interactions and resource conflicts like the one described and inevitably it’s openHAB that gets the blame instead of the fact that the user is asking the RPi to simply do more than it’s capable of doing.
Ciao rlkoshak,
Ican understand, more resources you have, less problems you should encounter. I’m upset because in my case i don’t have Pihole and the problem manifest with no apparent modification on the already running system since some month.
Now that i note down the restore procedure, with two16gb SD card, i can re-install everything in about 3/4 hours. After backup restore, now is 24 h running without problems
This is a problem type that is hard to track down.
A memleak in one binding is a possible explanation but rather unlikely in your case.
But there’s several potential reasons for Java running out of heap and most of them are in fact not OH problems but with the way you have have setup your hardware, Java and OS.
It’s impossible to analyze without that you tell us more details about your system.
So no, for now noone can “once and for all” explain.
Are you using openHABian, if so which version ? Show the parameter the java process runs with.
What OH version exactly do you use ?
Do you dynamically download bindings ?
Provide the full error message from the logs, no screenshots please.
Java uses a default maximum heap size dependent on physical RAM unless you explicitly increased that (like openHABian does for you).
For a 1GB system that’s 256M which is really tight, and once surpassed Java will terminate with that error message.
PS: re-installing won’t change anything about that when you install the same software version(s) so for now, save your time on that
Hei ciao Markus,
I’m very happy to try to explain better my problems but as non-expert i need some guide line that help me to help you understand.
I always used openhabian vanilla always and only the last stable release , + mosquitto, installed via openhabian + ookla speedtest installed via apt. Nothing else
Java parameter? Sorry I dont understand, I use openhabian out of the box, official java should already working…
I always run the last openhab stable Version 3.2 now
i did not touch ram or zram parameters, all was as out of the box
All seems to be very straight and standard, no garbage on the PI!!
2022-04-02 04:08:37.252 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.metrics.ThingStateMetric@804575' takes more than 5000ms.
2022-04-02 04:08:49.383 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.rest.sse.internal.listeners.SseEventSubscriber@101efc7' takes more than 5000ms.
2022-04-02 04:09:14.788 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:09:29.067 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:09:48.223 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.metrics.ThingStateMetric@804575' takes more than 5000ms.
2022-04-02 04:09:54.748 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:10:06.260 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:10:24.457 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:10:43.753 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:10:56.916 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:11:08.254 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.rest.sse.internal.listeners.SseEventSubscriber@101efc7' takes more than 5000ms.
2022-04-02 04:11:25.328 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:11:38.190 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:11:45.171 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:11:51.379 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:12:03.875 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:12:09.822 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:12:15.458 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:12:21.722 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:12:33.568 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:12:34.008 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:12:40.645 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:12:53.284 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:13:11.626 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.metrics.ThingStateMetric@804575' takes more than 5000ms.
2022-04-02 04:13:18.672 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:13:25.194 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:13:31.015 [ERROR] [io.openhabcloud.internal.CloudClient] - Error during communication
2022-04-02 04:13:31.085 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:13:41.917 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:13:48.313 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:13:59.522 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:13:59.560 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:14:11.324 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:14:11.455 [ERROR] [io.openhabcloud.internal.CloudClient] - Error during communication
2022-04-02 04:14:11.461 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:14:21.739 [WARN ] [okhttp3.OkHttpClient ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body? To see where this was allocated, set the OkHttpClient logger level to FINE: Logger.getLogger(OkHttpClient.class.getName()).setLevel(Level.FINE);
2022-04-02 04:14:21.743 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:14:38.655 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:14:38.695 [ERROR] [io.openhabcloud.internal.CloudClient] - Error during communication
2022-04-02 04:14:38.700 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:14:44.419 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.metrics.ThingStateMetric@804575' takes more than 5000ms.
2022-04-02 04:14:55.693 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.metrics.ThingStateMetric@804575' takes more than 5000ms.
2022-04-02 04:15:32.503 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:15:38.121 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:16:30.884 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:16:36.054 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:16:41.887 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:16:36.083 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:16:47.332 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:17:05.129 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:17:05.154 [WARN ] [okhttp3.OkHttpClient ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body? To see where this was allocated, set the OkHttpClient logger level to FINE: Logger.getLogger(OkHttpClient.class.getName()).setLevel(Level.FINE);
2022-04-02 04:16:59.408 [WARN ] [mazonechocontrol.internal.Connection] - Request to url 'https://alexa.amazon.it/api/notifications' fails with unknown error
javax.net.ssl.SSLHandshakeException: Remote host terminated the handshake
at sun.security.ssl.SSLSocketImpl.handleEOF(SSLSocketImpl.java:1598) ~[?:?]
at sun.security.ssl.SSLSocketImpl.decode(SSLSocketImpl.java:1426) ~[?:?]
at sun.security.ssl.SSLSocketImpl.readHandshakeRecord(SSLSocketImpl.java:1324) ~[?:?]
at sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:440) ~[?:?]
at sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:411) ~[?:?]
at sun.net.www.protocol.https.HttpsClient.afterConnect(HttpsClient.java:567) ~[?:?]
at sun.net.www.protocol.https.AbstractDelegateHttpsURLConnection.setNewClient(AbstractDelegateHttpsURLConnection.java:106) ~[?:?]
at sun.net.www.protocol.https.AbstractDelegateHttpsURLConnection.setNewClient(AbstractDelegateHttpsURLConnection.java:80) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.writeRequests(HttpURLConnection.java:724) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1613) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1520) ~[?:?]
at java.net.HttpURLConnection.getResponseCode(HttpURLConnection.java:527) ~[?:?]
at sun.net.www.protocol.https.HttpsURLConnectionImpl.getResponseCode(HttpsURLConnectionImpl.java:334) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.makeRequest(Connection.java:639) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.makeRequestAndReturnString(Connection.java:555) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.makeRequestAndReturnString(Connection.java:550) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.notifications(Connection.java:1920) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.refreshNotifications(AccountHandler.java:478) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.refreshData(AccountHandler.java:576) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.checkData(AccountHandler.java:455) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
at java.lang.Thread.run(Thread.java:829) [?:?]
Caused by: java.io.EOFException: SSL peer shut down incorrectly
at sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:483) ~[?:?]
at sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:472) ~[?:?]
at sun.security.ssl.SSLSocketInputRecord.decode(SSLSocketInputRecord.java:160) ~[?:?]
at sun.security.ssl.SSLTransport.decode(SSLTransport.java:110) ~[?:?]
at sun.security.ssl.SSLSocketImpl.decode(SSLSocketImpl.java:1418) ~[?:?]
... 24 more
2022-04-02 04:17:05.163 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:17:15.946 [ERROR] [io.openhabcloud.internal.CloudClient] - Error during communication
2022-04-02 04:17:15.964 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:17:29.122 [WARN ] [okhttp3.OkHttpClient ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body? To see where this was allocated, set the OkHttpClient logger level to FINE: Logger.getLogger(OkHttpClient.class.getName()).setLevel(Level.FINE);
2022-04-02 04:17:34.122 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:17:39.283 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.metrics.ThingStateMetric@804575' takes more than 5000ms.
2022-04-02 04:17:39.366 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:17:44.936 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:17:51.016 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:17:51.030 [ERROR] [io.openhabcloud.internal.CloudClient] - Error during communication
2022-04-02 04:17:51.034 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:18:24.556 [WARN ] [okhttp3.OkHttpClient ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body? To see where this was allocated, set the OkHttpClient logger level to FINE: Logger.getLogger(OkHttpClient.class.getName()).setLevel(Level.FINE);
2022-04-02 04:18:31.172 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:18:43.346 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:18:49.445 [ERROR] [io.openhabcloud.internal.CloudClient] - Error during communication
2022-04-02 04:18:49.455 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:18:54.664 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:19:00.516 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.automation.internal.module.handler.ItemStateTriggerHandler@1cf5bdc' takes more than 5000ms.
2022-04-02 04:19:06.483 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:19:06.645 [WARN ] [okhttp3.OkHttpClient ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body? To see where this was allocated, set the OkHttpClient logger level to FINE: Logger.getLogger(OkHttpClient.class.getName()).setLevel(Level.FINE);
2022-04-02 04:19:06.714 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:19:11.962 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:19:23.616 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:19:23.654 [ERROR] [io.openhabcloud.internal.CloudClient] - Error during communication
2022-04-02 04:19:23.704 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:19:29.103 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:19:34.887 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:19:40.473 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:19:58.248 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:19:58.254 [WARN ] [okhttp3.OkHttpClient ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body? To see where this was allocated, set the OkHttpClient logger level to FINE: Logger.getLogger(OkHttpClient.class.getName()).setLevel(Level.FINE);
2022-04-02 04:20:03.779 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:20:10.745 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:20:21.759 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:20:26.727 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:20:38.314 [ERROR] [io.openhabcloud.internal.CloudClient] - Error during communication
2022-04-02 04:20:38.404 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:21:06.268 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:21:18.842 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.automation.internal.module.handler.GroupStateTriggerHandler@1a07283' takes more than 5000ms.
2022-04-02 04:21:18.847 [WARN ] [okhttp3.OkHttpClient ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body? To see where this was allocated, set the OkHttpClient logger level to FINE: Logger.getLogger(OkHttpClient.class.getName()).setLevel(Level.FINE);
2022-04-02 04:21:24.326 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:21:35.138 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:21:41.133 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.thing.internal.CommunicationManager@8a0a52' takes more than 5000ms.
2022-04-02 04:21:46.628 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:22:02.369 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:22:23.388 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:22:28.513 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.metrics.ThingStateMetric@804575' takes more than 5000ms.
2022-04-02 04:22:48.616 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:23:03.820 [WARN ] [okhttp3.OkHttpClient ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body? To see where this was allocated, set the OkHttpClient logger level to FINE: Logger.getLogger(OkHttpClient.class.getName()).setLevel(Level.FINE);
2022-04-02 04:23:18.628 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:23:54.028 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:24:08.939 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.metrics.ThingStateMetric@804575' takes more than 5000ms.
2022-04-02 04:24:23.927 [ERROR] [io.openhabcloud.internal.CloudClient] - Error during communication
2022-04-02 04:24:28.634 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 17f53be5-ef28-4a3d-a047-82486b7c6baa, base URL = http://localhost:8080)
2022-04-02 04:24:38.220 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:24:43.724 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@6a2e83' takes more than 5000ms.
2022-04-02 04:25:47.729 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.rest.sse.internal.listeners.SseEventSubscriber@101efc7' takes more than 5000ms.
2022-04-02 04:27:29.947 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@115c25a' takes more than 5000ms.
2022-04-02 04:32:40.039 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1006. Reason: Disconnected
2022-04-02 04:33:23.834 [ERROR] [e.automation.internal.RuleEngineImpl] - Failed to execute rule 'IrrigardenV3-1': Fail to execute action: script
2022-04-02 04:41:18.260 [WARN ] [e.jetty.util.thread.QueuedThreadPool] -
java.lang.OutOfMemoryError: Java heap space
2022-04-02 04:48:06.439 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.metrics.EventCountMetric@1bc4362' takes more than 5000ms.
2022-04-02 04:46:44.763 [ERROR] [io.socket.thread.EventThread ] - Task threw exception
java.lang.OutOfMemoryError: Java heap space
2022-04-02 04:50:42.231 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 04:55:36.210 [WARN ] [ab.binding.mqtt.generic.ChannelState] - Incoming payload '849.84' not supported by type 'NumberValue'
2022-04-02 04:55:42.666 [ERROR] [internal.handler.ScriptActionHandler] - Script execution of rule with UID 'OrologioV3-1' failed: Java heap space in OrologioV3
2022-04-02 04:56:04.855 [WARN ] [ing.mqtt.internal.action.MQTTActions] - MQTT publish to Int/N_C_in/Ora failed!
2022-04-02 04:56:04.857 [WARN ] [ing.mqtt.internal.action.MQTTActions] - MQTT publish to Int/N_C_in/Out_T failed!
2022-04-02 04:56:04.859 [WARN ] [ing.mqtt.internal.action.MQTTActions] - MQTT publish to Int/N_C_in/Out_Wea failed!
2022-04-02 04:56:04.861 [WARN ] [ing.mqtt.internal.action.MQTTActions] - MQTT publish to Int/N_C_in/Ora failed!
2022-04-02 04:56:10.392 [WARN ] [ing.mqtt.internal.action.MQTTActions] - MQTT publish to Int/N_C_in/Out_T failed!
2022-04-02 04:56:10.394 [WARN ] [ing.mqtt.internal.action.MQTTActions] - MQTT publish to Int/N_C_in/Out_Wea failed!
2022-04-02 04:56:16.011 [WARN ] [ing.mqtt.internal.action.MQTTActions] - MQTT publish to Int/N_C_in/Ora failed!
2022-04-02 04:56:16.040 [WARN ] [ing.mqtt.internal.action.MQTTActions] - MQTT publish to Int/N_C_in/Out_T failed!
2022-04-02 04:56:16.042 [WARN ] [ing.mqtt.internal.action.MQTTActions] - MQTT publish to Int/N_C_in/Out_Wea failed!
2022-04-02 04:56:16.044 [WARN ] [ing.mqtt.internal.action.MQTTActions] - MQTT publish to Int/N_C_in/Ora failed!
2022-04-02 04:56:16.046 [WARN ] [ing.mqtt.internal.action.MQTTActions] - MQTT publish to Int/N_C_in/Out_T failed!
2022-04-02 04:56:16.048 [WARN ] [ing.mqtt.internal.action.MQTTActions] - MQTT publish to Int/N_C_in/Out_Wea failed!
2022-04-02 04:56:27.221 [WARN ] [ing.mqtt.internal.action.MQTTActions] - MQTT publish to Int/N_C_in/Out_Wea failed!
2022-04-02 04:56:27.284 [INFO ] [.transport.mqtt.MqttBrokerConnection] - Starting MQTT broker connection to '192.168.178.175' with clientid Mosquitto
2022-04-02 04:56:38.057 [INFO ] [control.internal.WebSocketConnection] - Web Socket error
javax.net.ssl.SSLHandshakeException: Abruptly closed by peer
at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.fill(SslConnection.java:769) ~[?:?]
at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.process(HttpReceiverOverHTTP.java:164) ~[?:?]
at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.receive(HttpReceiverOverHTTP.java:79) ~[?:?]
at org.eclipse.jetty.client.http.HttpChannelOverHTTP.receive(HttpChannelOverHTTP.java:131) ~[?:?]
at org.eclipse.jetty.client.http.HttpConnectionOverHTTP.onFillable(HttpConnectionOverHTTP.java:172) ~[?:?]
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) ~[?:?]
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[?:?]
at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:555) ~[?:?]
at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:410) ~[?:?]
at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:164) ~[?:?]
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[?:?]
at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) ~[?:?]
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [bundleFile:9.4.43.v20210629]
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [bundleFile:9.4.43.v20210629]
at java.lang.Thread.run(Thread.java:829) [?:?]
Suppressed: javax.net.ssl.SSLException: closing inbound before receiving peer's close_notify
at sun.security.ssl.Alert.createSSLException(Alert.java:133) ~[?:?]
at sun.security.ssl.Alert.createSSLException(Alert.java:117) ~[?:?]
at sun.security.ssl.TransportContext.fatal(TransportContext.java:336) ~[?:?]
at sun.security.ssl.TransportContext.fatal(TransportContext.java:292) ~[?:?]
at sun.security.ssl.TransportContext.fatal(TransportContext.java:283) ~[?:?]
at sun.security.ssl.SSLEngineImpl.closeInbound(SSLEngineImpl.java:733) ~[?:?]
at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.closeInbound(SslConnection.java:982) ~[?:?]
at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.fill(SslConnection.java:766) ~[?:?]
at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.process(HttpReceiverOverHTTP.java:164) ~[?:?]
at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.receive(HttpReceiverOverHTTP.java:79) ~[?:?]
at org.eclipse.jetty.client.http.HttpChannelOverHTTP.receive(HttpChannelOverHTTP.java:131) ~[?:?]
at org.eclipse.jetty.client.http.HttpConnectionOverHTTP.onFillable(HttpConnectionOverHTTP.java:172) ~[?:?]
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) ~[?:?]
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[?:?]
at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:555) ~[?:?]
at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:410) ~[?:?]
at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:164) ~[?:?]
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[?:?]
at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) ~[?:?]
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [bundleFile:9.4.43.v20210629]
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [bundleFile:9.4.43.v20210629]
at java.lang.Thread.run(Thread.java:829) [?:?]
Suppressed: org.eclipse.jetty.io.EofException
at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:279) ~[?:?]
at org.eclipse.jetty.io.ssl.SslConnection.networkFlush(SslConnection.java:489) ~[?:?]
at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.flush(SslConnection.java:1112) ~[?:?]
at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.doShutdownOutput(SslConnection.java:1329) ~[?:?]
at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.doClose(SslConnection.java:1411) ~[?:?]
at org.eclipse.jetty.io.AbstractEndPoint.doOnClose(AbstractEndPoint.java:220) ~[?:?]
at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:192) ~[?:?]
at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.fill(SslConnection.java:845) ~[?:?]
at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.process(HttpReceiverOverHTTP.java:164) ~[?:?]
at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.receive(HttpReceiverOverHTTP.java:79) ~[?:?]
at org.eclipse.jetty.client.http.HttpChannelOverHTTP.receive(HttpChannelOverHTTP.java:131) ~[?:?]
at org.eclipse.jetty.client.http.HttpConnectionOverHTTP.onFillable(HttpConnectionOverHTTP.java:172) ~[?:?]
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) ~[?:?]
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[?:?]
at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:555) ~[?:?]
at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:410) ~[?:?]
at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:164) ~[?:?]
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[?:?]
at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) ~[?:?]
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [bundleFile:9.4.43.v20210629]
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [bundleFile:9.4.43.v20210629]
at java.lang.Thread.run(Thread.java:829) [?:?]
Caused by: java.io.IOException: Broken pipe
at sun.nio.ch.FileDispatcherImpl.writev0(Native Method) ~[?:?]
at sun.nio.ch.SocketDispatcher.writev(SocketDispatcher.java:51) ~[?:?]
at sun.nio.ch.IOUtil.write(IOUtil.java:182) ~[?:?]
at sun.nio.ch.IOUtil.write(IOUtil.java:130) ~[?:?]
at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:493) ~[?:?]
at java.nio.channels.SocketChannel.write(SocketChannel.java:507) ~[?:?]
at org.eclipse.jetty.io.ChannelEndPoint.flush(ChannelEndPoint.java:273) ~[?:?]
... 21 more
2022-04-02 04:57:40.348 [WARN ] [mazonechocontrol.internal.Connection] - Request to url 'https://alexa.amazon.it/api/devices-v2/device?cached=false' fails with unknown error
javax.net.ssl.SSLHandshakeException: Remote host terminated the handshake
at sun.security.ssl.SSLSocketImpl.handleEOF(SSLSocketImpl.java:1598) ~[?:?]
at sun.security.ssl.SSLSocketImpl.decode(SSLSocketImpl.java:1426) ~[?:?]
at sun.security.ssl.SSLSocketImpl.readHandshakeRecord(SSLSocketImpl.java:1324) ~[?:?]
at sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:440) ~[?:?]
at sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:411) ~[?:?]
at sun.net.www.protocol.https.HttpsClient.afterConnect(HttpsClient.java:567) ~[?:?]
at sun.net.www.protocol.https.AbstractDelegateHttpsURLConnection.setNewClient(AbstractDelegateHttpsURLConnection.java:106) ~[?:?]
at sun.net.www.protocol.https.AbstractDelegateHttpsURLConnection.setNewClient(AbstractDelegateHttpsURLConnection.java:80) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.writeRequests(HttpURLConnection.java:724) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1613) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1520) ~[?:?]
at java.net.HttpURLConnection.getResponseCode(HttpURLConnection.java:527) ~[?:?]
at sun.net.www.protocol.https.HttpsURLConnectionImpl.getResponseCode(HttpsURLConnectionImpl.java:334) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.makeRequest(Connection.java:639) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.makeRequestAndReturnString(Connection.java:555) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.makeRequestAndReturnString(Connection.java:550) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.getDeviceListJson(Connection.java:1043) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.getDeviceList(Connection.java:1033) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.updateDeviceList(AccountHandler.java:616) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.refreshData(AccountHandler.java:506) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.checkData(AccountHandler.java:455) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
at java.lang.Thread.run(Thread.java:829) [?:?]
Caused by: java.io.EOFException: SSL peer shut down incorrectly
at sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:483) ~[?:?]
at sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:472) ~[?:?]
at sun.security.ssl.SSLSocketInputRecord.decode(SSLSocketInputRecord.java:160) ~[?:?]
at sun.security.ssl.SSLTransport.decode(SSLTransport.java:110) ~[?:?]
at sun.security.ssl.SSLSocketImpl.decode(SSLSocketImpl.java:1418) ~[?:?]
... 25 more
2022-04-02 04:59:14.003 [WARN ] [mazonechocontrol.internal.Connection] - Request to url 'https://alexa.amazon.it/api/wake-word?cached=true' fails with unknown error
javax.net.ssl.SSLHandshakeException: Remote host terminated the handshake
at sun.security.ssl.SSLSocketImpl.handleEOF(SSLSocketImpl.java:1598) ~[?:?]
at sun.security.ssl.SSLSocketImpl.decode(SSLSocketImpl.java:1426) ~[?:?]
at sun.security.ssl.SSLSocketImpl.readHandshakeRecord(SSLSocketImpl.java:1324) ~[?:?]
at sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:440) ~[?:?]
at sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:411) ~[?:?]
at sun.net.www.protocol.https.HttpsClient.afterConnect(HttpsClient.java:567) ~[?:?]
at sun.net.www.protocol.https.AbstractDelegateHttpsURLConnection.connect(AbstractDelegateHttpsURLConnection.java:197) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1592) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1520) ~[?:?]
at java.net.HttpURLConnection.getResponseCode(HttpURLConnection.java:527) ~[?:?]
at sun.net.www.protocol.https.HttpsURLConnectionImpl.getResponseCode(HttpsURLConnectionImpl.java:334) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.makeRequest(Connection.java:639) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.makeRequestAndReturnString(Connection.java:555) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.makeRequestAndReturnString(Connection.java:550) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.getWakeWords(Connection.java:986) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.updateDeviceList(AccountHandler.java:627) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.refreshData(AccountHandler.java:506) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.checkData(AccountHandler.java:455) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
at java.lang.Thread.run(Thread.java:829) [?:?]
Caused by: java.io.EOFException: SSL peer shut down incorrectly
at sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:483) ~[?:?]
at sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:472) ~[?:?]
at sun.security.ssl.SSLSocketInputRecord.decode(SSLSocketInputRecord.java:160) ~[?:?]
at sun.security.ssl.SSLTransport.decode(SSLTransport.java:110) ~[?:?]
at sun.security.ssl.SSLSocketImpl.decode(SSLSocketImpl.java:1418) ~[?:?]
... 22 more
2022-04-02 04:59:19.023 [INFO ] [mazonechocontrol.internal.Connection] - getting wakewords failed
javax.net.ssl.SSLHandshakeException: Remote host terminated the handshake
at sun.security.ssl.SSLSocketImpl.handleEOF(SSLSocketImpl.java:1598) ~[?:?]
at sun.security.ssl.SSLSocketImpl.decode(SSLSocketImpl.java:1426) ~[?:?]
at sun.security.ssl.SSLSocketImpl.readHandshakeRecord(SSLSocketImpl.java:1324) ~[?:?]
at sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:440) ~[?:?]
at sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:411) ~[?:?]
at sun.net.www.protocol.https.HttpsClient.afterConnect(HttpsClient.java:567) ~[?:?]
at sun.net.www.protocol.https.AbstractDelegateHttpsURLConnection.connect(AbstractDelegateHttpsURLConnection.java:197) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1592) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1520) ~[?:?]
at java.net.HttpURLConnection.getResponseCode(HttpURLConnection.java:527) ~[?:?]
at sun.net.www.protocol.https.HttpsURLConnectionImpl.getResponseCode(HttpsURLConnectionImpl.java:334) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.makeRequest(Connection.java:639) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.makeRequestAndReturnString(Connection.java:555) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.makeRequestAndReturnString(Connection.java:550) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.getWakeWords(Connection.java:986) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.updateDeviceList(AccountHandler.java:627) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.refreshData(AccountHandler.java:506) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.checkData(AccountHandler.java:455) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
at java.lang.Thread.run(Thread.java:829) [?:?]
Caused by: java.io.EOFException: SSL peer shut down incorrectly
at sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:483) ~[?:?]
at sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:472) ~[?:?]
at sun.security.ssl.SSLSocketInputRecord.decode(SSLSocketInputRecord.java:160) ~[?:?]
at sun.security.ssl.SSLTransport.decode(SSLTransport.java:110) ~[?:?]
at sun.security.ssl.SSLSocketImpl.decode(SSLSocketImpl.java:1418) ~[?:?]
... 22 more
2022-04-02 05:22:38.174 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 05:28:14.637 [ERROR] [internal.handler.ScriptActionHandler] - Script execution of rule with UID 'OrologioV3-1' failed: An error occurred during the script execution: Java heap space in OrologioV3
2022-04-02 05:29:31.120 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 05:25:38.974 [WARN ] [netty.util.concurrent.DefaultPromise] - An exception was thrown by org.openhab.binding.ipcamera.internal.handler.IpCameraHandler$2.operationComplete()
java.lang.OutOfMemoryError: Java heap space
2022-04-02 05:30:55.669 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 05:31:09.696 [WARN ] [ab.binding.mqtt.generic.ChannelState] - Incoming payload '-13.76' not supported by type 'NumberValue'
2022-04-02 05:31:09.697 [WARN ] [ab.binding.mqtt.generic.ChannelState] - Incoming payload '31.65' not supported by type 'NumberValue'
2022-04-02 05:32:07.112 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 05:36:56.755 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 05:37:01.448 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 05:44:15.059 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 05:49:14.247 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 05:49:46.722 [ERROR] [internal.handler.ScriptActionHandler] - Script execution of rule with UID 'OrologioV3-1' failed: An error occurred during the script execution: Java heap space in OrologioV3
2022-04-02 06:01:06.463 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 06:01:57.532 [WARN ] [ab.binding.mqtt.generic.ChannelState] - Incoming payload '-13.29' not supported by type 'NumberValue'
2022-04-02 06:03:16.644 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 06:17:11.946 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 06:36:29.316 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 06:40:50.429 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 07:24:29.218 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 07:25:18.311 [WARN ] [netty.util.concurrent.DefaultPromise] - An exception was thrown by org.openhab.binding.ipcamera.internal.handler.IpCameraHandler$2.operationComplete()
java.lang.OutOfMemoryError: Java heap space
2022-04-02 07:25:18.311 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 07:36:04.015 [ERROR] [internal.handler.ScriptActionHandler] - Script execution of rule with UID 'OrologioV3-1' failed: An error occurred during the script execution: Java heap space in OrologioV3
2022-04-02 07:48:07.551 [ERROR] [e.automation.internal.RuleEngineImpl] - Failed to execute rule 'OrologioV3-1': Fail to execute action: script
2022-04-02 07:51:54.211 [WARN ] [e.jetty.util.thread.QueuedThreadPool] -
java.lang.OutOfMemoryError: Java heap space
2022-04-02 07:53:27.656 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 07:53:18.387 [WARN ] [ler.disconnect.MqttDisconnectHandler] - Exception while disconnecting: {}
java.lang.OutOfMemoryError: Java heap space
2022-04-02 08:23:20.623 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 08:35:40.152 [WARN ] [hannel.AbstractChannelHandlerContext] - An exception 'java.lang.OutOfMemoryError: Java heap space' [enable DEBUG level for full stacktrace] was thrown by a user handler's exceptionCaught() method while handling the following exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 08:35:44.879 [ERROR] [e.automation.internal.RuleEngineImpl] - Failed to execute rule ‘OrologioV3-1' with status 'RUNNING'
2022-04-02 08:35:59.765 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.OutOfMemoryError: Java heap space
2022-04-02 08:37:59.111 [ERROR] [e.automation.internal.RuleEngineImpl] - Failed to execute rule ‘OrologioV3-1' with status 'RUNNING'
2022-04-02 08:37:59.117 [ERROR] [e.automation.internal.RuleEngineImpl] - Failed to execute rule ‘SpeedTestV3-2' with status 'RUNNING'
2022-04-02 08:40:42.519 [ERROR] [e.automation.internal.RuleEngineImpl] - Failed to execute rule ‘SpeedTestV3-2' with status 'RUNNING'
2022-04-02 08:44:39.122 [ERROR] [internal.handler.ScriptActionHandler] - Script execution of rule with UID 'SpazzatureV3-1' failed: An error occurred during the script execution: Java heap space in SpazzatureV3
I asked for openHABian not openHAB. The openhabian-config menu has a title line showing the version. I also asked if you dynamically download bindings.
The parameters passed on Linux level to the java process. Show the output of ps -ef|grep java.
As I already said there’s many possible reasons which is why it is hard to debug. It requires good general debugging understanding and practice.
Apologies for being frank but I won’t talk someone without that practice and decent Linux and OH knowledge through this.
You apparently use some memory hungry bindings. These may have a leak or may just regularly use too much memory your small box does not have. Uninstall the IPCamera binding for a test. MQTT too, eventually.
If your system is stable after that, moving to hardware with more RAM is probably your best bet.
Good luck.
My openhab is running very unstable. Sometimes it runs for a month but sometimes for 2 days. I get many java errors in my logs. I also see these passing by a lot. What would like to say below and does anyone have a solution?
Thanks
> *java.io.IOException: java.util.concurrent.ExecutionException: java.io.EOFException: HttpConnectionOverHTTP@190ae0e::SocketChannelEndPoint@1916229{l=/192.168.1.180:44790,r=opml.radiotime.com/104.17.107.184:80,ISHUT,fill=-,flush=-,to=51/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@190ae0e(l:/192.168.1.180:44790 <-> r:opml.radiotime.com/104.17.107.184:80,closed=false)=>HttpChannelOverHTTP@119f078(exchange=HttpExchange@1fb8ae1{req=HttpRequest[GET /Describe.ashx HTTP/1.1]@1ffba8e[TERMINATED/null] res=HttpResponse[null 0 null]@14102b2[PENDING/null]})[send=HttpSenderOverHTTP@17fbd12(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7a921e{s=START}],recv=HttpReceiverOverHTTP@3d6715(rsp=IDLE,failure=null)[HttpParser{s=CLOSED,0 of -1}]]*
> * at org.openhab.core.io.net.http.HttpUtil.executeUrlAndGetReponse(HttpUtil.java:260) ~[bundleFile:?]*
> * at org.openhab.core.io.net.http.HttpUtil.executeUrl(HttpUtil.java:155) ~[bundleFile:?]*
> * at org.openhab.core.io.net.http.HttpUtil.executeUrl(HttpUtil.java:130) ~[bundleFile:?]*
> * at org.openhab.core.io.net.http.HttpUtil.executeUrl(HttpUtil.java:108) ~[bundleFile:?]*
> * at org.openhab.core.io.net.http.HttpUtil.executeUrl(HttpUtil.java:89) ~[bundleFile:?]*
> * at org.openhab.binding.sonos.internal.handler.ZonePlayerHandler.updateMediaInformation(ZonePlayerHandler.java:1263) [bundleFile:?]*
> * at org.openhab.binding.sonos.internal.handler.ZonePlayerHandler.onValueReceived(ZonePlayerHandler.java:507) [bundleFile:?]*
> * at org.openhab.core.io.transport.upnp.internal.UpnpIOServiceImpl$UpnpSubscriptionCallback.eventReceived(UpnpIOServiceImpl.java:161) [bundleFile:?]*
> * at org.jupnp.controlpoint.SubscriptionCallback$2.eventReceived(SubscriptionCallback.java:222) [bundleFile:?]*
> * at org.jupnp.model.gena.RemoteGENASubscription.receive(RemoteGENASubscription.java:114) [bundleFile:?]*
> * at org.jupnp.protocol.sync.ReceivingEvent$2.run(ReceivingEvent.java:130) [bundleFile:?]*
> * at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]*
> * at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]*
> * at java.lang.Thread.run(Thread.java:829) [?:?]*
> *Caused by: java.util.concurrent.ExecutionException: java.io.EOFException: HttpConnectionOverHTTP@190ae0e::SocketChannelEndPoint@1916229{l=/192.168.1.180:44790,r=opml.radiotime.com/104.17.107.184:80,ISHUT,fill=-,flush=-,to=51/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@190ae0e(l:/192.168.1.180:44790 <-> r:opml.radiotime.com/104.17.107.184:80,closed=false)=>HttpChannelOverHTTP@119f078(exchange=HttpExchange@1fb8ae1{req=HttpRequest[GET /Describe.ashx HTTP/1.1]@1ffba8e[TERMINATED/null] res=HttpResponse[null 0 null]@14102b2[PENDING/null]})[send=HttpSenderOverHTTP@17fbd12(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7a921e{s=START}],recv=HttpReceiverOverHTTP@3d6715(rsp=IDLE,failure=null)[HttpParser{s=CLOSED,0 of -1}]]*
> * at org.eclipse.jetty.client.util.FutureResponseListener.getResult(FutureResponseListener.java:118) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.client.util.FutureResponseListener.get(FutureResponseListener.java:101) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.client.HttpRequest.send(HttpRequest.java:730) ~[bundleFile:9.4.46.v20220331]*
> * at org.openhab.core.io.net.http.HttpUtil.executeUrlAndGetReponse(HttpUtil.java:251) ~[bundleFile:?]*
> * ... 13 more*
> *Caused by: java.io.EOFException: HttpConnectionOverHTTP@190ae0e::SocketChannelEndPoint@1916229{l=/192.168.1.180:44790,r=opml.radiotime.com/104.17.107.184:80,ISHUT,fill=-,flush=-,to=51/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@190ae0e(l:/192.168.1.180:44790 <-> r:opml.radiotime.com/104.17.107.184:80,closed=false)=>HttpChannelOverHTTP@119f078(exchange=HttpExchange@1fb8ae1{req=HttpRequest[GET /Describe.ashx HTTP/1.1]@1ffba8e[TERMINATED/null] res=HttpResponse[null 0 null]@14102b2[PENDING/null]})[send=HttpSenderOverHTTP@17fbd12(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@7a921e{s=START}],recv=HttpReceiverOverHTTP@3d6715(rsp=IDLE,failure=null)[HttpParser{s=CLOSED,0 of -1}]]*
> * at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.earlyEOF(HttpReceiverOverHTTP.java:385) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:1620) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.shutdown(HttpReceiverOverHTTP.java:269) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.process(HttpReceiverOverHTTP.java:185) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.receive(HttpReceiverOverHTTP.java:80) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.client.http.HttpChannelOverHTTP.receive(HttpChannelOverHTTP.java:131) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.client.http.HttpConnectionOverHTTP.onFillable(HttpConnectionOverHTTP.java:172) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) ~[bundleFile:9.4.46.v20220331]*
> * at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) ~[bundleFile:9.4.46.v20220331]*
> * ... 1 more*
This is the opml.radiotime.com requests (triggered by the Sonos binding) failing due to an unknown reason. Maybe the URL you entered in binding config is wrong. Just remove the OPML URL in the Sonos binding config.
In case you can’t do that with Main UI, stop OH, remove the entry in your file “userdata/config/binding/sonos.config” and restart OH.
