[SOLVED] Commercial skipping not working on V31

For discussion related to MythTV which doesn't belong in another forum.

Moderator: Forum Moderators

Post Reply
rocknrobin72
Junior
Posts: 21
Joined: Thu Mar 26, 2020 8:07 pm
United States of America

[SOLVED] Commercial skipping not working on V31

Post by rocknrobin72 » Sun Apr 12, 2020 11:25 pm

I have had MythTV v31 installed and running for a couple of weeks. I am using an HDhomerun HDHR-US two tuner for the source and for now just using the transmitted guide. When I first started recording shows the commercial skipping was working fine. It has now stopped. After a show records I see the message in the backend:

Code: Select all

JobQueue: Commercial Detection Starting for "blah-blah-blah" recorded from channel 2051 at 2020-04-12T22:30:00Z
but I never see whether the this job was successful or not. In addition before the Commercial detection message I also see:

Code: Select all

JobQueue: Metadata Lookup Starting for "blah-blah-blah" recorded from channel 2051 at 2020-04-12T22:30:00Z
JobQueue: Metadata Lookup Errored: "blah-blah-blah" recorded from channel 2051 at 2020-04-12T22:30:00Z (Failed with Exit Status 128)
the above message that the Metadata Lookup errored. In reading some posts on this forum I see that the Metadata Lookup issue can be a problem with the python bindings. I made sure when I did the configure on the install that the bindings for Perl, Python and PHP were satisfied before I continued. When I did the install though it was against the Python version 3.7.2. Since then my Slackware distro was updated to Python version 3.8.2. Since that happened I ran configure again and the python bindings were not satisfied so I had to re-install mysqlclient to get back the MySQLdb requirement. I now have a clean configure again. Will I need to run make and make install again to re-compile my mythtv against the new version of Python? If that is the case any time there are changes to either Perl, Python, or PHP will I have to install mythtv again? As an addendum if I do not supply an alias, ie alias python=python3, and I run python --version the response is version 2.7.12. Do I need to have the alias in place every time I reboot for applications to line up with the latest version of python?
Last edited by rocknrobin72 on Wed Apr 22, 2020 7:22 pm, edited 1 time in total.

User avatar
bill6502
Developer
Posts: 1722
Joined: Fri Feb 07, 2014 5:28 pm
United States of America

Re: Commercial skipping not working on V31

Post by bill6502 » Mon Apr 13, 2020 12:30 am

Short answer, yes. But not really. The bindings (if building from source and using the default
location of /usr/local) will move every time the minor version changes. In my experience,
that's rare. Your bindings likely live in /usr/local/lib/python3.x/dist-packages/MythTV. Where
x just changed from 7 to 8. You can change to the bindings/python directory and run make
for just those bindings. E.g.

Code: Select all

make clean PREFIX=/usr/local PYTHON=python3
make PREFIX=/usr/local PYTHON=python3
sudo make install PREFIX=/usr/local PYTHON=python3
For information only, type:

Code: Select all

python
import sys
sys.version_info

User avatar
bill6502
Developer
Posts: 1722
Joined: Fri Feb 07, 2014 5:28 pm
United States of America

Re: Commercial skipping not working on V31

Post by bill6502 » Mon Apr 13, 2020 1:01 am

I'd expect to see a mythcommflag.log and that have a clue about what's [not] happening

Also, with the backend running type: mythbackend --setverbose commflag
and that turns on additional messages, or at least the ability to print them

Turn off with the same command only commflag:alert

rocknrobin72
Junior
Posts: 21
Joined: Thu Mar 26, 2020 8:07 pm
United States of America

Re: Commercial skipping not working on V31

Post by rocknrobin72 » Mon Apr 13, 2020 10:41 pm

Thanks for the reply in the previous post. I did as suggested and ran the first code snippet to make clean, make, and make install after the configure ran reflecting the Perl, Python, and PHP bindings were there. The python bindings being there are illustrated in the fact that /usr/lib64/python3.8/site-packages/ now has the MySQLdb directory and the MythTV directories whereas it didn't before. ...and yes the mythcommflag is enabled in mythsetup. I also ran across a command to test the bindings for running part of the Metadata scripts. It returns this now:

Code: Select all

#  /usr/local/share/mythtv/metadata/Movie/tmdb3.py --test
Everything appears in order.
whereas the above command returned two lines complaining about the python bindings not being in place previously.

After another recording today I still get the same thing:

Code: Select all

2020-04-13 17:00:00.367648 I  JobQueue: Metadata Lookup Starting for "blah-blah" recorded from channel 2041 at 2020-04-13T21:46:00Z
2020-04-13 17:00:03.815020 E  JobQueue: Metadata Lookup Errored: "blah-blah" recorded from channel 2041 at 2020-04-13T21:46:00Z (Failed with Exit Status 128)
2020-04-13 17:00:05.372803 I  JobQueue: Commercial Detection Starting for "blahblah" recorded from channel 2041 at 2020-04-13T21:46:00Z
and I never see the message that the JobQueue Commercial Detection finished successfully.
Am I chasing down the wrong rabbit hole for assuming that the Commercial Detection has to do with the python bindings? At this point I don't know of anything else that changed from the initial installation other than the upgrade from Python 3.7.2 to 3.8.2 as the commercial skipping was working.

User avatar
bill6502
Developer
Posts: 1722
Joined: Fri Feb 07, 2014 5:28 pm
United States of America

Re: Commercial skipping not working on V31

Post by bill6502 » Mon Apr 13, 2020 10:48 pm

You are, commercial flagging doesn't use any bindings.

Did you check mythcommflag.log? If that doesn't help, add the verbose options mentioned on the 12th.

rocknrobin72
Junior
Posts: 21
Joined: Thu Mar 26, 2020 8:07 pm
United States of America

Re: Commercial skipping not working on V31

Post by rocknrobin72 » Mon Apr 13, 2020 10:59 pm

Thanks again for the update. I didn't see your 8:01 pm update from yesterday until I just submitted my last post reply. That is another weird thing about my installation. There was a /var/log/mythtv directory created, but nothing is ever written to it. In addition I can't find a mythcommflag.log. When I tried to launch the backend with the verbose argument it would not start up.

Code: Select all

mythtv@XXX:/root$ mythbackend --setverbose commflag
2020-04-13 17:45:13.334082 C  mythbackend version: fixes/31 [v31.0-9579662cdcb] www.mythtv.org
2020-04-13 17:45:13.334106 C  Qt version: compile: 5.13.2, runtime: 5.13.2
2020-04-13 17:45:13.334145 I  Slackware 14.2 x86_64 (post 14.2 -current) (x86_64)
2020-04-13 17:45:13.334151 N  Enabled verbose msgs:  general
2020-04-13 17:45:13.334162 N  Setting Log Level to LOG_INFO
2020-04-13 17:45:13.344554 I  Added logging to the console
2020-04-13 17:45:13.344897 I  Setup Interrupt handler
2020-04-13 17:45:13.344910 I  Setup Terminated handler
2020-04-13 17:45:13.344917 I  Setup Segmentation fault handler
2020-04-13 17:45:13.344927 I  Setup Aborted handler
2020-04-13 17:45:13.344933 I  Setup Bus error handler
2020-04-13 17:45:13.344942 I  Setup Floating point exception handler
2020-04-13 17:45:13.344949 I  Setup Illegal instruction handler
2020-04-13 17:45:13.344960 I  Setup Real-time signal 0 handler
2020-04-13 17:45:13.344968 I  Setup Hangup handler
2020-04-13 17:45:13.345105 N  Using runtime prefix = /usr/local
2020-04-13 17:45:13.345109 N  Using configuration directory = /home/mythtv/.mythtv
2020-04-13 17:45:13.345149 I  Assumed character encoding: en_US
2020-04-13 17:45:13.345157 W  This application expects to be running a locale that specifies a UTF-8 codeset, and many features may behave improperly with your current language settings. Please set the LC_ALL or LC_CTYPE, and LANG variable(s) in the environment in which this program is executed to include a UTF-8 codeset (such as 'en_US.UTF-8').
2020-04-13 17:45:13.345527 I  Empty LocalHostName. This is typical.
2020-04-13 17:45:13.345534 I  Using a profile name of: 'robrutrm.marsinnovations.us' (Usually the same as this host's name.)
2020-04-13 17:45:13.345762 I  Start up testing connections. DB localhost, BE , attempt 0, status dbAwake, Delay: 2000
2020-04-13 17:45:14.356190 N  Setting QT default locale to en_US
2020-04-13 17:45:14.356207 I  Current locale en_US
2020-04-13 17:45:14.356270 N  Reading locale defaults from /usr/local/share/mythtv//locales/en_us.xml
2020-04-13 17:45:14.366062 W  PowerDBus: No login1 interface. Cannot change system power state
2020-04-13 17:45:14.367689 I  PowerDBus: Added UPower.Device '/org/freedesktop/UPower/devices/ups_hiddev0'
2020-04-13 17:45:14.367696 I  Power: Unknown power source
2020-04-13 17:45:14.368105 I  Power: Supported actions: None
2020-04-13 17:45:14.369105 I  Loading en_us translation for module mythfrontend
2020-04-13 17:45:14.371454 I  MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 10.160.134.60:6543 (try 1 of 1)
2020-04-13 17:45:14.372076 E  MythSocket(1318070:-1): Failed to connect to (127.0.0.1:6543) Connection refused
2020-04-13 17:45:14.372201 E  Connection to master server timed out.
                        Either the server is down or the master server settings
                        in mythtv-settings does not contain the proper IP address

2020-04-13 17:45:14.372215 E  Unable to connect to backend, verbose mask unchanged
2020-04-13 17:45:14.372911 I  PowerDBus: Closing interfaces
2020-04-13 17:45:14.373388 I  Exiting

Therefore I am unable to provide any logs. That is another issue I was going to dive into after getting this fixed, but it is apparent I need to get some logging working first.
FYI, the backend launches and runs just fine without the setverbose argument.

User avatar
bill6502
Developer
Posts: 1722
Joined: Fri Feb 07, 2014 5:28 pm
United States of America

Re: Commercial skipping not working on V31

Post by bill6502 » Mon Apr 13, 2020 11:06 pm

mythbackend --setverbose commflag is used to change the verbose level on a running backend,
it doesn't start a backend.

Make sure user mythtv has write permission on /var/log/mythtv. If you start your frontend as yourself,
which is common, you need write permission on the directory too.

rocknrobin72
Junior
Posts: 21
Joined: Thu Mar 26, 2020 8:07 pm
United States of America

Re: Commercial skipping not working on V31

Post by rocknrobin72 » Tue Apr 14, 2020 12:18 am

Thanks again. I ran the setverbose command again and indeed I see :

Code: Select all

2020-04-13 18:58:40.125498 N  MainServer: Verbose mask changed, new mask is:  general commflag
in the running backend.
mythtv has write permission in var/log/mythtv.

Code: Select all

mythtv@XXX:/var/log$ ls -l
drwxr-xr-x 2 mythtv mythtv     4096 Apr 13 19:01 mythtv
mythtv@XXX:/var/log$ cd mythtv
mythtv@XXX:/var/log/mythtv$ ls -l
total 4
-rw-r--r-- 1 mythtv mythtv 5 Apr 13 19:01 Testlog.txt
mythtv@XXX:/var/log/mythtv$ cat Testlog.txt
Test
mythtv@XXX:/var/log/mythtv$
As can be seen the owner and user for var/log/mythtv is mythtv:mythtv. When I sudo su to mythtv I am able to enter the /var/log/mythtv directory and also I created Testlog.txt with a text editor.
I don't really use the mythtv frontend on the Slack box, I am using Kodi as a frontend from both my Android TV and from my home PC as remote frontends. In the mythtv add on configuration Kodi is set up to skip commercials. As I said the skp commercial functionality was working up until last week sometime.
Now with setverbose configured on the backend I will run another test to see if I get any data when the Commercial Detection runs.

rocknrobin72
Junior
Posts: 21
Joined: Thu Mar 26, 2020 8:07 pm
United States of America

Re: Commercial skipping not working on V31

Post by rocknrobin72 » Tue Apr 14, 2020 3:07 pm

Okay, thanks again for all the updates. I am learning as I go here. I was not running the backend in the background before. I set it up to run in the background and after it launched I ran mythbackend --setverbose commflag and in addition mythbackend -- setloglevel debug and voila, I now see in my /var/log/mythtv:

Code: Select all

/var/log/mythtv# ls -l
total 260
-rw-r--r-- 1 mythtv mythtv      5 Apr 13 19:01 Testlog.txt
-rw-r--r-- 1 mythtv mythtv  20818 Apr 13 21:30 mythbackend.20200414022309.11504.log
-rw-r--r-- 1 mythtv mythtv 167818 Apr 14 09:37 mythbackend.20200414141503.12461.log
-rw-r--r-- 1 mythtv mythtv  31233 Apr 14 09:36 mythcommflag.20200414143019.12658.log
-rw-r--r-- 1 mythtv mythtv   8210 Apr 14 09:30 mythmetadatalookup.20200414143014.12642.log
-rw-r--r-- 1 mythtv mythtv  10194 Apr 14 09:18 mythpreviewgen.20200414141803.12613.log
-rw-r--r-- 1 mythtv mythtv  10196 Apr 14 09:36 mythpreviewgen.20200414143645.12682.log
So I set up another recording this morning and here is what was in the mythbackend log after the recording ended:

Code: Select all

2020-04-14 09:30:00.946035 I [12461/12467] TVRecEvent tv_rec.cpp:1057 (HandleStateChange) - TVRec[1]: Changing from RecordingOnly to None
2020-04-14 09:30:00.954027 D [12461/12534] RecThread portchecker.cpp:75 (checkPort) - PortChecker::checkPort(): host localhost port 3306 timeLimit 30000 linkLocalOnly 1
2020-04-14 09:30:00.959286 N [12461/12534] RecThread recorders/recorderbase.cpp:490 (FinishRecording) - Finished Recording: Container: MPEG2-TS Video Codec: mpeg2video (704x480 A/R: 3 29.97fps) Audio Codec: ac3
2020-04-14 09:30:00.963517 I [12461/12467] TVRecEvent recordinginfo.cpp:1224 (FinishedRecording) - Finished recording Auction Kings: channel 2084
2020-04-14 09:30:00.966928 E [12461/12461] CoreContext mainserver.cpp:1185 (customEvent) - MainServer: PREVIEW_SUCCESS but no receivers.
2020-04-14 09:30:00.967119 I [12461/12461] CoreContext scheduler.cpp:706 (UpdateRecStatus) - Updating status for "Auction Kings" on cardid [1] (Recording => Recorded)
2020-04-14 09:30:00.967842 I [12461/12474] Scheduler scheduler.cpp:2308 (HandleReschedule) - Reschedule requested for CHECK -3 19 0 UpdateRecStatus2 | Auction Kings |  | The team attempts to sell a Lomax trike and a poem written by Doors front man Jim Morrison; Paul has trouble with a couple of Cabbage Patch Kids dolls. |
2020-04-14 09:30:00.976991 I [12461/12474] Scheduler scheduler.cpp:2425 (HandleReschedule) - Scheduled 0 items in 0.0 = 0.00 match + 0.00 check + 0.00 place
2020-04-14 09:30:04.489635 D [12461/12461] CoreContext housekeeper.cpp:744 (Run) - Running HouseKeeper.
2020-04-14 09:30:04.489649 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run DBCleanup
2020-04-14 09:30:04.489659 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run HardwareProfiler
2020-04-14 09:30:04.489681 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run JobQueueRecover
2020-04-14 09:30:04.489688 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run LogClean
2020-04-14 09:30:04.489694 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run MythFillDB
2020-04-14 09:30:04.489746 D [12461/12461] CoreContext backendhousekeeper.cpp:706 (DoCheckRun) - MythFillDatabase scheduled to run at Tue Apr 14 21:12:55 2020 GMT.
2020-04-14 09:30:04.489766 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run RecordedArtworkUpdate
2020-04-14 09:30:04.489780 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run ThemeUpdateNotifications
2020-04-14 09:30:04.489792 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run UpdateRadioStreams
2020-04-14 09:30:14.728435 D [12461/12641] Metadata_30 portchecker.cpp:75 (checkPort) - PortChecker::checkPort(): host localhost port 3306 timeLimit 30000 linkLocalOnly 1
2020-04-14 09:30:14.728815 I [12461/12641] Metadata_30 jobqueue.cpp:2186 (DoMetadataLookupThread) - JobQueue: Metadata Lookup Starting for "Auction Kings" recorded from channel 2084 at 2020-04-14T14:18:00Z
2020-04-14 09:30:17.623387 D [12461/12641] Metadata_30 portchecker.cpp:75 (checkPort) - PortChecker::checkPort(): host localhost port 3306 timeLimit 30000 linkLocalOnly 1
2020-04-14 09:30:17.624400 E [12461/12641] Metadata_30 jobqueue.cpp:2251 (DoMetadataLookupThread) - JobQueue: Metadata Lookup Errored: "Auction Kings" recorded from channel 2084 at 2020-04-14T14:18:00Z (Failed with Exit Status 128)
2020-04-14 09:30:19.733457 D [12461/12657] Commflag_31 portchecker.cpp:75 (checkPort) - PortChecker::checkPort(): host localhost port 3306 timeLimit 30000 linkLocalOnly 1
2020-04-14 09:30:19.733835 I [12461/12657] Commflag_31 jobqueue.cpp:2311 (DoFlagCommercialsThread) - JobQueue: Commercial Detection Starting for "Auction Kings" recorded from channel 2084 at 2020-04-14T14:18:00Z
2020-04-14 09:30:20.894177 I [12461/12669] ProcessRequest mainserver.cpp:1768 (HandleAnnounce) - MainServer: MainServer::ANN Monitor
2020-04-14 09:30:20.894189 I [12461/12669] ProcessRequest mainserver.cpp:1770 (HandleAnnounce) - MainServer: adding: robrutrm.marsinnovations.us(25abae0) as a client (events: 0)
2020-04-14 09:30:20.895234 I [12461/12669] ProcessRequest mainserver.cpp:1768 (HandleAnnounce) - MainServer: MainServer::ANN Monitor
2020-04-14 09:30:20.895243 I [12461/12669] ProcessRequest mainserver.cpp:1770 (HandleAnnounce) - MainServer: adding: robrutrm.marsinnovations.us(2531310) as a client (events: 1)
2020-04-14 09:31:04.511702 D [12461/12461] CoreContext housekeeper.cpp:744 (Run) - Running HouseKeeper.
2020-04-14 09:31:04.511720 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run DBCleanup
2020-04-14 09:31:04.511730 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run HardwareProfiler
2020-04-14 09:31:04.511752 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run JobQueueRecover
2020-04-14 09:31:04.511757 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run LogClean
2020-04-14 09:31:04.511763 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run MythFillDB
2020-04-14 09:31:04.511805 D [12461/12461] CoreContext backendhousekeeper.cpp:706 (DoCheckRun) - MythFillDatabase scheduled to run at Tue Apr 14 21:12:55 2020 GMT.
2020-04-14 09:31:04.511812 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run RecordedArtworkUpdate
2020-04-14 09:31:04.511820 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run ThemeUpdateNotifications
2020-04-14 09:31:04.511829 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run UpdateRadioStreams
2020-04-14 09:32:04.508909 D [12461/12461] CoreContext housekeeper.cpp:744 (Run) - Running HouseKeeper.
2020-04-14 09:32:04.508925 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run DBCleanup
2020-04-14 09:32:04.508933 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run HardwareProfiler
2020-04-14 09:32:04.508952 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run JobQueueRecover
2020-04-14 09:32:04.508956 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run LogClean
2020-04-14 09:32:04.508970 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run MythFillDB
2020-04-14 09:32:04.509013 D [12461/12461] CoreContext backendhousekeeper.cpp:706 (DoCheckRun) - MythFillDatabase scheduled to run at Tue Apr 14 21:12:55 2020 GMT.
2020-04-14 09:32:04.509019 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run RecordedArtworkUpdate
2020-04-14 09:32:04.509026 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run ThemeUpdateNotifications
2020-04-14 09:32:04.509032 D [12461/12461] CoreContext housekeeper.cpp:115 (CheckRun) - Checking to run UpdateRadioStreams
2020-04-14 09:32:22.996767 N [12461/12475] Expire autoexpire.cpp:242 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2020-04-14 09:32:23.002930 N [12461/12475] Expire autoexpire.cpp:634 (SendDeleteMessages) - Expiring 498 MB for 2212 at 2020-04-09T01:00:00Z => "Gomer Pyle, U.S.M.C."
2020-04-14 09:32:23.002983 N [12461/12475] Expire autoexpire.cpp:634 (SendDeleteMessages) - Expiring 6 MB for 2211 at 2020-04-09T00:30:00Z => "Celebrity Page"
2020-04-14 09:32:23.006655 E [12461/12461] CoreContext programinfo.cpp:2524 (GetPlaybackURL) - ProgramInfo(2212_20200409010000.ts): GetPlaybackURL: '2212_20200409010000.ts' should be local, but it can not be found.
2020-04-14 09:32:23.006703 E [12461/12461] CoreContext mainserver.cpp:3101 (DoHandleDeleteRecording) - MainServer: ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/robrutrm.marsinnovations.us/2212_20200409010000.ts. File doesn't exist.  Database metadata will not be removed.
2020-04-14 09:32:23.009456 E [12461/12461] CoreContext programinfo.cpp:2524 (GetPlaybackURL) - ProgramInfo(2211_20200409003000.ts): GetPlaybackURL: '2211_20200409003000.ts' should be local, but it can not be found.
2020-04-14 09:32:23.009516 E [12461/12461] CoreContext mainserver.cpp:3101 (DoHandleDeleteRecording) - MainServer: ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/robrutrm.marsinnovations.us/2211_20200409003000.ts. File doesn't exist.  Database metadata will not be removed.
2020-04-14 09:33:00.037176 I [12461/12467] TVRecEvent tv_rec.cpp:3662 (TuningFrequency) - TVRec[1]: TuningFrequency
2020-04-14 09:33:00.069825 I [12461/12467] TVRecEvent recorders/hdhrstreamhandler.cpp:385 (Connect) - HDHRSH[1](1019344C): Added 2 devices from 1019344C
2020-04-14 09:33:00.107675 I [12461/12467] TVRecEvent recorders/hdhrstreamhandler.cpp:402 (Connect) - HDHRSH[1](1019344C): Connected to device(1019344C-0)
2020-04-14 09:33:00.117159 D [12461/12467] TVRecEvent recorders/dtvchannel.cpp:295 (SetChannelByString) - DTVChan[1](1019344C): SetChannelByString(13_1): Initialize multiplex options m_tunerType:3 mplexid:3
2020-04-14 09:33:00.120455 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Signal Lock, slock, 0, 1, 0, 1, 0, 1, false)
2020-04-14 09:33:00.120476 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Signal Power, signal, 0, 0, 0, 100, 0, 1, false)
2020-04-14 09:33:00.120497 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Script Status, script, 0, 3, 0, 3, 0, 1, false)
2020-04-14 09:33:00.120526 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Seen PAT, seen_pat, 0, 1, 0, 1, 0, 1, false)
2020-04-14 09:33:00.120549 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Seen PMT, seen_pmt, 0, 1, 0, 1, 0, 1, false)
2020-04-14 09:33:00.120556 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Seen MGT, seen_mgt, 0, 1, 0, 1, 0, 1, false)
2020-04-14 09:33:00.120564 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Seen VCT, seen_vct, 0, 1, 0, 1, 0, 1, false)
2020-04-14 09:33:00.120573 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Seen NIT, seen_nit, 0, 1, 0, 1, 0, 1, false)
2020-04-14 09:33:00.120588 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Seen SDT, seen_sdt, 0, 1, 0, 1, 0, 1, false)
2020-04-14 09:33:00.120596 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Seen Crypt, seen_crypt, 0, 1, 0, 1, 0, 1, false)
2020-04-14 09:33:00.120605 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Matching PAT, matching_pat, 0, 1, 0, 1, 0, 1, false)
2020-04-14 09:33:00.120612 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Matching PMT, matching_pmt, 0, 1, 0, 1, 0, 1, false)
2020-04-14 09:33:00.120621 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Matching MGT, matching_mgt, 0, 1, 0, 1, 0, 1, false)
2020-04-14 09:33:00.120630 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Matching VCT, matching_vct, 0, 1, 0, 1, 0, 1, false)
2020-04-14 09:33:00.120639 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Matching NIT, matching_nit, 0, 1, 0, 1, 0, 1, false)
2020-04-14 09:33:00.120648 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Matching SDT, matching_sdt, 0, 1, 0, 1, 0, 1, false)
2020-04-14 09:33:00.120656 D [12461/12467] TVRecEvent signalmonitorvalue.cpp:52 (SignalMonitorValue) - SignalMonitorValue(Matching Crypt, matching_crypt, 0, 1, 0, 1, 0, 1, false)
2020-04-14 09:33:00.120700 D [12461/12467] TVRecEvent standardsettings.cpp:183 (haveChanged) - Setting 'Lossless Transcoding' changed to 0
2020-04-14 09:33:00.120711 D [12461/12467] TVRecEvent standardsettings.cpp:183 (haveChanged) - Setting 'Resize Video While Transcoding' changed to 0
2020-04-14 09:33:00.120720 D [12461/12467] TVRecEvent standardsettings.cpp:183 (haveChanged) - Setting 'Enable Auto-transcode After Recording' changed to 0
2020-04-14 09:33:00.136916 D [12461/12467] TVRecEvent standardsettings.cpp:183 (haveChanged) - Setting 'Enable Auto-transcode After Recording' changed to 1
2020-04-14 09:33:01.150289 D [12461/12674] HDHRStreamHandler portchecker.cpp:75 (checkPort) - PortChecker::checkPort(): host localhost port 3306 timeLimit 30000 linkLocalOnly 1
2020-04-14 09:33:04.510662 D [12461/12461] CoreContext housekeeper.cpp:744 (Run) - Running HouseKeeper.
At 0930 is when the recording stopped whereupon the Metadata Lookup errored and stopped as before and the Commercial Detection started as before.
Here is what was in the mythcommflag log:

Code: Select all

2020-04-14 09:30:19.831479 C [12658/12658] thread_unknown mythcommandlineparser.cpp:2602 (ConfigureLogging) - mythcommflag version: fixes/31 [v31.0-9579662cdcb] www.mythtv.org
2020-04-14 09:30:19.831518 C [12658/12658] thread_unknown mythcommandlineparser.cpp:2606 (ConfigureLogging) - Qt version: compile: 5.13.2, runtime: 5.13.2
2020-04-14 09:30:19.831557 I [12658/12658] thread_unknown mythcommandlineparser.cpp:2608 (ConfigureLogging) - Slackware 14.2 x86_64 (post 14.2 -current) (x86_64)
2020-04-14 09:30:19.831559 N [12658/12658] thread_unknown mythcommandlineparser.cpp:2610 (ConfigureLogging) - Enabled verbose msgs:  general commflag
2020-04-14 09:30:19.831648 N [12658/12658] thread_unknown logging.cpp:724 (logStart) - Setting Log Level to LOG_DEBUG
2020-04-14 09:30:19.842046 I [12658/12660] Logger logging.cpp:280 (run) - Added logging to the console
2020-04-14 09:30:19.842433 I [12658/12658] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Interrupt handler
2020-04-14 09:30:19.842444 I [12658/12658] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Terminated handler
2020-04-14 09:30:19.842451 I [12658/12658] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Segmentation fault handler
2020-04-14 09:30:19.842457 I [12658/12658] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Aborted handler
2020-04-14 09:30:19.842466 I [12658/12658] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Bus error handler
2020-04-14 09:30:19.842473 I [12658/12658] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Floating point exception handler
2020-04-14 09:30:19.842482 I [12658/12658] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Illegal instruction handler
2020-04-14 09:30:19.842495 I [12658/12658] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Real-time signal 0 handler
2020-04-14 09:30:19.842506 I [12658/12658] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Hangup handler
2020-04-14 09:30:19.842731 I [12658/12659] LogForward loggingserver.cpp:128 (FileLogger) - Added logging to /var/log/mythtv/mythcommflag.20200414143019.12658.log
2020-04-14 09:30:19.843215 N [12658/12658] thread_unknown mythdirs.cpp:203 (InitializeMythDirs) - Using runtime prefix = /usr/local
2020-04-14 09:30:19.843218 N [12658/12658] thread_unknown mythdirs.cpp:204 (InitializeMythDirs) - Using configuration directory = /home/mythtv/.mythtv
2020-04-14 09:30:19.843222 D [12658/12658] thread_unknown mythdirs.cpp:207 (InitializeMythDirs) - appbindir         = /usr/local/bin/
2020-04-14 09:30:19.843225 D [12658/12658] thread_unknown mythdirs.cpp:208 (InitializeMythDirs) - sharedir          = /usr/local/share/mythtv/
2020-04-14 09:30:19.843230 D [12658/12658] thread_unknown mythdirs.cpp:209 (InitializeMythDirs) - libdir            = /usr/local/lib/mythtv/
2020-04-14 09:30:19.843233 D [12658/12658] thread_unknown mythdirs.cpp:210 (InitializeMythDirs) - themedir          = /usr/local/share/mythtv/themes/
2020-04-14 09:30:19.843237 D [12658/12658] thread_unknown mythdirs.cpp:211 (InitializeMythDirs) - pluginsdir        = /usr/local/lib/mythtv/plugins/
2020-04-14 09:30:19.843242 D [12658/12658] thread_unknown mythdirs.cpp:212 (InitializeMythDirs) - translationsdir   = /usr/local/share/mythtv/i18n/
2020-04-14 09:30:19.843245 D [12658/12658] thread_unknown mythdirs.cpp:213 (InitializeMythDirs) - filtersdir        = /usr/local/lib/mythtv/filters/
2020-04-14 09:30:19.843252 D [12658/12658] thread_unknown mythdirs.cpp:214 (InitializeMythDirs) - cachedir          = /home/mythtv/.mythtv/cache
2020-04-14 09:30:19.843255 D [12658/12658] thread_unknown mythdirs.cpp:215 (InitializeMythDirs) - remotecachedir    = /home/mythtv/.mythtv/cache/remotecache
2020-04-14 09:30:19.843260 D [12658/12658] thread_unknown mythdirs.cpp:216 (InitializeMythDirs) - themebasecachedir = /home/mythtv/.mythtv/cache/themecache
2020-04-14 09:30:19.843265 D [12658/12658] thread_unknown mythdirs.cpp:217 (InitializeMythDirs) - thumbnaildir      = /home/mythtv/.mythtv/cache/thumbnails
2020-04-14 09:30:19.843300 I [12658/12658] CoreContext mythcorecontext.cpp:284 (Init) - Assumed character encoding: en_US
2020-04-14 09:30:19.843304 W [12658/12658] CoreContext mythcorecontext.cpp:288 (Init) - This application expects to be running a locale that specifies a UTF-8 codeset, and many features may behave improperly with your current language settings. Please set the LC_ALL or LC_CTYPE, and LANG variable(s) in the environment in which this program is executed to include a UTF-8 codeset (such as 'en_US.UTF-8').
2020-04-14 09:30:19.843648 I [12658/12658] CoreContext mythcontext.cpp:629 (LoadDatabaseSettings) - Empty LocalHostName. This is typical.
2020-04-14 09:30:19.843655 I [12658/12658] CoreContext mythcontext.cpp:636 (LoadDatabaseSettings) - Using a profile name of: 'robrutrm.marsinnovations.us' (Usually the same as this host's name.)
2020-04-14 09:30:19.843727 I [12658/12658] CoreContext mythcontext.cpp:885 (TestDBconnection) - Start up testing connections. DB localhost, BE , attempt 0, status dbAwake, Delay: 2000
2020-04-14 09:30:19.843734 D [12658/12658] CoreContext portchecker.cpp:75 (checkPort) - PortChecker::checkPort(): host localhost port 3306 timeLimit 1000 linkLocalOnly 0
2020-04-14 09:30:20.344365 D [12658/12658] CoreContext portchecker.cpp:195 (checkPort) - PortChecker::checkPort(): socket state 1
2020-04-14 09:30:20.844851 D [12658/12658] CoreContext portchecker.cpp:195 (checkPort) - PortChecker::checkPort(): socket state 3
2020-04-14 09:30:20.849956 D [12658/12658] CoreContext portchecker.cpp:75 (checkPort) - PortChecker::checkPort(): host localhost port 3306 timeLimit 30000 linkLocalOnly 1
2020-04-14 09:30:20.852270 D [12658/12658] CoreContext mythcontext.cpp:513 (FindDatabase) - FindDatabase() - Success!
2020-04-14 09:30:20.852333 D [12658/12658] CoreContext portchecker.cpp:75 (checkPort) - PortChecker::checkPort(): host localhost port 3306 timeLimit 30000 linkLocalOnly 1
2020-04-14 09:30:20.854175 N [12658/12658] CoreContext mythcorecontext.cpp:1809 (InitLocale) - Setting QT default locale to en_US
2020-04-14 09:30:20.854191 I [12658/12658] CoreContext mythcorecontext.cpp:1842 (SaveLocaleDefaults) - Current locale en_US
2020-04-14 09:30:20.854251 N [12658/12658] CoreContext mythlocale.cpp:121 (LoadDefaultsFromXML) - Reading locale defaults from /usr/local/share/mythtv//locales/en_us.xml
2020-04-14 09:30:20.863549 W [12658/12658] CoreContext platforms/mythpowerdbus.cpp:104 (Init) - PowerDBus: No login1 interface. Cannot change system power state
2020-04-14 09:30:20.865183 I [12658/12658] CoreContext platforms/mythpowerdbus.cpp:360 (DeviceAdded) - PowerDBus: Added UPower.Device '/org/freedesktop/UPower/devices/ups_hiddev0'
2020-04-14 09:30:20.865190 I [12658/12658] CoreContext mythpower.cpp:374 (PowerLevelChanged) - Power: Unknown power source
2020-04-14 09:30:20.865602 I [12658/12658] CoreContext mythpower.cpp:131 (Init) - Power: Supported actions: None
2020-04-14 09:30:20.866539 I [12658/12658] CoreContext mythtranslation.cpp:62 (load) - Loading en_us translation for module mythfrontend
2020-04-14 09:30:20.871198 I [12658/12658] CoreContext main.cpp:804 (FlagCommercials) - Using method: 7 from channel 2084
2020-04-14 09:30:20.886280 I [12658/12658] CoreContext main.cpp:542 (DoFlagCommercials) - mythcommflag processing JobID 31
2020-04-14 09:30:20.886695 I [12658/12658] CoreContext main.cpp:561 (DoFlagCommercials) - mythcommflag sending COMMFLAG_START notification
2020-04-14 09:30:20.886964 D [12658/12664] SendMessage portchecker.cpp:75 (checkPort) - PortChecker::checkPort(): host localhost port 3306 timeLimit 30000 linkLocalOnly 1
2020-04-14 09:30:20.887499 I [12658/12658] CoreContext mythplayer.cpp:788 (OpenFile) - Player(0): Opening '/mnt/hd/mythtv/2084_20200414141800.ts'
2020-04-14 09:30:20.891005 I [12658/12658] CoreContext decoders/mythvdpauhelper.cpp:71 (HaveVDPAU) - VDPAUHelp: VDPAU is NOT available
2020-04-14 09:30:20.891484 I [12658/12664] SendMessage mythcorecontext.cpp:469 (ConnectCommandSocket) - MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 10.160.134.60:6543 (try 1 of 1)
2020-04-14 09:30:20.893414 I [12658/12658] CoreContext decoders/mythvaapicontext.cpp:468 (HaveVAAPI) - VAAPIDec: Supported/available VAAPI decoders:
2020-04-14 09:30:20.893874 I [12658/12658] CoreContext decoders/mythvaapicontext.cpp:474 (HaveVAAPI) - VAAPIDec: MPEG2 Simple (Max size: 2048x2048)
2020-04-14 09:30:20.893881 I [12658/12658] CoreContext decoders/mythvaapicontext.cpp:474 (HaveVAAPI) - VAAPIDec: MPEG2 Main (Max size: 2048x2048)
2020-04-14 09:30:20.893992 I [12658/12664] SendMessage mythcorecontext.cpp:1691 (CheckProtoVersion) - MythCoreContext::CheckProtoVersion(): Using protocol version 91 BuzzOff
2020-04-14 09:30:20.911567 I [12658/12658] CoreContext decoders/mythnvdeccontext.cpp:519 (HaveNVDEC) - NVDEC: No NVDEC decoders found
2020-04-14 09:30:20.912088 I [12658/12658] CoreContext decoders/mythv4l2m2mcontext.cpp:378 (HaveV4L2Codecs) - V4L2_M2M: No V4L2 decoders found
2020-04-14 09:30:30.349055 I [12658/12658] CoreContext decoders/avformatdecoder.cpp:2034 (ScanStreams) - AFD: codec AC3 has 2 channels
2020-04-14 09:30:30.349363 I [12658/12658] CoreContext decoders/avformatdecoder.cpp:2535 (OpenAVCodec) - AFD: Opened codec 0xf6d9c0, id(AC3) type(Audio)
2020-04-14 09:30:30.350072 I [12658/12658] CoreContext decoders/avformatdecoder.cpp:2448 (ScanStreams) - AFD: Using ffmpeg for video decoding
2020-04-14 09:30:30.350095 I [12658/12658] CoreContext decoders/avformatdecoder.cpp:2535 (OpenAVCodec) - AFD: Opened codec 0xf77b80, id(MPEG2VIDEO) type(Video)
2020-04-14 09:30:30.360797 I [12658/12658] CoreContext ClassicCommDetector.cpp:202 (Init) - Commercial Detection initialized: width = 704, height = 480, fps = 29.97, method = 7
2020-04-14 09:30:30.360806 I [12658/12658] CoreContext ClassicCommDetector.cpp:234 (Init) - Using Sample Spacing of 6 horizontal & 4 vertical pixels.
2020-04-14 09:30:30.360840 I [12658/12658] CoreContext ClassicCommDetector.cpp:1005 (ClearAllMaps) - CommDetect::ClearAllMaps()
2020-04-14 09:30:30.382905 I [12658/12658] CoreContext ClassicCommDetector.cpp:372 (go) - Finding Logo
2020-04-14 09:30:30.382914 I [12658/12658] CoreContext ClassicLogoDetector.cpp:83 (searchForLogo) - Searching for Station Logo
2020-04-14 09:30:30.382952 I [12658/12658] CoreContext ClassicLogoDetector.cpp:103 (searchForLogo) - Trying with edgeDiff == 5, minPixelsInMask=293
2020-04-14 09:30:30.493287 I [12658/12658] CoreContext main.cpp:483 (incomingCustomEvent) - mythcommflag: Received Event: 'COMMFLAG_START 2084_2020-04-14T14:18:00Z'
2020-04-14 09:30:30.493307 I [12658/12658] CoreContext main.cpp:483 (incomingCustomEvent) - mythcommflag: Received Event: 'RECORDING_LIST_CHANGE UPDATE'
2020-04-14 09:30:46.240782 I [12658/12658] CoreContext ClassicLogoDetector.cpp:139 (searchForLogo) - Analyzing edge data
2020-04-14 09:30:46.243206 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:30:46.246127 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:30:46.249238 I [12658/12658] CoreContext ClassicLogoDetector.cpp:208 (searchForLogo) - Testing Logo area: topleft (147,363), bottomright (646,439)
2020-04-14 09:30:46.249258 I [12658/12658] CoreContext ClassicLogoDetector.cpp:251 (searchForLogo) - Rejecting Logo area: topleft (147,363), bottomright (646,439), pixelsInMask (581). Not within specified limits.
2020-04-14 09:30:46.249263 I [12658/12658] CoreContext ClassicLogoDetector.cpp:103 (searchForLogo) - Trying with edgeDiff == 7, minPixelsInMask=293
2020-04-14 09:31:02.042015 I [12658/12658] CoreContext ClassicLogoDetector.cpp:139 (searchForLogo) - Analyzing edge data
2020-04-14 09:31:02.044002 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:31:02.046971 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:31:02.049543 I [12658/12658] CoreContext ClassicLogoDetector.cpp:208 (searchForLogo) - Testing Logo area: topleft (152,386), bottomright (165,399)
2020-04-14 09:31:02.049559 I [12658/12658] CoreContext ClassicLogoDetector.cpp:251 (searchForLogo) - Rejecting Logo area: topleft (152,386), bottomright (165,399), pixelsInMask (10). Not within specified limits.
2020-04-14 09:31:02.049564 I [12658/12658] CoreContext ClassicLogoDetector.cpp:103 (searchForLogo) - Trying with edgeDiff == 10, minPixelsInMask=293
2020-04-14 09:31:17.830782 I [12658/12658] CoreContext ClassicLogoDetector.cpp:139 (searchForLogo) - Analyzing edge data
2020-04-14 09:31:17.832797 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:31:17.835799 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:31:17.838253 I [12658/12658] CoreContext ClassicLogoDetector.cpp:208 (searchForLogo) - Testing Logo area: topleft (698,474), bottomright (5,5)
2020-04-14 09:31:17.838270 I [12658/12658] CoreContext ClassicLogoDetector.cpp:251 (searchForLogo) - Rejecting Logo area: topleft (698,474), bottomright (5,5), pixelsInMask (0). Not within specified limits.
2020-04-14 09:31:17.838276 I [12658/12658] CoreContext ClassicLogoDetector.cpp:103 (searchForLogo) - Trying with edgeDiff == 15, minPixelsInMask=293
2020-04-14 09:31:33.570355 I [12658/12658] CoreContext ClassicLogoDetector.cpp:139 (searchForLogo) - Analyzing edge data
2020-04-14 09:31:33.572266 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:31:33.575382 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:31:33.577775 I [12658/12658] CoreContext ClassicLogoDetector.cpp:208 (searchForLogo) - Testing Logo area: topleft (698,474), bottomright (5,5)
2020-04-14 09:31:33.577795 I [12658/12658] CoreContext ClassicLogoDetector.cpp:251 (searchForLogo) - Rejecting Logo area: topleft (698,474), bottomright (5,5), pixelsInMask (0). Not within specified limits.
2020-04-14 09:31:33.577801 I [12658/12658] CoreContext ClassicLogoDetector.cpp:103 (searchForLogo) - Trying with edgeDiff == 20, minPixelsInMask=293
2020-04-14 09:31:49.267657 I [12658/12658] CoreContext ClassicLogoDetector.cpp:139 (searchForLogo) - Analyzing edge data
2020-04-14 09:31:49.269619 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:31:49.272493 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:31:49.275115 I [12658/12658] CoreContext ClassicLogoDetector.cpp:208 (searchForLogo) - Testing Logo area: topleft (698,474), bottomright (5,5)
2020-04-14 09:31:49.275132 I [12658/12658] CoreContext ClassicLogoDetector.cpp:251 (searchForLogo) - Rejecting Logo area: topleft (698,474), bottomright (5,5), pixelsInMask (0). Not within specified limits.
2020-04-14 09:31:49.275138 I [12658/12658] CoreContext ClassicLogoDetector.cpp:103 (searchForLogo) - Trying with edgeDiff == 30, minPixelsInMask=293
2020-04-14 09:32:05.001829 I [12658/12658] CoreContext ClassicLogoDetector.cpp:139 (searchForLogo) - Analyzing edge data
2020-04-14 09:32:05.003800 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:32:05.006765 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:32:05.009477 I [12658/12658] CoreContext ClassicLogoDetector.cpp:208 (searchForLogo) - Testing Logo area: topleft (698,474), bottomright (5,5)
2020-04-14 09:32:05.009497 I [12658/12658] CoreContext ClassicLogoDetector.cpp:251 (searchForLogo) - Rejecting Logo area: topleft (698,474), bottomright (5,5), pixelsInMask (0). Not within specified limits.
2020-04-14 09:32:05.009503 I [12658/12658] CoreContext ClassicLogoDetector.cpp:103 (searchForLogo) - Trying with edgeDiff == 40, minPixelsInMask=293
2020-04-14 09:32:20.679101 I [12658/12658] CoreContext ClassicLogoDetector.cpp:139 (searchForLogo) - Analyzing edge data
2020-04-14 09:32:20.681084 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:32:20.684038 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:32:20.686541 I [12658/12658] CoreContext ClassicLogoDetector.cpp:208 (searchForLogo) - Testing Logo area: topleft (698,474), bottomright (5,5)
2020-04-14 09:32:20.686558 I [12658/12658] CoreContext ClassicLogoDetector.cpp:251 (searchForLogo) - Rejecting Logo area: topleft (698,474), bottomright (5,5), pixelsInMask (0). Not within specified limits.
2020-04-14 09:32:20.686563 I [12658/12658] CoreContext ClassicLogoDetector.cpp:103 (searchForLogo) - Trying with edgeDiff == 50, minPixelsInMask=293
2020-04-14 09:32:36.254306 I [12658/12658] CoreContext ClassicLogoDetector.cpp:139 (searchForLogo) - Analyzing edge data
2020-04-14 09:32:36.256275 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:32:36.259414 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:32:36.262378 I [12658/12658] CoreContext ClassicLogoDetector.cpp:208 (searchForLogo) - Testing Logo area: topleft (698,474), bottomright (5,5)
2020-04-14 09:32:36.262394 I [12658/12658] CoreContext ClassicLogoDetector.cpp:251 (searchForLogo) - Rejecting Logo area: topleft (698,474), bottomright (5,5), pixelsInMask (0). Not within specified limits.
2020-04-14 09:32:36.262400 I [12658/12658] CoreContext ClassicLogoDetector.cpp:103 (searchForLogo) - Trying with edgeDiff == 60, minPixelsInMask=293
2020-04-14 09:32:51.970963 I [12658/12658] CoreContext ClassicLogoDetector.cpp:139 (searchForLogo) - Analyzing edge data
2020-04-14 09:32:51.972941 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:32:51.975781 I [12658/12658] CoreContext ClassicLogoDetector.cpp:273 (SetLogoMaskArea) - SetLogoMaskArea()
2020-04-14 09:32:51.978507 I [12658/12658] CoreContext ClassicLogoDetector.cpp:208 (searchForLogo) - Testing Logo area: topleft (698,474), bottomright (5,5)
2020-04-14 09:32:51.978526 I [12658/12658] CoreContext ClassicLogoDetector.cpp:251 (searchForLogo) - Rejecting Logo area: topleft (698,474), bottomright (5,5), pixelsInMask (0). Not within specified limits.
2020-04-14 09:32:51.979094 N [12658/12658] CoreContext ClassicLogoDetector.cpp:264 (searchForLogo) - No suitable logo area found.
2020-04-14 09:32:52.030081 I [12658/12658] CoreContext ClassicCommDetector.cpp:718 (SetVideoParams) - CommDetect::SetVideoParams called with aspect = 1.77778
2020-04-14 09:33:38.826155 I [12658/12658] CoreContext ClassicCommDetector.cpp:550 (go) - 20% Completed @ 96.1641 fps.
2020-04-14 09:34:25.927412 I [12658/12658] CoreContext ClassicCommDetector.cpp:550 (go) - 40% Completed @ 95.8507 fps.
2020-04-14 09:35:33.950676 I [12658/12658] CoreContext ClassicCommDetector.cpp:550 (go) - 70% Completed @ 95.7269 fps.
2020-04-14 09:36:21.042272 I [12658/12658] CoreContext ClassicCommDetector.cpp:550 (go) - 90% Completed @ 95.6887 fps.
2020-04-14 09:36:42.680866 E [12658/12672] Decoder decoders/avformatdecoder.cpp:4979 (GetFrame) - decoding error End of file (-541478725)
2020-04-14 09:36:42.685571 I [12658/12658] CoreContext ClassicCommDetector.cpp:632 (GetCommercialBreakList) - CommDetect::GetCommBreakMap()
2020-04-14 09:36:42.685592 I [12658/12658] CoreContext ClassicCommDetector.cpp:2409 (CleanupFrameInfo) - CommDetect::CleanupFrameInfo()
2020-04-14 09:36:42.685599 I [12658/12658] CoreContext ClassicCommDetector.cpp:2419 (CleanupFrameInfo) - ClassicCommDetect: Only found 7 blank frames but wanted at least 8, rechecking data using higher threshold.
2020-04-14 09:36:42.690727 I [12658/12658] CoreContext ClassicCommDetector.cpp:2438 (CleanupFrameInfo) - Minimum Average Brightness on a frame was 24, will use 27 as new threshold
2020-04-14 09:36:42.708342 I [12658/12658] CoreContext ClassicCommDetector.cpp:2457 (CleanupFrameInfo) - Found 64 blank frames using new value
2020-04-14 09:36:42.802420 I [12658/12658] CoreContext ClassicCommDetector.cpp:1194 (BuildAllMethodsCommList) - CommDetect::BuildAllMethodsCommList()
2020-04-14 09:36:42.834122 I [12658/12658] CoreContext ClassicCommDetector.cpp:1334 (BuildAllMethodsCommList) - Initial Block pass
2020-04-14 09:36:42.834132 D [12658/12658] CoreContext ClassicCommDetector.cpp:1335 (BuildAllMethodsCommList) - Block StTime StFrm  EndFrm Frames Secs    Bf  Lg Cnt RT Cnt SC Cnt SC Rt FmtMch AspMch Score
2020-04-14 09:36:42.834153 I [12658/12658] CoreContext ClassicCommDetector.cpp:1338 (BuildAllMethodsCommList) - ----- ------ ------ ------ ------ ------- --- ------ ------ ------ ----- ------ ------ -----
2020-04-14 09:36:42.834178 D [12658/12658] CoreContext ClassicCommDetector.cpp:1346 (BuildAllMethodsCommList) -     0   0:00      0     28     29    0.97  28      0      0      3  0.00     28      0     0
2020-04-14 09:36:42.834190 D [12658/12658] CoreContext ClassicCommDetector.cpp:1500 (BuildAllMethodsCommList) -   NOW   0:00      0     28     29    0.97  28      0      0      3  0.00     28      0     0
2020-04-14 09:36:42.834205 D [12658/12658] CoreContext ClassicCommDetector.cpp:1346 (BuildAllMethodsCommList) -     1   0:00     28   7455   7428  247.85   2      0      0    194  0.78   7422      0     0
2020-04-14 09:36:42.834218 D [12658/12658] CoreContext ClassicCommDetector.cpp:1500 (BuildAllMethodsCommList) -   NOW   0:00     28   7455   7428  247.85   2      0      0    194  0.78   7422      0    20
2020-04-14 09:36:42.834229 D [12658/12658] CoreContext ClassicCommDetector.cpp:1346 (BuildAllMethodsCommList) -     2   4:08   7455   7457      3    0.10   3      0      0      0  0.00      2      0     0
2020-04-14 09:36:42.834239 D [12658/12658] CoreContext ClassicCommDetector.cpp:1500 (BuildAllMethodsCommList) -   NOW   4:08   7455   7457      3    0.10   3      0      0      0  0.00      2      0     0
2020-04-14 09:36:42.834253 D [12658/12658] CoreContext ClassicCommDetector.cpp:1346 (BuildAllMethodsCommList) -     3   4:08   7457  10914   3458  115.38   2      0      0     31  0.27   3455      0     0
2020-04-14 09:36:42.834265 D [12658/12658] CoreContext ClassicCommDetector.cpp:1500 (BuildAllMethodsCommList) -   NOW   4:08   7457  10914   3458  115.38   2      0      0     31  0.27   3455      0     0
2020-04-14 09:36:42.834279 D [12658/12658] CoreContext ClassicCommDetector.cpp:1346 (BuildAllMethodsCommList) -     4   6:04  10914  10941     28    0.93  28      0      0      0  0.00      0      0     0
2020-04-14 09:36:42.834290 D [12658/12658] CoreContext ClassicCommDetector.cpp:1500 (BuildAllMethodsCommList) -   NOW   6:04  10914  10941     28    0.93  28      0      0      0  0.00      0      0   -10
2020-04-14 09:36:42.834303 D [12658/12658] CoreContext ClassicCommDetector.cpp:1346 (BuildAllMethodsCommList) -     5   6:05  10941  12254   1314   43.84   2      0      0     37  0.84   1201      0     0
2020-04-14 09:36:42.834315 D [12658/12658] CoreContext ClassicCommDetector.cpp:1500 (BuildAllMethodsCommList) -   NOW   6:05  10941  12254   1314   43.84   2      0      0     37  0.84   1201      0     0
2020-04-14 09:36:42.834340 D [12658/12658] CoreContext ClassicCommDetector.cpp:1346 (BuildAllMethodsCommList) -     6   6:48  12254  12258      5    0.17   5      0      0      0  0.00      5      0     0
2020-04-14 09:36:42.834355 D [12658/12658] CoreContext ClassicCommDetector.cpp:1500 (BuildAllMethodsCommList) -   NOW   6:48  12254  12258      5    0.17   5      0      0      0  0.00      5      0     0
2020-04-14 09:36:42.834366 D [12658/12658] CoreContext ClassicCommDetector.cpp:1346 (BuildAllMethodsCommList) -     7   6:49  12258  22041   9784  326.46   1      0      0    178  0.55   9777      0     0
2020-04-14 09:36:42.834380 D [12658/12658] CoreContext ClassicCommDetector.cpp:1500 (BuildAllMethodsCommList) -   NOW   6:49  12258  22041   9784  326.46   1      0      0    178  0.55   9777      0    20
2020-04-14 09:36:42.834382 D [12658/12658] CoreContext ClassicCommDetector.cpp:1509 (BuildAllMethodsCommList) - ============================================
2020-04-14 09:36:42.834388 I [12658/12658] CoreContext ClassicCommDetector.cpp:1510 (BuildAllMethodsCommList) - Second Block pass
2020-04-14 09:36:42.834390 D [12658/12658] CoreContext ClassicCommDetector.cpp:1511 (BuildAllMethodsCommList) - Block StTime StFrm  EndFrm Frames Secs    Bf  Lg Cnt RT Cnt SC Cnt SC Rt FmtMch AspMch Score
2020-04-14 09:36:42.834394 D [12658/12658] CoreContext ClassicCommDetector.cpp:1514 (BuildAllMethodsCommList) - ----- ------ ------ ------ ------ ------- --- ------ ------ ------ ----- ------ ------ -----
2020-04-14 09:36:42.834406 D [12658/12658] CoreContext ClassicCommDetector.cpp:1522 (BuildAllMethodsCommList) -     0   0:00      0     28     29    0.97  28      0      0      3  0.00     28      0     0
2020-04-14 09:36:42.834420 D [12658/12658] CoreContext ClassicCommDetector.cpp:1624 (BuildAllMethodsCommList) -   NOW   0:00      0     28     29    0.97  28      0      0      3  0.00     28      0     0
2020-04-14 09:36:42.834431 D [12658/12658] CoreContext ClassicCommDetector.cpp:1522 (BuildAllMethodsCommList) -     1   0:00     28   7455   7428  247.85   2      0      0    194  0.78   7422      0    20
2020-04-14 09:36:42.834445 D [12658/12658] CoreContext ClassicCommDetector.cpp:1624 (BuildAllMethodsCommList) -   NOW   0:00     28   7455   7428  247.85   2      0      0    194  0.78   7422      0    20
2020-04-14 09:36:42.834459 D [12658/12658] CoreContext ClassicCommDetector.cpp:1522 (BuildAllMethodsCommList) -     2   4:08   7455   7457      3    0.10   3      0      0      0  0.00      2      0     0
2020-04-14 09:36:42.834471 D [12658/12658] CoreContext ClassicCommDetector.cpp:1624 (BuildAllMethodsCommList) -   NOW   4:08   7455   7457      3    0.10   3      0      0      0  0.00      2      0     0
2020-04-14 09:36:42.834488 D [12658/12658] CoreContext ClassicCommDetector.cpp:1522 (BuildAllMethodsCommList) -     3   4:08   7457  10914   3458  115.38   2      0      0     31  0.27   3455      0     0
2020-04-14 09:36:42.834500 D [12658/12658] CoreContext ClassicCommDetector.cpp:1624 (BuildAllMethodsCommList) -   NOW   4:08   7457  10914   3458  115.38   2      0      0     31  0.27   3455      0     0
2020-04-14 09:36:42.834513 D [12658/12658] CoreContext ClassicCommDetector.cpp:1522 (BuildAllMethodsCommList) -     4   6:04  10914  10941     28    0.93  28      0      0      0  0.00      0      0   -10
2020-04-14 09:36:42.834525 D [12658/12658] CoreContext ClassicCommDetector.cpp:1624 (BuildAllMethodsCommList) -   NOW   6:04  10914  10941     28    0.93  28      0      0      0  0.00      0      0   -10
2020-04-14 09:36:42.834539 D [12658/12658] CoreContext ClassicCommDetector.cpp:1522 (BuildAllMethodsCommList) -     5   6:05  10941  12254   1314   43.84   2      0      0     37  0.84   1201      0     0
2020-04-14 09:36:42.834550 D [12658/12658] CoreContext ClassicCommDetector.cpp:1624 (BuildAllMethodsCommList) -   NOW   6:05  10941  12254   1314   43.84   2      0      0     37  0.84   1201      0     0
2020-04-14 09:36:42.834563 D [12658/12658] CoreContext ClassicCommDetector.cpp:1522 (BuildAllMethodsCommList) -     6   6:48  12254  12258      5    0.17   5      0      0      0  0.00      5      0     0
2020-04-14 09:36:42.834575 D [12658/12658] CoreContext ClassicCommDetector.cpp:1624 (BuildAllMethodsCommList) -   NOW   6:48  12254  12258      5    0.17   5      0      0      0  0.00      5      0     0
2020-04-14 09:36:42.834596 D [12658/12658] CoreContext ClassicCommDetector.cpp:1522 (BuildAllMethodsCommList) -     7   6:49  12258  22041   9784  326.46   1      0      0    178  0.55   9777      0    20
2020-04-14 09:36:42.834610 D [12658/12658] CoreContext ClassicCommDetector.cpp:1624 (BuildAllMethodsCommList) -   NOW   6:49  12258  22041   9784  326.46   1      0      0    178  0.55   9777      0    20
2020-04-14 09:36:42.834613 D [12658/12658] CoreContext ClassicCommDetector.cpp:1630 (BuildAllMethodsCommList) - ============================================
2020-04-14 09:36:42.834618 I [12658/12658] CoreContext ClassicCommDetector.cpp:1631 (BuildAllMethodsCommList) - FINAL Block stats
2020-04-14 09:36:42.834620 D [12658/12658] CoreContext ClassicCommDetector.cpp:1632 (BuildAllMethodsCommList) - Block StTime StFrm  EndFrm Frames Secs    Bf  Lg Cnt RT Cnt SC Cnt SC Rt FmtMch AspMch Score
2020-04-14 09:36:42.834625 D [12658/12658] CoreContext ClassicCommDetector.cpp:1635 (BuildAllMethodsCommList) - ----- ------ ------ ------ ------ ------- --- ------ ------ ------ ----- ------ ------ -----
2020-04-14 09:36:42.834652 D [12658/12658] CoreContext ClassicCommDetector.cpp:1813 (BuildAllMethodsCommList) -     0   0:00      0     28     29    0.97  28      0      0      3  0.00     28      0     0
2020-04-14 09:36:42.834666 D [12658/12658] CoreContext ClassicCommDetector.cpp:1813 (BuildAllMethodsCommList) -     1   0:00     28   7455   7428  247.85   2      0      0    194  0.78   7422      0    20
2020-04-14 09:36:42.834678 D [12658/12658] CoreContext ClassicCommDetector.cpp:1813 (BuildAllMethodsCommList) -     2   4:08   7455   7457      3    0.10   3      0      0      0  0.00      2      0     0
2020-04-14 09:36:42.834691 D [12658/12658] CoreContext ClassicCommDetector.cpp:1813 (BuildAllMethodsCommList) -     3   4:08   7457  10914   3458  115.38   2      0      0     31  0.27   3455      0     0
2020-04-14 09:36:42.834702 D [12658/12658] CoreContext ClassicCommDetector.cpp:1813 (BuildAllMethodsCommList) -     4   6:04  10914  10941     28    0.93  28      0      0      0  0.00      0      0   -10
2020-04-14 09:36:42.834717 D [12658/12658] CoreContext ClassicCommDetector.cpp:1813 (BuildAllMethodsCommList) -     5   6:05  10941  12254   1314   43.84   2      0      0     37  0.84   1201      0     0
2020-04-14 09:36:42.834728 D [12658/12658] CoreContext ClassicCommDetector.cpp:1813 (BuildAllMethodsCommList) -     6   6:48  12254  12258      5    0.17   5      0      0      0  0.00      5      0     0
2020-04-14 09:36:42.834741 D [12658/12658] CoreContext ClassicCommDetector.cpp:1813 (BuildAllMethodsCommList) -     7   6:49  12258  22041   9784  326.46   1      0      0    178  0.55   9777      0    20
2020-04-14 09:36:42.834749 I [12658/12658] CoreContext ClassicCommDetector.cpp:656 (GetCommercialBreakList) - Final Commercial Break Map
2020-04-14 09:36:43.836930 N [12658/12658] CoreContext main.cpp:936 (FlagCommercials) - Finished, 0 break(s) found.
2020-04-14 09:36:43.847085 I [12658/12658] CoreContext platforms/mythpowerdbus.cpp:72 (~MythPowerDBus) - PowerDBus: Closing interfaces
2020-04-14 09:36:43.847508 I [12658/12658] CoreContext mythcontext.cpp:1659 (~MythContext) - Waiting for threads to exit.
2020-04-14 09:36:45.246955 I [12658/12658] CoreContext mythcontext.cpp:1665 (~MythContext) - Exiting
And here is the mythmetadatalookup log:

Code: Select all

2020-04-14 09:30:14.814101 C [12642/12642] thread_unknown mythcommandlineparser.cpp:2602 (ConfigureLogging) - mythmetadatalookup version: fixes/31 [v31.0-9579662cdcb] www.mythtv.org
2020-04-14 09:30:14.814142 C [12642/12642] thread_unknown mythcommandlineparser.cpp:2606 (ConfigureLogging) - Qt version: compile: 5.13.2, runtime: 5.13.2
2020-04-14 09:30:14.814177 I [12642/12642] thread_unknown mythcommandlineparser.cpp:2608 (ConfigureLogging) - Slackware 14.2 x86_64 (post 14.2 -current) (x86_64)
2020-04-14 09:30:14.814183 N [12642/12642] thread_unknown mythcommandlineparser.cpp:2610 (ConfigureLogging) - Enabled verbose msgs:  general commflag
2020-04-14 09:30:14.814253 N [12642/12642] thread_unknown logging.cpp:724 (logStart) - Setting Log Level to LOG_DEBUG
2020-04-14 09:30:14.824591 I [12642/12644] Logger logging.cpp:280 (run) - Added logging to the console
2020-04-14 09:30:14.824905 I [12642/12643] LogForward loggingserver.cpp:128 (FileLogger) - Added logging to /var/log/mythtv/mythmetadatalookup.20200414143014.12642.log
2020-04-14 09:30:14.824914 I [12642/12642] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Interrupt handler
2020-04-14 09:30:14.824925 I [12642/12642] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Terminated handler
2020-04-14 09:30:14.824932 I [12642/12642] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Segmentation fault handler
2020-04-14 09:30:14.824941 I [12642/12642] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Aborted handler
2020-04-14 09:30:14.824950 I [12642/12642] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Bus error handler
2020-04-14 09:30:14.824957 I [12642/12642] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Floating point exception handler
2020-04-14 09:30:14.824966 I [12642/12642] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Illegal instruction handler
2020-04-14 09:30:14.824975 I [12642/12642] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Real-time signal 0 handler
2020-04-14 09:30:14.824986 I [12642/12642] thread_unknown signalhandling.cpp:191 (SetHandlerPrivate) - Setup Hangup handler
2020-04-14 09:30:14.825105 N [12642/12642] thread_unknown mythdirs.cpp:203 (InitializeMythDirs) - Using runtime prefix = /usr/local
2020-04-14 09:30:14.825108 N [12642/12642] thread_unknown mythdirs.cpp:204 (InitializeMythDirs) - Using configuration directory = /home/mythtv/.mythtv
2020-04-14 09:30:14.825113 D [12642/12642] thread_unknown mythdirs.cpp:207 (InitializeMythDirs) - appbindir         = /usr/local/bin/
2020-04-14 09:30:14.825116 D [12642/12642] thread_unknown mythdirs.cpp:208 (InitializeMythDirs) - sharedir          = /usr/local/share/mythtv/
2020-04-14 09:30:14.825120 D [12642/12642] thread_unknown mythdirs.cpp:209 (InitializeMythDirs) - libdir            = /usr/local/lib/mythtv/
2020-04-14 09:30:14.825125 D [12642/12642] thread_unknown mythdirs.cpp:210 (InitializeMythDirs) - themedir          = /usr/local/share/mythtv/themes/
2020-04-14 09:30:14.825127 D [12642/12642] thread_unknown mythdirs.cpp:211 (InitializeMythDirs) - pluginsdir        = /usr/local/lib/mythtv/plugins/
2020-04-14 09:30:14.825132 D [12642/12642] thread_unknown mythdirs.cpp:212 (InitializeMythDirs) - translationsdir   = /usr/local/share/mythtv/i18n/
2020-04-14 09:30:14.825135 D [12642/12642] thread_unknown mythdirs.cpp:213 (InitializeMythDirs) - filtersdir        = /usr/local/lib/mythtv/filters/
2020-04-14 09:30:14.825140 D [12642/12642] thread_unknown mythdirs.cpp:214 (InitializeMythDirs) - cachedir          = /home/mythtv/.mythtv/cache
2020-04-14 09:30:14.825142 D [12642/12642] thread_unknown mythdirs.cpp:215 (InitializeMythDirs) - remotecachedir    = /home/mythtv/.mythtv/cache/remotecache
2020-04-14 09:30:14.825147 D [12642/12642] thread_unknown mythdirs.cpp:216 (InitializeMythDirs) - themebasecachedir = /home/mythtv/.mythtv/cache/themecache
2020-04-14 09:30:14.825155 D [12642/12642] thread_unknown mythdirs.cpp:217 (InitializeMythDirs) - thumbnaildir      = /home/mythtv/.mythtv/cache/thumbnails
2020-04-14 09:30:14.825190 I [12642/12642] CoreContext mythcorecontext.cpp:284 (Init) - Assumed character encoding: en_US
2020-04-14 09:30:14.825198 W [12642/12642] CoreContext mythcorecontext.cpp:288 (Init) - This application expects to be running a locale that specifies a UTF-8 codeset, and many features may behave improperly with your current language settings. Please set the LC_ALL or LC_CTYPE, and LANG variable(s) in the environment in which this program is executed to include a UTF-8 codeset (such as 'en_US.UTF-8').
2020-04-14 09:30:14.825517 I [12642/12642] CoreContext mythcontext.cpp:629 (LoadDatabaseSettings) - Empty LocalHostName. This is typical.
2020-04-14 09:30:14.825524 I [12642/12642] CoreContext mythcontext.cpp:636 (LoadDatabaseSettings) - Using a profile name of: 'robrutrm.marsinnovations.us' (Usually the same as this host's name.)
2020-04-14 09:30:14.825609 I [12642/12642] CoreContext mythcontext.cpp:885 (TestDBconnection) - Start up testing connections. DB localhost, BE , attempt 0, status dbAwake, Delay: 2000
2020-04-14 09:30:14.825619 D [12642/12642] CoreContext portchecker.cpp:75 (checkPort) - PortChecker::checkPort(): host localhost port 3306 timeLimit 1000 linkLocalOnly 0
2020-04-14 09:30:15.326278 D [12642/12642] CoreContext portchecker.cpp:195 (checkPort) - PortChecker::checkPort(): socket state 1
2020-04-14 09:30:15.826710 D [12642/12642] CoreContext portchecker.cpp:195 (checkPort) - PortChecker::checkPort(): socket state 3
2020-04-14 09:30:15.831641 D [12642/12642] CoreContext portchecker.cpp:75 (checkPort) - PortChecker::checkPort(): host localhost port 3306 timeLimit 30000 linkLocalOnly 1
2020-04-14 09:30:15.833958 D [12642/12642] CoreContext mythcontext.cpp:513 (FindDatabase) - FindDatabase() - Success!
2020-04-14 09:30:15.834021 D [12642/12642] CoreContext portchecker.cpp:75 (checkPort) - PortChecker::checkPort(): host localhost port 3306 timeLimit 30000 linkLocalOnly 1
2020-04-14 09:30:15.835874 N [12642/12642] CoreContext mythcorecontext.cpp:1809 (InitLocale) - Setting QT default locale to en_US
2020-04-14 09:30:15.835892 I [12642/12642] CoreContext mythcorecontext.cpp:1842 (SaveLocaleDefaults) - Current locale en_US
2020-04-14 09:30:15.835953 N [12642/12642] CoreContext mythlocale.cpp:121 (LoadDefaultsFromXML) - Reading locale defaults from /usr/local/share/mythtv//locales/en_us.xml
2020-04-14 09:30:15.845162 W [12642/12642] CoreContext platforms/mythpowerdbus.cpp:104 (Init) - PowerDBus: No login1 interface. Cannot change system power state
2020-04-14 09:30:15.846793 I [12642/12642] CoreContext platforms/mythpowerdbus.cpp:360 (DeviceAdded) - PowerDBus: Added UPower.Device '/org/freedesktop/UPower/devices/ups_hiddev0'
2020-04-14 09:30:15.846800 I [12642/12642] CoreContext mythpower.cpp:374 (PowerLevelChanged) - Power: Unknown power source
2020-04-14 09:30:15.847210 I [12642/12642] CoreContext mythpower.cpp:131 (Init) - Power: Supported actions: None
2020-04-14 09:30:15.848172 I [12642/12642] CoreContext mythtranslation.cpp:62 (load) - Loading en_us translation for module mythfrontend
2020-04-14 09:30:15.848810 I [12642/12642] CoreContext main.cpp:111 (main) - Testing grabbers and metadata sites for functionality...
2020-04-14 09:30:15.849689 I [12642/12648] SystemSignalManager mythsystemunix.cpp:488 (run) - Starting process signal handler
2020-04-14 09:30:15.851134 I [12642/12647] SystemManager mythsystemunix.cpp:262 (run) - Starting process manager
2020-04-14 09:30:15.851298 I [12642/12649] SystemIOHandlerR mythsystemunix.cpp:82 (run) - Starting IO manager (read)
2020-04-14 09:30:15.851380 I [12642/12650] SystemIOHandlerW mythsystemunix.cpp:82 (run) - Starting IO manager (write)
2020-04-14 09:30:17.401991 I [12642/12642] CoreContext metadatadownload.cpp:422 (TelevisionGrabberWorks) - Television grabber not functional.  Aborting this run.
2020-04-14 09:30:17.402094 I [12642/12642] CoreContext platforms/mythpowerdbus.cpp:72 (~MythPowerDBus) - PowerDBus: Closing interfaces
2020-04-14 09:30:17.402568 I [12642/12642] CoreContext mythcontext.cpp:1665 (~MythContext) - Exiting
I am not sure what the mythcommflag log is telling me, ie why it was not successful. It is evident that the mythmetadatalookup log reveals that this function didn't work because the Television grabber is not functional.
Any further suggestions?

MikeB2013
Senior
Posts: 519
Joined: Mon Jul 25, 2016 4:16 pm
Great Britain

Re: Commercial skipping not working on V31

Post by MikeB2013 » Tue Apr 14, 2020 5:22 pm

Have you got Storage Groups for Cover Art, Fan Art, Banners set using mythtv-setup Storage Directories?

Edit: the above is for metadata

User avatar
bill6502
Developer
Posts: 1722
Joined: Fri Feb 07, 2014 5:28 pm
United States of America

Re: Commercial skipping not working on V31

Post by bill6502 » Tue Apr 14, 2020 10:08 pm

You're really trying to solve two problems here.

For metadata, in addition to what Mike said above, the line containing
'...Television grabber not functional. Aborting this run...' in the last log
above guarantees that metadatalookup will fail. You can run the command
that does the test like this: /usr/local/share/mythtv/metadata/Television/ttvdb.py -t
That may give you a hint about what's wrong. Missing Python modules
would be my 1st guess, or the shebang is wrong (python2 where it should
be python3 for example.)

As to the initial topic (again, nothing to do with metadatalookup) the log
says you're using method 7 as defined for channel 2084. That says its
looking for Blank/Scene/Logo. Per the program notes, scene detection
in the USA gets too many false positives. Refs:
https://code.mythtv.org/cgit/mythtv/tre ... es.cpp#n77
https://code.mythtv.org/cgit/mythtv/tre ... ypes.h#n90

Is it possible you changed methods, or added methods to your channels?
All of my channels.commmethod are set to -1 (uninit.)
I'd experiment with other choices and note that per-channel settings aren't
required as there's a global one (set oddly enough, in the frontend settings).
Settings->Video->General->General (Jobs)->Commercial Detection Method.
The per-channel settings override the above.

rocknrobin72
Junior
Posts: 21
Joined: Thu Mar 26, 2020 8:07 pm
United States of America

Re: Commercial skipping not working on V31

Post by rocknrobin72 » Wed Apr 15, 2020 11:29 pm

Thanks Bill6502. I did see another post from jfabernathy concerning metadata lookup failing with exit code 128. In this post I see he mentions running the python test you suggest, /usr/local/share/mythtv/metadata/Television/ttvdb.py -t and the response was basically the same as his that requests_cache were missing. In /usr/lib64/python3.8/site-packages I had request, but not requests_cache. I installed requests-cache and now when I run the python test again I get:

Code: Select all

# /usr/local/share/mythtv/metadata/Television/ttvdb.py -t
Everything appears to be in order

Then the next time I set up a recording test the metadatalookup completed okay. In addition when I checked the mythcommflag log I was seeing breaks whereas I was seeing 0 before. All appears to be working now as I am actually skipping commercials, that is when they are flagged properly. Some commercials, very few, but some, don't seem to meet the parameters for comm flags so sometimes not all of the commercials will be skipped, but for the most part it is now working. I saw the global Commercial Detection Method in the backend and I have it set for All Available Methods. In addition when I got the mythcommflag.log running I could see that after a thirty minute recording it takes from five to seven minutes for the commercial detection routine to complete. I may have been trying to watch the recording before the commercial detection had completed. I am afraid to try to run commercial detection during the actual recording as I only have 4 GB of RAM on my server with an 8GB swap file.
As a sidebar when my Slackware distro upgraded from python 3.7 (which is what I compiled mythtv under initially) to python 3.8 it bit me because it broke mythtv and I had to add the dependencies again, mainly mysqlclient, to get the python bindings to work on configure when re-installing (I am installing from source). Unless I add an alias python=python3 after I reboot I end up with python --version still showing 2.7.17. I had to add the alias and then compile the latest install for mythtv under python 3.8 to use the latest version of python. In /usr/bin I still see the dynamic link python -> python2.7. I was wondering if that is going to bite me every time I want to re-install or if I could change that dynamic link to python->python3 or python->3.8 without breaking too much??

User avatar
bill6502
Developer
Posts: 1722
Joined: Fri Feb 07, 2014 5:28 pm
United States of America

Re: Commercial skipping not working on V31

Post by bill6502 » Tue Apr 21, 2020 9:28 pm

The default in ./configure is python3. So no need to use /etc/alternatives or aliases. The issue was
the minor version change (7->8.) You can go to the source mythtv/mythtv/bindings/python and just
run make/make install there if the minor version changes again.

rocknrobin72
Junior
Posts: 21
Joined: Thu Mar 26, 2020 8:07 pm
United States of America

Re: Commercial skipping not working on V31

Post by rocknrobin72 » Tue Apr 21, 2020 10:22 pm

Thanks for all the help bill6502. MythTV has been working like a champ since I re-installed it because of the minor python version change. Everything seems to be working as advertised.

User avatar
Steve Goodey
Moderator
Posts: 114
Joined: Fri Feb 07, 2014 6:30 pm
Location: Colchester, England
Great Britain

Re: Commercial skipping not working on V31

Post by Steve Goodey » Wed Apr 22, 2020 4:17 am

Hello,

If you're happy with the fix could you possibly added [Solved] to the subject line in the first post?

Ta.

Post Reply