[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