闲谈程序中如何打印log

[size=medium]
程序中记录日志一般有两个目的：Troubleshooting和显示程序运行状态。好的日志记录方式可以提供我们足够多定位问题的依据。

这周看过很多代码，对里面的日志记录方式有些想法，今天是周末，也想总结下我对这个问题的看法。日志记录大家都会认为简单，但如何通过日志可以高效定位问题可不是简单的事情。这里我会写下面三个方面的内容，主要是举例为主，提醒我以后得注意这些方面
[list]
[*] 怎样记日志可以方便Troubleshooting
[*] 程序运行状态可以记哪些
[*] 应该避免怎样的日志方式
[/list]
[b]方便Troubleshooting的日志：[/b]

[color=blue]对外部的调用封装[/color]：[/size]
[size=medium]程序中对外部系统与模块的依赖调用前后都记下日志，方便接口调试。出问题时也可以很快理清是哪块的问题[/size]

LOG.debug("Calling external system:" + parameters);
Object result = null;
try {
	result = callRemoteSystem(params);
	LOG.debug("Called successfully. result is " + result);
} catch (Exception e) {
	LOG.warn("Failed at calling xxx system . exception : " + e);
}

[size=medium][color=blue]状态变化[/color]：
程序中重要的状态信息的变化应该记录下来，方便查问题时还原现场，推断程序运行过程[/size]

boolean isRunning;

isRunning = true;
LOG.info("System is running");

//...

isRunning = false;
LOG.info("System was interrupted by " + Thread.currentThread().getName());

[size=medium][color=blue]系统入口与出口[/color]：
这个粒度可以是重要方法级或模块级。记录它的输入与输出，方便定位[/size]

void execute(Object input) {
	LOG.debug("Invoke parames : " + input);
	Object result = null;

	//business logical

	LOG.debug("Method result : " + result);
}

[size=medium][color=blue]业务异常[/color]：
任何业务异常都应该记下来[/size]

try {
	//business logical
} catch (IOException e) {
	LOG.warn("Description xxx" , e);
} catch (BusinessException e) {
	LOG.warn("Let me know anything");
} catch (Exception e) {
	LOG.error("Description xxx", e);
}

void invoke(Object primaryParam) {
	if (primaryParam == null) {
		LOG.warn("Why this primary param is null?");
		return;
	}
}

[size=medium][color=blue]非预期执行[/color]：
为程序在“有可能”执行到的地方打印日志。如果我想删除一个文件，结果返回成功。但事实上，那个文件在你想删除之前就不存在了。最终结果是一致的，但程序得让我们知道这种情况，要查清为什么文件在删除之前就已经不存在呢[/size]

int myValue = xxxx;
int absResult = Math.abs(myValue);
if (absResult < 0) {
	LOG.info("Original int " + myValue + "has nagetive abs " + absResult);
}

[size=medium][color=blue]很少出现的else情况[/color]：
else可能吞掉你的请求，或是赋予难以理解的最终结果[/size]

Object result = null;
if (running) {
   result = xxx;
} else {
   result = yyy;
   LOG.debug("System does not running, we change the final result");
}

[size=medium][b]程序运行状态[/b]
程序在运行时就像一个机器人，我们可以从它的日志看出它正在做什么，是不是按预期的设计在做，所以这些正常的运行状态是要有的。

[color=blue]程序运行时间[/color]：[/size]

long startTime = System.currentTime();

// business logical

LOG.info("execution cost : " + (System.currentTime() - startTime) + "ms");　

[size=medium][color=blue]大批量数据的执行进度[/color][/size]：

LOG.debug("current progress: " + (currentPos * 100 / totalAmount) + "%");

[size=medium][color=blue]关键变量及正在做哪些重要的事情[/color]：
执行关键的逻辑，做IO操作等等[/size]

String getJVMPid() {
   String pid = "";
   // Obtains JVM process ID
   LOG.info("JVM pid is " + pid);
   return pid;
}

void invokeRemoteMethod(Object params) {
    LOG.info("Calling remote method : " + params);
    //Calling remote server
}

[size=medium][b]避免的日志方式[/b]

[color=orange]无意义的Log[/color]：
日志不包含有意义的信息: 你肯定想知道的是哪个文件不存在吧[/size]

File file = new File("xxx");
if (!file.isExist()) {
	LOG.warn("File does not exist"); //Useless message
}

[size=medium][color=orange]混淆信息的Log[/color]：
日志应该是清晰准确的: 当看到日志的时候，你知道是因为连接池取不到连接导致的问题么？[/size]

Connection connection = ConnectionFactory.getConnection();
if (connection == null) {
    LOG.warn("System initialized unsuccessfully");
}