[SERVER-16673] fassert when multi-index build fails with WiredTiger Created: 26/Dec/14  Updated: 15/Jan/15  Resolved: 14/Jan/15

Status: Closed
Project: Core Server
Component/s: Index Maintenance, Storage
Affects Version/s: 2.8.0-rc4
Fix Version/s: 2.8.0-rc5

Type: Bug Priority: Major - P3
Reporter: Kamran K. Assignee: Mathias Stearn
Resolution: Done Votes: 0
Labels: 28qa, wiredtiger
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified

Backwards Compatibility: Fully Compatible
Operating System: ALL
Participants:

 Description   

This fassert occurs when using the WiredTiger storage engine, but not the mmapv1 one.

2014-12-26T15:55:15.811-0500 E INDEX    [conn1] exception while cleaning up in-progress index build: field not found, expected type 2
2014-12-26T15:55:15.811-0500 I -        [conn1] Fatal assertion 17493 Location13111 field not found, expected type 2
 
#0  0x00007ffff7bcc20b in raise (sig=5) at ../nptl/sysdeps/unix/sysv/linux/pt-raise.c:37
#1  0x00000000018a36df in mongo::mongo_breakpoint () at src/mongo/util/debug_util.cpp:58
#2  0x0000000001899ed4 in mongo::breakpoint () at src/mongo/util/debug_util.h:73
#3  0x000000000189907e in mongo::fassertFailedWithStatus (msgid=17493, status=...) at src/mongo/util/assert_util.cpp:178
#4  0x0000000001267f40 in mongo::IndexCatalog::IndexBuildBlock::fail (this=0x49714a0) at src/mongo/db/catalog/index_catalog.cpp:479
#5  0x00000000012719ef in mongo::MultiIndexBlock::~MultiIndexBlock (this=0x7ffff7fcb200, __in_chrg=<optimized out>) at src/mongo/db/catalog/index_create.cpp:86
#6  0x000000000129c465 in mongo::CmdCreateIndex::run (this=0x2835a40 <mongo::cmdCreateIndex>, txn=0x7ffff7fcc7b0, dbname=..., cmdObj=..., options=0, errmsg=..., result=..., 
    fromRepl=false) at src/mongo/db/commands/create_indexes.cpp:160
#7  0x0000000001322425 in mongo::_execCommand (txn=0x7ffff7fcc7b0, c=0x2835a40 <mongo::cmdCreateIndex>, dbname=..., cmdObj=..., queryOptions=0, errmsg=..., result=..., 
    fromRepl=false) at src/mongo/db/dbcommands.cpp:1252
#8  0x00000000013233a2 in mongo::Command::execCommand (txn=0x7ffff7fcc7b0, c=0x2835a40 <mongo::cmdCreateIndex>, queryOptions=0, cmdns=0x37bc014 "test.$cmd", cmdObj=..., 
    result=..., fromRepl=false) at src/mongo/db/dbcommands.cpp:1468
#9  0x0000000001323c84 in mongo::_runCommands (txn=0x7ffff7fcc7b0, ns=0x37bc014 "test.$cmd", _cmdobj=..., b=..., anObjBuilder=..., fromRepl=false, queryOptions=0)
    at src/mongo/db/dbcommands.cpp:1543
#10 0x00000000015277da in mongo::runCommands (txn=0x7ffff7fcc7b0, ns=0x37bc014 "test.$cmd", jsobj=..., curop=..., b=..., anObjBuilder=..., fromRepl=false, queryOptions=0)
    at src/mongo/db/query/find.cpp:131
#11 0x0000000001529563 in mongo::runQuery (txn=0x7ffff7fcc7b0, m=..., q=..., curop=..., result=..., fromDBDirectClient=false) at src/mongo/db/query/find.cpp:565
#12 0x000000000142f72b in mongo::receivedQuery (txn=0x7ffff7fcc7b0, c=..., dbresponse=..., m=..., fromDBDirectClient=false) at src/mongo/db/instance.cpp:224
#13 0x000000000143083d in mongo::assembleResponse (txn=0x7ffff7fcc7b0, m=..., dbresponse=..., remote=..., fromDBDirectClient=false) at src/mongo/db/instance.cpp:394
#14 0x00000000011326d6 in mongo::MyMessageHandler::process (this=0x307a108, m=..., port=0x30a9bb0, le=0x37c2cd0) at src/mongo/db/db.cpp:195
#15 0x00000000018c1025 in mongo::PortMessageServer::handleIncomingMsg (arg=0x30926b0) at src/mongo/util/net/message_server_port.cpp:234
#16 0x00007ffff7bc4182 in start_thread (arg=0x7ffff7fcd700) at pthread_create.c:312
#17 0x00007ffff6cc4efd in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:111


Version: b0014456dde



 Comments   
Comment by Daniel Pasette (Inactive) [ 14/Jan/15 ]

sallgeud, pretty sure this is not the same issue. Would you mind opening up a new SERVER ticket with any further details you can give about the history of this deployment?
Thanks,
Dan

Comment by Chad Kreimendahl [ 13/Jan/15 ]

This may or may not be related. I'm getting a similar assertion of "13111:field not found, expected type 2" in rc4.

Here is the message:

error.windows

2015-01-13T17:39:58.473-0600 I CONTROL  [initandlisten] MongoDB starting : pid=10700 port=27017 dbpath=D:/Databases/MongoDB/data/wiredTiger 64-bit host=some-pc
2015-01-13T17:39:58.473-0600 I CONTROL  [initandlisten] targetMinOS: Windows 7/Windows Server 2008 R2
2015-01-13T17:39:58.473-0600 I CONTROL  [initandlisten] db version v2.8.0-rc4
2015-01-13T17:39:58.473-0600 I CONTROL  [initandlisten] git version: 3ad571742911f04b307f0071979425511c4f2570
2015-01-13T17:39:58.473-0600 I CONTROL  [initandlisten] build info: windows sys.getwindowsversion(major=6, minor=1, build=7601, platform=2, service_pack='Service Pack 1') BOOST_LIB_VERSION=1_49
2015-01-13T17:39:58.473-0600 I CONTROL  [initandlisten] allocator: system
2015-01-13T17:39:58.473-0600 I CONTROL  [initandlisten] options: { config: "X:\Databases\MongoDB\mongo.yaml", net: { http: { enabled: true }, maxIncomingConnections: 1000000, port: 27017 }, operationProfiling: { mode: "slowOp", slowOpThresholdMs: 100 }, security: { authorization: "disabled" }, storage: { dbPath: "X:/Databases/MongoDB/data/wiredTiger", directoryPerDB: true, engine: "wiredTiger", journal: { enabled: true }, wiredTiger: { collectionConfig: { blockCompressor: "snappy" }, engineConfig: { cacheSizeGB: 3, checkpointDelaySecs: 60, directoryForIndexes: true, journalCompressor: "snappy", statisticsLogDelaySecs: 600 }, indexConfig: { prefixCompression: true } } }, systemLog: { destination: "file", logAppend: true, path: "X:/Databases/MongoDB/log/mongodb2.log" } }
2015-01-13T17:39:58.474-0600 I STORAGE  [initandlisten] wiredtiger_open config: create,cache_size=3G,session_max=20000,extensions=[local=(entry=index_collator_extension)],statistics=(fast),log=(enabled=true,archive=true,path=journal,compressor=snappy),checkpoint=(wait=60,log_size=2GB),statistics_log=(wait=600),
2015-01-13T17:39:58.656-0600 I -        [initandlisten] Assertion: 13111:field not found, expected type 2
2015-01-13T17:39:59.052-0600 I CONTROL  [initandlisten] mongod.exe    ...\src\mongo\util\stacktrace_win.cpp(175)                   mongo::printStackTrace+0x43
2015-01-13T17:39:59.052-0600 I CONTROL  [initandlisten] mongod.exe    ...\src\mongo\util\log.cpp(136)                              mongo::logContext+0x8f
2015-01-13T17:39:59.052-0600 I CONTROL  [initandlisten] mongod.exe    ...\src\mongo\util\assert_util.cpp(212)                      mongo::msgasserted+0xce
2015-01-13T17:39:59.052-0600 I CONTROL  [initandlisten] mongod.exe    ...\src\mongo\util\assert_util.cpp(204)                      mongo::msgasserted+0x13
2015-01-13T17:39:59.052-0600 I CONTROL  [initandlisten] mongod.exe    ...\src\mongo\bson\bsonelement.h(525)                        mongo::BSONElement::chk+0x2cc
2015-01-13T17:39:59.052-0600 I CONTROL  [initandlisten] mongod.exe    ...\src\mongo\bson\bsonelement.h(77)                         mongo::BSONElement::String+0x1b
2015-01-13T17:39:59.052-0600 I CONTROL  [initandlisten] mongod.exe    ...\src\mongo\db\storage\kv\kv_catalog.cpp(139)              mongo::KVCatalog::init+0x11d
2015-01-13T17:39:59.052-0600 I CONTROL  [initandlisten] mongod.exe    ...\src\mongo\db\storage\kv\kv_storage_engine.cpp(104)       mongo::KVStorageEngine::KVStorageEngine+0x588
2015-01-13T17:39:59.052-0600 I CONTROL  [initandlisten] mongod.exe    ...\src\mongo\db\storage\wiredtiger\wiredtiger_init.cpp(72)  mongo::`anonymous namespace'::WiredTigerFactory::create+0xef
2015-01-13T17:39:59.052-0600 I CONTROL  [initandlisten] mongod.exe    ...\src\mongo\db\global_environment_d.cpp(81)                mongo::GlobalEnvironmentMongoD::setGlobalStorageEngine+0x156
2015-01-13T17:39:59.052-0600 I CONTROL  [initandlisten] mongod.exe    ...\src\mongo\db\db.cpp(514)                                 mongo::_initAndListen+0xabf
2015-01-13T17:39:59.052-0600 I CONTROL  [initandlisten] mongod.exe    ...\src\mongo\db\db.cpp(626)                                 mongo::initAndListen+0x27
2015-01-13T17:39:59.052-0600 I CONTROL  [initandlisten] mongod.exe    ...\src\mongo\db\db.cpp(871)                                 mongoDbMain+0x1fe
2015-01-13T17:39:59.052-0600 I CONTROL  [initandlisten] mongod.exe    ...\src\mongo\db\db.cpp(669)                                 wmain+0x35
2015-01-13T17:39:59.052-0600 I CONTROL  [initandlisten] mongod.exe    f:\dd\vctools\crt\crtw32\startup\crt0.c(255)                 __tmainCRTStartup+0x144
2015-01-13T17:39:59.052-0600 I CONTROL  [initandlisten] KERNEL32.dll                                                               BaseThreadInitThunk+0x22
2015-01-13T17:39:59.053-0600 I CONTROL  [initandlisten] 
2015-01-13T17:39:59.053-0600 I STORAGE  [initandlisten] WiredTigerKVEngine shutting down
2015-01-13T17:39:59.070-0600 I STORAGE  [initandlisten] exception in initAndListen: 13111 field not found, expected type 2, terminating
2015-01-13T17:39:59.070-0600 I COMMAND  [initandlisten] dbexit:  rc: 100

Comment by Githook User [ 13/Jan/15 ]

Author:

{u'username': u'RedBeard0531', u'name': u'Mathias Stearn', u'email': u'mathias@10gen.com'}

Message: SERVER-16673 KVCCE no longer tries to remove indexes that don't exist
Branch: master
https://github.com/mongodb/mongo/commit/17b2853a6741996d648a84aba1982c71134c96b8

Generated at Thu Feb 08 03:41:53 UTC 2024 using Jira 9.7.1#970001-sha1:2222b88b221c4928ef0de3161136cc90c8356a66.