sabnzbd stops downloading after 1-2 minutes

[justin0469]

OS: WinXP x64
Install-type: Windows installer
Skin (if applicable): NA / Default
Firewall Software: Built-in Windows
Are you using IPV6? no
Is the issue reproducible? yes

This has been happening since several versions ago but when downloading it will slow down and then just stop. The only way I can get it to start again is to do a Queue Repair. Just stopping and starting it again doesn't help. I'm not entirely sure what that does but it works. Any ideas?

[justin0469]

Last portion of the log looks like this:

Code: Select all

2013-03-22 22:17:03,500::DEBUG::[bpsmeter:253] bps: 1277789.5077
2013-03-22 22:17:03,500::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:04,092::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:04,250::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:04,687::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:04,983::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:05,046::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:05,280::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:05,562::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:05,812::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:06,390::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:06,655::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:06,796::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:07,530::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:07,765::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:08,092::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:08,187::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:08,358::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:08,500::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:08,515::DEBUG::[bpsmeter:253] bps: 1343721.26434
2013-03-22 22:17:08,530::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:08,812::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:10,405::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:10,546::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:10,828::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:10,953::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:11,203::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:11,578::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:11,812::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:11,842::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:11,937::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:11,953::DEBUG::[__init__:829] Saving data for SABnzbd_nzo_lyu-ef in C:\Media\incomplete\xxxxxxxpathxxxxxx\__ADMIN__
2013-03-22 22:17:12,203::INFO::[__init__:904] Saving data for totals9.sab in C:\Documents and Settings\xxxxxpathxxxxxx\sabnzbd\admin\totals9.sab
2013-03-22 22:17:12,203::INFO::[assembler:87] Decoding C:\Media\incomplete\xxxxxxxxfilenamexxxxxxxx.part19.rar yenc
2013-03-22 22:17:12,217::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:12,842::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:13,453::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:13,515::DEBUG::[decoder:101] Decoding [email protected]
2013-03-22 22:17:13,530::DEBUG::[bpsmeter:253] bps: 1258659.30374
2013-03-22 22:17:19,140::DEBUG::[bpsmeter:253] bps: 463989.300535
2013-03-22 22:17:25,140::DEBUG::[bpsmeter:253] bps: 155389.07795
2013-03-22 22:17:31,140::DEBUG::[bpsmeter:253] bps: 52039.4878038
2013-03-22 22:17:36,046::DEBUG::[interface:420] API-call from 127.0.0.1 [CouchPotato windows - desktop-2.0.7.1 - v2] {'output': 'json', 'apikey': '1115677b9ff7c85a998907bb9c7392ad', 'mode': 'queue'}
2013-03-22 22:17:36,092::DEBUG::[interface:420] API-call from 127.0.0.1 [CouchPotato windows - desktop-2.0.7.1 - v2] {'output': 'json', 'apikey': '1115677b9ff7c85a998907bb9c7392ad', 'limit': '15', 'mode': 'history'}
2013-03-22 22:17:37,140::DEBUG::[bpsmeter:253] bps: 17427.919173
2013-03-22 22:17:43,140::DEBUG::[bpsmeter:253] bps: 5836.57486878
2013-03-22 22:17:49,140::DEBUG::[bpsmeter:253] bps: 1954.6571143
2013-03-22 22:17:55,140::DEBUG::[bpsmeter:253] bps: 654.610712683
2013-03-22 22:18:01,140::DEBUG::[bpsmeter:253] bps: 219.227803191
 
 

[shypike]

Look for error messages in the log, especially the word "trace".
Do you have any schedules?
Is there enough room on the hard disk?
Set the option Config->Special->folder_max_length to 32, to avoid oversized file names.

[justin0469]

I see nothing about "trace". I've tried changing the no_penalties to 1 but it didn't change anything noticeable.
No schedules.
over 100GB available

If I look about 2 min after the speeds drop to 0, I see this

Code: Select all

2013-03-23 08:03:31,203::INFO::[downloader:661] Thread [email protected]:5563: timed out
2013-03-23 08:03:31,203::INFO::[downloader:661] Thread [email protected]:5563: timed out
2013-03-23 08:03:31,203::INFO::[downloader:661] Thread [email protected]:5563: timed out
2013-03-23 08:03:31,203::INFO::[downloader:661] Thread [email protected]:5563: timed out
2013-03-23 08:03:31,203::INFO::[downloader:661] Thread [email protected]:5563: timed out
2013-03-23 08:03:31,203::INFO::[downloader:661] Thread [email protected]:5563: timed out
2013-03-23 08:03:32,203::INFO::[downloader:661] Thread [email protected]:5563: timed out
2013-03-23 08:03:32,203::INFO::[downloader:661] Thread [email protected]:5563: timed out
2013-03-23 08:03:32,203::INFO::[downloader:661] Thread [email protected]:5563: timed out
2013-03-23 08:03:32,203::INFO::[downloader:661] Thread [email protected]:5563: timed out
2013-03-23 08:03:32,203::INFO::[downloader:661] Thread [email protected]:5563: timed out
2013-03-23 08:03:32,203::INFO::[downloader:661] Thread [email protected]:5563: timed out

So it looks like my connections are just timing out after finishing downloading that piece of the file. Shouldn't SABnzbd be requesting the next file chunk? Once it does that (exactly 4 minutes after timing out) everything is back to normal for another couple of minutes until those pieces are done downloading again.

[shypike]

Are you sure the port number should be 5563?
Make sure you don't make the server time out too small in Config->Servers.
It should be least 60 sec or more.
Some servers work with lower times, but not all.
Also check your number of retries in Config->Switches, it should at least 3.

[justin0469]

Yes, Frugalusenet can use 563 or 5563 for HTTPS (http://frugalusenet.com/faq.html). Timeout is 240 sec and maximum retries is 5.

I use HTTPS because, in theory, it's more secure and doesn't let my ISP know what I'm doing.

[justin0469]

So it looks like my connections are just timing out after finishing downloading that piece of the file. Shouldn't SABnzbd be requesting the next file chunk? Once it does that (exactly 4 minutes after timing out) everything is back to normal for another couple of minutes until those pieces are done downloading again.

To be more clear about what I meant here, when SABnzbd does attempt to reconnect (exactly 4 minutes after it times out), everything connects again and is downloading at good speeds. Can I change how long it waits after timeouts like that? Is SAB or the usenet provider causing the timeout?

[shypike]

Hard to tell. I have no experience with frugalusenet.
The fact that it times out often is odd, the major providers rarely do that.

[jcfp]

Hard to tell. I have no experience with frugalusenet.
The fact that it times out often is odd, the major providers rarely do that.

frugal = usenetnow = blocknews (server ips all in the same /24)

[shypike]

Timeout is 240 sec and maximum retries is 5.

240 seconds is a bit over the top. It is actually 4 minutes Sounds familiar?
I think you should reduce it to 60 seconds.

[justin0469]

OK, I'll take it down to 60 and see what happens.