binlog分析方法

线上处理问题的时候经常遇到业务说写入了但是数据不对，这种情况八成是业务改了之后又改过来了。但是我们需要证据，下面我们就说说简单的binlog分析

 

工具:mysqlbinlog

#-vvv显示明文sql内容方便查阅

#start-datetime分析开始时间

#stop-datatime分析结束时间

#-d数据库名称

#mysql-bin.000001 二进制文件

$mysqlbinlog -vvv --start-datetime='2018-11-15 18:47:00' --stop-datetime='2018-11-15 18:59:01' -uxxxxx --socket=/export/data/mysql/tmp/mysql.socket --port=3358  -d xxxxx mysql-bin.000001 > 1.txt

 

#通过执行以上命令就可以获取这个时间段里面的binlog信息

 

 

以下是部分解析出来的内容，文件里面有时间戳，可以根据时间戳就判断某个操作是否执行

# at 590290541

#181115 18:47:00 server id 23083726  end_log_pos 590290606 CRC32 0x644652ac     GTID    last_committed=210987   sequence_number=210988  rbr_only=yes

/*!50718 SET TRANSACTION ISOLATION LEVEL READ COMMITTED*//*!*/;

SET @@SESSION.GTID_NEXT= 'd2eabe2a-d5cb-11e8-a72a-fa169c5fc76e:191960'/*!*/;

# at 590290606

#181115 18:47:00 server id 23083726  end_log_pos 590290695 CRC32 0xcdd93a59     Query   thread_id=95124 exec_time=0 error_code=0

SET TIMESTAMP=1542278820/*!*/;

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

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

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

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

/*!\C utf8mb4 *//*!*/;

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

SET @@session.time_zone='SYSTEM'/*!*/;

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

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

BEGIN

/*!*/;

# at 590290695

#181115 18:47:00 server id 23083726  end_log_pos 590291436 CRC32 0xf28f1bd8     Rows_query

# insert into xxxxx(do_no, group_key, wave_strategy, auto_able, expect_pick_time, partitions, loc_code_list, priority, wms_sku_list, merge_no, weight, volume, area_id, area_name, latitude, longitude, wh_id, tenant_id, uuid, create_time, wh_name, wh_code, expect_arrive_starttime, expect_arrive_endtime, do_create_date, genera_source, extend_data) values ('65000372273101', 'sell_default', 0, 1, '2018-11-15 19:18:00', null, '[\"36518\",\"40001\"]', 5, '[1007116,1002291,1011345]', null, 4.580, 14178.416, 200030, '褐石园区', '40.016893', '116.322341', 131231, 1, '65000372273101-1', now(), '五彩城店', 'WCC', '2018-11-15 19:30:00', '2018-11-15 20:15:00', '2018-11-15 18:47:00', 'AI', '{\"workFood\":\"1\"}')

# at 590291436

#181115 18:47:00 server id 23083726  end_log_pos 590291571 CRC32 0x3a2c02ea     Table_map: `xxxxxx`.`wms_ai_do` mapped to number 262

# at 590291571

#181115 18:47:00 server id 23083726  end_log_pos 590291874 CRC32 0x8f14b7cb     Write_rows: table id 262 flags: STMT_END_F