[SERVER-560] preallocate.js fails due to first mongod instance unexpectedly allocating a 3rd file Created: 20/Jan/10  Updated: 12/Jul/16  Resolved: 25/Jan/10

Status: Closed
Project: Core Server
Component/s: None
Affects Version/s: None
Fix Version/s: 1.3.2

Type: Bug Priority: Minor - P4
Reporter: Aaron Staple Assignee: Aaron Staple
Resolution: Done Votes: 0
Labels: None
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified

Participants:

 Description   

shell: started mongo program /home/buildbot/slave/Linux_64bit/mongo/mongod --port 46120 --dbpath /data/db/jstests_preallocate
m46120| Wed Jan 20 21:50:46 Mongo DB : starting : pid = 17278 port = 46120 dbpath = /data/db/jstests_preallocate master = 0 slave = 0 64-bit
m46120| Wed Jan 20 21:50:46 db version v1.3.2-, pdfile version 4.5
m46120| Wed Jan 20 21:50:46 git version: 6a78423e5d777bd9fb4c077e1e6ae1a1afecd6fa
m46120| Wed Jan 20 21:50:46 sys info: Linux domU-12-31-39-06-79-A1 2.6.21.7-2.ec2.v1.2.fc8xen #1 SMP Fri Nov 20 17:48:28 EST 2009 x86_64 BOOST_LIB_VERSION=1_41
m46120| Wed Jan 20 21:50:46 waiting for connections on port 46120
m46120| Wed Jan 20 21:50:46 web admin interface listening on port 47120

m46120| Wed Jan 20 21:50:46 connection accepted from 127.0.0.1:33444 #1
m46120| Wed Jan 20 21:50:46 allocating new datafile /data/db/jstests_preallocate/jstests_preallocate.ns, filling with zeroes...
m46120| Wed Jan 20 21:50:46 done allocating datafile /data/db/jstests_preallocate/jstests_preallocate.ns, size: 16MB, took 0.032 secs
m46120| Wed Jan 20 21:50:46 allocating new datafile /data/db/jstests_preallocate/jstests_preallocate.0, filling with zeroes...
m46120| Wed Jan 20 21:50:46 done allocating datafile /data/db/jstests_preallocate/jstests_preallocate.0, size: 64MB, took 0.115 secs
m46120| Wed Jan 20 21:50:46 building new index on

{ _id: ObjId(000000000000000000000000) }

for jstests_preallocate.jstests_preallocate1...
m46120| Wed Jan 20 21:50:46 Buildindex jstests_preallocate.jstests_preallocate1 idxNo:0 { name: "id", ns: "jstests_preallocate.jstests_preallocate1", key:

{ _id: ObjId(000000000000000000000000) }

}
m46120| Wed Jan 20 21:50:46 done for 0 records 0secs
m46120| Wed Jan 20 21:50:46 query jstests_preallocate.$cmd ntoreturn:1 reslen:53 nscanned:0

{ create: "jstests_preallocate1", capped: undefined, size: undefined, max: undefined }

nreturned:1 150ms
m46120| Wed Jan 20 21:50:46 allocating new datafile /data/db/jstests_preallocate/jstests_preallocate.1, filling with zeroes...
m46120| Wed Jan 20 21:50:46 got kill or ctrl c signal 15 (Terminated), will terminate after current cmd ends
m46120| Wed Jan 20 21:50:46 now exiting
m46120| Wed Jan 20 21:50:46 dbexit:
m46120| Wed Jan 20 21:50:46 shutdown: going to close listening sockets...
m46120| Wed Jan 20 21:50:46 going to close listening socket: 13
m46120| Wed Jan 20 21:50:46 going to close listening socket: 15
m46120| Wed Jan 20 21:50:46 shutdown: going to flush oplog...
m46120| Wed Jan 20 21:50:46 shutdown: going to close sockets...
m46120| Wed Jan 20 21:50:46 shutdown: waiting for fs preallocator...
m46120| Wed Jan 20 21:50:46 done allocating datafile /data/db/jstests_preallocate/jstests_preallocate.1, size: 128MB, took 0.219 secs
m46120| Wed Jan 20 21:50:46 shutdown: closing all files...
m46120| Wed Jan 20 21:50:46 closeAllFiles() finished
m46120| Wed Jan 20 21:50:46 shutdown: removing fs lock...
m46120| Wed Jan 20 21:50:46 dbexit: really exiting now
shell: stopped mongo program on port 46120
shell: started mongo program /home/buildbot/slave/Linux_64bit/mongo/mongod --port 46120 --dbpath /data/db/jstests_preallocate
m46120| Wed Jan 20 21:50:47 Mongo DB : starting : pid = 17289 port = 46120 dbpath = /data/db/jstests_preallocate master = 0 slave = 0 64-bit
m46120| Wed Jan 20 21:50:51 db version v1.3.2-, pdfile version 4.5
m46120| Wed Jan 20 21:50:51 git version: 6a78423e5d777bd9fb4c077e1e6ae1a1afecd6fa
m46120| Wed Jan 20 21:50:51 sys info: Linux domU-12-31-39-06-79-A1 2.6.21.7-2.ec2.v1.2.fc8xen #1 SMP Fri Nov 20 17:48:28 EST 2009 x86_64 BOOST_LIB_VERSION=1_41
m46120| Wed Jan 20 21:50:51 waiting for connections on port 46120
m46120| Wed Jan 20 21:50:51 web admin interface listening on port 47120
m46120| Wed Jan 20 21:50:51 connection accepted from 127.0.0.1:33465 #1
m46120| Wed Jan 20 21:50:51 building new index on

{ _id: ObjId(000000000000000000000000) }

for jstests_preallocate.jstests_preallocate2...
m46120| Wed Jan 20 21:50:51 Buildindex jstests_preallocate.jstests_preallocate2 idxNo:0 { name: "id", ns: "jstests_preallocate.jstests_preallocate2", key:

{ _id: ObjId(000000000000000000000000) }

}
m46120| Wed Jan 20 21:50:51 done for 0 records 0.079secs
assert: [222] != [157] are not equal : undefined
Wed Jan 20 21:50:51 JS Error: uncaught exception: [222] != [157] are not equal : undefined
failed to load: /home/buildbot/slave/Linux_64bit/mongo/jstests/disk
m27018| Wed Jan 20 21:50:51 got kill or ctrl c signal 15 (Terminated), will terminate after current cmd ends
m27018| Wed Jan 20 21:50:51 now exiting
m27018| Wed Jan 20 21:50:51 dbexit:
m27018| Wed Jan 20 21:50:51 shutdown: going to close listening sockets...
m27018| Wed Jan 20 21:50:51 going to close listening socket: 10
m27018| Wed Jan 20 21:50:51 going to close listening socket: 12
m27018| Wed Jan 20 21:50:51 shutdown: going to flush oplog...
m27018| Wed Jan 20 21:50:51 shutdown: going to close sockets...
m27018| Wed Jan 20 21:50:51 shutdown: waiting for fs preallocator...
m27018| Wed Jan 20 21:50:51 shutdown: closing all files...
m27018| Wed Jan 20 21:50:51 closeAllFiles() finished
m27018| Wed Jan 20 21:50:51 shutdown: removing fs lock...
m27018| Wed Jan 20 21:50:51 dbexit: really exiting now
m46120| Wed Jan 20 21:50:52 got kill or ctrl c signal 15 (Terminated), will terminate after current cmd ends
m46120| Wed Jan 20 21:50:52 now exiting
m46120| Wed Jan 20 21:50:52 dbexit:
m46120| Wed Jan 20 21:50:52 shutdown: going to close listening sockets...
m46120| Wed Jan 20 21:50:52 going to close listening socket: 15
m46120| Wed Jan 20 21:50:52 going to close listening socket: 17
m46120| Wed Jan 20 21:50:52 shutdown: going to flush oplog...
m46120| Wed Jan 20 21:50:52 shutdown: going to close sockets...
m46120| Wed Jan 20 21:50:52 shutdown: waiting for fs preallocator...
m46120| Wed Jan 20 21:50:52 shutdown: closing all files...
m46120| Wed Jan 20 21:50:52 closeAllFiles() finished
m46120| Wed Jan 20 21:50:52 shutdown: removing fs lock...
m46120| Wed Jan 20 21:50:52 dbexit: really exiting now
scons: *** [smokeDisk] Error 253
scons: building terminated because of errors.
program finished with exit code 2

<http://buildbot.mongodb.org/builders/Linux%2064-bit/builds/8/steps/test/logs/stdio>



 Comments   
Comment by Aaron Staple [ 25/Jan/10 ]

Looks like there was a race condition related to the original measurement of vsize, should be fixed now.

Comment by auto [ 25/Jan/10 ]

Author:

{'login': 'astaple', 'name': 'Aaron', 'email': 'aaron@10gen.com'}

Message: SERVER-560 fix race condition in preallocate.js test
http://github.com/mongodb/mongo/commit/84e5a5d88b2bce95b56f7904958733cd5ac0f7e2

Generated at Thu Feb 08 02:54:31 UTC 2024 using Jira 9.7.1#970001-sha1:2222b88b221c4928ef0de3161136cc90c8356a66.