https://issues.apache.org/jira/browse/HTTPCLIENT-2145
On Thu, Apr 1, 2021 at 2:31 PM Oleg Kalnichevski <[email protected]> wrote: > On Thu, 2021-04-01 at 11:28 -0700, Ryan Schmitt wrote: > > I've discovered that HTTP/2 connection validation hangs forever if a > > GOAWAY > > frame has previously been received on that connection. I've captured > > DEBUG > > logs showing the effect of validating such a connection. > > > > > Hi Ryan > > Could you please raise a JIRA for this defect? I will look into it > tomorrow. > > Do you happen to know what software is running on the server side? > > Oleg > > > Without inactive connection validation: > > 01 Apr 2021 11:17:32,251 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.async.InternalAbstractHttpAsyncClient > > : > > ex-00000002: preparing request execution > > 01 Apr 2021 11:17:32,252 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.async.AsyncProtocolExec: ex-00000002: > > target auth state: UNCHALLENGED > > 01 Apr 2021 11:17:32,252 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.async.AsyncProtocolExec: ex-00000002: > > proxy > > auth state: UNCHALLENGED > > 01 Apr 2021 11:17:32,252 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.async.AsyncConnectExec: ex-00000002: > > acquiring connection with route {}->http://localhost:8000 > > 01 Apr 2021 11:17:32,252 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.async.InternalHttpAsyncClient: ex- > > 00000002: > > acquiring endpoint (2500 MILLISECONDS) > > 01 Apr 2021 11:17:32,252 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.nio.PoolingAsyncClientConnectionManag > > er: > > ex-00000002: endpoint lease request (2500 MILLISECONDS) [route: > > {}->http://localhost:8000][total available: 1; route allocated: 1 of > > 2147483647; total allocated: 1 of 2147483647] > > 01 Apr 2021 11:17:32,252 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.nio.PoolingAsyncClientConnectionManag > > er: > > ex-00000002: endpoint leased [route: {}->http://localhost:8000][total > > available: 0; route allocated: 1 of 2147483647; total allocated: 1 of > > 2147483647] > > 01 Apr 2021 11:17:32,253 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.nio.PoolingAsyncClientConnectionManag > > er: > > ex-00000002: acquired ep-00000001 > > 01 Apr 2021 11:17:32,253 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.nio.DefaultManagedAsyncClientConnecti > > on: > > c-00000000: Shutdown connection IMMEDIATE > > 01 Apr 2021 11:17:32,253 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.async.InternalHttpAsyncClient: ex- > > 00000002: > > acquired endpoint ep-00000001 > > 01 Apr 2021 11:17:32,253 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.async.InternalHttpAsyncClient: ep- > > 00000001: > > connecting endpoint (2500 MILLISECONDS) > > 01 Apr 2021 11:17:32,253 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.nio.PoolingAsyncClientConnectionManag > > er: > > ep-00000001: connecting endpoint to http://localhost:8000 (2500 > > MILLISECONDS) > > 01 Apr 2021 11:17:32,253 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.nio.MultihomeIOSessionRequester: > > http://localhost:8000: resolving remote address > > 01 Apr 2021 11:17:32,253 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.nio.MultihomeIOSessionRequester: > > http://localhost:8000: resolved to [localhost/127.0.0.1, > > localhost/0:0:0:0:0:0:0:1] > > 01 Apr 2021 11:17:32,253 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.nio.MultihomeIOSessionRequester: > > http://localhost:8000: connecting null to localhost/127.0.0.1:8000 > > (2500 > > MILLISECONDS) > > 01 Apr 2021 11:17:32,254 [DEBUG] (httpclient-dispatch-2) > > org.apache.hc.client5.http.impl.nio.MultihomeIOSessionRequester: > > http://localhost:8000: connected c-00000001 /127.0.0.1:50735- > > >localhost/ > > 127.0.0.1:8000 > > 01 Apr 2021 11:17:32,254 [DEBUG] (httpclient-dispatch-2) > > org.apache.hc.client5.http.impl.nio.PoolingAsyncClientConnectionManag > > er: > > ep-00000001: connected c-00000001 > > 01 Apr 2021 11:17:32,254 [DEBUG] (httpclient-dispatch-2) > > org.apache.hc.client5.http.impl.async.InternalHttpAsyncClient: ep- > > 00000001: > > endpoint connected > > 01 Apr 2021 11:17:32,254 [DEBUG] (httpclient-dispatch-2) > > org.apache.hc.client5.http.impl.async.AsyncConnectExec: ex-00000002: > > connected to target > > 01 Apr 2021 11:17:32,254 [DEBUG] (httpclient-dispatch-2) > > org.apache.hc.client5.http.impl.async.AsyncConnectExec: ex-00000002: > > route > > fully established > > > > With inactive connection validation: > > 01 Apr 2021 11:00:19,656 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.async.InternalAbstractHttpAsyncClient > > : > > ex-00000002: preparing request execution > > 01 Apr 2021 11:00:19,657 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.async.AsyncProtocolExec: ex-00000002: > > target auth state: UNCHALLENGED > > 01 Apr 2021 11:00:19,657 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.async.AsyncProtocolExec: ex-00000002: > > proxy > > auth state: UNCHALLENGED > > 01 Apr 2021 11:00:19,657 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.async.AsyncConnectExec: ex-00000002: > > acquiring connection with route {}->http://localhost:8000 > > 01 Apr 2021 11:00:19,657 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.async.InternalHttpAsyncClient: ex- > > 00000002: > > acquiring endpoint (2500 MILLISECONDS) > > 01 Apr 2021 11:00:19,657 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.nio.PoolingAsyncClientConnectionManag > > er: > > ex-00000002: endpoint lease request (2500 MILLISECONDS) [route: > > {}->http://localhost:8000][total available: 1; route allocated: 1 of > > 2147483647; total allocated: 1 of 2147483647] > > 01 Apr 2021 11:00:19,658 [DEBUG] (coral-client-orchestrator-19) > > org.apache.hc.client5.http.impl.nio.DefaultManagedAsyncClientConnecti > > on: > > c-00000000: PingCommand with IMMEDIATE priority > > [ nothing happens ] > > > --------------------------------------------------------------------- > To unsubscribe, e-mail: [email protected] > For additional commands, e-mail: [email protected] > >
