MySQL測試版本5.7.14

設置GTID_MODE=ON

ON(3): Both new and replicated transactions must be GTID transactions(生成的是GTID事物，slave也只能應用GTID事物)

設置binlog格式為row模式

做如下操作

mysql> insert into test values(1,2);

Query OK, 1 row affected (0.01 sec)

mysql> insert into test values(2,3);

Query OK, 1 row affected (0.01 sec)

mysql> delete from test;

Query OK, 2 rows affected (0.01 sec)

mysql> select * from test;

Empty set (0.00 sec)

首先我通過自己的工具infobin找到了這段操作的binlog，如果想獲得這個工具學習可以參考文章最后

簡單解釋一下這里? Pos:是當前位置對應mysqlbinlog的 # at 504這里的N_pos是結束位置對應

mysqlbinlog的 end_log_pos

>Gtid Event:Pos:504(0X1f8) N_pos:569(0X239) Time:1496993578 Event_size:65(bytes)

Gtid:89dfa8a4-cb13-11e6-b54-0c29a879a3:2

-->Query Event:Pos:569(0X239) N_Pos:641(0X281) Time:1496993578 Event_size:72(bytes)

Exe_time:0? Use_db:test Statment(35b-trun):BEGIN /*!Trx begin!*/ Gno:2

---->Map Event:Pos641(0X281) N_pos:689(0X2b1) Time:1496993578 Event_size:48(bytes)

TABLE_ID:142 DB_NAME:test TABLE_NAME:test Gno:2

------>Insert Event:Pos:689(0X2b1) N_pos:733(0X2dd) Time:1496993578 Event_size:44(bytes)

Dml on table: test.test? table_id:142 Gno:2

>Xid Event:Pos:733(0X2dd) N_Pos:764(0X2fc) Time:1496993578 Event_size:31(bytes)

COMMIT; /*!Trx end*/ Gno:2 --注意這里以N_Pos為結尾及下一個event的開始位置

>Gtid Event:Pos:764(0X2fc) N_pos:829(0X33d) Time:1496993581 Event_size:65(bytes)

Gtid:89dfa8a4-cb13-11e6-b54-0c29a879a3:3

-->Query Event:Pos:829(0X33d) N_Pos:901(0X385) Time:1496993581 Event_size:72(bytes)

Exe_time:0? Use_db:test Statment(35b-trun):BEGIN /*!Trx begin!*/ Gno:3

---->Map Event:Pos901(0X385) N_pos:949(0X3b5) Time:1496993581 Event_size:48(bytes)

TABLE_ID:142 DB_NAME:test TABLE_NAME:test Gno:3

------>Insert Event:Pos:949(0X3b5) N_pos:993(0X3e1) Time:1496993581 Event_size:44(bytes)

Dml on table: test.test? table_id:142 Gno:3

>Xid Event:Pos:993(0X3e1) N_Pos:1024(0X400) Time:1496993581 Event_size:31(bytes)

COMMIT; /*!Trx end*/ Gno:3? --注意這里以N_Pos為結尾及下一個event的開始位置

>Gtid Event:Pos:1024(0X400) N_pos:1089(0X441) Time:1496993584 Event_size:65(bytes)

Gtid:89dfa8a4-cb13-11e6-b54-0c29a879a3:4

-->Query Event:Pos:1089(0X441) N_Pos:1161(0X489) Time:1496993584 Event_size:72(bytes)

Exe_time:0? Use_db:test Statment(35b-trun):BEGIN /*!Trx begin!*/ Gno:4

---->Map Event:Pos1161(0X489) N_pos:1209(0X4b9) Time:1496993584 Event_size:48(bytes)

TABLE_ID:142 DB_NAME:test TABLE_NAME:test Gno:4

------>Delete Event:Pos:1209(0X4b9) N_pos:1262(0X4ee) Time:1496993584 Event_size:53(bytes)

Dml on table: test.test? table_id:142 Gno:4

>Xid Event:Pos:1262(0X4ee) N_Pos:1293(0X50d) Time:1496993584 Event_size:31(bytes)

COMMIT; /*!Trx end*/ Gno:4 --注意這里以N_Pos為結尾及下一個event的開始位置

顯然這里包含了3個事物，

1、504到764為一個事物，工具顯示這個event為Insert Event，在表test.test

2、764到1024為一個事物，工具顯示這個event為Insert Event，在表test.test

3、1024到1293為一個事物，工具顯示這個event為Delete Event，在表test.test

這就是我做的操作，這個工具主要是通過分析binlog event方便尋找事物，當然mysqlbinlog也可以只是輸出有點不直觀。

在通過mysqlbinlog分析的時候一定要注意一個事物的開始和結束。

(mysqlbinlog testsla.000003? -vv --start-postions=504 --stop-postions=1024 --base64-output=decode-rows 查看不通過base64算法顯示二進制內容)

(mysqlbinlog testsla.000003? -vv --start-postions=504 --stop-postions=1024 查看通過base64算法顯示二進制內容)

下面我們通過mysqlbinlog來分析上面的事物1 504到764為

# at 473

#170609 15:20:45 server id 933310? end_log_pos 504 CRC32 0x609296d7? ? Xid = 161

COMMIT/*!*/; ---注意這里上一個事物的結束叫做xid event

# at 504? ? ---這里是事物1 的起點沒叫做gtid event

#170609 15:32:58 server id 933310? end_log_pos 569 CRC32 0xf7eebfc7? ? GTID [commit=yes]

SET @@SESSION.GTID_NEXT= '89dfa8a4-cb13-11e6-b504-000c29a879a3:2'/*!*/;

# at 569? ? ---這段event是query event

#170609 15:32:58 server id 933310? end_log_pos 641 CRC32 0xb4caa78c? ? Query? thread_id=4? ? exec_time=0? ? error_code=0

SET TIMESTAMP=1496993578/*!*/;

BEGIN

/*!*/;

# at 641? ? ---這段event是map event

#170609 15:32:58 server id 933310? end_log_pos 689 CRC32 0xb055655f? ? Table_map: `test`.`test` mapped to number 142

# at 689? ? ---這段event是insert event

#170609 15:32:58 server id 933310? end_log_pos 733 CRC32 0xd907a353? ? Write_rows: table id 142 flags: STMT_END_F

### INSERT INTO `test`.`test`

### SET

###? @1=1 /* INT meta=0 nullable=1 is_null=0 */

###? @2=2 /* INT meta=0 nullable=1 is_null=0 */

# at 733? ? --這段event是xid event

#170609 15:32:58 server id 933310? end_log_pos 764 CRC32 0x9dbe0a6b? ? Xid = 323

COMMIT/*!*/;? ---這里是一個事物的結尾叫做xid event，但是注意不是733而是下一個event開始的位置764 或者是 xid event 的end_log_pos ，否則將會被回滾掉

# at 764

#170609 15:33:01 server id 933310? end_log_pos 829 CRC32 0x82aac64c? ? GTID [commit=yes]

SET @@SESSION.GTID_NEXT= '89dfa8a4-cb13-11e6-b504-000c29a879a3:3'/*!*/;

所以我們認為一個事物的binlog是504到764

如果寫為733會出現(xiàn)這種情況

mysqlbinlog testsla.000003? --start-position=504 --stop-position=733 -vv? --base64-output=decode-rows

........

# at 689

#170609 15:32:58 server id 933310? end_log_pos 733 CRC32 0xd907a353? ? Write_rows: table id 142 flags: STMT_END_F

### INSERT INTO `test`.`test`

### SET

###? @1=1 /* INT meta=0 nullable=1 is_null=0 */

###? @2=2 /* INT meta=0 nullable=1 is_null=0 */

DELIMITER ;

# End of log file

ROLLBACK /* added by mysqlbinlog */;? --很明顯沒有xid event 沒有commit而mysqlbinlog自己家了一個rollback而回滾掉了

/*!50003 SET COMPLETION_TYPE=@OLD_COMPLETI

好這里我們簡單介紹一個事物binlog event的流程，正如我工具所展示的

>Gtid Event ：事物開始

-->Query Event：begin

---->Map Event ：表映射

------>Insert Event：正真的數(shù)據(jù)二進制格式

>Xid Event: commit

這事一個事物需要經歷的event，在5.7中不開啟GTID，GTID EVENT任然存在那叫匿名gtid event及NONYMOUS_GTID_LOG_EVENT，在5.6中

最多gtid event有變動，因為5.6的并行slave不依靠binlog group commit。

如果想了解這些event和infobin工具請參考的文章：

http://blog.itpub.net/7728585/viewspace-2133188/ 解析MYSQL BINLOG 二進制格式(1)--準備工作

http://blog.itpub.net/7728585/viewspace-2133189/ 解析MYSQL BINLOG 二進制格式(2)--FORMAT_DESCRIPTION_EVENT

http://blog.itpub.net/7728585/viewspace-2133321/ 解析MYSQL BINLOG 二進制格式(3)--QUERY_EVENT

http://blog.itpub.net/7728585/viewspace-2133429/ 解析MYSQL BINLOG 二進制格式(4)--TABLE_MAP_EVENT

http://blog.itpub.net/7728585/viewspace-2133463/ 解析MYSQL BINLOG 二進制格式(5)--WRITE_ROW_EVENT

http://blog.itpub.net/7728585/viewspace-2133469/ 解析MYSQL BINLOG 二進制格式(6)--UPDATE_ROW_EVENT/DELETE_ROW_EVENT

http://blog.itpub.net/7728585/viewspace-2133502/ 解析MYSQL BINLOG 二進制格式(7)--Xid_log_event/XID_EVENT

http://blog.itpub.net/7728585/viewspace-2133506/ 解析MYSQL BINLOG 二進制格式(8)--GTID_LOG_EVENT/ANONYMOUS_GTID_LOG_EVENT及其他

http://blog.itpub.net/7728585/viewspace-2133534/ 解析MYSQL BINLOG 二進制格式(9)--infobin解析binlog幫助文檔

http://blog.itpub.net/7728585/viewspace-2133537/ 解析MYSQL BINLOG 二進制格式(10)--問題解答

最后看看語句模式的一個事物，雖然很少人用但是我們做一個對比

>Gtid Event:Pos:194(0Xc2) N_pos:259(0X103) Time:1496998879 Event_size:65(bytes)

Gtid:89dfa8a4-cb13-11e6-b54-0c29a879a3:5

-->Query Event:Pos:259(0X103) N_Pos:338(0X152) Time:1496998879 Event_size:79(bytes)

Exe_time:0? Use_db:test Statment(35b-trun):BEGIN /*!Trx begin!*/ Gno:5

-->Query Event:Pos:338(0X152) N_Pos:428(0X1ac) Time:1496998879 Event_size:90(bytes)

Exe_time:0? Use_db:test Statment(35b-trun):delete from test Gno:5

>Xid Event:Pos:428(0X1ac) N_Pos:459(0X1cb) Time:1496998879 Event_size:31(bytes)

COMMIT; /*!Trx end*/ Gno:5

及

>GTID EVENT :事物開始

-->Query Event :begin

-->Query Event :正真的語句

>Xid Event: commit

沒有Map Event和Insert Event，當然記錄的是語句就節(jié)約空間了。

下面是mysqlbinlog輸出

# at 194 --GTID EVENT開始

#170609 17:01:19 server id 933310? end_log_pos 259 CRC32 0x6a408c33? ? GTID [commit=yes]

SET @@SESSION.GTID_NEXT= '89dfa8a4-cb13-11e6-b504-000c29a879a3:5'/*!*/;

# at 259? --Query Event BEGIN

#170609 17:01:19 server id 933310? end_log_pos 338 CRC32 0x9b25b2af? ? Query? thread_id=2? ? exec_time=0? ? error_code=0

SET TIMESTAMP=1496998879/*!*/;

SET @@session.pseudo_thread_id=2/*!*/;

SET @@session.foreign_key_checks=1, @@session.sql_auto_is_null=0, @@session.unique_checks=1, @@session.autocommit=1/*!*/;

SET @@session.sql_mode=1075838976/*!*/;

SET @@session.auto_increment_increment=1, @@session.auto_increment_offset=1/*!*/;

/*!\C utf8 *//*!*/;

SET @@session.character_set_client=83,@@session.collation_connection=83,@@session.collation_server=83/*!*/;

SET @@session.lc_time_names=0/*!*/;

SET @@session.collation_database=DEFAULT/*!*/;

BEGIN

/*!*/;

# at 338? --Query Event 正真的語句

#170609 17:01:19 server id 933310? end_log_pos 428 CRC32 0x4e4230f8? ? Query? thread_id=2? ? exec_time=0? ? error_code=0

use `test`/*!*/;

SET TIMESTAMP=1496998879/*!*/;

delete from test

/*!*/;

# at 428 -- XID EVENT結束

#170609 17:01:19 server id 933310? end_log_pos 459 CRC32 0x38079d60? ? Xid = 159

COMMIT/*!*/;

總結

以上是生活随笔為你收集整理的mysql一个事务多个log_MySQL识别一个binlog中的一个事物的全部內容，希望文章能夠幫你解決所遇到的問題。

如果覺得生活随笔網站內容還不錯，歡迎將生活随笔推薦給好友。