[SERVER-11089] replsets/ismaster1.js failing on Windows 64-bit DEBUG Created: 08/Oct/13  Updated: 11/Jul/16  Resolved: 08/Oct/13

Status: Closed
Project: Core Server
Component/s: Testing Infrastructure
Affects Version/s: None
Fix Version/s: 2.5.3

Type: Bug Priority: Critical - P2
Reporter: Matt Kangas Assignee: Mathias Stearn
Resolution: Done Votes: 0
Labels: buildbot
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified

Backwards Compatibility: Fully Compatible
Operating System: ALL
Participants:

 Description   

6069172335a0 Windows 64-bit DEBUG replicasets

Failed on ismaster1.js: http://buildlogs.mongodb.org/mci_0.9_windows_64_debug/builds/1146/test/replicasets_0/ismaster1.js

Waiting for primary and replica set version 2
2013-10-08T16:23:40.302+0000 Socket recv() errno:10053 An established connection was aborted by the software in your host machine. 127.0.0.1:31000
2013-10-08T16:23:40.302+0000 SocketException: remote: 127.0.0.1:31000 error: 9001 socket exception [RECV_ERROR] server [127.0.0.1:31000] 
2013-10-08T16:23:40.302+0000 DBClientCursor::init call() failed
2013-10-08T16:23:40.304+0000 Error: error doing query: failed at src/mongo/shell/collection.js:54
failed to load: D:\data\mci\git@github.commongodb\mongo.git\master\jstests\replsets\ismaster1.js

Hmm, just above that was:

 m31000| 2013-10-08T16:23:40.294+0000 [rsHealthPoll] replSet member WIN-THU1H72T4UU:31002 is up
 m31000| 2013-10-08T16:23:40.294+0000 [rsHealthPoll] replSet member WIN-THU1H72T4UU:31002 is now in state SECONDARY
 m31000| 2013-10-08T16:23:40.294+0000 [rsHealthPoll] replSet member WIN-THU1H72T4UU:31003 is up
 m31000| 2013-10-08T16:23:40.294+0000 [rsMgr] can't see a majority of the set, relinquishing primary
 m31000| 2013-10-08T16:23:40.295+0000 [rsMgr] replSet relinquishing primary state
 m31000| 2013-10-08T16:23:40.295+0000 [rsMgr] replSet SECONDARY
 m31000| 2013-10-08T16:23:40.295+0000 [rsMgr] replSet closing client sockets after relinquishing primary
 m31000| 2013-10-08T16:23:40.295+0000 [conn17] end connection 10.158.30.205:62397 (6 connections now open)
 m31000| 2013-10-08T16:23:40.295+0000 [rsHealthPoll] replSet member WIN-THU1H72T4UU:31001 is up
 m31000| 2013-10-08T16:23:40.295+0000 [conn18] end connection 127.0.0.1:62398 (6 connections now open)
 m31000| 2013-10-08T16:23:40.295+0000 [rsHealthPoll] replSet member WIN-THU1H72T4UU:31003 is now in state ARBITER
 m31000| 2013-10-08T16:23:40.295+0000 [initandlisten] connection accepted from 10.158.30.205:62399 #19 (6 connections now open)
 m31000| 2013-10-08T16:23:40.295+0000 [rsHealthPoll] replSet member WIN-THU1H72T4UU:31001 is now in state SECONDARY
 m31001| 2013-10-08T16:23:40.296+0000 [rsBackgroundSync] Socket recv() errno:10053 An established connection was aborted by the software in your host machine. 10.158.30.205:31000
 m31001| 2013-10-08T16:23:40.296+0000 [rsBackgroundSync] SocketException: remote: 10.158.30.205:31000 error: 9001 socket exception [RECV_ERROR] server [10.158.30.205:31000] 
 m31001| 2013-10-08T16:23:40.297+0000 [rsBackgroundSync] DBClientCursor::init call() failed
 m31000| 2013-10-08T16:23:40.297+0000 [rsMgr] not electing self, WIN-THU1H72T4UU:31003 would veto with 'I don't think WIN-THU1H72T4UU:31000 is electable'
 m31000| 2013-10-08T16:23:40.298+0000 [initandlisten] connection accepted from 10.158.30.205:62400 #20 (7 connections now open)
 m31000| 2013-10-08T16:23:40.298+0000 [rsMgr] not electing self, WIN-THU1H72T4UU:31003 would veto with 'I don't think WIN-THU1H72T4UU:31000 is electable'
 m31001| 2013-10-08T16:23:40.298+0000 [rsBackgroundSync] replSet remoteOldestOp:    Oct 08 16:23:18 52543176:1
 m31001| 2013-10-08T16:23:40.298+0000 [rsBackgroundSync] replSet lastOpTimeFetched: Oct 08 16:23:40 5254318c:1
 m31000| 2013-10-08T16:23:40.298+0000 [conn19] end connection 10.158.30.205:62399 (6 connections now open)
 m31000| 2013-10-08T16:23:40.299+0000 [initandlisten] connection accepted from 10.158.30.205:62401 #21 (7 connections now open)
 m31000| 2013-10-08T16:23:40.299+0000 [conn20] query has no more but tailable, cursorid: 120435651255851

Suspiciously, there are no "Task logs" in the MCI display. But it has been failing intermittently:

Possibly introduced in d119fc2d92ce or eb09362ff499?

LAST GREEN BUILD on Oct 7 (rev cbe5d8c779)
http://mci.10gen.com/ui/task/mongodb_mongo_master_windows_64_debug_cbe5d8c77986e940f3bb5fccf7c63732b9b0e907_13_10_07_22_40_42_replicasets_windows_64



 Comments   
Comment by auto [ 08/Oct/13 ]

Author:

{u'username': u'RedBeard0531', u'name': u'Mathias Stearn', u'email': u'mathias@10gen.com'}

Message: SERVER-11089 Make test more resilient to reconnects on reconfig.
Branch: master
https://github.com/mongodb/mongo/commit/631d4312066b143eeec36d7ff2a91448ac53065b

Generated at Thu Feb 08 03:24:51 UTC 2024 using Jira 9.7.1#970001-sha1:2222b88b221c4928ef0de3161136cc90c8356a66.