[SERVER-7558] read_pref_cmd.js fails when calling inline map reduce Created: 05/Nov/12  Updated: 11/Jul/16  Resolved: 26/Nov/12

Status: Closed
Project: Core Server
Component/s: Testing Infrastructure
Affects Version/s: 2.3.1
Fix Version/s: 2.3.1

Type: Bug Priority: Major - P3
Reporter: Randolph Tan Assignee: Randolph Tan
Resolution: Done Votes: 0
Labels: buildbot
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified

Attachments: Text File read_pref_cmd.txt    
Operating System: ALL
Participants:

 Description   

http://buildlogs.mongodb.org/Linux%20RHEL%2064-bit/builds/767/test/sharding/read_pref_cmd.js



 Comments   
Comment by Randolph Tan [ 26/Nov/12 ]

Test fixed with:

https://github.com/mongodb/mongo/commit/5ef99388a9c6687e00bdfe399e7d44e77a8d1a08

Comment by Eric Milkie [ 21/Nov/12 ]

Not quite fixed yet.

Comment by Randolph Tan [ 21/Nov/12 ]

New failure was fixed by:

https://github.com/mongodb/mongo/commit/08b96ce551d867c8de54a25ea2692744e14f3caf

Comment by Tad Marshall [ 21/Nov/12 ]

Also failing in Nightly Linux 64-bit.
http://buildlogs.mongodb.org/Nightly%20Linux%2064-bit/builds/1172/test/sharding/read_pref_cmd.js

 
Wed Nov 21 06:10:26.507 [conn77] end connection 127.0.0.1:49493 (0 connections now open)
MongoDB shell version: 2.3.1-pre-
null
Replica set test!
ReplSetTest Starting Set
ReplSetTest n is : 0
ReplSetTest n: 0 ports: [ 31100, 31101 ]	31100 number
{
	"useHostName" : true,
	"oplogSize" : 40,
	"keyFile" : undefined,
	"port" : 31100,
	"noprealloc" : "",
	"smallfiles" : "",
	"rest" : "",
	"replSet" : "test-rs0",
	"dbpath" : "$set-$node",
	"useHostname" : true,
	"noJournalPrealloc" : undefined,
	"pathOpts" : {
		"testName" : "test",
		"shard" : 0,
		"node" : 0,
		"set" : "test-rs0"
	},
	"restart" : undefined
}
ReplSetTest Starting....
Resetting db path '/data/db/test-rs0-0'
Wed Nov 21 06:10:27.008 shell: started program /mnt/home/buildbot/slave/Linux_64bit_Nightly/mongo/mongod --oplogSize 40 --port 31100 --noprealloc --smallfiles --rest --replSet test-rs0 --dbpath /data/db/test-rs0-0
 m31100| note: noprealloc may hurt performance in many applications
 m31100| Wed Nov 21 06:10:27.063 [initandlisten] MongoDB starting : pid=26475 port=31100 dbpath=/data/db/test-rs0-0 64-bit host=ip-10-2-29-40
 m31100| Wed Nov 21 06:10:27.063 [initandlisten] 
 m31100| Wed Nov 21 06:10:27.063 [initandlisten] ** NOTE: This is a development version (2.3.1-pre-) of MongoDB.
 m31100| Wed Nov 21 06:10:27.063 [initandlisten] **       Not recommended for production.
 m31100| Wed Nov 21 06:10:27.063 [initandlisten] 
 m31100| Wed Nov 21 06:10:27.063 [initandlisten] db version v2.3.1-pre-, pdfile version 4.5
 m31100| Wed Nov 21 06:10:27.063 [initandlisten] git version: cb14cf83102cca848e572ffd8b95c6fa35594175
 m31100| Wed Nov 21 06:10:27.063 [initandlisten] build info: Linux ip-10-2-29-40 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_49
 m31100| Wed Nov 21 06:10:27.063 [initandlisten] options: { dbpath: "/data/db/test-rs0-0", noprealloc: true, oplogSize: 40, port: 31100, replSet: "test-rs0", rest: true, smallfiles: true }
 m31100| Wed Nov 21 06:10:27.084 [initandlisten] journal dir=/data/db/test-rs0-0/journal
 m31100| Wed Nov 21 06:10:27.084 [initandlisten] recover : no journal files present, no recovery needed
 m31100| Wed Nov 21 06:10:27.307 [initandlisten] waiting for connections on port 31100
 m31100| Wed Nov 21 06:10:27.308 [websvr] admin web console waiting for connections on port 32100
 m31100| Wed Nov 21 06:10:27.311 [rsStart] replSet can't get local.system.replset config from self or any seed (EMPTYCONFIG)
 m31100| Wed Nov 21 06:10:27.311 [rsStart] replSet info you may need to run replSetInitiate -- rs.initiate() in the shell -- if that is not already done
[ connection to ip-10-2-29-40:31100 ]
ReplSetTest n is : 1
ReplSetTest n: 1 ports: [ 31100, 31101 ]	31101 number
{
	"useHostName" : true,
	"oplogSize" : 40,
	"keyFile" : undefined,
	"port" : 31101,
	"noprealloc" : "",
	"smallfiles" : "",
	"rest" : "",
	"replSet" : "test-rs0",
	"dbpath" : "$set-$node",
	"useHostname" : true,
	"noJournalPrealloc" : undefined,
	"pathOpts" : {
		"testName" : "test",
		"shard" : 0,
		"node" : 1,
		"set" : "test-rs0"
	},
	"restart" : undefined
}
ReplSetTest Starting....
Resetting db path '/data/db/test-rs0-1'
Wed Nov 21 06:10:27.416 shell: started program /mnt/home/buildbot/slave/Linux_64bit_Nightly/mongo/mongod --oplogSize 40 --port 31101 --noprealloc --smallfiles --rest --replSet test-rs0 --dbpath /data/db/test-rs0-1
 m31100| Wed Nov 21 06:10:27.416 [initandlisten] connection accepted from 127.0.0.1:60352 #1 (1 connection now open)
 m31101| note: noprealloc may hurt performance in many applications
 m31101| Wed Nov 21 06:10:27.464 [initandlisten] MongoDB starting : pid=26524 port=31101 dbpath=/data/db/test-rs0-1 64-bit host=ip-10-2-29-40
 m31101| Wed Nov 21 06:10:27.464 [initandlisten] 
 m31101| Wed Nov 21 06:10:27.464 [initandlisten] ** NOTE: This is a development version (2.3.1-pre-) of MongoDB.
 m31101| Wed Nov 21 06:10:27.464 [initandlisten] **       Not recommended for production.
 m31101| Wed Nov 21 06:10:27.464 [initandlisten] 
 m31101| Wed Nov 21 06:10:27.464 [initandlisten] db version v2.3.1-pre-, pdfile version 4.5
 m31101| Wed Nov 21 06:10:27.465 [initandlisten] git version: cb14cf83102cca848e572ffd8b95c6fa35594175
 m31101| Wed Nov 21 06:10:27.465 [initandlisten] build info: Linux ip-10-2-29-40 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_49
 m31101| Wed Nov 21 06:10:27.465 [initandlisten] options: { dbpath: "/data/db/test-rs0-1", noprealloc: true, oplogSize: 40, port: 31101, replSet: "test-rs0", rest: true, smallfiles: true }
 m31101| Wed Nov 21 06:10:27.469 [initandlisten] journal dir=/data/db/test-rs0-1/journal
 m31101| Wed Nov 21 06:10:27.469 [initandlisten] recover : no journal files present, no recovery needed
 m31101| Wed Nov 21 06:10:27.661 [initandlisten] waiting for connections on port 31101
 m31101| Wed Nov 21 06:10:27.662 [websvr] admin web console waiting for connections on port 32101
 m31101| Wed Nov 21 06:10:27.664 [rsStart] replSet can't get local.system.replset config from self or any seed (EMPTYCONFIG)
 m31101| Wed Nov 21 06:10:27.665 [rsStart] replSet info you may need to run replSetInitiate -- rs.initiate() in the shell -- if that is not already done
[ connection to ip-10-2-29-40:31100, connection to ip-10-2-29-40:31101 ]
 m31101| Wed Nov 21 06:10:27.820 [initandlisten] connection accepted from 127.0.0.1:38901 #1 (1 connection now open)
{
	"replSetInitiate" : {
		"_id" : "test-rs0",
		"members" : [
			{
				"_id" : 0,
				"host" : "ip-10-2-29-40:31100"
			},
			{
				"_id" : 1,
				"host" : "ip-10-2-29-40:31101"
			}
		]
	}
}
 m31100| Wed Nov 21 06:10:27.823 [conn1] replSet replSetInitiate admin command received from client
 m31100| Wed Nov 21 06:10:27.823 [conn1] replSet replSetInitiate config object parses ok, 2 members specified
 m31101| Wed Nov 21 06:10:27.825 [initandlisten] connection accepted from 10.2.29.40:54592 #2 (2 connections now open)
 m31100| Wed Nov 21 06:10:27.826 [conn1] replSet replSetInitiate all members seem up
 m31100| Wed Nov 21 06:10:27.826 [conn1] ******
 m31100| Wed Nov 21 06:10:27.826 [conn1] creating replication oplog of size: 40MB...
 m31100| Wed Nov 21 06:10:27.826 [FileAllocator] allocating new datafile /data/db/test-rs0-0/local.ns, filling with zeroes...
 m31100| Wed Nov 21 06:10:27.826 [FileAllocator] creating directory /data/db/test-rs0-0/_tmp
 m31100| Wed Nov 21 06:10:27.852 [FileAllocator] done allocating datafile /data/db/test-rs0-0/local.ns, size: 16MB,  took 0.024 secs
 m31100| Wed Nov 21 06:10:27.852 [FileAllocator] allocating new datafile /data/db/test-rs0-0/local.0, filling with zeroes...
 m31100| Wed Nov 21 06:10:27.947 [FileAllocator] done allocating datafile /data/db/test-rs0-0/local.0, size: 64MB,  took 0.094 secs
 m31100| Wed Nov 21 06:10:30.041 [conn1] ******
 m31100| Wed Nov 21 06:10:30.041 [conn1] replSet info saving a newer config version to local.system.replset
 m31100| Wed Nov 21 06:10:30.055 [conn1] replSet saveConfigLocally done
 m31100| Wed Nov 21 06:10:30.055 [conn1] replSet replSetInitiate config now saved locally.  Should come online in about a minute.
 m31100| Wed Nov 21 06:10:30.055 [conn1] command admin.$cmd command: { replSetInitiate: { _id: "test-rs0", members: [ { _id: 0.0, host: "ip-10-2-29-40:31100" }, { _id: 1.0, host: "ip-10-2-29-40:31101" } ] } } ntoreturn:1 keyUpdates:0 locks(micros) W:2228522 reslen:112 2231ms
{
	"info" : "Config now saved locally.  Should come online in about a minute.",
	"ok" : 1
}
 m31100| Wed Nov 21 06:10:37.311 [rsStart] replSet I am ip-10-2-29-40:31100
 m31100| Wed Nov 21 06:10:37.311 [rsStart] replSet STARTUP2
 m31100| Wed Nov 21 06:10:37.312 [rsHealthPoll] replSet member ip-10-2-29-40:31101 is up
 m31100| Wed Nov 21 06:10:37.312 [rsMgr] replSet total number of votes is even - add arbiter or give one member an extra vote
 m31101| Wed Nov 21 06:10:37.665 [rsStart] trying to contact ip-10-2-29-40:31100
 m31100| Wed Nov 21 06:10:37.667 [initandlisten] connection accepted from 10.2.29.40:41880 #2 (2 connections now open)
 m31101| Wed Nov 21 06:10:37.668 [rsStart] replSet I am ip-10-2-29-40:31101
 m31101| Wed Nov 21 06:10:37.668 [rsStart] replSet got config version 1 from a remote, saving locally
 m31101| Wed Nov 21 06:10:37.668 [rsStart] replSet info saving a newer config version to local.system.replset
 m31101| Wed Nov 21 06:10:37.668 [FileAllocator] allocating new datafile /data/db/test-rs0-1/local.ns, filling with zeroes...
 m31101| Wed Nov 21 06:10:37.668 [FileAllocator] creating directory /data/db/test-rs0-1/_tmp
 m31101| Wed Nov 21 06:10:37.717 [FileAllocator] done allocating datafile /data/db/test-rs0-1/local.ns, size: 16MB,  took 0.04 secs
 m31101| Wed Nov 21 06:10:37.718 [FileAllocator] allocating new datafile /data/db/test-rs0-1/local.0, filling with zeroes...
 m31101| Wed Nov 21 06:10:37.742 [FileAllocator] done allocating datafile /data/db/test-rs0-1/local.0, size: 16MB,  took 0.024 secs
 m31101| Wed Nov 21 06:10:38.106 [rsStart] replSet saveConfigLocally done
 m31101| Wed Nov 21 06:10:38.107 [rsStart] replSet STARTUP2
 m31101| Wed Nov 21 06:10:38.107 [rsMgr] replSet total number of votes is even - add arbiter or give one member an extra vote
 m31101| Wed Nov 21 06:10:38.107 [rsSync] ******
 m31101| Wed Nov 21 06:10:38.107 [rsSync] creating replication oplog of size: 40MB...
 m31101| Wed Nov 21 06:10:38.111 [FileAllocator] allocating new datafile /data/db/test-rs0-1/local.1, filling with zeroes...
 m31101| Wed Nov 21 06:10:38.207 [FileAllocator] done allocating datafile /data/db/test-rs0-1/local.1, size: 64MB,  took 0.096 secs
 m31100| Wed Nov 21 06:10:38.314 [rsSync] replSet SECONDARY
 m31100| Wed Nov 21 06:10:39.313 [rsHealthPoll] replset info ip-10-2-29-40:31101 thinks that we are down
 m31100| Wed Nov 21 06:10:39.313 [rsHealthPoll] replSet member ip-10-2-29-40:31101 is now in state STARTUP2
 m31100| Wed Nov 21 06:10:39.313 [rsMgr] not electing self, ip-10-2-29-40:31101 would veto
 m31101| Wed Nov 21 06:10:39.670 [rsHealthPoll] replSet member ip-10-2-29-40:31100 is up
 m31101| Wed Nov 21 06:10:39.670 [rsHealthPoll] replSet member ip-10-2-29-40:31100 is now in state SECONDARY
 m31101| Wed Nov 21 06:10:40.157 [rsSync] ******
 m31101| Wed Nov 21 06:10:40.157 [rsSync] replSet initial sync pending
 m31101| Wed Nov 21 06:10:40.157 [rsSync] replSet initial sync need a member to be primary or secondary to do our initial sync
 m31100| Wed Nov 21 06:10:45.315 [rsMgr] replSet info electSelf 0
 m31101| Wed Nov 21 06:10:45.316 [conn2] replSet RECOVERING
 m31101| Wed Nov 21 06:10:45.316 [conn2] replSet info voting yea for ip-10-2-29-40:31100 (0)
 m31100| Wed Nov 21 06:10:45.317 [rsMgr] replSet PRIMARY
 m31101| Wed Nov 21 06:10:45.672 [rsHealthPoll] replSet member ip-10-2-29-40:31100 is now in state PRIMARY
 m31100| Wed Nov 21 06:10:46.073 [FileAllocator] allocating new datafile /data/db/test-rs0-0/admin.ns, filling with zeroes...
 m31100| Wed Nov 21 06:10:46.108 [FileAllocator] done allocating datafile /data/db/test-rs0-0/admin.ns, size: 16MB,  took 0.034 secs
 m31100| Wed Nov 21 06:10:46.108 [FileAllocator] allocating new datafile /data/db/test-rs0-0/admin.0, filling with zeroes...
 m31100| Wed Nov 21 06:10:46.133 [FileAllocator] done allocating datafile /data/db/test-rs0-0/admin.0, size: 16MB,  took 0.024 secs
 m31100| Wed Nov 21 06:10:46.135 [conn1] build index admin.foo { _id: 1 }
 m31100| Wed Nov 21 06:10:46.136 [conn1] build index done.  scanned 0 total records. 0 secs
ReplSetTest [object Object]
ReplSetTest waiting for connection to ip-10-2-29-40:31101 to have an oplog built.
 m31100| Wed Nov 21 06:10:47.316 [rsHealthPoll] replSet member ip-10-2-29-40:31101 is now in state RECOVERING
ReplSetTest waiting for connection to ip-10-2-29-40:31101 to have an oplog built.
ReplSetTest waiting for connection to ip-10-2-29-40:31101 to have an oplog built.
ReplSetTest waiting for connection to ip-10-2-29-40:31101 to have an oplog built.
ReplSetTest waiting for connection to ip-10-2-29-40:31101 to have an oplog built.
ReplSetTest waiting for connection to ip-10-2-29-40:31101 to have an oplog built.
 m31101| Wed Nov 21 06:10:56.158 [rsSync] replSet initial sync pending
 m31101| Wed Nov 21 06:10:56.158 [rsSync] replSet syncing to: ip-10-2-29-40:31100
 m31100| Wed Nov 21 06:10:56.161 [initandlisten] connection accepted from 10.2.29.40:41887 #3 (3 connections now open)
 m31101| Wed Nov 21 06:10:56.164 [rsSync] build index local.me { _id: 1 }
 m31101| Wed Nov 21 06:10:56.165 [rsSync] build index done.  scanned 0 total records. 0.001 secs
 m31101| Wed Nov 21 06:10:56.165 [rsSync] replSet initial sync drop all databases
 m31101| Wed Nov 21 06:10:56.165 [rsSync] dropAllDatabasesExceptLocal 1
 m31101| Wed Nov 21 06:10:56.165 [rsSync] replSet initial sync clone all databases
 m31101| Wed Nov 21 06:10:56.166 [rsSync] replSet initial sync cloning db: admin
 m31100| Wed Nov 21 06:10:56.167 [initandlisten] connection accepted from 10.2.29.40:41888 #4 (4 connections now open)
 m31101| Wed Nov 21 06:10:56.167 [FileAllocator] allocating new datafile /data/db/test-rs0-1/admin.ns, filling with zeroes...
 m31101| Wed Nov 21 06:10:56.240 [FileAllocator] done allocating datafile /data/db/test-rs0-1/admin.ns, size: 16MB,  took 0.072 secs
 m31101| Wed Nov 21 06:10:56.242 [FileAllocator] allocating new datafile /data/db/test-rs0-1/admin.0, filling with zeroes...
 m31101| Wed Nov 21 06:10:56.266 [FileAllocator] done allocating datafile /data/db/test-rs0-1/admin.0, size: 16MB,  took 0.023 secs
 m31101| Wed Nov 21 06:10:56.273 [rsSync] build index admin.foo { _id: 1 }
 m31101| Wed Nov 21 06:10:56.274 [rsSync] 	 fastBuildIndex dupsToDrop:0
 m31101| Wed Nov 21 06:10:56.274 [rsSync] build index done.  scanned 1 total records. 0 secs
 m31101| Wed Nov 21 06:10:56.274 [rsSync] replSet initial sync data copy, starting syncup
 m31101| Wed Nov 21 06:10:56.274 [rsSync] oplog sync 1 of 3
 m31100| Wed Nov 21 06:10:56.274 [conn4] end connection 10.2.29.40:41888 (3 connections now open)
 m31101| Wed Nov 21 06:10:56.274 [rsSync] oplog sync 2 of 3
 m31101| Wed Nov 21 06:10:56.275 [rsSync] replSet initial sync building indexes
 m31101| Wed Nov 21 06:10:56.275 [rsSync] replSet initial sync cloning indexes for : admin
 m31100| Wed Nov 21 06:10:56.281 [initandlisten] connection accepted from 10.2.29.40:41891 #5 (4 connections now open)
 m31100| Wed Nov 21 06:10:56.281 [conn5] end connection 10.2.29.40:41891 (3 connections now open)
 m31101| Wed Nov 21 06:10:56.281 [rsSync] oplog sync 3 of 3
 m31101| Wed Nov 21 06:10:56.282 [rsSync] replSet initial sync finishing up
 m31101| Wed Nov 21 06:10:56.287 [rsSync] replSet set minValid=50acb6b6:1
 m31101| Wed Nov 21 06:10:56.288 [rsSync] build index local.replset.minvalid { _id: 1 }
 m31101| Wed Nov 21 06:10:56.288 [rsSync] build index done.  scanned 0 total records. 0 secs
 m31101| Wed Nov 21 06:10:56.289 [rsSync] replSet initial sync done
 m31100| Wed Nov 21 06:10:56.289 [conn3] end connection 10.2.29.40:41887 (2 connections now open)
 m31101| Wed Nov 21 06:10:57.125 [rsBackgroundSync] replSet syncing to: ip-10-2-29-40:31100
 m31100| Wed Nov 21 06:10:57.126 [initandlisten] connection accepted from 10.2.29.40:41892 #6 (3 connections now open)
 m31101| Wed Nov 21 06:10:57.275 [rsSyncNotifier] replset setting oplog notifier to ip-10-2-29-40:31100
 m31100| Wed Nov 21 06:10:57.276 [initandlisten] connection accepted from 10.2.29.40:41893 #7 (4 connections now open)
{
	"ts" : {
		"t" : 1353496246000,
		"i" : 1
	},
	"h" : NumberLong("6591055532541345809"),
	"v" : 2,
	"op" : "i",
	"ns" : "admin.foo",
	"o" : {
		"_id" : ObjectId("50acb6b6de5a9579f6a99818"),
		"x" : 1
	}
}
ReplSetTest await TS for connection to ip-10-2-29-40:31101 is 1353496246000:1 and latest is 1353496246000:1
ReplSetTest await oplog size for connection to ip-10-2-29-40:31101 is 1
ReplSetTest await synced=true
 m31100| Wed Nov 21 06:10:58.279 [slaveTracking] build index local.slaves { _id: 1 }
 m31100| Wed Nov 21 06:10:58.280 [slaveTracking] build index done.  scanned 0 total records. 0.001 secs
 m31101| Wed Nov 21 06:10:58.291 [rsSync] replSet SECONDARY
 m31100| Wed Nov 21 06:10:59.320 [rsHealthPoll] replSet member ip-10-2-29-40:31101 is now in state SECONDARY
Wed Nov 21 06:11:00.154 starting new replica set monitor for replica set test-rs0 with seed of ip-10-2-29-40:31100,ip-10-2-29-40:31101
 m31100| Wed Nov 21 06:11:00.157 [initandlisten] connection accepted from 10.2.29.40:41895 #8 (5 connections now open)
Wed Nov 21 06:11:00.157 successfully connected to seed ip-10-2-29-40:31100 for replica set test-rs0
Wed Nov 21 06:11:00.157 changing hosts to { 0: "ip-10-2-29-40:31100", 1: "ip-10-2-29-40:31101" } from test-rs0/
Wed Nov 21 06:11:00.157 trying to add new host ip-10-2-29-40:31100 to replica set test-rs0
 m31100| Wed Nov 21 06:11:00.158 [initandlisten] connection accepted from 10.2.29.40:41896 #9 (6 connections now open)
Wed Nov 21 06:11:00.158 successfully connected to new host ip-10-2-29-40:31100 in replica set test-rs0
Wed Nov 21 06:11:00.158 trying to add new host ip-10-2-29-40:31101 to replica set test-rs0
 m31101| Wed Nov 21 06:11:00.158 [initandlisten] connection accepted from 10.2.29.40:54612 #3 (3 connections now open)
Wed Nov 21 06:11:00.158 successfully connected to new host ip-10-2-29-40:31101 in replica set test-rs0
 m31100| Wed Nov 21 06:11:00.159 [initandlisten] connection accepted from 10.2.29.40:41898 #10 (7 connections now open)
 m31100| Wed Nov 21 06:11:00.159 [conn8] end connection 10.2.29.40:41895 (6 connections now open)
Wed Nov 21 06:11:00.160 Primary for replica set test-rs0 changed to ip-10-2-29-40:31100
 m31101| Wed Nov 21 06:11:00.160 [initandlisten] connection accepted from 10.2.29.40:54614 #4 (4 connections now open)
Wed Nov 21 06:11:00.160 replica set monitor for replica set test-rs0 started, address is test-rs0/ip-10-2-29-40:31100,ip-10-2-29-40:31101
Resetting db path '/data/db/test-config0'
Wed Nov 21 06:11:00.162 [ReplicaSetMonitorWatcher] starting
Wed Nov 21 06:11:00.164 shell: started program /mnt/home/buildbot/slave/Linux_64bit_Nightly/mongo/mongod --port 29000 --dbpath /data/db/test-config0 --configsvr
 m29000| Wed Nov 21 06:11:00.208 [initandlisten] MongoDB starting : pid=26616 port=29000 dbpath=/data/db/test-config0 64-bit host=ip-10-2-29-40
 m29000| Wed Nov 21 06:11:00.208 [initandlisten] 
 m29000| Wed Nov 21 06:11:00.208 [initandlisten] ** NOTE: This is a development version (2.3.1-pre-) of MongoDB.
 m29000| Wed Nov 21 06:11:00.208 [initandlisten] **       Not recommended for production.
 m29000| Wed Nov 21 06:11:00.208 [initandlisten] 
 m29000| Wed Nov 21 06:11:00.208 [initandlisten] db version v2.3.1-pre-, pdfile version 4.5
 m29000| Wed Nov 21 06:11:00.208 [initandlisten] git version: cb14cf83102cca848e572ffd8b95c6fa35594175
 m29000| Wed Nov 21 06:11:00.208 [initandlisten] build info: Linux ip-10-2-29-40 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_49
 m29000| Wed Nov 21 06:11:00.208 [initandlisten] options: { configsvr: true, dbpath: "/data/db/test-config0", port: 29000 }
 m29000| Wed Nov 21 06:11:00.212 [initandlisten] journal dir=/data/db/test-config0/journal
 m29000| Wed Nov 21 06:11:00.212 [initandlisten] recover : no journal files present, no recovery needed
 m29000| Wed Nov 21 06:11:00.441 [initandlisten] preallocateIsFaster=true 2.24
 m29000| Wed Nov 21 06:11:00.702 [initandlisten] preallocateIsFaster=true 2.58
 m29000| Wed Nov 21 06:11:02.070 [initandlisten] preallocateIsFaster=true 2.2
 m29000| Wed Nov 21 06:11:02.071 [initandlisten] preallocating a journal file /data/db/test-config0/journal/prealloc.0
 m31101| Wed Nov 21 06:11:05.322 [conn2] end connection 10.2.29.40:54592 (3 connections now open)
 m31101| Wed Nov 21 06:11:05.323 [initandlisten] connection accepted from 10.2.29.40:54642 #5 (4 connections now open)
 m29000| Wed Nov 21 06:11:05.680 [initandlisten] preallocating a journal file /data/db/test-config0/journal/prealloc.1
 m31100| Wed Nov 21 06:11:07.692 [conn2] end connection 10.2.29.40:41880 (5 connections now open)
 m31100| Wed Nov 21 06:11:07.693 [initandlisten] connection accepted from 10.2.29.40:41940 #11 (6 connections now open)
 m29000| Wed Nov 21 06:11:09.035 [initandlisten] preallocating a journal file /data/db/test-config0/journal/prealloc.2
 m29000| Wed Nov 21 06:11:12.465 [initandlisten] waiting for connections on port 29000
 m29000| Wed Nov 21 06:11:12.465 [websvr] admin web console waiting for connections on port 30000
"ip-10-2-29-40:29000"
 m29000| Wed Nov 21 06:11:12.666 [initandlisten] connection accepted from 127.0.0.1:37473 #1 (1 connection now open)
ShardingTest test :
{
	"config" : "ip-10-2-29-40:29000",
	"shards" : [
		connection to test-rs0/ip-10-2-29-40:31100,ip-10-2-29-40:31101
	]
}
 m29000| Wed Nov 21 06:11:12.668 [initandlisten] connection accepted from 10.2.29.40:42531 #2 (2 connections now open)
Wed Nov 21 06:11:12.671 shell: started program /mnt/home/buildbot/slave/Linux_64bit_Nightly/mongo/mongos --port 30999 --configdb ip-10-2-29-40:29000 --chunkSize 50
 m30999| Wed Nov 21 06:11:12.683 running with 1 config server should be done only for testing purposes and is not recommended for production
 m30999| Wed Nov 21 06:11:12.684 [mongosMain] MongoS version 2.3.1-pre- starting: pid=26699 port=30999 64-bit host=ip-10-2-29-40 (--help for usage)
 m30999| Wed Nov 21 06:11:12.684 [mongosMain] git version: cb14cf83102cca848e572ffd8b95c6fa35594175
 m30999| Wed Nov 21 06:11:12.684 [mongosMain] build info: Linux ip-10-2-29-40 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_49
 m30999| Wed Nov 21 06:11:12.684 [mongosMain] options: { chunkSize: 50, configdb: "ip-10-2-29-40:29000", port: 30999 }
 m29000| Wed Nov 21 06:11:12.686 [initandlisten] connection accepted from 10.2.29.40:42534 #3 (3 connections now open)
 m29000| Wed Nov 21 06:11:12.691 [initandlisten] connection accepted from 10.2.29.40:42536 #4 (4 connections now open)
 m29000| Wed Nov 21 06:11:12.691 [initandlisten] connection accepted from 10.2.29.40:42537 #5 (5 connections now open)
 m29000| Wed Nov 21 06:11:12.692 [FileAllocator] allocating new datafile /data/db/test-config0/config.ns, filling with zeroes...
 m29000| Wed Nov 21 06:11:12.692 [FileAllocator] creating directory /data/db/test-config0/_tmp
 m29000| Wed Nov 21 06:11:12.719 [FileAllocator] done allocating datafile /data/db/test-config0/config.ns, size: 16MB,  took 0.024 secs
 m29000| Wed Nov 21 06:11:12.719 [FileAllocator] allocating new datafile /data/db/test-config0/config.0, filling with zeroes...
 m29000| Wed Nov 21 06:11:12.743 [FileAllocator] done allocating datafile /data/db/test-config0/config.0, size: 16MB,  took 0.024 secs
 m29000| Wed Nov 21 06:11:12.743 [FileAllocator] allocating new datafile /data/db/test-config0/config.1, filling with zeroes...
 m29000| Wed Nov 21 06:11:12.750 [conn4] build index config.version { _id: 1 }
 m29000| Wed Nov 21 06:11:12.751 [conn4] build index done.  scanned 0 total records. 0 secs
 m30999| Wed Nov 21 06:11:12.753 [mongosMain] couldn't unlink socket file /tmp/mongodb-30999.sockerrno:1 Operation not permitted skipping
 m30999| Wed Nov 21 06:11:12.753 [mongosMain] waiting for connections on port 30999
 m30999| Wed Nov 21 06:11:12.753 [websvr] admin web console waiting for connections on port 31999
 m30999| Wed Nov 21 06:11:12.753 [Balancer] about to contact config servers and shards
 m29000| Wed Nov 21 06:11:12.755 [conn3] build index config.settings { _id: 1 }
 m29000| Wed Nov 21 06:11:12.756 [conn3] build index done.  scanned 0 total records. 0 secs
 m29000| Wed Nov 21 06:11:12.757 [conn3] build index config.chunks { _id: 1 }
 m29000| Wed Nov 21 06:11:12.758 [conn3] build index done.  scanned 0 total records. 0 secs
 m29000| Wed Nov 21 06:11:12.758 [conn3] info: creating collection config.chunks on add index
 m29000| Wed Nov 21 06:11:12.758 [conn3] build index config.chunks { ns: 1, min: 1 }
 m29000| Wed Nov 21 06:11:12.758 [conn3] build index done.  scanned 0 total records. 0 secs
 m29000| Wed Nov 21 06:11:12.759 [conn3] build index config.chunks { ns: 1, shard: 1, min: 1 }
 m29000| Wed Nov 21 06:11:12.761 [conn3] build index done.  scanned 0 total records. 0 secs
 m29000| Wed Nov 21 06:11:12.761 [conn3] build index config.chunks { ns: 1, lastmod: 1 }
 m29000| Wed Nov 21 06:11:12.761 [conn3] build index done.  scanned 0 total records. 0 secs
 m29000| Wed Nov 21 06:11:12.762 [conn3] build index config.shards { _id: 1 }
 m29000| Wed Nov 21 06:11:12.763 [conn3] build index done.  scanned 0 total records. 0 secs
 m29000| Wed Nov 21 06:11:12.763 [conn3] info: creating collection config.shards on add index
 m29000| Wed Nov 21 06:11:12.763 [conn3] build index config.shards { host: 1 }
 m29000| Wed Nov 21 06:11:12.764 [conn3] build index done.  scanned 0 total records. 0 secs
 m30999| Wed Nov 21 06:11:12.764 [Balancer] config servers and shards contacted successfully
 m30999| Wed Nov 21 06:11:12.764 [Balancer] balancer id: ip-10-2-29-40:30999 started at Nov 21 06:11:12
 m30999| Wed Nov 21 06:11:12.764 [Balancer] created new distributed lock for balancer on ip-10-2-29-40:29000 ( lock timeout : 900000, ping interval : 30000, process : 0 )
 m29000| Wed Nov 21 06:11:12.766 [initandlisten] connection accepted from 10.2.29.40:42538 #6 (6 connections now open)
 m29000| Wed Nov 21 06:11:12.767 [conn4] build index config.mongos { _id: 1 }
 m29000| Wed Nov 21 06:11:12.768 [conn4] build index done.  scanned 0 total records. 0 secs
 m30999| Wed Nov 21 06:11:12.770 [LockPinger] creating distributed lock ping thread for ip-10-2-29-40:29000 and process ip-10-2-29-40:30999:1353496272:1804289383 (sleeping for 30000ms)
 m29000| Wed Nov 21 06:11:12.770 [conn6] build index config.locks { _id: 1 }
 m29000| Wed Nov 21 06:11:12.770 [conn6] build index done.  scanned 0 total records. 0 secs
 m30999| Wed Nov 21 06:11:12.771 [Balancer] distributed lock 'balancer/ip-10-2-29-40:30999:1353496272:1804289383' acquired, ts : 50acb6d05199eb841cb264c2
 m29000| Wed Nov 21 06:11:12.772 [conn3] build index config.lockpings { _id: 1 }
 m29000| Wed Nov 21 06:11:12.772 [conn3] build index done.  scanned 0 total records. 0 secs
 m29000| Wed Nov 21 06:11:12.773 [conn3] build index config.lockpings { ping: new Date(1) }
 m29000| Wed Nov 21 06:11:12.775 [conn3] build index done.  scanned 1 total records. 0 secs
 m30999| Wed Nov 21 06:11:12.776 [Balancer] distributed lock 'balancer/ip-10-2-29-40:30999:1353496272:1804289383' unlocked. 
 m29000| Wed Nov 21 06:11:12.795 [FileAllocator] done allocating datafile /data/db/test-config0/config.1, size: 32MB,  took 0.051 secs
ShardingTest undefined going to add shard : test-rs0/ip-10-2-29-40:31100,ip-10-2-29-40:31101
 m30999| Wed Nov 21 06:11:12.873 [mongosMain] connection accepted from 127.0.0.1:43511 #1 (1 connection now open)
 m30999| Wed Nov 21 06:11:12.874 [conn1] couldn't find database [admin] in config db
 m29000| Wed Nov 21 06:11:12.875 [conn5] build index config.databases { _id: 1 }
 m29000| Wed Nov 21 06:11:12.876 [conn5] build index done.  scanned 0 total records. 0 secs
 m30999| Wed Nov 21 06:11:12.876 [conn1] 	 put [admin] on: config:ip-10-2-29-40:29000
 m30999| Wed Nov 21 06:11:12.876 [conn1] scoped connection to ip-10-2-29-40:29000 not being returned to the pool
 m30999| Wed Nov 21 06:11:12.876 [conn1] starting new replica set monitor for replica set test-rs0 with seed of ip-10-2-29-40:31100,ip-10-2-29-40:31101
 m29000| Wed Nov 21 06:11:12.876 [conn5] end connection 10.2.29.40:42537 (5 connections now open)
 m31100| Wed Nov 21 06:11:12.878 [initandlisten] connection accepted from 10.2.29.40:41977 #12 (7 connections now open)
 m30999| Wed Nov 21 06:11:12.878 [conn1] successfully connected to seed ip-10-2-29-40:31100 for replica set test-rs0
 m30999| Wed Nov 21 06:11:12.878 [conn1] changing hosts to { 0: "ip-10-2-29-40:31100", 1: "ip-10-2-29-40:31101" } from test-rs0/
 m30999| Wed Nov 21 06:11:12.878 [conn1] trying to add new host ip-10-2-29-40:31100 to replica set test-rs0
 m31100| Wed Nov 21 06:11:12.879 [initandlisten] connection accepted from 10.2.29.40:41978 #13 (8 connections now open)
 m30999| Wed Nov 21 06:11:12.879 [conn1] successfully connected to new host ip-10-2-29-40:31100 in replica set test-rs0
 m30999| Wed Nov 21 06:11:12.879 [conn1] trying to add new host ip-10-2-29-40:31101 to replica set test-rs0
 m31101| Wed Nov 21 06:11:12.880 [initandlisten] connection accepted from 10.2.29.40:54694 #6 (5 connections now open)
 m30999| Wed Nov 21 06:11:12.880 [conn1] successfully connected to new host ip-10-2-29-40:31101 in replica set test-rs0
 m31100| Wed Nov 21 06:11:12.881 [initandlisten] connection accepted from 10.2.29.40:41980 #14 (9 connections now open)
 m31100| Wed Nov 21 06:11:12.881 [conn12] end connection 10.2.29.40:41977 (8 connections now open)
 m30999| Wed Nov 21 06:11:12.881 [conn1] Primary for replica set test-rs0 changed to ip-10-2-29-40:31100
 m31101| Wed Nov 21 06:11:12.882 [initandlisten] connection accepted from 10.2.29.40:54696 #7 (6 connections now open)
 m30999| Wed Nov 21 06:11:12.883 [conn1] replica set monitor for replica set test-rs0 started, address is test-rs0/ip-10-2-29-40:31100,ip-10-2-29-40:31101
 m30999| Wed Nov 21 06:11:12.883 [ReplicaSetMonitorWatcher] starting
 m31100| Wed Nov 21 06:11:12.884 [initandlisten] connection accepted from 10.2.29.40:41982 #15 (9 connections now open)
 m30999| Wed Nov 21 06:11:12.885 [conn1] going to add shard: { _id: "test-rs0", host: "test-rs0/ip-10-2-29-40:31100,ip-10-2-29-40:31101" }
{ "shardAdded" : "test-rs0", "ok" : 1 }
 m30999| Wed Nov 21 06:11:12.886 [conn1] creating WriteBackListener for: ip-10-2-29-40:31100 serverID: 50acb6d05199eb841cb264c1
 m30999| Wed Nov 21 06:11:12.886 [conn1] creating WriteBackListener for: ip-10-2-29-40:31101 serverID: 50acb6d05199eb841cb264c1
 m31100| Wed Nov 21 06:11:12.887 [initandlisten] connection accepted from 10.2.29.40:41983 #16 (10 connections now open)
 m30999| Wed Nov 21 06:11:12.889 [conn1] creating WriteBackListener for: ip-10-2-29-40:29000 serverID: 50acb6d05199eb841cb264c1
 m29000| Wed Nov 21 06:11:12.889 [initandlisten] connection accepted from 10.2.29.40:42547 #7 (6 connections now open)
Waiting for active hosts...
Waiting for the balancer lock...
Waiting again for active hosts after balancer is off...
 m30999| Wed Nov 21 06:11:12.897 [conn1] couldn't find database [test] in config db
 m30999| Wed Nov 21 06:11:12.898 [conn1] 	 put [test] on: test-rs0:test-rs0/ip-10-2-29-40:31100,ip-10-2-29-40:31101
 m30999| Wed Nov 21 06:11:12.898 [conn1] enabling sharding on: test
 m31100| Wed Nov 21 06:11:12.899 [FileAllocator] allocating new datafile /data/db/test-rs0-0/test.ns, filling with zeroes...
 m31100| Wed Nov 21 06:11:12.944 [FileAllocator] done allocating datafile /data/db/test-rs0-0/test.ns, size: 16MB,  took 0.044 secs
 m31100| Wed Nov 21 06:11:12.944 [FileAllocator] allocating new datafile /data/db/test-rs0-0/test.0, filling with zeroes...
 m31100| Wed Nov 21 06:11:12.969 [FileAllocator] done allocating datafile /data/db/test-rs0-0/test.0, size: 16MB,  took 0.024 secs
 m31100| Wed Nov 21 06:11:12.972 [conn15] build index test.user { _id: 1 }
 m31100| Wed Nov 21 06:11:12.972 [conn15] build index done.  scanned 0 total records. 0 secs
 m31100| Wed Nov 21 06:11:12.972 [conn15] info: creating collection test.user on add index
 m31100| Wed Nov 21 06:11:12.972 [conn15] build index test.user { x: 1.0 }
 m31100| Wed Nov 21 06:11:12.973 [conn15] build index done.  scanned 0 total records. 0 secs
 m31101| Wed Nov 21 06:11:12.975 [FileAllocator] allocating new datafile /data/db/test-rs0-1/test.ns, filling with zeroes...
 m29000| Wed Nov 21 06:11:12.977 [conn3] build index config.collections { _id: 1 }
 m29000| Wed Nov 21 06:11:12.978 [conn3] build index done.  scanned 0 total records. 0 secs
 m31100| Wed Nov 21 06:11:12.979 [conn16] no current chunk manager found for this shard, will initialize
 m30999| Wed Nov 21 06:11:12.974 [conn1] CMD: shardcollection: { shardCollection: "test.user", key: { x: 1.0 } }
 m30999| Wed Nov 21 06:11:12.974 [conn1] enable sharding on: test.user with shard key: { x: 1.0 }
 m30999| Wed Nov 21 06:11:12.974 [conn1] going to create 1 chunk(s) for: test.user using new epoch 50acb6d05199eb841cb264c3
 m30999| Wed Nov 21 06:11:12.977 [conn1] ChunkManager: time to load chunks for test.user: 2ms sequenceNumber: 2 version: 1|0||50acb6d05199eb841cb264c3 based on: (empty)
 m29000| Wed Nov 21 06:11:12.981 [initandlisten] connection accepted from 10.2.29.40:42548 #8 (7 connections now open)
Awaiting ip-10-2-29-40:31100 to be { "ok" : true } for connection to ip-10-2-29-40:30999 (rs: undefined)
{
	"test-rs0" : {
		"hosts" : [
			{
				"addr" : "ip-10-2-29-40:31100",
				"ok" : true,
				"ismaster" : true,
				"hidden" : false,
				"secondary" : false,
				"pingTimeMillis" : 0
			},
			{
				"addr" : "ip-10-2-29-40:31101",
				"ok" : true,
				"ismaster" : false,
				"hidden" : false,
				"secondary" : true,
				"pingTimeMillis" : 0
			}
		],
		"master" : 0,
		"nextSlave" : 0
	}
}
Awaiting ip-10-2-29-40:31101 to be { "ok" : true } for connection to ip-10-2-29-40:30999 (rs: undefined)
{
	"test-rs0" : {
		"hosts" : [
			{
				"addr" : "ip-10-2-29-40:31100",
				"ok" : true,
				"ismaster" : true,
				"hidden" : false,
				"secondary" : false,
				"pingTimeMillis" : 0
			},
			{
				"addr" : "ip-10-2-29-40:31101",
				"ok" : true,
				"ismaster" : false,
				"hidden" : false,
				"secondary" : true,
				"pingTimeMillis" : 0
			}
		],
		"master" : 0,
		"nextSlave" : 0
	}
}
 m31100| Wed Nov 21 06:11:12.985 [conn1] creating profile collection: test.system.profile
 m31101| Wed Nov 21 06:11:13.000 [FileAllocator] done allocating datafile /data/db/test-rs0-1/test.ns, size: 16MB,  took 0.024 secs
 m31101| Wed Nov 21 06:11:13.000 [FileAllocator] allocating new datafile /data/db/test-rs0-1/test.0, filling with zeroes...
 m31101| Wed Nov 21 06:11:13.026 [FileAllocator] done allocating datafile /data/db/test-rs0-1/test.0, size: 16MB,  took 0.025 secs
 m31101| Wed Nov 21 06:11:13.029 [repl writer worker 1] build index test.user { _id: 1 }
 m31101| Wed Nov 21 06:11:13.029 [repl writer worker 1] build index done.  scanned 0 total records. 0 secs
 m31101| Wed Nov 21 06:11:13.029 [repl writer worker 1] info: creating collection test.user on add index
 m31101| Wed Nov 21 06:11:13.029 [repl writer worker 1] build index test.user { x: 1.0 }
 m31101| Wed Nov 21 06:11:13.030 [repl writer worker 1] build index done.  scanned 0 total records. 0 secs
 m31101| Wed Nov 21 06:11:13.030 [conn1] creating profile collection: test.system.profile
 m31101| Wed Nov 21 06:11:13.032 [initandlisten] connection accepted from 10.2.29.40:54701 #8 (7 connections now open)
 m31101| Wed Nov 21 06:11:13.035 [initandlisten] connection accepted from 10.2.29.40:54702 #9 (8 connections now open)
 
 
----
cmd result: {
	"values" : [ ],
	"stats" : {
		"n" : 0,
		"nscanned" : 0,
		"nscannedObjects" : 0,
		"timems" : 0,
		"cursor" : "BtreeCursor x_1"
	},
	"ok" : 1
}
----
 
 
 
 
----
cmd result: { "ok" : 1 }
----
 
 
 m31100| Wed Nov 21 06:11:13.038 [initandlisten] connection accepted from 10.2.29.40:41988 #17 (11 connections now open)
 m31100| Wed Nov 21 06:11:13.039 [conn17] build index test.mrIn { _id: 1 }
 m31100| Wed Nov 21 06:11:13.039 [conn17] build index done.  scanned 0 total records. 0 secs
 m31101| Wed Nov 21 06:11:13.041 [repl writer worker 1] build index test.mrIn { _id: 1 }
 m31101| Wed Nov 21 06:11:13.041 [repl writer worker 1] build index done.  scanned 0 total records. 0 secs
 
 
----
cmd result: {
	"results" : [ ],
	"timeMillis" : 34,
	"counts" : {
		"input" : 0,
		"emit" : 0,
		"reduce" : 0,
		"output" : 0
	},
	"ok" : 1
}
----
 
 
 m31100| Wed Nov 21 06:11:13.114 [conn17] CMD: drop test.tmp.mr.mrIn_0_inc
 m31100| Wed Nov 21 06:11:13.115 [conn17] build index test.tmp.mr.mrIn_0_inc { 0: 1 }
 m31100| Wed Nov 21 06:11:13.115 [conn17] build index done.  scanned 0 total records. 0 secs
 m31100| Wed Nov 21 06:11:13.116 [conn17] CMD: drop test.tmp.mr.mrIn_0
 m31100| Wed Nov 21 06:11:13.116 [conn17] build index test.tmp.mr.mrIn_0 { _id: 1 }
 m31100| Wed Nov 21 06:11:13.117 [conn17] build index done.  scanned 0 total records. 0 secs
 m31101| Wed Nov 21 06:11:13.117 [repl writer worker 1] build index test.tmp.mr.mrIn_0_inc { _id: 1 }
 m31100| Wed Nov 21 06:11:13.118 [conn17] CMD: drop test.mrOut
 m31100| Wed Nov 21 06:11:13.119 [conn17] CMD: drop test.tmp.mr.mrIn_0
 m31100| Wed Nov 21 06:11:13.119 [conn17] CMD: drop test.tmp.mr.mrIn_0
 m31100| Wed Nov 21 06:11:13.119 [conn17] CMD: drop test.tmp.mr.mrIn_0_inc
 
 
----
cmd result: {
	"result" : "mrOut",
	"timeMillis" : 40,
	"counts" : {
		"input" : 0,
		"emit" : 0,
		"reduce" : 0,
		"output" : 0
	},
	"ok" : 1
}
----
 
 
 m31101| Wed Nov 21 06:11:13.137 [repl writer worker 1] build index done.  scanned 0 total records. 0.02 secs
 m31101| Wed Nov 21 06:11:13.137 [repl writer worker 1] info: creating collection test.tmp.mr.mrIn_0_inc on add index
 m31101| Wed Nov 21 06:11:13.137 [repl writer worker 1] build index test.tmp.mr.mrIn_0_inc { 0: 1 }
 m31101| Wed Nov 21 06:11:13.138 [repl writer worker 1] build index done.  scanned 0 total records. 0 secs
 m31101| Wed Nov 21 06:11:13.139 [repl writer worker 1] build index test.tmp.mr.mrIn_0 { _id: 1 }
 m31101| Wed Nov 21 06:11:13.140 [repl writer worker 1] build index done.  scanned 0 total records. 0 secs
 
 
----
cmd result: { "n" : 0, "ok" : 1 }
----
 
 
 m31101| Wed Nov 21 06:11:13.147 [repl writer worker 1] CMD: drop test.tmp.mr.mrIn_0_inc
 m31101| Wed Nov 21 06:11:13.148 [conn9] timeoutMs not support for v8 yet  code: $reduce = function (a, b) {}
 m31101| in gc
 
 
----
cmd result: { "retval" : [ ], "count" : 0, "keys" : 0, "ok" : 1 }
----
 
 
 
 
----
cmd result: {
	"ns" : "test.user",
	"count" : 0,
	"size" : 0,
	"storageSize" : 8192,
	"numExtents" : 1,
	"nindexes" : 2,
	"lastExtentSize" : 8192,
	"paddingFactor" : 1,
	"systemFlags" : 1,
	"userFlags" : 0,
	"totalIndexSize" : 16352,
	"indexSizes" : {
		"_id_" : 8176,
		"x_1" : 8176
	},
	"ok" : 1
}
----
 
 
 
 
----
cmd result: {
	"db" : "test",
	"collections" : 6,
	"objects" : 27,
	"avgObjSize" : 231.7037037037037,
	"dataSize" : 6256,
	"storageSize" : 1081344,
	"numExtents" : 6,
	"indexes" : 4,
	"indexSize" : 32704,
	"fileSize" : 16777216,
	"nsSizeMB" : 16,
	"ok" : 1
}
----
 
 
 m31100| Wed Nov 21 06:11:13.162 [conn17] build index test.user { loc: "2d" }
 m31100| Wed Nov 21 06:11:13.163 [conn17] build index done.  scanned 0 total records. 0 secs
 m31101| Wed Nov 21 06:11:13.163 [repl writer worker 1] build index test.user { loc: "2d" }
 m31101| Wed Nov 21 06:11:13.164 [repl writer worker 1] build index done.  scanned 0 total records. 0 secs
 
 
----
cmd result: {
	"ns" : "test.user",
	"near" : "1100000000000000110011110011110000000000110011110011",
	"results" : [ ],
	"stats" : {
		"time" : 0,
		"btreelocs" : 0,
		"nscanned" : 0,
		"objectsLoaded" : 0,
		"avgDistance" : NaN,
		"maxDistance" : 0
	},
	"ok" : 1
}
----
 
 
 
 
----
cmd result: { "result" : [ ], "ok" : 1 }
----
 
 
 
 
----
cmd result: { "result" : [ ], "ok" : 1 }
----
 
 
 m30999| Wed Nov 21 06:11:13.170 [conn1] DROP DATABASE: test
 m30999| Wed Nov 21 06:11:13.170 [conn1] erased database test from local registry
 m30999| Wed Nov 21 06:11:13.171 [conn1] ChunkManager: time to load chunks for test.user: 0ms sequenceNumber: 3 version: 1|0||50acb6d05199eb841cb264c3 based on: (empty)
 m30999| Wed Nov 21 06:11:13.172 [conn1] ChunkManager: time to load chunks for test.user: 0ms sequenceNumber: 4 version: 1|0||50acb6d05199eb841cb264c3 based on: (empty)
 m30999| Wed Nov 21 06:11:13.172 [conn1] DBConfig::dropDatabase: test
 m30999| Wed Nov 21 06:11:13.172 [conn1] about to log metadata event: { _id: "ip-10-2-29-40-2012-11-21T11:11:13-0", server: "ip-10-2-29-40", clientAddr: "N/A", time: new Date(1353496273172), what: "dropDatabase.start", ns: "test", details: {} }
 m30999| Wed Nov 21 06:11:13.173 [conn1] about to log metadata event: { _id: "ip-10-2-29-40-2012-11-21T11:11:13-1", server: "ip-10-2-29-40", clientAddr: "N/A", time: new Date(1353496273173), what: "dropCollection.start", ns: "test.user", details: {} }
 m30999| Wed Nov 21 06:11:13.173 [conn1] created new distributed lock for test.user on ip-10-2-29-40:29000 ( lock timeout : 900000, ping interval : 30000, process : 0 )
 m30999| Wed Nov 21 06:11:13.174 [conn1] distributed lock 'test.user/ip-10-2-29-40:30999:1353496272:1804289383' acquired, ts : 50acb6d15199eb841cb264c4
 m29000| Wed Nov 21 06:11:13.172 [conn3] build index config.changelog { _id: 1 }
 m29000| Wed Nov 21 06:11:13.172 [conn3] build index done.  scanned 0 total records. 0 secs
 m31100| Wed Nov 21 06:11:13.174 [conn15] CMD: drop test.user
 m31100| Wed Nov 21 06:11:13.176 [conn15] wiping data for: test.user
 m31100| Wed Nov 21 06:11:13.177 [initandlisten] connection accepted from 10.2.29.40:41991 #18 (12 connections now open)
 m31101| Wed Nov 21 06:11:13.177 [repl writer worker 1] CMD: drop test.user
 m31100| Wed Nov 21 06:11:13.178 [conn18] dropDatabase test
 m30999| Wed Nov 21 06:11:13.176 [conn1] about to log metadata event: { _id: "ip-10-2-29-40-2012-11-21T11:11:13-2", server: "ip-10-2-29-40", clientAddr: "N/A", time: new Date(1353496273176), what: "dropCollection", ns: "test.user", details: {} }
 m30999| Wed Nov 21 06:11:13.176 [conn1] distributed lock 'test.user/ip-10-2-29-40:30999:1353496272:1804289383' unlocked. 
 m30999| Wed Nov 21 06:11:13.176 [conn1]    DBConfig::dropDatabase: test dropped sharded collections: 1
 m30999| Wed Nov 21 06:11:13.176 [conn1]    DBConfig::dropDatabase: test dropped sharded collections: 0
 m31100| Wed Nov 21 06:11:14.733 [conn18] removeJournalFiles
 m31100| Wed Nov 21 06:11:14.743 [conn6] getmore local.oplog.rs query: { ts: { $gte: Timestamp 1353496246000|1 } } cursorid:127815784982510 ntoreturn:0 keyUpdates:0 numYields: 1 locks(micros) r:127 nreturned:1 reslen:109 1567ms
 m31100| Wed Nov 21 06:11:14.743 [conn18] command test.$cmd command: { dropDatabase: 1 } ntoreturn:1 keyUpdates:0 locks(micros) W:1564903 reslen:55 1565ms
 m31100| Wed Nov 21 06:11:14.743 [conn7] getmore local.oplog.rs query: { ts: { $gte: Timestamp 1353496246000|1 } } cursorid:128455461418178 ntoreturn:0 keyUpdates:0 numYields: 1 locks(micros) r:69 nreturned:1 reslen:37 1565ms
 m31101| Wed Nov 21 06:11:14.744 [repl writer worker 1] dropDatabase test
 m31100| Wed Nov 21 06:11:14.934 [slaveTracking] update local.slaves query: { _id: ObjectId('50acb6c0058e1835927d99f9'), host: "10.2.29.40", ns: "local.oplog.rs" } update: { $set: { syncedTo: Timestamp 1353496273000|7 } } nscanned:1 nupdated:1 keyUpdates:0 locks(micros) w:313 185ms
 m31100| Wed Nov 21 06:11:14.934 [conn18] dropDatabase test
 m31100| Wed Nov 21 06:11:15.347 [conn18] removeJournalFiles
 m31100| Wed Nov 21 06:11:15.524 [conn18] command test.$cmd command: { dropDatabase: 1 } ntoreturn:1 keyUpdates:0 locks(micros) W:590641 reslen:55 590ms
 m30999| Wed Nov 21 06:11:15.525 [conn1] about to log metadata event: { _id: "ip-10-2-29-40-2012-11-21T11:11:15-3", server: "ip-10-2-29-40", clientAddr: "N/A", time: new Date(1353496275525), what: "dropDatabase", ns: "test", details: {} }
 m30999| Wed Nov 21 06:11:15.525 [conn1] couldn't find database [test] in config db
 m30999| Wed Nov 21 06:11:15.527 [conn1] 	 put [test] on: test-rs0:test-rs0/ip-10-2-29-40:31100,ip-10-2-29-40:31101
 m30999| Wed Nov 21 06:11:15.527 [conn1] enabling sharding on: test
 m31100| Wed Nov 21 06:11:15.918 [FileAllocator] allocating new datafile /data/db/test-rs0-0/test.ns, filling with zeroes...
 m31100| Wed Nov 21 06:11:15.951 [FileAllocator] done allocating datafile /data/db/test-rs0-0/test.ns, size: 16MB,  took 0.032 secs
 m31100| Wed Nov 21 06:11:15.951 [FileAllocator] allocating new datafile /data/db/test-rs0-0/test.0, filling with zeroes...
 m31100| Wed Nov 21 06:11:16.142 [FileAllocator] done allocating datafile /data/db/test-rs0-0/test.0, size: 16MB,  took 0.191 secs
 m31100| Wed Nov 21 06:11:16.145 [conn15] build index test.user { _id: 1 }
 m31100| Wed Nov 21 06:11:16.145 [conn15] build index done.  scanned 0 total records. 0 secs
 m31100| Wed Nov 21 06:11:16.145 [conn15] info: creating collection test.user on add index
 m31100| Wed Nov 21 06:11:16.145 [conn15] build index test.user { x: 1.0 }
 m31100| Wed Nov 21 06:11:16.284 [conn15] build index done.  scanned 0 total records. 0.139 secs
 m31100| Wed Nov 21 06:11:16.285 [conn15] insert test.system.indexes keyUpdates:0 locks(micros) w:366722 366ms
 m30999| Wed Nov 21 06:11:16.285 [conn1] CMD: shardcollection: { shardCollection: "test.user", key: { x: 1.0 } }
 m30999| Wed Nov 21 06:11:16.285 [conn1] enable sharding on: test.user with shard key: { x: 1.0 }
 m30999| Wed Nov 21 06:11:16.285 [conn1] going to create 1 chunk(s) for: test.user using new epoch 50acb6d45199eb841cb264c5
 m30999| Wed Nov 21 06:11:16.286 [conn1] ChunkManager: time to load chunks for test.user: 0ms sequenceNumber: 5 version: 1|0||50acb6d45199eb841cb264c5 based on: (empty)
 m31100| Wed Nov 21 06:11:16.287 [conn16] no current chunk manager found for this shard, will initialize
 m31100| Wed Nov 21 06:11:16.289 [conn1] creating profile collection: test.system.profile
 m31101| Wed Nov 21 06:11:16.341 [repl writer worker 1] removeJournalFiles
 m31101| Wed Nov 21 06:11:16.356 [conn1] creating profile collection: test.system.profile
 m31101| Wed Nov 21 06:11:16.356 [FileAllocator] allocating new datafile /data/db/test-rs0-1/test.ns, filling with zeroes...
 m31101| Wed Nov 21 06:11:16.381 [FileAllocator] done allocating datafile /data/db/test-rs0-1/test.ns, size: 16MB,  took 0.024 secs
 m31101| Wed Nov 21 06:11:16.381 [FileAllocator] allocating new datafile /data/db/test-rs0-1/test.0, filling with zeroes...
 m31101| Wed Nov 21 06:11:16.406 [FileAllocator] done allocating datafile /data/db/test-rs0-1/test.0, size: 16MB,  took 0.024 secs
 
 
----
Starting test for mongos connection
----
 
 
 m31101| Wed Nov 21 06:11:16.409 [repl writer worker 1] dropDatabase test
 m31101| Wed Nov 21 06:11:16.410 [initandlisten] connection accepted from 10.2.29.40:54709 #10 (9 connections now open)
 m31101| Wed Nov 21 06:11:17.302 [repl writer worker 1] removeJournalFiles
 
 
----
cmd result: { "values" : [ ], "ok" : 1 }
----
 
 
 m31101| Wed Nov 21 06:11:17.311 [FileAllocator] allocating new datafile /data/db/test-rs0-1/test.ns, filling with zeroes...
 m31101| Wed Nov 21 06:11:17.337 [FileAllocator] done allocating datafile /data/db/test-rs0-1/test.ns, size: 16MB,  took 0.024 secs
 m31101| Wed Nov 21 06:11:17.338 [FileAllocator] allocating new datafile /data/db/test-rs0-1/test.0, filling with zeroes...
 m31101| Wed Nov 21 06:11:17.363 [FileAllocator] done allocating datafile /data/db/test-rs0-1/test.0, size: 16MB,  took 0.025 secs
 m31101| Wed Nov 21 06:11:17.366 [repl writer worker 1] build index test.user { _id: 1 }
assert failed
 m31101| Wed Nov 21 06:11:17.366 [repl writer worker 1] build index done.  scanned 0 total records. 0 secs
 m31101| Wed Nov 21 06:11:17.366 [repl writer worker 1] info: creating collection test.user on add index
 m31101| Wed Nov 21 06:11:17.366 [repl writer worker 1] build index test.user { x: 1.0 }
 m31101| Wed Nov 21 06:11:17.367 [repl writer worker 1] build index done.  scanned 0 total records. 0 secs
Error: Printing Stack Trace
    at printStackTrace (src/mongo/shell/utils.js:37:7)
    at doassert (src/mongo/shell/utils.js:58:1)
    at assert (src/mongo/shell/utils.js:66:1)
    at cmdTest (/mnt/home/buildbot/slave/Linux_64bit_Nightly/mongo/jstests/sharding/read_pref_cmd.js:50:9)
    at doTest (/mnt/home/buildbot/slave/Linux_64bit_Nightly/mongo/jstests/sharding/read_pref_cmd.js:68:5)
    at /mnt/home/buildbot/slave/Linux_64bit_Nightly/mongo/jstests/sharding/read_pref_cmd.js:169:1
Wed Nov 21 06:11:17.370 exec error: src/mongo/shell/utils.js:59 assert failed
throw msg;
      ^
 
failed to load: /mnt/home/buildbot/slave/Linux_64bit_Nightly/mongo/jstests/sharding/read_pref_cmd.js
 m29000| Wed Nov 21 06:11:17.371 got signal 15 (Terminated), will terminate after current cmd ends
 m29000| Wed Nov 21 06:11:17.371 [interruptThread] now exiting
 m29000| Wed Nov 21 06:11:17.371 dbexit: 
 m29000| Wed Nov 21 06:11:17.371 [interruptThread] shutdown: going to close listening sockets...
 m29000| Wed Nov 21 06:11:17.371 [interruptThread] closing listening socket: 20
 m29000| Wed Nov 21 06:11:17.371 [interruptThread] closing listening socket: 21
 m29000| Wed Nov 21 06:11:17.371 [interruptThread] closing listening socket: 22
 m29000| Wed Nov 21 06:11:17.371 [interruptThread] removing socket file: /tmp/mongodb-29000.sock
 m29000| Wed Nov 21 06:11:17.371 [interruptThread] shutdown: going to flush diaglog...
 m29000| Wed Nov 21 06:11:17.371 [interruptThread] shutdown: going to close sockets...
 m29000| Wed Nov 21 06:11:17.371 [interruptThread] shutdown: waiting for fs preallocator...
 m29000| Wed Nov 21 06:11:17.371 [interruptThread] shutdown: lock for final commit...
 m29000| Wed Nov 21 06:11:17.371 [interruptThread] shutdown: final commit...
 m29000| Wed Nov 21 06:11:18.181 [interruptThread] shutdown: closing all files...
 m29000| Wed Nov 21 06:11:18.182 [interruptThread] closeAllFiles() finished
 m29000| Wed Nov 21 06:11:18.182 [interruptThread] journalCleanup...
 m29000| Wed Nov 21 06:11:18.182 [interruptThread] removeJournalFiles
 m29000| Wed Nov 21 06:11:18.185 [interruptThread] shutdown: removing fs lock...
 m29000| Wed Nov 21 06:11:18.185 dbexit: really exiting now
 m30999| Wed Nov 21 06:11:18.372 [mongosMain] dbexit: received signal 15 rc:0 received signal 15
 m31100| Wed Nov 21 06:11:18.373 [conn15] end connection 10.2.29.40:41982 (11 connections now open)
 m31100| Wed Nov 21 06:11:18.373 [conn16] end connection 10.2.29.40:41983 (10 connections now open)
 m31100| Wed Nov 21 06:11:18.373 [conn13] end connection 10.2.29.40:41978 (10 connections now open)
 m31101| Wed Nov 21 06:11:18.373 [conn6] end connection 10.2.29.40:54694 (8 connections now open)
 m31100| Wed Nov 21 06:11:18.373 [conn18] end connection 10.2.29.40:41991 (8 connections now open)
 m31101| Wed Nov 21 06:11:18.374 [conn10] end connection 10.2.29.40:54709 (7 connections now open)
 m31100| Wed Nov 21 06:11:19.373 got signal 15 (Terminated), will terminate after current cmd ends
 m31100| Wed Nov 21 06:11:19.373 [interruptThread] now exiting
 m31100| Wed Nov 21 06:11:19.373 dbexit: 
 m31100| Wed Nov 21 06:11:19.373 [interruptThread] shutdown: going to close listening sockets...
 m31100| Wed Nov 21 06:11:19.373 [interruptThread] closing listening socket: 10
 m31100| Wed Nov 21 06:11:19.373 [interruptThread] closing listening socket: 11
 m31100| Wed Nov 21 06:11:19.373 [interruptThread] closing listening socket: 12
 m31100| Wed Nov 21 06:11:19.373 [interruptThread] removing socket file: /tmp/mongodb-31100.sock
 m31100| Wed Nov 21 06:11:19.373 [interruptThread] shutdown: going to flush diaglog...
 m31100| Wed Nov 21 06:11:19.373 [interruptThread] shutdown: going to close sockets...
 m31100| Wed Nov 21 06:11:19.373 [interruptThread] shutdown: waiting for fs preallocator...
 m31100| Wed Nov 21 06:11:19.373 [interruptThread] shutdown: lock for final commit...
 m31100| Wed Nov 21 06:11:19.373 [interruptThread] shutdown: final commit...
 m31101| Wed Nov 21 06:11:19.374 [rsSyncNotifier] replset tracking exception: exception: 10278 dbclient error communicating with server: ip-10-2-29-40:31100
 m31101| Wed Nov 21 06:11:19.374 [rsBackgroundSync] replSet db exception in producer: 10278 dbclient error communicating with server: ip-10-2-29-40:31100
 m31101| Wed Nov 21 06:11:19.374 [conn5] end connection 10.2.29.40:54642 (6 connections now open)
 m31100| Wed Nov 21 06:11:19.698 [conn11] end connection 10.2.29.40:41940 (7 connections now open)
 m31101| Wed Nov 21 06:11:19.698 [rsHealthPoll] DBClientCursor::init call() failed
 m31101| Wed Nov 21 06:11:19.698 [rsHealthPoll] replset info ip-10-2-29-40:31100 heartbeat failed, retrying
 m31101| Wed Nov 21 06:11:19.699 [rsHealthPoll] replSet info ip-10-2-29-40:31100 is down (or slow to respond): 
 m31101| Wed Nov 21 06:11:19.699 [rsHealthPoll] replSet member ip-10-2-29-40:31100 is now in state DOWN
 m31101| Wed Nov 21 06:11:19.699 [rsMgr] replSet can't see a majority, will not try to elect self
 m31100| Wed Nov 21 06:11:19.938 [slaveTracking] Client::shutdown not called: slaveTracking
Wed Nov 21 06:11:20.178 [ReplicaSetMonitorWatcher] Socket recv() errno:104 Connection reset by peer 10.2.29.40:31100
Wed Nov 21 06:11:20.178 [ReplicaSetMonitorWatcher] SocketException: remote: 10.2.29.40:31100 error: 9001 socket exception [1] server [10.2.29.40:31100] 
Wed Nov 21 06:11:20.178 [ReplicaSetMonitorWatcher] DBClientCursor::init call() failed
Wed Nov 21 06:11:20.178 [ReplicaSetMonitorWatcher] trying reconnect to ip-10-2-29-40:31100
 m31100| Wed Nov 21 06:11:20.178 [conn9] end connection 10.2.29.40:41896 (6 connections now open)
Wed Nov 21 06:11:20.179 [ReplicaSetMonitorWatcher] reconnect ip-10-2-29-40:31100 failed couldn't connect to server ip-10-2-29-40:31100
 m31100| Wed Nov 21 06:11:20.196 [interruptThread] shutdown: closing all files...
 m31100| Wed Nov 21 06:11:20.196 [interruptThread] closeAllFiles() finished
 m31100| Wed Nov 21 06:11:20.196 [interruptThread] journalCleanup...
 m31100| Wed Nov 21 06:11:20.196 [interruptThread] removeJournalFiles
 m31100| Wed Nov 21 06:11:20.198 [interruptThread] shutdown: removing fs lock...
 m31100| Wed Nov 21 06:11:20.198 dbexit: really exiting now
 m31101| Wed Nov 21 06:11:20.373 got signal 15 (Terminated), will terminate after current cmd ends
 m31101| Wed Nov 21 06:11:20.374 [interruptThread] now exiting
 m31101| Wed Nov 21 06:11:20.374 dbexit: 
 m31101| Wed Nov 21 06:11:20.374 [interruptThread] shutdown: going to close listening sockets...
 m31101| Wed Nov 21 06:11:20.374 [interruptThread] closing listening socket: 13
 m31101| Wed Nov 21 06:11:20.374 [interruptThread] closing listening socket: 14
 m31101| Wed Nov 21 06:11:20.374 [interruptThread] closing listening socket: 15
 m31101| Wed Nov 21 06:11:20.374 [interruptThread] removing socket file: /tmp/mongodb-31101.sock
 m31101| Wed Nov 21 06:11:20.374 [interruptThread] shutdown: going to flush diaglog...
 m31101| Wed Nov 21 06:11:20.374 [interruptThread] shutdown: going to close sockets...
 m31101| Wed Nov 21 06:11:20.374 [interruptThread] shutdown: waiting for fs preallocator...
 m31101| Wed Nov 21 06:11:20.374 [interruptThread] shutdown: lock for final commit...
 m31101| Wed Nov 21 06:11:20.374 [interruptThread] shutdown: final commit...
 m31101| Wed Nov 21 06:11:21.150 [interruptThread] shutdown: closing all files...
 m31101| Wed Nov 21 06:11:21.150 [interruptThread] closeAllFiles() finished
 m31101| Wed Nov 21 06:11:21.150 [interruptThread] journalCleanup...
 m31101| Wed Nov 21 06:11:21.150 [interruptThread] removeJournalFiles
 m31101| Wed Nov 21 06:11:21.152 [interruptThread] shutdown: removing fs lock...
 m31101| Wed Nov 21 06:11:21.152 dbexit: really exiting now
Wed Nov 21 06:11:21.180 [ReplicaSetMonitorWatcher] ReplicaSetMonitor::_checkConnection: caught exception ip-10-2-29-40:31100 socket exception [FAILED_STATE] for ip-10-2-29-40:31100
Wed Nov 21 06:11:21.180 [ReplicaSetMonitorWatcher] DBClientCursor::init call() failed
Wed Nov 21 06:11:21.180 [ReplicaSetMonitorWatcher] ReplicaSetMonitor::_checkConnection: caught exception ip-10-2-29-40:31101 DBClientBase::findN: transport error: ip-10-2-29-40:31101 ns: admin.$cmd query: { ismaster: 1 }
 
 
 
 
 
Wed Nov 21 06:11:21.502 got signal 15 (Terminated), will terminate after current cmd ends
Wed Nov 21 06:11:21.502 [interruptThread] now exiting
Wed Nov 21 06:11:21.502 dbexit: 
Wed Nov 21 06:11:21.502 [interruptThread] shutdown: going to close listening sockets...
Wed Nov 21 06:11:21.502 [interruptThread] closing listening socket: 7
Wed Nov 21 06:11:21.502 [interruptThread] closing listening socket: 8
Wed Nov 21 06:11:21.502 [interruptThread] closing listening socket: 9
Wed Nov 21 06:11:21.502 [interruptThread] removing socket file: /tmp/mongodb-27999.sock
Wed Nov 21 06:11:21.502 [interruptThread] shutdown: going to flush diaglog...
Wed Nov 21 06:11:21.502 [interruptThread] shutdown: going to close sockets...
Wed Nov 21 06:11:21.502 [interruptThread] shutdown: waiting for fs preallocator...
Wed Nov 21 06:11:21.502 [interruptThread] shutdown: lock for final commit...
Wed Nov 21 06:11:21.502 [interruptThread] shutdown: final commit...
Wed Nov 21 06:11:21.502 [interruptThread] shutdown: closing all files...
Wed Nov 21 06:11:21.502 [interruptThread] closeAllFiles() finished
Wed Nov 21 06:11:21.502 [interruptThread] journalCleanup...
Wed Nov 21 06:11:21.502 [interruptThread] removeJournalFiles
Wed Nov 21 06:11:21.504 [interruptThread] shutdown: removing fs lock...
Wed Nov 21 06:11:21.504 dbexit: really exiting now

Comment by auto [ 06/Nov/12 ]

Author:

{u'date': u'2012-11-06T15:39:23Z', u'email': u'randolph@10gen.com', u'name': u'Randolph Tan'}

Message: SERVER-7558 read_pref_cmd.js fails when calling inline map reduce

Make sure that the creation of collections are propagated to all secondaries before proceeding.
Branch: master
https://github.com/mongodb/mongo/commit/e34b5106ced41b2f3775d1d28c3776b12ef0c595

Comment by auto [ 05/Nov/12 ]

Author:

{u'date': u'2012-11-05T18:41:54Z', u'name': u'Randolph Tan', u'email': u'randolph@10gen.com'}

Message: SERVER-7558 read_pref_cmd.js fails when calling inline map reduce

Added logs when command fails.
Branch: master
https://github.com/mongodb/mongo/commit/8afcdc545d5d756900d6ffff43c9e06f90318bff

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