log4j 与 logback 性能对比

代码来自 : logback performance clarification
机器配置
Win XP SP3
双核 Intel(R) Core(TM)2 Duo CPU E7400 @ 2.80GHz
2GB内存
结果:

引用

###################### loop 10w #########################
Log4j direct debug call: 250
Log4j direct warn call: 44381
Log4j tested (isDebugEnabled) debug call: 17
###############################################
###################### loop 100w # perfiles 500KB 2000files#########################
Log4j direct debug call: 250
Log4j direct warn call: 45664
Log4j tested (isDebugEnabled) debug call: 13
###############################################
######################## loop 100w # perfiles 500KB 2000files#######################
Log4j direct debug call: 239
Log4j direct warn call: 62462
Log4j tested (isDebugEnabled) debug call: 13
###############################################
################### loop 1000000 # perfiles 10MB 2000files############################
Log4j direct debug call: 236
Log4j direct warn call: 44482
Log4j tested (isDebugEnabled) debug call: 13
###############################################
################### loop 1000000 # perfiles 100MB 2000files############################
Log4j direct debug call: 240
Log4j direct warn call: 44305
Log4j tested (isDebugEnabled) debug call: 13
###############################################
################### loop 1000000 # perfiles 200MB 2000files############################
Log4j direct debug call: 236
Log4j direct warn call: 45445
Log4j tested (isDebugEnabled) debug call: 13
###############################################

########################### 异步模式 ########################################
################### loop 100000 #ASYNC 10线程 500KB 2000files############################
Log4j direct debug call: 938
Log4j direct warn call: 623017
Log4j tested (isDebugEnabled) debug call: 12
###############################################
################### loop 100000 #ASYNC 10线程 500KB 2000files############################
Log4j direct debug call: 989
Log4j direct warn call: 636342
Log4j tested (isDebugEnabled) debug call: 12
###############################################
################### loop 100000 #ASYNC 10线程 10MB 2000files############################
Log4j direct debug call: 954
Log4j direct warn call: 498409
Log4j tested (isDebugEnabled) debug call: 12
###############################################
################### loop 100000 #ASYNC 10线程 100MB 2000files############################
Log4j direct debug call: 961
Log4j direct warn call: 517658
Log4j tested (isDebugEnabled) debug call: 12
###############################################
################### loop 100000 #ASYNC 10线程 200MB 2000files############################
Log4j direct debug call: 994
Log4j direct warn call: 521485
Log4j tested (isDebugEnabled) debug call: 11
###############################################



##################### loop 10w ##########################
Logback direct debug call: 224
Logback direct warn call: 10649
Logback tested (isDebugEnabled) debug call: 16
Logback tested (isWarnEnabled) warn call: 10754
Logback parametrized debug call: 25
Logback parametrized warn call: 11544
###############################################
###############################################
Logback direct debug call: 205
Logback direct warn call: 10634
Logback tested (isDebugEnabled) debug call: 12
Logback tested (isWarnEnabled) warn call: 10886
Logback parametrized debug call: 20
Logback parametrized warn call: 11521
###############################################
################## loop 1000000 # perfiles 500KB and zip#############################
Logback direct debug call: 214
Logback direct warn call: 22342
Logback tested (isDebugEnabled) debug call: 11
Logback tested (isWarnEnabled) warn call: 11079
Logback parametrized debug call: 22
Logback parametrized warn call: 11959
###############################################
################## loop 1000000 # perfiles 500KB and zip#############################
Logback direct debug call: 203
Logback direct warn call: 10297
Logback tested (isDebugEnabled) debug call: 11
Logback tested (isWarnEnabled) warn call: 10585
Logback parametrized debug call: 20
Logback parametrized warn call: 10884
###############################################
################## loop 1000000 # perfiles 200MB and zip#############################
Logback direct debug call: 206
Logback direct warn call: 8472
Logback tested (isDebugEnabled) debug call: 12
Logback tested (isWarnEnabled) warn call: 8600
Logback parametrized debug call: 21
Logback parametrized warn call: 8707
###############################################
################## loop 1000000 # perfiles 100MB and zip#############################
Logback direct debug call: 395
Logback direct warn call: 40629
Logback tested (isDebugEnabled) debug call: 11
Logback tested (isWarnEnabled) warn call: 40417
Logback parametrized debug call: 20
Logback parametrized warn call: 40687
Count Times(ms) : 122156
###############################################
################## loop 1000000 # perfiles 100MB and zip#############################
Logback direct debug call: 236
Logback direct warn call: 8489
Logback tested (isDebugEnabled) debug call: 11
Logback tested (isWarnEnabled) warn call: 8535
Logback parametrized debug call: 21
Logback parametrized warn call: 9042
###############################################
################## loop 1000000 # perfiles 50MB and zip#############################
Logback direct debug call: 217
Logback direct warn call: 8508
Logback tested (isDebugEnabled) debug call: 11
Logback tested (isWarnEnabled) warn call: 8695
Logback parametrized debug call: 20
Logback parametrized warn call: 8757
###############################################
################## loop 1000000 # perfiles 10MB and zip#############################
Logback direct debug call: 208
Logback direct warn call: 8499
Logback tested (isDebugEnabled) debug call: 12
Logback tested (isWarnEnabled) warn call: 8578
Logback parametrized debug call: 22
Logback parametrized warn call: 8741
###############################################
################## loop 100000 # jdbc(c3p0) #############################
Logback direct debug call: 355
Logback direct warn call: 3937399
Logback tested (isDebugEnabled) debug call: 27
Logback tested (isWarnEnabled) warn call: 4040561
Logback parametrized debug call: 40
Logback parametrized warn call: 4004411
###############################################
################## loop 2000000 # 1 Main Thread 100MB #per LogSize 286 Byte#############################
Logback direct debug call: 406
Logback direct warn call: 40168
Logback tested (isDebugEnabled) debug call: 11
Logback tested (isWarnEnabled) warn call: 40212
Logback parametrized debug call: 20
Logback parametrized warn call: 40562
Count Times(ms) : 242765
###############################################
################## loop 1000000 # 1 Main Thread 100MB #per LogSize 286 Byte#############################
Logback direct debug call: 389
Logback direct warn call: 39958
Logback tested (isDebugEnabled) debug call: 11
Logback tested (isWarnEnabled) warn call: 40440
Logback parametrized debug call: 20
Logback parametrized warn call: 40335
Count Times(ms) : 121156
###############################################
################## loop 1000000 # socket 1 client 1 Main Thread 100MB #per LogSize 286 Byte#############################
Logback direct debug call: 401
Logback direct warn call: 117924
Logback tested (isDebugEnabled) debug call: 11
Logback tested (isWarnEnabled) warn call: 118562
Logback parametrized debug call: 19
Logback parametrized warn call: 128202
Count Times(ms) : 365110
###############################################
################## loop 100000 # socket 1 client 1 Main Thread 100MB #per LogSize 286 Byte#############################
Logback direct debug call: 148301
Logback direct warn call: 152806
Logback tested (isDebugEnabled) debug call: 150971
Logback tested (isWarnEnabled) warn call: 141062
Logback parametrized debug call: 158658
Logback parametrized warn call: 153140
Count Times(ms) : 90500
###############################################
################## loop 1000000 # socket 1 client 1 Main Thread 100MB #per LogSize 286 Byte#############################
Logback direct debug call: 388
Logback direct warn call: 144331
Logback tested (isDebugEnabled) debug call: 10
Logback tested (isWarnEnabled) warn call: 96334
Logback parametrized debug call: 20
Logback parametrized warn call: 92826
Count Times(ms) : 333907
###############################################
################## loop 1000000 # socket 1 client 1 Main Thread 100MB #per LogSize 286 Byte#############################
Logback direct debug call: 392
Logback direct warn call: 166776
Logback tested (isDebugEnabled) debug call: 11
Logback tested (isWarnEnabled) warn call: 216869
Logback parametrized debug call: 141
Logback parametrized warn call: 156749
Count Times(ms) : 540969
###############################################


################## loop 100000 # 10thread 20queueSize 500KB and zip #per LogSize 286 Byte#############################
Logback direct debug call: 885
Logback direct warn call: 451863
Logback tested (isDebugEnabled) debug call: 9
Logback tested (isWarnEnabled) warn call: 451750
Logback parametrized debug call: 21
Logback parametrized warn call: 449805
###############################################
################## loop 100000 # 10 Thread 20 QueueSize 10MB and zip #per LogSize 286 Byte#############################
Logback direct debug call: 918
Logback direct warn call: 450027
Logback tested (isDebugEnabled) debug call: 9
Logback tested (isWarnEnabled) warn call: 452299
Logback parametrized debug call: 22
Logback parametrized warn call: 437268
###############################################
################## loop 100000 # 10 Thread 20 QueueSize 100MB and zip #per LogSize 286 Byte#############################
Logback direct debug call: 808
Logback direct warn call: 445219
Logback tested (isDebugEnabled) debug call: 10
Logback tested (isWarnEnabled) warn call: 449316
Logback parametrized debug call: 20
Logback parametrized warn call: 431124
###############################################
################## loop 100000 # 10 Thread 20 QueueSize 200MB and zip #per LogSize 286 Byte#############################
Logback direct debug call: 851
Logback direct warn call: 445867
Logback tested (isDebugEnabled) debug call: 10
Logback tested (isWarnEnabled) warn call: 450838
Logback parametrized debug call: 21
Logback parametrized warn call: 440028
###############################################
################## loop 100000 # 50 Thread 50 QueueSize 100MB and zip #per LogSize 286 Byte#############################
Logback direct debug call: 8719
Logback direct warn call: 2358846
Logback tested (isDebugEnabled) debug call: 10
Logback tested (isWarnEnabled) warn call: 2346821
Logback parametrized debug call: 20
Logback parametrized warn call: 2329942
###############################################
################## loop 100000 # 4 Thread 20 QueueSize 100MB and zip #per LogSize 286 Byte#############################
Logback direct debug call: 1299
Logback direct warn call: 195412
Logback tested (isDebugEnabled) debug call: 14
Logback tested (isWarnEnabled) warn call: 194969
Logback parametrized debug call: 24
Logback parametrized warn call: 192029
###############################################
################## loop 100000 # 3 Thread 20 QueueSize 100MB and zip #per LogSize 286 Byte#############################
Logback direct debug call: 915
Logback direct warn call: 145027
Logback tested (isDebugEnabled) debug call: 11
Logback tested (isWarnEnabled) warn call: 140793
Logback parametrized debug call: 21
Logback parametrized warn call: 143145
###############################################
################## loop 100000 # 2 Thread 20 QueueSize 100MB and zip #per LogSize 286 Byte#############################
Logback direct debug call: 961
Logback direct warn call: 95941
Logback tested (isDebugEnabled) debug call: 10
Logback tested (isWarnEnabled) warn call: 96473
Logback parametrized debug call: 20
Logback parametrized warn call: 96345
###############################################
################## loop 100000 # 1 Thread 20 QueueSize 100MB and zip #per LogSize 286 Byte#############################
Logback direct debug call: 414
Logback direct warn call: 44408
Logback tested (isDebugEnabled) debug call: 9
Logback tested (isWarnEnabled) warn call: 44709
Logback parametrized debug call: 18
Logback parametrized warn call: 45287
###############################################
################## loop 100000 # 1 Thread 20 QueueSize 100MB #per LogSize 286 Byte#############################
Logback direct debug call: 403
Logback direct warn call: 43233
Logback tested (isDebugEnabled) debug call: 10
Logback tested (isWarnEnabled) warn call: 43490
Logback parametrized debug call: 19
Logback parametrized warn call: 43935
Count Times(ms) : 270000
###############################################
################## loop 100000 # 1 Thread 20 QueueSize 100MB #per LogSize 286 Byte#############################
Logback direct debug call: 410
Logback direct warn call: 43172
Logback tested (isDebugEnabled) debug call: 9
Logback tested (isWarnEnabled) warn call: 43314
Logback parametrized debug call: 19
Logback parametrized warn call: 43751
Count Times(ms) : 283812
###############################################
################## loop 100000 # 2 Thread 20 QueueSize 100MB #per LogSize 286 Byte#############################
Logback direct debug call: 511
Logback direct warn call: 92970
Logback tested (isDebugEnabled) debug call: 12
Logback tested (isWarnEnabled) warn call: 90352
Logback parametrized debug call: 20
Logback parametrized warn call: 92044
Count Times(ms) : 304656
###############################################
################## loop 100000 # 3 Thread 20 QueueSize 100MB #per LogSize 286 Byte#############################
Logback direct debug call: 619
Logback direct warn call: 136300
Logback tested (isDebugEnabled) debug call: 10
Logback tested (isWarnEnabled) warn call: 146003
Logback parametrized debug call: 20
Logback parametrized warn call: 109449
Count Times(ms) : 294078
###############################################
################## loop 100000 # 4 Thread 20 QueueSize 100MB #per LogSize 286 Byte#############################
Logback direct debug call: 702
Logback direct warn call: 171259
Logback tested (isDebugEnabled) debug call: 11
Logback tested (isWarnEnabled) warn call: 178437
Logback parametrized debug call: 21
Logback parametrized warn call: 187121
Count Times(ms) : 293609
###############################################

log4j测试代码

Java代码

1. /**
2. *$Id$
3. */
4. packageperfTest.ch.qos.logback;
6. importorg.apache.log4j.Logger;
7. importorg.apache.log4j.xml.DOMConfigurator;
9. /**
10. *@authorLastchangedby:$Author$
11. *@version$Revision$$Date$
12. */
13. publicclassPerformanceLog4j{
14. staticLoggerlog4jlogger=Logger.getLogger(PerformanceLog4j.class);
16. //Howmanytimesshouldwetrytolog:
17. staticintloop=1000000;
19. publicstaticvoidmain(String[]args)throwsInterruptedException{
20. initConfig();
22. //Let'srunonceforJustInTimecompiler
23. log4jDirectDebugCall();
24. log4jDirectWarnCall();
25. log4jTestedDebugCall();
27. //let'srunthetestsanddisplaytheresults:
28. longresult1=log4jDirectDebugCall();
29. longresult2=log4jDirectWarnCall();
30. longresult3=log4jTestedDebugCall();
32. System.out.println("###################loop"+loop+"############################");
33. System.out.println("Log4jdirectdebugcall:"+result1);
34. System.out.println("Log4jdirectwarncall:"+result2);
35. System.out.println("Log4jtested(isDebugEnabled)debugcall:"+result3);
36. System.out.println("###############################################");
37. }
39. privatestaticlonglog4jDirectDebugCall(){
40. Integerj=newInteger(1);
41. longstart=System.nanoTime();
42. for(inti=0;i<loop;i++){
43. log4jlogger.debug("SEEIFTHISISLOGGEDlog4jDirectDebugCall()"+j+".");
44. }
45. return(System.nanoTime()-start)/loop;
46. }
48. privatestaticlonglog4jDirectWarnCall(){
49. Integerj=newInteger(2);
50. longstart=System.nanoTime();
51. for(inti=0;i<loop;i++){
52. log4jlogger.warn("SEEIFTHISISLOGGEDlog4jDirectWarnCall()"+j+".");
53. }
54. return(System.nanoTime()-start)/loop;
55. }
57. privatestaticlonglog4jTestedDebugCall(){
58. Integerj=newInteger(3);
59. longstart=System.nanoTime();
60. for(inti=0;i<loop;i++){
61. if(log4jlogger.isDebugEnabled()){
62. log4jlogger.debug("SEEIFTHISISLOGGEDlog4jTestedDebugCall()"+j+".");
63. }
64. }
65. return(System.nanoTime()-start)/loop;
66. }
68. privatestaticvoidinitConfig(){
69. DOMConfigurator.configure("src/main/resource/log4j.xml");
71. //createtheloggers
72. org.apache.log4j.Logger.getLogger("perfTest");
73. org.apache.log4j.Logger.getLogger("perfTest.ch");
74. org.apache.log4j.Logger.getLogger("perfTest.ch.qos");
75. org.apache.log4j.Logger.getLogger("perfTest.ch.qos.logback");
76. }
77. }

/** * $Id$ */ package perfTest.ch.qos.logback; import org.apache.log4j.Logger; import org.apache.log4j.xml.DOMConfigurator; /** * @author Last changed by: $Author$ * @version $Revision$ $Date$ */ public class PerformanceLog4j { static Logger log4jlogger = Logger.getLogger(PerformanceLog4j.class); // How many times should we try to log: static int loop = 1000000; public static void main(String[] args) throws InterruptedException { initConfig(); // Let's run once for Just In Time compiler log4jDirectDebugCall(); log4jDirectWarnCall(); log4jTestedDebugCall(); // let's run the tests and display the results: long result1 = log4jDirectDebugCall(); long result2 = log4jDirectWarnCall(); long result3 = log4jTestedDebugCall(); System.out.println("################### loop " + loop + " ############################"); System.out.println("Log4j direct debug call: " + result1); System.out.println("Log4j direct warn call: " + result2); System.out.println("Log4j tested (isDebugEnabled) debug call: " + result3); System.out.println("###############################################"); } private static long log4jDirectDebugCall() { Integer j = new Integer(1); long start = System.nanoTime(); for (int i = 0; i < loop; i++) { log4jlogger.debug("SEE IF THIS IS LOGGED log4jDirectDebugCall() " + j + "."); } return (System.nanoTime() - start) / loop; } private static long log4jDirectWarnCall() { Integer j = new Integer(2); long start = System.nanoTime(); for (int i = 0; i < loop; i++) { log4jlogger.warn("SEE IF THIS IS LOGGED log4jDirectWarnCall()" + j + "."); } return (System.nanoTime() - start) / loop; } private static long log4jTestedDebugCall() { Integer j = new Integer(3); long start = System.nanoTime(); for (int i = 0; i < loop; i++) { if (log4jlogger.isDebugEnabled()) { log4jlogger.debug("SEE IF THIS IS LOGGED log4jTestedDebugCall() " + j + "."); } } return (System.nanoTime() - start) / loop; } private static void initConfig() { DOMConfigurator.configure("src/main/resource/log4j.xml"); // create the loggers org.apache.log4j.Logger.getLogger("perfTest"); org.apache.log4j.Logger.getLogger("perfTest.ch"); org.apache.log4j.Logger.getLogger("perfTest.ch.qos"); org.apache.log4j.Logger.getLogger("perfTest.ch.qos.logback"); } }

Xml代码

1. <?xmlversion="1.0"encoding="UTF-8"?>
2. <!DOCTYPElog4j:configurationSYSTEM"log4j.dtd">
4. <log4j:configurationxmlns:log4j='http://jakarta.apache.org/log4j/'>
6. <!--<appendername="ASYNC"class="org.apache.log4j.AsyncAppender">-->
7. <!--<appender-refref="STDOUT"/>-->
8. <!--</appender>-->
10. <appendername="STDOUT"class="org.apache.log4j.ConsoleAppender">
11. <layoutclass="org.apache.log4j.PatternLayout">
12. <paramname="ConversionPattern"value="%d(%t)[%24F:%-3L:%-5p]%x%m%n"/>
13. </layout>
14. </appender>
16. <appendername="FILE"class="org.apache.log4j.RollingFileAppender">
17. <paramname="File"value="logs/log4j-test.log"/>
18. <paramname="BufferSize"value="8129"/>
19. <paramname="Append"value="true"/>
20. <paramname="MaxFileSize"value="200MB"/>
21. <paramname="MaxBackupIndex"value="2000"/>
22. <layoutclass="org.apache.log4j.PatternLayout">
23. <paramname="ConversionPattern"value="%d(%t)[%24F:%-3L:%-5p]%x%m%n"/>
24. </layout>
25. </appender>
28. <root>
29. <priorityvalue="INFO"/>
30. <!--<appender-refref="STDOUT"/>-->
31. <appender-refref="FILE"/>
32. </root>
34. </log4j:configuration>

<?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE log4j:configuration SYSTEM "log4j.dtd"> <log4j:configuration xmlns:log4j='http://jakarta.apache.org/log4j/'> <!-- <appender name="ASYNC" class="org.apache.log4j.AsyncAppender">--> <!-- <appender-ref ref="STDOUT" />--> <!-- </appender>--> <appender name="STDOUT" class="org.apache.log4j.ConsoleAppender"> <layout class="org.apache.log4j.PatternLayout"> <param name="ConversionPattern" value="%d (%t) [%24F:%-3L:%-5p]%x %m%n" /> </layout> </appender> <appender name="FILE" class="org.apache.log4j.RollingFileAppender"> <param name="File" value="logs/log4j-test.log" /> <param name="BufferSize" value="8129" /> <param name="Append" value="true" /> <param name="MaxFileSize" value="200MB"/> <param name="MaxBackupIndex" value="2000"/> <layout class="org.apache.log4j.PatternLayout"> <param name="ConversionPattern" value="%d (%t) [%24F:%-3L:%-5p]%x %m%n" /> </layout> </appender> <root> <priority value="INFO" /> <!-- <appender-ref ref="STDOUT" />--> <appender-ref ref="FILE"/> </root> </log4j:configuration>

logback测试代码

Java代码

1. /**
2. *$Id$
3. */
4. packageperfTest.ch.qos.logback;
6. importorg.slf4j.Logger;
7. importorg.slf4j.LoggerFactory;
9. importch.qos.logback.classic.LoggerContext;
10. importch.qos.logback.classic.joran.JoranConfigurator;
11. importch.qos.logback.core.joran.spi.JoranException;
12. importch.qos.logback.core.util.StatusPrinter;
14. /**
15. *@authorLastchangedby:$Author$
16. *@version$Revision$$Date$
17. */
18. publicclassPerformanceLogback{
19. staticLoggerlogbacklogger=LoggerFactory.getLogger(PerformanceLogback.class);
21. //Howmanytimesshouldwetrytolog:
22. staticintloop=1000000;
24. publicstaticvoidmain(String[]args)throwsInterruptedException{
25. //initConfig();
27. //Let'srunonceforJustInTimecompiler
28. logbackDirectDebugCall();
29. logbackDirectWarnCall();
30. logbackTestedDebugCall();
31. logbackTestedWarnCall();
32. logbackParametrizedDebugCall();
33. logbackParametrizedWarnCall();
35. //let'srunthetestsanddisplaytheresults:
36. longresult4=logbackDirectDebugCall();
37. longresult5=logbackDirectWarnCall();
38. longresult6=logbackTestedDebugCall();
39. longresult7=logbackTestedWarnCall();
40. longresult8=logbackParametrizedDebugCall();
41. longresult9=logbackParametrizedWarnCall();
43. System.out.println("##################loop"+loop+"#############################");
44. System.out.println("Logbackdirectdebugcall:"+result4);
45. System.out.println("Logbackdirectwarncall:"+result5);
46. System.out.println("Logbacktested(isDebugEnabled)debugcall:"+result6);
47. System.out.println("Logbacktested(isWarnEnabled)warncall:"+result7);
48. System.out.println("Logbackparametrizeddebugcall:"+result8);
49. System.out.println("Logbackparametrizedwarncall:"+result9);
50. System.out.println("###############################################");
51. }
53. privatestaticlonglogbackDirectDebugCall(){
54. Integerj=newInteger(5);
55. longstart=System.nanoTime();
56. for(inti=0;i<loop;i++){
57. logbacklogger.debug("SEEIFTHISISLOGGED"+j+".");
58. }
59. return(System.nanoTime()-start)/loop;
60. }
62. privatestaticlonglogbackDirectWarnCall(){
63. Integerj=newInteger(6);
64. longstart=System.nanoTime();
65. for(inti=0;i<loop;i++){
66. logbacklogger.warn("SEEIFTHISISLOGGEDlogbackDirectWarnCall()"+j+".");
67. }
68. return(System.nanoTime()-start)/loop;
69. }
71. privatestaticlonglogbackTestedDebugCall(){
72. Integerj=newInteger(7);
73. longstart=System.nanoTime();
74. for(inti=0;i<loop;i++){
75. if(logbacklogger.isDebugEnabled())
76. logbacklogger.debug("SEEIFTHISISLOGGED"+j+".");
77. }
78. return(System.nanoTime()-start)/loop;
79. }
81. privatestaticlonglogbackTestedWarnCall(){
82. Integerj=newInteger(8);
83. longstart=System.nanoTime();
84. for(inti=0;i<loop;i++){
85. if(logbacklogger.isWarnEnabled())
86. logbacklogger.warn("SEEIFTHISISLOGGEDlogbackTestedWarnCall()"+j+".");
87. }
88. longend=System.nanoTime();
89. longresult=(end-start)/loop;
90. System.out.println("start="+start+"end="+end+"result="+result);
91. returnresult;
92. }
94. privatestaticlonglogbackParametrizedDebugCall(){
95. Integerj=newInteger(9);
96. longstart=System.nanoTime();
97. for(inti=0;i<loop;i++){
98. logbacklogger.debug("SEEIFTHISISLOGGED{}.",j);
99. }
100. return(System.nanoTime()-start)/loop;
101. }
103. privatestaticlonglogbackParametrizedWarnCall(){
104. Integerj=newInteger(10);
105. longstart=System.nanoTime();
106. for(inti=0;i<loop;i++){
107. logbacklogger.warn("SEEIFTHISISLOGGEDlogbackParametrizedWarnCall(){}.",j);
108. }
109. longend=System.nanoTime();
110. longresult=(end-start)/loop;
111. System.out.println("start="+start+"end="+end+"result="+result);
112. returnresult;
113. }
115. privatestaticvoidinitConfig(){
116. //assumeSLF4Jisboundtologbackinthecurrentenvironment
117. LoggerContextlc=(LoggerContext)LoggerFactory.getILoggerFactory();
119. try{
120. JoranConfiguratorconfigurator=newJoranConfigurator();
121. configurator.setContext(lc);
122. //thecontextwasprobablyalreadyconfiguredbydefaultconfiguration
123. //rules
124. lc.reset();
125. configurator.doConfigure("src/main/resource/logback.xml");
126. }catch(JoranExceptionje){
127. //StatusPrinterwillhandlethis
128. }
129. StatusPrinter.printInCaseOfErrorsOrWarnings(lc);
131. //createtheloggers
132. LoggerFactory.getLogger("perfTest");
133. LoggerFactory.getLogger("perfTest.ch");
134. LoggerFactory.getLogger("perfTest.ch.qos");
135. LoggerFactory.getLogger("perfTest.ch.qos.logback");
136. }
137. }

/** * $Id$ */ package perfTest.ch.qos.logback; import org.slf4j.Logger; import org.slf4j.LoggerFactory; import ch.qos.logback.classic.LoggerContext; import ch.qos.logback.classic.joran.JoranConfigurator; import ch.qos.logback.core.joran.spi.JoranException; import ch.qos.logback.core.util.StatusPrinter; /** * @author Last changed by: $Author$ * @version $Revision$ $Date$ */ public class PerformanceLogback { static Logger logbacklogger = LoggerFactory.getLogger(PerformanceLogback.class); // How many times should we try to log: static int loop = 1000000; public static void main(String[] args) throws InterruptedException { // initConfig(); // Let's run once for Just In Time compiler logbackDirectDebugCall(); logbackDirectWarnCall(); logbackTestedDebugCall(); logbackTestedWarnCall(); logbackParametrizedDebugCall(); logbackParametrizedWarnCall(); // let's run the tests and display the results: long result4 = logbackDirectDebugCall(); long result5 = logbackDirectWarnCall(); long result6 = logbackTestedDebugCall(); long result7 = logbackTestedWarnCall(); long result8 = logbackParametrizedDebugCall(); long result9 = logbackParametrizedWarnCall(); System.out.println("################## loop " + loop + " #############################"); System.out.println("Logback direct debug call: " + result4); System.out.println("Logback direct warn call: " + result5); System.out.println("Logback tested (isDebugEnabled) debug call: " + result6); System.out.println("Logback tested (isWarnEnabled) warn call: " + result7); System.out.println("Logback parametrized debug call: " + result8); System.out.println("Logback parametrized warn call: " + result9); System.out.println("###############################################"); } private static long logbackDirectDebugCall() { Integer j = new Integer(5); long start = System.nanoTime(); for (int i = 0; i < loop; i++) { logbacklogger.debug("SEE IF THIS IS LOGGED " + j + "."); } return (System.nanoTime() - start) / loop; } private static long logbackDirectWarnCall() { Integer j = new Integer(6); long start = System.nanoTime(); for (int i = 0; i < loop; i++) { logbacklogger.warn("SEE IF THIS IS LOGGED logbackDirectWarnCall()" + j + "."); } return (System.nanoTime() - start) / loop; } private static long logbackTestedDebugCall() { Integer j = new Integer(7); long start = System.nanoTime(); for (int i = 0; i < loop; i++) { if (logbacklogger.isDebugEnabled()) logbacklogger.debug("SEE IF THIS IS LOGGED " + j + "."); } return (System.nanoTime() - start) / loop; } private static long logbackTestedWarnCall() { Integer j = new Integer(8); long start = System.nanoTime(); for (int i = 0; i < loop; i++) { if (logbacklogger.isWarnEnabled()) logbacklogger.warn("SEE IF THIS IS LOGGED logbackTestedWarnCall()" + j + "."); } long end = System.nanoTime(); long result = (end - start) / loop; System.out.println("start = " + start + "end = " + end + "result = " + result); return result; } private static long logbackParametrizedDebugCall() { Integer j = new Integer(9); long start = System.nanoTime(); for (int i = 0; i < loop; i++) { logbacklogger.debug("SEE IF THIS IS LOGGED {}.", j); } return (System.nanoTime() - start) / loop; } private static long logbackParametrizedWarnCall() { Integer j = new Integer(10); long start = System.nanoTime(); for (int i = 0; i < loop; i++) { logbacklogger.warn("SEE IF THIS IS LOGGED logbackParametrizedWarnCall() {}.", j); } long end = System.nanoTime(); long result = (end - start) / loop; System.out.println("start = " + start + "end = " + end + "result = " + result); return result; } private static void initConfig() { // assume SLF4J is bound to logback in the current environment LoggerContext lc = (LoggerContext) LoggerFactory.getILoggerFactory(); try { JoranConfigurator configurator = new JoranConfigurator(); configurator.setContext(lc); // the context was probably already configured by default configuration // rules lc.reset(); configurator.doConfigure("src/main/resource/logback.xml"); } catch (JoranException je) { // StatusPrinter will handle this } StatusPrinter.printInCaseOfErrorsOrWarnings(lc); // create the loggers LoggerFactory.getLogger("perfTest"); LoggerFactory.getLogger("perfTest.ch"); LoggerFactory.getLogger("perfTest.ch.qos"); LoggerFactory.getLogger("perfTest.ch.qos.logback"); } }

Xml代码

1. <?xmlversion="1.0"encoding="UTF-8"?>
2. <configuration>
3. <propertyfile="src/main/resource/jdbc.properties"/>
5. <appendername="DB"class="ch.qos.logback.classic.db.DBAppender">
6. <connectionSourceclass="ch.qos.logback.core.db.DataSourceConnectionSource">
7. <dataSourceclass="com.mchange.v2.c3p0.ComboPooledDataSource">
8. <driverClass>${jdbc.driver.class}</driverClass>
9. <jdbcUrl>${jdbc.url}</jdbcUrl>
10. <!--<serverName>${serverName}</serverName>-->
11. <!--<databaseName>${databaseName}</databaseName>-->
12. <user>${jdbc.username}</user>
13. <password>${jdbc.password}</password>
14. <paramname="minPoolSize"value="30"/>
15. <paramname="maxPoolSize"value="60"/>
16. <paramname="initialPoolSize"value="30"/>
17. <paramname="acquireIncrement"value="3"/>
18. <paramname="acquireRetryAttempts"value="30"/>
19. <paramname="acquireRetryDelay"value="1000"/>
20. <paramname="autoCommitOnClose"value="false"/>
21. <paramname="breakAfterAcquireFailure"value="false"/>
22. <paramname="checkoutTimeout"value="0"/>
23. <paramname="maxIdleTime"value="60"/>
24. <paramname="maxStatements"value="100"/>
25. </dataSource>
27. </connectionSource>
28. </appender>
29. <appendername="FILE"class="ch.qos.logback.core.rolling.RollingFileAppender">
30. <file>logs/logback-test.log</file>
31. <rollingPolicyclass="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
32. <!--rolloverdaily-->
33. <fileNamePattern>logs/logback-%d{yyyy-MM-dd}.%i.zip</fileNamePattern>
34. <timeBasedFileNamingAndTriggeringPolicyclass="ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP">
35. <!--orwheneverthefilesizereaches100MB-->
36. <maxFileSize>10MB</maxFileSize>
37. </timeBasedFileNamingAndTriggeringPolicy>
38. </rollingPolicy>
39. <encoder>
40. <pattern>%date{ISO8601}[%thread][%24file:%M:%-3line:%-5level]-%msg%n</pattern>
41. </encoder>
42. </appender>
44. <rootlevel="INFO">
45. <!--<appender-refref="DB"/>-->
46. <appender-refref="FILE"/>
47. </root>
48. </configuration>

<?xml version="1.0" encoding="UTF-8" ?> <configuration> <property file="src/main/resource/jdbc.properties" /> <appender name="DB" class="ch.qos.logback.classic.db.DBAppender"> <connectionSource class="ch.qos.logback.core.db.DataSourceConnectionSource"> <dataSource class="com.mchange.v2.c3p0.ComboPooledDataSource"> <driverClass>${jdbc.driver.class}</driverClass> <jdbcUrl>${jdbc.url}</jdbcUrl> <!-- <serverName>${serverName}</serverName>--> <!-- <databaseName>${databaseName}</databaseName>--> <user>${jdbc.username}</user> <password>${jdbc.password}</password> <param name="minPoolSize" value="30" /> <param name="maxPoolSize" value="60" /> <param name="initialPoolSize" value="30" /> <param name="acquireIncrement" value="3" /> <param name="acquireRetryAttempts" value="30" /> <param name="acquireRetryDelay" value="1000" /> <param name="autoCommitOnClose" value="false" /> <param name="breakAfterAcquireFailure" value="false" /> <param name="checkoutTimeout" value="0" /> <param name="maxIdleTime" value="60" /> <param name="maxStatements" value="100" /> </dataSource> </connectionSource> </appender> <appender name="FILE" class="ch.qos.logback.core.rolling.RollingFileAppender"> <file>logs/logback-test.log</file> <rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy"> <!-- rollover daily --> <fileNamePattern>logs/logback-%d{yyyy-MM-dd}.%i.zip</fileNamePattern> <timeBasedFileNamingAndTriggeringPolicy class="ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP"> <!-- or whenever the file size reaches 100MB --> <maxFileSize>10MB</maxFileSize> </timeBasedFileNamingAndTriggeringPolicy> </rollingPolicy> <encoder> <pattern>%date{ISO8601} [%thread] [%24file:%M:%-3line:%-5level] - %msg%n</pattern> </encoder> </appender> <root level="INFO"> <!-- <appender-ref ref="DB" />--> <appender-ref ref="FILE" /> </root> </configuration>
jdbc.properties

Properties代码

1. #mysqlconfig
2. jdbc.driver.class=com.mysql.jdbc.Driver
3. jdbc.url=jdbc:mysql://localhost/logs?autoReconnect=true&useUnicode=true&characterEncoding=utf8
4. jdbc.username=root
5. jdbc.password=root

# mysql config jdbc.driver.class=com.mysql.jdbc.Driver jdbc.url=jdbc:mysql://localhost/logs?autoReconnect=true&useUnicode=true&characterEncoding=utf8 jdbc.username=root jdbc.password=root

10线程模式

Java代码

1. /**
2. *$Id$
3. */
4. packageperfTest.ch.qos.logback;
6. importjava.util.concurrent.ConcurrentHashMap;
7. importjava.util.concurrent.ConcurrentMap;
8. importjava.util.concurrent.ExecutorService;
9. importjava.util.concurrent.Executors;
11. importorg.apache.log4j.Logger;
12. importorg.apache.log4j.xml.DOMConfigurator;
14. /**
15. *@authorLastchangedby:$Author$
16. *@version$Revision$$Date$
17. */
18. publicclassPerformanceLog4j{
20. //Howmanytimesshouldwetrytolog:
21. staticintloop=100000;
23. staticConcurrentMap<Integer,Long>result=newConcurrentHashMap<Integer,Long>();
25. staticintRESULT_1=1;
26. staticintRESULT_2=2;
27. staticintRESULT_3=3;
28. staticintcountThread=20;
30. publicPerformanceLog4j(){
32. }
34. publicvoidstart(){
35. initConfig();
36. result.put(RESULT_1,0L);
37. result.put(RESULT_2,0L);
38. result.put(RESULT_3,0L);
39. intcountT=countThread;
40. ExecutorServiceexecutor=Executors.newFixedThreadPool(10);
42. for(inti=0;i<countThread;i++){
43. executor.execute(newLog4jRun());
44. }
46. while(countThread>0){
47. try{
48. Thread.sleep(10000);
49. }catch(InterruptedExceptione){
50. e.printStackTrace();
51. }
52. }
53. executor.shutdown();
55. System.out.println("###################loop"+loop+"#ASYNC############################");
56. System.out.println("Log4jdirectdebugcall:"+result.get(RESULT_1)/countT);
57. System.out.println("Log4jdirectwarncall:"+result.get(RESULT_2)/countT);
58. System.out.println("Log4jtested(isDebugEnabled)debugcall:"+result.get(RESULT_3)/countT);
59. System.out.println("###############################################");
61. }
63. publicstaticvoidmain(String[]args)throwsInterruptedException{
64. newPerformanceLog4j().start();
65. }
67. privatestaticvoidinitConfig(){
68. DOMConfigurator.configure("src/main/resource/log4j.xml");
70. //createtheloggers
71. org.apache.log4j.Logger.getLogger("perfTest");
72. org.apache.log4j.Logger.getLogger("perfTest.ch");
73. org.apache.log4j.Logger.getLogger("perfTest.ch.qos");
74. org.apache.log4j.Logger.getLogger("perfTest.ch.qos.logback");
75. }
77. staticclassLog4jRunimplementsRunnable{
78. staticLoggerlog4jlogger=Logger.getLogger(Log4jRun.class);
80. static{
81. //Let'srunonceforJustInTimecompiler
82. log4jDirectDebugCall();
83. log4jDirectWarnCall();
84. log4jTestedDebugCall();
85. }
87. publicLog4jRun(){
89. }
91. /*
92. *(non-Javadoc)
93. *@seejava.lang.Runnable#run()
94. */
95. @Override
96. publicvoidrun(){
97. System.out.println("start"+Thread.currentThread());
98. //let'srunthetestsanddisplaytheresults:
99. longresult1=log4jDirectDebugCall();
100. longresult2=log4jDirectWarnCall();
101. longresult3=log4jTestedDebugCall();
102. synchronized(result){
103. result.put(RESULT_1,result.get(RESULT_1)+result1);
104. result.put(RESULT_2,result.get(RESULT_2)+result2);
105. result.put(RESULT_3,result.get(RESULT_3)+result3);
106. }
107. countThread--;
108. System.out.println("count"+countThread+"end"+Thread.currentThread());
109. }
111. privatestaticlonglog4jDirectDebugCall(){
112. Integerj=newInteger(1);
113. longstart=System.nanoTime();
114. for(inti=0;i<loop;i++){
115. log4jlogger.debug("SEEIFTHISISLOGGEDlog4jDirectDebugCall()"+j+".");
116. }
117. return(System.nanoTime()-start)/loop;
118. }
120. privatestaticlonglog4jDirectWarnCall(){
121. Integerj=newInteger(2);
122. longstart=System.nanoTime();
123. for(inti=0;i<loop;i++){
124. log4jlogger.warn("SEEIFTHISISLOGGEDlog4jDirectWarnCall()"+j+".");
125. }
126. return(System.nanoTime()-start)/loop;
127. }
129. privatestaticlonglog4jTestedDebugCall(){
130. Integerj=newInteger(3);
131. longstart=System.nanoTime();
132. for(inti=0;i<loop;i++){
133. if(log4jlogger.isDebugEnabled()){
134. log4jlogger.debug("SEEIFTHISISLOGGEDlog4jTestedDebugCall()"+j+".");
135. }
136. }
137. return(System.nanoTime()-start)/loop;
138. }
140. }
141. }

/** * $Id$ */ package perfTest.ch.qos.logback; import java.util.concurrent.ConcurrentHashMap; import java.util.concurrent.ConcurrentMap; import java.util.concurrent.ExecutorService; import java.util.concurrent.Executors; import org.apache.log4j.Logger; import org.apache.log4j.xml.DOMConfigurator; /** * @author Last changed by: $Author$ * @version $Revision$ $Date$ */ public class PerformanceLog4j { // How many times should we try to log: static int loop = 100000; static ConcurrentMap<Integer, Long> result = new ConcurrentHashMap<Integer, Long>(); static int RESULT_1 = 1; static int RESULT_2 = 2; static int RESULT_3 = 3; static int countThread = 20; public PerformanceLog4j() { } public void start() { initConfig(); result.put(RESULT_1, 0L); result.put(RESULT_2, 0L); result.put(RESULT_3, 0L); int countT = countThread; ExecutorService executor = Executors.newFixedThreadPool(10); for (int i = 0; i < countThread; i++) { executor.execute(new Log4jRun()); } while (countThread > 0) { try { Thread.sleep(10000); } catch (InterruptedException e) { e.printStackTrace(); } } executor.shutdown(); System.out.println("################### loop " + loop + " #ASYNC ############################"); System.out.println("Log4j direct debug call: " + result.get(RESULT_1) / countT); System.out.println("Log4j direct warn call: " + result.get(RESULT_2) / countT); System.out.println("Log4j tested (isDebugEnabled) debug call: " + result.get(RESULT_3) / countT); System.out.println("###############################################"); } public static void main(String[] args) throws InterruptedException { new PerformanceLog4j().start(); } private static void initConfig() { DOMConfigurator.configure("src/main/resource/log4j.xml"); // create the loggers org.apache.log4j.Logger.getLogger("perfTest"); org.apache.log4j.Logger.getLogger("perfTest.ch"); org.apache.log4j.Logger.getLogger("perfTest.ch.qos"); org.apache.log4j.Logger.getLogger("perfTest.ch.qos.logback"); } static class Log4jRun implements Runnable { static Logger log4jlogger = Logger.getLogger(Log4jRun.class); static { // Let's run once for Just In Time compiler log4jDirectDebugCall(); log4jDirectWarnCall(); log4jTestedDebugCall(); } public Log4jRun() { } /* * (non-Javadoc) * @see java.lang.Runnable#run() */ @Override public void run() { System.out.println("start " + Thread.currentThread()); // let's run the tests and display the results: long result1 = log4jDirectDebugCall(); long result2 = log4jDirectWarnCall(); long result3 = log4jTestedDebugCall(); synchronized (result) { result.put(RESULT_1, result.get(RESULT_1) + result1); result.put(RESULT_2, result.get(RESULT_2) + result2); result.put(RESULT_3, result.get(RESULT_3) + result3); } countThread--; System.out.println("count " + countThread + " end " + Thread.currentThread()); } private static long log4jDirectDebugCall() { Integer j = new Integer(1); long start = System.nanoTime(); for (int i = 0; i < loop; i++) { log4jlogger.debug("SEE IF THIS IS LOGGED log4jDirectDebugCall() " + j + "."); } return (System.nanoTime() - start) / loop; } private static long log4jDirectWarnCall() { Integer j = new Integer(2); long start = System.nanoTime(); for (int i = 0; i < loop; i++) { log4jlogger.warn("SEE IF THIS IS LOGGED log4jDirectWarnCall()" + j + "."); } return (System.nanoTime() - start) / loop; } private static long log4jTestedDebugCall() { Integer j = new Integer(3); long start = System.nanoTime(); for (int i = 0; i < loop; i++) { if (log4jlogger.isDebugEnabled()) { log4jlogger.debug("SEE IF THIS IS LOGGED log4jTestedDebugCall() " + j + "."); } } return (System.nanoTime() - start) / loop; } } }

Java代码

1. /**
2. *$Id$
3. */
4. packageperfTest.ch.qos.logback;
6. importjava.util.concurrent.ConcurrentHashMap;
7. importjava.util.concurrent.ConcurrentMap;
8. importjava.util.concurrent.ExecutorService;
9. importjava.util.concurrent.Executors;
11. importorg.slf4j.Logger;
12. importorg.slf4j.LoggerFactory;
14. /**
15. *@authorLastchangedby:$Author$
16. *@version$Revision$$Date$
17. */
18. publicclassPerformanceLogback{
20. //Howmanytimesshouldwetrytolog:
21. staticintloop=100000;
23. staticConcurrentMap<Integer,Long>result=newConcurrentHashMap<Integer,Long>();
25. staticintRESULT_4=1;
26. staticintRESULT_5=2;
27. staticintRESULT_6=3;
28. staticintRESULT_7=4;
29. staticintRESULT_8=5;
30. staticintRESULT_9=6;
31. staticintcountThread=20;
33. publicPerformanceLogback(){
35. }
37. publicvoidstart(){
38. result.put(RESULT_4,0L);
39. result.put(RESULT_5,0L);
40. result.put(RESULT_6,0L);
41. result.put(RESULT_7,0L);
42. result.put(RESULT_8,0L);
43. result.put(RESULT_9,0L);
45. intcountT=countThread;
46. ExecutorServiceexecutor=Executors.newFixedThreadPool(10);
48. for(inti=0;i<countThread;i++){
49. executor.execute(newLogbackRun());
50. }
52. while(countThread>0){
53. try{
54. Thread.sleep(10000);
55. }catch(InterruptedExceptione){
56. e.printStackTrace();
57. }
58. }
59. executor.shutdown();
61. System.out.println("##################loop"+loop+"#############################");
62. System.out.println("Logbackdirectdebugcall:"+result.get(RESULT_4)/countT);
63. System.out.println("Logbackdirectwarncall:"+result.get(RESULT_5)/countT);
64. System.out.println("Logbacktested(isDebugEnabled)debugcall:"+result.get(RESULT_6)/countT);
65. System.out.println("Logbacktested(isWarnEnabled)warncall:"+result.get(RESULT_7)/countT);
66. System.out.println("Logbackparametrizeddebugcall:"+result.get(RESULT_8)/countT);
67. System.out.println("Logbackparametrizedwarncall:"+result.get(RESULT_9)/countT);
68. System.out.println("###############################################");
69. }
71. publicstaticvoidmain(String[]args)throwsInterruptedException{
72. newPerformanceLogback().start();
73. }
75. staticclassLogbackRunimplementsRunnable{
76. staticLoggerlogbacklogger=LoggerFactory.getLogger(LogbackRun.class);
78. static{
79. //Let'srunonceforJustInTimecompiler
80. logbackDirectDebugCall();
81. logbackDirectWarnCall();
82. logbackTestedDebugCall();
83. logbackTestedWarnCall();
84. logbackParametrizedDebugCall();
85. logbackParametrizedWarnCall();
86. }
87. /*
88. *(non-Javadoc)
89. *@seejava.lang.Runnable#run()
90. */
91. @Override
92. publicvoidrun(){
93. System.out.println("start"+Thread.currentThread());
94. //let'srunthetestsanddisplaytheresults:
95. longresult4=logbackDirectDebugCall();
96. longresult5=logbackDirectWarnCall();
97. longresult6=logbackTestedDebugCall();
98. longresult7=logbackTestedWarnCall();
99. longresult8=logbackParametrizedDebugCall();
100. longresult9=logbackParametrizedWarnCall();
101. synchronized(result){
102. result.put(RESULT_4,result.get(RESULT_4)+result4);
103. result.put(RESULT_5,result.get(RESULT_5)+result5);
104. result.put(RESULT_6,result.get(RESULT_6)+result6);
105. result.put(RESULT_7,result.get(RESULT_7)+result7);
106. result.put(RESULT_8,result.get(RESULT_8)+result8);
107. result.put(RESULT_9,result.get(RESULT_9)+result9);
108. }
109. countThread--;
110. System.out.println("count"+countThread+"end"+Thread.currentThread());
112. }
114. privatestaticlonglogbackDirectDebugCall(){
115. Integerj=newInteger(5);
116. longstart=System.nanoTime();
117. for(inti=0;i<loop;i++){
118. logbacklogger.debug("SEEIFTHISISLOGGED"+j+".");
119. }
120. return(System.nanoTime()-start)/loop;
121. }
123. privatestaticlonglogbackDirectWarnCall(){
124. Integerj=newInteger(6);
125. longstart=System.nanoTime();
126. for(inti=0;i<loop;i++){
127. logbacklogger.warn("SEEIFTHISISLOGGEDlogbackDirectWarnCall()"+j+".");
128. }
129. return(System.nanoTime()-start)/loop;
130. }
132. privatestaticlonglogbackTestedDebugCall(){
133. Integerj=newInteger(7);
134. longstart=System.nanoTime();
135. for(inti=0;i<loop;i++){
136. if(logbacklogger.isDebugEnabled())
137. logbacklogger.debug("SEEIFTHISISLOGGED"+j+".");
138. }
139. return(System.nanoTime()-start)/loop;
140. }
142. privatestaticlonglogbackTestedWarnCall(){
143. Integerj=newInteger(8);
144. longstart=System.nanoTime();
145. for(inti=0;i<loop;i++){
146. if(logbacklogger.isWarnEnabled())
147. logbacklogger.warn("SEEIFTHISISLOGGEDlogbackTestedWarnCall()"+j+".");
148. }
149. longend=System.nanoTime();
150. longresult=(end-start)/loop;
151. //System.out.println("start="+start+"end="+end+"result="+result);
152. returnresult;
153. }
155. privatestaticlonglogbackParametrizedDebugCall(){
156. Integerj=newInteger(9);
157. longstart=System.nanoTime();
158. for(inti=0;i<loop;i++){
159. logbacklogger.debug("SEEIFTHISISLOGGED{}.",j);
160. }
161. return(System.nanoTime()-start)/loop;
162. }
164. privatestaticlonglogbackParametrizedWarnCall(){
165. Integerj=newInteger(10);
166. longstart=System.nanoTime();
167. for(inti=0;i<loop;i++){
168. logbacklogger.warn("SEEIFTHISISLOGGEDlogbackParametrizedWarnCall(){}.",j);
169. }
170. longend=System.nanoTime();
171. longresult=(end-start)/loop;
172. //System.out.println("start="+start+"end="+end+"result="+result);
173. returnresult;
174. }
175. }
176. }