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 4OacdvkWSMrHxzsS0WZ5@JBinUp.local
2013-03-22 22:17:04,092::DEBUG::[decoder:101] Decoding nGJBzqZHzOrP0lRxCU3@JBinUp.local
2013-03-22 22:17:04,250::DEBUG::[decoder:101] Decoding 3hOPTbIHR6X3NERLnL7C@JBinUp.local
2013-03-22 22:17:04,687::DEBUG::[decoder:101] Decoding ZbrvKqxHPbTFA47FFu1s@JBinUp.local
2013-03-22 22:17:04,983::DEBUG::[decoder:101] Decoding FgvXO3hJJmqTrIRyGMOx@JBinUp.local
2013-03-22 22:17:05,046::DEBUG::[decoder:101] Decoding Hael9TLytgdPexLHGbC5@JBinUp.local
2013-03-22 22:17:05,280::DEBUG::[decoder:101] Decoding 7mHODkF6tgkTcubrYrW3@JBinUp.local
2013-03-22 22:17:05,562::DEBUG::[decoder:101] Decoding osO7kLiLURLYaDcBw7c2@JBinUp.local
2013-03-22 22:17:05,812::DEBUG::[decoder:101] Decoding 20uUVCuTttvnsTCgZasT@JBinUp.local
2013-03-22 22:17:06,390::DEBUG::[decoder:101] Decoding g8Df3JGYXGZ8WmuEEWn8@JBinUp.local
2013-03-22 22:17:06,655::DEBUG::[decoder:101] Decoding mBFPfLd5QO1bzsmpskto@JBinUp.local
2013-03-22 22:17:06,796::DEBUG::[decoder:101] Decoding 4uUWyLRJT3T9KvHoRE39@JBinUp.local
2013-03-22 22:17:07,530::DEBUG::[decoder:101] Decoding 0SrMetrEIgetI8Q4naaQ@JBinUp.local
2013-03-22 22:17:07,765::DEBUG::[decoder:101] Decoding vzgGdhB1ejQhGZtl5a7F@JBinUp.local
2013-03-22 22:17:08,092::DEBUG::[decoder:101] Decoding H8kJQTzhhXQj2AdsUFy6@JBinUp.local
2013-03-22 22:17:08,187::DEBUG::[decoder:101] Decoding EEU4U9GuwD9rr2fOB6g1@JBinUp.local
2013-03-22 22:17:08,358::DEBUG::[decoder:101] Decoding YKQsk2lm5xFgdPYJcghs@JBinUp.local
2013-03-22 22:17:08,500::DEBUG::[decoder:101] Decoding gXbp69cYEKyP6gIMEN5C@JBinUp.local
2013-03-22 22:17:08,515::DEBUG::[bpsmeter:253] bps: 1343721.26434
2013-03-22 22:17:08,530::DEBUG::[decoder:101] Decoding 1HQE3SeUCcUlkXk4GI7Q@JBinUp.local
2013-03-22 22:17:08,812::DEBUG::[decoder:101] Decoding dwGpJeqMWucYxZnonzro@JBinUp.local
2013-03-22 22:17:10,405::DEBUG::[decoder:101] Decoding v5zs715wxf6zyIPkzq2A@JBinUp.local
2013-03-22 22:17:10,546::DEBUG::[decoder:101] Decoding 1JJRahWUXIhMUc2EjRT@JBinUp.local
2013-03-22 22:17:10,828::DEBUG::[decoder:101] Decoding BPWaKigxWHRiDsb9JYJ2@JBinUp.local
2013-03-22 22:17:10,953::DEBUG::[decoder:101] Decoding AOprIiNk59nKHcd7qws9@JBinUp.local
2013-03-22 22:17:11,203::DEBUG::[decoder:101] Decoding pNwUqLDEu5c96OVUdya1@JBinUp.local
2013-03-22 22:17:11,578::DEBUG::[decoder:101] Decoding 2xIRFvUqJJKKZQ77baEB@JBinUp.local
2013-03-22 22:17:11,812::DEBUG::[decoder:101] Decoding DfR0RRvKUjGS4gjCcnvA@JBinUp.local
2013-03-22 22:17:11,842::DEBUG::[decoder:101] Decoding KcakIjk3dnpBjayfgyd2@JBinUp.local
2013-03-22 22:17:11,937::DEBUG::[decoder:101] Decoding RMph2b7z0Y354NAH0uoB@JBinUp.local
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 B47FjA7d3G0QBBuwOnS@JBinUp.local
2013-03-22 22:17:12,842::DEBUG::[decoder:101] Decoding vrUTfE1bIPzZgr5BdAcF@JBinUp.local
2013-03-22 22:17:13,453::DEBUG::[decoder:101] Decoding uM2H5pXYrY162tidx5v1@JBinUp.local
2013-03-22 22:17:13,515::DEBUG::[decoder:101] Decoding AocqEfawyQavkg6OKQM01@JBinUp.local
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 5@news.frugalusenet.com:5563: timed out
2013-03-23 08:03:31,203::INFO::[downloader:661] Thread 12@news.frugalusenet.com:5563: timed out
2013-03-23 08:03:31,203::INFO::[downloader:661] Thread 7@news.frugalusenet.com:5563: timed out
2013-03-23 08:03:31,203::INFO::[downloader:661] Thread 14@news.frugalusenet.com:5563: timed out
2013-03-23 08:03:31,203::INFO::[downloader:661] Thread 3@news.frugalusenet.com:5563: timed out
2013-03-23 08:03:31,203::INFO::[downloader:661] Thread 8@news.frugalusenet.com:5563: timed out
2013-03-23 08:03:32,203::INFO::[downloader:661] Thread 13@news.frugalusenet.com:5563: timed out
2013-03-23 08:03:32,203::INFO::[downloader:661] Thread 10@news.frugalusenet.com:5563: timed out
2013-03-23 08:03:32,203::INFO::[downloader:661] Thread 1@news.frugalusenet.com:5563: timed out
2013-03-23 08:03:32,203::INFO::[downloader:661] Thread 9@news.frugalusenet.com:5563: timed out
2013-03-23 08:03:32,203::INFO::[downloader:661] Thread 6@news.frugalusenet.com:5563: timed out
2013-03-23 08:03:32,203::INFO::[downloader:661] Thread 4@news.frugalusenet.com: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.