原文地址:http://fredpuls.com/site/softwaredevelopment/java/log4j/log4j_performance_tips.htm
使用日志可能会让你的应用性能下降20% —— 很难相信吧,但是却是真的可能。
这里通过Junit,使用不同的Log4j配置来测试。下面会展示测试结果。实际上影响log4j性能的最主要因素有三:
immediateFlush
bufferedIO
asycAppender
上面三个都是使用缓存,他们分三级。第一级,
一般地,输出流在使用的时候其他写入会被阻塞。使用缓存后可以减少这种阻塞。但是如果本来就没有阻塞(比如在写入本地日志的时候),使用缓存反而会延迟写入。这样讲的话,使用缓存反而没提升性能。所以性能调优要看情况:你使用的是本地日志还是远程日志,什么会造成资源竞争。
immediateFlush=false
会开启
java.io.Writer/java.io.OutputStreamWriter
的缓存,消息到达后会缓存最长1024字节。第二级
bufferedIO=true
会把
java.io.Writer
包装成
java.io.BufferedWriter
,缓存默认大小是 1024*8 字节。第三级是
asyncAppender
,会缓存Log4j事件,默认是128条。
一般地,输出流在使用的时候其他写入会被阻塞。使用缓存后可以减少这种阻塞。但是如果本来就没有阻塞(比如在写入本地日志的时候),使用缓存反而会延迟写入。这样讲的话,使用缓存反而没提升性能。所以性能调优要看情况:你使用的是本地日志还是远程日志,什么会造成资源竞争。
这个测试很简单,使用log4j输出2560行,看看不同的配置使用的时间如何。我们考察上面的三个因素:
每个相同的测试我们跑了三次。前三行结果是log4j的默认配置:同步+无缓存+立即刷入。第一条结果是157毫秒,我们忽略它,因为它和后两条(63毫秒,62毫秒)差距太大。
接下来三行 (63, 63, 62 milliseconds) 开启了缓存但是还是立即刷入。你可能想到这没用,立即刷入让缓存无用武之地。所以结果和前面一致还是60多毫秒。
然后我们看开启缓存后不立即刷入,也就是
如果关闭缓存不立即刷入呢?这个和上面结果一样。
接下来看异步日志。我们关闭缓存并且立即刷入,结果是 (78, 78, 94 milliseconds)。异步日志没有改善性能,反而降低了我们的性能。
最后,我们使用异步日志结合缓存打开、不立即刷入,结果是 (63, 47, 47 milliseconds) ,依然没有同步的效果好。
恩?咋回事呢?
![](http://fredpuls.com/images/log4j.gif)
每个相同的测试我们跑了三次。前三行结果是log4j的默认配置:同步+无缓存+立即刷入。第一条结果是157毫秒,我们忽略它,因为它和后两条(63毫秒,62毫秒)差距太大。
接下来三行 (63, 63, 62 milliseconds) 开启了缓存但是还是立即刷入。你可能想到这没用,立即刷入让缓存无用武之地。所以结果和前面一致还是60多毫秒。
然后我们看开启缓存后不立即刷入,也就是
immediateFlush=false
,结果是(31, 32, 31 milliseconds)。差不多是前面的一半,好厉害!
如果关闭缓存不立即刷入呢?这个和上面结果一样。
接下来看异步日志。我们关闭缓存并且立即刷入,结果是 (78, 78, 94 milliseconds)。异步日志没有改善性能,反而降低了我们的性能。
最后,我们使用异步日志结合缓存打开、不立即刷入,结果是 (63, 47, 47 milliseconds) ,依然没有同步的效果好。
恩?咋回事呢?
Log4j性能建议 |
本地日志:
|