FM / profiling

/usr/share/systemtap/examples/profiling/

/usr/share/systemtap/examples/profiling

Name	Size	Mode	Actions
container_check.meta	1064	0644	edit dl rm
container_check.stp	6323	0755	edit dl rm
errno.meta	456	0644	edit dl rm
errno.stp	984	0755	edit dl rm
fileline-profile.meta	783	0644	edit dl rm
fileline-profile.stp	1218	0755	edit dl rm
fntimes.meta	512	0644	edit dl rm
fntimes.stp	870	0755	edit dl rm
fntimes.txt	1375	0644	edit dl rm
functioncallcount.meta	649	0644	edit dl rm
functioncallcount.stp	490	0755	edit dl rm
functioncallcount.tcl	399	0644	edit dl rm
graphcall.stp	534	0755	edit dl rm
ioctl_handler.meta	1209	0644	edit dl rm
ioctl_handler.stp	811	0755	edit dl rm
latencytap.meta	877	0644	edit dl rm
latencytap.stp	12755	0755	edit dl rm
latencytap.tcl	140	0644	edit dl rm
latencytap.txt	2104	0644	edit dl rm
linetimes.meta	688	0644	edit dl rm
linetimes.stp	1608	0755	edit dl rm
linetimes.txt	13936	0644	edit dl rm
perf.meta	578	0644	edit dl rm
perf.stp	1936	0755	edit dl rm
perf.tcl	166	0644	edit dl rm
periodic.meta	895	0644	edit dl rm
periodic.stp	2659	0755	edit dl rm
pf2.meta	420	0644	edit dl rm
pf2.stp	370	0755	edit dl rm
pf2.txt	406	0644	edit dl rm
pf3.meta	439	0644	edit dl rm
pf3.stp	992	0755	edit dl rm
pf3.txt	489	0644	edit dl rm
pf4.meta	471	0644	edit dl rm
pf4.stp	952	0755	edit dl rm
pf4.tcl	79	0644	edit dl rm
pf4.txt	754	0644	edit dl rm
sched_switch.meta	780	0644	edit dl rm
sched_switch.stp	2127	0755	edit dl rm
syscallerrorsbypid.meta	1067	0644	edit dl rm
syscallerrorsbypid.stp	950	0755	edit dl rm
syscalllatency.meta	1072	0644	edit dl rm
syscalllatency.stp	989	0755	edit dl rm
syscallsbypid.meta	1020	0644	edit dl rm
syscallsbypid.stp	785	0755	edit dl rm
syscallsrw.meta	489	0644	edit dl rm
syscallsrw.stp	375	0755	edit dl rm
thread-times.meta	384	0644	edit dl rm
thread-times.stp	1241	0755	edit dl rm
thread-times.txt	1009	0644	edit dl rm
timeout.meta	728	0644	edit dl rm
timeout.stp	2775	0755	edit dl rm
topsys.meta	446	0644	edit dl rm
topsys.stp	745	0755	edit dl rm
ucalls.meta	1065	0644	edit dl rm
ucalls.stp	3544	0755	edit dl rm

Edit: /usr/share/systemtap/examples/profiling/fntimes.txt (1375B)

fntimes.stp - Show Functions Taking Longer Than Usual

The fntimes.stp script monitors the execution time history of a given
  function family (assumed non-recursive).  Each time (beyond a warmup
  interval) is then compared to the historical maximum.  If it exceeds
  a certain threshold (250%), a message is printed.

$ stap fntimes.stp 'kernel.function("sys_*")' -T 7

function SyS_ioctl well over maximum time (32 vs 12)
function SyS_open well over maximum time (2832 vs 188)
function SyS_newfstat well over maximum time (5 vs 2)
function SyS_mmap_pgoff well over maximum time (282 vs 12)
function SyS_mmap well over maximum time (286 vs 15)
function SyS_access well over maximum time (35 vs 11)
function SyS_access well over maximum time (92 vs 35)
function SyS_mprotect well over maximum time (44 vs 13)
function SyS_futex well over maximum time (9753 vs 3260)
function SyS_newstat well over maximum time (4882 vs 133)
function SyS_recvmsg well over maximum time (607 vs 44)
function SyS_futex well over maximum time (55896 vs 9753)
function SyS_futex well over maximum time (500089 vs 55927)
function SyS_poll well over maximum time (832629 vs 53308)

Getting no output is not a bad thing; it means that means that functions
did not exceed the threshold.

This output was gathered by running ls repeatedly in another terminal
a few seconds after the script started.

fntimes.stp - Show Functions Taking Longer Than Usual The fntimes.stp script monitors the execution time history of a given function family (assumed non-recursive). Each time (beyond a warmup interval) is then compared to the historical maximum. If it exceeds a certain threshold (250%), a message is printed. $ stap fntimes.stp 'kernel.function("sys_*")' -T 7 function SyS_ioctl well over maximum time (32 vs 12) function SyS_open well over maximum time (2832 vs 188) function SyS_newfstat well over maximum time (5 vs 2) function SyS_mmap_pgoff well over maximum time (282 vs 12) function SyS_mmap well over maximum time (286 vs 15) function SyS_access well over maximum time (35 vs 11) function SyS_access well over maximum time (92 vs 35) function SyS_mprotect well over maximum time (44 vs 13) function SyS_futex well over maximum time (9753 vs 3260) function SyS_newstat well over maximum time (4882 vs 133) function SyS_recvmsg well over maximum time (607 vs 44) function SyS_futex well over maximum time (55896 vs 9753) function SyS_futex well over maximum time (500089 vs 55927) function SyS_poll well over maximum time (832629 vs 53308) Getting no output is not a bad thing; it means that means that functions did not exceed the threshold. This output was gathered by running ls repeatedly in another terminal a few seconds after the script started.