Uploaded image for project: 'Core Server'
  1. Core Server
  2. SERVER-9292

optime lag, even with WriteConcern verified

    • Type: Icon: Bug Bug
    • Resolution: Done
    • Priority: Icon: Major - P3 Major - P3
    • None
    • Affects Version/s: 2.4.1, 2.5.0
    • Component/s: Replication
    • Labels:
      None
    • Environment:
      db version v2.5.0-pre-
      git version: b5aa82c6783029da7fd21480d0271731c0c770f3 (Mon Apr 8 19:32:36 2013 -0400)
    • ALL
    • Hide
      db.foo.insert({doc: "ument100"});
      printjson(db.runCommand({"getLastError": 1, "w": "majority"}))
      printjson(rs.stepDown(10));
      printjson(rs.status())
      
      Show
      db.foo.insert({doc: "ument100" }); printjson(db.runCommand({ "getLastError" : 1, "w" : "majority" })) printjson(rs.stepDown(10)); printjson(rs.status())

      My primary is refusing to rs.stepDown(), saying that it cannot find a secondary within 10seconds of its optime, which is confirmed by rs.status()

      However. The previous command was a GLE to majority, which infact did get replicated to all servers (according to the return value) - but the optime seemingly wasn't updated.

      bjori@anden:~/Sources/mongo/mongo (master) $ ./mongo localhost:30200 script.js
      MongoDB shell version: 2.5.0-pre-
      connecting to: localhost:30200/test
      {
      	"n" : 0,
      	"lastOp" : Timestamp(1365468913, 1),
      	"connectionId" : 175,
      	"writtenTo" : [
      		{
      			"_id" : 0,
      			"host" : "anden.local:30200",
      			"priority" : 42,
      			"tags" : {
      				"dc" : "ny",
      				"server" : "0"
      			}
      		},
      		{
      			"_id" : 1,
      			"host" : "anden.local:30201",
      			"tags" : {
      				"dc" : "ny",
      				"server" : "1"
      			}
      		},
      		{
      			"_id" : 3,
      			"host" : "anden.local:30203",
      			"tags" : {
      				"dc" : "sf",
      				"server" : "3"
      			}
      		},
      		{
      			"_id" : 2,
      			"host" : "anden.local:30202",
      			"tags" : {
      				"dc" : "sf",
      				"server" : "2"
      			}
      		}
      	],
      	"wtime" : 1,
      	"err" : null,
      	"ok" : 1
      }
      {
      	"closest" : NumberLong(1365468885),
      	"difference" : NumberLong(28),
      	"ok" : 0,
      	"errmsg" : "no secondaries within 10 seconds of my optime"
      }
      {
      	"set" : "REPLICASET",
      	"date" : ISODate("2013-04-09T00:55:13Z"),
      	"myState" : 1,
      	"members" : [
      		{
      			"_id" : 0,
      			"name" : "anden.local:30200",
      			"health" : 1,
      			"state" : 1,
      			"stateStr" : "PRIMARY",
      			"uptime" : 1035,
      			"optime" : Timestamp(1365468913, 1),
      			"optimeDate" : ISODate("2013-04-09T00:55:13Z"),
      			"self" : true
      		},
      		{
      			"_id" : 1,
      			"name" : "anden.local:30201",
      			"health" : 1,
      			"state" : 2,
      			"stateStr" : "SECONDARY",
      			"uptime" : 1032,
      			"optime" : Timestamp(1365468885, 1),
      			"optimeDate" : ISODate("2013-04-09T00:54:45Z"),
      			"lastHeartbeat" : ISODate("2013-04-09T00:55:12Z"),
      			"lastHeartbeatRecv" : ISODate("2013-04-09T00:55:12Z"),
      			"pingMs" : 0,
      			"lastHeartbeatMessage" : "syncing to: anden.local:30200",
      			"syncingTo" : "anden.local:30200"
      		},
      		{
      			"_id" : 2,
      			"name" : "anden.local:30202",
      			"health" : 1,
      			"state" : 2,
      			"stateStr" : "SECONDARY",
      			"uptime" : 1032,
      			"optime" : Timestamp(1365468885, 1),
      			"optimeDate" : ISODate("2013-04-09T00:54:45Z"),
      			"lastHeartbeat" : ISODate("2013-04-09T00:55:12Z"),
      			"lastHeartbeatRecv" : ISODate("2013-04-09T00:55:12Z"),
      			"pingMs" : 0,
      			"lastHeartbeatMessage" : "syncing to: anden.local:30200",
      			"syncingTo" : "anden.local:30200"
      		},
      		{
      			"_id" : 3,
      			"name" : "anden.local:30203",
      			"health" : 1,
      			"state" : 2,
      			"stateStr" : "SECONDARY",
      			"uptime" : 1032,
      			"optime" : Timestamp(1365468885, 1),
      			"optimeDate" : ISODate("2013-04-09T00:54:45Z"),
      			"lastHeartbeat" : ISODate("2013-04-09T00:55:12Z"),
      			"lastHeartbeatRecv" : ISODate("2013-04-09T00:55:13Z"),
      			"pingMs" : 0,
      			"lastHeartbeatMessage" : "syncing to: anden.local:30200",
      			"syncingTo" : "anden.local:30200"
      		},
      		{
      			"_id" : 4,
      			"name" : "anden.local:30204",
      			"health" : 1,
      			"state" : 7,
      			"stateStr" : "ARBITER",
      			"uptime" : 1032,
      			"lastHeartbeat" : ISODate("2013-04-09T00:55:12Z"),
      			"lastHeartbeatRecv" : ISODate("2013-04-09T00:55:13Z"),
      			"pingMs" : 0
      		}
      	],
      	"ok" : 1
      }
      

            Assignee:
            Unassigned Unassigned
            Reporter:
            bjori Hannes Magnusson
            Votes:
            1 Vote for this issue
            Watchers:
            4 Start watching this issue

              Created:
              Updated:
              Resolved: