[2021/07/29 18:52:42.694] Begin /inheritance/count_documents/readPrefs, seed 1627584660
[2021/07/29 18:52:42.694] listening on port 61714
[2021/07/29 18:52:42.705] listening on port 61715
[2021/07/29 18:52:42.705]  0.00  61716 -> server port 61714 (connected)
[2021/07/29 18:52:42.705]  0.02  61717 -> server port 61715 (connected)
[2021/07/29 18:52:42.734]  0.05  61717 -> 61715 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true, "client" : { "driver" : { "name" : "mongoc", "version" : "1.18.0" }, "os" : { "type" : "Windows", "name" : "Windows", "version" : "6.1 (7601)", "architecture" : "x86_64" }, "platform" : "cfg=0x0204172063 CC=MSVC 1900 CFLAGS=\"/DWIN32 /D_WINDOWS /W3\" LDFLAGS=\"/machine:x64\"" }, "compression" : [  ] } flags=SECONDARY_OK n_return=-1
[2021/07/29 18:53:40.518]  0.05  61716 -> 61714 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true, "client" : { "driver" : { "name" : "mongoc", "version" : "1.18.0" }, "os" : { "type" : "Windows", "name" : "Windows", "version" : "6.1 (7601)", "architecture" : "x86_64" }, "platform" : "cfg=0x0204172063 CC=MSVC 1900 CFLAGS=\"/DWIN32 /D_WINDOWS /W3\" LDFLAGS=\"/machine:x64\"" }, "compression" : [  ] } flags=SECONDARY_OK n_return=-1
[2021/07/29 18:53:40.518]  0.05  61716 <- 61714 OP_REPLY { "ok" : 1, "isWritablePrimary" : true, "secondary" : false, "$clusterTime" : { "clusterTime" : { "$timestamp" : { "t" : 1, "i" : 1 } }, "signature" : { "hash" : { "$binary" : "", "$type" : "00" }, "keyId" : 6446735049323708417 }, "operationTime" : { "$timestamp" : { "t" : 1, "i" : 1 } } }, "logicalSessionTimeoutMinutes" : 30, "tags" : {  }, "maxWireVersion" : 6, "setName" : "rs", "hosts" : [ "127.0.0.1:61714", "127.0.0.1:61715" ], "ismaster" : true, "minWireVersion" : 3 }
[2021/07/29 18:53:40.518]  0.05  61717 <- 61715 OP_REPLY { "ok" : 1, "isWritablePrimary" : false, "secondary" : true, "$clusterTime" : { "clusterTime" : { "$timestamp" : { "t" : 1, "i" : 1 } }, "signature" : { "hash" : { "$binary" : "", "$type" : "00" }, "keyId" : 6446735049323708417 }, "operationTime" : { "$timestamp" : { "t" : 1, "i" : 1 } } }, "logicalSessionTimeoutMinutes" : 30, "tags" : { "client" : "yes", "database" : "yes", "collection" : "yes", "function" : "yes" }, "maxWireVersion" : 6, "setName" : "rs", "hosts" : [ "127.0.0.1:61714", "127.0.0.1:61715" ], "ismaster" : false, "minWireVersion" : 3 }
[2021/07/29 18:53:40.518]  0.05  61716 -> 61714 OP_MSG { "aggregate" : "collection", "cursor" : {  }, "pipeline" : [ { "$match" : {  } }, { "$group" : { "_id" : 1, "n" : { "$sum" : 1 } } } ], "$db" : "database", "lsid" : { "id" : { "$binary" : "wcImKoucQp+7OzilEkoKQw==", "$type" : "04" } }, "$clusterTime" : { "clusterTime" : { "$timestamp" : { "t" : 1, "i" : 1 } }, "signature" : { "hash" : { "$binary" : "", "$type" : "00" }, "keyId" : 6446735049323708417 }, "operationTime" : { "$timestamp" : { "t" : 1, "i" : 1 } } } }
[2021/07/29 18:53:40.518]  0.05  61716 <- 61714 OP_MSG { "ok" : 1 }
[2021/07/29 18:53:40.518]  0.05  61716 -> 61714 OP_MSG { "endSessions" : [ { "id" : { "$binary" : "wcImKoucQp+7OzilEkoKQw==", "$type" : "04" } } ], "$db" : "admin", "$clusterTime" : { "clusterTime" : { "$timestamp" : { "t" : 1, "i" : 1 } }, "signature" : { "hash" : { "$binary" : "", "$type" : "00" }, "keyId" : 6446735049323708417 }, "operationTime" : { "$timestamp" : { "t" : 1, "i" : 1 } } } }
[2021/07/29 18:53:40.518]  0.05  61716 <- 61714 OP_MSG { "ok" : 1 }
[2021/07/29 18:53:40.518]  0.05  61720 -> server port 61714 (connected)
[2021/07/29 18:53:40.518]  0.06  61721 -> server port 61715 (connected)
[2021/07/29 18:53:40.518]  0.08  61720 -> 61714 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true, "client" : { "driver" : { "name" : "mongoc", "version" : "1.18.0" }, "os" : { "type" : "Windows", "name" : "Windows", "version" : "6.1 (7601)", "architecture" : "x86_64" }, "platform" : "cfg=0x0204172063 CC=MSVC 1900 CFLAGS=\"/DWIN32 /D_WINDOWS /W3\" LDFLAGS=\"/machine:x64\"" }, "compression" : [  ] } flags=SECONDARY_OK n_return=-1
[2021/07/29 18:53:40.518]  0.08  61720 <- 61714 OP_REPLY { "ok" : 1, "isWritablePrimary" : true, "secondary" : false, "$clusterTime" : { "clusterTime" : { "$timestamp" : { "t" : 1, "i" : 1 } }, "signature" : { "hash" : { "$binary" : "", "$type" : "00" }, "keyId" : 6446735049323708417 }, "operationTime" : { "$timestamp" : { "t" : 1, "i" : 1 } } }, "logicalSessionTimeoutMinutes" : 30, "tags" : {  }, "maxWireVersion" : 6, "setName" : "rs", "hosts" : [ "127.0.0.1:61714", "127.0.0.1:61715" ], "ismaster" : true, "minWireVersion" : 3 }
[2021/07/29 18:53:40.518]  0.09  61721 -> 61715 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true, "client" : { "driver" : { "name" : "mongoc", "version" : "1.18.0" }, "os" : { "type" : "Windows", "name" : "Windows", "version" : "6.1 (7601)", "architecture" : "x86_64" }, "platform" : "cfg=0x0204172063 CC=MSVC 1900 CFLAGS=\"/DWIN32 /D_WINDOWS /W3\" LDFLAGS=\"/machine:x64\"" }, "compression" : [  ] } flags=SECONDARY_OK n_return=-1
[2021/07/29 18:53:44.034] Searching for libmongocrypt
[2021/07/29 18:53:44.034] Command failed: command encountered problem: error waiting on process '8de64248-aac7-4a48-9bca-60f84eb1f8af': exit status 3
[2021/07/29 18:53:44.034] Task completed - FAILURE.