Re: rt20 patch question

[Steven Rostedt]

On Fri, 12 May 2006, Mark Hounschell wrote:

> Steven Rostedt wrote:
>  >
> > I was looking at the logdump, but I don't see anything spinning.  CPU 1
> > seems to be constantly running your v67 program (alternating with
> > posix_cpu_timer), and CPU: 0 is still switching with the swapper, along
> > with other tasks, so that this means nothing is just spinning and hogging
> > the CPU (on CPU 0, but I assume the v67 tasks is suppose to keep running).
> >
>
> Yes the v67 task is the CPU process. Could it also mean I just didn't
> get the logdump at the right time?
>


[  619.220396] CPU:0 (bash:7783) -->> (konsole:7763)
[  619.220558] CPU:0 (konsole:7763) -->> (swapper:0)
[  619.220706] CPU:1 (v67:11149) -->> (IRQ 161:11082)
[  619.220717] CPU:1 (IRQ 161:11082) -->> (v67:11149)
[  619.223111] CPU:0 (swapper:0) -->> (posix_cpu_timer:3)
[  619.223116] CPU:0 (posix_cpu_timer:3) -->> (softirq-timer/0:5)
[  619.223127] CPU:0 (softirq-timer/0:5) -->> (swapper:0)
[  619.223570] CPU:1 (v67:11149) -->> (posix_cpu_timer:14)
[  619.223573] CPU:1 (posix_cpu_timer:14) -->> (v67:11149)
[  619.227097] CPU:0 (swapper:0) -->> (posix_cpu_timer:3)
[  619.227099] CPU:0 (posix_cpu_timer:3) -->> (softirq-timer/0:5)
[  619.227102] CPU:0 (softirq-timer/0:5) -->> (swapper:0)
[  619.227566] CPU:1 (v67:11149) -->> (posix_cpu_timer:14)
[  619.227568] CPU:1 (posix_cpu_timer:14) -->> (v67:11149)

   ...

[  633.861475] CPU:1 (v67:11149) -->> (posix_cpu_timer:14)
[  633.861477] CPU:1 (posix_cpu_timer:14) -->> (v67:11149)
[  633.865001] CPU:0 (swapper:0) -->> (posix_cpu_timer:3)
[  633.865003] CPU:0 (posix_cpu_timer:3) -->> (softirq-timer/0:5)
[  633.865006] CPU:0 (softirq-timer/0:5) -->> (swapper:0)
[  633.865470] CPU:1 (v67:11149) -->> (posix_cpu_timer:14)
[  633.865473] CPU:1 (posix_cpu_timer:14) -->> (v67:11149)
[  633.866421] CPU:1 (v67:11149) -->> (IRQ 161:11082)
[  633.866430] CPU:1 (IRQ 161:11082) -->> (v67:11149)
[  633.868998] CPU:0 (swapper:0) -->> (posix_cpu_timer:3)
[  633.869000] CPU:0 (posix_cpu_timer:3) -->> (softirq-timer/0:5)
[  633.869002] CPU:0 (softirq-timer/0:5) -->> (swapper:0)
[  633.869467] CPU:1 (v67:11149) -->> (posix_cpu_timer:14)
[  633.869470] CPU:1 (posix_cpu_timer:14) -->> (v67:11149)
[  633.872993] CPU:0 (swapper:0) -->> (posix_cpu_timer:3)
[  633.872995] CPU:0 (posix_cpu_timer:3) -->> (softirq-timer/0:5)
[  633.872998] CPU:0 (softirq-timer/0:5) -->> (swapper:0)
[  633.873463] CPU:1 (v67:11149) -->> (posix_cpu_timer:14)
[  633.873465] CPU:1 (posix_cpu_timer:14) -->> (v67:11149)
[  633.874747] CPU:1 (v67:11149) -->> (IRQ 161:11082)
[  633.874756] CPU:1 (IRQ 161:11082) -->> (v67:11149)
[  633.876990] CPU:0 (swapper:0) -->> (posix_cpu_timer:3)
[  633.876992] CPU:0 (posix_cpu_timer:3) -->> (softirq-timer/0:5)
[  633.876996] CPU:0 (softirq-timer/0:5) -->> (kded:6119)
[  633.877030] CPU:0 (kded:6119) -->> (swapper:0)
[  633.877460] CPU:1 (v67:11149) -->> (posix_cpu_timer:14)
[  633.877462] CPU:1 (posix_cpu_timer:14) -->> (v67:11149)
[  633.878447] CPU:0 (swapper:0) -->> (IRQ 1:823)
[  633.878474] CPU:0 (IRQ 1:823) -->> (softirq-tasklet:9)
[  633.878478] CPU:0 (softirq-tasklet:9) -->> (events/0:24)
[  633.878488] CPU:0 (events/0:24) -->> (X:5513)
[  633.878627] CPU:0 (X:5513) -->> (konsole:7763)
[  633.878669] CPU:0 (konsole:7763) -->> (X:5513)
[  633.878683] CPU:0 (X:5513) -->> (konsole:7763)
[  633.879309] CPU:0 (konsole:7763) -->> (X:5513)
[  633.879415] CPU:0 (X:5513) -->> (konsole:7763)
[  633.879457] CPU:0 (konsole:7763) -->> (X:5513)
[  633.879463] CPU:0 (X:5513) -->> (konsole:7763)
[  633.879467] CPU:0 (konsole:7763) -->> (X:5513)
[  633.879553] CPU:0 (X:5513) -->> (kded:6119)
[  633.879651] CPU:0 (kded:6119) -->> (kwin:6135)
[  633.879711] CPU:0 (kwin:6135) -->> (kdesktop:6140)
[  633.879782] CPU:0 (kdesktop:6140) -->> (kicker:6142)
[  633.879858] CPU:0 (kicker:6142) -->> (X:5513)
[  633.879927] CPU:0 (X:5513) -->> (kwin:6135)
[  633.879963] CPU:0 (kwin:6135) -->> (X:5513)
[  633.879977] CPU:0 (X:5513) -->> (bash:7783)
[  633.880042] CPU:0 (bash:7783) -->> (konsole:7763)
[  633.880103] CPU:0 (konsole:7763) -->> (X:5513)
[  633.880119] CPU:0 (X:5513) -->> (konsole:7763)
[  633.880211] CPU:0 (konsole:7763) -->> (bash:7783)


Well, the bash is what turned off the logging, and the logging started at
619.xxx and ended at 633.xxx so that's ~14 seconds of logging.  So I would
assume you did it in the right place.

How long does the stop happen, and what exactly freezes?  Can you ping the
machine?  Also, have you tried to switch to a console before it freezes,
and see if it doesn't freeze that.  I'm curious if X isn't waiting on
something.

-- Steve
-
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/