gtid主从报错When@@SESSION.GTID_NEXT is set to a GTID

故障现象

gtid主从报错信息

When@@SESSION.GTID_NEXT is set to a GTID, you must explicitly set it to a differentvalue after a COMMIT or ROLLBACK. Please check GTID_NEXT variable manual pagefor detailed explanation. Current @@SESSION.GTID_NEXT is'039a0c0c-9cee-11e6-922c-525400a9bf0e:324065731'.

故障可能原因

1 enforce_gtid_consistency为OFF，主库执行create table as select * from xx;

2 myisam引擎表，主库执行insert delayed into xx values...

3 主从引擎不一致，主库innodb引擎一个事务中写入两条数据，传到从库的myisam引擎执行这个事务

原因分析

根本原因是每一个GTID需要与一个唯一的事务对应

针对原因1，create table as select * from xx会自动转换成row模式，这时会拆成crate table和insert两个事务，这时传到slave时，slave执行完crate以后，多出一个insert事务没有gtid，于是报错；

针对原因2，只有myisam引擎支持insert delayed语法，insert delay是异步写入，也就是一旦执行立即返回给客户端成功。mysql内部处理insert delay时，会将多个线程的insert合并后一起执行，但是只生成了一个GTID；于是传到slave后，由于是myisam表，从库的同样只能执行第一条SQL，于是报错

针对原因3，主库innodb执行一个事务，只产生一个gtid，myisam不支持事务，事务的第一条执行完以后，第二个sql就没有gtid，于是报错

复现方法

1和3很好复现，忽略，讲下复现2的方法

1 运行两个进程并发insert delayed to myisam表

root     16511 28347  4 17:05 pts/0    00:00:00 /bin/bash ./test_myisam_delayinsert1.sh
root     18763 28347  4 17:05 pts/0    00:00:00 /bin/bash ./test_myisam_delayinsert.sh
root     22925 28347  0 17:05 pts/0    00:00:00 grep test

其中脚本内容为

cat test_myisam_delayinsert.sh 
#!/bin/bash
while true
do
mysql -S /opt/udb/instance/mysql-5.6/573c430f-5d26-4ac3-85e5-a3a40adbb05b/mysqld.sock -uucloudbackup -pQXYM4DFKM0 xx -e "insert delayed into test_myisam values(123)"
done

2 等待复现后，查看报错提示的gtid号为97127

3 去主库解析GTID 97127的binlog内容，可以看到一个myisam表，这个GTID下面却有两条SQL，该binlog传到slave，执行完第一条，第二条肯定就报错了（注意进程1和进程2的SQL内容包含在一个GTID中）

SET @@SESSION.GTID_NEXT= '910ed792-8300-11e7-ae6f-6c92bf218a02:97127'/*!*/;
# at 218335
#170818 16:56:44 server id 168407287  end_log_pos 218405 CRC32 0x56926f4e       Query   thread_id=246690        exec_time=0     error_code=0
SET TIMESTAMP=1503046604/*!*/;
BEGIN
/*!*/;
# at 218405
#170818 16:56:44 server id 168407287  end_log_pos 218457 CRC32 0xdf1693fd       Table_map: `xx`.`test_myisam` mapped to number 104
# at 218457
#170818 16:56:44 server id 168407287  end_log_pos 218497 CRC32 0x2fb8234a       Write_rows: table id 104 flags: STMT_END_F


BINLOG '
zKuWWRP3sAkKNAAAAFlVAwAAAGgAAAAAAAEAAnh4AAt0ZXN0X215aXNhbQABAwAB/ZMW3w==
zKuWWR73sAkKKAAAAIFVAwAAAGgAAAAAAAEAAgAB//7qAAAASiO4Lw==
'/*!*/;
### INSERT INTO `xx`.`test_myisam`
### SET
###   @1=234 /* INT meta=0 nullable=1 is_null=0 */
# at 218497
#170818 16:56:44 server id 168407287  end_log_pos 218549 CRC32 0xf4e4d63a       Table_map: `xx`.`test_myisam` mapped to number 104
# at 218549
#170818 16:56:44 server id 168407287  end_log_pos 218589 CRC32 0x2e19d842       Write_rows: table id 104 flags: STMT_END_F


BINLOG '
zKuWWRP3sAkKNAAAALVVAwAAAGgAAAAAAAEAAnh4AAt0ZXN0X215aXNhbQABAwABOtbk9A==
zKuWWR73sAkKKAAAAN1VAwAAAGgAAAAAAAEAAgAB//57AAAAQtgZLg==
'/*!*/;
### INSERT INTO `xx`.`test_myisam`
### SET
###   @1=123 /* INT meta=0 nullable=1 is_null=0 */
# at 218589
#170818 16:56:44 server id 168407287  end_log_pos 218660 CRC32 0x4124e705       Query   thread_id=246690        exec_time=0     error_code=0
SET TIMESTAMP=1503046604/*!*/;
COMMIT