[SOLVED] Help! Upgrade distro and reinstall HDHomeRun unrecognised?

[jd_aus]

Hi All,

I'm stuck. Everything is working in MythTV (Mint 18.3 MythTV 0.28 HDHomeRun DVB-T) except when attempting to launch Live TV.

Linux mythtv 4.15.0-rc6+ #1 SMP Mon Jan 1 13:29:59 CET 2018 x86_64 x86_64 x86_64 GNU/Linux

Tuning works fine and all channels are discovered.

Here's the backend logs:

Code: Select all

mythic@mythtv ~ $ tail -f /var/log/mythtv/mythbackend.log 
Jan  7 22:40:37 mythtv mythbackend: mythbackend[1138]: E TVRecEvent tv_rec.cpp:3763 (TuningFrequency) TVRec[1]: Failed to set channel to 21. Reverting to kState_None
Jan  7 22:40:37 mythtv mythbackend: mythbackend[1138]: I TVRecEvent tv_rec.cpp:1073 (HandleStateChange) TVRec[1]: Changing from WatchingLiveTV to None
Jan  7 22:40:37 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(62) mainserver.cpp:7629 (connectionClosed) Playback sock(1bacc70) 'mythtv' disconnected
Jan  7 22:40:42 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan  7 22:40:42 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding: mythtv(1bad140) as a client (events: 0)
Jan  7 22:40:42 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(64) mainserver.cpp:7657 (connectionClosed) FileTransfer sock(1bad1c0) disconnected
Jan  7 22:40:42 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(62) mainserver.cpp:7629 (connectionClosed) Playback sock(1bad140) 'mythtv' disconnected
Jan  7 22:40:44 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(57) backendcontext.cpp:102 (SetFrontendDisconnected) BackendContext: Frontend 'mythtv' disconnected.
Jan  7 22:40:44 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(57) mainserver.cpp:7629 (connectionClosed) Playback sock(1bbb560) 'mythtv' disconnected
Jan  7 22:40:44 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(60) mainserver.cpp:7629 (connectionClosed) Monitor sock(1bbbf10) 'mythtv' disconnected
Jan  7 22:41:30 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer: MainServer::ANN Frontend
Jan  7 22:41:30 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding: mythtv(1ba2750) as a client (events: 0)
Jan  7 22:41:30 mythtv mythbackend: mythbackend[1138]: I ProcessRequest backendcontext.cpp:56 (SetFrontendConnected) BackendContext: Frontend 'mythtv' connected.
Jan  7 22:41:30 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan  7 22:41:30 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding: mythtv(1bbbf10) as a client (events: 1)
Jan  7 22:41:35 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan  7 22:41:35 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding: mythtv(1bac230) as a client (events: 0)
Jan  7 22:41:35 mythtv mythbackend: mythbackend[1138]: E ProcessRequest mythdb.cpp:183 (DBError) DB Error (ShouldSwitchToAnotherInput):#012Query was:#012SELECT channel.channum, channel.callsign FROM channel WHERE channel.chanid = ?#012Bindings were:#012:CHANID="21"#012No error type from QSqlError?  Strange...
Jan  7 22:41:35 mythtv mythbackend: mythbackend[1138]: I TVRecEvent tv_rec.cpp:1073 (HandleStateChange) TVRec[1]: Changing from None to WatchingLiveTV
Jan  7 22:41:35 mythtv mythbackend: mythbackend[1138]: I TVRecEvent tv_rec.cpp:3685 (TuningFrequency) TVRec[1]: TuningFrequency
Jan  7 22:41:35 mythtv mythbackend: mythbackend[1138]: E TVRecEvent dtvmultiplex.cpp:379 (ParseTuningParams) DTVMux: ParseTuningParams -- Unknown tuner type = 0xffffffff80000000
Jan  7 22:41:35 mythtv mythbackend: mythbackend[1138]: E TVRecEvent recorders/dtvchannel.cpp:299 (SetChannelByString) DTVChan[1](11100024-0): SetChannelByString(21): Failed to initialize multiplex options
Jan  7 22:41:35 mythtv mythbackend: mythbackend[1138]: E TVRecEvent tv_rec.cpp:3763 (TuningFrequency) TVRec[1]: Failed to set channel to 21. Reverting to kState_None
Jan  7 22:41:35 mythtv mythbackend: mythbackend[1138]: I TVRecEvent tv_rec.cpp:1073 (HandleStateChange) TVRec[1]: Changing from WatchingLiveTV to None
Jan  7 22:41:35 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(62) mainserver.cpp:7629 (connectionClosed) Playback sock(1bac230) 'mythtv' disconnected
Jan  7 22:41:38 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan  7 22:41:38 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding: mythtv(1bad020) as a client (events: 0)
Jan  7 22:41:38 mythtv mythbackend: mythbackend[1138]: E ProcessRequest mythdb.cpp:183 (DBError) DB Error (ShouldSwitchToAnotherInput):#012Query was:#012SELECT channel.channum, channel.callsign FROM channel WHERE channel.chanid = ?#012Bindings were:#012:CHANID="21"#012No error type from QSqlError?  Strange...
Jan  7 22:41:38 mythtv mythbackend: mythbackend[1138]: I TVRecEvent tv_rec.cpp:1073 (HandleStateChange) TVRec[1]: Changing from None to WatchingLiveTV
Jan  7 22:41:38 mythtv mythbackend: mythbackend[1138]: I TVRecEvent tv_rec.cpp:3685 (TuningFrequency) TVRec[1]: TuningFrequency
Jan  7 22:41:38 mythtv mythbackend: mythbackend[1138]: E TVRecEvent dtvmultiplex.cpp:379 (ParseTuningParams) DTVMux: ParseTuningParams -- Unknown tuner type = 0xffffffff80000000
Jan  7 22:41:38 mythtv mythbackend: mythbackend[1138]: E TVRecEvent recorders/dtvchannel.cpp:299 (SetChannelByString) DTVChan[1](11100024-0): SetChannelByString(21): Failed to initialize multiplex options
Jan  7 22:41:38 mythtv mythbackend: mythbackend[1138]: E TVRecEvent tv_rec.cpp:3763 (TuningFrequency) TVRec[1]: Failed to set channel to 21. Reverting to kState_None
Jan  7 22:41:38 mythtv mythbackend: mythbackend[1138]: I TVRecEvent tv_rec.cpp:1073 (HandleStateChange) TVRec[1]: Changing from WatchingLiveTV to None
Jan  7 22:41:38 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(62) mainserver.cpp:7629 (connectionClosed) Playback sock(1bad020) 'mythtv' disconnected
Jan  7 22:41:43 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(57) backendcontext.cpp:102 (SetFrontendDisconnected) BackendContext: Frontend 'mythtv' disconnected.
Jan  7 22:41:43 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(57) mainserver.cpp:7629 (connectionClosed) Playback sock(1ba2750) 'mythtv' disconnected
Jan  7 22:41:43 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(58) mainserver.cpp:7629 (connectionClosed) Monitor sock(1bbbf10) 'mythtv' disconnected
Jan  7 22:41:52 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer: MainServer::ANN Frontend
Jan  7 22:41:52 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding: mythtv(1ba2750) as a client (events: 0)
Jan  7 22:41:52 mythtv mythbackend: mythbackend[1138]: I ProcessRequest backendcontext.cpp:56 (SetFrontendConnected) BackendContext: Frontend 'mythtv' connected.
Jan  7 22:41:52 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan  7 22:41:52 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding: mythtv(1bbbf10) as a client (events: 1)
Jan  7 22:41:59 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(57) backendcontext.cpp:102 (SetFrontendDisconnected) BackendContext: Frontend 'mythtv' disconnected.
Jan  7 22:41:59 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(57) mainserver.cpp:7629 (connectionClosed) Playback sock(1ba2750) 'mythtv' disconnected
Jan  7 22:41:59 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(60) mainserver.cpp:7629 (connectionClosed) Monitor sock(1bbbf10) 'mythtv' disconnected

[Steve Goodey]

http://gedakc.users.sourceforge.net/dis ... -recording This of any use? Logs look a bit similair.

[jd_aus]

This and similar articles state that restarting the backend service can resolve the issue and the issue is related only to boot up. The problem for me is that it never connects regardless of whether I restart the backend service. I'll switch to using a reserved IP and see if that helps but it's unlikely to fix the issue as i can be constantly pinging the device; restart the backend and still get the same logs.

[jd_aus]

Ok I've updated my fritz.os on the router and reserved the IP for the HDHomeRun.

After switching the Tuners to use IP instead of broadcast I have the same symptons, however I notice there is a DB error? Given this DB was restored and upgraded from a very old MythTV install I'm wondering if I in fact have a DB issue?

Any assistance would be really appreciated as I'm not sure where to from here? (note I've installed LAMP in preparation for DB troubleshooting with phpmyadmin)

Code: Select all

Jan  8 08:20:59 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(56) mainserver.cpp:7629 (connectionClosed) Monitor sock(1b1f590) 'mythtv' disconnected
Jan  8 08:20:59 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(46) backendcontext.cpp:102 (SetFrontendDisconnected) BackendContext: Frontend 'mythtv' disconnected.
Jan  8 08:20:59 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(46) mainserver.cpp:7629 (connectionClosed) Playback sock(1bad360) 'mythtv' disconnected
Jan  8 08:20:59 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer: MainServer::ANN Frontend
Jan  8 08:20:59 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding: mythtv(1bad360) as a client (events: 0)
Jan  8 08:20:59 mythtv mythbackend: mythbackend[1138]: I ProcessRequest backendcontext.cpp:56 (SetFrontendConnected) BackendContext: Frontend 'mythtv' connected.
Jan  8 08:20:59 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan  8 08:20:59 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding: mythtv(1bad2e0) as a client (events: 1)
Jan  8 08:21:02 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan  8 08:21:02 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding: mythtv(1b9b940) as a client (events: 0)
Jan  8 08:21:02 mythtv mythbackend: mythbackend[1138]: E ProcessRequest mythdb.cpp:183 (DBError) DB Error (ShouldSwitchToAnotherInput):#012Query was:#012SELECT channel.channum, channel.callsign FROM channel WHERE channel.chanid = ?#012Bindings were:#012:CHANID="21"#012No error type from QSqlError?  Strange...
Jan  8 08:21:02 mythtv mythbackend: mythbackend[1138]: I TVRecEvent tv_rec.cpp:1073 (HandleStateChange) TVRec[1]: Changing from None to WatchingLiveTV
Jan  8 08:21:02 mythtv mythbackend: mythbackend[1138]: E TVRecEvent mythcorecontext.cpp:344 (ConnectToMasterServer) MythCoreContext::ConnectToMasterServer(): ERROR: Master backend tried to connect back to itself!
Jan  8 08:21:02 mythtv mythbackend: mythbackend[1138]: I TVRecEvent tv_rec.cpp:3685 (TuningFrequency) TVRec[1]: TuningFrequency
Jan  8 08:21:02 mythtv mythbackend: mythbackend[1138]: E TVRecEvent mythcorecontext.cpp:344 (ConnectToMasterServer) MythCoreContext::ConnectToMasterServer(): ERROR: Master backend tried to connect back to itself!
Jan  8 08:21:02 mythtv mythbackend: mythbackend[1138]: E TVRecEvent dtvmultiplex.cpp:379 (ParseTuningParams) DTVMux: ParseTuningParams -- Unknown tuner type = 0xffffffff80000000
Jan  8 08:21:02 mythtv mythbackend: mythbackend[1138]: E TVRecEvent recorders/dtvchannel.cpp:299 (SetChannelByString) DTVChan[1](11100024-0): SetChannelByString(21): Failed to initialize multiplex options
Jan  8 08:21:02 mythtv mythbackend: mythbackend[1138]: E TVRecEvent tv_rec.cpp:3763 (TuningFrequency) TVRec[1]: Failed to set channel to 21. Reverting to kState_None
Jan  8 08:21:02 mythtv mythbackend: mythbackend[1138]: I TVRecEvent tv_rec.cpp:1073 (HandleStateChange) TVRec[1]: Changing from WatchingLiveTV to None
Jan  8 08:21:02 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(62) mainserver.cpp:7629 (connectionClosed) Playback sock(1b9b940) 'mythtv' disconnected
Jan  8 08:21:13 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan  8 08:21:13 mythtv mythbackend: mythbackend[1138]: I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding: mythtv(1bac920) as a client (events: 0)
Jan  8 08:21:13 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(64) mainserver.cpp:7657 (connectionClosed) FileTransfer sock(1bbabe0) disconnected
Jan  8 08:21:13 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(62) mainserver.cpp:7629 (connectionClosed) Playback sock(1bac920) 'mythtv' disconnected
Jan  8 08:21:24 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(46) backendcontext.cpp:102 (SetFrontendDisconnected) BackendContext: Frontend 'mythtv' disconnected.
Jan  8 08:21:24 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(46) mainserver.cpp:7629 (connectionClosed) Playback sock(1bad360) 'mythtv' disconnected
Jan  8 08:21:24 mythtv mythbackend: mythbackend[1138]: I MythSocketThread(56) mainserver.cpp:7629 (connectionClosed) Monitor sock(1bad2e0) 'mythtv' disconnected

[Steve Goodey]

This looks weird.

ERROR: Master backend tried to connect back to itself!

http://lists.mythtv.org/pipermail/mytht ... 89688.html

[jd_aus]

Argh! Thanks.

I was pretty over the issue so I decided to sledgehammer the install:
- Deleted everything from mythtv-setup (Tuners, Sources etc.) - Didn't fix the issue
- Ran dpkg-reconfigure for mythtv-common, mythtv-database and mythtv-backend - Didn't fix the issue
- Checked all my config.xml files (found them all using locate mythtv/confix.xml)- all good
- I was about to pack it in but thought I'd have one more shot before reinstalling the distro:
- I found a derp, my host file had a typo - Not sure if this mattered? 172.0.1.1 mythtv - Corrected this to 127.0.1.1 mythtv and rebooted
- I recreated all the config using the broadcast config for the HDHomeRuns and ran mythfilldatabase.
- I noticed yet another DB error in the logs, and thought maybe it's a permissions issue?? So I reset all permissions on mythconverg

Re-Tested. Live TV launches as expected. So I think it was a combination of the bad loopback address and the incorrect mythconverg permissions. All existing recordings and rules are Ok

Thanks for the assistance.