Page 1 of 1

Downloads failing to move after upgrade to 1.0

Posted: April 20th, 2016, 8:28 pm
by wik_sammamish
Since upgrading from 0.7 to 1.0 all of my downloads will complete and unzip, but it looks like once it tries to move the file to the destination directory something goes wrong. Sabnzbd is installed on a windows VM running on my unraid server. I have all of my complete and incomplete directories using the main unraid share instead of the VM drive because it kept filling it up. the Z drive is a mapped network drive in windows. All of this had been trucking along for about a year prior to the upgrade. I have tried this with 4 different nzb files so I dont think its specific to a single file. Any help is appreciated!

Code: Select all

2016-04-19 23:25:43,631::INFO::[newsunpack:233] Unrar finished on Z:\sabznbd\PJO5B6~Z
2016-04-19 23:25:43,634::INFO::[postproc:357] unpack_magic finished on THING
2016-04-19 23:25:43,684::ERROR::[misc:844] Failed moving Z:\sabznbd\THING\THING.par2 to z:\tvshows\_UNPACK_THING\THING.par2
2016-04-19 23:25:43,684::INFO::[misc:845] Traceback: 
Traceback (most recent call last):
  File "sabnzbd\misc.pyo", line 840, in move_to_path
  File "shutil.pyo", line 82, in copyfile
IOError: [Errno 2] No such file or directory: u'\\\\?\\Z:\\sabznbd\\THING\\THING.par2'
2016-04-19 23:25:44,305::INFO::[postproc:550] Cleaning up THING (keep_basic=True)

Version: 1.0
OS: Windows XP SP2 in a VM
Install-type: Windows Installer
Skin (if applicable): Glitter - Night
Firewall Software: XP SP2 Firewall
Are you using IPV6? no
Is the issue reproducible? Yes

EDIT: Specific download names changes to "THING" I can pm logs and nzbs if needed.

Re: Downloads failing to move after upgrade to 1.0

Posted: April 21st, 2016, 4:06 pm
by shypike
The odd paths that you see are the Windows way to say you want to be able to use long paths.
It it perfectly normal and works fine on Windows.
What we haven't been able to test whether it works with all
the mountable file systems in the world, like unraid servers.
It also depends on whether unraid supports short MSDOS-style paths.
We require those to let some old-style tools (par2 in specific) deal with long paths.
Also we no longer put any effort in Windows XP.

In general we recommend not to use server-based storage for "incomplete".

Re: Downloads failing to move after upgrade to 1.0

Posted: April 21st, 2016, 5:10 pm
by wik_sammamish
Ah, I misspoke when I said it was a XP vm, its actually win7. Im not sure why I forgot that. Now that im reading through the faq I see that having incomplete on a network share isn't recommended, but it worked fine with 0.7 for a year so now I dont know what to do. Does the structure of the remote file system matter if its mapped to a network drive? I would assume that windows would just treat it like any normal drive.

I will play with it some more but I guess I will downgrade to 0.7 if I cant get it figured out.

Re: Downloads failing to move after upgrade to 1.0

Posted: April 22nd, 2016, 7:30 am
by shypike
Having a mounted drive for "incomplete" and "admin" should not matter.
Only it does sometimes.
I have no good explanation for this, but it seems that sometimes remote drives
aren't really 100% transparent.
One major change is that we now use paths in "Unicode/long-path" encoding
and revert to MSDOS 8.3 shorthand names, when needed.
Possibly this doesn't always work on remote drives.

One check you can do is this. Run this command from a CMD.EXE prompt:

Code: Select all

dir /x Z:\
where Z is your remote drive letter.
This should show double names, like this:

Code: Select all

17/03/2016  17:02            15,991 PROJEC~1.JSO projects.json

Re: Downloads failing to move after upgrade to 1.0

Posted: April 23rd, 2016, 11:33 am
by wik_sammamish
I just tried the "dir /x Z:\" commands and it is showing double file names like you were talking about. Oddly enough i left ver 1.0 running on the VM and three things attempted to download since my last reply. Two of them completed and one got the failed moving error. I'm not sure if there is a certain character that is causing the move fail or what.

Re: Downloads failing to move after upgrade to 1.0

Posted: April 23rd, 2016, 2:42 pm
by sander
Can you feed this NZB into SABnzbd: https://nzbindex.com/search/?q=Special- ... an-braille and let it download (so: to your Z: drive)
... and let us know what happens.

Re: Downloads failing to move after upgrade to 1.0

Posted: April 23rd, 2016, 7:43 pm
by wik_sammamish
I tried it twice and it failed to move both times. It appears to be downloading and unzipping properly though. Here is a log from the last time it tried.

Code: Select all

2016-04-23 19:08:27,717::DEBUG::[interface:458] API-call from 192.168.1.71 [CouchPotato linux - git-c4fad95c - v2] {'output': u'json', 'apikey': u'MYAPIKEY', 'mode': u'queue'}
2016-04-23 19:08:27,743::DEBUG::[interface:458] API-call from 192.168.1.71 [CouchPotato linux - git-c4fad95c - v2] {'output': u'json', 'apikey': u'MYAPIKEY', 'limit': u'15', 'mode': u'history'}
2016-04-23 19:09:27,240::DEBUG::[interface:458] API-call from 192.168.1.71 [CouchPotato linux - git-c4fad95c - v2] {'output': u'json', 'apikey': u'MYAPIKEY', 'mode': u'queue'}
2016-04-23 19:09:27,263::DEBUG::[interface:458] API-call from 192.168.1.71 [CouchPotato linux - git-c4fad95c - v2] {'output': u'json', 'apikey': u'MYAPIKEY', 'limit': u'15', 'mode': u'history'}
2016-04-23 19:10:26,641::DEBUG::[interface:458] API-call from 192.168.1.71 [CouchPotato linux - git-c4fad95c - v2] {'output': u'json', 'apikey': u'MYAPIKEY', 'mode': u'queue'}
2016-04-23 19:10:26,657::DEBUG::[interface:458] API-call from 192.168.1.71 [CouchPotato linux - git-c4fad95c - v2] {'output': u'json', 'apikey': u'MYAPIKEY', 'limit': u'15', 'mode': u'history'}
2016-04-23 19:11:26,223::DEBUG::[interface:458] API-call from 192.168.1.71 [CouchPotato linux - git-c4fad95c - v2] {'output': u'json', 'apikey': u'MYAPIKEY', 'mode': u'queue'}
2016-04-23 19:11:26,247::DEBUG::[interface:458] API-call from 192.168.1.71 [CouchPotato linux - git-c4fad95c - v2] {'output': u'json', 'apikey': u'MYAPIKEY', 'limit': u'15', 'mode': u'history'}
2016-04-23 19:11:39,240::INFO::[__init__:646] Adding Special-Characters--german-korean-braille.rar.nzb
2016-04-23 19:11:39,246::INFO::[misc:806] Creating directories: \\?\Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra
2016-04-23 19:11:39,269::DEBUG::[__init__:867] Saving data for SABnzbd_nzf_unbxnk in \\?\Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra\__ADMIN__
2016-04-23 19:11:39,273::INFO::[nzbstuff:479] File Special-Characters--german-korean-braille 2016-04-18 - "Special-Characters--german-korean-braille.rar" yEnc (1/10) added to queue
2016-04-23 19:11:39,273::DEBUG::[nzbstuff:509] META-DATA = {}
2016-04-23 19:11:39,305::INFO::[__init__:589] Backing up \\?\Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra\__ADMIN__\Special-Characters--german-korean-braille.rar.nzb.gz
2016-04-23 19:11:39,334::INFO::[nzbqueue:250] Saving queue
2016-04-23 19:11:39,335::DEBUG::[__init__:867] Saving data for SABnzbd_nzo_micx7h in \\?\Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra\__ADMIN__
2016-04-23 19:11:39,346::DEBUG::[__init__:867] Saving data for SABnzbd_nzo_micx7h in \\?\Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra\__ADMIN__
2016-04-23 19:11:39,354::INFO::[__init__:941] Saving data for queue10.sab in C:\Users\MYNAME\AppData\Local\sabnzbd\admin\queue10.sab
2016-04-23 19:11:40,355::DEBUG::[nzbstuff:243] Finishing import on Special-Characters--german-korean-braille 2016-04-18 - "Special-Characters--german-korean-braille.rar" yEnc (1/10)
2016-04-23 19:11:40,369::DEBUG::[__init__:901] Loading data for SABnzbd_nzf_unbxnk from \\?\Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra\__ADMIN__\SABnzbd_nzf_unbxnk
2016-04-23 19:11:40,385::INFO::[downloader:444] 3@news.USENETSERVER.com: Initiating connection
2016-04-23 19:11:41,701::INFO::[downloader:637] Connecting 3@news.USENETSERVER.com finished
2016-04-23 19:11:43,578::DEBUG::[decoder:103] Decoding ABbRy.1339753$cz.1012040@fx39.am4
2016-04-23 19:11:44,578::INFO::[downloader:444] 7@news.USENETSERVER.com: Initiating connection
2016-04-23 19:11:44,579::INFO::[downloader:444] 16@news.USENETSERVER.com: Initiating connection
2016-04-23 19:11:44,579::INFO::[downloader:444] 5@news.USENETSERVER.com: Initiating connection
2016-04-23 19:11:44,581::INFO::[downloader:444] 2@news.USENETSERVER.com: Initiating connection
2016-04-23 19:11:44,582::INFO::[downloader:444] 14@news.USENETSERVER.com: Initiating connection
2016-04-23 19:11:44,582::INFO::[downloader:444] 1@news.USENETSERVER.com: Initiating connection
2016-04-23 19:11:44,582::INFO::[downloader:444] 12@news.USENETSERVER.com: Initiating connection
2016-04-23 19:11:44,584::INFO::[downloader:444] 11@news.USENETSERVER.com: Initiating connection
2016-04-23 19:11:44,585::INFO::[downloader:444] 19@news.USENETSERVER.com: Initiating connection
2016-04-23 19:11:45,915::INFO::[downloader:637] Connecting 1@news.USENETSERVER.com finished
2016-04-23 19:11:45,915::INFO::[downloader:637] Connecting 14@news.USENETSERVER.com finished
2016-04-23 19:11:45,927::INFO::[downloader:637] Connecting 5@news.USENETSERVER.com finished
2016-04-23 19:11:45,927::INFO::[downloader:637] Connecting 11@news.USENETSERVER.com finished
2016-04-23 19:11:45,961::INFO::[downloader:637] Connecting 12@news.USENETSERVER.com finished
2016-04-23 19:11:46,234::INFO::[downloader:637] Connecting 2@news.USENETSERVER.com finished
2016-04-23 19:11:46,359::INFO::[downloader:637] Connecting 7@news.USENETSERVER.com finished
2016-04-23 19:11:46,359::INFO::[downloader:637] Connecting 16@news.USENETSERVER.com finished
2016-04-23 19:11:46,415::DEBUG::[bpsmeter:271] bps: 69764.5682189
2016-04-23 19:11:47,292::INFO::[downloader:637] Connecting 19@news.USENETSERVER.com finished
2016-04-23 19:11:47,313::DEBUG::[decoder:103] Decoding FBbRy.1339760$cz.990780@fx39.am4
2016-04-23 19:11:47,470::DEBUG::[decoder:103] Decoding EBbRy.1339759$cz.1233390@fx39.am4
2016-04-23 19:11:47,562::DEBUG::[decoder:103] Decoding HBbRy.1339762$cz.1229570@fx39.am4
2016-04-23 19:11:47,737::DEBUG::[decoder:103] Decoding GBbRy.1339761$cz.1071140@fx39.am4
2016-04-23 19:11:47,775::DEBUG::[decoder:103] Decoding DBbRy.1339757$cz.634735@fx39.am4
2016-04-23 19:11:48,036::DEBUG::[decoder:103] Decoding DBbRy.1339758$cz.385732@fx39.am4
2016-04-23 19:11:49,338::DEBUG::[decoder:103] Decoding HBbRy.1339763$cz.463693@fx39.am4
2016-04-23 19:11:52,345::DEBUG::[bpsmeter:271] bps: 213415.748054
2016-04-23 19:11:53,641::DEBUG::[decoder:103] Decoding BBbRy.1339755$cz.716706@fx39.am4
2016-04-23 19:11:55,589::DEBUG::[decoder:103] Decoding CBbRy.1339756$cz.778763@fx39.am4
2016-04-23 19:11:55,596::DEBUG::[__init__:867] Saving data for SABnzbd_nzo_micx7h in \\?\Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra\__ADMIN__
2016-04-23 19:11:55,607::INFO::[__init__:941] Saving data for totals10.sab in C:\Users\MYNAME\AppData\Local\sabnzbd\admin\totals10.sab
2016-04-23 19:11:55,611::INFO::[assembler:88] Decoding \\?\Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra\Special-Characters--german-korean-braille.rar yenc
2016-04-23 19:11:55,671::DEBUG::[assembler:366] file in rar file: Braille-ÔíÁÔíÂÔíÀÔí©Ôí╣Ôí║-signs.bin
2016-04-23 19:11:55,671::DEBUG::[assembler:366] file in rar file: Deutsch-ist-gefühlsmäßig.bin
2016-04-23 19:11:55,673::DEBUG::[assembler:366] file in rar file: Korean-ýòêÙàòÝòÿýä©ýÜöýä©Û│ä-stuff.bin
2016-04-23 19:11:55,686::INFO::[__init__:931] \\?\Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra\__ADMIN__\SABnzbd_nzo_micx7h removed
2016-04-23 19:11:55,686::INFO::[nzbqueue:250] Saving queue
2016-04-23 19:11:55,686::INFO::[__init__:941] Saving data for queue10.sab in C:\Users\MYNAME\AppData\Local\sabnzbd\admin\queue10.sab
2016-04-23 19:11:55,686::INFO::[postproc:83] Saving postproc queue
2016-04-23 19:11:55,687::INFO::[__init__:941] Saving data for postproc2.sab in C:\Users\MYNAME\AppData\Local\sabnzbd\admin\postproc2.sab
2016-04-23 19:11:55,700::INFO::[postproc:280] Starting PostProcessing on Special-Characters--german-korean-braille.rar => Repair:True, Unpack:True, Delete:True, Script:None, Cat:*
2016-04-23 19:11:55,700::INFO::[postproc:578] Par2 check starting on Special-Characters--german-korean-braille.rar
2016-04-23 19:11:55,703::INFO::[__init__:897] \\?\Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra\__ADMIN__\__verified__ missing
2016-04-23 19:11:55,703::INFO::[postproc:611] No par2 sets for Special-Characters--german-korean-braille.rar
2016-04-23 19:11:55,707::DEBUG::[__init__:867] Saving data for __verified__ in \\?\Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra\__ADMIN__
2016-04-23 19:11:55,714::INFO::[postproc:626] Par2 check finished on Special-Characters--german-korean-braille.rar
2016-04-23 19:11:55,716::INFO::[misc:806] Creating directories: \\?\Z:\sabznbd\Complete\Special-Characters--german-korean-braille.rar
2016-04-23 19:11:55,720::DEBUG::[misc:1224] Renaming "\\?\Z:\sabznbd\Complete\Special-Characters--german-korean-braille.rar" to "\\?\Z:\sabznbd\Complete\_UNPACK_Special-Characters--german-korean-braille.rar"
2016-04-23 19:11:55,726::INFO::[postproc:352] Running unpack_magic on Special-Characters--german-korean-braille.rar
2016-04-23 19:11:55,762::DEBUG::[newsunpack:1641] build_filelists(): joinables: []
2016-04-23 19:11:55,762::DEBUG::[newsunpack:1642] build_filelists(): zips: []
2016-04-23 19:11:55,762::DEBUG::[newsunpack:1643] build_filelists(): rars: [u'Z:\\sabznbd\\ISENQ5~5\\S4L20N~0.RA\\Special-Characters--german-korean-braille.rar']
2016-04-23 19:11:55,762::DEBUG::[newsunpack:1644] build_filelists(): 7zips: []
2016-04-23 19:11:55,762::DEBUG::[newsunpack:1645] build_filelists(): ts: []
2016-04-23 19:11:55,762::INFO::[newsunpack:229] Unrar starting on Z:\sabznbd\ISENQ5~5\S4L20N~0.RA
2016-04-23 19:11:55,762::DEBUG::[newsunpack:436] Rar_sets: {u'Special-Characters--german-korean-braille': [u'Z:\\sabznbd\\ISENQ5~5\\S4L20N~0.RA\\Special-Characters--german-korean-braille.rar']}
2016-04-23 19:11:55,763::INFO::[newsunpack:450] Extracting rarfile Z:\sabznbd\ISENQ5~5\S4L20N~0.RA\Special-Characters--german-korean-braille.rar (belonging to Special-Characters--german-korean-braille) to Z:\sabznbd\Complete\_QOQXI~W.RAR
2016-04-23 19:11:55,763::DEBUG::[newsunpack:556] rar_extract(): Extractionpath: Z:\sabznbd\Complete\_QOQXI~W.RAR
2016-04-23 19:11:55,769::DEBUG::[newsunpack:601] Analyzing rar file ... rar4 (or lower) signature found
2016-04-23 19:11:55,769::DEBUG::[newsunpack:602] Running unrar ['C:\\Program Files\\SABnzbd\\win\\unrar\\UnRAR.exe', 'x', '-idp', '-o-', '-or', '-ai', '-p-', 'Z:\\sabznbd\\ISENQ5~5\\S4L20N~0.RA\\Special-Characters--german-korean-braille.rar', 'Z:\\sabznbd\\Complete\\_QOQXI~W.RAR/']
2016-04-23 19:11:55,915::DEBUG::[newsunpack:746] Checking existence of Z:\sabznbd\Complete\_QOQXI~W.RAR\Braille-⡵⡶⡷⡸⡹⡺-signs.bin
2016-04-23 19:11:55,915::DEBUG::[newsunpack:746] Checking existence of Z:\sabznbd\Complete\_QOQXI~W.RAR\Deutsch-ist-gefühlsmäßig.bin
2016-04-23 19:11:55,917::DEBUG::[newsunpack:746] Checking existence of Z:\sabznbd\Complete\_QOQXI~W.RAR\Korean-안녕하세요세계-stuff.bin
2016-04-23 19:11:55,917::DEBUG::[newsunpack:764] UNRAR output 
UNRAR 5.30 freeware      Copyright (c) 1993-2015 Alexander Roshal


Extracting from Z:\sabznbd\ISENQ5~5\S4L20N~0.RA\Special-Characters--german-korean-braille.rar

Extracting  Z:\sabznbd\Complete\_QOQXI~W.RAR/Braille-??????-signs.bin  OK
Extracting  Z:\sabznbd\Complete\_QOQXI~W.RAR/Deutsch-ist-gefhlsm„áig.bin  OK
Extracting  Z:\sabznbd\Complete\_QOQXI~W.RAR/Korean-???????-stuff.bin  OK
All OK
2016-04-23 19:11:55,917::INFO::[newsunpack:768] Unpacked 3 files/folders in 0 seconds
2016-04-23 19:11:55,917::DEBUG::[newsunpack:468] rar_unpack(): Rars: [u'Z:\\sabznbd\\ISENQ5~5\\S4L20N~0.RA\\Special-Characters--german-korean-braille.rar']
2016-04-23 19:11:55,917::DEBUG::[newsunpack:469] rar_unpack(): Newfiles: [u'Z:\\sabznbd\\Complete\\_QOQXI~W.RAR\\Braille-??????-signs.bin', u'Z:\\sabznbd\\Complete\\_QOQXI~W.RAR\\Deutsch-ist-gef\xfchlsm\xe4\xdfig.bin', u'Z:\\sabznbd\\Complete\\_QOQXI~W.RAR\\Korean-???????-stuff.bin']
2016-04-23 19:11:55,918::INFO::[newsunpack:475] Deleting Z:\sabznbd\ISENQ5~5\S4L20N~0.RA\Special-Characters--german-korean-braille.rar
2016-04-23 19:11:55,921::INFO::[newsunpack:233] Unrar finished on Z:\sabznbd\ISENQ5~5\S4L20N~0.RA
2016-04-23 19:11:55,923::DEBUG::[newsunpack:1641] build_filelists(): joinables: []
2016-04-23 19:11:55,923::DEBUG::[newsunpack:1642] build_filelists(): zips: []
2016-04-23 19:11:55,923::DEBUG::[newsunpack:1643] build_filelists(): rars: []
2016-04-23 19:11:55,923::DEBUG::[newsunpack:1644] build_filelists(): 7zips: []
2016-04-23 19:11:55,923::DEBUG::[newsunpack:1645] build_filelists(): ts: []
2016-04-23 19:11:55,924::INFO::[postproc:357] unpack_magic finished on Special-Characters--german-korean-braille.rar
2016-04-23 19:11:55,930::DEBUG::[misc:831] Moving. Old path:\\?\Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra\Special-Characters--german-korean-braille.rar new path:\\?\Z:\sabznbd\Complete\_UNPACK_Special-Characters--german-korean-braille.rar\Special-Characters--german-korean-braille.rar overwrite?:0
2016-04-23 19:11:55,930::DEBUG::[misc:1224] Renaming "\\?\Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra\Special-Characters--german-korean-braille.rar" to "\\?\Z:\sabznbd\Complete\_UNPACK_Special-Characters--german-korean-braille.rar\Special-Characters--german-korean-braille.rar"
2016-04-23 19:11:55,933::ERROR::[misc:844] Failed moving Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra\Special-Characters--german-korean-braille.rar to Z:\sabznbd\Complete\_UNPACK_Special-Characters--german-korean-braille.rar\Special-Characters--german-korean-braille.rar
2016-04-23 19:11:55,933::INFO::[misc:845] Traceback: 
Traceback (most recent call last):
  File "sabnzbd\misc.pyo", line 840, in move_to_path
  File "shutil.pyo", line 82, in copyfile
IOError: [Errno 2] No such file or directory: u'\\\\?\\Z:\\sabznbd\\incomplete\\Special-Characters--german-korean-braille.ra\\Special-Characters--german-korean-braille.rar'
2016-04-23 19:11:55,950::DEBUG::[notifier:112] Sending registration to localhost:23053
2016-04-23 19:11:55,950::DEBUG::[notifier:102] Checking icon
2016-04-23 19:11:55,950::DEBUG::[notifier:219] To : localhost:23053 <<class 'gntp.GNTPRegister'>>
2016-04-23 19:11:56,461::INFO::[postproc:550] Cleaning up Special-Characters--german-korean-braille.rar (keep_basic=True)
2016-04-23 19:11:56,471::INFO::[misc:1283] Cannot remove folder \\?\Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra\__ADMIN__
2016-04-23 19:11:56,483::INFO::[misc:1283] Cannot remove folder \\?\Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra\__ADMIN__
2016-04-23 19:11:56,484::INFO::[postproc:83] Saving postproc queue
2016-04-23 19:11:56,484::INFO::[__init__:941] Saving data for postproc2.sab in C:\Users\MYNAME\AppData\Local\sabnzbd\admin\postproc2.sab
2016-04-23 19:11:56,486::INFO::[downloader:277] Post-processing finished, resuming download
2016-04-23 19:11:56,589::INFO::[downloader:723] Thread 10@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:56,589::INFO::[downloader:723] Thread 15@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:56,589::INFO::[downloader:723] Thread 18@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:56,589::INFO::[downloader:723] Thread 20@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:56,589::INFO::[downloader:723] Thread 17@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:56,589::INFO::[downloader:723] Thread 8@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:56,591::INFO::[downloader:723] Thread 9@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:56,591::INFO::[downloader:723] Thread 13@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:56,591::INFO::[downloader:723] Thread 4@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:56,591::INFO::[downloader:723] Thread 6@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:56,591::INFO::[downloader:723] Thread 3@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:56,691::INFO::[downloader:723] Thread 1@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:56,792::INFO::[downloader:723] Thread 14@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:56,892::INFO::[downloader:723] Thread 11@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:56,959::DEBUG::[growler:201] Cannot register with Growl [Errno 10061] No connection could be made because the target machine actively refused it
2016-04-23 19:11:56,993::INFO::[downloader:723] Thread 12@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:57,094::INFO::[downloader:723] Thread 5@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:57,194::INFO::[downloader:723] Thread 2@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:57,295::INFO::[downloader:723] Thread 19@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:57,395::INFO::[downloader:723] Thread 7@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:57,486::INFO::[nzbqueue:250] Saving queue
2016-04-23 19:11:57,486::INFO::[__init__:941] Saving data for queue10.sab in C:\Users\MYNAME\AppData\Local\sabnzbd\admin\queue10.sab
2016-04-23 19:11:57,487::INFO::[__init__:941] Saving data for totals10.sab in C:\Users\MYNAME\AppData\Local\sabnzbd\admin\totals10.sab
2016-04-23 19:11:57,489::INFO::[__init__:941] Saving data for rss_data.sab in C:\Users\MYNAME\AppData\Local\sabnzbd\admin\rss_data.sab
2016-04-23 19:11:57,490::INFO::[__init__:941] Saving data for Rating.sab in C:\Users\MYNAME\AppData\Local\sabnzbd\admin\Rating.sab
2016-04-23 19:11:57,496::INFO::[__init__:941] Saving data for watched_data2.sab in C:\Users\MYNAME\AppData\Local\sabnzbd\admin\watched_data2.sab
2016-04-23 19:11:57,499::INFO::[downloader:723] Thread 16@news.USENETSERVER.com: forcing disconnect
2016-04-23 19:11:57,499::INFO::[postproc:83] Saving postproc queue
2016-04-23 19:11:57,500::INFO::[__init__:941] Saving data for postproc2.sab in C:\Users\MYNAME\AppData\Local\sabnzbd\admin\postproc2.sab
2016-04-23 19:11:57,500::INFO::[postproc:679] Queue has finished, launching: None (None)
2016-04-23 19:12:25,819::DEBUG::[interface:458] API-call from 192.168.1.71 [CouchPotato linux - git-c4fad95c - v2] {'output': u'json', 'apikey': u'MYAPIKEY', 'mode': u'queue'}
2016-04-23 19:12:25,835::DEBUG::[interface:458] API-call from 192.168.1.71 [CouchPotato linux - git-c4fad95c - v2] {'output': u'json', 'apikey': u'MYAPIKEY', 'limit': u'15', 'mode': u'history'}
2016-04-23 19:13:25,377::DEBUG::[interface:458] API-call from 192.168.1.71 [CouchPotato linux - git-c4fad95c - v2] {'output': u'json', 'apikey': u'MYAPIKEY', 'mode': u'queue'}
2016-04-23 19:13:25,404::DEBUG::[interface:458] API-call from 192.168.1.71 [CouchPotato linux - git-c4fad95c - v2] {'output': u'json', 'apikey': u'MYAPIKEY', 'limit': u'15', 'mode': u'history'}

Re: Downloads failing to move after upgrade to 1.0

Posted: April 24th, 2016, 1:36 am
by sander
@wik_sammamish:

Have you checked the contents of Z:\sabznbd\Complete\Special-Characters--german-korean-braille.rar\ ? If it does not exist or there are not three correct files, there is a problem with your setup in combination with SABnzbd 1.0.0. Possibly something with your NAS not handling special characters or long file names or something else.

The download I pointed you to works with SAB 1.0.0 on my Windows 7 on a plain C: drive (NTFS). So could you both point SAB's Incomplete/Temp and Complete directories to a directory on your C:-drive, preferably a short directoryname, like C:\SABtemp\incomplete and C:\SABtemp\complete, and then download again?

EDIT:

This is your sabnzbd.log with the error, but the strange thing is in this logging (and operation?) there are no special characters; the directory names are plain ASCII. Strange.

Code: Select all

2016-04-23 19:11:55,933::ERROR::[misc:844] Failed moving Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra\Special-Characters--german-korean-braille.rar to Z:\sabznbd\Complete\_UNPACK_Special-Characters--german-korean-braille.rar\Special-Characters--german-korean-braille.rar
2016-04-23 19:11:55,933::INFO::[misc:845] Traceback: 
Traceback (most recent call last):
  File "sabnzbd\misc.pyo", line 840, in move_to_path
  File "shutil.pyo", line 82, in copyfile
IOError: [Errno 2] No such file or directory: u'\\\\?\\Z:\\sabznbd\\incomplete\\Special-Characters--german-korean-braille.ra\\Special-Characters--german-korean-braille.rar'

Re: Downloads failing to move after upgrade to 1.0

Posted: April 28th, 2016, 6:39 pm
by wik_sammamish
I have a directory called Z:\sabznbd\Complete\_UNPACK_Special-Characters--german-korean-braille.rar It has three files in it and all the special characters in the file names are rendering properly.

I also have a directory in incomplete called Z:\sabznbd\incomplete\Special-Characters--german-korean-braille.ra that has an _ADMIN_ folder left in it.

After changing the complete and incomplete directories to local files everything seems to bee working fine.

Re: Downloads failing to move after upgrade to 1.0

Posted: April 28th, 2016, 11:30 pm
by sander
OK. And can you now try the other thing I adviced?

"The download I pointed you to works with SAB 1.0.0 on my Windows 7 on a plain C: drive (NTFS). So could you both point SAB's Incomplete/Temp and Complete directories to a directory on your C:-drive, preferably a short directoryname, like C:\SABtemp\incomplete and C:\SABtemp\complete, and then download again?"

Re: Downloads failing to move after upgrade to 1.0

Posted: May 1st, 2016, 4:09 pm
by wik_sammamish
That is what I did. I changed the directories to C:\SABtemp\Complete and C:\SABtemp\Incomplete and its been working fine for about a week now.

Re: Downloads failing to move after upgrade to 1.0

Posted: May 1st, 2016, 4:38 pm
by sander
wik_sammamish wrote:That is what I did. I changed the directories to C:\SABtemp\Complete and C:\SABtemp\Incomplete and its been working fine for about a week now.
Ah, I missed the last line in your previous post.

OK, so, what kind of drive is your Z:? You earlier wrote "the Z drive is a mapped network drive in windows." Is it a drive within the VM-setup? Is it a NAS? A USB-drive? Or ... ?

Do you want to find out the root cause of this? I think it needs some python commands.