binlog
地址: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
)。 -
每个事件都包含头字节,后跟数据字节:
-
标头字节提供有关事件类型,事件的生成时间,服务器的类型等信息。
-
数据字节提供特定于事件类型的信息,例如特定的数据修改。
-
-
第一个事件是描述符事件,它描述文件的格式版本(用于在文件中写入事件的格式)。
-
其余事件将根据版本进行解释。
-
最后一个事件是一个日志旋转事件,它指定下一个二进制日志文件名。
-
-
索引文件是一个文本文件,列出了当前的二进制日志文件。
###################################################
对于解析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;
################################################
背景
MySQL 非 GTID 协议主备同步原理: 主库在执行 SQL 语句时产生binlog,在事务 commit 时将产生的binlog event写入binlog文件,备库IO线程通过 com_binlog_dump
用文件位置协议从主库拉取 binlog,将拉取的binlog存储到relaylog, SQL线程读取 relaylog 然后进行 apply,实现主备同步,在这个过程中有以下几个问题:
- 主库什么时间将产生的 binlog 真正刷到文件中?
- 备库IO线程从哪个位置读取主库的 binlog event 的?
- 备库SQL线程如何记录执行到的 relaylog 的位点?
- 备库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, 在这种情况下,当主库机器挂掉时,有以下两种情况:
- 主备同步无延迟,此时主库机器恢复后,备库接着之前的位点重新拉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'
- 备库没有读到主库失去的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