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

format sanitizer runs configured with LSM and without timestamps may timeout

      Evergreen link to failing test: https://evergreen.mongodb.com/task/wiredtiger_feature_ubuntu1804_format_stress_sanitizer_test_7e595e4a3a9c30c9db0eb33f7da72c97526a2b99_20_03_01_23_26_06

      The format-stress-sanitizer-test is failing with following signature:

       [2020/03/01 21:11:48.823] format.sh: job in /data/mci/b6259b5d082af46a3e88aa64ee22ab1c/wiredtiger/test/format/RUNDIR.61 failed
       [2020/03/01 21:11:48.823] format.sh: /data/mci/b6259b5d082af46a3e88aa64ee22ab1c/wiredtiger/test/format/RUNDIR.61 log:
       [2020/03/01 21:11:48.839] t: process 18817 running
       [2020/03/01 21:11:48.839] format run more than 15 minutes past the maximum time
       [2020/03/01 21:11:48.839] format run dumping cache and transaction state, then aborting the process
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:=========================
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:transaction state dump
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:current ID: 14975
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:last running ID: 14975
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:metadata_pinned ID: 6931
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:oldest ID: 6931
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:durable timestamp: (0, 29838)
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:oldest timestamp: (0, 29838)
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:pinned timestamp: (0, 29838)
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:stable timestamp: (0, 0)
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:has_durable_timestamp: yes
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:has_oldest_timestamp: yes
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:has_pinned_timestamp: yes
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:has_stable_timestamp: no
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:oldest_is_pinned: yes
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:stable_is_pinned: no
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:checkpoint running: no
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:checkpoint generation: 1
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:checkpoint pinned ID: 0
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:checkpoint txn ID: 0
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:oldest named snapshot ID: 0
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:session count: 26
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:Transaction state of active sessions:
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:ID: 0, pinned ID: 6931, metadata pinned ID: 0, name: WT_CURSOR.next
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:transaction id: 0, mod count: 0, snap min: 6931, snap max: 6953, snapshot count: 6, commit_timestamp: (0, 0), durable_timestamp: (0, 0), first_commit_timestamp: (0, 0), prepare_timestamp: (0, 0), read_timestamp: (0, 0), checkpoint LSN: [0][0], full checkpoint: false, rollback reason: , flags: 0x00000008, isolation: WT_ISO_READ_COMMITTED
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:=========================
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:cache dump
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:cache full: no
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:cache clean check: no (79.532%)
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:cache dirty check: yes (24.289%)
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:file:wt.wt(checkpoint=WiredTigerCheckpoint.1):
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:internal: 1 pages, 0MB, 1/0 clean/dirty pages, 0/0 clean/dirty MB, 0MB max page, 0MB max dirty page
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:leaf: 0 pages
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:file:wt.wt(<live>):
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:internal: 12984 pages, 48MB, 5948/7036 clean/dirty pages, 18/29 clean/dirty MB, 0MB max page, 0MB max dirty page
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:leaf: 17184 pages, 21MB, 1229/15955 clean/dirty pages, 0/21 clean/dirty MB, 0MB max page, 0MB max dirty page
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:file:WiredTigerLAS.wt(<live>):
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:internal: 1 pages, 0MB, 0/1 clean/dirty pages, 0/0 clean/dirty MB, 0MB max page, 0MB max dirty page
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:leaf: 0 pages
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:file:WiredTiger.wt(<live>):
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:internal: 1 pages, 0MB, 0/1 clean/dirty pages, 0/0 clean/dirty MB, 0MB max page, 0MB max dirty page
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:leaf: 1 pages, 0MB, 1/0 clean/dirty pages, 0/0 clean/dirty MB, 0MB max page, 0MB max dirty page
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:cache dump: total found: 75MB vs tracked inuse 75MB
       [2020/03/01 21:11:48.839] 0x7f5af6c98800:total dirty bytes: 50MB
       [2020/03/01 21:11:48.839] WiredTiger Error: aborting WiredTiger library

       

            Assignee:
            backlog-server-storage-engines Backlog - Storage Engines Team
            Reporter:
            haseeb.bokhari@mongodb.com Haseeb Bokhari (Inactive)
            Votes:
            0 Vote for this issue
            Watchers:
            8 Start watching this issue

              Created:
              Updated:
              Resolved: