奇怪的性能低下

背景

昨晚需要跑一批数据到mongo中,采用jmeter读取数据文件向服务端发送请求的方式,将数据库跑入数据库。
数据量:200w
mongo与服务端均部署在同一台机器上。

现象

在服务器上执行jmeter脚本,但是数据惨不忍睹

测试截图

看了服务器的cpu 和内存的使用率,发现cpu(24核)只有67%,内存只有20%(512G)。并发量只有200,明显对于服务端的压力不大,排除硬件。

后来将并发量降低至50,数据依然惨不忍睹。

猜测

怀疑是数据库的索引没有加,但是服务端是会自动建立索引的,进入mongo查看索引,发现都建立了。
决定开始mongo 慢查询,看看是否是数据入库时过慢造成的, 因为测试前数据库是空的,随着数据的进入,伴随着insert及query操作。

慢查询分析流程

通过慢查询日志,定位每条语句的执行时间,比如超过了200ms的,那么就需要针对这个进行优化。
优化步骤:

  1. 用慢查询日志(system.profile)找到超过200ms的语句
  2. 然后再通过.explain()解析影响行数,分析为什么超过200ms
  3. 决定是不是需要添加索引

开启慢查询

Profiling级别说明
  • 0:关闭,不收集任何数据。
  • 1:采集慢查询数据,默认是100毫秒。
  • 2:采集所有数据
开启profiling
#查看状态:级别和时间
> db.getProfilingStatus()
{ "was" : 0, "slowms" : 100 }
#查看级别
> db.getProfilingLevel()
0
#设置级别
> db.setProfilingLevel(1)
{ "was" : 0, "slowms" : 100, "ok" : 1 }
#设置级别和时间
> db.setProfilingLevel(1,200)
{ "was" : 2, "slowms" : 100, "ok" : 1 }

需要注意的是,以上操作如果是在collection下操作,只对该collection有效。如果需要对整个db有效,需要在db下执行。每次执行后返回的结果是修改前的状态。

慢查询分析


{
        "op" : "insert",   #操作类型,有insert、query、update、remove、getmore、command
        "ns" : "fingerprint.T_DEVICE_FP_ANDROID",   #操作集合
        "query" : {
                "insert" : "T_DEVICE_FP_ANDROID",
                "ordered" : true,
                "documents" : [
                        {
                                "_id" : ObjectId("58c143a5d8a7db667f13629b"),
                                "_class" : "*************",
                         
                        }
                ]
        },
        "ninserted" : 1,
        "keyUpdates" : 0,    #索引更新的数量,改变一个索引键带有一个小的性能开销,因为数据库必须删除旧的key,并插入一个新的key到B-树索引
        "writeConflicts" : 0,
        "numYield" : 0,   #该操作为了使其他操作完成而放弃的次数。通常来说,当他们需要访问还没有完全读入内存中的数据时,操作将放弃。这使得在MongoDB为了放弃操作进行数据读取的同时,还有数据在内存中的其他操作可以完成
        "locks" : {
                "Global" : {
                        "acquireCount" : {
                                "r" : NumberLong(1),
                                "w" : NumberLong(1)
                        }
                },
                "Database" : {
                        "acquireCount" : {
                                "w" : NumberLong(1)
                        }
                },
                "Collection" : {
                        "acquireCount" : {
                                "w" : NumberLong(1)
                        }
                }
        },
        "responseLength" : 40,
        "protocol" : "op_query",
        "millis" : 1601,   #消耗的时间(毫秒)
        "execStats" : {

        },
        "ts" : ISODate("2017-03-09T11:59:34.972Z"),
        "client" : "10.100.1.200",
        "allUsers" : [ ],
        "user" : ""
}

此处发现millis 数据很大,一个insert操作需要1.6s。需要进行优化。

总结

上面的只是一个例子,实际中,我当时是在query遇到问题,一个query执行了2800ms。后来发现是有两个字段没有建索引导致的。但是依然没有解决insert性能低下的问题。

后续有待继续研究。

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

推荐阅读更多精彩内容