Downloading many copies of same episode. CDH on

Birdperson · October 25, 2015, 7:45am

Sonarr is downloading multiple copies of the same episode. Each time it sees the same episode in the RSS feed it accepts it despite it already having been grabbed previously. No changes have been made to the download config recently and it was working correctly previously. Any help appreciated. Thanks.

Sonar version: 2.0.0.3530
Download client: sabnzbd. Imported using API with key
CDH is enabled but it hasn’t been successfully importing files. It just gets left in the completed download folder where sabnzbd puts it.

Here is a excerpt of the trace logging when a rss sync is run:

15-10-25 17:54:38.7|Trace|DownloadDecisionMaker|Processing release 67/100
15-10-25 17:54:38.7|Trace|EventAggregator|Publishing CommandUpdatedEvent
15-10-25 17:54:38.7|Trace|EventAggregator|CommandUpdatedEvent -> CommandModule
15-10-25 17:54:38.7|Trace|EventAggregator|CommandUpdatedEvent <- CommandModule
15-10-25 17:54:38.7|Debug|Parser|Parsing string 'Please.Like.Me.S03E02.PDTV.x264-FQM'
15-10-25 17:54:38.7|Trace|Parser|^(?<title>.+?)(?:(?:[-_\W](?<![()\[!]))+S?(?<season>(?<!\d+)(?:\d{1,2}|\d{4})(?!\d+))(?:[ex]|\W[ex]|_){1,2}(?<episode>\d{2,3}(?!\d+))(?:(?:\-|[ex]|\W[ex]|_){1,2}(?<episode>\d{2,3}(?!\d+)))*)\W?(?!\\)
15-10-25 17:54:38.7|Debug|Parser|Episode Parsed. Please Like Me - S03E02 
15-10-25 17:54:38.7|Debug|Parser|Language parsed: English
15-10-25 17:54:38.7|Debug|NzbDrone.Core.Parser.QualityParser|Trying to parse quality for Please.Like.Me.S03E02.PDTV.x264-FQM
15-10-25 17:54:38.7|Debug|Parser|Quality parsed: SDTV v1
15-10-25 17:54:38.7|Debug|Parser|Release Group parsed: FQM
15-10-25 17:54:38.7|Debug|AcceptableSizeSpecification|Beginning size check for: Please.Like.Me.S03E02.PDTV.x264-FQM
15-10-25 17:54:38.8|Debug|AcceptableSizeSpecification|Item: Please.Like.Me.S03E02.PDTV.x264-FQM, meets size constraints.
15-10-25 17:54:38.8|Debug|LanguageSpecification|Checking if report meets language requirements. English
15-10-25 17:54:38.8|Debug|ReleaseRestrictionsSpecification|Checking if release meets restrictions: Please.Like.Me.S03E02.PDTV.x264-FQM
15-10-25 17:54:38.8|Debug|ReleaseRestrictionsSpecification|[Please.Like.Me.S03E02.PDTV.x264-FQM] No restrictions apply, allowing
15-10-25 17:54:38.8|Debug|QualityAllowedByProfileSpecification|Checking if report meets quality requirements. SDTV v1
15-10-25 17:54:38.8|Trace|ConfigService|Using default config value for 'minimumage' defaultValue:'0'
15-10-25 17:54:38.8|Debug|MinimumAgeSpecification|Minimum age is not set.
15-10-25 17:54:38.8|Trace|ConfigService|Using default config value for 'retention' defaultValue:'0'
15-10-25 17:54:38.8|Debug|RetentionSpecification|Checking if report meets retention requirements. 0
15-10-25 17:54:38.8|Debug|DelaySpecification|Profile does not require a waiting period before download for Usenet.
15-10-25 17:54:38.8|Debug|HistorySpecification|Performing history status check on report
15-10-25 17:54:38.8|Debug|HistorySpecification|Checking current status of episode [4057] in history
15-10-25 17:54:38.9|Debug|QualityUpgradableSpecification|existing item has better or equal quality. skipping
15-10-25 17:54:38.9|Debug|DownloadDecisionMaker|Release accepted

Also, something unusual I have noticed. When I manually search for a release it grabs it correctly but it still reports it in the calendar as “missing” despite it being added to the history correctly in both sonarr and being sent to sabnzbd. The log is below:

15-10-25 18:06:23.0|Debug|Parser|Episode Parsed. Please Like Me - S03E02 
15-10-25 18:06:23.0|Debug|Parser|Language parsed: English
15-10-25 18:06:23.0|Debug|NzbDrone.Core.Parser.QualityParser|Trying to parse quality for Please.Like.Me.S03E02.PDTV.x264-FQM
15-10-25 18:06:23.0|Debug|Parser|Quality parsed: SDTV v1
15-10-25 18:06:23.0|Debug|Parser|Release Group parsed: FQM
15-10-25 18:06:23.0|Debug|AcceptableSizeSpecification|Beginning size check for: Please.Like.Me.S03E02.PDTV.x264-FQM
15-10-25 18:06:23.0|Debug|AcceptableSizeSpecification|Item: Please.Like.Me.S03E02.PDTV.x264-FQM, meets size constraints.
15-10-25 18:06:23.0|Debug|LanguageSpecification|Checking if report meets language requirements. English
15-10-25 18:06:23.0|Debug|ReleaseRestrictionsSpecification|Checking if release meets restrictions: Please.Like.Me.S03E02.PDTV.x264-FQM
15-10-25 18:06:23.1|Debug|ReleaseRestrictionsSpecification|[Please.Like.Me.S03E02.PDTV.x264-FQM] No restrictions apply, allowing
15-10-25 18:06:23.1|Debug|QualityAllowedByProfileSpecification|Checking if report meets quality requirements. SDTV v1
15-10-25 18:06:23.1|Trace|ConfigService|Using default config value for 'minimumage' defaultValue:'0'
15-10-25 18:06:23.1|Debug|MinimumAgeSpecification|Minimum age is not set.
15-10-25 18:06:23.1|Trace|ConfigService|Using default config value for 'retention' defaultValue:'0'
15-10-25 18:06:23.1|Debug|RetentionSpecification|Checking if report meets retention requirements. 0
15-10-25 18:06:23.1|Debug|DelaySpecification|Ignore delay for searches
15-10-25 18:06:23.1|Debug|HistorySpecification|Skipping history check during search
15-10-25 18:06:23.1|Debug|MonitoredEpisodeSpecification|Skipping monitored check during search
15-10-25 18:06:23.1|Debug|SeriesSpecification|Checking if series matches searched series
15-10-25 18:06:23.1|Debug|DownloadDecisionMaker|Release accepted
15-10-25 18:06:23.1|Trace|HttpClient|Req: [GET] https://dognzb.cr/fetch/1f585bffe49905264fdab237321c8dc5/dd8c1ba111e13f698bcb8f0aa4c799b9
15-10-25 18:06:24.1|Debug|X509CertificateValidationPolicy|Certificate validation for https://dognzb.cr/fetch/1f585bffe49905264fdab237321c8dc5/dd8c1ba111e13f698bcb8f0aa4c799b9 failed. RemoteCertificateChainErrors
15-10-25 18:06:24.8|Trace|HttpClient|Res: [GET] https://dognzb.cr/fetch/1f585bffe49905264fdab237321c8dc5/dd8c1ba111e13f698bcb8f0aa4c799b9 : 200.OK (1,738 ms)
15-10-25 18:06:24.8|Debug|Sabnzbd|Downloaded nzb for episode 'Please.Like.Me.S03E02.PDTV.x264-FQM' finished (27795 bytes from https://dognzb.cr/fetch/1f585bffe49905264fdab237321c8dc5/dd8c1ba111e13f698bcb8f0aa4c799b9)
15-10-25 18:06:24.8|Info|Sabnzbd|Adding report [Please.Like.Me.S03E02.PDTV.x264-FQM] to the queue.
15-10-25 18:06:24.8|Debug|SabnzbdProxy|Url: http://192.168.1.5:8080/api?mode=addfile&cat=sonarr&priority=1&apikey=<removed>&output=json
15-10-25 18:06:25.0|Trace|Scheduler|Pending Tasks: 1
15-10-25 18:06:25.0|Trace|CommandQueueManager|Publishing CheckForFinishedDownload
15-10-25 18:06:25.0|Trace|CommandQueueManager|Checking if command is queued or started: CheckForFinishedDownload
15-10-25 18:06:25.0|Trace|CommandQueueManager|Command is already in progress: CheckForFinishedDownload
15-10-25 18:06:25.2|Trace|ConfigService|Using default config value for 'downloadedepisodesfolder' defaultValue:''
15-10-25 18:06:25.2|Trace|ConfigService|Using default config value for 'downloadedepisodesfolder' defaultValue:''
15-10-25 18:06:25.4|Trace|SabnzbdProxy|Response: {"status":true,"nzo_ids":["SABnzbd_nzo_Tp2iu0"]}
15-10-25 18:06:25.4|Info|DownloadService|Report sent to SABnzbd. Please.Like.Me.S03E02.PDTV.x264-FQM

Taloth · October 25, 2015, 8:45am

Can you go to Settings->Download Clients->Sabnzbd and run the Test?

Birdperson · October 25, 2015, 9:17am

A test produces the popup “Testing ‘SABNzbd’ succeeded”.

The trace log is here: http://goo.gl/8ajGj3

Taloth · October 25, 2015, 12:09pm

Can you get a log of the task Check for Finished Download? (runs every minute)
At Trace level that will show the communication between Sonarr.

Birdperson · October 25, 2015, 12:32pm

The trace log with the section about the task is below. From what the log said it looked like the task was still running and had hung in the past or something. I have since restarted the jail that sonarr was running in and it seems to have fixed the problem. Thanks for your help.

15-10-25 22:46:55.9|Trace|Scheduler|Pending Tasks: 1
15-10-25 22:46:55.9|Trace|CommandQueueManager|Publishing CheckForFinishedDownload
15-10-25 22:46:55.9|Trace|CommandQueueManager|Checking if command is queued or started: CheckForFinishedDownload
15-10-25 22:46:55.9|Trace|CommandQueueManager|Command is already in progress: CheckForFinishedDownload
15-10-25 22:47:25.9|Trace|Scheduler|Pending Tasks: 2
15-10-25 22:47:25.9|Trace|CommandQueueManager|Publishing CheckForFinishedDownload
15-10-25 22:47:25.9|Trace|CommandQueueManager|Checking if command is queued or started: CheckForFinishedDownload
15-10-25 22:47:25.9|Trace|CommandQueueManager|Command is already in progress: CheckForFinishedDownload
15-10-25 22:47:25.9|Trace|CommandQueueManager|Publishing DownloadedEpisodesScan
15-10-25 22:47:25.9|Trace|CommandQueueManager|Checking if command is queued or started: DownloadedEpisodesScan
15-10-25 22:47:25.9|Trace|CommandQueueManager|Inserting new command: DownloadedEpisodesScan
15-10-25 22:47:25.9|Trace|CommandExecutor|DownloadedEpisodesScanCommand -> DownloadedEpisodesCommandService
15-10-25 22:47:25.9|Trace|CommandQueueManager|Marking command as started: DownloadedEpisodesScan
15-10-25 22:47:26.0|Trace|ConfigService|Using default config value for 'downloadedepisodesfolder' defaultValue:''
15-10-25 22:47:26.0|Trace|DownloadedEpisodesCommandService|Drone Factory folder is not configured
15-10-25 22:47:26.0|Trace|CommandQueueManager|Updating command status
15-10-25 22:47:26.0|Trace|EventAggregator|Publishing CommandExecutedEvent
15-10-25 22:47:26.0|Trace|EventAggregator|CommandExecutedEvent -> TaskManager
15-10-25 22:47:26.0|Trace|TaskManager|Updating last run time for: NzbDrone.Core.MediaFiles.Commands.DownloadedEpisodesScanCommand
15-10-25 22:47:26.1|Trace|EventAggregator|CommandExecutedEvent <- TaskManager
15-10-25 22:47:26.1|Trace|EventAggregator|CommandExecutedEvent -> TaskModule
15-10-25 22:47:26.1|Trace|EventAggregator|CommandExecutedEvent <- TaskModule
15-10-25 22:47:26.1|Trace|CommandExecutor|DownloadedEpisodesScanCommand <- DownloadedEpisodesCommandService [00:00:00.0385150]

Taloth · October 25, 2015, 8:59pm

Good to hear. If it happens again please let us know.

system · December 24, 2015, 9:13pm

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