github.com/mh-cbon/go@v0.0.0-20160603070303-9e112a3fe4c0/src/testing/benchmark.go (about) 1 // Copyright 2009 The Go Authors. All rights reserved. 2 // Use of this source code is governed by a BSD-style 3 // license that can be found in the LICENSE file. 4 5 package testing 6 7 import ( 8 "flag" 9 "fmt" 10 "os" 11 "runtime" 12 "sync" 13 "sync/atomic" 14 "time" 15 ) 16 17 var matchBenchmarks = flag.String("test.bench", "", "regular expression per path component to select benchmarks to run") 18 var benchTime = flag.Duration("test.benchtime", 1*time.Second, "approximate run time for each benchmark") 19 var benchmarkMemory = flag.Bool("test.benchmem", false, "print memory allocations for benchmarks") 20 21 // Global lock to ensure only one benchmark runs at a time. 22 var benchmarkLock sync.Mutex 23 24 // Used for every benchmark for measuring memory. 25 var memStats runtime.MemStats 26 27 // An internal type but exported because it is cross-package; part of the implementation 28 // of the "go test" command. 29 type InternalBenchmark struct { 30 Name string 31 F func(b *B) 32 } 33 34 // B is a type passed to Benchmark functions to manage benchmark 35 // timing and to specify the number of iterations to run. 36 // 37 // A benchmark ends when its Benchmark function returns or calls any of the methods 38 // FailNow, Fatal, Fatalf, SkipNow, Skip, or Skipf. Those methods must be called 39 // only from the goroutine running the Benchmark function. 40 // The other reporting methods, such as the variations of Log and Error, 41 // may be called simultaneously from multiple goroutines. 42 // 43 // Like in tests, benchmark logs are accumulated during execution 44 // and dumped to standard error when done. Unlike in tests, benchmark logs 45 // are always printed, so as not to hide output whose existence may be 46 // affecting benchmark results. 47 type B struct { 48 common 49 context *benchContext 50 N int 51 previousN int // number of iterations in the previous run 52 previousDuration time.Duration // total duration of the previous run 53 benchFunc func(b *B) 54 benchTime time.Duration 55 bytes int64 56 missingBytes bool // one of the subbenchmarks does not have bytes set. 57 timerOn bool 58 showAllocResult bool 59 hasSub bool 60 result BenchmarkResult 61 parallelism int // RunParallel creates parallelism*GOMAXPROCS goroutines 62 // The initial states of memStats.Mallocs and memStats.TotalAlloc. 63 startAllocs uint64 64 startBytes uint64 65 // The net total of this test after being run. 66 netAllocs uint64 67 netBytes uint64 68 } 69 70 // StartTimer starts timing a test. This function is called automatically 71 // before a benchmark starts, but it can also used to resume timing after 72 // a call to StopTimer. 73 func (b *B) StartTimer() { 74 if !b.timerOn { 75 runtime.ReadMemStats(&memStats) 76 b.startAllocs = memStats.Mallocs 77 b.startBytes = memStats.TotalAlloc 78 b.start = time.Now() 79 b.timerOn = true 80 } 81 } 82 83 // StopTimer stops timing a test. This can be used to pause the timer 84 // while performing complex initialization that you don't 85 // want to measure. 86 func (b *B) StopTimer() { 87 if b.timerOn { 88 b.duration += time.Now().Sub(b.start) 89 runtime.ReadMemStats(&memStats) 90 b.netAllocs += memStats.Mallocs - b.startAllocs 91 b.netBytes += memStats.TotalAlloc - b.startBytes 92 b.timerOn = false 93 } 94 } 95 96 // ResetTimer zeros the elapsed benchmark time and memory allocation counters. 97 // It does not affect whether the timer is running. 98 func (b *B) ResetTimer() { 99 if b.timerOn { 100 runtime.ReadMemStats(&memStats) 101 b.startAllocs = memStats.Mallocs 102 b.startBytes = memStats.TotalAlloc 103 b.start = time.Now() 104 } 105 b.duration = 0 106 b.netAllocs = 0 107 b.netBytes = 0 108 } 109 110 // SetBytes records the number of bytes processed in a single operation. 111 // If this is called, the benchmark will report ns/op and MB/s. 112 func (b *B) SetBytes(n int64) { b.bytes = n } 113 114 // ReportAllocs enables malloc statistics for this benchmark. 115 // It is equivalent to setting -test.benchmem, but it only affects the 116 // benchmark function that calls ReportAllocs. 117 func (b *B) ReportAllocs() { 118 b.showAllocResult = true 119 } 120 121 func (b *B) nsPerOp() int64 { 122 if b.N <= 0 { 123 return 0 124 } 125 return b.duration.Nanoseconds() / int64(b.N) 126 } 127 128 // runN runs a single benchmark for the specified number of iterations. 129 func (b *B) runN(n int) { 130 benchmarkLock.Lock() 131 defer benchmarkLock.Unlock() 132 // Try to get a comparable environment for each run 133 // by clearing garbage from previous runs. 134 runtime.GC() 135 b.N = n 136 b.parallelism = 1 137 b.ResetTimer() 138 b.StartTimer() 139 b.benchFunc(b) 140 b.StopTimer() 141 b.previousN = n 142 b.previousDuration = b.duration 143 } 144 145 func min(x, y int) int { 146 if x > y { 147 return y 148 } 149 return x 150 } 151 152 func max(x, y int) int { 153 if x < y { 154 return y 155 } 156 return x 157 } 158 159 // roundDown10 rounds a number down to the nearest power of 10. 160 func roundDown10(n int) int { 161 var tens = 0 162 // tens = floor(log_10(n)) 163 for n >= 10 { 164 n = n / 10 165 tens++ 166 } 167 // result = 10^tens 168 result := 1 169 for i := 0; i < tens; i++ { 170 result *= 10 171 } 172 return result 173 } 174 175 // roundUp rounds x up to a number of the form [1eX, 2eX, 3eX, 5eX]. 176 func roundUp(n int) int { 177 base := roundDown10(n) 178 switch { 179 case n <= base: 180 return base 181 case n <= (2 * base): 182 return 2 * base 183 case n <= (3 * base): 184 return 3 * base 185 case n <= (5 * base): 186 return 5 * base 187 default: 188 return 10 * base 189 } 190 } 191 192 // run1 runs the first iteration of benchFunc. It returns whether more 193 // iterations of this benchmarks should be run. 194 func (b *B) run1() bool { 195 if ctx := b.context; ctx != nil { 196 // Extend maxLen, if needed. 197 if n := len(b.name) + ctx.extLen + 1; n > ctx.maxLen { 198 ctx.maxLen = n + 8 // Add additional slack to avoid too many jumps in size. 199 } 200 } 201 go func() { 202 // Signal that we're done whether we return normally 203 // or by FailNow's runtime.Goexit. 204 defer func() { 205 b.signal <- true 206 }() 207 208 b.runN(1) 209 }() 210 <-b.signal 211 if b.failed { 212 fmt.Fprintf(b.w, "--- FAIL: %s\n%s", b.name, b.output) 213 return false 214 } 215 // Only print the output if we know we are not going to proceed. 216 // Otherwise it is printed in processBench. 217 if b.hasSub || b.finished { 218 tag := "BENCH" 219 if b.skipped { 220 tag = "SKIP" 221 } 222 if b.chatty && (len(b.output) > 0 || b.finished) { 223 b.trimOutput() 224 fmt.Fprintf(b.w, "--- %s: %s\n%s", tag, b.name, b.output) 225 } 226 return false 227 } 228 return true 229 } 230 231 // run executes the benchmark in a separate goroutine, including all of its 232 // subbenchmarks. b must not have subbenchmarks. 233 func (b *B) run() BenchmarkResult { 234 if b.context != nil { 235 // Running go test --test.bench 236 b.context.processBench(b) // Must call doBench. 237 } else { 238 // Running func Benchmark. 239 b.doBench() 240 } 241 return b.result 242 } 243 244 func (b *B) doBench() BenchmarkResult { 245 go b.launch() 246 <-b.signal 247 return b.result 248 } 249 250 // launch launches the benchmark function. It gradually increases the number 251 // of benchmark iterations until the benchmark runs for the requested benchtime. 252 // launch is run by the doBench function as a separate goroutine. 253 // run1 must have been called on b. 254 func (b *B) launch() { 255 // Signal that we're done whether we return normally 256 // or by FailNow's runtime.Goexit. 257 defer func() { 258 b.signal <- true 259 }() 260 261 // Run the benchmark for at least the specified amount of time. 262 d := b.benchTime 263 for n := 1; !b.failed && b.duration < d && n < 1e9; { 264 last := n 265 // Predict required iterations. 266 if b.nsPerOp() == 0 { 267 n = 1e9 268 } else { 269 n = int(d.Nanoseconds() / b.nsPerOp()) 270 } 271 // Run more iterations than we think we'll need (1.2x). 272 // Don't grow too fast in case we had timing errors previously. 273 // Be sure to run at least one more than last time. 274 n = max(min(n+n/5, 100*last), last+1) 275 // Round up to something easy to read. 276 n = roundUp(n) 277 b.runN(n) 278 } 279 b.result = BenchmarkResult{b.N, b.duration, b.bytes, b.netAllocs, b.netBytes} 280 } 281 282 // The results of a benchmark run. 283 type BenchmarkResult struct { 284 N int // The number of iterations. 285 T time.Duration // The total time taken. 286 Bytes int64 // Bytes processed in one iteration. 287 MemAllocs uint64 // The total number of memory allocations. 288 MemBytes uint64 // The total number of bytes allocated. 289 } 290 291 func (r BenchmarkResult) NsPerOp() int64 { 292 if r.N <= 0 { 293 return 0 294 } 295 return r.T.Nanoseconds() / int64(r.N) 296 } 297 298 func (r BenchmarkResult) mbPerSec() float64 { 299 if r.Bytes <= 0 || r.T <= 0 || r.N <= 0 { 300 return 0 301 } 302 return (float64(r.Bytes) * float64(r.N) / 1e6) / r.T.Seconds() 303 } 304 305 func (r BenchmarkResult) AllocsPerOp() int64 { 306 if r.N <= 0 { 307 return 0 308 } 309 return int64(r.MemAllocs) / int64(r.N) 310 } 311 312 func (r BenchmarkResult) AllocedBytesPerOp() int64 { 313 if r.N <= 0 { 314 return 0 315 } 316 return int64(r.MemBytes) / int64(r.N) 317 } 318 319 func (r BenchmarkResult) String() string { 320 mbs := r.mbPerSec() 321 mb := "" 322 if mbs != 0 { 323 mb = fmt.Sprintf("\t%7.2f MB/s", mbs) 324 } 325 nsop := r.NsPerOp() 326 ns := fmt.Sprintf("%10d ns/op", nsop) 327 if r.N > 0 && nsop < 100 { 328 // The format specifiers here make sure that 329 // the ones digits line up for all three possible formats. 330 if nsop < 10 { 331 ns = fmt.Sprintf("%13.2f ns/op", float64(r.T.Nanoseconds())/float64(r.N)) 332 } else { 333 ns = fmt.Sprintf("%12.1f ns/op", float64(r.T.Nanoseconds())/float64(r.N)) 334 } 335 } 336 return fmt.Sprintf("%8d\t%s%s", r.N, ns, mb) 337 } 338 339 func (r BenchmarkResult) MemString() string { 340 return fmt.Sprintf("%8d B/op\t%8d allocs/op", 341 r.AllocedBytesPerOp(), r.AllocsPerOp()) 342 } 343 344 // benchmarkName returns full name of benchmark including procs suffix. 345 func benchmarkName(name string, n int) string { 346 if n != 1 { 347 return fmt.Sprintf("%s-%d", name, n) 348 } 349 return name 350 } 351 352 type benchContext struct { 353 match *matcher 354 355 maxLen int // The largest recorded benchmark name. 356 extLen int // Maximum extension length. 357 } 358 359 // An internal function but exported because it is cross-package; part of the implementation 360 // of the "go test" command. 361 func RunBenchmarks(matchString func(pat, str string) (bool, error), benchmarks []InternalBenchmark) { 362 runBenchmarksInternal(matchString, benchmarks) 363 } 364 365 func runBenchmarksInternal(matchString func(pat, str string) (bool, error), benchmarks []InternalBenchmark) bool { 366 // If no flag was specified, don't run benchmarks. 367 if len(*matchBenchmarks) == 0 { 368 return true 369 } 370 // Collect matching benchmarks and determine longest name. 371 maxprocs := 1 372 for _, procs := range cpuList { 373 if procs > maxprocs { 374 maxprocs = procs 375 } 376 } 377 ctx := &benchContext{ 378 match: newMatcher(matchString, *matchBenchmarks, "-test.bench"), 379 extLen: len(benchmarkName("", maxprocs)), 380 } 381 var bs []InternalBenchmark 382 for _, Benchmark := range benchmarks { 383 if _, matched := ctx.match.fullName(nil, Benchmark.Name); matched { 384 bs = append(bs, Benchmark) 385 benchName := benchmarkName(Benchmark.Name, maxprocs) 386 if l := len(benchName) + ctx.extLen + 1; l > ctx.maxLen { 387 ctx.maxLen = l 388 } 389 } 390 } 391 main := &B{ 392 common: common{ 393 name: "Main", 394 w: os.Stdout, 395 chatty: *chatty, 396 }, 397 benchFunc: func(b *B) { 398 for _, Benchmark := range bs { 399 b.Run(Benchmark.Name, Benchmark.F) 400 } 401 }, 402 benchTime: *benchTime, 403 context: ctx, 404 } 405 main.runN(1) 406 return !main.failed 407 } 408 409 // processBench runs bench b for the configured CPU counts and prints the results. 410 func (ctx *benchContext) processBench(b *B) { 411 for i, procs := range cpuList { 412 runtime.GOMAXPROCS(procs) 413 benchName := benchmarkName(b.name, procs) 414 fmt.Fprintf(b.w, "%-*s\t", ctx.maxLen, benchName) 415 // Recompute the running time for all but the first iteration. 416 if i > 0 { 417 b = &B{ 418 common: common{ 419 signal: make(chan bool), 420 name: b.name, 421 w: b.w, 422 chatty: b.chatty, 423 }, 424 benchFunc: b.benchFunc, 425 benchTime: b.benchTime, 426 } 427 b.run1() 428 } 429 r := b.doBench() 430 if b.failed { 431 // The output could be very long here, but probably isn't. 432 // We print it all, regardless, because we don't want to trim the reason 433 // the benchmark failed. 434 fmt.Fprintf(b.w, "--- FAIL: %s\n%s", benchName, b.output) 435 continue 436 } 437 results := r.String() 438 if *benchmarkMemory || b.showAllocResult { 439 results += "\t" + r.MemString() 440 } 441 fmt.Fprintln(b.w, results) 442 // Unlike with tests, we ignore the -chatty flag and always print output for 443 // benchmarks since the output generation time will skew the results. 444 if len(b.output) > 0 { 445 b.trimOutput() 446 fmt.Fprintf(b.w, "--- BENCH: %s\n%s", benchName, b.output) 447 } 448 if p := runtime.GOMAXPROCS(-1); p != procs { 449 fmt.Fprintf(os.Stderr, "testing: %s left GOMAXPROCS set to %d\n", benchName, p) 450 } 451 } 452 } 453 454 // Run benchmarks f as a subbenchmark with the given name. It reports 455 // whether there were any failures. 456 // 457 // A subbenchmark is like any other benchmark. A benchmark that calls Run at 458 // least once will not be measured itself and will be called once with N=1. 459 func (b *B) Run(name string, f func(b *B)) bool { 460 // Since b has subbenchmarks, we will no longer run it as a benchmark itself. 461 // Release the lock and acquire it on exit to ensure locks stay paired. 462 b.hasSub = true 463 benchmarkLock.Unlock() 464 defer benchmarkLock.Lock() 465 466 benchName, ok := b.name, true 467 if b.context != nil { 468 benchName, ok = b.context.match.fullName(&b.common, name) 469 } 470 if !ok { 471 return true 472 } 473 sub := &B{ 474 common: common{ 475 signal: make(chan bool), 476 name: benchName, 477 parent: &b.common, 478 level: b.level + 1, 479 w: b.w, 480 chatty: b.chatty, 481 }, 482 benchFunc: f, 483 benchTime: b.benchTime, 484 context: b.context, 485 } 486 if sub.run1() { 487 sub.run() 488 } 489 b.add(sub.result) 490 return !sub.failed 491 } 492 493 // add simulates running benchmarks in sequence in a single iteration. It is 494 // used to give some meaningful results in case func Benchmark is used in 495 // combination with Run. 496 func (b *B) add(other BenchmarkResult) { 497 r := &b.result 498 // The aggregated BenchmarkResults resemble running all subbenchmarks as 499 // in sequence in a single benchmark. 500 r.N = 1 501 r.T += time.Duration(other.NsPerOp()) 502 if other.Bytes == 0 { 503 // Summing Bytes is meaningless in aggregate if not all subbenchmarks 504 // set it. 505 b.missingBytes = true 506 r.Bytes = 0 507 } 508 if !b.missingBytes { 509 r.Bytes += other.Bytes 510 } 511 r.MemAllocs += uint64(other.AllocsPerOp()) 512 r.MemBytes += uint64(other.AllocedBytesPerOp()) 513 } 514 515 // trimOutput shortens the output from a benchmark, which can be very long. 516 func (b *B) trimOutput() { 517 // The output is likely to appear multiple times because the benchmark 518 // is run multiple times, but at least it will be seen. This is not a big deal 519 // because benchmarks rarely print, but just in case, we trim it if it's too long. 520 const maxNewlines = 10 521 for nlCount, j := 0, 0; j < len(b.output); j++ { 522 if b.output[j] == '\n' { 523 nlCount++ 524 if nlCount >= maxNewlines { 525 b.output = append(b.output[:j], "\n\t... [output truncated]\n"...) 526 break 527 } 528 } 529 } 530 } 531 532 // A PB is used by RunParallel for running parallel benchmarks. 533 type PB struct { 534 globalN *uint64 // shared between all worker goroutines iteration counter 535 grain uint64 // acquire that many iterations from globalN at once 536 cache uint64 // local cache of acquired iterations 537 bN uint64 // total number of iterations to execute (b.N) 538 } 539 540 // Next reports whether there are more iterations to execute. 541 func (pb *PB) Next() bool { 542 if pb.cache == 0 { 543 n := atomic.AddUint64(pb.globalN, pb.grain) 544 if n <= pb.bN { 545 pb.cache = pb.grain 546 } else if n < pb.bN+pb.grain { 547 pb.cache = pb.bN + pb.grain - n 548 } else { 549 return false 550 } 551 } 552 pb.cache-- 553 return true 554 } 555 556 // RunParallel runs a benchmark in parallel. 557 // It creates multiple goroutines and distributes b.N iterations among them. 558 // The number of goroutines defaults to GOMAXPROCS. To increase parallelism for 559 // non-CPU-bound benchmarks, call SetParallelism before RunParallel. 560 // RunParallel is usually used with the go test -cpu flag. 561 // 562 // The body function will be run in each goroutine. It should set up any 563 // goroutine-local state and then iterate until pb.Next returns false. 564 // It should not use the StartTimer, StopTimer, or ResetTimer functions, 565 // because they have global effect. It should also not call Run. 566 func (b *B) RunParallel(body func(*PB)) { 567 if b.N == 0 { 568 return // Nothing to do when probing. 569 } 570 // Calculate grain size as number of iterations that take ~100µs. 571 // 100µs is enough to amortize the overhead and provide sufficient 572 // dynamic load balancing. 573 grain := uint64(0) 574 if b.previousN > 0 && b.previousDuration > 0 { 575 grain = 1e5 * uint64(b.previousN) / uint64(b.previousDuration) 576 } 577 if grain < 1 { 578 grain = 1 579 } 580 // We expect the inner loop and function call to take at least 10ns, 581 // so do not do more than 100µs/10ns=1e4 iterations. 582 if grain > 1e4 { 583 grain = 1e4 584 } 585 586 n := uint64(0) 587 numProcs := b.parallelism * runtime.GOMAXPROCS(0) 588 var wg sync.WaitGroup 589 wg.Add(numProcs) 590 for p := 0; p < numProcs; p++ { 591 go func() { 592 defer wg.Done() 593 pb := &PB{ 594 globalN: &n, 595 grain: grain, 596 bN: uint64(b.N), 597 } 598 body(pb) 599 }() 600 } 601 wg.Wait() 602 if n <= uint64(b.N) && !b.Failed() { 603 b.Fatal("RunParallel: body exited without pb.Next() == false") 604 } 605 } 606 607 // SetParallelism sets the number of goroutines used by RunParallel to p*GOMAXPROCS. 608 // There is usually no need to call SetParallelism for CPU-bound benchmarks. 609 // If p is less than 1, this call will have no effect. 610 func (b *B) SetParallelism(p int) { 611 if p >= 1 { 612 b.parallelism = p 613 } 614 } 615 616 // Benchmark benchmarks a single function. Useful for creating 617 // custom benchmarks that do not use the "go test" command. 618 // 619 // If f calls Run, the result will be an estimate of running all its 620 // subbenchmarks that don't call Run in sequence in a single benchmark. 621 func Benchmark(f func(b *B)) BenchmarkResult { 622 b := &B{ 623 common: common{ 624 signal: make(chan bool), 625 w: discard{}, 626 }, 627 benchFunc: f, 628 benchTime: *benchTime, 629 } 630 if !b.run1() { 631 return BenchmarkResult{} 632 } 633 return b.run() 634 } 635 636 type discard struct{} 637 638 func (discard) Write(b []byte) (n int, err error) { return len(b), nil }