Skip to content

Instantly share code, notes, and snippets.

@mendsley
Created June 30, 2016 00:10
Show Gist options
  • Save mendsley/4b56932056d5c231a94d1cdefd15c027 to your computer and use it in GitHub Desktop.
Save mendsley/4b56932056d5c231a94d1cdefd15c027 to your computer and use it in GitHub Desktop.
$ strace -cT time premake5 gmake
[Premake MSVC Windows XP support Extension loaded]
Building configurations...
Running action 'gmake'...
Done (20729ms).
PROFILE: interrupts/evictions/bytes = 1253/68/34464
% time seconds usecs/call calls errors syscall
------ ----------- ----------- --------- --------- ----------------
52.14 0.769342 20 39177 199 stat
46.99 0.693356 38 18046 120 openat
0.24 0.003600 0 69713 rt_sigprocmask
0.23 0.003465 0 35852 getdents
0.19 0.002751 0 41442 getcwd
0.10 0.001542 0 18034 close
0.08 0.001206 7 176 chdir
0.01 0.000188 1 138 fstat
0.01 0.000103 0 1253 2 rt_sigreturn
0.00 0.000069 0 646 read
0.00 0.000000 0 80 write
0.00 0.000000 0 180 72 open
0.00 0.000000 0 70 lseek
0.00 0.000000 0 134 mmap
0.00 0.000000 0 27 mprotect
0.00 0.000000 0 89 munmap
0.00 0.000000 0 204 brk
0.00 0.000000 0 8 rt_sigaction
0.00 0.000000 0 15 15 access
0.00 0.000000 0 210 1 msync
0.00 0.000000 0 1 1 mincore
0.00 0.000000 0 11011 madvise
0.00 0.000000 0 1 setitimer
0.00 0.000000 0 1 execve
0.00 0.000000 0 1 readlink
0.00 0.000000 0 1 getrlimit
0.00 0.000000 0 1 getuid
0.00 0.000000 0 1 geteuid
0.00 0.000000 0 1 arch_prctl
0.00 0.000000 0 6 futex
0.00 0.000000 0 1 set_tid_address
0.00 0.000000 0 2 clock_gettime
0.00 0.000000 0 1 set_robust_list
------ ----------- ----------- --------- --------- ----------------
100.00 1.475622 236523 410 total
6.40user 19.34system 0:41.14elapsed 62%CPU (0avgtext+0avgdata 215260maxresident)k
0inputs+0outputs (0major+54051minor)pagefaults 0swaps
$ google-pprof premake5 cpuprofile
(pprof) top10
Total: 1253 samples
337 26.9% 26.9% 337 26.9% __openat_nocancel
285 22.7% 49.6% 285 22.7% __GI___xstat
226 18.0% 67.7% 226 18.0% __getcwd
99 7.9% 75.6% 99 7.9% __getdents64
73 5.8% 81.4% 124 9.9% luaopen_string
53 4.2% 85.6% 1219 97.3% luaopen_table
37 3.0% 88.6% 37 3.0% close
28 2.2% 90.8% 28 2.2% madvise
17 1.4% 92.2% 46 3.7% lua_close
16 1.3% 93.5% 16 1.3% __sigprocmask
$ strace -cT time premake5 gmake
[Premake MSVC Windows XP support Extension loaded]
Building configurations...
Running action 'gmake'...
Done (59977ms).
PROFILE: interrupts/evictions/bytes = 2633/70/31320
% time seconds usecs/call calls errors syscall
------ ----------- ----------- --------- --------- ----------------
37.58 23658.568886 118865 199037 199 stat
28.66 18044.878481 136275 132415 rt_sigprocmask
16.49 10384.509953 250579 41442 getcwd
8.90 5603.682926 156300 35852 getdents
5.46 3439.671817 190606 18046 120 openat
4.53 2851.440298 158115 18034 close
0.61 381.913129 145049 2633 5 rt_sigreturn
0.21 129.090893 733471 176 chdir
0.16 99.018898 550105 180 72 open
0.11 66.195452 493996 134 mmap
0.06 37.040064 268406 138 fstat
0.06 35.074538 254163 138 msync
0.05 33.189392 372915 89 munmap
0.03 19.803636 733468 27 mprotect
0.02 14.631744 71724 204 brk
0.02 11.002020 733468 15 15 access
0.01 8.067640 12489 646 read
0.01 4.950908 618864 8 rt_sigaction
0.01 4.400808 733468 6 futex
0.00 0.733468 733468 1 1 mincore
0.00 0.733468 733468 1 setitimer
0.00 0.733468 733468 1 execve
0.00 0.733468 733468 1 readlink
0.00 0.733468 733468 1 getrlimit
0.00 0.733468 733468 1 getuid
0.00 0.733468 733468 1 geteuid
0.00 0.733468 733468 1 arch_prctl
0.00 0.733468 733468 1 set_tid_address
0.00 0.733468 733468 1 set_robust_list
0.00 0.550100 275050 2 clock_gettime
-0.02 -11.185464 18446744073709413525 81 1 write
-0.02 -12.835760 18446744073709368249 70 lseek
-2.94 -1853.872509 18446744073709385365 11151 madvise
------ ----------- ----------- --------- --------- ----------------
100.00 62957.122530 460534 413 total
11.28user 62.26system 2:09.70elapsed 56%CPU (0avgtext+0avgdata 216668maxresident)k
0inputs+0outputs (0major+53278minor)pagefaults 0swaps
$ google-pprof premake5 cpuprofile
(pprof) top10
Total: 2633 samples
1645 62.5% 62.5% 1645 62.5% __GI___xstat
359 13.6% 76.1% 359 13.6% __openat_nocancel
276 10.5% 86.6% 276 10.5% __getcwd
81 3.1% 89.7% 81 3.1% __getdents64
68 2.6% 92.3% 101 3.8% luaopen_string
46 1.7% 94.0% 2602 98.8% luaopen_table
36 1.4% 95.4% 36 1.4% close
27 1.0% 96.4% 27 1.0% madvise
15 0.6% 97.0% 2628 99.8% lua_yield
12 0.5% 97.4% 40 1.5% lua_close
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment