[mythtv-users] Failed HD-PVR recordings

Fri Jul 3 03:11:23 UTC 2009

On Thu, Jul 2, 2009 at 9:23 PM, Brad DerManouelian <bderman at gmail.com>wrote:

> Hello!
>
> I'm having a problem with random recordings failing with my 2 HD-PVR boxes.
>
> HD-PVR: They are connected to my Astound cable set-top boxes via
> Component+S/PDIF. They are running latest firmware from Hauppauge - dmesg
> reports firmware version 0x12. Not sure which driver version I'm using but I
> compiled it about 2 weeks ago.
>
> Random: Same show will record on either box just fine, sometimes it will
> fail. After a fail, I sometimes get a good recording, sometimes another
> failure. Sometimes the first show after a restart will fail, sometimes it
> will be ok. It doesn't appear to have anything to do with specific channels
> or anything like that.
>
> I'm changing channels via firewire.
>
> $ mythbackend --version
> Please include all output in bug reports.
> MythTV Version   : 20728
> MythTV Branch    : trunk
> Library API      : 0.22.20090424-2
> Network Protocol : 45
> QT Version       : 4.4.0
> Options compiled in:
> linux release using_oss using_alsa using_arts using_backend using_dvb
> using_firewire using_frontend using_hdhomerun using_hdpvr using_iptv
> using_ivtv using_joystick_menu using_lirc using_mheg using_opengl_video
> using_opengl_vsync using_qtwebkit using_v4l using_x11 using_xrandr using_xv
> using_xvmc using_xvmc_vld using_xvmcw using_bindings_perl
> using_bindings_python using_opengl using_vdpau using_ffmpeg_threads
> using_libavc_5_3 using_live using_mheg
>
> Here's what I find in my backend log:
>
> 2009-07-02 18:38:15.350 TVRec(1): ASK_RECORDING 1 0 0 0
> 2009-07-02 18:38:15.564 TVRec(1): Changing from None to Watching
> RecordingOnly
> 2009-07-02 18:38:15.573 TVRec(1): HW Tuner: 1->1
> 2009-07-02 18:38:16.479 ret_pid(11381) child(11381) status(0x0)
> 2009-07-02 18:38:16.481 External Tuning program exited with no error
> 2009-07-02 18:38:16.772 AutoExpire: CalcParams(): Max required Free Space:
> 7.0 GB w/freq: 15 min
> 2009-07-02 18:38:16.780 Started recording: The Rachel Maddow Show: channel
> 1037 on cardid 1, sourceid 1
> 2009-07-02 18:38:16.790 scheduler: Started recording: The Rachel Maddow
> Show: channel 1037 on cardid 1, sourceid 1
> 2009-07-02 18:38:24.351 MainServer::ANN Monitor
> 2009-07-02 18:38:24.354 adding: myth-mini as a client (events: 0)
> 2009-07-02 18:38:24.599 mythbackend version: trunk [20728] www.mythtv.org
> 2009-07-02 18:38:24.602 Using runtime prefix = /usr
> 2009-07-02 18:38:24.603 Using localhost value of livingroom
> 2009-07-02 18:38:24.618 New DB connection, total: 1
> 2009-07-02 18:38:24.629 Connected to database 'mythconverg' at host:
> localhost
> 2009-07-02 18:38:24.643 Closing DB connection named 'DBManager0'
> 2009-07-02 18:38:24.645 Connected to database 'mythconverg' at host:
> localhost
> 2009-07-02 18:38:24.654 Current Schema Version: 1235
> 2009-07-02 18:38:25.880 MPEGRec(/dev/video0) Error: Device error detected
> 2009-07-02 18:38:25.884 DevRdB(/dev/video0): Stop(): Not running.
> 2009-07-02 18:38:36.261 MPEGRec(/dev/video0) Error: Device error detected
> 2009-07-02 18:38:36.262 DevRdB(/dev/video0): Stop(): Not running.
> 2009-07-02 18:38:46.641 MPEGRec(/dev/video0) Error: Device error detected
> 2009-07-02 18:38:46.642 DevRdB(/dev/video0): Stop(): Not running.
> 2009-07-02 18:38:57.028 MPEGRec(/dev/video0) Error: Device error detected
> 2009-07-02 18:38:57.029 DevRdB(/dev/video0): Stop(): Not running.
> 2009-07-02 18:39:07.404 MPEGRec(/dev/video0) Error: Device error detected
> 2009-07-02 18:39:07.409 DevRdB(/dev/video0): Stop(): Not running.
>
> I get a bunch of these in /var/log/messages:
>
> Jul  2 18:16:47 mythtv kernel: [348910.645710] Pid: 14286, comm:
> mythbackend Tainted: P        (2.6.24-19-generic #1)
> Jul  2 18:16:47 mythtv kernel: [348910.645714] EIP:
> 0060:[sunrpc:_spin_lock+0x5/0x10] EFLAGS: 00000246 CPU: 0
> Jul  2 18:16:47 mythtv kernel: [348910.645717] EIP is at
> _spin_lock+0x5/0x10
> Jul  2 18:16:47 mythtv kernel: [348910.645718] EAX: e4a1ea68 EBX: e4a1ea64
> ECX: f8a3d220 EDX: f3fd0000
> Jul  2 18:16:47 mythtv kernel: [348910.645721] ESI: e4a1ea68 EDI: e49a7140
> EBP: df9aac68 ESP: f3fd1dd0
> Jul  2 18:16:47 mythtv kernel: [348910.645723]  DS: 007b ES: 007b FS: 00d8
> GS: 0000 SS: 0068
> Jul  2 18:16:47 mythtv kernel: [348910.645724] CR0: 8005003b CR2: b27fdd5c
> CR3: 1f8c7000 CR4: 00000690
> Jul  2 18:16:47 mythtv kernel: [348910.645726] DR0: 00000000 DR1: 00000000
> DR2: 00000000 DR3: 00000000
> Jul  2 18:16:47 mythtv kernel: [348910.645729] DR6: ffff0ff0 DR7: 00000400
> Jul  2 18:16:47 mythtv kernel: [348910.645731]
>  [__mutex_lock_slowpath+0x1a/0xa0] __mutex_lock_slowpath+0x1a/0xa0
> Jul  2 18:16:47 mythtv kernel: [348910.645740]  [d_kill+0x3d/0x60]
> d_kill+0x3d/0x60
> Jul  2 18:16:47 mythtv kernel: [348910.645767]  [nfs:mutex_lock+0x14/0x290]
> mutex_lock+0x14/0x20
> Jul  2 18:16:47 mythtv kernel: [348910.645777]  [<f9361da2>]
> hdpvr_release+0x22/0x60 [hdpvr]
> Jul  2 18:16:47 mythtv kernel: [348910.645797]  [<f8a3d242>]
> v4l2_release+0x22/0x40 [videodev]
> Jul  2 18:16:47 mythtv kernel: [348910.645814]  [__fput+0xa7/0x190]
> __fput+0xa7/0x190
> Jul  2 18:16:47 mythtv kernel: [348910.645859]  [filp_close+0x49/0x80]
> filp_close+0x49/0x80
> Jul  2 18:16:47 mythtv kernel: [348910.645882]
>  [put_files_struct+0x92/0xb0] put_files_struct+0x92/0xb0
> Jul  2 18:16:47 mythtv kernel: [348910.645909]  [do_exit+0x180/0x860]
> do_exit+0x180/0x860
> Jul  2 18:16:47 mythtv kernel: [348910.645945]
>  [sunrpc:recalc_sigpending+0xb/0x40] recalc_sigpending+0xb/0x40
> Jul  2 18:16:47 mythtv kernel: [348910.645948]  [dequeue_signal+0x6b/0x150]
> dequeue_signal+0x6b/0x150
> Jul  2 18:16:47 mythtv kernel: [348910.645975]  [do_group_exit+0x26/0x80]
> do_group_exit+0x26/0x80
> Jul  2 18:16:47 mythtv kernel: [348910.645993]
>  [get_signal_to_deliver+0x2b7/0x4a0] get_signal_to_deliver+0x2b7/0x4a0
> Jul  2 18:16:47 mythtv kernel: [348910.646048]
>  [do_notify_resume+0x93/0x750] do_notify_resume+0x93/0x750
> Jul  2 18:16:47 mythtv kernel: [348910.646061]  [<f8873a50>]
> scsi_next_command+0x30/0x50 [scsi_mod]
> Jul  2 18:16:47 mythtv kernel: [348910.646099]  [<f8873bdb>]
> scsi_end_request+0xab/0xe0 [scsi_mod]
> Jul  2 18:16:47 mythtv kernel: [348910.646174]
>  [snd_pcm:getnstimeofday+0x34/0x9830] getnstimeofday+0x34/0xe0
> Jul  2 18:16:47 mythtv kernel: [348910.646203]
>  [snd_pcm:ktime_get_ts+0x1e/0x4f0] ktime_get_ts+0x1e/0x60
> Jul  2 18:16:47 mythtv kernel: [348910.646222]  [ktime_get+0x18/0x40]
> ktime_get+0x18/0x40
> Jul  2 18:16:47 mythtv kernel: [348910.646248]  [sys_futex+0x97/0x120]
> sys_futex+0x97/0x120
> Jul  2 18:16:47 mythtv kernel: [348910.646280]
>  [snd_pcm:getnstimeofday+0x34/0x9830] getnstimeofday+0x34/0xe0
> Jul  2 18:16:47 mythtv kernel: [348910.646330]  [work_notifysig+0x13/0x25]
> work_notifysig+0x13/0x25
> Jul  2 18:16:47 mythtv kernel: [348910.646418]  =======================
>
>

Do you have your STB set up to force 720p or 1080i all the time? The hdpvr
still doesn't like resolution changes and that can cause the driver to crash
sometimes.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://mythtv.org/pipermail/mythtv-users/attachments/20090702/f0575e7a/attachment.htm>