面试被问在 Java 代码里如何打日志？

港控/mmm° 2023-01-03 12:42 172阅读 0赞

今年以来，我们招了不少新同事。不少工作了 3、4 年的还在用 System.out 打印日志。也有人使用 e.printStackTrace() 输出错误信息。使用者两个函数的，基本上都没看过其内部源码。kpi 绩效统统扣分，它们具体的危害可以看我的这篇文章：[都 9102 了，你还不知道 System.out.println 的危害！][9102 _ System.out.println]今天我们具体说说在 Java 中应该如何使用日志 api！

**使用slf4j**

1.  使用门面模式的日志框架，有利于维护和各个类的日志处理方式统一
2.  实现方式统一使用: Logback框架

**打日志的正确方式**

什么时候应该打日志

1.  当你遇到问题的时候，只能通过debug功能来确定问题，你应该考虑打日志，良好的系统，是可以通过日志进行问题定为的。
2.  当你碰到if…else 或者 switch这样的分支时，要在分支的首行打印日志，用来确定进入了哪个分支
3.  经常以功能为核心进行开发，你应该在提交代码前，可以确定通过日志可以看到整个流程

基本格式

必须使用参数化信息的方式:

logger.debug("Processing trade with id:[{}] and symbol : [{}] ", id, symbol);

对于debug日志，必须判断是否为debug级别后，才进行使用:

if (logger.isDebugEnabled()) {
        logger.debug("Processing trade with id: " +id + " symbol: " + symbol);
    }

不要进行字符串拼接,那样会产生很多String对象，占用空间，影响性能。

反例(不要这么做):

logger.debug("Processing trade with id: " + id + " symbol: " + symbol);

**使用\[\]进行参数变量隔离**

如有参数变量，应该写成如下写法:

logger.debug("Processing trade with id:[{}] and symbol : [{}] ", id, symbol);

这样的格式写法，可读性更好，对于排查问题更有帮助。

**不同级别的使用**

**ERROR:**

基本概念

影响到程序正常运行、当前请求正常运行的异常情况:

1.  打开配置文件失败
2.  所有第三方对接的异常(包括第三方返回错误码)
3.  所有影响功能使用的异常，包括:SQLException和除了业务异常之外的所有异常(RuntimeException和Exception)

不应该出现的情况:

1.  比如要使用Azure传图片，但是Azure未响应

如果有Throwable信息，需要记录完成的堆栈信息:

log.error("获取用户[{}]的用户信息时出错",userName,e);

说明

如果进行了抛出异常操作，请不要记录error日志，由最终处理方进行处理：

反例(不要这么做):

try{
        ....
    }catch(Exception ex){
      String errorMessage=String.format("Error while reading information of user [%s]",userName);
      logger.error(errorMessage,ex);
      throw new UserServiceException(errorMessage,ex);
    }

**WARN**

基本概念

不应该出现但是不影响程序、当前请求正常运行的异常情况:

1.  有容错机制的时候出现的错误情况
2.  找不到配置文件，但是系统能自动创建配置文件

即将接近临界值的时候，例如：

1.  缓存池占用达到警告线

业务异常的记录,比如:

1.  当接口抛出业务异常时，应该记录此异常

**INFO:**

基本概念

系统运行信息

1.  Service方法中对于系统/业务状态的变更
2.  主要逻辑中的分步骤

外部接口部分

1.  客户端请求参数(REST/WS)
2.  调用第三方时的调用参数和调用结果

说明

**1. **并不是所有的service都进行出入口打点记录,单一、简单service是没有意义的(job除外,job需要记录开始和结束,)。

反例(不要这么做):

public List listByBaseType(Integer baseTypeId) {
        log.info("开始查询基地");
        BaseExample ex=new BaseExample();
        BaseExample.Criteria ctr = ex.createCriteria();
        ctr.andIsDeleteEqualTo(IsDelete.USE.getValue());
        Optionals.doIfPresent(baseTypeId, ctr::andBaseTypeIdEqualTo);
        log.info("查询基地结束");
        return baseRepository.selectByExample(ex);
    }

**2. **对于复杂的业务逻辑，需要进行日志打点，以及埋点记录，比如电商系统中的下订单逻辑，以及OrderAction操作(业务状态变更)。

**3. **对于整个系统的提供出的接口(REST/WS)，使用info记录入参

**4. **如果所有的service为SOA架构，那么可以看成是一个外部接口提供方，那么必须记录入参。

**5. **调用其他第三方服务时，所有的出参和入参是必须要记录的(因为你很难追溯第三方模块发生的问题)

**DEBUG**

基本概念

1.  可以填写所有的想知道的相关信息(但不代表可以随便写，debug信息要有意义,最好有相关参数)
2.  生产环境需要关闭DEBUG信息
3.  如果在生产情况下需要开启DEBUG,需要使用开关进行管理，不能一直开启。

说明

如果代码中出现以下代码，可以进行优化:

//1. 获取用户基本薪资
    
    //2. 获取用户休假情况
    
    //3. 计算用户应得薪资

优化后的代码:

logger.debug("开始获取员工[{}] [{}]年基本薪资",employee,year);
    
    logger.debug("获取员工[{}] [{}]年的基本薪资为[{}]",employee,year,basicSalary);
    logger.debug("开始获取员工[{}] [{}]年[{}]月休假情况",employee,year,month);
    
    logger.debug("员工[{}][{}]年[{}]月年假/病假/事假为[{}]/[{}]/[{}]",employee,year,month,annualLeaveDays,sickLeaveDays,noPayLeaveDays);
    logger.debug("开始计算员工[{}][{}]年[{}]月应得薪资",employee,year,month);
    
    logger.debug("员工[{}] [{}]年[{}]月应得薪资为[{}]",employee,year,month,actualSalary);

**TRACE**

基本概念

特别详细的系统运行完成信息，业务代码中，不要使用.(除非有特殊用意，否则请使用DEBUG级别替代)

规范示例说明

@Override
    @Transactional
    public void createUserAndBindMobile(@NotBlank String mobile, @NotNull User user) throws CreateConflictException{
        boolean debug = log.isDebugEnabled();
        if(debug){
            log.debug("开始创建用户并绑定手机号. args[mobile=[{}],user=[{}]]", mobile, LogObjects.toString(user));
        }
        try {
            user.setCreateTime(new Date());
            user.setUpdateTime(new Date());
            userRepository.insertSelective(user);
            if(debug){
                log.debug("创建用户信息成功. insertedUser=[{}]",LogObjects.toString(user));
            }
            UserMobileRelationship relationship = new UserMobileRelationship();
            relationship.setMobile(mobile);
            relationship.setOpenId(user.getOpenId());
            relationship.setCreateTime(new Date());
            relationship.setUpdateTime(new Date());
            userMobileRelationshipRepository.insertOnDuplicateKey(relationship);
            if(debug){
                log.debug("绑定手机成功. relationship=[{}]",LogObjects.toString(relationship));
            }
            log.info("创建用户并绑定手机号. userId=[{}],openId=[{}],mobile=[{}]",user.getId(),user.getOpenId(),mobile);
        }catch(DuplicateKeyException e){
            log.info("创建用户并绑定手机号失败,已存在相同的用户. openId=[{}],mobile=[{}]",user.getOpenId(),mobile);
            throw new CreateConflictException("创建用户发生冲突, openid=[%s]",user.getOpenId());
        }
    }

![format_png][]

[9102 _ System.out.println]: http://mp.weixin.qq.com/s?__biz=MzIyODE5NjUwNQ%3D%3D&chksm=f387639dc4f0ea8bce80c2cebbbf92e0d12f0ac3b63aa3d6cb45215e94ebfbf599cf8ae1abcc&idx=1&mid=2653317739&scene=21&sn=40c88f5a773d6727c75f3ea5ebd5f92e#wechat_redirect
[format_png]: /images/20221119/cef0f5ee053a4fa1a816910af8e998db.png