[mythtv-users] Failed HD-PVR recordings

Brad DerManouelian bderman at gmail.com
Fri Jul 3 02:23:39 UTC 2009 

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]  =======================



Any ideas? More info needed?

Thanks,

Brad
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://mythtv.org/pipermail/mythtv-users/attachments/20090702/3159c63e/attachment.htm>

More information about the mythtv-users mailing list