pkg/jsonlog/jsonlog_test.go

package jsonlog

import (
	"bytes"
	"encoding/json"
	"io/ioutil"
	"regexp"
	"strconv"
	"strings"
	"testing"
	"time"

	"github.com/docker/docker/pkg/timeutils"
)

// Invalid json should return an error
func TestWriteLogWithInvalidJSON(t *testing.T) {
	json := strings.NewReader("Invalid json")
	w := bytes.NewBuffer(nil)
	if err := WriteLog(json, w, "json", time.Time{}); err == nil {
		t.Fatalf("Expected an error, got [%v]", w.String())
	}
}

// Any format is valid, it will just print it
func TestWriteLogWithInvalidFormat(t *testing.T) {
	testLine := "Line that thinks that it is log line from docker\n"
	var buf bytes.Buffer
	e := json.NewEncoder(&buf)
	for i := 0; i < 35; i++ {
		e.Encode(JSONLog{Log: testLine, Stream: "stdout", Created: time.Now()})
	}
	w := bytes.NewBuffer(nil)
	if err := WriteLog(&buf, w, "invalid format", time.Time{}); err != nil {
		t.Fatal(err)
	}
	res := w.String()
	t.Logf("Result of WriteLog: %q", res)
	lines := strings.Split(strings.TrimSpace(res), "\n")
	expression := "^invalid format Line that thinks that it is log line from docker$"
	logRe := regexp.MustCompile(expression)
	expectedLines := 35
	if len(lines) != expectedLines {
		t.Fatalf("Must be %v lines but got %d", expectedLines, len(lines))
	}
	for _, l := range lines {
		if !logRe.MatchString(l) {
			t.Fatalf("Log line not in expected format [%v]: %q", expression, l)
		}
	}
}

// Having multiple Log/Stream element
func TestWriteLogWithMultipleStreamLog(t *testing.T) {
	testLine := "Line that thinks that it is log line from docker\n"
	var buf bytes.Buffer
	e := json.NewEncoder(&buf)
	for i := 0; i < 35; i++ {
		e.Encode(JSONLog{Log: testLine, Stream: "stdout", Created: time.Now()})
	}
	w := bytes.NewBuffer(nil)
	if err := WriteLog(&buf, w, "invalid format", time.Time{}); err != nil {
		t.Fatal(err)
	}
	res := w.String()
	t.Logf("Result of WriteLog: %q", res)
	lines := strings.Split(strings.TrimSpace(res), "\n")
	expression := "^invalid format Line that thinks that it is log line from docker$"
	logRe := regexp.MustCompile(expression)
	expectedLines := 35
	if len(lines) != expectedLines {
		t.Fatalf("Must be %v lines but got %d", expectedLines, len(lines))
	}
	for _, l := range lines {
		if !logRe.MatchString(l) {
			t.Fatalf("Log line not in expected format [%v]: %q", expression, l)
		}
	}
}

// Write log with since after created, it won't print anything
func TestWriteLogWithDate(t *testing.T) {
	created, _ := time.Parse("YYYY-MM-dd", "2015-01-01")
	var buf bytes.Buffer
	testLine := "Line that thinks that it is log line from docker\n"
	jsonLog := JSONLog{Log: testLine, Stream: "stdout", Created: created}
	if err := json.NewEncoder(&buf).Encode(jsonLog); err != nil {
		t.Fatal(err)
	}
	w := bytes.NewBuffer(nil)
	if err := WriteLog(&buf, w, "json", time.Now()); err != nil {
		t.Fatal(err)
	}
	res := w.String()
	if res != "" {
		t.Fatalf("Expected empty log, got [%v]", res)
	}
}

// Happy path :)
func TestWriteLog(t *testing.T) {
	testLine := "Line that thinks that it is log line from docker\n"
	format := timeutils.RFC3339NanoFixed
	logs := map[string][]string{
		"":     {"35", "^Line that thinks that it is log line from docker$"},
		"json": {"1", `^{\"log\":\"Line that thinks that it is log line from docker\\n\",\"stream\":\"stdout\",\"time\":.{30,}\"}$`},
		// 30+ symbols, five more can come from system timezone
		format: {"35", `.{30,} Line that thinks that it is log line from docker`},
	}
	for givenFormat, expressionAndLines := range logs {
		expectedLines, _ := strconv.Atoi(expressionAndLines[0])
		expression := expressionAndLines[1]
		var buf bytes.Buffer
		e := json.NewEncoder(&buf)
		for i := 0; i < 35; i++ {
			e.Encode(JSONLog{Log: testLine, Stream: "stdout", Created: time.Now()})
		}
		w := bytes.NewBuffer(nil)
		if err := WriteLog(&buf, w, givenFormat, time.Time{}); err != nil {
			t.Fatal(err)
		}
		res := w.String()
		t.Logf("Result of WriteLog: %q", res)
		lines := strings.Split(strings.TrimSpace(res), "\n")
		if len(lines) != expectedLines {
			t.Fatalf("Must be %v lines but got %d", expectedLines, len(lines))
		}
		logRe := regexp.MustCompile(expression)
		for _, l := range lines {
			if !logRe.MatchString(l) {
				t.Fatalf("Log line not in expected format [%v]: %q", expression, l)
			}
		}
	}
}

func BenchmarkWriteLog(b *testing.B) {
	var buf bytes.Buffer
	e := json.NewEncoder(&buf)
	testLine := "Line that thinks that it is log line from docker\n"
	for i := 0; i < 30; i++ {
		e.Encode(JSONLog{Log: testLine, Stream: "stdout", Created: time.Now()})
	}
	r := bytes.NewReader(buf.Bytes())
	w := ioutil.Discard
	format := timeutils.RFC3339NanoFixed
	b.SetBytes(int64(r.Len()))
	b.ResetTimer()
	for i := 0; i < b.N; i++ {
		if err := WriteLog(r, w, format, time.Time{}); err != nil {
			b.Fatal(err)
		}
		b.StopTimer()
		r.Seek(0, 0)
		b.StartTimer()
	}
}