问题排查记录-mysql事务未提交导致的数据读取问题
1、问题背景
为了采集银行回单,需要调用一个接口(获取银行回单接口)获取一个任务号(task_id),调用方拿到这个任务号会生成一条任务数据入库,获取银行回单接口获取回单后,会回调调用方的接口,调用方获取这个刚才入库的任务进行后续处理。
图示:
现象:任务的状态一直是 0(初始化状态) 正常接收到回调,处理完成应该是9
2、排查过程
1、查看回调接口日志,是正常收到了回调的,因此回调没问题,那问题可能出现在了回调的接口调用资金模块处理上
2、查看资金模块处理接口,没加日志,于是使用arthas 的watch命令 监控参数和返回值
watch com.yzf.fintax.fund.acl.facade.ZjUploadRecordFacade handleCollectTask ‘{params,returnObj,throwExp}’ -n 5 -x 3 执行5次,打印参数 返回值 异常 深度为3(参数返回值结果深度)
继续发起调用,监控到参数是没问题的,函数也是正常退出,但是返回值对象里面只有一个属性status有值为3,查看代码 只有这块代码会导致这个结果 根据任务号查询任务
// 变更代账解析中
ZjUploadRecordBO record = zjUploadRecordService.selectOneBySerialId(taskId);
if (Objects.isNull(record) || !ZijinConstants.BANK_RECEIPT_PARSE_STATUS.IN_TASK.equals(record.getStatus())) {
// 记录为空或 当前状态不是任务中,本次则忽略
return ZjUploadRecordDTO.builder().status(ZijinConstants.BANK_RECEIPT_PARSE_STATUS.FAILURE).build();
}
为了进一步确定是record为null,在查询后加日志(用watch也可以对selectOneBySerialId这个方法)
log.info("[ZjUploadRecordFacade][handleCollectTask] taskId:{},ossurl:{}",taskId,ossUrlStr);
// 变更代账解析中
ZjUploadRecordBO record = zjUploadRecordService.selectOneBySerialId(taskId);
log.info("[ZjUploadRecordFacade][handleCollectTask] record:{}",record);
再次请求 发现确实为null,奇怪的现象,明明数据库可以查到为什么这边取不到呢,查询方法是没问题的。
查看数据库的这条数据,创建时间是2022-03-04 17:24:19
handleCollectTask 方法被调用的时候 2022-03-04 17:24:18 还没创建方法就被调用了,数据就被查询了,因此查不到,
为了进一步验证,让调用方做了个延迟调用,果然这边就取到了,如果调用方不好改(可以根据特征字段由回调接口进行延迟调用也可)
(ps:如果条件允许的话可以加日志的地方 可以选择远程调试)
3、总结
1、在日志不全的时候,用工具(arthas)在不用加日志重启的系统的时候可以观察方法入参和返回值的情况
2、观察数据的创建时间和日志时间比较等(数据记录要存数据的创建和修改时间戳)