[CDRIVER-4111] Mock server tests sometimes fail after receiving isMaster Created: 30/Jul/21  Updated: 27/Oct/23  Resolved: 28/Sep/23

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

Type: Task Priority: Unknown
Reporter: Kevin Albertson Assignee: Kevin Albertson
Resolution: Gone away Votes: 0
Labels: flaky-tests
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified

Attachments: File cdriver-4111.patch    
Issue Links:
Related
related to CDRIVER-4350 Unskip change stream and cursor tests Backlog
related to CDRIVER-4340 Mock server-related Client test failures Closed
related to CDRIVER-4134 /http test failing with "No response ... Closed
Epic Link: Stabilize Evergreen

 Description   

This debug-compile-compression-zlib task failed due to the mock server hanging to receive an isMaster command. Relevant logs:

[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.

These occurrences share similar characteristics on the same patch build:

  • debug-compile-sspi-openssl-patch failed after replying to an isMaster command due to the mock server still running after shutdown.
  • release-compile failed to receive a request to the mock server after receiving an isMaster on the mock server.


 Comments   
Comment by Kevin Albertson [ 28/Sep/23 ]

No observed failures from 2023-08-01 to 2023-09-28 for mock-server-test variant.

As of https://github.com/mongodb/mongo-c-driver/pull/1277, mock server tests are only run on ubuntu2204-small distro. The flaky test failure may no longer be applicable (ran on Windows 2015).

Closing as "Gone away".

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