package loki import ( "fmt" "io" "net/http" "strconv" "strings" "sync" "time" "github.com/VictoriaMetrics/VictoriaMetrics/app/vlstorage" "github.com/VictoriaMetrics/VictoriaMetrics/lib/bytesutil" "github.com/VictoriaMetrics/VictoriaMetrics/lib/httpserver" "github.com/VictoriaMetrics/VictoriaMetrics/lib/logstorage" "github.com/VictoriaMetrics/VictoriaMetrics/lib/writeconcurrencylimiter" "github.com/VictoriaMetrics/metrics" "github.com/golang/snappy" ) var ( bytesBufPool bytesutil.ByteBufferPool pushReqsPool sync.Pool ) func handleProtobuf(r *http.Request, w http.ResponseWriter) bool { startTime := time.Now() lokiRequestsProtobufTotal.Inc() wcr := writeconcurrencylimiter.GetReader(r.Body) data, err := io.ReadAll(wcr) writeconcurrencylimiter.PutReader(wcr) if err != nil { httpserver.Errorf(w, r, "cannot read request body: %s", err) return true } cp, err := getCommonParams(r) if err != nil { httpserver.Errorf(w, r, "cannot parse common params from request: %s", err) return true } lr := logstorage.GetLogRows(cp.StreamFields, cp.IgnoreFields) processLogMessage := cp.GetProcessLogMessageFunc(lr) n, err := parseProtobufRequest(data, processLogMessage) if err != nil { logstorage.PutLogRows(lr) httpserver.Errorf(w, r, "cannot parse Loki request: %s", err) return true } err = vlstorage.AddRows(lr) logstorage.PutLogRows(lr) if err != nil { httpserver.Errorf(w, r, "cannot insert rows: %s", err) return true } rowsIngestedProtobufTotal.Add(n) // update lokiRequestProtobufDuration only for successfully parsed requests // There is no need in updating lokiRequestProtobufDuration for request errors, // since their timings are usually much smaller than the timing for successful request parsing. lokiRequestProtobufDuration.UpdateDuration(startTime) return true } var ( lokiRequestsProtobufTotal = metrics.NewCounter(`vl_http_requests_total{path="/insert/loki/api/v1/push",format="protobuf"}`) rowsIngestedProtobufTotal = metrics.NewCounter(`vl_rows_ingested_total{type="loki",format="protobuf"}`) lokiRequestProtobufDuration = metrics.NewHistogram(`vl_http_request_duration_seconds{path="/insert/loki/api/v1/push",format="protobuf"}`) ) func parseProtobufRequest(data []byte, processLogMessage func(timestamp int64, fields []logstorage.Field) error) (int, error) { bb := bytesBufPool.Get() defer bytesBufPool.Put(bb) buf, err := snappy.Decode(bb.B[:cap(bb.B)], data) if err != nil { return 0, fmt.Errorf("cannot decode snappy-encoded request body: %w", err) } bb.B = buf req := getPushRequest() defer putPushRequest(req) err = req.Unmarshal(bb.B) if err != nil { return 0, fmt.Errorf("cannot parse request body: %s", err) } var commonFields []logstorage.Field rowsIngested := 0 streams := req.Streams currentTimestamp := time.Now().UnixNano() for i := range streams { stream := &streams[i] // st.Labels contains labels for the stream. // Labels are same for all entries in the stream. commonFields, err = parsePromLabels(commonFields[:0], stream.Labels) if err != nil { return rowsIngested, fmt.Errorf("cannot parse stream labels %q: %s", stream.Labels, err) } fields := commonFields entries := stream.Entries for j := range entries { entry := &entries[j] fields = append(fields[:len(commonFields)], logstorage.Field{ Name: "_msg", Value: entry.Line, }) ts := entry.Timestamp.UnixNano() if ts == 0 { ts = currentTimestamp } err = processLogMessage(ts, fields) if err != nil { return rowsIngested, err } } rowsIngested += len(stream.Entries) } return rowsIngested, nil } // parsePromLabels parses log fields in Prometheus text exposition format from s, appends them to dst and returns the result. // // See test data of promtail for examples: https://github.com/grafana/loki/blob/a24ef7b206e0ca63ee74ca6ecb0a09b745cd2258/pkg/push/types_test.go func parsePromLabels(dst []logstorage.Field, s string) ([]logstorage.Field, error) { // Make sure s is wrapped into `{...}` s = strings.TrimSpace(s) if len(s) < 2 { return nil, fmt.Errorf("too short string to parse: %q", s) } if s[0] != '{' { return nil, fmt.Errorf("missing `{` at the beginning of %q", s) } if s[len(s)-1] != '}' { return nil, fmt.Errorf("missing `}` at the end of %q", s) } s = s[1 : len(s)-1] for len(s) > 0 { // Parse label name n := strings.IndexByte(s, '=') if n < 0 { return nil, fmt.Errorf("cannot find `=` char for label value at %s", s) } name := s[:n] s = s[n+1:] // Parse label value qs, err := strconv.QuotedPrefix(s) if err != nil { return nil, fmt.Errorf("cannot parse value for label %q at %s: %w", name, s, err) } s = s[len(qs):] value, err := strconv.Unquote(qs) if err != nil { return nil, fmt.Errorf("cannot unquote value %q for label %q: %w", qs, name, err) } // Append the found field to dst. dst = append(dst, logstorage.Field{ Name: name, Value: value, }) // Check whether there are other labels remaining if len(s) == 0 { break } if !strings.HasPrefix(s, ",") { return nil, fmt.Errorf("missing `,` char at %s", s) } s = s[1:] s = strings.TrimPrefix(s, " ") } return dst, nil } func getPushRequest() *PushRequest { v := pushReqsPool.Get() if v == nil { return &PushRequest{} } return v.(*PushRequest) } func putPushRequest(req *PushRequest) { req.Reset() pushReqsPool.Put(req) }