[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