httpd-users mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Stefan Eissing <stefan.eiss...@greenbytes.de>
Subject Re: [users@httpd] Apache 2.4.23 and h2spec
Date Fri, 09 Sep 2016 06:55:22 GMT
Hi, this is a known issue with 2.4.23. It was fixed in the github version of the module since
v1.5.12 and is also fixed in the Apache subversion repository (trunk and 2.4.x).

Cheers,

Stefan

> Am 08.09.2016 um 17:39 schrieb Michael Johnson <buckethead@k.st>:
> 
> Hi there,
> 
> I am trying to use h2spec (https://github.com/summerwind/h2spec) with apache 2.4.23 but
pretty much at the beginning it stops the test with following error:
> ------------------------
> 3.5. HTTP/2 Connection Preface
>     ✓ Sends invalid connection preface
> 
>   4.2. Frame Size
>       Sends large size frame that exceeds the SETTINGS_MAX_FRAME_SIZE    
> 
> ERROR: HTTP/2 settings negotiation failed
> ------------------------
> I tried it with ubuntu 16.04 and 14.04 but the error stays the same.
> 
> To make sure that h2spec works properly, I tested it with other Http/2 webservers (nghttp2
and nginx) and the tests finished successfully.
> 
> In the error log I found this:
> 
> ----------------------
> 
> [Thu Sep 08 16:57:55.283025 2016] [http2:debug] [pid 14649] mod_http2.c(101): AH03089:
initializing post config dry run
> [Thu Sep 08 16:57:55.303927 2016] [http2:info] [pid 14650] AH03090: mod_http2 (v1.5.11,
feats=CHPRIO+SHA256, nghttp2 1.12.0-DEV), initializing...
> [Thu Sep 08 16:57:55.325803 2016] [mpm_prefork:notice] [pid 14650] AH00163: Apache/2.4.23
(Ubuntu) OpenSSL/1.0.2h PHP/5.5.9-1ubuntu4.19 configured -- resuming normal operations
> [Thu Sep 08 16:57:55.325867 2016] [core:notice] [pid 14650] AH00094: Command line: '/usr/sbin/apache2'
> [Thu Sep 08 16:57:55.325875 2016] [core:debug] [pid 14650] log.c(1546): AH02639: Using
SO_REUSEPORT: yes (1)
> [Thu Sep 08 16:58:09.226055 2016] [core:debug] [pid 14654] protocol.c(1893): [client
10.0.0.4:47316] AH03155: select protocol from h2, choices=h2-14,h2-15,h2-16,h2 for server
localhost
> [Thu Sep 08 16:58:09.226091 2016] [core:debug] [pid 14654] protocol.c(1938): [client
10.0.0.4:47316] AH03156: select protocol, proposals=h2 preferences=h2 configured=h2
> [Thu Sep 08 16:58:09.226107 2016] [core:debug] [pid 14654] protocol.c(1956): [client
10.0.0.4:47316] AH03157: selected protocol=h2
> [Thu Sep 08 16:58:09.231781 2016] [http2:debug] [pid 14654] h2_session.c(956): [client
10.0.0.4:47316] AH03200: h2_session(1) created, max_streams=100, stream_mem=65536, workers_limit=4,
workers_max=1, push_diary(type=1,N=256)
> [Thu Sep 08 16:58:09.231879 2016] [http2:debug] [pid 14654] h2_session.c(1058): [client
10.0.0.4:47316] AH03201: h2_session(1): start, INITIAL_WINDOW_SIZE=65535, MAX_CONCURRENT_STREAMS=100
> [Thu Sep 08 16:58:09.231907 2016] [http2:debug] [pid 14654] h2_session.c(2081): [client
10.0.0.4:47316] AH03079: h2_session(1): started on localhost:443
> [Thu Sep 08 16:58:09.231923 2016] [http2:debug] [pid 14654] h2_session.c(1703): [client
10.0.0.4:47316] AH03078: h2_session(1): transit [INIT] -- init --> [BUSY]
> [Thu Sep 08 16:58:09.232044 2016] [http2:debug] [pid 14654] h2_session.c(1806): [client
10.0.0.4:47316] AH03402: h2_session(1): proto error -> shutdown
> [Thu Sep 08 16:58:09.232083 2016] [http2:debug] [pid 14654] h2_session.c(655): [client
10.0.0.4:47316] AH03068: h2_session(1): sent FRAME[SETTINGS[length=6, stream=0]], frames=0/0
(r/s)
> [Thu Sep 08 16:58:09.232116 2016] [http2:debug] [pid 14654] h2_session.c(655): [client
10.0.0.4:47316] AH03068: h2_session(1): sent FRAME[WINDOW_UPDATE[stream=0, incr=2147418112]],
frames=0/1 (r/s)
> [Thu Sep 08 16:58:09.232135 2016] [http2:debug] [pid 14654] h2_session.c(655): [client
10.0.0.4:47316] AH03068: h2_session(1): sent FRAME[GOAWAY[error=-903, reason='Received bad
client magic byte string', last_stream=0]], frames=0/2 (r/s)
> [Thu Sep 08 16:58:09.232242 2016] [http2:debug] [pid 14654] h2_session.c(752): [client
10.0.0.4:47316] AH03069: session(1): sent GOAWAY, err=-903, msg=Received bad client magic
byte string
> [Thu Sep 08 16:58:09.232263 2016] [http2:debug] [pid 14654] h2_session.c(1703): [client
10.0.0.4:47316] AH03078: h2_session(1): transit [BUSY] -- local goaway --> [LSHUTDOWN]
> [Thu Sep 08 16:58:09.232292 2016] [http2:debug] [pid 14654] h2_session.c(1595): (20014)Internal
error (specific information not available): [client 10.0.0.4:47316] AH02950: h2_session(1):
error reading, terminating
> [Thu Sep 08 16:58:09.232308 2016] [http2:debug] [pid 14654] h2_session.c(1703): [client
10.0.0.4:47316] AH03078: h2_session(1): transit [LSHUTDOWN] -- conn error --> [DONE]
> [Thu Sep 08 16:58:09.232397 2016] [http2:debug] [pid 14654] h2_conn.c(215): (70014)End
of file found: [client 10.0.0.4:47316] AH03045: h2_session(1): process, closing conn
> [Thu Sep 08 16:58:09.234124 2016] [core:debug] [pid 14653] protocol.c(1893): [client
10.0.0.4:47318] AH03155: select protocol from h2, choices=h2-14,h2-15,h2-16,h2 for server
localhost
> [Thu Sep 08 16:58:09.234150 2016] [core:debug] [pid 14653] protocol.c(1938): [client
10.0.0.4:47318] AH03156: select protocol, proposals=h2 preferences=h2 configured=h2
> [Thu Sep 08 16:58:09.234164 2016] [core:debug] [pid 14653] protocol.c(1956): [client
10.0.0.4:47318] AH03157: selected protocol=h2
> [Thu Sep 08 16:58:09.236972 2016] [http2:debug] [pid 14653] h2_session.c(956): [client
10.0.0.4:47318] AH03200: h2_session(0) created, max_streams=100, stream_mem=65536, workers_limit=4,
workers_max=1, push_diary(type=1,N=256)
> [Thu Sep 08 16:58:09.236990 2016] [http2:debug] [pid 14653] h2_session.c(1058): [client
10.0.0.4:47318] AH03201: h2_session(0): start, INITIAL_WINDOW_SIZE=65535, MAX_CONCURRENT_STREAMS=100
> [Thu Sep 08 16:58:09.237001 2016] [http2:debug] [pid 14653] h2_session.c(2081): [client
10.0.0.4:47318] AH03079: h2_session(0): started on localhost:443
> [Thu Sep 08 16:58:09.237020 2016] [http2:debug] [pid 14653] h2_session.c(1703): [client
10.0.0.4:47318] AH03078: h2_session(0): transit [INIT] -- init --> [BUSY]
> [Thu Sep 08 16:58:09.237054 2016] [http2:debug] [pid 14653] h2_session.c(655): [client
10.0.0.4:47318] AH03068: h2_session(0): sent FRAME[SETTINGS[length=6, stream=0]], frames=0/0
(r/s)
> [Thu Sep 08 16:58:09.237075 2016] [http2:debug] [pid 14653] h2_session.c(655): [client
10.0.0.4:47318] AH03068: h2_session(0): sent FRAME[WINDOW_UPDATE[stream=0, incr=2147418112]],
frames=0/1 (r/s)
> [Thu Sep 08 16:58:09.237100 2016] [http2:debug] [pid 14653] h2_session.c(1703): [client
10.0.0.4:47318] AH03078: h2_session(0): transit [BUSY] -- no io (keepalive) --> [IDLE]
> [Thu Sep 08 16:58:09.237515 2016] [http2:debug] [pid 14653] h2_session.c(443): [client
10.0.0.4:47318] AH03066: h2_session(0): recv FRAME[SETTINGS[length=0, stream=0]], frames=0/2
(r/s)
> [Thu Sep 08 16:58:09.237537 2016] [http2:debug] [pid 14653] h2_session.c(1703): [client
10.0.0.4:47318] AH03078: h2_session(0): transit [IDLE] -- data read --> [BUSY]
> [Thu Sep 08 16:58:09.237556 2016] [http2:debug] [pid 14653] h2_session.c(655): [client
10.0.0.4:47318] AH03068: h2_session(0): sent FRAME[SETTINGS[ack=1, stream=0]], frames=1/2
(r/s)
> [Thu Sep 08 16:58:09.237574 2016] [http2:debug] [pid 14653] h2_session.c(1703): [client
10.0.0.4:47318] AH03078: h2_session(0): transit [BUSY] -- no io (keepalive) --> [IDLE]
> [Thu Sep 08 16:58:11.239245 2016] [http2:debug] [pid 14653] h2_session.c(1789): [client
10.0.0.4:47318] AH03401: h2_session(0): conn error -> shutdown
> [Thu Sep 08 16:58:11.239281 2016] [http2:debug] [pid 14653] h2_session.c(655): [client
10.0.0.4:47318] AH03068: h2_session(0): sent FRAME[GOAWAY[error=0, reason='error', last_stream=0]],
frames=1/3 (r/s)
> [Thu Sep 08 16:58:11.239464 2016] [http2:debug] [pid 14653] h2_session.c(752): [client
10.0.0.4:47318] AH03069: session(0): sent GOAWAY, err=0, msg=error
> [Thu Sep 08 16:58:11.239519 2016] [http2:debug] [pid 14653] h2_session.c(1703): [client
10.0.0.4:47318] AH03078: h2_session(0): transit [IDLE] -- local goaway --> [DONE]
> [Thu Sep 08 16:58:11.239717 2016] [http2:debug] [pid 14653] h2_conn.c(215): (70014)End
of file found: [client 10.0.0.4:47318] AH03045: h2_session(0): process, closing conn
> 
> ----------------------
> 
> 
> Has anyone (successfully) used h2spec with Apache 2.4.23 before and knows how to make
both work together?
> 
> thanks in advance
> 
> 
>  
> Powered by the worlds shortest email address ... http://www.k.st
> --------------------------------------------------------------------- To unsubscribe,
e-mail: users-unsubscribe@httpd.apache.org For additional commands, e-mail: users-help@httpd.apache.org


---------------------------------------------------------------------
To unsubscribe, e-mail: users-unsubscribe@httpd.apache.org
For additional commands, e-mail: users-help@httpd.apache.org


Mime
View raw message