Warning Shelly 2.5 since firmware update 1.8

to.bi · August 13, 2020, 5:36pm

Platform information:
- Hardware: raspberry PI 4 / 4 GB RAM / SD 32 GB
- OS: Raspbian GNU/Linux 10 (buster)
- Java Runtime Environment: which java platform is used and what version
- openHAB version: 2.5.7-1
Issue of the topic:

I integrated Shelly 2.5 in *.things file. Since firmware update 1.8 I got every few seconds warnings (see below). My Shelly HT & Shellt i3 over MQTT does not have this problem. Any idea to remove that warnings?

Please post configurations (if applicable):

Thing shelly:shelly25-roller:192_168_178_58 “Shelly 25 Roller (Arbeit)” @ “Arbeit” [ deviceIp=“192.168.178.58”, updateInterval=60 ]
Thing shelly:shelly25-roller:192_168_178_59 “Shelly 25 Roller (Eltern)” @ “Eltern” [ deviceIp=“192.168.178.59”, updateInterval=60 ]
Thing shelly:shelly25-roller:192_168_178_54 “Shelly 25 Roller (Gast)” @ “Gast” [ deviceIp=“192.168.178.54”, updateInterval=60 ]
Thing shelly:shelly25-roller:192_168_178_64 “Shelly 25 Roller (Markise)” @ “Aussen” [ deviceIp=“192.168.178.64”, updateInterval=60 ]
Thing shelly:shellyht:192_168_178_57 “Shelly H&T (Bad)” @ “Bad” [ deviceIp=“192.168.178.57”, updateInterval=600, lowBattery=10 ]
Thing shelly:shellyht:192_168_178_62 “Shelly H&T (Eltern)” @ “Eltern” [ deviceIp=“192.168.178.62”, updateInterval=600, lowBattery=10 ]
If logs where generated please post these here using code fences:

2020-08-13 19:18:00.615 [WARN ] [network.InMemoryMessageExchangeStore] - coap Exchange[L399] with manual token overrides existing Exchange[L398] with open request: KeyToken[192.168.178.64:5683-]

2020-08-13 19:18:02.911 [WARN ] [.californium.core.network.UdpMatcher] - error receiving response ACK-2.05 MID=46203, Token=, OptionSet={“Unknown (3332)”:0x534853572d3235234243444443323737393233322332}, "{“blk”:[{“I”:1,“D”:“rela”… 1024 bytes for Exchange[L398, complete]

com.google.gson.JsonSyntaxException: java.lang.IllegalStateException: Expected a string but was BEGIN_ARRAY at line 1 column 258 path $.sen[2].R

at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:224) ~[?:?]

at com.google.gson.internal.bind.TypeAdapterRuntimeTypeWrapper.read(TypeAdapterRuntimeTypeWrapper.java:41) ~[?:?]

at com.google.gson.internal.bind.CollectionTypeAdapterFactory$Adapter.read(CollectionTypeAdapterFactory.java:82) ~[?:?]

at com.google.gson.internal.bind.CollectionTypeAdapterFactory$Adapter.read(CollectionTypeAdapterFactory.java:61) ~[?:?]

at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$1.read(ReflectiveTypeAdapterFactory.java:129) ~[?:?]

at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:220) ~[?:?]

at com.google.gson.Gson.fromJson(Gson.java:888) ~[?:?]

at com.google.gson.Gson.fromJson(Gson.java:853) ~[?:?]

at com.google.gson.Gson.fromJson(Gson.java:802) ~[?:?]

at com.google.gson.Gson.fromJson(Gson.java:774) ~[?:?]

at org.openhab.binding.shelly.internal.coap.ShellyCoapHandler.handleDeviceDescription(ShellyCoapHandler.java:252) ~[?:?]

at org.openhab.binding.shelly.internal.coap.ShellyCoapHandler.processResponse(ShellyCoapHandler.java:216) ~[?:?]

at org.openhab.binding.shelly.internal.coap.ShellyCoapHandler$1.onResponse(ShellyCoapHandler.java:884) ~[?:?]

at org.eclipse.californium.core.coap.Request.setResponse(Request.java:711) ~[bundleFile:?]

at org.eclipse.californium.core.network.EndpointManager$ClientMessageDeliverer.deliverResponse(EndpointManager.java:272) ~[bundleFile:?]

at org.eclipse.californium.core.network.stack.BaseCoapStack$StackTopAdapter.receiveResponse(BaseCoapStack.java:210) ~[bundleFile:?]

at org.eclipse.californium.core.network.stack.AbstractLayer.receiveResponse(AbstractLayer.java:89) ~[bundleFile:?]

at org.eclipse.californium.core.network.stack.ExchangeCleanupLayer.receiveResponse(ExchangeCleanupLayer.java:93) ~[bundleFile:?]

at org.eclipse.californium.core.network.stack.ObserveLayer.receiveResponse(ObserveLayer.java:150) ~[bundleFile:?]

at org.eclipse.californium.core.network.stack.BlockwiseLayer.receiveResponse(BlockwiseLayer.java:676) ~[bundleFile:?]

at org.eclipse.californium.core.network.stack.ReliabilityLayer.receiveResponse(ReliabilityLayer.java:305) ~[bundleFile:?]

at org.eclipse.californium.core.network.stack.AbstractLayer.receiveResponse(AbstractLayer.java:89) ~[bundleFile:?]

at org.eclipse.californium.core.network.stack.BaseCoapStack.receiveResponse(BaseCoapStack.java:139) ~[bundleFile:?]

at org.eclipse.californium.core.network.CoapEndpoint$1.receiveResponse(CoapEndpoint.java:277) ~[bundleFile:?]

at org.eclipse.californium.core.network.UdpMatcher$4.run(UdpMatcher.java:399) [bundleFile:?]

at org.eclipse.californium.elements.util.SerialExecutor$1.run(SerialExecutor.java:276) [bundleFile:?]

at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:1.8.0_252]

at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:1.8.0_252]

at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:1.8.0_252]

at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:1.8.0_252]

at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_252]

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_252]

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

Caused by: java.lang.IllegalStateException: Expected a string but was BEGIN_ARRAY at line 1 column 258 path $.sen[2].R

at com.google.gson.stream.JsonReader.nextString(JsonReader.java:825) ~[?:?]

at com.google.gson.internal.bind.TypeAdapters$16.read(TypeAdapters.java:401) ~[?:?]

at com.google.gson.internal.bind.TypeAdapters$16.read(TypeAdapters.java:389) ~[?:?]

at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$1.read(ReflectiveTypeAdapterFactory.java:129) ~[?:?]

at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:220) ~[?:?]

... 32 more

hmerk · August 13, 2020, 5:43pm

You need the latest Dev version of the Binding as the Coap implementation changed with fw 1.8. Please follow Shelly Binding thread and read beta readme how to use Dev versions.

github.com

markus7017/myfiles/blob/master/shelly/READMEbeta.md


# Shelly Binding (org.openhab.binding.shelly)

This openHAB 2 Binding implements control for the Shelly series of devices.
This includes sending commands to the devices as well as reading the device status and sensor data.

Author: Markus Michels (markus7017)
Check  https://community.openhab.org/t/shelly-binding/ for more information, questions and contributing ideas. Any comments are welcome!

Also check section **Additional Information** at the end of the document.
This includes some general comments, information how to debug and request new features.

Please let me know if you have problems installing the new build or this doc can be improved.

---

DISCLAIMER: Please be ware, installing a SNAPSHOT or DEV build might impact the stability of your installation and cause backward compatibility issues. Do a full backup of your installation any time you do an update.

---

This file has been truncated. show original

to.bi · August 14, 2020, 5:15am

Thanks @hmerk for your quick reply. I have no experience with installing of dev version. I think I’ll wait for the release version.

StefanH · August 17, 2020, 8:25am

found this thread as I installed a new shelly1 and I hit the “update” button which led to v1.8 being installed, generating problems

I fixed this by downgrading the shelly.

download the correct firmware from here https://www.shelly-support.eu/filebase/
put the file in your openhab config html folder, like /etc/openhab2/html
in my case I tested the successful access via http://%OPENHAB_IP%/static/%FW_FILENAME%
Tell your shelly to install this file by using the following URL: http://%SHELLY_IP%/ota?url=%URL_FROM_STEP3%

to.bi · August 17, 2020, 10:01am

Hi Stefan,

yes I already downgraded my Shellies from 1.8 to 1.7. But you can do it more comfortable by using: http://archive.shelly-faq.de/

StefanH · August 17, 2020, 10:22am

UFFF awesome service, thanks

BoxSon · August 25, 2020, 6:31pm

fixed problems here! Thanks a lot!

DaveL · September 9, 2020, 2:59am

I’m having a few issues, in part with my Shelly’s. It started with the firmware updates. I opted to update to the 2.5.8 binding snapshot before the full release and that fixed my Shelly 2.5 issues. After the full release I removed the Shelly snapshot binding, but the old problem has now returned. I’ve deleted the binding, purged the cache and reinstalled the binding but no improvement. And my logs are now crammed full of errors:

2020-09-08 21:59:57.346 [WARN ] [.californium.core.network.UdpMatcher] - error receiving response ACK-2.05   MID=20613, Token=, OptionSet={"Unknown (3332)":0x534853572d31234334384144382332}, "{"blk":[{"I":1,"D":"rela".. 867 bytes for Exchange[L769, complete]
com.google.gson.JsonSyntaxException: java.lang.IllegalStateException: Expected a string but was BEGIN_ARRAY at line 1 column 319 path $.sen[3].R
	at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:224) ~[?:?]
	at com.google.gson.internal.bind.TypeAdapterRuntimeTypeWrapper.read(TypeAdapterRuntimeTypeWrapper.java:41) ~[?:?]
	at com.google.gson.internal.bind.CollectionTypeAdapterFactory$Adapter.read(CollectionTypeAdapterFactory.java:82) ~[?:?]
	at com.google.gson.internal.bind.CollectionTypeAdapterFactory$Adapter.read(CollectionTypeAdapterFactory.java:61) ~[?:?]
	at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$1.read(ReflectiveTypeAdapterFactory.java:129) ~[?:?]
	at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:220) ~[?:?]
	at com.google.gson.Gson.fromJson(Gson.java:888) ~[?:?]
	at com.google.gson.Gson.fromJson(Gson.java:853) ~[?:?]
	at com.google.gson.Gson.fromJson(Gson.java:802) ~[?:?]
	at com.google.gson.Gson.fromJson(Gson.java:774) ~[?:?]
	at org.openhab.binding.shelly.internal.coap.ShellyCoapHandler.handleDeviceDescription(ShellyCoapHandler.java:252) ~[?:?]
	at org.openhab.binding.shelly.internal.coap.ShellyCoapHandler.processResponse(ShellyCoapHandler.java:216) ~[?:?]
	at org.openhab.binding.shelly.internal.coap.ShellyCoapHandler$1.onResponse(ShellyCoapHandler.java:884) ~[?:?]
	at org.eclipse.californium.core.coap.Request.setResponse(Request.java:711) ~[bundleFile:?]
	at org.eclipse.californium.core.network.EndpointManager$ClientMessageDeliverer.deliverResponse(EndpointManager.java:272) ~[bundleFile:?]
	at org.eclipse.californium.core.network.stack.BaseCoapStack$StackTopAdapter.receiveResponse(BaseCoapStack.java:210) ~[bundleFile:?]
	at org.eclipse.californium.core.network.stack.AbstractLayer.receiveResponse(AbstractLayer.java:89) ~[bundleFile:?]
	at org.eclipse.californium.core.network.stack.ExchangeCleanupLayer.receiveResponse(ExchangeCleanupLayer.java:93) ~[bundleFile:?]
	at org.eclipse.californium.core.network.stack.ObserveLayer.receiveResponse(ObserveLayer.java:150) ~[bundleFile:?]
	at org.eclipse.californium.core.network.stack.BlockwiseLayer.receiveResponse(BlockwiseLayer.java:676) ~[bundleFile:?]
	at org.eclipse.californium.core.network.stack.ReliabilityLayer.receiveResponse(ReliabilityLayer.java:305) ~[bundleFile:?]
	at org.eclipse.californium.core.network.stack.AbstractLayer.receiveResponse(AbstractLayer.java:89) ~[bundleFile:?]
	at org.eclipse.californium.core.network.stack.BaseCoapStack.receiveResponse(BaseCoapStack.java:139) ~[bundleFile:?]
	at org.eclipse.californium.core.network.CoapEndpoint$1.receiveResponse(CoapEndpoint.java:277) ~[bundleFile:?]
	at org.eclipse.californium.core.network.UdpMatcher$4.run(UdpMatcher.java:399) [bundleFile:?]
	at org.eclipse.californium.elements.util.SerialExecutor$1.run(SerialExecutor.java:276) [bundleFile:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:1.8.0_265]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:1.8.0_265]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) [?:1.8.0_265]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) [?:1.8.0_265]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_265]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_265]
	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_265]
Caused by: java.lang.IllegalStateException: Expected a string but was BEGIN_ARRAY at line 1 column 319 path $.sen[3].R
	at com.google.gson.stream.JsonReader.nextString(JsonReader.java:825) ~[?:?]
	at com.google.gson.internal.bind.TypeAdapters$16.read(TypeAdapters.java:401) ~[?:?]
	at com.google.gson.internal.bind.TypeAdapters$16.read(TypeAdapters.java:389) ~[?:?]
	at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$1.read(ReflectiveTypeAdapterFactory.java:129) ~[?:?]
	at com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.read(ReflectiveTypeAdapterFactory.java:220) ~[?:?]
	... 32 more

Debug entries:

2020-09-08 21:52:37.737 [DEBUG] [rg.eclipse.jetty.client.HttpReceiver] - Parsed false, remaining 0 HttpParser{s=CONTENT,421 of 1288}
2020-09-08 21:52:37.737 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=CONTENT DirectByteBuffer@1f59ff53[p=536,l=536,c=16384,r=0]={HTTP/1.1 200 OK\r\n...vertemperature"<<<>>>,"is_valid":true}...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2020-09-08 21:52:37.737 [DEBUG] [rg.eclipse.jetty.client.HttpReceiver] - Parsed false, remaining 0 HttpParser{s=CONTENT,421 of 1288}
2020-09-08 21:52:37.737 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 DirectByteBuffer@1f59ff53[p=0,l=0,c=16384,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2020-09-08 21:52:37.738 [DEBUG] [rg.eclipse.jetty.client.HttpReceiver] - Read 0 bytes DirectByteBuffer@1f59ff53[p=0,l=0,c=16384,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} from SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OPEN,fill=-,flush=-,to=4/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=false)=>HttpChannelOverHTTP@220b6b09(exchange=HttpExchange@3523cb1a req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=CONTENT,failure=null)[HttpParser{s=CONTENT,421 of 1288}]]
2020-09-08 21:52:37.738 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested HttpConnectionOverHTTP@2d7fe857::SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OPEN,fill=-,flush=-,to=4/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=false)=>HttpChannelOverHTTP@220b6b09(exchange=HttpExchange@3523cb1a req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=CONTENT,failure=null)[HttpParser{s=CONTENT,421 of 1288}]]
2020-09-08 21:52:37.738 [DEBUG] [org.eclipse.jetty.io.FillInterest   ] - interested FillInterest@1c53058a{AC.ReadCB@2d7fe857{HttpConnectionOverHTTP@2d7fe857::SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OPEN,fill=FI,flush=-,to=0/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=false)=>HttpChannelOverHTTP@220b6b09(exchange=HttpExchange@3523cb1a req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=CONTENT,failure=null)[HttpParser{s=CONTENT,421 of 1288}]]}}
2020-09-08 21:52:37.739 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - changeInterests p=false 0->1 for SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OPEN,fill=FI,flush=-,to=0/0}{io=0/1,kio=0,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=false)=>HttpChannelOverHTTP@220b6b09(exchange=HttpExchange@3523cb1a req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=CONTENT,failure=null)[HttpParser{s=CONTENT,421 of 1288}]]
2020-09-08 21:52:37.739 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Queued change org.eclipse.jetty.io.ChannelEndPoint$1@1681b6b7 on ManagedSelector@66673d9a{STARTED} id=1 keys=1 selected=0 updates=0
2020-09-08 21:52:37.739 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Wakeup on submit ManagedSelector@66673d9a{STARTED} id=1 keys=1 selected=0 updates=1
2020-09-08 21:52:37.739 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.EPollSelectorImpl@67fc893d woken with none selected
2020-09-08 21:52:37.739 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.EPollSelectorImpl@67fc893d woken up from select, 0/0/1 selected
2020-09-08 21:52:37.740 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.EPollSelectorImpl@67fc893d processing 0 keys, 1 updates
2020-09-08 21:52:37.740 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - updateable 1
2020-09-08 21:52:37.740 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - update org.eclipse.jetty.io.ChannelEndPoint$1@1681b6b7
2020-09-08 21:52:37.740 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - Key interests updated 0 -> 1 on SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OPEN,fill=FI,flush=-,to=1/0}{io=1/1,kio=1,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=false)=>HttpChannelOverHTTP@220b6b09(exchange=HttpExchange@3523cb1a req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=CONTENT,failure=null)[HttpParser{s=CONTENT,421 of 1288}]]
2020-09-08 21:52:37.740 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - updates 0
2020-09-08 21:52:37.740 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.EPollSelectorImpl@67fc893d waiting with 1 keys
2020-09-08 21:52:37.741 [DEBUG] [y.util.thread.ReservedThreadExecutor] - ReservedThreadExecutor@4b8cb451{s=2/4,p=0}@3ab51ee9 waiting
2020-09-08 21:52:37.774 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.EPollSelectorImpl@67fc893d woken up from select, 1/1/1 selected
2020-09-08 21:52:37.774 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.EPollSelectorImpl@67fc893d processing 1 keys, 0 updates
2020-09-08 21:52:37.775 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - selected 1 sun.nio.ch.SelectionKeyImpl@4ac219ab SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OPEN,fill=FI,flush=-,to=36/0}{io=1/1,kio=1,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=false)=>HttpChannelOverHTTP@220b6b09(exchange=HttpExchange@3523cb1a req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=CONTENT,failure=null)[HttpParser{s=CONTENT,421 of 1288}]] 
2020-09-08 21:52:37.775 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - onSelected 1->0 r=true w=false for SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OPEN,fill=FI,flush=-,to=36/0}{io=1/0,kio=1,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=false)=>HttpChannelOverHTTP@220b6b09(exchange=HttpExchange@3523cb1a req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=CONTENT,failure=null)[HttpParser{s=CONTENT,421 of 1288}]]
2020-09-08 21:52:37.775 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - task CEP:SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OPEN,fill=FI,flush=-,to=37/0}{io=1/0,kio=1,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=false)=>HttpChannelOverHTTP@220b6b09(exchange=HttpExchange@3523cb1a req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=CONTENT,failure=null)[HttpParser{s=CONTENT,421 of 1288}]]:runFillable:BLOCKING
2020-09-08 21:52:37.776 [DEBUG] [y.util.thread.ReservedThreadExecutor] - ReservedThreadExecutor@4b8cb451{s=2/4,p=0} tryExecute EatWhatYouKill@424edc6/SelectorProducer@712391e/PRODUCING/p=false/QueuedThreadPool[ESH-httpClient-common]@32833df7{STARTED,10<=10<=40,i=4,r=4,q=0}[ReservedThreadExecutor@4b8cb451{s=2/4,p=0}][pc=0,pic=0,pec=0,epc=482]@2020-09-08T21:52:37.776-05:00
2020-09-08 21:52:37.776 [DEBUG] [y.util.thread.ReservedThreadExecutor] - ReservedThreadExecutor@4b8cb451{s=1/4,p=0}@3ab51ee9 offer EatWhatYouKill@424edc6/SelectorProducer@712391e/PRODUCING/p=false/QueuedThreadPool[ESH-httpClient-common]@32833df7{STARTED,10<=10<=40,i=4,r=4,q=0}[ReservedThreadExecutor@4b8cb451{s=1/4,p=0}][pc=0,pic=0,pec=0,epc=482]@2020-09-08T21:52:37.776-05:00
2020-09-08 21:52:37.776 [DEBUG] [.util.thread.strategy.EatWhatYouKill] - EatWhatYouKill@424edc6/SelectorProducer@712391e/IDLE/p=true/QueuedThreadPool[ESH-httpClient-common]@32833df7{STARTED,10<=10<=40,i=4,r=4,q=0}[ReservedThreadExecutor@4b8cb451{s=1/4,p=0}][pc=0,pic=0,pec=0,epc=482]@2020-09-08T21:52:37.776-05:00 m=EXECUTE_PRODUCE_CONSUME t=CEP:SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OPEN,fill=FI,flush=-,to=38/0}{io=1/0,kio=1,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=false)=>HttpChannelOverHTTP@220b6b09(exchange=HttpExchange@3523cb1a req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=CONTENT,failure=null)[HttpParser{s=CONTENT,421 of 1288}]]:runFillable:BLOCKING/BLOCKING
2020-09-08 21:52:37.777 [DEBUG] [org.eclipse.jetty.io.FillInterest   ] - fillable FillInterest@1c53058a{AC.ReadCB@2d7fe857{HttpConnectionOverHTTP@2d7fe857::SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OPEN,fill=FI,flush=-,to=38/0}{io=1/0,kio=1,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=false)=>HttpChannelOverHTTP@220b6b09(exchange=HttpExchange@3523cb1a req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=CONTENT,failure=null)[HttpParser{s=CONTENT,421 of 1288}]]}}
2020-09-08 21:52:37.777 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=CONTENT DirectByteBuffer@1f59ff53[p=0,l=0,c=16384,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2020-09-08 21:52:37.777 [DEBUG] [rg.eclipse.jetty.client.HttpReceiver] - Parsed false, remaining 0 HttpParser{s=CONTENT,421 of 1288}
2020-09-08 21:52:37.777 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 867 DirectByteBuffer@1f59ff53[p=0,l=867,c=16384,r=867]={<<<:false,"is_valid"...ptime":1039479}>>>49,"uptime":43569...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2020-09-08 21:52:37.777 [DEBUG] [rg.eclipse.jetty.client.HttpReceiver] - Read 867 bytes DirectByteBuffer@1f59ff53[p=0,l=867,c=16384,r=867]={<<<:false,"is_valid"...ptime":1039479}>>>49,"uptime":43569...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} from SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OPEN,fill=-,flush=-,to=0/0}{io=1/0,kio=1,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=false)=>HttpChannelOverHTTP@220b6b09(exchange=HttpExchange@3523cb1a req=TERMINATED/null@null res=PENDING/null@null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=CONTENT,failure=null)[HttpParser{s=CONTENT,421 of 1288}]]
2020-09-08 21:52:37.778 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=CONTENT DirectByteBuffer@1f59ff53[p=0,l=867,c=16384,r=867]={<<<:false,"is_valid"...ptime":1039479}>>>49,"uptime":43569...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2020-09-08 21:52:37.778 [DEBUG] [rg.eclipse.jetty.client.HttpReceiver] - Response content HttpResponse[HTTP/1.1 200 OK]@5d476716
DirectByteBufferR@6196631[p=0,l=867,c=16384,r=867]={<<<:false,"is_valid"...ptime":1039479}>>>49,"uptime":43569...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2020-09-08 21:52:37.778 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - CONTENT --> END
2020-09-08 21:52:37.778 [DEBUG] [y.util.thread.ReservedThreadExecutor] - ReservedThreadExecutor@4b8cb451{s=1/4,p=0}@3ab51ee9 task=EatWhatYouKill@424edc6/SelectorProducer@712391e/IDLE/p=true/QueuedThreadPool[ESH-httpClient-common]@32833df7{STARTED,10<=10<=40,i=4,r=4,q=0}[ReservedThreadExecutor@4b8cb451{s=1/4,p=0}][pc=0,pic=0,pec=0,epc=483]@2020-09-08T21:52:37.778-05:00
2020-09-08 21:52:37.778 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - reset HttpParser{s=END,1288 of 1288}
2020-09-08 21:52:37.779 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - END --> START
2020-09-08 21:52:37.779 [DEBUG] [rg.eclipse.jetty.client.HttpReceiver] - Response success HttpResponse[HTTP/1.1 200 OK]@5d476716
2020-09-08 21:52:37.779 [DEBUG] [.util.thread.strategy.EatWhatYouKill] - EatWhatYouKill@424edc6/SelectorProducer@712391e/IDLE/p=true/QueuedThreadPool[ESH-httpClient-common]@32833df7{STARTED,10<=10<=40,i=4,r=4,q=0}[ReservedThreadExecutor@4b8cb451{s=1/4,p=0}][pc=0,pic=0,pec=0,epc=483]@2020-09-08T21:52:37.779-05:00 tryProduce true
2020-09-08 21:52:37.779 [DEBUG] [rg.eclipse.jetty.client.HttpExchange] - Terminated response for HttpExchange@3523cb1a req=TERMINATED/null@null res=TERMINATED/null@null, result: Result[HttpRequest[GET /status/relay/0 HTTP/1.1]@3f4eec5b > HttpResponse[HTTP/1.1 200 OK]@5d476716] null
2020-09-08 21:52:37.779 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - updateable 0
2020-09-08 21:52:37.779 [DEBUG] [rg.eclipse.jetty.client.HttpReceiver] - Response complete HttpResponse[HTTP/1.1 200 OK]@5d476716
2020-09-08 21:52:37.779 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - updates 0
2020-09-08 21:52:37.779 [DEBUG] [org.eclipse.jetty.client.HttpChannel] - HttpExchange@3523cb1a req=TERMINATED/null@null res=TERMINATED/null@null disassociated true from HttpChannelOverHTTP@220b6b09(exchange=null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
2020-09-08 21:52:37.780 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - Key interests updated 1 -> 0 on SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OPEN,fill=-,flush=-,to=2/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=false)=>HttpChannelOverHTTP@220b6b09(exchange=null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
2020-09-08 21:52:37.780 [DEBUG] [org.eclipse.jetty.client.HttpChannel] - Closing, reason: http/1.1 - HttpConnectionOverHTTP@2d7fe857::SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OPEN,fill=-,flush=-,to=2/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=false)=>HttpChannelOverHTTP@220b6b09(exchange=null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
2020-09-08 21:52:37.780 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.EPollSelectorImpl@67fc893d waiting with 1 keys
2020-09-08 21:52:37.780 [DEBUG] [.jetty.client.AbstractConnectionPool] - Connection removed HttpConnectionOverHTTP@2d7fe857::SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OPEN,fill=-,flush=-,to=3/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=true)=>HttpChannelOverHTTP@220b6b09(exchange=null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] - pooled: 0
2020-09-08 21:52:37.781 [DEBUG] [rg.eclipse.jetty.io.AbstractEndPoint] - shutdownOutput SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OPEN,fill=-,flush=-,to=3/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=true)=>HttpChannelOverHTTP@220b6b09(exchange=null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
2020-09-08 21:52:37.781 [DEBUG] [y.client.http.HttpConnectionOverHTTP] - Shutdown HttpConnectionOverHTTP@2d7fe857::SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OSHUT,fill=-,flush=-,to=4/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=true)=>HttpChannelOverHTTP@220b6b09(exchange=null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
2020-09-08 21:52:37.782 [DEBUG] [rg.eclipse.jetty.io.AbstractEndPoint] - close SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OSHUT,fill=-,flush=-,to=4/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=true)=>HttpChannelOverHTTP@220b6b09(exchange=null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
2020-09-08 21:52:37.782 [DEBUG] [rg.eclipse.jetty.io.AbstractEndPoint] - close(null) SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,OSHUT,fill=-,flush=-,to=4/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=true)=>HttpChannelOverHTTP@220b6b09(exchange=null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
2020-09-08 21:52:37.782 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - doClose SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,CLOSED,fill=-,flush=-,to=5/0}{io=0/0,kio=0,kro=1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=true)=>HttpChannelOverHTTP@220b6b09(exchange=null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
2020-09-08 21:52:37.783 [DEBUG] [org.eclipse.jetty.io.FillInterest   ] - onClose FillInterest@1c53058a{null}
2020-09-08 21:52:37.783 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Wakeup ManagedSelector@66673d9a{STARTED} id=1 keys=1 selected=0 updates=0
2020-09-08 21:52:37.783 [DEBUG] [e.jetty.util.thread.QueuedThreadPool] - queue org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@7ffda839 startThread=false
2020-09-08 21:52:37.783 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.EPollSelectorImpl@67fc893d woken with none selected
2020-09-08 21:52:37.783 [DEBUG] [e.jetty.util.thread.QueuedThreadPool] - run org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@7ffda839 in QueuedThreadPool[ESH-httpClient-common]@32833df7{STARTED,10<=10<=40,i=3,r=4,q=0}[ReservedThreadExecutor@4b8cb451{s=1/4,p=0}]
2020-09-08 21:52:37.783 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.EPollSelectorImpl@67fc893d woken up from select, 0/0/0 selected
2020-09-08 21:52:37.783 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.EPollSelectorImpl@67fc893d processing 0 keys, 0 updates
2020-09-08 21:52:37.784 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - updateable 0
2020-09-08 21:52:37.784 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Destroyed SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,CLOSED,fill=-,flush=-,to=6/0}{io=0/0,kio=-1,kro=-1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=true)=>HttpChannelOverHTTP@220b6b09(exchange=null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
2020-09-08 21:52:37.784 [DEBUG] [y.client.http.HttpConnectionOverHTTP] - Closed HttpConnectionOverHTTP@2d7fe857::SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,CLOSED,fill=-,flush=-,to=6/0}{io=0/0,kio=-1,kro=-1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=true)=>HttpChannelOverHTTP@220b6b09(exchange=null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
2020-09-08 21:52:37.784 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - updates 0
2020-09-08 21:52:37.784 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.EPollSelectorImpl@67fc893d waiting with 0 keys
2020-09-08 21:52:37.784 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - onClose HttpConnectionOverHTTP@2d7fe857::SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,CLOSED,fill=-,flush=-,to=6/0}{io=0/0,kio=-1,kro=-1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=true)=>HttpChannelOverHTTP@220b6b09(exchange=null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]]
2020-09-08 21:52:37.784 [DEBUG] [rg.eclipse.jetty.client.HttpReceiver] - Request/Response succeeded: Result[HttpRequest[GET /status/relay/0 HTTP/1.1]@3f4eec5b > HttpResponse[HTTP/1.1 200 OK]@5d476716] null
2020-09-08 21:52:37.784 [DEBUG] [e.jetty.util.thread.QueuedThreadPool] - ran org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@7ffda839 in QueuedThreadPool[ESH-httpClient-common]@32833df7{STARTED,10<=10<=40,i=3,r=4,q=0}[ReservedThreadExecutor@4b8cb451{s=1/4,p=0}]
2020-09-08 21:52:37.785 [DEBUG] [jetty.client.TimeoutCompleteListener] - Cancelled (false) timeout for HttpRequest[GET /status/relay/0 HTTP/1.1]@3f4eec5b on org.eclipse.jetty.client.TimeoutCompleteListener@1bbc7d20
2020-09-08 21:52:37.786 [DEBUG] [rg.eclipse.jetty.client.HttpReceiver] - Parsed false, remaining 0 HttpParser{s=START,0 of -1}
2020-09-08 21:52:37.786 [DEBUG] [rg.eclipse.jetty.client.HttpReceiver] - HttpConnectionOverHTTP@2d7fe857::SocketChannelEndPoint@8bc2321{/10.1.10.116:80<->/10.1.10.50:42056,CLOSED,fill=-,flush=-,to=8/0}{io=0/0,kio=-1,kro=-1}->HttpConnectionOverHTTP@2d7fe857(l:/10.1.10.50:42056 <-> r:/10.1.10.116:80,closed=true)=>HttpChannelOverHTTP@220b6b09(exchange=null)[send=HttpSenderOverHTTP@5e5a804f(req=QUEUED,snd=COMPLETED,failure=null)[HttpGenerator@cb5e683{s=START}],recv=HttpReceiverOverHTTP@65f9791f(rsp=IDLE,failure=null)[HttpParser{s=START,0 of -1}]] closed
2020-09-08 21:52:37.786 [DEBUG] [y.util.thread.ReservedThreadExecutor] - ReservedThreadExecutor@4b8cb451{s=2/4,p=0}@3a961e7b waiting
2020-09-08 21:52:38.039 [DEBUG] [.jsonpath.internal.path.CompiledPath] - Evaluating path: $['SI7021']['Temperature']
2020-09-08 21:52:38.040 [DEBUG] [.jsonpath.internal.path.CompiledPath] - Evaluating path: $['SI7021']['Humidity']
2020-09-08 21:52:38.350 [DEBUG] [work.deduplication.SweepDeduplicator] - Sweep run took 0ms
2020-09-08 21:52:40.844 [DEBUG] [se.californium.elements.UDPConnector] - UDPConnector (0.0.0.0/0.0.0.0:5683) received 100 bytes from /10.1.10.112:5683
2020-09-08 21:52:40.845 [DEBUG] [.core.network.stack.ReliabilityLayer] - Exchange[L591] send request, failed transmissions: 0
2020-09-08 21:52:40.846 [DEBUG] [.core.network.stack.ReliabilityLayer] - Exchange[L591] prepare retransmission for CON-GET    MID=   -1, Token=, OptionSet={"Uri-Path":["cit","d"]}, no payload
2020-09-08 21:52:40.846 [DEBUG] [network.InMemoryMessageExchangeStore] - coap Exchange[L591] added with generated mid KeyMID[10.1.10.112:5683-20584], CON-GET    MID=20584, Token=, OptionSet={"Uri-Path":["cit","d"]}, no payload
2020-09-08 21:52:40.846 [DEBUG] [network.InMemoryMessageExchangeStore] - coap Exchange[L591] added with token KeyToken[10.1.10.112:5683-], CON-GET    MID=20584, Token=, OptionSet={"Uri-Path":["cit","d"]}, no payload
2020-09-08 21:52:40.847 [DEBUG] [.californium.core.network.UdpMatcher] - tracking open request [MID: 20584, Token: Token=]
2020-09-08 21:52:40.847 [DEBUG] [se.californium.elements.UDPConnector] - UDPConnector (Thread[UDP-Sender-0.0.0.0/0.0.0.0:0[0],5,Californium/Elements]) sent 10 bytes to /10.1.10.112:5683
2020-09-08 21:52:40.871 [DEBUG] [se.californium.elements.UDPConnector] - UDPConnector (0.0.0.0/0.0.0.0:49223) received 891 bytes from /10.1.10.112:5683
2020-09-08 21:52:40.871 [DEBUG] [se.californium.core.network.Exchange] - Exchange[L591, complete]!
2020-09-08 21:52:40.872 [DEBUG] [network.InMemoryMessageExchangeStore] - coap removing Exchange[L591, complete] for token KeyToken[10.1.10.112:5683-]
2020-09-08 21:52:40.872 [DEBUG] [network.InMemoryMessageExchangeStore] - coap removing Exchange[L591, complete] for MID KeyMID[10.1.10.112:5683-20584]
2020-09-08 21:52:40.872 [DEBUG] [se.californium.core.network.Exchange] - local Exchange[L591, complete] completed CON-GET    MID=20584, Token=, OptionSet={"Uri-Path":["cit","d"]}, no payload!

This isn’t my only problem, I’ve lost all of my notifications but I’m sure that’s another issue…

hmerk · September 9, 2020, 5:26am

You need the latest Dev build or 2.5.9 SNAPSHOT version of the Shelly binding for full 1.8x firmware support.

StefanH · September 21, 2020, 3:20pm

latest 2.5.9 fixes the coap issues. just upgraded OH and then tested with one shelly upgrading to 1.8.3
No problems at all. Then I upgraded all the other devices via mqtt “shellies/command” update_fw

Chentadot · September 28, 2020, 11:48am

Hi,
I’m having the same problem since I updated my shelly devices.
I prefer not to use a SNAPSHOT or DEV versions since i’m not sure I’d be able to deal with any instabilities. So I’d prefer waiting for a stable release.
Few questions about it:

Are the bindings update themselves when a newer version is available? Don’t remember ever updating a binding…
What is the ETA of a stable version if exists?
In the meantime I would like to downgrade my devices but couldn’t find the option for Shelly1 and Shelly2.5 in the link generator @to.bi shared. Did anyone downgraded these devices and can send me to the right direction?

Thanks a lot!

hmerk · September 28, 2020, 12:16pm

openHAB 2.5.9 was released September 20th and includes full support of Shelly firmware 1.8.

Chentadot · September 29, 2020, 10:32am

Hi, thanks for the reply, and sorry for the “newby” question but where can i find instructions for upgrading to the new stable release?
I’m running 2.5.1 and can’t seem to find the best practice to do so…
Thank you very good.

hmerk · September 29, 2020, 10:53am

It depends on how you installed openHAB and what server/OS you are using.
You should definitely read the installation docummentation.

Chentadot · September 29, 2020, 7:09pm

I’m running openhabian on a Raspberry Pi.
Found it inside openhabian-config.

I hope it works because I searched every place I found about openhabian and couldn’t find anywhere that explicitly says this is the way to upgrade.
Maybe it’s something that is worth adding to the documentaion.
Of course if I missed it than disregard everything I said

Thank you very much

Oliver2 · October 2, 2020, 4:26am

execute the following command:
sudo apt update && sudo apt upgrade

ulrichpa · October 17, 2020, 7:58am

Thanks, i can confirm the upgrade to 2.5.9 solves the problem with the warnings