From 179f7dc567ce860ceb93ce81bd200181945594b6 Mon Sep 17 00:00:00 2001 From: Alexander Borsuk Date: Sun, 29 Aug 2021 15:54:04 +0200 Subject: [PATCH] [debug] Minor search logs improvement Signed-off-by: Alexander Borsuk --- base/timer.hpp | 1 + search/emitter.hpp | 9 +++++---- search/engine.cpp | 2 +- 3 files changed, 7 insertions(+), 5 deletions(-) diff --git a/base/timer.hpp b/base/timer.hpp index c3ca261f2e..e411ae2134 100644 --- a/base/timer.hpp +++ b/base/timer.hpp @@ -28,6 +28,7 @@ public: } inline double ElapsedSeconds() const { return TimeElapsedAs>().count(); } + inline uint64_t ElapsedMilliseconds() const { return TimeElapsedAs().count(); } inline void Reset() { m_startTime = std::chrono::steady_clock::now(); } }; diff --git a/search/emitter.hpp b/search/emitter.hpp index 48a3a71294..956e8dbb45 100644 --- a/search/emitter.hpp +++ b/search/emitter.hpp @@ -33,12 +33,13 @@ public: void Emit(bool force = false) { - if (m_prevEmitSize == m_results.GetCount() && !force) + auto const newCount = m_results.GetCount(); + if (m_prevEmitSize == newCount && !force) return; - m_prevEmitSize = m_results.GetCount(); - LOG(LINFO, ("Emitting a new batch of results. Time since search start:", - m_timer.ElapsedSeconds(), "seconds.")); + LOG(LINFO, ("Emitting a new batch of results:", newCount - m_prevEmitSize, ",", + m_timer.ElapsedMilliseconds(), "ms since the search has started.")); + m_prevEmitSize = m_results.GetCount(); if (m_onResults) m_onResults(m_results); diff --git a/search/engine.cpp b/search/engine.cpp index be1f67fc8b..6072904c59 100644 --- a/search/engine.cpp +++ b/search/engine.cpp @@ -288,7 +288,7 @@ void Engine::DoSearch(SearchParams const & params, shared_ptr h LOG(LINFO, ("Search started.")); base::Timer timer; SCOPE_GUARD(printDuration, [&timer]() { - LOG(LINFO, ("Search ended. Time:", timer.ElapsedSeconds(), "seconds.")); + LOG(LINFO, ("Search ended in", timer.ElapsedMilliseconds(), "ms.")); }); processor.Reset();