Mailing List Archive

hdpvr failing to tune
hi,
I have a problem with my hdpvr that I can't seem to solve. It used to work
fine under LInux Mint, but when I migrated to Fedora 34, it no longer tunes
with mythtv. "cat /dev/video0" works fine, so it does not appear to be a
driver or hardware problem. I used the mythtv backup/restore tool to move
my database from Mint to Fedora, and I think I must have introduced some
little problem.
Below are the logs when I try to record. NOTE: where it says it is tuning
recording to "channel 21505". The actual channel is 1505 as displayed in
myth (and on my cable box). Could this be the problem? Assuming so, how can
I stop it from pre-pending an extra digit?
Thank you for any tips.

2021-07-02 14:09:02.690706 I [1260/1699] TVRecEvent
recorders/analogsignalmonitor.cpp:34 (AnalogSignalMonitor) - card
'Hauppauge HD PVR' driver 'hdpvr' version '330764'
2021-07-02 14:09:02.690713 I [1260/1699] TVRecEvent tv_rec.cpp:2057
(SetupSignalMonitor) - TVRec[9]: Signal monitor successfully created
2021-07-02 14:09:02.691273 I [1260/1699] TVRecEvent tv_rec.cpp:4424
(SetFlags) - TVRec[9]: SetFlags(SignalMonitorRunning,) ->
RunMainLoop,SignalMonitorRunning, @ tv_rec.cpp:3825
2021-07-02 14:09:02.691303 I [1260/1699] TVRecEvent tv_rec.cpp:4433
(ClearFlags) - TVRec[9]: ClearFlags(WaitingForSignal,) ->
RunMainLoop,SignalMonitorRunning, @ tv_rec.cpp:3826
2021-07-02 14:09:02.691322 I [1260/1699] TVRecEvent tv_rec.cpp:4424
(SetFlags) - TVRec[9]: SetFlags(WaitingForSignal,) ->
RunMainLoop,WaitingForSignal,SignalMonitorRunning, @ tv_rec.cpp:3831
2021-07-02 14:09:02.691339 I [1260/1699] TVRecEvent tv_rec.cpp:4433
(ClearFlags) - TVRec[9]: ClearFlags(NeedToStartRecorder,) ->
RunMainLoop,WaitingForSignal,SignalMonitorRunning, @ tv_rec.cpp:3892
2021-07-02 14:09:02.691350 I [1260/1699] TVRecEvent tv_rec.cpp:4424
(SetFlags) - TVRec[9]: SetFlags(NeedToStartRecorder,) ->
RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning, @
tv_rec.cpp:3894
2021-07-02 14:09:02.691421 I [1260/1699] TVRecEvent tv_rec.cpp:4009
(TuningSignalCheck) - TVRec[9]: TuningSignalCheck: Still waiting. Will
timeout @ 14:29:50.000
2021-07-02 14:09:02.692495 I [1260/1937] Scheduler scheduler.cpp:2882
(HandleRecordingStatusChange) - Tuning recording: Mom:"Crazy Hair and a
Teeny Tiny Part of Canada": channel 21505 on cardid [9], sourceid 2
2021-07-02 14:09:02.825969 C [1260/1260] CoreContext programinfo.cpp:257
(ProgramInfo) - ProgramInfo(): Failed to find recorded entry for 0.
2021-07-02 14:09:07.692368 I [1260/1699] TVRecEvent tv_rec.cpp:4009
(TuningSignalCheck) - TVRec[9]: TuningSignalCheck: Still waiting. Will
timeout @ 14:29:50.000
2021-07-02 14:09:13.693427 I [1260/1699] TVRecEvent tv_rec.cpp:4009
(TuningSignalCheck) - TVRec[9]: TuningSignalCheck: Still waiting. Will
timeout @ 14:29:50.000
2021-07-02 14:09:16.693709 E [1260/1699] TVRecEvent tv_rec.cpp:3967
(TuningSignalCheck) - TVRec[9]: TuningSignalCheck: Hit pre-fail timeout
2021-07-02 14:09:20.695767 I [1260/1699] TVRecEvent tv_rec.cpp:4009
(TuningSignalCheck) - TVRec[9]: TuningSignalCheck: Still waiting. Will
timeout @ 14:29:50.000
2021-07-02 14:09:22.698034 W [1260/1699] TVRecEvent tv_rec.cpp:3995
(TuningSignalCheck) - TVRec[9]: TuningSignalCheck: taking more than 20000
ms to get a lock. marking this recording as 'Failing'.
Re: hdpvr failing to tune [ In reply to ]
On Fri, Jul 2, 2021 at 12:19 PM Mary Strimel <mary.strimel@gmail.com> wrote:

> hi,
> I have a problem with my hdpvr that I can't seem to solve. It used to work
> fine under LInux Mint, but when I migrated to Fedora 34, it no longer tunes
> with mythtv. "cat /dev/video0" works fine, so it does not appear to be a
> driver or hardware problem. I used the mythtv backup/restore tool to move
> my database from Mint to Fedora, and I think I must have introduced some
> little problem.
> Below are the logs when I try to record. NOTE: where it says it is tuning
> recording to "channel 21505". The actual channel is 1505 as displayed in
> myth (and on my cable box). Could this be the problem? Assuming so, how can
> I stop it from pre-pending an extra digit?
> Thank you for any tips.
>
> 2021-07-02 14:09:02.690706 I [1260/1699] TVRecEvent
> recorders/analogsignalmonitor.cpp:34 (AnalogSignalMonitor) - card
> 'Hauppauge HD PVR' driver 'hdpvr' version '330764'
> 2021-07-02 14:09:02.690713 I [1260/1699] TVRecEvent tv_rec.cpp:2057
> (SetupSignalMonitor) - TVRec[9]: Signal monitor successfully created
> 2021-07-02 14:09:02.691273 I [1260/1699] TVRecEvent tv_rec.cpp:4424
> (SetFlags) - TVRec[9]: SetFlags(SignalMonitorRunning,) ->
> RunMainLoop,SignalMonitorRunning, @ tv_rec.cpp:3825
> 2021-07-02 14:09:02.691303 I [1260/1699] TVRecEvent tv_rec.cpp:4433
> (ClearFlags) - TVRec[9]: ClearFlags(WaitingForSignal,) ->
> RunMainLoop,SignalMonitorRunning, @ tv_rec.cpp:3826
> 2021-07-02 14:09:02.691322 I [1260/1699] TVRecEvent tv_rec.cpp:4424
> (SetFlags) - TVRec[9]: SetFlags(WaitingForSignal,) ->
> RunMainLoop,WaitingForSignal,SignalMonitorRunning, @ tv_rec.cpp:3831
> 2021-07-02 14:09:02.691339 I [1260/1699] TVRecEvent tv_rec.cpp:4433
> (ClearFlags) - TVRec[9]: ClearFlags(NeedToStartRecorder,) ->
> RunMainLoop,WaitingForSignal,SignalMonitorRunning, @ tv_rec.cpp:3892
> 2021-07-02 14:09:02.691350 I [1260/1699] TVRecEvent tv_rec.cpp:4424
> (SetFlags) - TVRec[9]: SetFlags(NeedToStartRecorder,) ->
> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning, @
> tv_rec.cpp:3894
> 2021-07-02 14:09:02.691421 I [1260/1699] TVRecEvent tv_rec.cpp:4009
> (TuningSignalCheck) - TVRec[9]: TuningSignalCheck: Still waiting. Will
> timeout @ 14:29:50.000
> 2021-07-02 14:09:02.692495 I [1260/1937] Scheduler scheduler.cpp:2882
> (HandleRecordingStatusChange) - Tuning recording: Mom:"Crazy Hair and a
> Teeny Tiny Part of Canada": channel 21505 on cardid [9], sourceid 2
> 2021-07-02 14:09:02.825969 C [1260/1260] CoreContext programinfo.cpp:257
> (ProgramInfo) - ProgramInfo(): Failed to find recorded entry for 0.
> 2021-07-02 14:09:07.692368 I [1260/1699] TVRecEvent tv_rec.cpp:4009
> (TuningSignalCheck) - TVRec[9]: TuningSignalCheck: Still waiting. Will
> timeout @ 14:29:50.000
> 2021-07-02 14:09:13.693427 I [1260/1699] TVRecEvent tv_rec.cpp:4009
> (TuningSignalCheck) - TVRec[9]: TuningSignalCheck: Still waiting. Will
> timeout @ 14:29:50.000
> 2021-07-02 14:09:16.693709 E [1260/1699] TVRecEvent tv_rec.cpp:3967
> (TuningSignalCheck) - TVRec[9]: TuningSignalCheck: Hit pre-fail timeout
> 2021-07-02 14:09:20.695767 I [1260/1699] TVRecEvent tv_rec.cpp:4009
> (TuningSignalCheck) - TVRec[9]: TuningSignalCheck: Still waiting. Will
> timeout @ 14:29:50.000
> 2021-07-02 14:09:22.698034 W [1260/1699] TVRecEvent tv_rec.cpp:3995
> (TuningSignalCheck) - TVRec[9]: TuningSignalCheck: taking more than 20000
> ms to get a lock. marking this recording as 'Failing'.
>

The leading 2 is the "source id". This is how mythtv keeps the same channel
number from different sources from conflicting with each other.

We really need to get rid of the [C]ritical message that it failed to find
the recorded entry for 0. Or, track down why that message is appearing and
is at the critical level, since it is unimportant.

When you record it in MythTV, does the red light on the HD-PVR light up and
stay lit?
Can you show more of the log. For example, I would want to see that it is
trying to use /dev/video0 and not something else by mistake.

John
Re: hdpvr failing to tune [ In reply to ]
On Fri, Jul 9, 2021 at 12:15 PM John P Poet <jppoet@gmail.com> wrote:

> On Fri, Jul 2, 2021 at 12:19 PM Mary Strimel <mary.strimel@gmail.com>
> wrote:
>
>> hi,
>> I have a problem with my hdpvr that I can't seem to solve. It used to
>> work fine under LInux Mint, but when I migrated to Fedora 34, it no longer
>> tunes with mythtv. "cat /dev/video0" works fine, so it does not appear to
>> be a driver or hardware problem. I used the mythtv backup/restore tool to
>> move my database from Mint to Fedora, and I think I must have introduced
>> some little problem.
>> Below are the logs when I try to record. NOTE: where it says it is tuning
>> recording to "channel 21505". The actual channel is 1505 as displayed in
>> myth (and on my cable box). Could this be the problem? Assuming so, how can
>> I stop it from pre-pending an extra digit?
>> Thank you for any tips.
>>
>> 2021-07-02 14:09:02.690706 I [1260/1699] TVRecEvent
>> recorders/analogsignalmonitor.cpp:34 (AnalogSignalMonitor) - card
>> 'Hauppauge HD PVR' driver 'hdpvr' version '330764'
>> 2021-07-02 14:09:02.690713 I [1260/1699] TVRecEvent tv_rec.cpp:2057
>> (SetupSignalMonitor) - TVRec[9]: Signal monitor successfully created
>> 2021-07-02 14:09:02.691273 I [1260/1699] TVRecEvent tv_rec.cpp:4424
>> (SetFlags) - TVRec[9]: SetFlags(SignalMonitorRunning,) ->
>> RunMainLoop,SignalMonitorRunning, @ tv_rec.cpp:3825
>> 2021-07-02 14:09:02.691303 I [1260/1699] TVRecEvent tv_rec.cpp:4433
>> (ClearFlags) - TVRec[9]: ClearFlags(WaitingForSignal,) ->
>> RunMainLoop,SignalMonitorRunning, @ tv_rec.cpp:3826
>> 2021-07-02 14:09:02.691322 I [1260/1699] TVRecEvent tv_rec.cpp:4424
>> (SetFlags) - TVRec[9]: SetFlags(WaitingForSignal,) ->
>> RunMainLoop,WaitingForSignal,SignalMonitorRunning, @ tv_rec.cpp:3831
>> 2021-07-02 14:09:02.691339 I [1260/1699] TVRecEvent tv_rec.cpp:4433
>> (ClearFlags) - TVRec[9]: ClearFlags(NeedToStartRecorder,) ->
>> RunMainLoop,WaitingForSignal,SignalMonitorRunning, @ tv_rec.cpp:3892
>> 2021-07-02 14:09:02.691350 I [1260/1699] TVRecEvent tv_rec.cpp:4424
>> (SetFlags) - TVRec[9]: SetFlags(NeedToStartRecorder,) ->
>> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning, @
>> tv_rec.cpp:3894
>> 2021-07-02 14:09:02.691421 I [1260/1699] TVRecEvent tv_rec.cpp:4009
>> (TuningSignalCheck) - TVRec[9]: TuningSignalCheck: Still waiting. Will
>> timeout @ 14:29:50.000
>> 2021-07-02 14:09:02.692495 I [1260/1937] Scheduler scheduler.cpp:2882
>> (HandleRecordingStatusChange) - Tuning recording: Mom:"Crazy Hair and a
>> Teeny Tiny Part of Canada": channel 21505 on cardid [9], sourceid 2
>> 2021-07-02 14:09:02.825969 C [1260/1260] CoreContext programinfo.cpp:257
>> (ProgramInfo) - ProgramInfo(): Failed to find recorded entry for 0.
>> 2021-07-02 14:09:07.692368 I [1260/1699] TVRecEvent tv_rec.cpp:4009
>> (TuningSignalCheck) - TVRec[9]: TuningSignalCheck: Still waiting. Will
>> timeout @ 14:29:50.000
>> 2021-07-02 14:09:13.693427 I [1260/1699] TVRecEvent tv_rec.cpp:4009
>> (TuningSignalCheck) - TVRec[9]: TuningSignalCheck: Still waiting. Will
>> timeout @ 14:29:50.000
>> 2021-07-02 14:09:16.693709 E [1260/1699] TVRecEvent tv_rec.cpp:3967
>> (TuningSignalCheck) - TVRec[9]: TuningSignalCheck: Hit pre-fail timeout
>> 2021-07-02 14:09:20.695767 I [1260/1699] TVRecEvent tv_rec.cpp:4009
>> (TuningSignalCheck) - TVRec[9]: TuningSignalCheck: Still waiting. Will
>> timeout @ 14:29:50.000
>> 2021-07-02 14:09:22.698034 W [1260/1699] TVRecEvent tv_rec.cpp:3995
>> (TuningSignalCheck) - TVRec[9]: TuningSignalCheck: taking more than 20000
>> ms to get a lock. marking this recording as 'Failing'.
>>
>
> The leading 2 is the "source id". This is how mythtv keeps the same
> channel number from different sources from conflicting with each other.
>
> We really need to get rid of the [C]ritical message that it failed to find
> the recorded entry for 0. Or, track down why that message is appearing and
> is at the critical level, since it is unimportant.
>
> When you record it in MythTV, does the red light on the HD-PVR light up
> and stay lit?
> Can you show more of the log. For example, I would want to see that it is
> trying to use /dev/video0 and not something else by mistake.
>
> John
> _______________________________________________
> mythtv-users mailing list
> mythtv-users@mythtv.org
> http://lists.mythtv.org/mailman/listinfo/mythtv-users
> http://wiki.mythtv.org/Mailing_List_etiquette
> MythTV Forums: https://forum.mythtv.org


Thank you so much for replying. In the meantime, after many failed searches
I finally found someone who had my problem, here
<https://forum.mythtv.org/viewtopic.php?t=2300>. The issue was my channel
change script ... it was throwing many errors due to lirc that I didn't
realize were there because I had simply ported it over from a working
install. Apparently an error in the channel change process will cause the
hdpvr not to tune at all. Props to kmpatel for pointing out that I could
just substitute "/bin/true" for the channel change script and isolate the
problem. I even got lirc working again, miracle upon miracle. Thanks for
answering!