[CDRIVER-4035] SDAM rediscover-quickly-after-step-down test failure on 4.4 replica set Created: 23/Jun/21  Updated: 28/Oct/23  Resolved: 20/Sep/21

Status: Closed
Project: C Driver
Component/s: None
Affects Version/s: None
Fix Version/s: 1.19.1

Type: Task Priority: Unknown
Reporter: Kevin Albertson Assignee: Kevin Albertson
Resolution: Fixed Votes: 0
Labels: failing-on-waterfall
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified


 Description   

The /server_discovery_and_monitoring/integration/rediscover-quickly-after-step-down test appears to be failing on replica set tests.

Example failure on test-asan-4.4-replica-set-auth-nosasl-openssl

Logs:

[2021/06/22 06:44:25.141] Begin /server_discovery_and_monitoring/integration/rediscover-quickly-after-step-down, seed 569362533
[2021/06/22 06:44:25.839] 2021/06/22 06:44:25.0415: [17756]:    DEBUG:       mongoc: running test: Rediscover quickly after replSetStepDown
[2021/06/22 06:44:25.839] 2021/06/22 06:44:25.0624: [17756]:    DEBUG:      cluster: Authentication failed: Authentication failed.
[2021/06/22 06:44:25.839] 2021/06/22 06:44:25.0625: [17756]:    DEBUG:       mongoc: error disabling failpoint: Authentication failed.
[2021/06/22 06:44:25.839] 2021/06/22 06:44:25.0777: [17756]:    DEBUG:       mongoc:      running operation insertMany : { "name" : "insertMany", "object" : "collection", "arguments" : { "documents" : [ { "_id" : 3 }, { "_id" : 4 } ] } }
[2021/06/22 06:44:25.839] 2021/06/22 06:44:25.0782: [12155]:    DEBUG:       mongoc: SERVER CHANGED: (localhost:27017) Unknown --> RSSecondary
[2021/06/22 06:44:25.839] 2021/06/22 06:44:25.0784: [12156]:    DEBUG:       mongoc: SERVER CHANGED: (localhost:27018) Unknown --> RSPrimary
[2021/06/22 06:44:25.839] 2021/06/22 06:44:25.0793: [12160]:    DEBUG:       mongoc: SERVER CHANGED: (localhost:27019) Unknown --> RSArbiter
[2021/06/22 06:44:25.839] 2021/06/22 06:44:25.0795: [17756]:    DEBUG:       mongoc: SERVER CHANGED: (localhost:27018) RSPrimary --> RSPrimary
[2021/06/22 06:44:25.839] { "insert" : "test-replSetStepDown", "ordered" : true, "$db" : "sdam-tests", "lsid" : { "id" : { "$binary" : { "base64" : "0Qdf2cMxTRO5r72P1lB/Hg==", "subType" : "04" } } }, "txnNumber" : { "$numberLong" : "1" }, "$clusterTime" : { "clusterTime" : { "$timestamp" : { "t" : 1624344265, "i" : 3 } }, "signature" : { "hash" : { "$binary" : { "base64" : "fnArXjSX2wHDcjbIMozx5rVwTxQ=", "subType" : "00" } }, "keyId" : { "$numberLong" : "6976501630149591043" } } }, "writeConcern" : { "w" : "majority" }, "documents" : [ { "_id" : { "$numberInt" : "3" } }, { "_id" : { "$numberInt" : "4" } } ] }
[2021/06/22 06:44:25.900] test error in: /data/mci/c6c8eafce9ea5543b85a9c422c3a08fd/mongoc/src/libmongoc/tests/json-test-operations.c 2573:json_test_operation()
[2021/06/22 06:44:25.900] admin command failed: { "operationTime" : { "$timestamp" : { "t" : 1624344265, "i" : 5 } }, "ok" : 0.0, "errmsg" : "Cannot freeze node when primary or running for election, state: Primary", "code" : 95, "codeName" : "NotSecondary", "$clusterTime" : { "clusterTime" : { "$timestamp" : { "t" : 1624344265, "i" : 5 } }, "signature" : { "hash" : { "$binary" : "fnArXjSX2wHDcjbIMozx5rVwTxQ=", "$type" : "00" }, "keyId" : 6976501630149591043 } } }
[2021/06/22 06:44:25.900] 2021/06/22 06:44:25.0843: [17756]:    DEBUG:       mongoc: <-- COMMAND SUCCEEDED: { "n" : { "$numberInt" : "2" }, "opTime" : { "ts" : { "$timestamp" : { "t" : 1624344265, "i" : 5 } }, "t" : { "$numberLong" : "3" } }, "electionId" : { "$oid" : "7fffffff0000000000000003" }, "ok" : { "$numberDouble" : "1.0" }, "$clusterTime" : { "clusterTime" : { "$timestamp" : { "t" : 1624344265, "i" : 5 } }, "signature" : { "hash" : { "$binary" : { "base64" : "fnArXjSX2wHDcjbIMozx5rVwTxQ=", "subType" : "00" } }, "keyId" : { "$numberLong" : "6976501630149591043" } } }, "operationTime" : { "$timestamp" : { "t" : 1624344265, "i" : 5 } } }
[2021/06/22 06:44:25.900] 2021/06/22 06:44:25.0843: [17756]:    DEBUG:       mongoc:      running operation recordPrimary : { "name" : "recordPrimary", "object" : "testRunner" }
[2021/06/22 06:44:25.900] 2021/06/22 06:44:25.0843: [17756]:    DEBUG:       mongoc:      running operation runAdminCommand : { "name" : "runAdminCommand", "object" : "testRunner", "command_name" : "replSetFreeze", "arguments" : { "command" : { "replSetFreeze" : 0 }, "readPreference" : { "mode" : "Secondary" } } }
[2021/06/22 06:44:25.952] Aborted



 Comments   
Comment by Githook User [ 21/Sep/21 ]

Author:

{'name': 'Kevin Albertson', 'email': 'kevin.albertson@mongodb.com', 'username': 'kevinAlbs'}

Message: CDRIVER-4035 use runAdminCommand.arguments.readPreference (#876)
Branch: r1.19
https://github.com/mongodb/mongo-c-driver/commit/5e33ee4287a501fd8097cefa1e765b5373b48325

Comment by Githook User [ 20/Sep/21 ]

Author:

{'name': 'Kevin Albertson', 'email': 'kevin.albertson@mongodb.com', 'username': 'kevinAlbs'}

Message: CDRIVER-4035 use runAdminCommand.arguments.readPreference (#876)
Branch: master
https://github.com/mongodb/mongo-c-driver/commit/766900bd336a2cd9069d952a73dd9e8986a5b81c

Generated at Wed Feb 07 21:19:45 UTC 2024 using Jira 9.7.1#970001-sha1:2222b88b221c4928ef0de3161136cc90c8356a66.