Re: scheduling anomaly on uml (was: -rt doesn't compile for UML)

From: Ingo Molnar
Date: Thu Nov 29 2007 - 12:17:20 EST



* Jeff Dike <jdike@xxxxxxxxxxx> wrote:

> On Thu, Nov 29, 2007 at 11:19:40AM +0100, Miklos Szeredi wrote:
> > date-7119 0.... 15636591us!: schedule <bash-502> (0 0)
> > bash-502 0.... 15643908us!: schedule <date-7119> (0 0)
> > bash-502 0.... 15646250us!: schedule <date-7120> (0 0)
>
> How exactly did you end up getting this data?
>
> And is there something I can read to tell what it means?

the header of /proc/latency_trace explains the format:

_------=> CPU#
/ _-----=> irqs-off
| / _----=> need-resched
|| / _---=> hardirq/softirq
||| / _--=> preempt-depth
|||| /
||||| delay
cmd pid ||||| time | caller
\ / ||||| \ | /
privoxy-12926 1.Ns1 0us : ktime_get_ts (ktime_get)

'time' is timestamp in microseconds. Then come the caller (and parent,
or other, special parameters like the task-pid of the scheduled task).
It's supposed to be easy to read to kernel hackers - let me know if any
of the details is non-obvious.

for example:

> > date-7119 0.... 15636591us!: schedule <bash-502> (0 0)

the task 'date' (pid 7119) scheduled at timestamp 15636591us, and
switched to another task 'bash' (pid 502). Both had a default nice level
of 0 [the (0 0) arguments].

Ingo
-
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
Please read the FAQ at http://www.tux.org/lkml/