On Thursday 12 February 2009 20:43:14 [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):
Hmm, this suggests a problem with VIDEO_GET_FRAME_COUNT. I've made a repository containing some extra debugging here: http://www.linuxtv.org/hg/~hverkuil/v4l-dvb-ivtv Instructions on how to build it are here: http://www.ivtvdriver.org/index.php/Download#Bleeding_Edge_driver Except that you use my repository instead of the v4l-dvb master repository. No need to enable the debugging options with this tree. Just run and hopefully when it crashes again the logging will help me narrow down the problem. Regards, Hans > > [ 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 -- Hans Verkuil - video4linux developer - sponsored by TANDBERG _______________________________________________ ivtv-users mailing list [email protected] http://ivtvdriver.org/mailman/listinfo/ivtv-users
