Hi Hans, I send you an additional dump, this time without a concurrently active recording:
[ 206.685092] ivtv0 ioctl: VIDEO_COMMAND 0 [ 206.685102] ivtv0 info: Starting decode stream decoder MPG (gop_offset 0) [ 206.685114] ivtv0 info: Setting some initial decoder settings [ 206.685482] ivtv0 info: Decoder VBI RE-Insert start 0x0019ac00 size 0x0000be00 [ 206.700144] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.700152] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.700157] ivtv0 ioctl: VIDEO_COMMAND 0 [ 206.714222] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.716197] ivtv0 info: ivtv_unmap_user_dma [ 206.758292] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.760293] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.762061] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.763827] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.765613] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.767355] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.769005] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.770646] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.772295] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.773965] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.793836] ivtv0 info: ivtv_unmap_user_dma [ 206.806621] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.821351] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.836935] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.849209] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.864520] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT [ 206.880390] BUG: unable to handle kernel paging request at ac563ce8 [ 206.880405] IP: [<f8333d47>] ivtv_decoder_ioctls+0x6b7/0x870 [ivtv] [ 206.880436] *pde = 76535067 [ 206.880445] Oops: 0003 [#1] SMP [ 206.880450] last sysfs file: /sys/devices/system/cpu/cpu0/cpufreq/scaling_cur_freq [ 206.880457] Dumping ftrace buffer: [ 206.880464] (ftrace buffer empty) [ 206.880468] Modules linked in: binfmt_misc powernow_k8 cx8800 cx88xx videobuf_dvb video output bttv ir_common videobuf_dma_sg videobuf_core input_polldev btcx_risc lirc_i2c lirc_dev iptable_filter ip_tables x_tables dm_crypt ivtvfb it87 hwmon_vid sbp2 tuner_simple tuner_types msp3400 serio_raw pcspkr saa7127 saa7115 tuner k8temp ivtv compat_ioctl32 i2c_algo_bit cx2341x v4l2_common videodev v4l1_compat tveeprom i2c_piix4 qt1010 zl10353 usblp dvb_usb_gl861 dvb_usb dvb_core lmpcm_usb usbhid snd_usb_audio snd_usb_lib snd_hwdep snd_hda_intel snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device snd soundcore snd_page_alloc fglrx(P) parport_pc parport ati_agp agpgart shpchp usb_storage firewire_ohci firewire_core crc_itu_t ohci1394 ieee1394 ehci_hcd ohci_hcd r8169 mii fuse fbcon tileblit font bitblit softcursor [ 206.880512] [ 206.880512] Pid: 5849, comm: mythfrontend.re Tainted: P (2.6.28-7-generic #20-Ubuntu) System Product Name [ 206.880512] EIP: 0060:[<f8333d47>] EFLAGS: 00010246 CPU: 1 [ 206.880512] EIP is at ivtv_decoder_ioctls+0x6b7/0x870 [ivtv] [ 206.880512] EAX: f5e80079 EBX: f67624c0 ECX: ac563ce8 EDX: 00000000 [ 206.880512] ESI: 00000000 EDI: f5e8f288 EBP: eee17f10 ESP: eee17d88 [ 206.880512] DS: 007b ES: 007b FS: 00d8 GS: 0033 SS: 0068 [ 206.880512] Process mythfrontend.re (pid: 5849, ti=eee16000 task=f2b79920 task.ti=eee16000) [ 206.880512] Stack: [ 206.880512] eee17ec4 00000000 ffffa50a 00000800 ac563ce8 f6630900 f5e80000 f6630954 [ 206.880512] eee17db8 c013e467 00000002 f2acc6e0 eee17dd4 c02b33d1 00408a3e 0000007a [ 206.880512] 0000007f f6630900 f2acc6e0 00000046 c02b4bd0 c04e4a3e c018ab7e f61c8000 [ 206.880512] Call Trace: [ 206.880512] [<c013e467>] ? mod_timer+0x37/0x80 [ 206.880512] [<c02b33d1>] ? cfq_arm_slice_timer+0xb1/0x110 [ 206.880512] [<c02b4bd0>] ? cfq_completed_request+0x160/0x220 [ 206.880512] [<c04e4a3e>] ? _spin_lock_irqsave+0x2e/0x40 [ 206.880512] [<c018ab7e>] ? mempool_free_slab+0xe/0x10 [ 206.880512] [<c02a82cf>] ? __freed_request+0xaf/0x120 [ 206.880512] [<c02a8a00>] ? blk_run_queue+0x30/0x40 [ 206.880512] [<c0150783>] ? getnstimeofday+0x53/0x110 [ 206.880512] [<c0113977>] ? xapic_wait_icr_idle+0x17/0x20 [ 206.880512] [<f8333f52>] ? ivtv_serialized_ioctl+0x52/0x370 [ivtv] [ 206.880512] [<c0154d83>] ? tick_dev_program_event+0x33/0xc0 [ 206.880512] [<f83342aa>] ? ivtv_v4l2_ioctl+0x3a/0x60 [ivtv] [ 206.880512] [<f8334270>] ? ivtv_v4l2_ioctl+0x0/0x60 [ivtv] [ 206.880512] [<c01c3c58>] ? vfs_ioctl+0x28/0x90 [ 206.880512] [<c01c413e>] ? do_vfs_ioctl+0x5e/0x200 [ 206.880512] [<c01c4343>] ? sys_ioctl+0x63/0x70 [ 206.880512] [<c0103f6b>] ? sysenter_do_call+0x12/0x2f [ 206.880512] Code: fe ff ff 81 c7 7c f2 00 00 a5 a5 a5 8b 85 90 fe ff ff 8b 95 90 fe ff ff 83 c0 79 f0 80 4a 79 40 8b 75 b4 31 d2 8b 8d 88 fe ff ff <89> 31 c7 41 04 00 00 00 00 e9 bb f9 ff ff 8b bd 90 fe ff ff 8b [ 206.880512] EIP: [<f8333d47>] ivtv_decoder_ioctls+0x6b7/0x870 [ivtv] SS:ESP 0068:eee17d88 [ 206.881090] ---[ end trace b59306ff13690a96 ]--- [ 206.935570] ivtv0 info: ivtv_unmap_user_dma [ 207.000447] ivtv0 info: Stereo mode changed [ 207.067686] ivtv0 info: ivtv_unmap_user_dma -jr [email protected] wrote: > Hi Hans, > > I enabled the debugging options as you suggested it. Here are the results > (please inform me if you need more context): > > [ 1854.792629] ivtv0 ioctl: VIDEO_COMMAND 0 > [ 1854.792637] ivtv0 info: Starting decode stream decoder MPG (gop_offset 0) > [ 1854.792642] ivtv0 info: Setting some initial decoder settings > [ 1854.792879] ivtv0 info: Decoder VBI RE-Insert start 0x0019ac00 size > 0x0000be00 > [ 1854.802811] ivtv0 info: ivtv_unmap_user_dma > [ 1854.804776] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.805091] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.805097] ivtv0 ioctl: VIDEO_COMMAND 0 > [ 1854.806805] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.845131] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.847492] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.850574] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.852600] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.854358] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.855988] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.858359] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.860017] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.862128] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.863789] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.873508] ivtv0 info: ivtv_unmap_user_dma > [ 1854.886092] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.897515] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.909854] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.923199] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.946509] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.946804] ivtv0 warn: encoder VBI: Couldn't find start of buffer within > the first 256 bytes > [ 1854.952262] ivtv0 info: ivtv_unmap_user_dma > [ 1854.953958] ivtv0 ioctl: VIDEO_GET_FRAME_COUNT > [ 1854.956567] BUG: unable to handle kernel paging request at acd65ce8 > [ 1854.956576] IP: [<f8351d47>] ivtv_decoder_ioctls+0x6b7/0x870 [ivtv] > [ 1854.956604] *pde = 76ee1067 > [ 1854.956612] Oops: 0003 [#1] SMP > [ 1854.956618] last sysfs file: > /sys/devices/system/cpu/cpu0/cpufreq/scaling_cur_freq > [ 1854.956625] Dumping ftrace buffer: > [ 1854.956631] (ftrace buffer empty) > [ 1854.956634] Modules linked in: binfmt_misc powernow_k8 video output cx8800 > cx88xx videobuf_dvb input_polldev bttv ir_common videobuf_dma_sg > videobuf_core btcx_risc lirc_i2c lirc_dev iptable_filter ip_tables x_tables > dm_crypt ivtvfb it87 hwmon_vid sbp2 tuner_simple tuner_types msp3400 pcspkr > saa7127 saa7115 serio_raw tuner ivtv compat_ioctl32 i2c_algo_bit cx2341x > v4l2_common videodev v4l1_compat tveeprom k8temp qt1010 zl10353 i2c_piix4 > dvb_usb_gl861 dvb_usb usblp lmpcm_usb dvb_core usbhid snd_usb_audio > snd_usb_lib snd_hda_intel snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm > snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event > fglrx(P) snd_seq snd_timer snd_seq_device snd soundcore snd_page_alloc > parport_pc parport ati_agp agpgart shpchp usb_storage firewire_ohci > firewire_core crc_itu_t ohci1394 ieee1394 ehci_hcd ohci_hcd r8169 mii fuse > fbcon tileblit font bitblit softcursor > [ 1854.956743] > [ 1854.956750] Pid: 6269, comm: mythfrontend.re Tainted: P > (2.6.28-7-generic #20-Ubuntu) System Product Name > [ 1854.956756] EIP: 0060:[<f8351d47>] EFLAGS: 00010246 CPU: 0 > [ 1854.956774] EIP is at ivtv_decoder_ioctls+0x6b7/0x870 [ivtv] > [ 1854.956778] EAX: f5e60079 EBX: f3bdfb60 ECX: acd65ce8 EDX: 00000000 > [ 1854.956783] ESI: 00000000 EDI: f5e6f288 EBP: efddbf10 ESP: efddbd88 > [ 1854.956787] DS: 007b ES: 007b FS: 00d8 GS: 0033 SS: 0068 > [ 1854.956793] Process mythfrontend.re (pid: 6269, ti=efdda000 task=f17e3ed0 > task.ti=efdda000) > [ 1854.956797] Stack: > [ 1854.956800] efddbec4 00000000 00000000 00000800 acd65ce8 21d1a067 > f5e60000 efddbdcc > [ 1854.956811] c0120475 f49440fc efddbdcc c012134b f1725ec8 c06ad700 > 00000000 00000008 > [ 1854.956822] f49440fc efddbe10 c019b024 21d1a067 00000001 f63f8cc4 > c1692880 00000007 > [ 1854.956834] Call Trace: > [ 1854.956838] [<c0120475>] ? ptep_set_access_flags+0x65/0x80 > [ 1854.956850] [<c012134b>] ? kunmap_atomic+0x3b/0xb0 > [ 1854.956859] [<c019b024>] ? do_wp_page+0xb4/0x630 > [ 1854.956868] [<c019bc20>] ? handle_mm_fault+0x300/0x380 > [ 1854.956875] [<c013e367>] ? __mod_timer+0xa7/0xf0 > [ 1854.956882] [<c01266ac>] ? enqueue_entity+0x13c/0x360 > [ 1854.956890] [<c012c0e8>] ? enqueue_task_fair+0x68/0x70 > [ 1854.956899] [<f8351f52>] ? ivtv_serialized_ioctl+0x52/0x370 [ivtv] > [ 1854.956918] [<f83522aa>] ? ivtv_v4l2_ioctl+0x3a/0x60 [ivtv] > [ 1854.956936] [<f8352270>] ? ivtv_v4l2_ioctl+0x0/0x60 [ivtv] > [ 1854.956954] [<c01c3c58>] ? vfs_ioctl+0x28/0x90 > [ 1854.956961] [<c01c413e>] ? do_vfs_ioctl+0x5e/0x200 > [ 1854.956967] [<c01c4343>] ? sys_ioctl+0x63/0x70 > [ 1854.956973] [<c0103f6b>] ? sysenter_do_call+0x12/0x2f > [ 1854.956982] Code: fe ff ff 81 c7 7c f2 00 00 a5 a5 a5 8b 85 90 fe ff ff 8b > 95 90 fe ff ff 83 c0 79 f0 80 4a 79 40 8b 75 b4 31 d2 8b 8d 88 fe ff ff <89> > 31 c7 41 04 00 00 00 00 e9 bb f9 ff ff 8b bd 90 fe ff ff 8b > [ 1854.957042] EIP: [<f8351d47>] ivtv_decoder_ioctls+0x6b7/0x870 [ivtv] > SS:ESP 0068:efddbd88 > [ 1854.957066] ---[ end trace 5f6d950fe3f75545 ]--- > [ 1855.028248] ivtv0 warn: encoder VBI: Couldn't find start of buffer within > the first 256 bytes > [ 1855.028265] ivtv0 info: ivtv_unmap_user_dma > [ 1855.052965] ivtv0 info: Stereo mode changed > [ 1855.342071] ivtv0 info: ivtv_unmap_user_dma > [ 1855.584054] ivtv0 info: ivtv_unmap_user_dma > [ 1855.820629] ivtv0 info: ivtv_unmap_user_dma > [ 1856.604031] ivtv0: All encoder VBI stream buffers are full. Dropping data. > [ 1856.604041] ivtv0: Cause: the application is not reading fast enough. > [ 1856.861811] ivtv0 info: ivtv_unmap_user_dma > [ 1857.575759] ivtv0 info: ivtv_unmap_user_dma > [ 1857.699770] ivtv0 info: ivtv_unmap_user_dma > [ 1857.943535] ivtv0 info: ivtv_unmap_user_dma > [ 1858.920899] ivtv0: All encoder MPG stream buffers are full. Dropping data. > [ 1858.920908] ivtv0: Cause: the application is not reading fast enough. > [ 1858.982605] ivtv0 info: ivtv_unmap_user_dma > [ 1859.048279] ivtv0: All encoder MPG stream buffers are full. Dropping data. > [ 1859.048288] ivtv0: Cause: the application is not reading fast enough. > [ 1859.058084] ivtv0 info: ivtv_unmap_user_dma > [ 1859.169817] ivtv0: All encoder MPG stream buffers are full. Dropping data. > [ 1859.169825] ivtv0: Cause: the application is not reading fast enough. > > -jr > > > Hans Verkuil wrote: >> On Wednesday 11 February 2009 23:34:03 [email protected] wrote: >>> Hi, >>> >>> I frequently get kernel errors in the ivtv driver with mythtv. >>> Can anybody help me - I found similar problem reports on this list >>> previously (e.g. Take.Vos at vosgames on Oct 13, 2008), but no >>> solution. The problem occurred very reproducable when doing fast >>> forwards during watching recordings. >> I must have missed that report, it is the first time I hear about this. >> >> We need to narrow this down a bit. Enable extra debug options using: >> >> ivtv-ctl -D 0xb (run as root) >> >> If the place it dies is reported correctly, then this should give me the >> actual command that causes this error in the log. >> >> We'll take it from there. >> >> Regards, >> >> Hans >> >>> My system: >>> Ubuntu intrepid, kernel 2.6.27 and 2.6.28 >>> (I had no problem previously on Ubuntu hardy, kernel 2.6.24) >> Hmm, not much has changed since that kernel in ivtv. >> >>> Mythtv (0.21) >>> PVR-350 >>> Mythfrontend running on second X-Session on tv-out of PVR-350 >>> CPU: Athlon 64 X2 5200+ >>> Mainboard: ASUS M2A-VM HDMI >>> >>> >>> Syslog excerpt: >>> >>> BUG: unable to handle kernel paging request at b216fce8 >>> IP: [<f8348d87>] ivtv_decoder_ioctls+0x6b7/0x870 [ivtv] >>> *pde = 7652d067 >>> Oops: 0003 [#1] SMP >>> last sysfs file: /sys/devices/system/cpu/cpu0/cpufreq/scaling_cur_freq >>> Dumping ftrace buffer: >>> (ftrace buffer empty) >>> Modules linked in: binfmt_misc vmnet vmblock vmci vmmon powernow_k8 >>> cx8800 cx88xx videobuf_dvb bttv video output ir_common videobuf_dma_sg >>> videobuf_core btcx_risc lirc_i2c lirc_dev iptable_filter ip_tables >>> x_tables dm_crypt ivtvfb it87 hwmon_vid sbp2 tuner_simple tuner_types >>> msp3400 saa7127 saa7115 pcspkr serio_raw tuner ivtv compat_ioctl32 >>> i2c_algo_bit cx2341x v4l2_common videodev v4l1_compat tveeprom qt1010 >>> k8temp zl10353 i2c_piix4 dvb_usb_gl861 usblp dvb_usb dvb_core lmpcm_usb >>> usbhid snd_usb_audio snd_usb_lib snd_hwdep snd_hda_intel snd_pcm_oss >>> snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss fglrx(P) snd_seq_midi >>> snd_rawmidi snd_seq_midi_event snd_seq snd_timer snd_seq_device snd >>> soundcore snd_page_alloc parport_pc parport ati_agp agpgart shpchp >>> usb_storage firewire_ohci firewire_core crc_itu_t ohci1394 ieee1394 >>> ehci_hcd ohci_hcd r8169 mii fuse fbcon tileblit font bitblit softcursor >>> >>> Pid: 16670, comm: mythfrontend.re Tainted: P (2.6.28-4-generic >>> #11-Ubuntu) System Product Name >>> EIP: 0060:[<f8348d87>] EFLAGS: 00010246 CPU: 1 >>> EIP is at ivtv_decoder_ioctls+0x6b7/0x870 [ivtv] >>> EAX: f5e60079 EBX: f4a73900 ECX: b216fce8 EDX: 00000000 >>> ESI: 0000e5ba EDI: f5e6f288 EBP: c432df10 ESP: c432dd88 >>> DS: 007b ES: 007b FS: 00d8 GS: 0033 SS: 0068 >>> Process mythfrontend.re (pid: 16670, ti=c432c000 task=f3ab8c90 >>> task.ti=c432c000) >>> Stack: >>> c432dec4 c01500cb c432ddc0 00000800 b216fce8 0442110e f5e60000 c0113913 >>> c432ddd4 c01537fa c432ddf0 c012e08a 785213a7 0000c292 c432ddc8 00000001 >>> 00000001 c1f1d600 00000001 00000000 c0154bc3 9d74ffb1 00000001 00000001 >>> Call Trace: >>> [<c01500cb>] ? getnstimeofday+0x4b/0x100 >>> [<c0113913>] ? lapic_next_event+0x13/0x20 >>> [<c01537fa>] ? clockevents_program_event+0x9a/0x150 >>> [<c012e08a>] ? rebalance_domains+0xda/0x150 >>> [<c0154bc3>] ? tick_dev_program_event+0x33/0xc0 >>> [<c012e134>] ? run_rebalance_domains+0x34/0xc0 >>> [<c0139071>] ? __do_softirq+0x111/0x170 >>> [<c014c9f6>] ? hrtimer_interrupt+0x186/0x1b0 >>> [<c01265ec>] ? enqueue_entity+0x13c/0x360 >>> [<c012c028>] ? enqueue_task_fair+0x68/0x70 >>> [<f8348f92>] ? ivtv_serialized_ioctl+0x52/0x370 [ivtv] >>> [<c0154bc3>] ? tick_dev_program_event+0x33/0xc0 >>> [<f83492ea>] ? ivtv_v4l2_ioctl+0x3a/0x60 [ivtv] >>> [<f83492b0>] ? ivtv_v4l2_ioctl+0x0/0x60 [ivtv] >>> [<c01c3728>] ? vfs_ioctl+0x28/0x90 >>> [<c01c3c0e>] ? do_vfs_ioctl+0x5e/0x200 >>> [<c01c3e13>] ? sys_ioctl+0x63/0x70 >>> [<c0103f6b>] ? sysenter_do_call+0x12/0x2f >>> Code: fe ff ff 81 c7 7c f2 00 00 a5 a5 a5 8b 85 90 fe ff ff 8b 95 90 fe >>> ff ff 83 c0 79 f0 80 4a 79 40 8b 75 b4 31 d2 8b 8d 88 fe ff ff <89> 31 >>> c7 41 04 00 00 00 00 e9 bb f9 ff ff 8b bd 90 fe ff ff 8b >>> EIP: [<f8348d87>] ivtv_decoder_ioctls+0x6b7/0x870 [ivtv] SS:ESP >>> 0068:c432dd88 >>> ---[ end trace 4ba414ae9ebaaa30 ]--- >>> mythfrontend.re[16672]: segfault at 0 ip b7abb7ff sp adfff150 error 4 in >>> libmythtv-0.21.so.0.21.0[b75e0000+a1e000] >>> >>> -hannes >>> >>> _______________________________________________ >>> ivtv-users mailing list >>> [email protected] >>> http://ivtvdriver.org/mailman/listinfo/ivtv-users >> >> > > _______________________________________________ > ivtv-users mailing list > [email protected] > http://ivtvdriver.org/mailman/listinfo/ivtv-users _______________________________________________ ivtv-users mailing list [email protected] http://ivtvdriver.org/mailman/listinfo/ivtv-users
