以前编程不喜欢对每个功能、重要的方法进行日志的格式化输出，即使要输出，也没按照规范进行输出。前段时间，随着我们平

台用户量不断增加，出现些问题。当用户遇到问题，就给我们客服打电话。然后客服毫无疑问就来找我们。当我们接收到这样问题的时候，

我们首先要定位是什么原因造成的。当时就是因为自己方法中输出的日志很少，而且不规范，所以根本找不到具体原因，虽然最后问题解

决了，但是花费很多不必要的时间。

那么我们怎样使用好日志这一利器呢？

1.写好日志：

我们先来看一下糟糕的日志打印：

上面代码是严格不符合规范的，每个公司都有自己的打印要求。首先日志的打印必须是以logger.error、logger.warn或logger.info

的方式打印出来。日志打印格式应包含[系统来源] 错误描述 [关键信息]，日志信息要能打印出能看懂的信息，有前因和后果。方法的入参和

出参也要考虑打印出来。

好的日志格式打印：

从上面的代码可以很轻松的定位方法、传入的参数及异常是调用接口抛出的异常还是系统级的异常。

2.良好的日志输出格式

最近测试同事采用两百并发对项目进行测试。

格式优化前：

经过测试查看每秒的并发量平均只有110多如下图：

通过查看jvm 虚拟机发现很多日志线程出现BLOCKED，如下：

我们定位org.apache.log4j.spi.LocationInfo类的代码如下：

从上面可以看出在该方法中用了synchronized锁，然后又通过打印堆栈来获取行号。那么肯定会影响到性能的，我们通过看

此时log4j.properties日志文件配置的输出格式为：

 %d %-5p %c:%L [%t] - %m%n

很明显就是%l输出行号的问题，那么我们把%l去掉结果会不会好很多呢？

把log4j.properties文件中输出格式改为：

 %d %-5p %c [%t] - %m%n

输出格式优化后：

 再看每秒的并发量如下图：

从图中我们可以看出并发量提高了40多，同时jvm线程日志中java.lang.Thread.State: BLOCKED也没有了。看来程序的性能和日志的输