prometheus/wal_test.go

387 lines
10 KiB
Go
Raw Normal View History

2017-04-10 11:59:45 -07:00
// Copyright 2017 The Prometheus Authors
// Licensed under the Apache License, Version 2.0 (the "License");
// you may not use this file except in compliance with the License.
// You may obtain a copy of the License at
//
// http://www.apache.org/licenses/LICENSE-2.0
//
// Unless required by applicable law or agreed to in writing, software
// distributed under the License is distributed on an "AS IS" BASIS,
// WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
// See the License for the specific language governing permissions and
// limitations under the License.
2016-12-22 06:18:33 -08:00
package tsdb
import (
2017-02-14 15:54:52 -08:00
"encoding/binary"
"fmt"
2016-12-22 06:18:33 -08:00
"io/ioutil"
2017-02-14 21:54:59 -08:00
"math/rand"
2016-12-22 06:18:33 -08:00
"os"
"testing"
2017-02-14 15:54:52 -08:00
"github.com/coreos/etcd/pkg/fileutil"
"github.com/go-kit/kit/log"
2016-12-22 06:18:33 -08:00
"github.com/stretchr/testify/require"
)
2017-05-13 08:09:26 -07:00
func TestSegmentWAL_initSegments(t *testing.T) {
2017-02-14 15:54:52 -08:00
tmpdir, err := ioutil.TempDir("", "test_wal_open")
require.NoError(t, err)
defer os.RemoveAll(tmpdir)
df, err := fileutil.OpenDir(tmpdir)
require.NoError(t, err)
2016-12-22 06:18:33 -08:00
2017-05-13 08:09:26 -07:00
w := &SegmentWAL{dirFile: df}
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
// Create segment files with an appropriate header.
for i := 1; i <= 5; i++ {
metab := make([]byte, 8)
binary.BigEndian.PutUint32(metab[:4], WALMagic)
metab[4] = WALFormatDefault
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
f, err := os.Create(fmt.Sprintf("%s/000%d", tmpdir, i))
require.NoError(t, err)
_, err = f.Write(metab)
require.NoError(t, err)
require.NoError(t, f.Close())
2016-12-22 06:18:33 -08:00
}
2017-02-14 15:54:52 -08:00
// Initialize 5 correct segment files.
require.NoError(t, w.initSegments())
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
require.Equal(t, 5, len(w.files), "unexpected number of segments loaded")
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
// Validate that files are locked properly.
for _, of := range w.files {
f, err := os.Open(of.f.Name())
2017-02-14 15:54:52 -08:00
require.NoError(t, err, "open locked segment %s", f.Name())
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
_, err = f.Read([]byte{0})
require.NoError(t, err, "read locked segment %s", f.Name())
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
_, err = f.Write([]byte{0})
require.Error(t, err, "write to tail segment file %s", f.Name())
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
require.NoError(t, f.Close())
}
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
for _, f := range w.files {
require.NoError(t, f.Close())
}
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
// Make initialization fail by corrupting the header of one file.
f, err := os.OpenFile(w.files[3].f.Name(), os.O_WRONLY, 0666)
2017-02-14 15:54:52 -08:00
require.NoError(t, err)
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
_, err = f.WriteAt([]byte{0}, 4)
require.NoError(t, err)
2016-12-22 06:18:33 -08:00
2017-05-13 08:09:26 -07:00
w = &SegmentWAL{dirFile: df}
2017-02-14 15:54:52 -08:00
require.Error(t, w.initSegments(), "init corrupted segments")
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
for _, f := range w.files {
require.NoError(t, f.Close())
}
}
2016-12-22 06:18:33 -08:00
2017-05-13 08:09:26 -07:00
func TestSegmentWAL_cut(t *testing.T) {
2017-02-14 15:54:52 -08:00
tmpdir, err := ioutil.TempDir("", "test_wal_cut")
require.NoError(t, err)
defer os.RemoveAll(tmpdir)
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
// This calls cut() implicitly the first time without a previous tail.
2017-05-13 08:09:26 -07:00
w, err := OpenSegmentWAL(tmpdir, nil, 0)
2017-02-14 15:54:52 -08:00
require.NoError(t, err)
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
require.NoError(t, w.entry(WALEntrySeries, 1, []byte("Hello World!!")))
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
require.NoError(t, w.cut(), "cut failed")
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
// Cutting creates a new file and close the previous tail file.
require.Equal(t, 2, len(w.files))
require.Error(t, w.files[0].Close())
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
require.NoError(t, w.entry(WALEntrySeries, 1, []byte("Hello World!!")))
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
require.NoError(t, w.Close())
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
for _, of := range w.files {
f, err := os.Open(of.f.Name())
2017-02-14 15:54:52 -08:00
require.NoError(t, err)
// Verify header data.
metab := make([]byte, 8)
_, err = f.Read(metab)
require.NoError(t, err, "read meta data %s", f.Name())
require.Equal(t, WALMagic, binary.BigEndian.Uint32(metab[:4]), "verify magic")
require.Equal(t, WALFormatDefault, metab[4], "verify format flag")
2016-12-22 06:18:33 -08:00
2017-02-14 15:54:52 -08:00
// We cannot actually check for correct pre-allocation as it is
// optional per filesystem and handled transparently.
et, flag, b, err := newWALReader(nil, 0, nil).entry(f)
2017-02-14 15:54:52 -08:00
require.NoError(t, err)
require.Equal(t, WALEntrySeries, et)
require.Equal(t, flag, byte(walSeriesSimple))
require.Equal(t, []byte("Hello World!!"), b)
}
2016-12-22 06:18:33 -08:00
}
2017-02-14 21:54:59 -08:00
// Symmetrical test of reading and writing to the WAL via its main interface.
2017-05-13 08:09:26 -07:00
func TestSegmentWAL_Log_Restore(t *testing.T) {
const (
numMetrics = 5000
iterations = 5
stepSize = 100
)
2017-02-14 21:54:59 -08:00
// Generate testing data. It does not make semantical sense but
// for the purpose of this test.
series, err := readPrometheusLabels("testdata/20k.series", numMetrics)
2017-02-14 21:54:59 -08:00
require.NoError(t, err)
dir, err := ioutil.TempDir("", "test_wal_log_restore")
require.NoError(t, err)
defer os.RemoveAll(dir)
var (
recordedSeries [][]RefSeries
recordedSamples [][]RefSample
recordedDeletes [][]Stone
)
var totalSamples int
2017-02-14 21:54:59 -08:00
// Open WAL a bunch of times, validate all previous data can be read,
// write more data to it, close it.
for k := 0; k < numMetrics; k += numMetrics / iterations {
2017-05-13 08:09:26 -07:00
w, err := OpenSegmentWAL(dir, nil, 0)
require.NoError(t, err)
2017-02-14 21:54:59 -08:00
// Set smaller segment size so we can actually write several files.
w.segmentSize = 1000 * 1000
r := w.Reader(0)
var (
resultSeries [][]RefSeries
resultSamples [][]RefSample
resultDeletes [][]Stone
)
serf := func(series []RefSeries) error {
if len(series) > 0 {
clsets := make([]RefSeries, len(series))
copy(clsets, series)
resultSeries = append(resultSeries, series)
}
return nil
}
smplf := func(smpls []RefSample) error {
if len(smpls) > 0 {
csmpls := make([]RefSample, len(smpls))
copy(csmpls, smpls)
resultSamples = append(resultSamples, csmpls)
}
return nil
}
delf := func(stones []Stone) error {
if len(stones) > 0 {
resultDeletes = append(resultDeletes, stones)
}
return nil
}
require.NoError(t, r.Read(serf, smplf, delf))
2017-02-14 21:54:59 -08:00
require.Equal(t, recordedSamples, resultSamples)
require.Equal(t, recordedSeries, resultSeries)
require.Equal(t, recordedDeletes, resultDeletes)
2017-02-14 21:54:59 -08:00
series := series[k : k+(numMetrics/iterations)]
2017-02-14 21:54:59 -08:00
// Insert in batches and generate different amounts of samples for each.
for i := 0; i < len(series); i += stepSize {
var samples []RefSample
var stones []Stone
2017-02-14 21:54:59 -08:00
for j := 0; j < i*10; j++ {
samples = append(samples, RefSample{
Ref: uint64(j % 10000),
T: int64(j * 2),
V: rand.Float64(),
})
}
2017-02-14 21:54:59 -08:00
for j := 0; j < i*20; j++ {
ts := rand.Int63()
stones = append(stones, Stone{rand.Uint32(), Intervals{{ts, ts + rand.Int63n(10000)}}})
}
lbls := series[i : i+stepSize]
series := make([]RefSeries, 0, len(series))
for j, l := range lbls {
series = append(series, RefSeries{
Ref: uint64(i + j),
Labels: l,
})
}
require.NoError(t, w.LogSeries(series))
require.NoError(t, w.LogSamples(samples))
require.NoError(t, w.LogDeletes(stones))
if len(lbls) > 0 {
recordedSeries = append(recordedSeries, series)
}
if len(samples) > 0 {
recordedSamples = append(recordedSamples, samples)
totalSamples += len(samples)
}
if len(stones) > 0 {
recordedDeletes = append(recordedDeletes, stones)
}
2017-02-14 21:54:59 -08:00
}
require.NoError(t, w.Close())
2017-02-14 21:54:59 -08:00
}
}
// Test reading from a WAL that has been corrupted through various means.
func TestWALRestoreCorrupted(t *testing.T) {
cases := []struct {
name string
2017-05-13 08:09:26 -07:00
f func(*testing.T, *SegmentWAL)
}{
{
name: "truncate_checksum",
2017-05-13 08:09:26 -07:00
f: func(t *testing.T, w *SegmentWAL) {
f, err := os.OpenFile(w.files[0].f.Name(), os.O_WRONLY, 0666)
require.NoError(t, err)
defer f.Close()
off, err := f.Seek(0, os.SEEK_END)
require.NoError(t, err)
require.NoError(t, f.Truncate(off-1))
},
},
{
name: "truncate_body",
2017-05-13 08:09:26 -07:00
f: func(t *testing.T, w *SegmentWAL) {
f, err := os.OpenFile(w.files[0].f.Name(), os.O_WRONLY, 0666)
require.NoError(t, err)
defer f.Close()
off, err := f.Seek(0, os.SEEK_END)
require.NoError(t, err)
require.NoError(t, f.Truncate(off-8))
},
},
{
name: "body_content",
2017-05-13 08:09:26 -07:00
f: func(t *testing.T, w *SegmentWAL) {
f, err := os.OpenFile(w.files[0].f.Name(), os.O_WRONLY, 0666)
require.NoError(t, err)
defer f.Close()
off, err := f.Seek(0, os.SEEK_END)
require.NoError(t, err)
// Write junk before checksum starts.
_, err = f.WriteAt([]byte{1, 2, 3, 4}, off-8)
require.NoError(t, err)
},
},
{
name: "checksum",
2017-05-13 08:09:26 -07:00
f: func(t *testing.T, w *SegmentWAL) {
f, err := os.OpenFile(w.files[0].f.Name(), os.O_WRONLY, 0666)
require.NoError(t, err)
defer f.Close()
off, err := f.Seek(0, os.SEEK_END)
require.NoError(t, err)
// Write junk into checksum
_, err = f.WriteAt([]byte{1, 2, 3, 4}, off-4)
require.NoError(t, err)
},
},
}
for _, c := range cases {
t.Run(c.name, func(t *testing.T) {
// Generate testing data. It does not make semantical sense but
// for the purpose of this test.
dir, err := ioutil.TempDir("", "test_corrupted_checksum")
require.NoError(t, err)
defer os.RemoveAll(dir)
2017-05-13 08:09:26 -07:00
w, err := OpenSegmentWAL(dir, nil, 0)
require.NoError(t, err)
require.NoError(t, w.LogSamples([]RefSample{{T: 1, V: 2}}))
require.NoError(t, w.LogSamples([]RefSample{{T: 2, V: 3}}))
require.NoError(t, w.cut())
require.NoError(t, w.LogSamples([]RefSample{{T: 3, V: 4}}))
require.NoError(t, w.LogSamples([]RefSample{{T: 5, V: 6}}))
require.NoError(t, w.Close())
// Corrupt the second entry in the first file.
// After re-opening we must be able to read the first entry
// and the rest, including the second file, must be truncated for clean further
// writes.
c.f(t, w)
logger := log.NewLogfmtLogger(os.Stderr)
2017-05-13 08:09:26 -07:00
w2, err := OpenSegmentWAL(dir, logger, 0)
require.NoError(t, err)
r := w2.Reader(0)
serf := func(l []RefSeries) error {
require.Equal(t, 0, len(l))
return nil
}
delf := func([]Stone) error { return nil }
// Weird hack to check order of reads.
i := 0
samplf := func(s []RefSample) error {
if i == 0 {
require.Equal(t, []RefSample{{T: 1, V: 2}}, s)
i++
} else {
require.Equal(t, []RefSample{{T: 99, V: 100}}, s)
}
return nil
}
require.NoError(t, r.Read(serf, samplf, delf))
require.NoError(t, w2.LogSamples([]RefSample{{T: 99, V: 100}}))
require.NoError(t, w2.Close())
// We should see the first valid entry and the new one, everything after
// is truncated.
2017-05-13 08:09:26 -07:00
w3, err := OpenSegmentWAL(dir, logger, 0)
require.NoError(t, err)
r = w3.Reader(0)
i = 0
require.NoError(t, r.Read(serf, samplf, delf))
})
}
}