[mythtv-users] HVR-950Q intermittent failure

Fri May 13 13:30:31 UTC 2011

I'm getting an occasional failure to record off the DVB/ATSC side of my
new HVR-950Q tuner.  The recording file never appears in my recordings
directory - not a zero length file, just no file at all.  Once the tuner
seems to get in this state, the only thing that seems to be able to fix
is a reboot.  (I can't seem to figure out what's keeping the device node
open, so I haven't been able to reload the device driver(s).  I'm not
using the analog side of the tuner.

I am running Fedora 14, kernel is 2.6.35.6-48.fc14.i686.PAE #1 SMP

MythTv version info is:  (from RPMFusion)

MythTV Version   : 0.24-2.fc14 (r27317)
MythTV Branch    : exported
Network Protocol : 63
Library API      : 0.24.20101028-1
QT Version       : 4.7.0
Options compiled in:
  linux release using_alsa using_jack using_oss using_pulse 
using_pulseoutput using_backend using_bindings_perl 
using_bindings_python using_crystalhd using_dvb using_firewire 
using_frontend using_hdhomerun using_hdpvr using_iptv using_ivtv 
using_joystick_menu using_libfftw3 using_lirc using_mheg 
using_opengl_video using_opengl_vsync using_qtdbus using_qtwebkit 
using_v4l using_x11 using_xrandr using_xv using_xvmc using_xvmc_vld 
using_xvmcw using_bindings_perl using_bindings_python 
using_mythtranscode using_opengl using_vdpau using_ffmpeg_threads 
using_live using_mheg

I am using the no_poweroff=1 option for the xc5000 driver.

One thing I have been able to glean from the backend log is this message
that appears just before mythtv attempts to tune to the channel needing
to be recorded:  (one example below)

2011-05-10 07:00:03.386 PIDInfo(/dev/dvb/adapter0/frontend0) Error:
Failed to set TS filter (pid 0x0)

This happens consistently with every failed recording, and I don't see
it at any other time.

Has anyone else seen similar behavior with their 959Q tuner, on the ATSC
side, at least?

I also found this message in the system log that seems to correspond
with the one in the backend log:

May 10 07:00:03 poseidon kernel: [988267.278242] mythbackend: page
allocation failure. order:4, mode:0xc0d0
May 10 07:00:03 poseidon kernel: [988267.278248] Pid: 354, comm:
mythbackend Tainted: P         C  2.6.35.6-48.fc14.i686.PAE #1
May 10 07:00:03 poseidon kernel: [988267.278251] Call Trace:
May 10 07:00:03 poseidon kernel: [988267.278259]  [<c07b7a7e>] ?
printk+0x25/0x2f
May 10 07:00:03 poseidon kernel: [988267.278264]  [<c04b42d3>]
__alloc_pages_nodemask+0x552/0x5ad
May 10 07:00:03 poseidon kernel: [988267.278269]  [<c04b4342>]
__get_free_pages+0x14/0x26
May 10 07:00:03 poseidon kernel: [988267.278280]  [<f89bc121>]
au0828_dvb_start_feed+0x130/0x224 [au0828]
May 10 07:00:03 poseidon kernel: [988267.278291]  [<f808efe5>]
dmx_ts_feed_start_filtering+0x6d/0xb4 [dvb_core]
May 10 07:00:03 poseidon kernel: [988267.278299]  [<f808d626>]
dvb_dmxdev_start_feed.clone.1+0x9c/0xbd [dvb_core]
May 10 07:00:03 poseidon kernel: [988267.278308]  [<f808d8bc>]
dvb_dmxdev_filter_start+0x275/0x2b2 [dvb_core]
May 10 07:00:03 poseidon kernel: [988267.278316]  [<f808deac>]
dvb_demux_do_ioctl+0x218/0x3fc [dvb_core]
May 10 07:00:03 poseidon kernel: [988267.278324]  [<f808c78b>]
dvb_usercopy+0xc9/0x136 [dvb_core]
May 10 07:00:03 poseidon kernel: [988267.278332]  [<f808dc94>] ?
dvb_demux_do_ioctl+0x0/0x3fc [dvb_core]
May 10 07:00:03 poseidon kernel: [988267.278337]  [<c04e20fd>] ?
nameidata_to_filp+0x31/0x45
May 10 07:00:03 poseidon kernel: [988267.278341]  [<c04e5590>] ?
chrdev_open+0x0/0x10d
May 10 07:00:03 poseidon kernel: [988267.278345]  [<c04f51cc>] ?
mntput_no_expire+0x1b/0x80
May 10 07:00:03 poseidon kernel: [988267.278348]  [<c04e9b12>] ?
mntput+0x18/0x1a
May 10 07:00:03 poseidon kernel: [988267.278351]  [<c04e9c98>] ?
path_put+0x1a/0x1d
May 10 07:00:03 poseidon kernel: [988267.278355]  [<c0430a5f>] ?
need_resched+0x19/0x23
May 10 07:00:03 poseidon kernel: [988267.278358]  [<c0430a76>] ?
should_resched+0xd/0x28
May 10 07:00:03 poseidon kernel: [988267.278361]  [<c07b8152>] ?
_cond_resched+0xd/0x21
May 10 07:00:03 poseidon kernel: [988267.278364]  [<c07b9639>] ?
_lock_kernel+0x5d/0x89
May 10 07:00:03 poseidon kernel: [988267.278373]  [<f808cde8>]
dvb_demux_ioctl+0x35/0x55 [dvb_core]
May 10 07:00:03 poseidon kernel: [988267.278381]  [<f808dc94>] ?
dvb_demux_do_ioctl+0x0/0x3fc [dvb_core]
May 10 07:00:03 poseidon kernel: [988267.278385]  [<c04ed7e0>]
vfs_ioctl+0x33/0x91
May 10 07:00:03 poseidon kernel: [988267.278392]  [<f808cdb3>] ?
dvb_demux_ioctl+0x0/0x55 [dvb_core]
May 10 07:00:03 poseidon kernel: [988267.278396]  [<c04ee1e0>]
do_vfs_ioctl+0x462/0x48d
May 10 07:00:03 poseidon kernel: [988267.278399]  [<c0430a5f>] ?
need_resched+0x19/0x23
May 10 07:00:03 poseidon kernel: [988267.278402]  [<c0430a76>] ?
should_resched+0xd/0x28
May 10 07:00:03 poseidon kernel: [988267.278406]  [<c04d5e1d>] ?
__raw_local_irq_save+0x19/0x1e
May 10 07:00:03 poseidon kernel: [988267.278409]  [<c04d7c81>] ?
kmem_cache_free+0x65/0x94
May 10 07:00:03 poseidon kernel: [988267.278412]  [<c04ea6f2>] ?
putname+0x29/0x2c
May 10 07:00:03 poseidon kernel: [988267.278415]  [<c04ea6f2>] ?
putname+0x29/0x2c
May 10 07:00:03 poseidon kernel: [988267.278419]  [<c04ee251>]
sys_ioctl+0x46/0x69
May 10 07:00:03 poseidon kernel: [988267.278423]  [<c040899f>]
sysenter_do_call+0x12/0x28
May 10 07:00:03 poseidon kernel: [988267.278425] Mem-Info:
May 10 07:00:03 poseidon kernel: [988267.278427] DMA per-cpu:
May 10 07:00:03 poseidon kernel: [988267.278429] CPU    0: hi:    0,
btch:   1 usd:   0
May 10 07:00:03 poseidon kernel: [988267.278431] CPU    1: hi:    0,
btch:   1 usd:   0
May 10 07:00:03 poseidon kernel: [988267.278433] Normal per-cpu:
May 10 07:00:03 poseidon kernel: [988267.278435] CPU    0: hi:  186,
btch:  31 usd:   0
May 10 07:00:03 poseidon kernel: [988267.278437] CPU    1: hi:  186,
btch:  31 usd:   0
May 10 07:00:03 poseidon kernel: [988267.278440] HighMem per-cpu:
May 10 07:00:03 poseidon kernel: [988267.278442] CPU    0: hi:  186,
btch:  31 usd:   0
May 10 07:00:03 poseidon kernel: [988267.278444] CPU    1: hi:  186,
btch:  31 usd:   0
May 10 07:00:03 poseidon kernel: [988267.278449] active_anon:58178
inactive_anon:25617 isolated_anon:0
May 10 07:00:03 poseidon kernel: [988267.278451]  active_file:62766
inactive_file:126684 isolated_file:40
May 10 07:00:03 poseidon kernel: [988267.278452]  unevictable:0 dirty:71
writeback:0 unstable:0
May 10 07:00:03 poseidon kernel: [988267.278453]  free:647116
slab_reclaimable:23719 slab_unreclaimable:5000
May 10 07:00:03 poseidon kernel: [988267.278455]  mapped:8162 shmem:527
pagetables:2347 bounce:0
May 10 07:00:03 poseidon kernel: [988267.278462] DMA free:3504kB
min:64kB low:80kB high:96kB active_anon:0kB inactive_anon:0kB
active_file:2152kB inactive_file:348kB unevictable:0kB
isolated(anon):0kB isolated(file):0kB present:15788kB mlocked:0kB
dirty:0kB writeb: