MongoDB 排序内存使用问题

背景

某次在客户现场处理一起APP业务中页面访问异常的问题,该页面直接是返回一行行硕大的报错代码,错误大概如下所示:

MongoDB.Driver.MongoQueryException: QueryFailure flag was Executor error: OperationFailed: Sort operation used more than the maximum 33554432 bytes of RAM. Add an index, or specify a smaller limit

报错页面很明显告知了问题排查的方向:

  • Sort operation 该页面涉及的MongoDB查询语句使用了排序。

  • more than the maximum 33554432 排序操作超过了MongoDB单个Session排序可使用的最大内存限制。

检索MongoDB的日志确实存在大量的查询报错,跟APP页面报错能够对应上;并且日志中排序使用的字段为DT_id,升序排序。

# 涉及业务敏感字,全文会略过、改写或使用'xxx'代替

2019-XX-XXTXX:XX:XX.XXX+0800 E QUERY    [conn3644666] Plan executor error during find: FAILURE, ·········· sortPattern: {DT: 1, _id: 1 }, memUsage: 33555513, memLimit: 33554432, ·············· }

2019-XX-XXTXX:XX:XX.XXX+0800 I QUERY    [conn3644666] assertion 17144 Executor error: OperationFailed: Sort operation used more than the maximum 33554432 bytes of RAM. Add an index, or specify a smaller limit. ns:XXXXX query:{ $query:········  $orderby: { DT: 1, _id: 1 }, $hint: { CID: 1, CVX: 1 } }

配置参数检查

MongoDB Server中确认了对于Sort排序能够支持的最大内存限制为32M。

> use admin
switched to db admin
> db.runCommand({ getParameter : 1, "internalQueryExecMaxBlockingSortBytes" : 1 } )
{ "internalQueryExecMaxBlockingSortBytes" : 33554432, "ok" : 1 }

排序字段是否存在索引

根据报错信息的建议,查看官方文档的解释:

In MongoDB, sort operations can obtain the sort order by retrieving documents based on the ordering in an index. If the query planner cannot obtain the sort order from an index, it will sort the results in memory. Sort operations that use an index often have better performance than those that do not use an index. In addition, sort operations that do not use an index will abort when they use 32 megabytes of memory.

  • 文档中意思大概是:在排序字段未利用到索引的情况下,若超过32M内存则会被Abort,语句直接返回报错。

那么现在方向基本可以锁定在排序操作是否使用到索引了;查看该集合状态,排序字段DT_id确实存在索引_id_DT_1DT_1_CID_1_id_1,为啥还会报错?带着疑问我们下文在测试环境进行模拟。

> db.xxx.getIndexes()
[
·········
    {
        "v" : 1,
        "key" : {
            "_id" : 1
        },
        "name" : "_id_",
        "ns" : "xxx.xxx"
    },
    {
        "v" : 1,
        "key" : {
            "DT" : 1
        },
        "name" : "DT_1",
        "ns" : "xxx.xxx"
    },
    {
        "v" : 1,
        "key" : {
            "DT" : 1,
            "CID" : 1,
            "_id" : 1
        },
        "name" : "DT_1_CID_1_id_1",
        "ns" : "xxx.xxx"
    }
···········

测试环境模拟索引对排序的影响

测试环境信息

MongoDB版本 4.0.10
MongoDB 存储引擎 wiredTiger
数据量 1000000
测试集合名 data_test
  • 集合数据存储格式
> db.data_test.findOne()
{
    "_id" : ObjectId("5d0872dc5f13ad3173457186"),
    "Name" : "Edison",
    "Num" : 195930,
    "loc" : {
        "type" : "Point",
        "coordinates" : [
            118.0222094243601,
            36.610739264097646
        ]
    }
}
  • 集合索引信息
> db.data_test.getIndexes()
[
    {
        "v" : 2,
        "key" : {
            "_id" : 1
        },
        "name" : "_id_",
        "ns" : "mongobench.data_test"
    },
    {
        "v" : 2,
        "key" : {
            "Name" : 1
        },
        "name" : "Name_1",
        "ns" : "mongobench.data_test"
    },
    {
        "v" : 2,
        "key" : {
            "Num" : 1
        },
        "name" : "Num_1",
        "ns" : "mongobench.data_test"
    },
    {
        "v" : 2,
        "key" : {
            "Num" : 1,
            "Name" : 1,
            "_id" : 1
        },
        "name" : "Num_1_Name_1__id_1",
        "ns" : "mongobench.data_test"
    }
]
  • 查询语句
    • 为测试方便,将业务中报错的聚合查询按同样查询逻辑修改为 Mongo Shell 中的普通 find() 查询

报错语句的执行计划解释

测试查询报错的语句,尝试查看其查询计划如下:

> db.data_test.find({'Num':{"$gt":500000}}).sort({"Num":1,"_id":1}).explain()
2019-06-19T18:21:14.745+0800 E QUERY    [js] Error: explain failed: {
    "ok" : 0,
    "errmsg" : "Sort operation used more than the maximum 33554432 bytes of RAM. Add an index, or specify a smaller limit.",
    "code" : 96,
    "codeName" : "OperationFailed"
}

直接报错,这里有个疑问为啥连执行计划都看不了?先不急,我们先删除对于排序字段的组合索引Num_1_Name_1__id_1后,再查看执行计划:

> db.data_test.dropIndex('Num_1_Name_1__id_1')
{ "nIndexesWas" : 4, "ok" : 1 }
> db.data_test.find({'Num':{"$gt":500000}}).sort({"Num":1,"_id":1}).explain('executionStats')
{
    "queryPlanner" : {
        "plannerVersion" : 1,
        "namespace" : "mongobench.data_test",
        "indexFilterSet" : false,
        "parsedQuery" : {
            "Num" : {
                "$gt" : 500000
            }
        },
        "winningPlan" : {
            "stage" : "SORT",
            "sortPattern" : {
                "Num" : 1,
                "_id" : 1
            },
            "inputStage" : {
                "stage" : "SORT_KEY_GENERATOR",
·······
        "rejectedPlans" : [ ]
    },
    "executionStats" : {
        "executionSuccess" : false,
        "errorMessage" : "Exec error resulting in state FAILURE :: caused by :: Sort operation used more than the maximum 33554432 bytes of RAM. Add an index, or specify a smaller limit.",
        "errorCode" : 96,
        "nReturned" : 0,
        "executionTimeMillis" : 1504,
        "totalKeysExamined" : 275037,
        "totalDocsExamined" : 275037,
        "executionStages" : {
            "stage" : "SORT",
            "nReturned" : 0,
            "executionTimeMillisEstimate" : 188,
····
            "memUsage" : 33554514,
            "memLimit" : 33554432,
            "inputStage" : {
                "stage" : "SORT_KEY_GENERATOR",
                "nReturned" : 275037,
·····

查询计划中关键参数的解释:

  • queryPlanner:explain中三种模式之一,默认模式。表示不会执行查询语句而是选出最优的查询计划即winning plan,剩余两种模式分别是 executionStats 和 allPlansExecution

    • winningPlan:MongoDB优化器选择的最优执行计划
      • stage:包括COLLSCAN 全表扫描、IXSCAN 索引扫描、FETCH 根据索引去检索指定文档、SORT 在内存中进行排序(未使用索引)
      • sortPattern:需排序的字段
      • inputStage:winningPlan.stage的子阶段
    • rejectedPlans:优化器弃用的执行计划
  • executionStats:返回执行结果的状态,如语句成功或失败等

    • executionSuccess:语句执行是否成功
    • errorMessage:错误信息
    • nReturned:返回的记录数
    • totalKeysExamined:索引扫描总行数
    • totalDocsExamined:文档扫描总行数
    • memUsage:Sort 使用内存排序操作使用的内存大小
    • memLimit:MongoDB 内部限制Sort操作的最大内存

上述执行计划表明查询语句在未使用索引排序的情况下如果排序使用的内存超过32M必定会报错,那么为什么没有使用到索引排序,是不是跟组合索引的顺序有关?

建立新的组合索引进行测试

直接创建Num_id列都为升序的组合索引,再次查看执行计划:

> db.data_test.ensureIndex({Num:1,_id:1})
{
    "createdCollectionAutomatically" : false,
    "numIndexesBefore" : 3,
    "numIndexesAfter" : 4,
    "ok" : 1
}
> db.data_test.find({'Num':{"$gt":500000}}).sort({"Num":1,"_id":1}).explain('executionStats')
{
    "queryPlanner" : {
        "plannerVersion" : 1,
        "namespace" : "mongobench.data_test",
        "indexFilterSet" : false,
        "parsedQuery" : {
            "Num" : {
                "$gt" : 500000
            }
        },
        "winningPlan" : {
            "stage" : "FETCH",
            "inputStage" : {
                "stage" : "IXSCAN",
                "keyPattern" : {
                    "Num" : 1,
                    "_id" : 1
                },
                "indexName" : "Num_1__id_1",
·········
        "rejectedPlans" : [
            {
                "stage" : "SORT",
                "sortPattern" : {
                    "Num" : 1,
                    "_id" : 1
                },
                "inputStage" : {
                    "stage" : "SORT_KEY_GENERATOR",
·········
    "executionStats" : {
        "executionSuccess" : true,
        "nReturned" : 499167,
        "executionTimeMillis" : 1355,
        "totalKeysExamined" : 499167,
        "totalDocsExamined" : 499167,
        "executionStages" : {
            "stage" : "FETCH",
            "nReturned" : 499167,
            "executionTimeMillisEstimate" : 102,
            "works" : 499168,
            "advanced" : 499167,
            "needTime" : 0,
            "needYield" : 0,
            "saveState" : 3901,
            "restoreState" : 3901,
            "isEOF" : 1,
            "invalidates" : 0,
            "docsExamined" : 499167,
            "alreadyHasObj" : 0,
            "inputStage" : {
                "stage" : "IXSCAN",
                "nReturned" : 499167,
                "executionTimeMillisEstimate" : 14,
                "works" : 499168,
·······

上述执行计划说明:

  • winningPlan.stage:优化器选择了FETCH+IXSCAN的Stage,而不是之前的Sort;这是最优的方式之一,也就是通过索引检索指定的文档数据,并在索引中完成排序("keyPattern" : {"Num" : 1,"_id" : 1}),效率最高
  • rejectedPlans:Sort 使用内存排序的方式被优化器弃用
  • executionSuccess:语句执行成功
  • nReturned:语句返回结果数为499167

引申的组合索引问题

上文中查询语句explain()直接报错,是因为组合索引为{Num_1_Name_1__id_1},而查询语句为sort({"Num":1,"_id":1}),未遵循最左原则,索引无法被使用到而后优化器选择Sort Stage触发了内存限制并Abort。

至于为啥MongoDB连执行计划都不返回给你,可以后续再讨论,欢迎评论

创建合适的组合索引后,查询语句成功执行;那么如果不按照索引的升降顺序执行语句会怎样?

查询语句中,排序字段_id 使用降序

当前的组合索引为{"key" : {"Num" : 1, "_id" : 1} },也就是都为升序,而我们将查询语句中排序字段_id使用降序排序时,查询语句直接报错,说明该语句也未使用到索引排序,而是使用的Sort Stage。

> db.data_test.find({'Num':{"$gt":500000}}).sort({"Num":1,"_id":-1}).explain('executionStats')
2019-06-19T19:32:30.939+0800 E QUERY    [js] Error: explain failed: {
    "ok" : 0,
    "errmsg" : "Sort operation used more than the maximum 33554432 bytes of RAM. Add an index, or specify a smaller limit.",
    "code" : 96,
    "codeName" : "OperationFailed"
}

查询语句中,排序字段Num_id 全部使用降序

我们现在将查询语句的排序字段全部使用降序,与组合索引全部相反再测试,执行成功。

> db.data_test.find({'Num':{"$gt":500000}}).sort({"Num":-1,"_id":-1}).explain('executionStats')
{
    "queryPlanner" : {
······
        "winningPlan" : {
            "stage" : "FETCH",
            "inputStage" : {
                "stage" : "IXSCAN",
                "keyPattern" : {
                    "Num" : 1,
                    "_id" : 1
                },
                "indexName" : "Num_1__id_1",
·······
        "rejectedPlans" : [
            {
                "stage" : "SORT",
·······
    "executionStats" : {
        "executionSuccess" : true,
·······
            "inputStage" : {
                "stage" : "IXSCAN",
·······
                "indexName" : "Num_1__id_1",
······
    "ok" : 1
}

再次做其他查询组合测试 sort({"Num":-1,"_id":1}),执行依然失败;说明只有在排序列的升降序只有和组合索引中的 方向 保持 全部相同 或 全部相反,语句执行才能成功。

引申的聚合查询问题

上文中的查询测试语句是在 MongoDB Shell 执行的 find() 查询方法,但是业务程序中查询一般都是使用聚合查询方法 aggregate(),对于聚合查询中的Sort Stage,官方文档说明了使用内存排序能使用最大的内存为 100M,若需要避免报错则需要添加 {allowDiskUse : true} 参数。

The sort stage has a limit of 100 megabytes of RAM. By default, if the stage exceeds this limit,sort will produce an error. To allow for the handling of large datasets, set the allowDiskUse option to true to enable $sort operations to write to temporary files. See the allowDiskUse option in db.collection.aggregate() method and the aggregate command for details.

Sort stage 使用内存排序

将普通的 find() 方法转为 aggregate() 聚合方法,语义不变,特意将排序字段 _id修改为 降序-1,那么查询计划将无法使用到组合索引只能使用Sort stage。下文中查询依然报错,Sort stage操作使用的内存超过100M

> db.data_test.explain('executionStats').aggregate([{ $match : { Num : { $gt : 500000} } },{ $sort : { "Num" : 1, _id: -1 } }])
2019-06-19T20:28:43.859+0800 E QUERY    [js] Error: explain failed: {
    "ok" : 0,
    "errmsg" : "Sort exceeded memory limit of 104857600 bytes, but did not opt in to external sorting. Aborting operation. Pass allowDiskUse:true to opt in.",
    "code" : 16819,
    "codeName" : "Location16819"
} :
_getErrorWithCode@src/mongo/shell/utils.js:25:13
throwOrReturn@src/mongo/shell/explainable.js:31:1
constructor/this.aggregate@src/mongo/shell/explainable.js:121:1
@(shell):1:1

添加 {allowDiskUse: true} 参数,可以使Sort stage操作绕过内存限制而使用磁盘,查询语句可以执行成功:

> db.data_test.explain('executionStats').aggregate([{ $match : { Num : { $gt : 500000} } },{ $sort : { "Num" : 1, _id: -1 } }],{allowDiskUse: true})
{
    "stages" : [
······
                "executionStats" : {
                    "executionSuccess" : true,
                    "nReturned" : 499167,
                    "executionTimeMillis" : 4128,
                    "totalKeysExamined" : 499167,
                    "totalDocsExamined" : 499167,
······
        {
            "$sort" : {
                "sortKey" : {
                    "Num" : 1,
                    "_id" : -1
                }
            }
        }
    ],
    "ok" : 1
}

结论

排序内存限制的问题

MongoDB使用内存进行排序的场景只有是Sort stage,官方文档有说明:

If MongoDB can use an index scan to obtain the requested sort order, the result will not include a SORT stage. Otherwise, if MongoDB cannot use the index to sort, the explain result will include a SORT stage.

意思大概是如果MongoDB可以使用索引扫描来进行排序,那么结果将不包括SORT stage。否则如果MongoDB无法使用索引进行排序,那么查询计划将包括SORT stage。

使用索引扫描的效率是远大于直接将结果集放在内存排序的,所以MongoDB为了使查询语句更有效率的执行,限制了 排序内存的使用,因而规定了只能使用 32M,该种考虑是非常合理的。

但也可通过手工调整参数进行修改(不建议):

# 比如调大到 128M
## 在线调整
> db.adminCommand({setParameter:1, internalQueryExecMaxBlockingSortBytes:134217728})

## 持久到配置文件
setParameter:
internalQueryExecMaxBlockingSortBytes: 134217728

使排序操作使用到索引

为查询语句创建合适的索引

  • 如果查询中排序是单列排序,如sort({"Num":1}),那么只需添加为Num列添加索引即可,排序的顺序无影响
## 例如索引为 {'Num':1},查询不管升/降序都可使用到索引排序
db.data_test.find().sort({Num:1})  
db.data_test.find().sort({Num:-1}) 
  • 如果查询中排序是使用组合排序,如sort({"Num":1,"_id":1}),那么需要建立对应的组合索引,如{"key" : {"Num" : 1, "_id" : 1} 或者 {"key" : {"Num" : -1, "_id" : -1}
## 例如索引为{"Num" : 1, "_id" : 1},可以用到索引排序的场景为
db.data_test.find().sort({Num:1,_id:1})
db.data_test.find().sort({Num:-1,_id:-1})
  • 注意保持查询中组合排序的升降序和组合索引中的 方向 保持 全部相同 或 全部相反

注意前缀索引的使用

上文查询报错的案例分析已说明了组合索引每一个键的顺序非常重要,这将决定该组合索引在查询过程中能否被使用到,也将是MongoDB的索引及排序同样需遵循最左前缀原则。

聚合查询添加allowDiskUse选项

尽可能的保证查询语句的排序能够使用索引排序,但如果业务需要规避排序内存限制报错的问题,那么需要在代码中添加 {allowDiskUse : true} 参数。

参考文献

https://docs.mongodb.com/manual/tutorial/sort-results-with-indexes/index.html

https://docs.mongodb.com/manual/reference/operator/aggregation/sort/#sort-memory-limit

https://docs.mongodb.com/manual/reference/explain-results/#executionstats

最后编辑于
©著作权归作者所有,转载或内容合作请联系作者
  • 序言:七十年代末,一起剥皮案震惊了整个滨河市,随后出现的几起案子,更是在滨河造成了极大的恐慌,老刑警刘岩,带你破解...
    沈念sama阅读 206,723评论 6 481
  • 序言:滨河连续发生了三起死亡事件,死亡现场离奇诡异,居然都是意外死亡,警方通过查阅死者的电脑和手机,发现死者居然都...
    沈念sama阅读 88,485评论 2 382
  • 文/潘晓璐 我一进店门,熙熙楼的掌柜王于贵愁眉苦脸地迎上来,“玉大人,你说我怎么就摊上这事。” “怎么了?”我有些...
    开封第一讲书人阅读 152,998评论 0 344
  • 文/不坏的土叔 我叫张陵,是天一观的道长。 经常有香客问我,道长,这世上最难降的妖魔是什么? 我笑而不...
    开封第一讲书人阅读 55,323评论 1 279
  • 正文 为了忘掉前任,我火速办了婚礼,结果婚礼上,老公的妹妹穿的比我还像新娘。我一直安慰自己,他们只是感情好,可当我...
    茶点故事阅读 64,355评论 5 374
  • 文/花漫 我一把揭开白布。 她就那样静静地躺着,像睡着了一般。 火红的嫁衣衬着肌肤如雪。 梳的纹丝不乱的头发上,一...
    开封第一讲书人阅读 49,079评论 1 285
  • 那天,我揣着相机与录音,去河边找鬼。 笑死,一个胖子当着我的面吹牛,可吹牛的内容都是我干的。 我是一名探鬼主播,决...
    沈念sama阅读 38,389评论 3 400
  • 文/苍兰香墨 我猛地睁开眼,长吁一口气:“原来是场噩梦啊……” “哼!你这毒妇竟也来了?” 一声冷哼从身侧响起,我...
    开封第一讲书人阅读 37,019评论 0 259
  • 序言:老挝万荣一对情侣失踪,失踪者是张志新(化名)和其女友刘颖,没想到半个月后,有当地人在树林里发现了一具尸体,经...
    沈念sama阅读 43,519评论 1 300
  • 正文 独居荒郊野岭守林人离奇死亡,尸身上长有42处带血的脓包…… 初始之章·张勋 以下内容为张勋视角 年9月15日...
    茶点故事阅读 35,971评论 2 325
  • 正文 我和宋清朗相恋三年,在试婚纱的时候发现自己被绿了。 大学时的朋友给我发了我未婚夫和他白月光在一起吃饭的照片。...
    茶点故事阅读 38,100评论 1 333
  • 序言:一个原本活蹦乱跳的男人离奇死亡,死状恐怖,灵堂内的尸体忽然破棺而出,到底是诈尸还是另有隐情,我是刑警宁泽,带...
    沈念sama阅读 33,738评论 4 324
  • 正文 年R本政府宣布,位于F岛的核电站,受9级特大地震影响,放射性物质发生泄漏。R本人自食恶果不足惜,却给世界环境...
    茶点故事阅读 39,293评论 3 307
  • 文/蒙蒙 一、第九天 我趴在偏房一处隐蔽的房顶上张望。 院中可真热闹,春花似锦、人声如沸。这庄子的主人今日做“春日...
    开封第一讲书人阅读 30,289评论 0 19
  • 文/苍兰香墨 我抬头看了看天上的太阳。三九已至,却和暖如春,着一层夹袄步出监牢的瞬间,已是汗流浃背。 一阵脚步声响...
    开封第一讲书人阅读 31,517评论 1 262
  • 我被黑心中介骗来泰国打工, 没想到刚下飞机就差点儿被人妖公主榨干…… 1. 我叫王不留,地道东北人。 一个月前我还...
    沈念sama阅读 45,547评论 2 354
  • 正文 我出身青楼,却偏偏与公主长得像,于是被迫代替她去往敌国和亲。 传闻我的和亲对象是个残疾皇子,可洞房花烛夜当晚...
    茶点故事阅读 42,834评论 2 345