记一次数据问题,使用 profiling 分析sql性能

v2-19f3b7ee997e1a4a7f27ec0a98d3f62f_r.jpeg

一、事故现场

有一张用户对 appid 的对应关系表,数据量 578261166 条,38GB 大小,data 17.3G , index 21 G

CREATE TABLE `user_subtasks_v5` (
  `id` int(11) unsigned NOT NULL AUTO_INCREMENT,
  `user_id` int(11) NOT NULL,
  `uid_cardid` int(11) NOT NULL DEFAULT '0' COMMENT '通用uid',
  `appid` varchar(50) NOT NULL,
  `created_at` timestamp NOT NULL DEFAULT CURRENT_TIMESTAMP,
  PRIMARY KEY (`id`),
  UNIQUE KEY `u_cardid_appid` (`uid_cardid`,`appid`),
  KEY `i_uid` (`user_id`)
) ENGINE=InnoDB AUTO_INCREMENT=578261111 DEFAULT CHARSET=utf8 COMMENT='用户任务表V5';

事故sql

-- 原sql
select appid from user_subtasks_v5  where uid_cardid=62719757;
-- 新sql
select * from user_subtasks_v5  where uid_cardid=62719757;

造成结果: 上线后 IOPS(mysql 读写次数) 飙升,读写吞吐量飙升,出现大量慢查询


F12C85C1-1F2B-4585-98E5-FF82C860A6EB.png

B4913BF4-6535-4F41-91DB-032854407D53.png

二、性能分析

1.explain 分析

  • 对上个两个两个sql 进行 explain 分析
explain select appid from user_subtasks_v5  where uid_cardid=62719757;
explain select * from user_subtasks_v5  where uid_cardid=62719757;
  • 上面2条语句 explain 的结构大部分是一样的,使用的索引策略也是一样的,差异是 appid 使用了索引覆盖,性能更好一些,不用回表查询

2. show processlist 分析

使用 show processlist 查看当前执行的sql语言有没有异常,查到的结果是:频繁的出现 “select * from user_subtasks_v5 where uid_cardid=62719757”,语句处于 “sending data”,状态。sending_data: The thread is reading and processing rows for a SELECT statement, and sending data to the client. Because operations occurring during this state tend to perform large amounts of disk access (reads), it is often the longest-running state over the lifetime of a given query.

sending_data 的过程包括从磁盘上 “读取 + 发送” 的过程
https://dev.mysql.com/doc/refman/5.6/en/general-thread-states.html]

3. show profile 分析sql

-- 打开 profile

set profiling=on;

-- 执行sql

select * from user_subtasks_v5  where uid_cardid=62719757;
-- 执行 show profiles 查看执行id
show profiles;
-- 使用 show profile all for query 查看sql详细执行情况
show profile all for query 20;

结果如下:


D2426EE3-3452-4C5D-99E9-BF2D59A543AE.png
4C1EEBFE-CC3C-4389-9E82-1D2A33CC2DE7.png

参数解释:


QQ20220113-180048.png

可以观察到 在 Duration, Context_voluntary, Block_ops_in, Page_faults_minor 项差异较大。

问题猜测

  • 1.覆盖索引问题,select appid 走覆盖索引,效率更高,不需要回表
  • 2.数据量问题,select * 数据量更大,读取的页更多
  • 3.有说是 参数 time_zone=system 时,查询 timestamp 字段会调用系统时区做时区转换,而由于系统时区存在全局锁问题,在多并发大数据量访问时会导致线程上下文频繁切换,CPU 使用率暴涨,系统响应变慢设置假死。 亲测: 没有什么差异。

优化方案:

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

推荐阅读更多精彩内容