Did you setup your -Xbootclasspath properly for the alpn-boot jar specific for your version of Java? (this is a non-optional requirement for success with HTTP/2 on Java 8)
Joakim Erdfelt / [email protected] On Thu, Sep 10, 2015 at 9:01 PM, Michael Ivanov <[email protected]> wrote: > Hi all, > > Has anyone successfully run the HTTP2Client example program at > > https://github.com/eclipse/jetty.project/blob/master/jetty-http2/http2-client/src/test/java/org/eclipse/jetty/http2/client/Client.java > ? Can you share your setup or any ideas for debugging the following > issue? > > I am trying to run the example but I keep getting TimeoutException > while connecting to any host which supports HTTP/2 (webtide.com, > hghttp2.org, or google.com). I have seen a couple of posts about > similar issues on StackOverflow and this list and tried the few > suggestions that were given but it still does not work for me. I have > tried http2-client-9.3.0.RC1 through 9.3.4.RC0 and always get the same > error. > > See the following log with the timeout at the end (Sorry for the long > log, this is with DEBUG level logging turned on). > > 2015-09-10 20:27:08.723:INFO::main: Logging initialized @168ms > 2015-09-10 20:27:08.735:DBUG:oejuc.ContainerLifeCycle:main: > org.eclipse.jetty.http2.client.HTTP2Client@7229724f added > {SslContextFactory@4c873330(null,null),AUTO} > 2015-09-10 20:27:08.736:DBUG:oejuc.AbstractLifeCycle:main: starting > org.eclipse.jetty.http2.client.HTTP2Client@7229724f > 2015-09-10 20:27:08.743:DBUG:oejuc.ContainerLifeCycle:main: > org.eclipse.jetty.http2.client.HTTP2Client@7229724f added > {qtp558638686{STOPPED,8<=0<=200,i=0,q=0},AUTO} > 2015-09-10 20:27:08.744:DBUG:oejuc.ContainerLifeCycle:main: > org.eclipse.jetty.http2.client.HTTP2Client@7229724f added > {org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@16b98e56,AUTO} > 2015-09-10 20:27:08.745:DBUG:oejuc.ContainerLifeCycle:main: > org.eclipse.jetty.http2.client.HTTP2Client@7229724f added > {org.eclipse.jetty.io.MappedByteBufferPool@4f3f5b24,POJO} > 2015-09-10 20:27:08.747:DBUG:oejuc.ContainerLifeCycle:main: > org.eclipse.jetty.http2.client.HTTP2Client@7229724f added > {org.eclipse.jetty.http2.client.HTTP2ClientConnectionFactory@1d81eb93 > ,POJO} > 2015-09-10 20:27:08.747:DBUG:oejuc.ContainerLifeCycle:main: > org.eclipse.jetty.http2.client.HTTP2Client@7229724f added {[],POJO} > 2015-09-10 20:27:08.749:DBUG:oejuc.ContainerLifeCycle:main: > org.eclipse.jetty.http2.client.HTTP2Client@7229724f added > {org.eclipse.jetty.http2.client.HTTP2Client$ClientSelectorManager@12edcd21 > ,AUTO} > 2015-09-10 20:27:08.749:DBUG:oejuc.AbstractLifeCycle:main: starting > SslContextFactory@4c873330(null,null) > 2015-09-10 20:27:09.086:DBUG:oejus.SslContextFactory:main: Selected > Protocols [TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, > TLSv1.1, TLSv1.2] > 2015-09-10 20:27:09.090: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_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] > 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, TLS_ECDHE_ECDSA_WITH_RC4_128_SHA, > TLS_ECDHE_RSA_WITH_RC4_128_SHA, SSL_RSA_WITH_RC4_128_SHA, > TLS_ECDH_ECDSA_WITH_RC4_128_SHA, TLS_ECDH_RSA_WITH_RC4_128_SHA, > SSL_RSA_WITH_RC4_128_MD5, TLS_ECDH_anon_WITH_RC4_128_SHA, > SSL_DH_anon_WITH_RC4_128_MD5, 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, SSL_RSA_EXPORT_WITH_RC4_40_MD5, > SSL_DH_anon_EXPORT_WITH_RC4_40_MD5, 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_RC4_128_SHA, > TLS_KRB5_WITH_RC4_128_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, TLS_KRB5_EXPORT_WITH_RC4_40_SHA, > TLS_KRB5_EXPORT_WITH_RC4_40_MD5] > 2015-09-10 20:27:09.090:DBUG:oejuc.AbstractLifeCycle:main: STARTED > @538ms SslContextFactory@4c873330(null,null) > 2015-09-10 20:27:09.091:DBUG:oejuc.AbstractLifeCycle:main: starting > qtp558638686{STOPPED,8<=0<=200,i=0,q=0} > 2015-09-10 20:27:09.093:DBUG:oejuc.AbstractLifeCycle:main: STARTED > @541ms qtp558638686{STARTED,8<=8<=200,i=7,q=0} > 2015-09-10 20:27:09.093:DBUG:oejuc.AbstractLifeCycle:main: starting > org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@16b98e56 > 2015-09-10 20:27:09.095:DBUG:oejuc.AbstractLifeCycle:main: STARTED > @543ms org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@16b98e56 > 2015-09-10 20:27:09.095:DBUG:oejuc.AbstractLifeCycle:main: starting > org.eclipse.jetty.http2.client.HTTP2Client$ClientSelectorManager@12edcd21 > 2015-09-10 20:27:09.100:DBUG:oejuc.AbstractLifeCycle:main: starting > org.eclipse.jetty.io.ManagedSelector@32d992b2 id=0 keys=-1 selected=-1 > 2015-09-10 20:27:09.105:DBUG:oejuc.AbstractLifeCycle:main: STARTED > @553ms org.eclipse.jetty.io.ManagedSelector@32d992b2 id=0 keys=0 > selected=0 > 2015-09-10 20:27:09.106:DBUG:oejut.QueuedThreadPool:main: queue > org.eclipse.jetty.io.ManagedSelector@32d992b2 id=0 keys=0 selected=0 > 2015-09-10 20:27:09.106:DBUG:oejuc.AbstractLifeCycle:main: STARTED > @554ms > org.eclipse.jetty.http2.client.HTTP2Client$ClientSelectorManager@12edcd21 > 2015-09-10 20:27:09.106:DBUG:oejuc.AbstractLifeCycle:main: STARTED > @554ms org.eclipse.jetty.http2.client.HTTP2Client@7229724f > 2015-09-10 20:27:09.106:DBUG:oejut.QueuedThreadPool:qtp558638686-9: > run org.eclipse.jetty.io.ManagedSelector@32d992b2 id=0 keys=0 > selected=0 > 2015-09-10 20:27:09.106:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-9: > EPR Idle/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > execute > 2015-09-10 20:27:09.106:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-9: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > produce enter > 2015-09-10 20:27:09.106:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-9: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > producing > 2015-09-10 20:27:09.107:DBUG:oeji.ManagedSelector:qtp558638686-9: > Selector loop waiting on select > 2015-09-10 20:27:09.126:DBUG:oeji.ManagedSelector:main: Queued change > org.eclipse.jetty.io.ManagedSelector$Connect@1b604f19 on > org.eclipse.jetty.io.ManagedSelector@32d992b2 id=0 keys=0 selected=0 > 2015-09-10 20:27:09.127:DBUG:oeji.ManagedSelector:qtp558638686-9: > Selector loop woken up from select, 0/0 selected > 2015-09-10 20:27:09.128:DBUG:oeji.ManagedSelector:qtp558638686-9: > Running change org.eclipse.jetty.io.ManagedSelector$Connect@1b604f19 > 2015-09-10 20:27:09.128:DBUG:oeji.ManagedSelector:qtp558638686-9: > Selector loop waiting on select > 2015-09-10 20:27:09.423:DBUG:oeji.ManagedSelector:qtp558638686-9: > Selector loop woken up from select, 1/1 selected > 2015-09-10 20:27:09.426:DBUG:oeji.ManagedSelector:qtp558638686-9: > Connected true java.nio.channels.SocketChannel[connected > local=/192.168.1.112:61635 remote=webtide.com/107.22.210.238:443] > 2015-09-10 20:27:09.427:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-9: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > produced org.eclipse.jetty.io.ManagedSelector$1@448427fb > 2015-09-10 20:27:09.427:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-9: > EPR Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > dispatch > 2015-09-10 20:27:09.427:DBUG:oejut.QueuedThreadPool:qtp558638686-9: > queue EPR > Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > 2015-09-10 20:27:09.427:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-9: > EPR Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > run org.eclipse.jetty.io.ManagedSelector$1@448427fb > 2015-09-10 20:27:09.427:DBUG:oejut.QueuedThreadPool:qtp558638686-11: > run EPR Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > 2015-09-10 20:27:09.427:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-11: > EPR Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > run > 2015-09-10 20:27:09.427:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-11: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > produce enter > 2015-09-10 20:27:09.427:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-11: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > producing > 2015-09-10 20:27:09.428:DBUG:oeji.ManagedSelector:qtp558638686-11: > Selector loop waiting on select > 2015-09-10 20:27:09.439:DBUG:oeji.AbstractEndPoint:qtp558638686-9: > onOpen SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,-,-,8/30000,null}{io=0/0,kio=0,kro=8} > 2015-09-10 20:27:09.440:DBUG:oeji.IdleTimeout:qtp558638686-9: > SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,-,-,8/30000,null}{io=0/0,kio=0,kro=8} > idle timeout check, elapsed: 8 ms, remaining: 29992 ms > 2015-09-10 20:27:09.452:DBUG:oejus.SslContextFactory:qtp558638686-9: > Customize 429dbc69[SSLEngine[hostname=webtide.com port=443] > SSL_NULL_WITH_NULL_NULL] > 2015-09-10 20:27:09.459:DBUG:oeji.IdleTimeout:qtp558638686-9: > DecryptedEndPoint@332ed4b9{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,-,-,1/30000,null}->SelectChannelEndPoint@5bfdd059{ > webtide.com/107.22.210.238:443 > <->61635,Open,in,out,-,-,28/30000,null}{io=0/0,kio=0,kro=8} > idle timeout check, elapsed: 0 ms, remaining: 30000 ms > 2015-09-10 20:27:09.470:DBUG:oeji.AbstractConnection:qtp558638686-9: > onOpen SslConnection@7b60cff9{NEED_WRAP,eio=-1/-1,di=-1} -> > ALPNClientConnection@2e633158 > 2015-09-10 20:27:09.471:DBUG:oeji.AbstractConnection:qtp558638686-9: > onOpen ALPNClientConnection@2e633158 > 2015-09-10 20:27:09.471:DBUG:oejis.SslConnection:qtp558638686-9: > SslConnection@7b60cff9{NEED_WRAP,eio=-1/-1,di=-1} -> > ALPNClientConnection@2e633158 flush b[0]= > 2015-09-10 20:27:09.472:DBUG:oejis.SslConnection:qtp558638686-9: > SslConnection@7b60cff9{NEED_UNWRAP,eio=-1/220,di=-1} -> > ALPNClientConnection@2e633158 wrap Status = OK HandshakeStatus = > NEED_UNWRAP bytesConsumed = 0 bytesProduced = 220 > 2015-09-10 20:27:09.475:DBUG:oejis.SslConnection:qtp558638686-9: > SslConnection@7b60cff9{NEED_UNWRAP,eio=-1/220,di=-1} -> > ALPNClientConnection@2e633158 wrap OK > b[220]=16030300D7010000D3030355F24A0DB3A8B8C5A4D678244E6D...6964652E636F6D > 2015-09-10 20:27:09.477:DBUG:oeji.ChannelEndPoint:qtp558638686-9: > flushed 220 SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,-,-,45/30000,SslConnection}{io=0/0,kio=0,kro=8} > 2015-09-10 20:27:09.478:DBUG:oeji.ChannelEndPoint:qtp558638686-9: > filled 0 SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,-,-,1/30000,SslConnection}{io=0/0,kio=0,kro=8} > 2015-09-10 20:27:09.478:DBUG:oejis.SslConnection:qtp558638686-9: > SslConnection@7b60cff9{NEED_UNWRAP,eio=0/0,di=0} -> > ALPNClientConnection@2e633158 net=0 unwrap Status = BUFFER_UNDERFLOW > HandshakeStatus = NEED_UNWRAP bytesConsumed = 0 bytesProduced = 0 > 2015-09-10 20:27:09.479:DBUG:oejis.SslConnection:qtp558638686-9: > SslConnection@7b60cff9{NEED_UNWRAP,eio=0/0,di=0} -> > ALPNClientConnection@2e633158 filled b[0]= > 2015-09-10 20:27:09.479:DBUG:oejut.QueuedThreadPool:qtp558638686-9: > queue org.eclipse.jetty.io.ssl.SslConnection$1@3c8cca4c > 2015-09-10 20:27:09.479:DBUG:oejut.QueuedThreadPool:qtp558638686-10: > run org.eclipse.jetty.io.ssl.SslConnection$1@3c8cca4c > 2015-09-10 20:27:09.479:DBUG:oeji.AbstractConnection:qtp558638686-9: > fillInterested ALPNClientConnection@2e633158 > 2015-09-10 20:27:09.479:DBUG:oeji.WriteFlusher:qtp558638686-10: > completeWrite: WriteFlusher@372d28e5{IDLE} > 2015-09-10 20:27:09.479:DBUG:oejut.QueuedThreadPool:qtp558638686-10: > ran org.eclipse.jetty.io.ssl.SslConnection$1@3c8cca4c > 2015-09-10 20:27:09.479:DBUG:oeji.FillInterest:qtp558638686-9: > FillInterest@61eb0ae6{true,AC.ReadCB@2e633158 > {ALPNClientConnection@2e633158}} > register AC.ReadCB@2e633158{ALPNClientConnection@2e633158} > 2015-09-10 20:27:09.480:DBUG:oeji.FillInterest:qtp558638686-9: > FillInterest@61eb0ae6{true,AC.ReadCB@2e633158 > {ALPNClientConnection@2e633158}} > register AC.ReadCB@2e633158{ALPNClientConnection@2e633158} > 2015-09-10 20:27:09.481:DBUG:oeji.AbstractConnection:qtp558638686-9: > fillInterested SslConnection@7b60cff9{NEED_UNWRAP,eio=-1/-1,di=-1} -> > ALPNClientConnection@2e633158 > 2015-09-10 20:27:09.481:DBUG:oeji.FillInterest:qtp558638686-9: > FillInterest@28d452a7{true,AC.ReadCB@7b60cff9{SslConnection@7b60cff9 > {NEED_UNWRAP,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158}} register > AC.ReadCB@7b60cff9{SslConnection@7b60cff9{NEED_UNWRAP,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158} > 2015-09-10 20:27:09.481:DBUG:oeji.FillInterest:qtp558638686-9: > FillInterest@28d452a7{true,AC.ReadCB@7b60cff9{SslConnection@7b60cff9 > {NEED_UNWRAP,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158}} register > AC.ReadCB@7b60cff9{SslConnection@7b60cff9{NEED_UNWRAP,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158} > 2015-09-10 20:27:09.482:DBUG:oeji.SelectChannelEndPoint:qtp558638686-9: > changeInterests p=false 0->1 for > SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,1/30000,SslConnection}{io=0/1,kio=0,kro=8} > 2015-09-10 20:27:09.482:DBUG:oeji.ManagedSelector:qtp558638686-9: > Queued change SelectChannelEndPoint@5bfdd059{ > webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,1/30000,SslConnection}{io=0/1,kio=0,kro=8}:runUpdateKey > on org.eclipse.jetty.io.ManagedSelector@32d992b2 id=0 keys=1 > selected=0 > 2015-09-10 20:27:09.483:DBUG:oeji.ManagedSelector:qtp558638686-11: > Selector loop woken up from select, 0/1 selected > 2015-09-10 20:27:09.482:DBUG:oeji.ManagedSelector:qtp558638686-9: > Created SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,2/30000,SslConnection}{io=0/1,kio=0,kro=8} > 2015-09-10 20:27:09.483:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-9: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > ran org.eclipse.jetty.io.ManagedSelector$1@448427fb > 2015-09-10 20:27:09.483:DBUG:oeji.ManagedSelector:qtp558638686-11: > Running change SelectChannelEndPoint@5bfdd059{ > webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,2/30000,SslConnection}{io=0/1,kio=0,kro=8}:runUpdateKey > 2015-09-10 20:27:09.483:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-9: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > produce exit > 2015-09-10 20:27:09.483:DBUG:oejut.QueuedThreadPool:qtp558638686-9: > ran org.eclipse.jetty.io.ManagedSelector@32d992b2 id=0 keys=1 > selected=0 > 2015-09-10 20:27:09.483:DBUG:oeji.SelectChannelEndPoint:qtp558638686-11: > Key interests updated 0 -> 1 on > SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,2/30000,SslConnection}{io=1/1,kio=1,kro=8} > 2015-09-10 20:27:09.483:DBUG:oeji.ManagedSelector:qtp558638686-11: > Selector loop waiting on select > 2015-09-10 20:27:09.583:DBUG:oeji.ManagedSelector:qtp558638686-11: > Selector loop woken up from select, 1/1 selected > 2015-09-10 20:27:09.584:DBUG:oeji.SelectChannelEndPoint:qtp558638686-11: > onSelected 1->0 r=true w=false for > SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,103/30000,SslConnection}{io=1/0,kio=1,kro=1} > 2015-09-10 20:27:09.584:DBUG:oeji.SelectChannelEndPoint:qtp558638686-11: > task SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,103/30000,SslConnection}{io=1/0,kio=1,kro=1}:runFillable > 2015-09-10 20:27:09.585:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-11: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > produced SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,104/30000,SslConnection}{io=1/0,kio=1,kro=1}:runFillable > 2015-09-10 20:27:09.585:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-11: > EPR Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > dispatch > 2015-09-10 20:27:09.585:DBUG:oejut.QueuedThreadPool:qtp558638686-11: > queue EPR > Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > 2015-09-10 20:27:09.585:DBUG:oejut.QueuedThreadPool:qtp558638686-15: > run EPR Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > 2015-09-10 20:27:09.585:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-15: > EPR Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > run > 2015-09-10 20:27:09.585:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-15: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > produce enter > 2015-09-10 20:27:09.585:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-15: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > producing > 2015-09-10 20:27:09.585:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-11: > EPR Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > run SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,104/30000,SslConnection}{io=1/0,kio=1,kro=1}:runFillable > 2015-09-10 20:27:09.585:DBUG:oeji.SelectChannelEndPoint:qtp558638686-15: > Key interests updated 1 -> 0 on > SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,104/30000,SslConnection}{io=0/0,kio=0,kro=1} > 2015-09-10 20:27:09.586:DBUG:oeji.ManagedSelector:qtp558638686-15: > Selector loop waiting on select > 2015-09-10 20:27:09.585:DBUG:oeji.FillInterest:qtp558638686-11: > FillInterest@28d452a7{true,AC.ReadCB@7b60cff9{SslConnection@7b60cff9 > {NEED_UNWRAP,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158}} fillable > AC.ReadCB@7b60cff9{SslConnection@7b60cff9{NEED_UNWRAP,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158} > 2015-09-10 20:27:09.586:DBUG:oejis.SslConnection:qtp558638686-11: > onFillable enter > DecryptedEndPoint@332ed4b9{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,107/30000,ALPNClientConnection}->SelectChannelEndPoint@5bfdd059 > {webtide.com/107.22.210.238:443 > <->61635,Open,in,out,-,-,105/30000,SslConnection}{io=0/0,kio=0,kro=1} > 2015-09-10 20:27:09.587:DBUG:oeji.FillInterest:qtp558638686-11: > FillInterest@61eb0ae6{true,AC.ReadCB@2e633158 > {ALPNClientConnection@2e633158}} > fillable AC.ReadCB@2e633158{ALPNClientConnection@2e633158} > 2015-09-10 20:27:09.587:DBUG:oeji.ChannelEndPoint:qtp558638686-11: > filled 5839 SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,-,-,106/30000,SslConnection}{io=0/0,kio=0,kro=1} > 2015-09-10 20:27:09.588:DBUG:oejis.SslConnection:qtp558638686-11: > SslConnection@7b60cff9{NEED_TASK,eio=0/-1,di=0} -> > ALPNClientConnection@2e633158 net=5839 unwrap Status = OK > HandshakeStatus = NEED_TASK bytesConsumed = 5839 bytesProduced = 0 > 2015-09-10 20:27:09.588:DBUG:oejis.SslConnection:qtp558638686-11: > SslConnection@7b60cff9{NEED_TASK,eio=0/-1,di=0} -> > ALPNClientConnection@2e633158 filled b[0]= > 2015-09-10 20:27:09.627:DBUG:oejis.SslConnection:qtp558638686-11: > SslConnection@7b60cff9{NEED_WRAP,eio=0/-1,di=0} -> > ALPNClientConnection@2e633158 net=5839 unwrap Status = OK > HandshakeStatus = NEED_WRAP bytesConsumed = 0 bytesProduced = 0 > 2015-09-10 20:27:09.627:DBUG:oejis.SslConnection:qtp558638686-11: > SslConnection@7b60cff9{NEED_WRAP,eio=0/-1,di=0} -> > ALPNClientConnection@2e633158 filled b[0]= > 2015-09-10 20:27:09.627:DBUG:oejis.SslConnection:qtp558638686-11: > SslConnection@7b60cff9{NEED_WRAP,eio=0/-1,di=0} -> > ALPNClientConnection@2e633158 flush b[0]= > 2015-09-10 20:27:09.628:DBUG:oejis.SslConnection:qtp558638686-11: > SslConnection@7b60cff9{NEED_WRAP,eio=0/75,di=0} -> > ALPNClientConnection@2e633158 wrap Status = OK HandshakeStatus = > NEED_WRAP bytesConsumed = 0 bytesProduced = 75 > 2015-09-10 20:27:09.628:DBUG:oejis.SslConnection:qtp558638686-11: > SslConnection@7b60cff9{NEED_WRAP,eio=0/75,di=0} -> > ALPNClientConnection@2e633158 wrap OK > b[75]=16030300461000004241044389BD090550DA82AA9C056F4AC8...622F5E562E0FAD > 2015-09-10 20:27:09.628:DBUG:oeji.ChannelEndPoint:qtp558638686-11: > flushed 75 SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,-,-,41/30000,SslConnection}{io=0/0,kio=0,kro=1} > 2015-09-10 20:27:09.628:DBUG:oejis.SslConnection:qtp558638686-11: > SslConnection@7b60cff9{NEED_WRAP,eio=0/6,di=0} -> > ALPNClientConnection@2e633158 wrap Status = OK HandshakeStatus = > NEED_WRAP bytesConsumed = 0 bytesProduced = 6 > 2015-09-10 20:27:09.628:DBUG:oejis.SslConnection:qtp558638686-11: > SslConnection@7b60cff9{NEED_WRAP,eio=0/6,di=0} -> > ALPNClientConnection@2e633158 wrap OK b[6]=140303000101 > 2015-09-10 20:27:09.629:DBUG:oeji.ChannelEndPoint:qtp558638686-11: > flushed 6 SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,-,-,1/30000,SslConnection}{io=0/0,kio=0,kro=1} > 2015-09-10 20:27:09.629:DBUG:oejis.SslConnection:qtp558638686-11: > SslConnection@7b60cff9{NEED_UNWRAP,eio=0/45,di=0} -> > ALPNClientConnection@2e633158 wrap Status = OK HandshakeStatus = > NEED_UNWRAP bytesConsumed = 0 bytesProduced = 45 > 2015-09-10 20:27:09.629:DBUG:oejis.SslConnection:qtp558638686-11: > SslConnection@7b60cff9{NEED_UNWRAP,eio=0/45,di=0} -> > ALPNClientConnection@2e633158 wrap OK > b[45]=1603030028000000000000000048531B1A3A09B3C7C9CAFB2D...C6D0F449381E8C > 2015-09-10 20:27:09.629:DBUG:oeji.ChannelEndPoint:qtp558638686-11: > flushed 45 SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,-,-,0/30000,SslConnection}{io=0/0,kio=0,kro=1} > 2015-09-10 20:27:09.629:DBUG:oejis.SslConnection:qtp558638686-11: > SslConnection@7b60cff9{NEED_UNWRAP,eio=0/-1,di=0} -> > ALPNClientConnection@2e633158 net=5839 unwrap Status = > BUFFER_UNDERFLOW HandshakeStatus = NEED_UNWRAP bytesConsumed = 0 > bytesProduced = 0 > 2015-09-10 20:27:09.629:DBUG:oejis.SslConnection:qtp558638686-11: > SslConnection@7b60cff9{NEED_UNWRAP,eio=0/-1,di=0} -> > ALPNClientConnection@2e633158 filled b[0]= > 2015-09-10 20:27:09.630:DBUG:oeji.ChannelEndPoint:qtp558638686-11: > filled 0 SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,-,-,1/30000,SslConnection}{io=0/0,kio=0,kro=1} > 2015-09-10 20:27:09.630:DBUG:oejis.SslConnection:qtp558638686-11: > SslConnection@7b60cff9{NEED_UNWRAP,eio=0/-1,di=0} -> > ALPNClientConnection@2e633158 net=0 unwrap Status = BUFFER_UNDERFLOW > HandshakeStatus = NEED_UNWRAP bytesConsumed = 0 bytesProduced = 0 > 2015-09-10 20:27:09.630:DBUG:oejis.SslConnection:qtp558638686-11: > SslConnection@7b60cff9{NEED_UNWRAP,eio=0/-1,di=0} -> > ALPNClientConnection@2e633158 filled b[0]= > 2015-09-10 20:27:09.630:DBUG:oeji.AbstractConnection:qtp558638686-11: > fillInterested ALPNClientConnection@2e633158 > 2015-09-10 20:27:09.630:DBUG:oeji.FillInterest:qtp558638686-11: > FillInterest@61eb0ae6{true,AC.ReadCB@2e633158 > {ALPNClientConnection@2e633158}} > register AC.ReadCB@2e633158{ALPNClientConnection@2e633158} > 2015-09-10 20:27:09.631:DBUG:oeji.FillInterest:qtp558638686-11: > FillInterest@61eb0ae6{true,AC.ReadCB@2e633158 > {ALPNClientConnection@2e633158}} > register AC.ReadCB@2e633158{ALPNClientConnection@2e633158} > 2015-09-10 20:27:09.631:DBUG:oeji.AbstractConnection:qtp558638686-11: > fillInterested SslConnection@7b60cff9{NEED_UNWRAP,eio=-1/-1,di=-1} -> > ALPNClientConnection@2e633158 > 2015-09-10 20:27:09.631:DBUG:oeji.FillInterest:qtp558638686-11: > FillInterest@28d452a7{true,AC.ReadCB@7b60cff9{SslConnection@7b60cff9 > {NEED_UNWRAP,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158}} register > AC.ReadCB@7b60cff9{SslConnection@7b60cff9{NEED_UNWRAP,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158} > 2015-09-10 20:27:09.631:DBUG:oeji.FillInterest:qtp558638686-11: > FillInterest@28d452a7{true,AC.ReadCB@7b60cff9{SslConnection@7b60cff9 > {NEED_UNWRAP,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158}} register > AC.ReadCB@7b60cff9{SslConnection@7b60cff9{NEED_UNWRAP,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158} > 2015-09-10 20:27:09.632:DBUG:oeji.SelectChannelEndPoint:qtp558638686-11: > changeInterests p=false 0->1 for > SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,1/30000,SslConnection}{io=0/1,kio=0,kro=1} > 2015-09-10 20:27:09.632:DBUG:oeji.ManagedSelector:qtp558638686-11: > Queued change SelectChannelEndPoint@5bfdd059{ > webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,1/30000,SslConnection}{io=0/1,kio=0,kro=1}:runUpdateKey > on org.eclipse.jetty.io.ManagedSelector@32d992b2 id=0 keys=1 > selected=0 > 2015-09-10 20:27:09.632:DBUG:oejis.SslConnection:qtp558638686-11: > onFillable exit > DecryptedEndPoint@332ed4b9{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,2/30000,ALPNClientConnection}->SelectChannelEndPoint@5bfdd059 > {webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,1/30000,SslConnection}{io=0/1,kio=0,kro=1} > 2015-09-10 20:27:09.633:DBUG:oeji.ManagedSelector:qtp558638686-15: > Selector loop woken up from select, 0/1 selected > 2015-09-10 20:27:09.633:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-11: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > ran SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,2/30000,SslConnection}{io=0/1,kio=0,kro=1}:runFillable > 2015-09-10 20:27:09.633:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-11: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > produce exit > 2015-09-10 20:27:09.633:DBUG:oejut.QueuedThreadPool:qtp558638686-11: > ran EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > 2015-09-10 20:27:09.633:DBUG:oeji.ManagedSelector:qtp558638686-15: > Running change SelectChannelEndPoint@5bfdd059{ > webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,2/30000,SslConnection}{io=0/1,kio=0,kro=1}:runUpdateKey > 2015-09-10 20:27:09.633:DBUG:oeji.SelectChannelEndPoint:qtp558638686-15: > Key interests updated 0 -> 1 on > SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,2/30000,SslConnection}{io=1/1,kio=1,kro=1} > 2015-09-10 20:27:09.634:DBUG:oeji.ManagedSelector:qtp558638686-15: > Selector loop waiting on select > 2015-09-10 20:27:09.730:DBUG:oeji.ManagedSelector:qtp558638686-15: > Selector loop woken up from select, 1/1 selected > 2015-09-10 20:27:09.730:DBUG:oeji.SelectChannelEndPoint:qtp558638686-15: > onSelected 1->0 r=true w=false for > SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,99/30000,SslConnection}{io=1/0,kio=1,kro=1} > 2015-09-10 20:27:09.731:DBUG:oeji.SelectChannelEndPoint:qtp558638686-15: > task SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,100/30000,SslConnection}{io=1/0,kio=1,kro=1}:runFillable > 2015-09-10 20:27:09.731:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-15: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > produced SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,100/30000,SslConnection}{io=1/0,kio=1,kro=1}:runFillable > 2015-09-10 20:27:09.731:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-15: > EPR Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > dispatch > 2015-09-10 20:27:09.731:DBUG:oejut.QueuedThreadPool:qtp558638686-15: > queue EPR > Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > 2015-09-10 20:27:09.732:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-15: > EPR Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > run SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,101/30000,SslConnection}{io=1/0,kio=1,kro=1}:runFillable > 2015-09-10 20:27:09.732:DBUG:oejut.QueuedThreadPool:qtp558638686-16: > run EPR Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > 2015-09-10 20:27:09.732:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-16: > EPR Pend/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > run > 2015-09-10 20:27:09.732:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-16: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > produce enter > 2015-09-10 20:27:09.732:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-16: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > producing > 2015-09-10 20:27:09.732:DBUG:oeji.FillInterest:qtp558638686-15: > FillInterest@28d452a7{true,AC.ReadCB@7b60cff9{SslConnection@7b60cff9 > {NEED_UNWRAP,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158}} fillable > AC.ReadCB@7b60cff9{SslConnection@7b60cff9{NEED_UNWRAP,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158} > 2015-09-10 20:27:09.732:DBUG:oeji.SelectChannelEndPoint:qtp558638686-16: > Key interests updated 1 -> 0 on > SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,101/30000,SslConnection}{io=0/0,kio=0,kro=1} > 2015-09-10 20:27:09.733:DBUG:oeji.ManagedSelector:qtp558638686-16: > Selector loop waiting on select > 2015-09-10 20:27:09.732:DBUG:oejis.SslConnection:qtp558638686-15: > onFillable enter > DecryptedEndPoint@332ed4b9{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,103/30000,ALPNClientConnection}->SelectChannelEndPoint@5bfdd059 > {webtide.com/107.22.210.238:443 > <->61635,Open,in,out,-,-,102/30000,SslConnection}{io=0/0,kio=0,kro=1} > 2015-09-10 20:27:09.733:DBUG:oeji.FillInterest:qtp558638686-15: > FillInterest@61eb0ae6{true,AC.ReadCB@2e633158 > {ALPNClientConnection@2e633158}} > fillable AC.ReadCB@2e633158{ALPNClientConnection@2e633158} > 2015-09-10 20:27:09.733:DBUG:oeji.ChannelEndPoint:qtp558638686-15: > filled 51 SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,-,-,102/30000,SslConnection}{io=0/0,kio=0,kro=1} > 2015-09-10 20:27:09.734:DBUG:oejis.SslConnection:qtp558638686-15: > SslConnection@7b60cff9{NEED_UNWRAP,eio=45/-1,di=0} -> > ALPNClientConnection@2e633158 net=51 unwrap Status = OK > HandshakeStatus = NEED_UNWRAP bytesConsumed = 6 bytesProduced = 0 > 2015-09-10 20:27:09.734:DBUG:oejis.SslConnection:qtp558638686-15: > SslConnection@7b60cff9{NEED_UNWRAP,eio=45/-1,di=0} -> > ALPNClientConnection@2e633158 filled b[0]= > 2015-09-10 20:27:09.736:DBUG:oejis.SslConnection:qtp558638686-15: > SslConnection@7b60cff9{NOT_HANDSHAKING,eio=0/-1,di=0} -> > ALPNClientConnection@2e633158 net=51 unwrap Status = OK > HandshakeStatus = FINISHED bytesConsumed = 45 bytesProduced = 0 > 2015-09-10 20:27:09.736:DBUG:oejis.SslConnection:qtp558638686-15: > SslConnection@7b60cff9{NOT_HANDSHAKING,eio=0/-1,di=0} -> > ALPNClientConnection@2e633158 filled b[0]= > 2015-09-10 20:27:09.736:DBUG:oejis.SslConnection:qtp558638686-15: > SslConnection@7b60cff9{NOT_HANDSHAKING,eio=0/-1,di=0} -> > ALPNClientConnection@2e633158 client handshook > TLSv1.2/TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256 > 2015-09-10 20:27:09.736:DBUG:oejis.SslConnection:qtp558638686-15: > SslConnection@7b60cff9{NOT_HANDSHAKING,eio=0/-1,di=0} -> > ALPNClientConnection@2e633158 net=51 unwrap Status = BUFFER_UNDERFLOW > HandshakeStatus = NOT_HANDSHAKING bytesConsumed = 0 bytesProduced = 0 > 2015-09-10 20:27:09.736:DBUG:oejis.SslConnection:qtp558638686-15: > SslConnection@7b60cff9{NOT_HANDSHAKING,eio=0/-1,di=0} -> > ALPNClientConnection@2e633158 filled b[0]= > 2015-09-10 20:27:09.736:DBUG:oeji.ChannelEndPoint:qtp558638686-15: > filled 0 SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,-,-,2/30000,SslConnection}{io=0/0,kio=0,kro=1} > 2015-09-10 20:27:09.737:DBUG:oejis.SslConnection:qtp558638686-15: > SslConnection@7b60cff9{NOT_HANDSHAKING,eio=0/-1,di=0} -> > ALPNClientConnection@2e633158 net=0 unwrap Status = BUFFER_UNDERFLOW > HandshakeStatus = NOT_HANDSHAKING bytesConsumed = 0 bytesProduced = 0 > 2015-09-10 20:27:09.737:DBUG:oejis.SslConnection:qtp558638686-15: > SslConnection@7b60cff9{NOT_HANDSHAKING,eio=0/-1,di=0} -> > ALPNClientConnection@2e633158 filled b[0]= > 2015-09-10 20:27:09.737:DBUG:oeji.AbstractConnection:qtp558638686-15: > fillInterested ALPNClientConnection@2e633158 > 2015-09-10 20:27:09.737:DBUG:oeji.FillInterest:qtp558638686-15: > FillInterest@61eb0ae6{true,AC.ReadCB@2e633158 > {ALPNClientConnection@2e633158}} > register AC.ReadCB@2e633158{ALPNClientConnection@2e633158} > 2015-09-10 20:27:09.737:DBUG:oeji.FillInterest:qtp558638686-15: > FillInterest@61eb0ae6{true,AC.ReadCB@2e633158 > {ALPNClientConnection@2e633158}} > register AC.ReadCB@2e633158{ALPNClientConnection@2e633158} > 2015-09-10 20:27:09.737:DBUG:oeji.AbstractConnection:qtp558638686-15: > fillInterested SslConnection@7b60cff9{NOT_HANDSHAKING,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158 > 2015-09-10 20:27:09.738:DBUG:oeji.FillInterest:qtp558638686-15: > FillInterest@28d452a7{true,AC.ReadCB@7b60cff9{SslConnection@7b60cff9 > {NOT_HANDSHAKING,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158}} register > AC.ReadCB@7b60cff9{SslConnection@7b60cff9{NOT_HANDSHAKING,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158} > 2015-09-10 20:27:09.738:DBUG:oeji.FillInterest:qtp558638686-15: > FillInterest@28d452a7{true,AC.ReadCB@7b60cff9{SslConnection@7b60cff9 > {NOT_HANDSHAKING,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158}} register > AC.ReadCB@7b60cff9{SslConnection@7b60cff9{NOT_HANDSHAKING,eio=-1/-1,di=-1} > -> ALPNClientConnection@2e633158} > 2015-09-10 20:27:09.738:DBUG:oeji.SelectChannelEndPoint:qtp558638686-15: > changeInterests p=false 0->1 for > SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,0/30000,SslConnection}{io=0/1,kio=0,kro=1} > 2015-09-10 20:27:09.738:DBUG:oeji.ManagedSelector:qtp558638686-15: > Queued change SelectChannelEndPoint@5bfdd059{ > webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,0/30000,SslConnection}{io=0/1,kio=0,kro=1}:runUpdateKey > on org.eclipse.jetty.io.ManagedSelector@32d992b2 id=0 keys=1 > selected=0 > 2015-09-10 20:27:09.739:DBUG:oeji.ManagedSelector:qtp558638686-16: > Selector loop woken up from select, 0/1 selected > 2015-09-10 20:27:09.739:DBUG:oeji.ManagedSelector:qtp558638686-16: > Running change SelectChannelEndPoint@5bfdd059{ > webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,1/30000,SslConnection}{io=0/1,kio=0,kro=1}:runUpdateKey > 2015-09-10 20:27:09.739:DBUG:oejis.SslConnection:qtp558638686-15: > onFillable exit > DecryptedEndPoint@332ed4b9{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,2/30000,ALPNClientConnection}->SelectChannelEndPoint@5bfdd059 > {webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,1/30000,SslConnection}{io=1/1,kio=0,kro=1} > 2015-09-10 20:27:09.739:DBUG:oeji.SelectChannelEndPoint:qtp558638686-16: > Key interests updated 0 -> 1 on > SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,1/30000,SslConnection}{io=1/1,kio=1,kro=1} > 2015-09-10 20:27:09.739:DBUG:oeji.ManagedSelector:qtp558638686-16: > Selector loop waiting on select > 2015-09-10 20:27:09.739:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-15: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > ran SelectChannelEndPoint@5bfdd059{webtide.com/107.22.210.238:443 > <->61635,Open,in,out,R,-,1/30000,SslConnection}{io=1/1,kio=1,kro=1}:runFillable > 2015-09-10 20:27:09.739:DBUG:oejuts.ExecuteProduceConsume:qtp558638686-15: > EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > produce exit > 2015-09-10 20:27:09.739:DBUG:oejut.QueuedThreadPool:qtp558638686-15: > ran EPR Prod/org.eclipse.jetty.io.ManagedSelector$SelectorProducer@6f1f1d7f > Exception in thread "main" java.util.concurrent.TimeoutException > at org.eclipse.jetty.util.FuturePromise.get(FuturePromise.java:130) > at org.eclipse.jetty.http2.client.Client.main(Client.java:55) > > > Thanks, > > Mike > _______________________________________________ > 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 >
_______________________________________________ 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
