[myopenhab - connection leaked] Strange log entry?


(Brad Gilmer) #1

Hello…

Just noticed the following in my log file after a restart - I do not recall seeing it before…

2018-01-28 11:58:12.094 [WARN ] [okhttp3.OkHttpClient                ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body?

Any ideas?


(SiHui) #2

Same here … I did ignore it :innocent::sunglasses: but that may be not the best option.


(Brad Gilmer) #3

A little additional information:

Hardware: raspberrypi B+
OS:Raspbian jessie
openHAB version: 2.2.0 Release Build


(Daniel Planets) #4

I get that message, too, raspb. 3 B, openhabian, OH2 2.2.0 Release build


(Salexes) #5

Same setup. Same log entry


(Skinah) #6

Getting this as well, any ideas what it means or what to do?

It happens after a long error that starts with…

[ERROR] [io.openhabcloud.internal.CloudClient] - Socket.IO error: {}
io.socket.engineio.client.EngineIOException: xhr poll error


(Brad Gilmer) #7

Maybe @Kai has a comment on this error?


(Stijn Debusschere) #8

Same here:

2018-03-21 12:43:28.435 [ERROR] [io.openhabcloud.internal.CloudClient] - Socket.IO error: {}

io.socket.engineio.client.EngineIOException: xhr post error

at io.socket.engineio.client.Transport.onError(Transport.java:71) [249:org.openhab.io.openhabcloud:2.3.0.201803161151]

at io.socket.engineio.client.transports.PollingXHR.access$000(PollingXHR.java:19) [249:org.openhab.io.openhabcloud:2.3.0.201803161151]

at io.socket.engineio.client.transports.PollingXHR$4$1.run(PollingXHR.java:88) [249:org.openhab.io.openhabcloud:2.3.0.201803161151]

at io.socket.thread.EventThread$2.run(EventThread.java:80) [249:org.openhab.io.openhabcloud:2.3.0.201803161151]

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.IOException: 502

at io.socket.engineio.client.transports.PollingXHR$Request$1.run(PollingXHR.java:218) ~[?:?]

... 1 more

2018-03-21 12:43:28.438 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 9c113b74-7fd3-47c4-9d42-9b83dc04da97, base URL = http://localhost:8080)

2018-03-21 12:47:54.686 [WARN ] [okhttp3.OkHttpClient ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body?

Is any solution available?


(Alexander) #9

same here


(OzZy) #10

Me too, I have all of those messages sometimes in my log. Is there any action rather than ignore?


(Ryan Houtz) #11

I’m seeing this too from 2 different systems in 2 different locations.
Both are Rpi 3 with the latest OH2. Mine gave IOException: 400. See event.log below.
Does anyone know if this is a security risk? In today’s environment leaks are never good.


(Sam) #12

I have a feeling myopenhab.org went down a couple of hours ago. Unfortunately I have to reboot my openhab to connect to it again.


(Kim Andersen) #13

I had this log entry as well untill two days ago, where I entered my Rpi using Putty, and ran the openhabian-config and the option “upgrade recommend system files” or something simular.
Also cleared the cache and tmp folder…

Since then I have not seen this log entry.


(Andrea Riela) #14

Same here right now. OH 2.4.0 Build #1351


(Chris Cornelius) #15

These seems to be no response. I also get exactly this WARN message as in the first post.

I run openHAB 2.3.0-1 (Release Build)

Any ideas from the developers?


(D) #16

Bump? Is obviously a very explicit message which was created, what does it mean?


(AE) #17

I had the same issue today, my openhab/google home integration wasn’t working. Upon further investigation found that it seems openhab lost it’s connection to myopenhab and didn’t properly re-establish it. I had to restart openhab.

Wondering if anyone has any suggestions on how to validate the connection in say a cron job so that I can automatically restart openhab in this situation.

This seems like a bug. If I see this occur again i’ll open a official bug report.

relevant logs, you can see it was disconnect and appeared to reconnect. However it doesn’t seem to be the case.

2019-01-05 11:13:03.665 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 39854f51-00db-4779-9ece-9f760add16e0, base URL = http://localhost:8080)
2019-01-05 11:13:09.605 [WARN ] [okhttp3.OkHttpClient                ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body?
2019-01-05 11:13:18.661 [ERROR] [io.openhabcloud.internal.CloudClient] - Socket.IO error: {}
io.socket.engineio.client.EngineIOException: xhr poll error
        at io.socket.engineio.client.Transport.onError(Transport.java:71) [237:org.openhab.io.openhabcloud:2.4.0]
        at io.socket.engineio.client.transports.PollingXHR.access$100(PollingXHR.java:19) [237:org.openhab.io.openhabcloud:2.4.0]
        at io.socket.engineio.client.transports.PollingXHR$6$1.run(PollingXHR.java:124) [237:org.openhab.io.openhabcloud:2.4.0]
        at io.socket.thread.EventThread$2.run(EventThread.java:80) [237: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.IOException: 502
        at io.socket.engineio.client.transports.PollingXHR$Request$1.run(PollingXHR.java:218) ~[?:?]
        ... 1 more
2019-01-05 11:13:18.663 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = xxx, base URL = http://localhost:8080)
2019-01-05 11:13:27.013 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = xxx, base URL = http://localhost:8080)
2019-01-05 11:15:44.540 [WARN ] [okhttp3.OkHttpClient                ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body?
2019-01-05 11:27:19.417 [WARN ] [okhttp3.OkHttpClient                ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body?

(Jan Gustafsson) #18

I got the same error in my logs today on OH2.4.0, my OH/GH integration was not working.

2019-01-08 14:04:44.398 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = ae56e724-d8a8-443a-aa02-3c7ae6bxxxx, base URL = http://localhost:8080)
2019-01-08 14:06:58.609 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = ae56e724-d8a8-443a-aa02-3c7ae6bxxxx, base URL = http://localhost:8080)
2019-01-08 14:07:18.433 [ERROR] [io.openhabcloud.internal.CloudClient] - Socket.IO error: {}
io.socket.engineio.client.EngineIOException: xhr post error
        at io.socket.engineio.client.Transport.onError(Transport.java:71) [228:org.openhab.io.openhabcloud:2.4.0]
        at io.socket.engineio.client.transports.PollingXHR.access$000(PollingXHR.java:19) [228:org.openhab.io.openhabcloud:2.4.0]
        at io.socket.engineio.client.transports.PollingXHR$4$1.run(PollingXHR.java:88) [228:org.openhab.io.openhabcloud:2.4.0]
        at io.socket.thread.EventThread$2.run(EventThread.java:80) [228:org.openhab.io.openhabcloud:2.4.0]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:?]
        at java.lang.Thread.run(Thread.java:745) [?:?]
Caused by: java.io.IOException: 502
        at io.socket.engineio.client.transports.PollingXHR$Request$1.run(PollingXHR.java:218) ~[?:?]
        ... 1 more
2019-01-08 14:07:18.496 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = ae56e724-d8a8-443a-aa02-3c7ae6bxxxx, base URL = http://localhost:8080)
2019-01-08 14:08:41.926 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = ae56e724-d8a8-443a-aa02-3c7ae6bxxxx, base URL = http://localhost:8080)
2019-01-08 14:09:07.189 [ERROR] [io.openhabcloud.internal.CloudClient] - Socket.IO error: {}
io.socket.engineio.client.EngineIOException: xhr post error
        at io.socket.engineio.client.Transport.onError(Transport.java:71) [228:org.openhab.io.openhabcloud:2.4.0]
        at io.socket.engineio.client.transports.PollingXHR.access$000(PollingXHR.java:19) [228:org.openhab.io.openhabcloud:2.4.0]
        at io.socket.engineio.client.transports.PollingXHR$4$1.run(PollingXHR.java:88) [228:org.openhab.io.openhabcloud:2.4.0]
        at io.socket.thread.EventThread$2.run(EventThread.java:80) [228:org.openhab.io.openhabcloud:2.4.0]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:?]
        at java.lang.Thread.run(Thread.java:745) [?:?]
Caused by: java.io.IOException: 502
        at io.socket.engineio.client.transports.PollingXHR$Request$1.run(PollingXHR.java:218) ~[?:?]
        ... 1 more
2019-01-08 14:09:07.216 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = ae56e724-d8a8-443a-aa02-3c7ae6bxxxxx, base URL = http://localhost:8080)
2019-01-08 14:09:09.107 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = ae56e724-d8a8-443a-aa02-3c7ae6bxxxx, base URL = http://localhost:8080)
2019-01-08 14:09:34.254 [ERROR] [io.openhabcloud.internal.CloudClient] - Socket.IO error: {}
io.socket.engineio.client.EngineIOException: xhr post error
        at io.socket.engineio.client.Transport.onError(Transport.java:71) [228:org.openhab.io.openhabcloud:2.4.0]
        at io.socket.engineio.client.transports.PollingXHR.access$000(PollingXHR.java:19) [228:org.openhab.io.openhabcloud:2.4.0]
        at io.socket.engineio.client.transports.PollingXHR$4$1.run(PollingXHR.java:88) [228:org.openhab.io.openhabcloud:2.4.0]
        at io.socket.thread.EventThread$2.run(EventThread.java:80) [228:org.openhab.io.openhabcloud:2.4.0]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:?]
        at java.lang.Thread.run(Thread.java:745) [?:?]
Caused by: java.io.IOException: 502
        at io.socket.engineio.client.transports.PollingXHR$Request$1.run(PollingXHR.java:218) ~[?:?]
        ... 1 more
2019-01-08 14:09:34.282 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = ae56e724-d8a8-443a-aa02-3c7ae6bxxxx, base URL = http://localhost:8080)
2019-01-08 14:09:41.997 [ERROR] [io.openhabcloud.internal.CloudClient] - Socket.IO error: {}
io.socket.engineio.client.EngineIOException: xhr poll error
        at io.socket.engineio.client.Transport.onError(Transport.java:71) [228:org.openhab.io.openhabcloud:2.4.0]
        at io.socket.engineio.client.transports.PollingXHR.access$100(PollingXHR.java:19) [228:org.openhab.io.openhabcloud:2.4.0]
        at io.socket.engineio.client.transports.PollingXHR$6$1.run(PollingXHR.java:124) [228:org.openhab.io.openhabcloud:2.4.0]
        at io.socket.thread.EventThread$2.run(EventThread.java:80) [228:org.openhab.io.openhabcloud:2.4.0]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:?]
        at java.lang.Thread.run(Thread.java:745) [?:?]
Caused by: java.io.IOException: 502
        at io.socket.engineio.client.transports.PollingXHR$Request$1.run(PollingXHR.java:218) ~[?:?]
        ... 1 more
2019-01-08 14:09:42.028 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = ae56e724-d8a8-443a-aa02-3c7ae6bxxxx, base URL = http://localhost:8080)
2019-01-08 14:09:46.091 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = ae56e724-d8a8-443a-aa02-3c7ae6bxxxx, base URL = http://localhost:8080)
2019-01-08 14:11:53.160 [WARN ] [okhttp3.OkHttpClient                ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body?
2019-01-08 14:13:18.273 [INFO ] [impl.connections.SubscriptionManager] - Publishing changes for 1486852426
2019-01-08 14:13:18.354 [INFO ] [impl.connections.SubscriptionManager] - Publishing changes for 122578313
2019-01-08 14:13:41.827 [WARN ] [okhttp3.OkHttpClient                ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body?
2019-01-08 14:14:08.970 [WARN ] [okhttp3.OkHttpClient                ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body?

myopenhab.org reports that my installation is offline, I’ll wait until tomorrow to see if I need to reboot my raspeberry PI to restore connection.

BR,

/Janne


(Davide ) #19

I also confirm a similar problem, with loss of connection with Google Home for some time
and disconnect / reconnect to OHcloud service

2019-01-10 01:30:55.376 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = aa5c3aa7-5c53-4881-81c7-cfb304f1d970, base URL = http://localhost:8080)
2019-01-10 01:31:33.195 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = aa5c3aa7-5c53-4881-81c7-cfb304f1d970, base URL = http://localhost:8080)
2019-01-10 01:35:25.312 [WARN ] [okhttp3.OkHttpClient                ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body?
2019-01-10 01:35:54.682 [WARN ] [okhttp3.OkHttpClient                ] - A connection to https://myopenhab.org/ was leaked. Did you forget to close a response body?
2019-01-10 05:57:58.989 [WARN ] [org.eclipse.jetty.http.HttpParser   ] - Illegal character 0x5 in state=START for buffer HeapByteBuffer@21a75ac2[p=1,l=3,c=8192,r=2]={\x05<<<\x01\x00>>>P/1.1 302 Found\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2019-01-10 05:57:58.990 [WARN ] [org.eclipse.jetty.http.HttpParser   ] - bad HTTP parsed: 400 Illegal character 0x5 for HttpChannelOverHttp@e962435{r=0,c=false,a=IDLE,uri=null}

(Ralph Sester) #20

Same to me.

socket error and no Connection from Alexa and Remote-Access.
In the browser it Displays “forbidden” oder “offline”.

After Restart of the openhabcloud-binding it works again for 1 or 2 days.

i use Openhab 2.4 RTM, with Windows Server 2016

I have no ideas….