ODPのパフォーマンスボトルネック

[2022-07-11 14:32:51.758195] WARN  [PROXY.SM] update_cmd_stats (ob_mysql_sm.cpp:8425) [74744][Y0-7F4B1CEA13A0] [lt=7] [dc=0] Slow Query: ((
client_ip={127.0.0.1:50422},   // SQLを送信したクライアントのアドレス
server_ip={xx.xx.xx.xx:33041}, // SQLがルーティングされたターゲットOBServer
obproxy_client_port={xx.xx.xx.xx:52052},  // OBServerと接続するローカルアドレス
server_trace_id=Y81100B7C0535-0005E3460FBBE3CD-0-0, // ターゲットOBServerでの実行プロセス中のtrace id
route_type=ROUTE_TYPE_NONPARTITION_UNMERGE_LOCAL, // SQLで使用されるルーティングポリシー
user_name=root,         // ユーザー名
tenant_name=sys,        // テナント名
cluster_name=ob9988.zhixin.lm.100.xx.xx.xx,  // クラスタ名
logic_database_name=,  // ロジックデータベース名
logic_tenant_name=,    // ロジックテナント名
ob_proxy_protocol=0,   // プロトコルタイプ
cs_id=14,         // client login時に表示されるconnection id、proxyが割り当てる
proxy_sessid=7230691598940700681, // clientがOBServerにアクセスする際に内部で記録されるconnection id
ss_id=21,
server_sessid=3221588238,  // SQLがターゲットOBServerでのconnection id、OBServerが割り当てる
sm_id=14,
cmd_size_stats={
    client_request_bytes:20,  // clientがproxyに送信するリクエストパケットのサイズ
    server_request_bytes:38,  // ODPがターゲットOBServerに送信するリクエストパケットのサイズ
    server_response_bytes:0,  // ターゲットOBServerがproxyに送信する応答パケットのサイズ
    client_response_bytes:71}, // ODPがclientに送信する応答パケットのサイズ
cmd_time_stats={
    client_transaction_idle_time_us=0, // トランザクション内でこのSQLと前のSQLの実行終了間隔時間、すなわちclientトランザクション間隔時間
    client_request_read_time_us=97, // ODPがclientソケットからリクエストパケットを読み取る時間
    client_request_analyze_time_us=95, // ODPがclientのSQLを分析する時間
    cluster_resource_create_time_us=0, // ODPがクラスタリソースを作成する時間（クラスタへの初回アクセス時のみ必要）
    pl_lookup_time_us=0, // SQLに基づいて関連するルーティングテーブルを取得する時間
    pl_process_time_us=0,  // 関連するルーティングテーブルのフィルタリングとソートにかかる時間
    congestion_control_time_us=21,  // SQLに基づいてブラックリスト情報を取得する時間
    congestion_process_time_us=3,  // ブラックリストに関連するチェックとフィルタリングにかかる時間
    do_observer_open_time_us=55,  // ターゲットOBServerから利用可能な接続を取得する時間、connect_timeを含む
        server_connect_time_us=0,  // ターゲットOBServerで接続を作成する時間
    server_sync_session_variable_time_us=0,  // 選択したターゲット接続の初期化にかかる時間、saved_login、db同期、システム変数同期、last_insert_id同期、start_trans同期を含む
        server_send_saved_login_time_us=0,  // 選択したターゲット接続でのsaved loginにかかる時間
        server_send_use_database_time_us=0,  // 選択したターゲット接続へのデータベース同期時間
        server_send_session_variable_time_us=0,  // 選択したターゲット接続への変更されたシステム変数の同期時間
        server_send_all_session_variable_time_us=0,  // 選択したターゲット接続へのすべてのシステム変数の同期時間
        server_send_last_insert_id_time_us=0, // 選択したターゲット接続へのlast_insert_idの同期時間
        server_send_start_trans_time_us=0,   // 選択したターゲット接続へのstart_trans/beginの同期時間
    build_server_request_time_us=23,  // ターゲットサーバーに対するリクエストパケットを構築する時間
    plugin_compress_request_time_us=0,   // リクエストパケットを圧縮する時間
    prepare_send_request_to_server_time_us=409,  // ODPがクライアントからのリクエストを受信してから、OBServerに転送して実行するまでの合計時間。通常は上記のすべての時間の合計である。
    server_request_write_time_us=32,  // ODPがターゲットサーバーソケットにリクエストパケットを送信する時間
    server_process_request_time_us=3039883,   // ターゲットサーバーがSQLを実行する時間
    server_response_read_time_us=67,   // ODPがターゲットサーバーソケットから応答パケットを読み取る時間
    plugin_decompress_response_time_us=59, // 応答パケットを解凍する時間
    server_response_analyze_time_us=70,  // 応答パケットを分析する時間
    ok_packet_trim_time_us=0, // 応答パケットから最後のOKパケットをトリムする時間
    client_response_write_time_us=185,  // ODPがクライアントソケットに応答パケットを送信する時間
    request_total_time_us=3041116},  // ODPがこのリクエストを処理する総時間。上記のすべての時間の合計と等しい
sql=select sleep(3)   //クライアントのリクエストSQL
)