在输出debug日志前加上logger.isDebugEnabled()判断的原因

场景:

String token = md5.substring(0, 10) + base64Two + md5.substring(10);

if (logger.isDebugEnabled()) {
    logger.debug("[op:genShareToken] userId={}, token={}", userId,
        token);
}
return token;

总结:

且看isDebugEnabled()的源码:

public boolean isDebugEnabled() {    
  if(repository.isDisabled( Level.DEBUG_INT))
      return false;
  return Level.DEBUG.isGreaterOrEqual(this.getEffectiveLevel());
}

以下是debug()的源码:

public void debug(Object message) {
    if(repository.isDisabled(Level.DEBUG_INT))
        return;
    if(Level.DEBUG.isGreaterOrEqual(this.getEffectiveLevel())) {
        forcedLog(FQCN, Level.DEBUG, message, null);
    }
}

可见,debug()中做了跟isDebugEnabled()几乎一样的判断,看起来直接调用debug()比先判断isDebugEnabled()更加效率。   

此时来看下面的代码: logger.debug("The money is " + getTotalMoney());

  假设我们的日志级别设置为info,debug()方法调用后会判断if(repository.isDisabled(Level.DEBUG_INT)),然后return。但是在调用debug()方法时,必须先提供参数。要获得参数,getTotalMoney()方法返回的结果就是参数的一部分,就需要执行getTotalMoney()并拼接,。假设这个获取参数的过程需要10秒钟,则系统会在花费10秒后决定return,这显然很得不偿失。

如果并发数大的话,这样写还是会影响系统的性能的。这个时候,就应该写成:

if(logger.isDebugEnabled()){

logger.debug("The money is " + getTotalMoney());

}

如果debug的参数很简单的话,也可以直接写 logger.debug(message)的。官方的说法,执行一次logger.isDebugEnabled()这样的判断花费的时间大概是写日志时间的万分之一.虽然这个比例很小, 但是,程序中的任何地方放到并发的环境下,我们就得重新考虑了。

原文地址:https://www.cnblogs.com/lvhouhou/p/11983453.html