Page 1 of 1

[v0.7.3 on OS X 10.7] SABnzbd wouldn't start

Posted: October 1st, 2012, 12:24 pm
by Grind
Every time I run SABnzbd it quits immediately. I tried to get the certificates right in the latest beta (0.7.4 RC2), but I couldn't get it right. Therefore I reinstalled version 0.7.3. Now the app doesn't start. The following messages are logged:

Code: Select all

 Marker - 1 Oct 2012 19:20:02
2012-10-01 19:20:14,645::INFO::[SABnzbd:1250] Console logging for OSX App disabled
2012-10-01 19:20:14,646::INFO::[SABnzbd:1257] --------------------------------
2012-10-01 19:20:14,646::INFO::[SABnzbd:1258] SABnzbd.py-0.7.3 (rev=e24aedc6acf1e477887a115b9a423838bdb19172)
2012-10-01 19:20:14,646::INFO::[SABnzbd:1270] Platform = posix
2012-10-01 19:20:14,647::INFO::[SABnzbd:1271] Python-version = 2.7.1 (r271:86832, Jun 16 2011, 16:59:05) 
[GCC 4.2.1 (Based on Apple Inc. build 5658) (LLVM build 2335.15.00)]
2012-10-01 19:20:14,647::INFO::[SABnzbd:1272] Arguments = /Applications/SABnzbd.app/Contents/Resources/SABnzbd.py -psn_0_1937881
2012-10-01 19:20:14,647::INFO::[SABnzbd:1276] [osx] IO priority setting
2012-10-01 19:20:14,648::INFO::[SABnzbd:1281] [osx] IO priority set to throttle for process scope
2012-10-01 19:20:14,648::INFO::[SABnzbd:1285] Read INI file /Network/Servers/mydomain.com/Home Folders/myusername/Library/Application Support/SABnzbd/sabnzbd.ini
2012-10-01 19:20:14,654::INFO::[__init__:919] Loading data for bookmarks.sab from /Network/Servers/mydomain.com/Home Folders/myusername/Library/Application Support/SABnzbd/admin/bookmarks.sab
2012-10-01 19:20:14,661::INFO::[__init__:919] Loading data for rss_data.sab from /Network/Servers/mydomain.com/Home Folders/myusername/Library/Application Support/SABnzbd/admin/rss_data.sab
2012-10-01 19:20:14,669::INFO::[__init__:919] Loading data for totals9.sab from /Network/Servers/mydomain.com/Home Folders/myusername/Library/Application Support/SABnzbd/admin/totals9.sab
2012-10-01 19:20:14,684::INFO::[osxmenu:375] [osx] queueUpdate Exception <type 'exceptions.AttributeError'>
2012-10-01 19:20:14,687::INFO::[postproc:90] Loading postproc queue
2012-10-01 19:20:14,688::INFO::[__init__:919] Loading data for postproc1.sab from /Network/Servers/mydomain.com/Home Folders/myusername/Library/Application Support/SABnzbd/admin/postproc1.sab
2012-10-01 19:20:14,691::INFO::[__init__:919] Loading data for queue9.sab from /Network/Servers/mydomain.com/Home Folders/myusername/Library/Application Support/SABnzbd/admin/queue9.sab
2012-10-01 19:20:14,695::INFO::[__init__:919] Loading data for watched_data.sab from /Network/Servers/mydomain.com/Home Folders/myusername/Library/Application Support/SABnzbd/admin/watched_data.sab
2012-10-01 19:20:14,751::INFO::[downloader:208] Resuming
2012-10-01 19:20:14,752::INFO::[__init__:318] All processes started
2012-10-01 19:20:14,752::INFO::[SABnzbd:333] Web dir is /Applications/SABnzbd.app/Contents/Resources/interfaces/Classic
2012-10-01 19:20:14,753::INFO::[SABnzbd:333] Web dir is /Applications/SABnzbd.app/Contents/Resources/interfaces/Config
2012-10-01 19:20:14,763::INFO::[osxmenu:481] [osx] stateUpdate Exception <type 'exceptions.AttributeError'>
2012-10-01 19:20:14,772::INFO::[SABnzbd:462] _yenc module... found!
2012-10-01 19:20:14,772::INFO::[SABnzbd:470] par2 binary... found (/Applications/SABnzbd.app/Contents/Resources/osx/par2/par2-sl)
2012-10-01 19:20:14,773::INFO::[SABnzbd:478] unrar binary... found (/Applications/SABnzbd.app/Contents/Resources/osx/unrar/unrar)
2012-10-01 19:20:14,773::INFO::[SABnzbd:483] unzip binary... found (/usr/bin/unzip)
2012-10-01 19:20:14,773::INFO::[SABnzbd:489] nice binary... found (/usr/bin/nice)
2012-10-01 19:20:14,773::INFO::[SABnzbd:495] ionice binary... NOT found!
2012-10-01 19:20:14,774::INFO::[SABnzbd:498] pyOpenSSL... found (True)
2012-10-01 19:20:14,779::INFO::[SABnzbd:1468] Starting web-interface on 0.0.0.0:9090
2012-10-01 19:20:14,787::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE Bus STARTING
2012-10-01 19:20:14,791::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE Started monitor thread '_TimeoutMonitor'.
2012-10-01 19:20:14,796::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE Serving on 0.0.0.0:8085
2012-10-01 19:20:14,800::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE Serving on ::1:8085
2012-10-01 19:20:14,802::ERROR::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE Error in HTTP server: shutting down
Traceback (most recent call last):
  File "cherrypy/process/servers.pyc", line 75, in _start_http_thread
  File "cherrypy/wsgiserver/__init__.pyc", line 1636, in start
  File "cherrypy/wsgiserver/__init__.pyc", line 1681, in bind
Error: [('PEM routines', 'PEM_read_bio', 'no start line'), ('SSL routines', 'SSL_CTX_use_certificate_file', 'PEM lib')]

2012-10-01 19:20:14,802::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE Bus STOPPING
2012-10-01 19:20:14,803::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE HTTP Server None already shut down
2012-10-01 19:20:14,805::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('0.0.0.0', 8085)) shut down
2012-10-01 19:20:14,808::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('::1', 8085)) shut down
2012-10-01 19:20:14,809::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('::1', 9090)) already shut down
2012-10-01 19:20:14,809::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE Stopped thread '_TimeoutMonitor'.
2012-10-01 19:20:14,809::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE Bus STOPPED
2012-10-01 19:20:14,810::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE Bus EXITING
2012-10-01 19:20:14,810::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE Bus EXITED
2012-10-01 19:20:14,902::ERROR::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE Error in 'start' listener <bound method ServerAdapter.start of <cherrypy.process.servers.ServerAdapter object at 0x10d28c1d0>>
Traceback (most recent call last):
  File "cherrypy/process/wspbus.pyc", line 147, in publish
  File "cherrypy/process/servers.pyc", line 62, in start
  File "cherrypy/process/servers.pyc", line 97, in wait
Error: [('PEM routines', 'PEM_read_bio', 'no start line'), ('SSL routines', 'SSL_CTX_use_certificate_file', 'PEM lib')]

2012-10-01 19:20:14,903::ERROR::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE Error in HTTP server: shutting down
Traceback (most recent call last):
  File "cherrypy/process/servers.pyc", line 75, in _start_http_thread
  File "cherrypy/wsgiserver/__init__.pyc", line 1636, in start
  File "cherrypy/wsgiserver/__init__.pyc", line 1681, in bind
Error: [('PEM routines', 'PEM_read_bio', 'no start line'), ('SSL routines', 'SSL_CTX_use_certificate_file', 'PEM lib')]

2012-10-01 19:20:14,904::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE Bus STOPPING
2012-10-01 19:20:14,904::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('0.0.0.0', 9090)) already shut down
2012-10-01 19:20:14,904::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('0.0.0.0', 8085)) already shut down
2012-10-01 19:20:14,905::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('::1', 8085)) already shut down
2012-10-01 19:20:14,905::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('::1', 9090)) already shut down
2012-10-01 19:20:14,905::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE No thread running for _TimeoutMonitor.
2012-10-01 19:20:14,905::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE Bus STOPPED
2012-10-01 19:20:14,906::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE Bus EXITING
2012-10-01 19:20:14,906::INFO::[_cplogging:55] [01/Oct/2012:19:20:14] ENGINE Bus EXITED
2012-10-01 19:20:15,003::ERROR::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE Error in 'start' listener <bound method Server.start of <cherrypy._cpserver.Server object at 0x10a562d50>>
Traceback (most recent call last):
  File "cherrypy/process/wspbus.pyc", line 147, in publish
  File "cherrypy/_cpserver.pyc", line 90, in start
  File "cherrypy/process/servers.pyc", line 62, in start
  File "cherrypy/process/servers.pyc", line 97, in wait
Error: [('PEM routines', 'PEM_read_bio', 'no start line'), ('SSL routines', 'SSL_CTX_use_certificate_file', 'PEM lib')]

2012-10-01 19:20:15,004::ERROR::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE Shutting down due to error in start listener:
Traceback (most recent call last):
  File "cherrypy/process/wspbus.pyc", line 184, in start
  File "cherrypy/process/wspbus.pyc", line 147, in publish
  File "cherrypy/_cpserver.pyc", line 90, in start
  File "cherrypy/process/servers.pyc", line 62, in start
  File "cherrypy/process/servers.pyc", line 97, in wait
Error: [('PEM routines', 'PEM_read_bio', 'no start line'), ('SSL routines', 'SSL_CTX_use_certificate_file', 'PEM lib')]

2012-10-01 19:20:15,004::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE Bus STOPPING
2012-10-01 19:20:15,004::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('0.0.0.0', 9090)) already shut down
2012-10-01 19:20:15,005::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('0.0.0.0', 8085)) already shut down
2012-10-01 19:20:15,005::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('::1', 8085)) already shut down
2012-10-01 19:20:15,005::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('::1', 9090)) already shut down
2012-10-01 19:20:15,005::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE No thread running for _TimeoutMonitor.
2012-10-01 19:20:15,006::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE Bus STOPPED
2012-10-01 19:20:15,006::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE Bus EXITING
2012-10-01 19:20:15,006::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE Bus EXITED
2012-10-01 19:20:15,006::ERROR::[SABnzbd:1489] Failed to start web-interface: 
Traceback (most recent call last):
  File "/Applications/SABnzbd.app/Contents/Resources/SABnzbd.py", line 1475, in main
  File "cherrypy/process/wspbus.pyc", line 184, in start
  File "cherrypy/process/wspbus.pyc", line 147, in publish
  File "cherrypy/_cpserver.pyc", line 90, in start
  File "cherrypy/process/servers.pyc", line 62, in start
  File "cherrypy/process/servers.pyc", line 97, in wait
Error: [('PEM routines', 'PEM_read_bio', 'no start line'), ('SSL routines', 'SSL_CTX_use_certificate_file', 'PEM lib')]
2012-10-01 19:20:15,007::ERROR::[SABnzbd:302] Failed to start web-interface : 
2012-10-01 19:20:15,007::INFO::[__init__:359] SABnzbd shutting down...
2012-10-01 19:20:15,007::INFO::[__init__:895] Saving data for bookmarks.sab in /Network/Servers/mydomain.com/Home Folders/myusername/Library/Application Support/SABnzbd/admin/bookmarks.sab
2012-10-01 19:20:15,008::INFO::[urlgrabber:72] URLGrabber shutting down
2012-10-01 19:20:15,009::INFO::[__init__:895] Saving data for watched_data.sab in /Network/Servers/mydomain.com/Home Folders/myusername/Library/Application Support/SABnzbd/admin/watched_data.sab
2012-10-01 19:20:15,009::INFO::[dirscanner:256] Dirscanner shutting down
2012-10-01 19:20:15,010::INFO::[notifier:87] Sending registration to localhost:23053
2012-10-01 19:20:15,511::INFO::[postproc:84] Saving postproc queue
2012-10-01 19:20:15,512::INFO::[__init__:895] Saving data for postproc1.sab in /Network/Servers/mydomain.com/Home Folders/myusername/Library/Application Support/SABnzbd/admin/postproc1.sab
2012-10-01 19:20:15,512::INFO::[nzbqueue:176] Saving queue
2012-10-01 19:20:15,513::INFO::[__init__:895] Saving data for queue9.sab in /Network/Servers/mydomain.com/Home Folders/myusername/Library/Application Support/SABnzbd/admin/queue9.sab
2012-10-01 19:20:15,514::INFO::[__init__:895] Saving data for totals9.sab in /Network/Servers/mydomain.com/Home Folders/myusername/Library/Application Support/SABnzbd/admin/totals9.sab
2012-10-01 19:20:15,514::INFO::[__init__:895] Saving data for rss_data.sab in /Network/Servers/mydomain.com/Home Folders/myusername/Library/Application Support/SABnzbd/admin/rss_data.sab
2012-10-01 19:20:15,515::INFO::[__init__:895] Saving data for bookmarks.sab in /Network/Servers/mydomain.com/Home Folders/myusername/Library/Application Support/SABnzbd/admin/bookmarks.sab
2012-10-01 19:20:15,516::INFO::[__init__:895] Saving data for watched_data.sab in /Network/Servers/mydomain.com/Home Folders/myusername/Library/Application Support/SABnzbd/admin/watched_data.sab
2012-10-01 19:20:15,516::INFO::[postproc:84] Saving postproc queue
2012-10-01 19:20:15,517::INFO::[__init__:895] Saving data for postproc1.sab in /Network/Servers/mydomain.com/Home Folders/myusername/Library/Application Support/SABnzbd/admin/postproc1.sab
2012-10-01 19:20:15,517::INFO::[__init__:422] All processes stopped
2012-10-01 19:20:15,521::INFO::[osxmenu:763] [osx] application terminating
2012-10-01 19:20:15,529::INFO::[osxmenu:767] [osx] application stopping daemon
2012-10-01 19:20:15,529::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE Bus STOPPING
2012-10-01 19:20:15,529::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('0.0.0.0', 9090)) already shut down
2012-10-01 19:20:15,530::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('0.0.0.0', 8085)) already shut down
2012-10-01 19:20:15,530::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('::1', 8085)) already shut down
2012-10-01 19:20:15,530::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('::1', 9090)) already shut down
2012-10-01 19:20:15,530::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE No thread running for _TimeoutMonitor.
2012-10-01 19:20:15,531::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE Bus STOPPED
2012-10-01 19:20:15,531::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE Bus EXITING
2012-10-01 19:20:15,531::INFO::[_cplogging:55] [01/Oct/2012:19:20:15] ENGINE Bus EXITED
2012-10-01 19:20:15,531::INFO::[osxmenu:776] Leaving SABnzbd 
Can someone help me please?

Re: [v0.7.3 on OS X 10.7] SABnzbd wouldn't start

Posted: October 1st, 2012, 1:26 pm
by shypike
You still have unusable certificates installed (at least as far as SABnzbd web framework is concerned.
Check the folder "Library/Application Support/SABnzbd/admin" for certificate files.
(It's a hidden folder in your home directory).

Re: [v0.7.3 on OS X 10.7] SABnzbd wouldn't start

Posted: October 1st, 2012, 1:36 pm
by Grind
Can I delete the certificates without further consequences?

Re: [v0.7.3 on OS X 10.7] SABnzbd wouldn't start

Posted: October 1st, 2012, 1:47 pm
by shypike
Yes, SABnzbd will create new ones if you still select HTTPS.
Did you get an official certificate and is it being refused by SABnzbd?
Can you try them without the Chain certificate?

Re: [v0.7.3 on OS X 10.7] SABnzbd wouldn't start

Posted: October 2nd, 2012, 2:15 am
by Grind
Ok, without the certificates SABnzbd is working again.
I received three certificates: a root, an intermediate and my own certificate. How can I combine them so SABnzbd can handle them?

Re: [v0.7.3 on OS X 10.7] SABnzbd wouldn't start

Posted: October 2nd, 2012, 11:59 am
by shypike
I'm not sure how all of it works.
I tried with an intermediate certificate from GoDaddy, and it works for me.
In the sense that SABnzbd starts up, but I still use self-signed certificates.
Can you PM the intermediate certificate to me?
It should be a public file from your certificate provider, so no security risk here.

Re: [v0.7.3 on OS X 10.7] SABnzbd wouldn't start

Posted: October 2nd, 2012, 1:22 pm
by Grind
You've got a PM :)

Re: [v0.7.3 on OS X 10.7] SABnzbd wouldn't start

Posted: October 2nd, 2012, 2:22 pm
by shypike
Release 0.7.3 does not support intermediate certificates.
Those are only in 0.7.4RC1 and 2, and in the soon to be released 0.7.4 Final.
So the intermediate file cannot be the cause.

Have you looked inside your certificates?
Do they look anything like the ones generated by SABnzbd itself?
I cannot test, but perhaps the intermediate certificate is needed along with your official certificates.
So that would mean they only work with 0.7.4 and with all three set in Config->General.
Try 0.7.4RC2 (See Announcements).

Re: [v0.7.3 on OS X 10.7] SABnzbd wouldn't start

Posted: October 3rd, 2012, 5:00 am
by Grind
I have reinstalled 0.7.4, but the root and intermediate certificates still aren't accepted by SABnzbd. I tried adding the root and the intermediate certificates to the chain.cert file and my private to the server.cert file. Also I tried adding the root to the chain.cert file and the intermediate and my private certificate to the server.cert file. Both ways don't work.
Yes, the certificates look like the ones generated by SABnzbd itself, they are only longer. My 3 certificates work fine with the web server built in 10.7 Server, but SABnzbd can't handle them. Or did I something wrong?

Re: [v0.7.3 on OS X 10.7] SABnzbd wouldn't start

Posted: November 24th, 2012, 9:11 pm
by sashaman
nothing was working for me either, been fiddlin around a few hours lol.
i also tried combining to form chains (like nginx needs for example), but nothing, only different errors :)
it also wouldn't accept the sub.class1.server.ca.pem from my ssl authority (crash T.T).
finally, the last thing i tried was to simply use the authoritys ca.pem as the chain cert and it worked like a charm.

unencrypted RSA private.key + server cert.pem + root ca.pem = working

maybe there are more people with similiar problems or other experiences ?
i'm courious now...

anyways, hope this helps and good luck
sash