Otter 同步出现时间列上值不一致问题
背景：
由于历史原因我们DB中引入了多源复制技术，在之后的使用过程中发现了一些弊端，于是准备去掉这套架构，使用otter来代替多源复制。在otter同步跑了很长一段时间之后，我们准备上线这套集群，却发现了一些不一致的情况。
问题：
之前otter使用的不是特别多，对其稳定性也不是很有信心，为了检验源库数据和目的数据的一致性，避免切换后出现数据不一致的问题，我们写脚本抽样比对了部分数据，结果发现两边的确存在数据不一致的情况，以下是其中一个案例。
源数据
< 2357 999999999 0 刘 2030420 罗 2409 红娘二部（易） 270 重庆解放碑店 2012218 陈 2020-05-18 02:27:04 2020-05-19 12:20:18 NULL 0 0 0 0 2020-05-19 12:20:18 2020-05-19 12:20:18
目的数据
> 2357 999999999 0 刘 2030420 罗 2409 红娘二部（易） 270 重庆解放碑店 2012218 陈 2020-05-18 02:27:04 2020-05-19 12:20:18 NULL 0 0 0 0 2020-05-19 12:20:18 2020-05-27 10:53:37
表结构
CREATE TABLE `ArchiveMeetDetail` ( `id` bigint(20) unsigned NOT NULL AUTO_INCREMENT COMMENT '主键', `memberId` int(11) NOT NULL ， `sex` tinyint(3) NOT NULL , `trueName` varchar(10) NOT NULL , `belongWorkerId` int(11) NOT NULL , `belongWorkerName` varchar(20) NOT NULL , `belongGroupId` int(11) NOT NULL , `belongGroupName` varchar(20) NOT NULL , `belongDeptId` int(11) NOT NULL , `belongDeptName` varchar(20) NOT NULL , `workerId` int(11) NOT NULL , `workerName` varchar(255) NOT NULL , `archiveTime` datetime NOT NULL , `archivePassTime` datetime NOT NULL , `firstMeetTime` datetime DEFAULT NULL , `meetThisMonth` int(11) NOT NULL DEFAULT '0' , `miniMeetThisMonth` int(11) NOT NULL DEFAULT '0' , `meetTotal` int(11) NOT NULL DEFAULT '0' , `miniMeetTotal` int(11) NOT NULL DEFAULT '0' , `createTime` datetime NOT NULL DEFAULT CURRENT_TIMESTAMP , `updateTime` datetime NOT NULL DEFAULT CURRENT_TIMESTAMP ON UPDATE CURRENT_TIMESTAMP , PRIMARY KEY (`id`), KEY `idx_member_id` (`memberId`) USING BTREE, KEY `idx_group_id` (`belongGroupId`) USING BTREE, KEY `idx_dept_id` (`belongDeptId`) USING BTREE, KEY `idx_archive_pass_time` (`archivePassTime`) USING BTREE ) ENGINE=InnoDB AUTO_INCREMENT=3543 DEFAULT CHARSET=utf8
我们发现：
1.都是这种updatetime时间列上的数据不一致。
2.updatetime列上有ON UPDATE CURRENT_TIMESTAMP 子句，也就是每次更新的时候这列的值会自动更新为当前时间。
排查：
首先我们在源库解析binlog，提取对这个表修改的sql，主要是两个sql，一个update语句和一个insert语句。
我们测试了update 语句在修改其他列或updatetime 列的情况，也测试了insert在指定updatetime值和不指定updatetime 值的情况（第一次可能测试的不是很仔细），结果都是源和目的的数据是完全一致的。
之后，根据目的数据的修改时间（2020-05-27 10:53:37），我们排查目的库的binlog，找到修改这条记录所对应的sql。
UPDATE `zhenai_crm_matchmaker`.`ArchiveMeetDetail` SET `firstMeetTime`=NULL, `updateTime`='2020-05-27 10:53:37', `miniMeetTotal`=0, `archiveTime`='2020-05-18 02:27:04', `belongDeptName`='重庆解放碑店', `workerId`=2012218, `createTime`='2020-05-19 12:20:18', `belongGroupId`=2409, `memberId`=999999999, `belongWorkerId`=2030420, `sex`=0, `belongGroupName`='红娘二部（易）', `trueName`='刘', `belongWorkerName`='罗', `meetTotal`=0, `workerName`='陈', `miniMeetThisMonth`=0, `belongDeptId`=270, `meetThisMonth`=0, `id`=2357, `archivePassTime`='2020-05-19 12:20:18' WHERE `firstMeetTime` IS NULL AND `updateTime`='2020-05-19 12:20:18' AND `miniMeetTotal`=0 AND `archiveTime`='2020-05-18 02:27:04' AND `belongDeptName`='重庆解放碑店' AND `workerId`=2030420 AND `createTime`='2020-05-19 12:20:18' AND `belongGroupId`=2409 AND `memberId`=999999999 AND `belongWorkerId`=2030420 AND `sex`=0 AND `belongGroupName`='红娘二部（易）' AND `trueName`='刘' AND `belongWorkerName`='罗' AND `meetTotal`=0 AND `workerName`='罗' AND `miniMeetThisMonth`=0 AND `belongDeptId`=270 AND `meetThisMonth`=0 AND `id`=2357 AND `archivePassTime`='2020-05-19 12:20:18' LIMIT 1; #start 142683935 end 142684421 time 2020-05-27 10:53:37
如果目的库这个时间有修改，那么源库这个时间应该也是有同样的sql在执行，顺着这条思路，查查了源库binlog，解析出了对应的sql。
UPDATE `zhenai_crm_matchmaker`.`ArchiveMeetDetail` SET `firstMeetTime`=NULL, `updateTime`='2020-05-19 12:20:18', `miniMeetTotal`=0, `archiveTime`='2020-05-18 02:27:04', `belongDeptName`='重庆解放碑店', `workerId`=2012218, `createTime`='2020-05-19 12:20:18', `belongGroupId`=2409, `memberId`=999999999, `belongWorkerId`=2030420, `sex`=0, `belongGroupName`='红娘二部（易）', `trueName`='刘', `belongWorkerName`='罗', `meetTotal`=0, `workerName`='陈', `miniMeetThisMonth`=0, `belongDeptId`=270, `meetThisMonth`=0, `id`=2357, `archivePassTime`='2020-05-19 12:20:18' WHERE `firstMeetTime` IS NULL AND `updateTime`='2020-05-19 12:20:18' AND `miniMeetTotal`=0 AND `archiveTime`='2020-05-18 02:27:04' AND `belongDeptName`='重庆解放碑店' AND `workerId`=2030420 AND `createTime`='2020-05-19 12:20:18' AND `belongGroupId`=2409 AND `memberId`=999999999 AND `belongWorkerId`=2030420 AND `sex`=0 AND `belongGroupName`='红娘二部（易）' AND `trueName`='刘' AND `belongWorkerName`='罗' AND `meetTotal`=0 AND `workerName`='罗' AND `miniMeetThisMonth`=0 AND `belongDeptId`=270 AND `meetThisMonth`=0 AND `id`=2357 AND `archivePassTime`='2020-05-19 12:20:18' LIMIT 1; #start 126420599 end 126421098 time 2020-05-27 10:53:37
单看每一边的binlog及表的数据，都没有什么问题，binlog和数据都是对应的。但问题是同步过去为什么数据不一样，当然是因为执行的sql不一样。可是执行的sql为什么不一样呢？难道同步过去binlog不一样。但是这不可能的，主库和从库的binlog应该是一样的，
那这里目的库的updatetime值为什么跟主库不一样呢？仔细看，就可以发现两点：
1.源库在修改这条数据的时候也修改了updatetime 的值，但不是修改成最新的时间，而是跟原来一样的时间。
2.而目的库，updatetime则是较新的一个值，很可能是当时执行时的时间。
基于以上两点我们又做了测试，终于发现了问题。
验证：
|
|
测试SQL |
结果 |
|
1 |
update set updatetime = '2020-05-19 12:20:18' , workerName = 'test' ... |
两边数据不一致 |
|
2 |
update set updatetime = '2020-05-28 10:00:00' , workerName = 'test' ... |
两边数据一致 |
我们发现出现不一致要有两个条件，
第一，updatetime的值要修改成跟原来一样，也就是updatetime的值要保持不变，
第二，除了updatetime列外，还需要变更其他列的数据。
对于源库来说，它在执行update的时候把updatetime列的值修改成跟原来一样的值（如果不这么做，那么updatetime的值就会是执行时的时间）。而binlog传到目的端，在解析binog的时候，（由于一些参数）otter会根据列的变更来同步数据，而updatetime这列修改前的时间和修改后的时间是一样的，otter就没有同步这列，比如第一个sql，otter解析执行的sql可能是这个样子的：update set workerName = 'test' ... 。由于updatetime这列是自动更新的，所以实际是这样的：update set updatetime = '#current_time#' ,workerName = 'test' ... 。这就导致了目的时间是当前时间，和源库的时间不一样。
解决：
查询资料及官方文档，我们找到了otter中的一个可能造成这种结果的参数--channel 中的同步模式，有两种同步模式：
行模式 ，兼容otter3的处理方案，改变记录中的任何一个字段，触发整行记录的数据同步，在目标库执行merge sql。
列模式 ，基于log中的具体变更字段，按需同步。
原先我们设置的是列模式，对于一般情况，同步不会有问题，但无法处理上面这种特殊情况下的同步。改为行模式后，经测试，两边的数据一致。