Found an update with max stop timestamp when inserting data to the history store

XMLWordPrintableJSON

    • Storage Engines, Storage Engines - Transactions
    • None
    • 13

      I tried to enable precise checkpoint and preserve prepared in test format. There is a test failure.

      [2025/08/21 13:39:15.368] #0  0x0000ffffb86bf9b4 in __pthread_kill_implementation () from /lib64/libc.so.6
[2025/08/21 13:39:15.374] #1  0x0000ffffb86763a0 [PAC] in raise () from /lib64/libc.so.6
[2025/08/21 13:39:15.374] #2  0x0000ffffb8662264 [PAC] in abort () from /lib64/libc.so.6
[2025/08/21 13:39:15.381] #3  0x0000ffffb884a1f4 [PAC] in __wt_abort (session=session@entry=0x71acffe9d888) at /data/mci/fa5895375eaf509866bba6c89762cc45/wiredtiger/src/os_common/os_abort.c:31
[2025/08/21 13:39:15.392] #4  0x0000ffffb89c61e0 in __rec_hs_cursor_pos (session=session@entry=0x71acffe9d888, hs_cursor=hs_cursor@entry=0x71acf104cc80, ts=1435995, upd_tw=upd_tw@entry=0xffffaeb0c820, non_ts_updates=non_ts_updates@entry=0xffffaeb0c2a3, key=0x71ace19d8440, btree_id=<optimized out>) at /data/mci/fa5895375eaf509866bba6c89762cc45/wiredtiger/src/reconcile/rec_hs.c:347
[2025/08/21 13:39:15.392] #5  0x0000ffffb89c6358 in __rec_hs_insert_record (session=session@entry=0x71acffe9d888, cursor=0x71acf104cc80, btree=btree@entry=0x71acffffb8c0, key=0x71ace19d8440, type=type@entry=3 '\003', hs_value=0x71acd5740800, tw=tw@entry=0xffffaeb0c820, error_on_ts_ordering=<optimized out>, error_on_ts_ordering@entry=false) at /data/mci/fa5895375eaf509866bba6c89762cc45/wiredtiger/src/reconcile/rec_hs.c:514
[2025/08/21 13:39:15.392] #6  0x0000ffffb89c732c in __wti_rec_hs_insert_updates (session=session@entry=0x71acffe9d888, r=r@entry=0x71acd6f78b00, multi=multi@entry=0x71ace3b25680) at /data/mci/fa5895375eaf509866bba6c89762cc45/wiredtiger/src/reconcile/rec_hs.c:1111
[2025/08/21 13:39:15.392] #7  0x0000ffffb89bc858 in __rec_hs_wrapup (session=0x71acffe9d888, r=0x71acd6f78b00) at /data/mci/fa5895375eaf509866bba6c89762cc45/wiredtiger/src/reconcile/rec_write.c:3526
[2025/08/21 13:39:15.400] #8  __rec_write_wrapup (session=session@entry=0x71acffe9d888, r=r@entry=0x71acd6f78b00) at /data/mci/fa5895375eaf509866bba6c89762cc45/wiredtiger/src/reconcile/rec_write.c:3198
[2025/08/21 13:39:15.407] #9  0x0000ffffb89bda28 in __reconcile (session=0x71acffe9d888, ref=0x71acfe3df6c0, salvage=0x0, flags=5282, page_lockedp=0xffffaeb0d61f) at /data/mci/fa5895375eaf509866bba6c89762cc45/wiredtiger/src/reconcile/rec_write.c:363
[2025/08/21 13:39:15.407] #10 __wt_reconcile (session=session@entry=0x71acffe9d888, ref=ref@entry=0x71acfe3df6c0, salvage=salvage@entry=0x0, flags=5282) at /data/mci/fa5895375eaf509866bba6c89762cc45/wiredtiger/src/reconcile/rec_write.c:124
[2025/08/21 13:39:15.407] #11 0x0000ffffb895c934 in __evict_reconcile (session=0x71acffe9d888, ref=0x71acfe3df6c0, evict_flags=4) at /data/mci/fa5895375eaf509866bba6c89762cc45/wiredtiger/src/evict/evict_page.c:1047
[2025/08/21 13:39:15.422] #12 __wt_evict (session=session@entry=0x71acffe9d888, ref=ref@entry=0x71acfe3df6c0, previous_state=previous_state@entry=3 '\003', flags=4) at /data/mci/fa5895375eaf509866bba6c89762cc45/wiredtiger/src/evict/evict_page.c:236
[2025/08/21 13:39:15.432] #13 0x0000ffffb889ad38 in __wt_page_release_evict (session=session@entry=0x71acffe9d888, ref=ref@entry=0x71acfe3df6c0, flags=flags@entry=0) at /data/mci/fa5895375eaf509866bba6c89762cc45/wiredtiger/src/btree/bt_read.c:130
[2025/08/21 13:39:15.441] #14 0x0000ffffb887b2d4 in __cursor_reset (cbt=cbt@entry=0x71acdf90aa00) at /data/mci/fa5895375eaf509866bba6c89762cc45/wiredtiger/src/include/cursor_inline.h:307
[2025/08/21 13:39:15.441] #15 0x0000ffffb887c9bc in __wt_btcur_reset (cbt=cbt@entry=0x71acdf90aa00) at /data/mci/fa5895375eaf509866bba6c89762cc45/wiredtiger/src/btree/bt_cursor.c:629
[2025/08/21 13:39:15.441] #16 0x0000ffffb8911da0 in __curfile_reset (cursor=0x71acdf90aa00) at /data/mci/fa5895375eaf509866bba6c89762cc45/wiredtiger/src/cursor/cur_file.c:273
[2025/08/21 13:39:15.441] #17 0x0000000000410724 in table_op (tinfo=tinfo@entry=0x71acf641f400, intxn=intxn@entry=true, iso_level=iso_level@entry=ISOLATION_SNAPSHOT, op=<optimized out>, op@entry=UPDATE) at /data/mci/fa5895375eaf509866bba6c89762cc45/wiredtiger/test/format/ops.c:953
[2025/08/21 13:39:15.441] #18 0x0000000000412fe8 in ops (arg=0x71acf641f400) at /data/mci/fa5895375eaf509866bba6c89762cc45/wiredtiger/test/format/ops.c:1341
[2025/08/21 13:39:15.442] #19 0x0000ffffb86bdd78 in start_thread () from /lib64/libc.so.6
[2025/08/21 13:39:15.442] #20 0x0000ffffb872addc [PAC] in thread_start () from /lib64/libc.so.6

      The core dump shows that there is a record in the history store with max stop timestamp. It is likely that in our new prepared commit or rollback code, we failed to delete this record from the history store.

      (gdb) p *twp
$4 = {
  durable_start_ts = 1414327,
  start_ts = 1414327,
  start_prepare_ts = 0,
  start_txn = 1211396,
  start_prepared_id = 0,
  durable_stop_ts = 0,
  stop_ts = 18446744073709551615,
  stop_prepare_ts = 0,
  stop_txn = 18446744073709551605,
  stop_prepared_id = 0
}

            Assignee:
            Chenhao Qu
            Reporter:
            Chenhao Qu
            Votes:
            0 Vote for this issue
            Watchers:
            1 Start watching this issue

              Created:
              Updated:
              Resolved: