You are viewing a plain text version of this content. The canonical link for it is here.
Posted to log4j-dev@logging.apache.org by ce...@apache.org on 2004/09/06 19:47:52 UTC
cvs commit: logging-log4j/tests/resources/history TORINO.logging FALKNIS.logging NAPOLI.logging GIL.logging KAL.logging
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