SAB overwriting sabnzbd.ini with garbage on launch

Support for the Debian/Ubuntu package, created by JCFP.
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
gvd
Newbie
Newbie
Posts: 10
Joined: June 15th, 2011, 9:45 am

SAB overwriting sabnzbd.ini with garbage on launch

Post by gvd »

tl;dr: sab is crashing on boot because it's overwriting my sabnzbd.ini on launch with information that it pulls from magic fairy-land. Oh, and that information happens to be wrong.



Hello,

running sabnzbd 0.6.15 on kubuntu 12.04. Installed from the ubuntu repository.


System was running great for months and months until I had to reboot for an unrelated reason. Now sab refuses to start up. Here is the log when I try and launch it using -l 2 for extra debug logging:

Code: Select all

2013-08-17 03:43:57,207::INFO::[sabnzbdplus:1195] --------------------------------
2013-08-17 03:43:57,208::INFO::[sabnzbdplus:1196] sabnzbdplus-0.6.15 (rev=d06f957a0f05a8766822acaa6de1f6d1e9f279e0)
2013-08-17 03:43:57,208::INFO::[sabnzbdplus:1208] Platform = posix
2013-08-17 03:43:57,208::INFO::[sabnzbdplus:1209] Python-version = 2.7.3 (default, Apr 10 2013, 06:20:15)
[GCC 4.6.3]
2013-08-17 03:43:57,208::INFO::[sabnzbdplus:1210] Arguments = "/usr/bin/sabnzbdplus", "-l", "2"
2013-08-17 03:43:57,208::INFO::[sabnzbdplus:1223] Read INI file /home/vince/.sabnzbd/sabnzbd.ini
2013-08-17 03:43:57,210::INFO::[__init__:870] Loading data for bookmarks.sab from /mythtv/incoming/nzb/admin/bookmarks.sab
2013-08-17 03:43:57,211::INFO::[__init__:870] Loading data for rss_data.sab from /mythtv/incoming/nzb/admin/rss_data.sab
2013-08-17 03:43:57,211::INFO::[__init__:870] Loading data for totals9.sab from /mythtv/incoming/nzb/admin/totals9.sab
2013-08-17 03:43:57,211::INFO::[postproc:89] Loading postproc queue
2013-08-17 03:43:57,211::INFO::[__init__:870] Loading data for postproc1.sab from /mythtv/incoming/nzb/admin/postproc1.sab
2013-08-17 03:43:57,212::INFO::[__init__:870] Loading data for queue9.sab from /mythtv/incoming/nzb/admin/queue9.sab
2013-08-17 03:43:57,212::DEBUG::[__init__:805] Loading data for SABnzbd_nzo_tj4XTi from /mythtv/incoming/nzb/temp/big fat tonic tarantula/__ADMIN__/SA
Bnzbd_nzo_tj4XTi
2013-08-17 03:43:57,214::DEBUG::[__init__:805] Loading data for SABnzbd_nzo_JHZMCw from /mythtv/incoming/nzb/temp/deorro/__ADMIN__/SABnzbd_nzo_JHZMCw
2013-08-17 03:43:57,215::DEBUG::[downloader:110] Initializing downloader/decoder
2013-08-17 03:43:57,216::INFO::[__init__:870] Loading data for watched_data.sab from /mythtv/incoming/nzb/admin/watched_data.sab
2013-08-17 03:43:57,216::DEBUG::[scheduler:136] Scheduling RSS interval task every 60 min (delay=21)
2013-08-17 03:43:57,217::DEBUG::[scheduler:147] Scheduling VersionCheck on day 5 at 11:0
2013-08-17 03:43:57,217::INFO::[downloader:194] Resuming
2013-08-17 03:43:57,217::DEBUG::[__init__:479] PAUSED_ALL inactive
2013-08-17 03:43:57,217::INFO::[__init__:289] All processes started
2013-08-17 03:43:57,217::INFO::[sabnzbdplus:332] Web dir is /usr/share/sabnzbdplus/interfaces/Classic
2013-08-17 03:43:57,221::INFO::[sabnzbdplus:442] _yenc module... found!
2013-08-17 03:43:57,222::INFO::[sabnzbdplus:450] par2 binary... found (/usr/bin/par2)
2013-08-17 03:43:57,222::INFO::[sabnzbdplus:458] unrar binary... found (/usr/bin/unrar)
2013-08-17 03:43:57,222::INFO::[sabnzbdplus:463] unzip binary... found (/usr/bin/unzip)
2013-08-17 03:43:57,222::INFO::[sabnzbdplus:469] nice binary... found (/usr/bin/nice)
2013-08-17 03:43:57,222::INFO::[sabnzbdplus:473] ionice binary... found (/usr/bin/ionice)
2013-08-17 03:43:57,222::INFO::[sabnzbdplus:478] pyOpenSSL... found (True)
2013-08-17 03:43:57,223::INFO::[sabnzbdplus:1390] Starting web-interface on 172.18.0.51:8085
2013-08-17 03:43:58,224::INFO::[_cplogging:55] [17/Aug/2013:03:43:58] ENGINE Bus STARTING
2013-08-17 03:43:58,226::INFO::[_cplogging:55] [17/Aug/2013:03:43:58] ENGINE Started monitor thread '_TimeoutMonitor'.
2013-08-17 03:43:58,328::ERROR::[_cplogging:55] [17/Aug/2013:03:43:58] ENGINE Error in HTTP server: shutting down
Traceback (most recent call last):
  File "/usr/share/sabnzbdplus/cherrypy/process/servers.py", line 75, in _start_http_thread
    self.httpserver.start()
  File "/usr/share/sabnzbdplus/cherrypy/wsgiserver/__init__.py", line 1644, in start
    raise socket.error, msg
error: [Errno 99] Cannot assign requested address

2013-08-17 03:43:58,329::INFO::[_cplogging:55] [17/Aug/2013:03:43:58] ENGINE Bus STOPPING
2013-08-17 03:43:58,329::INFO::[_cplogging:55] [17/Aug/2013:03:43:58] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('172.18.0.51', 8085)) a
lready shut down
2013-08-17 03:43:58,339::INFO::[_cplogging:55] [17/Aug/2013:03:43:58] ENGINE Stopped thread '_TimeoutMonitor'.
2013-08-17 03:43:58,340::INFO::[_cplogging:55] [17/Aug/2013:03:43:58] ENGINE Bus STOPPED
2013-08-17 03:43:58,340::INFO::[_cplogging:55] [17/Aug/2013:03:43:58] ENGINE Bus EXITING
2013-08-17 03:43:58,340::INFO::[_cplogging:55] [17/Aug/2013:03:43:58] ENGINE Bus EXITED
2013-08-17 03:43:58,427::ERROR::[_cplogging:55] [17/Aug/2013:03:43:58] ENGINE Error in 'start' listener <bound method Server.start of <cherrypy._cpser
ver.Server object at 0x2294b10>>
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 97, in wait
    raise self.interrupt
error: [Errno 99] Cannot assign requested address

2013-08-17 03:43:58,428::ERROR::[_cplogging:55] [17/Aug/2013:03:43:58] 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 97, in wait
    raise self.interrupt
error: [Errno 99] Cannot assign requested address

2013-08-17 03:43:58,428::INFO::[_cplogging:55] [17/Aug/2013:03:43:58] ENGINE Bus STOPPING
2013-08-17 03:43:58,428::INFO::[_cplogging:55] [17/Aug/2013:03:43:58] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('172.18.0.51', 8085)) a
lready shut down
2013-08-17 03:43:58,429::INFO::[_cplogging:55] [17/Aug/2013:03:43:58] ENGINE No thread running for _TimeoutMonitor.
2013-08-17 03:43:58,429::INFO::[_cplogging:55] [17/Aug/2013:03:43:58] ENGINE Bus STOPPED
2013-08-17 03:43:58,429::INFO::[_cplogging:55] [17/Aug/2013:03:43:58] ENGINE Bus EXITING
2013-08-17 03:43:58,429::INFO::[_cplogging:55] [17/Aug/2013:03:43:58] ENGINE Bus EXITED
2013-08-17 03:43:58,429::ERROR::[sabnzbdplus:1405] Failed to start web-interface:
Traceback (most recent call last):
  File "/usr/bin/sabnzbdplus", line 1397, 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 97, in wait
    raise self.interrupt
error: [Errno 99] Cannot assign requested address
2013-08-17 03:43:58,430::ERROR::[sabnzbdplus:302] Failed to start web-interface : [Errno 99] Cannot assign requested address
2013-08-17 03:43:58,430::INFO::[__init__:325] SABnzbd shutting down...
2013-08-17 03:43:58,430::INFO::[__init__:846] Saving data for bookmarks.sab in /mythtv/incoming/nzb/admin/bookmarks.sab
2013-08-17 03:43:58,430::DEBUG::[__init__:332] Stopping URLGrabber
2013-08-17 03:43:58,430::INFO::[urlgrabber:70] URLGrabber shutting down
2013-08-17 03:43:58,430::DEBUG::[__init__:339] Stopping Newzbin-Grabber
2013-08-17 03:43:58,431::DEBUG::[__init__:346] Stopping dirscanner
2013-08-17 03:43:58,431::INFO::[__init__:846] Saving data for watched_data.sab in /mythtv/incoming/nzb/admin/watched_data.sab
2013-08-17 03:43:58,431::INFO::[dirscanner:254] Dirscanner shutting down
2013-08-17 03:43:58,431::DEBUG::[__init__:355] Stopping downloader
2013-08-17 03:43:58,431::DEBUG::[__init__:358] Stopping assembler
2013-08-17 03:43:58,431::DEBUG::[__init__:365] Stopping postprocessor
2013-08-17 03:43:58,431::INFO::[postproc:83] Saving postproc queue
2013-08-17 03:43:58,432::INFO::[__init__:846] Saving data for postproc1.sab in /mythtv/incoming/nzb/admin/postproc1.sab
2013-08-17 03:43:58,432::INFO::[nzbqueue:170] Saving queue
2013-08-17 03:43:58,432::DEBUG::[__init__:769] Saving data for SABnzbd_nzo_tj4XTi in /mythtv/incoming/nzb/temp/big fat tonic tarantula/__ADMIN__
2013-08-17 03:43:58,434::DEBUG::[__init__:769] Saving data for SABnzbd_nzo_JHZMCw in /mythtv/incoming/nzb/temp/deorro/__ADMIN__
2013-08-17 03:43:58,436::INFO::[__init__:846] Saving data for queue9.sab in /mythtv/incoming/nzb/admin/queue9.sab
2013-08-17 03:43:58,436::INFO::[__init__:846] Saving data for totals9.sab in /mythtv/incoming/nzb/admin/totals9.sab
2013-08-17 03:43:58,437::INFO::[__init__:846] Saving data for rss_data.sab in /mythtv/incoming/nzb/admin/rss_data.sab
2013-08-17 03:43:58,437::INFO::[__init__:846] Saving data for bookmarks.sab in /mythtv/incoming/nzb/admin/bookmarks.sab
2013-08-17 03:43:58,437::INFO::[__init__:846] Saving data for watched_data.sab in /mythtv/incoming/nzb/admin/watched_data.sab
2013-08-17 03:43:58,438::INFO::[postproc:83] Saving postproc queue
2013-08-17 03:43:58,438::INFO::[__init__:846] Saving data for postproc1.sab in /mythtv/incoming/nzb/admin/postproc1.sab
2013-08-17 03:43:58,438::DEBUG::[scheduler:213] Terminating scheduler
2013-08-17 03:43:58,439::INFO::[__init__:385] All processes stopped

I can see what the problem is from the logs. For some reason sab thinks its ip address is 172.18.0.51. It isn't, its IP address is 172.18.0.50 . (172.18.0.51 is my backup machine, currently in s3 sleep and unreachable.)

And now here is where it gets weird. For some reason sab is overwriting the host definition in my sabnzbd.ini file on launch! Gentlement, we have our smoking gun!

Code: Select all

vince@Mythtery:~/.sabnzbd$ grep 172.18.0.51 sabnzbd.ini
host = 172.18.0.51
Should be easy enough to fix... Just change the host to the right IP, and restart sab, right? For some reason sab is OVERWRITING my ini file with incorrect info!

Code: Select all

vince@Mythtery:~/.sabnzbd$ grep 172.18.0.51 sabnzbd.ini
host = 172.18.0.51
vince@Mythtery:~/.sabnzbd$ vi sabnzbd.ini
vince@Mythtery:~/.sabnzbd$ grep 172.18.0.51 sabnzbd.ini
vince@Mythtery:~/.sabnzbd$ grep 172.18.0.50 sabnzbd.ini
host = 172.18.0.50
vince@Mythtery:~/.sabnzbd$ sudo /etc/init.d/sabnzbdplus start
 * Starting SABnzbd+ binary newsgrabber
   ...done.
vince@Mythtery:~/.sabnzbd$ #  failed to start up
vince@Mythtery:~/.sabnzbd$ grep 172.18.0.50 sabnzbd.ini
vince@Mythtery:~/.sabnzbd$ grep 172.18.0.51 sabnzbd.ini
host = 172.18.0.51
!!!!!!!!!!

So the question becomes... where is SAB grabbing the incorrect info from (so I can bitchslap that around a bit), and why is it overwriting my .ini file with incorrect info? Isn't ~/.sabnzbd/sabnzbd.ini the source for sab's configuration?? (Doesn't seem to be that way!)

Thanks in advance!

(Not using IPV6, skin is whatever the default is, obviously 100% reproducible)
Top
User avatar
jcfp
Release Testers
Release Testers
Posts: 1022
Joined: February 7th, 2008, 12:45 pm

Re: SAB overwriting sabnzbd.ini with garbage on launch

Post by jcfp »

Check if you set the ip in /etc/default/sabnzbdplus - and if so, for a machine only connected to your local network consider using 0.0.0.0 (= listen on all interfaces) instead.
Top
gvd
Newbie
Newbie
Posts: 10
Joined: June 15th, 2011, 9:45 am

Re: SAB overwriting sabnzbd.ini with garbage on launch

Post by gvd »

jcfp wrote:Check if you set the ip in /etc/default/sabnzbdplus - and if so, for a machine only connected to your local network consider using 0.0.0.0 (= listen on all interfaces) instead.
Yup, that was it. Errant ip address was in /etc/default/sabnzbdplus (how that got in there is anybody's guess).

Thanks for the point in the right direction!! Now back to running smooth & happy as a clam!
Top
Post Reply

Return to “Debian/Ubuntu Package”