logging-log4j-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From c...@apache.org
Subject cvs commit: logging-log4j/tests/resources/history TORINO.logging FALKNIS.logging NAPOLI.logging GIL.logging KAL.logging
Date Mon, 06 Sep 2004 17:47:52 GMT
ceki        2004/09/06 10:47:52

  Added:       tests/resources/history TORINO.logging FALKNIS.logging
                        NAPOLI.logging GIL.logging KAL.logging
  Log:
  results of previous performance tests
  
  Revision  Changes    Path
  1.1                  logging-log4j/tests/resources/history/TORINO.logging
  
  Index: TORINO.logging
  ===================================================================
  -------------- Fri Nov 5 23:52:59 CET 1999 ---------------
  Append based logging
  Each ttcc log operation took about 342.3 microsec.
  Each simple log operation took about 181.9 microsec.
  -------------- Sat Nov 6 13:35:44 CET 1999 ---------------
  Configuration file: 
  LogFile=test
  FileAppend=false
  DefaultPriority=INFO
  RelativeTime=true
  ecCategoriesFile=logging.cat
  
  Defined Categories: 
  A0123456789=INFO
  
  Results: 173 172 168 172 170 171 178 170 169 171 - average 171 (simple)
  Results: 337 332 347 333 335 335 342 333 335 336 - average 336 (ttcc)
  Results: 141 147 145 145 143 144 143 139 145 149 - average 144 (simple)
  Results: 294 291 292 279 294 292 292 293 292 304 - average 292 (ttcc)
  
  APPENDING to construct the log message
  Second result logs to NOPOutputStream
  -------------- Sat Nov 6 18:08:05 CET 1999 ---------------
  Without the cost of evaluating - Buffer optimized
  
  Results:  82  84  85  82  88  83  84  85  83  82 - average  83 (simple)
  Results: 271 273 275 259 271 270 272 272 275 273 - average 271 (ttcc)
  Results:  54  53  58  70  55  56  55  54  57  55 - average  56 (simple)
  Results: 240 236 234 236 233 239 238 236 234 235 - average 236 (ttcc)
  
  Conclusion cost of evaluation about 20 micros
  -------------- Sat Nov 6 13:47:41 CET 1999 ---------------
  Results: 100 100 103 100 102 102 100 100 103 100 - average 101 (simple)
  Results: 300 298 300 300 310 294 307 299 299 304 - average 301 (ttcc)
  Results:  80  79  78  80  78  79  78  79  77  78 - average  78 (simple)
  Results: 259 266 263 267 265 264 263 266 265 269 - average 264 (ttcc)
  
  buffering to construct the log message
  Second result logs to NOPOutputStream
  
  Conclusion buffering saves ~70 microsecs, or about 70% savings.
  -------------- Sat Nov 6 18:19:57 CET 1999 ---------------
  Removed " [" and " ]" from theadStr
  
  
  Results: 266 273 264 260 268 266 263 264 265 266 - average 265 (ttcc)
  Results: 242 242 237 245 242 239 239 245 227 235 - average 239 (ttcc)
  -------------- Sat Nov 6 18:27:09 CET 1999 ---------------
  Results: 266 273 265 265 266 268 265 267 268 266 - average 266 (ttcc)
  Results: 231 221 225 245 234 227 240 225 225 227 - average 230 (ttcc)
  
  Optimized  " [" + thread.getName() + " ]" 
  -------------- Thu Nov 11 00:55:20 CET 1999 ---------------
  Results: 100  84  84  83  85  85  84  84  84  81 - average  85 (simple)
  Results: 206 206 204 209 216 204 199 204 212 206 - average 206 (ttcc)
  Results:  56  55  56  56  56  55  56  56  56  56 - average  55 (simple)
  Results: 179 190 171 184 186 186 185 185 184 170 - average 182 (ttcc)
  
  Using Category class.
  -------------- Fri Nov 12 22:02:16 CET 1999 ---------------
  Starting tests, this make take a few seconds....
  
  StackInfoThreshold=INFO
  
  Results: 3890 3815 3842 3777 3845 
  -------------- Fri Nov 12 22:07:01 CET 1999 ---------------
  StackInfoThreshold=INFO
  
  Results: 3779 3979 3938 3989 3929 3757 3791 3986 3792 3772 - average 3871 (ttcc)
  Results: 3892 3749 3734 3709 3744 3758 3810 3859 3805 3742 - average 3780 (ttcc)
  
  -------------- Fri Nov 12 22:27:24 CET 1999 ---------------
  StackInfoThreshold=INFO
  
  Results: 3178 3201 3175 3183 3363 3205 3397 3206 3213 3212 - average 3233 (ttcc)
  Results: 3204 3232 3105 3183 3127 3236 3221 3124 3138 3182 - average 3175 (ttcc)
  
  Avoiding try and catch. 
  
  Conclusion the try and catch costs a whopping 600 millis or about 1/5
  of total time.
  -------------- Mon Nov 15 22:14:54 CET 1999 ---------------
  StackInfoThreshold=INFO
  
  Results: 1497 1463 1514 1445 1472 1498 1476 1471 1508 1503 - average 1484 (ttcc)
  Results: 1403 1483 1494 1399 1453 1463 1444 1430 1436 1406 - average 1441 (ttcc)
  
  New code based on internal TracerPrintStream class.
  -------------- Mon Nov 15 22:53:34 CET 1999 ---------------
  Results:  84  86  86  85  90  86  86  86  85  85 - average  85 (simple)
  Results: 219 211 203 205 206 202 203 203 215 204 - average 207 (ttcc)
  Results:  58  58  66  58  56  60  64  59  58  57 - average  59 (simple)
  Results: 174 188 177 170 173 185 171 172 171 175 - average 175 (ttcc)
  
  Results:  733  742  745  743  766  744  748  754  750  756 - average  748 (bas)
  Results: 1462 1481 1475 1515 1462 1479 1517 1497 1479 1555 - average 1492 (ttcc)
  Results:  624  628  622  616  618  612  618  605  606  618 - average  616 (bas)
  Results: 1353 1371 1374 1338 1374 1318 1360 1336 1329 1342 - average 1349 (ttcc)
  
  -------------- Mon Dec 13 21:49:49 CET 1999 ---------------
  No Layout indirection in Log. NDC in TTCCLog.
  
  Results: 82 84 86 83 87 87 88 84 84 81 - average 84 (simple)
  Results: 225 221 211 221 220 220 221 221 216 211 - average 218 (ttcc)
  Results: 60 58 60 54 60 56 55 59 55 56 - average 57 (simple)
  Results: 185 183 189 190 190 188 187 189 187 192 - average 188 (ttcc)
  
  Results: 725 723 737 733 731 738 756 728 755 738 - average 736 (simple)
  Results: 1166 1156 1170 1177 1184 1157 1172 1185 1177 1190 - average 1173 (ttcc)
  Results: 607 606 604 616 602 583 602 603 603 605 - average 603 (simple)
  Results: 1050 1021 1014 1023 1050 1019 1038 1034 1025 1012 - average 1028 (ttcc)
  
  -------------- Mon Dec 13 23:30:18 CET 1999 ---------------
  Layout indirection in Log. NDC in TTCCLayout 
  Results: 85 87 86 91 89 86 89 87 84 88 - average 87 (simple)
  Results: 214 221 214 212 219 213 224 222 210 210 - average 215 (ttcc)
  Results: 66 71 70 70 82 70 66 65 64 68 - average 69 (simple)
  Results: 192 193 194 182 195 186 184 193 191 199 - average 190 (ttcc)
  
  Results: 758 761 743 755 750 778 748 758 737 756 - average 754 (simple)
  Results: 1139 1169 1112 1129 1161 1146 1121 1145 1140 1134 - average 1139 (ttcc)
  Results: 667 684 699 699 717 700 681 693 680 692 - average 691 (simple)
  Results: 1115 1119 1103 1127 1106 1116 1100 1121 1096 1131 - average 1113 (ttcc)
  -------------- Tue Dec 28 20:35:17 CET 1999 ---------------
  v 0.7.1 using Suns JDK 1.2.2
  Results: 54 55 55 54 55 55 56 54 55 55 - average 54 (simple)
  Results: 123 123 125 122 128 122 122 123 124 123 - average 123 (ttcc)
  
  Results: 41 41 42 42 41 41 42 41 56 41 - average 42 (simple)
  Results: 102 102 102 102 102 102 102 101 103 102 - average 102 (ttcc)
  
  Results: 496 494 491 491 503 505 497 491 495 496 - average 495 (simple)
  Results: 609 601 613 607 638 606 610 608 610 607 - average 610 (ttcc)
  
  Results: 449 451 452 476 450 450 444 455 446 447 - average 452 (simple)
  Results: 563 548 555 555 556 545 557 551 545 559 - average 553 (ttcc)
  
  -------------- Tue Dec 28 23:44:36 CET 1999 ---------------
  Writer based log4j
  
  Results: 65 65 65 64 64 65 66 67 65 65 - average 65 (simple)
  Results: 122 121 121 121 121 122 122 122 122 121 - average 121 (ttcc)
  
  Results: 18 18 18 18 18 18 18 18 18 18 - average 18 (simple)
  Results: 60 72 59 59 60 60 60 60 60 60 - average 61 (ttcc)
  
  Results: 521 523 518 524 529 526 533 530 524 528 - average 525 (simple)
  Results: 619 629 621 622 626 622 625 624 616 624 - average 622 (ttcc)
  
  Results: 412 405 400 404 417 402 409 399 416 402 - average 406 (simple)
  Results: 496 492 480 488 482 488 487 492 489 488 - average 488 (ttcc)
  -------------- Wed Jan 12 00:05:48 CET 2000 ---------------
  0.7.2 code
  Starting tests, this make take a few seconds....
  
  Logging to a real file.
  Results: 64 63 65 64 63 63 64 64 - average 63 (simple)
  Results: 124 123 122 125 123 126 122 122 - average 123 (ttcc)
  
  Logging to a null (nop) output stream.
  Results: 17 18 17 18 17 18 17 17 - average 17 (simple)
  Results: 60 60 59 59 61 60 60 60 - average 59 (ttcc)
  
  Logging with stack info to a regular file.
  Results: 533 526 535 533 533 534 535 528 - average 532 (simple)
  Results: 633 630 633 628 639 619 638 630 - average 631 (ttcc)
  
  Logging with stack info to a null (nop) output stream.
  Results: 407 406 413 402 404 412 426 415 - average 410 (simple)
  Results: 491 499 494 495 487 504 497 493 - average 495 (ttcc)
  
  -------------- Sun Jul 9 21:55:09 CEST 2000 ---------------
  IBM JDK 1.1.8, log4j 0.8.5 with NDC
  
  NullAppender:
  
  12   SimpleLayout
  21   PatternLayout "%p - %m\n"
  22   PatternLayout "%-5p - %m\n"
  39   TTCCLayout/RELATIVE
  39   PatternLayout "%r [%t] %-5p %c{2} %x - %m\n"
  37   PatternLayout "%r [%t] %-5p %.10c %x - %m\n"
  37   PatternLayout "%r [%t] %-5p %.20c %x - %m\n"
  32   PatternLayout "%r [%t] %-5p %c - %m\n"
  62   TTCCLayout/ISO8601
  69   PatternLayout "%d{ISO8601} [%t] %-5p %c %x - %m\n"
  222  PatternLayout "%d{yyyy-MM-dd HH:mm:ss,SSS} [%t] %-5p %c %x - %m\n"
  1172 PatternLayout "%l - %m\n"
  1313 PatternLayout "%C.%M.%L - %m\n"
  
  FileAppender:
  
  44  SimpleLayout
  56  PatternLayout "%p - %m\n"
  87  TTCC/RELATIVE
  95  PatternLayout "%r [%t] %-5p %c %x - %m\n"
  126 TTCCLayout/ISO8601
  147 PatternLayout "%d{ISO8601} [%t] %-5p %c %x - %m\n"
  288 PatternLayout "%d{yyyy-MM-dd HH:mm:ss,SSS} [%t] %-5p %c %x - %m\n"
  447 PatternLayout "%l - %m\n"
  
  
  
  
  1.1                  logging-log4j/tests/resources/history/FALKNIS.logging
  
  Index: FALKNIS.logging
  ===================================================================
  All tests done with java version "1.2.2"
  Classic VM (J2RE 1.2.2 IBM build cadev-19991008 (JIT enabled: jitc))
  -------------- Wed Dec 8 20:55:19 MET 1999 ---------------
  Logging to a real file.
  Results: 516 525 - average 520 (basic)
  Results: 657 655 - average 656 (cgu)
  
  Logging to a null (nop) output stream.
  Results: 63 61 - average 62 (basic)
  Results: 155 144 - average 149 (cgu)
  
  Logging with stack info to a regular file.
  Results: 2991 2999 - average 2995 (basic)
  Results: 5174 5260 - average 5217 (cgu)
  
  Logging with stack info to a null (nop) output stream.
  Results: 779 780 - average 779 (basic)
  Results: 2716 2876 - average 2796 (cgu)
  
  Result with the DateFormat line  in CGULog.java:
  static private final java.text.DateFormat __DATETIME_FORMAT = new
                   java.text.SimpleDateFormat("[HH:mm:ss:SSS z] dd.MM.yy");
  -------------- Wed Dec 8 20:59:25 MET 1999 ---------------
  Logging to a real file.
  Results: 517 526 - average 521 (basic)
  Results: 720 656 - average 688 (cgu)
  
  Logging to a null (nop) output stream.
  Results: 61 63 - average 62 (basic)
  Results: 173 171 - average 172 (cgu)
  
  Logging with stack info to a regular file.
  Results: 3034 3048 - average 3041 (basic)
  Results: 5125 4603 - average 4864 (cgu)
  
  Logging with stack info to a null (nop) output stream.
  Results: 793 929 - average 861 (basic)
  -------------- Mon Jan 3 17:17:53 MET 2000 ---------------
  Logging to Writer instead of OutputStrem
  
  Logging to a real file.
  Results: 453 433 432 440 433 436 438 437 437 471 - average 441 (simple)
  Results: 611 588 587 619 595 585 596 590 639 600 - average 601 (ttcc)
  
  Logging to a null (nop) output stream.
  Results: 34 29 29 27 29 28 30 27 29 28 - average 29 (simple)
  Results: 87 112 89 87 86 87 86 87 86 86 - average 89 (ttcc)
  
  Logging with stack info to a regular file.
  Results: 1806 1786 1808 1807 1816 1862 1818 1808 1825 - average 1816 (simple)
  Results: 2391 2196 2170 2229 2206 2191 2183 2181 2244 - average 2221 (ttcc)
  
  Logging with stack info to a null (nop) output stream.
  Results: 763 767 806 772 769 758 756 756 768 - average 768 (simple)
  Results: 1046 1067 1095 1042 1037 1034 1058 1082 1057 - average 1058 (ttcc)
  -------------- Thu Jan 13 15:08:27 MET 2000 ---------------
  Using 0.7.3 style appender.doAppend method
  
  Logging to a real file.
  Results: 436 484 438 443 449 448 440 444 - average 447 (simple)
  Results: 665 616 598 596 604 588 603 593 - average 607 (ttcc)
  
  Logging to a null (nop) output stream.
  Results: 24 24 24 23 24 24 27 24 - average 24 (simple)
  Results: 81 83 81 81 82 80 80 81 - average 81 (ttcc)
  
  Logging with stack info to a regular file.
  Results: 1434 1392 1578 1430 1413 1409 1410 1409 - average 1434 (simple)
  Results: 1769 1801 1886 1783 1790 1754 1763 1771 - average 1789 (ttcc)
  
  Logging with stack info to a null (nop) output stream.
  Results: 726 710 721 695 707 699 707 710 - average 709 (simple)
  Results: 961 963 966 933 932 930 932 945 - average 945 (ttcc)
  -------------- Fri Jan 21 19:05:34 MET 2000 ---------------
  0.7.4 performance, JDK 1.2.2 GA
  
  Logging to a real file.
  Results: 436 432 479 451 457 454 436 434 - average 447 simple
  Results: 597 632 585 589 587 593 586 593 - average 595 ttcc/RelTime
  Results: 729 745 744 738 782 748 730 765 - average 747 ttcc/AbsTime
  Results: 765 832 767 772 763 762 778 770 - average 776 ttcc/Date
  
  Logging to a NOPWriter.
  Results: 17 18 18 17 17 18 18 17 - average 17 simple
  Results: 71 80 68 68 68 69 71 68 - average 70 ttcc/RelTime
  Results: 165 166 166 168 167 163 164 167 - average 165 ttcc/AbsTime
  Results: 176 179 175 178 179 180 175 176 - average 177 ttcc/Date
  
  Logging with stack info to a regular file.
  Results: 1403 1408 1399 1405 1406 1390 1399 1515 - average 1415 simple
  Results: 1697 1811 1676 1591 1631 1599 1616 1613 - average 1654 ttcc/RelTime
  
  Logging with stack info to a NOPWriter.
  Results: 599 597 604 595 605 596 597 616 - average 601 simple
  Results: 780 764 767 775 765 758 752 765 - average 765 ttcc/RelTime
  -------------- Fri Jan 21 19:29:31 MET 2000 ---------------
  static Date object
  
  Logging to a real file.
  Results: 467 437 442 442 433 453 438 435 - average 443 simple
  Results: 580 583 588 621 586 579 592 585 - average 589 ttcc/RelTime
  Results: 737 773 731 734 732 740 762 734 - average 742 ttcc/AbsTime
  Results: 755 755 760 805 757 752 770 766 - average 765 ttcc/Date
  
  Logging to a NOPWriter.
  Results: 11 11 12 12 11 11 11 11 - average 11 simple
  Results: 51 53 51 52 51 52 51 51 - average 51 ttcc/RelTime
  Results: 97 95 95 95 97 98 98 96 - average 96 ttcc/AbsTime
  Results: 113 112 113 113 113 110 114 115 - average 112 ttcc/Date
  
  Logging with stack info to a regular file.
  Results: 1391 1426 1410 1403 1454 1471 1475 1470 - average 1437 simple
  Results: 1620 1627 1631 1615 1601 1606 1598 1802 - average 1637 ttcc/RelTime
  
  Logging with stack info to a NOPWriter.
  Results: 598 629 609 605 595 602 599 608 - average 605 simple
  Results: 759 765 743 757 758 747 773 756 - average 757 ttcc/RelTime
  
  
  
  1.1                  logging-log4j/tests/resources/history/NAPOLI.logging
  
  Index: NAPOLI.logging
  ===================================================================
  All tests are done using Suns's 1.1.7U JDK on NT
  -------------- Sometime in 1999 ---------------
  Using OutputStream.
  Results: - average 79 (simple)
  Results: - average 164 (ttcc)
  
  Logging to a null (nop) output stream.
  Results: - average 56 (simple)
  Results: - average 131 (ttcc)
  
  Logging with stack info to a regular file.
  Results: - average 1064 (simple)
  Results: - average 1355 (ttcc)
  
  Logging with stack info to a null (nop) output stream.
  Results: - average  1002 (simple)
  Results: - average  1272 (ttcc)
  -------------- Mon Jan 3 17:30:21 2000 ---------------
  Using Writer instead of OutputStream.
  Results: 111 98 97 101 106 107 117 100 146 116 - average 109 (simple)
  Results: 200 175 175 169 206 214 185 172 173 196 - average 186 (ttcc)
  
  Logging to a null (nop) output stream.
  Results: 23 23 25 28 24 23 23 23 23 23 - average 23 (simple)
  Results: 67 66 64 65 75 65 66 64 65 64 - average 66 (ttcc)
  
  Logging with stack info to a regular file.
  Results: 981 986 1051 1021 1016 986 976 991 976 1021 - average 1000 (simple)
  Results: 1136 1136 1126 1131 1136 1151 1131 1126 1136 1137 - average 1134
  
  Logging with stack info to a null (nop) output stream.
  Results: 791 786 821 781 776 791 776 776 781 781 - average 786 (simple)
  Results: 911 901 901 896 906 943 906 966 901 906 - average 913 (ttcc)
  -------------- Thu Jan 13 18:31:46 2000 ---------------
  Using v0.7.3 FileAppender.doAppend
  Results: 105 95 91 93 92 94 96 96 - average 95 (simple)
  Results: 172 200 165 174 182 168 165 165 - average 173 (ttcc)
  
  Logging to a null (nop) output stream.
  Results: 22 22 26 22 22 21 24 22 - average 22 (simple)
  Results: 65 64 66 64 64 64 65 65 - average 64 (ttcc)
  
  Logging with stack info to a regular file.
  Results: 831 921 836 841 836 831 831 841 - average 846 (simple)
  Results: 1051 981 981 976 981 991 981 1026 - average 996 (ttcc)
  
  Logging with stack info to a null (nop) output stream.
  Results: 726 721 716 721 716 721 716 791 - average 728 (simple)
  Results: 841 841 841 841 841 841 841 841 - average 841 (ttcc)
  -------------- Fri Jan 14 15:12:11 2000 ---------------
  Using v0.7.3 FileAppender.doAppend and the FilterWriter based architecture
  
  Logging to a real file.
  Results: 96 94 96 95 99 96 92 94 - average 95 (simple)
  Results: 178 168 167 205 168 174 172 173 - average 175 (ttcc)
  
  Logging to a null (nop) output stream.
  Results: 28 22 21 22 25 21 22 24 - average 23 (simple)
  Results: 67 73 65 64 64 64 64 64 - average 65 (ttcc)
  
  Logging with stack info to a regular file.
  Results: 961 831 846 831 841 831 836 866 - average 855 (simple)
  Results: 981 976 1001 971 1242 981 1051 981 - average 1023 (ttcc)
  
  Logging with stack info to a null (nop) output stream.
  Results: 726 716 716 716 716 716 741 731 - average 722 (simple)
  Results: 846 841 841 841 841 866 841 841 - average 844 (ttcc)
  
  Note: only stack printing performance is affected.
  -------------- Fri Jan 21 20:42:03 2000 ---------------
  release 0.7.4
  Logging to a real file.
  Results: 90 94 92 90 92 91 88 92 - average 91 simple
  Results: 172 161 162 166 165 165 179 164 - average 166 ttcc/RelTime
  Results: 206 207 211 212 221 214 225 206 - average 212 ttcc/AbsTime
  Results: 228 236 239 234 233 231 229 240 - average 233 ttcc/Date
  
  Logging to a NOPWriter.
  Results: 21 21 20 20 20 20 20 20 - average 20 simple
  Results: 60 60 60 60 60 60 60 60 - average 60 ttcc/RelTime
  Results: 96 96 96 96 96 96 96 96 - average 96 ttcc/AbsTime
  Results: 111 111 112 111 112 108 111 112 - average 111 ttcc/Date
  
  Logging with stack info to a regular file.
  Results: 1091 1106 1092 1096 1096 1086 1106 1091 - average 1095 simple
  Results: 1256 1242 1257 1251 1246 1267 1251 1256 - average 1253 ttcc/RelTime
  
  Logging with stack info to a NOPWriter.
  Results: 866 871 856 856 846 851 846 846 - average 854 simple
  Results: 996 986 991 986 996 996 991 986 - average 991 ttcc/RelTime
  
  -------------- Fri Jan 21 20:26:43 2000 ---------------
  0.7.4 + static Date
  Logging to a real file.
  Results: 93 97 92 91 100 91 95 95 - average 94 simple
  Results: 170 177 179 195 214 176 188 167 - average 183 ttcc/RelTime
  Results: 215 214 218 212 211 215 217 224 - average 215 ttcc/AbsTime
  Results: 296 274 234 235 230 237 232 231 - average 246 ttcc/Date
  
  Logging to a NOPWriter.
  Results: 21 21 20 20 22 20 20 20 - average 20 simple
  Results: 60 60 60 60 60 60 60 60 - average 60 ttcc/RelTime
  Results: 94 93 94 93 93 94 93 93 - average 93 ttcc/AbsTime
  Results: 106 105 106 106 106 106 106 105 - average 105 ttcc/Date
  
  Logging with stack info to a regular file.
  Results: 1096 1091 1096 1087 1106 1091 1101 1091 - average 1094 simple
  Results: 1211 1216 1212 1217 1211 1221 1216 1217 - average 1215 ttcc/RelTime
  
  Logging with stack info to a NOPWriter.
  Results: 866 866 861 851 846 856 851 856 - average 856 simple
  Results: 956 981 966 966 956 961 961 961 - average 963 ttcc/RelTime
  
  The static date version is faster by at least 3 microsecs.
  -------------- Sat Jan 22 16:54:35 2000 ---------------
  static Date in DateFormat and static StringBuffer in TTCCLayout
  Logging to a real file.
  Results: 92 90 92 93 93 91 89 97 - average 92 simple
  Results: 159 161 167 160 159 161 164 159 - average 161 ttcc/RelTime
  Results: 206 205 205 203 213 207 224 204 - average 208 ttcc/AbsTime
  Results: 220 232 229 231 236 228 231 231 - average 229 ttcc/Date
  
  Logging to a NOPWriter.
  Results: 21 21 20 20 20 20 20 20 - average 20 simple
  Results: 55 55 55 55 55 55 55 55 - average 55 ttcc/RelTime
  Results: 88 88 87 89 88 92 90 87 - average 88 ttcc/AbsTime
  Results: 101 102 101 101 102 102 106 106 - average 102 ttcc/Date
  
  Logging with stack info to a regular file.
  Results: 1101 1096 1111 1106 1112 1097 1101 1101 - average 1103 simple
  Results: 1217 1212 1221 1271 1227 1217 1226 1216 - average 1225 ttcc/RelTime
  
  Logging with stack info to a NOPWriter.
  Results: 876 866 866 846 856 851 851 851 - average 857 simple
  Results: 961 961 961 956 961 961 951 961 - average 959 ttcc/RelTime
  -------------------------------------------------------
  227 using sockets and byte buffers
  -------------- Sat Jan 29 14:34:19 2000 ---------------
  Using DateFormat extensions in TTCCLayout.
  
  Logging to a real file.
  Results: 177 170 173 171 185 188 176 176 - average 177 ttcc/RelTime
  Results: 206 200 202 203 207 208 201 202 - average 203 ttcc/AbsTime
  Results: 234 240 245 236 238 236 246 269 - average 243 ttcc/DateTime
  
  Logging to a NOPWriter.
  Results: 57 57 57 57 - average 57 ttcc/RelTime
  Results: 86 84 85 85 - average 85 ttcc/AbsTime
  Results: 113 112 112 112 - average 112 ttcc/DateTime
  
  Logging with stack info to a regular file.
  Results: 1111 1116 1116 1157 - average 1125 simple
  Results: 1592 1592 1597 1597 - average 1594 ttcc/RelTime
  
  Logging with stack info to a NOPWriter.
  Results: 866 886 876 866 - average 873 simple
  Results: 1312 1312 1302 1302 - average 1307 ttcc/RelTime
  
  A noticable slowdown in RelTime printing, but sull faster than 0.7.4.
  -------------- Thu Feb 3 21:04:35 2000 ---------------
  Release 0.7.5 
  Logging to a real file.
  Results: 91 92 95 100 120 91 95 92 - average 97 simple
  Results: 178 176 192 183 176 179 174 181 - average 179 ttcc/RelTime
  Results: 211 201 203 203 206 220 210 216 - average 208 ttcc/AbsTime
  Results: 238 237 245 233 239 237 239 241 - average 238 ttcc/DateTime
  
  Logging to a NOPWriter.
  Results: 21 21 21 20 - average 20 simple
  Results: 58 58 58 58 - average 58 ttcc/RelTime
  Results: 87 87 86 86 - average 86 ttcc/AbsTime
  Results: 114 113 114 114 - average 113 ttcc/DateTime
  
  Logging with stack info to a regular file.
  Results: 1121 1121 1111 1126 - average 1119 simple
  Results: 1622 1617 1597 1622 - average 1614 ttcc/RelTime
  
  Logging with stack info to a NOPWriter.
  Results: 886 881 876 866 - average 877 simple
  Results: 1317 1317 1316 1317 - average 1316 ttcc/RelTime
  
  -------------- Tue Mar 21 16:57:01 2000 ---------------
  Release 0.8.2 code
  
  Results:  30  30  31  31 - average 30 NullAppender/SimpleLayout
  Results:  32  32  32  32 - average 32 NullAppender/PatternLayout "%p - %m\n"
  Results:  31  31  31  31 - average 31 NullAppender/PatternLayout "%-5p - %m\n"
  Results:  65  65  65  65 - average 65 NullAppender/TTCCLayout/RELATIVE
  Results:  64  65  64  64 - average 64 NullAppender/PatternLayout "%r [%t] %-5p %c %x - %m\n"
  Results:  64  64  64  64 - average 64 NullAppender/PatternLayout "%r [%t] %-5p %.10c %x
- %m\n"
  Results:  64  65  64  64 - average 64 NullAppender/PatternLayout "%r [%t] %-5p %.20c %x
- %m\n"
  Results:  58  58  60  58 - average 58 NullAppender/PatternLayout "%r [%t] %-5p %c - %m\n"
  Results: 122 124 121 122 - average 122 NullAppender/TTCCLayout/ISO8601
  Results: 152 152 152 152 - average 152 NullAppender/PatternLayout "%d{ISO8601} [%t] %-5p
%c %x - %m\n"
  Results: 405 405 404 405 - average 404 NullAppender/PatternLayout "%d{yyyy-MM-dd HH:mm:ss,SSS}
[%t] %-5p %c %x - %m\n"
  Results: 111 112 113 110 110 111 114 120 - average 112 FileAppender/SimpleLayout
  Results: 127 124 125 129 140 121 121 123 - average 126 FileAppender/PatternLayout "%p -
%m\n"
  Results: 196 195 186 183 186 190 182 189 - average 188 FileAppender/TTCC/RELATIVE
  Results: 188 184 186 195 195 214 193 191 - average 193 FileAppender/PatternLayout "%r [%t]
%-5p %c %x - %m\n"
  Results: 257 265 300 252                 - average 268 FileAppender/TTCCLayout/ISO8601
  Results: 289 332 320 338 308 290 288 290 - average 306 FileAppender/PatternLayout "%d{ISO8601}
[%t] %-5p %c %x - %m\n"
  Results: 574 577 580 572 573 577 576 589 - average 577 FileAppender/PatternLayout "%d{yyyy-MM-dd
HH:mm:ss,SSS} [%t]
   %-5p %c %x - %m\n"
  
  Notice the good performance of PatternLayout compared to dedicated
  SimpleLayout and TTCCLayout except when SimpleDateFormat is used.
  
  
  
  
  1.1                  logging-log4j/tests/resources/history/GIL.logging
  
  Index: GIL.logging
  ===================================================================
  GIL is a AMD Duron running at 800 MHz running W2000 and Sun's JDK 1.3.
  
  NullAppender:
  
   4  SimpleLayout
   4  PatternLayout "%p - %m%n"
   4  PatternLayout "%-5p - %m%n"
   10 TTCCLayout/RELATIVE
   10 PatternLayout "%r [%t] %-5p %c{2} %x - %m%n"
   10 PatternLayout "%r [%t] %-5p %.10c %x - %m%n"
   10 PatternLayout "%r [%t] %-5p %.20c %x - %m%n"
   9  PatternLayout "%r [%t] %-5p %c - %m%n"
   25 TTCCLayout/ISO8601
   28 PatternLayout "%d{ISO8601} [%t] %-5p %c %x - %m%n"
   58 PatternLayout "%d{yyyy-MM-dd HH:mm:ss,SSS} [%t] %-5p %c %x - %m%n"
   1309 PatternLayout "%l - %m%n"
   1379 PatternLayout "%C.%M.%L - %m%n"
  
  FileAppender:
  
   26 SimpleLayout
   28 PatternLayout "%p - %m%n"
   47 TTCC/RELATIVE
   46 PatternLayout "%r [%t] %-5p %c %x - %m%n"
   70 TTCCLayout/ISO8601
   75 PatternLayout "%d{ISO8601} [%t] %-5p %c %x - %m%n"
   99 PatternLayout "%d{yyyy-MM-dd HH:mm:ss,SSS} [%t] %-5p %c %x - %m%n"
   1524 PatternLayout "%l - %m%n"
  
  FileAppender: (ImmediateFlush=false)
  
   20 SimpleLayout
   21 PatternLayout "%p - %m%n"
   38 TTCC/RELATIVE
   38 PatternLayout "%r [%t] %-5p %c %x - %m%n"
   62 TTCCLayout/ISO8601
   67 PatternLayout "%d{ISO8601} [%t] %-5p %c %x - %m%n"
   86 PatternLayout "%d{yyyy-MM-dd HH:mm:ss,SSS} [%t] %-5p %c %x - %m%n"
  
  Notice the small but noticable performance gain when forgoing
  immediate flush.
  
  
  
  1.1                  logging-log4j/tests/resources/history/KAL.logging
  
  Index: KAL.logging
  ===================================================================
  KAL is an Intel Pentium 4 clocked at 2.53 GHz running XP and Sun's JDK 1.4.1.
  
  Results are in microseconds.
  
  NullAppender:
  
    SimpleLayout
    0.6 PatternLayout "%p - %m%n"
    0.6 PatternLayout "%-5p - %m%n"
  
    TTCCLayout/RELATIVE
    0.6 PatternLayout "%r [%t] %-5p %c{2} %x - %m%n"
    PatternLayout "%r [%t] %-5p %.10c %x - %m%n"
    PatternLayout "%r [%t] %-5p %.20c %x - %m%n"
     PatternLayout "%r [%t] %-5p %c - %m%n"
    TTCCLayout/ISO8601
    PatternLayout "%d{ISO8601} [%t] %-5p %c %x - %m%n"
    PatternLayout "%d{yyyy-MM-dd HH:mm:ss,SSS} [%t] %-5p %c %x - %m%n"
   PatternLayout "%l - %m%n"
   PatternLayout "%C.%M.%L - %m%n"
  
  FileAppender:
  
    SimpleLayout
    PatternLayout "%p - %m%n"
    TTCC/RELATIVE
    PatternLayout "%r [%t] %-5p %c %x - %m%n"
    TTCCLayout/ISO8601
    PatternLayout "%d{ISO8601} [%t] %-5p %c %x - %m%n"
    PatternLayout "%d{yyyy-MM-dd HH:mm:ss,SSS} [%t] %-5p %c %x - %m%n"
    PatternLayout "%l - %m%n"
  
  FileAppender: (ImmediateFlush=false)
  
    SimpleLayout
    PatternLayout "%p - %m%n"
    TTCC/RELATIVE
    PatternLayout "%r [%t] %-5p %c %x - %m%n"
    TTCCLayout/ISO8601
    PatternLayout "%d{ISO8601} [%t] %-5p %c %x - %m%n"
    PatternLayout "%d{yyyy-MM-dd HH:mm:ss,SSS} [%t] %-5p %c %x - %m%n"
  
  Notice the small but noticable performance gain when forgoing
  immediate flush.
  
  
  
  
  
  

---------------------------------------------------------------------
To unsubscribe, e-mail: log4j-dev-unsubscribe@logging.apache.org
For additional commands, e-mail: log4j-dev-help@logging.apache.org


Mime
View raw message