/*
 * 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.util;

import java.io.Serializable;
import java.text.DecimalFormat;

Primarily used in unit tests, but can be used to track elapsed time for a request or portion of any other operation so long as all the timer methods are called on the same thread in which it was started. Calling start on multiple threads will cause the the times to be aggregated.
/** * Primarily used in unit tests, but can be used to track elapsed time for a request or portion of any other operation * so long as all the timer methods are called on the same thread in which it was started. Calling start on * multiple threads will cause the the times to be aggregated. */
public class Timer implements Serializable, StringBuilderFormattable { private static final long serialVersionUID = 9175191792439630013L; private final String name; // The timer's name public enum Status { Started, Stopped, Paused } private Status status; // The timer's status private long elapsedTime; // The elapsed time private final int iterations; private static long NANO_PER_SECOND = 1000000000L; private static long NANO_PER_MINUTE = NANO_PER_SECOND * 60; private static long NANO_PER_HOUR = NANO_PER_MINUTE * 60; private ThreadLocal<Long> startTime = new ThreadLocal<Long>() { @Override protected Long initialValue() { return 0L; } };
Constructor.
Params:
  • name – the timer name.
/** * Constructor. * @param name the timer name. */
public Timer(final String name) { this(name, 0); }
Constructor.
Params:
  • name – the timer name.
/** * Constructor. * * @param name the timer name. */
public Timer(final String name, final int iterations) { this.name = name; status = Status.Stopped; this.iterations = (iterations > 0) ? iterations : 0; }
Start the timer.
/** * Start the timer. */
public synchronized void start() { startTime.set(System.nanoTime()); elapsedTime = 0; status = Status.Started; } public synchronized void startOrResume() { if (status == Status.Stopped) { start(); } else { resume(); } }
Stop the timer.
/** * Stop the timer. */
public synchronized String stop() { elapsedTime += System.nanoTime() - startTime.get(); startTime.set(0L); status = Status.Stopped; return toString(); }
Pause the timer.
/** * Pause the timer. */
public synchronized void pause() { elapsedTime += System.nanoTime() - startTime.get(); startTime.set(0L); status = Status.Paused; }
Resume the timer.
/** * Resume the timer. */
public synchronized void resume() { startTime.set(System.nanoTime()); status = Status.Started; }
Accessor for the name.
Returns:the timer's name.
/** * Accessor for the name. * @return the timer's name. */
public String getName() { return name; }
Access the elapsed time.
Returns:the elapsed time.
/** * Access the elapsed time. * * @return the elapsed time. */
public long getElapsedTime() { return elapsedTime / 1000000; }
Access the elapsed time.
Returns:the elapsed time.
/** * Access the elapsed time. * * @return the elapsed time. */
public long getElapsedNanoTime() { return elapsedTime; }
Returns the name of the last operation performed on this timer (Start, Stop, Pause or Resume).
Returns:the string representing the last operation performed.
/** * Returns the name of the last operation performed on this timer (Start, Stop, Pause or * Resume). * @return the string representing the last operation performed. */
public Status getStatus() { return status; }
Returns the String representation of the timer based upon its current state
/** * Returns the String representation of the timer based upon its current state */
@Override public String toString() { final StringBuilder result = new StringBuilder(); formatTo(result); return result.toString(); } @Override public void formatTo(final StringBuilder buffer) { buffer.append("Timer ").append(name); switch (status) { case Started: buffer.append(" started"); break; case Paused: buffer.append(" paused"); break; case Stopped: long nanoseconds = elapsedTime; // Get elapsed hours long hours = nanoseconds / NANO_PER_HOUR; // Get remaining nanoseconds nanoseconds = nanoseconds % NANO_PER_HOUR; // Get minutes long minutes = nanoseconds / NANO_PER_MINUTE; // Get remaining nanoseconds nanoseconds = nanoseconds % NANO_PER_MINUTE; // Get seconds long seconds = nanoseconds / NANO_PER_SECOND; // Get remaining nanoseconds nanoseconds = nanoseconds % NANO_PER_SECOND; String elapsed = Strings.EMPTY; if (hours > 0) { elapsed += hours + " hours "; } if (minutes > 0 || hours > 0) { elapsed += minutes + " minutes "; } DecimalFormat numFormat; numFormat = new DecimalFormat("#0"); elapsed += numFormat.format(seconds) + '.'; numFormat = new DecimalFormat("000000000"); elapsed += numFormat.format(nanoseconds) + " seconds"; buffer.append(" stopped. Elapsed time: ").append(elapsed); if (iterations > 0) { nanoseconds = elapsedTime / iterations; // Get elapsed hours hours = nanoseconds / NANO_PER_HOUR; // Get remaining nanoseconds nanoseconds = nanoseconds % NANO_PER_HOUR; // Get minutes minutes = nanoseconds / NANO_PER_MINUTE; // Get remaining nanoseconds nanoseconds = nanoseconds % NANO_PER_MINUTE; // Get seconds seconds = nanoseconds / NANO_PER_SECOND; // Get remaining nanoseconds nanoseconds = nanoseconds % NANO_PER_SECOND; elapsed = Strings.EMPTY; if (hours > 0) { elapsed += hours + " hours "; } if (minutes > 0 || hours > 0) { elapsed += minutes + " minutes "; } numFormat = new DecimalFormat("#0"); elapsed += numFormat.format(seconds) + '.'; numFormat = new DecimalFormat("000000000"); elapsed += numFormat.format(nanoseconds) + " seconds"; buffer.append(" Average per iteration: ").append(elapsed); } break; default: buffer.append(' ').append(status); break; } } @Override public boolean equals(final Object o) { if (this == o) { return true; } if (!(o instanceof Timer)) { return false; } final Timer timer = (Timer) o; if (elapsedTime != timer.elapsedTime) { return false; } if (startTime != timer.startTime) { return false; } if (name != null ? !name.equals(timer.name) : timer.name != null) { return false; } if (status != null ? !status.equals(timer.status) : timer.status != null) { return false; } return true; } @Override public int hashCode() { int result; result = (name != null ? name.hashCode() : 0); result = 29 * result + (status != null ? status.hashCode() : 0); long time = startTime.get(); result = 29 * result + (int) (time ^ (time >>> 32)); result = 29 * result + (int) (elapsedTime ^ (elapsedTime >>> 32)); return result; } }