Return-Path: X-Original-To: archive-asf-public-internal@cust-asf2.ponee.io Delivered-To: archive-asf-public-internal@cust-asf2.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by cust-asf2.ponee.io (Postfix) with ESMTP id 4A9C0200B43 for ; Wed, 8 Jun 2016 04:06:23 +0200 (CEST) Received: by cust-asf.ponee.io (Postfix) id 473FD160A4F; Wed, 8 Jun 2016 02:06:23 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id 4489C160A5A for ; Wed, 8 Jun 2016 04:06:22 +0200 (CEST) Received: (qmail 8601 invoked by uid 500); 8 Jun 2016 02:06:21 -0000 Mailing-List: contact log4j-dev-help@logging.apache.org; run by ezmlm Precedence: bulk List-Unsubscribe: List-Help: List-Post: List-Id: "Log4J Developers List" Reply-To: "Log4J Developers List" Delivered-To: mailing list log4j-dev@logging.apache.org Received: (qmail 8575 invoked by uid 99); 8 Jun 2016 02:06:21 -0000 Received: from arcas.apache.org (HELO arcas) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 08 Jun 2016 02:06:21 +0000 Received: from arcas.apache.org (localhost [127.0.0.1]) by arcas (Postfix) with ESMTP id 432F82C1F5D for ; Wed, 8 Jun 2016 02:06:21 +0000 (UTC) Date: Wed, 8 Jun 2016 02:06:21 +0000 (UTC) From: "Remko Popma (JIRA)" To: log4j-dev@logging.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Comment Edited] (LOG4J2-1395) Faster Console appender MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 archived-at: Wed, 08 Jun 2016 02:06:23 -0000 [ https://issues.apache.org/jira/browse/LOG4J2-1395?page=3Dcom.atlassia= n.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=3D153= 18190#comment-15318190 ]=20 Remko Popma edited comment on LOG4J2-1395 at 6/8/16 2:06 AM: ------------------------------------------------------------- *Linux 1 thread* {color:red} Something went bad with the Linux test. Notice there is no "appenderDirectC= onsole" result. Some exception or initialization problem? Same thing happen= ed with the 4 threads test on Linux. (Need to investigate but no time now..= .) {color} {code} Benchmark Mode= Samples Score Error Units o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderConsole thrpt= 20 147452.021 =C2=B1 33514.022 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderFile thrpt= 20 2234577.235 =C2=B1 218498.789 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderMMap thrpt= 20 2329409.003 =C2=B1 198796.157 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderNoop thrpt= 20 149720377.842 =C2=B1 17804473.271 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderRAF thrpt= 20 2063633.128 =C2=B1 247989.703 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderRewrite thrpt= 20 19682771.308 =C2=B1 2075195.499 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.baseline thrpt= 20 2741023752.188 =C2=B1 314323378.970 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endConsole thrpt= 20 149909.599 =C2=B1 47015.584 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endDirectConsole thrpt= 20 471146636.469 =C2=B1 33450500.357 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endFile thrpt= 20 1377810.177 =C2=B1 128314.984 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endMMap thrpt= 20 1222680.095 =C2=B1 277079.233 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endNoop thrpt= 20 6511841.054 =C2=B1 743196.256 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endRAF thrpt= 20 1399987.132 =C2=B1 127872.116 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endRewrite thrpt= 20 2314119.725 =C2=B1 274703.905 ops/s {code} *Windows 1 thread* {code} Benchmark Mod= e Samples Score Error Units o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderConsole thrp= t 20 56832.983 =E2=96=92} 2802.240 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderDirectConsole thrp= t 20 378385.134 =E2=96=92} 6631.688 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderFile thrp= t 20 1129532.192 =E2=96=92} 23263.940 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderMMap thrp= t 20 1276745.239 =E2=96=92} 19106.636 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderNoop thrp= t 20 115293601.304 =E2=96=92} 940371.277 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderRAF thrp= t 20 1265487.063 =E2=96=92} 12316.610 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderRewrite thrp= t 20 12606468.237 =E2=96=92} 170407.536 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.baseline thrp= t 20 2546731181.299 =E2=96=92} 12768234.725 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endConsole thrp= t 20 54941.997 =E2=96=92} 3568.042 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endDirectConsole thrp= t 20 225775.408 =E2=96=92} 5476.913 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endFile thrp= t 20 731310.525 =E2=96=92} 11114.343 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endMMap thrp= t 20 828480.404 =E2=96=92} 18808.924 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endNoop thrp= t 20 7058097.116 =E2=96=92} 72897.218 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endRAF thrp= t 20 782810.400 =E2=96=92} 4930.361 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endRewrite thrp= t 20 2488038.697 =E2=96=92} 43576.434 ops/s {code} *Windows 4 threads* {code} Benchmark Mod= e Samples Score Error Units o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderConsole thrp= t 20 53574.704 =E2=96=92} 4299.183 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderDirectConsole thrp= t 20 234439.934 =E2=96=92} 6311.309 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderFile thrp= t 20 1866238.755 =E2=96=92} 25956.744 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderMMap thrp= t 20 3602075.958 =E2=96=92} 261109.877 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderNoop thrp= t 20 52229803.307 =E2=96=92} 4906667.806 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderRAF thrp= t 20 3252844.780 =E2=96=92} 179887.163 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderRewrite thrp= t 20 22727534.382 =E2=96=92} 3591496.173 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.baseline thrp= t 20 10222654166.652 =E2=96=92} 45072491.403 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endConsole thrp= t 20 73129.899 =E2=96=92} 11314.153 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endDirectConsole thrp= t 20 190761.527 =E2=96=92} 6879.776 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endFile thrp= t 20 1201769.508 =E2=96=92} 47831.593 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endMMap thrp= t 20 2195625.288 =E2=96=92} 137982.114 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endNoop thrp= t 20 10077332.720 =E2=96=92} 1775005.843 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endRAF thrp= t 20 2060484.738 =E2=96=92} 179977.267 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endRewrite thrp= t 20 4416596.263 =E2=96=92} 621545.485 ops/s {code} was (Author: remkop@yahoo.com): *Linux 1 thread* {color:red} Something went bad with this test. Notice there is no "appenderDirectConsol= e" result. Some exception or initialization problem? (Need to investigate b= ut no time now...) {color} {code} Benchmark Mode= Samples Score Error Units o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderConsole thrpt= 20 147452.021 =C2=B1 33514.022 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderFile thrpt= 20 2234577.235 =C2=B1 218498.789 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderMMap thrpt= 20 2329409.003 =C2=B1 198796.157 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderNoop thrpt= 20 149720377.842 =C2=B1 17804473.271 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderRAF thrpt= 20 2063633.128 =C2=B1 247989.703 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderRewrite thrpt= 20 19682771.308 =C2=B1 2075195.499 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.baseline thrpt= 20 2741023752.188 =C2=B1 314323378.970 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endConsole thrpt= 20 149909.599 =C2=B1 47015.584 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endDirectConsole thrpt= 20 471146636.469 =C2=B1 33450500.357 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endFile thrpt= 20 1377810.177 =C2=B1 128314.984 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endMMap thrpt= 20 1222680.095 =C2=B1 277079.233 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endNoop thrpt= 20 6511841.054 =C2=B1 743196.256 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endRAF thrpt= 20 1399987.132 =C2=B1 127872.116 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endRewrite thrpt= 20 2314119.725 =C2=B1 274703.905 ops/s {code} *Windows 1 thread* {code} Benchmark Mod= e Samples Score Error Units o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderConsole thrp= t 20 56832.983 =E2=96=92} 2802.240 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderDirectConsole thrp= t 20 378385.134 =E2=96=92} 6631.688 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderFile thrp= t 20 1129532.192 =E2=96=92} 23263.940 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderMMap thrp= t 20 1276745.239 =E2=96=92} 19106.636 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderNoop thrp= t 20 115293601.304 =E2=96=92} 940371.277 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderRAF thrp= t 20 1265487.063 =E2=96=92} 12316.610 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderRewrite thrp= t 20 12606468.237 =E2=96=92} 170407.536 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.baseline thrp= t 20 2546731181.299 =E2=96=92} 12768234.725 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endConsole thrp= t 20 54941.997 =E2=96=92} 3568.042 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endDirectConsole thrp= t 20 225775.408 =E2=96=92} 5476.913 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endFile thrp= t 20 731310.525 =E2=96=92} 11114.343 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endMMap thrp= t 20 828480.404 =E2=96=92} 18808.924 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endNoop thrp= t 20 7058097.116 =E2=96=92} 72897.218 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endRAF thrp= t 20 782810.400 =E2=96=92} 4930.361 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endRewrite thrp= t 20 2488038.697 =E2=96=92} 43576.434 ops/s {code} *Windows 4 threads* {code} Benchmark Mod= e Samples Score Error Units o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderConsole thrp= t 20 53574.704 =E2=96=92} 4299.183 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderDirectConsole thrp= t 20 234439.934 =E2=96=92} 6311.309 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderFile thrp= t 20 1866238.755 =E2=96=92} 25956.744 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderMMap thrp= t 20 3602075.958 =E2=96=92} 261109.877 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderNoop thrp= t 20 52229803.307 =E2=96=92} 4906667.806 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderRAF thrp= t 20 3252844.780 =E2=96=92} 179887.163 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.appenderRewrite thrp= t 20 22727534.382 =E2=96=92} 3591496.173 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.baseline thrp= t 20 10222654166.652 =E2=96=92} 45072491.403 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endConsole thrp= t 20 73129.899 =E2=96=92} 11314.153 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endDirectConsole thrp= t 20 190761.527 =E2=96=92} 6879.776 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endFile thrp= t 20 1201769.508 =E2=96=92} 47831.593 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endMMap thrp= t 20 2195625.288 =E2=96=92} 137982.114 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endNoop thrp= t 20 10077332.720 =E2=96=92} 1775005.843 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endRAF thrp= t 20 2060484.738 =E2=96=92} 179977.267 ops/s o.a.l.l.p.j.Log4j2AppenderComparisonBenchmark.end2endRewrite thrp= t 20 4416596.263 =E2=96=92} 621545.485 ops/s {code} > Faster Console appender > ----------------------- > > Key: LOG4J2-1395 > URL: https://issues.apache.org/jira/browse/LOG4J2-1395 > Project: Log4j 2 > Issue Type: Improvement > Components: Appenders > Affects Versions: 2.5, 2.6 > Reporter: Mikael St=C3=A5ldal > Attachments: ggregory-hardware.txt > > > The ConsoleAppender is much slower than the FileAppender. > It would be nice to improve the performance of the ConsoleAppender. -- This message was sent by Atlassian JIRA (v6.3.4#6332) --------------------------------------------------------------------- To unsubscribe, e-mail: log4j-dev-unsubscribe@logging.apache.org For additional commands, e-mail: log4j-dev-help@logging.apache.org