golang性能分析pprof使用

1、关键代码如下:

源码地址:GitHub - wolfogre/go-pprof-practice: go pprof practice.

package main

import (
	"log"
	"net/http"
	_ "net/http/pprof"
	"os"
	"runtime"
	"time"

	"github.com/wolfogre/go-pprof-practice/animal"
)

func main() {
	log.SetFlags(log.Lshortfile | log.LstdFlags)
	log.SetOutput(os.Stdout)

	runtime.GOMAXPROCS(1)
	runtime.SetMutexProfileFraction(1)
	runtime.SetBlockProfileRate(1)

	go func() {
		if err := http.ListenAndServe(":6060", nil); err != nil {
			log.Fatal(err)
		}
		os.Exit(0)
	}()

	for {
		for _, v := range animal.AllAnimals {
			v.Live()
		}
		time.Sleep(time.Second)
	}
}


_ "net/http/pprof"
runtime.GOMAXPROCS(1)
runtime.SetMutexProfileFraction(1)
runtime.SetBlockProfileRate(1)

命令如下:

Microsoft Windows [版本 10.0.22000.978]
(c) Microsoft Corporation。保留所有权利。

C:\Users\90760>go tool pprof -h
usage:

Produce output in the specified format.

   pprof <format> [options] [binary] <source> ...

Omit the format to get an interactive shell whose commands can be used
to generate various views of a profile

   pprof [options] [binary] <source> ...

Omit the format and provide the "-http" flag to get an interactive web
interface at the specified host:port that can be used to navigate through
various views of a profile.

   pprof -http [host]:[port] [options] [binary] <source> ...

Details:
  Output formats (select at most one):
    -callgrind       Outputs a graph in callgrind format
    -comments        Output all profile comments
    -disasm          Output assembly listings annotated with samples
    -dot             Outputs a graph in DOT format
    -eog             Visualize graph through eog
    -evince          Visualize graph through evince
    -gif             Outputs a graph image in GIF format
    -gv              Visualize graph through gv
    -kcachegrind     Visualize report in KCachegrind
    -list            Output annotated source for functions matching regexp
    -pdf             Outputs a graph in PDF format
    -peek            Output callers/callees of functions matching regexp
    -png             Outputs a graph image in PNG format
    -proto           Outputs the profile in compressed protobuf format
    -ps              Outputs a graph in PS format
    -raw             Outputs a text representation of the raw profile
    -svg             Outputs a graph in SVG format
    -tags            Outputs all tags in the profile
    -text            Outputs top entries in text form
    -top             Outputs top entries in text form
    -topproto        Outputs top entries in compressed protobuf format
    -traces          Outputs all profile samples in text form
    -tree            Outputs a text rendering of call graph
    -web             Visualize graph through web browser
    -weblist         Display annotated source in a web browser

  Options:
    -call_tree       Create a context-sensitive call tree
    -compact_labels  Show minimal headers
    -divide_by       Ratio to divide all samples before visualization
    -drop_negative   Ignore negative differences
    -edgefraction    Hide edges below <f>*total
    -focus           Restricts to samples going through a node matching regexp
    -hide            Skips nodes matching regexp
    -ignore          Skips paths going through any nodes matching regexp
    -intel_syntax    Show assembly in Intel syntax
    -mean            Average sample value over first value (count)
    -nodecount       Max number of nodes to show
    -nodefraction    Hide nodes below <f>*total
    -noinlines       Ignore inlines.
    -normalize       Scales profile based on the base profile.
    -output          Output filename for file-based outputs
    -prune_from      Drops any functions below the matched frame.
    -relative_percentages Show percentages relative to focused subgraph
    -sample_index    Sample value to report (0-based index or name)
    -show            Only show nodes matching regexp
    -show_from       Drops functions above the highest matched frame.
    -source_path     Search path for source files
    -tagfocus        Restricts to samples with tags in range or matched by regexp
    -taghide         Skip tags matching this regexp
    -tagignore       Discard samples with tags in range or matched by regexp
    -tagshow         Only consider tags matching this regexp
    -trim            Honor nodefraction/edgefraction/nodecount defaults
    -trim_path       Path to trim from source paths before search
    -unit            Measurement units to display

  Option groups (only set one per group):
    granularity
      -functions       Aggregate at the function level.
      -filefunctions   Aggregate at the function level.
      -files           Aggregate at the file level.
      -lines           Aggregate at the source code line level.
      -addresses       Aggregate at the address level.
    sort
      -cum             Sort entries based on cumulative weight
      -flat            Sort entries based on own weight

  Source options:
    -seconds              Duration for time-based profile collection
    -timeout              Timeout in seconds for profile collection
    -buildid              Override build id for main binary
    -add_comment          Free-form annotation to add to the profile
                          Displayed on some reports or with pprof -comments
    -diff_base source     Source of base profile for comparison
    -base source          Source of base profile for profile subtraction
    profile.pb.gz         Profile in compressed protobuf format
    legacy_profile        Profile in legacy pprof format
    http://host/profile   URL for profile handler to retrieve
    -symbolize=           Controls source of symbol information
      none                  Do not attempt symbolization
      local                 Examine only local binaries
      fastlocal             Only get function names from local binaries
      remote                Do not examine local binaries
      force                 Force re-symbolization
    Binary                  Local path or build id of binary for symbolization
    -tls_cert             TLS client certificate file for fetching profile and symbols
    -tls_key              TLS private key file for fetching profile and symbols
    -tls_ca               TLS CA certs file for fetching profile and symbols

  Misc options:
   -http              Provide web interface at host:port.
                      Host is optional and 'localhost' by default.
                      Port is optional and a randomly available port by default.
   -no_browser        Skip opening a browser for the interactive web UI.
   -tools             Search path for object tools

  Legacy convenience options:
   -inuse_space           Same as -sample_index=inuse_space
   -inuse_objects         Same as -sample_index=inuse_objects
   -alloc_space           Same as -sample_index=alloc_space
   -alloc_objects         Same as -sample_index=alloc_objects
   -total_delay           Same as -sample_index=delay
   -contentions           Same as -sample_index=contentions
   -mean_delay            Same as -mean -sample_index=delay

  Environment Variables:
   PPROF_TMPDIR       Location for saved profiles (default $HOME/pprof)
   PPROF_TOOLS        Search path for object-level tools
   PPROF_BINARY_PATH  Search path for local binary files
                      default: $HOME/pprof/binaries
                      searches $name, $path, $buildid/$name, $path/$buildid
   * On Windows, %USERPROFILE% is used instead of $HOME

C:\Users\90760>go tool pprof http://localhost:6060/debug/pprof/allocs
Fetching profile over HTTP from http://localhost:6060/debug/pprof/allocs
Saved profile in C:\Users\90760\pprof\pprof.alloc_objects.alloc_space.inuse_objects.inuse_space.001.pb.gz
Type: alloc_space
Time: Oct 29, 2022 at 4:33pm (CST)
Entering interactive mode (type "help" for commands, "o" for options)
(pprof) top
Showing nodes accounting for 2750.04MB, 99.95% of 2751.54MB total
Dropped 15 nodes (cum <= 13.76MB)
      flat  flat%   sum%        cum   cum%
 2046.04MB 74.36% 74.36%  2046.04MB 74.36%  github.com/wolfogre/go-pprof-practice/animal/muridae/mouse.(*Mouse).Steal
     704MB 25.59% 99.95%      704MB 25.59%  github.com/wolfogre/go-pprof-practice/animal/canidae/dog.(*Dog).Run (inline)
         0     0% 99.95%      704MB 25.59%  github.com/wolfogre/go-pprof-practice/animal/canidae/dog.(*Dog).Live
         0     0% 99.95%  2046.04MB 74.36%  github.com/wolfogre/go-pprof-practice/animal/muridae/mouse.(*Mouse).Live
         0     0% 99.95%  2750.04MB 99.95%  main.main
         0     0% 99.95%  2750.04MB 99.95%  runtime.main
(pprof) top 5
Showing nodes accounting for 2750.04MB, 99.95% of 2751.54MB total
Dropped 15 nodes (cum <= 13.76MB)
Showing top 5 nodes out of 6
      flat  flat%   sum%        cum   cum%
 2046.04MB 74.36% 74.36%  2046.04MB 74.36%  github.com/wolfogre/go-pprof-practice/animal/muridae/mouse.(*Mouse).Steal
     704MB 25.59% 99.95%      704MB 25.59%  github.com/wolfogre/go-pprof-practice/animal/canidae/dog.(*Dog).Run (inline)
         0     0% 99.95%      704MB 25.59%  github.com/wolfogre/go-pprof-practice/animal/canidae/dog.(*Dog).Live
         0     0% 99.95%  2046.04MB 74.36%  github.com/wolfogre/go-pprof-practice/animal/muridae/mouse.(*Mouse).Live
         0     0% 99.95%  2750.04MB 99.95%  main.main
(pprof) list
command list requires an argument
(pprof) list Steal
Total: 2.69GB
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/muridae/mouse.(*Mouse).Steal in D:\code\goproject\src\go-pprof-practice\animal\muridae\mouse\mouse.go
       2GB        2GB (flat, cum) 74.36% of Total
         .          .     45:
         .          .     46:func (m *Mouse) Steal() {
         .          .     47:   log.Println(m.Name(), "steal")
         .          .     48:   max := constant.Gi
         .          .     49:   for len(m.buffer)*constant.Mi < max {
       2GB        2GB     50:           m.buffer = append(m.buffer, [constant.Mi]byte{})
         .          .     51:   }
         .          .     52:}
(pprof) list dog
Total: 2.69GB
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/dog.(*Dog).Live in D:\code\goproject\src\go-pprof-practice\animal\canidae\dog\dog.go
         0      704MB (flat, cum) 25.59% of Total
         .          .     16:func (d *Dog) Live() {
         .          .     17:   d.Eat()
         .          .     18:   d.Drink()
         .          .     19:   d.Shit()
         .          .     20:   d.Pee()
         .      704MB     21:   d.Run()
         .          .     22:   d.Howl()
         .          .     23:}
         .          .     24:
         .          .     25:func (d *Dog) Eat() {
         .          .     26:   log.Println(d.Name(), "eat")
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/dog.(*Dog).Run in D:\code\goproject\src\go-pprof-practice\animal\canidae\dog\dog.go
     704MB      704MB (flat, cum) 25.59% of Total
         .          .     38:   log.Println(d.Name(), "pee")
         .          .     39:}
         .          .     40:
         .          .     41:func (d *Dog) Run() {
         .          .     42:   log.Println(d.Name(), "run")
     704MB      704MB     43:   _ = make([]byte, 16*constant.Mi)
         .          .     44:}
         .          .     45:
         .          .     46:func (d *Dog) Howl() {
         .          .     47:   log.Println(d.Name(), "howl")
         .          .     48:}
(pprof) c

Microsoft Windows [版本 10.0.22000.978]
(c) Microsoft Corporation。保留所有权利。

C:\Users\90760>go tool pprof http://localhost:6060/debug/pprof/block
Fetching profile over HTTP from http://localhost:6060/debug/pprof/block
Saved profile in C:\Users\90760\pprof\pprof.contentions.delay.001.pb.gz
Type: delay
Time: Oct 29, 2022 at 4:55pm (CST)
Entering interactive mode (type "help" for commands, "o" for options)
(pprof) top
Showing nodes accounting for 643.37s, 100% of 643.39s total
Dropped 4 nodes (cum <= 3.22s)
Showing top 10 nodes out of 15
      flat  flat%   sum%        cum   cum%
   299.42s 46.54% 46.54%    299.42s 46.54%  runtime.chanrecv1
   298.85s 46.45% 92.99%    298.85s 46.45%  sync.(*Mutex).Lock (inline)
    45.10s  7.01%   100%     45.10s  7.01%  runtime.selectgo
         0     0%   100%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Howl
         0     0%   100%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live
         0     0%   100%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live
         0     0%   100%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee
         0     0%   100%    597.90s 92.93%  main.main
         0     0%   100%     45.47s  7.07%  net/http.(*ServeMux).ServeHTTP
         0     0%   100%     45.49s  7.07%  net/http.(*conn).serve
(pprof) top live
Focus expression matched no samples
Active filters:
   focus=live
Showing nodes accounting for 0, 0% of 643.39s total
      flat  flat%   sum%        cum   cum%
(pprof) list cat
Total: 643.39s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     14:
         .          .     15:func (c *Cat) Live() {
         .          .     16:   c.Eat()
         .          .     17:   c.Drink()
         .          .     18:   c.Shit()
         .    299.05s     19:   c.Pee()
         .          .     20:   c.Climb()
         .          .     21:   c.Sneak()
         .          .     22:}
         .          .     23:
         .          .     24:func (c *Cat) Eat() {
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     34:}
         .          .     35:
         .          .     36:func (c *Cat) Pee() {
         .          .     37:   log.Println(c.Name(), "pee")
         .          .     38:
         .    299.05s     39:   <-time.After(time.Second)
         .          .     40:}
         .          .     41:
         .          .     42:func (c *Cat) Climb() {
         .          .     43:   log.Println(c.Name(), "climb")
         .          .     44:}
(pprof)

Microsoft Windows [版本 10.0.22000.978]
(c) Microsoft Corporation。保留所有权利。

C:\Users\90760>go tool pprof http://localhost:6060/debug/pprof/block
Fetching profile over HTTP from http://localhost:6060/debug/pprof/block
Saved profile in C:\Users\90760\pprof\pprof.contentions.delay.001.pb.gz
Type: delay
Time: Oct 29, 2022 at 4:55pm (CST)
Entering interactive mode (type "help" for commands, "o" for options)
(pprof) top
Showing nodes accounting for 643.37s, 100% of 643.39s total
Dropped 4 nodes (cum <= 3.22s)
Showing top 10 nodes out of 15
      flat  flat%   sum%        cum   cum%
   299.42s 46.54% 46.54%    299.42s 46.54%  runtime.chanrecv1
   298.85s 46.45% 92.99%    298.85s 46.45%  sync.(*Mutex).Lock (inline)
    45.10s  7.01%   100%     45.10s  7.01%  runtime.selectgo
         0     0%   100%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Howl
         0     0%   100%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live
         0     0%   100%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live
         0     0%   100%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee
         0     0%   100%    597.90s 92.93%  main.main
         0     0%   100%     45.47s  7.07%  net/http.(*ServeMux).ServeHTTP
         0     0%   100%     45.49s  7.07%  net/http.(*conn).serve
(pprof) top live
Focus expression matched no samples
Active filters:
   focus=live
Showing nodes accounting for 0, 0% of 643.39s total
      flat  flat%   sum%        cum   cum%
(pprof) list cat
Total: 643.39s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     14:
         .          .     15:func (c *Cat) Live() {
         .          .     16:   c.Eat()
         .          .     17:   c.Drink()
         .          .     18:   c.Shit()
         .    299.05s     19:   c.Pee()
         .          .     20:   c.Climb()
         .          .     21:   c.Sneak()
         .          .     22:}
         .          .     23:
         .          .     24:func (c *Cat) Eat() {
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     34:}
         .          .     35:
         .          .     36:func (c *Cat) Pee() {
         .          .     37:   log.Println(c.Name(), "pee")
         .          .     38:
         .    299.05s     39:   <-time.After(time.Second)
         .          .     40:}
         .          .     41:
         .          .     42:func (c *Cat) Climb() {
         .          .     43:   log.Println(c.Name(), "climb")
         .          .     44:}
(pprof) list wolf
Total: 643.39s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Howl in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0    298.85s (flat, cum) 46.45% of Total
         .          .     55:   m.Lock()
         .          .     56:   go func() {
         .          .     57:           time.Sleep(time.Second)
         .          .     58:           m.Unlock()
         .          .     59:   }()
         .    298.85s     60:   m.Lock()
         .          .     61:}
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0    298.85s (flat, cum) 46.45% of Total
         .          .     18:   w.Eat()
         .          .     19:   w.Drink()
         .          .     20:   w.Shit()
         .          .     21:   w.Pee()
         .          .     22:   w.Run()
         .    298.85s     23:   w.Howl()
         .          .     24:}
         .          .     25:
         .          .     26:func (w *Wolf) Eat() {
         .          .     27:   log.Println(w.Name(), "eat")
         .          .     28:}
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     14:
         .          .     15:func (c *Cat) Live() {
         .          .     16:   c.Eat()
         .          .     17:   c.Drink()
         .          .     18:   c.Shit()
         .    299.05s     19:   c.Pee()
         .          .     20:   c.Climb()
         .          .     21:   c.Sneak()
         .          .     22:}
         .          .     23:
         .          .     24:func (c *Cat) Eat() {
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     34:}
         .          .     35:
         .          .     36:func (c *Cat) Pee() {
         .          .     37:   log.Println(c.Name(), "pee")
         .          .     38:
         .    299.05s     39:   <-time.After(time.Second)
         .          .     40:}
         .          .     41:
         .          .     42:func (c *Cat) Climb() {
         .          .     43:   log.Println(c.Name(), "climb")
         .          .     44:}
(pprof) top live
Focus expression matched no samples
Active filters:
   focus=live
Showing nodes accounting for 0, 0% of 643.39s total
      flat  flat%   sum%        cum   cum%
(pprof) list live
Total: 643.39s
(pprof) list howl
Total: 643.39s
(pprof) list Live
Total: 643.39s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0    298.85s (flat, cum) 46.45% of Total
         .          .     18:   w.Eat()
         .          .     19:   w.Drink()
         .          .     20:   w.Shit()
         .          .     21:   w.Pee()
         .          .     22:   w.Run()
         .    298.85s     23:   w.Howl()
         .          .     24:}
         .          .     25:
         .          .     26:func (w *Wolf) Eat() {
         .          .     27:   log.Println(w.Name(), "eat")
         .          .     28:}
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     14:
         .          .     15:func (c *Cat) Live() {
         .          .     16:   c.Eat()
         .          .     17:   c.Drink()
         .          .     18:   c.Shit()
         .    299.05s     19:   c.Pee()
         .          .     20:   c.Climb()
         .          .     21:   c.Sneak()
         .          .     22:}
         .          .     23:
         .          .     24:func (c *Cat) Eat() {
(pprof) top
Showing nodes accounting for 643.37s, 100% of 643.39s total
Dropped 4 nodes (cum <= 3.22s)
Showing top 10 nodes out of 15
      flat  flat%   sum%        cum   cum%
   299.42s 46.54% 46.54%    299.42s 46.54%  runtime.chanrecv1
   298.85s 46.45% 92.99%    298.85s 46.45%  sync.(*Mutex).Lock (inline)
    45.10s  7.01%   100%     45.10s  7.01%  runtime.selectgo
         0     0%   100%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Howl
         0     0%   100%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live
         0     0%   100%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live
         0     0%   100%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee
         0     0%   100%    597.90s 92.93%  main.main
         0     0%   100%     45.47s  7.07%  net/http.(*ServeMux).ServeHTTP
         0     0%   100%     45.49s  7.07%  net/http.(*conn).serve
(pprof) top Live
Active filters:
   focus=Live
Showing nodes accounting for 597.90s, 92.93% of 643.39s total
      flat  flat%   sum%        cum   cum%
   299.05s 46.48% 46.48%    299.05s 46.48%  runtime.chanrecv1
   298.85s 46.45% 92.93%    298.85s 46.45%  sync.(*Mutex).Lock (inline)
         0     0% 92.93%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Howl
         0     0% 92.93%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live
         0     0% 92.93%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live
         0     0% 92.93%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee
         0     0% 92.93%    597.90s 92.93%  main.main
         0     0% 92.93%    597.90s 92.93%  runtime.main
(pprof) list Live
Total: 643.39s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0    298.85s (flat, cum) 46.45% of Total
         .          .     18:   w.Eat()
         .          .     19:   w.Drink()
         .          .     20:   w.Shit()
         .          .     21:   w.Pee()
         .          .     22:   w.Run()
         .    298.85s     23:   w.Howl()
         .          .     24:}
         .          .     25:
         .          .     26:func (w *Wolf) Eat() {
         .          .     27:   log.Println(w.Name(), "eat")
         .          .     28:}
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     14:
         .          .     15:func (c *Cat) Live() {
         .          .     16:   c.Eat()
         .          .     17:   c.Drink()
         .          .     18:   c.Shit()
         .    299.05s     19:   c.Pee()
         .          .     20:   c.Climb()
         .          .     21:   c.Sneak()
         .          .     22:}
         .          .     23:
         .          .     24:func (c *Cat) Eat() {
(pprof) list Howl
Total: 643.39s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Howl in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0    298.85s (flat, cum) 46.45% of Total
         .          .     55:   m.Lock()
         .          .     56:   go func() {
         .          .     57:           time.Sleep(time.Second)
         .          .     58:           m.Unlock()
         .          .     59:   }()
         .    298.85s     60:   m.Lock()
         .          .     61:}
(pprof)

Microsoft Windows [版本 10.0.22000.978]
(c) Microsoft Corporation。保留所有权利。

C:\Users\90760>go tool pprof http://localhost:6060/debug/pprof/block
Fetching profile over HTTP from http://localhost:6060/debug/pprof/block
Saved profile in C:\Users\90760\pprof\pprof.contentions.delay.001.pb.gz
Type: delay
Time: Oct 29, 2022 at 4:55pm (CST)
Entering interactive mode (type "help" for commands, "o" for options)
(pprof) top
Showing nodes accounting for 643.37s, 100% of 643.39s total
Dropped 4 nodes (cum <= 3.22s)
Showing top 10 nodes out of 15
      flat  flat%   sum%        cum   cum%
   299.42s 46.54% 46.54%    299.42s 46.54%  runtime.chanrecv1
   298.85s 46.45% 92.99%    298.85s 46.45%  sync.(*Mutex).Lock (inline)
    45.10s  7.01%   100%     45.10s  7.01%  runtime.selectgo
         0     0%   100%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Howl
         0     0%   100%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live
         0     0%   100%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live
         0     0%   100%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee
         0     0%   100%    597.90s 92.93%  main.main
         0     0%   100%     45.47s  7.07%  net/http.(*ServeMux).ServeHTTP
         0     0%   100%     45.49s  7.07%  net/http.(*conn).serve
(pprof) top live
Focus expression matched no samples
Active filters:
   focus=live
Showing nodes accounting for 0, 0% of 643.39s total
      flat  flat%   sum%        cum   cum%
(pprof) list cat
Total: 643.39s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     14:
         .          .     15:func (c *Cat) Live() {
         .          .     16:   c.Eat()
         .          .     17:   c.Drink()
         .          .     18:   c.Shit()
         .    299.05s     19:   c.Pee()
         .          .     20:   c.Climb()
         .          .     21:   c.Sneak()
         .          .     22:}
         .          .     23:
         .          .     24:func (c *Cat) Eat() {
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     34:}
         .          .     35:
         .          .     36:func (c *Cat) Pee() {
         .          .     37:   log.Println(c.Name(), "pee")
         .          .     38:
         .    299.05s     39:   <-time.After(time.Second)
         .          .     40:}
         .          .     41:
         .          .     42:func (c *Cat) Climb() {
         .          .     43:   log.Println(c.Name(), "climb")
         .          .     44:}
(pprof) list wolf
Total: 643.39s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Howl in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0    298.85s (flat, cum) 46.45% of Total
         .          .     55:   m.Lock()
         .          .     56:   go func() {
         .          .     57:           time.Sleep(time.Second)
         .          .     58:           m.Unlock()
         .          .     59:   }()
         .    298.85s     60:   m.Lock()
         .          .     61:}
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0    298.85s (flat, cum) 46.45% of Total
         .          .     18:   w.Eat()
         .          .     19:   w.Drink()
         .          .     20:   w.Shit()
         .          .     21:   w.Pee()
         .          .     22:   w.Run()
         .    298.85s     23:   w.Howl()
         .          .     24:}
         .          .     25:
         .          .     26:func (w *Wolf) Eat() {
         .          .     27:   log.Println(w.Name(), "eat")
         .          .     28:}
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     14:
         .          .     15:func (c *Cat) Live() {
         .          .     16:   c.Eat()
         .          .     17:   c.Drink()
         .          .     18:   c.Shit()
         .    299.05s     19:   c.Pee()
         .          .     20:   c.Climb()
         .          .     21:   c.Sneak()
         .          .     22:}
         .          .     23:
         .          .     24:func (c *Cat) Eat() {
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     34:}
         .          .     35:
         .          .     36:func (c *Cat) Pee() {
         .          .     37:   log.Println(c.Name(), "pee")
         .          .     38:
         .    299.05s     39:   <-time.After(time.Second)
         .          .     40:}
         .          .     41:
         .          .     42:func (c *Cat) Climb() {
         .          .     43:   log.Println(c.Name(), "climb")
         .          .     44:}
(pprof) top live
Focus expression matched no samples
Active filters:
   focus=live
Showing nodes accounting for 0, 0% of 643.39s total
      flat  flat%   sum%        cum   cum%
(pprof) list live
Total: 643.39s
(pprof) list howl
Total: 643.39s
(pprof) list Live
Total: 643.39s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0    298.85s (flat, cum) 46.45% of Total
         .          .     18:   w.Eat()
         .          .     19:   w.Drink()
         .          .     20:   w.Shit()
         .          .     21:   w.Pee()
         .          .     22:   w.Run()
         .    298.85s     23:   w.Howl()
         .          .     24:}
         .          .     25:
         .          .     26:func (w *Wolf) Eat() {
         .          .     27:   log.Println(w.Name(), "eat")
         .          .     28:}
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     14:
         .          .     15:func (c *Cat) Live() {
         .          .     16:   c.Eat()
         .          .     17:   c.Drink()
         .          .     18:   c.Shit()
         .    299.05s     19:   c.Pee()
         .          .     20:   c.Climb()
         .          .     21:   c.Sneak()
         .          .     22:}
         .          .     23:
         .          .     24:func (c *Cat) Eat() {
(pprof) top
Showing nodes accounting for 643.37s, 100% of 643.39s total
Dropped 4 nodes (cum <= 3.22s)
Showing top 10 nodes out of 15
      flat  flat%   sum%        cum   cum%
   299.42s 46.54% 46.54%    299.42s 46.54%  runtime.chanrecv1
   298.85s 46.45% 92.99%    298.85s 46.45%  sync.(*Mutex).Lock (inline)
    45.10s  7.01%   100%     45.10s  7.01%  runtime.selectgo
         0     0%   100%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Howl
         0     0%   100%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live
         0     0%   100%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live
         0     0%   100%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee
         0     0%   100%    597.90s 92.93%  main.main
         0     0%   100%     45.47s  7.07%  net/http.(*ServeMux).ServeHTTP
         0     0%   100%     45.49s  7.07%  net/http.(*conn).serve
(pprof) top Live
Active filters:
   focus=Live
Showing nodes accounting for 597.90s, 92.93% of 643.39s total
      flat  flat%   sum%        cum   cum%
   299.05s 46.48% 46.48%    299.05s 46.48%  runtime.chanrecv1
   298.85s 46.45% 92.93%    298.85s 46.45%  sync.(*Mutex).Lock (inline)
         0     0% 92.93%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Howl
         0     0% 92.93%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live
         0     0% 92.93%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live
         0     0% 92.93%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee
         0     0% 92.93%    597.90s 92.93%  main.main
         0     0% 92.93%    597.90s 92.93%  runtime.main
(pprof) list Live
Total: 643.39s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0    298.85s (flat, cum) 46.45% of Total
         .          .     18:   w.Eat()
         .          .     19:   w.Drink()
         .          .     20:   w.Shit()
         .          .     21:   w.Pee()
         .          .     22:   w.Run()
         .    298.85s     23:   w.Howl()
         .          .     24:}
         .          .     25:
         .          .     26:func (w *Wolf) Eat() {
         .          .     27:   log.Println(w.Name(), "eat")
         .          .     28:}
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     14:
         .          .     15:func (c *Cat) Live() {
         .          .     16:   c.Eat()
         .          .     17:   c.Drink()
         .          .     18:   c.Shit()
         .    299.05s     19:   c.Pee()
         .          .     20:   c.Climb()
         .          .     21:   c.Sneak()
         .          .     22:}
         .          .     23:
         .          .     24:func (c *Cat) Eat() {
(pprof) list Howl
Total: 643.39s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Howl in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0    298.85s (flat, cum) 46.45% of Total
         .          .     55:   m.Lock()
         .          .     56:   go func() {
         .          .     57:           time.Sleep(time.Second)
         .          .     58:           m.Unlock()
         .          .     59:   }()
         .    298.85s     60:   m.Lock()
         .          .     61:}
(pprof) exit

C:\Users\90760>go tool pprof http://localhost:6060/debug/pprof/goroutine
Fetching profile over HTTP from http://localhost:6060/debug/pprof/goroutine
Saved profile in C:\Users\90760\pprof\pprof.goroutine.001.pb.gz
Type: goroutine
Time: Oct 29, 2022 at 5:08pm (CST)
Entering interactive mode (type "help" for commands, "o" for options)
(pprof) top
Showing nodes accounting for 53, 98.15% of 54 total
Showing top 10 nodes out of 32
      flat  flat%   sum%        cum   cum%
        51 94.44% 94.44%         51 94.44%  runtime.gopark
         1  1.85% 96.30%          1  1.85%  runtime.asyncPreempt2
         1  1.85% 98.15%          1  1.85%  runtime/pprof.runtime_goroutineProfileWithLabels
         0     0% 98.15%         50 92.59%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Drink.func1
         0     0% 98.15%          1  1.85%  github.com/wolfogre/go-pprof-practice/animal/felidae/tiger.(*Tiger).Eat
         0     0% 98.15%          1  1.85%  github.com/wolfogre/go-pprof-practice/animal/felidae/tiger.(*Tiger).Live
         0     0% 98.15%          1  1.85%  internal/poll.(*FD).Accept
         0     0% 98.15%          1  1.85%  internal/poll.(*FD).acceptOne
         0     0% 98.15%          1  1.85%  internal/poll.(*pollDesc).wait
         0     0% 98.15%          1  1.85%  internal/poll.execIO
(pprof) list Drink
Total: 54
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Drink.func1 in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0         50 (flat, cum) 92.59% of Total
         .          .     29:
         .          .     30:func (w *Wolf) Drink() {
         .          .     31:   log.Println(w.Name(), "drink")
         .          .     32:   for i := 0; i < 10; i++ {
         .          .     33:           go func() {
         .         50     34:                   time.Sleep(30 * time.Second)
         .          .     35:           }()
         .          .     36:   }
         .          .     37:}
         .          .     38:
         .          .     39:func (w *Wolf) Shit() {
(pprof) exit

C:\Users\90760>go tool pprof http://localhost:6060/debug/pprof/heap
Fetching profile over HTTP from http://localhost:6060/debug/pprof/heap
Saved profile in C:\Users\90760\pprof\pprof.alloc_objects.alloc_space.inuse_objects.inuse_space.002.pb.gz
Type: inuse_space
Time: Oct 29, 2022 at 5:11pm (CST)
Entering interactive mode (type "help" for commands, "o" for options)
(pprof) top
Showing nodes accounting for 1GB, 99.90% of 1GB total
Dropped 13 nodes (cum <= 0.01GB)
      flat  flat%   sum%        cum   cum%
       1GB 99.90% 99.90%        1GB 99.90%  github.com/wolfogre/go-pprof-practice/animal/muridae/mouse.(*Mouse).Steal
         0     0% 99.90%        1GB 99.90%  github.com/wolfogre/go-pprof-practice/animal/muridae/mouse.(*Mouse).Live
         0     0% 99.90%        1GB 99.90%  main.main
         0     0% 99.90%        1GB 99.90%  runtime.main
(pprof) list Steal
Total: 1GB
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/muridae/mouse.(*Mouse).Steal in D:\code\goproject\src\go-pprof-practice\animal\muridae\mouse\mouse.go
       1GB        1GB (flat, cum) 99.90% of Total
         .          .     45:
         .          .     46:func (m *Mouse) Steal() {
         .          .     47:   log.Println(m.Name(), "steal")
         .          .     48:   max := constant.Gi
         .          .     49:   for len(m.buffer)*constant.Mi < max {
       1GB        1GB     50:           m.buffer = append(m.buffer, [constant.Mi]byte{})
         .          .     51:   }
         .          .     52:}
(pprof) list Live
Total: 1GB
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/muridae/mouse.(*Mouse).Live in D:\code\goproject\src\go-pprof-practice\animal\muridae\mouse\mouse.go
         0        1GB (flat, cum) 99.90% of Total
         .          .     18:   m.Eat()
         .          .     19:   m.Drink()
         .          .     20:   m.Shit()
         .          .     21:   m.Pee()
         .          .     22:   m.Hole()
         .        1GB     23:   m.Steal()
         .          .     24:}
         .          .     25:
         .          .     26:func (m *Mouse) Eat() {
         .          .     27:   log.Println(m.Name(), "eat")
         .          .     28:}
(pprof) list Steal
Total: 1GB
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/muridae/mouse.(*Mouse).Steal in D:\code\goproject\src\go-pprof-practice\animal\muridae\mouse\mouse.go
       1GB        1GB (flat, cum) 99.90% of Total
         .          .     45:
         .          .     46:func (m *Mouse) Steal() {
         .          .     47:   log.Println(m.Name(), "steal")
         .          .     48:   max := constant.Gi
         .          .     49:   for len(m.buffer)*constant.Mi < max {
       1GB        1GB     50:           m.buffer = append(m.buffer, [constant.Mi]byte{})
         .          .     51:   }
         .          .     52:}
(pprof) exit

C:\Users\90760>go tool pprof http://localhost:6060/debug/pprof/profile?seconds=60
Fetching profile over HTTP from http://localhost:6060/debug/pprof/profile?seconds=60
Saved profile in C:\Users\90760\pprof\pprof.samples.cpu.001.pb.gz
Type: cpu
Time: Oct 29, 2022 at 5:14pm (CST)
Duration: 60.11s, Total samples = 18.24s (30.34%)
Entering interactive mode (type "help" for commands, "o" for options)
(pprof) top
Showing nodes accounting for 18.05s, 98.96% of 18.24s total
Dropped 64 nodes (cum <= 0.09s)
      flat  flat%   sum%        cum   cum%
    18.05s 98.96% 98.96%     18.08s 99.12%  github.com/wolfogre/go-pprof-practice/animal/felidae/tiger.(*Tiger).Eat
         0     0% 98.96%     18.08s 99.12%  github.com/wolfogre/go-pprof-practice/animal/felidae/tiger.(*Tiger).Live
         0     0% 98.96%     18.18s 99.67%  main.main
         0     0% 98.96%     18.18s 99.67%  runtime.main
(pprof) list Eat
Total: 18.24s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/dog.(*Dog).Eat in D:\code\goproject\src\go-pprof-practice\animal\canidae\dog\dog.go
         0       30ms (flat, cum)  0.16% of Total
         .          .     21:   d.Run()
         .          .     22:   d.Howl()
         .          .     23:}
         .          .     24:
         .          .     25:func (d *Dog) Eat() {
         .       30ms     26:   log.Println(d.Name(), "eat")
         .          .     27:}
         .          .     28:
         .          .     29:func (d *Dog) Drink() {
         .          .     30:   log.Println(d.Name(), "drink")
         .          .     31:}
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Eat in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0       10ms (flat, cum) 0.055% of Total
         .          .     20:   c.Climb()
         .          .     21:   c.Sneak()
         .          .     22:}
         .          .     23:
         .          .     24:func (c *Cat) Eat() {
         .       10ms     25:   log.Println(c.Name(), "eat")
         .          .     26:}
         .          .     27:
         .          .     28:func (c *Cat) Drink() {
         .          .     29:   log.Println(c.Name(), "drink")
         .          .     30:}
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/tiger.(*Tiger).Eat in D:\code\goproject\src\go-pprof-practice\animal\felidae\tiger\tiger.go
    18.05s     18.08s (flat, cum) 99.12% of Total
         .          .     19:}
         .          .     20:
         .          .     21:func (t *Tiger) Eat() {
         .          .     22:   log.Println(t.Name(), "eat")
         .          .     23:   loop := 10000000000
    18.05s     18.08s     24:   for i := 0; i < loop; i++ {
         .          .     25:           // do nothing
         .          .     26:   }
         .          .     27:}
         .          .     28:
         .          .     29:func (t *Tiger) Drink() {
(pprof) web
failed to execute dot. Is Graphviz installed? Error: exec: "dot": executable file not found in %PATH%
(pprof) top
Showing nodes accounting for 18.05s, 98.96% of 18.24s total
Dropped 64 nodes (cum <= 0.09s)
      flat  flat%   sum%        cum   cum%
    18.05s 98.96% 98.96%     18.08s 99.12%  github.com/wolfogre/go-pprof-practice/animal/felidae/tiger.(*Tiger).Eat
         0     0% 98.96%     18.08s 99.12%  github.com/wolfogre/go-pprof-practice/animal/felidae/tiger.(*Tiger).Live
         0     0% 98.96%     18.18s 99.67%  main.main
         0     0% 98.96%     18.18s 99.67%  runtime.main
(pprof) web
failed to execute dot. Is Graphviz installed? Error: exec: "dot": executable file not found in %PATH%
(pprof)


Microsoft Windows [版本 10.0.22000.978]
(c) Microsoft Corporation。保留所有权利。

C:\Users\90760>go tool pprof http://localhost:6060/debug/pprof/block
Fetching profile over HTTP from http://localhost:6060/debug/pprof/block
Saved profile in C:\Users\90760\pprof\pprof.contentions.delay.001.pb.gz
Type: delay
Time: Oct 29, 2022 at 4:55pm (CST)
Entering interactive mode (type "help" for commands, "o" for options)
(pprof) top
Showing nodes accounting for 643.37s, 100% of 643.39s total
Dropped 4 nodes (cum <= 3.22s)
Showing top 10 nodes out of 15
      flat  flat%   sum%        cum   cum%
   299.42s 46.54% 46.54%    299.42s 46.54%  runtime.chanrecv1
   298.85s 46.45% 92.99%    298.85s 46.45%  sync.(*Mutex).Lock (inline)
    45.10s  7.01%   100%     45.10s  7.01%  runtime.selectgo
         0     0%   100%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Howl
         0     0%   100%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live
         0     0%   100%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live
         0     0%   100%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee
         0     0%   100%    597.90s 92.93%  main.main
         0     0%   100%     45.47s  7.07%  net/http.(*ServeMux).ServeHTTP
         0     0%   100%     45.49s  7.07%  net/http.(*conn).serve
(pprof) top live
Focus expression matched no samples
Active filters:
   focus=live
Showing nodes accounting for 0, 0% of 643.39s total
      flat  flat%   sum%        cum   cum%
(pprof) list cat
Total: 643.39s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     14:
         .          .     15:func (c *Cat) Live() {
         .          .     16:   c.Eat()
         .          .     17:   c.Drink()
         .          .     18:   c.Shit()
         .    299.05s     19:   c.Pee()
         .          .     20:   c.Climb()
         .          .     21:   c.Sneak()
         .          .     22:}
         .          .     23:
         .          .     24:func (c *Cat) Eat() {
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     34:}
         .          .     35:
         .          .     36:func (c *Cat) Pee() {
         .          .     37:   log.Println(c.Name(), "pee")
         .          .     38:
         .    299.05s     39:   <-time.After(time.Second)
         .          .     40:}
         .          .     41:
         .          .     42:func (c *Cat) Climb() {
         .          .     43:   log.Println(c.Name(), "climb")
         .          .     44:}
(pprof) list wolf
Total: 643.39s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Howl in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0    298.85s (flat, cum) 46.45% of Total
         .          .     55:   m.Lock()
         .          .     56:   go func() {
         .          .     57:           time.Sleep(time.Second)
         .          .     58:           m.Unlock()
         .          .     59:   }()
         .    298.85s     60:   m.Lock()
         .          .     61:}
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0    298.85s (flat, cum) 46.45% of Total
         .          .     18:   w.Eat()
         .          .     19:   w.Drink()
         .          .     20:   w.Shit()
         .          .     21:   w.Pee()
         .          .     22:   w.Run()
         .    298.85s     23:   w.Howl()
         .          .     24:}
         .          .     25:
         .          .     26:func (w *Wolf) Eat() {
         .          .     27:   log.Println(w.Name(), "eat")
         .          .     28:}
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     14:
         .          .     15:func (c *Cat) Live() {
         .          .     16:   c.Eat()
         .          .     17:   c.Drink()
         .          .     18:   c.Shit()
         .    299.05s     19:   c.Pee()
         .          .     20:   c.Climb()
         .          .     21:   c.Sneak()
         .          .     22:}
         .          .     23:
         .          .     24:func (c *Cat) Eat() {
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     34:}
         .          .     35:
         .          .     36:func (c *Cat) Pee() {
         .          .     37:   log.Println(c.Name(), "pee")
         .          .     38:
         .    299.05s     39:   <-time.After(time.Second)
         .          .     40:}
         .          .     41:
         .          .     42:func (c *Cat) Climb() {
         .          .     43:   log.Println(c.Name(), "climb")
         .          .     44:}
(pprof) top live
Focus expression matched no samples
Active filters:
   focus=live
Showing nodes accounting for 0, 0% of 643.39s total
      flat  flat%   sum%        cum   cum%
(pprof) list live
Total: 643.39s
(pprof) list howl
Total: 643.39s
(pprof) list Live
Total: 643.39s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0    298.85s (flat, cum) 46.45% of Total
         .          .     18:   w.Eat()
         .          .     19:   w.Drink()
         .          .     20:   w.Shit()
         .          .     21:   w.Pee()
         .          .     22:   w.Run()
         .    298.85s     23:   w.Howl()
         .          .     24:}
         .          .     25:
         .          .     26:func (w *Wolf) Eat() {
         .          .     27:   log.Println(w.Name(), "eat")
         .          .     28:}
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     14:
         .          .     15:func (c *Cat) Live() {
         .          .     16:   c.Eat()
         .          .     17:   c.Drink()
         .          .     18:   c.Shit()
         .    299.05s     19:   c.Pee()
         .          .     20:   c.Climb()
         .          .     21:   c.Sneak()
         .          .     22:}
         .          .     23:
         .          .     24:func (c *Cat) Eat() {
(pprof) top
Showing nodes accounting for 643.37s, 100% of 643.39s total
Dropped 4 nodes (cum <= 3.22s)
Showing top 10 nodes out of 15
      flat  flat%   sum%        cum   cum%
   299.42s 46.54% 46.54%    299.42s 46.54%  runtime.chanrecv1
   298.85s 46.45% 92.99%    298.85s 46.45%  sync.(*Mutex).Lock (inline)
    45.10s  7.01%   100%     45.10s  7.01%  runtime.selectgo
         0     0%   100%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Howl
         0     0%   100%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live
         0     0%   100%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live
         0     0%   100%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee
         0     0%   100%    597.90s 92.93%  main.main
         0     0%   100%     45.47s  7.07%  net/http.(*ServeMux).ServeHTTP
         0     0%   100%     45.49s  7.07%  net/http.(*conn).serve
(pprof) top Live
Active filters:
   focus=Live
Showing nodes accounting for 597.90s, 92.93% of 643.39s total
      flat  flat%   sum%        cum   cum%
   299.05s 46.48% 46.48%    299.05s 46.48%  runtime.chanrecv1
   298.85s 46.45% 92.93%    298.85s 46.45%  sync.(*Mutex).Lock (inline)
         0     0% 92.93%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Howl
         0     0% 92.93%    298.85s 46.45%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live
         0     0% 92.93%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live
         0     0% 92.93%    299.05s 46.48%  github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Pee
         0     0% 92.93%    597.90s 92.93%  main.main
         0     0% 92.93%    597.90s 92.93%  runtime.main
(pprof) list Live
Total: 643.39s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Live in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0    298.85s (flat, cum) 46.45% of Total
         .          .     18:   w.Eat()
         .          .     19:   w.Drink()
         .          .     20:   w.Shit()
         .          .     21:   w.Pee()
         .          .     22:   w.Run()
         .    298.85s     23:   w.Howl()
         .          .     24:}
         .          .     25:
         .          .     26:func (w *Wolf) Eat() {
         .          .     27:   log.Println(w.Name(), "eat")
         .          .     28:}
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Live in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0    299.05s (flat, cum) 46.48% of Total
         .          .     14:
         .          .     15:func (c *Cat) Live() {
         .          .     16:   c.Eat()
         .          .     17:   c.Drink()
         .          .     18:   c.Shit()
         .    299.05s     19:   c.Pee()
         .          .     20:   c.Climb()
         .          .     21:   c.Sneak()
         .          .     22:}
         .          .     23:
         .          .     24:func (c *Cat) Eat() {
(pprof) list Howl
Total: 643.39s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Howl in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0    298.85s (flat, cum) 46.45% of Total
         .          .     55:   m.Lock()
         .          .     56:   go func() {
         .          .     57:           time.Sleep(time.Second)
         .          .     58:           m.Unlock()
         .          .     59:   }()
         .    298.85s     60:   m.Lock()
         .          .     61:}
(pprof) exit

C:\Users\90760>go tool pprof http://localhost:6060/debug/pprof/goroutine
Fetching profile over HTTP from http://localhost:6060/debug/pprof/goroutine
Saved profile in C:\Users\90760\pprof\pprof.goroutine.001.pb.gz
Type: goroutine
Time: Oct 29, 2022 at 5:08pm (CST)
Entering interactive mode (type "help" for commands, "o" for options)
(pprof) top
Showing nodes accounting for 53, 98.15% of 54 total
Showing top 10 nodes out of 32
      flat  flat%   sum%        cum   cum%
        51 94.44% 94.44%         51 94.44%  runtime.gopark
         1  1.85% 96.30%          1  1.85%  runtime.asyncPreempt2
         1  1.85% 98.15%          1  1.85%  runtime/pprof.runtime_goroutineProfileWithLabels
         0     0% 98.15%         50 92.59%  github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Drink.func1
         0     0% 98.15%          1  1.85%  github.com/wolfogre/go-pprof-practice/animal/felidae/tiger.(*Tiger).Eat
         0     0% 98.15%          1  1.85%  github.com/wolfogre/go-pprof-practice/animal/felidae/tiger.(*Tiger).Live
         0     0% 98.15%          1  1.85%  internal/poll.(*FD).Accept
         0     0% 98.15%          1  1.85%  internal/poll.(*FD).acceptOne
         0     0% 98.15%          1  1.85%  internal/poll.(*pollDesc).wait
         0     0% 98.15%          1  1.85%  internal/poll.execIO
(pprof) list Drink
Total: 54
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/wolf.(*Wolf).Drink.func1 in D:\code\goproject\src\go-pprof-practice\animal\canidae\wolf\wolf.go
         0         50 (flat, cum) 92.59% of Total
         .          .     29:
         .          .     30:func (w *Wolf) Drink() {
         .          .     31:   log.Println(w.Name(), "drink")
         .          .     32:   for i := 0; i < 10; i++ {
         .          .     33:           go func() {
         .         50     34:                   time.Sleep(30 * time.Second)
         .          .     35:           }()
         .          .     36:   }
         .          .     37:}
         .          .     38:
         .          .     39:func (w *Wolf) Shit() {
(pprof) exit

C:\Users\90760>go tool pprof http://localhost:6060/debug/pprof/heap
Fetching profile over HTTP from http://localhost:6060/debug/pprof/heap
Saved profile in C:\Users\90760\pprof\pprof.alloc_objects.alloc_space.inuse_objects.inuse_space.002.pb.gz
Type: inuse_space
Time: Oct 29, 2022 at 5:11pm (CST)
Entering interactive mode (type "help" for commands, "o" for options)
(pprof) top
Showing nodes accounting for 1GB, 99.90% of 1GB total
Dropped 13 nodes (cum <= 0.01GB)
      flat  flat%   sum%        cum   cum%
       1GB 99.90% 99.90%        1GB 99.90%  github.com/wolfogre/go-pprof-practice/animal/muridae/mouse.(*Mouse).Steal
         0     0% 99.90%        1GB 99.90%  github.com/wolfogre/go-pprof-practice/animal/muridae/mouse.(*Mouse).Live
         0     0% 99.90%        1GB 99.90%  main.main
         0     0% 99.90%        1GB 99.90%  runtime.main
(pprof) list Steal
Total: 1GB
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/muridae/mouse.(*Mouse).Steal in D:\code\goproject\src\go-pprof-practice\animal\muridae\mouse\mouse.go
       1GB        1GB (flat, cum) 99.90% of Total
         .          .     45:
         .          .     46:func (m *Mouse) Steal() {
         .          .     47:   log.Println(m.Name(), "steal")
         .          .     48:   max := constant.Gi
         .          .     49:   for len(m.buffer)*constant.Mi < max {
       1GB        1GB     50:           m.buffer = append(m.buffer, [constant.Mi]byte{})
         .          .     51:   }
         .          .     52:}
(pprof) list Live
Total: 1GB
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/muridae/mouse.(*Mouse).Live in D:\code\goproject\src\go-pprof-practice\animal\muridae\mouse\mouse.go
         0        1GB (flat, cum) 99.90% of Total
         .          .     18:   m.Eat()
         .          .     19:   m.Drink()
         .          .     20:   m.Shit()
         .          .     21:   m.Pee()
         .          .     22:   m.Hole()
         .        1GB     23:   m.Steal()
         .          .     24:}
         .          .     25:
         .          .     26:func (m *Mouse) Eat() {
         .          .     27:   log.Println(m.Name(), "eat")
         .          .     28:}
(pprof) list Steal
Total: 1GB
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/muridae/mouse.(*Mouse).Steal in D:\code\goproject\src\go-pprof-practice\animal\muridae\mouse\mouse.go
       1GB        1GB (flat, cum) 99.90% of Total
         .          .     45:
         .          .     46:func (m *Mouse) Steal() {
         .          .     47:   log.Println(m.Name(), "steal")
         .          .     48:   max := constant.Gi
         .          .     49:   for len(m.buffer)*constant.Mi < max {
       1GB        1GB     50:           m.buffer = append(m.buffer, [constant.Mi]byte{})
         .          .     51:   }
         .          .     52:}
(pprof) exit

C:\Users\90760>go tool pprof http://localhost:6060/debug/pprof/profile?seconds=60
Fetching profile over HTTP from http://localhost:6060/debug/pprof/profile?seconds=60
Saved profile in C:\Users\90760\pprof\pprof.samples.cpu.001.pb.gz
Type: cpu
Time: Oct 29, 2022 at 5:14pm (CST)
Duration: 60.11s, Total samples = 18.24s (30.34%)
Entering interactive mode (type "help" for commands, "o" for options)
(pprof) top
Showing nodes accounting for 18.05s, 98.96% of 18.24s total
Dropped 64 nodes (cum <= 0.09s)
      flat  flat%   sum%        cum   cum%
    18.05s 98.96% 98.96%     18.08s 99.12%  github.com/wolfogre/go-pprof-practice/animal/felidae/tiger.(*Tiger).Eat
         0     0% 98.96%     18.08s 99.12%  github.com/wolfogre/go-pprof-practice/animal/felidae/tiger.(*Tiger).Live
         0     0% 98.96%     18.18s 99.67%  main.main
         0     0% 98.96%     18.18s 99.67%  runtime.main
(pprof) list Eat
Total: 18.24s
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/canidae/dog.(*Dog).Eat in D:\code\goproject\src\go-pprof-practice\animal\canidae\dog\dog.go
         0       30ms (flat, cum)  0.16% of Total
         .          .     21:   d.Run()
         .          .     22:   d.Howl()
         .          .     23:}
         .          .     24:
         .          .     25:func (d *Dog) Eat() {
         .       30ms     26:   log.Println(d.Name(), "eat")
         .          .     27:}
         .          .     28:
         .          .     29:func (d *Dog) Drink() {
         .          .     30:   log.Println(d.Name(), "drink")
         .          .     31:}
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/cat.(*Cat).Eat in D:\code\goproject\src\go-pprof-practice\animal\felidae\cat\cat.go
         0       10ms (flat, cum) 0.055% of Total
         .          .     20:   c.Climb()
         .          .     21:   c.Sneak()
         .          .     22:}
         .          .     23:
         .          .     24:func (c *Cat) Eat() {
         .       10ms     25:   log.Println(c.Name(), "eat")
         .          .     26:}
         .          .     27:
         .          .     28:func (c *Cat) Drink() {
         .          .     29:   log.Println(c.Name(), "drink")
         .          .     30:}
ROUTINE ======================== github.com/wolfogre/go-pprof-practice/animal/felidae/tiger.(*Tiger).Eat in D:\code\goproject\src\go-pprof-practice\animal\felidae\tiger\tiger.go
    18.05s     18.08s (flat, cum) 99.12% of Total
         .          .     19:}
         .          .     20:
         .          .     21:func (t *Tiger) Eat() {
         .          .     22:   log.Println(t.Name(), "eat")
         .          .     23:   loop := 10000000000
    18.05s     18.08s     24:   for i := 0; i < loop; i++ {
         .          .     25:           // do nothing
         .          .     26:   }
         .          .     27:}
         .          .     28:
         .          .     29:func (t *Tiger) Drink() {
(pprof) web
failed to execute dot. Is Graphviz installed? Error: exec: "dot": executable file not found in %PATH%
(pprof) top
Showing nodes accounting for 18.05s, 98.96% of 18.24s total
Dropped 64 nodes (cum <= 0.09s)
      flat  flat%   sum%        cum   cum%
    18.05s 98.96% 98.96%     18.08s 99.12%  github.com/wolfogre/go-pprof-practice/animal/felidae/tiger.(*Tiger).Eat
         0     0% 98.96%     18.08s 99.12%  github.com/wolfogre/go-pprof-practice/animal/felidae/tiger.(*Tiger).Live
         0     0% 98.96%     18.18s 99.67%  main.main
         0     0% 98.96%     18.18s 99.67%  runtime.main
(pprof) web
failed to execute dot. Is Graphviz installed? Error: exec: "dot": executable file not found in %PATH%
(pprof) exit

C:\Users\90760>go tool pprof http://localhost:6060/debug/pprof/threadcreate
Fetching profile over HTTP from http://localhost:6060/debug/pprof/threadcreate
Saved profile in C:\Users\90760\pprof\pprof.threadcreate.001.pb.gz
Type: threadcreate
Time: Oct 29, 2022 at 5:24pm (CST)
Entering interactive mode (type "help" for commands, "o" for options)
(pprof) top
Showing nodes accounting for 1, 14.29% of 7 total
Showing top 10 nodes out of 11
      flat  flat%   sum%        cum   cum%
         1 14.29% 14.29%          1 14.29%  runtime.allocm
         0     0% 14.29%          1 14.29%  net/http.(*ServeMux).ServeHTTP
         0     0% 14.29%          1 14.29%  net/http.(*conn).serve
         0     0% 14.29%          1 14.29%  net/http.HandlerFunc.ServeHTTP
         0     0% 14.29%          1 14.29%  net/http.serverHandler.ServeHTTP
         0     0% 14.29%          1 14.29%  net/http/pprof.Profile
         0     0% 14.29%          1 14.29%  runtime.SetCPUProfileRate
         0     0% 14.29%          1 14.29%  runtime.newm
         0     0% 14.29%          1 14.29%  runtime.setProcessCPUProfiler
         0     0% 14.29%          1 14.29%  runtime.setcpuprofilerate
(pprof) list
command list requires an argument
(pprof) exit

C:\Users\90760>go tool pprof -http=:8000 http://localhost:6060/debug/pprof/profile
Fetching profile over HTTP from http://localhost:6060/debug/pprof/profile
Saved profile in C:\Users\90760\pprof\pprof.samples.cpu.003.pb.gz
Serving web UI on http://localhost:8000
Failed to execute dot. Is Graphviz installed?
exec: "dot": executable file not found in %PATH%

需要安装Graphviz并且添加环境变量

下载地址:https://www2.graphviz.org/Packages/stable/windows/10/cmake/Release/x64/

添加环境变量:

 

 最终的图形化界面:

 

评论
添加红包

请填写红包祝福语或标题

红包个数最小为10个

红包金额最低5元

当前余额3.43前往充值 >
需支付:10.00
成就一亿技术人!
领取后你会自动成为博主和红包主的粉丝 规则
hope_wisdom
发出的红包
实付
使用余额支付
点击重新获取
扫码支付
钱包余额 0

抵扣说明:

1.余额是钱包充值的虚拟货币,按照1:1的比例进行支付金额的抵扣。
2.余额无法直接购买下载,可以购买VIP、付费专栏及课程。

余额充值