1 // Copyright (C) The Arvados Authors. All rights reserved.
3 // SPDX-License-Identifier: AGPL-3.0
12 "git.curoverse.com/arvados.git/sdk/go/stats"
13 "github.com/Sirupsen/logrus"
16 type contextKey struct {
20 var requestTimeContextKey = contextKey{"requestTime"}
22 // LogRequests wraps an http.Handler, logging each request and
23 // response via logger.
24 func LogRequests(logger logrus.FieldLogger, h http.Handler) http.Handler {
26 logger = logrus.StandardLogger()
28 return http.HandlerFunc(func(wrapped http.ResponseWriter, req *http.Request) {
29 w := &responseTimer{ResponseWriter: WrapResponseWriter(wrapped)}
30 req = req.WithContext(context.WithValue(req.Context(), &requestTimeContextKey, time.Now()))
31 lgr := logger.WithFields(logrus.Fields{
32 "RequestID": req.Header.Get("X-Request-Id"),
33 "remoteAddr": req.RemoteAddr,
34 "reqForwardedFor": req.Header.Get("X-Forwarded-For"),
35 "reqMethod": req.Method,
37 "reqPath": req.URL.Path[1:],
38 "reqQuery": req.URL.RawQuery,
39 "reqBytes": req.ContentLength,
41 logRequest(w, req, lgr)
42 defer logResponse(w, req, lgr)
47 func logRequest(w *responseTimer, req *http.Request, lgr *logrus.Entry) {
51 func logResponse(w *responseTimer, req *http.Request, lgr *logrus.Entry) {
52 if tStart, ok := req.Context().Value(&requestTimeContextKey).(time.Time); ok {
54 lgr = lgr.WithFields(logrus.Fields{
55 "timeTotal": stats.Duration(tDone.Sub(tStart)),
56 "timeToStatus": stats.Duration(w.writeTime.Sub(tStart)),
57 "timeWriteBody": stats.Duration(tDone.Sub(w.writeTime)),
60 respCode := w.WroteStatus()
62 respCode = http.StatusOK
64 lgr.WithFields(logrus.Fields{
65 "respStatusCode": respCode,
66 "respStatus": http.StatusText(respCode),
67 "respBytes": w.WroteBodyBytes(),
71 type responseTimer struct {
77 func (rt *responseTimer) CloseNotify() <-chan bool {
78 if cn, ok := rt.ResponseWriter.(http.CloseNotifier); ok {
79 return cn.CloseNotify()
84 func (rt *responseTimer) WriteHeader(code int) {
87 rt.writeTime = time.Now()
89 rt.ResponseWriter.WriteHeader(code)
92 func (rt *responseTimer) Write(p []byte) (int, error) {
95 rt.writeTime = time.Now()
97 return rt.ResponseWriter.Write(p)