-
Type:
Bug
-
Resolution: Done
-
Priority:
Major - P3
-
Affects Version/s: None
-
Component/s: Testing Infrastructure
-
Environment:Linux 32-bit build slave
-
ALL
jstests/replsets/replset9.js is failing in 32-bit builds, apparently due to lack of address space. This test may need to be changed or skipped for 32-bit builds.
http://buildlogs.mongodb.org/Linux%2032-bit/builds/5219/test/replica%20sets/replset9.js
Sun Nov 11 22:42:03.084 [initandlisten] connection accepted from 127.0.0.1:47747 #32 (21 connections now open) MongoDB shell version: 2.3.1-pre- null ReplSetTest Starting Set ReplSetTest n is : 0 ReplSetTest n: 0 ports: [ 31000 ] 31000 number { "useHostName" : true, "oplogSize" : 400, "keyFile" : undefined, "port" : 31000, "noprealloc" : "", "smallfiles" : "", "rest" : "", "replSet" : "replset9tests", "dbpath" : "$set-$node", "restart" : undefined, "pathOpts" : { "node" : 0, "set" : "replset9tests" } } ReplSetTest Starting.... Resetting db path '/data/db/replset9tests-0' Sun Nov 11 22:42:03.531 shell: started program /mnt/slaves/Linux_32bit/mongo/mongod --oplogSize 400 --port 31000 --noprealloc --smallfiles --rest --replSet replset9tests --dbpath /data/db/replset9tests-0 m31000| note: noprealloc may hurt performance in many applications m31000| Sun Nov 11 22:42:03.571 m31000| Sun Nov 11 22:42:03.571 32-bit servers don't have journaling enabled by default. Please use --journal if you want durability. m31000| Sun Nov 11 22:42:03.571 m31000| Sun Nov 11 22:42:03.581 [initandlisten] MongoDB starting : pid=32366 port=31000 dbpath=/data/db/replset9tests-0 32-bit host=domU-12-31-39-01-70-B4 m31000| Sun Nov 11 22:42:03.581 [initandlisten] m31000| Sun Nov 11 22:42:03.581 [initandlisten] ** NOTE: This is a development version (2.3.1-pre-) of MongoDB. m31000| Sun Nov 11 22:42:03.581 [initandlisten] ** Not recommended for production. m31000| Sun Nov 11 22:42:03.581 [initandlisten] m31000| Sun Nov 11 22:42:03.581 [initandlisten] ** NOTE: This is a 32 bit MongoDB binary. m31000| Sun Nov 11 22:42:03.581 [initandlisten] ** 32 bit builds are limited to less than 2GB of data (or less with --journal). m31000| Sun Nov 11 22:42:03.581 [initandlisten] ** Note that journaling defaults to off for 32 bit and is currently off. m31000| Sun Nov 11 22:42:03.581 [initandlisten] ** See http://www.mongodb.org/display/DOCS/32+bit m31000| Sun Nov 11 22:42:03.581 [initandlisten] m31000| Sun Nov 11 22:42:03.581 [initandlisten] db version v2.3.1-pre-, pdfile version 4.5 m31000| Sun Nov 11 22:42:03.581 [initandlisten] git version: 889fd753e98dfb91970560c798c3b9c15bdb96f2 m31000| Sun Nov 11 22:42:03.581 [initandlisten] build info: Linux domU-12-31-39-01-70-B4 2.6.21.7-2.fc8xen #1 SMP Fri Feb 15 12:39:36 EST 2008 i686 BOOST_LIB_VERSION=1_49 m31000| Sun Nov 11 22:42:03.581 [initandlisten] options: { dbpath: "/data/db/replset9tests-0", noprealloc: true, oplogSize: 400, port: 31000, replSet: "replset9tests", rest: true, smallfiles: true } m31000| Sun Nov 11 22:42:03.581 [initandlisten] Unable to check for journal files due to: boost::filesystem::directory_iterator::construct: No such file or directory: "/data/db/replset9tests-0/journal" m31000| Sun Nov 11 22:42:03.622 [initandlisten] waiting for connections on port 31000 m31000| Sun Nov 11 22:42:03.623 [websvr] admin web console waiting for connections on port 32000 m31000| Sun Nov 11 22:42:03.623 [rsStart] replSet can't get local.system.replset config from self or any seed (EMPTYCONFIG) m31000| Sun Nov 11 22:42:03.623 [rsStart] replSet info you may need to run replSetInitiate -- rs.initiate() in the shell -- if that is not already done [ connection to domU-12-31-39-01-70-B4:31000 ] { "replSetInitiate" : { "_id" : "replset9tests", "members" : [ { "_id" : 0, "host" : "domU-12-31-39-01-70-B4:31000" } ] } } m31000| Sun Nov 11 22:42:03.734 [initandlisten] connection accepted from 127.0.0.1:53668 #1 (1 connection now open) m31000| Sun Nov 11 22:42:03.736 [conn1] replSet replSetInitiate admin command received from client m31000| Sun Nov 11 22:42:03.736 [conn1] replSet replSetInitiate config object parses ok, 1 members specified m31000| Sun Nov 11 22:42:03.737 [conn1] replSet replSetInitiate all members seem up m31000| Sun Nov 11 22:42:03.737 [conn1] ****** m31000| Sun Nov 11 22:42:03.737 [conn1] creating replication oplog of size: 400MB... m31000| Sun Nov 11 22:42:03.737 [FileAllocator] allocating new datafile /data/db/replset9tests-0/local.ns, filling with zeroes... m31000| Sun Nov 11 22:42:03.737 [FileAllocator] creating directory /data/db/replset9tests-0/_tmp m31000| Sun Nov 11 22:42:03.986 [FileAllocator] done allocating datafile /data/db/replset9tests-0/local.ns, size: 16MB, took 0.247 secs m31000| Sun Nov 11 22:42:03.993 [FileAllocator] allocating new datafile /data/db/replset9tests-0/local.0, filling with zeroes... m31000| Sun Nov 11 22:42:14.010 [FileAllocator] done allocating datafile /data/db/replset9tests-0/local.0, size: 512MB, took 10.016 secs m31000| Sun Nov 11 22:42:14.114 [conn1] ****** m31000| Sun Nov 11 22:42:14.114 [conn1] replSet info saving a newer config version to local.system.replset m31000| Sun Nov 11 22:42:14.115 [conn1] replSet saveConfigLocally done m31000| Sun Nov 11 22:42:14.115 [conn1] replSet replSetInitiate config now saved locally. Should come online in about a minute. m31000| Sun Nov 11 22:42:14.115 [conn1] command admin.$cmd command: { replSetInitiate: { _id: "replset9tests", members: [ { _id: 0.0, host: "domU-12-31-39-01-70-B4:31000" } ] } } ntoreturn:1 keyUpdates:0 locks(micros) W:10377931 reslen:112 10379ms m31000| Sun Nov 11 22:42:14.115 [rsStart] replSet I am domU-12-31-39-01-70-B4:31000 m31000| Sun Nov 11 22:42:14.115 [rsStart] replSet STARTUP2 { "info" : "Config now saved locally. Should come online in about a minute.", "ok" : 1 } m31000| Sun Nov 11 22:42:15.118 [rsSync] replSet SECONDARY m31000| Sun Nov 11 22:42:15.118 [rsMgr] replSet info electSelf 0 m31000| Sun Nov 11 22:42:15.119 [rsMgr] replSet PRIMARY inserting bigstrings m31000| Sun Nov 11 22:42:16.119 [FileAllocator] allocating new datafile /data/db/replset9tests-0/d.ns, filling with zeroes... m31000| Sun Nov 11 22:42:16.374 [FileAllocator] done allocating datafile /data/db/replset9tests-0/d.ns, size: 16MB, took 0.254 secs m31000| Sun Nov 11 22:42:16.382 [FileAllocator] allocating new datafile /data/db/replset9tests-0/d.0, filling with zeroes... m31000| Sun Nov 11 22:42:16.694 [FileAllocator] done allocating datafile /data/db/replset9tests-0/d.0, size: 16MB, took 0.311 secs m31000| Sun Nov 11 22:42:16.695 [conn1] build index d.c { _id: 1 } m31000| Sun Nov 11 22:42:16.696 [conn1] build index done. scanned 0 total records. 0.001 secs m31000| Sun Nov 11 22:42:16.697 [conn1] insert d.c keyUpdates:0 locks(micros) w:577418 577ms m31000| Sun Nov 11 22:42:17.153 [FileAllocator] allocating new datafile /data/db/replset9tests-0/d.1, filling with zeroes... m31000| Sun Nov 11 22:42:17.857 [FileAllocator] done allocating datafile /data/db/replset9tests-0/d.1, size: 32MB, took 0.703 secs m31000| Sun Nov 11 22:42:17.857 [conn1] insert d.c keyUpdates:0 locks(micros) w:704455 704ms m31000| Sun Nov 11 22:42:18.444 [FileAllocator] allocating new datafile /data/db/replset9tests-0/d.2, filling with zeroes... m31000| Sun Nov 11 22:42:19.774 [FileAllocator] done allocating datafile /data/db/replset9tests-0/d.2, size: 64MB, took 1.33 secs m31000| Sun Nov 11 22:42:19.774 [conn1] insert d.c keyUpdates:0 locks(micros) w:1330756 1330ms m31000| Sun Nov 11 22:42:20.441 [FileAllocator] allocating new datafile /data/db/replset9tests-0/d.3, filling with zeroes... m31000| Sun Nov 11 22:42:23.894 [FileAllocator] done allocating datafile /data/db/replset9tests-0/d.3, size: 128MB, took 3.453 secs m31000| Sun Nov 11 22:42:23.895 [conn1] insert d.c keyUpdates:0 locks(micros) w:3453768 3453ms m31000| Sun Nov 11 22:42:25.423 [FileAllocator] allocating new datafile /data/db/replset9tests-0/d.4, filling with zeroes... m31000| Sun Nov 11 22:42:33.547 [FileAllocator] done allocating datafile /data/db/replset9tests-0/d.4, size: 256MB, took 8.123 secs m31000| Sun Nov 11 22:42:33.548 [conn1] insert d.c keyUpdates:0 locks(micros) w:8124552 8124ms inserting x m31000| Sun Nov 11 22:42:36.221 [conn1] insert d.c keyUpdates:0 locks(micros) w:306046 153ms m31000| Sun Nov 11 22:42:36.405 [conn1] insert d.c keyUpdates:0 locks(micros) w:175718 175ms m31000| Sun Nov 11 22:42:36.539 [conn1] insert d.c keyUpdates:0 locks(micros) w:244884 122ms m31000| Sun Nov 11 22:42:36.659 [conn1] insert d.c keyUpdates:0 locks(micros) w:107700 107ms m31000| Sun Nov 11 22:42:36.922 [conn1] insert d.c keyUpdates:0 locks(micros) w:129079 129ms m31000| Sun Nov 11 22:42:37.334 [conn1] insert d.c keyUpdates:0 locks(micros) w:179438 179ms m31000| Sun Nov 11 22:42:37.448 [conn1] insert d.c keyUpdates:0 locks(micros) w:204731 102ms m31000| Sun Nov 11 22:42:37.684 [conn1] insert d.c keyUpdates:0 locks(micros) w:237945 119ms m31000| Sun Nov 11 22:42:37.860 [conn1] insert d.c keyUpdates:0 locks(micros) w:154256 154ms m31000| Sun Nov 11 22:42:37.981 [conn1] insert d.c keyUpdates:0 locks(micros) w:108819 108ms m31000| Sun Nov 11 22:42:38.198 [conn1] insert d.c keyUpdates:0 locks(micros) w:293607 146ms m31000| Sun Nov 11 22:42:38.769 [conn1] insert d.c keyUpdates:0 locks(micros) w:216836 216ms m31000| Sun Nov 11 22:42:39.189 [conn1] insert d.c keyUpdates:0 locks(micros) w:563210 281ms m31000| Sun Nov 11 22:42:39.441 [conn1] insert d.c keyUpdates:0 locks(micros) w:252617 252ms m31000| Sun Nov 11 22:42:39.788 [conn1] insert d.c keyUpdates:0 locks(micros) w:522547 261ms m31000| Sun Nov 11 22:42:40.329 [conn1] insert d.c keyUpdates:0 locks(micros) w:754448 504ms m31000| Sun Nov 11 22:42:40.651 [conn1] insert d.c keyUpdates:0 locks(micros) w:238246 238ms m31000| Sun Nov 11 22:42:40.915 [conn1] insert d.c keyUpdates:0 locks(micros) w:456154 228ms m31000| Sun Nov 11 22:42:41.336 [conn1] insert d.c keyUpdates:0 locks(micros) w:670010 335ms m31000| Sun Nov 11 22:42:41.652 [conn1] insert d.c keyUpdates:0 locks(micros) w:231502 231ms m31000| Sun Nov 11 22:42:41.853 [conn1] insert d.c keyUpdates:0 locks(micros) w:375312 187ms m31000| Sun Nov 11 22:42:42.448 [conn1] insert d.c keyUpdates:0 locks(micros) w:797436 584ms m31000| Sun Nov 11 22:42:42.791 [conn1] insert d.c keyUpdates:0 locks(micros) w:269308 269ms m31000| Sun Nov 11 22:42:43.121 [conn1] insert d.c keyUpdates:0 locks(micros) w:281838 281ms m31000| Sun Nov 11 22:42:43.323 [conn1] insert d.c keyUpdates:0 locks(micros) w:219432 109ms m31000| Sun Nov 11 22:42:43.754 [conn1] insert d.c keyUpdates:0 locks(micros) w:104229 104ms m31000| Sun Nov 11 22:42:43.933 [conn1] insert d.c keyUpdates:0 locks(micros) w:106091 106ms m31000| Sun Nov 11 22:42:44.441 [FileAllocator] allocating new datafile /data/db/replset9tests-0/d.5, filling with zeroes... m31000| Sun Nov 11 22:42:56.147 [FileAllocator] done allocating datafile /data/db/replset9tests-0/d.5, size: 511MB, took 11.705 secs m31000| Sun Nov 11 22:42:56.147 [conn1] mmap() failed for /data/db/replset9tests-0/d.5 len:536608768 errno:12 Cannot allocate memory m31000| Sun Nov 11 22:42:56.147 [conn1] mmap failed with out of memory. You are using a 32-bit build and probably need to upgrade to 64 m31000| Sun Nov 11 22:42:56.229 [conn1] insert d.c keyUpdates:0 exception: can't map file memory - mongo requires 64 bit build for larger datasets code:10084 locks(micros) w:11788949 11788ms m31000| Sun Nov 11 22:42:56.230 [conn1] mmap() failed for /data/db/replset9tests-0/d.5 len:536608768 errno:12 Cannot allocate memory m31000| Sun Nov 11 22:42:56.230 [conn1] mmap failed with out of memory. You are using a 32-bit build and probably need to upgrade to 64 m31000| Sun Nov 11 22:42:56.230 [conn1] mmap() failed for /data/db/replset9tests-0/d.5 len:536608768 errno:12 Cannot allocate memory m31000| Sun Nov 11 22:42:56.230 [conn1] mmap failed with out of memory. You are using a 32-bit build and probably need to upgrade to 64 m31000| Sun Nov 11 22:42:56.230 [conn1] mmap() failed for /data/db/replset9tests-0/d.5 len:536608768 errno:12 Cannot allocate memory m31000| Sun Nov 11 22:42:56.230 [conn1] mmap failed with out of memory. You are using a 32-bit build and probably need to upgrade to 64 m31000| Sun Nov 11 22:42:56.230 [conn1] mmap() failed for /data/db/replset9tests-0/d.5 len:536608768 errno:12 Cannot allocate memory m31000| Sun Nov 11 22:42:56.230 [conn1] mmap failed with out of memory. You are using a 32-bit build and probably need to upgrade to 64 m31000| Sun Nov 11 22:42:56.231 [conn1] mmap() failed for /data/db/replset9tests-0/d.5 len:536608768 errno:12 Cannot allocate memory m31000| Sun Nov 11 22:42:56.231 [conn1] mmap failed with out of memory. You are using a 32-bit build and probably need to upgrade to 64 m31000| Sun Nov 11 22:42:56.231 [conn1] mmap() failed for /data/db/replset9tests-0/d.5 len:536608768 errno:12 Cannot allocate memory m31000| Sun Nov 11 22:42:56.231 [conn1] mmap failed with out of memory. You are using a 32-bit build and probably need to upgrade to 64 m31000| Sun Nov 11 22:42:56.231 [conn1] mmap() failed for /data/db/replset9tests-0/d.5 len:536608768 errno:12 Cannot allocate memory m31000| Sun Nov 11 22:42:56.231 [conn1] mmap failed with out of memory. You are using a 32-bit build and probably need to upgrade to 64 m31000| Sun Nov 11 22:42:56.231 [conn1] mmap() failed for /data/db/replset9tests-0/d.5 len:536608768 errno:12 Cannot allocate memory m31000| Sun Nov 11 22:42:56.231 [conn1] mmap failed with out of memory. You are using a 32-bit build and probably need to upgrade to 64 m31000| Sun Nov 11 22:42:56.232 [conn1] mmap() failed for /data/db/replset9tests-0/d.5 len:536608768 errno:12 Cannot allocate memory m31000| Sun Nov 11 22:42:56.232 [conn1] mmap failed with out of memory. You are using a 32-bit build and probably need to upgrade to 64 m31000| Sun Nov 11 22:42:56.232 [conn1] mmap() failed for /data/db/replset9tests-0/d.5 len:536608768 errno:12 Cannot allocate memory m31000| Sun Nov 11 22:42:56.232 [conn1] mmap failed with out of memory. You are using a 32-bit build and probably need to upgrade to 64 m31000| Sun Nov 11 22:42:56.232 [conn1] mmap() failed for /data/db/replset9tests-0/d.5 len:536608768 errno:12 Cannot allocate memory m31000| Sun Nov 11 22:42:56.232 [conn1] mmap failed with out of memory. You are using a 32-bit build and probably need to upgrade to 64 m31000| Sun Nov 11 22:42:56.233 [conn1] mmap() failed for /data/db/replset9tests-0/d.5 len:536608768 errno:12 Cannot allocate memory m31000| Sun Nov 11 22:42:56.233 [conn1] mmap failed with out of memory. You are using a 32-bit build and probably need to upgrade to 64 m31000| Sun Nov 11 22:42:56.233 [conn1] mmap() failed for /data/db/replset9tests-0/d.5 len:536608768 errno:12 Cannot allocate memory m31000| Sun Nov 11 22:42:56.233 [conn1] mmap failed with out of memory. You are using a 32-bit build and probably need to upgrade to 64 m31000| Sun Nov 11 22:42:56.233 [conn1] mmap() failed for /data/db/replset9tests-0/d.5 len:536608768 errno:12 Cannot allocate memory m31000| Sun Nov 11 22:42:56.233 [conn1] mmap failed with out of memory. You are using a 32-bit build and probably need to upgrade to 64
Later in the same test:
ReplSetTest waiting for connection to domU-12-31-39-01-70-B4:31001 to have an oplog built. m31001| Sun Nov 11 22:50:19.962 [rsSync] replSet initial sync pending m31001| Sun Nov 11 22:50:19.962 [rsSync] replSet syncing to: domU-12-31-39-01-70-B4:31000 m31000| Sun Nov 11 22:50:19.962 [initandlisten] connection accepted from 10.255.119.66:43144 #31 (3 connections now open) m31001| Sun Nov 11 22:50:19.963 [rsSync] replSet initial sync drop all databases m31001| Sun Nov 11 22:50:19.963 [rsSync] dropAllDatabasesExceptLocal 2 m31001| Sun Nov 11 22:50:20.286 [rsSync] replSet initial sync clone all databases ReplSetTest waiting for connection to domU-12-31-39-01-70-B4:31001 to have an oplog built. m31001| Sun Nov 11 22:50:20.287 [rsSync] replSet initial sync cloning db: d m31000| Sun Nov 11 22:50:20.287 [initandlisten] connection accepted from 10.255.119.66:43145 #32 (4 connections now open) m31001| Sun Nov 11 22:50:20.289 [FileAllocator] allocating new datafile /data/db/replset9tests-1/d.ns, filling with zeroes... m31001| Sun Nov 11 22:50:20.590 [FileAllocator] done allocating datafile /data/db/replset9tests-1/d.ns, size: 16MB, took 0.3 secs m31001| Sun Nov 11 22:50:20.598 [FileAllocator] allocating new datafile /data/db/replset9tests-1/d.0, filling with zeroes... m31001| Sun Nov 11 22:50:20.920 [FileAllocator] done allocating datafile /data/db/replset9tests-1/d.0, size: 16MB, took 0.32 secs m31001| Sun Nov 11 22:50:21.043 [FileAllocator] allocating new datafile /data/db/replset9tests-1/d.1, filling with zeroes... m31001| Sun Nov 11 22:50:21.709 [FileAllocator] done allocating datafile /data/db/replset9tests-1/d.1, size: 32MB, took 0.665 secs m31001| Sun Nov 11 22:50:21.865 [FileAllocator] allocating new datafile /data/db/replset9tests-1/d.2, filling with zeroes... m31001| Sun Nov 11 22:50:23.369 [FileAllocator] done allocating datafile /data/db/replset9tests-1/d.2, size: 64MB, took 1.504 secs ReplSetTest waiting for connection to domU-12-31-39-01-70-B4:31001 to have an oplog built. m31001| Sun Nov 11 22:50:23.679 [FileAllocator] allocating new datafile /data/db/replset9tests-1/d.3, filling with zeroes... ReplSetTest waiting for connection to domU-12-31-39-01-70-B4:31001 to have an oplog built. m31001| Sun Nov 11 22:50:26.399 [FileAllocator] done allocating datafile /data/db/replset9tests-1/d.3, size: 128MB, took 2.718 secs m31001| Sun Nov 11 22:50:26.843 [FileAllocator] allocating new datafile /data/db/replset9tests-1/d.4, filling with zeroes... m31001| Sun Nov 11 22:50:32.945 [FileAllocator] done allocating datafile /data/db/replset9tests-1/d.4, size: 256MB, took 6.101 secs ReplSetTest waiting for connection to domU-12-31-39-01-70-B4:31001 to have an oplog built. m31001| Sun Nov 11 22:50:33.960 [FileAllocator] allocating new datafile /data/db/replset9tests-1/d.5, filling with zeroes... m31001| Sun Nov 11 22:50:38.811 [conn16] end connection 10.255.119.66:43630 (1 connection now open) m31001| Sun Nov 11 22:50:38.812 [initandlisten] connection accepted from 10.255.119.66:53884 #17 (2 connections now open) m31001| Sun Nov 11 22:50:45.610 [FileAllocator] done allocating datafile /data/db/replset9tests-1/d.5, size: 511MB, took 11.649 secs ReplSetTest waiting for connection to domU-12-31-39-01-70-B4:31001 to have an oplog built. m31001| Sun Nov 11 22:50:45.610 [rsSync] mmap() failed for /data/db/replset9tests-1/d.5 len:536608768 errno:12 Cannot allocate memory m31001| Sun Nov 11 22:50:45.610 [rsSync] mmap failed with out of memory. You are using a 32-bit build and probably need to upgrade to 64 m31001| Sun Nov 11 22:50:45.612 [rsSync] error: exception cloning object in d.c can't map file memory - mongo requires 64 bit build for larger datasets obj:{ _id: ObjectId('50a0705b656384ba9446eb34'), bs: "aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa..." } m31001| Socket flush send() errno:9 Bad file descriptor 10.255.119.66:31000 m31001| Sun Nov 11 22:50:45.612 [rsSync] d caught exception (socket exception [SEND_ERROR] for 10.255.119.66:31000) in destructor (~PiggyBackData) m31001| Sun Nov 11 22:50:45.612 [rsSync] replSet initial sync exception: 10084 can't map file memory - mongo requires 64 bit build for larger datasets 2 attempts remaining m31000| Sun Nov 11 22:50:45.613 [conn32] SocketException handling request, closing client connection: 9001 socket exception [2] server [10.255.119.66:43145] m31000| Sun Nov 11 22:50:45.614 [conn31] end connection 10.255.119.66:43144 (2 connections now open)