Re: [jetty-users] Issue with HTTP2Client sample program

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
Re: [jetty-users] Issue with HTTP2Client sample program
From: Michael Ivanov <mivanov@xxxxxxxxx>
Date: Thu, 10 Sep 2015 23:16:33 -0700
Delivered-to: jetty-users@xxxxxxxxxxx
List-archive: <https://dev.eclipse.org/mailman/private/jetty-users>
List-help: <mailto:jetty-users-request@eclipse.org?subject=help>
List-subscribe: <https://dev.eclipse.org/mailman/listinfo/jetty-users>, <mailto:jetty-users-request@eclipse.org?subject=subscribe>
List-unsubscribe: <https://dev.eclipse.org/mailman/options/jetty-users>, <mailto:jetty-users-request@eclipse.org?subject=unsubscribe>
That was it. I thought I had set it up properly, but I did not give
the absolute path to the alpn-boot jar file. I took it for granted
that I would see a different error if it couldn't be found. I was
wrong. Giving the absolute path to the alpn-boot jar fixed the issue.
Thanks Joakim!

Mike

On Thu, Sep 10, 2015 at 9:40 PM, Joakim Erdfelt <joakim@xxxxxxxxxxx> wrote:
> 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 / joakim@xxxxxxxxxxx
>
> On Thu, Sep 10, 2015 at 9:01 PM, Michael Ivanov <mivanov@xxxxxxxxx> 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
>> jetty-users@xxxxxxxxxxx
>> 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
> jetty-users@xxxxxxxxxxx
> To change your delivery options, retrieve your password, or unsubscribe from
> this list, visit
> https://dev.eclipse.org/mailman/listinfo/jetty-users
References:
- [jetty-users] Issue with HTTP2Client sample program
  - From: Michael Ivanov
- Re: [jetty-users] Issue with HTTP2Client sample program
  - From: Joakim Erdfelt
Prev by Date: Re: [jetty-users] Issue with HTTP2Client sample program
Next by Date: [jetty-users] About web application class loading (property setParentLoaderPriority)
Previous by thread: Re: [jetty-users] Issue with HTTP2Client sample program
Next by thread: [jetty-users] About web application class loading (property setParentLoaderPriority)
Index(es):
- Date
- Thread
Breadcrumbs
