[Wed Oct 28 13:23:29.915753 2020] [proxy:debug] [pid 6924:tid 452] proxy_util.c(1938): AH00925: initializing worker h2://localhost:5001/tunnel.TunnelMessaging shared [Wed Oct 28 13:23:29.915753 2020] [proxy:debug] [pid 6924:tid 452] proxy_util.c(1998): AH00927: initializing worker h2://localhost:5001/tunnel.TunnelMessaging local [Wed Oct 28 13:23:29.915753 2020] [proxy:debug] [pid 6924:tid 452] proxy_util.c(2032): AH00930: initialized pool in child 6924 for (localhost) min=0 max=159 smax=159 [Wed Oct 28 13:23:29.915753 2020] [proxy:debug] [pid 6924:tid 452] proxy_util.c(1938): AH00925: initializing worker proxy:reverse shared [Wed Oct 28 13:23:29.915753 2020] [proxy:debug] [pid 6924:tid 452] proxy_util.c(1998): AH00927: initializing worker proxy:reverse local [Wed Oct 28 13:23:29.915753 2020] [proxy:debug] [pid 6924:tid 452] proxy_util.c(2032): AH00930: initialized pool in child 6924 for (*) min=0 max=159 smax=159 [Wed Oct 28 13:23:29.915753 2020] [proxy:debug] [pid 6924:tid 452] proxy_util.c(1933): AH00924: worker h2://localhost:5001/tunnel.TunnelMessaging shared already initialized [Wed Oct 28 13:23:29.915753 2020] [proxy:debug] [pid 6924:tid 452] proxy_util.c(1990): AH00926: worker h2://localhost:5001/tunnel.TunnelMessaging local already initialized [Wed Oct 28 13:23:29.915753 2020] [mpm_winnt:notice] [pid 6924:tid 452] AH00354: Child: Starting 64 worker threads. [Wed Oct 28 13:23:42.596184 2020] [http2:trace1] [pid 6924:tid 2160] h2_switch.c(116): [client ::1:54795] proposing protocol 'h2' [Wed Oct 28 13:23:42.596184 2020] [http2:trace1] [pid 6924:tid 2160] h2_switch.c(149): [client ::1:54795] switching protocol to 'h2' [Wed Oct 28 13:23:42.618421 2020] [http2:trace1] [pid 6924:tid 2160] h2_h2.c(588): [client ::1:54795] h2_h2, process_conn [Wed Oct 28 13:23:42.618421 2020] [http2:trace1] [pid 6924:tid 2160] h2_h2.c(647): [client ::1:54795] process_conn [Wed Oct 28 13:23:42.618421 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(948): [client ::1:54795] AH03200: h2_session(62,INIT,0): created, max_streams=100, stream_mem=32768, workers_limit=6, workers_max=96, push_diary(type=0,N=256) [Wed Oct 28 13:23:42.618421 2020] [http2:trace1] [pid 6924:tid 2160] h2_h2.c(651): [client ::1:54795] conn_setup [Wed Oct 28 13:23:42.618421 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(1037): [client ::1:54795] AH03201: h2_session(62,INIT,0): start, INITIAL_WINDOW_SIZE=65535, MAX_CONCURRENT_STREAMS=100 [Wed Oct 28 13:23:42.618421 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(2130): [client ::1:54795] AH03079: h2_session(62,INIT,0): started on thomas-sls-ro:443 [Wed Oct 28 13:23:42.618421 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,0): transit [INIT] -- init --> [BUSY] [Wed Oct 28 13:23:42.618421 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.618421 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.618421 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.618421 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(589): [client ::1:54795] AH03068: h2_session(62,BUSY,0): sent FRAME[SETTINGS[length=6, stream=0]], frames=0/1 (r/s) [Wed Oct 28 13:23:42.618421 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(589): [client ::1:54795] AH03068: h2_session(62,BUSY,0): sent FRAME[WINDOW_UPDATE[stream=0, incr=2147418112]], frames=0/2 (r/s) [Wed Oct 28 13:23:42.618421 2020] [http2:trace2] [pid 6924:tid 2160] h2_conn_io.c(126): [client ::1:54795] h2_session(62)-out: heap[28] flush [Wed Oct 28 13:23:42.618421 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.618421 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.618421 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.618421 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,0): NO_IO event, 0 streams open [Wed Oct 28 13:23:42.618421 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,IDLE,0): transit [BUSY] -- no io (keepalive) --> [IDLE] [Wed Oct 28 13:23:42.618421 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1713): [client ::1:54795] h2_session(62,IDLE,0): enter idle, timeout = 60 sec [Wed Oct 28 13:23:42.618421 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1729): [client ::1:54795] h2_session(62,IDLE,0): enter idle, timeout = 60 sec [Wed Oct 28 13:23:42.618421 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, BLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.715243 2020] [http2:trace2] [pid 6924:tid 2160] h2_filter.c(94): [client ::1:54795] bb_dump(62): RAW_in(TRANSIENT[58]) [Wed Oct 28 13:23:42.715243 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(337): [client ::1:54795] AH03066: h2_session(62,IDLE,0): recv FRAME[SETTINGS[length=12, stream=0]], frames=0/2 (r/s) [Wed Oct 28 13:23:42.715243 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(418): [client ::1:54795] h2_session(62,IDLE,0): SETTINGS, len=12 [Wed Oct 28 13:23:42.715243 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(444): [client ::1:54795] h2_session(62,IDLE,0): session has 1 idle frames [Wed Oct 28 13:23:42.715243 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(337): [client ::1:54795] AH03066: h2_session(62,IDLE,0): recv FRAME[WINDOW_UPDATE[stream=0, incr=67043329]], frames=1/2 (r/s) [Wed Oct 28 13:23:42.715243 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(378): [client ::1:54795] h2_stream(62-0): WINDOW_UPDATE incr=67043329 [Wed Oct 28 13:23:42.715243 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,0): transit [IDLE] -- frame received --> [BUSY] [Wed Oct 28 13:23:42.715243 2020] [http2:trace2] [pid 6924:tid 2160] h2_filter.c(63): [client ::1:54795] h2_session(62,BUSY,0): fed 58 bytes to nghttp2, 58 read [Wed Oct 28 13:23:42.715243 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.715243 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.715243 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(589): [client ::1:54795] AH03068: h2_session(62,BUSY,0): sent FRAME[SETTINGS[ack=1, stream=0]], frames=2/3 (r/s) [Wed Oct 28 13:23:42.715243 2020] [http2:trace2] [pid 6924:tid 2160] h2_conn_io.c(126): [client ::1:54795] h2_session(62)-out: heap[9] flush [Wed Oct 28 13:23:42.715243 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.715243 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.715243 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.715243 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,0): NO_IO event, 0 streams open [Wed Oct 28 13:23:42.715243 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,IDLE,0): transit [BUSY] -- no io (keepalive) --> [IDLE] [Wed Oct 28 13:23:42.715243 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1713): [client ::1:54795] h2_session(62,IDLE,0): enter idle, timeout = 60 sec [Wed Oct 28 13:23:42.715243 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1729): [client ::1:54795] h2_session(62,IDLE,0): enter idle, timeout = 60 sec [Wed Oct 28 13:23:42.715243 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, BLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.725305 2020] [http2:trace2] [pid 6924:tid 2160] h2_filter.c(94): [client ::1:54795] bb_dump(62): RAW_in(TRANSIENT[9]) [Wed Oct 28 13:23:42.725305 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(337): [client ::1:54795] AH03066: h2_session(62,IDLE,0): recv FRAME[SETTINGS[ack=1, stream=0]], frames=2/3 (r/s) [Wed Oct 28 13:23:42.725305 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(418): [client ::1:54795] h2_session(62,IDLE,0): SETTINGS, len=0 [Wed Oct 28 13:23:42.725305 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(444): [client ::1:54795] h2_session(62,IDLE,0): session has 2 idle frames [Wed Oct 28 13:23:42.725305 2020] [http2:trace2] [pid 6924:tid 2160] h2_filter.c(63): [client ::1:54795] h2_session(62,IDLE,0): fed 9 bytes to nghttp2, 9 read [Wed Oct 28 13:23:42.725305 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.725305 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.725305 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, BLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.745364 2020] [http2:trace2] [pid 6924:tid 2160] h2_filter.c(94): [client ::1:54795] bb_dump(62): RAW_in(TRANSIENT[147]) [Wed Oct 28 13:23:42.745364 2020] [http2:debug] [pid 6924:tid 2160] h2_stream.c(551): [client ::1:54795] AH03082: h2_stream(62-1,IDLE): created [Wed Oct 28 13:23:42.745364 2020] [http2:trace2] [pid 6924:tid 2160] h2_stream.c(256): [client ::1:54795] h2_stream(62-1,IDLE): ========= on_state_enter [Wed Oct 28 13:23:42.745364 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1953): [client ::1:54795] h2_stream(62-1,IDLE): entered state [Wed Oct 28 13:23:42.745364 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [IDLE] -- stream change --> [BUSY] [Wed Oct 28 13:23:42.745364 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(337): [client ::1:54795] AH03066: h2_session(62,BUSY,1): recv FRAME[HEADERS[length=138, hend=1, stream=1, eos=0]], frames=3/3 (r/s) [Wed Oct 28 13:23:42.745364 2020] [http2:trace1] [pid 6924:tid 2160] h2_stream.c(310): [client ::1:54795] h2_stream(62-1,IDLE): transit to [OPEN] [Wed Oct 28 13:23:42.745364 2020] [http2:trace2] [pid 6924:tid 2160] h2_stream.c(256): [client ::1:54795] h2_stream(62-1,OPEN): ========= on_state_enter [Wed Oct 28 13:23:42.745364 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1953): [client ::1:54795] h2_stream(62-1,OPEN): entered state [Wed Oct 28 13:23:42.745364 2020] [http2:trace2] [pid 6924:tid 2160] h2_filter.c(63): [client ::1:54795] h2_session(62,BUSY,1): fed 147 bytes to nghttp2, 147 read [Wed Oct 28 13:23:42.745364 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.745364 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.745364 2020] [http2:trace1] [pid 6924:tid 2160] h2_stream.c(592): [client ::1:54795] h2_stream(62-1,OPEN): schedule POST https://localhost/tunnel.TunnelMessaging/SendData chunked=1 [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_mplx.c(704): [client ::1:54795] h2_stream(62-1,OPEN): process, added to q [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 0.010000 ms) [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 1464] h2_task.c(494): [client ::1:54795] h2_secondary(62-1), pre_connection, adding filters [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 1464] h2_task.c(618): [client ::1:54795] h2_task(62-1): process connection [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 1464] h2_task.c(702): [client ::1:54795] h2_h2, processing request directly [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 1464] h2_task.c(635): [client ::1:54795] h2_task(62-1): create request_rec [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 0.020000 ms) [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 1464] h2_task.c(653): [client ::1:54795] h2_task(62-1): start process_request [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 0.040000 ms) [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.746166 2020] [proxy_http2:trace1] [pid 6924:tid 1464] mod_proxy_http2.c(130): [client ::1:54795] HTTP2: canonicalising URL //localhost:5001/tunnel.TunnelMessaging/SendData [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 0.080000 ms) [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 0.160000 ms) [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:42.746166 2020] [proxy:debug] [pid 6924:tid 1464] mod_proxy.c(1251): [client ::1:54795] AH01143: Running scheme h2 handler (attempt 0) [Wed Oct 28 13:23:42.746166 2020] [proxy_http2:trace1] [pid 6924:tid 1464] mod_proxy_http2.c(349): [client ::1:54795] H2: serving URL h2://localhost:5001/tunnel.TunnelMessaging/SendData [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 0.320000 ms) [Wed Oct 28 13:23:42.746166 2020] [proxy:debug] [pid 6924:tid 1464] proxy_util.c(2336): AH00942: H2: has acquired connection for (localhost) [Wed Oct 28 13:23:42.746166 2020] [proxy:debug] [pid 6924:tid 1464] proxy_util.c(2392): [client ::1:54795] AH00944: connecting h2://localhost:5001/tunnel.TunnelMessaging/SendData to localhost:5001 [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 0.640000 ms) [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.746166 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:42.746166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.748192 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 1.280000 ms) [Wed Oct 28 13:23:42.748192 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:42.748192 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.748192 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.748192 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.748192 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:42.748192 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.750177 2020] [proxy:debug] [pid 6924:tid 1464] proxy_util.c(2615): [client ::1:54795] AH00947: connected /tunnel.TunnelMessaging/SendData to localhost:5001 [Wed Oct 28 13:23:42.750177 2020] [proxy:debug] [pid 6924:tid 1464] proxy_util.c(3081): AH02824: H2: connection established with [::1]:5001 (localhost) [Wed Oct 28 13:23:42.750177 2020] [proxy:debug] [pid 6924:tid 1464] proxy_util.c(3267): AH00962: H2: connection complete to [::1]:5001 (localhost) [Wed Oct 28 13:23:42.750177 2020] [proxy_http2:trace1] [pid 6924:tid 1464] mod_proxy_http2.c(405): [client ::1:54795] set SNI to localhost for (localhost) [Wed Oct 28 13:23:42.750177 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(770): [remote ::1:5001] AH03362: setup session for localhost [Wed Oct 28 13:23:42.750177 2020] [proxy_http2:debug] [pid 6924:tid 1464] mod_proxy_http2.c(245): [client ::1:54795] AH03373: eng(62-1): run session 62-1 [Wed Oct 28 13:23:42.750177 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(1062): [client ::1:54795] AH03381: process stream(0): POST localhost:5001/tunnel.TunnelMessaging/SendData, original: POST /tunnel.TunnelMessaging/SendData HTTP/2.0 [Wed Oct 28 13:23:42.750177 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(931): [remote ::1:5001] AH03363: h2_proxy_session(62-1): submit localhost:5001/tunnel.TunnelMessaging/SendData -> 1 [Wed Oct 28 13:23:42.750177 2020] [proxy_http2:trace2] [pid 6924:tid 1464] h2_proxy_session.c(1510): [remote ::1:5001] h2_proxy_session(62-1): process [Wed Oct 28 13:23:42.750177 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(1185): [remote ::1:5001] AH03345: h2_proxy_session(62-1): transit [INIT] -- init --> [BUSY] [Wed Oct 28 13:23:42.751320 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 2.560000 ms) [Wed Oct 28 13:23:42.751320 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:42.751320 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.751320 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.751320 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.751320 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:42.751320 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.756649 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 5.120000 ms) [Wed Oct 28 13:23:42.756649 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:42.756649 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.756649 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.756649 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.756649 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:42.756649 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.767300 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 10.240000 ms) [Wed Oct 28 13:23:42.767300 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:42.767300 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.767300 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.767300 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.767300 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:42.767300 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.788505 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 20.480000 ms) [Wed Oct 28 13:23:42.788505 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:42.788505 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.788505 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.788505 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.788505 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:42.788505 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.828833 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 40.960000 ms) [Wed Oct 28 13:23:42.828833 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:42.828833 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.828833 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.828833 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.828833 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:42.828833 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.871046 2020] [proxy_http2:trace1] [pid 6924:tid 1464] h2_proxy_session.c(246): [remote ::1:5001] h2_proxy_sesssion(62-1): raw_send 24 bytes, flush=1 [Wed Oct 28 13:23:42.871046 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(345): [remote ::1:5001] AH03343: h2_proxy_session(62-1): sent FRAME[SETTINGS[length=12, stream=0]] [Wed Oct 28 13:23:42.871046 2020] [proxy_http2:trace1] [pid 6924:tid 1464] h2_proxy_session.c(246): [remote ::1:5001] h2_proxy_sesssion(62-1): raw_send 21 bytes, flush=1 [Wed Oct 28 13:23:42.871046 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(345): [remote ::1:5001] AH03343: h2_proxy_session(62-1): sent FRAME[WINDOW_UPDATE[stream=0, incr=1073676288]] [Wed Oct 28 13:23:42.871046 2020] [proxy_http2:trace1] [pid 6924:tid 1464] h2_proxy_session.c(246): [remote ::1:5001] h2_proxy_sesssion(62-1): raw_send 13 bytes, flush=1 [Wed Oct 28 13:23:42.871046 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(345): [remote ::1:5001] AH03343: h2_proxy_session(62-1): sent FRAME[HEADERS[length=178, hend=1, stream=1, eos=0]] [Wed Oct 28 13:23:42.871046 2020] [proxy_http2:trace1] [pid 6924:tid 1464] h2_proxy_session.c(246): [remote ::1:5001] h2_proxy_sesssion(62-1): raw_send 187 bytes, flush=1 [Wed Oct 28 13:23:42.871046 2020] [proxy_http2:trace2] [pid 6924:tid 1464] h2_proxy_session.c(681): (11)Resource temporarily unavailable: [client ::1:54795] h2_proxy_stream(62-1-1): suspending [Wed Oct 28 13:23:42.871046 2020] [proxy_http2:trace2] [pid 6924:tid 1464] h2_proxy_session.c(1510): [remote ::1:5001] h2_proxy_session(62-1): process [Wed Oct 28 13:23:42.871046 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(1185): [remote ::1:5001] AH03345: h2_proxy_session(62-1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.892218 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(267): [remote ::1:5001] AH03341: h2_proxy_session(62-1): recv FRAME[SETTINGS[length=18, stream=0]] [Wed Oct 28 13:23:42.892218 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(1682): [remote ::1:5001] AH03470: h2_proxy_session(62-1): ping arrived, unblocking streams [Wed Oct 28 13:23:42.892218 2020] [proxy_http2:trace2] [pid 6924:tid 1464] h2_proxy_session.c(1075): [remote ::1:5001] h2_proxy_stream(62-1-1): resuming [Wed Oct 28 13:23:42.892218 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(1185): [remote ::1:5001] AH03345: h2_proxy_session(62-1): transit [WAIT] -- stream resumed --> [BUSY] [Wed Oct 28 13:23:42.892218 2020] [proxy_http2:trace1] [pid 6924:tid 1464] h2_proxy_session.c(975): [remote ::1:5001] h2_proxy_session(62-1): feeding 27 bytes -> 27 [Wed Oct 28 13:23:42.892218 2020] [proxy_http2:trace1] [pid 6924:tid 1464] h2_proxy_session.c(994): [remote ::1:5001] h2_proxy_session(62-1): fed 27 bytes of input to session [Wed Oct 28 13:23:42.892218 2020] [proxy_http2:trace2] [pid 6924:tid 1464] h2_proxy_session.c(1510): [remote ::1:5001] h2_proxy_session(62-1): process [Wed Oct 28 13:23:42.892218 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(345): [remote ::1:5001] AH03343: h2_proxy_session(62-1): sent FRAME[SETTINGS[ack=1, stream=0]] [Wed Oct 28 13:23:42.892218 2020] [proxy_http2:trace1] [pid 6924:tid 1464] h2_proxy_session.c(246): [remote ::1:5001] h2_proxy_sesssion(62-1): raw_send 9 bytes, flush=1 [Wed Oct 28 13:23:42.892218 2020] [http2:trace2] [pid 6924:tid 1464] h2_from_h1.c(806): [client ::1:54795] h2_task(62-1): request filter, exp=0 [Wed Oct 28 13:23:42.892218 2020] [http2:trace1] [pid 6924:tid 1464] h2_task.c(226): [client ::1:54795] h2_secondary_in(62-1): read, mode=0, block=1, readbytes=32768 [Wed Oct 28 13:23:42.892218 2020] [http2:trace1] [pid 6924:tid 1464] h2_task.c(256): [client ::1:54795] h2_secondary_in(62-1): get more data from mplx, block=1, readbytes=32768 [Wed Oct 28 13:23:42.892218 2020] [http2:trace2] [pid 6924:tid 1464] h2_task.c(269): (11)Resource temporarily unavailable: [client ::1:54795] h2_secondary_in(62-1): read returned [Wed Oct 28 13:23:42.892218 2020] [proxy_http2:trace2] [pid 6924:tid 1464] h2_proxy_session.c(628): (11)Resource temporarily unavailable: [client ::1:54795] h2_proxy_stream(62-1-1): request body read [Wed Oct 28 13:23:42.892218 2020] [proxy_http2:trace2] [pid 6924:tid 1464] h2_proxy_session.c(681): (11)Resource temporarily unavailable: [client ::1:54795] h2_proxy_stream(62-1-1): suspending [Wed Oct 28 13:23:42.892218 2020] [proxy_http2:trace2] [pid 6924:tid 1464] h2_proxy_session.c(1510): [remote ::1:5001] h2_proxy_session(62-1): process [Wed Oct 28 13:23:42.892911 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(1185): [remote ::1:5001] AH03345: h2_proxy_session(62-1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.892911 2020] [http2:trace2] [pid 6924:tid 1464] h2_from_h1.c(806): [client ::1:54795] h2_task(62-1): request filter, exp=0 [Wed Oct 28 13:23:42.892911 2020] [http2:trace1] [pid 6924:tid 1464] h2_task.c(226): [client ::1:54795] h2_secondary_in(62-1): read, mode=0, block=1, readbytes=32768 [Wed Oct 28 13:23:42.892911 2020] [http2:trace1] [pid 6924:tid 1464] h2_task.c(256): [client ::1:54795] h2_secondary_in(62-1): get more data from mplx, block=1, readbytes=32768 [Wed Oct 28 13:23:42.892911 2020] [http2:trace2] [pid 6924:tid 1464] h2_task.c(269): (11)Resource temporarily unavailable: [client ::1:54795] h2_secondary_in(62-1): read returned [Wed Oct 28 13:23:42.892911 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(267): [remote ::1:5001] AH03341: h2_proxy_session(62-1): recv FRAME[WINDOW_UPDATE[stream=0, incr=65537]] [Wed Oct 28 13:23:42.892911 2020] [proxy_http2:trace1] [pid 6924:tid 1464] h2_proxy_session.c(975): [remote ::1:5001] h2_proxy_session(62-1): feeding 13 bytes -> 13 [Wed Oct 28 13:23:42.892911 2020] [proxy_http2:trace1] [pid 6924:tid 1464] h2_proxy_session.c(994): [remote ::1:5001] h2_proxy_session(62-1): fed 13 bytes of input to session [Wed Oct 28 13:23:42.892911 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(1185): [remote ::1:5001] AH03345: h2_proxy_session(62-1): transit [WAIT] -- data read --> [BUSY] [Wed Oct 28 13:23:42.892911 2020] [proxy_http2:trace2] [pid 6924:tid 1464] h2_proxy_session.c(1510): [remote ::1:5001] h2_proxy_session(62-1): process [Wed Oct 28 13:23:42.892911 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(1185): [remote ::1:5001] AH03345: h2_proxy_session(62-1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.892911 2020] [http2:trace2] [pid 6924:tid 1464] h2_from_h1.c(806): [client ::1:54795] h2_task(62-1): request filter, exp=0 [Wed Oct 28 13:23:42.892911 2020] [http2:trace1] [pid 6924:tid 1464] h2_task.c(226): [client ::1:54795] h2_secondary_in(62-1): read, mode=0, block=1, readbytes=32768 [Wed Oct 28 13:23:42.892911 2020] [http2:trace1] [pid 6924:tid 1464] h2_task.c(256): [client ::1:54795] h2_secondary_in(62-1): get more data from mplx, block=1, readbytes=32768 [Wed Oct 28 13:23:42.892911 2020] [http2:trace2] [pid 6924:tid 1464] h2_task.c(269): (11)Resource temporarily unavailable: [client ::1:54795] h2_secondary_in(62-1): read returned [Wed Oct 28 13:23:42.893829 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(267): [remote ::1:5001] AH03341: h2_proxy_session(62-1): recv FRAME[SETTINGS[ack=1, stream=0]] [Wed Oct 28 13:23:42.893829 2020] [proxy_http2:trace1] [pid 6924:tid 1464] h2_proxy_session.c(975): [remote ::1:5001] h2_proxy_session(62-1): feeding 9 bytes -> 9 [Wed Oct 28 13:23:42.893829 2020] [proxy_http2:trace1] [pid 6924:tid 1464] h2_proxy_session.c(994): [remote ::1:5001] h2_proxy_session(62-1): fed 9 bytes of input to session [Wed Oct 28 13:23:42.893829 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(1185): [remote ::1:5001] AH03345: h2_proxy_session(62-1): transit [WAIT] -- data read --> [BUSY] [Wed Oct 28 13:23:42.893829 2020] [proxy_http2:trace2] [pid 6924:tid 1464] h2_proxy_session.c(1510): [remote ::1:5001] h2_proxy_session(62-1): process [Wed Oct 28 13:23:42.893829 2020] [proxy_http2:debug] [pid 6924:tid 1464] h2_proxy_session.c(1185): [remote ::1:5001] AH03345: h2_proxy_session(62-1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:42.893829 2020] [http2:trace2] [pid 6924:tid 1464] h2_from_h1.c(806): [client ::1:54795] h2_task(62-1): request filter, exp=0 [Wed Oct 28 13:23:42.893829 2020] [http2:trace1] [pid 6924:tid 1464] h2_task.c(226): [client ::1:54795] h2_secondary_in(62-1): read, mode=0, block=1, readbytes=32768 [Wed Oct 28 13:23:42.893829 2020] [http2:trace1] [pid 6924:tid 1464] h2_task.c(256): [client ::1:54795] h2_secondary_in(62-1): get more data from mplx, block=1, readbytes=32768 [Wed Oct 28 13:23:42.893829 2020] [http2:trace2] [pid 6924:tid 1464] h2_task.c(269): (11)Resource temporarily unavailable: [client ::1:54795] h2_secondary_in(62-1): read returned [Wed Oct 28 13:23:42.910459 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 81.920000 ms) [Wed Oct 28 13:23:42.910459 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:42.910459 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:42.910459 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:42.910459 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:42.910459 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:42.910459 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:43.074285 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 163.840000 ms) [Wed Oct 28 13:23:43.074285 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:43.074285 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:43.074285 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:43.074285 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:43.074285 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:43.074285 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:43.274456 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:43.274456 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:43.274456 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:43.274456 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:43.274456 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:43.274456 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:43.274456 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:43.474578 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:43.474578 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:43.474578 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:43.474578 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:43.474578 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:43.474578 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:43.474578 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:43.675254 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:43.675254 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:43.675254 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:43.675254 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:43.675254 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:43.675254 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:43.675254 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:43.876583 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:43.876583 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:43.876583 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:43.876583 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:43.876583 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:43.876583 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:43.876583 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:44.077258 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:44.077258 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:44.077258 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:44.077258 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:44.077258 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:44.077258 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:44.077258 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:44.277751 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:44.277751 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:44.277751 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:44.277751 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:44.277751 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:44.277751 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:44.277751 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:44.478299 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:44.478299 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:44.478299 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:44.478299 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:44.478299 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:44.478299 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:44.478299 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:44.679360 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:44.679360 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:44.682406 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:44.682406 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:44.682406 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:44.682406 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:44.682406 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:44.882435 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:44.882435 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:44.882435 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:44.882435 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:44.882435 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:44.882435 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:44.882435 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:45.082861 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:45.082861 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:45.082861 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:45.082861 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:45.082861 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:45.082861 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:45.082861 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:45.283834 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:45.283834 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:45.283834 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:45.283834 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:45.283834 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:45.283834 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:45.283834 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:45.484117 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:45.484117 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:45.484117 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:45.484117 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:45.484117 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:45.484117 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:45.484117 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:45.684184 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:45.684184 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:45.684184 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:45.684184 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:45.684184 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:45.684184 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:45.684184 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:45.885003 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:45.885003 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:45.885003 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:45.885003 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:45.885003 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:45.885003 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:45.885003 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:46.085403 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:46.085403 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:46.085403 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:46.085403 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:46.085403 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:46.085403 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:46.085403 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:46.286691 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:46.286691 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:46.286691 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:46.286691 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:46.286691 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:46.286691 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:46.286691 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:46.487820 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:46.487820 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:46.487820 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:46.487820 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:46.487820 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:46.487820 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:46.487820 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:46.688875 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:46.688875 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:46.688875 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:46.688875 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:46.688875 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:46.688875 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:46.688875 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:46.889816 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:46.889816 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:46.889816 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:46.889816 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:46.889816 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:46.889816 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:46.889816 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:47.090010 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:47.090010 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:47.090010 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:47.090010 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:47.090010 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:47.090010 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:47.090010 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:47.290373 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:47.290373 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:47.290373 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:47.290373 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:47.290373 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:47.290373 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:47.290373 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:47.491183 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:47.491183 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:47.491183 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:47.491183 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:47.491183 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:47.491183 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:47.491183 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:47.691570 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:47.691570 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:47.691570 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:47.691570 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:47.691570 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:47.691570 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:47.691570 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:47.891992 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:47.891992 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:47.891992 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:47.891992 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:47.891992 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:47.891992 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:47.891992 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:48.092166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:48.092166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:48.092166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:48.092166 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:48.092166 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:48.092166 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:48.092166 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:48.292581 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:48.292581 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:48.292581 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:48.292581 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:48.292581 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:48.292581 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:48.292581 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:48.492762 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:48.492762 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:48.492762 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:48.492762 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:48.492762 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:48.492762 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:48.492762 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:48.692870 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:48.692870 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:48.692870 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:48.692870 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:48.692870 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:48.692870 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:48.692870 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:48.893964 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:48.893964 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:48.893964 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:48.893964 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:48.893964 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:48.893964 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:48.893964 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:49.094139 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:49.094139 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:49.094139 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:49.094139 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:49.094139 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:49.094139 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:49.094139 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:49.294364 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:49.294364 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:49.294364 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:49.294364 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:49.294364 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:49.294364 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:49.294364 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:49.494893 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:49.494893 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:49.494893 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:49.494893 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:49.494893 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:49.494893 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:49.494893 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:49.694931 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:49.694931 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:49.694931 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:49.694931 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:49.694931 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:49.694931 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:49.694931 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:49.895388 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:49.895388 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:49.895388 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:49.895388 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:49.895388 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:49.895388 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:49.895388 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:50.095599 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:50.095599 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:50.095599 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:50.095599 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:50.095599 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:50.100506 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:50.100506 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:50.301085 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:50.301085 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:50.301085 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:50.301085 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:50.301085 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:50.301085 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:50.301085 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:50.501898 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:50.501898 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:50.501898 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:50.501898 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:50.501898 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:50.501898 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:50.501898 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:50.702788 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:50.702788 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:50.702788 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:50.702788 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:50.702788 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:50.702788 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:50.702788 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:50.903667 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:50.903667 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:50.903667 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:50.903667 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:50.903667 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:50.903667 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:50.903667 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:51.104201 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:51.104201 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:51.104201 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:51.104201 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:51.104201 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:51.104201 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:51.104201 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:51.304928 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:51.304928 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:51.304928 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:51.304928 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:51.304928 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:51.304928 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:51.304928 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:51.505040 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:51.505040 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:51.505040 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:51.505040 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:51.505040 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:51.505040 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:51.505040 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:51.706293 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:51.706293 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:51.706293 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:51.706293 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:51.706293 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:51.706293 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:51.706293 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:51.906486 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:51.906486 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:51.907143 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:51.907143 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:51.907143 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:51.907143 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:51.907143 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.107465 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:52.107465 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.107465 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.107465 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.107465 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.107465 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.107465 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.308041 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:52.308041 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.308041 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.308041 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.308041 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.308041 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.308041 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.508194 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:52.508194 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.508194 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.508194 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.508194 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.508194 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.508194 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_filter.c(94): [client ::1:54795] bb_dump(62): RAW_in(TRANSIENT[36]) [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_stream.c(510): [client ::1:54795] h2_stream(62-1,OPEN): recv DATA, len=27 [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_stream.c(352): [client ::1:54795] h2_stream(62-1,OPEN): dispatch event 4 [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_stream.c(364): [client ::1:54795] h2_stream(62-1,OPEN): non-state event 4 [Wed Oct 28 13:23:52.708907 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(337): [client ::1:54795] AH03066: h2_session(62,BUSY,1): recv FRAME[DATA[length=27, flags=0, stream=1, padlen=0]], frames=4/3 (r/s) [Wed Oct 28 13:23:52.708907 2020] [http2:debug] [pid 6924:tid 2160] h2_session.c(359): [client ::1:54795] AH02923: h2_stream(62-1,OPEN): DATA, len=27, flags=0 [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_filter.c(63): [client ::1:54795] h2_session(62,BUSY,1): fed 36 bytes to nghttp2, 36 read [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 0.010000 ms) [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 0.020000 ms) [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 0.040000 ms) [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 0.080000 ms) [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 0.160000 ms) [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 0.320000 ms) [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 0.640000 ms) [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.708907 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.708907 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.711193 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 1.280000 ms) [Wed Oct 28 13:23:52.711193 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.711193 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.711193 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.711193 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.711193 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.711193 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.714072 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 2.560000 ms) [Wed Oct 28 13:23:52.714072 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.714072 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.714072 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.714072 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.714072 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.714072 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.720205 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 5.120000 ms) [Wed Oct 28 13:23:52.720205 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.720205 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.720205 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.720205 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.720205 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.720205 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.730381 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 10.240000 ms) [Wed Oct 28 13:23:52.730381 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.730381 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.730381 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.730381 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.730381 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.730381 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.751222 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 20.480000 ms) [Wed Oct 28 13:23:52.751222 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.751222 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.751222 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.751222 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.751222 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.751222 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.792067 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 40.960000 ms) [Wed Oct 28 13:23:52.792067 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.792067 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.792067 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.792067 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.792067 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.792067 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:52.873285 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 81.920000 ms) [Wed Oct 28 13:23:52.873285 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:52.873285 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:52.873285 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:52.873285 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:52.873285 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:52.873285 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:53.038769 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 163.840000 ms) [Wed Oct 28 13:23:53.038769 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:53.038769 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:53.038769 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:53.038769 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:53.038769 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:53.038769 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:53.238941 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:53.238941 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:53.238941 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:53.238941 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:53.238941 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:53.238941 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:53.238941 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:53.439003 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:53.439003 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:53.439003 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:53.439003 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:53.439003 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:53.439003 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:53.439003 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:53.677416 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:53.677416 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:53.677416 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:53.678476 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:53.678476 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:53.678476 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:53.678476 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:53.878574 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:53.878574 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:53.878574 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:53.878574 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:53.878574 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:53.878574 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:53.878574 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:54.079132 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:54.079132 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:54.079132 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:54.079132 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:54.079132 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:54.079132 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:54.079132 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:54.280098 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:54.280098 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:54.280098 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:54.280098 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:54.280098 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:54.280394 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:54.280394 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:54.480642 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:54.480642 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:54.480642 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:54.480642 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:54.480642 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:54.480642 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:54.480642 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:54.681274 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:54.681274 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:54.681274 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:54.681274 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:54.681274 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:54.681274 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:54.681274 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:54.881587 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:54.881587 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:54.881587 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:54.881587 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:54.881587 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:54.881587 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:54.881587 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT] [Wed Oct 28 13:23:55.082956 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(617): [client ::1:54795] h2_mplx(62): trywait on data for 200.000000 ms) [Wed Oct 28 13:23:55.082956 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,BUSY,1): transit [WAIT] -- wait cycle --> [BUSY] [Wed Oct 28 13:23:55.082956 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(143): [client ::1:54795] h2_session(62): read, NONBLOCK_READ, mode=0, readbytes=65536 [Wed Oct 28 13:23:55.082956 2020] [http2:trace1] [pid 6924:tid 2160] h2_filter.c(190): (11)Resource temporarily unavailable: [client ::1:54795] h2_session(62): read [Wed Oct 28 13:23:55.082956 2020] [http2:trace2] [pid 6924:tid 2160] h2_mplx.c(1109): [client ::1:54795] h2_mplx(62): dispatch events [Wed Oct 28 13:23:55.082956 2020] [http2:trace2] [pid 6924:tid 2160] h2_session.c(1822): [client ::1:54795] h2_session(62,BUSY,1): NO_IO event, 1 streams open [Wed Oct 28 13:23:55.082956 2020] [http2:trace1] [pid 6924:tid 2160] h2_session.c(1694): [client ::1:54795] AH03078: h2_session(62,WAIT,1): transit [BUSY] -- no io --> [WAIT]