场景:
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); } }
假设我们的日志级别设置为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()这样的判断花费的时间大概是写日志时间的万分之一.虽然这个比例很小, 但是,程序中的任何地方放到并发的环境下,我们就得重新考虑了。