|
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
|