Uploaded image for project: 'Core Server'
  1. Core Server
  2. SERVER-37688

unable to read root page from file:WiredTiger.wt:

    • Type: Icon: Bug Bug
    • Resolution: Done
    • Priority: Icon: Major - P3 Major - P3
    • None
    • Affects Version/s: 3.6.8
    • Component/s: WiredTiger
    • ALL

      Hello,

      For two days i'm trying to restore my broken database but even my backups are broken ... I tried to --repair my database without success, I saw some other problems were fixed by your team so can you take a look to my logs and files and see if it's recoverable ?

       

      That would be amazing, because my latest working backup is from 1st august 2018.

       

      Here are the logs from my most recent backup:

      C:\Program Files\MongoDB\Server\3.6\bin>mongod.exe --auth
      2018-10-20T00:38:23.311-0700 I CONTROL  [initandlisten] MongoDB starting : pid=4844 port=27017 dbpath=C:\data\db\ 64-bit host=DESKTOP-*******
      2018-10-20T00:38:23.311-0700 I CONTROL  [initandlisten] targetMinOS: Windows 7/Windows Server 2008 R2
      2018-10-20T00:38:23.311-0700 I CONTROL  [initandlisten] db version v3.6.8-85-g9739f4e597
      2018-10-20T00:38:23.311-0700 I CONTROL  [initandlisten] git version: 9739f4e5974ef70daac2190acc8db90d6e8311d1
      2018-10-20T00:38:23.311-0700 I CONTROL  [initandlisten] OpenSSL version: OpenSSL 1.0.2o-fips  27 Mar 2018
      2018-10-20T00:38:23.311-0700 I CONTROL  [initandlisten] allocator: tcmalloc
      2018-10-20T00:38:23.311-0700 I CONTROL  [initandlisten] modules: none
      2018-10-20T00:38:23.312-0700 I CONTROL  [initandlisten] build environment:
      2018-10-20T00:38:23.312-0700 I CONTROL  [initandlisten]     distmod: 2008plus-ssl
      2018-10-20T00:38:23.312-0700 I CONTROL  [initandlisten]     distarch: x86_64
      2018-10-20T00:38:23.312-0700 I CONTROL  [initandlisten]     target_arch: x86_64
      2018-10-20T00:38:23.312-0700 I CONTROL  [initandlisten] options: { security: { authorization: "enabled" } }
      2018-10-20T00:38:23.312-0700 W -        [initandlisten] Detected unclean shutdown - C:\data\db\mongod.lock is not empty.
      2018-10-20T00:38:23.312-0700 I -        [initandlisten] Detected data files in C:\data\db\ created by the 'wiredTiger' storage engine, so setting the active storage engine to 'wiredTiger'.
      2018-10-20T00:38:23.313-0700 W STORAGE  [initandlisten] Recovering data from the last clean checkpoint.
      2018-10-20T00:38:23.313-0700 I STORAGE  [initandlisten] wiredtiger_open config: create,cache_size=7624M,session_max=20000,eviction=(threads_min=4,threads_max=4),config_base=false,statistics=(fast),cache_cursors=false,compatibility=(release="3.0",require_max="3.0"),log=(enabled=true,archive=true,path=journal,compressor=snappy),file_manager=(close_idle_time=100000),statistics_log=(wait=0),verbose=(recovery_progress),
      2018-10-20T00:38:23.318-0700 E STORAGE  [initandlisten] WiredTiger error (-31802) [1540021103:317632][4844:140726302030928], file:WiredTiger.wt, connection: unable to read root page from file:WiredTiger.wt: WT_ERROR: non-specific WiredTiger error
      2018-10-20T00:38:23.318-0700 E STORAGE  [initandlisten] WiredTiger error (0) [1540021103:317632][4844:140726302030928], file:WiredTiger.wt, connection: WiredTiger has failed to open its metadata
      2018-10-20T00:38:23.318-0700 E STORAGE  [initandlisten] WiredTiger error (0) [1540021103:317632][4844:140726302030928], file:WiredTiger.wt, connection: This may be due to the database files being encrypted, being from an older version or due to corruption on disk
      2018-10-20T00:38:23.318-0700 E STORAGE  [initandlisten] WiredTiger error (0) [1540021103:317632][4844:140726302030928], file:WiredTiger.wt, connection: You should confirm that you have opened the database with the correct options including all encryption and compression options
      2018-10-20T00:38:23.323-0700 E -        [initandlisten] Assertion: 28595:-31802: WT_ERROR: non-specific WiredTiger error src\mongo\db\storage\wiredtiger\wiredtiger_kv_engine.cpp 421
      2018-10-20T00:38:23.323-0700 I STORAGE  [initandlisten] exception in initAndListen: Location28595: -31802: WT_ERROR: non-specific WiredTiger error, terminating
      2018-10-20T00:38:23.323-0700 I NETWORK  [initandlisten] shutdown: going to close listening sockets...
      2018-10-20T00:38:23.323-0700 I CONTROL  [initandlisten] now exiting
      2018-10-20T00:38:23.323-0700 I CONTROL  [initandlisten] shutting down with code:100
      
      C:\Program Files\MongoDB\Server\3.6\bin>mongod.exe --auth --repair
      2018-10-20T00:38:41.349-0700 I CONTROL  [initandlisten] MongoDB starting : pid=12552 port=27017 dbpath=C:\data\db\ 64-bit host=DESKTOP-*******
      2018-10-20T00:38:41.350-0700 I CONTROL  [initandlisten] targetMinOS: Windows 7/Windows Server 2008 R2
      2018-10-20T00:38:41.350-0700 I CONTROL  [initandlisten] db version v3.6.8-85-g9739f4e597
      2018-10-20T00:38:41.350-0700 I CONTROL  [initandlisten] git version: 9739f4e5974ef70daac2190acc8db90d6e8311d1
      2018-10-20T00:38:41.350-0700 I CONTROL  [initandlisten] OpenSSL version: OpenSSL 1.0.2o-fips  27 Mar 2018
      2018-10-20T00:38:41.350-0700 I CONTROL  [initandlisten] allocator: tcmalloc
      2018-10-20T00:38:41.350-0700 I CONTROL  [initandlisten] modules: none
      2018-10-20T00:38:41.350-0700 I CONTROL  [initandlisten] build environment:
      2018-10-20T00:38:41.350-0700 I CONTROL  [initandlisten]     distmod: 2008plus-ssl
      2018-10-20T00:38:41.350-0700 I CONTROL  [initandlisten]     distarch: x86_64
      2018-10-20T00:38:41.350-0700 I CONTROL  [initandlisten]     target_arch: x86_64
      2018-10-20T00:38:41.351-0700 I CONTROL  [initandlisten] options: { repair: true, security: { authorization: "enabled" } }
      2018-10-20T00:38:41.351-0700 W -        [initandlisten] Detected unclean shutdown - C:\data\db\mongod.lock is not empty.
      2018-10-20T00:38:41.351-0700 I -        [initandlisten] Detected data files in C:\data\db\ created by the 'wiredTiger' storage engine, so setting the active storage engine to 'wiredTiger'.
      2018-10-20T00:38:41.351-0700 W STORAGE  [initandlisten] Recovering data from the last clean checkpoint.
      2018-10-20T00:38:41.351-0700 I STORAGE  [initandlisten] Detected WT journal files.  Running recovery from last checkpoint.
      2018-10-20T00:38:41.351-0700 I STORAGE  [initandlisten] journal to nojournal transition config: create,cache_size=7624M,session_max=20000,eviction=(threads_min=4,threads_max=4),config_base=false,statistics=(fast),cache_cursors=false,compatibility=(release="3.0",require_max="3.0"),log=(enabled=true,archive=true,path=journal,compressor=snappy),file_manager=(close_idle_time=100000),statistics_log=(wait=0),verbose=(recovery_progress),
      2018-10-20T00:38:41.356-0700 E STORAGE  [initandlisten] WiredTiger error (-31802) [1540021121:356413][12552:140726302030928], file:WiredTiger.wt, connection: unable to read root page from file:WiredTiger.wt: WT_ERROR: non-specific WiredTiger error
      2018-10-20T00:38:41.356-0700 E STORAGE  [initandlisten] WiredTiger error (0) [1540021121:356413][12552:140726302030928], file:WiredTiger.wt, connection: WiredTiger has failed to open its metadata
      2018-10-20T00:38:41.356-0700 E STORAGE  [initandlisten] WiredTiger error (0) [1540021121:356413][12552:140726302030928], file:WiredTiger.wt, connection: This may be due to the database files being encrypted, being from an older version or due to corruption on disk
      2018-10-20T00:38:41.356-0700 E STORAGE  [initandlisten] WiredTiger error (0) [1540021121:356413][12552:140726302030928], file:WiredTiger.wt, connection: You should confirm that you have opened the database with the correct options including all encryption and compression options
      2018-10-20T00:38:41.361-0700 E -        [initandlisten] Assertion: 28718:-31802: WT_ERROR: non-specific WiredTiger error src\mongo\db\storage\wiredtiger\wiredtiger_kv_engine.cpp 397
      2018-10-20T00:38:41.361-0700 I STORAGE  [initandlisten] exception in initAndListen: Location28718: -31802: WT_ERROR: non-specific WiredTiger error, terminating
      2018-10-20T00:38:41.361-0700 I NETWORK  [initandlisten] shutdown: going to close listening sockets...
      2018-10-20T00:38:41.362-0700 I CONTROL  [initandlisten] now exiting
      2018-10-20T00:38:41.362-0700 I CONTROL  [initandlisten] shutting down with code:100
      
      C:\Program Files\MongoDB\Server\3.6\bin>mongod.exe --repair
      2018-10-20T00:39:05.005-0700 I CONTROL  [initandlisten] MongoDB starting : pid=18108 port=27017 dbpath=C:\data\db\ 64-bit host=DESKTOP-*******
      2018-10-20T00:39:05.005-0700 I CONTROL  [initandlisten] targetMinOS: Windows 7/Windows Server 2008 R2
      2018-10-20T00:39:05.006-0700 I CONTROL  [initandlisten] db version v3.6.8-85-g9739f4e597
      2018-10-20T00:39:05.006-0700 I CONTROL  [initandlisten] git version: 9739f4e5974ef70daac2190acc8db90d6e8311d1
      2018-10-20T00:39:05.007-0700 I CONTROL  [initandlisten] OpenSSL version: OpenSSL 1.0.2o-fips  27 Mar 2018
      2018-10-20T00:39:05.007-0700 I CONTROL  [initandlisten] allocator: tcmalloc
      2018-10-20T00:39:05.007-0700 I CONTROL  [initandlisten] modules: none
      2018-10-20T00:39:05.008-0700 I CONTROL  [initandlisten] build environment:
      2018-10-20T00:39:05.008-0700 I CONTROL  [initandlisten]     distmod: 2008plus-ssl
      2018-10-20T00:39:05.009-0700 I CONTROL  [initandlisten]     distarch: x86_64
      2018-10-20T00:39:05.009-0700 I CONTROL  [initandlisten]     target_arch: x86_64
      2018-10-20T00:39:05.009-0700 I CONTROL  [initandlisten] options: { repair: true }
      2018-10-20T00:39:05.010-0700 W -        [initandlisten] Detected unclean shutdown - C:\data\db\mongod.lock is not empty.
      2018-10-20T00:39:05.010-0700 I -        [initandlisten] Detected data files in C:\data\db\ created by the 'wiredTiger' storage engine, so setting the active storage engine to 'wiredTiger'.
      2018-10-20T00:39:05.010-0700 W STORAGE  [initandlisten] Recovering data from the last clean checkpoint.
      2018-10-20T00:39:05.011-0700 I STORAGE  [initandlisten] Detected WT journal files.  Running recovery from last checkpoint.
      2018-10-20T00:39:05.011-0700 I STORAGE  [initandlisten] journal to nojournal transition config: create,cache_size=7624M,session_max=20000,eviction=(threads_min=4,threads_max=4),config_base=false,statistics=(fast),cache_cursors=false,compatibility=(release="3.0",require_max="3.0"),log=(enabled=true,archive=true,path=journal,compressor=snappy),file_manager=(close_idle_time=100000),statistics_log=(wait=0),verbose=(recovery_progress),
      2018-10-20T00:39:05.018-0700 E STORAGE  [initandlisten] WiredTiger error (-31802) [1540021145:17966][18108:140726302030928], file:WiredTiger.wt, connection: unable to read root page from file:WiredTiger.wt: WT_ERROR: non-specific WiredTiger error
      2018-10-20T00:39:05.018-0700 E STORAGE  [initandlisten] WiredTiger error (0) [1540021145:17966][18108:140726302030928], file:WiredTiger.wt, connection: WiredTiger has failed to open its metadata
      2018-10-20T00:39:05.019-0700 E STORAGE  [initandlisten] WiredTiger error (0) [1540021145:18963][18108:140726302030928], file:WiredTiger.wt, connection: This may be due to the database files being encrypted, being from an older version or due to corruption on disk
      2018-10-20T00:39:05.020-0700 E STORAGE  [initandlisten] WiredTiger error (0) [1540021145:19968][18108:140726302030928], file:WiredTiger.wt, connection: You should confirm that you have opened the database with the correct options including all encryption and compression options
      2018-10-20T00:39:05.025-0700 E -        [initandlisten] Assertion: 28718:-31802: WT_ERROR: non-specific WiredTiger error src\mongo\db\storage\wiredtiger\wiredtiger_kv_engine.cpp 397
      2018-10-20T00:39:05.025-0700 I STORAGE  [initandlisten] exception in initAndListen: Location28718: -31802: WT_ERROR: non-specific WiredTiger error, terminating
      2018-10-20T00:39:05.025-0700 I NETWORK  [initandlisten] shutdown: going to close listening sockets...
      2018-10-20T00:39:05.026-0700 I CONTROL  [initandlisten] now exiting
      2018-10-20T00:39:05.026-0700 I CONTROL  [initandlisten] shutting down with code:100
      

      I also join WiredTiger.wt & WiredTiger.turtle to this post.

       

      Thanks in advance !

            Assignee:
            kelsey.schubert@mongodb.com Kelsey Schubert
            Reporter:
            Elanis Axel "Elanis" Soupé [X]
            Votes:
            0 Vote for this issue
            Watchers:
            7 Start watching this issue

              Created:
              Updated:
              Resolved: