// Copyright (c) 2009-2010 Satoshi Nakamoto // Copyright (c) 2009-2021 The Bitcoin Core developers // Distributed under the MIT software license, see the accompanying // file COPYING or http://www.opensource.org/licenses/mit-license.php. #ifndef BITCOIN_LOGGING_TIMER_H #define BITCOIN_LOGGING_TIMER_H #include #include #include #include #include #include namespace BCLog { //! RAII-style object that outputs timing information to logs. template class Timer { public: //! If log_category is left as the default, end_msg will log unconditionally //! (instead of being filtered by category). Timer( std::string prefix, std::string end_msg, BCLog::LogFlags log_category = BCLog::LogFlags::ALL, bool msg_on_completion = true) : m_prefix(std::move(prefix)), m_title(std::move(end_msg)), m_log_category(log_category), m_message_on_completion(msg_on_completion) { this->Log(strprintf("%s started", m_title)); m_start_t = GetTime(); } ~Timer() { if (m_message_on_completion) { this->Log(strprintf("%s completed", m_title)); } else { this->Log("completed"); } } void Log(const std::string& msg) { const std::string full_msg = this->LogMsg(msg); if (m_log_category == BCLog::LogFlags::ALL) { LogPrintf("%s\n", full_msg); } else { LogPrint(m_log_category, "%s\n", full_msg); } } std::string LogMsg(const std::string& msg) { const auto end_time = GetTime() - m_start_t; if (m_start_t.count() <= 0) { return strprintf("%s: %s", m_prefix, msg); } if constexpr (std::is_same::value) { return strprintf("%s: %s (%iμs)", m_prefix, msg, end_time.count()); } else if constexpr (std::is_same::value) { return strprintf("%s: %s (%.2fms)", m_prefix, msg, end_time.count() * 0.001); } else if constexpr (std::is_same::value) { return strprintf("%s: %s (%.2fs)", m_prefix, msg, end_time.count() * 0.000001); } else { static_assert(ALWAYS_FALSE, "Error: unexpected time type"); } } private: std::chrono::microseconds m_start_t{}; //! Log prefix; usually the name of the function this was created in. const std::string m_prefix; //! A descriptive message of what is being timed. const std::string m_title; //! Forwarded on to LogPrint if specified - has the effect of only //! outputting the timing log when a particular debug= category is specified. const BCLog::LogFlags m_log_category; //! Whether to output the message again on completion. const bool m_message_on_completion; }; } // namespace BCLog #define LOG_TIME_MICROS_WITH_CATEGORY(end_msg, log_category) \ BCLog::Timer UNIQUE_NAME(logging_timer)(__func__, end_msg, log_category) #define LOG_TIME_MILLIS_WITH_CATEGORY(end_msg, log_category) \ BCLog::Timer UNIQUE_NAME(logging_timer)(__func__, end_msg, log_category) #define LOG_TIME_MILLIS_WITH_CATEGORY_MSG_ONCE(end_msg, log_category) \ BCLog::Timer UNIQUE_NAME(logging_timer)(__func__, end_msg, log_category, /* msg_on_completion=*/false) #define LOG_TIME_SECONDS(end_msg) \ BCLog::Timer UNIQUE_NAME(logging_timer)(__func__, end_msg) #endif // BITCOIN_LOGGING_TIMER_H