地址：​​https://dev.mysql.com/doc/internals/en/binary-log.html​​

 

二进制日志是一组文件，其中包含有关对MySQL服务器实例进行的数据修改的信息。

  • 该日志由一组二进制日志文件以及一个索引文件组成。
  • 每个日志文件均包含一个4字节的幻数，其后是一组描述数据修改的事件：
  • 幻数字节为0xfe 0x62 0x69 0x6e = 0xfe'b''i''n'（这是中的​​BINLOG_MAGIC​​ 常数​​log_event.h​​）。
  • 每个事件都包含头字节，后跟数据字节：
  • 标头字节提供有关事件类型，事件的生成时间，服务器的类型等信息。
  • 数据字节提供特定于事件类型的信息，例如特定的数据修改。
  • 第一个事件是描述符事件，它描述文件的格式版本（用于在文件中写入事件的格式）。
  • 其余事件将根据版本进行解释。
  • 最后一个事件是一个日志旋转事件，它指定下一个二进制日志文件名。
  • 索引文件是一个文本文件，列出了当前的二进制日志文件。

 

binlog_MySQL

 

 

###################################################

对于解析MySQL的binlog用来更新其他数据存储的应用来说，binlog的顺序标识是很重要的。比如根据时间戳得到binlog位点作为解析起点。

但是binlog里面的事件，是否有稳定的有序性？

binlog中有三个看上去可能有序的信息：xid、timestamp、gno。本文分析这三个信息在binlog中的有序性。

Xid事件：根据事务提交顺序写入，而不是事务开始

当binlog格式为row，且事务中更新的是事务引擎时，每个事务的结束位置都有Xid，Xid的类型为整型。

MySQL中每个语句都会被分配一个全局递增的query_id(重启会被重置)，每个事务的Xid来源于事务第一个语句的query_id。

考虑一个简单的操作顺序：

session 1: begin; select; update;

session 2: begin; select; update; insert; commit;

session 1: insert; commit;

显然Xid2 > Xid1，但因为事务2会先于事务1记录写binlog，因此在这个binlog中，会出现Xid不是有序的情况。

TIMESTAMP也是事务提交顺序而写入的

时间戳的有序性可能是被误用最多的。在mysqlbinlog这个工具的输出结果中，每个事务起始有会输出一个SET TIMESTAMP=n。这个值取自第一个更新事件的时间。上一节的例子中,timestamp2>timestamp1,但因为事务2会先于事务1记录写binlog，因此在这个binlog中，会出现TIMESTAMP不是有序的情况。

GNO事务起始gtid事件

对于打开了gtid_mode的实例，每个事务起始位置都会有一个gtid event，其内容输出格式为UUID:gn，gno是一个整型数。

由于NEXT_GTID是可以直接指定的，因此若故意构造，可以很容易得到不是递增的情况，这里只讨论automatic模式下的有序性。

与上述两种情况不同，gno生成于事务提交时写binlog的时候。注意这里不是生成binlog，而是将binlog写入磁盘的时候。因此实现上确保了同一个UUID下gno的有序性。

小结

一个binlog文件中的Xid和TIMESTAMP无法保证有序性。在无特殊操作的情况下，相同的UUID可以保证gno的有序性。

 

insert语句包含的事件：

执行语句​​INSERT INTO​​​这个语句会产生​​一个：​​

​​GTID：如果开启了gtid的话，就会有该gtid事件。​​

​​Query：Query事件包含了事务开始的BEGIN关键字。​​

​​Rows_query：就是咱们发给数据库的sql语句。​
​​

Table_map：描述了库表名称与表空间id的映射关系。

Write_rows：插入的具体数据，包含了表的每个字段的具体值：

Xid：包含了事务提交的COMMIT关键字。


# at 11065
#201120 15:43:50 server id 176441356  end_log_pos 11126         GTID    last_committed=54       sequence_number=55      rbr_only=yes
/*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
SET @@SESSION.GTID_NEXT= 'eddc13e2-1e6d-11eb-9b37-246e9691b550:276606'/*!*/;


# at 11126
#201120 15:43:50 server id 176441356  end_log_pos 11195         Query   thread_id=392954        exec_time=0     error_code=0
SET TIMESTAMP=1605858230/*!*/;
/*!\C utf8mb4 *//*!*/;
SET @@session.character_set_client=45,@@session.collation_connection=45,@@session.collation_server=45/*!*/;
BEGIN
/*!*/;


# at 11195
#201120 15:43:50 server id 176441356  end_log_pos 11258         Rows_query
# insert into order_ipad values  (null,'glc')


# at 11258
#201120 15:43:50 server id 176441356  end_log_pos 11311         Table_map: `apple`.`order_ipad` mapped to number 152


# at 11311
#201120 15:43:50 server id 176441356  end_log_pos 11356         Write_rows: table id 152 flags: STMT_END_F
### INSERT INTO `apple`.`order_ipad`
### SET
###   @1=1 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='glc' /* VARSTRING(256) meta=256 nullable=0 is_null=0 */


# at 11356
#201120 15:43:50 server id 176441356  end_log_pos 11383         Xid = 15030314
COMMIT/*!*/;

 

 

update事件：

# at 12565
#201120 15:44:22 server id 176441356  end_log_pos 12626         GTID    last_committed=61       sequence_number=62      rbr_only=yes
/*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
SET @@SESSION.GTID_NEXT= 'eddc13e2-1e6d-11eb-9b37-246e9691b550:276613'/*!*/;


# at 12626
#201120 15:44:22 server id 176441356  end_log_pos 12695         Query   thread_id=392954        exec_time=0     error_code=0
SET TIMESTAMP=1605858262/*!*/;
/*!\C utf8mb4 *//*!*/;
SET @@session.character_set_client=45,@@session.collation_connection=45,@@session.collation_server=45/*!*/;
BEGIN
/*!*/;


# at 12695
#201120 15:44:22 server id 176441356  end_log_pos 12754         Rows_query
# update order_ipad set name='guolicheng'


# at 12754
#201120 15:44:22 server id 176441356  end_log_pos 12807         Table_map: `apple`.`order_ipad` mapped to number 152


# at 12807
#201120 15:44:22 server id 176441356  end_log_pos 12874         Update_rows: table id 152 flags: STMT_END_F
### UPDATE `apple`.`order_ipad`
### WHERE
###   @1=1 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='glc' /* VARSTRING(256) meta=256 nullable=0 is_null=0 */
### SET
###   @1=1 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='guolicheng' /* VARSTRING(256) meta=256 nullable=0 is_null=0 */


# at 12874
#201120 15:44:22 server id 176441356  end_log_pos 12901         Xid = 15030687
COMMIT/*!*/;

 

 

 delete事件：

# at 13886
#201120 15:44:47 server id 176441356  end_log_pos 13947         GTID    last_committed=67       sequence_number=68      rbr_only=yes
/*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
SET @@SESSION.GTID_NEXT= 'eddc13e2-1e6d-11eb-9b37-246e9691b550:276619'/*!*/;


# at 13947
#201120 15:44:47 server id 176441356  end_log_pos 14016         Query   thread_id=392954        exec_time=0     error_code=0
SET TIMESTAMP=1605858287/*!*/;
/*!\C utf8mb4 *//*!*/;
SET @@session.character_set_client=45,@@session.collation_connection=45,@@session.collation_server=45/*!*/;
BEGIN
/*!*/;


# at 14016
#201120 15:44:47 server id 176441356  end_log_pos 14072         Rows_query
# delete from order_ipad  where id = 1


# at 14072
#201120 15:44:47 server id 176441356  end_log_pos 14125         Table_map: `apple`.`order_ipad` mapped to number 152


# at 14125
#201120 15:44:47 server id 176441356  end_log_pos 14177         Delete_rows: table id 152 flags: STMT_END_F
### DELETE FROM `apple`.`order_ipad`
### WHERE
###   @1=1 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='guolicheng' /* VARSTRING(256) meta=256 nullable=0 is_null=0 */


# at 14177
#201120 15:44:47 server id 176441356  end_log_pos 14204         Xid = 15030940
COMMIT/*!*/;

 

 

 

 

 

 

 

 

 事件类型：

事件字节序列具有标题部分和数据部分。类型代码出现在每个事件的标题部分。

Log_event_type枚举 中列出了事件的可能类型代码 ：


enum Log_event_type { 
  UNKNOWN_EVENT= 0, 
  START_EVENT_V3= 1, 
  QUERY_EVENT= 2, 
  STOP_EVENT= 3, 
  ROTATE_EVENT= 4, 
  INTVAR_EVENT= 5, 
  LOAD_EVENT= 6, 
  SLAVE_EVENT= 7, 
  CREATE_FILE_EVENT= 8, 
  APPEND_BLOCK_EVENT= 9, 
  EXEC_LOAD_EVENT= 10, 
  DELETE_FILE_EVENT= 11, 
  NEW_LOAD_EVENT= 12, 
  RAND_EVENT= 13, 
  USER_VAR_EVENT= 14, 
  FORMAT_DESCRIPTION_EVENT= 15, 
  XID_EVENT= 16, 
  BEGIN_LOAD_QUERY_EVENT= 17, 
  EXECUTE_LOAD_QUERY_EVENT= 18, 
  TABLE_MAP_EVENT = 19, 
  PRE_GA_WRITE_ROWS_EVENT = 20, 
  PRE_GA_UPDATE_ROWS_EVENT = 21, 
  PRE_GA_DELETE_ROWS_EVENT = 22, 
  WRITE_ROWS_EVENT = 23, 
  UPDATE_ROWS_EVENT = 24, 
  DELETE_ROWS_EVENT = 25, 
  INCIDENT_EVENT= 26, 
  HEARTBEAT_LOG_EVENT= 27, 
  IGNORABLE_LOG_EVENT= 28,
  ROWS_QUERY_LOG_EVENT= 29,
  WRITE_ROWS_EVENT = 30,
  UPDATE_ROWS_EVENT = 31,
  DELETE_ROWS_EVENT = 32,
  GTID_LOG_EVENT= 33,
  ANONYMOUS_GTID_LOG_EVENT= 34,
  PREVIOUS_GTIDS_LOG_EVENT= 35, 
  ENUM_END_EVENT 
  /* end marker */ 
};
该INTVAR_EVENT类型具有Int_event_type枚举中列出的“子类型” ：

 

 

 

 

 

 

 

 

 

 

 

 

show binlog events in 'mysql-bin.000011'  limit 3330000,10000; 

binlog_mysql_02

 ################################################

背景

MySQL 非 GTID 协议主备同步原理： 主库在执行 SQL 语句时产生binlog，在事务 commit 时将产生的binlog event写入binlog文件，备库IO线程通过 ​​com_binlog_dump​​ 用文件位置协议从主库拉取 binlog，将拉取的binlog存储到relaylog, SQL线程读取 relaylog 然后进行 apply，实现主备同步，在这个过程中有以下几个问题：

  1. 主库什么时间将产生的 binlog 真正刷到文件中？
  2. 备库IO线程从哪个位置读取主库的 binlog event 的？
  3. 备库SQL线程如何记录执行到的 relaylog 的位点？
  4. 备库IO线程何时将cache中的event 刷到relay log 文件中的?

问题分析

下面对这几个问题挨个解答。

问题 1: 主库什么时间将产生的binlog 真正刷到文件中

事务​​ordered_commit​​​ 中，会将 ​​thd->cache_mngr​​ 中的 binlog cache 写入到 binlog 文件中，但并没有执行fsync()操作，即只将文件内容写入到 OS 缓存中，详细 bt 为：

#0  my_write
#1  0x0000000000a92f50 in inline_mysql_file_write
#2  0x0000000000a9612e in my_b_flush_io_cache
#3  0x0000000000a43466 in MYSQL_BIN_LOG::flush_cache_to_file
#4  0x0000000000a43a4d in MYSQL_BIN_LOG::ordered_commit
#5  0x0000000000a429f2 in MYSQL_BIN_LOG::commit
#6  0x000000000063d3e2 in ha_commit_trans
#7  0x00000000008adb7a in trans_commit_stmt
#8  0x00000000007e511f in mysql_execute_command
#9  0x00000000007e7e0e in mysql_parse
#10 0x00000000007dae0e in dispatch_command
#11 0x00000000007d9634 in do_command
#12 0x00000000007a046d in do_handle_one_connection
#13 0x000000000079ff75 in handle_one_connection
#14 0x0000003a00a07851 in start_thread ()
#15 0x0000003a006e767d in clone ()

commit 时，会判断是否将产生的 binlog flush 到文件中，即执行 fsync操作，详细bt 为：

#0  MYSQL_BIN_LOG::sync_binlog_file
#1  0x0000000000a43c62 in MYSQL_BIN_LOG::ordered_commit
#2  0x0000000000a429f2 in MYSQL_BIN_LOG::commit
#3  0x000000000063d3e2 in ha_commit_trans
#4  0x00000000008adb7a in trans_commit_stmt
#5  0x00000000007e511f in mysql_execute_command
#6  0x00000000007e7e0e in mysql_parse
#7  0x00000000007dae0e in dispatch_command
#8  0x00000000007d9634 in do_command (thd=0x37a40160)
#9  0x00000000007a046d in do_handle_one_connection
#10 0x000000000079ff75 in handle_one_connection
#11 0x0000003a00a07851 in start_thread ()
#12 0x0000003a006e767d in clone ()

由 ​​MYSQL_BIN_LOG::sync_binlog_file​​ 可以看出，每提交一个事务，会 fsync 一次binlog file。 当 sync_binlog != 1 的时候，每次事务提交的时候，不一定会执行 fsync 操作，binlog 的内容只是缓存在了 OS（是否会执行fsync操作，取决于OS缓存的大小），此时备库可以读到主库产生的 binlog, 在这种情况下，当主库机器挂掉时，有以下两种情况：

  1. 主备同步无延迟，此时主库机器恢复后，备库接着之前的位点重新拉binlog, 但是主库由于没有fsync最后的binlog，所以会返回1236 的错误： ​​MySQL error code 1236 (ER_MASTER_FATAL_ERROR_READING_BINLOG): Got fatal error %d from master when reading data from binary log: '%-.256s'​​
  2. 备库没有读到主库失去的binlog，此时备库无法同步主库最后的更新，备库不可用。

问题 2: 备库IO线程从哪个位置读取主库的binlog event 的

更新位点信息的 bt 如下：

#0  Rpl_info_table::do_flush_info (this=0x379cbf90, force=false)
#1  0x0000000000a78270 in Rpl_info_handler::flush_info
#2  0x0000000000a773b9 in Master_info::flush_info
#3  0x0000000000a5da4b in flush_master_info
#4  0x0000000000a697eb in handle_slave_io
#5  0x0000003a00a07851 in start_thread () from /lib64/libpthread.so.0
#6  0x0000003a006e767d in clone () from /lib64/libc.so.6

备库通过 ​​master_log_info​​​ 来记录主库的相关信息，通过参数 ​​sync_master_info​​​ 来设置备库经过多少个 binlog event 来更新已经读取到的位点信息。当stop slave时，会把正常的位点更新到​​master_log_info​​中，此时，如果最后的位点不是commit，则在start slave后，会继续上一位点拉取 binlog，从而造成同一个事务的binlog event分布在不同的binlog file中，此时如果执行顺利则不会有问题；如果在拉这个事务的过程中，sql 线程出错中断，在并行复制下会引起分发线程停在事务中间，再次启动的时候，会从上一次分发的事务继续分发，会造成在并行复制中不可分发的情况，因此需要注意。

当 sync_master_info > 1000时，可能在第1000个binlog 拉取的时候机器出问题，此时重启后会从主库多拉999个 binlog event，造成事务在备库多次执行问题，对于没有 primary key, unique key 可能会有问题，造成主备数据不一致，最常遇到的是1062问题。

问题3: 备库SQL线程如何记录执行到的relaylog 的位点

同问题2一样，相关的 bt 也类似，​​relay_log_info​​​ 记录的是备库已经执行了的最后的位点，这个位点不会处于事务中间，即是每 ​​sync_relay_log_info​​ 个事务更新一下这个位点。

相关​​bugs​​ bug 原因: 备库异常 crash 后，可能造成事务在拉取过程中被重新拉取，binlog序列如下：

begin;
table_map;
begin;
table_map;
rows_log_event;
commit;

在并行复制条件下，由于出现了不完整的事务，所以会造成绑定事务信息无法恢复，造成hang的情况，详情见 ​​bug 分析​​。

问题 4: 备库IO线程何时将cache中的event 刷到relay log 文件中的

这个问题的解答和问题1类似，也是以binlog event为单位的，当然也存在着和问题1中同样的问题，在此不在赘述。

结语

MySQL 通过 ​​sync_binlog​​​，​​sync_master_info​​​，​​sync_relay_log_info​​​，​​sync_relay_log​​ 来记录相关的位点信息，出于性能考虑以及程序本身的健壮性，引入了各式要样的bug，类似的bug在此不在列举，那么有没有更好的方法来记录这些信息呢，当然有，即GTID 协议，会在下期月报分析。

################################################

 

# at 179666727
#191123 20:19:52 server id 3232266753  end_log_pos 179666788    GTID    last_committed=557619   sequence_number=557620  rbr_only=no
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662575'/*!*/;
# at 179666788
#191123 20:19:52 server id 3232266753  end_log_pos 179666957    Query   thread_id=557624        exec_time=0     error_code=0
use `google`/*!*/;
SET TIMESTAMP=1574511592/*!*/;
create table tb_person(id bigint(20) not null auto_increment,name varchar(255),primary key(id))
/*!*/;
# at 179666957
#191123 20:23:50 server id 3232266753  end_log_pos 179667018    GTID    last_committed=557620   sequence_number=557621  rbr_only=no
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662576'/*!*/;
# at 179667018
#191123 20:23:50 server id 3232266753  end_log_pos 179667185    Query   thread_id=557624        exec_time=0     error_code=0
SET TIMESTAMP=1574511830/*!*/;
create table tb_host(id bigint(20) not null auto_increment,name varchar(255),primary key(id))
/*!*/;
# at 179667185
#191123 20:43:26 server id 3232266753  end_log_pos 179667246    GTID    last_committed=557621   sequence_number=557622  rbr_only=yes
/*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662577'/*!*/;
# at 179667246
#191123 20:43:26 server id 3232266753  end_log_pos 179667316    Query   thread_id=557624        exec_time=0     error_code=0
SET TIMESTAMP=1574513006/*!*/;
BEGIN
/*!*/;
# at 179667316
#191123 20:43:26 server id 3232266753  end_log_pos 179667373    Rows_query
# insert into tb_host(name) values('5')
# at 179667373
#191123 20:43:26 server id 3232266753  end_log_pos 179667424    Table_map: `google`.`tb_host` mapped to number 109
# at 179667424
#191123 20:43:26 server id 3232266753  end_log_pos 179667467    Write_rows: table id 109 flags: STMT_END_F
### INSERT INTO `google`.`tb_host`
### SET
###   @1=1 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='5' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
# at 179667467
#191123 20:43:26 server id 3232266753  end_log_pos 179667494    Xid = 2788131
COMMIT/*!*/;
# at 179667494
#191123 20:52:02 server id 3232266753  end_log_pos 179667555    GTID    last_committed=557622   sequence_number=557623  rbr_only=yes
/*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662578'/*!*/;
# at 179667555
#191123 20:52:02 server id 3232266753  end_log_pos 179667625    Query   thread_id=557624        exec_time=0     error_code=0
SET TIMESTAMP=1574513522/*!*/;
BEGIN
/*!*/;
# at 179667625
#191123 20:52:02 server id 3232266753  end_log_pos 179667684    Rows_query
# update tb_host set name='10' where id=1
# at 179667684
#191123 20:52:02 server id 3232266753  end_log_pos 179667735    Table_map: `google`.`tb_host` mapped to number 109
# at 179667735
#191123 20:52:02 server id 3232266753  end_log_pos 179667792    Update_rows: table id 109 flags: STMT_END_F
### UPDATE `google`.`tb_host`
### WHERE
###   @1=1 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='5' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
### SET
###   @1=1 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='10' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
# at 179667792
#191123 20:52:02 server id 3232266753  end_log_pos 179667819    Xid = 2788133
COMMIT/*!*/;
# at 179667819
#191123 21:01:24 server id 3232266753  end_log_pos 179667880    GTID    last_committed=557623   sequence_number=557624  rbr_only=yes
/*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662579'/*!*/;
# at 179667880
#191123 21:01:24 server id 3232266753  end_log_pos 179667950    Query   thread_id=557624        exec_time=0     error_code=0
SET TIMESTAMP=1574514084/*!*/;
BEGIN
/*!*/;
# at 179667950
#191123 21:01:24 server id 3232266753  end_log_pos 179668000    Rows_query
# delete from tb_host where id=1
# at 179668000
#191123 21:01:24 server id 3232266753  end_log_pos 179668051    Table_map: `google`.`tb_host` mapped to number 109
# at 179668051
#191123 21:01:24 server id 3232266753  end_log_pos 179668095    Delete_rows: table id 109 flags: STMT_END_F
### DELETE FROM `google`.`tb_host`
### WHERE
###   @1=1 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='10' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
# at 179668095
#191123 21:01:24 server id 3232266753  end_log_pos 179668122    Xid = 2788134
COMMIT/*!*/;
# at 179668122
#191123 21:11:42 server id 3232266753  end_log_pos 179668183    GTID    last_committed=557624   sequence_number=557625  rbr_only=no
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662580'/*!*/;
# at 179668183
#191123 21:11:42 server id 3232266753  end_log_pos 179668303    Query   thread_id=557624        exec_time=0     error_code=0
SET TIMESTAMP=1574514702/*!*/;
SET @@session.pseudo_thread_id=557624/*!*/;
DROP TABLE `tb_host` /* generated by server */
/*!*/;
# at 179668303
#191123 21:19:09 server id 3232266753  end_log_pos 179668364    GTID    last_committed=557625   sequence_number=557626  rbr_only=no
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662581'/*!*/;
# at 179668364
#191123 21:19:09 server id 3232266753  end_log_pos 179668493    Query   thread_id=557624        exec_time=0     error_code=0
SET TIMESTAMP=1574515149/*!*/;
alter table tb_person add column age smallint default 0
/*!*/;
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*/;
####################################################
####################################################
####################################################
MySQL的二进制日志binlog可以说是MySQL最重要的日志，它记录了所有的DDL和DML语句（除了数据查询语句select），以事件形式记录，还包含语句所执行的消耗的时间，MySQL的二进制日志是事务安全型的。

MySQL binlog记录的所有操作实际上都有对应的事件类型的，譬如STATEMENT格式中的DML操作对应的是QUERY_EVENT类型，ROW格式下的DML操作对应的是ROWS_EVENT类型。
一、对于ROW格式的binlog，所有的DML语句都是记录在ROWS_EVENT中。
ROWS_EVENT分为三种：WRITE_ROWS_EVENT，UPDATE_ROWS_EVENT，DELETE_ROWS_EVENT，分别对应insert，update和delete操作。
1、对于insert操作，WRITE_ROWS_EVENT包含了要插入的数据
2、对于update操作，UPDATE_ROWS_EVENT不仅包含了修改后的数据，还包含了修改前的值。
3、对于delete操作，仅仅需要指定删除的主键（在没有主键的情况下，会给定所有列）
二、对于QUERY_EVENT事件，是以文本形式记录DML操作的。而对于ROWS_EVENT事件，并不是文本形式，所以在通过mysqlbinlog查看基于ROW格式的binlog时，需要指定-vv --base64-output=decode-rows。

mysqlbinlog常见的选项有以下几个：
--start-datetime：从二进制日志中读取指定等于时间戳或者晚于本地计算机的时间
--stop-datetime：从二进制日志中读取指定小于时间戳或者等于本地计算机的时间 取值和上述一样
--start-position：从二进制日志中读取指定position 事件位置作为开始。
--stop-position：从二进制日志中读取指定position 事件位置作为事件截至


########################################################################################################################################
##################日志文件开头：
Previous-GTIDs：ceabbacf-0c77-11ea-b49f-2016d8c96b46:1-1662585 # 表示前一个binlog中gtid值已经执行这个位置了。

# binlog版本，mysql服务器版本，binlog文件创建时间


/*!50530 SET @@SESSION.PSEUDO_SLAVE_MODE=1*/;
/*!50003 SET @OLD_COMPLETION_TYPE=@@COMPLETION_TYPE,COMPLETION_TYPE=0*/;
DELIMITER /*!*/;
# at 4
#191123 23:21:49 server id 3232266753  end_log_pos 123  Start: binlog v 4, server v 5.7.27-log created 191123 23:21:49
# Warning: this binlog is either in use or was not closed properly.
# at 123
#191123 23:21:49 server id 3232266753  end_log_pos 190  Previous-GTIDs
# ceabbacf-0c77-11ea-b49f-2016d8c96b46:1-1662585

########################################################################################################################################
################下面是共同的结尾，是二进制日志结尾的标志
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*/;


######################################
########stop事件：
mysql服务停止时，会在当前的binlog日志添加一个stop事件,下次重启服务后会新开一个binlog日志，故当前的binlog日志必须给以标记：
at
stop # 表示stop事件

# at 356010155
#191123  4:03:12 server id 3232266753  end_log_pos 356010174    Stop
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*/;

#########################################
##############Rotate事件：
当binlog文件的大小达到max_binlog_size的值或者执行flush logs命令时，binlog会发生切换，这个时候会在当前的binlog日志添加一个ROTATE_EVENT事件，用于指定下一个日志的名称和位置：
at
Rotate to mysql-bin.000012  pos: 4 #表示下一个binlog日志的文件名称和位置

# at 179670297
#191123 23:21:49 server id 3232266753  end_log_pos 179670340    Rotate to mysql-bin.000012  pos: 4
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*/;

########################################################################################################################################
建表语句格式：以at开头，以“/*!*/;”结尾


# at 179666957
#191123 20:23:50 server id 3232266753  end_log_pos 179667018    GTID    last_committed=557620   sequence_number=557621  rbr_only=no
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662576'/*!*/;
# at 179667018
#191123 20:23:50 server id 3232266753  end_log_pos 179667185    Query   thread_id=557624        exec_time=0     error_code=0
SET TIMESTAMP=1574511830/*!*/;
create table tb_host(id bigint(20) not null auto_increment,name varchar(255),primary key(id))
/*!*/;




########################################################################################################################################
删除表语句格式：占用一个gtid_next
开头：at
结尾：/*!*/

# at 179668122
#191123 21:11:42 server id 3232266753  end_log_pos 179668183    GTID    last_committed=557624   sequence_number=557625  rbr_only=no
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662580'/*!*/;
# at 179668183
#191123 21:11:42 server id 3232266753  end_log_pos 179668303    Query   thread_id=557624        exec_time=0     error_code=0
SET TIMESTAMP=1574514702/*!*/;
SET @@session.pseudo_thread_id=557624/*!*/;
DROP TABLE `tb_host` /* generated by server */
/*!*/;



########################################################################################################################################
修改表结构语句格式：占用一个gtid_next
开头：at
结尾：/*!*/

# at 179668303
#191123 21:19:09 server id 3232266753  end_log_pos 179668364    GTID    last_committed=557625   sequence_number=557626  rbr_only=no
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662581'/*!*/;
# at 179668364
#191123 21:19:09 server id 3232266753  end_log_pos 179668493    Query   thread_id=557624        exec_time=0     error_code=0
SET TIMESTAMP=1574515149/*!*/;
alter table tb_person add column age smallint default 0
/*!*/;




########################################################################################################################################
insert语句：Write_rows类型，占用一个gtid_next
事件头以at开头，
事件体开头：
“SET TIMESTAMP=1574513006/*!*/;
BEGIN
/*!*/;” 
# 中间是事件的具体内容
事件体结尾：“COMMIT/*!*/;”


# at 179667185
#191123 20:43:26 server id 3232266753  end_log_pos 179667246    GTID    last_committed=557621   sequence_number=557622  rbr_only=yes
/*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662577'/*!*/;
# at 179667246
#191123 20:43:26 server id 3232266753  end_log_pos 179667316    Query   thread_id=557624        exec_time=0     error_code=0
SET TIMESTAMP=1574513006/*!*/;
BEGIN
/*!*/;
# at 179667316
#191123 20:43:26 server id 3232266753  end_log_pos 179667373    Rows_query
# insert into tb_host(name) values('5')
# at 179667373
#191123 20:43:26 server id 3232266753  end_log_pos 179667424    Table_map: `google`.`tb_host` mapped to number 109
# at 179667424
#191123 20:43:26 server id 3232266753  end_log_pos 179667467    Write_rows: table id 109 flags: STMT_END_F
### INSERT INTO `google`.`tb_host`
### SET
###   @1=1 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='5' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
# at 179667467
#191123 20:43:26 server id 3232266753  end_log_pos 179667494    Xid = 2788131
COMMIT/*!*/;

############################################################
测试一次插入多行：
发现：Write_rows这个内容与插入的数据量成正比。多个insert set语句

# at 179668493
#191123 22:05:11 server id 3232266753  end_log_pos 179668554    GTID    last_committed=557626   sequence_number=557627  rbr_only=yes
/*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662582'/*!*/;
# at 179668554
#191123 22:05:11 server id 3232266753  end_log_pos 179668624    Query   thread_id=557625        exec_time=0     error_code=0
SET TIMESTAMP=1574517911/*!*/;
BEGIN
/*!*/;
# at 179668624
#191123 22:05:11 server id 3232266753  end_log_pos 179668708    Rows_query
# insert into tb_person (name,age) values('1',1),('5',5),('10',10)
# at 179668708
#191123 22:05:11 server id 3232266753  end_log_pos 179668762    Table_map: `google`.`tb_person` mapped to number 111
# at 179668762
#191123 22:05:11 server id 3232266753  end_log_pos 179668836    Write_rows: table id 111 flags: STMT_END_F
### INSERT INTO `google`.`tb_person`
### SET
###   @1=1 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='1' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=1 /* SHORTINT meta=0 nullable=1 is_null=0 */
### INSERT INTO `google`.`tb_person`
### SET
###   @1=2 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='5' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=5 /* SHORTINT meta=0 nullable=1 is_null=0 */
### INSERT INTO `google`.`tb_person`
### SET
###   @1=3 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='10' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=10 /* SHORTINT meta=0 nullable=1 is_null=0 */
# at 179668836
#191123 22:05:11 server id 3232266753  end_log_pos 179668863    Xid = 2788147
COMMIT/*!*/;


########################################################################################################################################
更新事务： Update_rows类型，占用一个gtid_next
事件头以at开头，
事件体的开头：
SET TIMESTAMP=1574513522/*!*/;
BEGIN
/*!*/;
# 中间是事件的内容
事件体的结尾：
COMMIT/*!*/;


# at 179667494
#191123 20:52:02 server id 3232266753  end_log_pos 179667555    GTID    last_committed=557622   sequence_number=557623  rbr_only=yes
/*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662578'/*!*/;
# at 179667555
#191123 20:52:02 server id 3232266753  end_log_pos 179667625    Query   thread_id=557624        exec_time=0     error_code=0
SET TIMESTAMP=1574513522/*!*/;
BEGIN
/*!*/;
# at 179667625
#191123 20:52:02 server id 3232266753  end_log_pos 179667684    Rows_query
# update tb_host set name='10' where id=1
# at 179667684
#191123 20:52:02 server id 3232266753  end_log_pos 179667735    Table_map: `google`.`tb_host` mapped to number 109
# at 179667735
#191123 20:52:02 server id 3232266753  end_log_pos 179667792    Update_rows: table id 109 flags: STMT_END_F
### UPDATE `google`.`tb_host`
### WHERE
###   @1=1 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='5' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
### SET
###   @1=1 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='10' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
# at 179667792
#191123 20:52:02 server id 3232266753  end_log_pos 179667819    Xid = 2788133
COMMIT/*!*/;

############################################################
一次更新多条记录：Update_rows这个内容与插入的数据量成正比。多个update where set语句


# at 179668863
#191123 22:17:06 server id 3232266753  end_log_pos 179668924    GTID    last_committed=557627   sequence_number=557628  rbr_only=yes
/*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662583'/*!*/;
# at 179668924
#191123 22:17:06 server id 3232266753  end_log_pos 179668994    Query   thread_id=557625        exec_time=0     error_code=0
SET TIMESTAMP=1574518626/*!*/;
BEGIN
/*!*/;
# at 179668994
#191123 22:17:06 server id 3232266753  end_log_pos 179669052    Rows_query
# update tb_person set name='glc',age=18
# at 179669052
#191123 22:17:06 server id 3232266753  end_log_pos 179669106    Table_map: `google`.`tb_person` mapped to number 111
# at 179669106
#191123 22:17:06 server id 3232266753  end_log_pos 179669229    Update_rows: table id 111 flags: STMT_END_F
### UPDATE `google`.`tb_person`
### WHERE
###   @1=1 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='1' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=1 /* SHORTINT meta=0 nullable=1 is_null=0 */
### SET
###   @1=1 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='glc' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=18 /* SHORTINT meta=0 nullable=1 is_null=0 */
### UPDATE `google`.`tb_person`
### WHERE
###   @1=2 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='5' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=5 /* SHORTINT meta=0 nullable=1 is_null=0 */
### SET
###   @1=2 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='glc' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=18 /* SHORTINT meta=0 nullable=1 is_null=0 */
### UPDATE `google`.`tb_person`
### WHERE
###   @1=3 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='10' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=10 /* SHORTINT meta=0 nullable=1 is_null=0 */
### SET
###   @1=3 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='glc' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=18 /* SHORTINT meta=0 nullable=1 is_null=0 */
# at 179669229
#191123 22:17:06 server id 3232266753  end_log_pos 179669256    Xid = 2788148
COMMIT/*!*/;





########################################################################################################################################
删除事务：Delete_rows类型，占用一个gtid_next
事件头以at开头，
事件体开头：
“SET TIMESTAMP=1574513006/*!*/; #该时间戳就是"2019-11-23 21:01:24"的含义。表达了事件发生时间。
BEGIN
/*!*/;” 
# 中间是事件的具体内容:thread_id、Rows_query、Table_map、Delete_rows、Xid
# 即线程id、sql语句、操作的表对象、sql类型、事务结束标志Xid
事件体结尾：“COMMIT/*!*/;”




# at 179667819
#191123 21:01:24 server id 3232266753  end_log_pos 179667880    GTID    last_committed=557623   sequence_number=557624  rbr_only=yes
/*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662579'/*!*/;
# at 179667880
#191123 21:01:24 server id 3232266753  end_log_pos 179667950    Query   thread_id=557624        exec_time=0     error_code=0
SET TIMESTAMP=1574514084/*!*/;
BEGIN
/*!*/;
# at 179667950
#191123 21:01:24 server id 3232266753  end_log_pos 179668000    Rows_query
# delete from tb_host where id=1
# at 179668000
#191123 21:01:24 server id 3232266753  end_log_pos 179668051    Table_map: `google`.`tb_host` mapped to number 109
# at 179668051
#191123 21:01:24 server id 3232266753  end_log_pos 179668095    Delete_rows: table id 109 flags: STMT_END_F
### DELETE FROM `google`.`tb_host`
### WHERE
###   @1=1 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='10' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
# at 179668095
#191123 21:01:24 server id 3232266753  end_log_pos 179668122    Xid = 2788134
COMMIT/*!*/;

########################################################################################
一次删除多条记录：Update_rows这个内容与插入的数据量成正比。多个delete from where语句

# at 179669256
#191123 22:22:54 server id 3232266753  end_log_pos 179669317    GTID    last_committed=557628   sequence_number=557629  rbr_only=yes
/*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662584'/*!*/;
# at 179669317
#191123 22:22:54 server id 3232266753  end_log_pos 179669387    Query   thread_id=557625        exec_time=0     error_code=0
SET TIMESTAMP=1574518974/*!*/;
BEGIN
/*!*/;
# at 179669387
#191123 22:22:54 server id 3232266753  end_log_pos 179669429    Rows_query
# delete from  tb_person
# at 179669429
#191123 22:22:54 server id 3232266753  end_log_pos 179669483    Table_map: `google`.`tb_person` mapped to number 111
# at 179669483
#191123 22:22:54 server id 3232266753  end_log_pos 179669562    Delete_rows: table id 111 flags: STMT_END_F
### DELETE FROM `google`.`tb_person`
### WHERE
###   @1=1 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='glc' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=18 /* SHORTINT meta=0 nullable=1 is_null=0 */
### DELETE FROM `google`.`tb_person`
### WHERE
###   @1=2 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='glc' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=18 /* SHORTINT meta=0 nullable=1 is_null=0 */
### DELETE FROM `google`.`tb_person`
### WHERE
###   @1=3 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='glc' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=18 /* SHORTINT meta=0 nullable=1 is_null=0 */
# at 179669562
#191123 22:22:54 server id 3232266753  end_log_pos 179669589    Xid = 2788149
COMMIT/*!*/;


#######################################################################
###################################
删除数据库
at
GTID
at 251 # drop开始位置为251
end_log_pos 345  Query # drop结束位置是345
第一：GTID事件
第二：Query事件，在这里就是具体的drop事件




# at 190
#191124  0:17:14 server id 3232266753  end_log_pos 251  GTID    last_committed=0        sequence_number=1       rbr_only=no
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662586'/*!*/;
# at 251
#191124  0:17:14 server id 3232266753  end_log_pos 345  Query   thread_id=557625        exec_time=0     error_code=0
SET TIMESTAMP=1574525834/*!*/;
SET @@session.pseudo_thread_id=557625/*!*/;
SET @@session.foreign_key_checks=1, @@session.sql_auto_is_null=0, @@session.unique_checks=1, @@session.autocommit=1/*!*/;
SET @@session.sql_mode=1436549120/*!*/;
SET @@session.auto_increment_increment=1, @@session.auto_increment_offset=1/*!*/;
/*!\C utf8 *//*!*/;
SET @@session.character_set_client=33,@@session.collation_connection=33,@@session.collation_server=45/*!*/;
SET @@session.lc_time_names=0/*!*/;
SET @@session.collation_database=DEFAULT/*!*/;
drop database google
/*!*/;


#############################################
先插入三条数据，再修改一条数据，再删除一条数据
at  # GTID事件。设置事务隔离级别和GTID_NEXT
GTID
SET TRANSACTION ISOLATION LEVEL
SET @@SESSION.GTID_NEXT
at # Query事件。报告服务端的线程thread_id和事务开始标志BEGIN
Query
at # Rows_query事件。报告表面执行语句
Rows_query
at # Table_map事件。报告操作的表
Table_map
at # Write_rows/Update_rows/Delete_rows事件。报告实际执行语句的具体内容
Write_rows/Update_rows/Delete_rows
at # Xid事件。
Xid # 报告事务结束，每个事务都有唯一的Xid和GTID,在事务提交时，不管是STATEMENT还是ROW格式的binlog，都会在末尾添加一个XID_EVENT事件代表事务的结束。该事件记录了该事务的ID，在MySQL进行崩溃恢复时，根据事务在binlog中的提交情况来决定是否提交存储引擎中状态为prepared的事务。
--xid行给的时间减去最开始的GTID行给的时间就是事务时间。
--xid行给的end_log_pos表示该事务结束的pos位置点。

Xid事件分析：
# at 179670270
#191123 22:29:07 server id 3232266753  end_log_pos 179670297    Xid = 2788151
COMMIT/*!*/;
第一：Xid = 2788151 #表示这是一个事务结束标志，其事务id为2788151，第2788151个事务。
第二：191123 22:29:07 # 表示该事务的结束时间为2019-11-23 22:29:07
第三：at 179670270 # 表示Xid事件的开始位置是179670270
第四：end_log_pos 179670297 # 表示Xid事件的截止位置为179670297
第五：COMMIT/*!*/; # 表示这是一个dml事务类型的commit结束标志。若为/*!*/; 表示ddl语句


事务语句：Rows_query+Table_map+Write_rows/Update_rows/Delete_rows组成，其余部分相同
事务结束标志：Xid

# at 179669589
#191123 22:29:07 server id 3232266753  end_log_pos 179669650    GTID    last_committed=557626   sequence_number=557630  rbr_only=yes
/*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;
SET @@SESSION.GTID_NEXT= 'ceabbacf-0c77-11ea-b49f-2016d8c96b46:1662585'/*!*/;
# at 179669650
#191123 22:27:59 server id 3232266753  end_log_pos 179669720    Query   thread_id=557625        exec_time=0     error_code=0
SET TIMESTAMP=1574519279/*!*/;
BEGIN
/*!*/;
# at 179669720
#191123 22:27:59 server id 3232266753  end_log_pos 179669804    Rows_query
# insert into tb_person (name,age) values('1',1),('5',5),('10',10)
# at 179669804
#191123 22:27:59 server id 3232266753  end_log_pos 179669858    Table_map: `google`.`tb_person` mapped to number 111
# at 179669858
#191123 22:27:59 server id 3232266753  end_log_pos 179669932    Write_rows: table id 111 flags: STMT_END_F
### INSERT INTO `google`.`tb_person`
### SET
###   @1=4 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='1' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=1 /* SHORTINT meta=0 nullable=1 is_null=0 */
### INSERT INTO `google`.`tb_person`
### SET
###   @1=5 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='5' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=5 /* SHORTINT meta=0 nullable=1 is_null=0 */
### INSERT INTO `google`.`tb_person`
### SET
###   @1=6 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='10' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=10 /* SHORTINT meta=0 nullable=1 is_null=0 */
# at 179669932
#191123 22:28:32 server id 3232266753  end_log_pos 179670002    Rows_query
# update tb_person set name='glc',age=18 where age=1
# at 179670002
#191123 22:28:32 server id 3232266753  end_log_pos 179670056    Table_map: `google`.`tb_person` mapped to number 111
# at 179670056
#191123 22:28:32 server id 3232266753  end_log_pos 179670118    Update_rows: table id 111 flags: STMT_END_F
### UPDATE `google`.`tb_person`
### WHERE
###   @1=4 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='1' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=1 /* SHORTINT meta=0 nullable=1 is_null=0 */
### SET
###   @1=4 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='glc' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=18 /* SHORTINT meta=0 nullable=1 is_null=0 */
# at 179670118
#191123 22:29:01 server id 3232266753  end_log_pos 179670171    Rows_query
# delete from tb_person where age=5
# at 179670171
#191123 22:29:01 server id 3232266753  end_log_pos 179670225    Table_map: `google`.`tb_person` mapped to number 111
# at 179670225
#191123 22:29:01 server id 3232266753  end_log_pos 179670270    Delete_rows: table id 111 flags: STMT_END_F
### DELETE FROM `google`.`tb_person`
### WHERE
###   @1=5 /* LONGINT meta=0 nullable=0 is_null=0 */
###   @2='5' /* VARSTRING(1020) meta=1020 nullable=1 is_null=0 */
###   @3=5 /* SHORTINT meta=0 nullable=1 is_null=0 */
# at 179670270
#191123 22:29:07 server id 3232266753  end_log_pos 179670297    Xid = 2788151
COMMIT/*!*/;


#############################################################
####################
查看binlog日志

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


show binlog events in 'mysql-bin.000011'  limit 3330000,10000;




################################################################
                            恢复数据
################################################################



从binlog日志恢复数据
恢复命令的语法格式：
mysqlbinlog mysql-bin.0000xx | mysql -u用户名 -p密码 数据库名

--------------------------------------------------------
常用参数选项解释：
--start-position=875 起始pos点
--stop-position=954 结束pos点
--start-datetime="2016-9-25 22:01:08" 起始时间点
--stop-datetime="2019-9-25 22:09:46" 结束时间点
--database=zyyshop 指定只恢复zyyshop数据库(一台主机上往往有多个数据库，只限本地log日志)
-------------------------------------------------------- 
不常用选项： 
-u --user=name 连接到远程主机的用户名
-p --password[=name] 连接到远程主机的密码
-h --host=name 从远程主机上获取binlog日志
--read-from-remote-server 从某个MySQL服务器上读取binlog日志

 

 

 

 

 

 

 

 

dscsddccdcd