Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 1 | #ifndef AOS_COMMON_UTIL_LOG_INTERVAL_H_ |
| 2 | #define AOS_COMMON_UTIL_LOG_INTERVAL_H_ |
| 3 | |
| 4 | #include "aos/common/time.h" |
| 5 | #include "aos/common/logging/logging.h" |
| 6 | |
| 7 | #include <string> |
| 8 | |
| 9 | namespace aos { |
| 10 | namespace util { |
| 11 | |
| 12 | // A class to help with logging things that happen a lot only occasionally. |
| 13 | // |
| 14 | // Intended use { |
Austin Schuh | f2a50ba | 2016-12-24 16:16:26 -0800 | [diff] [blame^] | 15 | // static LogInterval interval(::std::chrono::millseconds(200)); |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 16 | // |
| 17 | // if (WantToLog()) { |
| 18 | // interval.WantToLog(); |
| 19 | // } |
| 20 | // if (interval.ShouldLog()) { |
| 21 | // LOG(DEBUG, "thingie happened! (%d times)\n", interval.Count()); |
| 22 | // } |
| 23 | // } |
| 24 | class LogInterval { |
| 25 | public: |
Austin Schuh | 61bdc60 | 2016-12-04 19:10:10 -0800 | [diff] [blame] | 26 | constexpr LogInterval(::std::chrono::nanoseconds interval) |
| 27 | : interval_(interval) {} |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 28 | |
| 29 | void WantToLog() { |
| 30 | if (count_ == 0) { |
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 | |
| 68 | #define LOG_INTERVAL(simple_log) \ |
Brian Silverman | 9c9ab42 | 2014-02-25 13:42:53 -0800 | [diff] [blame] | 69 | simple_log.WantToLog(LOG_SOURCENAME ": " STRINGIFY(__LINE__)) |
| 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()) { |
Brian Silverman | 9c9ab42 | 2014-02-25 13:42:53 -0800 | [diff] [blame] | 77 | CHECK_NOTNULL(context_); |
| 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(), |
| 81 | ::std::chrono::duration_cast<::std::chrono::duration<double>>( |
| 82 | interval_.interval()).count()); |
Brian Silverman | 9c9ab42 | 2014-02-25 13:42:53 -0800 | [diff] [blame] | 83 | context_ = NULL; |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 84 | } |
| 85 | } |
| 86 | |
| 87 | private: |
| 88 | LogInterval interval_; |
| 89 | const log_level level_; |
| 90 | const ::std::string message_; |
Brian Silverman | 9c9ab42 | 2014-02-25 13:42:53 -0800 | [diff] [blame] | 91 | const char *context_ = NULL; |
Brian Silverman | 01f0d22 | 2014-02-16 01:09:11 -0800 | [diff] [blame] | 92 | }; |
| 93 | |
| 94 | } // namespace util |
| 95 | } // namespace aos |
| 96 | |
| 97 | #endif // AOS_COMMON_UTIL_LOG_INTERVAL_H_ |