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?