Constantly failing due to transcoder errors

Plex Media Server
1

Server Version#: 1.20.1.3252-a78fef9a9
Player Version#: Version 4.34.4 (shiled, Windows PMP, Web browser, etc)

For the past few months users of my server have said it’s un-useable due to constant transcoding errors. Lately, I’ve been having them several times a day on my local network as well. I currently can’t watch a 20 minute show without it erroring out 5-6 times during playback. Also, if I leave the tab open for http://127.0.0.1:32400/web/index.html it eventually becomes a completely black screen and I need to double click the plex icon in my task bar to regain access.

I’m attaching my logs and have tried every setting I can think of in the transcoder section.

2

Plex Media Server Logs_2020-09-23_12-27-38.zip (5.9 MB)

3

Can anyone help me please? Plex used to work for years, but is completely broken now. I’m not sure where to look in the logs…

4

There are many FFMPEG / CUDA out of memory errors. Also, Plex is not always recognizing the GPU. See log entries below.

Which NVIDIA card are you using?
Maybe re-installing the Nvidia drivers would help.

Also, I’m not sure if it is relevant to your transcoder problems, but Plex is constantly re-analyzing the same iCloud photos. It detects a rename, which kicks off an analysis.

For example, it reanalyzed IMG_4158 three times between 12:12:51 and 12:26:19, a little over 13 minutes.

For all your pictures, there were 9018 renames, and corresponding analysis, during the same 13 minute time period.

I don’t use iCloud Photos or have photos in my Plex Server. But maybe your system is so busy analyzing photos it has trouble streaming/transcoding movies & shows?

Out of memory errors
Sep 23, 2020 12:15:29.032 [47588] ERROR - [FFMPEG] - InitializeEncoder failed: out of memory (10)
Sep 23, 2020 12:18:47.055 [39480] ERROR - [FFMPEG] - -> CUDA_ERROR_OUT_OF_MEMORY: out of memory

Recognition issues
Sep 23, 2020 12:13:17.608 [37952] DEBUG - TPU: hardware transcoding: enabled, but no hardware decode accelerator found
Sep 23, 2020 12:13:17.608 [37952] DEBUG - TPU: hardware transcoding: final decoder: , final encoder:
Sep 23, 2020 12:14:32.887 [18888] DEBUG - TPU: hardware transcoding: final decoder: nvdec, final encoder:

Picture Analysis

Sep 23, 2020 12:26:19.613 [49048] DEBUG - We found a hash match for [C:\Users\peloq\Pictures\iCloud Photos\Downloads\IMG_4158.jpg] which was [C:\Users\peloq\Pictures\iCloud Photos\Downloads\IMG_4158.jpg].
Sep 23, 2020 12:26:19.614 [49048] INFO - Part rename detected [C:\Users\peloq\Pictures\iCloud Photos\Downloads\IMG_4158.jpg] was renamed [C:\Users\peloq\Pictures\iCloud Photos\Downloads\IMG_4158.jpg]
Sep 23, 2020 12:26:19.633 [49048] DEBUG - Updating media item 99032430, size=2587460, metadata_item_id=99003813
Sep 23, 2020 12:26:19.633 [49048] DEBUG - Updating part with ID=99041780 [C:\Users\peloq\Pictures\iCloud Photos\Downloads\IMG_4158.jpg]
Sep 23, 2020 12:26:19.637 [49048] DEBUG - Added new metadata item (IMG_4158) with ID 99007538
Sep 23, 2020 12:26:19.637 [31080] DEBUG - [LibraryTimeline] Scanner activity on section 3: 2131 added, 1065 deleted
Sep 23, 2020 12:26:19.644 [49048] DEBUG - Doing expensive tags write for 'IMG_4158' because something changed.
Sep 23, 2020 12:26:19.645 [49048] DEBUG - Doing expensive tags write for 'IMG_4158' because something changed.
Sep 23, 2020 12:26:19.646 [49048] DEBUG - Doing expensive tags write for 'IMG_4158' because something changed.
Sep 23, 2020 12:26:19.646 [49048] DEBUG - Doing expensive tags write for 'IMG_4158' because something changed.
Sep 23, 2020 12:26:19.647 [49048] DEBUG - Doing expensive tags write for 'IMG_4158' because something changed.
Sep 23, 2020 12:26:19.647 [49048] DEBUG - Doing expensive tags write for 'IMG_4158' because something changed.
Sep 23, 2020 12:26:19.648 [49048] DEBUG - Doing expensive tags write for 'IMG_4158' because something changed.
Sep 23, 2020 12:26:19.649 [49048] DEBUG - Deleting media item 99032430.
Sep 23, 2020 12:26:19.649 [49048] DEBUG - Was connected to metadata item 99003813, count is now 0.
Sep 23, 2020 12:26:19.650 [49048] DEBUG - Destroying metadata item 99003813 (IMG_4158)
5

Thanks for the response. I’ll try removing photos from Plex altogether. My video card is a GeForce GTX 1080 Ti and the driver version is 456.38 (latest).

How would I resolve the out FFMPEG / CUDA out of memory errors? I’ve reinstalled the drivers and they say in the geforce app that they’re the latest versions.

image
image879×569 189 KB

6

Should I just disable these options? Or is there a way for me to fix my video card so it works?

image
image1107×191 95.8 KB

7

Leave hardware acceleration enabled.

It looks like you have an i7-7700K CPU. It is more than capable of transcoding multiple 1080p streams w/o hardware acceleration. However, hardware acceleration should work. Disabling it would be a workaround.

Let’s see what happens after you remove the photos from Plex.

Let’s try this:

  • Once Plex has finished removing the photos, reboot your PC (since you re-installed the drivers).
  • Wait 2 - 3 minutes for Plex to finish startup.
  • Play a movie or TV show and force a video transcode.
  • Let it run and see what happens.
  • If the transcoder fails, then pull the log files.
  • Even if it doesn’t fail, still pull the logs, to make sure Plex is using the 1080 Ti for transcoding.

Edit: FYI, the log entries in the earlier post are from Plex Media Server.log (wraps to .1 to .5) in the ZIP file.

If hardware acceleration is working correctly, you’ll see final decoder = nvdec and final encoder = nvenc in the entries with TPU:.

8

Ok, I rebooted and then tried playing a movie from my mobile device off of the wifi. It errored out about 15 minutes in, logs attached.Plex Media Server Logs_2020-09-25_15-18-12.zip (5.7 MB)

9

I tried playing the movie 300 and that one appears to have transcoded correctly, but I see this for The Dark Knight in the logs which indicates it directly played I believe.

Sep 25, 2020 14:59:59.498 [7092] DEBUG - Auth: authenticated user 1 as pelamatt
Sep 25, 2020 14:59:59.500 [9460] DEBUG - Created thumbnail of size 240x360, has pixels: 1
Sep 25, 2020 14:59:59.502 [7092] DEBUG - It took 0.000000 ms to retrieve 223 items.
Sep 25, 2020 14:59:59.503 [7092] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Sep 25, 2020 14:59:59.503 [7092] DEBUG - Selecting best audio stream for part ID 53452083 (autoselect: 0 language: en)
Sep 25, 2020 14:59:59.503 [7092] DEBUG - Audio Stream: 7238326, Subtitle Stream: -1
Sep 25, 2020 14:59:59.504 [7092] DEBUG - MDE: Selected protocol http; container: mp4
Sep 25, 2020 14:59:59.504 [7092] DEBUG - MDE: analyzing media item 6369498
Sep 25, 2020 14:59:59.504 [7092] DEBUG - MDE: The Dark Knight (2008): Direct Playing due to no transcode profile
Sep 25, 2020 14:59:59.504 [7092] DEBUG - MDE: The Dark Knight (2008): DirectPlay
Sep 25, 2020 14:59:59.504 [7092] DEBUG - MDE: The Dark Knight (2008): selected media 0 / 6369498
Sep 25, 2020 14:59:59.504 [7092] DEBUG - Streaming Resource: Calculated bandwidth of 15589kbps exceeds bandwidth limit. Changing decision parameters provided by client to fit bandwidth limit of 720kbps
Sep 25, 2020 14:59:59.504 [7092] DEBUG - Streaming Resource: Determining preferred transcode encoders through transcode only decision.
Sep 25, 2020 14:59:59.505 [7092] DEBUG - Codecs: testing h264 (decoder) with hwdevice nvdec
Sep 25, 2020 14:59:59.505 [7092] DEBUG - Codecs: hardware transcoding: testing API nvdec

10

For the movie ‘300’ I do see the “TPU: hardware transcoding: using hardware decode accelerator nvdec”

Sep 25, 2020 14:37:36.456 [20844] DEBUG - Cleaning directory for session o1qhr0qokelmjdvzn1su9070 ()
Sep 25, 2020 14:37:36.456 [20844] DEBUG - Starting a transcode session o1qhr0qokelmjdvzn1su9070 at offset -1.0 (state=3)
Sep 25, 2020 14:37:36.457 [20844] DEBUG - TPU: hardware transcoding: using hardware decode accelerator nvdec
Sep 25, 2020 14:37:36.457 [20844] DEBUG - TPU: hardware transcoding: zero-copy support not present
Sep 25, 2020 14:37:36.457 [20844] DEBUG - [Universal] Using local file path instead of URL: I:\Film\300 (2006)\300.(2006).1080p.BluRay.x265.HEVC.AAC.5.1.mkv
Sep 25, 2020 14:37:36.458 [20844] DEBUG - TPU: hardware transcoding: final decoder: nvdec, final encoder:
Sep 25, 2020 14:37:36.458 [20844] DEBUG - Job running: EAE_ROOT=’\?\D:\Transcodes\Transcode\Sessions\EasyAudioEncoder’ FFMPEG_EXTERNAL_LIBS=’\\?\C:\Users\peloq\AppData\Local\Plex\ Media\ Server\Codecs\96550d8-3281-windows-x86\’ X_PLEX_TOKEN=‘xxxxxxxxxxxxxxxxxxxx’ C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe -codec:0 hevc -hwaccel:0 nvdec -hwaccel_fallback_threshold:0 10 -analyzeduration 20000000 -probesize 20000000 -i “I:\Film\300 (2006)\300.(2006).1080p.BluRay.x265.HEVC.AAC.5.1.mkv” -filter_complex [0:0]scale=w=1920:h=800[0];[0]format=pix_fmts=yuv420p|nv12[1] -map [1] -codec:0 libx264 -crf:0 16 -maxrate:0 24943k -bufsize:0 49886k -r:0 23.975999999999999 -preset:0 veryfast -x264opts:0 subme=2:me_range=4:rc_lookahead=10:me=hex:8x8dct=1 -force_key_frames:0 expr:gte(t,0+n_forced*1) -map 0:1 -metadata:s:1 language=eng -codec:1 copy -copypriorss:1 0 -f dash -seg_duration 1 -init_seg_name init-stream$RepresentationID$.m4s -media_seg_name chunk-stream$RepresentationID$-Number%05d.m4s -window_size 5 -delete_removed false -skip_to_segment 1 -time_delta 0.0625 -manifest_name http://127.0.0.1:32400/video/:/transcode/session/o1qhr0qokelmjdvzn1su9070/e8262723-49f6-4f73-8147-b131e9e72b98/manifest?X-Plex-Http-Pipeline=infinite -avoid_negative_ts disabled -map_metadata -1 -map_chapters -1 dash -start_at_zero -copyts -vsync cfr -y -init_hw_device cuda=cuda: -hwaccel_device cuda -filter_hw_device cuda -nostats -loglevel quiet -loglevel_plex error -progressurl http://127.0.0.1:32400/video/:/transcode/session/o1qhr0qokelmjdvzn1su9070/e8262723-49f6-4f73-8147-b131e9e72b98/progress
Sep 25, 2020 14:37:36.460 [20844] DEBUG - Jobs: Starting child process with pid 11508

11

I just got the transcoder error again from the web player on chrome only 30 seconds into a small file-size tv show, attaching those logs here.

Playing from:
http://127.0.0.1:32400/web/index.html#!/server/df2825cb6b8dc8ab5f6c779dbd992c5a4637f9ff/details?key=%2Flibrary%2Fmetadata%2F3881954&context=home%3Ahub.home.ondeck

image
image1097×456 92.7 KB
Plex Media Server Logs_2020-09-25_16-11-59.zip (5.2 MB)

12

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