Java SpringBoot 日志
此组件解决的问题是:「谁」在「什么时间」对「什么」做了「什么事」
此组件目前针对 Spring-boot 做了 Autoconfig,如果是 SpringMVC,也可自己在 xml 初始化 bean

项目源码

https://github.com/mouzt/mzt-biz-log

基本使用

Maven依赖添加SDK依赖

  1. <dependency>
  2. <groupId>io.github.mouzt</groupId>
  3. <artifactId>bizlog-sdk</artifactId>
  4. <version>1.0.1</version>
  5. </dependency>

SpringBoot入口打开开关,添加 @EnableLogRecord 注解

tenant是代表租户的标识,一般一个服务或者一个业务下的多个服务都写死一个 tenant 就可以

  1. @SpringBootApplication(exclude = DataSourceAutoConfiguration.class)
  2. @EnableTransactionManagement
  3. @EnableLogRecord(tenant = "com.mzt.test")
  4. public class Main {
  5. public static void main(String[] args) {
  6. SpringApplication.run(Main.class, args);
  7. }
  8. }

日志埋点

1. 普通的记录日志

  • pefix:是拼接在 bizNo 上作为 log 的一个标识。避免 bizNo 都为整数 ID 的时候和其他的业务中的 ID 重复。比如订单 ID、用户 ID 等
  • bizNo:就是业务的 ID,比如订单ID,查询的时候可以根据 bizNo 查询和它相关的操作日志
  • success:方法调用成功后把 success 记录在日志的内容中
  • SpEL 表达式:其中用双大括号包围起来的(例如:{{#order.purchaseName}})#order.purchaseName 是 SpEL表达式。Spring中支持的它都支持的。比如调用静态方法,三目表达式。SpEL 可以使用方法中的任何参数

    1. @LogRecordAnnotation(success = "{{#order.purchaseName}}下了一个订单,购买商品「{{#order.productName}}」,下单结果:{{#_ret}}",
    2. prefix = LogRecordType.ORDER, bizNo = "{{#order.orderNo}}")
    3. public boolean createOrder(Order order) {
    4. log.info("【创建订单】orderNo={}", order.getOrderNo());
    5. // db insert order
    6. return true;
    7. }

    此时会打印操作日志 “张三下了一个订单,购买商品「超值优惠红烧肉套餐」,下单结果:true”

    2. 期望记录失败的日志, 如果抛出异常则记录fail的日志,没有抛出记录 success 的日志

    1. @LogRecordAnnotation(
    2. fail = "创建订单失败,失败原因:「{{#_errorMsg}}」",
    3. success = "{{#order.purchaseName}}下了一个订单,购买商品「{{#order.productName}}」,下单结果:{{#_ret}}",
    4. prefix = LogRecordType.ORDER, bizNo = "{{#order.orderNo}}")
    5. public boolean createOrder(Order order) {
    6. log.info("【创建订单】orderNo={}", order.getOrderNo());
    7. // db insert order
    8. return true;
    9. }

    其中的 #_errorMsg 是取的方法抛出异常后的异常的 errorMessage。

    3. 日志支持种类

    比如一个订单的操作日志,有些操作日志是用户自己操作的,有些操作是系统运营人员做了修改产生的操作日志,系统不希望把运营的操作日志暴露给用户看到, 但是运营期望可以看到用户的日志以及运营自己操作的日志,这些操作日志的bizNo都是订单号,所以为了扩展添加了类型字段,主要是为了对日志做分类,查询方便,支持更多的业务。

    1. @LogRecordAnnotation(
    2. fail = "创建订单失败,失败原因:「{{#_errorMsg}}」",
    3. category = "MANAGER",
    4. success = "{{#order.purchaseName}}下了一个订单,购买商品「{{#order.productName}}」,下单结果:{{#_ret}}",
    5. prefix = LogRecordType.ORDER, bizNo = "{{#order.orderNo}}")
    6. public boolean createOrder(Order order) {
    7. log.info("【创建订单】orderNo={}", order.getOrderNo());
    8. // db insert order
    9. return true;
    10. }

    4. 支持记录操作的详情或者额外信息

    如果一个操作修改了很多字段,但是success的日志模版里面防止过长不能把修改详情全部展示出来,这时候需要把修改的详情保存到 detail 字段, detail 是一个 String ,需要自己序列化。这里的 #order.toString() 是调用了 Order 的 toString() 方法。如果保存 JSON,自己重写一下 Order 的 toString() 方法就可以。

    1. @LogRecordAnnotation(
    2. fail = "创建订单失败,失败原因:「{{#_errorMsg}}」",
    3. category = "MANAGER_VIEW",
    4. detail = "{{#order.toString()}}",
    5. success = "{{#order.purchaseName}}下了一个订单,购买商品「{{#order.productName}}」,下单结果:{{#_ret}}",
    6. prefix = LogRecordType.ORDER, bizNo = "{{#order.orderNo}}")
    7. public boolean createOrder(Order order) {
    8. log.info("【创建订单】orderNo={}", order.getOrderNo());
    9. // db insert order
    10. return true;
    11. }

    5. 指定操作日志的操作人是什么?框架提供了两种方法

    第一种:手工在LogRecord的注解上指定。这种需要方法参数上有operator

    1. @LogRecordAnnotation(
    2. fail = "创建订单失败,失败原因:「{{#_errorMsg}}」",
    3. category = "MANAGER_VIEW",
    4. detail = "{{#order.toString()}}",
    5. operator = "{{#currentUser}}",
    6. success = "{{#order.purchaseName}}下了一个订单,购买商品「{{#order.productName}}」,下单结果:{{#_ret}}",
    7. prefix = LogRecordType.ORDER, bizNo = "{{#order.orderNo}}")
    8. public boolean createOrder(Order order, String currentUser) {
    9. log.info("【创建订单】orderNo={}", order.getOrderNo());
    10. // db insert order
    11. return true;
    12. }

    这种方法手工指定,需要方法参数上有 operator 参数,或者通过 SpEL 调用静态方法获取当前用户。
    第二种:通过默认实现类来自动的获取操作人,由于在大部分web应用中当前的用户都是保存在一个线程上下文中的,所以每个注解都加一个operator获取操作人显得有些重复劳动,所以提供了一个扩展接口来获取操作人 框架提供了一个扩展接口,使用框架的业务可以 implements 这个接口自己实现获取当前用户的逻辑, 对于使用 Springboot 的只需要实现 IOperatorGetService 接口,然后把这个 Service 作为一个单例放到 Spring 的上下文中。使用 SpringMVC的就需要自己手工装配这些 bean 了。

    1. @Configuration
    2. public class LogRecordConfiguration {
    3. @Bean
    4. public IOperatorGetService operatorGetService() {
    5. return () -> Optional.of(OrgUserUtils.getCurrentUser())
    6. .map(a -> new OperatorDO(a.getMisId()))
    7. .orElseThrow(() -> new IllegalArgumentException("user is null"));
    8. }
    9. }
    10. //也可以这么搞:
    11. @Service
    12. public class DefaultOperatorGetServiceImpl implements IOperatorGetService {
    13. @Override
    14. public OperatorDO getUser() {
    15. OperatorDO operatorDO = new OperatorDO();
    16. operatorDO.setOperatorId("SYSTEM");
    17. return operatorDO;
    18. }
    19. }

    6. 日志文案调整

    对于更新等方法,方法的参数上大部分都是订单ID、或者产品ID等, 比如下面的例子:日志记录的success内容是:“更新了订单{{#orderId}},更新内容为…”,这种对于运营或者产品来说难以理解,所以引入了自定义函数的功能。使用方法是在原来的变量的两个大括号之间加一个函数名称 例如 “{ORDER{#orderId}}” 其中 ORDER 是一个函数名称。只有一个函数名称是不够的,需要添加这个函数的定义和实现。可以看下面例子 自定义的函数需要实现框架里面的IParseFunction的接口,需要实现两个方法:

  • functionName() 方法就返回注解上面的函数名;

  • apply()函数参数是 “{ORDER{#orderId}}“中SpEL解析的#orderId的值,这里是一个数字1223110,接下来只需要在实现的类中把 ID 转换为可读懂的字符串就可以了, 一般为了方便排查问题需要把名称和ID都展示出来,例如:”订单名称(ID)”的形式。

    这里有个问题:加了自定义函数后,框架怎么能调用到呢? 答:对于SpringBoot应用很简单,只需要把它暴露在Spring的上下文中就可以了,可以加上Spring的 @Component 或者 @Service 很方便😄。SpringMVC 应用需要自己装配 Bean。

  1. // 没有使用自定义函数
  2. @LogRecordAnnotation(success = "更新了订单{{#orderId}},更新内容为....",
  3. prefix = LogRecordType.ORDER, bizNo = "{{#order.orderNo}}",
  4. detail = "{{#order.toString()}}")
  5. public boolean update(Long orderId, Order order) {
  6. return false;
  7. }
  8. //使用了自定义函数,主要是在 {{#orderId}} 的大括号中间加了 functionName
  9. @LogRecordAnnotation(success = "更新了订单ORDER{#orderId}},更新内容为...",
  10. prefix = LogRecordType.ORDER, bizNo = "{{#order.orderNo}}",
  11. detail = "{{#order.toString()}}")
  12. public boolean update(Long orderId, Order order) {
  13. return false;
  14. }
  15. // 还需要加上函数的实现
  16. @Component
  17. public class OrderParseFunction implements IParseFunction {
  18. @Resource
  19. @Lazy //为了避免类加载顺序的问题 最好为Lazy,没有问题也可以不加
  20. private OrderQueryService orderQueryService;
  21. @Override
  22. public String functionName() {
  23. // 函数名称为 ORDER
  24. return "ORDER";
  25. }
  26. @Override
  27. //这里的 value 可以吧 Order 的JSON对象的传递过来,然后反解析拼接一个定制的操作日志内容
  28. public String apply(String value) {
  29. if(StringUtils.isEmpty(value)){
  30. return value;
  31. }
  32. Order order = orderQueryService.queryOrder(Long.parseLong(value));
  33. //把订单产品名称加上便于理解,加上 ID 便于查问题
  34. return order.getProductName().concat("(").concat(value).concat(")");
  35. }
  36. }

7. 日志文案调整 使用 SpEL 三目表达式

  1. @LogRecordAnnotation(prefix = LogRecordTypeConstant.CUSTOM_ATTRIBUTE, bizNo = "{{#businessLineId}}",
  2. success = "{{#disable ? '停用' : '启用'}}了自定义属性{ATTRIBUTE{#attributeId}}")
  3. public CustomAttributeVO disableAttribute(Long businessLineId, Long attributeId, boolean disable) {
  4. return xxx;
  5. }

框架的扩展点

重写OperatorGetServiceImpl通过上下文获取用户的扩展

例子如下

  1. @Service
  2. public class DefaultOperatorGetServiceImpl implements IOperatorGetService {
  3. @Override
  4. public Operator getUser() {
  5. return Optional.ofNullable(UserUtils.getUser())
  6. .map(a -> new Operator(a.getName(), a.getLogin()))
  7. .orElseThrow(()->new IllegalArgumentException("user is null"));
  8. }
  9. }

ILogRecordService 保存/查询日志的例子,使用者可以根据数据量保存到合适的存储介质上,比如保存在数据库/或者ES。自己实现保存和删除就可以了

也可以只实现查询的接口,毕竟已经保存在业务的存储上了,查询业务可以自己实现,不走 ILogRecordService 这个接口,毕竟会有一些千奇百怪的查询需求。

  1. @Service
  2. public class DbLogRecordServiceImpl implements ILogRecordService {
  3. @Resource
  4. private LogRecordMapper logRecordMapper;
  5. @Override
  6. @Transactional(propagation = Propagation.REQUIRES_NEW)
  7. public void record(LogRecord logRecord) {
  8. log.info("【logRecord】log={}", logRecord);
  9. LogRecordPO logRecordPO = LogRecordPO.toPo(logRecord);
  10. logRecordMapper.insert(logRecordPO);
  11. }
  12. @Override
  13. public List<LogRecord> queryLog(String bizKey, Collection<String> types) {
  14. return Lists.newArrayList();
  15. }
  16. @Override
  17. public PageDO<LogRecord> queryLogByBizNo(String bizNo, Collection<String> types, PageRequestDO pageRequestDO) {
  18. return logRecordMapper.selectByBizNoAndCategory(bizNo, types, pageRequestDO);
  19. }
  20. }

IParseFunction 自定义转换函数的接口

可以实现IParseFunction 实现对LogRecord注解中使用的函数扩展 例子:

  1. @Component
  2. public class UserParseFunction implements IParseFunction {
  3. private final Splitter splitter = Splitter.on(",").trimResults();
  4. @Resource
  5. @Lazy
  6. private UserQueryService userQueryService;
  7. @Override
  8. public String functionName() {
  9. return "USER";
  10. }
  11. @Override
  12. // 11,12 返回 11(小明),12(张三)
  13. public String apply(String value) {
  14. if (StringUtils.isEmpty(value)) {
  15. return value;
  16. }
  17. List<String> userIds = Lists.newArrayList(splitter.split(value));
  18. List<User> misDOList = userQueryService.getUserList(userIds);
  19. Map<String, User> userMap = StreamUtil.extractMap(misDOList, User::getId);
  20. StringBuilder stringBuilder = new StringBuilder();
  21. for (String userId : userIds) {
  22. stringBuilder.append(userId);
  23. if (userMap.get(userId) != null) {
  24. stringBuilder.append("(").append(userMap.get(userId).getUsername()).append(")");
  25. }
  26. stringBuilder.append(",");
  27. }
  28. return stringBuilder.toString().replaceAll(",$", "");
  29. }
  30. }

变量相关

LogRecordAnnotation 可以使用的变量出了参数也可以使用返回值#_ret变量,以及异常的错误信息#_errorMsg,也可以通过SpEL的 T 方式调用静态方法噢

待扩展

实现一个 Log的 Context,可以解决方法参数中没有的变量但是想使用的问题,作者的初步想法是可以通过在方法中 add 变量的形式实现,很快就可以实现了。

注意点

⚠️ 整体日志拦截是在方法执行之后记录的,所以对于方法内部修改了方法参数之后,LogRecordAnnotation 的注解上的 SpEL 对变量的取值是修改后的值。