Skip to main content

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
[jetty-users] Issue with HTTP2Client sample program

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


Back to the top