Transcoder not doing anything

Describe the bug Transcoder isn't triggered under any circumstances

Technical Specs

Running on (Windows, Linux, NAS Model etc) 'FreeNAS 11.2'
Python version '2.7'
Download Client (NZBget, SABnbzd, Transmission) 'Transmission'
Intended Media Management (SickChill, CouchPotoato, Radarr, Sonarr) 'CouchPotato'

Expected behavior Having used nzbToMedia for a number of years and having a drive that is getting fuller by the day, I decided I would use the transcoder options in nzbToMedia to reencode all downloads in HEVC before they were passed to CouchPotato to be renamed and stored in the appropriate place. I've looked at the wiki and at some issue threads (also Googled about but not much to see), however when I set off something to download through CP, the torrent completes, the script triggers CP and the movie is renamed, moved and Plex notified but the transcoder does not kick in. Looking at the nzbToMedia logs, there is no reference to the transcoder even attempting to run, just all of the environment details, etc.

I have the latest ffmpeg, etc. and hwaccel point of view, everything there is working as I can use ffmpeg manually.

I've also attached my autoProcessMedia.cfg for reference.

autoProcessMedia.cfg.txt

Log Please provide an extract, or full debug log that indicates the issue. nzbtomedia.log

Ok, the log certainly looks like it stopped early (very early) so either the log wasn't complete, or the script itself failed at trying to load the config.

Can you check a manual run of the script to be sure that it actually runs to completion? or otherwise check if there is any additional logging or traceback?

I tried running the script manually without any variables and got a message about being unable to strip the config setting 'VideoCodecAllow'. I had these in a comma separated list so added double quotes to make this a string instead of a list and got a completed successfully message from the script. I then downloaded something but that failed with result 1. If I run the FFmpeg command from the logs manually, it runs without a hitch:

2019-05-27 10:19:24 INFO    ::MAIN: nzbToMedia Version:0db7c3e10c01cee85814a31fd3c5232b1d7d144e Branch:master (FreeBSD 11.2-STABLE)
2019-05-27 10:19:24 DEBUG   ::MAIN: Connecting to transmission: http://localhost:9091
2019-05-27 10:19:24 INFO    ::MAIN: #########################################################
2019-05-27 10:19:24 INFO    ::MAIN: ## ..::[TorrentToMedia.py]::.. ##
2019-05-27 10:19:24 INFO    ::MAIN: #########################################################
2019-05-27 10:19:24 DEBUG   ::MAIN: Options passed into TorrentToMedia: ['/mnt/Share/Downloads/nzbToMedia/TorrentToMedia.py']
2019-05-27 10:19:24 DEBUG   ::MAIN: Adding TORRENT download info for directory /mnt/Share/Downloads/Torrent/Complete/movie to database
2019-05-27 10:19:24 DB      ::MAIN: nzbtomedia.db: UPDATE downloads SET status = ?, client_agent = ?, last_update = ?, input_id = ?, input_hash = ?, input_name = ? WHERE input_directory = ? with args [0, u'transmission', 737206, u'14', u'95102dc9480d8fd974de044612150cc3b22a4f65', u'The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT', u'/mnt/Share/Downloads/Torrent/Complete/movie']
2019-05-27 10:19:24 DB      ::MAIN: nzbtomedia.db: INSERT OR IGNORE INTO downloads (status, client_agent, last_update, input_id, input_hash, input_name) VALUES (?, ?, ?, ?, ?, ?) with args [0, u'transmission', 737206, u'14', u'95102dc9480d8fd974de044612150cc3b22a4f65', u'The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT']
2019-05-27 10:19:25 DEBUG   ::MAIN: Received Directory: /mnt/Share/Downloads/Torrent/Complete/movie | Name: The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT | Category: 
2019-05-27 10:19:25 DEBUG   ::MAIN: SEARCH: Found Category: movie in directory structure
2019-05-27 10:19:25 INFO    ::MAIN: SEARCH: Found torrent directory The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT in input directory directory /mnt/Share/Downloads/Torrent/Complete/movie
2019-05-27 10:19:25 INFO    ::MAIN: SEARCH: Setting input_directory to /mnt/Share/Downloads/Torrent/Complete/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT
2019-05-27 10:19:25 DEBUG   ::MAIN: Determined Directory: /mnt/Share/Downloads/Torrent/Complete/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT | Name: The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT | Category: movie
2019-05-27 10:19:25 INFO    ::MAIN: Auto-detected SECTION:CouchPotato
2019-05-27 10:19:25 DEBUG   ::MAIN: Stopping torrent The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT in transmission while processing
2019-05-27 10:19:30 INFO    ::MAIN: Output directory set to: /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT
2019-05-27 10:19:30 DEBUG   ::MAIN: Scanning files in directory: /mnt/Share/Downloads/Torrent/Complete/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT
2019-05-27 10:19:30 DEBUG   ::MAIN: Found 3 files in /mnt/Share/Downloads/Torrent/Complete/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT
2019-05-27 10:19:30 INFO    ::COPYLINK: MEDIAFILE: [English.srt]
2019-05-27 10:19:30 INFO    ::COPYLINK: SOURCE FOLDER: [/mnt/Share/Downloads/Torrent/Complete/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT]
2019-05-27 10:19:30 INFO    ::COPYLINK: TARGET FOLDER: [/mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT]
2019-05-27 10:19:30 INFO    ::COPYLINK: Hard linking SOURCE MEDIAFILE -> TARGET FOLDER
2019-05-27 10:19:30 INFO    ::COPYLINK: MEDIAFILE: [The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT.nfo]
2019-05-27 10:19:30 INFO    ::COPYLINK: SOURCE FOLDER: [/mnt/Share/Downloads/Torrent/Complete/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT]
2019-05-27 10:19:30 INFO    ::COPYLINK: TARGET FOLDER: [/mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT]
2019-05-27 10:19:30 INFO    ::COPYLINK: Hard linking SOURCE MEDIAFILE -> TARGET FOLDER
2019-05-27 10:19:30 INFO    ::COPYLINK: MEDIAFILE: [The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT.mkv]
2019-05-27 10:19:30 INFO    ::COPYLINK: SOURCE FOLDER: [/mnt/Share/Downloads/Torrent/Complete/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT]
2019-05-27 10:19:30 INFO    ::COPYLINK: TARGET FOLDER: [/mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT]
2019-05-27 10:19:30 INFO    ::COPYLINK: Hard linking SOURCE MEDIAFILE -> TARGET FOLDER
2019-05-27 10:19:30 DEBUG   ::MAIN: Checking for archives to extract in directory: /mnt/Share/Downloads/Torrent/Complete/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT
2019-05-27 10:19:30 INFO    ::MAIN: FLATTEN: Flattening directory: /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT
2019-05-27 10:19:30 DEBUG   ::MAIN: Checking for empty folders in:/mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT
2019-05-27 10:19:30 INFO    ::MAIN: Found 1 media files in /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT
2019-05-27 10:19:30 INFO    ::MAIN: Calling CouchPotato:movie to post-process:The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT
2019-05-27 10:19:30 INFO    ::MAIN: Attemping imdbID lookup for The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT
2019-05-27 10:19:30 INFO    ::MAIN: Searching folder and file names for imdbID ...
2019-05-27 10:19:30 INFO    ::MAIN: Searching IMDB for imdbID ...
2019-05-27 10:19:30 INFO    ::MAIN: Unable to determine imdbID: No api key provided for ombdapi.com.
2019-05-27 10:19:30 DEBUG   ::SERVER: Attempting to connect to server at http://10.0.0.4:5050/couchpotato/api/cd0c2cbf514a425a80d092c86c3967c3/
2019-05-27 10:19:30 DEBUG   ::SERVER: Server responded at http://10.0.0.4:5050/couchpotato/api/cd0c2cbf514a425a80d092c86c3967c3/
2019-05-27 10:19:30 DEBUG   ::MAIN: Opening URL: http://10.0.0.4:5050/couchpotato/api/cd0c2cbf514a425a80d092c86c3967c3/media.list with PARAMS: {}
2019-05-27 10:19:30 ERROR   ::MAIN: CouchPotato returned the following non-json data
2019-05-27 10:19:30 ERROR   ::MAIN: 
2019-05-27 10:19:30 ERROR   ::MAIN: <!doctype html>
2019-05-27 10:19:30 ERROR   ::MAIN: <html class="">
2019-05-27 10:19:30 ERROR   ::MAIN: <head>
2019-05-27 10:19:30 ERROR   ::MAIN: <title>CouchPotato</title>
2019-05-27 10:19:30 ERROR   ::MAIN: <meta name="viewport" content="width=device-width, initial-scale=1.0, maximum-scale=1.0, user-scalable=no"/>
2019-05-27 10:19:30 ERROR   ::MAIN: <meta name="apple-mobile-web-app-capable" content="yes" />
2019-05-27 10:19:30 ERROR   ::MAIN: <meta name="mobile-web-app-capable" content="yes" />
2019-05-27 10:19:30 ERROR   ::MAIN: <meta http-equiv="X-UA-Compatible" content="IE=edge" />
2019-05-27 10:19:30 ERROR   ::MAIN: <meta name="msapplication-tap-highlight" content="no" />
2019-05-27 10:19:30 ERROR   ::MAIN: 
2019-05-27 10:19:30 ERROR   ::MAIN: 
2019-05-27 10:19:30 ERROR   ::MAIN: <!-- IOS -->
2019-05-27 10:19:30 ERROR   ::MAIN: <link rel="apple-touch-icon-precomposed" href="/static/images/icons/ios.png" />
2019-05-27 10:19:30 ERROR   ::MAIN: <!-- Safari Pinned Tab Icon -->
2019-05-27 10:19:30 ERROR   ::MAIN: <link rel="mask-icon" href="/static/images/icons/safari.svg" color="#AC0000">
2019-05-27 10:19:30 ERROR   ::MAIN: <!-- Android -->
2019-05-27 10:19:30 ERROR   ::MAIN: <link rel="icon" type="image/png" href="/static/images/icons/android.png" sizes="192x192">
2019-05-27 10:19:30 ERROR   ::MAIN: <!-- Windows 8 -->
2019-05-27 10:19:30 ERROR   ::MAIN: <meta name="msapplication-TileColor" content="#ac0000">
2019-05-27 10:19:30 ERROR   ::MAIN: <meta name="msapplication-TileImage" content="/static/images/icons/windows.png">
2019-05-27 10:19:30 ERROR   ::MAIN: <meta name="msapplication-square70x70logo" content="/static/images/icons/windows.png">
2019-05-27 10:19:30 ERROR   ::MAIN: <meta name="msapplication-square150x150logo" content="/static/images/icons/windows.png">
2019-05-27 10:19:30 ERROR   ::MAIN: <meta name="msapplication-square310x310logo" content="/static/images/icons/windows.png">
2019-05-27 10:19:30 ERROR   ::MAIN: <link href="/static/images/icons/favicon.ico" rel="icon" type="image/x-icon" />
2019-05-27 10:19:30 ERROR   ::MAIN: 
2019-05-27 10:19:30 ERROR   ::MAIN: <script type="text/javascript" src="/static/scripts/combined.vendor.min.js?1558646905"></script>
2019-05-27 10:19:30 ERROR   ::MAIN: <script type="text/javascript" src="/static/scripts/combined.base.min.js?1558646905"></script>
2019-05-27 10:19:30 ERROR   ::MAIN: <script type="text/javascript" src="/static/scripts/combined.plugins.min.js?1558646905"></script>
2019-05-27 10:19:30 ERROR   ::MAIN: 
2019-05-27 10:19:30 ERROR   ::MAIN: <script type="text/javascript">
2019-05-27 10:19:30 ERROR   ::MAIN: window.addEvent('domready', function() {
2019-05-27 10:19:30 ERROR   ::MAIN: new Uniform();
2019-05-27 10:19:30 ERROR   ::MAIN: Api.setup({
2019-05-27 10:19:30 ERROR   ::MAIN: 'url': "/api/cd0c2cbf514a425a80d092c86c3967c3/",
2019-05-27 10:19:30 ERROR   ::MAIN: 'path_sep': "/",
2019-05-27 10:19:30 ERROR   ::MAIN: 'is_remote': false
2019-05-27 10:19:30 ERROR   ::MAIN: });
2019-05-27 10:19:30 ERROR   ::MAIN: $(document.body).set('data-api', window.location.protocol + '//' + window.location.host + Api.createUrl().replace('/default/', '/'));
2019-05-27 10:19:30 ERROR   ::MAIN: // Catch errors
2019-05-27 10:19:30 ERROR   ::MAIN: var dev = false;
2019-05-27 10:19:30 ERROR   ::MAIN: if(!dev){
2019-05-27 10:19:30 ERROR   ::MAIN: window.onerror = function(message, file, line){
2019-05-27 10:19:30 ERROR   ::MAIN: p(message, file, line);
2019-05-27 10:19:30 ERROR   ::MAIN: Api.request('logging.log', {
2019-05-27 10:19:30 ERROR   ::MAIN: 'data': {
2019-05-27 10:19:30 ERROR   ::MAIN: 'type': 'error',
2019-05-27 10:19:30 ERROR   ::MAIN: 'message': Browser.name + ' ' + Browser.version + ': \n' + message,
2019-05-27 10:19:30 ERROR   ::MAIN: 'page': window.location.href.replace(window.location.host, 'HOST'),
2019-05-27 10:19:30 ERROR   ::MAIN: 'file': file.replace(window.location.host, 'HOST'),
2019-05-27 10:19:30 ERROR   ::MAIN: 'line': line
2019-05-27 10:19:30 ERROR   ::MAIN: }
2019-05-27 10:19:30 ERROR   ::MAIN: });
2019-05-27 10:19:30 ERROR   ::MAIN: return true;
2019-05-27 10:19:30 ERROR   ::MAIN: }
2019-05-27 10:19:30 ERROR   ::MAIN: }
2019-05-27 10:19:30 ERROR   ::MAIN: Quality.setup({
2019-05-27 10:19:30 ERROR   ::MAIN: 'profiles': [{"core": false, "_rev": "0006707c", "finish": [true, true, true], "hide": false, "qualities": ["2160p", "1080p", "720p"], "_id": "a248593cfabb41a59d27fc9939ae2b4d", "_t": "profile", "label": "UHD 4K", "minimum_score": 1, "stop_after": [60, 60, 60], "wait_for": [0, 0, 0], "order": 0, "3d": [0, 0, 0]}, {"core": false, "_rev": "0006751d", "finish": [true, true, true, true], "hide": false, "qualities": ["1080p", "720p", "brrip", "dvdrip"], "_id": "708ba4686b584f38beab631560a32e43", "_t": "profile", "label": "Best", "minimum_score": 1, "stop_after": [60, 60, 60, 60], "wait_for": [0, 0, 0, 0], "order": 1, "3d": [0, 0, 0, 0]}, {"core": false, "_rev": "00045893", "finish": [true, true], "hide": false, "qualities": ["1080p", "720p"], "_id": "9884ccdd30fd498f9e130e2a9ba9be44", "_t": "profile", "label": "HD", "minimum_score": 1, "stop_after": [0, 0], "wait_for": [0, 0], "order": 2, "3d": [0, 0]}, {"core": true, "_rev": "0003e810", "finish": [true], "hide": false, "_id": "3d0c957dad364bd6a077f215df8bd6a3", "_t": "profile", "label": "1080p", "qualities": ["1080p"], "wait_for": [0], "order": 3}, {"core": false, "_rev": "0005a76c", "finish": [true, true], "hide": false, "qualities": ["dvdrip", "dvdr"], "_id": "2229704819804136b867c2a6ab8452d8", "_t": "profile", "label": "SD", "minimum_score": 1, "stop_after": [60, 60], "wait_for": [0, 0], "order": 4, "3d": [0, 0]}, {"core": false, "_rev": "000621be", "finish": [true, true, true, true], "hide": false, "qualities": ["1080p", "720p", "1080p", "720p"], "_id": "52ae17acc02d4e4da783ef89b9ee9f29", "_t": "profile", "label": "Prefer 3D HD", "minimum_score": 1, "stop_after": [60, 60, 60, 60], "wait_for": [0, 0, 0, 0], "order": 5, "3d": [1, 1, 0, 0]}, {"core": false, "_rev": "00054f47", "finish": [true, true], "hide": false, "qualities": ["1080p", "720p"], "_id": "231d9f60a9144594b1f8f8e3024a64e0", "_t": "profile", "label": "3D HD", "minimum_score": 1, "stop_after": [60, 60], "wait_for": [0, 0], "order": 6, "3d": [1, 1]}, {"core": true, "_rev": "0003ed0a", "finish": [true], "hide": false, "_id": "e7278e1c1384491ea8285017cf9fcfc5", "_t": "profile", "label": "2160p", "qualities": ["2160p"], "wait_for": [0], "order": 7}, {"core": true, "_rev": "00031691", "finish": [true], "hide": false, "_id": "d14325c415134dcd8913e1ac0a423264", "_t": "profile", "label": "BR-Disk", "qualities": ["bd50"], "wait_for": [0], "order": 8}, {"core": true, "_rev": "0003ed57", "finish": [true], "hide": false, "_id": "87f137d3be9548edbe85e7404029b855", "_t": "profile", "label": "720p", "qualities": ["720p"], "wait_for": [0], "order": 9}, {"core": true, "_rev": "0003eac7", "finish": [true], "hide": false, "_id": "2caf1580e5164a86b2c9fc7a6145e1cb", "_t": "profile", "label": "BR-Rip", "qualities": ["brrip"], "wait_for": [0], "order": 10}, {"core": true, "_rev": "00038da5", "finish": [true], "hide": false, "_id": "8a386a99cc114e0a8a12a8c9ad58fc09", "_t": "profile", "label": "DVD-R", "qualities": ["dvdr"], "wait_for": [0], "order": 11}, {"core": true, "_rev": "00033e9c", "finish": [true], "hide": false, "_id": "013d21407a30426796ae9d518365f74c", "_t": "profile", "label": "DVD-Rip", "qualities": ["dvdrip"], "wait_for": [0], "order": 12}, {"core": true, "_rev": "00039859", "finish": [true], "hide": false, "_id": "b593f0e5a5e84aabab80fcd2bed0bf8c", "_t": "profile", "label": "Screener", "qualities": ["scr"], "wait_for": [0], "order": 13}, {"core": true, "_rev": "0003a877", "finish": [true], "hide": false, "_id": "cb8a13ee85244b94b74e951b8eba0363", "_t": "profile", "label": "R5", "qualities": ["r5"], "wait_for": [0], "order": 14}, {"core": true, "_rev": "00039b23", "finish": [true], "hide": false, "_id": "5bfaf3f790fe4fefb66b56ce98331035", "_t": "profile", "label": "TeleCine", "qualities": ["tc"], "wait_for": [0], "order": 15}, {"core": true, "_rev": "000309ef", "finish": [true], "hide": false, "_id": "7efe901a12954226a955841e3c1bdb1d", "_t": "profile", "label": "TeleSync", "qualities": ["ts"], "wait_for": [0], "order": 16}, {"core": true, "_rev": "0003391e", "finish": [true], "hide": false, "_id": "05a5c228d8414680b646dbbf2bf159d9", "_t": "profile", "label": "Cam", "qualities": ["cam"], "wait_for": [0], "order": 17}],
2019-05-27 10:19:30 ERROR   ::MAIN: 'qualities': [{"_rev": "0004e340", "identifier": "2160p", "size_min": 4000, "tags": ["x264", "h264", "2160"], "_id": "27c90435bcea42468e03a6655e4e9ce3", "_t": "quality", "size_max": 20000, "label": "2160p", "height": 2160, "order": 0, "width": 3840, "ext": ["mkv"], "is_3d": false, "allow": [], "alternative": [], "allow_3d": true, "median_size": 20000, "hd": true, "size": [10000, 650000]}, {"_id": "68f41f4aa1fe44628532ac4b7ee0e982", "tags": ["bdmv", "certificate", ["complete", "bluray"], "avc", "mvc"], "_rev": "00019ca0", "_t": "quality", "size_max": 60000, "size_min": 20000, "label": "BR-Disk", "order": 1, "ext": ["iso", "img"], "is_3d": false, "allow_3d": true, "allow": ["1080p"], "alternative": ["bd25", ["br", "disk"]], "identifier": "bd50", "median_size": 40000, "hd": true, "size": [20000, 60000]}, {"_rev": "00033819", "identifier": "1080p", "size_min": 1500, "tags": ["m2ts", "x264", "h264", "1080"], "_id": "40bba5da9b3e46d3b26b7327b1491bc6", "_t": "quality", "size_max": 20000, "label": "1080p", "height": 1080, "order": 2, "width": 1920, "ext": ["mkv", "m2ts", "ts"], "is_3d": false, "allow": [], "alternative": [], "allow_3d": true, "median_size": 10000, "hd": true, "size": [4000, 20000]}, {"_rev": "0003f5b2", "identifier": "720p", "size_min": 1000, "tags": ["x264", "h264", "720"], "_id": "f3d3e6d4f45443988b0c2a4c6e028cbb", "_t": "quality", "size_max": 10000, "label": "720p", "height": 720, "order": 3, "width": 1280, "ext": ["mkv", "ts"], "is_3d": false, "allow": [], "alternative": [], "allow_3d": true, "median_size": 5500, "hd": true, "size": [3000, 10000]}, {"_id": "2a740978a51a4d8ca20c7b567632efb1", "tags": ["webdl", ["web", "dl"]], "_rev": "00031185", "_t": "quality", "size_max": 15000, "size_min": 700, "label": "BR-Rip", "order": 4, "ext": ["mp4", "avi"], "is_3d": false, "allow_3d": true, "allow": ["720p", "1080p", "2160p"], "alternative": ["bdrip", ["br", "rip"], "hdtv", "hdrip"], "identifier": "brrip", "median_size": 2000, "hd": true, "size": [700, 7000]}, {"_id": "9f8fbd384a704a7d9d9653ffa823e56c", "tags": ["pal", "ntsc", "video_ts", "audio_ts", ["dvd", "r"], "dvd9"], "_rev": "0001d58b", "_t": "quality", "size_max": 10000, "size_min": 3000, "label": "DVD-R", "order": 5, "ext": ["iso", "img", "vob"], "is_3d": false, "allow": [], "alternative": ["br2dvd", ["dvd", "r"]], "identifier": "dvdr", "median_size": 4500, "size": [3000, 10000]}, {"_id": "6e72e48774c341e2b1e8554ce55c7214", "tags": [["dvd", "rip"], ["dvd", "xvid"], ["dvd", "divx"]], "_rev": "00016618", "_t": "quality", "size_max": 2400, "size_min": 600, "label": "DVD-Rip", "order": 6, "width": 720, "ext": ["avi"], "is_3d": false, "allow": [], "alternative": [["dvd", "rip"]], "identifier": "dvdrip", "median_size": 1500, "size": [600, 2400]}, {"_id": "e3f986dbdde14fd3b669a75de9322c94", "tags": [], "_rev": "0001b92f", "_t": "quality", "size_max": 1600, "size_min": 600, "label": "Screener", "order": 7, "ext": [], "is_3d": false, "allow": ["dvdr", "dvdrip", "720p", "1080p"], "alternative": ["screener", "dvdscr", "ppvrip", "dvdscreener", "hdscr", "webrip", ["web", "rip"]], "identifier": "scr", "median_size": 700, "size": [600, 1600]}, {"_rev": "00010d1f", "order": 8, "size_min": 600, "is_3d": false, "alternative": ["r6"], "size": [600, 1000], "identifier": "r5", "_t": "quality", "size_max": 1000, "label": "R5", "ext": [], "allow": ["dvdr", "720p", "1080p"], "_id": "9066b95466354605801ca7e178633b9f", "median_size": 700}, {"_rev": "0001e103", "order": 9, "size_min": 600, "is_3d": false, "alternative": ["telecine"], "size": [600, 1000], "identifier": "tc", "_t": "quality", "size_max": 1000, "label": "TeleCine", "ext": [], "allow": ["720p", "1080p"], "_id": "1459c0d3caad465593e0bf9e8057ee18", "median_size": 700}, {"_rev": "00014ffa", "order": 10, "size_min": 600, "is_3d": false, "alternative": ["telesync", "hdts"], "size": [600, 1000], "identifier": "ts", "_t": "quality", "size_max": 1000, "label": "TeleSync", "ext": [], "allow": ["720p", "1080p"], "_id": "fb77a94f1caa4284b8f8c4b822d82cd7", "median_size": 700}, {"_rev": "0001cdbf", "order": 11, "size_min": 600, "is_3d": false, "alternative": ["camrip", "hdcam"], "size": [600, 1000], "identifier": "cam", "_t": "quality", "size_max": 1000, "label": "Cam", "ext": [], "allow": ["720p", "1080p"], "_id": "1eaae0c253cf4c3bbe6c87da242c7f1e", "median_size": 700}]
2019-05-27 10:19:30 ERROR   ::MAIN: });
2019-05-27 10:19:30 ERROR   ::MAIN: CategoryList.setup([]);
2019-05-27 10:19:30 ERROR   ::MAIN: App.setup({
2019-05-27 10:19:30 ERROR   ::MAIN: 'dev': dev,
2019-05-27 10:19:30 ERROR   ::MAIN: 'base_url': "/",
2019-05-27 10:19:30 ERROR   ::MAIN: 'dereferer': "http://www.nullrefer.com/?",
2019-05-27 10:19:30 ERROR   ::MAIN: 'args': "['--daemon', '--data_dir', '/var/db/couchpotato', '--pid_file', '/var/run/couchpotato/couchpotato.pid', '--config_file', '/usr/local/etc/couchpotato/settings.conf']",
2019-05-27 10:19:30 ERROR   ::MAIN: 'options': "Namespace(config_file='/usr/local/etc/couchpotato/settings.conf', console_log=False, daemon=True, data_dir='/var/db/couchpotato', debug=False, pid_file='/var/run/couchpotato/couchpotato.pid', quiet=False)",
2019-05-27 10:19:30 ERROR   ::MAIN: 'app_dir': "/usr/local/share/couchpotato",
2019-05-27 10:19:30 ERROR   ::MAIN: 'data_dir': "/var/db/couchpotato",
2019-05-27 10:19:30 ERROR   ::MAIN: 'pid': "90886 ",
2019-05-27 10:19:30 ERROR   ::MAIN: 'userscript_version': 34,
2019-05-27 10:19:30 ERROR   ::MAIN: 'webui_feature': {
2019-05-27 10:19:30 ERROR   ::MAIN: 'hide_about_dirs' : false,
2019-05-27 10:19:30 ERROR   ::MAIN: 'hide_menuitem_update' : false,
2019-05-27 10:19:30 ERROR   ::MAIN: 'hide_about_update' : false,
2019-05-27 10:19:30 ERROR   ::MAIN: }
2019-05-27 10:19:30 ERROR   ::MAIN: });
2019-05-27 10:19:30 ERROR   ::MAIN: })
2019-05-27 10:19:30 ERROR   ::MAIN: 
2019-05-27 10:19:30 ERROR   ::MAIN: </script>
2019-05-27 10:19:30 ERROR   ::MAIN: 
2019-05-27 10:19:30 ERROR   ::MAIN: <link data-url="static/style/combined.min.css?1558646905" rel="stylesheet" href="/static/style/combined.min.css?1558646905" type="text/css">
2019-05-27 10:19:30 ERROR   ::MAIN: </head>
2019-05-27 10:19:30 ERROR   ::MAIN: <body></body>
2019-05-27 10:19:30 ERROR   ::MAIN: </html>
2019-05-27 10:19:30 DEBUG   ::EXCEPTION: Replacing file name English with directory name The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT
2019-05-27 10:19:30 DEBUG   ::EXCEPTION: Replacing file name /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT/English.srt with download name /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT.srt
2019-05-27 10:19:30 DEBUG   ::MAIN: calling command: /usr/local/bin/ffprobe -v quiet -print_format json -show_format -show_streams -show_error /mnt/Share/Downloads/nzbToMedia/tests/test.mp4
2019-05-27 10:19:30 INFO    ::TRANSCODER: Checking [The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT.mkv] for corruption, please stand by ...
2019-05-27 10:19:30 DEBUG   ::MAIN: calling command: /usr/local/bin/ffprobe -v quiet -print_format json -show_format -show_streams -show_error /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT.mkv
2019-05-27 10:19:30 INFO    ::TRANSCODER: SUCCESS: [The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT.mkv] has no corruption.
2019-05-27 10:19:30 INFO    ::MAIN: Checking for files to be transcoded
2019-05-27 10:19:30 DEBUG   ::MAIN: calling command: /usr/local/bin/ffprobe -v quiet -print_format json -show_format -show_streams -show_error /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT.mkv
2019-05-27 10:19:30 DEBUG   ::MAIN: calling command: /usr/local/bin/ffprobe -v quiet -print_format json -show_format -show_streams -show_error /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT.srt
2019-05-27 10:19:30 INFO    ::MAIN: Transcoding video: /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT-transcoded.mkv
2019-05-27 10:19:30 DEBUG   ::MAIN: calling command: /usr/local/bin/ffmpeg -loglevel warning -hwaccel vaapi -hwaccel_device /dev/dri/renderD128 -hwaccel_output_format vaapi -fflags +genpts -i /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT.mkv -i /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT.srt -map 0:0 -map 0:1 -map 0:1 -map 0:2 -map 1:0 -c:v hevc_vaapi -preset veryfast -crf 21 -c:a:0 ac3 -b:a:0 640000 -c:a:1 aac -strict -2 -b:a:1 192000 -c:s aac /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT-transcoded.mkv
2019-05-27 10:19:30 ERROR   ::MAIN: Transcoding of video to /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT-transcoded.mkv failed with result 1
2019-05-27 10:19:30 ERROR   ::COUCHPOTATO: Transcoding failed for files in /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT
2019-05-27 10:19:30 ERROR   ::MAIN: A problem was reported in the autoProcess* script. If torrent was paused we will resume seeding
2019-05-27 10:19:30 DEBUG   ::MAIN: Starting torrent The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT in transmission
2019-05-27 10:19:35 ERROR   ::MAIN: A problem was reported in the /mnt/Share/Downloads/nzbToMedia/TorrentToMedia.py script.

Having looked at some of the historic issues on here, I added '-report' to the general options but cannot find the log file anywhere when executed through CP. I've tried a filesystem 'find' and no sign of one anywhere. Really confused.

When you say you run ffmpeg manually, you used the following command? /usr/local/bin/ffmpeg -loglevel warning -hwaccel vaapi -hwaccel_device /dev/dri/renderD128 -hwaccel_output_format vaapi -fflags +genpts -i /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT.mkv -i /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT.srt -map 0:0 -map 0:1 -map 0:1 -map 0:2 -map 1:0 -c:v hevc_vaapi -preset veryfast -crf 21 -c:a:0 ac3 -b:a:0 640000 -c:a:1 aac -strict -2 -b:a:1 192000 -c:s aac /mnt/Share/Downloads/Process/movie/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT/The.Jungle.Book.2016.1080p.BluRay.x264.DTS-FGT-transcoded.mkv

If this runs, but not when called by nzbToMedia, then there is something in the environment that is different. Check your -hwaccel vaapi -hwaccel_device /dev/dri/renderD128 -hwaccel_output_format vaapi -fflags +genpts flags to see if any of these require elevated permissions or otherwise have environmental dependencies.

Yes, I took the command exactly from the logs and ran it from the command line. I had been running it as root (CP, transmission and nzbToMedia run as ‘media’ so I ran using that user too) and everything works fine. Any ideas where nzbToMedia would put the ffmpeg -report log when being triggered from transmission? When I run it manually it just saves it in the folder I’m in, but no signs anywhere when ran as part of the workflow.

if that file is created, I would expect it to be in the nzbToMedia directory, however I suspect something is causing this to fault before the file is generated.

Is it possible to Have Transmission call the script and redirect all output to a specific log file? TorrentToMedia.py > output.txt 2>&1

to create an output.txt that hopefully includes all output logging and all error tracing.

you can also specify the absolute path for this output.txt... I just haven't tried doing this through Transmission before.

Not sure how to do that with Transmission, the script to call on completion is part of the setting file and expects to just include a file name to the script.

In the meantime, my efforts to figure this out have been thwarted as I’m now having issues getting Transmission to run the script at all. The nzbToMedia log is not updated (unless I run it manually, with no arguments passed to the script, and then it tells me it’s ran successfully).

I decided to try something through SABnzb to see if that worked and it fails too but gives me the following in the SAB log:

sabnzbdplus
-- Cleanup finished --

Traceback (most recent call last): File "/mnt/Share/Downloads/nzbToMedia/nzbToCouchPotato.py", line 263, in result = nzbToMedia.main(sys.argv, section) File "/mnt/Share/Downloads/nzbToMedia/nzbToMedia.py", line 894, in main download_id='', failure_link=''.join(args[8:])) File "/mnt/Share/Downloads/nzbToMedia/nzbToMedia.py", line 757, in process result = movies.process(section_name, input_directory, input_name, status, client_agent, download_id, input_category, failure_link) File "/mnt/Share/Downloads/nzbToMedia/core/auto_process/movies.py", line 218, in process result = r.json() File "/mnt/Share/Downloads/nzbToMedia/libs/common/requests/models.py", line 897, in json return complexjson.loads(self.text, **kwargs) File "/usr/local/lib/python2.7/json/init.py", line 339, in loads return _default_decoder.decode(s) File "/usr/local/lib/python2.7/json/decoder.py", line 364, in decode obj, end = self.raw_decode(s, idx=_w(s, 0).end()) File "/usr/local/lib/python2.7/json/decoder.py", line 382, in raw_decode raise ValueError("No JSON object could be decoded") ValueError: No JSON object could be decoded Exception AttributeError: "'NoneType' object has no attribute 'path'" in <bound method PosixProcess.del of <core.utils.processes.PosixProcess object at 0x80076fe50>> ignored

> 

NzbToMedia log:

>

2019-05-29 17:03:58 INFO ::MAIN: ######################################################### 2019-05-29 17:03:58 INFO ::MAIN: ## ..::[nzbToMedia.py]::.. ## 2019-05-29 17:03:58 INFO ::MAIN: ######################################################### 2019-05-29 17:03:58 DEBUG ::MAIN: Options passed into nzbToMedia: ['/mnt/Share/Downloads/nzbToMedia/nzbToCouchPotato.py', '/mnt/Share/Downloads/Usenet/Complete/movie/Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL.cp(tt4438848).5', 'Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL.cp(tt4438848).nzb', 'Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL.cp(tt4438848)', '', 'movie', 'alt.binaries.ghost', '0', ''] 2019-05-29 17:03:58 INFO ::MAIN: Script triggered from SABnzbd 0.7.17+ 2019-05-29 17:03:58 DEBUG ::MAIN: Searching for nzoid from SAbnzbd ... 2019-05-29 17:03:58 DEBUG ::MAIN: Found nzoid: SABnzbd_nzo_5XX7qE 2019-05-29 17:03:58 DEBUG ::MAIN: Adding NZB download info for directory /mnt/Share/Downloads/Usenet/Complete/movie/Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL.cp(tt4438848).5 to database 2019-05-29 17:03:58 DB ::MAIN: nzbtomedia.db: UPDATE downloads SET status = ?, client_agent = ?, last_update = ?, input_id = ?, input_hash = ?, input_name = ? WHERE input_directory = ? with args [0, u'sabnzbd', 737208, u'SABnzbd_nzo_5XX7qE', u'SABnzbd_nzo_5XX7qE', u'Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL.cp(tt4438848).nzb', u'/mnt/Share/Downloads/Usenet/Complete/movie/Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL.cp(tt4438848).5'] 2019-05-29 17:03:58 DB ::MAIN: nzbtomedia.db: INSERT OR IGNORE INTO downloads (status, client_agent, last_update, input_id, input_hash, input_name) VALUES (?, ?, ?, ?, ?, ?) with args [0, u'sabnzbd', 737208, u'SABnzbd_nzo_5XX7qE', u'SABnzbd_nzo_5XX7qE', u'Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL.cp(tt4438848).nzb'] 2019-05-29 17:03:58 INFO ::MAIN: Auto-detected SECTION:CouchPotato 2019-05-29 17:03:58 DEBUG ::MAIN: Checking for archives to extract in directory: /mnt/Share/Downloads/Usenet/Complete/movie/Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL.cp(tt4438848).5 2019-05-29 17:03:58 INFO ::MAIN: Calling CouchPotato:movie to post-process:Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL.cp(tt4438848).nzb 2019-05-29 17:03:58 INFO ::MAIN: Attemping imdbID lookup for Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL.cp(tt4438848).nzb 2019-05-29 17:03:58 INFO ::MAIN: Searching folder and file names for imdbID ... 2019-05-29 17:03:58 INFO ::MAIN: Found imdbID [tt4438848] 2019-05-29 17:03:58 DEBUG ::SERVER: Attempting to connect to server at http://10.0.0.4:5050/couchpotato/api/cd0c2cbf514a425a80d092c86c3967c3/ 2019-05-29 17:03:58 DEBUG ::SERVER: Server responded at http://10.0.0.4:5050/couchpotato/api/cd0c2cbf514a425a80d092c86c3967c3/ 2019-05-29 17:03:58 DEBUG ::MAIN: Opening URL: http://10.0.0.4:5050/couchpotato/api/cd0c2cbf514a425a80d092c86c3967c3/media.get with PARAMS: {'id': 'tt4438848'} 2019-05-29 17:03:58 ERROR ::MAIN: CouchPotato returned the following non-json data 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: <!doctype html> 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: CouchPotato 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 ERROR ::MAIN: 2019-05-29 17:03:58 DEBUG ::MAIN: calling command: /usr/local/bin/ffprobe -v quiet -print_format json -show_format -show_streams -show_error /mnt/Share/Downloads/nzbToMedia/tests/test.mp4 2019-05-29 17:03:58 INFO ::TRANSCODER: Checking [Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL.mkv] for corruption, please stand by ... 2019-05-29 17:03:58 DEBUG ::MAIN: calling command: /usr/local/bin/ffprobe -v quiet -print_format json -show_format -show_streams -show_error /mnt/Share/Downloads/Usenet/Complete/movie/Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL.cp(tt4438848).5/Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL/Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL.mkv 2019-05-29 17:03:58 INFO ::TRANSCODER: SUCCESS: [Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL.mkv] has no corruption. 2019-05-29 17:03:58 DEBUG ::COUCHPOTATO: Opening URL: http://10.0.0.4:5050/couchpotato/api/cd0c2cbf514a425a80d092c86c3967c3/renamer.scan with PARAMS: {'media_folder': '/mnt/Share/Downloads/Usenet/Complete/movie/Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL.cp(tt4438848).5'} 2019-05-29 17:03:58 POSTPROCESS::COUCHPOTATO: Starting renamer scan for Neighbors 2 Sorority Rising (2016) X264 1080P WEB-DL.cp(tt4438848).nzb



No idea why it now won’t run, nothing has changed, as far as I’m aware as I was just messing around with the config for nzbToMedia. Just in case, I moved the autoProcessMedia.cfg and let nzbToMedia recreate a blank one and I’ve just put the basics in for SAB, Transmission, CP and SickChill. Still no luck :(

So what is happening here is:

CouchPotato is not returning the correct (json) data when checking which release this was.
Transcoding is not being done (this isn't necessarily an issue, but just confirming)
When we send to CouchPotato, we again don't get correctly returned json, and this time the script crashes out.

So the question is, what is wrong with the CP interface? Check the api etc to be 100% sure it is right.

in your browser what happens when you go to http://10.0.0.4:5050/couchpotato/api/cd0c2cbf514a425a80d092c86c3967c3/media.get?id=tt4438848

Makes sense (I think), in case I'd messed something up, I reinstalled CP and configured from scratch again and tested again but same behaviour. I tried following the link provided (as the api key had changed I swapped that out with the new one) and I get the CP sidebar but the rest is white.

Okay, so I double checked my CP config and noticed that it still included the webroot from a previous setup, when I removed that and followed the link provided (with the webroot removed) I got a screen with the json data for the download. Following that I updated autoProcessMedia.cfg and tried downloading something else. All appeared to work correctly, script ran, movie was renamed and moved but the nzbToMedia log states:

2019-05-29 22:45:03 INFO    ::MAIN: ## ..::[TorrentToMedia.py]::.. ##
2019-05-29 22:45:03 INFO    ::MAIN: #########################################################
2019-05-29 22:45:03 DEBUG   ::MAIN: Options passed into TorrentToMedia: ['/mnt/Share/Downloads/nzbToMedia/TorrentToMedia.py']
2019-05-29 22:45:03 DEBUG   ::MAIN: Adding TORRENT download info for directory /mnt/Share/Downloads/Torrent/Complete/movie to database
2019-05-29 22:45:03 DB      ::MAIN: nzbtomedia.db: UPDATE downloads SET status = ?, client_agent = ?, last_update = ?, input_id = ?, input_hash = ?, input_name = ? WHERE input_directory = ? with args [0, u'transmission', 737208, u'22', u'938a8efb74bca33e72fa2e1d3fcfbb393c6f854a', u'Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO', u'/mnt/Share/Downloads/Torrent/Complete/movie']
2019-05-29 22:45:03 DB      ::MAIN: nzbtomedia.db: INSERT OR IGNORE INTO downloads (status, client_agent, last_update, input_id, input_hash, input_name) VALUES (?, ?, ?, ?, ?, ?) with args [0, u'transmission', 737208, u'22', u'938a8efb74bca33e72fa2e1d3fcfbb393c6f854a', u'Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO']
2019-05-29 22:45:03 DEBUG   ::MAIN: Received Directory: /mnt/Share/Downloads/Torrent/Complete/movie | Name: Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO | Category: 
2019-05-29 22:45:03 DEBUG   ::MAIN: SEARCH: Found Category: movie in directory structure
2019-05-29 22:45:03 INFO    ::MAIN: SEARCH: Found torrent directory Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO in input directory directory /mnt/Share/Downloads/Torrent/Complete/movie
2019-05-29 22:45:03 INFO    ::MAIN: SEARCH: Setting input_directory to /mnt/Share/Downloads/Torrent/Complete/movie/Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO
2019-05-29 22:45:03 DEBUG   ::MAIN: Determined Directory: /mnt/Share/Downloads/Torrent/Complete/movie/Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO | Name: Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO | Category: movie
2019-05-29 22:45:03 INFO    ::MAIN: Auto-detected SECTION:CouchPotato
2019-05-29 22:45:03 DEBUG   ::MAIN: Stopping torrent Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO in transmission while processing
2019-05-29 22:45:08 INFO    ::MAIN: Output directory set to: /mnt/Share/Downloads/Process/movie/Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO
2019-05-29 22:45:08 DEBUG   ::MAIN: Scanning files in directory: /mnt/Share/Downloads/Torrent/Complete/movie/Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO
2019-05-29 22:45:08 DEBUG   ::MAIN: Found 1 files in /mnt/Share/Downloads/Torrent/Complete/movie/Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO
2019-05-29 22:45:08 INFO    ::COPYLINK: MEDIAFILE: [Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO.mkv]
2019-05-29 22:45:08 INFO    ::COPYLINK: SOURCE FOLDER: [/mnt/Share/Downloads/Torrent/Complete/movie/Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO]
2019-05-29 22:45:08 INFO    ::COPYLINK: TARGET FOLDER: [/mnt/Share/Downloads/Process/movie/Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO]
2019-05-29 22:45:08 INFO    ::COPYLINK: Hard linking SOURCE MEDIAFILE -> TARGET FOLDER
2019-05-29 22:45:08 DEBUG   ::MAIN: Checking for archives to extract in directory: /mnt/Share/Downloads/Torrent/Complete/movie/Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO
2019-05-29 22:45:08 INFO    ::MAIN: FLATTEN: Flattening directory: /mnt/Share/Downloads/Process/movie/Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO
2019-05-29 22:45:08 DEBUG   ::MAIN: Checking for empty folders in:/mnt/Share/Downloads/Process/movie/Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO
2019-05-29 22:45:08 INFO    ::MAIN: Found 1 media files in /mnt/Share/Downloads/Process/movie/Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO
2019-05-29 22:45:08 INFO    ::MAIN: Calling CouchPotato:movie to post-process:Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO
2019-05-29 22:45:08 INFO    ::MAIN: Attemping imdbID lookup for Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO
2019-05-29 22:45:08 INFO    ::MAIN: Searching folder and file names for imdbID ...
2019-05-29 22:45:08 INFO    ::MAIN: Searching IMDB for imdbID ...
2019-05-29 22:45:08 INFO    ::MAIN: Unable to determine imdbID: No api key provided for ombdapi.com.
2019-05-29 22:45:08 DEBUG   ::SERVER: Attempting to connect to server at http://10.0.0.4:5050/api/025967fa9045466ba038b7e1115c87c2/
2019-05-29 22:45:08 DEBUG   ::SERVER: Server responded at http://10.0.0.4:5050/api/025967fa9045466ba038b7e1115c87c2/
2019-05-29 22:45:08 DEBUG   ::MAIN: Opening URL: http://10.0.0.4:5050/api/025967fa9045466ba038b7e1115c87c2/media.list with PARAMS: {}
2019-05-29 22:45:09 DEBUG   ::MAIN: calling command: /usr/local/bin/ffprobe -v quiet -print_format json -show_format -show_streams -show_error /mnt/Share/Downloads/nzbToMedia/tests/test.mp4
2019-05-29 22:45:09 INFO    ::TRANSCODER: Checking [Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO.mkv] for corruption, please stand by ...
2019-05-29 22:45:09 DEBUG   ::MAIN: calling command: /usr/local/bin/ffprobe -v quiet -print_format json -show_format -show_streams -show_error /mnt/Share/Downloads/Process/movie/Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO/Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO.mkv
2019-05-29 22:45:09 INFO    ::TRANSCODER: SUCCESS: [Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO.mkv] has no corruption.
2019-05-29 22:45:09 DEBUG   ::COUCHPOTATO: Opening URL: http://10.0.0.4:5050/api/025967fa9045466ba038b7e1115c87c2/renamer.scan with PARAMS: {'downloader': u'Transmission', 'download_id': u'938a8efb74bca33e72fa2e1d3fcfbb393c6f854a', 'media_folder': '/mnt/Share/Downloads/Process/movie/Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO'}
2019-05-29 22:45:09 POSTPROCESS::COUCHPOTATO: Starting renamer scan for Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO
2019-05-29 22:45:10 POSTPROCESS::COUCHPOTATO: SUCCESS: Finished renamer scan for folder /mnt/Share/Downloads/Process/movie/Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO
2019-05-29 22:45:10 POSTPROCESS::COUCHPOTATO: Checking for status change, please stand by ...
2019-05-29 22:45:10 DEBUG   ::MAIN: Opening URL: http://10.0.0.4:5050/api/025967fa9045466ba038b7e1115c87c2/media.get with PARAMS: {'id': u'2cc2896a5c9641b2917f7c390c167116'}
2019-05-29 22:45:30 POSTPROCESS::COUCHPOTATO: Checking for status change, please stand by ...
2019-05-29 22:45:30 DEBUG   ::MAIN: Opening URL: http://10.0.0.4:5050/api/025967fa9045466ba038b7e1115c87c2/media.get with PARAMS: {'id': u'2cc2896a5c9641b2917f7c390c167116'}
2019-05-29 22:45:50 POSTPROCESS::COUCHPOTATO: Checking for status change, please stand by ...
2019-05-29 22:45:50 DEBUG   ::MAIN: Opening URL: http://10.0.0.4:5050/api/025967fa9045466ba038b7e1115c87c2/media.get with PARAMS: {'id': u'2cc2896a5c9641b2917f7c390c167116'}
2019-05-29 22:46:10 POSTPROCESS::COUCHPOTATO: Checking for status change, please stand by ...
2019-05-29 22:46:10 DEBUG   ::MAIN: Opening URL: http://10.0.0.4:5050/api/025967fa9045466ba038b7e1115c87c2/media.get with PARAMS: {'id': u'2cc2896a5c9641b2917f7c390c167116'}
2019-05-29 22:46:31 POSTPROCESS::COUCHPOTATO: Checking for status change, please stand by ...
2019-05-29 22:46:31 DEBUG   ::MAIN: Opening URL: http://10.0.0.4:5050/api/025967fa9045466ba038b7e1115c87c2/media.get with PARAMS: {'id': u'2cc2896a5c9641b2917f7c390c167116'}
2019-05-29 22:46:51 POSTPROCESS::COUCHPOTATO: Checking for status change, please stand by ...
2019-05-29 22:46:51 DEBUG   ::MAIN: Opening URL: http://10.0.0.4:5050/api/025967fa9045466ba038b7e1115c87c2/media.get with PARAMS: {'id': u'2cc2896a5c9641b2917f7c390c167116'}
2019-05-29 22:47:11 WARNING ::COUCHPOTATO: Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO does not appear to have changed status after 2 minutes, Please check your logs.
2019-05-29 22:47:11 ERROR   ::MAIN: A problem was reported in the autoProcess* script. If torrent was paused we will resume seeding
2019-05-29 22:47:11 DEBUG   ::MAIN: Starting torrent Mission.Impossible.Fallout.2018.IMAX.1080p.Bluray.X264-EVO in transmission
2019-05-29 22:47:16 ERROR   ::MAIN: A problem was reported in the /mnt/Share/Downloads/nzbToMedia/TorrentToMedia.py script.

So what that says is that when renaming CouchPotato never marked the download (or at least that particular release) as done. so http://10.0.0.4:5050/api/025967fa9045466ba038b7e1115c87c2/media.get?id=2cc2896a5c9641b2917f7c390c167116 should be showing status "snatched"??

What do the CouchPotato logs show at 2019-05-29 22:45:09? Were there any error in CP that prevented this from completing? What does the "release table" for that movie look like in CP? (when you click on the movie in "snatched and available", does it show a release name and done, or does it show a release name with snacthed and an "n/a" release with status done?

I note that again this didn't attempt to transcode.

The strange thing is that CP picks up the download completion from nzbToMedia and then extracts, renames, and notifies immediately. Looking at the release table, the specific release is marked as downloaded and looking at the timing of the logs, CP shows the status updated to ‘downloaded’ well before the last attempt by nzbToMedia to check the status.

Example on a new file below:

05-30 11:00:02 INFO [tato.core.plugins.renamer] Scanning media folder /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS... 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 2160p via tags ['x264', 'h264', '2160'] in /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 2160p with .mkv extension in /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 1080p via identifier 1080p in /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 1080p via tags ['m2ts', 'x264', 'h264', '1080'] in /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 1080p with .mkv extension in /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 720p via tags ['x264', 'h264', '720'] in /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 720p with .mkv extension in /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 2160p via release size: 5000.0 MB < 8137.0 MB < 25000.0 MB 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 1080p via release size: 1500.0 MB < 8137.0 MB < 15000.0 MB 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found brrip via release size: 700.0 MB < 8137.0 MB < 10000.0 MB 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found dvdr via release size: 3000.0 MB < 8137.0 MB < 10000.0 MB 05-30 11:00:02 DEBUG [hpotato.core.plugins.base] Setting cache ['/mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain'] 05-30 11:00:02 DEBUG [tato.core.plugins.scanner] Grouping files: brokeback mountain 2005 05-30 11:00:02 DEBUG [tato.core.plugins.scanner] Getting metadata for brokeback mountain 2005 05-30 11:00:02 DEBUG [hpotato.core.plugins.base] Getting cache ['/mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain'] 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 2160p via tags ['x264', 'h264', '2160'] in /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 2160p with .mkv extension in /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 1080p via identifier 1080p in /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 1080p via tags ['m2ts', 'x264', 'h264', '1080'] in /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 1080p with .mkv extension in /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 720p via tags ['x264', 'h264', '720'] in /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 720p with .mkv extension in /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 2160p via release size: 5000.0 MB < 8137.0 MB < 25000.0 MB 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 1080p via release size: 1500.0 MB < 8137.0 MB < 15000.0 MB 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found 1080p via resolution_width: 1920 == 1920 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found brrip via release size: 700.0 MB < 8137.0 MB < 10000.0 MB 05-30 11:00:02 DEBUG [tato.core.plugins.quality] Found dvdr via release size: 3000.0 MB < 8137.0 MB < 10000.0 MB 05-30 11:00:02 DEBUG [hpotato.core.plugins.base] Setting cache ['/mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain'] 05-30 11:00:02 DEBUG [tato.core.plugins.scanner] Found movie via imdb id from it's download id: tt0388795 05-30 11:00:02 INFO [tato.core.plugins.scanner] Found 1 movies in the folder /mnt/Share/Downloads/Process/movie 05-30 11:00:02 ERROR [tato.core.plugins.renamer] Missing cd or cd_nr in the renamer. This will cause multi-file releases of being renamed to the same file. Please add it in the renamer settings. Force adding it for now. 05-30 11:00:02 DEBUG [hpotato.core.plugins.base] Acquiring lock: media.get.8cf1964b29b743418c2ef3b48d189d0d 05-30 11:00:02 INFO [hpotato.core.plugins.base] Opening url: get https://api.couchpota.to/info/tt0388795/?ignore=1, data: [] 05-30 11:00:02 INFO [hpotato.core.plugins.base] Opening url: get https://api.themoviedb.org/3/movie/tt0388795?api_key=xxx&append_to_response=alternative_titles&language=en, data: [] 05-30 11:00:02 INFO [hpotato.core.plugins.base] Opening url: get http://www.omdbapi.com/?type=movie&i=tt0388795, data: [] 05-30 11:00:02 DEBUG [hpotato.core.plugins.base] Failed getting cache: Traceback (most recent call last): SysCallError: (32, 'EPIPE') 05-30 11:00:02 DEBUG [providers.info.themoviedb] Movie not found: movie/tt0388795, append_to_response=alternative_titles&language=en 05-30 11:00:02 ERROR [hpotato.core.plugins.base] Failed opening url in OMDBAPI: http://www.omdbapi.com/?type=movie&i=tt0388795 Traceback (most recent call last): HTTPError: 401 Client Error: Unauthorized for url: http://www.omdbapi.com/?type=movie&i=tt0388795 05-30 11:00:02 DEBUG [hpotato.core.plugins.base] Failed getting cache: Traceback (most recent call last): HTTPError: 401 Client Error: Unauthorized for url: http://www.omdbapi.com/?type=movie&i=tt0388795 05-30 11:00:02 DEBUG [ couchpotato.core.event] Return modified results for movie.info 05-30 11:00:02 DEBUG [to.core.media.movie._base] Adding titles: [] 05-30 11:00:02 DEBUG [hpotato.core.plugins.base] Releasing lock: media.get.8cf1964b29b743418c2ef3b48d189d0d 05-30 11:00:02 DEBUG [tato.core.plugins.release] Marking release Brokeback Mountain 2005 1080p HDDVD x264-TiMELORDS[TorrentDay] as downloaded 05-30 11:00:02 DEBUG [o.core.notifications.core] Notifying frontend 05-30 11:00:02 DEBUG [o.core.notifications.core] Done notifying frontend 05-30 11:00:02 DEBUG [tato.core.plugins.renamer] Removing leftover files 05-30 11:00:02 INFO [tato.core.plugins.renamer] Linking "/mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv" to "/mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv" 05-30 11:00:02 DEBUG [tato.core.plugins.renamer] Hardlinking file "/mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv" to "/mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv"... 05-30 11:00:02 INFO [hpotato.core.plugins.base] Opening url: post https://api.pushbullet.com/v2/pushes, data: ['body', 'type', 'title'] 05-30 11:00:02 DEBUG [hpotato.core.plugins.base] Failed getting cache: Traceback (most recent call last): SysCallError: (32, 'EPIPE') 05-30 11:00:02 ERROR [.notifications.pushbullet] Unable to push notification to Pushbullet device with ID None 05-30 11:00:02 INFO [hpotato.core.plugins.base] Opening url: get http://10.0.0.6:32400/library/sections?X-Plex-Token=PkNUf9YsL6ZFUmzsU3x8, data: [] 05-30 11:00:02 INFO [hpotato.core.plugins.base] Opening url: get http://10.0.0.6:32400/library/sections/5/refresh?X-Plex-Token=PkNUf9YsL6ZFUmzsU3x8, data: [] 05-30 11:00:02 INFO [hpotato.core.plugins.base] Opening url: get http://10.0.0.6:32400/library/sections/4/refresh?X-Plex-Token=PkNUf9YsL6ZFUmzsU3x8, data: [] 05-30 11:00:02 DEBUG [o.core.notifications.core] Cleaning messages 05-30 11:00:02 DEBUG [o.core.notifications.core] Done cleaning messages 05-30 11:00:02 INFO [hpotato.core.plugins.base] Opening url: get http://10.0.0.6:32400/library/sections/1/refresh?X-Plex-Token=PkNUf9YsL6ZFUmzsU3x8, data: [] 05-30 11:00:03 DEBUG [o.core.notifications.core] Notifying frontend 05-30 11:00:03 DEBUG [o.core.notifications.core] Done notifying frontend 05-30 11:00:03 INFO [o.core.notifications.plex] Failed to send notification to client "". Client list is stale, updating the client list and retrying. 05-30 11:00:03 INFO [notifications.plex.server] Searching for clients on Plex Media Server 05-30 11:00:03 INFO [hpotato.core.plugins.base] Opening url: get http://10.0.0.6:32400/clients?X-Plex-Token=PkNUf9YsL6ZFUmzsU3x8, data: [] 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 2160p via tags ['x264', 'h264', '2160'] in /mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 2160p with .mkv extension in /mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 1080p via identifier 1080p in /mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 1080p via tags ['m2ts', 'x264', 'h264', '1080'] in /mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 1080p with .mkv extension in /mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 720p via tags ['x264', 'h264', '720'] in /mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 720p with .mkv extension in /mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 2160p via release size: 5000.0 MB < 8137.0 MB < 25000.0 MB 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 1080p via release size: 1500.0 MB < 8137.0 MB < 15000.0 MB 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found brrip via release size: 700.0 MB < 8137.0 MB < 10000.0 MB 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found dvdr via release size: 3000.0 MB < 8137.0 MB < 10000.0 MB 05-30 11:00:03 DEBUG [hpotato.core.plugins.base] Setting cache ['/mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264)'] 05-30 11:00:03 DEBUG [tato.core.plugins.scanner] Grouping files: brokeback mountain 0 05-30 11:00:03 DEBUG [tato.core.plugins.scanner] Getting metadata for brokeback mountain 0 05-30 11:00:03 DEBUG [hpotato.core.plugins.base] Getting cache ['/mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264)'] 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 2160p via tags ['x264', 'h264', '2160'] in /mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 2160p with .mkv extension in /mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 1080p via identifier 1080p in /mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 1080p via tags ['m2ts', 'x264', 'h264', '1080'] in /mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 1080p with .mkv extension in /mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 720p via tags ['x264', 'h264', '720'] in /mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 720p with .mkv extension in /mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 2160p via release size: 5000.0 MB < 8137.0 MB < 25000.0 MB 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 1080p via release size: 1500.0 MB < 8137.0 MB < 15000.0 MB 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found 1080p via resolution_width: 1920 == 1920 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found brrip via release size: 700.0 MB < 8137.0 MB < 10000.0 MB 05-30 11:00:03 DEBUG [tato.core.plugins.quality] Found dvdr via release size: 3000.0 MB < 8137.0 MB < 10000.0 MB 05-30 11:00:03 DEBUG [hpotato.core.plugins.base] Setting cache ['/mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264)'] 05-30 11:00:03 ERROR [tato.core.plugins.scanner] No imdb_id found for [u'brokeback mountain 0 1080p', u'brokeback mountain 0']. Add a NFO file with IMDB id or add the year to the filename. 05-30 11:00:03 ERROR [tato.core.plugins.scanner] Unable to determine media: [u'brokeback mountain 0 1080p', u'brokeback mountain 0'] 05-30 11:00:03 INFO [tato.core.plugins.scanner] Found 1 movies in the folder /mnt/Share/Media/Movies 05-30 11:00:17 DEBUG [o.core.notifications.core] Cleaning messages 05-30 11:00:17 DEBUG [o.core.notifications.core] Done cleaning messages 05-30 11:00:32 DEBUG [o.core.notifications.core] Cleaning messages 05-30 11:00:32 DEBUG [o.core.notifications.core] Done cleaning messages

nzbToMedia

2019-05-30 10:56:53 DEBUG ::MAIN: Connecting to transmission: http://10.0.0.3:9091 2019-05-30 10:56:53 INFO ::MAIN: ######################################################### 2019-05-30 10:56:53 INFO ::MAIN: ## ..::[TorrentToMedia.py]::.. ## 2019-05-30 10:56:53 INFO ::MAIN: ######################################################### 2019-05-30 10:56:53 DEBUG ::MAIN: Options passed into TorrentToMedia: ['/mnt/Share/Downloads/nzbToMedia/TorrentToMedia.py'] 2019-05-30 10:56:53 DEBUG ::MAIN: Adding TORRENT download info for directory /mnt/Share/Downloads/Torrent/Complete/movie to database 2019-05-30 10:56:53 DB ::MAIN: nzbtomedia.db: UPDATE downloads SET status = ?, client_agent = ?, last_update = ?, input_id = ?, input_hash = ?, input_name = ? WHERE input_directory = ? with args [0, u'transmission', 737209, u'27', u'4c4613a2bb81cf6188d355fbf57bfdce7f394319', u'Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS', u'/mnt/Share/Downloads/Torrent/Complete/movie'] 2019-05-30 10:56:53 DB ::MAIN: nzbtomedia.db: INSERT OR IGNORE INTO downloads (status, client_agent, last_update, input_id, input_hash, input_name) VALUES (?, ?, ?, ?, ?, ?) with args [0, u'transmission', 737209, u'27', u'4c4613a2bb81cf6188d355fbf57bfdce7f394319', u'Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS'] 2019-05-30 10:56:53 DEBUG ::MAIN: Received Directory: /mnt/Share/Downloads/Torrent/Complete/movie | Name: Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS | Category: 2019-05-30 10:56:53 DEBUG ::MAIN: SEARCH: Found Category: movie in directory structure 2019-05-30 10:56:53 INFO ::MAIN: SEARCH: Found torrent directory Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS in input directory directory /mnt/Share/Downloads/Torrent/Complete/movie 2019-05-30 10:56:53 INFO ::MAIN: SEARCH: Setting input_directory to /mnt/Share/Downloads/Torrent/Complete/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS 2019-05-30 10:56:53 DEBUG ::MAIN: Determined Directory: /mnt/Share/Downloads/Torrent/Complete/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS | Name: Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS | Category: movie 2019-05-30 10:56:53 INFO ::MAIN: Auto-detected SECTION:CouchPotato 2019-05-30 10:56:53 DEBUG ::MAIN: Stopping torrent Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS in transmission while processing 2019-05-30 10:56:58 INFO ::MAIN: Output directory set to: /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS 2019-05-30 10:56:58 DEBUG ::MAIN: Scanning files in directory: /mnt/Share/Downloads/Torrent/Complete/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS 2019-05-30 10:56:58 DEBUG ::MAIN: Found 0 files in /mnt/Share/Downloads/Torrent/Complete/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS 2019-05-30 10:56:58 DEBUG ::MAIN: Checking for archives to extract in directory: /mnt/Share/Downloads/Torrent/Complete/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r50 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r27 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r68 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r15 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r62 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r43 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r34 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r49 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r06 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r71 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r76 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r01 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r33 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r44 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r39 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r81 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r18 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r65 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r12 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r20 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r57 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r24 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r53 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r59 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r61 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r16 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r85 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r37 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r40 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r78 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r72 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r05 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r08 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r02 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r75 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r47 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r30 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r11 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r66 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r82 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r54 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r23 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r29 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r13 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r64 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r80 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r19 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r56 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r21 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r00 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r77 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r38 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r45 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r32 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r48 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r35 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r42 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r70 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r07 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r26 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r51 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r63 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r14 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r69 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r83 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r67 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r10 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r28 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r22 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r55 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r74 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r03 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r09 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r31 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r46 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r41 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r36 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r04 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r73 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 DEBUG ::MAIN: EXTRACTOR: Unknown file type: .r79 2019-05-30 10:56:58 WARNING ::MAIN: EXTRACTOR: Cannot determine which tool to use when called from Transmission 2019-05-30 10:56:58 INFO ::MAIN: Extracting /mnt/Share/Downloads/Torrent/Complete/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.rar to /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS 2019-05-30 10:56:58 DEBUG ::MAIN: Extracting ['unrar', 'x', '-o+', '-y'] /mnt/Share/Downloads/Torrent/Complete/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.rar /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS 2019-05-30 10:59:58 INFO ::MAIN: EXTRACTOR: Extraction was successful for /mnt/Share/Downloads/Torrent/Complete/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.rar to /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS 2019-05-30 11:00:01 INFO ::MAIN: FLATTEN: Flattening directory: /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS 2019-05-30 11:00:01 DEBUG ::MAIN: Checking for empty folders in:/mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS 2019-05-30 11:00:01 INFO ::MAIN: Found 1 media files in /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS 2019-05-30 11:00:01 INFO ::MAIN: Calling CouchPotato:movie to post-process:Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS 2019-05-30 11:00:01 INFO ::MAIN: Attemping imdbID lookup for Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS 2019-05-30 11:00:01 INFO ::MAIN: Searching folder and file names for imdbID ... 2019-05-30 11:00:01 INFO ::MAIN: Searching IMDB for imdbID ... 2019-05-30 11:00:01 INFO ::MAIN: Unable to determine imdbID: No api key provided for ombdapi.com. 2019-05-30 11:00:01 DEBUG ::SERVER: Attempting to connect to server at http://10.0.0.4:5050/couchpotato/api/025967fa9045466ba038b7e1115c87c2/ 2019-05-30 11:00:01 DEBUG ::SERVER: Server responded at http://10.0.0.4:5050/couchpotato/api/025967fa9045466ba038b7e1115c87c2/ 2019-05-30 11:00:01 DEBUG ::MAIN: Opening URL: http://10.0.0.4:5050/couchpotato/api/025967fa9045466ba038b7e1115c87c2/media.list with PARAMS: {} 2019-05-30 11:00:02 DEBUG ::MAIN: calling command: /usr/local/bin/ffprobe -v quiet -print_format json -show_format -show_streams -show_error /mnt/Share/Downloads/nzbToMedia/tests/test.mp4 2019-05-30 11:00:02 INFO ::TRANSCODER: Checking [tl-brokeback.mountain.mkv] for corruption, please stand by ... 2019-05-30 11:00:02 DEBUG ::MAIN: calling command: /usr/local/bin/ffprobe -v quiet -print_format json -show_format -show_streams -show_error /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv 2019-05-30 11:00:02 INFO ::TRANSCODER: SUCCESS: [tl-brokeback.mountain.mkv] has no corruption. 2019-05-30 11:00:02 DEBUG ::COUCHPOTATO: Opening URL: http://10.0.0.4:5050/couchpotato/api/025967fa9045466ba038b7e1115c87c2/renamer.scan with PARAMS: {'downloader': u'Transmission', 'download_id': u'4c4613a2bb81cf6188d355fbf57bfdce7f394319', 'media_folder': '/mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS'} 2019-05-30 11:00:02 POSTPROCESS::COUCHPOTATO: Starting renamer scan for Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS 2019-05-30 11:00:03 POSTPROCESS::COUCHPOTATO: SUCCESS: Finished renamer scan for folder /mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS 2019-05-30 11:00:03 POSTPROCESS::COUCHPOTATO: Checking for status change, please stand by ... 2019-05-30 11:00:03 DEBUG ::MAIN: Opening URL: http://10.0.0.4:5050/couchpotato/api/025967fa9045466ba038b7e1115c87c2/media.get with PARAMS: {'id': u'd304e426ba03464184ea6d64a051d601'} 2019-05-30 11:00:23 POSTPROCESS::COUCHPOTATO: Checking for status change, please stand by ... 2019-05-30 11:00:23 DEBUG ::MAIN: Opening URL: http://10.0.0.4:5050/couchpotato/api/025967fa9045466ba038b7e1115c87c2/media.get with PARAMS: {'id': u'd304e426ba03464184ea6d64a051d601'} 2019-05-30 11:00:43 POSTPROCESS::COUCHPOTATO: Checking for status change, please stand by ... 2019-05-30 11:00:43 DEBUG ::MAIN: Opening URL: http://10.0.0.4:5050/couchpotato/api/025967fa9045466ba038b7e1115c87c2/media.get with PARAMS: {'id': u'd304e426ba03464184ea6d64a051d601'} 2019-05-30 11:01:03 POSTPROCESS::COUCHPOTATO: Checking for status change, please stand by ... 2019-05-30 11:01:03 DEBUG ::MAIN: Opening URL: http://10.0.0.4:5050/couchpotato/api/025967fa9045466ba038b7e1115c87c2/media.get with PARAMS: {'id': u'd304e426ba03464184ea6d64a051d601'} 2019-05-30 11:01:23 POSTPROCESS::COUCHPOTATO: Checking for status change, please stand by ... 2019-05-30 11:01:23 DEBUG ::MAIN: Opening URL: http://10.0.0.4:5050/couchpotato/api/025967fa9045466ba038b7e1115c87c2/media.get with PARAMS: {'id': u'd304e426ba03464184ea6d64a051d601'} 2019-05-30 11:01:43 POSTPROCESS::COUCHPOTATO: Checking for status change, please stand by ... 2019-05-30 11:01:43 DEBUG ::MAIN: Opening URL: http://10.0.0.4:5050/couchpotato/api/025967fa9045466ba038b7e1115c87c2/media.get with PARAMS: {'id': u'd304e426ba03464184ea6d64a051d601'} 2019-05-30 11:02:03 WARNING ::COUCHPOTATO: Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS does not appear to have changed status after 2 minutes, Please check your logs. 2019-05-30 11:02:03 ERROR ::MAIN: A problem was reported in the autoProcess* script. If torrent was paused we will resume seeding 2019-05-30 11:02:03 DEBUG ::MAIN: Starting torrent Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS in transmission 2019-05-30 11:02:08 ERROR ::MAIN: A problem was reported in the /mnt/Share/Downloads/nzbToMedia/TorrentToMedia.py script.

As for transcoding, sorry, I forgot to mention last time that I turned it off until normal processing starts working properly again.

Also, reinstated the webroot in CP as I’d realised that it was just a mismatch between CP config and others that was stopping the api calls being returned - all works fine there now with the web rot included (when taking the url and putting it into the browser)

ok... a few things here that can cause issues.

Hardlinking file "/mnt/Share/Downloads/Process/movie/Brokeback.Mountain.2005.1080p.HDDVD.x264-TiMELORDS/tl-brokeback.mountain.mkv" to "/mnt/Share/Media/Movies/Brokeback Mountain (0) 1080p (H264).mkv"... 05-30 11:00:02 INFO

in CP, settings, renamer, set Default File Action = Move and Torrent File Action = move nzbToMedia already links this... so this linking again causes issues.

also, be sure that CP, settings, renamer, Run Every = 0 and Force Every = 0

I note a few failures with notifications (plex etc in CP)... each of these can cause the over-all rename process to fail and therefore prevent the release being marked as done. but at least when nzbToMedia cheack and finds the files have moved (not linked) it will know everything has finished.

Okay, I’ll make changes to the ‘Torrent File Action’, strange as I’m sure that’s how it’s always been (though in hindsight, that’s probably never been a great way to have it setup... call it naivety when I first set things up years ago and have just left).

THe Plex and PushBullet notifications are working okay, though I can see that error about ‘Device’ being none for PushBullet - though blank is a valid setting and it works at the other end so assume it’s always been like that.

The Renamer settings are already set to Run Every = 0 and Force Every = 0.

Just out of curiosity I reset up the Pushbullet notifier from scratch and now everything seems to be working fine again. NzbToMedia logs show everything finishing correctly.

Thanks for your help with that side tracking issue.

Just heading off to backup all of the configs before trying to figure out the transcoder mystery.

SUCCESS!

I decided to have a play around and see what was going wrong. There was no sign of the ffmpeg -report log file, which made me suspect it was trying to be written to somewhere my 'media' user couldn't access. So with my very limited knowledge of Python I tracked down where the ffmpeg command was being executed in transcoder.py and piped STDERR into a log file of my own, where it immediately showed that there was a permission error writing back the -report log. I removed -report from general options in autoProcessMedia.cfg and tried again and the transcoder started!

I think what was originally causing the issue was the 'nice' setting - the version of nice on FreeNAS 11.2 doesn't accept '-n' as an option, it just expects the number. I made this "" and that removed the option from the ffmpeg command.

Everything completed successfully and the script exited cleanly. I've downloaded a few things through CP and SickChill without a hitch so far.

Thanks for all your help.

Glad to hear you traced this down... and well done finding the place to pipe STDERR

For the nice command, I am wondering if I should allow a way to over-ride the command. i.e. in config

[Posix]
    niceness = 0

This handles as normal (nice -n0), but if we pass a comma separated list of commands

[Posix]
    niceness = nice,0

This explicitly passes as nice 0 This way it wouldn't have backwards compatibility issues for anyone who has already set an integer and requires the -n

in https://github.com/clinton-hall/nzbToMedia/blob/master/core/__init__.py#L453

            NICENESS.extend(['nice', '-n{0}'.format(int(CFG['Posix']['niceness']))])

becomes (as a guess for now... haven't had a chance to test)

            if len(CFG['Posix']['niceness'].split(',')) > 1:
                NICENESS.extend(CFG['Posix']['niceness'].split(','))
            else:
                NICENESS.extend(['nice', '-n{0}'.format(int(CFG['Posix']['niceness']))])

Oh... as a quick note.

The changes you made to trasncoder.py.... these will prevent automatic updating.

So run git reset --hard and then git pull to get everything back in sync

Thanks, when I say very limited, I mean limited so I’m pretty pleased with myself! :)

I think your suggestion about factoring in the option for niceness on implementations that don’t take -nX would be a lot more elegant than not using nice at all (like I’ve had to do).

I’m also wondering if it would be useful to somehow have the STDERR from external calls included in the standard nzbToMedia log file, for anyone else struggling to diagnose issues like this in future?

Thanks for the heads up on git, I’d have forgotten all about my changes at some point and wonder why it wouldn’t update.

I can look to add the STDERR in the log file.

Any chance you can share the actual change you made (and possibly even how this looked in the log)?

Adding the STDERR is (in my opinion) not an easy change. Not only did you have to track it down to see where ffmpeg was called, you then needed to correctly pipe the STDERR and presumably add this into the log? so indeed be very pleased with yourself 👍 (....edit: I see that you said you piped it into a separate file. So I'll see what i can do to write back into log....)

This is where I'll be honest and say that I started this as a hobby. Just about everything here has been learn as I go (with a lot of help from some very talented contributors etc.) so while I think I know what you have done, I would like to see the code and the output as this is one of those changes that can be fun to try and test at my end.

Ok... I have added some stuff. Are you able to checkout and test the Transcode-patch-1 branch?

If you were to add back in the -report option that should trigger the error and I'd be curious to see how that looks in the logs.

Likewise, if you set niceness = 1 this should fail (again curious to see how it looks), but setting niceness = nice,1 should (hopefully) work.

Thank you, appreciate that. I’ve always liked to dabble about and see what I can do (or break :)).

Yes, I wasn’t quite able to figure out how to pipe the output into your existing log, so just threw it out into a separate file:

try: if isinstance(file, string_types): extlog = open('/mnt/Share/Downloads/nzbToMedia/logs/extlog.log', 'w') proc = subprocess.Popen(command, stdout=bitbucket, stderr=subprocess.extlog) extlog.close() else: img, data = next(iteritems(file)) extlog = open('/mnt/Share/Downloads/nzbToMedia/logs/extlog.log', "w") proc = subprocess.Popen(command, stdout=bitbucket, stderr=extlog, stdin=subprocess.PIPE) extlog.close() for vob in data['files']: procin = zip_out(vob, img, bitbucket) if procin: shutil.copyfileobj(procin.stdout, proc.stdin) procin.stdout.close() proc.communicate() result = proc.returncode except Exception: logger.error('Transcoding of video {0} has failed'.format(newfile_path))

Your version is much nicer, as expected, but actually makes sense now I’ve seen it.

I’ll update to your updated version later today when I finish work, however in the meantime I’ve had a little issue with a movie I downloaded this morning. It’s an HEVC encoded file, which should have been skipped by the allowed codecs list I have but the transcoder has attempted to run anyway.

[matroska,webm @ 0x80cc45000] Could not find codec parameters for stream 2 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size Consider increasing the value for the 'analyzeduration' and 'probesize' options [matroska,webm @ 0x80cc45000] Could not find codec parameters for stream 3 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size Consider increasing the value for the 'analyzeduration' and 'probesize' options [hevc @ 0x80cff0e00] Failed setup for format vaapi_vld: hwaccel initialisation returned error. Impossible to convert between the formats supported by the filter 'Parsed_null_0' and the filter 'auto_scaler_0' Error reinitializing filters! Failed to inject frame into filter network: Function not implemented Error while processing the decoded data for stream #0:0

The VAAPI errors don’t surprise me as my hardware isn’t able to cope with HEVC 10bit and actually I don’t want to reencode anything already HEVC.

I am a bit interested in the pgs_sub errors though, assume these would have caused the script the error, is his something I need to adjust settings for or is it something not accounted for in the script?

I ran an ffprobe on the input file to see the vide codec:

Stream #0:0: Video: hevc (Main 10), yuv420p10le(tv, bt2020nc/bt2020/smpte2084), 3840x2160 [SAR 1:1 DAR 16:9], 23.98 fps, 23.98 tbr, 1k tbn, 23.98 tbc (default) Metadata: BPS : 15637241 BPS-eng : 15637241 DURATION : 01:46:38.225000000 DURATION-eng : 01:46:38.225000000 NUMBER_OF_FRAMES: 153404 NUMBER_OF_FRAMES-eng: 153404 NUMBER_OF_BYTES : 12506323564 NUMBER_OF_BYTES-eng: 12506323564 _STATISTICS_WRITING_APP: mkvmerge v15.0.0 ('Duel with the Devil') 64-bit _STATISTICS_WRITING_APP-eng: mkvmerge v15.0.0 ('Duel with the Devil') 64-bit _STATISTICS_WRITING_DATE_UTC: 2018-01-06 08:09:00 _STATISTICS_WRITING_DATE_UTC-eng: 2018-01-06 08:09:00 _STATISTICS_TAGS: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES

My current allow list is "HEVC,hevc,h265,x265,h.265". Do I need to account for variations of profile (main, high, etc) and bits (8bit, 10bit, etc) within the config?

for subs, try outputSubtitleCodec = mov_text

As far as allowing hvec, we need to see what ffprobe returns from the detailed stream output ffprobe -v quiet -print_format json -show_format -show_streams -show_error <videofile>

the ffprobe json data should be in the format [streams{[codec_type: video, codec_name:<this is what we want>....],[codec_type: audio,.......]}]

Attached ffprobe output - strange it shows as ‘hevc’, which is definitely in my list. ffprobe.txt

I’ll change my subs setting :)

I see it now.

you have

    outputVideoCRF = 21
    outputVideoLevel = 5.1

I do have improvements to make in transcoder, but basically (for now) if you specify these requirements it will always transcode even if the codec is in the allow list.

https://github.com/clinton-hall/nzbToMedia/blob/master/core/transcoder.py#L248-L252

Ah, so... I think I’ve answered my own question. My understanding of how the transcoder works was totally wrong, not through any fault of yours just because I hadn’t really thought it through.

As I’m not overly concerned about audio formats, all I was focusing on was the video codec. Basically what I wanted the script to do was check if the video was HEVC and if so, skip the transcoder altogether for that file and just throw it over to CP or SickChill to be renamed, etc. That stopped me worrying about things like the hardware acceleration support on my Skylake i7 not including 10bit HEVC.

Obviously that’s not what it’s going to do, it’s going to attempt to make the files copy/convert the selected streams and dump them in an mkv container. Doh!

Do you have any suggestions how I can work around this for my workflow or am I resigned to just dealing with the occasional failed download where I’ll have to intervene manually?

I have had (for about 4 years) plans to improve the transcoder and decide when to transcode and what to transcode based on different criteria.... but it is complex and the settings/options that different people want is hard to work around.

for what you want, we would need to add some code to skip transcoding based on allow codec?

the current set up is that allow codecs simply mean we don't transcode (just copy) that stream. The only ignore/skip feature is based on file type (.mkv, .mp4 etc)

As far as "failed" downloads, the corruption check (before transcoding) uses ffmpeg to verify that the video has a valid audio and video stream.... you could add some codec requirements to that to flag as failed and re-queue a download...

Hey! Apologies for the delay in coming back... work's been mega busy.

I can totally imagine how hard it'd be to try and accommodate everyone's requirements :)

It would be good to have options for skipping transcoding, leaving the original file intact, to pass on to whichever frontend its heading to for renaming, etc. Maybe some additional option for if extension, allowed video codec, allowed audio codec, etc. all match or are blank (""), skip transcode and continue as if it wasn't enabled?

However, in my case, my main reason for wanting to skip transcoding on some files was because I'm using hardware transcoding but my GPU has limitations on which formats/levels it can transcode from and with the general options I was using would cause transcoding to fail if it came across one of these formats it couldn't handle.

Having dug a little deeper in to the ffmpeg world I discovered they've accounted for this with some filter options, the only problem is that these options need to be added after the input file name (-i option), which can't be done in the nzbToMedia config. I'm wondering if maybe the answer to my problem and that of others is to have an advanced option where the user enters what they want before -i (general options) and what they want after the input file and before the output file (new config field)? Then it's up to the user to know what they're doing with ffmpeg rather than you having to code around everything? With the change you made to capture the external process stderr in the log, they can diagnose issues themselves too.

P.s. when I was referring to "failed downloads" I meant where the transcoder receives and error because of my issue with hardware decoder formats and then doesn't pass the file to CP, SickChill, etc.) not a genuine failed download.

try the latest nightly and set

[Transcoder]
    otherOptions = <set,options,here>

same as general options, just replace spaces with commas...

Wow! That was fast. Thanks for this, I'll give it a whirl and see how it goes.

Just realised that my response nearly two weeks ago hadn’t posted. Apologies. Everything worked great with the new ‘otherOptions’ field in the config, downloads have been processing exactly as expected. Thanks for your help with this, really appreciate it and it’s doing exactly what I want.

One thing I have noticed is the new option for the nice setting (nice,0) doesn’t seem to do anything and just behaves as having nice set to “”. Not a big deal for me but just flagging it in case someone else needs that option in future.

Are you saying the command for nice 0 is not passed, or just that nice 0 doesn't have any impact? my understanding (probably wrong) is that nice 0 doesn't have any impact (default) but any other integer would either increase or decrease the priority...

Does setting nice 10 decrease the priority and nice -10 increase the priority?

I'll merge this into nightly branch. Once done this branch will disappear, so manually switch to nightly branch to allow continued updating.

clinton-hall / nzbToMedia

Transcoder not doing anything #1619