[SERVER-21157] confusing protocol version 0 warning message while configuring a replica set for the first time Created: 27/Oct/15  Updated: 25/Jan/17  Resolved: 27/Oct/15

Status: Closed
Project: Core Server
Component/s: Replication
Affects Version/s: 3.2.0-rc1
Fix Version/s: 3.2.0-rc2

Type: Improvement Priority: Minor - P4
Reporter: Benety Goh Assignee: Siyuan Zhou
Resolution: Done Votes: 0
Labels: None
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified

Issue Links:
Related
is related to SERVER-21117 Do not update term in protocol version 0 Closed
Backwards Compatibility: Fully Compatible
Sprint: Repl B (10/30/15)
Participants:

 Description   

Example from nightly build:

https://logkeeper.mongodb.org/build/562f6ea5be07c42d838f19cb/test/562f6ea59041304df68f3989

	
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.383-0400 [
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.383-0400 	connection to build10.ny.cbi.10gen.cc:22760,
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.383-0400 	connection to build10.ny.cbi.10gen.cc:22761,
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.383-0400 	connection to build10.ny.cbi.10gen.cc:22762
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.383-0400 ]
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.383-0400 {
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.383-0400 	"replSetInitiate" : {
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.383-0400 		"_id" : "stepDownWithLongWait",
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.383-0400 		"members" : [
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.384-0400 			{
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.384-0400 				"_id" : 0,
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.384-0400 				"host" : "build10.ny.cbi.10gen.cc:22760",
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.384-0400 				"priority" : 3
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.384-0400 			},
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.384-0400 			{
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.384-0400 				"_id" : 1,
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.385-0400 				"host" : "build10.ny.cbi.10gen.cc:22761"
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.385-0400 			},
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.385-0400 			{
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.385-0400 				"_id" : 2,
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.385-0400 				"host" : "build10.ny.cbi.10gen.cc:22762",
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.385-0400 				"arbiterOnly" : true
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.385-0400 			}
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.385-0400 		]
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.386-0400 	}
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.386-0400 }
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.386-0400 d22760| 2015-10-27T08:31:34.383-0400 I REPL     [conn1] replSetInitiate admin command received from client
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.386-0400 d22761| 2015-10-27T08:31:34.384-0400 I NETWORK  [initandlisten] connection accepted from 172.30.200.42:35217 #2 (2 connections now open)
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.387-0400 d22761| 2015-10-27T08:31:34.384-0400 I NETWORK  [conn2] end connection 172.30.200.42:35217 (1 connection now open)
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.387-0400 d22762| 2015-10-27T08:31:34.384-0400 I NETWORK  [initandlisten] connection accepted from 172.30.200.42:44001 #2 (2 connections now open)
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.387-0400 d22760| 2015-10-27T08:31:34.384-0400 I REPL     [conn1] replSetInitiate config object with 3 members parses ok
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.387-0400 d22762| 2015-10-27T08:31:34.385-0400 I NETWORK  [conn2] end connection 172.30.200.42:44001 (1 connection now open)
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.388-0400 d22761| 2015-10-27T08:31:34.385-0400 I NETWORK  [initandlisten] connection accepted from 172.30.200.42:35219 #3 (2 connections now open)
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.388-0400 d22762| 2015-10-27T08:31:34.385-0400 I NETWORK  [initandlisten] connection accepted from 172.30.200.42:44003 #3 (2 connections now open)
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.388-0400 d22760| 2015-10-27T08:31:34.385-0400 I ASIO     [NetworkInterfaceASIO] Successfully connected to build10.ny.cbi.10gen.cc:22761
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.389-0400 d22760| 2015-10-27T08:31:34.385-0400 I ASIO     [NetworkInterfaceASIO] Successfully connected to build10.ny.cbi.10gen.cc:22762
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.389-0400 d22760| 2015-10-27T08:31:34.386-0400 I NETWORK  [initandlisten] connection accepted from 172.30.200.42:36395 #2 (2 connections now open)
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.389-0400 d22760| 2015-10-27T08:31:34.386-0400 I NETWORK  [initandlisten] connection accepted from 172.30.200.42:36396 #3 (3 connections now open)
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.390-0400 d22761| 2015-10-27T08:31:34.386-0400 I ASIO     [NetworkInterfaceASIO] Successfully connected to build10.ny.cbi.10gen.cc:22760
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.390-0400 d22762| 2015-10-27T08:31:34.386-0400 I ASIO     [NetworkInterfaceASIO] Successfully connected to build10.ny.cbi.10gen.cc:22760
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.390-0400 d22762| 2015-10-27T08:31:34.386-0400 W REPL     [ReplicationExecutor] Cannot update term in election protocol version 0
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.391-0400 d22762| 2015-10-27T08:31:34.386-0400 W REPL     [ReplicationExecutor] Cannot update term in election protocol version 0
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.391-0400 d22761| 2015-10-27T08:31:34.386-0400 W REPL     [ReplicationExecutor] Cannot update term in election protocol version 0
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.392-0400 d22761| 2015-10-27T08:31:34.386-0400 W REPL     [ReplicationExecutor] Cannot update term in election protocol version 0
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.401-0400 d22760| 2015-10-27T08:31:34.400-0400 I REPL     [ReplicationExecutor] New replica set config in use: { _id: "stepDownWithLongWait", version: 1, protocolVersion: 1, members: [ { _id: 0, host: "build10.ny.cbi.10gen.cc:22760", arbiterOnly: false, buildIndexes: true, hidden: false, priority: 3.0, tags: {}, slaveDelay: 0, votes: 1 }, { _id: 1, host: "build10.ny.cbi.10gen.cc:22761", arbiterOnly: false, buildIndexes: true, hidden: false, priority: 1.0, tags: {}, slaveDelay: 0, votes: 1 }, { _id: 2, host: "build10.ny.cbi.10gen.cc:22762", arbiterOnly: true, buildIndexes: true, hidden: false, priority: 1.0, tags: {}, slaveDelay: 0, votes: 1 } ], settings: { chainingAllowed: true, heartbeatIntervalMillis: 2000, heartbeatTimeoutSecs: 10, electionTimeoutMillis: 5000, getLastErrorModes: {}, getLastErrorDefaults: { w: 1, wtimeout: 0 } } }
[js_test:stepdown_long_wait_time] 2015-10-27T08:31:34.401-0400 d22760| 2015-10-27T08:31:34.400-0400 I REPL     [ReplicationExecutor] This node is build10.ny.cbi.10gen.cc:22760 in the config



 Comments   
Comment by Githook User [ 27/Oct/15 ]

Author:

{u'username': u'visualzhou', u'name': u'Siyuan Zhou', u'email': u'siyuan.zhou@mongodb.com'}

Message: SERVER-21157 Change term warning message in protocol version 0 to LOG(3)
Branch: master
https://github.com/mongodb/mongo/commit/90df9a791998da8fb0b1d39d0dc908e8b3864e0e

Comment by Eric Milkie [ 27/Oct/15 ]

I concur.

Comment by Siyuan Zhou [ 27/Oct/15 ]

I propose changing the log level from warning to LOG(3). benety.goh, milkie

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