Add Start-aware output handler

Author: veproza

src/Runner/Runner.php

@@ -121,6 +121,7 @@ public function run(): bool
 				$running[] = $job = array_shift($this->jobs);
 				$async = $this->threadCount > 1 && (count($running) + count($this->jobs) > 1);
 				$job->setEnvironmentVariable(Environment::THREAD, (string) array_shift($threads));
+				$this->startTest($job->getTest());
 				$job->run($async ? $job::RUN_ASYNC : 0);
 			}
 
@@ -191,6 +192,19 @@ public function prepareTest(Test $test): void
 	}
 
 
+	/**
+	 * Writes to start-aware output handlers
+	 */
+	private function startTest(Test $test): void
+	{
+		foreach ($this->outputHandlers as $handler) {
+			if ($handler instanceof StartAwareOutputHandler) {
+				$handler->start($test);
+			}
+		}
+	}
+
+
 	/**
 	 * Writes to output handlers.
 	 */

src/Runner/StartAwareOutputHandler.php

@@ -0,0 +1,16 @@
+<?php
+declare(strict_types=1);
+
+namespace Tester\Runner;
+
+/**
+ * For output handlers that wish to output the time it took to perform a test
+ */
+interface StartAwareOutputHandler extends OutputHandler
+{
+    /**
+     * Called when the test starts
+     * @param Test $test
+     */
+    function start(Test $test): void;
+}

src/tester.php

@@ -15,6 +15,7 @@
 require __DIR__ . '/Runner/CommandLine.php';
 require __DIR__ . '/Runner/TestHandler.php';
 require __DIR__ . '/Runner/OutputHandler.php';
+require __DIR__ . '/Runner/StartAwareOutputHandler.php';
 require __DIR__ . '/Runner/Output/Logger.php';
 require __DIR__ . '/Runner/Output/TapPrinter.php';
 require __DIR__ . '/Runner/Output/ConsolePrinter.php';

tests/Runner/Runner.start-aware-output.phpt

@@ -0,0 +1,56 @@
+<?php
+
+declare(strict_types=1);
+
+use Tester\Assert;
+use Tester\Runner\Test;
+
+require __DIR__ . '/../bootstrap.php';
+require __DIR__ . '/../../src/Runner/Test.php';
+require __DIR__ . '/../../src/Runner/TestHandler.php';
+require __DIR__ . '/../../src/Runner/Runner.php';
+require __DIR__ . '/../../src/Runner/OutputHandler.php';
+require __DIR__ . '/../../src/Runner/StartAwareOutputHandler.php';
+
+class TimeAwareLogger implements \Tester\Runner\StartAwareOutputHandler
+{
+	/**
+	 * @var float
+	 */
+	public $lastTimeStarted = NULL;
+
+	function start(Test $test): void
+	{
+		$this->lastTimeStarted = microtime(TRUE);
+	}
+
+	function begin(): void
+	{
+	}
+
+	function prepare(Test $test): void
+	{
+	}
+
+	function finish(Test $test): void
+	{
+	}
+
+	function end(): void
+	{
+	}
+}
+
+$start = microtime(TRUE);
+$runner = new Tester\Runner\Runner(createInterpreter());
+$runner->paths = [__DIR__ . "/start-aware/*.phptx"];
+$runner->outputHandlers[] = $logger = new TimeAwareLogger;
+
+$toleranceMicroSeconds = 1000;
+$runner->run();
+
+Assert::notSame(NULL, $logger->lastTimeStarted, "Test start time should be logged");
+$difference = abs($start - $logger->lastTimeStarted);
+if ($difference > $toleranceMicroSeconds) {
+   Assert::fail("Time {$logger->lastTimeStarted} should be within {$toleranceMicroSeconds} microseconds of $start, was {$difference}");
+}

tests/Runner/start-aware/pass.phptx

@@ -0,0 +1,4 @@
+<?php
+
+require __DIR__ . '/../../bootstrap.php';
+Tester\Environment::$checkAssertions = false;