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-unsubscr...@httpd.apache.org
For additional commands, e-mail: users-h...@httpd.apache.org
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
