Re: [jetty-users] WebSocket over SSL in Embedded Jetty 9

Alexander Farber Thu, 23 Jun 2016 06:47:09 -0700

Oh, you meant to run my client with

-Dorg.eclipse.jetty.LEVEL=DEBUG -Djavax.net.debug=ssl

I am attaching the log files (now there are exceptions for some reason too).

(CC-ing you Simone in case the mailing list removes the file attachments)

Regards
Alex

On Thu, Jun 23, 2016 at 1:14 PM, Simone Bordet <[email protected]> wrote:

>
> It shows the client had a problem during the TLS handshake.
>
> Can you please enable DEBUG logging for "org.eclipse.jetty" for the
> client and *attach* the logs ?
>
> Do you have exceptions on the server ?
>
>

C:\Users\user1\jetty-newbie\WssClient>util_win.bat wss://127.0.0.1:8443

Active code page: 65001
Picked up JAVA_TOOL_OPTIONS: -Dfile.encoding=UTF8
2016-06-23 15:41:13.527:INFO::main: Logging initialized @203ms
2016-06-23 15:41:13.558:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.websocket.client.WebSocketClient@7699a589 added 
{SslContextFactory@58372a00(null,null),AUTO}
2016-06-23 15:41:13.558:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.websocket.client.WebSocketClient@7699a589 added 
{org.eclipse.jetty.io.MappedByteBufferPool@4dd8dc3,POJ
O}
2016-06-23 15:41:13.573:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.websocket.client.WebSocketClient@7699a589
2016-06-23 15:41:13.573:DBUG:oejwc.WebSocketClient:main: Starting 
org.eclipse.jetty.websocket.client.WebSocketClient@7699a589
2016-06-23 15:41:13.589:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.websocket.client.WebSocketClient@7699a589 added 
{org.eclipse.jetty.util.thread.ScheduledExecutorSchedu
ler@1a6c5a9e,AUTO}
2016-06-23 15:41:13.589:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.websocket.client.WebSocketClient@7699a589 added 
{org.eclipse.jetty.util.HttpCookieStore$Empty@254989ff
,POJO}
2016-06-23 15:41:13.589:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.websocket.client.WebSocketClient@7699a589 added 
{org.eclipse.jetty.websocket.common.WebSocketSessionFa
ctory@49c2faae,POJO}
2016-06-23 15:41:13.589:DBUG:oejuc.AbstractLifeCycle:main: starting 
SslContextFactory@58372a00(null,null)
2016-06-23 15:41:13.589:DBUG:oejus.SslContextFactory:main: No keystore or trust 
store configured.  ACCEPTING UNTRUSTED CERTIFICATES!!!!!
trigger seeding of SecureRandom
done seeding SecureRandom
Using SSLEngineImpl.
Ignoring unavailable cipher suite: TLS_DHE_DSS_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_RSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_DHE_RSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_RSA_WITH_AES_256_CBC_SHA256
Ignoring unavailable cipher suite: TLS_DHE_DSS_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDH_ECDSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_RSA_WITH_AES_256_CBC_SHA384
Ignoring unavailable cipher suite: TLS_RSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA384
Ignoring unavailable cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_RSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDH_RSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA384
Ignoring unavailable cipher suite: TLS_DHE_RSA_WITH_AES_256_CBC_SHA256
Ignoring unavailable cipher suite: TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_DHE_DSS_WITH_AES_256_CBC_SHA256
Ignoring unavailable cipher suite: TLS_DHE_RSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_DHE_DSS_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_DH_anon_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_DH_anon_WITH_AES_256_CBC_SHA256
Ignoring unavailable cipher suite: TLS_RSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_DHE_RSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_RSA_WITH_AES_256_CBC_SHA256
Ignoring unavailable cipher suite: TLS_DHE_DSS_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDH_ECDSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_RSA_WITH_AES_256_CBC_SHA384
Ignoring unavailable cipher suite: TLS_RSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_anon_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_RSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDH_RSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA384
Ignoring unavailable cipher suite: TLS_DHE_RSA_WITH_AES_256_CBC_SHA256
Ignoring unavailable cipher suite: TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_DHE_DSS_WITH_AES_256_CBC_SHA256
Ignoring unavailable cipher suite: TLS_DHE_RSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_DH_anon_WITH_AES_256_GCM_SHA384
2016-06-23 15:41:14.073:DBUG:oejus.SslContextFactory:main: Selected Protocols 
[TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
Ignoring unavailable cipher suite: TLS_DHE_DSS_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_DH_anon_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_DH_anon_WITH_AES_256_CBC_SHA256
Ignoring unavailable cipher suite: TLS_RSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_DHE_RSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_RSA_WITH_AES_256_CBC_SHA256
Ignoring unavailable cipher suite: TLS_DHE_DSS_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDH_ECDSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_RSA_WITH_AES_256_CBC_SHA384
Ignoring unavailable cipher suite: TLS_RSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_anon_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_RSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDH_RSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA384
Ignoring unavailable cipher suite: TLS_DHE_RSA_WITH_AES_256_CBC_SHA256
Ignoring unavailable cipher suite: TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_DHE_DSS_WITH_AES_256_CBC_SHA256
Ignoring unavailable cipher suite: TLS_DHE_RSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_DH_anon_WITH_AES_256_GCM_SHA384
2016-06-23 15:41:14.135:DBUG:oejus.SslContextFactory:main: Selected Ciphers   
[TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256, 
TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_
CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256, 
TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_RSA_WITH_AES_128_CBC_SHA256, 
TLS_DHE_DSS_WITH_AES_128_CBC_SHA256, TLS_ECDHE_ECDSA_
WITH_AES_128_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA, 
TLS_DHE_DSS_WITH_AES_128_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, 
TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA
_WITH_AES_128_GCM_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_GCM_SHA256, 
TLS_ECDH_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_RSA_WITH_AES_128_GCM_SHA256, 
TLS_DHE_DSS_WITH_AES_128_GCM_SHA256, TL
S_ECDHE_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_ECDSA_WITH_3DES_EDE_CBC_SHA, 
SSL_DHE_DSS_WITH_3DES_EDE_CBC_SHA, TLS_EMPTY_RENEGOTIATION_INFO_SCSV] of 
[TLS_ECDHE_ECDSA_WITH_AES_128_CB
C_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, 
TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256, 
TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_RSA_WITH_AES
_128_CBC_SHA256, TLS_DHE_DSS_WITH_AES_128_CBC_SHA256, 
TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, 
TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDH_ECDSA_WITH_AE
S_128_CBC_SHA, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA, 
TLS_DHE_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_DSS_WITH_AES_128_CBC_SHA, 
TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AE
S_128_GCM_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, 
TLS_ECDH_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_RSA_WITH_AES_128_GCM_SHA256, 
TLS_DHE_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_DSS_WI
TH_AES_128_GCM_SHA256, TLS_ECDHE_ECDSA_WITH_3DES_EDE_CBC_SHA, 
TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, 
TLS_ECDH_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_RSA
_WITH_3DES_EDE_CBC_SHA, SSL_DHE_RSA_WITH_3DES_EDE_CBC_SHA, 
SSL_DHE_DSS_WITH_3DES_EDE_CBC_SHA, TLS_EMPTY_RENEGOTIATION_INFO_SCSV, 
TLS_DH_anon_WITH_AES_128_GCM_SHA256, TLS_DH_anon_WI
TH_AES_128_CBC_SHA256, TLS_ECDH_anon_WITH_AES_128_CBC_SHA, 
TLS_DH_anon_WITH_AES_128_CBC_SHA, TLS_ECDH_anon_WITH_3DES_EDE_CBC_SHA, 
SSL_DH_anon_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_DE
S_CBC_SHA, SSL_DHE_RSA_WITH_DES_CBC_SHA, SSL_DHE_DSS_WITH_DES_CBC_SHA, 
SSL_DH_anon_WITH_DES_CBC_SHA, SSL_RSA_EXPORT_WITH_DES40_CBC_SHA, 
SSL_DHE_RSA_EXPORT_WITH_DES40_CBC_SHA, SSL_D
HE_DSS_EXPORT_WITH_DES40_CBC_SHA, SSL_DH_anon_EXPORT_WITH_DES40_CBC_SHA, 
TLS_RSA_WITH_NULL_SHA256, TLS_ECDHE_ECDSA_WITH_NULL_SHA, 
TLS_ECDHE_RSA_WITH_NULL_SHA, SSL_RSA_WITH_NULL_SHA
, TLS_ECDH_ECDSA_WITH_NULL_SHA, TLS_ECDH_RSA_WITH_NULL_SHA, 
TLS_ECDH_anon_WITH_NULL_SHA, SSL_RSA_WITH_NULL_MD5, 
TLS_KRB5_WITH_3DES_EDE_CBC_SHA, TLS_KRB5_WITH_3DES_EDE_CBC_MD5, TLS_
KRB5_WITH_DES_CBC_SHA, TLS_KRB5_WITH_DES_CBC_MD5, 
TLS_KRB5_EXPORT_WITH_DES_CBC_40_SHA, TLS_KRB5_EXPORT_WITH_DES_CBC_40_MD5]
2016-06-23 15:41:14.166:DBUG:oejuc.AbstractLifeCycle:main: STARTED @845ms 
SslContextFactory@58372a00(null,null)
2016-06-23 15:41:14.182:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@1a6c5a9e
2016-06-23 15:41:14.182:DBUG:oejuc.AbstractLifeCycle:main: STARTED @854ms 
org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@1a6c5a9e
2016-06-23 15:41:14.182:DBUG:oejwc.WebSocketClient:main: Started 
org.eclipse.jetty.websocket.client.WebSocketClient@7699a589
2016-06-23 15:41:14.182:DBUG:oejuc.AbstractLifeCycle:main: STARTED @858ms 
org.eclipse.jetty.websocket.client.WebSocketClient@7699a589
2016-06-23 15:41:14.197:DBUG:oejwc.WebSocketClient:main: connect websocket 
de.afarber.wssclient.MyListener@20e2cbe0 to wss://127.0.0.1:8443
2016-06-23 15:41:14.213:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.websocket.client.WebSocketClient@7699a589 added 
{WebSocketClient@1989780873{STOPPED,8<=0<=200,i=0,q=0}
,MANAGED}
2016-06-23 15:41:14.213:DBUG:oejuc.AbstractLifeCycle:main: starting 
WebSocketClient@1989780873{STOPPED,8<=0<=200,i=0,q=0}
2016-06-23 15:41:14.213:DBUG:oejuc.AbstractLifeCycle:main: STARTED @887ms 
WebSocketClient@1989780873{STARTED,8<=8<=200,i=7,q=0}
2016-06-23 15:41:14.213:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.websocket.client.WebSocketClient@7699a589 added 
{org.eclipse.jetty.websocket.client.io.ConnectionManag
er@4501b7af,MANAGED}
2016-06-23 15:41:14.229:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.websocket.client.io.ConnectionManager@4501b7af
2016-06-23 15:41:14.229:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.websocket.client.io.ConnectionManager@4501b7af added 
{org.eclipse.jetty.websocket.client.io.WebSocketC
lientSelectorManager@77caeb3e,AUTO}
2016-06-23 15:41:14.229:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.websocket.client.io.WebSocketClientSelectorManager@77caeb3e
2016-06-23 15:41:14.244:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.websocket.client.io.WebSocketClientSelectorManager@77caeb3e 
added {org.eclipse.jetty.io.ManagedSelecto
r@6a41eaa2 id=0 keys=-1 selected=-1,AUTO}
2016-06-23 15:41:14.244:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.websocket.client.io.WebSocketClientSelectorManager@77caeb3e 
added {org.eclipse.jetty.io.ManagedSelecto
r@7cd62f43 id=1 keys=-1 selected=-1,AUTO}
2016-06-23 15:41:14.244:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.io.ManagedSelector@6a41eaa2 id=0 keys=-1 selected=-1
2016-06-23 15:41:14.260:DBUG:oejut.QueuedThreadPool:main: queue 
org.eclipse.jetty.io.ManagedSelector@6a41eaa2 id=0 keys=0 selected=0
2016-06-23 15:41:14.275:DBUG:oejuc.AbstractLifeCycle:main: STARTED @941ms 
org.eclipse.jetty.io.ManagedSelector@6a41eaa2 id=0 keys=0 selected=0
2016-06-23 
15:41:14.275:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-11: run 
org.eclipse.jetty.io.ManagedSelector@6a41eaa2 id=0 keys=0 selected=0
2016-06-23 15:41:14.275:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.io.ManagedSelector@7cd62f43 id=1 keys=-1 selected=-1
2016-06-23 
15:41:14.275:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-11: 
EPC Idle/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d execute
2016-06-23 15:41:14.275:DBUG:oejut.QueuedThreadPool:main: queue 
org.eclipse.jetty.io.ManagedSelector@7cd62f43 id=1 keys=0 selected=0
2016-06-23 15:41:14.275:DBUG:oejuc.AbstractLifeCycle:main: STARTED @954ms 
org.eclipse.jetty.io.ManagedSelector@7cd62f43 id=1 keys=0 selected=0
2016-06-23 
15:41:14.275:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-11: 
EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d produce 
enter
2016-06-23 
15:41:14.291:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-11: 
EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d 
producing
2016-06-23 15:41:14.291:DBUG:oejuc.AbstractLifeCycle:main: STARTED @956ms 
org.eclipse.jetty.websocket.client.io.WebSocketClientSelectorManager@77caeb3e
2016-06-23 
15:41:14.275:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-12: run 
org.eclipse.jetty.io.ManagedSelector@7cd62f43 id=1 keys=0 selected=0
2016-06-23 
15:41:14.291:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-12: 
EPC Idle/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@4166316 execute
2016-06-23 15:41:14.291:DBUG:oejuc.AbstractLifeCycle:main: STARTED @964ms 
org.eclipse.jetty.websocket.client.io.ConnectionManager@4501b7af
2016-06-23 
15:41:14.291:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-11: Selector 
loop waiting on select
2016-06-23 
15:41:14.291:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-12: 
EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@4166316 produce 
enter
2016-06-23 
15:41:14.307:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-12: 
EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@4166316 producing
2016-06-23 
15:41:14.307:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-12: Selector 
loop waiting on select
2016-06-23 15:41:14.307:DBUG:oejwc.WebSocketClient:main: Connect Promise: 
FutureCallback@6500df86{false,false,null}
2016-06-23 15:41:14.322:DBUG:oejut.QueuedThreadPool:main: queue 
FutureCallback@6500df86{false,false,null}
2016-06-23 
15:41:14.322:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-13: run 
FutureCallback@6500df86{false,false,null}
2016-06-23 
15:41:14.322:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-13: Queued 
change org.eclipse.jetty.io.ManagedSelector$Connect@73ad0028 on 
org.eclipse.jetty.io.Managed
Selector@6a41eaa2 id=0 keys=0 selected=0
2016-06-23 
15:41:14.338:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-11: Selector 
loop woken up from select, 0/0 selected
2016-06-23 
15:41:14.338:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-13: ran 
FutureCallback@6500df86{false,false,null}
2016-06-23 
15:41:14.338:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-11: Running 
change org.eclipse.jetty.io.ManagedSelector$Connect@73ad0028
2016-06-23 
15:41:14.338:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-11: Selector 
loop waiting on select
2016-06-23 
15:41:14.338:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-11: Selector 
loop woken up from select, 1/1 selected
2016-06-23 
15:41:14.353:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-11: Connected 
true java.nio.channels.SocketChannel[connected local=/127.0.0.1:57661 
remote=/127.0.0.1:8
443]
2016-06-23 
15:41:14.353:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-11: 
EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d 
produced org.eclips
e.jetty.io.ManagedSelector$1@165a9494
2016-06-23 
15:41:14.353:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-11: 
EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d dispatch
2016-06-23 
15:41:14.353:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-11: queue 
EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d
2016-06-23 
15:41:14.369:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-11: 
EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d run 
org.eclipse.jet
ty.io.ManagedSelector$1@165a9494
2016-06-23 
15:41:14.369:DBUG:oejwci.WebSocketClientSelectorManager:WebSocketClient@1989780873-11:
 newEndPoint(java.nio.channels.SocketChannel[connected local=/127.0.0.1:57661 
remot
e=/127.0.0.1:8443], org.eclipse.jetty.io.ManagedSelector@6a41eaa2 id=0 keys=1 
selected=1, sun.nio.ch.SelectionKeyImpl@44f8702a)
2016-06-23 
15:41:14.369:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-14: run EPC 
Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d
2016-06-23 
15:41:14.400:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-14: 
EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d run
2016-06-23 
15:41:14.400:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-14: 
EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d produce 
enter
2016-06-23 
15:41:14.416:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-14: 
EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d 
producing
2016-06-23 
15:41:14.400:DBUG:oeji.AbstractEndPoint:WebSocketClient@1989780873-11: onOpen 
SelectChannelEndPoint@2e9d560a{/127.0.0.1:8443<->57661,Open,in,out,-,-,15/300000,null@0}{io
=0/0,kio=0,kro=8}
2016-06-23 15:41:14.416:DBUG:oeji.IdleTimeout:WebSocketClient@1989780873-11: 
SelectChannelEndPoint@2e9d560a{/127.0.0.1:8443<->57661,Open,in,out,-,-,31/300000,null@0}{io=0/0,kio=0,k
ro=8} idle timeout check, elapsed: 31 ms, remaining: 299969 ms
2016-06-23 
15:41:14.416:DBUG:oejwci.WebSocketClientSelectorManager:WebSocketClient@1989780873-11:
 newConnection(java.nio.channels.SocketChannel[connected local=/127.0.0.1:57661 
rem
ote=/127.0.0.1:8443],SelectChannelEndPoint@2e9d560a{/127.0.0.1:8443<->57661,Open,in,out,-,-,31/300000,null@0}{io=0/0,kio=0,kro=8},FutureCallback@6500df86{false,false,null})
2016-06-23 
15:41:14.416:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-14: Selector 
loop waiting on select
Using SSLEngineImpl.
Ignoring unavailable cipher suite: TLS_DHE_DSS_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_RSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_DHE_RSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_RSA_WITH_AES_256_CBC_SHA256
Ignoring unavailable cipher suite: TLS_DHE_DSS_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDH_ECDSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_RSA_WITH_AES_256_CBC_SHA384
Ignoring unavailable cipher suite: TLS_RSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_ECDSA_WITH_AES_256_CBC_SHA384
Ignoring unavailable cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384
Ignoring unavailable cipher suite: TLS_ECDH_RSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDH_RSA_WITH_AES_256_GCM_SHA384
Ignoring unavailable cipher suite: TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA384
Ignoring unavailable cipher suite: TLS_DHE_RSA_WITH_AES_256_CBC_SHA256
Ignoring unavailable cipher suite: TLS_ECDHE_ECDSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_DHE_DSS_WITH_AES_256_CBC_SHA256
Ignoring unavailable cipher suite: TLS_DHE_RSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA
Ignoring unavailable cipher suite: TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384
2016-06-23 
15:41:14.478:DBUG:oejus.SslContextFactory:WebSocketClient@1989780873-11: 
Customize 539458e3[SSLEngine[hostname=127.0.0.1 port=8443] 
SSL_NULL_WITH_NULL_NULL]
2016-06-23 
15:41:14.494:DBUG:oeji.AbstractConnection:WebSocketClient@1989780873-11: onOpen 
SslConnection@14f4f541{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> 
UpgradeConnection@4d726355[Dec
ryptedEndPoint@4c22bcf3{127.0.0.1/127.0.0.1:8443<->57661,Open,in,out,-,-,0/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,Open,
in,out,-,-,109/300000,SslConnection@14f4f541}{io=0/0,kio=0,kro=8}]
2016-06-23 
15:41:14.509:DBUG:oeji.AbstractConnection:WebSocketClient@1989780873-11: onOpen 
UpgradeConnection@4d726355[DecryptedEndPoint@4c22bcf3{127.0.0.1/127.0.0.1:8443<->57661,Op
en,in,out,-,-,15/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,Open,in,out,-,-,124/300000,SslConnection@14f4f541}{io=0/0,kio=0
,kro=8}]
2016-06-23 
15:41:14.509:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-11: queue 
FutureCallback@5edaeb21{false,false}
2016-06-23 
15:41:14.509:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-15: run 
FutureCallback@5edaeb21{false,false}
2016-06-23 
15:41:14.509:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-11: Created 
SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,Open,in,out,-,-,124/300000,
SslConnection@14f4f541}{io=0/0,kio=0,kro=8}
2016-06-23 15:41:14.525:DBUG:oeji.WriteFlusher:WebSocketClient@1989780873-15: 
write: WriteFlusher@2ecd4a5a{IDLE} 
[HeapByteBuffer@200ab02b[p=0,l=196,c=196,r=196]={<<<GET / HTTP/1.1\
r\nH...l: no-cache\r\n\r\n>>>}]
2016-06-23 
15:41:14.525:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-11: 
EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d ran 
org.eclipse.jet
ty.io.ManagedSelector$1@165a9494
2016-06-23 15:41:14.525:DBUG:oeji.WriteFlusher:WebSocketClient@1989780873-15: 
update WriteFlusher@2ecd4a5a{WRITING}:IDLE-->WRITING
2016-06-23 
15:41:14.525:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-11: 
EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d produce 
exit
2016-06-23 
15:41:14.541:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-11: ran 
org.eclipse.jetty.io.ManagedSelector@6a41eaa2 id=0 keys=1 selected=0
2016-06-23 15:41:14.541:DBUG:oejis.SslConnection:WebSocketClient@1989780873-15: 
SslConnection@14f4f541{NOT_HANDSHAKING,eio=-1/-1,di=-1} -> 
UpgradeConnection@4d726355[DecryptedEndPo
int@4c22bcf3{127.0.0.1/127.0.0.1:8443<->57661,Open,in,out,-,W,47/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,Open,in,out,-,-
,156/300000,SslConnection@14f4f541}{io=0/0,kio=0,kro=8}] flush 
b[196]=474554202F20485454502F312E310D0A486F73743A20313237...6368650D0A0D0A
Allow unsafe renegotiation: false
Allow legacy hello messages: true
Is initial handshake: true
Is secure renegotiation: false
Ignoring unsupported cipher suite: TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256 for 
TLSv1
Ignoring unsupported cipher suite: TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256 for 
TLSv1
Ignoring unsupported cipher suite: TLS_RSA_WITH_AES_128_CBC_SHA256 for TLSv1
Ignoring unsupported cipher suite: TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256 for 
TLSv1
Ignoring unsupported cipher suite: TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256 for 
TLSv1
Ignoring unsupported cipher suite: TLS_DHE_RSA_WITH_AES_128_CBC_SHA256 for TLSv1
Ignoring unsupported cipher suite: TLS_DHE_DSS_WITH_AES_128_CBC_SHA256 for TLSv1
Ignoring unsupported cipher suite: TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256 for 
TLSv1.1
Ignoring unsupported cipher suite: TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256 for 
TLSv1.1
Ignoring unsupported cipher suite: TLS_RSA_WITH_AES_128_CBC_SHA256 for TLSv1.1
Ignoring unsupported cipher suite: TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256 for 
TLSv1.1
Ignoring unsupported cipher suite: TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256 for 
TLSv1.1
Ignoring unsupported cipher suite: TLS_DHE_RSA_WITH_AES_128_CBC_SHA256 for 
TLSv1.1
Ignoring unsupported cipher suite: TLS_DHE_DSS_WITH_AES_128_CBC_SHA256 for 
TLSv1.1
%% No cached client session
*** ClientHello, TLSv1.2
RandomCookie:  GMT: 1466623482 bytes = { 220, 161, 20, 86, 110, 198, 107, 30, 
187, 123, 154, 212, 125, 190, 143, 42, 125, 64, 98, 48, 120, 102, 226, 63, 89, 
42, 114, 4 }
Session ID:  {}
Cipher Suites: [TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256, 
TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, 
TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_RS
A_WITH_AES_128_CBC_SHA256, TLS_DHE_RSA_WITH_AES_128_CBC_SHA256, 
TLS_DHE_DSS_WITH_AES_128_CBC_SHA256, TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA, 
TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA, TLS_
DHE_DSS_WITH_AES_128_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, 
TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, 
TLS_ECDH_ECDSA_WITH_AES_128_GCM_SHA25
6, TLS_ECDH_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_RSA_WITH_AES_128_GCM_SHA256, 
TLS_DHE_DSS_WITH_AES_128_GCM_SHA256, TLS_ECDHE_ECDSA_WITH_3DES_EDE_CBC_SHA, 
TLS_ECDH_ECDSA_WITH_3DES_E
DE_CBC_SHA, SSL_DHE_DSS_WITH_3DES_EDE_CBC_SHA, 
TLS_EMPTY_RENEGOTIATION_INFO_SCSV]
Compression Methods:  { 0 }
Extension elliptic_curves, curve names: {secp256r1, sect163k1, sect163r2, 
secp192r1, secp224r1, sect233k1, sect233r1, sect283k1, sect283r1, secp384r1, 
sect409k1, sect409r1, secp521
r1, sect571k1, sect571r1, secp160k1, secp160r1, secp160r2, sect163r1, 
secp192k1, sect193r1, sect193r2, secp224k1, sect239k1, secp256k1}
Extension ec_point_formats, formats: [uncompressed]
Extension signature_algorithms, signature_algorithms: SHA512withECDSA, 
SHA512withRSA, SHA384withECDSA, SHA384withRSA, SHA256withECDSA, SHA256withRSA, 
SHA224withECDSA, SHA224withRSA
, SHA1withECDSA, SHA1withRSA, SHA1withDSA, MD5withRSA
***
WebSocketClient@1989780873-15, WRITE: TLSv1.2 Handshake, length = 179
2016-06-23 15:41:14.665:DBUG:oejis.SslConnection:WebSocketClient@1989780873-15: 
SslConnection@14f4f541{NEED_UNWRAP,eio=-1/184,di=-1} -> 
UpgradeConnection@4d726355[DecryptedEndPoint
@4c22bcf3{127.0.0.1/127.0.0.1:8443<->57661,Open,in,out,-,W,171/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,Open,in,out,-,-,2
80/300000,SslConnection@14f4f541}{io=0/0,kio=0,kro=8}] wrap Status = OK 
HandshakeStatus = NEED_UNWRAP bytesConsumed = 0 bytesProduced = 184
2016-06-23 15:41:14.665:DBUG:oejis.SslConnection:WebSocketClient@1989780873-15: 
SslConnection@14f4f541{NEED_UNWRAP,eio=-1/184,di=-1} -> 
UpgradeConnection@4d726355[DecryptedEndPoint
@4c22bcf3{127.0.0.1/127.0.0.1:8443<->57661,Open,in,out,-,W,171/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,Open,in,out,-,-,2
80/300000,SslConnection@14f4f541}{io=0/0,kio=0,kro=8}] wrap OK 
b[184]=16030300B3010000AF0303576BE6FADCA114566EC66B1EBB7B...03020102020101
2016-06-23 
15:41:14.681:DBUG:oeji.ChannelEndPoint:WebSocketClient@1989780873-15: flushed 
184 
SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,Open,in,out,-,-,296/300
000,SslConnection@14f4f541}{io=0/0,kio=0,kro=8}
2016-06-23 
15:41:14.681:DBUG:oeji.ChannelEndPoint:WebSocketClient@1989780873-15: filled -1 
SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,Open,in,out,-,-,0/300000,
SslConnection@14f4f541}{io=0/0,kio=0,kro=8}
2016-06-23 
15:41:14.681:DBUG:oeji.ChannelEndPoint:WebSocketClient@1989780873-15: ishut 
SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,Open,in,out,-,-,16/300000,Ssl
Connection@14f4f541}{io=0/0,kio=0,kro=8}
2016-06-23 15:41:14.697:DBUG:oejis.SslConnection:WebSocketClient@1989780873-15: 
SslConnection@14f4f541{NEED_UNWRAP,eio=0/0,di=0} -> 
UpgradeConnection@4d726355[DecryptedEndPoint@4c2
2bcf3{127.0.0.1/127.0.0.1:8443<->57661,Open,in,out,-,W,203/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,Open,ISHUT,out,-,-,16
/300000,SslConnection@14f4f541}{io=0/0,kio=0,kro=8}] net=-1 unwrap Status = 
BUFFER_UNDERFLOW HandshakeStatus = NEED_UNWRAP bytesConsumed = 0 bytesProduced 
= 0
2016-06-23 15:41:14.697:DBUG:oejis.SslConnection:WebSocketClient@1989780873-15: 
SslConnection@14f4f541{NEED_UNWRAP,eio=0/0,di=0} -> 
UpgradeConnection@4d726355[DecryptedEndPoint@4c2
2bcf3{127.0.0.1/127.0.0.1:8443<->57661,Open,in,out,-,W,203/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,Open,ISHUT,out,-,-,16
/300000,SslConnection@14f4f541}{io=0/0,kio=0,kro=8}] filled b[0]=
WebSocketClient@1989780873-15, called closeInbound()
WebSocketClient@1989780873-15, fatal error: 80: Inbound closed before receiving 
peer's close_notify: possible truncation attack?
javax.net.ssl.SSLException: Inbound closed before receiving peer's 
close_notify: possible truncation attack?
WebSocketClient@1989780873-15, SEND TLSv1.2 ALERT:  fatal, description = 
internal_error
WebSocketClient@1989780873-15, WRITE: TLSv1.2 Alert, length = 2
2016-06-23 
15:41:14.728:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-15: queue 
org.eclipse.jetty.io.ssl.SslConnection$1@7a5bf222
2016-06-23 
15:41:14.728:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-16: run 
org.eclipse.jetty.io.ssl.SslConnection$1@7a5bf222
2016-06-23 15:41:14.728:DBUG:oeji.WriteFlusher:WebSocketClient@1989780873-16: 
completeWrite: WriteFlusher@2ecd4a5a{WRITING}
2016-06-23 
15:41:14.728:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-16: ran 
org.eclipse.jetty.io.ssl.SslConnection$1@7a5bf222
2016-06-23 15:41:14.728:DBUG:oeji.WriteFlusher:WebSocketClient@1989780873-15: 
Flushed=false 0/196+0 WriteFlusher@2ecd4a5a{WRITING}
2016-06-23 15:41:14.728:DBUG:oeji.WriteFlusher:WebSocketClient@1989780873-15: 
!fully flushed WriteFlusher@2ecd4a5a{WRITING}
2016-06-23 15:41:14.728:DBUG:oeji.WriteFlusher:WebSocketClient@1989780873-15: 
flushed incomplete
2016-06-23 15:41:14.743:DBUG:oeji.WriteFlusher:WebSocketClient@1989780873-15: 
update WriteFlusher@2ecd4a5a{PENDING}:WRITING-->PENDING
2016-06-23 15:41:14.743:DBUG:oejis.SslConnection:WebSocketClient@1989780873-15: 
onIncompleteFlush SslConnection@14f4f541{NEED_WRAP,eio=-1/-1,di=-1} -> 
UpgradeConnection@4d726355[De
cryptedEndPoint@4c22bcf3{127.0.0.1/127.0.0.1:8443<->57661,Open,ISHUT,out,-,P,249/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661
,Open,ISHUT,out,-,-,62/300000,SslConnection@14f4f541}{io=0/0,kio=0,kro=8}]
2016-06-23 
15:41:14.743:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-15: queue 
org.eclipse.jetty.io.ssl.SslConnection$1@7a5bf222
2016-06-23 
15:41:14.743:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-17: run 
org.eclipse.jetty.io.ssl.SslConnection$1@7a5bf222
2016-06-23 
15:41:14.743:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-15: ran 
FutureCallback@5edaeb21{false,false}
2016-06-23 15:41:14.759:DBUG:oeji.WriteFlusher:WebSocketClient@1989780873-17: 
completeWrite: WriteFlusher@2ecd4a5a{PENDING}
2016-06-23 15:41:14.759:DBUG:oeji.WriteFlusher:WebSocketClient@1989780873-17: 
update WriteFlusher@2ecd4a5a{COMPLETING}:PENDING-->COMPLETING
2016-06-23 15:41:14.759:DBUG:oejis.SslConnection:WebSocketClient@1989780873-17: 
SslConnection@14f4f541{NEED_WRAP,eio=-1/-1,di=-1} -> 
UpgradeConnection@4d726355[DecryptedEndPoint@4c
22bcf3{127.0.0.1/127.0.0.1:8443<->57661,Open,ISHUT,out,-,C,265/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,Open,ISHUT,out,-,
-,78/300000,SslConnection@14f4f541}{io=0/0,kio=0,kro=8}] flush 
b[196]=474554202F20485454502F312E310D0A486F73743A20313237...6368650D0A0D0A
2016-06-23 15:41:14.775:DBUG:oejis.SslConnection:WebSocketClient@1989780873-17: 
SslConnection@14f4f541{NEED_UNWRAP,eio=-1/7,di=-1} -> 
UpgradeConnection@4d726355[DecryptedEndPoint@4
c22bcf3{127.0.0.1/127.0.0.1:8443<->57661,Open,ISHUT,OSHUT,-,C,281/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,Open,ISHUT,out
,-,-,94/300000,SslConnection@14f4f541}{io=0/0,kio=0,kro=8}] wrap Status = 
CLOSED HandshakeStatus = NEED_UNWRAP bytesConsumed = 0 bytesProduced = 7
2016-06-23 
15:41:14.775:DBUG:oeji.ChannelEndPoint:WebSocketClient@1989780873-17: flushed 7 
SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,Open,ISHUT,out,-,-,94/300
000,SslConnection@14f4f541}{io=0/0,kio=0,kro=8}
2016-06-23 
15:41:14.775:DBUG:oeji.ChannelEndPoint:WebSocketClient@1989780873-17: oshut 
SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,Open,ISHUT,out,-,-,0/300000,S
slConnection@14f4f541}{io=0/0,kio=0,kro=8}
2016-06-23 15:41:14.790:DBUG:oeji.WriteFlusher:WebSocketClient@1989780873-17: 
ignored: WriteFlusher@5299068e{IDLE} java.nio.channels.ClosedChannelException
2016-06-23 
15:41:14.790:DBUG:oeji.ChannelEndPoint:WebSocketClient@1989780873-17: close 
SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,-,-,15/300
000,SslConnection@14f4f541}{io=0/0,kio=0,kro=8}
2016-06-23 
15:41:14.790:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-17: Queued 
change org.eclipse.jetty.io.ManagedSelector$2@1d63e896 on 
org.eclipse.jetty.io.ManagedSelect
or@6a41eaa2 id=0 keys=1 selected=0
2016-06-23 15:41:14.790:DBUG:oeji.WriteFlusher:WebSocketClient@1989780873-17: 
Flushed=false 0/196+0 WriteFlusher@2ecd4a5a{COMPLETING}
2016-06-23 
15:41:14.806:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-14: Selector 
loop woken up from select, 0/0 selected
2016-06-23 15:41:14.806:DBUG:oeji.WriteFlusher:WebSocketClient@1989780873-17: 
!fully flushed WriteFlusher@2ecd4a5a{COMPLETING}
2016-06-23 
15:41:14.806:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-14: 
EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d 
produced org.eclips
e.jetty.io.ManagedSelector$2@1d63e896
2016-06-23 15:41:14.806:DBUG:oeji.WriteFlusher:WebSocketClient@1989780873-17: 
flushed incomplete [HeapByteBuffer@200ab02b[p=0,l=196,c=196,r=196]={<<<GET / 
HTTP/1.1\r\nH...l: no-cac
he\r\n\r\n>>>}]
2016-06-23 
15:41:14.806:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-14: 
EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d dispatch
2016-06-23 15:41:14.821:DBUG:oeji.WriteFlusher:WebSocketClient@1989780873-17: 
update WriteFlusher@2ecd4a5a{PENDING}:COMPLETING-->PENDING
2016-06-23 
15:41:14.821:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-14: queue 
EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d
2016-06-23 15:41:14.821:DBUG:oejis.SslConnection:WebSocketClient@1989780873-17: 
onIncompleteFlush SslConnection@14f4f541{NEED_UNWRAP,eio=-1/-1,di=-1} -> 
UpgradeConnection@4d726355[
DecryptedEndPoint@4c22bcf3{127.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,-,P,327/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<-
>57661,CLOSED,ISHUT,OSHUT,-,-,46/300000,SslConnection@14f4f541}{io=0/0,kio=-1,kro=-1}]
2016-06-23 
15:41:14.821:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-18: run EPC 
Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d
2016-06-23 
15:41:14.821:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-18: 
EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d run
2016-06-23 
15:41:14.837:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-18: 
EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d produce 
enter
2016-06-23 
15:41:14.821:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-14: 
EPC Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d run 
org.eclipse.jet
ty.io.ManagedSelector$2@1d63e896
2016-06-23 
15:41:14.837:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-18: 
EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d 
producing
2016-06-23 
15:41:14.837:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-18: Selector 
loop waiting on select
2016-06-23 
15:41:14.821:DBUG:oeji.AbstractConnection:WebSocketClient@1989780873-17: 
fillInterested SslConnection@14f4f541{NEED_UNWRAP,eio=-1/-1,di=-1} -> 
UpgradeConnection@4d726355
[DecryptedEndPoint@4c22bcf3{127.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,-,P,327/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<
->57661,CLOSED,ISHUT,OSHUT,-,-,46/300000,SslConnection@14f4f541}{io=0/0,kio=-1,kro=-1}]
2016-06-23 
15:41:14.837:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-14: Destroyed 
SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,-,-,62
/300000,SslConnection@14f4f541}{io=0/0,kio=-1,kro=-1}
2016-06-23 15:41:14.853:DBUG:oeji.FillInterest:WebSocketClient@1989780873-17: 
FillInterest@7d430c2{true,AC.ReadCB@14f4f541{SslConnection@14f4f541{NEED_UNWRAP,eio=-1/-1,di=-1}
 -> Up
gradeConnection@4d726355[DecryptedEndPoint@4c22bcf3{127.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,-,P,359/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{1
27.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,FI,-,0/300000,SslConnection@14f4f541}{io=0/0,kio=-1,kro=-1}]}}
 register AC.ReadCB@14f4f541{SslConnection@14f4f541{NEED_UNWRAP,eio
=-1/-1,di=-1} -> 
UpgradeConnection@4d726355[DecryptedEndPoint@4c22bcf3{127.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,-,P,359/300000,UpgradeConnection@4d726355}->SelectChannel
EndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,FI,-,0/300000,SslConnection@14f4f541}{io=0/0,kio=-1,kro=-1}]}
2016-06-23 
15:41:14.853:DBUG:oejwci.UpgradeConnection:WebSocketClient@1989780873-14: 
Closed connection 
UpgradeConnection@4d726355[DecryptedEndPoint@4c22bcf3{127.0.0.1/127.0.0.1:844
3<->57661,CLOSED,ISHUT,OSHUT,-,P,359/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,FI,-,0/300000,SslConnect
ion@14f4f541}{io=0/0,kio=-1,kro=-1}]
2016-06-23 15:41:14.868:DBUG:oeji.FillInterest:WebSocketClient@1989780873-17: 
FillInterest@7d430c2{true,AC.ReadCB@14f4f541{SslConnection@14f4f541{NEED_UNWRAP,eio=-1/-1,di=-1}
 -> Up
gradeConnection@4d726355[DecryptedEndPoint@4c22bcf3{127.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,-,P,374/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{1
27.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,FI,-,15/300000,SslConnection@14f4f541}{io=0/0,kio=-1,kro=-1}]}}
 register AC.ReadCB@14f4f541{SslConnection@14f4f541{NEED_UNWRAP,ei
o=-1/-1,di=-1} -> 
UpgradeConnection@4d726355[DecryptedEndPoint@4c22bcf3{127.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,-,P,374/300000,UpgradeConnection@4d726355}->SelectChanne
lEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,FI,-,15/300000,SslConnection@14f4f541}{io=0/0,kio=-1,kro=-1}]}
2016-06-23 
15:41:14.868:DBUG:oeji.AbstractConnection:WebSocketClient@1989780873-14: 
onClose 
UpgradeConnection@4d726355[DecryptedEndPoint@4c22bcf3{127.0.0.1/127.0.0.1:8443<->57661,C
LOSED,ISHUT,OSHUT,-,P,374/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,FI,-,15/300000,SslConnection@14f4f5
41}{io=0/0,kio=-1,kro=-1}]
2016-06-23 
15:41:14.884:DBUG:oeji.SelectChannelEndPoint:WebSocketClient@1989780873-17: 
changeInterests p=false 0->1 for 
SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57
661,CLOSED,ISHUT,OSHUT,FI,-,31/300000,SslConnection@14f4f541}{io=0/1,kio=-1,kro=-1}
2016-06-23 
15:41:14.884:DBUG:oeji.AbstractConnection:WebSocketClient@1989780873-14: 
onClose SslConnection@14f4f541{NEED_UNWRAP,eio=-1/-1,di=-1} -> 
UpgradeConnection@4d726355[Decryp
tedEndPoint@4c22bcf3{127.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,-,P,390/300000,UpgradeConnection@4d726355}->SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661
,CLOSED,ISHUT,OSHUT,FI,-,31/300000,SslConnection@14f4f541}{io=0/1,kio=-1,kro=-1}]
2016-06-23 
15:41:14.899:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-17: Queued 
change 
SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,FI
,-,46/300000,SslConnection@14f4f541}{io=0/1,kio=-1,kro=-1}:runUpdateKey on 
org.eclipse.jetty.io.ManagedSelector@6a41eaa2 id=0 keys=0 selected=0
2016-06-23 
15:41:14.899:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-14: 
EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d ran 
org.eclipse.jet
ty.io.ManagedSelector$2@1d63e896
2016-06-23 
15:41:14.899:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-18: Selector 
loop woken up from select, 0/0 selected
2016-06-23 
15:41:14.899:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-17: ran 
org.eclipse.jetty.io.ssl.SslConnection$1@7a5bf222
2016-06-23 
15:41:14.915:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-18: Running 
change 
SelectChannelEndPoint@2e9d560a{127.0.0.1/127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,F
I,-,62/300000,SslConnection@14f4f541}{io=0/1,kio=-1,kro=-1}:runUpdateKey
2016-06-23 
15:41:14.915:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-14: 
EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d produce 
exit
2016-06-23 
15:41:14.915:DBUG:oeji.SelectChannelEndPoint:WebSocketClient@1989780873-18: 
Ignoring key update for concurrently closed channel 
SelectChannelEndPoint@2e9d560a{127.0.0.1/
127.0.0.1:8443<->57661,CLOSED,ISHUT,OSHUT,FI,-,62/300000,SslConnection@14f4f541}{io=1/1,kio=-1,kro=-1}
2016-06-23 
15:41:14.915:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-14: ran EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d
2016-06-23 
15:41:14.931:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-18: Selector 
loop waiting on select
2016-06-23 15:41:19.333:DBUG:oejuc.AbstractLifeCycle:main: stopping 
org.eclipse.jetty.websocket.client.WebSocketClient@7699a589
2016-06-23 15:41:19.333:DBUG:oejwc.WebSocketClient:main: Stopping 
org.eclipse.jetty.websocket.client.WebSocketClient@7699a589
2016-06-23 15:41:19.333:DBUG:oejuc.AbstractLifeCycle:main: stopping 
org.eclipse.jetty.websocket.client.io.ConnectionManager@4501b7af
2016-06-23 15:41:19.333:DBUG:oejuc.AbstractLifeCycle:main: stopping 
org.eclipse.jetty.websocket.client.io.WebSocketClientSelectorManager@77caeb3e
2016-06-23 15:41:19.333:DBUG:oejuc.AbstractLifeCycle:main: stopping 
org.eclipse.jetty.io.ManagedSelector@7cd62f43 id=1 keys=0 selected=0
2016-06-23 15:41:19.333:DBUG:oeji.ManagedSelector:main: Stopping 
org.eclipse.jetty.io.ManagedSelector@7cd62f43 id=1 keys=0 selected=0
2016-06-23 15:41:19.349:DBUG:oeji.ManagedSelector:main: Queued change 
org.eclipse.jetty.io.ManagedSelector$CloseEndPoints@4cf777e8 on 
org.eclipse.jetty.io.ManagedSelector@7cd62f43
id=1 keys=0 selected=0
2016-06-23 
15:41:19.349:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-12: Selector 
loop woken up from select, 0/0 selected
2016-06-23 
15:41:19.349:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-12: Running 
change org.eclipse.jetty.io.ManagedSelector$CloseEndPoints@4cf777e8
2016-06-23 
15:41:19.349:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-12: Closing 0 
endPoints on org.eclipse.jetty.io.ManagedSelector@7cd62f43 id=1 keys=0 
selected=0
2016-06-23 
15:41:19.364:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-12: Closed 0 
endPoints on org.eclipse.jetty.io.ManagedSelector@7cd62f43 id=1 keys=0 
selected=0
2016-06-23 
15:41:19.364:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-12: Selector 
loop waiting on select
2016-06-23 15:41:19.364:DBUG:oeji.ManagedSelector:main: Queued change 
org.eclipse.jetty.io.ManagedSelector$CloseSelector@73ad2d6 on 
org.eclipse.jetty.io.ManagedSelector@7cd62f43 id
=1 keys=0 selected=0
2016-06-23 
15:41:19.364:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-12: Selector 
loop woken up from select, 0/0 selected
2016-06-23 
15:41:19.364:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-12: Running 
change org.eclipse.jetty.io.ManagedSelector$CloseSelector@73ad2d6
2016-06-23 15:41:19.364:DBUG:oeji.ManagedSelector:main: Stopped 
org.eclipse.jetty.io.ManagedSelector@7cd62f43 id=1 keys=-1 selected=-1
2016-06-23 
15:41:19.364:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-12: 
EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@4166316 produced 
null
2016-06-23 15:41:19.380:DBUG:oejuc.AbstractLifeCycle:main: STOPPED 
org.eclipse.jetty.io.ManagedSelector@7cd62f43 id=1 keys=-1 selected=-1
2016-06-23 15:41:19.380:DBUG:oejuc.AbstractLifeCycle:main: stopping 
org.eclipse.jetty.io.ManagedSelector@6a41eaa2 id=0 keys=0 selected=0
2016-06-23 15:41:19.380:DBUG:oeji.ManagedSelector:main: Stopping 
org.eclipse.jetty.io.ManagedSelector@6a41eaa2 id=0 keys=0 selected=0
2016-06-23 15:41:19.380:DBUG:oeji.ManagedSelector:main: Queued change 
org.eclipse.jetty.io.ManagedSelector$CloseEndPoints@7085bdee on 
org.eclipse.jetty.io.ManagedSelector@6a41eaa2
id=0 keys=0 selected=0
2016-06-23 
15:41:19.380:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-12: 
EPC Idle/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@4166316 produce 
exit
2016-06-23 
15:41:19.395:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-12: ran 
org.eclipse.jetty.io.ManagedSelector@7cd62f43 id=1 keys=-1 selected=-1
2016-06-23 
15:41:19.380:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-18: Selector 
loop woken up from select, 0/0 selected
2016-06-23 
15:41:19.395:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-18: Running 
change org.eclipse.jetty.io.ManagedSelector$CloseEndPoints@7085bdee
2016-06-23 
15:41:19.395:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-18: Closing 0 
endPoints on org.eclipse.jetty.io.ManagedSelector@6a41eaa2 id=0 keys=0 
selected=0
2016-06-23 
15:41:19.395:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-18: Closed 0 
endPoints on org.eclipse.jetty.io.ManagedSelector@6a41eaa2 id=0 keys=0 
selected=0
2016-06-23 
15:41:19.411:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-18: Selector 
loop waiting on select
2016-06-23 15:41:19.395:DBUG:oeji.ManagedSelector:main: Queued change 
org.eclipse.jetty.io.ManagedSelector$CloseSelector@1ce92674 on 
org.eclipse.jetty.io.ManagedSelector@6a41eaa2 i
d=0 keys=0 selected=0
2016-06-23 
15:41:19.411:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-18: Selector 
loop woken up from select, 0/0 selected
2016-06-23 
15:41:19.411:DBUG:oeji.ManagedSelector:WebSocketClient@1989780873-18: Running 
change org.eclipse.jetty.io.ManagedSelector$CloseSelector@1ce92674
2016-06-23 
15:41:19.411:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-18: 
EPC Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d 
produced null
2016-06-23 
15:41:19.411:DBUG:oejuts.ExecuteProduceConsume:WebSocketClient@1989780873-18: 
EPC Idle/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d produce 
exit
2016-06-23 
15:41:19.427:DBUG:oejut.QueuedThreadPool:WebSocketClient@1989780873-18: ran EPC 
Idle/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@3dc1769d
2016-06-23 15:41:19.411:DBUG:oeji.ManagedSelector:main: Stopped 
org.eclipse.jetty.io.ManagedSelector@6a41eaa2 id=0 keys=-1 selected=-1
2016-06-23 15:41:19.427:DBUG:oejuc.AbstractLifeCycle:main: STOPPED 
org.eclipse.jetty.io.ManagedSelector@6a41eaa2 id=0 keys=-1 selected=-1
2016-06-23 15:41:19.427:DBUG:oejuc.AbstractLifeCycle:main: STOPPED 
org.eclipse.jetty.websocket.client.io.WebSocketClientSelectorManager@77caeb3e
2016-06-23 15:41:19.427:DBUG:oejuc.AbstractLifeCycle:main: STOPPED 
org.eclipse.jetty.websocket.client.io.ConnectionManager@4501b7af
2016-06-23 15:41:19.427:DBUG:oejuc.AbstractLifeCycle:main: stopping 
WebSocketClient@1989780873{STARTED,8<=8<=200,i=8,q=0}
2016-06-23 15:41:19.442:DBUG:oejuc.AbstractLifeCycle:main: STOPPED 
WebSocketClient@1989780873{STOPPED,8<=8<=200,i=0,q=0}
2016-06-23 15:41:19.442:DBUG:oejuc.AbstractLifeCycle:main: stopping 
org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@1a6c5a9e
2016-06-23 15:41:19.442:DBUG:oejuc.AbstractLifeCycle:main: STOPPED 
org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@1a6c5a9e
2016-06-23 15:41:19.442:DBUG:oejuc.AbstractLifeCycle:main: stopping 
SslContextFactory@58372a00(null,null)
2016-06-23 15:41:19.442:DBUG:oejuc.AbstractLifeCycle:main: STOPPED 
SslContextFactory@58372a00(null,null)
2016-06-23 15:41:19.442:DBUG:oejwc.WebSocketClient:main: Stopped 
org.eclipse.jetty.websocket.client.WebSocketClient@7699a589
2016-06-23 15:41:19.458:DBUG:oejuc.AbstractLifeCycle:main: STOPPED 
org.eclipse.jetty.websocket.client.WebSocketClient@7699a589

NetBeans: JPDA Listening Start...
JPDA Address: XXX:57643
Port:57643
cd C:\Users\user1\jetty-newbie\WssEmbedded; "JAVA_HOME=C:\\Program 
Files\\Java\\jdk1.8.0_66" cmd /c "\"\"C:\\Program Files\\NetBeans 
8.0.2\\java\\maven\\bin\\mvn.bat\" -Dexec.args=\"-Xdebug 
-Xrunjdwp:transport=dt_socket,server=n,address=57643 -classpath %classpath 
de.afarber.wssembedded.MyHandler\" -Dexec.executable=\"C:\\Program 
Files\\Java\\jdk1.8.0_66\\bin\\java.exe\" -Djpda.listen=true 
-Djpda.address=57643 
-Dmaven.ext.class.path=C:\\Users\\user1\\AppData\\Roaming\\NetBeans\\8.0.2\\maven-nblib\\netbeans-eventspy.jar
 --debug --debug org.codehaus.mojo:exec-maven-plugin:1.2.1:exec\""
Running NetBeans Compile On Save execution. Phase execution is skipped and 
output directories of dependency projects (with Compile on Save turned on) will 
be used instead of their jar artifacts.
Apache Maven 3.0.5 (r01de14724cdef164cd33c7c8c2fe155faf9602da; 2013-02-19 
14:51:28+0100)
Maven home: C:\Program Files\NetBeans 8.0.2\java\maven
Java version: 1.8.0_66, vendor: Oracle Corporation
Java home: C:\Program Files\Java\jdk1.8.0_66\jre
Default locale: en_US, platform encoding: UTF8
OS name: "windows 7", version: "6.1", arch: "amd64", family: "dos"
Populating class realm maven.ext
  Included 
C:\Users\user1\AppData\Roaming\NetBeans\8.0.2\maven-nblib\netbeans-eventspy.jar
Error stacktraces are turned on.
Reading global settings from C:\Program Files\NetBeans 
8.0.2\java\maven\conf\settings.xml
Reading user settings from C:\Users\user1\.m2\settings.xml
Using local repository at C:\Users\user1\.m2\repository
Using manager EnhancedLocalRepositoryManager with priority 10 for 
C:\Users\user1\.m2\repository
Scanning for projects...
Extension realms for project de.afarber:wss-embedded:jar:1.0-SNAPSHOT: (none)
Looking up lifecyle mappings for packaging jar from ClassRealm[maven.ext, 
parent: ClassRealm[plexus.core, parent: null]]
=== REACTOR BUILD PLAN ================================================
Project: de.afarber:wss-embedded:jar:1.0-SNAPSHOT
Tasks:   [org.codehaus.mojo:exec-maven-plugin:1.2.1:exec]
Style:   Regular
=======================================================================
                                                                        
------------------------------------------------------------------------
Building WssEmbedded 1.0-SNAPSHOT
------------------------------------------------------------------------
Lifecycle default -> [validate, initialize, generate-sources, process-sources, 
generate-resources, process-resources, compile, process-classes, 
generate-test-sources, process-test-sources, generate-test-resources, 
process-test-resources, test-compile, process-test-classes, test, 
prepare-package, package, pre-integration-test, integration-test, 
post-integration-test, verify, install, deploy]
Lifecycle clean -> [pre-clean, clean, post-clean]
Lifecycle site -> [pre-site, site, post-site, site-deploy]
=== PROJECT BUILD PLAN ================================================
Project:       de.afarber:wss-embedded:1.0-SNAPSHOT
Dependencies (collect): []
Dependencies (resolve): [test]
Repositories (dependencies): [central (http://repo.maven.apache.org/maven2, 
releases)]
Repositories (plugins)     : [central (http://repo.maven.apache.org/maven2, 
releases)]
-----------------------------------------------------------------------
Goal:          org.codehaus.mojo:exec-maven-plugin:1.2.1:exec (default-cli)
Style:         Regular
Configuration: <?xml version="1.0" encoding="UTF-8"?>
<configuration>
  <basedir default-value="${basedir}"/>
  <classpathScope 
default-value="runtime">${exec.classpathScope}</classpathScope>
  <commandlineArgs>${exec.args}</commandlineArgs>
  <executable>${exec.executable}</executable>
  <longClasspath default-value="false">${exec.longClasspath}</longClasspath>
  <outputFile>${exec.outputFile}</outputFile>
  <project default-value="${project}"/>
  <session default-value="${session}"/>
  <skip default-value="false">${skip}</skip>
  <sourceRoot>${sourceRoot}</sourceRoot>
  <testSourceRoot>${testSourceRoot}</testSourceRoot>
  <workingDirectory>${exec.workingdir}</workingDirectory>
</configuration>
=======================================================================
de.afarber:wss-embedded:jar:1.0-SNAPSHOT
   javax.servlet:javax.servlet-api:jar:3.1.0:compile
   org.eclipse.jetty.websocket:websocket-server:jar:9.3.10.v20160621:compile
      org.eclipse.jetty.websocket:websocket-common:jar:9.3.10.v20160621:compile
         org.eclipse.jetty:jetty-io:jar:9.3.10.v20160621:compile
      org.eclipse.jetty.websocket:websocket-client:jar:9.3.10.v20160621:compile
      org.eclipse.jetty:jetty-servlet:jar:9.3.10.v20160621:compile
         org.eclipse.jetty:jetty-security:jar:9.3.10.v20160621:compile
            org.eclipse.jetty:jetty-server:jar:9.3.10.v20160621:compile
      org.eclipse.jetty:jetty-http:jar:9.3.10.v20160621:compile
   org.eclipse.jetty.websocket:websocket-servlet:jar:9.3.10.v20160621:compile
      org.eclipse.jetty.websocket:websocket-api:jar:9.3.10.v20160621:compile
   org.eclipse.jetty:jetty-util-ajax:jar:9.3.10.v20160621:compile
      org.eclipse.jetty:jetty-util:jar:9.3.10.v20160621:compile

--- exec-maven-plugin:1.2.1:exec (default-cli) @ wss-embedded ---
Created new class realm maven.api
Importing foreign packages into class realm maven.api
  Imported: org.apache.maven.cli < maven.ext
  Imported: org.codehaus.plexus.lifecycle < maven.ext
  Imported: org.apache.maven.lifecycle < maven.ext
  Imported: org.apache.maven.repository < maven.ext
  Imported: org.codehaus.plexus.personality < maven.ext
  Imported: org.apache.maven.usability < maven.ext
  Imported: org.codehaus.plexus.configuration < maven.ext
  Imported: org.sonatype.aether.version < maven.ext
  Imported: org.sonatype.aether.* < maven.ext
  Imported: org.sonatype.aether.artifact < maven.ext
  Imported: org.apache.maven.* < maven.ext
  Imported: org.apache.maven.project < maven.ext
  Imported: org.sonatype.aether.repository < maven.ext
  Imported: org.sonatype.aether.impl < maven.ext
  Imported: org.apache.maven.exception < maven.ext
  Imported: org.apache.maven.plugin < maven.ext
  Imported: org.sonatype.aether.collection < maven.ext
  Imported: org.codehaus.plexus.* < maven.ext
  Imported: org.codehaus.plexus.logging < maven.ext
  Imported: org.apache.maven.profiles < maven.ext
  Imported: org.sonatype.aether.metadata < maven.ext
  Imported: org.sonatype.aether.spi < maven.ext
  Imported: org.codehaus.plexus.util.xml.pull.XmlPullParserException < maven.ext
  Imported: org.apache.maven.wagon.* < maven.ext
  Imported: org.sonatype.aether.graph < maven.ext
  Imported: org.apache.maven.rtinfo < maven.ext
  Imported: org.sonatype.aether.installation < maven.ext
  Imported: org.apache.maven.monitor < maven.ext
  Imported: org.sonatype.aether.transfer < maven.ext
  Imported: org.codehaus.plexus.context < maven.ext
  Imported: org.apache.maven.wagon.observers < maven.ext
  Imported: org.apache.maven.wagon.resource < maven.ext
  Imported: org.sonatype.aether.deployment < maven.ext
  Imported: org.apache.maven.model < maven.ext
  Imported: org.codehaus.plexus.util.xml.Xpp3Dom < maven.ext
  Imported: org.apache.maven.artifact < maven.ext
  Imported: org.apache.maven.toolchain < maven.ext
  Imported: org.codehaus.plexus.util.xml.pull.XmlSerializer < maven.ext
  Imported: org.apache.maven.settings < maven.ext
  Imported: org.apache.maven.wagon.authorization < maven.ext
  Imported: org.apache.maven.wagon.events < maven.ext
  Imported: org.apache.maven.wagon.authentication < maven.ext
  Imported: org.apache.maven.reporting < maven.ext
  Imported: org.apache.maven.wagon.repository < maven.ext
  Imported: org.apache.maven.configuration < maven.ext
  Imported: org.codehaus.plexus.classworlds < maven.ext
  Imported: org.codehaus.classworlds < maven.ext
  Imported: org.codehaus.plexus.util.xml.pull.XmlPullParser < maven.ext
  Imported: org.apache.maven.classrealm < maven.ext
  Imported: org.sonatype.aether.resolution < maven.ext
  Imported: org.apache.maven.execution < maven.ext
  Imported: org.apache.maven.wagon.proxy < maven.ext
  Imported: org.codehaus.plexus.container < maven.ext
  Imported: org.codehaus.plexus.component < maven.ext
Populating class realm maven.api
org.codehaus.mojo:exec-maven-plugin:jar:1.2.1:
   org.apache.maven:maven-toolchain:jar:1.0:compile
   org.apache.maven:maven-project:jar:2.0.6:compile
      org.apache.maven:maven-settings:jar:2.0.6:compile
      org.apache.maven:maven-profile:jar:2.0.6:compile
      org.apache.maven:maven-plugin-registry:jar:2.0.6:compile
   org.apache.maven:maven-model:jar:2.0.6:compile
   org.apache.maven:maven-artifact:jar:2.0.6:compile
   org.apache.maven:maven-artifact-manager:jar:2.0.6:compile
      org.apache.maven:maven-repository-metadata:jar:2.0.6:compile
   org.apache.maven:maven-core:jar:2.0.6:compile
      org.apache.maven:maven-plugin-parameter-documenter:jar:2.0.6:compile
      org.apache.maven.reporting:maven-reporting-api:jar:2.0.6:compile
         org.apache.maven.doxia:doxia-sink-api:jar:1.0-alpha-7:compile
      org.apache.maven:maven-error-diagnostics:jar:2.0.6:compile
      commons-cli:commons-cli:jar:1.0:compile
      org.apache.maven:maven-plugin-descriptor:jar:2.0.6:compile
      org.codehaus.plexus:plexus-interactivity-api:jar:1.0-alpha-4:compile
      org.apache.maven:maven-monitor:jar:2.0.6:compile
      classworlds:classworlds:jar:1.1:compile
   org.apache.maven:maven-plugin-api:jar:2.0.6:compile
   org.codehaus.plexus:plexus-utils:jar:2.0.5:compile
   org.codehaus.plexus:plexus-container-default:jar:1.0-alpha-9:compile
      junit:junit:jar:3.8.2:test (scope managed from compile) (version managed 
from 3.8.1)
   org.apache.commons:commons-exec:jar:1.1:compile
Created new class realm plugin>org.codehaus.mojo:exec-maven-plugin:1.2.1
Importing foreign packages into class realm 
plugin>org.codehaus.mojo:exec-maven-plugin:1.2.1
  Imported:  < maven.api
Populating class realm plugin>org.codehaus.mojo:exec-maven-plugin:1.2.1
  Included: org.codehaus.mojo:exec-maven-plugin:jar:1.2.1
  Included: org.apache.maven.reporting:maven-reporting-api:jar:2.0.6
  Included: org.apache.maven.doxia:doxia-sink-api:jar:1.0-alpha-7
  Included: commons-cli:commons-cli:jar:1.0
  Included: org.codehaus.plexus:plexus-interactivity-api:jar:1.0-alpha-4
  Included: org.codehaus.plexus:plexus-utils:jar:2.0.5
  Included: org.apache.commons:commons-exec:jar:1.1
  Excluded: org.apache.maven:maven-toolchain:jar:1.0
  Excluded: org.apache.maven:maven-project:jar:2.0.6
  Excluded: org.apache.maven:maven-settings:jar:2.0.6
  Excluded: org.apache.maven:maven-profile:jar:2.0.6
  Excluded: org.apache.maven:maven-plugin-registry:jar:2.0.6
  Excluded: org.apache.maven:maven-model:jar:2.0.6
  Excluded: org.apache.maven:maven-artifact:jar:2.0.6
  Excluded: org.apache.maven:maven-artifact-manager:jar:2.0.6
  Excluded: org.apache.maven:maven-repository-metadata:jar:2.0.6
  Excluded: org.apache.maven:maven-core:jar:2.0.6
  Excluded: org.apache.maven:maven-plugin-parameter-documenter:jar:2.0.6
  Excluded: org.apache.maven:maven-error-diagnostics:jar:2.0.6
  Excluded: org.apache.maven:maven-plugin-descriptor:jar:2.0.6
  Excluded: org.apache.maven:maven-monitor:jar:2.0.6
  Excluded: classworlds:classworlds:jar:1.1
  Excluded: org.apache.maven:maven-plugin-api:jar:2.0.6
  Excluded: org.codehaus.plexus:plexus-container-default:jar:1.0-alpha-9
  Excluded: junit:junit:jar:3.8.2
Configuring mojo org.codehaus.mojo:exec-maven-plugin:1.2.1:exec from plugin 
realm ClassRealm[plugin>org.codehaus.mojo:exec-maven-plugin:1.2.1, parent: 
sun.misc.Launcher$AppClassLoader@70dea4e]
Configuring mojo 'org.codehaus.mojo:exec-maven-plugin:1.2.1:exec' with basic 
configurator -->
  (f) basedir = C:\Users\user1\jetty-newbie\WssEmbedded
  (f) classpathScope = runtime
  (f) commandlineArgs = -Xdebug 
-Xrunjdwp:transport=dt_socket,server=n,address=57643 -classpath %classpath 
de.afarber.wssembedded.MyHandler
  (f) executable = C:\Program Files\Java\jdk1.8.0_66\bin\java.exe
  (f) longClasspath = false
  (f) project = MavenProject: de.afarber:wss-embedded:1.0-SNAPSHOT @ 
C:\Users\user1\jetty-newbie\WssEmbedded\pom.xml
  (f) session = org.apache.maven.execution.MavenSession@5460cf3a
  (f) skip = false
-- end configuration --
Collected project artifacts [javax.servlet:javax.servlet-api:jar:3.1.0:compile, 
org.eclipse.jetty.websocket:websocket-server:jar:9.3.10.v20160621:compile, 
org.eclipse.jetty.websocket:websocket-common:jar:9.3.10.v20160621:compile, 
org.eclipse.jetty:jetty-io:jar:9.3.10.v20160621:compile, 
org.eclipse.jetty.websocket:websocket-client:jar:9.3.10.v20160621:compile, 
org.eclipse.jetty:jetty-servlet:jar:9.3.10.v20160621:compile, 
org.eclipse.jetty:jetty-security:jar:9.3.10.v20160621:compile, 
org.eclipse.jetty:jetty-server:jar:9.3.10.v20160621:compile, 
org.eclipse.jetty:jetty-http:jar:9.3.10.v20160621:compile, 
org.eclipse.jetty.websocket:websocket-servlet:jar:9.3.10.v20160621:compile, 
org.eclipse.jetty.websocket:websocket-api:jar:9.3.10.v20160621:compile, 
org.eclipse.jetty:jetty-util-ajax:jar:9.3.10.v20160621:compile, 
org.eclipse.jetty:jetty-util:jar:9.3.10.v20160621:compile]
Collected project classpath 
[C:\Users\user1\jetty-newbie\WssEmbedded\target\classes]
dealing with javax.servlet:javax.servlet-api:jar:3.1.0:compile
dealing with 
org.eclipse.jetty.websocket:websocket-server:jar:9.3.10.v20160621:compile
dealing with 
org.eclipse.jetty.websocket:websocket-common:jar:9.3.10.v20160621:compile
dealing with org.eclipse.jetty:jetty-io:jar:9.3.10.v20160621:compile
dealing with 
org.eclipse.jetty.websocket:websocket-client:jar:9.3.10.v20160621:compile
dealing with org.eclipse.jetty:jetty-servlet:jar:9.3.10.v20160621:compile
dealing with org.eclipse.jetty:jetty-security:jar:9.3.10.v20160621:compile
dealing with org.eclipse.jetty:jetty-server:jar:9.3.10.v20160621:compile
dealing with org.eclipse.jetty:jetty-http:jar:9.3.10.v20160621:compile
dealing with 
org.eclipse.jetty.websocket:websocket-servlet:jar:9.3.10.v20160621:compile
dealing with 
org.eclipse.jetty.websocket:websocket-api:jar:9.3.10.v20160621:compile
dealing with org.eclipse.jetty:jetty-util-ajax:jar:9.3.10.v20160621:compile
dealing with org.eclipse.jetty:jetty-util:jar:9.3.10.v20160621:compile
Toolchains are ignored, 'executable' parameter is set to C:\Program 
Files\Java\jdk1.8.0_66\bin\java.exe
Executing command line: C:\Program Files\Java\jdk1.8.0_66\bin\java.exe -Xdebug 
-Xrunjdwp:transport=dt_socket,server=n,address=57643 -classpath 
C:\Users\user1\jetty-newbie\WssEmbedded\target\classes;C:\Users\user1\.m2\repository\javax\servlet\javax.servlet-api\3.1.0\javax.servlet-api-3.1.0.jar;C:\Users\user1\.m2\repository\org\eclipse\jetty\websocket\websocket-server\9.3.10.v20160621\websocket-server-9.3.10.v20160621.jar;C:\Users\user1\.m2\repository\org\eclipse\jetty\websocket\websocket-common\9.3.10.v20160621\websocket-common-9.3.10.v20160621.jar;C:\Users\user1\.m2\repository\org\eclipse\jetty\jetty-io\9.3.10.v20160621\jetty-io-9.3.10.v20160621.jar;C:\Users\user1\.m2\repository\org\eclipse\jetty\websocket\websocket-client\9.3.10.v20160621\websocket-client-9.3.10.v20160621.jar;C:\Users\user1\.m2\repository\org\eclipse\jetty\jetty-servlet\9.3.10.v20160621\jetty-servlet-9.3.10.v20160621.jar;C:\Users\user1\.m2\repository\org\eclipse\jetty\jetty-security\9.3.10.v20160621\jetty-security-9.3.10.v20160621.jar;C:\Users\user1\.m2\repository\org\eclipse\jetty\jetty-server\9.3.10.v20160621\jetty-server-9.3.10.v20160621.jar;C:\Users\user1\.m2\repository\org\eclipse\jetty\jetty-http\9.3.10.v20160621\jetty-http-9.3.10.v20160621.jar;C:\Users\user1\.m2\repository\org\eclipse\jetty\websocket\websocket-servlet\9.3.10.v20160621\websocket-servlet-9.3.10.v20160621.jar;C:\Users\user1\.m2\repository\org\eclipse\jetty\websocket\websocket-api\9.3.10.v20160621\websocket-api-9.3.10.v20160621.jar;C:\Users\user1\.m2\repository\org\eclipse\jetty\jetty-util-ajax\9.3.10.v20160621\jetty-util-ajax-9.3.10.v20160621.jar;C:\Users\user1\.m2\repository\org\eclipse\jetty\jetty-util\9.3.10.v20160621\jetty-util-9.3.10.v20160621.jar
 de.afarber.wssembedded.MyHandler
2016-06-23 15:41:00.606:INFO::main: Logging initialized @399ms
2016-06-23 15:41:00.638:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.server.Server@180bc464 added 
{qtp1789550256{STOPPED,8<=0<=200,i=0,q=0},AUTO}
2016-06-23 15:41:00.669:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.websocket.server.WebSocketServerFactory@7c0e2abd added 
{org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@7921b0a2,AUTO}
2016-06-23 15:41:00.669:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.websocket.server.WebSocketServerFactory@7c0e2abd added 
{org.eclipse.jetty.io.MappedByteBufferPool@48eff760,POJO}
2016-06-23 15:41:00.700:DBUG:oejuc.ContainerLifeCycle:main: 
de.afarber.wssembedded.MyHandler@5e3a8624 added 
{org.eclipse.jetty.websocket.server.WebSocketServerFactory@7c0e2abd,AUTO}
2016-06-23 15:41:00.700:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.server.Server@180bc464 added 
{de.afarber.wssembedded.MyHandler@5e3a8624,MANAGED}
2016-06-23 15:41:00.747:DBUG:oejuc.ContainerLifeCycle:main: 
HttpConnectionFactory@6321e813[HTTP/1.1] added 
{HttpConfiguration@79be0360{32768/8192,8192/8192,https://:0,[]},POJO}
2016-06-23 15:41:00.747:DBUG:oejuc.ContainerLifeCycle:main: 
ServerConnector@72b6cbcc{null,[]}{0.0.0.0:0} added 
{org.eclipse.jetty.server.Server@180bc464,UNMANAGED}
2016-06-23 15:41:00.747:DBUG:oejuc.ContainerLifeCycle:main: 
ServerConnector@72b6cbcc{null,[]}{0.0.0.0:0} added 
{qtp1789550256{STOPPED,8<=0<=200,i=0,q=0},AUTO}
2016-06-23 15:41:00.747:DBUG:oejuc.ContainerLifeCycle:main: 
ServerConnector@72b6cbcc{null,[]}{0.0.0.0:0} added 
{org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@22a67b4,AUTO}
2016-06-23 15:41:00.747:DBUG:oejuc.ContainerLifeCycle:main: 
ServerConnector@72b6cbcc{null,[]}{0.0.0.0:0} added 
{org.eclipse.jetty.io.ArrayByteBufferPool@30b8a058,POJO}
2016-06-23 15:41:00.747:DBUG:oejuc.ContainerLifeCycle:main: 
ServerConnector@72b6cbcc{null,[http/1.1]}{0.0.0.0:0} added 
{HttpConnectionFactory@6321e813[HTTP/1.1],AUTO}
2016-06-23 15:41:00.747:DBUG:oejs.AbstractConnector:main: 
ServerConnector@72b6cbcc{HTTP/1.1,[http/1.1]}{0.0.0.0:0} added 
HttpConnectionFactory@6321e813[HTTP/1.1]
2016-06-23 15:41:00.747:DBUG:oejuc.ContainerLifeCycle:main: 
ServerConnector@72b6cbcc{HTTP/1.1,[http/1.1]}{0.0.0.0:0} added 
{org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@694e1548,MANAGED}
2016-06-23 15:41:00.747:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.server.Server@180bc464 added 
{ServerConnector@72b6cbcc{HTTP/1.1,[http/1.1]}{127.0.0.1:8080},AUTO}
2016-06-23 15:41:00.762:DBUG:oejuc.ContainerLifeCycle:main: 
SslConnectionFactory@71bbf57e{SSL->HTTP/1.1} added 
{SslContextFactory@7f13d6e(file:///C:/Users/user1/jetty-newbie/WssEmbedded/keystore.jks,null),AUTO}
2016-06-23 15:41:00.762:DBUG:oejuc.ContainerLifeCycle:main: 
ServerConnector@d44fc21{null,[]}{0.0.0.0:0} added 
{org.eclipse.jetty.server.Server@180bc464,UNMANAGED}
2016-06-23 15:41:00.762:DBUG:oejuc.ContainerLifeCycle:main: 
ServerConnector@d44fc21{null,[]}{0.0.0.0:0} added 
{qtp1789550256{STOPPED,8<=0<=200,i=0,q=0},AUTO}
2016-06-23 15:41:00.762:DBUG:oejuc.ContainerLifeCycle:main: 
ServerConnector@d44fc21{null,[]}{0.0.0.0:0} added 
{org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@51cdd8a,AUTO}
2016-06-23 15:41:00.762:DBUG:oejuc.ContainerLifeCycle:main: 
ServerConnector@d44fc21{null,[]}{0.0.0.0:0} added 
{org.eclipse.jetty.io.ArrayByteBufferPool@23faf8f2,POJO}
2016-06-23 15:41:00.762:DBUG:oejuc.ContainerLifeCycle:main: 
ServerConnector@d44fc21{null,[ssl]}{0.0.0.0:0} added 
{SslConnectionFactory@71bbf57e{SSL->HTTP/1.1},AUTO}
2016-06-23 15:41:00.762:DBUG:oejs.AbstractConnector:main: 
ServerConnector@d44fc21{SSL,[ssl]}{0.0.0.0:0} added 
SslConnectionFactory@71bbf57e{SSL->HTTP/1.1}
2016-06-23 15:41:00.762:DBUG:oejuc.ContainerLifeCycle:main: 
ServerConnector@d44fc21{SSL,[ssl]}{0.0.0.0:0} added 
{org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@2d6eabae,MANAGED}
2016-06-23 15:41:00.762:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.server.Server@180bc464 added 
{ServerConnector@d44fc21{SSL,[ssl]}{127.0.0.1:8443},AUTO}
2016-06-23 15:41:00.762:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.server.Server@180bc464
2016-06-23 15:41:00.762:INFO:oejs.Server:main: jetty-9.3.10.v20160621
2016-06-23 15:41:00.778:DBUG:oejsh.AbstractHandler:main: starting 
org.eclipse.jetty.server.Server@180bc464
2016-06-23 15:41:00.778:DBUG:oejuc.AbstractLifeCycle:main: starting 
qtp1789550256{STOPPED,8<=0<=200,i=0,q=0}
2016-06-23 15:41:00.778:DBUG:oejuc.AbstractLifeCycle:main: STARTED @572ms 
qtp1789550256{STARTED,8<=8<=200,i=7,q=0}
2016-06-23 15:41:00.778:DBUG:oejuc.AbstractLifeCycle:main: starting 
de.afarber.wssembedded.MyHandler@5e3a8624
2016-06-23 15:41:00.778:DBUG:oejsh.AbstractHandler:main: starting 
de.afarber.wssembedded.MyHandler@5e3a8624
2016-06-23 15:41:00.778:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.websocket.server.WebSocketServerFactory@7c0e2abd
2016-06-23 15:41:00.794:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@7921b0a2
2016-06-23 15:41:00.794:DBUG:oejuc.AbstractLifeCycle:main: STARTED @578ms 
org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@7921b0a2
2016-06-23 15:41:00.794:DBUG:oejuc.AbstractLifeCycle:main: STARTED @578ms 
org.eclipse.jetty.websocket.server.WebSocketServerFactory@7c0e2abd
2016-06-23 15:41:00.794:DBUG:oejuc.AbstractLifeCycle:main: STARTED @578ms 
de.afarber.wssembedded.MyHandler@5e3a8624
2016-06-23 15:41:00.794:DBUG:oejuc.AbstractLifeCycle:main: starting 
ServerConnector@72b6cbcc{HTTP/1.1,[http/1.1]}{127.0.0.1:8080}
2016-06-23 15:41:00.794:DBUG:oejuc.ContainerLifeCycle:main: 
ServerConnector@72b6cbcc{HTTP/1.1,[http/1.1]}{127.0.0.1:8080} added 
{sun.nio.ch.ServerSocketChannelImpl[/127.0.0.1:8080],POJO}
2016-06-23 15:41:00.794:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@22a67b4
2016-06-23 15:41:00.794:DBUG:oejuc.AbstractLifeCycle:main: STARTED @586ms 
org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@22a67b4
2016-06-23 15:41:00.794:DBUG:oejuc.AbstractLifeCycle:main: starting 
HttpConnectionFactory@6321e813[HTTP/1.1]
2016-06-23 15:41:00.794:DBUG:oejuc.AbstractLifeCycle:main: STARTED @586ms 
HttpConnectionFactory@6321e813[HTTP/1.1]
2016-06-23 15:41:00.794:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@694e1548
2016-06-23 15:41:00.794:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@694e1548 added 
{org.eclipse.jetty.io.ManagedSelector@11758f2a id=0 keys=-1 selected=-1,AUTO}
2016-06-23 15:41:00.794:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@694e1548 added 
{org.eclipse.jetty.io.ManagedSelector@e720b71 id=1 keys=-1 selected=-1,AUTO}
2016-06-23 15:41:00.809:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.io.ManagedSelector@11758f2a id=0 keys=-1 selected=-1
2016-06-23 15:41:00.872:DBUG:oejut.QueuedThreadPool:main: queue 
org.eclipse.jetty.io.ManagedSelector@11758f2a id=0 keys=0 selected=0
2016-06-23 15:41:00.872:DBUG:oejuc.AbstractLifeCycle:main: STARTED @668ms 
org.eclipse.jetty.io.ManagedSelector@11758f2a id=0 keys=0 selected=0
2016-06-23 15:41:00.872:DBUG:oejut.QueuedThreadPool:qtp1789550256-13: run 
org.eclipse.jetty.io.ManagedSelector@11758f2a id=0 keys=0 selected=0
2016-06-23 15:41:00.872:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.io.ManagedSelector@e720b71 id=1 keys=-1 selected=-1
2016-06-23 15:41:00.872:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-13: EPC 
Idle/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@547a30f3 execute
2016-06-23 15:41:00.872:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-13: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@547a30f3 produce 
enter
2016-06-23 15:41:00.872:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-13: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@547a30f3 producing
2016-06-23 15:41:00.872:DBUG:oeji.ManagedSelector:qtp1789550256-13: Selector 
loop waiting on select
2016-06-23 15:41:00.887:DBUG:oejut.QueuedThreadPool:main: queue 
org.eclipse.jetty.io.ManagedSelector@e720b71 id=1 keys=0 selected=0
2016-06-23 15:41:00.887:DBUG:oejuc.AbstractLifeCycle:main: STARTED @670ms 
org.eclipse.jetty.io.ManagedSelector@e720b71 id=1 keys=0 selected=0
2016-06-23 15:41:00.887:DBUG:oejut.QueuedThreadPool:qtp1789550256-15: run 
org.eclipse.jetty.io.ManagedSelector@e720b71 id=1 keys=0 selected=0
2016-06-23 15:41:01.137:DBUG:oejuc.AbstractLifeCycle:main: STARTED @920ms 
org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@694e1548
2016-06-23 15:41:01.137:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-15: EPC 
Idle/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@17d7315b execute
2016-06-23 15:41:01.137:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-15: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@17d7315b produce 
enter
2016-06-23 15:41:01.137:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-15: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@17d7315b producing
2016-06-23 15:41:01.137:DBUG:oeji.ManagedSelector:qtp1789550256-15: Selector 
loop waiting on select
2016-06-23 15:41:01.137:DBUG:oejuc.ContainerLifeCycle:main: 
ServerConnector@72b6cbcc{HTTP/1.1,[http/1.1]}{127.0.0.1:8080} added 
{acceptor-0@d6da883,POJO}
2016-06-23 15:41:01.137:DBUG:oejut.QueuedThreadPool:main: queue 
acceptor-0@d6da883
2016-06-23 15:41:01.137:INFO:oejs.AbstractConnector:main: Started 
ServerConnector@72b6cbcc{HTTP/1.1,[http/1.1]}{127.0.0.1:8080}
2016-06-23 15:41:01.137:DBUG:oejuc.AbstractLifeCycle:main: STARTED @927ms 
ServerConnector@72b6cbcc{HTTP/1.1,[http/1.1]}{127.0.0.1:8080}
2016-06-23 15:41:01.137:DBUG:oejuc.AbstractLifeCycle:main: starting 
ServerConnector@d44fc21{SSL,[ssl]}{127.0.0.1:8443}
2016-06-23 15:41:01.137:DBUG:oejuc.ContainerLifeCycle:main: 
ServerConnector@d44fc21{SSL,[ssl]}{127.0.0.1:8443} added 
{sun.nio.ch.ServerSocketChannelImpl[/127.0.0.1:8443],POJO}
2016-06-23 15:41:01.137:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@51cdd8a
2016-06-23 15:41:01.137:DBUG:oejuc.AbstractLifeCycle:main: STARTED @931ms 
org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@51cdd8a
2016-06-23 15:41:01.137:DBUG:oejuc.AbstractLifeCycle:main: starting 
SslConnectionFactory@71bbf57e{SSL->HTTP/1.1}
2016-06-23 15:41:01.137:DBUG:oejuc.AbstractLifeCycle:main: starting 
SslContextFactory@7f13d6e(file:///C:/Users/user1/jetty-newbie/WssEmbedded/keystore.jks,null)
2016-06-23 15:41:01.137:DBUG:oejut.QueuedThreadPool:qtp1789550256-17: run 
acceptor-0@d6da883
2016-06-23 15:41:01.199:DBUG:oejus.X509:main: Certificate CN alias=key1 
CN=Alexander Farber in X509@6279cee3(key1,h=[],w=[])
2016-06-23 15:41:01.199:INFO:oejus.SslContextFactory:main: 
x509=X509@6279cee3(key1,h=[],w=[]) for 
SslContextFactory@7f13d6e(file:///C:/Users/user1/jetty-newbie/WssEmbedded/keystore.jks,null)
2016-06-23 15:41:01.246:DBUG:oejus.SslContextFactory:main: 
managers=[Ljavax.net.ssl.KeyManager;@48524010 for 
SslContextFactory@7f13d6e(file:///C:/Users/user1/jetty-newbie/WssEmbedded/keystore.jks,null)
2016-06-23 15:41:01.620:DBUG:oejus.SslContextFactory:main: Selected Protocols 
[TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
2016-06-23 15:41:01.636:DBUG:oejus.SslContextFactory:main: Selected Ciphers   
[TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256, 
TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, 
TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256, 
TLS_DHE_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_DSS_WITH_AES_128_CBC_SHA256, 
TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA, 
TLS_DHE_DSS_WITH_AES_128_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, 
TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, 
TLS_ECDH_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_RSA_WITH_AES_128_GCM_SHA256, 
TLS_DHE_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_DSS_WITH_AES_128_GCM_SHA256, 
TLS_ECDHE_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_ECDSA_WITH_3DES_EDE_CBC_SHA, 
SSL_DHE_DSS_WITH_3DES_EDE_CBC_SHA, TLS_EMPTY_RENEGOTIATION_INFO_SCSV] of 
[TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA256, 
TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, 
TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA256, TLS_ECDH_RSA_WITH_AES_128_CBC_SHA256, 
TLS_DHE_RSA_WITH_AES_128_CBC_SHA256, TLS_DHE_DSS_WITH_AES_128_CBC_SHA256, 
TLS_ECDHE_ECDSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, 
TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDH_ECDSA_WITH_AES_128_CBC_SHA, 
TLS_ECDH_RSA_WITH_AES_128_CBC_SHA, TLS_DHE_RSA_WITH_AES_128_CBC_SHA, 
TLS_DHE_DSS_WITH_AES_128_CBC_SHA, TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256, 
TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, 
TLS_ECDH_ECDSA_WITH_AES_128_GCM_SHA256, TLS_ECDH_RSA_WITH_AES_128_GCM_SHA256, 
TLS_DHE_RSA_WITH_AES_128_GCM_SHA256, TLS_DHE_DSS_WITH_AES_128_GCM_SHA256, 
TLS_ECDHE_ECDSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, 
SSL_RSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDH_ECDSA_WITH_3DES_EDE_CBC_SHA, 
TLS_ECDH_RSA_WITH_3DES_EDE_CBC_SHA, SSL_DHE_RSA_WITH_3DES_EDE_CBC_SHA, 
SSL_DHE_DSS_WITH_3DES_EDE_CBC_SHA, TLS_EMPTY_RENEGOTIATION_INFO_SCSV, 
TLS_DH_anon_WITH_AES_128_GCM_SHA256, TLS_DH_anon_WITH_AES_128_CBC_SHA256, 
TLS_ECDH_anon_WITH_AES_128_CBC_SHA, TLS_DH_anon_WITH_AES_128_CBC_SHA, 
TLS_ECDH_anon_WITH_3DES_EDE_CBC_SHA, SSL_DH_anon_WITH_3DES_EDE_CBC_SHA, 
SSL_RSA_WITH_DES_CBC_SHA, SSL_DHE_RSA_WITH_DES_CBC_SHA, 
SSL_DHE_DSS_WITH_DES_CBC_SHA, SSL_DH_anon_WITH_DES_CBC_SHA, 
SSL_RSA_EXPORT_WITH_DES40_CBC_SHA, SSL_DHE_RSA_EXPORT_WITH_DES40_CBC_SHA, 
SSL_DHE_DSS_EXPORT_WITH_DES40_CBC_SHA, SSL_DH_anon_EXPORT_WITH_DES40_CBC_SHA, 
TLS_RSA_WITH_NULL_SHA256, TLS_ECDHE_ECDSA_WITH_NULL_SHA, 
TLS_ECDHE_RSA_WITH_NULL_SHA, SSL_RSA_WITH_NULL_SHA, 
TLS_ECDH_ECDSA_WITH_NULL_SHA, TLS_ECDH_RSA_WITH_NULL_SHA, 
TLS_ECDH_anon_WITH_NULL_SHA, SSL_RSA_WITH_NULL_MD5, 
TLS_KRB5_WITH_3DES_EDE_CBC_SHA, TLS_KRB5_WITH_3DES_EDE_CBC_MD5, 
TLS_KRB5_WITH_DES_CBC_SHA, TLS_KRB5_WITH_DES_CBC_MD5, 
TLS_KRB5_EXPORT_WITH_DES_CBC_40_SHA, TLS_KRB5_EXPORT_WITH_DES_CBC_40_MD5]
2016-06-23 15:41:01.636:DBUG:oejuc.AbstractLifeCycle:main: STARTED @1422ms 
SslContextFactory@7f13d6e(file:///C:/Users/user1/jetty-newbie/WssEmbedded/keystore.jks,null)
2016-06-23 15:41:01.636:DBUG:oejus.SslContextFactory:main: Customize 
3d34d211[SSLEngine[hostname=null port=-1] SSL_NULL_WITH_NULL_NULL]
2016-06-23 15:41:01.636:DBUG:oejuc.AbstractLifeCycle:main: STARTED @1424ms 
SslConnectionFactory@71bbf57e{SSL->HTTP/1.1}
2016-06-23 15:41:01.636:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@2d6eabae
2016-06-23 15:41:01.636:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@2d6eabae added 
{org.eclipse.jetty.io.ManagedSelector@7dc0f706 id=0 keys=-1 selected=-1,AUTO}
2016-06-23 15:41:01.636:DBUG:oejuc.ContainerLifeCycle:main: 
org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@2d6eabae added 
{org.eclipse.jetty.io.ManagedSelector@4009e306 id=1 keys=-1 selected=-1,AUTO}
2016-06-23 15:41:01.636:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.io.ManagedSelector@7dc0f706 id=0 keys=-1 selected=-1
2016-06-23 15:41:01.636:DBUG:oejut.QueuedThreadPool:main: queue 
org.eclipse.jetty.io.ManagedSelector@7dc0f706 id=0 keys=0 selected=0
2016-06-23 15:41:01.636:DBUG:oejuc.AbstractLifeCycle:main: STARTED @1427ms 
org.eclipse.jetty.io.ManagedSelector@7dc0f706 id=0 keys=0 selected=0
2016-06-23 15:41:01.636:DBUG:oejuc.AbstractLifeCycle:main: starting 
org.eclipse.jetty.io.ManagedSelector@4009e306 id=1 keys=-1 selected=-1
2016-06-23 15:41:01.636:DBUG:oejut.QueuedThreadPool:qtp1789550256-16: run 
org.eclipse.jetty.io.ManagedSelector@7dc0f706 id=0 keys=0 selected=0
2016-06-23 15:41:01.636:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-16: EPC 
Idle/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c execute
2016-06-23 15:41:01.636:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-16: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c produce 
enter
2016-06-23 15:41:01.636:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-16: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c producing
2016-06-23 15:41:01.636:DBUG:oeji.ManagedSelector:qtp1789550256-16: Selector 
loop waiting on select
2016-06-23 15:41:01.636:DBUG:oejut.QueuedThreadPool:main: queue 
org.eclipse.jetty.io.ManagedSelector@4009e306 id=1 keys=0 selected=0
2016-06-23 15:41:01.636:DBUG:oejut.QueuedThreadPool:qtp1789550256-14: run 
org.eclipse.jetty.io.ManagedSelector@4009e306 id=1 keys=0 selected=0
2016-06-23 15:41:01.636:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-14: EPC 
Idle/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@66cff2d7 execute
2016-06-23 15:41:01.636:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-14: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@66cff2d7 produce 
enter
2016-06-23 15:41:01.636:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-14: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@66cff2d7 producing
2016-06-23 15:41:01.636:DBUG:oeji.ManagedSelector:qtp1789550256-14: Selector 
loop waiting on select
2016-06-23 15:41:01.636:DBUG:oejuc.AbstractLifeCycle:main: STARTED @1430ms 
org.eclipse.jetty.io.ManagedSelector@4009e306 id=1 keys=0 selected=0
2016-06-23 15:41:01.636:DBUG:oejuc.AbstractLifeCycle:main: STARTED @1430ms 
org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@2d6eabae
2016-06-23 15:41:01.636:DBUG:oejuc.ContainerLifeCycle:main: 
ServerConnector@d44fc21{SSL,[ssl]}{127.0.0.1:8443} added 
{acceptor-0@22fcf7ab,POJO}
2016-06-23 15:41:01.636:DBUG:oejut.QueuedThreadPool:main: queue 
acceptor-0@22fcf7ab
2016-06-23 15:41:01.636:INFO:oejs.AbstractConnector:main: Started 
ServerConnector@d44fc21{SSL,[ssl]}{127.0.0.1:8443}
2016-06-23 15:41:01.636:DBUG:oejuc.AbstractLifeCycle:main: STARTED @1431ms 
ServerConnector@d44fc21{SSL,[ssl]}{127.0.0.1:8443}
2016-06-23 15:41:01.636:INFO:oejs.Server:main: Started @1431ms
2016-06-23 15:41:01.636:DBUG:oejuc.AbstractLifeCycle:main: STARTED @1431ms 
org.eclipse.jetty.server.Server@180bc464
2016-06-23 15:41:01.636:DBUG:oejut.QueuedThreadPool:qtp1789550256-18: run 
acceptor-0@22fcf7ab
2016-06-23 
15:41:14.322:DBUG:oeji.ManagedSelector:qtp1789550256-18-acceptor-0@22fcf7ab-ServerConnector@d44fc21{SSL,[ssl]}{127.0.0.1:8443}:
 Queued change org.eclipse.jetty.io.ManagedSelector$Accept@34ddeffe on 
org.eclipse.jetty.io.ManagedSelector@7dc0f706 id=0 keys=0 selected=0
2016-06-23 15:41:14.322:DBUG:oeji.ManagedSelector:qtp1789550256-16: Selector 
loop woken up from select, 0/0 selected
2016-06-23 15:41:14.322:DBUG:oeji.ManagedSelector:qtp1789550256-16: Running 
change org.eclipse.jetty.io.ManagedSelector$Accept@34ddeffe
2016-06-23 15:41:14.322:DBUG:oeji.ManagedSelector:qtp1789550256-16: Queued 
change org.eclipse.jetty.io.ManagedSelector$CreateEndPoint@2947c2c7 on 
org.eclipse.jetty.io.ManagedSelector@7dc0f706 id=0 keys=1 selected=0
2016-06-23 15:41:14.322:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-16: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c produced 
org.eclipse.jetty.io.ManagedSelector$CreateEndPoint@2947c2c7
2016-06-23 15:41:14.322:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-16: EPC 
Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c dispatch
2016-06-23 15:41:14.322:DBUG:oejut.QueuedThreadPool:qtp1789550256-16: queue EPC 
Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c
2016-06-23 15:41:14.322:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-16: EPC 
Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c run 
org.eclipse.jetty.io.ManagedSelector$CreateEndPoint@2947c2c7
2016-06-23 15:41:14.322:DBUG:oejut.QueuedThreadPool:qtp1789550256-19: run EPC 
Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c
2016-06-23 15:41:14.322:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-19: EPC 
Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c run
2016-06-23 15:41:14.322:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-19: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c produce 
enter
2016-06-23 15:41:14.322:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-19: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c producing
2016-06-23 15:41:14.322:DBUG:oeji.ManagedSelector:qtp1789550256-19: Selector 
loop waiting on select
2016-06-23 15:41:14.385:DBUG:oeji.AbstractEndPoint:qtp1789550256-16: onOpen 
SelectChannelEndPoint@4a9f1e5d{/127.0.0.1:57661<->8443,Open,in,out,-,-,32/30000,null@0}{io=0/0,kio=0,kro=0}
2016-06-23 15:41:14.385:DBUG:oeji.IdleTimeout:qtp1789550256-16: 
SelectChannelEndPoint@4a9f1e5d{/127.0.0.1:57661<->8443,Open,in,out,-,-,32/30000,null@0}{io=0/0,kio=0,kro=0}
 idle timeout check, elapsed: 32 ms, remaining: 29968 ms
2016-06-23 15:41:14.400:DBUG:oejus.SslContextFactory:qtp1789550256-16: 
Customize 62332813[SSLEngine[hostname=127.0.0.1 port=57661] 
SSL_NULL_WITH_NULL_NULL]
2016-06-23 15:41:14.416:DBUG:oeji.ManagedSelector:qtp1789550256-16: 
java.lang.NullPointerException
        at 
org.eclipse.jetty.server.SslConnectionFactory.newConnection(SslConnectionFactory.java:89)
        at 
org.eclipse.jetty.server.ServerConnector$ServerConnectorManager.newConnection(ServerConnector.java:517)
        at 
org.eclipse.jetty.io.ManagedSelector.createEndPoint(ManagedSelector.java:422)
        at 
org.eclipse.jetty.io.ManagedSelector.access$1600(ManagedSelector.java:56)
        at 
org.eclipse.jetty.io.ManagedSelector$CreateEndPoint.run(ManagedSelector.java:605)
        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.execute(ExecuteProduceConsume.java:100)
        at org.eclipse.jetty.io.ManagedSelector.run(ManagedSelector.java:147)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
        at java.lang.Thread.run(Thread.java:745)
2016-06-23 15:41:14.416:DBUG:oeji.ManagedSelector:qtp1789550256-16: 
java.lang.NullPointerException
        at 
org.eclipse.jetty.server.SslConnectionFactory.newConnection(SslConnectionFactory.java:89)
        at 
org.eclipse.jetty.server.ServerConnector$ServerConnectorManager.newConnection(ServerConnector.java:517)
        at 
org.eclipse.jetty.io.ManagedSelector.createEndPoint(ManagedSelector.java:422)
        at 
org.eclipse.jetty.io.ManagedSelector.access$1600(ManagedSelector.java:56)
        at 
org.eclipse.jetty.io.ManagedSelector$CreateEndPoint.run(ManagedSelector.java:605)
        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.execute(ExecuteProduceConsume.java:100)
        at org.eclipse.jetty.io.ManagedSelector.run(ManagedSelector.java:147)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
        at java.lang.Thread.run(Thread.java:745)
2016-06-23 15:41:14.416:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-16: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c ran 
org.eclipse.jetty.io.ManagedSelector$CreateEndPoint@2947c2c7
2016-06-23 15:41:14.416:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-16: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c produce exit
2016-06-23 15:41:14.416:DBUG:oejut.QueuedThreadPool:qtp1789550256-16: ran 
org.eclipse.jetty.io.ManagedSelector@7dc0f706 id=0 keys=1 selected=0
2016-06-23 15:41:44.372:DBUG:oeji.IdleTimeout:Scheduler-85777802: 
SelectChannelEndPoint@4a9f1e5d{/127.0.0.1:57661<->8443,Open,ISHUT,OSHUT,-,-,30019/30000,null@0}{io=0/0,kio=-1,kro=-1}
 idle timeout check, elapsed: 30019 ms, remaining: -19 ms
2016-06-23 15:41:44.372:DBUG:oeji.IdleTimeout:Scheduler-85777802: 
SelectChannelEndPoint@4a9f1e5d{/127.0.0.1:57661<->8443,Open,ISHUT,OSHUT,-,-,30019/30000,null@0}{io=0/0,kio=-1,kro=-1}
 idle timeout expired
2016-06-23 15:41:44.372:DBUG:oeji.WriteFlusher:Scheduler-85777802: ignored: 
WriteFlusher@3798bc34{IDLE} java.util.concurrent.TimeoutException: Idle timeout 
expired: 30019/30000 ms
2016-06-23 15:41:44.372:DBUG:oeji.WriteFlusher:Scheduler-85777802: ignored: 
WriteFlusher@3798bc34{IDLE} java.nio.channels.ClosedChannelException
2016-06-23 15:41:44.372:DBUG:oeji.ChannelEndPoint:Scheduler-85777802: close 
SelectChannelEndPoint@4a9f1e5d{/127.0.0.1:57661<->8443,CLOSED,ISHUT,OSHUT,-,-,30019/30000,null@0}{io=0/0,kio=-1,kro=-1}
2016-06-23 15:41:44.372:DBUG:oeji.ManagedSelector:Scheduler-85777802: Queued 
change org.eclipse.jetty.io.ManagedSelector$2@4d448b29 on 
org.eclipse.jetty.io.ManagedSelector@7dc0f706 id=0 keys=1 selected=0
2016-06-23 15:41:44.372:DBUG:oeji.ManagedSelector:qtp1789550256-19: Selector 
loop woken up from select, 0/0 selected
2016-06-23 15:41:44.372:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-19: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c produced 
org.eclipse.jetty.io.ManagedSelector$2@4d448b29
2016-06-23 15:41:44.372:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-19: EPC 
Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c dispatch
2016-06-23 15:41:44.372:DBUG:oejut.QueuedThreadPool:qtp1789550256-19: queue EPC 
Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c
2016-06-23 15:41:44.372:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-19: EPC 
Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c run 
org.eclipse.jetty.io.ManagedSelector$2@4d448b29
2016-06-23 15:41:44.372:DBUG:oejut.QueuedThreadPool:qtp1789550256-20: run EPC 
Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c
2016-06-23 15:41:44.372:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-20: EPC 
Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c run
2016-06-23 15:41:44.372:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-20: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c produce 
enter
2016-06-23 15:41:44.372:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-20: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c producing
2016-06-23 15:41:44.372:DBUG:oeji.ManagedSelector:qtp1789550256-20: Selector 
loop waiting on select
2016-06-23 15:41:44.372:DBUG:oeji.ManagedSelector:qtp1789550256-19: Destroyed 
SelectChannelEndPoint@4a9f1e5d{/127.0.0.1:57661<->8443,CLOSED,ISHUT,OSHUT,-,-,30019/30000,null@0}{io=0/0,kio=-1,kro=-1}
2016-06-23 15:41:44.372:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-19: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c ran 
org.eclipse.jetty.io.ManagedSelector$2@4d448b29
2016-06-23 15:41:44.372:DBUG:oejuts.ExecuteProduceConsume:qtp1789550256-19: EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c produce exit
2016-06-23 15:41:44.372:DBUG:oejut.QueuedThreadPool:qtp1789550256-19: ran EPC 
Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@7e0af00c

_______________________________________________
jetty-users mailing list
[email protected]
To change your delivery options, retrieve your password, or unsubscribe from 
this list, visit
https://dev.eclipse.org/mailman/listinfo/jetty-users

Re: [jetty-users] WebSocket over SSL in Embedded Jetty 9

Reply via email to