Cannot Analyze files for Auto Subtitle Sync

Plex Media Server Desktops & Laptops
1

For the life of me, I cannot seem to get any existing files analyzed for Auto Subtitle Sync, whether as a scheduled task or manually trying to analyze individual episodes.

I have enabled this setting in the Library > Manage Library > Edit > Advanced > Enable voice activity detection

I have enabled this setting in Server > Library > Generate voice activity data > as a scheduled task and when media is added

I tried going into several individual TV episodes and selecting Analyze manually. Then checking on the web client and Apple TV, always get either a greyed out checkbox or the message: “The current video file has not been analyzed for voice activity yet”. I made sure that the video has an external SRT file selected under Playback Settings. I normally grab them from OpenSubtitles.

I have rebooted both the clients and server with no change.

I tried poking around in the various logs on the server after running “Analyze” on a single episode, and I can’t seem to find much relevant to voice activity, other than the flags for Plex Media Scanner showing this:

--analyze --manual --server-action index,intro,credits,voiceActivity

It seems only these 2 files get updated when running Analyze:

Plex Media Scanner Analysis.log
Plex Media Server.log

Here is an excerpt from Plex Media Scanner Analysis.log:

Oct 03, 2024 11:31:21.108 [0x16bcdb000] INFO - Plex Media Scanner v1.41.0.8994-f2c27da23 - Apple Mac14,12 arm64 - build: darwin-aarch64 - GMT -07:00
Oct 03, 2024 11:31:21.110 [0x16bcdb000] INFO - MacOSX version: 14.6.1, language: en-US
Oct 03, 2024 11:31:21.110 [0x16bcdb000] INFO - Processor: 10-core Apple M2 Pro
Oct 03, 2024 11:31:21.110 [0x16bcdb000] INFO - /Applications/Plex Media Server.app/Contents/MacOS/Plex Media Scanner --analyze --manual --server-action index,intro,credits,voiceActivity --item 3283 --log-file-suffix  Analysis
Oct 03, 2024 11:31:21.113 [0x1f9aa8f40] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.39.4, threadsafe=1
Oct 03, 2024 11:31:21.151 [0x1f9aa8f40] DEBUG - Analyzing media parts for item 3283 (The Referee's A W***er): 4837
Oct 03, 2024 11:31:21.153 [0x1f9aa8f40] DEBUG - [ID 4850] Media part analysis: /Volumes/SSD/Plex/Chris TV/Inside No. 9/Inside No. 9 - s05e01 - The Referee's A.mkv
Oct 03, 2024 11:31:21.153 [0x1f9aa8f40] DEBUG - [MI] Opening input file: "/Volumes/SSD/Plex/Chris TV/Inside No. 9/Inside No. 9 - s05e01 - The Referee's A.mkv"
Oct 03, 2024 11:31:21.153 [0x1f9aa8f40] DEBUG - [FFMPEG] - Opening '/Volumes/SSD/Plex/Chris TV/Inside No. 9/Inside No. 9 - s05e01 - The Referee's A.mkv' for reading
Oct 03, 2024 11:31:21.153 [0x1f9aa8f40] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto,data'
Oct 03, 2024 11:31:21.154 [0x1f9aa8f40] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Oct 03, 2024 11:31:21.154 [0x1f9aa8f40] DEBUG - [FFMPEG] - Unknown entry 0x22B59D at pos. 237
Oct 03, 2024 11:31:21.154 [0x1f9aa8f40] DEBUG - [FFMPEG] - Unknown entry 0x22B59D at pos. 305
Oct 03, 2024 11:31:21.154 [0x1f9aa8f40] DEBUG - [FFMPEG] - Unknown entry 0x22B59D at pos. 381
Oct 03, 2024 11:31:21.154 [0x1f9aa8f40] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Oct 03, 2024 11:31:21.154 [0x1f9aa8f40] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Oct 03, 2024 11:31:21.154 [0x1f9aa8f40] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Oct 03, 2024 11:31:21.154 [0x1f9aa8f40] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5722 bytes read:33753 seeks:2 nb_streams:3
Oct 03, 2024 11:31:21.154 [0x1f9aa8f40] DEBUG - [FFMPEG] - nal_unit_type: 7(SPS), nal_ref_idc: 3
Oct 03, 2024 11:31:21.154 [0x1f9aa8f40] DEBUG - [FFMPEG] - nal_unit_type: 8(PPS), nal_ref_idc: 3
Oct 03, 2024 11:31:21.154 [0x1f9aa8f40] DEBUG - [FFMPEG] - nal_unit_type: 7(SPS), nal_ref_idc: 3
Oct 03, 2024 11:31:21.154 [0x1f9aa8f40] DEBUG - [FFMPEG] - nal_unit_type: 8(PPS), nal_ref_idc: 3
Oct 03, 2024 11:31:21.154 [0x1f9aa8f40] DEBUG - [FFMPEG] - nal_unit_type: 5(IDR), nal_ref_idc: 3
Oct 03, 2024 11:31:21.154 [0x1f9aa8f40] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Oct 03, 2024 11:31:21.155 [0x1f9aa8f40] DEBUG - [FFMPEG] - no picture 
Oct 03, 2024 11:31:21.166 [0x1f9aa8f40] DEBUG - [FFMPEG] - nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 2
Oct 03, 2024 11:31:21.166 [0x1f9aa8f40] DEBUG - [FFMPEG] - no picture 
Oct 03, 2024 11:31:21.177 [0x1f9aa8f40] DEBUG - [FFMPEG] - nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 2
Oct 03, 2024 11:31:21.185 [0x1f9aa8f40] DEBUG - [FFMPEG] - nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 0
Oct 03, 2024 11:31:21.193 [0x1f9aa8f40] DEBUG - [FFMPEG] - nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 0
Oct 03, 2024 11:31:21.201 [0x1f9aa8f40] DEBUG - [FFMPEG] - nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 2
Oct 03, 2024 11:31:21.210 [0x1f9aa8f40] DEBUG - [FFMPEG] - nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 2
Oct 03, 2024 11:31:21.218 [0x1f9aa8f40] DEBUG - [FFMPEG] - nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 0
Oct 03, 2024 11:31:21.226 [0x1f9aa8f40] DEBUG - [FFMPEG] - nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 0
Oct 03, 2024 11:31:21.234 [0x1f9aa8f40] DEBUG - [FFMPEG] - All info found
Oct 03, 2024 11:31:21.234 [0x1f9aa8f40] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 910712 bytes read:938743 seeks:2 frames:25
Oct 03, 2024 11:31:21.236 [0x1f9aa8f40] DEBUG - [MI]  * a21278c5e84494deba81b2418f38df30ace88a7c [mkv h264/eac3] Duration: 1738048ms, 1920x1080 (1.777778) 25.000fps, bitrate=9966kbps, audio channels=2, optimizedForStreaming=0 ("/Volumes/SSD/Plex/Chris TV/Inside No. 9/Inside No. 9 - s05e01 - The Referee's A.mkv")
Oct 03, 2024 11:31:21.236 [0x1f9aa8f40] DEBUG - [MI] Chapters had generic titles, clearing.
Oct 03, 2024 11:31:21.237 [0x1f9aa8f40] DEBUG - [MI] Closing input file: "/Volumes/SSD/Plex/Chris TV/Inside No. 9/Inside No. 9 - s05e01 - The Referee's A.mkv"
Oct 03, 2024 11:31:21.237 [0x1f9aa8f40] DEBUG - Updating media item 4837, size=2165362283, metadata_item_id=3283
Oct 03, 2024 11:31:21.237 [0x1f9aa8f40] DEBUG - Updating part with ID=4850 [/Volumes/SSD/Plex/Chris TV/Inside No. 9/Inside No. 9 - s05e01 - The Referee's A.mkv]
Oct 03, 2024 11:31:21.237 [0x16bd67000] DEBUG - [HCl#1] HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=3283&metadataType=4&state=-1&parentID=-1&rootID=-1&mediaState=analyzing
Oct 03, 2024 11:31:21.238 [0x1f9aa8f40] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Oct 03, 2024 11:31:21.238 [0x1f9aa8f40] DEBUG - [HCl#2] HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Oct 03, 2024 11:31:22.253 [0x16bdf3000] DEBUG - [HttpClient/HCl#1] HTTP/1.1 (1.0s) 401 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=3283&metadataType=4&state=-1&parentID=-1&rootID=-1&mediaState=analyzing
Oct 03, 2024 11:31:22.254 [0x16bd67000] DEBUG - [HCl#3] HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=3283&metadataType=4&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing
Oct 03, 2024 11:31:22.254 [0x16bdf3000] DEBUG - [HttpClient/HCl#2] HTTP/1.1 (1.0s) 401 response from GET http://127.0.0.1:32400/library/changestamp
Oct 03, 2024 11:31:22.255 [0x16be7f000] ERROR - downloadContainer: expected MediaContainer element, found html
Oct 03, 2024 11:31:22.255 [0x1f9aa8f40] ERROR - Exception thrown during analysis: Unable to allocate a changestamp from the server
Oct 03, 2024 11:31:22.255 [0x16bdf3000] DEBUG - [HttpClient/HCl#3] HTTP/1.1 (0.0s) 401 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=3283&metadataType=4&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing
Oct 03, 2024 11:31:22.255 [0x16bd67000] DEBUG - [HCl#4] HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=3283&metadataType=4&state=-1&parentID=-1&rootID=-1&mediaState=idle
Oct 03, 2024 11:31:22.256 [0x16bdf3000] DEBUG - [HttpClient/HCl#4] HTTP/1.1 (0.0s) 401 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=3283&metadataType=4&state=-1&parentID=-1&rootID=-1&mediaState=idle

Mac mini M2 Pro
Plex Media Server 1.41.0.8994-f2c27da23
macOS 14.6.1

Apple TV 4K (3rd gen)
Plex App 8.4.0 (9370)
tvOS 18.0

1 Like
2

I have the same on my Debian Linux and latest Plex Server

3

Just an update in case others run into this issue.

New media that was being added was being analyzed.

For all existing media, I had to move everything off the disk, wait for Plex to see it was gone and move it back.

Now everything has been analyzed for Voice and I can use auto sync subtitles.

Apparently for TV shows at least, moving just single episodes out of the library folder and back in was not enough. It seems you need the entire show to disappear from the library and then reappear to trigger the voice analysis.

4

Plex Media Server v1.41.1.9057 (current beta)

Voice activity analysis is apparently not working as a scheduled task right now (no info on when it will be fixed).

It is working when new media is added if so configured in Settings → Library.

You can also start the process manually by analyzing a movie or tv show (show/season/episode).

Choosing analyze for an entire library does not work (three dots → manage library → analyze).

However, you can select multiple movies or shows in a library and then choose to analyze them.

5

@FordGuy61 Appreciate the info.

As for starting the process manually for existing media, that is not working for me (see original post).

I have tried analyzing individual movies and TV shows, as well as multi select on all episodes for a given TV show, with the same failed result.

6

Not sure why it is not working for you.

I’m running the current beta on Linux, but release notes do not mention anything about subtitle sync.

I’ll update if I run across any new info.

7

I’m running latest production, so perhaps the beta is the difference or macOS vs Linux, even if they didn’t put it in release notes.

At this point I’m all squared away as new media added seems to be working…

8

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