Skip to content

Instantly share code, notes, and snippets.

@leifwalsh

leifwalsh/gist:7010507

Created October 16, 2013 16:11
Show Gist options
  • Save leifwalsh/7010507 to your computer and use it in GitHub Desktop.
Save leifwalsh/7010507 to your computer and use it in GitHub Desktop.
Download ZIP
parallel test log
Raw
gistfile1.txt
% python2 buildscripts/smoke.py parallel
running /home/leif/git/mongo/mongod --port 27999 --dbpath /data/db/sconsTests/ --setParameter enableTestCommands=1 --debug
Wed Oct 16 12:02:43.183 nssize is a deprecated parameter
Wed Oct 16 12:02:43.186 [initandlisten] TokuMX starting : pid=25368 port=27999 dbpath=/data/db/sconsTests/ 64-bit host=cavil
Wed Oct 16 12:02:43.186 [initandlisten] TokuMX mongod server v1.3.0-pre--mongodb-2.4.7, using TokuKV rev notokukvversion
Wed Oct 16 12:02:43.186 [initandlisten] git version: b241f06d9e94ceeaf351a2d8eaea785706842f41
Wed Oct 16 12:02:43.186 [initandlisten] build info: Linux cavil 3.11.4-1-ARCH #1 SMP PREEMPT Sat Oct 5 21:22:51 CEST 2013 x86_64 BOOST_LIB_VERSION=1_49
Wed Oct 16 12:02:43.186 [initandlisten] options: { dbpath: "/data/db/sconsTests/", debug: true, port: 27999, setParameter: [ "enableTestCommands=1" ] }
Wed Oct 16 12:02:43.221 [initandlisten] [tokumx] startup
Wed Oct 16 12:02:43 2013 Tokudb recovery starting in env /data/db/sconsTests/
Wed Oct 16 12:02:43 2013 Tokudb recovery scanning backward from 13357473
Wed Oct 16 12:02:43 2013 Tokudb recovery bw_end_checkpoint at 13357473 timestamp 1381939133938725 xid 13357425 (bw_newer)
Wed Oct 16 12:02:43 2013 Tokudb recovery bw_begin_checkpoint at 13357425 timestamp 1381939133011966 (bw_between)
Wed Oct 16 12:02:43 2013 Tokudb recovery turning around at begin checkpoint 13357425 time 926759
Wed Oct 16 12:02:43 2013 Tokudb recovery starts scanning forward to 13357473 from 13357425 left 48 (fw_between)
Wed Oct 16 12:02:43 2013 Tokudb recovery closing 46 dictionaries
Wed Oct 16 12:02:43 2013 Tokudb recovery making a checkpoint
Wed Oct 16 12:02:43 2013 Tokudb recovery done
Wed Oct 16 12:02:43.340 [initandlisten] waiting for connections on port 27999
Wed Oct 16 12:02:43.340 [websvr] admin web console waiting for connections on port 28999
Wed Oct 16 12:02:44.178 [initandlisten] connection accepted from 127.0.0.1:50031 #1 (1 connection now open)
Wed Oct 16 12:02:44.179 [conn1] end connection 127.0.0.1:50031 (0 connections now open)
Wed Oct 16 12:02:44.203 [initandlisten] connection accepted from 127.0.0.1:50032 #2 (1 connection now open)
Wed Oct 16 12:02:44.204 [conn2] end connection 127.0.0.1:50032 (0 connections now open)
*******************************************
Test : allops.js ...
Command : /home/leif/git/mongo/mongo --port 27999 --authenticationMechanism MONGODB-CR /home/leif/git/mongo/jstests/parallel/allops.js --eval TestData = new Object();TestData.testPath = "/home/leif/git/mongo/jstests/parallel/allops.js";TestData.testFile = "allops.js";TestData.testName = "allops";TestData.noJournal = false;TestData.noJournalPrealloc = false;TestData.auth = false;TestData.keyFile = null;TestData.keyFileData = null;
Date : Wed Oct 16 12:02:44 2013
Wed Oct 16 12:02:44.230 [initandlisten] connection accepted from 127.0.0.1:50033 #3 (1 connection now open)
Wed Oct 16 12:02:44.231 [conn3] CMD: drop test.jstests_parallel_allops
Wed Oct 16 12:02:44.550 [initandlisten] connection accepted from 127.0.0.1:50034 #4 (2 connections now open)
Wed Oct 16 12:02:44.566 [initandlisten] connection accepted from 127.0.0.1:50035 #5 (3 connections now open)
Wed Oct 16 12:02:44.568 [initandlisten] connection accepted from 127.0.0.1:50036 #6 (4 connections now open)
Wed Oct 16 12:02:44.571 [initandlisten] connection accepted from 127.0.0.1:50037 #7 (5 connections now open)
Wed Oct 16 12:02:44.571 [initandlisten] connection accepted from 127.0.0.1:50038 #8 (6 connections now open)
Wed Oct 16 12:02:44.576 [initandlisten] connection accepted from 127.0.0.1:50039 #9 (7 connections now open)
Wed Oct 16 12:02:44.576 [initandlisten] connection accepted from 127.0.0.1:50040 #10 (8 connections now open)
Wed Oct 16 12:02:44.576 [initandlisten] connection accepted from 127.0.0.1:50041 #11 (9 connections now open)
Wed Oct 16 12:02:44.581 [initandlisten] connection accepted from 127.0.0.1:50042 #12 (10 connections now open)
Wed Oct 16 12:02:44.583 [initandlisten] connection accepted from 127.0.0.1:50043 #13 (11 connections now open)
Wed Oct 16 12:02:44.583 [initandlisten] connection accepted from 127.0.0.1:50044 #14 (12 connections now open)
Wed Oct 16 12:02:44.584 [initandlisten] connection accepted from 127.0.0.1:50045 #15 (13 connections now open)
Wed Oct 16 12:02:44.596 [initandlisten] connection accepted from 127.0.0.1:50046 #16 (14 connections now open)
Wed Oct 16 12:02:44.597 [initandlisten] connection accepted from 127.0.0.1:50047 #17 (15 connections now open)
Wed Oct 16 12:02:44.600 [initandlisten] connection accepted from 127.0.0.1:50048 #18 (16 connections now open)
Wed Oct 16 12:02:44.600 [initandlisten] connection accepted from 127.0.0.1:50049 #19 (17 connections now open)
Wed Oct 16 12:02:44.601 [initandlisten] connection accepted from 127.0.0.1:50050 #20 (18 connections now open)
Wed Oct 16 12:02:44.606 [initandlisten] connection accepted from 127.0.0.1:50051 #21 (19 connections now open)
Wed Oct 16 12:02:44.608 [initandlisten] connection accepted from 127.0.0.1:50052 #22 (20 connections now open)
Wed Oct 16 12:02:44.609 [initandlisten] connection accepted from 127.0.0.1:50053 #23 (21 connections now open)
Wed Oct 16 12:02:44.610 [initandlisten] connection accepted from 127.0.0.1:50054 #24 (22 connections now open)
Wed Oct 16 12:02:44.613 [initandlisten] connection accepted from 127.0.0.1:50055 #25 (23 connections now open)
Wed Oct 16 12:03:03.266 [conn3] CMD: validate test.jstests_parallel_allops
Wed Oct 16 12:03:03.266 [conn3] test.jstests_parallel_allops validate is a deprecated command, assuming everything is ok.
Wed Oct 16 12:03:03.269 [conn3] end connection 127.0.0.1:50033 (22 connections now open)
Wed Oct 16 12:03:03.277 [conn4] end connection 127.0.0.1:50034 (21 connections now open)
Wed Oct 16 12:03:03.277 [conn11] end connection 127.0.0.1:50041 (21 connections now open)
Wed Oct 16 12:03:03.277 [conn8] end connection 127.0.0.1:50038 (21 connections now open)
Wed Oct 16 12:03:03.277 [conn6] end connection 127.0.0.1:50036 (21 connections now open)
Wed Oct 16 12:03:03.277 [conn9] end connection 127.0.0.1:50039 (21 connections now open)
Wed Oct 16 12:03:03.277 [conn5] end connection 127.0.0.1:50035 (21 connections now open)
Wed Oct 16 12:03:03.277 [conn10] end connection 127.0.0.1:50040 (16 connections now open)
Wed Oct 16 12:03:03.277 [conn15] end connection 127.0.0.1:50045 (15 connections now open)
Wed Oct 16 12:03:03.277 [conn19] end connection 127.0.0.1:50049 (15 connections now open)
Wed Oct 16 12:03:03.277 [conn14] end connection 127.0.0.1:50044 (15 connections now open)
Wed Oct 16 12:03:03.277 [conn22] end connection 127.0.0.1:50052 (13 connections now open)
Wed Oct 16 12:03:03.277 [conn16] end connection 127.0.0.1:50046 (12 connections now open)
Wed Oct 16 12:03:03.277 [conn13] end connection 127.0.0.1:50043 (12 connections now open)
Wed Oct 16 12:03:03.277 [conn12] end connection 127.0.0.1:50042 (12 connections now open)
Wed Oct 16 12:03:03.278 [conn23] end connection 127.0.0.1:50053 (9 connections now open)
Wed Oct 16 12:03:03.277 [conn18] end connection 127.0.0.1:50048 (12 connections now open)
19072.248936ms
TokuMX mongo shell v1.3.0-pre--mongodb-2.4.7
connecting to: 127.0.0.1:27999/test
null
setting random seed: 1381939364232
connecting to: 127.0.0.1:27999/test
connecting to: 127.0.0.1:27999/test
connecting to: 127.0.0.1:27999/test
connecting to: 127.0.0.1:27999/test
connecting to: 127.0.0.1:27999/test
connecting to: 127.0.0.1:27999/test
connecting to: 127.0.0.1:27999/test
connecting to: 127.0.0.1:27999/test
connecting to: 127.0.0.1:27999/test
connecting to: 127.0.0.1:27999/test
connecting to: 127.0.0.1:27999/test
10 0
10 100
10 200
10 300
10 400
10 500
10 600
10 700
10 800
10 900
10 1000
10 1100
10 1200
10 1300
10 1400
10 1500
10 1600
10 1700
10 1800
10 1900
Wed Oct 16 12:03:03.278 [conn25] end connection 127.0.0.1:50055 (7 connections now open)
Wed Oct 16 12:03:03.278 [conn24] end connection 127.0.0.1:50054 (7 connections now open)
Wed Oct 16 12:03:03.277 [conn20] end connection 127.0.0.1:50050 (12 connections now open)
Wed Oct 16 12:03:03.277 [conn21] end connection 127.0.0.1:50051 (12 connections now open)
Wed Oct 16 12:03:03.277 [conn17] end connection 127.0.0.1:50047 (12 connections now open)
Wed Oct 16 12:03:03.277 [conn7] end connection 127.0.0.1:50037 (21 connections now open)
Wed Oct 16 12:03:03.278 [initandlisten] connection accepted from 127.0.0.1:50060 #26 (2 connections now open)
*******************************************
Test : basic.js ...
Wed Oct 16 12:03:03.279 [conn26] end connection 127.0.0.1:50060 (0 connections now open)
Command : /home/leif/git/mongo/mongo --port 27999 --authenticationMechanism MONGODB-CR /home/leif/git/mongo/jstests/parallel/basic.js --eval TestData = new Object();TestData.testPath = "/home/leif/git/mongo/jstests/parallel/basic.js";TestData.testFile = "basic.js";TestData.testName = "basic";TestData.noJournal = false;TestData.noJournalPrealloc = false;TestData.auth = false;TestData.keyFile = null;TestData.keyFileData = null;
Date : Wed Oct 16 12:03:03 2013
Wed Oct 16 12:03:03.305 [initandlisten] connection accepted from 127.0.0.1:50061 #27 (1 connection now open)
Wed Oct 16 12:03:03.347 [initandlisten] connection accepted from 127.0.0.1:50062 #28 (2 connections now open)
Wed Oct 16 12:03:03.349 [conn28] CMD: drop test.regex6
Wed Oct 16 12:03:03.354 [initandlisten] connection accepted from 127.0.0.1:50063 #29 (3 connections now open)
Wed Oct 16 12:03:03.357 [initandlisten] connection accepted from 127.0.0.1:50064 #30 (4 connections now open)
Wed Oct 16 12:03:03.357 [initandlisten] connection accepted from 127.0.0.1:50065 #31 (5 connections now open)
Wed Oct 16 12:03:03.381 [conn29] CMD: drop test.jstests_date2
Wed Oct 16 12:03:03.381 [conn31] CMD: drop test.jstests_orl
Wed Oct 16 12:03:03.598 [conn28] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:37 w:216338 216ms
Wed Oct 16 12:03:03.598 [conn30] CMD: drop test.update_mulit4
Wed Oct 16 12:03:03.831 [conn29] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:70 w:233367 233ms
Wed Oct 16 12:03:04.081 [conn31] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:138 w:249871 250ms
Wed Oct 16 12:03:04.115 [conn30] insert test.update_mulit4 ninserted:1 keyUpdates:0 locks(micros) r:126 w:33428 283ms
Wed Oct 16 12:03:04.115 [conn29] CMD: drop test.jstests_txn_isolation
Wed Oct 16 12:03:04.116 [conn31] CMD: drop test.update_multi5
Wed Oct 16 12:03:04.143 [initandlisten] connection accepted from 127.0.0.1:50066 #32 (6 connections now open)
Wed Oct 16 12:03:04.183 [conn31] CMD: drop test.inc1
Wed Oct 16 12:03:04.218 [conn28] CMD: drop test.jstests_updatej
Wed Oct 16 12:03:04.491 [conn31] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:37 w:240952 241ms
Wed Oct 16 12:03:04.491 [conn28] CMD: drop test.indexd
Wed Oct 16 12:03:04.494 [conn31] CMD: drop test.remove_multicursor6124
Wed Oct 16 12:03:04.825 [conn28] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:54 w:300028 300ms
Wed Oct 16 12:03:05.209 [conn31] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:87 w:383550 683ms
Wed Oct 16 12:03:05.209 [conn28] CMD: drop test.indexd.$_id_
Wed Oct 16 12:03:05.442 [conn31] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:36 w:232789 232ms
Wed Oct 16 12:03:05.442 [conn28] CMD: drop test.indexd
Wed Oct 16 12:03:05.544 [conn28] CMD: drop test.jstests_not2
Wed Oct 16 12:03:05.568 [conn31] CMD: drop test.remove_multicursor6124
Wed Oct 16 12:03:05.818 [conn31] command test.$cmd command: { drop: "remove_multicursor6124" } ntoreturn:1 keyUpdates:0 locks(micros) w:250021 reslen:134 250ms
Wed Oct 16 12:03:05.851 [conn28] Count with ns: test.jstests_not2 and query: { i: { $not: "a" } } failed with exception: 13041 invalid use of $not code: 13041
Wed Oct 16 12:03:06.146 [conn32] end connection 127.0.0.1:50066 (5 connections now open)
Wed Oct 16 12:03:06.268 [conn31] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:123 w:416799 416ms
Wed Oct 16 12:03:06.268 [conn29] CMD: drop test.jstests_txn_isolation
Wed Oct 16 12:03:06.368 [conn28] Count with ns: test.jstests_not2 and query: { i: { $not: { $not: "a" } } } failed with exception: 13034 invalid use of $not code: 13034
Wed Oct 16 12:03:06.393 [initandlisten] connection accepted from 127.0.0.1:50067 #33 (6 connections now open)
Wed Oct 16 12:03:06.602 [conn31] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:35 w:200250 233ms
Wed Oct 16 12:03:06.602 [conn28] Count with ns: test.jstests_not2 and query: { i: { $not: { $not: { $gt: "a" } } } } failed with exception: 13034 invalid use of $not code: 13034
Wed Oct 16 12:03:06.602 [conn28] Count with ns: test.jstests_not2 and query: { i: { $not: { $ref: "foo" } } } failed with exception: 13034 invalid use of $not code: 13034
Wed Oct 16 12:03:06.603 [conn31] CMD: drop test.lock_diag_size_limit
Wed Oct 16 12:03:06.603 [conn28] Count with ns: test.jstests_not2 and query: { i: { $not: {} } } failed with exception: 13030 $not cannot be empty code: 13030
Wed Oct 16 12:03:06.835 [conn31] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:67 w:232062 232ms
Wed Oct 16 12:03:06.842 [conn28] Count with ns: test.jstests_not2 and query: { i: { $not: { $regex: "a" } } } failed with exception: 13032 can't use $not with $regex, use BSON regex type instead code: 13032
Wed Oct 16 12:03:06.843 [conn28] Count with ns: test.jstests_not2 and query: { i: { $not: { $options: "a" } } } failed with exception: 13029 can't use $not with $options, use BSON regex type instead code: 13029
Wed Oct 16 12:03:07.069 [conn28] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:41 w:219047 219ms
Wed Oct 16 12:03:07.071 [conn28] Count with ns: test.jstests_not2 and query: { i: { $not: "a" } } failed with exception: 13041 invalid use of $not code: 13041
Wed Oct 16 12:03:07.072 [conn28] Count with ns: test.jstests_not2 and query: { i: { $not: { $not: "a" } } } failed with exception: 13034 invalid use of $not code: 13034
Wed Oct 16 12:03:07.072 [conn28] Count with ns: test.jstests_not2 and query: { i: { $not: { $not: { $gt: "a" } } } } failed with exception: 13034 invalid use of $not code: 13034
Wed Oct 16 12:03:07.073 [conn28] Count with ns: test.jstests_not2 and query: { i: { $not: { $ref: "foo" } } } failed with exception: 13034 invalid use of $not code: 13034
Wed Oct 16 12:03:07.073 [conn28] Count with ns: test.jstests_not2 and query: { i: { $not: {} } } failed with exception: 13030 $not cannot be empty code: 13030
Wed Oct 16 12:03:07.294 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:34 w:217570 217ms
Wed Oct 16 12:03:07.299 [conn28] Count with ns: test.jstests_not2 and query: { i: { $not: { $regex: "a" } } } failed with exception: 13032 can't use $not with $regex, use BSON regex type instead code: 13032
Wed Oct 16 12:03:07.300 [conn28] Count with ns: test.jstests_not2 and query: { i: { $not: { $options: "a" } } } failed with exception: 13029 can't use $not with $options, use BSON regex type instead code: 13029
Wed Oct 16 12:03:07.306 [conn30] CMD: drop test.jstests_or3
Wed Oct 16 12:03:07.378 [conn30] assertion 13086 $and/$or/$nor must be a nonempty array ns:test.jstests_or3 query:{ x: 0.0, $nor: "a" }
Wed Oct 16 12:03:07.378 [conn30] problem detected during query over test.jstests_or3 : { $err: "$and/$or/$nor must be a nonempty array", code: 13086 }
Wed Oct 16 12:03:07.379 [conn30] assertion 13086 $and/$or/$nor must be a nonempty array ns:test.jstests_or3 query:{ x: 0.0, $nor: {} }
Wed Oct 16 12:03:07.379 [conn30] problem detected during query over test.jstests_or3 : { $err: "$and/$or/$nor must be a nonempty array", code: 13086 }
Wed Oct 16 12:03:07.380 [conn30] assertion 13087 $and/$or/$nor match element must be an object ns:test.jstests_or3 query:{ x: 0.0, $nor: [ "a" ] }
Wed Oct 16 12:03:07.380 [conn30] problem detected during query over test.jstests_or3 : { $err: "$and/$or/$nor match element must be an object", code: 13087 }
Wed Oct 16 12:03:07.381 [conn28] CMD: drop test.jstests_not2
Wed Oct 16 12:03:07.561 [conn28] command test.$cmd command: { drop: "jstests_not2" } ntoreturn:1 keyUpdates:0 locks(micros) w:180079 reslen:124 180ms
Wed Oct 16 12:03:07.769 [conn28] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:36 w:174916 175ms
Wed Oct 16 12:03:07.970 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:42 w:197986 198ms
Wed Oct 16 12:03:07.976 [conn28] CMD: drop test.jstests_not2
Wed Oct 16 12:03:08.212 [conn28] command test.$cmd command: { drop: "jstests_not2" } ntoreturn:1 keyUpdates:0 locks(micros) w:235697 reslen:124 235ms
Wed Oct 16 12:03:08.212 [conn30] assertion 13086 $and/$or/$nor must be a nonempty array ns:test.jstests_or3 query:{ x: 0.0, $nor: "a" }
Wed Oct 16 12:03:08.212 [conn30] problem detected during query over test.jstests_or3 : { $err: "$and/$or/$nor must be a nonempty array", code: 13086 }
Wed Oct 16 12:03:08.213 [conn30] assertion 13086 $and/$or/$nor must be a nonempty array ns:test.jstests_or3 query:{ x: 0.0, $nor: {} }
Wed Oct 16 12:03:08.213 [conn30] problem detected during query over test.jstests_or3 : { $err: "$and/$or/$nor must be a nonempty array", code: 13086 }
Wed Oct 16 12:03:08.396 [conn33] end connection 127.0.0.1:50067 (5 connections now open)
Wed Oct 16 12:03:08.462 [conn28] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:80 w:248464 249ms
Wed Oct 16 12:03:08.462 [conn29] CMD: drop test.jstests_txn_isolation
Wed Oct 16 12:03:08.562 [conn30] assertion 13087 $and/$or/$nor match element must be an object ns:test.jstests_or3 query:{ x: 0.0, $nor: [ "a" ] }
Wed Oct 16 12:03:08.562 [conn30] problem detected during query over test.jstests_or3 : { $err: "$and/$or/$nor match element must be an object", code: 13087 }
Wed Oct 16 12:03:08.588 [initandlisten] connection accepted from 127.0.0.1:50069 #34 (6 connections now open)
Wed Oct 16 12:03:08.607 [conn28] CMD: drop test.jstests_indexg
Wed Oct 16 12:03:08.607 [conn30] CMD: drop test.jstests_or3
Wed Oct 16 12:03:08.737 [conn30] command test.$cmd command: { drop: "jstests_or3" } ntoreturn:1 keyUpdates:0 locks(micros) w:130175 reslen:123 130ms
Wed Oct 16 12:03:09.379 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:78 w:640705 641ms
Wed Oct 16 12:03:09.412 [conn28] insert test.jstests_indexg ninserted:1 keyUpdates:0 locks(micros) r:115 w:33421 675ms
Wed Oct 16 12:03:09.413 [conn30] assertion 13086 $and/$or/$nor must be a nonempty array ns:test.jstests_or3 query:{ x: 0.0, $nor: "a" }
Wed Oct 16 12:03:09.413 [conn30] problem detected during query over test.jstests_or3 : { $err: "$and/$or/$nor must be a nonempty array", code: 13086 }
Wed Oct 16 12:03:09.413 [conn30] assertion 13086 $and/$or/$nor must be a nonempty array ns:test.jstests_or3 query:{ x: 0.0, $nor: {} }
Wed Oct 16 12:03:09.413 [conn30] problem detected during query over test.jstests_or3 : { $err: "$and/$or/$nor must be a nonempty array", code: 13086 }
Wed Oct 16 12:03:09.414 [conn30] assertion 13087 $and/$or/$nor match element must be an object ns:test.jstests_or3 query:{ x: 0.0, $nor: [ "a" ] }
Wed Oct 16 12:03:09.414 [conn30] problem detected during query over test.jstests_or3 : { $err: "$and/$or/$nor match element must be an object", code: 13087 }
Wed Oct 16 12:03:09.596 [conn28] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:32 w:181828 182ms
Wed Oct 16 12:03:09.597 [conn34] end connection 127.0.0.1:50069 (5 connections now open)
Wed Oct 16 12:03:09.598 [conn28] CMD: drop test.jstests_queryoptimizer7
Wed Oct 16 12:03:09.600 [conn30] CMD: drop test.jstests_or3
Wed Oct 16 12:03:09.788 [conn30] command test.$cmd command: { drop: "jstests_or3" } ntoreturn:1 keyUpdates:0 locks(micros) w:187354 reslen:123 187ms
Wed Oct 16 12:03:10.005 [conn28] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:76 w:216558 405ms
Wed Oct 16 12:03:10.263 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:143 w:257518 258ms
Wed Oct 16 12:03:10.264 [conn30] assertion 13086 $and/$or/$nor must be a nonempty array ns:test.jstests_or3 query:{ x: 0.0, $nor: "a" }
Wed Oct 16 12:03:10.264 [conn30] problem detected during query over test.jstests_or3 : { $err: "$and/$or/$nor must be a nonempty array", code: 13086 }
Wed Oct 16 12:03:10.265 [conn30] assertion 13086 $and/$or/$nor must be a nonempty array ns:test.jstests_or3 query:{ x: 0.0, $nor: {} }
Wed Oct 16 12:03:10.265 [conn30] problem detected during query over test.jstests_or3 : { $err: "$and/$or/$nor must be a nonempty array", code: 13086 }
Wed Oct 16 12:03:10.265 [conn30] assertion 13087 $and/$or/$nor match element must be an object ns:test.jstests_or3 query:{ x: 0.0, $nor: [ "a" ] }
Wed Oct 16 12:03:10.265 [conn30] problem detected during query over test.jstests_or3 : { $err: "$and/$or/$nor match element must be an object", code: 13087 }
Wed Oct 16 12:03:10.267 [conn28] CMD: drop test.jstests_queryoptimizer7
Wed Oct 16 12:03:10.372 [conn28] command test.$cmd command: { drop: "jstests_queryoptimizer7" } ntoreturn:1 keyUpdates:0 locks(micros) w:105179 reslen:135 105ms
Wed Oct 16 12:03:10.663 [conn28] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:118 w:291092 291ms
Wed Oct 16 12:03:10.667 [conn29] CMD: drop test.jstests_txn_isolation
Wed Oct 16 12:03:10.897 [conn29] command test.$cmd command: { drop: "jstests_txn_isolation" } ntoreturn:1 keyUpdates:0 locks(micros) w:230360 reslen:133 230ms
Wed Oct 16 12:03:10.924 [initandlisten] connection accepted from 127.0.0.1:50070 #35 (6 connections now open)
Wed Oct 16 12:03:10.939 [conn28] CMD: drop test.jstests_queryoptimizer7
Wed Oct 16 12:03:11.072 [conn28] command test.$cmd command: { drop: "jstests_queryoptimizer7" } ntoreturn:1 keyUpdates:0 locks(micros) w:133059 reslen:135 133ms
Wed Oct 16 12:03:11.339 [conn28] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:69 w:265941 266ms
Wed Oct 16 12:03:11.339 [conn30] CMD: drop test.jstests_or3
Wed Oct 16 12:03:11.540 [conn28] CMD: drop test.ed_db_update2
Wed Oct 16 12:03:11.540 [conn30] command test.$cmd command: { drop: "jstests_or3" } ntoreturn:1 keyUpdates:0 locks(micros) w:200475 reslen:123 201ms
Wed Oct 16 12:03:11.798 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:79 w:216544 216ms
Wed Oct 16 12:03:11.798 [conn28] CMD: drop test.ed_db_update2
Wed Oct 16 12:03:11.936 [conn30] assertion 13086 $and/$or/$nor must be a nonempty array ns:test.jstests_or3 query:{ x: 0.0, $nor: "a" }
Wed Oct 16 12:03:11.936 [conn30] problem detected during query over test.jstests_or3 : { $err: "$and/$or/$nor must be a nonempty array", code: 13086 }
Wed Oct 16 12:03:12.348 [conn28] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:46 w:411217 416ms
Wed Oct 16 12:03:12.348 [conn30] assertion 13086 $and/$or/$nor must be a nonempty array ns:test.jstests_or3 query:{ x: 0.0, $nor: {} }
Wed Oct 16 12:03:12.348 [conn30] problem detected during query over test.jstests_or3 : { $err: "$and/$or/$nor must be a nonempty array", code: 13086 }
Wed Oct 16 12:03:12.349 [conn30] assertion 13087 $and/$or/$nor match element must be an object ns:test.jstests_or3 query:{ x: 0.0, $nor: [ "a" ] }
Wed Oct 16 12:03:12.349 [conn30] problem detected during query over test.jstests_or3 : { $err: "$and/$or/$nor match element must be an object", code: 13087 }
Wed Oct 16 12:03:16.348 [conn29] Count with ns: test.jstests_txn_isolation and query: {} failed with exception: 16759 Lock not granted. Try restarting the transaction. code: 16759
Wed Oct 16 12:03:16.348 [conn29] command test.$cmd command: { count: "jstests_txn_isolation", query: {}, fields: {} } ntoreturn:1 keyUpdates:0 lockNotGranted: { index: "test.jstests_txn_isolation.$_id_", requestingTxnid: 4289366, blockingTxnid: 4289362, bounds: [ { _id: MinKey }, { _id: MaxKey } ] } locks(micros) r:4000264 reslen:105 4000ms
Wed Oct 16 12:03:16.348 [conn28] CMD: drop test.ed_db_update2
Wed Oct 16 12:03:16.515 [conn28] command test.$cmd command: { drop: "ed_db_update2" } ntoreturn:1 keyUpdates:0 locks(micros) w:166594 reslen:125 166ms
Wed Oct 16 12:03:16.557 [conn28] CMD: drop test.find_and_modify2
Wed Oct 16 12:03:16.598 [conn30] CMD: drop test.explain1
Wed Oct 16 12:03:16.634 [conn28] CMD: drop profile4.profile4
Wed Oct 16 12:03:16.965 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:39 w:326213 326ms
Wed Oct 16 12:03:16.969 [conn30] CMD: drop test.mr_errorhandling
Wed Oct 16 12:03:17.070 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_0
Wed Oct 16 12:03:17.071 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_0_inc
Wed Oct 16 12:03:17.299 [conn28] insert profile4.system.users ninserted:1 keyUpdates:0 locks(micros) r:114 w:664249 664ms
Wed Oct 16 12:03:17.299 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:36 w:175124 175ms
Wed Oct 16 12:03:17.300 [conn28] authenticate db: profile4 { authenticate: 1, nonce: "1b81afab71d9896e", user: "jstests_profile4_user", key: "b1d28709719d5096325610596a606607" }
Wed Oct 16 12:03:17.301 [conn28] CMD: drop profile4.system.profile
Wed Oct 16 12:03:17.302 [conn28] creating profile collection: profile4.system.profile
Wed Oct 16 12:03:17.302 [conn28] creating profile collection: profile4.system.profile
Wed Oct 16 12:03:17.361 [conn30] CMD: drop test.mr_errorhandling_out
Wed Oct 16 12:03:17.403 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_0
Wed Oct 16 12:03:17.404 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_0
Wed Oct 16 12:03:17.404 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_0_inc
Wed Oct 16 12:03:17.436 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_0
Wed Oct 16 12:03:17.436 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_0_inc
Wed Oct 16 12:03:17.439 [conn28] CMD: drop profile4.system.profile
Wed Oct 16 12:03:18.534 [conn31] insert test.lock_diag_size_limit ninserted:1 keyUpdates:0 locks(micros) r:283397 283ms
Wed Oct 16 12:03:18.780 [conn30] command test.$cmd command: { mapreduce: "mr_errorhandling", map: function (){
for ( var i=0; i<this.a.length; i++ ){
emit( this.a[i] , 1 );
}
}, reduce: function ( k , v ){
var total = 0;
for ( var i=0; i<v.length; i++ )
total += v[i];
return total;
}, out: "mr_errorhandling_out" } ntoreturn:1 keyUpdates:0 locks(micros) r:697 w:110519 reslen:144 1722ms
Wed Oct 16 12:03:18.783 [conn30] CMD: drop test.mr_errorhandling_out
Wed Oct 16 12:03:18.811 [conn28] command profile4.$cmd command: { drop: "system.profile" } ntoreturn:1 keyUpdates:0 locks(micros) w:1372272 reslen:130 1372ms
Wed Oct 16 12:03:19.076 [conn30] command test.$cmd command: { drop: "mr_errorhandling_out" } ntoreturn:1 keyUpdates:0 locks(micros) w:293008 reslen:132 293ms
Wed Oct 16 12:03:19.076 [conn28] CMD: drop test.jstests_shardingindex
Wed Oct 16 12:03:19.081 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_1
Wed Oct 16 12:03:19.081 [conn28] CMD: drop test.jstests_shardingindex
Wed Oct 16 12:03:19.276 [conn28] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:73 w:194545 194ms
Wed Oct 16 12:03:19.276 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_1_inc
Wed Oct 16 12:03:19.427 [conn30] Error: fast_emit takes 2 args near '{ emit( this.' (line 3)
Wed Oct 16 12:03:19.431 [conn28] CMD: drop test.jstests_shardingindex
Wed Oct 16 12:03:20.927 [conn35] end connection 127.0.0.1:50070 (5 connections now open)
Wed Oct 16 12:03:23.602 [conn28] command test.$cmd command: { drop: "jstests_shardingindex" } ntoreturn:1 keyUpdates:0 lockNotGranted: { index: "test.system.namespaces.$$_", requestingTxnid: 4295630, blockingTxnid: 4295564, bounds: [ { $_: MinKey }, { $_: MaxKey } ] } locks(micros) w:4170839 reslen:141 4170ms
Wed Oct 16 12:03:23.602 [conn29] CMD: drop test.jstests_txn_isolation
Wed Oct 16 12:03:27.677 [conn29] command test.$cmd command: { drop: "jstests_txn_isolation" } ntoreturn:1 keyUpdates:0 lockNotGranted: { index: "test.system.namespaces.$$_", requestingTxnid: 4295633, blockingTxnid: 4295564, bounds: [ { $_: MinKey }, { $_: MaxKey } ] } locks(micros) w:4075120 reslen:141 4075ms
Wed Oct 16 12:03:27.677 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_1
Wed Oct 16 12:03:27.679 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_1_inc
Wed Oct 16 12:03:27.682 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_1
Wed Oct 16 12:03:27.682 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_1_inc
Wed Oct 16 12:03:28.310 [conn31] insert test.lock_diag_size_limit ninserted:1 keyUpdates:0 locks(micros) r:268626 268ms
Wed Oct 16 12:03:28.385 [conn30] mr failed, removing collection :: caused by :: 16722 Error: fast_emit takes 2 args near '{ emit( this.' (line 3)
Wed Oct 16 12:03:28.385 [conn30] command test.$cmd command: { mapreduce: "mr_errorhandling", map: function (){
for ( var i=0; i<this.a.length; i++ ){
emit( this.a[i] );
}
}, reduce: function ( k , v ){
var total = 0;
for ( var i=0; i<v.length; i++ )
total += v[i];
return total;
}, out: "mr_errorhandling_out" } ntoreturn:1 keyUpdates:0 locks(micros) r:1068 w:64755 reslen:139 9308ms
Wed Oct 16 12:03:28.399 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_2
Wed Oct 16 12:03:28.399 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_2_inc
Wed Oct 16 12:03:28.670 [conn30] CMD: drop test.mr_errorhandling_out
Wed Oct 16 12:03:28.674 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_2
Wed Oct 16 12:03:28.674 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_2
Wed Oct 16 12:03:28.674 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_2_inc
Wed Oct 16 12:03:28.677 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_2
Wed Oct 16 12:03:28.677 [conn30] CMD: drop test.tmp.mr.mr_errorhandling_2_inc
Wed Oct 16 12:03:29.766 [conn30] command test.$cmd command: { mapreduce: "mr_errorhandling", map: function (){
for ( var i=0; i<this.a.length; i++ ){
emit( this.a[i] , 1 );
}
}, reduce: function ( k , v ){
var total = 0;
for ( var i=0; i<v.length; i++ )
total += v[i];
return total;
}, out: "mr_errorhandling_out" } ntoreturn:1 keyUpdates:0 locks(micros) r:765 w:177379 reslen:144 1381ms
Wed Oct 16 12:03:32.141 [conn30] CMD: drop test.mr_errorhandling_out
Wed Oct 16 12:03:32.141 [conn31] update test.lock_diag_size_limit update: { $set: { y: 1.0 } } nscanned:10000 nupdated:10000 keyUpdates:0 locks(micros) r:3242446 3242ms
Wed Oct 16 12:03:32.277 [conn30] command test.$cmd command: { drop: "mr_errorhandling_out" } ntoreturn:1 keyUpdates:0 locks(micros) w:136413 reslen:132 136ms
Wed Oct 16 12:03:32.299 [conn30] SyntaxError: Unexpected token ;
Wed Oct 16 12:03:32.300 [conn30] CMD: drop test.mr2
Wed Oct 16 12:03:32.328 [conn30] CMD: drop test.tmp.mr.mr2_4
Wed Oct 16 12:03:32.328 [conn30] CMD: drop test.tmp.mr.mr2_4_inc
Wed Oct 16 12:03:32.479 [conn30] CMD: drop test.mr2_out
Wed Oct 16 12:03:32.481 [conn30] CMD: drop test.tmp.mr.mr2_4
Wed Oct 16 12:03:32.481 [conn30] CMD: drop test.tmp.mr.mr2_4
Wed Oct 16 12:03:32.481 [conn30] CMD: drop test.tmp.mr.mr2_4_inc
Wed Oct 16 12:03:32.484 [conn30] CMD: drop test.tmp.mr.mr2_4
Wed Oct 16 12:03:32.484 [conn30] CMD: drop test.tmp.mr.mr2_4_inc
Wed Oct 16 12:03:32.728 [conn30] command test.$cmd command: { mapreduce: "mr2", map: function m(){
for ( var i=0; i<this.comments.length; i++ ){
var c = this.comments[i];
emit( c.who , { totalSize : c.txt.length , num : 1 } );
}
}, reduce: function r( who , values ){
var n = { totalSize : 0 , num : 0 };
for ( var i=0; i<values.length; i++ ){
n.totalSize += values[i].totalSize;
n.num += values[i].num;
}
return n;
}, finalize: function f( who , res ){
res.avg = res.totalSize / res.num;
return res;
}, out: "mr2_out" } ntoreturn:1 keyUpdates:0 locks(micros) r:944 w:66051 reslen:131 400ms
Wed Oct 16 12:03:32.729 [conn30] CMD: drop test.mr2_out
Wed Oct 16 12:03:32.831 [conn30] CMD: drop test.tmp.mr.mr2_5
Wed Oct 16 12:03:32.831 [conn30] CMD: drop test.tmp.mr.mr2_5_inc
Wed Oct 16 12:03:32.979 [conn30] CMD: drop test.mr2_out
Wed Oct 16 12:03:32.981 [conn30] CMD: drop test.tmp.mr.mr2_5
Wed Oct 16 12:03:32.981 [conn30] CMD: drop test.tmp.mr.mr2_5
Wed Oct 16 12:03:32.981 [conn30] CMD: drop test.tmp.mr.mr2_5_inc
Wed Oct 16 12:03:32.983 [conn30] CMD: drop test.tmp.mr.mr2_5
Wed Oct 16 12:03:32.983 [conn30] CMD: drop test.tmp.mr.mr2_5_inc
Wed Oct 16 12:03:33.154 [conn30] command test.$cmd command: { mapreduce: "mr2", map: function m(){
for ( var i=0; i<this.comments.length; i++ ){
var c = this.comments[i];
emit( c.who , { totalSize : c.txt.length , num : 1 } );
}
}, reduce: function r( who , values ){
var n = { totalSize : 0 , num : 0 };
for ( var i=0; i<values.length; i++ ){
n.totalSize += values[i].totalSize;
n.num += values[i].num;
}
return n;
}, finalize: function f( who , res ){
res.avg = res.totalSize / res.num;
return res;
}, out: "mr2_out", jsMode: true } ntoreturn:1 keyUpdates:0 locks(micros) r:569 w:64109 reslen:131 323ms
Wed Oct 16 12:03:33.154 [conn30] CMD: drop test.mr2_out
Wed Oct 16 12:03:33.256 [conn30] CMD: drop test.idprojection
Wed Oct 16 12:03:33.495 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:70 w:239137 239ms
Wed Oct 16 12:03:33.496 [conn30] CMD: dropIndexes test.idprojection
Wed Oct 16 12:03:33.779 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:49 w:183107 183ms
Wed Oct 16 12:03:33.782 [conn30] CMD: dropIndexes test.idprojection
Wed Oct 16 12:03:33.996 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:52 w:191543 191ms
Wed Oct 16 12:03:34.000 [conn30] CMD: dropIndexes test.idprojection
Wed Oct 16 12:03:34.238 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:50 w:208222 208ms
Wed Oct 16 12:03:34.242 [conn30] CMD: dropIndexes test.idprojection
Wed Oct 16 12:03:34.455 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:55 w:183232 183ms
Wed Oct 16 12:03:34.459 [conn30] CMD: dropIndexes test.idprojection
Wed Oct 16 12:03:34.672 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:50 w:183211 183ms
Wed Oct 16 12:03:34.676 [conn30] CMD: dropIndexes test.idprojection
Wed Oct 16 12:03:34.889 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:50 w:183283 183ms
Wed Oct 16 12:03:34.892 [conn30] CMD: dropIndexes test.idprojection
Wed Oct 16 12:03:35.106 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:61 w:183129 183ms
Wed Oct 16 12:03:35.109 [conn30] CMD: dropIndexes test.idprojection
Wed Oct 16 12:03:35.323 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:51 w:183187 183ms
Wed Oct 16 12:03:35.327 [conn30] CMD: drop test.jstests_orp
Wed Oct 16 12:03:35.327 [conn30] CMD: drop test.jstests_orp
Wed Oct 16 12:03:35.657 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:33 w:288486 288ms
Wed Oct 16 12:03:35.865 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:57 w:208044 208ms
Wed Oct 16 12:03:35.875 [conn30] CMD: drop test.jstests_orp
Wed Oct 16 12:03:36.166 [conn30] command test.$cmd command: { drop: "jstests_orp" } ntoreturn:1 keyUpdates:0 locks(micros) w:291271 reslen:123 291ms
Wed Oct 16 12:03:36.582 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:35 w:380100 380ms
Wed Oct 16 12:03:36.816 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:51 w:233166 233ms
Wed Oct 16 12:03:36.826 [conn30] CMD: drop test.update5
Wed Oct 16 12:03:37.091 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:36 w:182084 182ms
Wed Oct 16 12:03:37.092 [conn30] CMD: drop test.update5
Wed Oct 16 12:03:37.434 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:47 w:206722 206ms
Wed Oct 16 12:03:37.435 [conn30] CMD: drop test.update5
Wed Oct 16 12:03:37.760 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:39 w:190427 190ms
Wed Oct 16 12:03:37.761 [conn30] CMD: drop test.update5
Wed Oct 16 12:03:38.085 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:38 w:190424 190ms
Wed Oct 16 12:03:38.086 [conn30] CMD: drop test.update5
Wed Oct 16 12:03:38.411 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:49 w:190131 190ms
Wed Oct 16 12:03:38.412 [conn30] CMD: drop test.update5
Wed Oct 16 12:03:39.044 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:43 w:498524 498ms
Wed Oct 16 12:03:39.045 [conn30] CMD: drop test.update5
Wed Oct 16 12:03:39.370 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:49 w:190158 190ms
Wed Oct 16 12:03:39.372 [conn30] CMD: drop test.update5
Wed Oct 16 12:03:39.703 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:43 w:198287 198ms
Wed Oct 16 12:03:39.705 [conn30] CMD: drop test.jstests_exists4
Wed Oct 16 12:03:39.937 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:73 w:232026 232ms
Wed Oct 16 12:03:39.940 [conn30] CMD: drop test.jstests_orm
Wed Oct 16 12:03:39.967 [initandlisten] connection accepted from 127.0.0.1:50072 #36 (6 connections now open)
Wed Oct 16 12:03:40.579 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:34 w:311987 312ms
Wed Oct 16 12:03:40.813 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:51 w:233284 233ms
Wed Oct 16 12:03:41.065 [conn30] update test.jstests_orm query: { $or: [ { a: { $lte: 500.0 }, i: 49999.0 }, { b: { $lte: 500.0 }, i: 49999.0 }, { a: { $lte: 1000.0 }, i: 49999.0 }, { b: { $lte: 1000.0 }, i: 49999.0 }, { a: { $lte: 1500.0 }, i: 49999.0 }, { b: { $lte: 1500.0 }, i: 49999.0 }, { a: { $lte: 2000.0 }, i: 49999.0 }, { b: { $lte: 2000.0 }, i: 49999.0 }, { a: { $lte: 2500.0 }, i: 49999.0 }, { b: { $lte: 2500.0 }, i: 49999.0 }, { a: { $lte: 3000.0 }, i: 49999.0 }, { b: { $lte: 3000.0 }, i: 49999.0 }, { a: { $lte: 3500.0 }, i: 49999.0 }, { b: { $lte: 3500.0 }, i: 49999.0 }, { a: { $lte: 4000.0 }, i: 49999.0 }, { b: { $lte: 4000.0 }, i: 49999.0 }, { a: { $lte: 4500.0 }, i: 49999.0 }, { b: { $lte: 4500.0 }, i: 49999.0 }, { a: { $lte: 5000.0 }, i: 49999.0 }, { b: { $lte: 5000.0 }, i: 49999.0 } ] } nscanned:10000 nupdated:0 keyUpdates:0 locks(micros) r:109228 109ms
Wed Oct 16 12:03:41.065 [conn36] CMD: drop test.jstests_orm
Wed Oct 16 12:03:41.189 [conn36] command test.$cmd command: { drop: "jstests_orm" } ntoreturn:1 keyUpdates:0 locks(micros) w:123371 reslen:123 123ms
Wed Oct 16 12:03:41.780 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:36 w:272045 272ms
Wed Oct 16 12:03:42.022 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:51 w:241560 241ms
Wed Oct 16 12:03:42.277 [conn30] update test.jstests_orm query: { $or: [ { a: { $lte: 500.0 }, i: 49999.0 }, { b: { $lte: 500.0 }, i: 49999.0 }, { a: { $lte: 1000.0 }, i: 49999.0 }, { b: { $lte: 1000.0 }, i: 49999.0 }, { a: { $lte: 1500.0 }, i: 49999.0 }, { b: { $lte: 1500.0 }, i: 49999.0 }, { a: { $lte: 2000.0 }, i: 49999.0 }, { b: { $lte: 2000.0 }, i: 49999.0 }, { a: { $lte: 2500.0 }, i: 49999.0 }, { b: { $lte: 2500.0 }, i: 49999.0 }, { a: { $lte: 3000.0 }, i: 49999.0 }, { b: { $lte: 3000.0 }, i: 49999.0 }, { a: { $lte: 3500.0 }, i: 49999.0 }, { b: { $lte: 3500.0 }, i: 49999.0 }, { a: { $lte: 4000.0 }, i: 49999.0 }, { b: { $lte: 4000.0 }, i: 49999.0 }, { a: { $lte: 4500.0 }, i: 49999.0 }, { b: { $lte: 4500.0 }, i: 49999.0 }, { a: { $lte: 5000.0 }, i: 49999.0 }, { b: { $lte: 5000.0 }, i: 49999.0 } ] } nscanned:10000 nupdated:0 keyUpdates:0 locks(micros) r:110456 110ms
Wed Oct 16 12:03:42.277 [conn36] CMD: drop test.jstests_orm
Wed Oct 16 12:03:42.448 [conn36] command test.$cmd command: { drop: "jstests_orm" } ntoreturn:1 keyUpdates:0 locks(micros) w:170134 reslen:123 170ms
Wed Oct 16 12:03:43.064 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:37 w:293608 293ms
Wed Oct 16 12:03:43.322 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:68 w:257721 257ms
Wed Oct 16 12:03:43.424 [conn30] query test.jstests_orm query: { $or: [ { a: { $lte: 500.0 }, i: 49999.0 }, { b: { $lte: 500.0 }, i: 49999.0 }, { a: { $lte: 1000.0 }, i: 49999.0 }, { b: { $lte: 1000.0 }, i: 49999.0 }, { a: { $lte: 1500.0 }, i: 49999.0 }, { b: { $lte: 1500.0 }, i: 49999.0 }, { a: { $lte: 2000.0 }, i: 49999.0 }, { b: { $lte: 2000.0 }, i: 49999.0 }, { a: { $lte: 2500.0 }, i: 49999.0 }, { b: { $lte: 2500.0 }, i: 49999.0 }, { a: { $lte: 3000.0 }, i: 49999.0 }, { b: { $lte: 3000.0 }, i: 49999.0 }, { a: { $lte: 3500.0 }, i: 49999.0 }, { b: { $lte: 3500.0 }, i: 49999.0 }, { a: { $lte: 4000.0 }, i: 49999.0 }, { b: { $lte: 4000.0 }, i: 49999.0 }, { a: { $lte: 4500.0 }, i: 49999.0 }, { b: { $lte: 4500.0 }, i: 49999.0 }, { a: { $lte: 5000.0 }, i: 49999.0 }, { b: { $lte: 5000.0 }, i: 49999.0 } ] } ntoreturn:0 ntoskip:0 nscanned:11002 keyUpdates:0 locks(micros) r:101786 nreturned:0 reslen:20 101ms
Wed Oct 16 12:03:43.497 [conn36] CMD: drop test.jstests_orm
Wed Oct 16 12:03:43.632 [conn36] command test.$cmd command: { drop: "jstests_orm" } ntoreturn:1 keyUpdates:0 locks(micros) w:134481 reslen:123 134ms
Wed Oct 16 12:03:44.422 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:38 w:441173 441ms
Wed Oct 16 12:03:44.706 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:53 w:283098 283ms
Wed Oct 16 12:03:44.706 [conn36] CMD: drop test.jstests_orm
Wed Oct 16 12:03:44.832 [conn36] command test.$cmd command: { drop: "jstests_orm" } ntoreturn:1 keyUpdates:0 locks(micros) w:125661 reslen:123 125ms
Wed Oct 16 12:03:45.473 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:36 w:272879 272ms
Wed Oct 16 12:03:45.706 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:51 w:233160 233ms
Wed Oct 16 12:03:45.861 [conn36] CMD: drop test.jstests_orm
Wed Oct 16 12:03:45.974 [conn36] command test.$cmd command: { drop: "jstests_orm" } ntoreturn:1 keyUpdates:0 locks(micros) w:112863 reslen:123 112ms
Wed Oct 16 12:03:46.974 [conn36] CMD: drop test.jstests_orm
Wed Oct 16 12:03:48.074 [conn36] CMD: drop test.jstests_orm
Wed Oct 16 12:03:49.074 [conn36] CMD: drop test.jstests_orm
Wed Oct 16 12:03:50.075 [conn36] CMD: drop test.jstests_orm
Wed Oct 16 12:03:51.076 [conn36] CMD: drop test.jstests_orm
Wed Oct 16 12:03:52.076 [conn36] CMD: drop test.jstests_orm
Wed Oct 16 12:03:53.077 [conn36] CMD: drop test.jstests_orm
Wed Oct 16 12:03:54.077 [conn36] CMD: drop test.jstests_orm
Wed Oct 16 12:03:55.078 [conn36] CMD: drop test.jstests_orm
Wed Oct 16 12:03:56.079 [conn36] CMD: drop test.jstests_orm
Wed Oct 16 12:03:56.080 [conn36] end connection 127.0.0.1:50072 (5 connections now open)
Wed Oct 16 12:03:56.082 [conn30] CMD: drop test.multi2
Wed Oct 16 12:03:56.325 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:42 w:207789 207ms
Wed Oct 16 12:03:56.327 [conn30] CMD: dropIndexes test.multi2
Wed Oct 16 12:03:56.558 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:50 w:199784 199ms
Wed Oct 16 12:03:56.560 [conn30] CMD: drop test.mr_merge
Wed Oct 16 12:03:56.600 [conn30] CMD: drop test.mr_merge_out
Wed Oct 16 12:03:56.601 [conn30] CMD: drop test.tmp.mr.mr_merge_6
Wed Oct 16 12:03:56.601 [conn30] CMD: drop test.tmp.mr.mr_merge_6_inc
Wed Oct 16 12:03:56.761 [conn30] CMD: drop test.mr_merge_out
Wed Oct 16 12:03:56.763 [conn30] CMD: drop test.tmp.mr.mr_merge_6
Wed Oct 16 12:03:56.763 [conn30] CMD: drop test.tmp.mr.mr_merge_6
Wed Oct 16 12:03:56.763 [conn30] CMD: drop test.tmp.mr.mr_merge_6_inc
Wed Oct 16 12:03:56.765 [conn30] CMD: drop test.tmp.mr.mr_merge_6
Wed Oct 16 12:03:56.765 [conn30] CMD: drop test.tmp.mr.mr_merge_6_inc
Wed Oct 16 12:03:57.035 [conn30] command test.$cmd command: { mapreduce: "mr_merge", map: function (){ for (i=0; i<this.a.length; i++ ) emit( this.a[i] , 1 ); }, reduce: function (k,vs){ return Array.sum( vs ); }, out: "mr_merge_out" } ntoreturn:1 keyUpdates:0 locks(micros) r:702 w:75205 reslen:136 434ms
Wed Oct 16 12:03:57.037 [conn30] CMD: drop test.tmp.mr.mr_merge_7
Wed Oct 16 12:03:57.037 [conn30] CMD: drop test.tmp.mr.mr_merge_7_inc
Wed Oct 16 12:03:57.185 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:40 w:108357 108ms
Wed Oct 16 12:03:57.236 [conn30] CMD: drop test.mr_merge_out
Wed Oct 16 12:03:57.323 [conn30] CMD: drop test.tmp.mr.mr_merge_7
Wed Oct 16 12:03:57.323 [conn30] CMD: drop test.tmp.mr.mr_merge_7
Wed Oct 16 12:03:57.323 [conn30] CMD: drop test.tmp.mr.mr_merge_7_inc
Wed Oct 16 12:03:57.325 [conn30] CMD: drop test.tmp.mr.mr_merge_7
Wed Oct 16 12:03:57.325 [conn30] CMD: drop test.tmp.mr.mr_merge_7_inc
Wed Oct 16 12:03:57.586 [conn30] command test.$cmd command: { mapreduce: "mr_merge", map: function (){ for (i=0; i<this.a.length; i++ ) emit( this.a[i] , 1 ); }, reduce: function (k,vs){ return Array.sum( vs ); }, out: "mr_merge_out" } ntoreturn:1 keyUpdates:0 locks(micros) r:782 w:90197 reslen:136 549ms
Wed Oct 16 12:03:57.587 [conn30] CMD: drop test.tmp.mr.mr_merge_8
Wed Oct 16 12:03:57.587 [conn30] CMD: drop test.tmp.mr.mr_merge_8_inc
Wed Oct 16 12:03:57.754 [conn30] CMD: drop test.tmp.mr.mr_merge_8
Wed Oct 16 12:03:57.756 [conn30] CMD: drop test.tmp.mr.mr_merge_8
Wed Oct 16 12:03:57.756 [conn30] CMD: drop test.tmp.mr.mr_merge_8_inc
Wed Oct 16 12:03:57.759 [conn30] CMD: drop test.tmp.mr.mr_merge_8
Wed Oct 16 12:03:57.759 [conn30] CMD: drop test.tmp.mr.mr_merge_8_inc
Wed Oct 16 12:03:58.069 [conn30] command test.$cmd command: { mapreduce: "mr_merge", map: function (){ for (i=0; i<this.a.length; i++ ) emit( this.a[i] , 1 ); }, reduce: function (k,vs){ return Array.sum( vs ); }, out: { merge: "mr_merge_out" } } ntoreturn:1 keyUpdates:0 locks(micros) r:1113 w:73644 reslen:136 482ms
Wed Oct 16 12:03:58.071 [conn30] CMD: drop test.tmp.mr.mr_merge_9
Wed Oct 16 12:03:58.071 [conn30] CMD: drop test.tmp.mr.mr_merge_9_inc
Wed Oct 16 12:03:58.221 [conn30] CMD: drop test.tmp.mr.mr_merge_9
Wed Oct 16 12:03:58.223 [conn30] CMD: drop test.tmp.mr.mr_merge_9
Wed Oct 16 12:03:58.223 [conn30] CMD: drop test.tmp.mr.mr_merge_9_inc
Wed Oct 16 12:03:58.226 [conn30] CMD: drop test.tmp.mr.mr_merge_9
Wed Oct 16 12:03:58.226 [conn30] CMD: drop test.tmp.mr.mr_merge_9_inc
Wed Oct 16 12:03:58.537 [conn30] command test.$cmd command: { mapreduce: "mr_merge", map: function (){ for (i=0; i<this.a.length; i++ ) emit( this.a[i] , 1 ); }, reduce: function (k,vs){ return Array.sum( vs ); }, out: { merge: "mr_merge_out" } } ntoreturn:1 keyUpdates:0 locks(micros) r:1171 w:65209 reslen:136 466ms
Wed Oct 16 12:03:58.538 [conn30] creating profile collection: test.system.profile
Wed Oct 16 12:03:58.538 [conn30] creating profile collection: test.system.profile
Wed Oct 16 12:03:58.570 [conn30] CMD: drop test.update_setOnInsert
Wed Oct 16 12:03:58.604 [conn30] CMD: drop test.update_setOnInsert
Wed Oct 16 12:03:58.945 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:77 w:241398 241ms
Wed Oct 16 12:03:58.947 [conn30] CMD: drop test.jstests_update3
Wed Oct 16 12:03:58.988 [conn30] CMD: drop test.jstests_update3
Wed Oct 16 12:03:59.162 [conn30] command test.$cmd command: { drop: "jstests_update3" } ntoreturn:1 keyUpdates:0 locks(micros) w:174508 reslen:127 174ms
Wed Oct 16 12:03:59.196 [conn30] CMD: drop test.jstests_update3
Wed Oct 16 12:03:59.330 [conn30] CMD: drop test.jstests_update3
Wed Oct 16 12:03:59.463 [conn30] CMD: drop test.jstests_update3
Wed Oct 16 12:03:59.597 [conn30] CMD: drop test.jstests_6072
Wed Oct 16 12:03:59.630 [conn30] CMD: drop test.jstests_6072
Wed Oct 16 12:03:59.730 [conn30] CMD: drop test.jstests_update_arraymatch8
Wed Oct 16 12:03:59.971 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:59 w:240661 240ms
Wed Oct 16 12:03:59.973 [conn30] CMD: drop test.jstests_update_arraymatch8
Wed Oct 16 12:04:00.147 [conn30] command test.$cmd command: { drop: "jstests_update_arraymatch8" } ntoreturn:1 keyUpdates:0 locks(micros) w:173649 reslen:138 173ms
Wed Oct 16 12:04:00.363 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:90 w:216220 216ms
Wed Oct 16 12:04:00.365 [conn30] CMD: drop test.jstests_update_arraymatch8
Wed Oct 16 12:04:00.622 [conn30] command test.$cmd command: { drop: "jstests_update_arraymatch8" } ntoreturn:1 keyUpdates:0 locks(micros) w:256985 reslen:138 256ms
Wed Oct 16 12:04:01.150 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:80 w:527579 527ms
Wed Oct 16 12:04:01.152 [conn30] CMD: drop test.jstests_update_arraymatch8
Wed Oct 16 12:04:01.355 [conn30] command test.$cmd command: { drop: "jstests_update_arraymatch8" } ntoreturn:1 keyUpdates:0 locks(micros) w:203166 reslen:138 203ms
Wed Oct 16 12:04:01.706 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:74 w:350407 350ms
Wed Oct 16 12:04:01.707 [conn30] CMD: drop test.jstests_update_arraymatch8
Wed Oct 16 12:04:01.906 [conn30] command test.$cmd command: { drop: "jstests_update_arraymatch8" } ntoreturn:1 keyUpdates:0 locks(micros) w:198882 reslen:138 198ms
Wed Oct 16 12:04:02.165 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:80 w:257718 257ms
Wed Oct 16 12:04:02.166 [conn30] CMD: drop test.jstests_update_arraymatch8
Wed Oct 16 12:04:02.340 [conn30] command test.$cmd command: { drop: "jstests_update_arraymatch8" } ntoreturn:1 keyUpdates:0 locks(micros) w:173626 reslen:138 173ms
Wed Oct 16 12:04:02.557 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:85 w:216173 216ms
Wed Oct 16 12:04:02.559 [conn30] CMD: drop test.jstests_update_arraymatch8
Wed Oct 16 12:04:02.732 [conn30] command test.$cmd command: { drop: "jstests_update_arraymatch8" } ntoreturn:1 keyUpdates:0 locks(micros) w:173471 reslen:138 173ms
Wed Oct 16 12:04:02.949 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:73 w:216159 216ms
Wed Oct 16 12:04:02.951 [conn30] CMD: drop test.jstests_update_arraymatch8
Wed Oct 16 12:04:03.124 [conn30] command test.$cmd command: { drop: "jstests_update_arraymatch8" } ntoreturn:1 keyUpdates:0 locks(micros) w:173532 reslen:138 173ms
Wed Oct 16 12:04:03.341 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:72 w:216214 216ms
Wed Oct 16 12:04:03.342 [conn30] CMD: drop test.jstests_update_arraymatch8
Wed Oct 16 12:04:03.516 [conn30] command test.$cmd command: { drop: "jstests_update_arraymatch8" } ntoreturn:1 keyUpdates:0 locks(micros) w:173997 reslen:138 174ms
Wed Oct 16 12:04:03.749 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:79 w:232844 232ms
Wed Oct 16 12:04:03.751 [conn30] CMD: drop test.jstests_update_arraymatch8
Wed Oct 16 12:04:03.925 [conn30] command test.$cmd command: { drop: "jstests_update_arraymatch8" } ntoreturn:1 keyUpdates:0 locks(micros) w:173841 reslen:138 173ms
Wed Oct 16 12:04:04.142 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:67 w:216191 216ms
Wed Oct 16 12:04:04.143 [conn30] CMD: drop test.jstests_update_arraymatch8
Wed Oct 16 12:04:04.317 [conn30] command test.$cmd command: { drop: "jstests_update_arraymatch8" } ntoreturn:1 keyUpdates:0 locks(micros) w:173694 reslen:138 173ms
Wed Oct 16 12:04:04.534 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:77 w:216103 216ms
Wed Oct 16 12:04:04.535 [conn30] CMD: drop test.jstests_update_arraymatch8
Wed Oct 16 12:04:04.717 [conn30] command test.$cmd command: { drop: "jstests_update_arraymatch8" } ntoreturn:1 keyUpdates:0 locks(micros) w:181918 reslen:138 181ms
Wed Oct 16 12:04:04.934 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:77 w:216104 216ms
Wed Oct 16 12:04:04.936 [conn30] CMD: drop test.jstests_update_arraymatch8
Wed Oct 16 12:04:05.118 [conn30] command test.$cmd command: { drop: "jstests_update_arraymatch8" } ntoreturn:1 keyUpdates:0 locks(micros) w:181382 reslen:138 181ms
Wed Oct 16 12:04:05.376 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:81 w:257541 257ms
Wed Oct 16 12:04:05.378 [conn30] CMD: drop test.jstests_update_arraymatch8
Wed Oct 16 12:04:05.594 [conn30] command test.$cmd command: { drop: "jstests_update_arraymatch8" } ntoreturn:1 keyUpdates:0 locks(micros) w:216872 reslen:138 216ms
Wed Oct 16 12:04:05.869 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:77 w:274435 274ms
Wed Oct 16 12:04:06.161 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:49 w:291356 291ms
Wed Oct 16 12:04:06.163 [conn30] CMD: drop test.loadersparse
Wed Oct 16 12:04:06.164 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loadersparse", indexes: [ { key: { a: 1.0 }, name: "a_1", sparse: true } ], options: {} }
Wed Oct 16 12:04:06.378 [conn30] command test.$cmd command: { beginLoad: 1.0, ns: "loadersparse", indexes: [ { key: { a: 1.0 }, name: "a_1", sparse: true } ], options: {} } ntoreturn:1 keyUpdates:0 locks(micros) w:214022 reslen:53 214ms
Wed Oct 16 12:04:06.621 [conn30] command test.$cmd command: { commitTransaction: 1.0 } ntoreturn:1 keyUpdates:0 reslen:71 191ms
Wed Oct 16 12:04:06.623 [conn30] CMD: drop test.loadersparse
Wed Oct 16 12:04:06.821 [conn30] command test.$cmd command: { drop: "loadersparse" } ntoreturn:1 keyUpdates:0 locks(micros) w:198308 reslen:124 198ms
Wed Oct 16 12:04:06.822 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loadersparse", indexes: [ { key: { e: 1.0 }, name: "a_1", sparse: true } ], options: {} }
Wed Oct 16 12:04:07.187 [conn30] command test.$cmd command: { beginLoad: 1.0, ns: "loadersparse", indexes: [ { key: { e: 1.0 }, name: "a_1", sparse: true } ], options: {} } ntoreturn:1 keyUpdates:0 locks(micros) w:365000 reslen:53 365ms
Wed Oct 16 12:04:07.496 [conn30] command test.$cmd command: { commitTransaction: 1.0 } ntoreturn:1 keyUpdates:0 reslen:71 250ms
Wed Oct 16 12:04:07.499 [conn30] CMD: drop test.loadermultikey
Wed Oct 16 12:04:07.499 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loadermultikey", indexes: [ { key: { a: 1.0 }, name: "a_1" } ], options: {} }
Wed Oct 16 12:04:07.637 [conn30] command test.$cmd command: { beginLoad: 1.0, ns: "loadermultikey", indexes: [ { key: { a: 1.0 }, name: "a_1" } ], options: {} } ntoreturn:1 keyUpdates:0 locks(micros) w:138214 reslen:53 138ms
Wed Oct 16 12:04:07.930 [conn30] command test.$cmd command: { commitTransaction: 1.0 } ntoreturn:1 keyUpdates:0 reslen:71 224ms
Wed Oct 16 12:04:07.933 [conn30] CMD: drop test.loadernotmultikey
Wed Oct 16 12:04:07.933 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loadernotmultikey", indexes: [ { key: { a: 1.0 }, name: "a_1" } ], options: {} }
Wed Oct 16 12:04:08.088 [conn30] command test.$cmd command: { beginLoad: 1.0, ns: "loadernotmultikey", indexes: [ { key: { a: 1.0 }, name: "a_1" } ], options: {} } ntoreturn:1 keyUpdates:0 locks(micros) w:155116 reslen:53 155ms
Wed Oct 16 12:04:08.331 [conn30] command test.$cmd command: { commitTransaction: 1.0 } ntoreturn:1 keyUpdates:0 reslen:71 174ms
Wed Oct 16 12:04:08.333 [conn30] CMD: drop test.loaderhashedidx
Wed Oct 16 12:04:08.333 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loaderhashedidx", indexes: [ { key: { a: "hashed" }, name: "a_hashed" } ], options: {} }
Wed Oct 16 12:04:08.489 [conn30] command test.$cmd command: { beginLoad: 1.0, ns: "loaderhashedidx", indexes: [ { key: { a: "hashed" }, name: "a_hashed" } ], options: {} } ntoreturn:1 keyUpdates:0 locks(micros) w:155442 reslen:53 155ms
Wed Oct 16 12:04:08.697 [conn30] command test.$cmd command: { commitLoad: 1.0 } ntoreturn:1 keyUpdates:0 locks(micros) w:207735 reslen:57 207ms
Wed Oct 16 12:04:08.956 [conn30] command test.$cmd command: { commitTransaction: 1.0 } ntoreturn:1 keyUpdates:0 reslen:71 258ms
Wed Oct 16 12:04:08.958 [conn30] CMD: drop test.loaderhashedidx
Wed Oct 16 12:04:08.982 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loaderhashedidx", indexes: [ { key: { a: "hashed" }, name: "a_hashed" } ], options: {} }
Wed Oct 16 12:04:09.147 [conn30] command test.$cmd command: { beginLoad: 1.0, ns: "loaderhashedidx", indexes: [ { key: { a: "hashed" }, name: "a_hashed" } ], options: {} } ntoreturn:1 keyUpdates:0 locks(micros) w:165756 reslen:53 165ms
Wed Oct 16 12:04:09.398 [conn30] command test.$cmd command: { commitTransaction: 1.0 } ntoreturn:1 keyUpdates:0 reslen:71 149ms
Wed Oct 16 12:04:09.399 [conn30] CMD: drop test.loaderambiguousfieldnames
Wed Oct 16 12:04:09.399 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loaderambiguousfieldnames", indexes: [ { key: { a.0: 1.0 }, name: "a_1" } ], options: {} }
Wed Oct 16 12:04:09.540 [conn30] command test.$cmd command: { beginLoad: 1.0, ns: "loaderambiguousfieldnames", indexes: [ { key: { a.0: 1.0 }, name: "a_1" } ], options: {} } ntoreturn:1 keyUpdates:0 locks(micros) w:140414 reslen:53 140ms
Wed Oct 16 12:04:09.774 [conn30] command test.$cmd command: { commitTransaction: 1.0 } ntoreturn:1 keyUpdates:0 reslen:71 148ms
Wed Oct 16 12:04:09.775 [conn30] CMD: drop test.jstests_nin
Wed Oct 16 12:04:09.833 [conn30] CMD: drop test.jstests_nin
Wed Oct 16 12:04:09.957 [conn30] command test.$cmd command: { drop: "jstests_nin" } ntoreturn:1 keyUpdates:0 locks(micros) w:124023 reslen:123 124ms
Wed Oct 16 12:04:10.249 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:65 w:291630 291ms
Wed Oct 16 12:04:10.260 [conn30] CMD: drop test.group4
Wed Oct 16 12:04:10.312 [conn30] CMD: drop test.sort5
Wed Oct 16 12:04:10.874 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:34 w:499267 499ms
Wed Oct 16 12:04:10.875 [conn30] CMD: validate test.sort5
Wed Oct 16 12:04:10.876 [conn30] test.sort5 validate is a deprecated command, assuming everything is ok.
Wed Oct 16 12:04:11.066 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:44 w:189898 189ms
Wed Oct 16 12:04:11.067 [conn30] CMD: validate test.sort5
Wed Oct 16 12:04:11.067 [conn30] test.sort5 validate is a deprecated command, assuming everything is ok.
Wed Oct 16 12:04:11.068 [conn30] dropDatabase test
Wed Oct 16 12:04:11.070 [conn30] dropDatabase test
Wed Oct 16 12:04:11.070 [conn30] CMD: drop test.jstests_update_arraymatch7
Wed Oct 16 12:04:11.283 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:39 w:182800 182ms
Wed Oct 16 12:04:11.285 [conn30] CMD: drop test.stats1
Wed Oct 16 12:04:11.319 [conn30] CMD: drop test.jstests_exists9
Wed Oct 16 12:04:11.534 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:39 w:182635 182ms
Wed Oct 16 12:04:11.535 [conn30] CMD: drop test.jstests_exists9
Wed Oct 16 12:04:11.942 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:40 w:274003 274ms
Wed Oct 16 12:04:11.944 [conn30] CMD: drop test.jstests_exists9
Wed Oct 16 12:04:12.276 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:49 w:198847 198ms
Wed Oct 16 12:04:12.279 [conn30] CMD: drop test.ed_db_cursor2_ccvsal
Wed Oct 16 12:04:12.311 [conn30] CMD: drop test.jstests_uniqueness
Wed Oct 16 12:04:12.485 [conn30] update test.jstests_uniqueness query: { _id: 3.0 } update: { _id: 3.0 } nscanned:0 idhack:1 nupdated:1 upsert:1 keyUpdates:0 locks(micros) r:65 w:173687 173ms
Wed Oct 16 12:04:12.487 [conn30] CMD: drop test.jstests_uniqueness2
Wed Oct 16 12:04:12.777 [conn30] insert test.system.indexes keyUpdates:0 exception: E11000 duplicate key error, { : 3.0 } already exists in unique index code:11000 locks(micros) r:48 w:232606 232ms
Wed Oct 16 12:04:12.777 [conn30] CMD: drop test.jstests_uniqueness2
Wed Oct 16 12:04:13.077 [conn30] insert test.system.indexes keyUpdates:0 exception: E11000 duplicate key error, { : 3.0 } already exists in unique index code:11000 locks(micros) r:40 w:240959 241ms
Wed Oct 16 12:04:13.078 [conn30] CMD: drop test.jstests_uniqueness
Wed Oct 16 12:04:13.225 [conn30] command test.$cmd command: { drop: "jstests_uniqueness" } ntoreturn:1 keyUpdates:0 locks(micros) w:146636 reslen:130 146ms
Wed Oct 16 12:04:13.261 [conn30] CMD: drop test.jstests_uniqueness
Wed Oct 16 12:04:13.396 [conn30] CMD: drop test.jstests_all5
Wed Oct 16 12:04:13.396 [conn30] CMD: drop test.jstests_all5
Wed Oct 16 12:04:13.429 [conn30] CMD: drop test.jstests_all5
Wed Oct 16 12:04:13.629 [conn30] command test.$cmd command: { drop: "jstests_all5" } ntoreturn:1 keyUpdates:0 locks(micros) w:199481 reslen:124 199ms
Wed Oct 16 12:04:13.671 [conn30] CMD: drop test.jstests_all5
Wed Oct 16 12:04:13.845 [conn30] command test.$cmd command: { drop: "jstests_all5" } ntoreturn:1 keyUpdates:0 locks(micros) w:174448 reslen:124 174ms
Wed Oct 16 12:04:13.879 [conn30] CMD: drop test.jstests_all5
Wed Oct 16 12:04:14.104 [conn30] command test.$cmd command: { drop: "jstests_all5" } ntoreturn:1 keyUpdates:0 locks(micros) w:224463 reslen:124 224ms
Wed Oct 16 12:04:14.138 [conn30] CMD: drop test.jstests_all5
Wed Oct 16 12:04:14.254 [conn30] command test.$cmd command: { drop: "jstests_all5" } ntoreturn:1 keyUpdates:0 locks(micros) w:116096 reslen:124 116ms
Wed Oct 16 12:04:14.288 [conn30] CMD: drop test.jstests_all5
Wed Oct 16 12:04:14.531 [conn30] command test.$cmd command: { drop: "jstests_all5" } ntoreturn:1 keyUpdates:0 locks(micros) w:242733 reslen:124 242ms
Wed Oct 16 12:04:14.572 [conn30] CMD: drop test.arrayfind2
Wed Oct 16 12:04:14.606 [conn30] assertion 13020 with $all, can't mix $elemMatch and others ns:test.arrayfind2 query:{ a: { $all: [ 1.0, { $elemMatch: { x: 3.0 } } ] } }
Wed Oct 16 12:04:14.606 [conn30] ntoskip:0 ntoreturn:-1
Wed Oct 16 12:04:14.606 [conn30] problem detected during query over test.arrayfind2 : { $err: "with $all, can't mix $elemMatch and others", code: 13020 }
Wed Oct 16 12:04:14.607 [conn30] assertion 13020 with $all, can't mix $elemMatch and others ns:test.arrayfind2 query:{ a: { $all: [ /a/, { $elemMatch: { x: 3.0 } } ] } }
Wed Oct 16 12:04:14.607 [conn30] ntoskip:0 ntoreturn:-1
Wed Oct 16 12:04:14.607 [conn30] problem detected during query over test.arrayfind2 : { $err: "with $all, can't mix $elemMatch and others", code: 13020 }
Wed Oct 16 12:04:14.821 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:34 w:214123 214ms
Wed Oct 16 12:04:14.823 [conn30] assertion 13020 with $all, can't mix $elemMatch and others ns:test.arrayfind2 query:{ a: { $all: [ 1.0, { $elemMatch: { x: 3.0 } } ] } }
Wed Oct 16 12:04:14.823 [conn30] ntoskip:0 ntoreturn:-1
Wed Oct 16 12:04:14.823 [conn30] problem detected during query over test.arrayfind2 : { $err: "with $all, can't mix $elemMatch and others", code: 13020 }
Wed Oct 16 12:04:14.824 [conn30] assertion 13020 with $all, can't mix $elemMatch and others ns:test.arrayfind2 query:{ a: { $all: [ /a/, { $elemMatch: { x: 3.0 } } ] } }
Wed Oct 16 12:04:14.824 [conn30] ntoskip:0 ntoreturn:-1
Wed Oct 16 12:04:14.824 [conn30] problem detected during query over test.arrayfind2 : { $err: "with $all, can't mix $elemMatch and others", code: 13020 }
Wed Oct 16 12:04:15.038 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:34 w:213485 213ms
Wed Oct 16 12:04:15.039 [conn30] CMD: drop test.find_and_modify_server6582
Wed Oct 16 12:04:15.080 [conn30] CMD: drop test.find_and_modify_server6582
Wed Oct 16 12:04:15.188 [conn30] command test.$cmd command: { drop: "find_and_modify_server6582" } ntoreturn:1 keyUpdates:0 locks(micros) w:107729 reslen:138 107ms
Wed Oct 16 12:04:15.222 [conn30] CMD: drop test.find_and_modify_server6659
Wed Oct 16 12:04:15.264 [conn30] CMD: drop test.updatea
Wed Oct 16 12:04:15.307 [conn30] CMD: drop test.updatea
Wed Oct 16 12:04:15.439 [conn30] CMD: drop test.updatea
Wed Oct 16 12:04:15.573 [conn30] CMD: drop test.updatea
Wed Oct 16 12:04:15.680 [conn30] command test.$cmd command: { drop: "updatea" } ntoreturn:1 keyUpdates:0 locks(micros) w:107420 reslen:119 107ms
Wed Oct 16 12:04:15.723 [conn30] CMD: drop test.array_match1
Wed Oct 16 12:04:15.981 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:41 w:215864 215ms
Wed Oct 16 12:04:15.982 [conn30] CMD: drop test.array_match1
Wed Oct 16 12:04:16.098 [conn30] command test.$cmd command: { drop: "array_match1" } ntoreturn:1 keyUpdates:0 locks(micros) w:115899 reslen:124 115ms
Wed Oct 16 12:04:16.331 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:45 w:190768 190ms
Wed Oct 16 12:04:16.332 [conn30] CMD: drop test.jstests_indexl
Wed Oct 16 12:04:16.377 [conn30] CMD: drop test.jstests_indexl
Wed Oct 16 12:04:16.489 [conn30] command test.$cmd command: { drop: "jstests_indexl" } ntoreturn:1 keyUpdates:0 locks(micros) w:112828 reslen:126 112ms
Wed Oct 16 12:04:16.731 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:85 w:241300 241ms
Wed Oct 16 12:04:16.736 [conn30] CMD: drop test.count4
Wed Oct 16 12:04:16.965 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:57 w:185225 185ms
Wed Oct 16 12:04:16.967 [conn30] CMD: drop test.removetest
Wed Oct 16 12:04:17.365 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:60 w:397931 398ms
Wed Oct 16 12:04:17.395 [conn30] CMD: validate test.removetest
Wed Oct 16 12:04:17.395 [conn30] test.removetest validate is a deprecated command, assuming everything is ok.
Wed Oct 16 12:04:17.666 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:42 w:269783 269ms
Wed Oct 16 12:04:17.723 [conn30] CMD: validate test.removetest
Wed Oct 16 12:04:17.723 [conn30] test.removetest validate is a deprecated command, assuming everything is ok.
Wed Oct 16 12:04:17.724 [conn30] CMD: validate test.removetest
Wed Oct 16 12:04:17.724 [conn30] test.removetest validate is a deprecated command, assuming everything is ok.
Wed Oct 16 12:04:17.724 [conn30] CMD: drop test.inc2
Wed Oct 16 12:04:18.083 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:38 w:323843 323ms
Wed Oct 16 12:04:18.084 [conn30] CMD: drop test.scan_capped_id
Wed Oct 16 12:04:18.316 [conn30] command test.$cmd command: { create: "scan_capped_id", capped: true, size: 10000.0 } ntoreturn:1 keyUpdates:0 locks(micros) w:231641 reslen:37 231ms
Wed Oct 16 12:04:18.325 [conn30] CMD: drop test.jstests_array_match2
Wed Oct 16 12:04:18.360 [conn30] CMD: drop test.jstests_js4
Wed Oct 16 12:04:18.429 [conn30] CMD: drop test.jstests_js4
Wed Oct 16 12:04:18.550 [conn30] command test.$cmd command: { drop: "jstests_js4" } ntoreturn:1 keyUpdates:0 locks(micros) w:121168 reslen:123 121ms
Wed Oct 16 12:04:18.601 [conn30] CMD: validate test.jstests_js4
Wed Oct 16 12:04:18.601 [conn30] test.jstests_js4 validate is a deprecated command, assuming everything is ok.
Wed Oct 16 12:04:18.601 [conn30] CMD: drop test.getLogTest2
Wed Oct 16 12:04:19.634 [conn30] query test.getLogTest2 query: { SENTINEL: 1.0, $where: function () { sleep(1000); return true; } } ntoreturn:1 ntoskip:0 nscanned:1 keyUpdates:0 locks(micros) r:1000441 nreturned:1 reslen:60 1000ms
Wed Oct 16 12:04:20.635 [conn30] update test.getLogTest2 query: { SENTINEL: 1.0, $where: function () { sleep(1000); return true; } } update: { x: "x" } nscanned:1 nupdated:1 keyUpdates:0 locks(micros) r:1000385 1000ms
Wed Oct 16 12:04:20.643 [conn30] CMD: drop test.update_multikey_clustering
Wed Oct 16 12:04:20.850 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:89 w:206614 206ms
Wed Oct 16 12:04:20.854 [conn30] CMD: drop test.update_multikey_clustering
Wed Oct 16 12:04:21.025 [conn30] command test.$cmd command: { drop: "update_multikey_clustering" } ntoreturn:1 keyUpdates:0 locks(micros) w:171749 reslen:138 171ms
Wed Oct 16 12:04:21.242 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:34 w:183365 183ms
Wed Oct 16 12:04:21.245 [conn30] CMD: drop test.update_multikey_clustering
Wed Oct 16 12:04:21.418 [conn30] command test.$cmd command: { drop: "update_multikey_clustering" } ntoreturn:1 keyUpdates:0 locks(micros) w:172287 reslen:138 172ms
Wed Oct 16 12:04:21.651 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:82 w:232683 232ms
Wed Oct 16 12:04:21.654 [conn30] CMD: drop test.update_multikey_clustering
Wed Oct 16 12:04:21.826 [conn30] command test.$cmd command: { drop: "update_multikey_clustering" } ntoreturn:1 keyUpdates:0 locks(micros) w:172291 reslen:138 172ms
Wed Oct 16 12:04:22.076 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:39 w:208241 208ms
Wed Oct 16 12:04:22.079 [conn30] CMD: drop test.jstests_evale
Wed Oct 16 12:04:22.098 [conn30] CMD: drop test.jstests_queryoptimizer8
Wed Oct 16 12:04:22.310 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:67 w:211832 211ms
Wed Oct 16 12:04:22.510 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:45 w:199797 199ms
Wed Oct 16 12:04:22.588 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:22.588 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:22.629 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:22.944 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:47 w:183281 183ms
Wed Oct 16 12:04:22.949 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:23.169 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:220538 reslen:130 220ms
Wed Oct 16 12:04:23.204 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:23.544 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:34 w:208382 208ms
Wed Oct 16 12:04:23.549 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:23.720 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:171081 reslen:130 171ms
Wed Oct 16 12:04:23.755 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:23.870 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:114969 reslen:130 114ms
Wed Oct 16 12:04:24.388 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:37 w:483445 483ms
Wed Oct 16 12:04:24.392 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:24.588 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:196169 reslen:130 196ms
Wed Oct 16 12:04:24.631 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:24.793 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:162473 reslen:130 162ms
Wed Oct 16 12:04:24.947 [conn30] insert test.jstests_arrayfind8 ninserted:1 keyUpdates:0 locks(micros) r:78 w:152715 152ms
Wed Oct 16 12:04:25.189 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:34 w:241787 241ms
Wed Oct 16 12:04:25.194 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:25.389 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:195449 reslen:130 195ms
Wed Oct 16 12:04:25.432 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:25.547 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:115032 reslen:130 115ms
Wed Oct 16 12:04:25.797 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:42 w:216723 216ms
Wed Oct 16 12:04:25.802 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:25.981 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:178898 reslen:130 178ms
Wed Oct 16 12:04:26.016 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:26.181 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:165232 reslen:130 165ms
Wed Oct 16 12:04:26.298 [conn30] insert test.jstests_arrayfind8 ninserted:1 keyUpdates:0 locks(micros) r:70 w:116256 116ms
Wed Oct 16 12:04:26.648 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:44 w:350032 350ms
Wed Oct 16 12:04:26.653 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:26.848 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:195004 reslen:130 195ms
Wed Oct 16 12:04:26.892 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:27.007 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:114977 reslen:130 114ms
Wed Oct 16 12:04:27.240 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:36 w:191623 191ms
Wed Oct 16 12:04:27.246 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:27.441 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:195065 reslen:130 195ms
Wed Oct 16 12:04:27.442 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:27.690 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:78 w:247683 247ms
Wed Oct 16 12:04:27.695 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:27.891 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:196015 reslen:130 196ms
Wed Oct 16 12:04:27.934 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:28.049 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:115384 reslen:130 115ms
Wed Oct 16 12:04:28.401 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:39 w:309656 309ms
Wed Oct 16 12:04:28.405 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:28.583 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:178047 reslen:130 178ms
Wed Oct 16 12:04:28.618 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:28.933 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:34 w:183341 183ms
Wed Oct 16 12:04:28.938 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:29.109 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:170781 reslen:130 170ms
Wed Oct 16 12:04:29.143 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:29.567 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:35 w:291510 291ms
Wed Oct 16 12:04:29.570 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:29.743 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:172087 reslen:130 172ms
Wed Oct 16 12:04:29.777 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:30.093 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:36 w:183334 183ms
Wed Oct 16 12:04:30.098 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:30.268 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:170512 reslen:130 170ms
Wed Oct 16 12:04:30.303 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:30.618 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:33 w:183284 183ms
Wed Oct 16 12:04:30.623 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:30.860 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:237407 reslen:130 237ms
Wed Oct 16 12:04:30.895 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:31.236 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:36 w:208334 208ms
Wed Oct 16 12:04:31.240 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:31.478 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:237118 reslen:130 237ms
Wed Oct 16 12:04:31.529 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:31.853 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:32 w:200050 200ms
Wed Oct 16 12:04:31.859 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:32.087 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:227891 reslen:130 227ms
Wed Oct 16 12:04:32.138 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:32.253 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:115234 reslen:130 115ms
Wed Oct 16 12:04:32.520 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:33 w:233264 233ms
Wed Oct 16 12:04:32.525 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:32.837 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:312434 reslen:130 312ms
Wed Oct 16 12:04:32.872 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:33.212 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:37 w:208335 208ms
Wed Oct 16 12:04:33.216 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:34.029 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:813014 reslen:130 813ms
Wed Oct 16 12:04:34.082 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:34.214 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:131357 reslen:130 131ms
Wed Oct 16 12:04:34.447 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:38 w:183329 183ms
Wed Oct 16 12:04:34.453 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:34.831 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:378364 reslen:130 378ms
Wed Oct 16 12:04:34.866 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:35.181 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:33 w:183363 183ms
Wed Oct 16 12:04:35.186 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:35.373 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:187474 reslen:130 187ms
Wed Oct 16 12:04:35.408 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:35.757 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:39 w:216672 216ms
Wed Oct 16 12:04:35.761 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:35.957 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:195930 reslen:130 195ms
Wed Oct 16 12:04:35.992 [conn30] CMD: drop test.jstests_arrayfind8
Wed Oct 16 12:04:36.107 [conn30] command test.$cmd command: { drop: "jstests_arrayfind8" } ntoreturn:1 keyUpdates:0 locks(micros) w:115109 reslen:130 115ms
Wed Oct 16 12:04:36.324 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:42 w:183310 183ms
Wed Oct 16 12:04:36.330 [conn30] CMD: drop test.jstests_slow_in1
Wed Oct 16 12:04:36.574 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:68 w:243191 243ms
Wed Oct 16 12:04:36.575 [conn30] Count with ns: test.jstests_slow_in1 and query: { a: { $in: [ 1.0, 2.0, 3.0, 4.0, 5.0, 6.0, 7.0, 8.0, 9.0, 10.0, 11.0 ] }, b: { $in: [ 1.0, 2.0, 3.0, 4.0, 5.0, 6.0, 7.0, 8.0, 9.0, 10.0, 11.0 ] }, c: { $in: [ 1.0, 2.0, 3.0, 4.0, 5.0, 6.0, 7.0, 8.0, 9.0, 10.0, 11.0 ] }, d: { $in: [ 1.0, 2.0, 3.0, 4.0, 5.0, 6.0, 7.0, 8.0, 9.0, 10.0, 11.0 ] }, e: { $in: [ 1.0, 2.0, 3.0, 4.0, 5.0, 6.0, 7.0, 8.0, 9.0, 10.0, 11.0 ] }, f: { $in: [ 1.0, 2.0, 3.0, 4.0, 5.0, 6.0, 7.0, 8.0, 9.0, 10.0, 11.0 ] }, g: { $in: [ 1.0, 2.0, 3.0, 4.0, 5.0, 6.0, 7.0, 8.0, 9.0, 10.0, 11.0 ] } } failed with exception: 13385 combinatorial limit of $in partitioning of result set exceeded code: 13385
Wed Oct 16 12:04:36.576 [conn30] CMD: drop test.distinct2
Wed Oct 16 12:04:36.617 [conn30] CMD: drop test.distinct2
Wed Oct 16 12:04:36.724 [conn30] command test.$cmd command: { drop: "distinct2" } ntoreturn:1 keyUpdates:0 locks(micros) w:107723 reslen:121 107ms
Wed Oct 16 12:04:36.759 [conn30] CMD: drop test.index_check7
Wed Oct 16 12:04:37.149 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:32 w:343644 343ms
Wed Oct 16 12:04:37.342 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:41 w:191762 191ms
Wed Oct 16 12:04:37.344 [conn30] CMD: drop test.SERVER828Test
Wed Oct 16 12:04:37.709 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:34 w:205473 205ms
Wed Oct 16 12:04:37.901 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:65 w:191173 191ms
Wed Oct 16 12:04:37.940 [conn30] assertion 16354 Positional operator does not match the query specifier. ns:test.SERVER828Test query:{ group: 3.0, x.a: 2.0 }
Wed Oct 16 12:04:37.940 [conn30] problem detected during query over test.SERVER828Test : { $err: "Positional operator does not match the query specifier.", code: 16354 }
Wed Oct 16 12:04:37.940 [conn30] assertion 16354 Positional operator does not match the query specifier. ns:test.SERVER828Test query:{ query: { group: 3.0, x.a: 2.0 }, orderby: { x: 1.0 } }
Wed Oct 16 12:04:37.940 [conn30] problem detected during query over test.SERVER828Test : { $err: "Positional operator does not match the query specifier.", code: 16354 }
Wed Oct 16 12:04:37.941 [conn30] assertion 10053 You cannot currently mix including and excluding fields. Contact us if this is an issue. ns:test.SERVER828Test query:{ query: { group: 3.0, x.a: 2.0 }, orderby: { x: 1.0 } }
Wed Oct 16 12:04:37.941 [conn30] problem detected during query over test.SERVER828Test : { $err: "You cannot currently mix including and excluding fields. Contact us if this is an issue.", code: 10053 }
Wed Oct 16 12:04:37.941 [conn30] assertion 16346 Cannot specify more than one positional array element per query (currently unsupported). ns:test.SERVER828Test query:{ group: 3.0, x.a: 1.0, y.aa: 1.0 }
Wed Oct 16 12:04:37.941 [conn30] problem detected during query over test.SERVER828Test : { $err: "Cannot specify more than one positional array element per query (currently unsupported).", code: 16346 }
Wed Oct 16 12:04:37.941 [conn30] assertion 16354 Positional operator does not match the query specifier. ns:test.SERVER828Test query:{ group: 3.0 }
Wed Oct 16 12:04:37.941 [conn30] problem detected during query over test.SERVER828Test : { $err: "Positional operator does not match the query specifier.", code: 16354 }
Wed Oct 16 12:04:38.048 [conn30] CMD: drop test.ed_db_cursor6
Wed Oct 16 12:04:38.334 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:31 w:249990 250ms
Wed Oct 16 12:04:38.342 [conn30] CMD: drop test.index_arr1
Wed Oct 16 12:04:38.559 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:43 w:182965 183ms
Wed Oct 16 12:04:38.743 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:34 w:182268 182ms
Wed Oct 16 12:04:38.745 [conn30] CMD: drop test.jstests_js7
Wed Oct 16 12:04:38.746 [conn30] CMD: drop test.jstests_group6
Wed Oct 16 12:04:38.781 [conn30] CMD: drop test.jstests_group6
Wed Oct 16 12:04:38.910 [conn30] command test.$cmd command: { drop: "jstests_group6" } ntoreturn:1 keyUpdates:0 locks(micros) w:128894 reslen:126 128ms
Wed Oct 16 12:04:38.948 [conn30] CMD: drop test.jstests_group6
Wed Oct 16 12:04:39.081 [conn30] command test.$cmd command: { drop: "jstests_group6" } ntoreturn:1 keyUpdates:0 locks(micros) w:132887 reslen:126 132ms
Wed Oct 16 12:04:39.112 [conn30] CMD: drop test.capped9
Wed Oct 16 12:04:39.442 [conn30] command test.$cmd command: { create: "capped9", capped: true, size: 51200.0 } ntoreturn:1 keyUpdates:0 locks(micros) w:329435 reslen:37 329ms
Wed Oct 16 12:04:39.711 [conn30] insert test.system.users ninserted:1 keyUpdates:0 locks(micros) r:79 w:265609 265ms
Wed Oct 16 12:04:39.712 [conn30] authenticate db: test { authenticate: 1, nonce: "b0fb739eb3a65d9", user: "eliot", key: "62b9309e440896f3af8723fa8885bce8" }
Wed Oct 16 12:04:39.712 [conn30] authenticate db: test { authenticate: 1, nonce: "6bab630ffa2e3f0f", user: "eliot", key: "2fab758d484be0f12456db4f901a1932" }
Wed Oct 16 12:04:39.712 [conn30] auth: key mismatch eliot, ns:test
Wed Oct 16 12:04:39.714 [conn30] authenticate db: test { authenticate: 1, nonce: "8daf99180659281", user: "eliot", key: "2bc5d8215ace9baf3e4dbd9fe68ed180" }
Wed Oct 16 12:04:39.714 [conn30] auth: key mismatch eliot, ns:test
Wed Oct 16 12:04:39.714 [conn30] authenticate db: test { authenticate: 1, nonce: "b2c5f705ef75cfcb", user: "eliot", key: "a5484b8c22b9dc9a0b19aaafa514756d" }
Wed Oct 16 12:04:39.714 [conn30] authenticate db: test { authenticate: 1, nonce: "5e1e4157bc9100c0", user: "eliot", key: "d9e3ffa1c38bf46399e941e9b4883c72" }
Wed Oct 16 12:04:39.715 [conn30] authenticate db: test { authenticate: 1, nonce: "4c69b70e3afee7ad", user: "eliot", key: "86ec5f89e72bfbd9fd6dcda23fcb4d35" }
Wed Oct 16 12:04:39.715 [conn30] auth: couldn't find user eliot@test, test.system.users
Wed Oct 16 12:04:40.053 [conn30] insert admin.system.users ninserted:1 keyUpdates:0 locks(micros) r:96 337ms
Wed Oct 16 12:04:40.054 [conn30] authenticate db: admin { authenticate: 1, nonce: "ee2a369c51e73a7f", user: "super", key: "58505547ef551a829a407c724ab61b6d" }
Wed Oct 16 12:04:40.054 [conn30] authenticate db: admin { authenticate: 1, nonce: "3c204992b78dae4b", user: "super", key: "c52ffe15b2bc9268417bbcb4e72797e4" }
Wed Oct 16 12:04:40.054 [conn30] auth: key mismatch super, ns:admin
Wed Oct 16 12:04:40.055 [conn30] authenticate db: test { authenticate: 1.0, user: "eliot", nonce: "foo", key: "bar" }
Wed Oct 16 12:04:40.055 [conn30] auth: bad nonce received or getnonce not called. could be a driver bug or a security attack. db:test
Wed Oct 16 12:04:40.088 [conn30] CMD: drop test.ed_db_find2_oif
Wed Oct 16 12:04:40.137 [conn30] CMD: drop test.objid2
Wed Oct 16 12:04:40.179 [conn30] CMD: drop test.big_object1
Wed Oct 16 12:04:40.439 [conn30] insert test.big_object1 ninserted:1 keyUpdates:0 locks(micros) r:105397 105ms
Wed Oct 16 12:04:41.027 [conn30] insert test.big_object1 ninserted:1 keyUpdates:0 locks(micros) r:432359 432ms
0x6f2c83 0x6c778a 0x6c782c 0x6026e2 0x602eb5 0x6b3051 0x6c13b1 0x6b1dae 0x1d2eee3beef9
/home/leif/git/mongo/mongo(_ZN5mongo15printStackTraceERSo+0x23) [0x6f2c83]
/home/leif/git/mongo/mongo(_ZN5mongo11msgassertedEiPKc+0x9a) [0x6c778a]
/home/leif/git/mongo/mongo() [0x6c782c]
/home/leif/git/mongo/mongo(_ZNK5mongo7BSONObj14_assertInvalidEv+0x2e2) [0x6026e2]
/home/leif/git/mongo/mongo(_ZN5mongo14BSONObjBuilder3objEv+0x155) [0x602eb5]
/home/leif/git/mongo/mongo(_ZN5mongo7V8Scope9v8ToMongoEN2v86HandleINS1_6ObjectEEEi+0x261) [0x6b3051]
/home/leif/git/mongo/mongo(_ZN5mongo11mongoInsertEPNS_7V8ScopeERKN2v89ArgumentsE+0x331) [0x6c13b1]
/home/leif/git/mongo/mongo(_ZN5mongo7V8Scope10v8CallbackERKN2v89ArgumentsE+0xbe) [0x6b1dae]
[0x1d2eee3beef9]
Wed Oct 16 12:04:41.377 [conn30] CMD: drop test.big_object1
Wed Oct 16 12:04:41.711 [conn30] command test.$cmd command: { drop: "big_object1" } ntoreturn:1 keyUpdates:0 locks(micros) w:334182 reslen:123 334ms
Wed Oct 16 12:04:41.712 [conn30] CMD: drop test.type1
Wed Oct 16 12:04:41.929 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:42 w:181822 181ms
Wed Oct 16 12:04:41.931 [conn30] CMD: drop test.jstests_in3
Wed Oct 16 12:04:42.171 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:65 w:239561 239ms
Wed Oct 16 12:04:42.174 [conn30] CMD: drop test.jstests_arrayfind4
Wed Oct 16 12:04:42.404 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:40 w:191637 191ms
Wed Oct 16 12:04:42.407 [conn30] CMD: drop test.jstests_6202
Wed Oct 16 12:04:42.546 [conn30] insert test.jstests_6202 ninserted:1 keyUpdates:0 locks(micros) r:59 w:139609 139ms
Wed Oct 16 12:04:42.731 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:38 w:184537 184ms
Wed Oct 16 12:04:42.733 [conn30] CMD: drop test.jstests_6202
Wed Oct 16 12:04:42.907 [conn30] command test.$cmd command: { drop: "jstests_6202" } ntoreturn:1 keyUpdates:0 locks(micros) w:174099 reslen:124 174ms
Wed Oct 16 12:04:42.908 [conn30] CMD: drop test.loadnsexists
Wed Oct 16 12:04:42.941 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loadnsexists", indexes: {}, options: {} }
Wed Oct 16 12:04:42.943 [conn30] CMD: drop test.loadnsprovexists
Wed Oct 16 12:04:42.973 [initandlisten] connection accepted from 127.0.0.1:50080 #37 (6 connections now open)
Wed Oct 16 12:04:43.947 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loadnsprovexists", indexes: {}, options: {} }
Wed Oct 16 12:04:44.976 [conn37] end connection 127.0.0.1:50080 (5 connections now open)
Wed Oct 16 12:04:44.976 [conn30] CMD: drop test.loadnsprovdropped
Wed Oct 16 12:04:45.199 [conn30] insert test.loadnsprovdropped ninserted:1 keyUpdates:0 locks(micros) r:61 w:222651 222ms
Wed Oct 16 12:04:45.230 [initandlisten] connection accepted from 127.0.0.1:50086 #38 (6 connections now open)
Wed Oct 16 12:04:45.232 [conn38] CMD: drop test.loadnsprovdropped
Wed Oct 16 12:04:45.702 [conn38] command test.$cmd command: { drop: "loadnsprovdropped" } ntoreturn:1 keyUpdates:0 locks(micros) w:469849 reslen:129 469ms
Wed Oct 16 12:04:45.703 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loadnsprovdropped", indexes: {}, options: {} }
Wed Oct 16 12:04:47.703 [conn38] end connection 127.0.0.1:50086 (5 connections now open)
Wed Oct 16 12:04:49.704 [conn30] command test.$cmd command: { beginLoad: 1.0, ns: "loadnsprovdropped", indexes: {}, options: {} } ntoreturn:1 keyUpdates:0 lockNotGranted: { index: "test.ns", requestingTxnid: 4361205, blockingTxnid: 4361207, bounds: [ { ns: "test.loadnsprovdropped" }, { ns: "test.loadnsprovdropped" } ] } locks(micros) w:4000230 reslen:120 4000ms
Wed Oct 16 12:04:49.737 [conn30] CMD: drop test.loadbadindexes
Wed Oct 16 12:04:49.739 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loadbadindexes", indexes: [ { ns: "test.loadbadindexes", key: { a: 1.0 }, name: "a_1" }, "xyz" ], options: {} }
Wed Oct 16 12:04:49.740 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loadbadindexes", indexes: [ "xyz", { ns: "test.loadbadindexes", key: { a: 1.0 }, name: "a_1" } ], options: {} }
Wed Oct 16 12:04:49.741 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loadbadindexes", indexes: [ { ns: "test.thisisntright", key: { a: 1.0 }, name: "a_1" } ], options: {} }
Wed Oct 16 12:04:49.887 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loadbadindexes", indexes: [ { ns: "test.loadbadindexes", key: "xyz", name: "xyz_1" } ], options: {} }
Wed Oct 16 12:04:49.994 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loadbadindexes", indexes: [ { ns: "test.loadbadindexes", key: { a: 1.0 }, name: { thisisntright: 1.0 } } ], options: {} }
Wed Oct 16 12:04:50.102 [conn30] CMD: drop test.loadbadoptions
Wed Oct 16 12:04:50.107 [conn30] CMD: drop test.loadcappedfails
Wed Oct 16 12:04:50.107 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loadcappedfails", indexes: [ { ns: "test.loadcappedfails", key: { $_: 1.0 }, name: "$_1" } ], options: { capped: true, size: 1024.0 } }
Wed Oct 16 12:04:50.108 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loadcappedfails", indexes: [ { ns: "test.loadcappedfails", key: { a: 1.0 }, name: "$_1" } ], options: { capped: true, size: 1024.0 } }
Wed Oct 16 12:04:50.109 [conn30] CMD: drop test.loadnaturalfails
Wed Oct 16 12:04:50.110 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loadnaturalfails", indexes: [ { ns: "test.loadnaturalfails", key: { a: 1.0 }, name: "$_1" } ], options: { natural: 1.0 } }
Wed Oct 16 12:04:50.111 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "loadnaturalfails", indexes: [ { ns: "test.loadnaturalfails", key: { $_: 1.0 }, name: "$_1" }, { ns: "test.loadnaturalfails", key: { a: 1.0 }, name: "a_1" } ], options: { natural: 1.0 } }
Wed Oct 16 12:04:50.112 [conn30] dropDatabase test
Wed Oct 16 12:04:54.337 [conn30] command test.$cmd command: { dropDatabase: 1.0 } ntoreturn:1 keyUpdates:0 lockNotGranted: { index: "", requestingTxnid: 4361272, blockingTxnid: 4299201, bounds: [ "-infinity", "+infinity" ] } locks(micros) W:4224742 reslen:120 4224ms
Wed Oct 16 12:04:54.337 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "system.indexes", indexes: {}, options: {} }
Wed Oct 16 12:04:54.338 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "system.indexes", indexes: [ { ns: "test.system.indexes", key: { $_: 1.0 }, name: "$_1" } ], options: {} }
Wed Oct 16 12:04:54.338 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "system.namespaces", indexes: {}, options: {} }
Wed Oct 16 12:04:54.339 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "system.namespaces", indexes: [ { ns: "test.system.namespaces", key: { $_: 1.0 }, name: "$_1" } ], options: {} }
Wed Oct 16 12:04:54.339 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "system.profile", indexes: {}, options: {} }
Wed Oct 16 12:04:54.339 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "system.profile", indexes: [ { ns: "test.system.profile", key: { $_: 1.0 }, name: "$_1" } ], options: {} }
Wed Oct 16 12:04:54.340 [conn30] Beginning bulk load, cmd: { beginLoad: 1.0, ns: "system.profile", indexes: [ { ns: "test.system.profile", key: { $_: 1.0 }, name: "$_1" } ], options: { capped: true, size: 1024.0 } }
Wed Oct 16 12:04:54.340 [conn30] CMD: drop test.update_arraymatch3
Wed Oct 16 12:04:54.369 [conn30] CMD: drop test.jstests_rename5
Wed Oct 16 12:04:54.493 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:79 w:123773 123ms
Wed Oct 16 12:04:54.495 [conn30] CMD: drop test.jstests_rename5
Wed Oct 16 12:04:54.536 [conn30] CMD: drop test.jstests_rename5
Wed Oct 16 12:04:54.570 [conn30] CMD: drop test.find5
Wed Oct 16 12:04:54.605 [conn30] CMD: drop test.find5
Wed Oct 16 12:04:54.645 [conn30] CMD: drop test.find5
Wed Oct 16 12:04:54.678 [conn30] CMD: drop test.jstests_sortg
Wed Oct 16 12:04:54.901 [conn30] insert test.jstests_sortg ninserted:1 keyUpdates:0 locks(micros) r:137564 137ms
Wed Oct 16 12:04:54.943 [conn30] assertion 10128 too much data for sort() with no index. add an index or specify a smaller limit ns:test.jstests_sortg query:{ query: {}, orderby: { a: 1.0 } }
Wed Oct 16 12:04:54.943 [conn30] ntoskip:0 ntoreturn:1000
Wed Oct 16 12:04:54.943 [conn30] problem detected during query over test.jstests_sortg : { $err: "too much data for sort() with no index. add an index or specify a smaller limit", code: 10128 }
Wed Oct 16 12:04:54.970 [conn30] assertion 10128 too much data for sort() with no index. add an index or specify a smaller limit ns:test.jstests_sortg query:{ query: {}, orderby: { a: 1.0 }, $explain: true }
Wed Oct 16 12:04:54.970 [conn30] ntoskip:0 ntoreturn:1000
Wed Oct 16 12:04:54.970 [conn30] problem detected during query over test.jstests_sortg : { $err: "too much data for sort() with no index. add an index or specify a smaller limit", code: 10128 }
Wed Oct 16 12:04:54.994 [conn30] assertion 10128 too much data for sort() with no index. add an index or specify a smaller limit ns:test.jstests_sortg query:{ query: {}, orderby: { b: 1.0 } }
Wed Oct 16 12:04:54.994 [conn30] ntoskip:0 ntoreturn:1000
Wed Oct 16 12:04:54.994 [conn30] problem detected during query over test.jstests_sortg : { $err: "too much data for sort() with no index. add an index or specify a smaller limit", code: 10128 }
Wed Oct 16 12:04:55.017 [conn30] assertion 10128 too much data for sort() with no index. add an index or specify a smaller limit ns:test.jstests_sortg query:{ query: {}, orderby: { b: 1.0 }, $explain: true }
Wed Oct 16 12:04:55.017 [conn30] ntoskip:0 ntoreturn:1000
Wed Oct 16 12:04:55.017 [conn30] problem detected during query over test.jstests_sortg : { $err: "too much data for sort() with no index. add an index or specify a smaller limit", code: 10128 }
Wed Oct 16 12:04:55.444 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:51 w:286721 286ms
Wed Oct 16 12:04:55.608 [conn30] assertion 10128 too much data for sort() with no index. add an index or specify a smaller limit ns:test.jstests_sortg query:{ query: { b: null, c: null }, orderby: { d: 1.0 } }
Wed Oct 16 12:04:55.608 [conn30] ntoskip:0 ntoreturn:1000
Wed Oct 16 12:04:55.608 [conn30] problem detected during query over test.jstests_sortg : { $err: "too much data for sort() with no index. add an index or specify a smaller limit", code: 10128 }
Wed Oct 16 12:04:55.622 [conn30] assertion 10128 too much data for sort() with no index. add an index or specify a smaller limit ns:test.jstests_sortg query:{ query: { b: null, c: null }, orderby: { d: 1.0 }, $explain: true }
Wed Oct 16 12:04:55.622 [conn30] ntoskip:0 ntoreturn:1000
Wed Oct 16 12:04:55.622 [conn30] problem detected during query over test.jstests_sortg : { $err: "too much data for sort() with no index. add an index or specify a smaller limit", code: 10128 }
Wed Oct 16 12:04:55.853 [conn30] CMD: drop test.jstests_sortg
Wed Oct 16 12:04:55.859 [conn30] CMD: drop test.jstests_ord
Wed Oct 16 12:04:55.986 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:70 w:126966 127ms
Wed Oct 16 12:04:56.095 [conn30] insert test.system.indexes ninserted:1 keyUpdates:0 locks(micros) r:54 w:108084 108ms
Wed Oct 16 12:04:56.106 [conn30] CMD: dropIndexes test.jstests_ord
Wed Oct 16 12:04:56.106 [conn30] [tokumx] TokuMX: toku_c_close: Transaction cannot do work when child exists
Wed Oct 16 12:04:56.106 [conn30] Assertion: 16770:Error 22 (Invalid argument) from the ydb layer. You may have hit a bug. Check the error log for more details.
0xa820e3 0x9886bb 0x988bc7 0x984431 0x8489be 0x848b39 0x713e5e 0x917ecd 0x713e5e 0x8b9ef5 0x8b9f2d 0x8ba10b 0x91fb7e 0x91fbf9 0x713e5e 0x7cc656 0x7cc78b 0x7ccc35 0x89243e 0x826058
/home/leif/git/mongo/mongod(_ZN5mongo15printStackTraceERSo+0x23) [0xa820e3]
/home/leif/git/mongo/mongod(_ZN5mongo7storage21MsgAssertionExceptionC1EiRKSs+0x9b) [0x9886bb]
/home/leif/git/mongo/mongod(_ZN5mongo7storage15SystemExceptionC1EiiRKSs+0x27) [0x988bc7]
/home/leif/git/mongo/mongod(_ZN5mongo7storage16handle_ydb_errorEi+0x91) [0x984431]
/home/leif/git/mongo/mongod(_ZN5mongo11IndexCursorD1Ev+0xce) [0x8489be]
/home/leif/git/mongo/mongod(_ZN5mongo11IndexCursorD0Ev+0x9) [0x848b39]
/home/leif/git/mongo/mongod(_ZN5boost6detail15sp_counted_base7releaseEv+0x1e) [0x713e5e]
/home/leif/git/mongo/mongod(_ZN5boost6detail17sp_counted_impl_pIN5mongo15QueryPlanRunnerEE7disposeEv+0x3d) [0x917ecd]
/home/leif/git/mongo/mongod(_ZN5boost6detail15sp_counted_base7releaseEv+0x1e) [0x713e5e]
/home/leif/git/mongo/mongod(_ZNSt12_Destroy_auxILb0EE9__destroyIPN5boost10shared_ptrIN5mongo15QueryPlanRunnerEEEEEvT_S8_+0x35) [0x8b9ef5]
/home/leif/git/mongo/mongod(_ZNSt6vectorIN5boost10shared_ptrIN5mongo15QueryPlanRunnerEEESaIS4_EED1Ev+0x1d) [0x8b9f2d]
/home/leif/git/mongo/mongod(_ZN5mongo16MultiPlanScannerD1Ev+0x2b) [0x8ba10b]
/home/leif/git/mongo/mongod(_ZN5mongo24QueryOptimizerCursorImplD1Ev+0x5e) [0x91fb7e]
/home/leif/git/mongo/mongod(_ZN5mongo24QueryOptimizerCursorImplD0Ev+0x9) [0x91fbf9]
/home/leif/git/mongo/mongod(_ZN5boost6detail15sp_counted_base7releaseEv+0x1e) [0x713e5e]
/home/leif/git/mongo/mongod(_ZN5mongo12ClientCursorD1Ev+0xc6) [0x7cc656]
/home/leif/git/mongo/mongod(_ZN5mongo12ClientCursor14LockedIterator16deleteAndAdvanceEv+0x1b) [0x7cc78b]
/home/leif/git/mongo/mongod(_ZN5mongo12ClientCursor10invalidateERKNS_10StringDataE+0x135) [0x7ccc35]
/home/leif/git/mongo/mongod(_ZN5mongo16NamespaceDetails11dropIndexesERKNS_10StringDataES3_RSsRNS_14BSONObjBuilderEb+0x5e) [0x89243e]
/home/leif/git/mongo/mongod(_ZN5mongo14CmdDropIndexes3runERKSsRNS_7BSONObjEiRSsRNS_14BSONObjBuilderEb+0x598) [0x826058]
Wed Oct 16 12:04:56.111 [conn30] [tokumx] TokuMX: toku_c_close: Transaction cannot do work when child exists
Wed Oct 16 12:04:56.111 [conn30] Assertion: 16770:Error 22 (Invalid argument) from the ydb layer. You may have hit a bug. Check the error log for more details.
0xa820e3 0x9886bb 0x988bc7 0x984431 0x8489be 0x848b39 0x713e5e 0x917ecd 0x713e5e 0x8ba216 0x91fb7e 0x91fbf9 0x713e5e 0x7cc656 0x7cc78b 0x7ccc35 0x89243e 0x826058 0x81f228 0x8202c3
/home/leif/git/mongo/mongod(_ZN5mongo15printStackTraceERSo+0x23) [0xa820e3]
/home/leif/git/mongo/mongod(_ZN5mongo7storage21MsgAssertionExceptionC1EiRKSs+0x9b) [0x9886bb]
/home/leif/git/mongo/mongod(_ZN5mongo7storage15SystemExceptionC1EiiRKSs+0x27) [0x988bc7]
/home/leif/git/mongo/mongod(_ZN5mongo7storage16handle_ydb_errorEi+0x91) [0x984431]
/home/leif/git/mongo/mongod(_ZN5mongo11IndexCursorD1Ev+0xce) [0x8489be]
/home/leif/git/mongo/mongod(_ZN5mongo11IndexCursorD0Ev+0x9) [0x848b39]
/home/leif/git/mongo/mongod(_ZN5boost6detail15sp_counted_base7releaseEv+0x1e) [0x713e5e]
/home/leif/git/mongo/mongod(_ZN5boost6detail17sp_counted_impl_pIN5mongo15QueryPlanRunnerEE7disposeEv+0x3d) [0x917ecd]
/home/leif/git/mongo/mongod(_ZN5boost6detail15sp_counted_base7releaseEv+0x1e) [0x713e5e]
/home/leif/git/mongo/mongod(_ZN5mongo16MultiPlanScannerD1Ev+0x136) [0x8ba216]
/home/leif/git/mongo/mongod(_ZN5mongo24QueryOptimizerCursorImplD1Ev+0x5e) [0x91fb7e]
/home/leif/git/mongo/mongod(_ZN5mongo24QueryOptimizerCursorImplD0Ev+0x9) [0x91fbf9]
/home/leif/git/mongo/mongod(_ZN5boost6detail15sp_counted_base7releaseEv+0x1e) [0x713e5e]
/home/leif/git/mongo/mongod(_ZN5mongo12ClientCursorD1Ev+0xc6) [0x7cc656]
/home/leif/git/mongo/mongod(_ZN5mongo12ClientCursor14LockedIterator16deleteAndAdvanceEv+0x1b) [0x7cc78b]
/home/leif/git/mongo/mongod(_ZN5mongo12ClientCursor10invalidateERKNS_10StringDataE+0x135) [0x7ccc35]
/home/leif/git/mongo/mongod(_ZN5mongo16NamespaceDetails11dropIndexesERKNS_10StringDataES3_RSsRNS_14BSONObjBuilderEb+0x5e) [0x89243e]
/home/leif/git/mongo/mongod(_ZN5mongo14CmdDropIndexes3runERKSsRNS_7BSONObjEiRSsRNS_14BSONObjBuilderEb+0x598) [0x826058]
/home/leif/git/mongo/mongod(_ZN5mongo12_execCommandEPNS_7CommandERKSsRNS_7BSONObjEiRSsRNS_14BSONObjBuilderEb+0x28) [0x81f228]
/home/leif/git/mongo/mongod(_ZN5mongo7Command11execCommandEPS0_RNS_6ClientEiPKcRNS_7BSONObjERNS_14BSONObjBuilderEb+0xa53) [0x8202c3]
Wed Oct 16 12:04:56.116 terminate() called, printing stack (if implemented for platform):
0xa820e3 0x708da5 0x7f8992e36c46 0x7f8992e35d09 0x7f8992e368ba 0x7f8992bd17d3 0x7f8992bd1d07 0x917f42 0x713e5e 0x8ba216 0x91fb7e 0x91fbf9 0x713e5e 0x7cc656 0x7cc78b 0x7ccc35 0x89243e 0x826058 0x81f228 0x8202c3
/home/leif/git/mongo/mongod(_ZN5mongo15printStackTraceERSo+0x23) [0xa820e3]
/home/leif/git/mongo/mongod(_ZN5mongo11myterminateEv+0x45) [0x708da5]
/usr/lib/libstdc++.so.6(+0x5ec46) [0x7f8992e36c46]
/usr/lib/libstdc++.so.6(+0x5dd09) [0x7f8992e35d09]
/usr/lib/libstdc++.so.6(__gxx_personality_v0+0x52a) [0x7f8992e368ba]
/usr/lib/libgcc_s.so.1(+0xf7d3) [0x7f8992bd17d3]
/usr/lib/libgcc_s.so.1(_Unwind_Resume+0x57) [0x7f8992bd1d07]
/home/leif/git/mongo/mongod(_ZN5boost6detail17sp_counted_impl_pIN5mongo15QueryPlanRunnerEE7disposeEv+0xb2) [0x917f42]
/home/leif/git/mongo/mongod(_ZN5boost6detail15sp_counted_base7releaseEv+0x1e) [0x713e5e]
/home/leif/git/mongo/mongod(_ZN5mongo16MultiPlanScannerD1Ev+0x136) [0x8ba216]
/home/leif/git/mongo/mongod(_ZN5mongo24QueryOptimizerCursorImplD1Ev+0x5e) [0x91fb7e]
/home/leif/git/mongo/mongod(_ZN5mongo24QueryOptimizerCursorImplD0Ev+0x9) [0x91fbf9]
/home/leif/git/mongo/mongod(_ZN5boost6detail15sp_counted_base7releaseEv+0x1e) [0x713e5e]
/home/leif/git/mongo/mongod(_ZN5mongo12ClientCursorD1Ev+0xc6) [0x7cc656]
/home/leif/git/mongo/mongod(_ZN5mongo12ClientCursor14LockedIterator16deleteAndAdvanceEv+0x1b) [0x7cc78b]
/home/leif/git/mongo/mongod(_ZN5mongo12ClientCursor10invalidateERKNS_10StringDataE+0x135) [0x7ccc35]
/home/leif/git/mongo/mongod(_ZN5mongo16NamespaceDetails11dropIndexesERKNS_10StringDataES3_RSsRNS_14BSONObjBuilderEb+0x5e) [0x89243e]
/home/leif/git/mongo/mongod(_ZN5mongo14CmdDropIndexes3runERKSsRNS_7BSONObjEiRSsRNS_14BSONObjBuilderEb+0x598) [0x826058]
/home/leif/git/mongo/mongod(_ZN5mongo12_execCommandEPNS_7CommandERKSsRNS_7BSONObjEiRSsRNS_14BSONObjBuilderEb+0x28) [0x81f228]
/home/leif/git/mongo/mongod(_ZN5mongo7Command11execCommandEPS0_RNS_6ClientEiPKcRNS_7BSONObjERNS_14BSONObjBuilderEb+0xa53) [0x8202c3]
Wed Oct 16 12:04:56.120 Got signal: 6 (Aborted).
Wed Oct 16 12:04:56.177 Backtrace:
0xa820e3 0x708f42 0x7f899284c450 0x7f899284c3d9 0x7f899284d7d8 0x708daa 0x7f8992e36c46 0x7f8992e35d09 0x7f8992e368ba 0x7f8992bd17d3 0x7f8992bd1d07 0x917f42 0x713e5e 0x8ba216 0x91fb7e 0x91fbf9 0x713e5e 0x7cc656 0x7cc78b 0x7ccc35
/home/leif/git/mongo/mongod(_ZN5mongo15printStackTraceERSo+0x23) [0xa820e3]
/home/leif/git/mongo/mongod(_ZN5mongo10abruptQuitEi+0x102) [0x708f42]
/usr/lib/libc.so.6(+0x35450) [0x7f899284c450]
/usr/lib/libc.so.6(gsignal+0x39) [0x7f899284c3d9]
/usr/lib/libc.so.6(abort+0x148) [0x7f899284d7d8]
/home/leif/git/mongo/mongod(_ZN5mongo11myterminateEv+0x4a) [0x708daa]
/usr/lib/libstdc++.so.6(+0x5ec46) [0x7f8992e36c46]
/usr/lib/libstdc++.so.6(+0x5dd09) [0x7f8992e35d09]
/usr/lib/libstdc++.so.6(__gxx_personality_v0+0x52a) [0x7f8992e368ba]
/usr/lib/libgcc_s.so.1(+0xf7d3) [0x7f8992bd17d3]
/usr/lib/libgcc_s.so.1(_Unwind_Resume+0x57) [0x7f8992bd1d07]
/home/leif/git/mongo/mongod(_ZN5boost6detail17sp_counted_impl_pIN5mongo15QueryPlanRunnerEE7disposeEv+0xb2) [0x917f42]
/home/leif/git/mongo/mongod(_ZN5boost6detail15sp_counted_base7releaseEv+0x1e) [0x713e5e]
/home/leif/git/mongo/mongod(_ZN5mongo16MultiPlanScannerD1Ev+0x136) [0x8ba216]
/home/leif/git/mongo/mongod(_ZN5mongo24QueryOptimizerCursorImplD1Ev+0x5e) [0x91fb7e]
/home/leif/git/mongo/mongod(_ZN5mongo24QueryOptimizerCursorImplD0Ev+0x9) [0x91fbf9]
/home/leif/git/mongo/mongod(_ZN5boost6detail15sp_counted_base7releaseEv+0x1e) [0x713e5e]
/home/leif/git/mongo/mongod(_ZN5mongo12ClientCursorD1Ev+0xc6) [0x7cc656]
/home/leif/git/mongo/mongod(_ZN5mongo12ClientCursor14LockedIterator16deleteAndAdvanceEv+0x1b) [0x7cc78b]
/home/leif/git/mongo/mongod(_ZN5mongo12ClientCursor10invalidateERKNS_10StringDataE+0x135) [0x7ccc35]
113628.055096ms
TokuMX mongo shell v1.3.0-pre--mongodb-2.4.7
connecting to: 127.0.0.1:27999/test
null
setting random seed: 1381939383305
>>>>>>>>>>>>>>> skipping jstests/repl
>>>>>>>>>>>>>>> skipping jstests/auth
>>>>>>>>>>>>>>> skipping jstests/fsync.js
>>>>>>>>>>>>>>> skipping jstests/_runner_leak.js
>>>>>>>>>>>>>>> skipping jstests/bench_test1.js
>>>>>>>>>>>>>>> skipping jstests/_runner_leak_nojni.js
>>>>>>>>>>>>>>> skipping jstests/opcounters.js
>>>>>>>>>>>>>>> skipping jstests/replsets
>>>>>>>>>>>>>>> skipping jstests/evald.js
>>>>>>>>>>>>>>> skipping jstests/connections_opened.js
>>>>>>>>>>>>>>> skipping jstests/multiClient
>>>>>>>>>>>>>>> skipping jstests/notablescan.js
>>>>>>>>>>>>>>> skipping jstests/indexh.js
>>>>>>>>>>>>>>> skipping jstests/clone
>>>>>>>>>>>>>>> skipping jstests/_fail.js
>>>>>>>>>>>>>>> skipping jstests/indexb.js
>>>>>>>>>>>>>>> skipping jstests/perf
>>>>>>>>>>>>>>> skipping jstests/fail_point
>>>>>>>>>>>>>>> skipping jstests/currentop.js
>>>>>>>>>>>>>>> skipping jstests/disk
>>>>>>>>>>>>>>> skipping jstests/loglong.js
>>>>>>>>>>>>>>> skipping jstests/_runner.js
>>>>>>>>>>>>>>> skipping jstests/_tst.js
>>>>>>>>>>>>>>> skipping jstests/dur
>>>>>>>>>>>>>>> skipping jstests/fsync2.js
>>>>>>>>>>>>>>> skipping jstests/evalb.js
>>>>>>>>>>>>>>> skipping jstests/evalf.js
>>>>>>>>>>>>>>> skipping jstests/misc
>>>>>>>>>>>>>>> skipping jstests/drop2.js
>>>>>>>>>>>>>>> skipping jstests/mr3.js
>>>>>>>>>>>>>>> skipping jstests/dbadmin.js
>>>>>>>>>>>>>>> skipping jstests/parallel
>>>>>>>>>>>>>>> skipping jstests/libs
>>>>>>>>>>>>>>> skipping jstests/find_failing_tests.sh
>>>>>>>>>>>>>>> skipping jstests/dropdb_race.js
>>>>>>>>>>>>>>> skipping jstests/queryoptimizera.js
>>>>>>>>>>>>>>> skipping jstests/run_program1.js
>>>>>>>>>>>>>>> skipping jstests/profile1.js
>>>>>>>>>>>>>>> skipping jstests/tool
>>>>>>>>>>>>>>> skipping jstests/sharding
>>>>>>>>>>>>>>> skipping jstests/_lodeRunner.js
>>>>>>>>>>>>>>> skipping jstests/apitest_db.js
>>>>>>>>>>>>>>> skipping jstests/geo_disabled
>>>>>>>>>>>>>>> skipping jstests/slowWeekly
>>>>>>>>>>>>>>> skipping jstests/_runner_sharding.js
>>>>>>>>>>>>>>> skipping jstests/ssl
>>>>>>>>>>>>>>> skipping jstests/misc_disabled
>>>>>>>>>>>>>>> skipping jstests/multiVersion
>>>>>>>>>>>>>>> skipping jstests/killop.js
>>>>>>>>>>>>>>> skipping jstests/repl_disabled
>>>>>>>>>>>>>>> skipping jstests/aggregation
>>>>>>>>>>>>>>> skipping jstests/slowNightly
>>>>>>>>>>>>>>> skipping jstests/cursor8.js
>>>>>>>>>>>>>>> skipping jstests/quota
connecting to: 127.0.0.1:27999/test
connecting to: 127.0.0.1:27999/test
S2 Test : jstests/regex6.js ...
connecting to: 127.0.0.1:27999/test
connecting to: 127.0.0.1:27999/test
S1 Test : jstests/date2.js ...
S3 Test : jstests/update_multi4.js ...
S0 Test : jstests/orl.js ...
S1 Test : jstests/date2.js 476ms
S1 Test : jstests/txn_isolation.js ...
S0 Test : jstests/orl.js 725ms
S0 Test : jstests/update_multi5.js ...
Wed Oct 16 12:03:04.116 shell: started program /home/leif/git/mongo/mongo --eval db = db.getSiblingDB('test'); t = db.jstests_txn_isolation; r = db.runCommand("beginTransaction"); t.insert({a:"during"}); sleep(2000); r = db.runCommand({"commitTransaction":1}); assert(r.ok == 1); 127.0.0.1:27999/admin
sh25531| TokuMX mongo shell v1.3.0-pre--mongodb-2.4.7
sh25531| connecting to: 127.0.0.1:27999/admin
S0 Test : jstests/update_multi5.js 100ms
S0 Test : jstests/basic6.js ...
S0 Test : jstests/basic6.js 1ms
S0 Test : jstests/inc1.js ...
S2 Test : jstests/regex6.js 870ms
S2 Test : jstests/updatej.js ...
S2 Test : jstests/updatej.js 32ms
S2 Test : jstests/indexd.js ...
S0 Test : jstests/inc1.js 310ms
S0 Test : jstests/remove_multicursor6124.js ...
S2 Test : jstests/indexd.js 1292ms
S2 Test : jstests/not2.js ...
Wed Oct 16 12:03:06.369 shell: started program /home/leif/git/mongo/mongo --eval db = db.getSiblingDB('test'); t = db.jstests_txn_isolation; r = db.runCommand("beginTransaction", {"isolation":"readUncommitted"}); t.insert({a:"during"}); sleep(2000); r = db.runCommand({"commitTransaction":1}); assert(r.ok == 1); 127.0.0.1:27999/admin
sh25554| TokuMX mongo shell v1.3.0-pre--mongodb-2.4.7
sh25554| connecting to: 127.0.0.1:27999/admin
S0 Test : jstests/remove_multicursor6124.js 2109ms
S0 Test : jstests/lock_diag_size_limit.js ...
S3 Test : jstests/update_multi4.js 3943ms
S3 Test : jstests/or3.js ...
Wed Oct 16 12:03:08.563 shell: started program /home/leif/git/mongo/mongo --eval db = db.getSiblingDB('test'); t = db.jstests_txn_isolation; sleep(1000); r = db.runCommand("beginTransaction"); t.insert({a:"during"}); r = db.runCommand({"commitTransaction":1}); assert(r.ok == 1); 127.0.0.1:27999/admin
sh25583| TokuMX mongo shell v1.3.0-pre--mongodb-2.4.7
sh25583| connecting to: 127.0.0.1:27999/admin
S2 Test : jstests/not2.js 3061ms
S2 Test : jstests/indexg.js ...
S2 Test : jstests/indexg.js 993ms
S2 Test : jstests/queryoptimizer7.js ...
Wed Oct 16 12:03:10.898 shell: started program /home/leif/git/mongo/mongo --eval db = db.getSiblingDB('test'); t = db.jstests_txn_isolation; r = db.runCommand("beginTransaction"); t.insert({a:"during"}); sleep(10000); r = db.runCommand({"commitTransaction":1}); assert(r.ok == 1); 127.0.0.1:27999/admin
sh25607| TokuMX mongo shell v1.3.0-pre--mongodb-2.4.7
sh25607| connecting to: 127.0.0.1:27999/admin
S2 Test : jstests/queryoptimizer7.js 1943ms
S2 Test : jstests/update2.js ...
S2 Test : jstests/update2.js 5017ms
S2 Test : jstests/find_and_modify2.js ...
S3 Test : jstests/or3.js 9259ms
S3 Test : jstests/explain1.js ...
S2 Test : jstests/find_and_modify2.js 76ms
S2 Test : jstests/profile4.js ...
{
"user" : "jstests_profile4_user",
"readOnly" : false,
"pwd" : "b2f822e50e64926f59d0e7c13fe1c979",
"_id" : ObjectId("525eb8c4bba3d5dda85f5282")
}
S3 Test : jstests/explain1.js 409ms
S3 Test : jstests/mr_errorhandling.js ...
{
"timeLockedMicros" : {
"r" : NumberLong(76),
"w" : NumberLong(30542)
},
"timeAcquiringMicros" : {
"r" : NumberLong(6),
"w" : NumberLong(2)
}
}
{
"timeLockedMicros" : {
"r" : NumberLong(52),
"w" : NumberLong(0)
},
"timeAcquiringMicros" : {
"r" : NumberLong(4),
"w" : NumberLong(0)
}
}
{
"timeLockedMicros" : {
"r" : NumberLong(53),
"w" : NumberLong(0)
},
"timeAcquiringMicros" : {
"r" : NumberLong(2),
"w" : NumberLong(0)
}
}
S2 Test : jstests/profile4.js 2179ms
S2 Test : jstests/check_shard_index.js ...
Wed Oct 16 12:03:23.602 drop failed: {
"nIndexesWas" : 2,
"errmsg" : "exception: Lock not granted. Try restarting the transaction.",
"code" : 16759,
"ok" : 0
} at src/mongo/shell/collection.js:377
********** Parallel Test FAILED: Error: error loading js file: jstests/check_shard_index.js
Wed Oct 16 12:03:27.679 drop failed: {
"nIndexesWas" : 1,
"errmsg" : "exception: Lock not granted. Try restarting the transaction.",
"code" : 16759,
"ok" : 0
} at src/mongo/shell/collection.js:377
********** Parallel Test FAILED: Error: error loading js file: jstests/txn_isolation.js
assert: [2] != [1] are not equal : undefined
Error: Printing Stack Trace
at printStackTrace (src/mongo/shell/utils.js:37:15)
at doassert (src/mongo/shell/assert.js:6:5)
at Function.assert.eq (src/mongo/shell/assert.js:32:5)
at jstests/lock_diag_size_limit.js:25:8
at <anonymous>:7:61
at Function.Date.timeFunc (src/mongo/shell/types.js:27:17)
at <anonymous>:7:38
at Array.forEach (native)
at Number.__parallelTests__fun (<anonymous>:4:14)
at ____MongoToV8_newFunction_temp (<anonymous>:162:65)
Wed Oct 16 12:03:32.197 [2] != [1] are not equal : undefined at src/mongo/shell/assert.js:7
********** Parallel Test FAILED: Error: error loading js file: jstests/lock_diag_size_limit.js
S3 Test : jstests/mr_errorhandling.js 15310ms
S3 Test : jstests/eval7.js ...
S3 Test : jstests/eval7.js 20ms
S3 Test : jstests/mr2.js ...
{
"result" : "mr2_out",
"timeMillis" : 153,
"counts" : {
"input" : 2,
"emit" : 4,
"reduce" : 1,
"output" : 3
},
"ok" : 1,
}
{
"results" : [
{
"_id" : "a",
"value" : {
"totalSize" : 9,
"num" : 1,
"avg" : 9
}
},
{
"_id" : "b",
"value" : {
"totalSize" : 32,
"num" : 2,
"avg" : 16
}
},
{
"_id" : "c",
"value" : {
"totalSize" : 18,
"num" : 1,
"avg" : 18
}
}
],
"timeMillis" : 0,
"counts" : {
"input" : 2,
"emit" : 4,
"reduce" : 1,
"output" : 3
},
"ok" : 1,
}
{
"result" : "mr2_out",
"timeMillis" : 151,
"counts" : {
"input" : 2,
"emit" : 4,
"reduce" : 1,
"output" : 3
},
"ok" : 1,
}
{
"results" : [
{
"_id" : "a",
"value" : {
"totalSize" : 9,
"num" : 1,
"avg" : 9
}
},
{
"_id" : "b",
"value" : {
"totalSize" : 32,
"num" : 2,
"avg" : 16
}
},
{
"_id" : "c",
"value" : {
"totalSize" : 18,
"num" : 1,
"avg" : 18
}
}
],
"timeMillis" : 0,
"counts" : {
"input" : 2,
"emit" : 4,
"reduce" : 1,
"output" : 3
},
"ok" : 1,
}
S3 Test : jstests/mr2.js 957ms
S3 Test : jstests/idprojection.js ...
S3 Test : jstests/idprojection.js 2070ms
S3 Test : jstests/orp.js ...
S3 Test : jstests/orp.js 1499ms
S3 Test : jstests/update5.js ...
S3 Test : jstests/update5.js 2879ms
S3 Test : jstests/exists4.js ...
S3 Test : jstests/exists4.js 236ms
S3 Test : jstests/orm.js ...
Wed Oct 16 12:03:39.942 shell: started program /home/leif/git/mongo/mongo --eval db = db.getSiblingDB('test');for( i = 0; i < 15; ++i ) { sleep( 1000 ); db.jstests_orm.drop() } 127.0.0.1:27999/admin
sh25766| TokuMX mongo shell v1.3.0-pre--mongodb-2.4.7
sh25766| connecting to: 127.0.0.1:27999/admin
sh25766| false
S3 Test : jstests/orm.js 16140ms
S3 Test : jstests/ismaster.js ...
S3 Test : jstests/ismaster.js 2ms
S3 Test : jstests/multi2.js ...
S3 Test : jstests/multi2.js 477ms
S3 Test : jstests/binData.js ...
S3 Test : jstests/binData.js 0ms
S3 Test : jstests/mr_merge.js ...
S3 Test : jstests/mr_merge.js 1977ms
S3 Test : jstests/update_setOnInsert.js ...
S3 Test : jstests/update_setOnInsert.js 410ms
S3 Test : jstests/update3.js ...
S3 Test : jstests/update3.js 649ms
S3 Test : jstests/6072_touch.js ...
S3 Test : jstests/6072_touch.js 134ms
S3 Test : jstests/update_arraymatch8.js ...
S3 Test : jstests/update_arraymatch8.js 6433ms
S3 Test : jstests/loader_index.js ...
null
null
null
null
null
null
null
S3 Test : jstests/loader_index.js 3611ms
S3 Test : jstests/nin.js ...
S3 Test : jstests/nin.js 485ms
S3 Test : jstests/group4.js ...
S3 Test : jstests/group4.js 53ms
S3 Test : jstests/sort5.js ...
S3 Test : jstests/sort5.js 756ms
S3 Test : jstests/txn_rollback_dropdatabase.js ...
S3 Test : jstests/txn_rollback_dropdatabase.js 2ms
S3 Test : jstests/update_arraymatch7.js ...
S3 Test : jstests/update_arraymatch7.js 214ms
S3 Test : jstests/stats.js ...
S3 Test : jstests/stats.js 34ms
S3 Test : jstests/exists9.js ...
S3 Test : jstests/exists9.js 959ms
S3 Test : jstests/numberlong.js ...
S3 Test : jstests/numberlong.js 2ms
S3 Test : jstests/cursor2.js ...
S3 Test : jstests/cursor2.js 31ms
S3 Test : jstests/uniqueness.js ...
S3 Test : jstests/uniqueness.js 1086ms
S3 Test : jstests/all5.js ...
S3 Test : jstests/all5.js 1175ms
S3 Test : jstests/arrayfind2.js ...
S3 Test : jstests/arrayfind2.js 468ms
S3 Test : jstests/find_and_modify_server6582.js ...
S3 Test : jstests/find_and_modify_server6582.js 183ms
S3 Test : jstests/find_and_modify_server6659.js ...
S3 Test : jstests/find_and_modify_server6659.js 42ms
S3 Test : jstests/updatea.js ...
S3 Test : jstests/updatea.js 459ms
S3 Test : jstests/array_match1.js ...
S3 Test : jstests/array_match1.js 609ms
S3 Test : jstests/indexl.js ...
S3 Test : jstests/indexl.js 403ms
S3 Test : jstests/count4.js ...
S3 Test : jstests/count4.js 232ms
S3 Test : jstests/remove.js ...
S3 Test : jstests/remove.js 757ms
S3 Test : jstests/inc2.js ...
S3 Test : jstests/inc2.js 360ms
S3 Test : jstests/cappeda.js ...
S3 Test : jstests/cappeda.js 241ms
S3 Test : jstests/array_match2.js ...
S3 Test : jstests/array_match2.js 35ms
S3 Test : jstests/js4.js ...
S3 Test : jstests/js4.js 241ms
S3 Test : jstests/getlog2.js ...
Wed Oct 16 12:04:20.635 [conn30] update test.getLogTest2 query: { SENTINEL: 1.0, $where: function () { sleep(1000); return true; } } update: { x: "x" } nscanned:1 nupdated:1 keyUpdates:0 locks(micros) r:1000385 1000ms
Wed Oct 16 12:04:19.634 [conn30] query test.getLogTest2 query: { SENTINEL: 1.0, $where: function () { sleep(1000); return true; } } ntoreturn:1 ntoskip:0 nscanned:1 keyUpdates:0 locks(micros) r:1000441 nreturned:1 reslen:60 1000ms
Wed Oct 16 12:04:20.635 [conn30] update test.getLogTest2 query: { SENTINEL: 1.0, $where: function () { sleep(1000); return true; } } update: { x: "x" } nscanned:1 nupdated:1 keyUpdates:0 locks(micros) r:1000385 1000ms
S3 Test : jstests/getlog2.js 2041ms
S3 Test : jstests/update_multikey_clustering.js ...
S3 Test : jstests/update_multikey_clustering.js 1437ms
S3 Test : jstests/evale.js ...
S3 Test : jstests/evale.js 18ms
S3 Test : jstests/queryoptimizer8.js ...
S3 Test : jstests/queryoptimizer8.js 490ms
S3 Test : jstests/arrayfind8.js ...
S3 Test : jstests/arrayfind8.js 13743ms
S3 Test : jstests/in7.js ...
S3 Test : jstests/in7.js 245ms
S3 Test : jstests/distinct2.js ...
S3 Test : jstests/distinct2.js 183ms
S3 Test : jstests/index_check7.js ...
S3 Test : jstests/index_check7.js 584ms
S3 Test : jstests/elemMatchProjection.js ...
S3 Test : jstests/elemMatchProjection.js 704ms
S3 Test : jstests/cursor6.js ...
S3 Test : jstests/cursor6.js 294ms
S3 Test : jstests/index_arr1.js ...
S3 Test : jstests/index_arr1.js 402ms
S3 Test : jstests/shelltypes.js ...
ObjectId("525eb916bba3d5dda86026e8")
DBRef("test", "theid")
DBPointer("test", ObjectId("525eb916bba3d5dda86026e9"))
Timestamp(10, 20)
BinData(3,"VQ6EAOKbQdSnFkRmVUQAAA==")
BinData(3,"VQ6EAOKbQdSnFkRmVUQAAA==")
BinData(5,"VQ6EAOKbQdSnFkRmVUQAAA==")
BinData(4,"VQ6EAOKbQdSnFkRmVUQAAA==")
NumberLong(100)
NumberInt(100)
S3 Test : jstests/shelltypes.js 1ms
S3 Test : jstests/js7.js ...
S3 Test : jstests/js7.js 0ms
S3 Test : jstests/group6.js ...
S3 Test : jstests/group6.js 367ms
S3 Test : jstests/capped9.js ...
S3 Test : jstests/capped9.js 332ms
S3 Test : jstests/auth1.js ...
{
"user" : "eliot",
"readOnly" : false,
"pwd" : "c77dfd17271e0162e336ff0b6bd7f617",
"_id" : ObjectId("525eb917bba3d5dda8602709")
}
Error: 18 { code: 18, ok: 0.0, errmsg: "auth fails" }
{
"_id" : ObjectId("525eb917bba3d5dda8602709"),
"user" : "eliot",
"readOnly" : false,
"pwd" : "49b31fa4f3ba016d33b661f7f76cc493"
}
Error: 18 { code: 18, ok: 0.0, errmsg: "auth fails" }
Error: 18 { code: 18, ok: 0.0, errmsg: "auth fails" }
{
"user" : "super",
"readOnly" : false,
"pwd" : "98d3854ed5431970863fc3779c7d1cd9",
"_id" : ObjectId("525eb917bba3d5dda860270a")
}
Error: 18 { code: 18, ok: 0.0, errmsg: "auth fails" }
{
"user" : "eliot",
"readOnly" : false,
"pwd" : "0ecb8eb17e1735888b4fa8fc0865ded0",
"_id" : ObjectId("525eb918bba3d5dda860270b")
}
{
"user" : "",
"readOnly" : false,
"pwd" : "941f6b10ef5fa8a6eb26c6d11ad4a068",
"_id" : ObjectId("525eb918bba3d5dda860270c")
}
S3 Test : jstests/auth1.js 643ms
S3 Test : jstests/find2.js ...
S3 Test : jstests/find2.js 50ms
S3 Test : jstests/objid2.js ...
S3 Test : jstests/objid2.js 42ms
S3 Test : jstests/big_object1.js ...
Wed Oct 16 12:04:41.052 Assertion: 10334:BSONObj size: 17408196 (0xC4A00901) is invalid. Size must be between 0 and 16793600(16MB) First element: _id: 6.0
{
"ns" : "test.big_object1",
"count" : 6,
"nindexes" : 1,
"nindexesbeingbuilt" : 1,
"size" : 82,
"storageSize" : 0,
"totalIndexSize" : 0,
"totalIndexStorageSize" : 0,
"indexDetails" : [
{
"name" : "_id_",
"count" : 6,
"size" : 82,
"avgObjSize" : 14361773.5,
"storageSize" : 0,
"pageSize" : 4,
"readPageSize" : 0,
"compression" : "zlib",
"queries" : 0,
"nscanned" : 0,
"nscannedObjects" : 0,
"inserts" : 6,
"deletes" : 0
}
],
"ok" : 1
}
SUCCESS
S3 Test : jstests/big_object1.js 1533ms
S3 Test : jstests/type1.js ...
S3 Test : jstests/type1.js 219ms
S3 Test : jstests/in3.js ...
S3 Test : jstests/in3.js 242ms
S3 Test : jstests/arrayfind4.js ...
S3 Test : jstests/arrayfind4.js 233ms
S3 Test : jstests/6202.js ...
S3 Test : jstests/6202.js 501ms
S3 Test : jstests/loader_begin.js ...
"Cannot bulk load a collection that already exists."
Wed Oct 16 12:04:42.946 shell: started program /home/leif/git/mongo/mongo --eval db = db.getSiblingDB('test');db.runCommand({ beginTransaction: 1 });db.loadnsprovexists.insert({ prov: 1 });sleep(2000); db.runCommand({ commitTransaction: 1 }) 127.0.0.1:27999/admin
sh26197| TokuMX mongo shell v1.3.0-pre--mongodb-2.4.7
sh26197| connecting to: 127.0.0.1:27999/admin
"Cannot bulk load a collection that already exists."
sh26197| [object Object]
Wed Oct 16 12:04:45.203 shell: started program /home/leif/git/mongo/mongo --eval db = db.getSiblingDB('test');db.runCommand({ beginTransaction: 1 });db.loadnsprovdropped.drop(); sleep(2000); db.runCommand({ abortTransaction: 1 }) 127.0.0.1:27999/admin
sh26209| TokuMX mongo shell v1.3.0-pre--mongodb-2.4.7
sh26209| connecting to: 127.0.0.1:27999/admin
sh26209| [object Object]
"Lock not granted. Try restarting the transaction."
"The indexes field must be an array of index objects."
"Each index spec must be an object describing the index to be built"
"Each index spec must be an object describing the index to be built"
"Each index spec's ns field, if provided, must match the loaded ns."
"invalid parameter: expected an object (key)"
"Each index spec must have a string name field."
"The options field must be an object."
"The options field must be an object."
"The options field must be an object."
"Cannot bulk load a capped collection"
"Cannot bulk load a capped collection"
"Cannot bulk load a natural order collection"
"Cannot bulk load a natural order collection"
"Cannot bulk load a collection that already exists."
"Cannot bulk load a collection that already exists."
"Cannot bulk load a collection that already exists."
"Cannot bulk load a collection that already exists."
"Cannot bulk load a collection that already exists."
"Cannot bulk load a collection that already exists."
"Cannot bulk load a collection that already exists."
S3 Test : jstests/loader_begin.js 11433ms
S3 Test : jstests/update_arraymatch3.js ...
S3 Test : jstests/update_arraymatch3.js 29ms
S3 Test : jstests/rename5.js ...
S3 Test : jstests/rename5.js 200ms
S3 Test : jstests/objid7.js ...
S3 Test : jstests/objid7.js 0ms
S3 Test : jstests/find5.js ...
S3 Test : jstests/find5.js 108ms
S3 Test : jstests/sortg.js ...
S3 Test : jstests/sortg.js 1181ms
S3 Test : jstests/ord.js ...
Wed Oct 16 12:04:56.890 DBClientCursor::init call() failed
Wed Oct 16 12:04:56.890 Error: error doing query: failed at src/mongo/shell/collection.js:53
********** Parallel Test FAILED: Error: error loading js file: jstests/ord.js
assert: [0] != [4] are not equal : one or more tests failed
Error: Printing Stack Trace
at printStackTrace (src/mongo/shell/utils.js:37:15)
at doassert (src/mongo/shell/assert.js:6:5)
at Function.assert.eq (src/mongo/shell/assert.js:32:5)
at Function.assert.parallelTests (src/mongo/shell/utils.js:300:16)
at ParallelTester.run (src/mongo/shell/utils.js:157:16)
at /home/leif/git/mongo/jstests/parallel/basic.js:11:3
Wed Oct 16 12:04:56.892 [0] != [4] are not equal : one or more tests failed at src/mongo/shell/assert.js:7
failed to load: /home/leif/git/mongo/jstests/parallel/basic.js
test /home/leif/git/mongo/jstests/parallel/basic.js exited with status 253
restarting mongod...
running /home/leif/git/mongo/mongod --port 27999 --dbpath /data/db/sconsTests/ --setParameter enableTestCommands=1 --debug
Wed Oct 16 12:04:56.918 nssize is a deprecated parameter
Wed Oct 16 12:04:56.920 [initandlisten] TokuMX starting : pid=26300 port=27999 dbpath=/data/db/sconsTests/ 64-bit host=cavil
Wed Oct 16 12:04:56.920 [initandlisten] TokuMX mongod server v1.3.0-pre--mongodb-2.4.7, using TokuKV rev notokukvversion
Wed Oct 16 12:04:56.920 [initandlisten] git version: b241f06d9e94ceeaf351a2d8eaea785706842f41
Wed Oct 16 12:04:56.920 [initandlisten] build info: Linux cavil 3.11.4-1-ARCH #1 SMP PREEMPT Sat Oct 5 21:22:51 CEST 2013 x86_64 BOOST_LIB_VERSION=1_49
Wed Oct 16 12:04:56.920 [initandlisten] options: { dbpath: "/data/db/sconsTests/", debug: true, port: 27999, setParameter: [ "enableTestCommands=1" ] }
Wed Oct 16 12:04:57.023 [initandlisten] [tokumx] startup
Wed Oct 16 12:04:57 2013 Tokudb recovery starting in env /data/db/sconsTests/
Wed Oct 16 12:04:57 2013 Tokudb recovery scanning backward from 13608658
[Errno 111] Connection refused
Wed Oct 16 12:04:58 2013 Tokudb recovery bw_end_checkpoint at 13606496 timestamp 1381939489492556 xid 13606307 (bw_newer)
Wed Oct 16 12:04:58 2013 Tokudb recovery bw_begin_checkpoint at 13606307 timestamp 1381939483229416 (bw_between)
Wed Oct 16 12:04:58 2013 Tokudb recovery turning around at begin checkpoint 13606307 time 6263140
Wed Oct 16 12:04:58 2013 Tokudb recovery starts scanning forward to 13608658 from 13606307 left 2351 (fw_between)
[Errno 111] Connection refused
[Errno 111] Connection refused
Wed Oct 16 12:04:59 2013 Tokudb recovery has 2 live transactions
/home/leif/git/ft-index/ft/cachetable.cc:1376 cachetable_fetch_pair: Assertion `r == 0' failed (errno=2)
: No such file or directory
Backtrace: (Note: toku_do_assert=0x0x7f6fb18b3387)
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokuportability.so(+0xbf0d)[0x7f6fb18b2f0d]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokuportability.so(_Z24toku_do_assert_zero_failmPKcS0_S0_ii+0x0)[0x7f6fb18b31ef]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0x3d22e)[0x7f6fb194822e]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0x3dce7)[0x7f6fb1948ce7]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0x3de9b)[0x7f6fb1948e9b]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xa7042)[0x7f6fb19b2042]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xdc34a)[0x7f6fb19e734a]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xdc5bf)[0x7f6fb19e75bf]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xda6a9)[0x7f6fb19e56a9]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xda980)[0x7f6fb19e5980]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xdafd1)[0x7f6fb19e5fd1]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xad4cd)[0x7f6fb19b84cd]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xad317)[0x7f6fb19b8317]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xa43a9)[0x7f6fb19af3a9]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xa441b)[0x7f6fb19af41b]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xa5599)[0x7f6fb19b0599]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xa5a1e)[0x7f6fb19b0a1e]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0x74ca)[0x7f6fb19124ca]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0x8db6)[0x7f6fb1913db6]
/home/leif/git/mongo/mongod(_ZN5mongo7storage7startupEv+0x271)[0x9851a1]
/home/leif/git/mongo/mongod(_ZN5mongo14_initAndListenEi+0x3cd)[0x7100dd]
/home/leif/git/mongo/mongod(_ZN5mongo13initAndListenEi+0xf)[0x71048f]
/home/leif/git/mongo/mongod(main+0x2af)[0x6f7aff]
/usr/lib/libc.so.6(__libc_start_main+0xf5)[0x7f6fb0494bc5]
/home/leif/git/mongo/mongod[0x708ae9]
Engine status function not available
Memory usage:
Arena 0:
system bytes = 0
in use bytes = 0
Total (incl. mmap):
system bytes = 0
in use bytes = 0
max mmap regions = 0
max mmap bytes = 0
Wed Oct 16 12:05:00.006 Got signal: 6 (Aborted).
Wed Oct 16 12:05:00.007 Backtrace:
0xa820e3 0x708f42 0x7f6fb04a8450 0x7f6fb04a83d9 0x7f6fb04a97d8 0x7f6fb18b3100 0x7f6fb18b31ef 0x7f6fb194822e 0x7f6fb1948ce7 0x7f6fb1948e9b 0x7f6fb19b2042 0x7f6fb19e734a 0x7f6fb19e75bf 0x7f6fb19e56a9 0x7f6fb19e5980 0x7f6fb19e5fd1 0x7f6fb19b84cd 0x7f6fb19b8317 0x7f6fb19af3a9 0x7f6fb19af41b
/home/leif/git/mongo/mongod(_ZN5mongo15printStackTraceERSo+0x23) [0xa820e3]
/home/leif/git/mongo/mongod(_ZN5mongo10abruptQuitEi+0x102) [0x708f42]
/usr/lib/libc.so.6(+0x35450) [0x7f6fb04a8450]
/usr/lib/libc.so.6(gsignal+0x39) [0x7f6fb04a83d9]
/usr/lib/libc.so.6(abort+0x148) [0x7f6fb04a97d8]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokuportability.so(+0xc100) [0x7f6fb18b3100]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokuportability.so(_Z24toku_do_assert_zero_failmPKcS0_S0_ii+0) [0x7f6fb18b31ef]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0x3d22e) [0x7f6fb194822e]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0x3dce7) [0x7f6fb1948ce7]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0x3de9b) [0x7f6fb1948e9b]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xa7042) [0x7f6fb19b2042]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xdc34a) [0x7f6fb19e734a]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xdc5bf) [0x7f6fb19e75bf]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xda6a9) [0x7f6fb19e56a9]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xda980) [0x7f6fb19e5980]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xdafd1) [0x7f6fb19e5fd1]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xad4cd) [0x7f6fb19b84cd]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xad317) [0x7f6fb19b8317]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xa43a9) [0x7f6fb19af3a9]
/home/leif/git/mongo/src/third_party/tokukv/lib/libtokufractaltree.so(+0xa441b) [0x7f6fb19af41b]
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
[Errno 111] Connection refused
^C1 tests succeeded
7 tests didn't get run
Traceback (most recent call last):
File "buildscripts/smoke.py", line 1162, in <module>
main()
File "buildscripts/smoke.py", line 1151, in main
run_tests(tests)
File "buildscripts/smoke.py", line 692, in run_tests
master = mongod(small_oplog_rs=small_oplog_rs,small_oplog=small_oplog,no_journal=no_journal,no_preallocj=no_preallocj,auth=auth).__enter__()
File "buildscripts/smoke.py", line 147, in __enter__
self.start()
File "buildscripts/smoke.py", line 269, in start
if not self.did_mongod_start(self.port):
File "buildscripts/smoke.py", line 181, in did_mongod_start
time.sleep(1)
KeyboardInterrupt
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment