[mythtv-users] HD-PVR: Encoding Errors Running 0.22

Tue Dec 1 21:00:21 UTC 2009

> At this point I've tried every voodoo computing solution I can think
> of - different kernel versions, different USB cables, different USB
> ports, turning the HD PVR upside down, doing the hokey pokey and
> turning myself around... and the device still fails to start
> recordings sporadically.
>

This is the mythbackend.log of the only failure I have encountered
with my HD-PVR not recoding. I've only been online with this unit less
than 30 days so this is my first failure to record.

2009-11-25 18:29:29.952 Grabbing next suggested grabbing time
2009-11-25 18:29:30.266 DataDirect: BlockedTime is: 2009-11-25T18:29:30
2009-11-25 18:29:30.305 DataDirect: NextSuggestedTime is: 2009-11-26T07:55:39
2009-11-25 18:29:30.352
===============================================================
| Attempting to contact the master backend for rescheduling.  |
| If the master is not running, rescheduling will happen when |
| the master backend is restarted.                            |
===============================================================
2009-11-25 18:29:30.398 MythContext: Connecting to backend server:
10.0.1.199:6543 (try 1 of 1)
2009-11-25 18:29:30.444 Using protocol version 50
2009-11-25 18:29:30.481 MainServer::ANN Monitor
2009-11-25 18:29:30.532 adding: mythserver as a client (events: 0)
2009-11-25 18:29:30.566 MainServer::ANN Monitor
2009-11-25 18:29:30.624 adding: mythserver as a client (events: 1)
2009-11-25 18:29:30.668 Reschedule requested for id -1.
2009-11-25 18:29:30.669 Received a remote 'Clear Cache' request
Segmentation fault
2009-11-25 18:29:30.949 Scheduled 21 items in 0.3 = 0.07 match + 0.21 place
2009-11-25 18:38:51.544 AutoExpire: CalcParams(): Max required Free
Space: 1.0 GB w/freq: 15 min
2009-11-25 18:53:51.658 AutoExpire: CalcParams(): Max required Free
Space: 1.0 GB w/freq: 15 min
2009-11-25 18:58:13.906 UPnpMedia: BuildMediaMap - no VideoStartupDir
set,  skipping scan.
2009-11-25 19:08:51.759 AutoExpire: CalcParams(): Max required Free
Space: 1.0 GB w/freq: 15 min
2009-11-25 19:23:51.882 AutoExpire: CalcParams(): Max required Free
Space: 1.0 GB w/freq: 15 min
2009-11-25 19:28:17.975 UPnpMedia: BuildMediaMap - no VideoStartupDir
set,  skipping scan.
2009-11-25 19:38:51.975 AutoExpire: CalcParams(): Max required Free
Space: 1.0 GB w/freq: 15 min
2009-11-25 19:53:52.080 AutoExpire: CalcParams(): Max required Free
Space: 1.0 GB w/freq: 15 min
2009-11-25 19:58:18.030 UPnpMedia: BuildMediaMap - no VideoStartupDir
set,  skipping scan.
2009-11-25 19:59:00.845 Reschedule requested for id 0.
2009-11-25 19:59:01.016 Scheduled 21 items in 0.2 = 0.05 match + 0.12 place
2009-11-25 19:59:29.824 TVRec(1): ASK_RECORDING 1 29 0 0
2009-11-25 20:00:02.144 TVRec(1): Changing from None to Watching RecordingOnly
2009-11-25 20:00:02.191 TVRec(1): HW Tuner: 1->1
2009-11-25 20:00:12.299 Channel(/dev/video0) Error: SetInputAndFormat(1, NTSC)
                        while setting input (v4l v2)
                        eno: Connection timed out (110)
2009-11-25 20:00:22.347 Channel(/dev/video0) Error: SetInputAndFormat(1, NTSC)
                        while setting format (v4l v2)
                        eno: Connection timed out (110)
2009-11-25 20:00:32.391 Channel(/dev/video0) Error: SetInputAndFormat(1, ATSC)
                        while setting input (v4l v2)
                        eno: Connection timed out (110)
2009-11-25 20:00:42.439 Channel(/dev/video0) Error: SetInputAndFormat(1, ATSC)
                        while setting format (v4l v2)
                        eno: Connection timed out (110)
2009-11-25 20:00:42.471 Channel(/dev/video0): SetInputAndFormat() failed
2009-11-25 20:00:42.513 TVRec(1) Error: Failed to set channel to 8.
Reverting to kState_None
2009-11-25 20:00:42.555 TVRec(1): Changing from Watching RecordingOnly to None
2009-11-25 20:00:42.606 AutoExpire: CalcParams(): Max required Free
Space: 1.0 GB w/freq: 15 min
2009-11-25 20:00:42.664 Canceled recording (Recorder Failed): Modern
Family "Fizbo": channel 1008 on cardid 1, sourceid 1
2009-11-25 20:00:42.735 ProgramInfo, Error: GetPlaybackURL:
'1008_20091125200000.mpg' should be local, but it can not be found.
2009-11-25 20:00:43.705 Reschedule requested for id 0.
2009-11-25 20:00:43.841 Scheduled 21 items in 0.1 = 0.03 match + 0.10 place
2009-11-25 20:00:45.785 Error deleting
'/GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/mythserver/1008_20091125200000.mpg'
could not open
                        eno: No such file or directory (2)
2009-11-25 20:00:45.828 Delete Error
'/GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/mythserver/1008_20091125200000.mpg'
                        eno: No such file or directory (2)
2009-11-25 20:00:49.874 Reschedule requested for id 0.
2009-11-25 20:00:50.006 Scheduled 21 items in 0.1 = 0.03 match + 0.10 place
2009-11-25 20:08:52.167 AutoExpire: CalcParams(): Max required Free
Space: 1.0 GB w/freq: 15 min
2009-11-25 20:23:52.281 AutoExpire: CalcParams(): Max required Free
Space: 1.0 GB w/freq: 15 min
2009-11-25 20:28:18.175 UPnpMedia: BuildMediaMap - no VideoStartupDir
set,  skipping scan.

I assumed it was related (somehow) to the segmentation fault that the
mythfilldatabase caused.

Here's what was in the kern.log:

Nov 23 22:03:17 mythserver kernel: [16896.644278] hdpvr 1-4:1.0:
untested firmware version 0x12, the driver might not work
Nov 23 22:03:17 mythserver kernel: [16896.965075] hdpvr 1-4:1.0:
device now attached to /dev/video0
Nov 24 21:34:42 mythserver kernel: [101581.371180] tun: Universal
TUN/TAP device driver, 1.6
Nov 24 21:34:42 mythserver kernel: [101581.371187] tun: (C) 1999-2004
Max Krasnyansky <maxk at qualcomm.com>
Nov 24 21:34:42 mythserver kernel: [101581.372163] tun0: Disabled
Privacy Extensions
Nov 24 21:36:26 mythserver kernel: [101686.110161] tun0: Disabled
Privacy Extensions
Nov 25 18:29:30 mythserver kernel: [176870.102732]
mythfilldatabas[5914]: segfault at 0 ip 002ccf3c sp b7639c90 error 4
in libmythdb-0.22.so.0.22.0[296000+97000]
Nov 25 21:20:34 mythserver kernel: Kernel logging (proc) stopped.
Nov 25 21:21:08 mythserver kernel: imklog 4.2.0, log source =
/var/run/rsyslog/kmsg started.
Nov 25 21:21:08 mythserver kernel: [    0.000000] Initializing cgroup
subsys cpuset
Nov 25 21:21:08 mythserver kernel: [    0.000000] Initializing cgroup subsys cpu
Nov 25 21:21:08 mythserver kernel: [    0.000000] Linux version
2.6.31-15-generic (buildd at rothera) (gcc version 4.4.1 (Ubuntu
4.4.1-4ubuntu8) ) #50-Ubuntu SMP Tue Nov 10 14:54:29 UTC 2009 (Ubuntu
2.6.31-15.50-generic)