Happy New Year, everyone!
Further to this, I added connection management loglevel to the errorlog level and managed
to capture the output during one of the events when the connection seems to stall. Would
anyone be able to help me make sense of it?
Thanks a lot,
Trevor Fong
Access log:
[02/Jan/2020:08:21:00.925703124 -0800] conn=258144 fd=263 slot=263 SSL connection from
<cleint ip> to <host ip>
[02/Jan/2020:08:21:00.934435506 -0800] conn=258144 TLS1.2 256-bit AES-GCM
< expecting other transactions with conn=258144 but nothing happens until the
following, when the connection is eventually timed out (600 sec) and broken by the
client>
[02/Jan/2020:08:31:01.024762657 -0800] conn=258144 op=-1 fd=263 closed - Encountered end
of file.
Error log:
[02/Jan/2020:08:21:00.924588379 -0800] - DEBUG - connection_reset - new SSL connection on
263
[02/Jan/2020:08:21:00.927088611 -0800] - DEBUG -
connection_table_dump_activity_to_errors_log - activity on 263r
[02/Jan/2020:08:21:00.927961983 -0800] - DEBUG - handle_pr_read_ready - read activity on
263
[02/Jan/2020:08:21:00.932285653 -0800] - DEBUG - connection_read_operation - connection
258144 waited 1 times for read to be ready
[02/Jan/2020:08:21:00.934724384 -0800] - DEBUG - connection_read_operation - connection
258144 waited 2 times for read to be ready
[02/Jan/2020:08:21:01.035814543 -0800] - DEBUG - connection_threadmain - conn 258144 read
not ready due to 4 - thread_turbo_flag 0 more_data 0 ops_initiated 1 refcnt 2 flags 17
[02/Jan/2020:08:21:01.036940723 -0800] - DEBUG - connection_check_activity_level - conn
258144 activity level = 0
[02/Jan/2020:08:21:01.037824240 -0800] - DEBUG - connection_threadmain - conn 258144
leaving turbo mode due to 4
[02/Jan/2020:08:21:01.038667951 -0800] - DEBUG - connection_threadmain - conn 258144 check
more_data 0 thread_turbo_flag 0repl_conn_bef 0, repl_conn_now 0
[02/Jan/2020:08:21:01.039407337 -0800] - DEBUG - connection_make_readable_nolock - making
readable conn 258144 fd=263
…
[02/Jan/2020:08:31:01.018473459 -0800] - DEBUG -
connection_table_dump_activity_to_errors_log - activity on 263r
[02/Jan/2020:08:31:01.020162681 -0800] - DEBUG - handle_pr_read_ready - read activity on
263
[02/Jan/2020:08:31:01.021136264 -0800] - DEBUG - connection_read_operation - PR_Recv for
connection 258144 returns -5938 (Encountered end of file.)
[02/Jan/2020:08:31:01.022435629 -0800] - DEBUG - disconnect_server_nomutex_ext - Setting
conn 258144 fd=263 to be disconnected: reason -5938
[02/Jan/2020:08:31:01.024785254 -0800] - DEBUG - connection_threadmain - conn 258144 read
not ready due to 3 - thread_turbo_flag 0 more_data 0 ops_initiated 2 refcnt 2 flags 19
[02/Jan/2020:08:31:01.026135420 -0800] - DEBUG - connection_check_activity_level - conn
258144 activity level = 1
[02/Jan/2020:08:31:01.027294400 -0800] - DEBUG - connection_enter_leave_turbo - conn
258144 turbo rank = 41 out of 841 conns
[02/Jan/2020:08:31:01.028297819 -0800] - DEBUG - connection_threadmain - conn 258144
leaving turbo mode due to 3
[02/Jan/2020:08:31:01.029284720 -0800] - DEBUG - connection_threadmain - conn 258144 check
more_data 0 thread_turbo_flag 0repl_conn_bef 0, repl_conn_now 0
[02/Jan/2020:08:31:01.034004014 -0800] - DEBUG - connection_make_readable_nolock - making
readable conn 258144 fd=263
[02/Jan/2020:08:31:01.036209375 -0800] - DEBUG - clear_signal - Listener got signaled
[02/Jan/2020:08:31:01.037395981 -0800] - DEBUG -
connection_table_move_connection_out_of_active_list - Moved conn 263 out of active list
and freed