[mythtv-users] Can't tune HDHR

Thu May 14 10:32:09 UTC 2020

On Wed, May 13, 2020 at 10:50 PM Stephen Worthington <
stephen_agent at jsw.gen.nz> wrote:

> On Wed, 13 May 2020 16:56:07 -0700, you wrote:
>
> >On Wed, May 13, 2020 at 4:37 PM DryHeat122 . <dryheat122 at gmail.com>
> wrote:
> >
> >> On Wed, May 13, 2020 at 2:08 AM Stephen Worthington <
> >> stephen_agent at jsw.gen.nz> wrote:
> >>
> >>> On Tue, 12 May 2020 20:22:59 -0700, you wrote:
> >>>
> >>> >When it rains it pours I guess.  I was just posting about HDPVR
> problems
> >>> a
> >>> >few days ago.  Now Myth can't tune any channels on my HDHomerun Duo.
> >>> >Checked the troubleshooting section on the wiki, but it assumes the
> >>> problem
> >>> >is with the HDHR. I can tune channels just fine on a Windows machine
> on
> >>> the
> >>> >same network, so its not the HDHR.  Any advice on how to troubleshoot?
> >>>
> >>> Does it work if you restart mythbackend?  Are there any error messages
> >>> in mythbackend.log?  You can get additional logging about the
> >>> recording process by adding -v record to the mythbackend command line:
> >>>
> >>> root at mypvr:/etc/mythtv# cat additional.args
> >>> ADDITIONAL_ARGS=-v record
> >>>
> >>> Then restart mythbackend.  Or from a command line, temporarily add the
> >>> extra "record" logging while mythbackend is running:
> >>>
> >>> sudo mythbackend --setverbose record
> >>>
> >>> and turn it off again with:
> >>>
> >>> sudo mythbackend --setverbose norecord
> >>>
> >>> I am not sure if sudo is required above - all the second copy of
> >>> mythbackend is doing is to open a connection to a port on the main
> >>> mythbackend and send it the serverbose command.  So it may not need
> >>> any extra privileges.
> >>>
> >>> Thanks for the advice.  I checked the logs before doing anything, and
> >> found (machine ID stuff stripped out for readability):
> >>
> >> May 13 15:39:21  I ProcessRequest mainserver.cpp:1698 (HandleAnnounce)
> >> MainServer: MainServer::ANN Playback
> >> May 13 15:39:21  I ProcessRequest mainserver.cpp:1703 (HandleAnnounce)
> >> MainServer: adding: steve-EP45-UD3P(1f26320) as a client (events: 0)
> >> May 13 15:39:21  I TVRecEvent tv_rec.cpp:1073 (HandleStateChange)
> >> TVRec[2]: Changing from None to WatchingLiveTV
> >> May 13 15:39:21  I TVRecEvent mythdbcon.cpp:422 (PurgeIdleConnections)
> New
> >> DB connection, total: 13
> >> May 13 15:39:21  I TVRecEvent tv_rec.cpp:3685 (TuningFrequency)
> TVRec[2]:
> >> TuningFrequency
> >> May 13 15:39:21  N CoreContext autoexpire.cpp:264 (CalcParams)
> AutoExpire:
> >> CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min
> >> May 13 15:39:21  N TVRecEvent recordinginfo.cpp:687
> >> (ApplyRecordRecGroupChange) ApplyRecordRecGroupChange: LiveTV to LiveTV
> (2)
> >> May 13 15:39:25  W TVRecEvent tv_rec.cpp:4004 (TuningSignalCheck)
> >> TVRec[2]: TuningSignalCheck: taking more than 3000 ms to get a lock.
> >> marking this recording as 'Failing'.
> >> May 13 15:39:25  W TVRecEvent tv_rec.cpp:4006 (TuningSignalCheck)
> >> TVRec[2]: See 'Tuning timeout' in mythtv-setup for this input
> >> May 13 15:39:36  I TVRecEvent tv_rec.cpp:1073 (HandleStateChange)
> >> TVRec[2]: Changing from WatchingLiveTV to None
> >> May 13 15:39:36  I MythSocketThread(74) mainserver.cpp:7629
> >> (connectionClosed) Playback sock(1f26320) 'steve-EP45-UD3P' disconnected
> >>
> >> I then rebooted and tried to tune one of the HDHR channels (3_1) from
> the
> >> program guide.  Now it doesn't even try to tune and I get:
> >>
> >> May 13 15:55:33  I ProcessRequest mainserver.cpp:1698 (HandleAnnounce)
> >> MainServer: MainServer::ANN Playback
> >> May 13 15:55:33  I ProcessRequest mainserver.cpp:1703 (HandleAnnounce)
> >> MainServer: adding: steve-EP45-UD3P(13e54b0) as a client (events: 0)
> >> May 13 15:55:33  I TVRecEvent tv_rec.cpp:1073 (HandleStateChange)
> >> TVRec[2]: Changing from None to WatchingLiveTV
> >> May 13 15:55:33  I TVRecEvent tv_rec.cpp:3685 (TuningFrequency)
> TVRec[2]:
> >> TuningFrequency
> >> May 13 15:55:33  E TVRecEvent dtvmultiplex.cpp:379 (ParseTuningParams)
> >> DTVMux: ParseTuningParams -- Unknown tuner type = 0xffffffff80000000
> >> May 13 15:55:33  E TVRecEvent recorders/dtvchannel.cpp:299
> >> (SetChannelByString) DTVChan[2](10668BF0-0): SetChannelByString(3_1):
> >> Failed to initialize multiplex options
> >> May 13 15:55:33  E TVRecEvent tv_rec.cpp:3763 (TuningFrequency)
> TVRec[2]:
> >> Failed to set channel to 3_1. Reverting to kState_None
> >> May 13 15:55:33  I TVRecEvent tv_rec.cpp:1073 (HandleStateChange)
> >> TVRec[2]: Changing from WatchingLiveTV to None
> >> May 13 15:55:33  I MythSocketThread(67) mainserver.cpp:7629
> >> (connectionClosed) Playback sock(13e54b0) 'steve-EP45-UD3P' disconnected
> >>
> >> Hmm.  That doesn't look good.  I checked backend setup for tuners and
> >> nothing seems to be amiss.
> >>
> >> I also tried to follow your instructions for configuring verbose logging
> >> but it didn't work:
> >>
> >> steve at steve-EP45-UD3P:~$ cd /etc/mythtv
> >> steve at steve-EP45-UD3P:/etc/mythtv$ ls
> >> config.xml  config.xml.orig  session-settings
> >> steve at steve-EP45-UD3P:/etc/mythtv$ cat additional.args
> >> cat: additional.args: No such file or directory
> >> steve at steve-EP45-UD3P:/etc/mythtv$ ADDITIONAL_ARGS=-v record
> >> The program 'record' is currently not installed. You can install it by
> >> typing:
> >> sudo apt install xawtv-tools
> >> steve at steve-EP45-UD3P:/etc/mythtv$ cat additional.args
> >> cat: additional.args: No such file or directory
> >>
> >> Did you mean cat "ADDITIONAL_ARGS=-v record" > additional.args?
> >>
> >>
> >I just started mythbackend with the -v record option and got:
> >
> >2020-05-13 16:48:14.026697 I  MainServer: MainServer::ANN Playback
> >2020-05-13 16:48:14.026707 I  MainServer: adding: steve-EP45-UD3P(223c4b0)
> >as a client (events: 0)
> >2020-05-13 16:48:14.033902 I  TVRec[2]: Found channel (3_1) on current
> >input(2).
> >2020-05-13 16:48:14.044957 I  TVRec[2]: Changing from None to
> WatchingLiveTV
> >2020-05-13 16:48:14.044977 I  TVRec[2]:
> >ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, @
> >tv_rec.cpp:1301
> >2020-05-13 16:48:14.044991 I  TVRec[2]: HandleTuning Request:
> Program(NULL)
> >channel() input() flags(LiveTV,)
> >2020-05-13 16:48:14.047755 I  TVRec[2]: TuningShutdowns(Program(NULL)
> >channel(3_1) input() flags(LiveTV,))
> >2020-05-13 16:48:14.048112 I  TVRec[2]: ClearFlags(PENDINGACTIONS,) ->
> >RunMainLoop, @ tv_rec.cpp:3663
> >2020-05-13 16:48:14.048122 I  TVRec[2]: No recorder yet, calling
> >TuningFrequency
> >2020-05-13 16:48:14.048129 I  TVRec[2]: TuningFrequency
> >2020-05-13 16:48:14.050975 E  DTVMux: ParseTuningParams -- Unknown tuner
> >type = 0xffffffff80000000
> >2020-05-13 16:48:14.051000 E  DTVChan[2](10668BF0-0):
> >SetChannelByString(3_1): Failed to initialize multiplex options
> >2020-05-13 16:48:14.051010 E  TVRec[2]: Failed to set channel to 3_1.
> >Reverting to kState_None
> >2020-05-13 16:48:14.051028 I  TVRec[2]: Changing from WatchingLiveTV to
> None
> >2020-05-13 16:48:14.051209 I  TVRec[2]:
> >ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, @
> >tv_rec.cpp:1301
> >2020-05-13 16:48:14.051224 I  TVRec[2]: HandleTuning Request:
> Program(NULL)
> >channel() input() flags(KillRec,KillRingBuffer,)
> >2020-05-13 16:48:14.051236 I  TVRec[2]: TuningShutdowns(Program(NULL)
> >channel() input() flags(KillRec,KillRingBuffer,))
> >2020-05-13 16:48:14.051258 I  TVRec[2]: ClearFlags(PENDINGACTIONS,) ->
> >RunMainLoop, @ tv_rec.cpp:3663
> >2020-05-13 16:48:14.051291 I  TVRec[2]: SetFlags(CancelNextRecording,) ->
> >RunMainLoop,CancelNextRecording, @ tv_rec.cpp:2667
> >2020-05-13 16:48:14.051989 I  Playback sock(223c4b0) 'steve-EP45-UD3P'
> >disconnected
> >2020-05-13 16:48:32.395051 I  BackendContext: Frontend 'steve-EP45-UD3P'
> >disconnected.
> >2020-05-13 16:48:32.395070 I  Playback sock(224ec00) 'steve-EP45-UD3P'
> >disconnected
> >2020-05-13 16:48:32.395299 I  Monitor sock(1fdca30) 'steve-EP45-UD3P'
> >disconnected
>
> Two things:
>
> 1) In the prior log, I noticed that the tuning timeout was 3000 ms.
> That is too short these days.  It was the default value a long time
> ago, and anyone who has upgraded MythTV over the years will still have
> that, but the default these days I think is 10000 ms.  I know I had to
> change my settings to 10000 several versions of MythTV ago, as we have
> one DVB-T channel here in New Zealand ("Three") that normally takes
> over 7 seconds to tune.
>
> 2) I do not have any HDHRs, so I do not know exactly what messages
> should be seen when tuning one, but looking at the above log, I can
> not see anything that says mythbackend was actually ever receiving a
> reply from the HDHR.  So if it was my system, I would now be running
> Wireshark using a capture filter setting of "host <ip address of
> HDHR>" and seeing what the traffic was.  Was mythbackend sending
> messages to the right IP address?  Was the HDHR replying?  And also
> see what happens when VLC talks to the HDHR.
> _______________________________________________
> mythtv-users mailing list
> mythtv-users at mythtv.org
> http://lists.mythtv.org/mailman/listinfo/mythtv-users
> http://wiki.mythtv.org/Mailing_List_etiquette
> MythTV Forums: https://forum.mythtv.org

Is the HDHR firmware up to date?
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.mythtv.org/pipermail/mythtv-users/attachments/20200514/04e6bd6d/attachment.htm>