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

Assertion failure btBuilder.getn() == nkeys || dropDups db/pdfile.cpp 1194

    • Type: Icon: Bug Bug
    • Resolution: Done
    • Priority: Icon: Minor - P4 Minor - P4
    • 1.8.0-rc2
    • Affects Version/s: 1.8.0-rc1
    • Component/s: None
    • Labels:
      None
    • ALL

      Didn't cause a crash and doesn't seem to have affected anything.

      Mon Mar 7 10:57:46 [initandlisten] MongoDB starting : pid=1062 port=27018 dbpath=/mongodbdata/ 64-bit
      Mon Mar 7 10:57:46 [initandlisten] db version v1.8.0-rc1, pdfile version 4.5
      Mon Mar 7 10:57:46 [initandlisten] git version: 87dd5720e48e875487d5fbd78cbe120d5a610973
      Mon Mar 7 10:57:46 [initandlisten] build sys info: Linux bs-linux64.10gen.cc 2.6.21.7-2.ec2.v1.2.fc8xen #1 SMP Fri Nov 20 17:48:28 EST 2009 x86_64 BOOST_LIB_VERSION=1_41
      Mon Mar 7 10:57:46 [initandlisten] waiting for connections on port 27018
      Mon Mar 7 10:57:46 [websvr] web admin interface listening on port 28018
      Mon Mar 7 10:57:46 [startReplSets] replSet can't get local.system.replset config from self or any seed (EMPTYCONFIG)
      Mon Mar 7 10:57:46 [startReplSets] replSet info you may need to run replSetInitiate – rs.initiate() in the shell – if that is not already done
      Mon Mar 7 10:57:56 [startReplSets] trying to contact rs2a:27018
      Mon Mar 7 10:57:57 [startReplSets] replSet got config version 7 from a remote, saving locally
      Mon Mar 7 10:57:57 [startReplSets] replSet info saving a newer config version to local.system.replset
      Mon Mar 7 10:57:57 [FileAllocator] allocating new datafile /mongodbdata/local.ns, filling with zeroes...
      Mon Mar 7 10:57:57 [FileAllocator] done allocating datafile /mongodbdata/local.ns, size: 16MB, took 0.017 secs
      Mon Mar 7 10:57:57 [FileAllocator] allocating new datafile /mongodbdata/local.0, filling with zeroes...
      Mon Mar 7 10:57:57 [FileAllocator] done allocating datafile /mongodbdata/local.0, size: 64MB, took 0.072 secs
      Mon Mar 7 10:57:57 [FileAllocator] allocating new datafile /mongodbdata/local.1, filling with zeroes...
      Mon Mar 7 10:57:57 [FileAllocator] done allocating datafile /mongodbdata/local.1, size: 128MB, took 0.153 secs
      Mon Mar 7 10:57:57 [startReplSets] replSet STARTUP2
      Mon Mar 7 10:57:57 [replica set sync] ******
      Mon Mar 7 10:57:57 [replica set sync] creating replication oplog of size: 25582MB...
      Mon Mar 7 10:57:57 [FileAllocator] allocating new datafile /mongodbdata/local.2, filling with zeroes...
      Mon Mar 7 10:57:59 [ReplSetHealthPollTask] replSet info rs2d:27018 is up
      Mon Mar 7 10:57:59 [ReplSetHealthPollTask] replSet member rs2d:27018 SECONDARY
      Mon Mar 7 10:57:59 [ReplSetHealthPollTask] replSet info rs2a:27018 is up
      Mon Mar 7 10:57:59 [ReplSetHealthPollTask] replSet member rs2a:27018 PRIMARY
      Mon Mar 7 10:57:59 [ReplSetHealthPollTask] replSet info rs2arbiter:27018 is up
      Mon Mar 7 10:57:59 [ReplSetHealthPollTask] replSet member rs2arbiter:27018 ARBITER
      Mon Mar 7 10:57:59 [ReplSetHealthPollTask] replSet info rs2b:27018 is up
      Mon Mar 7 10:57:59 [ReplSetHealthPollTask] replSet member rs2b:27018 SECONDARY
      Mon Mar 7 10:57:59 [FileAllocator] done allocating datafile /mongodbdata/local.2, size: 2047MB, took 2.247 secs
      Mon Mar 7 10:57:59 [FileAllocator] allocating new datafile /mongodbdata/local.3, filling with zeroes...
      Mon Mar 7 10:58:04 [FileAllocator] done allocating datafile /mongodbdata/local.3, size: 2047MB, took 4.954 secs
      Mon Mar 7 10:58:04 [FileAllocator] allocating new datafile /mongodbdata/local.4, filling with zeroes...
      Mon Mar 7 10:58:10 [FileAllocator] done allocating datafile /mongodbdata/local.4, size: 2047MB, took 5.49 secs
      Mon Mar 7 10:58:10 [FileAllocator] allocating new datafile /mongodbdata/local.5, filling with zeroes...
      Mon Mar 7 10:58:15 [FileAllocator] done allocating datafile /mongodbdata/local.5, size: 2047MB, took 5.387 secs
      Mon Mar 7 10:58:15 [FileAllocator] allocating new datafile /mongodbdata/local.6, filling with zeroes...
      Mon Mar 7 10:58:21 [FileAllocator] done allocating datafile /mongodbdata/local.6, size: 2047MB, took 5.501 secs
      Mon Mar 7 10:58:21 [FileAllocator] allocating new datafile /mongodbdata/local.7, filling with zeroes...
      Mon Mar 7 10:58:26 [FileAllocator] done allocating datafile /mongodbdata/local.7, size: 2047MB, took 5.738 secs
      Mon Mar 7 10:58:26 [FileAllocator] allocating new datafile /mongodbdata/local.8, filling with zeroes...
      Mon Mar 7 10:58:32 [FileAllocator] done allocating datafile /mongodbdata/local.8, size: 2047MB, took 5.737 secs
      Mon Mar 7 10:58:32 [FileAllocator] allocating new datafile /mongodbdata/local.9, filling with zeroes...
      Mon Mar 7 10:58:38 [FileAllocator] done allocating datafile /mongodbdata/local.9, size: 2047MB, took 5.952 secs
      Mon Mar 7 10:58:38 [FileAllocator] allocating new datafile /mongodbdata/local.10, filling with zeroes...
      Mon Mar 7 10:58:44 [FileAllocator] done allocating datafile /mongodbdata/local.10, size: 2047MB, took 5.913 secs
      Mon Mar 7 10:58:44 [FileAllocator] allocating new datafile /mongodbdata/local.11, filling with zeroes...
      Mon Mar 7 10:58:50 [FileAllocator] done allocating datafile /mongodbdata/local.11, size: 2047MB, took 5.824 secs
      Mon Mar 7 10:58:50 [FileAllocator] allocating new datafile /mongodbdata/local.12, filling with zeroes...
      Mon Mar 7 10:58:55 [FileAllocator] done allocating datafile /mongodbdata/local.12, size: 2047MB, took 5.55 secs
      Mon Mar 7 10:58:55 [FileAllocator] allocating new datafile /mongodbdata/local.13, filling with zeroes...
      Mon Mar 7 10:59:01 [FileAllocator] done allocating datafile /mongodbdata/local.13, size: 2047MB, took 5.503 secs
      Mon Mar 7 10:59:01 [FileAllocator] allocating new datafile /mongodbdata/local.14, filling with zeroes...
      Mon Mar 7 10:59:07 [FileAllocator] done allocating datafile /mongodbdata/local.14, size: 2047MB, took 5.732 secs
      Mon Mar 7 10:59:11 [replica set sync] ******
      Mon Mar 7 10:59:11 [replica set sync] replSet initial sync pending
      Mon Mar 7 10:59:11 [replica set sync] replSet syncing to: rs2d:27018
      Mon Mar 7 10:59:11 [conn8] query admin.$cmd ntoreturn:1 command:

      { listDatabases: 1 }

      reslen:192 18187ms
      Mon Mar 7 10:59:11 [replica set sync] building new index on

      { _id: 1 }

      for local.me
      Mon Mar 7 10:59:11 [replica set sync] done for 0 records 0.013secs
      Mon Mar 7 10:59:11 [replica set sync] replSet initial sync drop all databases
      Mon Mar 7 10:59:11 [replica set sync] dropAllDatabasesExceptLocal 1
      Mon Mar 7 10:59:11 [replica set sync] replSet initial sync clone all databases
      Mon Mar 7 10:59:11 [replica set sync] replSet initial sync cloning db: accounts
      Mon Mar 7 10:59:11 [FileAllocator] allocating new datafile /mongodbdata/accounts.ns, filling with zeroes...
      Mon Mar 7 10:59:11 [FileAllocator] done allocating datafile /mongodbdata/accounts.ns, size: 16MB, took 0.016 secs
      Mon Mar 7 10:59:11 [FileAllocator] allocating new datafile /mongodbdata/accounts.0, filling with zeroes...
      Mon Mar 7 10:59:11 [FileAllocator] done allocating datafile /mongodbdata/accounts.0, size: 64MB, took 0.067 secs
      Mon Mar 7 10:59:11 [FileAllocator] allocating new datafile /mongodbdata/accounts.1, filling with zeroes...
      Mon Mar 7 10:59:12 [FileAllocator] done allocating datafile /mongodbdata/accounts.1, size: 128MB, took 0.14 secs
      Mon Mar 7 10:59:12 [replica set sync] replauthenticate: no user in local.system.users to use for authentication
      building new index on

      { _id: 1 }

      for accounts.accounts
      Mon Mar 7 10:59:12 [replica set sync] done for 4275 records 0.022secs
      Mon Mar 7 10:59:12 [replica set sync] building new index on

      { hostname: 1.0 }

      for accounts.accounts
      Mon Mar 7 10:59:12 [replica set sync] accounts Btree::insert: key too large to index, skipping accounts.accounts.$hostname_1 2155 { : "dddddddddddddddddddddddddddddddddddddddddddddddddddddddddddddddddddsas..." }
      Mon Mar 7 10:59:12 [replica set sync] accounts Assertion failure btBuilder.getn() == nkeys || dropDups db/pdfile.cpp 1194
      0x55127e 0x5628cf 0x7775ae 0x777a78 0x7794d0 0x77a91f 0x7251b8 0x726b2d 0x727b6d 0x70e002 0x70fa78 0x70775c 0x70784c 0x70a662 0x8b84f0 0x35e700673d 0x35e68d3f6d
      /home/david/mongodb/latest/bin/mongod(_ZN5mongo12sayDbContextEPKc+0xae) [0x55127e]
      /home/david/mongodb/latest/bin/mongod(_ZN5mongo9wassertedEPKcS1_j+0x10f) [0x5628cf]
      /home/david/mongodb/latest/bin/mongod(_ZN5mongo14fastBuildIndexEPKcPNS_16NamespaceDetailsERNS_12IndexDetailsEi+0x184e) [0x7775ae]
      /home/david/mongodb/latest/bin/mongod [0x777a78]
      /home/david/mongodb/latest/bin/mongod(_ZN5mongo11DataFileMgr6insertEPKcPKvibRKNS_11BSONElementEb+0xf60) [0x7794d0]
      /home/david/mongodb/latest/bin/mongod(_ZN5mongo11DataFileMgr16insertWithObjModEPKcRNS_7BSONObjEb+0x5f) [0x77a91f]
      /home/david/mongodb/latest/bin/mongod(_ZN5mongo6Cloner4copyEPKcS2_bbbbNS_5QueryE+0x3a8) [0x7251b8]
      /home/david/mongodb/latest/bin/mongod(_ZN5mongo6Cloner2goEPKcRSsRKSsbbbb+0x10cd) [0x726b2d]
      /home/david/mongodb/latest/bin/mongod(_ZN5mongo9cloneFromEPKcRSsRKSsbbbb+0x3d) [0x727b6d]
      /home/david/mongodb/latest/bin/mongod(_ZN5mongo11ReplSetImpl18_syncDoInitialSyncEv+0xf12) [0x70e002]
      /home/david/mongodb/latest/bin/mongod(_ZN5mongo11ReplSetImpl17syncDoInitialSyncEv+0x28) [0x70fa78]
      /home/david/mongodb/latest/bin/mongod(_ZN5mongo11ReplSetImpl11_syncThreadEv+0x5c) [0x70775c]
      /home/david/mongodb/latest/bin/mongod(_ZN5mongo11ReplSetImpl10syncThreadEv+0x3c) [0x70784c]
      /home/david/mongodb/latest/bin/mongod(_ZN5mongo15startSyncThreadEv+0x252) [0x70a662]
      /home/david/mongodb/latest/bin/mongod(thread_proxy+0x80) [0x8b84f0]
      /lib64/libpthread.so.0 [0x35e700673d]
      /lib64/libc.so.6(clone+0x6d) [0x35e68d3f6d]
      Mon Mar 7 10:59:12 [replica set sync] done for 4275 records 0.206secs
      Mon Mar 7 10:59:12 [replica set sync] replSet initial sync cloning db: sd

            Assignee:
            Unassigned Unassigned
            Reporter:
            boxedice David Mytton
            Votes:
            0 Vote for this issue
            Watchers:
            0 Start watching this issue

              Created:
              Updated:
              Resolved: