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/thread-times.stp (1241B)

#!/usr/bin/stap

probe perf.sw.cpu_clock!, timer.profile {
  // NB: To avoid contention on SMP machines, no global scalars/arrays used,
  // only contention-free statistics aggregates.
  tid=tid(); e=execname()
  if (!user_mode())
    kticks[e,tid] <<< 1
  else
    uticks[e,tid] <<< 1
  ticks <<< 1
  tids[e,tid] <<< 1
}

global uticks%, kticks%, ticks

global tids%

probe timer.s(5), end {
  allticks = @count(ticks)
  printf ("%16s %5s %7s %7s (of %d ticks)\n",
          "comm", "tid", "%user", "%kernel", allticks)
  foreach ([e,tid] in tids- limit 20) {
    // SystemTap only performs integer arithmetic.
    // To avoid losing precision the decimal point is shifted
    // to the right four places (*100000). Think of this as
    // the original result value x.xxyy becoming xxxyy.0.
    // The integer percentage xxx is obtained
    // by dividing by 100 and the fractional percentage
    // is obtained with a modulo 100 operation.
    uscaled = @count(uticks[e,tid])*10000/allticks
    kscaled = @count(kticks[e,tid])*10000/allticks
    printf ("%16s %5d %3d.%02d%% %3d.%02d%%\n",
      e, tid, uscaled/100, uscaled%100, kscaled/100, kscaled%100)
  }
  printf("\n")

delete uticks
  delete kticks
  delete ticks
  delete tids
}

#!/usr/bin/stap probe perf.sw.cpu_clock!, timer.profile { // NB: To avoid contention on SMP machines, no global scalars/arrays used, // only contention-free statistics aggregates. tid=tid(); e=execname() if (!user_mode()) kticks[e,tid] <<< 1 else uticks[e,tid] <<< 1 ticks <<< 1 tids[e,tid] <<< 1 } global uticks%, kticks%, ticks global tids% probe timer.s(5), end { allticks = @count(ticks) printf ("%16s %5s %7s %7s (of %d ticks)\n", "comm", "tid", "%user", "%kernel", allticks) foreach ([e,tid] in tids- limit 20) { // SystemTap only performs integer arithmetic. // To avoid losing precision the decimal point is shifted // to the right four places (*100000). Think of this as // the original result value x.xxyy becoming xxxyy.0. // The integer percentage xxx is obtained // by dividing by 100 and the fractional percentage // is obtained with a modulo 100 operation. uscaled = @count(uticks[e,tid])*10000/allticks kscaled = @count(kticks[e,tid])*10000/allticks printf ("%16s %5d %3d.%02d%% %3d.%02d%%\n", e, tid, uscaled/100, uscaled%100, kscaled/100, kscaled%100) } printf("\n") delete uticks delete kticks delete ticks delete tids }