java/com/google/gerrit/metrics/Timer2.java

// Copyright (C) 2015 The Android Open Source Project
//
// 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 com.google.gerrit.metrics;

import static java.util.concurrent.TimeUnit.NANOSECONDS;

import com.google.common.flogger.FluentLogger;
import com.google.gerrit.extensions.registration.RegistrationHandle;
import com.google.gerrit.server.cancellation.RequestStateContext;
import com.google.gerrit.server.logging.LoggingContext;
import com.google.gerrit.server.logging.Metadata;
import com.google.gerrit.server.logging.PerformanceLogRecord;
import java.util.concurrent.TimeUnit;

/**
 * Records elapsed time for an operation or span.
 *
 * <p>Typical usage in a try-with-resources block:
 *
 * <pre>
 * try (Timer2.Context ctx = timer.start(field)) {
 * }
 * </pre>
 *
 * @param <F1> type of the field.
 * @param <F2> type of the field.
 */
public abstract class Timer2<F1, F2> implements RegistrationHandle {
  private static final FluentLogger logger = FluentLogger.forEnclosingClass();

  public static class Context<F1, F2> extends TimerContext {
    private final Timer2<F1, F2> timer;
    private final F1 fieldValue1;
    private final F2 fieldValue2;

    Context(Timer2<F1, F2> timer, F1 fieldValue1, F2 fieldValue2) {
      this.timer = timer;
      this.fieldValue1 = fieldValue1;
      this.fieldValue2 = fieldValue2;
    }

    @Override
    public void record(long elapsed) {
      timer.record(fieldValue1, fieldValue2, elapsed, NANOSECONDS);
    }
  }

  private boolean suppressLogging;

  protected final String name;
  protected final Field<F1> field1;
  protected final Field<F2> field2;

  public Timer2(String name, Field<F1> field1, Field<F2> field2) {
    this.name = name;
    this.field1 = field1;
    this.field2 = field2;
  }

  /**
   * Begin a timer for the current block, value will be recorded when closed.
   *
   * @param fieldValue1 bucket to record the timer
   * @param fieldValue2 bucket to record the timer
   * @return timer context
   */
  public Context<F1, F2> start(F1 fieldValue1, F2 fieldValue2) {
    RequestStateContext.abortIfCancelled();
    return new Context<>(this, fieldValue1, fieldValue2);
  }

  /**
   * Record a value in the distribution.
   *
   * @param fieldValue1 bucket to record the timer
   * @param fieldValue2 bucket to record the timer
   * @param value value to record
   * @param unit time unit of the value
   */
  public final void record(F1 fieldValue1, F2 fieldValue2, long value, TimeUnit unit) {
    long durationMs = unit.toMillis(value);

    Metadata.Builder metadataBuilder = Metadata.builder();
    field1.metadataMapper().accept(metadataBuilder, fieldValue1);
    field2.metadataMapper().accept(metadataBuilder, fieldValue2);
    Metadata metadata = metadataBuilder.build();

    if (!suppressLogging) {
      LoggingContext.getInstance()
          .addPerformanceLogRecord(() -> PerformanceLogRecord.create(name, durationMs, metadata));
      logger.atFinest().log(
          "%s (%s = %s, %s = %s) took %dms",
          name, field1.name(), fieldValue1, field2.name(), fieldValue2, durationMs);
    }

    doRecord(fieldValue1, fieldValue2, value, unit);
    RequestStateContext.abortIfCancelled();
  }

  /** Suppress logging (debug log and performance log) when values are recorded. */
  public final Timer2<F1, F2> suppressLogging() {
    this.suppressLogging = true;
    return this;
  }

  /**
   * Record a value in the distribution.
   *
   * @param fieldValue1 bucket to record the timer
   * @param fieldValue2 bucket to record the timer
   * @param value value to record
   * @param unit time unit of the value
   */
  protected abstract void doRecord(F1 fieldValue1, F2 fieldValue2, long value, TimeUnit unit);
}