github.com/keybase/client/go@v0.0.0-20241007131713-f10651d043c8/logger/file.go (about)

     1  // Copyright 2017 Keybase, Inc. All rights reserved. Use of
     2  // this source code is governed by the included BSD license.
     3  
     4  package logger
     5  
     6  import (
     7  	"errors"
     8  	"fmt"
     9  	"os"
    10  	"path/filepath"
    11  	"regexp"
    12  	"sort"
    13  	"sync"
    14  	"time"
    15  
    16  	logging "github.com/keybase/go-logging"
    17  )
    18  
    19  // LogFileConfig is the config structure for new style log files with rotation.
    20  type LogFileConfig struct {
    21  	// Path is the path of the log file to use
    22  	Path string
    23  	// MaxSize is the size of log file (in bytes) before rotation, 0 for infinite.
    24  	MaxSize int64
    25  	// MaxAge is the duration before log rotation, zero value for infinite.
    26  	MaxAge time.Duration
    27  	// MaxKeepFiles is maximum number of log files for this service, older
    28  	// files are deleted.
    29  	MaxKeepFiles int
    30  	// RedirectStdErr indicates if the current stderr redirected to the given
    31  	// Path.
    32  	SkipRedirectStdErr bool
    33  }
    34  
    35  // SetLogFileConfig sets the log file config to be used globally.
    36  func SetLogFileConfig(lfc *LogFileConfig, blc *BufferedLoggerConfig) error {
    37  	globalLock.Lock()
    38  	defer globalLock.Unlock()
    39  
    40  	first := true
    41  	var w = currentLogFileWriter
    42  	if w != nil {
    43  		first = false
    44  		w.lock.Lock()
    45  		defer w.lock.Unlock()
    46  		w.Close()
    47  		w.config = *lfc
    48  	} else {
    49  		w = NewLogFileWriter(*lfc)
    50  
    51  		// Clean up the default logger, if it is in use
    52  		select {
    53  		case stdErrLoggingShutdown <- struct{}{}:
    54  		default:
    55  		}
    56  	}
    57  
    58  	if err := w.Open(time.Now()); err != nil {
    59  		return err
    60  	}
    61  
    62  	if first {
    63  		buf, shutdown, _ := NewAutoFlushingBufferedWriter(w, blc)
    64  		w.stopFlushing = shutdown
    65  		fileBackend := logging.NewLogBackend(buf, "", 0)
    66  		logging.SetBackend(fileBackend)
    67  
    68  		stderrIsTerminal = false
    69  		currentLogFileWriter = w
    70  	}
    71  	return nil
    72  }
    73  
    74  type LogFileWriter struct {
    75  	lock         sync.Mutex
    76  	config       LogFileConfig
    77  	file         *os.File
    78  	currentSize  int64
    79  	currentStart time.Time
    80  	stopFlushing chan<- struct{}
    81  }
    82  
    83  func NewLogFileWriter(config LogFileConfig) *LogFileWriter {
    84  	return &LogFileWriter{
    85  		config: config,
    86  	}
    87  }
    88  
    89  func (lfw *LogFileWriter) Open(at time.Time) error {
    90  	var err error
    91  	_, lfw.file, err = OpenLogFile(lfw.config.Path)
    92  	if err != nil {
    93  		return err
    94  	}
    95  	lfw.currentStart = at
    96  	lfw.currentSize = 0
    97  	fi, err := lfw.file.Stat()
    98  	if err != nil {
    99  		return err
   100  	}
   101  	lfw.currentSize = fi.Size()
   102  	if !lfw.config.SkipRedirectStdErr {
   103  		_ = tryRedirectStderrTo(lfw.file)
   104  	}
   105  	return nil
   106  }
   107  
   108  func (lfw *LogFileWriter) Close() error {
   109  	if lfw == nil {
   110  		return nil
   111  	}
   112  	lfw.lock.Lock()
   113  	defer lfw.lock.Unlock()
   114  	if lfw.file == nil {
   115  		return nil
   116  	}
   117  	if lfw.stopFlushing != nil {
   118  		lfw.stopFlushing <- struct{}{}
   119  	}
   120  
   121  	return lfw.file.Close()
   122  }
   123  
   124  const zeroDuration time.Duration = 0
   125  const oldLogFileTimeRangeTimeLayout = "20060102T150405Z0700"
   126  const oldLogFileTimeRangeTimeLayoutLegacy = "20060102T150405"
   127  
   128  func (lfw *LogFileWriter) Write(bs []byte) (int, error) {
   129  	lfw.lock.Lock()
   130  	defer lfw.lock.Unlock()
   131  	n, err := lfw.file.Write(bs)
   132  	if err != nil {
   133  		return n, err
   134  	}
   135  	needRotation := false
   136  	if lfw.config.MaxSize > 0 {
   137  		lfw.currentSize += int64(n)
   138  		needRotation = needRotation || lfw.currentSize > lfw.config.MaxSize
   139  	}
   140  	if lfw.config.MaxAge != zeroDuration {
   141  		elapsed := time.Since(lfw.currentStart)
   142  		needRotation = needRotation || elapsed > lfw.config.MaxAge
   143  	}
   144  	if !needRotation {
   145  		return n, nil
   146  	}
   147  	// Close first because some systems don't like to rename otherwise.
   148  	lfw.file.Close()
   149  	lfw.file = nil
   150  	now := time.Now()
   151  	start := lfw.currentStart.Format(oldLogFileTimeRangeTimeLayout)
   152  	end := now.Format(oldLogFileTimeRangeTimeLayout)
   153  	tgt := fmt.Sprintf("%s-%s-%s", lfw.config.Path, start, end)
   154  	// Handle the error further down
   155  	err = os.Rename(lfw.config.Path, tgt)
   156  	if err != nil {
   157  		return n, err
   158  	}
   159  	// Spawn old log deletion worker if we have a max-amount of log-files.
   160  	if lfw.config.MaxKeepFiles > 0 {
   161  		go deleteOldLogFilesIfNeeded(lfw.config)
   162  	}
   163  	err = lfw.Open(now)
   164  	return n, err
   165  }
   166  
   167  func deleteOldLogFilesIfNeeded(config LogFileConfig) {
   168  	err := deleteOldLogFilesIfNeededWorker(config)
   169  	if err != nil {
   170  		log := New("logger")
   171  		log.Warning("Deletion of old log files failed: %v", err)
   172  	}
   173  }
   174  
   175  func deleteOldLogFilesIfNeededWorker(config LogFileConfig) error {
   176  	// Returns list of old log files (not the current one) sorted.
   177  	// The oldest one is first in the list.
   178  	entries, err := scanOldLogFiles(config.Path)
   179  	if err != nil {
   180  		return err
   181  	}
   182  	// entries has only the old renamed log files, not the current
   183  	// log file. E.g. if MaxKeepFiles is 2 then we keep the current
   184  	// file and one archived log file. If there are 3 archived files
   185  	// then removeN = 1 + 3 - 2 = 2.
   186  	removeN := 1 + len(entries) - config.MaxKeepFiles
   187  	if config.MaxKeepFiles <= 0 || removeN <= 0 {
   188  		return nil
   189  	}
   190  	// Try to remove all old log files that we want to remove, and
   191  	// don't stop on the first error.
   192  	for i := 0; i < removeN; i++ {
   193  		err2 := os.Remove(entries[i])
   194  		if err == nil {
   195  			err = err2
   196  		}
   197  	}
   198  	return err
   199  }
   200  
   201  type logFilename struct {
   202  	fName string
   203  	start time.Time
   204  }
   205  
   206  type logFilenamesByTime []logFilename
   207  
   208  func (a logFilenamesByTime) Len() int      { return len(a) }
   209  func (a logFilenamesByTime) Swap(i, j int) { a[i], a[j] = a[j], a[i] }
   210  func (a logFilenamesByTime) Less(i, j int) bool {
   211  	return a[i].start.Before(a[j].start)
   212  }
   213  
   214  // getLogFilenamesOrderByTime filters fNames to return only old log files
   215  // starting with baseName, followed by a timestamp-range suffix. It also sorts
   216  // them by start time, in increasing order.
   217  //
   218  // Both baseName and fNames are base names not including dir names.
   219  //
   220  // This function supports both old (no timezone) and current (with timezone)
   221  // format of log file names. TODO: simplify this when we don't care about old
   222  // format any more.
   223  func getLogFilenamesOrderByTime(
   224  	baseName string, fNames []string) (names []string, err error) {
   225  	re, err := regexp.Compile(`^` + regexp.QuoteMeta(baseName) +
   226  		`-(\d{8}T\d{6}(?:(?:[Z\+-]\d{4})|(?:Z))?)-\d{8}T\d{6}(?:(?:[Z\+-]\d{4})|(?:Z))?$`)
   227  	if err != nil {
   228  		return nil, err
   229  	}
   230  
   231  	var logFilenames []logFilename
   232  	for _, fName := range fNames {
   233  		match := re.FindStringSubmatch(fName)
   234  		if len(match) != 2 {
   235  			continue
   236  		}
   237  		t, err1 := time.ParseInLocation(oldLogFileTimeRangeTimeLayout, match[1], time.Local)
   238  		if err1 != nil {
   239  			var err2 error
   240  			t, err2 = time.ParseInLocation(oldLogFileTimeRangeTimeLayoutLegacy, match[1], time.Local)
   241  			if err2 != nil {
   242  				return nil, errors.New(err1.Error() + " | " + err2.Error())
   243  			}
   244  		}
   245  		logFilenames = append(logFilenames, logFilename{fName: fName, start: t})
   246  	}
   247  
   248  	sort.Sort(logFilenamesByTime(logFilenames))
   249  
   250  	names = make([]string, 0, len(logFilenames))
   251  	for _, f := range logFilenames {
   252  		names = append(names, f.fName)
   253  	}
   254  
   255  	return names, nil
   256  }
   257  
   258  // scanOldLogFiles finds old archived log files corresponding to the log file path.
   259  // Returns the list of such log files sorted with the eldest one first.
   260  func scanOldLogFiles(path string) ([]string, error) {
   261  	dname, fname := filepath.Split(path)
   262  	if dname == "" {
   263  		dname = "."
   264  	}
   265  	dir, err := os.Open(dname)
   266  	if err != nil {
   267  		return nil, err
   268  	}
   269  	defer dir.Close()
   270  	ns, err := dir.Readdirnames(-1)
   271  	if err != nil {
   272  		return nil, err
   273  	}
   274  	names, err := getLogFilenamesOrderByTime(fname, ns)
   275  	if err != nil {
   276  		return nil, err
   277  	}
   278  	var res []string
   279  	for _, name := range names {
   280  		res = append(res, filepath.Join(dname, name))
   281  	}
   282  	return res, nil
   283  }