Details
-
Bug
-
Resolution: Done
-
Major - P3
-
2.5.0
-
buildbot Windows 32-bit
buildbot Nightly Windows 32-bit
-
Fully Compatible
-
Windows
Description
jstests/replsets/sync_passive.js failed on Windows 32-bit:
http://buildbot.mongodb.org/builders/Windows%2032-bit/builds/5843/steps/test_9/logs/stdio
http://buildlogs.mongodb.org/Windows%2032-bit/builds/5843/test/replica%20sets/sync_passive.js
For some reason, the server on port 31000 didn't finish shutting down in a timely fashion, but the test proceeded anyway, announcing that the server had been stopped when it was in fact still running.
ReplSetTest stop *** Shutting down mongod in port 31000 ***
|
m31000| Mon Jun 03 06:36:00.024 [initandlisten] connection accepted from 127.0.0.1:57852 #13 (5 connections now open)
|
m31000| Mon Jun 03 06:36:00.024 [conn13] terminating, shutdown command received
|
m31000| Mon Jun 03 06:36:00.024 dbexit: shutdown called
|
m31000| Mon Jun 03 06:36:00.024 [conn13] shutdown: going to close listening sockets...
|
m31000| Mon Jun 03 06:36:00.024 [conn13] closing listening socket: 476
|
m31000| Mon Jun 03 06:36:00.024 [conn13] shutdown: going to flush diaglog...
|
m31000| Mon Jun 03 06:36:00.024 [conn13] shutdown: going to close sockets...
|
m31000| Mon Jun 03 06:36:00.024 [conn13] shutdown: waiting for fs preallocator...
|
m31000| Mon Jun 03 06:36:00.024 [conn13] shutdown: closing all files...
|
m31002| Mon Jun 03 06:36:00.024 [conn2] end connection 10.151.17.233:57822 (1 connection now open)
|
m31000| Mon Jun 03 06:36:00.024 [conn1] end connection 127.0.0.1:57815 (4 connections now open)
|
m31002| Mon Jun 03 06:36:00.024 [rsBackgroundSync] replSet db exception in producer: 10278 dbclient error communicating with server: EC2AMAZ-EXWX6MN:31000
|
m31000| Mon Jun 03 06:36:00.024 [conn6] end connection 10.151.17.233:57835 (4 connections now open)
|
m31002| Mon Jun 03 06:36:00.024 [rsSyncNotifier] replset tracking exception: exception: 10278 dbclient error communicating with server: EC2AMAZ-EXWX6MN:31000
|
Mon Jun 03 06:36:00.024 DBClientCursor::init call() failed
|
Mon Jun 03 06:36:01.038 shell: stopped mongo program on port 31000
|
// ... snip ...
|
ReplSetTest (Re)Starting....
|
Mon Jun 03 06:36:25.889 shell: started program mongod.exe --oplogSize 40 --port 31000 --noprealloc --smallfiles --rest --replSet sync_passive --dbpath /data/db/sync_passive-0 --setParameter enableTestCommands=1
|
m31000| note: noprealloc may hurt performance in many applications
|
m31000| Mon Jun 03 06:36:25.920
|
m31000| Mon Jun 03 06:36:25.920 warning: 32-bit servers don't have journaling enabled by default. Please use --journal if you want durability.
|
m31000| Mon Jun 03 06:36:25.920
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] MongoDB starting : pid=4804 port=31000 dbpath=/data/db/sync_passive-0 32-bit host=EC2AMAZ-EXWX6MN
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten]
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] ** NOTE: This is a development version (2.5.1-pre-) of MongoDB.
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] ** Not recommended for production.
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten]
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] ** NOTE: This is a 32 bit MongoDB binary.
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] ** 32 bit builds are limited to less than 2GB of data (or less with --journal).
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] ** Note that journaling defaults to off for 32 bit and is currently off.
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] ** See http://dochub.mongodb.org/core/32bit
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten]
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] db version v2.5.1-pre-
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] git version: 7652fb20c47fb3847a53bb2231ea23ee8e485623
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] build info: windows sys.getwindowsversion(major=6, minor=0, build=6002, platform=2, service_pack='Service Pack 2') BOOST_LIB_VERSION=1_49
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] allocator: system
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] options: { dbpath: "/data/db/sync_passive-0", noprealloc: true, oplogSize: 40, port: 31000, replSet: "sync_passive", rest: true, setParameter: [ "enableTestCommands=1" ], smallfiles: true }
|
m31000| **************
|
m31000| Unclean shutdown detected.
|
m31000| Please visit http://dochub.mongodb.org/core/repair for recovery instructions.
|
m31000| *************
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] exception in initAndListen: 12596 old lock file, terminating
|
m31000| Mon Jun 03 06:36:25.920 dbexit:
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] shutdown: going to close listening sockets...
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] shutdown: going to flush diaglog...
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] shutdown: going to close sockets...
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] shutdown: waiting for fs preallocator...
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] shutdown: closing all files...
|
m31000| Mon Jun 03 06:36:25.920 [initandlisten] closeAllFiles() finished
|
m31000| Mon Jun 03 06:36:25.920 dbexit: really exiting now
|
The shell announced:
Mon Jun 03 06:36:01.038 shell: stopped mongo program on port 31000
but the server on port 31000 had not printed "dbexit: really exiting now", so it was still running. This caused the restart to fail, causing the test to fail.
Attachments
Issue Links
- is related to
-
SERVER-10236 Make logging work during thread destruction
-
- Closed
-
-
SERVER-11826 DataFileSync thread should shutdown its Client cleanly
-
- Closed
-