-
Type:
Bug
-
Resolution: Done
-
Priority:
Major - P3
-
Affects Version/s: None
-
Component/s: Testing Infrastructure
-
Environment:Failures seen on Solaris build slave and Windows 64-bit.
-
ALL
-
None
-
3
-
None
-
None
-
None
-
None
-
None
-
None
ReplSetTest await TS for connection to bs-smartos-x86-64-1.10gen.cc:31004 is 1356198556000:1 and latest is 1356198556000:1
ReplSetTest await oplog size for connection to bs-smartos-x86-64-1.10gen.cc:31004 is 20002
ReplSetTest await synced=true
force 2 to sync from 3
m31002| Sat Dec 22 17:49:56.892 [conn2] attempting to sync from bs-smartos-x86-64-1.10gen.cc:31003, but its latest opTime is 1356198504 and ours is 1356198556 so this may not work
m31000| Sat Dec 22 17:49:57.073 [conn23] end connection 165.225.128.186:64842 (10 connections now open)
m31002| Sat Dec 22 17:49:57.073 [rsBackgroundSync] replSet syncing to: bs-smartos-x86-64-1.10gen.cc:31003 by request
m31003| Sat Dec 22 17:49:57.073 [initandlisten] connection accepted from 165.225.128.186:54430 #19 (6 connections now open)
m31002| Sat Dec 22 17:49:57.074 [rsBackgroundSync] replSet syncing to: bs-smartos-x86-64-1.10gen.cc:31000
m31003| Sat Dec 22 17:49:57.074 [conn19] end connection 165.225.128.186:54430 (5 connections now open)
m31000| Sat Dec 22 17:49:57.074 [initandlisten] connection accepted from 165.225.128.186:47032 #30 (11 connections now open)
{
"set" : "sync_passive2",
"date" : ISODate("2012-12-22T17:49:57Z"),
"myState" : 2,
"syncingTo" : "bs-smartos-x86-64-1.10gen.cc:31000",
"members" : [
{
"_id" : 0,
"name" : "bs-smartos-x86-64-1.10gen.cc:31000",
"health" : 1,
"state" : 1,
"stateStr" : "PRIMARY",
"uptime" : 40,
"optime" : {
"t" : 1356198556000,
"i" : 1
},
"optimeDate" : ISODate("2012-12-22T17:49:16Z"),
"lastHeartbeat" : ISODate("2012-12-22T17:49:55Z"),
"lastHeartbeatRecv" : ISODate("2012-12-22T17:49:56Z"),
"pingMs" : 0
},
{
"_id" : 1,
"name" : "bs-smartos-x86-64-1.10gen.cc:31001",
"health" : 1,
"state" : 7,
"stateStr" : "ARBITER",
"uptime" : 40,
"lastHeartbeat" : ISODate("2012-12-22T17:49:55Z"),
"lastHeartbeatRecv" : ISODate("2012-12-22T17:49:56Z"),
"pingMs" : 0
},
{
"_id" : 2,
"name" : "bs-smartos-x86-64-1.10gen.cc:31002",
"health" : 1,
"state" : 2,
"stateStr" : "SECONDARY",
"uptime" : 74,
"optime" : {
"t" : 1356198556000,
"i" : 1
},
"optimeDate" : ISODate("2012-12-22T17:49:16Z"),
"errmsg" : "syncing to: bs-smartos-x86-64-1.10gen.cc:31000",
"self" : true
},
{
"_id" : 3,
"name" : "bs-smartos-x86-64-1.10gen.cc:31003",
"health" : 1,
"state" : 2,
"stateStr" : "SECONDARY",
"uptime" : 40,
"optime" : {
"t" : 1356198504000,
"i" : 4242
},
"optimeDate" : ISODate("2012-12-22T17:48:24Z"),
"lastHeartbeat" : ISODate("2012-12-22T17:49:55Z"),
"lastHeartbeatRecv" : ISODate("2012-12-22T17:49:57Z"),
"pingMs" : 0,
"lastHeartbeatMessage" : "syncing to: bs-smartos-x86-64-1.10gen.cc:31000"
},
{
"_id" : 4,
"name" : "bs-smartos-x86-64-1.10gen.cc:31004",
"health" : 1,
"state" : 2,
"stateStr" : "SECONDARY",
"uptime" : 40,
"optime" : {
"t" : 1356198556000,
"i" : 1
},
"optimeDate" : ISODate("2012-12-22T17:49:16Z"),
"lastHeartbeat" : ISODate("2012-12-22T17:49:55Z"),
"lastHeartbeatRecv" : ISODate("2012-12-22T17:49:56Z"),
"pingMs" : 0,
"lastHeartbeatMessage" : "syncing to: bs-smartos-x86-64-1.10gen.cc:31000"
}
],
"ok" : 1
}
This test has problems at this stage. It expects to be able to see that it has successfully changed the "sync to" setting for a node and expects to see it be automatically changed to a non-slave-delayed node "within ~30 seconds". In this failure, the setting was successfully changed and was automatically reset 1 millisecond later:
m31002| Sat Dec 22 17:49:56.892 [conn2] attempting to sync from bs-smartos-x86-64-1.10gen.cc:31003, but its latest opTime is 1356198504 and ours is 1356198556 so this may not work
m31000| Sat Dec 22 17:49:57.073 [conn23] end connection 165.225.128.186:64842 (10 connections now open)
m31002| Sat Dec 22 17:49:57.073 [rsBackgroundSync] replSet syncing to: bs-smartos-x86-64-1.10gen.cc:31003 by request
m31003| Sat Dec 22 17:49:57.073 [initandlisten] connection accepted from 165.225.128.186:54430 #19 (6 connections now open)
m31002| Sat Dec 22 17:49:57.074 [rsBackgroundSync] replSet syncing to: bs-smartos-x86-64-1.10gen.cc:31000
This very fast reset doesn't give the script any time to see that its setting did, in fact, work.