build: attach name of pipeline step to summary

internal/build/pipeline_state.go

@@ -10,15 +10,26 @@ import (
 )
 
 type PipelineState struct {
-	curPipelineStep        int
+	curPipelineStep        PipelineStep
 	curBuildStep           int
 	totalPipelineStepCount int
-	pipelineStepDurations  []time.Duration
+	pipelineSteps          []PipelineStep
 	curPipelineStart       time.Time
 	curPipelineStepStart   time.Time
 	c                      Clock
 }
 
+type PipelineStep struct {
+	Name     string        // for logging
+	Index    int           // human-readable, 1-indexed
+	Duration time.Duration // not populated until end of the step
+}
+
+func (pStep PipelineStep) withDuration(d time.Duration) PipelineStep {
+	pStep.Duration = d
+	return pStep
+}
+
 type Clock interface {
 	Now() time.Time
 }
@@ -35,7 +46,7 @@ const buildStepOutputPrefix = "     "
 
 func NewPipelineState(ctx context.Context, totalStepCount int, c Clock) *PipelineState {
 	return &PipelineState{
-		curPipelineStep:        1,
+		curPipelineStep:        PipelineStep{Index: 0},
 		totalPipelineStepCount: totalStepCount,
 		curPipelineStart:       c.Now(),
 		c:                      c,
@@ -48,7 +59,7 @@ func NewPipelineState(ctx context.Context, totalStepCount int, c Clock) *Pipelin
 // and NOT:
 //     defer ps.End(ctx, err)
 func (ps *PipelineState) End(ctx context.Context, err error) {
-	ps.curPipelineStep = 0
+	ps.curPipelineStep = PipelineStep{Index: 0}
 	ps.curBuildStep = 0
 
 	if err != nil {
@@ -59,27 +70,35 @@ func (ps *PipelineState) End(ctx context.Context, err error) {
 
 	elapsed := ps.c.Now().Sub(ps.curPipelineStart)
 
-	for i, duration := range ps.pipelineStepDurations {
-		l.Infof("%sStep %d - %.2fs", buildStepOutputPrefix, i+1, duration.Seconds())
+	for i, step := range ps.pipelineSteps {
+		l.Infof("%sStep %d - %.2fs (%s)", buildStepOutputPrefix, i+1, step.Duration.Seconds(), step.Name)
 	}
 
 	t := logger.Blue(l).Sprintf("%.2fs", elapsed.Seconds())
 	l.Infof("%sDONE IN: %s \n", buildStepOutputPrefix, t)
 }
 
+func (ps *PipelineState) curPipelineIndex() int {
+	return ps.curPipelineStep.Index
+}
+
 func (ps *PipelineState) StartPipelineStep(ctx context.Context, format string, a ...interface{}) {
 	l := logger.Get(ctx)
-	line := logger.Blue(l).Sprintf("STEP %d/%d", ps.curPipelineStep, ps.totalPipelineStepCount)
-	l.Infof("%s — %s", line, fmt.Sprintf(format, a...))
-	ps.curPipelineStep++
+	stepName := fmt.Sprintf(format, a...)
+	ps.curPipelineStep = PipelineStep{
+		Name:  stepName,
+		Index: ps.curPipelineIndex() + 1,
+	}
+	line := logger.Blue(l).Sprintf("STEP %d/%d", ps.curPipelineIndex(), ps.totalPipelineStepCount)
+	l.Infof("%s — %s", line, stepName)
 	ps.curBuildStep = 1
 	ps.curPipelineStepStart = ps.c.Now()
 }
 
 func (ps *PipelineState) EndPipelineStep(ctx context.Context) {
 	elapsed := ps.c.Now().Sub(ps.curPipelineStepStart)
 	logger.Get(ctx).Infof("")
-	ps.pipelineStepDurations = append(ps.pipelineStepDurations, elapsed)
+	ps.pipelineSteps = append(ps.pipelineSteps, ps.curPipelineStep.withDuration(elapsed))
 }
 
 func (ps *PipelineState) StartBuildStep(ctx context.Context, format string, a ...interface{}) {

internal/build/pipeline_state_test.go

@@ -56,6 +56,26 @@ func TestPipelineMultilinePrint(t *testing.T) {
 	assertSnapshot(t, out.String())
 }
 
+func TestPipelineMultistep(t *testing.T) {
+	var err error
+	out := &bytes.Buffer{}
+	ctx := logger.WithLogger(context.Background(), logger.NewLogger(logger.InfoLvl, out))
+	ps := NewPipelineState(ctx, 3, fakeClock{})
+	ps.StartPipelineStep(ctx, "%s %s", "hello", "world")
+	ps.Printf(ctx, "in ur step")
+	ps.EndPipelineStep(ctx)
+	ps.StartPipelineStep(ctx, "doing stuff")
+	ps.Printf(ctx, "here is some stuff!")
+	ps.EndPipelineStep(ctx)
+	ps.StartPipelineStep(ctx, "different stuff")
+	ps.Printf(ctx, "even more stuff")
+	ps.Printf(ctx, "i can't believe it's not stuff")
+	ps.EndPipelineStep(ctx)
+	ps.End(ctx, err)
+
+	assertSnapshot(t, out.String())
+}
+
 func assertSnapshot(t *testing.T, output string) {
 	d1 := []byte(output)
 	gmPath := fmt.Sprintf("testdata/%s_master", t.Name())

internal/build/testdata/TestPipelineMultilinePrint_master

@@ -3,6 +3,6 @@ STEP 1/1 — hello world
      line 2
 
 
-     Step 1 - 0.00s
+     Step 1 - 0.00s (hello world)
      DONE IN: 0.00s 
 

internal/build/testdata/TestPipelineMultistep_master

@@ -0,0 +1,15 @@
+STEP 1/3 — hello world
+     in ur step
+
+STEP 2/3 — doing stuff
+     here is some stuff!
+
+STEP 3/3 — different stuff
+     even more stuff
+     i can't believe it's not stuff
+
+     Step 1 - 0.00s (hello world)
+     Step 2 - 0.00s (doing stuff)
+     Step 3 - 0.00s (different stuff)
+     DONE IN: 0.00s 
+

internal/build/testdata/TestPipeline_master

@@ -1,6 +1,6 @@
 STEP 1/1 — hello world
      in ur step
 
-     Step 1 - 0.00s
+     Step 1 - 0.00s (hello world)
      DONE IN: 0.00s 
 

web/src/subdir/index.test.js

@@ -0,0 +1,3 @@
+test("everything is okay", () => {
+    expect(1).toEqual(1)
+});