You are viewing a plain text version of this content. The canonical link for it is here.
Posted to commits@logging.apache.org by rp...@apache.org on 2016/03/28 10:22:41 UTC
[3/5] logging-log4j2 git commit: LOG4J2-1271 added benchmark for
different ways to format parameterized messages
LOG4J2-1271 added benchmark for different ways to format parameterized messages
Project: http://git-wip-us.apache.org/repos/asf/logging-log4j2/repo
Commit: http://git-wip-us.apache.org/repos/asf/logging-log4j2/commit/8242eb6b
Tree: http://git-wip-us.apache.org/repos/asf/logging-log4j2/tree/8242eb6b
Diff: http://git-wip-us.apache.org/repos/asf/logging-log4j2/diff/8242eb6b
Branch: refs/heads/LOG4J2-1278-gc-free-logger
Commit: 8242eb6b6234d0550a4285bc481ea9f3a0709ebb
Parents: b1e1db8
Author: rpopma <rp...@apache.org>
Authored: Mon Mar 28 17:10:35 2016 +0900
Committer: rpopma <rp...@apache.org>
Committed: Mon Mar 28 17:10:35 2016 +0900
----------------------------------------------------------------------
.../message/ParameterFormatterBenchmark.java | 188 +++++++++++++++++++
1 file changed, 188 insertions(+)
----------------------------------------------------------------------
http://git-wip-us.apache.org/repos/asf/logging-log4j2/blob/8242eb6b/log4j-perf/src/main/java/org/apache/logging/log4j/message/ParameterFormatterBenchmark.java
----------------------------------------------------------------------
diff --git a/log4j-perf/src/main/java/org/apache/logging/log4j/message/ParameterFormatterBenchmark.java b/log4j-perf/src/main/java/org/apache/logging/log4j/message/ParameterFormatterBenchmark.java
new file mode 100644
index 0000000..d7dbf5d
--- /dev/null
+++ b/log4j-perf/src/main/java/org/apache/logging/log4j/message/ParameterFormatterBenchmark.java
@@ -0,0 +1,188 @@
+/*
+ * Licensed to the Apache Software Foundation (ASF) under one or more
+ * contributor license agreements. See the NOTICE file distributed with
+ * this work for additional information regarding copyright ownership.
+ * The ASF licenses this file to You under the Apache license, Version 2.0
+ * (the "License"); you may not use this file except in compliance with
+ * the License. You may obtain a copy of the License at
+ *
+ * http://www.apache.org/licenses/LICENSE-2.0
+ *
+ * Unless required by applicable law or agreed to in writing, software
+ * distributed under the License is distributed on an "AS IS" BASIS,
+ * WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
+ * See the license for the specific language governing permissions and
+ * limitations under the license.
+ */
+
+package org.apache.logging.log4j.message;
+
+import java.util.concurrent.TimeUnit;
+
+import org.apache.logging.log4j.message.ParameterizedMessage;
+import org.apache.logging.log4j.perf.jmh.ParameterizedMessageBenchmark;
+import org.openjdk.jmh.annotations.Benchmark;
+import org.openjdk.jmh.annotations.BenchmarkMode;
+import org.openjdk.jmh.annotations.Mode;
+import org.openjdk.jmh.annotations.OutputTimeUnit;
+import org.openjdk.jmh.annotations.Scope;
+import org.openjdk.jmh.annotations.State;
+
+/**
+ * This benchmark is not in the perf.jmh package because it tests the package-protected ParameterFormatter class.
+ */
+// ============================== HOW TO RUN THIS TEST: ====================================
+//
+// single thread:
+// java -jar log4j-perf/target/benchmarks.jar ".*ParameterFormatterBench.*" -f 1 -wi 5 -i 10
+//
+// multiple threads (for example, 4 threads):
+// java -jar log4j-perf/target/benchmarks.jar ".*ParameterFormatterBench.*" -f 1 -wi 5 -i 10 -t 4 -si true
+//
+// Usage help:
+// java -jar log4j-perf/target/benchmarks.jar -help
+//
+@State(Scope.Benchmark)
+public class ParameterFormatterBenchmark {
+ private static final Object[] ARGS = { "arg1", "arg2", "arg3", "arg4", "arg5", "arg6", "arg7", "arg8",
+ "arg9", "arg10",};
+
+ @State(Scope.Thread)
+ public static class ThreadState {
+ StringBuilder buffer = new StringBuilder(2048);
+ int[] indices = new int[255];
+ char[] copy = new char[4096];
+ }
+
+ @Benchmark
+ @BenchmarkMode(Mode.SampleTime)
+ @OutputTimeUnit(TimeUnit.NANOSECONDS)
+ public int latency3ParamsV3(final ThreadState state) {
+ state.buffer.setLength(0);
+ String STR = "p1={}, p2={}, p3={}";
+ int length = STR.length();
+ STR.getChars(0, length, state.copy, 0);
+ int count = ParameterFormatter.countArgumentPlaceholders3(state.copy, length, state.indices);
+ ParameterFormatter.formatMessage3(state.buffer, state.copy, length, ARGS, count, state.indices);
+ return state.buffer.length();
+ }
+
+ @Benchmark
+ @BenchmarkMode(Mode.SampleTime)
+ @OutputTimeUnit(TimeUnit.NANOSECONDS)
+ public int latency5ParamsV3(final ThreadState state) {
+ state.buffer.setLength(0);
+ String STR = "p1={}, p2={}, p3={}, p4={}, p5={}";
+ int length = STR.length();
+ STR.getChars(0, length, state.copy, 0);
+ int count = ParameterFormatter.countArgumentPlaceholders3(state.copy, length, state.indices);
+ ParameterFormatter.formatMessage3(state.buffer, state.copy, length, ARGS, count, state.indices);
+ return state.buffer.length();
+ }
+
+ @Benchmark
+ @BenchmarkMode(Mode.SampleTime)
+ @OutputTimeUnit(TimeUnit.NANOSECONDS)
+ public int latency7ParamsV3(final ThreadState state) {
+ state.buffer.setLength(0);
+ String STR = "p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}";
+ int length = STR.length();
+ STR.getChars(0, length, state.copy, 0);
+ int count = ParameterFormatter.countArgumentPlaceholders3(state.copy, length, state.indices);
+ ParameterFormatter.formatMessage3(state.buffer, state.copy, length, ARGS, count, state.indices);
+ return state.buffer.length();
+ }
+
+ @Benchmark
+ @BenchmarkMode(Mode.SampleTime)
+ @OutputTimeUnit(TimeUnit.NANOSECONDS)
+ public int latency9ParamsV3(final ThreadState state) {
+ state.buffer.setLength(0);
+ String STR = "p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}, p8={}, p9={}";
+ int length = STR.length();
+ STR.getChars(0, length, state.copy, 0);
+ int count = ParameterFormatter.countArgumentPlaceholders3(state.copy, length, state.indices);
+ ParameterFormatter.formatMessage3(state.buffer, state.copy, length, ARGS, count, state.indices);
+ return state.buffer.length();
+ }
+
+ @Benchmark
+ @BenchmarkMode(Mode.SampleTime)
+ @OutputTimeUnit(TimeUnit.NANOSECONDS)
+ public int latency3ParamsV2(final ThreadState state) {
+ state.buffer.setLength(0);
+ int count = ParameterFormatter.countArgumentPlaceholders2("p1={}, p2={}, p3={}", state.indices);
+ ParameterFormatter.formatMessage2(state.buffer, "p1={}, p2={}, p3={}", ARGS, count, state.indices);
+ return state.buffer.length();
+ }
+
+ @Benchmark
+ @BenchmarkMode(Mode.SampleTime)
+ @OutputTimeUnit(TimeUnit.NANOSECONDS)
+ public int latency5ParamsV2(final ThreadState state) {
+ state.buffer.setLength(0);
+ int count = ParameterFormatter.countArgumentPlaceholders2("p1={}, p2={}, p3={}, p4={}, p5={}", state.indices);
+ ParameterFormatter.formatMessage2(state.buffer, "p1={}, p2={}, p3={}, p4={}, p5={}", ARGS, count, state.indices);
+ return state.buffer.length();
+ }
+
+ @Benchmark
+ @BenchmarkMode(Mode.SampleTime)
+ @OutputTimeUnit(TimeUnit.NANOSECONDS)
+ public int latency7ParamsV2(final ThreadState state) {
+ state.buffer.setLength(0);
+ int count = ParameterFormatter.countArgumentPlaceholders2("p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}", state.indices);
+ ParameterFormatter.formatMessage2(state.buffer, "p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}", ARGS, count, state.indices);
+ return state.buffer.length();
+ }
+
+ @Benchmark
+ @BenchmarkMode(Mode.SampleTime)
+ @OutputTimeUnit(TimeUnit.NANOSECONDS)
+ public int latency9ParamsV2(final ThreadState state) {
+ state.buffer.setLength(0);
+ int count = ParameterFormatter.countArgumentPlaceholders2("p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}, p8={}, p9={}", state.indices);
+ ParameterFormatter.formatMessage2(state.buffer, "p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}, p8={}, p9={}", ARGS, count, state.indices);
+ return state.buffer.length();
+ }
+
+ @Benchmark
+ @BenchmarkMode(Mode.SampleTime)
+ @OutputTimeUnit(TimeUnit.NANOSECONDS)
+ public int latency3Params(final ThreadState state) {
+ state.buffer.setLength(0);
+ int count = ParameterFormatter.countArgumentPlaceholders("p1={}, p2={}, p3={}");
+ ParameterFormatter.formatMessage(state.buffer, "p1={}, p2={}, p3={}", ARGS, count);
+ return state.buffer.length();
+ }
+
+ @Benchmark
+ @BenchmarkMode(Mode.SampleTime)
+ @OutputTimeUnit(TimeUnit.NANOSECONDS)
+ public int latency5Params(final ThreadState state) {
+ state.buffer.setLength(0);
+ int count = ParameterFormatter.countArgumentPlaceholders("p1={}, p2={}, p3={}, p4={}, p5={}");
+ ParameterFormatter.formatMessage(state.buffer, "p1={}, p2={}, p3={}, p4={}, p5={}", ARGS, count);
+ return state.buffer.length();
+ }
+
+ @Benchmark
+ @BenchmarkMode(Mode.SampleTime)
+ @OutputTimeUnit(TimeUnit.NANOSECONDS)
+ public int latency7Params(final ThreadState state) {
+ state.buffer.setLength(0);
+ int count = ParameterFormatter.countArgumentPlaceholders("p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}");
+ ParameterFormatter.formatMessage(state.buffer, "p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}", ARGS, count);
+ return state.buffer.length();
+ }
+
+ @Benchmark
+ @BenchmarkMode(Mode.SampleTime)
+ @OutputTimeUnit(TimeUnit.NANOSECONDS)
+ public int latency9Params(final ThreadState state) {
+ state.buffer.setLength(0);
+ int count = ParameterFormatter.countArgumentPlaceholders("p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}, p8={}, p9={}");
+ ParameterFormatter.formatMessage(state.buffer, "p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}, p8={}, p9={}", ARGS, count);
+ return state.buffer.length();
+ }
+}