[root@dbatest-9-21 log]# [root@dbatest-9-21 log]# [root@dbatest-9-21 log]# tail -200 /tmp/observer.log | grep YB42AC160915 [2023-05-19 10:18:43.972654] INFO [ARCHIVE] update_log_fetch_task_ (ob_archive_fetcher.cpp:753) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=20] back fill log fetch task succ(fetch_task={tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269479795}, unfinished_data_size:4121229, max_scn:{val:1684462723841028770}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479673}, end_offset:{lsn:11269479795}, max_scn:{val:1684462723841028770}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7394606030}, this:0x7f739d8e0c30}) [2023-05-19 10:18:43.972671] INFO [ARCHIVE] push_fetch_log (ob_ls_task.cpp:709) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=16] print push_fetch_log(task={tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269479795}, unfinished_data_size:4121229, max_scn:{val:1684462723841028770}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479673}, end_offset:{lsn:11269479795}, max_scn:{val:1684462723841028770}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7394606030}, this:0x7f739d8e0c30}, this={has_encount_error:false, is_worm:false, max_archived_info:{offset:{lsn:11269479307}, scn:{val:1684462723337674302}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, max_seq_log_offset:{lsn:11273601024}, max_fetch_info:{offset:{lsn:11269479673}, scn:{val:1684462723740487874}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, piece_min_lsn:{lsn:10334134272}, archive_file_id:168, archive_file_offset:62983051, piece_dir_exist:true, wait_send_task_count:1, tasks:{tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269479795}, unfinished_data_size:4121229, max_scn:{val:1684462723841028770}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479673}, end_offset:{lsn:11269479795}, max_scn:{val:1684462723841028770}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7394606030}, this:0x7f739d8e0c30}, {tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269479795}, unfinished_data_size:4121229, max_scn:{val:1684462723841028770}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479673}, end_offset:{lsn:11269479795}, max_scn:{val:1684462723841028770}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7394606030}, this:0x7f739d8e0c30}}) [2023-05-19 10:18:43.972719] INFO [ARCHIVE] submit_fetch_log_ (ob_archive_fetcher.cpp:785) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=47] push fetch log succ(id={id:1001}, &task=0x7f739d8e0c30) [2023-05-19 10:18:43.972723] INFO [ARCHIVE] handle_log_fetch_task_ (ob_archive_fetcher.cpp:398) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=5] handle log fetch task succ(id={id:1001}) [2023-05-19 10:18:43.972728] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:333) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=4] handle task succ(id={id:1001}) [2023-05-19 10:18:43.972733] INFO [ARCHIVE] get_sorted_fetch_log (ob_ls_task.cpp:188) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=4] the same file(tuple={offset:{lsn:11269479673}, scn:{val:1684462723740487874}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, tmp_task={tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269479795}, unfinished_data_size:4121229, max_scn:{val:1684462723841028770}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479673}, end_offset:{lsn:11269479795}, max_scn:{val:1684462723841028770}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7394606030}, this:0x7f739d8e0c30}) [2023-05-19 10:18:43.972751] INFO [ARCHIVE] get_sorted_fetch_log_ (ob_archive_fetcher.cpp:886) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=18] get sorted fetch log succ(task={tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269479795}, unfinished_data_size:4121229, max_scn:{val:1684462723841028770}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479673}, end_offset:{lsn:11269479795}, max_scn:{val:1684462723841028770}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7394606030}, this:0x7f739d8e0c30}) [2023-05-19 10:18:43.972767] INFO [ARCHIVE] submit_send_task_ (ob_archive_fetcher.cpp:938) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=15] submit send task succ(send_task=0x7f7394606030) [2023-05-19 10:18:43.972774] INFO [ARCHIVE] submit_residual_log_fetch_task_ (ob_archive_fetcher.cpp:927) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=6] submit residual log fetch task succ(&task=0x7f739d8e0c30) [2023-05-19 10:18:43.972780] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:341) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=3] try consume task status succ(id={id:1001}) [2023-05-19 10:18:43.972873] INFO [ARCHIVE] get_commit_index_ (ob_archive_sequencer.cpp:259) [86301][T1002_ArcSeq][T1002][YB42AC160915-0005FBC6CE764D59-0-0] [lt=4] get commit index succ [2023-05-19 10:18:43.973079] INFO [ARCHIVE] get_commit_index_ (ob_archive_sequencer.cpp:259) [86301][T1002_ArcSeq][T1002][YB42AC160915-0005FBC6CE764D59-0-0] [lt=5] get commit index succ [2023-05-19 10:18:43.985726] INFO [ARCHIVE] push_log_ (ob_archive_sender.cpp:735) [208151][T1008_ArcSender][T1008][YB42AC160915-0005FBC6DD264D59-0-0] [lt=13] push log succ(id={id:1}) [2023-05-19 10:18:43.985737] INFO [ARCHIVE] archive_log_ (ob_archive_sender.cpp:623) [208151][T1008_ArcSender][T1008][YB42AC160915-0005FBC6DD264D59-0-0] [lt=12] finish task succ(id={id:1}) [2023-05-19 10:18:43.985743] INFO [ARCHIVE] handle (ob_archive_sender.cpp:467) [208151][T1008_ArcSender][T1008][YB42AC160915-0005FBC6DD264D59-0-0] [lt=6] archive log succ(id={id:1}) [2023-05-19 10:18:43.985753] INFO [ARCHIVE] update_archive_progress (ob_ls_task.cpp:376) [208151][T1008_ArcSender][T1008][YB42AC160915-0005FBC6DD264D59-0-0] [lt=5] update archive progress succ(ret=0, id_={id:1001}, station={key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, tuple={offset:{lsn:593558428}, scn:{val:1684462723337873819}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}}, file_id=9, file_offset=56720284) [2023-05-19 10:18:43.985771] INFO [ARCHIVE] update_archive_progress (ob_ls_task.cpp:376) [208151][T1008_ArcSender][T1008][YB42AC160915-0005FBC6DD264D59-0-0] [lt=15] update archive progress succ(ret=0, id_={id:1}, station={key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, tuple={offset:{lsn:654056436}, scn:{val:1684462723337873819}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}}, file_id=10, file_offset=50113524) [2023-05-19 10:18:43.998599] INFO [ARCHIVE] push_log_ (ob_archive_sender.cpp:735) [86303][T1002_ArcSender][T1002][YB42AC160915-0005FBC6CE964D59-0-0] [lt=9] push log succ(id={id:1001}) [2023-05-19 10:18:43.998610] INFO [ARCHIVE] archive_log_ (ob_archive_sender.cpp:623) [86303][T1002_ArcSender][T1002][YB42AC160915-0005FBC6CE964D59-0-0] [lt=12] finish task succ(id={id:1001}) [2023-05-19 10:18:43.998615] INFO [ARCHIVE] handle (ob_archive_sender.cpp:467) [86303][T1002_ArcSender][T1002][YB42AC160915-0005FBC6CE964D59-0-0] [lt=5] archive log succ(id={id:1001}) [2023-05-19 10:18:44.006432] INFO [COMMON] advance_clock (ob_fifo_arena.cpp:346) [208142][T1008_TenantWea][T1007][YB42AC160915-0005FBC6F5C931E4-0-0] [lt=44] current clock is (clock_=5397546400, max_seq_=5397546400, mem_limit=219362092, cur_mem_hold=102760448, attr_.tenant_id_=1007) [2023-05-19 10:18:44.006924] WDIAG [STORAGE.TRANS] set_hash_holder (ob_lock_wait_mgr.cpp:152) [208212][T1007_PLSSer][T1007][YB42AC160915-0005FBC6DF39E445-0-0] [lt=8][errcode=-4017] set hash holder error(ret=-4017, ret="OB_ENTRY_EXIST", hash=4345340486999059591, tx_id={txid:10006174}) [2023-05-19 10:18:44.006956] INFO [DETECT] maintain_deadlock_info_when_end_stmt (ob_trans_deadlock_adapter.cpp:644) [208212][T1007_PLSSer][T1007][YB42AC160915-0005FBC6DF39E445-0-0] [lt=13] inner session no need register to deadlock(step=2, ret=0, ret="OB_SUCCESS", exec_ctx.get_errcode()=0, exec_ctx.get_errcode()="OB_SUCCESS", session={this:0x7f73aa2880d8, id:1, tenant:"sys", tenant_id:1, effective_tenant:"sys", effective_tenant_id:1007, database:"oceanbase", user:"root@%", consistency_level:3, session_state:0, autocommit:true, tx:0x7f7392ef8aa0}, desc=NULL, is_rollback=false, conflict_txs=[]) [2023-05-19 10:18:44.007088] INFO [DETECT] maintain_deadlock_info_when_end_stmt (ob_trans_deadlock_adapter.cpp:644) [208212][T1007_PLSSer][T1007][YB42AC160915-0005FBC6DF39E445-0-0] [lt=14] inner session no need register to deadlock(step=2, ret=0, ret="OB_SUCCESS", exec_ctx.get_errcode()=0, exec_ctx.get_errcode()="OB_SUCCESS", session={this:0x7f73aa2880d8, id:1, tenant:"sys", tenant_id:1, effective_tenant:"sys", effective_tenant_id:1007, database:"oceanbase", user:"root@%", consistency_level:3, session_state:0, autocommit:true, tx:0x7f7392ef8aa0}, desc=NULL, is_rollback=false, conflict_txs=[]) [2023-05-19 10:18:44.028327] INFO [ARCHIVE] push_log_ (ob_archive_sender.cpp:735) [208151][T1008_ArcSender][T1008][YB42AC160915-0005FBC6DD264D59-0-0] [lt=9] push log succ(id={id:1001}) [2023-05-19 10:18:44.028339] INFO [ARCHIVE] archive_log_ (ob_archive_sender.cpp:623) [208151][T1008_ArcSender][T1008][YB42AC160915-0005FBC6DD264D59-0-0] [lt=12] finish task succ(id={id:1001}) [2023-05-19 10:18:44.028351] INFO [ARCHIVE] handle (ob_archive_sender.cpp:467) [208151][T1008_ArcSender][T1008][YB42AC160915-0005FBC6DD264D59-0-0] [lt=11] archive log succ(id={id:1001}) [2023-05-19 10:18:44.047709] INFO [ARCHIVE] push_log_ (ob_archive_sender.cpp:735) [86303][T1002_ArcSender][T1002][YB42AC160915-0005FBC6CE964D59-0-0] [lt=4] push log succ(id={id:1}) [2023-05-19 10:18:44.047722] INFO [ARCHIVE] archive_log_ (ob_archive_sender.cpp:623) [86303][T1002_ArcSender][T1002][YB42AC160915-0005FBC6CE964D59-0-0] [lt=15] finish task succ(id={id:1}) [2023-05-19 10:18:44.047728] INFO [ARCHIVE] handle (ob_archive_sender.cpp:467) [86303][T1002_ArcSender][T1002][YB42AC160915-0005FBC6CE964D59-0-0] [lt=6] archive log succ(id={id:1}) [2023-05-19 10:18:44.047741] INFO [ARCHIVE] update_archive_progress (ob_ls_task.cpp:376) [86303][T1002_ArcSender][T1002][YB42AC160915-0005FBC6CE964D59-0-0] [lt=5] update archive progress succ(ret=0, id_={id:1001}, station={key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, tuple={offset:{lsn:11269479551}, scn:{val:1684462723545979562}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, file_id=168, file_offset=62983295) [2023-05-19 10:18:44.047761] INFO [ARCHIVE] update_archive_progress (ob_ls_task.cpp:376) [86303][T1002_ArcSender][T1002][YB42AC160915-0005FBC6CE964D59-0-0] [lt=16] update archive progress succ(ret=0, id_={id:1}, station={key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, tuple={offset:{lsn:2398109398}, scn:{val:1684462723544199159}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, file_id=36, file_offset=49442518) [2023-05-19 10:18:44.062459] INFO [ARCHIVE] get_max_lsn_scn_ (ob_archive_fetcher.cpp:431) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=5] get end lsn scn succ(ret=0, id={id:1}, lsn={lsn:654058124}, scn={val:1684462723941817667}) [2023-05-19 10:18:44.062484] INFO [PALF] do_init_ (palf_iterator.h:265) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=15] PalfIterator init success(ret=0, start_offset={lsn:654058002}, this={iterator_impl:{buf_:null, next_round_pread_size:2121728, curr_read_pos:0, curr_read_buf_start_pos:0, curr_read_buf_end_pos:0, log_storage_:{IteratorStorage:{start_lsn:{lsn:654058002}, end_lsn:{lsn:654058002}, read_buf:{buf_len_:0, buf_:null}, block_size:67104768, log_storage_:0x7f736e9fa070}, IteratorStorageType::"DiskIteratorStorage"}, curr_entry_is_raw_write:false, curr_entry_size:0, prev_entry_scn:{val:18446744073709551615}, curr_entry:{LogGroupEntryHeader:{magic:0, version:0, group_size:0, proposal_id:9223372036854775807, committed_lsn:{lsn:18446744073709551615}, max_scn:{val:18446744073709551615}, accumulated_checksum:0, log_id:0, flag:0}}, init_mode_version:0, accumlate_checksum:-1}}) [2023-05-19 10:18:44.063002] INFO [PALF] verify_accum_checksum_ (log_iterator_impl.h:696) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=22] init accumlate_checksum to first LogGroupEntry(entry={LogGroupEntryHeader:{magic:18258, version:1, group_size:66, proposal_id:1, committed_lsn:{lsn:654058002}, max_scn:{val:1684462723941817667}, accumulated_checksum:1683798665, log_id:2513050, flag:0}}, this={buf_:0x7f73e40bd000, next_round_pread_size:2121728, curr_read_pos:0, curr_read_buf_start_pos:0, curr_read_buf_end_pos:122, log_storage_:{IteratorStorage:{start_lsn:{lsn:654058002}, end_lsn:{lsn:654058124}, read_buf:{buf_len_:8192, buf_:0x7f73e40bd000}, block_size:67104768, log_storage_:0x7f736e9fa070}, IteratorStorageType::"DiskIteratorStorage"}, curr_entry_is_raw_write:false, curr_entry_size:0, prev_entry_scn:{val:18446744073709551615}, curr_entry:{LogGroupEntryHeader:{magic:18258, version:1, group_size:66, proposal_id:1, committed_lsn:{lsn:654058002}, max_scn:{val:1684462723941817667}, accumulated_checksum:1683798665, log_id:2513050, flag:0}}, init_mode_version:0, accumlate_checksum:-1}, new_accumlate_checksum=1683798665) [2023-05-19 10:18:44.063025] INFO [ARCHIVE] build_send_task_ (ob_archive_fetcher.cpp:809) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=20] build send task succ(id={id:1}, station={key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}) [2023-05-19 10:18:44.063034] INFO [ARCHIVE] back_fill (ob_archive_task.cpp:134) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=7] print back fill task succ(cur_piece={interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset={lsn:654058002}, end_offset={lsn:654058124}, max_scn={val:1684462723941817667}, this={tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:603942912}, end_offset:{lsn:671047680}, cur_offset:{lsn:654058124}, unfinished_data_size:16989556, max_scn:{val:1684462723941817667}, send_task:{status:0, tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:654058002}, end_offset:{lsn:654058124}, max_scn:{val:1684462723941817667}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73646fa030}, this:0x7f730bf3f180}) [2023-05-19 10:18:44.063057] INFO [ARCHIVE] update_log_fetch_task_ (ob_archive_fetcher.cpp:753) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=23] back fill log fetch task succ(fetch_task={tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:603942912}, end_offset:{lsn:671047680}, cur_offset:{lsn:654058124}, unfinished_data_size:16989556, max_scn:{val:1684462723941817667}, send_task:{status:0, tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:654058002}, end_offset:{lsn:654058124}, max_scn:{val:1684462723941817667}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73646fa030}, this:0x7f730bf3f180}) [2023-05-19 10:18:44.063075] INFO [ARCHIVE] push_fetch_log (ob_ls_task.cpp:709) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=16] print push_fetch_log(task={tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:603942912}, end_offset:{lsn:671047680}, cur_offset:{lsn:654058124}, unfinished_data_size:16989556, max_scn:{val:1684462723941817667}, send_task:{status:0, tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:654058002}, end_offset:{lsn:654058124}, max_scn:{val:1684462723941817667}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73646fa030}, this:0x7f730bf3f180}, this={has_encount_error:false, is_worm:false, max_archived_info:{offset:{lsn:654056436}, scn:{val:1684462723337873819}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}}, max_seq_log_offset:{lsn:671047680}, max_fetch_info:{offset:{lsn:654058002}, scn:{val:1684462723773073844}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}}, piece_min_lsn:{lsn:469733376}, archive_file_id:10, archive_file_offset:50113524, piece_dir_exist:true, wait_send_task_count:1, tasks:{tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:603942912}, end_offset:{lsn:671047680}, cur_offset:{lsn:654058124}, unfinished_data_size:16989556, max_scn:{val:1684462723941817667}, send_task:{status:0, tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:654058002}, end_offset:{lsn:654058124}, max_scn:{val:1684462723941817667}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73646fa030}, this:0x7f730bf3f180}, {tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:603942912}, end_offset:{lsn:671047680}, cur_offset:{lsn:654058124}, unfinished_data_size:16989556, max_scn:{val:1684462723941817667}, send_task:{status:0, tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:654058002}, end_offset:{lsn:654058124}, max_scn:{val:1684462723941817667}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73646fa030}, this:0x7f730bf3f180}}) [2023-05-19 10:18:44.063124] INFO [ARCHIVE] submit_fetch_log_ (ob_archive_fetcher.cpp:785) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=49] push fetch log succ(id={id:1}, &task=0x7f730bf3f180) [2023-05-19 10:18:44.063128] INFO [ARCHIVE] handle_log_fetch_task_ (ob_archive_fetcher.cpp:398) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=4] handle log fetch task succ(id={id:1}) [2023-05-19 10:18:44.063133] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:333) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=4] handle task succ(id={id:1}) [2023-05-19 10:18:44.063136] INFO [ARCHIVE] get_sorted_fetch_log (ob_ls_task.cpp:188) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=3] the same file(tuple={offset:{lsn:654058002}, scn:{val:1684462723773073844}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}}, tmp_task={tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:603942912}, end_offset:{lsn:671047680}, cur_offset:{lsn:654058124}, unfinished_data_size:16989556, max_scn:{val:1684462723941817667}, send_task:{status:0, tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:654058002}, end_offset:{lsn:654058124}, max_scn:{val:1684462723941817667}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73646fa030}, this:0x7f730bf3f180}) [2023-05-19 10:18:44.063154] INFO [ARCHIVE] get_sorted_fetch_log_ (ob_archive_fetcher.cpp:886) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=17] get sorted fetch log succ(task={tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:603942912}, end_offset:{lsn:671047680}, cur_offset:{lsn:654058124}, unfinished_data_size:16989556, max_scn:{val:1684462723941817667}, send_task:{status:0, tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:654058002}, end_offset:{lsn:654058124}, max_scn:{val:1684462723941817667}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73646fa030}, this:0x7f730bf3f180}) [2023-05-19 10:18:44.063170] INFO [ARCHIVE] submit_send_task_ (ob_archive_fetcher.cpp:938) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=15] submit send task succ(send_task=0x7f73646fa030) [2023-05-19 10:18:44.063174] INFO [ARCHIVE] submit_residual_log_fetch_task_ (ob_archive_fetcher.cpp:927) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=3] submit residual log fetch task succ(&task=0x7f730bf3f180) [2023-05-19 10:18:44.063181] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:341) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=2] try consume task status succ(id={id:1}) [2023-05-19 10:18:44.063186] INFO [ARCHIVE] get_max_lsn_scn_ (ob_archive_fetcher.cpp:431) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=3] get end lsn scn succ(ret=0, id={id:1001}, lsn={lsn:593558916}, scn={val:1684462723941817667}) [2023-05-19 10:18:44.063193] INFO [PALF] do_init_ (palf_iterator.h:265) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=5] PalfIterator init success(ret=0, start_offset={lsn:593558794}, this={iterator_impl:{buf_:null, next_round_pread_size:2121728, curr_read_pos:0, curr_read_buf_start_pos:0, curr_read_buf_end_pos:0, log_storage_:{IteratorStorage:{start_lsn:{lsn:593558794}, end_lsn:{lsn:593558794}, read_buf:{buf_len_:0, buf_:null}, block_size:67104768, log_storage_:0x7f7354a4e070}, IteratorStorageType::"DiskIteratorStorage"}, curr_entry_is_raw_write:false, curr_entry_size:0, prev_entry_scn:{val:18446744073709551615}, curr_entry:{LogGroupEntryHeader:{magic:0, version:0, group_size:0, proposal_id:9223372036854775807, committed_lsn:{lsn:18446744073709551615}, max_scn:{val:18446744073709551615}, accumulated_checksum:0, log_id:0, flag:0}}, init_mode_version:0, accumlate_checksum:-1}}) [2023-05-19 10:18:44.063245] INFO [ARCHIVE] get_commit_index_ (ob_archive_sequencer.cpp:259) [208149][T1008_ArcSeq][T1008][YB42AC160915-0005FBC6DD064D59-0-0] [lt=10] get commit index succ [2023-05-19 10:18:44.063489] INFO [ARCHIVE] get_commit_index_ (ob_archive_sequencer.cpp:259) [208149][T1008_ArcSeq][T1008][YB42AC160915-0005FBC6DD064D59-0-0] [lt=6] get commit index succ [2023-05-19 10:18:44.064100] INFO [PALF] verify_accum_checksum_ (log_iterator_impl.h:696) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=12] init accumlate_checksum to first LogGroupEntry(entry={LogGroupEntryHeader:{magic:18258, version:1, group_size:66, proposal_id:1, committed_lsn:{lsn:593558794}, max_scn:{val:1684462723941817667}, accumulated_checksum:938232027, log_id:2230796, flag:0}}, this={buf_:0x7f73e4131000, next_round_pread_size:2121728, curr_read_pos:0, curr_read_buf_start_pos:0, curr_read_buf_end_pos:122, log_storage_:{IteratorStorage:{start_lsn:{lsn:593558794}, end_lsn:{lsn:593558916}, read_buf:{buf_len_:8192, buf_:0x7f73e4131000}, block_size:67104768, log_storage_:0x7f7354a4e070}, IteratorStorageType::"DiskIteratorStorage"}, curr_entry_is_raw_write:false, curr_entry_size:0, prev_entry_scn:{val:18446744073709551615}, curr_entry:{LogGroupEntryHeader:{magic:18258, version:1, group_size:66, proposal_id:1, committed_lsn:{lsn:593558794}, max_scn:{val:1684462723941817667}, accumulated_checksum:938232027, log_id:2230796, flag:0}}, init_mode_version:0, accumlate_checksum:-1}, new_accumlate_checksum=938232027) [2023-05-19 10:18:44.064126] INFO [ARCHIVE] build_send_task_ (ob_archive_fetcher.cpp:809) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=24] build send task succ(id={id:1001}, station={key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}) [2023-05-19 10:18:44.064133] INFO [ARCHIVE] back_fill (ob_archive_task.cpp:134) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=7] print back fill task succ(cur_piece={interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset={lsn:593558794}, end_offset={lsn:593558916}, max_scn={val:1684462723941817667}, this={tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:536838144}, end_offset:{lsn:603942912}, cur_offset:{lsn:593558916}, unfinished_data_size:10383996, max_scn:{val:1684462723941817667}, send_task:{status:0, tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:593558794}, end_offset:{lsn:593558916}, max_scn:{val:1684462723941817667}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73e40c6030}, this:0x7f730bf3f070}) [2023-05-19 10:18:44.064154] INFO [ARCHIVE] update_log_fetch_task_ (ob_archive_fetcher.cpp:753) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=21] back fill log fetch task succ(fetch_task={tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:536838144}, end_offset:{lsn:603942912}, cur_offset:{lsn:593558916}, unfinished_data_size:10383996, max_scn:{val:1684462723941817667}, send_task:{status:0, tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:593558794}, end_offset:{lsn:593558916}, max_scn:{val:1684462723941817667}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73e40c6030}, this:0x7f730bf3f070}) [2023-05-19 10:18:44.064172] INFO [ARCHIVE] push_fetch_log (ob_ls_task.cpp:709) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=15] print push_fetch_log(task={tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:536838144}, end_offset:{lsn:603942912}, cur_offset:{lsn:593558916}, unfinished_data_size:10383996, max_scn:{val:1684462723941817667}, send_task:{status:0, tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:593558794}, end_offset:{lsn:593558916}, max_scn:{val:1684462723941817667}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73e40c6030}, this:0x7f730bf3f070}, this={has_encount_error:false, is_worm:false, max_archived_info:{offset:{lsn:593558428}, scn:{val:1684462723337873819}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}}, max_seq_log_offset:{lsn:603942912}, max_fetch_info:{offset:{lsn:593558794}, scn:{val:1684462723773073844}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}}, piece_min_lsn:{lsn:134209536}, archive_file_id:9, archive_file_offset:56720284, piece_dir_exist:true, wait_send_task_count:1, tasks:{tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:536838144}, end_offset:{lsn:603942912}, cur_offset:{lsn:593558916}, unfinished_data_size:10383996, max_scn:{val:1684462723941817667}, send_task:{status:0, tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:593558794}, end_offset:{lsn:593558916}, max_scn:{val:1684462723941817667}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73e40c6030}, this:0x7f730bf3f070}, {tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:536838144}, end_offset:{lsn:603942912}, cur_offset:{lsn:593558916}, unfinished_data_size:10383996, max_scn:{val:1684462723941817667}, send_task:{status:0, tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:593558794}, end_offset:{lsn:593558916}, max_scn:{val:1684462723941817667}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73e40c6030}, this:0x7f730bf3f070}}) [2023-05-19 10:18:44.064230] INFO [ARCHIVE] submit_fetch_log_ (ob_archive_fetcher.cpp:785) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=58] push fetch log succ(id={id:1001}, &task=0x7f730bf3f070) [2023-05-19 10:18:44.064234] INFO [ARCHIVE] handle_log_fetch_task_ (ob_archive_fetcher.cpp:398) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=4] handle log fetch task succ(id={id:1001}) [2023-05-19 10:18:44.064239] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:333) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=3] handle task succ(id={id:1001}) [2023-05-19 10:18:44.064243] INFO [ARCHIVE] get_sorted_fetch_log (ob_ls_task.cpp:188) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=3] the same file(tuple={offset:{lsn:593558794}, scn:{val:1684462723773073844}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}}, tmp_task={tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:536838144}, end_offset:{lsn:603942912}, cur_offset:{lsn:593558916}, unfinished_data_size:10383996, max_scn:{val:1684462723941817667}, send_task:{status:0, tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:593558794}, end_offset:{lsn:593558916}, max_scn:{val:1684462723941817667}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73e40c6030}, this:0x7f730bf3f070}) [2023-05-19 10:18:44.064260] INFO [ARCHIVE] get_sorted_fetch_log_ (ob_archive_fetcher.cpp:886) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=17] get sorted fetch log succ(task={tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:536838144}, end_offset:{lsn:603942912}, cur_offset:{lsn:593558916}, unfinished_data_size:10383996, max_scn:{val:1684462723941817667}, send_task:{status:0, tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:593558794}, end_offset:{lsn:593558916}, max_scn:{val:1684462723941817667}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73e40c6030}, this:0x7f730bf3f070}) [2023-05-19 10:18:44.064275] INFO [ARCHIVE] submit_send_task_ (ob_archive_fetcher.cpp:938) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=15] submit send task succ(send_task=0x7f73e40c6030) [2023-05-19 10:18:44.064279] INFO [ARCHIVE] submit_residual_log_fetch_task_ (ob_archive_fetcher.cpp:927) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=2] submit residual log fetch task succ(&task=0x7f730bf3f070) [2023-05-19 10:18:44.064285] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:341) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=3] try consume task status succ(id={id:1001}) [2023-05-19 10:18:44.064298] INFO [ARCHIVE] get_commit_index_ (ob_archive_sequencer.cpp:259) [208149][T1008_ArcSeq][T1008][YB42AC160915-0005FBC6DD064D59-0-0] [lt=7] get commit index succ [2023-05-19 10:18:44.064509] INFO [ARCHIVE] get_commit_index_ (ob_archive_sequencer.cpp:259) [208149][T1008_ArcSeq][T1008][YB42AC160915-0005FBC6DD064D59-0-0] [lt=4] get commit index succ [2023-05-19 10:18:44.072064] INFO [ARCHIVE] get_max_lsn_scn_ (ob_archive_fetcher.cpp:431) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=3] get end lsn scn succ(ret=0, id={id:1}, lsn={lsn:2398110600}, scn={val:1684462723941659070}) [2023-05-19 10:18:44.072085] INFO [PALF] do_init_ (palf_iterator.h:265) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=13] PalfIterator init success(ret=0, start_offset={lsn:2398110478}, this={iterator_impl:{buf_:null, next_round_pread_size:2121728, curr_read_pos:0, curr_read_buf_start_pos:0, curr_read_buf_end_pos:0, log_storage_:{IteratorStorage:{start_lsn:{lsn:2398110478}, end_lsn:{lsn:2398110478}, read_buf:{buf_len_:0, buf_:null}, block_size:67104768, log_storage_:0x7f742d3da070}, IteratorStorageType::"DiskIteratorStorage"}, curr_entry_is_raw_write:false, curr_entry_size:0, prev_entry_scn:{val:18446744073709551615}, curr_entry:{LogGroupEntryHeader:{magic:0, version:0, group_size:0, proposal_id:9223372036854775807, committed_lsn:{lsn:18446744073709551615}, max_scn:{val:18446744073709551615}, accumulated_checksum:0, log_id:0, flag:0}}, init_mode_version:0, accumlate_checksum:-1}}) [2023-05-19 10:18:44.075050] INFO [SERVER] add_changed_session_info (obmp_utils.cpp:81) [91299][T1002_L0_G0][T1002][YB42AC160915-0005FBC6F6A65252-0-0] [lt=15] success add system var to ok pack(str_kv={key:"autocommit", value:"0"}, change_var={id_:2, old_val_:{"BIGINT":1}}, new_val={"BIGINT":0}, session.get_sessid()=3221500839, session.get_proxy_sessid()=12400108609999147339, change_var.id_=2) [2023-05-19 10:18:44.075155] INFO [SERVER] add_changed_session_info (obmp_utils.cpp:81) [96371][T1002_L0_G0][T1002][YB42AC160915-0005FBC6F6D657DB-0-0] [lt=26] success add system var to ok pack(str_kv={key:"autocommit", value:"0"}, change_var={id_:2, old_val_:{"BIGINT":1}}, new_val={"BIGINT":0}, session.get_sessid()=3221507999, session.get_proxy_sessid()=12400108609999145072, change_var.id_=2) [2023-05-19 10:18:44.076460] WDIAG [SERVER] get_int (ob_inner_sql_result.cpp:500) [85134][DBMS_SCHEDULER][T0][YB42AC160915-0005FBC6D1164D59-0-0] [lt=6][errcode=-4152] get obj error(ret=-4152) [2023-05-19 10:18:44.077011] INFO [SERVER] add_changed_session_info (obmp_utils.cpp:81) [79863][T1002_L0_G0][T1002][YB42AC160915-0005FBC6F666FC13-0-0] [lt=14] success add system var to ok pack(str_kv={key:"autocommit", value:"1"}, change_var={id_:2, old_val_:{"BIGINT":0}}, new_val={"BIGINT":1}, session.get_sessid()=3221507999, session.get_proxy_sessid()=12400108609999145072, change_var.id_=2) [2023-05-19 10:18:44.077362] INFO [SERVER] add_changed_session_info (obmp_utils.cpp:81) [79863][T1002_L0_G0][T1002][YB42AC160915-0005FBC6F666FC14-0-0] [lt=15] success add system var to ok pack(str_kv={key:"autocommit", value:"1"}, change_var={id_:2, old_val_:{"BIGINT":0}}, new_val={"BIGINT":1}, session.get_sessid()=3221500839, session.get_proxy_sessid()=12400108609999147339, change_var.id_=2) [2023-05-19 10:18:44.077479] INFO [SERVER] add_changed_session_info (obmp_utils.cpp:81) [96371][T1002_L0_G0][T1002][YB42AC160915-0005FBC6F6D657DD-0-0] [lt=16] success add system var to ok pack(str_kv={key:"autocommit", value:"1"}, change_var={id_:2, old_val_:{"BIGINT":0}}, new_val={"BIGINT":1}, session.get_sessid()=3221597477, session.get_proxy_sessid()=12400108609999148232, change_var.id_=2) [2023-05-19 10:18:44.079035] INFO [PALF] verify_accum_checksum_ (log_iterator_impl.h:696) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=22] init accumlate_checksum to first LogGroupEntry(entry={LogGroupEntryHeader:{magic:18258, version:1, group_size:66, proposal_id:7, committed_lsn:{lsn:2398110478}, max_scn:{val:1684462723941659070}, accumulated_checksum:4214816951, log_id:9782342, flag:1}}, this={buf_:0x7f7368439000, next_round_pread_size:2121728, curr_read_pos:0, curr_read_buf_start_pos:0, curr_read_buf_end_pos:122, log_storage_:{IteratorStorage:{start_lsn:{lsn:2398110478}, end_lsn:{lsn:2398110600}, read_buf:{buf_len_:8192, buf_:0x7f7368439000}, block_size:67104768, log_storage_:0x7f742d3da070}, IteratorStorageType::"DiskIteratorStorage"}, curr_entry_is_raw_write:false, curr_entry_size:0, prev_entry_scn:{val:18446744073709551615}, curr_entry:{LogGroupEntryHeader:{magic:18258, version:1, group_size:66, proposal_id:7, committed_lsn:{lsn:2398110478}, max_scn:{val:1684462723941659070}, accumulated_checksum:4214816951, log_id:9782342, flag:1}}, init_mode_version:0, accumlate_checksum:-1}, new_accumlate_checksum=4214816951) [2023-05-19 10:18:44.079082] INFO [ARCHIVE] build_send_task_ (ob_archive_fetcher.cpp:809) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=45] build send task succ(id={id:1}, station={key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}) [2023-05-19 10:18:44.079092] INFO [ARCHIVE] back_fill (ob_archive_task.cpp:134) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=9] print back fill task succ(cur_piece={interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset={lsn:2398110478}, end_offset={lsn:2398110600}, max_scn={val:1684462723941659070}, this={tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:2348666880}, end_offset:{lsn:2415771648}, cur_offset:{lsn:2398110600}, unfinished_data_size:17661048, max_scn:{val:1684462723941659070}, send_task:{status:0, tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:2398110478}, end_offset:{lsn:2398110600}, max_scn:{val:1684462723941659070}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7368410030}, this:0x7f739d8e04c0}) [2023-05-19 10:18:44.079117] INFO [ARCHIVE] update_log_fetch_task_ (ob_archive_fetcher.cpp:753) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=25] back fill log fetch task succ(fetch_task={tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:2348666880}, end_offset:{lsn:2415771648}, cur_offset:{lsn:2398110600}, unfinished_data_size:17661048, max_scn:{val:1684462723941659070}, send_task:{status:0, tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:2398110478}, end_offset:{lsn:2398110600}, max_scn:{val:1684462723941659070}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7368410030}, this:0x7f739d8e04c0}) [2023-05-19 10:18:44.079137] INFO [ARCHIVE] push_fetch_log (ob_ls_task.cpp:709) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=17] print push_fetch_log(task={tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:2348666880}, end_offset:{lsn:2415771648}, cur_offset:{lsn:2398110600}, unfinished_data_size:17661048, max_scn:{val:1684462723941659070}, send_task:{status:0, tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:2398110478}, end_offset:{lsn:2398110600}, max_scn:{val:1684462723941659070}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7368410030}, this:0x7f739d8e04c0}, this={has_encount_error:false, is_worm:false, max_archived_info:{offset:{lsn:2398109398}, scn:{val:1684462723544199159}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, max_seq_log_offset:{lsn:2415771648}, max_fetch_info:{offset:{lsn:2398110478}, scn:{val:1684462723841028770}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, piece_min_lsn:{lsn:2147352576}, archive_file_id:36, archive_file_offset:49442518, piece_dir_exist:true, wait_send_task_count:1, tasks:{tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:2348666880}, end_offset:{lsn:2415771648}, cur_offset:{lsn:2398110600}, unfinished_data_size:17661048, max_scn:{val:1684462723941659070}, send_task:{status:0, tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:2398110478}, end_offset:{lsn:2398110600}, max_scn:{val:1684462723941659070}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7368410030}, this:0x7f739d8e04c0}, {tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:2348666880}, end_offset:{lsn:2415771648}, cur_offset:{lsn:2398110600}, unfinished_data_size:17661048, max_scn:{val:1684462723941659070}, send_task:{status:0, tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:2398110478}, end_offset:{lsn:2398110600}, max_scn:{val:1684462723941659070}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7368410030}, this:0x7f739d8e04c0}}) [2023-05-19 10:18:44.079186] INFO [ARCHIVE] submit_fetch_log_ (ob_archive_fetcher.cpp:785) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=50] push fetch log succ(id={id:1}, &task=0x7f739d8e04c0) [2023-05-19 10:18:44.079192] INFO [ARCHIVE] handle_log_fetch_task_ (ob_archive_fetcher.cpp:398) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=5] handle log fetch task succ(id={id:1}) [2023-05-19 10:18:44.079198] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:333) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=4] handle task succ(id={id:1}) [2023-05-19 10:18:44.079203] INFO [ARCHIVE] get_sorted_fetch_log (ob_ls_task.cpp:188) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=4] the same file(tuple={offset:{lsn:2398110478}, scn:{val:1684462723841028770}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, tmp_task={tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:2348666880}, end_offset:{lsn:2415771648}, cur_offset:{lsn:2398110600}, unfinished_data_size:17661048, max_scn:{val:1684462723941659070}, send_task:{status:0, tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:2398110478}, end_offset:{lsn:2398110600}, max_scn:{val:1684462723941659070}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7368410030}, this:0x7f739d8e04c0}) [2023-05-19 10:18:44.079230] INFO [ARCHIVE] get_sorted_fetch_log_ (ob_archive_fetcher.cpp:886) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=27] get sorted fetch log succ(task={tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:2348666880}, end_offset:{lsn:2415771648}, cur_offset:{lsn:2398110600}, unfinished_data_size:17661048, max_scn:{val:1684462723941659070}, send_task:{status:0, tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:2398110478}, end_offset:{lsn:2398110600}, max_scn:{val:1684462723941659070}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7368410030}, this:0x7f739d8e04c0}) [2023-05-19 10:18:44.079247] INFO [ARCHIVE] submit_send_task_ (ob_archive_fetcher.cpp:938) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=16] submit send task succ(send_task=0x7f7368410030) [2023-05-19 10:18:44.079251] INFO [ARCHIVE] submit_residual_log_fetch_task_ (ob_archive_fetcher.cpp:927) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=4] submit residual log fetch task succ(&task=0x7f739d8e04c0) [2023-05-19 10:18:44.079258] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:341) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=4] try consume task status succ(id={id:1}) [2023-05-19 10:18:44.079265] INFO [ARCHIVE] get_max_lsn_scn_ (ob_archive_fetcher.cpp:431) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=4] get end lsn scn succ(ret=0, id={id:1001}, lsn={lsn:11269479917}, scn={val:1684462723941659070}) [2023-05-19 10:18:44.079275] INFO [PALF] do_init_ (palf_iterator.h:265) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=6] PalfIterator init success(ret=0, start_offset={lsn:11269479795}, this={iterator_impl:{buf_:null, next_round_pread_size:2121728, curr_read_pos:0, curr_read_buf_start_pos:0, curr_read_buf_end_pos:0, log_storage_:{IteratorStorage:{start_lsn:{lsn:11269479795}, end_lsn:{lsn:11269479795}, read_buf:{buf_len_:0, buf_:null}, block_size:67104768, log_storage_:0x7f742d3e4070}, IteratorStorageType::"DiskIteratorStorage"}, curr_entry_is_raw_write:false, curr_entry_size:0, prev_entry_scn:{val:18446744073709551615}, curr_entry:{LogGroupEntryHeader:{magic:0, version:0, group_size:0, proposal_id:9223372036854775807, committed_lsn:{lsn:18446744073709551615}, max_scn:{val:18446744073709551615}, accumulated_checksum:0, log_id:0, flag:0}}, init_mode_version:0, accumlate_checksum:-1}}) [2023-05-19 10:18:44.079489] INFO [ARCHIVE] get_commit_index_ (ob_archive_sequencer.cpp:259) [86301][T1002_ArcSeq][T1002][YB42AC160915-0005FBC6CE764D59-0-0] [lt=5] get commit index succ [2023-05-19 10:18:44.079731] INFO [ARCHIVE] get_commit_index_ (ob_archive_sequencer.cpp:259) [86301][T1002_ArcSeq][T1002][YB42AC160915-0005FBC6CE764D59-0-0] [lt=7] get commit index succ [2023-05-19 10:18:44.079749] INFO [PALF] verify_accum_checksum_ (log_iterator_impl.h:696) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=15] init accumlate_checksum to first LogGroupEntry(entry={LogGroupEntryHeader:{magic:18258, version:1, group_size:66, proposal_id:7, committed_lsn:{lsn:11269479795}, max_scn:{val:1684462723941659070}, accumulated_checksum:2410782133, log_id:14322878, flag:0}}, this={buf_:0x7f7368439000, next_round_pread_size:2121728, curr_read_pos:0, curr_read_buf_start_pos:0, curr_read_buf_end_pos:122, log_storage_:{IteratorStorage:{start_lsn:{lsn:11269479795}, end_lsn:{lsn:11269479917}, read_buf:{buf_len_:8192, buf_:0x7f7368439000}, block_size:67104768, log_storage_:0x7f742d3e4070}, IteratorStorageType::"DiskIteratorStorage"}, curr_entry_is_raw_write:false, curr_entry_size:0, prev_entry_scn:{val:18446744073709551615}, curr_entry:{LogGroupEntryHeader:{magic:18258, version:1, group_size:66, proposal_id:7, committed_lsn:{lsn:11269479795}, max_scn:{val:1684462723941659070}, accumulated_checksum:2410782133, log_id:14322878, flag:0}}, init_mode_version:0, accumlate_checksum:-1}, new_accumlate_checksum=2410782133) [2023-05-19 10:18:44.079770] INFO [ARCHIVE] build_send_task_ (ob_archive_fetcher.cpp:809) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=19] build send task succ(id={id:1001}, station={key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}) [2023-05-19 10:18:44.079777] INFO [ARCHIVE] back_fill (ob_archive_task.cpp:134) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=7] print back fill task succ(cur_piece={interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset={lsn:11269479795}, end_offset={lsn:11269479917}, max_scn={val:1684462723941659070}, this={tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269479917}, unfinished_data_size:4121107, max_scn:{val:1684462723941659070}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479795}, end_offset:{lsn:11269479917}, max_scn:{val:1684462723941659070}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73685fc030}, this:0x7f739d8e0c30}) [2023-05-19 10:18:44.079802] INFO [ARCHIVE] update_log_fetch_task_ (ob_archive_fetcher.cpp:753) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=25] back fill log fetch task succ(fetch_task={tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269479917}, unfinished_data_size:4121107, max_scn:{val:1684462723941659070}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479795}, end_offset:{lsn:11269479917}, max_scn:{val:1684462723941659070}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73685fc030}, this:0x7f739d8e0c30}) [2023-05-19 10:18:44.079820] INFO [ARCHIVE] push_fetch_log (ob_ls_task.cpp:709) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=16] print push_fetch_log(task={tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269479917}, unfinished_data_size:4121107, max_scn:{val:1684462723941659070}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479795}, end_offset:{lsn:11269479917}, max_scn:{val:1684462723941659070}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73685fc030}, this:0x7f739d8e0c30}, this={has_encount_error:false, is_worm:false, max_archived_info:{offset:{lsn:11269479551}, scn:{val:1684462723545979562}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, max_seq_log_offset:{lsn:11273601024}, max_fetch_info:{offset:{lsn:11269479795}, scn:{val:1684462723841028770}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, piece_min_lsn:{lsn:10334134272}, archive_file_id:168, archive_file_offset:62983295, piece_dir_exist:true, wait_send_task_count:1, tasks:{tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269479917}, unfinished_data_size:4121107, max_scn:{val:1684462723941659070}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479795}, end_offset:{lsn:11269479917}, max_scn:{val:1684462723941659070}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73685fc030}, this:0x7f739d8e0c30}, {tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269479917}, unfinished_data_size:4121107, max_scn:{val:1684462723941659070}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479795}, end_offset:{lsn:11269479917}, max_scn:{val:1684462723941659070}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73685fc030}, this:0x7f739d8e0c30}}) [2023-05-19 10:18:44.079872] INFO [ARCHIVE] submit_fetch_log_ (ob_archive_fetcher.cpp:785) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=52] push fetch log succ(id={id:1001}, &task=0x7f739d8e0c30) [2023-05-19 10:18:44.079877] INFO [ARCHIVE] handle_log_fetch_task_ (ob_archive_fetcher.cpp:398) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=5] handle log fetch task succ(id={id:1001}) [2023-05-19 10:18:44.079882] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:333) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=4] handle task succ(id={id:1001}) [2023-05-19 10:18:44.079887] INFO [ARCHIVE] get_sorted_fetch_log (ob_ls_task.cpp:188) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=4] the same file(tuple={offset:{lsn:11269479795}, scn:{val:1684462723841028770}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, tmp_task={tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269479917}, unfinished_data_size:4121107, max_scn:{val:1684462723941659070}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479795}, end_offset:{lsn:11269479917}, max_scn:{val:1684462723941659070}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73685fc030}, this:0x7f739d8e0c30}) [2023-05-19 10:18:44.079905] INFO [ARCHIVE] get_sorted_fetch_log_ (ob_archive_fetcher.cpp:886) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=18] get sorted fetch log succ(task={tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269479917}, unfinished_data_size:4121107, max_scn:{val:1684462723941659070}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479795}, end_offset:{lsn:11269479917}, max_scn:{val:1684462723941659070}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73685fc030}, this:0x7f739d8e0c30}) [2023-05-19 10:18:44.079922] INFO [ARCHIVE] submit_send_task_ (ob_archive_fetcher.cpp:938) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=15] submit send task succ(send_task=0x7f73685fc030) [2023-05-19 10:18:44.079926] INFO [ARCHIVE] submit_residual_log_fetch_task_ (ob_archive_fetcher.cpp:927) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=4] submit residual log fetch task succ(&task=0x7f739d8e0c30) [2023-05-19 10:18:44.079933] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:341) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=4] try consume task status succ(id={id:1001}) [2023-05-19 10:18:44.080010] INFO [ARCHIVE] get_commit_index_ (ob_archive_sequencer.cpp:259) [86301][T1002_ArcSeq][T1002][YB42AC160915-0005FBC6CE764D59-0-0] [lt=5] get commit index succ [2023-05-19 10:18:44.080220] INFO [ARCHIVE] get_commit_index_ (ob_archive_sequencer.cpp:259) [86301][T1002_ArcSeq][T1002][YB42AC160915-0005FBC6CE764D59-0-0] [lt=5] get commit index succ [2023-05-19 10:18:44.088539] INFO [SERVER] add_changed_session_info (obmp_utils.cpp:81) [208189][T1008_L0_G0][T1008][YB42AC160915-0005FBC6F48A38CA-0-0] [lt=19] success add system var to ok pack(str_kv={key:"autocommit", value:"0"}, change_var={id_:2, old_val_:{"BIGINT":1}}, new_val={"BIGINT":0}, session.get_sessid()=3221633732, session.get_proxy_sessid()=12400108609999149276, change_var.id_=2) [2023-05-19 10:18:44.090389] INFO [ARCHIVE] push_log_ (ob_archive_sender.cpp:735) [208151][T1008_ArcSender][T1008][YB42AC160915-0005FBC6DD264D59-0-0] [lt=4] push log succ(id={id:1}) [2023-05-19 10:18:44.090401] INFO [ARCHIVE] archive_log_ (ob_archive_sender.cpp:623) [208151][T1008_ArcSender][T1008][YB42AC160915-0005FBC6DD264D59-0-0] [lt=13] finish task succ(id={id:1}) [2023-05-19 10:18:44.090406] INFO [ARCHIVE] handle (ob_archive_sender.cpp:467) [208151][T1008_ArcSender][T1008][YB42AC160915-0005FBC6DD264D59-0-0] [lt=5] archive log succ(id={id:1}) [2023-05-19 10:18:44.090418] INFO [ARCHIVE] update_archive_progress (ob_ls_task.cpp:376) [208151][T1008_ArcSender][T1008][YB42AC160915-0005FBC6DD264D59-0-0] [lt=4] update archive progress succ(ret=0, id_={id:1001}, station={key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, tuple={offset:{lsn:593558550}, scn:{val:1684462723490537409}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}}, file_id=9, file_offset=56720406) [2023-05-19 10:18:44.090451] INFO [ARCHIVE] update_archive_progress (ob_ls_task.cpp:376) [208151][T1008_ArcSender][T1008][YB42AC160915-0005FBC6DD264D59-0-0] [lt=15] update archive progress succ(ret=0, id_={id:1}, station={key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, tuple={offset:{lsn:654057392}, scn:{val:1684462723490537409}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}}, file_id=10, file_offset=50114480) [2023-05-19 10:18:44.099598] INFO [SERVER] add_changed_session_info (obmp_utils.cpp:81) [208189][T1008_L0_G0][T1008][YB42AC160915-0005FBC6F48A38CD-0-0] [lt=17] success add system var to ok pack(str_kv={key:"autocommit", value:"0"}, change_var={id_:2, old_val_:{"BIGINT":1}}, new_val={"BIGINT":0}, session.get_sessid()=3221633732, session.get_proxy_sessid()=12400108609999149276, change_var.id_=2) [2023-05-19 10:18:44.101600] INFO [ARCHIVE] push_log_ (ob_archive_sender.cpp:735) [86303][T1002_ArcSender][T1002][YB42AC160915-0005FBC6CE964D59-0-0] [lt=8] push log succ(id={id:1001}) [2023-05-19 10:18:44.101620] INFO [ARCHIVE] archive_log_ (ob_archive_sender.cpp:623) [86303][T1002_ArcSender][T1002][YB42AC160915-0005FBC6CE964D59-0-0] [lt=21] finish task succ(id={id:1001}) [2023-05-19 10:18:44.101625] INFO [ARCHIVE] handle (ob_archive_sender.cpp:467) [86303][T1002_ArcSender][T1002][YB42AC160915-0005FBC6CE964D59-0-0] [lt=5] archive log succ(id={id:1001}) [2023-05-19 10:18:44.119028] WDIAG [SERVER] get_int (ob_inner_sql_result.cpp:500) [85134][DBMS_SCHEDULER][T0][YB42AC160915-0005FBC6D1164D59-0-0] [lt=7][errcode=-4152] get obj error(ret=-4152) [2023-05-19 10:18:44.119528] WDIAG [SERVER] get_int (ob_inner_sql_result.cpp:500) [85134][DBMS_SCHEDULER][T0][YB42AC160915-0005FBC6D1164D59-0-0] [lt=6][errcode=-4152] get obj error(ret=-4152) [2023-05-19 10:18:44.125037] INFO [SERVER] add_changed_session_info (obmp_utils.cpp:81) [208189][T1008_L0_G0][T1008][YB42AC160915-0005FBC6F48A38D0-0-0] [lt=16] success add system var to ok pack(str_kv={key:"autocommit", value:"0"}, change_var={id_:2, old_val_:{"BIGINT":1}}, new_val={"BIGINT":0}, session.get_sessid()=3221633732, session.get_proxy_sessid()=12400108609999149276, change_var.id_=2) [2023-05-19 10:18:44.126205] WDIAG [STORAGE.TRANS] set_hash_holder (ob_lock_wait_mgr.cpp:152) [208052][T1008_TeRec][T1007][YB42AC160915-0005FBC6F64C6BC8-0-0] [lt=17][errcode=-4017] set hash holder error(ret=-4017, ret="OB_ENTRY_EXIST", hash=4345340486999059591, tx_id={txid:10006177}) [2023-05-19 10:18:44.153532] INFO [ARCHIVE] push_log_ (ob_archive_sender.cpp:735) [208151][T1008_ArcSender][T1008][YB42AC160915-0005FBC6DD264D59-0-0] [lt=14] push log succ(id={id:1001}) [2023-05-19 10:18:44.153544] INFO [ARCHIVE] archive_log_ (ob_archive_sender.cpp:623) [208151][T1008_ArcSender][T1008][YB42AC160915-0005FBC6DD264D59-0-0] [lt=13] finish task succ(id={id:1001}) [2023-05-19 10:18:44.153550] INFO [ARCHIVE] handle (ob_archive_sender.cpp:467) [208151][T1008_ArcSender][T1008][YB42AC160915-0005FBC6DD264D59-0-0] [lt=5] archive log succ(id={id:1001}) [2023-05-19 10:18:44.158314] INFO [DETECT] maintain_deadlock_info_when_end_stmt (ob_trans_deadlock_adapter.cpp:644) [85736][T1001_PLSSer][T1001][YB42AC160915-0005FBC6D5CA2F9D-0-0] [lt=18] inner session no need register to deadlock(step=2, ret=0, ret="OB_SUCCESS", exec_ctx.get_errcode()=0, exec_ctx.get_errcode()="OB_SUCCESS", session={this:0x7f7389c6e0d8, id:1, tenant:"sys", tenant_id:1, effective_tenant:"sys", effective_tenant_id:1001, database:"oceanbase", user:"root@%", consistency_level:3, session_state:0, autocommit:true, tx:0x7f739a63a920}, desc=NULL, is_rollback=false, conflict_txs=[]) [2023-05-19 10:18:44.158472] INFO [DETECT] maintain_deadlock_info_when_end_stmt (ob_trans_deadlock_adapter.cpp:644) [85736][T1001_PLSSer][T1001][YB42AC160915-0005FBC6D5CA2F9D-0-0] [lt=25] inner session no need register to deadlock(step=2, ret=0, ret="OB_SUCCESS", exec_ctx.get_errcode()=0, exec_ctx.get_errcode()="OB_SUCCESS", session={this:0x7f7389c6e0d8, id:1, tenant:"sys", tenant_id:1, effective_tenant:"sys", effective_tenant_id:1001, database:"oceanbase", user:"root@%", consistency_level:3, session_state:0, autocommit:true, tx:0x7f739a63a920}, desc=NULL, is_rollback=false, conflict_txs=[]) [2023-05-19 10:18:44.158573] INFO [PALF] handle_next_submit_log_ (log_sliding_window.cpp:1010) [85736][T1001_PLSSer][T1001][YB42AC160915-0005FBC6D5CA2F9D-0-0] [lt=18] [PALF STAT GROUP LOG INFO](palf_id=1, self="172.22.9.21:2882", role="LEADER", total_group_log_cnt=47, avg_log_batch_cnt=1, total_group_log_size=32130, avg_group_log_size=683) [2023-05-19 10:18:44.158585] INFO [PALF] submit_log (palf_handle_impl.cpp:403) [85736][T1001_PLSSer][T1001][YB42AC160915-0005FBC6D5CA2F9D-0-0] [lt=12] [PALF STAT APPEND DATA SIZE](this={palf_id:1, self:"172.22.9.21:2882", has_set_deleted:false}, append size=32232) [2023-05-19 10:18:44.158594] INFO [LIB] stat (utility.h:1140) [85736][T1001_PLSSer][T1001][YB42AC160915-0005FBC6D5CA2F9D-0-0] [lt=8] [PALF STAT APPEND COST](cur_stat_count=47, stat_interval=1000000, avg cost=0, this=0x7f743f9f0950) [2023-05-19 10:18:44.159002] INFO [ARCHIVE] gc_stale_ls_task_ (ob_ls_mgr.cpp:537) [208305][T1007_LSArchive][T1007][YB42AC160915-0005FBC6DD764D59-0-0] [lt=13] gc stale ls task succ [2023-05-19 10:18:44.168454] INFO [ARCHIVE] get_max_lsn_scn_ (ob_archive_fetcher.cpp:431) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=3] get end lsn scn succ(ret=0, id={id:1}, lsn={lsn:654058246}, scn={val:1684462724042343174}) [2023-05-19 10:18:44.168479] INFO [PALF] do_init_ (palf_iterator.h:265) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=16] PalfIterator init success(ret=0, start_offset={lsn:654058124}, this={iterator_impl:{buf_:null, next_round_pread_size:2121728, curr_read_pos:0, curr_read_buf_start_pos:0, curr_read_buf_end_pos:0, log_storage_:{IteratorStorage:{start_lsn:{lsn:654058124}, end_lsn:{lsn:654058124}, read_buf:{buf_len_:0, buf_:null}, block_size:67104768, log_storage_:0x7f736e9fa070}, IteratorStorageType::"DiskIteratorStorage"}, curr_entry_is_raw_write:false, curr_entry_size:0, prev_entry_scn:{val:18446744073709551615}, curr_entry:{LogGroupEntryHeader:{magic:0, version:0, group_size:0, proposal_id:9223372036854775807, committed_lsn:{lsn:18446744073709551615}, max_scn:{val:18446744073709551615}, accumulated_checksum:0, log_id:0, flag:0}}, init_mode_version:0, accumlate_checksum:-1}}) [2023-05-19 10:18:44.168912] INFO [PALF] verify_accum_checksum_ (log_iterator_impl.h:696) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=35] init accumlate_checksum to first LogGroupEntry(entry={LogGroupEntryHeader:{magic:18258, version:1, group_size:66, proposal_id:1, committed_lsn:{lsn:654058124}, max_scn:{val:1684462724042343174}, accumulated_checksum:2681272483, log_id:2513051, flag:0}}, this={buf_:0x7f73e4115000, next_round_pread_size:2121728, curr_read_pos:0, curr_read_buf_start_pos:0, curr_read_buf_end_pos:122, log_storage_:{IteratorStorage:{start_lsn:{lsn:654058124}, end_lsn:{lsn:654058246}, read_buf:{buf_len_:8192, buf_:0x7f73e4115000}, block_size:67104768, log_storage_:0x7f736e9fa070}, IteratorStorageType::"DiskIteratorStorage"}, curr_entry_is_raw_write:false, curr_entry_size:0, prev_entry_scn:{val:18446744073709551615}, curr_entry:{LogGroupEntryHeader:{magic:18258, version:1, group_size:66, proposal_id:1, committed_lsn:{lsn:654058124}, max_scn:{val:1684462724042343174}, accumulated_checksum:2681272483, log_id:2513051, flag:0}}, init_mode_version:0, accumlate_checksum:-1}, new_accumlate_checksum=2681272483) [2023-05-19 10:18:44.168940] INFO [ARCHIVE] build_send_task_ (ob_archive_fetcher.cpp:809) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=26] build send task succ(id={id:1}, station={key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}) [2023-05-19 10:18:44.168950] INFO [ARCHIVE] back_fill (ob_archive_task.cpp:134) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=9] print back fill task succ(cur_piece={interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset={lsn:654058124}, end_offset={lsn:654058246}, max_scn={val:1684462724042343174}, this={tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:603942912}, end_offset:{lsn:671047680}, cur_offset:{lsn:654058246}, unfinished_data_size:16989434, max_scn:{val:1684462724042343174}, send_task:{status:0, tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:654058124}, end_offset:{lsn:654058246}, max_scn:{val:1684462724042343174}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7305dfce40}, this:0x7f730bf3f180}) [2023-05-19 10:18:44.168975] INFO [ARCHIVE] update_log_fetch_task_ (ob_archive_fetcher.cpp:753) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=25] back fill log fetch task succ(fetch_task={tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:603942912}, end_offset:{lsn:671047680}, cur_offset:{lsn:654058246}, unfinished_data_size:16989434, max_scn:{val:1684462724042343174}, send_task:{status:0, tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:654058124}, end_offset:{lsn:654058246}, max_scn:{val:1684462724042343174}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7305dfce40}, this:0x7f730bf3f180}) [2023-05-19 10:18:44.168994] INFO [ARCHIVE] push_fetch_log (ob_ls_task.cpp:709) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=17] print push_fetch_log(task={tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:603942912}, end_offset:{lsn:671047680}, cur_offset:{lsn:654058246}, unfinished_data_size:16989434, max_scn:{val:1684462724042343174}, send_task:{status:0, tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:654058124}, end_offset:{lsn:654058246}, max_scn:{val:1684462724042343174}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7305dfce40}, this:0x7f730bf3f180}, this={has_encount_error:false, is_worm:false, max_archived_info:{offset:{lsn:654057392}, scn:{val:1684462723490537409}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}}, max_seq_log_offset:{lsn:671047680}, max_fetch_info:{offset:{lsn:654058124}, scn:{val:1684462723941817667}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}}, piece_min_lsn:{lsn:469733376}, archive_file_id:10, archive_file_offset:50114480, piece_dir_exist:true, wait_send_task_count:1, tasks:{tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:603942912}, end_offset:{lsn:671047680}, cur_offset:{lsn:654058246}, unfinished_data_size:16989434, max_scn:{val:1684462724042343174}, send_task:{status:0, tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:654058124}, end_offset:{lsn:654058246}, max_scn:{val:1684462724042343174}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7305dfce40}, this:0x7f730bf3f180}, {tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:603942912}, end_offset:{lsn:671047680}, cur_offset:{lsn:654058246}, unfinished_data_size:16989434, max_scn:{val:1684462724042343174}, send_task:{status:0, tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:654058124}, end_offset:{lsn:654058246}, max_scn:{val:1684462724042343174}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7305dfce40}, this:0x7f730bf3f180}}) [2023-05-19 10:18:44.169045] INFO [ARCHIVE] submit_fetch_log_ (ob_archive_fetcher.cpp:785) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=51] push fetch log succ(id={id:1}, &task=0x7f730bf3f180) [2023-05-19 10:18:44.169050] INFO [ARCHIVE] handle_log_fetch_task_ (ob_archive_fetcher.cpp:398) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=5] handle log fetch task succ(id={id:1}) [2023-05-19 10:18:44.169056] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:333) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=4] handle task succ(id={id:1}) [2023-05-19 10:18:44.169061] INFO [ARCHIVE] get_sorted_fetch_log (ob_ls_task.cpp:188) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=4] the same file(tuple={offset:{lsn:654058124}, scn:{val:1684462723941817667}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}}, tmp_task={tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:603942912}, end_offset:{lsn:671047680}, cur_offset:{lsn:654058246}, unfinished_data_size:16989434, max_scn:{val:1684462724042343174}, send_task:{status:0, tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:654058124}, end_offset:{lsn:654058246}, max_scn:{val:1684462724042343174}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7305dfce40}, this:0x7f730bf3f180}) [2023-05-19 10:18:44.169080] INFO [ARCHIVE] get_sorted_fetch_log_ (ob_archive_fetcher.cpp:886) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=19] get sorted fetch log succ(task={tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:603942912}, end_offset:{lsn:671047680}, cur_offset:{lsn:654058246}, unfinished_data_size:16989434, max_scn:{val:1684462724042343174}, send_task:{status:0, tenant_id:1008, id:{id:1}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:654058124}, end_offset:{lsn:654058246}, max_scn:{val:1684462724042343174}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7305dfce40}, this:0x7f730bf3f180}) [2023-05-19 10:18:44.169098] INFO [ARCHIVE] submit_send_task_ (ob_archive_fetcher.cpp:938) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=16] submit send task succ(send_task=0x7f7305dfce40) [2023-05-19 10:18:44.169103] INFO [ARCHIVE] submit_residual_log_fetch_task_ (ob_archive_fetcher.cpp:927) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=5] submit residual log fetch task succ(&task=0x7f730bf3f180) [2023-05-19 10:18:44.169113] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:341) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=3] try consume task status succ(id={id:1}) [2023-05-19 10:18:44.169120] INFO [ARCHIVE] get_max_lsn_scn_ (ob_archive_fetcher.cpp:431) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=4] get end lsn scn succ(ret=0, id={id:1001}, lsn={lsn:593559038}, scn={val:1684462724042343174}) [2023-05-19 10:18:44.169129] INFO [PALF] do_init_ (palf_iterator.h:265) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=6] PalfIterator init success(ret=0, start_offset={lsn:593558916}, this={iterator_impl:{buf_:null, next_round_pread_size:2121728, curr_read_pos:0, curr_read_buf_start_pos:0, curr_read_buf_end_pos:0, log_storage_:{IteratorStorage:{start_lsn:{lsn:593558916}, end_lsn:{lsn:593558916}, read_buf:{buf_len_:0, buf_:null}, block_size:67104768, log_storage_:0x7f7354a4e070}, IteratorStorageType::"DiskIteratorStorage"}, curr_entry_is_raw_write:false, curr_entry_size:0, prev_entry_scn:{val:18446744073709551615}, curr_entry:{LogGroupEntryHeader:{magic:0, version:0, group_size:0, proposal_id:9223372036854775807, committed_lsn:{lsn:18446744073709551615}, max_scn:{val:18446744073709551615}, accumulated_checksum:0, log_id:0, flag:0}}, init_mode_version:0, accumlate_checksum:-1}}) [2023-05-19 10:18:44.169501] INFO [PALF] verify_accum_checksum_ (log_iterator_impl.h:696) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=14] init accumlate_checksum to first LogGroupEntry(entry={LogGroupEntryHeader:{magic:18258, version:1, group_size:66, proposal_id:1, committed_lsn:{lsn:593558916}, max_scn:{val:1684462724042343174}, accumulated_checksum:1461032659, log_id:2230797, flag:1}}, this={buf_:0x7f736466d000, next_round_pread_size:2121728, curr_read_pos:0, curr_read_buf_start_pos:0, curr_read_buf_end_pos:122, log_storage_:{IteratorStorage:{start_lsn:{lsn:593558916}, end_lsn:{lsn:593559038}, read_buf:{buf_len_:8192, buf_:0x7f736466d000}, block_size:67104768, log_storage_:0x7f7354a4e070}, IteratorStorageType::"DiskIteratorStorage"}, curr_entry_is_raw_write:false, curr_entry_size:0, prev_entry_scn:{val:18446744073709551615}, curr_entry:{LogGroupEntryHeader:{magic:18258, version:1, group_size:66, proposal_id:1, committed_lsn:{lsn:593558916}, max_scn:{val:1684462724042343174}, accumulated_checksum:1461032659, log_id:2230797, flag:1}}, init_mode_version:0, accumlate_checksum:-1}, new_accumlate_checksum=1461032659) [2023-05-19 10:18:44.169527] INFO [ARCHIVE] build_send_task_ (ob_archive_fetcher.cpp:809) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=24] build send task succ(id={id:1001}, station={key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}) [2023-05-19 10:18:44.169534] INFO [ARCHIVE] back_fill (ob_archive_task.cpp:134) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=6] print back fill task succ(cur_piece={interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset={lsn:593558916}, end_offset={lsn:593559038}, max_scn={val:1684462724042343174}, this={tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:536838144}, end_offset:{lsn:603942912}, cur_offset:{lsn:593559038}, unfinished_data_size:10383874, max_scn:{val:1684462724042343174}, send_task:{status:0, tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:593558916}, end_offset:{lsn:593559038}, max_scn:{val:1684462724042343174}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73fc454030}, this:0x7f730bf3f070}) [2023-05-19 10:18:44.169553] INFO [ARCHIVE] update_log_fetch_task_ (ob_archive_fetcher.cpp:753) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=20] back fill log fetch task succ(fetch_task={tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:536838144}, end_offset:{lsn:603942912}, cur_offset:{lsn:593559038}, unfinished_data_size:10383874, max_scn:{val:1684462724042343174}, send_task:{status:0, tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:593558916}, end_offset:{lsn:593559038}, max_scn:{val:1684462724042343174}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73fc454030}, this:0x7f730bf3f070}) [2023-05-19 10:18:44.169570] INFO [ARCHIVE] push_fetch_log (ob_ls_task.cpp:709) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=16] print push_fetch_log(task={tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:536838144}, end_offset:{lsn:603942912}, cur_offset:{lsn:593559038}, unfinished_data_size:10383874, max_scn:{val:1684462724042343174}, send_task:{status:0, tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:593558916}, end_offset:{lsn:593559038}, max_scn:{val:1684462724042343174}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73fc454030}, this:0x7f730bf3f070}, this={has_encount_error:false, is_worm:false, max_archived_info:{offset:{lsn:593558550}, scn:{val:1684462723490537409}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}}, max_seq_log_offset:{lsn:603942912}, max_fetch_info:{offset:{lsn:593558916}, scn:{val:1684462723941817667}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}}, piece_min_lsn:{lsn:134209536}, archive_file_id:9, archive_file_offset:56720406, piece_dir_exist:true, wait_send_task_count:1, tasks:{tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:536838144}, end_offset:{lsn:603942912}, cur_offset:{lsn:593559038}, unfinished_data_size:10383874, max_scn:{val:1684462724042343174}, send_task:{status:0, tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:593558916}, end_offset:{lsn:593559038}, max_scn:{val:1684462724042343174}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73fc454030}, this:0x7f730bf3f070}, {tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:536838144}, end_offset:{lsn:603942912}, cur_offset:{lsn:593559038}, unfinished_data_size:10383874, max_scn:{val:1684462724042343174}, send_task:{status:0, tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:593558916}, end_offset:{lsn:593559038}, max_scn:{val:1684462724042343174}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73fc454030}, this:0x7f730bf3f070}}) [2023-05-19 10:18:44.169616] INFO [ARCHIVE] submit_fetch_log_ (ob_archive_fetcher.cpp:785) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=47] push fetch log succ(id={id:1001}, &task=0x7f730bf3f070) [2023-05-19 10:18:44.169621] INFO [ARCHIVE] handle_log_fetch_task_ (ob_archive_fetcher.cpp:398) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=4] handle log fetch task succ(id={id:1001}) [2023-05-19 10:18:44.169626] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:333) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=4] handle task succ(id={id:1001}) [2023-05-19 10:18:44.169630] INFO [ARCHIVE] get_sorted_fetch_log (ob_ls_task.cpp:188) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=3] the same file(tuple={offset:{lsn:593558916}, scn:{val:1684462723941817667}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}}, tmp_task={tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:536838144}, end_offset:{lsn:603942912}, cur_offset:{lsn:593559038}, unfinished_data_size:10383874, max_scn:{val:1684462724042343174}, send_task:{status:0, tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:593558916}, end_offset:{lsn:593559038}, max_scn:{val:1684462724042343174}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73fc454030}, this:0x7f730bf3f070}) [2023-05-19 10:18:44.169648] INFO [ARCHIVE] get_sorted_fetch_log_ (ob_archive_fetcher.cpp:886) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=17] get sorted fetch log succ(task={tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:536838144}, end_offset:{lsn:603942912}, cur_offset:{lsn:593559038}, unfinished_data_size:10383874, max_scn:{val:1684462724042343174}, send_task:{status:0, tenant_id:1008, id:{id:1001}, station:{key:{incarnation:1, dest_id:1005, round:3}, lease:{lease_id:1, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392186237043885}, piece_id:3, base_piece_id:3}, start_offset:{lsn:593558916}, end_offset:{lsn:593559038}, max_scn:{val:1684462724042343174}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73fc454030}, this:0x7f730bf3f070}) [2023-05-19 10:18:44.169663] INFO [ARCHIVE] submit_send_task_ (ob_archive_fetcher.cpp:938) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=16] submit send task succ(send_task=0x7f73fc454030) [2023-05-19 10:18:44.169668] INFO [ARCHIVE] submit_residual_log_fetch_task_ (ob_archive_fetcher.cpp:927) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=4] submit residual log fetch task succ(&task=0x7f730bf3f070) [2023-05-19 10:18:44.169671] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:341) [208150][T1008_ArcFetche][T1008][YB42AC160915-0005FBC6DD464D59-0-0] [lt=4] try consume task status succ(id={id:1001}) [2023-05-19 10:18:44.170314] INFO [ARCHIVE] push_log_ (ob_archive_sender.cpp:735) [86303][T1002_ArcSender][T1002][YB42AC160915-0005FBC6CE964D59-0-0] [lt=4] push log succ(id={id:1}) [2023-05-19 10:18:44.170326] INFO [ARCHIVE] archive_log_ (ob_archive_sender.cpp:623) [86303][T1002_ArcSender][T1002][YB42AC160915-0005FBC6CE964D59-0-0] [lt=14] finish task succ(id={id:1}) [2023-05-19 10:18:44.170332] INFO [ARCHIVE] handle (ob_archive_sender.cpp:467) [86303][T1002_ArcSender][T1002][YB42AC160915-0005FBC6CE964D59-0-0] [lt=5] archive log succ(id={id:1}) [2023-05-19 10:18:44.170343] INFO [ARCHIVE] update_archive_progress (ob_ls_task.cpp:376) [86303][T1002_ArcSender][T1002][YB42AC160915-0005FBC6CE964D59-0-0] [lt=4] update archive progress succ(ret=0, id_={id:1001}, station={key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, tuple={offset:{lsn:11269479673}, scn:{val:1684462723740487874}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, file_id=168, file_offset=62983417) [2023-05-19 10:18:44.170367] INFO [ARCHIVE] update_archive_progress (ob_ls_task.cpp:376) [86303][T1002_ArcSender][T1002][YB42AC160915-0005FBC6CE964D59-0-0] [lt=15] update archive progress succ(ret=0, id_={id:1}, station={key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, tuple={offset:{lsn:2398110356}, scn:{val:1684462723705090311}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, file_id=36, file_offset=49443476) [2023-05-19 10:18:44.172378] INFO [ARCHIVE] get_commit_index_ (ob_archive_sequencer.cpp:259) [208149][T1008_ArcSeq][T1008][YB42AC160915-0005FBC6DD064D59-0-0] [lt=4] get commit index succ [2023-05-19 10:18:44.172624] INFO [ARCHIVE] get_commit_index_ (ob_archive_sequencer.cpp:259) [208149][T1008_ArcSeq][T1008][YB42AC160915-0005FBC6DD064D59-0-0] [lt=10] get commit index succ [2023-05-19 10:18:44.172757] INFO [ARCHIVE] get_max_lsn_scn_ (ob_archive_fetcher.cpp:431) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=4] get end lsn scn succ(ret=0, id={id:1}, lsn={lsn:2398110722}, scn={val:1684462724042151560}) [2023-05-19 10:18:44.172770] INFO [PALF] do_init_ (palf_iterator.h:265) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=7] PalfIterator init success(ret=0, start_offset={lsn:2398110600}, this={iterator_impl:{buf_:null, next_round_pread_size:2121728, curr_read_pos:0, curr_read_buf_start_pos:0, curr_read_buf_end_pos:0, log_storage_:{IteratorStorage:{start_lsn:{lsn:2398110600}, end_lsn:{lsn:2398110600}, read_buf:{buf_len_:0, buf_:null}, block_size:67104768, log_storage_:0x7f742d3da070}, IteratorStorageType::"DiskIteratorStorage"}, curr_entry_is_raw_write:false, curr_entry_size:0, prev_entry_scn:{val:18446744073709551615}, curr_entry:{LogGroupEntryHeader:{magic:0, version:0, group_size:0, proposal_id:9223372036854775807, committed_lsn:{lsn:18446744073709551615}, max_scn:{val:18446744073709551615}, accumulated_checksum:0, log_id:0, flag:0}}, init_mode_version:0, accumlate_checksum:-1}}) [2023-05-19 10:18:44.173139] INFO [PALF] verify_accum_checksum_ (log_iterator_impl.h:696) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=18] init accumlate_checksum to first LogGroupEntry(entry={LogGroupEntryHeader:{magic:18258, version:1, group_size:66, proposal_id:7, committed_lsn:{lsn:2398110600}, max_scn:{val:1684462724042151560}, accumulated_checksum:1998166793, log_id:9782343, flag:1}}, this={buf_:0x7f7368437000, next_round_pread_size:2121728, curr_read_pos:0, curr_read_buf_start_pos:0, curr_read_buf_end_pos:122, log_storage_:{IteratorStorage:{start_lsn:{lsn:2398110600}, end_lsn:{lsn:2398110722}, read_buf:{buf_len_:8192, buf_:0x7f7368437000}, block_size:67104768, log_storage_:0x7f742d3da070}, IteratorStorageType::"DiskIteratorStorage"}, curr_entry_is_raw_write:false, curr_entry_size:0, prev_entry_scn:{val:18446744073709551615}, curr_entry:{LogGroupEntryHeader:{magic:18258, version:1, group_size:66, proposal_id:7, committed_lsn:{lsn:2398110600}, max_scn:{val:1684462724042151560}, accumulated_checksum:1998166793, log_id:9782343, flag:1}}, init_mode_version:0, accumlate_checksum:-1}, new_accumlate_checksum=1998166793) [2023-05-19 10:18:44.173158] INFO [ARCHIVE] build_send_task_ (ob_archive_fetcher.cpp:809) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=18] build send task succ(id={id:1}, station={key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}) [2023-05-19 10:18:44.173165] INFO [ARCHIVE] back_fill (ob_archive_task.cpp:134) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=6] print back fill task succ(cur_piece={interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset={lsn:2398110600}, end_offset={lsn:2398110722}, max_scn={val:1684462724042151560}, this={tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:2348666880}, end_offset:{lsn:2415771648}, cur_offset:{lsn:2398110722}, unfinished_data_size:17660926, max_scn:{val:1684462724042151560}, send_task:{status:0, tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:2398110600}, end_offset:{lsn:2398110722}, max_scn:{val:1684462724042151560}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73685fa030}, this:0x7f739d8e04c0}) [2023-05-19 10:18:44.173186] INFO [ARCHIVE] update_log_fetch_task_ (ob_archive_fetcher.cpp:753) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=20] back fill log fetch task succ(fetch_task={tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:2348666880}, end_offset:{lsn:2415771648}, cur_offset:{lsn:2398110722}, unfinished_data_size:17660926, max_scn:{val:1684462724042151560}, send_task:{status:0, tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:2398110600}, end_offset:{lsn:2398110722}, max_scn:{val:1684462724042151560}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73685fa030}, this:0x7f739d8e04c0}) [2023-05-19 10:18:44.173203] INFO [ARCHIVE] push_fetch_log (ob_ls_task.cpp:709) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=15] print push_fetch_log(task={tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:2348666880}, end_offset:{lsn:2415771648}, cur_offset:{lsn:2398110722}, unfinished_data_size:17660926, max_scn:{val:1684462724042151560}, send_task:{status:0, tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:2398110600}, end_offset:{lsn:2398110722}, max_scn:{val:1684462724042151560}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73685fa030}, this:0x7f739d8e04c0}, this={has_encount_error:false, is_worm:false, max_archived_info:{offset:{lsn:2398110356}, scn:{val:1684462723705090311}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, max_seq_log_offset:{lsn:2415771648}, max_fetch_info:{offset:{lsn:2398110600}, scn:{val:1684462723941659070}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, piece_min_lsn:{lsn:2147352576}, archive_file_id:36, archive_file_offset:49443476, piece_dir_exist:true, wait_send_task_count:1, tasks:{tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:2348666880}, end_offset:{lsn:2415771648}, cur_offset:{lsn:2398110722}, unfinished_data_size:17660926, max_scn:{val:1684462724042151560}, send_task:{status:0, tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:2398110600}, end_offset:{lsn:2398110722}, max_scn:{val:1684462724042151560}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73685fa030}, this:0x7f739d8e04c0}, {tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:2348666880}, end_offset:{lsn:2415771648}, cur_offset:{lsn:2398110722}, unfinished_data_size:17660926, max_scn:{val:1684462724042151560}, send_task:{status:0, tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:2398110600}, end_offset:{lsn:2398110722}, max_scn:{val:1684462724042151560}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73685fa030}, this:0x7f739d8e04c0}}) [2023-05-19 10:18:44.173258] INFO [ARCHIVE] submit_fetch_log_ (ob_archive_fetcher.cpp:785) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=56] push fetch log succ(id={id:1}, &task=0x7f739d8e04c0) [2023-05-19 10:18:44.173262] INFO [ARCHIVE] handle_log_fetch_task_ (ob_archive_fetcher.cpp:398) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=3] handle log fetch task succ(id={id:1}) [2023-05-19 10:18:44.173266] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:333) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=3] handle task succ(id={id:1}) [2023-05-19 10:18:44.173270] INFO [ARCHIVE] get_sorted_fetch_log (ob_ls_task.cpp:188) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=3] the same file(tuple={offset:{lsn:2398110600}, scn:{val:1684462723941659070}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, tmp_task={tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:2348666880}, end_offset:{lsn:2415771648}, cur_offset:{lsn:2398110722}, unfinished_data_size:17660926, max_scn:{val:1684462724042151560}, send_task:{status:0, tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:2398110600}, end_offset:{lsn:2398110722}, max_scn:{val:1684462724042151560}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73685fa030}, this:0x7f739d8e04c0}) [2023-05-19 10:18:44.173287] INFO [ARCHIVE] get_sorted_fetch_log_ (ob_archive_fetcher.cpp:886) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=17] get sorted fetch log succ(task={tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:2348666880}, end_offset:{lsn:2415771648}, cur_offset:{lsn:2398110722}, unfinished_data_size:17660926, max_scn:{val:1684462724042151560}, send_task:{status:0, tenant_id:1002, id:{id:1}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:2398110600}, end_offset:{lsn:2398110722}, max_scn:{val:1684462724042151560}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f73685fa030}, this:0x7f739d8e04c0}) [2023-05-19 10:18:44.173302] INFO [ARCHIVE] submit_send_task_ (ob_archive_fetcher.cpp:938) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=15] submit send task succ(send_task=0x7f73685fa030) [2023-05-19 10:18:44.173306] INFO [ARCHIVE] submit_residual_log_fetch_task_ (ob_archive_fetcher.cpp:927) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=3] submit residual log fetch task succ(&task=0x7f739d8e04c0) [2023-05-19 10:18:44.173312] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:341) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=3] try consume task status succ(id={id:1}) [2023-05-19 10:18:44.173316] INFO [ARCHIVE] get_max_lsn_scn_ (ob_archive_fetcher.cpp:431) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=3] get end lsn scn succ(ret=0, id={id:1001}, lsn={lsn:11269480039}, scn={val:1684462724042151560}) [2023-05-19 10:18:44.173323] INFO [PALF] do_init_ (palf_iterator.h:265) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=5] PalfIterator init success(ret=0, start_offset={lsn:11269479917}, this={iterator_impl:{buf_:null, next_round_pread_size:2121728, curr_read_pos:0, curr_read_buf_start_pos:0, curr_read_buf_end_pos:0, log_storage_:{IteratorStorage:{start_lsn:{lsn:11269479917}, end_lsn:{lsn:11269479917}, read_buf:{buf_len_:0, buf_:null}, block_size:67104768, log_storage_:0x7f742d3e4070}, IteratorStorageType::"DiskIteratorStorage"}, curr_entry_is_raw_write:false, curr_entry_size:0, prev_entry_scn:{val:18446744073709551615}, curr_entry:{LogGroupEntryHeader:{magic:0, version:0, group_size:0, proposal_id:9223372036854775807, committed_lsn:{lsn:18446744073709551615}, max_scn:{val:18446744073709551615}, accumulated_checksum:0, log_id:0, flag:0}}, init_mode_version:0, accumlate_checksum:-1}}) [2023-05-19 10:18:44.173363] INFO [ARCHIVE] get_commit_index_ (ob_archive_sequencer.cpp:259) [86301][T1002_ArcSeq][T1002][YB42AC160915-0005FBC6CE764D59-0-0] [lt=5] get commit index succ [2023-05-19 10:18:44.173605] INFO [ARCHIVE] get_commit_index_ (ob_archive_sequencer.cpp:259) [86301][T1002_ArcSeq][T1002][YB42AC160915-0005FBC6CE764D59-0-0] [lt=4] get commit index succ [2023-05-19 10:18:44.173767] INFO [PALF] verify_accum_checksum_ (log_iterator_impl.h:696) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=13] init accumlate_checksum to first LogGroupEntry(entry={LogGroupEntryHeader:{magic:18258, version:1, group_size:66, proposal_id:7, committed_lsn:{lsn:11269479917}, max_scn:{val:1684462724042151560}, accumulated_checksum:2746121053, log_id:14322879, flag:0}}, this={buf_:0x7f7368439000, next_round_pread_size:2121728, curr_read_pos:0, curr_read_buf_start_pos:0, curr_read_buf_end_pos:122, log_storage_:{IteratorStorage:{start_lsn:{lsn:11269479917}, end_lsn:{lsn:11269480039}, read_buf:{buf_len_:8192, buf_:0x7f7368439000}, block_size:67104768, log_storage_:0x7f742d3e4070}, IteratorStorageType::"DiskIteratorStorage"}, curr_entry_is_raw_write:false, curr_entry_size:0, prev_entry_scn:{val:18446744073709551615}, curr_entry:{LogGroupEntryHeader:{magic:18258, version:1, group_size:66, proposal_id:7, committed_lsn:{lsn:11269479917}, max_scn:{val:1684462724042151560}, accumulated_checksum:2746121053, log_id:14322879, flag:0}}, init_mode_version:0, accumlate_checksum:-1}, new_accumlate_checksum=2746121053) [2023-05-19 10:18:44.173786] INFO [ARCHIVE] build_send_task_ (ob_archive_fetcher.cpp:809) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=17] build send task succ(id={id:1001}, station={key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}) [2023-05-19 10:18:44.173792] INFO [ARCHIVE] back_fill (ob_archive_task.cpp:134) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=5] print back fill task succ(cur_piece={interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset={lsn:11269479917}, end_offset={lsn:11269480039}, max_scn={val:1684462724042151560}, this={tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269480039}, unfinished_data_size:4120985, max_scn:{val:1684462724042151560}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479917}, end_offset:{lsn:11269480039}, max_scn:{val:1684462724042151560}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7368436030}, this:0x7f739d8e0c30}) [2023-05-19 10:18:44.173811] INFO [ARCHIVE] update_log_fetch_task_ (ob_archive_fetcher.cpp:753) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=18] back fill log fetch task succ(fetch_task={tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269480039}, unfinished_data_size:4120985, max_scn:{val:1684462724042151560}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479917}, end_offset:{lsn:11269480039}, max_scn:{val:1684462724042151560}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7368436030}, this:0x7f739d8e0c30}) [2023-05-19 10:18:44.173827] INFO [ARCHIVE] push_fetch_log (ob_ls_task.cpp:709) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=14] print push_fetch_log(task={tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269480039}, unfinished_data_size:4120985, max_scn:{val:1684462724042151560}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479917}, end_offset:{lsn:11269480039}, max_scn:{val:1684462724042151560}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7368436030}, this:0x7f739d8e0c30}, this={has_encount_error:false, is_worm:false, max_archived_info:{offset:{lsn:11269479673}, scn:{val:1684462723740487874}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, max_seq_log_offset:{lsn:11273601024}, max_fetch_info:{offset:{lsn:11269479917}, scn:{val:1684462723941659070}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, piece_min_lsn:{lsn:10334134272}, archive_file_id:168, archive_file_offset:62983417, piece_dir_exist:true, wait_send_task_count:1, tasks:{tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269480039}, unfinished_data_size:4120985, max_scn:{val:1684462724042151560}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479917}, end_offset:{lsn:11269480039}, max_scn:{val:1684462724042151560}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7368436030}, this:0x7f739d8e0c30}, {tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269480039}, unfinished_data_size:4120985, max_scn:{val:1684462724042151560}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479917}, end_offset:{lsn:11269480039}, max_scn:{val:1684462724042151560}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7368436030}, this:0x7f739d8e0c30}}) [2023-05-19 10:18:44.173873] INFO [ARCHIVE] submit_fetch_log_ (ob_archive_fetcher.cpp:785) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=46] push fetch log succ(id={id:1001}, &task=0x7f739d8e0c30) [2023-05-19 10:18:44.173876] INFO [ARCHIVE] handle_log_fetch_task_ (ob_archive_fetcher.cpp:398) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=4] handle log fetch task succ(id={id:1001}) [2023-05-19 10:18:44.173880] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:333) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=3] handle task succ(id={id:1001}) [2023-05-19 10:18:44.173884] INFO [ARCHIVE] get_sorted_fetch_log (ob_ls_task.cpp:188) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=3] the same file(tuple={offset:{lsn:11269479917}, scn:{val:1684462723941659070}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}}, tmp_task={tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269480039}, unfinished_data_size:4120985, max_scn:{val:1684462724042151560}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479917}, end_offset:{lsn:11269480039}, max_scn:{val:1684462724042151560}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7368436030}, this:0x7f739d8e0c30}) [2023-05-19 10:18:44.173901] INFO [ARCHIVE] get_sorted_fetch_log_ (ob_archive_fetcher.cpp:886) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=17] get sorted fetch log succ(task={tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, cur_piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, next_piece:{interval_us:86400000000, genesis_scn:{val:18446744073709551615}, piece_id:0, base_piece_id:0}, start_offset:{lsn:11206496256}, end_offset:{lsn:11273601024}, cur_offset:{lsn:11269480039}, unfinished_data_size:4120985, max_scn:{val:1684462724042151560}, send_task:{status:0, tenant_id:1002, id:{id:1001}, station:{key:{incarnation:1, dest_id:1012, round:33}, lease:{lease_id:7, lease_start_ts:0, lease_end_ts:0}}, piece:{interval_us:86400000000, genesis_scn:{val:1684392095608891270}, piece_id:33, base_piece_id:33}, start_offset:{lsn:11269479917}, end_offset:{lsn:11269480039}, max_scn:{val:1684462724042151560}, file_id:0, file_offset:-1, data:G, data_len:122, this:0x7f7368436030}, this:0x7f739d8e0c30}) [2023-05-19 10:18:44.173916] INFO [ARCHIVE] submit_send_task_ (ob_archive_fetcher.cpp:938) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=15] submit send task succ(send_task=0x7f7368436030) [2023-05-19 10:18:44.173919] INFO [ARCHIVE] submit_residual_log_fetch_task_ (ob_archive_fetcher.cpp:927) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=3] submit residual log fetch task succ(&task=0x7f739d8e0c30) [2023-05-19 10:18:44.173924] INFO [ARCHIVE] handle_single_task_ (ob_archive_fetcher.cpp:341) [86302][T1002_ArcFetche][T1002][YB42AC160915-0005FBC6CE864D59-0-0] [lt=2] try consume task status succ(id={id:1001}) [2023-05-19 10:18:44.173934] INFO [ARCHIVE] get_commit_index_ (ob_archive_sequencer.cpp:259) [86301][T1002_ArcSeq][T1002][YB42AC160915-0005FBC6CE764D59-0-0] [lt=3] get commit index succ [2023-05-19 10:18:44.174122] INFO [ARCHIVE] get_commit_index_ (ob_archive_sequencer.cpp:259) [86301][T1002_ArcSeq][T1002][YB42AC160915-0005FBC6CE764D59-0-0] [lt=3] get commit index succ [root@dbatest-9-21 log]#