[root@juyi-02 log]# tail -n 500 libobcdc.log [2024-11-15 11:31:55.262846] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779607][RpcIO][T0][Y0-0000000000000000-0-0] [lt=48] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:55.263583] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779608][RpcIO][T0][Y0-0000000000000000-0-0] [lt=38] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:55.264370] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779610][RpcIO][T0][Y0-0000000000000000-0-0] [lt=29] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:55.264419] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779609][RpcIO][T0][Y0-0000000000000000-0-0] [lt=24] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:55.442179] INFO eloop_run (eloop.c:140) [2779597][pnio1][T0][Y0-0000000000000000-0-0] [lt=90] PNIO [ratelimit] time: 1731641515442173, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007606, add_bytes: 0 [2024-11-15 11:31:55.487412] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779614][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=59] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:55.489625] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779616][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=36] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:55.492497] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779612][BatchIO][T0][Y0-0000000000000000-0-0] [lt=36] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:55.494576] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779618][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=40] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:55.590255] INFO eloop_run (eloop.c:140) [2779601][pnio2][T0][Y0-0000000000000000-0-0] [lt=51] PNIO [ratelimit] time: 1731641515590249, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007633, add_bytes: 0 [2024-11-15 11:31:55.663176] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779622][RpcIO][T0][Y0-0000000000000000-0-0] [lt=52] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:31:55.663260] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779624][RpcIO][T0][Y0-0000000000000000-0-0] [lt=13] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:31:55.663273] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779623][RpcIO][T0][Y0-0000000000000000-0-0] [lt=38] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:31:55.664951] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779625][RpcIO][T0][Y0-0000000000000000-0-0] [lt=21] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:55.665797] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779629][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=54] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:55.668337] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779631][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=38] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:55.668966] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779627][BatchIO][T0][Y0-0000000000000000-0-0] [lt=61] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:55.671252] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779633][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=72] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:56.263515] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779608][RpcIO][T0][Y0-0000000000000000-0-0] [lt=30] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:56.263740] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779607][RpcIO][T0][Y0-0000000000000000-0-0] [lt=63] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:56.264066] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779609][RpcIO][T0][Y0-0000000000000000-0-0] [lt=20] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:56.264446] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779610][RpcIO][T0][Y0-0000000000000000-0-0] [lt=59] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:56.449887] INFO eloop_run (eloop.c:140) [2779597][pnio1][T0][Y0-0000000000000000-0-0] [lt=53] PNIO [ratelimit] time: 1731641516449881, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007708, add_bytes: 0 [2024-11-15 11:31:56.488423] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779614][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=49] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:56.489645] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779616][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=39] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:56.492477] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779612][BatchIO][T0][Y0-0000000000000000-0-0] [lt=65] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:56.493543] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779618][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=37] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:56.597903] INFO eloop_run (eloop.c:140) [2779601][pnio2][T0][Y0-0000000000000000-0-0] [lt=75] PNIO [ratelimit] time: 1731641516597889, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007640, add_bytes: 0 [2024-11-15 11:31:56.663341] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779622][RpcIO][T0][Y0-0000000000000000-0-0] [lt=47] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:31:56.663788] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779624][RpcIO][T0][Y0-0000000000000000-0-0] [lt=37] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:31:56.663892] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779623][RpcIO][T0][Y0-0000000000000000-0-0] [lt=36] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:31:56.664810] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779625][RpcIO][T0][Y0-0000000000000000-0-0] [lt=42] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:56.665922] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779629][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=38] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:56.667990] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779631][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=59] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:56.669923] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779627][BatchIO][T0][Y0-0000000000000000-0-0] [lt=60] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:56.671223] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779633][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=141] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:57.263522] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779607][RpcIO][T0][Y0-0000000000000000-0-0] [lt=29] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:57.263613] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779608][RpcIO][T0][Y0-0000000000000000-0-0] [lt=52] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:57.264163] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779609][RpcIO][T0][Y0-0000000000000000-0-0] [lt=31] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:57.264490] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779610][RpcIO][T0][Y0-0000000000000000-0-0] [lt=26] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:57.457425] INFO eloop_run (eloop.c:140) [2779597][pnio1][T0][Y0-0000000000000000-0-0] [lt=63] PNIO [ratelimit] time: 1731641517457420, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007539, add_bytes: 0 [2024-11-15 11:31:57.488481] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779614][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=45] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:57.489415] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779616][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=39] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:57.492441] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779612][BatchIO][T0][Y0-0000000000000000-0-0] [lt=49] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:57.493418] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779618][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=38] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:57.605475] INFO eloop_run (eloop.c:140) [2779601][pnio2][T0][Y0-0000000000000000-0-0] [lt=127] PNIO [ratelimit] time: 1731641517605468, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007579, add_bytes: 0 [2024-11-15 11:31:57.663400] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779623][RpcIO][T0][Y0-0000000000000000-0-0] [lt=17] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:31:57.663420] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779622][RpcIO][T0][Y0-0000000000000000-0-0] [lt=79] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:31:57.663397] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779624][RpcIO][T0][Y0-0000000000000000-0-0] [lt=40] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:31:57.664730] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779625][RpcIO][T0][Y0-0000000000000000-0-0] [lt=34] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:57.665989] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779629][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=38] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:57.667786] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779631][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=39] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:57.669926] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779627][BatchIO][T0][Y0-0000000000000000-0-0] [lt=54] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:57.671308] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779633][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=33] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:58.263629] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779608][RpcIO][T0][Y0-0000000000000000-0-0] [lt=30] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:58.263629] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779607][RpcIO][T0][Y0-0000000000000000-0-0] [lt=62] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:58.263762] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779609][RpcIO][T0][Y0-0000000000000000-0-0] [lt=29] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:58.264602] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779610][RpcIO][T0][Y0-0000000000000000-0-0] [lt=38] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:58.465042] INFO eloop_run (eloop.c:140) [2779597][pnio1][T0][Y0-0000000000000000-0-0] [lt=51] PNIO [ratelimit] time: 1731641518465036, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007616, add_bytes: 0 [2024-11-15 11:31:58.487583] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779614][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=43] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:58.489227] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779616][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=37] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:58.492441] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779612][BatchIO][T0][Y0-0000000000000000-0-0] [lt=36] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:58.494428] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779618][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=40] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:58.613088] INFO eloop_run (eloop.c:140) [2779601][pnio2][T0][Y0-0000000000000000-0-0] [lt=63] PNIO [ratelimit] time: 1731641518613083, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007615, add_bytes: 0 [2024-11-15 11:31:58.663525] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779623][RpcIO][T0][Y0-0000000000000000-0-0] [lt=52] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:31:58.663545] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779622][RpcIO][T0][Y0-0000000000000000-0-0] [lt=76] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:31:58.663806] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779624][RpcIO][T0][Y0-0000000000000000-0-0] [lt=109] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:31:58.664670] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779625][RpcIO][T0][Y0-0000000000000000-0-0] [lt=40] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:58.665732] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779629][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=39] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:58.667734] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779631][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=52] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:58.669751] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779627][BatchIO][T0][Y0-0000000000000000-0-0] [lt=55] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:58.671041] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779633][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=47] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:58.828660] INFO [TLOG] update_zone_cache_ (ob_log_all_svr_cache.cpp:425) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=73] [STAT] [ALL_ZONE] INDEX=0/1 ZONE=zone1 REGION=sys_region STORAGE_TYPE=LOCAL VERSION=1126 [2024-11-15 11:31:58.828720] INFO [TLOG] update_zone_cache_ (ob_log_all_svr_cache.cpp:450) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=53] [STAT] [ALL_ZONE] INDEX=0/1 ZONE=zone1 ZONE_TYPE=ReadWrite VERSION=1126 [2024-11-15 11:31:58.828744] INFO [TLOG] update_zone_cache_ (ob_log_all_svr_cache.cpp:454) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=23] [STAT] [ALL_ZONE] COUNT=1 VERSION=1126 [2024-11-15 11:31:58.828764] INFO [TLOG] purge_stale_zone_records_ (ob_log_all_svr_cache.cpp:660) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=9] [STAT] [ALL_ZONE] [PURGE] PURGE_COUNT=0 CUR_COUNT=1 VERSION=1126 [2024-11-15 11:31:58.829365] INFO [TLOG] update_server_cache_ (ob_log_all_svr_cache.cpp:521) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=9] [STAT] [ALL_SERVER_LIST] INDEX=0/1 SERVER_ID=1 SERVER="172.19.70.221:2882" STATUS=1(ACTIVE) ZONE=zone1 REGION=sys_region(LOW) VERSION=1125 [2024-11-15 11:31:58.829420] INFO [TLOG] update_server_cache_ (ob_log_all_svr_cache.cpp:525) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=53] [STAT] [ALL_SERVER_LIST] COUNT=1 VERSION=1125 [2024-11-15 11:31:58.829463] INFO [TLOG] purge_stale_records_ (ob_log_all_svr_cache.cpp:646) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=9] [STAT] [ALL_SERVER_LIST] [PURGE] PURGE_COUNT=0 CUR_COUNT=1 VERSION=1125 [2024-11-15 11:31:58.830331] INFO [TLOG.FETCHER] add_server_or_update (ob_ls_server_list.cpp:87) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=10] add_server_or_update(next_svr_index=0, svr_items=[{server:"172.19.70.221:2882", ranges:[LSN:{0, 73453102}], priority:[is_meta_table:1 region:LOW, replica:FULL, is_leader:1]}]) [2024-11-15 11:31:58.830395] INFO [TLOG] get_ls_svr_list_ (ob_log_route_service.cpp:835) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=38] get_ls_svr_list_ done(ret=0, ret="OB_SUCCESS", router_key={cluster_id:1730442730, tenant_id:1006, ls_id:{id:1}}, svr_list={next_svr_index:0, svr_num:1, svr_items:[{server:"172.19.70.221:2882", ranges:[LSN:{0, 73453102}], priority:[is_meta_table:1 region:LOW, replica:FULL, is_leader:1]}]}, tg_id_=259) [2024-11-15 11:31:59.263300] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779607][RpcIO][T0][Y0-0000000000000000-0-0] [lt=101] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:59.263410] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779609][RpcIO][T0][Y0-0000000000000000-0-0] [lt=59] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:59.263528] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779608][RpcIO][T0][Y0-0000000000000000-0-0] [lt=78] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:59.264626] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779610][RpcIO][T0][Y0-0000000000000000-0-0] [lt=35] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:59.472659] INFO eloop_run (eloop.c:140) [2779597][pnio1][T0][Y0-0000000000000000-0-0] [lt=67] PNIO [ratelimit] time: 1731641519472652, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007616, add_bytes: 0 [2024-11-15 11:31:59.487552] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779614][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=47] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:59.489891] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779616][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=45] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:59.492281] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779612][BatchIO][T0][Y0-0000000000000000-0-0] [lt=39] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:59.494407] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779618][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=35] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:59.620719] INFO eloop_run (eloop.c:140) [2779601][pnio2][T0][Y0-0000000000000000-0-0] [lt=51] PNIO [ratelimit] time: 1731641519620713, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007630, add_bytes: 0 [2024-11-15 11:31:59.663289] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779622][RpcIO][T0][Y0-0000000000000000-0-0] [lt=51] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:31:59.663680] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779623][RpcIO][T0][Y0-0000000000000000-0-0] [lt=60] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:31:59.663732] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779624][RpcIO][T0][Y0-0000000000000000-0-0] [lt=29] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:31:59.664410] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779625][RpcIO][T0][Y0-0000000000000000-0-0] [lt=38] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:59.665752] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779629][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=26] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:59.667080] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779631][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=28] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:59.669740] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779627][BatchIO][T0][Y0-0000000000000000-0-0] [lt=50] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:31:59.670510] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779633][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=47] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:00.162131] INFO [TLOG] add_ls_and_fetch_until_the_progress_is_reached (ob_log_meta_data_fetcher.cpp:196) [2779504][][T0][Y0-0000000000000000-0-0] [lt=8] ObLogFetcher check_progress is less than end_timestamp(tenant_id=1006, cur_progress=1731592847317178999, end_tstamp_ns=1731635887247529000) [2024-11-15 11:32:00.162170] INFO [TLOG] print_stat_info (ob_log_task_pool.h:266) [2779504][][T0][Y0-0000000000000000-0-0] [lt=38] [STAT] [TRANS_TASK_POOL] OUT=0/1000 FIXED=0/1000 DYNAMIC=0 PAGE_POOL=1000/1000 [2024-11-15 11:32:00.262687] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779607][RpcIO][T0][Y0-0000000000000000-0-0] [lt=49] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:00.263411] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779608][RpcIO][T0][Y0-0000000000000000-0-0] [lt=30] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:00.264430] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779609][RpcIO][T0][Y0-0000000000000000-0-0] [lt=18] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:00.264556] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779610][RpcIO][T0][Y0-0000000000000000-0-0] [lt=25] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:00.452249] INFO pn_print_diag_info (group.c:714) [2779598][pnio1][T0][Y0-0000000000000000-0-0] [lt=69] PNIO client_send:0/0, client_queue_time=0, cnt=0, server_send:0/0, server_queue_time=0, cnt=0 [2024-11-15 11:32:00.480291] INFO eloop_run (eloop.c:140) [2779597][pnio1][T0][Y0-0000000000000000-0-0] [lt=71] PNIO [ratelimit] time: 1731641520480286, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007634, add_bytes: 0 [2024-11-15 11:32:00.480400] INFO pn_print_diag_info (group.c:714) [2779597][pnio1][T0][Y0-0000000000000000-0-0] [lt=69] PNIO client_send:0/0, client_queue_time=0, cnt=0, server_send:0/0, server_queue_time=0, cnt=0 [2024-11-15 11:32:00.487433] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779614][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=56] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:00.489923] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779616][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=39] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:00.491430] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779612][BatchIO][T0][Y0-0000000000000000-0-0] [lt=39] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:00.494430] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779618][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=38] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:00.512819] INFO [COMMON] generate_mod_stat_task (memory_dump.cpp:220) [2779521][MemoryDump][T0][Y0-0000000000000000-0-0] [lt=10] task info(*task={type_:2, dump_all_:false, p_context_:null, slot_idx_:0, dump_tenant_ctx_:false, tenant_id_:0, ctx_id_:0, p_chunk_:null}) [2024-11-15 11:32:00.512871] INFO [COMMON] handle (memory_dump.cpp:552) [2779521][MemoryDump][T0][Y0-0000000000000000-0-0] [lt=49] handle dump task(task={type_:2, dump_all_:false, p_context_:null, slot_idx_:0, dump_tenant_ctx_:false, tenant_id_:0, ctx_id_:0, p_chunk_:null}) [2024-11-15 11:32:00.513210] INFO [COMMON] update_check_range (ob_sql_mem_leak_checker.cpp:62) [2779521][MemoryDump][T0][Y0-0000000000000000-0-0] [lt=16] update_check_range(min_check_version=560, max_check_version=561, global_version=562) [2024-11-15 11:32:00.517297] INFO handle (memory_dump.cpp:679) [2779521][MemoryDump][T0][Y0-0000000000000000-0-0] [lt=54] statistics: tenant_cnt: 3, max_chunk_cnt: 524288 tenant_id ctx_id chunk_cnt label_cnt segv_cnt 1 0 2 2 0 500 0 45 96 0 500 8 13 1 0 500 17 9 1 0 500 23 4 4 0 1006 0 1 1 0 cost_time: 4087 [2024-11-15 11:32:00.517396] INFO [LIB] operator() (ob_malloc_allocator.cpp:542) [2779521][MemoryDump][T0][Y0-0000000000000000-0-0] [lt=41] [MEMORY] tenant: 1, limit: 9,223,372,036,854,775,807 hold: 4,194,304 rpc_hold: 0 cache_hold: 0 cache_used: 0 cache_item_count: 0 [MEMORY] ctx_id= DEFAULT_CTX_ID hold_bytes= 4,194,304 limit= 9,223,372,036,854,775,807 [2024-11-15 11:32:00.517487] INFO [LIB] print_usage (ob_tenant_ctx_allocator.cpp:178) [2779521][MemoryDump][T0][Y0-0000000000000000-0-0] [lt=25] [MEMORY] tenant_id= 1 ctx_id= DEFAULT_CTX_ID hold= 4,194,304 used= 2,271,104 limit= 9,223,372,036,854,775,807 [MEMORY] idle_size= 0 free_size= 0 [MEMORY] wash_related_chunks= 0 washed_blocks= 0 washed_size= 0 [MEMORY] request_cached_chunk_cnt= 0 [MEMORY] hold= 2,198,864 used= 416,700 count= 25,277 avg_used= 16 block_cnt= 270 chunk_cnt= 2 mod=Number [MEMORY] hold= 72,240 used= 1,024 count= 896 avg_used= 1 block_cnt= 10 chunk_cnt= 1 mod=CharsetUtil [MEMORY] hold= 2,271,104 used= 417,724 count= 26,173 avg_used= 15 mod=SUMMARY [2024-11-15 11:32:00.517741] INFO [LIB] operator() (ob_malloc_allocator.cpp:542) [2779521][MemoryDump][T0][Y0-0000000000000000-0-0] [lt=23] [MEMORY] tenant: 500, limit: 9,223,372,036,854,775,807 hold: 1,020,964,864 rpc_hold: 0 cache_hold: 0 cache_used: 0 cache_item_count: 0 [MEMORY] ctx_id= DEFAULT_CTX_ID hold_bytes= 737,624,064 limit= 9,223,372,036,854,775,807 [MEMORY] ctx_id= CO_STACK hold_bytes= 27,262,976 limit= 9,223,372,036,854,775,807 [MEMORY] ctx_id= PKT_NIO hold_bytes= 27,492,352 limit= 9,223,372,036,854,775,807 [MEMORY] ctx_id= UNEXPECTED_IN_500 hold_bytes= 228,585,472 limit= 9,223,372,036,854,775,807 [2024-11-15 11:32:00.518146] INFO [LIB] print_usage (ob_tenant_ctx_allocator.cpp:178) [2779521][MemoryDump][T0][Y0-0000000000000000-0-0] [lt=38] [MEMORY] tenant_id= 500 ctx_id= DEFAULT_CTX_ID hold= 737,624,064 used= 734,438,784 limit= 9,223,372,036,854,775,807 [MEMORY] idle_size= 0 free_size= 0 [MEMORY] wash_related_chunks= 0 washed_blocks= 0 washed_size= 0 [MEMORY] request_cached_chunk_cnt= 0 [MEMORY] hold= 209,920,000 used= 209,715,200 count= 10 avg_used= 20,971,520 block_cnt= 10 chunk_cnt= 10 mod=CDCBufBlock [MEMORY] hold= 166,694,912 used= 166,656,000 count= 2 avg_used= 83,328,000 block_cnt= 2 chunk_cnt= 2 mod=LSTranTasPreaPa [MEMORY] hold= 110,247,936 used= 110,208,000 count= 2 avg_used= 55,104,000 block_cnt= 2 chunk_cnt= 2 mod=LSTranTaskPool [MEMORY] hold= 43,083,840 used= 42,583,360 count= 61 avg_used= 698,087 block_cnt= 61 chunk_cnt= 13 mod=FixedQueue [MEMORY] hold= 33,575,296 used= 33,554,688 count= 3 avg_used= 11,184,896 block_cnt= 3 chunk_cnt= 2 mod=LogFetcArpcRes [MEMORY] hold= 32,038,912 used= 32,000,000 count= 2 avg_used= 16,000,000 block_cnt= 2 chunk_cnt= 2 mod=LogPartProgrCon [MEMORY] hold= 17,846,272 used= 17,827,584 count= 1 avg_used= 17,827,584 block_cnt= 1 chunk_cnt= 1 mod=LogFetcArpcReq [MEMORY] hold= 17,080,320 used= 17,039,360 count= 2 avg_used= 8,519,680 block_cnt= 2 chunk_cnt= 2 mod=LogRouteSrv [MEMORY] hold= 16,961,536 used= 16,937,216 count= 3 avg_used= 5,645,738 block_cnt= 3 chunk_cnt= 3 mod=LogPartFetCtxPo [MEMORY] hold= 16,813,696 used= 16,793,088 count= 3 avg_used= 5,597,696 block_cnt= 3 chunk_cnt= 3 mod=LogPartTransRes [MEMORY] hold= 16,798,080 used= 16,777,472 count= 3 avg_used= 5,592,490 block_cnt= 3 chunk_cnt= 2 mod=LogFetchStrePoo [MEMORY] hold= 13,479,936 used= 13,403,328 count= 8 avg_used= 1,675,416 block_cnt= 8 chunk_cnt= 3 mod=KvstCachWashStr [MEMORY] hold= 12,873,728 used= 12,856,352 count= 1 avg_used= 12,856,352 block_cnt= 1 chunk_cnt= 1 mod=MemDumpContext [MEMORY] hold= 4,739,072 used= 4,718,712 count= 1 avg_used= 4,718,712 block_cnt= 1 chunk_cnt= 1 mod=HashBucTenComMo [MEMORY] hold= 4,215,168 used= 4,194,560 count= 3 avg_used= 1,398,186 block_cnt= 3 chunk_cnt= 3 mod=LogSvrStreaPool [MEMORY] hold= 3,538,944 used= 3,318,528 count= 48 avg_used= 69,136 block_cnt= 48 chunk_cnt= 7 mod=[T]ObSessionDIB [MEMORY] hold= 2,080,768 used= 2,079,744 count= 1 avg_used= 2,079,744 block_cnt= 1 chunk_cnt= 1 mod=ThreadLocalNode [MEMORY] hold= 1,605,632 used= 1,600,000 count= 1 avg_used= 1,600,000 block_cnt= 1 chunk_cnt= 1 mod=LogBinloRecoPoo [MEMORY] hold= 1,228,800 used= 1,225,312 count= 1 avg_used= 1,225,312 block_cnt= 1 chunk_cnt= 1 mod=LogFormatter [MEMORY] hold= 1,072,768 used= 1,064,480 count= 3 avg_used= 354,826 block_cnt= 3 chunk_cnt= 2 mod=SqlConnectPool [MEMORY] hold= 1,056,768 used= 1,048,608 count= 1 avg_used= 1,048,608 block_cnt= 1 chunk_cnt= 1 mod=DDTENANT [MEMORY] hold= 1,056,768 used= 1,048,608 count= 1 avg_used= 1,048,608 block_cnt= 1 chunk_cnt= 1 mod=LibObsql [MEMORY] hold= 933,888 used= 917,576 count= 2 avg_used= 458,788 block_cnt= 2 chunk_cnt= 2 mod=CACHE_INST_MAP [MEMORY] hold= 802,816 used= 800,000 count= 1 avg_used= 800,000 block_cnt= 1 chunk_cnt= 1 mod=LogTransCtx [MEMORY] hold= 773,056 used= 758,400 count= 3 avg_used= 252,800 block_cnt= 3 chunk_cnt= 3 mod=TenantCtxAlloca [MEMORY] hold= 458,560 used= 417,400 count= 145 avg_used= 2,878 block_cnt= 33 chunk_cnt= 3 mod=tg [MEMORY] hold= 393,216 used= 392,832 count= 3 avg_used= 130,944 block_cnt= 3 chunk_cnt= 2 mod=Value [MEMORY] hold= 278,720 used= 274,432 count= 67 avg_used= 4,096 block_cnt= 67 chunk_cnt= 8 mod=LinearHashMapCn [MEMORY] hold= 278,720 used= 274,432 count= 67 avg_used= 4,096 block_cnt= 67 chunk_cnt= 9 mod=LinearHashMapDi [MEMORY] hold= 250,544 used= 243,144 count= 5 avg_used= 48,628 block_cnt= 5 chunk_cnt= 4 mod=HashBucket [MEMORY] hold= 180,224 used= 172,064 count= 1 avg_used= 172,064 block_cnt= 1 chunk_cnt= 1 mod=TenantMBList [MEMORY] hold= 163,840 used= 147,792 count= 2 avg_used= 73,896 block_cnt= 2 chunk_cnt= 1 mod=HashNodNexWaiMa [MEMORY] hold= 163,408 used= 162,704 count= 9 avg_used= 18,078 block_cnt= 9 chunk_cnt= 2 mod=RingBuffer [MEMORY] hold= 153,856 used= 152,576 count= 20 avg_used= 7,628 block_cnt= 20 chunk_cnt= 4 mod=LogStarLogIdLoc [MEMORY] hold= 152,000 used= 150,784 count= 19 avg_used= 7,936 block_cnt= 19 chunk_cnt= 7 mod=HashMapArray [MEMORY] hold= 128,000 used= 126,976 count= 16 avg_used= 7,936 block_cnt= 16 chunk_cnt= 2 mod=LogStreaWorkThr [MEMORY] hold= 92,352 used= 91,584 count= 12 avg_used= 7,632 block_cnt= 12 chunk_cnt= 1 mod=HashNodeConfCon [MEMORY] hold= 92,160 used= 89,280 count= 45 avg_used= 1,984 block_cnt= 12 chunk_cnt= 4 mod=CreateContext [MEMORY] hold= 73,728 used= 65,600 count= 1 avg_used= 65,600 block_cnt= 1 chunk_cnt= 1 mod=TCREF [MEMORY] hold= 73,728 used= 65,536 count= 1 avg_used= 65,536 block_cnt= 1 chunk_cnt= 1 mod=[T]char [MEMORY] hold= 65,536 used= 65,408 count= 1 avg_used= 65,408 block_cnt= 1 chunk_cnt= 1 mod=KVCACHE_HAZARD [MEMORY] hold= 65,536 used= 49,376 count= 4 avg_used= 12,344 block_cnt= 4 chunk_cnt= 2 mod=HashBuckConfCon [MEMORY] hold= 65,536 used= 65,192 count= 1 avg_used= 65,192 block_cnt= 1 chunk_cnt= 1 mod=LatchStat [MEMORY] hold= 64,000 used= 63,488 count= 8 avg_used= 7,936 block_cnt= 8 chunk_cnt= 2 mod=LogFetchIdlePoo [MEMORY] hold= 64,000 used= 63,488 count= 8 avg_used= 7,936 block_cnt= 8 chunk_cnt= 7 mod=CommonArray [MEMORY] hold= 49,152 used= 32,768 count= 2 avg_used= 16,384 block_cnt= 2 chunk_cnt= 2 mod=CACHE_TNT_LST [MEMORY] hold= 48,288 used= 40,064 count= 4 avg_used= 10,016 block_cnt= 4 chunk_cnt= 2 mod=MallocInfoMap [MEMORY] hold= 39,040 used= 38,720 count= 5 avg_used= 7,744 block_cnt= 5 chunk_cnt= 2 mod=HashNode [MEMORY] hold= 32,768 used= 16,384 count= 2 avg_used= 8,192 block_cnt= 2 chunk_cnt= 2 mod=LogTimer [MEMORY] hold= 32,000 used= 31,744 count= 4 avg_used= 7,936 block_cnt= 4 chunk_cnt= 3 mod=Pool [MEMORY] hold= 32,000 used= 31,744 count= 4 avg_used= 7,936 block_cnt= 4 chunk_cnt= 2 mod=LogAllServeCach [MEMORY] hold= 17,728 used= 13,616 count= 2 avg_used= 6,808 block_cnt= 2 chunk_cnt= 2 mod=HasBucTimZonInM [MEMORY] hold= 16,640 used= 16,384 count= 4 avg_used= 4,096 block_cnt= 4 chunk_cnt= 2 mod=ExtSvrBlackList [MEMORY] hold= 16,496 used= 16,176 count= 4 avg_used= 4,044 block_cnt= 4 chunk_cnt= 2 mod=ScheObSchemAren [MEMORY] hold= 16,384 used= 9,336 count= 1 avg_used= 9,336 block_cnt= 1 chunk_cnt= 1 mod=InnerLobHash [MEMORY] hold= 16,384 used= 12,344 count= 1 avg_used= 12,344 block_cnt= 1 chunk_cnt= 1 mod=MemDumpMap [MEMORY] hold= 16,384 used= 8,768 count= 1 avg_used= 8,768 block_cnt= 1 chunk_cnt= 1 mod=[T]ObDSActionAr [MEMORY] hold= 16,384 used= 8,192 count= 1 avg_used= 8,192 block_cnt= 1 chunk_cnt= 1 mod=LogPartInfo [MEMORY] hold= 16,384 used= 8,192 count= 1 avg_used= 8,192 block_cnt= 1 chunk_cnt= 1 mod=LogTenanStatInf [MEMORY] hold= 16,000 used= 15,872 count= 2 avg_used= 7,936 block_cnt= 2 chunk_cnt= 2 mod=LogPartFetCtxMa [MEMORY] hold= 16,000 used= 15,872 count= 2 avg_used= 7,936 block_cnt= 2 chunk_cnt= 1 mod=CommSysVarDefVa [MEMORY] hold= 16,000 used= 15,872 count= 2 avg_used= 7,936 block_cnt= 2 chunk_cnt= 2 mod=Node [MEMORY] hold= 16,000 used= 15,872 count= 2 avg_used= 7,936 block_cnt= 2 chunk_cnt= 2 mod=LogFetchDeadPoo [MEMORY] hold= 16,000 used= 15,872 count= 2 avg_used= 7,936 block_cnt= 2 chunk_cnt= 2 mod=LogSvrStreaMap [MEMORY] hold= 16,000 used= 15,872 count= 2 avg_used= 7,936 block_cnt= 2 chunk_cnt= 2 mod=TrafficMap [MEMORY] hold= 16,000 used= 15,872 count= 2 avg_used= 7,936 block_cnt= 2 chunk_cnt= 2 mod=LSRouterMap [MEMORY] hold= 16,000 used= 15,872 count= 2 avg_used= 7,936 block_cnt= 2 chunk_cnt= 2 mod=SchemaRowKey [MEMORY] hold= 14,016 used= 6,656 count= 97 avg_used= 68 block_cnt= 71 chunk_cnt= 7 mod=Coro [MEMORY] hold= 8,672 used= 8,215 count= 6 avg_used= 1,369 block_cnt= 4 chunk_cnt= 2 mod=LogTempMemory [MEMORY] hold= 8,000 used= 7,936 count= 1 avg_used= 7,936 block_cnt= 1 chunk_cnt= 1 mod=LogTableIdCache [MEMORY] hold= 8,000 used= 7,936 count= 1 avg_used= 7,936 block_cnt= 1 chunk_cnt= 1 mod=LogHbasColIdMap [MEMORY] hold= 8,000 used= 7,936 count= 1 avg_used= 7,936 block_cnt= 1 chunk_cnt= 1 mod=LogRouter [MEMORY] hold= 8,000 used= 7,936 count= 1 avg_used= 7,936 block_cnt= 1 chunk_cnt= 1 mod=DATADICTDB [MEMORY] hold= 8,000 used= 7,936 count= 1 avg_used= 7,936 block_cnt= 1 chunk_cnt= 1 mod=DATADICTTB [MEMORY] hold= 8,000 used= 7,936 count= 1 avg_used= 7,936 block_cnt= 1 chunk_cnt= 1 mod=LogTablSchMetMa [MEMORY] hold= 8,000 used= 7,936 count= 1 avg_used= 7,936 block_cnt= 1 chunk_cnt= 1 mod=LogTableMetaMap [MEMORY] hold= 8,000 used= 7,936 count= 1 avg_used= 7,936 block_cnt= 1 chunk_cnt= 1 mod=LogDatabMetaMap [MEMORY] hold= 8,000 used= 7,936 count= 1 avg_used= 7,936 block_cnt= 1 chunk_cnt= 1 mod=LobAuxMetaMap [MEMORY] hold= 8,000 used= 7,936 count= 1 avg_used= 7,936 block_cnt= 1 chunk_cnt= 1 mod=TLSIDSerialize [MEMORY] hold= 8,000 used= 7,936 count= 1 avg_used= 7,936 block_cnt= 1 chunk_cnt= 1 mod=RouterAsynTask [MEMORY] hold= 8,000 used= 7,936 count= 1 avg_used= 7,936 block_cnt= 1 chunk_cnt= 1 mod=LogTenanStatMap [MEMORY] hold= 8,000 used= 7,936 count= 1 avg_used= 7,936 block_cnt= 1 chunk_cnt= 1 mod=LogFetcArpcResQ [MEMORY] hold= 8,000 used= 7,936 count= 1 avg_used= 7,936 block_cnt= 1 chunk_cnt= 1 mod=LogGlobNorIndCa [MEMORY] hold= 6,032 used= 3,264 count= 34 avg_used= 96 block_cnt= 20 chunk_cnt= 6 mod=PThread [MEMORY] hold= 5,376 used= 5,248 count= 2 avg_used= 2,624 block_cnt= 1 chunk_cnt= 1 mod=RootContext [MEMORY] hold= 4,800 used= 4,656 count= 2 avg_used= 2,328 block_cnt= 2 chunk_cnt= 1 mod=sessid_conn_map [MEMORY] hold= 4,672 used= 1,056 count= 44 avg_used= 24 block_cnt= 26 chunk_cnt= 6 mod=[T]MemoryContex [MEMORY] hold= 3,648 used= 3,584 count= 1 avg_used= 3,584 block_cnt= 1 chunk_cnt= 1 mod=LogMysqlConnect [MEMORY] hold= 3,328 used= 3,072 count= 4 avg_used= 768 block_cnt= 1 chunk_cnt= 1 mod=TGTimer [MEMORY] hold= 1,664 used= 1,536 count= 2 avg_used= 768 block_cnt= 2 chunk_cnt= 2 mod=timer [MEMORY] hold= 1,200 used= 1,136 count= 1 avg_used= 1,136 block_cnt= 1 chunk_cnt= 1 mod=CDCBlockArray [MEMORY] hold= 1,152 used= 1,024 count= 2 avg_used= 512 block_cnt= 2 chunk_cnt= 2 mod=TntResourceMgr [MEMORY] hold= 1,152 used= 1,088 count= 1 avg_used= 1,088 block_cnt= 1 chunk_cnt= 1 mod=memdumpqueue [MEMORY] hold= 192 used= 88 count= 1 avg_used= 88 block_cnt= 1 chunk_cnt= 1 mod=DestKAState [MEMORY] hold= 192 used= 64 count= 2 avg_used= 32 block_cnt= 2 chunk_cnt= 2 mod=Rpc [MEMORY] hold= 80 used= 7 count= 1 avg_used= 7 block_cnt= 1 chunk_cnt= 1 mod=SqlExpr [MEMORY] hold= 734,438,784 used= 732,823,470 count= 868 avg_used= 844,266 mod=SUMMARY [2024-11-15 11:32:00.518263] INFO [LIB] print_usage (ob_tenant_ctx_allocator.cpp:178) [2779521][MemoryDump][T0][Y0-0000000000000000-0-0] [lt=44] [MEMORY] tenant_id= 500 ctx_id= CO_STACK hold= 27,262,976 used= 26,836,992 limit= 9,223,372,036,854,775,807 [MEMORY] idle_size= 0 free_size= 0 [MEMORY] wash_related_chunks= 0 washed_blocks= 0 washed_size= 0 [MEMORY] request_cached_chunk_cnt= 0 [MEMORY] hold= 26,836,992 used= 26,787,072 count= 52 avg_used= 515,136 block_cnt= 52 chunk_cnt= 13 mod=CoStack [MEMORY] hold= 26,836,992 used= 26,787,072 count= 52 avg_used= 515,136 mod=SUMMARY [2024-11-15 11:32:00.518408] INFO [LIB] print_usage (ob_tenant_ctx_allocator.cpp:178) [2779521][MemoryDump][T0][Y0-0000000000000000-0-0] [lt=43] [MEMORY] tenant_id= 500 ctx_id= PKT_NIO hold= 27,492,352 used= 25,396,928 limit= 9,223,372,036,854,775,807 [MEMORY] idle_size= 0 free_size= 0 [MEMORY] wash_related_chunks= 0 washed_blocks= 0 washed_size= 0 [MEMORY] request_cached_chunk_cnt= 0 [MEMORY] hold= 25,396,928 used= 25,251,056 count= 10 avg_used= 2,525,105 block_cnt= 9 chunk_cnt= 9 mod=DEFAULT [MEMORY] hold= 25,396,928 used= 25,251,056 count= 10 avg_used= 2,525,105 mod=SUMMARY [2024-11-15 11:32:00.518502] INFO [LIB] print_usage (ob_tenant_ctx_allocator.cpp:178) [2779521][MemoryDump][T0][Y0-0000000000000000-0-0] [lt=26] [MEMORY] tenant_id= 500 ctx_id= UNEXPECTED_IN_500 hold= 228,585,472 used= 226,492,272 limit= 9,223,372,036,854,775,807 [MEMORY] idle_size= 0 free_size= 0 [MEMORY] wash_related_chunks= 0 washed_blocks= 0 washed_size= 0 [MEMORY] request_cached_chunk_cnt= 0 [MEMORY] hold= 134,238,288 used= 134,217,736 count= 2 avg_used= 67,108,868 block_cnt= 2 chunk_cnt= 2 mod=CACHE_MAP_BKT [MEMORY] hold= 79,646,720 used= 79,630,080 count= 1 avg_used= 79,630,080 block_cnt= 1 chunk_cnt= 1 mod=CACHE_MB_HANDLE [MEMORY] hold= 12,603,392 used= 12,582,920 count= 1 avg_used= 12,582,920 block_cnt= 1 chunk_cnt= 1 mod=CACHE_MAP_LOCK [MEMORY] hold= 3,872 used= 704 count= 44 avg_used= 16 block_cnt= 1 chunk_cnt= 1 mod=ConfigChecker [MEMORY] hold= 226,492,272 used= 226,431,440 count= 48 avg_used= 4,717,321 mod=SUMMARY [2024-11-15 11:32:00.518552] INFO [LIB] operator() (ob_malloc_allocator.cpp:542) [2779521][MemoryDump][T0][Y0-0000000000000000-0-0] [lt=25] [MEMORY] tenant: 1006, limit: 9,223,372,036,854,775,807 hold: 4,214,784 rpc_hold: 0 cache_hold: 0 cache_used: 0 cache_item_count: 0 [MEMORY] ctx_id= DEFAULT_CTX_ID hold_bytes= 4,214,784 limit= 9,223,372,036,854,775,807 [2024-11-15 11:32:00.518587] INFO [LIB] print_usage (ob_tenant_ctx_allocator.cpp:178) [2779521][MemoryDump][T0][Y0-0000000000000000-0-0] [lt=16] [MEMORY] tenant_id= 1006 ctx_id= DEFAULT_CTX_ID hold= 4,214,784 used= 4,214,784 limit= 9,223,372,036,854,775,807 [MEMORY] idle_size= 0 free_size= 0 [MEMORY] wash_related_chunks= 0 washed_blocks= 0 washed_size= 0 [MEMORY] request_cached_chunk_cnt= 0 [MEMORY] hold= 4,214,784 used= 4,194,304 count= 1 avg_used= 4,194,304 block_cnt= 1 chunk_cnt= 1 mod=ObDataDict [MEMORY] hold= 4,214,784 used= 4,194,304 count= 1 avg_used= 4,194,304 mod=SUMMARY [2024-11-15 11:32:00.540949] INFO pn_print_diag_info (group.c:714) [2779602][pnio2][T0][Y0-0000000000000000-0-0] [lt=59] PNIO client_send:0/0, client_queue_time=0, cnt=0, server_send:0/0, server_queue_time=0, cnt=0 [2024-11-15 11:32:00.543873] INFO pn_print_diag_info (group.c:714) [2779599][pnio1][T0][Y0-0000000000000000-0-0] [lt=44] PNIO client_send:0/0, client_queue_time=0, cnt=0, server_send:0/0, server_queue_time=0, cnt=0 [2024-11-15 11:32:00.580168] INFO pn_print_diag_info (group.c:714) [2779600][pnio1][T0][Y0-0000000000000000-0-0] [lt=43] PNIO client_send:0/0, client_queue_time=0, cnt=0, server_send:0/0, server_queue_time=0, cnt=0 [2024-11-15 11:32:00.618505] INFO pn_print_diag_info (group.c:714) [2779603][pnio2][T0][Y0-0000000000000000-0-0] [lt=58] PNIO client_send:0/0, client_queue_time=0, cnt=0, server_send:0/0, server_queue_time=0, cnt=0 [2024-11-15 11:32:00.628337] INFO eloop_run (eloop.c:140) [2779601][pnio2][T0][Y0-0000000000000000-0-0] [lt=73] PNIO [ratelimit] time: 1731641520628333, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007620, add_bytes: 0 [2024-11-15 11:32:00.628430] INFO pn_print_diag_info (group.c:714) [2779601][pnio2][T0][Y0-0000000000000000-0-0] [lt=74] PNIO client_send:0/0, client_queue_time=0, cnt=0, server_send:0/0, server_queue_time=0, cnt=0 [2024-11-15 11:32:00.663018] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779622][RpcIO][T0][Y0-0000000000000000-0-0] [lt=34] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:00.663652] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779623][RpcIO][T0][Y0-0000000000000000-0-0] [lt=37] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:00.664243] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779625][RpcIO][T0][Y0-0000000000000000-0-0] [lt=26] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:00.664764] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779624][RpcIO][T0][Y0-0000000000000000-0-0] [lt=29] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:00.665754] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779629][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=21] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:00.667714] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779631][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=36] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:00.669559] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779627][BatchIO][T0][Y0-0000000000000000-0-0] [lt=37] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:00.671279] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779633][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=28] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:00.685916] INFO pn_print_diag_info (group.c:714) [2779604][pnio2][T0][Y0-0000000000000000-0-0] [lt=46] PNIO client_send:0/0, client_queue_time=0, cnt=0, server_send:0/0, server_queue_time=0, cnt=0 [2024-11-15 11:32:01.263457] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779608][RpcIO][T0][Y0-0000000000000000-0-0] [lt=25] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:01.263565] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779607][RpcIO][T0][Y0-0000000000000000-0-0] [lt=50] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:01.264415] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779610][RpcIO][T0][Y0-0000000000000000-0-0] [lt=29] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:01.264451] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779609][RpcIO][T0][Y0-0000000000000000-0-0] [lt=39] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:01.487506] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779614][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=94] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:01.487883] INFO eloop_run (eloop.c:140) [2779597][pnio1][T0][Y0-0000000000000000-0-0] [lt=18] PNIO [ratelimit] time: 1731641521487879, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007593, add_bytes: 0 [2024-11-15 11:32:01.489895] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779616][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=37] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:01.492534] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779612][BatchIO][T0][Y0-0000000000000000-0-0] [lt=46] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:01.493820] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779618][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=53] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:01.635937] INFO eloop_run (eloop.c:140) [2779601][pnio2][T0][Y0-0000000000000000-0-0] [lt=17] PNIO [ratelimit] time: 1731641521635931, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007598, add_bytes: 0 [2024-11-15 11:32:01.662426] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779622][RpcIO][T0][Y0-0000000000000000-0-0] [lt=60] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:01.663097] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779623][RpcIO][T0][Y0-0000000000000000-0-0] [lt=39] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:01.663108] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779624][RpcIO][T0][Y0-0000000000000000-0-0] [lt=59] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:01.664044] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779625][RpcIO][T0][Y0-0000000000000000-0-0] [lt=35] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:01.665576] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779629][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=38] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:01.667429] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779631][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=45] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:01.669571] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779627][BatchIO][T0][Y0-0000000000000000-0-0] [lt=38] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:01.671284] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779633][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=51] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:02.262942] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779608][RpcIO][T0][Y0-0000000000000000-0-0] [lt=144] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:02.263547] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779607][RpcIO][T0][Y0-0000000000000000-0-0] [lt=59] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:02.264506] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779610][RpcIO][T0][Y0-0000000000000000-0-0] [lt=38] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:02.264536] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779609][RpcIO][T0][Y0-0000000000000000-0-0] [lt=31] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:02.487478] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779614][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=59] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:02.489832] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779616][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=38] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:02.492428] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779612][BatchIO][T0][Y0-0000000000000000-0-0] [lt=32] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:02.493440] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779618][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=35] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:02.495520] INFO eloop_run (eloop.c:140) [2779597][pnio1][T0][Y0-0000000000000000-0-0] [lt=35] PNIO [ratelimit] time: 1731641522495516, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007637, add_bytes: 0 [2024-11-15 11:32:02.643566] INFO eloop_run (eloop.c:140) [2779601][pnio2][T0][Y0-0000000000000000-0-0] [lt=61] PNIO [ratelimit] time: 1731641522643560, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007629, add_bytes: 0 [2024-11-15 11:32:02.663332] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779622][RpcIO][T0][Y0-0000000000000000-0-0] [lt=57] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:02.663845] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779623][RpcIO][T0][Y0-0000000000000000-0-0] [lt=28] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:02.663936] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779624][RpcIO][T0][Y0-0000000000000000-0-0] [lt=39] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:02.665082] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779625][RpcIO][T0][Y0-0000000000000000-0-0] [lt=59] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:02.666658] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779629][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=35] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:02.667746] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779631][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=38] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:02.669681] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779627][BatchIO][T0][Y0-0000000000000000-0-0] [lt=44] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:02.671197] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779633][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=46] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:03.263617] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779607][RpcIO][T0][Y0-0000000000000000-0-0] [lt=38] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:03.263703] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779608][RpcIO][T0][Y0-0000000000000000-0-0] [lt=55] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:03.264283] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779610][RpcIO][T0][Y0-0000000000000000-0-0] [lt=31] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:03.264505] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779609][RpcIO][T0][Y0-0000000000000000-0-0] [lt=28] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:03.487586] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779614][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=78] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:03.489930] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779616][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=38] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:03.492247] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779612][BatchIO][T0][Y0-0000000000000000-0-0] [lt=36] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:03.494267] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779618][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=80] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:03.503167] INFO eloop_run (eloop.c:140) [2779597][pnio1][T0][Y0-0000000000000000-0-0] [lt=43] PNIO [ratelimit] time: 1731641523503162, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007646, add_bytes: 0 [2024-11-15 11:32:03.622735] INFO [TLOG.FETCHER] print_stat (ob_log_fetch_stream_container_mgr.cpp:181) [2779653][][T0][Y0-0000000000000000-0-0] [lt=10] [STAT] [FS_CONTAINER_POOL] USED=1 FREE=0 FIXED=16 DYNAMIC=0 [2024-11-15 11:32:03.622785] INFO [TLOG.FETCHER] print_stat (ob_log_fetch_stream_pool.cpp:76) [2779653][][T0][Y0-0000000000000000-0-0] [lt=49] [STAT] [FETCH_STREAM_POOL] USED=1 FREE=0 FIXED=16 DYNAMIC=0 [2024-11-15 11:32:03.622798] INFO [TLOG.FETCHER] print_stat (ob_log_fetch_log_rpc.cpp:1400) [2779653][][T0][Y0-0000000000000000-0-0] [lt=12] [STAT] [RPC_RESULT_POOL] USED=0 FREE=1 FIXED=16 DYNAMIC=0 [2024-11-15 11:32:03.622886] INFO [TLOG.FETCHER] do_stat (ob_log_ls_fetch_stream.cpp:303) [2779653][][T0][Y0-0000000000000000-0-0] [lt=9] [STAT] [FETCH_STREAM] stream="172.19.70.221:2882"(0x7f171280faa0:SYS_LS)({tenant_id:1006, ls_id:{id:1}})(FETCHED_LOG:1.20MB) traffic=0.00B/sec log_size=0 size/rpc=0.00B log_cnt/rpc=0 rpc_cnt=0(0/sec) single_rpc=0(0/sec)(upper_limit=0(0/sec),max_log=0(0/sec),no_log=0(0/sec),max_result=0(0/sec)) rpc_time=0 svr_time=(queue=0,process=0) net_time=(l2s=0,s2l=0) cb_time=0 handle_rpc_time=0 flush_time=0 read_log_time=0(log_entry=0,trans=0) trans_count=0 trans_size=0.00B [2024-11-15 11:32:03.622971] INFO [TLOG.FETCHER] operator() (ob_log_fetch_stream_container_mgr.cpp:230) [2779653][][T0][Y0-0000000000000000-0-0] [lt=58] [STAT] [FETCH_STREAM] TENANT=1006, TRAFFIC=0.00B/sec [2024-11-15 11:32:03.623034] INFO [TLOG.FETCHER] print_fetcher_stat_ (ob_log_fetcher.cpp:842) [2779653][][T0][Y0-0000000000000000-0-0] [lt=11] [STAT] [FETCHER](upper_limit="[2024-11-14 22:01:17.317179]", dml_progress_limit_sec=30, fetcher_delay="[13:31:16.305838]") [2024-11-15 11:32:03.623123] INFO [TLOG.FETCHER] print_k_slowest_ls (ob_log_ls_fetch_mgr.cpp:430) [2779653][][T0][Y0-0000000000000000-0-0] [lt=14] [STAT] slow fetch progress start. ls_count=1/1 [2024-11-15 11:32:03.623152] INFO [TLOG.FETCHER] print_fetch_progress (ob_log_ls_fetch_ctx.cpp:1545) [2779653][][T0][Y0-0000000000000000-0-0] [lt=9] [STAT] slow fetch progress idx=0/1 tls_id={tenant_id:1006, ls_id:{id:1}} mod=["172.19.70.221:2882"](0x7f171280faa0) discarded=0 delay=[13:31:16.305943] tps=0.00 progress={next_lsn:{lsn:11166388}, log_progress:"[2024-11-14 22:00:47.317179]", log_touch_tstamp:"[2024-11-15 09:58:09.843280]"} [2024-11-15 11:32:03.623176] INFO [TLOG.FETCHER] print_k_slowest_ls (ob_log_ls_fetch_mgr.cpp:437) [2779653][][T0][Y0-0000000000000000-0-0] [lt=11] [STAT] slow fetch progress end [2024-11-15 11:32:03.623191] INFO [TLOG.FETCHER] print_k_slowest_ls (ob_log_ls_fetch_mgr.cpp:442) [2779653][][T0][Y0-0000000000000000-0-0] [lt=15] [STAT] slow dispatch progress start. ls_count=1/1 [2024-11-15 11:32:03.623209] INFO [TLOG.FETCHER] print_dispatch_progress (ob_log_ls_fetch_ctx.cpp:1567) [2779653][][T0][Y0-0000000000000000-0-0] [lt=8] [STAT] slow dispatch progress idx=0/1 tls_id={tenant_id:1006, ls_id:{id:1}} delay=[13:31:16.305944] pending_task(queue/total)=0/0 dispatch_progress=[2024-11-14 22:00:47.317178] last_dispatch_log_lsn=18446744073709551615 next_task=INVALID next_trans(log_lsn=18446744073709551615,committed=0,ready_to_commit=0,global_version=[1970-01-01 08:00:00.000000]) checkpoint=[1970-01-01 08:00:00.000000] [2024-11-15 11:32:03.623221] INFO [TLOG.FETCHER] print_k_slowest_ls (ob_log_ls_fetch_mgr.cpp:449) [2779653][][T0][Y0-0000000000000000-0-0] [lt=11] [STAT] slow dispatch progress end [2024-11-15 11:32:03.623233] INFO [TLOG.PARSER] stat_ignored_tps (ob_log_cluster_id_filter.cpp:130) [2779653][][T0][Y0-0000000000000000-0-0] [lt=7] [TPS_STAT] CLUSTER_ID_IGNORED_PART_TPS=0.000 [2024-11-15 11:32:03.651169] INFO eloop_run (eloop.c:140) [2779601][pnio2][T0][Y0-0000000000000000-0-0] [lt=72] PNIO [ratelimit] time: 1731641523651164, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007604, add_bytes: 0 [2024-11-15 11:32:03.663529] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779622][RpcIO][T0][Y0-0000000000000000-0-0] [lt=71] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:03.663865] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779623][RpcIO][T0][Y0-0000000000000000-0-0] [lt=39] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:03.664036] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779624][RpcIO][T0][Y0-0000000000000000-0-0] [lt=39] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:03.664222] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779625][RpcIO][T0][Y0-0000000000000000-0-0] [lt=51] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:03.665472] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779629][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=34] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:03.667851] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779631][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=41] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:03.669346] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779627][BatchIO][T0][Y0-0000000000000000-0-0] [lt=37] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:03.671204] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779633][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=55] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:03.832151] INFO [TLOG] update_zone_cache_ (ob_log_all_svr_cache.cpp:425) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=45] [STAT] [ALL_ZONE] INDEX=0/1 ZONE=zone1 REGION=sys_region STORAGE_TYPE=LOCAL VERSION=1127 [2024-11-15 11:32:03.832224] INFO [TLOG] update_zone_cache_ (ob_log_all_svr_cache.cpp:450) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=65] [STAT] [ALL_ZONE] INDEX=0/1 ZONE=zone1 ZONE_TYPE=ReadWrite VERSION=1127 [2024-11-15 11:32:03.832240] INFO [TLOG] update_zone_cache_ (ob_log_all_svr_cache.cpp:454) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=15] [STAT] [ALL_ZONE] COUNT=1 VERSION=1127 [2024-11-15 11:32:03.832264] INFO [TLOG] purge_stale_zone_records_ (ob_log_all_svr_cache.cpp:660) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=11] [STAT] [ALL_ZONE] [PURGE] PURGE_COUNT=0 CUR_COUNT=1 VERSION=1127 [2024-11-15 11:32:03.832909] INFO [TLOG] update_server_cache_ (ob_log_all_svr_cache.cpp:521) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=14] [STAT] [ALL_SERVER_LIST] INDEX=0/1 SERVER_ID=1 SERVER="172.19.70.221:2882" STATUS=1(ACTIVE) ZONE=zone1 REGION=sys_region(LOW) VERSION=1126 [2024-11-15 11:32:03.832964] INFO [TLOG] update_server_cache_ (ob_log_all_svr_cache.cpp:525) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=53] [STAT] [ALL_SERVER_LIST] COUNT=1 VERSION=1126 [2024-11-15 11:32:03.832991] INFO [TLOG] purge_stale_records_ (ob_log_all_svr_cache.cpp:646) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=12] [STAT] [ALL_SERVER_LIST] [PURGE] PURGE_COUNT=0 CUR_COUNT=1 VERSION=1126 [2024-11-15 11:32:03.833888] INFO [TLOG.FETCHER] add_server_or_update (ob_ls_server_list.cpp:87) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=11] add_server_or_update(next_svr_index=0, svr_items=[{server:"172.19.70.221:2882", ranges:[LSN:{0, 73456328}], priority:[is_meta_table:1 region:LOW, replica:FULL, is_leader:1]}]) [2024-11-15 11:32:03.833941] INFO [TLOG] get_ls_svr_list_ (ob_log_route_service.cpp:835) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=45] get_ls_svr_list_ done(ret=0, ret="OB_SUCCESS", router_key={cluster_id:1730442730, tenant_id:1006, ls_id:{id:1}}, svr_list={next_svr_index:0, svr_num:1, svr_items:[{server:"172.19.70.221:2882", ranges:[LSN:{0, 73456328}], priority:[is_meta_table:1 region:LOW, replica:FULL, is_leader:1]}]}, tg_id_=259) [2024-11-15 11:32:04.263280] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779607][RpcIO][T0][Y0-0000000000000000-0-0] [lt=47] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:04.263440] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779608][RpcIO][T0][Y0-0000000000000000-0-0] [lt=29] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:04.264134] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779610][RpcIO][T0][Y0-0000000000000000-0-0] [lt=30] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:04.264569] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779609][RpcIO][T0][Y0-0000000000000000-0-0] [lt=40] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:04.486814] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779614][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=47] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:04.489923] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779616][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=39] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:04.492283] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779612][BatchIO][T0][Y0-0000000000000000-0-0] [lt=37] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:04.494210] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779618][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=85] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:04.510717] INFO eloop_run (eloop.c:140) [2779597][pnio1][T0][Y0-0000000000000000-0-0] [lt=43] PNIO [ratelimit] time: 1731641524510713, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007551, add_bytes: 0 [2024-11-15 11:32:04.658790] INFO eloop_run (eloop.c:140) [2779601][pnio2][T0][Y0-0000000000000000-0-0] [lt=70] PNIO [ratelimit] time: 1731641524658785, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007621, add_bytes: 0 [2024-11-15 11:32:04.663606] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779622][RpcIO][T0][Y0-0000000000000000-0-0] [lt=41] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:04.663623] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779623][RpcIO][T0][Y0-0000000000000000-0-0] [lt=33] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:04.663637] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779624][RpcIO][T0][Y0-0000000000000000-0-0] [lt=50] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:04.664241] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779625][RpcIO][T0][Y0-0000000000000000-0-0] [lt=28] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:04.666402] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779629][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=37] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:04.667802] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779631][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=48] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:04.669471] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779627][BatchIO][T0][Y0-0000000000000000-0-0] [lt=96] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:04.671202] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779633][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=36] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:05.262715] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779607][RpcIO][T0][Y0-0000000000000000-0-0] [lt=48] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:05.263444] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779608][RpcIO][T0][Y0-0000000000000000-0-0] [lt=38] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:05.263521] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779610][RpcIO][T0][Y0-0000000000000000-0-0] [lt=29] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:05.264644] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779609][RpcIO][T0][Y0-0000000000000000-0-0] [lt=38] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:05.487973] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779614][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=62] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:05.490026] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779616][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=54] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:05.491894] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779612][BatchIO][T0][Y0-0000000000000000-0-0] [lt=68] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:05.493760] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779618][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=29] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:05.518353] INFO eloop_run (eloop.c:140) [2779597][pnio1][T0][Y0-0000000000000000-0-0] [lt=42] PNIO [ratelimit] time: 1731641525518347, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007634, add_bytes: 0 [2024-11-15 11:32:05.663665] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779622][RpcIO][T0][Y0-0000000000000000-0-0] [lt=54] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:05.663654] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779623][RpcIO][T0][Y0-0000000000000000-0-0] [lt=45] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:05.664272] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779625][RpcIO][T0][Y0-0000000000000000-0-0] [lt=20] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:05.664300] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779624][RpcIO][T0][Y0-0000000000000000-0-0] [lt=46] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:05.666411] INFO eloop_run (eloop.c:140) [2779601][pnio2][T0][Y0-0000000000000000-0-0] [lt=55] PNIO [ratelimit] time: 1731641525666407, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007622, add_bytes: 0 [2024-11-15 11:32:05.666473] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779629][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=36] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:05.667803] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779631][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=37] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:05.669536] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779627][BatchIO][T0][Y0-0000000000000000-0-0] [lt=40] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:05.671293] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779633][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=37] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:06.263614] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779608][RpcIO][T0][Y0-0000000000000000-0-0] [lt=48] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:06.263901] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779607][RpcIO][T0][Y0-0000000000000000-0-0] [lt=72] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:06.264332] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779610][RpcIO][T0][Y0-0000000000000000-0-0] [lt=14] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:06.264583] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779609][RpcIO][T0][Y0-0000000000000000-0-0] [lt=38] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:06.487987] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779614][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=51] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:06.489949] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779616][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=54] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:06.491687] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779612][BatchIO][T0][Y0-0000000000000000-0-0] [lt=64] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:06.493664] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779618][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=33] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:06.525960] INFO eloop_run (eloop.c:140) [2779597][pnio1][T0][Y0-0000000000000000-0-0] [lt=72] PNIO [ratelimit] time: 1731641526525956, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007609, add_bytes: 0 [2024-11-15 11:32:06.663757] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779622][RpcIO][T0][Y0-0000000000000000-0-0] [lt=53] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:06.663797] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779623][RpcIO][T0][Y0-0000000000000000-0-0] [lt=75] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:06.664124] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779625][RpcIO][T0][Y0-0000000000000000-0-0] [lt=26] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:06.664370] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779624][RpcIO][T0][Y0-0000000000000000-0-0] [lt=24] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:06.666418] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779629][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=30] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:06.667739] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779631][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=23] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:06.669649] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779627][BatchIO][T0][Y0-0000000000000000-0-0] [lt=39] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:06.671338] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779633][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=102] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:06.674030] INFO eloop_run (eloop.c:140) [2779601][pnio2][T0][Y0-0000000000000000-0-0] [lt=43] PNIO [ratelimit] time: 1731641526674026, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007619, add_bytes: 0 [2024-11-15 11:32:07.263735] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779610][RpcIO][T0][Y0-0000000000000000-0-0] [lt=29] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:07.263797] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779608][RpcIO][T0][Y0-0000000000000000-0-0] [lt=59] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:07.264002] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779607][RpcIO][T0][Y0-0000000000000000-0-0] [lt=30] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:07.264584] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779609][RpcIO][T0][Y0-0000000000000000-0-0] [lt=39] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:07.487409] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779614][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=57] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:07.489721] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779616][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=51] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:07.492590] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779612][BatchIO][T0][Y0-0000000000000000-0-0] [lt=38] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:07.493613] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779618][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=51] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:07.533585] INFO eloop_run (eloop.c:140) [2779597][pnio1][T0][Y0-0000000000000000-0-0] [lt=40] PNIO [ratelimit] time: 1731641527533580, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007624, add_bytes: 0 [2024-11-15 11:32:07.663829] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779623][RpcIO][T0][Y0-0000000000000000-0-0] [lt=30] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:07.663871] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779622][RpcIO][T0][Y0-0000000000000000-0-0] [lt=41] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:07.664112] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779624][RpcIO][T0][Y0-0000000000000000-0-0] [lt=55] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:07.664126] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779625][RpcIO][T0][Y0-0000000000000000-0-0] [lt=38] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:07.666413] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779629][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=33] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:07.667850] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779631][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=42] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:07.669648] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779627][BatchIO][T0][Y0-0000000000000000-0-0] [lt=36] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:07.671474] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779633][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=33] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:07.681656] INFO eloop_run (eloop.c:140) [2779601][pnio2][T0][Y0-0000000000000000-0-0] [lt=36] PNIO [ratelimit] time: 1731641527681649, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007623, add_bytes: 0 [2024-11-15 11:32:08.262622] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779607][RpcIO][T0][Y0-0000000000000000-0-0] [lt=29] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:08.263766] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779609][RpcIO][T0][Y0-0000000000000000-0-0] [lt=39] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:08.263855] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779608][RpcIO][T0][Y0-0000000000000000-0-0] [lt=46] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:08.264453] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779610][RpcIO][T0][Y0-0000000000000000-0-0] [lt=63] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:08.274588] INFO [COMMON] replace_map (ob_kv_storecache.cpp:766) [2779520][KVCacheRep][T0][Y0-0000000000000000-0-0] [lt=43] replace map num details(ret=0, replace_node_count=0, map_once_replace_num_=100000, map_replace_skip_count_=5) [2024-11-15 11:32:08.488405] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779614][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=46] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:08.489405] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779616][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=39] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:08.492665] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779612][BatchIO][T0][Y0-0000000000000000-0-0] [lt=55] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:08.493632] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779618][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=43] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:08.541189] INFO eloop_run (eloop.c:140) [2779597][pnio1][T0][Y0-0000000000000000-0-0] [lt=68] PNIO [ratelimit] time: 1731641528541184, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007604, add_bytes: 0 [2024-11-15 11:32:08.607191] INFO [COMMON] compute_tenant_wash_size (ob_kvcache_store.cpp:1134) [2779519][KVCacheWash][T0][Y0-0000000000000000-0-0] [lt=23] Wash compute wash size(is_wash_valid=false, sys_total_wash_size=-12172548505, global_cache_size=0, tenant_max_wash_size=0, tenant_min_wash_size=0, tenant_ids_=[cnt:2, 1, 500]) [2024-11-15 11:32:08.607846] INFO print_current_status (ob_kvcache_hazard_version.cpp:328) [2779519][KVCacheWash][T0][Y0-0000000000000000-0-0] [lt=62] [KVCACHE-HAZARD] hazard version status info: current_version: 0 | min_version= 0 | total_nodes_count: 0 | [MEMORY] ctx_id= DEFAULT_CTX_ID hold_bytes= 4,214,784 limit= 9,223,372,036,854,775,807 [2024-11-15 11:32:08.662982] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779622][RpcIO][T0][Y0-0000000000000000-0-0] [lt=72] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:08.663886] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779623][RpcIO][T0][Y0-0000000000000000-0-0] [lt=47] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:08.664044] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779625][RpcIO][T0][Y0-0000000000000000-0-0] [lt=29] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:08.664158] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779624][RpcIO][T0][Y0-0000000000000000-0-0] [lt=28] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:08.666338] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779629][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=51] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:08.666959] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779631][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=38] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:08.669700] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779627][BatchIO][T0][Y0-0000000000000000-0-0] [lt=53] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:08.671466] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779633][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=37] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:08.689259] INFO eloop_run (eloop.c:140) [2779601][pnio2][T0][Y0-0000000000000000-0-0] [lt=52] PNIO [ratelimit] time: 1731641528689254, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007605, add_bytes: 0 [2024-11-15 11:32:08.835632] INFO [TLOG] update_zone_cache_ (ob_log_all_svr_cache.cpp:425) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=34] [STAT] [ALL_ZONE] INDEX=0/1 ZONE=zone1 REGION=sys_region STORAGE_TYPE=LOCAL VERSION=1128 [2024-11-15 11:32:08.835690] INFO [TLOG] update_zone_cache_ (ob_log_all_svr_cache.cpp:450) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=51] [STAT] [ALL_ZONE] INDEX=0/1 ZONE=zone1 ZONE_TYPE=ReadWrite VERSION=1128 [2024-11-15 11:32:08.835703] INFO [TLOG] update_zone_cache_ (ob_log_all_svr_cache.cpp:454) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=12] [STAT] [ALL_ZONE] COUNT=1 VERSION=1128 [2024-11-15 11:32:08.835735] INFO [TLOG] purge_stale_zone_records_ (ob_log_all_svr_cache.cpp:660) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=8] [STAT] [ALL_ZONE] [PURGE] PURGE_COUNT=0 CUR_COUNT=1 VERSION=1128 [2024-11-15 11:32:08.836297] INFO [TLOG] update_server_cache_ (ob_log_all_svr_cache.cpp:521) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=10] [STAT] [ALL_SERVER_LIST] INDEX=0/1 SERVER_ID=1 SERVER="172.19.70.221:2882" STATUS=1(ACTIVE) ZONE=zone1 REGION=sys_region(LOW) VERSION=1127 [2024-11-15 11:32:08.836332] INFO [TLOG] update_server_cache_ (ob_log_all_svr_cache.cpp:525) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=34] [STAT] [ALL_SERVER_LIST] COUNT=1 VERSION=1127 [2024-11-15 11:32:08.836352] INFO [TLOG] purge_stale_records_ (ob_log_all_svr_cache.cpp:646) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=9] [STAT] [ALL_SERVER_LIST] [PURGE] PURGE_COUNT=0 CUR_COUNT=1 VERSION=1127 [2024-11-15 11:32:08.837307] INFO [TLOG.FETCHER] add_server_or_update (ob_ls_server_list.cpp:87) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=11] add_server_or_update(next_svr_index=0, svr_items=[{server:"172.19.70.221:2882", ranges:[LSN:{0, 73461837}], priority:[is_meta_table:1 region:LOW, replica:FULL, is_leader:1]}]) [2024-11-15 11:32:08.837358] INFO [TLOG] get_ls_svr_list_ (ob_log_route_service.cpp:835) [2779620][LogRouter][T0][Y0-0000000000000000-0-0] [lt=43] get_ls_svr_list_ done(ret=0, ret="OB_SUCCESS", router_key={cluster_id:1730442730, tenant_id:1006, ls_id:{id:1}}, svr_list={next_svr_index:0, svr_num:1, svr_items:[{server:"172.19.70.221:2882", ranges:[LSN:{0, 73461837}], priority:[is_meta_table:1 region:LOW, replica:FULL, is_leader:1]}]}, tg_id_=259) [2024-11-15 11:32:09.263524] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779607][RpcIO][T0][Y0-0000000000000000-0-0] [lt=47] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:09.263726] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779609][RpcIO][T0][Y0-0000000000000000-0-0] [lt=83] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:09.263752] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779608][RpcIO][T0][Y0-0000000000000000-0-0] [lt=28] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:09.264567] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779610][RpcIO][T0][Y0-0000000000000000-0-0] [lt=36] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:09.488452] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779614][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=32] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:09.489469] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779616][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=20] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:09.492680] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779612][BatchIO][T0][Y0-0000000000000000-0-0] [lt=51] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:09.493421] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779618][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=53] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:09.548838] INFO eloop_run (eloop.c:140) [2779597][pnio1][T0][Y0-0000000000000000-0-0] [lt=47] PNIO [ratelimit] time: 1731641529548830, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007646, add_bytes: 0 [2024-11-15 11:32:09.663023] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779622][RpcIO][T0][Y0-0000000000000000-0-0] [lt=83] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:09.663641] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779623][RpcIO][T0][Y0-0000000000000000-0-0] [lt=51] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:09.664157] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779624][RpcIO][T0][Y0-0000000000000000-0-0] [lt=28] [RPC EASY STAT](log_str=conn count=1/0, request done=1/0, request doing=0/0) [2024-11-15 11:32:09.665111] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779625][RpcIO][T0][Y0-0000000000000000-0-0] [lt=31] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:09.666407] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779629][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=54] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:09.667230] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779631][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=35] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:09.669406] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779627][BatchIO][T0][Y0-0000000000000000-0-0] [lt=57] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:09.671552] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779633][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=28] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:09.696861] INFO eloop_run (eloop.c:140) [2779601][pnio2][T0][Y0-0000000000000000-0-0] [lt=68] PNIO [ratelimit] time: 1731641529696857, bytes: 0, bw: 0.000000 MB/s, add_ts: 1007603, add_bytes: 0 [2024-11-15 11:32:10.162728] INFO [TLOG] add_ls_and_fetch_until_the_progress_is_reached (ob_log_meta_data_fetcher.cpp:196) [2779504][][T0][Y0-0000000000000000-0-0] [lt=7] ObLogFetcher check_progress is less than end_timestamp(tenant_id=1006, cur_progress=1731592847317178999, end_tstamp_ns=1731635887247529000) [2024-11-15 11:32:10.162815] INFO [TLOG] print_stat_info (ob_log_task_pool.h:266) [2779504][][T0][Y0-0000000000000000-0-0] [lt=85] [STAT] [TRANS_TASK_POOL] OUT=0/1000 FIXED=0/1000 DYNAMIC=0 PAGE_POOL=1000/1000 [2024-11-15 11:32:10.263491] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779608][RpcIO][T0][Y0-0000000000000000-0-0] [lt=30] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:10.263568] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779607][RpcIO][T0][Y0-0000000000000000-0-0] [lt=50] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:10.264023] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779610][RpcIO][T0][Y0-0000000000000000-0-0] [lt=50] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:10.264783] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779609][RpcIO][T0][Y0-0000000000000000-0-0] [lt=30] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:10.486848] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779614][MysqlIO][T0][Y0-0000000000000000-0-0] [lt=50] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:10.489569] INFO [RPC.FRAME] mysql_easy_timer_cb (ob_net_easy.cpp:676) [2779616][MysqlUnix][T0][Y0-0000000000000000-0-0] [lt=41] [MYSQL EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:10.492683] INFO [RPC.FRAME] batch_rpc_easy_timer_cb (ob_net_easy.cpp:641) [2779612][BatchIO][T0][Y0-0000000000000000-0-0] [lt=41] [BATCH_RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:10.494474] INFO [RPC.FRAME] rpc_easy_timer_cb (ob_net_easy.cpp:603) [2779618][RpcUnix][T0][Y0-0000000000000000-0-0] [lt=40] [RPC EASY STAT](log_str=conn count=0/0, request done=0/0, request doing=0/0) [2024-11-15 11:32:10.536643] INFO [COMMON] generate_mod_stat_task (memory_dump.cpp:220) [2779521][MemoryDump][T0][Y0-0000000000000000-0-0] [lt=19] task info(*task={type_:2, dump_all_:false, p_context_:null, slot_idx_:0, dump_tenant_ctx_:false, tenant_id_:0, ctx_id_:0, p_chunk_:null}) [2024-11-15 11:32:10.536765] INFO [COMMON] handle (memory_dump.cpp:552) [2779521][MemoryDump][T0][Y0-0000000000000000-0-0] [lt=94] handle dump task(task={type_:2, dump_all_:false, p_context_:null, slot_idx_:0, dump_tenant_ctx_:false, tenant_id_:0, ctx_id_:0, p_chunk_:null}) [2024-11-15 11:32:10.537059] INFO [COMMON] update_check_range (ob_sql_mem_leak_checker.cpp:62) [2779521][MemoryDump][T0][Y0-0000000000000000-0-0] [lt=29] update_check_range(min_check_version=561, max_check_version=562, global_version=563)