flamenco/internal/worker/log_chunker_test.go
Sybren A. Stüvel adf7113b34 Worker: log chunker, also flush log after certain time
This flushes the log when the previous `Append()` call was too long ago.
Note that this doesn't flush after X seconds of silence; a call to
`Append()` or `Flush()` still has to happen in order to do the flushing.
2022-02-22 15:34:37 +01:00

122 lines
3.5 KiB
Go

package worker
import (
"context"
"testing"
"time"
"github.com/benbjohnson/clock"
"github.com/golang/mock/gomock"
"github.com/stretchr/testify/assert"
"gitlab.com/blender/flamenco-ng-poc/internal/worker/mocks"
)
/* ***** BEGIN GPL LICENSE BLOCK *****
*
* Original Code Copyright (C) 2022 Blender Foundation.
*
* This file is part of Flamenco.
*
* Flamenco is free software: you can redistribute it and/or modify it under
* the terms of the GNU General Public License as published by the Free Software
* Foundation, either version 3 of the License, or (at your option) any later
* version.
*
* Flamenco is distributed in the hope that it will be useful, but WITHOUT ANY
* WARRANTY; without even the implied warranty of MERCHANTABILITY or FITNESS FOR
* A PARTICULAR PURPOSE. See the GNU General Public License for more details.
*
* You should have received a copy of the GNU General Public License along with
* Flamenco. If not, see <https://www.gnu.org/licenses/>.
*
* ***** END GPL LICENSE BLOCK ***** */
type LogChunkerMocks struct {
listener *mocks.MockCommandListener
clock *clock.Mock
}
func mockedLogChunker(t *testing.T, mockCtrl *gomock.Controller) (*LogChunker, *LogChunkerMocks) {
mocks := LogChunkerMocks{
clock: mockedClock(t),
listener: mocks.NewMockCommandListener(mockCtrl),
}
lc := NewLogChunker("taskID", mocks.listener, mocks.clock)
return lc, &mocks
}
func TestLogChunkerEmpty(t *testing.T) {
mockCtrl := gomock.NewController(t)
defer mockCtrl.Finish()
lc, _ := mockedLogChunker(t, mockCtrl)
// Note: no call to mockListener is expected.
err := lc.Flush(context.Background())
assert.NoError(t, err)
assert.Equal(t, 0, lc.buffer.Len(), "buffer should be empty")
}
func TestLogChunkerSimple(t *testing.T) {
mockCtrl := gomock.NewController(t)
defer mockCtrl.Finish()
lc, mocks := mockedLogChunker(t, mockCtrl)
ctx := context.Background()
mocks.listener.EXPECT().LogProduced(ctx, "taskID", "just one line\n")
err := lc.Append(ctx, "just one line")
assert.NoError(t, err)
err = lc.Flush(ctx)
assert.NoError(t, err)
assert.Equal(t, 0, lc.buffer.Len(), "buffer should be empty")
}
func TestLogChunkerMuchLogging(t *testing.T) {
mockCtrl := gomock.NewController(t)
defer mockCtrl.Finish()
lc, mocks := mockedLogChunker(t, mockCtrl)
lc.bufferFlushSize = 12
ctx := context.Background()
err := lc.Append(ctx, "één regel") // 9 runes, 11 bytes, 12 with newline, within buffer size.
assert.NoError(t, err)
mocks.listener.EXPECT().LogProduced(ctx, "taskID", "één regel\nsecond line\n")
err = lc.Append(ctx, "second line") // this pushes the buffer over its max size.
assert.NoError(t, err)
assert.Equal(t, 0, lc.buffer.Len(), "buffer should be empty")
}
func TestLogChunkerTimedFlush(t *testing.T) {
mockCtrl := gomock.NewController(t)
defer mockCtrl.Finish()
lc, mocks := mockedLogChunker(t, mockCtrl)
lc.flushAfter = 2 * time.Second
ctx := context.Background()
err := lc.Append(ctx, "één regel") // No flush yet
assert.NoError(t, err)
mocks.clock.Add(2000 * time.Millisecond) // Exactly the threshold
err = lc.Append(ctx, "second line") // No flush yet
assert.NoError(t, err)
mocks.clock.Add(1 * time.Millisecond) // Juuuuust a bit longer than the threshold.
mocks.listener.EXPECT().LogProduced(ctx, "taskID", "één regel\nsecond line\nthird line\n")
err = lc.Append(ctx, "third line") // This should flush due to the long wait.
assert.NoError(t, err)
assert.Equal(t, 0, lc.buffer.Len(), "buffer should be empty")
}