1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
103
104
|
// Copyright 2019 The Chromium OS Authors. All rights reserved.
// Use of this source code is governed by a BSD-style license that can be
// found in the LICENSE file.
package main
import (
"fmt"
"os"
"path/filepath"
"strings"
"syscall"
"time"
)
func getRusageLogFilename(env env) string {
value, _ := env.getenv("GETRUSAGE")
return value
}
func lockFileExclusive(fd uintptr) error {
maxTries := 100
for i := 0; i < maxTries; i++ {
const seekSet = 0
err := syscall.FcntlFlock(fd, syscall.F_SETLKW, &syscall.Flock_t{
Type: syscall.F_WRLCK,
Whence: seekSet,
Start: 0,
Len: 0,
})
if err == nil {
return nil
}
if err != syscall.EINTR {
return fmt.Errorf("locking file: %v", err)
}
}
return fmt.Errorf("locking file failed after %d tries", maxTries)
}
func logRusage(env env, logFileName string, compilerCmd *command) (exitCode int, err error) {
rusageBefore := syscall.Rusage{}
if err := syscall.Getrusage(syscall.RUSAGE_CHILDREN, &rusageBefore); err != nil {
return 0, err
}
compilerCmdWithoutRusage := &command{
Path: compilerCmd.Path,
Args: compilerCmd.Args,
EnvUpdates: append(compilerCmd.EnvUpdates, "GETRUSAGE="),
}
startTime := time.Now()
exitCode, err = wrapSubprocessErrorWithSourceLoc(compilerCmdWithoutRusage,
env.run(compilerCmdWithoutRusage, env.stdin(), env.stdout(), env.stderr()))
if err != nil {
return 0, err
}
elapsedRealTime := time.Since(startTime)
rusageAfter := syscall.Rusage{}
if err := syscall.Getrusage(syscall.RUSAGE_CHILDREN, &rusageAfter); err != nil {
return 0, err
}
elapsedSysTime := time.Duration(rusageAfter.Stime.Nano()-rusageBefore.Stime.Nano()) * time.Nanosecond
elapsedUserTime := time.Duration(rusageAfter.Utime.Nano()-rusageBefore.Utime.Nano()) * time.Nanosecond
// Note: We assume that the compiler takes more heap than any other
// subcommands that we might have executed before.
maxMemUsed := rusageAfter.Maxrss
absCompilerPath := getAbsCmdPath(env, compilerCmd)
if err := os.MkdirAll(filepath.Dir(logFileName), 0777); err != nil {
return 0, wrapErrorwithSourceLocf(err, "error creating rusage log directory %s", logFileName)
}
timeUnit := float64(time.Second)
data := fmt.Sprintf("%.5f : %.5f : %.5f : %d : %s : %s\n",
float64(elapsedRealTime)/timeUnit, float64(elapsedUserTime)/timeUnit, float64(elapsedSysTime)/timeUnit,
maxMemUsed, absCompilerPath,
strings.Join(append([]string{filepath.Base(absCompilerPath)}, compilerCmd.Args...), " "))
// Note: using file mode 0666 so that a root-created log is writable by others.
logFile, err := os.OpenFile(logFileName, os.O_CREATE|os.O_APPEND|os.O_WRONLY, 0666)
if err != nil {
return 0, wrapErrorwithSourceLocf(err, "creating rusage logfile %s", logFileName)
}
// O_APPEND's atomicity guarantees are only for writes up to a certain size. If we don't
// lock the file, we might end up with corrupted records.
//
// Note that Close()'ing the file releases all associated locks.
if err := lockFileExclusive(logFile.Fd()); err != nil {
_ = logFile.Close()
return 0, wrapErrorwithSourceLocf(err, "locking rusage logfile %s: %v", logFileName, err)
}
_, err = logFile.WriteString(data)
closeErr := logFile.Close()
if err != nil {
return 0, wrapErrorwithSourceLocf(err, "writing to rusage logfile %s: %v", logFileName, err)
}
if closeErr != nil {
return 0, wrapErrorwithSourceLocf(err, "closing rusage logfile %s: %v", logFileName, closeErr)
}
return exitCode, nil
}
|
