[mythtv-users] Recorder failed

[Rich West]

Rich West wrote:
> Rich West wrote:
>   
>> Ack.  I just checked "Heroes", and it didn't record.  *cry*
>>
>> All I see in the logs and on the screen is :
>> "Canceled recording (Recorder Failed)"
>>
>> Other items (kids shows) were recorded, though.  I didn't find anything 
>> in the system logs.  In the mythbackend.log file, I see:
>> 2007-04-22 21:00:03.512 TVRec(3): Changing from None to RecordingOnly
>> 2007-04-22 21:00:04.088 TVRec(3): HW Tuner: 3->3
>> 2007-04-22 21:00:05.419 ret_pid(3753) child(3753) status(0x0)
>> 2007-04-22 21:00:05.421 External Tuning program exited with no error
>> 2007-04-22 21:00:05.655 Started recording: Deal or No Deal: channel 1010 
>> on cardid 3, sourceid 1
>> 2007-04-22 21:00:05.847 TVRec(1): Changing from None to RecordingOnly
>> 2007-04-22 21:00:05.866 TVRec(1): HW Tuner: 1->1
>> getattr: Input/output error
>> 2007-04-22 21:00:06.886 ret_pid(3760) child(3760) status(0x500)
>> 2007-04-22 21:00:06.888 ChannelBase: external tuning program exited with 
>> error 5
>> 2007-04-22 21:00:06.888 TVRec(1) Error: Failed to set channel to 6. 
>> Reverting to kState_None
>> 2007-04-22 21:00:06.889 TVRec(1): Changing from RecordingOnly to None
>> 2007-04-22 21:00:06.947 Canceled recording (Recorder Failed): Desperate 
>> Housewives "God, That's Good": channel 1006 on cardid 1, sourceid 1
>> 2007-04-22 21:00:09.967 Error deleting '/video/1006_20070422210000.mpg': 
>> No such file or directory
>>
>> Any idea what might have caused that?  It's not like the system was 
>> recording anything else at the time, either.. :(
>>
>> Ack.. Wait a sec.. I'm seeing this issue since 4-20-2007!!  Ack!  That's 
>> when I upgraded to FC6 (from FC5).  Oh-no..
>>
>>
>>
>>
>> Also, probably unrelated, but after restarting my main backend, I saw:
>> 2007-04-23 21:45:30.669 Using runtime prefix = /usr
>> 2007-04-23 21:45:30.932 New DB connection, total: 1
>> 2007-04-23 21:45:30.994 Connected to database 'mythconverg' at host: 
>> localhost
>> 2007-04-23 21:45:31.171 Current Schema Version: 1160
>> Starting up as the master server.
>> 2007-04-23 21:45:31.363 New DB connection, total: 2
>> 2007-04-23 21:45:31.407 Connected to database 'mythconverg' at host: 
>> localhost
>> 2007-04-23 21:45:31.529 EITHelper: localtime offset -4:00:00
>> 2007-04-23 21:45:31.687 TVRec(1) Error: Start channel invalid, setting 
>> to '254' on input S-Video 1 instead.
>> 2007-04-23 21:45:31.690 New DB connection, total: 3
>> 2007-04-23 21:45:31.691 Connected to database 'mythconverg' at host: 
>> localhost
>> 2007-04-23 21:45:31.981 ChannelBase: Could not find input: Tuner 1 on 
>> card when setting channel 254
>> 2007-04-23 21:45:32.060 ChannelBase: Could not find input: Tuner 1 on 
>> card when setting channel 3
>> 2007-04-23 21:45:32.065 TVRec(1) Error: Setting start channel '254' 
>> failed, and backup '3' failed as well.
>> 2007-04-23 21:45:32.090 EITHelper: localtime offset -4:00:00
>> 2007-04-23 21:45:32.093 TVRec(2) Error: Start channel invalid, setting 
>> to '254' on input S-Video 1 instead.
>> 2007-04-23 21:45:32.353 ChannelBase: Could not find input: Tuner 1 on 
>> card when setting channel 254
>> 2007-04-23 21:45:32.422 ChannelBase: Could not find input: Tuner 1 on 
>> card when setting channel 3
>> 2007-04-23 21:45:32.988 TVRec(2) Error: Setting start channel '254' 
>> failed, and backup '3' failed as well.
>> 2007-04-23 21:45:33.284 EITHelper: localtime offset -4:00:00
>> 2007-04-23 21:45:33.856 TVRec(3) Error: Start channel invalid, setting 
>> to '254' on input S-Video 1 instead.
>> 2007-04-23 21:45:34.865 ChannelBase: Could not find input: Tuner 1 on 
>> card when setting channel 254
>>
>> It looks like it isn't setting up the initial channel properly or 
>> something..
>>     
>
> $#!&$#*  When I upgraded the machine, the /video directory became owned 
> by root.  The "mythtv" user didn't have write permissions to the 
> directory.  ugh.
>
> However, I am still getting that odd "ChannelBase" error, but it doesn't 
> seem to be impacting anything.

CRAP.  I'm confused.. :(  I don't think it is permission related.  
*some* stuff was recorded from the 20th, 21st, 22nd, and even today, the 
23rd.  But a LOT of stuff failed with the error above.

I'm seeing this pretty continually:
2007-04-23 22:30:36.936 adding: mythtv3 as a client (events: 0)
2007-04-23 22:30:36.938 TVRec(1): Changing from None to WatchingLiveTV
2007-04-23 22:30:36.940 TVRec(1) Error: Start channel invalid, setting 
to '254' on input S-Video 1 instead.
2007-04-23 22:30:36.941 TVRec(1): HW Tuner: 1->0
2007-04-23 22:30:36.950 ChannelBase: Could not find input: Tuner 1 on 
card when setting channel 254

2007-04-23 22:30:36.951 TVRec(1) Error: Failed to set channel to 254.
2007-04-23 22:30:36.969 TVRec(1) Error: GetProgramRingBufferForLiveTV()
                        ProgramInfo is invalid.
ProgramInfo: channame() startts(Mon Apr 23 22:30:36 2007) endts(Mon Apr 
23 22:30:36 2007)
             recstartts(Mon Apr 23 22:30:36 2007) recendts(Mon Apr 23 
22:30:36 2007)
             title()

What the heck does *that* mean?!

-Rich