I’m having the same issue on some, but not all episodes, of some but not all shows. Not .rar related though in my case. In my logs the only thing I see is:
16-9-7 21:53:38.1|Info|RefreshSeriesService|Updating Info for Mr. Robot
16-9-7 21:53:38.3|Info|MetadataService|Series folder does not exist, skipping metadata creation
16-9-7 21:53:38.9|Info|RefreshEpisodeService|Starting episode info refresh for: [289590][Mr. Robot]
16-9-7 21:53:38.9|Info|RefreshEpisodeService|Finished episode refresh for series: [289590][Mr. Robot].
16-9-7 21:53:38.9|Info|DiskScanService|Scanning disk for Mr. Robot
16-9-7 21:53:38.9|Info|DiskScanService|Completed scanning disk for Mr. Robot
The Series folder most definitely does exist, and like I mentioned other shows are able to be imported just fine, most of the time but not always. It’s hit or miss, the above episode just happens to be the one giving me grief this time.
Well the original problem is the same, the other poster discovered later that it was rar related, but the behavior they were seeing was the same as what I was seeing so I considered that the same issue. Didn’t want to create a whole new thread and add clutter to the forum for the same thing but if you prefer a whole new thread… so be it.
A new thread doesn’t add clutter, it helps keep things organized and focused on a single topic. We’ll need the information requested when opening a new thread:
Sonarr version (exact version): Mono version (if Sonarr is not running on Windows): OS: ((Debug logs)): (Make sure debug logging is enabled in settings and post the full log to hastebin/pastebin/dropbox/google drive or something similar, do not post them directly here) Description of issue:
The debug logs will need to cover the failed importing.
Description of issue:
Episodes just stick at 100% downloaded and fail to import sometimes, but other times finish and import just fine. No pattern that I’ve been able to see. The relevant (I believe) portion of the logs is this:
16-9-7 09:51:19.4|Info|RefreshSeriesService|Updating Info for Mr. Robot
16-9-7 09:51:19.5|Info|MetadataService|Series folder does not exist, skipping metadata creation
16-9-7 09:51:19.8|Info|RefreshEpisodeService|Starting episode info refresh for: [289590][Mr. Robot]
16-9-7 09:51:19.8|Info|RefreshEpisodeService|Finished episode refresh for series: [289590][Mr. Robot].
16-9-7 09:51:19.8|Info|DiskScanService|Scanning disk for Mr. Robot
16-9-7 09:51:19.9|Info|DiskScanService|Completed scanning disk for Mr. Robot
It claims the Series folder does not exist, but it definitely does. Permissions are set to red/write for everyone, and as I mentioned it does work sometimes. It doesn’t always fail or succeed for the same shows.
The logs you highlighed are about the sorted files, not the ones still in the download client.
Your log is full of:
16-9-7 09:52:09.7|Warn|UpdateEpisodeFileService|Unable to set date of file [/Volumes/Streaming/Shannon Media/TV/Twilight Zone/60s/Season 5/S05E26 Color Me Black.avi]
[v2.0.0.4230] System.IO.IOException: Invalid parameter
at System.IO.File.SetLastWriteTime (System.String path, DateTime lastWriteTime) [0x00029] in /private/tmp/source-mono-mac-4.0.0-bug-33585-hotfix/bockbuild-mono-4.0.0-branch/profiles/mono-mac-xamarin/build-root/mono-4.0.4/mcs/class/corlib/System.IO/File.cs:474
at NzbDrone.Common.Disk.DiskProviderBase.FileSetLastWriteTime (System.String path, DateTime dateTime) [0x00048] in M:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Common\Disk\DiskProviderBase.cs:250
at NzbDrone.Core.MediaFiles.UpdateEpisodeFileService.ChangeFileDateToLocalAirDate (System.String filePath, System.String fileDate, System.String fileTime) [0x0002b] in M:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\MediaFiles\UpdateEpisodeFileService.cs:130
16-9-7 09:52:09.7|Warn|UpdateEpisodeFileService|Unable to set date of file [/Volumes/Streaming/Shannon Media/TV/Twilight Zone/60s/Season 5/S05E27 Sounds and Silences.avi]
You should disable that option because it doesn’t work and post fresh logs. Your logs also aren’t debug logs, which we need for additional details.
Those logs look like they are for a search of Mr. Robot Season 2. I don’t see anything waiting to be imported.
Is the download at 100% still showing in Sonarr’s queue (Activity: Queue)?
If so, hover over the icon on the left and check the message to see why it wasn’t imported. (the logs should also show something after a few minutes in most cases, but the search seems to have buried anything)
If not, we’ll see to see something there so Sonarr will log the import process.
It just says to check the download client for more details, but there’s nothing in UTorrent to indicate any problem. The torrent has already been decompressed from the rars it was delivered in, and the .mkv file is just sitting waiting to be imported.
There are plenty of other episodes of other shows also in the queue saying the same thing, and they also had already been decompressed but were never picked up. They have since been watched and deleted by me, but the Mr. Robot episode I have left sitting there since I was making this forum post.
All I see is “invalid download state, try resuming”. But of course it’s an invalid download state, the download is already long since completed, decompressed, and deleted. Sonarr is just not realizing that the finished .mkv file is sitting there waiting to be picked up.
Aha, got it! The problem was that I’ve had The Unarchiver moving the archived files to trash after decompressing them, which is confusing Sonarr and it’s not realizing what happened when Utorrent reports the archive is missing. I’ve turned off that feature and now Sonarr is picking up the episode just fine, and then deleting the downloaded archive.
This explains why it was inconsistent. Shows that weren’t compressed, wouldn’t exhibit the issue.
