package handler import ( "bytes" "context" "fmt" "io" "net/http" "net/http/httputil" "time" "zero/core/iox" "zero/core/logx" "zero/core/timex" "zero/core/utils" "zero/rest/internal" ) const slowThreshold = time.Millisecond * 500 type LoggedResponseWriter struct { w http.ResponseWriter r *http.Request code int } func (w *LoggedResponseWriter) Header() http.Header { return w.w.Header() } func (w *LoggedResponseWriter) Write(bytes []byte) (int, error) { return w.w.Write(bytes) } func (w *LoggedResponseWriter) WriteHeader(code int) { w.w.WriteHeader(code) w.code = code } func LogHandler(next http.Handler) http.Handler { return http.HandlerFunc(func(w http.ResponseWriter, r *http.Request) { timer := utils.NewElapsedTimer() logs := new(internal.LogCollector) lrw := LoggedResponseWriter{ w: w, r: r, code: http.StatusOK, } var dup io.ReadCloser r.Body, dup = iox.DupReadCloser(r.Body) next.ServeHTTP(&lrw, r.WithContext(context.WithValue(r.Context(), internal.LogContext, logs))) r.Body = dup logBrief(r, lrw.code, timer, logs) }) } type DetailLoggedResponseWriter struct { writer *LoggedResponseWriter buf *bytes.Buffer } func newDetailLoggedResponseWriter(writer *LoggedResponseWriter, buf *bytes.Buffer) *DetailLoggedResponseWriter { return &DetailLoggedResponseWriter{ writer: writer, buf: buf, } } func (w *DetailLoggedResponseWriter) Header() http.Header { return w.writer.Header() } func (w *DetailLoggedResponseWriter) Write(bs []byte) (int, error) { w.buf.Write(bs) return w.writer.Write(bs) } func (w *DetailLoggedResponseWriter) WriteHeader(code int) { w.writer.WriteHeader(code) } func DetailedLogHandler(next http.Handler) http.Handler { return http.HandlerFunc(func(w http.ResponseWriter, r *http.Request) { timer := utils.NewElapsedTimer() var buf bytes.Buffer lrw := newDetailLoggedResponseWriter(&LoggedResponseWriter{ w: w, r: r, code: http.StatusOK, }, &buf) var dup io.ReadCloser r.Body, dup = iox.DupReadCloser(r.Body) logs := new(internal.LogCollector) next.ServeHTTP(lrw, r.WithContext(context.WithValue(r.Context(), internal.LogContext, logs))) r.Body = dup logDetails(r, lrw, timer, logs) }) } func dumpRequest(r *http.Request) string { reqContent, err := httputil.DumpRequest(r, true) if err != nil { return err.Error() } else { return string(reqContent) } } func logBrief(r *http.Request, code int, timer *utils.ElapsedTimer, logs *internal.LogCollector) { var buf bytes.Buffer duration := timer.Duration() buf.WriteString(fmt.Sprintf("%d - %s - %s - %s - %s", code, r.RequestURI, internal.GetRemoteAddr(r), r.UserAgent(), timex.ReprOfDuration(duration))) if duration > slowThreshold { logx.Slowf("[HTTP] %d - %s - %s - %s - slowcall(%s)", code, r.RequestURI, internal.GetRemoteAddr(r), r.UserAgent(), timex.ReprOfDuration(duration)) } ok := isOkResponse(code) if !ok { buf.WriteString(fmt.Sprintf("\n%s", dumpRequest(r))) } body := logs.Flush() if len(body) > 0 { buf.WriteString(fmt.Sprintf("\n%s", body)) } if ok { logx.Info(buf.String()) } else { logx.Error(buf.String()) } } func logDetails(r *http.Request, response *DetailLoggedResponseWriter, timer *utils.ElapsedTimer, logs *internal.LogCollector) { var buf bytes.Buffer duration := timer.Duration() buf.WriteString(fmt.Sprintf("%d - %s - %s\n=> %s\n", response.writer.code, r.RemoteAddr, timex.ReprOfDuration(duration), dumpRequest(r))) if duration > slowThreshold { logx.Slowf("[HTTP] %d - %s - slowcall(%s)\n=> %s\n", response.writer.code, r.RemoteAddr, timex.ReprOfDuration(duration), dumpRequest(r)) } body := logs.Flush() if len(body) > 0 { buf.WriteString(fmt.Sprintf("%s\n", body)) } respBuf := response.buf.Bytes() if len(respBuf) > 0 { buf.WriteString(fmt.Sprintf("<= %s", respBuf)) } logx.Info(buf.String()) } func isOkResponse(code int) bool { // not server error return code < http.StatusInternalServerError }