Page 1 of 1

SABNZBD stuck in Idle

Posted: May 30th, 2015, 11:34 am
by pminkler
Recently, I purged all failed files from the history (there were a lot). Since then, Sab has been stuck on Idle.

I think, but I can't be sure, that it switched over to Idle based on scheduling...since it should be on Idle based on the schedule.

To fix this, I've tried:

Removing all schedules...then restarting
Toggling Pause/resume a bunch of times...restarting
Choosing different items in the queue to download
Removing the queue...restarting
Removing the history...restarting
rm -rf on .sabnzb/admin...restarting
Removing all incomplete files on disk
sudo apt-get remove sabnzbdplus and then install
sudo apt-get purge sabnzbdplus and then install
Reinstall while deleting everything known to man that has sabnzbd in the name
Disabling different providers

Fresh install and no history...still stuck in Idle.

Does this mean it HAS to be my provider? This was just working this morning. I'm out of ideas?

Log when restarting SAB:

^A2015-05-30 12:37:21,855::INFO::[sabnzbdplus:1244] --------------------------------
2015-05-30 12:37:21,855::INFO::[sabnzbdplus:1245] sabnzbdplus-0.7.16 (rev=14f39a21e3aa063d128f16505032e759c7ba3265)
2015-05-30 12:37:21,855::INFO::[sabnzbdplus:1257] Platform = posix
2015-05-30 12:37:21,855::INFO::[sabnzbdplus:1258] Python-version = 2.7.6 (default, Mar 22 2014, 22:59:56)
[GCC 4.8.2]
2015-05-30 12:37:21,855::INFO::[sabnzbdplus:1259] Arguments = /usr/bin/sabnzbdplus --daemon
2015-05-30 12:37:21,855::INFO::[sabnzbdplus:1272] Read INI file /home/phil/.sabnzbd/sabnzbd.ini
2015-05-30 12:37:21,856::INFO::[__init__:928] Loading data for bookmarks.sab from /home/phil/.sabnzbd/admin/bookmarks.sab
2015-05-30 12:37:21,857::INFO::[__init__:928] Loading data for rss_data.sab from /home/phil/.sabnzbd/admin/rss_data.sab
2015-05-30 12:37:21,857::INFO::[__init__:928] Loading data for totals9.sab from /home/phil/.sabnzbd/admin/totals9.sab
2015-05-30 12:37:21,857::INFO::[__init__:931] /home/phil/.sabnzbd/admin/totals9.sab missing, trying old cache
2015-05-30 12:37:21,857::INFO::[__init__:934] /home/phil/.sabnzbd/cache/totals9.sab missing
2015-05-30 12:37:21,981::INFO::[postproc:90] Loading postproc queue
2015-05-30 12:37:21,981::INFO::[__init__:928] Loading data for postproc1.sab from /home/phil/.sabnzbd/admin/postproc1.sab
2015-05-30 12:37:21,981::INFO::[__init__:928] Loading data for queue9.sab from /home/phil/.sabnzbd/admin/queue9.sab
2015-05-30 12:37:21,987::INFO::[__init__:928] Loading data for watched_data.sab from /home/phil/.sabnzbd/admin/watched_data.sab
2015-05-30 12:37:21,987::INFO::[scheduler:172] Setting schedule for midnight BPS reset
2015-05-30 12:37:21,987::INFO::[downloader:211] Resuming
2015-05-30 12:37:21,993::INFO::[__init__:330] All processes started
2015-05-30 12:37:21,993::INFO::[sabnzbdplus:335] Web dir is /usr/share/sabnzbdplus/interfaces/smpl
2015-05-30 12:37:21,993::INFO::[sabnzbdplus:335] Web dir is /usr/share/sabnzbdplus/interfaces/Config
2015-05-30 12:37:22,003::INFO::[sabnzbdplus:451] _yenc module... found!
2015-05-30 12:37:22,003::INFO::[sabnzbdplus:459] par2 binary... found (/usr/bin/par2)
2015-05-30 12:37:22,003::INFO::[sabnzbdplus:467] unrar binary... found (/usr/bin/unrar)
2015-05-30 12:37:22,003::INFO::[sabnzbdplus:472] unzip binary... found (/usr/bin/unzip)
2015-05-30 12:37:22,003::INFO::[sabnzbdplus:478] nice binary... found (/usr/bin/nice)
2015-05-30 12:37:22,004::INFO::[sabnzbdplus:482] ionice binary... found (/usr/bin/ionice)
2015-05-30 12:37:22,004::INFO::[sabnzbdplus:487] pyOpenSSL... found (True)
2015-05-30 12:37:22,005::INFO::[sabnzbdplus:1471] Starting web-interface on 0.0.0.0:7990
2015-05-30 12:37:23,006::INFO::[_cplogging:55] [30/May/2015:12:37:23] ENGINE Bus STARTING
2015-05-30 12:37:23,009::INFO::[_cplogging:55] [30/May/2015:12:37:23] ENGINE Started monitor thread '_TimeoutMonitor'.

Re: SABNZBD stuck in Idle

Posted: May 30th, 2015, 11:47 am
by pminkler
I did see more errors after it starts and has been running for maybe 30sec (the timestamp doesn't match the above because I restarted multiple times to make sure I was always getting the error)...

2015-05-30 12:41:08,671::ERROR::[_cplogging:55] [30/May/2015:12:41:08] ENGINE Error in 'start' listener <bound method Server.start of <cherrypy._cpserver.Server object at 0x7efee492f490>>
Traceback (most recent call last):
File "/usr/share/sabnzbdplus/cherrypy/process/wspbus.py", line 147, in publish
output.append(listener(*args, **kwargs))
File "/usr/share/sabnzbdplus/cherrypy/_cpserver.py", line 90, in start
ServerAdapter.start(self)
File "/usr/share/sabnzbdplus/cherrypy/process/servers.py", line 62, in start
self.wait()
File "/usr/share/sabnzbdplus/cherrypy/process/servers.py", line 103, in wait
wait_for_occupied_port(host, port)
File "/usr/share/sabnzbdplus/cherrypy/process/servers.py", line 275, in wait_for_occupied_port
raise IOError("Port %r not bound on %r" % (port, host))
IOError: Port 7990 not bound on '0.0.0.0'

2015-05-30 12:41:08,671::ERROR::[_cplogging:55] [30/May/2015:12:41:08] ENGINE Shutting down due to error in start listener:
Traceback (most recent call last):
File "/usr/share/sabnzbdplus/cherrypy/process/wspbus.py", line 184, in start
self.publish('start')
File "/usr/share/sabnzbdplus/cherrypy/process/wspbus.py", line 147, in publish
output.append(listener(*args, **kwargs))
File "/usr/share/sabnzbdplus/cherrypy/_cpserver.py", line 90, in start
ServerAdapter.start(self)
File "/usr/share/sabnzbdplus/cherrypy/process/servers.py", line 62, in start
self.wait()
File "/usr/share/sabnzbdplus/cherrypy/process/servers.py", line 103, in wait
wait_for_occupied_port(host, port)
File "/usr/share/sabnzbdplus/cherrypy/process/servers.py", line 275, in wait_for_occupied_port
raise IOError("Port %r not bound on %r" % (port, host))
IOError: Port 7990 not bound on '0.0.0.0'

2015-05-30 12:41:08,671::INFO::[_cplogging:55] [30/May/2015:12:41:08] ENGINE Bus STOPPING
2015-05-30 12:41:08,672::INFO::[_cplogging:55] [30/May/2015:12:41:08] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('0.0.0.0', 7990)) already shut down
2015-05-30 12:41:08,675::INFO::[_cplogging:55] [30/May/2015:12:41:08] ENGINE Stopped thread '_TimeoutMonitor'.
2015-05-30 12:41:08,675::INFO::[_cplogging:55] [30/May/2015:12:41:08] ENGINE Bus STOPPED
2015-05-30 12:41:08,675::INFO::[_cplogging:55] [30/May/2015:12:41:08] ENGINE Bus EXITING
2015-05-30 12:41:08,675::INFO::[_cplogging:55] [30/May/2015:12:41:08] ENGINE Bus EXITED
2015-05-30 12:41:08,675::ERROR::[sabnzbdplus:1486] Failed to start web-interface:
Traceback (most recent call last):
File "/usr/bin/sabnzbdplus", line 1478, in main
cherrypy.engine.start()
File "/usr/share/sabnzbdplus/cherrypy/process/wspbus.py", line 184, in start
self.publish('start')
File "/usr/share/sabnzbdplus/cherrypy/process/wspbus.py", line 147, in publish
output.append(listener(*args, **kwargs))
File "/usr/share/sabnzbdplus/cherrypy/_cpserver.py", line 90, in start
ServerAdapter.start(self)
File "/usr/share/sabnzbdplus/cherrypy/process/servers.py", line 62, in start
self.wait()
File "/usr/share/sabnzbdplus/cherrypy/process/servers.py", line 103, in wait
wait_for_occupied_port(host, port)
File "/usr/share/sabnzbdplus/cherrypy/process/servers.py", line 275, in wait_for_occupied_port
raise IOError("Port %r not bound on %r" % (port, host))
IOError: Port 7990 not bound on '0.0.0.0'
2015-05-30 12:41:08,676::ERROR::[sabnzbdplus:304] Failed to start web-interface : Port 7990 not bound on '0.0.0.0'
2015-05-30 12:41:08,676::INFO::[__init__:366] SABnzbd shutting down...
2015-05-30 12:41:08,677::INFO::[__init__:904] Saving data for bookmarks.sab in /home/phil/.sabnzbd/admin/bookmarks.sab
2015-05-30 12:41:08,677::INFO::[urlgrabber:72] URLGrabber shutting down
2015-05-30 12:41:08,677::INFO::[__init__:904] Saving data for watched_data.sab in /home/phil/.sabnzbd/admin/watched_data.sab
2015-05-30 12:41:08,678::INFO::[dirscanner:265] Dirscanner shutting down
2015-05-30 12:41:09,179::INFO::[postproc:84] Saving postproc queue
2015-05-30 12:41:09,179::INFO::[__init__:904] Saving data for postproc1.sab in /home/phil/.sabnzbd/admin/postproc1.sab
2015-05-30 12:41:09,180::INFO::[nzbqueue:211] Saving queue
2015-05-30 12:41:09,185::INFO::[__init__:904] Saving data for queue9.sab in /home/phil/.sabnzbd/admin/queue9.sab
2015-05-30 12:41:09,185::INFO::[__init__:904] Saving data for rss_data.sab in /home/phil/.sabnzbd/admin/rss_data.sab
2015-05-30 12:41:09,186::INFO::[__init__:904] Saving data for bookmarks.sab in /home/phil/.sabnzbd/admin/bookmarks.sab
2015-05-30 12:41:09,186::INFO::[__init__:904] Saving data for watched_data.sab in /home/phil/.sabnzbd/admin/watched_data.sab
2015-05-30 12:41:09,187::INFO::[postproc:84] Saving postproc queue
2015-05-30 12:41:09,187::INFO::[__init__:904] Saving data for postproc1.sab in /home/phil/.sabnzbd/admin/postproc1.sab
2015-05-30 12:41:09,187::INFO::[__init__:426] All processes stopped

Re: SABNZBD stuck in Idle

Posted: May 30th, 2015, 11:53 am
by pminkler
It says Port 7990 not bound on 0.0.0.0 ... but I'm looking at the interface right now.

ps aux | grep sabnzbd just shows:


phil 15896 0.0 0.0 7232 616 pts/5 S+ 12:07 0:00 tail -f sabnzbd.error.log sabnzbd.log
phil 16877 0.2 0.4 989596 36664 ? Sl 12:40 0:02 /usr/bin/python -OO /usr/bin/sabnzbdplus --daemon
phil 17139 0.0 0.0 11740 932 pts/6 S+ 12:53 0:00 grep --color=auto sab

Re: SABNZBD stuck in Idle

Posted: May 31st, 2015, 1:09 am
by sander
What do you get when you go to http://localhost:7990/ ? Can you post a screenshot?

What if you click on Test Server (in Config -> Servers)?