When running profile_operation_metrics.js, it's possible for queries to yield. Each yield results in the cursorSeeks metric being incremented. There's a build failure on the debug variants experiencing this behaviour, specifically the findIxScanAndFetch test in profile_operation_metrics.js.
On a successful run, where there's no yielding, there are 2 cursorSeeks. This is what the test expects.
[conn1] "Slow query","attr":{"type":"command","ns":"profile_operation_metrics.coll","appName":"MongoDB Shell","command":{"find":"coll","filter":{"_id":1},"lsid":{"id":{"$uuid":"a910abbe-144f-45a6-86b3-efa816fc27ab"}},"$db":"profile_operation_metrics"},"planSummary":"IXSCAN { _id: 1 }","keysExamined":1,"docsExamined":1,"fromMultiPlanner":true,"cursorExhausted":true,"numYields":0,"nreturned":1,"reslen":151,"locks":{"Global":{"acquireCount":{"r":3}},"Mutex":{"acquireCount":{"r":3}}},"storage":{},"operationMetrics":{"docBytesRead":29,"docUnitsRead":1,"idxEntryBytesRead":3,"idxEntryUnitsRead":1,"docUnitsReturned":1,"cursorSeeks":2,"cpuNanos":4718330},"remote":"127.0.0.1:44910","protocol":"op_msg","durationMillis":4}
But when there's a yield, there are 3 cursorSeeks, causing the test to fail.
[conn1] "Slow query","attr":{"type":"command","ns":"profile_operation_metrics.coll","appName":"MongoDB Shell","command":{"find":"coll","filter":{"_id":1},"lsid":{"id":{"$uuid":"ebdfd9b7-35b7-429a-aba5-315b45fef51f"}},"$db":"profile_operation_metrics"},"planSummary":"IXSCAN { _id: 1 }","keysExamined":1,"docsExamined":1,"fromMultiPlanner":true,"cursorExhausted":true,"numYields":1,"nreturned":1,"reslen":151,"locks":{"Global":{"acquireCount":{"r":6}},"Mutex":{"acquireCount":{"r":5}}},"storage":{},"operationMetrics":{"docBytesRead":29,"docUnitsRead":1,"idxEntryBytesRead":3,"idxEntryUnitsRead":1,"docUnitsReturned":1,"cursorSeeks":3,"cpuNanos":6101484},"remote":"127.0.0.1:43070","protocol":"op_msg","durationMillis":27}
- is related to
-
SERVER-57501 Disable profile_operation_metrics.js temporarily
- Closed