I've noticed a few write errors to my destination drive, which is an internal hard drive with 500GB+ free on it. Today I watched while one happened, and think I might have found the problem. Here are the log entries from around the time the movie finished and processed:
Code: Select all
2014-04-15 12:31:52,351::INFO::[newsunpack:427] Extracting rarfile d:\downloads\incomplete\The.Hobbit.The.Desolation.Of.Smaug.2013.1080p.BluRay.AC3..DTS-HD.MA.7.1. jackane.cp(tt1170358)\DX9w08wTZrmq5tq3BOoP2F41347JE0f.part01.rar (belonging to DX9w08wTZrmq5tq3BOoP2F41347JE0f) to d:\downloads\complete\Movies\The Hobbit The Desolation Of Smaug (2013)\_UNPACK_The.Hobbit.The.Desolation.Of.Smaug.2013.1080p.BluRay.AC3..DTS-HD.MA.7.1. jackane.cp(tt1170358)
2014-04-15 12:31:52,351::DEBUG::[newsunpack:530] rar_extract(): Extractionpath: d:\downloads\complete\Movies\The Hobbit The Desolation Of Smaug (2013)\_UNPACK_The.Hobbit.The.Desolation.Of.Smaug.2013.1080p.BluRay.AC3..DTS-HD.MA.7.1. jackane.cp(tt1170358)
2014-04-15 12:31:52,426::DEBUG::[newsunpack:575] Running unrar ['C:\\Program Files (x86)\\SABnzbd\\win\\unrar\\x64\\UnRAR.exe', 'x', '-idp', '-o-', '-or', '-ai', '-p-', 'd:\\downloads\\incomplete\\The.Hobbit.The.Desolation.Of.Smaug.2013.1080p.BluRay.AC3..DTS-HD.MA.7.1. jackane.cp(tt1170358)\\DX9w08wTZrmq5tq3BOoP2F41347JE0f.part01.rar', 'd:\\downloads\\complete\\Movies\\The Hobbit The Desolation Of Smaug (2013)\\_UNPACK_The.Hobbit.The.Desolation.Of.Smaug.2013.1080p.BluRay.AC3..DTS-HD.MA.7.1. jackane.cp(tt1170358)/']
2014-04-15 12:31:52,734::DEBUG::[growler:171] Cannot register with Growl (10061, 'Connection refused')
2014-04-15 12:31:52,858::ERROR::[newsunpack:638] ERROR: write error ( d:\downloads\complete\Movies\The Hobbit The Desolation Of Smaug (2013)\_UNPACK_The.Hobbit.The.Desolation.Of.Smaug.2013.1080p.BluRay.AC3..DTS-HD.MA.7.1. jackane.cp(tt1170358)/DX9w08wTZrmq5tq3BOoP2F41347JE0f\DX9w08wTZrmq5tq3BOoP2F41347JE0f\enakcaj .1.7.AM.DH-STD.&.3CA.yaRulB.p0801.3102.guamS.fO.noitaloseD.ehT.tibboH.ehT.mkv)
2014-04-15 12:31:52,872::INFO::[newsunpack:215] Unrar finished on d:\downloads\incomplete\The.Hobbit.The.Desolation.Of.Smaug.2013.1080p.BluRay.AC3..DTS-HD.MA.7.1. jackane.cp(tt1170358)
2014-04-15 12:31:52,874::DEBUG::[newsunpack:1408] build_filelists(): joinables: []
2014-04-15 12:31:52,874::DEBUG::[newsunpack:1409] build_filelists(): zips: []
2014-04-15 12:31:52,874::DEBUG::[newsunpack:1410] build_filelists(): rars: []
2014-04-15 12:31:52,874::DEBUG::[newsunpack:1411] build_filelists(): ts: []
2014-04-15 12:31:52,874::INFO::[postproc:348] unpack_magic finished on The.Hobbit.The.Desolation.Of.Smaug.2013.1080p.BluRay.AC3..DTS-HD.MA.7.1. jackane.cp(tt1170358)
2014-04-15 12:31:52,875::DEBUG::[notifier:112] Sending registration to localhost:23053
2014-04-15 12:31:52,875::DEBUG::[notifier:102] Checking icon
2014-04-15 12:31:52,875::DEBUG::[notifier:219] To : localhost:23053 <<class 'gntp.GNTPRegister'>>
2014-04-15 12:31:53,391::INFO::[postproc:537] Cleaning up The.Hobbit.The.Desolation.Of.Smaug.2013.1080p.BluRay.AC3..DTS-HD.MA.7.1. jackane.cp(tt1170358) (keep_basic=True)
In any event, the real problem is after failing to unrar due to folder length, sabnzbd proceeds with folder cleanup as if it were successful. I had the download folder open and watched as the files disappeared. Before I could react, the files were gone. As far as I can tell my only option now is to re-download the title because I can't find the deleted files. Back in the DOS days I used to use undelete a lot but I'm not sure if that's even an option any more In case it's relevant, I'm also using CouchPotato. It had a different nzb queued up seconds after the previous one failed. I realize now I've been re-downloading titles more than once due to this error but don't notice it unless I check the downloads the day they finish.
I will reduce my folder size limit to try to avoid the problem, but I think there's a potential bug in the error handling where sabnzbd applies post processing cleanup after a failed unpack. Please let me know if you need more information.
Thanks.