`middleware.BodyDump`: data race — error response is written into a dump buffer already returned to `sync.Pool`
Mantenedores costumam responder em até 1 dia
Avaliação
- Dificuldade
- 3/5
- Tempo estimado
- Meio dia
- Facilidade para iniciantes
- 62/100
Direção de pesquisa
Start in middleware/body_dump.go, where the response writer is installed with c.SetResponse and the dump buffer is returned to bodyDumpBufferPool, then check echo.go around serveHTTP to see when HTTPErrorHandler runs after the chain returns. Add a regression test based on the reproduction in the issue and run it with go test -race. Done means the race is gone, the writer is restored before BodyDump returns, and the error response body is unchanged.
Escrita pelo modelo de indexação a partir do texto da issue.
Descrição
Echo version: github.com/labstack/echo/v5 v5.4.0
Go version: 1.25
Summary
BodyDump installs a response-writer wrapper on the Context via c.SetResponse and never restores it. It also returns its dump buffer to bodyDumpBufferPool when the middleware returns.
Because Echo.serveHTTP invokes the global HTTPErrorHandler after the middleware chain has returned, the error response is written through the still-installed wrapper — landing in a *bytes.Buffer that has already been handed back to the pool.
Root cause
middleware/body_dump.go:
resBuf := bodyDumpBufferPool.Get().(*bytes.Buffer) // L116
resBuf.Reset()
defer bodyDumpBufferPool.Put(resBuf) // L118 <- returned to pool
...
writer := &bodyDumpResponseWriter{...}
c.SetResponse(writer) // L134 <- never restored
err := next(c) // L136
config.Handler(c, reqBody, resBuf.Bytes(), err) // L139
return err // L141 <- deferred Put runs here
echo.go:
err = e.preChain(c) // L841
...
if err != nil {
e.HTTPErrorHandler(c, err) // L845 <- runs after the chain returned
}
So the ordering is: buffer returned to pool → error handler writes JSON → limitedWriter.Write (L193) → dumpBuf.Write on the pooled buffer.
Reproduction
package middleware_test
import (
"bytes"
"net/http"
"net/http/httptest"
"sync"
"testing"
"github.com/labstack/echo/v5"
"github.com/labstack/echo/v5/middleware"
)
func TestBodyDumpErrorHandlerWritesToReturnedBuffer(t *testing.T) {
e := echo.New()
e.HTTPErrorHandler = func(c *echo.Context, err error) {
_ = c.JSON(http.StatusOK, map[string]any{"error": err.Error()})
}
e.Pre(middleware.BodyDump(func(c *echo.Context, reqBody, rspBody []byte, err error) {}))
e.POST("/err", func(c *echo.Context) error {
return echo.NewHTTPError(http.StatusBadRequest, "boom")
})
var wg sync.WaitGroup
for i := 0; i < 16; i++ {
wg.Add(1)
go func() {
defer wg.Done()
for j := 0; j < 500; j++ {
req := httptest.NewRequest(http.MethodPost, "/err", bytes.NewReader(make([]byte, 256)))
e.ServeHTTP(httptest.NewRecorder(), req)
}
}()
}
wg.Wait()
}
$ go test -race -run TestBodyDumpErrorHandlerWritesToReturnedBuffer ./middleware/
WARNING: DATA RACE
Write at 0x00c00028e0e0 by goroutine 11:
bytes.(*Buffer).Write()
middleware.(*limitedWriter).Write()
middleware/body_dump.go:193
middleware.(*bodyDumpResponseWriter).Write()
middleware/body_dump.go:151
echo.(*delayedStatusWriter).Write()
response.go:157
encoding/json.(*Encoder).Encode()
echo.(*Context).JSON()
main.TestBodyDumpErrorHandlerWritesToReturnedBuffer.func1()
echo.(*Echo).serveHTTP()
echo.go:845
The conflicting access is another request's reqBuf.Reset() at body_dump.go:94. Reproduced on every run (35–43 races per run with 16 goroutines × 500 requests).
Note this needs e.Pre(...) or e.Use(...) — either works, since HTTPErrorHandler is always called after the chain returns.
Impact
- Genuine data race on
bytes.Bufferinternals — undefined behaviour per the Go memory model, flagged by-race. - The pool is shared by
reqBuf(L93) andresBuf(L116), so the stale write can land in another request'sreqBuf. BodyDump rebuildsc.Request().Bodyfrom that buffer (L111-113), so a concurrent request can have its request body corrupted before it reaches the handler — not just polluted dump output. - Client-visible responses are not affected:
limitedWriter.Write(L184) writes to the real writer before mirroring into the dump buffer. - Intermittent by nature:
sync.Poolmay drop the object at GC, so it can go unnoticed in short runs.
Suggested fix
Restore the previous response writer before the middleware returns, so that writes occurring after the chain has unwound do not go through the wrapper:
prev := c.Response()
c.SetResponse(writer)
defer c.SetResponse(prev)
Either defer ordering works — the requirement is only that c.Response() is back to the previous writer by the time BodyDump returns.
Workaround
Wrap BodyDump and restore the writer after next returns:
inner := middleware.BodyDumpWithConfig(cfg)
return func(c *echo.Context) error {
orig := c.Response()
err := inner(next)(c)
c.SetResponse(orig)
return err
}
Or restore it in the global error handler:
if r, uerr := echo.UnwrapResponse(c.Response()); uerr == nil && r != nil {
c.SetResponse(r)
}
Both verified to eliminate the race (-race clean, error response body unchanged).
- Linguagem predominante
- Go
- Estrelas
- 32.8k
- Forks
- 5.1k
- Merge médio
- 14h 51min
- PRs com merge (30d)
- 32
Preparar o ambiente
Este projeto não oferece contêiner de desenvolvimento, Dockerfile nem guia de contribuição, então a configuração fica por sua conta: comece pelo README e veja nosso guia da primeira contribuição para os passos gerais.
Primeiros passos
- Leia a issue inteira e depois o guia de contribuição do projeto.
- Comente na issue dizendo que vai assumir — evita que duas pessoas façam o mesmo trabalho.
- Faça um fork do repositório e trabalhe em uma branch.
- Abra um pull request que referencie o número da issue.
Mais de labstack/echo
-
BindHeaders panics on nil or empty header value slicesTalvez já em andamento @SashaMIT assumiu há 4 dias. Aberta
Dificuldade 2/5 1-3 horas Facilidade para iniciantes 73/100
Mantenedores costumam responder em até 1 dia
-
Group routes does not work without leading slashTalvez já em andamento @team-humaki assumiu há 25 dias. Abertaenhancement router v5
Dificuldade 2/5 1-3 horas Facilidade para iniciantes 74/100
labstack/echo#3099 · 2 comentários ·
Mantenedores costumam responder em até 1 dia
-
enhancement v5
Dificuldade 5/5 Mais de uma semana Facilidade para iniciantes 35/100
labstack/echo#3066 · 1 comentário ·
Mantenedores costumam responder em até 1 dia
-
Rate limit middleware doesn't set headers or provide metadata via interface.Talvez já em andamento @AdamMagued assumiu há 6 dias. Abertaenhancement middleware
Dificuldade 4/5 3-5 dias Facilidade para iniciantes 48/100
labstack/echo#2961 · 2 comentários ·
Mantenedores costumam responder em até 1 dia
-
enhancement router
Dificuldade 4/5 3-5 dias Facilidade para iniciantes 42/100
labstack/echo#2895 · 16 comentários · 1 reação ·
Mantenedores costumam responder em até 1 dia
Todas as issues de labstack/echo
Issues semelhantes
-
area: watch bug priority: low
Dificuldade 2/5 1-3 horas Facilidade para iniciantes 88/100
Mantenedores costumam responder em até 1 dia
-
Dificuldade 2/5 1-3 horas Facilidade para iniciantes 82/100
mariadb-operator/mariadb-operator#1962 ·
Mantenedores costumam responder em até 1 dia
-
bug good first issue
Dificuldade 2/5 1-3 horas Facilidade para iniciantes 82/100
eugenioenko/ttt#919 · 1 comentário ·
Mantenedores costumam responder em até 1 dia
-
Dificuldade 2/5 1-3 horas Facilidade para iniciantes 74/100
QuantumNous/new-api#7839 ·
Mantenedores costumam responder em até 1 dia
-
bug needs-triage service/costoptimizationhub
Dificuldade 2/5 1-3 horas Facilidade para iniciantes 72/100
hashicorp/terraform-provider-aws#50507 · 1 comentário ·
Mantenedores costumam responder em até 1 dia