[dev.ssa] cmd/compile/ssa: add timing to compiler passes

Add timing/allocation information to each compiler pass for both the
console and html output.

Change-Id: I75833003b806a09b4fb1bbf63983258612cdb7b0
Reviewed-on: https://go-review.googlesource.com/14277
Reviewed-by: Keith Randall <khr@golang.org>

diff --git a/src/cmd/compile/internal/ssa/compile.go b/src/cmd/compile/internal/ssa/compile.go
index 7413e721fe505c8d8cf5e81fb68be19a39fb935d..bff1a8103b9dee690e51a509ad161c1bad504e70 100644 (file)
--- a/src/cmd/compile/internal/ssa/compile.go
+++ b/src/cmd/compile/internal/ssa/compile.go
@@ -5,8 +5,10 @@
 package ssa
 
 import (
+       "fmt"
        "log"
        "runtime"
+       "time"
 )
 
 // Compile is the main entry point for this package.
@@ -36,14 +38,34 @@ func Compile(f *Func) {
        printFunc(f)
        f.Config.HTML.WriteFunc("start", f)
        checkFunc(f)
+       const logMemStats = false
        for _, p := range passes {
                phaseName = p.name
                f.Logf("  pass %s begin\n", p.name)
                // TODO: capture logging during this pass, add it to the HTML
+               var mStart runtime.MemStats
+               if logMemStats {
+                       runtime.ReadMemStats(&mStart)
+               }
+
+               tStart := time.Now()
                p.fn(f)
-               f.Logf("  pass %s end\n", p.name)
+               tEnd := time.Now()
+
+               time := tEnd.Sub(tStart).Nanoseconds()
+               var stats string
+               if logMemStats {
+                       var mEnd runtime.MemStats
+                       runtime.ReadMemStats(&mEnd)
+                       nAllocs := mEnd.TotalAlloc - mStart.TotalAlloc
+                       stats = fmt.Sprintf("[%d ns %d bytes]", time, nAllocs)
+               } else {
+                       stats = fmt.Sprintf("[%d ns]", time)
+               }
+
+               f.Logf("  pass %s end %s\n", p.name, stats)
                printFunc(f)
-               f.Config.HTML.WriteFunc("after "+phaseName, f)
+               f.Config.HTML.WriteFunc(fmt.Sprintf("after %s %s", phaseName, stats), f)
                checkFunc(f)
        }