Philip Rowlands wrote:
Attached are serial console logs captured by Grant Kwok (thanks Grant!)
showing the kernel log during system lockup - he's having some trouble
mailing the list.
Anyone familiar with interrupt handler oopses? I'm among the least
qualified to comment on interrupt handlers, but... is it correct that
ivtv uses SA_INTERRUPT (disable all interrupts during handler), yet
doesn't use a minimal "top-half"? In ivtv_irq_handler I see spinlocks,
printks, and other fun stuff which might cause complications.
If not that, then perhaps the kfree() at ivtv-fileops.c:1494 (in
ivtv_v4l2_close)?
These logs are quite similar to my situation since I upgraded to newer
versions than 0.3.2f.
System description:
- PVR 350 (using TV out)
- PVR 250
- 384 MB memory (no swapfile enabled)
It happened multiple times though I don't have all logs anymore. Below
is a kernel log of one occurance (I also have logs of mythfrontend and
mythbackend during those times if necessary.)
Sep 2 18:23:46 pvr kernel: ivtv: ==================== START INIT IVTV
====================
Sep 2 18:23:46 pvr kernel: ivtv: version 0.3.8 ((svn snapshot revision
1280)) loading
Sep 2 18:23:46 pvr kernel: ivtv: Linux version: 2.6.8 preempt
PENTIUMIII gcc-3.3
Sep 2 18:23:46 pvr kernel: ivtv: In case of problems please include the
debug info
Sep 2 18:23:46 pvr kernel: ivtv: between the START INIT IVTV and END
INIT IVTV lines when
Sep 2 18:23:46 pvr kernel: ivtv: mailing the ivtv-devel mailinglist.
Sep 2 18:23:46 pvr kernel: ivtv: Autodetected WinTV PVR 250 card
(iTVC16 based)
Sep 2 18:23:46 pvr kernel: PCI: Found IRQ 9 for device 0000:00:12.0
Sep 2 18:23:46 pvr kernel: PCI: Sharing IRQ 9 with 0000:00:07.2
Sep 2 18:23:46 pvr kernel: PCI: Sharing IRQ 9 with 0000:00:07.3
Sep 2 18:23:46 pvr kernel: ivtv: Unreasonably low latency timer,
setting to 64 (was 32)
Sep 2 18:23:46 pvr kernel: msp34xx: ivtv version
Sep 2 18:23:46 pvr kernel: msp34xx: init: chip=MSP3415G-B8, has NICAM
support, simple (D) mode, simpler (G) no-thread mode
Sep 2 18:23:46 pvr kernel: msp34xx: $Id$ compiled on: Aug 31 2005 17:46:22
Sep 2 18:23:46 pvr kernel: ivtv: i2c attach to card #0 ok
[client=MSP3415G-B8, addr=40]
Sep 2 18:23:46 pvr kernel: ivtv: i2c attach to card #0 ok
[client=saa7115[50], addr=21]
Sep 2 18:23:46 pvr kernel: tuner: chip found at addr 0xc2 i2c-bus ivtv
i2c driver #0
Sep 2 18:23:46 pvr kernel: ivtv: i2c attach to card #0 ok
[client=(tuner unset), addr=61]
Sep 2 18:23:46 pvr kernel: lirc_i2c: chip found @ 0x18 (Hauppauge IR)
Sep 2 18:23:46 pvr kernel: ivtv: i2c attach to card #0 ok
[client=Hauppauge IR, addr=18]
Sep 2 18:23:46 pvr kernel: lirc_dev: lirc_register_plugin:sample_rate: 10
Sep 2 18:23:46 pvr kernel: tveeprom: Ignoring new-style parameters in
presence of obsolete ones
Sep 2 18:23:46 pvr kernel: tveeprom: Hauppauge: model = 32034, rev =
B129, serial# = 6695945
Sep 2 18:23:46 pvr kernel: tveeprom: tuner = Temic 4006FH5 (idx = 29,
type = 14)
Sep 2 18:23:46 pvr kernel: tveeprom: tuner fmt = PAL(B/G) (eeprom =
0x04, v4l2 = 0x00000007)
Sep 2 18:23:46 pvr kernel: tveeprom: audio processor = MSP3415 (type = 6)
Sep 2 18:23:46 pvr kernel: tveeprom: decoder processor = SAA7115 (type
= 13)
Sep 2 18:23:46 pvr kernel: ivtv: i2c attach to card #0 ok
[client=tveeprom[50], addr=50]
Sep 2 18:23:46 pvr kernel: ivtv: Encoder revision: 0x02040011
Sep 2 18:23:46 pvr kernel: ivtv: Create DMA encoder MPEG stream: 0 x
32768 buffers (0KB total)
Sep 2 18:23:46 pvr kernel: ivtv: Create DMA encoder YUV stream: 0 x
12960 buffers (0KB total)
Sep 2 18:23:46 pvr kernel: ivtv: Create DMA encoder VBI stream: 0 x
26208 buffers (0KB total)
Sep 2 18:23:46 pvr kernel: ivtv: Create DMA encoder PCM audio stream: 0
x 4608 buffers (0KB total)
Sep 2 18:23:46 pvr kernel: tuner: type set to 14 (Temic PAL_BG
(4006FH5)) by ivtv i2c driver #0
Sep 2 18:23:46 pvr kernel: ivtv: Initialized WinTV PVR 250, card #0
Sep 2 18:23:46 pvr kernel: ivtv: ====================== NEXT CARD
======================
Sep 2 18:23:46 pvr kernel: ivtv: Autodetected WinTV PVR 350 card
(iTVC15 based)
Sep 2 18:23:46 pvr kernel: PCI: Found IRQ 5 for device 0000:00:13.0
Sep 2 18:23:46 pvr kernel: PCI: Sharing IRQ 5 with 0000:00:07.5
Sep 2 18:23:46 pvr kernel: ivtv: Unreasonably low latency timer,
setting to 64 (was 32)
Sep 2 18:23:46 pvr kernel: msp34xx: ivtv version
Sep 2 18:23:46 pvr kernel: msp34xx: init: chip=MSP3418W-A2, has NICAM
support, simple (D) mode, simpler (G) no-thread mode
Sep 2 18:23:46 pvr kernel: msp34xx: $Id$ compiled on: Aug 31 2005 17:46:22
Sep 2 18:23:46 pvr kernel: ivtv: i2c attach to card #1 ok
[client=MSP3418W-A2, addr=40]
Sep 2 18:23:46 pvr kernel: ivtv: i2c attach to card #1 ok
[client=saa7115[50], addr=21]
Sep 2 18:23:46 pvr kernel: tuner: chip found at addr 0xc2 i2c-bus ivtv
i2c driver #1
Sep 2 18:23:46 pvr kernel: ivtv: i2c attach to card #1 ok
[client=(tuner unset), addr=61]
Sep 2 18:23:46 pvr kernel: lirc_i2c: chip found @ 0x18 (Hauppauge IR)
Sep 2 18:23:46 pvr kernel: ivtv: i2c attach to card #1 ok
[client=Hauppauge IR, addr=18]
Sep 2 18:23:46 pvr kernel: lirc_dev: lirc_register_plugin:sample_rate: 10
Sep 2 18:23:46 pvr kernel: tveeprom: Hauppauge: model = 48134, rev =
I121, serial# = 6174323
Sep 2 18:23:46 pvr kernel: tveeprom: tuner = Philips FM1216 (idx = 21,
type = 5)
Sep 2 18:23:46 pvr kernel: tveeprom: tuner fmt = PAL(B/G) (eeprom =
0x04, v4l2 = 0x00000007)
Sep 2 18:23:46 pvr kernel: tveeprom: audio processor = MSP4418 (type = 19)
Sep 2 18:23:46 pvr kernel: tveeprom: decoder processor = SAA7115 (type
= 13)
Sep 2 18:23:46 pvr kernel: ivtv: i2c attach to card #1 ok
[client=tveeprom[50], addr=50]
Sep 2 18:23:46 pvr kernel: saa7127: Ignoring new-style parameters in
presence of obsolete ones
Sep 2 18:23:46 pvr kernel: ivtv: i2c attach to card #1 ok
[client=saa7127[50], addr=44]
Sep 2 18:23:46 pvr kernel: ivtv: Encoder revision: 0x02040011
Sep 2 18:23:46 pvr kernel: ivtv: Decoder revision: 0x02020023
Sep 2 18:23:46 pvr kernel: ivtv: Create DMA encoder MPEG stream: 0 x
32768 buffers (0KB total)
Sep 2 18:23:46 pvr kernel: ivtv: Create DMA encoder YUV stream: 0 x
12960 buffers (0KB total)
Sep 2 18:23:46 pvr kernel: ivtv: Create DMA encoder VBI stream: 0 x
26208 buffers (0KB total)
Sep 2 18:23:46 pvr kernel: ivtv: Create DMA encoder PCM audio stream: 0
x 4608 buffers (0KB total)
Sep 2 18:23:46 pvr kernel: ivtv: Create encoder radio stream
Sep 2 18:23:46 pvr kernel: ivtv: Create DMA decoder MPEG stream: 0 x
65536 buffers (0KB total)
Sep 2 18:23:46 pvr kernel: ivtv: Create DMA decoder VBI stream: 0 x
2048 buffers (0KB total)
Sep 2 18:23:46 pvr kernel: ivtv: Create decoder VOUT stream
Sep 2 18:23:46 pvr kernel: ivtv: Create DMA decoder YUV stream: 0 x
51840 buffers (0KB total)
Sep 2 18:23:46 pvr kernel: ivtv: Create DMA decoder OSD stream: 0 x
65536 buffers (0KB total)
Sep 2 18:23:46 pvr kernel: tuner: type set to 5 (Philips PAL_BG (FI1216
and compatibles)) by ivtv i2c driver #1
Sep 2 18:23:46 pvr kernel: ivtv: Initialized WinTV PVR 350, card #1
Sep 2 18:23:46 pvr kernel: ivtv: ==================== END INIT IVTV
====================
Sep 2 18:23:46 pvr kernel: ivtv-osd: Framebuffer module loaded
(attached to ivtv card id 1)
Sep 2 18:23:46 pvr kernel: ivtv-osd: screen coords: [0 0] -> [720 576]
Sep 2 18:23:46 pvr kernel: ivtv-osd: original global alpha = 208
Sep 2 18:23:46 pvr kernel: ivtv-osd: current OSD state = 39
Sep 2 18:23:46 pvr kernel: ivtv-osd: new global alpha = 208 (1 255 0)
Sep 2 18:23:46 pvr kernel: ivtv-osd: framebuffer at 0xdd510000, mapped
to 0xda682000, size 1620k
Sep 2 18:23:46 pvr kernel: ivtv-osd: mode is 720x576x32, linelength=2880
Sep 2 18:23:46 pvr kernel: ivtv-osd: fb1: iTVC15 TV out frame buffer device
Sep 2 18:23:46 pvr kernel: ivtv: Allocate DMA decoder OSD stream: 16 x
65536 buffers (1024KB total)
[...]
Sep 2 18:24:29 pvr kernel: ivtv: Tried to open YUV output device but
need to send data to mpeg decoder before it can be used
[...]
Sep 3 19:50:41 pvr kernel: ivtv: Allocate DMA encoder MPEG stream: 128
x 32768 buffers (4096KB total)
[...]
Sep 3 20:19:35 pvr kernel: ivtv: Allocate DMA decoder MPEG stream: 16 x
65536 buffers (1024KB total)
Sep 3 20:19:35 pvr kernel: mythfrontend: page allocation failure.
order:4, mode:0xd0
Sep 3 20:19:35 pvr kernel: [__alloc_pages+760/880]
__alloc_pages+0x2f8/0x370
Sep 3 20:19:35 pvr kernel: [__get_free_pages+31/64]
__get_free_pages+0x1f/0x40
Sep 3 20:19:35 pvr kernel: [kmem_getpages+31/208] kmem_getpages+0x1f/0xd0
Sep 3 20:19:35 pvr kernel: [cache_grow+186/384] cache_grow+0xba/0x180
Sep 3 20:19:35 pvr kernel: [cache_alloc_refill+362/544]
cache_alloc_refill+0x16a/0x220
Sep 3 20:19:35 pvr kernel: [__kmalloc+116/128] __kmalloc+0x74/0x80
Sep 3 20:19:35 pvr kernel: [__crc_skb_iter_next+750272/1736680]
ivtv_init_buffer+0x3d/0x160 [ivtv]
Sep 3 20:19:35 pvr kernel: [__crc_skb_iter_next+750611/1736680]
ivtv_init_queue_nolock+0x30/0x80 [ivtv]
Sep 3 20:19:35 pvr kernel: [__crc_skb_iter_next+750774/1736680]
ivtv_init_queues_nolock+0x53/0x90 [ivtv]
Sep 3 20:19:35 pvr kernel: [__crc_skb_iter_next+757245/1736680]
ivtv_stream_alloc+0xca/0x230 [ivtv]
Sep 3 20:19:35 pvr kernel: [__crc_skb_iter_next+768128/1736680]
ivtv_claim_stream+0x5d/0x200 [ivtv]
Sep 3 20:19:35 pvr kernel: [__crc_skb_iter_next+775874/1736680]
ivtv_v4l2_write+0x8f/0x150 [ivtv]
Sep 3 20:19:35 pvr kernel: [do_poll+106/208] do_poll+0x6a/0xd0
Sep 3 20:19:35 pvr kernel: [vfs_write+184/304] vfs_write+0xb8/0x130
Sep 3 20:19:35 pvr kernel: [sys_write+81/128] sys_write+0x51/0x80
Sep 3 20:19:35 pvr kernel: [syscall_call+7/11] syscall_call+0x7/0xb
Sep 3 20:19:35 pvr kernel: ivtv: Couldn't allocate buffers for decoder
MPEG stream
Sep 3 20:19:46 pvr kernel: ivtv: Allocate DMA decoder MPEG stream: 16 x
65536 buffers (1024KB total)
Sep 3 20:19:46 pvr kernel: mythfrontend: page allocation failure.
order:4, mode:0xd0
Sep 3 20:19:46 pvr kernel: [__alloc_pages+760/880]
__alloc_pages+0x2f8/0x370
Sep 3 20:19:46 pvr kernel: [__get_free_pages+31/64]
__get_free_pages+0x1f/0x40
Sep 3 20:19:46 pvr kernel: [kmem_getpages+31/208] kmem_getpages+0x1f/0xd0
Sep 3 20:19:46 pvr kernel: [cache_grow+186/384] cache_grow+0xba/0x180
Sep 3 20:19:46 pvr kernel: [cache_alloc_refill+362/544]
cache_alloc_refill+0x16a/0x220
Sep 3 20:19:46 pvr kernel: [schedule+696/1232] schedule+0x2b8/0x4d0
Sep 3 20:19:46 pvr kernel: [__kmalloc+116/128] __kmalloc+0x74/0x80
Sep 3 20:19:46 pvr kernel: [__crc_skb_iter_next+750272/1736680]
ivtv_init_buffer+0x3d/0x160 [ivtv]
Sep 3 20:19:46 pvr kernel: [__crc_skb_iter_next+750611/1736680]
ivtv_init_queue_nolock+0x30/0x80 [ivtv]
Sep 3 20:19:46 pvr kernel: [__crc_skb_iter_next+750774/1736680]
ivtv_init_queues_nolock+0x53/0x90 [ivtv]
Sep 3 20:19:46 pvr kernel: [__crc_skb_iter_next+757245/1736680]
ivtv_stream_alloc+0xca/0x230 [ivtv]
Sep 3 20:19:46 pvr kernel: [__crc_skb_iter_next+768128/1736680]
ivtv_claim_stream+0x5d/0x200 [ivtv]
Sep 3 20:19:46 pvr kernel: [__crc_skb_iter_next+775874/1736680]
ivtv_v4l2_write+0x8f/0x150 [ivtv]
Sep 3 20:19:46 pvr kernel: [do_poll+106/208] do_poll+0x6a/0xd0
Sep 3 20:19:46 pvr kernel: [vfs_write+184/304] vfs_write+0xb8/0x130
Sep 3 20:19:46 pvr kernel: [sys_write+81/128] sys_write+0x51/0x80
Sep 3 20:19:46 pvr kernel: [syscall_call+7/11] syscall_call+0x7/0xb
Sep 3 20:19:46 pvr kernel: ivtv: Couldn't allocate buffers for decoder
MPEG stream
*LOCKUP*
-------------------------------------------------------
SF.Net email is Sponsored by the Better Software Conference & EXPO
September 19-22, 2005 * San Francisco, CA * Development Lifecycle Practices
Agile & Plan-Driven Development * Managing Projects & Teams * Testing & QA
Security * Process Improvement & Measurement * http://www.sqe.com/bsce5sf
_______________________________________________
ivtv-devel mailing list
ivtv-devel@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/ivtv-devel
