You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@trafficserver.apache.org by GitBox <gi...@apache.org> on 2021/06/03 06:23:28 UTC
[GitHub] [trafficserver] masaori335 edited a comment on issue #7915: h2spec.test.py has become flakey
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.
<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:
users@infra.apache.org