masaori335 edited a comment on issue #7915: URL: https://github.com/apache/trafficserver/issues/7915#issuecomment-853600232
It looks like `http2/4.2/1` hangs intermittently. Debug logs with `http2|ssl` tag is below. <details><summary>Debug logs of good case</summary> ``` [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <Http2ClientSession.cc:388 (state_read_connection_preface)> (http2_cs) [3] [&Http2ClientSession::state_read_connection_preface, VC_EVENT_READ_READY/TS_EVENT_VCONN_READ_READY] [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <Http2ClientSession.cc:409 (state_read_connection_preface)> (http2_cs) [3] received connection preface [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <Http2ClientSession.cc:438 (state_start_frame_read)> (http2_cs) [3] [&Http2ClientSession::state_start_frame_read, VC_EVENT_READ_READY/TS_EVENT_VCONN_READ_READY] [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <Http2ClientSession.cc:452 (do_start_frame_read)> (http2_cs) [3] receiving frame header [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <Http2ClientSession.cc:469 (do_start_frame_read)> (http2_cs) [3] frame header length=6, type=4, flags=0x0, streamid=0 [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <Http2ConnectionState.cc:566 (rcv_settings_frame)> (http2_con) [3] [0] Received SETTINGS frame [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <Http2ConnectionState.cc:638 (rcv_settings_frame)> (http2_con) [3] [0] INITIAL_WINDOW_SIZE : 65535 [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4057 [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=0 [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:308 (ssl_read_from_net)> (ssl.error) SSL_ERROR_WOULD_BLOCK(read) [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:719 (net_read_io)> (ssl) read finished - would block [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:780 (load_buffer_and_write)> (ssl) towrite=30 [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:836 (load_buffer_and_write)> (ssl) try_to_write=30 written=30 total_written=30 [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4057 [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=9 [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4048 [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=25 [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4023 [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=0 [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:308 (ssl_read_from_net)> (ssl.error) SSL_ERROR_WOULD_BLOCK(read) [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:349 (ssl_read_from_net)> (ssl) bytes_read=34 [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <Http2ClientSession.cc:438 (state_start_frame_read)> (http2_cs) [3] [&Http2ClientSession::state_start_frame_read, VC_EVENT_READ_READY/TS_EVENT_VCONN_READ_READY] [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <Http2ClientSession.cc:452 (do_start_frame_read)> (http2_cs) [3] receiving frame header [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <Http2ClientSession.cc:469 (do_start_frame_read)> (http2_cs) [3] frame header length=0, type=4, flags=0x1, streamid=0 [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <Http2ConnectionState.cc:566 (rcv_settings_frame)> (http2_con) [3] [0] Received SETTINGS frame [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <Http2ClientSession.cc:452 (do_start_frame_read)> (http2_cs) [3] receiving frame header [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <Http2ClientSession.cc:469 (do_start_frame_read)> (http2_cs) [3] frame header length=16, type=1, flags=0x4, streamid=1 [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <Http2ConnectionState.cc:233 (rcv_headers_frame)> (http2_con) [3] [1] Received HEADERS frame [Jun 3 06:20:45.062] [ET_NET 1] DEBUG: <Http2Stream.cc:370 (change_state)> (http2_stream) [3] [1] Http2StreamState::HTTP2_STREAM_STATE_OPEN [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4023 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=4023 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4096 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=1872 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=2224 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=2224 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:349 (ssl_read_from_net)> (ssl) bytes_read=8119 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <Http2ClientSession.cc:438 (state_start_frame_read)> (http2_cs) [3] [&Http2ClientSession::state_start_frame_read, VC_EVENT_READ_READY/TS_EVENT_VCONN_READ_READY] [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <Http2ClientSession.cc:452 (do_start_frame_read)> (http2_cs) [3] receiving frame header [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <Http2ClientSession.cc:469 (do_start_frame_read)> (http2_cs) [3] frame header length=16384, type=0, flags=0x1, streamid=1 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4096 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=4096 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:349 (ssl_read_from_net)> (ssl) bytes_read=4096 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <Http2ClientSession.cc:499 (state_complete_frame_read)> (http2_cs) [3] [&Http2ClientSession::state_complete_frame_read, VC_EVENT_READ_READY/TS_EVENT_VCONN_READ_READY] [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4096 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=754 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=3342 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=3342 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:349 (ssl_read_from_net)> (ssl) bytes_read=4096 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <Http2ClientSession.cc:499 (state_complete_frame_read)> (http2_cs) [3] [&Http2ClientSession::state_complete_frame_read, VC_EVENT_READ_READY/TS_EVENT_VCONN_READ_READY] [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4096 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=82 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4014 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=0 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:308 (ssl_read_from_net)> (ssl.error) SSL_ERROR_WOULD_BLOCK(read) [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:349 (ssl_read_from_net)> (ssl) bytes_read=82 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <Http2ClientSession.cc:499 (state_complete_frame_read)> (http2_cs) [3] [&Http2ClientSession::state_complete_frame_read, VC_EVENT_READ_READY/TS_EVENT_VCONN_READ_READY] [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <Http2ClientSession.cc:514 (state_complete_frame_read)> (http2_cs) [3] completed frame read, 16384 bytes available [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <Http2ConnectionState.cc:89 (rcv_data_frame)> (http2_con) [3] [1] Received DATA frame [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <Http2Stream.cc:370 (change_state)> (http2_stream) [3] [1] Http2StreamState::HTTP2_STREAM_STATE_HALF_CLOSED_REMOTE [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <Http2ConnectionState.cc:176 (rcv_data_frame)> (http2_con) [3] [1] Received DATA frame: rwnd con=49151/65535 stream=49151/65535 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4014 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=0 [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:308 (ssl_read_from_net)> (ssl.error) SSL_ERROR_WOULD_BLOCK(read) [Jun 3 06:20:45.063] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:719 (net_read_io)> (ssl) read finished - would block [Jun 3 06:20:45.064] [ET_NET 1] DEBUG: <Http2ConnectionState.cc:1756 (send_headers_frame)> (http2_con) [3] [1] Send HEADERS frame [Jun 3 06:20:45.064] [ET_NET 1] DEBUG: <Http2ConnectionState.cc:1700 (send_a_data_frame)> (http2_con) [3] [1] Send a DATA frame - client window con: 65357 stream: 65357 payload: 178 [Jun 3 06:20:45.064] [ET_NET 1] DEBUG: <Http2ConnectionState.cc:1700 (send_a_data_frame)> (http2_con) [3] [1] Send a DATA frame - client window con: 65357 stream: 65357 payload: 0 [Jun 3 06:20:45.064] [ET_NET 1] DEBUG: <Http2ConnectionState.cc:1708 (send_a_data_frame)> (http2_con) [3] [1] END_STREAM [Jun 3 06:20:45.064] [ET_NET 1] DEBUG: <Http2Stream.cc:370 (change_state)> (http2_stream) [3] [1] Http2StreamState::HTTP2_STREAM_STATE_CLOSED [Jun 3 06:20:45.064] [ET_NET 1] DEBUG: <Http2ConnectionState.cc:1741 (send_data_frames)> (http2_con) [3] [1] Shutdown stream [Jun 3 06:20:45.064] [ET_NET 1] DEBUG: <Http2Stream.cc:493 (initiating_close)> (http2_stream) [3] [1] initiating_close [Jun 3 06:20:45.064] [ET_NET 1] DEBUG: <Http2Stream.cc:520 (initiating_close)> (http2_stream) [3] [1] handle write from destroy (event=103) [Jun 3 06:20:45.064] [ET_NET 1] DEBUG: <Http2Stream.cc:68 (~Http2Stream)> (http2_stream) [3] [1] Destroy stream, sent 178 bytes [Jun 3 06:20:45.064] [ET_NET 1] DEBUG: <Http2ConnectionState.cc:1487 (delete_stream)> (http2_con) [3] [1] Delete stream [Jun 3 06:20:45.064] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:780 (load_buffer_and_write)> (ssl) towrite=361 [Jun 3 06:20:45.064] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:836 (load_buffer_and_write)> (ssl) try_to_write=361 written=361 total_written=361 [Jun 3 06:20:45.065] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4014 [Jun 3 06:20:45.065] [ET_NET 1] DEBUG: <SSLUtils.cc:1074 (ssl_callback_info)> (ssl) ssl_callback_info ssl: 0x7fffe402d070, where: 16388, ret: 256, State: SSL negotiation finished successfully [Jun 3 06:20:45.065] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=0 [Jun 3 06:20:45.065] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:334 (ssl_read_from_net)> (ssl.error) SSL_ERROR_ZERO_RETURN [Jun 3 06:20:45.065] [ET_NET 1] DEBUG: <Http2ClientSession.cc:327 (main_event_handler)> (http2_cs) [3] Closing event 104 [Jun 3 06:20:45.065] [ET_NET 1] DEBUG: <Http2ClientSession.cc:239 (do_io_close)> (http2_cs) [3] session closed [Jun 3 06:20:45.065] [ET_NET 1] DEBUG: <Http2ClientSession.cc:78 (destroy)> (http2_cs) [3] session destroy [Jun 3 06:20:45.065] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:1694 (callHooks)> (ssl) sslHandshakeHookState=7 eventID=108 [Jun 3 06:20:45.065] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:1811 (callHooks)> (ssl) iterated to curHook=(nil) [Jun 3 06:20:45.065] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:905 (do_io_close)> (ssl-shutdown) previous shutdown state 0x2 [Jun 3 06:20:45.065] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:935 (do_io_close)> (ssl-shutdown) Enable quiet shutdown [Jun 3 06:20:45.065] [ET_NET 1] DEBUG: <Http2ClientSession.cc:106 (free)> (http2_cs) [3] session free [Jun 3 06:20:45.065] [ET_NET 1] DEBUG: <SSLNetVConnection.cc:739 (net_read_io)> (ssl) read finished - 0 useful bytes read, bytes used by SSL layer ``` </details> <details><summary>Debug logs of bad case </summary> ``` [Jun 3 06:14:04.942] [ET_NET 0] DEBUG: <Http2ClientSession.cc:388 (state_read_connection_preface)> (http2_cs) [8] [&Http2ClientSession::state_read_connection_preface, VC_EVENT_READ_READY/TS_EVENT_VCONN_READ_READY] [Jun 3 06:14:04.942] [ET_NET 0] DEBUG: <Http2ClientSession.cc:409 (state_read_connection_preface)> (http2_cs) [8] received connection preface [Jun 3 06:14:04.942] [ET_NET 0] DEBUG: <Http2ClientSession.cc:438 (state_start_frame_read)> (http2_cs) [8] [&Http2ClientSession::state_start_frame_read, VC_EVENT_READ_READY/TS_EVENT_VCONN_READ_READY] [Jun 3 06:14:04.942] [ET_NET 0] DEBUG: <Http2ClientSession.cc:452 (do_start_frame_read)> (http2_cs) [8] receiving frame header [Jun 3 06:14:04.942] [ET_NET 0] DEBUG: <Http2ClientSession.cc:469 (do_start_frame_read)> (http2_cs) [8] frame header length=6, type=4, flags=0x0, streamid=0 [Jun 3 06:14:04.942] [ET_NET 0] DEBUG: <Http2ConnectionState.cc:566 (rcv_settings_frame)> (http2_con) [8] [0] Received SETTINGS frame [Jun 3 06:14:04.942] [ET_NET 0] DEBUG: <Http2ConnectionState.cc:638 (rcv_settings_frame)> (http2_con) [8] [0] INITIAL_WINDOW_SIZE : 65535 [Jun 3 06:14:04.942] [ET_NET 0] DEBUG: <Http2ClientSession.cc:452 (do_start_frame_read)> (http2_cs) [8] receiving frame header [Jun 3 06:14:04.942] [ET_NET 0] DEBUG: <Http2ClientSession.cc:469 (do_start_frame_read)> (http2_cs) [8] frame header length=0, type=4, flags=0x1, streamid=0 [Jun 3 06:14:04.942] [ET_NET 0] DEBUG: <Http2ConnectionState.cc:566 (rcv_settings_frame)> (http2_con) [8] [0] Received SETTINGS frame [Jun 3 06:14:04.942] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4048 [Jun 3 06:14:04.942] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=0 [Jun 3 06:14:04.942] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:308 (ssl_read_from_net)> (ssl.error) SSL_ERROR_WOULD_BLOCK(read) [Jun 3 06:14:04.942] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:719 (net_read_io)> (ssl) read finished - would block [Jun 3 06:14:04.942] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:780 (load_buffer_and_write)> (ssl) towrite=9 [Jun 3 06:14:04.942] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:836 (load_buffer_and_write)> (ssl) try_to_write=9 written=9 total_written=9 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4048 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=25 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4023 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=0 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:308 (ssl_read_from_net)> (ssl.error) SSL_ERROR_WOULD_BLOCK(read) [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:349 (ssl_read_from_net)> (ssl) bytes_read=25 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <Http2ClientSession.cc:438 (state_start_frame_read)> (http2_cs) [8] [&Http2ClientSession::state_start_frame_read, VC_EVENT_READ_READY/TS_EVENT_VCONN_READ_READY] [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <Http2ClientSession.cc:452 (do_start_frame_read)> (http2_cs) [8] receiving frame header [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <Http2ClientSession.cc:469 (do_start_frame_read)> (http2_cs) [8] frame header length=16, type=1, flags=0x4, streamid=1 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <Http2ConnectionState.cc:233 (rcv_headers_frame)> (http2_con) [8] [1] Received HEADERS frame [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <Http2Stream.cc:370 (change_state)> (http2_stream) [8] [1] Http2StreamState::HTTP2_STREAM_STATE_OPEN [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4023 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=4023 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4096 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=1872 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=2224 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=2224 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:349 (ssl_read_from_net)> (ssl) bytes_read=8119 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <Http2ClientSession.cc:438 (state_start_frame_read)> (http2_cs) [8] [&Http2ClientSession::state_start_frame_read, VC_EVENT_READ_READY/TS_EVENT_VCONN_READ_READY] [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <Http2ClientSession.cc:452 (do_start_frame_read)> (http2_cs) [8] receiving frame header [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <Http2ClientSession.cc:469 (do_start_frame_read)> (http2_cs) [8] frame header length=16384, type=0, flags=0x1, streamid=1 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4096 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=4096 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:349 (ssl_read_from_net)> (ssl) bytes_read=4096 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <Http2ClientSession.cc:499 (state_complete_frame_read)> (http2_cs) [8] [&Http2ClientSession::state_complete_frame_read, VC_EVENT_READ_READY/TS_EVENT_VCONN_READ_READY] [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4096 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=754 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=3342 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=3342 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:349 (ssl_read_from_net)> (ssl) bytes_read=4096 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <Http2ClientSession.cc:499 (state_complete_frame_read)> (http2_cs) [8] [&Http2ClientSession::state_complete_frame_read, VC_EVENT_READ_READY/TS_EVENT_VCONN_READ_READY] [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4096 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=82 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4014 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=0 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:308 (ssl_read_from_net)> (ssl.error) SSL_ERROR_WOULD_BLOCK(read) [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:349 (ssl_read_from_net)> (ssl) bytes_read=82 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <Http2ClientSession.cc:499 (state_complete_frame_read)> (http2_cs) [8] [&Http2ClientSession::state_complete_frame_read, VC_EVENT_READ_READY/TS_EVENT_VCONN_READ_READY] [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <Http2ClientSession.cc:514 (state_complete_frame_read)> (http2_cs) [8] completed frame read, 16384 bytes available [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <Http2ConnectionState.cc:89 (rcv_data_frame)> (http2_con) [8] [1] Received DATA frame [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <Http2Stream.cc:370 (change_state)> (http2_stream) [8] [1] Http2StreamState::HTTP2_STREAM_STATE_HALF_CLOSED_REMOTE [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <Http2ConnectionState.cc:176 (rcv_data_frame)> (http2_con) [8] [1] Received DATA frame: rwnd con=49151/65535 stream=49151/65535 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:282 (ssl_read_from_net)> (ssl) amount_to_read=4014 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:287 (ssl_read_from_net)> (ssl) nread=0 [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:308 (ssl_read_from_net)> (ssl.error) SSL_ERROR_WOULD_BLOCK(read) [Jun 3 06:14:04.943] [ET_NET 0] DEBUG: <SSLNetVConnection.cc:719 (net_read_io)> (ssl) read finished - would block ### got timeout after 10 seconds ``` </details> -- This is an automated message from the Apache Git Service. To respond to the message, please log on to GitHub and use the URL above to go to the specific comment. For queries about this service, please contact Infrastructure at: [email protected]
