Waiting to import

Mean_Gene · February 27, 2023, 3:45pm

Sonarr version (exact version): 3.0.9.1549
Mono version: 6.8.0.105
OS: Debian 11.4

This has been happening for a while and decided to finally make a post about it.

Whenever my friends request a TV show, I have things set up on my side so their requests are grabbed in whatever format is available but HEVC/265 is preferred. If a show only has a 264 version, Sonarr grabs it and when the 265 version comes along a few hours later, Sonarr downloads -that- version and it replaces the 264 copy and all is well.

BUT, every now and then, something gets out of alignment and it’s the same problem. Example, Last Week Tonight with John Oliver…

Sonarr downloaded S10E02 (the 264 version) last night and it got added to the right folder and shows up in Plex.

A little bit later, the x265 version popped up on Usenet and Sonarr downloaded it, and successfully imported it into the proper folder and it’s in Plex.

However, in the download folder, I still have the 264 version and in Sonarr>Activity, I have this error:

Downloaded - Waiting to Import

One or more episodes expected in this release were not imported or missing

last.week.tonight.with.john.oliver.s10e02.720p.web.h264-glhf.mkv

Not a preferred word upgrade for existing episode file(s)

If I simply go into the Queue and delete the stalled download and refresh the series, the error disappears and life goes on. In an average week, I’d guess that 20 episodes are upgraded from the 264 version to their 265 equivalent and I run into this problem with 1 or 2 episodes and I need to go into Sonarr’s queue and nuke the stalled download.

I don’t know why the majority of these downloads/264 to 265 conversions go off without a hitch and only a fraction of them need intervention.

I did some searching on the forum and found this

…same bug?

markus101 · March 2, 2023, 9:17pm

Before the x265 release is grabbed is the x264 release stuck in the queue with the same error as the thread you linked? There should be something in the logs as well.

What does the history look like for that episode?

Mean_Gene · March 28, 2023, 2:27pm

It happened again last night with American Dad.

2023-03-27 22:44:34.3|Info|Sabnzbd|Adding report [American.Dad.S19E01.720p.HDTV.x264-SYNCOPY] to the queue.
2023-03-27 22:44:34.3|Info|DownloadService|Report sent to SABnzbd. American.Dad.S19E01.720p.HDTV.x264-SYNCOPY
2023-03-27 22:44:34.3|Info|RssSyncService|RSS Sync Completed. Reports found: 200, Reports grabbed: 1
2023-03-27 23:00:01.5|Info|RssSyncService|Starting RSS Sync
2023-03-27 23:00:02.2|Warn|HttpClient|HTTP Error - Res: [GET] https://drunkenslug.com/api?t=tvsearch&cat=5030,5040&extended=1&apikey=(removed)&offset=0&limit=100: 500.InternalServerError (0 bytes)

2023-03-27 23:00:02.2|Warn|Newznab|DrunkenSlug HTTP request failed: [500:InternalServerError] [GET] at [https://drunkenslug.com/api?t=tvsearch&cat=5030,5040&extended=1&apikey=(removed)&offset=0&limit=100]
2023-03-27 23:00:02.2|Info|DownloadDecisionMaker|Processing 100 releases
2023-03-27 23:00:04.1|Info|Sabnzbd|Adding report [American.Dad.S19E01.720p.HEVC.x265-MeGusta] to the queue.
2023-03-27 23:00:04.1|Info|DownloadService|Report sent to SABnzbd. American.Dad.S19E01.720p.HEVC.x265-MeGusta

And looking at the SABNzbd logs, the 264 version was downloaded at 10:44pm (but it’s still sitting in the unpack folder just waiting to be imported … and at 11pm, the 265 version was downloaded and imported into Plex.

SAB grabbing the 264 version:

2023-03-27 22:44:43,942::INFO::[newsunpack:297] Unrar finished on /mnt/m2ssd/intermediate/American.Dad.S19E01.720p.HDTV.x264-SYNCOPY
2023-03-27 22:44:43,942::INFO::[postproc:434] Unpacked files ['/mnt/m2ssd/downloads/_UNPACK_American.Dad.S19E01.720p.HDTV.x264-SYNCOPY/79838c9244384c82aba29d46e7c5c30a.mkv']
2023-03-27 22:44:43,942::INFO::[filesystem:319] Checking if any resulting filenames need to be sanitized
2023-03-27 22:44:43,942::INFO::[postproc:438] Finished unpack_magic on American.Dad.S19E01.720p.HDTV.x264-SYNCOPY
2023-03-27 22:44:43,942::INFO::[nzbstuff:1797] [N/A] Purging data for job American.Dad.S19E01.720p.HDTV.x264-SYNCOPY (delete_all_data=True)
2023-03-27 22:44:43,943::INFO::[notifier:123] Sending notification: Download Completed - American.Dad.S19E01.720p.HDTV.x264-SYNCOPY (type=complete, job_cat=tv)
2023-03-27 22:44:43,943::INFO::[notifier:183] Send to NotifyOSD: Download Completed / American.Dad.S19E01.720p.HDTV.x264-SYNCOPY
2023-03-27 22:44:43,994::INFO::[database:292] Added job American.Dad.S19E01.720p.HDTV.x264-SYNCOPY to history
2023-03-27 22:44:43,994::INFO::[postproc:129] Saving postproc queue
2023-03-27 22:44:43,995::INFO::[downloader:410] Post-processing finished, resuming download
2023-03-27 22:44:47,995::INFO::[nzbqueue:234] Saving queue
2023-03-27 22:44:47,998::INFO::[postproc:129] Saving postproc queue
2023-03-27 22:44:48,002::INFO::[notifier:123] Sending notification: SABnzbd - Queue finished (type=queue_done, job_cat=None)
2023-03-27 22:44:48,002::INFO::[notifier:183] Send to NotifyOSD: SABnzbd / Queue finished

… so after the download/unpack was all finished, it stayed in queue and Sonarr didn’t move it.

But the 265 version at 11pm -WAS- pulled in by Sonarr.

2023-03-27 23:00:14,193::INFO::[newsunpack:297] Unrar finished on /mnt/m2ssd/intermediate/American.Dad.S19E01.720p.HEVC.x265-MeGusta
2023-03-27 23:00:14,193::INFO::[postproc:434] Unpacked files ['/mnt/m2ssd/downloads/_UNPACK_American.Dad.S19E01.720p.HEVC.x265-MeGusta/a39a4ab69fb54d8ba9df0862928c1f30.mkv']
2023-03-27 23:00:14,193::INFO::[filesystem:319] Checking if any resulting filenames need to be sanitized
2023-03-27 23:00:14,193::INFO::[postproc:438] Finished unpack_magic on American.Dad.S19E01.720p.HEVC.x265-MeGusta
2023-03-27 23:00:14,202::INFO::[nzbstuff:1797] [N/A] Purging data for job American.Dad.S19E01.720p.HEVC.x265-MeGusta (delete_all_data=True)
2023-03-27 23:00:14,203::INFO::[notifier:123] Sending notification: Download Completed - American.Dad.S19E01.720p.HEVC.x265-MeGusta (type=complete, job_cat=tv)
2023-03-27 23:00:14,203::INFO::[notifier:183] Send to NotifyOSD: Download Completed / American.Dad.S19E01.720p.HEVC.x265-MeGusta
2023-03-27 23:00:14,233::INFO::[database:292] Added job American.Dad.S19E01.720p.HEVC.x265-MeGusta to history
2023-03-27 23:00:14,234::INFO::[postproc:129] Saving postproc queue
2023-03-27 23:00:14,234::INFO::[downloader:410] Post-processing finished, resuming download
2023-03-27 23:00:18,234::INFO::[nzbqueue:234] Saving queue
2023-03-27 23:00:18,237::INFO::[postproc:129] Saving postproc queue
2023-03-27 23:00:18,240::INFO::[notifier:123] Sending notification: SABnzbd - Queue finished (type=queue_done, job_cat=None)
2023-03-27 23:00:18,240::INFO::[notifier:183] Send to NotifyOSD: SABnzbd / Queue finished

TLDR: if the 264 version was downloaded at 10:44pm, why wasn’t it moved into the Plex folder? SAB downloaded the 265 version 15 minutes later and that version WAS moved into the Plex folder.

markus101 · March 28, 2023, 2:31pm

The missing XEM mapping likely had something to do with it, but only debug/trace logs would be able to tell you for sure.

Mean_Gene · March 29, 2023, 1:18pm

Yes, I’ll admit that the mappings for American Dad seem to be permanently screwed up and could have caused this problem so this might be a bad example. Still weird that even though the 265 version has the same ‘S19E01’ name, it was pulled in successfully.

I’ll keep an eye on things and wait for a better/cleaner (non-XEM screwed up ) series and report back.

Thanks!

Mean_Gene · March 31, 2023, 1:50pm

Happened again. Series is ‘Love is Blind’. 3 episodes were released last night around 3am. Sonarr had SAB grab episode 6 first (264 version) at 3:44am and 15 minutes later, it grabbed the 265 version at 4am. For whatever reason, the 264 version (despite being downloaded first) was never imported.

Currently in my download directory, all 4 files (264 and 265 versions) are just hanging out and were never moved into the Plex TV folder.

For episode 7, looks like the 264 version was downloaded at 3:30am and the 265 version at 4:46am and neither were moved.

For episode 8… Sonarr had SAB download the 265 version at 4am but it was never imported. This is the odd one because Sonarr/SAB never downloaded a 264 version and only had to deal with downloading/importing the single 265 version but maybe because episodes 6 and 7 were gummed up, this prevented episode 8 from being imported?

Shortened up the log file:

2023-03-31 03:29:10.7|Info|Sabnzbd|Adding report [Love.Is.Blind.S04E07.720p.WEB.h264-EDITH] to the queue.
2023-03-31 03:29:10.7|Info|DownloadService|Report sent to SABnzbd. Love.Is.Blind.S04E07.720p.WEB.h264-EDITH

2023-03-31 03:44:40.8|Info|Sabnzbd|Adding report [Love.Is.Blind.S04E06.720p.WEB.h264-EDITH] to the queue.
2023-03-31 03:44:41.0|Info|DownloadService|Report sent to SABnzbd. Love.Is.Blind.S04E06.720p.WEB.h264-EDITH
2023-03-31 03:44:41.0|Info|RssSyncService|RSS Sync Completed. Reports found: 200, Reports grabbed: 1
2023-03-31 04:00:08.3|Info|RssSyncService|Starting RSS Sync
2023-03-31 04:00:08.9|Info|DownloadDecisionMaker|Processing 200 releases
2023-03-31 04:00:11.0|Info|Sabnzbd|Adding report [Love.Is.Blind.S04E06.720p.HEVC.x265-MeGusta] to the queue.
2023-03-31 04:00:11.0|Info|DownloadService|Report sent to SABnzbd. Love.Is.Blind.S04E06.720p.HEVC.x265-MeGusta
2023-03-31 04:00:13.1|Info|Sabnzbd|Adding report [Love.Is.Blind.S04E08.720p.HEVC.x265-MeGusta] to the queue.
2023-03-31 04:00:13.1|Info|DownloadService|Report sent to SABnzbd. Love.Is.Blind.S04E08.720p.HEVC.x265-MeGusta
2023-03-31 04:00:13.1|Info|RssSyncService|RSS Sync Completed. Reports found: 200, Reports grabbed: 2
2023-03-31 04:08:08.5|Info|SceneMappingService|Updating Scene mappings
2023-03-31 04:15:38.5|Info|RssSyncService|Starting RSS Sync
2023-03-31 04:15:39.1|Info|DownloadDecisionMaker|Processing 200 releases
2023-03-31 04:15:40.0|Info|RssSyncService|RSS Sync Completed. Reports found: 200, Reports grabbed: 0
2023-03-31 04:31:08.8|Info|RssSyncService|Starting RSS Sync
2023-03-31 04:31:09.6|Info|DownloadDecisionMaker|Processing 200 releases
2023-03-31 04:31:10.3|Info|RssSyncService|RSS Sync Completed. Reports found: 200, Reports grabbed: 0
2023-03-31 04:46:39.0|Info|RssSyncService|Starting RSS Sync
2023-03-31 04:46:40.1|Info|DownloadDecisionMaker|Processing 200 releases
2023-03-31 04:46:41.6|Info|Sabnzbd|Adding report [Love.Is.Blind.S04E07.720p.HEVC.x265-MeGusta] to the queue.
2023-03-31 04:46:41.7|Info|DownloadService|Report sent to SABnzbd. Love.Is.Blind.S04E07.720p.HEVC.x265-MeGusta
2023-03-31 04:46:41.7|Info|RssSyncService|RSS Sync Completed. Reports found: 200, Reports grabbed: 1

And here is the SAB log

Maybe a clue but something I noticed… all these episodes (along with the ‘Downloaded - Waiting to import’ tag… ) also have a ‘Episode has a TBA title and recently aired’ notice on them.

markus101 · March 31, 2023, 10:23pm

SAB logs aren’t useful to see why Sonarr isn’t importing.

That would do it, you can disable that, but the default is waiting 24 hours for an episode without a title to become available if the title is part of the renaming pattern.

Mean_Gene · April 24, 2023, 1:31am

Thanks for pointing me in the right direction. Been a few weeks and I haven’t seen the problem since making that change!

system · June 23, 2023, 1:32am

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