Page 1 of 1

SABnzbd server errors (Debian Lenny ARM)

Posted: June 14th, 2010, 5:09 am
by langerak
Hi all,

I have something that i can't seem to fix, so i need your help on this matter:

I have a LaCie ED Mini V2 running Debian Lenny on ARM platform. I have Python 2.5.5 installed and the latest required python libraries (PyOpenSSL, Cheetah, Yenc).

Everytime i start Sabnzbd it start and i can access the webinterface, but after 10 seconds or so it kills itsels saying there was a timeout.

Here is the output from the commandline:

Code: Select all

2010-06-14 09:53:00,184::INFO::[SABnzbd:952] --------------------------------
2010-06-14 09:53:00,193::INFO::[SABnzbd:953] SABnzbd.py-0.5.2 (rev=2412)
2010-06-14 09:53:00,202::INFO::[SABnzbd:963] Platform = posix
2010-06-14 09:53:00,209::INFO::[SABnzbd:964] Python-version = 2.5.5 (r255:77872, Jun 13 2010, 12:54:24) 
[GCC 4.3.2]
2010-06-14 09:53:01,173::INFO::[__init__:662] Loading data for bookmarks.sab from /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/bookmarks.sab
2010-06-14 09:53:01,192::INFO::[__init__:693] /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/bookmarks.sab removed
2010-06-14 09:53:01,203::INFO::[__init__:662] Loading data for rss_data.sab from /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/rss_data.sab
2010-06-14 09:53:01,221::INFO::[__init__:662] Loading data for bytes9.sab from /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/bytes9.sab
2010-06-14 09:53:01,234::INFO::[__init__:662] Loading data for queue9.sab from /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/queue9.sab
2010-06-14 09:53:01,246::INFO::[postproc:139] Loading postproc queue
2010-06-14 09:53:01,256::INFO::[__init__:662] Loading data for postproc1.sab from /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/postproc1.sab
2010-06-14 09:53:01,268::INFO::[__init__:693] /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/postproc1.sab removed
2010-06-14 09:53:01,295::INFO::[__init__:662] Loading data for watched_data.sab from /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/watched_data.sab
2010-06-14 09:53:01,314::INFO::[downloader:327] Resuming
2010-06-14 09:53:01,575::INFO::[__init__:250] All processes started
2010-06-14 09:53:01,585::INFO::[SABnzbd:265] Web dir is /home/jeffrey/Downloads/SABnzbd-0.5.2/interfaces/Plush
2010-06-14 09:53:01,953::INFO::[SABnzbd:1017] Starting SABnzbd.py-0.5.2
2010-06-14 09:53:02,002::INFO::[dirscanner:367] Dirscanner starting up
2010-06-14 09:53:02,022::INFO::[urlgrabber:103] URLGrabber starting up
2010-06-14 09:53:02,032::INFO::[SABnzbd:386] _yenc module... found!
2010-06-14 09:53:02,039::INFO::[SABnzbd:394] par2 binary... found (/usr/bin/par2)
2010-06-14 09:53:02,048::INFO::[SABnzbd:402] unrar binary... found (/usr/bin/unrar)
2010-06-14 09:53:02,057::INFO::[SABnzbd:407] unzip binary... found (/usr/bin/unzip)
2010-06-14 09:53:02,065::INFO::[SABnzbd:413] nice binary... found (/usr/bin/nice)
2010-06-14 09:53:02,099::INFO::[SABnzbd:417] ionice binary... found (/usr/bin/ionice)
2010-06-14 09:53:02,108::INFO::[SABnzbd:422] pyOpenSSL... found (True)
2010-06-14 09:53:02,151::INFO::[SABnzbd:1115] Starting web-interface on 192.168.0.78:9090
2010-06-14 09:53:03,173::INFO::[_cplogging:55] [14/Jun/2010:09:53:03] ENGINE Bus STARTING
2010-06-14 09:53:03,252::INFO::[_cplogging:55] [14/Jun/2010:09:53:03] ENGINE Started monitor thread '_TimeoutMonitor'.
2010-06-14 09:53:59,519::ERROR::[_cplogging:55] [14/Jun/2010:09:53:59] ENGINE Error in 'start' listener <bound method Server.start of <cherrypy._cpserver.Server object at 0x34deac>>
Traceback (most recent call last):
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/wspbus.py", line 147, in publish
    output.append(listener(*args, **kwargs))
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/_cpserver.py", line 90, in start
    ServerAdapter.start(self)
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/servers.py", line 62, in start
    self.wait()
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/servers.py", line 103, in wait
    wait_for_occupied_port(host, port)
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/servers.py", line 275, in wait_for_occupied_port
    raise IOError("Port %r not bound on %r" % (port, host))
IOError: Port 9090 not bound on '192.168.0.78'

2010-06-14 09:53:59,558::ERROR::[_cplogging:55] [14/Jun/2010:09:53:59] ENGINE Shutting down due to error in start listener:
Traceback (most recent call last):
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/wspbus.py", line 184, in start
    self.publish('start')
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/wspbus.py", line 147, in publish
    output.append(listener(*args, **kwargs))
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/_cpserver.py", line 90, in start
    ServerAdapter.start(self)
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/servers.py", line 62, in start
    self.wait()
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/servers.py", line 103, in wait
    wait_for_occupied_port(host, port)
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/servers.py", line 275, in wait_for_occupied_port
    raise IOError("Port %r not bound on %r" % (port, host))
IOError: Port 9090 not bound on '192.168.0.78'

2010-06-14 09:53:59,589::INFO::[_cplogging:55] [14/Jun/2010:09:53:59] ENGINE Bus STOPPING
2010-06-14 09:53:59,603::INFO::[_cplogging:55] [14/Jun/2010:09:53:59] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('192.168.0.78', 9090)) already shut down
2010-06-14 09:53:59,653::INFO::[_cplogging:55] [14/Jun/2010:09:53:59] ENGINE Stopped thread '_TimeoutMonitor'.
2010-06-14 09:53:59,663::INFO::[_cplogging:55] [14/Jun/2010:09:53:59] ENGINE Bus STOPPED
2010-06-14 09:53:59,672::INFO::[_cplogging:55] [14/Jun/2010:09:53:59] ENGINE Bus EXITING
2010-06-14 09:53:59,682::INFO::[_cplogging:55] [14/Jun/2010:09:53:59] ENGINE Bus EXITED
2010-06-14 09:53:59,691::ERROR::[SABnzbd:238] Failed to start web-interface
2010-06-14 09:53:59,702::INFO::[__init__:286] SABnzbd shutting down...
2010-06-14 09:53:59,713::INFO::[__init__:641] Saving data for bookmarks.sab in /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/bookmarks.sab
2010-06-14 09:53:59,723::INFO::[urlgrabber:98] URLGrabber shutting down
2010-06-14 09:53:59,739::INFO::[__init__:641] Saving data for watched_data.sab in /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/watched_data.sab
2010-06-14 09:53:59,748::INFO::[dirscanner:281] Dirscanner shutting down
2010-06-14 09:54:00,599::INFO::[downloader:483] Shutting down
2010-06-14 09:54:00,633::INFO::[assembler:102] Shutting down
2010-06-14 09:54:00,645::INFO::[postproc:134] Saving postproc queue
2010-06-14 09:54:00,672::INFO::[__init__:641] Saving data for postproc1.sab in /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/postproc1.sab
2010-06-14 09:54:00,683::INFO::[nzbqueue:92] Saving queue
2010-06-14 09:54:00,694::INFO::[__init__:641] Saving data for queue9.sab in /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/queue9.sab
2010-06-14 09:54:00,705::INFO::[__init__:641] Saving data for bytes9.sab in /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/bytes9.sab
2010-06-14 09:54:00,717::INFO::[__init__:641] Saving data for rss_data.sab in /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/rss_data.sab
2010-06-14 09:54:00,729::INFO::[__init__:641] Saving data for bookmarks.sab in /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/bookmarks.sab
2010-06-14 09:54:00,742::INFO::[__init__:641] Saving data for watched_data.sab in /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/watched_data.sab
2010-06-14 09:54:00,752::INFO::[postproc:134] Saving postproc queue
2010-06-14 09:54:00,762::INFO::[__init__:641] Saving data for postproc1.sab in /home/jeffrey/Downloads/SABnzbd-0.5.2/cache/postproc1.sab
2010-06-14 09:54:00,775::INFO::[__init__:321] All processes stopped
Exception in thread HTTPServer Thread-10 (most likely raised during interpreter shutdown):
Traceback (most recent call last):
  File "/usr/local/lib/python2.5/threading.py", line 486, in __bootstrap_inner
  File "/usr/local/lib/python2.5/threading.py", line 446, in run
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/servers.py", line 89, in _start_http_thread
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/wspbus.py", line 341, in log
<type 'exceptions.AttributeError'>: 'NoneType' object has no attribute 'exc_info'
Unhandled exception in thread started by <bound method Thread.__bootstrap of <Thread(HTTPServer Thread-10, started)>>
Traceback (most recent call last):
  File "/usr/local/lib/python2.5/threading.py", line 462, in __bootstrap
    self.__bootstrap_inner()
  File "/usr/local/lib/python2.5/threading.py", line 527, in __bootstrap_inner
    _active_limbo_lock.acquire()
AttributeError: 'NoneType' object has no attribute 'acquire'
Does anyone have a solution for this? I know it ain't a powerful box, but for my purposes it suits well :).

Re: SABnzbd server errors (Debian Lenny ARM)

Posted: June 14th, 2010, 6:22 am
by shypike
How do you start SABnzbd.
It attempts to open port 9090, which is the default for the HTTPS port.
Try with https support off.

Re: SABnzbd server errors (Debian Lenny ARM)

Posted: June 14th, 2010, 8:10 am
by langerak
sudo python SABnzbd.py -f /home/jeffrey/.sabnzbd/sabnzbd.ini

In my configfile i have specified the use of HTTPS, i'll try to disable that tonight when i get home :).

Re: SABnzbd server errors (Debian Lenny ARM)

Posted: June 14th, 2010, 8:43 am
by langerak
Ok, with HTTPS off it complains about the same thing, but now about port 9090 (HTTP) not being accessible...

Re: SABnzbd server errors (Debian Lenny ARM)

Posted: June 14th, 2010, 11:49 am
by shypike
Is the host:port combination available?
Is 192.168.0.78 the correct IP address of your system?
Have you tried 127.0.0.1:8080 ?
Do you have any firewall?

Re: SABnzbd server errors (Debian Lenny ARM)

Posted: June 14th, 2010, 12:03 pm
by langerak
Hi,

It sure is available! When trying 127.0.0.1 it quits immediately. Tried port 9000 as well but still no luck. AFAIK there is no firewall on this machine...

When trying without HTTPS enabled i get this:

Code: Select all

2010-06-14 16:48:37,918::INFO::[SABnzbd:952] --------------------------------
2010-06-14 16:48:37,926::INFO::[SABnzbd:953] SABnzbd.py-0.5.2 (rev=2412)
2010-06-14 16:48:37,935::INFO::[SABnzbd:963] Platform = posix
2010-06-14 16:48:37,943::INFO::[SABnzbd:964] Python-version = 2.5.5 (r255:77872, Jun 13 2010, 12:54:24) 
[GCC 4.3.2]
2010-06-14 16:48:39,478::INFO::[__init__:662] Loading data for bookmarks.sab from /home/jeffrey/.sabnzbd/cache/bookmarks.sab
2010-06-14 16:48:39,489::INFO::[__init__:693] /home/jeffrey/.sabnzbd/cache/bookmarks.sab removed
2010-06-14 16:48:39,500::INFO::[__init__:662] Loading data for rss_data.sab from /home/jeffrey/.sabnzbd/cache/rss_data.sab
2010-06-14 16:48:39,518::INFO::[__init__:662] Loading data for bytes9.sab from /home/jeffrey/.sabnzbd/cache/bytes9.sab
2010-06-14 16:48:39,529::INFO::[__init__:662] Loading data for queue9.sab from /home/jeffrey/.sabnzbd/cache/queue9.sab
2010-06-14 16:48:39,541::INFO::[postproc:139] Loading postproc queue
2010-06-14 16:48:39,551::INFO::[__init__:662] Loading data for postproc1.sab from /home/jeffrey/.sabnzbd/cache/postproc1.sab
2010-06-14 16:48:39,562::INFO::[__init__:693] /home/jeffrey/.sabnzbd/cache/postproc1.sab removed
2010-06-14 16:48:39,588::INFO::[__init__:662] Loading data for watched_data.sab from /home/jeffrey/.sabnzbd/cache/watched_data.sab
2010-06-14 16:48:39,607::INFO::[downloader:327] Resuming
2010-06-14 16:48:39,869::INFO::[__init__:250] All processes started
2010-06-14 16:48:39,879::INFO::[SABnzbd:265] Web dir is /home/jeffrey/Downloads/SABnzbd-0.5.2/interfaces/Plush
2010-06-14 16:48:39,995::INFO::[SABnzbd:265] Web dir is /home/jeffrey/Downloads/SABnzbd-0.5.2/interfaces/Mobile
2010-06-14 16:48:40,447::INFO::[SABnzbd:1017] Starting SABnzbd.py-0.5.2
2010-06-14 16:48:40,492::INFO::[dirscanner:367] Dirscanner starting up
2010-06-14 16:48:40,512::INFO::[urlgrabber:103] URLGrabber starting up
2010-06-14 16:48:40,521::INFO::[SABnzbd:386] _yenc module... found!
2010-06-14 16:48:40,529::INFO::[SABnzbd:394] par2 binary... found (/usr/bin/par2)
2010-06-14 16:48:40,538::INFO::[SABnzbd:402] unrar binary... found (/usr/bin/unrar)
2010-06-14 16:48:40,547::INFO::[SABnzbd:407] unzip binary... found (/usr/bin/unzip)
2010-06-14 16:48:40,555::INFO::[SABnzbd:413] nice binary... found (/usr/bin/nice)
2010-06-14 16:48:40,564::INFO::[SABnzbd:417] ionice binary... found (/usr/bin/ionice)
2010-06-14 16:48:40,572::INFO::[SABnzbd:422] pyOpenSSL... found (True)
2010-06-14 16:48:40,603::INFO::[SABnzbd:1115] Starting web-interface on 192.168.0.78:9090
2010-06-14 16:48:41,623::INFO::[_cplogging:55] [14/Jun/2010:16:48:41] ENGINE Bus STARTING
2010-06-14 16:48:41,752::INFO::[_cplogging:55] [14/Jun/2010:16:48:41] ENGINE Started monitor thread '_TimeoutMonitor'.
/usr/local/lib/python2.5/site-packages/Cheetah/Compiler.py:1509: UserWarning: 
You don't have the C version of NameMapper installed! I'm disabling Cheetah's useStackFrames option as it is painfully slow with the Python version of NameMapper. You should get a copy of Cheetah with the compiled C version of NameMapper.
  "\nYou don't have the C version of NameMapper installed! "
/usr/local/lib/python2.5/site-packages/Cheetah/Compiler.py:1509: UserWarning: 
You don't have the C version of NameMapper installed! I'm disabling Cheetah's useStackFrames option as it is painfully slow with the Python version of NameMapper. You should get a copy of Cheetah with the compiled C version of NameMapper.
  "\nYou don't have the C version of NameMapper installed! "
2010-06-14 16:49:38,024::ERROR::[_cplogging:55] [14/Jun/2010:16:49:38] ENGINE Error in 'start' listener <bound method Server.start of <cherrypy._cpserver.Server object at 0x34de8c>>
Traceback (most recent call last):
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/wspbus.py", line 147, in publish
    output.append(listener(*args, **kwargs))
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/_cpserver.py", line 90, in start
    ServerAdapter.start(self)
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/servers.py", line 62, in start
    self.wait()
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/servers.py", line 103, in wait
    wait_for_occupied_port(host, port)
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/servers.py", line 275, in wait_for_occupied_port
    raise IOError("Port %r not bound on %r" % (port, host))
IOError: Port 9090 not bound on '192.168.0.78'

2010-06-14 16:49:38,060::ERROR::[_cplogging:55] [14/Jun/2010:16:49:38] ENGINE Shutting down due to error in start listener:
Traceback (most recent call last):
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/wspbus.py", line 184, in start
    self.publish('start')
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/wspbus.py", line 147, in publish
    output.append(listener(*args, **kwargs))
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/_cpserver.py", line 90, in start
    ServerAdapter.start(self)
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/servers.py", line 62, in start
    self.wait()
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/servers.py", line 103, in wait
    wait_for_occupied_port(host, port)
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/servers.py", line 275, in wait_for_occupied_port
    raise IOError("Port %r not bound on %r" % (port, host))
IOError: Port 9090 not bound on '192.168.0.78'

2010-06-14 16:49:38,095::INFO::[_cplogging:55] [14/Jun/2010:16:49:38] ENGINE Bus STOPPING
2010-06-14 16:49:38,104::INFO::[_cplogging:55] [14/Jun/2010:16:49:38] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('192.168.0.78', 9090)) already shut down
2010-06-14 16:49:38,143::INFO::[_cplogging:55] [14/Jun/2010:16:49:38] ENGINE Stopped thread '_TimeoutMonitor'.
2010-06-14 16:49:38,152::INFO::[_cplogging:55] [14/Jun/2010:16:49:38] ENGINE Bus STOPPED
2010-06-14 16:49:38,161::INFO::[_cplogging:55] [14/Jun/2010:16:49:38] ENGINE Bus EXITING
2010-06-14 16:49:38,171::INFO::[_cplogging:55] [14/Jun/2010:16:49:38] ENGINE Bus EXITED
2010-06-14 16:49:38,181::ERROR::[SABnzbd:238] Kan web interface niet starten
2010-06-14 16:49:38,191::INFO::[__init__:286] SABnzbd shutting down...
2010-06-14 16:49:38,202::INFO::[__init__:641] Saving data for bookmarks.sab in /home/jeffrey/.sabnzbd/cache/bookmarks.sab
2010-06-14 16:49:38,213::INFO::[urlgrabber:98] URLGrabber shutting down
2010-06-14 16:49:38,228::INFO::[__init__:641] Saving data for watched_data.sab in /home/jeffrey/.sabnzbd/cache/watched_data.sab
2010-06-14 16:49:38,238::INFO::[dirscanner:281] Dirscanner shutting down
2010-06-14 16:49:38,470::INFO::[downloader:483] Shutting down
2010-06-14 16:49:38,480::INFO::[assembler:102] Shutting down
2010-06-14 16:49:38,493::INFO::[postproc:134] Saving postproc queue
2010-06-14 16:49:38,503::INFO::[__init__:641] Saving data for postproc1.sab in /home/jeffrey/.sabnzbd/cache/postproc1.sab
2010-06-14 16:49:38,514::INFO::[nzbqueue:92] Saving queue
2010-06-14 16:49:38,523::INFO::[__init__:641] Saving data for queue9.sab in /home/jeffrey/.sabnzbd/cache/queue9.sab
2010-06-14 16:49:38,535::INFO::[__init__:641] Saving data for bytes9.sab in /home/jeffrey/.sabnzbd/cache/bytes9.sab
2010-06-14 16:49:38,546::INFO::[__init__:641] Saving data for rss_data.sab in /home/jeffrey/.sabnzbd/cache/rss_data.sab
2010-06-14 16:49:38,558::INFO::[__init__:641] Saving data for bookmarks.sab in /home/jeffrey/.sabnzbd/cache/bookmarks.sab
2010-06-14 16:49:38,571::INFO::[__init__:641] Saving data for watched_data.sab in /home/jeffrey/.sabnzbd/cache/watched_data.sab
2010-06-14 16:49:38,582::INFO::[postproc:134] Saving postproc queue
2010-06-14 16:49:38,593::INFO::[__init__:641] Saving data for postproc1.sab in /home/jeffrey/.sabnzbd/cache/postproc1.sab
2010-06-14 16:49:38,603::INFO::[__init__:321] All processes stopped
Exception in thread HTTPServer Thread-10 (most likely raised during interpreter shutdown):
Traceback (most recent call last):
  File "/usr/local/lib/python2.5/threading.py", line 486, in __bootstrap_inner
  File "/usr/local/lib/python2.5/threading.py", line 446, in run
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/servers.py", line 89, in _start_http_thread
  File "/home/jeffrey/Downloads/SABnzbd-0.5.2/cherrypy/process/wspbus.py", line 341, in log
<type 'exceptions.AttributeError'>: 'NoneType' object has no attribute 'exc_info'
Unhandled exception in thread started by <bound method Thread.__bootstrap of <Thread(HTTPServer Thread-10, started)>>
Traceback (most recent call last):
  File "/usr/local/lib/python2.5/threading.py", line 462, in __bootstrap
    self.__bootstrap_inner()
  File "/usr/local/lib/python2.5/threading.py", line 527, in __bootstrap_inner
    _active_limbo_lock.acquire()
AttributeError: 'NoneType' object has no attribute 'acquire'
But it hangs for about a minute on this line:

Code: Select all

2010-06-14 16:52:43,122::INFO::[_cplogging:55] [14/Jun/2010:16:52:43] ENGINE Started monitor thread '_TimeoutMonitor'.
I guess it gets a timeout and then shuts down. No matter what i try on what port, HTTPS or not, it just keeps on quitting with the error above...

Re: SABnzbd server errors (Debian Lenny ARM)

Posted: June 15th, 2010, 1:55 am
by shypike
The only conclusion I can draw is that the installed Python (or one of the extra modules) is not correct.