/*
* 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.perf.jmh;
import java.io.File;
import java.util.concurrent.TimeUnit;
import org.apache.logging.log4j.LogManager;
import org.apache.logging.log4j.Logger;
import org.apache.logging.log4j.core.LifeCycle;
import org.apache.logging.log4j.perf.util.BenchmarkMessageParams;
import org.openjdk.jmh.annotations.Benchmark;
import org.openjdk.jmh.annotations.BenchmarkMode;
import org.openjdk.jmh.annotations.Level;
import org.openjdk.jmh.annotations.Mode;
import org.openjdk.jmh.annotations.OutputTimeUnit;
import org.openjdk.jmh.annotations.Scope;
import org.openjdk.jmh.annotations.Setup;
import org.openjdk.jmh.annotations.State;
import org.openjdk.jmh.annotations.TearDown;
import static org.apache.logging.log4j.perf.util.BenchmarkMessageParams.*;
/**
* Tests Log4j2 Async Loggers performance.
*/
// ============================== HOW TO RUN THIS TEST: ====================================
//
// single thread:
// java -jar log4j-perf/target/benchmarks.jar ".*AsyncLoggersBenchmark.*" -f 1 -wi 10 -i 20
//
// multiple threads (for example, 4 threads):
// java -jar log4j-perf/target/benchmarks.jar ".*AsyncLoggersBenchmark.*" -f 1 -wi 10 -i 20 -t 4 -si true
//
// Usage help:
// java -jar log4j-perf/target/benchmarks.jar -help
//
@State(Scope.Thread)
public class AsyncLoggersBenchmark {
Logger logger;
@Setup(Level.Trial)
public void up() {
System.setProperty("log4j.configurationFile", "perf-WithoutAnyAppender.xml");
System.setProperty("Log4jContextSelector", "org.apache.logging.log4j.core.async.AsyncLoggerContextSelector");
System.setProperty("AsyncLogger.RingBufferSize", "262144");
System.setProperty("AsyncLogger.WaitStrategy", "Yield");
//System.setProperty("log4j2.enable.threadlocals", "true");
//System.setProperty("log4j.format.msg.async", "true");
logger = LogManager.getLogger(getClass());
new File("perftest.log").delete();
}
@TearDown(Level.Trial)
public void down() {
((LifeCycle) LogManager.getContext(false)).stop();
new File("perftest.log").delete();
}
@Benchmark
@BenchmarkMode(Mode.Throughput)
@OutputTimeUnit(TimeUnit.SECONDS)
public void throughputSimple() {
logger.info(BenchmarkMessageParams.TEST);
}
@Benchmark
@BenchmarkMode(Mode.Throughput)
@OutputTimeUnit(TimeUnit.SECONDS)
public void throughput1Param() {
logger.info("p1={}", one);
}
@Benchmark
@BenchmarkMode(Mode.Throughput)
@OutputTimeUnit(TimeUnit.SECONDS)
public void throughput2Params() {
logger.info("p1={}, p2={}", one, two);
}
@Benchmark
@BenchmarkMode(Mode.Throughput)
@OutputTimeUnit(TimeUnit.SECONDS)
public void throughput3Params() {
logger.info("p1={}, p2={}, p3={}", one, two, three);
}
@Benchmark
@BenchmarkMode(Mode.Throughput)
@OutputTimeUnit(TimeUnit.SECONDS)
public void throughput4Params() {
logger.info("p1={}, p2={}, p3={}, p4={}", one, two, three, four);
}
@Benchmark
@BenchmarkMode(Mode.Throughput)
@OutputTimeUnit(TimeUnit.SECONDS)
public void throughput5Params() {
logger.info("p1={}, p2={}, p3={}, p4={}, p5={}", one, two, three, four, five);
}
@Benchmark
@BenchmarkMode(Mode.Throughput)
@OutputTimeUnit(TimeUnit.SECONDS)
public void throughput6Params() {
logger.info("p1={}, p2={}, p3={}, p4={}, p5={}, p6={}", one, two, three, four, five, six);
}
@Benchmark
@BenchmarkMode(Mode.Throughput)
@OutputTimeUnit(TimeUnit.SECONDS)
public void throughput7Params() {
logger.info("p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}", one, two, three, four, five, six, seven);
}
@Benchmark
@BenchmarkMode(Mode.Throughput)
@OutputTimeUnit(TimeUnit.SECONDS)
public void throughput8Params() {
logger.info("p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}, p8={}", one, two, three, four, five, six, seven,
eight);
}
@Benchmark
@BenchmarkMode(Mode.Throughput)
@OutputTimeUnit(TimeUnit.SECONDS)
public void throughput9Params() {
logger.info("p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}, p8={}, p9={}", one, two, three, four, five, six,
seven, eight, nine);
}
@Benchmark
@BenchmarkMode(Mode.Throughput)
@OutputTimeUnit(TimeUnit.SECONDS)
public void throughput10Params() {
logger.info("p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}, p8={}, p9={}, p10={}", one, two, three, four,
five, six, seven, eight, nine, ten);
}
@Benchmark
@BenchmarkMode(Mode.Throughput)
@OutputTimeUnit(TimeUnit.SECONDS)
public void throughput11Params() {
logger.info("p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}, p8={}, p9={}, p10={}, p11={}", one, two, three,
four, five, six, seven, eight, nine, ten, eleven);
}
// @Benchmark
// @BenchmarkMode(Mode.SampleTime)
// @OutputTimeUnit(TimeUnit.NANOSECONDS)
// public void latencySimple() {
// logger.info(TEST);
// }
//
// @Benchmark
// @BenchmarkMode(Mode.SampleTime)
// @OutputTimeUnit(TimeUnit.NANOSECONDS)
// public void latency3Params() {
// logger.info("p1={}, p2={}, p3={}", "1", "2", "3");
// }
//
// @Benchmark
// @BenchmarkMode(Mode.SampleTime)
// @OutputTimeUnit(TimeUnit.NANOSECONDS)
// public void latency5Params() {
// logger.info("p1={}, p2={}, p3={}, p4={}, p5={}", "1", "2", "3", "4", "5");
// }
//
// @Benchmark
// @BenchmarkMode(Mode.SampleTime)
// @OutputTimeUnit(TimeUnit.NANOSECONDS)
// public void latency7Params() {
// logger.info("p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}", "1", "2", "3", "4", "5", "6", "7");
// }
//
// @Benchmark
// @BenchmarkMode(Mode.SampleTime)
// @OutputTimeUnit(TimeUnit.NANOSECONDS)
// public void latency9Params() {
// logger.info("p1={}, p2={}, p3={}, p4={}, p5={}, p6={}, p7={}, p8={}, p9={}", "1", "2", "3", "4", "5", "6", "7", "8", "9");
// }
}