今天看到一篇文章，談Log4j對(duì)系統(tǒng)的效能的影響...整理如下：
一般，我們用log4j寫log的時(shí)候，很習(xí)慣于如下面記錄：
2005-01-11 09:57:57,816 DEBUG (BizProc.java:275) Begin to get connection...
2005-01-11 09:57:57,816 DEBUG (BizProc.java:275) Obtain connection 2345@defd
2005-01-11 09:57:57,816 DEBUG (BizProc.java:275) Begin transaction [TX_2015_RECV] processing...
2005-01-11 09:57:57,925 DEBUG (BizProc.java:328) All of the values were: |
這里會(huì)把Java Source Code中的行號(hào)帶出來(lái)，查找錯(cuò)誤很方便...
但，這也是問(wèn)題之所在...文章中介紹，Log4j之所以可以得到行號(hào)，是用了JVM的一個(gè)特性，不知道大家有沒(méi)有注意到，如果我們的Java程序在運(yùn)行時(shí)出現(xiàn)錯(cuò)誤，會(huì)出現(xiàn)下面的信息：
javax.naming.NamingException: Cannot create resource instance
at org.apache.naming.factory.ResourceFactory.getObjectInstance(ResourceFactory.java:189)
at javax.naming.spi.NamingManager.getObjectInstance(NamingManager.java:301)
at org.apache.naming.NamingContext.lookup(NamingContext.java:834)
at org.apache.naming.NamingContext.lookup(NamingContext.java:181)
at org.apache.naming.NamingContext.lookup(NamingContext.java:822)
at org.apache.naming.NamingContext.lookup(NamingContext.java:181)
at org.apache.naming.NamingContext.lookup(NamingContext.java:822)
at org.apache.naming.NamingContext.lookup(NamingContext.java:181)
at org.apache.naming.NamingContext.lookup(NamingContext.java:822)
at org.apache.naming.NamingContext.lookup(NamingContext.java:194)
at org.apache.naming.SelectorContext.lookup(SelectorContext.java:183)
at javax.naming.InitialContext.lookup(InitialContext.java:347) |
看到黑體字沒(méi)有，原來(lái)JVM已經(jīng)內(nèi)嵌支持Debug出行號(hào)信息了！可是，這里是出錯(cuò)的時(shí)候，JVM拋出的，那為什么用 log4j.info() 的時(shí)候，也可以看到行號(hào)呢？
下面就是重點(diǎn)，Log4j中記錄行號(hào)的過(guò)程...
|
(1) 調(diào)用了 log4j.info() 或是log4j.debug()方法...
(2) 建立一個(gè)Throwable的對(duì)象來(lái)取得當(dāng)前運(yùn)行堆棧的快照...Throwable.fillInStackTrace();
(3) 從拋出的Throwable對(duì)象中，來(lái)分析出當(dāng)前l(fā)og信息的行號(hào)...
|
但是，請(qǐng)注意，建立運(yùn)行時(shí)的堆棧跟蹤，對(duì)JVM來(lái)說(shuō)，是一項(xiàng)非常大的開(kāi)銷！
所以，我們?cè)谂渲胠og4j的PatternLayout的時(shí)候，如果是要上線到正式環(huán)境，一定要記者把參數(shù)關(guān)掉，
一般來(lái)說(shuō)，用 “%d %-5p %m%n" 就可以滿足了！
原文請(qǐng)參考這里：
Sneaky, sneaky Log4J===========================================================================
補(bǔ)遺篇~~~~~！
log4j.rootLogger=info, Console
log4j.logger.net.csdn.blog.xport=debug, RollingFile
log4j.additivity.net.csdn.blog.xport=false
log4j.appender.Console=org.apache.log4j.ConsoleAppender
log4j.appender.Console.layout=org.apache.log4j.PatternLayout
log4j.appender.Console.layout.ConversionPattern=%d %-5p [%t] %C{1} - %m%n
log4j.appender.RollingFile=org.apache.log4j.RollingFileAppender
log4j.appender.RollingFile.File=SystemLog.log
log4j.appender.RollingFile.Append=true
log4j.appender.RollingFile.MaxFileSize=2MB
log4j.appender.RollingFile.layout=org.apache.log4j.PatternLayout
log4j.appender.RollingFile.layout.ConversionPattern=%d %-5p [%t] %C{1} - %m%n