tests/src/java/org/apache/log4j/performance/Loop.java

/*
 * Copyright 1999,2004 The Apache Software Foundation.
 *
 * Licensed 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.log4j.performance;

import org.apache.log4j.ConsoleAppender;
import org.apache.log4j.Level;
import org.apache.log4j.LogManager;
import org.apache.log4j.Logger;
import org.apache.log4j.PatternLayout;
import org.apache.log4j.joran.JoranConfigurator;


/**
 * Logs in a loop a number of times and measure the elapsed time.
 *
 * @author Ceki Gülcü
 */
public class Loop {
  static int runLength;
  static final Logger logger = Logger.getLogger(Loop.class);

  public static void main(String[] args) throws Exception {
    Logger j = Logger.getLogger("org.apache.log4j.joran");
    j.setAdditivity(false);
    j.setLevel(Level.WARN);
    ConsoleAppender a = new ConsoleAppender();
    a.setLayout(new PatternLayout("%d %level %c - %m%n"));
    a.setName("console");
    a.activateOptions();
    j.addAppender(a);

    if (args.length == 2) {
      init(args[0], args[1]);
    } else {
      usage("Wrong number of arguments.");
    }

    //memPrint();
    loop(1000, logger, "Some fix message of medium length.");
    //memPrint();

    long res = loop(runLength, logger, "Some fix message of medium length.");
    double average = (res * 1000.0) / runLength;
    System.out.println(
      "Loop completed in [" + res + "] milliseconds, or [" + average
      + "] microseconds per log.");

    //memPrint();
  }

  static void usage(String msg) {
    System.err.println(msg);
    System.err.println(
      "Usage: java " + Loop.class.getName() + " runLength configFile");
    System.err.println("\trunLength (integer) is the length of test loop.");
    System.err.println("\tconfigFile is an XML configuration file");

    System.exit(1);
  }

  static void memPrint() {
    Runtime runtime = Runtime.getRuntime();
    long total = runtime.totalMemory();
    long free = runtime.freeMemory();
    long used = total - free;
    System.out.println(
      "Total: " + total + ", free: " + free + ", used: " + used);
  }

  static void init(String runLengthStr, String configFile)
    throws Exception {
    runLength = Integer.parseInt(runLengthStr);
    JoranConfigurator jc = new JoranConfigurator();
    jc.doConfigure(configFile, LogManager.getLoggerRepository());
  }

  static long loop(long len, Logger logger, String msg) {
    long before = System.currentTimeMillis();
    for (int i = 0; i < len; i++) {
      logger.debug(msg);
      //if(i == 1000) {
        //memPrint();
      //}
    }
    return (System.currentTimeMillis() - before);
  }
}