Switch to timer class and time actions separately

Author: sebastianfeldmann

src/Runner/Hook.php

@@ -249,7 +249,7 @@ public function shouldSkipActions(?bool $shouldSkip = null): bool
     private function executeActions(array $actions): void
     {
         $status = self::HOOK_SUCCEEDED;
-        $start  = microtime(true);
+        $timer  = Timer::createAndStart();
         try {
             if ($this->config->failOnFirstError()) {
                 $this->executeFailOnFirstError($actions);
@@ -262,8 +262,7 @@ private function executeActions(array $actions): void
             $this->dispatcher->dispatch('onHookFailure');
             throw $e;
         } finally {
-            $duration = microtime(true) - $start;
-            $this->printer->hookEnded($status, $this->hookLog, $duration);
+            $this->printer->hookEnded($status, $this->hookLog, $timer->stop());
         }
     }
 
@@ -319,6 +318,7 @@ private function handleAction(Config\Action $action): void
 
         $io     = new IO\CollectorIO($this->io);
         $status = ActionLog::ACTION_SUCCEEDED;
+        $timer  = Timer::createAndStart();
 
         try {
             if (!$this->doConditionsApply($action->getConditions(), $io)) {
@@ -336,13 +336,13 @@ private function handleAction(Config\Action $action): void
 
             $runner = $this->createActionRunner(Util::getExecType($action->getAction()));
             $runner->execute($this->config, $io, $this->repository, $action);
-            $this->printer->actionSucceeded($action);
+            $this->printer->actionSucceeded($action, $timer->stop());
         } catch (ActionNotApplicable $e) {
             $this->printer->actionSkipped($action);
             return;
         } catch (Exception $e) {
             $status = ActionLog::ACTION_FAILED;
-            $this->printer->actionFailed($action);
+            $this->printer->actionFailed($action, $timer->stop());
             if (!$action->isFailureAllowed($this->config->isFailureAllowed())) {
                 throw $e;
             }

src/Runner/Hook/Printer.php

@@ -62,12 +62,14 @@ public function __construct(IO $io)
     /**
      * Prints the action success line
      *
-     * @param \CaptainHook\App\Config\Action $action
+     * @param  \CaptainHook\App\Config\Action $action
+     * @param  float                          $duration
      * @return void
      */
-    public function actionSucceeded(Action $action): void
+    public function actionSucceeded(Action $action, float $duration): void
     {
-        $this->io->write($this->actionHeadline($action) . '<info>done</info>');
+        $time = $this->verbosity === IO::VERBOSE ? sprintf(' (%01.2fs)', $duration) : '';
+        $this->io->write($this->actionHeadline($action) . '<info>done</info>' . $time);
     }
 
     /**
@@ -84,12 +86,14 @@ public function actionSkipped(Action $action): void
     /**
      * Prints the action failed line
      *
-     * @param \CaptainHook\App\Config\Action $action
+     * @param  \CaptainHook\App\Config\Action $action
+     * @param  float                          $duration
      * @return void
      */
-    public function actionFailed(Action $action): void
+    public function actionFailed(Action $action, float $duration): void
     {
-        $this->io->write($this->actionHeadline($action) . '<fg=red>failed</>');
+        $time = $this->verbosity === IO::VERBOSE ? sprintf(' (%01.2fs)', $duration) : '';
+        $this->io->write($this->actionHeadline($action) . '<fg=red>failed</>' . $time);
     }
 
     /**