Transcode not working at all on PMS?

TheCrow2k · March 13, 2021, 10:09am

Server Version#: 1.22.0.4163
Player Version#:

I have been using PLEX server since 2016, I am using DLNA server & recently upgraded.
Most of my media plays fine still but now whenever a media file needs to be transcoded it seems to fail on playback and after spending 8 hours poking around the log files & trying various things I have noticed that normally when a file trans codes it creates a set of sub folders like so:

Now since upgrading all i get are the root folders with nothing in them

which does not seem right. however I cannot see anything of note in the server logs ?

Plex Transcoder Statistics.zip
(68.8 KB)

I am at a loss as to what to do here, it could be a synology permissions issue but I have checked everything I can think of, Plex has read & right access to the plex folder and i have set the default transcoding path to where I assume I have to ? I do not recall ever having to set this path before but it appears this is where transcode files went before I upgraded to the current PMS release.

If someone can please help I would really appreciate it.

ChuckPa · March 13, 2021, 4:02pm

What did you do to the tmp_transcoding directory?

Pathname not showing as a Linux path (/).
Linux does not use \, Windows does.
Default configuration uses tmp_transcoding. You do not need spedify it.

Mar 13, 2021 20:32:05.522 [0x7f95baeb5780] DEBUG - Image transcode cache directory: "/volume1/Plex/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder"
Mar 13, 2021 20:32:05.522 [0x7f95baeb5780] DEBUG - Transcoder: Cleaning old transcode directories.
Mar 13, 2021 20:32:05.522 [0x7f95baeb5780] ERROR - Error creating directory "\volume1\Plex\tmp_transcoding": Permission denied
Mar 13, 2021 20:32:05.523 [0x7f95baeb5780] DEBUG - MyPlex: stop public ip check and mapping - current mapped state: 'Unknown'.
Mar 13, 2021 20:32:05.523 [0x7f95baeb5780] DEBUG - NetworkInterface: Starting watch thread.
Mar 13, 2021 20:32:05.522 [0x7f95baeb5780] DEBUG - Image transcode cache directory: "/volume1/Plex/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder"
Mar 13, 2021 20:32:05.522 [0x7f95baeb5780] DEBUG - Transcoder: Cleaning old transcode directories.
Mar 13, 2021 20:32:05.522 [0x7f95baeb5780] ERROR - Error creating directory "\volume1\Plex\tmp_transcoding": Permission denied
Mar 13, 2021 20:32:05.523 [0x7f95baeb5780] DEBUG - MyPlex: stop public ip check and mapping - current mapped state: 'Unknown'.
Mar 13, 2021 20:32:05.523 [0x7f95baeb5780] DEBUG - NetworkInterface: Starting watch thread.
Mar 13, 2021 20:32:05.523 [0x7f95baeb5780] DEBUG - Network change.

TheCrow2k · March 13, 2021, 10:31pm

Thanks for the reply @ChuckPa

1 & 2) I tried the path name both ways / and \ but got error messages from both, I have removed it now.
3) I only entered the path manually when transcode suddenly ceased working,

When I manually installed PlexMediaServer-1.21.4.4079 (64 bit) on the synology I found some settings had to be set again in PMS (DLNA was off for some reason) and in Synology shared folder management Plex user had no access right to anything anymore (prety sure i had the manually installed 1.19 PMS before upgrade).

Initially I set user plex to have read write to the video & music shared folders (as read only was greyed out) and transcoding was workinging at that point.

I later worked out how to set the Plex user to read only on the video and music folders.

but I did not notice at that point that transcode was not working.

A day or two later I noticed transcode was not working, I checked the error logs & it was saying access was being denied to the plex folder itself, so I gave user Plex read/write access to the plex folder.

which cleared the error but still transcode would not work. At this point I installed PlexMediaServer-1.22.0.4163 (64 bit) which had just come out, hoping maybe this would fix it.

but nothing has worked thus far.

I do not know if these are of any help.

some updated logs after a fresh plex boot (and removal of manually entered path)
Plex DLNA Server.zip (67.2 KB)

and just in case here is a set of the same actions but with verbose logging turned on.
Plex DLNA Server VERBOSE.zip (136.0 KB)

ChuckPa · March 13, 2021, 11:06pm

If you’re putting your files in the Synolgy-provided shared folders, this will be a problem. Synology likes claiming exclusive access to them even though Video Station isn’t installed. It may work for a while but suddenly, without warning, user plex will be denied access. This is why we always advocate making your own shared folders.

if you uninstall then reinstall the same SPK file, the installer will recreate tmp_transcoding with the right permissions.

tmp_transcoding is also used by the Codec Manager when downloading new / updated codecs. It must exist. I have changed that in DSM 7 but DSM 6 has too many active installations to change.

TheCrow2k · March 14, 2021, 12:06am

@ChuckPa thankyou.

So just for clarity instead of using the default Synology video folder with a movies & TV shows subfolder in those i should move the Movies & TV shows folders to root of Volume 1 ? will moving the folders be ok ? I dont want to shift folders that are stuck with hidden inherited permissions or something silly.

and is it ok to make one Share called “Plex Content” and then have the Tv Shows, Movies etc as subfolders in there ?

Before I uninstall PlexMediaServer-1.22.0.4163 is there anything I should be backing up from plex ?

ChuckPa · March 14, 2021, 4:30am

On my Synology, I have “Movies” , “Television”, and “Music” shares.

These names do not collide with Synology.

The contents of the “Plex” share are not erased during package removal.
I leave that there. It’s done this way because Synology doesn’t let us downgrade on top of an existing. (when we try beta but go back to public version)

When you eventually migrate to DSM 7, you’ll see how I changed all that to give you more control . I expressly show you what’s happening and give you options to go with it.

For now, while on DSM 6, If you are changing PMS version… Uninstall the package. Don’t delete the Plex user or the Plex shared folder.

When the installer runs, it will find both and continue where it left off without alteration

TheCrow2k · March 14, 2021, 8:47am

Thanks @ChuckPa

I stopped Plex, & uninstalled it.

Then I created a new share: plex content/TV Shows & Copies attack on titan season 1 to that new location.

I manually installed PlexMediaServer-1.22.0.4163-d8c4875dd-x86_64.spk

Added this new TV Shows folder to Plex Librarier as Tv Shows 2.

Fired up my WD Live TV & Navigated to Tv Shows 2, pressed play on Season 1 Episode 1 and it did not transcode / failed to play

Plex Logs-fresh install.zip (120.4 KB)

if this had have worked I would have copied the rest of my media from the old shares over to the new folders.

below is the activity in the transcode folder.

Plex user:

TheCrow2k · March 14, 2021, 9:46am

should i try uninstalling & removing user plex & then re-installing ?

the format the trasncode is delivered in has not changed in these recent versions has it ?

ChuckPa · March 14, 2021, 3:40pm

Thank you for the logs.

This isn’t a server issue. The player is exiting.

Does this same video play in the Web player?

Mar 14, 2021 19:07:45.890 [0x7f098d3be700] DEBUG - [Transcode] Streaming Resource: Attempting to create AdHoc transcode session 
Mar 14, 2021 19:07:45.906 [0x7f098d3be700] DEBUG - [Transcode] MDE: Selected protocol http; container: mpegts
Mar 14, 2021 19:07:45.906 [0x7f098d3be700] DEBUG - [Transcode] MDE: analyzing media item 14905
Mar 14, 2021 19:07:45.906 [0x7f098d3be700] DEBUG - [Transcode] MDE: E1 - To You, in 2000 Years: The Fall of Shiganshina, Part 1: Direct Play is disabled
Mar 14, 2021 19:07:45.906 [0x7f098d3be700] DEBUG - [Transcode] MDE: E1 - To You, in 2000 Years: The Fall of Shiganshina, Part 1: selected audio stream is not the first audio stream and direct play stream selection is not enabled
Mar 14, 2021 19:07:45.906 [0x7f098d3be700] DEBUG - [Transcode] MDE: Attack on Titan - S1 E1 - To You, in 2000 Years: The Fall of Shiganshina, Part 1: selected media 0 / 14905
Mar 14, 2021 19:07:45.906 [0x7f098d3be700] DEBUG - [Transcode] Streaming Resource: Added session 0x7f095011ecb0:
Mar 14, 2021 19:07:45.906 [0x7f098d3be700] DEBUG - [Transcode] Streaming Resource: Reached Decision id=13985 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=14905 part=(id=14993 decision=transcode container=mpegts protocol=http streams=(Video=(id=28914 decision=copy width=720 height=404) Audio=(id=28916 decision=copy channels=2 rate=48000))))
Mar 14, 2021 19:07:45.907 [0x7f098d3be700] DEBUG - [Transcode] Cleaning directory for session 2425671e2675f52009f8e45c1b2f4bc6721a3bc0 ()
Mar 14, 2021 19:07:45.907 [0x7f098d3be700] DEBUG - [Transcode] Starting a transcode session 2425671e2675f52009f8e45c1b2f4bc6721a3bc0 at offset -1.0 (state=3)
Mar 14, 2021 19:07:45.908 [0x7f098d3be700] DEBUG - [Transcode] [Universal] Using local file path instead of URL: /volume1/Plex Content/TV Shows/Attack.On.Titan.S01.BDRip.x264-Scene/attack.on.titan.s01e01.bdrip.x264-debtvid.mkv
Mar 14, 2021 19:07:45.917 [0x7f098d3be700] DEBUG - [Transcode/JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/volume1/Plex/Library/Application\ Support/Plex\ Media\ Server/Codecs/367b3d4-3673-linux-x86_64/' '/volume1/@appstore/Plex Media Server/Plex Transcoder' '-codec:0' 'h264' '-noaccurate_seek' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/volume1/Plex Content/TV Shows/Attack.On.Titan.S01.BDRip.x264-Scene/attack.on.titan.s01e01.bdrip.x264-debtvid.mkv' '-map' '0:0' '-metadata:s:0' 'language=eng' '-codec:0' 'copy' '-map' '0:2' '-metadata:s:1' 'language=eng' '-codec:1' 'copy' '-f' 'segment' '-segment_format' 'mpegts' '-segment_time' '1' '-segment_header_filename' 'header' '-segment_start_number' '0' '-segment_list' 'http://127.0.0.1:32400/video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/seglist?X-Plex-Http-Pipeline=infinite' '-segment_list_type' 'csv' '-segment_list_unfinished' '1' '-segment_list_size' '5' '-segment_list_separate_stream_times' '1' '-map_metadata' '-1' '-map_chapters' '-1' 'chunk-%05d' '-y' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress'
Mar 14, 2021 19:07:45.926 [0x7f098d3be700] DEBUG - [Transcode/JobRunner] Jobs: Starting child process with pid 2263
Mar 14, 2021 19:07:46.352 [0x7f0957735700] DEBUG - Request: [127.0.0.1:38554 (Loopback)] GET /photo/:/transcode?format=jpg&height=512&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2Flibrary%2Fmetadata%2F13985%2Fthumb%2F1615682317&width=512 (5 live)
Mar 14, 2021 19:07:46.352 [0x7f0957735700] DEBUG - [Transcode] Photo transcoder: Request for url [http://127.0.0.1:32400/library/metadata/13985/thumb/1615682317] (is local: 1 upscaled: 0)
Mar 14, 2021 19:07:46.352 [0x7f0957735700] DEBUG - Content-Length of /volume1/Plex/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/00/00460b32f4cff726dbe8d1befa9f2d3b85d45066.jpg is 17093 (of total: 17093).
Mar 14, 2021 19:07:46.353 [0x7f0994758700] DEBUG - Completed: [127.0.0.1:38554] 200 GET /photo/:/transcode?format=jpg&height=512&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2Flibrary%2Fmetadata%2F13985%2Fthumb%2F1615682317&width=512 (5 live) 0ms 17093 bytes
Mar 14, 2021 19:07:46.384 [0x7f098dc88700] DEBUG - Request: [127.0.0.1:38555 (Loopback)] PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress?status=startup (5 live) (range: bytes=0-) 
Mar 14, 2021 19:07:46.384 [0x7f0994758700] DEBUG - Completed: [127.0.0.1:38555] 204 PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress?status=startup (5 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-) 
Mar 14, 2021 19:07:46.555 [0x7f0957d11700] DEBUG - Request: [127.0.0.1:38555 (Loopback)] PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress?status=startup (5 live) (range: bytes=0-) 
Mar 14, 2021 19:07:46.555 [0x7f0994a46700] DEBUG - Completed: [127.0.0.1:38555] 204 PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress?status=startup (5 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-) 
Mar 14, 2021 19:07:46.556 [0x7f098d99a700] DEBUG - Request: [127.0.0.1:38555 (Loopback)] PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress?status=opening (5 live) (range: bytes=0-) 
Mar 14, 2021 19:07:46.556 [0x7f0994758700] DEBUG - Completed: [127.0.0.1:38555] 204 PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress?status=opening (5 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-) 
Mar 14, 2021 19:07:46.592 [0x7f098dc88700] DEBUG - Request: [127.0.0.1:38555 (Loopback)] PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress?status=opened (5 live) (range: bytes=0-) 
Mar 14, 2021 19:07:46.592 [0x7f0994a46700] DEBUG - Completed: [127.0.0.1:38555] 204 PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress?status=opened (5 live) 0ms 203 bytes (pipelined: 4) (range: bytes=0-) 
Mar 14, 2021 19:07:46.593 [0x7f0957d11700] DEBUG - Request: [127.0.0.1:38555 (Loopback)] PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress/stream?index=0&id=0&codec=h264&type=video (5 live) (range: bytes=0-) 
Mar 14, 2021 19:07:46.593 [0x7f0994758700] DEBUG - Completed: [127.0.0.1:38555] 200 PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress/stream?index=0&id=0&codec=h264&type=video (5 live) 0ms 195 bytes (pipelined: 5) (range: bytes=0-) 
Mar 14, 2021 19:07:46.610 [0x7f098d99a700] DEBUG - Request: [127.0.0.1:38555 (Loopback)] PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress/stream?index=1&id=0&codec=aac&type=audio&profile=LC (5 live) (range: bytes=0-) 
Mar 14, 2021 19:07:46.610 [0x7f0994a46700] DEBUG - Completed: [127.0.0.1:38555] 200 PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress/stream?index=1&id=0&codec=aac&type=audio&profile=LC (5 live) 0ms 195 bytes (pipelined: 6) (range: bytes=0-) 
Mar 14, 2021 19:07:46.611 [0x7f098df76700] DEBUG - Request: [127.0.0.1:38555 (Loopback)] PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress/stream?index=2&id=0&codec=aac&type=audio&profile=LC (5 live) (range: bytes=0-) 
Mar 14, 2021 19:07:46.611 [0x7f0994758700] DEBUG - Completed: [127.0.0.1:38555] 200 PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress/stream?index=2&id=0&codec=aac&type=audio&profile=LC (5 live) 0ms 195 bytes (pipelined: 7) (range: bytes=0-) 
Mar 14, 2021 19:07:46.611 [0x7f0957fff700] DEBUG - Request: [127.0.0.1:38555 (Loopback)] PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress/streamDetail?index=0&id=0&codec=h264&type=video&profile=High&language=eng&width=720&height=404&interlaced=0&sar=1:1&level=31&frameRate=23.976&disp_default=1 (5 live) (range: bytes=0-) 
Mar 14, 2021 19:07:46.612 [0x7f0994758700] DEBUG - Completed: [127.0.0.1:38555] 200 PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress/streamDetail?index=0&id=0&codec=h264&type=video&profile=High&language=eng&width=720&height=404&interlaced=0&sar=1:1&level=31&frameRate=23.976&disp_default=1 (5 live) 0ms 195 bytes (pipelined: 8) (range: bytes=0-) 
Mar 14, 2021 19:07:46.613 [0x7f0957735700] DEBUG - Request: [127.0.0.1:38555 (Loopback)] PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress/streamDetail?index=1&id=0&codec=aac&type=audio&profile=LC&language=jpn&channels=2&layout=2%20channels&sampleRate=48000&disp_default=1 (5 live) (range: bytes=0-) 
Mar 14, 2021 19:07:46.614 [0x7f0994a46700] DEBUG - Completed: [127.0.0.1:38555] 200 PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress/streamDetail?index=1&id=0&codec=aac&type=audio&profile=LC&language=jpn&channels=2&layout=2%20channels&sampleRate=48000&disp_default=1 (5 live) 0ms 195 bytes (pipelined: 9) (range: bytes=0-) 
Mar 14, 2021 19:07:46.615 [0x7f098c518700] DEBUG - Request: [127.0.0.1:38555 (Loopback)] PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress/streamDetail?index=2&id=0&codec=aac&type=audio&profile=LC&language=eng&channels=2&layout=2%20channels&sampleRate=48000 (5 live) (range: bytes=0-) 
Mar 14, 2021 19:07:46.615 [0x7f0994758700] DEBUG - Completed: [127.0.0.1:38555] 200 PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress/streamDetail?index=2&id=0&codec=aac&type=audio&profile=LC&language=eng&channels=2&layout=2%20channels&sampleRate=48000 (5 live) 0ms 195 bytes (pipelined: 10) (range: bytes=0-) 
Mar 14, 2021 19:07:46.616 [0x7f098caf4700] DEBUG - Request: [127.0.0.1:38555 (Loopback)] PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress/streamDetail?index=3&id=0&codec=subrip&type=subtitle&language=eng&disp_default=1&disp_forced=1 (5 live) (range: bytes=0-) 
Mar 14, 2021 19:07:46.616 [0x7f0994758700] DEBUG - Completed: [127.0.0.1:38555] 200 PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress/streamDetail?index=3&id=0&codec=subrip&type=subtitle&language=eng&disp_default=1&disp_forced=1 (5 live) 0ms 195 bytes (pipelined: 11) (range: bytes=0-) 
Mar 14, 2021 19:07:46.617 [0x7f098dc88700] DEBUG - Request: [127.0.0.1:38555 (Loopback)] PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress/streamDetail?index=4&id=0&codec=subrip&type=subtitle&language=eng (5 live) (range: bytes=0-) 
Mar 14, 2021 19:07:46.617 [0x7f0994758700] DEBUG - Completed: [127.0.0.1:38555] 200 PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress/streamDetail?index=4&id=0&codec=subrip&type=subtitle&language=eng (5 live) 0ms 195 bytes (pipelined: 12) (range: bytes=0-) 
Mar 14, 2021 19:07:46.619 [0x7f0957d11700] DEBUG - Request: [127.0.0.1:38555 (Loopback)] PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress?duration=1542.004000 (5 live) (range: bytes=0-) 
Mar 14, 2021 19:07:46.619 [0x7f098d3be700] DEBUG - [Transcode] Started session successfully: 2425671e2675f52009f8e45c1b2f4bc6721a3bc0
Mar 14, 2021 19:07:46.619 [0x7f098d3be700] DEBUG - Content-Length is -1 (of total: -1).
Mar 14, 2021 19:07:46.620 [0x7f0994a46700] DEBUG - Completed: [127.0.0.1:38555] 204 PUT /video/:/transcode/session/2425671e2675f52009f8e45c1b2f4bc6721a3bc0/ef981c9c-d8bf-4aa7-9e40-e3670f4d87a8/progress?duration=1542.004000 (5 live) 1ms 203 bytes (pipelined: 13) (range: bytes=0-) 
Mar 14, 2021 19:07:46.620 [0x7f098d0d0700] DEBUG - [TranscodeOutputStream] Input processing thread started at offset 0 for -1 bytes.
Mar 14, 2021 19:07:46.624 [0x7f098d99a700] DEBUG - [Transcode] Transcoder segment range: 0 - 0 (-1)
Mar 14, 2021 19:07:46.644 [0x7f098df76700] DEBUG - [Transcode] Transcoder segment range: 0 - 0 (0)
Mar 14, 2021 19:07:46.645 [0x7f0957fff700] DEBUG - [Transcode] Transcoder segment range: 0 - 1 (0)
Mar 14, 2021 19:07:46.646 [0x7f098d0d0700] DEBUG - [TranscodeOutputStream] Input processed first bytes after 25 ms.
Mar 14, 2021 19:07:46.649 [0x7f0957735700] DEBUG - Request: [127.0.0.1:38559 (Loopback)] GET /video/:/transcode/universal/start?audioBoost=100&directPlay=0&directStream=1&directStreamAudio=1&path=%2flibrary%2fmetadata%2f13985&session=2425671e2675f52009f8e45c1b2f4bc6721a3bc0&subtitleSize=100&videoQuality=74&videoResolution=1920x1080 (6 live) Token ()
Mar 14, 2021 19:07:46.650 [0x7f0957735700] DEBUG - [Transcode] Found session GUID of 2425671e2675f52009f8e45c1b2f4bc6721a3bc0 in session start.
Mar 14, 2021 19:07:46.650 [0x7f0957735700] DEBUG - [Transcode] TranscodeUniversalRequest: using profile Western Digital TV Live
Mar 14, 2021 19:07:46.650 [0x7f0957735700] DEBUG - [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/13985?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1
Mar 14, 2021 19:07:46.656 [0x7f098c518700] DEBUG - [Transcode] Transcoder segment range: 0 - 1 (1)
Mar 14, 2021 19:07:46.657 [0x7f0957735700] DEBUG - [Transcode] We're going to try to auto-select an audio stream for account 1.
Mar 14, 2021 19:07:46.657 [0x7f0957735700] DEBUG - [Transcode] Selecting best audio stream for part ID 14993 (autoselect: 1 language: en)
Mar 14, 2021 19:07:46.658 [0x7f0957735700] DEBUG - [Transcode] Audio Stream: 28916, Subtitle Stream: -1
Mar 14, 2021 19:07:46.658 [0x7f098caf4700] DEBUG - [Transcode] Transcoder segment range: 0 - 2 (1)
Mar 14, 2021 19:07:46.659 [0x7f0957735700] DEBUG - [Transcode] Using existing transcode session.
Mar 14, 2021 19:07:46.659 [0x7f0957735700] DEBUG - Content-Length is -1 (of total: -1).
Mar 14, 2021 19:07:46.660 [0x7f0956445700] DEBUG - [TranscodeOutputStream] Input processing thread started at offset 0 for -1 bytes.
Mar 14, 2021 19:07:46.660 [0x7f0956445700] DEBUG - [TranscodeOutputStream] Input processed first bytes after 0 ms.
Mar 14, 2021 19:07:46.665 [0x7f098dc88700] DEBUG - [Transcode] Transcoder segment range: 0 - 2 (2)
Mar 14, 2021 19:07:46.667 [0x7f0957d11700] DEBUG - [Transcode] Transcoder segment range: 0 - 3 (2)
Mar 14, 2021 19:07:46.674 [0x7f098d3be700] DEBUG - [Transcode] Transcoder segment range: 0 - 3 (3)
Mar 14, 2021 19:07:46.676 [0x7f098d99a700] DEBUG - Request: [10.1.1.9:50950 (Subnet)] GET /status/sessions (8 live) GZIP
Mar 14, 2021 19:07:46.676 [0x7f098d99a700] DEBUG - [Now] Adding 0 sessions.
Mar 14, 2021 19:07:46.677 [0x7f098df76700] DEBUG - [Transcode] Transcoder segment range: 0 - 4 (3)
Mar 14, 2021 19:07:46.677 [0x7f0994758700] DEBUG - Completed: [10.1.1.9:50950] 200 GET /status/sessions (8 live) GZIP 1ms 350 bytes (pipelined: 1)
Mar 14, 2021 19:07:46.696 [0x7f0957fff700] DEBUG - [Transcode] Transcoder segment range: 0 - 4 (4)
Mar 14, 2021 19:07:46.699 [0x7f098c518700] DEBUG - [Transcode] Transcoder segment range: 0 - 5 (4)
Mar 14, 2021 19:07:46.707 [0x7f098caf4700] DEBUG - [Transcode] Transcoder segment range: 0 - 5 (5)
Mar 14, 2021 19:07:46.709 [0x7f0957735700] DEBUG - [Transcode] Transcoder segment range: 0 - 6 (5)
Mar 14, 2021 19:07:46.720 [0x7f0994758700] DEBUG - [TranscodeOutputStream] Sent back first bytes after 100 ms.
Mar 14, 2021 19:07:46.720 [0x7f0994758700] DEBUG - handleStreamWrite code 32: Broken pipe
Mar 14, 2021 19:07:46.720 [0x7f0994758700] DEBUG - Removed transcode data consumer, active count 2 => 1
Mar 14, 2021 19:07:46.720 [0x7f0994758700] DEBUG - Completed after connection close: [127.0.0.1:38553] 200 HEAD /video/:/transcode/universal/start?audioBoost=100&directPlay=0&directStream=1&directStreamAudio=1&path=%2flibrary%2fmetadata%2f13985&session=2425671e2675f52009f8e45c1b2f4bc6721a3bc0&subtitleSize=100&videoQuality=74&videoResolution=1920x1080 (7 live) 904ms 65536 bytes
Mar 14, 2021 19:07:46.720 [0x7f098d0d0700] DEBUG - [TranscodeOutputStream] Input processing thread exited after writing 2145080 bytes, m_closed=1, m_endOfFileReached=1, session->isStopped()=0
Mar 14, 2021 19:07:46.726 [0x7f098dc88700] DEBUG - [Transcode] Transcoder segment range: 0 - 6 (6)
Mar 14, 2021 19:07:46.729 [0x7f0957d11700] DEBUG - [Transcode] Transcoder segment range: 0 - 7 (6)
Mar 14, 2021 19:07:46.740 [0x7f098d3be700] DEBUG - [Transcode] Transcoder segment range: 0 - 7 (7)
Mar 14, 2021 19:07:46.742 [0x7f098d99a700] DEBUG - [Transcode] Transcoder segment range: 0 - 8 (7)
Mar 14, 2021 19:07:46.760 [0x7f0994a46700] DEBUG - [TranscodeOutputStream] Sent back first bytes after 100 ms.
Mar 14, 2021 19:07:46.761 [0x7f098df76700] DEBUG - [Transcode] Transcoder segment range: 0 - 8 (8)
Mar 14, 2021 19:07:46.765 [0x7f0957fff700] DEBUG - [Transcode] Transcoder segment range: 0 - 9 (8)
Mar 14, 2021 19:07:46.780 [0x7f098c518700] DEBUG - [Transcode] Transcoder segment range: 0 - 9 (9)
Mar 14, 2021 19:07:46.781 [0x7f098caf4700] DEBUG - [Transcode] Transcoder segment range: 0 - 10 (9)
Mar 14, 2021 19:07:46.790 [0x7f0957735700] DEBUG - [Transcode] Transcoder segment range: 0 - 10 (10)
Mar 14, 2021 19:07:46.790 [0x7f098dc88700] DEBUG - [Transcode] Transcoder segment range: 0 - 11 (10)
Mar 14, 2021 19:07:46.823 [0x7f0957d11700] DEBUG - [Transcode] Transcoder segment range: 0 - 11 (11)
Mar 14, 2021 19:07:46.825 [0x7f098d3be700] DEBUG - [Transcode] Transcoder segment range: 0 - 12 (11)
Mar 14, 2021 19:07:46.837 [0x7f098d99a700] DEBUG - [Transcode] Transcoder segment range: 0 - 12 (12)
Mar 14, 2021 19:07:46.841 [0x7f098df76700] DEBUG - [Transcode] Transcoder segment range: 0 - 13 (12)
Mar 14, 2021 19:07:46.878 [0x7f0957fff700] DEBUG - [Transcode] Transcoder segment range: 0 - 13 (13)
Mar 14, 2021 19:07:46.880 [0x7f098c518700] DEBUG - [Transcode] Transcoder segment range: 0 - 14 (13)
Mar 14, 2021 19:07:46.917 [0x7f098caf4700] DEBUG - [Transcode] Transcoder segment range: 0 - 14 (14)
Mar 14, 2021 19:07:46.918 [0x7f0957735700] DEBUG - [Transcode] Transcoder segment range: 0 - 15 (14)
Mar 14, 2021 19:07:46.929 [0x7f098dc88700] DEBUG - [Transcode] Transcoder segment range: 0 - 15 (15)
Mar 14, 2021 19:07:46.931 [0x7f0957d11700] DEBUG - [Transcode] Transcoder segment range: 0 - 16 (15)
Mar 14, 2021 19:07:46.961 [0x7f098d3be700] DEBUG - Request: [127.0.0.1:38591 (Loopback)] GET /:/timeline?containerKey=%2Flibrary%2Fmetadata%2F13985&duration=1542004&guid=com%2Eplexapp%2Eagents%2Ethetvdb%3A%2F%2F267440%2F1%2F1%3Flang%3Den&key=%2Flibrary%2Fmetadata%2F13985&ratingKey=13985&report=1&state=playing&time=1000 (7 live) GZIP
Mar 14, 2021 19:07:46.968 [0x7f098d3be700] DEBUG - Client [2425671e2675f52009f8e45c1b2f4bc6721a3bc0] reporting timeline state playing, progress of 1000/1542004ms for guid=com.plexapp.agents.thetvdb://267440/1/1?lang=en, playbackTime=-1ms ratingKey=13985 url=, key=/library/metadata/13985, containerKey=/library/metadata/13985, metadataId=13985, source=
Mar 14, 2021 19:07:46.977 [0x7f098d3be700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 14, 2021 19:07:46.977 [0x7f098d3be700] DEBUG - Selecting best audio stream for part ID 14993 (autoselect: 1 language: en)
Mar 14, 2021 19:07:46.977 [0x7f098d3be700] DEBUG - Audio Stream: 28916, Subtitle Stream: -1
Mar 14, 2021 19:07:46.978 [0x7f098d3be700] DEBUG - [Now] Device is  (Western Digital TV Live).
Mar 14, 2021 19:07:46.978 [0x7f098d3be700] DEBUG - [Now] Profile is Western Digital TV Live
Mar 14, 2021 19:07:46.978 [0x7f098d3be700] DEBUG - [Now] Updated play state for /library/metadata/13985.
Mar 14, 2021 19:07:47.007 [0x7f098d99a700] DEBUG - [Transcode] Transcoder segment range: 0 - 16 (16)
Mar 14, 2021 19:07:47.009 [0x7f098df76700] DEBUG - [Transcode] Transcoder segment range: 0 - 17 (16)
Mar 14, 2021 19:07:47.029 [0x7f0994758700] DEBUG - handleStreamWrite code 104: Connection reset by peer
Mar 14, 2021 19:07:47.029 [0x7f0994758700] DEBUG - Removed transcode data consumer, active count 1 => 0
Mar 14, 2021 19:07:47.029 [0x7f0994758700] DEBUG - Completed after connection close: [127.0.0.1:38559] 200 GET /video/:/transcode/universal/start?audioBoost=100&directPlay=0&directStream=1&directStreamAudio=1&path=%2flibrary%2fmetadata%2f13985&session=2425671e2675f52009f8e45c1b2f4bc6721a3bc0&subtitleSize=100&videoQuality=74&videoResolution=1920x1080 (7 live) 379ms 1982162 bytes

Notice the “Connection reset by peer” (104)

TheCrow2k · March 14, 2021, 9:29pm

@ChuckPa yes, it plays in the web player fine because I do not think it is transcoding ?

I have 2 x WD TV Lives, it is doing this on both, but like I said it was all working fine on PlexMediaServer-1.21.4.4079 (64 bit) with transcoding until it just stopped for no apparent reason.

Media that does not need transcode still works fine playing from plex to the WD TV Live…
If PMS recieves the instruction to begin transcoding a file shouldnt it create a new transcode folder inside tmp_transcoding more or less immediately ?

I also last night tried rolling back to the last synology listed version of PMS 1.18 but issue persists.

Really wish I had no upgraded now…

Oh I keep forgetting to mention this is all happening on my local wired network although you can likely tell that from the logs.

One other thing I just thought of, does the WDLive user on my synology (which is the user credentials my players use) need access to the Plex folder so it can read transcode data ? or is all that handled by Plex DLNA itself ?

TheCrow2k · March 15, 2021, 12:24am

I just found in another thread a user with a WD Live noted similar disconnect errors in the log which had not effect on playback from DLNA.

TheCrow2k · March 15, 2021, 11:25pm

@ChuckPa Bump.

ChuckPa · March 16, 2021, 12:22am

I can’t help with DLNA.

Don’t know a thing about it
Don’t watch TV with it. (no display screen on my refrigerator ) Sorry
I have zero knowledge with anything WD. Sorry.

If it’s connections being “reset by peer” - that is the DLNA client disconnecting from Plex. (Plex is the source. The DLNA player is the peer)

TheCrow2k · March 16, 2021, 5:26am

@ChuckPa thankyou, I understand

FINAL QUESTION IF YOU PLEASE, since this all started when I installed 1.21.4.4079 (64 bit) and then had to grant plex permissions to both my media & the plex folder is it worth uninstalling Plex & removing the Plex user from Synology ?

if so should i backup the Plex library or just let it re-create ?

ChuckPa · March 16, 2021, 5:46am

On Synology,

The data (your server installation and all it knows about your media) is stored separately from the program.
If you want to change versions:
a. DSM 6 – simply Uninstall & reinstall the desired.
b. DSM 7 – Uninstall, select “Keep” option (the default) & reinstall the desired.

It might just make best sense to back down from the bleeding edge. Stay with a version for a while . 1.21.2 / 1.21.3 were very stable version.

TheCrow2k · March 17, 2021, 3:09am

But is it ok to remove the Plex user account from Synology users ?

Is there somewhere I can download 1.21.2 or 1.21.3 from ? the download page only offers me the latest.

TheCrow2k · March 17, 2021, 8:55am

just for the sakew of it i installed Plex on my PC & setup access to the same files on my synology, remarkably the file that ive been using to test worked on first play but when i tried to tweak the language settings it then failed & now will not transcode again even after reverting language settings back

Plex Media Server Logs_2021-03-17_19-21-54.zip (396.8 KB)

TheCrow2k · March 17, 2021, 10:49am

@ChuckPa I think I have actually found a bug. If you readon this problem seems to be language/Audio track setting causing the failures to transcode.

I Shutdown the Synology Plex server. (PlexMediaServer-1.22.0.4163-d8c4875dd-x86_64)

Installed windows PMS PlexMediaServer-1.22.0.4163-d8c4875dd-x86 onto my PC, not sure why the downloader only gave me this version not 64 bit…

turned on & setup dlna

Mapped TV shows 2 directory with just one show in the folder (stored on synology nas, this PC has full admin rights to the NAS)

hit play on Episode 1 through WD TV Live, PLAYBACK SUCCESS. played fine, but in japanese audio.

went into plex & changed the default language of episode 1 to english. as below

hit play on Episode 1 through WD TV Live, PLAYBACK FAILED.

switched back to japanese audio in plex

hit play on Episode 1 through WD TV Live, PLAYBACK SUCCESS. played fine, hit audio button on WD Live TV remote and switched to english audio fine.

Plex Media Server Logs_2021-03-17_20-34-25.zip (481.3 KB)

I am watching episodes right now so it works… so long as I dont let plex try to specify the language tracks.

I have since shut down the windows test server, gone into my Synology Plex server, restarted it and disabled Audio track selection & now everything works fine ?

ChuckPa · March 17, 2021, 4:59pm

Why reference Windows logs?

I don’t have windows and can’t replicate anything nor do I understand windows. – sorry, I’m 100% Linux here.
Flipping language from what is in the video / default track, if you’re not careful, will cause the subtitles to be invoked.

In this case:

Primary audio is not the default
Subtitles burning.

That’s where I get lost

Mar 17, 2021 19:14:37.133 [7684] DEBUG - [Transcode] Streaming Resource: Attempting to create AdHoc transcode session 
Mar 17, 2021 19:14:37.140 [7684] DEBUG - [Transcode] Scaled up video bitrate to 1815Kbps based on 1.500000x fudge factor.
Mar 17, 2021 19:14:37.141 [7684] DEBUG - [Transcode] MDE: Selected protocol http; container: mpegts
Mar 17, 2021 19:14:37.141 [7684] DEBUG - [Transcode] MDE: analyzing media item 1
Mar 17, 2021 19:14:37.141 [7684] DEBUG - [Transcode] MDE: E1 - To You, in 2000 Years: The Fall of Shiganshina, Part 1: Direct Play is disabled
Mar 17, 2021 19:14:37.141 [7684] DEBUG - [Transcode] MDE: E1 - To You, in 2000 Years: The Fall of Shiganshina, Part 1: selected audio stream is not the first audio stream and direct play stream selection is not enabled
Mar 17, 2021 19:14:37.141 [7684] DEBUG - [Transcode] MDE: E1 - To You, in 2000 Years: The Fall of Shiganshina, Part 1: selected subtitle cannot be converted to a compatible format, burning into video stream
Mar 17, 2021 19:14:37.142 [7684] DEBUG - [Transcode] MDE: E1 - To You, in 2000 Years: The Fall of Shiganshina, Part 1: avoiding video remux due to burned subtitle stream
Mar 17, 2021 19:14:37.142 [7684] DEBUG - [Transcode] MDE: E1 - To You, in 2000 Years: The Fall of Shiganshina, Part 1: no remuxable profile found, so video stream will be transcoded
Mar 17, 2021 19:14:37.142 [7684] DEBUG - [Transcode] MDE: Cannot direct stream video stream due to profile or setting limitations
Mar 17, 2021 19:14:37.142 [7684] DEBUG - [Transcode] Scaled up video bitrate to 1815Kbps based on 1.500000x fudge factor.
Mar 17, 2021 19:14:37.143 [7684] DEBUG - [Transcode] MDE: Attack on Titan - S1 E1 - To You, in 2000 Years: The Fall of Shiganshina, Part 1: selected media 0 / 1
Mar 17, 2021 19:14:37.145 [7684] DEBUG - [Transcode] Streaming Resource: Adding session 0x53f13e0: which is using transcoder slot.  Used slots is now 1
Mar 17, 2021 19:14:37.145 [7684] DEBUG - [Transcode] Streaming Resource: Added session 0x53f13e0:
Mar 17, 2021 19:14:37.146 [7684] DEBUG - [Transcode] Streaming Resource: Reached Decision id=3 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=1 part=(id=1 decision=transcode container=mpegts protocol=http streams=(Video=(id=1 decision=transcode bitrate=1815 encoder=libx264 width=720 height=404) Audio=(id=3 decision=copy channels=2 rate=48000) Subtitle=(id=4 decision=burn languageCode=eng location=embedded))))
Mar 17, 2021 19:14:37.147 [7684] DEBUG - [Transcode] Cleaning directory for session 2425671e2675f52009f8e45c1b2f4bc6721a3bc0 ()
Mar 17, 2021 19:14:37.149 [7684] DEBUG - [Transcode] Starting a transcode session 2425671e2675f52009f8e45c1b2f4bc6721a3bc0 at offset -1.0 (state=3)
Mar 17, 2021 19:14:37.155 [7684] DEBUG - [Transcode] [Universal] Using local file path instead of URL: X:\TV Shows\Attack.On.Titan.S01.BDRip.x264-Scene\attack.on.titan.s01e01.bdrip.x264-debtvid.mkv
Mar 17, 2021 19:14:37.156 [7684] INFO - [Transcode] CodecManager: obtaining encoder 'libx264'
Mar 17, 2021 19:14:37.157 [7684] DEBUG - [Transcode] HTTP requesting GET https://plex.tv/api/codecs/libx264_encoder?build=windows-x86-standard&deviceId=dc95461c-75f1-4cfa-a7e8-6d4e9534f88c&oldestPreviousVersion=1%2E22%2E0%2E4163-d8c4875dd&version=367b3d4-3673
Mar 17, 2021 19:14:37.457 [2004] DEBUG - Completed: [127.0.0.1:57131] 200 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (5 live) GZIP 20002ms 5 bytes (pipelined: 44)

Is there any way you could curate this a little better and remux out the unwanted or set the desired as default? (mkvtoolnix-gui can do that)

TheCrow2k · March 18, 2021, 12:35am

Sorry I thought if I tried windows server it might eliminate any issues with hidden permissions dramas in my Synology. I just did it to try and eliminate permissions issues as a cause.

I think you are right about it being related to the subtitles, though my initial theory was it was because I was using audio track 2 on those episodes. However further testing back on the synology revealed that other episodes with dual audio where I have not changed he default audio in plex and the Audio track 1 is English refuse to play through plex (unsupported media type) and yet these same files play fine through the WD TV Live when played as network shares…

It seems like Plex always transcoded some MKV content for the WD TV Live that it did not need to (I can usually tell as the volume is noticeably lower on some shows), I thought I had a custom WD TV Live profile in my plex folder at one point but its not there anymore.

@ChuckPa When content is being transcoded, should I be able to see a new folder/folders & files generated inside the plex tmp_transcoding directory ? because there is literally nothing happening in that directory.

I noticed a few interesting things happening in the console when I played one tv episode & ti didn’t play but Plex kept on logging as though it was still playing the episode, I will look at this more tonight & post the logs.

I only ever messed with the default audio tracks in Plex like I have because the last version of plex I was using (sadly I don’t know what version) did not respond to my WD remote commands to change audio tracks or subtitles.

Topic		Replies	Views
Synology NAS - Updated to PlexMediaServer-1.21.4.4079 and now nothing but trouble, now transcoding doesnt seem to be working on DLNA? NAS & Devices server-linux , server-dlna , server-synology , server-western-digital	3	141	June 11, 2021
PMS 1.23.0.4497 Transcoder no longer transcodes anything Plex Media Server server-linux	25	551	August 6, 2021
Why Transcoding? Gaming Consoles xbox	17	150	January 8, 2020
Receiving transcoding error without config changes NAS & Devices server-synology	10	187	May 9, 2021
Getting a "Conversion failed. Transcoder crashed or failed to start up" error. Desktops & Laptops server-windows	141	11529	January 8, 2020

Transcode not working at all on PMS?

Related topics