[SERVER-21298] rollback_index.js fails to wait for replication before continuing Created: 31/Oct/15  Updated: 17/Nov/15  Resolved: 04/Nov/15

Status: Closed
Project: Core Server
Component/s: None
Affects Version/s: None
Fix Version/s: 3.2.0-rc3

Type: Bug Priority: Major - P3
Reporter: Scott Hernandez (Inactive) Assignee: Matt Dannenberg
Resolution: Done Votes: 0
Labels: None
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified

Issue Links:
Depends
depends on SERVER-21237 ReplSetTest.prototype.awaitReplicatio... Closed
Backwards Compatibility: Fully Compatible
Operating System: ALL
Sprint: Repl C (11/20/15)
Participants:

 Description   

Cause by SERVER-21237

Not a real failure, just a test framework problem.



 Comments   
Comment by Githook User [ 04/Nov/15 ]

Author:

{u'username': u'milkie', u'name': u'Eric Milkie', u'email': u'milkie@10gen.com'}

Message: SERVER-21298 wait for SECONDARY state before calling count
Branch: master
https://github.com/mongodb/mongo/commit/e8d5fd065fcc04d289b9068971647948abb8d282

Comment by Spencer Brody (Inactive) [ 04/Nov/15 ]

Actually failing in multiple suites (replsets, replsets_auth, replsets_WT, replsets_WT_ese) across the enterprise-rhel-62-64-bit build variant

See https://evergreen.mongodb.com/build/mongodb_mongo_master_enterprise_rhel_62_64_bit_71e4c39d173c849a705b8eb0b29769a45a59d8a0_15_11_04_19_22_58

Comment by Spencer Brody (Inactive) [ 04/Nov/15 ]

Still failing
https://evergreen.mongodb.com/task/mongodb_mongo_master_enterprise_rhel_62_64_bit_replicasets_71e4c39d173c849a705b8eb0b29769a45a59d8a0_15_11_04_19_22_58

	
[js_test:rollback_index] 2015-11-04T20:22:17.525+0000 d20262| 2015-11-04T20:22:17.525+0000 I REPL     [ReplicationExecutor] Error in heartbeat request to ip-10-239-178-114:20261; HostUnreachable End of file
[js_test:rollback_index] 2015-11-04T20:22:17.526+0000 d20261| 2015-11-04T20:22:17.526+0000 I NETWORK  [initandlisten] connection accepted from 10.239.178.114:53053 #7 (3 connections now open)
[js_test:rollback_index] 2015-11-04T20:22:17.526+0000 d20262| 2015-11-04T20:22:17.526+0000 I ASIO     [NetworkInterfaceASIO] Successfully connected to ip-10-239-178-114:20261
[js_test:rollback_index] 2015-11-04T20:22:17.527+0000 d20262| 2015-11-04T20:22:17.526+0000 I REPL     [ReplicationExecutor] Member ip-10-239-178-114:20261 is now in state RECOVERING
[js_test:rollback_index] 2015-11-04T20:22:17.550+0000 ReplSetTest Could not call ismaster on node 1: Error: error doing query: failed
[js_test:rollback_index] 2015-11-04T20:22:17.552+0000 2015-11-04T20:22:17.552+0000 I NETWORK  [thread1] trying reconnect to 127.0.0.1:20261 (127.0.0.1) failed
[js_test:rollback_index] 2015-11-04T20:22:17.552+0000 d20261| 2015-11-04T20:22:17.552+0000 I NETWORK  [initandlisten] connection accepted from 127.0.0.1:50822 #8 (4 connections now open)
[js_test:rollback_index] 2015-11-04T20:22:17.553+0000 2015-11-04T20:22:17.553+0000 I NETWORK  [thread1] reconnect 127.0.0.1:20261 (127.0.0.1) ok
[js_test:rollback_index] 2015-11-04T20:22:17.555+0000 ReplSetTest awaitReplication: starting: timestamp for primary, ip-10-239-178-114:20260, is Timestamp(1446668533, 1), last oplog entry is Timestamp(1446668533, 1)
[js_test:rollback_index] 2015-11-04T20:22:17.555+0000 ReplSetTest awaitReplication: checking secondaries against timestamp Timestamp(1446668533, 1)
[js_test:rollback_index] 2015-11-04T20:22:17.556+0000 ReplSetTest awaitReplication: checking secondary #1: ip-10-239-178-114:20261
[js_test:rollback_index] 2015-11-04T20:22:17.556+0000 ReplSetTest awaitReplication: secondary #1, ip-10-239-178-114:20261, is synced
[js_test:rollback_index] 2015-11-04T20:22:17.557+0000 ReplSetTest awaitReplication: finished: all 1 secondaries synced at timestamp Timestamp(1446668533, 1)
[js_test:rollback_index] 2015-11-04T20:22:17.575+0000 2015-11-04T20:22:17.574+0000 E QUERY    [thread1] Error: count failed: { "ok" : 0, "errmsg" : "node is recovering", "code" : 13436 } :
[js_test:rollback_index] 2015-11-04T20:22:17.575+0000 _getErrorWithCode@src/mongo/shell/utils.js:23:13
[js_test:rollback_index] 2015-11-04T20:22:17.575+0000 DBQuery.prototype.count@src/mongo/shell/query.js:352:11
[js_test:rollback_index] 2015-11-04T20:22:17.575+0000 DBCollection.prototype.count@src/mongo/shell/collection.js:1627:12
[js_test:rollback_index] 2015-11-04T20:22:17.575+0000 @jstests/replsets/rollback_index.js:76:14
[js_test:rollback_index] 2015-11-04T20:22:17.576+0000 
[js_test:rollback_index] 2015-11-04T20:22:17.576+0000 failed to load: jstests/replsets/rollback_index.js

Comment by Matt Dannenberg [ 03/Nov/15 ]

fixed by: https://github.com/mongodb/mongo/commit/42d0577042dbc4b63e54eb40765c052baafed555

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