Code is attached. These were built by with vc7.1 by creating a console project, adding these files, adding links to the boost libs, including boost_root. It should build for you. Please post if you try and have problems. After printing 20,000 numbers to the console it should have output like: release |Tk:|1282| Th:|155| NS/call:|15625| cout| |Tk:|1281| Th:|343| NS/call:|34375| enabled log| |Tk:|0| Th:|0| NS/call:|0| cout disabled| |Tk:|16| Th:|15| NS/call:|15| disabled log| Basically works like this: string l_sDC; disable_logs("lgTest"); START_MEASUREQ(1000000, "disabled log", &l_sDL) BOOST_SCOPEDLOG(l_lgTest) << l_ui << endl; STOP_MEASURE() BOOST_SCOPEDLOG(l_lg) << l_sDL << endl; There's a supporting cMeasureTime class that records time. Macros basically start and stop a for loop, then record the results to a string (or log if you pass in a logger).
_______________________________________________ Unsubscribe & other changes: http://lists.boost.org/mailman/listinfo.cgi/boost
bool UseCout(){return false;} #include "windows.h" #include "boost/log/log.hpp" #include <boost/log/functions.hpp> #include <boost/format.hpp> #include <iostream> using namespace std; using namespace boost::logging; using namespace boost; class cMeasureTime { public: cMeasureTime(){memset(this, 0, sizeof(*this));} void Start() { m_uiStartTick = GetTickCount(); FILETIME l_CreationTime; FILETIME l_ExitTime; if(!GetThreadTimes(GetCurrentThread(), &l_CreationTime, &l_ExitTime, (FILETIME*)&m_ui64KStart, (FILETIME*)&m_ui64UStart)) { cout << "GetThreadTimes failed: " << GetLastError() << endl; } } void Stop() { ++m_uiCalls; DWORD l_dwEndTick = GetTickCount(); m_uiTickDiff = l_dwEndTick > m_uiStartTick ? l_dwEndTick - m_uiStartTick : m_uiStartTick - l_dwEndTick; m_uiTickDiffTotal += m_uiTickDiff; FILETIME l_CreationTime; FILETIME l_ExitTime; unsigned __int64 l_ui64KEnd, l_ui64UEnd; if(!GetThreadTimes(GetCurrentThread(), &l_CreationTime, &l_ExitTime, (FILETIME*)&l_ui64KEnd, (FILETIME*)&l_ui64UEnd)) { cout << "GetThreadTimes failed: " << GetLastError() << endl; } m_ui64KDiff = l_ui64KEnd - m_ui64KStart; m_ui64UDiff = l_ui64UEnd - m_ui64UStart; m_ui64KDiffTotal += m_ui64KDiff; m_ui64UDiffTotal += m_ui64UDiff; } unsigned int GetCalls() const {return m_uiCalls;} unsigned __int64 GetTickDiffMS() const {return m_uiTickDiff;} unsigned __int64 GetKDiffHNS() const {return m_ui64KDiff;} unsigned __int64 GetUDiffHNS() const {return m_ui64UDiff;} unsigned __int64 GetThDiffHNS() const {return GetKDiffHNS() + GetUDiffHNS();} unsigned __int64 GetKDiffMS() const {return GetKDiffHNS() / 10000;} unsigned __int64 GetUDiffMS() const {return GetUDiffHNS() / 10000;} unsigned __int64 GetThDiffMS() const {return GetKDiffMS() + GetUDiffMS();} unsigned int GetTickDiffTotalMS()const {return m_uiTickDiffTotal;} unsigned __int64 GetKDiffTotalHNS() const {return m_ui64KDiffTotal;} unsigned __int64 GetUDiffTotalHNS() const {return m_ui64UDiffTotal;} unsigned __int64 GetThDiffTotalHNS() const {return GetKDiffTotalHNS() + GetUDiffTotalHNS();} unsigned __int64 GetKDiffTotalMS() const {return GetUDiffTotalHNS() / 1000;} unsigned __int64 GetUDiffTotalMS() const {return GetKDiffTotalHNS() / 1000;} unsigned __int64 GetThDiffTotalMS() const {return GetKDiffTotalMS() + GetUDiffTotalMS();} unsigned __int64 GetThNSperCall(unsigned int uiItr) const { unsigned __int64 l_ui64ThNS = GetThDiffHNS() * 100; unsigned __int64 l_uiNSperCall = 0; if(uiItr) { l_uiNSperCall = l_ui64ThNS / uiItr; } return l_uiNSperCall; } unsigned __int64 GetThNSperCallTotal() const { unsigned __int64 l_ui64ThNS = GetThDiffTotalHNS() * 100; unsigned __int64 l_uiNSperCall = 0; if(m_uiCalls) { l_uiNSperCall = l_ui64ThNS / m_uiCalls; } return l_uiNSperCall; } string ShortString(unsigned int uiItr, string strTag = "") const { format l_fmt("|Tk:|%u| Th:|%u| NS/call:|%u| %s|"); l_fmt % GetTickDiffMS() % GetThDiffMS() % GetThNSperCall(uiItr) % strTag; return l_fmt.str(); } string ShortStringTotal(string strTag = "") const { format l_fmt("|Tk:|%u| Th:|%u| NS/call:|%u| %s|"); l_fmt % GetTickDiffMS() % GetThDiffMS() % GetThNSperCallTotal() % strTag; return l_fmt.str(); } private: unsigned int m_uiCalls; unsigned int m_uiTickDiff; unsigned __int64 m_ui64KDiff; unsigned __int64 m_ui64UDiff; unsigned int m_uiTickDiffTotal; unsigned __int64 m_ui64KDiffTotal; unsigned __int64 m_ui64UDiffTotal; unsigned int m_uiStartTick; unsigned __int64 m_ui64KStart; unsigned __int64 m_ui64UStart; }; #define START_MEASURE(uiItr_, szTag_, pLog_, pMeasureTime_, pString_) \ { \ unsigned int l_uiItr = uiItr_; \ const char* l_szTag = szTag_; \ logger* l_pLog = pLog_; \ cMeasureTime* l_pMT = pMeasureTime_; \ cMeasureTime l_MT; \ if(!l_pMT){l_pMT = &l_MT;} \ string* l_pString = pString_; \ l_pMT->Start(); \ for(unsigned int l_ui = 0; l_ui < l_uiItr; ++l_ui) \ { \ #define STOP_MEASURE() \ } \ l_pMT->Stop(); \ if(l_pString) \ { \ *l_pString = l_pMT->ShortString(l_uiItr, l_szTag); \ } \ if(l_pLog) \ { \ BOOST_SCOPEDLOG(*l_pLog) << l_pMT->ShortString(l_uiItr, l_szTag); \ } \ } \ #define START_MEASUREQ(uiItr_, szTag_, pString_) \ START_MEASURE(uiItr_, szTag_, NULL, NULL, pString_) extern bool UseCout(); int main(int argc, char* argv[]) { logger l_lg("lg"); logger l_lgTest("lgTest"); manipulate_logs("*").add_appender(write_to_cout); enable_logs("lg"); set_log_caching(false); string l_sCout, l_sDL, l_sEL, l_sDC; START_MEASUREQ(10000, "cout", &l_sCout) cout << l_ui << endl; STOP_MEASURE() START_MEASUREQ(1000000, "cout disabled", &l_sDC) if(UseCout()) { cout << l_ui << endl; } STOP_MEASURE() disable_logs("lgTest"); START_MEASUREQ(1000000, "disabled log", &l_sDL) BOOST_SCOPEDLOG(l_lgTest) << l_ui << endl; STOP_MEASURE() enable_logs("lgTest"); START_MEASUREQ(10000, "enabled log", &l_sEL) BOOST_SCOPEDLOG(l_lgTest) << l_ui << endl; STOP_MEASURE() #ifdef _DEBUG BOOST_SCOPEDLOG(l_lg) << "debug" << endl; #else BOOST_SCOPEDLOG(l_lg) << "release" << endl; #endif BOOST_SCOPEDLOG(l_lg) << l_sCout << endl; BOOST_SCOPEDLOG(l_lg) << l_sEL << endl; BOOST_SCOPEDLOG(l_lg) << l_sDC << endl; BOOST_SCOPEDLOG(l_lg) << l_sDL << endl; return 0; }