Transcoder errors after updating

Plex Media Server Desktops & Laptops
64

Yes, that’s exactly the XML section I’m looking for.
The <media> section tells me everything which is in the file.

Thank you for changing the transcoder logging. it makes a whole lot more sense.
Now, however, I’m not seeing the transcoder start!

Would you mind setting it to debug (which should be the default) ?

What I’m finding in your logs is PMS trying to play the file 3 times (overlapped) and each of them , in turn, failing. due to what looks like timeout. ( CPU overload / IO delays ?)

65

I’m assuming you mean in the General settings? The Enable Plex Media Server debug logging has been checked. Also in the Debug section of Plex Web, the Debug Level is set to Enabled.

Now that the logging makes sense, do you think it could be the FFMEG encoder? I see something about having the wrong version installed? Weird thing with all of this is that things worked fine until two updates ago where I couldnt even cast to a chromecast anymore.

66

What I’m seeing in the logs is:

  1. Decision is made
  2. Transcoder apparently starts up –
  3. The cuda drivers are loaded into the transcoder (proof its there)
  4. –NOTHING–
  5. Start the next attempt to play.

What’s the player? If that’s the Windows player, would you mind trying Plex/Web ?

Jun 11, 2021 22:19:55.724 [0x7fbc4a23bb38] DEBUG - Request: [192.168.86.182:52840 (Subnet)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F109&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&maxVideoBitrate=1500&directStreamAudio=0&session=jl0m8tle7jfff6191rtn4441&offset=12&subtitles=auto&copyts=1&Accept-Language=en (15 live) TLS GZIP Signed-in Token (gomezm149)
Jun 11, 2021 22:19:55.724 [0x7fbc4a23bb38] DEBUG - [Transcode] Found session GUID of jl0m8tle7jfff6191rtn4441 in session start.
Jun 11, 2021 22:19:55.724 [0x7fbc4a23bb38] DEBUG - [Transcode] TranscodeUniversalRequest: using profile Plex Desktop
Jun 11, 2021 22:19:55.724 [0x7fbc4a23bb38] DEBUG - [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/109?includeBandwidths=1&offset=12&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=e3ttbdqtayb693v6v6vb4xcy
Jun 11, 2021 22:19:55.724 [0x7fbc4a23bb38] DEBUG - [Transcode] Auth: authenticated user 1 as gomezm149
Jun 11, 2021 22:19:55.729 [0x7fbc4a23bb38] DEBUG - [Transcode] It took 10.000000 ms to retrieve 118 items.
Jun 11, 2021 22:19:55.763 [0x7fbc4a23bb38] DEBUG - [Transcode] It took 50.000000 ms to retrieve 118 items.
Jun 11, 2021 22:19:55.769 [0x7fbc4a23bb38] DEBUG - [Transcode] We're going to try to auto-select an audio stream for account 1.
Jun 11, 2021 22:19:55.769 [0x7fbc4a23bb38] DEBUG - [Transcode] Selecting best audio stream for part ID 17734 (autoselect: 1 language: en)
Jun 11, 2021 22:19:55.769 [0x7fbc4a23bb38] DEBUG - [Transcode] We're going to try to auto-select a subtitle.
Jun 11, 2021 22:19:55.769 [0x7fbc4a23bb38] DEBUG - [Transcode] Subtitles: Found a candidate subtitle language [] for a foreign film
Jun 11, 2021 22:19:55.769 [0x7fbc4a23bb38] DEBUG - [Transcode] Audio Stream: 50872, Subtitle Stream: 45200
Jun 11, 2021 22:19:55.774 [0x7fbc4a23bb38] DEBUG - [Transcode] Codecs: testing h264_nvenc (encoder)
Jun 11, 2021 22:19:55.774 [0x7fbc4a23bb38] DEBUG - [Transcode] Codecs: hardware transcoding: testing API nvenc
Jun 11, 2021 22:19:55.774 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded lib: libcuda.so.1
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuInit
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuDeviceGetCount
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuDeviceGet
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuDeviceGetAttribute
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuDeviceGetName
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuDeviceComputeCapability
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuCtxCreate_v2
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuCtxSetLimit
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuCtxPushCurrent_v2
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuCtxPopCurrent_v2
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuCtxDestroy_v2
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuMemAlloc_v2
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuMemAllocPitch_v2
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuMemsetD8Async
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuMemFree_v2
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuMemcpy2D_v2
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuMemcpy2DAsync_v2
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuGetErrorName
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuGetErrorString
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuCtxGetDevice
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuStreamCreate
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuStreamQuery
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuStreamSynchronize
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuStreamDestroy_v2
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuStreamAddCallback
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuEventCreate
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuEventDestroy_v2
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuEventSynchronize
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuEventQuery
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuEventRecord
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuLaunchKernel
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuLinkCreate
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuLinkAddData
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuLinkComplete
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuLinkDestroy
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuModuleLoadData
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuModuleUnload
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuModuleGetFunction
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuTexObjectCreate
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuTexObjectDestroy
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuGLGetDevices_v2
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuGraphicsGLRegisterImage
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuGraphicsUnregisterResource
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuGraphicsMapResources
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuGraphicsUnmapResources
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuGraphicsSubResourceGetMappedArray
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Cannot load optional cuDeviceGetUuid
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Cannot load optional cuImportExternalMemory
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Cannot load optional cuDestroyExternalMemory
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Cannot load optional cuExternalMemoryGetMappedBuffer
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Cannot load optional cuExternalMemoryGetMappedMipmappedArray
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuMipmappedArrayGetLevel
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuMipmappedArrayDestroy
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Cannot load optional cuImportExternalSemaphore
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Cannot load optional cuDestroyExternalSemaphore
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Cannot load optional cuSignalExternalSemaphoresAsync
Jun 11, 2021 22:19:55.776 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Cannot load optional cuWaitExternalSemaphoresAsync
Jun 11, 2021 22:19:56.646 [0x7fbc49f34b38] DEBUG - BPQ: [Starting] -> [Processing]
Jun 11, 2021 22:19:56.647 [0x7fbc49f34b38] DEBUG - BPQ: generating queue items from 0 generator(s)
Jun 11, 2021 22:19:56.647 [0x7fbc49f34b38] DEBUG - BPQ: generated 0 item(s) for queue
Jun 11, 2021 22:19:56.648 [0x7fbc49f34b38] DEBUG - PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
Jun 11, 2021 22:19:56.648 [0x7fbc49f34b38] DEBUG - PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
Jun 11, 2021 22:19:56.648 [0x7fbc49f34b38] DEBUG - BPQ: [Processing] -> [Idle]
Jun 11, 2021 22:19:57.813 [0x7fbc4d9e9b38] DEBUG - Auth: authenticated user 1 as gomezm149
Jun 11, 2021 22:19:57.813 [0x7fbc4a541b38] DEBUG - Request: [192.168.86.182:52847 (Subnet)] GET /video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F109&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&maxVideoBitrate=1500&directStreamAudio=0&session=jl0m8tle7jfff6191rtn4441&offset=12&subtitles=auto&copyts=1&Accept-Language=en (16 live) TLS Signed-in Token (gomezm149) (range: bytes=0-) 
Jun 11, 2021 22:19:57.813 [0x7fbc4a541b38] DEBUG - [Transcode] Found session GUID of jl0m8tle7jfff6191rtn4441 in session start.
Jun 11, 2021 22:19:57.813 [0x7fbc4a541b38] DEBUG - [Transcode] TranscodeUniversalRequest: using profile Plex Desktop
Jun 11, 2021 22:19:57.813 [0x7fbc4a541b38] DEBUG - [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/109?checkFiles=1&includeBandwidths=1&offset=12&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=e3ttbdqtayb693v6v6vb4xcy
Jun 11, 2021 22:19:57.813 [0x7fbc4a541b38] DEBUG - [Transcode] Auth: authenticated user 1 as gomezm149
Jun 11, 2021 22:19:57.818 [0x7fbc4a541b38] DEBUG - [Transcode] It took 10.000000 ms to retrieve 118 items.
Jun 11, 2021 22:19:57.822 [0x7fbc4a541b38] DEBUG - [Transcode] It took 20.000000 ms to retrieve 118 items.
Jun 11, 2021 22:19:57.832 [0x7fbc4a541b38] DEBUG - [Transcode] We're going to try to auto-select an audio stream for account 1.
Jun 11, 2021 22:19:57.832 [0x7fbc4a541b38] DEBUG - [Transcode] Selecting best audio stream for part ID 17734 (autoselect: 1 language: en)
Jun 11, 2021 22:19:57.832 [0x7fbc4a541b38] DEBUG - [Transcode] We're going to try to auto-select a subtitle.
Jun 11, 2021 22:19:57.832 [0x7fbc4a541b38] DEBUG - [Transcode] Subtitles: Found a candidate subtitle language [] for a foreign film
Jun 11, 2021 22:19:57.832 [0x7fbc4a541b38] DEBUG - [Transcode] Audio Stream: 50872, Subtitle Stream: 45200
Jun 11, 2021 22:19:57.834 [0x7fbc4a541b38] WARN - [Transcode] Denying access due to session lacking permission to transcode key /library/metadata/109
Jun 11, 2021 22:19:57.835 [0x7fbc4da0cb38] DEBUG - Completed: [192.168.86.182:52847] 400 GET /video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F109&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&maxVideoBitrate=1500&directStreamAudio=0&session=jl0m8tle7jfff6191rtn4441&offset=12&subtitles=auto&copyts=1&Accept-Language=en (16 live) TLS 21ms 265 bytes (range: bytes=0-) 
Jun 11, 2021 22:19:57.891 [0x7fbc4da0cb38] DEBUG - Auth: authenticated user 1 as gomezm149
Jun 11, 2021 22:19:57.891 [0x7fbc4a541b38] DEBUG - Request: [192.168.86.182:52848 (Subnet)] GET /:/timeline?ratingKey=109&key=%2Flibrary%2Fmetadata%2F109&playbackTime=13141&playQueueItemID=44837&state=error&hasMDE=1&context=home%3Ahub.continueWatching&row=0&col=0&time=12000&duration=6346000 (16 live) TLS GZIP Signed-in Token (gomezm149)
Jun 11, 2021 22:19:57.891 [0x7fbc4a541b38] DEBUG - Client [e3ttbdqtayb693v6v6vb4xcy] reporting timeline state error, progress of 12000/6346000ms for guid=, playbackTime=13141ms ratingKey=109 url=, key=/library/metadata/109, containerKey=, metadataId=109, source=
Jun 11, 2021 22:19:57.892 [0x7fbc4a541b38] DEBUG - [Now] User is gomezm149 (ID: 1)
Jun 11, 2021 22:19:57.892 [0x7fbc4a541b38] DEBUG - [Now] Device is windows (ChemicalRig).
Jun 11, 2021 22:19:57.892 [0x7fbc4a541b38] DEBUG - [Now] Profile is Plex Desktop
Jun 11, 2021 22:19:57.892 [0x7fbc4a541b38] DEBUG - [Now] Updated play state for /library/metadata/109.
Jun 11, 2021 22:19:57.892 [0x7fbc4a541b38] DEBUG - Statistics: (h3qyh4479qjht38lgox5mc6g) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1
Jun 11, 2021 22:19:57.894 [0x7fbc4d9e9b38] DEBUG - Completed: [192.168.86.182:52848] 200 GET /:/timeline?ratingKey=109&key=%2Flibrary%2Fmetadata%2F109&playbackTime=13141&playQueueItemID=44837&state=error&hasMDE=1&context=home%3Ahub.continueWatching&row=0&col=0&time=12000&duration=6346000 (16 live) TLS GZIP 3ms 456 bytes (pipelined: 1)
Jun 11, 2021 22:19:58.511 [0x7fbc4da0cb38] DEBUG - Auth: authenticated user 8752174 as NateMan99
Jun 11, 2021 22:19:58.511 [0x7fbc4da0cb38] DEBUG - Request: [108.203.7.162:52425 (WAN)] GET /activities (17 live) TLS GZIP Signed-in Token ()
Jun 11, 2021 22:19:58.512 [0x7fbc4da0cb38] DEBUG - Completed: [108.203.7.162:52425] 401 GET /activities (17 live) TLS GZIP 0ms 357 bytes
Jun 11, 2021 22:19:59.017 [0x7fbc4a541b38] DEBUG - Request: [127.0.0.1:39940 (Loopback)] GET /servers (17 live) GZIP Signed-in Token (gomezm149)
Jun 11, 2021 22:19:59.018 [0x7fbc4da0cb38] DEBUG - Completed: [127.0.0.1:39940] 200 GET /servers (17 live) GZIP 0ms 496 bytes
Jun 11, 2021 22:19:59.021 [0x7fbc4a541b38] DEBUG - Request: [127.0.0.1:39942 (Loopback)] GET /library/sections (17 live) GZIP Signed-in Token (gomezm149)
Jun 11, 2021 22:19:59.023 [0x7fbc4da0cb38] DEBUG - Completed: [127.0.0.1:39942] 200 GET /library/sections (17 live) GZIP 1ms 1204 bytes
Jun 11, 2021 22:19:59.027 [0x7fbc4a541b38] DEBUG - Request: [127.0.0.1:39944 (Loopback)] GET /channels/all (17 live) GZIP Signed-in Token (gomezm149)
Jun 11, 2021 22:19:59.027 [0x7fbc4da0cb38] DEBUG - Completed: [127.0.0.1:39944] 200 GET /channels/all (17 live) GZIP 0ms 387 bytes
Jun 11, 2021 22:19:59.042 [0x7fbc4a541b38] DEBUG - Request: [127.0.0.1:39946 (Loopback)] GET /library/sections (17 live) GZIP Signed-in Token (gomezm149)
Jun 11, 2021 22:19:59.044 [0x7fbc4da0cb38] DEBUG - Completed: [127.0.0.1:39946] 200 GET /library/sections (17 live) GZIP 1ms 1204 bytes
Jun 11, 2021 22:19:59.047 [0x7fbc4a541b38] DEBUG - Request: [127.0.0.1:39948 (Loopback)] GET /channels/all (17 live) GZIP Signed-in Token (gomezm149)
Jun 11, 2021 22:19:59.047 [0x7fbc4da0cb38] DEBUG - Completed: [127.0.0.1:39948] 200 GET /channels/all (17 live) GZIP 0ms 387 bytes
Jun 11, 2021 22:20:00.201 [0x7fbc4d9e9b38] DEBUG - Auth: authenticated user 1 as gomezm149
Jun 11, 2021 22:20:00.201 [0x7fbc4a541b38] DEBUG - Request: [192.168.86.182:52848 (Subnet)] GET /:/timeline?ratingKey=109&key=%2Flibrary%2Fmetadata%2F109&playbackTime=13141&playQueueItemID=44837&state=stopped&hasMDE=1&context=home%3Ahub.continueWatching&row=0&col=0&time=12000&duration=6346000 (16 live) TLS GZIP Signed-in Token (gomezm149)
Jun 11, 2021 22:20:00.201 [0x7fbc4a541b38] DEBUG - Client [e3ttbdqtayb693v6v6vb4xcy] reporting timeline state stopped, progress of 12000/6346000ms for guid=, playbackTime=13141ms ratingKey=109 url=, key=/library/metadata/109, containerKey=, metadataId=109, source=
Jun 11, 2021 22:20:00.206 [0x7fbc4a541b38] DEBUG - It took 10.000000 ms to retrieve 118 items.
Jun 11, 2021 22:20:00.220 [0x7fbc4a541b38] DEBUG - [Now] User is gomezm149 (ID: 1)
Jun 11, 2021 22:20:00.220 [0x7fbc4a541b38] DEBUG - [Now] Device is windows (ChemicalRig).
Jun 11, 2021 22:20:00.221 [0x7fbc4a541b38] DEBUG - [Now] Profile is Plex Desktop
Jun 11, 2021 22:20:00.221 [0x7fbc4a541b38] DEBUG - [Now] Updated play state for /library/metadata/109.
Jun 11, 2021 22:20:00.229 [0x7fbc4a541b38] DEBUG - Statistics: (h3qyh4479qjht38lgox5mc6g) Reporting active playback in state 3 of type 1 (scrobble: 0) for account 1
Jun 11, 2021 22:20:00.230 [0x7fbc4a541b38] DEBUG - Streaming Resource: Terminated session 0x7fbc4d75b958:e3ttbdqtayb693v6v6vb4xcy with reason Client stopped playback.
Jun 11, 2021 22:20:00.230 [0x7fbc4a541b38] DEBUG - Streaming Resource: Removing session 0x7fbc4d75b958:e3ttbdqtayb693v6v6vb4xcy
Jun 11, 2021 22:20:00.231 [0x7fbc4da0cb38] DEBUG - Completed: [192.168.86.182:52848] 200 GET /:/timeline?ratingKey=109&key=%2Flibrary%2Fmetadata%2F109&playbackTime=13141&playQueueItemID=44837&state=stopped&hasMDE=1&context=home%3Ahub.continueWatching&row=0&col=0&time=12000&duration=6346000 (16 live) TLS GZIP 30ms 456 bytes (pipelined: 2)Jun 11, 2021 22:19:53.646 [0x7fbc4d9e9b38] DEBUG - Failed to stream media, client probably disconnected after 2080768 bytes: 104 - Connection reset by peer
Jun 11, 2021 22:19:53.651 [0x7fbc4a574b38] DEBUG - [Transcode] It took 30.000000 ms to retrieve 118 items.
Jun 11, 2021 22:19:53.653 [0x7fbc4d9e9b38] DEBUG - Completed after connection close: [192.168.86.182:52830] 206 GET /library/parts/17734/1426297903/file.mp4?Accept-Language=en (16 live) TLS 192ms 2080768 bytes (range: bytes=3231009-) 
Jun 11, 2021 22:20:00.237 [0x7fbc4da0cb38] DEBUG - Auth: authenticated user 1 as gomezm149
Jun 11, 2021 22:20:00.242 [0x7fbc4d9e9b38] DEBUG - Auth: authenticated user 1 as gomezm149
Jun 11, 2021 22:20:00.243 [0x7fbc4d9e9b38] DEBUG - Auth: authenticated user 1 as gomezm149
Jun 11, 2021 22:20:00.243 [0x7fbc4a541b38] DEBUG - Request: [192.168.86.182:51170 (Subnet)] GET /library/sections/1/all?sort=addedAt:desc&contentDirectoryID=1&pinnedContentDirectoryID=1%2C3%2C5%2C2%2Cplaylists%2C6 (19 live) TLS Page 0-41 GZIP Signed-in Token (gomezm149)
Jun 11, 2021 22:20:00.247 [0x7fbc4a541b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Jun 11, 2021 22:20:00.247 [0x7fbc4a541b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Jun 11, 2021 22:20:00.247 [0x7fbc4a541b38] DEBUG - Setting container serialization range to [0, 41] (total=-1)
Jun 11, 2021 22:20:00.249 [0x7fbc49e57b38] DEBUG - Request: [192.168.86.182:51172 (Subnet)] GET /hubs/home/recentlyAdded?type=2&sectionID=2&contentDirectoryID=2&pinnedContentDirectoryID=1%2C3%2C5%2C2%2Cplaylists%2C6 (19 live) TLS Page 0-23 GZIP Signed-in Token (gomezm149)
Jun 11, 2021 22:20:00.248 [0x7fbc49e7ab38] DEBUG - Request: [192.168.86.182:52848 (Subnet)] GET /hubs/continueWatching/items?contentDirectoryID=1%2C3%2C5%2C2%2Cplaylists%2C6 (19 live) TLS GZIP Signed-in Token (gomezm149)
Jun 11, 2021 22:20:00.257 [0x7fbc49e57b38] WARN - QueryParser: Invalid field 'sectionID' found, ignoring.
Jun 11, 2021 22:20:00.257 [0x7fbc49e57b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Jun 11, 2021 22:20:00.257 [0x7fbc49e57b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Jun 11, 2021 22:20:00.259 [0x7fbc49e57b38] DEBUG - It took 0.000000 ms to retrieve 200 items.
Jun 11, 2021 22:20:00.259 [0x7fbc49e57b38] WARN - QueryParser: Invalid field 'sectionID' found, ignoring.
Jun 11, 2021 22:20:00.259 [0x7fbc49e57b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Jun 11, 2021 22:20:00.260 [0x7fbc49e57b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Jun 11, 2021 22:20:00.260 [0x7fbc49e57b38] ERROR - SQLITE3:0x80000001, 28, double-quoted string literal: "2021-05-28 17:31:43"
Jun 11, 2021 22:20:00.262 [0x7fbc49e7ab38] DEBUG - HubCache: Retrieving '1/continueWatching/1/hubs/continueWatching/items/enexternal-media,indirect-media/contentDirectoryID=1%2C3%2C5%2C2%2Cplaylists%2C6' from the cache.
Jun 11, 2021 22:20:00.264 [0x7fbc49e57b38] DEBUG - It took 20.000000 ms to retrieve 418 items.
Jun 11, 2021 22:20:00.272 [0x7fbc49e7ab38] DEBUG - We're going to try to auto-select a subtitle.
Jun 11, 2021 22:20:00.273 [0x7fbc49e7ab38] DEBUG - Audio Stream: 92370, Subtitle Stream: -1
Jun 11, 2021 22:20:00.274 [0x7fbc4da0cb38] DEBUG - Completed: [192.168.86.182:52848] 200 GET /hubs/continueWatching/items?contentDirectoryID=1%2C3%2C5%2C2%2Cplaylists%2C6 (19 live) TLS GZIP 37ms 5106 bytes (pipelined: 3)
Jun 11, 2021 22:20:00.372 [0x7fbc49e57b38] WARN - SLOW QUERY: It took 280.000000 ms to retrieve 50 items.
Jun 11, 2021 22:20:00.401 [0x7fbc4a541b38] WARN - SLOW QUERY: It took 220.000000 ms to retrieve 42 items.
Jun 11, 2021 22:20:00.402 [0x7fbc4a541b38] DEBUG - Setting container serialization range to [0, 41] (total=508)
Jun 11, 2021 22:20:00.414 [0x7fbc4da0cb38] DEBUG - Completed: [192.168.86.182:51170] 200 GET /library/sections/1/all?sort=addedAt:desc&contentDirectoryID=1&pinnedContentDirectoryID=1%2C3%2C5%2C2%2Cplaylists%2C6 (19 live) TLS GZIP Page 0-41 172ms 17351 bytes (pipelined: 1)
Jun 11, 2021 22:20:00.428 [0x7fbc49e57b38] DEBUG - It took 70.000000 ms to retrieve 37 items.
Jun 11, 2021 22:20:00.440 [0x7fbc49e57b38] DEBUG - Setting container serialization range to [0, 23] (total=-1)
Jun 11, 2021 22:20:00.465 [0x7fbc49e57b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jun 11, 2021 22:20:00.465 [0x7fbc49e57b38] DEBUG - Selecting best audio stream for part ID 42448 (autoselect: 1 l

Where is this “version” thing you saw? I’m not seeing any

67

This has been happening on different computers, with the Windows app, the browser/web app, the iOS app, and when casting to chromecasts.

68

That’s bizarre… I will try something ,

This is what’s bothering me.

From the end of the first [FFMPEG] block (transcoder loading cuda libs)
until the start of the next one where I see [FFMPEG] startup.

There’s nothing… Not even an error message.

Jun 11, 2021 22:19:53.669 [0x7fbc4a574b38] DEBUG - [Transcode] [FFMPEG] - Cannot load optional cuImportExternalSemaphore
Jun 11, 2021 22:19:53.670 [0x7fbc4a574b38] DEBUG - [Transcode] [FFMPEG] - Cannot load optional cuDestroyExternalSemaphore
Jun 11, 2021 22:19:53.670 [0x7fbc4a574b38] DEBUG - [Transcode] [FFMPEG] - Cannot load optional cuSignalExternalSemaphoresAsync
Jun 11, 2021 22:19:53.670 [0x7fbc4a574b38] DEBUG - [Transcode] [FFMPEG] - Cannot load optional cuWaitExternalSemaphoresAsync
Jun 11, 2021 22:19:53.673 [0x7fbc4d9e9b38] DEBUG - Auth: authenticated user 1 as gomezm149
Jun 11, 2021 22:19:53.677 [0x7fbc4a541b38] DEBUG - Request: [192.168.86.182:52833 (Subnet)] GET /video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F109&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&maxVideoBitrate=1500&directStreamAudio=0&session=q7ochq9oal987khirlsds9cf&offset=12&subtitles=auto&copyts=1&Accept-Language=en (15 live) TLS Signed-in Token (gomezm149) (range: bytes=0-) 
Jun 11, 2021 22:19:53.678 [0x7fbc4a541b38] DEBUG - [Transcode] Found session GUID of q7ochq9oal987khirlsds9cf in session start.
Jun 11, 2021 22:19:53.678 [0x7fbc4a541b38] DEBUG - [Transcode] TranscodeUniversalRequest: using profile Plex Desktop
Jun 11, 2021 22:19:53.678 [0x7fbc4a541b38] DEBUG - [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/109?checkFiles=1&includeBandwidths=1&offset=12&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=e3ttbdqtayb693v6v6vb4xcy
Jun 11, 2021 22:19:53.678 [0x7fbc4a541b38] DEBUG - [Transcode] Auth: authenticated user 1 as gomezm149
Jun 11, 2021 22:19:53.694 [0x7fbc4a541b38] DEBUG - [Transcode] It took 10.000000 ms to retrieve 118 items.
Jun 11, 2021 22:19:53.699 [0x7fbc4a541b38] DEBUG - [Transcode] It took 10.000000 ms to retrieve 118 items.
Jun 11, 2021 22:19:53.718 [0x7fbc4a541b38] DEBUG - [Transcode] We're going to try to auto-select an audio stream for account 1.
Jun 11, 2021 22:19:53.718 [0x7fbc4a541b38] DEBUG - [Transcode] Selecting best audio stream for part ID 17734 (autoselect: 1 language: en)
Jun 11, 2021 22:19:53.718 [0x7fbc4a541b38] DEBUG - [Transcode] We're going to try to auto-select a subtitle.
Jun 11, 2021 22:19:53.718 [0x7fbc4a541b38] DEBUG - [Transcode] Subtitles: Found a candidate subtitle language [] for a foreign film
Jun 11, 2021 22:19:53.718 [0x7fbc4a541b38] DEBUG - [Transcode] Audio Stream: 50872, Subtitle Stream: 45200
Jun 11, 2021 22:19:53.719 [0x7fbc4a541b38] WARN - [Transcode] Denying access due to session lacking permission to transcode key /library/metadata/109
Jun 11, 2021 22:19:53.723 [0x7fbc4d9e9b38] DEBUG - Completed: [192.168.86.182:52833] 400 GET /video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F109&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&maxVideoBitrate=1500&directStreamAudio=0&session=q7ochq9oal987khirlsds9cf&offset=12&subtitles=auto&copyts=1&Accept-Language=en (15 live) TLS 49ms 265 bytes (range: bytes=0-) 
Jun 11, 2021 22:19:55.664 [0x7fbc4d9e9b38] DEBUG - Completed: [192.168.86.182:52709] 200 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (15 live) TLS GZIP 20000ms 5 bytes (pipelined: 7)
Jun 11, 2021 22:19:55.672 [0x7fbc4d9e9b38] DEBUG - Auth: authenticated user 1 as gomezm149
Jun 11, 2021 22:19:55.672 [0x7fbc4a541b38] DEBUG - Request: [192.168.86.182:52836 (Subnet)] GET /video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F109&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&maxVideoBitrate=1500&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&advancedSubtitles=text&session=apjg4pntvvwziem4asapzdym&offset=12&subtitles=auto&copyts=1&Accept-Language=en (15 live) TLS Signed-in Token (gomezm149) (range: bytes=0-) 
Jun 11, 2021 22:19:55.673 [0x7fbc4a541b38] DEBUG - [Transcode] Found session GUID of apjg4pntvvwziem4asapzdym in session start.
Jun 11, 2021 22:19:55.673 [0x7fbc4a541b38] DEBUG - [Transcode] TranscodeUniversalRequest: using profile Plex Desktop
Jun 11, 2021 22:19:55.673 [0x7fbc4a541b38] DEBUG - [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/109?checkFiles=1&includeBandwidths=1&offset=12&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=e3ttbdqtayb693v6v6vb4xcy
Jun 11, 2021 22:19:55.673 [0x7fbc4a541b38] DEBUG - [Transcode] Auth: authenticated user 1 as gomezm149
Jun 11, 2021 22:19:55.677 [0x7fbc4a541b38] DEBUG - [Transcode] It took 10.000000 ms to retrieve 118 items.
Jun 11, 2021 22:19:55.681 [0x7fbc4d9e9b38] DEBUG - Auth: authenticated user 1 as gomezm149
Jun 11, 2021 22:19:55.685 [0x7fbc4a541b38] DEBUG - [Transcode] It took 20.000000 ms to retrieve 118 items.
Jun 11, 2021 22:19:55.687 [0x7fbc4a541b38] DEBUG - [Transcode] We're going to try to auto-select an audio stream for account 1.
Jun 11, 2021 22:19:55.688 [0x7fbc4a541b38] DEBUG - [Transcode] Selecting best audio stream for part ID 17734 (autoselect: 1 language: en)
Jun 11, 2021 22:19:55.688 [0x7fbc4a541b38] DEBUG - [Transcode] We're going to try to auto-select a subtitle.
Jun 11, 2021 22:19:55.688 [0x7fbc4a541b38] DEBUG - [Transcode] Subtitles: Found a candidate subtitle language [] for a foreign film
Jun 11, 2021 22:19:55.688 [0x7fbc4a541b38] DEBUG - [Transcode] Audio Stream: 50872, Subtitle Stream: 45200
Jun 11, 2021 22:19:55.690 [0x7fbc4a541b38] WARN - [Transcode] Denying access due to session lacking permission to transcode key /library/metadata/109
Jun 11, 2021 22:19:55.691 [0x7fbc4d9e9b38] DEBUG - Completed: [192.168.86.182:52836] 400 GET /video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F109&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&maxVideoBitrate=1500&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&advancedSubtitles=text&session=apjg4pntvvwziem4asapzdym&offset=12&subtitles=auto&copyts=1&Accept-Language=en (15 live) TLS 18ms 265 bytes (range: bytes=0-) 
Jun 11, 2021 22:19:55.690 [0x7fbc4a23bb38] DEBUG - Request: [192.168.86.182:52709 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (15 live) TLS GZIP Signed-in Token (gomezm149)
Jun 11, 2021 22:19:55.692 [0x7fbc4a23bb38] DEBUG - Content-Length is -1 (of total: -1).
Jun 11, 2021 22:19:55.724 [0x7fbc4da0cb38] DEBUG - Auth: authenticated user 1 as gomezm149
Jun 11, 2021 22:19:55.724 [0x7fbc4a23bb38] DEBUG - Request: [192.168.86.182:52840 (Subnet)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F109&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&maxVideoBitrate=1500&directStreamAudio=0&session=jl0m8tle7jfff6191rtn4441&offset=12&subtitles=auto&copyts=1&Accept-Language=en (15 live) TLS GZIP Signed-in Token (gomezm149)
Jun 11, 2021 22:19:55.724 [0x7fbc4a23bb38] DEBUG - [Transcode] Found session GUID of jl0m8tle7jfff6191rtn4441 in session start.
Jun 11, 2021 22:19:55.724 [0x7fbc4a23bb38] DEBUG - [Transcode] TranscodeUniversalRequest: using profile Plex Desktop
Jun 11, 2021 22:19:55.724 [0x7fbc4a23bb38] DEBUG - [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/109?includeBandwidths=1&offset=12&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=e3ttbdqtayb693v6v6vb4xcy
Jun 11, 2021 22:19:55.724 [0x7fbc4a23bb38] DEBUG - [Transcode] Auth: authenticated user 1 as gomezm149
Jun 11, 2021 22:19:55.729 [0x7fbc4a23bb38] DEBUG - [Transcode] It took 10.000000 ms to retrieve 118 items.
Jun 11, 2021 22:19:55.763 [0x7fbc4a23bb38] DEBUG - [Transcode] It took 50.000000 ms to retrieve 118 items.
Jun 11, 2021 22:19:55.769 [0x7fbc4a23bb38] DEBUG - [Transcode] We're going to try to auto-select an audio stream for account 1.
Jun 11, 2021 22:19:55.769 [0x7fbc4a23bb38] DEBUG - [Transcode] Selecting best audio stream for part ID 17734 (autoselect: 1 language: en)
Jun 11, 2021 22:19:55.769 [0x7fbc4a23bb38] DEBUG - [Transcode] We're going to try to auto-select a subtitle.
Jun 11, 2021 22:19:55.769 [0x7fbc4a23bb38] DEBUG - [Transcode] Subtitles: Found a candidate subtitle language [] for a foreign film
Jun 11, 2021 22:19:55.769 [0x7fbc4a23bb38] DEBUG - [Transcode] Audio Stream: 50872, Subtitle Stream: 45200
Jun 11, 2021 22:19:55.774 [0x7fbc4a23bb38] DEBUG - [Transcode] Codecs: testing h264_nvenc (encoder)
Jun 11, 2021 22:19:55.774 [0x7fbc4a23bb38] DEBUG - [Transcode] Codecs: hardware transcoding: testing API nvenc
Jun 11, 2021 22:19:55.774 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded lib: libcuda.so.1
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuInit
Jun 11, 2021 22:19:55.775 [0x7fbc4a23bb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: cuDeviceGetCount

can you try this on something without the subtitle?

69

The log I sent was without the subtitle. It was transcoding to a lower quality.

The really odd thing is that it never times out. It keeps trying until I reboot the PMS. Even when someone has given up, that sequence will keep popping up every few seconds.

70

Do you have a device (tablet or phone) you could play this on?

I know those behave differently. I’m curious of the result.

At this point though, I’m starting to suspect one of the codecs is blown and needs reloading.

71

Just tried to play this with my iPhone. It will play with and without subtitles. But it will not let me change to a lower quality.

I’ve stopped it on my device but it’s still pulling on my CPU at 100%.

Plex Media Server Logs_2021-06-12_00-37-17.zip (2.7 MB)
.

72

Open a terminal window

ps -ef | grep -i trans and just kill -9 on those pids.

73

Gotcha, that’s better than a restart. So do I need to reinstall the codec?

74

For the codecs

  1. This will require a PMS restart so do it when you are ready to restart it.
  2. here’s the details
sudo bash
cd "/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Codecs"
rm -rf *
ls -la
  1. You will still have a .device_id file
  2. Leave it because it’s your server’s codec license.
  3. Restart PMS

The codecs will download again as needed. You’ll see a slight (2-3 sec) delay as it does this the first time each codec is grabbed. After that, they will again be local.

75

Thanks! I’ll give it a try tomorrow and see what happens!

Thanks again for all your help so far. Fingers crossed this works!

76

Just updated back to 1.23.2.4656, ran your script and here are my logs.

  • Will not play at all in Chrome h.264 or 265 (transcoded)
  • Direct play in Plex for Windows works but transcoding a h.264 file fails
  • Direct playing and transcoding a HEVC file works in Plex for Windows but not at all in browser.
  • Transcoder failed message when transcoding on Android app, direct play works

Plex Media Server Logs_2021-06-12_07-04-46.zip (5.1 MB)
Plex Media Server Logs_2021-06-12_07-16-43.zip (4.4 MB)
Plex Media Server Logs_2021-06-12_07-12-11.zip (4.4 MB)

This is from after a full server reboot. A lot of functionality came back once the system was back up and running, but transcoding an H.264 and H.265 file to a lower quality in Plex for Windows caused a transcoder crash both files play in browser fine now. Android also seems to be playing fine now h264 and 265. Playing an H.264 file on Xbox Series X both direct play and transcoded is fine but H.265 transcoded I received an error.

Plex Media Server Logs_2021-06-12_08-03-24.zip (4.0 MB)

This log should include the Xbox
Plex Media Server Logs_2021-06-12_08-17-46.zip (4.3 MB)

77

Unfortunately, it did not work. and it crashes my server every time.

I do keep seeing this pop up over and over and am wondering if there’s something wrong with the way my video card and the transcoder is set up in my system?

[Transcode] [FFMPEG] - libva: vaGetDriverNameByIndex() failed with unknown libva error, driver_name = (null) 

[Transcode] [FFMPEG] - Failed to initialise VAAPI connection: -1 (unknown libva error). 

[Transcode] [FFMPEG] - Driver does not support the required nvenc API version. Required: 9.0 Found: 8.1 

[Transcode] [FFMPEG] - The minimum required Nvidia driver for nvenc is 418.30 or newer

Attached are the logs after I tried to play it three separate times after a restart. The last time, it didnt even play the movie.
Plex Media Server Logs_2021-06-12_11-21-21.zip (1.1 MB)

78

No it didn’t but it told you what’s wrong and why.

Jun 12, 2021 11:16:04.815 [0x7f1c1b98fb38] DEBUG - [Transcode] [FFMPEG] - Loaded lib: libnvidia-encode.so.1
Jun 12, 2021 11:16:04.815 [0x7f1c1b98fb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: NvEncodeAPICreateInstance
Jun 12, 2021 11:16:04.815 [0x7f1c1b98fb38] DEBUG - [Transcode] [FFMPEG] - Loaded sym: NvEncodeAPIGetMaxSupportedVersion
Jun 12, 2021 11:16:04.815 [0x7f1c1b98fb38] ERROR - [Transcode] [FFMPEG] - Driver does not support the required nvenc API version. Required: 9.0 Found: 8.1
Jun 12, 2021 11:16:04.815 [0x7f1c1b98fb38] ERROR - [Transcode] [FFMPEG] - The minimum required Nvidia driver for nvenc is 418.30 or newer

The API version must be 9.0 or higher.

Update the Nvidia drivers

79

That was it! Thank you so much! I had a little bit of an issue there trying to update my drivers but it seems to work perfectly now!

Thanks for your patience and help!

80

You’re welcome. Glad it’s all sorted.

Now, to share what happened :

  1. Initially, 418 was required.
  2. HEVC HDR tone mapping support was added.
  3. The installed drivers worked for most of the cases but not all.
  4. Updating the Nvidia API gave the team what they needed to do it all.
  5. That update came out in the 1.23.x chain
  6. It just now finally caught up to you.
  7. If your card is capable, you’ll have full functionality because PMS is ready
81

Any update for my scenario? I’m truly not trying to be a pain, it’s just getting frustrating.

82

Willing to experiment ?

If so.

  1. Sign out of Plex/web app (upper right)
  2. Stop Plex
  3. cd /var/lib/plexmediaserver
  4. Rename Library → Library.keep
  5. Start Plex
  6. Sign into the server – you WILL be creating a new “test” server
  7. As the startup wizard runs,
  8. MAKE CERTAIN – Give it a Friendly Name “TEST SERVER” – to avoid conflicting with your existing.
  9. Setup one Library section (the movies which don’t play)
  10. Let it finish
  11. When done, Attempt playback
83

Thank you for the reply, I’ll see if I can get this set up tomorrow morning.