Movies not post-processing since latest CP update

[nikotime]

I'm not sure if it is just a coincidence, but since the latest CouchPotato update (hash b4ad7b45) none of the movies that it downloaded have successfully post-processed.

http://pastebin.com/MAieffWA

Any ideas? I'm running the dev branch.

[clinton-hall]

Can you show the CouchPotato logs from the same time?

It appears that all Api calls to CouchPotato worked, and the postprocess script ran to completion.

I also note you are using the WOL settings. What system are you waking? CouchPotato is on the local system?

Where does CouchPotato move renamed movies to?

[nikotime]

CouchPotato is in the local system yes, WOL goes to my HTPC with XBMC installed. Just checked CP logs and had the 'Renamer is already running, if you see this often, check the logs above for errors' error. Rebooted my NAS and now everything working again. Thanks, sorry for that

[nikotime]

Ah, I was perhaps too hasty to state that everything works. I let CP redownload a film and received the same error in the postprocess log and then this in CP:
10-24 11:03:21 INFO [tato.core.plugins.renamer] Scanning movie folder /mnt/HD/HD_a2/Nfs/nfs/temporary/movies/The Naked Gun...10-24 11:03:21 ERROR [ couchpotato.core.event] Error in event "renamer.scan", that wasn't caught: Traceback (most recent call last):
File "/mnt/HD/HD_a2/ffp/home/root/CP/CouchPotatoServer/couchpotato/core/event.py", line 12, in runHandler
return handler(_args, *_kwargs)
File "/mnt/HD/HD_a2/ffp/home/root/CP/CouchPotatoServer/couchpotato/core/plugins/renamer/main.py", line 139, in scan
if release_download['files']:
KeyError: 'files'
encoding=UTF-8 debug=False args=[] app_dir=/mnt/HD/HD_a2/ffp/home/root/CP/CouchPotatoServer data_dir=/ffp/home/root/.couchpotato desktop=None options=Namespace(config_file='/ffp/home/root/.couchpotato/settings.conf', console_log=False, daemon=False, data_dir=None, debug=False, pid_file='/ffp/home/root/.couchpotato/couchpotato.pid', quiet=False)

The file has been successfully hardlinked and recides happily in my temporary/movies/ folder

[clinton-hall]

I am looking at the code in CouchPotato and might have a fix... but first I need to see the postporcess.log from nzbToCouchPotato (for the above error) to verify what was passed in.

[nikotime]

This is the corresponding log:

11:00:24|INFO ====================
11:00:24|INFO TorrentToMedia V8.4
11:00:24|INFO Loading WakeOnLan config from
/mnt/HD/HD_a2/Transmission/Downloads/nzbToMedia-dev/autoProcessMedia.cfg
11:00:27|INFO Sending WakeOnLan Magic Packet for mac: 00:01:2e:BC:98:08
11:00:50|INFO Sending WakeOnLan Magic Packet for mac: 00:01:2e:BC:98:08
11:01:13|INFO Sending WakeOnLan Magic Packet for mac: 00:01:2e:BC:98:08
11:01:39|WARNING System with mac: 00:01:2e:BC:98:08 has not woken after 3
attempts. Continuing with the rest of the script.
11:01:39|INFO MAIN: Loading config from
/mnt/HD/HD_a2/Transmission/Downloads/nzbToMedia-dev/autoProcessMedia.cfg
11:01:39|DEBUG MAIN: Received Directory:
/mnt/HD/HD_a2/Transmission/Downloads/Seeding/movies | Name: The Naked
Gun.mkv | Category:
11:01:39|DEBUG SEARCH: Found Category: movies in directory structure
11:01:39|INFO SEARCH: Determined Category to be: movies
11:01:39|INFO SEARCH: Could not find a unique torrent folder in the
directory structure
11:01:39|INFO SEARCH: The directory passed is the root directory for
category movies
11:01:39|WARNING SEARCH: You should change settings to download torrents to
their own directory if possible
11:01:39|INFO SEARCH: We will try and determine which files to process,
individually
11:01:39|INFO MAIN: Download is not a directory
11:01:39|INFO MAIN: Output directory set to:
/mnt/HD/HD_a2/Nfs/nfs/temporary/movies/The Naked Gun
11:01:39|DEBUG MAIN: Scanning files in directory:
/mnt/HD/HD_a2/Transmission/Downloads/Seeding/movies
11:01:39|DEBUG MAIN: Looking for The Naked Gun.mkv in:
A.Fei.zheng.chuan.1990.720p.BluRay.DTS.x264-CtrlHD.unknown.ignore
11:01:39|DEBUG MAIN: Looking for The Naked Gun.mkv in:
DCOM.-.Zenon.The.Zequel.(2001).HR.DSR.XviD-LaR.avi
11:01:39|DEBUG MAIN: Looking for The Naked Gun.mkv in:
Super_Mario_Brothers.unknown.ignore
11:01:39|DEBUG MAIN: Looking for The Naked Gun.mkv in:
DCOM.-.Zenon.The.Zequel.(2001).HR.DSR.XviD-LaR.unknown.ignore
11:01:39|DEBUG MAIN: Looking for The Naked Gun.mkv in:
Hello.Dolly.1969.DVDRip.x264.AC3.mkv
11:01:39|DEBUG MAIN: Looking for The Naked Gun.mkv in: The Naked Gun.mkv
11:01:39|DEBUG MAIN: Found file The Naked Gun.mkv that matches Torrent
Name The Naked Gun.mkv
11:01:39|INFO MAIN: Found video file .mkv in
/mnt/HD/HD_a2/Transmission/Downloads/Seeding/movies/The Naked Gun.mkv
11:01:39|INFO CREATE DESTINATION: Creating destination folder:
/mnt/HD/HD_a2/Nfs/nfs/temporary/movies/The Naked Gun
11:01:39|INFO COPYLINK: Hard linking
/mnt/HD/HD_a2/Transmission/Downloads/Seeding/movies/The Naked Gun.mkv to
/mnt/HD/HD_a2/Nfs/nfs/temporary/movies/The Naked Gun/The Naked Gun.mkv
11:01:39|INFO FLATTEN: Flattening directory:
/mnt/HD/HD_a2/Nfs/nfs/temporary/movies/The Naked Gun
11:01:39|INFO REMOVER: Removing empty folders in:
/mnt/HD/HD_a2/Nfs/nfs/temporary/movies/The Naked Gun
11:01:39|DEBUG MAIN: Connecting to transmission: http://localhost:9091
11:01:40|DEBUG MAIN: Stoping torrent The Naked Gun.mkv in transmission
while processing
11:01:45|DEBUG MAIN: Calling autoProcess script for successful download.
11:01:45|INFO MAIN: Calling CouchPotatoServer to post-process: The Naked
Gun.mkv
11:01:45|INFO Loading config from
/mnt/HD/HD_a2/Transmission/Downloads/nzbToMedia-dev/autoProcessMedia.cfg
11:01:45|WARNING Could not find an imdb id in directory or name
11:01:45|INFO Postprocessing will continue, but the movie may not be
identified correctly by CouchPotato
11:01:45|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.list/?status=active
11:01:50|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=448
11:01:50|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=473
11:01:51|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=358
11:01:52|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=543
11:01:52|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=450
11:01:53|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=444
11:01:53|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=445
11:01:53|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=487
11:01:54|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=324
11:01:55|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=555
11:01:55|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=527
11:01:55|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=532
11:01:56|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=322
11:01:57|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=478
11:01:57|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=327
11:01:58|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=488
11:01:58|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=489
11:01:58|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=581
11:01:58|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=541
11:01:59|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=421
11:01:59|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=576
11:02:00|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=452
11:02:00|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=550
11:02:01|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=480
11:02:01|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=579
11:02:02|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=564
11:02:02|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=584
11:02:03|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=386
11:02:03|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=578
11:02:04|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=321
11:02:04|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=562
11:02:05|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=529
11:02:05|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=582
11:02:06|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=593
11:02:06|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=485
11:02:07|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=591
11:02:07|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=325
11:02:07|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=316
11:02:08|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=413
11:02:08|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=412
11:02:09|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=420
11:02:09|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=589
11:02:10|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=583
11:02:10|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=383
11:02:11|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=588
11:02:11|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=567
11:02:12|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=329
11:02:12|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=554
11:02:13|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=544
11:02:13|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=449
11:02:14|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=380
11:02:14|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=484
11:02:15|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=595
11:02:15|INFO Found movie id 595 in database via download_id
978dceaaad69327b6743edf67ccd549c009f34e0
11:02:15|DEBUG Looking for status of movie: 595 - with release sent to
clientAgent: transmission and download_id:
978dceaaad69327b6743edf67ccd549c009f34e0
11:02:15|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=595
11:02:16|DEBUG This movie is marked as status active in CouchPotatoServer
11:02:16|DEBUG Found a single release with download_id:
978dceaaad69327b6743edf67ccd549c009f34e0 for clientAgent: Transmission.
Release status is: snatched
11:02:16|INFO Waiting for 65.0 seconds to allow CPS to process newly
extracted files
11:03:21|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/renamer.scan/?movie_folder=/mnt/HD/HD_a2/Nfs/nfs/temporary/movies/TheNaked
Gun&downloader=Transmission&download_id=978dceaaad69327b6743edf67ccd549c009f34e0
11:03:21|INFO CouchPotatoServer returned {u'success': True}
11:03:21|INFO renamer scan started on CouchPotatoServer for The Naked
Gun.mkv
11:03:21|DEBUG Looking for status of movie: 595 - with release sent to
clientAgent: Transmission and download_id:
978dceaaad69327b6743edf67ccd549c009f34e0
11:03:21|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=595
11:03:21|DEBUG This movie is marked as status active in CouchPotatoServer
11:03:21|DEBUG Found a single release with download_id:
978dceaaad69327b6743edf67ccd549c009f34e0 for clientAgent: Transmission.
Release status is: snatched
11:03:41|DEBUG Looking for status of movie: 595 - with release sent to
clientAgent: Transmission and download_id:
978dceaaad69327b6743edf67ccd549c009f34e0
11:03:41|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=595
11:03:42|DEBUG This movie is marked as status active in CouchPotatoServer
11:03:42|DEBUG Found a single release with download_id:
978dceaaad69327b6743edf67ccd549c009f34e0 for clientAgent: Transmission.
Release status is: snatched
11:04:02|DEBUG Looking for status of movie: 595 - with release sent to
clientAgent: Transmission and download_id:
978dceaaad69327b6743edf67ccd549c009f34e0
11:04:02|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=595
11:04:03|DEBUG This movie is marked as status active in CouchPotatoServer
11:04:03|DEBUG Found a single release with download_id:
978dceaaad69327b6743edf67ccd549c009f34e0 for clientAgent: Transmission.
Release status is: snatched
11:04:23|DEBUG Looking for status of movie: 595 - with release sent to
clientAgent: Transmission and download_id:
978dceaaad69327b6743edf67ccd549c009f34e0
11:04:23|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=595
11:04:25|DEBUG This movie is marked as status active in CouchPotatoServer
11:04:25|DEBUG Found a single release with download_id:
978dceaaad69327b6743edf67ccd549c009f34e0 for clientAgent: Transmission.
Release status is: snatched
11:04:45|DEBUG Looking for status of movie: 595 - with release sent to
clientAgent: Transmission and download_id:
978dceaaad69327b6743edf67ccd549c009f34e0
11:04:45|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=595
11:04:46|DEBUG This movie is marked as status active in CouchPotatoServer
11:04:46|DEBUG Found a single release with download_id:
978dceaaad69327b6743edf67ccd549c009f34e0 for clientAgent: Transmission.
Release status is: snatched
11:05:06|DEBUG Looking for status of movie: 595 - with release sent to
clientAgent: Transmission and download_id:
978dceaaad69327b6743edf67ccd549c009f34e0
11:05:06|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=595
11:05:07|DEBUG This movie is marked as status active in CouchPotatoServer
11:05:07|DEBUG Found a single release with download_id:
978dceaaad69327b6743edf67ccd549c009f34e0 for clientAgent: Transmission.
Release status is: snatched
11:05:27|WARNING The movie does not appear to have changed status after 2
minutes. Please check CouchPotato Logs
11:05:27|INFO MAIN: A problem was reported in the autoProcess* script.
If torrent was pasued we will resume seeding
11:05:27|DEBUG MAIN: Starting torrent The Naked Gun.mkv in transmission
11:05:27|INFO MAIN: All done.

Thanks!

On 24 October 2013 12:52, Clinton Hall notifications@github.com wrote:

I am looking at the code in CouchPotato and might have a fix... but first
I need to see the postporcess.log from nzbToCouchPotato (for the above
error) to verify what was passed in.

�
Reply to this email directly or view it on GitHubhttps://github.com//issues/194#issuecomment-26985764
.

[clinton-hall]

CouchPotato/CouchPotatoServer#2392

If merged, this will be fixed in develop branch of CouchPotatoServer

On 24 Oct 2013, at 10:30 pm, "nikotime" notifications@github.com wrote:

This is the corresponding log:

11:00:24|INFO ====================
11:00:24|INFO TorrentToMedia V8.4
11:00:24|INFO Loading WakeOnLan config from
/mnt/HD/HD_a2/Transmission/Downloads/nzbToMedia-dev/autoProcessMedia.cfg
11:00:27|INFO Sending WakeOnLan Magic Packet for mac: 00:01:2e:BC:98:08
11:00:50|INFO Sending WakeOnLan Magic Packet for mac: 00:01:2e:BC:98:08
11:01:13|INFO Sending WakeOnLan Magic Packet for mac: 00:01:2e:BC:98:08
11:01:39|WARNING System with mac: 00:01:2e:BC:98:08 has not woken after 3
attempts. Continuing with the rest of the script.
11:01:39|INFO MAIN: Loading config from
/mnt/HD/HD_a2/Transmission/Downloads/nzbToMedia-dev/autoProcessMedia.cfg
11:01:39|DEBUG MAIN: Received Directory:
/mnt/HD/HD_a2/Transmission/Downloads/Seeding/movies | Name: The Naked
Gun.mkv | Category:
11:01:39|DEBUG SEARCH: Found Category: movies in directory structure
11:01:39|INFO SEARCH: Determined Category to be: movies
11:01:39|INFO SEARCH: Could not find a unique torrent folder in the
directory structure
11:01:39|INFO SEARCH: The directory passed is the root directory for
category movies
11:01:39|WARNING SEARCH: You should change settings to download torrents to
their own directory if possible
11:01:39|INFO SEARCH: We will try and determine which files to process,
individually
11:01:39|INFO MAIN: Download is not a directory
11:01:39|INFO MAIN: Output directory set to:
/mnt/HD/HD_a2/Nfs/nfs/temporary/movies/The Naked Gun
11:01:39|DEBUG MAIN: Scanning files in directory:
/mnt/HD/HD_a2/Transmission/Downloads/Seeding/movies
11:01:39|DEBUG MAIN: Looking for The Naked Gun.mkv in:
A.Fei.zheng.chuan.1990.720p.BluRay.DTS.x264-CtrlHD.unknown.ignore
11:01:39|DEBUG MAIN: Looking for The Naked Gun.mkv in:
DCOM.-.Zenon.The.Zequel.(2001).HR.DSR.XviD-LaR.avi
11:01:39|DEBUG MAIN: Looking for The Naked Gun.mkv in:
Super_Mario_Brothers.unknown.ignore
11:01:39|DEBUG MAIN: Looking for The Naked Gun.mkv in:
DCOM.-.Zenon.The.Zequel.(2001).HR.DSR.XviD-LaR.unknown.ignore
11:01:39|DEBUG MAIN: Looking for The Naked Gun.mkv in:
Hello.Dolly.1969.DVDRip.x264.AC3.mkv
11:01:39|DEBUG MAIN: Looking for The Naked Gun.mkv in: The Naked Gun.mkv
11:01:39|DEBUG MAIN: Found file The Naked Gun.mkv that matches Torrent
Name The Naked Gun.mkv
11:01:39|INFO MAIN: Found video file .mkv in
/mnt/HD/HD_a2/Transmission/Downloads/Seeding/movies/The Naked Gun.mkv
11:01:39|INFO CREATE DESTINATION: Creating destination folder:
/mnt/HD/HD_a2/Nfs/nfs/temporary/movies/The Naked Gun
11:01:39|INFO COPYLINK: Hard linking
/mnt/HD/HD_a2/Transmission/Downloads/Seeding/movies/The Naked Gun.mkv to
/mnt/HD/HD_a2/Nfs/nfs/temporary/movies/The Naked Gun/The Naked Gun.mkv
11:01:39|INFO FLATTEN: Flattening directory:
/mnt/HD/HD_a2/Nfs/nfs/temporary/movies/The Naked Gun
11:01:39|INFO REMOVER: Removing empty folders in:
/mnt/HD/HD_a2/Nfs/nfs/temporary/movies/The Naked Gun
11:01:39|DEBUG MAIN: Connecting to transmission: http://localhost:9091
11:01:40|DEBUG MAIN: Stoping torrent The Naked Gun.mkv in transmission
while processing
11:01:45|DEBUG MAIN: Calling autoProcess script for successful download.
11:01:45|INFO MAIN: Calling CouchPotatoServer to post-process: The Naked
Gun.mkv
11:01:45|INFO Loading config from
/mnt/HD/HD_a2/Transmission/Downloads/nzbToMedia-dev/autoProcessMedia.cfg
11:01:45|WARNING Could not find an imdb id in directory or name
11:01:45|INFO Postprocessing will continue, but the movie may not be
identified correctly by CouchPotato
11:01:45|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.list/?status=active
11:01:50|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=448
11:01:50|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=473
11:01:51|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=358
11:01:52|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=543
11:01:52|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=450
11:01:53|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=444
11:01:53|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=445
11:01:53|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=487
11:01:54|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=324
11:01:55|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=555
11:01:55|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=527
11:01:55|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=532
11:01:56|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=322
11:01:57|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=478
11:01:57|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=327
11:01:58|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=488
11:01:58|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=489
11:01:58|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=581
11:01:58|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=541
11:01:59|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=421
11:01:59|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=576
11:02:00|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=452
11:02:00|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=550
11:02:01|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=480
11:02:01|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=579
11:02:02|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=564
11:02:02|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=584
11:02:03|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=386
11:02:03|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=578
11:02:04|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=321
11:02:04|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=562
11:02:05|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=529
11:02:05|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=582
11:02:06|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=593
11:02:06|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=485
11:02:07|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=591
11:02:07|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=325
11:02:07|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=316
11:02:08|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=413
11:02:08|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=412
11:02:09|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=420
11:02:09|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=589
11:02:10|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=583
11:02:10|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=383
11:02:11|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=588
11:02:11|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=567
11:02:12|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=329
11:02:12|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=554
11:02:13|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=544
11:02:13|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=449
11:02:14|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=380
11:02:14|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=484
11:02:15|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=595
11:02:15|INFO Found movie id 595 in database via download_id
978dceaaad69327b6743edf67ccd549c009f34e0
11:02:15|DEBUG Looking for status of movie: 595 - with release sent to
clientAgent: transmission and download_id:
978dceaaad69327b6743edf67ccd549c009f34e0
11:02:15|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=595
11:02:16|DEBUG This movie is marked as status active in CouchPotatoServer
11:02:16|DEBUG Found a single release with download_id:
978dceaaad69327b6743edf67ccd549c009f34e0 for clientAgent: Transmission.
Release status is: snatched
11:02:16|INFO Waiting for 65.0 seconds to allow CPS to process newly
extracted files
11:03:21|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/renamer.scan/?movie_folder=/mnt/HD/HD_a2/Nfs/nfs/temporary/movies/TheNaked
Gun&downloader=Transmission&download_id=978dceaaad69327b6743edf67ccd549c009f34e0
11:03:21|INFO CouchPotatoServer returned {u'success': True}
11:03:21|INFO renamer scan started on CouchPotatoServer for The Naked
Gun.mkv
11:03:21|DEBUG Looking for status of movie: 595 - with release sent to
clientAgent: Transmission and download_id:
978dceaaad69327b6743edf67ccd549c009f34e0
11:03:21|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=595
11:03:21|DEBUG This movie is marked as status active in CouchPotatoServer
11:03:21|DEBUG Found a single release with download_id:
978dceaaad69327b6743edf67ccd549c009f34e0 for clientAgent: Transmission.
Release status is: snatched
11:03:41|DEBUG Looking for status of movie: 595 - with release sent to
clientAgent: Transmission and download_id:
978dceaaad69327b6743edf67ccd549c009f34e0
11:03:41|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=595
11:03:42|DEBUG This movie is marked as status active in CouchPotatoServer
11:03:42|DEBUG Found a single release with download_id:
978dceaaad69327b6743edf67ccd549c009f34e0 for clientAgent: Transmission.
Release status is: snatched
11:04:02|DEBUG Looking for status of movie: 595 - with release sent to
clientAgent: Transmission and download_id:
978dceaaad69327b6743edf67ccd549c009f34e0
11:04:02|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=595
11:04:03|DEBUG This movie is marked as status active in CouchPotatoServer
11:04:03|DEBUG Found a single release with download_id:
978dceaaad69327b6743edf67ccd549c009f34e0 for clientAgent: Transmission.
Release status is: snatched
11:04:23|DEBUG Looking for status of movie: 595 - with release sent to
clientAgent: Transmission and download_id:
978dceaaad69327b6743edf67ccd549c009f34e0
11:04:23|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=595
11:04:25|DEBUG This movie is marked as status active in CouchPotatoServer
11:04:25|DEBUG Found a single release with download_id:
978dceaaad69327b6743edf67ccd549c009f34e0 for clientAgent: Transmission.
Release status is: snatched
11:04:45|DEBUG Looking for status of movie: 595 - with release sent to
clientAgent: Transmission and download_id:
978dceaaad69327b6743edf67ccd549c009f34e0
11:04:45|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=595
11:04:46|DEBUG This movie is marked as status active in CouchPotatoServer
11:04:46|DEBUG Found a single release with download_id:
978dceaaad69327b6743edf67ccd549c009f34e0 for clientAgent: Transmission.
Release status is: snatched
11:05:06|DEBUG Looking for status of movie: 595 - with release sent to
clientAgent: Transmission and download_id:
978dceaaad69327b6743edf67ccd549c009f34e0
11:05:06|DEBUG Opening URL:
http://localhost:5050/api/590a95b4e27b45ecaf94f2e074e8c8e6/movie.get/?id=595
11:05:07|DEBUG This movie is marked as status active in CouchPotatoServer
11:05:07|DEBUG Found a single release with download_id:
978dceaaad69327b6743edf67ccd549c009f34e0 for clientAgent: Transmission.
Release status is: snatched
11:05:27|WARNING The movie does not appear to have changed status after 2
minutes. Please check CouchPotato Logs
11:05:27|INFO MAIN: A problem was reported in the autoProcess* script.
If torrent was pasued we will resume seeding
11:05:27|DEBUG MAIN: Starting torrent The Naked Gun.mkv in transmission
11:05:27|INFO MAIN: All done.

Thanks!

On 24 October 2013 12:52, Clinton Hall notifications@github.com wrote:

I am looking at the code in CouchPotato and might have a fix... but first
I need to see the postporcess.log from nzbToCouchPotato (for the above
error) to verify what was passed in.

�
Reply to this email directly or view it on GitHubhttps://github.com//issues/194#issuecomment-26985764
.

—
Reply to this email directly or view it on GitHub.

[nikotime]

Thanks a lot clinton!

[josh4trunks]

I think this is affecting me as well. So fix in Couchpotato should eventually fix this?

[clinton-hall]

Already fixed in develop if you want to switch to develop branch of CouchPotato. Otherwise this will be fixed when develop is next merged into master.

[clinton-hall]

The transmission RPC issues you reported is very different. The issue you reported there prevented the script from even trying to start the renamer...

The reason I suggested changing the folder structure is that in the particular case you posted, the hard linking failed as the destination appeared to exist, where in fact it didn't. I believe this is due to the sub-directory and file having the same name which... Still not sure exactly how the script failed as the specific comment for "destination file already exists" should only happen if both the sub-directory and the file do infant exist... So I am confused as to what happened.

In general, this script was designed to hard link to a new location and then postprocess this location while leaving the original files alone so that they can be seeded. Therefore a separate outputDirectory is the most reliable option to use with this script.

The issue reported in this thread, that is fixed in CouchPotato develop branch, is an error with the renamer in CouchPotato.

[josh4trunks]

Yeah, looking at the files I'm getting something weird where I seem to have an avi in the folder, and outside of the folder. I believe these are hard links cause du -hs and ls -l are all giving me weird stuff. I'll need to investigate more but it may be a problem with these specific torrents.