Uploaded image for project: 'WiredTiger'
  1. WiredTiger
  2. WT-9125

unexpected standard output "oldest pinned transaction ID rolled back"

    • Type: Icon: Build Failure Build Failure
    • Resolution: Duplicate
    • Priority: Icon: Major - P3 Major - P3
    • None
    • Affects Version/s: None
    • Component/s: None

      unit-test on macos-1014

      Host: macos-1014-112.macstadium.build.10gen.cc
      Project: wiredtiger
      Commit: 07cee370

      Task Logs:

      unit-test task_log

      Logs:

      Compiler and other error messages
      test_debug_mode01.test_debug_mode01.test_rollback_error: 1.05 seconds
      Link: https://evergreen.mongodb.com/task_log_raw/wiredtiger_macos_1014_unit_test_07cee370d83fd1c90f4ecf6781331db020960323_22_04_11_22_20_20/0?type=T#L17102
      
      Test failure
      ERROR: test_txn24.test_txn24.test_snapshot_isolation_and_eviction(column) (subunit.RemotedTestCase)
      test_txn24.test_txn24.test_snapshot_isolation_and_eviction(column)
      ----------------------------------------------------------------------
      testtools.testresult.real._StringException: Traceback (most recent call last):
        File "/data/mci/e86d1edf686e7b6bff7150530672e7bc/wiredtiger/test/suite/test_txn24.py", line 117, in test_snapshot_isolation_and_eviction
          cursor4[start_row] = new_val
        File "/data/mci/e86d1edf686e7b6bff7150530672e7bc/wiredtiger/cmake_build/lang/python/wiredtiger/swig_wiredtiger.py", line 320, in __setitem__
          if self.insert() != 0:
      _wiredtiger.WiredTigerError: WT_ROLLBACK: conflict between concurrent operations
      ----------------------------------------------------------------------
      Ran 8793 tests in 2207.629s
      FAILED (errors=1, skipped=1346)
      failure: test_txn24.test_txn24.test_snapshot_isolation_and_eviction(column) [
      Traceback (most recent call last):
        File "/data/mci/e86d1edf686e7b6bff7150530672e7bc/wiredtiger/test/suite/wttest.py", line 565, in tearDown
          self.captureout.check(self)
        File "/data/mci/e86d1edf686e7b6bff7150530672e7bc/wiredtiger/test/suite/wttest.py", line 116, in check
          testcase.fail('unexpected ' + self.desc + ', contains: "' +
        File "/usr/local/Cellar/python@3.9/3.9.4/Frameworks/Python.framework/Versions/3.9/lib/python3.9/unittest/case.py", line 670, in fail
          raise self.failureException(msg)
      AssertionError: unexpected standard output, contains: "[1649717770:420671][75333:0x1192fa5c0], test_txn24.test_txn24.test_snapshot_isolation_and_eviction(column), file:test_txn24.wt, WT_CURSOR.insert: [WT_VERB_TRANSACTION][NOTICE]: oldest pinned transaction ID rolled back for eviction
      "
      ]
      sysctl -n hw.logicalcpu
      git rev-parse --show-toplevel
      Command failed: command encountered problem: error waiting on process '596c7476-7a92-4db6-9ce0-f152e85496e0': exit status 1
      Task completed - FAILURE.
      Running post-task commands.
      Running command 'shell.exec' in "print python stacktrace" (step 1 of 5)
      if [ -d "WT_TEST" ]; then
        python3 ../test/evergreen/print_python_stack_trace.py -e python3 -c WT_TEST -l .
      fi
      Finished 'shell.exec' in "print python stacktrace" in 54.739414ms
      Running command 'shell.exec' in "dump stderr/stdout" (step 2 of 5)
      if [ -d "WT_TEST" ]; then
        # Dump stderr/stdout contents generated by the C libraries onto console for Python tests
        find "WT_TEST" -name "std*.txt" ! -empty -exec sh -c "echo 'Contents from {}:'; cat '{}'" \;
      fi
      Contents from WT_TEST/test_txn24.test_txn24.test_snapshot_isolation_and_eviction_column/stdout.txt:
      [1649717770:420671][75333:0x1192fa5c0], test_txn24.test_txn24.test_snapshot_isolation_and_eviction(column), file:test_txn24.wt, WT_CURSOR.insert: [WT_VERB_TRANSACTION][NOTICE]: oldest pinned transaction ID rolled back for eviction
      Finished 'shell.exec' in "dump stderr/stdout" in 10.571806ms
      Running command 'archive.targz_pack' in "upload artifact" (step 3.1 of 5)
      Finished 'archive.targz_pack' in "upload artifact" in 5.91973788s
      Running command 's3.put' in "upload artifact" (step 3.2 of 5)
      Putting wiredtiger.tgz into build_external/wiredtiger/macos-1014/07cee370d83fd1c90f4ecf6781331db020960323/artifacts/unit-test_wiredtiger_macos_1014_07cee370d83fd1c90f4ecf6781331db020960323_22_04_11_22_20_20-0.tgz (https://build_external.s3.amazonaws.com/wiredtiger/macos-1014/07cee370d83fd1c90f4ecf6781331db020960323/artifacts/unit-test_wiredtiger_macos_1014_07cee370d83fd1c90f4ecf6781331db020960323_22_04_11_22_20_20-0.tgz)
      performing s3 put to build_external of wiredtiger/macos-1014/07cee370d83fd1c90f4ecf6781331db020960323/artifacts/unit-test_wiredtiger_macos_1014_07cee370d83fd1c90f4ecf6781331db020960323_22_04_11_22_20_20-0.tgz [1 of 5]
      Finished 's3.put' in "upload artifact" in 10.122296449s
      Running command 'archive.targz_pack' in "save wt hang analyzer core/debugger files" (step 4.1 of 5)
      Finished 'archive.targz_pack' in "save wt hang analyzer core/debugger files" in 34.423907ms
      Running command 's3.put' in "save wt hang analyzer core/debugger files" (step 4.2 of 5)
      Putting wt-hang-analyzer.tgz into build_external/wiredtiger/macos-1014/07cee370d83fd1c90f4ecf6781331db020960323/wt_hang_analyzer/wt-hang-analyzer_unit-test_wiredtiger_macos_1014_07cee370d83fd1c90f4ecf6781331db020960323_22_04_11_22_20_20-0.tgz (https://build_external.s3.amazonaws.com/wiredtiger/macos-1014/07cee370d83fd1c90f4ecf6781331db020960323/wt_hang_analyzer/wt-hang-analyzer_unit-test_wiredtiger_macos_1014_07cee370d83fd1c90f4ecf6781331db020960323_22_04_11_22_20_20-0.tgz)
      performing s3 put to build_external of wiredtiger/macos-1014/07cee370d83fd1c90f4ecf6781331db020960323/wt_hang_analyzer/wt-hang-analyzer_unit-test_wiredtiger_macos_1014_07cee370d83fd1c90f4ecf6781331db020960323_22_04_11_22_20_20-0.tgz [1 of 5]
      file '/data/mci/e86d1edf686e7b6bff7150530672e7bc/wt-hang-analyzer.tgz' not found but skip missing true
      Finished 's3.put' in "save wt hang analyzer core/debugger files" in 75.770967ms
      Running command 'shell.exec' in "cleanup" (step 5 of 5)
      Finished 'shell.exec' in "cleanup" in 285.593383ms
      [message='Finished running post-task commands.' total_time='16.523076428s']
      Link: https://evergreen.mongodb.com/task_log_raw/wiredtiger_macos_1014_unit_test_07cee370d83fd1c90f4ecf6781331db020960323_22_04_11_22_20_20/0?type=T#L31436
      

      logs

            Assignee:
            keith.bostic@mongodb.com Keith Bostic (Inactive)
            Reporter:
            Xgen-BuildBaron-User xgen-buildbaron-user
            Votes:
            0 Vote for this issue
            Watchers:
            1 Start watching this issue

              Created:
              Updated:
              Resolved: