summaryrefslogtreecommitdiff
path: root/lib/gitlab/checks/timed_logger.rb
blob: 0db38d32bb32aad57b7411f522a9c23c604a3a7e (plain)
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
# frozen_string_literal: true

module Gitlab
  module Checks
    class TimedLogger
      TimeoutError = Class.new(StandardError)

      attr_reader :start_time, :header, :log, :timeout

      def initialize(start_time: Time.now, log: [], header: "", timeout:)
        @start_time = start_time
        @timeout = timeout
        @header = header
        @log = log
      end

      # Adds trace of method being tracked with
      # the correspondent time it took to run it.
      # We make use of the start default argument
      # on unit tests related to this method
      #
      def log_timed(log_message, start = Time.now)
        check_timeout_reached

        timed = true

        yield

        append_message(log_message + time_suffix_message(start: start))
      rescue GRPC::DeadlineExceeded, TimeoutError
        args = { cancelled: true }
        args[:start] = start if timed

        append_message(log_message + time_suffix_message(**args))

        raise TimeoutError
      end

      def check_timeout_reached
        return unless time_expired?

        raise TimeoutError
      end

      def time_left
        (start_time + timeout.seconds) - Time.now
      end

      def full_message
        header + log.join("\n")
      end

      # We always want to append in-place on the log
      def append_message(message)
        log << message
      end

      private

      def time_expired?
        time_left <= 0
      end

      def time_suffix_message(cancelled: false, start: nil)
        return " (#{elapsed_time(start)}ms)" unless cancelled

        if start
          " (cancelled after #{elapsed_time(start)}ms)"
        else
          " (cancelled)"
        end
      end

      def elapsed_time(start)
        to_ms(Time.now - start)
      end

      def to_ms(elapsed)
        (elapsed.to_f * 1000).round(2)
      end
    end
  end
end