记一次 MySQL 数据库问题排查

数据库小白一枚，正在不断学习积累知识，现将学习到的知识记录一下，也是将我的所得分享给大家！而今天这篇文章《记一次 MySQL 数据库问题排查》带大家来了解一下记一次 MySQL 数据库问题排查，希望对大家的知识积累有所帮助，从而弥补自己的不足，助力实战开发！

最近遇到应用频繁的响应缓慢，无法正常访问。帮忙一起定位原因，最后定位到的问题说起来真的是很小的细节问题，但是就是这些小细节导致了服务不稳定，真是细节决定成败。这里尝试着来分享下，希望对大家有所帮助。

问题 1：占着茅坑不拉屎

遇到问题首先要看的还是服务器错误日志。

错误日志中看到频繁有这样的一个异常报错：

var mysql = require('mysql');
// 建立连接池
var pool = mysql.createPool({
    host: 'host',
    user: 'user',
    password: 'password',
    database: 'db'
});

exports.query = function(sql, cb) {
    // 从池子里面取一个可用连接
    pool.getConnection(function(err, connection) {
        if (err) throw err;
        // 执行sql
        connection.query(sql, function(err, rows, fields) {
            if (err) {
                return cosole.error(err);
            }
            cb(rows);
        });
        // 释放此连接
        connection.release();
    });

};

刚开始我还真没看出来有什么问题，后来仔细读了 node-mysql 的文档及这个 issue，终于发现了我们的写法是有问题的。

再次看看上面的代码，

exports.query = function(sql, cb) {
    // 从池子里面取一个可用连接
    pool.getConnection(function(err, connection) {
        if (err) throw err;
        // 执行sql
        connection.query(sql, function(err, rows, fields) {
            // 释放连接（一定要在错误处理前，不然出错的时候也会导致该连接得不到释放）
           connection.release();

            if (err) {
                return cosole.error(err);
            }
            cb(rows);
        });
    });

};

也可以用更简单的写法

pool.query

完成此次修改后，这个异常没有再复现，但是响应缓慢的情况依然没有得到缓解。

问题 2：一条 UPDATE 引发的血案

我们再次查看了错误日志，发现了另一个异常报错：

Error: ER_LOCK_WAIT_TIMEOUT: Lock wait timeout exceeded; try restarting transaction

我们先大概说下什么是事务（transaction）。事务应该具有 4 个属性：

• 原子性（事务作为整体执行，操作要么全部执行、要么全部不执行）

• 一致性（事务应该确保数据库状态从一个一致状态转变为另一个一致状态）

• 隔离性（多个事务并发执行时，一个事务执行不影响其他事务执行）

• 持久性（事务提交后，对数据库修改应该永久保存在数据库中）

对于隔离性，还会分出多个隔离级别：

• 脏读（Dirty Read）：A 事务读到 B 事务未提交的修改。

• 不可重复读（NonRepeatable Read）：A 事务还没有结束时，B 事务也访问同一数据。在 A 事务的两次读取之间，由于 B 事务的修改，A 事务两次读到的数据可能是不一样的。

• 幻读（Phantom Read）：A 事务对一个表中的数据进行了修改，这种修改涉及到表中的全部数据行。同时，B 事务也修改这个表中的数据，这种修改是向表中插入一行新数据。操作 A 事务的用户发现表中出现了 B 事务插入的行，就好象发生了幻觉一样。

MySQL 默认的级别是 REPEATABLE READ（可重复读），这表示在 MySQL 的默认情况下，“脏读”、“不可重复读”是不会发生的。这就需要在更新的时候进行必要的锁定（InnoDB 是采用行级锁的方式），从而保证一致性。需要注意的是 InnoDB 的行锁是通过给索引上的索引项加锁来实现的，这个特点意味着：只有通过索引条件检索数据，InnoDB 才使用行级锁，否则，InnoDB 将使用表锁！

我们数据库表是 InnoDB 引擎的表，而 MySQL 的 InnoDB 引擎是一个支持事务的引擎，其默认操作模式是 autocommit 自动提交模式。什么意思呢？除非我们显式地开始一个事务，否则每个查询都被当做一个单独的事务自动执行。

回到上面的报错，错误日志里抛出异常时执行的 SQL 语句，都是类似这样的一条 UPDATE 语句：

update testScore set status=1,executionId='946012' where token='f7900c40-8f4b-11e5-b2f1-6feca76a1bf5'

问题产生的原因可以这样来描述了：我们在执行 UPDATE 语句时，MySQL 会将其当成一个事务，对表的行进行锁定，这时又有其他连接进来要 UPDATE 同样的表或者 SELECT 这张表时就必须等待锁资源，而这个等待时间太久，导致超时了。

什么？一个 UPDATE 语句居然会这么慢？这我简直不能接受啊！那我只能看看为啥这个语句如此慢了。

查看慢查询（slow_queries.log）日志里面对应的查询信息：

# Query_time: 56.855324  Lock_time: 48.054343 Rows_sent: 0  Rows_examined: 29400
update testScore set uiTaskId=81041 where token='e7d7d8f0-8f4b-11e5-99be-9dfbb419755e';

这样一条 UPDATE 语句花了 56 秒，扫了 29400 条表记录。看到这样的执行日志，也大概猜到原因了，没有为查询字段 token 加索引！这样 MySQL 在进行 update 操作时不会走行锁，直接锁定了整张表，而这个 update 语句本身也够慢（扫了全表），那并发多个 update 更新时导致了等待锁超时。

给 testScore 表的 token 字段增加了索引，终于，这个异常不再复现，响应时间开始回归正常。

参考资料

• https://github.com/felixge/node-mysql#pooling-connections

• https://github.com/felixge/node-mysql/issues/712

• http://dev.mysql.com/doc/refman/5.5/en/too-many-connections.html

• http://www.cnblogs.com/zhoujinyi/p/3437475.html

• http://stackoverflow.com/questions/5836623/getting-lock-wait-timeout-exceeded-try-restarting-transaction-even-though-im

• https://www.percona.com/blog/2015/04/09/innodb-locks-deadlocks-without-index-different-isolation-level/

好了，本文到此结束，带大家了解了《记一次 MySQL 数据库问题排查》，希望本文对你有所帮助！关注golang学习网公众号，给大家分享更多数据库知识！