Sonarr & Radarr Rename File Issue

[johnwchambers]

It appears that Sonarr & Radarr are having issues with the final portion of the postaar scripts where it tells each one to rename the file just imported. Upon looking at the logs, it seems it's passing the filename of file to be renamed, but it's missing the "rnm" portion of the name, hence erroring out that it doesn't exist I presume. When I look at the filesystem, the file is there, but includes .rnm.mp4 in it instead.

I'm not sure how long this has been occurring, I just noticed today after I was sifting through some recently added series my users requested and was expecting to not see any files with "rnm" in their names still.

Sonarr log:

2023-01-21 14:13:15.3|Info|DiskScanService|Scanning The Jeffersons
2023-01-21 14:13:15.3|Info|RenameEpisodeFileService|Renaming 1 files for The Jeffersons
2023-01-21 14:13:15.3|Error|RenameEpisodeFileService|Failed to rename file /mnt/nas01/mm-tv01/THE JEFFERSONS (1975) {TVDB-77792}/SEASON 01/THE JEFFERSONS - S01E12 - [WEBDL-480P][AAC 2.0][H264]-SQUALOR.mp4

[v3.0.9.1549] System.IO.FileNotFoundException: Episode file path does not exist
File name: '/mnt/nas01/mm-tv01/THE JEFFERSONS (1975) {TVDB-77792}/SEASON 01/THE JEFFERSONS - S01E12 - [WEBDL-480P][AAC 2.0][H264]-SQUALOR.mp4'
  at NzbDrone.Core.MediaFiles.EpisodeFileMovingService.TransferFile (NzbDrone.Core.MediaFiles.EpisodeFile episodeFile, NzbDrone.Core.Tv.Series series, System.Collections.Generic.List`1[T] episodes, System.String destinationFilePath, NzbDrone.Common.Disk.TransferMode mode) [0x00103] in <0b43b6e47ef8497b83f58230dd1ec559>:0
  at NzbDrone.Core.MediaFiles.EpisodeFileMovingService.MoveEpisodeFile (NzbDrone.Core.MediaFiles.EpisodeFile episodeFile, NzbDrone.Core.Tv.Series series) [0x00073] in <0b43b6e47ef8497b83f58230dd1ec559>:0
  at NzbDrone.Core.MediaFiles.RenameEpisodeFileService.RenameFiles (System.Collections.Generic.List`1[T] episodeFiles, NzbDrone.Core.Tv.Series series) [0x00045] in <0b43b6e47ef8497b83f58230dd1ec559>:0

File that actually exists is /mnt/nas01/mm-tv01/THE JEFFERSONS (1975) {TVDB-77792}/SEASON 01/THE JEFFERSONS - S01E12 - [WEBDL-480P][AAC 2.0][H264]-SQUALOR.rnm.mp4

Radarr log:

2023-01-21 12:58:04.3|Info|DiskScanService|Scanning disk for A Night at the Roxbury
2023-01-21 12:58:04.4|Info|RenameMovieFileService|Renaming 1 files for A Night at the Roxbury
2023-01-21 12:58:04.4|Error|RenameMovieFileService|Failed to rename file: /mnt/nas01/mm-movies01/A NIGHT AT THE ROXBURY [1998]/A NIGHT AT THE ROXBURY [1998] - [BLURAY-1080P][AAC 2.0][X264][IMDB-TT0120770][TMDB-9429]-VETO.mp4

[v4.3.2.6857] System.IO.FileNotFoundException: Movie file path does not exist
File name: '/mnt/nas01/mm-movies01/A NIGHT AT THE ROXBURY [1998]/A NIGHT AT THE ROXBURY [1998] - [BLURAY-1080P][AAC 2.0][X264][IMDB-TT0120770][TMDB-9429]-VETO.mp4'
   at NzbDrone.Core.MediaFiles.MovieFileMovingService.TransferFile(MovieFile movieFile, Movie movie, String destinationFilePath, TransferMode mode) in D:\a\1\s\src\NzbDrone.Core\MediaFiles\MovieFileMovingService.cs:line 134
   at NzbDrone.Core.MediaFiles.RenameMovieFileService.RenameFiles(List`1 movieFiles, Movie movie) in D:\a\1\s\src\NzbDrone.Core\MediaFiles\RenameMovieFileService.cs:line 95

2023-01-21 12:58:04.6|Info|RenameMovieFileService|Selected movie files renamed for A Night at the Roxbury

File that actually exists is /mnt/nas01/mm-movies01/A NIGHT AT THE ROXBURY [1998]/A NIGHT AT THE ROXBURY [1998] - [BLURAY-1080P][AAC 2.0][X264][IMDB-TT0120770][TMDB-9429]-VETO.rnm.mp4

The force-rename option is set to True for both Sonarr and Radarr in the autoProcess.ini file.

System Information

• OS: CentOS 7
• Python version 3.8

Sonarr version: 3.0.9.1549 Radarr version: 4.3.2.6857

[mdhiggins]

Not able to reproduce this on my end, renaming is still working as intended

The actual API call to perform the rename does not include the file path, but just the ID (episodeFileId/movieFileId) so the failure you're seeing is probably from a rescan after the renaming event has taken place failing so the internal database for Sonarr/Radarr has an out of date filename

Do you have SMA logs from a job that's giving you this issue? I suspect their might be an upstream API failure that's giving you this problem

Typically injecting the .rnm tag in the filename is one of the first steps that happens before any conversion steps take place, and then there is a rescan step that is called getting an updated fileId which is then used for the rename command

There is a situation where "downloadedMoviesScanInProgress" returns True in which case some other process has already triggered a scan of the directory you're trying to post process and it has to asynchronously queue the rescan and rename commands and then it might not have an up to date fileId, I wonder if that's where you're running into your issue. Not sure why that would be the case though unless maybe you have some custom logic that's causing downloadedMoviesScanInProgress to be true most of the time

sma logs would be helpful here

[mdhiggins]

09d53c30aaebcd89814068834a84660da3f67d47

See if that helps

[johnwchambers]

I imported a season of something in Sonarr for testing and it all looks good. There was one episode that had an issue, but I think the below snippet from the sma.log is just a bad file.

2023-01-22 01:18:50 - resources.mediaprocessor - DEBUG - /mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}/SEASON 01/SINGLE'S INFERNO - S01E05 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.rnm.mp4 created from
/mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}/SEASON 01/SINGLE'S INFERNO - S01E05 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.rnm.mp4 successfully.
2023-01-22 01:18:50 - resources.mediaprocessor - DEBUG - Height: 1080
2023-01-22 01:18:50 - resources.mediaprocessor - DEBUG - Width: 1920
2023-01-22 01:18:50 - resources.mediaprocessor - DEBUG - Tag language setting is eng, using language eng for tagging.
2023-01-22 01:18:50 - resources.mediaprocessor - INFO - Tagging /mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}/SEASON 01/SINGLE'S INFERNO - S01E05 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.rnm.mp4 with T
MDB ID 139798.
2023-01-22 01:18:50 - resources.metadata - INFO - Tagging file: /mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}/SEASON 01/SINGLE'S INFERNO - S01E05 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.rnm.mp4.
2023-01-22 01:18:50 - resources.metadata - INFO - Trying to write tags.
2023-01-22 01:18:51 - resources.metadata - INFO - Tags written successfully using mutagen.
2023-01-22 01:18:51 - resources.mediaprocessor - INFO - Relocating MOOV atom to start of file.
2023-01-22 01:18:51 - qtfaststart - DEBUG - Getting index of top level atoms...
2023-01-22 01:18:51 - qtfaststart - DEBUG - ftyp: 36
2023-01-22 01:18:51 - qtfaststart - DEBUG - free: 8
2023-01-22 01:18:51 - qtfaststart - DEBUG - mdat: 4286884895
2023-01-22 01:18:51 - qtfaststart - DEBUG - moov: 10393035
2023-01-22 01:18:51 - qtfaststart - INFO - Removing free atom at 36 (8 bytes)
2023-01-22 01:18:51 - qtfaststart - INFO - Patching stco with 84048 entries
2023-01-22 01:18:51 - resources.mediaprocessor - ERROR - Error processing
Traceback (most recent call last):
  File "/opt/mp4automator/resources/mediaprocessor.py", line 81, in fullprocess
    self.QTFS(output['output'])
  File "/opt/mp4automator/resources/mediaprocessor.py", line 2397, in QTFS
    processor.process(inputfile, outputfile)
  File "/usr/local/lib/python3.8/site-packages/qtfaststart/processor.py", line 197, in process
    moov = _patch_moov(datastream, moov_atom, offset)
  File "/usr/local/lib/python3.8/site-packages/qtfaststart/processor.py", line 263, in _patch_moov
    moov.write(struct.pack(struct_fmt, *offset_entries))
struct.error: 'L' format requires 0 <= number <= 4294967295
2023-01-22 01:18:51 - SonarrPostProcess - INFO - Processing returned False.
2023-01-22 01:18:51 - SonarrPostProcess - ERROR - Error processing file.
Traceback (most recent call last):
  File "/opt/mp4automator/postSonarr.py", line 319, in <module>
    sys.exit(1)
SystemExit: 1
2023-01-22 01:19:37 - SonarrPostProcess - INFO - Sonarr extra script post processing started.

Here's a snippet of the sma.log of one of the episode files that processed fine after your update:

2023-01-22 01:22:48 - resources.readsettings - INFO - /usr/local/bin/python3.8
2023-01-22 01:22:48 - resources.readsettings - DEBUG - Loading default config file.
2023-01-22 01:22:48 - resources.readsettings - INFO - Loading config file /opt/mp4automator/config/autoProcess.ini.
2023-01-22 01:22:48 - SonarrPostProcess - DEBUG - environ({'sonarr_series_type': 'Standard', 'XDG_DATA_HOME': '/home/sonarr/.config/Sonarr/.config/share', 'sonarr_episodefile_episodeairdates': '2022-01-08', 'sonarr_episodefile_relativepath': "SEASON 01/SINGLE'S INFERNO - S01E08 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.mp4", 'SHELL': '/bin/bash', 'sonarr_download_id': '', 'No_Expand': 'true', 'sonarr_series_id': '883', 'No_SQLiteXmlConfigFile': 'true', 'sonarr_episodefile_releasegroup': 'HBO', 'sonarr_deletedpaths': "/mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}/SEASON 01/SINGLE'S INFERNO - S01E08 - [NF][WEBDL-1080P][AAC 2.0][HEVC]-SMURF.mp4", 'sonarr_episodefile_sourcefolder': '/dwnldr/tor/converted/Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO', 'sonarr_eventtype': 'Download', 'USER': 'sonarr', 'sonarr_download_client': '', 'sonarr_episodefile_episodetitles': 'Episode 8', 'sonarr_episodefile_qualityversion': '1', 'sonarr_isupgrade': 'True', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin', 'sonarr_episodefile_path': "/mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}/SEASON 01/SINGLE'S INFERNO - S01E08 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.mp4", 'sonarr_episodefile_episodeids': '68739', 'sonarr_series_title': "Single's Inferno", 'PWD': '/', 'sonarr_download_client_type': '', 'LANG': 'en_US.UTF-8', 'sonarr_episodefile_episodenumbers': '8', 'sonarr_episodefile_scenename': "Single's.Inferno.S01E08.1080p.NF.WEB-DL.DDP5.1.x264-HBO", 'sonarr_episodefile_quality': 'WEBDL-1080p', 'SHLVL': '1', 'HOME': '/home/sonarr', 'sonarr_series_tvdbid': '411597', 'sonarr_series_tvmazeid': '59587', 'sonarr_episodefile_id': '90132', 'sonarr_series_path': "/mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}", 'sonarr_episodefile_episodecount': '1', 'sonarr_episodefile_sourcepath': "/dwnldr/tor/converted/Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO/Single's.Inferno.S01E08.1080p.NF.WEB-DL.DDP5.1.x264-HBO.mp4", 'LOGNAME': 'sonarr', 'sonarr_deletedrelativepaths': "SEASON 01/SINGLE'S INFERNO - S01E08 - [NF][WEBDL-1080P][AAC 2.0][HEVC]-SMURF.mp4", 'No_SQLiteFunctions': 'true', 'No_PreLoadSQLite': 'true', 'sonarr_episodefile_seasonnumber': '1', 'sonarr_series_imdbid': 'tt16283666', 'sonarr_episodefile_episodeairdatesutc': '1/8/2022 11:09:00 PM', '_': '/usr/local/bin/python3.8'})
2023-01-22 01:22:48 - SonarrPostProcess - DEBUG - Input file: /mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}/SEASON 01/SINGLE'S INFERNO - S01E08 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.mp4.
2023-01-22 01:22:48 - SonarrPostProcess - DEBUG - Original name: Single's.Inferno.S01E08.1080p.NF.WEB-DL.DDP5.1.x264-HBO.
2023-01-22 01:22:48 - SonarrPostProcess - DEBUG - TVDB ID: 411597.
2023-01-22 01:22:48 - SonarrPostProcess - DEBUG - Season: 1 episode: 8.
2023-01-22 01:22:48 - SonarrPostProcess - DEBUG - Sonarr series ID: 883.
2023-01-22 01:22:48 - SonarrPostProcess - DEBUG - Renaming file /mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}/SEASON 01/SINGLE'S INFERNO - S01E08 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.mp4 to /mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}/SEASON 01/SINGLE'S INFERNO - S01E08 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.rnm.mp4.
2023-01-22 01:22:49 - resources.mediaprocessor - INFO - Processing /mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}/SEASON 01/SINGLE'S INFERNO - S01E08 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.rnm.mp4.
2023-01-22 01:22:49 - resources.metadata - DEBUG - Input IDs:
2023-01-22 01:22:49 - resources.metadata - DEBUG - TMDBID: None
2023-01-22 01:22:49 - resources.metadata - DEBUG - IMDBID: tt16283666
2023-01-22 01:22:49 - resources.metadata - DEBUG - TVDBID: 411597
2023-01-22 01:22:49 - resources.metadata - DEBUG - Using TMDB ID: 139798
2023-01-22 01:22:49 - resources.metadata - DEBUG - Tagging language determined to be None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Process started.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Input Data
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - {
    "format": "mov,mp4,m4a,3gp,3g2,mj2",
    "format-fullname": "QuickTime / MOV",
    "video": {
        "index": 0,
        "codec": "h264",
        "bitrate": 5549783,
        "pix_fmt": "yuv420p",
        "profile": "high",
        "fps": 23.976023976023978,
        "framedata": {
            "pix_fmt": "yuv420p",
            "side_data_list": [
                {
                    "side_data_type": "H.26[45] User Data Unregistered SEI message"
                }
            ]
        },
        "dimensions": "1920x1080",
        "level": 4.0,
        "field_order": "progressive"
    },
    "audio": [
        {
            "index": 1,
            "codec": "aac",
            "bitrate": 251766,
            "channels": 2,
            "samplerate": 48000,
            "language": "eng",
            "disposition": "+default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions"
        },
        {
            "index": 2,
            "codec": "eac3",
            "bitrate": 640000,
            "channels": 6,
            "samplerate": 48000,
            "language": "eng",
            "disposition": "-default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions"
        },
        {
            "index": 3,
            "codec": "aac",
            "bitrate": 252110,
            "channels": 2,
            "samplerate": 48000,
            "language": "deu",
            "disposition": "-default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions"
        },
        {
            "index": 4,
            "codec": "eac3",
            "bitrate": 640000,
            "channels": 6,
            "samplerate": 48000,
            "language": "deu",
            "disposition": "-default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions"
        },
        {
            "index": 5,
            "codec": "aac",
            "bitrate": 251977,
            "channels": 2,
            "samplerate": 48000,
            "language": "jpn",
            "disposition": "-default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions"
        },
        {
            "index": 6,
            "codec": "eac3",
            "bitrate": 640000,
            "channels": 6,
            "samplerate": 48000,
            "language": "jpn",
            "disposition": "-default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions"
        },
        {
            "index": 7,
            "codec": "aac",
            "bitrate": 246817,
            "channels": 2,
            "samplerate": 48000,
            "language": "kor",
            "disposition": "-default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions"
        },
        {
            "index": 8,
            "codec": "eac3",
            "bitrate": 640000,
            "channels": 6,
            "samplerate": 48000,
            "language": "kor",
            "disposition": "-default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions"
        },
        {
            "index": 9,
            "codec": "aac",
            "bitrate": 251182,
            "channels": 2,
            "samplerate": 48000,
            "language": "tha",
            "disposition": "-default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions"
        },
        {
            "index": 10,
            "codec": "eac3",
            "bitrate": 640000,
            "channels": 6,
            "samplerate": 48000,
            "language": "tha",
            "disposition": "-default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions"
        }
    ],
    "subtitle": [],
    "attachment": []
}
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Reading video stream.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Video codec detected: h264.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Pix Fmt: yuv420p.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Profile: high.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Stream is not HDR, color parameter unknown does not match ['bt2020nc'] [hdr-['bt2020nc']].
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Pool of video codecs is ['h264_nvenc', 'h264', 'x264', 'h265_nvenc', 'hevc', 'h265', 'x265'].
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Total bitrate is 10037838.0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Total audio bitrate is 4453852.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Estimated video bitrate is 5583986.0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Using video bitrate ratio of 1.000000, which results in 5304.786700 changing to 5304.786700.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - VCRF profiles detected [video-crf-profiles].
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Acceptable profile match found for VBR 5304.7867 using CRF 21, maxrate 8m, bufsize 24m.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Source bit-depth 8, output 0, using depth 8.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Video codec: copy.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Video bitrate: 5304.7867.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Video CRF: 21.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Video maxrate: 8m.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Video bufsize: 24m.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Video level: 0.0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Video profile: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Video preset: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Video pix_fmt: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Video field order: progressive.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Video width: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Video debug video.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Video codec parameters None.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Creating copy video stream from source stream 0.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Reading audio streams.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - The following stream indexes have been identified as being copies: [] [stream-codec-combinations].
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Pool universal audio codecs is ['aac'].
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Pool of audio codecs is ['ac3', 'eac3'].
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Audio detected for stream 1 - aac eng 2 channel.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Overriding default channel settings because universal audio is enabled but the source is stereo [universal-audio].
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio codec: copy.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Channels: 2.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Bitrate: 256.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio Profile: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Language: eng.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Filter: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Disposition: +default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Debug: universal-audio.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Creating copy audio stream from source stream 1.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Audio detected for stream 2 - eac3 eng 6 channel.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Found an audio filter for converting from 6 channels to 2 channels. Applying filter pan=stereo|FL=0.5*FC+0.707*FL+0.707*BL+0.5*LFE|FR=0.5*FC+0.707*FR+0.707*BR+0.5*LFE to UA.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio codec: aac.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Channels: 2.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Filter: pan=stereo|FL=0.5*FC+0.707*FL+0.707*BL+0.5*LFE|FR=0.5*FC+0.707*FR+0.707*BR+0.5*LFE.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Bitrate: 256.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Profile: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Language: eng.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Disposition: -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio codec: copy.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Channels: 6.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Bitrate: 768.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio Profile: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Language: eng.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Filter: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Disposition: -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Debug: audio.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Creating copy audio stream from source stream 2.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Creating aac audio stream from source audio stream 2 [universal-audio].
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Audio detected for stream 3 - aac deu 2 channel.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Overriding default channel settings because universal audio is enabled but the source is stereo [universal-audio].
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio codec: copy.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Channels: 2.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Bitrate: 256.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio Profile: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Language: deu.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Filter: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Disposition: -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Debug: universal-audio.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Creating copy audio stream from source stream 3.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Audio detected for stream 4 - eac3 deu 6 channel.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Found an audio filter for converting from 6 channels to 2 channels. Applying filter pan=stereo|FL=0.5*FC+0.707*FL+0.707*BL+0.5*LFE|FR=0.5*FC+0.707*FR+0.707*BR+0.5*LFE to UA.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio codec: aac.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Channels: 2.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Filter: pan=stereo|FL=0.5*FC+0.707*FL+0.707*BL+0.5*LFE|FR=0.5*FC+0.707*FR+0.707*BR+0.5*LFE.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Bitrate: 256.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Profile: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Language: deu.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Disposition: -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio codec: copy.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Channels: 6.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Bitrate: 768.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio Profile: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Language: deu.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Filter: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Disposition: -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Debug: audio.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Creating copy audio stream from source stream 4.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Creating aac audio stream from source audio stream 4 [universal-audio].
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Audio detected for stream 5 - aac jpn 2 channel.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Overriding default channel settings because universal audio is enabled but the source is stereo [universal-audio].
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio codec: copy.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Channels: 2.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Bitrate: 256.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio Profile: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Language: jpn.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Filter: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Disposition: -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Debug: universal-audio.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Creating copy audio stream from source stream 5.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Audio detected for stream 6 - eac3 jpn 6 channel.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Found an audio filter for converting from 6 channels to 2 channels. Applying filter pan=stereo|FL=0.5*FC+0.707*FL+0.707*BL+0.5*LFE|FR=0.5*FC+0.707*FR+0.707*BR+0.5*LFE to UA.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio codec: aac.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Channels: 2.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Filter: pan=stereo|FL=0.5*FC+0.707*FL+0.707*BL+0.5*LFE|FR=0.5*FC+0.707*FR+0.707*BR+0.5*LFE.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Bitrate: 256.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Profile: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Language: jpn.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Disposition: -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio codec: copy.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Channels: 6.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Bitrate: 768.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio Profile: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Language: jpn.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Filter: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Disposition: -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Debug: audio.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Creating copy audio stream from source stream 6.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Creating aac audio stream from source audio stream 6 [universal-audio].
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Audio detected for stream 7 - aac kor 2 channel.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Overriding default channel settings because universal audio is enabled but the source is stereo [universal-audio].
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio codec: copy.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Channels: 2.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Bitrate: 256.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio Profile: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Language: kor.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Filter: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Disposition: -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Debug: universal-audio.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Creating copy audio stream from source stream 7.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Audio detected for stream 8 - eac3 kor 6 channel.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Found an audio filter for converting from 6 channels to 2 channels. Applying filter pan=stereo|FL=0.5*FC+0.707*FL+0.707*BL+0.5*LFE|FR=0.5*FC+0.707*FR+0.707*BR+0.5*LFE to UA.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio codec: aac.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Channels: 2.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Filter: pan=stereo|FL=0.5*FC+0.707*FL+0.707*BL+0.5*LFE|FR=0.5*FC+0.707*FR+0.707*BR+0.5*LFE.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Bitrate: 256.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Profile: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Language: kor.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Disposition: -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio codec: copy.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Channels: 6.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Bitrate: 768.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio Profile: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Language: kor.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Filter: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Disposition: -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Debug: audio.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Creating copy audio stream from source stream 8.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Creating aac audio stream from source audio stream 8 [universal-audio].
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Audio detected for stream 9 - aac tha 2 channel.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Overriding default channel settings because universal audio is enabled but the source is stereo [universal-audio].
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio codec: copy.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Channels: 2.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Bitrate: 256.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio Profile: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Language: tha.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Filter: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Disposition: -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Debug: universal-audio.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Creating copy audio stream from source stream 9.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Audio detected for stream 10 - eac3 tha 6 channel.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Found an audio filter for converting from 6 channels to 2 channels. Applying filter pan=stereo|FL=0.5*FC+0.707*FL+0.707*BL+0.5*LFE|FR=0.5*FC+0.707*FR+0.707*BR+0.5*LFE to UA.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio codec: aac.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Channels: 2.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Filter: pan=stereo|FL=0.5*FC+0.707*FL+0.707*BL+0.5*LFE|FR=0.5*FC+0.707*FR+0.707*BR+0.5*LFE.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Bitrate: 256.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Profile: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Language: tha.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Disposition: -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio codec: copy.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Channels: 6.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Bitrate: 768.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Audio Profile: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Language: tha.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Filter: None.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Disposition: -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Debug: audio.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Creating copy audio stream from source stream 10.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Creating aac audio stream from source audio stream 10 [universal-audio].
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Purging the following streams:
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - []
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Found 0 streams that can be removed from the output file since they will be duplicates [stream-codec-combinations].
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Triggering audio track sort [audio.sorting-sorting].
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Sorting streams with keys ['language', 'channels.a', 'map', 'd.comment'].
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Sorted ascending with language:
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - ['0->0', '1->1', '2->2', '3->9', '4->10', '5->11', '6->6', '7->7', '8->8', '9->3', '10->4', '11->5', '12->12', '13->13', '14->14']
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Sorted ascending with channels:
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - ['0->0', '1->10', '2->1', '3->2', '4->11', '5->3', '6->4', '7->12', '8->5', '9->6', '10->13', '11->7', '12->8', '13->14', '14->9']
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Sorted ascending with map:
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - ['0->0', '1->1', '2->9', '3->10', '4->6', '5->7', '6->3', '7->4', '8->12', '9->13', '10->2', '11->11', '12->8', '13->5', '14->14']
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Sorted ascending with d.comment:
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - ['0->0', '1->1', '2->2', '3->3', '4->4', '5->5', '6->6', '7->7', '8->8', '9->9', '10->10', '11->11', '12->12', '13->13', '14->14']
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Final sorting:
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - ['0->0', '1->2', '2->1', '3->3', '4->5', '5->4', '6->6', '7->8', '8->7', '9->9', '10->11', '11->10', '12->12', '13->14', '14->13']
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Sorting streams with keys ['channels.a', 'map', 'd.comment'].
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Sorted ascending with channels:
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - ['0->0', '1->1', '2->10', '3->2', '4->3', '5->11', '6->4', '7->5', '8->12', '9->6', '10->7', '11->13', '12->8', '13->9', '14->14']
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Sorted ascending with map:
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - ['0->0', '1->1', '2->3', '3->4', '4->6', '5->7', '6->9', '7->10', '8->12', '9->13', '10->2', '11->5', '12->8', '13->11', '14->14']
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Sorted ascending with d.comment:
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - ['0->0', '1->1', '2->2', '3->3', '4->4', '5->5', '6->6', '7->7', '8->8', '9->9', '10->10', '11->11', '12->12', '13->13', '14->14']
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Final sorting:
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - ['0->0', '1->1', '2->2', '3->3', '4->4', '5->5', '6->6', '7->7', '8->8', '9->9', '10->10', '11->11', '12->12', '13->13', '14->14']
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Sorting audio streams for default audio stream designation.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - 15 total audio streams with 1 set to default disposition. 1 defaults in your preferred language (eng), 0 in other languages.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Default audio stream already inherited from source material, will not override to audio-language-default.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Default audio stream set to eng copy 2 channel stream [audio-default-sorting: ['channels.a', 'map', 'd.comment']].
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Reading subtitle streams.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Scanned for external subtitles and found 0 results in your approved languages.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Subtitle output is empty or no default subtitle language is set, will not pass over subtitle output to set a default stream.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Sorting streams with keys ['language', 'd.comment', 'd.default.d', 'd.forced.d'].
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Final sorting:
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - []
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Input and output extensions match and the file appears to have already been processed by SMA, enable force-convert to override [force-convert: False].
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Bypassing conversion and setting outputfile to inputfile.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - /mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}/SEASON 01/SINGLE'S INFERNO - S01E08 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.rnm.mp4 created from /mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}/SEASON 01/SINGLE'S INFERNO - S01E08 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.rnm.mp4 successfully.
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Height: 1080
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Width: 1920
2023-01-22 01:22:50 - resources.mediaprocessor - DEBUG - Tag language setting is eng, using language eng for tagging.
2023-01-22 01:22:50 - resources.mediaprocessor - INFO - Tagging /mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}/SEASON 01/SINGLE'S INFERNO - S01E08 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.rnm.mp4 with TMDB ID 139798.
2023-01-22 01:22:50 - resources.metadata - INFO - Tagging file: /mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}/SEASON 01/SINGLE'S INFERNO - S01E08 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.rnm.mp4.
2023-01-22 01:22:51 - resources.metadata - INFO - Trying to write tags.
2023-01-22 01:22:51 - resources.metadata - INFO - Tags written successfully using mutagen.
2023-01-22 01:22:51 - resources.mediaprocessor - INFO - Relocating MOOV atom to start of file.
2023-01-22 01:22:51 - qtfaststart - DEBUG - Getting index of top level atoms...
2023-01-22 01:22:51 - qtfaststart - DEBUG - ftyp: 36
2023-01-22 01:22:51 - qtfaststart - DEBUG - mdat: 4973762135
2023-01-22 01:22:51 - qtfaststart - DEBUG - moov: 18378953
2023-01-22 01:22:51 - qtfaststart - INFO - Patching co64 with 95344 entries
2023-01-22 01:22:51 - qtfaststart - INFO - Patching co64 with 186501 entries
2023-01-22 01:22:51 - qtfaststart - INFO - Patching co64 with 124333 entries
2023-01-22 01:22:51 - qtfaststart - INFO - Patching co64 with 186501 entries
2023-01-22 01:22:51 - qtfaststart - INFO - Patching co64 with 124333 entries
2023-01-22 01:22:51 - qtfaststart - INFO - Patching co64 with 186501 entries
2023-01-22 01:22:51 - qtfaststart - INFO - Patching co64 with 124333 entries
2023-01-22 01:22:51 - qtfaststart - INFO - Patching co64 with 186501 entries
2023-01-22 01:22:51 - qtfaststart - INFO - Patching co64 with 124333 entries
2023-01-22 01:22:51 - qtfaststart - INFO - Patching co64 with 186501 entries
2023-01-22 01:22:51 - qtfaststart - INFO - Patching co64 with 124333 entries
2023-01-22 01:22:51 - qtfaststart - INFO - Writing output...
2023-01-22 01:22:51 - qtfaststart - DEBUG - Writing ftyp... (36 bytes)
2023-01-22 01:22:51 - qtfaststart - DEBUG - Writing moov... (18378953 bytes)
2023-01-22 01:22:51 - qtfaststart - DEBUG - Writing mdat... (4973762135 bytes)
2023-01-22 01:23:26 - resources.mediaprocessor - DEBUG - Final output file: /mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}/SEASON 01/SINGLE'S INFERNO - S01E08 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.rnm.mp4.
2023-01-22 01:23:26 - SonarrPostProcess - DEBUG - Sonarr baseURL: http://localhost:8989.
2023-01-22 01:23:26 - SonarrPostProcess - DEBUG - Sonarr apikey: 02dc28cfb7cb4a1f8cbb917fcc593d1c.
2023-01-22 01:23:26 - SonarrPostProcess - DEBUG - Requesting commands in process from Sonarr via http://localhost:8989/api/v3/command.
2023-01-22 01:23:26 - SonarrPostProcess - DEBUG - [{'name': 'ManualImport', 'commandName': 'Manual Import', 'message': 'Processing file 8 of 8', 'body': {'files': [{'path': "/dwnldr/tor/converted/Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO/Single's.Inferno.S01E07.1080p.NF.WEB-DL.DDP5.1.x264-HBO.mp4", 'folderName': 'Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO', 'seriesId': 883, 'episodeIds': [68738], 'quality': {'quality': {'id': 3, 'name': 'WEBDL-1080p', 'source': 'web', 'resolution': 1080}, 'revision': {'version': 1, 'real': 0, 'isRepack': False}}, 'language': {'id': 1, 'name': 'English'}, 'releaseGroup': 'HBO'}, {'path': "/dwnldr/tor/converted/Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO/Single's.Inferno.S01E02.1080p.NF.WEB-DL.DDP5.1.x264-HBO.mp4", 'folderName': 'Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO', 'seriesId': 883, 'episodeIds': [68733], 'quality': {'quality': {'id': 3, 'name': 'WEBDL-1080p', 'source': 'web', 'resolution': 1080}, 'revision': {'version': 1, 'real': 0, 'isRepack': False}}, 'language': {'id': 1, 'name': 'English'}, 'releaseGroup': 'HBO'}, {'path': "/dwnldr/tor/converted/Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO/Single's.Inferno.S01E03.1080p.NF.WEB-DL.DDP5.1.x264-HBO.mp4", 'folderName': 'Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO', 'seriesId': 883, 'episodeIds': [68734], 'quality': {'quality': {'id': 3, 'name': 'WEBDL-1080p', 'source': 'web', 'resolution': 1080}, 'revision': {'version': 1, 'real': 0, 'isRepack': False}}, 'language': {'id': 1, 'name': 'English'}, 'releaseGroup': 'HBO'}, {'path': "/dwnldr/tor/converted/Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO/Single's.Inferno.S01E04.1080p.NF.WEB-DL.DDP5.1.x264-HBO.mp4", 'folderName': 'Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO', 'seriesId': 883, 'episodeIds': [68735], 'quality': {'quality': {'id': 3, 'name': 'WEBDL-1080p', 'source': 'web', 'resolution': 1080}, 'revision': {'version': 1, 'real': 0, 'isRepack': False}}, 'language': {'id': 1, 'name': 'English'}, 'releaseGroup': 'HBO'}, {'path': "/dwnldr/tor/converted/Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO/Single's.Inferno.S01E05.1080p.NF.WEB-DL.DDP5.1.x264-HBO.mp4", 'folderName': 'Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO', 'seriesId': 883, 'episodeIds': [68736], 'quality': {'quality': {'id': 3, 'name': 'WEBDL-1080p', 'source': 'web', 'resolution': 1080}, 'revision': {'version': 1, 'real': 0, 'isRepack': False}}, 'language': {'id': 1, 'name': 'English'}, 'releaseGroup': 'HBO'}, {'path': "/dwnldr/tor/converted/Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO/Single's.Inferno.S01E06.1080p.NF.WEB-DL.DDP5.1.x264-HBO.mp4", 'folderName': 'Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO', 'seriesId': 883, 'episodeIds': [68737], 'quality': {'quality': {'id': 3, 'name': 'WEBDL-1080p', 'source': 'web', 'resolution': 1080}, 'revision': {'version': 1, 'real': 0, 'isRepack': False}}, 'language': {'id': 1, 'name': 'English'}, 'releaseGroup': 'HBO'}, {'path': "/dwnldr/tor/converted/Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO/Single's.Inferno.S01E01.1080p.NF.WEB-DL.DDP5.1.x264-HBO.mp4", 'folderName': 'Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO', 'seriesId': 883, 'episodeIds': [68732], 'quality': {'quality': {'id': 3, 'name': 'WEBDL-1080p', 'source': 'web', 'resolution': 1080}, 'revision': {'version': 1, 'real': 0, 'isRepack': False}}, 'language': {'id': 1, 'name': 'English'}, 'releaseGroup': 'HBO'}, {'path': "/dwnldr/tor/converted/Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO/Single's.Inferno.S01E08.1080p.NF.WEB-DL.DDP5.1.x264-HBO.mp4", 'folderName': 'Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO', 'seriesId': 883, 'episodeIds': [68739], 'quality': {'quality': {'id': 3, 'name': 'WEBDL-1080p', 'source': 'web', 'resolution': 1080}, 'revision': {'version': 1, 'real': 0, 'isRepack': False}}, 'language': {'id': 1, 'name': 'English'}, 'releaseGroup': 'HBO'}], 'sendUpdatesToClient': True, 'requiresDiskAccess': True, 'importMode': 'move', 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'ManualImport', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36'}, 'priority': 'normal', 'status': 'started', 'queued': '2023-01-21T16:12:21.678356Z', 'started': '2023-01-21T16:12:21.683071Z', 'trigger': 'manual', 'stateChangeTime': '2023-01-21T16:12:21.683071Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248458}, {'name': 'ProcessMonitoredDownloads', 'commandName': 'Process Monitored Downloads', 'body': {'requiresDiskAccess': True, 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'ProcessMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'queued', 'queued': '2023-01-21T16:12:55.269442Z', 'trigger': 'unspecified', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248460}, {'name': 'RenameFiles', 'commandName': 'Rename Files', 'body': {'seriesId': 883, 'files': [90119], 'sendUpdatesToClient': True, 'requiresDiskAccess': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'RenameFiles', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'SMA - postSonarr'}, 'priority': 'normal', 'status': 'queued', 'queued': '2023-01-21T16:14:01.707366Z', 'trigger': 'manual', 'clientUserAgent': 'SMA - postSonarr', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248463}, {'name': 'RenameFiles', 'commandName': 'Rename Files', 'body': {'seriesId': 883, 'files': [90121], 'sendUpdatesToClient': True, 'requiresDiskAccess': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'RenameFiles', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'SMA - postSonarr'}, 'priority': 'normal', 'status': 'queued', 'queued': '2023-01-21T16:15:29.51465Z', 'trigger': 'manual', 'clientUserAgent': 'SMA - postSonarr', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248467}, {'name': 'RenameFiles', 'commandName': 'Rename Files', 'body': {'seriesId': 883, 'files': [90123], 'sendUpdatesToClient': True, 'requiresDiskAccess': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'RenameFiles', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'SMA - postSonarr'}, 'priority': 'normal', 'status': 'queued', 'queued': '2023-01-21T16:16:53.631569Z', 'trigger': 'manual', 'clientUserAgent': 'SMA - postSonarr', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248472}, {'name': 'RenameFiles', 'commandName': 'Rename Files', 'body': {'seriesId': 883, 'files': [90125], 'sendUpdatesToClient': True, 'requiresDiskAccess': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'RenameFiles', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'SMA - postSonarr'}, 'priority': 'normal', 'status': 'queued', 'queued': '2023-01-21T16:18:08.860233Z', 'trigger': 'manual', 'clientUserAgent': 'SMA - postSonarr', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248476}, {'name': 'RenameFiles', 'commandName': 'Rename Files', 'body': {'seriesId': 883, 'files': [90129], 'sendUpdatesToClient': True, 'requiresDiskAccess': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'RenameFiles', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'SMA - postSonarr'}, 'priority': 'normal', 'status': 'queued', 'queued': '2023-01-21T16:20:19.793078Z', 'trigger': 'manual', 'clientUserAgent': 'SMA - postSonarr', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248483}, {'name': 'RenameFiles', 'commandName': 'Rename Files', 'body': {'seriesId': 883, 'files': [90131], 'sendUpdatesToClient': True, 'requiresDiskAccess': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'RenameFiles', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'SMA - postSonarr'}, 'priority': 'normal', 'status': 'queued', 'queued': '2023-01-21T16:21:49.570694Z', 'trigger': 'manual', 'clientUserAgent': 'SMA - postSonarr', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248488}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T16:16:49.481195Z', 'started': '2023-01-21T16:16:49.484758Z', 'ended': '2023-01-21T16:16:50.098998Z', 'duration': '00:00:00.6142400', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T16:16:49.484758Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248471}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T16:16:58.791393Z', 'started': '2023-01-21T16:16:58.794721Z', 'ended': '2023-01-21T16:17:05.105493Z', 'duration': '00:00:06.3107720', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T16:16:58.794721Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248473}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T16:18:04.64445Z', 'started': '2023-01-21T16:18:04.648024Z', 'ended': '2023-01-21T16:18:05.27472Z', 'duration': '00:00:00.6266960', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T16:18:04.648024Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248475}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T16:18:14.00177Z', 'started': '2023-01-21T16:18:14.006152Z', 'ended': '2023-01-21T16:18:21.145757Z', 'duration': '00:00:07.1396050', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T16:18:14.006152Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248477}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T16:18:56.48047Z', 'started': '2023-01-21T16:18:56.484671Z', 'ended': '2023-01-21T16:19:09.804686Z', 'duration': '00:00:13.3200150', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T16:18:56.484671Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248478}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'lastExecutionTime': '2023-01-21T16:19:09.814071Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T16:20:11.479886Z', 'started': '2023-01-21T16:20:11.835495Z', 'ended': '2023-01-21T16:20:12.502874Z', 'duration': '00:00:00.6673790', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T16:20:11.835495Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T16:19:09.814071Z', 'id': 3248481}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T16:20:17.503107Z', 'started': '2023-01-21T16:20:17.50746Z', 'ended': '2023-01-21T16:20:18.119337Z', 'duration': '00:00:00.6118770', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T16:20:17.50746Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248482}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T16:20:25.024637Z', 'started': '2023-01-21T16:20:25.03003Z', 'ended': '2023-01-21T16:20:42.00377Z', 'duration': '00:00:16.9737400', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T16:20:25.03003Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248484}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T16:21:45.376489Z', 'started': '2023-01-21T16:21:45.382142Z', 'ended': '2023-01-21T16:21:45.996433Z', 'duration': '00:00:00.6142910', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T16:21:45.382142Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248487}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T16:21:54.710935Z', 'started': '2023-01-21T16:21:54.71455Z', 'ended': '2023-01-21T16:22:13.388468Z', 'duration': '00:00:18.6739180', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T16:21:54.71455Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248489}, {'name': 'RescanSeries', 'commandName': 'Rescan Series', 'message': 'Completed', 'body': {'seriesId': 883, 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RescanSeries', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'SMA - postSonarr'}, 'priority': 'normal', 'status': 'completed', 'queued': '2023-01-21T16:16:43.551271Z', 'started': '2023-01-21T16:16:43.555704Z', 'ended': '2023-01-21T16:16:44.608795Z', 'duration': '00:00:01.0530910', 'trigger': 'manual', 'clientUserAgent': 'SMA - postSonarr', 'stateChangeTime': '2023-01-21T16:16:43.555704Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248470}, {'name': 'RescanSeries', 'commandName': 'Rescan Series', 'message': 'Completed', 'body': {'seriesId': 883, 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RescanSeries', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'SMA - postSonarr'}, 'priority': 'normal', 'status': 'completed', 'queued': '2023-01-21T16:17:58.787993Z', 'started': '2023-01-21T16:17:58.793575Z', 'ended': '2023-01-21T16:17:59.769261Z', 'duration': '00:00:00.9756860', 'trigger': 'manual', 'clientUserAgent': 'SMA - postSonarr', 'stateChangeTime': '2023-01-21T16:17:58.793575Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248474}, {'name': 'RescanSeries', 'commandName': 'Rescan Series', 'message': 'Completed', 'body': {'seriesId': 883, 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RescanSeries', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'SMA - postSonarr'}, 'priority': 'normal', 'status': 'completed', 'queued': '2023-01-21T16:20:09.454916Z', 'started': '2023-01-21T16:20:09.502355Z', 'ended': '2023-01-21T16:20:11.045192Z', 'duration': '00:00:01.5428370', 'trigger': 'manual', 'clientUserAgent': 'SMA - postSonarr', 'stateChangeTime': '2023-01-21T16:20:09.502355Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248480}, {'name': 'RescanSeries', 'commandName': 'Rescan Series', 'message': 'Completed', 'body': {'seriesId': 883, 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RescanSeries', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'SMA - postSonarr'}, 'priority': 'normal', 'status': 'completed', 'queued': '2023-01-21T16:21:39.498546Z', 'started': '2023-01-21T16:21:39.503287Z', 'ended': '2023-01-21T16:21:40.489994Z', 'duration': '00:00:00.9867070', 'trigger': 'manual', 'clientUserAgent': 'SMA - postSonarr', 'stateChangeTime': '2023-01-21T16:21:39.503287Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248485}, {'name': 'RssSync', 'commandName': 'Rss Sync', 'message': 'Completed', 'body': {'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RssSync', 'lastExecutionTime': '2023-01-21T16:03:42.415672Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'low', 'status': 'completed', 'queued': '2023-01-21T16:19:11.4743Z', 'started': '2023-01-21T16:19:11.477786Z', 'ended': '2023-01-21T16:20:03.933749Z', 'duration': '00:00:52.4559630', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T16:19:11.477786Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T16:03:42.415672Z', 'id': 3248479}, {'name': 'MessagingCleanup', 'commandName': 'Messaging Cleanup', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'MessagingCleanup', 'lastExecutionTime': '2023-01-21T16:16:41.505127Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'low', 'status': 'completed', 'queued': '2023-01-21T16:21:41.83674Z', 'started': '2023-01-21T16:21:41.842119Z', 'ended': '2023-01-21T16:21:41.857181Z', 'duration': '00:00:00.0150620', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T16:21:41.842119Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T16:16:41.505127Z', 'id': 3248486}]
2023-01-22 01:23:26 - SonarrPostProcess - DEBUG - /dwnldr/tor/converted/Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO
2023-01-22 01:23:26 - SonarrPostProcess - DEBUG - No commands in progress for /dwnldr/tor/converted/Single_s.Inferno.S01.1080p.NF.WEB-DL.DDP5.1.x264-HBO.
2023-01-22 01:23:26 - SonarrPostProcess - DEBUG - Queueing rescan command to Sonarr via http://localhost:8989/api/v3/command.
2023-01-22 01:23:26 - SonarrPostProcess - DEBUG - {'name': 'RescanSeries', 'seriesId': 883}
2023-01-22 01:23:26 - SonarrPostProcess - DEBUG - {'name': 'RescanSeries', 'commandName': 'Rescan Series', 'body': {'seriesId': 883, 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RescanSeries', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'SMA - postSonarr'}, 'priority': 'normal', 'status': 'queued', 'queued': '2023-01-21T16:23:26.098355Z', 'trigger': 'manual', 'clientUserAgent': 'SMA - postSonarr', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248490}
2023-01-22 01:23:26 - SonarrPostProcess - INFO - Sonarr response from RescanSeries command: ID 3248490 queued.
2023-01-22 01:23:26 - SonarrPostProcess - DEBUG - Requesting command status from Sonarr for command ID 3248490.
2023-01-22 01:23:26 - SonarrPostProcess - DEBUG - Status: started.
2023-01-22 01:23:36 - SonarrPostProcess - DEBUG - {'name': 'RescanSeries', 'commandName': 'Rescan Series', 'message': 'Completed', 'body': {'seriesId': 883, 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RescanSeries', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'SMA - postSonarr'}, 'priority': 'normal', 'status': 'completed', 'queued': '2023-01-21T16:23:26.098355Z', 'started': '2023-01-21T16:23:26.103137Z', 'ended': '2023-01-21T16:23:27.213341Z', 'duration': '00:00:01.1102040', 'trigger': 'manual', 'clientUserAgent': 'SMA - postSonarr', 'stateChangeTime': '2023-01-21T16:23:26.103137Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248490}
2023-01-22 01:23:36 - SonarrPostProcess - DEBUG - Final status: completed.
2023-01-22 01:23:36 - SonarrPostProcess - INFO - Rescan command completed.
2023-01-22 01:23:36 - SonarrPostProcess - DEBUG - Requesting episode from Sonarr via http://localhost:8989/api/v3/episode/68739.
2023-01-22 01:23:36 - SonarrPostProcess - DEBUG - {'seriesId': 883, 'tvdbId': 8868751, 'episodeFileId': 90133, 'seasonNumber': 1, 'episodeNumber': 8, 'title': 'Episode 8', 'airDate': '2022-01-08', 'airDateUtc': '2022-01-08T23:09:00Z', 'overview': 'On the final day, singles choose the person they want to leave Inferno with. How many couples will depart the island hand-in-hand?', 'episodeFile': {'seriesId': 883, 'seasonNumber': 1, 'relativePath': "SEASON 01/SINGLE'S INFERNO - S01E08 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.rnm.mp4", 'path': "/mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}/SEASON 01/SINGLE'S INFERNO - S01E08 - [NF][WEBDL-1080P][AAC 2.0][X264]-HBO.rnm.mp4", 'size': 4992141124, 'dateAdded': '2023-01-21T16:23:27.079498Z', 'releaseGroup': 'HBO', 'language': {'id': 1, 'name': 'English'}, 'quality': {'quality': {'id': 3, 'name': 'WEBDL-1080p', 'source': 'web', 'resolution': 1080}, 'revision': {'version': 1, 'real': 0, 'isRepack': False}}, 'mediaInfo': {'audioBitrate': 251766, 'audioChannels': 2.0, 'audioCodec': 'AAC', 'audioLanguages': 'English / English / German / German / Japanese / Japanese / Korean / Korean / Thai / Thai', 'audioStreamCount': 10, 'videoBitDepth': 8, 'videoBitrate': 5549776, 'videoCodec': 'x264', 'videoFps': 23.976, 'videoDynamicRange': '', 'videoDynamicRangeType': '', 'resolution': '1920x1080', 'runTime': '1:06:16', 'scanType': 'Progressive', 'subtitles': ''}, 'qualityCutoffNotMet': True, 'languageCutoffNotMet': True, 'id': 90133}, 'hasFile': True, 'monitored': True, 'unverifiedSceneNumbering': False, 'series': {'title': "Single's Inferno", 'sortTitle': 'singles inferno', 'status': 'ended', 'ended': True, 'overview': 'Stranded and ready to mingle, flirty singles search for love on a deserted island they can only escape as couples for romantic date nights in paradise.', 'network': 'Netflix', 'airTime': '17:00', 'images': [{'coverType': 'banner', 'url': 'https://artworks.thetvdb.com/banners/v4/series/411597/banners/6324bbc6c9821.jpg'}, {'coverType': 'poster', 'url': 'https://artworks.thetvdb.com/banners/v4/series/411597/posters/6391e7b1e17e9.jpg'}, {'coverType': 'fanart', 'url': 'https://artworks.thetvdb.com/banners/v4/series/411597/backgrounds/639658d6303ce.jpg'}], 'seasons': [{'seasonNumber': 1, 'monitored': True}, {'seasonNumber': 2, 'monitored': True}], 'year': 2021, 'path': "/mnt/nas01/mm-tv01/SINGLE'S INFERNO (2021) {TVDB-411597}", 'qualityProfileId': 10, 'languageProfileId': 2, 'seasonFolder': True, 'monitored': True, 'useSceneNumbering': False, 'runtime': 69, 'tvdbId': 411597, 'tvRageId': 0, 'tvMazeId': 59587, 'firstAired': '2021-12-18T00:00:00Z', 'seriesType': 'standard', 'cleanTitle': 'singlesinferno', 'imdbId': 'tt16283666', 'titleSlug': 'singles-inferno', 'certification': '12', 'genres': ['Reality', 'Romance'], 'tags': [], 'added': '2022-12-23T13:03:17.995291Z', 'ratings': {'votes': 0, 'value': 0.0}, 'id': 883}, 'images': [{'coverType': 'screenshot', 'url': 'https://artworks.thetvdb.com/banners/v4/episode/8868751/screencap/61d9adf32dfa3.jpg'}], 'id': 68739}
2023-01-22 01:23:36 - SonarrPostProcess - DEBUG - Requesting episode update to Sonarr via http://localhost:8989/api/v3/episode/68739.
2023-01-22 01:23:36 - SonarrPostProcess - INFO - Sonarr monitoring information updated for episode Episode 8.
2023-01-22 01:23:36 - SonarrPostProcess - DEBUG - Trying to restore scene information.
2023-01-22 01:23:36 - SonarrPostProcess - DEBUG - Requesting episodefile from Sonarr for episodefile via http://localhost:8989/api/v3/episodefile/90133.
2023-01-22 01:23:36 - SonarrPostProcess - DEBUG - Requesting episodefile update to Sonarr via http://localhost:8989/api/v3/episodefile/90133.
2023-01-22 01:23:36 - SonarrPostProcess - DEBUG - Restored sceneName to Single's.Inferno.S01E08.1080p.NF.WEB-DL.DDP5.1.x264-HBO.
2023-01-22 01:23:36 - SonarrPostProcess - DEBUG - Restored releaseGroup to HBO.
2023-01-22 01:23:36 - SonarrPostProcess - DEBUG - Queueing rename command to Sonarr via http://localhost:8989/api/v3/command.
2023-01-22 01:23:36 - SonarrPostProcess - DEBUG - {'name': 'RenameFiles', 'files': [90133], 'seriesId': 883}
2023-01-22 01:23:36 - SonarrPostProcess - INFO - Sonarr response RenameFiles command: ID 3248492 queued.

And here is a snippet of the sma.log before the update for a file where it looks like it's fine, but the file was never renamed:

2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Script triggered from NZBGet (11.0 or later).
2023-01-22 00:00:09 - resources.readsettings - INFO - /usr/local/bin/python3.8
2023-01-22 00:00:09 - resources.readsettings - DEBUG - Configuration file specified is a directory, joining with autoProcess.ini.
2023-01-22 00:00:09 - resources.readsettings - INFO - Loading config file /opt/mp4automator/config/autoProcess.ini.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Processing file /dwnldr/nzb/comp/tv/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat.mkv.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Input Data
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - {
    "format": "matroska,webm",
    "format-fullname": "Matroska / WebM",
    "video": {
        "index": 0,
        "codec": "h264",
        "pix_fmt": "yuv420p",
        "profile": "high",
        "fps": 23.976023976023978,
        "framedata": {
            "pix_fmt": "yuv420p"
        },
        "dimensions": "1280x720",
        "level": 3.1,
        "field_order": "progressive"
    },
    "audio": [
        {
            "index": 1,
            "codec": "aac",
            "channels": 2,
            "samplerate": 48000,
            "language": "eng",
            "disposition": "+default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions"
        }
    ],
    "subtitle": [
        {
            "index": 2,
            "codec": "subrip",
            "disposition": "+default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions",
            "language": "eng"
        }
    ],
    "attachment": []
}
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Reading video stream.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Video codec detected: h264.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Pix Fmt: yuv420p.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Profile: high.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Acceptable profile match found for VBR 2750.40485 using CRF 23, maxrate 4.5m, bufsize 12m.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Video codec parameters None.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Creating copy video stream from source stream 0.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Reading audio streams.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - The following stream indexes have been identified as being copies: [] [stream-codec-combinations].
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Audio detected for stream 1 - aac eng 2 channel.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Creating copy audio stream from source stream 1.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Found 0 streams that can be removed from the output file since they will be duplicates [stream-codec-combinations].
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Final sorting:
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - ['0->0']
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Final sorting:
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - ['0->0']
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Default audio stream set to eng copy 2 channel stream [audio-default-sorting: ['channels.a', 'map', 'd.comment']].
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Reading subtitle streams.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Subtitle detected for stream 2 - subrip eng.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Stream 2 is text-based subtitle for codec subrip.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Scanned for external subtitles and found 0 results in your approved languages.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Final sorting:
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - []
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Output Data
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - {
    "source": [
        "/dwnldr/nzb/comp/tv/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat.mkv"
    ],
    "format": "mp4",
    "video": {
        "codec": "copy",
        "map": 0,
        "bitrate": 2750.40485,
        "crf": 23,
        "maxrate": "4.5m",
        "bufsize": "12m",
        "level": 0.0,
        "profile": null,
        "preset": null,
        "pix_fmt": null,
        "field_order": "progressive",
        "width": null,
        "filter": null,
        "params": null,
        "framedata": {
            "pix_fmt": "yuv420p"
        },
        "bsf": null,
        "debug": "video",
        "title": "HD"
    },
    "audio": [
        {
            "map": 1,
            "codec": "copy",
            "channels": 2,
            "bitrate": 256,
            "profile": null,
            "quality": 0,
            "filter": null,
            "samplerate": null,
            "sampleformat": "",
            "language": "eng",
            "disposition": "+default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions",
            "bsf": null,
            "debug": "universal-audio",
            "title": "Stereo"
        }
    ],
    "subtitle": [],
    "attachment": [],
    "sub-encoding": "UTF-8"
}
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Preopts
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - []
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Postopts
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - [
    "-threads",
    "0",
    "-metadata:g",
    "encoding_tool=SMA",
    "-max_muxing_queue_size",
    "9999"
]
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Subtitle Extracts
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - [
    {
        "source": [
            "/dwnldr/nzb/comp/tv/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat.mkv"
        ],
        "subtitle": [
            {
                "map": 2,
                "codec": "srt",
                "language": "eng",
                "debug": "subtitle"
            }
        ],
        "format": "srt",
        "disposition": "+default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions",
        "language": "eng",
        "index": 2
    }
]
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Ripping eng subtitle from source stream 2 into external file.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - /dwnldr/nzb/comp/tv/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat.eng.srt created.
2023-01-22 00:00:09 - NZBGetPostProcess - INFO - Starting conversion.
2023-01-22 00:00:10 - NZBGetPostProcess - INFO - FFmpeg command:
2023-01-22 00:00:10 - NZBGetPostProcess - INFO - ======================
2023-01-22 00:00:10 - NZBGetPostProcess - INFO - /usr/bin/ffmpeg -i /dwnldr/nzb/comp/tv/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat.mkv -vcodec copy -map 0:0 -metadata:s:v title=HD -metadata:s:v handler_name=HD -c:a:0 copy -map 0:1 -metadata:s:a:0 title=Stereo -metadata:s:a:0 handler_name=Stereo -metadata:s:a:0 language=eng -disposition:a:0 +default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions -f mp4 -threads 0 -metadata:g encoding_tool=SMA -max_muxing_queue_size 9999 -y /dwnldr/nzb/comp/tv/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat.mp4
2023-01-22 00:00:10 - NZBGetPostProcess - INFO - ======================
2023-01-22 00:00:10 - NZBGetPostProcess - INFO - 0
2023-01-22 00:00:11 - NZBGetPostProcess - INFO - 42
2023-01-22 00:00:11 - NZBGetPostProcess - INFO - 86
2023-01-22 00:00:11 - NZBGetPostProcess - INFO - 100
2023-01-22 00:00:11 - NZBGetPostProcess - INFO - /dwnldr/nzb/comp/tv/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat.mp4 created.
2023-01-22 00:00:11 - NZBGetPostProcess - INFO - Successfully processed /dwnldr/nzb/comp/tv/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat.mkv.
2023-01-22 00:00:11 - autoprocess.sonarr - INFO - [INFO] Sonarr notifier started.
2023-01-22 00:00:11 - autoprocess.sonarr - DEBUG - Sonarr host: localhost.
2023-01-22 00:00:11 - autoprocess.sonarr - DEBUG - Sonarr port: 8989.
2023-01-22 00:00:11 - autoprocess.sonarr - DEBUG - Sonarr webroot: .
2023-01-22 00:00:11 - autoprocess.sonarr - DEBUG - Sonarr apikey: 02dc28cfb7cb4a1f8cbb917fcc593d1c.
2023-01-22 00:00:11 - autoprocess.sonarr - DEBUG - Sonarr protocol: http://.
2023-01-22 00:00:11 - autoprocess.sonarr - DEBUG - URL 'http://localhost:8989/api/v3/command' with payload '{'name': 'DownloadedEpisodesScan', 'path': '/dwnldr/nzb/comp/tv/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat', 'importMode': 'Move'}.'
2023-01-22 00:00:11 - autoprocess.sonarr - INFO - [INFO] Requesting Sonarr to scan directory '/dwnldr/nzb/comp/tv/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat'.
2023-01-22 00:00:11 - autoprocess.sonarr - DEBUG - {'name': 'DownloadedEpisodesScan', 'commandName': 'Downloaded Episodes Scan', 'body': {'path': '/dwnldr/nzb/comp/tv/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat', 'importMode': 'move', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'DownloadedEpisodesScan', 'trigger': 'manual', 'suppressMessages': True, 'clientUserAgent': 'SMA - autoprocess/sonarr'}, 'priority': 'normal', 'status': 'started', 'queued': '2023-01-21T15:00:11.45838Z', 'started': '2023-01-21T15:00:11.46382Z', 'trigger': 'manual', 'clientUserAgent': 'SMA - autoprocess/sonarr', 'stateChangeTime': '2023-01-21T15:00:11.46382Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248340}
2023-01-22 00:00:11 - autoprocess.sonarr - INFO - [INFO] Sonarr response DownloadedEpisodesScan command: ID 3248340 started.
2023-01-22 00:00:19 - SonarrPostProcess - INFO - Sonarr extra script post processing started.
2023-01-22 00:00:19 - resources.readsettings - INFO - /usr/local/bin/python3.8
2023-01-22 00:00:19 - resources.readsettings - DEBUG - Loading default config file.
2023-01-22 00:00:19 - resources.readsettings - INFO - Loading config file /opt/mp4automator/config/autoProcess.ini.
2023-01-22 00:00:19 - SonarrPostProcess - DEBUG - environ({'sonarr_series_type': 'Standard', 'XDG_DATA_HOME': '/home/sonarr/.config/Sonarr/.config/share', 'sonarr_episodefile_episodeairdates': '2023-01-18', 'sonarr_episodefile_relativepath': 'SEASON 03/TRAFFICKED WITH MARIANA VAN ZELLER - S03E01 - [HULU][WEBDL-720P][AAC 2.0][H264]-WHITEHAT.mp4', 'SHELL': '/bin/bash', 'sonarr_download_id': '', 'No_Expand': 'true', 'sonarr_series_id': '558', 'No_SQLiteXmlConfigFile': 'true', 'sonarr_episodefile_releasegroup': 'WhiteHat', 'sonarr_deletedpaths': '/mnt/nas01/mm-tv01/TRAFFICKED WITH MARIANA VAN ZELLER (2020) {TVDB-390354}/SEASON 03/TRAFFICKED WITH MARIANA VAN ZELLER - S03E01 - [HULU][WEBDL-720P][AAC 2.0][H264]-WHITEHAT.rnm.mp4', 'sonarr_episodefile_sourcefolder': '/dwnldr/nzb/comp/tv/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat', 'sonarr_eventtype': 'Download', 'USER': 'sonarr', 'sonarr_download_client': '', 'sonarr_episodefile_episodetitles': 'Black Market Organs', 'sonarr_episodefile_qualityversion': '1', 'sonarr_isupgrade': 'True', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin', 'sonarr_episodefile_path': '/mnt/nas01/mm-tv01/TRAFFICKED WITH MARIANA VAN ZELLER (2020) {TVDB-390354}/SEASON 03/TRAFFICKED WITH MARIANA VAN ZELLER - S03E01 - [HULU][WEBDL-720P][AAC 2.0][H264]-WHITEHAT.mp4', 'sonarr_episodefile_episodeids': '68802', 'sonarr_series_title': 'Trafficked with Mariana van Zeller', 'PWD': '/', 'sonarr_download_client_type': '', 'LANG': 'en_US.UTF-8', 'sonarr_episodefile_episodenumbers': '1', 'sonarr_episodefile_scenename': 'Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat', 'sonarr_episodefile_quality': 'WEBDL-720p', 'SHLVL': '1', 'HOME': '/home/sonarr', 'sonarr_series_tvdbid': '390354', 'sonarr_series_tvmazeid': '49496', 'sonarr_episodefile_id': '90116', 'sonarr_series_path': '/mnt/nas01/mm-tv01/TRAFFICKED WITH MARIANA VAN ZELLER (2020) {TVDB-390354}', 'sonarr_episodefile_episodecount': '1', 'sonarr_episodefile_sourcepath': '/dwnldr/nzb/comp/tv/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat.mp4', 'LOGNAME': 'sonarr', 'sonarr_deletedrelativepaths': 'SEASON 03/TRAFFICKED WITH MARIANA VAN ZELLER - S03E01 - [HULU][WEBDL-720P][AAC 2.0][H264]-WHITEHAT.rnm.mp4', 'No_SQLiteFunctions': 'true', 'No_PreLoadSQLite': 'true', 'sonarr_episodefile_seasonnumber': '3', 'sonarr_series_imdbid': 'tt10370750', 'sonarr_episodefile_episodeairdatesutc': '1/19/2023 2:00:00 AM', '_': '/usr/local/bin/python3.8'})
2023-01-22 00:00:19 - SonarrPostProcess - DEBUG - Input file: /mnt/nas01/mm-tv01/TRAFFICKED WITH MARIANA VAN ZELLER (2020) {TVDB-390354}/SEASON 03/TRAFFICKED WITH MARIANA VAN ZELLER - S03E01 - [HULU][WEBDL-720P][AAC 2.0][H264]-WHITEHAT.mp4.
2023-01-22 00:00:19 - SonarrPostProcess - DEBUG - Original name: Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat.
2023-01-22 00:00:19 - SonarrPostProcess - DEBUG - TVDB ID: 390354.
2023-01-22 00:00:19 - SonarrPostProcess - DEBUG - Season: 3 episode: 1.
2023-01-22 00:00:19 - SonarrPostProcess - DEBUG - Sonarr series ID: 558.
2023-01-22 00:00:19 - SonarrPostProcess - DEBUG - Renaming file /mnt/nas01/mm-tv01/TRAFFICKED WITH MARIANA VAN ZELLER (2020) {TVDB-390354}/SEASON 03/TRAFFICKED WITH MARIANA VAN ZELLER - S03E01 - [HULU][WEBDL-720P][AAC 2.0][H264]-WHITEHAT.mp4 to /mnt/nas01/mm-tv01/TRAFFICKED WITH MARIANA VAN ZELLER (2020) {TVDB-390354}/SEASON 03/TRAFFICKED WITH MARIANA VAN ZELLER - S03E01 - [HULU][WEBDL-720P][AAC 2.0][H264]-WHITEHAT.rnm.mp4.
2023-01-22 00:00:19 - resources.mediaprocessor - INFO - Processing /mnt/nas01/mm-tv01/TRAFFICKED WITH MARIANA VAN ZELLER (2020) {TVDB-390354}/SEASON 03/TRAFFICKED WITH MARIANA VAN ZELLER - S03E01 - [HULU][WEBDL-720P][AAC 2.0][H264]-WHITEHAT.rnm.mp4.
2023-01-22 00:00:19 - resources.metadata - DEBUG - Input IDs:
2023-01-22 00:00:19 - resources.metadata - DEBUG - TMDBID: None
2023-01-22 00:00:19 - resources.metadata - DEBUG - IMDBID: tt10370750
2023-01-22 00:00:19 - resources.metadata - DEBUG - TVDBID: 390354
2023-01-22 00:00:20 - resources.metadata - DEBUG - Using TMDB ID: 113888
2023-01-22 00:00:20 - resources.metadata - DEBUG - Tagging language determined to be None.
2023-01-22 00:00:23 - resources.metadata - ERROR - Unable to retrieve rating.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Process started.
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Input Data
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - {
    "format": "mov,mp4,m4a,3gp,3g2,mj2",
    "format-fullname": "QuickTime / MOV",
    "video": {
        "index": 0,
        "codec": "h264",
        "bitrate": 2956306,
        "pix_fmt": "yuv420p",
        "profile": "high",
        "fps": 23.976023976023978,
        "framedata": {
            "pix_fmt": "yuv420p"
        },
        "dimensions": "1280x720",
        "level": 3.1,
        "field_order": "progressive"
    },
    "audio": [
        {
            "index": 1,
            "codec": "aac",
            "bitrate": 64000,
            "channels": 2,
            "samplerate": 48000,
            "language": "eng",
            "disposition": "+default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions"
        }
    ],
    "subtitle": [],
    "attachment": []
}
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Reading video stream.
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Video codec detected: h264.
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Pix Fmt: yuv420p.
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Profile: high.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Stream is not HDR, color parameter unknown does not match ['bt2020nc'] [hdr-['bt2020nc']].
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Pool of video codecs is ['h264_nvenc', 'h264', 'x264', 'h265_nvenc', 'hevc', 'h265', 'x265'].
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Total bitrate is 3025627.0.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Total audio bitrate is 64000.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Estimated video bitrate is 2961627.0.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Using video bitrate ratio of 1.000000, which results in 2813.545650 changing to 2813.545650.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - VCRF profiles detected [video-crf-profiles].
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Acceptable profile match found for VBR 2813.54565 using CRF 23, maxrate 4.5m, bufsize 12m.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Source bit-depth 8, output 0, using depth 8.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Video codec: copy.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Video bitrate: 2813.54565.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Video CRF: 23.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Video maxrate: 4.5m.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Video bufsize: 12m.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Video level: 0.0.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Video profile: None.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Video preset: None.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Video pix_fmt: None.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Video field order: progressive.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Video width: None.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Video debug video.
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Video codec parameters None.
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Creating copy video stream from source stream 0.
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Reading audio streams.
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - The following stream indexes have been identified as being copies: [] [stream-codec-combinations].
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Pool universal audio codecs is ['aac'].
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Pool of audio codecs is ['ac3', 'eac3'].
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Audio detected for stream 1 - aac eng 2 channel.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Overriding default channel settings because universal audio is enabled but the source is stereo [universal-audio].
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Audio codec: copy.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Channels: 2.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Bitrate: 256.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Audio Profile: None.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Language: eng.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Filter: None.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Disposition: +default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Debug: universal-audio.
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Creating copy audio stream from source stream 1.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Purging the following streams:
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - []
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Found 0 streams that can be removed from the output file since they will be duplicates [stream-codec-combinations].
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Triggering audio track sort [audio.sorting-sorting].
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Sorting streams with keys ['language', 'channels.a', 'map', 'd.comment'].
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Final sorting:
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - ['0->0']
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Sorting streams with keys ['channels.a', 'map', 'd.comment'].
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Final sorting:
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - ['0->0']
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Sorting audio streams for default audio stream designation.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - 1 total audio streams with 1 set to default disposition. 1 defaults in your preferred language (eng), 0 in other languages.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Default audio stream already inherited from source material, will not override to audio-language-default.
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Default audio stream set to eng copy 2 channel stream [audio-default-sorting: ['channels.a', 'map', 'd.comment']].
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Reading subtitle streams.
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Scanned for external subtitles and found 0 results in your approved languages.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Subtitle output is empty or no default subtitle language is set, will not pass over subtitle output to set a default stream.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Sorting streams with keys ['language', 'd.comment', 'd.default.d', 'd.forced.d'].
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Final sorting:
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - []
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Input and output extensions match and the file appears to have already been processed by SMA, enable force-convert to override [force-convert: False].
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Bypassing conversion and setting outputfile to inputfile.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - /mnt/nas01/mm-tv01/TRAFFICKED WITH MARIANA VAN ZELLER (2020) {TVDB-390354}/SEASON 03/TRAFFICKED WITH MARIANA VAN ZELLER - S03E01 - [HULU][WEBDL-720P][AAC 2.0][H264]-WHITEHAT.rnm.mp4 created from /mnt/nas01/mm-tv01/TRAFFICKED WITH MARIANA VAN ZELLER (2020) {TVDB-390354}/SEASON 03/TRAFFICKED WITH MARIANA VAN ZELLER - S03E01 - [HULU][WEBDL-720P][AAC 2.0][H264]-WHITEHAT.rnm.mp4 successfully.
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Height: 720
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Width: 1280
2023-01-22 00:00:23 - resources.mediaprocessor - DEBUG - Tag language setting is eng, using language eng for tagging.
2023-01-22 00:00:23 - resources.mediaprocessor - INFO - Tagging /mnt/nas01/mm-tv01/TRAFFICKED WITH MARIANA VAN ZELLER (2020) {TVDB-390354}/SEASON 03/TRAFFICKED WITH MARIANA VAN ZELLER - S03E01 - [HULU][WEBDL-720P][AAC 2.0][H264]-WHITEHAT.rnm.mp4 with TMDB ID 113888.
2023-01-22 00:00:23 - resources.metadata - INFO - Tagging file: /mnt/nas01/mm-tv01/TRAFFICKED WITH MARIANA VAN ZELLER (2020) {TVDB-390354}/SEASON 03/TRAFFICKED WITH MARIANA VAN ZELLER - S03E01 - [HULU][WEBDL-720P][AAC 2.0][H264]-WHITEHAT.rnm.mp4.
2023-01-22 00:00:23 - resources.metadata - INFO - Trying to write tags.
2023-01-22 00:00:24 - resources.metadata - INFO - Tags written successfully using mutagen.
2023-01-22 00:00:24 - resources.mediaprocessor - INFO - Relocating MOOV atom to start of file.
2023-01-22 00:00:24 - qtfaststart - DEBUG - Getting index of top level atoms...
2023-01-22 00:00:24 - qtfaststart - DEBUG - ftyp: 32
2023-01-22 00:00:24 - qtfaststart - DEBUG - free: 8
2023-01-22 00:00:24 - qtfaststart - DEBUG - mdat: 1001729910
2023-01-22 00:00:24 - qtfaststart - DEBUG - moov: 1812096
2023-01-22 00:00:24 - qtfaststart - INFO - Removing free atom at 32 (8 bytes)
2023-01-22 00:00:24 - qtfaststart - INFO - Patching stco with 62188 entries
2023-01-22 00:00:24 - qtfaststart - INFO - Patching stco with 62188 entries
2023-01-22 00:00:24 - qtfaststart - INFO - Writing output...
2023-01-22 00:00:24 - qtfaststart - DEBUG - Writing ftyp... (32 bytes)
2023-01-22 00:00:24 - qtfaststart - DEBUG - Writing moov... (1812096 bytes)
2023-01-22 00:00:24 - qtfaststart - DEBUG - Writing mdat... (1001729910 bytes)
2023-01-22 00:00:29 - resources.mediaprocessor - DEBUG - Final output file: /mnt/nas01/mm-tv01/TRAFFICKED WITH MARIANA VAN ZELLER (2020) {TVDB-390354}/SEASON 03/TRAFFICKED WITH MARIANA VAN ZELLER - S03E01 - [HULU][WEBDL-720P][AAC 2.0][H264]-WHITEHAT.rnm.mp4.
2023-01-22 00:00:29 - SonarrPostProcess - DEBUG - Sonarr baseURL: http://localhost:8989.
2023-01-22 00:00:29 - SonarrPostProcess - DEBUG - Sonarr apikey: 02dc28cfb7cb4a1f8cbb917fcc593d1c.
2023-01-22 00:00:29 - SonarrPostProcess - DEBUG - Requesting commands in process from Sonarr via http://localhost:8989/api/v3/command.
2023-01-22 00:00:29 - SonarrPostProcess - DEBUG - [{'name': 'DownloadedEpisodesScan', 'commandName': 'Downloaded Episodes Scan', 'body': {'path': '/dwnldr/nzb/comp/tv/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat', 'importMode': 'move', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'DownloadedEpisodesScan', 'trigger': 'manual', 'suppressMessages': True, 'clientUserAgent': 'SMA - autoprocess/sonarr'}, 'priority': 'normal', 'status': 'started', 'queued': '2023-01-21T15:00:11.45838Z', 'started': '2023-01-21T15:00:11.46382Z', 'trigger': 'manual', 'clientUserAgent': 'SMA - autoprocess/sonarr', 'stateChangeTime': '2023-01-21T15:00:11.46382Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248340}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'lastExecutionTime': '2023-01-21T14:52:34.604215Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T14:54:03.974785Z', 'started': '2023-01-21T14:54:03.979148Z', 'ended': '2023-01-21T14:54:04.678682Z', 'duration': '00:00:00.6995340', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T14:54:03.979148Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T14:52:34.604215Z', 'id': 3248328}, {'name': 'ProcessMonitoredDownloads', 'commandName': 'Process Monitored Downloads', 'message': 'Completed', 'body': {'requiresDiskAccess': True, 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'ProcessMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T14:54:04.67104Z', 'started': '2023-01-21T14:54:04.678701Z', 'ended': '2023-01-21T14:54:04.70517Z', 'duration': '00:00:00.0264690', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T14:54:04.678701Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248329}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'lastExecutionTime': '2023-01-21T14:54:04.684134Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T14:55:33.98073Z', 'started': '2023-01-21T14:55:33.984312Z', 'ended': '2023-01-21T14:55:34.657094Z', 'duration': '00:00:00.6727820', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T14:55:33.984312Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T14:54:04.684134Z', 'id': 3248330}, {'name': 'ProcessMonitoredDownloads', 'commandName': 'Process Monitored Downloads', 'message': 'Completed', 'body': {'requiresDiskAccess': True, 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'ProcessMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T14:55:34.653824Z', 'started': '2023-01-21T14:55:34.657166Z', 'ended': '2023-01-21T14:55:34.782826Z', 'duration': '00:00:00.1256600', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T14:55:34.657166Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248331}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'lastExecutionTime': '2023-01-21T14:55:34.662359Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T14:57:03.985693Z', 'started': '2023-01-21T14:57:03.991225Z', 'ended': '2023-01-21T14:57:04.675511Z', 'duration': '00:00:00.6842860', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T14:57:03.991225Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T14:55:34.662359Z', 'id': 3248332}, {'name': 'ProcessMonitoredDownloads', 'commandName': 'Process Monitored Downloads', 'message': 'Completed', 'body': {'requiresDiskAccess': True, 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'ProcessMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T14:57:04.670267Z', 'started': '2023-01-21T14:57:04.675533Z', 'ended': '2023-01-21T14:57:04.685834Z', 'duration': '00:00:00.0103010', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T14:57:04.675533Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248333}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'lastExecutionTime': '2023-01-21T14:57:04.679857Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T14:58:33.996836Z', 'started': '2023-01-21T14:58:34.000406Z', 'ended': '2023-01-21T14:58:34.67345Z', 'duration': '00:00:00.6730440', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T14:58:34.000406Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T14:57:04.679857Z', 'id': 3248335}, {'name': 'ProcessMonitoredDownloads', 'commandName': 'Process Monitored Downloads', 'message': 'Completed', 'body': {'requiresDiskAccess': True, 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'ProcessMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T14:58:34.66378Z', 'started': '2023-01-21T14:58:34.673521Z', 'ended': '2023-01-21T14:58:34.774364Z', 'duration': '00:00:00.1008430', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T14:58:34.673521Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248336}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T14:59:51.329867Z', 'started': '2023-01-21T14:59:51.35758Z', 'ended': '2023-01-21T14:59:52.330774Z', 'duration': '00:00:00.9731940', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T14:59:51.35758Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248338}, {'name': 'ProcessMonitoredDownloads', 'commandName': 'Process Monitored Downloads', 'message': 'Completed', 'body': {'requiresDiskAccess': True, 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'ProcessMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T14:59:52.041123Z', 'started': '2023-01-21T14:59:52.330803Z', 'ended': '2023-01-21T14:59:52.467049Z', 'duration': '00:00:00.1362460', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T14:59:52.330803Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248339}, {'name': 'MessagingCleanup', 'commandName': 'Messaging Cleanup', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'MessagingCleanup', 'lastExecutionTime': '2023-01-21T14:52:33.992957Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'low', 'status': 'completed', 'queued': '2023-01-21T14:58:03.992309Z', 'started': '2023-01-21T14:58:03.995964Z', 'ended': '2023-01-21T14:58:04.013807Z', 'duration': '00:00:00.0178430', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T14:58:03.995964Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T14:52:33.992957Z', 'id': 3248334}, {'name': 'RssSync', 'commandName': 'Rss Sync', 'message': 'Completed', 'body': {'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RssSync', 'lastExecutionTime': '2023-01-21T14:44:33.403691Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'low', 'status': 'completed', 'queued': '2023-01-21T14:59:34.001836Z', 'started': '2023-01-21T14:59:34.007691Z', 'ended': '2023-01-21T14:59:46.33752Z', 'duration': '00:00:12.3298290', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T14:59:34.007691Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T14:44:33.403691Z', 'id': 3248337}]
2023-01-22 00:00:29 - SonarrPostProcess - DEBUG - /dwnldr/nzb/comp/tv/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat
2023-01-22 00:00:29 - SonarrPostProcess - DEBUG - Found a matching path scan in progress /dwnldr/nzb/comp/tv/Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat.
2023-01-22 00:00:29 - SonarrPostProcess - INFO - DownloadedEpisodesScan command is in process for this episode, cannot wait for rescan but will queue.
2023-01-22 00:00:29 - SonarrPostProcess - DEBUG - Queueing rescan command to Sonarr via http://localhost:8989/api/v3/command.
2023-01-22 00:00:29 - SonarrPostProcess - DEBUG - {'name': 'RescanSeries', 'seriesId': 558}
2023-01-22 00:00:29 - SonarrPostProcess - DEBUG - {'name': 'RescanSeries', 'commandName': 'Rescan Series', 'body': {'seriesId': 558, 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RescanSeries', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'SMA - postSonarr'}, 'priority': 'normal', 'status': 'queued', 'queued': '2023-01-21T15:00:29.050302Z', 'trigger': 'manual', 'clientUserAgent': 'SMA - postSonarr', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248341}
2023-01-22 00:00:29 - SonarrPostProcess - INFO - Sonarr response from RescanSeries command: ID 3248341 queued.
2023-01-22 00:00:29 - SonarrPostProcess - DEBUG - Requesting command status from Sonarr for command ID 3248341.
2023-01-22 00:00:29 - SonarrPostProcess - DEBUG - {'name': 'RescanSeries', 'commandName': 'Rescan Series', 'body': {'seriesId': 558, 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RescanSeries', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'SMA - postSonarr'}, 'priority': 'normal', 'status': 'started', 'queued': '2023-01-21T15:00:29.050302Z', 'started': '2023-01-21T15:00:29.055896Z', 'trigger': 'manual', 'clientUserAgent': 'SMA - postSonarr', 'stateChangeTime': '2023-01-21T15:00:29.055896Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248341}
2023-01-22 00:00:29 - SonarrPostProcess - DEBUG - Final status: started.
2023-01-22 00:00:29 - SonarrPostProcess - DEBUG - Queueing rename command to Sonarr via http://localhost:8989/api/v3/command.
2023-01-22 00:00:29 - SonarrPostProcess - DEBUG - {'name': 'RenameFiles', 'files': ['90116'], 'seriesId': 558}

The Sonarr log to go with the above sma.log snippet before your update:

2023-01-21 23:44:33.2|Info|Nzbget|Adding report [Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat] to the queue.
2023-01-21 23:44:33.3|Info|DownloadService|Report sent to NZBGet. Trafficked.with.Mariana.van.Zeller.S03E01.Black.Market.Organs.720p.HULU.WEB-DL.AAC2.0.H264-WhiteHat
2023-01-21 23:44:33.3|Info|RssSyncService|RSS Sync Completed. Reports found: 669, Reports grabbed: 1
2023-01-21 23:45:16.2|Info|DiskScanService|Scanning Trafficked with Mariana van Zeller
2023-01-21 23:45:16.2|Info|RenameEpisodeFileService|Renaming 1 files for Trafficked with Mariana van Zeller
2023-01-21 23:45:16.2|Error|RenameEpisodeFileService|Failed to rename file /mnt/nas01/mm-tv01/TRAFFICKED WITH MARIANA VAN ZELLER (2020) {TVDB-390354}/SEASON 03/TRAFFICKED WITH MARIANA VAN ZELLER - S03E01 - [HULU][WEBDL-720P][AAC 2.0][H264]-WHITEHAT.mp4

[v3.0.9.1549] System.IO.FileNotFoundException: Episode file path does not exist
File name: '/mnt/nas01/mm-tv01/TRAFFICKED WITH MARIANA VAN ZELLER (2020) {TVDB-390354}/SEASON 03/TRAFFICKED WITH MARIANA VAN ZELLER - S03E01 - [HULU][WEBDL-720P][AAC 2.0][H264]-WHITEHAT.mp4'
  at NzbDrone.Core.MediaFiles.EpisodeFileMovingService.TransferFile (NzbDrone.Core.MediaFiles.EpisodeFile episodeFile, NzbDrone.Core.Tv.Series series, System.Collections.Generic.List`1[T] episodes, System.String destinationFilePath, NzbDrone.Common.Disk.TransferMode mode) [0x00103] in <0b43b6e47ef8497b83f58230dd1ec559>:0
  at NzbDrone.Core.MediaFiles.EpisodeFileMovingService.MoveEpisodeFile (NzbDrone.Core.MediaFiles.EpisodeFile episodeFile, NzbDrone.Core.Tv.Series series) [0x00073] in <0b43b6e47ef8497b83f58230dd1ec559>:0
  at NzbDrone.Core.MediaFiles.RenameEpisodeFileService.RenameFiles (System.Collections.Generic.List`1[T] episodeFiles, NzbDrone.Core.Tv.Series series) [0x00045] in <0b43b6e47ef8497b83f58230dd1ec559>:0

2023-01-21 23:45:16.2|Info|RenameEpisodeFileService|Selected episode files renamed for Trafficked with Mariana van Zeller
2023-01-21 23:45:16.6|Info|DiskScanService|Completed scanning disk for Trafficked with Mariana van Zeller
2023-01-21 23:45:16.6|Info|ExistingMetadataImporter|Found 0 existing metadata files
2023-01-21 23:45:16.6|Info|ExistingSubtitleImporter|Found 0 existing subtitle files
2023-01-21 23:45:16.7|Info|ExistingOtherExtraImporter|Found 0 existing other extra files
2023-01-21 23:45:16.7|Info|ExistingExtraFileService|Found 18 possible extra files, imported 18 files.

[johnwchambers]

I may have gotten ahead of myself, looks like it succeeded on the previous episode manual import but not on this automatic one below.

Sonarr.txt log snippet:

2023-01-22 02:37:43.2|Info|RssSyncService|Starting RSS Sync
2023-01-22 02:37:43.2|Warn|Torznab|Indexer Jackett/XSpeeds rss sync didn't cover the period between 1/21/2023 5:06:44 PM and 1/21/2023 5:06:44 PM UTC. Search may be required.
2023-01-22 02:37:46.9|Info|DownloadDecisionMaker|Processing 669 releases
2023-01-22 02:38:44.1|Info|RssSyncService|RSS Sync Completed. Reports found: 669, Reports grabbed: 0
2023-01-22 02:39:13.2|Info|DiskScanService|Scanning The L Word: Generation Q
2023-01-22 02:39:13.2|Info|RenameEpisodeFileService|Renaming all files in series: The L Word: Generation Q
2023-01-22 02:39:13.4|Error|RenameEpisodeFileService|Failed to rename file /mnt/nas01/mm-tv01/THE L WORD - GENERATION Q (2019) {TVDB-364477}/SEASON 03/THE L WORD - GENERATION Q - S03E10 - [AMZN][WEBDL-1080P][AAC 2.0][H264]-NTB.mp4

[v3.0.9.1549] System.InvalidOperationException: Sequence contains no elements
  at System.Linq.Enumerable.First[TSource] (System.Collections.Generic.IEnumerable`1[T] source) [0x00010] in <d9f4d70345484e55bbf6c1ef353f07c4>:0
  at NzbDrone.Core.Organizer.FileNameBuilder.BuildFilePath (System.Collections.Generic.List`1[T] episodes, NzbDrone.Core.Tv.Series series, NzbDrone.Core.MediaFiles.EpisodeFile episodeFile, System.String extension, NzbDrone.Core.Organizer.NamingConfig namingConfig, NzbDrone.Core.Profiles.Releases.PreferredWordMatchResults preferredWords) [0x00048] in <0b43b6e47ef8497b83f58230dd1ec559>:0
  at NzbDrone.Core.MediaFiles.EpisodeFileMovingService.MoveEpisodeFile (NzbDrone.Core.MediaFiles.EpisodeFile episodeFile, NzbDrone.Core.Tv.Series series) [0x00026] in <0b43b6e47ef8497b83f58230dd1ec559>:0
  at NzbDrone.Core.MediaFiles.RenameEpisodeFileService.RenameFiles (System.Collections.Generic.List`1[T] episodeFiles, NzbDrone.Core.Tv.Series series) [0x00045] in <0b43b6e47ef8497b83f58230dd1ec559>:0

2023-01-22 02:39:13.4|Info|RenameEpisodeFileService|All episode files renamed for The L Word: Generation Q
2023-01-22 02:39:14.1|Info|DiskScanService|Completed scanning disk for The L Word: Generation Q
2023-01-22 02:39:14.1|Info|ExistingMetadataImporter|Found 0 existing metadata files
2023-01-22 02:39:14.2|Info|ExistingSubtitleImporter|Found 0 existing subtitle files
2023-01-22 02:39:14.2|Info|ExistingOtherExtraImporter|Found 0 existing other extra files
2023-01-22 02:39:14.2|Info|ExistingExtraFileService|Found 46 possible extra files, imported 46 files.

Sma.log log snippet:

2023-01-22 02:35:17 - resources.readsettings - INFO - /usr/local/bin/python3.8
2023-01-22 02:35:17 - resources.readsettings - DEBUG - Configuration file specified is a directory, joining with autoProcess.ini.
2023-01-22 02:35:17 - resources.readsettings - INFO - Loading config file /opt/mp4automator/config/autoProcess.ini.
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Processing file /dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb.mkv.
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Input Data
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - {
    "format": "matroska,webm",
    "format-fullname": "Matroska / WebM",
    "video": {
        "index": 0,
        "codec": "h264",
        "bitrate": 9174668,
        "pix_fmt": "yuv420p",
        "profile": "high",
        "fps": 23.976023976023978,
        "framedata": {
            "pix_fmt": "yuv420p",
            "color_space": "bt709",
            "color_primaries": "bt709",
            "color_transfer": "bt709"
        },
        "dimensions": "1920x1080",
        "level": 4.0,
        "field_order": "progressive"
    },
    "audio": [
        {
            "index": 1,
            "codec": "eac3",
            "bitrate": 640000,
            "channels": 6,
            "samplerate": 48000,
            "language": "eng",
            "disposition": "+default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions"
        }
    ],
    "subtitle": [
        {
            "index": 2,
            "codec": "subrip",
            "disposition": "-default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions",
            "language": "eng"
        },
        {
            "index": 3,
            "codec": "subrip",
            "disposition": "-default-dub-original-comment-lyrics-karaoke-forced+hearing_impaired-visual_impaired-captions",
            "language": "eng"
        }
    ],
    "attachment": []
}
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Reading video stream.
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Video codec detected: h264.
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Pix Fmt: yuv420p.
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Profile: high.
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Acceptable profile match found for VBR 8718.235499999999 using CRF 17, maxrate 10m, bufsize 30m.
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Video codec parameters None.
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Creating copy video stream from source stream 0.
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Reading audio streams.
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - The following stream indexes have been identified as being copies: [] [stream-codec-combinations].
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Audio detected for stream 1 - eac3 eng 6 channel.
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Creating copy audio stream from source stream 1.
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Creating aac audio stream from source audio stream 1 [universal-audio].
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Found 0 streams that can be removed from the output file since they will be duplicates [stream-codec-combinations].
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Final sorting:
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - ['0->1', '1->0']
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Final sorting:
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - ['0->0', '1->1']
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Default audio stream set to eng aac 2 channel stream [audio-default-sorting: ['channels.a', 'map', 'd.comment']].
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Reading subtitle streams.
2023-01-22 02:35:17 - NZBGetPostProcess - INFO - Subtitle detected for stream 2 - subrip eng.
2023-01-22 02:35:18 - NZBGetPostProcess - INFO - Stream 2 is text-based subtitle for codec subrip.
2023-01-22 02:35:18 - NZBGetPostProcess - INFO - Subtitle detected for stream 3 - subrip eng.
2023-01-22 02:35:18 - resources.metadata - INFO - Trying to write tags.
2023-01-22 02:35:18 - NZBGetPostProcess - INFO - Stream 3 is text-based subtitle for codec subrip.
2023-01-22 02:35:18 - NZBGetPostProcess - INFO - Scanned for external subtitles and found 0 results in your approved languages.
2023-01-22 02:35:18 - NZBGetPostProcess - INFO - Final sorting:
2023-01-22 02:35:18 - NZBGetPostProcess - INFO - []
2023-01-22 02:35:18 - NZBGetPostProcess - INFO - Output Data
2023-01-22 02:35:18 - NZBGetPostProcess - INFO - {
    "source": [
        "/dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb.mkv"
    ],
    "format": "mp4",
    "video": {
        "codec": "copy",
        "map": 0,
        "bitrate": 8718.235499999999,
        "crf": 17,
        "maxrate": "10m",
        "bufsize": "30m",
        "level": 0.0,
        "profile": null,
        "preset": null,
        "pix_fmt": null,
        "field_order": "progressive",
        "width": null,
        "filter": null,
        "params": null,
        "framedata": {
            "pix_fmt": "yuv420p",
            "color_space": "bt709",
            "color_primaries": "bt709",
            "color_transfer": "bt709"
        },
        "bsf": null,
        "debug": "video",
        "title": "FHD"
    },
    "audio": [
        {
            "map": 1,
            "codec": "aac",
            "channels": 2,
            "bitrate": 256,
            "quality": 0,
            "profile": null,
            "samplerate": null,
            "sampleformat": "",
            "filter": "pan=stereo|FL=0.5*FC+0.707*FL+0.707*BL+0.5*LFE|FR=0.5*FC+0.707*FR+0.707*BR+0.5*LFE",
            "language": "eng",
            "disposition": "+default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions",
            "debug": "universal-audio",
            "title": "Stereo"
        },
        {
            "map": 1,
            "codec": "copy",
            "channels": 6,
            "bitrate": 768,
            "profile": null,
            "quality": 0,
            "filter": null,
            "samplerate": null,
            "sampleformat": "",
            "language": "eng",
            "disposition": "-default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions",
            "bsf": null,
            "debug": "audio",
            "title": "5.1 Channel"
        }
    ],
    "subtitle": [],
    "attachment": [],
    "sub-encoding": "UTF-8"
}
2023-01-22 02:35:18 - NZBGetPostProcess - INFO - Preopts
2023-01-22 02:35:18 - NZBGetPostProcess - INFO - []
2023-01-22 02:35:18 - NZBGetPostProcess - INFO - Postopts
2023-01-22 02:35:18 - NZBGetPostProcess - INFO - [
    "-threads",
    "0",
    "-metadata:g",
    "encoding_tool=SMA",
    "-max_muxing_queue_size",
    "9999"
]
2023-01-22 02:35:18 - NZBGetPostProcess - INFO - Subtitle Extracts
2023-01-22 02:35:18 - NZBGetPostProcess - INFO - [
    {
        "source": [
            "/dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb.mkv"
        ],
        "subtitle": [
            {
                "map": 2,
                "codec": "srt",
                "language": "eng",
                "debug": "subtitle"
            }
        ],
        "format": "srt",
        "disposition": "-default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions",
        "language": "eng",
        "index": 2
    },
    {
        "source": [
            "/dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb.mkv"
        ],
        "subtitle": [
            {
                "map": 3,
                "codec": "srt",
                "language": "eng",
                "debug": "subtitle"
            }
        ],
        "format": "srt",
        "disposition": "-default-dub-original-comment-lyrics-karaoke-forced+hearing_impaired-visual_impaired-captions",
        "language": "eng",
        "index": 3
    }
]
2023-01-22 02:35:18 - NZBGetPostProcess - INFO - Ripping eng subtitle from source stream 2 into external file.
2023-01-22 02:35:18 - resources.metadata - INFO - Tags written successfully using mutagen.
2023-01-22 02:35:18 - resources.mediaprocessor - INFO - Relocating MOOV atom to start of file.
2023-01-22 02:35:18 - qtfaststart - DEBUG - Getting index of top level atoms...
2023-01-22 02:35:18 - qtfaststart - DEBUG - ftyp: 36
2023-01-22 02:35:18 - qtfaststart - DEBUG - mdat: 7791816415
2023-01-22 02:35:18 - qtfaststart - DEBUG - moov: 30038036
2023-01-22 02:35:18 - qtfaststart - INFO - Patching co64 with 156389 entries
2023-01-22 02:35:18 - qtfaststart - INFO - Patching co64 with 305769 entries
2023-01-22 02:35:18 - qtfaststart - INFO - Patching co64 with 203845 entries
2023-01-22 02:35:18 - qtfaststart - INFO - Patching co64 with 305769 entries
2023-01-22 02:35:19 - qtfaststart - INFO - Patching co64 with 203845 entries
2023-01-22 02:35:19 - qtfaststart - INFO - Patching co64 with 305769 entries
2023-01-22 02:35:19 - qtfaststart - INFO - Patching co64 with 203845 entries
2023-01-22 02:35:19 - qtfaststart - INFO - Patching co64 with 305769 entries
2023-01-22 02:35:19 - qtfaststart - INFO - Patching co64 with 203845 entries
2023-01-22 02:35:19 - qtfaststart - INFO - Patching co64 with 305769 entries
2023-01-22 02:35:19 - qtfaststart - INFO - Patching co64 with 203845 entries
2023-01-22 02:35:19 - qtfaststart - INFO - Writing output...
2023-01-22 02:35:19 - qtfaststart - DEBUG - Writing ftyp... (36 bytes)
2023-01-22 02:35:19 - qtfaststart - DEBUG - Writing moov... (30038036 bytes)
2023-01-22 02:35:19 - qtfaststart - DEBUG - Writing mdat... (7791816415 bytes)
2023-01-22 02:35:19 - NZBGetPostProcess - INFO - /dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb.eng.srt created.
2023-01-22 02:35:19 - NZBGetPostProcess - INFO - Ripping eng subtitle from source stream 3 into external file.
2023-01-22 02:35:20 - NZBGetPostProcess - INFO - /dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb.eng.2.srt created.
2023-01-22 02:35:21 - NZBGetPostProcess - INFO - Starting conversion.
2023-01-22 02:35:21 - NZBGetPostProcess - INFO - FFmpeg command:
2023-01-22 02:35:21 - NZBGetPostProcess - INFO - ======================
2023-01-22 02:35:21 - NZBGetPostProcess - INFO - /usr/bin/ffmpeg -i /dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb.mkv -vcodec copy -map 0:0 -metadata:s:v title=FHD -metadata:s:v handler_name=FHD -c:a:0 aac -map 0:1 -ac:a:0 2 -b:a:0 256k -metadata:s:a:0 BPS=256000 -metadata:s:a:0 BPS-eng=256000 -filter:a:0 "pan=stereo|FL=0.5*FC+0.707*FL+0.707*BL+0.5*LFE|FR=0.5*FC+0.707*FR+0.707*BR+0.5*LFE" -metadata:s:a:0 title=Stereo -metadata:s:a:0 handler_name=Stereo -metadata:s:a:0 language=eng -disposition:a:0 +default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions -strict experimental -c:a:1 copy -map 0:1 -metadata:s:a:1 "title=5.1 Channel" -metadata:s:a:1 "handler_name=5.1 Channel" -metadata:s:a:1 language=eng -disposition:a:1 -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions -f mp4 -threads 0 -metadata:g encoding_tool=SMA -max_muxing_queue_size 9999 -y /dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb.mp4
2023-01-22 02:35:21 - NZBGetPostProcess - INFO - ======================
2023-01-22 02:35:21 - NZBGetPostProcess - INFO - 0
2023-01-22 02:35:22 - NZBGetPostProcess - INFO - 0
2023-01-22 02:35:22 - NZBGetPostProcess - INFO - 0
2023-01-22 02:35:23 - NZBGetPostProcess - INFO - 0
2023-01-22 02:36:13 - resources.mediaprocessor - DEBUG - Final output file: /mnt/nas01/mm-movies01/BLEACH [2018]/BLEACH [2018] - [HDTV-1080P][AAC 2.0][X264][IMDB-TT5979872][TMDB-420426]-DRAGSTERPS.rnm.mp4.
2023-01-22 02:36:13 - RadarrPostProcess - DEBUG - Radarr baseURL: http://localhost:7878.
2023-01-22 02:36:13 - RadarrPostProcess - DEBUG - Radarr apikey: 60c905f957d44413b90e50303744aad2.
2023-01-22 02:36:13 - RadarrPostProcess - DEBUG - Requesting commands in process from Radarr via http://localhost:7878/api/v3/command.
2023-01-22 02:36:13 - RadarrPostProcess - DEBUG - [{'name': 'ManualImport', 'commandName': 'Manual Import', 'message': 'Processing file 2 of 2', 'body': {'files': [{'path': '/dwnldr/tor/converted/Raging.Fire.1080p.AMZN.WEB-DL.DDP5.1.H.264.mkv/Raging.Fire.1080p.AMZN.WEB-DL.DDP5.1.H.264.mp4', 'quality': {'quality': {'id': 3, 'name': 'WEBDL-1080p', 'source': 'webdl', 'resolution': 1080, 'modifier': 'none'}, 'revision': {'version': 1, 'real': 0, 'isRepack': False}}, 'languages': [{'id': 1, 'name': 'English'}], 'movieId': 7935}, {'path': '/dwnldr/tor/converted/_DragsterPS_ Bleach _1080p_ _Multi-Audio_ _Multi-Subs_ _82F7CE55_.mkv/[DragsterPS] Bleach [1080p] [Multi-Audio] [Multi-Subs] [82F7CE55].mp4', 'quality': {'quality': {'id': 9, 'name': 'HDTV-1080p', 'source': 'tv', 'resolution': 1080, 'modifier': 'none'}, 'revision': {'version': 1, 'real': 0, 'isRepack': False}}, 'languages': [{'id': 1, 'name': 'English'}, {'id': 4, 'name': 'German'}, {'id': 21, 'name': 'Korean'}, {'id': 8, 'name': 'Japanese'}], 'releaseGroup': 'DragsterPS', 'movieId': 7841}], 'sendUpdatesToClient': True, 'requiresDiskAccess': True, 'importMode': 'move', 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'isTypeExclusive': False, 'isLongRunning': False, 'name': 'ManualImport', 'trigger': 'manual', 'suppressMessages': False}, 'priority': 'normal', 'status': 'started', 'queued': '2023-01-21T17:32:21Z', 'started': '2023-01-21T17:32:21Z', 'trigger': 'manual', 'stateChangeTime': '2023-01-21T17:32:21Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 803312}, {'name': 'Backup', 'commandName': 'Backup', 'message': 'Creating backup zip', 'body': {'type': 'scheduled', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'isTypeExclusive': False, 'isLongRunning': False, 'name': 'Backup', 'lastExecutionTime': '2023-01-14T17:35:51Z', 'lastStartTime': '2023-01-14T17:35:24Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'low', 'status': 'started', 'queued': '2023-01-21T17:35:51Z', 'started': '2023-01-21T17:35:52Z', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T17:35:52Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-14T17:35:51Z', 'id': 803325}, {'name': 'ProcessMonitoredDownloads', 'commandName': 'Process Monitored Downloads', 'body': {'requiresDiskAccess': True, 'isLongRunning': True, 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'isTypeExclusive': False, 'name': 'ProcessMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'queued', 'queued': '2023-01-21T17:32:22Z', 'trigger': 'unspecified', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 803314}, {'name': 'RenameFiles', 'commandName': 'Rename Files', 'body': {'movieId': 7913, 'files': [13230], 'sendUpdatesToClient': True, 'requiresDiskAccess': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'isTypeExclusive': False, 'isLongRunning': False, 'name': 'RenameFiles', 'trigger': 'manual', 'suppressMessages': False}, 'priority': 'normal', 'status': 'queued', 'queued': '2023-01-21T17:32:55Z', 'trigger': 'manual', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 803315}, {'name': 'RenameFiles', 'commandName': 'Rename Files', 'body': {'movieId': 7935, 'files': [13232], 'sendUpdatesToClient': True, 'requiresDiskAccess': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'isTypeExclusive': False, 'isLongRunning': False, 'name': 'RenameFiles', 'trigger': 'manual', 'suppressMessages': False}, 'priority': 'normal', 'status': 'queued', 'queued': '2023-01-21T17:34:16Z', 'trigger': 'manual', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 803319}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'isTypeExclusive': False, 'isLongRunning': False, 'name': 'RefreshMonitoredDownloads', 'lastExecutionTime': '2023-01-21T17:29:22Z', 'lastStartTime': '2023-01-21T17:29:21Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T17:30:51Z', 'started': '2023-01-21T17:30:51Z', 'ended': '2023-01-21T17:30:52Z', 'duration': '00:00:00.1967312', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T17:30:51Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T17:29:22Z', 'id': 803310}, {'name': 'ProcessMonitoredDownloads', 'commandName': 'Process Monitored Downloads', 'message': 'Completed', 'body': {'requiresDiskAccess': True, 'isLongRunning': True, 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'isTypeExclusive': False, 'name': 'ProcessMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T17:30:52Z', 'started': '2023-01-21T17:30:52Z', 'ended': '2023-01-21T17:30:52Z', 'duration': '00:00:00.1423477', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T17:30:52Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 803311}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'isTypeExclusive': False, 'isLongRunning': False, 'name': 'RefreshMonitoredDownloads', 'lastExecutionTime': '2023-01-21T17:30:52Z', 'lastStartTime': '2023-01-21T17:30:51Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T17:32:21Z', 'started': '2023-01-21T17:32:21Z', 'ended': '2023-01-21T17:32:22Z', 'duration': '00:00:00.2724610', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T17:32:21Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T17:30:52Z', 'id': 803313}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'isTypeExclusive': False, 'isLongRunning': False, 'name': 'RefreshMonitoredDownloads', 'lastExecutionTime': '2023-01-21T17:32:22Z', 'lastStartTime': '2023-01-21T17:32:21Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T17:33:51Z', 'started': '2023-01-21T17:33:51Z', 'ended': '2023-01-21T17:33:52Z', 'duration': '00:00:00.2995264', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T17:33:51Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T17:32:22Z', 'id': 803316}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'isTypeExclusive': False, 'isLongRunning': False, 'name': 'RefreshMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T17:34:12Z', 'started': '2023-01-21T17:34:12Z', 'ended': '2023-01-21T17:34:12Z', 'duration': '00:00:00.1429136', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T17:34:12Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 803318}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'isTypeExclusive': False, 'isLongRunning': False, 'name': 'RefreshMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T17:34:21Z', 'started': '2023-01-21T17:34:21Z', 'ended': '2023-01-21T17:34:22Z', 'duration': '00:00:00.1467578', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T17:34:21Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 803320}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'isTypeExclusive': False, 'isLongRunning': False, 'name': 'RefreshMonitoredDownloads', 'lastExecutionTime': '2023-01-21T17:34:22Z', 'lastStartTime': '2023-01-21T17:34:21Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T17:35:51Z', 'started': '2023-01-21T17:35:51Z', 'ended': '2023-01-21T17:35:52Z', 'duration': '00:00:00.1486888', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T17:35:51Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T17:34:22Z', 'id': 803324}, {'name': 'RescanMovie', 'commandName': 'Rescan Movie', 'message': 'Completed', 'body': {'movieId': 7935, 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'isTypeExclusive': False, 'isLongRunning': False, 'name': 'RescanMovie', 'trigger': 'manual', 'suppressMessages': False}, 'priority': 'normal', 'status': 'completed', 'queued': '2023-01-21T17:34:06Z', 'started': '2023-01-21T17:34:06Z', 'ended': '2023-01-21T17:34:07Z', 'duration': '00:00:01.3222793', 'trigger': 'manual', 'stateChangeTime': '2023-01-21T17:34:06Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 803317}, {'name': 'RefreshMovie', 'commandName': 'Refresh Movie', 'message': 'Completed', 'body': {'movieIds': [7913], 'isNewMovie': False, 'sendUpdatesToClient': True, 'updateScheduledTask': False, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'isTypeExclusive': False, 'isLongRunning': False, 'name': 'RefreshMovie', 'trigger': 'manual', 'suppressMessages': False}, 'priority': 'normal', 'status': 'completed', 'queued': '2023-01-21T17:34:45Z', 'started': '2023-01-21T17:34:45Z', 'ended': '2023-01-21T17:35:07Z', 'duration': '00:00:22.2579050', 'trigger': 'manual', 'stateChangeTime': '2023-01-21T17:34:45Z', 'sendUpdatesToClient': True, 'updateScheduledTask': False, 'id': 803321}, {'name': 'RescanMovie', 'commandName': 'Rescan Movie', 'message': 'Completed', 'body': {'movieId': 7935, 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'isTypeExclusive': False, 'isLongRunning': False, 'name': 'RescanMovie', 'trigger': 'manual', 'suppressMessages': False}, 'priority': 'normal', 'status': 'completed', 'queued': '2023-01-21T17:34:54Z', 'started': '2023-01-21T17:34:54Z', 'ended': '2023-01-21T17:35:07Z', 'duration': '00:00:13.2018844', 'trigger': 'manual', 'stateChangeTime': '2023-01-21T17:34:54Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 803322}, {'name': 'MessagingCleanup', 'commandName': 'Messaging Cleanup', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'isTypeExclusive': False, 'isLongRunning': False, 'name': 'MessagingCleanup', 'lastExecutionTime': '2023-01-21T17:30:21Z', 'lastStartTime': '2023-01-21T17:30:21Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'low', 'status': 'completed', 'queued': '2023-01-21T17:35:21Z', 'started': '2023-01-21T17:35:21Z', 'ended': '2023-01-21T17:35:21Z', 'duration': '00:00:00.0170443', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T17:35:21Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T17:30:21Z', 'id': 803323}]
2023-01-22 02:36:13 - RadarrPostProcess - DEBUG - /dwnldr/tor/converted/_DragsterPS_ Bleach _1080p_ _Multi-Audio_ _Multi-Subs_ _82F7CE55_.mkv
2023-01-22 02:36:13 - RadarrPostProcess - DEBUG - No commands in progress for /dwnldr/tor/converted/_DragsterPS_ Bleach _1080p_ _Multi-Audio_ _Multi-Subs_ _82F7CE55_.mkv.
2023-01-22 02:36:13 - RadarrPostProcess - DEBUG - Queueing rescan command to Radarr via http://localhost:7878/api/v3/command.
2023-01-22 02:36:13 - RadarrPostProcess - DEBUG - {'name': 'RescanMovie', 'movieId': 7841}
2023-01-22 02:36:14 - RadarrPostProcess - DEBUG - {'name': 'RescanMovie', 'commandName': 'Rescan Movie', 'body': {'movieId': 7841, 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'isTypeExclusive': False, 'isLongRunning': False, 'name': 'RescanMovie', 'trigger': 'manual', 'suppressMessages': False}, 'priority': 'normal', 'status': 'queued', 'queued': '2023-01-21T17:36:13Z', 'trigger': 'manual', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 803326}
2023-01-22 02:36:14 - RadarrPostProcess - DEBUG - Radarr response from RescanMovie command: ID 803326 queued.
2023-01-22 02:36:14 - RadarrPostProcess - DEBUG - Requesting command status from Sonarr via http://localhost:7878/api/v3/command/803326.
2023-01-22 02:36:14 - RadarrPostProcess - DEBUG - Status: started.
2023-01-22 02:36:24 - RadarrPostProcess - DEBUG - {'name': 'RescanMovie', 'commandName': 'Rescan Movie', 'message': 'Completed', 'body': {'movieId': 7841, 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'isTypeExclusive': False, 'isLongRunning': False, 'name': 'RescanMovie', 'trigger': 'manual', 'suppressMessages': False}, 'priority': 'normal', 'status': 'completed', 'queued': '2023-01-21T17:36:13Z', 'started': '2023-01-21T17:36:14Z', 'ended': '2023-01-21T17:36:15Z', 'duration': '00:00:01.6707156', 'trigger': 'manual', 'stateChangeTime': '2023-01-21T17:36:14Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 803326}
2023-01-22 02:36:24 - RadarrPostProcess - DEBUG - Final status: completed.
2023-01-22 02:36:24 - RadarrPostProcess - INFO - Rescan command completed successfully.
2023-01-22 02:36:24 - RadarrPostProcess - DEBUG - Requesting movie from Radarr via http://localhost:7878/api/v3/movie/7841.
2023-01-22 02:36:24 - RadarrPostProcess - DEBUG - Requesting movie update to Radarr via http://localhost:7878/api/v3/movie/7841.
2023-01-22 02:36:24 - RadarrPostProcess - DEBUG - {'title': 'Bleach', 'originalTitle': 'ブリーチ', 'originalLanguage': {'id': 8, 'name': 'Japanese'}, 'alternateTitles': [{'sourceType': 'tmdb', 'movieMetadataId': 4575, 'title': '死神', 'sourceId': 0, 'votes': 0, 'voteCount': 0, 'language': {'id': 1, 'name': 'English'}, 'id': 33917}, {'sourceType': 'tmdb', 'movieMetadataId': 4575, 'title': 'Bleach: The Soul Reaper Agent Arc', 'sourceId': 0, 'votes': 0, 'voteCount': 0, 'language': {'id': 1, 'name': 'English'}, 'id': 33918}, {'sourceType': 'tmdb', 'movieMetadataId': 4575, 'title': 'ブリーチ', 'sourceId': 0, 'votes': 0, 'voteCount': 0, 'language': {'id': 7, 'name': 'Dutch'}, 'id': 33919}, {'sourceType': 'tmdb', 'movieMetadataId': 4575, 'title': '死神真人版：境·界', 'sourceId': 0, 'votes': 0, 'voteCount': 0, 'language': {'id': 1, 'name': 'English'}, 'id': 33920}, {'sourceType': 'tmdb', 'movieMetadataId': 4575, 'title': 'เทพมรณะ', 'sourceId': 0, 'votes': 0, 'voteCount': 0, 'language': {'id': 28, 'name': 'Thai'}, 'id': 33921}, {'sourceType': 'tmdb', 'movieMetadataId': 4575, 'title': 'BLEACH 死神代行篇', 'sourceId': 0, 'votes': 0, 'voteCount': 0, 'language': {'id': 1, 'name': 'English'}, 'id': 33922}], 'secondaryYearSourceId': 0, 'sortTitle': 'bleach', 'sizeOnDisk': 7821854487, 'status': 'released', 'overview': "High school student Ichigo Kurosaki lives an ordinary life, besides being able to see ghosts and the blurry memories of his mother's death under strange circumstances when he was a kid. His peaceful world suddenly breaks as he meets Rukia Kuchiki, a God of Death.", 'inCinemas': '2018-07-20T00:00:00Z', 'physicalRelease': '2018-09-14T00:00:00Z', 'digitalRelease': '2018-09-14T00:00:00Z', 'images': [{'coverType': 'poster', 'url': '/MediaCover/7841/poster.jpg?lastWrite=638098610623495004', 'remoteUrl': 'https://image.tmdb.org/t/p/original/dWiwxqAtP09fBfYyzH85u8nFwOH.jpg'}, {'coverType': 'fanart', 'url': '/MediaCover/7841/fanart.jpg?lastWrite=638098610642155232', 'remoteUrl': 'https://image.tmdb.org/t/p/original/oZc9fUwHJ880Q4lFP98s7T5a7Fe.jpg'}], 'website': 'https://www.netflix.com/title/80217733', 'year': 2018, 'hasFile': True, 'youTubeTrailerId': 'u8SDFDDuR5A', 'studio': 'Warner Bros. Japan', 'path': '/mnt/nas01/mm-movies01/BLEACH [2018]', 'qualityProfileId': 1, 'monitored': True, 'minimumAvailability': 'announced', 'isAvailable': True, 'folderName': '/mnt/nas01/mm-movies01/BLEACH [2018]', 'runtime': 108, 'cleanTitle': 'bleach', 'imdbId': 'tt5979872', 'tmdbId': 420426, 'titleSlug': '420426', 'certification': 'PG-13', 'genres': ['Action', 'Fantasy', 'Adventure'], 'tags': [], 'added': '2022-12-11T06:45:43Z', 'ratings': {'imdb': {'votes': 12007, 'value': 6.3, 'type': 'user'}, 'tmdb': {'votes': 481, 'value': 6.6, 'type': 'user'}, 'rottenTomatoes': {'votes': 0, 'value': 71, 'type': 'user'}}, 'movieFile': {'movieId': 7841, 'relativePath': 'BLEACH [2018] - [HDTV-1080P][AAC 2.0][X264][IMDB-TT5979872][TMDB-420426]-DRAGSTERPS.rnm.mp4', 'path': '/mnt/nas01/mm-movies01/BLEACH [2018]/BLEACH [2018] - [HDTV-1080P][AAC 2.0][X264][IMDB-TT5979872][TMDB-420426]-DRAGSTERPS.rnm.mp4', 'size': 7821854487, 'dateAdded': '2023-01-21T17:36:15Z', 'indexerFlags': 0, 'quality': {'quality': {'id': 9, 'name': 'HDTV-1080p', 'source': 'tv', 'resolution': 1080, 'modifier': 'none'}, 'revision': {'version': 1, 'real': 0, 'isRepack': False}}, 'mediaInfo': {'audioBitrate': 254200, 'audioChannels': 2, 'audioCodec': 'AAC', 'audioLanguages': 'eng/eng/deu/deu/kor/kor/jpn/jpn/jpn/jpn', 'audioStreamCount': 10, 'videoBitDepth': 8, 'videoBitrate': 5085478, 'videoCodec': 'x264', 'videoDynamicRangeType': '', 'videoFps': 23.976, 'resolution': '1920x1080', 'runTime': '1:48:42', 'scanType': 'Progressive', 'subtitles': ''}, 'qualityCutoffNotMet': False, 'languages': [{'id': 1, 'name': 'English'}, {'id': 4, 'name': 'German'}, {'id': 21, 'name': 'Korean'}, {'id': 8, 'name': 'Japanese'}], 'releaseGroup': 'DRAGSTERPS', 'edition': '', 'id': 13234}, 'popularity': 18.567, 'id': 7841}
2023-01-22 02:36:24 - RadarrPostProcess - INFO - Radarr monitoring information updated for movie Bleach.
2023-01-22 02:36:24 - RadarrPostProcess - DEBUG - Trying to restore scene information.
2023-01-22 02:36:24 - RadarrPostProcess - DEBUG - Requesting moviefile from Radarr for moviefile via http://localhost:7878/api/v3/moviefile/13234.
2023-01-22 02:36:24 - RadarrPostProcess - DEBUG - Requesting moviefile update to Radarr via http://localhost:7878/api/v3/moviefile/13234.
2023-01-22 02:36:24 - RadarrPostProcess - DEBUG - Restored releaseGroup to DragsterPS.
2023-01-22 02:36:24 - RadarrPostProcess - DEBUG - Queueing rename command to Radarr via http://localhost:7878/api/v3/command.
2023-01-22 02:36:24 - RadarrPostProcess - DEBUG - {'name': 'RenameFiles', 'files': [13234], 'movieId': 7841}
2023-01-22 02:36:24 - RadarrPostProcess - INFO - Radarr response Rename command: ID 803328 queued.
2023-01-22 02:36:24 - NZBGetPostProcess - INFO - 43
2023-01-22 02:36:24 - NZBGetPostProcess - INFO - 43
2023-01-22 02:36:24 - NZBGetPostProcess - INFO - 43
2023-01-22 02:37:45 - NZBGetPostProcess - INFO - 99
2023-01-22 02:37:46 - NZBGetPostProcess - INFO - 99
2023-01-22 02:37:46 - NZBGetPostProcess - INFO - 99
2023-01-22 02:37:46 - NZBGetPostProcess - INFO - 100
2023-01-22 02:37:46 - NZBGetPostProcess - INFO - /dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb.mp4 created.
2023-01-22 02:37:47 - NZBGetPostProcess - INFO - Successfully processed /dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb.mkv.
2023-01-22 02:37:47 - autoprocess.sonarr - INFO - [INFO] Sonarr notifier started.
2023-01-22 02:37:47 - autoprocess.sonarr - DEBUG - Sonarr host: localhost.
2023-01-22 02:37:47 - autoprocess.sonarr - DEBUG - Sonarr port: 8989.
2023-01-22 02:37:47 - autoprocess.sonarr - DEBUG - Sonarr webroot: .
2023-01-22 02:37:47 - autoprocess.sonarr - DEBUG - Sonarr apikey: 02dc28cfb7cb4a1f8cbb917fcc593d1c.
2023-01-22 02:37:47 - autoprocess.sonarr - DEBUG - Sonarr protocol: http://.
2023-01-22 02:37:47 - autoprocess.sonarr - DEBUG - URL 'http://localhost:8989/api/v3/command' with payload '{'name': 'DownloadedEpisodesScan', 'path': '/dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb', 'importMode': 'Move'}.'
2023-01-22 02:37:47 - autoprocess.sonarr - INFO - [INFO] Requesting Sonarr to scan directory '/dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb'.
2023-01-22 02:37:47 - autoprocess.sonarr - DEBUG - {'name': 'DownloadedEpisodesScan', 'commandName': 'Downloaded Episodes Scan', 'body': {'path': '/dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb', 'importMode': 'move', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'DownloadedEpisodesScan', 'trigger': 'manual', 'suppressMessages': True, 'clientUserAgent': 'SMA - autoprocess/sonarr'}, 'priority': 'normal', 'status': 'queued', 'queued': '2023-01-21T17:37:47.120577Z', 'trigger': 'manual', 'clientUserAgent': 'SMA - autoprocess/sonarr', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248618}
2023-01-22 02:37:47 - autoprocess.sonarr - INFO - [INFO] Sonarr response DownloadedEpisodesScan command: ID 3248618 queued.
2023-01-22 02:38:42 - SonarrPostProcess - INFO - Sonarr extra script post processing started.
2023-01-22 02:38:42 - resources.readsettings - INFO - /usr/local/bin/python3.8
2023-01-22 02:38:42 - resources.readsettings - DEBUG - Loading default config file.
2023-01-22 02:38:42 - resources.readsettings - INFO - Loading config file /opt/mp4automator/config/autoProcess.ini.
2023-01-22 02:38:42 - SonarrPostProcess - DEBUG - environ({'sonarr_series_type': 'Standard', 'XDG_DATA_HOME': '/home/sonarr/.config/Sonarr/.config/share', 'sonarr_episodefile_episodeairdates': '2023-01-22', 'sonarr_episodefile_relativepath': 'SEASON 03/THE L WORD - GENERATION Q - S03E10 - [AMZN][WEBDL-1080P][AAC 2.0][H264]-NTB.mp4', 'SHELL': '/bin/bash', 'sonarr_download_id': '', 'No_Expand': 'true', 'sonarr_series_id': '854', 'No_SQLiteXmlConfigFile': 'true', 'sonarr_episodefile_releasegroup': 'NTb', 'sonarr_deletedpaths': '/mnt/nas01/mm-tv01/THE L WORD - GENERATION Q (2019) {TVDB-364477}/SEASON 03/THE L WORD - GENERATION Q - S03E10 - [WEBRIP-1080P][AAC 2.0][X264]-NTB.mp4', 'sonarr_episodefile_sourcefolder': '/dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb', 'sonarr_eventtype': 'Download', 'USER': 'sonarr', 'sonarr_download_client': '', 'sonarr_episodefile_episodetitles': 'Looking Ahead', 'sonarr_episodefile_qualityversion': '1', 'sonarr_isupgrade': 'True', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin', 'sonarr_episodefile_path': '/mnt/nas01/mm-tv01/THE L WORD - GENERATION Q (2019) {TVDB-364477}/SEASON 03/THE L WORD - GENERATION Q - S03E10 - [AMZN][WEBDL-1080P][AAC 2.0][H264]-NTB.mp4', 'sonarr_episodefile_episodeids': '67439', 'sonarr_series_title': 'The L Word: Generation Q', 'PWD': '/', 'sonarr_download_client_type': '', 'LANG': 'en_US.UTF-8', 'sonarr_episodefile_episodenumbers': '10', 'sonarr_episodefile_scenename': 'The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb', 'sonarr_episodefile_quality': 'WEBDL-1080p', 'SHLVL': '1', 'HOME': '/home/sonarr', 'sonarr_series_tvdbid': '364477', 'sonarr_series_tvmazeid': '42287', 'sonarr_episodefile_id': '90135', 'sonarr_series_path': '/mnt/nas01/mm-tv01/THE L WORD - GENERATION Q (2019) {TVDB-364477}', 'sonarr_episodefile_episodecount': '1', 'sonarr_episodefile_sourcepath': '/dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb.mp4', 'LOGNAME': 'sonarr', 'sonarr_deletedrelativepaths': 'SEASON 03/THE L WORD - GENERATION Q - S03E10 - [WEBRIP-1080P][AAC 2.0][X264]-NTB.mp4', 'No_SQLiteFunctions': 'true', 'No_PreLoadSQLite': 'true', 'sonarr_episodefile_seasonnumber': '3', 'sonarr_series_imdbid': 'tt7661384', 'sonarr_episodefile_episodeairdatesutc': '1/23/2023 1:00:00 AM', '_': '/usr/local/bin/python3.8'})
2023-01-22 02:38:42 - SonarrPostProcess - DEBUG - Input file: /mnt/nas01/mm-tv01/THE L WORD - GENERATION Q (2019) {TVDB-364477}/SEASON 03/THE L WORD - GENERATION Q - S03E10 - [AMZN][WEBDL-1080P][AAC 2.0][H264]-NTB.mp4.
2023-01-22 02:38:42 - SonarrPostProcess - DEBUG - Original name: The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb.
2023-01-22 02:38:42 - SonarrPostProcess - DEBUG - TVDB ID: 364477.
2023-01-22 02:38:42 - SonarrPostProcess - DEBUG - Season: 3 episode: 10.
2023-01-22 02:38:42 - SonarrPostProcess - DEBUG - Sonarr series ID: 854.
2023-01-22 02:38:42 - SonarrPostProcess - DEBUG - Renaming file /mnt/nas01/mm-tv01/THE L WORD - GENERATION Q (2019) {TVDB-364477}/SEASON 03/THE L WORD - GENERATION Q - S03E10 - [AMZN][WEBDL-1080P][AAC 2.0][H264]-NTB.mp4 to /mnt/nas01/mm-tv01/THE L WORD - GENERATION Q (2019) {TVDB-364477}/SEASON 03/THE L WORD - GENERATION Q - S03E10 - [AMZN][WEBDL-1080P][AAC 2.0][H264]-NTB.rnm.mp4.
2023-01-22 02:38:43 - resources.mediaprocessor - INFO - Processing /mnt/nas01/mm-tv01/THE L WORD - GENERATION Q (2019) {TVDB-364477}/SEASON 03/THE L WORD - GENERATION Q - S03E10 - [AMZN][WEBDL-1080P][AAC 2.0][H264]-NTB.rnm.mp4.
2023-01-22 02:38:43 - resources.metadata - DEBUG - Input IDs:
2023-01-22 02:38:43 - resources.metadata - DEBUG - TMDBID: None
2023-01-22 02:38:43 - resources.metadata - DEBUG - IMDBID: tt7661384
2023-01-22 02:38:43 - resources.metadata - DEBUG - TVDBID: 364477
2023-01-22 02:38:43 - resources.metadata - DEBUG - Using TMDB ID: 89630
2023-01-22 02:38:43 - resources.metadata - DEBUG - Tagging language determined to be None.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Process started.
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Input Data
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - {
    "format": "mov,mp4,m4a,3gp,3g2,mj2",
    "format-fullname": "QuickTime / MOV",
    "video": {
        "index": 0,
        "codec": "h264",
        "bitrate": 9174674,
        "pix_fmt": "yuv420p",
        "profile": "high",
        "fps": 23.976023976023978,
        "framedata": {
            "pix_fmt": "yuv420p",
            "color_space": "bt709",
            "color_primaries": "bt709",
            "color_transfer": "bt709"
        },
        "dimensions": "1920x1080",
        "level": 4.0,
        "field_order": "progressive"
    },
    "audio": [
        {
            "index": 1,
            "codec": "aac",
            "bitrate": 255491,
            "channels": 2,
            "samplerate": 48000,
            "language": "eng",
            "disposition": "+default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions"
        },
        {
            "index": 2,
            "codec": "eac3",
            "bitrate": 640000,
            "channels": 6,
            "samplerate": 48000,
            "language": "eng",
            "disposition": "-default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions"
        }
    ],
    "subtitle": [],
    "attachment": []
}
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Reading video stream.
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Video codec detected: h264.
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Pix Fmt: yuv420p.
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Profile: high.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Stream is not HDR, color parameter bt709 does not match ['bt2020nc'] [hdr-['bt2020nc']].
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Pool of video codecs is ['h264_nvenc', 'h264', 'x264', 'h265_nvenc', 'hevc', 'h265', 'x265'].
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Total bitrate is 10079158.0.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Total audio bitrate is 895491.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Estimated video bitrate is 9183667.0.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Using video bitrate ratio of 1.000000, which results in 8724.483650 changing to 8724.483650.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - VCRF profiles detected [video-crf-profiles].
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Acceptable profile match found for VBR 8724.483649999998 using CRF 17, maxrate 10m, bufsize 30m.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Source bit-depth 8, output 0, using depth 8.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Video codec: copy.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Video bitrate: 8724.483649999998.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Video CRF: 17.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Video maxrate: 10m.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Video bufsize: 30m.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Video level: 0.0.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Video profile: None.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Video preset: None.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Video pix_fmt: None.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Video field order: progressive.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Video width: None.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Video debug video.
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Video codec parameters None.
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Creating copy video stream from source stream 0.
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Reading audio streams.
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - The following stream indexes have been identified as being copies: [] [stream-codec-combinations].
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Pool universal audio codecs is ['aac'].
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Pool of audio codecs is ['ac3', 'eac3'].
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Audio detected for stream 1 - aac eng 2 channel.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Overriding default channel settings because universal audio is enabled but the source is stereo [universal-audio].
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Audio codec: copy.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Channels: 2.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Bitrate: 256.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Audio Profile: None.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Language: eng.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Filter: None.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Disposition: +default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Debug: universal-audio.
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Creating copy audio stream from source stream 1.
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Audio detected for stream 2 - eac3 eng 6 channel.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Found an audio filter for converting from 6 channels to 2 channels. Applying filter pan=stereo|FL=0.5*FC+0.707*FL+0.707*BL+0.5*LFE|FR=0.5*FC+0.707*FR+0.707*BR+0.5*LFE to UA.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Audio codec: aac.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Channels: 2.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Filter: pan=stereo|FL=0.5*FC+0.707*FL+0.707*BL+0.5*LFE|FR=0.5*FC+0.707*FR+0.707*BR+0.5*LFE.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Bitrate: 256.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Profile: None.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Language: eng.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Disposition: -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Audio codec: copy.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Channels: 6.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Bitrate: 768.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - VBR: 0.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Audio Profile: None.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Language: eng.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Filter: None.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Disposition: -default-dub-original-comment-lyrics-karaoke-forced-hearing_impaired-visual_impaired-captions.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Debug: audio.
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Creating copy audio stream from source stream 2.
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Creating aac audio stream from source audio stream 2 [universal-audio].
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Purging the following streams:
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - []
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Found 0 streams that can be removed from the output file since they will be duplicates [stream-codec-combinations].
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Triggering audio track sort [audio.sorting-sorting].
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Sorting streams with keys ['language', 'channels.a', 'map', 'd.comment'].
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Sorted ascending with language:
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - ['0->0', '1->1', '2->2']
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Sorted ascending with channels:
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - ['0->0', '1->2', '2->1']
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Sorted ascending with map:
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - ['0->0', '1->1', '2->2']
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Sorted ascending with d.comment:
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - ['0->0', '1->1', '2->2']
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Final sorting:
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - ['0->0', '1->2', '2->1']
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Sorting streams with keys ['channels.a', 'map', 'd.comment'].
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Sorted ascending with channels:
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - ['0->0', '1->1', '2->2']
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Sorted ascending with map:
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - ['0->0', '1->1', '2->2']
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Sorted ascending with d.comment:
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - ['0->0', '1->1', '2->2']
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Final sorting:
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - ['0->0', '1->1', '2->2']
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Sorting audio streams for default audio stream designation.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - 3 total audio streams with 1 set to default disposition. 1 defaults in your preferred language (eng), 0 in other languages.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Default audio stream already inherited from source material, will not override to audio-language-default.
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Default audio stream set to eng copy 2 channel stream [audio-default-sorting: ['channels.a', 'map', 'd.comment']].
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Reading subtitle streams.
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Scanned for external subtitles and found 0 results in your approved languages.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Subtitle output is empty or no default subtitle language is set, will not pass over subtitle output to set a default stream.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Sorting streams with keys ['language', 'd.comment', 'd.default.d', 'd.forced.d'].
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Final sorting:
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - []
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Input and output extensions match and the file appears to have already been processed by SMA, enable force-convert to override [force-convert: False].
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Bypassing conversion and setting outputfile to inputfile.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - /mnt/nas01/mm-tv01/THE L WORD - GENERATION Q (2019) {TVDB-364477}/SEASON 03/THE L WORD - GENERATION Q - S03E10 - [AMZN][WEBDL-1080P][AAC 2.0][H264]-NTB.rnm.mp4 created from /mnt/nas01/mm-tv01/THE L WORD - GENERATION Q (2019) {TVDB-364477}/SEASON 03/THE L WORD - GENERATION Q - S03E10 - [AMZN][WEBDL-1080P][AAC 2.0][H264]-NTB.rnm.mp4 successfully.
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Height: 1080
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Width: 1920
2023-01-22 02:38:45 - resources.mediaprocessor - DEBUG - Tag language setting is eng, using language eng for tagging.
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Tagging /mnt/nas01/mm-tv01/THE L WORD - GENERATION Q (2019) {TVDB-364477}/SEASON 03/THE L WORD - GENERATION Q - S03E10 - [AMZN][WEBDL-1080P][AAC 2.0][H264]-NTB.rnm.mp4 with TMDB ID 89630.
2023-01-22 02:38:45 - resources.metadata - INFO - Tagging file: /mnt/nas01/mm-tv01/THE L WORD - GENERATION Q (2019) {TVDB-364477}/SEASON 03/THE L WORD - GENERATION Q - S03E10 - [AMZN][WEBDL-1080P][AAC 2.0][H264]-NTB.rnm.mp4.
2023-01-22 02:38:45 - resources.metadata - INFO - Trying to write tags.
2023-01-22 02:38:45 - resources.metadata - INFO - Tags written successfully using mutagen.
2023-01-22 02:38:45 - resources.mediaprocessor - INFO - Relocating MOOV atom to start of file.
2023-01-22 02:38:45 - qtfaststart - DEBUG - Getting index of top level atoms...
2023-01-22 02:38:45 - qtfaststart - DEBUG - ftyp: 36
2023-01-22 02:38:45 - qtfaststart - DEBUG - free: 8
2023-01-22 02:38:45 - qtfaststart - DEBUG - mdat: 4065697115
2023-01-22 02:38:45 - qtfaststart - DEBUG - moov: 3687872
2023-01-22 02:38:45 - qtfaststart - INFO - Removing free atom at 36 (8 bytes)
2023-01-22 02:38:45 - qtfaststart - INFO - Patching stco with 77439 entries
2023-01-22 02:38:45 - qtfaststart - INFO - Patching stco with 127556 entries
2023-01-22 02:38:45 - qtfaststart - INFO - Patching stco with 100935 entries
2023-01-22 02:38:45 - qtfaststart - INFO - Writing output...
2023-01-22 02:38:45 - qtfaststart - DEBUG - Writing ftyp... (36 bytes)
2023-01-22 02:38:45 - qtfaststart - DEBUG - Writing moov... (3687872 bytes)
2023-01-22 02:38:45 - qtfaststart - DEBUG - Writing mdat... (4065697115 bytes)
2023-01-22 02:39:13 - resources.mediaprocessor - DEBUG - Final output file: /mnt/nas01/mm-tv01/THE L WORD - GENERATION Q (2019) {TVDB-364477}/SEASON 03/THE L WORD - GENERATION Q - S03E10 - [AMZN][WEBDL-1080P][AAC 2.0][H264]-NTB.rnm.mp4.
2023-01-22 02:39:13 - SonarrPostProcess - DEBUG - Sonarr baseURL: http://localhost:8989.
2023-01-22 02:39:13 - SonarrPostProcess - DEBUG - Sonarr apikey: 02dc28cfb7cb4a1f8cbb917fcc593d1c.
2023-01-22 02:39:13 - SonarrPostProcess - DEBUG - Requesting commands in process from Sonarr via http://localhost:8989/api/v3/command.
2023-01-22 02:39:13 - SonarrPostProcess - DEBUG - [{'name': 'DownloadedEpisodesScan', 'commandName': 'Downloaded Episodes Scan', 'body': {'path': '/dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb', 'importMode': 'move', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'DownloadedEpisodesScan', 'trigger': 'manual', 'suppressMessages': True, 'clientUserAgent': 'SMA - autoprocess/sonarr'}, 'priority': 'normal', 'status': 'started', 'queued': '2023-01-21T17:37:47.120577Z', 'started': '2023-01-21T17:37:47.123235Z', 'trigger': 'manual', 'clientUserAgent': 'SMA - autoprocess/sonarr', 'stateChangeTime': '2023-01-21T17:37:47.123235Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248618}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'lastExecutionTime': '2023-01-21T17:32:43.809187Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T17:34:13.222381Z', 'started': '2023-01-21T17:34:13.227804Z', 'ended': '2023-01-21T17:34:13.860351Z', 'duration': '00:00:00.6325470', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T17:34:13.227804Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T17:32:43.809187Z', 'id': 3248611}, {'name': 'ProcessMonitoredDownloads', 'commandName': 'Process Monitored Downloads', 'message': 'Completed', 'body': {'requiresDiskAccess': True, 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'ProcessMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T17:34:13.853937Z', 'started': '2023-01-21T17:34:13.860367Z', 'ended': '2023-01-21T17:34:13.867267Z', 'duration': '00:00:00.0069000', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T17:34:13.860367Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248612}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'lastExecutionTime': '2023-01-21T17:34:13.91124Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T17:35:43.229274Z', 'started': '2023-01-21T17:35:43.232847Z', 'ended': '2023-01-21T17:35:43.842451Z', 'duration': '00:00:00.6096040', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T17:35:43.232847Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T17:34:13.91124Z', 'id': 3248613}, {'name': 'ProcessMonitoredDownloads', 'commandName': 'Process Monitored Downloads', 'message': 'Completed', 'body': {'requiresDiskAccess': True, 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'ProcessMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T17:35:43.837181Z', 'started': '2023-01-21T17:35:43.842532Z', 'ended': '2023-01-21T17:35:43.951161Z', 'duration': '00:00:00.1086290', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T17:35:43.842532Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248614}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'lastExecutionTime': '2023-01-21T17:35:43.847912Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T17:37:13.234239Z', 'started': '2023-01-21T17:37:13.23879Z', 'ended': '2023-01-21T17:37:13.839391Z', 'duration': '00:00:00.6006010', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T17:37:13.23879Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T17:35:43.847912Z', 'id': 3248615}, {'name': 'ProcessMonitoredDownloads', 'commandName': 'Process Monitored Downloads', 'message': 'Completed', 'body': {'requiresDiskAccess': True, 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'ProcessMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T17:37:13.83386Z', 'started': '2023-01-21T17:37:13.839411Z', 'ended': '2023-01-21T17:37:13.916019Z', 'duration': '00:00:00.0766080', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T17:37:13.839411Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248616}, {'name': 'RefreshMonitoredDownloads', 'commandName': 'Refresh Monitored Downloads', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RefreshMonitoredDownloads', 'lastExecutionTime': '2023-01-21T17:37:13.844825Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T17:38:20.822558Z', 'started': '2023-01-21T17:38:20.826839Z', 'ended': '2023-01-21T17:38:42.162521Z', 'duration': '00:00:21.3356820', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T17:38:20.826839Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T17:37:13.844825Z', 'id': 3248619}, {'name': 'ProcessMonitoredDownloads', 'commandName': 'Process Monitored Downloads', 'message': 'Completed', 'body': {'requiresDiskAccess': True, 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'isExclusive': False, 'name': 'ProcessMonitoredDownloads', 'trigger': 'unspecified', 'suppressMessages': False}, 'priority': 'high', 'status': 'completed', 'queued': '2023-01-21T17:38:42.156193Z', 'started': '2023-01-21T17:38:42.173349Z', 'ended': '2023-01-21T17:38:42.177788Z', 'duration': '00:00:00.0044390', 'trigger': 'unspecified', 'stateChangeTime': '2023-01-21T17:38:42.173349Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'id': 3248620}, {'name': 'RssSync', 'commandName': 'Rss Sync', 'message': 'Completed', 'body': {'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RssSync', 'lastExecutionTime': '2023-01-21T17:22:20.578865Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'low', 'status': 'completed', 'queued': '2023-01-21T17:37:43.239691Z', 'started': '2023-01-21T17:37:43.245435Z', 'ended': '2023-01-21T17:38:44.145401Z', 'duration': '00:01:00.8999660', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T17:37:43.245435Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T17:22:20.578865Z', 'id': 3248617}, {'name': 'MessagingCleanup', 'commandName': 'Messaging Cleanup', 'message': 'Completed', 'body': {'sendUpdatesToClient': False, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'MessagingCleanup', 'lastExecutionTime': '2023-01-21T17:33:43.238683Z', 'trigger': 'scheduled', 'suppressMessages': False}, 'priority': 'low', 'status': 'completed', 'queued': '2023-01-21T17:38:50.827851Z', 'started': '2023-01-21T17:38:50.831772Z', 'ended': '2023-01-21T17:38:50.851596Z', 'duration': '00:00:00.0198240', 'trigger': 'scheduled', 'stateChangeTime': '2023-01-21T17:38:50.831772Z', 'sendUpdatesToClient': False, 'updateScheduledTask': True, 'lastExecutionTime': '2023-01-21T17:33:43.238683Z', 'id': 3248621}]
2023-01-22 02:39:13 - SonarrPostProcess - DEBUG - /dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb
2023-01-22 02:39:13 - SonarrPostProcess - DEBUG - Found a matching path scan in progress /dwnldr/nzb/comp/tv/The.L.Word.Generation.Q.S03E10.Looking.Ahead.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb.
2023-01-22 02:39:13 - SonarrPostProcess - INFO - DownloadedEpisodesScan command is in process for this episode, cannot wait for rescan but will queue.
2023-01-22 02:39:13 - SonarrPostProcess - DEBUG - Queueing rescan command to Sonarr via http://localhost:8989/api/v3/command.
2023-01-22 02:39:13 - SonarrPostProcess - DEBUG - {'name': 'RescanSeries', 'seriesId': 854}
2023-01-22 02:39:13 - SonarrPostProcess - DEBUG - {'name': 'RescanSeries', 'commandName': 'Rescan Series', 'body': {'seriesId': 854, 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RescanSeries', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'SMA - postSonarr'}, 'priority': 'normal', 'status': 'queued', 'queued': '2023-01-21T17:39:13.234598Z', 'trigger': 'manual', 'clientUserAgent': 'SMA - postSonarr', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248622}
2023-01-22 02:39:13 - SonarrPostProcess - INFO - Sonarr response from RescanSeries command: ID 3248622 queued.
2023-01-22 02:39:13 - SonarrPostProcess - DEBUG - Requesting command status from Sonarr for command ID 3248622.
2023-01-22 02:39:13 - SonarrPostProcess - DEBUG - {'name': 'RescanSeries', 'commandName': 'Rescan Series', 'body': {'seriesId': 854, 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'completionMessage': 'Completed', 'requiresDiskAccess': False, 'isExclusive': False, 'name': 'RescanSeries', 'trigger': 'manual', 'suppressMessages': False, 'clientUserAgent': 'SMA - postSonarr'}, 'priority': 'normal', 'status': 'started', 'queued': '2023-01-21T17:39:13.234598Z', 'started': '2023-01-21T17:39:13.238968Z', 'trigger': 'manual', 'clientUserAgent': 'SMA - postSonarr', 'stateChangeTime': '2023-01-21T17:39:13.238968Z', 'sendUpdatesToClient': True, 'updateScheduledTask': True, 'id': 3248622}
2023-01-22 02:39:13 - SonarrPostProcess - DEBUG - Final status: started.
2023-01-22 02:39:13 - SonarrPostProcess - DEBUG - Queueing rename command to Sonarr via http://localhost:8989/api/v3/command.
2023-01-22 02:39:13 - SonarrPostProcess - DEBUG - {'name': 'RenameSeries', 'seriesIds': [854]}

[johnwchambers]

Also, I just checked Radarr logs, and manual import and automatic new movie download/ingest renaming is working fine there.

[johnwchambers]

autoProcess.ini settings:

[Converter]
ffmpeg = /usr/bin/ffmpeg
ffprobe = /usr/bin/ffprobe
threads = 0
hwaccels = dxva2, cuvid, qsv, d3d11va, cuda
hwaccel-decoders = h264_cuvid, h265_cuvid, hevc_cuvid, mjpeg_cuvid, mpeg1_cuvid, mpeg2_cuvid, mpeg4_cuvid, vc1_cuvid, hevc_qsv, h264_qsv
output-directory =
output-format = mp4
output-extension = mp4
temp-extension =
ignored-extensions = nfo, ds_store
copy-to =
move-to =
delete-original = True
process-same-extensions = True
force-convert = False
post-process = False
preopts =
postopts = -max_muxing_queue_size, 9999
minimum-size = 0
detailed-progress = False
hwdevices = vaapi:/dev/dri/renderD128
hwaccel-output-format = vaapi:vaapi
wait-post-process = True
bypass-if-copying-all = False
opts-separator = ,
regex-directory-replace = [^\w\-_\. ]
temp-output = False
output-directory-space-ratio = 0.0

[Permissions]
chmod = 0777
uid = -1
gid = -1

[Metadata]
relocate-moov = True
full-path-guess = True
tag = True
tag-language = eng
download-artwork = False
preserve-source-disposition = True
sanitize-disposition =
strip-metadata = False
keep-titles = True

[Video]
codec = h264_nvenc, h264, x264, h265_nvenc, hevc, h265, x265
bitrate = 0
crf = -1
crf-profiles = 0:23:4.5M:12M,4100:21:8M:24M,8000:17:10M:30M
max-width = 0
profile =
max-level = 0.0
pix-fmt =
max-bitrate = 0
filter =
force-filter = False
codec-parameters =
dynamic-parameters = True
preset =
bitrate-ratio =
prioritize-source-pix-fmt = True

[HDR]
codec =
pix-fmt =
space = bt2020nc
transfer = smpte2084
primaries = bt2020
preset =
codec-parameters =
filter =
force-filter = False
profile =

[Audio]
codec = ac3, eac3
languages = eng, kor, jpn, chi, zho, deu, ger, tha, und
default-language = eng
channel-bitrate = 128
max-channels = 0
filter =
sample-rates =
copy-original = False
first-track-of-language = False
allow-language-relax = True
aac-adtstoasc = False
ignore-truehd = m4v
first-stream-of-language = False
max-bitrate = 0
force-filter = False
ignored-dispositions =
unique-dispositions = False
sample-format =
stream-codec-combinations =
variable-bitrate = 0
profile =
relax-to-default = False
force-default = False
include-original-language = True

[Universal Audio]
codec = aac
first-track-only = False
move-last = False
filter =
first-stream-only = False
channel-bitrate = 128
force-filter = False
variable-bitrate = 0
profile =

[Audio.ChannelFilters]
6-2 = pan=stereo|FL=0.5*FC+0.707*FL+0.707*BL+0.5*LFE|FR=0.5*FC+0.707*FR+0.707*BR+0.5*LFE

[Subtitle]
codec = srt
codec-image-based = dvd_subtitle
languages = eng, kor, tha, und
default-language = eng
encoding = UTF-8
burn-subtitles = False
embed-subs = False
embed-image-subs = False
embed-only-internal-subs = False
attachment-codec =
first-stream-of-language = False
ignore-embedded-subs = False
burn-dispositions =
filename-dispositions = forced
ignored-dispositions =
unique-dispositions = False
remove-bitstream-subs = False
force-default = False
include-original-language = False

[Subtitle.CleanIt]
enabled = True
config-path =
tags = default, no-style

[Subtitle.Subliminal]
download-subs = False
download-hearing-impaired-subs = False
providers =
download-forced-subs = False
include-hearing-impaired-subs = False

[Subtitle.Subliminal.Auth]
opensubtitles =
tvsubtitles =

[Sonarr]
host = localhost
port = 8989
apikey = asdf
ssl = False
webroot =
force-rename = True
rescan = True
block-reprocess = False

[Radarr]
host = localhost
port = 7878
apikey = asdf
ssl = False
webroot =
force-rename = True
rescan = True
block-reprocess = False

[Sickbeard]
host = localhost
port = 8081
ssl = False
apikey =
webroot =
username =
password =

[Sickrage]
host = localhost
port = 8081
ssl = False
apikey =
webroot =
username =
password =

[CouchPotato]
host = localhost
port = 5050
username =
password =
apikey =
delay = 65
method = renamer
delete-failed = False
ssl = False
webroot =

[SABNZBD]
convert = True
sickbeard-category = sickbeard
sickrage-category = sickrage
couchpotato-category = couchpotato
sonarr-category = tv
radarr-category = movie
bypass-category = bypass
output-directory = None
path-mapping =

[Deluge]
couchpotato-label = couchpotato
sickbeard-label = sickbeard
sickrage-label = sickrage
sonarr-label = tv
radarr-label = movie
bypass-label = bypass
convert = True
host = localhost
port = 58846
username = mp4automator
password = mp4automator
output-directory = /dwnldr/tor/converted
remove = False
path-mapping =

[qBittorrent]
couchpotato-label = couchpotato
sickbeard-label = sickbeard
sickrage-label = sickrage
sonarr-label = tv
radarr-label = movie
bypass-label = bypass
convert = True
action-before =
action-after =
host = localhost
port = 8080
ssl = False
username =
password =
output-directory = /dwnldr/tor/converted
path-mapping =

[uTorrent]
couchpotato-label = couchpotato
sickbeard-label = sickbeard
sickrage-label = sickrage
sonarr-label = sonarr
radarr-label = radarr
bypass-label = bypass
convert = True
webui = False
action-before = stop
action-after = removedata
host = localhost
ssl = False
port = 8080
username =
password =
output-directory =
path-mapping =

[Plex]
host = 192.168.0.21
port = 32400
refresh = False
token = asdf

[Audio.Sorting]
sorting = language, channels.a, map, d.comment
default-sorting = channels.a, map, d.comment
codecs =

[Subtitle.Sorting]
sorting = language, d.comment, d.default.d, d.forced.d
codecs =
burn-sorting = language, d.comment, d.default.d, d.forced.d

[Subtitle.FFSubsync]
enabled = False

[mdhiggins]

Yeah so looking at the logs it does look like the file that was giving you trouble was a command that had to be queued so that last change I pushed should have addressed this but avoiding using the fileId which will end up changing before the queued rename can run

SonarrPostProcess - INFO - DownloadedEpisodesScan command is in process for this episode, cannot wait for rescan but will queue

Does it seem to be fixed on your end?

[johnwchambers]

I still had hit or misses so I just ended up disabling the force rename and rescan options. All my downloads are processed fully before finally being handed over to *arr for import, so I think I should be good with leaving those as false. I apologize for throwing in the towel, but my users had queued up over 300 episodes while I was trying to troubleshoot lol. Thanks for your help!