diff --git a/CHANGELOG.md b/CHANGELOG.md index 6c14164f1a..a259255de7 100644 --- a/CHANGELOG.md +++ b/CHANGELOG.md @@ -28,6 +28,7 @@ We use *breaking* word for marking changes that are not backward compatible (rel - [#2532](https://github.com/thanos-io/thanos/pull/2532) Store: Added hidden option `--store.caching-bucket.config=` (or `--store.caching-bucket.config-file=`) for experimental caching bucket, that can cache chunks into shared memcached. This can speed up querying and reduce number of requests to object storage. - [#2579](https://github.com/thanos-io/thanos/pull/2579) Store: Experimental caching bucket can now cache metadata as well. Config has changed from #2532. - [#2526](https://github.com/thanos-io/thanos/pull/2526) Compact: In case there are no labels left after deduplication via `--deduplication.replica-label`, assign first `replica-label` with value `deduped`. +- [#2621](https://github.com/thanos-io/thanos/pull/2621) Receive: add flag to configure forward request timeout. ### Changed diff --git a/cmd/thanos/receive.go b/cmd/thanos/receive.go index cf2f6fba21..972e02f250 100644 --- a/cmd/thanos/receive.go +++ b/cmd/thanos/receive.go @@ -81,6 +81,8 @@ func registerReceive(m map[string]setupFunc, app *kingpin.Application) { replicationFactor := cmd.Flag("receive.replication-factor", "How many times to replicate incoming write requests.").Default("1").Uint64() + forwardTimeout := modelDuration(cmd.Flag("receive-forward-timeout", "Timeout for forward requests.").Default("5s").Hidden()) + tsdbMinBlockDuration := modelDuration(cmd.Flag("tsdb.min-block-duration", "Min duration for local TSDB blocks").Default("2h").Hidden()) tsdbMaxBlockDuration := modelDuration(cmd.Flag("tsdb.max-block-duration", "Max duration for local TSDB blocks").Default("2h").Hidden()) ignoreBlockSize := cmd.Flag("shipper.ignore-unequal-block-size", "If true receive will not require min and max block size flags to be set to the same value. Only use this if you want to keep long retention and compaction enabled, as in the worst case it can result in ~2h data loss for your Thanos bucket storage.").Default("false").Hidden().Bool() @@ -153,6 +155,7 @@ func registerReceive(m map[string]setupFunc, app *kingpin.Application) { *tenantLabelName, *replicaHeader, *replicationFactor, + time.Duration(*forwardTimeout), comp, ) } @@ -190,6 +193,7 @@ func runReceive( tenantLabelName string, replicaHeader string, replicationFactor uint64, + forwardTimeout time.Duration, comp component.SourceStoreAPI, ) error { logger = log.With(logger, "component", "receive") @@ -256,6 +260,7 @@ func runReceive( Tracer: tracer, TLSConfig: rwTLSConfig, DialOpts: dialOpts, + ForwardTimeout: forwardTimeout, }) grpcProbe := prober.NewGRPC() diff --git a/pkg/receive/handler.go b/pkg/receive/handler.go index ded8059527..b1f1c3eeca 100644 --- a/pkg/receive/handler.go +++ b/pkg/receive/handler.go @@ -13,6 +13,7 @@ import ( "net/http" "strconv" "sync" + "time" "github.com/go-kit/kit/log" "github.com/go-kit/kit/log/level" @@ -67,6 +68,7 @@ type Options struct { Tracer opentracing.Tracer TLSConfig *tls.Config DialOpts []grpc.DialOption + ForwardTimeout time.Duration } // Handler serves a Prometheus remote write receiving HTTP endpoint. @@ -324,32 +326,38 @@ func (h *Handler) forward(ctx context.Context, tenant string, r replica, wreq *p } h.mtx.RUnlock() - return h.parallelizeRequests(ctx, tenant, replicas, wreqs) + return h.fanoutForward(ctx, tenant, replicas, wreqs, len(wreqs)) } -// parallelizeRequests parallelizes a given set of write requests. -// The function only returns when all requests have finished -// or the context is canceled. -func (h *Handler) parallelizeRequests(ctx context.Context, tenant string, replicas map[string]replica, wreqs map[string]*prompb.WriteRequest) error { +// writeQuorum returns minimum number of replicas that has to confirm write success before claiming replication success. +func (h *Handler) writeQuorum() int { + return int((h.options.ReplicationFactor / 2) + 1) +} + +// fanoutForward fanouts concurrently given set of write requests. It returns status immediately when quorum of +// requests succeeds or fails or if context is cancelled. +func (h *Handler) fanoutForward(ctx context.Context, tenant string, replicas map[string]replica, wreqs map[string]*prompb.WriteRequest, successThreshold int) error { ec := make(chan error) - defer close(ec) - // We don't wan't to use a sync.WaitGroup here because that - // introduces an unnecessary second synchronization mechanism, - // the first being the error chan. Plus, it saves us a goroutine - // as in order to collect errors while doing wg.Wait, we would - // need a separate error collection goroutine. - var n int + + var wg sync.WaitGroup for endpoint := range wreqs { - n++ + wg.Add(1) + // If the request is not yet replicated, let's replicate it. // If the replication factor isn't greater than 1, let's // just forward the requests. if !replicas[endpoint].replicated && h.options.ReplicationFactor > 1 { go func(endpoint string) { - ec <- h.replicate(ctx, tenant, wreqs[endpoint]) + defer wg.Done() + if err := h.replicate(ctx, tenant, wreqs[endpoint]); err != nil { + ec <- errors.Wrap(err, "replicate write request") + return + } + ec <- nil }(endpoint) continue } + // If the endpoint for the write request is the // local node, then don't make a request but store locally. // By handing replication to the local node in the same @@ -358,37 +366,42 @@ func (h *Handler) parallelizeRequests(ctx context.Context, tenant string, replic // can be ignored if the replication factor is met. if endpoint == h.options.Endpoint { go func(endpoint string) { + defer wg.Done() var err error - tracing.DoInSpan(ctx, "receive_tsdb_write", func(ctx context.Context) { err = h.writer.Write(tenant, wreqs[endpoint]) }) - - // When a MultiError is added to another MultiError, the error slices are concatenated, not nested. - // To avoid breaking the counting logic, we need to flatten the error. - if errs, ok := err.(terrors.MultiError); ok { - if countCause(errs, isConflict) > 0 { - err = errors.Wrap(conflictErr, errs.Error()) - } else if countCause(errs, isNotReady) > 0 { - err = tsdb.ErrNotReady - } else { - err = errors.New(errs.Error()) - } - } if err != nil { - level.Error(h.logger).Log("msg", "storing locally", "err", err, "endpoint", endpoint) + // When a MultiError is added to another MultiError, the error slices are concatenated, not nested. + // To avoid breaking the counting logic, we need to flatten the error. + if errs, ok := err.(terrors.MultiError); ok { + if countCause(errs, isConflict) > 0 { + err = errors.Wrap(conflictErr, errs.Error()) + } else if countCause(errs, isNotReady) > 0 { + err = tsdb.ErrNotReady + } else { + err = errors.New(errs.Error()) + } + } + ec <- errors.Wrapf(err, "storing locally, endpoint %v", endpoint) + return } - ec <- err + ec <- nil + }(endpoint) continue } + // Make a request to the specified endpoint. go func(endpoint string) { - var err error + defer wg.Done() - // Increment the counters as necessary now that - // the requests will go out. + var ( + err error + cl storepb.WriteableStoreClient + ) defer func() { + // This is an actual remote forward request so report metric here. if err != nil { h.forwardRequestsTotal.WithLabelValues("error").Inc() return @@ -396,44 +409,71 @@ func (h *Handler) parallelizeRequests(ctx context.Context, tenant string, replic h.forwardRequestsTotal.WithLabelValues("success").Inc() }() - cl, err := h.peers.get(ctx, endpoint) + cl, err = h.peers.get(ctx, endpoint) if err != nil { - level.Error(h.logger).Log("msg", "failed to get peer connection to forward request", "err", err, "endpoint", endpoint) - ec <- err + ec <- errors.Wrapf(err, "get peer connection for endpoint %v", endpoint) return } // Create a span to track the request made to another receive node. tracing.DoInSpan(ctx, "receive_forward", func(ctx context.Context) { - // Actually make the request against the endpoint - // we determined should handle these time series. + // Actually make the request against the endpoint we determined should handle these time series. _, err = cl.RemoteWrite(ctx, &storepb.WriteRequest{ Timeseries: wreqs[endpoint].Timeseries, Tenant: tenant, - Replica: int64(replicas[endpoint].n + 1), // increment replica since on-the-wire format is 1-indexed and 0 indicates unreplicated. + // Increment replica since on-the-wire format is 1-indexed and 0 indicates un-replicated. + Replica: int64(replicas[endpoint].n + 1), }) - if err != nil { - level.Error(h.logger).Log("msg", "forwarding request", "err", err, "endpoint", endpoint) - ec <- err - return - } - ec <- nil }) + if err != nil { + ec <- errors.Wrapf(err, "forwarding request to endpoint %v", endpoint) + return + } + ec <- nil }(endpoint) } - // Collect any errors from forwarding the time series. - // Rather than doing a wg.Wait here, we decrement a counter - // for every error received on the chan. This simplifies - // error collection and avoids data races with a separate - // error collection goroutine. - var errs terrors.MultiError - for ; n > 0; n-- { - if err := <-ec; err != nil { + go func() { + wg.Wait() + close(ec) + }() + + // At the end, make sure to exhaust the channel, letting remaining unnecessary requests finish asnychronously. + // This is needed if context is cancelled or if we reached success of fail quorum faster. + defer func() { + go func() { + for err := range ec { + if err != nil { + level.Debug(h.logger).Log("msg", "request failed, but not needed to achieve quorum", "err", err) + } + } + }() + }() + + var ( + success int + errs terrors.MultiError + ) + for { + select { + case <-ctx.Done(): + return ctx.Err() + case err, more := <-ec: + if !more { + return errs + } + if err == nil { + success++ + if success >= successThreshold { + // In case the success threshold is lower than the total + // number of requests, then we can finish early here. This + // is the case for quorum writes for example. + return nil + } + continue + } errs.Add(err) } } - - return errs.Err() } // replicate replicates a write request to (replication-factor) nodes @@ -464,20 +504,22 @@ func (h *Handler) replicate(ctx context.Context, tenant string, wreq *prompb.Wri } h.mtx.RUnlock() - err := h.parallelizeRequests(ctx, tenant, replicas, wreqs) - if errs, ok := err.(terrors.MultiError); ok { - if uint64(countCause(errs, isNotReady)) >= (h.options.ReplicationFactor+1)/2 { - return tsdb.ErrNotReady - } - if uint64(countCause(errs, isConflict)) >= (h.options.ReplicationFactor+1)/2 { - return errors.Wrap(conflictErr, "did not meet replication threshold") - } - if uint64(len(errs)) >= (h.options.ReplicationFactor+1)/2 { - return errors.Wrap(err, "did not meet replication threshold") - } - return nil + ctx, cancel := context.WithTimeout(ctx, h.options.ForwardTimeout) + defer cancel() + + quorum := h.writeQuorum() + err := h.fanoutForward(ctx, tenant, replicas, wreqs, quorum) + if countCause(err, isNotReady) >= quorum { + return tsdb.ErrNotReady } - return errors.Wrap(err, "could not replicate write request") + if countCause(err, isConflict) >= quorum { + return errors.Wrap(conflictErr, "did not meet success threshold due to conflict") + } + if err != nil { + return errors.Wrap(err, "replicate") + } + + return nil } // RemoteWrite implements the gRPC remote write handler for storepb.WriteableStore. diff --git a/pkg/receive/handler_test.go b/pkg/receive/handler_test.go index 9612a13b83..731c821b40 100644 --- a/pkg/receive/handler_test.go +++ b/pkg/receive/handler_test.go @@ -165,6 +165,7 @@ func newHandlerHashring(appendables []*fakeAppendable, replicationFactor uint64) TenantHeader: DefaultTenantHeader, ReplicaHeader: DefaultReplicaHeader, ReplicationFactor: replicationFactor, + ForwardTimeout: 5 * time.Second, Writer: NewWriter(log.NewNopLogger(), newFakeTenantAppendable(appendables[i])), }) handlers = append(handlers, h) @@ -181,7 +182,7 @@ func newHandlerHashring(appendables []*fakeAppendable, replicationFactor uint64) return handlers, hashring } -func TestReceive(t *testing.T) { +func TestReceiveQuorum(t *testing.T) { defer leaktest.CheckTimeout(t, 10*time.Second) appenderErrFn := func() error { return errors.New("failed to get appender") } @@ -491,6 +492,350 @@ func TestReceive(t *testing.T) { t.Errorf("handler %d: got unexpected HTTP status code: expected %d, got %d; body: %s", i, tc.status, rec.Code, rec.Body.String()) } } + // Test that each time series is stored + // the correct amount of times in each fake DB. + for _, ts := range tc.wreq.Timeseries { + lset := make(labels.Labels, len(ts.Labels)) + for j := range ts.Labels { + lset[j] = labels.Label{ + Name: ts.Labels[j].Name, + Value: ts.Labels[j].Value, + } + } + for j, a := range tc.appendables { + var expectedMin int + n := a.appender.(*fakeAppender).Get(lset) + got := uint64(len(n)) + if a.appenderErr == nil && endpointHit(t, hashring, tc.replicationFactor, handlers[j].options.Endpoint, tenant, &ts) { + // We have len(handlers) copies of each sample because the test case + // is run once for each handler and they all use the same appender. + expectedMin = int((tc.replicationFactor/2)+1) * len(ts.Samples) + } + if uint64(expectedMin) > got { + t.Errorf("handler: %d, labels %q: expected minimum of %d samples, got %d", j, lset.String(), expectedMin, got) + } + } + } + }) + } +} + +func TestReceiveWithConsistencyDelay(t *testing.T) { + defer leaktest.CheckTimeout(t, 10*time.Second) + + appenderErrFn := func() error { return errors.New("failed to get appender") } + conflictErrFn := func() error { return storage.ErrOutOfBounds } + commitErrFn := func() error { return errors.New("failed to commit") } + wreq1 := &prompb.WriteRequest{ + Timeseries: []prompb.TimeSeries{ + { + Labels: []prompb.Label{ + { + Name: "foo", + Value: "bar", + }, + }, + Samples: []prompb.Sample{ + { + Value: 1, + Timestamp: 1, + }, + { + Value: 2, + Timestamp: 2, + }, + { + Value: 3, + Timestamp: 3, + }, + }, + }, + }, + } + for _, tc := range []struct { + name string + status int + replicationFactor uint64 + wreq *prompb.WriteRequest + appendables []*fakeAppendable + }{ + { + name: "size 1 success", + status: http.StatusOK, + replicationFactor: 1, + wreq: wreq1, + appendables: []*fakeAppendable{ + { + appender: newFakeAppender(nil, nil, nil, nil), + }, + }, + }, + { + name: "size 1 commit error", + status: http.StatusInternalServerError, + replicationFactor: 1, + wreq: wreq1, + appendables: []*fakeAppendable{ + { + appender: newFakeAppender(nil, nil, commitErrFn, nil), + }, + }, + }, + { + name: "size 1 conflict", + status: http.StatusConflict, + replicationFactor: 1, + wreq: wreq1, + appendables: []*fakeAppendable{ + { + appender: newFakeAppender(conflictErrFn, nil, nil, nil), + }, + }, + }, + { + name: "size 2 success", + status: http.StatusOK, + replicationFactor: 1, + wreq: wreq1, + appendables: []*fakeAppendable{ + { + appender: newFakeAppender(nil, nil, nil, nil), + }, + { + appender: newFakeAppender(nil, nil, nil, nil), + }, + }, + }, + { + name: "size 3 success", + status: http.StatusOK, + replicationFactor: 1, + wreq: wreq1, + appendables: []*fakeAppendable{ + { + appender: newFakeAppender(nil, nil, nil, nil), + }, + { + appender: newFakeAppender(nil, nil, nil, nil), + }, + { + appender: newFakeAppender(nil, nil, nil, nil), + }, + }, + }, + { + name: "size 3 success with replication", + status: http.StatusOK, + replicationFactor: 3, + wreq: wreq1, + appendables: []*fakeAppendable{ + { + appender: newFakeAppender(nil, nil, nil, nil), + }, + { + appender: newFakeAppender(nil, nil, nil, nil), + }, + { + appender: newFakeAppender(nil, nil, nil, nil), + }, + }, + }, + { + name: "size 3 commit error", + status: http.StatusInternalServerError, + replicationFactor: 1, + wreq: wreq1, + appendables: []*fakeAppendable{ + { + appender: newFakeAppender(nil, nil, commitErrFn, nil), + }, + { + appender: newFakeAppender(nil, nil, commitErrFn, nil), + }, + { + appender: newFakeAppender(nil, nil, commitErrFn, nil), + }, + }, + }, + { + name: "size 3 commit error with replication", + status: http.StatusInternalServerError, + replicationFactor: 3, + wreq: wreq1, + appendables: []*fakeAppendable{ + { + appender: newFakeAppender(nil, nil, commitErrFn, nil), + }, + { + appender: newFakeAppender(nil, nil, commitErrFn, nil), + }, + { + appender: newFakeAppender(nil, nil, commitErrFn, nil), + }, + }, + }, + { + name: "size 3 appender error with replication", + status: http.StatusInternalServerError, + replicationFactor: 3, + wreq: wreq1, + appendables: []*fakeAppendable{ + { + appender: newFakeAppender(nil, nil, nil, nil), + appenderErr: appenderErrFn, + }, + { + appender: newFakeAppender(nil, nil, nil, nil), + appenderErr: appenderErrFn, + }, + { + appender: newFakeAppender(nil, nil, nil, nil), + appenderErr: appenderErrFn, + }, + }, + }, + { + name: "size 3 conflict with replication", + status: http.StatusConflict, + replicationFactor: 3, + wreq: wreq1, + appendables: []*fakeAppendable{ + { + appender: newFakeAppender(conflictErrFn, nil, nil, nil), + }, + { + appender: newFakeAppender(conflictErrFn, nil, nil, nil), + }, + { + appender: newFakeAppender(conflictErrFn, nil, nil, nil), + }, + }, + }, + { + name: "size 3 conflict and commit error with replication", + status: http.StatusConflict, + replicationFactor: 3, + wreq: wreq1, + appendables: []*fakeAppendable{ + { + appender: newFakeAppender(conflictErrFn, nil, commitErrFn, nil), + }, + { + appender: newFakeAppender(conflictErrFn, nil, commitErrFn, nil), + }, + { + appender: newFakeAppender(conflictErrFn, nil, commitErrFn, nil), + }, + }, + }, + { + name: "size 3 with replication and one faulty", + status: http.StatusOK, + replicationFactor: 3, + wreq: wreq1, + appendables: []*fakeAppendable{ + { + appender: newFakeAppender(cycleErrors([]error{storage.ErrOutOfBounds, storage.ErrOutOfOrderSample, storage.ErrDuplicateSampleForTimestamp}), nil, nil, nil), + }, + { + appender: newFakeAppender(nil, nil, nil, nil), + }, + { + appender: newFakeAppender(nil, nil, nil, nil), + }, + }, + }, + { + name: "size 3 with replication and one commit error", + status: http.StatusOK, + replicationFactor: 3, + wreq: wreq1, + appendables: []*fakeAppendable{ + { + appender: newFakeAppender(nil, nil, commitErrFn, nil), + }, + { + appender: newFakeAppender(nil, nil, nil, nil), + }, + { + appender: newFakeAppender(nil, nil, nil, nil), + }, + }, + }, + { + name: "size 3 with replication and two conflicts", + status: http.StatusConflict, + replicationFactor: 3, + wreq: wreq1, + appendables: []*fakeAppendable{ + { + appender: newFakeAppender(cycleErrors([]error{storage.ErrOutOfBounds, storage.ErrOutOfOrderSample, storage.ErrDuplicateSampleForTimestamp}), nil, nil, nil), + }, + { + appender: newFakeAppender(conflictErrFn, nil, nil, nil), + }, + { + appender: newFakeAppender(nil, nil, nil, nil), + }, + }, + }, + { + name: "size 3 with replication one conflict and one commit error", + status: http.StatusInternalServerError, + replicationFactor: 3, + wreq: wreq1, + appendables: []*fakeAppendable{ + { + appender: newFakeAppender(cycleErrors([]error{storage.ErrOutOfBounds, storage.ErrOutOfOrderSample, storage.ErrDuplicateSampleForTimestamp}), nil, nil, nil), + }, + { + appender: newFakeAppender(nil, nil, commitErrFn, nil), + }, + { + appender: newFakeAppender(nil, nil, nil, nil), + }, + }, + }, + { + name: "size 3 with replication two commit errors", + status: http.StatusInternalServerError, + replicationFactor: 3, + wreq: wreq1, + appendables: []*fakeAppendable{ + { + appender: newFakeAppender(nil, nil, commitErrFn, nil), + }, + { + appender: newFakeAppender(nil, nil, commitErrFn, nil), + }, + { + appender: newFakeAppender(nil, nil, nil, nil), + }, + }, + }, + } { + // Run the quorum tests with consistency delay, which should allow us + // to see all requests completing all the time, since we're using local + // network we are not expecting anything to go wrong with these. + t.Run(tc.name, func(t *testing.T) { + handlers, hashring := newHandlerHashring(tc.appendables, tc.replicationFactor) + tenant := "test" + // Test from the point of view of every node + // so that we know status code does not depend + // on which node is erroring and which node is receiving. + for i, handler := range handlers { + // Test that the correct status is returned. + rec, err := makeRequest(handler, tenant, tc.wreq) + if err != nil { + t.Fatalf("handler %d: unexpectedly failed making HTTP request: %v", tc.status, err) + } + if rec.Code != tc.status { + t.Errorf("handler %d: got unexpected HTTP status code: expected %d, got %d; body: %s", i, tc.status, rec.Code, rec.Body.String()) + } + } + + time.Sleep(50 * time.Millisecond) + // Test that each time series is stored // the correct amount of times in each fake DB. for _, ts := range tc.wreq.Timeseries { @@ -503,7 +848,7 @@ func TestReceive(t *testing.T) { } for j, a := range tc.appendables { var expected int - n := a.appender.(*fakeAppender).samples[lset.Hash()] + n := a.appender.(*fakeAppender).Get(lset) got := uint64(len(n)) if a.appenderErr == nil && endpointHit(t, hashring, tc.replicationFactor, handlers[j].options.Endpoint, tenant, &ts) { // We have len(handlers) copies of each sample because the test case diff --git a/pkg/receive/writer.go b/pkg/receive/writer.go index 728abbe742..f5ad27ef95 100644 --- a/pkg/receive/writer.go +++ b/pkg/receive/writer.go @@ -171,6 +171,15 @@ func newFakeAppender(addErr, addFastErr, commitErr, rollbackErr func() error) *f } } +func (f *fakeAppender) Get(l labels.Labels) []prompb.Sample { + f.Lock() + defer f.Unlock() + s := f.samples[l.Hash()] + res := make([]prompb.Sample, len(s)) + copy(res, s) + return res +} + func (f *fakeAppender) Add(l labels.Labels, t int64, v float64) (uint64, error) { f.Lock() defer f.Unlock()