Page 1 of 1

Crashing on Windows 8 due to port not bound on ::1

Posted: July 5th, 2013, 6:53 am
by ianl
Hello - I've been using SABNZBD for years and love it. Recently I was cursed with a new laptop with Windows 8 and no downgrade path. SABNZBD seems to crash because of error "IOError: Port 8081 not bound on '::1'". I've changed the port from 8080 to 8081 to see if that fixes it but it did not help. I'm running zonealarm firewall but permissions are set correctly, and I've also tested with the firewall disabled. The error log is attached below from my most recent attempt to load and NZB.

Any help is greatly appreciated so I can stop using old News File Grabber. Thank you so much. ianl

Version: 7.12
OS: Windows 8
Install-type:Windows Installer
Skin Default
Firewall Software: Zone Alarm and none
Are you using IPV6? no
Is the issue reproducible? yes

Code: Select all

2013-07-04 20:25:59,082::INFO::[sabnzbd:1256] --------------------------------
2013-07-04 20:25:59,082::INFO::[sabnzbd:1257] SABnzbd.exe-0.7.12 (rev=ca9f10c12fb3dada0c50b7e6aa3f08e091a003d3)
2013-07-04 20:25:59,740::INFO::[sabnzbd:1265] Platform=Windows-Vista-6.2.9200 (=Vista+ x64) Class=nt
2013-07-04 20:25:59,740::INFO::[sabnzbd:1270] Python-version = 2.5.5 (r255:77872, Jan 31 2010, 15:49:35) [MSC v.1310 32 bit (Intel)]
2013-07-04 20:25:59,742::INFO::[sabnzbd:1271] Arguments = "C:\Program Files (x86)\SABnzbd\SABnzbd.exe" "C:\Users\hp\AppData\Local\Temp\falling skies.nzb"
2013-07-04 20:25:59,742::INFO::[sabnzbd:1284] Read INI file C:\Users\hp\AppData\Local\sabnzbd\sabnzbd.ini
2013-07-04 20:25:59,743::INFO::[__init__:928] Loading data for bookmarks.sab from C:\Users\hp\AppData\Local\sabnzbd\admin\bookmarks.sab
2013-07-04 20:25:59,743::INFO::[__init__:928] Loading data for rss_data.sab from C:\Users\hp\AppData\Local\sabnzbd\admin\rss_data.sab
2013-07-04 20:25:59,744::INFO::[__init__:928] Loading data for totals9.sab from C:\Users\hp\AppData\Local\sabnzbd\admin\totals9.sab
2013-07-04 20:25:59,744::INFO::[__init__:931] C:\Users\hp\AppData\Local\sabnzbd\admin\totals9.sab missing, trying old cache
2013-07-04 20:25:59,744::INFO::[__init__:934] C:\Users\hp\AppData\Local\sabnzbd\cache\totals9.sab missing
2013-07-04 20:25:59,825::INFO::[postproc:90] Loading postproc queue
2013-07-04 20:25:59,825::INFO::[__init__:928] Loading data for postproc1.sab from C:\Users\hp\AppData\Local\sabnzbd\admin\postproc1.sab
2013-07-04 20:25:59,825::INFO::[__init__:928] Loading data for queue9.sab from C:\Users\hp\AppData\Local\sabnzbd\admin\queue9.sab
2013-07-04 20:25:59,825::INFO::[__init__:928] Loading data for watched_data.sab from C:\Users\hp\AppData\Local\sabnzbd\admin\watched_data.sab
2013-07-04 20:25:59,826::INFO::[scheduler:172] Setting schedule for midnight BPS reset
2013-07-04 20:25:59,826::INFO::[downloader:211] Resuming
2013-07-04 20:25:59,832::INFO::[__init__:330] All processes started
2013-07-04 20:25:59,832::INFO::[sabnzbd:334] Web dir is C:\Program Files (x86)\SABnzbd\interfaces\Classic
2013-07-04 20:25:59,834::INFO::[sabnzbd:334] Web dir is C:\Program Files (x86)\SABnzbd\interfaces\Config
2013-07-04 20:25:59,845::INFO::[sabnzbd:463] _yenc module... found!
2013-07-04 20:25:59,846::INFO::[sabnzbd:471] par2 binary... found (C:\Program Files (x86)\SABnzbd\win\par2\x64\par2.exe)
2013-07-04 20:25:59,848::INFO::[sabnzbd:476] par2-classic binary... found (C:\Program Files (x86)\SABnzbd\win\par2\par2-classic.exe)
2013-07-04 20:25:59,848::INFO::[sabnzbd:479] unrar binary... found (C:\Program Files (x86)\SABnzbd\win\unrar\x64\UnRAR.exe)
2013-07-04 20:25:59,848::INFO::[sabnzbd:484] unzip binary... found (C:\Program Files (x86)\SABnzbd\win\unzip\unzip.exe)
2013-07-04 20:25:59,848::INFO::[sabnzbd:499] pyOpenSSL... found (True)
2013-07-04 20:25:59,849::INFO::[sabnzbd:1483] Starting web-interface on ::1:8081
2013-07-04 20:26:01,852::INFO::[_cplogging:55] [04/Jul/2013:20:26:01] ENGINE Bus STARTING
2013-07-04 20:26:01,858::INFO::[_cplogging:55] [04/Jul/2013:20:26:01] ENGINE Started monitor thread '_TimeoutMonitor'.
2013-07-04 20:26:02,062::INFO::[_cplogging:55] [04/Jul/2013:20:26:02] ENGINE Serving on 127.0.0.1:8081
2013-07-04 20:26:57,301::ERROR::[_cplogging:55] [04/Jul/2013:20:26:57] ENGINE Error in 'start' listener <bound method Server.start of <cherrypy._cpserver.Server object at 0x02768BF0>>
Traceback (most recent call last):
  File "cherrypy\process\wspbus.pyo", line 147, in publish
  File "cherrypy\_cpserver.pyo", line 90, in start
  File "cherrypy\process\servers.pyo", line 62, in start
  File "cherrypy\process\servers.pyo", line 103, in wait
  File "cherrypy\process\servers.pyo", line 275, in wait_for_occupied_port
IOError: Port 8081 not bound on '::1'

2013-07-04 20:26:57,301::ERROR::[_cplogging:55] [04/Jul/2013:20:26:57] ENGINE Shutting down due to error in start listener:
Traceback (most recent call last):
  File "cherrypy\process\wspbus.pyo", line 184, in start
  File "cherrypy\process\wspbus.pyo", line 147, in publish
  File "cherrypy\_cpserver.pyo", line 90, in start
  File "cherrypy\process\servers.pyo", line 62, in start
  File "cherrypy\process\servers.pyo", line 103, in wait
  File "cherrypy\process\servers.pyo", line 275, in wait_for_occupied_port
IOError: Port 8081 not bound on '::1'

2013-07-04 20:26:57,302::INFO::[_cplogging:55] [04/Jul/2013:20:26:57] ENGINE Bus STOPPING
2013-07-04 20:26:57,302::INFO::[_cplogging:55] [04/Jul/2013:20:26:57] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('::1', 8081)) already shut down
2013-07-04 20:26:57,408::INFO::[_cplogging:55] [04/Jul/2013:20:26:57] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('127.0.0.1', 8081)) shut down
2013-07-04 20:26:57,414::INFO::[_cplogging:55] [04/Jul/2013:20:26:57] ENGINE Stopped thread '_TimeoutMonitor'.
2013-07-04 20:26:57,414::INFO::[_cplogging:55] [04/Jul/2013:20:26:57] ENGINE Bus STOPPED
2013-07-04 20:26:57,415::INFO::[_cplogging:55] [04/Jul/2013:20:26:57] ENGINE Bus EXITING
2013-07-04 20:26:57,415::INFO::[_cplogging:55] [04/Jul/2013:20:26:57] ENGINE Bus EXITED
2013-07-04 20:26:57,415::ERROR::[sabnzbd:1498] Failed to start web-interface: 
Traceback (most recent call last):
  File "SABnzbd.py", line 1490, in main
  File "cherrypy\process\wspbus.pyo", line 184, in start
  File "cherrypy\process\wspbus.pyo", line 147, in publish
  File "cherrypy\_cpserver.pyo", line 90, in start
  File "cherrypy\process\servers.pyo", line 62, in start
  File "cherrypy\process\servers.pyo", line 103, in wait
  File "cherrypy\process\servers.pyo", line 275, in wait_for_occupied_port
IOError: Port 8081 not bound on '::1'
2013-07-04 20:26:57,417::ERROR::[sabnzbd:303] Failed to start web-interface : Port 8081 not bound on '::1'
2013-07-04 20:26:57,417::INFO::[__init__:366] SABnzbd shutting down...
2013-07-04 20:26:57,417::INFO::[__init__:904] Saving data for bookmarks.sab in C:\Users\hp\AppData\Local\sabnzbd\admin\bookmarks.sab
2013-07-04 20:26:57,417::INFO::[urlgrabber:72] URLGrabber shutting down
2013-07-04 20:26:57,418::INFO::[__init__:904] Saving data for watched_data.sab in C:\Users\hp\AppData\Local\sabnzbd\admin\watched_data.sab
2013-07-04 20:26:57,420::INFO::[dirscanner:265] Dirscanner shutting down
2013-07-04 20:26:57,921::INFO::[postproc:84] Saving postproc queue
2013-07-04 20:26:57,921::INFO::[__init__:904] Saving data for postproc1.sab in C:\Users\hp\AppData\Local\sabnzbd\admin\postproc1.sab
2013-07-04 20:26:57,921::INFO::[nzbqueue:211] Saving queue
2013-07-04 20:26:57,923::INFO::[__init__:904] Saving data for queue9.sab in C:\Users\hp\AppData\Local\sabnzbd\admin\queue9.sab
2013-07-04 20:26:57,924::INFO::[__init__:904] Saving data for rss_data.sab in C:\Users\hp\AppData\Local\sabnzbd\admin\rss_data.sab
2013-07-04 20:26:57,924::INFO::[__init__:904] Saving data for bookmarks.sab in C:\Users\hp\AppData\Local\sabnzbd\admin\bookmarks.sab
2013-07-04 20:26:57,926::INFO::[__init__:904] Saving data for watched_data.sab in C:\Users\hp\AppData\Local\sabnzbd\admin\watched_data.sab
2013-07-04 20:26:57,926::INFO::[postproc:84] Saving postproc queue
2013-07-04 20:26:57,927::INFO::[__init__:904] Saving data for postproc1.sab in C:\Users\hp\AppData\Local\sabnzbd\admin\postproc1.sab
2013-07-04 20:26:57,927::INFO::[__init__:426] All processes stopped

Re: Crashing on Windows 8 due to port not bound on ::1

Posted: July 5th, 2013, 6:59 am
by ianl
Upgraded to 7.13 with same results.

Re: Crashing on Windows 8 due to port not bound on ::1

Posted: July 5th, 2013, 7:27 am
by sander
"::1" is the IPv6 localhost. Which is strange because "ENGINE Serving on 127.0.0.1:8081", so clearly IPv4

I believe there is a parameter "no ipv6" in SAB ... can check that?

EDIT:

Found it: http://wiki.sabnzbd.org/command-line-parameters ...

" --no_ipv6 Use this when your system cannot handle ::1 (the IPv6 value of "localhost"). The setting will be stored in the sabnzbd.ini file (0.7.1+)"

Re: Crashing on Windows 8 due to port not bound on ::1

Posted: July 5th, 2013, 9:05 am
by ianl
I checked the no_ipv6 box in config and saved/restarted but the error persists.

Re: Crashing on Windows 8 due to port not bound on ::1

Posted: July 5th, 2013, 9:08 am
by sander
ianl wrote:I checked the no_ipv6 box in config and saved/restarted but the error persists.
Wait ... you were able to access the SAB webinterface?

If so, does that mean SAB is working?

Re: Crashing on Windows 8 due to port not bound on ::1

Posted: July 5th, 2013, 10:01 pm
by ianl
It crashes after 2-3 actions. It never downloads. For example it took me 20 tries to get through the setup menu, but eventually I got it configured. It starts and I can check the no_ipv6 box, but when I click another config screen it crashes. i was able to add an NZB but it sits in the queue with no action. The behavior with no_ipv6 is unchanged. Here is the most recent error log with no_ipv6 checked:

Code: Select all

2013-07-06 10:53:52,746::INFO::[sabnzbd:1256] --------------------------------
2013-07-06 10:53:52,746::INFO::[sabnzbd:1257] SABnzbd.exe-0.7.13 (rev=f33a95253690e754ab6be3579b13b6936b3d42e0)
2013-07-06 10:53:53,424::INFO::[sabnzbd:1265] Platform=Windows-Vista-6.2.9200 (=Vista+ x64) Class=nt
2013-07-06 10:53:53,424::INFO::[sabnzbd:1270] Python-version = 2.5.5 (r255:77872, Jan 31 2010, 15:49:35) [MSC v.1310 32 bit (Intel)]
2013-07-06 10:53:53,424::INFO::[sabnzbd:1271] Arguments = "C:\Program Files (x86)\SABnzbd\SABnzbd.exe"
2013-07-06 10:53:53,426::INFO::[sabnzbd:1284] Read INI file C:\Users\hp\AppData\Local\sabnzbd\sabnzbd.ini
2013-07-06 10:53:53,427::INFO::[__init__:928] Loading data for bookmarks.sab from C:\Users\hp\AppData\Local\sabnzbd\admin\bookmarks.sab
2013-07-06 10:53:53,427::INFO::[__init__:928] Loading data for rss_data.sab from C:\Users\hp\AppData\Local\sabnzbd\admin\rss_data.sab
2013-07-06 10:53:53,427::INFO::[__init__:928] Loading data for totals9.sab from C:\Users\hp\AppData\Local\sabnzbd\admin\totals9.sab
2013-07-06 10:53:53,427::INFO::[__init__:931] C:\Users\hp\AppData\Local\sabnzbd\admin\totals9.sab missing, trying old cache
2013-07-06 10:53:53,428::INFO::[__init__:934] C:\Users\hp\AppData\Local\sabnzbd\cache\totals9.sab missing
2013-07-06 10:53:53,525::INFO::[postproc:90] Loading postproc queue
2013-07-06 10:53:53,526::INFO::[__init__:928] Loading data for postproc1.sab from C:\Users\hp\AppData\Local\sabnzbd\admin\postproc1.sab
2013-07-06 10:53:53,526::INFO::[__init__:928] Loading data for queue9.sab from C:\Users\hp\AppData\Local\sabnzbd\admin\queue9.sab
2013-07-06 10:53:53,532::INFO::[__init__:928] Loading data for watched_data.sab from C:\Users\hp\AppData\Local\sabnzbd\admin\watched_data.sab
2013-07-06 10:53:53,535::INFO::[scheduler:172] Setting schedule for midnight BPS reset
2013-07-06 10:53:53,535::INFO::[downloader:211] Resuming
2013-07-06 10:53:53,546::INFO::[__init__:330] All processes started
2013-07-06 10:53:53,546::INFO::[sabnzbd:334] Web dir is C:\Program Files (x86)\SABnzbd\interfaces\Classic
2013-07-06 10:53:53,548::INFO::[sabnzbd:334] Web dir is C:\Program Files (x86)\SABnzbd\interfaces\Config
2013-07-06 10:53:53,568::INFO::[sabnzbd:463] _yenc module... found!
2013-07-06 10:53:53,568::INFO::[sabnzbd:471] par2 binary... found (C:\Program Files (x86)\SABnzbd\win\par2\x64\par2.exe)
2013-07-06 10:53:53,569::INFO::[sabnzbd:476] par2-classic binary... found (C:\Program Files (x86)\SABnzbd\win\par2\par2-classic.exe)
2013-07-06 10:53:53,569::INFO::[sabnzbd:479] unrar binary... found (C:\Program Files (x86)\SABnzbd\win\unrar\x64\UnRAR.exe)
2013-07-06 10:53:53,569::INFO::[sabnzbd:484] unzip binary... found (C:\Program Files (x86)\SABnzbd\win\unzip\unzip.exe)
2013-07-06 10:53:53,569::INFO::[sabnzbd:499] pyOpenSSL... found (True)
2013-07-06 10:53:53,572::INFO::[sabnzbd:1483] Starting web-interface on ::1:8081
2013-07-06 10:53:55,582::INFO::[_cplogging:55] [06/Jul/2013:10:53:55] ENGINE Bus STARTING
2013-07-06 10:53:55,588::INFO::[_cplogging:55] [06/Jul/2013:10:53:55] ENGINE Started monitor thread '_TimeoutMonitor'.
2013-07-06 10:53:55,792::INFO::[_cplogging:55] [06/Jul/2013:10:53:55] ENGINE Serving on 127.0.0.1:8081
2013-07-06 10:54:51,148::ERROR::[_cplogging:55] [06/Jul/2013:10:54:51] ENGINE Error in 'start' listener <bound method Server.start of <cherrypy._cpserver.Server object at 0x024E2BF0>>
Traceback (most recent call last):
  File "cherrypy\process\wspbus.pyo", line 147, in publish
  File "cherrypy\_cpserver.pyo", line 90, in start
  File "cherrypy\process\servers.pyo", line 62, in start
  File "cherrypy\process\servers.pyo", line 103, in wait
  File "cherrypy\process\servers.pyo", line 275, in wait_for_occupied_port
IOError: Port 8081 not bound on '::1'

2013-07-06 10:54:51,148::ERROR::[_cplogging:55] [06/Jul/2013:10:54:51] ENGINE Shutting down due to error in start listener:
Traceback (most recent call last):
  File "cherrypy\process\wspbus.pyo", line 184, in start
  File "cherrypy\process\wspbus.pyo", line 147, in publish
  File "cherrypy\_cpserver.pyo", line 90, in start
  File "cherrypy\process\servers.pyo", line 62, in start
  File "cherrypy\process\servers.pyo", line 103, in wait
  File "cherrypy\process\servers.pyo", line 275, in wait_for_occupied_port
IOError: Port 8081 not bound on '::1'

2013-07-06 10:54:51,148::INFO::[_cplogging:55] [06/Jul/2013:10:54:51] ENGINE Bus STOPPING
2013-07-06 10:54:51,148::INFO::[_cplogging:55] [06/Jul/2013:10:54:51] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('::1', 8081)) already shut down
2013-07-06 10:55:00,937::INFO::[_cplogging:55] [06/Jul/2013:10:55:00] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('127.0.0.1', 8081)) shut down
2013-07-06 10:55:00,979::INFO::[_cplogging:55] [06/Jul/2013:10:55:00] ENGINE Stopped thread '_TimeoutMonitor'.
2013-07-06 10:55:00,979::INFO::[_cplogging:55] [06/Jul/2013:10:55:00] ENGINE Bus STOPPED
2013-07-06 10:55:00,980::INFO::[_cplogging:55] [06/Jul/2013:10:55:00] ENGINE Bus EXITING
2013-07-06 10:55:00,980::INFO::[_cplogging:55] [06/Jul/2013:10:55:00] ENGINE Bus EXITED
2013-07-06 10:55:00,980::ERROR::[sabnzbd:1498] Failed to start web-interface: 
Traceback (most recent call last):
  File "SABnzbd.py", line 1490, in main
  File "cherrypy\process\wspbus.pyo", line 184, in start
  File "cherrypy\process\wspbus.pyo", line 147, in publish
  File "cherrypy\_cpserver.pyo", line 90, in start
  File "cherrypy\process\servers.pyo", line 62, in start
  File "cherrypy\process\servers.pyo", line 103, in wait
  File "cherrypy\process\servers.pyo", line 275, in wait_for_occupied_port
IOError: Port 8081 not bound on '::1'
2013-07-06 10:55:00,980::ERROR::[sabnzbd:303] Failed to start web-interface : Port 8081 not bound on '::1'
2013-07-06 10:55:00,980::INFO::[__init__:366] SABnzbd shutting down...
2013-07-06 10:55:00,982::INFO::[__init__:904] Saving data for bookmarks.sab in C:\Users\hp\AppData\Local\sabnzbd\admin\bookmarks.sab
2013-07-06 10:55:00,983::INFO::[urlgrabber:72] URLGrabber shutting down
2013-07-06 10:55:00,983::INFO::[__init__:904] Saving data for watched_data.sab in C:\Users\hp\AppData\Local\sabnzbd\admin\watched_data.sab
2013-07-06 10:55:00,983::INFO::[dirscanner:265] Dirscanner shutting down
2013-07-06 10:55:01,487::INFO::[postproc:84] Saving postproc queue
2013-07-06 10:55:01,487::INFO::[__init__:904] Saving data for postproc1.sab in C:\Users\hp\AppData\Local\sabnzbd\admin\postproc1.sab
2013-07-06 10:55:01,489::INFO::[nzbqueue:211] Saving queue
2013-07-06 10:55:01,503::INFO::[__init__:904] Saving data for queue9.sab in C:\Users\hp\AppData\Local\sabnzbd\admin\queue9.sab
2013-07-06 10:55:01,503::INFO::[__init__:904] Saving data for rss_data.sab in C:\Users\hp\AppData\Local\sabnzbd\admin\rss_data.sab
2013-07-06 10:55:01,505::INFO::[__init__:904] Saving data for bookmarks.sab in C:\Users\hp\AppData\Local\sabnzbd\admin\bookmarks.sab
2013-07-06 10:55:01,506::INFO::[__init__:904] Saving data for watched_data.sab in C:\Users\hp\AppData\Local\sabnzbd\admin\watched_data.sab
2013-07-06 10:55:01,506::INFO::[postproc:84] Saving postproc queue
2013-07-06 10:55:01,506::INFO::[__init__:904] Saving data for postproc1.sab in C:\Users\hp\AppData\Local\sabnzbd\admin\postproc1.sab
2013-07-06 10:55:01,507::INFO::[__init__:426] All processes stopped

Re: Crashing on Windows 8 due to port not bound on ::1

Posted: July 6th, 2013, 1:50 am
by sander
In SABnzbd: Config -> General: what is the value for "SABnzbd Host"? Is it "127.0.0.1"? Can you change it to "localhost" and try again? And "0.0.0.0"? And "::"?

Re: Crashing on Windows 8 due to port not bound on ::1

Posted: July 6th, 2013, 4:17 am
by ianl
Hello,

I did a clean install and manually setup everything in sabnzbd.ini, including disable ipv6 and disable 64 bit tools, and it seems to be working now. Works at 127.0.0.1 and localhost. No crashes no problems.

Thanks so much for your help, glad to be back on sabnzbd :)

Re: Crashing on Windows 8 due to port not bound on ::1

Posted: August 23rd, 2014, 12:25 pm
by andy_0
Hey,

a friend of mine just ran into this problem (sabnzbd 0.7.18, Windows 8.1 x64). I diagnosed his issue, sabnzbd starts corectly with any given port (e.g. 8080, 8081, ...). There is no other service connecting to or working on given port but sabnzbd crashes with "port in use" error message. I looked at the computer connections with Sysinternals TCPView and one can clearly see that one IPv4 server is running while multiple IPv6 connection on ::1 are trying to connect. After a minute sabnzbd discontinues and closes all instances. One MUST change the "host" parameter in the setup file to "127.0.0.1" as "localhost" generates this mentioned issue. Setting all IPv6 options to disabled does not help.

If you ask me this seems like a bug in the connection establishing routine.

Best regards,

Andy

Re: Crashing on Windows 8 due to port not bound on ::1

Posted: August 23rd, 2014, 2:33 pm
by shypike
The IPv6 support is and endless source of problems.
It's also influenced by your local router and possibly your ISP.
We'll have a look at this again.

We're using "localhost" because it looks nicer that the ugly 127.0.0.1,
however since IPv6 support started in OSses, localhost has become an ambiguous address.