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

setTableLogging error at startup while creating index entry, in backup/restore test

    • Type: Icon: Bug Bug
    • Resolution: Cannot Reproduce
    • Priority: Icon: Major - P3 Major - P3
    • None
    • Affects Version/s: None
    • Component/s: Storage
    • None
    • ALL
    • v4.4

      In writing a test for a different bug, I received the following fassert:

      [js_test:backup_restore] 2020-07-15T17:27:36.677+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.677+00:00"},"s":"F",  "c":"STORAGE",  "id":50756,   "ctx":"initandlisten","msg":"Failed to update log setting","attr":{"uri":"table:index-25--5733517019667255102","enable":true,"error":16,"metadata":"access_pattern_hint=none,allocation_size=4KB,app_metadata=(formatVersion=12),assert=(commit_timestamp=none,durable_timestamp=none,read_timestamp=none),block_allocation=best,block_compressor=,cache_resident=false,checksum=on,colgroups=,collator=,columns=,dictionary=0,encryption=(keyid=,name=),exclusive=false,extractor=,format=btree,huffman_key=,huffman_value=,ignore_in_memory_cache_size=false,immutable=false,internal_item_max=0,internal_key_max=0,internal_key_truncate=true,internal_page_max=16k,key_format=u,key_gap=10,leaf_item_max=0,leaf_key_max=0,leaf_page_max=16k,leaf_value_max=0,log=(enabled=false),lsm=(auto_throttle=true,bloom=true,bloom_bit_count=16,bloom_config=,bloom_hash_count=8,bloom_oldest=false,chunk_count_limit=0,chunk_max=5GB,chunk_size=10MB,merge_custom=(prefix=,start_generation=0,suffix=),merge_max=15,merge_min=0),memory_page_image_max=0,memory_page_max=5MB,os_cache_dirty_max=0,os_cache_max=0,prefix_compression=true,prefix_compression_min=4,source=\"file:index-25--5733517019667255102.wt\",split_deepen_min_child=0,split_deepen_per_child=0,split_pct=90,type=file,value_format=u","message":"Device or resource busy"}}
      [js_test:backup_restore] 2020-07-15T17:27:36.678+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.677+00:00"},"s":"F",  "c":"-",        "id":23089,   "ctx":"initandlisten","msg":"Fatal assertion","attr":{"msgid":50756,"file":"src/mongo/db/storage/wiredtiger/wiredtiger_util.cpp","line":656}}
      [js_test:backup_restore] 2020-07-15T17:27:36.678+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.677+00:00"},"s":"F",  "c":"-",        "id":23090,   "ctx":"initandlisten","msg":"\n\n***aborting after fassert() failure\n\n"}
      [js_test:backup_restore] 2020-07-15T17:27:36.678+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.677+00:00"},"s":"F",  "c":"CONTROL",  "id":4757800, "ctx":"initandlisten","msg":"Writing fatal message","attr":{"message":"Got signal: 6 (Aborted).\n"}}
      [js_test:backup_restore] 2020-07-15T17:27:36.754+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31380,   "ctx":"initandlisten","msg":"BACKTRACE","attr":{"bt":{"backtrace":[{"a":"7F3D3D463103","b":"7F3D3D24C000","o":"217103","s":"_ZN5mongo18stack_trace_detail12_GLOBAL__N_119printStackTraceImplERKNS1_7OptionsEPNS_14StackTraceSinkE","s+":"113"},{"a":"7F3D3D464217","b":"7F3D3D24C000","o":"218217","s":"_ZN5mongo15printStackTraceEv","s+":"27"},{"a":"7F3D3D4619CF","b":"7F3D3D24C000","o":"2159CF","s":"_ZN5mongo12_GLOBAL__N_116abruptQuitActionEiP9siginfo_tPv","s+":"FF"},{"a":"7F3D3B2EB890","b":"7F3D3B2D9000","o":"12890","s":"funlockfile","s+":"50"},{"a":"7F3D3AF26E97","b":"7F3D3AEE8000","o":"3EE97","s":"gsignal","s+":"C7"},{"a":"7F3D3AF28801","b":"7F3D3AEE8000","o":"40801","s":"abort","s+":"141"},{"a":"7F3D3D452352","b":"7F3D3D24C000","o":"206352","s":"_ZN5mongo25fassertFailedWithLocationEiPKcj","s+":"132"},{"a":"7F3D2C484914","b":"7F3D2C399000","o":"EB914","s":"_ZN5mongo14WiredTigerUtil15setTableLoggingEP12__wt_sessionRKNSt7__cxx1112basic_stringIcSt11char_traitsIcESaIcEEEb","s+":"744"},{"a":"7F3D2C484188","b":"7F3D2C399000","o":"EB188","s":"_ZN5mongo14WiredTigerUtil15setTableLoggingEPNS_16OperationContextERKNSt7__cxx1112basic_stringIcSt11char_traitsIcESaIcEEEb","s+":"78"},{"a":"7F3D2C41312F","b":"7F3D2C399000","o":"7A12F","s":"_ZN5mongo15WiredTigerIndex18_handleVersionInfoEPNS_16OperationContextERKNSt7__cxx1112basic_stringIcSt11char_traitsIcESaIcEEEPKNS_15IndexDescriptorEb","s+":"FF"},{"a":"7F3D2C412E41","b":"7F3D2C399000","o":"79E41","s":"_ZN5mongo15WiredTigerIndexC2EPNS_16OperationContextERKNSt7__cxx1112basic_stringIcSt11char_traitsIcESaIcEEEPKNS_15IndexDescriptorENS_8KVPrefixEb","s+":"41"},{"a":"7F3D2C416A22","b":"7F3D2C399000","o":"7DA22","s":"_ZN5mongo21WiredTigerIndexUniqueC1EPNS_16OperationContextERKNSt7__cxx1112basic_stringIcSt11char_traitsIcESaIcEEEPKNS_15IndexDescriptorENS_8KVPrefixEb","s+":"12"},{"a":"7F3D2C434A8E","b":"7F3D2C399000","o":"9BA8E","s":"_ZN5mongo18WiredTigerKVEngine29getGroupedSortedDataInterfaceEPNS_16OperationContextENS_10StringDataEPKNS_15IndexDescriptorENS_8KVPrefixE","s+":"6E"},{"a":"7F3D37290C84","b":"7F3D3720F000","o":"81C84","s":"_ZN5mongo16IndexCatalogImpl16createIndexEntryEPNS_16OperationContextESt10unique_ptrINS_15IndexDescriptorESt14default_deleteIS4_EENS_21CreateIndexEntryFlagsE","s+":"194"},{"a":"7F3D37287F91","b":"7F3D3720F000","o":"78F91","s":"_ZN5mongo16IndexCatalogImpl4initEPNS_16OperationContextE","s+":"4B1"},{"a":"7F3D37250F57","b":"7F3D3720F000","o":"41F57","s":"_ZN5mongo14CollectionImpl4initEPNS_16OperationContextE","s+":"407"},{"a":"7F3D372697A8","b":"7F3D3720F000","o":"5A7A8","s":"_ZNK5mongo12DatabaseImpl4initEPNS_16OperationContextE","s+":"C8"},{"a":"7F3D37265000","b":"7F3D3720F000","o":"56000","s":"_ZN5mongo18DatabaseHolderImpl6openDbEPNS_16OperationContextENS_10StringDataEPb","s+":"1D0"},{"a":"7F3D380B7671","b":"7F3D380A8000","o":"F671","s":"_ZN5mongo12_GLOBAL__N_126ensureCollectionPropertiesEPNS_16OperationContextERKSt6vectorINSt7__cxx1112basic_stringIcSt11char_traitsIcESaIcEEESaIS9_EE","s+":"131"},{"a":"7F3D380B3FF7","b":"7F3D380A8000","o":"BFF7","s":"_ZN5mongo30repairDatabasesAndCheckVersionEPNS_16OperationContextE","s+":"F37"},{"a":"7F3D3D6EF6EA","b":"7F3D3D6C3000","o":"2C6EA","s":"_ZN5mongo12_GLOBAL__N_114_initAndListenEPNS_14ServiceContextEi","s+":"EBA"},{"a":"7F3D3D6E984C","b":"7F3D3D6C3000","o":"2684C","s":"_ZN5mongo11mongod_mainEiPPc","s+":"174C"},{"a":"5626685630F9","b":"56266836C000","o":"1F70F9","s":"main","s+":"9"},{"a":"7F3D3AF09B97","b":"7F3D3AEE8000","o":"21B97","s":"__libc_start_main","s+":"E7"},{"a":"56266856302A","b":"56266836C000","o":"1F702A","s":"_start","s+":"2A"}],"processInfo":{"mongodbVersion":"4.5.0-1883-g261c779","gitVersion":"261c779608a67303fb8294a139662bbdb50dca72","compiledModules":["enterprise"],"uname":{"sysname":"Linux","release":"5.3.0-1019-aws","version":"#21~18.04.1-Ubuntu SMP Mon May 11 12:33:03 UTC 2020","machine":"x86_64"},"somap":[{"b":"56266836C000","elfType":3,"buildId":"2D42A23308F12D5C"},{"b":"7F3D3D6C3000","path":"/home/ubuntu/mongo/build/install/bin/../lib/libmongod_main.so","elfType":3,"buildId":"91DE6743D08BB433"},{"b":"7F3D3D24C000","path":"/home/ubuntu/mongo/build/install/bin/../lib/libbase.so","elfType":3,"buildId":"D5398E93432023DD"},{"b":"7F3D3B2D9000","path":"/lib/x86_64-linux-gnu/libpthread.so.0","elfType":3,"buildId":"28C6AADE70B2D40D1F0F3D0A1A0CAD1AB816448F"},{"b":"7F3D3AEE8000","path":"/lib/x86_64-linux-gnu/libc.so.6","elfType":3,"buildId":"B417C0BA7CC5CF06D1D1BED6652CEDB9253C60D0"},{"b":"7F3D380A8000","path":"/home/ubuntu/mongo/build/install/bin/../lib/../lib/librepair_database_and_check_version.so","elfType":3,"buildId":"F8F7E1EE13C97F2C"},{"b":"7F3D3720F000","path":"/home/ubuntu/mongo/build/install/bin/../lib/../lib/libcatalog_impl.so","elfType":3,"buildId":"AA603B129BA121E2"},{"b":"7F3D2C399000","path":"/home/ubuntu/mongo/build/install/bin/../lib/../lib/../lib/libstorage_wiredtiger_core.so","elfType":3,"buildId":"4C4D710851DE8B95"}]}}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.754+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D3D463103","b":"7F3D3D24C000","o":"217103","s":"_ZN5mongo18stack_trace_detail12_GLOBAL__N_119printStackTraceImplERKNS1_7OptionsEPNS_14StackTraceSinkE","s+":"113"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.754+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D3D464217","b":"7F3D3D24C000","o":"218217","s":"_ZN5mongo15printStackTraceEv","s+":"27"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.754+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D3D4619CF","b":"7F3D3D24C000","o":"2159CF","s":"_ZN5mongo12_GLOBAL__N_116abruptQuitActionEiP9siginfo_tPv","s+":"FF"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.754+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D3B2EB890","b":"7F3D3B2D9000","o":"12890","s":"funlockfile","s+":"50"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.754+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D3AF26E97","b":"7F3D3AEE8000","o":"3EE97","s":"gsignal","s+":"C7"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.754+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D3AF28801","b":"7F3D3AEE8000","o":"40801","s":"abort","s+":"141"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.754+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D3D452352","b":"7F3D3D24C000","o":"206352","s":"_ZN5mongo25fassertFailedWithLocationEiPKcj","s+":"132"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.754+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D2C484914","b":"7F3D2C399000","o":"EB914","s":"_ZN5mongo14WiredTigerUtil15setTableLoggingEP12__wt_sessionRKNSt7__cxx1112basic_stringIcSt11char_traitsIcESaIcEEEb","s+":"744"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.754+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D2C484188","b":"7F3D2C399000","o":"EB188","s":"_ZN5mongo14WiredTigerUtil15setTableLoggingEPNS_16OperationContextERKNSt7__cxx1112basic_stringIcSt11char_traitsIcESaIcEEEb","s+":"78"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.754+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D2C41312F","b":"7F3D2C399000","o":"7A12F","s":"_ZN5mongo15WiredTigerIndex18_handleVersionInfoEPNS_16OperationContextERKNSt7__cxx1112basic_stringIcSt11char_traitsIcESaIcEEEPKNS_15IndexDescriptorEb","s+":"FF"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.754+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D2C412E41","b":"7F3D2C399000","o":"79E41","s":"_ZN5mongo15WiredTigerIndexC2EPNS_16OperationContextERKNSt7__cxx1112basic_stringIcSt11char_traitsIcESaIcEEEPKNS_15IndexDescriptorENS_8KVPrefixEb","s+":"41"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.754+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D2C416A22","b":"7F3D2C399000","o":"7DA22","s":"_ZN5mongo21WiredTigerIndexUniqueC1EPNS_16OperationContextERKNSt7__cxx1112basic_stringIcSt11char_traitsIcESaIcEEEPKNS_15IndexDescriptorENS_8KVPrefixEb","s+":"12"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.754+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D2C434A8E","b":"7F3D2C399000","o":"9BA8E","s":"_ZN5mongo18WiredTigerKVEngine29getGroupedSortedDataInterfaceEPNS_16OperationContextENS_10StringDataEPKNS_15IndexDescriptorENS_8KVPrefixE","s+":"6E"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.754+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D37290C84","b":"7F3D3720F000","o":"81C84","s":"_ZN5mongo16IndexCatalogImpl16createIndexEntryEPNS_16OperationContextESt10unique_ptrINS_15IndexDescriptorESt14default_deleteIS4_EENS_21CreateIndexEntryFlagsE","s+":"194"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.754+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D37287F91","b":"7F3D3720F000","o":"78F91","s":"_ZN5mongo16IndexCatalogImpl4initEPNS_16OperationContextE","s+":"4B1"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.755+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D37250F57","b":"7F3D3720F000","o":"41F57","s":"_ZN5mongo14CollectionImpl4initEPNS_16OperationContextE","s+":"407"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.755+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D372697A8","b":"7F3D3720F000","o":"5A7A8","s":"_ZNK5mongo12DatabaseImpl4initEPNS_16OperationContextE","s+":"C8"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.755+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D37265000","b":"7F3D3720F000","o":"56000","s":"_ZN5mongo18DatabaseHolderImpl6openDbEPNS_16OperationContextENS_10StringDataEPb","s+":"1D0"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.755+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D380B7671","b":"7F3D380A8000","o":"F671","s":"_ZN5mongo12_GLOBAL__N_126ensureCollectionPropertiesEPNS_16OperationContextERKSt6vectorINSt7__cxx1112basic_stringIcSt11char_traitsIcESaIcEEESaIS9_EE","s+":"131"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.755+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D380B3FF7","b":"7F3D380A8000","o":"BFF7","s":"_ZN5mongo30repairDatabasesAndCheckVersionEPNS_16OperationContextE","s+":"F37"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.755+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D3D6EF6EA","b":"7F3D3D6C3000","o":"2C6EA","s":"_ZN5mongo12_GLOBAL__N_114_initAndListenEPNS_14ServiceContextEi","s+":"EBA"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.755+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D3D6E984C","b":"7F3D3D6C3000","o":"2684C","s":"_ZN5mongo11mongod_mainEiPPc","s+":"174C"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.755+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"5626685630F9","b":"56266836C000","o":"1F70F9","s":"main","s+":"9"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.755+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"7F3D3AF09B97","b":"7F3D3AEE8000","o":"21B97","s":"__libc_start_main","s+":"E7"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.755+0000 d20021| {"t":{"$date":"2020-07-15T17:27:36.753+00:00"},"s":"I",  "c":"CONTROL",  "id":31445,   "ctx":"initandlisten","msg":"Frame","attr":{"frame":{"a":"56266856302A","b":"56266836C000","o":"1F702A","s":"_start","s+":"2A"}}}
      [js_test:backup_restore] 2020-07-15T17:27:36.963+0000 mongo program was not running at 20021, process ended with exit code: -6
      

        1. backup_restore.js
          5 kB
          Judah Schvimer

            Assignee:
            judah.schvimer@mongodb.com Judah Schvimer
            Reporter:
            judah.schvimer@mongodb.com Judah Schvimer
            Votes:
            0 Vote for this issue
            Watchers:
            7 Start watching this issue

              Created:
              Updated:
              Resolved: