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

jstests/sharding/writeback_shard_version.js triggers apparent race, hits AssertionException 13297

    • Type: Icon: Bug Bug
    • Resolution: Duplicate
    • Priority: Icon: Major - P3 Major - P3
    • None
    • Affects Version/s: None
    • Component/s: Testing Infrastructure
    • ALL

      The Linux 32-bit build slave failed running jstests/sharding/writeback_shard_version.js .

      http://buildlogs.mongodb.org/Linux%2032-bit/builds/5308/test/sharding/writeback_shard_version.js

      From looking at the log, it seems that two mongoses each tried to create the admin database on the config server at the same time. Both reported "[conn1] couldn't find database [admin] in config db", but the second one to report this then reported "[conn1] User Assertion: 13297:can't have 2 databases that just differ on case have: admin want to add: admin" and terminated the client connection, causing the test to fail. The initial reports of not finding the admin database were 24 milliseconds apart.

       m30998| Sat Nov 24 02:17:06.320 [conn1] couldn't find database [admin] in config db
      // ... snip ...
       m30999| Sat Nov 24 02:17:06.344 [conn1] couldn't find database [admin] in config db
       m30999| Sat Nov 24 02:17:06.344 [conn1] User Assertion: 13297:can't have 2 databases that just differ on case  have: admin want to add: admin
       m30999| Sat Nov 24 02:17:06.344 [conn1] error creating initial database config information :: caused by :: can't have 2 databases that just differ on case  have: admin want to add: admin
       m30999| Sat Nov 24 02:17:06.614 [conn1] AssertionException handling request, closing client connection: 13297 error creating initial database config information :: caused by :: can't have 2 databases that just differ on case  have: admin want to add: admin
      Sat Nov 24 02:17:06.615 Socket recv() errno:104 Connection reset by peer 127.0.0.1:30999
      Sat Nov 24 02:17:06.618 SocketException: remote: 127.0.0.1:30999 error: 9001 socket exception [1] server [127.0.0.1:30999] 
      Sat Nov 24 02:17:06.618 DBClientCursor::init call() failed
      Sat Nov 24 02:17:06.901 exec error: src/mongo/shell/query.js:78 error doing query: failed
      this._cursor = this._mongo.find( this._ns , this._query , this._fields , this.
                                 ^
      
      failed to load: /mnt/slaves/Linux_32bit/mongo/jstests/sharding/writeback_shard_version.js
      
      Sat Nov 24 02:17:05.029 [initandlisten] connection accepted from 127.0.0.1:38597 #116 (37 connections now open)
      MongoDB shell version: 2.3.1-pre-
      null
      
      
      ----
      Starting sharded cluster...
      ----
      
      
      Resetting db path '/data/db/test0'
      Sat Nov 24 02:17:05.462 shell: started program /mnt/slaves/Linux_32bit/mongo/mongod --port 30000 --dbpath /data/db/test0
       m30000| Sat Nov 24 02:17:05.501 
       m30000| Sat Nov 24 02:17:05.501 32-bit servers don't have journaling enabled by default. Please use --journal if you want durability.
       m30000| Sat Nov 24 02:17:05.501 
       m30000| Sat Nov 24 02:17:05.512 [initandlisten] MongoDB starting : pid=14757 port=30000 dbpath=/data/db/test0 32-bit host=domU-12-31-39-01-70-B4
       m30000| Sat Nov 24 02:17:05.512 [initandlisten] 
       m30000| Sat Nov 24 02:17:05.512 [initandlisten] ** NOTE: This is a development version (2.3.1-pre-) of MongoDB.
       m30000| Sat Nov 24 02:17:05.512 [initandlisten] **       Not recommended for production.
       m30000| Sat Nov 24 02:17:05.512 [initandlisten] 
       m30000| Sat Nov 24 02:17:05.512 [initandlisten] ** NOTE: This is a 32 bit MongoDB binary.
       m30000| Sat Nov 24 02:17:05.512 [initandlisten] **       32 bit builds are limited to less than 2GB of data (or less with --journal).
       m30000| Sat Nov 24 02:17:05.512 [initandlisten] **       Note that journaling defaults to off for 32 bit and is currently off.
       m30000| Sat Nov 24 02:17:05.512 [initandlisten] **       See http://www.mongodb.org/display/DOCS/32+bit
       m30000| Sat Nov 24 02:17:05.512 [initandlisten] 
       m30000| Sat Nov 24 02:17:05.512 [initandlisten] db version v2.3.1-pre-, pdfile version 4.5
       m30000| Sat Nov 24 02:17:05.512 [initandlisten] git version: 32151400c2b4cf93b025723903794f1e2ec26d3c
       m30000| Sat Nov 24 02:17:05.512 [initandlisten] build info: Linux domU-12-31-39-01-70-B4 2.6.21.7-2.fc8xen #1 SMP Fri Feb 15 12:39:36 EST 2008 i686 BOOST_LIB_VERSION=1_49
       m30000| Sat Nov 24 02:17:05.512 [initandlisten] options: { dbpath: "/data/db/test0", port: 30000 }
       m30000| Sat Nov 24 02:17:05.512 [initandlisten] Unable to check for journal files due to: boost::filesystem::directory_iterator::construct: No such file or directory: "/data/db/test0/journal"
       m30000| Sat Nov 24 02:17:05.521 [initandlisten] waiting for connections on port 30000
       m30000| Sat Nov 24 02:17:05.521 [websvr] admin web console waiting for connections on port 31000
       m30000| Sat Nov 24 02:17:05.668 [initandlisten] connection accepted from 127.0.0.1:46348 #1 (1 connection now open)
      "localhost:30000"
       m30000| Sat Nov 24 02:17:05.672 [initandlisten] connection accepted from 127.0.0.1:46349 #2 (2 connections now open)
      ShardingTest test :
      {
      	"config" : "localhost:30000",
      	"shards" : [
      		connection to localhost:30000
      	]
      }
      Sat Nov 24 02:17:05.676 shell: started program /mnt/slaves/Linux_32bit/mongo/mongos --port 30999 --configdb localhost:30000 -vv --chunkSize 50
       m30999| Sat Nov 24 02:17:05.687 running with 1 config server should be done only for testing purposes and is not recommended for production
       m30999| Sat Nov 24 02:17:05.687 [mongosMain] MongoS version 2.3.1-pre- starting: pid=14774 port=30999 32-bit host=domU-12-31-39-01-70-B4 (--help for usage)
       m30999| Sat Nov 24 02:17:05.687 [mongosMain] git version: 32151400c2b4cf93b025723903794f1e2ec26d3c
       m30999| Sat Nov 24 02:17:05.687 [mongosMain] build info: Linux domU-12-31-39-01-70-B4 2.6.21.7-2.fc8xen #1 SMP Fri Feb 15 12:39:36 EST 2008 i686 BOOST_LIB_VERSION=1_49
       m30999| Sat Nov 24 02:17:05.687 [mongosMain] options: { chunkSize: 50, configdb: "localhost:30000", port: 30999, vv: true }
       m30999| Sat Nov 24 02:17:05.688 [mongosMain]  config string : localhost:30000
       m30999| Sat Nov 24 02:17:05.688 [mongosMain] creating new connection to:localhost:30000
       m30999| Sat Nov 24 02:17:05.688 BackgroundJob starting: ConnectBG
       m30000| Sat Nov 24 02:17:05.688 [initandlisten] connection accepted from 127.0.0.1:46351 #3 (3 connections now open)
       m30000| Sat Nov 24 02:17:05.691 [initandlisten] connection accepted from 127.0.0.1:46352 #4 (4 connections now open)
       m30000| Sat Nov 24 02:17:05.691 [FileAllocator] allocating new datafile /data/db/test0/config.ns, filling with zeroes...
       m30000| Sat Nov 24 02:17:05.691 [FileAllocator] creating directory /data/db/test0/_tmp
       m30999| Sat Nov 24 02:17:05.688 [mongosMain] connected connection!
       m30999| Sat Nov 24 02:17:05.690 [mongosMain] creating new connection to:localhost:30000
       m30999| Sat Nov 24 02:17:05.690 BackgroundJob starting: CheckConfigServers
       m30999| Sat Nov 24 02:17:05.691 BackgroundJob starting: ConnectBG
       m30999| Sat Nov 24 02:17:05.691 [mongosMain] connected connection!
       m30000| Sat Nov 24 02:17:05.915 [FileAllocator] done allocating datafile /data/db/test0/config.ns, size: 16MB,  took 0.223 secs
       m30000| Sat Nov 24 02:17:05.915 [FileAllocator] allocating new datafile /data/db/test0/config.0, filling with zeroes...
       m30000| Sat Nov 24 02:17:06.215 [FileAllocator] done allocating datafile /data/db/test0/config.0, size: 16MB,  took 0.299 secs
       m30000| Sat Nov 24 02:17:06.216 [FileAllocator] allocating new datafile /data/db/test0/config.1, filling with zeroes...
       m30000| Sat Nov 24 02:17:06.244 [conn4] build index config.version { _id: 1 }
       m30000| Sat Nov 24 02:17:06.245 [conn4] build index done.  scanned 0 total records. 0 secs
       m30000| Sat Nov 24 02:17:06.245 [conn4] insert config.version keyUpdates:0 locks(micros) w:554212 554ms
       m30999| Sat Nov 24 02:17:06.245 [mongosMain] creating new connection to:localhost:30000
       m30999| Sat Nov 24 02:17:06.245 BackgroundJob starting: ConnectBG
       m30000| Sat Nov 24 02:17:06.246 [initandlisten] connection accepted from 127.0.0.1:46355 #5 (5 connections now open)
       m30999| Sat Nov 24 02:17:06.246 [mongosMain] connected connection!
       m30999| Sat Nov 24 02:17:06.248 [websvr] fd limit hard:1024 soft:1024 max conn: 819
       m30999| Sat Nov 24 02:17:06.248 [websvr] admin web console waiting for connections on port 31999
       m30999| Sat Nov 24 02:17:06.248 [mongosMain] fd limit hard:1024 soft:1024 max conn: 819
       m30999| Sat Nov 24 02:17:06.248 [mongosMain] waiting for connections on port 30999
       m30999| Sat Nov 24 02:17:06.252 BackgroundJob starting: Balancer
       m30999| Sat Nov 24 02:17:06.252 [Balancer] about to contact config servers and shards
       m30999| Sat Nov 24 02:17:06.252 BackgroundJob starting: cursorTimeout
       m30999| Sat Nov 24 02:17:06.253 BackgroundJob starting: PeriodicTask::Runner
       m30000| Sat Nov 24 02:17:06.253 [conn5] build index config.settings { _id: 1 }
       m30000| Sat Nov 24 02:17:06.253 [conn5] build index done.  scanned 0 total records. 0 secs
       m30000| Sat Nov 24 02:17:06.254 [conn5] build index config.chunks { _id: 1 }
       m30000| Sat Nov 24 02:17:06.254 [conn5] build index done.  scanned 0 total records. 0 secs
       m30000| Sat Nov 24 02:17:06.254 [conn5] info: creating collection config.chunks on add index
       m30000| Sat Nov 24 02:17:06.254 [conn5] build index config.chunks { ns: 1, min: 1 }
       m30000| Sat Nov 24 02:17:06.254 [conn5] build index done.  scanned 0 total records. 0 secs
       m30000| Sat Nov 24 02:17:06.258 [conn5] build index config.chunks { ns: 1, shard: 1, min: 1 }
       m30000| Sat Nov 24 02:17:06.259 [conn5] build index done.  scanned 0 total records. 0 secs
       m30000| Sat Nov 24 02:17:06.259 [conn5] build index config.chunks { ns: 1, lastmod: 1 }
       m30000| Sat Nov 24 02:17:06.259 [conn5] build index done.  scanned 0 total records. 0 secs
       m30000| Sat Nov 24 02:17:06.259 [conn5] build index config.shards { _id: 1 }
       m30000| Sat Nov 24 02:17:06.259 [conn5] build index done.  scanned 0 total records. 0 secs
       m30000| Sat Nov 24 02:17:06.259 [conn5] info: creating collection config.shards on add index
       m30000| Sat Nov 24 02:17:06.260 [conn5] build index config.shards { host: 1 }
       m30000| Sat Nov 24 02:17:06.260 [conn5] build index done.  scanned 0 total records. 0 secs
       m30999| Sat Nov 24 02:17:06.260 [Balancer] config servers and shards contacted successfully
       m30999| Sat Nov 24 02:17:06.260 [Balancer] balancer id: domU-12-31-39-01-70-B4:30999 started at Nov 24 02:17:06
       m30999| Sat Nov 24 02:17:06.264 [Balancer] created new distributed lock for balancer on localhost:30000 ( lock timeout : 900000, ping interval : 30000, process : 0 )
       m30000| Sat Nov 24 02:17:06.265 [conn4] build index config.mongos { _id: 1 }
       m30000| Sat Nov 24 02:17:06.265 [conn4] build index done.  scanned 0 total records. 0 secs
       m30999| Sat Nov 24 02:17:06.265 [Balancer] creating new connection to:localhost:30000
       m30999| Sat Nov 24 02:17:06.265 BackgroundJob starting: ConnectBG
       m30000| Sat Nov 24 02:17:06.265 [initandlisten] connection accepted from 127.0.0.1:46356 #6 (6 connections now open)
       m30999| Sat Nov 24 02:17:06.265 [Balancer] connected connection!
       m30999| Sat Nov 24 02:17:06.266 [Balancer] Refreshing MaxChunkSize: 50
       m30999| Sat Nov 24 02:17:06.266 [Balancer] skew from remote server localhost:30000 found: 0
       m30999| Sat Nov 24 02:17:06.267 [Balancer] skew from remote server localhost:30000 found: -1
       m30999| Sat Nov 24 02:17:06.267 [Balancer] skew from remote server localhost:30000 found: 0
       m30999| Sat Nov 24 02:17:06.271 [Balancer] total clock skew of 0ms for servers localhost:30000 is in 30000ms bounds.
       m30999| Sat Nov 24 02:17:06.271 [Balancer] inserting initial doc in config.locks for lock balancer
       m30000| Sat Nov 24 02:17:06.271 [conn6] build index config.locks { _id: 1 }
       m30000| Sat Nov 24 02:17:06.271 [conn6] build index done.  scanned 0 total records. 0 secs
       m30999| Sat Nov 24 02:17:06.272 [Balancer] about to acquire distributed lock 'balancer/domU-12-31-39-01-70-B4:30999:1353741426:1804289383:
       m30999| { "state" : 1,
       m30999|   "who" : "domU-12-31-39-01-70-B4:30999:1353741426:1804289383:Balancer:846930886",
       m30999|   "process" : "domU-12-31-39-01-70-B4:30999:1353741426:1804289383",
       m30999|   "when" : { "$date" : "Sat Nov 24 02:17:06 2012" },
       m30999|   "why" : "doing balance round",
       m30999|   "ts" : { "$oid" : "50b07472a457e8a1b92fe65b" } }
       m30999| { "_id" : "balancer",
       m30999|   "state" : 0 }
       m30999| Sat Nov 24 02:17:06.272 [Balancer] distributed lock 'balancer/domU-12-31-39-01-70-B4:30999:1353741426:1804289383' acquired, ts : 50b07472a457e8a1b92fe65b
       m30999| Sat Nov 24 02:17:06.277 [Balancer] *** start balancing round
       m30999| Sat Nov 24 02:17:06.277 [Balancer] no collections to balance
       m30999| Sat Nov 24 02:17:06.277 [Balancer] no need to move any chunk
       m30999| Sat Nov 24 02:17:06.277 [Balancer] *** end of balancing round
       m30999| Sat Nov 24 02:17:06.277 [Balancer] distributed lock 'balancer/domU-12-31-39-01-70-B4:30999:1353741426:1804289383' unlocked. 
       m30999| Sat Nov 24 02:17:06.277 [LockPinger] creating distributed lock ping thread for localhost:30000 and process domU-12-31-39-01-70-B4:30999:1353741426:1804289383 (sleeping for 30000ms)
       m30000| Sat Nov 24 02:17:06.278 [conn3] build index config.lockpings { _id: 1 }
       m30000| Sat Nov 24 02:17:06.278 [conn3] build index done.  scanned 0 total records. 0 secs
       m30999| Sat Nov 24 02:17:06.279 [LockPinger] cluster localhost:30000 pinged successfully at Sat Nov 24 02:17:06 2012 by distributed lock pinger 'localhost:30000/domU-12-31-39-01-70-B4:30999:1353741426:1804289383', sleeping for 30000ms
       m30000| Sat Nov 24 02:17:06.279 [conn3] build index config.lockpings { ping: new Date(1) }
       m30000| Sat Nov 24 02:17:06.279 [conn3] build index done.  scanned 1 total records. 0 secs
       m30999| Sat Nov 24 02:17:06.288 [mongosMain] connection accepted from 127.0.0.1:58540 #1 (1 connection now open)
      Sat Nov 24 02:17:06.303 shell: started program /mnt/slaves/Linux_32bit/mongo/mongos --port 30998 --configdb localhost:30000 -vv --chunkSize 50
       m30998| Sat Nov 24 02:17:06.302 running with 1 config server should be done only for testing purposes and is not recommended for production
       m30998| Sat Nov 24 02:17:06.302 [mongosMain] MongoS version 2.3.1-pre- starting: pid=14795 port=30998 32-bit host=domU-12-31-39-01-70-B4 (--help for usage)
       m30998| Sat Nov 24 02:17:06.302 [mongosMain] git version: 32151400c2b4cf93b025723903794f1e2ec26d3c
       m30998| Sat Nov 24 02:17:06.302 [mongosMain] build info: Linux domU-12-31-39-01-70-B4 2.6.21.7-2.fc8xen #1 SMP Fri Feb 15 12:39:36 EST 2008 i686 BOOST_LIB_VERSION=1_49
       m30998| Sat Nov 24 02:17:06.302 [mongosMain] options: { chunkSize: 50, configdb: "localhost:30000", port: 30998, vv: true }
       m30998| Sat Nov 24 02:17:06.303 [mongosMain]  config string : localhost:30000
       m30998| Sat Nov 24 02:17:06.303 [mongosMain] creating new connection to:localhost:30000
       m30998| Sat Nov 24 02:17:06.304 BackgroundJob starting: ConnectBG
       m30000| Sat Nov 24 02:17:06.304 [initandlisten] connection accepted from 127.0.0.1:46358 #7 (7 connections now open)
       m30998| Sat Nov 24 02:17:06.308 [mongosMain] connected connection!
       m30998| Sat Nov 24 02:17:06.310 BackgroundJob starting: CheckConfigServers
       m30998| Sat Nov 24 02:17:06.311 [mongosMain] MaxChunkSize: 50
       m30998| Sat Nov 24 02:17:06.313 [mongosMain] fd limit hard:1024 soft:1024 max conn: 819
       m30998| Sat Nov 24 02:17:06.313 [mongosMain] waiting for connections on port 30998
      ShardingTest undefined going to add shard : localhost:30000
       m30998| Sat Nov 24 02:17:06.315 [websvr] fd limit hard:1024 soft:1024 max conn: 819
       m30998| Sat Nov 24 02:17:06.315 [websvr] admin web console waiting for connections on port 31998
       m30998| Sat Nov 24 02:17:06.315 BackgroundJob starting: Balancer
       m30998| Sat Nov 24 02:17:06.315 [Balancer] about to contact config servers and shards
       m30998| Sat Nov 24 02:17:06.315 [Balancer] creating new connection to:localhost:30000
       m30998| Sat Nov 24 02:17:06.315 BackgroundJob starting: cursorTimeout
       m30998| Sat Nov 24 02:17:06.315 BackgroundJob starting: PeriodicTask::Runner
       m30998| Sat Nov 24 02:17:06.319 [mongosMain] connection accepted from 127.0.0.1:48948 #1 (1 connection now open)
       m30998| Sat Nov 24 02:17:06.320 [conn1] couldn't find database [admin] in config db
       m30998| Sat Nov 24 02:17:06.320 [conn1] creating new connection to:localhost:30000
       m30998| Sat Nov 24 02:17:06.320 BackgroundJob starting: ConnectBG
       m30000| Sat Nov 24 02:17:06.320 [initandlisten] connection accepted from 127.0.0.1:46360 #8 (8 connections now open)
       m30998| Sat Nov 24 02:17:06.320 BackgroundJob starting: ConnectBG
       m30000| Sat Nov 24 02:17:06.320 [initandlisten] connection accepted from 127.0.0.1:46361 #9 (9 connections now open)
       m30998| Sat Nov 24 02:17:06.320 [Balancer] connected connection!
       m30998| Sat Nov 24 02:17:06.320 [Balancer] config servers and shards contacted successfully
       m30998| Sat Nov 24 02:17:06.320 [Balancer] balancer id: domU-12-31-39-01-70-B4:30998 started at Nov 24 02:17:06
       m30998| Sat Nov 24 02:17:06.320 [Balancer] created new distributed lock for balancer on localhost:30000 ( lock timeout : 900000, ping interval : 30000, process : 0 )
       m30998| Sat Nov 24 02:17:06.325 [Balancer] creating new connection to:localhost:30000
       m30998| Sat Nov 24 02:17:06.325 BackgroundJob starting: ConnectBG
       m30998| Sat Nov 24 02:17:06.325 [Balancer] connected connection!
       m30000| Sat Nov 24 02:17:06.325 [initandlisten] connection accepted from 127.0.0.1:46362 #10 (10 connections now open)
       m30998| Sat Nov 24 02:17:06.325 [conn1] connected connection!
       m30000| Sat Nov 24 02:17:06.326 [conn7] build index config.databases { _id: 1 }
       m30000| Sat Nov 24 02:17:06.326 [conn7] build index done.  scanned 0 total records. 0 secs
       m30998| Sat Nov 24 02:17:06.326 [conn1] 	 put [admin] on: config:localhost:30000
       m30998| Sat Nov 24 02:17:06.326 [conn1] scoped connection to localhost:30000 not being returned to the pool
       m30000| Sat Nov 24 02:17:06.326 [conn7] end connection 127.0.0.1:46358 (9 connections now open)
       m30998| Sat Nov 24 02:17:06.327 [Balancer] Refreshing MaxChunkSize: 50
       m30998| Sat Nov 24 02:17:06.327 [Balancer] skew from remote server localhost:30000 found: 0
       m30998| Sat Nov 24 02:17:06.331 [Balancer] skew from remote server localhost:30000 found: 0
       m30998| Sat Nov 24 02:17:06.332 [Balancer] skew from remote server localhost:30000 found: -1
       m30998| Sat Nov 24 02:17:06.332 [Balancer] total clock skew of 0ms for servers localhost:30000 is in 30000ms bounds.
       m30998| Sat Nov 24 02:17:06.332 [LockPinger] creating distributed lock ping thread for localhost:30000 and process domU-12-31-39-01-70-B4:30998:1353741426:1804289383 (sleeping for 30000ms)
       m30998| Sat Nov 24 02:17:06.332 [LockPinger] creating new connection to:localhost:30000
       m30998| Sat Nov 24 02:17:06.332 BackgroundJob starting: ConnectBG
       m30000| Sat Nov 24 02:17:06.332 [initandlisten] connection accepted from 127.0.0.1:46363 #11 (10 connections now open)
       m30998| Sat Nov 24 02:17:06.332 [Balancer] about to acquire distributed lock 'balancer/domU-12-31-39-01-70-B4:30998:1353741426:1804289383:
       m30998| { "state" : 1,
       m30998|   "who" : "domU-12-31-39-01-70-B4:30998:1353741426:1804289383:Balancer:846930886",
       m30998|   "process" : "domU-12-31-39-01-70-B4:30998:1353741426:1804289383",
       m30998|   "when" : { "$date" : "Sat Nov 24 02:17:06 2012" },
       m30998|   "why" : "doing balance round",
       m30998|   "ts" : { "$oid" : "50b074720841eb03cbb221c7" } }
       m30998| { "_id" : "balancer",
       m30998|   "state" : 0,
       m30998|   "ts" : { "$oid" : "50b07472a457e8a1b92fe65b" } }
       m30998| Sat Nov 24 02:17:06.337 [Balancer] distributed lock 'balancer/domU-12-31-39-01-70-B4:30998:1353741426:1804289383' acquired, ts : 50b074720841eb03cbb221c7
       m30998| Sat Nov 24 02:17:06.337 [Balancer] *** start balancing round
       m30998| Sat Nov 24 02:17:06.337 [Balancer] no collections to balance
       m30998| Sat Nov 24 02:17:06.337 [Balancer] no need to move any chunk
       m30998| Sat Nov 24 02:17:06.337 [Balancer] *** end of balancing round
       m30998| Sat Nov 24 02:17:06.337 [Balancer] distributed lock 'balancer/domU-12-31-39-01-70-B4:30998:1353741426:1804289383' unlocked. 
       m30998| Sat Nov 24 02:17:06.337 [LockPinger] connected connection!
       m30998| Sat Nov 24 02:17:06.343 [LockPinger] cluster localhost:30000 pinged successfully at Sat Nov 24 02:17:06 2012 by distributed lock pinger 'localhost:30000/domU-12-31-39-01-70-B4:30998:1353741426:1804289383', sleeping for 30000ms
       m30999| Sat Nov 24 02:17:06.344 [conn1] couldn't find database [admin] in config db
       m30999| Sat Nov 24 02:17:06.344 [conn1] User Assertion: 13297:can't have 2 databases that just differ on case  have: admin want to add: admin
       m30999| Sat Nov 24 02:17:06.344 [conn1] error creating initial database config information :: caused by :: can't have 2 databases that just differ on case  have: admin want to add: admin
       m30999| Sat Nov 24 02:17:06.614 [conn1] AssertionException handling request, closing client connection: 13297 error creating initial database config information :: caused by :: can't have 2 databases that just differ on case  have: admin want to add: admin
      Sat Nov 24 02:17:06.615 Socket recv() errno:104 Connection reset by peer 127.0.0.1:30999
      Sat Nov 24 02:17:06.618 SocketException: remote: 127.0.0.1:30999 error: 9001 socket exception [1] server [127.0.0.1:30999] 
      Sat Nov 24 02:17:06.618 DBClientCursor::init call() failed
      Sat Nov 24 02:17:06.901 exec error: src/mongo/shell/query.js:78 error doing query: failed
      this._cursor = this._mongo.find( this._ns , this._query , this._fields , this.
                                 ^
      
      failed to load: /mnt/slaves/Linux_32bit/mongo/jstests/sharding/writeback_shard_version.js
       m30000| Sat Nov 24 02:17:06.903 got signal 15 (Terminated), will terminate after current cmd ends
       m30000| Sat Nov 24 02:17:06.903 [interruptThread] now exiting
       m30000| Sat Nov 24 02:17:06.903 dbexit: 
       m30000| Sat Nov 24 02:17:06.903 [interruptThread] shutdown: going to close listening sockets...
       m30000| Sat Nov 24 02:17:06.903 [interruptThread] closing listening socket: 48
       m30000| Sat Nov 24 02:17:06.903 [interruptThread] closing listening socket: 49
       m30000| Sat Nov 24 02:17:06.903 [interruptThread] closing listening socket: 50
       m30000| Sat Nov 24 02:17:06.903 [interruptThread] removing socket file: /tmp/mongodb-30000.sock
       m30000| Sat Nov 24 02:17:06.903 [interruptThread] shutdown: going to flush diaglog...
       m30000| Sat Nov 24 02:17:06.903 [interruptThread] shutdown: going to close sockets...
       m30000| Sat Nov 24 02:17:06.903 [interruptThread] shutdown: waiting for fs preallocator...
       m30000| Sat Nov 24 02:17:06.958 [FileAllocator] done allocating datafile /data/db/test0/config.1, size: 32MB,  took 0.7
      Sat Nov 24 02:17:10.075 got signal 15 (Terminated), will terminate after current cmd ends
      Sat Nov 24 02:17:10.088 [interruptThread] now exiting
      Sat Nov 24 02:17:10.088 dbexit: 
      Sat Nov 24 02:17:10.088 [interruptThread] shutdown: going to close listening sockets...
      Sat Nov 24 02:17:10.088 [interruptThread] closing listening socket: 8
      Sat Nov 24 02:17:10.088 [interruptThread] closing listening socket: 9
      Sat Nov 24 02:17:10.088 [interruptThread] closing listening socket: 10
      Sat Nov 24 02:17:10.088 [interruptThread] removing socket file: /tmp/mongodb-27999.sock
      Sat Nov 24 02:17:10.088 [interruptThread] shutdown: going to flush diaglog...
      Sat Nov 24 02:17:10.088 [interruptThread] shutdown: going to close sockets...
      Sat Nov 24 02:17:10.088 [interruptThread] shutdown: waiting for fs preallocator...
      Sat Nov 24 02:17:10.088 [interruptThread] shutdown: closing all files...
      Sat Nov 24 02:17:10.088 [interruptThread] closeAllFiles() finished
      Sat Nov 24 02:17:10.088 [interruptThread] shutdown: removing fs lock...
      Sat Nov 24 02:17:10.139 dbexit: really exiting now
      

            Assignee:
            mathias@mongodb.com Mathias Stearn
            Reporter:
            tad Tad Marshall
            Votes:
            0 Vote for this issue
            Watchers:
            3 Start watching this issue

              Created:
              Updated:
              Resolved: