mirror of
https://github.com/syncthing/syncthing.git
synced 2024-11-17 02:48:57 -07:00
76af9ba53d
This implements a new debug/trace infrastructure based on a slightly hacked up logger. Instead of the traditional "if debug { ... }" I've rewritten the logger to have no-op Debugln and Debugf, unless debugging has been enabled for a given "facility". The "facility" is just a string, typically a package name. This will be slightly slower than before; but not that much as it's mostly a function call that returns immediately. For the cases where it matters (the Debugln takes a hex.Dump() of something for example, and it's not in a very occasional "if err != nil" branch) there is an l.ShouldDebug(facility) that is fast enough to be used like the old "if debug". The point of all this is that we can now toggle debugging for the various packages on and off at runtime. There's a new method /rest/system/debug that can be POSTed a set of facilities to enable and disable debug for, or GET from to get a list of facilities with descriptions and their current debug status. Similarly a /rest/system/log?since=... can grab the latest log entries, up to 250 of them (hardcoded constant in main.go) plus the initial few. Not implemented in this commit (but planned) is a simple debug GUI available on /debug that shows the current log in an easily pasteable format and has checkboxes to enable the various debug facilities. The debug instructions to a user then becomes "visit this URL, check these boxes, reproduce your problem, copy and paste the log". The actual log viewer on the hypothetical /debug URL can poll regularly for new log entries and this bypass the 250 line limit. The existing STTRACE=foo variable is still obeyed and just sets the start state of the system.
280 lines
6.8 KiB
Go
280 lines
6.8 KiB
Go
// Copyright (C) 2014 The Syncthing Authors.
|
|
//
|
|
// This Source Code Form is subject to the terms of the Mozilla Public
|
|
// License, v. 2.0. If a copy of the MPL was not distributed with this file,
|
|
// You can obtain one at http://mozilla.org/MPL/2.0/.
|
|
|
|
package versioner
|
|
|
|
import (
|
|
"os"
|
|
"path/filepath"
|
|
"strconv"
|
|
"time"
|
|
|
|
"github.com/syncthing/syncthing/lib/osutil"
|
|
"github.com/syncthing/syncthing/lib/sync"
|
|
)
|
|
|
|
func init() {
|
|
// Register the constructor for this type of versioner with the name "staggered"
|
|
Factories["staggered"] = NewStaggered
|
|
}
|
|
|
|
type Interval struct {
|
|
step int64
|
|
end int64
|
|
}
|
|
|
|
type Staggered struct {
|
|
versionsPath string
|
|
cleanInterval int64
|
|
folderPath string
|
|
interval [4]Interval
|
|
mutex sync.Mutex
|
|
}
|
|
|
|
func NewStaggered(folderID, folderPath string, params map[string]string) Versioner {
|
|
maxAge, err := strconv.ParseInt(params["maxAge"], 10, 0)
|
|
if err != nil {
|
|
maxAge = 31536000 // Default: ~1 year
|
|
}
|
|
cleanInterval, err := strconv.ParseInt(params["cleanInterval"], 10, 0)
|
|
if err != nil {
|
|
cleanInterval = 3600 // Default: clean once per hour
|
|
}
|
|
|
|
// Use custom path if set, otherwise .stversions in folderPath
|
|
var versionsDir string
|
|
if params["versionsPath"] == "" {
|
|
l.Debugln("using default dir .stversions")
|
|
versionsDir = filepath.Join(folderPath, ".stversions")
|
|
} else {
|
|
l.Debugln("using dir", params["versionsPath"])
|
|
versionsDir = params["versionsPath"]
|
|
}
|
|
|
|
s := Staggered{
|
|
versionsPath: versionsDir,
|
|
cleanInterval: cleanInterval,
|
|
folderPath: folderPath,
|
|
interval: [4]Interval{
|
|
{30, 3600}, // first hour -> 30 sec between versions
|
|
{3600, 86400}, // next day -> 1 h between versions
|
|
{86400, 592000}, // next 30 days -> 1 day between versions
|
|
{604800, maxAge}, // next year -> 1 week between versions
|
|
},
|
|
mutex: sync.NewMutex(),
|
|
}
|
|
|
|
l.Debugf("instantiated %#v", s)
|
|
|
|
go func() {
|
|
s.clean()
|
|
for _ = range time.Tick(time.Duration(cleanInterval) * time.Second) {
|
|
s.clean()
|
|
}
|
|
}()
|
|
|
|
return s
|
|
}
|
|
|
|
func (v Staggered) clean() {
|
|
l.Debugln("Versioner clean: Waiting for lock on", v.versionsPath)
|
|
v.mutex.Lock()
|
|
defer v.mutex.Unlock()
|
|
l.Debugln("Versioner clean: Cleaning", v.versionsPath)
|
|
|
|
if _, err := os.Stat(v.versionsPath); os.IsNotExist(err) {
|
|
// There is no need to clean a nonexistent dir.
|
|
return
|
|
}
|
|
|
|
versionsPerFile := make(map[string][]string)
|
|
filesPerDir := make(map[string]int)
|
|
|
|
err := filepath.Walk(v.versionsPath, func(path string, f os.FileInfo, err error) error {
|
|
if err != nil {
|
|
return err
|
|
}
|
|
|
|
if f.Mode().IsDir() && f.Mode()&os.ModeSymlink == 0 {
|
|
filesPerDir[path] = 0
|
|
if path != v.versionsPath {
|
|
dir := filepath.Dir(path)
|
|
filesPerDir[dir]++
|
|
}
|
|
} else {
|
|
// Regular file, or possibly a symlink.
|
|
ext := filepath.Ext(path)
|
|
versionTag := filenameTag(path)
|
|
dir := filepath.Dir(path)
|
|
withoutExt := path[:len(path)-len(ext)-len(versionTag)-1]
|
|
name := withoutExt + ext
|
|
|
|
filesPerDir[dir]++
|
|
versionsPerFile[name] = append(versionsPerFile[name], path)
|
|
}
|
|
|
|
return nil
|
|
})
|
|
if err != nil {
|
|
l.Warnln("Versioner: error scanning versions dir", err)
|
|
return
|
|
}
|
|
|
|
for _, versionList := range versionsPerFile {
|
|
// List from filepath.Walk is sorted
|
|
v.expire(versionList)
|
|
}
|
|
|
|
for path, numFiles := range filesPerDir {
|
|
if numFiles > 0 {
|
|
continue
|
|
}
|
|
|
|
if path == v.versionsPath {
|
|
l.Debugln("Cleaner: versions dir is empty, don't delete", path)
|
|
continue
|
|
}
|
|
|
|
l.Debugln("Cleaner: deleting empty directory", path)
|
|
err = os.Remove(path)
|
|
if err != nil {
|
|
l.Warnln("Versioner: can't remove directory", path, err)
|
|
}
|
|
}
|
|
|
|
l.Debugln("Cleaner: Finished cleaning", v.versionsPath)
|
|
}
|
|
|
|
func (v Staggered) expire(versions []string) {
|
|
l.Debugln("Versioner: Expiring versions", versions)
|
|
var prevAge int64
|
|
firstFile := true
|
|
for _, file := range versions {
|
|
fi, err := osutil.Lstat(file)
|
|
if err != nil {
|
|
l.Warnln("versioner:", err)
|
|
continue
|
|
}
|
|
|
|
if fi.IsDir() {
|
|
l.Infof("non-file %q is named like a file version", file)
|
|
continue
|
|
}
|
|
|
|
loc, _ := time.LoadLocation("Local")
|
|
versionTime, err := time.ParseInLocation(TimeFormat, filenameTag(file), loc)
|
|
if err != nil {
|
|
l.Debugf("Versioner: file name %q is invalid: %v", file, err)
|
|
continue
|
|
}
|
|
age := int64(time.Since(versionTime).Seconds())
|
|
|
|
// If the file is older than the max age of the last interval, remove it
|
|
if lastIntv := v.interval[len(v.interval)-1]; lastIntv.end > 0 && age > lastIntv.end {
|
|
l.Debugln("Versioner: File over maximum age -> delete ", file)
|
|
err = os.Remove(file)
|
|
if err != nil {
|
|
l.Warnf("Versioner: can't remove %q: %v", file, err)
|
|
}
|
|
continue
|
|
}
|
|
|
|
// If it's the first (oldest) file in the list we can skip the interval checks
|
|
if firstFile {
|
|
prevAge = age
|
|
firstFile = false
|
|
continue
|
|
}
|
|
|
|
// Find the interval the file fits in
|
|
var usedInterval Interval
|
|
for _, usedInterval = range v.interval {
|
|
if age < usedInterval.end {
|
|
break
|
|
}
|
|
}
|
|
|
|
if prevAge-age < usedInterval.step {
|
|
l.Debugln("too many files in step -> delete", file)
|
|
err = os.Remove(file)
|
|
if err != nil {
|
|
l.Warnf("Versioner: can't remove %q: %v", file, err)
|
|
}
|
|
continue
|
|
}
|
|
|
|
prevAge = age
|
|
}
|
|
}
|
|
|
|
// Archive moves the named file away to a version archive. If this function
|
|
// returns nil, the named file does not exist any more (has been archived).
|
|
func (v Staggered) Archive(filePath string) error {
|
|
l.Debugln("Waiting for lock on ", v.versionsPath)
|
|
v.mutex.Lock()
|
|
defer v.mutex.Unlock()
|
|
|
|
_, err := osutil.Lstat(filePath)
|
|
if os.IsNotExist(err) {
|
|
l.Debugln("not archiving nonexistent file", filePath)
|
|
return nil
|
|
} else if err != nil {
|
|
return err
|
|
}
|
|
|
|
if _, err := os.Stat(v.versionsPath); err != nil {
|
|
if os.IsNotExist(err) {
|
|
l.Debugln("creating versions dir", v.versionsPath)
|
|
osutil.MkdirAll(v.versionsPath, 0755)
|
|
osutil.HideFile(v.versionsPath)
|
|
} else {
|
|
return err
|
|
}
|
|
}
|
|
|
|
l.Debugln("archiving", filePath)
|
|
|
|
file := filepath.Base(filePath)
|
|
inFolderPath, err := filepath.Rel(v.folderPath, filepath.Dir(filePath))
|
|
if err != nil {
|
|
return err
|
|
}
|
|
|
|
dir := filepath.Join(v.versionsPath, inFolderPath)
|
|
err = osutil.MkdirAll(dir, 0755)
|
|
if err != nil && !os.IsExist(err) {
|
|
return err
|
|
}
|
|
|
|
ver := taggedFilename(file, time.Now().Format(TimeFormat))
|
|
dst := filepath.Join(dir, ver)
|
|
l.Debugln("moving to", dst)
|
|
err = osutil.Rename(filePath, dst)
|
|
if err != nil {
|
|
return err
|
|
}
|
|
|
|
// Glob according to the new file~timestamp.ext pattern.
|
|
newVersions, err := osutil.Glob(filepath.Join(dir, taggedFilename(file, TimeGlob)))
|
|
if err != nil {
|
|
l.Warnln("globbing:", err)
|
|
return nil
|
|
}
|
|
|
|
// Also according to the old file.ext~timestamp pattern.
|
|
oldVersions, err := osutil.Glob(filepath.Join(dir, file+"~"+TimeGlob))
|
|
if err != nil {
|
|
l.Warnln("globbing:", err)
|
|
return nil
|
|
}
|
|
|
|
// Use all the found filenames.
|
|
versions := append(oldVersions, newVersions...)
|
|
v.expire(uniqueSortedStrings(versions))
|
|
|
|
return nil
|
|
}
|