ASF Bugzilla – Attachment 37004 Details for
Bug 64133
mod_proxy does not work with h2 protocol enabled with small request bodies
Home
|
New
|
Browse
|
Search
|
[?]
|
Reports
|
Help
|
New Account
|
Log In
Remember
[x]
|
Forgot Password
Login:
[x]
chrome http2 log
apache-error.h2.chrome.log (text/x-log), 60.25 KB, created by
charly ghislain
on 2020-02-11 09:05:08 UTC
(
hide
)
Description:
chrome http2 log
Filename:
MIME Type:
Creator:
charly ghislain
Created:
2020-02-11 09:05:08 UTC
Size:
60.25 KB
patch
obsolete
>[Tue Feb 11 08:26:30.696646 2020] [ssl:info] [pid 85:tid 139928649190464] AH01914: Configuring server gestemps.local:443 for SSL protocol >[Tue Feb 11 08:26:30.696662 2020] [ssl:trace3] [pid 85:tid 139928649190464] ssl_engine_init.c(597): Creating new SSL context (protocols: TLSv1.1, TLSv1.2, TLSv1.3) >[Tue Feb 11 08:26:30.696697 2020] [ssl:trace1] [pid 85:tid 139928649190464] ssl_engine_init.c(932): Configuring permitted SSL ciphers [HIGH:!aNULL:!MD5:!aNULL:!eNULL:!EXP] >[Tue Feb 11 08:26:30.696731 2020] [ssl:debug] [pid 85:tid 139928649190464] ssl_engine_init.c(497): AH01893: Configuring TLS extension handling >[Tue Feb 11 08:26:30.696827 2020] [ssl:trace3] [pid 85:tid 139928649190464] ssl_util_ssl.c(461): [gestemps.local:443] modssl_X509_match_name: expecting name 'gestemps.local', matched by ID 'gestemps.local' >[Tue Feb 11 08:26:30.696848 2020] [ssl:debug] [pid 85:tid 139928649190464] ssl_util_ssl.c(476): AH02412: [gestemps.local:443] Cert matches for name 'gestemps.local' [subject: CN=gestemps.local,OU=Docker devs,O=Valuya,L=Local,ST=Local,C=BE / issuer: CN=Valuya dev CA,OU=Docker devs,O=Valuya,L=Local,ST=Local,C=BE / serial: 60E4E64368B001A22741D6A2EB569C0A2C6906E9 / notbefore: Dec 1 20:13:27 2019 GMT / notafter: Sep 20 20:13:27 2022 GMT] >[Tue Feb 11 08:26:30.696853 2020] [ssl:info] [pid 85:tid 139928649190464] AH02568: Certificate and private key gestemps.local:443:0 configured from /certs/gestemps.local.pem and /certs/gestemps.local.key >[Tue Feb 11 08:26:30.696888 2020] [ssl:trace3] [pid 85:tid 139928649190464] ssl_engine_init.c(597): Creating new SSL context (protocols: TLSv1, TLSv1.1, TLSv1.2, TLSv1.3) >[Tue Feb 11 08:26:30.696926 2020] [ssl:trace1] [pid 85:tid 139928649190464] ssl_engine_init.c(932): Configuring permitted SSL ciphers [ALL:!COMPLEMENTOFDEFAULT:!eNULL:!aNULL:!eNULL:!EXP] >[Tue Feb 11 08:26:30.701262 2020] [ssl:info] [pid 85:tid 139928649190464] AH01914: Configuring server gestemps.local:443 for SSL protocol >[Tue Feb 11 08:26:30.701268 2020] [ssl:trace3] [pid 85:tid 139928649190464] ssl_engine_init.c(597): Creating new SSL context (protocols: TLSv1.1, TLSv1.2, TLSv1.3) >[Tue Feb 11 08:26:30.701298 2020] [ssl:trace1] [pid 85:tid 139928649190464] ssl_engine_init.c(932): Configuring permitted SSL ciphers [HIGH:!aNULL:!MD5:!aNULL:!eNULL:!EXP] >[Tue Feb 11 08:26:30.701331 2020] [ssl:debug] [pid 85:tid 139928649190464] ssl_engine_init.c(497): AH01893: Configuring TLS extension handling >[Tue Feb 11 08:26:30.701415 2020] [ssl:trace3] [pid 85:tid 139928649190464] ssl_util_ssl.c(461): [gestemps.local:443] modssl_X509_match_name: expecting name 'gestemps.local', matched by ID 'gestemps.local' >[Tue Feb 11 08:26:30.701430 2020] [ssl:debug] [pid 85:tid 139928649190464] ssl_util_ssl.c(476): AH02412: [gestemps.local:443] Cert matches for name 'gestemps.local' [subject: CN=gestemps.local,OU=Docker devs,O=Valuya,L=Local,ST=Local,C=BE / issuer: CN=Valuya dev CA,OU=Docker devs,O=Valuya,L=Local,ST=Local,C=BE / serial: 60E4E64368B001A22741D6A2EB569C0A2C6906E9 / notbefore: Dec 1 20:13:27 2019 GMT / notafter: Sep 20 20:13:27 2022 GMT] >[Tue Feb 11 08:26:30.701433 2020] [ssl:info] [pid 85:tid 139928649190464] AH02568: Certificate and private key gestemps.local:443:0 configured from /certs/gestemps.local.pem and /certs/gestemps.local.key >[Tue Feb 11 08:26:30.701465 2020] [ssl:trace3] [pid 85:tid 139928649190464] ssl_engine_init.c(597): Creating new SSL context (protocols: TLSv1, TLSv1.1, TLSv1.2, TLSv1.3) >[Tue Feb 11 08:26:30.701500 2020] [ssl:trace1] [pid 85:tid 139928649190464] ssl_engine_init.c(932): Configuring permitted SSL ciphers [ALL:!COMPLEMENTOFDEFAULT:!eNULL:!aNULL:!eNULL:!EXP] >[Tue Feb 11 08:26:30.703696 2020] [proxy:debug] [pid 86:tid 139928649190464] proxy_util.c(1933): AH00925: initializing worker http://as:8080/ shared >[Tue Feb 11 08:26:30.703709 2020] [proxy:debug] [pid 86:tid 139928649190464] proxy_util.c(1990): AH00927: initializing worker http://as:8080/ local >[Tue Feb 11 08:26:30.703718 2020] [proxy:debug] [pid 86:tid 139928649190464] proxy_util.c(2024): AH00930: initialized pool in child 86 for (as) min=0 max=61 smax=61 >[Tue Feb 11 08:26:30.703796 2020] [proxy:debug] [pid 87:tid 139928649190464] proxy_util.c(1933): AH00925: initializing worker http://as:8080/ shared >[Tue Feb 11 08:26:30.703809 2020] [proxy:debug] [pid 87:tid 139928649190464] proxy_util.c(1990): AH00927: initializing worker http://as:8080/ local >[Tue Feb 11 08:26:30.703818 2020] [proxy:debug] [pid 87:tid 139928649190464] proxy_util.c(2024): AH00930: initialized pool in child 87 for (as) min=0 max=61 smax=61 >[Tue Feb 11 08:26:37.527267 2020] [ssl:info] [pid 86:tid 139928314013440] [client 172.21.0.1:36444] AH01964: Connection to child 1 established (server gestemps.local:443) >[Tue Feb 11 08:26:37.527367 2020] [ssl:trace2] [pid 86:tid 139928314013440] ssl_engine_rand.c(125): Server: Seeding PRNG with 656 bytes of entropy >[Tue Feb 11 08:26:37.527453 2020] [ssl:trace3] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2182): [client 172.21.0.1:36444] OpenSSL: Handshake: start >[Tue Feb 11 08:26:37.527485 2020] [ssl:trace3] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2190): [client 172.21.0.1:36444] OpenSSL: Loop: before SSL initialization >[Tue Feb 11 08:26:37.527503 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: read 5/5 bytes from BIO#7f438c002ca0 [mem: 7f438c008543] >[Tue Feb 11 08:26:37.527509 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: read 4/625 bytes from BIO#7f438c002ca0 [mem: 7f438c008548] >[Tue Feb 11 08:26:37.527515 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: read 621/621 bytes from BIO#7f438c002ca0 [mem: 7f438c00854c] >[Tue Feb 11 08:26:37.527521 2020] [ssl:trace3] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2190): [client 172.21.0.1:36444] OpenSSL: Loop: before SSL initialization >[Tue Feb 11 08:26:37.527562 2020] [ssl:debug] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2349): [client 172.21.0.1:36444] AH02043: SSL virtual host for servername gestemps.local found >[Tue Feb 11 08:26:37.527570 2020] [ssl:debug] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2349): [client 172.21.0.1:36444] AH02043: SSL virtual host for servername gestemps.local found >[Tue Feb 11 08:26:37.527574 2020] [core:debug] [pid 86:tid 139928314013440] protocol.c(2313): [client 172.21.0.1:36444] AH03155: select protocol from h2,h2c,http/1.1, choices=h2,http/1.1 for server gestemps.local >[Tue Feb 11 08:26:37.527583 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_switch.c(116): [client 172.21.0.1:36444] proposing protocol 'h2' >[Tue Feb 11 08:26:37.527587 2020] [core:debug] [pid 86:tid 139928314013440] protocol.c(2357): [client 172.21.0.1:36444] AH03156: select protocol, proposals=h2,http/1.1 preferences=h2,h2c,http/1.1 configured=h2,h2c,http/1.1 >[Tue Feb 11 08:26:37.527592 2020] [core:debug] [pid 86:tid 139928314013440] protocol.c(2377): [client 172.21.0.1:36444] AH03157: selected protocol=h2 >[Tue Feb 11 08:26:37.527596 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_switch.c(149): [client 172.21.0.1:36444] switching protocol to 'h2' >[Tue Feb 11 08:26:37.527624 2020] [ssl:trace3] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2190): [client 172.21.0.1:36444] OpenSSL: Loop: SSLv3/TLS read client hello >[Tue Feb 11 08:26:37.527770 2020] [ssl:trace3] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2190): [client 172.21.0.1:36444] OpenSSL: Loop: SSLv3/TLS write server hello >[Tue Feb 11 08:26:37.527810 2020] [ssl:trace3] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2190): [client 172.21.0.1:36444] OpenSSL: Loop: SSLv3/TLS write change cipher spec >[Tue Feb 11 08:26:37.527823 2020] [ssl:trace3] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2190): [client 172.21.0.1:36444] OpenSSL: Loop: TLSv1.3 write encrypted extensions >[Tue Feb 11 08:26:37.527852 2020] [ssl:trace3] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2190): [client 172.21.0.1:36444] OpenSSL: Loop: SSLv3/TLS write certificate >[Tue Feb 11 08:26:37.532752 2020] [ssl:trace3] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2190): [client 172.21.0.1:36444] OpenSSL: Loop: TLSv1.3 write server certificate verify >[Tue Feb 11 08:26:37.532789 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: write 1985/1985 bytes to BIO#7f438c002be0 [mem: 7f438c0117f0] >[Tue Feb 11 08:26:37.532851 2020] [ssl:trace3] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2190): [client 172.21.0.1:36444] OpenSSL: Loop: SSLv3/TLS write finished >[Tue Feb 11 08:26:37.532856 2020] [ssl:trace3] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2190): [client 172.21.0.1:36444] OpenSSL: Loop: TLSv1.3 early data >[Tue Feb 11 08:26:37.533296 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: read 5/5 bytes from BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.533324 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: read 1/1 bytes from BIO#7f438c002ca0 [mem: 7f438c01bc88] >[Tue Feb 11 08:26:37.533328 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: read 5/5 bytes from BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.533331 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: read 69/69 bytes from BIO#7f438c002ca0 [mem: 7f438c01bc88] >[Tue Feb 11 08:26:37.533337 2020] [ssl:trace3] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2190): [client 172.21.0.1:36444] OpenSSL: Loop: TLSv1.3 early data >[Tue Feb 11 08:26:37.533394 2020] [ssl:trace3] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2190): [client 172.21.0.1:36444] OpenSSL: Loop: SSLv3/TLS read finished >[Tue Feb 11 08:26:37.533397 2020] [ssl:trace3] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2186): [client 172.21.0.1:36444] OpenSSL: Handshake: done >[Tue Feb 11 08:26:37.533403 2020] [ssl:debug] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2231): [client 172.21.0.1:36444] AH02041: Protocol: TLSv1.3, Cipher: TLS_AES_256_GCM_SHA384 (256/256 bits) >[Tue Feb 11 08:26:37.533492 2020] [socache_shmcb:debug] [pid 86:tid 139928314013440] mod_socache_shmcb.c(493): AH00831: socache_shmcb_store (0xfa -> subcache 26) >[Tue Feb 11 08:26:37.533503 2020] [socache_shmcb:debug] [pid 86:tid 139928314013440] mod_socache_shmcb.c(847): AH00847: insert happened at idx=0, data=(0:32) >[Tue Feb 11 08:26:37.533506 2020] [socache_shmcb:debug] [pid 86:tid 139928314013440] mod_socache_shmcb.c(850): AH00848: finished insert, subcache: idx_pos/idx_used=0/1, data_pos/data_used=0/212 >[Tue Feb 11 08:26:37.533509 2020] [socache_shmcb:debug] [pid 86:tid 139928314013440] mod_socache_shmcb.c(515): AH00834: leaving socache_shmcb_store successfully >[Tue Feb 11 08:26:37.533529 2020] [ssl:trace2] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2038): Inter-Process Session Cache: request=SET status=OK id=fac1964bb76024b5b53b8be372c91339cacafdb64a0e62d12128f6f9e1801525 timeout=300s (session caching) >[Tue Feb 11 08:26:37.533556 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: write 303/303 bytes to BIO#7f438c002be0 [mem: 7f438c0117f0] >[Tue Feb 11 08:26:37.533594 2020] [ssl:trace3] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2190): [client 172.21.0.1:36444] OpenSSL: Loop: SSLv3/TLS write session ticket >[Tue Feb 11 08:26:37.533624 2020] [socache_shmcb:debug] [pid 86:tid 139928314013440] mod_socache_shmcb.c(493): AH00831: socache_shmcb_store (0xf2 -> subcache 18) >[Tue Feb 11 08:26:37.533630 2020] [socache_shmcb:debug] [pid 86:tid 139928314013440] mod_socache_shmcb.c(847): AH00847: insert happened at idx=0, data=(0:32) >[Tue Feb 11 08:26:37.533632 2020] [socache_shmcb:debug] [pid 86:tid 139928314013440] mod_socache_shmcb.c(850): AH00848: finished insert, subcache: idx_pos/idx_used=0/1, data_pos/data_used=0/212 >[Tue Feb 11 08:26:37.533635 2020] [socache_shmcb:debug] [pid 86:tid 139928314013440] mod_socache_shmcb.c(515): AH00834: leaving socache_shmcb_store successfully >[Tue Feb 11 08:26:37.533638 2020] [ssl:trace2] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2038): Inter-Process Session Cache: request=SET status=OK id=f230b5bcde4bd910d4e2bc2650a80979b75f72f1e7662a02317ac4a13fef5a95 timeout=300s (session caching) >[Tue Feb 11 08:26:37.533649 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: write 303/303 bytes to BIO#7f438c002be0 [mem: 7f438c0117f0] >[Tue Feb 11 08:26:37.533667 2020] [ssl:trace3] [pid 86:tid 139928314013440] ssl_engine_kernel.c(2190): [client 172.21.0.1:36444] OpenSSL: Loop: SSLv3/TLS write session ticket >[Tue Feb 11 08:26:37.533678 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_h2.c(588): [client 172.21.0.1:36444] h2_h2, process_conn >[Tue Feb 11 08:26:37.533681 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_h2.c(647): [client 172.21.0.1:36444] process_conn >[Tue Feb 11 08:26:37.533696 2020] [http2:trace4] [pid 86:tid 139928314013440] h2_conn_io.c(156): [client 172.21.0.1:36444] h2_conn_io(1): init, buffering=1, warmup_size=1048576, cd_secs=1.000000 >[Tue Feb 11 08:26:37.533711 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(948): [client 172.21.0.1:36444] AH03200: h2_session(1,INIT,0): created, max_streams=100, stream_mem=32768, workers_limit=6, workers_max=37, push_diary(type=1,N=256) >[Tue Feb 11 08:26:37.533715 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_h2.c(651): [client 172.21.0.1:36444] conn_setup >[Tue Feb 11 08:26:37.533718 2020] [http2:trace3] [pid 86:tid 139928314013440] h2_session.c(2101): [client 172.21.0.1:36444] h2_session(1,INIT,0): process start, async=1 >[Tue Feb 11 08:26:37.533724 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(1037): [client 172.21.0.1:36444] AH03201: h2_session(1,INIT,0): start, INITIAL_WINDOW_SIZE=65535, MAX_CONCURRENT_STREAMS=100 >[Tue Feb 11 08:26:37.533728 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(2130): [client 172.21.0.1:36444] AH03079: h2_session(1,INIT,0): started on gestemps.local:443 >[Tue Feb 11 08:26:37.533731 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(1694): [client 172.21.0.1:36444] AH03078: h2_session(1,BUSY,0): transit [INIT] -- init --> [BUSY] >[Tue Feb 11 08:26:37.533734 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(143): [client 172.21.0.1:36444] h2_session(1): read, NONBLOCK_READ, mode=0, readbytes=65536 >[Tue Feb 11 08:26:37.533745 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: read 5/5 bytes from BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.533748 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: read 81/81 bytes from BIO#7f438c002ca0 [mem: 7f438c01bc88] >[Tue Feb 11 08:26:37.533754 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_filter.c(94): [client 172.21.0.1:36444] bb_dump(1): RAW_in(TRANSIENT[64]) >[Tue Feb 11 08:26:37.533760 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(337): [client 172.21.0.1:36444] AH03066: h2_session(1,BUSY,0): recv FRAME[SETTINGS[length=18, stream=0]], frames=0/0 (r/s) >[Tue Feb 11 08:26:37.533763 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(418): [client 172.21.0.1:36444] h2_session(1,BUSY,0): SETTINGS, len=18 >[Tue Feb 11 08:26:37.533767 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(337): [client 172.21.0.1:36444] AH03066: h2_session(1,BUSY,0): recv FRAME[WINDOW_UPDATE[stream=0, incr=15663105]], frames=1/0 (r/s) >[Tue Feb 11 08:26:37.533769 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(378): [client 172.21.0.1:36444] h2_stream(1-0): WINDOW_UPDATE incr=15663105 >[Tue Feb 11 08:26:37.533772 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_filter.c(63): [client 172.21.0.1:36444] h2_session(1,BUSY,0): fed 64 bytes to nghttp2, 64 read >[Tue Feb 11 08:26:37.533775 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(143): [client 172.21.0.1:36444] h2_session(1): read, NONBLOCK_READ, mode=0, readbytes=65536 >[Tue Feb 11 08:26:37.533778 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: read 5/5 bytes from BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.533785 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: read 426/426 bytes from BIO#7f438c002ca0 [mem: 7f438c01bc88] >[Tue Feb 11 08:26:37.533789 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_filter.c(94): [client 172.21.0.1:36444] bb_dump(1): RAW_in(TRANSIENT[409]) >[Tue Feb 11 08:26:37.533796 2020] [http2:debug] [pid 86:tid 139928314013440] h2_stream.c(542): [client 172.21.0.1:36444] AH03082: h2_stream(1-1,IDLE): created >[Tue Feb 11 08:26:37.533799 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1953): [client 172.21.0.1:36444] h2_stream(1-1,IDLE): entered state >[Tue Feb 11 08:26:37.533821 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(337): [client 172.21.0.1:36444] AH03066: h2_session(1,BUSY,1): recv FRAME[HEADERS[length=400, hend=1, stream=1, eos=0]], frames=2/0 (r/s) >[Tue Feb 11 08:26:37.533825 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_stream.c(301): [client 172.21.0.1:36444] h2_stream(1-1,IDLE): transit to [OPEN] >[Tue Feb 11 08:26:37.533828 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1953): [client 172.21.0.1:36444] h2_stream(1-1,OPEN): entered state >[Tue Feb 11 08:26:37.533831 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_filter.c(63): [client 172.21.0.1:36444] h2_session(1,BUSY,1): fed 409 bytes to nghttp2, 409 read >[Tue Feb 11 08:26:37.533834 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(143): [client 172.21.0.1:36444] h2_session(1): read, NONBLOCK_READ, mode=0, readbytes=65536 >[Tue Feb 11 08:26:37.533837 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: read 5/5 bytes from BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.533841 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: read 28/28 bytes from BIO#7f438c002ca0 [mem: 7f438c01bc88] >[Tue Feb 11 08:26:37.533844 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_filter.c(94): [client 172.21.0.1:36444] bb_dump(1): RAW_in(TRANSIENT[11]) >[Tue Feb 11 08:26:37.533848 2020] [http2:trace3] [pid 86:tid 139928314013440] h2_stream.c(496): [client 172.21.0.1:36444] h2_stream(1-1,OPEN): recv DATA, len=2: -->{}<-- >[Tue Feb 11 08:26:37.533851 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_stream.c(343): [client 172.21.0.1:36444] h2_stream(1-1,OPEN): dispatch event 4 >[Tue Feb 11 08:26:37.533853 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_stream.c(355): [client 172.21.0.1:36444] h2_stream(1-1,OPEN): non-state event 4 >[Tue Feb 11 08:26:37.533885 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(337): [client 172.21.0.1:36444] AH03066: h2_session(1,BUSY,1): recv FRAME[DATA[length=2, flags=1, stream=1, padlen=0]], frames=3/0 (r/s) >[Tue Feb 11 08:26:37.533888 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(359): [client 172.21.0.1:36444] AH02923: h2_stream(1-1,OPEN): DATA, len=2, flags=1 >[Tue Feb 11 08:26:37.533891 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_stream.c(301): [client 172.21.0.1:36444] h2_stream(1-1,OPEN): transit to [HALF_CLOSED_REMOTE] >[Tue Feb 11 08:26:37.533894 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_stream.c(210): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): closing input >[Tue Feb 11 08:26:37.533896 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1953): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): entered state >[Tue Feb 11 08:26:37.533899 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_filter.c(63): [client 172.21.0.1:36444] h2_session(1,BUSY,1): fed 11 bytes to nghttp2, 11 read >[Tue Feb 11 08:26:37.533902 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(143): [client 172.21.0.1:36444] h2_session(1): read, NONBLOCK_READ, mode=0, readbytes=65536 >[Tue Feb 11 08:26:37.533927 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2217): [client 172.21.0.1:36444] OpenSSL: I/O error, 5 bytes expected to read on BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.533933 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(190): (11)Resource temporarily unavailable: [client 172.21.0.1:36444] h2_session(1): read >[Tue Feb 11 08:26:37.533943 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_stream.c(583): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): schedule POST https://gestemps.local:1443/Gestemps-ws/front/trustee/search chunked=0 >[Tue Feb 11 08:26:37.533952 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_mplx.c(688): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): process, added to q >[Tue Feb 11 08:26:37.533956 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(1094): [client 172.21.0.1:36444] h2_mplx(1): dispatch events >[Tue Feb 11 08:26:37.533961 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(589): [client 172.21.0.1:36444] AH03068: h2_session(1,BUSY,1): sent FRAME[SETTINGS[length=6, stream=0]], frames=4/1 (r/s) >[Tue Feb 11 08:26:37.533964 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(589): [client 172.21.0.1:36444] AH03068: h2_session(1,BUSY,1): sent FRAME[SETTINGS[ack=1, stream=0]], frames=4/2 (r/s) >[Tue Feb 11 08:26:37.533968 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(589): [client 172.21.0.1:36444] AH03068: h2_session(1,BUSY,1): sent FRAME[WINDOW_UPDATE[stream=0, incr=2147418112]], frames=4/3 (r/s) >[Tue Feb 11 08:26:37.533967 2020] [http2:trace3] [pid 86:tid 139928507143936] h2_conn.c(273): [client 172.21.0.1:36444] h2_stream(1-1): create slave >[Tue Feb 11 08:26:37.533973 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_conn_io.c(126): [client 172.21.0.1:36444] h2_session(1)-out: heap[37] flush >[Tue Feb 11 08:26:37.533980 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: write 59/59 bytes to BIO#7f438c002be0 [mem: 7f438c00ac43] >[Tue Feb 11 08:26:37.533988 2020] [http2:trace3] [pid 86:tid 139928507143936] h2_conn.c(344): [client 172.21.0.1:36444] h2_slave(1-1): created >[Tue Feb 11 08:26:37.533995 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_task.c(494): [client 172.21.0.1:36444] h2_slave(1-1), pre_connection, adding filters >[Tue Feb 11 08:26:37.533999 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(143): [client 172.21.0.1:36444] h2_session(1): read, NONBLOCK_READ, mode=0, readbytes=65536 >[Tue Feb 11 08:26:37.534006 2020] [http2:trace1] [pid 86:tid 139928507143936] h2_task.c(617): [client 172.21.0.1:36444] h2_task(1-1): process connection >[Tue Feb 11 08:26:37.534006 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2217): [client 172.21.0.1:36444] OpenSSL: I/O error, 5 bytes expected to read on BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.534010 2020] [http2:trace1] [pid 86:tid 139928507143936] h2_task.c(701): [client 172.21.0.1:36444] h2_h2, processing request directly >[Tue Feb 11 08:26:37.534012 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(190): (11)Resource temporarily unavailable: [client 172.21.0.1:36444] h2_session(1): read >[Tue Feb 11 08:26:37.534034 2020] [http2:trace1] [pid 86:tid 139928507143936] h2_task.c(634): [client 172.21.0.1:36444] h2_task(1-1): create request_rec >[Tue Feb 11 08:26:37.534036 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(1094): [client 172.21.0.1:36444] h2_mplx(1): dispatch events >[Tue Feb 11 08:26:37.534040 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1822): [client 172.21.0.1:36444] h2_session(1,BUSY,1): NO_IO event, 1 streams open >[Tue Feb 11 08:26:37.534043 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_session.c(1694): [client 172.21.0.1:36444] AH03078: h2_session(1,WAIT,1): transit [BUSY] -- no io --> [WAIT] >[Tue Feb 11 08:26:37.534045 2020] [http2:trace3] [pid 86:tid 139928314013440] h2_session.c(2318): [client 172.21.0.1:36444] h2_session: wait for data, 10 micros >[Tue Feb 11 08:26:37.534053 2020] [http2:trace3] [pid 86:tid 139928507143936] h2_h2.c(720): [client 172.21.0.1:36444] h2_task(1-1): adding request filters, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534108 2020] [ssl:debug] [pid 86:tid 139928507143936] ssl_engine_kernel.c(376): [client 172.21.0.1:36444] AH02034: Subsequent (No.2) HTTPS request received for child 4294967297 (server gestemps.local:443), referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534113 2020] [http2:trace1] [pid 86:tid 139928507143936] h2_task.c(652): [client 172.21.0.1:36444] h2_task(1-1): start process_request >[Tue Feb 11 08:26:37.534112 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(609): [client 172.21.0.1:36444] h2_mplx(1): trywait on data for 0.010000 ms) >[Tue Feb 11 08:26:37.534116 2020] [http:trace4] [pid 86:tid 139928507143936] http_request.c(436): [client 172.21.0.1:36444] Headers received from client:, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534118 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_session.c(1694): [client 172.21.0.1:36444] AH03078: h2_session(1,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] >[Tue Feb 11 08:26:37.534119 2020] [http:trace4] [pid 86:tid 139928507143936] http_request.c(439): [client 172.21.0.1:36444] Content-Length: 2, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534122 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(143): [client 172.21.0.1:36444] h2_session(1): read, NONBLOCK_READ, mode=0, readbytes=65536 >[Tue Feb 11 08:26:37.534122 2020] [http:trace4] [pid 86:tid 139928507143936] http_request.c(439): [client 172.21.0.1:36444] Origin: http://localhost:4200, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534126 2020] [http:trace4] [pid 86:tid 139928507143936] http_request.c(439): [client 172.21.0.1:36444] Authorization: Bearer , referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534128 2020] [http:trace4] [pid 86:tid 139928507143936] http_request.c(439): [client 172.21.0.1:36444] Content-Type: application/json; charset=UTF-8, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534128 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: read 5/5 bytes from BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.534131 2020] [http:trace4] [pid 86:tid 139928507143936] http_request.c(439): [client 172.21.0.1:36444] Accept: application/json, text/plain, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534133 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: read 26/26 bytes from BIO#7f438c002ca0 [mem: 7f438c01bc88] >[Tue Feb 11 08:26:37.534134 2020] [http:trace4] [pid 86:tid 139928507143936] http_request.c(439): [client 172.21.0.1:36444] X-Requested-With: XmlHttpRequest, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534137 2020] [http:trace4] [pid 86:tid 139928507143936] http_request.c(439): [client 172.21.0.1:36444] User-Agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.130 Safari/537.36 OPR/66.0.3515.72, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534138 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_filter.c(94): [client 172.21.0.1:36444] bb_dump(1): RAW_in(TRANSIENT[9]) >[Tue Feb 11 08:26:37.534140 2020] [http:trace4] [pid 86:tid 139928507143936] http_request.c(439): [client 172.21.0.1:36444] Dnt: 1, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534142 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(337): [client 172.21.0.1:36444] AH03066: h2_session(1,BUSY,1): recv FRAME[SETTINGS[ack=1, stream=0]], frames=4/3 (r/s) >[Tue Feb 11 08:26:37.534142 2020] [http:trace4] [pid 86:tid 139928507143936] http_request.c(439): [client 172.21.0.1:36444] Sec-Fetch-Site: cross-site, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534145 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(418): [client 172.21.0.1:36444] h2_session(1,BUSY,1): SETTINGS, len=0 >[Tue Feb 11 08:26:37.534146 2020] [http:trace4] [pid 86:tid 139928507143936] http_request.c(439): [client 172.21.0.1:36444] Sec-Fetch-Mode: cors, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534151 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_filter.c(63): [client 172.21.0.1:36444] h2_session(1,BUSY,1): fed 9 bytes to nghttp2, 9 read >[Tue Feb 11 08:26:37.534153 2020] [http:trace4] [pid 86:tid 139928507143936] http_request.c(439): [client 172.21.0.1:36444] Referer: http://localhost:4200/trustee/list, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534154 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(143): [client 172.21.0.1:36444] h2_session(1): read, NONBLOCK_READ, mode=0, readbytes=65536 >[Tue Feb 11 08:26:37.534157 2020] [http:trace4] [pid 86:tid 139928507143936] http_request.c(439): [client 172.21.0.1:36444] Accept-Encoding: gzip, deflate, br, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534159 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2217): [client 172.21.0.1:36444] OpenSSL: I/O error, 5 bytes expected to read on BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.534160 2020] [http:trace4] [pid 86:tid 139928507143936] http_request.c(439): [client 172.21.0.1:36444] Accept-Language: en,nl;q=0.9,en-US;q=0.8, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534163 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(190): (11)Resource temporarily unavailable: [client 172.21.0.1:36444] h2_session(1): read >[Tue Feb 11 08:26:37.534164 2020] [http:trace4] [pid 86:tid 139928507143936] http_request.c(439): [client 172.21.0.1:36444] Host: gestemps.local:1443, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534166 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(1094): [client 172.21.0.1:36444] h2_mplx(1): dispatch events >[Tue Feb 11 08:26:37.534170 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(143): [client 172.21.0.1:36444] h2_session(1): read, NONBLOCK_READ, mode=0, readbytes=65536 >[Tue Feb 11 08:26:37.534170 2020] [proxy:trace2] [pid 86:tid 139928507143936] mod_proxy.c(680): [client 172.21.0.1:36444] AH03461: attempting to match URI path '/Gestemps-ws/front/trustee/search' against prefix '/' for proxying, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534173 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2217): [client 172.21.0.1:36444] OpenSSL: I/O error, 5 bytes expected to read on BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.534174 2020] [proxy:trace1] [pid 86:tid 139928507143936] mod_proxy.c(766): [client 172.21.0.1:36444] AH03464: URI path '/Gestemps-ws/front/trustee/search' matches proxy handler 'proxy:http://as:8080/Gestemps-ws/front/trustee/search', referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534176 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(190): (11)Resource temporarily unavailable: [client 172.21.0.1:36444] h2_session(1): read >[Tue Feb 11 08:26:37.534179 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(1094): [client 172.21.0.1:36444] h2_mplx(1): dispatch events >[Tue Feb 11 08:26:37.534182 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1822): [client 172.21.0.1:36444] h2_session(1,BUSY,1): NO_IO event, 1 streams open >[Tue Feb 11 08:26:37.534184 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_session.c(1694): [client 172.21.0.1:36444] AH03078: h2_session(1,WAIT,1): transit [BUSY] -- no io --> [WAIT] >[Tue Feb 11 08:26:37.534186 2020] [authz_core:debug] [pid 86:tid 139928507143936] mod_authz_core.c(845): [client 172.21.0.1:36444] AH01628: authorization result: granted (no directives), referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534187 2020] [http2:trace3] [pid 86:tid 139928314013440] h2_session.c(2318): [client 172.21.0.1:36444] h2_session: wait for data, 10 micros >[Tue Feb 11 08:26:37.534190 2020] [core:trace3] [pid 86:tid 139928507143936] request.c(310): [client 172.21.0.1:36444] request authorized without authentication by access_checker_ex hook: /Gestemps-ws/front/trustee/search, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534205 2020] [proxy_http:trace1] [pid 86:tid 139928507143936] mod_proxy_http.c(62): [client 172.21.0.1:36444] HTTP: canonicalising URL //as:8080/Gestemps-ws/front/trustee/search, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534232 2020] [proxy:trace2] [pid 86:tid 139928507143936] proxy_util.c(2131): [client 172.21.0.1:36444] http: found worker http://as:8080/ for http://as:8080/Gestemps-ws/front/trustee/search, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534236 2020] [proxy:debug] [pid 86:tid 139928507143936] mod_proxy.c(1247): [client 172.21.0.1:36444] AH01143: Running scheme http handler (attempt 0), referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534239 2020] [proxy_http:trace1] [pid 86:tid 139928507143936] mod_proxy_http.c(1987): [client 172.21.0.1:36444] HTTP: serving URL http://as:8080/Gestemps-ws/front/trustee/search, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534245 2020] [proxy:debug] [pid 86:tid 139928507143936] proxy_util.c(2325): AH00942: HTTP: has acquired connection for (as) >[Tue Feb 11 08:26:37.534249 2020] [proxy:debug] [pid 86:tid 139928507143936] proxy_util.c(2379): [client 172.21.0.1:36444] AH00944: connecting http://as:8080/Gestemps-ws/front/trustee/search to as:8080, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534258 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(609): [client 172.21.0.1:36444] h2_mplx(1): trywait on data for 0.010000 ms) >[Tue Feb 11 08:26:37.534268 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_session.c(1694): [client 172.21.0.1:36444] AH03078: h2_session(1,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] >[Tue Feb 11 08:26:37.534272 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(143): [client 172.21.0.1:36444] h2_session(1): read, NONBLOCK_READ, mode=0, readbytes=65536 >[Tue Feb 11 08:26:37.534280 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2217): [client 172.21.0.1:36444] OpenSSL: I/O error, 5 bytes expected to read on BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.534284 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(190): (11)Resource temporarily unavailable: [client 172.21.0.1:36444] h2_session(1): read >[Tue Feb 11 08:26:37.534288 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(1094): [client 172.21.0.1:36444] h2_mplx(1): dispatch events >[Tue Feb 11 08:26:37.534292 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1822): [client 172.21.0.1:36444] h2_session(1,BUSY,1): NO_IO event, 1 streams open >[Tue Feb 11 08:26:37.534295 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_session.c(1694): [client 172.21.0.1:36444] AH03078: h2_session(1,WAIT,1): transit [BUSY] -- no io --> [WAIT] >[Tue Feb 11 08:26:37.534298 2020] [http2:trace3] [pid 86:tid 139928314013440] h2_session.c(2318): [client 172.21.0.1:36444] h2_session: wait for data, 20 micros >[Tue Feb 11 08:26:37.534387 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(609): [client 172.21.0.1:36444] h2_mplx(1): trywait on data for 0.020000 ms) >[Tue Feb 11 08:26:37.534396 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_session.c(1694): [client 172.21.0.1:36444] AH03078: h2_session(1,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] >[Tue Feb 11 08:26:37.534400 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(143): [client 172.21.0.1:36444] h2_session(1): read, NONBLOCK_READ, mode=0, readbytes=65536 >[Tue Feb 11 08:26:37.534407 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2217): [client 172.21.0.1:36444] OpenSSL: I/O error, 5 bytes expected to read on BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.534411 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(190): (11)Resource temporarily unavailable: [client 172.21.0.1:36444] h2_session(1): read >[Tue Feb 11 08:26:37.534415 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(1094): [client 172.21.0.1:36444] h2_mplx(1): dispatch events >[Tue Feb 11 08:26:37.534424 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1822): [client 172.21.0.1:36444] h2_session(1,BUSY,1): NO_IO event, 1 streams open >[Tue Feb 11 08:26:37.534427 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_session.c(1694): [client 172.21.0.1:36444] AH03078: h2_session(1,WAIT,1): transit [BUSY] -- no io --> [WAIT] >[Tue Feb 11 08:26:37.534431 2020] [http2:trace3] [pid 86:tid 139928314013440] h2_session.c(2318): [client 172.21.0.1:36444] h2_session: wait for data, 40 micros >[Tue Feb 11 08:26:37.534528 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(609): [client 172.21.0.1:36444] h2_mplx(1): trywait on data for 0.040000 ms) >[Tue Feb 11 08:26:37.534533 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_session.c(1694): [client 172.21.0.1:36444] AH03078: h2_session(1,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] >[Tue Feb 11 08:26:37.534536 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(143): [client 172.21.0.1:36444] h2_session(1): read, NONBLOCK_READ, mode=0, readbytes=65536 >[Tue Feb 11 08:26:37.534542 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2217): [client 172.21.0.1:36444] OpenSSL: I/O error, 5 bytes expected to read on BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.534545 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(190): (11)Resource temporarily unavailable: [client 172.21.0.1:36444] h2_session(1): read >[Tue Feb 11 08:26:37.534548 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(1094): [client 172.21.0.1:36444] h2_mplx(1): dispatch events >[Tue Feb 11 08:26:37.534551 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1822): [client 172.21.0.1:36444] h2_session(1,BUSY,1): NO_IO event, 1 streams open >[Tue Feb 11 08:26:37.534554 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_session.c(1694): [client 172.21.0.1:36444] AH03078: h2_session(1,WAIT,1): transit [BUSY] -- no io --> [WAIT] >[Tue Feb 11 08:26:37.534556 2020] [http2:trace3] [pid 86:tid 139928314013440] h2_session.c(2318): [client 172.21.0.1:36444] h2_session: wait for data, 80 micros >[Tue Feb 11 08:26:37.534692 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(609): [client 172.21.0.1:36444] h2_mplx(1): trywait on data for 0.080000 ms) >[Tue Feb 11 08:26:37.534696 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_session.c(1694): [client 172.21.0.1:36444] AH03078: h2_session(1,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] >[Tue Feb 11 08:26:37.534699 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(143): [client 172.21.0.1:36444] h2_session(1): read, NONBLOCK_READ, mode=0, readbytes=65536 >[Tue Feb 11 08:26:37.534704 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2217): [client 172.21.0.1:36444] OpenSSL: I/O error, 5 bytes expected to read on BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.534708 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(190): (11)Resource temporarily unavailable: [client 172.21.0.1:36444] h2_session(1): read >[Tue Feb 11 08:26:37.534711 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(1094): [client 172.21.0.1:36444] h2_mplx(1): dispatch events >[Tue Feb 11 08:26:37.534713 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1822): [client 172.21.0.1:36444] h2_session(1,BUSY,1): NO_IO event, 1 streams open >[Tue Feb 11 08:26:37.534716 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_session.c(1694): [client 172.21.0.1:36444] AH03078: h2_session(1,WAIT,1): transit [BUSY] -- no io --> [WAIT] >[Tue Feb 11 08:26:37.534719 2020] [http2:trace3] [pid 86:tid 139928314013440] h2_session.c(2318): [client 172.21.0.1:36444] h2_session: wait for data, 160 micros >[Tue Feb 11 08:26:37.534718 2020] [proxy:debug] [pid 86:tid 139928507143936] proxy_util.c(2588): [client 172.21.0.1:36444] AH00947: connected /Gestemps-ws/front/trustee/search to as:8080, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534740 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_from_h1.c(764): [client 172.21.0.1:36444] h2_task(1-1): request filter, exp=0, referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534746 2020] [http2:trace1] [pid 86:tid 139928507143936] h2_task.c(226): [client 172.21.0.1:36444] h2_slave_in(1-1): read, mode=0, block=0, readbytes=4 >[Tue Feb 11 08:26:37.534749 2020] [http2:trace1] [pid 86:tid 139928507143936] h2_task.c(256): [client 172.21.0.1:36444] h2_slave_in(1-1): get more data from mplx, block=0, readbytes=4 >[Tue Feb 11 08:26:37.534752 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_task.c(269): [client 172.21.0.1:36444] h2_slave_in(1-1): read returned >[Tue Feb 11 08:26:37.534755 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_task.c(287): [client 172.21.0.1:36444] bb_dump(1): input.beam recv raw(BEAM[2] EOS) >[Tue Feb 11 08:26:37.534758 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_task.c(302): [client 172.21.0.1:36444] bb_dump(1): task_input.bb(BEAM[2] EOS) >[Tue Feb 11 08:26:37.534760 2020] [http2:trace1] [pid 86:tid 139928507143936] h2_task.c(354): [client 172.21.0.1:36444] h2_slave_in(1-1): 2 data bytes >[Tue Feb 11 08:26:37.534763 2020] [http2:trace1] [pid 86:tid 139928507143936] h2_task.c(226): [client 172.21.0.1:36444] h2_slave_in(1-1): read, mode=0, block=0, readbytes=2 >[Tue Feb 11 08:26:37.534766 2020] [http2:trace1] [pid 86:tid 139928507143936] h2_task.c(256): [client 172.21.0.1:36444] h2_slave_in(1-1): get more data from mplx, block=0, readbytes=2 >[Tue Feb 11 08:26:37.534768 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_task.c(269): (70014)End of file found: [client 172.21.0.1:36444] h2_slave_in(1-1): read returned >[Tue Feb 11 08:26:37.534771 2020] [proxy_http:error] [pid 86:tid 139928507143936] (70008)Partial results are valid but processing is incomplete: [client 172.21.0.1:36444] AH01095: prefetch request body failed to 172.21.0.3:8080 (as) from 172.21.0.1 (), referer: http://localhost:4200/trustee/list >[Tue Feb 11 08:26:37.534775 2020] [proxy:debug] [pid 86:tid 139928507143936] proxy_util.c(2340): AH00943: HTTP: has released connection for (as) >[Tue Feb 11 08:26:37.534790 2020] [http2:trace1] [pid 86:tid 139928507143936] h2_from_h1.c(533): [client 172.21.0.1:36444] h2_task(1-1): output_filter called >[Tue Feb 11 08:26:37.534799 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_task.c(61): [client 172.21.0.1:36444] bb_dump(1-1): h2_task send_out(H2HEADERS HEAP[308] EOS) >[Tue Feb 11 08:26:37.534802 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_bucket_beam.c(1275): [client 172.21.0.1:36444] beam(1-1,output,closed=0,aborted=0,empty=1,buf=0): send_out(before) >[Tue Feb 11 08:26:37.534805 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_bucket_beam.c(1275): [client 172.21.0.1:36444] beam(1-1,output,closed=1,aborted=0,empty=0,buf=308): send_out(after) >[Tue Feb 11 08:26:37.534808 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_task.c(114): [client 172.21.0.1:36444] h2_task(1-1): send_out done >[Tue Feb 11 08:26:37.534811 2020] [http2:debug] [pid 86:tid 139928507143936] h2_task.c(83): [client 172.21.0.1:36444] AH03348: h2_task(1-1): open output to POST gestemps.local:1443 /Gestemps-ws/front/trustee/search >[Tue Feb 11 08:26:37.534814 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_bucket_beam.c(1275): [client 172.21.0.1:36444] beam(1-1,output,closed=1,aborted=0,empty=0,buf=308): out_open >[Tue Feb 11 08:26:37.534819 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_task.c(191): [client 172.21.0.1:36444] h2_slave_out(1-1): slave_out leave >[Tue Feb 11 08:26:37.534823 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_task.c(61): [client 172.21.0.1:36444] bb_dump(1-1): h2_task send_out(EOR) >[Tue Feb 11 08:26:37.534826 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_bucket_beam.c(1275): [client 172.21.0.1:36444] beam(1-1,output,closed=1,aborted=0,empty=0,buf=308): send_out(before) >[Tue Feb 11 08:26:37.534829 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_bucket_beam.c(1275): [client 172.21.0.1:36444] beam(1-1,output,closed=1,aborted=0,empty=0,buf=308): send_out(after) >[Tue Feb 11 08:26:37.534834 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_task.c(114): [client 172.21.0.1:36444] h2_task(1-1): send_out done >[Tue Feb 11 08:26:37.534836 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_task.c(191): [client 172.21.0.1:36444] h2_slave_out(1-1): slave_out leave >[Tue Feb 11 08:26:37.534840 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_task.c(61): [client 172.21.0.1:36444] bb_dump(1-1): h2_task send_out(FLUSH) >[Tue Feb 11 08:26:37.534842 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_bucket_beam.c(1275): [client 172.21.0.1:36444] beam(1-1,output,closed=1,aborted=0,empty=0,buf=308): send_out(before) >[Tue Feb 11 08:26:37.534845 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_bucket_beam.c(1275): [client 172.21.0.1:36444] beam(1-1,output,closed=1,aborted=0,empty=0,buf=308): send_out(after) >[Tue Feb 11 08:26:37.534847 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_task.c(114): [client 172.21.0.1:36444] h2_task(1-1): send_out done >[Tue Feb 11 08:26:37.534846 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(609): [client 172.21.0.1:36444] h2_mplx(1): trywait on data for 0.160000 ms) >[Tue Feb 11 08:26:37.534850 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_task.c(191): [client 172.21.0.1:36444] h2_slave_out(1-1): slave_out leave >[Tue Feb 11 08:26:37.534853 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_session.c(1694): [client 172.21.0.1:36444] AH03078: h2_session(1,BUSY,1): transit [WAIT] -- stream change --> [BUSY] >[Tue Feb 11 08:26:37.534853 2020] [http2:trace1] [pid 86:tid 139928507143936] h2_task.c(663): [client 172.21.0.1:36444] h2_task(1-1): process_request done >[Tue Feb 11 08:26:37.534856 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(143): [client 172.21.0.1:36444] h2_session(1): read, NONBLOCK_READ, mode=0, readbytes=65536 >[Tue Feb 11 08:26:37.534857 2020] [http2:trace1] [pid 86:tid 139928507143936] h2_task.c(623): [client 172.21.0.1:36444] h2_task(1-1): processing done >[Tue Feb 11 08:26:37.534860 2020] [http2:trace1] [pid 86:tid 139928507143936] h2_mplx.c(776): [client 172.21.0.1:36444] h2_mplx(1): task(1-1) done >[Tue Feb 11 08:26:37.534861 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2217): [client 172.21.0.1:36444] OpenSSL: I/O error, 5 bytes expected to read on BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.534863 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_mplx.c(581): [client 172.21.0.1:36444] h2_mplx(1-1): close >[Tue Feb 11 08:26:37.534865 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(190): (11)Resource temporarily unavailable: [client 172.21.0.1:36444] h2_session(1): read >[Tue Feb 11 08:26:37.534866 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_bucket_beam.c(1275): [client 172.21.0.1:36444] beam(1-1,output,closed=1,aborted=0,empty=0,buf=308): out_close >[Tue Feb 11 08:26:37.534868 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(1094): [client 172.21.0.1:36444] h2_mplx(1): dispatch events >[Tue Feb 11 08:26:37.534869 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_mplx.c(782): [client 172.21.0.1:36444] h2_mplx(1-1): request done, 0.875000 ms elapsed >[Tue Feb 11 08:26:37.534871 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_stream.c(1108): [client 172.21.0.1:36444] h2_stream(1-1): consumed 2 bytes, window now 65533/65535 >[Tue Feb 11 08:26:37.534872 2020] [http2:trace2] [pid 86:tid 139928507143936] h2_mplx.c(805): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): task_done, stream open >[Tue Feb 11 08:26:37.534875 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1511): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): on_resume >[Tue Feb 11 08:26:37.534878 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_stream.c(180): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): out-buffer(add_buffered_data()) >[Tue Feb 11 08:26:37.534881 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_stream.c(180): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): out-buffer(pre()) >[Tue Feb 11 08:26:37.534891 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_stream.c(180): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): out-buffer(post(H2HEADERS BEAM[308] EOS FLUSH)) >[Tue Feb 11 08:26:37.534895 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_stream.c(180): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): out-buffer(add_buffered_data(H2HEADERS BEAM[308] EOS FLUSH)) >[Tue Feb 11 08:26:37.534897 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_stream.c(846): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): prep, -> response 400 >[Tue Feb 11 08:26:37.534900 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_stream.c(962): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): prepare, no data >[Tue Feb 11 08:26:37.534902 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1517): (70014)End of file found: [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): prepared len=0, eos=0 >[Tue Feb 11 08:26:37.534905 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1354): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): on_headers >[Tue Feb 11 08:26:37.534907 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(1384): [client 172.21.0.1:36444] AH03073: h2_stream(1-1,HALF_CLOSED_REMOTE): submit response 400, REMOTE_WINDOW_SIZE=6291456 >[Tue Feb 11 08:26:37.534914 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_stream.c(180): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): out-buffer(add_buffered_data(BEAM[308] EOS FLUSH)) >[Tue Feb 11 08:26:37.534916 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_stream.c(956): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): prepare, len=308 eos=1 >[Tue Feb 11 08:26:37.534919 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1517): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): prepared len=308, eos=1 >[Tue Feb 11 08:26:37.534922 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(1539): [client 172.21.0.1:36444] AH02936: h2_stream(1-1,HALF_CLOSED_REMOTE): resumed >[Tue Feb 11 08:26:37.534928 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(589): [client 172.21.0.1:36444] AH03068: h2_session(1,BUSY,1): sent FRAME[HEADERS[length=76, hend=1, stream=1, eos=0]], frames=5/4 (r/s) >[Tue Feb 11 08:26:37.534931 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_stream.c(407): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): send frame 1, eos=0 >[Tue Feb 11 08:26:37.534935 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_stream.c(180): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): out-buffer(add_buffered_data(BEAM[308] EOS FLUSH)) >[Tue Feb 11 08:26:37.534938 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_stream.c(956): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): prepare, len=308 eos=1 >[Tue Feb 11 08:26:37.534940 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1113): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): prepared no_copy, len=308, eos=1 >[Tue Feb 11 08:26:37.534944 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_conn_io.c(126): [client 172.21.0.1:36444] h2_session(1)-out: heap[85] flush >[Tue Feb 11 08:26:37.534951 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: write 107/107 bytes to BIO#7f438c002be0 [mem: 7f438c00ac43] >[Tue Feb 11 08:26:37.534974 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(143): [client 172.21.0.1:36444] h2_session(1): read, NONBLOCK_READ, mode=0, readbytes=65536 >[Tue Feb 11 08:26:37.534980 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2217): [client 172.21.0.1:36444] OpenSSL: I/O error, 5 bytes expected to read on BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.534984 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(190): (11)Resource temporarily unavailable: [client 172.21.0.1:36444] h2_session(1): read >[Tue Feb 11 08:26:37.534987 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(1094): [client 172.21.0.1:36444] h2_mplx(1): dispatch events >[Tue Feb 11 08:26:37.534993 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1511): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): on_resume >[Tue Feb 11 08:26:37.534996 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_stream.c(180): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): out-buffer(add_buffered_data(BEAM[308] EOS FLUSH)) >[Tue Feb 11 08:26:37.534999 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_stream.c(956): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): prepare, len=308 eos=1 >[Tue Feb 11 08:26:37.535001 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1517): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): prepared len=308, eos=1 >[Tue Feb 11 08:26:37.535004 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(1539): [client 172.21.0.1:36444] AH02936: h2_stream(1-1,HALF_CLOSED_REMOTE): resumed >[Tue Feb 11 08:26:37.535007 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(515): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): send_data_cb for 308 bytes >[Tue Feb 11 08:26:37.535010 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_stream.c(987): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): read_to, len=308 eos=1 >[Tue Feb 11 08:26:37.535013 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(589): [client 172.21.0.1:36444] AH03068: h2_session(1,BUSY,1): sent FRAME[DATA[length=308, flags=1, stream=1, padlen=0]], frames=5/5 (r/s) >[Tue Feb 11 08:26:37.535016 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_stream.c(407): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): send frame 0, eos=1 >[Tue Feb 11 08:26:37.535018 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_stream.c(301): [client 172.21.0.1:36444] h2_stream(1-1,HALF_CLOSED_REMOTE): transit to [CLOSED] >[Tue Feb 11 08:26:37.535021 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1953): [client 172.21.0.1:36444] h2_stream(1-1,CLOSED): entered state >[Tue Feb 11 08:26:37.535026 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_conn_io.c(126): [client 172.21.0.1:36444] h2_session(1)-out: heap[317] flush h2eos flush >[Tue Feb 11 08:26:37.535040 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: write 339/339 bytes to BIO#7f438c002be0 [mem: 7f438c00ac43] >[Tue Feb 11 08:26:37.535063 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_stream.c(343): [client 172.21.0.1:36444] h2_stream(1-1,CLOSED): dispatch event 3 >[Tue Feb 11 08:26:37.535068 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_stream.c(301): [client 172.21.0.1:36444] h2_stream(1-1,CLOSED): transit to [CLEANUP] >[Tue Feb 11 08:26:37.535071 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1953): [client 172.21.0.1:36444] h2_stream(1-1,CLEANUP): entered state >[Tue Feb 11 08:26:37.535075 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(487): [client 172.21.0.1:36444] h2_stream(1-1,CLEANUP): cleanup >[Tue Feb 11 08:26:37.535081 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(143): [client 172.21.0.1:36444] h2_session(1): read, NONBLOCK_READ, mode=0, readbytes=65536 >[Tue Feb 11 08:26:37.535088 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2217): [client 172.21.0.1:36444] OpenSSL: I/O error, 5 bytes expected to read on BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.535093 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(190): (11)Resource temporarily unavailable: [client 172.21.0.1:36444] h2_session(1): read >[Tue Feb 11 08:26:37.535097 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_mplx.c(1094): [client 172.21.0.1:36444] h2_mplx(1): dispatch events >[Tue Feb 11 08:26:37.535100 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_bucket_beam.c(1275): [client 172.21.0.1:36444] beam(1-1,input,closed=1,aborted=1,empty=1,buf=0): stream_destroy >[Tue Feb 11 08:26:37.535104 2020] [http2:debug] [pid 86:tid 139928314013440] h2_bucket_beam.c(1275): [client 172.21.0.1:36444] beam(1-1,output,closed=1,aborted=1,empty=1,buf=0): AH03385: h2_task_destroy, reuse slave >[Tue Feb 11 08:26:37.535146 2020] [http2:trace3] [pid 86:tid 139928314013440] h2_stream.c(574): [client 172.21.0.1:36444] h2_stream(1-1,CLEANUP): destroy >[Tue Feb 11 08:26:37.535153 2020] [http2:trace2] [pid 86:tid 139928314013440] h2_session.c(1822): [client 172.21.0.1:36444] h2_session(1,BUSY,0): NO_IO event, 0 streams open >[Tue Feb 11 08:26:37.535156 2020] [http2:debug] [pid 86:tid 139928314013440] h2_session.c(1694): [client 172.21.0.1:36444] AH03078: h2_session(1,IDLE,0): transit [BUSY] -- no io (keepalive) --> [IDLE] >[Tue Feb 11 08:26:37.535159 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_session.c(1729): [client 172.21.0.1:36444] h2_session(1,IDLE,0): enter idle, keepalive = 5 sec >[Tue Feb 11 08:26:37.535162 2020] [http2:trace3] [pid 86:tid 139928314013440] h2_session.c(2167): [client 172.21.0.1:36444] h2_session(1,IDLE,0): nonblock read, 0 streams open >[Tue Feb 11 08:26:37.535164 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(143): [client 172.21.0.1:36444] h2_session(1): read, NONBLOCK_READ, mode=0, readbytes=65536 >[Tue Feb 11 08:26:37.535170 2020] [ssl:trace4] [pid 86:tid 139928314013440] ssl_engine_io.c(2217): [client 172.21.0.1:36444] OpenSSL: I/O error, 5 bytes expected to read on BIO#7f438c002ca0 [mem: 7f438c01bc83] >[Tue Feb 11 08:26:37.535173 2020] [http2:trace1] [pid 86:tid 139928314013440] h2_filter.c(190): (11)Resource temporarily unavailable: [client 172.21.0.1:36444] h2_session(1): read >[Tue Feb 11 08:26:37.535177 2020] [http2:trace3] [pid 86:tid 139928314013440] h2_session.c(2366): (11)Resource temporarily unavailable: [client 172.21.0.1:36444] h2_session(1,IDLE,0): process returns >[Tue Feb 11 08:26:42.537461 2020] [http2:trace1] [pid 86:tid 139928222562048] h2_session.c(2384): [client 172.21.0.1:36444] h2_session(1,IDLE,0): pre_close >[Tue Feb 11 08:26:42.537541 2020] [http2:debug] [pid 86:tid 139928222562048] h2_session.c(589): [client 172.21.0.1:36444] AH03068: h2_session(1,IDLE,0): sent FRAME[GOAWAY[error=0, reason='timeout', last_stream=1]], frames=5/6 (r/s) >[Tue Feb 11 08:26:42.537595 2020] [http2:trace2] [pid 86:tid 139928222562048] h2_conn_io.c(126): [client 172.21.0.1:36444] h2_session(3)-out: heap[24] flush >[Tue Feb 11 08:26:42.537649 2020] [ssl:trace4] [pid 86:tid 139928222562048] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: write 46/46 bytes to BIO#7f438c002be0 [mem: 7f438c00ac43] >[Tue Feb 11 08:26:42.537810 2020] [http2:debug] [pid 86:tid 139928222562048] h2_session.c(751): [client 172.21.0.1:36444] AH03069: h2_session(1,IDLE,0): sent GOAWAY, err=0, msg=timeout >[Tue Feb 11 08:26:42.537837 2020] [http2:debug] [pid 86:tid 139928222562048] h2_session.c(1694): [client 172.21.0.1:36444] AH03078: h2_session(1,DONE,0): transit [IDLE] -- local goaway --> [DONE] >[Tue Feb 11 08:26:42.537853 2020] [http2:trace1] [pid 86:tid 139928222562048] h2_session.c(762): [client 172.21.0.1:36444] h2_session(1,DONE,0): pool_cleanup >[Tue Feb 11 08:26:42.537866 2020] [http2:debug] [pid 86:tid 139928222562048] h2_session.c(1694): [client 172.21.0.1:36444] AH03078: h2_session(1,CLEANUP,0): transit [DONE] -- pre_close --> [CLEANUP] >[Tue Feb 11 08:26:42.537880 2020] [http2:trace2] [pid 86:tid 139928222562048] h2_mplx.c(420): [client 172.21.0.1:36444] h2_mplx(1): start release >[Tue Feb 11 08:26:42.537894 2020] [http2:trace1] [pid 86:tid 139928222562048] h2_mplx.c(437): [client 172.21.0.1:36444] h2_mplx(1): release, 0/0/0 streams (total/hold/purge), 0 active tasks >[Tue Feb 11 08:26:42.537908 2020] [http2:trace1] [pid 86:tid 139928222562048] h2_mplx.c(480): [client 172.21.0.1:36444] h2_mplx(1): released >[Tue Feb 11 08:26:42.538104 2020] [ssl:trace4] [pid 86:tid 139928222562048] ssl_engine_io.c(2207): [client 172.21.0.1:36444] OpenSSL: write 24/24 bytes to BIO#7f438c002be0 [mem: 7f438c00ac43] >[Tue Feb 11 08:26:42.538198 2020] [ssl:trace3] [pid 86:tid 139928222562048] ssl_engine_kernel.c(2200): [client 172.21.0.1:36444] OpenSSL: Write: SSL negotiation finished successfully >[Tue Feb 11 08:26:42.538249 2020] [ssl:debug] [pid 86:tid 139928222562048] ssl_engine_io.c(1102): [client 172.21.0.1:36444] AH02001: Connection closed to child 3 with standard shutdown (server gestemps.local:443)
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 64133
:
37003
| 37004 |
37005
|
37006
|
37007
|
37008
|
37009