-
Type: Bug
-
Resolution: Duplicate
-
Priority: Major - P3
-
None
-
Affects Version/s: 3.6.7
-
Component/s: Querying
-
None
-
ALL
-
Getting cursor id not found errors from the server even though multiple getMore where emitted soon before. The cursor is not up since more than 10mi and anyhow if previous getMore worked within less than 10min this shouldn't happen.
Cursor is started and the error occurs at 2018-08-23T11:05:28.528Z
Many getMore are occuring the last 5 ones being respectively at:
- [2018-08-23T11:05:25.467Z] schedule getMore call for query {"bson":{},"ns":"fdp-collector.$cmd","query":{"find":"items","filter":
Unknown macro: {"contents.iid"}
,"projection":{},"batchSize":100,"returnKey":false,"showRecordId":false,"lsid":{"id":"TIRV4MZNTSON2fMvPNiSpw=="}},"options":{"numberToSkip":0,"numberToReturn":1,"checkKeys":false,"returnFieldSelector":null,"serializeFunctions":false,"ignoreUndefined":false},"numberToSkip":0,"numberToReturn":1,"returnFieldSelector":null,"requestId":154,"serializeFunctions":false,"ignoreUndefined":false,"maxBsonSize":16777216,"checkKeys":false,"batchSize":1,"tailable":false,"slaveOk":false,"oplogReplay":false,"noCursorTimeout":false,"awaitData":false,"exhaust":false,"partial":false,"documentsReturnedIn":"firstBatch"} cursorId: 19000582380
- [2018-08-23T11:05:26.378Z] schedule getMore call for query {"bson":{},"ns":"fdp-collector.$cmd","query":{"find":"items","filter":
Unknown macro: {"contents.iid"}
,"projection":{},"batchSize":100,"returnKey":false,"showRecordId":false,"lsid":{"id":"TIRV4MZNTSON2fMvPNiSpw=="}},"options":{"numberToSkip":0,"numberToReturn":1,"checkKeys":false,"returnFieldSelector":null,"serializeFunctions":false,"ignoreUndefined":false},"numberToSkip":0,"numberToReturn":1,"returnFieldSelector":null,"requestId":154,"serializeFunctions":false,"ignoreUndefined":false,"maxBsonSize":16777216,"checkKeys":false,"batchSize":1,"tailable":false,"slaveOk":false,"oplogReplay":false,"noCursorTimeout":false,"awaitData":false,"exhaust":false,"partial":false,"documentsReturnedIn":"firstBatch"}cursorId: 19000582380
- [2018-08-23T11:05:26.434Z] schedule getMore call for query {"bson":{},"ns":"fdp-collector.$cmd","query":{"find":"items","filter":
Unknown macro: {"contents.iid"}
,"projection":{},"batchSize":100,"returnKey":false,"showRecordId":false,"lsid":{"id":"TIRV4MZNTSON2fMvPNiSpw=="}},"options":{"numberToSkip":0,"numberToReturn":1,"checkKeys":false,"returnFieldSelector":null,"serializeFunctions":false,"ignoreUndefined":false},"numberToSkip":0,"numberToReturn":1,"returnFieldSelector":null,"requestId":154,"serializeFunctions":false,"ignoreUndefined":false,"maxBsonSize":16777216,"checkKeys":false,"batchSize":1,"tailable":false,"slaveOk":false,"oplogReplay":false,"noCursorTimeout":false,"awaitData":false,"exhaust":false,"partial":false,"documentsReturnedIn":"firstBatch"}cursorId: 19000582380
- [2018-08-23T11:05:27.096Z] schedule getMore call for query {"bson":{},"ns":"fdp-collector.$cmd","query":{"find":"items","filter":
Unknown macro: {"contents.iid"}
,"projection":{},"batchSize":100,"returnKey":false,"showRecordId":false,"lsid":{"id":"TIRV4MZNTSON2fMvPNiSpw=="}},"options":{"numberToSkip":0,"numberToReturn":1,"checkKeys":false,"returnFieldSelector":null,"serializeFunctions":false,"ignoreUndefined":false},"numberToSkip":0,"numberToReturn":1,"returnFieldSelector":null,"requestId":154,"serializeFunctions":false,"ignoreUndefined":false,"maxBsonSize":16777216,"checkKeys":false,"batchSize":1,"tailable":false,"slaveOk":false,"oplogReplay":false,"noCursorTimeout":false,"awaitData":false,"exhaust":false,"partial":false,"documentsReturnedIn":"firstBatch"}cursorId: 19000582380',
'[2018-08-23T11:05:27.333Z] schedule getMore call for query {"bson":{},"ns":"fdp-collector.$cmd","query":{"find":"items","filter":Unknown macro: {"contents.iid"},"projection":{},"batchSize":100,"returnKey":false,"showRecordId":false,"lsid":{"id":"TIRV4MZNTSON2fMvPNiSpw=="}},"options":{"numberToSkip":0,"numberToReturn":1,"checkKeys":false,"returnFieldSelector":null,"serializeFunctions":false,"ignoreUndefined":false},"numberToSkip":0,"numberToReturn":1,"returnFieldSelector":null,"requestId":154,"serializeFunctions":false,"ignoreUndefined":false,"maxBsonSize":16777216,"checkKeys":false,"batchSize":1,"tailable":false,"slaveOk":false,"oplogReplay":false,"noCursorTimeout":false,"awaitData":false,"exhaust":false,"partial":false,"documentsReturnedIn":"firstBatch"}cursorId: 19000582380
- [2018-08-23T11:05:27.914Z] schedule getMore call for query {"bson":{},"ns":"fdp-collector.$cmd","query":{"find":"items","filter":
Unknown macro: {"contents.iid"}
,"projection":{},"batchSize":100,"returnKey":false,"showRecordId":false,"lsid":{"id":"TIRV4MZNTSON2fMvPNiSpw=="}},"options":{"numberToSkip":0,"numberToReturn":1,"checkKeys":false,"returnFieldSelector":null,"serializeFunctions":false,"ignoreUndefined":false},"numberToSkip":0,"numberToReturn":1,"returnFieldSelector":null,"requestId":154,"serializeFunctions":false,"ignoreUndefined":false,"maxBsonSize":16777216,"checkKeys":false,"batchSize":1,"tailable":false,"slaveOk":false,"oplogReplay":false,"noCursorTimeout":false,"awaitData":false,"exhaust":false,"partial":false,"documentsReturnedIn":"firstBatch"}cursorId: 19000582380
As you can see the error happens less than a second after the last getMore
- duplicates
-
SERVER-36808 Server closes cursors that are still in use during session cache refresh
- Closed