[mythtv-users] HD-PVR stopped working

Fred Hamilton fred at yonkitime.com
Sat Oct 12 00:36:42 UTC 2013


 On Fri, Oct 11, 2013 at 3:48 PM, John P Poet <jppoet at gmail.com> wrote:

> On Fri, Oct 11, 2013 at 4:26 PM, Fred Hamilton <fred at yonkitime.com> wrote:
>
>> Running 0.27 + fixes on Ubuntu 12.04.
>>
>> 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.
>>
>> mythbackend.log just reports "(Tuning => Recorder Failed)" and then
>> reschedules.
>>
>> Where should I look next?
>>
>> 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.
>>
>> But "cat /dev/video0 > test.ts" works - I thought that was supposed to be
>> the hard part!  :-)
>>
>
> It sounds like myth *thinks* the tuning is failing.  Are you sure your
> channel-change script is reliably returning true?
>
> You probably should have mythbackend running with "-v record,channel" if
> you don't already.
>

Hi John,

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).

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":

2013-10-11 17:27:50.924133 I  TVRec[9]: RecordPending on inputid 1
2013-10-11 17:27:50.924477 I  TVRec[9]: StartRecording("The Middle":"The
Legacy")
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)
2013-10-11 17:27:50.994382 I  Using profile 'Default' to record
2013-10-11 17:27:50.995456 I  TVRec[9]: ClearFlags(CancelNextRecording,) ->
FrontendReady,RunMainLoop,RingBufferReady,
2013-10-11 17:27:51.006640 I  TVRec[9]: Changing from None to RecordingOnly
2013-10-11 17:27:51.006669 I  TVRec[9]:
ClearFlags(FrontendReady,CancelNextRecording,) ->
RunMainLoop,RingBufferReady,
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)
             recstartts(Sat Oct 12 00:28:00 2013) recendts(Sat Oct 12
00:30:00 2013)
             title(The Middle)) channel() input() flags(Recording,)
2013-10-11 17:27:51.007392 I  TVRec[9]: HW Tuner: 9->9
2013-10-11 17:27:51.007402 I  TVRec[9]: ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,RingBufferReady,
2013-10-11 17:27:51.007411 I  TVRec[9]: No recorder yet, calling
TuningFrequency
2013-10-11 17:27:51.007618 I  V4LChannel[9](/dev/video0): Device name
'Hauppauge HD PVR' driver 'hdpvr'.
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
2013-10-11 17:27:51.017572 I  ChannelBase[9]: Input #1: 'Component'
schan(2) sourceid(2) ccid(9)
2013-10-11 17:27:51.017592 I  ChannelBase[9]: Current Input #1: 'Component'
2013-10-11 17:27:51.017637 I  Global TVFormat Setting 'NTSC'
2013-10-11 17:27:51.017674 I  V4LChannel[9](/dev/video0): Input #1:
'Component' schan(2) tun() v4l2(NTSC)
2013-10-11 17:27:51.017691 I  V4LChannel[9](/dev/video0):
SetFormat(Default) fmt(NTSC) input(1)
2013-10-11 17:27:51.017704 I  Channel(/dev/video0)::SwitchToInput(in 1, '')
2013-10-11 17:27:51.019279 I  V4LChannel[9](/dev/video0):
SetInputAndFormat(1, NTSC) (v4l v2) input_switch: 0 mode_switch: 0
2013-10-11 17:27:51.019294 I  DTVChan[9](/dev/video0):
SetChannelByString(768):
2013-10-11 17:27:51.021307 I  V4LChannel[9](/dev/video0):
SetFormat(Default) fmt(NTSC) input(1)
2013-10-11 17:27:51.021321 I  DTVChan[9](/dev/video0):
SetChannelByString(768): success
2013-10-11 17:27:51.040828 I  ChannelBase[9]: Running command: 6200ch  768
2013-10-11 17:27:51.042951 I  TVRec[9]: Starting Signal Monitor
2013-10-11 17:27:51.042993 I  TVRec[9]: SetupSignalMonitor(1, 0)
2013-10-11 17:27:51.043126 I  card 'Hauppauge HD PVR' driver 'hdpvr'
version '197170'
2013-10-11 17:27:51.043156 I  TVRec[9]: Signal monitor successfully created
2013-10-11 17:27:51.044661 I  TVRec[9]: SetFlags(SignalMonitorRunning,) ->
RunMainLoop,SignalMonitorRunning,RingBufferReady,
2013-10-11 17:27:51.044687 I  TVRec[9]: ClearFlags(WaitingForSignal,) ->
RunMainLoop,SignalMonitorRunning,RingBufferReady,
2013-10-11 17:27:51.044720 I  TVRec[9]: SetFlags(WaitingForSignal,) ->
RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady,
2013-10-11 17:27:51.044749 I  TVRec[9]: ClearFlags(NeedToStartRecorder,) ->
RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady,
2013-10-11 17:27:51.044771 I  TVRec[9]: SetFlags(NeedToStartRecorder,) ->
RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,RingBufferReady,
2013-10-11 17:27:51.044811 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:27:51.044878 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:27:51.044915 I  ChannelBase[9]: GetScriptStatus() 142
2013-10-11 17:27:51.044930 I  ChannelBase[9]: GetScriptStatus() 142 -> 1
2013-10-11 17:27:51.048438 N  AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 15 min
2013-10-11 17:27:51.049240 I  Tuning recording: "The Middle":"The Legacy":
channel 2768 on cardid 9, sourceid 2
2013-10-11 17:27:51.095034 I  ChannelBase[9]: GetScriptStatus() 142
2013-10-11 17:27:51.095047 I  ChannelBase[9]: GetScriptStatus() 142 -> 1
2013-10-11 17:27:51.145157 I  ChannelBase[9]: GetScriptStatus() 142
2013-10-11 17:27:51.145175 I  ChannelBase[9]: GetScriptStatus() 142 -> 1
2013-10-11 17:27:51.195303 I  ChannelBase[9]: GetScriptStatus() 1
2013-10-11 17:27:51.195320 I  ChannelBase[9]: GetScriptStatus() 1 -> 2
2013-10-11 17:27:52.045043 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:27:53.045208 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:27:54.045395 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:27:55.045560 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:27:56.045726 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:27:57.045893 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:27:58.046058 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:27:59.046224 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:00.046387 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:01.046558 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:02.046722 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:03.046888 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:04.047038 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
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.
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.
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
2013-10-11 17:28:05.047216 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:05.722325 I  MainServer::ANN Monitor
2013-10-11 17:28:05.722340 I  adding: mythpc as a client (events: 0)
2013-10-11 17:28:05.723176 I  MainServer::ANN Monitor
2013-10-11 17:28:05.723189 I  adding: mythpc as a client (events: 1)
2013-10-11 17:28:06.047370 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:07.047549 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:08.047715 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:09.047833 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:10.048003 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:11.048170 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:12.048335 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:13.048503 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:14.048664 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:15.048826 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:16.048991 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:17.049155 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:18.049318 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:19.049483 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:20.049644 I  TVRec[9]: TuningSignalCheck: Still waiting.
Will timeout @ 17:28:21.044
2013-10-11 17:28:21.049777 E  TVRec[9]: TuningSignalCheck: SignalMonitor
timed out
2013-10-11 17:28:21.049811 I  TVRec[9]: ClearFlags(NeedToStartRecorder,) ->
RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady,
2013-10-11 17:28:21.049885 I  TVRec[9]: TeardownSignalMonitor() -- begin
2013-10-11 17:28:21.050010 I  Updating status for "The Middle":"The Legacy"
on cardid 9 (Tuning => Recorder Failed)
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
2013-10-11 17:28:21.088567 I  TVRec[9]: TeardownSignalMonitor() -- end
2013-10-11 17:28:21.088582 I  TVRec[9]: ClearFlags(SignalMonitorRunning,)
-> RunMainLoop,WaitingForSignal,RingBufferReady,
2013-10-11 17:28:21.088592 I  TVRec[9]: ClearFlags(WaitingForSignal,) ->
RunMainLoop,RingBufferReady,
2013-10-11 17:28:21.139705 I  Scheduled 226 items in 0.0 = 0.00 match +
0.00 check + 0.04 place
2013-10-11 17:28:37.346389 I  Reschedule requested for MATCH 902 0 0 -
DeleteRule The Middle
2013-10-11 17:28:37.438924 I  Scheduled 225 items in 0.1 = 0.01 match +
0.00 check + 0.04 place
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.
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.
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
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.
2013-10-11 17:30:30.101986 I  TVRec[9]: ClearFlags(FinishRecording,) ->
RunMainLoop,RingBufferReady,
2013-10-11 17:30:30.102026 I  TVRec[9]: Changing from RecordingOnly to None
2013-10-11 17:30:30.102047 I  TVRec[9]:
ClearFlags(FrontendReady,CancelNextRecording,) ->
RunMainLoop,RingBufferReady,
2013-10-11 17:30:30.102067 I  TVRec[9]: HandleTuning Request: Program(NULL)
channel() input() flags(CloseRec,KillRingBuffer,)
2013-10-11 17:30:30.102083 I  TVRec[9]: ClearFlags(RecorderRunning,) ->
RunMainLoop,RingBufferReady,
2013-10-11 17:30:30.102760 I  TVRec[9]:
FinishedRecording(2768_2013-10-12T00:28:00Z)
            title: The Middle
            in recgroup: Default status: Recorder Failed:Recorder Failed
not_dummy finished_now
2013-10-11 17:30:30.103973 I  SaveVideoProperties(0x3a, 0x02)
2013-10-11 17:30:30.106287 I  TVRec[9]: ClearFlags(RecorderRunning,) ->
RunMainLoop,RingBufferReady,
2013-10-11 17:30:30.273278 I  TVRec[9]: ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,RingBufferReady,






> John
>
> _______________________________________________
> mythtv-users mailing list
> mythtv-users at mythtv.org
> http://www.mythtv.org/mailman/listinfo/mythtv-users
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://www.mythtv.org/pipermail/mythtv-users/attachments/20131011/946b8bd3/attachment-0001.html>


More information about the mythtv-users mailing list