Skipping 554 KB.. Full Log

16:48:03.672 [Thread-22] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=85 remaining=0 WriteFlusher@49e796ed{WRITING}->null
16:48:03.672 [Thread-22] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@49e796ed{IDLE}->null:WRITING-->IDLE
16:48:03.672 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - selected 1 sun.nio.ch.SelectionKeyImpl@60e4ddad SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=187/3600000}{io=1/1,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=24,f=null]] 
16:48:03.672 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flushing Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=85,terminated=null]
16:48:03.672 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=85,terminated=null] processing 0 entries: []
16:48:03.672 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=85,terminated=null] auto flushing
16:48:03.672 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=85,terminated=null] flushing 0 frames: []
16:48:03.672 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.extensions.ExtensionStack - Entering IDLE
16:48:03.672 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ChannelEndPoint - onSelected 1->0 r=true w=false for SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=187/3600000}{io=1/0,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=24,f=null]]
16:48:03.672 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ChannelEndPoint - task CEP:SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=187/3600000}{io=1/0,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=24,f=null]]:runFillable:BLOCKING
16:48:03.673 [qtp1292040526-28] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@f78a47e{s=2/12,p=0} tryExecute EatWhatYouKill@7bbc8656/SelectorProducer@6933b6c6/PRODUCING/p=false/QueuedThreadPool[qtp1292040526]@4d02f94e{STARTED,8<=10<=200,i=1,r=-1,q=0}[ReservedThreadExecutor@f78a47e{s=2/12,p=0}][pc=0,pic=0,pec=0,epc=2]@2021-11-24T16:48:03.672Z
16:48:03.673 [qtp1292040526-28] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@f78a47e{s=1/12,p=0}@5e2b4586 offer EatWhatYouKill@7bbc8656/SelectorProducer@6933b6c6/PRODUCING/p=false/QueuedThreadPool[qtp1292040526]@4d02f94e{STARTED,8<=10<=200,i=1,r=-1,q=0}[ReservedThreadExecutor@f78a47e{s=1/12,p=0}][pc=0,pic=0,pec=0,epc=2]@2021-11-24T16:48:03.673Z
16:48:03.674 [qtp1292040526-35] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@f78a47e{s=1/12,p=0}@5e2b4586 task=EatWhatYouKill@7bbc8656/SelectorProducer@6933b6c6/IDLE/p=true/QueuedThreadPool[qtp1292040526]@4d02f94e{STARTED,8<=10<=200,i=1,r=-1,q=0}[ReservedThreadExecutor@f78a47e{s=1/12,p=0}][pc=0,pic=0,pec=0,epc=2]@2021-11-24T16:48:03.674Z
16:48:03.674 [qtp1292040526-28] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@7bbc8656/SelectorProducer@6933b6c6/IDLE/p=true/QueuedThreadPool[qtp1292040526]@4d02f94e{STARTED,8<=10<=200,i=1,r=-1,q=0}[ReservedThreadExecutor@f78a47e{s=1/12,p=0}][pc=0,pic=0,pec=0,epc=2]@2021-11-24T16:48:03.673Z m=EXECUTE_PRODUCE_CONSUME t=CEP:SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=189/3600000}{io=1/0,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=24,f=null]]:runFillable:BLOCKING/BLOCKING
16:48:03.674 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.FillInterest - fillable FillInterest@fb7340b{AC.ReadCB@213c163f{WebSocketServerConnection@213c163f::SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=189/3600000}{io=1/0,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=24,f=null]]}}
16:48:03.674 [qtp1292040526-35] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@7bbc8656/SelectorProducer@6933b6c6/IDLE/p=true/QueuedThreadPool[qtp1292040526]@4d02f94e{STARTED,8<=10<=200,i=1,r=-1,q=0}[ReservedThreadExecutor@f78a47e{s=1/12,p=0}][pc=0,pic=0,pec=0,epc=3]@2021-11-24T16:48:03.674Z tryProduce true
16:48:03.674 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - SERVER onFillable()
16:48:03.674 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 0
16:48:03.674 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:03.674 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - SERVER onFillable(ByteBuffer): java.nio.DirectByteBuffer[pos=0 lim=0 cap=4096]
16:48:03.674 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - ReadState@67beabc6[READING] getAction(DirectByteBuffer@6c24f588[p=0,l=0,c=4096,r=0]={<<<>>>\x81\x98F\xB5{\xB4{"u...\x00\x00\x00\x00\x00\x00\x00})
16:48:03.674 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - ReadState Action: FILL
16:48:03.674 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 1 -> 0 on SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=-,flush=-,to=190/3600000}{io=0/0,kio=0,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=24,f=null]]
16:48:03.674 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6e232e19 waiting with 1 keys
16:48:03.674 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 85 DirectByteBuffer@6c24f588[p=0,l=85,c=4096,r=85]={<<<\x81\xCf\xC2\xB9B\xCf\xB9\x9b7\xBd\xAb\x9bx\xEd\xEd\xDa#\xA3\xAe\xDb#\xAc\xA9\xEd'...\xB7\xDc1\xBb\x9e\x9bx\x93\xE0\xF4;\x8c\xAd\xD76\xAa\xAc\xCd\x1e\xEd\xBf\x9b?>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00}
16:48:03.675 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - Filled 85 bytes - DirectByteBuffer@6c24f588[p=0,l=85,c=4096,r=85]={<<<\x81\xCf\xC2\xB9B\xCf\xB9\x9b7\xBd\xAb\x9bx\xEd\xEd\xDa#\xA3\xAe\xDb#\xAc\xA9\xEd'...\xB7\xDc1\xBb\x9e\x9bx\x93\xE0\xF4;\x8c\xAd\xD76\xAa\xAc\xCd\x1e\xEd\xBf\x9b?>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00}
16:48:03.675 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - ReadState@67beabc6[READING] getAction(DirectByteBuffer@6c24f588[p=0,l=85,c=4096,r=85]={<<<\x81\xCf\xC2\xB9B\xCf\xB9\x9b7\xBd\xAb\x9bx\xEd\xEd\xDa#\xA3\xAe\xDb#\xAc\xA9\xEd'...\xB7\xDc1\xBb\x9e\x9bx\x93\xE0\xF4;\x8c\xAd\xD76\xAa\xAc\xCd\x1e\xEd\xBf\x9b?>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00})
16:48:03.675 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - ReadState Action: PARSE
16:48:03.675 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.Parser - SERVER Parsing 85 bytes
16:48:03.675 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.Parser - SERVER OpCode TEXT, fin=true rsv=...
16:48:03.675 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.Parser - SERVER Payload Length: 79 - Parser@7d8da195[ExtensionStack,s=PAYLOAD_LEN,c=0,len=79,f=TEXT[len=0,fin=true,rsv=...,masked=true]]
16:48:03.675 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.Parser - SERVER Window: DirectByteBuffer@2de1e8ca[p=0,l=79,c=79,r=79]={<<<\xB9\x9b7\xBd\xAb\x9bx\xEd\xEd\xDa#\xA3\xAe\xDb#\xAc\xA9\xEd'\xBc\xB6\x88m\xB8\xAb...\xB7\xDc1\xBb\x9e\x9bx\x93\xE0\xF4;\x8c\xAd\xD76\xAa\xAc\xCd\x1e\xEd\xBf\x9b?>>>}
16:48:03.675 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.Parser - SERVER Parsed Frame: TEXT[len=79,fin=true,rsv=...,masked=true]
16:48:03.675 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.Parser - SERVER Notify ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.websocket.common.WebSocketSession,outgoing=org.eclipse.jetty.websocket.server.WebSocketServerConnection]
16:48:03.675 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - incomingFrame(TEXT[len=79,fin=true,rsv=...,masked=true])
16:48:03.675 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - onFrame(TEXT[len=79,fin=true,rsv=...,masked=true]) - events.onFrame=null
16:48:03.675 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - onTextFrame(DirectByteBuffer@2de1e8ca[p=0,l=79,c=79,r=79]={<<<{"uri":"/callbackTest1/wi...uest\\":\\"MyContent\\"}"}>>>}, true) - events.onText=OptionalSessionCallableMethod[pojo=org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet,method=public void org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet.onMessage(java.lang.String),wantsSession=false,streaming=false], activeMessage=null
16:48:03.676 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - onTextMessage([79] "{"uri":"/callbackTes...eters":"{\"request\":\"MyContent\"}"}") - events.onText=OptionalSessionCallableMethod[pojo=org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet,method=public void org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet.onMessage(java.lang.String),wantsSession=false,streaming=false]
16:48:03.676 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.WebSocketSession - [SERVER] WebSocketSession.getRemote()
16:48:03.676 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.WebSocketRemoteEndpoint - sendString with HeapByteBuffer@32024a18[p=0,l=37,c=37,r=37]={<<<[WEBSOCKET]/callbackTest1/withContent>>>}
16:48:03.676 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.extensions.ExtensionStack - Queuing TEXT[len=37,fin=true,rsv=...,masked=false]
16:48:03.676 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.extensions.ExtensionStack - Processing TEXT[len=37,fin=true,rsv=...,masked=false]
16:48:03.676 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - outgoingFrame(TEXT[len=37,fin=true,rsv=...,masked=false], Flusher@219b9586[PROCESSING])
16:48:03.676 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Enqueued FrameEntry[TEXT[len=37,fin=true,rsv=...,masked=false],Flusher@219b9586[PROCESSING],AUTO,null] to Flusher@4163fc48[IDLE][queueSize=1,aggregateSize=-1,terminated=null]
16:48:03.676 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flushing Flusher@4163fc48[PROCESSING][queueSize=1,aggregateSize=-1,terminated=null]
16:48:03.676 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=-1,terminated=null] processing 1 entries: [FrameEntry[TEXT[len=37,fin=true,rsv=...,masked=false],Flusher@219b9586[PROCESSING],AUTO,null]]
16:48:03.676 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] acquired aggregate buffer java.nio.DirectByteBuffer[pos=0 lim=0 cap=32768]
16:48:03.677 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] aggregated 1 frames: [FrameEntry[TEXT[len=0,fin=true,rsv=...,masked=false],Flusher@219b9586[PROCESSING],AUTO,null]]
16:48:03.677 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flushing Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=0,terminated=null]
16:48:03.677 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] processing 0 entries: []
16:48:03.677 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] auto flushing
16:48:03.677 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] flushing aggregate java.nio.DirectByteBuffer[pos=0 lim=39 cap=32768]
16:48:03.677 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] flushing 0 frames: []
16:48:03.677 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@37678170{IDLE}->null [DirectByteBuffer@7a35579[p=0,l=39,c=32768,r=39]={<<<\x81%[WEBSOCKET]/callbackTest1/withContent>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00}]
16:48:03.677 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@37678170{WRITING}->null:IDLE-->WRITING
16:48:03.677 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec woken up from select, 1/1/1 selected
16:48:03.677 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec processing 1 keys, 0 updates
16:48:03.677 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ChannelEndPoint - flushed 39 SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=-,flush=W,to=2/3600000}{io=0/0,kio=0,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=39,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=79,f=TEXT[len=0,fin=true,rsv=...,masked=true]]]
16:48:03.677 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=39 remaining=0 WriteFlusher@37678170{WRITING}->null
16:48:03.677 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@37678170{IDLE}->null:WRITING-->IDLE
16:48:03.677 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - selected 1 sun.nio.ch.SelectionKeyImpl@555521ee SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=5/3600000}{io=1/1,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=25,f=null]] 
16:48:03.677 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flushing Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=39,terminated=null]
16:48:03.678 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=39,terminated=null] processing 0 entries: []
16:48:03.678 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=39,terminated=null] auto flushing
16:48:03.678 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ChannelEndPoint - onSelected 1->0 r=true w=false for SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=5/3600000}{io=1/0,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=25,f=null]]
16:48:03.678 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=39,terminated=null] flushing 0 frames: []
16:48:03.678 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.extensions.ExtensionStack - Entering IDLE
16:48:03.678 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - ReadState@67beabc6[READING] getAction(DirectByteBuffer@6c24f588[p=85,l=85,c=4096,r=0]={\x81\xCf\xC2\xB9B\xCf{"u...nt\\"}"}<<<>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00})
16:48:03.678 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ChannelEndPoint - task CEP:SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=6/3600000}{io=1/0,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=25,f=null]]:runFillable:BLOCKING
16:48:03.678 [qtp1292040526-28] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - ReadState Action: FILL
16:48:03.678 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 DirectByteBuffer@6c24f588[p=0,l=0,c=4096,r=0]={<<<>>>\x81\xCf\xC2\xB9B\xCf{"u...\x00\x00\x00\x00\x00\x00\x00}
16:48:03.678 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=1/12,p=0} tryExecute EatWhatYouKill@64040287/SelectorProducer@110844f6/PRODUCING/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=8<=200,i=1,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=1/12,p=0}][pc=0,pic=0,pec=1,epc=1]@2021-11-24T16:48:03.678Z
16:48:03.678 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested WebSocketServerConnection@213c163f::SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=-,flush=-,to=0/3600000}{io=0/0,kio=0,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=79,f=null]]
16:48:03.678 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@fb7340b{AC.ReadCB@213c163f{WebSocketServerConnection@213c163f::SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=0/3600000}{io=0/0,kio=0,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=79,f=null]]}}
16:48:03.678 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=0/12,p=0}@59903810 offer EatWhatYouKill@64040287/SelectorProducer@110844f6/PRODUCING/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=8<=200,i=1,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=0/12,p=0}][pc=0,pic=0,pec=1,epc=1]@2021-11-24T16:48:03.678Z
16:48:03.678 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=0/12,p=1} startReservedThread p=1
16:48:03.679 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=0/3600000}{io=0/1,kio=0,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=79,f=null]]
16:48:03.679 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue ReservedThreadExecutor@5339bbad{s=0/12,p=1}@682b8b58 startThread=0
16:48:03.679 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.ChannelEndPoint$$Lambda$483/2027922092@220da7 on ManagedSelector@7d3e8655{STARTED} id=2 keys=1 selected=0 updates=0
16:48:03.679 [WebSocketClient@423711439-47] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run ReservedThreadExecutor@5339bbad{s=0/12,p=1}@682b8b58 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=8<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=0/12,p=1}]
16:48:03.679 [WebSocketClient@423711439-47] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=1/12,p=1}@682b8b58 started
16:48:03.679 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@7d3e8655{STARTED} id=2 keys=1 selected=0 updates=1
16:48:03.679 [WebSocketClient@423711439-47] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=1/12,p=0}@682b8b58 waiting
16:48:03.679 [qtp1292040526-28] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@f78a47e{s=2/12,p=0}@18839795 waiting
16:48:03.679 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6e232e19 woken with none selected
16:48:03.679 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6e232e19 woken up from select, 0/0/1 selected
16:48:03.679 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6e232e19 processing 0 keys, 1 updates
16:48:03.679 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
16:48:03.679 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ChannelEndPoint$$Lambda$483/2027922092@220da7
16:48:03.679 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@64040287/SelectorProducer@110844f6/IDLE/p=true/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=8<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=1/12,p=0}][pc=0,pic=0,pec=1,epc=1]@2021-11-24T16:48:03.679Z m=EXECUTE_PRODUCE_CONSUME t=CEP:SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=7/3600000}{io=1/0,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=25,f=null]]:runFillable:BLOCKING/BLOCKING
16:48:03.679 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=0/3600000}{io=1/1,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=79,f=null]]
16:48:03.679 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:03.679 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=0/12,p=1}@59903810 task=EatWhatYouKill@64040287/SelectorProducer@110844f6/IDLE/p=true/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=8<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=1/12,p=0}][pc=0,pic=0,pec=1,epc=1]@2021-11-24T16:48:03.679Z
16:48:03.679 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6e232e19 waiting with 1 keys
16:48:03.679 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.FillInterest - fillable FillInterest@42d8c2aa{AC.ReadCB@72a82a7c{WebSocketClientConnection@72a82a7c::SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=7/3600000}{io=1/0,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=25,f=null]]}}
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - CLIENT onFillable()
16:48:03.680 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@64040287/SelectorProducer@110844f6/IDLE/p=true/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=8<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=1/12,p=0}][pc=0,pic=0,pec=1,epc=2]@2021-11-24T16:48:03.68Z tryProduce true
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - CLIENT onFillable(ByteBuffer): java.nio.DirectByteBuffer[pos=0 lim=0 cap=4096]
16:48:03.680 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 0
16:48:03.680 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - ReadState@212475e8[READING] getAction(DirectByteBuffer@2536389d[p=0,l=0,c=4096,r=0]={<<<>>>\x81\x19[WEBSOC...\x00\x00\x00\x00\x00\x00\x00})
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - ReadState Action: FILL
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 39 DirectByteBuffer@2536389d[p=0,l=39,c=4096,r=39]={<<<\x81%[WEBSOCKET]/callbackTest1/withContent>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00}
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - Filled 39 bytes - DirectByteBuffer@2536389d[p=0,l=39,c=4096,r=39]={<<<\x81%[WEBSOCKET]/callbackTest1/withContent>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00}
16:48:03.680 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 1 -> 0 on SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=-,flush=-,to=8/3600000}{io=0/0,kio=0,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=25,f=null]]
16:48:03.680 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec waiting with 1 keys
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - ReadState@212475e8[READING] getAction(DirectByteBuffer@2536389d[p=0,l=39,c=4096,r=39]={<<<\x81%[WEBSOCKET]/callbackTest1/withContent>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00})
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - ReadState Action: PARSE
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT Parsing 39 bytes
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT OpCode TEXT, fin=true rsv=...
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT Payload Length: 37 - Parser@75f81da0[ExtensionStack,s=PAYLOAD_LEN,c=0,len=37,f=TEXT[len=0,fin=true,rsv=...,masked=false]]
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT Window: DirectByteBuffer@172b4e6b[p=0,l=37,c=37,r=37]={<<<[WEBSOCKET]/callbackTest1/withContent>>>}
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT Parsed Frame: TEXT[len=37,fin=true,rsv=...,masked=false]
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT Notify ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.websocket.common.WebSocketSession,outgoing=org.eclipse.jetty.websocket.client.io.WebSocketClientConnection]
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - incomingFrame(TEXT[len=37,fin=true,rsv=...,masked=false])
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - onFrame(TEXT[len=37,fin=true,rsv=...,masked=false]) - events.onFrame=null
16:48:03.680 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - onTextFrame(DirectByteBuffer@172b4e6b[p=0,l=37,c=37,r=37]={<<<[WEBSOCKET]/callbackTest1/withContent>>>}, true) - events.onText=OptionalSessionCallableMethod[pojo=org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient,method=public void org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient.onMessage(java.lang.String),wantsSession=false,streaming=false], activeMessage=null
16:48:03.681 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - onTextMessage([37] "[WEBSOCKET]/callbackTest1/withContent") - events.onText=OptionalSessionCallableMethod[pojo=org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient,method=public void org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient.onMessage(java.lang.String),wantsSession=false,streaming=false]
16:48:03.681 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - ReadState@212475e8[READING] getAction(DirectByteBuffer@2536389d[p=39,l=39,c=4096,r=0]={\x81%[WEBSOC...Content<<<>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00})
16:48:03.681 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - ReadState Action: FILL
16:48:03.681 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 DirectByteBuffer@2536389d[p=0,l=0,c=4096,r=0]={<<<>>>\x81%[WEBSOC...\x00\x00\x00\x00\x00\x00\x00}
16:48:03.681 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested WebSocketClientConnection@72a82a7c::SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=-,flush=-,to=1/3600000}{io=0/0,kio=0,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=37,f=null]]
16:48:03.681 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@42d8c2aa{AC.ReadCB@72a82a7c{WebSocketClientConnection@72a82a7c::SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=0/3600000}{io=0/0,kio=0,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=37,f=null]]}}
16:48:03.681 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=0/3600000}{io=0/1,kio=0,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=37,f=null]]
16:48:03.681 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.ChannelEndPoint$$Lambda$483/2027922092@272ad126 on ManagedSelector@6f89f665{STARTED} id=1 keys=1 selected=0 updates=0
16:48:03.682 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@6f89f665{STARTED} id=1 keys=1 selected=0 updates=1
16:48:03.682 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec woken with none selected
16:48:03.682 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=2/12,p=0}@14682bd5 waiting
16:48:03.682 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec woken up from select, 0/0/1 selected
16:48:03.682 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec processing 0 keys, 1 updates
16:48:03.682 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
16:48:03.682 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ChannelEndPoint$$Lambda$483/2027922092@272ad126
16:48:03.682 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=0/3600000}{io=1/1,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=37,f=null]]
16:48:03.682 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:03.682 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec waiting with 1 keys
[WEBSOCKET]/callbackTest1/withContent
16:48:03.872 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.WebSocketSession - [CLIENT] WebSocketSession.getRemote()
16:48:03.872 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.WebSocketRemoteEndpoint - sendStringByFuture with HeapByteBuffer@550bac49[p=0,l=2,c=2,r=2]={<<<{}>>>}
16:48:03.872 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.extensions.ExtensionStack - Queuing TEXT[len=2,fin=true,rsv=...,masked=false]
16:48:03.872 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.extensions.ExtensionStack - Processing TEXT[len=2,fin=true,rsv=...,masked=false]
16:48:03.873 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - outgoingFrame(TEXT[len=2,fin=true,rsv=...,masked=true], Flusher@4375070d[PROCESSING])
16:48:03.873 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Enqueued FrameEntry[TEXT[len=2,fin=true,rsv=...,masked=true],Flusher@4375070d[PROCESSING],AUTO,null] to Flusher@1f4e1f5e[IDLE][queueSize=1,aggregateSize=-1,terminated=null]
16:48:03.873 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flushing Flusher@1f4e1f5e[PROCESSING][queueSize=1,aggregateSize=-1,terminated=null]
16:48:03.873 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=-1,terminated=null] processing 1 entries: [FrameEntry[TEXT[len=2,fin=true,rsv=...,masked=true],Flusher@4375070d[PROCESSING],AUTO,null]]
16:48:03.873 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] acquired aggregate buffer java.nio.DirectByteBuffer[pos=0 lim=0 cap=32768]
16:48:03.873 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] aggregated 1 frames: [FrameEntry[TEXT[len=0,fin=true,rsv=...,masked=true],Flusher@4375070d[PROCESSING],AUTO,null]]
16:48:03.873 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FutureWriteCallback - .writeSuccess
16:48:03.873 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flushing Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=0,terminated=null]
16:48:03.873 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] processing 0 entries: []
16:48:03.873 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] auto flushing
16:48:03.873 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] flushing aggregate java.nio.DirectByteBuffer[pos=0 lim=8 cap=32768]
16:48:03.873 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] flushing 0 frames: []
16:48:03.873 [Thread-22] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@49e796ed{IDLE}->null [DirectByteBuffer@790b3f7e[p=0,l=8,c=32768,r=8]={<<<\x81\x82\x08@\xA2\x17s=>>>7\xBd\xAb\x9bx\xEd\xEd\xDa#...\x00\x00\x00\x00\x00\x00\x00}]
16:48:03.874 [Thread-22] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@49e796ed{WRITING}->null:IDLE-->WRITING
16:48:03.874 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6e232e19 woken up from select, 1/1/1 selected
16:48:03.874 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6e232e19 processing 1 keys, 0 updates
16:48:03.874 [Thread-22] DEBUG org.eclipse.jetty.io.ChannelEndPoint - flushed 8 SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=W,to=192/3600000}{io=1/1,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=8,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=37,f=null]]
16:48:03.874 [Thread-22] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=8 remaining=0 WriteFlusher@49e796ed{WRITING}->null
16:48:03.874 [Thread-22] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@49e796ed{IDLE}->null:WRITING-->IDLE
16:48:03.874 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flushing Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=8,terminated=null]
16:48:03.874 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ManagedSelector - selected 1 sun.nio.ch.SelectionKeyImpl@60e4ddad SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=195/3600000}{io=1/1,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=79,f=null]] 
16:48:03.874 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=8,terminated=null] processing 0 entries: []
16:48:03.874 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=8,terminated=null] auto flushing
16:48:03.874 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=8,terminated=null] flushing 0 frames: []
16:48:03.874 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ChannelEndPoint - onSelected 1->0 r=true w=false for SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=195/3600000}{io=1/0,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=79,f=null]]
16:48:03.874 [Thread-22] DEBUG org.eclipse.jetty.websocket.common.extensions.ExtensionStack - Entering IDLE
16:48:03.874 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ChannelEndPoint - task CEP:SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=196/3600000}{io=1/0,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=79,f=null]]:runFillable:BLOCKING
16:48:03.875 [qtp1292040526-35] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@f78a47e{s=2/12,p=0} tryExecute EatWhatYouKill@7bbc8656/SelectorProducer@6933b6c6/PRODUCING/p=false/QueuedThreadPool[qtp1292040526]@4d02f94e{STARTED,8<=10<=200,i=1,r=-1,q=0}[ReservedThreadExecutor@f78a47e{s=2/12,p=0}][pc=0,pic=0,pec=0,epc=3]@2021-11-24T16:48:03.875Z
16:48:03.875 [qtp1292040526-35] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@f78a47e{s=1/12,p=0}@18839795 offer EatWhatYouKill@7bbc8656/SelectorProducer@6933b6c6/PRODUCING/p=false/QueuedThreadPool[qtp1292040526]@4d02f94e{STARTED,8<=10<=200,i=1,r=-1,q=0}[ReservedThreadExecutor@f78a47e{s=1/12,p=0}][pc=0,pic=0,pec=0,epc=3]@2021-11-24T16:48:03.875Z
16:48:03.875 [qtp1292040526-35] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@7bbc8656/SelectorProducer@6933b6c6/IDLE/p=true/QueuedThreadPool[qtp1292040526]@4d02f94e{STARTED,8<=10<=200,i=1,r=-1,q=0}[ReservedThreadExecutor@f78a47e{s=1/12,p=0}][pc=0,pic=0,pec=0,epc=3]@2021-11-24T16:48:03.875Z m=EXECUTE_PRODUCE_CONSUME t=CEP:SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=197/3600000}{io=1/0,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=79,f=null]]:runFillable:BLOCKING/BLOCKING
16:48:03.875 [qtp1292040526-28] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@f78a47e{s=1/12,p=0}@18839795 task=EatWhatYouKill@7bbc8656/SelectorProducer@6933b6c6/IDLE/p=true/QueuedThreadPool[qtp1292040526]@4d02f94e{STARTED,8<=10<=200,i=1,r=-1,q=0}[ReservedThreadExecutor@f78a47e{s=1/12,p=0}][pc=0,pic=0,pec=0,epc=3]@2021-11-24T16:48:03.875Z
16:48:03.876 [qtp1292040526-28] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@7bbc8656/SelectorProducer@6933b6c6/IDLE/p=true/QueuedThreadPool[qtp1292040526]@4d02f94e{STARTED,8<=10<=200,i=1,r=-1,q=0}[ReservedThreadExecutor@f78a47e{s=1/12,p=0}][pc=0,pic=0,pec=0,epc=4]@2021-11-24T16:48:03.876Z tryProduce true
16:48:03.876 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.FillInterest - fillable FillInterest@fb7340b{AC.ReadCB@213c163f{WebSocketServerConnection@213c163f::SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=197/3600000}{io=1/0,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=79,f=null]]}}
16:48:03.876 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 0
16:48:03.876 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - SERVER onFillable()
16:48:03.876 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:03.876 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - SERVER onFillable(ByteBuffer): java.nio.DirectByteBuffer[pos=0 lim=0 cap=4096]
16:48:03.876 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - ReadState@67beabc6[READING] getAction(DirectByteBuffer@6c24f588[p=0,l=0,c=4096,r=0]={<<<>>>\x81\xCf\xC2\xB9B\xCf{"u...\x00\x00\x00\x00\x00\x00\x00})
16:48:03.876 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - ReadState Action: FILL
16:48:03.876 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 1 -> 0 on SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=-,flush=-,to=197/3600000}{io=0/0,kio=0,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=79,f=null]]
16:48:03.876 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 8 DirectByteBuffer@6c24f588[p=0,l=8,c=4096,r=8]={<<<\x81\x82\x08@\xA2\x17s=>>>uri":"/ca...\x00\x00\x00\x00\x00\x00\x00}
16:48:03.876 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6e232e19 waiting with 1 keys
16:48:03.876 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - Filled 8 bytes - DirectByteBuffer@6c24f588[p=0,l=8,c=4096,r=8]={<<<\x81\x82\x08@\xA2\x17s=>>>uri":"/ca...\x00\x00\x00\x00\x00\x00\x00}
16:48:03.876 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - ReadState@67beabc6[READING] getAction(DirectByteBuffer@6c24f588[p=0,l=8,c=4096,r=8]={<<<\x81\x82\x08@\xA2\x17s=>>>uri":"/ca...\x00\x00\x00\x00\x00\x00\x00})
16:48:03.876 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - ReadState Action: PARSE
16:48:03.876 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.Parser - SERVER Parsing 8 bytes
16:48:03.876 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.Parser - SERVER OpCode TEXT, fin=true rsv=...
16:48:03.876 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.Parser - SERVER Payload Length: 2 - Parser@7d8da195[ExtensionStack,s=PAYLOAD_LEN,c=0,len=2,f=TEXT[len=0,fin=true,rsv=...,masked=true]]
16:48:03.877 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.Parser - SERVER Window: DirectByteBuffer@55248fca[p=0,l=2,c=2,r=2]={<<<s=>>>}
16:48:03.877 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.Parser - SERVER Parsed Frame: TEXT[len=2,fin=true,rsv=...,masked=true]
16:48:03.877 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.Parser - SERVER Notify ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.websocket.common.WebSocketSession,outgoing=org.eclipse.jetty.websocket.server.WebSocketServerConnection]
16:48:03.877 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - incomingFrame(TEXT[len=2,fin=true,rsv=...,masked=true])
16:48:03.877 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - onFrame(TEXT[len=2,fin=true,rsv=...,masked=true]) - events.onFrame=null
16:48:03.877 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - onTextFrame(DirectByteBuffer@55248fca[p=0,l=2,c=2,r=2]={<<<{}>>>}, true) - events.onText=OptionalSessionCallableMethod[pojo=org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet,method=public void org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet.onMessage(java.lang.String),wantsSession=false,streaming=false], activeMessage=null
16:48:03.877 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - onTextMessage([2] "{}") - events.onText=OptionalSessionCallableMethod[pojo=org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet,method=public void org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet.onMessage(java.lang.String),wantsSession=false,streaming=false]
16:48:03.877 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.WebSocketSession - [SERVER] WebSocketSession.getRemote()
16:48:03.877 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.WebSocketRemoteEndpoint - sendString with HeapByteBuffer@3fbc7b44[p=0,l=11,c=11,r=11]={<<<[WEBSOCKET]>>>}
16:48:03.877 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.extensions.ExtensionStack - Queuing TEXT[len=11,fin=true,rsv=...,masked=false]
16:48:03.877 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.extensions.ExtensionStack - Processing TEXT[len=11,fin=true,rsv=...,masked=false]
16:48:03.877 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - outgoingFrame(TEXT[len=11,fin=true,rsv=...,masked=false], Flusher@219b9586[PROCESSING])
16:48:03.877 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Enqueued FrameEntry[TEXT[len=11,fin=true,rsv=...,masked=false],Flusher@219b9586[PROCESSING],AUTO,null] to Flusher@4163fc48[IDLE][queueSize=1,aggregateSize=-1,terminated=null]
16:48:03.877 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flushing Flusher@4163fc48[PROCESSING][queueSize=1,aggregateSize=-1,terminated=null]
16:48:03.877 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=-1,terminated=null] processing 1 entries: [FrameEntry[TEXT[len=11,fin=true,rsv=...,masked=false],Flusher@219b9586[PROCESSING],AUTO,null]]
16:48:03.878 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] acquired aggregate buffer java.nio.DirectByteBuffer[pos=0 lim=0 cap=32768]
16:48:03.878 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] aggregated 1 frames: [FrameEntry[TEXT[len=0,fin=true,rsv=...,masked=false],Flusher@219b9586[PROCESSING],AUTO,null]]
16:48:03.878 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flushing Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=0,terminated=null]
16:48:03.878 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] processing 0 entries: []
16:48:03.878 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] auto flushing
16:48:03.878 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] flushing aggregate java.nio.DirectByteBuffer[pos=0 lim=13 cap=32768]
16:48:03.878 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=0,terminated=null] flushing 0 frames: []
16:48:03.878 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@37678170{IDLE}->null [DirectByteBuffer@7a35579[p=0,l=13,c=32768,r=13]={<<<\x81\x0b[WEBSOCKET]>>>/callback...\x00\x00\x00\x00\x00\x00\x00}]
16:48:03.878 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@37678170{WRITING}->null:IDLE-->WRITING
16:48:03.878 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec woken up from select, 1/1/1 selected
16:48:03.878 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec processing 1 keys, 0 updates
16:48:03.878 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ChannelEndPoint - flushed 13 SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=-,flush=W,to=2/3600000}{io=0/0,kio=0,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=13,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=TEXT[len=0,fin=true,rsv=...,masked=true]]]
16:48:03.878 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=13 remaining=0 WriteFlusher@37678170{WRITING}->null
16:48:03.878 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@37678170{IDLE}->null:WRITING-->IDLE
16:48:03.879 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flushing Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=13,terminated=null]
16:48:03.879 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - selected 1 sun.nio.ch.SelectionKeyImpl@555521ee SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=4/3600000}{io=1/1,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=37,f=null]] 
16:48:03.879 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=13,terminated=null] processing 0 entries: []
16:48:03.879 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=13,terminated=null] auto flushing
16:48:03.879 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=13,terminated=null] flushing 0 frames: []
16:48:03.879 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ChannelEndPoint - onSelected 1->0 r=true w=false for SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=4/3600000}{io=1/0,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=37,f=null]]
16:48:03.879 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.extensions.ExtensionStack - Entering IDLE
16:48:03.879 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - ReadState@67beabc6[READING] getAction(DirectByteBuffer@6c24f588[p=8,l=8,c=4096,r=0]={\x81\x82\x08@\xA2\x17{}<<<>>>uri":"/ca...\x00\x00\x00\x00\x00\x00\x00})
16:48:03.879 [qtp1292040526-35] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - ReadState Action: FILL
16:48:03.879 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ChannelEndPoint - task CEP:SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=4/3600000}{io=1/0,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=37,f=null]]:runFillable:BLOCKING
16:48:03.879 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 DirectByteBuffer@6c24f588[p=0,l=0,c=4096,r=0]={<<<>>>\x81\x82\x08@\xA2\x17{}u...\x00\x00\x00\x00\x00\x00\x00}
16:48:03.879 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested WebSocketServerConnection@213c163f::SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=-,flush=-,to=0/3600000}{io=0/0,kio=0,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]]
16:48:03.879 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=2/12,p=0} tryExecute EatWhatYouKill@64040287/SelectorProducer@110844f6/PRODUCING/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=8<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=2/12,p=0}][pc=0,pic=0,pec=1,epc=2]@2021-11-24T16:48:03.879Z
16:48:03.879 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@fb7340b{AC.ReadCB@213c163f{WebSocketServerConnection@213c163f::SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=0/3600000}{io=0/0,kio=0,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]]}}
16:48:03.879 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=1/12,p=0}@14682bd5 offer EatWhatYouKill@64040287/SelectorProducer@110844f6/PRODUCING/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=8<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=1/12,p=0}][pc=0,pic=0,pec=1,epc=2]@2021-11-24T16:48:03.879Z
16:48:03.880 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=0/3600000}{io=0/1,kio=0,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]]
16:48:03.880 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.ChannelEndPoint$$Lambda$483/2027922092@220da7 on ManagedSelector@7d3e8655{STARTED} id=2 keys=1 selected=0 updates=0
16:48:03.880 [qtp1292040526-35] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@7d3e8655{STARTED} id=2 keys=1 selected=0 updates=1
16:48:03.880 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6e232e19 woken with none selected
16:48:03.880 [qtp1292040526-35] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@f78a47e{s=2/12,p=0}@5e2b4586 waiting
16:48:03.880 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6e232e19 woken up from select, 0/0/1 selected
16:48:03.880 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6e232e19 processing 0 keys, 1 updates
16:48:03.880 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
16:48:03.880 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ChannelEndPoint$$Lambda$483/2027922092@220da7
16:48:03.880 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@64040287/SelectorProducer@110844f6/IDLE/p=true/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=8<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=1/12,p=0}][pc=0,pic=0,pec=1,epc=2]@2021-11-24T16:48:03.88Z m=EXECUTE_PRODUCE_CONSUME t=CEP:SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=5/3600000}{io=1/0,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=37,f=null]]:runFillable:BLOCKING/BLOCKING
16:48:03.880 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=1/12,p=0}@14682bd5 task=EatWhatYouKill@64040287/SelectorProducer@110844f6/IDLE/p=true/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=8<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=1/12,p=0}][pc=0,pic=0,pec=1,epc=2]@2021-11-24T16:48:03.88Z
16:48:03.880 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=0/3600000}{io=1/1,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]]
16:48:03.880 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:03.880 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6e232e19 waiting with 1 keys
16:48:03.880 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.FillInterest - fillable FillInterest@42d8c2aa{AC.ReadCB@72a82a7c{WebSocketClientConnection@72a82a7c::SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=6/3600000}{io=1/0,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=37,f=null]]}}
16:48:03.880 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@64040287/SelectorProducer@110844f6/IDLE/p=true/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=8<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=1/12,p=0}][pc=0,pic=0,pec=1,epc=3]@2021-11-24T16:48:03.88Z tryProduce true
16:48:03.880 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - CLIENT onFillable()
16:48:03.880 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 0
16:48:03.880 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:03.880 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - CLIENT onFillable(ByteBuffer): java.nio.DirectByteBuffer[pos=0 lim=0 cap=4096]
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - ReadState@212475e8[READING] getAction(DirectByteBuffer@2536389d[p=0,l=0,c=4096,r=0]={<<<>>>\x81%[WEBSOC...\x00\x00\x00\x00\x00\x00\x00})
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - ReadState Action: FILL
16:48:03.881 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 1 -> 0 on SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=-,flush=-,to=6/3600000}{io=0/0,kio=0,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=37,f=null]]
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 13 DirectByteBuffer@2536389d[p=0,l=13,c=4096,r=13]={<<<\x81\x0b[WEBSOCKET]>>>/callback...\x00\x00\x00\x00\x00\x00\x00}
16:48:03.881 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec waiting with 1 keys
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - Filled 13 bytes - DirectByteBuffer@2536389d[p=0,l=13,c=4096,r=13]={<<<\x81\x0b[WEBSOCKET]>>>/callback...\x00\x00\x00\x00\x00\x00\x00}
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - ReadState@212475e8[READING] getAction(DirectByteBuffer@2536389d[p=0,l=13,c=4096,r=13]={<<<\x81\x0b[WEBSOCKET]>>>/callback...\x00\x00\x00\x00\x00\x00\x00})
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - ReadState Action: PARSE
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT Parsing 13 bytes
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT OpCode TEXT, fin=true rsv=...
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT Payload Length: 11 - Parser@75f81da0[ExtensionStack,s=PAYLOAD_LEN,c=0,len=11,f=TEXT[len=0,fin=true,rsv=...,masked=false]]
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT Window: DirectByteBuffer@56b38f87[p=0,l=11,c=11,r=11]={<<<[WEBSOCKET]>>>}
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT Parsed Frame: TEXT[len=11,fin=true,rsv=...,masked=false]
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT Notify ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.websocket.common.WebSocketSession,outgoing=org.eclipse.jetty.websocket.client.io.WebSocketClientConnection]
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - incomingFrame(TEXT[len=11,fin=true,rsv=...,masked=false])
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - onFrame(TEXT[len=11,fin=true,rsv=...,masked=false]) - events.onFrame=null
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - onTextFrame(DirectByteBuffer@56b38f87[p=0,l=11,c=11,r=11]={<<<[WEBSOCKET]>>>}, true) - events.onText=OptionalSessionCallableMethod[pojo=org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient,method=public void org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient.onMessage(java.lang.String),wantsSession=false,streaming=false], activeMessage=null
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - onTextMessage([11] "[WEBSOCKET]") - events.onText=OptionalSessionCallableMethod[pojo=org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient,method=public void org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient.onMessage(java.lang.String),wantsSession=false,streaming=false]
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - ReadState@212475e8[READING] getAction(DirectByteBuffer@2536389d[p=13,l=13,c=4096,r=0]={\x81\x0b[WEBSOCKET]<<<>>>/callback...\x00\x00\x00\x00\x00\x00\x00})
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - ReadState Action: FILL
16:48:03.881 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 DirectByteBuffer@2536389d[p=0,l=0,c=4096,r=0]={<<<>>>\x81\x0b[WEBSOC...\x00\x00\x00\x00\x00\x00\x00}
16:48:03.882 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested WebSocketClientConnection@72a82a7c::SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=-,flush=-,to=0/3600000}{io=0/0,kio=0,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=11,f=null]]
16:48:03.882 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@42d8c2aa{AC.ReadCB@72a82a7c{WebSocketClientConnection@72a82a7c::SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=0/3600000}{io=0/0,kio=0,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=11,f=null]]}}
16:48:03.882 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=0/3600000}{io=0/1,kio=0,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=11,f=null]]
16:48:03.882 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.ChannelEndPoint$$Lambda$483/2027922092@272ad126 on ManagedSelector@6f89f665{STARTED} id=1 keys=1 selected=0 updates=0
16:48:03.882 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@6f89f665{STARTED} id=1 keys=1 selected=0 updates=1
16:48:03.882 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec woken with none selected
16:48:03.882 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=2/12,p=0}@59903810 waiting
16:48:03.882 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec woken up from select, 0/0/1 selected
16:48:03.882 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec processing 0 keys, 1 updates
16:48:03.882 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
16:48:03.882 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ChannelEndPoint$$Lambda$483/2027922092@272ad126
16:48:03.883 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=0/3600000}{io=1/1,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=11,f=null]]
16:48:03.883 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:03.883 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec waiting with 1 keys
[WEBSOCKET]
16:48:03.960 [main] INFO org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackFactory - Callback servlet '%s' unregistered
16:48:03.960 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping WebSocketServerFactory@378580da[defaultPolicy=WebSocketPolicy@335a3c8d[behavior=SERVER,maxTextMessageSize=65536,maxTextMessageBufferSize=32768,maxBinaryMessageSize=65536,maxBinaryMessageBufferSize=32768,asyncWriteTimeout=60000,idleTimeout=3600000,inputBufferSize=4096],creator=org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketPool]
16:48:03.960 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping SessionTracker@77f3e223{STARTED}
16:48:03.961 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping WebSocketSession[websocket=JettyAnnotatedEventDriver[org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet@6f7c7459],behavior=SERVER,connection=WebSocketServerConnection@213c163f::SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=81/3600000}{io=1/1,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]],remote=WebSocketRemoteEndpoint@69535568[batching=true],incoming=JettyAnnotatedEventDriver[org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet@6f7c7459],outgoing=ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.websocket.common.WebSocketSession,outgoing=org.eclipse.jetty.websocket.server.WebSocketServerConnection]]
16:48:03.961 [main] DEBUG org.eclipse.jetty.websocket.common.WebSocketSession - stopping - WebSocketSession[websocket=JettyAnnotatedEventDriver[org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet@6f7c7459],behavior=SERVER,connection=WebSocketServerConnection@213c163f::SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=81/3600000}{io=1/1,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[OPENED],f=Flusher@4163fc48[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]],remote=WebSocketRemoteEndpoint@69535568[batching=true],incoming=JettyAnnotatedEventDriver[org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet@6f7c7459],outgoing=ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.websocket.common.WebSocketSession,outgoing=org.eclipse.jetty.websocket.server.WebSocketServerConnection]]
16:48:03.963 [main] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - outgoingFrame(CLOSE[len=10,fin=true,rsv=...,masked=false], org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection$CallbackBridge@5a101b1c)
16:48:03.963 [main] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Enqueued FrameEntry[CLOSE[len=10,fin=true,rsv=...,masked=false],org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection$CallbackBridge@5a101b1c,OFF,null] to Flusher@4163fc48[IDLE][queueSize=1,aggregateSize=-1,terminated=null]
16:48:03.964 [main] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flushing Flusher@4163fc48[PROCESSING][queueSize=1,aggregateSize=-1,terminated=null]
16:48:03.964 [main] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=-1,terminated=null] processing 1 entries: [FrameEntry[CLOSE[len=10,fin=true,rsv=...,masked=false],org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection$CallbackBridge@5a101b1c,OFF,null]]
16:48:03.964 [main] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=-1,terminated=null] flushing 1 frames: [FrameEntry[CLOSE[len=10,fin=true,rsv=...,masked=false],org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection$CallbackBridge@5a101b1c,OFF,null]]
16:48:03.964 [main] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@37678170{IDLE}->null [DirectByteBuffer@2160e52a[p=0,l=2,c=1024,r=2]={<<<\x88\n>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00},HeapByteBuffer@29f0802c[p=0,l=10,c=10,r=10]={<<<\x03\xE9Shutdown>>>}]
16:48:03.964 [main] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@37678170{WRITING}->null:IDLE-->WRITING
16:48:03.964 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec woken up from select, 1/1/1 selected
16:48:03.964 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec processing 1 keys, 0 updates
16:48:03.964 [main] DEBUG org.eclipse.jetty.io.ChannelEndPoint - flushed 12 SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=W,to=85/3600000}{io=1/1,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[CLOSING],f=Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]]
16:48:03.965 [main] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=12 remaining=0 WriteFlusher@37678170{WRITING}->null
16:48:03.965 [main] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@37678170{IDLE}->null:WRITING-->IDLE
16:48:03.965 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - selected 1 sun.nio.ch.SelectionKeyImpl@555521ee SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=82/3600000}{io=1/1,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=11,f=null]] 
16:48:03.965 [main] DEBUG org.eclipse.jetty.websocket.common.WebSocketSession - callApplicationOnClose(CloseInfo[code=1006,reason=Disconnected])
16:48:03.965 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ChannelEndPoint - onSelected 1->0 r=true w=false for SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=82/3600000}{io=1/0,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=11,f=null]]
16:48:03.965 [main] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - onClose(CloseInfo[code=1006,reason=Disconnected]) - events.onClose=OptionalSessionCallableMethod[pojo=org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet,method=public void org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet.onClose(int,java.lang.String),wantsSession=false,streaming=false]
16:48:03.965 [main] DEBUG org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet - 
16:48:03.965 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ChannelEndPoint - task CEP:SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=83/3600000}{io=1/0,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=11,f=null]]:runFillable:BLOCKING
16:48:03.965 [main] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - SERVER disconnect()
16:48:03.965 [main] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Terminating Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected]
16:48:03.965 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=2/12,p=0} tryExecute EatWhatYouKill@64040287/SelectorProducer@110844f6/PRODUCING/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=8<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=2/12,p=0}][pc=0,pic=0,pec=1,epc=3]@2021-11-24T16:48:03.965Z
16:48:03.965 [main] DEBUG org.eclipse.jetty.io.AbstractEndPoint - shutdownOutput SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OPEN,fill=FI,flush=-,to=0/3600000}{io=1/1,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[DISCONNECTED],f=Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]]
16:48:03.966 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=1/12,p=0}@59903810 offer EatWhatYouKill@64040287/SelectorProducer@110844f6/PRODUCING/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=8<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=1/12,p=0}][pc=0,pic=0,pec=1,epc=3]@2021-11-24T16:48:03.965Z
16:48:03.966 [main] DEBUG org.eclipse.jetty.io.AbstractEndPoint - close SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OSHUT,fill=FI,flush=-,to=1/3600000}{io=1/1,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[DISCONNECTED],f=Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]]
16:48:03.966 [main] DEBUG org.eclipse.jetty.io.AbstractEndPoint - close(null) SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,OSHUT,fill=FI,flush=-,to=1/3600000}{io=1/1,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[DISCONNECTED],f=Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]]
16:48:03.966 [main] DEBUG org.eclipse.jetty.io.ChannelEndPoint - doClose SocketChannelEndPoint@2ff7135d{l=/127.0.0.1:8899,r=/127.0.0.1:38034,CLOSED,fill=FI,flush=-,to=1/3600000}{io=1/1,kio=1,kro=1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[DISCONNECTED],f=Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]]
16:48:03.966 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@64040287/SelectorProducer@110844f6/IDLE/p=true/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=8<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=1/12,p=0}][pc=0,pic=0,pec=1,epc=3]@2021-11-24T16:48:03.966Z m=EXECUTE_PRODUCE_CONSUME t=CEP:SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=84/3600000}{io=1/0,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=11,f=null]]:runFillable:BLOCKING/BLOCKING
16:48:03.966 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=1/12,p=0}@59903810 task=EatWhatYouKill@64040287/SelectorProducer@110844f6/IDLE/p=true/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=8<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=1/12,p=0}][pc=0,pic=0,pec=1,epc=3]@2021-11-24T16:48:03.966Z
16:48:03.966 [main] DEBUG org.eclipse.jetty.io.FillInterest - onClose FillInterest@fb7340b{AC.ReadCB@213c163f{WebSocketServerConnection@213c163f::SocketChannelEndPoint@2ff7135d{l=0.0.0.0/0.0.0.0:8899,r=/127.0.0.1:38034,CLOSED,fill=FI,flush=-,to=1/3600000}{io=1/1,kio=-1,kro=-1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[DISCONNECTED],f=Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]]}}
16:48:03.966 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@64040287/SelectorProducer@110844f6/IDLE/p=true/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=8<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=1/12,p=0}][pc=0,pic=0,pec=1,epc=4]@2021-11-24T16:48:03.966Z tryProduce true
16:48:03.967 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 0
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.FillInterest - fillable FillInterest@42d8c2aa{AC.ReadCB@72a82a7c{WebSocketClientConnection@72a82a7c::SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=FI,flush=-,to=84/3600000}{io=1/0,kio=1,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=11,f=null]]}}
16:48:03.967 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - CLIENT onFillable()
16:48:03.967 [main] DEBUG org.eclipse.jetty.io.AbstractConnection - WebSocketServerConnection@213c163f::SocketChannelEndPoint@2ff7135d{l=0.0.0.0/0.0.0.0:8899,r=/127.0.0.1:38034,CLOSED,fill=-,flush=-,to=2/3600000}{io=1/1,kio=-1,kro=-1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[DISCONNECTED],f=Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]] onFillInterestedFailed {}
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - CLIENT onFillable(ByteBuffer): java.nio.DirectByteBuffer[pos=0 lim=0 cap=4096]
16:48:03.967 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup ManagedSelector@7d3e8655{STARTED} id=2 keys=1 selected=0 updates=0
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - ReadState@212475e8[READING] getAction(DirectByteBuffer@2536389d[p=0,l=0,c=4096,r=0]={<<<>>>\x81\x0b[WEBSOC...\x00\x00\x00\x00\x00\x00\x00})
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - ReadState Action: FILL
16:48:03.967 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@3a60c416 startThread=0
16:48:03.967 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6e232e19 woken with none selected
16:48:03.967 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 1 -> 0 on SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=-,flush=-,to=84/3600000}{io=0/0,kio=0,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[OPENED],f=Flusher@1f4e1f5e[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=11,f=null]]
16:48:03.967 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6e232e19 woken up from select, 0/0/0 selected
16:48:03.967 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6e232e19 processing 0 keys, 0 updates
16:48:03.967 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec waiting with 1 keys
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 12 DirectByteBuffer@2536389d[p=0,l=12,c=4096,r=12]={<<<\x88\n\x03\xE9Shutdown>>>]/callbac...\x00\x00\x00\x00\x00\x00\x00}
16:48:03.967 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 0
16:48:03.967 [qtp1292040526-33] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@3a60c416 in QueuedThreadPool[qtp1292040526]@4d02f94e{STARTED,8<=10<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@f78a47e{s=2/12,p=0}]
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - Filled 12 bytes - DirectByteBuffer@2536389d[p=0,l=12,c=4096,r=12]={<<<\x88\n\x03\xE9Shutdown>>>]/callbac...\x00\x00\x00\x00\x00\x00\x00}
16:48:03.967 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:03.967 [qtp1292040526-28] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6e232e19 waiting with 0 keys
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - ReadState@212475e8[READING] getAction(DirectByteBuffer@2536389d[p=0,l=12,c=4096,r=12]={<<<\x88\n\x03\xE9Shutdown>>>]/callbac...\x00\x00\x00\x00\x00\x00\x00})
16:48:03.967 [main] DEBUG org.eclipse.jetty.io.AbstractEndPoint - shutdownOutput SocketChannelEndPoint@2ff7135d{l=0.0.0.0/0.0.0.0:8899,r=null,CLOSED,fill=-,flush=-,to=2/3600000}{io=1/1,kio=-1,kro=-1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[DISCONNECTED],f=Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]]
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - ReadState Action: PARSE
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT Parsing 12 bytes
16:48:03.967 [main] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flushing Flusher@4163fc48[PROCESSING][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected]
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT OpCode CLOSE, fin=true rsv=...
16:48:03.967 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.websocket.common.WebSocketSession,outgoing=org.eclipse.jetty.websocket.server.WebSocketServerConnection]
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT Payload Length: 10 - Parser@75f81da0[ExtensionStack,s=PAYLOAD_LEN,c=0,len=10,f=CLOSE[len=0,fin=true,rsv=...,masked=false]]
16:48:03.967 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.websocket.common.WebSocketSession,outgoing=org.eclipse.jetty.websocket.server.WebSocketServerConnection]
16:48:03.967 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping JettyAnnotatedEventDriver[org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet@6f7c7459]
16:48:03.967 [qtp1292040526-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Destroyed SocketChannelEndPoint@2ff7135d{l=0.0.0.0/0.0.0.0:8899,r=null,CLOSED,fill=-,flush=-,to=2/3600000}{io=1/1,kio=-1,kro=-1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[DISCONNECTED],f=Flusher@4163fc48[SUCCEEDED][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]]
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT Window: DirectByteBuffer@b4528ea[p=0,l=10,c=10,r=10]={<<<\x03\xE9Shutdown>>>}
16:48:03.967 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED JettyAnnotatedEventDriver[org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet@6f7c7459]
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT Parsed Frame: CLOSE[len=10,fin=true,rsv=...,masked=false]
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.Parser - CLIENT Notify ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.websocket.common.WebSocketSession,outgoing=org.eclipse.jetty.websocket.client.io.WebSocketClientConnection]
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - incomingFrame(CLOSE[len=10,fin=true,rsv=...,masked=false])
16:48:03.967 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - onFrame(CLOSE[len=10,fin=true,rsv=...,masked=false]) - events.onFrame=null
16:48:03.967 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED WebSocketSession[websocket=JettyAnnotatedEventDriver[org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet@6f7c7459],behavior=SERVER,connection=WebSocketServerConnection@213c163f::SocketChannelEndPoint@2ff7135d{l=0.0.0.0/0.0.0.0:8899,r=null,CLOSED,fill=-,flush=-,to=2/3600000}{io=1/1,kio=-1,kro=-1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[DISCONNECTED],f=Flusher@4163fc48[SUCCEEDED][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]],remote=WebSocketRemoteEndpoint@69535568[batching=true],incoming=JettyAnnotatedEventDriver[org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketServlet@6f7c7459],outgoing=ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.websocket.common.WebSocketSession,outgoing=org.eclipse.jetty.websocket.server.WebSocketServerConnection]]
16:48:03.967 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED SessionTracker@77f3e223{STOPPED}
16:48:03.968 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping WebSocketExtensionFactory@14fe8f09{STARTED}
16:48:03.968 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.WebSocketSession - callApplicationOnClose(CloseInfo[code=1001,reason=Shutdown])
16:48:03.968 [qtp1292040526-33] DEBUG org.eclipse.jetty.io.AbstractConnection - onClose WebSocketServerConnection@213c163f::SocketChannelEndPoint@2ff7135d{l=0.0.0.0/0.0.0.0:8899,r=null,CLOSED,fill=-,flush=-,to=2/3600000}{io=1/1,kio=-1,kro=-1}->WebSocketServerConnection@213c163f[s=ConnectionState@21e063db[DISCONNECTED],f=Flusher@4163fc48[SUCCEEDED][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[SERVER,validating],p=Parser@7d8da195[ExtensionStack,s=START,c=0,len=2,f=null]]
16:48:03.968 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping DeflaterPool@32404985{STARTED,size=0,capacity=UNLIMITED}
16:48:03.968 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED DeflaterPool@32404985{STOPPED,size=0,capacity=UNLIMITED}
16:48:03.968 [qtp1292040526-33] DEBUG org.eclipse.jetty.websocket.common.WebSocketSession - [SERVER] WebSocketSession.onSessionClosed()
16:48:03.968 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.events.JettyAnnotatedEventDriver - onClose(CloseInfo[code=1001,reason=Shutdown]) - events.onClose=OptionalSessionCallableMethod[pojo=org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient,method=public void org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient.onClose(int,java.lang.String),wantsSession=false,streaming=false]
16:48:03.968 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping InflaterPool@442efeef{STARTED,size=0,capacity=UNLIMITED}
16:48:03.968 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED InflaterPool@442efeef{STOPPED,size=0,capacity=UNLIMITED}
16:48:03.968 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED WebSocketExtensionFactory@14fe8f09{STOPPED}
16:48:03.968 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ScheduledExecutorScheduler@24351cbb{STARTED}
16:48:03.968 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - outgoingFrame(CLOSE[len=10,fin=true,rsv=...,masked=true], org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection$CallbackBridge@17df1dda)
16:48:03.968 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ScheduledExecutorScheduler@24351cbb{STOPPED}
16:48:03.968 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED WebSocketServerFactory@378580da[defaultPolicy=WebSocketPolicy@335a3c8d[behavior=SERVER,maxTextMessageSize=65536,maxTextMessageBufferSize=32768,maxBinaryMessageSize=65536,maxBinaryMessageBufferSize=32768,asyncWriteTimeout=60000,idleTimeout=3600000,inputBufferSize=4096],creator=org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackWebSocketPool]
16:48:03.968 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Enqueued FrameEntry[CLOSE[len=10,fin=true,rsv=...,masked=true],org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection$CallbackBridge@17df1dda,OFF,null] to Flusher@1f4e1f5e[IDLE][queueSize=1,aggregateSize=-1,terminated=null]
16:48:03.968 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flushing Flusher@1f4e1f5e[PROCESSING][queueSize=1,aggregateSize=-1,terminated=null]
16:48:03.968 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=-1,terminated=null] processing 1 entries: [FrameEntry[CLOSE[len=10,fin=true,rsv=...,masked=true],org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection$CallbackBridge@17df1dda,OFF,null]]
16:48:03.968 [main] INFO org.eclipse.sensinact.gateway.nthbnd.http.callback.internal.CallbackFactory - Callback servlet '%s' unregistered
16:48:03.968 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=-1,terminated=null] flushing 1 frames: [FrameEntry[CLOSE[len=10,fin=true,rsv=...,masked=true],org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection$CallbackBridge@17df1dda,OFF,null]]
16:48:03.968 [qtp1292040526-33] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@3a60c416 in QueuedThreadPool[qtp1292040526]@4d02f94e{STARTED,8<=10<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@f78a47e{s=2/12,p=0}]
16:48:03.968 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@49e796ed{IDLE}->null [DirectByteBuffer@231a2532[p=0,l=6,c=1024,r=6]={<<<\x88\x8a\xD3F4\x03>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00},HeapByteBuffer@6e0f7f2c[p=0,l=10,c=10,r=10]={<<<\xD0\xAfgk\xA62Pl\xA4(>>>}]
16:48:03.968 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@49e796ed{WRITING}->null:IDLE-->WRITING
16:48:03.969 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ChannelEndPoint - flushed 16 SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=-,flush=W,to=1/3600000}{io=0/0,kio=0,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[CLOSING],f=Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=10,f=CLOSE[len=10,fin=true,rsv=...,masked=false]]]
16:48:03.969 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=16 remaining=0 WriteFlusher@49e796ed{WRITING}->null
# Execution Finished: testCallbackHttpAndWebSocket(BundleContext) - [engine:bnd-bundle-engine]/[bundle:org.eclipse.sensinact.gateway.nthbnd.http-tools;2.0.0.202111241647]/[fragment:org.eclipse.sensinact.gateway.nthbnd.http-tools-tests;2.0.0.202111241647]/[sub-engine:junit-jupiter]/[class:org.eclipse.sensinact.gateway.nthbnd.forward.test.TestForwardingService]/[method:testCallbackHttpAndWebSocket(org.osgi.framework.BundleContext)] - TestExecutionResult [status = SUCCESSFUL, throwable = null]
16:48:03.969 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@49e796ed{IDLE}->null:WRITING-->IDLE
16:48:03.969 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.WebSocketSession - callApplicationOnClose(CloseInfo[code=1006,reason=Disconnected])
16:48:03.969 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - CLIENT disconnect()
16:48:03.969 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Terminating Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected]
16:48:03.969 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.AbstractEndPoint - shutdownOutput SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OPEN,fill=-,flush=-,to=0/3600000}{io=0/0,kio=0,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[DISCONNECTED],f=Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=10,f=CLOSE[len=10,fin=true,rsv=...,masked=false]]]
16:48:03.970 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.AbstractEndPoint - close SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OSHUT,fill=-,flush=-,to=0/3600000}{io=0/0,kio=0,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[DISCONNECTED],f=Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=10,f=CLOSE[len=10,fin=true,rsv=...,masked=false]]]
16:48:03.970 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.AbstractEndPoint - close(null) SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,OSHUT,fill=-,flush=-,to=0/3600000}{io=0/0,kio=0,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[DISCONNECTED],f=Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=10,f=CLOSE[len=10,fin=true,rsv=...,masked=false]]]
16:48:03.970 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ChannelEndPoint - doClose SocketChannelEndPoint@195b1add{l=/127.0.0.1:38034,r=/127.0.0.1:8899,CLOSED,fill=-,flush=-,to=0/3600000}{io=0/0,kio=0,kro=1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[DISCONNECTED],f=Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=10,f=CLOSE[len=10,fin=true,rsv=...,masked=false]]]
16:48:03.970 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.FillInterest - onClose FillInterest@42d8c2aa{null}
16:48:03.970 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup ManagedSelector@6f89f665{STARTED} id=1 keys=1 selected=0 updates=0
16:48:03.970 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@4c2f187f startThread=1
16:48:03.970 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec woken with none selected
16:48:03.970 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec woken up from select, 0/0/0 selected
# Execution Finished: TestForwardingService - [engine:bnd-bundle-engine]/[bundle:org.eclipse.sensinact.gateway.nthbnd.http-tools;2.0.0.202111241647]/[fragment:org.eclipse.sensinact.gateway.nthbnd.http-tools-tests;2.0.0.202111241647]/[sub-engine:junit-jupiter]/[class:org.eclipse.sensinact.gateway.nthbnd.forward.test.TestForwardingService] - TestExecutionResult [status = SUCCESSFUL, throwable = null]
16:48:03.970 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Starting Thread[WebSocketClient@423711439-59,5,main]
16:48:03.970 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec processing 0 keys, 0 updates
16:48:03.970 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 0
16:48:03.970 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
# Execution Finished: JUnit Jupiter - [engine:bnd-bundle-engine]/[bundle:org.eclipse.sensinact.gateway.nthbnd.http-tools;2.0.0.202111241647]/[fragment:org.eclipse.sensinact.gateway.nthbnd.http-tools-tests;2.0.0.202111241647]/[sub-engine:junit-jupiter] - TestExecutionResult [status = SUCCESSFUL, throwable = null]
16:48:03.970 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec waiting with 0 keys
16:48:03.971 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.io.AbstractEndPoint - shutdownOutput SocketChannelEndPoint@195b1add{l=0.0.0.0/0.0.0.0:38034,r=null,CLOSED,fill=-,flush=-,to=1/3600000}{io=0/0,kio=-1,kro=-1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[DISCONNECTED],f=Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=10,f=CLOSE[len=10,fin=true,rsv=...,masked=false]]]
16:48:03.971 [WebSocketClient@423711439-59] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Runner started for QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=1/12,p=0}]
16:48:03.971 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.FrameFlusher - Flushing Flusher@1f4e1f5e[PROCESSING][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected]
16:48:03.971 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - ReadState@212475e8[READING] getAction(DirectByteBuffer@2536389d[p=12,l=12,c=4096,r=0]={\x88\n\x03\xE9Shutdown<<<>>>]/callbac...\x00\x00\x00\x00\x00\x00\x00})
16:48:03.971 [WebSocketClient@423711439-59] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@4c2f187f in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=1/12,p=0}]
# Execution Finished: [21] org.eclipse.sensinact.gateway.nthbnd.http-tools-tests;2.0.0.202111241647 - [engine:bnd-bundle-engine]/[bundle:org.eclipse.sensinact.gateway.nthbnd.http-tools;2.0.0.202111241647]/[fragment:org.eclipse.sensinact.gateway.nthbnd.http-tools-tests;2.0.0.202111241647] - TestExecutionResult [status = SUCCESSFUL, throwable = null]
16:48:03.971 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - ReadState Action: FILL
16:48:03.971 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - eof READING
16:48:03.971 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.ReadState - ReadState@212475e8[EOF] getAction(DirectByteBuffer@2536389d[p=12,l=12,c=4096,r=0]={\x88\n\x03\xE9Shutdown<<<>>>]/callbac...\x00\x00\x00\x00\x00\x00\x00})
16:48:03.971 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection - ReadState Action: EOF
16:48:03.971 [WebSocketClient@423711439-59] DEBUG org.eclipse.jetty.io.ManagedSelector - Destroyed SocketChannelEndPoint@195b1add{l=0.0.0.0/0.0.0.0:38034,r=null,CLOSED,fill=-,flush=-,to=2/3600000}{io=0/0,kio=-1,kro=-1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[DISCONNECTED],f=Flusher@1f4e1f5e[SUCCEEDED][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=10,f=null]]
16:48:03.972 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=2/12,p=0}@14682bd5 waiting
16:48:03.972 [WebSocketClient@423711439-59] DEBUG org.eclipse.jetty.io.AbstractConnection - onClose WebSocketClientConnection@72a82a7c::SocketChannelEndPoint@195b1add{l=0.0.0.0/0.0.0.0:38034,r=null,CLOSED,fill=-,flush=-,to=2/3600000}{io=0/0,kio=-1,kro=-1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[DISCONNECTED],f=Flusher@1f4e1f5e[SUCCEEDED][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=10,f=null]]
16:48:03.972 [WebSocketClient@423711439-59] DEBUG org.eclipse.jetty.websocket.common.WebSocketSession - [CLIENT] WebSocketSession.onSessionClosed()
16:48:03.972 [WebSocketClient@423711439-59] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping WebSocketSession[websocket=JettyAnnotatedEventDriver[org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient@7b22ec89],behavior=CLIENT,connection=WebSocketClientConnection@72a82a7c::SocketChannelEndPoint@195b1add{l=0.0.0.0/0.0.0.0:38034,r=null,CLOSED,fill=-,flush=-,to=3/3600000}{io=0/0,kio=-1,kro=-1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[DISCONNECTED],f=Flusher@1f4e1f5e[SUCCEEDED][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=10,f=null]],remote=WebSocketRemoteEndpoint@27f15809[batching=true],incoming=JettyAnnotatedEventDriver[org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient@7b22ec89],outgoing=ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.websocket.common.WebSocketSession,outgoing=org.eclipse.jetty.websocket.client.io.WebSocketClientConnection]]
16:48:03.973 [WebSocketClient@423711439-59] DEBUG org.eclipse.jetty.websocket.common.WebSocketSession - stopping - WebSocketSession[websocket=JettyAnnotatedEventDriver[org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient@7b22ec89],behavior=CLIENT,connection=WebSocketClientConnection@72a82a7c::SocketChannelEndPoint@195b1add{l=0.0.0.0/0.0.0.0:38034,r=null,CLOSED,fill=-,flush=-,to=3/3600000}{io=0/0,kio=-1,kro=-1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[DISCONNECTED],f=Flusher@1f4e1f5e[SUCCEEDED][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=10,f=null]],remote=WebSocketRemoteEndpoint@27f15809[batching=true],incoming=JettyAnnotatedEventDriver[org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient@7b22ec89],outgoing=ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.websocket.common.WebSocketSession,outgoing=org.eclipse.jetty.websocket.client.io.WebSocketClientConnection]]
16:48:03.973 [WebSocketClient@423711439-59] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.websocket.common.WebSocketSession,outgoing=org.eclipse.jetty.websocket.client.io.WebSocketClientConnection]
16:48:03.973 [WebSocketClient@423711439-59] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.websocket.common.WebSocketSession,outgoing=org.eclipse.jetty.websocket.client.io.WebSocketClientConnection]
16:48:03.973 [WebSocketClient@423711439-59] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping JettyAnnotatedEventDriver[org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient@7b22ec89]
16:48:03.973 [WebSocketClient@423711439-59] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED JettyAnnotatedEventDriver[org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient@7b22ec89]
16:48:03.973 [WebSocketClient@423711439-59] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED WebSocketSession[websocket=JettyAnnotatedEventDriver[org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient@7b22ec89],behavior=CLIENT,connection=WebSocketClientConnection@72a82a7c::SocketChannelEndPoint@195b1add{l=0.0.0.0/0.0.0.0:38034,r=null,CLOSED,fill=-,flush=-,to=4/3600000}{io=0/0,kio=-1,kro=-1}->WebSocketClientConnection@72a82a7c[s=ConnectionState@28daf634[DISCONNECTED],f=Flusher@1f4e1f5e[SUCCEEDED][queueSize=0,aggregateSize=-1,terminated=java.io.EOFException: Disconnected],g=Generator[CLIENT,validating],p=Parser@75f81da0[ExtensionStack,s=START,c=0,len=10,f=null]],remote=WebSocketRemoteEndpoint@27f15809[batching=true],incoming=JettyAnnotatedEventDriver[org.eclipse.sensinact.gateway.nthbnd.forward.test.WsServiceTestClient@7b22ec89],outgoing=ExtensionStack[queueSize=0,extensions=[],incoming=org.eclipse.jetty.websocket.common.WebSocketSession,outgoing=org.eclipse.jetty.websocket.client.io.WebSocketClientConnection]]
16:48:03.973 [WebSocketClient@423711439-59] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@4c2f187f in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=0,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=2/12,p=0}]
# Execution Finished: [20] org.eclipse.sensinact.gateway.nthbnd.http-tools;2.0.0.202111241647 - [engine:bnd-bundle-engine]/[bundle:org.eclipse.sensinact.gateway.nthbnd.http-tools;2.0.0.202111241647] - TestExecutionResult [status = SUCCESSFUL, throwable = null]
# Execution Finished: Bnd JUnit Platform Bundle Engine - [engine:bnd-bundle-engine] - TestExecutionResult [status = SUCCESSFUL, throwable = null]
# TestPlan Execution Finished: org.junit.platform.launcher.core.InternalTestPlan@b93aad
# 
Test run finished after 1091 ms
[         5 containers found      ]
[         0 containers skipped    ]
[         5 containers started    ]
[         0 containers aborted    ]
[         5 containers successful ]
[         0 containers failed     ]
[         3 tests found           ]
[         0 tests skipped         ]
[         3 tests started         ]
[         0 tests aborted         ]
[         3 tests successful      ]
[         0 tests failed          ]


# test ran
# queue []
16:48:03.995 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping WebSocketClient@1d1fc5fc[httpClient=HttpClient@194152cf{STARTED},openSessions.size=0]
16:48:03.995 [JettyShutdownThread] DEBUG org.eclipse.jetty.websocket.client.WebSocketClient - Stopping WebSocketClient@1d1fc5fc[httpClient=HttpClient@194152cf{STARTED},openSessions.size=0]
16:48:03.995 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.thread.ShutdownThread - shutdown already commenced
16:48:03.995 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping SessionTracker@6974a715{STARTED}
16:48:03.995 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED SessionTracker@6974a715{STOPPED}
16:48:03.995 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping WebSocketExtensionFactory@16943e88{STARTED}
16:48:03.995 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping DeflaterPool@73d6d0c{STARTED,size=0,capacity=UNLIMITED}
16:48:03.995 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED DeflaterPool@73d6d0c{STOPPED,size=0,capacity=UNLIMITED}
16:48:03.995 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping InflaterPool@4604b900{STARTED,size=0,capacity=UNLIMITED}
16:48:03.995 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED InflaterPool@4604b900{STOPPED,size=0,capacity=UNLIMITED}
16:48:03.995 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED WebSocketExtensionFactory@16943e88{STOPPED}
16:48:03.995 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping HttpClient@194152cf{STARTED}
16:48:03.996 [JettyShutdownThread] DEBUG org.eclipse.jetty.client.HttpDestination - Closed HttpDestination[ws://127.0.0.1:8899]@456be73c,queue=0,pool=DuplexConnectionPool@6d6cb754[c=0/0/64,a=0,i=0,q=0]
16:48:03.996 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping HttpDestination[ws://127.0.0.1:8899]@456be73c,queue=0,pool=DuplexConnectionPool@6d6cb754[c=0/0/64,a=0,i=0,q=0]
16:48:03.996 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping DuplexConnectionPool@6d6cb754[c=0/0/64,a=0,i=0,q=0]
16:48:03.996 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED DuplexConnectionPool@6d6cb754[c=0/0/64,a=0,i=0,q=0]
16:48:03.996 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED HttpDestination[ws://127.0.0.1:8899]@456be73c,queue=0,pool=DuplexConnectionPool@6d6cb754[c=0/0/64,a=0,i=0,q=0]
16:48:03.996 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ScheduledExecutorScheduler@3c4bc9fc{STARTED}
16:48:03.996 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ScheduledExecutorScheduler@3c4bc9fc{STOPPED}
16:48:03.996 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping Client@ec50f54[provider=null,keyStore=null,trustStore=null]
16:48:03.996 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED Client@ec50f54[provider=null,keyStore=null,trustStore=null]
16:48:03.996 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping HttpClientTransportOverHTTP@49d98dc5{STARTED}
16:48:03.997 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ClientSelectorManager@b0964b2{STARTED}
16:48:03.997 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ManagedSelector@38b972d7{STARTED} id=5 keys=0 selected=0 updates=0
16:48:03.997 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.ManagedSelector$CloseConnections@3c698e30 on ManagedSelector@38b972d7{STOPPING} id=5 keys=0 selected=0 updates=0
16:48:03.997 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@38b972d7{STOPPING} id=5 keys=0 selected=0 updates=1
16:48:03.997 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2240176f woken with none selected
16:48:03.997 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2240176f woken up from select, 0/0/0 selected
16:48:03.997 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2240176f processing 0 keys, 1 updates
16:48:03.997 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
16:48:03.997 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@3c698e30
16:48:03.997 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 0 connections on ManagedSelector@38b972d7{STOPPING} id=5 keys=0 selected=0 updates=0
16:48:03.997 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:03.997 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2240176f waiting with 0 keys
16:48:03.997 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.ManagedSelector$StopSelector@796b17d9 on ManagedSelector@38b972d7{STOPPING} id=5 keys=0 selected=0 updates=0
16:48:03.997 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@38b972d7{STOPPING} id=5 keys=0 selected=0 updates=1
16:48:03.997 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2240176f woken with none selected
16:48:03.997 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2240176f woken up from select, 0/0/0 selected
16:48:03.997 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2240176f processing 0 keys, 1 updates
16:48:03.997 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
16:48:03.997 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@796b17d9
16:48:03.999 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:03.999 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping EatWhatYouKill@68105edc/SelectorProducer@511816c0/PRODUCING/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=1,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=2/12,p=0}][pc=0,pic=0,pec=0,epc=0]@2021-11-24T16:48:03.999Z
16:48:03.999 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$41/1571798597@7dcc91fd in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=1,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=2/12,p=0}]
16:48:03.999 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED EatWhatYouKill@68105edc/SelectorProducer@511816c0/IDLE/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=1,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=2/12,p=0}][pc=0,pic=0,pec=0,epc=0]@2021-11-24T16:48:03.999Z
16:48:03.999 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ManagedSelector@38b972d7{STOPPED} id=5 keys=-1 selected=-1 updates=0
16:48:03.999 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ManagedSelector@60c16548{STARTED} id=4 keys=0 selected=0 updates=0
16:48:03.999 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.ManagedSelector$CloseConnections@40695651 on ManagedSelector@60c16548{STOPPING} id=4 keys=0 selected=0 updates=0
16:48:03.999 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@60c16548{STOPPING} id=4 keys=0 selected=0 updates=1
16:48:03.999 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@4fdf0965 woken with none selected
16:48:03.999 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@4fdf0965 woken up from select, 0/0/0 selected
16:48:03.999 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@4fdf0965 processing 0 keys, 1 updates
16:48:03.999 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
16:48:03.999 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@40695651
16:48:04.002 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 0 connections on ManagedSelector@60c16548{STOPPING} id=4 keys=0 selected=0 updates=0
16:48:04.003 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:04.003 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@4fdf0965 waiting with 0 keys
16:48:04.003 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.ManagedSelector$StopSelector@48d8eab3 on ManagedSelector@60c16548{STOPPING} id=4 keys=0 selected=0 updates=0
16:48:04.003 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@60c16548{STOPPING} id=4 keys=0 selected=0 updates=1
16:48:04.003 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@4fdf0965 woken with none selected
16:48:04.003 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@4fdf0965 woken up from select, 0/0/0 selected
16:48:04.003 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@4fdf0965 processing 0 keys, 1 updates
16:48:04.003 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
16:48:04.003 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@48d8eab3
16:48:04.003 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:04.003 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$41/1571798597@36074e47 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=2,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=2/12,p=0}]
16:48:04.003 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping EatWhatYouKill@c96a4ea/SelectorProducer@28782602/IDLE/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=3,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=2/12,p=0}][pc=0,pic=0,pec=0,epc=0]@2021-11-24T16:48:04.003Z
16:48:04.004 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED EatWhatYouKill@c96a4ea/SelectorProducer@28782602/IDLE/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=3,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=2/12,p=0}][pc=0,pic=0,pec=0,epc=0]@2021-11-24T16:48:04.004Z
16:48:04.004 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ManagedSelector@60c16548{STOPPED} id=4 keys=-1 selected=-1 updates=0
16:48:04.004 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ManagedSelector@5f2afe62{STARTED} id=3 keys=0 selected=0 updates=0
16:48:04.004 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.ManagedSelector$CloseConnections@3830f596 on ManagedSelector@5f2afe62{STOPPING} id=3 keys=0 selected=0 updates=0
16:48:04.004 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@5f2afe62{STOPPING} id=3 keys=0 selected=0 updates=1
16:48:04.004 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@10a5ec97 woken with none selected
16:48:04.004 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@10a5ec97 woken up from select, 0/0/0 selected
16:48:04.004 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@10a5ec97 processing 0 keys, 1 updates
16:48:04.004 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
16:48:04.004 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@3830f596
16:48:04.004 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 0 connections on ManagedSelector@5f2afe62{STOPPING} id=3 keys=0 selected=0 updates=0
16:48:04.004 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:04.004 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@10a5ec97 waiting with 0 keys
16:48:04.004 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.ManagedSelector$StopSelector@1944e896 on ManagedSelector@5f2afe62{STOPPING} id=3 keys=0 selected=0 updates=0
16:48:04.004 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@5f2afe62{STOPPING} id=3 keys=0 selected=0 updates=1
16:48:04.005 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@10a5ec97 woken with none selected
16:48:04.005 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@10a5ec97 woken up from select, 0/0/0 selected
16:48:04.005 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@10a5ec97 processing 0 keys, 1 updates
16:48:04.005 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
16:48:04.005 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@1944e896
16:48:04.005 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:04.005 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$41/1571798597@697446d4 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=3,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=2/12,p=0}]
16:48:04.005 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping EatWhatYouKill@3a43d133/SelectorProducer@39ce27f2/IDLE/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=4,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=2/12,p=0}][pc=0,pic=0,pec=0,epc=0]@2021-11-24T16:48:04.005Z
16:48:04.005 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED EatWhatYouKill@3a43d133/SelectorProducer@39ce27f2/IDLE/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=4,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=2/12,p=0}][pc=0,pic=0,pec=0,epc=0]@2021-11-24T16:48:04.005Z
16:48:04.005 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ManagedSelector@5f2afe62{STOPPED} id=3 keys=-1 selected=-1 updates=0
16:48:04.005 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ManagedSelector@1ad926d3{STARTED} id=2 keys=0 selected=0 updates=0
16:48:04.005 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.ManagedSelector$CloseConnections@1395ee31 on ManagedSelector@1ad926d3{STOPPING} id=2 keys=0 selected=0 updates=0
16:48:04.005 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@1ad926d3{STOPPING} id=2 keys=0 selected=0 updates=1
16:48:04.006 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5ef85e81 woken with none selected
16:48:04.006 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5ef85e81 woken up from select, 0/0/0 selected
16:48:04.006 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5ef85e81 processing 0 keys, 1 updates
16:48:04.006 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
16:48:04.006 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@1395ee31
16:48:04.006 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 0 connections on ManagedSelector@1ad926d3{STOPPING} id=2 keys=0 selected=0 updates=0
16:48:04.006 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:04.006 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5ef85e81 waiting with 0 keys
16:48:04.006 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.ManagedSelector$StopSelector@1c971382 on ManagedSelector@1ad926d3{STOPPING} id=2 keys=0 selected=0 updates=0
16:48:04.006 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@1ad926d3{STOPPING} id=2 keys=0 selected=0 updates=1
16:48:04.006 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5ef85e81 woken with none selected
16:48:04.006 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5ef85e81 woken up from select, 0/0/0 selected
16:48:04.006 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5ef85e81 processing 0 keys, 1 updates
16:48:04.006 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
16:48:04.006 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@1c971382
16:48:04.006 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:04.006 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$41/1571798597@3e6fd0b9 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=4,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=2/12,p=0}]
16:48:04.006 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping EatWhatYouKill@df1cff6/SelectorProducer@4925f4f5/IDLE/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=5,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=2/12,p=0}][pc=0,pic=0,pec=0,epc=0]@2021-11-24T16:48:04.006Z
16:48:04.006 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED EatWhatYouKill@df1cff6/SelectorProducer@4925f4f5/IDLE/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=5,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=2/12,p=0}][pc=0,pic=0,pec=0,epc=0]@2021-11-24T16:48:04.006Z
16:48:04.007 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ManagedSelector@1ad926d3{STOPPED} id=2 keys=-1 selected=-1 updates=0
16:48:04.007 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ManagedSelector@6f89f665{STARTED} id=1 keys=0 selected=0 updates=0
16:48:04.007 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.ManagedSelector$CloseConnections@2334d9c3 on ManagedSelector@6f89f665{STOPPING} id=1 keys=0 selected=0 updates=0
16:48:04.007 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@6f89f665{STOPPING} id=1 keys=0 selected=0 updates=1
16:48:04.007 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec woken with none selected
16:48:04.007 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec woken up from select, 0/0/0 selected
16:48:04.007 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec processing 0 keys, 1 updates
16:48:04.007 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
16:48:04.007 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@2334d9c3
16:48:04.007 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 0 connections on ManagedSelector@6f89f665{STOPPING} id=1 keys=0 selected=0 updates=0
16:48:04.007 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:04.007 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec waiting with 0 keys
16:48:04.007 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.ManagedSelector$StopSelector@1d0a956b on ManagedSelector@6f89f665{STOPPING} id=1 keys=0 selected=0 updates=0
16:48:04.007 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@6f89f665{STOPPING} id=1 keys=0 selected=0 updates=1
16:48:04.007 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec woken with none selected
16:48:04.007 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec woken up from select, 0/0/0 selected
16:48:04.007 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5b92c4ec processing 0 keys, 1 updates
16:48:04.007 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
16:48:04.007 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@1d0a956b
16:48:04.007 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:04.007 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping EatWhatYouKill@64040287/SelectorProducer@110844f6/PRODUCING/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=5,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=2/12,p=0}][pc=0,pic=0,pec=1,epc=4]@2021-11-24T16:48:04.007Z
16:48:04.007 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=3/12,p=0}@59903810 waiting
16:48:04.008 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED EatWhatYouKill@64040287/SelectorProducer@110844f6/IDLE/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=5,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=3/12,p=0}][pc=0,pic=0,pec=1,epc=4]@2021-11-24T16:48:04.008Z
16:48:04.008 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ManagedSelector@6f89f665{STOPPED} id=1 keys=-1 selected=-1 updates=0
16:48:04.008 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ManagedSelector@24e8de5c{STARTED} id=0 keys=0 selected=0 updates=0
16:48:04.008 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.ManagedSelector$CloseConnections@2152049d on ManagedSelector@24e8de5c{STOPPING} id=0 keys=0 selected=0 updates=0
16:48:04.008 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@24e8de5c{STOPPING} id=0 keys=0 selected=0 updates=1
16:48:04.008 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@443d6875 woken with none selected
16:48:04.008 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@443d6875 woken up from select, 0/0/0 selected
16:48:04.008 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@443d6875 processing 0 keys, 1 updates
16:48:04.008 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
16:48:04.008 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@2152049d
16:48:04.008 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 0 connections on ManagedSelector@24e8de5c{STOPPING} id=0 keys=0 selected=0 updates=0
16:48:04.008 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:04.008 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@443d6875 waiting with 0 keys
16:48:04.008 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.ManagedSelector$StopSelector@57d0836 on ManagedSelector@24e8de5c{STOPPING} id=0 keys=0 selected=0 updates=0
16:48:04.008 [JettyShutdownThread] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@24e8de5c{STOPPING} id=0 keys=0 selected=0 updates=1
16:48:04.008 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@443d6875 woken with none selected
16:48:04.008 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@443d6875 woken up from select, 0/0/0 selected
16:48:04.008 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@443d6875 processing 0 keys, 1 updates
16:48:04.008 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
16:48:04.008 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@57d0836
16:48:04.008 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
16:48:04.008 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping EatWhatYouKill@48e7b3d2/SelectorProducer@7f4037ed/PRODUCING/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=5,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=3/12,p=0}][pc=0,pic=0,pec=0,epc=0]@2021-11-24T16:48:04.008Z
16:48:04.009 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=5,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=3/12,p=0}]
16:48:04.009 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$41/1571798597@27494e46 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STARTED,8<=9<=200,i=5,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=3/12,p=0}]
16:48:04.009 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Stopping QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=9<=200,i=5,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=3/12,p=0}]
16:48:04.009 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ReservedThreadExecutor@5339bbad{s=3/12,p=0}
16:48:04.009 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=2/12,p=0}@59903810 offer STOP!
16:48:04.009 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=1/12,p=0}@14682bd5 offer STOP!
16:48:04.009 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=1/12,p=0}@59903810 task=STOP!
16:48:04.009 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=0/12,p=0}@682b8b58 offer STOP!
16:48:04.009 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=0/12,p=0}@59903810 Exited
16:48:04.009 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ReservedThreadExecutor@5339bbad{s=-1/12,p=0}
16:48:04.009 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran ReservedThreadExecutor@5339bbad{s=-1/12,p=0}@59903810 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=9<=200,i=6,r=-1,q=0}[ReservedThreadExecutor@5339bbad{s=-1/12,p=0}]
16:48:04.009 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Waiting for Thread[WebSocketClient@423711439-48,5,main] for 14999
16:48:04.009 [WebSocketClient@423711439-54] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Thread[WebSocketClient@423711439-54,5,main] exited for QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.009 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=-1/12,p=0}@14682bd5 task=STOP!
16:48:04.009 [WebSocketClient@423711439-47] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=-1/12,p=0}@682b8b58 task=STOP!
16:48:04.009 [WebSocketClient@423711439-59] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$10/285133380@6b627009 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.009 [WebSocketClient@423711439-47] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=-1/12,p=0}@682b8b58 Exited
16:48:04.009 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$10/285133380@6b627009 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.009 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$10/285133380@6b627009 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.009 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$10/285133380@6b627009 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.009 [WebSocketClient@423711439-59] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$10/285133380@6b627009 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.009 [WebSocketClient@423711439-47] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran ReservedThreadExecutor@5339bbad{s=-1/12,p=0}@682b8b58 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.009 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$10/285133380@6b627009 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.009 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$10/285133380@6b627009 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.009 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$10/285133380@6b627009 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.009 [WebSocketClient@423711439-59] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Thread[WebSocketClient@423711439-59,5,main] exited for QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.009 [WebSocketClient@423711439-47] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Thread[WebSocketClient@423711439-47,5,main] exited for QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.009 [WebSocketClient@423711439-51] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Thread[WebSocketClient@423711439-51,5,main] exited for QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.009 [WebSocketClient@423711439-50] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Thread[WebSocketClient@423711439-50,5,main] exited for QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.009 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@5339bbad{s=-1/12,p=0}@14682bd5 Exited
16:48:04.009 [WebSocketClient@423711439-52] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Thread[WebSocketClient@423711439-52,5,main] exited for QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.010 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$10/285133380@6b627009 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.009 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$10/285133380@6b627009 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.010 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran ReservedThreadExecutor@5339bbad{s=-1/12,p=0}@14682bd5 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.010 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$10/285133380@6b627009 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.010 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$10/285133380@6b627009 in QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.010 [WebSocketClient@423711439-53] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Thread[WebSocketClient@423711439-53,5,main] exited for QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.010 [WebSocketClient@423711439-48] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Thread[WebSocketClient@423711439-48,5,main] exited for QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.010 [WebSocketClient@423711439-49] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Thread[WebSocketClient@423711439-49,5,main] exited for QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPING,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.010 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Waiting for Thread[WebSocketClient@423711439-49,5,main] for 14999
16:48:04.010 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPED,8<=0<=200,i=6,r=-1,q=0}[NO_TRY]
16:48:04.010 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED EatWhatYouKill@48e7b3d2/SelectorProducer@7f4037ed/IDLE/p=false/QueuedThreadPool[WebSocketClient@423711439]@30cdae70{STOPPED,8<=0<=200,i=6,r=-1,q=0}[NO_TRY][pc=0,pic=0,pec=0,epc=0]@2021-11-24T16:48:04.01Z
16:48:04.010 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ManagedSelector@24e8de5c{STOPPED} id=0 keys=-1 selected=-1 updates=0
16:48:04.010 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ClientSelectorManager@b0964b2{STOPPED}
16:48:04.010 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED HttpClientTransportOverHTTP@49d98dc5{STOPPED}
16:48:04.010 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED HttpClient@194152cf{STOPPED}
16:48:04.010 [JettyShutdownThread] DEBUG org.eclipse.jetty.websocket.client.WebSocketClient - Stopped WebSocketClient@1d1fc5fc[httpClient=HttpClient@194152cf{STOPPED},openSessions.size=0]
16:48:04.010 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED WebSocketClient@1d1fc5fc[httpClient=HttpClient@194152cf{STOPPED},openSessions.size=0]
16:48:04.010 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.thread.ShutdownThread - Stopped WebSocketClient@1d1fc5fc[httpClient=HttpClient@194152cf{STOPPED},openSessions.size=0]
16:48:04.010 [JettyShutdownThread] DEBUG org.eclipse.jetty.util.thread.ShutdownThread - Destroyed WebSocketClient@1d1fc5fc[httpClient=HttpClient@194152cf{STOPPED},openSessions.size=0]
[INFO] No Errors