<div dir="ltr"><div><div><div> On Fri, Oct 11, 2013 at 3:48 PM, John P Poet <span dir="ltr"><<a href="mailto:jppoet@gmail.com" target="_blank">jppoet@gmail.com</a>></span> wrote:</div><blockquote style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex" class="gmail_quote">
<div><div class="h5">On Fri, Oct 11, 2013 at 4:26 PM, Fred Hamilton <span dir="ltr"><<a href="mailto:fred@yonkitime.com" target="_blank">fred@yonkitime.com</a>></span> wrote:<br>
<blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex"><div dir="ltr">Running 0.27 + fixes on Ubuntu 12.04. <div><br></div><div>cat
/dev/video0 > test.ts works. And the channel it records is the last
one myth tried to record, indicating the 1394 channel changing is
working fine.</div>
<div><br></div><div>mythbackend.log just reports "(Tuning => Recorder Failed)" and then reschedules.</div><div><br></div><div>Where should I look next? </div><div><br></div><div>About
4 or 5 shows didn't record a few days ago. I cycled power on my HDPVR
and rebooted my MythTV box (FE and BE). It worked OK for a day or tow,
and then this morning I noticed 4 failures last night. However today no
amount of rebooting or powering down everything seems to help.</div>
<div><br></div><div>But "cat /dev/video0 > test.ts" works - I thought that was supposed to be the hard part! :-)</div></div></blockquote><div><br></div></div></div><div>It sounds like myth *thinks* the tuning is failing. Are you sure your channel-change script is reliably returning true?<br>
<br></div>You probably should have mythbackend running with "-v record,channel" if you don't already.<span class=""></span><br><span class=""></span></blockquote><br>Hi John,<br><br></div>Thanks for the quick response. Good guess - my channel change command does not seem to be making it to the cable tuner. I tried to record the station below while in the middle of another "cat /dev/video0 > test.ts". and the tuner stayed on the same channel (what I reported earlier was just a coincidence).<br>
<br></div>I'm going to check out my 6200ch command and see what might be happening. In the meantime, here's the log data with "-v record,channel":<br><div><br>2013-10-11 17:27:50.924133 I TVRec[9]: RecordPending on inputid 1<br>
2013-10-11 17:27:50.924477 I TVRec[9]: StartRecording("The Middle":"The Legacy")<br>2013-10-11 17:27:50.985282 I TVRec[9]: StartedRecording(2768_2013-10-12T00:28:00Z) fn(/media/disk1/mythtv_recordings/2768_20131012002800.mpg)<br>
2013-10-11 17:27:50.994382 I Using profile 'Default' to record<br>2013-10-11 17:27:50.995456 I TVRec[9]: ClearFlags(CancelNextRecording,) -> FrontendReady,RunMainLoop,RingBufferReady,<br>2013-10-11 17:27:51.006640 I TVRec[9]: Changing from None to RecordingOnly<br>
2013-10-11 17:27:51.006669 I TVRec[9]: ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RingBufferReady,<br>2013-10-11 17:27:51.006738 I TVRec[9]: HandleTuning Request: Program(ProgramInfo(2768_20131012002800.mpg): channame(ABC Family HD (Pacific)) startts(Sat Oct 12 00:00:00 2013) endts(Sat Oct 12 00:30:00 2013)<br>
recstartts(Sat Oct 12 00:28:00 2013) recendts(Sat Oct 12 00:30:00 2013)<br> title(The Middle)) channel() input() flags(Recording,)<br>2013-10-11 17:27:51.007392 I TVRec[9]: HW Tuner: 9->9<br>
2013-10-11 17:27:51.007402 I TVRec[9]: ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady,<br>
2013-10-11 17:27:51.007411 I TVRec[9]: No recorder yet, calling TuningFrequency<br>2013-10-11 17:27:51.007618 I V4LChannel[9](/dev/video0): Device name 'Hauppauge HD PVR' driver 'hdpvr'.<br>2013-10-11 17:27:51.007631 I V4LChannel[9](/dev/video0): v4l2: stream io: 0 std io: 1 async io: 0 tuner 0 sliced vbi 0<br>
2013-10-11 17:27:51.017572 I ChannelBase[9]: Input #1: 'Component' schan(2) sourceid(2) ccid(9)<br>2013-10-11 17:27:51.017592 I ChannelBase[9]: Current Input #1: 'Component'<br>2013-10-11 17:27:51.017637 I Global TVFormat Setting 'NTSC'<br>
2013-10-11 17:27:51.017674 I V4LChannel[9](/dev/video0): Input #1: 'Component' schan(2) tun() v4l2(NTSC)<br>2013-10-11 17:27:51.017691 I V4LChannel[9](/dev/video0): SetFormat(Default) fmt(NTSC) input(1)<br>2013-10-11 17:27:51.017704 I Channel(/dev/video0)::SwitchToInput(in 1, '')<br>
2013-10-11 17:27:51.019279 I V4LChannel[9](/dev/video0): SetInputAndFormat(1, NTSC) (v4l v2) input_switch: 0 mode_switch: 0<br>2013-10-11 17:27:51.019294 I DTVChan[9](/dev/video0): SetChannelByString(768): <br>2013-10-11 17:27:51.021307 I V4LChannel[9](/dev/video0): SetFormat(Default) fmt(NTSC) input(1)<br>
2013-10-11 17:27:51.021321 I DTVChan[9](/dev/video0): SetChannelByString(768): success<br>2013-10-11 17:27:51.040828 I ChannelBase[9]: Running command: 6200ch 768<br>2013-10-11 17:27:51.042951 I TVRec[9]: Starting Signal Monitor<br>
2013-10-11 17:27:51.042993 I TVRec[9]: SetupSignalMonitor(1, 0)<br>2013-10-11 17:27:51.043126 I card 'Hauppauge HD PVR' driver 'hdpvr' version '197170'<br>2013-10-11 17:27:51.043156 I TVRec[9]: Signal monitor successfully created<br>
2013-10-11 17:27:51.044661 I TVRec[9]: SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning,RingBufferReady,<br>2013-10-11 17:27:51.044687 I TVRec[9]: ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning,RingBufferReady,<br>
2013-10-11 17:27:51.044720 I TVRec[9]: SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady,<br>2013-10-11 17:27:51.044749 I TVRec[9]: ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady,<br>
2013-10-11 17:27:51.044771 I TVRec[9]: SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,RingBufferReady,<br>2013-10-11 17:27:51.044811 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>
2013-10-11 17:27:51.044878 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:27:51.044915 I ChannelBase[9]: GetScriptStatus() 142<br>2013-10-11 17:27:51.044930 I ChannelBase[9]: GetScriptStatus() 142 -> 1<br>
2013-10-11 17:27:51.048438 N AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 15 min<br>2013-10-11 17:27:51.049240 I Tuning recording: "The Middle":"The Legacy": channel 2768 on cardid 9, sourceid 2<br>
2013-10-11 17:27:51.095034 I ChannelBase[9]: GetScriptStatus() 142<br>2013-10-11 17:27:51.095047 I ChannelBase[9]: GetScriptStatus() 142 -> 1<br>2013-10-11 17:27:51.145157 I ChannelBase[9]: GetScriptStatus() 142<br>
2013-10-11 17:27:51.145175 I ChannelBase[9]: GetScriptStatus() 142 -> 1<br>2013-10-11 17:27:51.195303 I ChannelBase[9]: GetScriptStatus() 1<br>2013-10-11 17:27:51.195320 I ChannelBase[9]: GetScriptStatus() 1 -> 2<br>
2013-10-11 17:27:52.045043 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:27:53.045208 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:27:54.045395 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>
2013-10-11 17:27:55.045560 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:27:56.045726 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:27:57.045893 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>
2013-10-11 17:27:58.046058 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:27:59.046224 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:28:00.046387 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>
2013-10-11 17:28:01.046558 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:28:02.046722 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:28:03.046888 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>
2013-10-11 17:28:04.047038 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:28:04.689547 E ProgramInfo(2768_20131012002800.mpg): GetPlaybackURL: '2768_20131012002800.mpg' should be local, but it can not be found.<br>
2013-10-11 17:28:04.690868 E ProgramInfo(2768_20131012002800.mpg): GetPlaybackURL: '2768_20131012002800.mpg' should be local, but it can not be found.<br>2013-10-11 17:28:04.693141 I JobQueue: Metadata Lookup Starting for "The Middle":"The Legacy" recorded from channel 2768 at 2013-10-12T00:28:00Z<br>
2013-10-11 17:28:05.047216 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:28:05.722325 I MainServer::ANN Monitor<br>2013-10-11 17:28:05.722340 I adding: mythpc as a client (events: 0)<br>
2013-10-11 17:28:05.723176 I MainServer::ANN Monitor<br>2013-10-11 17:28:05.723189 I adding: mythpc as a client (events: 1)<br>2013-10-11 17:28:06.047370 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>
2013-10-11 17:28:07.047549 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:28:08.047715 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:28:09.047833 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>
2013-10-11 17:28:10.048003 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:28:11.048170 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:28:12.048335 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>
2013-10-11 17:28:13.048503 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:28:14.048664 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:28:15.048826 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>
2013-10-11 17:28:16.048991 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:28:17.049155 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:28:18.049318 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>
2013-10-11 17:28:19.049483 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:28:20.049644 I TVRec[9]: TuningSignalCheck: Still waiting. Will timeout @ 17:28:21.044<br>2013-10-11 17:28:21.049777 E TVRec[9]: TuningSignalCheck: SignalMonitor timed out<br>
2013-10-11 17:28:21.049811 I TVRec[9]: ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady,<br>2013-10-11 17:28:21.049885 I TVRec[9]: TeardownSignalMonitor() -- begin<br>
2013-10-11 17:28:21.050010 I Updating status for "The Middle":"The Legacy" on cardid 9 (Tuning => Recorder Failed)<br>2013-10-11 17:28:21.051043 I Reschedule requested for CHECK -9 902 0 UpdateRecStatus2 | The Middle | The Legacy | Mike's motives come into question when he punishes Axl by forbidding him to play in the last basketball game of the season; Sue wins a trophy; Brick has to wear his cousin's clothes. | EP011581190044<br>
2013-10-11 17:28:21.088567 I TVRec[9]: TeardownSignalMonitor() -- end<br>2013-10-11 17:28:21.088582 I TVRec[9]: ClearFlags(SignalMonitorRunning,) -> RunMainLoop,WaitingForSignal,RingBufferReady,<br>2013-10-11 17:28:21.088592 I TVRec[9]: ClearFlags(WaitingForSignal,) -> RunMainLoop,RingBufferReady,<br>
2013-10-11 17:28:21.139705 I Scheduled 226 items in 0.0 = 0.00 match + 0.00 check + 0.04 place<br>2013-10-11 17:28:37.346389 I Reschedule requested for MATCH 902 0 0 - DeleteRule The Middle<br>2013-10-11 17:28:37.438924 I Scheduled 225 items in 0.1 = 0.01 match + 0.00 check + 0.04 place<br>
2013-10-11 17:29:09.697782 E ProgramInfo(2768_20131012002800.mpg): GetPlaybackURL: '2768_20131012002800.mpg' should be local, but it can not be found.<br>2013-10-11 17:29:09.700265 E ProgramInfo(2768_20131012002800.mpg): GetPlaybackURL: '2768_20131012002800.mpg' should be local, but it can not be found.<br>
2013-10-11 17:29:09.702738 I JobQueue: Commercial Detection Starting for "The Middle":"The Legacy" recorded from channel 2768 at 2013-10-12T00:28:00Z<br>2013-10-11 17:29:10.360585 E ProgramInfo(2768_20131012002800.mpg): GetPlaybackURL: '2768_20131012002800.mpg' should be local, but it can not be found.<br>
2013-10-11 17:30:30.101986 I TVRec[9]: ClearFlags(FinishRecording,) -> RunMainLoop,RingBufferReady,<br>2013-10-11 17:30:30.102026 I TVRec[9]: Changing from RecordingOnly to None<br>2013-10-11 17:30:30.102047 I TVRec[9]: ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RingBufferReady,<br>
2013-10-11 17:30:30.102067 I TVRec[9]: HandleTuning Request: Program(NULL) channel() input() flags(CloseRec,KillRingBuffer,)<br>2013-10-11 17:30:30.102083 I TVRec[9]: ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady,<br>
2013-10-11 17:30:30.102760 I TVRec[9]: FinishedRecording(2768_2013-10-12T00:28:00Z)<br> title: The Middle<br> in recgroup: Default status: Recorder Failed:Recorder Failed not_dummy finished_now<br>
2013-10-11 17:30:30.103973 I SaveVideoProperties(0x3a, 0x02)<br>
2013-10-11 17:30:30.106287 I TVRec[9]: ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady,<br>2013-10-11 17:30:30.273278 I TVRec[9]: ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady,<br><br><br>
<div class="gmail_extra"><br><br><div class="gmail_quote"><br><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex"><div dir="ltr"><div class="gmail_extra">
<div class="gmail_quote"><div><span class=""><font color="#888888"><br></font></span></div><span class=""><font color="#888888"><div>John <br></div></font></span></div></div></div>
<br>_______________________________________________<br>
mythtv-users mailing list<br>
<a href="mailto:mythtv-users@mythtv.org">mythtv-users@mythtv.org</a><br>
<a href="http://www.mythtv.org/mailman/listinfo/mythtv-users" target="_blank">http://www.mythtv.org/mailman/listinfo/mythtv-users</a><br>
<br></blockquote></div><br></div></div></div>