-
Type:
Bug
-
Resolution: Done
-
Priority:
Major - P3
-
Affects Version/s: None
-
Component/s: None
-
None
-
Fully Compatible
-
ALL
Member #2 seems to be failing to sync from #1 after #1 becomes primary (after bringing #0 down).
07728dc1e6 Linux 64-bit replicasets
https://logkeeper.mongodb.org/build/55dc08b8be07c47abf8d93e0/test/55dc18f4be07c47abf8dd717
[js_test:sync_passive] 2015-08-25T07:28:21.359+0000 m31001| 2015-08-25T07:28:21.359+0000 I REPL [ReplicationExecutor] Standing for election
[js_test:sync_passive] 2015-08-25T07:28:21.359+0000 m31001| 2015-08-25T07:28:21.359+0000 I REPL [ReplicationExecutor] running for election
[js_test:sync_passive] 2015-08-25T07:28:21.360+0000 m31002| 2015-08-25T07:28:21.359+0000 I REPL [ReplicationExecutor] replSetElect voting yea for ip-10-170-157-105:31001 (1)
[js_test:sync_passive] 2015-08-25T07:28:21.360+0000 m31001| 2015-08-25T07:28:21.359+0000 I REPL [ReplicationExecutor] received vote: 1 votes from ip-10-170-157-105:31002
[js_test:sync_passive] 2015-08-25T07:28:21.360+0000 m31001| 2015-08-25T07:28:21.360+0000 I REPL [ReplicationExecutor] election succeeded, assuming primary role
[js_test:sync_passive] 2015-08-25T07:28:21.360+0000 m31001| 2015-08-25T07:28:21.360+0000 I REPL [ReplicationExecutor] transition to PRIMARY
...
[js_test:sync_passive] 2015-08-25T07:28:54.230+0000 ReplSetTest awaitReplication: checking secondaries against timestamp Timestamp(1440487703, 1)
[js_test:sync_passive] 2015-08-25T07:28:54.231+0000 ReplSetTest awaitReplication: checking secondary #1: ip-10-170-157-105:31002
[js_test:sync_passive] 2015-08-25T07:28:54.231+0000 ReplSetTest awaitReplication: timestamp for secondary #1, ip-10-170-157-105:31002, is Timestamp(1440487676, 1001) but latest is Timestamp(1440487703, 1)
[js_test:sync_passive] 2015-08-25T07:28:54.231+0000 ReplSetTest awaitReplication: last oplog entry (of 1104) for secondary #1, ip-10-170-157-105:31002, is { "ts" : Timestamp(1440487676, 1001), "h" : NumberLong("5407822403662064979"), "v" : 2, "op" : "i", "ns" : "test.bar", "o" : { "_id" : ObjectId("55dc18fc36578cb7b6f8ed43"), "x" : 999 } }
[js_test:sync_passive] 2015-08-25T07:28:54.232+0000 ReplSetTest awaitReplication: secondary #1, ip-10-170-157-105:31002, is NOT synced
[js_test:sync_passive] 2015-08-25T07:28:54.435+0000 ReplSetTest awaitReplication: checking secondaries against timestamp Timestamp(1440487703, 1)
[js_test:sync_passive] 2015-08-25T07:28:54.435+0000 ReplSetTest awaitReplication: checking secondary #1: ip-10-170-157-105:31002
[js_test:sync_passive] 2015-08-25T07:28:54.435+0000 ReplSetTest awaitReplication: timestamp for secondary #1, ip-10-170-157-105:31002, is Timestamp(1440487676, 1001) but latest is Timestamp(1440487703, 1)
[js_test:sync_passive] 2015-08-25T07:28:54.436+0000 ReplSetTest awaitReplication: last oplog entry (of 1104) for secondary #1, ip-10-170-157-105:31002, is { "ts" : Timestamp(1440487676, 1001), "h" : NumberLong("5407822403662064979"), "v" : 2, "op" : "i", "ns" : "test.bar", "o" : { "_id" : ObjectId("55dc18fc36578cb7b6f8ed43"), "x" : 999 } }
[js_test:sync_passive] 2015-08-25T07:28:54.436+0000 ReplSetTest awaitReplication: secondary #1, ip-10-170-157-105:31002, is NOT synced
[js_test:sync_passive] 2015-08-25T07:28:54.436+0000 assert.soon failed, msg:awaiting replication
[js_test:sync_passive] 2015-08-25T07:28:54.436+0000 doassert@src/mongo/shell/assert.js:11:14
[js_test:sync_passive] 2015-08-25T07:28:54.436+0000 assert.soon@src/mongo/shell/assert.js:189:13
[js_test:sync_passive] 2015-08-25T07:28:54.436+0000 ReplSetTest.prototype.awaitReplication@src/mongo/shell/replsettest.js:524:1
[js_test:sync_passive] 2015-08-25T07:28:54.436+0000 @jstests/replsets/sync_passive.js:65:1
[js_test:sync_passive] 2015-08-25T07:28:54.437+0000