| /* |
| * 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.sql.Connection; |
| import java.sql.DriverManager; |
| import java.sql.SQLException; |
| import java.sql.Statement; |
| import java.util.concurrent.TimeUnit; |
| |
| import org.apache.logging.log4j.LogManager; |
| import org.apache.logging.log4j.Logger; |
| import org.apache.logging.log4j.core.LoggerContext; |
| import org.apache.logging.log4j.core.appender.db.jpa.JpaAppender; |
| import org.apache.logging.log4j.core.config.ConfigurationFactory; |
| import org.apache.logging.log4j.core.config.DefaultConfiguration; |
| import org.apache.logging.log4j.core.util.Closer; |
| import org.apache.logging.log4j.status.StatusLogger; |
| 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.Setup; |
| import org.openjdk.jmh.annotations.State; |
| import org.openjdk.jmh.annotations.TearDown; |
| import org.openjdk.jmh.infra.Blackhole; |
| |
| /** |
| * Tests the overhead of a number of JPA Appenders. |
| */ |
| // ============================== HOW TO RUN THIS TEST: ==================================== |
| // |
| // java -jar log4j-perf/target/benchmarks.jar ".*JdbcAppenderBenchmark.*" -f 1 -wi 5 -i 5 |
| // |
| // Usage help: |
| // java -jar log4j-perf/target/benchmarks.jar -help |
| // |
| @State(Scope.Thread) |
| public class JpaAppenderBenchmark { |
| private Logger loggerH2; |
| private Logger loggerHSQLDB; |
| private Connection connectionHSQLDB; |
| private Connection connectionH2; |
| private final RuntimeException exception = new RuntimeException("Hello, world!"); |
| |
| @Setup |
| public void setup() throws Exception { |
| connectionHSQLDB = getConnectionHSQLDB(); |
| connectionH2 = getConnectionH2(); |
| |
| System.setProperty(ConfigurationFactory.CONFIGURATION_FILE_PROPERTY, "log4j2-jpa-appender.xml"); |
| final LoggerContext context = LoggerContext.getContext(false); |
| if (context.getConfiguration() instanceof DefaultConfiguration) { |
| context.reconfigure(); |
| } |
| StatusLogger.getLogger().reset(); |
| loggerH2 = LogManager.getLogger("H2Logger"); |
| loggerHSQLDB = LogManager.getLogger("HSQLDBLogger"); |
| } |
| |
| @BenchmarkMode(Mode.Throughput) |
| @OutputTimeUnit(TimeUnit.SECONDS) |
| @Benchmark |
| public void testThroughputH2Message(final Blackhole bh) { |
| loggerH2.info("Test message"); |
| } |
| |
| @BenchmarkMode(Mode.Throughput) |
| @OutputTimeUnit(TimeUnit.SECONDS) |
| @Benchmark |
| public void testThroughputH2Exception(final Blackhole bh) { |
| loggerH2.warn("Test message", exception); |
| } |
| |
| @BenchmarkMode(Mode.SampleTime) |
| @OutputTimeUnit(TimeUnit.MICROSECONDS) |
| @Benchmark |
| public void testResponseTimeH2Message(final Blackhole bh) { |
| loggerH2.info("Test message"); |
| } |
| |
| @BenchmarkMode(Mode.SampleTime) |
| @OutputTimeUnit(TimeUnit.MICROSECONDS) |
| @Benchmark |
| public void testResponseTimeH2Exception(final Blackhole bh) { |
| loggerH2.warn("Test message", exception); |
| } |
| |
| @BenchmarkMode(Mode.Throughput) |
| @OutputTimeUnit(TimeUnit.SECONDS) |
| @Benchmark |
| public void testThroughputHSQLDBMessage(final Blackhole bh) { |
| loggerHSQLDB.info("Test message"); |
| } |
| |
| @BenchmarkMode(Mode.Throughput) |
| @OutputTimeUnit(TimeUnit.SECONDS) |
| @Benchmark |
| public void testThroughputHSQLDBException(final Blackhole bh) { |
| loggerHSQLDB.warn("Test message", exception); |
| } |
| |
| @BenchmarkMode(Mode.SampleTime) |
| @OutputTimeUnit(TimeUnit.MICROSECONDS) |
| @Benchmark |
| public void testResponseTimeHSQLDBMessage(final Blackhole bh) { |
| loggerHSQLDB.info("Test message"); |
| } |
| |
| @BenchmarkMode(Mode.SampleTime) |
| @OutputTimeUnit(TimeUnit.MICROSECONDS) |
| @Benchmark |
| public void testResponseTimeHSQLDBException(final Blackhole bh) { |
| loggerHSQLDB.warn("Test message", exception); |
| } |
| |
| @TearDown |
| public void tearDown() throws SQLException { |
| final LoggerContext context = LoggerContext.getContext(false); |
| try { |
| ((JpaAppender) context.getConfiguration().getAppender("H2Appender")).getManager().close(); |
| ((JpaAppender) context.getConfiguration().getAppender("HSQLDBAppender")).getManager().close(); |
| } finally { |
| System.clearProperty(ConfigurationFactory.CONFIGURATION_FILE_PROPERTY); |
| // context.reconfigure(); |
| // StatusLogger.getLogger().reset(); |
| |
| Statement statement = null; |
| try { |
| statement = connectionHSQLDB.createStatement(); |
| statement.execute("SHUTDOWN"); |
| } catch (final SQLException ignore) { |
| // ignore |
| } finally { |
| Closer.closeSilently(statement); |
| Closer.closeSilently(connectionHSQLDB); |
| } |
| try { |
| statement = connectionH2.createStatement(); |
| statement.execute("SHUTDOWN"); |
| } catch (final SQLException ignore) { |
| // ignore |
| } finally { |
| Closer.closeSilently(statement); |
| Closer.closeSilently(connectionH2); |
| } |
| } |
| } |
| |
| /** |
| * Referred from log4j2-jdbc-appender.xml. |
| */ |
| public static Connection getConnectionH2() throws Exception { |
| Class.forName("org.h2.Driver"); |
| final Connection connection = DriverManager.getConnection("jdbc:h2:mem:Log4j", "sa", ""); |
| final Statement statement = connection.createStatement(); |
| statement |
| .executeUpdate("CREATE TABLE jpaBasicLogEntry ( " |
| + "id INTEGER IDENTITY, timemillis BIGINT, level NVARCHAR(10), loggerName NVARCHAR(255), " |
| + "message NVARCHAR(1024), thrown NVARCHAR(1048576), contextMapJson NVARCHAR(1048576)," |
| + "loggerFQCN NVARCHAR(1024), contextStack NVARCHAR(1048576), marker NVARCHAR(255), source NVARCHAR(2048)," |
| + "threadName NVARCHAR(255)" + " )"); |
| statement.close(); |
| return connection; |
| } |
| |
| /** |
| * Referred from log4j2-jdbc-appender.xml. |
| */ |
| public static Connection getConnectionHSQLDB() throws Exception { |
| Class.forName("org.hsqldb.jdbcDriver"); |
| final Connection connection = DriverManager.getConnection("jdbc:hsqldb:mem:Log4j", "sa", ""); |
| final Statement statement = connection.createStatement(); |
| statement.executeUpdate("CREATE TABLE jpaBasicLogEntry ( " |
| + "id INTEGER IDENTITY, timemillis BIGINT, level VARCHAR(10), loggerName VARCHAR(255), " |
| + "message VARCHAR(1024), thrown VARCHAR(1048576), contextMapJson VARCHAR(1048576)," |
| + "loggerFQCN VARCHAR(1024), contextStack VARCHAR(1048576), marker VARCHAR(255), source VARCHAR(2048)," |
| + "threadName VARCHAR(255)" + " )"); |
| statement.close(); |
| return connection; |
| } |
| } |