Output timing info to verbose log, support snapshot testing (#81)

* Move timing info to verbose log, support snapshot testing

* PR feedback

Author: kmturley

Source/PluginTests.cpp

@@ -88,7 +88,8 @@ void PluginTests::runTest()
     // This has to be called on a background thread to keep the message thread free
     jassert (! juce::MessageManager::existsAndIsCurrentThread());
 
-    logMessage ("Validation started: " + Time::getCurrentTime().toString (true, true) + "\n");
+    logMessage ("Validation started");
+    logVerboseMessage ("\t" + Time::getCurrentTime().toString (true, true) + "\n");
     logMessage ("Strictness level: " + String (options.strictnessLevel));
 
     if (fileOrID.isNotEmpty())
@@ -133,7 +134,7 @@ void PluginTests::testType (const PluginDescription& pd)
         beginTest ("Open plugin (cold)");
         StopwatchTimer sw;
         deletePluginAsync (testOpenPlugin (pd));
-        logMessage ("\nTime taken to open plugin (cold): " + sw.getDescription());
+        logVerboseMessage ("\nTime taken to open plugin (cold): " + sw.getDescription());
     }
 
     {
@@ -142,7 +143,7 @@ void PluginTests::testType (const PluginDescription& pd)
 
         if (auto instance = testOpenPlugin (pd))
         {
-            logMessage ("\nTime taken to open plugin (warm): " + sw.getDescription());
+            logVerboseMessage ("\nTime taken to open plugin (warm): " + sw.getDescription());
             logMessage (String ("Running tests 123 times").replace ("123", String (options.numRepeats)));
 
             // This sleep is here to allow time for plugin async initialisation as in most cases
@@ -198,13 +199,13 @@ void PluginTests::testType (const PluginDescription& pd)
                         t->runTest (*this, *instance);
                     }
 
-                    logMessage ("\nTime taken to run test: " + sw2.getDescription());
+                    logVerboseMessage ("\nTime taken to run test: " + sw2.getDescription());
                 }
             }
 
             deletePluginAsync (std::move (instance));
         }
     }
 
-    logMessage ("\nTime taken to run all tests: " + totalTimer.getDescription());
+    logVerboseMessage ("\nTime taken to run all tests: " + totalTimer.getDescription());
 }

Source/tests/BasicTests.cpp

@@ -105,14 +105,14 @@ struct EditorTest   : public PluginTest
             {
                 ScopedEditorShower editorShower (instance);
                 ut.expect (editorShower.editor != nullptr, "Unable to create editor");
-                ut.logMessage ("\nTime taken to open editor (cold): " + timer.getDescription());
+                ut.logVerboseMessage ("\nTime taken to open editor (cold): " + timer.getDescription());
             }
 
             {
                 timer.reset();
                 ScopedEditorShower editorShower (instance);
                 ut.expect (editorShower.editor != nullptr, "Unable to create editor on second attempt");
-                ut.logMessage ("Time taken to open editor (warm): " + timer.getDescription());
+                ut.logVerboseMessage ("Time taken to open editor (warm): " + timer.getDescription());
             }
         }
     }