Starting about 3 months ago I am getting errors in post processing script for extracted subtitle files. The post processing was failing overall due to being unable to locate the subtitle (SRT) file. It only seemed to affect NZB's that contained a subtitle (SRT) file in the archive, and not those that did not / were manually downloaded later.
Pre-error log
Code: Select all
Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7
[08:36:51] [INFO]::MAIN: Loading config from [/etc/sabnzbd/scripts/nzbToMedia/autoProcessMedia.cfg]
[08:36:51] [INFO]::MAIN: Checking database structure...
[08:36:51] [DEBUG]::MAIN: Checking Initial Schema database upgrade
[08:36:51] [DEBUG]::MAIN: InitialSchema upgrade not required
[08:36:51] [DEBUG]::MAIN: Checking if we can use git commands: "/usr/bin/git" version
[08:36:51] [DEBUG]::MAIN: Executing "/usr/bin/git" version with your shell in /etc/sabnzbd/scripts/nzbToMedia
[08:36:51] [DEBUG]::MAIN: git output: git version 2.10.2
[08:36:51] [DEBUG]::MAIN: "/usr/bin/git" version : returned successful
[08:36:51] [DEBUG]::MAIN: Using: "/usr/bin/git"
[08:36:51] [DEBUG]::MAIN: Executing "/usr/bin/git" symbolic-ref -q HEAD with your shell in /etc/sabnzbd/scripts/nzbToMedia
[08:36:51] [DEBUG]::MAIN: git output: refs/heads/nightly
[08:36:51] [DEBUG]::MAIN: "/usr/bin/git" symbolic-ref -q HEAD : returned successful
[08:36:51] [INFO]::MAIN: Checking if git needs an update
[08:36:51] [DEBUG]::MAIN: Executing "/usr/bin/git" rev-parse HEAD with your shell in /etc/sabnzbd/scripts/nzbToMedia
[08:36:51] [DEBUG]::MAIN: git output: 80c8ad58523ab99825c02f3855f9bd3dc9945d57
[08:36:51] [DEBUG]::MAIN: "/usr/bin/git" rev-parse HEAD : returned successful
[08:36:51] [DEBUG]::MAIN: Executing "/usr/bin/git" fetch origin with your shell in /etc/sabnzbd/scripts/nzbToMedia
[08:36:52] [DEBUG]::MAIN: git output:
[08:36:52] [DEBUG]::MAIN: "/usr/bin/git" fetch origin : returned successful
[08:36:52] [DEBUG]::MAIN: Executing "/usr/bin/git" rev-parse --verify --quiet "@{upstream}" with your shell in /etc/sabnzbd/scripts/nzbToMedia
[08:36:52] [DEBUG]::MAIN: git output: 80c8ad58523ab99825c02f3855f9bd3dc9945d57
[08:36:52] [DEBUG]::MAIN: "/usr/bin/git" rev-parse --verify --quiet "@{upstream}" : returned successful
[08:36:52] [DEBUG]::MAIN: Executing "/usr/bin/git" rev-list --left-right "@{upstream}"...HEAD with your shell in /etc/sabnzbd/scripts/nzbToMedia
[08:36:52] [DEBUG]::MAIN: git output:
[08:36:52] [DEBUG]::MAIN: "/usr/bin/git" rev-list --left-right "@{upstream}"...HEAD : returned successful
[08:36:52] [DEBUG]::MAIN: cur_commit = 80c8ad58523ab99825c02f3855f9bd3dc9945d57 % (newest_commit)= 80c8ad58523ab99825c02f3855f9bd3dc9945d57, num_commits_behind = 0, num_commits_ahead = 0
[08:36:52] [INFO]::MAIN: No update needed
[08:36:52] [INFO]::MAIN: nzbToMedia Version:80c8ad58523ab99825c02f3855f9bd3dc9945d57 Branch:nightly (Linux 4.4.6-gentoo)
[08:36:52] [INFO]::MAIN: #########################################################
[08:36:52] [INFO]::MAIN: ## ..::[nzbToMedia.pyc]::.. ##
[08:36:52] [INFO]::MAIN: #########################################################
[08:36:52] [DEBUG]::MAIN: Options passed into nzbToMedia: ['/etc/sabnzbd/scripts/nzbToMedia/nzbToSickBeard.py', '/storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7', 'Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.nzb', 'Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7', '', 'tv', 'alt.binaries.warez', '0', 'https://dognzb.cr/fail/2989612eda4a99c345789e62deeab12d/f97ada473c5cea9fc19eb1db3e204e62']
[08:36:52] [INFO]::MAIN: Script triggered from SABnzbd 0.7.17+
[08:36:52] [DEBUG]::MAIN: Searching for nzoid from SAbnzbd ...
[08:36:52] [DEBUG]::MAIN: Adding NZB download info for directory /storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7 to database
[08:36:52] [INFO]::MAIN: Auto-detected SECTION:SickBeard
[08:36:52] [DEBUG]::MAIN: Checking for archives to extract in directory: /storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7
[08:36:52] [INFO]::MAIN: Calling SickBeard:tv to post-process:Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.nzb
[08:36:52] [DEBUG]::SERVER: Attempting to connect to server at http://localhost:8081
[08:36:52] [DEBUG]::SERVER: Server responded at http://localhost:8081
[08:36:52] [INFO]::MAIN: Attempting to auto-detect tv fork
[08:36:53] [INFO]::MAIN: SickBeard:tv fork auto-detection successful ...
[08:36:53] [INFO]::MAIN: SickBeard:tv fork set to sickrage
[08:36:53] [INFO]::MAIN: FLATTEN: Flattening directory: /storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7
[08:36:53] [DEBUG]::MAIN: Checking for empty folders in:/storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7
[08:36:53] [DEBUG]::MAIN: calling command: /usr/bin/ffprobe -v quiet -print_format json -show_format -show_streams -show_error /etc/sabnzbd/scripts/nzbToMedia/tests/test.mp4
[08:36:53] [INFO]::TRANSCODER: Checking [Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.mkv] for corruption, please stand by ...
[08:36:53] [DEBUG]::MAIN: calling command: /usr/bin/ffprobe -v quiet -print_format json -show_format -show_streams -show_error /storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7/Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.mkv
[08:36:53] [INFO]::TRANSCODER: SUCCESS: [Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.mkv] has no corruption.
[08:36:53] [DEBUG]::SUBTITLES: Attempting to download subtitles for /storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7/Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.mkv
[08:37:01] [POSTPROCESS]::SICKBEARD: SUCCESS: The download succeeded, sending a post-process request
[08:37:01] [DEBUG]::SICKBEARD: Opening URL: http://localhost:8081/home/postprocess/processEpisode with params: {'nzbName': 'Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.nzb', 'quiet': 1, 'failed': '0', 'force_next': 1, 'proc_dir': '/storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7', 'delete_on': 1}
[08:37:18] [POSTPROCESS]::SICKBEARD: Processing in folder /storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7
[08:37:18] [POSTPROCESS]::SICKBEARD: Processing /storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7
[08:37:18] [POSTPROCESS]::SICKBEARD: Processing folder /storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7
[08:37:18] [POSTPROCESS]::SICKBEARD: Processing /storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7/Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.mkv (Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.nzb)
[08:37:18] [POSTPROCESS]::SICKBEARD: Retrieving episode object for 3x3
[08:37:18] [POSTPROCESS]::SICKBEARD: Snatch history had a quality in it, using that: 720p WEB-DL
[08:37:18] [POSTPROCESS]::SICKBEARD: SR snatched this episode and it is not processed before
[08:37:18] [POSTPROCESS]::SICKBEARD: Is ep a priority download: True
[08:37:18] [POSTPROCESS]::SICKBEARD: There is no existing file so there's no worries about replacing it
[08:37:18] [POSTPROCESS]::SICKBEARD: This download is marked a priority download so I'm going to replace an existing file if I find one
[08:37:18] [POSTPROCESS]::SICKBEARD: Found release name Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7
[08:37:18] [POSTPROCESS]::SICKBEARD: Destination folder for this episode: /Media/TV/Deadbeat/Season 03
[08:37:18] [POSTPROCESS]::SICKBEARD: Found the following associated files for /storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7/Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.mkv: [u'/storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7/Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.en.srt']
[08:37:18] [POSTPROCESS]::SICKBEARD: Allowing associated files for /storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7/Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.mkv: [u'/storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7/Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.en.srt']
[08:37:18] [POSTPROCESS]::SICKBEARD: Copying file from /storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7/Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.mkv to /Media/TV/Deadbeat/Season 03/Deadbeat - S03E03 - Bong Pong.mkv
Code: Select all
[08:37:18] [POSTPROCESS]::SICKBEARD: Copying file from /storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7/Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.en.srt to /Media/TV/Deadbeat/Season 03/Deadbeat - S03E03 - Bong Pong.en.srt
[08:37:18] [POSTPROCESS]::SICKBEARD: Unable to copy file /storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7/Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.en.srt to /Media/TV/Deadbeat/Season 03/Deadbeat - S03E03 - Bong Pong.en.srt: error 2 : No such file or directory
[08:37:18] [POSTPROCESS]::SICKBEARD: Processing failed for /storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7/Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.mkv: Unable to move the files to their new home
[08:37:18] [POSTPROCESS]::SICKBEARD: Processing Failed
Code: Select all
[08:37:18] [POSTPROCESS]::SICKBEARD: Some items were not processed.
[08:37:18] [POSTPROCESS]::SICKBEARD: /storage/sabnzbd/complete/TV/Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7/Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.mkv : Processing failed: Unable to move the files to their new home
[08:37:18] [ERROR]::MAIN: A problem was reported in the /etc/sabnzbd/scripts/nzbToMedia/nzbToSickBeard.py script.
SickBeard: Failed to post-process - Returned log from SickBeard was not as expected.!
Looking into it further by trying to copy the file manually as the sickrage user, I received an error that the file did not exist. Checking the directory again with root found inconsistent file permissions:
Code: Select all
# ls -ltrah
total 3.1G
-rw-rw-r-- 1 sabnzbd sabnzbd 3.1G Jun 8 03:33 Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7/Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.mkv
-rw-rw-r-- 1 sabnzbd sabnzbd 46 Jun 12 13:03 fHl1nJUY7jzDUpYL984fLCk
-rw------- 1 sabnzbd sabnzbd 29K Jun 12 13:04 Deadbeat S03E03 Bong Pong 720p WEB-DL DD5 1 H 264-Coo7/Deadbeat.S03E03.Bong.Pong.720p.WEB-DL.DD5.1.H.264-Coo7.en.srt
drwxrwxr-x 2 sabnzbd sabnzbd 4.0K Jun 12 13:04 .
drwxrwxrwx 33 sabnzbd sabnzbd 4.0K Jun 13 15:57 ..
Double checking my settings, I can see SabNZBD folder permissions set to 0755
Code: Select all
# cat /etc/sabnzbd/sabnzbd.ini | grep permissions
permissions = 0775
This is leading me to believe that any archive that contains a subtitle (SRT) file will fail with this error. I have attempted a workaround by excluding (deleting) srt files before the post processing script is run, which does work, but I would ultimately like to correct the issue properly.
I had a feeling it was due to an update as it was working fine, only started giving errors the past few months. But with each passing update and the issue remaining, I am starting to believe I may have done something wrong here. But I've already double checked the permissions in SabNZBD, where else can I look / where else would SAB be setting permissions for subtitles differently than the main folder permission structure? I am lost at this point and any help would be appreciated
Version: 2.1.0
OS: Gentoo Linux 4.4.6
Install-type: Linux Source
Skin (if applicable): Default
Firewall Software: None
Are you using IPV6? No
Is the issue reproducible? Yes