restarts ends with stop

[funfactor]

Hello,

i installed SABnzb on my opensuse Tumbleweed system, everythings works like a charm exept the Restart button in the Webgiu.
If i press this button, the following appears in the log:

2014-12-30 08:58:23,874::INFO::[__init__:372] SABnzbd shutting down...
2014-12-30 08:58:23,876::INFO::[__init__:918] Saving data for bookmarks.sab in /home/twurfbaum/.sabnzbd/admin/bookmarks.sab
2014-12-30 08:58:23,878::INFO::[urlgrabber:71] URLGrabber shutting down
2014-12-30 08:58:24,296::INFO::[__init__:918] Saving data for watched_data.sab in /home/twurfbaum/.sabnzbd/admin/watched_data.sab
2014-12-30 08:58:24,297::INFO::[dirscanner:267] Dirscanner shutting down
2014-12-30 08:58:24,809::INFO::[downloader:404] Shutting down
2014-12-30 08:58:24,811::INFO::[assembler:73] Shutting down
2014-12-30 08:58:24,814::INFO::[postproc:84] Saving postproc queue
2014-12-30 08:58:24,815::INFO::[__init__:918] Saving data for postproc1.sab in /home/twurfbaum/.sabnzbd/admin/postproc1.sab
2014-12-30 08:58:24,815::INFO::[nzbqueue:217] Saving queue
2014-12-30 08:58:24,816::INFO::[__init__:918] Saving data for queue9.sab in /home/twurfbaum/.sabnzbd/admin/queue9.sab
2014-12-30 08:58:24,817::INFO::[__init__:918] Saving data for totals9.sab in /home/twurfbaum/.sabnzbd/admin/totals9.sab
2014-12-30 08:58:24,818::INFO::[__init__:918] Saving data for rss_data.sab in /home/twurfbaum/.sabnzbd/admin/rss_data.sab
2014-12-30 08:58:24,821::INFO::[__init__:918] Saving data for bookmarks.sab in /home/twurfbaum/.sabnzbd/admin/bookmarks.sab
2014-12-30 08:58:24,822::INFO::[__init__:918] Saving data for watched_data.sab in /home/twurfbaum/.sabnzbd/admin/watched_data.sab
2014-12-30 08:58:24,823::INFO::[postproc:84] Saving postproc queue
2014-12-30 08:58:24,823::INFO::[__init__:918] Saving data for postproc1.sab in /home/twurfbaum/.sabnzbd/admin/postproc1.sab
2014-12-30 08:58:24,824::INFO::[__init__:439] All processes stopped
2014-12-30 08:58:24,825::INFO::[_cplogging:55] [30/Dec/2014:08:58:24] ENGINE Bus STOPPING
2014-12-30 08:58:25,836::INFO::[_cplogging:55] [30/Dec/2014:08:58:25] ENGINE Bus STOPPING
2014-12-30 08:58:29,829::INFO::[_cplogging:55] [30/Dec/2014:08:58:29] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('0.0.0.0', 8080)) shut down
2014-12-30 08:58:29,833::INFO::[_cplogging:55] [30/Dec/2014:08:58:29] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('::1', 8080)) shut down
2014-12-30 08:58:29,883::INFO::[_cplogging:55] [30/Dec/2014:08:58:29] ENGINE Stopped thread '_TimeoutMonitor'.
2014-12-30 08:58:29,884::INFO::[_cplogging:55] [30/Dec/2014:08:58:29] ENGINE Bus STOPPED
2014-12-30 08:58:29,884::INFO::[_cplogging:55] [30/Dec/2014:08:58:29] ENGINE Bus EXITING
2014-12-30 08:58:29,884::INFO::[_cplogging:55] [30/Dec/2014:08:58:29] ENGINE Bus EXITED
2014-12-30 08:58:30,838::INFO::[_cplogging:55] [30/Dec/2014:08:58:30] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('0.0.0.0', 8080)) shut down
2014-12-30 08:58:30,838::INFO::[_cplogging:55] [30/Dec/2014:08:58:30] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('::1', 8080)) already shut down
2014-12-30 08:58:30,839::INFO::[_cplogging:55] [30/Dec/2014:08:58:30] ENGINE No thread running for _TimeoutMonitor.
2014-12-30 08:58:30,839::INFO::[_cplogging:55] [30/Dec/2014:08:58:30] ENGINE Bus STOPPED
2014-12-30 08:58:30,840::INFO::[_cplogging:55] [30/Dec/2014:08:58:30] ENGINE Bus EXITING
2014-12-30 08:58:30,841::INFO::[_cplogging:55] [30/Dec/2014:08:58:30] ENGINE Bus EXITED
2014-12-30 08:58:30,842::INFO::[_cplogging:55] [30/Dec/2014:08:58:30] ENGINE Re-spawning /usr/share/SABnzbd/SABnzbd.py -d -f /home/twurfbaum/.sabnzbd/sabnzbd.ini --pid /home/twurfbaum/.sabnzbd -p
2014-12-30 08:58:31,696::INFO::[SABnzbd:1262] --------------------------------
2014-12-30 08:58:31,697::INFO::[SABnzbd:1263] SABnzbd.py-0.7.20 (rev=1df2943d05d64915a166e2c97e1eef86f72e3ff3)
2014-12-30 08:58:31,698::INFO::[SABnzbd:1275] Platform = posix
2014-12-30 08:58:31,699::INFO::[SABnzbd:1276] Python-version = 2.7.9 (default, Dec 13 2014, 18:02:08) [GCC]
2014-12-30 08:58:31,699::INFO::[SABnzbd:1277] Arguments = /usr/share/SABnzbd/SABnzbd.py -d -f /home/twurfbaum/.sabnzbd/sabnzbd.ini --pid /home/twurfbaum/.sabnzbd -p
2014-12-30 08:58:31,699::INFO::[SABnzbd:1327] Read INI file /home/twurfbaum/.sabnzbd/sabnzbd.ini
2014-12-30 08:58:31,719::INFO::[__init__:942] Loading data for bookmarks.sab from /home/twurfbaum/.sabnzbd/admin/bookmarks.sab
2014-12-30 08:58:31,720::INFO::[__init__:942] Loading data for rss_data.sab from /home/twurfbaum/.sabnzbd/admin/rss_data.sab
2014-12-30 08:58:31,721::INFO::[__init__:942] Loading data for totals9.sab from /home/twurfbaum/.sabnzbd/admin/totals9.sab
2014-12-30 08:58:31,722::INFO::[postproc:90] Loading postproc queue
2014-12-30 08:58:31,723::INFO::[__init__:942] Loading data for postproc1.sab from /home/twurfbaum/.sabnzbd/admin/postproc1.sab
2014-12-30 08:58:31,724::INFO::[__init__:942] Loading data for queue9.sab from /home/twurfbaum/.sabnzbd/admin/queue9.sab
2014-12-30 08:58:31,725::INFO::[__init__:942] Loading data for watched_data.sab from /home/twurfbaum/.sabnzbd/admin/watched_data.sab
2014-12-30 08:58:31,727::INFO::[__init__:942] Loading data for Rating.sab from /home/twurfbaum/.sabnzbd/admin/Rating.sab
2014-12-30 08:58:31,727::INFO::[__init__:945] /home/twurfbaum/.sabnzbd/admin/Rating.sab missing, trying old cache
2014-12-30 08:58:31,728::INFO::[__init__:948] /home/twurfbaum/.sabnzbd/cache/Rating.sab missing
2014-12-30 08:58:31,729::INFO::[scheduler:171] Setting schedule for midnight BPS reset
2014-12-30 08:58:31,730::INFO::[__init__:334] All processes started
2014-12-30 08:58:31,730::INFO::[SABnzbd:335] Web dir is /usr/share/SABnzbd/interfaces/Plush
2014-12-30 08:58:31,731::INFO::[SABnzbd:335] Web dir is /usr/share/SABnzbd/interfaces/Plush
2014-12-30 08:58:31,731::INFO::[SABnzbd:335] Web dir is /usr/share/SABnzbd/interfaces/Config
2014-12-30 08:58:31,852::INFO::[SABnzbd:464] _yenc module... found!
2014-12-30 08:58:31,861::INFO::[SABnzbd:472] par2 binary... found (/usr/bin/par2)
2014-12-30 08:58:31,868::INFO::[SABnzbd:480] unrar binary... found (/usr/bin/unrar)
2014-12-30 08:58:31,868::INFO::[SABnzbd:485] unzip binary... found (/usr/bin/unzip)
2014-12-30 08:58:31,869::INFO::[SABnzbd:491] nice binary... found (/usr/bin/nice)
2014-12-30 08:58:31,869::INFO::[SABnzbd:495] ionice binary... found (/usr/bin/ionice)
2014-12-30 08:58:31,869::INFO::[SABnzbd:500] pyOpenSSL... found (True)
2014-12-30 08:58:31,912::INFO::[SABnzbd:1528] Starting web-interface on 0.0.0.0:8080
2014-12-30 08:58:31,920::INFO::[_cplogging:55] [30/Dec/2014:08:58:31] ENGINE Bus STARTING
2014-12-30 08:58:31,953::INFO::[_cplogging:55] [30/Dec/2014:08:58:31] ENGINE Started monitor thread '_TimeoutMonitor'.
2014-12-30 08:58:32,129::INFO::[__init__:372] SABnzbd shutting down...
2014-12-30 08:58:32,131::INFO::[__init__:918] Saving data for bookmarks.sab in /home/twurfbaum/.sabnzbd/admin/bookmarks.sab
2014-12-30 08:58:32,132::INFO::[urlgrabber:71] URLGrabber shutting down
2014-12-30 08:58:32,133::INFO::[__init__:918] Saving data for watched_data.sab in /home/twurfbaum/.sabnzbd/admin/watched_data.sab
2014-12-30 08:58:32,134::INFO::[dirscanner:267] Dirscanner shutting down
2014-12-30 08:58:32,157::INFO::[_cplogging:55] [30/Dec/2014:08:58:32] ENGINE Serving on ::1:8080
2014-12-30 08:58:32,360::INFO::[_cplogging:55] [30/Dec/2014:08:58:32] ENGINE Serving on 0.0.0.0:8080
2014-12-30 08:58:32,362::INFO::[_cplogging:55] [30/Dec/2014:08:58:32] ENGINE Bus STARTED
2014-12-30 08:58:32,637::INFO::[postproc:84] Saving postproc queue
2014-12-30 08:58:32,638::INFO::[__init__:918] Saving data for postproc1.sab in /home/twurfbaum/.sabnzbd/admin/postproc1.sab
2014-12-30 08:58:32,639::INFO::[nzbqueue:217] Saving queue
2014-12-30 08:58:32,639::INFO::[__init__:918] Saving data for queue9.sab in /home/twurfbaum/.sabnzbd/admin/queue9.sab
2014-12-30 08:58:32,640::INFO::[__init__:918] Saving data for totals9.sab in /home/twurfbaum/.sabnzbd/admin/totals9.sab
2014-12-30 08:58:32,641::INFO::[__init__:918] Saving data for rss_data.sab in /home/twurfbaum/.sabnzbd/admin/rss_data.sab
2014-12-30 08:58:32,642::INFO::[__init__:918] Saving data for bookmarks.sab in /home/twurfbaum/.sabnzbd/admin/bookmarks.sab
2014-12-30 08:58:32,643::INFO::[__init__:918] Saving data for watched_data.sab in /home/twurfbaum/.sabnzbd/admin/watched_data.sab
2014-12-30 08:58:32,644::INFO::[postproc:84] Saving postproc queue
2014-12-30 08:58:32,644::INFO::[__init__:918] Saving data for postproc1.sab in /home/twurfbaum/.sabnzbd/admin/postproc1.sab
2014-12-30 08:58:32,645::INFO::[__init__:439] All processes stopped
2014-12-30 08:58:32,646::INFO::[_cplogging:55] [30/Dec/2014:08:58:32] ENGINE Bus STOPPING
2014-12-30 08:58:32,649::INFO::[_cplogging:55] [30/Dec/2014:08:58:32] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('0.0.0.0', 8080)) shut down
2014-12-30 08:58:32,652::INFO::[_cplogging:55] [30/Dec/2014:08:58:32] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('::1', 8080)) shut down
2014-12-30 08:58:32,679::INFO::[_cplogging:55] [30/Dec/2014:08:58:32] ENGINE Stopped thread '_TimeoutMonitor'.
2014-12-30 08:58:32,680::INFO::[_cplogging:55] [30/Dec/2014:08:58:32] ENGINE Bus STOPPED
2014-12-30 08:58:32,681::INFO::[_cplogging:55] [30/Dec/2014:08:58:32] ENGINE Bus EXITING
2014-12-30 08:58:32,682::INFO::[_cplogging:55] [30/Dec/2014:08:58:32] ENGINE Bus EXITED


And thats all, SABnzb doesn't come back.

Any ideas?

Greets,
Thomas

[shypike]

Set the logging level to "Debug" on the Status page.
Then try again, it's likely that the log file will contain more useful info.

[funfactor]

sadly i then run in another problem:
if i raise the log level i get the error
--snip--
twurfbaum@download:~> /usr/share/SABnzbd/SABnzbd.py -l2
2014-12-31 09:23:39,009::INFO::[SABnzbd:1262] --------------------------------
2014-12-31 09:23:39,012::INFO::[SABnzbd:1263] SABnzbd.py-0.7.20 (rev=1df2943d05d64915a166e2c97e1eef86f72e3ff3)
2014-12-31 09:23:39,013::INFO::[SABnzbd:1275] Platform = posix
2014-12-31 09:23:39,014::INFO::[SABnzbd:1276] Python-version = 2.7.9 (default, Dec 13 2014, 18:02:08) [GCC]
2014-12-31 09:23:39,014::INFO::[SABnzbd:1277] Arguments = /usr/share/SABnzbd/SABnzbd.py -l2
2014-12-31 09:23:39,083::DEBUG::[SABnzbd:1283] My IPv4 address = 138.199.66.34
2014-12-31 09:23:39,084::DEBUG::[SABnzbd:1295] Could not determine my IPv6 address
Traceback (most recent call last):
File "/usr/share/SABnzbd/SABnzbd.py", line 1885, in <module>
main()
File "/usr/share/SABnzbd/SABnzbd.py", line 1304, in main
from util.pystone import pystones
ImportError: No module named util.pystone
--snap--
on startup
The code says if loglevel >1 then load pystone

My opensuse system has no pystone.py, nevertheless if i read the code correct sabnzb should use the pystone.py that comes with it.
But it seems that sabnzb cannot find his own pystone.py

download:~ # find / -name pystone*
/usr/share/SABnzbd/util/pystone.py

Greets,
Thomas

[sander]

Interesting.

And weird: why doesn't the "from util.pystone import pystones" work? Maybe it's about the Working Directory?

Can you do this:


First, go to the directory containing SABnzbd.py and directory util/:

Code: Select all

sander@haring:~$ cd git/sabnzbd
sander@haring:~/git/sabnzbd$ ls SABnzbd.py
SABnzbd.py
sander@haring:~/git/sabnzbd$ ls util/
apireg.py  __init__.py  __init__.pyc  mailslot.py  pystone.py  pystone.pyc
sander@haring:~/git/sabnzbd$
sander@haring:~/git/sabnzbd$ python
Python 2.7.6 (default, Mar 22 2014, 22:59:38)
[GCC 4.8.2] on linux2
Type "help", "copyright", "credits" or "license" for more information.
>>> from util.pystone import pystones
>>>
sander@haring:~/git/sabnzbd$

Then go to your home directory:

Code: Select all

sander@haring:~$ cd
sander@haring:~$ pwd
/home/sander
sander@haring:~$ python
Python 2.7.6 (default, Mar 22 2014, 22:59:38)
[GCC 4.8.2] on linux2
Type "help", "copyright", "credits" or "license" for more information.
>>> from util.pystone import pystones
Traceback (most recent call last):
  File "<stdin>", line 1, in <module>
ImportError: No module named util.pystone
>>>

[sander]

Still waiting for the OP funfactor's follow-up as I don't understand what's going on on his/her system.

Anyway: I've sent a pull request to Shypike with a patch for situations like this: https://github.com/sabnzbd/sabnzbd/pull/191

It will land in develop aka 0.8.x, not in 0.7.x

[funfactor]

Hello sander,

i have not installed via git, i installed a prepacked RPM from SUSE which installs SABnzbd in /usr/share
Version is:
0.7.20-42.1

Heres' your output:

Code: Select all

twurfbaum@download:~> cd /usr/share/SABnzbd
twurfbaum@download:/usr/share/SABnzbd> ls SABnzbd.py
SABnzbd.py
twurfbaum@download:/usr/share/SABnzbd> ls util/
apireg.py  mailslot.py  pystone.py
twurfbaum@download:/usr/share/SABnzbd> python
Python 2.7.9 (default, Dec 13 2014, 18:02:08) [GCC] on linux2
Type "help", "copyright", "credits" or "license" for more information.
>>> from util.pystone import pystones
Traceback (most recent call last):
  File "<stdin>", line 1, in <module>
ImportError: No module named util.pystone
>>> quit
Use quit() or Ctrl-D (i.e. EOF) to exit
>>> 
twurfbaum@download:/usr/share/SABnzbd> 

i think i must not go to the second part if the module cannot be found here ....

i',m not a python expert, but maybe something is wrong with the search path?
i did:

Code: Select all

download:~ # rpm -ql `rpm -qa | grep python` | grep "util/"
......
/usr/lib/python2.7/site-packages/keyring/util/XDG.py
/usr/lib/python2.7/site-packages/keyring/util/XDG.pyc
/usr/lib/python2.7/site-packages/keyring/util/__init__.py
/usr/lib/python2.7/site-packages/keyring/util/__init__.pyc
/usr/lib/python2.7/site-packages/keyring/util/escape.py
/usr/lib/python2.7/site-packages/keyring/util/escape.pyc
/usr/lib/python2.7/site-packages/keyring/util/platform_.py
/usr/lib/python2.7/site-packages/keyring/util/platform_.pyc
/usr/lib/python2.7/site-packages/keyring/util/properties.py
/usr/lib/python2.7/site-packages/keyring/util/properties.pyc
download:~ #

So there is maybe a package which installs a search path "util" that comes before /usr/share/SABnzbd/util can be reached.
How can this be solved?

Greets,
Thomas

[sander]

I've put your code snippets into CODE make-up; it makes reading easier. Can you do that yourself in future posts?

OK, now back to the problem: what do you want
1) a quick solution, commenting out the pystone part?
2) a bit more analysis, so that we find the cause? (This is what I would prefer)
3) manually put in the new code from the patch?

[funfactor]

In my opinion a bit more analysis would be the right solution to improve the code for everybody.
So, if you want more information from me i'll try to deliver.

Next time i'll use CODE make-up for the answers

Greets,
Thomas

[sander]

Good: more analysis. Can you do this:

First: cp the file pystone.py from the util/ directory into the existing directory sabnzbd/ in the SABnzbd directory structure. The directory sabnzbd/ contains a lot of other files, like assembler.py and decoder.py ... please check that first.

Then, in SABnzbd.py change the line

Code: Select all

from util.pystone import pystones

to

Code: Select all

from sabnzbd.pystone import pystones

Then stop and start SABnzbd.py, and check the result

EDIT:

Ah, this might be the cause:

https://docs.python.org/2/tutorial/modules.html says

Code: Select all

The __init__.py files are required to make Python treat the directories as containing packages; this is done to prevent directories with a common name, such as string, from unintentionally hiding valid modules that occur later on the module search path. In the simplest case, __init__.py can just be an empty file, but it can also execute initialization code for the 

... and there is no __init__.py in util/, and there is in sabnzbd/ ...

But let's wait for your result.

EDIT 2:

I can reproduce it on my system:

Code: Select all

sander@flappie:~/git/sanderjo-sabnzbd-2015-01-01$ python
Python 2.7.6 (default, Mar 22 2014, 22:59:56) 
[GCC 4.8.2] on linux2
Type "help", "copyright", "credits" or "license" for more information.
>>> from util.pystone import pystones
>>> 

... no problem, so let's check my util/ directory:

Code: Select all

sander@flappie:~/git/sanderjo-sabnzbd-2015-01-01$ ll util/
total 40
drwxrwxr-x  2 sander sander 4096 jan  2 17:42 ./
drwxrwxr-x 17 sander sander 4096 jan  1 22:53 ../
-rw-rw-r--  1 sander sander 3906 jan  1 22:32 apireg.py
-rw-rw-r--  1 sander sander    0 jan  1 14:55 __init__.py
-rw-rw-r--  1 sander sander  103 jan  2 17:42 __init__.pyc
-rw-rw-r--  1 sander sander  148 jan  1 22:38 __init__.pyo
-rw-rw-r--  1 sander sander 4059 jan  1 14:55 mailslot.py
-rw-rw-r--  1 sander sander 7367 jan  1 22:33 pystone.py
-rw-rw-r--  1 sander sander 7608 jan  2 17:42 pystone.pyc

Ah, there is __init__.py. So let's remove it and try again:

Code: Select all

sander@flappie:~/git/sanderjo-sabnzbd-2015-01-01$ rm util/__init__.py*
sander@flappie:~/git/sanderjo-sabnzbd-2015-01-01$ python
Python 2.7.6 (default, Mar 22 2014, 22:59:56) 
[GCC 4.8.2] on linux2
Type "help", "copyright", "credits" or "license" for more information.
>>> from util.pystone import pystones
Traceback (most recent call last):
  File "<stdin>", line 1, in <module>
ImportError: No module named util.pystone
>>> 
sander@flappie:~/git/sanderjo-sabnzbd-2015-01-01$

Bingo! Same error message!

One more check:

Code: Select all

sander@flappie:~/git/sanderjo-sabnzbd-2015-01-01$ touch util/__init__.py
sander@flappie:~/git/sanderjo-sabnzbd-2015-01-01$ python
Python 2.7.6 (default, Mar 22 2014, 22:59:56) 
[GCC 4.8.2] on linux2
Type "help", "copyright", "credits" or "license" for more information.
>>> from util.pystone import pystones
>>>

QED

[funfactor]

yes, you're right.
a simple

Code: Select all

download:~ # touch /usr/share/SABnzbd/util/__init__.py
download:~ # chmod 644 /usr/share/SABnzbd/util/__init__.p

makes starting SABnzbd with a raised loglevel possible.

OK first problem found! Again something learned about python (maybew i'll begin to learn it, lets see if i'm bored in my next holydays ).


Next problem (why does a restart end with stop).

Here is the log with loglevel 2 when pressing button Restart:

Code: Select all

2015-01-02 19:01:47,575::INFO::[__init__:372] SABnzbd shutting down...
2015-01-02 19:01:47,577::INFO::[__init__:918] Saving data for bookmarks.sab in /home/twurfbaum/.sabnzbd/admin/bookmarks.sab
2015-01-02 19:01:47,580::DEBUG::[__init__:379] Stopping URLGrabber
2015-01-02 19:01:47,581::INFO::[urlgrabber:71] URLGrabber shutting down
2015-01-02 19:01:47,583::DEBUG::[__init__:386] Stopping Newzbin-Grabber
2015-01-02 19:01:47,584::DEBUG::[newzbin:141] Stopping MSGIDGrabber
2015-01-02 19:01:47,585::DEBUG::[__init__:393] Stopping rating
2015-01-02 19:01:47,867::DEBUG::[rating:142] Stopping ratings
2015-01-02 19:01:47,868::DEBUG::[__init__:400] Stopping dirscanner
2015-01-02 19:01:47,869::INFO::[__init__:918] Saving data for watched_data.sab in /home/twurfbaum/.sabnzbd/admin/watched_data.sab
2015-01-02 19:01:47,871::INFO::[dirscanner:267] Dirscanner shutting down
2015-01-02 19:01:47,872::DEBUG::[__init__:409] Stopping downloader
2015-01-02 19:01:47,874::DEBUG::[notifier:112] Sending registration to localhost:23053
2015-01-02 19:01:47,875::DEBUG::[notifier:102] Checking icon
2015-01-02 19:01:47,877::DEBUG::[notifier:219] To : localhost:23053 <<class 'gntp.GNTPRegister'>>
2015-01-02 19:01:47,878::DEBUG::[growler:192] Cannot register with Growl [Errno 111] Connection refused
2015-01-02 19:01:48,376::INFO::[downloader:404] Shutting down
2015-01-02 19:01:48,377::DEBUG::[__init__:412] Stopping assembler
2015-01-02 19:01:48,378::INFO::[assembler:73] Shutting down
2015-01-02 19:01:48,381::DEBUG::[__init__:419] Stopping postprocessor
2015-01-02 19:01:48,383::INFO::[postproc:84] Saving postproc queue
2015-01-02 19:01:48,384::INFO::[__init__:918] Saving data for postproc1.sab in /home/twurfbaum/.sabnzbd/admin/postproc1.sab
2015-01-02 19:01:48,388::INFO::[nzbqueue:217] Saving queue
2015-01-02 19:01:48,389::INFO::[__init__:918] Saving data for queue9.sab in /home/twurfbaum/.sabnzbd/admin/queue9.sab
2015-01-02 19:01:48,394::INFO::[__init__:918] Saving data for totals9.sab in /home/twurfbaum/.sabnzbd/admin/totals9.sab
2015-01-02 19:01:48,396::INFO::[__init__:918] Saving data for rss_data.sab in /home/twurfbaum/.sabnzbd/admin/rss_data.sab
2015-01-02 19:01:48,398::INFO::[__init__:918] Saving data for bookmarks.sab in /home/twurfbaum/.sabnzbd/admin/bookmarks.sab
2015-01-02 19:01:48,400::INFO::[__init__:918] Saving data for watched_data.sab in /home/twurfbaum/.sabnzbd/admin/watched_data.sab
2015-01-02 19:01:48,401::INFO::[postproc:84] Saving postproc queue
2015-01-02 19:01:48,402::INFO::[__init__:918] Saving data for postproc1.sab in /home/twurfbaum/.sabnzbd/admin/postproc1.sab
2015-01-02 19:01:48,403::DEBUG::[scheduler:228] Terminating scheduler
2015-01-02 19:01:48,404::INFO::[__init__:439] All processes stopped
2015-01-02 19:01:48,405::INFO::[_cplogging:55] [02/Jan/2015:19:01:48] ENGINE Bus STOPPING
2015-01-02 19:01:48,878::DEBUG::[scheduler:214] Stopping scheduler
2015-01-02 19:01:48,878::INFO::[_cplogging:55] [02/Jan/2015:19:01:48] ENGINE Bus STOPPING
2015-01-02 19:01:48,879::INFO::[_cplogging:55] [02/Jan/2015:19:01:48] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('172.16.31.6', 8080)) shut down
2015-01-02 19:01:48,892::INFO::[_cplogging:55] [02/Jan/2015:19:01:48] ENGINE Stopped thread '_TimeoutMonitor'.
2015-01-02 19:01:48,892::INFO::[_cplogging:55] [02/Jan/2015:19:01:48] ENGINE Bus STOPPED
2015-01-02 19:01:48,893::INFO::[_cplogging:55] [02/Jan/2015:19:01:48] ENGINE Bus EXITING
2015-01-02 19:01:48,893::INFO::[_cplogging:55] [02/Jan/2015:19:01:48] ENGINE Bus EXITED
2015-01-02 19:01:48,893::INFO::[_cplogging:55] [02/Jan/2015:19:01:48] ENGINE Re-spawning /usr/share/SABnzbd/SABnzbd.py -d -f /home/twurfbaum/.sabnzbd/sabnzbd.ini --pid /home/twurfbaum/.sabnzbd -p
2015-01-02 19:01:49,725::INFO::[SABnzbd:1262] --------------------------------
2015-01-02 19:01:49,726::INFO::[SABnzbd:1263] SABnzbd.py-0.7.20 (rev=1df2943d05d64915a166e2c97e1eef86f72e3ff3)
2015-01-02 19:01:49,727::INFO::[SABnzbd:1275] Platform = posix
2015-01-02 19:01:49,728::INFO::[SABnzbd:1276] Python-version = 2.7.9 (default, Dec 13 2014, 18:02:08) [GCC]
2015-01-02 19:01:49,728::INFO::[SABnzbd:1277] Arguments = /usr/share/SABnzbd/SABnzbd.py -d -f /home/twurfbaum/.sabnzbd/sabnzbd.ini --pid /home/twurfbaum/.sabnzbd -p
2015-01-02 19:01:49,808::DEBUG::[SABnzbd:1283] My IPv4 address = 192.168.2.6
2015-01-02 19:01:49,809::DEBUG::[SABnzbd:1295] Could not determine my IPv6 address
2015-01-02 19:01:49,841::DEBUG::[SABnzbd:1306] CPU Pystone available performance is 45320
2015-01-02 19:01:49,843::DEBUG::[SABnzbd:1310] CPU model name is AMD Opteron 22xx (Gen 2 Class Opteron)
2015-01-02 19:01:49,843::INFO::[SABnzbd:1327] Read INI file /home/twurfbaum/.sabnzbd/sabnzbd.ini
2015-01-02 19:01:49,864::INFO::[__init__:942] Loading data for bookmarks.sab from /home/twurfbaum/.sabnzbd/admin/bookmarks.sab
2015-01-02 19:01:49,866::INFO::[__init__:942] Loading data for rss_data.sab from /home/twurfbaum/.sabnzbd/admin/rss_data.sab
2015-01-02 19:01:49,868::INFO::[__init__:942] Loading data for totals9.sab from /home/twurfbaum/.sabnzbd/admin/totals9.sab
2015-01-02 19:01:49,869::DEBUG::[bpsmeter:170] Read quota q=0.0 l=0.0 reset=0
2015-01-02 19:01:49,870::INFO::[postproc:90] Loading postproc queue
2015-01-02 19:01:49,871::INFO::[__init__:942] Loading data for postproc1.sab from /home/twurfbaum/.sabnzbd/admin/postproc1.sab
2015-01-02 19:01:49,872::INFO::[__init__:942] Loading data for queue9.sab from /home/twurfbaum/.sabnzbd/admin/queue9.sab
2015-01-02 19:01:49,873::DEBUG::[downloader:126] Initializing downloader/decoder
2015-01-02 19:01:49,875::INFO::[__init__:942] Loading data for watched_data.sab from /home/twurfbaum/.sabnzbd/admin/watched_data.sab
2015-01-02 19:01:49,877::INFO::[__init__:942] Loading data for Rating.sab from /home/twurfbaum/.sabnzbd/admin/Rating.sab
2015-01-02 19:01:49,879::INFO::[__init__:945] /home/twurfbaum/.sabnzbd/admin/Rating.sab missing, trying old cache
2015-01-02 19:01:49,879::INFO::[__init__:948] /home/twurfbaum/.sabnzbd/cache/Rating.sab missing
2015-01-02 19:01:49,881::DEBUG::[scheduler:139] Scheduling RSS interval task every 60 min (delay=32)
2015-01-02 19:01:49,883::DEBUG::[scheduler:151] Scheduling VersionCheck on day 6 at 8:11
2015-01-02 19:01:49,884::INFO::[scheduler:171] Setting schedule for midnight BPS reset
2015-01-02 19:01:49,885::INFO::[__init__:334] All processes started
2015-01-02 19:01:49,886::INFO::[SABnzbd:335] Web dir is /usr/share/SABnzbd/interfaces/Plush
2015-01-02 19:01:49,886::INFO::[SABnzbd:335] Web dir is /usr/share/SABnzbd/interfaces/Plush
2015-01-02 19:01:49,887::INFO::[SABnzbd:335] Web dir is /usr/share/SABnzbd/interfaces/Config
2015-01-02 19:01:49,887::DEBUG::[SABnzbd:1364] Unwanted extensions are ... []
2015-01-02 19:01:49,988::INFO::[SABnzbd:464] _yenc module... found!
2015-01-02 19:01:50,000::INFO::[SABnzbd:472] par2 binary... found (/usr/bin/par2)
2015-01-02 19:01:50,002::INFO::[SABnzbd:480] unrar binary... found (/usr/bin/unrar)
2015-01-02 19:01:50,009::INFO::[SABnzbd:485] unzip binary... found (/usr/bin/unzip)
2015-01-02 19:01:50,009::INFO::[SABnzbd:491] nice binary... found (/usr/bin/nice)
2015-01-02 19:01:50,009::INFO::[SABnzbd:495] ionice binary... found (/usr/bin/ionice)
2015-01-02 19:01:50,010::INFO::[SABnzbd:500] pyOpenSSL... found (True)
2015-01-02 19:01:50,038::INFO::[SABnzbd:1528] Starting web-interface on 172.16.31.6:8080
2015-01-02 19:01:50,048::INFO::[_cplogging:55] [02/Jan/2015:19:01:50] ENGINE Bus STARTING
2015-01-02 19:01:50,079::INFO::[_cplogging:55] [02/Jan/2015:19:01:50] ENGINE Started monitor thread '_TimeoutMonitor'.

Here ends the log, SABnzbd is not startet.

Greets,
Thomas

[sander]

A hint:

Code: Select all

cat sabnzbd.log | grep -e IPv4 -e Starting
2015-01-02 19:01:49,808::DEBUG::[SABnzbd:1283] My IPv4 address = 192.168.2.6
2015-01-02 19:01:50,038::INFO::[SABnzbd:1528] Starting web-interface on 172.16.31.6:8080

HTH

[funfactor]

yes, my system has 2 IPs on eth0

Code: Select all

download:~ # ip a s
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default 
    link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
    inet 127.0.0.1/8 scope host lo
       valid_lft forever preferred_lft forever
    inet6 ::1/128 scope host 
       valid_lft forever preferred_lft forever
2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP group default qlen 1000
    link/ether 52:54:00:f9:ac:45 brd ff:ff:ff:ff:ff:ff
    inet 172.16.31.6/16 brd 172.16.255.255 scope global eth0
       valid_lft forever preferred_lft forever
    inet 192.168.2.6/24 brd 192.168.2.255 scope global eth0
       valid_lft forever preferred_lft forever
    inet6 fe80::5054:ff:fef9:ac45/64 scope link 
       valid_lft forever preferred_lft forever
download:~ # ip r s
default via 192.168.2.1 dev eth0  proto static  metric 1024 
172.16.0.0/16 dev eth0  proto kernel  scope link  src 172.16.31.6 
192.168.2.0/24 dev eth0  proto kernel  scope link  src 192.168.2.6 
download:~ # 

On 172.16.31.6 i talk with the Webinterface, this is my internal net
But i have a dedicated router for Usenet traffic with IP 192.168.2.1.

Can this combination be a problem for SABnzbd?

Regards,
Thomas

[sander]

I would start with

Code: Select all

./SABnzbd.py -s 127.0.0.1:8080

(or sabnzbdplus or how it is called on SuSE)

[funfactor]

If i call SABnzbd like this everything works as expected, restart works without problems.

Greets,
Thomas