|
Oh, not to add on to this, but if I run the setFeatureCompatibilityVersion command, it also spits out a lot before printing out the result document.
For me I'm using both shell and mongod version 4.4.0-rc3-8-ge9c2db4
MongoDB Enterprise shardA:PRIMARY> db.adminCommand( { setFeatureCompatibilityVersion: "4.2" } )
|
|
{"t":{"$date":"2020-04-30T01:00:02.078Z"},"s":"I", "c":"NETWORK", "id":4333213,"ctx":"ReplicaSetMonitor-TaskExecutor","msg":"RSM {setName} Topology Change: {topologyDescription}","attr":{"setName":"shardA","topologyDescription":"{ id: \"604076b0-1f10-45e0-9058-c650d2e7b527\", topologyType: \"ReplicaSetWithPrimary\", servers: { Kays-MacBook-Pro.local:27018: { address: \"Kays-MacBook-Pro.local:27018\", topologyVersion: { processId: ObjectId('5eaa1e97c4d650aa78176230'), counter: 6 }, roundTripTime: 0, lastWriteDate: new Date(1588208390000), opTime: { ts: Timestamp(1588208390, 1), t: 1 }, type: \"RSPrimary\", minWireVersion: 0, maxWireVersion: 9, streamable: false, poolResetCounter: 0, me: \"Kays-MacBook-Pro.local:27018\", setName: \"shardA\", setVersion: 1, electionId: ObjectId('7fffffff0000000000000001'), primary: \"Kays-MacBook-Pro.local:27018\", lastUpdateTime: new Date(1588208392075), logicalSessionTimeoutMinutes: 30, hosts: [ \"Kays-MacBook-Pro.local:27018\", \"Kays-MacBook-Pro.local:27019\", \"Kays-MacBook-Pro.local:27020\" ], arbiters: [], passives: [] }, Kays-MacBook-Pro.local:27019: { address: \"Kays-MacBook-Pro.local:27019\", topologyVersion: { processId: ObjectId('5eaa1e987c31e29eb81668a4'), counter: 5 }, roundTripTime: 0, lastWriteDate: new Date(1588208400000), opTime: { ts: Timestamp(1588208400, 1), t: 1 }, type: \"RSSecondary\", minWireVersion: 0, maxWireVersion: 9, streamable: false, poolResetCounter: 0, me: \"Kays-MacBook-Pro.local:27019\", setName: \"shardA\", setVersion: 2, primary: \"Kays-MacBook-Pro.local:27018\", lastUpdateTime: new Date(1588208402078), logicalSessionTimeoutMinutes: 30, hosts: [ \"Kays-MacBook-Pro.local:27018\", \"Kays-MacBook-Pro.local:27019\", \"Kays-MacBook-Pro.local:27020\" ], arbiters: [], passives: [] }, Kays-MacBook-Pro.local:27020: { address: \"Kays-MacBook-Pro.local:27020\", topologyVersion: { processId: ObjectId('5eaa1e99b92524d903f7e0e8'), counter: 4 }, roundTripTime: 0, lastWriteDate: new Date(1588208390000), opTime: { ts: Timestamp(1588208390, 1), t: 1 }, type: \"RSSecondary\", minWireVersion: 0, maxWireVersion: 9, streamable: false, poolResetCounter: 0, me: \"Kays-MacBook-Pro.local:27020\", setName: \"shardA\", setVersion: 1, primary: \"Kays-MacBook-Pro.local:27018\", lastUpdateTime: new Date(1588208392075), logicalSessionTimeoutMinutes: 30, hosts: [ \"Kays-MacBook-Pro.local:27018\", \"Kays-MacBook-Pro.local:27019\", \"Kays-MacBook-Pro.local:27020\" ], arbiters: [], passives: [] } }, logicalSessionTimeoutMinutes: 30, setName: \"shardA\", compatible: true, maxSetVersion: 1, maxElectionId: ObjectId('7fffffff0000000000000001') }"}}
|
{"t":{"$date":"2020-04-30T01:00:02.078Z"},"s":"I", "c":"NETWORK", "id":4333213,"ctx":"ReplicaSetMonitor-TaskExecutor","msg":"RSM {setName} Topology Change: {topologyDescription}","attr":{"setName":"shardA","topologyDescription":"{ id: \"604076b0-1f10-45e0-9058-c650d2e7b527\", topologyType: \"ReplicaSetWithPrimary\", servers: { Kays-MacBook-Pro.local:27018: { address: \"Kays-MacBook-Pro.local:27018\", topologyVersion: { processId: ObjectId('5eaa1e97c4d650aa78176230'), counter: 6 }, roundTripTime: 0, lastWriteDate: new Date(1588208390000), opTime: { ts: Timestamp(1588208390, 1), t: 1 }, type: \"RSPrimary\", minWireVersion: 0, maxWireVersion: 9, streamable: false, poolResetCounter: 0, me: \"Kays-MacBook-Pro.local:27018\", setName: \"shardA\", setVersion: 1, electionId: ObjectId('7fffffff0000000000000001'), primary: \"Kays-MacBook-Pro.local:27018\", lastUpdateTime: new Date(1588208392075), logicalSessionTimeoutMinutes: 30, hosts: [ \"Kays-MacBook-Pro.local:27018\", \"Kays-MacBook-Pro.local:27019\", \"Kays-MacBook-Pro.local:27020\" ], arbiters: [], passives: [] }, Kays-MacBook-Pro.local:27019: { address: \"Kays-MacBook-Pro.local:27019\", topologyVersion: { processId: ObjectId('5eaa1e987c31e29eb81668a4'), counter: 5 }, roundTripTime: 0, lastWriteDate: new Date(1588208400000), opTime: { ts: Timestamp(1588208400, 1), t: 1 }, type: \"RSSecondary\", minWireVersion: 0, maxWireVersion: 9, streamable: false, poolResetCounter: 0, me: \"Kays-MacBook-Pro.local:27019\", setName: \"shardA\", setVersion: 2, primary: \"Kays-MacBook-Pro.local:27018\", lastUpdateTime: new Date(1588208402078), logicalSessionTimeoutMinutes: 30, hosts: [ \"Kays-MacBook-Pro.local:27018\", \"Kays-MacBook-Pro.local:27019\", \"Kays-MacBook-Pro.local:27020\" ], arbiters: [], passives: [] }, Kays-MacBook-Pro.local:27020: { address: \"Kays-MacBook-Pro.local:27020\", topologyVersion: { processId: ObjectId('5eaa1e99b92524d903f7e0e8'), counter: 5 }, roundTripTime: 0, lastWriteDate: new Date(1588208400000), opTime: { ts: Timestamp(1588208400, 1), t: 1 }, type: \"RSSecondary\", minWireVersion: 0, maxWireVersion: 9, streamable: false, poolResetCounter: 0, me: \"Kays-MacBook-Pro.local:27020\", setName: \"shardA\", setVersion: 2, primary: \"Kays-MacBook-Pro.local:27018\", lastUpdateTime: new Date(1588208402078), logicalSessionTimeoutMinutes: 30, hosts: [ \"Kays-MacBook-Pro.local:27018\", \"Kays-MacBook-Pro.local:27019\", \"Kays-MacBook-Pro.local:27020\" ], arbiters: [], passives: [] } }, logicalSessionTimeoutMinutes: 30, setName: \"shardA\", compatible: true, maxSetVersion: 1, maxElectionId: ObjectId('7fffffff0000000000000001') }"}}
|
{"t":{"$date":"2020-04-30T01:00:02.079Z"},"s":"I", "c":"NETWORK", "id":20224, "ctx":"ReplicaSetMonitor-TaskExecutor","msg":"{kLogPrefix}topology max election id set to {newMaxElectionId}","attr":{"kLogPrefix":"sdam : ","newMaxElectionId":{"$oid":"7fffffff0000000000000001"}}}
|
{"t":{"$date":"2020-04-30T01:00:02.079Z"},"s":"I", "c":"NETWORK", "id":20225, "ctx":"ReplicaSetMonitor-TaskExecutor","msg":"{kLogPrefix}topology max set version set to {newMaxSetVersion}","attr":{"kLogPrefix":"sdam : ","newMaxSetVersion":2}}
|
{"t":{"$date":"2020-04-30T01:00:02.079Z"},"s":"I", "c":"NETWORK", "id":20221, "ctx":"ReplicaSetMonitor-TaskExecutor","msg":"{kLogPrefix}the topology type was set to {topologyType}","attr":{"kLogPrefix":"sdam : ","topologyType":"ReplicaSetWithPrimary"}}
|
{"t":{"$date":"2020-04-30T01:00:02.079Z"},"s":"I", "c":"NETWORK", "id":4333213,"ctx":"ReplicaSetMonitor-TaskExecutor","msg":"RSM {setName} Topology Change: {topologyDescription}","attr":{"setName":"shardA","topologyDescription":"{ id: \"604076b0-1f10-45e0-9058-c650d2e7b527\", topologyType: \"ReplicaSetWithPrimary\", servers: { Kays-MacBook-Pro.local:27018: { address: \"Kays-MacBook-Pro.local:27018\", topologyVersion: { processId: ObjectId('5eaa1e97c4d650aa78176230'), counter: 7 }, roundTripTime: 0, lastWriteDate: new Date(1588208400000), opTime: { ts: Timestamp(1588208400, 1), t: 1 }, type: \"RSPrimary\", minWireVersion: 0, maxWireVersion: 9, streamable: false, poolResetCounter: 0, me: \"Kays-MacBook-Pro.local:27018\", setName: \"shardA\", setVersion: 2, electionId: ObjectId('7fffffff0000000000000001'), primary: \"Kays-MacBook-Pro.local:27018\", lastUpdateTime: new Date(1588208402079), logicalSessionTimeoutMinutes: 30, hosts: [ \"Kays-MacBook-Pro.local:27018\", \"Kays-MacBook-Pro.local:27019\", \"Kays-MacBook-Pro.local:27020\" ], arbiters: [], passives: [] }, Kays-MacBook-Pro.local:27019: { address: \"Kays-MacBook-Pro.local:27019\", topologyVersion: { processId: ObjectId('5eaa1e987c31e29eb81668a4'), counter: 5 }, roundTripTime: 0, lastWriteDate: new Date(1588208400000), opTime: { ts: Timestamp(1588208400, 1), t: 1 }, type: \"RSSecondary\", minWireVersion: 0, maxWireVersion: 9, streamable: false, poolResetCounter: 0, me: \"Kays-MacBook-Pro.local:27019\", setName: \"shardA\", setVersion: 2, primary: \"Kays-MacBook-Pro.local:27018\", lastUpdateTime: new Date(1588208402078), logicalSessionTimeoutMinutes: 30, hosts: [ \"Kays-MacBook-Pro.local:27018\", \"Kays-MacBook-Pro.local:27019\", \"Kays-MacBook-Pro.local:27020\" ], arbiters: [], passives: [] }, Kays-MacBook-Pro.local:27020: { address: \"Kays-MacBook-Pro.local:27020\", topologyVersion: { processId: ObjectId('5eaa1e99b92524d903f7e0e8'), counter: 5 }, roundTripTime: 0, lastWriteDate: new Date(1588208400000), opTime: { ts: Timestamp(1588208400, 1), t: 1 }, type: \"RSSecondary\", minWireVersion: 0, maxWireVersion: 9, streamable: false, poolResetCounter: 0, me: \"Kays-MacBook-Pro.local:27020\", setName: \"shardA\", setVersion: 2, primary: \"Kays-MacBook-Pro.local:27018\", lastUpdateTime: new Date(1588208402078), logicalSessionTimeoutMinutes: 30, hosts: [ \"Kays-MacBook-Pro.local:27018\", \"Kays-MacBook-Pro.local:27019\", \"Kays-MacBook-Pro.local:27020\" ], arbiters: [], passives: [] } }, logicalSessionTimeoutMinutes: 30, setName: \"shardA\", compatible: true, maxSetVersion: 2, maxElectionId: ObjectId('7fffffff0000000000000001') }"}}
|
{
|
"ok" : 1,
|
"$clusterTime" : {
|
"clusterTime" : Timestamp(1588208402, 1),
|
"signature" : {
|
"hash" : BinData(0,"BMrSp2qccXBDZoqSJMdHzQxnLHI="),
|
"keyId" : NumberLong("6821298283919441923")
|
}
|
},
|
"operationTime" : Timestamp(1588208402, 1)
|
}
|
|
|
I think this problem is bad enough that it warrants being P3. When you start a mongo shell against a replica set without --quiet you get about 100 lines of messages related to replica set monitoring. Here's a brief sample:
{"t":{"$date":"2020-03-19T12:57:20.996Z"},"s":"I", "c":"NETWORK", "id":4648601,"ctx":"main","msg":"Implicit TCP FastOpen unavailable. If TCP FastOpen is required, set tcpFastOpenServer, tcpFastOpenClient, and tcpFastOpenQueueSize."},
|
{"t":{"$date":"2020-03-19T12:57:21.049Z"},"s":"I", "c":"NETWORK", "id":4333205,"ctx":"js","msg":"Starting Streamable ReplicaSetMonitor","attr":{"uri":"r/localhost:27017"}},
|
{"t":{"$date":"2020-03-19T12:57:21.049Z"},"s":"I", "c":"NETWORK", "id":4333206,"ctx":"js","msg":"Starting Replica Set Monitor","attr":{"uri":"r/localhost:27017"}},
|
{"t":{"$date":"2020-03-19T12:57:21.049Z"},"s":"I", "c":"-", "id":4333223,"ctx":"js","msg":"RSM monitoring","attr":{"setName":"r","size":1}},
|
{"t":{"$date":"2020-03-19T12:57:21.049Z"},"s":"I", "c":"-", "id":4333226,"ctx":"js","msg":"RSM host was added to the toplogy","attr":{"setName":"r","hostAndPort":"localhost:27017"}},
|
{"t":{"$date":"2020-03-19T12:57:21.049Z"},"s":"I", "c":"-", "id":4333227,"ctx":"js","msg":"RSM monitoring host in expedited mode until we detect a primary.","attr":{"hostAndPort":"localhost:27017","setName":"r"}},
|
{"t":{"$date":"2020-03-19T12:57:21.049Z"},"s":"I", "c":"-", "id":4333218,"ctx":"js","msg":"RSM rescheduling next isMaster check for host","attr":{"hostAndPort":"localhost:27017","delayMillis":0,"setName":"r"}},
|
{"t":{"$date":"2020-03-19T12:57:21.049Z"},"s":"I", "c":"CONNPOOL","id":22576,"ctx":"ReplicaSetMonitor-TaskExecutor","msg":"Connecting to host","attr":{"hostAndPort":"localhost:27017"}},
|
{"t":{"$date":"2020-03-19T12:57:21.051Z"},"s":"I", "c":"NETWORK", "id":20221,"ctx":"ReplicaSetMonitor-TaskExecutor","msg":"The topology type was set","attr":{"topologyType":"ReplicaSetNoPrimary"}},
|
{"t":{"$date":"2020-03-19T12:57:21.051Z"},"s":"I", "c":"NETWORK", "id":20222,"ctx":"ReplicaSetMonitor-TaskExecutor","msg":"The topology setName was set","attr":{"setName":"r"}},
|
(Note: I've actually modified the "msg" string in the above to meet our current recommendations to log without substitution strings.) When formatted by the code proposed in SERVER-46933 this becomes much more readable:
2020-03-19T12:57:20.996Z I NETWORK [main] Implicit TCP FastOpen unavailable. If TCP FastOpen is required, set tcpFastOpenServer, tcpFastOpenClient, and tcpFastOpenQueueSize.
|
2020-03-19T12:57:21.049Z I NETWORK [js] Starting Streamable ReplicaSetMonitor uri: r/localhost:27017
|
2020-03-19T12:57:21.049Z I NETWORK [js] Starting Replica Set Monitor uri: r/localhost:27017
|
2020-03-19T12:57:21.049Z I - [js] RSM monitoring setName: r size: 1
|
2020-03-19T12:57:21.049Z I - [js] RSM host was added to the toplogy setName: r hostAndPort: localhost:27017
|
2020-03-19T12:57:21.049Z I - [js] RSM monitoring host in expedited mode until we detect a primary. hostAndPort: localhost:27017 setName: r
|
2020-03-19T12:57:21.049Z I - [js] RSM rescheduling next isMaster check for host hostAndPort: localhost:27017 delayMillis: 0 setName: r
|
2020-03-19T12:57:21.049Z I CONNPOOL [ReplicaSetMonitor-TaskExecutor] Connecting to host hostAndPort: localhost:27017
|
2020-03-19T12:57:21.051Z I NETWORK [ReplicaSetMonitor-TaskExecutor] The topology type was set topologyType: ReplicaSetNoPrimary
|
2020-03-19T12:57:21.051Z I NETWORK [ReplicaSetMonitor-TaskExecutor] The topology setName was set setName: r
|
|