《MySQL高级篇》十五、其他数据库日志

矫情吗；* 2023-10-14 14:30 58阅读 0赞

#### 文章目录 ####

*   *  1. MySQL支持的日志
     *   *  1.1 日志类型
         *  1.2 日志的弊端
     *  2. 慢查询日志(slow query log)
     *  3. 通用查询日志
     *   *  3.1 问题场景
         *  3.2 查看当前状态
         *  3.3 启动日志
         *  3.4 查看日志
         *  3.5 停止日志
         *  3.6 删除\\刷新日志
     *  4. 错误日志(error log)
     *   *  4.1 启动日志
         *  4.2 查看日志
         *  4.3 删除\\刷新日志
         *  4.4 MySQL8.0新特性
     *  5. 二进制日志(bin log)
     *   *  5.1 查看默认情况
         *  5.2 日志参数设置
         *  5.3 查看日志
         *  5.4 使用日志恢复数据
         *  5.5 删除二进制日志
         *  5.6 其它场景
     *  6. 再谈二进制日志(binlog)
     *   *  6.1 写入机制
         *  6.2 binlog与redolog对比
         *  6.3 两阶段提交
     *  7. 中继日志(relay log)
     *   *  7.1 介绍
         *  7.2 查看中继日志
         *  7.3 恢复的典型错误

--------------------

我们在讲解数据库事务时，讲过两种日志:`重做日志`、`回滚日志`。

对于线上数据库应用系统，突然遭遇`数据库宕机`怎么办?在这种情况下，定位宕机的原因就非常关键。可以查看数据库的错误日志。因为日志中记录了数据库运行中的诊断信息，包括了错误、警告和注释等信息。比如:从日志中发现某个连接中的SQL操作发生了死循环，导致内存不足，被系统强行终止了。明确了原因，处理起来也就轻松了，系统很快就恢复了运行。

除了发现错误，日志在数据复制、数据恢复、操作审计，以及确保数据的永久性和一致性等方面，都有着不可替代的作用。

千万不要小看日志。很多看似奇怪的问题，答案往往就藏在日志里。很多情况下，只有通过查看日志才能发现问题的原因，真正解决问题。所以，一定要学会查看日志，养成检查日志的习惯，对提升你的数据库应用开发能力至关重要。

MySQL8.0官网日志地址: [https://dev.mysql.com/doc/refman/8.0/en/server-logs.html][https_dev.mysql.com_doc_refman_8.0_en_server-logs.html]

### 1. MySQL支持的日志 ###

#### 1.1 日志类型 ####

MySQL有不同类型的日志文件，用来存储不同类型的日志，分为`二进制日志`、`错误日志`、`通用查询日志`和`慢查询日志`，这也是常用的4种。MySQL 8又新增两种支持的日志:`中继日志`和`数据定义语句日志`。使用这些日志文件，可以查看MySQL内部发生的事情。

**这6类日志分别为：**

*  `慢查询日志`:记录所有执行时间超过long\_query\_time的所有查询，方便对查询进行优化。
 *  `通用查询日志`:记录所有连接的起始时间和终止时间，以及连接发送给数据库服务器的所有指令，对复原操作的实际场景、发现问题，甚至是对数据库操作的审计都有很大的帮助。
 *  `错误日志`:记录MySQL服务的启动、运行或停止MySQL服务时出现的问题，方便我们了解服务器的状态，从而从而对服务器进行维护。
 *  `二进制日志`:记录所有更改数据的语句，可以用于主从服务器之间的数据同步，以及服务器遇到故障时数据的无损失恢复。
 *  `中继日志`:用于主从服务器架构中，从服务器用来存放主服务器二进制日志内容的一个中间文件。从服务器通过读取中继日志的内容，来同步主服务器上的操作。
 *  `数据定义语句日志`:记录数据定义语句执行的元数据操作。

除二进制日志外，其他日志都是`文本文件`。默认情况下，所有日志创建于`MySQL数据目录`中。

#### 1.2 日志的弊端 ####

*  日志功能会`降低MySQL数据库的性能`。例如，在查询非常频繁的MySQL数据库系统中，如果开启了通用查询日志和慢查询日志，MySQL数据库会花费很多时间记录日志。
 *  日志会占用`大量的磁盘空间`。对于用户量非常大、操作非常频繁的数据库，日志文件需要的存储空间设置比数据库文件需要的存储空间还要大。

--------------------

### 2. 慢查询日志(slow query log) ###

前面章节[《第07章 性能分析工具的使用》][07_]已经详细讲述

### 3. 通用查询日志 ###

通用查询日志用来`记录用户的所有操作`，包括启动和关闭MysQL服务、所有用户的连接开始时间和截止时间、发给MySQL数据库服务器的所有SQL指令等。当我们的数据发生异常时\*\*，查看通用查询日志，还原操作时的具体场景\*\* ，可以帮助我们准确定位问题。

#### 3.1 问题场景 ####

在电商系统中，购买商品并且使用微信支付完成以后，却发现支付中心的记录并没有新增，此时用户再次使用支付宝支付，就会出现`重复支付`的问题。但是当去数据库中查询数据的时候，会发现只有一条记录存在。那么此时给到的现象就是只有一条支付记录，但是用户却支付了两次。

对系统进行了仔细检查，没有发现数据问题，因为用户编号和订单编号以及第三方流水号都是对的。可是用户确实支付了两次，这个时候，我们想到了检查通用查询日志，看看当天到底发生了什么。

查看之后，发现: 1月1日下午2点，用户使用微信支付完以后，但是由于网络故障，支付中心没有及时收到微信支付的回调通知，导致当时没有写入数据。1月1日下午2点30，用户又使用支付宝支付，此时记录更新到支付中心。1月1日晚上9点，微信的回调通知过来了，但是支付中心已经存在了支付宝的记录，所以只能覆盖记录了。

由于网络的原因导致了重复支付。至于解决问题的方案就很多了，这里省略。

可以看到通用查询日志可以帮助我们了解操作发生的具体时间和操作的细节，对找出异常发生的原因极其关键。

#### 3.2 查看当前状态 ####

说明1∶系统变量general\_log的值是OFF，即通用查询日志处于关闭状态。在MySQL中，这个参数的`默认值是关闭的`。因为一旦开启记录通用查询日志，MySQL 会记录所有的连接起止和相关的SQL操作，这样会消耗系统资源并且占用磁盘空间。我们可以通过手动修改变量的值，在`要的时候开启日志`。

说明2:通用查询日志文件的名称是主机.log(hadoop102.log)。存储路径是`/var/lib/mysql/`，默认也是数据路径。这样我们就知道在哪里可以查看通用查询日志的内容了

#### 3.3 启动日志 ####

**方式1：永久性方式**

修改`my.cnf`或者`my.ini`配置文件来设置。在\[mysqld\]组下加入log选项，并重启MySQL服务。格式如下：

[mysqld]
    general_log=ON
    general_log_file=[path[filename]] #日志文件所在目录路径，filename为日志文件名

如果不指定目录和文件名，通用查询日志将默认存储在MySQL数据目录中的hostname.log文件中，hostname表示主机名。

**方式2：临时性方式**

使用SET语句停止MySQL通用查询日志功能:

SET GLOBAL general_log=on; # 开启通用查询日志
    SET GLOBAL general_log_file='path/filename'; # 设置日志文件保存位置

对应的，关闭操作SQL命令如下：

SET GLOBAL general_log=off; # 关闭通用查询日志

查看设置后情况：

SHOW VARIABLES LIKE 'general_log%';

#### 3.4 查看日志 ####

通用查询日志是以`文本文件` 的形式存储在文件系统中的，可以使用文本编辑器 直接打开日志文件。每台MySQL服务器的通用查询日志内容是不同的。

*  在Windows操作系统中，使用文本文件查看器；
 *  在Linux系统中，可以使用vi工具或者gedit工具查看；
 *  在Mac OSX系统中，可以使用文本文件查看器或者vi等工具查看。

从`SHOW VARIABLES LIKE ‘general_log%’;` 结果中可以看到通用查询日志的位置。

[root@hadoop102 mysql]# cat hadoop102.log 
    /usr/sbin/mysqld, Version: 8.0.25 (MySQL Community Server - GPL). started with:
    Tcp port: 3306  Unix socket: /var/lib/mysql/mysql.sock
    Time                 Id Command    Argument
    2023-07-09T11:06:37.920806Z	   12 Query	SHOW VARIABLES LIKE '%general%'
    2023-07-10T00:49:48.408063Z	   12 Quit	
    /usr/sbin/mysqld, Version: 8.0.25 (MySQL Community Server - GPL). started with:
    Tcp port: 3306  Unix socket: /var/lib/mysql/mysql.sock
    Time                 Id Command    Argument
    2023-07-22T06:29:01.004735Z	   11 Query	SELECT DATABASE()
    2023-07-22T06:29:01.004903Z	   11 Init DB	atguigudb2
    2023-07-22T06:29:01.005518Z	   11 Query	show databases
    2023-07-22T06:29:01.006686Z	   11 Query	show tables
    2023-07-22T06:29:01.007981Z	   11 Field List	a 
    2023-07-22T06:29:01.008874Z	   11 Field List	b 
    2023-07-22T06:29:01.009481Z	   11 Field List	book 
    2023-07-22T06:29:01.010170Z	   11 Field List	class 
    2023-07-22T06:29:01.011040Z	   11 Field List	student 
    2023-07-22T06:29:01.011209Z	   11 Field List	type 
    2023-07-22T06:29:01.011303Z	   11 Field List	user3 
    2023-07-22T06:29:13.504517Z	   11 Query	select * from student
    2023-07-22T06:29:32.236803Z	   11 Query	select * from type
    2023-07-22T06:29:58.236644Z	   11 Quit

在通用查询日志里面，我们可以清楚地看到，什么时候开启了新的客户端登陆数据库，登录之后做了什么 SQL 操作，针对的是哪个数据表等信息。

#### 3.5 停止日志 ####

**方式1：永久性方式**  
修改 my.cnf 或者 my.ini 文件，把\[mysqld\]组下的 general\_log 值设置为 OFF 或者把general\_log一项注释掉。修改保存后，再 重启MySQL服务 ，即可生效。

举例1：

[mysqld]
    general_log=OFF

举例2：

[mysqld]
    #general_log=ON

**方式2：临时性方式**  
使用SET语句停止MySQL通用查询日志功能：

SET GLOBAL general_log=off;

查询通用日志功能：

SHOW VARIABLES LIKE 'general_log%';

#### 3.6 删除\\刷新日志 ####

如果数据的使用非常频繁，那么通用查询日志会占用服务器非常大的磁盘空间。数据管理员可以删除很长时间之前的查询日志，以保证MySQL服务器上的硬盘空间

**手动删除文件**

SHOW VARIABLES LIKE 'general_log%';

可以看出，通用查询日志的目录默认为MySQL数据目录。在该目录下手动删除通用查询日志hadoop01.log。

使用如下命令重新生成查询日志文件，具体命令如下。刷新MySQL数据目录，发现创建了新的日志文件。前提一定要开启通用日志。

mysqladmin -uroot -p flush-logs

如果希望备份旧的通用查询日志，就必须先将旧的日志文件复制出来或者改名，然后执行上面的mysqladmin命令。正确流程如下

cd mysql-data-directory #输入自己的通用日志文件所在目录
    mv mysql.general.log mysql.general.log.old #指名就的文件名 以及新的文件名
    mysqladmin -uroot -p flush-logs

### 4. 错误日志(error log) ###

错误日志记录了MySQL服务器启动、停止运行的时间，以及系统启动、运行和停止过程中的诊断信息，包括`错误`、`警告`和`提示`等。

通过错误日志可以查看系统的运行状态，便于即时发现故障、修复故障。如果MysQL服务`出现异常`，错误日志是发现问题、解决故障的`首选`。

#### 4.1 启动日志 ####

在MySQL数据库中，错误日志功能是 `默认开启` 的。而且，错误日志 `无法被禁止` 。

默认情况下，错误日志存储在MySQL数据库的数据文件夹下，名称默认为 `mysqld.log`（Linux系统）或 `hostname.err` （mac系统）。如果需要制定文件名，则需要在my.cnf或者my.ini中做如下配置：

[mysqld]
    log-error=[path/[filename]] #path为日志文件所在的目录路径，filename为日志文件名

修改配置项后，需要重启MySQL服务以生效。

#### 4.2 查看日志 ####

MySQL错误日志是以文本文件形式存储的，可以使用文本编辑器直接查看。

查询错误日志的存储路径：

执行结果中可以看到错误日志文件是mysqld.log，位于MySQL默认的数据目录下。

下面我们查看一下错误日志的内容。

[root@hadoop102 log]# cat mysqld.log
    2022-05-09T13:36:18.316947Z 0 [System] [MY-013169] [Server] /usr/sbin/mysqld (mysqld 8.0.25) initializing of server in progress as process 8770
    2022-05-09T13:36:18.339461Z 1 [System] [MY-013576] [InnoDB] InnoDB initialization has started.
    2022-05-09T13:36:18.969919Z 1 [System] [MY-013577] [InnoDB] InnoDB initialization has ended.
    2022-05-09T13:36:20.519755Z 6 [Note] [MY-010454] [Server] A temporary password is generated for root@localhost: wtOQr<yC9NHM
    2022-05-09T13:37:00.981062Z 0 [System] [MY-010116] [Server] /usr/sbin/mysqld (mysqld 8.0.25) starting as process 8872
    2022-05-09T13:37:00.993416Z 1 [System] [MY-013576] [InnoDB] InnoDB initialization has started.
    2022-05-09T13:37:01.118904Z 1 [System] [MY-013577] [InnoDB] InnoDB initialization has ended.
    2022-05-09T13:37:01.211523Z 0 [System] [MY-011323] [Server] X Plugin ready for connections. Bind-address: '::' port: 33060, socket: /var/run/mysqld/mysqlx.sock
    //...

可以看到，错误日志文件中记录了服务器启动的时间，以及存储引擎InnoDB启动和停止等，我们在做初始化时候生成的数据库初始密码也是记录在error.log中。

#### 4.3 删除\\刷新日志 ####

对于很久以前的错误日志，数据库管理员查看这些错误日志的可能性不大，可以将这些错误日志删除，以保证MySQL服务器上的 `硬盘空间` 。MySQL的错误日志是以文本文件的形式存储在文件系统中的，可以`直接删除`。

*  第1步(方式1)︰删除操作 `rm` （ 在运行状态下删除错误日志文件后，MySQL并不会自动创建日志文件）
 *  第1步(方式2)︰重命名文件 `mv`
 *  第2步：重建日志 `mysqladmin -uroot -p flush-logs`

可能报错

[root@atguigu01 log]# mysqladmin -uroot -p flush-logs
    Enter password:
    mysqladmin: refresh failed; error: 'Could not open file '/var/log/mysqld.log' for error logging.'

官网提示:

![image-20230722145912407][]

执行下面命令：

install -omysql -gmysql -m0644 /dev/null /var/log/mysqld.log

`flush-logs`指令操作:

MySQL 5.5.7以前的版本，flush-logs将错误日志文件重命名为filename.err\_old，并创建新的日志文件。  
从MySQL 5.5.7开始，flush-logs只是重新打开日志文件，并不做日志备份和创建的操作。  
如果日志文件不存在，MySQL启动或者执行flush-logs时会自动创建新的日志文件。重新创建错误日志，大小为0字节。

#### 4.4 MySQL8.0新特性 ####

MySQL8.0里对错误日志的改进。MySQL8.0的错误日志可以理解为一个全新的日志，在这个版本里，接受了来自社区的广泛批评意见，在这些意见和建议的基础上生成了新的日志。

*  下面这些是来自社区的意见:
 *  默认情况下内容过于冗长
 *  遗漏了有用的信息
 *  难以过滤某些信息
 *  没有标识错误信息的子系统源
 *  没有错误代码，解析消息需要识别错误
 *  引导消息可能会丢失
 *  固定格式

针对这些意见，MySQL做了如下改变:

*  采用组件架构，通过不同的组件执行日志的写入和过滤功能
 *  写入错误日志的全部信息都具有唯一的错误代码从10000开始
 *  增加了一个新的消息分类《system》用于在错误日志中始终可见的非错误但服务器状态更改事件的消息。增加了额外的附加信息，例如关机时的版本信息，谁发起的关机等等
 *  两种过滤方式，Internal和Dragnet
 *  三种写入形式，经典、JSON和syseventlog

> 小结:
> 
> 通常情况下，管理员不需要查看错误日志。但是，MySQL服务器发生异常时，管理员可以从错误日志中找到发生异常的时间、原因，然后根据这些信息来解决异常。

### 5. 二进制日志(bin log) ###

binlog可以说是MySQL中比较 `重要` 的日志了，在日常开发及运维过程中，经常会遇到。

binlog即binary log，二进制日志文件，也叫作变更日志（update log）。它记录了数据库所有执行的`DD`和 `DML` 等数据库更新事件的语句，但是不包含没有修改任何数据的语句（如数据查询语句select、show等）。

它以`事件形式`记录并保存在`二进制文件`中。通过这些信息，我们可以再现数据更新操作的全过程。

> 如果想要记录所有语句（例如，为了识别有问题的查询)，需要使用通用查询日志。

binlog主要应用场景:

*  一是用于`数据恢复`，如果MySQL数据库意外停止，可以通过二进制日志文件来查看用户执行了哪些操作，对数据库服务器文件做了哪些修改，然后根据二进制日志文件中的记录来恢复数据库服务器。
 *  二是用于`数据复制`，由于日志的延续性和时效性，master把它的二进制日志传递给slaves来达到master-slave数据—致的目的。  
    可以说MySQL数据库的**数据备份、主备、主主、主从**都离不开binlog，需要依靠binlog来同步数据，保证数据—致性。

![image-20230722145925374][]

#### 5.1 查看默认情况 ####

查看记录二进制日志是否开启：在MySQL8中默认情况下，二进制文件是开启的。

`log_bin_basename`: 是binlog日志的基本文件名，后面会追加标识来表示每一个文件

`log_bin_index`:是binlog文件的索引文件，这个文件管理了所有的binlog文件的目录

`log_bin_trust_function_creators`: 限制存储过程，前面我们已经讲过了，这是因为二进制日志的一个重要功能是用于主从复制，而存储函数有可能导致主从的数据不一致。所以当开启二进制日志后，需要限制存储函数的创建、修改、调用

`log_bin_use_v1_row_events`：此只读系统变量已弃用。ON表示使用版本1二进制日志行，OFF表示使用版本2二进制日志行(MysQL 5.6的默认值为2)。

每次服务重启，都会新创建一个binlog

![image-20230722152900088][]

#### 5.2 日志参数设置 ####

**方式1：永久性方式**

修改MySQL的 my.cnf 或 my.ini 文件可以设置二进制日志的相关参数：

[mysqld]
    #启用二进制日志
    log-bin=atguigu-bin
    binlog_expire_logs_seconds=600
    max_binlog_size=100M

> 提示:
> 
> 1.  log-bin=mysql-bin \#打开日志(主机需要打开)，这个mysql-bin也可以自定义，这里也可以加上路径，如: /home/www/mysql\_bin\_log/mysql-bin
> 2.  binlog\_expire\_logs\_seconds:此参数控制二进制日志文件保留的时长，单位是秒，默认2592000 3(天-- 14400 4小时; 86400 1天; 259200 3天;
> 3.  max\_binlog\_size:控制单个二进制日志大小，当前日志文件大小超过此变量时，执行切换动作。此参数的`最大和默认值是1GB`，该设置并不能严格控制Binlog的大小，尤其是Binlog比较靠近最大值而又遇到一个比较大事务时，为了保证事务的完整性，可能不做切换日志的动作，只能将该事务的所有SQL都记录进当前日志，直到事务结束。一般情况下可采取默认值

重新启动MySQL服务，查询二进制日志的信息，执行结果：

![image-20230722161129546][]

**设置带文件夹的bin-log日志存放目录**

如果想改变日志文件的目录和名称，可以对my.cnf或my.ini中的log\_bin参数修改如下：

[mysqld]
    log-bin="/var/lib/mysql/binlog/atguigu-bin"

注意：新建的文件夹需要使用mysql用户，使用下面的命令即可。

chown -R -v mysql:mysql binlog

重启MySQL服务之后，新的二进制日志文件将出现在/var/lib/mysql/binlog/文件夹下面:

> 提示：`数据库文件最好不要与日志文件放在同一个磁盘上!` 这样，当数据库文件所在的磁盘发生故障时，可以使用日志文件恢复数据。

**方式2：临时性方式**

如果不希望通过修改配置文件并重启的方式设置二进制日志的话，还可以使用如下指令，需要注意的是在mysql8中只有 会话级别 的设置，没有了global级别的设置。

# global 级别
    set global sql_log_bin=0;
    #ERROR 1228 (HY000): Variable 'sql_log_bin' is a SESSION variable and can`t be used with SET GLOBAL
    
    # session级别
    SET sql_log_bin=0;
    #Query OK, 0 rows affected (0.01 秒)

#### 5.3 查看日志 ####

当MySQL创建二进制日志文件时，先创建一个以“filename”为名称、以“.index”为后缀的文件，再创建一个以“filename”为名称、以“.000001”为后缀的文件。

MySQL服务 `重新启动一次` ，以“.000001”为后缀的文件就会增加一个，并且后缀名按1递增。即日志文件的个数与MySQL服务启动的次数相同；如果日志长度超过了 `max_binlog_size`的上限（默认是1GB），就会创建一个新的日志文件。

查看当前的二进制日志文件列表及大小。指令如下：

mysql> SHOW BINARY LOGS; 
    ERROR 2013 (HY000): Lost connection to MySQL server during query
    No connection. Trying to reconnect...
    Connection id:    8
    Current database: *** NONE ***
    
    +--------------------+-----------+-----------+
    | Log_name           | File_size | Encrypted |
    +--------------------+-----------+-----------+
    | atguigu-bin.000001 |       179 | No        |
    | atguigu-bin.000002 |       156 | No        |
    +--------------------+-----------+-----------+
    2 rows in set (0.00 sec)

所有对数据库的修改都会记录在binglog中。但binlog是二进制文件，无法直接查看，想要更直观的观测它就要借助`mysqlbinlog`命令工具了。指令如下:在查看执行，先执行两条SQL语句，如下

insert into student(id,name,class) values(18,'Jerry','四班');
    update student set name = 'Tom' where id = 15;

开始查看binlog

[root@hadoop102 mysql]# mysqlbinlog "/var/lib/mysql/atguigu-bin.000002"
    /*!50530 SET @@SESSION.PSEUDO_SLAVE_MODE=1*/;
    /*!50003 SET @OLD_COMPLETION_TYPE=@@COMPLETION_TYPE,COMPLETION_TYPE=0*/;
    DELIMITER /*!*/;
    # at 4
    #230722 16:14:20 server id 1  end_log_pos 125 CRC32 0xfbe10f64 	Start: binlog v 4, server v 8.0.25 created 230722 16:14:20 at startup
    # Warning: this binlog is either in use or was not closed properly.
    ROLLBACK/*!*/;
    BINLOG '
    '/*!*/;
    # at 547
    #230722 16:19:02 server id 1  end_log_pos 637 CRC32 0x0e4d6052 	Query	thread_id=8	exec_time=0	error_code=0
    SET TIMESTAMP=1690013942/*!*/;
    BEGIN
    //......
    # at 776
    #230722 16:19:02 server id 1  end_log_pos 807 CRC32 0x6f80cb79 	Xid = 15
    COMMIT/*!*/;
    SET @@SESSION.GTID_NEXT= 'AUTOMATIC' /* added by mysqlbinlog */ /*!*/;
    DELIMITER ;
    # End of log file
    /*!50003 SET COMPLETION_TYPE=@OLD_COMPLETION_TYPE*/;
    /*!50530 SET @@SESSION.PSEUDO_SLAVE_MODE=0*/;

执行结果可以看到，这是一个简单的日志文件，日志中记录了用户的一些操作，这里并没有出现具体的SQL语句，这是因为binlog关键字后面的内容是经过编码后的`二进制日志`。

这里一个update语句包含如下事件

*  Query事件负责开始一个事务(BEGIN)
 *  Table\_map事件负责映射需要的表.
 *  Update\_rows事件负责写入数据
 *  Xid事件负责结束事务

下面命令将行事件以 `伪SQL的形式` 表现出来

[root@hadoop102 mysql]# mysqlbinlog -v "/var/lib/mysql/atguigu-bin.000002"
    /*!50530 SET @@SESSION.PSEUDO_SLAVE_MODE=1*/;
    /*!50003 SET @OLD_COMPLETION_TYPE=@@COMPLETION_TYPE,COMPLETION_TYPE=0*/;
    DELIMITER /*!*/;
    # at 4
    #230722 16:14:20 server id 1  end_log_pos 125 CRC32 0xfbe10f64 	Start: binlog v 4, server v 8.0.25 created 230722 16:14:20 at startup
    # Warning: this binlog is either in use or was not closed properly.
    ROLLBACK/*!*/;
    BINLOG '
    3I+7ZA8BAAAAeQAAAH0AAAABAAQAOC4wLjI1AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
    AAAAAAAAAAAAAAAAAADcj7tkEwANAAgAAAAABAAEAAAAYQAEGggAAAAICAgCAAAACgoKKioAEjQA
    CigBZA/h+w==
    '/*!*/;
    # at 125
    #230722 16:14:20 server id 1  end_log_pos 156 CRC32 0x7b4b2408 	Previous-GTIDs
    # [empty]
    # at 156
    #230722 16:18:26 server id 1  end_log_pos 235 CRC32 0x80f68688 	Anonymous_GTID	last_committed=0	sequence_number=1	rbr_only=yes	original_committed_timestamp=1690013906305153	immediate_commit_timestamp=1690013906305153	transaction_length=312
    /*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
    # original_commit_timestamp=1690013906305153 (2023-07-22 16:18:26.305153 CST)
    # immediate_commit_timestamp=1690013906305153 (2023-07-22 16:18:26.305153 CST)
    /*!80001 SET @@session.original_commit_timestamp=1690013906305153*//*!*/;
    /*!80014 SET @@session.original_server_version=80025*//*!*/;
    /*!80014 SET @@session.immediate_server_version=80025*//*!*/;
    SET @@SESSION.GTID_NEXT= 'ANONYMOUS'/*!*/;
    # at 235
    #230722 16:18:26 server id 1  end_log_pos 316 CRC32 0x0e6498f6 	Query	thread_id=8	exec_time=0	error_code=0
    SET TIMESTAMP=1690013906/*!*/;
    SET @@session.pseudo_thread_id=8/*!*/;
    SET @@session.foreign_key_checks=1, @@session.sql_auto_is_null=0, @@session.unique_checks=1, @@session.autocommit=1/*!*/;
    SET @@session.sql_mode=1168113696/*!*/;
    SET @@session.auto_increment_increment=1, @@session.auto_increment_offset=1/*!*/;
    /*!\C utf8mb4 *//*!*/;
    SET @@session.character_set_client=255,@@session.collation_connection=255,@@session.collation_server=255/*!*/;
    SET @@session.lc_time_names=0/*!*/;
    SET @@session.collation_database=DEFAULT/*!*/;
    /*!80011 SET @@session.default_collation_for_utf8mb4=255*//*!*/;
    BEGIN
    /*!*/;
    # at 316
    #230722 16:18:26 server id 1  end_log_pos 384 CRC32 0xa3ddc17f 	Table_map: `atguigudb3`.`student` mapped to number 92
    # at 384
    #230722 16:18:26 server id 1  end_log_pos 437 CRC32 0x77beae88 	Write_rows: table id 92 flags: STMT_END_F
    
    BINLOG '
    0pC7ZBMBAAAARAAAAIABAAAAAFwAAAAAAAEACmF0Z3VpZ3VkYjMAB3N0dWRlbnQAAwMPDwQ8AB4A
    BgEBAAIBIX/B3aM=
    0pC7ZB4BAAAANQAAALUBAAAAAFwAAAAAAAEAAgAD/wASAAAABUplcnJ5BuWbm+ePrYiuvnc=
    '/*!*/;
    ### INSERT INTO `atguigudb3`.`student`
    ### SET
    ###   @1=18
    ###   @2='Jerry'
    ###   @3='四班'
    # at 437
    #230722 16:18:26 server id 1  end_log_pos 468 CRC32 0x0ba33a3f 	Xid = 14
    COMMIT/*!*/;
    # at 468
    #230722 16:19:02 server id 1  end_log_pos 547 CRC32 0xcd6ec87a 	Anonymous_GTID	last_committed=1	sequence_number=2	rbr_only=yes	original_committed_timestamp=1690013942336137	immediate_commit_timestamp=1690013942336137	transaction_length=339
    /*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
    # original_commit_timestamp=1690013942336137 (2023-07-22 16:19:02.336137 CST)
    # immediate_commit_timestamp=1690013942336137 (2023-07-22 16:19:02.336137 CST)
    /*!80001 SET @@session.original_commit_timestamp=1690013942336137*//*!*/;
    /*!80014 SET @@session.original_server_version=80025*//*!*/;
    /*!80014 SET @@session.immediate_server_version=80025*//*!*/;
    SET @@SESSION.GTID_NEXT= 'ANONYMOUS'/*!*/;
    # at 547
    #230722 16:19:02 server id 1  end_log_pos 637 CRC32 0x0e4d6052 	Query	thread_id=8	exec_time=0	error_code=0
    SET TIMESTAMP=1690013942/*!*/;
    BEGIN
    /*!*/;
    # at 637
    #230722 16:19:02 server id 1  end_log_pos 705 CRC32 0xdff84bbe 	Table_map: `atguigudb3`.`student` mapped to number 92
    # at 705
    #230722 16:19:02 server id 1  end_log_pos 776 CRC32 0xfbd17653 	Update_rows: table id 92 flags: STMT_END_F
    
    BINLOG '
    9pC7ZBMBAAAARAAAAMECAAAAAFwAAAAAAAEACmF0Z3VpZ3VkYjMAB3N0dWRlbnQAAwMPDwQ8AB4A
    BgEBAAIBIb5L+N8=
    9pC7ZB8BAAAARwAAAAgDAAAAAFwAAAAAAAEAAgAD//8ADwAAAAbotbXlha0G5LqM54+tAA8AAAAD
    VG9tBuS6jOePrVN20fs=
    '/*!*/;
    ### UPDATE `atguigudb3`.`student`
    ### WHERE
    ###   @1=15
    ###   @2='赵六'
    ###   @3='二班'
    ### SET
    ###   @1=15
    ###   @2='Tom'
    ###   @3='二班'
    # at 776
    #230722 16:19:02 server id 1  end_log_pos 807 CRC32 0x6f80cb79 	Xid = 15
    COMMIT/*!*/;
    SET @@SESSION.GTID_NEXT= 'AUTOMATIC' /* added by mysqlbinlog */ /*!*/;
    DELIMITER ;
    # End of log file
    /*!50003 SET COMPLETION_TYPE=@OLD_COMPLETION_TYPE*/;
    /*!50530 SET @@SESSION.PSEUDO_SLAVE_MODE=0*/;

前面的命令同时显示binlog格式的语句，使用如下命令不显示它

[root@hadoop102 mysql]# mysqlbinlog -v --base64-output=DECODE-ROWS "/var/lib/mysql/atguigu-bin.000002"
    /*!50530 SET @@SESSION.PSEUDO_SLAVE_MODE=1*/;
    /*!50003 SET @OLD_COMPLETION_TYPE=@@COMPLETION_TYPE,COMPLETION_TYPE=0*/;
    DELIMITER /*!*/;
    # at 4
    #230722 16:14:20 server id 1  end_log_pos 125 CRC32 0xfbe10f64 	Start: binlog v 4, server v 8.0.25 created 230722 16:14:20 at startup
    # Warning: this binlog is either in use or was not closed properly.
    ROLLBACK/*!*/;
    # at 125
    #230722 16:14:20 server id 1  end_log_pos 156 CRC32 0x7b4b2408 	Previous-GTIDs
    # [empty]
    # at 156
    #230722 16:18:26 server id 1  end_log_pos 235 CRC32 0x80f68688 	Anonymous_GTID	last_committed=0	sequence_number=1	rbr_only=yes	original_committed_timestamp=1690013906305153	immediate_commit_timestamp=1690013906305153	transaction_length=312
    /*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
    # original_commit_timestamp=1690013906305153 (2023-07-22 16:18:26.305153 CST)
    # immediate_commit_timestamp=1690013906305153 (2023-07-22 16:18:26.305153 CST)
    /*!80001 SET @@session.original_commit_timestamp=1690013906305153*//*!*/;
    /*!80014 SET @@session.original_server_version=80025*//*!*/;
    /*!80014 SET @@session.immediate_server_version=80025*//*!*/;
    SET @@SESSION.GTID_NEXT= 'ANONYMOUS'/*!*/;
    # at 235
    #230722 16:18:26 server id 1  end_log_pos 316 CRC32 0x0e6498f6 	Query	thread_id=8	exec_time=0	error_code=0
    SET TIMESTAMP=1690013906/*!*/;
    SET @@session.pseudo_thread_id=8/*!*/;
    SET @@session.foreign_key_checks=1, @@session.sql_auto_is_null=0, @@session.unique_checks=1, @@session.autocommit=1/*!*/;
    SET @@session.sql_mode=1168113696/*!*/;
    SET @@session.auto_increment_increment=1, @@session.auto_increment_offset=1/*!*/;
    /*!\C utf8mb4 *//*!*/;
    SET @@session.character_set_client=255,@@session.collation_connection=255,@@session.collation_server=255/*!*/;
    SET @@session.lc_time_names=0/*!*/;
    SET @@session.collation_database=DEFAULT/*!*/;
    /*!80011 SET @@session.default_collation_for_utf8mb4=255*//*!*/;
    BEGIN
    /*!*/;
    # at 316
    #230722 16:18:26 server id 1  end_log_pos 384 CRC32 0xa3ddc17f 	Table_map: `atguigudb3`.`student` mapped to number 92
    # at 384
    #230722 16:18:26 server id 1  end_log_pos 437 CRC32 0x77beae88 	Write_rows: table id 92 flags: STMT_END_F
    ### INSERT INTO `atguigudb3`.`student`
    ### SET
    ###   @1=18
    ###   @2='Jerry'
    ###   @3='四班'
    # at 437
    #230722 16:18:26 server id 1  end_log_pos 468 CRC32 0x0ba33a3f 	Xid = 14
    COMMIT/*!*/;
    # at 468
    #230722 16:19:02 server id 1  end_log_pos 547 CRC32 0xcd6ec87a 	Anonymous_GTID	last_committed=1	sequence_number=2	rbr_only=yes	original_committed_timestamp=1690013942336137	immediate_commit_timestamp=1690013942336137	transaction_length=339
    /*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
    # original_commit_timestamp=1690013942336137 (2023-07-22 16:19:02.336137 CST)
    # immediate_commit_timestamp=1690013942336137 (2023-07-22 16:19:02.336137 CST)
    /*!80001 SET @@session.original_commit_timestamp=1690013942336137*//*!*/;
    /*!80014 SET @@session.original_server_version=80025*//*!*/;
    /*!80014 SET @@session.immediate_server_version=80025*//*!*/;
    SET @@SESSION.GTID_NEXT= 'ANONYMOUS'/*!*/;
    # at 547
    #230722 16:19:02 server id 1  end_log_pos 637 CRC32 0x0e4d6052 	Query	thread_id=8	exec_time=0	error_code=0
    SET TIMESTAMP=1690013942/*!*/;
    BEGIN
    /*!*/;
    # at 637
    #230722 16:19:02 server id 1  end_log_pos 705 CRC32 0xdff84bbe 	Table_map: `atguigudb3`.`student` mapped to number 92
    # at 705
    #230722 16:19:02 server id 1  end_log_pos 776 CRC32 0xfbd17653 	Update_rows: table id 92 flags: STMT_END_F
    ### UPDATE `atguigudb3`.`student`
    ### WHERE
    ###   @1=15
    ###   @2='赵六'
    ###   @3='二班'
    ### SET
    ###   @1=15
    ###   @2='Tom'
    ###   @3='二班'
    # at 776
    #230722 16:19:02 server id 1  end_log_pos 807 CRC32 0x6f80cb79 	Xid = 15
    COMMIT/*!*/;
    SET @@SESSION.GTID_NEXT= 'AUTOMATIC' /* added by mysqlbinlog */ /*!*/;
    DELIMITER ;
    # End of log file
    /*!50003 SET COMPLETION_TYPE=@OLD_COMPLETION_TYPE*/;
    /*!50530 SET @@SESSION.PSEUDO_SLAVE_MODE=0*/;

关于mysqlbinlog工具的使用技巧还有很多，例如只解析对某个库的操作或者某个时间段内的操作等。简单分享几个常用的语句，更多操作可以参考官方文档。

#  可查看参数帮助
    mysqlbinlog  --no-defaults --help
    #  查看最后100行
    mysqlbinlog --no-defaults --base64-output=decode-rows -vv atguigu-bin.000002 |tail -100
    # 根据position查找
    mysqlbinlog  --no-defaults --base64-output=decode-rows -vv atguigu-bin.000002 |grep -A 20  '4939002'

上面这种办法读取出binlog日志的全文内容比较多，不容易分辨查看到pos点信息，下面介绍一种更为方便的查询命令：

show binlog events [IN 'log_name'] [FROM pos] [LIMIT [offset,] row_count];

*  `IN ‘log_name’` ：指定要查询的binlog文件名（不指定就是第一个binlog文件）
 *  `FROM pos` ：指定从哪个pos起始点开始查起（不指定就是从整个文件首个pos点开始算）
 *  `LIMIT [offset]`：偏移量(不指定就是0)
 *  `row_count` :查询总条数（不指定就是所有行）

mysql> show binlog events in 'atguigu-bin.000002';  
    +--------------------+-----+----------------+-----------+-------------+--------------------------------------+
    | Log_name           | Pos | Event_type     | Server_id | End_log_pos | Info                                 |
    +--------------------+-----+----------------+-----------+-------------+--------------------------------------+
    | atguigu-bin.000002 |   4 | Format_desc    |         1 |         125 | Server ver: 8.0.25, Binlog ver: 4    |
    | atguigu-bin.000002 | 125 | Previous_gtids |         1 |         156 |                                      |
    | atguigu-bin.000002 | 156 | Anonymous_Gtid |         1 |         235 | SET @@SESSION.GTID_NEXT= 'ANONYMOUS' |
    | atguigu-bin.000002 | 235 | Query          |         1 |         316 | BEGIN                                |
    | atguigu-bin.000002 | 316 | Table_map      |         1 |         384 | table_id: 92 (atguigudb3.student)    |
    | atguigu-bin.000002 | 384 | Write_rows     |         1 |         437 | table_id: 92 flags: STMT_END_F       |
    | atguigu-bin.000002 | 437 | Xid            |         1 |         468 | COMMIT /* xid=14 */                  |
    | atguigu-bin.000002 | 468 | Anonymous_Gtid |         1 |         547 | SET @@SESSION.GTID_NEXT= 'ANONYMOUS' |
    | atguigu-bin.000002 | 547 | Query          |         1 |         637 | BEGIN                                |
    | atguigu-bin.000002 | 637 | Table_map      |         1 |         705 | table_id: 92 (atguigudb3.student)    |
    | atguigu-bin.000002 | 705 | Update_rows    |         1 |         776 | table_id: 92 flags: STMT_END_F       |
    | atguigu-bin.000002 | 776 | Xid            |         1 |         807 | COMMIT /* xid=15 */                  |
    +--------------------+-----+----------------+-----------+-------------+--------------------------------------+

上面这条语句可以将指定的binlog日志文件，分成有效事件行的方式返回，并可使用limit指定pos点的起始偏移，查询条数。其它举例:

#a、查询第一个最早的binlog日志:
    show binlog events\G ;
    
    #b、指定查询mysql-bin.088002这个文件
    show binlog events in 'atguigu-bin.000002'\G;
    
    #c、指定查询mysql-bin.0888日2这个文件，从pos点:391开始查起:
    show binlog events in 'atguigu-bin . 088882' from 391\G;
    
    #d、指定查询mysql-bin.000002这个文件，从pos点:391开始查起，查询5条（即5条语句
    show binlog events in 'atguigu-bin.080802' from 391 limit 5\G;
    
    #e、指定查询 mysql-bin.000002这个文件，从pos点:391开始查起，偏移2行〈即中间跳过2个）查询5条（即5条语句)。
    show binlog events in 'atguigu-bin.088002 ' from 391 limit 2,5\G;

上面我们讲了这么多都是基于binlog的默认格式，binlog格式查看

show variables like 'binlog_format';
    /*
     + ---------------+-------  +
    | Variable_name | Value |
     + ---------------+-------  +
    | binlog_format | ROW   |
     + ---------------+-------  +
    1 行于数据集 (0.02秒)
    */

除此之外，binlog还有2种格式，分别是`Statement`和`Mixed`

*  Statement  
    每一条会修改数据的sql都会记录在binlog中。
    
    优点：不需要记录每一行的变化，减少了binlog日志量，节约了IO，提高性能。
 *  Row  
    5.1.5版本的MySQL才开始支持row level 的复制，它不记录sql语句上下文相关信息，仅保存哪条记录被修改。  
    优点：row level 的日志内容会非常清楚的记录下每一行数据修改的细节。而且不会出现某些特定情况下的存储过程，或function，以及trigger的调用和触发无法被正确复制的问题。
 *  Mixed
    
    从5.1.8版本开始，MySQL提供了Mixed格式，实际上就是Statement与Row的结合。

详细情况，下章讲解。

#### 5.4 使用日志恢复数据 ####

如果MySQL服务器启用了二进制日志，在数据库出现意外丢失数据时，可以使用MySQLbinlog工具从指定的时间点开始（例如，最后一次备份）直到现在或另一个指定的时间点的日志中恢复数据。

mysqlbinlog恢复数据的语法如下：

mysqlbinlog [option] filename|mysql –uuser -ppass;

这个命令可以这样理解：使用mysqlbinlog命令来读取filename中的内容，然后使用mysql命令将这些内容恢复到数据库中。

*  `filename`：是日志文件名。
 *  `option`：可选项，比较重要的两对option参数是–start-date、–stop-date 和–start-position、-- stop-position。
    
     *  –start-date 和 --stop-date ：可以指定恢复数据库的起始时间点和结束时间点。
     *  –start-position和–stop-position ：可以指定恢复数据的开始位置和结束位置。

> 注意：使用mysqlbinlog命令进行恢复操作时，必须是编号小的先恢复，例如atguigu-bin.000001必须在atguigu-bin.000002之前恢复。

**案例**

现在对student表有以下操作

mysql> use atguigudb3;
    Database changed
    mysql> select * from student;
    +----+---------+--------+
    | id | name    | class  |
    +----+---------+--------+
    |  1 | 张三2   | 一班   |
    |  3 | 李四1   | 一班   |
    |  6 | Jane    | 一班   |
    |  8 | 王五    | 二班   |
    | 15 | Tom     | 二班   |
    | 18 | Jerry   | 四班   |
    | 20 | 钱七    | 三班   |
    +----+---------+--------+
    7 rows in set (0.00 sec)
    
    #插入数据
    mysql> insert into student(id,name,class) values(21,'aaa','No1');
    Query OK, 1 row affected (0.00 sec)
    
    mysql> insert into student(id,name,class) values(22,'aaa','No1');
    Query OK, 1 row affected (0.00 sec)
    
    mysql> insert into student(id,name,class) values(23,'aaa','No1');
    Query OK, 1 row affected (0.00 sec)
    
    mysql> select * from student;
    +----+---------+--------+
    | id | name    | class  |
    +----+---------+--------+
    |  1 | 张三2   | 一班   |
    |  3 | 李四1   | 一班   |
    |  6 | Jane    | 一班   |
    |  8 | 王五    | 二班   |
    | 15 | Tom     | 二班   |
    | 18 | Jerry   | 四班   |
    | 20 | 钱七    | 三班   |
    | 21 | aaa     | No1    |
    | 22 | aaa     | No1    |
    | 23 | aaa     | No1    |
    +----+---------+--------+
    10 rows in set (0.01 sec)
    
    mysql> delete from student where id = 21;
    Query OK, 1 row affected (0.01 sec)
    
    mysql> update student set name='bbb' where id=22;
    Query OK, 1 row affected (0.00 sec)
    Rows matched: 1  Changed: 1  Warnings: 0
    
    mysql>  select * from student;
    +----+---------+--------+
    | id | name    | class  |
    +----+---------+--------+
    |  1 | 张三2   | 一班   |
    |  3 | 李四1   | 一班   |
    |  6 | Jane    | 一班   |
    |  8 | 王五    | 二班   |
    | 15 | Tom     | 二班   |
    | 18 | Jerry   | 四班   |
    | 20 | 钱七    | 三班   |
    | 22 | bbb     | No1    |
    | 23 | aaa     | No1    |
    +----+---------+--------+
    9 rows in set (0.00 sec)

**误操作**

mysql> delete from student where id > 21;
    Query OK, 2 rows affected (0.01 sec)
    
    mysql>  select * from student;
    +----+---------+--------+
    | id | name    | class  |
    +----+---------+--------+
    |  1 | 张三2   | 一班   |
    |  3 | 李四1   | 一班   |
    |  6 | Jane    | 一班   |
    |  8 | 王五    | 二班   |
    | 15 | Tom     | 二班   |
    | 18 | Jerry   | 四班   |
    | 20 | 钱七    | 三班   |
    +----+---------+--------+
    7 rows in set (0.00 sec)

**尝试恢复**

# 查看当前数据库的bin_log日志
    mysql> show binary logs;
    +--------------------+-----------+-----------+
    | Log_name           | File_size | Encrypted |
    +--------------------+-----------+-----------+
    | atguigu-bin.000001 |       179 | No        |
    | atguigu-bin.000002 |      2685 | No        |
    +--------------------+-----------+-----------+
    2 rows in set (0.00 sec)
    
    #新增binlog【利用日志恢复本质上依旧是对表进行增删改，仍然会产生bin_log日志。所以我们应该新增一个bin_log日志，避免对用于恢复的日志00002产生影响】
    mysql> show binary logs;
    +--------------------+-----------+-----------+
    | Log_name           | File_size | Encrypted |
    +--------------------+-----------+-----------+
    | atguigu-bin.000001 |       179 | No        |
    | atguigu-bin.000002 |      2734 | No        |
    | atguigu-bin.000003 |       156 | No        |
    +--------------------+-----------+-----------+
    3 rows in set (0.00 sec)

位置恢复show binlog events in

![ebcaa86c8cae0ed97acf3e7441e37624.png][]

查看日志发现，在备份数据后首先执行的是插入数据操作，在Info信息中xid的值分别是22、23、24 (紧邻的三项)

\*\*思路：\*\*先恢复3个insert22 23 24，再恢复delete 26，最后update 27

步骤1:恢复插入的数据

#语法
    [root@centos7-mysql-1 mysql]# /usr/bin/mysqlbinlog --start-position=初位置 --stop-position=初位置 --database=数据库名 /var/lib/mysql/日志文件 | /usr/bin/mysql -uroot -p密码 -v 数据库名
    #实战
    [root@hadoop102 mysql]# /usr/bin/mysqlbinlog --start-position=886 --stop-position=1728 --database=atguigudb3 /var/lib/mysql/atguigu-bin.000002 | /usr/bin/mysql -uroot -proot -v atguigudb3;

执行完进行查看，发现这三个插入操作已经被恢复

![image-20230722190125039][]

步骤2:恢复删除的数据

[root@hadoop102 mysql]# /usr/bin/mysqlbinlog --start-position=1807 --stop-position=2035 --database=atguigudb3 /var/lib/mysql/atguigu-bin.000002 | /usr/bin/mysql -uroot -proot -v atguigudb3;

查看结果

![image-20230722191750190][]

步骤3:恢复修改的数据的数据

[root@hadoop102 mysql]# /usr/bin/mysqlbinlog --start-position=2114 --stop-position=2365 --database=atguigudb3 /var/lib/mysql/atguigu-bin.000002 | /usr/bin/mysql -uroot -proot -v atguigudb3;

![image-20230722205229333][]

可以看到最终结果和删除数据之前的结果一样，利用binlog实现了数据恢复。

当然也可以使用日期恢复，命令格式如下：

/usr/bin/mysqlbinlog --start-datetime="2023-07-22 16:14:20” --stop-datetime="2023-07-22 16:19:02"  --database=atguigudb3 /var/lib/mysql/binlog/atguigu-bin.000002 | /usr/bin/mysql -uroot root  -v atguigudb3

另外，有时候可能出现一个事务A执行时间过短，几秒内执行完成。此时我们找到下一个事务B的开始时间和结束时间。只要恢复的时间在B结束时间之前就可以只恢复A不恢复B【只要时间不完全包含一个事务，那么此事务就不会进行恢复~】

# at 4
    #230722 16:14:20 server id 1  end_log_pos 125 CRC32 0xfbe10f64 	Start: binlog v 4, server v 8.0.25 created 230722 16:14:20 at startup
    # Warning: this binlog is either in use or was not closed properly.
    ROLLBACK/*!*/;
    BINLOG '
    '/*!*/;
    # at 547
    #230722 16:19:02 server id 1  end_log_pos 637 CRC32 0x0e4d6052 	Query	thread_id=8	exec_time=0	error_code=0
    SET TIMESTAMP=1690013942/*!*/;
    BEGIN
    //......
    # at 776
    #230722 16:19:02 server id 1  end_log_pos 807 CRC32 0x6f80cb79 	Xid = 15
    COMMIT/*!*/;
    SET @@SESSION.GTID_NEXT= 'AUTOMATIC' /* added by mysqlbinlog */ /*!*/;
    DELIMITER ;
    # End of log file
    /*!50003 SET COMPLETION_TYPE=@OLD_COMPLETION_TYPE*/;

mysqlbinlog命令对于意外操作非常有效，比如因操作不当误删了数据表。

#### 5.5 删除二进制日志 ####

MySQL的二进制文件可以配置自动删除，同时MySQL也提供了安全的手动删除二进制文件的方法。PURGE MASTER LOGS只删除指定部分的二进制日志文件， RESET MASTER删除所有的二进制日志文件。具体如下：

1.  **PURGE MASTER LOGS：删除指定日志文件**

PURGE {MASTER | BINARY} LOGS TO '指定日志文件名'
    PURGE {MASTER | BINARY} LOGS BEFORE '指定日期'

举例:使用PURGE MASTER LOGS语句删除创建时间比binlog.000005早的所有日志

(1）多次重新启动MySQL服务，便于生成多个日志文件。然后用SHOW语句显示二进制日志文件列表

SHOW BINARY LOGS;

(2）执行PURGE MASTER LOGS语句删除创建时间比binlog.oo0005早的所有日志

PURGE MASTER LoGS TO "binlog.000005";

(3）显示二进制日志文件列表

SHOW BINARY LOGS ;

比binlog.000005早的所有日志文件都已经被删除了。

举例:使用PURGE MASTER LOGS语句删除2020年10月25号前创建的所有日志文件。具体步骤如下:

(1）显示二进制日志文件列表

SHOW BINARY LOGS;

(2）执行mysqlbinlog命令查看二进制日志文件binlog.000005的内容

mysqlbinlog --no-defaults "/var/lib/mysql/atguigu-bin.000005"

![image-20230805163137644][]

结果可以看出20230805为日志创建的时间，即2023年08月05日。

(3）使用PURGE MASTER LOGs语句删除2022年1月05日前创建的所有日志文件

PURGE MASTER LoGS before "20220105";

(4）显示二进制日志文件列表

SHOW BINARY LOGS ;

2023年08月05号之前的二进制日志文件都已经被删除，最后一个没有删除，是因为当前在用，还未记录最后的时间，所以未被删除。

**2.RESET MASTER:删除所有二进制日志文件**

使用`RESET MASTER`语句，清空所有的binlog日志。MySQL会重新创建二进制文件，新的日志文件扩展名将重新从000001开始编号。`慎用`!

举例:使用RESET MASTER语句删除所有日志文件。

(1）重启MySQL服务若干次，执行SHOW语句显示二进制日志文件列表。

SHOW BINARY LOGS;

(2)执行RESET MASTER语句，删除所有日志文件

RESET MASTER;

执行完该语句后，原来的所有二进制日志已经全部被删除。

#### 5.6 其它场景 ####

二进制日志可以通过数据库的`全量备份`和二进制日志中保存的`增量信息` ，完成数据库的`无损失恢复` 。但是，如果遇到数据量大、数据库和数据表很多（比如分库分表的应用）的场景，用二进制日志进行数据恢复，是很有挑战性的，因为起止位置不容易管理。

在这种情况下，一个有效的解决办法是`配置主从数据库服务器` ，甚至是一主多从 的架构，把二进制日志文件的内容通过中继日志，同步到从数据库服务器中，这样就可以有效避免数据库故障导致的数据异常等问题。

### 6. 再谈二进制日志(binlog) ###

#### 6.1 写入机制 ####

binlog的写入时机也非常简单，事务执行过程中，先把日志写到`binlog cache` ，事务提交的时候，再把binlog cache写到binlog文件中。因为一个事务的binlog不能被拆开，无论这个事务多大，也要确保一次性写入，所以系统会给每个线程分配一个块内存作为binlog cache。

我们可以通过`binlog_cache_size`参数控制单个线程binlog cache大小，如果存储内容超过了这个参数，就要暂存到磁盘(Swap)。binlog日志刷盘流程如下:

![image-20230806151615285][]

write和fsync的时机，可以由参数`sync_binlog`控制，默认是0 。为0的时候，表示每次提交事务都只write，由系统自行判断什么时候执行fsync。虽然性能得到提升，但是机器宕机，page cache里面的binglog 会丢失。如下图：

![image-20230806151719545][]

为了安全起见，可以设置为1 ，表示每次提交事务都会执行fsync，就如同`redo log 刷盘流程`一样。最后还有一种折中方式，可以设置为`N`(N>1)，表示每次提交事务都write，但累积N个事务后才fsync。

![image-20230806151910974][]

在出现IO瓶颈的场景里，将sync\_binlog设置成一个比较大的值，可以提升性能。同样的，如果机器宕机，会丢失最近N个事务的binlog日志。

#### 6.2 binlog与redolog对比 ####

*  redo log 它是`物理日志` ，记录内容是“在某个数据页上做了什么修改”，属于 InnoDB 存储引擎层产生的。
 *  而 binlog 是`逻辑日志` ，记录内容是语句的原始逻辑，类似于“给 ID=2 这一行的 c 字段加 1”，属于 MySQL Server层。

虽然它们都属于持久化的保证，但是侧重点不同：

*  redo log让InnoDB存储引擎拥有了崩溃恢复能力
 *  binlog 保证了MySQL集群架构的数据一致性

#### 6.3 两阶段提交 ####

在执行更新语句过程，会记录redo log与binlog两块日志，以基本的事务为单位，redo log在事务执行过程中可以不断写入，而binlog只有在提交事务时才写入，所以redo log与binlog的 `写入时机` 不一样

![image-20230806152740528][]

**redo log与binlog两份日志之间的逻辑不一致，会出现什么问题？**

以update语句为例，假设id=2的记录，字段c值是0，把字段c值更新成1，SQL语句为update T set c=1 where id=2。

假设执行过程中写完redo log日志后，binlog日志写期间发生了异常，会出现什么情况呢?

![image-20230806152946955][]

由于binlog没写完就异常，这时候binlog里面没有对应的修改记录。因此，之后用binlog日志恢复数据时，就会少这一次更新，恢复出来的这一行c值是0，而原库因为redo log日志恢复，这一行c值是1，最终数据不一致。

![image-20230806153016005][]

为了解决两份日志之间的逻辑一致问题，InnoDB存储引擎使用两阶段提交方案。原理很简单，将redo log的写入拆成了两个步骤prepare和commit，这就是`两阶段提交`。

![image-20230806153119152][]

使用`两阶段提交`后，写入binlog时发生异常也不会有影响，因为MySQL根据redo log日志恢复数据时，发现redolog还处于prepare阶段，并且没有对应binlog日志，就会回滚该事务。

![image-20230806153232003][]

另一个场景，redo log设置commit阶段发生异常，那会不会回滚事务呢？

![image-20230806153253328][]

并不会回滚事务，它会执行上图框住的逻辑，虽然redo log是处于prepare阶段，但是能通过事务id找到对应的binlog日志，所以MySQL认为是完整的，就会提交事务恢复数据。

### 7. 中继日志(relay log) ###

#### 7.1 介绍 ####

**中继日志只在主从服务器架构的从服务器上存在**。从服务器为了与主服务器保持一致，要从主服务器读取二进制日志的内容，并且把读取到的信息写入`本地的日志文件`中，这个从服务器本地的日志文件就叫中继日志。然后，从服务器读取中继日志，并根据中继日志的内容对从服务器的数据进行更新，完成主从服务器的`数据同步`。

搭建好主从服务器之后，中继日志默认会保存在从服务器的数据目录下。

文件名的格式是：`从服务器名 -relay-bin.序号` 。中继日志还有一个索引文件： `从服务器名 -relay-bin.index` ，用来定位当前正在使用的中继日志。

![4917cb9f9fec2f218b66c0205226f2c3.png][]

#### 7.2 查看中继日志 ####

中继日志与二进制日志的格式相同，可以用 `mysqlbinlog`工具进行查看。下面是中继日志的一个片段：

SET TIMESTAMP=1618558728/*!*/;
    BEGIN
    /*
    /*!*/;
    # at 950
    #210416 15:38:48 server id 1  end_log_pos 832 CRC32 0xcc16d651  Table_map:
    `atguigu`.`test` mapped to number 91
    # at 1000
    #210416 15:38:48 server id 1  end_log_pos 872 CRC32 0x07e4047c  Delete_rows: table id
    91 flags: STMT_END_F  -- server id 1 是主服务器，意思是主服务器删了一行数据
    BINLOG '
    CD95YBMBAAAAMgAAAEADAAAAAFsAAAAAAAEABGRlbW8ABHRlc3QAAQMAAQEBAFHWFsw=
    CD95YCABAAAAKAAAAGgDAAAAAFsAAAAAAAEAAgAB/wABAAAAfATkBw==
    '/*!*/;
    # at 1040
    */

这一段的意思是，主服务器（“server id 1”）对表 atguigu.test 进行了 2 步操作：

定位到表 atguigu.test 编号是 91 的记录，日志位置是 832;
    删除编号是 91 的记录，日志位置是 872。

#### 7.3 恢复的典型错误 ####

如果从服务器宕机，有的时候为了系统恢复，要重装操作系统，这样就可能会导致你的`服务器名称`与之前`不同` 。而中继日志里是 包含从服务器名 的。在这种情况下，就可能导致你恢复从服务器的时候，无法从宕机前的中继日志里读取数据，以为是日志文件损坏了，其实是名称不对了。

解决的方法也很简单，把从服务器的名称改回之前的名称。

[https_dev.mysql.com_doc_refman_8.0_en_server-logs.html]: https://dev.mysql.com/doc/refman/8.0/en/server-logs.html
[07_]: https://blog.csdn.net/LXYDSF/article/details/126338994
[image-20230722145912407]: https://img-blog.csdnimg.cn/img_convert/506149d067d12f1723d5a4e5d6786330.png
[image-20230722145925374]: https://img-blog.csdnimg.cn/img_convert/ca708dc378598a80f9dbeba33257fed2.png
[image-20230722152900088]: https://img-blog.csdnimg.cn/img_convert/98cfa94a495938cc16a9c3d055e7a146.png
[image-20230722161129546]: https://img-blog.csdnimg.cn/img_convert/196215f0b012280ea0361596ffec61b3.png
[ebcaa86c8cae0ed97acf3e7441e37624.png]: https://img-blog.csdnimg.cn/img_convert/ebcaa86c8cae0ed97acf3e7441e37624.png
[image-20230722190125039]: https://img-blog.csdnimg.cn/img_convert/1d0310cefb8cf8ce05e806fdb72bad67.png
[image-20230722191750190]: https://img-blog.csdnimg.cn/img_convert/7b677e9bc338452b85cb4e1c0bd50e47.png
[image-20230722205229333]: https://img-blog.csdnimg.cn/img_convert/01a49947ad8f21f486014217d4e53cbf.png
[image-20230805163137644]: https://img-blog.csdnimg.cn/img_convert/3e188acdde3edbd41a901e54d9118075.png
[image-20230806151615285]: https://img-blog.csdnimg.cn/img_convert/a677bcef63df83e0d1c5c5ae8caf5d17.png
[image-20230806151719545]: https://img-blog.csdnimg.cn/img_convert/cc4192b43863c5c693b05f18a07a5627.png
[image-20230806151910974]: https://img-blog.csdnimg.cn/img_convert/247e091321a3a14d54e59990cc02f31c.png
[image-20230806152740528]: https://img-blog.csdnimg.cn/img_convert/6b3f460ea5fc25e555117c1d973caac1.png
[image-20230806152946955]: https://img-blog.csdnimg.cn/img_convert/770160bfb24a05685bb615563154e94e.png
[image-20230806153016005]: https://img-blog.csdnimg.cn/img_convert/86dfadb2c7a8d65a22442dc1a0e3ecaf.png
[image-20230806153119152]: https://img-blog.csdnimg.cn/img_convert/9ba946be43ac83a2a73167f0884169c3.png
[image-20230806153232003]: https://img-blog.csdnimg.cn/img_convert/dabffd05d0bb188136980fc78459bf31.png
[image-20230806153253328]: https://img-blog.csdnimg.cn/img_convert/5da35f27b4177d1db4def94b362f6268.png
[4917cb9f9fec2f218b66c0205226f2c3.png]: https://img-blog.csdnimg.cn/img_convert/4917cb9f9fec2f218b66c0205226f2c3.png