Uploaded nzb straight to orphan job on Solaris Zone/SmartOS

Get help with all aspects of SABnzbd
Forum rules
Help us help you:
  • Are you using the latest stable version of SABnzbd? Downloads page.
  • Tell us what system you run SABnzbd on.
  • Adhere to the forum rules.
  • Do you experience problems during downloading?
    Check your connection in Status and Interface settings window.
    Use Test Server in Config > Servers.
    We will probably ask you to do a test using only basic settings.
  • Do you experience problems during repair or unpacking?
    Enable +Debug logging in the Status and Interface settings window and share the relevant parts of the log here using [ code ] sections.
Post Reply
modernpacifist
Newbie
Newbie
Posts: 6
Joined: August 16th, 2012, 11:09 pm

Uploaded nzb straight to orphan job on Solaris Zone/SmartOS

Post by modernpacifist »

Hi Everyone

I'm experimenting with SABnzbd inside a SmartOS (smartos.org) zone, which is just a Solaris zone in reality. I've got the entire thing up and running correctly and everything is marked as found (the dependent libraries) according to the logs except for ionice. What I'm experiencing is whenever I upload a .nzb or .zip file through the web UI, the upload goes through but it doesn't show up in the queue. I then go to the Status page and Queue Repair and the download is present there. I've loaded the same nzb file into another client and the download begins.

I've put the highest log level turned on and this is all that is yielded:

Code: Select all

2012-08-17 04:34:14,137::INFO::[__init__:560] Adding <thing getting downloaded>.nzb
2012-08-17 04:34:14,139::INFO::[misc:722] Creating directories: /home/admin/Downloads/incomplete/<thing getting downloaded>.2
but nothing more.
Under the Queue Repair, if I tell it to put the nzb back into the queue, it immediately goes to failed with the error "Download Failed: Out of server's retention?". The logs produce:

Code: Select all

2012-08-17 04:59:25,427::INFO::[__init__:835] /home/admin/Downloads/incomplete/<thing being downloaded>.1/__ADMIN__/SABnzbd_nzo_CwDMBM removed
2012-08-17 04:59:25,427::INFO::[postproc:83] Saving postproc queue
2012-08-17 04:59:25,427::INFO::[__init__:846] Saving data for postproc1.sab in /home/admin/.sabnzbd/admin/postproc1.sab
2012-08-17 04:59:25,444::INFO::[postproc:258] Starting PostProcessing on <thing being downloaded>.1 => Repair:False, Unpack:False, Delete:True, Script:None, Cat:*
2012-08-17 04:59:25,478::INFO::[postproc:482] Cleaning up <thing being downloaded>.1 (keep_basic=True)
2012-08-17 04:59:25,478::INFO::[misc:1172] Cannot remove folder /home/admin/Downloads/incomplete/<thing being downloaded>.1/__ADMIN__
2012-08-17 04:59:25,479::INFO::[misc:1172] Cannot remove folder /home/admin/Downloads/incomplete/<thing being downloaded>.1/__ADMIN__
2012-08-17 04:59:25,479::INFO::[postproc:83] Saving postproc queue
2012-08-17 04:59:25,479::INFO::[__init__:846] Saving data for postproc1.sab in /home/admin/.sabnzbd/admin/postproc1.sab
2012-08-17 04:59:25,479::INFO::[downloader:229] Post-processing finished, resuming download
2012-08-17 04:59:28,489::INFO::[nzbqueue:170] Saving queue
2012-08-17 04:59:28,489::INFO::[__init__:846] Saving data for queue9.sab in /home/admin/.sabnzbd/admin/queue9.sab
2012-08-17 04:59:28,490::INFO::[__init__:846] Saving data for rss_data.sab in /home/admin/.sabnzbd/admin/rss_data.sab
2012-08-17 04:59:28,490::INFO::[__init__:846] Saving data for bookmarks.sab in /home/admin/.sabnzbd/admin/bookmarks.sab
2012-08-17 04:59:28,490::INFO::[__init__:846] Saving data for watched_data.sab in /home/admin/.sabnzbd/admin/watched_data.sab
2012-08-17 04:59:28,491::INFO::[postproc:83] Saving postproc queue
2012-08-17 04:59:28,491::INFO::[__init__:846] Saving data for postproc1.sab in /home/admin/.sabnzbd/admin/postproc1.sab
2012-08-17 04:59:28,491::INFO::[postproc:619] Queue has finished, launching: None (None)
The OS is running on top of ZFS and I'm using Python 2.7. This is the startup log:

Code: Select all

2012-08-17 05:02:05,857::INFO::[SABnzbd:1207] --------------------------------
2012-08-17 05:02:05,857::INFO::[SABnzbd:1208] SABnzbd.py-0.6.15 (rev=d06f957a0f05a8766822acaa6de1f6d1e9f279e0)
2012-08-17 05:02:05,858::INFO::[SABnzbd:1220] Platform = posix
2012-08-17 05:02:05,858::INFO::[SABnzbd:1221] Python-version = 2.7.2 (default, May 11 2012, 13:40:50) 
[GCC 4.7.0]
2012-08-17 05:02:05,858::INFO::[SABnzbd:1222] Arguments = "SABnzbd.py", "-s", "0.0.0.0:8080", "--logging", "2"
2012-08-17 05:02:05,858::INFO::[SABnzbd:1235] Read INI file /home/admin/.sabnzbd/sabnzbd.ini
2012-08-17 05:02:05,860::INFO::[__init__:870] Loading data for bookmarks.sab from /home/admin/.sabnzbd/admin/bookmarks.sab
2012-08-17 05:02:05,860::INFO::[__init__:870] Loading data for rss_data.sab from /home/admin/.sabnzbd/admin/rss_data.sab
2012-08-17 05:02:05,861::INFO::[__init__:870] Loading data for totals9.sab from /home/admin/.sabnzbd/admin/totals9.sab
2012-08-17 05:02:05,861::INFO::[__init__:873] /home/admin/.sabnzbd/admin/totals9.sab missing, trying old cache
2012-08-17 05:02:05,861::INFO::[__init__:876] /home/admin/.sabnzbd/cache/totals9.sab missing
2012-08-17 05:02:05,910::INFO::[postproc:89] Loading postproc queue
2012-08-17 05:02:05,910::INFO::[__init__:870] Loading data for postproc1.sab from /home/admin/.sabnzbd/admin/postproc1.sab
2012-08-17 05:02:05,910::INFO::[__init__:870] Loading data for queue9.sab from /home/admin/.sabnzbd/admin/queue9.sab
2012-08-17 05:02:05,911::DEBUG::[downloader:110] Initializing downloader/decoder
2012-08-17 05:02:05,911::INFO::[__init__:870] Loading data for watched_data.sab from /home/admin/.sabnzbd/admin/watched_data.sab
2012-08-17 05:02:05,912::DEBUG::[scheduler:136] Scheduling RSS interval task every 60 min (delay=2)
2012-08-17 05:02:05,912::DEBUG::[scheduler:147] Scheduling VersionCheck on day 3 at 19:46
2012-08-17 05:02:05,912::INFO::[downloader:194] Resuming
2012-08-17 05:02:05,912::DEBUG::[__init__:479] PAUSED_ALL inactive
2012-08-17 05:02:05,916::INFO::[__init__:289] All processes started
2012-08-17 05:02:05,917::INFO::[SABnzbd:331] Web dir is /opt/SABnzbd/interfaces/Plush
2012-08-17 05:02:05,920::INFO::[SABnzbd:454] _yenc module... found!
2012-08-17 05:02:05,920::INFO::[SABnzbd:462] par2 binary... found (/opt/local/bin/par2)
2012-08-17 05:02:05,920::INFO::[SABnzbd:470] unrar binary... found (/opt/local/bin/unrar)
2012-08-17 05:02:05,920::INFO::[SABnzbd:475] unzip binary... found (/opt/local/bin/unzip)
2012-08-17 05:02:05,920::INFO::[SABnzbd:481] nice binary... found (/opt/local/bin/nice)
2012-08-17 05:02:05,921::INFO::[SABnzbd:487] ionice binary... NOT found!
2012-08-17 05:02:05,921::INFO::[SABnzbd:490] pyOpenSSL... found (True)
2012-08-17 05:02:05,922::INFO::[SABnzbd:1402] Starting web-interface on 0.0.0.0:9090
2012-08-17 05:02:05,923::INFO::[_cplogging:55] [17/Aug/2012:05:02:05] ENGINE Bus STARTING
2012-08-17 05:02:05,925::INFO::[_cplogging:55] [17/Aug/2012:05:02:05] ENGINE Started monitor thread '_TimeoutMonitor'.
2012-08-17 05:02:06,026::INFO::[_cplogging:55] [17/Aug/2012:05:02:06] ENGINE Serving on 0.0.0.0:8080
2012-08-17 05:02:06,127::INFO::[_cplogging:55] [17/Aug/2012:05:02:06] ENGINE Serving on 0.0.0.0:9090
2012-08-17 05:02:06,127::INFO::[_cplogging:55] [17/Aug/2012:05:02:06] ENGINE Bus STARTED
2012-08-17 05:02:06,128::INFO::[panic:243] Lauching browser with https://localhost:9090/sabnzbd
2012-08-17 05:02:06,667::DEBUG::[misc:560] Checked for a new release, cur= 61599, latest= 70399 (on http://sourceforge.net/projects/sabnzbdplus/files/sabnzbdplus/0.7.3/)
2012-08-17 05:02:06,667::INFO::[SABnzbd:1471] Starting SABnzbd.py-0.6.15
2012-08-17 05:02:06,667::DEBUG::[__init__:300] Starting postprocessor
2012-08-17 05:02:06,668::DEBUG::[__init__:303] Starting assembler
2012-08-17 05:02:06,668::DEBUG::[__init__:306] Starting downloader
2012-08-17 05:02:06,669::DEBUG::[scheduler:172] Starting scheduler
2012-08-17 05:02:06,669::DEBUG::[__init__:311] Starting dirscanner
2012-08-17 05:02:06,669::INFO::[dirscanner:263] Dirscanner starting up
2012-08-17 05:02:06,670::DEBUG::[__init__:316] Starting urlgrabber
2012-08-17 05:02:06,670::INFO::[urlgrabber:75] URLGrabber starting up
One thing is that when I kick the orphaned job back into the queue, it actually shows up in the history. When I first uploaded the NZB, it doesn't appear in either the pending or history queues. I've also tried with the latest version from Sourceforge, Github and this older version and all have produced the same results.
Top
User avatar
sander
Release Testers
Release Testers
Posts: 9429
Joined: January 22nd, 2008, 2:22 pm

Re: Uploaded nzb straight to orphan job on Solaris Zone/Smar

Post by sander »

Code: Select all

Cannot remove folder /home/admin/Downloads/incomplete/<thing being downloaded>.1/__ADMIN__
can you investigate why that happens?
Top
modernpacifist
Newbie
Newbie
Posts: 6
Joined: August 16th, 2012, 11:09 pm

Re: Uploaded nzb straight to orphan job on Solaris Zone/Smar

Post by modernpacifist »

sander wrote:

Code: Select all

Cannot remove folder /home/admin/Downloads/incomplete/<thing being downloaded>.1/__ADMIN__
can you investigate why that happens?
This I'm not sure of. The folder is certainly there and is owned by the user that is running the process and running rm -rf on it manually (running not as root, but as the admin user running SABnzbd) removes the file as expected. The one thought that I do have though is that maybe the folder is getting removed by some part of the download process but then recreated after the download has failed?

When I can get the NZB to show up in the History (after I've gone into Status -> Queue Repair -> Add NZB back), it goes there instantly. Even if I have all downloading paused and I give the server configuration incorrect credentials, it still goes instantly to History.
Top
modernpacifist
Newbie
Newbie
Posts: 6
Joined: August 16th, 2012, 11:09 pm

Re: Uploaded nzb straight to orphan job on Solaris Zone/Smar

Post by modernpacifist »

I should also mention that the basic folder appears in the incomplete downloads folder (the actual one, not the incomplete within completed folder) as soon as I upload the NZB, and the ___ADMIN____ folder is present.
Top
modernpacifist
Newbie
Newbie
Posts: 6
Joined: August 16th, 2012, 11:09 pm

Re: Uploaded nzb straight to orphan job on Solaris Zone/Smar

Post by modernpacifist »

Updated logs running 0.7.x from Github master

Startup:

Code: Select all

2012-08-17 13:33:41,190::INFO::[SABnzbd:1257] --------------------------------
2012-08-17 13:33:41,190::INFO::[SABnzbd:1258] SABnzbd.py-0.7.x (rev=unknown)
2012-08-17 13:33:41,190::INFO::[SABnzbd:1270] Platform = posix
2012-08-17 13:33:41,191::INFO::[SABnzbd:1271] Python-version = 2.7.2 (default, May 11 2012, 13:40:50) 
[GCC 4.7.0]
2012-08-17 13:33:41,191::INFO::[SABnzbd:1272] Arguments = SABnzbd.py --logging 2
2012-08-17 13:33:41,191::INFO::[SABnzbd:1285] Read INI file /home/admin/.sabnzbd/sabnzbd.ini
2012-08-17 13:33:41,192::INFO::[__init__:919] Loading data for bookmarks.sab from /home/admin/.sabnzbd/admin/bookmarks.sab
2012-08-17 13:33:41,192::INFO::[__init__:919] Loading data for rss_data.sab from /home/admin/.sabnzbd/admin/rss_data.sab
2012-08-17 13:33:41,193::INFO::[__init__:919] Loading data for totals9.sab from /home/admin/.sabnzbd/admin/totals9.sab
2012-08-17 13:33:41,193::INFO::[__init__:922] /home/admin/.sabnzbd/admin/totals9.sab missing, trying old cache
2012-08-17 13:33:41,193::INFO::[__init__:925] /home/admin/.sabnzbd/cache/totals9.sab missing
2012-08-17 13:33:41,193::DEBUG::[bpsmeter:142] Setting default BPS meter values
2012-08-17 13:33:41,240::INFO::[postproc:90] Loading postproc queue
2012-08-17 13:33:41,240::INFO::[__init__:919] Loading data for postproc1.sab from /home/admin/.sabnzbd/admin/postproc1.sab
2012-08-17 13:33:41,240::INFO::[__init__:919] Loading data for queue9.sab from /home/admin/.sabnzbd/admin/queue9.sab
2012-08-17 13:33:41,241::DEBUG::[downloader:124] Initializing downloader/decoder
2012-08-17 13:33:41,241::INFO::[__init__:919] Loading data for watched_data.sab from /home/admin/.sabnzbd/admin/watched_data.sab
2012-08-17 13:33:41,242::DEBUG::[scheduler:136] Scheduling RSS interval task every 60 min (delay=7)
2012-08-17 13:33:41,242::DEBUG::[scheduler:147] Scheduling VersionCheck on day 2 at 9:49
2012-08-17 13:33:41,242::INFO::[downloader:208] Resuming
2012-08-17 13:33:41,242::DEBUG::[__init__:528] PAUSED_ALL inactive
2012-08-17 13:33:41,242::INFO::[__init__:318] All processes started
2012-08-17 13:33:41,243::INFO::[SABnzbd:333] Web dir is /opt/SABnzbd/interfaces/Classic
2012-08-17 13:33:41,243::INFO::[SABnzbd:333] Web dir is /opt/SABnzbd/interfaces/Config
2012-08-17 13:33:41,247::INFO::[SABnzbd:462] _yenc module... found!
2012-08-17 13:33:41,248::INFO::[SABnzbd:470] par2 binary... found (/opt/local/bin/par2)
2012-08-17 13:33:41,248::INFO::[SABnzbd:478] unrar binary... found (/opt/local/bin/unrar)
2012-08-17 13:33:41,248::INFO::[SABnzbd:483] unzip binary... found (/opt/local/bin/unzip)
2012-08-17 13:33:41,248::INFO::[SABnzbd:489] nice binary... found (/opt/local/bin/nice)
2012-08-17 13:33:41,248::INFO::[SABnzbd:495] ionice binary... NOT found!
2012-08-17 13:33:41,248::INFO::[SABnzbd:498] pyOpenSSL... found (True)
2012-08-17 13:33:41,250::INFO::[SABnzbd:1468] Starting web-interface on 0.0.0.0:9090
2012-08-17 13:33:41,251::INFO::[_cplogging:55] [17/Aug/2012:13:33:41] ENGINE Bus STARTING
2012-08-17 13:33:41,253::INFO::[_cplogging:55] [17/Aug/2012:13:33:41] ENGINE Started monitor thread '_TimeoutMonitor'.
2012-08-17 13:33:41,354::INFO::[_cplogging:55] [17/Aug/2012:13:33:41] ENGINE Serving on 0.0.0.0:8080
2012-08-17 13:33:41,455::INFO::[_cplogging:55] [17/Aug/2012:13:33:41] ENGINE Serving on 0.0.0.0:9090
2012-08-17 13:33:41,455::INFO::[_cplogging:55] [17/Aug/2012:13:33:41] ENGINE Bus STARTED
2012-08-17 13:33:41,456::INFO::[panic:247] Lauching browser with https://localhost:9090/sabnzbd
2012-08-17 13:33:41,456::INFO::[notifier:87] Sending registration to localhost:23053
2012-08-17 13:33:41,465::DEBUG::[notifier:156] To : localhost:23053 <register>
2012-08-17 13:33:41,466::DEBUG::[growler:169] Cannot register with Growl [Errno 146] Connection refused
2012-08-17 13:33:41,947::DEBUG::[misc:558] Unsupported release number (0.7.x), will not check
2012-08-17 13:33:41,947::INFO::[SABnzbd:1537] Starting SABnzbd.py-0.7.x
2012-08-17 13:33:41,947::DEBUG::[__init__:329] Starting postprocessor
2012-08-17 13:33:41,947::DEBUG::[__init__:332] Starting assembler
2012-08-17 13:33:41,948::DEBUG::[__init__:335] Starting downloader
2012-08-17 13:33:41,948::DEBUG::[scheduler:179] Starting scheduler
2012-08-17 13:33:41,949::DEBUG::[__init__:340] Starting dirscanner
2012-08-17 13:33:41,949::INFO::[dirscanner:265] Dirscanner starting up
2012-08-17 13:33:41,949::DEBUG::[__init__:345] Starting urlgrabber
2012-08-17 13:33:41,950::INFO::[urlgrabber:77] URLGrabber starting up
Adding NZB

Code: Select all

2012-08-17 13:35:10,623::INFO::[__init__:609] Adding <thing downloading>.nzb
2012-08-17 13:35:10,625::INFO::[misc:792] Creating directories: /home/admin/Downloads/incomplete/<thing downloading>.3
Nothing appears in the pending queue. Going to Status -> Queue Repair and clicking Add.

Code: Select all

2012-08-17 13:36:37,708::DEBUG::[nzbqueue:164] Repair job <thing downloading>.2 without stored NZB
2012-08-17 13:36:37,709::INFO::[__init__:884] /home/admin/Downloads/incomplete/<thing downloading>.2/__ADMIN__/SABnzbd_nzo_oge3_9 removed
2012-08-17 13:36:37,709::INFO::[postproc:84] Saving postproc queue
2012-08-17 13:36:37,709::INFO::[__init__:895] Saving data for postproc1.sab in /home/admin/.sabnzbd/admin/postproc1.sab
2012-08-17 13:36:37,744::INFO::[postproc:280] Starting PostProcessing on <thing downloading>.2 => Repair:False, Unpack:False, Delete:True, Script:None, Cat:*
2012-08-17 13:36:37,745::INFO::[notifier:87] Sending registration to localhost:23053
2012-08-17 13:36:37,745::DEBUG::[notifier:156] To : localhost:23053 <register>
2012-08-17 13:36:37,746::DEBUG::[growler:169] Cannot register with Growl [Errno 146] Connection refused
2012-08-17 13:36:38,271::INFO::[postproc:519] Cleaning up <thing downloading>.2 (keep_basic=True)
2012-08-17 13:36:38,271::INFO::[misc:1260] Cannot remove folder /home/admin/Downloads/incomplete/<thing downloading>.2/__ADMIN__
2012-08-17 13:36:38,272::INFO::[misc:1260] Cannot remove folder /home/admin/Downloads/incomplete<thing downloading>.2/__ADMIN__
2012-08-17 13:36:38,272::INFO::[postproc:84] Saving postproc queue
2012-08-17 13:36:38,272::INFO::[__init__:895] Saving data for postproc1.sab in /home/admin/.sabnzbd/admin/postproc1.sab
2012-08-17 13:36:38,272::INFO::[downloader:243] Post-processing finished, resuming download
2012-08-17 13:36:41,282::INFO::[nzbqueue:176] Saving queue
2012-08-17 13:36:41,282::INFO::[__init__:895] Saving data for queue9.sab in /home/admin/.sabnzbd/admin/queue9.sab
2012-08-17 13:36:41,282::INFO::[__init__:895] Saving data for rss_data.sab in /home/admin/.sabnzbd/admin/rss_data.sab
2012-08-17 13:36:41,282::INFO::[__init__:895] Saving data for bookmarks.sab in /home/admin/.sabnzbd/admin/bookmarks.sab
2012-08-17 13:36:41,283::INFO::[__init__:895] Saving data for watched_data.sab in /home/admin/.sabnzbd/admin/watched_data.sab
2012-08-17 13:36:41,283::INFO::[postproc:84] Saving postproc queue
2012-08-17 13:36:41,283::INFO::[__init__:895] Saving data for postproc1.sab in /home/admin/.sabnzbd/admin/postproc1.sab
2012-08-17 13:36:41,283::INFO::[postproc:627] Queue has finished, launching: None (None)
Immediately send the NZB into the History with a "Download failed - Out of your server's retention?"
Top
modernpacifist
Newbie
Newbie
Posts: 6
Joined: August 16th, 2012, 11:09 pm

Re: Uploaded nzb straight to orphan job on Solaris Zone/Smar

Post by modernpacifist »

One complete cycle

Start Server -> Upload NZB -> No NZB so go through Queue Repair -> Download Failed

Code: Select all

2012-08-17 13:48:18,976::INFO::[SABnzbd:1257] --------------------------------
2012-08-17 13:48:18,976::INFO::[SABnzbd:1258] SABnzbd.py-0.7.x (rev=unknown)
2012-08-17 13:48:18,977::INFO::[SABnzbd:1270] Platform = posix
2012-08-17 13:48:18,977::INFO::[SABnzbd:1271] Python-version = 2.7.2 (default, May 11 2012, 13:40:50) 
[GCC 4.7.0]
2012-08-17 13:48:18,977::INFO::[SABnzbd:1272] Arguments = SABnzbd.py --logging 2
2012-08-17 13:48:18,977::INFO::[SABnzbd:1285] Read INI file /home/admin/.sabnzbd/sabnzbd.ini
2012-08-17 13:48:18,978::INFO::[__init__:919] Loading data for bookmarks.sab from /home/admin/.sabnzbd/admin/bookmarks.sab
2012-08-17 13:48:18,979::INFO::[__init__:919] Loading data for rss_data.sab from /home/admin/.sabnzbd/admin/rss_data.sab
2012-08-17 13:48:18,979::INFO::[__init__:919] Loading data for totals9.sab from /home/admin/.sabnzbd/admin/totals9.sab
2012-08-17 13:48:18,979::INFO::[__init__:922] /home/admin/.sabnzbd/admin/totals9.sab missing, trying old cache
2012-08-17 13:48:18,979::INFO::[__init__:925] /home/admin/.sabnzbd/cache/totals9.sab missing
2012-08-17 13:48:18,979::DEBUG::[bpsmeter:142] Setting default BPS meter values
2012-08-17 13:48:19,022::INFO::[postproc:90] Loading postproc queue
2012-08-17 13:48:19,023::INFO::[__init__:919] Loading data for postproc1.sab from /home/admin/.sabnzbd/admin/postproc1.sab
2012-08-17 13:48:19,023::INFO::[__init__:919] Loading data for queue9.sab from /home/admin/.sabnzbd/admin/queue9.sab
2012-08-17 13:48:19,023::DEBUG::[downloader:124] Initializing downloader/decoder
2012-08-17 13:48:19,024::INFO::[__init__:919] Loading data for watched_data.sab from /home/admin/.sabnzbd/admin/watched_data.sab
2012-08-17 13:48:19,024::DEBUG::[scheduler:136] Scheduling RSS interval task every 60 min (delay=50)
2012-08-17 13:48:19,025::DEBUG::[scheduler:147] Scheduling VersionCheck on day 2 at 8:29
2012-08-17 13:48:19,025::INFO::[downloader:208] Resuming
2012-08-17 13:48:19,025::DEBUG::[__init__:528] PAUSED_ALL inactive
2012-08-17 13:48:19,025::INFO::[__init__:318] All processes started
2012-08-17 13:48:19,025::INFO::[SABnzbd:333] Web dir is /opt/SABnzbd/interfaces/Plush
2012-08-17 13:48:19,026::INFO::[SABnzbd:333] Web dir is /opt/SABnzbd/interfaces/Config
2012-08-17 13:48:19,030::INFO::[SABnzbd:462] _yenc module... found!
2012-08-17 13:48:19,030::INFO::[SABnzbd:470] par2 binary... found (/opt/local/bin/par2)
2012-08-17 13:48:19,030::INFO::[SABnzbd:478] unrar binary... found (/opt/local/bin/unrar)
2012-08-17 13:48:19,030::INFO::[SABnzbd:483] unzip binary... found (/opt/local/bin/unzip)
2012-08-17 13:48:19,031::INFO::[SABnzbd:489] nice binary... found (/opt/local/bin/nice)
2012-08-17 13:48:19,031::INFO::[SABnzbd:495] ionice binary... NOT found!
2012-08-17 13:48:19,031::INFO::[SABnzbd:498] pyOpenSSL... found (True)
2012-08-17 13:48:19,033::INFO::[SABnzbd:1468] Starting web-interface on 0.0.0.0:9090
2012-08-17 13:48:19,033::INFO::[_cplogging:55] [17/Aug/2012:13:48:19] ENGINE Bus STARTING
2012-08-17 13:48:19,036::INFO::[_cplogging:55] [17/Aug/2012:13:48:19] ENGINE Started monitor thread '_TimeoutMonitor'.
2012-08-17 13:48:19,136::INFO::[_cplogging:55] [17/Aug/2012:13:48:19] ENGINE Serving on 0.0.0.0:8080
2012-08-17 13:48:19,337::INFO::[_cplogging:55] [17/Aug/2012:13:48:19] ENGINE Serving on 0.0.0.0:9090
2012-08-17 13:48:19,338::INFO::[_cplogging:55] [17/Aug/2012:13:48:19] ENGINE Bus STARTED
2012-08-17 13:48:19,338::INFO::[panic:247] Lauching browser with https://localhost:9090/sabnzbd
2012-08-17 13:48:19,339::INFO::[notifier:87] Sending registration to localhost:23053
2012-08-17 13:48:19,348::DEBUG::[notifier:156] To : localhost:23053 <register>
2012-08-17 13:48:19,348::DEBUG::[growler:169] Cannot register with Growl [Errno 146] Connection refused
2012-08-17 13:48:19,829::DEBUG::[misc:558] Unsupported release number (0.7.x), will not check
2012-08-17 13:48:19,829::INFO::[SABnzbd:1537] Starting SABnzbd.py-0.7.x
2012-08-17 13:48:19,830::DEBUG::[__init__:329] Starting postprocessor
2012-08-17 13:48:19,830::DEBUG::[__init__:332] Starting assembler
2012-08-17 13:48:19,830::DEBUG::[__init__:335] Starting downloader
2012-08-17 13:48:19,831::DEBUG::[scheduler:179] Starting scheduler
2012-08-17 13:48:19,831::DEBUG::[__init__:340] Starting dirscanner
2012-08-17 13:48:19,832::INFO::[dirscanner:265] Dirscanner starting up
2012-08-17 13:48:19,832::DEBUG::[__init__:345] Starting urlgrabber
2012-08-17 13:48:19,832::INFO::[urlgrabber:77] URLGrabber starting up
2012-08-17 13:48:29,328::INFO::[__init__:609] Adding <download>.nzb
2012-08-17 13:48:29,330::INFO::[misc:792] Creating directories: /home/admin/Downloads/incomplete/<download>
2012-08-17 13:48:38,926::DEBUG::[nzbqueue:164] Repair job <download> without stored NZB
2012-08-17 13:48:38,927::INFO::[__init__:884] /home/admin/Downloads/incomplete/<download>/__ADMIN__/SABnzbd_nzo_5QEzKJ removed
2012-08-17 13:48:38,927::INFO::[postproc:84] Saving postproc queue
2012-08-17 13:48:38,928::INFO::[__init__:895] Saving data for postproc1.sab in /home/admin/.sabnzbd/admin/postproc1.sab
2012-08-17 13:48:38,948::INFO::[postproc:280] Starting PostProcessing on <download> => Repair:False, Unpack:False, Delete:True, Script:None, Cat:*
2012-08-17 13:48:38,949::INFO::[notifier:87] Sending registration to localhost:23053
2012-08-17 13:48:38,949::DEBUG::[notifier:156] To : localhost:23053 <register>
2012-08-17 13:48:38,950::DEBUG::[growler:169] Cannot register with Growl [Errno 146] Connection refused
2012-08-17 13:48:39,488::INFO::[postproc:519] Cleaning up <download> (keep_basic=True)
2012-08-17 13:48:39,489::INFO::[misc:1260] Cannot remove folder /home/admin/Downloads/incomplete/<download>/__ADMIN__
2012-08-17 13:48:39,489::INFO::[misc:1260] Cannot remove folder /home/admin/Downloads/incomplete/<download>/__ADMIN__
2012-08-17 13:48:39,490::INFO::[postproc:84] Saving postproc queue
2012-08-17 13:48:39,490::INFO::[__init__:895] Saving data for postproc1.sab in /home/admin/.sabnzbd/admin/postproc1.sab
2012-08-17 13:48:39,490::INFO::[downloader:243] Post-processing finished, resuming download
2012-08-17 13:48:42,500::INFO::[nzbqueue:176] Saving queue
2012-08-17 13:48:42,500::INFO::[__init__:895] Saving data for queue9.sab in /home/admin/.sabnzbd/admin/queue9.sab
2012-08-17 13:48:42,500::INFO::[__init__:895] Saving data for rss_data.sab in /home/admin/.sabnzbd/admin/rss_data.sab
2012-08-17 13:48:42,500::INFO::[__init__:895] Saving data for bookmarks.sab in /home/admin/.sabnzbd/admin/bookmarks.sab
2012-08-17 13:48:42,501::INFO::[__init__:895] Saving data for watched_data.sab in /home/admin/.sabnzbd/admin/watched_data.sab
2012-08-17 13:48:42,501::INFO::[postproc:84] Saving postproc queue
2012-08-17 13:48:42,501::INFO::[__init__:895] Saving data for postproc1.sab in /home/admin/.sabnzbd/admin/postproc1.sab
2012-08-17 13:48:42,501::INFO::[postproc:627] Queue has finished, launching: None (None)
Top
modernpacifist
Newbie
Newbie
Posts: 6
Joined: August 16th, 2012, 11:09 pm

Re: Uploaded nzb straight to orphan job on Solaris Zone/Smar

Post by modernpacifist »

Got things working - turns out that I was missing an XML parser all along. ProcessSingleFile was returning -1 which seems to indicate an issue with parsing the NZB file. When I exposed the exception that was getting thrown, I found this:

Code: Select all

Traceback (most recent call last):
  File "/opt/SABnzbd/sabnzbd/dirscanner.py", line 170, in ProcessSingleFile
    nzo_info=nzo_info, url=url, reuse=reuse, dup_check=dup_check)
  File "/opt/SABnzbd/sabnzbd/nzbstuff.py", line 670, in __init__
    parser = xml.sax.make_parser()
  File "/opt/local/lib/python2.7/xml/sax/__init__.py", line 93, in make_parser
    raise SAXReaderNotAvailable("No parsers found", None)
Running

Code: Select all

sudo pkgin in libxml2 py27-libxml2 py27-xml
within the zone resolved that issue and stuff now downloads! Thanks to _zoggy_ for all your assistance.
Top
Post Reply

Return to “General Help”