ktracedump.1m (2010 09)

k
ktracedump(1M) ktracedump(1M)
corroborating that one hundredth of a second elapses between each hardclock call on each hyperthreaded
CPU.
Sample Invocation and Output
# ktracedump_opts=’-j0 -JZSeq -JCpu -JCaller%-11s -JFunction -JAbsSec
-JElUsec’;
# ktracer -Z -z -a per_spu_hardclock -D "${ktracedump_opts}" -g 5
++++++++++++++++++++++++++++++++
Spu 0; 501 traces; The columns mean:
column# Header - Description - Format
------- ------ - ---------------------------------------- - ------
1 ZSeq - Zero-based Sequence number - %6llu
2 Cpu - Processor Number - %3u
3 Caller - Caller function - %-11s
4 Function - Traced function (callee) - %-20s
5 AbsSec - Absolute Time in Seconds.NanoSec - %17.9lf
6 ElUSec - Elapsed Time in microseconds.ns - %10.3lf
ZSeq Cpu Caller Function AbsSec ElUSec
0 0 clock_int per_spu_hardclock 21566.530004843 0.000
32 0 clock_int per_spu_hardclock 21566.540004615 9999.773
64 0 clock_int per_spu_hardclock 21566.550003218 9998.603
96 0 clock_int per_spu_hardclock 21566.560005192 10001.975
128 0 clock_int per_spu_hardclock 21566.570002707 9997.515
... 496 more trace records like this for CPU 0 ...
++++++++++++++++++++++++++++++++
Spu 1; 501 traces; The columns mean:
column# Header - Description - Format
------- ------ - ---------------------------------------- - ------
1 ZSeq - Zero-based Sequence number - %6llu
2 Cpu - Processor Number - %3u
3 Caller - Caller function - %-11s
4 Function - Traced function (callee) - %-20s
5 AbsSec - Absolute Time in Seconds.NanoSec - %17.9lf
6 ElUSec - Elapsed Time in microseconds.ns - %10.3lf
ZSeq Cpu Caller Function AbsSec ElUSec
1 1 clock_int per_spu_hardclock 21566.530159777 0.000
33 1 clock_int per_spu_hardclock 21566.540159370 9999.593
65 1 clock_int per_spu_hardclock 21566.550159263 9999.892
97 1 clock_int per_spu_hardclock 21566.560159312 10000.050
129 1 clock_int per_spu_hardclock 21566.570159227 9999.915
... 496 more trace records like this for CPU 1 ...
The Meaning of Each Column
PID Process ID for the currently running process, if any. If an interrupt is currently being han-
dled, PID will indicate the ID for the process that was running when the lowest priority inter-
rupt came in. If no process is running, the PID column will show "-1" since 0 is a valid process
id.
TID Thread ID for the currently running thread, if any. If an interrupt is currently being handled,
TID will indicate the ID for the thread that was running when the lowest priority interrupt
came in. If no thread is running, the TID column will show "-1".
AbsTime Absolute Time (AbsTime) is a timestamp in units of iticks, based on the per-CPU interval
timer, and normalized to CPU 0.
AbsSec Absolute Time in Seconds (AbsSec) is computed as AbsTime / (itick_per_tick * HZ). where
itick_per_tick is a kernel global value, and HZ is 100 ticks per second.
6 Hewlett-Packard Company − 6 − HP-UX 11i Version 3: September 2010