From 170962becb4dbafd3ee13676aa999e086937d195 Mon Sep 17 00:00:00 2001
From: Jeff Grafton <jgrafton@google.com>
Date: Tue, 15 Sep 2015 15:03:34 -0700
Subject: [PATCH] Explicitly log how long each step of e2e setup/test/teardown
 takes.

---
 hack/e2e.go | 6 ++++++
 1 file changed, 6 insertions(+)

diff --git a/hack/e2e.go b/hack/e2e.go
index f8614028de1..3fcb233a37f 100644
--- a/hack/e2e.go
+++ b/hack/e2e.go
@@ -31,6 +31,7 @@ import (
 	"path/filepath"
 	"strconv"
 	"strings"
+	"time"
 )
 
 var (
@@ -84,6 +85,7 @@ type TestResult struct {
 type ResultsByTest map[string]TestResult
 
 func main() {
+	log.SetFlags(log.LstdFlags | log.Lshortfile)
 	flag.Parse()
 
 	if *isup {
@@ -297,6 +299,10 @@ func finishRunning(stepName string, cmd *exec.Cmd) bool {
 		cmd.Stderr = os.Stderr
 	}
 	log.Printf("Running: %v", stepName)
+	defer func(start time.Time) {
+		log.Printf("Step '%s' finished in %s", stepName, time.Since(start))
+	}(time.Now())
+
 	if err := cmd.Run(); err != nil {
 		log.Printf("Error running %v: %v", stepName, err)
 		return false