导读

线上有个MySQL实例,存在严重的复制延迟问题,原因出乎意料。

线上有个MySQL 5.7版本的实例,从服务器延迟了3万多秒,而且延迟看起来好像还在加剧。

MySQL版本

    Serverversion:5.7.18-logMySQLCommunityServer(GPL)

看下延迟状况

    [email protected]:mysql3306.sock:(none)>showslavestatus\GMaster_Log_File:mysql-bin.013225Read_Master_Log_Pos:1059111551Relay_Master_Log_File:mysql-bin.013161Exec_Master_Log_Pos:773131396Master_UUID:e7c35a95-ffb1-11e6-9620-90e2babb5b90

我们看到,binlog文件落后了64个,相当的夸张。

MySQL 5.7不是已经实现并行复制了吗,怎么还会延迟这么厉害?

先检查系统负载。

看到mysqld进程其实负载还好,不算太高,也不存在严重的SWAP等问题。

再看I/O子系统负载,没看到这方面存在瓶颈(await\svctm\%util都不高)。

再看mysqld进程的CPU消耗。

虽然mysqld进程的CPU消耗总是超过100%,不过也不算太高。

再检查MySQL复制现场,确认了几个频繁更新的表都有主键,以及必要的索引。相应的DML操作也几乎都是基于主键或唯一索引条件执行的,排除无主键、无合理索引方面的因素。

***只能祭出perf top神器了。

perf top -p `pidof mysqld`

看到perf top***的报告是这样的

    Samples:107Kofevent'cycles',Eventcount(approx.):29813195000OverheadSharedObjectSymbol56.19%mysqld[.]bitmap_get_next_set16.18%mysqld[.]build_template_field4.61%mysqld[.]ha_innopart::try_semi_consistent_read4.44%mysqld[.]dict_index_copy_types4.16%libc-2.12.so[.]__memset_sse22.92%mysqld[.]ha_innobase::build_template

我们看到, bitmap_get_next_set 这个函数调用占到了 56.19%,非常高,其次是 build_template_field 函数,占了 16.18%。

经过检查MySQL源码并请教MySQL内核开发专家,***确认这两个函数跟启用表分区有关系。

查询下当前实例有多少个表分区:

    [email protected]:mysql3306.sock:(none)>selectcount(*)frompartitionswherepartition_nameisnotnull;+----------+|count(*)|+----------+|32128|+----------+1rowinset(11.92sec)

额滴神啊,竟然有3万多个表分区,难怪上面那两个函数调用那么高。

这个业务数据库几个大表采用每天一个分区方案,而且把直到当年年底所有分区也都给提前创建好了,所以才会有这么多。

不过,虽然有这么多表分区,在master服务器上却不存在这个瓶颈,看起来是在主从复制以及大量表分区的综合因素下才有这个瓶颈,最终导致主从复制延迟越来越严重。

知道问题所在,解决起来就简单了。把到下个月底前用不到的表分区全部删除,之后约只剩下1.6万个分区。重启slave线程,问题解决,主从复制延迟很快就消失了。