2019-04-12 14:36:17 -04:00
|
|
|
/*
|
|
|
|
*
|
|
|
|
* Copyright 2018 gRPC authors.
|
|
|
|
*
|
|
|
|
* Licensed under the Apache License, Version 2.0 (the "License");
|
|
|
|
* you may not use this file except in compliance with the License.
|
|
|
|
* You may obtain a copy of the License at
|
|
|
|
*
|
|
|
|
* http://www.apache.org/licenses/LICENSE-2.0
|
|
|
|
*
|
|
|
|
* Unless required by applicable law or agreed to in writing, software
|
|
|
|
* distributed under the License is distributed on an "AS IS" BASIS,
|
|
|
|
* WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
|
|
|
|
* See the License for the specific language governing permissions and
|
|
|
|
* limitations under the License.
|
|
|
|
*
|
|
|
|
*/
|
|
|
|
|
|
|
|
package binarylog
|
|
|
|
|
|
|
|
import (
|
|
|
|
"bufio"
|
|
|
|
"encoding/binary"
|
|
|
|
"io"
|
|
|
|
"sync"
|
|
|
|
"time"
|
|
|
|
|
|
|
|
"github.com/golang/protobuf/proto"
|
2023-07-19 18:26:26 -04:00
|
|
|
binlogpb "google.golang.org/grpc/binarylog/grpc_binarylog_v1"
|
2019-04-12 14:36:17 -04:00
|
|
|
)
|
|
|
|
|
|
|
|
var (
|
2021-08-12 11:11:44 -04:00
|
|
|
// DefaultSink is the sink where the logs will be written to. It's exported
|
|
|
|
// for the binarylog package to update.
|
|
|
|
DefaultSink Sink = &noopSink{} // TODO(blog): change this default (file in /tmp).
|
2019-04-12 14:36:17 -04:00
|
|
|
)
|
|
|
|
|
|
|
|
// Sink writes log entry into the binary log sink.
|
2021-08-12 11:11:44 -04:00
|
|
|
//
|
|
|
|
// sink is a copy of the exported binarylog.Sink, to avoid circular dependency.
|
2019-04-12 14:36:17 -04:00
|
|
|
type Sink interface {
|
|
|
|
// Write will be called to write the log entry into the sink.
|
|
|
|
//
|
|
|
|
// It should be thread-safe so it can be called in parallel.
|
2023-07-19 18:26:26 -04:00
|
|
|
Write(*binlogpb.GrpcLogEntry) error
|
2019-04-12 14:36:17 -04:00
|
|
|
// Close will be called when the Sink is replaced by a new Sink.
|
|
|
|
Close() error
|
|
|
|
}
|
|
|
|
|
|
|
|
type noopSink struct{}
|
|
|
|
|
2023-07-19 18:26:26 -04:00
|
|
|
func (ns *noopSink) Write(*binlogpb.GrpcLogEntry) error { return nil }
|
|
|
|
func (ns *noopSink) Close() error { return nil }
|
2019-04-12 14:36:17 -04:00
|
|
|
|
|
|
|
// newWriterSink creates a binary log sink with the given writer.
|
|
|
|
//
|
2020-04-16 10:34:35 -04:00
|
|
|
// Write() marshals the proto message and writes it to the given writer. Each
|
2019-04-12 14:36:17 -04:00
|
|
|
// message is prefixed with a 4 byte big endian unsigned integer as the length.
|
|
|
|
//
|
|
|
|
// No buffer is done, Close() doesn't try to close the writer.
|
2021-08-12 11:11:44 -04:00
|
|
|
func newWriterSink(w io.Writer) Sink {
|
2019-04-12 14:36:17 -04:00
|
|
|
return &writerSink{out: w}
|
|
|
|
}
|
|
|
|
|
|
|
|
type writerSink struct {
|
|
|
|
out io.Writer
|
|
|
|
}
|
|
|
|
|
2023-07-19 18:26:26 -04:00
|
|
|
func (ws *writerSink) Write(e *binlogpb.GrpcLogEntry) error {
|
2019-04-12 14:36:17 -04:00
|
|
|
b, err := proto.Marshal(e)
|
|
|
|
if err != nil {
|
2022-03-24 08:21:41 -04:00
|
|
|
grpclogLogger.Errorf("binary logging: failed to marshal proto message: %v", err)
|
|
|
|
return err
|
2019-04-12 14:36:17 -04:00
|
|
|
}
|
|
|
|
hdr := make([]byte, 4)
|
|
|
|
binary.BigEndian.PutUint32(hdr, uint32(len(b)))
|
|
|
|
if _, err := ws.out.Write(hdr); err != nil {
|
|
|
|
return err
|
|
|
|
}
|
|
|
|
if _, err := ws.out.Write(b); err != nil {
|
|
|
|
return err
|
|
|
|
}
|
|
|
|
return nil
|
|
|
|
}
|
|
|
|
|
|
|
|
func (ws *writerSink) Close() error { return nil }
|
|
|
|
|
2021-08-12 11:11:44 -04:00
|
|
|
type bufferedSink struct {
|
2022-03-24 08:21:41 -04:00
|
|
|
mu sync.Mutex
|
|
|
|
closer io.Closer
|
|
|
|
out Sink // out is built on buf.
|
|
|
|
buf *bufio.Writer // buf is kept for flush.
|
|
|
|
flusherStarted bool
|
|
|
|
|
|
|
|
writeTicker *time.Ticker
|
|
|
|
done chan struct{}
|
2019-04-12 14:36:17 -04:00
|
|
|
}
|
|
|
|
|
2023-07-19 18:26:26 -04:00
|
|
|
func (fs *bufferedSink) Write(e *binlogpb.GrpcLogEntry) error {
|
2019-04-12 14:36:17 -04:00
|
|
|
fs.mu.Lock()
|
2022-03-24 08:21:41 -04:00
|
|
|
defer fs.mu.Unlock()
|
|
|
|
if !fs.flusherStarted {
|
|
|
|
// Start the write loop when Write is called.
|
|
|
|
fs.startFlushGoroutine()
|
|
|
|
fs.flusherStarted = true
|
|
|
|
}
|
2019-04-12 14:36:17 -04:00
|
|
|
if err := fs.out.Write(e); err != nil {
|
|
|
|
return err
|
|
|
|
}
|
|
|
|
return nil
|
|
|
|
}
|
|
|
|
|
|
|
|
const (
|
|
|
|
bufFlushDuration = 60 * time.Second
|
|
|
|
)
|
|
|
|
|
2021-08-12 11:11:44 -04:00
|
|
|
func (fs *bufferedSink) startFlushGoroutine() {
|
2019-04-12 14:36:17 -04:00
|
|
|
fs.writeTicker = time.NewTicker(bufFlushDuration)
|
|
|
|
go func() {
|
2022-03-24 08:21:41 -04:00
|
|
|
for {
|
|
|
|
select {
|
|
|
|
case <-fs.done:
|
|
|
|
return
|
|
|
|
case <-fs.writeTicker.C:
|
|
|
|
}
|
2019-04-12 14:36:17 -04:00
|
|
|
fs.mu.Lock()
|
2021-08-12 11:11:44 -04:00
|
|
|
if err := fs.buf.Flush(); err != nil {
|
|
|
|
grpclogLogger.Warningf("failed to flush to Sink: %v", err)
|
|
|
|
}
|
2019-04-12 14:36:17 -04:00
|
|
|
fs.mu.Unlock()
|
|
|
|
}
|
|
|
|
}()
|
|
|
|
}
|
|
|
|
|
2021-08-12 11:11:44 -04:00
|
|
|
func (fs *bufferedSink) Close() error {
|
2022-03-24 08:21:41 -04:00
|
|
|
fs.mu.Lock()
|
|
|
|
defer fs.mu.Unlock()
|
2019-04-12 14:36:17 -04:00
|
|
|
if fs.writeTicker != nil {
|
|
|
|
fs.writeTicker.Stop()
|
|
|
|
}
|
2022-03-24 08:21:41 -04:00
|
|
|
close(fs.done)
|
2021-08-12 11:11:44 -04:00
|
|
|
if err := fs.buf.Flush(); err != nil {
|
|
|
|
grpclogLogger.Warningf("failed to flush to Sink: %v", err)
|
|
|
|
}
|
|
|
|
if err := fs.closer.Close(); err != nil {
|
|
|
|
grpclogLogger.Warningf("failed to close the underlying WriterCloser: %v", err)
|
|
|
|
}
|
|
|
|
if err := fs.out.Close(); err != nil {
|
|
|
|
grpclogLogger.Warningf("failed to close the Sink: %v", err)
|
|
|
|
}
|
2019-04-12 14:36:17 -04:00
|
|
|
return nil
|
|
|
|
}
|
|
|
|
|
2021-08-12 11:11:44 -04:00
|
|
|
// NewBufferedSink creates a binary log sink with the given WriteCloser.
|
|
|
|
//
|
|
|
|
// Write() marshals the proto message and writes it to the given writer. Each
|
|
|
|
// message is prefixed with a 4 byte big endian unsigned integer as the length.
|
|
|
|
//
|
|
|
|
// Content is kept in a buffer, and is flushed every 60 seconds.
|
|
|
|
//
|
|
|
|
// Close closes the WriteCloser.
|
|
|
|
func NewBufferedSink(o io.WriteCloser) Sink {
|
2019-04-12 14:36:17 -04:00
|
|
|
bufW := bufio.NewWriter(o)
|
2021-08-12 11:11:44 -04:00
|
|
|
return &bufferedSink{
|
2019-04-12 14:36:17 -04:00
|
|
|
closer: o,
|
|
|
|
out: newWriterSink(bufW),
|
|
|
|
buf: bufW,
|
2022-03-24 08:21:41 -04:00
|
|
|
done: make(chan struct{}),
|
2019-04-12 14:36:17 -04:00
|
|
|
}
|
|
|
|
}
|