MongoDB慢日志分析

最新推荐文章于 2025-09-02 16:02:06 发布

原创最新推荐文章于 2025-09-02 16:02:06 发布 · 8.5k 阅读

3 ·

CC 4.0 BY-SA版权

文章标签：

#mongodb

MongoDB 专栏收录该内容

12 篇文章

订阅专栏

MongoDB的数据库Profiler提供慢查询记录，类似MySQL的慢查询日志。可以通过设置--profile级别开启，记录慢查询时间默认为100ms。查询Profiler记录位于system.profile集合，可以通过db.system.profile.find()获取。优化建议包括减少nscanned与nreturned的差距，合理使用索引，以及在适用情况下使用fast modify operations。

在 MySQL中，慢查询日志是经常作为我们优化数据库的依据，那在MongoDB中是否有类似的功能呢?答案是肯定的，那就是Mongo Database Profiler.不仅有，而且还有一些比MySQL的Slow Query Log更详细的信息。它就是我们这篇文章的主题。

　　开启 Profiling 功能

　　有两种方式可以控制 Profiling 的开关和级别，第一种是直接在启动参数里直接进行设置。

　　启动MongoDB时加上–profile=级别即可。

　　也可以在客户端调用db.setProfilingLevel(级别) 命令来实时配置。可以通过db.getProfilingLevel()命令来获取当前的Profile级别。

> db.setProfilingLevel(2);
　　{"was" : 0 , "ok" : 1}
　　> db.getProfilingLevel()

　　上面斜体的级别可以取0，1，2 三个值，他们表示的意义如下：

　　0 – 不开启

　　1 – 记录慢命令 (默认为>100ms)

　　2 – 记录所有命令

　　Profile 记录在级别1时会记录慢命令，那么这个慢的定义是什么?上面我们说到其默认为100ms，当然有默认就有设置，其设置方法和级别一样有两种，一种是通过添加–slowms启动参数配置。第二种是调用db.setProfilingLevel时加上第二个参数：

db.setProfilingLevel( level , slowms )
　　db.setProfilingLevel( 1 , 10 );

　　查询 Profiling 记录

　　与MySQL的慢查询日志不同，Mongo Profile 记录是直接存在系统db里的，记录位置 system.profile ，所以，我们只要查询这个Collection的记录就可以获取到我们的 Profile 记录了。

> db.system.profile.find()
　　{"ts" : "Thu Jan 29 2009 15:19:32 GMT-0500 (EST)" , "info" : "query test.$cmd ntoreturn:1 reslen:66 nscanned:0
　　query: { profile: 2 } nreturned:1 bytes:50" , "millis" : 0}
　　db.system.profile.find( { info: /test.foo/ } )
　　{"ts" : "Thu Jan 29 2009 15:19:40 GMT-0500 (EST)" , "info" : "insert test.foo" , "millis" : 0}
　　{"ts" : "Thu Jan 29 2009 15:19:42 GMT-0500 (EST)" , "info" : "insert test.foo" , "millis" : 0}
　　{"ts" : "Thu Jan 29 2009 15:19:45 GMT-0500 (EST)" , "info" : "query test.foo ntoreturn:0 reslen:102 nscanned:2
　　query: {} nreturned:2 bytes:86" , "millis" : 0}
　　{"ts" : "Thu Jan 29 2009 15:21:17 GMT-0500 (EST)" , "info" : "query test.foo ntoreturn:0 reslen:36 nscanned:2
　　query: { $not: { x: 2 } } nreturned:0 bytes:20" , "millis" : 0}
　　{"ts" : "Thu Jan 29 2009 15:21:27 GMT-0500 (EST)" , "info" : "query test.foo ntoreturn:0 exception bytes:53" , "millis" : 88}

　　列出执行时间长于某一限度(5ms)的 Profile 记录：

> db.system.profile.find( { millis : { $gt : 5 } } )
　　{"ts" : "Thu Jan 29 2009 15:21:27 GMT-0500 (EST)" , "info" : "query test.foo ntoreturn:0 exception bytes:53" , "millis" : 88}

　　查看最新的 Profile 记录：

　　db.system.profile.find().sort({$natural:-1})

　　Mongo Shell 还提供了一个比较简洁的命令show profile，可列出最近5条执行时间超过1ms的 Profile 记录。

　　Profile 信息内容详解：

　　ts-该命令在何时执行.

　　millis Time-该命令执行耗时，以毫秒记.

　　info-本命令的详细信息.

　　query-表明这是一个query查询操作.

　　ntoreturn-本次查询客户端要求返回的记录数.比如, findOne()命令执行时 ntoreturn 为 1.有limit(n) 条件时ntoreturn为n.

　　query-具体的查询条件(如x>3).

　　nscanned-本次查询扫描的记录数.

　　reslen-返回结果集的大小.