more accurate time measurements in DbgMsgSrc

Author: janwilmans

DbgMsgSrc/DbgMsgSrc.cpp

@@ -17,6 +17,7 @@
 #include <sys/timeb.h>
 #include "Win32/Win32Lib.h"
 #include "dbgstream.h"
+#include "Timer.h"
 
 #pragma comment(lib, "Ws2_32.lib")
 #pragma comment(lib, "psapi.lib")
@@ -194,20 +195,14 @@ void Output(const std::string& filename)
 	fs.close();
 
 	std::cout << "writing... " << lines.size() << " lines\n";
-
-	int i = 0;
-	long t1 = getMilliCount();
-
-	for (auto s = lines.begin(); s != lines.end(); ++s)
+	Timer timer;
+	auto t1 = timer.now();
+	for (const auto& s : lines)
 	{
-		++i;
-		//auto t = s + "\n";
-		OutputDebugStringA(s->c_str());
-		//Sleep(50);
+		OutputDebugStringA(s.c_str());
 	}
-	long t2 = getMilliCount();
-
-	std::cout << "OutputDebugStringA " << i << " lines, took: " << t2 - t1 << " ms\n";
+	auto elepsed = timer.now() - t1;
+	std::cout << "OutputDebugStringA " << lines.size() << " lines, took: " << static_cast<int>(Timer::ToMs(elepsed)) << " ms\n";
 }
 
 void EndlessTest()

DbgMsgSrc/DbgMsgSrc.vcxproj

@@ -157,9 +157,11 @@
   </ItemDefinitionGroup>
   <ItemGroup>
     <ClCompile Include="DbgMsgSrc.cpp" />
+    <ClCompile Include="Timer.cpp" />
   </ItemGroup>
   <ItemGroup>
     <ClInclude Include="dbgstream.h" />
+    <ClInclude Include="Timer.h" />
   </ItemGroup>
   <ItemGroup>
     <ProjectReference Include="..\Win32Lib\Win32Lib.vcxproj">

DbgMsgSrc/DbgMsgSrc.vcxproj.filters

@@ -18,11 +18,17 @@
     <ClCompile Include="DbgMsgSrc.cpp">
       <Filter>Source Files</Filter>
     </ClCompile>
+    <ClCompile Include="Timer.cpp">
+      <Filter>Source Files</Filter>
+    </ClCompile>
   </ItemGroup>
   <ItemGroup>
     <ClInclude Include="dbgstream.h">
       <Filter>Header Files</Filter>
     </ClInclude>
+    <ClInclude Include="Timer.h">
+      <Filter>Header Files</Filter>
+    </ClInclude>
   </ItemGroup>
   <ItemGroup>
     <None Include="packages.config" />

DbgMsgSrc/Timer.cpp

@@ -0,0 +1,27 @@
+#include <stdexcept>
+#include <chrono>
+#include "Timer.h"
+
+#define MICROSECONDS (1e6 / li.QuadPart)
+
+Timer::Timer()
+    : m_timerUnit(0.0)
+{
+    LARGE_INTEGER li;
+    QueryPerformanceFrequency(&li);
+    if (li.QuadPart == 0)
+        throw std::runtime_error("QueryPerformanceCounter not supported!");
+    m_timerUnit = MICROSECONDS;
+}
+
+std::chrono::steady_clock::time_point Timer::now() const
+{
+    return std::chrono::steady_clock::time_point(std::chrono::microseconds(static_cast<long long>(GetTicks() * m_timerUnit)));
+}
+
+long long Timer::GetTicks() const
+{
+    LARGE_INTEGER li;
+    QueryPerformanceCounter(&li);
+    return li.QuadPart;
+}

DbgMsgSrc/Timer.h

@@ -0,0 +1,21 @@
+#pragma once
+
+#include <mutex>
+#include <chrono>
+#include <windows.h>
+
+class Timer
+{
+public:
+    Timer();
+    std::chrono::steady_clock::time_point now() const;
+
+	static double ToMs(std::chrono::steady_clock::duration duration)
+	{
+		return static_cast<double>(std::chrono::duration_cast<std::chrono::microseconds>(duration).count()) / 1000.0;
+	}
+
+private:
+    long long GetTicks() const;
+    double m_timerUnit;
+};

OutputForwarderVSIX/Forwarder.cs

@@ -53,7 +53,7 @@ private Forwarder(Package package)
             InstallForwarder(package);
         }
 
-        private void InstallForwarder(Package)
+        private void InstallForwarder(Package package)
         {
             IVsOutputWindow outWindow = Package.GetGlobalService(typeof(SVsOutputWindow)) as IVsOutputWindow;
             Guid paneGuid = VSConstants.GUID_OutWindowDebugPane;    // codenotes: GUID_BuildOutputWindowPane / GUID_OutWindowDebugPane