So, just when I was about to declare victory of solving my timeout
issues one popped up.
So the back story: Hans suggested a long time ago I run with "ivtvctl
-D585" which I've been doing. *But* when I logged all those resulting
notes to disk through rsyslog, it never ran into issues and the problem
sort of went away. But boy could you hear it writing to the disk, and
boy did the hard drive fill up quickly with /var/log/xxx messages.
So, I thought to myself, what happens if I tell syslog to log it to
/dev/null instead. Well, immediately I got timeout issues again. So
that didn't work as a solution.
The other day, I decided to try a new trick: log to a named pipe
instead. Then have something read from the named pipe and look for
timeout issues and capture the last 1000 lines if it found one. This
meant no extra disk writing (quieter!) and I'd still catch the errors.
So, after running this for a number of days it wasn't dying on me.
Until today! So here are 1000 lines of debug statements before the
TIMEOUT. First is my rsyslogd.conf, FYI:
kern.* |/var/log/kernpipe
And my script to watch that pipe (created first with mkfifo):
#!/usr/bin/perl
my @buffer;
my $buffersize = 1000;
open(I,"/var/log/kernpipe");
while (<I>) {
push @buffer, $_;
if (/DMA TIMEOUT/) {
my $stamp = time();
open(O,">/tmp/checkerrors.$stamp");
print O @buffer;
close(O);
}
shift @buffer if ($#buffer > $buffersize);
}
And finally some debug messages which may or may not be helpful to Hans
or anyone else with more knowledge than I. It certainly feels
"race-conditiony" because of the fact that I can slow down the events by
syslog related activity (without -D585 and log capturing it happens
within an hour frequently).
One more note: it usually takes recording something *and* watching
something to trigger the issue. I suspect it's lame-motherboard issue
(via chipset) and if i replace the motherboard I won't have issues.
That's likely my next step.
(Ok, the mailing list won't let me post something that large, so here is
a truncated copy. A full trace is at
http://www.hardakers.net/temp/ivtv-debug.txt )
Mar 7 06:29:18 machine kernel: ivtv0 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv0 dma: include DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG completed (13b6c0)
Mar 7 06:29:18 machine kernel: ivtv0 dma: PIO encoder VBI completed (0)
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START CAP 0: 000ff6c0 00005000
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG: 0x00005000 bytes
at 0x000ff6c0
Mar 7 06:29:18 machine kernel: ivtv1 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv1 dma: include DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel:last message repeated 2 times
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG completed (ff6c0)
Mar 7 06:29:18 machine kernel: ivtv1 dma: PIO encoder VBI completed (0)
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START CAP 0: 001416c0 0000a800
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG: 0x0000a800 bytes
at 0x001416c0
Mar 7 06:29:18 machine kernel: ivtv0 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG completed (1416c0)
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00302030
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START CAP 0: 001046c0 00007800
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG: 0x00007800 bytes
at 0x001046c0
Mar 7 06:29:18 machine kernel: ivtv1 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG completed (1046c0)
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv1 dma: PIO encoder VBI: 0x00001d40 bytes at
0x0030a780
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00303b40
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START CAP 0: 0014bec0 00004000
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG: 0x00004000 bytes
at 0x0014bec0
Mar 7 06:29:18 machine kernel: ivtv0 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv0 dma: include DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel:last message repeated 2 times
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG completed (14bec0)
Mar 7 06:29:18 machine kernel: ivtv0 dma: PIO encoder VBI completed (0)
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START CAP 0: 0010bec0 00003800
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG: 0x00003800 bytes
at 0x0010bec0
Mar 7 06:29:18 machine kernel: ivtv1 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv1 dma: include DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG completed (10bec0)
Mar 7 06:29:18 machine kernel: ivtv1 dma: PIO encoder VBI completed (0)
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv1 dma: PIO encoder VBI: 0x00001d40 bytes at
0x002fea10
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00305650
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv1 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00300520
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START CAP 0: 0010f6c0 00004800
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG: 0x00004800 bytes
at 0x0010f6c0
Mar 7 06:29:18 machine kernel: ivtv1 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv1 dma: include DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel:last message repeated 2 times
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG completed (10f6c0)
Mar 7 06:29:18 machine kernel: ivtv1 dma: PIO encoder VBI completed (0)
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00307160
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START CAP 0: 00113ec0 00008800
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG: 0x00008800 bytes
at 0x00113ec0
Mar 7 06:29:18 machine kernel: ivtv1 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG completed (113ec0)
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START CAP 0: 0014fec0 00005000
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG: 0x00005000 bytes
at 0x0014fec0
Mar 7 06:29:18 machine kernel: ivtv0 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv0 dma: include DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel:last message repeated 2 times
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG completed (14fec0)
Mar 7 06:29:18 machine kernel: ivtv0 dma: PIO encoder VBI completed (0)
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START CAP 0: 00154ec0 00007800
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG: 0x00007800 bytes
at 0x00154ec0
Mar 7 06:29:18 machine kernel: ivtv0 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG completed (154ec0)
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv1 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00302030
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00308c70
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START CAP 0: 0011c6c0 00003800
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG: 0x00003800 bytes
at 0x0011c6c0
Mar 7 06:29:18 machine kernel: ivtv1 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv1 dma: include DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG completed (11c6c0)
Mar 7 06:29:18 machine kernel: ivtv1 dma: PIO encoder VBI completed (0)
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv1 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00303b40
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START CAP 0: 0015c6c0 00005000
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG: 0x00005000 bytes
at 0x0015c6c0
Mar 7 06:29:18 machine kernel: ivtv0 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv0 dma: include DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG completed (15c6c0)
Mar 7 06:29:18 machine kernel: ivtv0 dma: PIO encoder VBI completed (0)
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x0030a780
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv1 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00305650
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START CAP 0: 0011fec0 00005000
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG: 0x00005000 bytes
at 0x0011fec0
Mar 7 06:29:18 machine kernel: ivtv1 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv1 dma: include DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel:last message repeated 2 times
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG completed (11fec0)
Mar 7 06:29:18 machine kernel: ivtv1 dma: PIO encoder VBI completed (0)
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x002fea10
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START CAP 0: 00124ec0 00008000
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG: 0x00008000 bytes
at 0x00124ec0
Mar 7 06:29:18 machine kernel: ivtv1 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG completed (124ec0)
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START CAP 0: 001616c0 00005800
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG: 0x00005800 bytes
at 0x001616c0
Mar 7 06:29:18 machine kernel: ivtv0 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv0 dma: include DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel:last message repeated 2 times
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG completed (1616c0)
Mar 7 06:29:18 machine kernel: ivtv0 dma: PIO encoder VBI completed (0)
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START CAP 0: 00166ec0 00008000
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG: 0x00008000 bytes
at 0x00166ec0
Mar 7 06:29:18 machine kernel: ivtv0 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG completed (166ec0)
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv1 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00307160
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00300520
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START CAP 0: 0012cec0 00003800
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG: 0x00003800 bytes
at 0x0012cec0
Mar 7 06:29:18 machine kernel: ivtv1 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv1 dma: include DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv1 dma: DMA encoder MPEG completed (12cec0)
Mar 7 06:29:18 machine kernel: ivtv1 dma: PIO encoder VBI completed (0)
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv1 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00308c70
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START CAP 0: 0016eec0 00004800
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG: 0x00004800 bytes
at 0x0016eec0
Mar 7 06:29:18 machine kernel: ivtv0 dma: start DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv0 dma: include DMA for encoder MPEG
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC DMA COMPLETE 3 0 (0)
Mar 7 06:29:18 machine kernel: ivtv0 dma: DMA encoder MPEG completed (16eec0)
Mar 7 06:29:18 machine kernel: ivtv0 dma: PIO encoder VBI completed (0)
Mar 7 06:29:18 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 06:29:18 machine kernel: ivtv1 irq: ENC START VBI CAP
Mar 7 08:48:17 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 08:48:17 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00308c70
Mar 7 08:48:17 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 08:48:17 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00300520
Mar 7 08:48:17 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 08:48:17 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00302030
Mar 7 08:48:17 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 08:48:17 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00303b40
Mar 7 08:48:17 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 08:48:17 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00305650
Mar 7 08:48:17 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 08:48:17 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00307160
Mar 7 08:48:17 machine kernel: ivtv0 irq: ENC START CAP 0: 000dd700 000117c0
Mar 7 08:48:17 machine kernel: ivtv0 dma: DMA encoder MPEG: 0x000117c0 bytes
at 0x000dd700
Mar 7 08:48:17 machine kernel: ivtv0 dma: start DMA for encoder MPEG
Mar 7 08:48:17 machine kernel: ivtv0 dma: include DMA for encoder MPEG
Mar 7 08:48:17 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 08:48:17 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x00308c70
Mar 7 08:48:17 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 08:48:17 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x0030a780
Mar 7 08:48:17 machine kernel: ivtv0 irq: ENC START VBI CAP
Mar 7 08:48:17 machine kernel: ivtv0 dma: PIO encoder VBI: 0x00001d40 bytes at
0x002fea10
Mar 7 08:48:17 machine kernel: ivtv0: DMA TIMEOUT 00000001 0
--
"In the bathtub of history the truth is harder to hold than the soap,
and much more difficult to find." -- Terry Pratchett
_______________________________________________
ivtv-users mailing list
[email protected]
http://ivtvdriver.org/mailman/listinfo/ivtv-users
