search

stuart-knock / myth2kodi

Maps MythTV recording files to a Kodi library.
6 stars 2 forks source link

Unrecognized shows are not being moved to "Showings" directory #3

Open szczys opened 6 years ago

szczys commented 6 years ago

I have disabled the showstopper setting but unrecognized shows are not being moved to the "Showings" directory. Here's a log:

myth2kodi Version 1.5.1 on Linux
[2017-12-27T23:06:08] INFO: Processing: /Lenny/mythtv/1151_20171206043400.ts
[2017-12-27T23:06:09] INFO: Accessing MythTV DataBase Using PythonBindings.
mythdb_access: WARNING:__main__:No comskip information found.
[2017-12-27T23:06:09] INFO: Processing MythTV database info as SchedulesDirect.
[2017-12-27T23:06:09] INFO: We have existing info for: 'The Tonight Show Starring Jimmy Fallon'
[2017-12-27T23:06:09] WARNING: get_absolute_episode_number_fuzzy_plot(): AbsoluteEpisodeNumber guess from full Plot failed or too fuzzy, trying chunked match.
[2017-12-27T23:06:10] WARNING: get_absolute_episode_number_fuzzy_plot(): Chunked Plot match is still too fuzzy, failed to guess AbsoluteEpisodeNumber.
[2017-12-27T23:06:10] WARNING: Failed to identify episode.
[2017-12-27T23:06:10] WARNING: Low episode-confidence-rating (-8), requiring doover.
[2017-12-27T23:06:10] INFO: ################ OPERATION SUMMARY ################
      JOB: myth2kodi '/Lenny/mythtv/1151_20171206043400.ts' "" "" "" ''
      CALLER=mythtv-
      PROCESS_RECORDING_MODE=MOVE-
      SYMLINK=Disabled-
      RECORDING_PATH=/Lenny/mythtv/1151_20171206043400.ts-
      TVDB_SERIESID=270261-
      TVMAZE_SERIESID=718-
      TITLE=The Tonight Show Starring Jimmy Fallon-
      SUBTITLE=Kate McKinnon Patton Oswalt Wiz Khalifa Ty Dolla ign-
      SEASON=-
      EPISODE=-
      MoveFileSize=783 MB
      ConfidenceRating=-7-
      ConfidenceReasoning= Have existing series info; Attempted SUBTITLE exact match: Failed; Attempted SUBTITLE fuzzy match: Failed; Attempted match on ORIGINALAIRDATE: Failed. Attempted Plot fuzzy match: Failed;-
      MDB_STARTTIME=2017-12-05 22:34:00-06:00-
      MDB_PROGRAMID=EP017211070892-
      MDB_STORAGEGROUP=Default-
      MDB_CATEGORY=Talk-
      ProgramIDType=Series With Episode Data-
      Zap2itSeriesID=1721107-
      Plot=Actress Kate McKinnon actor Patton Oswalt Wiz Khalifa performs with Ty Dolla ign.-
[2017-12-27T23:06:10] INFO: ################ END OF OPERATION SUMMARY ################
[2017-12-27T23:06:10] WARNING: Episode could not be matched with supplied information.
[2017-12-27T23:06:10] WARNING: NAME COULD NOT BE ASSIGNED BASED UPON DATA SUPPLIED
@@@@ COMPLETE: 2017-12-27 23:06:10-06:00 @@@@

Here are the pertinent lines from myth2kodi.conf:

PROCESS_RECORDING_MODE='MOVE'
SYMLINK='Disabled'
ShowStopper='Disabled'

It doesn't look like the script is trying to move these. Do I have it set correctly?

stuart-knock commented 6 years ago

Apologies, the functionality of this user-setting has changed slightly over the years but the associated comment had not been updated to reflect those changes. I've tried to clarify the wording (b0c40810ad78621eaa4937d257f445f72afbb229) and will look into the issue further in the new year.

Currently, ShowStopper only affects recordings that have not been tagged as either a movie or an episode of a continuing tv-series with an entry in TheTVDB (ie not tagged as either RECORDING_TYPE=MV or RECORDING_TYPE=EP). This typically means things like news or sporting-events.

The example that you provided (The Tonight Show Starring Jimmy Fallon) has an entry in TheTVDB and your MythTV-DB identifies it as being an episode (the EP part at the start of MDB_PROGRAMID). So, what is happening in the log you provide is that the series is correctly identified but the specific episode is not. In this case, myth2kodi defaults to not processing the episode (regardless of ShowStopper). This is because the reasons for an episode not being recognised can usually be corrected in a number of ways and subsequent re-processing of the recording will result in a correctly identified episode. NOTE: with your settings, forcing processing/moving in this instance would remove the possibility of subsequent correct processing of the recording (as disabling SYMLINK means the recording is now disconnected from the MythTV-DB).

That being said, I created a fake recording containing the information from the log you posted and the episode is correctly identified for me... It is possible that when you originally processed this recording the information in TheTVDB had not been updated and that if you reprocess the recording now it will process correctly (episode identified from fuzzy subtitle match).

If the recording still does not process correctly for you, then please set LOGLEVEL=3 and try processing the recording again and post the full log.

If you would still like to force processing of all unrecognised recordings, despite the negative impact this can have on any subsequent correct processing, please provide a detailed description of how you would expect this to behave. I will consider(no promises) adding a user-setting that enables this behaviour.

szczys commented 6 years ago

I am having trouble with matching and it seems weird to me because fuzzy matching shouldn't really be needed. Below I'll paste full debugging for a Late Night with Stephen Colbert episode that has good support in thetvdb.

As for ShowStopper, I've been using this since Adam Outler originally developed it. Basically any unmatched get dumped into the Showings directory with folders for the show name and filenames with the recording date. I can just watch them without scanning into the library, or I can manually sort out later if I want. That was kind of a nice feature as now it's rather labor intensive to get an unscanned recording into a folder where Kodi can see it.

Here's the log, any idea where my system is going wrong with matching these?

[2018-01-06T19:21:55] DEBUG: LOGFILE=/home/mythtv/.myth2kodi/myth2kodi.log-
[2018-01-06T19:21:55] DEBUG: LOGLEVEL=3-
[2018-01-06T19:21:55] DEBUG: LOGTYPE=filestderr-
[2018-01-06T19:21:55] DEBUG: ENTERING: validate_settings() ; CALLED FROM: m2k_init()
[2018-01-06T19:21:55] DEBUG: ENTERING: prune_series_list() ; CALLED FROM: m2k_init()
[2018-01-06T19:21:55] DEBUG: ENTERING: get_tvdb_token() ; CALLED FROM: m2k_init()
[2018-01-06T19:21:55] DEBUG: Token last updated: 'Sat Jan  6 15:39:25 CST 2018'
[2018-01-06T19:21:55] DEBUG: Using existing token.
[2018-01-06T19:21:55] DEBUG: myth2kodi script initialisation complete.
[2018-01-06T19:21:55] DEBUG: Running myth2kodi as 'mythtv'
[2018-01-06T19:21:55] DEBUG: ENTERING: validate_args() ; CALLED FROM: main()
[2018-01-06T19:21:55] DEBUG: Called with 1 arguments. Called as:
       myth2kodi '/Lenny/mythtv/1031_20180104043500.ts' "" "" "" ''
[2018-01-06T19:21:55] DEBUG: ENTERING: check_path_permission() ; CALLED FROM: validate_args()
       path_to_check = '/Lenny/mythtv/1031_20180104043500.ts'
       required_permission = 'rw'
[2018-01-06T19:21:55] DEBUG: Checking 'rw' permissions for: '/Lenny/mythtv/1031_20180104043500.ts'
[2018-01-06T19:21:55] DEBUG: Good start: 'mythtv' owns it.
[2018-01-06T19:21:55] DEBUG: Permissions ok for: '/Lenny/mythtv/1031_20180104043500.ts'
[2018-01-06T19:21:55] DEBUG: ENTERING: pre_process_check() ; CALLED FROM: validate_args()
       arg1: '/Lenny/mythtv/1031_20180104043500.ts'
[2018-01-06T19:21:55] DEBUG: Looks ok, file_path is named like a MythTV recording: '/Lenny/mythtv/1031_20180104043500.ts'
[2018-01-06T19:21:55] DEBUG: Arguments seem valid.
[2018-01-06T19:21:55] DEBUG: No command flag set. Processing as recording.
[2018-01-06T19:21:55] DEBUG: ENTERING: periodic_maintenace() ; CALLED FROM: main()
[2018-01-06T19:21:55] DEBUG: ENTERING: set_recording_name() ; CALLED FROM: main()
       arg1: '/Lenny/mythtv/1031_20180104043500.ts'
[2018-01-06T19:21:55] DEBUG: Setting RECORDING_PATH='/Lenny/mythtv/1031_20180104043500.ts'
[2018-01-06T19:21:55] DEBUG: Setting RECORDING_NAME='1031_20180104043500.ts'
@@@@ PROCESSING RECORDING: 2018-01-06 19:21:55-06:00 @@@@
myth2kodi Version 1.5.1 on Linux
[2018-01-06T19:21:55] INFO: Processing: /Lenny/mythtv/1031_20180104043500.ts
[2018-01-06T19:21:55] DEBUG: ENTERING: process_mythtvdb() ; CALLED FROM: main()
[2018-01-06T19:21:55] DEBUG: ENTERING: get_mythtvdb_info() ; CALLED FROM: process_mythtvdb()
[2018-01-06T19:21:55] DEBUG: ENTERING: get_mythtvdb_info_pythonbindings() ; CALLED FROM: get_mythtvdb_info()
       m2kdir: '/home/mythtv/.myth2kodi'
       binpath: '/usr/local/bin'
       INPUT_PATH_BASENAME: '1031_20180104043500.ts'
       DBHostName: 'localhost'
       MySQLMythDb: 'mythconverg'
       MySQLuser: 'mythtv'
[2018-01-06T19:21:55] INFO: Accessing MythTV DataBase Using PythonBindings.
[2018-01-06T19:21:55] DEBUG: Storing MythTV-DB info in temporary file: '/tmp/m2k_recinfo_2018-01-06T192155-psN3'
mythdb_access: DEBUG:__main__:Establishing database connection
mythdb_access: WARNING:__main__:No comskip information found.
mythdb_access: DEBUG:__main__:Attempting to write data to file: /tmp/m2k_recinfo_2018-01-06T192155-psN3
mythdb_access: DEBUG:__main__:Calling write_data()
mythdb_access: DEBUG:__main__:Operation complete.
mythdb_access: DEBUG:__main__:Closing out the file/stdout
[2018-01-06T19:21:55] DEBUG: Guide data as extracted by: get_mythtvdb_info_pythonbindings()
       MDB_CHANID='1031'
       MDB_STARTTIME='2018-01-03 22:35:00-06:00'
       MDB_TITLE='The Late Show With Stephen Colbert'
       MDB_SUBTITLE='David Harbour; Julien Baker'
       MDB_DESCRIPTION='Actor David Harbour; Julien Baker performs.'
       MDB_SEASON='0'
       MDB_EPISODE='0'
       MDB_CATEGORY='Talk'
       MDB_SERIESID='EP01906276'
       MDB_PROGRAMID='EP019062760472'
       MDB_INETREF=''
       MDB_STARS='0.0'
       MDB_ORIGINALAIRDATE='2018-01-03'
       MDB_STORAGEGROUP='Default'
       MDB_XMLTVGRABBER='schedulesdirect1'
[2018-01-06T19:21:55] DEBUG: Created a temporary comskip markupstart file: '/tmp/m2k_markupstart_2018-01-06T192155-f5jJ'
[2018-01-06T19:21:55] DEBUG: Created a temporary comskip markupstop file: '/tmp/m2k_markupstop_2018-01-06T192155-1jCP'
[2018-01-06T19:21:55] DEBUG: ENTERING: generate_comskip() ; CALLED FROM: get_mythtvdb_info_pythonbindings()
[2018-01-06T19:21:55] DEBUG: Created a temporary comskip markupframes file: '/tmp/m2k_markupframes_2018-01-06T192155-zhd9'
[2018-01-06T19:21:55] DEBUG: Not removing the temporary markup files: '/tmp/m2k_markupstart_2018-01-06T192155-f5jJ'; '/tmp/m2k_markupstop_2018-01-06T192155-1jCP'.
[2018-01-06T19:21:55] DEBUG: Not removing the temporary MythTV-DB info file: '/tmp/m2k_recinfo_2018-01-06T192155-psN3'
[2018-01-06T19:21:55] DEBUG: ENTERING: process_mythtvdb_schedulesdirect() ; CALLED FROM: process_mythtvdb()
[2018-01-06T19:21:55] INFO: Processing MythTV database info as SchedulesDirect.
[2018-01-06T19:21:55] DEBUG: ENTERING: process_mythtvdb_common() ; CALLED FROM: process_mythtvdb_schedulesdirect()
[2018-01-06T19:21:55] DEBUG: ENTERING: set_ProgramIDType() ; CALLED FROM: process_mythtvdb_schedulesdirect()
       RECORDING_TYPE='EP'
[2018-01-06T19:21:55] DEBUG: Input and MythTV-DB guide data for 1031_20180104043500.ts
       RECSTART:'2018-01-03 22:35:00-06:00'
       DATE:'' '2018-01-03'
       PROGRAMID:'EP019062760472'
       CATEGORY:'Talk'
       TITLE:'The Late Show With Stephen Colbert'
       SUBTITLE:'David Harbour; Julien Baker'
       SEASON:'UNKNOWN'
       EPISODE:'UNKNOWN'
       Plot: "Actor David Harbour Julien Baker performs."
[2018-01-06T19:21:55] DEBUG: ENTERING: show_translation() ; CALLED FROM: main()
[2018-01-06T19:21:55] DEBUG: Checking for translations in: '/home/mythtv/.myth2kodi/showTranslations'.
[2018-01-06T19:21:55] DEBUG: No showTranslations file.
[2018-01-06T19:21:55] DEBUG: ENTERING: process_ignore_lists() ; CALLED FROM: main()
[2018-01-06T19:21:55] DEBUG: ENTERING: identify_series() ; CALLED FROM: main()
       RECORDING_TYPE='EP'
       ONLINE_LOOKUP='Enabled'
       TITLE='The Late Show With Stephen Colbert'
       Zap2itSeriesID='1906276'
[2018-01-06T19:21:55] DEBUG: ENTERING: have_series_info() ; CALLED FROM: identify_series()
       TITLE='The Late Show With Stephen Colbert'
[2018-01-06T19:21:55] INFO: No existing info for: 'The Late Show With Stephen Colbert'
[2018-01-06T19:21:55] DEBUG: ENTERING: download_series_identification() ; CALLED FROM: identify_series()
[2018-01-06T19:21:55] INFO: SEARCHING: www.TheTVDB.com for 'The Late Show With Stephen Colbert'
[2018-01-06T19:21:56] DEBUG: ENTERING: get_serieslinenumber_showname() ; CALLED FROM: identify_series()
[2018-01-06T19:21:56] DEBUG: ENTERING: set_tvdb_seriesid_title_from_serieslinenumber() ; CALLED FROM: identify_series()
[2018-01-06T19:21:56] INFO: Found: "The Late Show with Stephen Colbert" ID#: '289574' with CONFIDENCE:'1'
[2018-01-06T19:21:57] INFO: Identified TVMAZE_SERIESID='2756' for TVDB_SERIESID='289574'.
[2018-01-06T19:21:57] DEBUG: ENTERING: get_series_info() ; CALLED FROM: main()
       TVDB_SERIESID='289574'
       TVDB_TITLE='The Late Show with Stephen Colbert'
[2018-01-06T19:21:57] DEBUG: ENTERING: make_m2k_database_dir() ; CALLED FROM: get_series_info()
[2018-01-06T19:21:57] DEBUG: ENTERING: check_m2k_database_up_to_date() ; CALLED FROM: get_series_info()
[2018-01-06T19:21:57] DEBUG: The series has status='Continuing'.
[2018-01-06T19:21:57] DEBUG: Database maintained. Current: 1515288117. Next update: 1515552737.
[2018-01-06T19:21:57] DEBUG: ENTERING: identify_episode() ; CALLED FROM: main()
       RECORDING_TYPE='EP'
       TVDB_TITLE='The Late Show with Stephen Colbert'
       SUBTITLE='David Harbour Julien Baker'
       SEASON=''
       EPISODE=''
       Plot='Actor David Harbour Julien Baker performs.'
[2018-01-06T19:21:57] DEBUG: ENTERING: set_absolute_episode_number_by_ename() ; CALLED FROM: identify_episode()
       TVDB_TITLE = 'The Late Show with Stephen Colbert'
       SUBTITLE = 'David Harbour Julien Baker'
[2018-01-06T19:21:57] DEBUG: ENTERING: set_absolute_episode_number_by_ename_fuzzy() ; CALLED FROM: identify_episode()
       TVDB_TITLE = 'The Late Show with Stephen Colbert'
       SUBTITLE = 'David Harbour Julien Baker'
       AbsoluteEpisodeNumber guesses: 397,844,198,953.
       Cost: 15,15,1,1.
[2018-01-06T19:21:57] DEBUG: ENTERING: multi_match_check() ; CALLED FROM: set_absolute_episode_number_by_ename_fuzzy()
       TVDB_TITLE: 'The Late Show with Stephen Colbert'
       There are 2 AENguesses: 198,953.
[2018-01-06T19:21:57] DEBUG: Multiple AbsoluteEpisodeNumber guesses matched plot, confirming they're the same episode before using.
[2018-01-06T19:21:57] DEBUG: There are '2' matching episodes: s03e62,s2018e02.
[2018-01-06T19:21:57] DEBUG: most_common_match='s03e62'
[2018-01-06T19:21:57] DEBUG: most_common_match_count='1'
[2018-01-06T19:21:57] DEBUG: The AbsoluteEpisodeNumber guesses were different:
       AENguess = SxxExxGuesses
       0: 198 = 's03e62'
       1: 953 = 's2018e02'
[2018-01-06T19:21:57] DEBUG: ENTERING: set_absolute_episode_number_by_airdate() ; CALLED FROM: identify_episode()
[2018-01-06T19:21:57] DEBUG: ENTERING: get_absolute_episode_number_fuzzy_plot() ; CALLED FROM: identify_episode()
       AbsoluteEpisodeNumber: '198'
       TVDB_TITLE: 'The Late Show with Stephen Colbert'
       Plot: 'Actor David Harbour Julien Baker performs.'
       PlotLength: '43'
[2018-01-06T19:21:57] DEBUG: ENTERING: fuzzy_plot_match() ; CALLED FROM: get_absolute_episode_number_fuzzy_plot()
       TVDB_TITLE: 'The Late Show with Stephen Colbert'
       Plot: 'Actor David Harbour Julien Baker performs.'
       PlotLength: '43'
       AbsoluteEpisodeNumber guesses: 953.
       Cost: 15.
       MatchedPlot 0: America Ferrera David Harbour musical performance by Julien Baker
[2018-01-06T19:21:57] WARNING: get_absolute_episode_number_fuzzy_plot(): AbsoluteEpisodeNumber guess from full Plot failed or too fuzzy, trying chunked match.
[2018-01-06T19:21:57] DEBUG: ENTERING: chunked_fuzzy_plot_match() ; CALLED FROM: get_absolute_episode_number_fuzzy_plot()
       TVDB_TITLE: 'The Late Show with Stephen Colbert'
       Plot: 'Actor David Harbour Julien Baker performs.'
[2018-01-06T19:21:57] DEBUG: Attempting chunked fuzzy match.
       PlotWords='6'
       chunck_size='5'
       chunk_overlap='3'
       min_chunk_length='23'
       inv_error_rate='5'
       numchunks='3'
       i='0'
       n='1'
       m='5'
       chunk='Actor David Harbour Julien Baker'
       ChunkLength='33'
       chunked_fuzzy_plot_match(): fuzzychunk 1: 198:10:Stephen welcomes actress America Ferrera, actor David Harbour, plus a musical performance by Julien Baker..
       chunked_fuzzy_plot_match(): ChunkGuesses 1: 198.
       chunked_fuzzy_plot_match(): ChunkCosts 1: 10.
       i='1'
       n='3'
       m='7'
       chunk='Harbour Julien Baker performs.'
       ChunkLength='31'
       chunked_fuzzy_plot_match(): fuzzychunk 2: 534:11:Actress Sharon Stone actor Justin Theroux James Taylor performs.,953:11:America Ferrera David Harbour musical performance by Julien Baker.
       chunked_fuzzy_plot_match(): ChunkGuesses 2: 534,953.
       chunked_fuzzy_plot_match(): ChunkCosts 2: 11,11.
[2018-01-06T19:21:58] DEBUG: chunked_fuzzy_plot_match(): GoodChunkGuesses 0: .
[2018-01-06T19:21:58] WARNING: get_absolute_episode_number_fuzzy_plot(): Chunked Plot match is still too fuzzy, failed to guess AbsoluteEpisodeNumber.
[2018-01-06T19:21:58] WARNING: Failed to identify episode.
[2018-01-06T19:21:58] WARNING: Low episode-confidence-rating (-8), requiring doover.
[2018-01-06T19:21:58] DEBUG: ENTERING: set_sxxexx_with_season_episode() ; CALLED FROM: main()
       SEASON=''
       EPISODE=''
       SeasonEpisode=''
[2018-01-06T19:21:58] DEBUG: No Season & Episode number information is available.
[2018-01-06T19:21:58] DEBUG: ENTERING: storagegroup_fallback() ; CALLED FROM: main()
[2018-01-06T19:21:58] DEBUG: ENTERING: check_filesystem() ; CALLED FROM: main()
[2018-01-06T19:21:58] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '653'
       arg 2 = '366083'
       arg 3 = '/Lenny/videoLibrary/TV'
[2018-01-06T19:21:58] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '653'
       arg 2 = '366083'
       arg 3 = '/Lenny/videoLibrary/TV'
[2018-01-06T19:21:58] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '653'
       arg 2 = '366083'
       arg 3 = '/Lenny/videoLibrary/Movies'
[2018-01-06T19:21:58] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '653'
       arg 2 = '366083'
       arg 3 = '/Lenny/videoLibrary/Movies'
[2018-01-06T19:21:58] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '653'
       arg 2 = '366083'
       arg 3 = '/Lenny/videoLibrary/Showings'
[2018-01-06T19:21:58] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '653'
       arg 2 = '366083'
       arg 3 = '/Lenny/videoLibrary/Showings'
[2018-01-06T19:21:58] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '1'
       arg 2 = '366083'
       arg 3 = '/Lenny/mythtv'
[2018-01-06T19:21:58] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '5'
       arg 2 = '800011'
       arg 3 = '/home/mythtv/.myth2kodi'
[2018-01-06T19:21:58] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '5'
       arg 2 = '13504'
       arg 3 = '/tmp'
[2018-01-06T19:21:58] DEBUG: filesystem_write_state='0'
[2018-01-06T19:21:58] DEBUG: ENTERING: msg_operation_summary() ; CALLED FROM: main()
[2018-01-06T19:21:58] INFO: ################ OPERATION SUMMARY ################
      JOB: myth2kodi '/Lenny/mythtv/1031_20180104043500.ts' "" "" "" ''
       m2kVersion=myth2kodi Version 1.5.1-
      CALLER=mythtv-
       m2kdir=/home/mythtv/.myth2kodi-
       Timeout=50-
      PROCESS_RECORDING_MODE=MOVE-
      SYMLINK=Disabled-
       TargetPathIsInputPath=Disabled-
       FailSafeMode=Disabled-
       FailSafeDir=/home/mythtv/FailSafe-
       Notify=Disabled-
[2018-01-06T19:21:58] DEBUG: ENTERING: msg_dir_summary() ; CALLED FROM: msg_operation_summary()
      A summary table of writeable directories: 0='WRITEABLE' ; 1='NOT WRITEABLE'
      USER SETTING      |FLAG |Free Space  |Folder name
      m2kdir            |  0  |  800011 MB | /home/mythtv/.myth2kodi
      M2K_TMPDIR        |  0  |   13504 MB | /tmp
      ORIGINAL_DIRNAME  |  0  |  366083 MB | /Lenny/mythtv
      MoveDir           |  0  |  366083 MB | /Lenny/videoLibrary/TV
      AlternateMoveDir  |  0  |  366083 MB | /Lenny/videoLibrary/TV
      PrimaryMovieDir   |  0  |  366083 MB | /Lenny/videoLibrary/Movies
      AlternateMovieDir |  0  |  366083 MB | /Lenny/videoLibrary/Movies
      PrimaryShowDir    |  0  |  366083 MB | /Lenny/videoLibrary/Showings
      AlternateShowDir  |  0  |  366083 MB | /Lenny/videoLibrary/Showings
      RECORDING_PATH=/Lenny/mythtv/1031_20180104043500.ts-
      TVDB_SERIESID=289574-
      TVMAZE_SERIESID=2756-
      TITLE=The Late Show with Stephen Colbert-
       INPUT_TITLE=-
       MDB_TITLE=The Late Show With Stephen Colbert-
       TVDB_TITLE=The Late Show with Stephen Colbert-
      SUBTITLE=David Harbour Julien Baker-
       INPUT_SUBTITLE=-
       MDB_SUBTITLE=David Harbour; Julien Baker-
       TVDB_SUBTITLE=-
      SEASON=-
       INPUT_SEASON=-
       MDB_SEASON=0-
       TVDB_SEASON=-
      EPISODE=-
       INPUT_EPISODE=-
       MDB_EPISODE=0-
       TVDB_EPISODE=-
       Sxx=- Exx=-
       LastUpdatedTVDB=1515287817-
       Current Time=1515288118-
       AbsoluteEpisodeNumber=-
       HasSxxExx=-
      MoveFileSize=653 MB
      ConfidenceRating=-7-
      ConfidenceReasoning= Attempted Title match on Standard Logic: Successful; Attempted SUBTITLE exact match: Failed; Attempted SUBTITLE fuzzy match: Failed; Attempted match on ORIGINALAIRDATE: Failed. Attempted Plot fuzzy match: Failed;-
       MDB_XMLTVGRABBER=schedulesdirect1-
      MDB_STARTTIME=2018-01-03 22:35:00-06:00-
       MDB_AIRDATE=-
       ORIGINALAIRDATE=2018-01-03-
      MDB_PROGRAMID=EP019062760472-
       MDB_CHANID=1031-
      MDB_STORAGEGROUP=Default-
      MDB_CATEGORY=Talk-
      ProgramIDType=Series With Episode Data-
       RATING=0-
      Zap2itSeriesID=1906276-
       MDB_SERIESID=EP01906276-
      Plot=Actor David Harbour Julien Baker performs.-
[2018-01-06T19:21:58] INFO: ################ END OF OPERATION SUMMARY ################
[2018-01-06T19:21:58] DEBUG: ENTERING: naming_checks() ; CALLED FROM: main()
[2018-01-06T19:21:58] WARNING: Episode could not be matched with supplied information.
[2018-01-06T19:21:58] DEBUG: Exited main() function with an error state.
[2018-01-06T19:21:58] DEBUG: ENTERING: exit_job() ; CALLED FROM: main()
       Called with 1 arguments
       arg 1 = 'NameCouldNotBeAssigned'
[2018-01-06T19:21:59] WARNING: NAME COULD NOT BE ASSIGNED BASED UPON DATA SUPPLIED
[2018-01-06T19:21:59] DEBUG: Either: Notify=Disabled; scan mode; recording is in an ignore list; or NOTIFY_MSG was not set.
@@@@ COMPLETE: 2018-01-06 19:21:59-06:00 @@@@
[2018-01-06T19:21:59] DEBUG: Checking for user job: '/home/mythtv/.myth2kodi/userjobs/NameCouldNotBeAssigned'.
[2018-01-06T19:21:59] DEBUG: No user job: '/home/mythtv/.myth2kodi/userjobs/NameCouldNotBeAssigned'.
[2018-01-06T19:21:59] DEBUG: Checking for user job: '/home/mythtv/.myth2kodi/userjobs/InsufficientInformation'.
[2018-01-06T19:21:59] DEBUG: No user job: '/home/mythtv/.myth2kodi/userjobs/InsufficientInformation'.
[2018-01-06T19:21:59] DEBUG: Checking for user job: '/home/mythtv/.myth2kodi/userjobs/FailedProcessing'.
[2018-01-06T19:21:59] DEBUG: No user job: '/home/mythtv/.myth2kodi/userjobs/FailedProcessing'.
[2018-01-06T19:21:59] DEBUG: Checking for user job: '/home/mythtv/.myth2kodi/userjobs/Failed'.
[2018-01-06T19:21:59] DEBUG: No user job: '/home/mythtv/.myth2kodi/userjobs/Failed'.
[2018-01-06T19:21:59] DEBUG: Checking for user job: '/home/mythtv/.myth2kodi/userjobs/ExitJob'.
[2018-01-06T19:21:59] DEBUG: No user job: '/home/mythtv/.myth2kodi/userjobs/ExitJob'.
[2018-01-06T19:21:59] DEBUG: There are no userjobs defined for this exit type.
szczys commented 6 years ago

One more thought. As I'm trying to troubleshoot this I'm getting a lot of 503 Server Unavaialble errors when manually searching thetvdb.com. Do you think this is causing problems at all?

Here's another log that I find peculiar. I would think this should be an easy match based on original airdate:

[2018-01-06T19:30:00] DEBUG: LOGFILE=/home/mythtv/.myth2kodi/myth2kodi.log-
[2018-01-06T19:30:00] DEBUG: LOGLEVEL=3-
[2018-01-06T19:30:00] DEBUG: LOGTYPE=filestderr-
[2018-01-06T19:30:00] DEBUG: ENTERING: validate_settings() ; CALLED FROM: m2k_init()
[2018-01-06T19:30:00] DEBUG: ENTERING: prune_series_list() ; CALLED FROM: m2k_init()
[2018-01-06T19:30:00] DEBUG: ENTERING: get_tvdb_token() ; CALLED FROM: m2k_init()
[2018-01-06T19:30:00] DEBUG: Token last updated: 'Sat Jan  6 15:39:25 CST 2018'
[2018-01-06T19:30:00] DEBUG: Using existing token.
[2018-01-06T19:30:00] DEBUG: myth2kodi script initialisation complete.
[2018-01-06T19:30:00] DEBUG: Running myth2kodi as 'mythtv'
[2018-01-06T19:30:00] DEBUG: ENTERING: validate_args() ; CALLED FROM: main()
[2018-01-06T19:30:00] DEBUG: Called with 1 arguments. Called as:
       myth2kodi '/Lenny/mythtv/1151_20180106003000.ts' "" "" "" ''
[2018-01-06T19:30:00] DEBUG: ENTERING: check_path_permission() ; CALLED FROM: validate_args()
       path_to_check = '/Lenny/mythtv/1151_20180106003000.ts'
       required_permission = 'rw'
[2018-01-06T19:30:00] DEBUG: Checking 'rw' permissions for: '/Lenny/mythtv/1151_20180106003000.ts'
[2018-01-06T19:30:00] DEBUG: Good start: 'mythtv' owns it.
[2018-01-06T19:30:00] DEBUG: Permissions ok for: '/Lenny/mythtv/1151_20180106003000.ts'
[2018-01-06T19:30:00] DEBUG: ENTERING: pre_process_check() ; CALLED FROM: validate_args()
       arg1: '/Lenny/mythtv/1151_20180106003000.ts'
[2018-01-06T19:30:00] DEBUG: Looks ok, file_path is named like a MythTV recording: '/Lenny/mythtv/1151_20180106003000.ts'
[2018-01-06T19:30:00] DEBUG: Arguments seem valid.
[2018-01-06T19:30:00] DEBUG: No command flag set. Processing as recording.
[2018-01-06T19:30:00] DEBUG: ENTERING: periodic_maintenace() ; CALLED FROM: main()
[2018-01-06T19:30:00] DEBUG: ENTERING: set_recording_name() ; CALLED FROM: main()
       arg1: '/Lenny/mythtv/1151_20180106003000.ts'
[2018-01-06T19:30:00] DEBUG: Setting RECORDING_PATH='/Lenny/mythtv/1151_20180106003000.ts'
[2018-01-06T19:30:00] DEBUG: Setting RECORDING_NAME='1151_20180106003000.ts'
@@@@ PROCESSING RECORDING: 2018-01-06 19:30:00-06:00 @@@@
myth2kodi Version 1.5.1 on Linux
[2018-01-06T19:30:00] INFO: Processing: /Lenny/mythtv/1151_20180106003000.ts
[2018-01-06T19:30:00] DEBUG: ENTERING: process_mythtvdb() ; CALLED FROM: main()
[2018-01-06T19:30:00] DEBUG: ENTERING: get_mythtvdb_info() ; CALLED FROM: process_mythtvdb()
[2018-01-06T19:30:00] DEBUG: ENTERING: get_mythtvdb_info_pythonbindings() ; CALLED FROM: get_mythtvdb_info()
       m2kdir: '/home/mythtv/.myth2kodi'
       binpath: '/usr/local/bin'
       INPUT_PATH_BASENAME: '1151_20180106003000.ts'
       DBHostName: 'localhost'
       MySQLMythDb: 'mythconverg'
       MySQLuser: 'mythtv'
[2018-01-06T19:30:00] INFO: Accessing MythTV DataBase Using PythonBindings.
[2018-01-06T19:30:00] DEBUG: Storing MythTV-DB info in temporary file: '/tmp/m2k_recinfo_2018-01-06T193000-eFMw'
mythdb_access: DEBUG:__main__:Establishing database connection
mythdb_access: WARNING:__main__:No comskip information found.
mythdb_access: DEBUG:__main__:Attempting to write data to file: /tmp/m2k_recinfo_2018-01-06T193000-eFMw
mythdb_access: DEBUG:__main__:Calling write_data()
mythdb_access: DEBUG:__main__:Operation complete.
mythdb_access: DEBUG:__main__:Closing out the file/stdout
[2018-01-06T19:30:00] DEBUG: Guide data as extracted by: get_mythtvdb_info_pythonbindings()
       MDB_CHANID='1151'
       MDB_STARTTIME='2018-01-05 18:30:00-06:00'
       MDB_TITLE='Wheel of Fortune'
       MDB_SUBTITLE='America's Game'
       MDB_DESCRIPTION=''
       MDB_SEASON='0'
       MDB_EPISODE='0'
       MDB_CATEGORY='Game show'
       MDB_SERIESID='EP00004762'
       MDB_PROGRAMID='EP000047623257'
       MDB_INETREF=''
       MDB_STARS='0.0'
       MDB_ORIGINALAIRDATE='2018-01-05'
       MDB_STORAGEGROUP='Default'
       MDB_XMLTVGRABBER='schedulesdirect1'
[2018-01-06T19:30:00] DEBUG: Created a temporary comskip markupstart file: '/tmp/m2k_markupstart_2018-01-06T193000-arFD'
[2018-01-06T19:30:00] DEBUG: Created a temporary comskip markupstop file: '/tmp/m2k_markupstop_2018-01-06T193000-e9qV'
[2018-01-06T19:30:00] DEBUG: ENTERING: generate_comskip() ; CALLED FROM: get_mythtvdb_info_pythonbindings()
[2018-01-06T19:30:00] DEBUG: Created a temporary comskip markupframes file: '/tmp/m2k_markupframes_2018-01-06T193000-57MP'
[2018-01-06T19:30:00] DEBUG: Not removing the temporary markup files: '/tmp/m2k_markupstart_2018-01-06T193000-arFD'; '/tmp/m2k_markupstop_2018-01-06T193000-e9qV'.
[2018-01-06T19:30:00] DEBUG: Not removing the temporary MythTV-DB info file: '/tmp/m2k_recinfo_2018-01-06T193000-eFMw'
[2018-01-06T19:30:00] DEBUG: ENTERING: process_mythtvdb_schedulesdirect() ; CALLED FROM: process_mythtvdb()
[2018-01-06T19:30:00] INFO: Processing MythTV database info as SchedulesDirect.
[2018-01-06T19:30:00] DEBUG: ENTERING: process_mythtvdb_common() ; CALLED FROM: process_mythtvdb_schedulesdirect()
[2018-01-06T19:30:00] DEBUG: ENTERING: set_ProgramIDType() ; CALLED FROM: process_mythtvdb_schedulesdirect()
       RECORDING_TYPE='EP'
[2018-01-06T19:30:00] DEBUG: Input and MythTV-DB guide data for 1151_20180106003000.ts
       RECSTART:'2018-01-05 18:30:00-06:00'
       DATE:'' '2018-01-05'
       PROGRAMID:'EP000047623257'
       CATEGORY:'Game show'
       TITLE:'Wheel of Fortune'
       SUBTITLE:'America's Game'
       SEASON:'UNKNOWN'
       EPISODE:'UNKNOWN'
       Plot: ""
[2018-01-06T19:30:00] DEBUG: ENTERING: show_translation() ; CALLED FROM: main()
[2018-01-06T19:30:00] DEBUG: Checking for translations in: '/home/mythtv/.myth2kodi/showTranslations'.
[2018-01-06T19:30:00] DEBUG: No showTranslations file.
[2018-01-06T19:30:00] DEBUG: ENTERING: process_ignore_lists() ; CALLED FROM: main()
[2018-01-06T19:30:00] DEBUG: ENTERING: identify_series() ; CALLED FROM: main()
       RECORDING_TYPE='EP'
       ONLINE_LOOKUP='Enabled'
       TITLE='Wheel of Fortune'
       Zap2itSeriesID='4762'
[2018-01-06T19:30:00] DEBUG: ENTERING: have_series_info() ; CALLED FROM: identify_series()
       TITLE='Wheel of Fortune'
[2018-01-06T19:30:00] INFO: We have existing info for: 'Wheel of Fortune'
[2018-01-06T19:30:00] DEBUG: ENTERING: get_series_info() ; CALLED FROM: main()
       TVDB_SERIESID='76573'
       TVDB_TITLE='Wheel of Fortune'
[2018-01-06T19:30:00] DEBUG: ENTERING: make_m2k_database_dir() ; CALLED FROM: get_series_info()
[2018-01-06T19:30:00] DEBUG: ENTERING: check_m2k_database_up_to_date() ; CALLED FROM: get_series_info()
[2018-01-06T19:30:00] DEBUG: The series has status='Continuing'.
[2018-01-06T19:30:00] DEBUG: Database maintained. Current: 1515288600. Next update: 1515379429.
[2018-01-06T19:30:00] DEBUG: ENTERING: identify_episode() ; CALLED FROM: main()
       RECORDING_TYPE='EP'
       TVDB_TITLE='Wheel of Fortune'
       SUBTITLE='Americas Game'
       SEASON=''
       EPISODE=''
       Plot=''
[2018-01-06T19:30:00] DEBUG: ENTERING: set_absolute_episode_number_by_ename() ; CALLED FROM: identify_episode()
       TVDB_TITLE = 'Wheel of Fortune'
       SUBTITLE = 'Americas Game'
[2018-01-06T19:30:01] DEBUG: ENTERING: set_absolute_episode_number_by_ename_fuzzy() ; CALLED FROM: identify_episode()
       TVDB_TITLE = 'Wheel of Fortune'
       SUBTITLE = 'Americas Game'
       AbsoluteEpisodeNumber guesses: 274,278,282,286,526,274,278,282,286,526.
       Cost: 0,0,0,0,0,0,0,0,0,0.
[2018-01-06T19:30:01] DEBUG: ENTERING: multi_match_check() ; CALLED FROM: set_absolute_episode_number_by_ename_fuzzy()
       TVDB_TITLE: 'Wheel of Fortune'
       There are 10 AENguesses: 274,278,282,286,526,274,278,282,286,526.
[2018-01-06T19:30:01] DEBUG: Multiple AbsoluteEpisodeNumber guesses matched plot, confirming they're the same episode before using.
[2018-01-06T19:30:01] DEBUG: There are '10' matching episodes: s27e82,s27e83,s27e84,s27e85,s31e186,s27e82,s27e83,s27e84,s27e85,s31e186.
[2018-01-06T19:30:01] DEBUG: most_common_match='s27e82'
[2018-01-06T19:30:01] DEBUG: most_common_match_count='2'
[2018-01-06T19:30:01] DEBUG: The AbsoluteEpisodeNumber guesses were different:
       AENguess = SxxExxGuesses
       0: 274 = 's27e82'
       1: 278 = 's27e83'
       2: 282 = 's27e84'
       3: 286 = 's27e85'
       4: 526 = 's31e186'
       5: 274 = 's27e82'
       6: 278 = 's27e83'
       7: 282 = 's27e84'
       8: 286 = 's27e85'
       9: 526 = 's31e186'
[2018-01-06T19:30:01] DEBUG: ENTERING: set_absolute_episode_number_by_airdate() ; CALLED FROM: identify_episode()
[2018-01-06T19:30:01] WARNING: Failed to identify episode.
[2018-01-06T19:30:01] WARNING: Low episode-confidence-rating (-6), requiring doover.
[2018-01-06T19:30:01] DEBUG: ENTERING: set_sxxexx_with_season_episode() ; CALLED FROM: main()
       SEASON=''
       EPISODE=''
       SeasonEpisode=''
[2018-01-06T19:30:01] DEBUG: No Season & Episode number information is available.
[2018-01-06T19:30:01] DEBUG: ENTERING: storagegroup_fallback() ; CALLED FROM: main()
[2018-01-06T19:30:01] DEBUG: ENTERING: check_filesystem() ; CALLED FROM: main()
[2018-01-06T19:30:01] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '494'
       arg 2 = '366083'
       arg 3 = '/Lenny/videoLibrary/TV'
[2018-01-06T19:30:01] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '494'
       arg 2 = '366083'
       arg 3 = '/Lenny/videoLibrary/TV'
[2018-01-06T19:30:01] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '494'
       arg 2 = '366083'
       arg 3 = '/Lenny/videoLibrary/Movies'
[2018-01-06T19:30:01] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '494'
       arg 2 = '366083'
       arg 3 = '/Lenny/videoLibrary/Movies'
[2018-01-06T19:30:01] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '494'
       arg 2 = '366083'
       arg 3 = '/Lenny/videoLibrary/Showings'
[2018-01-06T19:30:01] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '494'
       arg 2 = '366083'
       arg 3 = '/Lenny/videoLibrary/Showings'
[2018-01-06T19:30:01] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '1'
       arg 2 = '366083'
       arg 3 = '/Lenny/mythtv'
[2018-01-06T19:30:01] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '5'
       arg 2 = '800014'
       arg 3 = '/home/mythtv/.myth2kodi'
[2018-01-06T19:30:01] DEBUG: ENTERING: check_write() ; CALLED FROM: check_filesystem()
       Called with 3 arguments
       arg 1 = '5'
       arg 2 = '13504'
       arg 3 = '/tmp'
[2018-01-06T19:30:01] DEBUG: filesystem_write_state='0'
[2018-01-06T19:30:01] DEBUG: ENTERING: msg_operation_summary() ; CALLED FROM: main()
[2018-01-06T19:30:01] INFO: ################ OPERATION SUMMARY ################
      JOB: myth2kodi '/Lenny/mythtv/1151_20180106003000.ts' "" "" "" ''
       m2kVersion=myth2kodi Version 1.5.1-
      CALLER=mythtv-
       m2kdir=/home/mythtv/.myth2kodi-
       Timeout=50-
      PROCESS_RECORDING_MODE=MOVE-
      SYMLINK=Disabled-
       TargetPathIsInputPath=Disabled-
       FailSafeMode=Disabled-
       FailSafeDir=/home/mythtv/FailSafe-
       Notify=Disabled-
[2018-01-06T19:30:01] DEBUG: ENTERING: msg_dir_summary() ; CALLED FROM: msg_operation_summary()
      A summary table of writeable directories: 0='WRITEABLE' ; 1='NOT WRITEABLE'
      USER SETTING      |FLAG |Free Space  |Folder name
      m2kdir            |  0  |  800014 MB | /home/mythtv/.myth2kodi
      M2K_TMPDIR        |  0  |   13504 MB | /tmp
      ORIGINAL_DIRNAME  |  0  |  366083 MB | /Lenny/mythtv
      MoveDir           |  0  |  366083 MB | /Lenny/videoLibrary/TV
      AlternateMoveDir  |  0  |  366083 MB | /Lenny/videoLibrary/TV
      PrimaryMovieDir   |  0  |  366083 MB | /Lenny/videoLibrary/Movies
      AlternateMovieDir |  0  |  366083 MB | /Lenny/videoLibrary/Movies
      PrimaryShowDir    |  0  |  366083 MB | /Lenny/videoLibrary/Showings
      AlternateShowDir  |  0  |  366083 MB | /Lenny/videoLibrary/Showings
      RECORDING_PATH=/Lenny/mythtv/1151_20180106003000.ts-
      TVDB_SERIESID=76573-
      TVMAZE_SERIESID=2199-
      TITLE=Wheel of Fortune-
       INPUT_TITLE=-
       MDB_TITLE=Wheel of Fortune-
       TVDB_TITLE=Wheel of Fortune-
      SUBTITLE=Americas Game-
       INPUT_SUBTITLE=-
       MDB_SUBTITLE=America's Game-
       TVDB_SUBTITLE=-
      SEASON=-
       INPUT_SEASON=-
       MDB_SEASON=0-
       TVDB_SEASON=-
      EPISODE=-
       INPUT_EPISODE=-
       MDB_EPISODE=0-
       TVDB_EPISODE=-
       Sxx=- Exx=-
       LastUpdatedTVDB=1515114509-
       Current Time=1515288601-
       AbsoluteEpisodeNumber=288-
       HasSxxExx=-
      MoveFileSize=494 MB
      ConfidenceRating=-5-
      ConfidenceReasoning= Have existing series info; Attempted SUBTITLE exact match: Failed; Attempted SUBTITLE fuzzy match: Failed; Attempted match on ORIGINALAIRDATE: Failed.-
       MDB_XMLTVGRABBER=schedulesdirect1-
      MDB_STARTTIME=2018-01-05 18:30:00-06:00-
       MDB_AIRDATE=-
       ORIGINALAIRDATE=2018-01-05-
      MDB_PROGRAMID=EP000047623257-
       MDB_CHANID=1151-
      MDB_STORAGEGROUP=Default-
      MDB_CATEGORY=Game show-
      ProgramIDType=Series With Episode Data-
       RATING=0-
      Zap2itSeriesID=4762-
       MDB_SERIESID=EP00004762-
      Plot=-
      Matched Plot=-
[2018-01-06T19:30:01] INFO: ################ END OF OPERATION SUMMARY ################
[2018-01-06T19:30:01] DEBUG: ENTERING: naming_checks() ; CALLED FROM: main()
[2018-01-06T19:30:01] WARNING: Episode could not be matched with supplied information.
[2018-01-06T19:30:01] DEBUG: Exited main() function with an error state.
[2018-01-06T19:30:01] DEBUG: ENTERING: exit_job() ; CALLED FROM: main()
       Called with 1 arguments
       arg 1 = 'NameCouldNotBeAssigned'
[2018-01-06T19:30:01] WARNING: NAME COULD NOT BE ASSIGNED BASED UPON DATA SUPPLIED
[2018-01-06T19:30:01] DEBUG: Either: Notify=Disabled; scan mode; recording is in an ignore list; or NOTIFY_MSG was not set.
@@@@ COMPLETE: 2018-01-06 19:30:01-06:00 @@@@
[2018-01-06T19:30:01] DEBUG: Checking for user job: '/home/mythtv/.myth2kodi/userjobs/NameCouldNotBeAssigned'.
[2018-01-06T19:30:01] DEBUG: No user job: '/home/mythtv/.myth2kodi/userjobs/NameCouldNotBeAssigned'.
[2018-01-06T19:30:01] DEBUG: Checking for user job: '/home/mythtv/.myth2kodi/userjobs/InsufficientInformation'.
[2018-01-06T19:30:01] DEBUG: No user job: '/home/mythtv/.myth2kodi/userjobs/InsufficientInformation'.
[2018-01-06T19:30:01] DEBUG: Checking for user job: '/home/mythtv/.myth2kodi/userjobs/FailedProcessing'.
[2018-01-06T19:30:01] DEBUG: No user job: '/home/mythtv/.myth2kodi/userjobs/FailedProcessing'.
[2018-01-06T19:30:01] DEBUG: Checking for user job: '/home/mythtv/.myth2kodi/userjobs/Failed'.
[2018-01-06T19:30:01] DEBUG: No user job: '/home/mythtv/.myth2kodi/userjobs/Failed'.
[2018-01-06T19:30:01] DEBUG: Checking for user job: '/home/mythtv/.myth2kodi/userjobs/ExitJob'.
[2018-01-06T19:30:01] DEBUG: No user job: '/home/mythtv/.myth2kodi/userjobs/ExitJob'.
[2018-01-06T19:30:01] DEBUG: There are no userjobs defined for this exit type.