[CDRIVER-4257] /Topology/slow_server/pooled test failure Created: 29/Dec/21  Updated: 28/Oct/23  Resolved: 09/Oct/23

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

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

Epic Link: Stabilize Evergreen

 Description   

Example failure with logs:

Begin /Topology/slow_server/pooled, seed 1640805829
listening on port 54992
listening on port 54993
 0.00  54994 -> server port 54992 (connected)
 0.00  54994 -> 54992 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=0x02041720e9 CC=MSVC 1900 CFLAGS=\"/DWIN32 /D_WINDOWS /W3\" LDFLAGS=\"/machine:x64\"" }, "compression" : [  ] } flags=SECONDARY_OK n_return=-1
 0.00  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.00  54995 -> server port 54993 (connected)
 0.00  54995 -> 54993 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=0x02041720e9 CC=MSVC 1900 CFLAGS=\"/DWIN32 /D_WINDOWS /W3\" LDFLAGS=\"/machine:x64\"" }, "compression" : [  ] } flags=SECONDARY_OK n_return=-1
 0.01  54996 -> server port 54992 (connected)
 0.03  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
 0.03  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.05  54996 -> 54992 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=0x02041720e9 CC=MSVC 1900 CFLAGS=\"/DWIN32 /D_WINDOWS /W3\" LDFLAGS=\"/machine:x64\"" }, "compression" : [  ] } flags=SECONDARY_OK n_return=-1
 0.05  54996 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.05  54996 -> 54992 OP_MSG { "ping" : 1, "$db" : "admin" }
 0.05  54996 <- 54992 OP_MSG { "ok" : 1 }
 0.06  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
 0.06  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.11  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
 0.11  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.14  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
 0.14  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.16  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
 0.16  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.19  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
Searching for libmongocrypt
FAIL:C:\data\mci\d19805a62718e12922158d23e8ced03b\mongoc\src\libmongoc\tests\test-mongoc-topology.c:2207  test_slow_server_pooled()
  ret
  No suitable servers found: `serverSelectionTimeoutMS` expired
 0.19  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.22  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
Process was interrupted by the delivery of a signal.
  SIGABRT - Abnormal termination
 0.22  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.25  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
 0.25  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.28  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
 0.28  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.31  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
 0.31  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.34  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
 0.34  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.37  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
 0.37  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.41  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
 0.41  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.44  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
 0.44  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.47  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
 0.47  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.50  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
 0.50  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.53  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
 0.53  54994 <- 54992 OP_REPLY { "ok" : 1, "setName" : "rs", "hosts" : [ "127.0.0.1:54992", "127.0.0.1:54993" ], "maxWireVersion" : 14, "isWritablePrimary" : true, "secondary" : false, "ismaster" : true, "minWireVersion" : 3 }
 0.56  54994 -> 54992 OP_QUERY admin.$cmd { "isMaster" : 1, "helloOk" : true } flags=SECONDARY_OK n_return=-1
Command failed: command encountered problem: error waiting on process '7201d118-09d9-46fd-8e2d-343451ed88d2': exit status 22
Task completed - FAILURE.



 Comments   
Comment by Githook User [ 09/Oct/23 ]

Author:

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

Message: CDRIVER-4257 unskip `/Topology/slow_server/pooled` (#1418)
Branch: master
https://github.com/mongodb/mongo-c-driver/commit/93ddd71898cac570bb687b13f13cc1f6b0d41a26

Comment by Kevin Albertson [ 02/Oct/23 ]

Test is currently skipped.

Unskipping test and running five times in Evergreen resulted in success: https://spruce.mongodb.com/version/6515985ea4cf4765a33fb138

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

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