John Park | 33858a3 | 2018-09-28 23:05:48 -0700 | [diff] [blame] | 1 | #ifndef AOS_UTIL_LOG_INTERVAL_H_ |
| 2 | #define AOS_UTIL_LOG_INTERVAL_H_ |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 3 | |
Austin Schuh | 60e7794 | 2022-05-16 17:48:24 -0700 | [diff] [blame] | 4 | #include <string> |
| 5 | |
John Park | 33858a3 | 2018-09-28 23:05:48 -0700 | [diff] [blame] | 6 | #include "aos/logging/logging.h" |
James Kuszmaul | 651fc3f | 2019-05-15 21:14:25 -0700 | [diff] [blame] | 7 | #include "aos/time/time.h" |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 8 | |
Stephan Pleines | d99b1ee | 2024-02-02 20:56:44 -0800 | [diff] [blame] | 9 | namespace aos::util { |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 10 | |
| 11 | // A class to help with logging things that happen a lot only occasionally. |
| 12 | // |
| 13 | // Intended use { |
Austin Schuh | f2a50ba | 2016-12-24 16:16:26 -0800 | [diff] [blame] | 14 | // static LogInterval interval(::std::chrono::millseconds(200)); |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 15 | // |
| 16 | // if (WantToLog()) { |
| 17 | // interval.WantToLog(); |
| 18 | // } |
| 19 | // if (interval.ShouldLog()) { |
| 20 | // LOG(DEBUG, "thingie happened! (%d times)\n", interval.Count()); |
| 21 | // } |
| 22 | // } |
| 23 | class LogInterval { |
| 24 | public: |
Austin Schuh | 61bdc60 | 2016-12-04 19:10:10 -0800 | [diff] [blame] | 25 | constexpr LogInterval(::std::chrono::nanoseconds interval) |
| 26 | : interval_(interval) {} |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 27 | |
| 28 | void WantToLog() { |
| 29 | if (count_ == 0) { |
Austin Schuh | 9fe68f7 | 2019-08-10 19:32:03 -0700 | [diff] [blame] | 30 | // TODO(austin): event loops! |
Austin Schuh | 61bdc60 | 2016-12-04 19:10:10 -0800 | [diff] [blame] | 31 | last_done_ = ::aos::monotonic_clock::now(); |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 32 | } |
| 33 | ++count_; |
| 34 | } |
| 35 | bool ShouldLog() { |
Austin Schuh | 61bdc60 | 2016-12-04 19:10:10 -0800 | [diff] [blame] | 36 | const ::aos::monotonic_clock::time_point now = |
| 37 | ::aos::monotonic_clock::now(); |
| 38 | const bool r = now >= interval_ + last_done_ && count_ > 0; |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 39 | if (r) { |
| 40 | last_done_ = now; |
| 41 | } |
| 42 | return r; |
| 43 | } |
| 44 | int Count() { |
| 45 | const int r = count_; |
| 46 | count_ = 0; |
| 47 | return r; |
| 48 | } |
| 49 | |
Austin Schuh | 61bdc60 | 2016-12-04 19:10:10 -0800 | [diff] [blame] | 50 | ::std::chrono::nanoseconds interval() const { return interval_; } |
Brian Silverman | 50a9d03 | 2014-02-16 17:20:57 -0800 | [diff] [blame] | 51 | |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 52 | private: |
Austin Schuh | 61bdc60 | 2016-12-04 19:10:10 -0800 | [diff] [blame] | 53 | int count_ = 0; |
| 54 | const ::std::chrono::nanoseconds interval_; |
| 55 | ::aos::monotonic_clock::time_point last_done_ = |
| 56 | ::aos::monotonic_clock::min_time; |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 57 | }; |
| 58 | |
| 59 | // This one is even easier to use. It always logs with a message "%s %d |
Brian Silverman | 9c9ab42 | 2014-02-25 13:42:53 -0800 | [diff] [blame] | 60 | // times\n". Call LOG_INTERVAL wherever it should log and make sure Print gets |
| 61 | // called often (ie not after a conditional return) |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 62 | class SimpleLogInterval { |
| 63 | public: |
Austin Schuh | 61bdc60 | 2016-12-04 19:10:10 -0800 | [diff] [blame] | 64 | SimpleLogInterval(::std::chrono::nanoseconds interval, log_level level, |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 65 | const ::std::string &message) |
| 66 | : interval_(interval), level_(level), message_(message) {} |
| 67 | |
Austin Schuh | f257f3c | 2019-10-27 21:00:43 -0700 | [diff] [blame] | 68 | #define AOS_LOG_INTERVAL(simple_log) \ |
Austin Schuh | c69e679 | 2021-02-05 11:20:05 -0800 | [diff] [blame] | 69 | simple_log.WantToLog(LOG_SOURCENAME ": " AOS_STRINGIFY(__LINE__)) |
Brian Silverman | 9c9ab42 | 2014-02-25 13:42:53 -0800 | [diff] [blame] | 70 | void WantToLog(const char *context) { |
| 71 | context_ = context; |
| 72 | interval_.WantToLog(); |
| 73 | } |
| 74 | |
| 75 | void Print() { |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 76 | if (interval_.ShouldLog()) { |
Austin Schuh | f257f3c | 2019-10-27 21:00:43 -0700 | [diff] [blame] | 77 | AOS_CHECK_NOTNULL(context_); |
Brian Silverman | 9c9ab42 | 2014-02-25 13:42:53 -0800 | [diff] [blame] | 78 | log_do(level_, "%s: %.*s %d times over %f sec\n", context_, |
Brian Silverman | 9747834 | 2014-02-16 20:26:31 -0800 | [diff] [blame] | 79 | static_cast<int>(message_.size()), message_.data(), |
Austin Schuh | 61bdc60 | 2016-12-04 19:10:10 -0800 | [diff] [blame] | 80 | interval_.Count(), |
James Kuszmaul | 651fc3f | 2019-05-15 21:14:25 -0700 | [diff] [blame] | 81 | ::aos::time::DurationInSeconds(interval_.interval())); |
Brian Silverman | 9c9ab42 | 2014-02-25 13:42:53 -0800 | [diff] [blame] | 82 | context_ = NULL; |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 83 | } |
| 84 | } |
| 85 | |
| 86 | private: |
| 87 | LogInterval interval_; |
| 88 | const log_level level_; |
| 89 | const ::std::string message_; |
Brian Silverman | 9c9ab42 | 2014-02-25 13:42:53 -0800 | [diff] [blame] | 90 | const char *context_ = NULL; |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 91 | }; |
| 92 | |
Stephan Pleines | d99b1ee | 2024-02-02 20:56:44 -0800 | [diff] [blame] | 93 | } // namespace aos::util |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 94 | |
John Park | 33858a3 | 2018-09-28 23:05:48 -0700 | [diff] [blame] | 95 | #endif // AOS_UTIL_LOG_INTERVAL_H_ |