Downloading stops after about a minute

[fred44]

After working great for months, now I find SAB has stopped downloading anything despite everything looking normal - it's not paused, plenty of space etc.

If I restart the service downloading will resume for about a minute then stop again. It's not stuck on any file that I can see as downloads will finish successfully, just it takes many restarts to do this.

Windows 10
Running SAB 1.2.1 now. Experienced the problem on 1.2.2 and downgrading didn't fix it.

Any ideas?

[safihre]

How about 1.1.1 or 1.0.3? They are available from the downloads page.

Also, can you set logging to Debug in the Status window and then look at the log what happens before it stops?

[fred44]

Downgraded to 1.1.1 and all started normally. But then it slowed down and stopped as 1.2.2 did.

Here goes the last bit of the logs, trimming out some similar/repeated sections to reduce size and URL restriction:
Actually I'll send in a few batches as I can't see where to upload a file and the forum keeps complaining about URLs that I can't find.

Code: Select all

2017-03-13 19:16:26,621::DEBUG::[bpsmeter:270] bps: 408999.398645
2017-03-13 19:16:28,595::DEBUG::[decoder:106] Decoding 1488299048.71639.33@reader2
2017-03-13 19:16:31,631::DEBUG::[bpsmeter:270] bps: 882963.16525
2017-03-13 19:16:34,305::DEBUG::[decoder:106] Decoding 1488299048.77915.35@reader2
2017-03-13 19:16:36,641::DEBUG::[bpsmeter:270] bps: 1171633.98686
2017-03-13 19:16:36,746::DEBUG::[decoder:106] Decoding 1488299043.53064.48@reader2
2017-03-13 19:16:41,648::DEBUG::[bpsmeter:270] bps: 1292665.96446
2017-03-13 19:17:06,690::DEBUG::[bpsmeter:270] bps: 1138261.58236
2017-03-13 19:17:11,697::DEBUG::[bpsmeter:270] bps: 1254767.9012
2017-03-13 19:17:15,832::DEBUG::[interface:522] API-call from 127.0.0.1 [CouchPotato windows - desktop-3.0.1 - v2] {'output': u'json', 'apikey': u'<HASH>1804379', 'mode': u'queue'}
2017-03-13 19:17:15,845::DEBUG::[interface:522] API-call from 127.0.0.1 [CouchPotato windows - desktop-3.0.1 - v2] {'output': u'json', 'apikey': u'<HASH>1804379', 'limit': u'15', 'mode': u'history'}
2017-03-13 19:17:16,700::DEBUG::[bpsmeter:270] bps: 1377383.73029
2017-03-13 19:17:21,709::DEBUG::[bpsmeter:270] bps: 1262426.56232
2017-03-13 19:17:39,793::DEBUG::[interface:522] API-call from 127.0.0.1 [Sonarr/2.0.0.4645 (windows 10.0.14393.0)] {'start': u'0', 'apikey': u'<HASH>1804379', 'limit': u'0', 'mode': u'queue', 'output': u'json'}
2017-03-13 19:17:39,801::DEBUG::[interface:522] API-call from 127.0.0.1 [Sonarr/2.0.0.4645 (windows 10.0.14393.0)] {'start': u'0', 'apikey': u'<HASH>1804379', 'limit': u'30', 'mode': u'history', 'output': u'json'}
2017-03-13 19:17:41,736::DEBUG::[bpsmeter:270] bps: 1280747.70309
2017-03-13 19:17:46,740::DEBUG::[bpsmeter:270] bps: 1217673.78086
2017-03-13 19:17:51,798::DEBUG::[bpsmeter:270] bps: 1272920.407
2017-03-13 19:18:26,836::DEBUG::[bpsmeter:270] bps: 1268464.2002
2017-03-13 19:19:06,917::DEBUG::[bpsmeter:270] bps: 1297965.39169
2017-03-13 19:19:09,857::DEBUG::[interface:522] API-call from 127.0.0.1 [Sonarr/2.0.0.4645 (windows 10.0.14393.0)] {'start': u'0', 'apikey': u'<HASH>1804379', 'limit': u'0', 'mode': u'queue', 'output': u'json'}
2017-03-13 19:19:09,868::DEBUG::[interface:522] API-call from 127.0.0.1 [Sonarr/2.0.0.4645 (windows 10.0.14393.0)] {'start': u'0', 'apikey': u'<HASH>1804379', 'limit': u'30', 'mode': u'history', 'output': u'json'}
2017-03-13 19:19:11,926::DEBUG::[bpsmeter:270] bps: 1214000.35555
2017-03-13 19:19:16,930::DEBUG::[bpsmeter:270] bps: 1196547.64258
2017-03-13 19:19:21,940::DEBUG::[bpsmeter:270] bps: 1170267.96084
2017-03-13 19:19:26,940::DEBUG::[bpsmeter:270] bps: 1218689.72869
2017-03-13 19:19:31,966::DEBUG::[bpsmeter:270] bps: 1276027.01784
2017-03-13 19:19:36,982::DEBUG::[bpsmeter:270] bps: 1201566.134

[fred44]

Code: Select all

2017-03-13 19:19:40,059::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:40,237::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:40,469::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:41,667::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:41,993::DEBUG::[bpsmeter:270] bps: 1264103.13931
2017-03-13 19:19:42,148::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:42,674::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:43,348::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:43,549::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:46,780::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:47,036::DEBUG::[bpsmeter:270] bps: 1108499.83867
2017-03-13 19:19:47,091::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:47,114::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:47,792::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:47,898::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:48,361::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:48,961::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:49,924::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:50,698::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:50,762::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:52,111::DEBUG::[bpsmeter:270] bps: 600266.884847
2017-03-13 19:19:55,486::DEBUG::[downloader:263] Delaying
2017-03-13 19:19:57,509::DEBUG::[bpsmeter:270] bps: 268477.65601
2017-03-13 19:20:02,566::DEBUG::[bpsmeter:270] bps: 106875.964511
2017-03-13 19:20:07,625::DEBUG::[bpsmeter:270] bps: 42538.2569975
2017-03-13 19:20:12,684::DEBUG::[bpsmeter:270] bps: 16925.2408294
2017-03-13 19:20:17,744::DEBUG::[bpsmeter:270] bps: 6734.26222304
2017-03-13 19:20:22,802::DEBUG::[bpsmeter:270] bps: 2680.3386378
2017-03-13 19:20:27,858::DEBUG::[bpsmeter:270] bps: 1067.1704846
2017-03-13 19:20:32,917::DEBUG::[bpsmeter:270] bps: 424.679449943
2017-03-13 19:20:37,974::DEBUG::[bpsmeter:270] bps: 169.056995264
2017-03-13 19:20:38,927::DEBUG::[interface:522] API-call from 127.0.0.1 [Sonarr/2.0.0.4645 (windows 10.0.14393.0)] {'start': u'0', 'apikey': u'<HASH>1804379', 'limit': u'0', 'mode': u'queue', 'output': u'json'}
2017-03-13 19:20:38,936::DEBUG::[interface:522] API-call from 127.0.0.1 [Sonarr/2.0.0.4645 (windows 10.0.14393.0)] {'start': u'0', 'apikey': u'<HASH>1804379', 'limit': u'30', 'mode': u'history', 'output': u'json'}
2017-03-13 19:20:43,033::DEBUG::[bpsmeter:270] bps: 67.2760602318
2017-03-13 19:20:48,092::DEBUG::[bpsmeter:270] bps: 26.7768936917
2017-03-13 19:20:53,148::DEBUG::[bpsmeter:270] bps: 10.6593836613
2017-03-13 19:20:58,207::DEBUG::[bpsmeter:270] bps: 4.24259658097
2017-03-13 19:21:03,266::DEBUG::[bpsmeter:270] bps: 1.68833701721
2017-03-13 19:21:08,322::DEBUG::[bpsmeter:270] bps: 0.672207405914
2017-03-13 19:21:13,380::DEBUG::[bpsmeter:270] bps: 0.267548756347
2017-03-13 19:21:18,437::DEBUG::[bpsmeter:270] bps: 0.106506186821
2017-03-13 19:21:23,490::DEBUG::[bpsmeter:270] bps: 0.0424192983975
__version__ = 19
__encoding__ = utf-8
[misc]
win_menu = 1
queue_complete = ""
https_port = 9090
replace_spaces = 0
allow_64bit_tools = 1
password_file = ""
movie_rename_limit = 100M
ionice = ""
https_key = server.key
cleanup_list = ,
notified_new_skin = 2
rating_filter_enable = 0
cache_limit = 450M
rating_enable = 1
ignore_unrar_dates = 0
par_option = ""
web_color = Default
inet_exposure = 0
enable_recursive = 1
selftest_host = self-test
pre_check = 0
folder_rename = 1
web_color2 = ""
dirscan_speed = 60
enable_filejoin = 1
create_group_folders = 0
api_key = <HASH>1804379
rating_feedback = 1
no_penalties = 0
load_balancing = 2
rating_filter_abort_video = 0
osx_speed = 1
dirscan_dir = d:\Down
disable_api_key = 0
quota_period = m
email_to = ,
enable_7zip = 1
rating_filter_pause_audio = 0
rss_filenames = 0
password = ""
permissions = ""
quota_day = ""
auto_disconnect = 1
use_pickle = 0
https_chain = ""
warn_empty_nzb = 1
history_limit = 250
rating_filter_pause_encrypted_confirm = 0
warned_old_queue9 = 0
enable_https_verification = 0
replace_dots = 1
ipv6_hosting = 0
enable_bonjour = 1
movie_sort_extra = -cd%1
quota_size = ""
rss_rate = 60
enable_unzip = 1
download_free = ""
port = 8080
enable_movie_sorting = 0
wait_ext_drive = 5
pre_script = None
ipv6_servers = 1
enable_all_par = 0
enable_date_sorting = 0
email_full = 0
unpack_check = 1
log_dir = logs
rating_filter_pause_encrypted = 0
queue_limit = 50
admin_dir = admin
nzb_backup_dir = ""
tv_sort_countries = 1
date_categories = tv,
osx_menu = 1
show_sysload = 2
sfv_check = 1
date_sort_string = ""
schedlines = ,
host = 127.0.0.1
https_cert = server.cert
email_rss = 0
sanitize_safe = 0
req_completion_rate = 100.2
rating_filter_abort_encrypted_confirm = 0
movie_sort_string = ""
rating_filter_pause_spam_confirm = 0
fsys_type = 0
pause_on_post_processing = 0
allow_incomplete_nzb = 0
rating_filter_abort_keywords = ""
enable_unrar = 1
enable_meta = 1
ignore_samples = 0
email_account = ""
email_server = ""
allow_streaming = 0
config_lock = 0
quota_resume = 0
api_warnings = 1
wait_for_dfolder = 0
tv_sort_string = ""
rating_filter_abort_encrypted = 0
rating_filter_pause_keywords = ""
warn_dupl_jobs = 1
fail_hopeless = 0
rating_filter_abort_spam_confirm = 0
web_dir = Glitter
no_series_dupes = 0
rating_filter_abort_downvoted = 0
download_dir = W:\SAB\Temp
size_limit = 0
enable_par_cleanup = 1
safe_postproc = 1
language = en
new_nzb_on_failure = 0
nzb_key = <HASH>3c1c6eb
complete_dir = W:\SAB\Complete
rating_filter_pause_downvoted = 0
replace_illegal = 1
rating_filter_abort_audio = 0
nomedia_marker = ""
max_art_opt = 0
par2_multicore = 1
enable_tsjoin = 1
check_new_rel = 1
bandwidth_max = ""
local_ranges = ,
enable_https = 0
username = ""
never_repair = 0
no_dupes = 2
keep_awake = 1
top_only = 0
quick_check = 0
auto_browser = 0
email_from = ""
unwanted_extensions = ,
max_art_tries = 3
movie_extra_folder = 0
email_endjob = 0
tv_categories = ,
rss_odd_titles = /
pause_on_pwrar = 2
login_realm = SABnzbd
rating_filter_pause_spam = 0
backup_for_duplicates = 1
refresh_rate = 1
sched_converted = 1
prio_sort_list = ,
movie_categories = movies,
script_can_fail = 0
script_dir = ""
bandwidth_perc = 0
email_pwd = ""
empty_postproc = 0
ignore_wrong_unrar = 0
nice = ""
rating_host = api
email_dir = ""
rating_filter_pause_video = 0
web_dir2 = ""
api_logging = 1
web_watchdog = 0
action_on_unwanted_extensions = 0
rating_api_key = <REMOVED>
flat_unpack = 0
auto_sort = 0
ampm = 0
rating_filter_abort_spam = 0
start_paused = 0
overwrite_files = 0
queue_complete_pers = 0
enable_tv_sorting = 0
folder_max_length = 128
allow_duplicate_files = 0
fail_hopeless_jobs = 1
fixed_ports = 0
html_login = 1
prospective_par_download = 1
propagation_delay = 0
ssl_ciphers = ""
[logging]
log_new = 0
max_log_size = 5242880
log_level = 2
log_backups = 5
enable_cherrypy_logging = 0
[ncenter]
ncenter_prio_download = 0
ncenter_prio_warning = 0
ncenter_prio_queue_done = 0
ncenter_prio_startup = 0
ncenter_prio_failed = 0
ncenter_prio_disk_full = 0
ncenter_prio_error = 0
ncenter_enable = 0
ncenter_prio_complete = 0
ncenter_prio_pp = 0
ncenter_prio_other = 0
ncenter_prio_new_login = 0
[pushover]
pushover_prio_error = 1
pushover_enable = 0
pushover_prio_complete = -1
pushover_prio_warning = 1
pushover_prio_startup = -3
pushover_prio_queue_done = -1
pushover_prio_other = -3
pushover_prio_pp = -3
pushover_prio_disk_full = 1
pushover_device = ""
pushover_token = ""
pushover_prio_failed = -1
pushover_prio_download = -2
pushover_userkey = ""
pushover_prio_new_login = -3
[growl]
growl_prio_disk_full = 1
growl_enable = 0
growl_prio_other = 0
growl_prio_download = 0
growl_server = ""
growl_prio_startup = 1
growl_prio_failed = 1
growl_prio_queue_done = 1
growl_prio_warning = 0
growl_prio_error = 0
growl_prio_complete = 1
growl_prio_pp = 0
growl_password = ""
growl_prio_new_login = 0
[prowl]
prowl_prio_disk_full = 1
prowl_prio_error = -3
prowl_prio_download = -3
prowl_enable = 0
prowl_prio_failed = 1
prowl_prio_pp = -3
prowl_apikey = ""
prowl_prio_startup = -3
prowl_prio_warning = -3
prowl_prio_other = -3
prowl_prio_complete = 0
prowl_prio_queue_done = 0
prowl_prio_new_login = -3
[acenter]
acenter_prio_warning = 1
acenter_prio_disk_full = 1
acenter_prio_error = 1
acenter_prio_failed = 0
acenter_prio_startup = 0
acenter_prio_queue_done = 0
acenter_enable = 1
acenter_prio_complete = 0
acenter_prio_download = 0
acenter_prio_pp = 0
acenter_prio_other = 0
acenter_prio_new_login = 0
[ntfosd]
ntfosd_prio_queue_done = 0
ntfosd_enable = 0
ntfosd_prio_error = 0
ntfosd_prio_startup = 0
ntfosd_prio_complete = 0
ntfosd_prio_pp = 0
ntfosd_prio_warning = 0
ntfosd_prio_other = 0
ntfosd_prio_disk_full = 0
ntfosd_prio_download = 0
ntfosd_prio_failed = 0
ntfosd_prio_new_login = 0
[pushbullet]
pushbullet_prio_download = 0
pushbullet_prio_queue_done = 0
pushbullet_enable = 0
pushbullet_prio_complete = 1
pushbullet_prio_warning = 0
pushbullet_device = 0
pushbullet_prio_failed = 1
pushbullet_prio_other = 0
pushbullet_apikey = ""
pushbullet_prio_disk_full = 1
pushbullet_prio_pp = 0
pushbullet_prio_startup = 0
pushbullet_prio_error = 0
pushbullet_prio_new_login = 0
[servers]
[[news]]
username=<USER>
priority = 0
enable = 1
displayname = news
name = news
ssl_type = ""
notes = ""
connections = 28
ssl = 0
host = news
timeout = 120
send_group = 0
password=<PASSWORD>
optional = 0
port = 119
categories = Default,
retention = 2000
[categories]
[[*]]
priority = 0
pp = 3
name = *
script = Default
newzbin = ""
dir = W:\SAB\Complete
[nscript]
nscript_prio_warning = 0
nscript_prio_complete = 1
nscript_prio_other = 0
nscript_prio_queue_done = 1
nscript_prio_failed = 1
nscript_prio_pp = 0
nscript_prio_new_login = 0
nscript_script = 0
nscript_prio_download = 0
nscript_prio_error = 0
nscript_prio_startup = 1
nscript_parameters = ""
nscript_enable = 0
nscript_prio_disk_full = 1

[safihre]

Can you try SABnzbd 2.0.0Beta1? Other users with similar problems this fixed it.
This happens with *every* NZB?

It seems the decoder freezes somehow..

[fred44]

Yes, was a problem on every NZB.

But 2.0beta1 is going a treat. Been going a few hours now and no problems at all.

[safihre]

What usenetprovider do you use?

[fred44]

nextgennews.net

[safihre]

Could you share some NZBs with us so we can test? There was/is a problem with nextgen and we would like to solve it

[fred44]

https://mega.nz/#!wMsR1CAD!ZBX3OPeYk2TU ... exo7wW2QCw
https://mega.nz/#!hFFxBZTC!-MYBpMGplAA5 ... lu1at8EsHY

[safihre]

Strange, these work fine for me.
Since 2.0.0 is working for you, it seems solved for now, right?

[fred44]

Yes, 2.0.0 is still going fine without any indication of a problem. Sorry if nextgen or my setup was causing problems. It's a great app.

[jhaywood]

Came here looking for answers to the same issue. Downloads run for a minute or two, before the speed drops to 0. Looked at the logs, and the only thing I could see to be an issue were these errors:

2017-03-27 00:23:44,285::INFO::[decoder:141] CRC Error in [email protected] (3039197782 -> 4201259448)

Also using nextgennews.

Upgraded to 2.0.0. beta, and still getting the errors, but download speed no longer drops to 0.

[safihre]

Could you share the NZB? So we can test with our nextgen account.

[jhaywood]

It was quite a few of them that I was getting stuck on. So many, in fact, that I never suspected the issue to be specific to nzb files. I'll go have a look when I get home, and post a few I remember it getting stuck on.