-
Type: Bug
-
Resolution: Done
-
Priority: Major - P3
-
Affects Version/s: 2.5.0
-
Component/s: Testing Infrastructure
-
Environment:buildbot Windows 32-bit
buildbot Nightly Windows 32-bit
-
Fully Compatible
-
Windows
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.
- is related to
-
SERVER-10236 Make logging work during thread destruction
- Closed
-
SERVER-11826 DataFileSync thread should shutdown its Client cleanly
- Closed