Loading...

XML

Word

Printable

JSON

Type: Build Failure
Resolution: Cannot Reproduce
Priority: Critical - P2
Fix Version/s: None
Affects Version/s: None
Component/s: Logging
Labels:
- BB-Tools

Assigned Teams:

Storage Engines, Storage Engines - Persistence
Sprint:
SE Persistence backlog
Story Points:
3
Evergreen Project:
- wiredtiger-mongo-v7.0
Failure Type:
- failed
Failing Buildvariants:
- macos-1100
Failing Tasks:
- unit-test-macos
First Failing Revision:
- 51cd3b7453ef3e4fcd93b7f9fadfe16213f69d89(wiredtiger-mongo-v7.0)
Linked BFG List:
https://buildbaron.corp.mongodb.com/ui/#/bf/WT-14850
Count of Linked BFGs (Last 30 days):
2

Host: macos-11-amd64-3.mac.devprod.mongodb.com
Project: wiredtiger-mongo-v7.0
Commit: 51cd3b74
Please refer to BF(G) Playbook for instructions on handling BF and BFG tickets as well as Auto-Resolution Rules

Task Logs:

task unit-test-macos

unit-test-macos task_log

Logs:

test_timestamp03.test_timestamp03.test_timestamp03(file-col.use_ts_false.V1), 
        txn-recover: [WT_VERB_LOG][NOTICE]: WiredTigerLog.0000000002: log record at position 36801536 has record len corruption 0x0
        txn-recover: [WT_VERB_LOG][NOTICE]: log file WiredTigerLog.0000000002 corrupted at position 36801536, truncated
        txn-recover: [WT_VERB_LOG][NOTICE]: salvage: log file 2 truncated

[pid:78975]: ERROR: test_cursor_bound02.test_cursor_bound02.test_bound_api(file.bytes.string.no-inclusive)
----------------------------------------------------------------------
[pid:78975]: testtools.testresult.real._StringException: lost connection during test 'test_cursor_bound02.test_cursor_bound02.test_bound_api(file.bytes.string.no-inclusive)'
======================================================================
[pid:78975]: FAIL: test_timestamp03.test_timestamp03.test_timestamp03(file-col.use_ts_false.V1)
----------------------------------------------------------------------
[pid:78975]: testtools.testresult.real._StringException: Traceback (most recent call last):
  File "/Users/ec2-user/data/mci/d4ae5c30c1bff6f69c4408d8dee94073/wiredtiger/test/suite/wttest.py", line 782, in tearDown
    self.captureout.check(self)
  File "/Users/ec2-user/data/mci/d4ae5c30c1bff6f69c4408d8dee94073/wiredtiger/test/suite/wttest.py", line 156, in check
    testcase.fail('unexpected ' + self.desc + ', contains: "' +
  File "/Applications/Xcode12.4.app/Contents/Developer/Library/Frameworks/Python3.framework/Versions/3.8/lib/python3.8/unittest/case.py", line 753, in fail
    raise self.failureException(msg)
AssertionError: unexpected standard output, contains: "[1750776286:225383][78978:0x10f233e00], test_timestamp03.test_timestamp03.test_timestamp03(file-col.use_ts_false.V1), txn-recover: [WT_VERB_LOG][NOTICE]: ./WiredTigerLog.0000000002: log record at position 36801536 has record len corruption 0x0
[1750776286:228081][78978:0x10f233e00], test_timestamp03.test_timestamp03.test_timestamp03(file-col.use_ts_false.V1), txn-recover: [WT_VERB_LOG][NOTICE]: log file ./WiredTigerLog.0000000002 corrupted at position 36801536, truncated
[1750776286:247264][78978:0x10f233e00], test_timestamp03.test_timestamp03.test_timestamp03(file-col.use_ts_false.V1), txn-recover: [WT_VERB_LOG][NOTICE]: salvage: log file 2 truncated
"
----------------------------------------------------------------------
Ran 12694 tests in 5765.644s

logs

unit-test-macos task_log

Logs:

[1750777436:582525][78978:0x10f233e00], test_cursor_bound02.test_cursor_bound02.test_bound_api(file.bytes.string.no-inclusive), WT_CURSOR.bound: [WT_VERB_DEFAULT][ERROR]: int __wt_cursor_bound(WT_CURSOR *, const char *), 1269: The provided cursor bounds are overlapping: Invalid argument
[1750777436:586526][78978:0x10f233e00], test_cursor_bound02.test_cursor_bound02.test_bound_api(file.bytes.string.no-inclusive), WT_CURSOR.bound: [WT_VERB_DEFAULT][ERROR]: int __wt_cursor_bound(WT_CURSOR *, const char *), 1295: The provided cursor bounds are overlapping: Invalid argument
[1750777436:588523][78978:0x10f233e00], test_cursor_bound02.test_cursor_bound02.test_bound_api(file.bytes.string.no-inclusive), WT_CURSOR.bound: [WT_VERB_DEFAULT][ERROR]: int __wt_cursor_kv_not_set(WT_CURSOR *, _Bool), 256: requires key be set: Invalid argument
[1750777436:593228][78978:0x10f233e00], test_cursor_bound02.test_cursor_bound02.test_bound_api(file.bytes.string.no-inclusive), WT_CURSOR.bound: [WT_VERB_DEFAULT][ERROR]: int __wt_cursor_kv_not_set(WT_CURSOR *, _Bool), 256: requires key be set: Invalid argument
[1750777436:593736][78978:0x10f233e00], test_cursor_bound02.test_cursor_bound02.test_bound_api(file.bytes.string.no-inclusive), WT_CURSOR.bound: [WT_VERB_DEFAULT][ERROR]: int __wt_cursor_bound(WT_CURSOR *, const char *), 1302: The provided cursor bounds are equal but not inclusive: Invalid argument
[1750777436:603873][78978:0x10f233e00], test_cursor_bound02.test_cursor_bound02.test_bound_api(file.bytes.string.no-inclusive), WT_CURSOR.bound: [WT_VERB_DEFAULT][ERROR]: int __wt_cursor_bound(WT_CURSOR *, const char *), 1276: The provided cursor bounds are equal but not inclusive: Invalid argument
[1750777436:604045][78978:0x10f233e00], test_cursor_bound02.test_cursor_bound02.test_bound_api(file.bytes.string.no-inclusive), WT_CURSOR.bound: [WT_VERB_DEFAULT][ERROR]: int __wt_cursor_bound(WT_CURSOR *, const char *), 1276: The provided cursor bounds are equal but not inclusive: Invalid argument
[1750777436:608449][78978:0x10f233e00], test_cursor_bound02.test_cursor_bound02.test_bound_api(file.bytes.string.no-inclusive), WT_CURSOR.bound: [WT_VERB_DEFAULT][ERROR]: int __wt_cursor_bound(WT_CURSOR *, const char *), 1276: The provided cursor bounds are equal but not inclusive: Invalid argument
[1750777436:611854][78978:0x10f233e00], test_cursor_bound02.test_cursor_bound02.test_bound_api(file.bytes.string.no-inclusive), WT_CURSOR.bound: [WT_VERB_DEFAULT][ERROR]: int __wt_cursor_bound(WT_CURSOR *, const char *), 1302: The provided cursor bounds are equal but not inclusive: Invalid argument
[1750777436:612070][78978:0x10f233e00], test_cursor_bound02.test_cursor_bound02.test_bound_api(file.bytes.string.no-inclusive), WT_CURSOR.bound: [WT_VERB_DEFAULT][ERROR]: int __wt_cursor_bound(WT_CURSOR *, const char *), 1302: The provided cursor bounds are equal but not inclusive: Invalid argument

logs

unit-test-macos task_log

Logs:

[1750775006:312141][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99032, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312155][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99033, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312167][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99034, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312179][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99035, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312189][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99036, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312200][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99037, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312211][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99038, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312221][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99039, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312253][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99040, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312264][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99041, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312272][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99042, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312281][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99043, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312289][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99044, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312298][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99045, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312306][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99046, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312314][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99047, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312323][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99048, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312331][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99049, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312339][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99050, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312356][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99051, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312365][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99052, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312373][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99053, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312382][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99054, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312390][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99055, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312398][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99056, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312406][78977:0x10f233e00], test_rollback_to_stable22.test_rollback_to_stable22.test_rollback_to_stable, file:rollback_to_stable22_0.wt, txn rollback_to_stable: [WT_VERB_RTS][DEBUG_1]: [UPDATE_ABORT] rollback to stable aborting update with txnid=99057, txnid_not_visible=false, stable_timestamp=(0, 990) < durable_timestamp=(0, 1000): true, prepare_state=WT_PREPARE_INIT
[1750775006:312415][78977:0x10f233e00], te

logs

Assignee:: [DO NOT USE] Backlog - Storage Engines Team
Reporter:: xgen-buildbaron-user
Votes:: 0 Vote for this issue
Watchers:: 2 Start watching this issue

Created:: Jun 26 2025 12:22:22 AM UTC
Updated:: Jul 16 2025 03:55:11 AM UTC
Resolved:: Jul 16 2025 03:55:11 AM UTC

Details

Description

Attachments

Forms

Activity

People

Dates