I am getting this error in my log every few minutes. I have seen the old thread but I am on openHAB3.2.0 snapshot build 2618. The error stops when I disable the binding.
2021-12-14 19:50:23.590 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
2021-12-14 19:52:32.717 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
Hi,
Have the same problem.
Previously i found a custom jar that fixed the issue, but the link has been removed from the original thread.
Pb still remains.
Thx for the updated link. v3.2.8 of the binding works fine with OH3.2.0 RC1 on openhabian. The old version v3.2.7 causes issue with OH3.2.0 RC1 which is probably why it was removed.
Oddly enough when I did the OH3.2.0 RC1 upgrade with the v3.2.7 echo binding previously installed it refused to install any other bindings other than v3.2.7 echo binding. After a few minutes of messing around I discovered that if I DIDN’T have v3.2.7 echo binding installed then the other bindings installed just fine…all confirmed through the console. Re-installing v3.2.7 echo at this point resulted in all other bindings disappearing, again confirmed through the console.
After which I came to the oh community and found this post about the 3.2.8 echo binding file which works great. I didn’t dig into it any further.
Is there any reason why this binding isn’t in the openhab-addons repository? It’s kinda weird to have 2 different bindings, one that constantly logs errors and one that apparently works and doesn’t.
I know this does not answer your question, but at least it makes it possible to install those addons through the MainUI:
For openHAB 3.2.0 and later it is recommended to add https://download.smarthomej.org/addons.json as JSON 3rd Party Addon Service in the settings. Afterwards all addons can be installed from the UI.
Hate to ask but can you clarify
“For openHAB 3.2.0 and later it is recommended to add https://download.smarthomej.org/addons.json as JSON 3rd Party Addon Service in the settings. Afterwards all addons can be installed from the UI.”
How to add */addons.json as a 3rd party addon service?
Hello all, i currently do have a similar issue. However, before i update to a 3rd Party Addon and re-setup all the things and items i want to clarify if i dont have a different issue.
My current setup:
Rasperry PI 3b+ with Openhabian
Openhab 3.2
Amazon Control Echo Binding Version 3.2 (Written in settings → bindings → Amazon Control Echo Binding → Version)
I do get the following warning in the openhab log:
2021-12-31 11:30:01.261 [WARN ] [mazonechocontrol.internal.Connection] - Request to url 'https://alexa.amazon.de/api/notification/sounds?deviceSerialNumber=REMOVEDFORTHISPOST&deviceType=REMOVEDFORTHISPOST&softwareVersion=0' fails with unknown error
java.net.ConnectException: Die Wartezeit für die Verbindung ist abgelaufen (Connection timed out)
at java.net.PlainSocketImpl.socketConnect(Native Method) ~[?:?]
at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:399) ~[?:?]
at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:242) ~[?:?]
at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:224) ~[?:?]
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) ~[?:?]
at java.net.Socket.connect(Socket.java:609) ~[?:?]
at sun.security.ssl.SSLSocketImpl.connect(SSLSocketImpl.java:299) ~[?:?]
at sun.security.ssl.BaseSSLSocketImpl.connect(BaseSSLSocketImpl.java:173) ~[?:?]
at sun.net.NetworkClient.doConnect(NetworkClient.java:182) ~[?:?]
at sun.net.www.http.HttpClient.openServer(HttpClient.java:474) ~[?:?]
at sun.net.www.http.HttpClient.openServer(HttpClient.java:569) ~[?:?]
at sun.net.www.protocol.https.HttpsClient.<init>(HttpsClient.java:266) ~[?:?]
at sun.net.www.protocol.https.HttpsClient.New(HttpsClient.java:373) ~[?:?]
at sun.net.www.protocol.https.AbstractDelegateHttpsURLConnection.getNewHttpClient(AbstractDelegateHttpsURLConnection.java:203) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.plainConnect0(HttpURLConnection.java:1187) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.plainConnect(HttpURLConnection.java:1081) ~[?:?]
at sun.net.www.protocol.https.AbstractDelegateHttpsURLConnection.connect(AbstractDelegateHttpsURLConnection.java:189) ~[?:?]
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.getNotificationSounds(Connection.java:1912) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.refreshData(AccountHandler.java:542) ~[?:?]
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) [?:?]
and also:
2021-12-31 11:39:19.106 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
2021-12-31 11:41:16.949 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
2021-12-31 11:43:06.011 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
2021-12-31 11:44:57.089 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
2021-12-31 11:46:46.166 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
2021-12-31 11:48:42.604 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
2021-12-31 11:50:36.486 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
2021-12-31 11:59:51.397 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
2021-12-31 12:01:51.892 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
2021-12-31 12:03:48.039 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
2021-12-31 12:05:54.508 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
2021-12-31 12:10:04.865 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
2021-12-31 12:12:07.274 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
2021-12-31 12:16:13.477 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null
Also the communication between amazon → openhab seems to be very slow since a few weeks.
Will the change to the 3rd party addon fix both issues or is it something different?
Connection Timeouts happen sometimes and usually fix by themself on next attempt. It’s a bit confusing that they are logged with the full stacktrace. If this is only happening randomly, you can simply ignore that.
The websocket issue can cause delays for the whole connection.
Thank you for your fast answer!
Got some additional log infos / warnings / failures in the meantime:
2021-12-31 12:54:09.588 [WARN ] [trol.internal.handler.AccountHandler] - updateSmartHomeState fails with unexpected error
java.net.ConnectException: Die Wartezeit für die Verbindung ist abgelaufen (Connection timed out)
at java.net.PlainSocketImpl.socketConnect(Native Method) ~[?:?]
at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:399) ~[?:?]
at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:242) ~[?:?]
at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:224) ~[?:?]
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) ~[?:?]
at java.net.Socket.connect(Socket.java:609) ~[?:?]
at sun.security.ssl.SSLSocketImpl.connect(SSLSocketImpl.java:299) ~[?:?]
at sun.security.ssl.BaseSSLSocketImpl.connect(BaseSSLSocketImpl.java:173) ~[?:?]
at sun.net.NetworkClient.doConnect(NetworkClient.java:182) ~[?:?]
at sun.net.www.http.HttpClient.openServer(HttpClient.java:474) ~[?:?]
at sun.net.www.http.HttpClient.openServer(HttpClient.java:569) ~[?:?]
at sun.net.www.protocol.https.HttpsClient.<init>(HttpsClient.java:266) ~[?:?]
at sun.net.www.protocol.https.HttpsClient.New(HttpsClient.java:373) ~[?:?]
at sun.net.www.protocol.https.AbstractDelegateHttpsURLConnection.getNewHttpClient(AbstractDelegateHttpsURLConnection.java:203) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.plainConnect0(HttpURLConnection.java:1187) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.plainConnect(HttpURLConnection.java:1081) ~[?:?]
at sun.net.www.protocol.https.AbstractDelegateHttpsURLConnection.connect(AbstractDelegateHttpsURLConnection.java:189) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.getOutputStream0(HttpURLConnection.java:1367) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.getOutputStream(HttpURLConnection.java:1342) ~[?:?]
at sun.net.www.protocol.https.HttpsURLConnectionImpl.getOutputStream(HttpsURLConnectionImpl.java:246) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.makeRequest(Connection.java:634) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.makeRequestAndReturnString(Connection.java:555) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.Connection.getSmartHomeDeviceStatesJson(Connection.java:1076) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.updateSmartHomeState(AccountHandler.java:911) ~[?:?]
at org.openhab.binding.amazonechocontrol.internal.handler.AccountHandler.lambda$2(AccountHandler.java:167) ~[?:?]
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) [?:?]
==> /var/log/openhab/events.log <==
2021-12-31 12:54:09.607 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:account:account1' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Die Wartezeit für die Verbindung ist abgelaufen (Connection timed out)
2021-12-31 12:54:09.612 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:wha:account1:xxxxxxxxxx' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2021-12-31 12:54:09.618 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:echospot:account1:xxxxxxx5' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2021-12-31 12:54:09.622 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:wha:account1:2xxxxxxdb' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2021-12-31 12:54:09.625 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:echo:account1:xxxxxxx' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2021-12-31 12:54:09.628 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:echospot:6xxxxxxxx' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2021-12-31 12:54:09.631 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:smartHomeDevice:account1:9xxxxxxx1' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2021-12-31 12:54:09.635 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:smartHomeDevice:account1:04xxxxxxx46b' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2021-12-31 12:54:09.637 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:smartHomeDevice:account1:dxxxxxxxx11' changed from UNKNOWN: State not found to OFFLINE (BRIDGE_OFFLINE)
2021-12-31 12:54:09.640 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:smartHomeDevice:account1:16xxxxxxx' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2021-12-31 12:54:09.643 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:smartHomeDevice:account1:6xxxxxxxxxa' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2021-12-31 12:54:09.742 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'ArduinoIRcontrol_ArduinoIRcontrol_Alive' changed from 1059.44 to 1059.64
2021-12-31 12:54:16.929 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Ble2mqtt_Ble2mqtt_Xiaomi_Sensor_3_Timestamp' changed from 1640951614 to 1640951656
2021-12-31 12:54:19.438 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Ble2mqtt_Ble2mqtt_Xiaomi_Sensor_3_Timestamp' changed from 1640951656 to 1640951659
2021-12-31 12:54:20.039 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:smartHomeDevice:account1:xxxxxxx' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE
2021-12-31 12:54:20.049 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:smartHomeDevice:account1:xxxxxxxxx' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE
2021-12-31 12:54:20.058 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:smartHomeDevice:account1:xxxxxxxxx' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE
2021-12-31 12:54:20.068 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:smartHomeDevice:account1:xxxxxxxxx' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE
2021-12-31 12:54:20.077 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:smartHomeDevice:account1:xxxxxxxxx' changed from OFFLINE (BRIDGE_OFFLINE) to UNKNOWN: State not found
2021-12-31 12:56:22.705 [INFO ] [mazonechocontrol.internal.Connection] - getting wakewords failed
java.net.ConnectException: Die Wartezeit für die Verbindung ist abgelaufen (Connection timed out)
at java.net.PlainSocketImpl.socketConnect(Native Method) ~[?:?]
at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:399) ~[?:?]
at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:242) ~[?:?]
at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:224) ~[?:?]
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) ~[?:?]
at java.net.Socket.connect(Socket.java:609) ~[?:?]
at sun.security.ssl.SSLSocketImpl.connect(SSLSocketImpl.java:299) ~[?:?]
at sun.security.ssl.BaseSSLSocketImpl.connect(BaseSSLSocketImpl.java:173) ~[?:?]
at sun.net.NetworkClient.doConnect(NetworkClient.java:182) ~[?:?]
at sun.net.www.http.HttpClient.openServer(HttpClient.java:474) ~[?:?]
at sun.net.www.http.HttpClient.openServer(HttpClient.java:569) ~[?:?]
at sun.net.www.protocol.https.HttpsClient.<init>(HttpsClient.java:266) ~[?:?]
at sun.net.www.protocol.https.HttpsClient.New(HttpsClient.java:373) ~[?:?]
at sun.net.www.protocol.https.AbstractDelegateHttpsURLConnection.getNewHttpClient(AbstractDelegateHttpsURLConnection.java:203) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.plainConnect0(HttpURLConnection.java:1187) ~[?:?]
at sun.net.www.protocol.http.HttpURLConnection.plainConnect(HttpURLConnection.java:1081) ~[?:?]
at sun.net.www.protocol.https.AbstractDelegateHttpsURLConnection.connect(AbstractDelegateHttpsURLConnection.java:189) ~[?:?]
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) [?:?]
==> /var/log/openhab/events.log <==
2021-12-31 12:56:22.722 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:wha:account1:xxxxxx' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE
2021-12-31 12:56:22.760 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:echospot:account1:xxxxxxx' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE
2021-12-31 12:56:22.773 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:wha:account1:xxxxxx' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE
2021-12-31 12:56:22.786 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:echo:account1:xxxxxxx' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE
2021-12-31 12:56:22.795 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'amazonechocontrol:echospot:xxxxxxx' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE