You defined the vhost incorrectly to begin with. Do not use a hostname in the <VirtualHost> definition. Use *:443 or IP:443 if you must.
Then, re-run apachectl -S to see your defined vhosts. On 15/04/20 06:41 PM, Alex Hautequest wrote: > This is the very first VirtualHost entry on the HTTPD configuration > files (no _default_ entry). HTML content is returned regardless of the > presence or not of client SNI extension offering. > > <VirtualHost hquest.pro.br:443 <http://hquest.pro.br:443>> > DocumentRoot “/xxx/htdocs" > ServerName hquest.pro.br <http://hquest.pro.br> > ServerAdmin [email protected] <mailto:[email protected]> > LogFormat "%h %l %u %t \"%r\" %>s %b \"%{Referer}i\" \"%{User-Agent}i\"" > combined > ErrorLog “/xxx/error_log" > CustomLog “/xxx/access_log" combined > SSLEngine on > SSLStrictSNIVHostCheck on > SSLCertificateFile “/xxx/xxx.ec.pem" > SSLCertificateFile “/xxx/xxx.rsa.pem" > SSLCertificateKeyFile “/xxx/xxx.ec.key" > SSLCertificateKeyFile “/xxx/xxx.rsa.key" > SSLCertificateChainFile “/xxx/xxx.chain.ec.pem" > SSLCertificateChainFile “/xxx/xxx.chain.rsa.pem" > SSLCACertificatePath "/etc/ssl/certs" > SSLCACertificateFile "/etc/ssl/certs/ca-certificates.crt" > <FilesMatch "\.(cgi|shtml|phtml|php|fcgi)$"> > SSLOptions +StdEnvVars > </FilesMatch> > <Directory “/xxx/cgi-bin"> > SSLOptions +StdEnvVars +StrictRequire > ErrorDocument 403 default > SSLRequireSSL > </Directory> > CustomLog "/xxx/ssl_request_log" \ > "%t %h %{SSL_PROTOCOL}x %{SSL_CIPHER}x \"%r\" %b" > Header always unset "X-Powered-By" > Header unset "X-Powered-By" > Header always edit Set-Cookie (.*) "$1;HttpOnly;Secure" > Header always set Strict-Transport-Security "max-age=63072000; > includeSubdomains; preload" > Header set Public-Key-Pins > "pin-sha256=\"YLh1dUR9y6Kja30RrAn7JKnbQG/uEtLMkBgFF2Fuihg=\"; > pin-sha256=\"sRHdihwgkaib1P1gxX8HFszlD+7/gTfNvuAybgLPNis=\"; > max-age=5184000; includeSubDomains; > report-uri=\"https://hquest.report-uri.io/r/default/hpkp/enforce\""; > Header set X-XSS-Protection "1; mode=block" > Header set X-Content-Type-Options nosniff > Header always set X-Frame-Options SAMEORIGIN > Header set Content-Security-Policy "default-src 'self'; script-src > 'self'; style-src 'self'; frame-src 'self'; frame-ancestors 'self'; > object-src 'none'; form-action 'self'; upgrade-insecure-requests; > block-all-mixed-content; require-sri-for script style;" > Header set Referrer-Policy "no-referrer" > Header set Expect-CT "max-age=300, > report-uri=\"https://hquest.report-uri.io/r/default/ct/enforce\""; > Header set Expect-Staple "max-age=300, > report-uri=\"https://hquest.report-uri.io/r/default/staple/reportOnly\""; > Header set Cache-control "no-cache, no-store, must-revalidate" > Header set Access-Control-Allow-Origin "https://hquest.pro.br"; > Header set Access-Control-Allow-Methods "GET" > Header set Access-Control-Allow-Headers "Content-Type" > Header set Access-Control-Max-Age "86400" > Header set Feature-Policy "vibrate 'self'; sync-xhr 'self' > https://hquest.pro.br"; > Protocols h2 http/1.1 > H2Upgrade on > H2ModernTLSOnly on > H2Direct on > <Location "/"> > AllowMethods GET POST HEAD OPTIONS > </Location> > TraceEnable off > <Directory “/xxx/htdocs/"> > ErrorDocument 403 default > SSLRequireSSL > SSLOptions +StrictRequire > </Directory> > </VirtualHost> > > > With SNI: > > > [Wed Apr 15 18:32:38.173741 2020] [ssl:info] [pid 9879:tid > 140268677838592] [client 192.168.0.10:50075] AH01964: Connection to > child 192 established (server hquest.pro.br:443 <http://hquest.pro.br:443>) > [Wed Apr 15 18:32:38.174056 2020] [ssl:debug] [pid 9879:tid > 140268677838592] ssl_engine_kernel.c(2351): [client 192.168.0.10:50075] > AH02043: SSL virtual host for servername hquest.pro.br > <http://hquest.pro.br> found > [Wed Apr 15 18:32:38.174204 2020] [core:debug] [pid 9879:tid > 140268677838592] protocol.c(2313): [client 192.168.0.10:50075] AH03155: > select protocol from h2,http/1.1, choices=h2,http/1.1 for server > hquest.pro.br <http://hquest.pro.br> > [Wed Apr 15 18:32:38.174225 2020] [core:debug] [pid 9879:tid > 140268677838592] protocol.c(2357): [client 192.168.0.10:50075] AH03156: > select protocol, proposals=h2,http/1.1 preferences=h2,http/1.1 > configured=h2,http/1.1 > [Wed Apr 15 18:32:38.174240 2020] [core:debug] [pid 9879:tid > 140268677838592] protocol.c(2377): [client 192.168.0.10:50075] AH03157: > selected protocol=h2 > [Wed Apr 15 18:32:38.174270 2020] [ssl:debug] [pid 9879:tid > 140268677838592] ssl_util_stapling.c(795): AH01951: stapling_cb: OCSP > Stapling callback called > [Wed Apr 15 18:32:38.174279 2020] [ssl:debug] [pid 9879:tid > 140268677838592] ssl_util_stapling.c(822): AH01952: stapling_cb: > retrieved cached certificate data > [Wed Apr 15 18:32:38.174314 2020] [socache_shmcb:debug] [pid 9879:tid > 140268677838592] mod_socache_shmcb.c(530): AH00835: > socache_shmcb_retrieve (0x3e -> subcache 0) > [Wed Apr 15 18:32:38.174327 2020] [socache_shmcb:debug] [pid 9879:tid > 140268677838592] mod_socache_shmcb.c(883): AH00849: match at idx=0, data=0 > [Wed Apr 15 18:32:38.174332 2020] [socache_shmcb:debug] [pid 9879:tid > 140268677838592] mod_socache_shmcb.c(541): AH00836: leaving > socache_shmcb_retrieve successfully > [Wed Apr 15 18:32:38.174349 2020] [ssl:debug] [pid 9879:tid > 140268677838592] ssl_util_stapling.c(356): AH01933: > stapling_get_cached_response: cache hit > [Wed Apr 15 18:32:38.174354 2020] [ssl:debug] [pid 9879:tid > 140268677838592] ssl_util_stapling.c(741): AH01953: stapling_cb: > retrieved cached response > [Wed Apr 15 18:32:38.174396 2020] [ssl:debug] [pid 9879:tid > 140268677838592] ssl_util_stapling.c(873): AH01956: stapling_cb: setting > response > [Wed Apr 15 18:32:38.178183 2020] [ssl:info] [pid 9592:tid > 140268539971328] [client 192.168.0.10:50076] AH01964: Connection to > child 72 established (server hquest.pro.br:443 <http://hquest.pro.br:443>) > [Wed Apr 15 18:32:38.178880 2020] [ssl:debug] [pid 9592:tid > 140268539971328] ssl_engine_kernel.c(2351): [client 192.168.0.10:50076] > AH02043: SSL virtual host for servername hquest.pro.br > <http://hquest.pro.br> found > [Wed Apr 15 18:32:38.178952 2020] [core:debug] [pid 9592:tid > 140268539971328] protocol.c(2313): [client 192.168.0.10:50076] AH03155: > select protocol from h2,http/1.1, choices=h2,http/1.1 for server > hquest.pro.br <http://hquest.pro.br> > [Wed Apr 15 18:32:38.178973 2020] [core:debug] [pid 9592:tid > 140268539971328] protocol.c(2357): [client 192.168.0.10:50076] AH03156: > select protocol, proposals=h2,http/1.1 preferences=h2,http/1.1 > configured=h2,http/1.1 > [Wed Apr 15 18:32:38.178978 2020] [core:debug] [pid 9592:tid > 140268539971328] protocol.c(2377): [client 192.168.0.10:50076] AH03157: > selected protocol=h2 > [Wed Apr 15 18:32:38.179027 2020] [ssl:debug] [pid 9592:tid > 140268539971328] ssl_util_stapling.c(795): AH01951: stapling_cb: OCSP > Stapling callback called > [Wed Apr 15 18:32:38.179037 2020] [ssl:debug] [pid 9592:tid > 140268539971328] ssl_util_stapling.c(822): AH01952: stapling_cb: > retrieved cached certificate data > [Wed Apr 15 18:32:38.179060 2020] [socache_shmcb:debug] [pid 9592:tid > 140268539971328] mod_socache_shmcb.c(530): AH00835: > socache_shmcb_retrieve (0x3e -> subcache 0) > [Wed Apr 15 18:32:38.179072 2020] [socache_shmcb:debug] [pid 9592:tid > 140268539971328] mod_socache_shmcb.c(883): AH00849: match at idx=0, data=0 > [Wed Apr 15 18:32:38.179076 2020] [socache_shmcb:debug] [pid 9592:tid > 140268539971328] mod_socache_shmcb.c(541): AH00836: leaving > socache_shmcb_retrieve successfully > [Wed Apr 15 18:32:38.179100 2020] [ssl:debug] [pid 9592:tid > 140268539971328] ssl_util_stapling.c(356): AH01933: > stapling_get_cached_response: cache hit > [Wed Apr 15 18:32:38.179107 2020] [ssl:debug] [pid 9592:tid > 140268539971328] ssl_util_stapling.c(741): AH01953: stapling_cb: > retrieved cached response > [Wed Apr 15 18:32:38.179489 2020] [ssl:debug] [pid 9592:tid > 140268539971328] ssl_util_stapling.c(873): AH01956: stapling_cb: setting > response > [Wed Apr 15 18:32:38.183829 2020] [ssl:debug] [pid 9879:tid > 140268677838592] ssl_engine_kernel.c(2231): [client 192.168.0.10:50075] > AH02041: Protocol: TLSv1.3, Cipher: TLS_AES_256_GCM_SHA384 (256/256 bits) > [Wed Apr 15 18:32:38.184008 2020] [socache_shmcb:debug] [pid 9879:tid > 140268677838592] mod_socache_shmcb.c(493): AH00831: socache_shmcb_store > (0x7d -> subcache 29) > [Wed Apr 15 18:32:38.184116 2020] [socache_shmcb:debug] [pid 9879:tid > 140268677838592] mod_socache_shmcb.c(847): AH00847: insert happened at > idx=0, data=(0:32) > [Wed Apr 15 18:32:38.184210 2020] [socache_shmcb:debug] [pid 9879:tid > 140268677838592] mod_socache_shmcb.c(850): AH00848: finished insert, > subcache: idx_pos/idx_used=0/1, data_pos/data_used=0/211 > [Wed Apr 15 18:32:38.184282 2020] [socache_shmcb:debug] [pid 9879:tid > 140268677838592] mod_socache_shmcb.c(515): AH00834: leaving > socache_shmcb_store successfully > [Wed Apr 15 18:32:38.184445 2020] [socache_shmcb:debug] [pid 9879:tid > 140268677838592] mod_socache_shmcb.c(493): AH00831: socache_shmcb_store > (0xd9 -> subcache 25) > [Wed Apr 15 18:32:38.184532 2020] [socache_shmcb:debug] [pid 9879:tid > 140268677838592] mod_socache_shmcb.c(847): AH00847: insert happened at > idx=0, data=(0:32) > [Wed Apr 15 18:32:38.184583 2020] [socache_shmcb:debug] [pid 9879:tid > 140268677838592] mod_socache_shmcb.c(850): AH00848: finished insert, > subcache: idx_pos/idx_used=0/1, data_pos/data_used=0/211 > [Wed Apr 15 18:32:38.184721 2020] [socache_shmcb:debug] [pid 9879:tid > 140268677838592] mod_socache_shmcb.c(515): AH00834: leaving > socache_shmcb_store successfully > [Wed Apr 15 18:32:38.184874 2020] [http2:debug] [pid 9879:tid > 140268677838592] h2_session.c(948): [client 192.168.0.10:50075] AH03200: > h2_session(192,INIT,0): created, max_streams=100, stream_mem=32768, > workers_limit=6, workers_max=37, push_diary(type=1,N=256) > [Wed Apr 15 18:32:38.184951 2020] [http2:debug] [pid 9879:tid > 140268677838592] h2_session.c(1037): [client 192.168.0.10:50075] > AH03201: h2_session(192,INIT,0): start, INITIAL_WINDOW_SIZE=65535, > MAX_CONCURRENT_STREAMS=100 > [Wed Apr 15 18:32:38.185004 2020] [http2:debug] [pid 9879:tid > 140268677838592] h2_session.c(2130): [client 192.168.0.10:50075] > AH03079: h2_session(192,INIT,0): started on hquest.pro.br:443 > <http://hquest.pro.br:443> > [Wed Apr 15 18:32:38.185050 2020] [http2:debug] [pid 9879:tid > 140268677838592] h2_session.c(1694): [client 192.168.0.10:50075] > AH03078: h2_session(192,BUSY,0): transit [INIT] -- init --> [BUSY] > [Wed Apr 15 18:32:38.185115 2020] [http2:debug] [pid 9879:tid > 140268677838592] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,0): sent FRAME[SETTINGS[length=6, stream=0]], > frames=0/1 (r/s) > [Wed Apr 15 18:32:38.185179 2020] [http2:debug] [pid 9879:tid > 140268677838592] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,0): sent FRAME[WINDOW_UPDATE[stream=0, > incr=2147418112]], frames=0/2 (r/s) > [Wed Apr 15 18:32:38.185289 2020] [http2:debug] [pid 9879:tid > 140268677838592] h2_session.c(1694): [client 192.168.0.10:50075] > AH03078: h2_session(192,IDLE,0): transit [BUSY] -- no io (keepalive) --> > [IDLE] > [Wed Apr 15 18:32:38.190009 2020] [ssl:debug] [pid 9592:tid > 140268539971328] ssl_engine_kernel.c(2231): [client 192.168.0.10:50076] > AH02041: Protocol: TLSv1.3, Cipher: TLS_AES_256_GCM_SHA384 (256/256 bits) > [Wed Apr 15 18:32:38.190237 2020] [socache_shmcb:debug] [pid 9592:tid > 140268539971328] mod_socache_shmcb.c(493): AH00831: socache_shmcb_store > (0xfd -> subcache 29) > [Wed Apr 15 18:32:38.190298 2020] [socache_shmcb:debug] [pid 9592:tid > 140268539971328] mod_socache_shmcb.c(847): AH00847: insert happened at > idx=1, data=(211:243) > [Wed Apr 15 18:32:38.190344 2020] [socache_shmcb:debug] [pid 9592:tid > 140268539971328] mod_socache_shmcb.c(850): AH00848: finished insert, > subcache: idx_pos/idx_used=0/2, data_pos/data_used=0/423 > [Wed Apr 15 18:32:38.190468 2020] [socache_shmcb:debug] [pid 9592:tid > 140268539971328] mod_socache_shmcb.c(515): AH00834: leaving > socache_shmcb_store successfully > [Wed Apr 15 18:32:38.190612 2020] [socache_shmcb:debug] [pid 9592:tid > 140268539971328] mod_socache_shmcb.c(493): AH00831: socache_shmcb_store > (0x79 -> subcache 25) > [Wed Apr 15 18:32:38.190669 2020] [socache_shmcb:debug] [pid 9592:tid > 140268539971328] mod_socache_shmcb.c(847): AH00847: insert happened at > idx=1, data=(211:243) > [Wed Apr 15 18:32:38.190714 2020] [socache_shmcb:debug] [pid 9592:tid > 140268539971328] mod_socache_shmcb.c(850): AH00848: finished insert, > subcache: idx_pos/idx_used=0/2, data_pos/data_used=0/422 > [Wed Apr 15 18:32:38.190759 2020] [socache_shmcb:debug] [pid 9592:tid > 140268539971328] mod_socache_shmcb.c(515): AH00834: leaving > socache_shmcb_store successfully > [Wed Apr 15 18:32:38.190938 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_session.c(948): [client 192.168.0.10:50076] AH03200: > h2_session(72,INIT,0): created, max_streams=100, stream_mem=32768, > workers_limit=6, workers_max=37, push_diary(type=1,N=256) > [Wed Apr 15 18:32:38.191015 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_session.c(1037): [client 192.168.0.10:50076] > AH03201: h2_session(72,INIT,0): start, INITIAL_WINDOW_SIZE=65535, > MAX_CONCURRENT_STREAMS=100 > [Wed Apr 15 18:32:38.191067 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_session.c(2130): [client 192.168.0.10:50076] > AH03079: h2_session(72,INIT,0): started on hquest.pro.br:443 > <http://hquest.pro.br:443> > [Wed Apr 15 18:32:38.191113 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_session.c(1694): [client 192.168.0.10:50076] > AH03078: h2_session(72,BUSY,0): transit [INIT] -- init --> [BUSY] > [Wed Apr 15 18:32:38.191200 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_session.c(337): [client 192.168.0.10:50076] AH03066: > h2_session(72,BUSY,0): recv FRAME[SETTINGS[length=12, stream=0]], > frames=0/0 (r/s) > [Wed Apr 15 18:32:38.191262 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_session.c(337): [client 192.168.0.10:50076] AH03066: > h2_session(72,BUSY,0): recv FRAME[WINDOW_UPDATE[stream=0, > incr=10485760]], frames=1/0 (r/s) > [Wed Apr 15 18:32:38.191323 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_stream.c(542): [client 192.168.0.10:50076] AH03082: > h2_stream(72-1,IDLE): created > [Wed Apr 15 18:32:38.191392 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_session.c(337): [client 192.168.0.10:50076] AH03066: > h2_session(72,BUSY,1): recv FRAME[HEADERS[length=181, hend=1, stream=1, > eos=1]], frames=2/0 (r/s) > [Wed Apr 15 18:32:38.191911 2020] [ssl:debug] [pid 9592:tid > 140268703016704] ssl_engine_kernel.c(376): [client 192.168.0.10:50076] > AH02034: Subsequent (No.2) HTTPS request received for child 18433 > (server hquest.pro.br:443 <http://hquest.pro.br:443>) > [Wed Apr 15 18:32:38.192100 2020] [authz_core:debug] [pid 9592:tid > 140268703016704] mod_authz_core.c(843): [client 192.168.0.10:50076] > AH01628: authorization result: granted (no directives) > [Wed Apr 15 18:32:38.192780 2020] [authz_core:debug] [pid 9592:tid > 140268703016704] mod_authz_core.c(843): [client 192.168.0.10:50076] > AH01628: authorization result: granted (no directives) > [Wed Apr 15 18:32:38.192917 2020] [http2:debug] [pid 9592:tid > 140268703016704] h2_task.c(83): [client 192.168.0.10:50076] AH03348: > h2_task(000): open output to GET hquest.pro.br <http://hquest.pro.br> / > [Wed Apr 15 18:32:38.193143 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_session.c(1384): [client 192.168.0.10:50076] > AH03073: h2_stream(72-1,HALF_CLOSED_REMOTE): submit response 200, > REMOTE_WINDOW_SIZE=1048576 > [Wed Apr 15 18:32:38.193280 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_session.c(1539): [client 192.168.0.10:50076] > AH02936: h2_stream(72-1,HALF_CLOSED_REMOTE): resumed > [Wed Apr 15 18:32:38.193333 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_session.c(589): [client 192.168.0.10:50076] AH03068: > h2_session(72,BUSY,1): sent FRAME[SETTINGS[length=6, stream=0]], > frames=3/1 (r/s) > [Wed Apr 15 18:32:38.193382 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_session.c(589): [client 192.168.0.10:50076] AH03068: > h2_session(72,BUSY,1): sent FRAME[SETTINGS[ack=1, stream=0]], frames=3/2 > (r/s) > [Wed Apr 15 18:32:38.193429 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_session.c(589): [client 192.168.0.10:50076] AH03068: > h2_session(72,BUSY,1): sent FRAME[WINDOW_UPDATE[stream=0, > incr=2147418112]], frames=3/3 (r/s) > [Wed Apr 15 18:32:38.193491 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_session.c(589): [client 192.168.0.10:50076] AH03068: > h2_session(72,BUSY,1): sent FRAME[HEADERS[length=889, hend=1, stream=1, > eos=0]], frames=3/4 (r/s) > [Wed Apr 15 18:32:38.193554 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_session.c(589): [client 192.168.0.10:50076] AH03068: > h2_session(72,BUSY,1): sent FRAME[DATA[length=254, flags=1, stream=1, > padlen=0]], frames=3/5 (r/s) > [Wed Apr 15 18:32:38.193754 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_bucket_beam.c(1275): [client 192.168.0.10:50076] > beam(72-1,output,closed=1,aborted=1,empty=1,buf=0): AH03385: > h2_task_destroy, reuse slave > [Wed Apr 15 18:32:38.193910 2020] [http2:debug] [pid 9592:tid > 140268539971328] h2_session.c(1694): [client 192.168.0.10:50076] > AH03078: h2_session(72,IDLE,0): transit [BUSY] -- no io (keepalive) --> > [IDLE] > [Wed Apr 15 18:32:38.195708 2020] [http2:debug] [pid 9592:tid > 140268523185920] h2_session.c(337): [client 192.168.0.10:50076] AH03066: > h2_session(72,IDLE,0): recv FRAME[SETTINGS[ack=1, stream=0]], frames=3/5 > (r/s) > [Wed Apr 15 18:32:43.198765 2020] [http2:debug] [pid 9592:tid > 140268472829696] h2_session.c(589): [client 192.168.0.10:50076] AH03068: > h2_session(72,IDLE,0): sent FRAME[GOAWAY[error=0, reason='timeout', > last_stream=1]], frames=4/6 (r/s) > [Wed Apr 15 18:32:43.198963 2020] [http2:debug] [pid 9592:tid > 140268472829696] h2_session.c(751): [client 192.168.0.10:50076] AH03069: > h2_session(72,IDLE,0): sent GOAWAY, err=0, msg=timeout > [Wed Apr 15 18:32:43.198985 2020] [http2:debug] [pid 9592:tid > 140268472829696] h2_session.c(1694): [client 192.168.0.10:50076] > AH03078: h2_session(72,DONE,0): transit [IDLE] -- local goaway --> [DONE] > [Wed Apr 15 18:32:43.198991 2020] [http2:debug] [pid 9592:tid > 140268472829696] h2_session.c(1694): [client 192.168.0.10:50076] > AH03078: h2_session(72,CLEANUP,0): transit [DONE] -- pre_close --> [CLEANUP] > [Wed Apr 15 18:32:43.199062 2020] [ssl:debug] [pid 9592:tid > 140268472829696] ssl_engine_io.c(1102): [client 192.168.0.10:50076] > AH02001: Connection closed to child 80 with standard shutdown (server > hquest.pro.br:443 <http://hquest.pro.br:443>) > [Wed Apr 15 18:32:44.079218 2020] [http2:debug] [pid 9879:tid > 140268414080768] h2_session.c(337): [client 192.168.0.10:50075] AH03066: > h2_session(192,IDLE,0): recv FRAME[SETTINGS[length=12, stream=0]], > frames=0/2 (r/s) > [Wed Apr 15 18:32:44.079280 2020] [http2:debug] [pid 9879:tid > 140268414080768] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,IDLE,0): sent FRAME[SETTINGS[ack=1, stream=0]], > frames=1/3 (r/s) > [Wed Apr 15 18:32:44.080335 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(337): [client 192.168.0.10:50075] AH03066: > h2_session(192,IDLE,0): recv FRAME[WINDOW_UPDATE[stream=0, > incr=10485760]], frames=1/3 (r/s) > [Wed Apr 15 18:32:44.080367 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_stream.c(542): [client 192.168.0.10:50075] AH03082: > h2_stream(192-1,IDLE): created > [Wed Apr 15 18:32:44.080375 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(1694): [client 192.168.0.10:50075] > AH03078: h2_session(192,BUSY,1): transit [IDLE] -- stream change --> [BUSY] > [Wed Apr 15 18:32:44.080410 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(337): [client 192.168.0.10:50075] AH03066: > h2_session(192,BUSY,1): recv FRAME[HEADERS[length=203, hend=1, stream=1, > eos=1]], frames=2/3 (r/s) > [Wed Apr 15 18:32:44.080428 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(337): [client 192.168.0.10:50075] AH03066: > h2_session(192,BUSY,1): recv FRAME[SETTINGS[ack=1, stream=0]], > frames=3/3 (r/s) > [Wed Apr 15 18:32:44.080734 2020] [ssl:debug] [pid 9879:tid > 140268736587520] ssl_engine_kernel.c(376): [client 192.168.0.10:50075] > AH02034: Subsequent (No.2) HTTPS request received for child 54533 > (server hquest.pro.br:443 <http://hquest.pro.br:443>) > [Wed Apr 15 18:32:44.080862 2020] [authz_core:debug] [pid 9879:tid > 140268736587520] mod_authz_core.c(843): [client 192.168.0.10:50075] > AH01628: authorization result: granted (no directives) > [Wed Apr 15 18:32:44.081107 2020] [http2:debug] [pid 9879:tid > 140268736587520] h2_task.c(83): [client 192.168.0.10:50075] AH03348: > h2_task(000): open output to GET hquest.pro.br <http://hquest.pro.br> > /xxx.png > [Wed Apr 15 18:32:44.081287 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(1384): [client 192.168.0.10:50075] > AH03073: h2_stream(192-1,HALF_CLOSED_REMOTE): submit response 200, > REMOTE_WINDOW_SIZE=1048576 > [Wed Apr 15 18:32:44.081323 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(1539): [client 192.168.0.10:50075] > AH02936: h2_stream(192-1,HALF_CLOSED_REMOTE): resumed > [Wed Apr 15 18:32:44.081356 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[HEADERS[length=890, hend=1, stream=1, > eos=0]], frames=4/4 (r/s) > [Wed Apr 15 18:32:44.081374 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/5 (r/s) > [Wed Apr 15 18:32:44.081384 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/6 (r/s) > [Wed Apr 15 18:32:44.081394 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/7 (r/s) > [Wed Apr 15 18:32:44.081404 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/8 (r/s) > [Wed Apr 15 18:32:44.081417 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/9 (r/s) > [Wed Apr 15 18:32:44.081427 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/10 (r/s) > [Wed Apr 15 18:32:44.081437 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/11 (r/s) > [Wed Apr 15 18:32:44.081447 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/12 (r/s) > [Wed Apr 15 18:32:44.081456 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/13 (r/s) > [Wed Apr 15 18:32:44.081474 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/14 (r/s) > [Wed Apr 15 18:32:44.081488 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/15 (r/s) > [Wed Apr 15 18:32:44.081503 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/16 (r/s) > [Wed Apr 15 18:32:44.081517 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/17 (r/s) > [Wed Apr 15 18:32:44.081532 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/18 (r/s) > [Wed Apr 15 18:32:44.081546 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/19 (r/s) > [Wed Apr 15 18:32:44.081560 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/20 (r/s) > [Wed Apr 15 18:32:44.081581 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/21 (r/s) > [Wed Apr 15 18:32:44.081596 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/22 (r/s) > [Wed Apr 15 18:32:44.081610 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/23 (r/s) > [Wed Apr 15 18:32:44.081624 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/24 (r/s) > [Wed Apr 15 18:32:44.081638 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/25 (r/s) > [Wed Apr 15 18:32:44.081653 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/26 (r/s) > [Wed Apr 15 18:32:44.081670 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/27 (r/s) > [Wed Apr 15 18:32:44.081684 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/28 (r/s) > [Wed Apr 15 18:32:44.081698 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/29 (r/s) > [Wed Apr 15 18:32:44.081909 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/30 (r/s) > [Wed Apr 15 18:32:44.081922 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/31 (r/s) > [Wed Apr 15 18:32:44.081931 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/32 (r/s) > [Wed Apr 15 18:32:44.081939 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/33 (r/s) > [Wed Apr 15 18:32:44.081947 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/34 (r/s) > [Wed Apr 15 18:32:44.081955 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/35 (r/s) > [Wed Apr 15 18:32:44.081964 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/36 (r/s) > [Wed Apr 15 18:32:44.081972 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/37 (r/s) > [Wed Apr 15 18:32:44.081985 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/38 (r/s) > [Wed Apr 15 18:32:44.081994 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/39 (r/s) > [Wed Apr 15 18:32:44.082002 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/40 (r/s) > [Wed Apr 15 18:32:44.082010 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/41 (r/s) > [Wed Apr 15 18:32:44.082018 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/42 (r/s) > [Wed Apr 15 18:32:44.082026 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/43 (r/s) > [Wed Apr 15 18:32:44.082034 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/44 (r/s) > [Wed Apr 15 18:32:44.082042 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/45 (r/s) > [Wed Apr 15 18:32:44.082051 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/46 (r/s) > [Wed Apr 15 18:32:44.082059 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/47 (r/s) > [Wed Apr 15 18:32:44.082067 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/48 (r/s) > [Wed Apr 15 18:32:44.082075 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/49 (r/s) > [Wed Apr 15 18:32:44.082083 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/50 (r/s) > [Wed Apr 15 18:32:44.082093 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,BUSY,1): sent FRAME[DATA[length=605, flags=1, stream=1, > padlen=0]], frames=4/51 (r/s) > [Wed Apr 15 18:32:44.082659 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_bucket_beam.c(1275): [client 192.168.0.10:50075] > beam(213-1,output,closed=1,aborted=1,empty=1,buf=0): AH03385: > h2_task_destroy, reuse slave > [Wed Apr 15 18:32:44.082754 2020] [http2:debug] [pid 9879:tid > 140268430866176] h2_session.c(1694): [client 192.168.0.10:50075] > AH03078: h2_session(192,IDLE,0): transit [BUSY] -- no io (keepalive) --> > [IDLE] > [Wed Apr 15 18:32:49.083447 2020] [http2:debug] [pid 9879:tid > 140268439258880] h2_session.c(589): [client 192.168.0.10:50075] AH03068: > h2_session(192,IDLE,0): sent FRAME[GOAWAY[error=0, reason='timeout', > last_stream=1]], frames=4/52 (r/s) > [Wed Apr 15 18:32:49.083699 2020] [http2:debug] [pid 9879:tid > 140268439258880] h2_session.c(751): [client 192.168.0.10:50075] AH03069: > h2_session(192,IDLE,0): sent GOAWAY, err=0, msg=timeout > [Wed Apr 15 18:32:49.083744 2020] [http2:debug] [pid 9879:tid > 140268439258880] h2_session.c(1694): [client 192.168.0.10:50075] > AH03078: h2_session(192,DONE,0): transit [IDLE] -- local goaway --> [DONE] > [Wed Apr 15 18:32:49.083786 2020] [http2:debug] [pid 9879:tid > 140268439258880] h2_session.c(1694): [client 192.168.0.10:50075] > AH03078: h2_session(192,CLEANUP,0): transit [DONE] -- pre_close --> > [CLEANUP] > [Wed Apr 15 18:32:49.083934 2020] [ssl:debug] [pid 9879:tid > 140268439258880] ssl_engine_io.c(1102): [client 192.168.0.10:50075] > AH02001: Connection closed to child 212 with standard shutdown (server > hquest.pro.br:443 <http://hquest.pro.br:443>) > > > > Without SNI: > > > [Wed Apr 15 18:36:34.809660 2020] [ssl:info] [pid 9879:tid > 140268531578624] [client 192.168.0.10:50281] AH01964: Connection to > child 201 established (server hquest.pro.br:443 <http://hquest.pro.br:443>) > [Wed Apr 15 18:36:34.809987 2020] [ssl:debug] [pid 9879:tid > 140268531578624] ssl_engine_kernel.c(2351): [client > 192.168.0.10:50281] AH02645: Server name not provided via TLS extension > (using default/first virtual host) > [Wed Apr 15 18:36:34.810111 2020] [core:debug] [pid 9879:tid > 140268531578624] protocol.c(2313): [client 192.168.0.10:50281] AH03155: > select protocol from h2,http/1.1, choices=h2,http/1.1 for server > hquest.pro.br <http://hquest.pro.br> > [Wed Apr 15 18:36:34.810132 2020] [core:debug] [pid 9879:tid > 140268531578624] protocol.c(2357): [client 192.168.0.10:50281] AH03156: > select protocol, proposals=h2,http/1.1 preferences=h2,http/1.1 > configured=h2,http/1.1 > [Wed Apr 15 18:36:34.810138 2020] [core:debug] [pid 9879:tid > 140268531578624] protocol.c(2377): [client 192.168.0.10:50281] AH03157: > selected protocol=h2 > [Wed Apr 15 18:36:34.813895 2020] [ssl:debug] [pid 9879:tid > 140268531578624] ssl_engine_kernel.c(2231): [client 192.168.0.10:50281] > AH02041: Protocol: TLSv1.3, Cipher: TLS_AES_256_GCM_SHA384 (256/256 bits) > [Wed Apr 15 18:36:34.814004 2020] [socache_shmcb:debug] [pid 9879:tid > 140268531578624] mod_socache_shmcb.c(493): AH00831: socache_shmcb_store > (0x01 -> subcache 1) > [Wed Apr 15 18:36:34.814065 2020] [socache_shmcb:debug] [pid 9879:tid > 140268531578624] mod_socache_shmcb.c(847): AH00847: insert happened at > idx=0, data=(0:32) > [Wed Apr 15 18:36:34.814075 2020] [socache_shmcb:debug] [pid 9879:tid > 140268531578624] mod_socache_shmcb.c(850): AH00848: finished insert, > subcache: idx_pos/idx_used=0/1, data_pos/data_used=0/211 > [Wed Apr 15 18:36:34.814080 2020] [socache_shmcb:debug] [pid 9879:tid > 140268531578624] mod_socache_shmcb.c(515): AH00834: leaving > socache_shmcb_store successfully > [Wed Apr 15 18:36:34.814193 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(948): [client 192.168.0.10:50281] AH03200: > h2_session(201,INIT,0): created, max_streams=100, stream_mem=32768, > workers_limit=6, workers_max=37, push_diary(type=1,N=256) > [Wed Apr 15 18:36:34.814212 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(1037): [client 192.168.0.10:50281] > AH03201: h2_session(201,INIT,0): start, INITIAL_WINDOW_SIZE=65535, > MAX_CONCURRENT_STREAMS=100 > [Wed Apr 15 18:36:34.814221 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(2130): [client 192.168.0.10:50281] > AH03079: h2_session(201,INIT,0): started on hquest.pro.br:443 > <http://hquest.pro.br:443> > [Wed Apr 15 18:36:34.814226 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(1694): [client 192.168.0.10:50281] > AH03078: h2_session(201,BUSY,0): transit [INIT] -- init --> [BUSY] > [Wed Apr 15 18:36:34.814252 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,0): sent FRAME[SETTINGS[length=6, stream=0]], > frames=0/1 (r/s) > [Wed Apr 15 18:36:34.814260 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,0): sent FRAME[WINDOW_UPDATE[stream=0, > incr=2147418112]], frames=0/2 (r/s) > [Wed Apr 15 18:36:34.814309 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(1694): [client 192.168.0.10:50281] > AH03078: h2_session(201,IDLE,0): transit [BUSY] -- no io (keepalive) --> > [IDLE] > [Wed Apr 15 18:36:34.814618 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(337): [client 192.168.0.10:50281] AH03066: > h2_session(201,IDLE,0): recv FRAME[SETTINGS[length=12, stream=0]], > frames=0/2 (r/s) > [Wed Apr 15 18:36:34.814826 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(337): [client 192.168.0.10:50281] AH03066: > h2_session(201,IDLE,0): recv FRAME[WINDOW_UPDATE[stream=0, > incr=10485760]], frames=1/2 (r/s) > [Wed Apr 15 18:36:34.814880 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(1694): [client 192.168.0.10:50281] > AH03078: h2_session(201,BUSY,0): transit [IDLE] -- frame received --> [BUSY] > [Wed Apr 15 18:36:34.814939 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_stream.c(542): [client 192.168.0.10:50281] AH03082: > h2_stream(201-1,IDLE): created > [Wed Apr 15 18:36:34.815007 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(337): [client 192.168.0.10:50281] AH03066: > h2_session(201,BUSY,1): recv FRAME[HEADERS[length=203, hend=1, stream=1, > eos=1]], frames=2/2 (r/s) > [Wed Apr 15 18:36:34.815231 2020] [ssl:debug] [pid 9879:tid > 140268736587520] ssl_engine_kernel.c(376): [client 192.168.0.10:50281] > AH02034: Subsequent (No.2) HTTPS request received for child 51461 > (server hquest.pro.br:443 <http://hquest.pro.br:443>) > [Wed Apr 15 18:36:34.815329 2020] [authz_core:debug] [pid 9879:tid > 140268736587520] mod_authz_core.c(843): [client 192.168.0.10:50281] > AH01628: authorization result: granted (no directives) > [Wed Apr 15 18:36:34.815489 2020] [http2:debug] [pid 9879:tid > 140268736587520] h2_task.c(83): [client 192.168.0.10:50281] AH03348: > h2_task(000): open output to GET hquest.pro.br <http://hquest.pro.br> > /xxx.png > [Wed Apr 15 18:36:34.815566 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[SETTINGS[ack=1, stream=0]], > frames=3/3 (r/s) > [Wed Apr 15 18:36:34.815682 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(1384): [client 192.168.0.10:50281] > AH03073: h2_stream(201-1,HALF_CLOSED_REMOTE): submit response 200, > REMOTE_WINDOW_SIZE=1048576 > [Wed Apr 15 18:36:34.815744 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(1539): [client 192.168.0.10:50281] > AH02936: h2_stream(201-1,HALF_CLOSED_REMOTE): resumed > [Wed Apr 15 18:36:34.815808 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[HEADERS[length=890, hend=1, stream=1, > eos=0]], frames=3/4 (r/s) > [Wed Apr 15 18:36:34.815869 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/5 (r/s) > [Wed Apr 15 18:36:34.815924 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/6 (r/s) > [Wed Apr 15 18:36:34.815978 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/7 (r/s) > [Wed Apr 15 18:36:34.816032 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/8 (r/s) > [Wed Apr 15 18:36:34.816085 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/9 (r/s) > [Wed Apr 15 18:36:34.816138 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/10 (r/s) > [Wed Apr 15 18:36:34.816214 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/11 (r/s) > [Wed Apr 15 18:36:34.816396 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/12 (r/s) > [Wed Apr 15 18:36:34.816451 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/13 (r/s) > [Wed Apr 15 18:36:34.816506 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/14 (r/s) > [Wed Apr 15 18:36:34.816559 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/15 (r/s) > [Wed Apr 15 18:36:34.816612 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/16 (r/s) > [Wed Apr 15 18:36:34.816666 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/17 (r/s) > [Wed Apr 15 18:36:34.816720 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/18 (r/s) > [Wed Apr 15 18:36:34.816773 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/19 (r/s) > [Wed Apr 15 18:36:34.816827 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/20 (r/s) > [Wed Apr 15 18:36:34.816880 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/21 (r/s) > [Wed Apr 15 18:36:34.816933 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/22 (r/s) > [Wed Apr 15 18:36:34.816986 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/23 (r/s) > [Wed Apr 15 18:36:34.817040 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/24 (r/s) > [Wed Apr 15 18:36:34.817093 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/25 (r/s) > [Wed Apr 15 18:36:34.817165 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/26 (r/s) > [Wed Apr 15 18:36:34.817225 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/27 (r/s) > [Wed Apr 15 18:36:34.817361 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/28 (r/s) > [Wed Apr 15 18:36:34.817416 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=3/29 (r/s) > [Wed Apr 15 18:36:34.817745 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(337): [client 192.168.0.10:50281] AH03066: > h2_session(201,BUSY,1): recv FRAME[SETTINGS[ack=1, stream=0]], > frames=3/29 (r/s) > [Wed Apr 15 18:36:34.817816 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/30 (r/s) > [Wed Apr 15 18:36:34.817871 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/31 (r/s) > [Wed Apr 15 18:36:34.817923 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/32 (r/s) > [Wed Apr 15 18:36:34.817976 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/33 (r/s) > [Wed Apr 15 18:36:34.818028 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/34 (r/s) > [Wed Apr 15 18:36:34.818079 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/35 (r/s) > [Wed Apr 15 18:36:34.818131 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/36 (r/s) > [Wed Apr 15 18:36:34.818202 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/37 (r/s) > [Wed Apr 15 18:36:34.818256 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/38 (r/s) > [Wed Apr 15 18:36:34.818309 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/39 (r/s) > [Wed Apr 15 18:36:34.818380 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/40 (r/s) > [Wed Apr 15 18:36:34.818449 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/41 (r/s) > [Wed Apr 15 18:36:34.818656 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/42 (r/s) > [Wed Apr 15 18:36:34.818714 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/43 (r/s) > [Wed Apr 15 18:36:34.818768 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/44 (r/s) > [Wed Apr 15 18:36:34.818823 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/45 (r/s) > [Wed Apr 15 18:36:34.818885 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/46 (r/s) > [Wed Apr 15 18:36:34.818940 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/47 (r/s) > [Wed Apr 15 18:36:34.818994 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/48 (r/s) > [Wed Apr 15 18:36:34.819048 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1291, flags=0, stream=1, > padlen=0]], frames=4/49 (r/s) > [Wed Apr 15 18:36:34.819106 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(589): [client 192.168.0.10:50281] AH03068: > h2_session(201,BUSY,1): sent FRAME[DATA[length=1201, flags=1, stream=1, > padlen=0]], frames=4/50 (r/s) > [Wed Apr 15 18:36:34.819280 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_bucket_beam.c(1275): [client 192.168.0.10:50281] > beam(201-1,output,closed=1,aborted=1,empty=1,buf=0): AH03385: > h2_task_destroy, reuse slave > [Wed Apr 15 18:36:34.819411 2020] [http2:debug] [pid 9879:tid > 140268531578624] h2_session.c(1694): [client 192.168.0.10:50281] > AH03078: h2_session(201,IDLE,0): transit [BUSY] -- no io (keepalive) --> > [IDLE] > > >> On Apr 15, 2020, at 12:51 AM, Ruediger Pluem <[email protected] >> <mailto:[email protected]>> wrote: >> >> >> >> On 4/15/20 4:50 AM, Alex Hautequest wrote: >>> "SSLStrictSNIVHostCheck On” directive in either _default_ or specific >>> vhost entry has no effect: clients not forced to provide SNI >>> data for web site access. >>> >>> Environment: >>> - Standard HTTPD 2.4.43 and OpenSSL 1.1.1f builds from Pat Volkerdi’ >>> Slackware -current. >>> >>> Enabled "LogLevel debug”, not seeing *anything* SNI related in the logs. >>> >>> Ideas? >> >> Please provide the setup of your virtual hosts (the virtualhost >> container directives and the servername directives within) and a >> request that behaves in the wrong way. >> >> Regards >> >> Rüdiger >> >
