Flaky Test: ensure monitors properly create and unpause connection pools when they discover servers [46]

XMLWordPrintableJSON

    • Type: Build Failure
    • Resolution: Fixed
    • Priority: Unknown
    • 6.21.0
    • Affects Version/s: None
    • Component/s: Flaky Test
    • 3
    • Not Needed
    • 🔵 Done
    • Hide

      Create a copy of the Kickoff Template with the issue key (NODE-XXX) in the filename and share a link to the new doc via this field.

      Show
      Create a copy of the Kickoff Template with the issue key (NODE-XXX) in the filename and share a link to the new doc via this field.
    • Not Needed
    • Hide

      1. What would you like to communicate to the user about this feature?
      2. Would you like the user to see examples of the syntax and/or executable code and its output?
      3. Which versions of the driver/connector does this apply to?

      Show
      1. What would you like to communicate to the user about this feature? 2. Would you like the user to see examples of the syntax and/or executable code and its output? 3. Which versions of the driver/connector does this apply to?
    • None
    • None
    • None
    • None
    • None
    • None

      https://evergreen.mongodb.com/task/mongo_node_driver_next_windows_64_vs2019_fermium_test_latest_server_v1_api_b439893b7404180e6d39f1c7efbb241042411b1f_23_04_12_20_22_08

      [2023/04/12 20:52:10.990]   1) Server Discovery and Monitoring Prose Tests
      [2023/04/12 20:52:10.990]        Connection Pool Management
      [2023/04/12 20:52:10.990]          ensure monitors properly create and unpause connection pools when they discover servers:
      [2023/04/12 20:52:10.990]       AssertionError: expected 'serverHeartbeatFailed' to equal 'serverHeartbeatSucceeded'
      [2023/04/12 20:52:10.990]       + expected - actual
      [2023/04/12 20:52:10.990]       -serverHeartbeatFailed
      [2023/04/12 20:52:10.990]       +serverHeartbeatSucceeded
      [2023/04/12 20:52:10.990]       
      [2023/04/12 20:52:10.990]       at Context.test (Z:\data\mci\5faec0d9a4c55281764356efcacc5cb6\src\test\integration\server-discovery-and-monitoring\server_discovery_and_monitoring.prose.test.ts:175:35)
      [2023/04/12 20:52:10.990]       at runMicrotasks (<anonymous>)
      [2023/04/12 20:52:10.990]       at processTicksAndRejections (internal/process/task_queues.js:95:5) 

      Captured logs from:
      https://evergreen.mongodb.com/task_log_raw/mongo_node_driver_next_rhel80_large_node_latest_test_latest_server_v1_api_2f67139da901d417c8dc065258bc7e6f1abaddca_25_09_02_20_22_24/0?type=T
       

      [2025/09/02 16:34:50.096] {"t":"2025-09-02T20:34:48.584Z","id":"68b754e88d511ad854d67eed","c":"topology","s":"debug","topologyId":6562,"message":"Starting topology monitoring"}
       [2025/09/02 16:34:50.096] {"t":"2025-09-02T20:34:48.584Z","id":"68b754e88d511ad854d67eed","c":"topology","s":"debug","topologyId":6562,"message":"Topology description changed","reply":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Unknown\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":0,\"roundTripTime\":-1,\"minRoundTripTime\":0,\"lastUpdateTime\":629884,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":null,\"se...","previousDescription":"{\"type\":\"Unknown\",\"servers\":{},\"stale\":false,\"compatible\":true,\"heartbeatFrequencyMS\":0,\"localThresholdMS\":15,\"setName\":null,\"maxElectionId\":null,\"maxSetVersion\":null,\"commonWireVersion\":0,\"logicalSessionTimeoutMinutes\":null}","newDescription":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Unknown\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":0,\"roundTripTime\":-1,\"minRoundTripTime\":0,\"lastUpdateTime\":629884,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":null,\"se..."}
       [2025/09/02 16:34:50.096] {"t":"2025-09-02T20:34:48.585Z","id":"68b754e88d511ad854d67eed","c":"topology","s":"debug","topologyId":6562,"serverHost":"localhost","serverPort":27017,"message":"Starting server monitoring"}
       [2025/09/02 16:34:50.096] {"t":"2025-09-02T20:34:48.586Z","id":"68b754e88d511ad854d67eed","c":"topology","s":"debug","topologyId":6562,"awaited":false,"driverConnectionId":"localhost:27017","serverHost":"localhost","serverPort":27017,"message":"Server heartbeat started"}
       [2025/09/02 16:34:50.096] {"t":"2025-09-02T20:34:48.586Z","id":"68b754e88d511ad854d67eed","c":"serverSelection","s":"debug","selector":"readPreferenceServers","operation":"handshake","topologyDescription":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Unknown\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":0,\"roundTripTime\":-1,\"minRoundTripTime\":0,\"lastUpdateTime\":629884,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":null,\"se...","message":"Server selection started"}
       [2025/09/02 16:34:50.096] {"t":"2025-09-02T20:34:48.586Z","id":"68b754e88d511ad854d67eed","c":"serverSelection","s":"info","selector":"readPreferenceServers","operation":"handshake","topologyDescription":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Unknown\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":0,\"roundTripTime\":-1,\"minRoundTripTime\":0,\"lastUpdateTime\":629884,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":null,\"se...","message":"Waiting for suitable server to become available","remainingTimeMS":30000}
       [2025/09/02 16:34:50.096] {"t":"2025-09-02T20:34:48.586Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection pool created","maxIdleTimeMS":0,"minPoolSize":0,"maxPoolSize":100,"maxConnecting":2,"waitQueueTimeoutMS":0}
       [2025/09/02 16:34:50.096] {"t":"2025-09-02T20:34:48.587Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection pool ready"}
       [2025/09/02 16:34:50.096] {"t":"2025-09-02T20:34:48.587Z","id":"68b754e88d511ad854d67eed","c":"serverSelection","s":"debug","selector":"readPreferenceServers","operation":"handshake","topologyDescription":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Unknown\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":0,\"roundTripTime\":-1,\"minRoundTripTime\":0,\"lastUpdateTime\":629884,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":null,\"se...","message":"Server selection succeeded","serverHost":"localhost","serverPort":27017}
       [2025/09/02 16:34:50.096] {"t":"2025-09-02T20:34:48.587Z","id":"68b754e88d511ad854d67eed","c":"topology","s":"debug","topologyId":6562,"message":"Topology description changed","reply":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Standalone\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":28,\"roundTripTime\":1,\"minRoundTripTime\":0,\"lastUpdateTime\":629887,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":{\"pro...","previousDescription":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Unknown\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":0,\"roundTripTime\":-1,\"minRoundTripTime\":0,\"lastUpdateTime\":629884,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":null,\"se...","newDescription":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Standalone\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":28,\"roundTripTime\":1,\"minRoundTripTime\":0,\"lastUpdateTime\":629887,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":{\"pro..."}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:48.587Z","id":"68b754e88d511ad854d67eed","c":"topology","s":"debug","topologyId":6562,"awaited":true,"driverConnectionId":"localhost:27017","serverHost":"localhost","serverPort":27017,"message":"Server heartbeat succeeded","durationMS":1,"serverConnectionId":13647,"reply":"{\"helloOk\":true,\"isWritablePrimary\":true,\"topologyVersion\":{\"processId\":{\"$oid\":\"68b75301b6599644f66d16f9\"},\"counter\":0},\"maxBsonObjectSize\":16777216,\"maxMessageSizeBytes\":48000000,\"maxWriteBatchSize\":100000,\"localTime\":{\"$date\":\"2025-09-02T20:34:48.587Z\"},\"logicalSessionTimeoutMinutes\":30,\"connecti..."}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:48.587Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection checkout started"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:48.587Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection created","driverConnectionId":1}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:48.588Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection ready","driverConnectionId":1,"durationMS":1}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:48.588Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection checked out","driverConnectionId":1,"durationMS":1}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:48.588Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection checked in","driverConnectionId":1}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:48.589Z","id":"68b754e88d511ad854d67eed","c":"serverSelection","s":"debug","selector":"{\"mode\":\"primary\",\"tags\":null,\"hedge\":null,\"maxStalenessSeconds\":null,\"minWireVersion\":null}","operation":"runCommand","topologyDescription":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Standalone\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":28,\"roundTripTime\":1,\"minRoundTripTime\":0,\"lastUpdateTime\":629887,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":{\"pro...","message":"Server selection started"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:48.589Z","id":"68b754e88d511ad854d67eed","c":"serverSelection","s":"debug","selector":"readPreferenceServers","operation":"runCommand","topologyDescription":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Standalone\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":28,\"roundTripTime\":1,\"minRoundTripTime\":0,\"lastUpdateTime\":629887,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":{\"pro...","message":"Server selection succeeded","serverHost":"localhost","serverPort":27017}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:48.589Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection checkout started"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:48.589Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection checked out","driverConnectionId":1,"durationMS":0}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:48.589Z","id":"68b754e88d511ad854d67eed","c":"command","s":"debug","commandName":"configureFailPoint","requestId":69374,"driverConnectionId":1,"serverHost":"::1","serverPort":27017,"databaseName":"admin","serverConnectionId":{"bigint":{"$numberLong":"13648"}},"message":"Command started","command":"{\"configureFailPoint\":\"failCommand\",\"mode\":{\"times\":2},\"data\":{\"failCommands\":[\"hello\"],\"errorCode\":1234,\"appName\":\"SDAMPoolManagementTest\"},\"apiVersion\":\"1\",\"lsid\":{\"id\":{\"$binary\":{\"base64\":\"C6/Fi2PkSSG/iYN79fAXCQ==\",\"subType\":\"04\"}}},\"$db\":\"admin\"}"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:48.589Z","id":"68b754e88d511ad854d67eed","c":"command","s":"debug","commandName":"configureFailPoint","requestId":69374,"driverConnectionId":1,"serverHost":"::1","serverPort":27017,"databaseName":"admin","serverConnectionId":{"bigint":{"$numberLong":"13648"}},"message":"Command succeeded","durationMS":0,"reply":"{\"count\":509,\"ok\":1}"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:48.589Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection checked in","driverConnectionId":1}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:49.086Z","id":"68b754e88d511ad854d67eed","c":"topology","s":"debug","topologyId":6562,"awaited":true,"driverConnectionId":"localhost:27017","serverHost":"localhost","serverPort":27017,"message":"Server heartbeat started"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:49.087Z","id":"68b754e88d511ad854d67eed","c":"topology","s":"debug","topologyId":6562,"awaited":true,"driverConnectionId":"localhost:27017","serverHost":"localhost","serverPort":27017,"message":"Server heartbeat failed","durationMS":1,"failure":"Failing command via 'failCommand' failpoint"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:49.087Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection pool cleared"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:49.087Z","id":"68b754e88d511ad854d67eed","c":"topology","s":"debug","topologyId":6562,"message":"Topology description changed","reply":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Unknown\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":0,\"roundTripTime\":-1,\"minRoundTripTime\":0,\"lastUpdateTime\":630387,\"lastWriteDate\":0,\"error\":{\"errorLabelSet\":{},\"errorRespo...","previousDescription":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Standalone\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":28,\"roundTripTime\":1,\"minRoundTripTime\":0,\"lastUpdateTime\":629887,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":{\"pro...","newDescription":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Unknown\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":0,\"roundTripTime\":-1,\"minRoundTripTime\":0,\"lastUpdateTime\":630387,\"lastWriteDate\":0,\"error\":{\"errorLabelSet\":{},\"errorRespo..."}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:49.588Z","id":"68b754e88d511ad854d67eed","c":"topology","s":"debug","topologyId":6562,"awaited":false,"driverConnectionId":"localhost:27017","serverHost":"localhost","serverPort":27017,"message":"Server heartbeat started"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:49.590Z","id":"68b754e88d511ad854d67eed","c":"topology","s":"debug","topologyId":6562,"awaited":false,"driverConnectionId":"localhost:27017","serverHost":"localhost","serverPort":27017,"message":"Server heartbeat failed","durationMS":0,"failure":"Failing command via 'failCommand' failpoint"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.090Z","id":"68b754e88d511ad854d67eed","c":"topology","s":"debug","topologyId":6562,"awaited":false,"driverConnectionId":"localhost:27017","serverHost":"localhost","serverPort":27017,"message":"Server heartbeat started"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.091Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection pool ready"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.092Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection closed","driverConnectionId":1,"reason":"Connection became stale because the pool was cleared"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.092Z","id":"68b754e88d511ad854d67eed","c":"topology","s":"debug","topologyId":6562,"message":"Topology description changed","reply":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Standalone\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":28,\"roundTripTime\":1,\"minRoundTripTime\":1,\"lastUpdateTime\":631391,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":{\"pro...","previousDescription":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Unknown\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":0,\"roundTripTime\":-1,\"minRoundTripTime\":0,\"lastUpdateTime\":630889,\"lastWriteDate\":0,\"error\":{\"errorLabelSet\":{},\"errorRespo...","newDescription":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Standalone\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":28,\"roundTripTime\":1,\"minRoundTripTime\":1,\"lastUpdateTime\":631391,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":{\"pro..."}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.092Z","id":"68b754e88d511ad854d67eed","c":"topology","s":"debug","topologyId":6562,"awaited":true,"driverConnectionId":"localhost:27017","serverHost":"localhost","serverPort":27017,"message":"Server heartbeat succeeded","durationMS":1,"serverConnectionId":13651,"reply":"{\"helloOk\":true,\"isWritablePrimary\":true,\"topologyVersion\":{\"processId\":{\"$oid\":\"68b75301b6599644f66d16f9\"},\"counter\":0},\"maxBsonObjectSize\":16777216,\"maxMessageSizeBytes\":48000000,\"maxWriteBatchSize\":100000,\"localTime\":{\"$date\":\"2025-09-02T20:34:50.091Z\"},\"logicalSessionTimeoutMinutes\":30,\"connecti..."}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.093Z","id":"68b754e88d511ad854d67eed","c":"serverSelection","s":"debug","selector":"{\"mode\":\"primary\",\"tags\":null,\"hedge\":null,\"maxStalenessSeconds\":null,\"minWireVersion\":null}","operation":"runCommand","topologyDescription":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Standalone\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":28,\"roundTripTime\":1,\"minRoundTripTime\":1,\"lastUpdateTime\":631391,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":{\"pro...","message":"Server selection started"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.093Z","id":"68b754e88d511ad854d67eed","c":"serverSelection","s":"debug","selector":"readPreferenceServers","operation":"runCommand","topologyDescription":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Standalone\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":28,\"roundTripTime\":1,\"minRoundTripTime\":1,\"lastUpdateTime\":631391,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":{\"pro...","message":"Server selection succeeded","serverHost":"localhost","serverPort":27017}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.093Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection checkout started"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.093Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection created","driverConnectionId":2}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.095Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection ready","driverConnectionId":2,"durationMS":2}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.095Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection checked out","driverConnectionId":2,"durationMS":2}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.095Z","id":"68b754e88d511ad854d67eed","c":"command","s":"debug","commandName":"configureFailPoint","requestId":69381,"driverConnectionId":2,"serverHost":"::1","serverPort":27017,"databaseName":"admin","serverConnectionId":{"bigint":{"$numberLong":"13652"}},"message":"Command started","command":"{\"configureFailPoint\":\"failCommand\",\"mode\":\"off\",\"data\":{\"failCommands\":[\"hello\"],\"errorCode\":1234,\"appName\":\"SDAMPoolManagementTest\"},\"apiVersion\":\"1\",\"lsid\":{\"id\":{\"$binary\":{\"base64\":\"C6/Fi2PkSSG/iYN79fAXCQ==\",\"subType\":\"04\"}}},\"$db\":\"admin\"}"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.095Z","id":"68b754e88d511ad854d67eed","c":"command","s":"debug","commandName":"configureFailPoint","requestId":69381,"driverConnectionId":2,"serverHost":"::1","serverPort":27017,"databaseName":"admin","serverConnectionId":{"bigint":{"$numberLong":"13652"}},"message":"Command succeeded","durationMS":0,"reply":"{\"count\":512,\"ok\":1}"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.095Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection checked in","driverConnectionId":2}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.095Z","id":"68b754e88d511ad854d67eed","c":"serverSelection","s":"debug","selector":"{\"mode\":\"primary\",\"tags\":null,\"hedge\":null,\"maxStalenessSeconds\":null,\"minWireVersion\":null}","operation":"endSessions","topologyDescription":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Standalone\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":28,\"roundTripTime\":1,\"minRoundTripTime\":1,\"lastUpdateTime\":631391,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":{\"pro...","message":"Server selection started"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.096Z","id":"68b754e88d511ad854d67eed","c":"serverSelection","s":"debug","selector":"readPreferenceServers","operation":"endSessions","topologyDescription":"{\"type\":\"Single\",\"servers\":{\"localhost:27017\":{\"address\":\"localhost:27017\",\"type\":\"Standalone\",\"hosts\":[],\"passives\":[],\"arbiters\":[],\"tags\":{},\"minWireVersion\":0,\"maxWireVersion\":28,\"roundTripTime\":1,\"minRoundTripTime\":1,\"lastUpdateTime\":631391,\"lastWriteDate\":0,\"error\":null,\"topologyVersion\":{\"pro...","message":"Server selection succeeded","serverHost":"localhost","serverPort":27017}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.096Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection checkout started"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.096Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection checked out","driverConnectionId":2,"durationMS":0}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.096Z","id":"68b754e88d511ad854d67eed","c":"command","s":"debug","commandName":"endSessions","requestId":69382,"driverConnectionId":2,"serverHost":"::1","serverPort":27017,"databaseName":"admin","serverConnectionId":{"bigint":{"$numberLong":"13652"}},"message":"Command started","command":"{\"endSessions\":[{\"id\":{\"$binary\":{\"base64\":\"C6/Fi2PkSSG/iYN79fAXCQ==\",\"subType\":\"04\"}}}],\"apiVersion\":\"1\",\"$db\":\"admin\"}"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.096Z","id":"68b754e88d511ad854d67eed","c":"command","s":"debug","commandName":"endSessions","requestId":69382,"driverConnectionId":2,"serverHost":"::1","serverPort":27017,"databaseName":"admin","serverConnectionId":{"bigint":{"$numberLong":"13652"}},"message":"Command succeeded","durationMS":1,"reply":"null"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.096Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection checked in","driverConnectionId":2}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.096Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection closed","driverConnectionId":2,"reason":"Connection pool was closed"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.096Z","id":"68b754e88d511ad854d67eed","c":"connection","s":"debug","serverHost":"localhost","serverPort":27017,"message":"Connection pool closed"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.096Z","id":"68b754e88d511ad854d67eed","c":"topology","s":"debug","topologyId":6562,"serverHost":"localhost","serverPort":27017,"message":"Stopped server monitoring"}
       [2025/09/02 16:34:50.097] {"t":"2025-09-02T20:34:50.096Z","id":"68b754e88d511ad854d67eed","c":"topology","s":"debug","topologyId":6562,"message":"Stopped topology monitoring"}

      AC

      • Investigate the logs and try to find and address the root cause of the flakiness; send back to team if path forward is still unclear

            Assignee:
            Durran Jordan
            Reporter:
            Daria Pardue
            Bailey Pearson
            Votes:
            0 Vote for this issue
            Watchers:
            9 Start watching this issue

              Created:
              Updated:
              Resolved: