sabnzbd does not download anymore (downloading state)

[fzeihfioez]

Hi,
I have been using sabnzbd for a long time now, as a service on ubuntu 10.04.
I'm not quite sure if it has been updated, but for a couple of days, every download I add stays in the "downloading" state but nothing happens...
Disk is not full, and I tried to restart but nothing shows up in the logs.
So I don't know what to do anymore...
Thanks for your help
David

[shypike]

Go to the Status page, tab "Queue Repair".
Click the Repair button. This will restart SABnzbd and reconstruct the queue.

[fzeihfioez]

Thanks, but no luck... (( here's the log

Code: Select all


2012-02-13 19:16:36,051::INFO::[__init__:325] SABnzbd shutting down...
2012-02-13 19:16:36,051::INFO::[__init__:846] Saving data for bookmarks.sab in /home/xxxxxx/.sabnzbd/admin/bookmarks.sab
2012-02-13 19:16:36,066::DEBUG::[__init__:332] Stopping URLGrabber
2012-02-13 19:16:36,066::INFO::[urlgrabber:70] URLGrabber shutting down
2012-02-13 19:16:36,066::DEBUG::[__init__:339] Stopping Newzbin-Grabber
2012-02-13 19:16:36,066::DEBUG::[newzbin:143] Stopping MSGIDGrabber
2012-02-13 19:16:36,067::DEBUG::[__init__:346] Stopping dirscanner
2012-02-13 19:16:36,067::INFO::[__init__:846] Saving data for watched_data.sab in /home/xxxxxx/.sabnzbd/admin/watched_data.sab
2012-02-13 19:16:36,067::INFO::[dirscanner:254] Dirscanner shutting down
2012-02-13 19:16:36,540::DEBUG::[__init__:355] Stopping downloader
2012-02-13 19:16:37,312::INFO::[downloader:375] Shutting down
2012-02-13 19:16:37,313::DEBUG::[__init__:358] Stopping assembler
2012-02-13 19:16:37,313::INFO::[assembler:70] Shutting down
2012-02-13 19:16:37,313::DEBUG::[__init__:365] Stopping postprocessor
2012-02-13 19:16:37,313::INFO::[postproc:83] Saving postproc queue
2012-02-13 19:16:37,313::INFO::[__init__:846] Saving data for postproc1.sab in /home/xxxxxx/.sabnzbd/admin/postproc1.sab
2012-02-13 19:16:37,317::INFO::[nzbqueue:170] Saving queue
2012-02-13 19:16:37,318::DEBUG::[__init__:769] Saving data for SABnzbd_nzo_XrATe_ in /var/tmp/downloads-nzb/incomplete/c86863 Fringe.S04E12.720p.HDTV.X264-DIMENSION/__ADMIN__
2012-02-13 19:16:37,457::DEBUG::[__init__:769] Saving data for SABnzbd_nzo_JFW68y in /var/tmp/downloads-nzb/incomplete/SSF Bleach - 359 HD/__ADMIN__
2012-02-13 19:16:37,463::INFO::[__init__:846] Saving data for queue9.sab in /home/xxxxxx/.sabnzbd/admin/queue9.sab
2012-02-13 19:16:37,464::INFO::[__init__:846] Saving data for totals9.sab in /home/xxxxxx/.sabnzbd/admin/totals9.sab
2012-02-13 19:16:37,464::INFO::[__init__:846] Saving data for rss_data.sab in /home/xxxxxx/.sabnzbd/admin/rss_data.sab
2012-02-13 19:16:37,464::INFO::[__init__:846] Saving data for bookmarks.sab in /home/xxxxxx/.sabnzbd/admin/bookmarks.sab
2012-02-13 19:16:37,464::INFO::[__init__:846] Saving data for watched_data.sab in /home/xxxxxx/.sabnzbd/admin/watched_data.sab
2012-02-13 19:16:37,465::INFO::[postproc:83] Saving postproc queue
2012-02-13 19:16:37,465::INFO::[__init__:846] Saving data for postproc1.sab in /home/xxxxxx/.sabnzbd/admin/postproc1.sab
2012-02-13 19:16:37,465::DEBUG::[scheduler:213] Terminating scheduler
2012-02-13 19:16:37,465::INFO::[__init__:385] All processes stopped
2012-02-13 19:16:37,465::INFO::[_cplogging:55] [13/Feb/2012:19:16:37] ENGINE Bus STOPPING
2012-02-13 19:16:37,492::INFO::[_cplogging:55] [13/Feb/2012:19:16:37] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('0.0.0.0', 9000)) shut down
2012-02-13 19:16:37,496::INFO::[_cplogging:55] [13/Feb/2012:19:16:37] ENGINE Stopped thread '_TimeoutMonitor'.
2012-02-13 19:16:37,496::INFO::[_cplogging:55] [13/Feb/2012:19:16:37] ENGINE Bus STOPPED
2012-02-13 19:16:37,497::INFO::[_cplogging:55] [13/Feb/2012:19:16:37] ENGINE Bus EXITING
2012-02-13 19:16:37,497::INFO::[_cplogging:55] [13/Feb/2012:19:16:37] ENGINE Bus EXITED
2012-02-13 19:16:39,218::DEBUG::[scheduler:199] Stopping scheduler
2012-02-13 19:16:39,787::INFO::[_cplogging:55] [13/Feb/2012:19:16:39] ENGINE Bus STOPPING
2012-02-13 19:16:39,787::INFO::[_cplogging:55] [13/Feb/2012:19:16:39] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('0.0.0.0', 9000)) already shut down
2012-02-13 19:16:39,787::INFO::[_cplogging:55] [13/Feb/2012:19:16:39] ENGINE No thread running for _TimeoutMonitor.
2012-02-13 19:16:39,787::INFO::[_cplogging:55] [13/Feb/2012:19:16:39] ENGINE Bus STOPPED
2012-02-13 19:16:39,787::INFO::[_cplogging:55] [13/Feb/2012:19:16:39] ENGINE Bus EXITING
2012-02-13 19:16:39,787::INFO::[_cplogging:55] [13/Feb/2012:19:16:39] ENGINE Bus EXITED
2012-02-13 19:16:39,788::INFO::[_cplogging:55] [13/Feb/2012:19:16:39] ENGINE Re-spawning /usr/bin/sabnzbdplus --daemon
2012-02-13 19:16:41,324::INFO::[sabnzbdplus:1195] --------------------------------
2012-02-13 19:16:41,324::INFO::[sabnzbdplus:1196] sabnzbdplus-0.6.15 (rev=d06f957a0f05a8766822acaa6de1f6d1e9f279e0)
2012-02-13 19:16:41,324::INFO::[sabnzbdplus:1208] Platform = posix
2012-02-13 19:16:41,324::INFO::[sabnzbdplus:1209] Python-version = 2.6.5 (r265:79063, Apr 16 2010, 13:57:41)
[GCC 4.4.3]
2012-02-13 19:16:41,324::INFO::[sabnzbdplus:1210] Arguments = "/usr/bin/sabnzbdplus", "--daemon"
2012-02-13 19:16:41,324::INFO::[sabnzbdplus:1223] Read INI file /home/xxxxxx/.sabnzbd/sabnzbd.ini
2012-02-13 19:16:41,352::INFO::[__init__:870] Loading data for bookmarks.sab from /home/xxxxxx/.sabnzbd/admin/bookmarks.sab
2012-02-13 19:16:41,353::INFO::[__init__:870] Loading data for rss_data.sab from /home/xxxxxx/.sabnzbd/admin/rss_data.sab
2012-02-13 19:16:41,353::INFO::[__init__:870] Loading data for totals9.sab from /home/xxxxxx/.sabnzbd/admin/totals9.sab
2012-02-13 19:16:41,353::INFO::[postproc:89] Loading postproc queue
2012-02-13 19:16:41,353::INFO::[__init__:870] Loading data for postproc1.sab from /home/xxxxxx/.sabnzbd/admin/postproc1.sab
2012-02-13 19:16:41,599::INFO::[nzbqueue:139] Repairing job /var/tmp/downloads-nzb/incomplete/c86863 Fringe.S04E12.720p.HDTV.X264-DIMENSION
2012-02-13 19:16:41,600::DEBUG::[nzbqueue:155] Repair job c86863 Fringe.S04E12.720p.HDTV.X264-DIMENSION by reparsing stored NZB
2012-02-13 19:16:41,600::INFO::[__init__:560] Adding c86863 Fringe.S04E12.720p.HDTV.X264-DIMENSION.nzb.gz
2012-02-13 19:16:41,643::INFO::[misc:1175] Cannot remove folder /var/tmp/downloads-nzb/incomplete/c86863 Fringe.S04E12.720p.HDTV.X264-DIMENSION/__ADMIN__
2012-02-13 19:16:41,644::INFO::[misc:1175] Cannot remove folder /var/tmp/downloads-nzb/incomplete/c86863 Fringe.S04E12.720p.HDTV.X264-DIMENSION/__ADMIN__
2012-02-13 19:16:41,655::DEBUG::[__init__:769] Saving data for SABnzbd_nzf_8QUBGE in /var/tmp/downloads-nzb/incomplete/c86863 Fringe.S04E12.720p.HDTV.X264-DIMENSION/__ADMIN__
2012-02-13 19:16:41,655::INFO::[nzbstuff:423] File fringe.412.720p-dimension.srr added to queue
...
2012-02-13 19:16:41,691::INFO::[nzbstuff:423] File fringe.412.720p-dimension.vol01+02.par2 added to queue
2012-02-13 19:16:41,692::DEBUG::[__init__:769] Saving data for SABnzbd_nzf_ivCj7o in /var/tmp/downloads-nzb/incomplete/c86863 Fringe.S04E12.720p.HDTV.X264-DIMENSION/__ADMIN__
2012-02-13 19:16:41,692::INFO::[nzbstuff:423] File fringe.412.720p-dimension.vol00+01.par2 added to queue
2012-02-13 19:16:41,692::INFO::[__init__:516] Backing up /var/tmp/downloads-nzb/incomplete/c86863 Fringe.S04E12.720p.HDTV.X264-DIMENSION/__ADMIN__/c86863 Fringe.S04E12.720p.HDTV.X264-DIMENSION.nzb.gz
2012-02-13 19:16:41,700::INFO::[nzbqueue:170] Saving queue
2012-02-13 19:16:41,700::DEBUG::[__init__:769] Saving data for SABnzbd_nzo_jDRS8u in /var/tmp/downloads-nzb/incomplete/c86863 Fringe.S04E12.720p.HDTV.X264-DIMENSION/__ADMIN__
2012-02-13 19:16:41,702::INFO::[__init__:846] Saving data for queue9.sab in /home/xxxxxx/.sabnzbd/admin/queue9.sab
2012-02-13 19:16:41,702::INFO::[nzbqueue:139] Repairing job /var/tmp/downloads-nzb/incomplete/SSF Bleach - 359 HD
2012-02-13 19:16:41,702::DEBUG::[nzbqueue:155] Repair job SSF Bleach - 359 HD by reparsing stored NZB
2012-02-13 19:16:41,702::INFO::[__init__:560] Adding SSF Bleach - 359 HD .nzb.gz
2012-02-13 19:16:41,710::INFO::[misc:1175] Cannot remove folder /var/tmp/downloads-nzb/incomplete/SSF Bleach - 359 HD/__ADMIN__
2012-02-13 19:16:41,710::INFO::[misc:1175] Cannot remove folder /var/tmp/downloads-nzb/incomplete/SSF Bleach - 359 HD/__ADMIN__
2012-02-13 19:16:41,712::DEBUG::[__init__:769] Saving data for SABnzbd_nzf_Ch8JVW in /var/tmp/downloads-nzb/incomplete/SSF Bleach - 359 HD/__ADMIN__
2012-02-13 19:16:41,712::INFO::[nzbstuff:423] File [SSF] Bleach - 359 HD [1280x720 x264 8bit AAC].vol40+32.PAR2 added to queue
2012-02-13 19:16:41,712::DEBUG::[__init__:769] Saving data for SABnzbd_nzf_NLwa8S in /var/tmp/downloads-nzb/incomplete/SSF Bleach - 359 HD/__ADMIN__
...
2012-02-13 19:16:41,738::DEBUG::[__init__:769] Saving data for SABnzbd_nzf_2t__pf in /var/tmp/downloads-nzb/incomplete/SSF Bleach - 359 HD/__ADMIN__
2012-02-13 19:16:41,738::INFO::[nzbstuff:423] File [SSF] Bleach - 359 HD [1280x720 x264 8bit AAC].par2 added to queue
2012-02-13 19:16:41,739::INFO::[__init__:516] Backing up /var/tmp/downloads-nzb/incomplete/SSF Bleach - 359 HD/__ADMIN__/SSF Bleach - 359 HD .nzb.gz
2012-02-13 19:16:41,747::INFO::[nzbqueue:170] Saving queue
2012-02-13 19:16:41,748::DEBUG::[__init__:769] Saving data for SABnzbd_nzo_mS9vXV in /var/tmp/downloads-nzb/incomplete/SSF Bleach - 359 HD/__ADMIN__
2012-02-13 19:16:41,749::INFO::[__init__:846] Saving data for queue9.sab in /home/xxxxxx/.sabnzbd/admin/queue9.sab
2012-02-13 19:16:41,749::DEBUG::[downloader:110] Initializing downloader/decoder
2012-02-13 19:16:41,750::INFO::[__init__:870] Loading data for watched_data.sab from /home/xxxxxx/.sabnzbd/admin/watched_data.sab
2012-02-13 19:16:41,750::DEBUG::[scheduler:136] Scheduling RSS interval task every 60 min (delay=2)
2012-02-13 19:16:41,750::DEBUG::[scheduler:147] Scheduling VersionCheck on day 5 at 19:44
2012-02-13 19:16:41,750::INFO::[__init__:289] All processes started
2012-02-13 19:16:41,750::INFO::[sabnzbdplus:332] Web dir is /usr/share/sabnzbdplus/interfaces/Plush
2012-02-13 19:16:41,762::INFO::[sabnzbdplus:442] _yenc module... found!
2012-02-13 19:16:41,762::INFO::[sabnzbdplus:450] par2 binary... found (/usr/bin/par2)
2012-02-13 19:16:41,762::INFO::[sabnzbdplus:458] unrar binary... found (/usr/bin/unrar)
2012-02-13 19:16:41,763::INFO::[sabnzbdplus:463] unzip binary... found (/usr/bin/unzip)
2012-02-13 19:16:41,763::INFO::[sabnzbdplus:469] nice binary... found (/usr/bin/nice)
2012-02-13 19:16:41,763::INFO::[sabnzbdplus:473] ionice binary... found (/usr/bin/ionice)
2012-02-13 19:16:41,770::INFO::[sabnzbdplus:478] pyOpenSSL... found (True)
2012-02-13 19:16:41,771::INFO::[sabnzbdplus:1390] Starting web-interface on 0.0.0.0:9000
2012-02-13 19:16:41,772::INFO::[_cplogging:55] [13/Feb/2012:19:16:41] ENGINE Bus STARTING
2012-02-13 19:16:41,817::INFO::[_cplogging:55] [13/Feb/2012:19:16:41] ENGINE Started monitor thread '_TimeoutMonitor'.
2012-02-13 19:16:41,918::INFO::[_cplogging:55] [13/Feb/2012:19:16:41] ENGINE Serving on 0.0.0.0:9000
2012-02-13 19:16:41,918::INFO::[_cplogging:55] [13/Feb/2012:19:16:41] ENGINE Bus STARTED
2012-02-13 19:16:41,919::DEBUG::[misc:522] Version check disabled by Ubuntu/Debian packaging
2012-02-13 19:16:41,919::INFO::[sabnzbdplus:1459] Starting sabnzbdplus-0.6.15
2012-02-13 19:16:41,919::DEBUG::[__init__:300] Starting postprocessor
2012-02-13 19:16:41,919::DEBUG::[__init__:303] Starting assembler
2012-02-13 19:16:41,919::DEBUG::[__init__:306] Starting downloader
2012-02-13 19:16:41,920::DEBUG::[scheduler:172] Starting scheduler
2012-02-13 19:16:41,920::DEBUG::[__init__:311] Starting dirscanner
2012-02-13 19:16:41,920::INFO::[dirscanner:263] Dirscanner starting up
2012-02-13 19:16:41,921::DEBUG::[__init__:316] Starting urlgrabber
2012-02-13 19:16:41,921::INFO::[urlgrabber:75] URLGrabber starting up


[shypike]

Looks like something odd is going on with file/folder permissions.
Are you using some external disk or share?

[fzeihfioez]

i double checked permissions, but they seems correct...
i don't use an external disk but the directory /var/tmp/ is shared with samba
where did you see this issue in the logs ?
thanks

[edit]
i cleared incomplete/ and restarted but...

Code: Select all

2012-02-13 21:28:34,508::INFO::[__init__:325] SABnzbd shutting down...
2012-02-13 21:28:34,508::INFO::[__init__:846] Saving data for bookmarks.sab in /home/xxxxxx/.sabnzbd/admin/bookmarks.sab
2012-02-13 21:28:34,519::DEBUG::[__init__:332] Stopping URLGrabber
2012-02-13 21:28:34,519::INFO::[urlgrabber:70] URLGrabber shutting down
2012-02-13 21:28:34,519::DEBUG::[__init__:339] Stopping Newzbin-Grabber
2012-02-13 21:28:34,520::DEBUG::[newzbin:143] Stopping MSGIDGrabber
2012-02-13 21:28:34,520::DEBUG::[__init__:346] Stopping dirscanner
2012-02-13 21:28:34,520::INFO::[__init__:846] Saving data for watched_data.sab in /home/xxxxxx/.sabnzbd/admin/watched_data.sab
2012-02-13 21:28:34,520::INFO::[dirscanner:254] Dirscanner shutting down
2012-02-13 21:28:34,957::DEBUG::[__init__:355] Stopping downloader
2012-02-13 21:28:34,957::INFO::[downloader:375] Shutting down
2012-02-13 21:28:34,958::DEBUG::[__init__:358] Stopping assembler
2012-02-13 21:28:34,958::INFO::[assembler:70] Shutting down
2012-02-13 21:28:34,958::DEBUG::[__init__:365] Stopping postprocessor
2012-02-13 21:28:34,958::INFO::[postproc:83] Saving postproc queue
2012-02-13 21:28:34,958::INFO::[__init__:846] Saving data for postproc1.sab in /home/xxxxxx/.sabnzbd/admin/postproc1.sab
2012-02-13 21:28:35,007::INFO::[nzbqueue:170] Saving queue
2012-02-13 21:28:35,007::INFO::[__init__:846] Saving data for queue9.sab in /home/xxxxxx/.sabnzbd/admin/queue9.sab
2012-02-13 21:28:35,008::INFO::[__init__:846] Saving data for totals9.sab in /home/xxxxxx/.sabnzbd/admin/totals9.sab
2012-02-13 21:28:35,009::INFO::[__init__:846] Saving data for rss_data.sab in /home/xxxxxx/.sabnzbd/admin/rss_data.sab
2012-02-13 21:28:35,009::INFO::[__init__:846] Saving data for bookmarks.sab in /home/xxxxxx/.sabnzbd/admin/bookmarks.sab
2012-02-13 21:28:35,009::INFO::[__init__:846] Saving data for watched_data.sab in /home/xxxxxx/.sabnzbd/admin/watched_data.sab
2012-02-13 21:28:35,009::INFO::[postproc:83] Saving postproc queue
2012-02-13 21:28:35,010::INFO::[__init__:846] Saving data for postproc1.sab in /home/xxxxxx/.sabnzbd/admin/postproc1.sab
2012-02-13 21:28:35,010::DEBUG::[scheduler:213] Terminating scheduler
2012-02-13 21:28:35,010::INFO::[__init__:385] All processes stopped
2012-02-13 21:28:35,010::INFO::[_cplogging:55] [13/Feb/2012:21:28:35] ENGINE Bus STOPPING
2012-02-13 21:28:36,895::DEBUG::[scheduler:199] Stopping scheduler
2012-02-13 21:28:39,656::INFO::[_cplogging:55] [13/Feb/2012:21:28:39] ENGINE Bus STOPPING
2012-02-13 21:28:43,142::INFO::[_cplogging:55] [13/Feb/2012:21:28:43] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('0.0.0.0', 9000)) shut down
2012-02-13 21:28:43,179::INFO::[_cplogging:55] [13/Feb/2012:21:28:43] ENGINE Stopped thread '_TimeoutMonitor'.
2012-02-13 21:28:43,179::INFO::[_cplogging:55] [13/Feb/2012:21:28:43] ENGINE Bus STOPPED
2012-02-13 21:28:43,179::INFO::[_cplogging:55] [13/Feb/2012:21:28:43] ENGINE Bus EXITING
2012-02-13 21:28:43,179::INFO::[_cplogging:55] [13/Feb/2012:21:28:43] ENGINE Bus EXITED
2012-02-13 21:28:48,138::INFO::[_cplogging:55] [13/Feb/2012:21:28:48] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('0.0.0.0', 9000)) shut down
2012-02-13 21:28:48,138::INFO::[_cplogging:55] [13/Feb/2012:21:28:48] ENGINE No thread running for _TimeoutMonitor.
2012-02-13 21:28:48,163::INFO::[_cplogging:55] [13/Feb/2012:21:28:48] ENGINE Bus STOPPED
2012-02-13 21:28:48,163::INFO::[_cplogging:55] [13/Feb/2012:21:28:48] ENGINE Bus EXITING
2012-02-13 21:28:48,164::INFO::[_cplogging:55] [13/Feb/2012:21:28:48] ENGINE Bus EXITED
2012-02-13 21:28:48,164::INFO::[_cplogging:55] [13/Feb/2012:21:28:48] ENGINE Re-spawning /usr/bin/sabnzbdplus --daemon -p
2012-02-13 21:28:48,365::INFO::[sabnzbdplus:1195] --------------------------------
2012-02-13 21:28:48,365::INFO::[sabnzbdplus:1196] sabnzbdplus-0.6.15 (rev=d06f957a0f05a8766822acaa6de1f6d1e9f279e0)
2012-02-13 21:28:48,365::INFO::[sabnzbdplus:1208] Platform = posix
2012-02-13 21:28:48,365::INFO::[sabnzbdplus:1209] Python-version = 2.6.5 (r265:79063, Apr 16 2010, 13:57:41)
[GCC 4.4.3]
2012-02-13 21:28:48,365::INFO::[sabnzbdplus:1210] Arguments = "/usr/bin/sabnzbdplus", "--daemon", "-p"
2012-02-13 21:28:48,366::INFO::[sabnzbdplus:1223] Read INI file /home/xxxxxx/.sabnzbd/sabnzbd.ini
2012-02-13 21:28:48,373::INFO::[__init__:870] Loading data for bookmarks.sab from /home/xxxxxx/.sabnzbd/admin/bookmarks.sab
2012-02-13 21:28:48,373::INFO::[__init__:870] Loading data for rss_data.sab from /home/xxxxxx/.sabnzbd/admin/rss_data.sab
2012-02-13 21:28:48,373::INFO::[__init__:870] Loading data for totals9.sab from /home/xxxxxx/.sabnzbd/admin/totals9.sab
2012-02-13 21:28:48,373::INFO::[postproc:89] Loading postproc queue
2012-02-13 21:28:48,373::INFO::[__init__:870] Loading data for postproc1.sab from /home/xxxxxx/.sabnzbd/admin/postproc1.sab
2012-02-13 21:28:48,490::DEBUG::[downloader:110] Initializing downloader/decoder
2012-02-13 21:28:48,491::INFO::[__init__:870] Loading data for watched_data.sab from /home/xxxxxx/.sabnzbd/admin/watched_data.sab
2012-02-13 21:28:48,491::DEBUG::[scheduler:136] Scheduling RSS interval task every 60 min (delay=46)
2012-02-13 21:28:48,492::DEBUG::[scheduler:147] Scheduling VersionCheck on day 2 at 7:23
2012-02-13 21:28:48,492::INFO::[__init__:289] All processes started
2012-02-13 21:28:48,492::INFO::[sabnzbdplus:332] Web dir is /usr/share/sabnzbdplus/interfaces/Plush
2012-02-13 21:28:48,513::INFO::[sabnzbdplus:442] _yenc module... found!
2012-02-13 21:28:48,514::INFO::[sabnzbdplus:450] par2 binary... found (/usr/bin/par2)
2012-02-13 21:28:48,514::INFO::[sabnzbdplus:458] unrar binary... found (/usr/bin/unrar)
2012-02-13 21:28:48,514::INFO::[sabnzbdplus:463] unzip binary... found (/usr/bin/unzip)
2012-02-13 21:28:48,514::INFO::[sabnzbdplus:469] nice binary... found (/usr/bin/nice)
2012-02-13 21:28:48,514::INFO::[sabnzbdplus:473] ionice binary... found (/usr/bin/ionice)
2012-02-13 21:28:48,514::INFO::[sabnzbdplus:478] pyOpenSSL... found (True)
2012-02-13 21:28:48,515::INFO::[sabnzbdplus:1390] Starting web-interface on 0.0.0.0:9000
2012-02-13 21:28:48,516::INFO::[_cplogging:55] [13/Feb/2012:21:28:48] ENGINE Bus STARTING
2012-02-13 21:28:48,524::INFO::[_cplogging:55] [13/Feb/2012:21:28:48] ENGINE Started monitor thread '_TimeoutMonitor'.
2012-02-13 21:28:48,731::INFO::[_cplogging:55] [13/Feb/2012:21:28:48] ENGINE Serving on 0.0.0.0:9000
2012-02-13 21:28:48,732::INFO::[_cplogging:55] [13/Feb/2012:21:28:48] ENGINE Bus STARTED
2012-02-13 21:28:48,732::DEBUG::[misc:522] Version check disabled by Ubuntu/Debian packaging
2012-02-13 21:28:48,732::INFO::[sabnzbdplus:1459] Starting sabnzbdplus-0.6.15
2012-02-13 21:28:48,733::DEBUG::[__init__:300] Starting postprocessor
2012-02-13 21:28:48,733::DEBUG::[__init__:303] Starting assembler
2012-02-13 21:28:48,733::DEBUG::[__init__:306] Starting downloader
2012-02-13 21:28:48,733::DEBUG::[scheduler:172] Starting scheduler
2012-02-13 21:28:48,734::DEBUG::[__init__:311] Starting dirscanner
2012-02-13 21:28:48,734::INFO::[dirscanner:263] Dirscanner starting up
2012-02-13 21:28:48,734::DEBUG::[__init__:316] Starting urlgrabber
2012-02-13 21:28:48,735::INFO::[urlgrabber:75] URLGrabber starting up
2012-02-13 21:29:43,108::INFO::[__init__:560] Adding SSF Bleach - 359 HD.nzb
2012-02-13 21:29:43,109::INFO::[misc:725] Creating directories: /var/tmp/downloads-nzb/incomplete/SSF Bleach - 359 HD
2012-02-13 21:29:43,111::DEBUG::[__init__:769] Saving data for SABnzbd_nzf_TRIpGh in /var/tmp/downloads-nzb/incomplete/SSF Bleach - 359 HD/__ADMIN__
2012-02-13 21:29:43,111::INFO::[nzbstuff:423] File [SSF] Bleach - 359 HD [1280x720 x264 8bit AAC].vol40+32.PAR2 added to queue
...
2012-02-13 21:29:43,137::DEBUG::[__init__:769] Saving data for SABnzbd_nzf_n6DtBY in /var/tmp/downloads-nzb/incomplete/SSF Bleach - 359 HD/__ADMIN__
2012-02-13 21:29:43,137::INFO::[nzbstuff:423] File [SSF] Bleach - 359 HD [1280x720 x264 8bit AAC].part01.rar added to queue
2012-02-13 21:29:43,137::DEBUG::[__init__:769] Saving data for SABnzbd_nzf_Scw6ek in /var/tmp/downloads-nzb/incomplete/SSF Bleach - 359 HD/__ADMIN__
2012-02-13 21:29:43,138::INFO::[nzbstuff:423] File [SSF] Bleach - 359 HD [1280x720 x264 8bit AAC].par2 added to queue
2012-02-13 21:29:43,138::INFO::[__init__:516] Backing up /var/tmp/downloads-nzb/incomplete/SSF Bleach - 359 HD/__ADMIN__/SSF Bleach - 359 HD.nzb.gz
2012-02-13 21:29:43,147::INFO::[nzbqueue:170] Saving queue
2012-02-13 21:29:43,147::DEBUG::[__init__:769] Saving data for SABnzbd_nzo_pRQ7kL in /var/tmp/downloads-nzb/incomplete/SSF Bleach - 359 HD/__ADMIN__
2012-02-13 21:29:43,160::INFO::[__init__:846] Saving data for queue9.sab in /home/xxxxxx/.sabnzbd/admin/queue9.sab


[shypike]

where did you see this issue in the logs ?
i cleared incomplete/ and restarted but...

Samba is is a "share", so a recipe for permission problems
and also for file name issues, because Samba is less permissive than Linux.

"and restarted but..."
But what?

Your first log says:

Code: Select all

2012-02-13 19:16:41,643::INFO::[misc:1175] Cannot remove folder /var/tmp/downloads-nzb/incomplete/c86863 Fringe.S04E12.720p.HDTV.X264-DIMENSION/__ADMIN__

That's where I got suspicious.

[fzeihfioez]

Thanks for taking time to help me

Samba is is a "share", so a recipe for permission problems
and also for file name issues, because Samba is less permissive than Linux.

Yes I know that

"and restarted but..."
But what?

Your first log says:

Code: Select all

2012-02-13 19:16:41,643::INFO::[misc:1175] Cannot remove folder /var/tmp/downloads-nzb/incomplete/c86863 Fringe.S04E12.720p.HDTV.X264-DIMENSION/__ADMIN__

That's where I got suspicious.

I remove the files, but... nothing, same as before

I tried to remove the ~/.sabnzbd directory, and after setup, the default directory was a "regular" directory in home, but no luck
I noticed that the "test button" in the wizard was hanging, so i'm starting to think that the problem could be on the side of my provider or network settings... I will try with another grabber in a vbox and keep you posted
thanks

[hunterpr0]

Same problem here. Very simple: i've mounted my smb dir in /mnt/sharedoos with -o gid=0,uid=0. Sab runs as root. Touching stuff on console as root in there works fine. Dir/filemasks are 755. Easy as kittens, you'd think.

But, Sab says:

Code: Select all

2012-02-13 22:16:36,856::INFO::[__init__:428] Fetching msgid 6496561 from www.newzbin2.es
2012-02-13 22:16:36,859::INFO::[nzbqueue:170] Saving queue
2012-02-13 22:16:36,860::DEBUG::[__init__:769] Saving data for SABnzbd_nzo_s3ZKl8 in /root/.sabnzbd/admin/future
2012-02-13 22:16:36,861::INFO::[__init__:846] Saving data for queue9.sab in /root/.sabnzbd/admin/queue9.sab
2012-02-13 22:16:36,863::DEBUG::[newzbin:82] Adding msgid 6496561 to the queue
2012-02-13 22:16:36,865::DEBUG::[newzbin:105] Popping msgid 6496561
2012-02-13 22:16:36,866::INFO::[newzbin:153] Fetching NZB for Newzbin report #6496561
2012-02-13 22:16:37,711::INFO::[newzbin:252] Successfully fetched report 6496561 - Californication - 5x06 - Love Song (cat=TV) (Californication - 5x06 - Love Song.nzb)
2012-02-13 22:16:37,712::INFO::[__init__:835] /root/.sabnzbd/admin/future/SABnzbd_nzo_s3ZKl8 removed
2012-02-13 22:16:37,713::INFO::[nzbqueue:207] Regenerating item: SABnzbd_nzo_s3ZKl8
2012-02-13 22:16:37,715::INFO::[misc:725] Creating directories: /mnt/sharedoos/news/incomplete/Californication - 5x06 - Love Song
2012-02-13 22:16:37,731::DEBUG::[__init__:769] Saving data for SABnzbd_nzf_lKRBDo in /mnt/sharedoos/news/incomplete/Californication - 5x06 - Love Song/__ADMIN__
2012-02-13 22:16:37,733::ERROR::[__init__:789] Saving /mnt/sharedoos/news/incomplete/Californication - 5x06 - Love Song/__ADMIN__/SABnzbd_nzf_lKRBDo failed
2012-02-13 22:16:37,734::INFO::[__init__:790] Traceback:
Traceback (most recent call last):
  File "/usr/share/sabnzbdplus/sabnzbd/__init__.py", line 773, in save_data
    _f = open(path, 'wb')
IOError: [Errno 2] No such file or directory: '/mnt/sharedoos/news/incomplete/Californication - 5x06 - Love Song/__ADMIN__/SABnzbd_nzf_lKRBDo'
2012-02-13 22:16:37,735::INFO::[nzbstuff:198] Saving /mnt/sharedoos/news/incomplete/Californication - 5x06 - Love Song/__ADMIN__/SABnzbd_nzf_lKRBDo failed
2012-02-13 22:16:37,743::WARNING::[nzbstuff:671] Cannot save NZB admin files for "Californication - 5x06 - Love Song"
2012-02-13 22:16:37,746::INFO::[nzbqueue:170] Saving queue
2012-02-13 22:16:37,746::INFO::[__init__:846] Saving data for queue9.sab in /root/.sabnzbd/admin/queue9.sab


As soon as I unmount /mnt/sharedoos (its owner is still root, the dirmask is still 755), everything changes: sab can create the complete path, create the adminfiles, and starts downloading like a happy sab should.

This exact situation worked with my previous (ubuntu repo) 0.5.0 just fine. After being forced to update (newzbin2) i now have this.

More info, logging etc available upon request.

[shypike]

Samba results are unpredictable, that's the whole issue.
There have been twenty something releases since 0.5.0 and a huge amount of changes.
As far as SABnzbd is concerned, it expects the file system to behave like a normal native file system.
In fact SABnzbd doesn't really bother with access rights on admin and incomplete folders,
it just uses defaults.
Only for "complete" will it set explicit access rights, if so instructed.

Do you run under a "special" account that has different rights?
Can you create the failing files from a terminal session on the Linux box?
Like: /mnt/sharedoos/news/incomplete/Californication - 5x06 - Love Song/__ADMIN__/SABnzbd_nzf_lKRBDo

[fzeihfioez]

just to say that I just tried grabbit on windows on the same computer and it's working fine

my setup is somewhat simple (i think) :
- ubuntu server with sab as a service
- sab is running with a normal user (me)
- /var/tmp/complete directory belongs to me
- /var/tmp/complete directory is shared as a samba folder for external acces (i'm not storing TO an external share)
- i just did a touch in the complete dir using my account and it's working fine

and as i said, i tried with a "normal" dir and that's not changing anything
edit: i checked the logs and i think that it happened while upgrading sab

good night

[shypike]

I was referring to /var/tmp/INcomplete and you refer to /var/tmp/complete
Those are different folders.
You say "run as a service" and run as "me". Is that possible?

BTW: I'm moving this to the Ubuntu board, in the hope that the Linux gurus can answer this.

[hunterpr0]

Samba results are unpredictable, that's the whole issue.
There have been twenty something releases since 0.5.0 and a huge amount of changes.
As far as SABnzbd is concerned, it expects the file system to behave like a normal native file system.
In fact SABnzbd doesn't really bother with access rights on admin and incomplete folders,
it just uses defaults.
Only for "complete" will it set explicit access rights, if so instructed.

I'd expect this to work too. I cannot understand what Sab is doing differently. Perhaps its the 'binary' flag (for windows).

Do you run under a "special" account that has different rights?

as you can see in the log, nope; its running as root (set to root in the defaults-file).

Can you create the failing files from a terminal session on the Linux box?
Like: /mnt/sharedoos/news/incomplete/Californication - 5x06 - Love Song/__ADMIN__/SABnzbd_nzf_lKRBDo

yes. And if I do that, it'll try to create 'Californication - 5x06 - Love Song.1' which will fail for the same reason. This tells me it, indeed, does have access to the filesystem, yet does not want to/cannot write.

It's bizarre a directory with -exactly- the same rights (and remember, samba is a thing the kernel has to think about, not userland apps; its all overlaid in a posix compliant sauce) that isnt a mount works fine, and when you mount your sambadevice in it, with proper access rights, it fails.

[shypike]

Is the failing path the path to the native file system or is it the mounted path to the Samba share?
If you (temporarily) stop Samba, does SABnzbd then suddenly work?
Which OS are you running exactly and which Samba version?

[hunterpr0]

When I have mounted the samba-share on /mnt/sharedoos, the log above happens.

When I unmount /mnt/sharedoos, basically /mnt/sharedoos becomes a regular folder. If I then start Sab again, and feed it the nzb trough the api (sickbeard) it'll create the full directory structure like the little happy Sabnzbd it is supposed to be.

If I then shutdown Sab, remove everything in /mnt/sharedoos, and use /mnt/sharedoos as a mountpoint again instead of a regular directory, and start Sab again, it breaks again, with the same error.

I'm running Ubuntu 10.04.2 LTS, with a 2.6.32-28 kernel. Python is at version 2.6.5.

[fzeihfioez]

for the record : http://forums.sabnzbd.org/viewtopic.php?f=3&t=10037