Mailing-List: contact dev-help@httpd.apache.org; run by ezmlm
Precedence: bulk
Reply-To: dev@httpd.apache.org
Content-Type: text/plain; charset=windows-1252
Mime-Version: 1.0 (Mac OS X Mail 9.3 \(3124\))
Subject: Re: mod_http2 - h2_session(): connections get's closed on graceful restart
From: Stefan Eissing <stefan.eissing@greenbytes.de>
In-Reply-To: <1e3ff06a-7d3b-14e8-be7c-3633ca6b5c72@profihost.ag>
Date: Wed, 24 Aug 2016 11:12:50 +0200
Cc: dev@httpd.apache.org
Content-Transfer-Encoding: quoted-printable
Message-Id: <D493EA67-D130-4AEE-99BD-79CBB51C376B@greenbytes.de>
References: <57B99DF9.60004@profihost.ag> <47944231-A1D3-4691-9894-6E78694377FC@greenbytes.de> <1e3ff06a-7d3b-14e8-be7c-3633ca6b5c72@profihost.ag>
To: Stefan Priebe - Profihost AG <s.priebe@profihost.ag>
archived-at: Wed, 24 Aug 2016 09:13:04 -0000

Thanks, I am almost ready with a new version that handles that =
correctly.

> Am 24.08.2016 um 10:18 schrieb Stefan Priebe - Profihost AG =
<s.priebe@profihost.ag>:
>=20
> Hi Stefan,
>=20
> were you able to reproduce? Did you need any help?
>=20
> Greets,
> Stefan
> Am 22.08.2016 um 18:07 schrieb Stefan Eissing:
>> Hi Stefan,
>>=20
>> I am looking into this. It is definitely a bug in mod_http2.
>>=20
>>> Am 21.08.2016 um 14:26 schrieb Stefan Priebe =
<s.priebe@profihost.ag>:
>>>=20
>>> Hi,
>>>=20
>>> i'm running apache 2.4.23 with nghttpd 1.13 and i'm wondoring if it =
is correct that chrome says:
>>> ERR_CONNECTION_CLOSED
>>>=20
>>> while loading a page when the webserver does a graceful reload. This =
does not happen with http 1/1 and mod_ssl.
>>>=20
>>> The log is below.
>>>=20
>>> Thanks,
>>> Stefan
>>>=20
>>>=20
>>> [Sun Aug 21 14:15:43.779555 2016] [http2:debug] [pid 29625:tid =
140311678985984] h2_session.c(1703): [client X.X.X.X:53763] AH03078: =
h2_session(0): transit [WAIT] -- wait cycle --> [BUSY]
>>> [Sun Aug 21 14:15:43.779619 2016] [http2:debug] [pid 29625:tid =
140311678985984] h2_session.c(1703): [client X.X.X.X:53763] AH03078: =
h2_session(0): transit [BUSY] -- no io --> [WAIT]
>>> [Sun Aug 21 14:15:43.979704 2016] [http2:debug] [pid 29625:tid =
140311678985984] h2_session.c(1703): [client X.X.X.X:53763] AH03078: =
h2_session(0): transit [WAIT] -- wait cycle --> [BUSY]
>>> [Sun Aug 21 14:15:43.979757 2016] [http2:debug] [pid 29625:tid =
140311678985984] h2_session.c(1703): [client X.X.X.X:53763] AH03078: =
h2_session(0): transit [BUSY] -- no io --> [WAIT]
>>> [Sun Aug 21 14:15:44.179865 2016] [http2:debug] [pid 29625:tid =
140311678985984] h2_session.c(1703): [client X.X.X.X:53763] AH03078: =
h2_session(0): transit [WAIT] -- wait cycle --> [BUSY]
>>> [Sun Aug 21 14:15:44.179922 2016] [http2:debug] [pid 29625:tid =
140311678985984] h2_session.c(1703): [client X.X.X.X:53763] AH03078: =
h2_session(0): transit [BUSY] -- no io --> [WAIT]
>>> [Sun Aug 21 14:15:44.380030 2016] [http2:debug] [pid 29625:tid =
140311678985984] h2_session.c(1703): [client X.X.X.X:53763] AH03078: =
h2_session(0): transit [WAIT] -- wait cycle --> [BUSY]
>>> [Sun Aug 21 14:15:44.380080 2016] [http2:debug] [pid 29625:tid =
140311678985984] h2_session.c(1703): [client X.X.X.X:53763] AH03078: =
h2_session(0): transit [BUSY] -- no io --> [WAIT]
>>> [Sun Aug 21 14:15:44.486387 2016] [mpm_event:notice] [pid 29610:tid =
140311984822144] AH00493: SIGUSR1 received.  Doing graceful restart
>>> [Sun Aug 21 14:15:44.580194 2016] [http2:debug] [pid 29625:tid =
140311678985984] h2_session.c(1703): [client X.X.X.X:53763] AH03078: =
h2_session(0): transit [WAIT] -- wait cycle --> [BUSY]
>>> [Sun Aug 21 14:15:44.580242 2016] [http2:debug] [pid 29625:tid =
140311678985984] h2_session.c(655): [client X.X.X.X:53763] AH03068: =
h2_session(0): sent FRAME[GOAWAY[error=3D0, reason=3D'', =
last_stream=3D1]], frames=3D4/3 (r/s)
>>> [Sun Aug 21 14:15:44.580296 2016] [http2:debug] [pid 29625:tid =
140311678985984] h2_session.c(752): [client X.X.X.X:53763] AH03069: =
session(0): sent GOAWAY, err=3D0, msg=3D
>>> [Sun Aug 21 14:15:44.580301 2016] [http2:debug] [pid 29625:tid =
140311678985984] h2_session.c(1703): [client X.X.X.X:53763] AH03078: =
h2_session(0): transit [BUSY] -- local goaway --> [LSHUTDOWN]
>>> [Sun Aug 21 14:15:44.580333 2016] [ssl:debug] [pid 29625:tid =
140311678985984] ssl_engine_io.c(1033): [client X.X.X.X:53763] AH02001: =
Connection closed to child 0 with standard shutdown (server XYZ:443)
>>> [Sun Aug 21 14:15:47.495352 2016] [ldap:debug] [pid 29610:tid =
140311984822144] util_ldap.c(2533): AH01307: LDAP: SSL trusted mode - =
SSL
>>> [Sun Aug 21 14:15:47.495871 2016] [ldap:debug] [pid 29610:tid =
140311984822144] util_ldap.c(2569): AH01308: LDAP: SSL verify server =
certificate - FALSE
>>> [Sun Aug 21 14:15:47.544226 2016] [auth_digest:notice] [pid =
29610:tid 140311984822144] AH01757: generating secret for digest =
authentication ...
>>> ...
>>>=20
>>=20