Downloads are delayed for longer than minimum age setting

aptalca · October 10, 2015, 6:01am

Hi,

I am noticing that many downloads are delayed by much longer than the minimum age / delay settings.

I am only using a minimum age setting that is set to 30 min. and no other profile delays (see screenshots below). However, I am often seeing that releases are delayed for over an hour for no reason. Below are some screenshots and a debug log.

Here you can see that the download is delayed for another 1 hour and 14 minutes.

Here you can see that Sonarr gets the correct release time and it is already above the 30min threshold.

Here’s a debug log:

15-10-10 01:33:06.3|Debug|SabnzbdProxy|Url: http://192.168.1.40:8081/api?mode=queue&start=0&limit=0&apikey=<removed>&output=json
15-10-10 01:33:06.3|Debug|SabnzbdProxy|Url: http://192.168.1.40:8081/api?mode=history&start=0&limit=30&apikey=<removed>&output=json
15-10-10 01:33:36.3|Debug|DiskScanService|Scanning '/mnt/user/.apps/sabnzbd/complete/Sonarr-manual/' for video files
15-10-10 01:33:36.3|Debug|DiskScanService|0 video files were found in /mnt/user/.apps/sabnzbd/complete/Sonarr-manual/
15-10-10 01:33:43.3|Info|NzbSearchService|Searching 3 indexers for [Shark Tank : S07E03]
15-10-10 01:33:43.3|Debug|Newznab|Downloading Feed https://newztown.co.za/api?t=tvsearch&cat=5030,5040&extended=1&apikey=<removed>&offset=0&limit=100&rid=22610&season=7&ep=3
15-10-10 01:33:43.3|Debug|Newznab|Downloading Feed https://api.oznzb.com/api?t=tvsearch&cat=5030,5040&extended=1&apikey=<removed>&offset=0&limit=100&rid=22610&season=7&ep=3
15-10-10 01:33:43.3|Debug|Newznab|Downloading Feed https://nzbs.in/api?t=tvsearch&cat=5030,5040&extended=1&apikey=<removed>&offset=0&limit=100&rid=22610&season=7&ep=3
15-10-10 01:33:43.4|Debug|X509CertificateValidationPolicy|Certificate validation for https://newztown.co.za/api?t=tvsearch&cat=5030,5040&extended=1&apikey=<removed>&offset=0&limit=100&rid=22610&season=7&ep=3 failed. RemoteCertificateChainErrors
15-10-10 01:33:43.6|Debug|X509CertificateValidationPolicy|Certificate validation for https://nzbs.in/api?t=tvsearch&cat=5030,5040&extended=1&apikey=<removed>&offset=0&limit=100&rid=22610&season=7&ep=3 failed. RemoteCertificateChainErrors
15-10-10 01:33:43.8|Debug|X509CertificateValidationPolicy|Certificate validation for https://api.oznzb.com/api?t=tvsearch&cat=5030,5040&extended=1&apikey=<removed>&offset=0&limit=100&rid=22610&season=7&ep=3 failed. RemoteCertificateChainErrors
15-10-10 01:33:44.4|Debug|NzbSearchService|Total of 2 reports were found for [Shark Tank : S07E03] from 3 indexers
15-10-10 01:33:44.4|Info|DownloadDecisionMaker|Processing 2 releases
15-10-10 01:33:44.4|Debug|Parser|Parsing string 'Shark.Tank.S07E03.720p.HDTV.X264-UAV'
15-10-10 01:33:44.4|Debug|Parser|Episode Parsed. Shark Tank - S07E03 
15-10-10 01:33:44.4|Debug|Parser|Language parsed: English
15-10-10 01:33:44.4|Debug|NzbDrone.Core.Parser.QualityParser|Trying to parse quality for Shark.Tank.S07E03.720p.HDTV.X264-UAV
15-10-10 01:33:44.4|Debug|Parser|Quality parsed: HDTV-720p v1
15-10-10 01:33:44.4|Debug|Parser|Release Group parsed: UAV
15-10-10 01:33:44.4|Debug|AcceptableSizeSpecification|Beginning size check for: Shark.Tank.S07E03.720p.HDTV.X264-UAV
15-10-10 01:33:44.4|Debug|AcceptableSizeSpecification|Item: Shark.Tank.S07E03.720p.HDTV.X264-UAV, meets size constraints.
15-10-10 01:33:44.4|Debug|LanguageSpecification|Checking if report meets language requirements. English
15-10-10 01:33:44.4|Debug|ReleaseRestrictionsSpecification|Checking if release meets restrictions: Shark.Tank.S07E03.720p.HDTV.X264-UAV
15-10-10 01:33:44.4|Debug|ReleaseRestrictionsSpecification|[Shark.Tank.S07E03.720p.HDTV.X264-UAV] No restrictions apply, allowing
15-10-10 01:33:44.4|Debug|QualityAllowedByProfileSpecification|Checking if report meets quality requirements. HDTV-720p v1
15-10-10 01:33:44.4|Debug|QualityAllowedByProfileSpecification|Quality HDTV-720p v1 rejected by Series' quality profile
15-10-10 01:33:44.4|Debug|MinimumAgeSpecification|Checking if report meets minimum age requirements. 103.524280533333
15-10-10 01:33:44.4|Debug|MinimumAgeSpecification|Release is 103.524280533333 minutes old, greater than minimum age of 30 minutes
15-10-10 01:33:44.4|Debug|RetentionSpecification|Checking if report meets retention requirements. 0
15-10-10 01:33:44.4|Debug|DelaySpecification|Ignore delay for searches
15-10-10 01:33:44.4|Debug|HistorySpecification|Skipping history check during search
15-10-10 01:33:44.4|Debug|MonitoredEpisodeSpecification|Skipping monitored check during search
15-10-10 01:33:44.4|Debug|SeriesSpecification|Checking if series matches searched series
15-10-10 01:33:44.4|Debug|DownloadDecisionMaker|Release rejected for the following reasons: [Permanent] HDTV-720p is not wanted in profile
15-10-10 01:33:44.4|Debug|Parser|Parsing string 'Shark.Tank.S07E03.HDTV.x264-UAV'
15-10-10 01:33:44.4|Debug|Parser|Episode Parsed. Shark Tank - S07E03 
15-10-10 01:33:44.4|Debug|Parser|Language parsed: English
15-10-10 01:33:44.4|Debug|NzbDrone.Core.Parser.QualityParser|Trying to parse quality for Shark.Tank.S07E03.HDTV.x264-UAV
15-10-10 01:33:44.4|Debug|Parser|Quality parsed: SDTV v1
15-10-10 01:33:44.4|Debug|Parser|Release Group parsed: UAV
15-10-10 01:33:44.4|Debug|AcceptableSizeSpecification|Beginning size check for: Shark.Tank.S07E03.HDTV.x264-UAV
15-10-10 01:33:44.4|Debug|AcceptableSizeSpecification|Item: Shark.Tank.S07E03.HDTV.x264-UAV, meets size constraints.
15-10-10 01:33:44.4|Debug|LanguageSpecification|Checking if report meets language requirements. English
15-10-10 01:33:44.4|Debug|ReleaseRestrictionsSpecification|Checking if release meets restrictions: Shark.Tank.S07E03.HDTV.x264-UAV
15-10-10 01:33:44.4|Debug|ReleaseRestrictionsSpecification|[Shark.Tank.S07E03.HDTV.x264-UAV] No restrictions apply, allowing
15-10-10 01:33:44.4|Debug|QualityAllowedByProfileSpecification|Checking if report meets quality requirements. SDTV v1
15-10-10 01:33:44.4|Debug|MinimumAgeSpecification|Checking if report meets minimum age requirements. 103.774433683333
15-10-10 01:33:44.4|Debug|MinimumAgeSpecification|Release is 103.774433683333 minutes old, greater than minimum age of 30 minutes
15-10-10 01:33:44.4|Debug|RetentionSpecification|Checking if report meets retention requirements. 0
15-10-10 01:33:44.4|Debug|DelaySpecification|Ignore delay for searches
15-10-10 01:33:44.4|Debug|HistorySpecification|Skipping history check during search
15-10-10 01:33:44.4|Debug|MonitoredEpisodeSpecification|Skipping monitored check during search
15-10-10 01:33:44.4|Debug|SeriesSpecification|Checking if series matches searched series
15-10-10 01:34:36.3|Debug|SabnzbdProxy|Url: http://192.168.1.40:8081/api?mode=queue&start=0&limit=0&apikey=<removed>&output=json
15-10-10 01:34:36.3|Debug|SabnzbdProxy|Url: http://192.168.1.40:8081/api?mode=history&start=0&limit=30&apikey=<removed>&output=json
15-10-10 01:35:06.3|Debug|DiskScanService|Scanning '/mnt/user/.apps/sabnzbd/complete/Sonarr-manual/' for video files
15-10-10 01:35:06.3|Debug|DiskScanService|0 video files were found in /mnt/user/.apps/sabnzbd/complete/Sonarr-manual/
15-10-10 01:36:06.4|Debug|SabnzbdProxy|Url: http://192.168.1.40:8081/api?mode=queue&start=0&limit=0&apikey=<removed>&output=json
15-10-10 01:36:06.4|Debug|SabnzbdProxy|Url: http://192.168.1.40:8081/api?mode=history&start=0&limit=30&apikey=<removed>&output=json
15-10-10 01:36:36.4|Debug|DiskScanService|Scanning '/mnt/user/.apps/sabnzbd/complete/Sonarr-manual/' for video files
15-10-10 01:36:36.4|Debug|DiskScanService|0 video files were found in /mnt/user/.apps/sabnzbd/complete/Sonarr-manual/
15-10-10 01:36:49.4|Info|NzbSearchService|Searching 3 indexers for [Shark Tank : S07E03]
15-10-10 01:36:49.4|Debug|Newznab|Downloading Feed https://newztown.co.za/api?t=tvsearch&cat=5030,5040&extended=1&apikey=<removed>&offset=0&limit=100&rid=22610&season=7&ep=3
15-10-10 01:36:49.4|Debug|Newznab|Downloading Feed https://nzbs.in/api?t=tvsearch&cat=5030,5040&extended=1&apikey=<removed>&offset=0&limit=100&rid=22610&season=7&ep=3
15-10-10 01:36:49.4|Debug|Newznab|Downloading Feed https://api.oznzb.com/api?t=tvsearch&cat=5030,5040&extended=1&apikey=<removed>&offset=0&limit=100&rid=22610&season=7&ep=3
15-10-10 01:36:49.5|Debug|X509CertificateValidationPolicy|Certificate validation for https://newztown.co.za/api?t=tvsearch&cat=5030,5040&extended=1&apikey=<removed>&offset=0&limit=100&rid=22610&season=7&ep=3 failed. RemoteCertificateChainErrors
15-10-10 01:36:49.7|Debug|X509CertificateValidationPolicy|Certificate validation for https://nzbs.in/api?t=tvsearch&cat=5030,5040&extended=1&apikey=<removed>&offset=0&limit=100&rid=22610&season=7&ep=3 failed. RemoteCertificateChainErrors
15-10-10 01:36:50.0|Debug|X509CertificateValidationPolicy|Certificate validation for https://api.oznzb.com/api?t=tvsearch&cat=5030,5040&extended=1&apikey=<removed>&offset=0&limit=100&rid=22610&season=7&ep=3 failed. RemoteCertificateChainErrors
15-10-10 01:36:50.7|Debug|NzbSearchService|Total of 4 reports were found for [Shark Tank : S07E03] from 3 indexers
15-10-10 01:36:50.7|Info|DownloadDecisionMaker|Processing 4 releases
15-10-10 01:36:50.7|Debug|Parser|Parsing string 'Shark.Tank.S07E03.720p.HDTV.X264-UAV'
15-10-10 01:36:50.7|Debug|Parser|Episode Parsed. Shark Tank - S07E03 
15-10-10 01:36:50.7|Debug|Parser|Language parsed: English
15-10-10 01:36:50.7|Debug|NzbDrone.Core.Parser.QualityParser|Trying to parse quality for Shark.Tank.S07E03.720p.HDTV.X264-UAV
15-10-10 01:36:50.7|Debug|Parser|Quality parsed: HDTV-720p v1
15-10-10 01:36:50.7|Debug|Parser|Release Group parsed: UAV
15-10-10 01:36:50.7|Debug|AcceptableSizeSpecification|Beginning size check for: Shark.Tank.S07E03.720p.HDTV.X264-UAV
15-10-10 01:36:50.7|Debug|AcceptableSizeSpecification|Item: Shark.Tank.S07E03.720p.HDTV.X264-UAV, meets size constraints.
15-10-10 01:36:50.7|Debug|LanguageSpecification|Checking if report meets language requirements. English
15-10-10 01:36:50.7|Debug|ReleaseRestrictionsSpecification|Checking if release meets restrictions: Shark.Tank.S07E03.720p.HDTV.X264-UAV
15-10-10 01:36:50.7|Debug|ReleaseRestrictionsSpecification|[Shark.Tank.S07E03.720p.HDTV.X264-UAV] No restrictions apply, allowing
15-10-10 01:36:50.7|Debug|QualityAllowedByProfileSpecification|Checking if report meets quality requirements. HDTV-720p v1
15-10-10 01:36:50.7|Debug|QualityAllowedByProfileSpecification|Quality HDTV-720p v1 rejected by Series' quality profile
15-10-10 01:36:50.7|Debug|MinimumAgeSpecification|Checking if report meets minimum age requirements. 106.62917265
15-10-10 01:36:50.7|Debug|MinimumAgeSpecification|Release is 106.62917265 minutes old, greater than minimum age of 30 minutes
15-10-10 01:36:50.7|Debug|RetentionSpecification|Checking if report meets retention requirements. 0
15-10-10 01:36:50.7|Debug|DelaySpecification|Ignore delay for searches
15-10-10 01:36:50.7|Debug|HistorySpecification|Skipping history check during search
15-10-10 01:36:50.7|Debug|MonitoredEpisodeSpecification|Skipping monitored check during search
15-10-10 01:36:50.7|Debug|SeriesSpecification|Checking if series matches searched series
15-10-10 01:36:50.7|Debug|DownloadDecisionMaker|Release rejected for the following reasons: [Permanent] HDTV-720p is not wanted in profile
15-10-10 01:36:50.7|Debug|Parser|Parsing string 'Shark.Tank.S07E03.HDTV.x264-UAV'
15-10-10 01:36:50.7|Debug|Parser|Episode Parsed. Shark Tank - S07E03 
15-10-10 01:36:50.7|Debug|Parser|Language parsed: English
15-10-10 01:36:50.7|Debug|NzbDrone.Core.Parser.QualityParser|Trying to parse quality for Shark.Tank.S07E03.HDTV.x264-UAV
15-10-10 01:36:50.7|Debug|Parser|Quality parsed: SDTV v1
15-10-10 01:36:50.7|Debug|Parser|Release Group parsed: UAV
15-10-10 01:36:50.7|Debug|AcceptableSizeSpecification|Beginning size check for: Shark.Tank.S07E03.HDTV.x264-UAV
15-10-10 01:36:50.7|Debug|AcceptableSizeSpecification|Item: Shark.Tank.S07E03.HDTV.x264-UAV, meets size constraints.
15-10-10 01:36:50.7|Debug|LanguageSpecification|Checking if report meets language requirements. English
15-10-10 01:36:50.7|Debug|ReleaseRestrictionsSpecification|Checking if release meets restrictions: Shark.Tank.S07E03.HDTV.x264-UAV
15-10-10 01:36:50.7|Debug|ReleaseRestrictionsSpecification|[Shark.Tank.S07E03.HDTV.x264-UAV] No restrictions apply, allowing
15-10-10 01:36:50.7|Debug|QualityAllowedByProfileSpecification|Checking if report meets quality requirements. SDTV v1
15-10-10 01:36:50.7|Debug|MinimumAgeSpecification|Checking if report meets minimum age requirements. 106.879395466667
15-10-10 01:36:50.7|Debug|MinimumAgeSpecification|Release is 106.879395466667 minutes old, greater than minimum age of 30 minutes
15-10-10 01:36:50.7|Debug|RetentionSpecification|Checking if report meets retention requirements. 0
15-10-10 01:36:50.7|Debug|DelaySpecification|Ignore delay for searches
15-10-10 01:36:50.7|Debug|HistorySpecification|Skipping history check during search
15-10-10 01:36:50.7|Debug|MonitoredEpisodeSpecification|Skipping monitored check during search
15-10-10 01:36:50.7|Debug|SeriesSpecification|Checking if series matches searched series
15-10-10 01:36:50.7|Debug|Parser|Parsing string 'Shark.Tank.S07E03.720p.HDTV.X264-UAV'
15-10-10 01:36:50.7|Debug|Parser|Episode Parsed. Shark Tank - S07E03 
15-10-10 01:36:50.7|Debug|Parser|Language parsed: English
15-10-10 01:36:50.7|Debug|NzbDrone.Core.Parser.QualityParser|Trying to parse quality for Shark.Tank.S07E03.720p.HDTV.X264-UAV
15-10-10 01:36:50.7|Debug|Parser|Quality parsed: HDTV-720p v1
15-10-10 01:36:50.7|Debug|Parser|Release Group parsed: UAV
15-10-10 01:36:50.7|Debug|AcceptableSizeSpecification|Beginning size check for: Shark.Tank.S07E03.720p.HDTV.X264-UAV
15-10-10 01:36:50.7|Debug|AcceptableSizeSpecification|Item: Shark.Tank.S07E03.720p.HDTV.X264-UAV, meets size constraints.
15-10-10 01:36:50.7|Debug|LanguageSpecification|Checking if report meets language requirements. English
15-10-10 01:36:50.7|Debug|ReleaseRestrictionsSpecification|Checking if release meets restrictions: Shark.Tank.S07E03.720p.HDTV.X264-UAV
15-10-10 01:36:50.7|Debug|ReleaseRestrictionsSpecification|[Shark.Tank.S07E03.720p.HDTV.X264-UAV] No restrictions apply, allowing
15-10-10 01:36:50.7|Debug|QualityAllowedByProfileSpecification|Checking if report meets quality requirements. HDTV-720p v1
15-10-10 01:36:50.7|Debug|QualityAllowedByProfileSpecification|Quality HDTV-720p v1 rejected by Series' quality profile
15-10-10 01:36:50.7|Debug|MinimumAgeSpecification|Checking if report meets minimum age requirements. 106.16287755
15-10-10 01:36:50.7|Debug|MinimumAgeSpecification|Release is 106.16287755 minutes old, greater than minimum age of 30 minutes
15-10-10 01:36:50.7|Debug|RetentionSpecification|Checking if report meets retention requirements. 0
15-10-10 01:36:50.7|Debug|DelaySpecification|Ignore delay for searches
15-10-10 01:36:50.7|Debug|HistorySpecification|Skipping history check during search
15-10-10 01:36:50.7|Debug|MonitoredEpisodeSpecification|Skipping monitored check during search
15-10-10 01:36:50.7|Debug|SeriesSpecification|Checking if series matches searched series
15-10-10 01:36:50.7|Debug|DownloadDecisionMaker|Release rejected for the following reasons: [Permanent] HDTV-720p is not wanted in profile
15-10-10 01:36:50.7|Debug|Parser|Parsing string 'Shark.Tank.S07E03.HDTV.x264-UAV'
15-10-10 01:36:50.7|Debug|Parser|Episode Parsed. Shark Tank - S07E03 
15-10-10 01:36:50.7|Debug|Parser|Language parsed: English
15-10-10 01:36:50.7|Debug|NzbDrone.Core.Parser.QualityParser|Trying to parse quality for Shark.Tank.S07E03.HDTV.x264-UAV
15-10-10 01:36:50.7|Debug|Parser|Quality parsed: SDTV v1
15-10-10 01:36:50.7|Debug|Parser|Release Group parsed: UAV
15-10-10 01:36:50.7|Debug|AcceptableSizeSpecification|Beginning size check for: Shark.Tank.S07E03.HDTV.x264-UAV
15-10-10 01:36:50.7|Debug|AcceptableSizeSpecification|Item: Shark.Tank.S07E03.HDTV.x264-UAV, meets size constraints.
15-10-10 01:36:50.7|Debug|LanguageSpecification|Checking if report meets language requirements. English
15-10-10 01:36:50.7|Debug|ReleaseRestrictionsSpecification|Checking if release meets restrictions: Shark.Tank.S07E03.HDTV.x264-UAV
15-10-10 01:36:50.7|Debug|ReleaseRestrictionsSpecification|[Shark.Tank.S07E03.HDTV.x264-UAV] No restrictions apply, allowing
15-10-10 01:36:50.7|Debug|QualityAllowedByProfileSpecification|Checking if report meets quality requirements. SDTV v1
15-10-10 01:36:50.7|Debug|MinimumAgeSpecification|Checking if report meets minimum age requirements. 106.696358083333
15-10-10 01:36:50.7|Debug|MinimumAgeSpecification|Release is 106.696358083333 minutes old, greater than minimum age of 30 minutes
15-10-10 01:36:50.7|Debug|RetentionSpecification|Checking if report meets retention requirements. 0
15-10-10 01:36:50.7|Debug|DelaySpecification|Ignore delay for searches
15-10-10 01:36:50.7|Debug|HistorySpecification|Skipping history check during search
15-10-10 01:36:50.7|Debug|MonitoredEpisodeSpecification|Skipping monitored check during search
15-10-10 01:36:50.7|Debug|SeriesSpecification|Checking if series matches searched series

In the log, you can see that the 720p releases are properly rejected for being the wrong quality. But the sdtv ones (the ones that are supposed to be downloaded) never get a rejection. No messages related to delay in the log either.

I would appreciate any help.

Thanks

markus101 · October 10, 2015, 8:56am

Was the release still in pending queue when you ran that manual search?
Is the time on the server running Sonarr correct (and the correct timezone)? Since Sonarr relies on the clock being set properly it being off by and hour or having the wrong daylight savings time will cause delays.

Please use hastebin for the logs, much more readable, also need debug logs of an RSS Sync with the delayed item on the feed.

aptalca · October 10, 2015, 7:49pm

Thanks for the reply

The release was still pending in queue when I ran the manual search. It had been pending for at least 30 minutes (can’t remember the exact time)

The server and sonarr clocks are correct, the log timestamps match the current local time.

I have a few episodes set to download tomorrow night. I will watch the logs closely then and will post longer logs (including RSS sync) on hastebin

Thanks

markus101 · October 11, 2015, 12:41am

If you leave the download in the queue, at what time does it automatically get grabbed?

The time could be correct, but the UTC offset off by an hour if Daylight Savings has not been configured correctly, being roughly an hour apart is a pretty good indication of this.

aptalca · October 14, 2015, 3:16pm

I have been watching the logs closely for the last few days and noticed a few things and one may be a bug/feature request.

First of all, I confirmed that the clock is in fact correct. The downloads in the queue are grabbed at the time the interface shows.

Now, this is something I just realized and may have misunderstood originally so please correct me if I’m wrong: If a release is newer than the minimum age setting, it is delayed until the next RSS sync (is that correct?). Because my original assumption was that the releases were delayed until they met the minimum age criteria. For instance, if a release is 25 mins old and my minimum age is set to 30 minutes, I assumed it would be delayed for 5 mins until the release was 30 mins old. It seems that it is delayed until the next RSS sync and is grabbed then if it meets the minimum age criteria.

In that case, with a minimum age setting of 30 mins and RSS syncs every 30 mins, I should expect the releases to be grabbed when they are between 30-59 mins old. That seems to be the case almost every time in my experience.

However, the picture above (in the first post) shows a delay that is longer than both the minimum age setting and the RSS sync frequency. I realized that the release in question was listed on womble’s who doesn’t use correct timestamps. So that release seemed to be -3 hours old, so Sonarr thought that it’s age would reach 30 min several hours later. I can see why that was delayed for so long.

I since disabled womble’s for that reason and everything now seems to be grabbed when they are 30-59 mins old.

However, one bug that Sonarr may have is that, while the womble’s release was being delayed, there were other releases from other sources (nzbs’in and newztown as seen in the first post) that met the criteria and were older than 30 mins at the time of RSS sync. The issue was that Sonarr still kept the womble’s release in the queue until it thought that one was 30 mins in age. Whereas Sonarr really should have dropped the womble’s version that was temporarily rejected due to age, and grabbed one of the other releases that met all the criteria.

Thanks

markus101 · October 14, 2015, 4:18pm

Yes, this is correct, it only checks on RSS Sync cycles.

The delay is based off of the oldest release, so yes it should have grabbed another release and dropped the release from womble’s since its “newer” than the other releases. We’ll need to take a look at this.

system · December 13, 2015, 4:25pm

This topic was automatically closed 60 days after the last reply. New replies are no longer allowed.